2025-03-10T15:56:13.994Z,1741622173.994 [Supervisor](DEBUG): Initializing supervisor.
2025-03-10T15:56:14.002Z,1741622174.002 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-03-10T15:56:14.002Z,1741622174.002 [SyncHandler](INFO): Protected caller Thread ID is 1464
2025-03-10T15:56:14.003Z,1741622174.003 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-03-10T15:56:14.004Z,1741622174.004 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-03-10T15:56:14.004Z,1741622174.004 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1465
2025-03-10T15:56:14.008Z,1741622174.008 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-03-10T15:56:14.027Z,1741622174.027 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-03-10T15:56:14.028Z,1741622174.028 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-03-10T15:56:14.028Z,1741622174.028 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1466
2025-03-10T15:56:14.033Z,1741622174.033 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-03-10T15:56:14.034Z,1741622174.034 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-03-10T15:56:14.034Z,1741622174.034 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1467
2025-03-10T15:56:14.036Z,1741622174.036 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-03-10T15:56:14.037Z,1741622174.037 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-03-10T15:56:14.038Z,1741622174.038 [logger ThreadHandler](INFO): Protected caller Thread ID is 1468
2025-03-10T15:56:14.042Z,1741622174.042 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-03-10T15:56:14.042Z,1741622174.042 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-03-10T15:56:14.043Z,1741622174.043 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-03-10T15:56:14.218Z,1741622174.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-03-10T15:56:14.219Z,1741622174.219 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-03-10T15:56:14.457Z,1741622174.457 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-03-10T15:56:14.458Z,1741622174.458 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-03-10T15:56:14.735Z,1741622174.735 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-03-10T15:56:14.736Z,1741622174.736 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-03-10T15:56:14.858Z,1741622174.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-03-10T15:56:14.858Z,1741622174.858 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-03-10T15:56:15.638Z,1741622175.638 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-03-10T15:56:15.639Z,1741622175.639 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-03-10T15:56:16.165Z,1741622176.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-03-10T15:56:16.165Z,1741622176.165 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-03-10T15:56:16.724Z,1741622176.724 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-03-10T15:56:16.724Z,1741622176.724 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-03-10T15:56:17.167Z,1741622177.167 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-03-10T15:56:17.167Z,1741622177.167 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-03-10T15:56:17.706Z,1741622177.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-03-10T15:56:17.706Z,1741622177.706 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-03-10T15:56:18.245Z,1741622178.245 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-03-10T15:56:18.245Z,1741622178.245 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-03-10T15:56:18.489Z,1741622178.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-03-10T15:56:18.490Z,1741622178.490 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-03-10T15:56:18.644Z,1741622178.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-03-10T15:56:18.644Z,1741622178.644 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-03-10T15:56:18.738Z,1741622178.738 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-03-10T15:56:18.829Z,1741622178.829 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-03-10T15:56:19.030Z,1741622179.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-03-10T15:56:19.030Z,1741622179.030 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-03-10T15:56:19.290Z,1741622179.290 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-03-10T15:56:19.292Z,1741622179.292 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2025-03-10T15:56:19.293Z,1741622179.293 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2025-03-10T15:56:19.389Z,1741622179.389 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2025-03-10T15:56:19.670Z,1741622179.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-03-10T15:56:19.670Z,1741622179.670 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2025-03-10T15:56:19.767Z,1741622179.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2025-03-10T15:56:19.947Z,1741622179.947 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2025-03-10T15:56:20.059Z,1741622180.059 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2025-03-10T15:56:20.183Z,1741622180.183 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2025-03-10T15:56:20.310Z,1741622180.310 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2025-03-10T15:56:20.426Z,1741622180.426 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2025-03-10T15:56:20.602Z,1741622180.602 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2025-03-10T15:56:20.714Z,1741622180.714 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2025-03-10T15:56:20.811Z,1741622180.811 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2025-03-10T15:56:20.922Z,1741622180.922 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2025-03-10T15:56:21.025Z,1741622181.025 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2025-03-10T15:56:21.025Z,1741622181.025 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-03-10T15:56:21.031Z,1741622181.030 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-03-10T15:56:21.238Z,1741622181.238 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-03-10T15:56:21.238Z,1741622181.238 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-03-10T15:56:21.405Z,1741622181.405 [BuoyancyServo] Loaded
2025-03-10T15:56:21.405Z,1741622181.405 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-03-10T15:56:21.429Z,1741622181.429 [ElevatorServo] Loaded
2025-03-10T15:56:21.429Z,1741622181.429 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-03-10T15:56:21.451Z,1741622181.451 [MassServo] Loaded
2025-03-10T15:56:21.452Z,1741622181.452 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-03-10T15:56:21.474Z,1741622181.474 [RudderServo] Loaded
2025-03-10T15:56:21.474Z,1741622181.474 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-03-10T15:56:21.492Z,1741622181.492 [ThrusterHE] Loaded
2025-03-10T15:56:21.492Z,1741622181.492 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-03-10T15:56:21.493Z,1741622181.493 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-03-10T15:56:21.493Z,1741622181.493 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-03-10T15:56:21.682Z,1741622181.682 [SBIT](DEBUG): Construct Startup Built In Test.
2025-03-10T15:56:21.697Z,1741622181.697 [SBIT] Loaded
2025-03-10T15:56:21.698Z,1741622181.698 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-03-10T15:56:21.700Z,1741622181.700 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-03-10T15:56:21.721Z,1741622181.721 [IBIT] Loaded
2025-03-10T15:56:21.722Z,1741622181.722 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-03-10T15:56:21.727Z,1741622181.727 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-03-10T15:56:21.923Z,1741622181.923 [CBIT] Loaded
2025-03-10T15:56:21.923Z,1741622181.923 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-03-10T15:56:21.954Z,1741622181.954 [GFScanner] Loaded
2025-03-10T15:56:21.955Z,1741622181.955 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-03-10T15:56:21.955Z,1741622181.955 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-03-10T15:56:21.956Z,1741622181.956 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-03-10T15:56:22.168Z,1741622182.168 [CTD_Seabird] Loaded
2025-03-10T15:56:22.168Z,1741622182.168 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-03-10T15:56:22.175Z,1741622182.175 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406834E0
2025-03-10T15:56:22.175Z,1741622182.175 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1556
2025-03-10T15:56:22.211Z,1741622182.211 [ESPComponent] Loaded
2025-03-10T15:56:22.211Z,1741622182.211 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-03-10T15:56:22.233Z,1741622182.233 [PAR_Licor] Loaded
2025-03-10T15:56:22.233Z,1741622182.233 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-03-10T15:56:22.279Z,1741622182.279 [WetLabsBB2FL] Loaded
2025-03-10T15:56:22.279Z,1741622182.279 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-03-10T15:56:22.280Z,1741622182.280 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B34E0
2025-03-10T15:56:22.281Z,1741622182.281 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1557
2025-03-10T15:56:22.281Z,1741622182.281 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-03-10T15:56:22.282Z,1741622182.282 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-03-10T15:56:22.345Z,1741622182.345 [DepthRateCalculator] Loaded
2025-03-10T15:56:22.345Z,1741622182.345 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-03-10T15:56:22.350Z,1741622182.350 [PitchRateCalculator] Loaded
2025-03-10T15:56:22.351Z,1741622182.351 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-03-10T15:56:22.361Z,1741622182.361 [SpeedCalculator] Loaded
2025-03-10T15:56:22.361Z,1741622182.361 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-03-10T15:56:22.366Z,1741622182.366 [YawRateCalculator] Loaded
2025-03-10T15:56:22.366Z,1741622182.366 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-03-10T15:56:22.386Z,1741622182.386 [ElevatorOffsetCalculator] Loaded
2025-03-10T15:56:22.386Z,1741622182.386 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-03-10T15:56:22.387Z,1741622182.387 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-03-10T15:56:22.387Z,1741622182.387 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-03-10T15:56:22.549Z,1741622182.549 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-03-10T15:56:22.549Z,1741622182.549 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-03-10T15:56:22.595Z,1741622182.595 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-03-10T15:56:22.596Z,1741622182.596 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-03-10T15:56:22.733Z,1741622182.733 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-03-10T15:56:22.733Z,1741622182.733 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-03-10T15:56:22.747Z,1741622182.747 [NavChart] Loaded
2025-03-10T15:56:22.747Z,1741622182.747 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-03-10T15:56:22.753Z,1741622182.753 [UniversalFixResidualReporter] Loaded
2025-03-10T15:56:22.753Z,1741622182.753 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-03-10T15:56:22.754Z,1741622182.754 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-03-10T15:56:22.754Z,1741622182.754 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-03-10T15:56:24.387Z,1741622184.387 [AHRS_M2] Loaded
2025-03-10T15:56:24.387Z,1741622184.387 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-03-10T15:56:24.643Z,1741622184.643 [BackseatComponent] Loaded
2025-03-10T15:56:24.643Z,1741622184.643 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-03-10T15:56:24.647Z,1741622184.647 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A7E4E0
2025-03-10T15:56:24.648Z,1741622184.648 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1558
2025-03-10T15:56:24.651Z,1741622184.651 [LcmUniversalReporter] Loaded
2025-03-10T15:56:24.651Z,1741622184.651 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-03-10T15:56:25.411Z,1741622185.411 [BPC1] Loaded
2025-03-10T15:56:25.412Z,1741622185.412 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-03-10T15:56:25.515Z,1741622185.515 [DAT] Loaded
2025-03-10T15:56:25.516Z,1741622185.516 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-03-10T15:56:25.527Z,1741622185.527 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AAE4E0
2025-03-10T15:56:25.527Z,1741622185.527 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1559
2025-03-10T15:56:25.624Z,1741622185.624 [DataOverHttps] Loaded
2025-03-10T15:56:25.624Z,1741622185.624 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-03-10T15:56:25.625Z,1741622185.625 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40ADE4E0
2025-03-10T15:56:25.626Z,1741622185.626 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1560
2025-03-10T15:56:25.649Z,1741622185.649 [Depth_Keller] Loaded
2025-03-10T15:56:25.649Z,1741622185.649 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-03-10T15:56:25.655Z,1741622185.655 [DropWeight] Loaded
2025-03-10T15:56:25.655Z,1741622185.655 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-03-10T15:56:25.697Z,1741622185.697 [DVL_micro] Loaded
2025-03-10T15:56:25.698Z,1741622185.698 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2025-03-10T15:56:25.761Z,1741622185.761 [NAL9602] Loaded
2025-03-10T15:56:25.762Z,1741622185.762 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-03-10T15:56:25.788Z,1741622185.788 [Onboard] Loaded
2025-03-10T15:56:25.789Z,1741622185.789 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-03-10T15:56:25.790Z,1741622185.790 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B0E4E0
2025-03-10T15:56:25.790Z,1741622185.790 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1561
2025-03-10T15:56:25.804Z,1741622185.804 [Power24vConverter] Loaded
2025-03-10T15:56:25.805Z,1741622185.805 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-03-10T15:56:25.821Z,1741622185.821 [Radio_Surface] Loaded
2025-03-10T15:56:25.822Z,1741622185.822 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-03-10T15:56:25.823Z,1741622185.823 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B3E4E0
2025-03-10T15:56:25.823Z,1741622185.823 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1562
2025-03-10T15:56:25.839Z,1741622185.839 [Sonardyne_Nano] Loaded
2025-03-10T15:56:25.839Z,1741622185.839 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-03-10T15:56:25.840Z,1741622185.840 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-03-10T15:56:25.840Z,1741622185.840 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-03-10T15:56:25.924Z,1741622185.924 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-03-10T15:56:25.924Z,1741622185.924 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-03-10T15:56:25.995Z,1741622185.995 [VerticalControl](DEBUG): Construct VerticalControl.
2025-03-10T15:56:26.047Z,1741622186.047 [VerticalControl] Loaded
2025-03-10T15:56:26.047Z,1741622186.047 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-03-10T15:56:26.050Z,1741622186.050 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-03-10T15:56:26.094Z,1741622186.094 [HorizontalControl] Loaded
2025-03-10T15:56:26.095Z,1741622186.095 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-03-10T15:56:26.097Z,1741622186.097 [SpeedControl](DEBUG): Construct SpeedControl.
2025-03-10T15:56:26.099Z,1741622186.099 [SpeedControl] Loaded
2025-03-10T15:56:26.099Z,1741622186.099 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-03-10T15:56:26.102Z,1741622186.102 [LoopControl](DEBUG): Construct LoopControl.
2025-03-10T15:56:26.103Z,1741622186.103 [LoopControl] Loaded
2025-03-10T15:56:26.103Z,1741622186.103 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-03-10T15:56:26.103Z,1741622186.103 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-03-10T15:56:26.104Z,1741622186.104 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-03-10T15:56:26.118Z,1741622186.118 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-03-10T15:56:26.119Z,1741622186.119 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-03-10T15:56:26.522Z,1741622186.522 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-03-10T15:56:26.529Z,1741622186.529 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-03-10T15:56:26.531Z,1741622186.531 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-03-10T15:56:26.543Z,1741622186.543 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-03-10T15:56:26.544Z,1741622186.544 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C644E0
2025-03-10T15:56:26.544Z,1741622186.544 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1563
2025-03-10T15:56:26.549Z,1741622186.549 [Supervisor](INFO): Main Thread ID is 1462
2025-03-10T15:56:26.549Z,1741622186.549 [Supervisor](DEBUG): Running supervisor.
2025-03-10T15:56:26.549Z,1741622186.549 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1564
2025-03-10T15:56:26.550Z,1741622186.550 [CommandExec](INFO): Initializing the command executive.
2025-03-10T15:56:26.551Z,1741622186.551 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1565
2025-03-10T15:56:26.555Z,1741622186.555 [controlThread ThreadHandler](INFO): Handler Thread ID is 1566
2025-03-10T15:56:26.556Z,1741622186.556 [controlThread](DEBUG): Initializing ControlThread
2025-03-10T15:56:26.557Z,1741622186.557 [SBIT](INFO): Initialize SBIT Component.
2025-03-10T15:56:26.558Z,1741622186.558 [SBIT](IMPORTANT): git: 2025-03-06_B
2025-03-10T15:56:26.558Z,1741622186.558 [SBIT](INFO): git hash: 24b4ce5baafc07409b46e74dcb267db57334b86b
2025-03-10T15:56:26.558Z,1741622186.558 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-03-10T15:56:26.560Z,1741622186.560 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-03-10T15:56:26.561Z,1741622186.561 [SBIT](INFO): Beginning SBIT in 68.000000 seconds.
2025-03-10T15:56:26.561Z,1741622186.561 [IBIT](INFO): Initialize IBIT Component.
2025-03-10T15:56:26.562Z,1741622186.562 [CBIT](DEBUG): Initialize CBIT Component.
2025-03-10T15:56:26.563Z,1741622186.563 [logger ThreadHandler](INFO): Handler Thread ID is 1567
2025-03-10T15:56:26.576Z,1741622186.576 [CBIT](DEBUG): Initialized mux pins.
2025-03-10T15:56:26.576Z,1741622186.576 [CBIT](DEBUG): Initializing the watchdog timer.
2025-03-10T15:56:26.589Z,1741622186.589 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1568
2025-03-10T15:56:26.589Z,1741622186.589 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-03-10T15:56:26.594Z,1741622186.594 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1570
2025-03-10T15:56:26.597Z,1741622186.597 [WetLabsBB2FL](INFO): Powering up
2025-03-10T15:56:26.598Z,1741622186.598 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1572
2025-03-10T15:56:26.600Z,1741622186.600 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-03-10T15:56:26.601Z,1741622186.601 [CBIT](DEBUG): Initializing heartbeat.
2025-03-10T15:56:26.615Z,1741622186.615 [DAT ThreadHandler](INFO): Handler Thread ID is 1573
2025-03-10T15:56:26.615Z,1741622186.615 [DAT](INFO): Powering up
2025-03-10T15:56:26.616Z,1741622186.616 [DAT](DEBUG): Initializing DAT.
2025-03-10T15:56:26.625Z,1741622186.625 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1575
2025-03-10T15:56:26.626Z,1741622186.626 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-03-10T15:56:26.633Z,1741622186.633 [Onboard ThreadHandler](INFO): Handler Thread ID is 1576
2025-03-10T15:56:26.662Z,1741622186.662 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1578
2025-03-10T15:56:26.666Z,1741622186.666 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1577
2025-03-10T15:56:26.672Z,1741622186.672 [CBIT](DEBUG): Deactivating emergency mode.
2025-03-10T15:56:26.712Z,1741622186.712 [CBIT](DEBUG): Backplane powered.
2025-03-10T15:56:26.713Z,1741622186.713 [GFScanner](DEBUG): Initializing GFScanner
2025-03-10T15:56:26.716Z,1741622186.716 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-03-10T15:56:26.717Z,1741622186.717 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-03-10T15:56:26.717Z,1741622186.717 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-03-10T15:56:26.718Z,1741622186.718 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-03-10T15:56:26.718Z,1741622186.718 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-03-10T15:56:26.719Z,1741622186.719 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-03-10T15:56:26.719Z,1741622186.719 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-03-10T15:56:26.726Z,1741622186.726 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-03-10T15:56:26.728Z,1741622186.728 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-03-10T15:56:26.729Z,1741622186.729 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-03-10T15:56:26.730Z,1741622186.730 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-03-10T15:56:26.730Z,1741622186.730 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-03-10T15:56:26.731Z,1741622186.731 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-03-10T15:56:26.731Z,1741622186.731 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-03-10T15:56:26.733Z,1741622186.733 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-03-10T15:56:26.998Z,1741622186.998 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-03-10T15:56:26.998Z,1741622186.998 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-03-10T15:56:26.998Z,1741622186.998 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-03-10T15:56:26.998Z,1741622186.998 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-03-10T15:56:26.999Z,1741622186.999 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-03-10T15:56:26.999Z,1741622186.999 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-03-10T15:56:26.999Z,1741622186.999 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-03-10T15:56:26.999Z,1741622186.999 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-03-10T15:56:27.030Z,1741622187.030 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-03-10T15:56:27.068Z,1741622187.068 [Radio_Surface](INFO): Powering up
2025-03-10T15:56:27.072Z,1741622187.072 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-10T15:56:27.109Z,1741622187.109 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2025-03-10T15:56:27.110Z,1741622187.110 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-03-10T15:56:27.110Z,1741622187.110 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-03-10T15:56:27.111Z,1741622187.111 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-03-10T15:56:27.462Z,1741622187.462 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-03-10T15:56:27.500Z,1741622187.500 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-03-10T15:56:27.502Z,1741622187.502 [Default:A.Wait](DEBUG): Construct Wait.
2025-03-10T15:56:27.504Z,1741622187.504 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-10T15:56:27.549Z,1741622187.549 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-03-10T15:56:27.551Z,1741622187.551 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-03-10T15:56:27.573Z,1741622187.573 [Default:E.Execute](DEBUG): Construct Execute.
2025-03-10T15:56:27.577Z,1741622187.577 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2025-03-10T15:56:27.581Z,1741622187.581 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-03-10T15:56:27.642Z,1741622187.642 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-03-10T15:56:27.789Z,1741622187.789 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-03-10T15:56:27.798Z,1741622187.798 [Power24vConverter](INFO): Powering up.
2025-03-10T15:56:27.798Z,1741622187.798 [Sonardyne_Nano](INFO): Initializing.
2025-03-10T15:56:27.840Z,1741622187.840 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-03-10T15:56:27.897Z,1741622187.897 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-03-10T15:56:27.905Z,1741622187.905 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-03-10T15:56:27.906Z,1741622187.906 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-03-10T15:56:27.912Z,1741622187.912 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-03-10T15:56:27.913Z,1741622187.913 [MassServo](DEBUG): Initializing EZServoServo.
2025-03-10T15:56:27.920Z,1741622187.920 [MassServo](DEBUG): Initializing MassServo.
2025-03-10T15:56:27.921Z,1741622187.921 [RudderServo](DEBUG): Initializing EZServoServo.
2025-03-10T15:56:27.928Z,1741622187.928 [RudderServo](DEBUG): Initializing RudderServo.
2025-03-10T15:56:27.929Z,1741622187.929 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-10T15:56:27.936Z,1741622187.936 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-10T15:56:29.213Z,1741622189.213 [WetLabsBB2FL](INFO): Powering down
2025-03-10T15:56:30.019Z,1741622190.019 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-03-10T15:56:31.257Z,1741622191.257 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2025-03-10T15:56:31.688Z,1741622191.688 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-03-10T15:56:33.485Z,1741622193.485 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-03-10T15:56:40.477Z,1741622200.477 [DAT](INFO): DAT read:
2025-03-10T15:56:40.478Z,1741622200.478 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-03-10T15:56:40.879Z,1741622200.879 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004857
2025-03-10T15:56:42.241Z,1741622202.241 [DAT](INFO): DAT read: MF Frequency Band
2025-03-10T15:56:42.242Z,1741622202.242 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-03-10T15:56:42.243Z,1741622202.243 [DAT](INFO): DAT read: Mar 7 2025 00:56:41
2025-03-10T15:56:43.249Z,1741622203.249 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-03-10T15:56:43.250Z,1741622203.250 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2025-03-10T15:56:43.251Z,1741622203.251 [DAT](INFO): commRate: 1200
2025-03-10T15:56:45.316Z,1741622205.316 [DAT](INFO): entering command mode
2025-03-10T15:56:45.517Z,1741622205.517 [DAT](INFO): DAT read:
2025-03-10T15:56:45.517Z,1741622205.517 [DAT](INFO): DAT read: user:1>
2025-03-10T15:56:45.518Z,1741622205.518 [DAT](INFO): setting verbose to 3
2025-03-10T15:56:45.769Z,1741622205.769 [DAT](INFO): DAT read: user:1>
2025-03-10T15:56:45.770Z,1741622205.770 [DAT](INFO): DAT read: Verbose | 3
2025-03-10T15:56:45.770Z,1741622205.770 [DAT](INFO): set verbose to 3
2025-03-10T15:56:45.770Z,1741622205.770 [DAT](INFO): setting DatVerbose to 27440
2025-03-10T15:56:46.021Z,1741622206.021 [DAT](INFO): DAT read: user:2>
2025-03-10T15:56:46.022Z,1741622206.022 [DAT](INFO): DAT read: DatVerbose | 27440
2025-03-10T15:56:46.022Z,1741622206.022 [DAT](INFO): set DatVerbose to 27440
2025-03-10T15:56:46.023Z,1741622206.023 [DAT](INFO): setting transmit power to 8
2025-03-10T15:56:46.273Z,1741622206.273 [DAT](INFO): DAT read: user:3>
2025-03-10T15:56:46.274Z,1741622206.274 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-03-10T15:56:46.274Z,1741622206.274 [DAT](INFO): set transmit power to 8
2025-03-10T15:56:46.275Z,1741622206.275 [DAT](INFO): setting local address to 5
2025-03-10T15:56:46.525Z,1741622206.525 [DAT](INFO): DAT read: user:4>
2025-03-10T15:56:46.526Z,1741622206.526 [DAT](INFO): DAT read: LocalAddr | 5
2025-03-10T15:56:46.526Z,1741622206.526 [DAT](INFO): set local address to 5
2025-03-10T15:56:46.528Z,1741622206.528 [DAT](INFO): Setting time to: 15:56:46 And date to:3/10/2025
2025-03-10T15:56:46.777Z,1741622206.777 [DAT](INFO): DAT read: user:5>
2025-03-10T15:56:46.778Z,1741622206.778 [DAT](INFO): DAT read: Mon Mar 10, 2025 15:56:46
2025-03-10T15:56:46.778Z,1741622206.778 [DAT](INFO): Local DAT time set to Mon Mar 10, 2025 15:56:46
2025-03-10T15:56:55.878Z,1741622215.878 [NAL9602](INFO): Powering up NAL9602
2025-03-10T15:57:06.795Z,1741622226.795 [NAL9602](INFO): NAL9602 initialized
2025-03-10T15:57:35.115Z,1741622255.115 [SBIT](IMPORTANT): Beginning Startup BIT
2025-03-10T15:58:29.246Z,1741622309.246 [SBIT](IMPORTANT): SBIT PASSED
2025-03-10T15:58:29.247Z,1741622309.247 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-03-10T15:58:29.248Z,1741622309.248 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;
2025-03-10T15:58:29.248Z,1741622309.248 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=214.355421 cubic_centimeter;
2025-03-10T15:58:29.248Z,1741622309.248 [SBIT](IMPORTANT): VerticalControl.massDefault=12.200269 millimeter;
2025-03-10T15:58:29.640Z,1741622309.640 [MissionManager](IMPORTANT): Started mission Startup
2025-03-10T15:58:29.663Z,1741622309.663 [Startup] Running Loop=1
2025-03-10T15:58:29.664Z,1741622309.664 [Startup](DEBUG): Aggregate::initialize Startup
2025-03-10T15:58:29.664Z,1741622309.664 [Startup:A.GoToSurface] Running Loop=1
2025-03-10T15:58:29.664Z,1741622309.664 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-10T15:58:29.665Z,1741622309.665 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-10T15:58:29.665Z,1741622309.665 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-10T15:58:29.665Z,1741622309.665 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-10T15:58:29.666Z,1741622309.666 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-10T15:58:29.666Z,1741622309.666 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-10T15:58:29.666Z,1741622309.666 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-10T15:58:29.668Z,1741622309.668 [Startup:StartupSatComms] Running Loop=1
2025-03-10T15:58:29.668Z,1741622309.668 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-03-10T15:58:29.668Z,1741622309.668 [Startup:StartupSatComms:A] Running Loop=1
2025-03-10T15:58:30.038Z,1741622310.038 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-03-10T15:59:27.086Z,1741622367.086 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-03-10T15:59:27.086Z,1741622367.086 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T15:59:27.097Z,1741622367.097 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T15:59:27.420Z,1741622367.420 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T15:59:27.420Z,1741622367.420 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-03-10T15:59:29.866Z,1741622369.866 [Startup:StartupSatComms:A](INFO): Timed out from 2025-03-10T15:58:29.7Z
2025-03-10T15:59:29.866Z,1741622369.866 [Startup:StartupSatComms:A] Stopped
2025-03-10T15:59:29.866Z,1741622369.866 [Startup:StartupSatComms:B] Running Loop=1
2025-03-10T15:59:30.247Z,1741622370.247 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-10T15:59:36.385Z,1741622376.385 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250306T182827/Courier0112.lzma
2025-03-10T15:59:37.387Z,1741622377.387 [DataOverHttps](INFO): Moved sent file to Logs/20250306T182827/Courier0112.lzma.bak
2025-03-10T15:59:37.387Z,1741622377.387 [DataOverHttps](INFO): SBD MOMSN=24149035
2025-03-10T15:59:53.121Z,1741622393.121 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250310T155613/Courier0000.lzma
2025-03-10T15:59:54.122Z,1741622394.122 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Courier0000.lzma.bak
2025-03-10T15:59:54.122Z,1741622394.122 [DataOverHttps](INFO): SBD MOMSN=24149037
2025-03-10T16:00:09.901Z,1741622409.901 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250306T182827/Express0113.lzma
2025-03-10T16:00:10.902Z,1741622410.902 [DataOverHttps](INFO): Moved sent file to Logs/20250306T182827/Express0113.lzma.bak
2025-03-10T16:00:10.902Z,1741622410.902 [DataOverHttps](INFO): SBD MOMSN=24149040
2025-03-10T16:00:26.769Z,1741622426.769 [DataOverHttps](INFO): Sending 614 bytes from file Logs/20250310T155613/Express0001.lzma
2025-03-10T16:00:27.770Z,1741622427.770 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Express0001.lzma.bak
2025-03-10T16:00:27.770Z,1741622427.770 [DataOverHttps](INFO): SBD MOMSN=24149043
2025-03-10T16:00:28.845Z,1741622428.845 [Startup:StartupSatComms:B] Stopped
2025-03-10T16:00:28.845Z,1741622428.845 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-03-10T16:00:28.845Z,1741622428.845 [Startup:StartupSatComms] Stopped
2025-03-10T16:00:28.846Z,1741622428.846 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-03-10T16:00:28.847Z,1741622428.847 [Startup](INFO): Completed Startup
2025-03-10T16:00:28.847Z,1741622428.847 [MissionManager](INFO): Startup is completed.
2025-03-10T16:00:28.847Z,1741622428.847 [MissionManager](INFO): Uninitializing Mission Startup
2025-03-10T16:00:28.847Z,1741622428.847 [Startup] Stopped
2025-03-10T16:00:28.847Z,1741622428.847 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-03-10T16:00:28.847Z,1741622428.847 [Startup:A.GoToSurface] Stopped
2025-03-10T16:00:28.847Z,1741622428.847 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-10T16:00:29.241Z,1741622429.241 [MissionManager](IMPORTANT): Started mission Default
2025-03-10T16:00:29.241Z,1741622429.241 [Default] Running Loop=1
2025-03-10T16:00:29.241Z,1741622429.241 [Default](DEBUG): Aggregate::initialize Default
2025-03-10T16:00:29.241Z,1741622429.241 [Default:B.GoToSurface] Running Loop=1
2025-03-10T16:00:29.241Z,1741622429.241 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-10T16:00:29.242Z,1741622429.242 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-10T16:00:29.242Z,1741622429.242 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-10T16:00:29.242Z,1741622429.242 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-10T16:00:29.243Z,1741622429.243 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-10T16:00:29.243Z,1741622429.243 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-10T16:00:29.243Z,1741622429.243 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-10T16:00:29.244Z,1741622429.244 [Default:A.Wait] Running Loop=1
2025-03-10T16:00:29.244Z,1741622429.244 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-03-10T16:00:42.594Z,1741622442.594 [Default:A.Wait](INFO): Done Waiting.
2025-03-10T16:00:42.594Z,1741622442.594 [Default:A.Wait] Stopped
2025-03-10T16:00:42.594Z,1741622442.594 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T16:00:42.989Z,1741622442.989 [Default:CheckIn] Running Loop=1
2025-03-10T16:00:42.989Z,1741622442.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-10T16:00:42.989Z,1741622442.989 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-10T16:00:43.382Z,1741622443.382 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-03-10T16:02:09.818Z,1741622529.818 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-03-10T16:02:28.065Z,1741622548.065 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-03-10T16:02:28.066Z,1741622548.066 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:02:28.076Z,1741622548.076 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:02:28.420Z,1741622548.420 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:02:28.420Z,1741622548.420 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-03-10T16:02:35.262Z,1741622555.262 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-03-10T16:04:01.336Z,1741622641.336 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-03-10T16:04:01.339Z,1741622641.339 [BPC1](INFO): Received data from all battery sticks.
2025-03-10T16:05:28.993Z,1741622728.993 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-03-10T16:05:28.993Z,1741622728.993 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:05:29.004Z,1741622729.004 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:05:29.410Z,1741622729.410 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:05:29.410Z,1741622729.410 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-03-10T16:05:43.150Z,1741622743.150 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T16:00:42.0Z
2025-03-10T16:05:43.150Z,1741622743.150 [Default:CheckIn:Read_GPS] Stopped
2025-03-10T16:05:43.150Z,1741622743.150 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-10T16:05:43.541Z,1741622743.541 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-10T16:05:43.973Z,1741622743.973 [Default:CheckIn:Read_Iridium] Stopped
2025-03-10T16:05:43.973Z,1741622743.973 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-10T16:05:43.973Z,1741622743.973 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-10T16:07:08.784Z,1741622828.784 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-10T16:07:08.784Z,1741622828.784 [NAL9602] Data Fault, FailCount= 1
2025-03-10T16:07:08.784Z,1741622828.784 [NAL9602](ERROR): Data Fault
2025-03-10T16:07:08.801Z,1741622828.801 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-10T16:07:09.175Z,1741622829.175 [NAL9602](INFO): Powering down
2025-03-10T16:07:10.007Z,1741622830.007 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-10T16:07:10.007Z,1741622830.007 [NAL9602] No Fault, FailCount= 1
2025-03-10T16:07:39.492Z,1741622859.492 [NAL9602](INFO): Powering up NAL9602
2025-03-10T16:07:50.400Z,1741622870.400 [NAL9602](INFO): NAL9602 initialized
2025-03-10T16:08:30.029Z,1741622910.029 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-03-10T16:08:30.029Z,1741622910.029 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:08:30.039Z,1741622910.039 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:08:30.414Z,1741622910.414 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:08:30.414Z,1741622910.414 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-03-10T16:10:44.534Z,1741623044.534 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-10T16:10:44.534Z,1741623044.534 [Default:CheckIn:C.Wait] Stopped
2025-03-10T16:10:44.534Z,1741623044.534 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T16:10:44.534Z,1741623044.534 [Default:CheckIn:D] Running Loop=1
2025-03-10T16:10:44.931Z,1741623044.931 [Default:CheckIn:D] Stopped
2025-03-10T16:10:44.931Z,1741623044.931 [Default:CheckIn:E] Running Loop=1
2025-03-10T16:10:45.344Z,1741623045.344 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.261495 min
2025-03-10T16:10:45.345Z,1741623045.345 [Default:CheckIn:E] Stopped
2025-03-10T16:10:45.345Z,1741623045.345 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-10T16:10:45.345Z,1741623045.345 [Default:CheckIn] Stopped
2025-03-10T16:10:45.345Z,1741623045.345 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-10T16:10:45.345Z,1741623045.345 [Default:CheckIn](INFO): Running loop #2
2025-03-10T16:10:45.345Z,1741623045.345 [Default:CheckIn] Running Loop=2
2025-03-10T16:10:45.345Z,1741623045.345 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-10T16:10:45.345Z,1741623045.345 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-10T16:11:30.984Z,1741623090.984 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-03-10T16:11:30.985Z,1741623090.985 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:11:30.995Z,1741623090.995 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:11:31.386Z,1741623091.386 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:11:31.386Z,1741623091.386 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-03-10T16:14:31.966Z,1741623271.966 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-03-10T16:14:31.966Z,1741623271.966 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:14:31.977Z,1741623271.977 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:14:32.392Z,1741623272.392 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:14:32.392Z,1741623272.392 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-03-10T16:15:45.508Z,1741623345.508 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T16:10:45.3Z
2025-03-10T16:15:45.508Z,1741623345.508 [Default:CheckIn:Read_GPS] Stopped
2025-03-10T16:15:45.509Z,1741623345.509 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-10T16:15:52.370Z,1741623352.370 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250310T155613/Courier0004.lzma
2025-03-10T16:15:53.370Z,1741623353.370 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Courier0004.lzma.bak
2025-03-10T16:15:53.370Z,1741623353.370 [DataOverHttps](INFO): SBD MOMSN=24149083
2025-03-10T16:16:09.152Z,1741623369.152 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250310T155613/Courier0007.lzma
2025-03-10T16:16:10.154Z,1741623370.154 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Courier0007.lzma.bak
2025-03-10T16:16:10.154Z,1741623370.154 [DataOverHttps](INFO): SBD MOMSN=24149085
2025-03-10T16:16:25.924Z,1741623385.924 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20250310T155613/Express0005.lzma
2025-03-10T16:16:26.926Z,1741623386.926 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Express0005.lzma.bak
2025-03-10T16:16:26.926Z,1741623386.926 [DataOverHttps](INFO): SBD MOMSN=24149087
2025-03-10T16:16:42.876Z,1741623402.876 [DataOverHttps](INFO): Sending 185 bytes from file Logs/20250310T155613/Express0008.lzma
2025-03-10T16:16:43.878Z,1741623403.878 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Express0008.lzma.bak
2025-03-10T16:16:43.878Z,1741623403.878 [DataOverHttps](INFO): SBD MOMSN=24149097
2025-03-10T16:16:44.916Z,1741623404.916 [Default:CheckIn:Read_Iridium] Stopped
2025-03-10T16:16:44.916Z,1741623404.916 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-10T16:16:44.916Z,1741623404.916 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-10T16:17:32.961Z,1741623452.961 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-03-10T16:17:32.961Z,1741623452.961 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:17:32.993Z,1741623452.993 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:17:33.413Z,1741623453.413 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:17:33.414Z,1741623453.414 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-03-10T16:17:51.963Z,1741623471.963 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-10T16:17:51.963Z,1741623471.963 [NAL9602] Data Fault, FailCount= 2
2025-03-10T16:17:51.963Z,1741623471.963 [NAL9602](ERROR): Data Fault
2025-03-10T16:17:51.986Z,1741623471.986 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-10T16:17:52.354Z,1741623472.354 [NAL9602](INFO): Powering down
2025-03-10T16:17:53.177Z,1741623473.177 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-10T16:17:53.177Z,1741623473.177 [NAL9602] No Fault, FailCount= 2
2025-03-10T16:18:22.646Z,1741623502.646 [NAL9602](INFO): Powering up NAL9602
2025-03-10T16:18:33.555Z,1741623513.555 [NAL9602](INFO): NAL9602 initialized
2025-03-10T16:20:33.956Z,1741623633.956 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-03-10T16:20:33.956Z,1741623633.956 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:20:33.993Z,1741623633.993 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:20:34.367Z,1741623634.367 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:20:34.367Z,1741623634.367 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-03-10T16:21:45.515Z,1741623705.515 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-10T16:21:45.516Z,1741623705.516 [Default:CheckIn:C.Wait] Stopped
2025-03-10T16:21:45.516Z,1741623705.516 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T16:21:45.516Z,1741623705.516 [Default:CheckIn:D] Running Loop=1
2025-03-10T16:21:45.935Z,1741623705.935 [Default:CheckIn:D] Stopped
2025-03-10T16:21:45.935Z,1741623705.935 [Default:CheckIn:E] Running Loop=1
2025-03-10T16:21:46.350Z,1741623706.350 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.278229 min
2025-03-10T16:21:46.350Z,1741623706.350 [Default:CheckIn:E] Stopped
2025-03-10T16:21:46.350Z,1741623706.350 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-10T16:21:46.350Z,1741623706.350 [Default:CheckIn] Stopped
2025-03-10T16:21:46.350Z,1741623706.350 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-10T16:21:46.350Z,1741623706.350 [Default:CheckIn](INFO): Running loop #3
2025-03-10T16:21:46.350Z,1741623706.350 [Default:CheckIn] Running Loop=3
2025-03-10T16:21:46.351Z,1741623706.351 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-10T16:21:46.351Z,1741623706.351 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-10T16:23:35.021Z,1741623815.021 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-03-10T16:23:35.021Z,1741623815.021 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:23:35.031Z,1741623815.031 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:23:35.445Z,1741623815.445 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:23:35.445Z,1741623815.445 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-03-10T16:26:36.017Z,1741623996.017 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-03-10T16:26:36.017Z,1741623996.017 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:26:36.029Z,1741623996.029 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:26:36.420Z,1741623996.420 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:26:36.420Z,1741623996.420 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-03-10T16:26:46.509Z,1741624006.509 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T16:21:46.4Z
2025-03-10T16:26:46.509Z,1741624006.509 [Default:CheckIn:Read_GPS] Stopped
2025-03-10T16:26:46.509Z,1741624006.509 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-10T16:26:53.304Z,1741624013.304 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250310T155613/Courier0010.lzma
2025-03-10T16:26:54.306Z,1741624014.306 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Courier0010.lzma.bak
2025-03-10T16:26:54.306Z,1741624014.306 [DataOverHttps](INFO): SBD MOMSN=24149121
2025-03-10T16:27:10.140Z,1741624030.140 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250310T155613/Express0011.lzma
2025-03-10T16:27:11.143Z,1741624031.143 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Express0011.lzma.bak
2025-03-10T16:27:11.143Z,1741624031.143 [DataOverHttps](INFO): SBD MOMSN=24149125
2025-03-10T16:27:12.355Z,1741624032.355 [Default:CheckIn:Read_Iridium] Stopped
2025-03-10T16:27:12.355Z,1741624032.355 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-10T16:27:12.355Z,1741624032.355 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-10T16:27:40.630Z,1741624060.630 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-03-10T16:28:35.167Z,1741624115.167 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-10T16:28:35.167Z,1741624115.167 [NAL9602] Data Fault, FailCount= 3
2025-03-10T16:28:35.167Z,1741624115.167 [NAL9602](ERROR): Data Fault
2025-03-10T16:28:35.183Z,1741624115.183 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-10T16:28:35.581Z,1741624115.581 [NAL9602](INFO): Powering down
2025-03-10T16:28:36.404Z,1741624116.404 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-10T16:28:36.404Z,1741624116.404 [NAL9602] No Fault, FailCount= 3
2025-03-10T16:29:05.871Z,1741624145.871 [NAL9602](INFO): Powering up NAL9602
2025-03-10T16:29:16.778Z,1741624156.778 [NAL9602](INFO): NAL9602 initialized
2025-03-10T16:29:37.010Z,1741624177.010 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2025-03-10T16:29:37.010Z,1741624177.010 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:29:37.026Z,1741624177.026 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:29:37.443Z,1741624177.443 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:29:37.443Z,1741624177.443 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2025-03-10T16:32:12.983Z,1741624332.983 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-10T16:32:12.983Z,1741624332.983 [Default:CheckIn:C.Wait] Stopped
2025-03-10T16:32:12.983Z,1741624332.983 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T16:32:12.983Z,1741624332.983 [Default:CheckIn:D] Running Loop=1
2025-03-10T16:32:13.398Z,1741624333.398 [Default:CheckIn:D] Stopped
2025-03-10T16:32:13.399Z,1741624333.399 [Default:CheckIn:E] Running Loop=1
2025-03-10T16:32:13.822Z,1741624333.822 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.735954 min
2025-03-10T16:32:13.822Z,1741624333.822 [Default:CheckIn:E] Stopped
2025-03-10T16:32:13.823Z,1741624333.823 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-10T16:32:13.823Z,1741624333.823 [Default:CheckIn] Stopped
2025-03-10T16:32:13.823Z,1741624333.823 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-10T16:32:13.823Z,1741624333.823 [Default:CheckIn](INFO): Running loop #4
2025-03-10T16:32:13.823Z,1741624333.823 [Default:CheckIn] Running Loop=4
2025-03-10T16:32:13.823Z,1741624333.823 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-10T16:32:13.823Z,1741624333.823 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-10T16:32:38.044Z,1741624358.044 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2025-03-10T16:32:38.044Z,1741624358.044 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:32:38.055Z,1741624358.055 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:32:38.472Z,1741624358.472 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:32:38.472Z,1741624358.472 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2025-03-10T16:35:39.014Z,1741624539.014 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2025-03-10T16:35:39.014Z,1741624539.014 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:35:39.025Z,1741624539.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:35:39.436Z,1741624539.436 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:35:39.436Z,1741624539.436 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2025-03-10T16:37:13.970Z,1741624633.970 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T16:32:13.8Z
2025-03-10T16:37:13.970Z,1741624633.970 [Default:CheckIn:Read_GPS] Stopped
2025-03-10T16:37:13.970Z,1741624633.970 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-10T16:37:21.520Z,1741624641.520 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250310T155613/Courier0013.lzma
2025-03-10T16:37:22.522Z,1741624642.522 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Courier0013.lzma.bak
2025-03-10T16:37:22.522Z,1741624642.522 [DataOverHttps](INFO): SBD MOMSN=24149192
2025-03-10T16:37:38.308Z,1741624658.308 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250310T155613/Express0014.lzma
2025-03-10T16:37:39.310Z,1741624659.310 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Express0014.lzma.bak
2025-03-10T16:37:39.310Z,1741624659.310 [DataOverHttps](INFO): SBD MOMSN=24149194
2025-03-10T16:37:40.629Z,1741624660.629 [Default:CheckIn:Read_Iridium] Stopped
2025-03-10T16:37:40.629Z,1741624660.629 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-10T16:37:40.630Z,1741624660.630 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-10T16:38:39.993Z,1741624719.993 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2025-03-10T16:38:39.994Z,1741624719.994 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:38:40.004Z,1741624720.004 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:38:40.419Z,1741624720.419 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:38:40.419Z,1741624720.419 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2025-03-10T16:39:17.967Z,1741624757.967 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-10T16:39:17.967Z,1741624757.967 [NAL9602] Data Fault, FailCount= 4
2025-03-10T16:39:17.967Z,1741624757.967 [NAL9602](ERROR): Data Fault
2025-03-10T16:39:18.013Z,1741624758.013 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-10T16:39:18.383Z,1741624758.383 [NAL9602](INFO): Powering down
2025-03-10T16:39:19.194Z,1741624759.194 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-10T16:39:19.194Z,1741624759.194 [NAL9602] No Fault, FailCount= 4
2025-03-10T16:39:48.668Z,1741624788.668 [NAL9602](INFO): Powering up NAL9602
2025-03-10T16:39:59.583Z,1741624799.583 [NAL9602](INFO): NAL9602 initialized
2025-03-10T16:41:41.004Z,1741624901.004 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2025-03-10T16:41:41.004Z,1741624901.004 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:41:41.015Z,1741624901.015 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:41:41.428Z,1741624901.428 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:41:41.428Z,1741624901.428 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2025-03-10T16:42:41.194Z,1741624961.194 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-10T16:42:41.194Z,1741624961.194 [Default:CheckIn:C.Wait] Stopped
2025-03-10T16:42:41.194Z,1741624961.194 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T16:42:41.194Z,1741624961.194 [Default:CheckIn:D] Running Loop=1
2025-03-10T16:42:41.599Z,1741624961.599 [Default:CheckIn:D] Stopped
2025-03-10T16:42:41.599Z,1741624961.599 [Default:CheckIn:E] Running Loop=1
2025-03-10T16:42:42.026Z,1741624962.026 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.205965 min
2025-03-10T16:42:42.026Z,1741624962.026 [Default:CheckIn:E] Stopped
2025-03-10T16:42:42.026Z,1741624962.026 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-10T16:42:42.026Z,1741624962.026 [Default:CheckIn] Stopped
2025-03-10T16:42:42.026Z,1741624962.026 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-10T16:42:42.026Z,1741624962.026 [Default:CheckIn](INFO): Running loop #5
2025-03-10T16:42:42.027Z,1741624962.027 [Default:CheckIn] Running Loop=5
2025-03-10T16:42:42.027Z,1741624962.027 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-10T16:42:42.027Z,1741624962.027 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-10T16:44:41.988Z,1741625081.988 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2025-03-10T16:44:41.989Z,1741625081.989 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:44:42.027Z,1741625082.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:44:42.403Z,1741625082.403 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:44:42.403Z,1741625082.403 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2025-03-10T16:47:42.190Z,1741625262.190 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T16:42:42.0Z
2025-03-10T16:47:42.190Z,1741625262.190 [Default:CheckIn:Read_GPS] Stopped
2025-03-10T16:47:42.190Z,1741625262.190 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-10T16:47:42.981Z,1741625262.981 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2025-03-10T16:47:42.981Z,1741625262.981 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:47:43.021Z,1741625263.021 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:47:43.397Z,1741625263.397 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:47:43.397Z,1741625263.397 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2025-03-10T16:47:49.524Z,1741625269.524 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250310T155613/Courier0016.lzma
2025-03-10T16:47:50.526Z,1741625270.526 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Courier0016.lzma.bak
2025-03-10T16:47:50.526Z,1741625270.526 [DataOverHttps](INFO): SBD MOMSN=24149210
2025-03-10T16:48:08.424Z,1741625288.424 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250310T155613/Express0017.lzma
2025-03-10T16:48:09.426Z,1741625289.426 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Express0017.lzma.bak
2025-03-10T16:48:09.426Z,1741625289.426 [DataOverHttps](INFO): SBD MOMSN=24149212
2025-03-10T16:48:10.489Z,1741625290.489 [Default:CheckIn:Read_Iridium] Stopped
2025-03-10T16:48:10.489Z,1741625290.489 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-10T16:48:10.489Z,1741625290.489 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-10T16:50:00.743Z,1741625400.743 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-10T16:50:00.743Z,1741625400.743 [NAL9602] Data Fault, FailCount= 5
2025-03-10T16:50:00.743Z,1741625400.743 [NAL9602](ERROR): Data Fault
2025-03-10T16:50:00.789Z,1741625400.789 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-10T16:50:00.789Z,1741625400.789 [CBIT](CRITICAL): Data Fault in component: NAL9602
2025-03-10T16:50:01.150Z,1741625401.150 [NAL9602](INFO): Powering down
2025-03-10T16:50:01.184Z,1741625401.184 [CommandExec](FAULT): Scheduling is paused
2025-03-10T16:50:01.185Z,1741625401.185 [CBIT](INFO): Critical error at 20250310T165000
2025-03-10T16:50:01.185Z,1741625401.185 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-03-10T16:50:43.979Z,1741625443.979 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2025-03-10T16:50:43.979Z,1741625443.979 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:50:43.995Z,1741625443.995 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:50:44.411Z,1741625444.411 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:50:44.411Z,1741625444.411 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2025-03-10T16:52:00.744Z,1741625520.744 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-10T16:52:00.744Z,1741625520.744 [NAL9602] No Fault, FailCount= 5
2025-03-10T16:52:01.135Z,1741625521.135 [NAL9602](INFO): Powering up NAL9602
2025-03-10T16:52:12.052Z,1741625532.052 [NAL9602](INFO): NAL9602 initialized
2025-03-10T16:53:11.057Z,1741625591.057 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-10T16:53:11.057Z,1741625591.057 [Default:CheckIn:C.Wait] Stopped
2025-03-10T16:53:11.057Z,1741625591.057 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T16:53:11.057Z,1741625591.057 [Default:CheckIn:D] Running Loop=1
2025-03-10T16:53:11.442Z,1741625591.442 [Default:CheckIn:D] Stopped
2025-03-10T16:53:11.442Z,1741625591.442 [Default:CheckIn:E] Running Loop=1
2025-03-10T16:53:11.846Z,1741625591.846 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.703345 min
2025-03-10T16:53:11.846Z,1741625591.846 [Default:CheckIn:E] Stopped
2025-03-10T16:53:11.846Z,1741625591.846 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-10T16:53:11.846Z,1741625591.846 [Default:CheckIn] Stopped
2025-03-10T16:53:11.846Z,1741625591.846 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-10T16:53:11.846Z,1741625591.846 [Default:CheckIn](INFO): Running loop #6
2025-03-10T16:53:11.847Z,1741625591.847 [Default:CheckIn] Running Loop=6
2025-03-10T16:53:11.847Z,1741625591.847 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-10T16:53:11.847Z,1741625591.847 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-10T16:53:44.982Z,1741625624.982 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2025-03-10T16:53:44.982Z,1741625624.982 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:53:44.992Z,1741625624.992 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:53:45.400Z,1741625625.400 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:53:45.400Z,1741625625.400 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2025-03-10T16:56:45.968Z,1741625805.968 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2025-03-10T16:56:45.968Z,1741625805.968 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:56:45.991Z,1741625805.991 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:56:46.414Z,1741625806.414 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:56:46.414Z,1741625806.414 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2025-03-10T16:58:12.045Z,1741625892.045 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T16:53:11.8Z
2025-03-10T16:58:12.046Z,1741625892.046 [Default:CheckIn:Read_GPS] Stopped
2025-03-10T16:58:12.046Z,1741625892.046 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-10T16:58:21.644Z,1741625901.644 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20250310T155613/Courier0019.lzma
2025-03-10T16:58:22.646Z,1741625902.646 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Courier0019.lzma.bak
2025-03-10T16:58:22.646Z,1741625902.646 [DataOverHttps](INFO): SBD MOMSN=24149328
2025-03-10T16:58:38.360Z,1741625918.360 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20250310T155613/Express0020.lzma
2025-03-10T16:58:39.362Z,1741625919.362 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Express0020.lzma.bak
2025-03-10T16:58:39.362Z,1741625919.362 [DataOverHttps](INFO): SBD MOMSN=24149331
2025-03-10T16:58:40.733Z,1741625920.733 [Default:CheckIn:Read_Iridium] Stopped
2025-03-10T16:58:40.734Z,1741625920.734 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-10T16:58:40.734Z,1741625920.734 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-10T16:59:46.965Z,1741625986.965 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2025-03-10T16:59:46.965Z,1741625986.965 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T16:59:46.975Z,1741625986.975 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T16:59:47.402Z,1741625987.402 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T16:59:47.402Z,1741625987.402 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2025-03-10T17:02:13.203Z,1741626133.203 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-10T17:02:13.203Z,1741626133.203 [NAL9602] Data Fault, FailCount= 1
2025-03-10T17:02:13.203Z,1741626133.203 [NAL9602](ERROR): Data Fault
2025-03-10T17:02:13.219Z,1741626133.219 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-10T17:02:13.614Z,1741626133.614 [NAL9602](INFO): Powering down
2025-03-10T17:02:14.430Z,1741626134.430 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-10T17:02:14.430Z,1741626134.430 [NAL9602] No Fault, FailCount= 1
2025-03-10T17:02:43.916Z,1741626163.916 [NAL9602](INFO): Powering up NAL9602
2025-03-10T17:02:47.950Z,1741626167.950 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2025-03-10T17:02:47.950Z,1741626167.950 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T17:02:47.961Z,1741626167.961 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T17:02:48.390Z,1741626168.390 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T17:02:48.390Z,1741626168.390 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2025-03-10T17:02:54.833Z,1741626174.833 [NAL9602](INFO): NAL9602 initialized
2025-03-10T17:03:41.282Z,1741626221.282 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-10T17:03:41.283Z,1741626221.283 [Default:CheckIn:C.Wait] Stopped
2025-03-10T17:03:41.283Z,1741626221.283 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T17:03:41.283Z,1741626221.283 [Default:CheckIn:D] Running Loop=1
2025-03-10T17:03:41.689Z,1741626221.689 [Default:CheckIn:D] Stopped
2025-03-10T17:03:41.689Z,1741626221.689 [Default:CheckIn:E] Running Loop=1
2025-03-10T17:03:42.110Z,1741626222.110 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.207454 min
2025-03-10T17:03:42.110Z,1741626222.110 [Default:CheckIn:E] Stopped
2025-03-10T17:03:42.110Z,1741626222.110 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-10T17:03:42.110Z,1741626222.110 [Default:CheckIn] Stopped
2025-03-10T17:03:42.110Z,1741626222.110 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-10T17:03:42.111Z,1741626222.111 [Default:CheckIn](INFO): Running loop #7
2025-03-10T17:03:42.111Z,1741626222.111 [Default:CheckIn] Running Loop=7
2025-03-10T17:03:42.111Z,1741626222.111 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-10T17:03:42.111Z,1741626222.111 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-10T17:05:49.117Z,1741626349.117 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2025-03-10T17:05:49.117Z,1741626349.117 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T17:05:49.160Z,1741626349.160 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T17:05:49.411Z,1741626349.411 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T17:05:49.411Z,1741626349.411 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2025-03-10T17:08:42.270Z,1741626522.270 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T17:03:42.1Z
2025-03-10T17:08:42.270Z,1741626522.270 [Default:CheckIn:Read_GPS] Stopped
2025-03-10T17:08:42.270Z,1741626522.270 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-10T17:08:49.592Z,1741626529.592 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250310T155613/Courier0022.lzma
2025-03-10T17:08:49.975Z,1741626529.975 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2025-03-10T17:08:49.976Z,1741626529.976 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T17:08:49.986Z,1741626529.986 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T17:08:50.358Z,1741626530.358 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T17:08:50.358Z,1741626530.358 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2025-03-10T17:08:50.594Z,1741626530.594 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Courier0022.lzma.bak
2025-03-10T17:08:50.594Z,1741626530.594 [DataOverHttps](INFO): SBD MOMSN=24149348
2025-03-10T17:09:06.440Z,1741626546.440 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250310T155613/Express0023.lzma
2025-03-10T17:09:07.442Z,1741626547.442 [DataOverHttps](INFO): Moved sent file to Logs/20250310T155613/Express0023.lzma.bak
2025-03-10T17:09:07.442Z,1741626547.442 [DataOverHttps](INFO): SBD MOMSN=24149350
2025-03-10T17:09:08.566Z,1741626548.566 [Default:CheckIn:Read_Iridium] Stopped
2025-03-10T17:09:08.566Z,1741626548.566 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-10T17:09:08.566Z,1741626548.566 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-10T17:11:38.017Z,1741626698.017 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6.
2025-03-10T17:11:38.019Z,1741626698.019 [BPC1](INFO): Received data from all battery sticks.
2025-03-10T17:11:50.965Z,1741626710.965 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25
2025-03-10T17:11:50.965Z,1741626710.965 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T17:11:50.976Z,1741626710.976 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T17:11:51.367Z,1741626711.367 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T17:11:51.367Z,1741626711.367 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25
2025-03-10T17:12:55.980Z,1741626775.980 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-10T17:12:55.980Z,1741626775.980 [NAL9602] Data Fault, FailCount= 2
2025-03-10T17:12:55.980Z,1741626775.980 [NAL9602](ERROR): Data Fault
2025-03-10T17:12:55.997Z,1741626775.997 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-10T17:12:56.395Z,1741626776.395 [NAL9602](INFO): Powering down
2025-03-10T17:12:57.210Z,1741626777.210 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-10T17:12:57.210Z,1741626777.210 [NAL9602] No Fault, FailCount= 2
2025-03-10T17:13:26.691Z,1741626806.691 [NAL9602](INFO): Powering up NAL9602
2025-03-10T17:13:37.595Z,1741626817.595 [NAL9602](INFO): NAL9602 initialized
2025-03-10T17:14:09.114Z,1741626849.114 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-03-10T17:14:09.114Z,1741626849.114 [Default:CheckIn:C.Wait] Stopped
2025-03-10T17:14:09.114Z,1741626849.114 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T17:14:09.114Z,1741626849.114 [Default:CheckIn:D] Running Loop=1
2025-03-10T17:14:09.527Z,1741626849.527 [Default:CheckIn:D] Stopped
2025-03-10T17:14:09.527Z,1741626849.527 [Default:CheckIn:E] Running Loop=1
2025-03-10T17:14:09.955Z,1741626849.955 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.671427 min
2025-03-10T17:14:09.955Z,1741626849.955 [Default:CheckIn:E] Stopped
2025-03-10T17:14:09.955Z,1741626849.955 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-03-10T17:14:09.955Z,1741626849.955 [Default:CheckIn] Stopped
2025-03-10T17:14:09.955Z,1741626849.955 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-10T17:14:09.955Z,1741626849.955 [Default:CheckIn](INFO): Running loop #8
2025-03-10T17:14:09.956Z,1741626849.956 [Default:CheckIn] Running Loop=8
2025-03-10T17:14:09.956Z,1741626849.956 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-10T17:14:09.956Z,1741626849.956 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-10T17:14:51.948Z,1741626891.948 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26
2025-03-10T17:14:51.948Z,1741626891.948 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T17:14:51.959Z,1741626891.959 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T17:14:52.386Z,1741626892.386 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T17:14:52.387Z,1741626892.387 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26
2025-03-10T17:14:59.349Z,1741626899.349 [CommandExec](IMPORTANT): got command strobe off
2025-03-10T17:14:59.350Z,1741626899.350 [CommandExec](IMPORTANT): Deactivating strobe
2025-03-10T17:15:04.985Z,1741626904.985 [CommandExec](IMPORTANT): got command gfscan
2025-03-10T17:15:25.822Z,1741626925.822 [CommandExec](IMPORTANT): got command configSet GFScanner.loadAtStartup 1 bool persist
2025-03-10T17:15:25.823Z,1741626925.823 [CommandExec](IMPORTANT): configSet GFScanner.loadAtStartup requires a restart to take effect.
2025-03-10T17:15:25.826Z,1741626925.826 [CommandExec](IMPORTANT): got command configSet GFScanner.simulateHardware 0 bool persist
2025-03-10T17:15:25.826Z,1741626925.826 [CommandExec](IMPORTANT): configSet GFScanner.simulateHardware requires a restart to take effect.
2025-03-10T17:15:29.478Z,1741626929.478 [CommandExec](IMPORTANT): got command restart application
2025-03-10T17:15:30.480Z,1741626930.480 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T17:15:30.480Z,1741626930.480 [CommandExec](INFO): Uninitializing the command executive.
2025-03-10T17:15:30.481Z,1741626930.481 [CommandExec](INFO): Uninitializing the command scheduler.
2025-03-10T17:15:30.481Z,1741626930.481 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:30.648Z,1741626930.648 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-03-10T17:15:30.648Z,1741626930.648 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-03-10T17:15:30.648Z,1741626930.648 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:30.649Z,1741626930.649 [NavChartDb](INFO): Join timeout helper Thread ID is 1637
2025-03-10T17:15:30.908Z,1741626930.908 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T17:15:30.909Z,1741626930.909 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:30.929Z,1741626930.929 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-03-10T17:15:30.929Z,1741626930.929 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:30.931Z,1741626930.931 [Radio_Surface](INFO): Join timeout helper Thread ID is 1638
2025-03-10T17:15:30.961Z,1741626930.961 [Radio_Surface](INFO): Powering down
2025-03-10T17:15:30.962Z,1741626930.962 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T17:15:30.962Z,1741626930.962 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:30.968Z,1741626930.968 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-03-10T17:15:30.968Z,1741626930.968 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:30.969Z,1741626930.969 [Onboard](INFO): Join timeout helper Thread ID is 1639
2025-03-10T17:15:31.437Z,1741626931.437 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-03-10T17:15:35.520Z,1741626935.520 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T17:15:35.521Z,1741626935.521 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:35.543Z,1741626935.543 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-03-10T17:15:35.543Z,1741626935.543 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:35.544Z,1741626935.544 [DataOverHttps](INFO): Join timeout helper Thread ID is 1640
2025-03-10T17:15:36.456Z,1741626936.456 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T17:15:36.457Z,1741626936.457 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:36.472Z,1741626936.472 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-03-10T17:15:36.472Z,1741626936.472 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:36.473Z,1741626936.473 [DAT](INFO): Join timeout helper Thread ID is 1641
2025-03-10T17:15:36.664Z,1741626936.664 [DAT](INFO): Powering down
2025-03-10T17:15:36.737Z,1741626936.737 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T17:15:36.737Z,1741626936.737 [DAT](INFO): Powering down
2025-03-10T17:15:36.738Z,1741626936.738 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:36.757Z,1741626936.757 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-03-10T17:15:36.757Z,1741626936.757 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:36.758Z,1741626936.758 [BackseatComponent](INFO): Join timeout helper Thread ID is 1642
2025-03-10T17:15:36.937Z,1741626936.937 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T17:15:36.937Z,1741626936.937 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:36.945Z,1741626936.945 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-03-10T17:15:36.945Z,1741626936.945 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:36.945Z,1741626936.945 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1643
2025-03-10T17:15:37.365Z,1741626937.365 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T17:15:37.365Z,1741626937.365 [WetLabsBB2FL](INFO): Powering down
2025-03-10T17:15:37.366Z,1741626937.366 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:37.385Z,1741626937.385 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-03-10T17:15:37.385Z,1741626937.385 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:37.386Z,1741626937.386 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1644
2025-03-10T17:15:38.232Z,1741626938.232 [CTD_Seabird](INFO): Powering down
2025-03-10T17:15:38.247Z,1741626938.247 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T17:15:38.248Z,1741626938.248 [CTD_Seabird](INFO): Powering down
2025-03-10T17:15:38.260Z,1741626938.260 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.265Z,1741626938.265 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-03-10T17:15:38.265Z,1741626938.265 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.265Z,1741626938.265 [logger](INFO): Join timeout helper Thread ID is 1645
2025-03-10T17:15:38.288Z,1741626938.288 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T17:15:38.289Z,1741626938.289 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.305Z,1741626938.305 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-03-10T17:15:38.305Z,1741626938.305 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.305Z,1741626938.305 [CommandLine](INFO): Join timeout helper Thread ID is 1646
2025-03-10T17:15:38.360Z,1741626938.360 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T17:15:38.361Z,1741626938.361 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.365Z,1741626938.365 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-03-10T17:15:38.365Z,1741626938.365 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.365Z,1741626938.365 [CommandExec](INFO): Join timeout helper Thread ID is 1647
2025-03-10T17:15:38.366Z,1741626938.366 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-03-10T17:15:38.367Z,1741626938.367 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.367Z,1741626938.367 [controlThread](INFO): Join timeout helper Thread ID is 1648
2025-03-10T17:15:38.372Z,1741626938.372 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T17:15:38.373Z,1741626938.373 [controlThread](DEBUG): Uninitializing ControlThread
2025-03-10T17:15:38.374Z,1741626938.374 [AHRS_M2](INFO): Powering down
2025-03-10T17:15:38.540Z,1741626938.540 [DVL_micro](INFO): Powering down
2025-03-10T17:15:38.541Z,1741626938.541 [NAL9602](INFO): Powering down
2025-03-10T17:15:38.542Z,1741626938.542 [Sonardyne_Nano](INFO): Powering down
2025-03-10T17:15:38.661Z,1741626938.661 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-03-10T17:15:38.662Z,1741626938.662 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-03-10T17:15:38.662Z,1741626938.662 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-03-10T17:15:38.662Z,1741626938.662 [MissionManager](INFO): Uninitializing Mission Default
2025-03-10T17:15:38.663Z,1741626938.663 [Default] Stopped
2025-03-10T17:15:38.663Z,1741626938.663 [Default](DEBUG): Aggregate::uninitialize Default
2025-03-10T17:15:38.663Z,1741626938.663 [Default:B.GoToSurface] Stopped
2025-03-10T17:15:38.663Z,1741626938.663 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-10T17:15:38.663Z,1741626938.663 [Default:CheckIn] Stopped
2025-03-10T17:15:38.663Z,1741626938.663 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-10T17:15:38.663Z,1741626938.663 [Default:CheckIn:Read_GPS] Stopped
2025-03-10T17:15:38.666Z,1741626938.666 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-03-10T17:15:38.666Z,1741626938.666 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-03-10T17:15:38.667Z,1741626938.667 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-03-10T17:15:38.667Z,1741626938.667 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-03-10T17:15:38.667Z,1741626938.667 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-03-10T17:15:38.668Z,1741626938.668 [BuoyancyServo](INFO): Powering down
2025-03-10T17:15:38.680Z,1741626938.680 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-03-10T17:15:38.680Z,1741626938.680 [ElevatorServo](INFO): Powering down
2025-03-10T17:15:38.681Z,1741626938.681 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-03-10T17:15:38.681Z,1741626938.681 [MassServo](INFO): Powering down
2025-03-10T17:15:38.682Z,1741626938.682 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-03-10T17:15:38.682Z,1741626938.682 [RudderServo](INFO): Powering down
2025-03-10T17:15:38.683Z,1741626938.683 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-10T17:15:38.683Z,1741626938.683 [ThrusterHE](INFO): Powering down
2025-03-10T17:15:38.684Z,1741626938.684 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-03-10T17:15:38.684Z,1741626938.684 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-03-10T17:15:38.685Z,1741626938.685 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-03-10T17:15:38.685Z,1741626938.685 [CBIT](DEBUG): Powering off loads.
2025-03-10T17:15:38.696Z,1741626938.696 [CBIT](DEBUG): Disabling WDT.
2025-03-10T17:15:38.708Z,1741626938.708 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-03-10T17:15:38.709Z,1741626938.709 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.742Z,1741626938.742 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-03-10T17:15:38.757Z,1741626938.757 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.764Z,1741626938.764 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.795Z,1741626938.795 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.797Z,1741626938.797 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.804Z,1741626938.804 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.811Z,1741626938.811 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.855Z,1741626938.855 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.909Z,1741626938.909 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-10T17:15:38.974Z,1741626938.974 [logger ThreadHandler](INFO): Thread cancelled.