2025-04-01T20:22:42.240Z,1743538962.240 [Supervisor](DEBUG): Initializing supervisor.
2025-04-01T20:22:42.244Z,1743538962.244 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-04-01T20:22:42.244Z,1743538962.244 [SyncHandler](INFO): Protected caller Thread ID is 8502
2025-04-01T20:22:42.245Z,1743538962.245 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-04-01T20:22:42.246Z,1743538962.246 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-04-01T20:22:42.246Z,1743538962.246 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8503
2025-04-01T20:22:42.250Z,1743538962.250 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-04-01T20:22:42.270Z,1743538962.270 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-04-01T20:22:42.271Z,1743538962.271 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-04-01T20:22:42.272Z,1743538962.272 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8504
2025-04-01T20:22:42.276Z,1743538962.276 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-04-01T20:22:42.277Z,1743538962.277 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-04-01T20:22:42.277Z,1743538962.277 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8505
2025-04-01T20:22:42.279Z,1743538962.279 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-04-01T20:22:42.280Z,1743538962.280 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-04-01T20:22:42.281Z,1743538962.281 [logger ThreadHandler](INFO): Protected caller Thread ID is 8506
2025-04-01T20:22:42.284Z,1743538962.284 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-04-01T20:22:42.285Z,1743538962.285 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-04-01T20:22:42.287Z,1743538962.287 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-04-01T20:22:42.588Z,1743538962.588 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-04-01T20:22:42.588Z,1743538962.588 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-04-01T20:22:42.708Z,1743538962.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-04-01T20:22:42.709Z,1743538962.709 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-04-01T20:22:42.817Z,1743538962.817 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-04-01T20:22:42.818Z,1743538962.818 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-04-01T20:22:43.325Z,1743538963.325 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-04-01T20:22:43.327Z,1743538963.327 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-04-01T20:22:43.698Z,1743538963.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-04-01T20:22:43.699Z,1743538963.699 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-04-01T20:22:44.066Z,1743538964.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-04-01T20:22:44.067Z,1743538964.067 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-04-01T20:22:44.274Z,1743538964.274 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-04-01T20:22:44.274Z,1743538964.274 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-04-01T20:22:44.368Z,1743538964.368 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-04-01T20:22:44.462Z,1743538964.462 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-04-01T20:22:44.896Z,1743538964.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-04-01T20:22:44.897Z,1743538964.897 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-04-01T20:22:45.206Z,1743538965.206 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-04-01T20:22:45.206Z,1743538965.206 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-04-01T20:22:45.872Z,1743538965.872 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-04-01T20:22:45.874Z,1743538965.874 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-04-01T20:22:46.540Z,1743538966.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-04-01T20:22:46.541Z,1743538966.541 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-04-01T20:22:46.803Z,1743538966.803 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-04-01T20:22:46.804Z,1743538966.804 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-04-01T20:22:46.957Z,1743538966.957 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-04-01T20:22:46.958Z,1743538966.958 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-04-01T20:22:47.856Z,1743538967.856 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-04-01T20:22:47.858Z,1743538967.858 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2025-04-01T20:22:47.859Z,1743538967.859 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2025-04-01T20:22:47.957Z,1743538967.957 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2025-04-01T20:22:48.104Z,1743538968.104 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2025-04-01T20:22:48.387Z,1743538968.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-04-01T20:22:48.388Z,1743538968.388 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2025-04-01T20:22:48.525Z,1743538968.525 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2025-04-01T20:22:48.636Z,1743538968.636 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2025-04-01T20:22:48.742Z,1743538968.742 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2025-04-01T20:22:48.864Z,1743538968.864 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2025-04-01T20:22:48.961Z,1743538968.961 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2025-04-01T20:22:49.061Z,1743538969.061 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2025-04-01T20:22:49.165Z,1743538969.165 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2025-04-01T20:22:49.350Z,1743538969.350 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2025-04-01T20:22:49.350Z,1743538969.350 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-04-01T20:22:49.365Z,1743538969.365 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-04-01T20:22:49.437Z,1743538969.437 [DepthRateCalculator] Loaded
2025-04-01T20:22:49.437Z,1743538969.437 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-04-01T20:22:49.442Z,1743538969.442 [PitchRateCalculator] Loaded
2025-04-01T20:22:49.443Z,1743538969.443 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-04-01T20:22:49.453Z,1743538969.453 [SpeedCalculator] Loaded
2025-04-01T20:22:49.453Z,1743538969.453 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-04-01T20:22:49.458Z,1743538969.458 [YawRateCalculator] Loaded
2025-04-01T20:22:49.458Z,1743538969.458 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-04-01T20:22:49.480Z,1743538969.480 [ElevatorOffsetCalculator] Loaded
2025-04-01T20:22:49.480Z,1743538969.480 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-04-01T20:22:49.481Z,1743538969.481 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-04-01T20:22:49.483Z,1743538969.483 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-04-01T20:22:49.532Z,1743538969.532 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-04-01T20:22:49.532Z,1743538969.532 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-04-01T20:22:49.548Z,1743538969.548 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-04-01T20:22:49.549Z,1743538969.549 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-04-01T20:22:50.392Z,1743538970.392 [AHRS_M2] Loaded
2025-04-01T20:22:50.392Z,1743538970.392 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-04-01T20:22:50.649Z,1743538970.649 [BackseatComponent] Loaded
2025-04-01T20:22:50.649Z,1743538970.649 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-04-01T20:22:50.650Z,1743538970.650 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408754E0
2025-04-01T20:22:50.651Z,1743538970.651 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8589
2025-04-01T20:22:50.654Z,1743538970.654 [LcmUniversalReporter] Loaded
2025-04-01T20:22:50.654Z,1743538970.654 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-04-01T20:22:51.445Z,1743538971.445 [BPC1] Loaded
2025-04-01T20:22:51.445Z,1743538971.445 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-04-01T20:22:51.844Z,1743538971.844 [DAT] Loaded
2025-04-01T20:22:51.845Z,1743538971.845 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-04-01T20:22:51.847Z,1743538971.847 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408A54E0
2025-04-01T20:22:51.847Z,1743538971.847 [DAT ThreadHandler](INFO): Protected caller Thread ID is 8590
2025-04-01T20:22:52.067Z,1743538972.067 [DataOverHttps] Loaded
2025-04-01T20:22:52.067Z,1743538972.067 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-04-01T20:22:52.068Z,1743538972.068 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D54E0
2025-04-01T20:22:52.068Z,1743538972.068 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8591
2025-04-01T20:22:52.091Z,1743538972.091 [Depth_Keller] Loaded
2025-04-01T20:22:52.091Z,1743538972.091 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-04-01T20:22:52.096Z,1743538972.096 [DropWeight] Loaded
2025-04-01T20:22:52.096Z,1743538972.096 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-04-01T20:22:52.117Z,1743538972.117 [MultiRay](INFO): Found secondary power supply at: /dev/loadC7
2025-04-01T20:22:52.118Z,1743538972.118 [MultiRay] Loaded
2025-04-01T20:22:52.118Z,1743538972.118 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2025-04-01T20:22:52.242Z,1743538972.242 [NAL9602] Loaded
2025-04-01T20:22:52.243Z,1743538972.243 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-04-01T20:22:52.272Z,1743538972.272 [Onboard] Loaded
2025-04-01T20:22:52.273Z,1743538972.273 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-04-01T20:22:52.274Z,1743538972.274 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409054E0
2025-04-01T20:22:52.274Z,1743538972.274 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8592
2025-04-01T20:22:52.280Z,1743538972.280 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2025-04-01T20:22:52.293Z,1743538972.293 [PowerOnly] Loaded
2025-04-01T20:22:52.293Z,1743538972.293 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-04-01T20:22:52.307Z,1743538972.307 [Power24vConverter] Loaded
2025-04-01T20:22:52.307Z,1743538972.307 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-04-01T20:22:52.324Z,1743538972.324 [Radio_Surface] Loaded
2025-04-01T20:22:52.325Z,1743538972.325 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-04-01T20:22:52.326Z,1743538972.326 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409354E0
2025-04-01T20:22:52.326Z,1743538972.326 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8593
2025-04-01T20:22:52.351Z,1743538972.351 [Waterlinked] Loaded
2025-04-01T20:22:52.351Z,1743538972.351 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2025-04-01T20:22:52.351Z,1743538972.351 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-04-01T20:22:52.352Z,1743538972.352 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-04-01T20:22:52.491Z,1743538972.491 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-04-01T20:22:52.492Z,1743538972.492 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-04-01T20:22:52.505Z,1743538972.505 [NavChart] Loaded
2025-04-01T20:22:52.505Z,1743538972.505 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-04-01T20:22:52.511Z,1743538972.511 [UniversalFixResidualReporter] Loaded
2025-04-01T20:22:52.511Z,1743538972.511 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-04-01T20:22:52.512Z,1743538972.512 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-04-01T20:22:52.513Z,1743538972.513 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-04-01T20:22:52.709Z,1743538972.709 [SBIT](DEBUG): Construct Startup Built In Test.
2025-04-01T20:22:52.723Z,1743538972.723 [SBIT] Loaded
2025-04-01T20:22:52.723Z,1743538972.723 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-04-01T20:22:52.726Z,1743538972.726 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-04-01T20:22:52.739Z,1743538972.739 [IBIT] Loaded
2025-04-01T20:22:52.739Z,1743538972.739 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-04-01T20:22:52.744Z,1743538972.744 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-04-01T20:22:52.823Z,1743538972.823 [CBIT] Loaded
2025-04-01T20:22:52.823Z,1743538972.823 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-04-01T20:22:52.855Z,1743538972.855 [GFScanner] Loaded
2025-04-01T20:22:52.855Z,1743538972.855 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-04-01T20:22:52.856Z,1743538972.856 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-04-01T20:22:52.856Z,1743538972.856 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-04-01T20:22:53.072Z,1743538973.072 [CTD_Seabird] Loaded
2025-04-01T20:22:53.072Z,1743538973.072 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-04-01T20:22:53.074Z,1743538973.074 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A474E0
2025-04-01T20:22:53.075Z,1743538973.075 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8594
2025-04-01T20:22:53.097Z,1743538973.097 [PAR_Licor] Loaded
2025-04-01T20:22:53.097Z,1743538973.097 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-04-01T20:22:53.142Z,1743538973.142 [WetLabsBB2FL] Loaded
2025-04-01T20:22:53.142Z,1743538973.142 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-04-01T20:22:53.144Z,1743538973.144 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A774E0
2025-04-01T20:22:53.144Z,1743538973.144 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8595
2025-04-01T20:22:53.145Z,1743538973.145 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-04-01T20:22:53.145Z,1743538973.145 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-04-01T20:22:53.315Z,1743538973.315 [BuoyancyServo] Loaded
2025-04-01T20:22:53.315Z,1743538973.315 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-04-01T20:22:53.339Z,1743538973.339 [ElevatorServo] Loaded
2025-04-01T20:22:53.339Z,1743538973.339 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-04-01T20:22:53.362Z,1743538973.362 [MassServo] Loaded
2025-04-01T20:22:53.362Z,1743538973.362 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-04-01T20:22:53.385Z,1743538973.385 [RudderServo] Loaded
2025-04-01T20:22:53.385Z,1743538973.385 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-04-01T20:22:53.403Z,1743538973.403 [ThrusterHE] Loaded
2025-04-01T20:22:53.403Z,1743538973.403 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-04-01T20:22:53.404Z,1743538973.404 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-04-01T20:22:53.404Z,1743538973.404 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-04-01T20:22:53.476Z,1743538973.476 [VerticalControl](DEBUG): Construct VerticalControl.
2025-04-01T20:22:53.527Z,1743538973.527 [VerticalControl] Loaded
2025-04-01T20:22:53.528Z,1743538973.528 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-04-01T20:22:53.531Z,1743538973.531 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-04-01T20:22:53.574Z,1743538973.574 [HorizontalControl] Loaded
2025-04-01T20:22:53.575Z,1743538973.575 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-04-01T20:22:53.577Z,1743538973.577 [SpeedControl](DEBUG): Construct SpeedControl.
2025-04-01T20:22:53.579Z,1743538973.579 [SpeedControl] Loaded
2025-04-01T20:22:53.579Z,1743538973.579 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-04-01T20:22:53.582Z,1743538973.582 [LoopControl](DEBUG): Construct LoopControl.
2025-04-01T20:22:53.582Z,1743538973.582 [LoopControl] Loaded
2025-04-01T20:22:53.583Z,1743538973.583 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-04-01T20:22:53.583Z,1743538973.583 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-04-01T20:22:53.584Z,1743538973.584 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-04-01T20:22:53.747Z,1743538973.747 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-04-01T20:22:53.748Z,1743538973.748 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-04-01T20:22:53.963Z,1743538973.963 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-04-01T20:22:53.965Z,1743538973.965 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-04-01T20:22:54.373Z,1743538974.373 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-04-01T20:22:54.373Z,1743538974.373 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-04-01T20:22:54.460Z,1743538974.460 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-04-01T20:22:54.466Z,1743538974.466 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-04-01T20:22:54.469Z,1743538974.469 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-04-01T20:22:54.482Z,1743538974.482 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-04-01T20:22:54.483Z,1743538974.483 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0
2025-04-01T20:22:54.483Z,1743538974.483 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8596
2025-04-01T20:22:54.488Z,1743538974.488 [Supervisor](INFO): Main Thread ID is 8501
2025-04-01T20:22:54.488Z,1743538974.488 [Supervisor](DEBUG): Running supervisor.
2025-04-01T20:22:54.489Z,1743538974.489 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8597
2025-04-01T20:22:54.489Z,1743538974.489 [CommandExec](INFO): Initializing the command executive.
2025-04-01T20:22:54.491Z,1743538974.491 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8598
2025-04-01T20:22:54.495Z,1743538974.495 [controlThread ThreadHandler](INFO): Handler Thread ID is 8599
2025-04-01T20:22:54.496Z,1743538974.496 [controlThread](DEBUG): Initializing ControlThread
2025-04-01T20:22:54.497Z,1743538974.497 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-04-01T20:22:54.497Z,1743538974.497 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-04-01T20:22:54.497Z,1743538974.497 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-04-01T20:22:54.498Z,1743538974.498 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-04-01T20:22:54.498Z,1743538974.498 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-04-01T20:22:54.502Z,1743538974.502 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-04-01T20:22:54.503Z,1743538974.503 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-04-01T20:22:54.504Z,1743538974.504 [SBIT](INFO): Initialize SBIT Component.
2025-04-01T20:22:54.504Z,1743538974.504 [SBIT](IMPORTANT): git: 2025-03-26-1-g1815535cc
2025-04-01T20:22:54.504Z,1743538974.504 [SBIT](INFO): git hash: 1815535cca105e464bdb7831f6233b5043f0283f
2025-04-01T20:22:54.505Z,1743538974.505 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-04-01T20:22:54.506Z,1743538974.506 [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-04-01T20:22:54.507Z,1743538974.507 [SBIT](INFO): Beginning SBIT in 120.000000 seconds.
2025-04-01T20:22:54.507Z,1743538974.507 [IBIT](INFO): Initialize IBIT Component.
2025-04-01T20:22:54.508Z,1743538974.508 [CBIT](DEBUG): Initialize CBIT Component.
2025-04-01T20:22:54.509Z,1743538974.509 [logger ThreadHandler](INFO): Handler Thread ID is 8600
2025-04-01T20:22:54.519Z,1743538974.519 [CBIT](DEBUG): Initialized mux pins.
2025-04-01T20:22:54.519Z,1743538974.519 [CBIT](DEBUG): Initializing the watchdog timer.
2025-04-01T20:22:54.528Z,1743538974.528 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8601
2025-04-01T20:22:54.543Z,1743538974.543 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2025-04-01T20:22:54.544Z,1743538974.544 [CBIT](DEBUG): Initializing heartbeat.
2025-04-01T20:22:54.544Z,1743538974.544 [DAT ThreadHandler](INFO): Handler Thread ID is 8602
2025-04-01T20:22:54.545Z,1743538974.545 [DAT](INFO): Powering up
2025-04-01T20:22:54.545Z,1743538974.545 [DAT](DEBUG): Initializing DAT.
2025-04-01T20:22:54.549Z,1743538974.549 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8604
2025-04-01T20:22:54.551Z,1743538974.551 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-04-01T20:22:54.572Z,1743538974.572 [Onboard ThreadHandler](INFO): Handler Thread ID is 8605
2025-04-01T20:22:54.589Z,1743538974.589 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8606
2025-04-01T20:22:54.612Z,1743538974.612 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8607
2025-04-01T20:22:54.612Z,1743538974.612 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-04-01T20:22:54.615Z,1743538974.615 [CBIT](DEBUG): Deactivating emergency mode.
2025-04-01T20:22:54.617Z,1743538974.617 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8609
2025-04-01T20:22:54.620Z,1743538974.620 [WetLabsBB2FL](INFO): Powering up
2025-04-01T20:22:54.621Z,1743538974.621 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8611
2025-04-01T20:22:54.629Z,1743538974.629 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-04-01T20:22:54.629Z,1743538974.629 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-04-01T20:22:54.629Z,1743538974.629 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-04-01T20:22:54.629Z,1743538974.629 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-04-01T20:22:54.629Z,1743538974.629 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-04-01T20:22:54.629Z,1743538974.629 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-04-01T20:22:54.630Z,1743538974.630 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-04-01T20:22:54.630Z,1743538974.630 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-04-01T20:22:54.655Z,1743538974.655 [CBIT](DEBUG): Backplane powered.
2025-04-01T20:22:54.656Z,1743538974.656 [GFScanner](DEBUG): Initializing GFScanner
2025-04-01T20:22:54.656Z,1743538974.656 [GFScanner](DEBUG): Deactivating GF circuits.
2025-04-01T20:22:54.665Z,1743538974.665 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-04-01T20:22:54.666Z,1743538974.666 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-04-01T20:22:54.667Z,1743538974.667 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-04-01T20:22:54.668Z,1743538974.668 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-04-01T20:22:54.668Z,1743538974.668 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-04-01T20:22:54.669Z,1743538974.669 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-04-01T20:22:54.669Z,1743538974.669 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-04-01T20:22:54.674Z,1743538974.674 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-04-01T20:22:54.758Z,1743538974.758 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-04-01T20:22:54.762Z,1743538974.762 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-04-01T20:22:54.786Z,1743538974.786 [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-04-01T20:22:54.787Z,1743538974.787 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-04-01T20:22:54.787Z,1743538974.787 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-04-01T20:22:54.790Z,1743538974.790 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-04-01T20:22:54.996Z,1743538974.996 [Radio_Surface](INFO): Powering up
2025-04-01T20:22:55.014Z,1743538975.014 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2025-04-01T20:22:55.040Z,1743538975.040 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-04-01T20:22:55.042Z,1743538975.042 [Default:A.Wait](DEBUG): Construct Wait.
2025-04-01T20:22:55.044Z,1743538975.044 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-04-01T20:22:55.068Z,1743538975.068 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-04-01T20:22:55.071Z,1743538975.071 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-04-01T20:22:55.089Z,1743538975.089 [Default:E.Execute](DEBUG): Construct Execute.
2025-04-01T20:22:55.100Z,1743538975.100 [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-04-01T20:22:55.116Z,1743538975.116 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,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-04-01T20:22:55.124Z,1743538975.124 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-04-01T20:22:55.264Z,1743538975.264 [Depth_Keller](INFO): Initializing.
2025-04-01T20:22:55.265Z,1743538975.265 [MultiRay](INFO): Powering up MultiRay Lights
2025-04-01T20:22:55.265Z,1743538975.265 [MultiRay](INFO): Powering up secondary power supply.
2025-04-01T20:22:55.301Z,1743538975.301 [Power24vConverter](INFO): Powering up.
2025-04-01T20:22:55.310Z,1743538975.310 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-04-01T20:22:55.331Z,1743538975.331 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-04-01T20:22:55.344Z,1743538975.344 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-04-01T20:22:55.345Z,1743538975.345 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-01T20:22:55.352Z,1743538975.352 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-01T20:22:55.353Z,1743538975.353 [MassServo](DEBUG): Initializing EZServoServo.
2025-04-01T20:22:55.360Z,1743538975.360 [MassServo](DEBUG): Initializing MassServo.
2025-04-01T20:22:55.360Z,1743538975.360 [RudderServo](DEBUG): Initializing EZServoServo.
2025-04-01T20:22:55.368Z,1743538975.368 [RudderServo](DEBUG): Initializing RudderServo.
2025-04-01T20:22:55.368Z,1743538975.368 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-04-01T20:22:55.376Z,1743538975.376 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-04-01T20:22:55.378Z,1743538975.378 [CommandExec](FAULT): Scheduling is paused
2025-04-01T20:22:55.378Z,1743538975.378 [CBIT](INFO): Critical error at 20250401T202254
2025-04-01T20:22:55.378Z,1743538975.378 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-04-01T20:22:55.581Z,1743538975.581 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-04-01T20:22:55.582Z,1743538975.582 [DropWeight] Hardware Fault, FailCount= 1
2025-04-01T20:22:55.582Z,1743538975.582 [DropWeight](ERROR): Hardware Fault
2025-04-01T20:22:55.666Z,1743538975.666 [CBIT](INFO): Critical error at 20250401T202255
2025-04-01T20:22:55.673Z,1743538975.673 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-04-01T20:22:55.673Z,1743538975.673 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-04-01T20:22:55.971Z,1743538975.971 [CBIT](INFO): Critical error at 20250401T202255
2025-04-01T20:22:57.243Z,1743538977.243 [WetLabsBB2FL](INFO): Powering down
2025-04-01T20:23:01.212Z,1743538981.212 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-04-01T20:23:05.567Z,1743538985.567 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005776
2025-04-01T20:23:06.396Z,1743538986.396 [DAT](INFO): DAT read: 

2025-04-01T20:23:06.400Z,1743538986.400 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-04-01T20:23:07.904Z,1743538987.904 [DAT](INFO): DAT read: MF Frequency Band

2025-04-01T20:23:07.905Z,1743538987.905 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-04-01T20:23:07.906Z,1743538987.906 [DAT](INFO): DAT read: Mar 27 2025 22:54:12 

2025-04-01T20:23:08.912Z,1743538988.912 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-04-01T20:23:08.914Z,1743538988.914 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2025-04-01T20:23:08.914Z,1743538988.914 [DAT](INFO): commRate: 800
2025-04-01T20:23:10.980Z,1743538990.980 [DAT](INFO): entering command mode
2025-04-01T20:23:11.180Z,1743538991.180 [DAT](INFO): DAT read: 

2025-04-01T20:23:11.181Z,1743538991.181 [DAT](INFO): DAT read: user:1>

2025-04-01T20:23:11.181Z,1743538991.181 [DAT](INFO): setting verbose to 3
2025-04-01T20:23:11.432Z,1743538991.432 [DAT](INFO): DAT read: user:1>

2025-04-01T20:23:11.433Z,1743538991.433 [DAT](INFO): DAT read: Verbose         | 3           

2025-04-01T20:23:11.433Z,1743538991.433 [DAT](INFO): set verbose to 3
2025-04-01T20:23:11.434Z,1743538991.434 [DAT](INFO): setting DatVerbose to 27440
2025-04-01T20:23:11.684Z,1743538991.684 [DAT](INFO): DAT read: user:2>

2025-04-01T20:23:11.686Z,1743538991.686 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-04-01T20:23:11.686Z,1743538991.686 [DAT](INFO): set DatVerbose to 27440
2025-04-01T20:23:11.687Z,1743538991.687 [DAT](INFO): setting transmit power to 8
2025-04-01T20:23:11.936Z,1743538991.936 [DAT](INFO): DAT read: user:3>

2025-04-01T20:23:11.937Z,1743538991.937 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-04-01T20:23:11.937Z,1743538991.937 [DAT](INFO): set transmit power to 8
2025-04-01T20:23:11.938Z,1743538991.938 [DAT](INFO): setting local address to 9
2025-04-01T20:23:12.189Z,1743538992.189 [DAT](INFO): DAT read: user:4>

2025-04-01T20:23:12.190Z,1743538992.190 [DAT](INFO): DAT read: LocalAddr       | 9           

2025-04-01T20:23:12.190Z,1743538992.190 [DAT](INFO): set local address to 9
2025-04-01T20:23:12.192Z,1743538992.192 [DAT](INFO): Setting time to: 20:23:12 And date to:4/1/2025
2025-04-01T20:23:12.443Z,1743538992.443 [DAT](INFO): DAT read: user:5>

2025-04-01T20:23:12.444Z,1743538992.444 [DAT](INFO): DAT read: Tue Apr 1, 2025  20:23:12  

2025-04-01T20:23:12.444Z,1743538992.444 [DAT](INFO): Local DAT time set to Tue Apr 1, 2025  20:23:12  

2025-04-01T20:23:22.398Z,1743539002.398 [NAL9602](INFO): Powering up NAL9602
2025-04-01T20:23:25.392Z,1743539005.392 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch
2025-04-01T20:23:25.393Z,1743539005.393 [CommandExec](IMPORTANT): Onboard.Pressure 9.882669 psi
2025-04-01T20:23:32.528Z,1743539012.528 [Waterlinked](INFO): Powering down
2025-04-01T20:23:33.209Z,1743539013.209 [NAL9602](INFO): NAL9602 initialized
2025-04-01T20:23:35.692Z,1743539015.692 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2025-04-01T20:23:35.692Z,1743539015.692 [CommandExec](IMPORTANT): Onboard.Humidity 8.008790 %
2025-04-01T20:24:33.616Z,1743539073.616 [CommandExec](IMPORTANT): got command gfscan
2025-04-01T20:24:33.973Z,1743539073.973 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-01T20:24:38.404Z,1743539078.404 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006439
CHAN A1 (24V): 0.000174
CHAN A2 (12V): -0.006006
CHAN A3 (5V): -0.002560
CHAN B0 (3.3V): -0.001641
CHAN B1 (3.15aV): -0.001724
CHAN B2 (3.15bV): -0.001553
CHAN B3 (GND): -0.000747
OPEN: 0.004780
Full Scale: +/- 1 mA
2025-04-01T20:24:42.480Z,1743539082.480 [CommandExec](IMPORTANT): got command failComponent
2025-04-01T20:24:42.480Z,1743539082.480 [CommandExec](IMPORTANT): Failed components:
2025-04-01T20:24:42.481Z,1743539082.481 [CommandExec](IMPORTANT): DropWeight: Hardware Fault
2025-04-01T20:24:54.973Z,1743539094.973 [SBIT](IMPORTANT): Beginning Startup BIT
2025-04-01T20:24:54.977Z,1743539094.977 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-01T20:24:59.320Z,1743539099.320 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006297
CHAN A1 (24V): 0.000449
CHAN A2 (12V): -0.005799
CHAN A3 (5V): -0.002960
CHAN B0 (3.3V): -0.001743
CHAN B1 (3.15aV): -0.001902
CHAN B2 (3.15bV): -0.001182
CHAN B3 (GND): -0.000500
OPEN: 0.005136
Full Scale: +/- 1 mA
2025-04-01T20:25:49.125Z,1743539149.125 [SBIT](IMPORTANT): SBIT PASSED
2025-04-01T20:25:49.125Z,1743539149.125 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-04-01T20:25:49.126Z,1743539149.126 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;

2025-04-01T20:25:49.126Z,1743539149.126 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;

2025-04-01T20:25:49.126Z,1743539149.126 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool;

2025-04-01T20:25:49.126Z,1743539149.126 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool;

2025-04-01T20:25:49.127Z,1743539149.127 [SBIT](IMPORTANT): MultiRay.brightnessWhite=100 none;

2025-04-01T20:25:49.127Z,1743539149.127 [SBIT](IMPORTANT): MultiRay.loadAtStartup=1 bool;

2025-04-01T20:25:49.127Z,1743539149.127 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool;

2025-04-01T20:25:49.127Z,1743539149.127 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;

2025-04-01T20:25:49.127Z,1743539149.127 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=80 cubic_centimeter;

2025-04-01T20:25:49.127Z,1743539149.127 [SBIT](IMPORTANT): VerticalControl.elevLimit=10 degree;

2025-04-01T20:25:49.127Z,1743539149.127 [SBIT](IMPORTANT): VerticalControl.kdDepth=0.2 radian_per_second_per_meter;

2025-04-01T20:25:49.128Z,1743539149.128 [SBIT](IMPORTANT): VerticalControl.kpDepth=0.15 radian_per_meter;

2025-04-01T20:25:49.128Z,1743539149.128 [SBIT](IMPORTANT): VerticalControl.massDefault=-25 millimeter;

2025-04-01T20:25:49.461Z,1743539149.461 [MissionManager](IMPORTANT): Started mission Startup
2025-04-01T20:25:49.462Z,1743539149.462 [Startup] Running Loop=1
2025-04-01T20:25:49.462Z,1743539149.462 [Startup](DEBUG): Aggregate::initialize Startup
2025-04-01T20:25:49.462Z,1743539149.462 [Startup:A.GoToSurface] Running Loop=1
2025-04-01T20:25:49.462Z,1743539149.462 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-01T20:25:49.462Z,1743539149.462 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-04-01T20:25:49.463Z,1743539149.463 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-04-01T20:25:49.463Z,1743539149.463 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-04-01T20:25:49.464Z,1743539149.464 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-04-01T20:25:49.464Z,1743539149.464 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-01T20:25:49.464Z,1743539149.464 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-01T20:25:49.466Z,1743539149.466 [Startup:StartupSatComms] Running Loop=1
2025-04-01T20:25:49.466Z,1743539149.466 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-04-01T20:25:49.466Z,1743539149.466 [Startup:StartupSatComms:A] Running Loop=1
2025-04-01T20:25:49.869Z,1743539149.869 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-04-01T20:25:54.711Z,1743539154.711 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-04-01T20:25:54.712Z,1743539154.712 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-01T20:25:54.726Z,1743539154.726 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-01T20:25:55.145Z,1743539155.145 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-01T20:25:55.145Z,1743539155.145 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-04-01T20:26:49.670Z,1743539209.670 [Startup:StartupSatComms:A](INFO): Timed out from 2025-04-01T20:25:49.5Z
2025-04-01T20:26:49.670Z,1743539209.670 [Startup:StartupSatComms:A] Stopped
2025-04-01T20:26:49.670Z,1743539209.670 [Startup:StartupSatComms:B] Running Loop=1
2025-04-01T20:26:50.081Z,1743539210.081 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-04-01T20:26:56.977Z,1743539216.977 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250327T211740/Courier0045.lzma
2025-04-01T20:26:57.977Z,1743539217.977 [DataOverHttps](INFO): Moved sent file to Logs/20250327T211740/Courier0045.lzma.bak
2025-04-01T20:26:57.978Z,1743539217.978 [DataOverHttps](INFO): SBD MOMSN=24512455
2025-04-01T20:27:13.920Z,1743539233.920 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20250401T202242/Courier0000.lzma
2025-04-01T20:27:14.921Z,1743539234.921 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Courier0000.lzma.bak
2025-04-01T20:27:14.921Z,1743539234.921 [DataOverHttps](INFO): SBD MOMSN=24512457
2025-04-01T20:27:30.612Z,1743539250.612 [DataOverHttps](INFO): Sending 97 bytes from file Logs/20250327T211740/Express0046.lzma
2025-04-01T20:27:31.613Z,1743539251.613 [DataOverHttps](INFO): Moved sent file to Logs/20250327T211740/Express0046.lzma.bak
2025-04-01T20:27:31.614Z,1743539251.614 [DataOverHttps](INFO): SBD MOMSN=24512463
2025-04-01T20:27:47.562Z,1743539267.562 [DataOverHttps](INFO): Sending 1163 bytes from file Logs/20250401T202242/Express0001.lzma
2025-04-01T20:27:48.563Z,1743539268.563 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0001.lzma.bak
2025-04-01T20:27:48.563Z,1743539268.563 [DataOverHttps](INFO): SBD MOMSN=24512466
2025-04-01T20:27:49.882Z,1743539269.882 [Startup:StartupSatComms:B](INFO): Timed out from 2025-04-01T20:26:49.7Z
2025-04-01T20:27:49.882Z,1743539269.882 [Startup:StartupSatComms:B] Stopped
2025-04-01T20:27:49.882Z,1743539269.882 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-04-01T20:27:49.882Z,1743539269.882 [Startup:StartupSatComms] Stopped
2025-04-01T20:27:49.882Z,1743539269.882 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-04-01T20:27:49.884Z,1743539269.884 [Startup](INFO): Completed Startup
2025-04-01T20:27:49.884Z,1743539269.884 [MissionManager](INFO): Startup is completed.
2025-04-01T20:27:49.884Z,1743539269.884 [MissionManager](INFO): Uninitializing Mission Startup
2025-04-01T20:27:49.884Z,1743539269.884 [Startup] Stopped
2025-04-01T20:27:49.884Z,1743539269.884 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-04-01T20:27:49.884Z,1743539269.884 [Startup:A.GoToSurface] Stopped
2025-04-01T20:27:49.884Z,1743539269.884 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-01T20:27:50.309Z,1743539270.309 [MissionManager](IMPORTANT): Started mission Default
2025-04-01T20:27:50.309Z,1743539270.309 [Default] Running Loop=1
2025-04-01T20:27:50.309Z,1743539270.309 [Default](DEBUG): Aggregate::initialize Default
2025-04-01T20:27:50.310Z,1743539270.310 [Default:B.GoToSurface] Running Loop=1
2025-04-01T20:27:50.310Z,1743539270.310 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-04-01T20:27:50.310Z,1743539270.310 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-04-01T20:27:50.310Z,1743539270.310 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-04-01T20:27:50.310Z,1743539270.310 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-04-01T20:27:50.311Z,1743539270.311 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-04-01T20:27:50.311Z,1743539270.311 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-04-01T20:27:50.312Z,1743539270.312 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-04-01T20:27:50.312Z,1743539270.312 [Default:A.Wait] Running Loop=1
2025-04-01T20:27:50.312Z,1743539270.312 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-04-01T20:28:03.649Z,1743539283.649 [Default:A.Wait](INFO): Done Waiting.
2025-04-01T20:28:03.649Z,1743539283.649 [Default:A.Wait] Stopped
2025-04-01T20:28:03.649Z,1743539283.649 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T20:28:03.988Z,1743539283.988 [Default:CheckIn] Running Loop=1
2025-04-01T20:28:03.988Z,1743539283.988 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T20:28:03.988Z,1743539283.988 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T20:28:04.402Z,1743539284.402 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-04-01T20:28:36.293Z,1743539316.293 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-04-01T20:28:55.717Z,1743539335.717 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-04-01T20:28:55.717Z,1743539335.717 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-01T20:28:55.728Z,1743539335.728 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-01T20:28:56.136Z,1743539336.136 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-01T20:28:56.136Z,1743539336.136 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-04-01T20:30:17.325Z,1743539417.325 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-04-01T20:30:17.329Z,1743539417.329 [BPC1](INFO): Received data from all battery sticks.
2025-04-01T20:31:56.710Z,1743539516.710 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-04-01T20:31:56.710Z,1743539516.710 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-01T20:31:56.720Z,1743539516.720 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-01T20:31:57.125Z,1743539517.125 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-01T20:31:57.126Z,1743539517.126 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-04-01T20:33:03.763Z,1743539583.763 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203302.00,A,3648.17687,N,12147.28489,W,0.019,308.21,010425,,,A*76
2025-04-01T20:33:03.766Z,1743539583.766 [NAL9602](INFO): GPS fix at 20250401T203302: (36.802948, -121.788082)
2025-04-01T20:33:03.778Z,1743539583.778 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T20:33:03.778Z,1743539583.778 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T20:33:04.193Z,1743539584.193 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-04-01T20:33:11.493Z,1743539591.493 [DataOverHttps](INFO): Sending 289 bytes from file Logs/20250401T202242/Courier0004.lzma
2025-04-01T20:33:12.485Z,1743539592.485 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Courier0004.lzma.bak
2025-04-01T20:33:12.485Z,1743539592.485 [DataOverHttps](INFO): SBD MOMSN=24512521
2025-04-01T20:33:28.352Z,1743539608.352 [DataOverHttps](INFO): Sending 400 bytes from file Logs/20250401T202242/Express0005.lzma
2025-04-01T20:33:29.353Z,1743539609.353 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0005.lzma.bak
2025-04-01T20:33:29.353Z,1743539609.353 [DataOverHttps](INFO): SBD MOMSN=24512524
2025-04-01T20:33:30.843Z,1743539610.843 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T20:33:30.844Z,1743539610.844 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T20:33:30.844Z,1743539610.844 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T20:33:36.488Z,1743539616.488 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T20:37:55.916Z,1743539875.916 [CBIT](INFO): Clearing failed state for component DropWeight
2025-04-01T20:37:55.916Z,1743539875.916 [DropWeight] No Fault, FailCount= 1
2025-04-01T20:38:31.422Z,1743539911.422 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T20:38:31.422Z,1743539911.422 [Default:CheckIn:C.Wait] Stopped
2025-04-01T20:38:31.422Z,1743539911.422 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T20:38:31.422Z,1743539911.422 [Default:CheckIn:D] Running Loop=1
2025-04-01T20:38:31.821Z,1743539911.821 [Default:CheckIn:D] Stopped
2025-04-01T20:38:31.821Z,1743539911.821 [Default:CheckIn:E] Running Loop=1
2025-04-01T20:38:32.233Z,1743539912.233 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  10.691864 min 
2025-04-01T20:38:32.233Z,1743539912.233 [Default:CheckIn:E] Stopped
2025-04-01T20:38:32.233Z,1743539912.233 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T20:38:32.233Z,1743539912.233 [Default:CheckIn] Stopped
2025-04-01T20:38:32.233Z,1743539912.233 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T20:38:32.233Z,1743539912.233 [Default:CheckIn](INFO): Running loop #2
2025-04-01T20:38:32.233Z,1743539912.233 [Default:CheckIn] Running Loop=2
2025-04-01T20:38:32.233Z,1743539912.233 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T20:38:32.233Z,1743539912.233 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T20:38:34.236Z,1743539914.236 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203832.00,A,3648.17731,N,12147.28446,W,0.019,334.41,010425,,,A*78
2025-04-01T20:38:34.238Z,1743539914.238 [NAL9602](INFO): GPS fix at 20250401T203832: (36.802955, -121.788074)
2025-04-01T20:38:34.248Z,1743539914.248 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T20:38:34.248Z,1743539914.248 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T20:38:41.795Z,1743539921.795 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250401T202242/Courier0007.lzma
2025-04-01T20:38:42.798Z,1743539922.798 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Courier0007.lzma.bak
2025-04-01T20:38:42.798Z,1743539922.798 [DataOverHttps](INFO): SBD MOMSN=24512579
2025-04-01T20:38:58.591Z,1743539938.591 [DataOverHttps](INFO): Sending 183 bytes from file Logs/20250401T202242/Express0008.lzma
2025-04-01T20:38:59.594Z,1743539939.594 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0008.lzma.bak
2025-04-01T20:38:59.594Z,1743539939.594 [DataOverHttps](INFO): SBD MOMSN=24512582
2025-04-01T20:39:00.919Z,1743539940.919 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T20:39:00.919Z,1743539940.919 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T20:39:00.919Z,1743539940.919 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T20:39:04.937Z,1743539944.937 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-04-01T20:39:05.011Z,1743539945.011 [NAL9602](ERROR): received: 
+CSQ:0

OK
2025-04-01T20:39:29.183Z,1743539969.183 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=18769, MT Status=1, MTMSN=1083
2025-04-01T20:39:29.183Z,1743539969.183 [NAL9602](INFO): Data available in MT queue
2025-04-01T20:39:29.680Z,1743539969.680 [NAL9602](INFO): Received command: strobe on
2025-04-01T20:39:29.773Z,1743539969.773 [CommandExec](IMPORTANT): got command strobe on
2025-04-01T20:39:29.773Z,1743539969.773 [CommandExec](IMPORTANT): Activating strobe
2025-04-01T20:40:00.288Z,1743540000.288 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T20:41:30.141Z,1743540090.141 [DataOverHttps](IMPORTANT): SBD MTMSN=20250401T204129
2025-04-01T20:41:37.599Z,1743540097.599 [DataOverHttps](INFO): Received command: ibit
2025-04-01T20:41:37.660Z,1743540097.660 [CommandExec](IMPORTANT): got command ibit
2025-04-01T20:41:37.688Z,1743540097.688 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-04-01T20:41:37.688Z,1743540097.688 [IBIT](IMPORTANT): Beginning control surface checks.
2025-04-01T20:41:37.691Z,1743540097.691 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-01T20:41:38.604Z,1743540098.604 [DataOverHttps](IMPORTANT): SBD MTMSN=20250401T204137
2025-04-01T20:41:39.276Z,1743540099.276 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204138.00,A,3648.17761,N,12147.28301,W,0.019,334.41,010425,,,A*7D
2025-04-01T20:41:39.279Z,1743540099.279 [NAL9602](INFO): GPS fix at 20250401T204138: (36.802960, -121.788050)
2025-04-01T20:41:42.467Z,1743540102.467 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007899
CHAN A1 (24V): 0.000601
CHAN A2 (12V): -0.005747
CHAN A3 (5V): -0.002860
CHAN B0 (3.3V): -0.000820
CHAN B1 (3.15aV): -0.001567
CHAN B2 (3.15bV): -0.001617
CHAN B3 (GND): -0.000324
OPEN: 0.004964
Full Scale: +/- 1 mA
2025-04-01T20:41:48.875Z,1743540108.875 [DataOverHttps](INFO): Received command: ibit
2025-04-01T20:41:48.904Z,1743540108.904 [CommandExec](IMPORTANT): got command ibit
2025-04-01T20:41:52.663Z,1743540112.663 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=18770, MT Status=1, MTMSN=1084
2025-04-01T20:41:52.663Z,1743540112.663 [NAL9602](INFO): Data available in MT queue
2025-04-01T20:41:53.205Z,1743540113.205 [NAL9602](INFO): Received command: configSet VerticalControl.massDefault -15 mm persist
2025-04-01T20:41:53.688Z,1743540113.688 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault -15 millimeter persist
2025-04-01T20:41:54.275Z,1743540114.275 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204153.00,A,3648.17668,N,12147.28283,W,0.078,334.41,010425,,,A*74
2025-04-01T20:41:54.277Z,1743540114.277 [NAL9602](INFO): GPS fix at 20250401T204153: (36.802945, -121.788047)
2025-04-01T20:41:57.098Z,1743540117.098 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204156.00,A,3648.17650,N,12147.28287,W,0.058,334.41,010425,,,A*7C
2025-04-01T20:41:57.101Z,1743540117.101 [NAL9602](INFO): GPS fix at 20250401T204156: (36.802942, -121.788048)
2025-04-01T20:41:59.936Z,1743540119.936 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204159.00,A,3648.17638,N,12147.28292,W,0.058,334.41,010425,,,A*79
2025-04-01T20:41:59.939Z,1743540119.939 [NAL9602](INFO): GPS fix at 20250401T204159: (36.802940, -121.788049)
2025-04-01T20:42:02.763Z,1743540122.763 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204202.00,A,3648.17635,N,12147.28298,W,0.136,334.41,010425,,,A*7A
2025-04-01T20:42:02.765Z,1743540122.765 [NAL9602](INFO): GPS fix at 20250401T204202: (36.802939, -121.788050)
2025-04-01T20:42:05.989Z,1743540125.989 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204205.00,A,3648.17633,N,12147.28310,W,0.039,334.41,010425,,,A*74
2025-04-01T20:42:05.991Z,1743540125.991 [NAL9602](INFO): GPS fix at 20250401T204205: (36.802939, -121.788052)
2025-04-01T20:42:08.816Z,1743540128.816 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204208.00,A,3648.17638,N,12147.28322,W,0.136,334.41,010425,,,A*7D
2025-04-01T20:42:08.818Z,1743540128.818 [NAL9602](INFO): GPS fix at 20250401T204208: (36.802940, -121.788054)
2025-04-01T20:42:12.047Z,1743540132.047 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204211.00,A,3648.17642,N,12147.28328,W,0.097,334.41,010425,,,A*78
2025-04-01T20:42:12.049Z,1743540132.049 [NAL9602](INFO): GPS fix at 20250401T204211: (36.802940, -121.788055)
2025-04-01T20:42:14.875Z,1743540134.875 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204214.00,A,3648.17636,N,12147.28334,W,0.019,334.41,010425,,,A*75
2025-04-01T20:42:14.877Z,1743540134.877 [NAL9602](INFO): GPS fix at 20250401T204214: (36.802939, -121.788056)
2025-04-01T20:42:18.107Z,1743540138.107 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204217.00,A,3648.17627,N,12147.28340,W,0.078,334.41,010425,,,A*72
2025-04-01T20:42:18.109Z,1743540138.109 [NAL9602](INFO): GPS fix at 20250401T204217: (36.802938, -121.788057)
2025-04-01T20:42:20.943Z,1743540140.943 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204220.00,A,3648.17621,N,12147.28343,W,0.058,334.41,010425,,,A*71
2025-04-01T20:42:20.945Z,1743540140.945 [NAL9602](INFO): GPS fix at 20250401T204220: (36.802937, -121.788057)
2025-04-01T20:42:23.762Z,1743540143.762 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204223.00,A,3648.17627,N,12147.28350,W,0.058,334.41,010425,,,A*76
2025-04-01T20:42:23.765Z,1743540143.765 [NAL9602](INFO): GPS fix at 20250401T204223: (36.802938, -121.788058)
2025-04-01T20:42:24.206Z,1743540144.206 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802937 Longitude: -121.788055
2025-04-01T20:42:24.600Z,1743540144.600 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.977000
2025-04-01T20:42:24.601Z,1743540144.601 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-04-01T20:42:24.601Z,1743540144.601 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-04-01T20:42:24.989Z,1743540144.989 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-04-01T20:42:24.989Z,1743540144.989 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-04-01T20:42:24.990Z,1743540144.990 [IBIT](IMPORTANT): Pressure:9.835336 PSI
2025-04-01T20:42:24.990Z,1743540144.990 [IBIT](IMPORTANT): Humidity:7.905017 %
2025-04-01T20:42:25.397Z,1743540145.397 [IBIT](IMPORTANT): Vehicle Pitch:-0.431761 degrees
2025-04-01T20:42:25.397Z,1743540145.397 [IBIT](IMPORTANT): Vehicle  Roll:6.843953 degrees
2025-04-01T20:42:25.397Z,1743540145.397 [IBIT](IMPORTANT): Vehicle  Heading:200.339508 degrees
2025-04-01T20:42:25.925Z,1743540145.925 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-04-01T20:42:25.925Z,1743540145.925 [IBIT](IMPORTANT): buoyancyNeutral: 80.000000 cc
2025-04-01T20:42:25.925Z,1743540145.925 [IBIT](IMPORTANT): massDefault: -2.500000 cm
2025-04-01T20:42:25.925Z,1743540145.925 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2025-04-01T20:42:25.926Z,1743540145.926 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-04-01T20:42:25.926Z,1743540145.926 [IBIT](IMPORTANT): IBIT PASSED
2025-04-01T20:42:56.485Z,1743540176.485 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T20:44:01.539Z,1743540241.539 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T20:44:01.539Z,1743540241.539 [Default:CheckIn:C.Wait] Stopped
2025-04-01T20:44:01.540Z,1743540241.540 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T20:44:01.540Z,1743540241.540 [Default:CheckIn:D] Running Loop=1
2025-04-01T20:44:01.945Z,1743540241.945 [Default:CheckIn:D] Stopped
2025-04-01T20:44:01.945Z,1743540241.945 [Default:CheckIn:E] Running Loop=1
2025-04-01T20:44:02.356Z,1743540242.356 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  16.193922 min 
2025-04-01T20:44:02.357Z,1743540242.357 [Default:CheckIn:E] Stopped
2025-04-01T20:44:02.357Z,1743540242.357 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T20:44:02.357Z,1743540242.357 [Default:CheckIn] Stopped
2025-04-01T20:44:02.357Z,1743540242.357 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T20:44:02.357Z,1743540242.357 [Default:CheckIn](INFO): Running loop #3
2025-04-01T20:44:02.357Z,1743540242.357 [Default:CheckIn] Running Loop=3
2025-04-01T20:44:02.357Z,1743540242.357 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T20:44:02.357Z,1743540242.357 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T20:44:04.363Z,1743540244.363 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204403.00,A,3648.17620,N,12147.28382,W,0.136,334.41,010425,,,A*73
2025-04-01T20:44:04.365Z,1743540244.365 [NAL9602](INFO): GPS fix at 20250401T204403: (36.802937, -121.788064)
2025-04-01T20:44:04.412Z,1743540244.412 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T20:44:04.412Z,1743540244.412 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T20:44:07.566Z,1743540247.566 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250401T202242/Courier0010.lzma
2025-04-01T20:44:08.253Z,1743540248.253 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Courier0010.lzma.bak
2025-04-01T20:44:08.253Z,1743540248.253 [DataOverHttps](INFO): SBD MOMSN=24512607
2025-04-01T20:44:24.371Z,1743540264.371 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250401T202242/Courier0013.lzma
2025-04-01T20:44:25.373Z,1743540265.373 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Courier0013.lzma.bak
2025-04-01T20:44:25.373Z,1743540265.373 [DataOverHttps](INFO): SBD MOMSN=24512611
2025-04-01T20:44:25.513Z,1743540265.513 [NAL9602](INFO): SBD MO Status=0, MOMSN=18771, MT Status=0, MTMSN=0
2025-04-01T20:44:25.513Z,1743540265.513 [NAL9602](INFO): No messages in MT queue
2025-04-01T20:44:41.239Z,1743540281.239 [DataOverHttps](INFO): Sending 1133 bytes from file Logs/20250401T202242/Express0011.lzma
2025-04-01T20:44:42.237Z,1743540282.237 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0011.lzma.bak
2025-04-01T20:44:42.238Z,1743540282.238 [DataOverHttps](INFO): SBD MOMSN=24512614
2025-04-01T20:44:56.209Z,1743540296.209 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T20:44:58.387Z,1743540298.387 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250401T202242/Express0014.lzma
2025-04-01T20:44:59.389Z,1743540299.389 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0014.lzma.bak
2025-04-01T20:44:59.390Z,1743540299.390 [DataOverHttps](INFO): SBD MOMSN=24512660
2025-04-01T20:45:00.663Z,1743540300.663 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T20:45:00.679Z,1743540300.679 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T20:45:00.679Z,1743540300.679 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T20:50:01.280Z,1743540601.280 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T20:50:01.280Z,1743540601.280 [Default:CheckIn:C.Wait] Stopped
2025-04-01T20:50:01.280Z,1743540601.280 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T20:50:01.280Z,1743540601.280 [Default:CheckIn:D] Running Loop=1
2025-04-01T20:50:01.670Z,1743540601.670 [Default:CheckIn:D] Stopped
2025-04-01T20:50:01.670Z,1743540601.670 [Default:CheckIn:E] Running Loop=1
2025-04-01T20:50:02.068Z,1743540602.068 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  22.189347 min 
2025-04-01T20:50:02.068Z,1743540602.068 [Default:CheckIn:E] Stopped
2025-04-01T20:50:02.068Z,1743540602.068 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T20:50:02.068Z,1743540602.068 [Default:CheckIn] Stopped
2025-04-01T20:50:02.068Z,1743540602.068 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T20:50:02.068Z,1743540602.068 [Default:CheckIn](INFO): Running loop #4
2025-04-01T20:50:02.068Z,1743540602.068 [Default:CheckIn] Running Loop=4
2025-04-01T20:50:02.069Z,1743540602.069 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T20:50:02.069Z,1743540602.069 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T20:50:04.086Z,1743540604.086 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205003.00,A,3648.17699,N,12147.28182,W,0.097,334.41,010425,,,A*7C
2025-04-01T20:50:04.089Z,1743540604.089 [NAL9602](INFO): GPS fix at 20250401T205003: (36.802950, -121.788030)
2025-04-01T20:50:04.099Z,1743540604.099 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T20:50:04.099Z,1743540604.099 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T20:50:11.345Z,1743540611.345 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250401T202242/Courier0016.lzma
2025-04-01T20:50:12.163Z,1743540612.163 [NAL9602](INFO): SBD MO Status=0, MOMSN=18772, MT Status=0, MTMSN=0
2025-04-01T20:50:12.163Z,1743540612.163 [NAL9602](INFO): No messages in MT queue
2025-04-01T20:50:12.346Z,1743540612.346 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Courier0016.lzma.bak
2025-04-01T20:50:12.346Z,1743540612.346 [DataOverHttps](INFO): SBD MOMSN=24512818
2025-04-01T20:50:28.287Z,1743540628.287 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250401T202242/Express0017.lzma
2025-04-01T20:50:29.292Z,1743540629.292 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0017.lzma.bak
2025-04-01T20:50:29.292Z,1743540629.292 [DataOverHttps](INFO): SBD MOMSN=24512833
2025-04-01T20:50:30.755Z,1743540630.755 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T20:50:30.755Z,1743540630.755 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T20:50:30.755Z,1743540630.755 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T20:50:42.869Z,1743540642.869 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T20:52:59.697Z,1743540779.697 [DataOverHttps](IMPORTANT): SBD MTMSN=20250401T205258
2025-04-01T20:53:07.083Z,1743540787.083 [DataOverHttps](INFO): Received command: ibit
2025-04-01T20:53:07.144Z,1743540787.144 [CommandExec](IMPORTANT): got command ibit
2025-04-01T20:53:07.157Z,1743540787.157 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-04-01T20:53:07.157Z,1743540787.157 [IBIT](IMPORTANT): Beginning control surface checks.
2025-04-01T20:53:07.165Z,1743540787.165 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-01T20:53:08.731Z,1743540788.731 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205308.00,A,3648.17536,N,12147.28490,W,0.078,334.41,010425,,,A*75
2025-04-01T20:53:08.733Z,1743540788.733 [NAL9602](INFO): GPS fix at 20250401T205308: (36.802923, -121.788082)
2025-04-01T20:53:11.859Z,1743540791.859 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007892
CHAN A1 (24V): 0.000481
CHAN A2 (12V): -0.005678
CHAN A3 (5V): -0.003072
CHAN B0 (3.3V): -0.001213
CHAN B1 (3.15aV): -0.001378
CHAN B2 (3.15bV): -0.001269
CHAN B3 (GND): -0.000583
OPEN: 0.004835
Full Scale: +/- 1 mA
2025-04-01T20:53:28.933Z,1743540808.933 [NAL9602](INFO): SBD MO Status=0, MOMSN=18773, MT Status=0, MTMSN=0
2025-04-01T20:53:28.933Z,1743540808.933 [NAL9602](INFO): No messages in MT queue
2025-04-01T20:53:30.143Z,1743540810.143 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205329.00,A,3648.17538,N,12147.28571,W,0.078,334.41,010425,,,A*76
2025-04-01T20:53:30.145Z,1743540810.145 [NAL9602](INFO): GPS fix at 20250401T205329: (36.802923, -121.788095)
2025-04-01T20:53:32.972Z,1743540812.972 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205332.00,A,3648.17537,N,12147.28564,W,0.019,334.41,010425,,,A*70
2025-04-01T20:53:32.974Z,1743540812.974 [NAL9602](INFO): GPS fix at 20250401T205332: (36.802923, -121.788094)
2025-04-01T20:53:35.807Z,1743540815.807 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205335.00,A,3648.17536,N,12147.28567,W,0.019,334.41,010425,,,A*75
2025-04-01T20:53:35.809Z,1743540815.809 [NAL9602](INFO): GPS fix at 20250401T205335: (36.802923, -121.788094)
2025-04-01T20:53:39.056Z,1743540819.056 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205338.00,A,3648.17536,N,12147.28566,W,0.078,334.41,010425,,,A*7E
2025-04-01T20:53:39.058Z,1743540819.058 [NAL9602](INFO): GPS fix at 20250401T205338: (36.802923, -121.788094)
2025-04-01T20:53:41.899Z,1743540821.899 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205341.00,A,3648.17535,N,12147.28566,W,0.039,334.41,010425,,,A*76
2025-04-01T20:53:41.901Z,1743540821.901 [NAL9602](INFO): GPS fix at 20250401T205341: (36.802923, -121.788094)
2025-04-01T20:53:45.125Z,1743540825.125 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205344.00,A,3648.17534,N,12147.28566,W,0.019,334.41,010425,,,A*70
2025-04-01T20:53:45.127Z,1743540825.127 [NAL9602](INFO): GPS fix at 20250401T205344: (36.802922, -121.788094)
2025-04-01T20:53:47.947Z,1743540827.947 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205347.00,A,3648.17533,N,12147.28563,W,0.019,334.41,010425,,,A*71
2025-04-01T20:53:47.949Z,1743540827.949 [NAL9602](INFO): GPS fix at 20250401T205347: (36.802922, -121.788094)
2025-04-01T20:53:50.773Z,1743540830.773 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205350.00,A,3648.17530,N,12147.28562,W,0.039,334.41,010425,,,A*77
2025-04-01T20:53:50.784Z,1743540830.784 [NAL9602](INFO): GPS fix at 20250401T205350: (36.802922, -121.788094)
2025-04-01T20:53:53.630Z,1743540833.630 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802921 Longitude: -121.788094
2025-04-01T20:53:54.003Z,1743540834.003 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205353.00,A,3648.17527,N,12147.28559,W,0.019,334.41,010425,,,A*78
2025-04-01T20:53:54.005Z,1743540834.005 [NAL9602](INFO): GPS fix at 20250401T205353: (36.802921, -121.788093)
2025-04-01T20:53:54.050Z,1743540834.050 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.977000
2025-04-01T20:53:54.050Z,1743540834.050 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-04-01T20:53:54.050Z,1743540834.050 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-04-01T20:53:54.459Z,1743540834.459 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-04-01T20:53:54.472Z,1743540834.472 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-04-01T20:53:54.472Z,1743540834.472 [IBIT](IMPORTANT): Pressure:9.797139 PSI
2025-04-01T20:53:54.472Z,1743540834.472 [IBIT](IMPORTANT): Humidity:7.801245 %
2025-04-01T20:53:54.837Z,1743540834.837 [IBIT](IMPORTANT): Vehicle Pitch:-0.423469 degrees
2025-04-01T20:53:54.838Z,1743540834.838 [IBIT](IMPORTANT): Vehicle  Roll:6.838939 degrees
2025-04-01T20:53:54.838Z,1743540834.838 [IBIT](IMPORTANT): Vehicle  Heading:200.378494 degrees
2025-04-01T20:53:55.259Z,1743540835.259 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-04-01T20:53:55.264Z,1743540835.264 [IBIT](IMPORTANT): buoyancyNeutral: 80.000000 cc
2025-04-01T20:53:55.264Z,1743540835.264 [IBIT](IMPORTANT): massDefault: -1.500000 cm
2025-04-01T20:53:55.264Z,1743540835.264 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2025-04-01T20:53:55.264Z,1743540835.264 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-04-01T20:53:55.265Z,1743540835.265 [IBIT](IMPORTANT): IBIT PASSED
2025-04-01T20:54:26.323Z,1743540866.323 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T20:55:31.378Z,1743540931.378 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T20:55:31.378Z,1743540931.378 [Default:CheckIn:C.Wait] Stopped
2025-04-01T20:55:31.378Z,1743540931.378 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T20:55:31.378Z,1743540931.378 [Default:CheckIn:D] Running Loop=1
2025-04-01T20:55:31.793Z,1743540931.793 [Default:CheckIn:D] Stopped
2025-04-01T20:55:31.793Z,1743540931.793 [Default:CheckIn:E] Running Loop=1
2025-04-01T20:55:32.190Z,1743540932.190 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  27.691392 min 
2025-04-01T20:55:32.190Z,1743540932.190 [Default:CheckIn:E] Stopped
2025-04-01T20:55:32.190Z,1743540932.190 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T20:55:32.190Z,1743540932.190 [Default:CheckIn] Stopped
2025-04-01T20:55:32.191Z,1743540932.191 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T20:55:32.191Z,1743540932.191 [Default:CheckIn](INFO): Running loop #5
2025-04-01T20:55:32.191Z,1743540932.191 [Default:CheckIn] Running Loop=5
2025-04-01T20:55:32.191Z,1743540932.191 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T20:55:32.191Z,1743540932.191 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T20:55:34.196Z,1743540934.196 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205533.00,A,3648.17481,N,12147.28445,W,0.019,334.41,010425,,,A*79
2025-04-01T20:55:34.198Z,1743540934.198 [NAL9602](INFO): GPS fix at 20250401T205533: (36.802914, -121.788074)
2025-04-01T20:55:34.208Z,1743540934.208 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T20:55:34.208Z,1743540934.208 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T20:55:41.979Z,1743540941.979 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250401T202242/Courier0019.lzma
2025-04-01T20:55:42.981Z,1743540942.981 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Courier0019.lzma.bak
2025-04-01T20:55:42.981Z,1743540942.981 [DataOverHttps](INFO): SBD MOMSN=24512859
2025-04-01T20:56:01.770Z,1743540961.770 [DataOverHttps](INFO): Sending 947 bytes from file Logs/20250401T202242/Express0020.lzma
2025-04-01T20:56:02.769Z,1743540962.769 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0020.lzma.bak
2025-04-01T20:56:02.769Z,1743540962.769 [DataOverHttps](INFO): SBD MOMSN=24512862
2025-04-01T20:56:19.155Z,1743540979.155 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250401T202242/Express0023.lzma
2025-04-01T20:56:20.162Z,1743540980.162 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0023.lzma.bak
2025-04-01T20:56:20.162Z,1743540980.162 [DataOverHttps](INFO): SBD MOMSN=24512899
2025-04-01T20:56:21.562Z,1743540981.562 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T20:56:21.562Z,1743540981.562 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T20:56:21.563Z,1743540981.563 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T20:56:49.812Z,1743541009.812 [NAL9602](INFO): SBD MO Status=2, MOMSN=18774, MT Status=2, MTMSN=0
2025-04-01T20:56:49.812Z,1743541009.812 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-01T20:58:02.130Z,1743541082.130 [NAL9602](INFO): SBD MO Status=0, MOMSN=18774, MT Status=0, MTMSN=0
2025-04-01T20:58:02.130Z,1743541082.130 [NAL9602](INFO): No messages in MT queue
2025-04-01T20:58:32.833Z,1743541112.833 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T21:01:22.116Z,1743541282.116 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T21:01:22.116Z,1743541282.116 [Default:CheckIn:C.Wait] Stopped
2025-04-01T21:01:22.116Z,1743541282.116 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T21:01:22.116Z,1743541282.116 [Default:CheckIn:D] Running Loop=1
2025-04-01T21:01:22.531Z,1743541282.531 [Default:CheckIn:D] Stopped
2025-04-01T21:01:22.531Z,1743541282.531 [Default:CheckIn:E] Running Loop=1
2025-04-01T21:01:22.927Z,1743541282.927 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  33.537028 min 
2025-04-01T21:01:22.927Z,1743541282.927 [Default:CheckIn:E] Stopped
2025-04-01T21:01:22.928Z,1743541282.928 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T21:01:22.928Z,1743541282.928 [Default:CheckIn] Stopped
2025-04-01T21:01:22.928Z,1743541282.928 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T21:01:22.928Z,1743541282.928 [Default:CheckIn](INFO): Running loop #6
2025-04-01T21:01:22.928Z,1743541282.928 [Default:CheckIn] Running Loop=6
2025-04-01T21:01:22.928Z,1743541282.928 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T21:01:22.928Z,1743541282.928 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T21:01:24.938Z,1743541284.938 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210124.00,A,3648.17368,N,12147.28444,W,0.019,334.41,010425,,,A*7E
2025-04-01T21:01:24.941Z,1743541284.941 [NAL9602](INFO): GPS fix at 20250401T210124: (36.802895, -121.788074)
2025-04-01T21:01:24.951Z,1743541284.951 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T21:01:24.951Z,1743541284.951 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T21:01:32.699Z,1743541292.699 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250401T202242/Courier0025.lzma
2025-04-01T21:01:33.701Z,1743541293.701 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Courier0025.lzma.bak
2025-04-01T21:01:33.701Z,1743541293.701 [DataOverHttps](INFO): SBD MOMSN=24512956
2025-04-01T21:01:41.101Z,1743541301.101 [NAL9602](INFO): SBD MO Status=0, MOMSN=18775, MT Status=0, MTMSN=0
2025-04-01T21:01:41.101Z,1743541301.101 [NAL9602](INFO): No messages in MT queue
2025-04-01T21:01:52.823Z,1743541312.823 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250401T202242/Express0026.lzma
2025-04-01T21:01:53.826Z,1743541313.826 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0026.lzma.bak
2025-04-01T21:01:53.826Z,1743541313.826 [DataOverHttps](INFO): SBD MOMSN=24512959
2025-04-01T21:01:55.339Z,1743541315.339 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T21:01:55.339Z,1743541315.339 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T21:01:55.343Z,1743541315.343 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T21:02:11.881Z,1743541331.881 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T21:06:55.966Z,1743541615.966 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T21:06:55.966Z,1743541615.966 [Default:CheckIn:C.Wait] Stopped
2025-04-01T21:06:55.966Z,1743541615.966 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T21:06:55.966Z,1743541615.966 [Default:CheckIn:D] Running Loop=1
2025-04-01T21:06:56.355Z,1743541616.355 [Default:CheckIn:D] Stopped
2025-04-01T21:06:56.355Z,1743541616.355 [Default:CheckIn:E] Running Loop=1
2025-04-01T21:06:56.756Z,1743541616.756 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  39.100757 min 
2025-04-01T21:06:56.756Z,1743541616.756 [Default:CheckIn:E] Stopped
2025-04-01T21:06:56.756Z,1743541616.756 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T21:06:56.756Z,1743541616.756 [Default:CheckIn] Stopped
2025-04-01T21:06:56.756Z,1743541616.756 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T21:06:56.756Z,1743541616.756 [Default:CheckIn](INFO): Running loop #7
2025-04-01T21:06:56.756Z,1743541616.756 [Default:CheckIn] Running Loop=7
2025-04-01T21:06:56.757Z,1743541616.757 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T21:06:56.757Z,1743541616.757 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T21:06:58.766Z,1743541618.766 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210658.00,A,3648.17621,N,12147.28169,W,0.156,0.00,010425,,,A*7B
2025-04-01T21:06:58.769Z,1743541618.769 [NAL9602](INFO): GPS fix at 20250401T210658: (36.802937, -121.788028)
2025-04-01T21:06:58.791Z,1743541618.791 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T21:06:58.791Z,1743541618.791 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T21:07:06.147Z,1743541626.147 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250401T202242/Courier0028.lzma
2025-04-01T21:07:07.149Z,1743541627.149 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Courier0028.lzma.bak
2025-04-01T21:07:07.149Z,1743541627.149 [DataOverHttps](INFO): SBD MOMSN=24512977
2025-04-01T21:07:18.564Z,1743541638.564 [NAL9602](INFO): SBD MO Status=0, MOMSN=18776, MT Status=0, MTMSN=0
2025-04-01T21:07:18.564Z,1743541638.564 [NAL9602](INFO): No messages in MT queue
2025-04-01T21:07:23.019Z,1743541643.019 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250401T202242/Express0029.lzma
2025-04-01T21:07:24.021Z,1743541644.021 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0029.lzma.bak
2025-04-01T21:07:24.021Z,1743541644.021 [DataOverHttps](INFO): SBD MOMSN=24512980
2025-04-01T21:07:25.449Z,1743541645.449 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T21:07:25.449Z,1743541645.449 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T21:07:25.449Z,1743541645.449 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T21:07:49.267Z,1743541669.267 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T21:07:57.346Z,1743541677.346 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-04-01T21:07:57.346Z,1743541677.346 [DropWeight] Hardware Fault, FailCount= 1
2025-04-01T21:07:57.346Z,1743541677.346 [DropWeight](ERROR): Hardware Fault
2025-04-01T21:07:57.364Z,1743541677.364 [CBIT](INFO): Critical error at 20250401T210757
2025-04-01T21:07:57.366Z,1743541677.366 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-04-01T21:07:57.367Z,1743541677.367 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-04-01T21:07:57.776Z,1743541677.776 [CBIT](INFO): Critical error at 20250401T210757
2025-04-01T21:12:26.098Z,1743541946.098 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T21:12:26.098Z,1743541946.098 [Default:CheckIn:C.Wait] Stopped
2025-04-01T21:12:26.098Z,1743541946.098 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T21:12:26.099Z,1743541946.099 [Default:CheckIn:D] Running Loop=1
2025-04-01T21:12:26.497Z,1743541946.497 [Default:CheckIn:D] Stopped
2025-04-01T21:12:26.497Z,1743541946.497 [Default:CheckIn:E] Running Loop=1
2025-04-01T21:12:26.910Z,1743541946.910 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  44.603121 min 
2025-04-01T21:12:26.910Z,1743541946.910 [Default:CheckIn:E] Stopped
2025-04-01T21:12:26.910Z,1743541946.910 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T21:12:26.910Z,1743541946.910 [Default:CheckIn] Stopped
2025-04-01T21:12:26.911Z,1743541946.911 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T21:12:26.911Z,1743541946.911 [Default:CheckIn](INFO): Running loop #8
2025-04-01T21:12:26.911Z,1743541946.911 [Default:CheckIn] Running Loop=8
2025-04-01T21:12:26.911Z,1743541946.911 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T21:12:26.911Z,1743541946.911 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T21:12:28.910Z,1743541948.910 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211228.00,A,3648.17722,N,12147.28436,W,0.058,0.00,010425,,,A*7B
2025-04-01T21:12:28.913Z,1743541948.913 [NAL9602](INFO): GPS fix at 20250401T211228: (36.802954, -121.788073)
2025-04-01T21:12:28.923Z,1743541948.923 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T21:12:28.923Z,1743541948.923 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T21:12:36.679Z,1743541956.679 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250401T202242/Courier0031.lzma
2025-04-01T21:12:37.681Z,1743541957.681 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Courier0031.lzma.bak
2025-04-01T21:12:37.681Z,1743541957.681 [DataOverHttps](INFO): SBD MOMSN=24513033
2025-04-01T21:12:53.611Z,1743541973.611 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250401T202242/Express0032.lzma
2025-04-01T21:12:54.613Z,1743541974.613 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0032.lzma.bak
2025-04-01T21:12:54.613Z,1743541974.613 [DataOverHttps](INFO): SBD MOMSN=24513038
2025-04-01T21:12:55.000Z,1743541976.000 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T21:12:55.000Z,1743541976.000 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T21:12:55.000Z,1743541976.000 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T21:14:02.642Z,1743542042.642 [NAL9602](INFO): SBD MO Status=0, MOMSN=18777, MT Status=0, MTMSN=0
2025-04-01T21:14:02.643Z,1743542042.643 [NAL9602](INFO): No messages in MT queue
2025-04-01T21:14:33.341Z,1743542073.341 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T21:17:56.619Z,1743542276.619 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T21:17:56.619Z,1743542276.619 [Default:CheckIn:C.Wait] Stopped
2025-04-01T21:17:56.620Z,1743542276.620 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T21:17:56.620Z,1743542276.620 [Default:CheckIn:D] Running Loop=1
2025-04-01T21:17:57.035Z,1743542277.035 [Default:CheckIn:D] Stopped
2025-04-01T21:17:57.035Z,1743542277.035 [Default:CheckIn:E] Running Loop=1
2025-04-01T21:17:57.426Z,1743542277.426 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  50.111829 min 
2025-04-01T21:17:57.426Z,1743542277.426 [Default:CheckIn:E] Stopped
2025-04-01T21:17:57.426Z,1743542277.426 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T21:17:57.426Z,1743542277.426 [Default:CheckIn] Stopped
2025-04-01T21:17:57.426Z,1743542277.426 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T21:17:57.427Z,1743542277.427 [Default:CheckIn](INFO): Running loop #9
2025-04-01T21:17:57.427Z,1743542277.427 [Default:CheckIn] Running Loop=9
2025-04-01T21:17:57.427Z,1743542277.427 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T21:17:57.427Z,1743542277.427 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T21:17:59.436Z,1743542279.436 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211758.00,A,3648.17920,N,12147.28257,W,0.097,0.00,010425,,,A*77
2025-04-01T21:17:59.438Z,1743542279.438 [NAL9602](INFO): GPS fix at 20250401T211758: (36.802987, -121.788043)
2025-04-01T21:17:59.448Z,1743542279.448 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T21:17:59.448Z,1743542279.448 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T21:18:06.795Z,1743542286.795 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250401T202242/Courier0034.lzma
2025-04-01T21:18:08.094Z,1743542288.094 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Courier0034.lzma.bak
2025-04-01T21:18:08.095Z,1743542288.095 [DataOverHttps](INFO): SBD MOMSN=24513056
2025-04-01T21:18:14.796Z,1743542294.796 [NAL9602](INFO): SBD MO Status=0, MOMSN=18778, MT Status=0, MTMSN=0
2025-04-01T21:18:14.796Z,1743542294.796 [NAL9602](INFO): No messages in MT queue
2025-04-01T21:18:23.679Z,1743542303.679 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250401T202242/Express0035.lzma
2025-04-01T21:18:24.681Z,1743542304.681 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0035.lzma.bak
2025-04-01T21:18:24.682Z,1743542304.682 [DataOverHttps](INFO): SBD MOMSN=24513059
2025-04-01T21:18:26.123Z,1743542306.123 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T21:18:26.123Z,1743542306.123 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T21:18:26.123Z,1743542306.123 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T21:18:45.489Z,1743542325.489 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T21:22:57.629Z,1743542577.629 [CBIT](INFO): Clearing failed state for component DropWeight
2025-04-01T21:22:57.629Z,1743542577.629 [DropWeight] No Fault, FailCount= 1
2025-04-01T21:23:26.714Z,1743542606.714 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T21:23:26.714Z,1743542606.714 [Default:CheckIn:C.Wait] Stopped
2025-04-01T21:23:26.714Z,1743542606.714 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T21:23:26.715Z,1743542606.715 [Default:CheckIn:D] Running Loop=1
2025-04-01T21:23:27.126Z,1743542607.126 [Default:CheckIn:D] Stopped
2025-04-01T21:23:27.126Z,1743542607.126 [Default:CheckIn:E] Running Loop=1
2025-04-01T21:23:27.520Z,1743542607.520 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  55.613611 min 
2025-04-01T21:23:27.521Z,1743542607.521 [Default:CheckIn:E] Stopped
2025-04-01T21:23:27.521Z,1743542607.521 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T21:23:27.521Z,1743542607.521 [Default:CheckIn] Stopped
2025-04-01T21:23:27.521Z,1743542607.521 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T21:23:27.521Z,1743542607.521 [Default:CheckIn](INFO): Running loop #10
2025-04-01T21:23:27.521Z,1743542607.521 [Default:CheckIn] Running Loop=10
2025-04-01T21:23:27.521Z,1743542607.521 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T21:23:27.521Z,1743542607.521 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T21:23:29.527Z,1743542609.527 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212328.00,A,3648.17683,N,12147.28455,W,0.039,0.00,010425,,,A*71
2025-04-01T21:23:29.529Z,1743542609.529 [NAL9602](INFO): GPS fix at 20250401T212328: (36.802947, -121.788076)
2025-04-01T21:23:29.539Z,1743542609.539 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T21:23:29.539Z,1743542609.539 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T21:23:36.511Z,1743542616.511 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250401T202242/Courier0037.lzma
2025-04-01T21:23:37.513Z,1743542617.513 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Courier0037.lzma.bak
2025-04-01T21:23:37.513Z,1743542617.513 [DataOverHttps](INFO): SBD MOMSN=24513118
2025-04-01T21:23:43.670Z,1743542623.670 [NAL9602](INFO): SBD MO Status=0, MOMSN=18779, MT Status=0, MTMSN=0
2025-04-01T21:23:43.670Z,1743542623.670 [NAL9602](INFO): No messages in MT queue
2025-04-01T21:23:56.339Z,1743542636.339 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250401T202242/Express0038.lzma
2025-04-01T21:23:57.342Z,1743542637.342 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0038.lzma.bak
2025-04-01T21:23:57.342Z,1743542637.342 [DataOverHttps](INFO): SBD MOMSN=24513122
2025-04-01T21:23:58.679Z,1743542638.679 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T21:23:58.679Z,1743542638.679 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T21:23:58.679Z,1743542638.679 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T21:24:14.414Z,1743542654.414 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T21:28:59.270Z,1743542939.270 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T21:28:59.270Z,1743542939.270 [Default:CheckIn:C.Wait] Stopped
2025-04-01T21:28:59.270Z,1743542939.270 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T21:28:59.270Z,1743542939.270 [Default:CheckIn:D] Running Loop=1
2025-04-01T21:28:59.680Z,1743542939.680 [Default:CheckIn:D] Stopped
2025-04-01T21:28:59.680Z,1743542939.680 [Default:CheckIn:E] Running Loop=1
2025-04-01T21:29:00.079Z,1743542940.079 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  61.156173 min 
2025-04-01T21:29:00.079Z,1743542940.079 [Default:CheckIn:E] Stopped
2025-04-01T21:29:00.079Z,1743542940.079 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T21:29:00.079Z,1743542940.079 [Default:CheckIn] Stopped
2025-04-01T21:29:00.079Z,1743542940.079 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T21:29:00.095Z,1743542940.095 [Default:CheckIn](INFO): Running loop #11
2025-04-01T21:29:00.095Z,1743542940.095 [Default:CheckIn] Running Loop=11
2025-04-01T21:29:00.096Z,1743542940.096 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T21:29:00.096Z,1743542940.096 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T21:29:02.094Z,1743542942.094 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212901.00,A,3648.17629,N,12147.28507,W,0.019,0.00,010425,,,A*74
2025-04-01T21:29:02.097Z,1743542942.097 [NAL9602](INFO): GPS fix at 20250401T212901: (36.802938, -121.788084)
2025-04-01T21:29:02.108Z,1743542942.108 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T21:29:02.108Z,1743542942.108 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T21:29:09.095Z,1743542949.095 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250401T202242/Courier0040.lzma
2025-04-01T21:29:10.097Z,1743542950.097 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Courier0040.lzma.bak
2025-04-01T21:29:10.097Z,1743542950.097 [DataOverHttps](INFO): SBD MOMSN=24513138
2025-04-01T21:29:11.829Z,1743542951.829 [NAL9602](INFO): SBD MO Status=0, MOMSN=18780, MT Status=0, MTMSN=0
2025-04-01T21:29:11.829Z,1743542951.829 [NAL9602](INFO): No messages in MT queue
2025-04-01T21:29:25.959Z,1743542965.959 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250401T202242/Express0041.lzma
2025-04-01T21:29:26.961Z,1743542966.961 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0041.lzma.bak
2025-04-01T21:29:26.962Z,1743542966.962 [DataOverHttps](INFO): SBD MOMSN=24513141
2025-04-01T21:29:28.407Z,1743542968.407 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T21:29:28.407Z,1743542968.407 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T21:29:28.407Z,1743542968.407 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T21:29:42.530Z,1743542982.530 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T21:34:29.002Z,1743543269.002 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T21:34:29.002Z,1743543269.002 [Default:CheckIn:C.Wait] Stopped
2025-04-01T21:34:29.002Z,1743543269.002 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T21:34:29.002Z,1743543269.002 [Default:CheckIn:D] Running Loop=1
2025-04-01T21:34:29.399Z,1743543269.399 [Default:CheckIn:D] Stopped
2025-04-01T21:34:29.399Z,1743543269.399 [Default:CheckIn:E] Running Loop=1
2025-04-01T21:34:29.810Z,1743543269.810 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  66.651489 min 
2025-04-01T21:34:29.811Z,1743543269.811 [Default:CheckIn:E] Stopped
2025-04-01T21:34:29.811Z,1743543269.811 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T21:34:29.811Z,1743543269.811 [Default:CheckIn] Stopped
2025-04-01T21:34:29.811Z,1743543269.811 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T21:34:29.811Z,1743543269.811 [Default:CheckIn](INFO): Running loop #12
2025-04-01T21:34:29.811Z,1743543269.811 [Default:CheckIn] Running Loop=12
2025-04-01T21:34:29.811Z,1743543269.811 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T21:34:29.812Z,1743543269.812 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T21:34:31.825Z,1743543271.825 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213431.00,A,3648.17671,N,12147.28377,W,0.039,0.00,010425,,,A*75
2025-04-01T21:34:31.827Z,1743543271.827 [NAL9602](INFO): GPS fix at 20250401T213431: (36.802945, -121.788063)
2025-04-01T21:34:31.837Z,1743543271.837 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T21:34:31.837Z,1743543271.837 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T21:34:39.459Z,1743543279.459 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250401T202242/Courier0043.lzma
2025-04-01T21:34:40.462Z,1743543280.462 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Courier0043.lzma.bak
2025-04-01T21:34:40.462Z,1743543280.462 [DataOverHttps](INFO): SBD MOMSN=24513191
2025-04-01T21:34:47.977Z,1743543287.977 [NAL9602](INFO): SBD MO Status=0, MOMSN=18781, MT Status=0, MTMSN=0
2025-04-01T21:34:47.977Z,1743543287.977 [NAL9602](INFO): No messages in MT queue
2025-04-01T21:34:56.531Z,1743543296.531 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250401T202242/Express0044.lzma
2025-04-01T21:34:57.534Z,1743543297.534 [DataOverHttps](INFO): Moved sent file to Logs/20250401T202242/Express0044.lzma.bak
2025-04-01T21:34:57.534Z,1743543297.534 [DataOverHttps](INFO): SBD MOMSN=24513194
2025-04-01T21:34:58.929Z,1743543298.929 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T21:34:58.929Z,1743543298.929 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T21:34:58.929Z,1743543298.929 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T21:35:18.681Z,1743543318.681 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T21:36:06.082Z,1743543366.082 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.045048
2025-04-01T21:36:40.216Z,1743543400.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:37:15.356Z,1743543435.356 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:37:39.680Z,1743543459.680 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-04-01T21:37:39.682Z,1743543459.682 [BPC1](INFO): Received data from all battery sticks.
2025-04-01T21:37:50.512Z,1743543470.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:38:25.652Z,1743543505.652 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:39:00.800Z,1743543540.800 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:39:35.940Z,1743543575.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:39:59.466Z,1743543599.466 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T21:39:59.466Z,1743543599.466 [Default:CheckIn:C.Wait] Stopped
2025-04-01T21:39:59.466Z,1743543599.466 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T21:39:59.466Z,1743543599.466 [Default:CheckIn:D] Running Loop=1
2025-04-01T21:39:59.874Z,1743543599.874 [Default:CheckIn:D] Stopped
2025-04-01T21:39:59.874Z,1743543599.874 [Default:CheckIn:E] Running Loop=1
2025-04-01T21:40:00.277Z,1743543600.277 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  72.159416 min 
2025-04-01T21:40:00.277Z,1743543600.277 [Default:CheckIn:E] Stopped
2025-04-01T21:40:00.277Z,1743543600.277 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T21:40:00.277Z,1743543600.277 [Default:CheckIn] Stopped
2025-04-01T21:40:00.277Z,1743543600.277 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T21:40:00.278Z,1743543600.278 [Default:CheckIn](INFO): Running loop #13
2025-04-01T21:40:00.278Z,1743543600.278 [Default:CheckIn] Running Loop=13
2025-04-01T21:40:00.278Z,1743543600.278 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T21:40:00.278Z,1743543600.278 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T21:40:02.288Z,1743543602.288 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214001.00,A,3648.17671,N,12147.28302,W,0.019,0.00,010425,,,A*75
2025-04-01T21:40:02.290Z,1743543602.290 [NAL9602](INFO): GPS fix at 20250401T214001: (36.802945, -121.788050)
2025-04-01T21:40:02.318Z,1743543602.318 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T21:40:02.318Z,1743543602.318 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T21:40:11.080Z,1743543611.080 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:40:26.581Z,1743543626.581 [NAL9602](INFO): SBD MO Status=2, MOMSN=18782, MT Status=2, MTMSN=0
2025-04-01T21:40:26.582Z,1743543626.582 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-01T21:40:46.220Z,1743543646.220 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:40:54.090Z,1743543654.090 [NAL9602](INFO): SBD MO Status=2, MOMSN=18782, MT Status=2, MTMSN=0
2025-04-01T21:40:54.090Z,1743543654.091 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-01T21:41:21.364Z,1743543681.364 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:41:24.439Z,1743543684.439 [NAL9602](INFO): SBD MO Status=2, MOMSN=18782, MT Status=2, MTMSN=0
2025-04-01T21:41:24.439Z,1743543684.439 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-01T21:41:34.191Z,1743543694.191 [NAL9602](INFO): SBD MO Status=1, MOMSN=18782, MT Status=0, MTMSN=0
2025-04-01T21:41:34.242Z,1743543694.242 [NAL9602](INFO): Sent 86 bytes from file Logs/20250401T202242/Courier0046.lzma
2025-04-01T21:41:34.242Z,1743543694.242 [NAL9602](INFO): Packets left to send: 0
2025-04-01T21:41:46.380Z,1743543706.380 [NAL9602](INFO): SBD MO Status=1, MOMSN=18783, MT Status=0, MTMSN=0
2025-04-01T21:41:46.440Z,1743543706.440 [NAL9602](INFO): Sent 155 bytes from file Logs/20250401T202242/Express0047.lzma
2025-04-01T21:41:46.440Z,1743543706.440 [NAL9602](INFO): Packets left to send: 0
2025-04-01T21:41:53.259Z,1743543713.259 [NAL9602](INFO): SBD MO Status=0, MOMSN=18784, MT Status=0, MTMSN=0
2025-04-01T21:41:53.347Z,1743543713.347 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T21:41:53.347Z,1743543713.347 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T21:41:53.347Z,1743543713.347 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T21:41:56.504Z,1743543716.504 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:42:23.958Z,1743543743.958 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T21:42:31.684Z,1743543751.684 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:43:06.824Z,1743543786.824 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:43:41.964Z,1743543821.964 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:44:17.104Z,1743543857.104 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:44:52.244Z,1743543892.244 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:45:27.384Z,1743543927.384 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:46:02.525Z,1743543962.525 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:46:37.664Z,1743543997.664 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:46:53.831Z,1743544013.831 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T21:46:53.831Z,1743544013.831 [Default:CheckIn:C.Wait] Stopped
2025-04-01T21:46:53.831Z,1743544013.831 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T21:46:53.832Z,1743544013.832 [Default:CheckIn:D] Running Loop=1
2025-04-01T21:46:54.254Z,1743544014.254 [Default:CheckIn:D] Stopped
2025-04-01T21:46:54.254Z,1743544014.254 [Default:CheckIn:E] Running Loop=1
2025-04-01T21:46:54.653Z,1743544014.653 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  79.065747 min 
2025-04-01T21:46:54.653Z,1743544014.653 [Default:CheckIn:E] Stopped
2025-04-01T21:46:54.654Z,1743544014.654 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T21:46:54.654Z,1743544014.654 [Default:CheckIn] Stopped
2025-04-01T21:46:54.654Z,1743544014.654 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T21:46:54.654Z,1743544014.654 [Default:CheckIn](INFO): Running loop #14
2025-04-01T21:46:54.654Z,1743544014.654 [Default:CheckIn] Running Loop=14
2025-04-01T21:46:54.654Z,1743544014.654 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T21:46:54.654Z,1743544014.654 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T21:46:56.652Z,1743544016.652 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214655.00,A,3648.17627,N,12147.28354,W,0.019,0.00,010425,,,A*72
2025-04-01T21:46:56.654Z,1743544016.654 [NAL9602](INFO): GPS fix at 20250401T214655: (36.802938, -121.788059)
2025-04-01T21:46:56.664Z,1743544016.664 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T21:46:56.664Z,1743544016.664 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T21:47:10.830Z,1743544030.830 [NAL9602](INFO): SBD MO Status=1, MOMSN=18785, MT Status=0, MTMSN=0
2025-04-01T21:47:10.880Z,1743544030.880 [NAL9602](INFO): Sent 74 bytes from file Logs/20250401T202242/Courier0049.lzma
2025-04-01T21:47:10.880Z,1743544030.880 [NAL9602](INFO): Packets left to send: 0
2025-04-01T21:47:12.812Z,1743544032.812 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:47:22.182Z,1743544042.182 [NAL9602](INFO): SBD MO Status=1, MOMSN=18786, MT Status=0, MTMSN=0
2025-04-01T21:47:22.236Z,1743544042.236 [NAL9602](INFO): Sent 151 bytes from file Logs/20250401T202242/Express0050.lzma
2025-04-01T21:47:22.236Z,1743544042.236 [NAL9602](INFO): Packets left to send: 0
2025-04-01T21:47:29.055Z,1743544049.055 [NAL9602](INFO): SBD MO Status=0, MOMSN=18787, MT Status=0, MTMSN=0
2025-04-01T21:47:29.136Z,1743544049.136 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T21:47:29.136Z,1743544049.136 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T21:47:29.136Z,1743544049.136 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T21:47:47.952Z,1743544067.952 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:47:59.757Z,1743544079.757 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T21:48:23.092Z,1743544103.092 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:48:58.232Z,1743544138.232 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:49:33.376Z,1743544173.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:50:08.517Z,1743544208.517 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:50:43.656Z,1743544243.656 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:51:18.820Z,1743544278.820 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:51:53.980Z,1743544313.980 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:52:29.144Z,1743544349.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:52:29.634Z,1743544349.634 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T21:52:29.634Z,1743544349.634 [Default:CheckIn:C.Wait] Stopped
2025-04-01T21:52:29.634Z,1743544349.634 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T21:52:29.635Z,1743544349.635 [Default:CheckIn:D] Running Loop=1
2025-04-01T21:52:30.056Z,1743544350.056 [Default:CheckIn:D] Stopped
2025-04-01T21:52:30.056Z,1743544350.056 [Default:CheckIn:E] Running Loop=1
2025-04-01T21:52:30.459Z,1743544350.459 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  84.662451 min 
2025-04-01T21:52:30.459Z,1743544350.459 [Default:CheckIn:E] Stopped
2025-04-01T21:52:30.460Z,1743544350.460 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T21:52:30.460Z,1743544350.460 [Default:CheckIn] Stopped
2025-04-01T21:52:30.460Z,1743544350.460 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T21:52:30.460Z,1743544350.460 [Default:CheckIn](INFO): Running loop #15
2025-04-01T21:52:30.460Z,1743544350.460 [Default:CheckIn] Running Loop=15
2025-04-01T21:52:30.460Z,1743544350.460 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T21:52:30.460Z,1743544350.460 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T21:52:32.460Z,1743544352.460 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215231.00,A,3648.17596,N,12147.28446,W,0.019,0.00,010425,,,A*78
2025-04-01T21:52:32.463Z,1743544352.463 [NAL9602](INFO): GPS fix at 20250401T215231: (36.802933, -121.788074)
2025-04-01T21:52:32.490Z,1743544352.490 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T21:52:32.490Z,1743544352.490 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T21:52:48.658Z,1743544368.658 [NAL9602](INFO): SBD MO Status=1, MOMSN=18788, MT Status=0, MTMSN=0
2025-04-01T21:52:48.716Z,1743544368.716 [NAL9602](INFO): Sent 74 bytes from file Logs/20250401T202242/Courier0052.lzma
2025-04-01T21:52:48.716Z,1743544368.716 [NAL9602](INFO): Packets left to send: 0
2025-04-01T21:52:59.208Z,1743544379.208 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-04-01T21:52:59.209Z,1743544379.209 [DropWeight] Hardware Fault, FailCount= 1
2025-04-01T21:52:59.209Z,1743544379.209 [DropWeight](ERROR): Hardware Fault
2025-04-01T21:52:59.276Z,1743544379.276 [CBIT](INFO): Critical error at 20250401T215259
2025-04-01T21:52:59.278Z,1743544379.278 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-04-01T21:52:59.278Z,1743544379.278 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-04-01T21:52:59.636Z,1743544379.636 [CBIT](INFO): Critical error at 20250401T215259
2025-04-01T21:53:00.827Z,1743544380.827 [NAL9602](INFO): SBD MO Status=1, MOMSN=18789, MT Status=0, MTMSN=0
2025-04-01T21:53:00.880Z,1743544380.880 [NAL9602](INFO): Sent 152 bytes from file Logs/20250401T202242/Express0053.lzma
2025-04-01T21:53:00.880Z,1743544380.880 [NAL9602](INFO): Packets left to send: 0
2025-04-01T21:53:04.284Z,1743544384.284 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:53:06.483Z,1743544386.483 [NAL9602](INFO): SBD MO Status=0, MOMSN=18790, MT Status=0, MTMSN=0
2025-04-01T21:53:06.559Z,1743544386.559 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T21:53:06.559Z,1743544386.559 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T21:53:06.559Z,1743544386.559 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T21:53:37.192Z,1743544417.192 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T21:53:39.440Z,1743544419.440 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:54:14.580Z,1743544454.580 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:54:49.720Z,1743544489.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:55:24.872Z,1743544524.872 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:56:00.013Z,1743544560.013 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:56:35.180Z,1743544595.180 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:57:10.340Z,1743544630.340 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:57:45.480Z,1743544665.480 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:58:07.070Z,1743544687.070 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T21:58:07.070Z,1743544687.070 [Default:CheckIn:C.Wait] Stopped
2025-04-01T21:58:07.070Z,1743544687.070 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T21:58:07.070Z,1743544687.070 [Default:CheckIn:D] Running Loop=1
2025-04-01T21:58:07.478Z,1743544687.478 [Default:CheckIn:D] Stopped
2025-04-01T21:58:07.478Z,1743544687.478 [Default:CheckIn:E] Running Loop=1
2025-04-01T21:58:07.887Z,1743544687.887 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  90.286141 min 
2025-04-01T21:58:07.887Z,1743544687.887 [Default:CheckIn:E] Stopped
2025-04-01T21:58:07.887Z,1743544687.887 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T21:58:07.887Z,1743544687.887 [Default:CheckIn] Stopped
2025-04-01T21:58:07.888Z,1743544687.888 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T21:58:07.888Z,1743544687.888 [Default:CheckIn](INFO): Running loop #16
2025-04-01T21:58:07.888Z,1743544687.888 [Default:CheckIn] Running Loop=16
2025-04-01T21:58:07.888Z,1743544687.888 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T21:58:07.888Z,1743544687.888 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T21:58:09.887Z,1743544689.887 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215809.00,A,3648.17671,N,12147.28344,W,0.019,0.00,010425,,,A*76
2025-04-01T21:58:09.890Z,1743544689.890 [NAL9602](INFO): GPS fix at 20250401T215809: (36.802945, -121.788057)
2025-04-01T21:58:09.900Z,1743544689.900 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T21:58:09.900Z,1743544689.900 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T21:58:20.624Z,1743544700.624 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:58:34.990Z,1743544714.990 [NAL9602](INFO): SBD MO Status=2, MOMSN=18791, MT Status=2, MTMSN=0
2025-04-01T21:58:34.991Z,1743544714.991 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-01T21:58:54.436Z,1743544734.436 [NAL9602](INFO): SBD MO Status=2, MOMSN=18791, MT Status=2, MTMSN=0
2025-04-01T21:58:54.436Z,1743544734.436 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-01T21:58:55.764Z,1743544735.764 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:59:18.334Z,1743544758.334 [NAL9602](INFO): SBD MO Status=2, MOMSN=18791, MT Status=2, MTMSN=0
2025-04-01T21:59:18.335Z,1743544758.335 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-01T21:59:30.904Z,1743544770.904 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T21:59:38.986Z,1743544778.986 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=18791, MT Status=1, MTMSN=1085
2025-04-01T21:59:39.044Z,1743544779.044 [NAL9602](INFO): Sent 151 bytes from file Logs/20250401T202242/Courier0055.lzma
2025-04-01T21:59:39.044Z,1743544779.044 [NAL9602](INFO): Packets left to send: 0
2025-04-01T21:59:39.480Z,1743544779.480 [NAL9602](INFO): Received command: ibit
2025-04-01T21:59:39.481Z,1743544779.481 [CommandExec](IMPORTANT): got command ibit
2025-04-01T21:59:39.502Z,1743544779.502 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-04-01T21:59:39.502Z,1743544779.502 [IBIT](IMPORTANT): Beginning control surface checks.
2025-04-01T21:59:39.506Z,1743544779.506 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-04-01T21:59:40.594Z,1743544780.594 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215939.00,A,3648.17556,N,12147.28329,W,0.058,0.00,010425,,,A*7C
2025-04-01T21:59:40.597Z,1743544780.597 [NAL9602](INFO): GPS fix at 20250401T215939: (36.802926, -121.788055)
2025-04-01T21:59:44.118Z,1743544784.118 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006574
CHAN A1 (24V): 0.000458
CHAN A2 (12V): -0.005226
CHAN A3 (5V): -0.003243
CHAN B0 (3.3V): -0.000916
CHAN B1 (3.15aV): -0.001571
CHAN B2 (3.15bV): -0.001452
CHAN B3 (GND): -0.000386
OPEN: 0.004784
Full Scale: +/- 1 mA
2025-04-01T21:59:59.243Z,1743544799.243 [NAL9602](INFO): SBD MO Status=1, MOMSN=18792, MT Status=0, MTMSN=0
2025-04-01T21:59:59.296Z,1743544799.296 [NAL9602](INFO): Sent 151 bytes from file Logs/20250401T202242/Express0056.lzma
2025-04-01T21:59:59.296Z,1743544799.296 [NAL9602](INFO): Packets left to send: 0
2025-04-01T22:00:06.048Z,1743544806.048 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:00:18.235Z,1743544818.235 [NAL9602](INFO): SBD MO Status=0, MOMSN=18793, MT Status=0, MTMSN=0
2025-04-01T22:00:19.446Z,1743544819.446 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220018.00,A,3648.17562,N,12147.28321,W,0.019,0.00,010425,,,A*7A
2025-04-01T22:00:19.449Z,1743544819.449 [NAL9602](INFO): GPS fix at 20250401T220018: (36.802927, -121.788054)
2025-04-01T22:00:25.657Z,1743544825.657 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802925 Longitude: -121.788055
2025-04-01T22:00:26.025Z,1743544826.025 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.937000
2025-04-01T22:00:26.025Z,1743544826.025 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-04-01T22:00:26.026Z,1743544826.026 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-04-01T22:00:26.416Z,1743544826.416 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-04-01T22:00:26.416Z,1743544826.416 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-04-01T22:00:26.416Z,1743544826.416 [IBIT](IMPORTANT): Pressure:9.754103 PSI
2025-04-01T22:00:26.416Z,1743544826.416 [IBIT](IMPORTANT): Humidity:7.837871 %
2025-04-01T22:00:26.820Z,1743544826.820 [IBIT](IMPORTANT): Vehicle Pitch:-0.395377 degrees
2025-04-01T22:00:26.820Z,1743544826.820 [IBIT](IMPORTANT): Vehicle  Roll:6.866739 degrees
2025-04-01T22:00:26.821Z,1743544826.821 [IBIT](IMPORTANT): Vehicle  Heading:200.245087 degrees
2025-04-01T22:00:27.236Z,1743544827.236 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-04-01T22:00:27.237Z,1743544827.237 [IBIT](IMPORTANT): buoyancyNeutral: 80.000000 cc
2025-04-01T22:00:27.237Z,1743544827.237 [IBIT](IMPORTANT): massDefault: -1.500000 cm
2025-04-01T22:00:27.237Z,1743544827.237 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2025-04-01T22:00:27.238Z,1743544827.238 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-04-01T22:00:27.238Z,1743544827.238 [IBIT](IMPORTANT): IBIT PASSED
2025-04-01T22:00:28.014Z,1743544828.014 [NAL9602](INFO): SBD MO Status=1, MOMSN=18794, MT Status=0, MTMSN=0
2025-04-01T22:00:28.068Z,1743544828.068 [NAL9602](INFO): Sent 74 bytes from file Logs/20250401T202242/Courier0058.lzma
2025-04-01T22:00:28.068Z,1743544828.068 [NAL9602](INFO): Packets left to send: 0
2025-04-01T22:00:40.310Z,1743544840.310 [NAL9602](INFO): SBD MO Status=1, MOMSN=18795, MT Status=0, MTMSN=0
2025-04-01T22:00:40.360Z,1743544840.360 [NAL9602](INFO): Sent 332 bytes from file Logs/20250401T202242/Express0059.lzma
2025-04-01T22:00:40.360Z,1743544840.360 [NAL9602](INFO): Packets left to send: 1
2025-04-01T22:00:41.188Z,1743544841.188 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:00:50.058Z,1743544850.058 [NAL9602](INFO): SBD MO Status=1, MOMSN=18796, MT Status=0, MTMSN=0
2025-04-01T22:00:50.108Z,1743544850.108 [NAL9602](INFO): Sent 57 bytes from file Logs/20250401T202242/Express0059.lzma
2025-04-01T22:00:50.108Z,1743544850.108 [NAL9602](INFO): Packets left to send: 0
2025-04-01T22:01:02.178Z,1743544862.178 [NAL9602](INFO): SBD MO Status=0, MOMSN=18797, MT Status=0, MTMSN=0
2025-04-01T22:01:02.259Z,1743544862.259 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T22:01:02.259Z,1743544862.259 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T22:01:02.259Z,1743544862.259 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T22:01:16.336Z,1743544876.336 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:01:32.880Z,1743544892.880 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T22:01:51.476Z,1743544911.476 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:02:26.616Z,1743544946.616 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:03:01.756Z,1743544981.756 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:03:36.908Z,1743545016.908 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:04:12.048Z,1743545052.048 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:04:47.220Z,1743545087.220 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:05:22.360Z,1743545122.360 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:05:57.520Z,1743545157.520 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:06:02.766Z,1743545162.766 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T22:06:02.766Z,1743545162.766 [Default:CheckIn:C.Wait] Stopped
2025-04-01T22:06:02.766Z,1743545162.766 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T22:06:02.766Z,1743545162.766 [Default:CheckIn:D] Running Loop=1
2025-04-01T22:06:03.170Z,1743545163.170 [Default:CheckIn:D] Stopped
2025-04-01T22:06:03.170Z,1743545163.170 [Default:CheckIn:E] Running Loop=1
2025-04-01T22:06:03.582Z,1743545163.582 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  98.214339 min 
2025-04-01T22:06:03.582Z,1743545163.582 [Default:CheckIn:E] Stopped
2025-04-01T22:06:03.582Z,1743545163.582 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T22:06:03.582Z,1743545163.582 [Default:CheckIn] Stopped
2025-04-01T22:06:03.583Z,1743545163.583 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T22:06:03.583Z,1743545163.583 [Default:CheckIn](INFO): Running loop #17
2025-04-01T22:06:03.583Z,1743545163.583 [Default:CheckIn] Running Loop=17
2025-04-01T22:06:03.583Z,1743545163.583 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T22:06:03.583Z,1743545163.583 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T22:06:05.582Z,1743545165.582 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220604.00,A,3648.17670,N,12147.28344,W,0.000,0.00,010425,,,A*7A
2025-04-01T22:06:05.584Z,1743545165.584 [NAL9602](INFO): GPS fix at 20250401T220604: (36.802945, -121.788057)
2025-04-01T22:06:05.594Z,1743545165.594 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T22:06:05.595Z,1743545165.595 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T22:06:18.174Z,1743545178.174 [NAL9602](INFO): SBD MO Status=1, MOMSN=18798, MT Status=0, MTMSN=0
2025-04-01T22:06:18.232Z,1743545178.232 [NAL9602](INFO): Sent 73 bytes from file Logs/20250401T202242/Courier0061.lzma
2025-04-01T22:06:18.232Z,1743545178.232 [NAL9602](INFO): Packets left to send: 0
2025-04-01T22:06:32.668Z,1743545192.668 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:06:40.030Z,1743545200.030 [NAL9602](INFO): SBD MO Status=1, MOMSN=18799, MT Status=0, MTMSN=0
2025-04-01T22:06:40.080Z,1743545200.080 [NAL9602](INFO): Sent 332 bytes from file Logs/20250401T202242/Express0062.lzma
2025-04-01T22:06:40.080Z,1743545200.080 [NAL9602](INFO): Packets left to send: 1
2025-04-01T22:06:54.241Z,1743545214.241 [NAL9602](INFO): SBD MO Status=1, MOMSN=18800, MT Status=0, MTMSN=0
2025-04-01T22:06:54.292Z,1743545214.292 [NAL9602](INFO): Sent 234 bytes from file Logs/20250401T202242/Express0062.lzma
2025-04-01T22:06:54.292Z,1743545214.292 [NAL9602](INFO): Packets left to send: 0
2025-04-01T22:07:07.808Z,1743545227.808 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:07:11.195Z,1743545231.195 [NAL9602](INFO): SBD MO Status=2, MOMSN=18801, MT Status=2, MTMSN=0
2025-04-01T22:07:11.195Z,1743545231.195 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-01T22:07:28.570Z,1743545248.570 [NAL9602](INFO): SBD MO Status=2, MOMSN=18801, MT Status=2, MTMSN=0
2025-04-01T22:07:28.570Z,1743545248.570 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-04-01T22:07:39.077Z,1743545259.077 [NAL9602](INFO): SBD MO Status=0, MOMSN=18801, MT Status=0, MTMSN=0
2025-04-01T22:07:39.173Z,1743545259.173 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T22:07:39.173Z,1743545259.173 [Default:CheckIn:C.Wait] Running Loop=1
2025-04-01T22:07:39.173Z,1743545259.173 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-04-01T22:07:42.948Z,1743545262.948 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:07:59.311Z,1743545279.311 [CBIT](INFO): Clearing failed state for component DropWeight
2025-04-01T22:07:59.312Z,1743545279.312 [DropWeight] No Fault, FailCount= 1
2025-04-01T22:08:09.777Z,1743545289.777 [NAL9602](INFO): Not Powering down - fast GPS
2025-04-01T22:08:18.088Z,1743545298.088 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:08:53.228Z,1743545333.228 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:09:28.376Z,1743545368.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:10:03.516Z,1743545403.516 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:10:38.656Z,1743545438.656 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:11:13.796Z,1743545473.796 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:11:48.964Z,1743545508.964 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:12:24.113Z,1743545544.113 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:12:39.665Z,1743545559.665 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-04-01T22:12:39.665Z,1743545559.665 [Default:CheckIn:C.Wait] Stopped
2025-04-01T22:12:39.666Z,1743545559.666 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-04-01T22:12:39.666Z,1743545559.666 [Default:CheckIn:D] Running Loop=1
2025-04-01T22:12:40.060Z,1743545560.060 [Default:CheckIn:D] Stopped
2025-04-01T22:12:40.060Z,1743545560.060 [Default:CheckIn:E] Running Loop=1
2025-04-01T22:12:40.475Z,1743545560.475 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  104.829175 min 
2025-04-01T22:12:40.475Z,1743545560.475 [Default:CheckIn:E] Stopped
2025-04-01T22:12:40.475Z,1743545560.475 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-04-01T22:12:40.475Z,1743545560.475 [Default:CheckIn] Stopped
2025-04-01T22:12:40.475Z,1743545560.475 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T22:12:40.476Z,1743545560.476 [Default:CheckIn](INFO): Running loop #18
2025-04-01T22:12:40.476Z,1743545560.476 [Default:CheckIn] Running Loop=18
2025-04-01T22:12:40.476Z,1743545560.476 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-04-01T22:12:40.476Z,1743545560.476 [Default:CheckIn:Read_GPS] Running Loop=1
2025-04-01T22:12:42.486Z,1743545562.486 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221241.00,A,3648.17554,N,12147.28563,W,0.058,0.00,010425,,,A*75
2025-04-01T22:12:42.489Z,1743545562.489 [NAL9602](INFO): GPS fix at 20250401T221241: (36.802926, -121.788094)
2025-04-01T22:12:42.499Z,1743545562.499 [Default:CheckIn:Read_GPS] Stopped
2025-04-01T22:12:42.499Z,1743545562.499 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-04-01T22:12:59.252Z,1743545579.252 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-04-01T22:13:03.532Z,1743545583.532 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=18802, MT Status=1, MTMSN=1086
2025-04-01T22:13:03.580Z,1743545583.580 [NAL9602](INFO): Sent 74 bytes from file Logs/20250401T202242/Courier0064.lzma
2025-04-01T22:13:03.580Z,1743545583.580 [NAL9602](INFO): Packets left to send: 0
2025-04-01T22:13:04.024Z,1743545584.024 [NAL9602](INFO): Received command: restart sys
2025-04-01T22:13:04.102Z,1743545584.102 [CommandExec](IMPORTANT): got command restart system
2025-04-01T22:13:06.216Z,1743545586.216 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-01T22:13:06.216Z,1743545586.216 [CommandExec](INFO): Uninitializing the command executive.
2025-04-01T22:13:06.216Z,1743545586.216 [CommandExec](INFO): Uninitializing the command scheduler.
2025-04-01T22:13:06.216Z,1743545586.216 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:06.367Z,1743545586.367 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-04-01T22:13:06.368Z,1743545586.368 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-04-01T22:13:06.368Z,1743545586.368 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:06.369Z,1743545586.369 [NavChartDb](INFO): Join timeout helper Thread ID is 8834
2025-04-01T22:13:06.524Z,1743545586.524 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-01T22:13:06.524Z,1743545586.524 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:06.531Z,1743545586.531 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-04-01T22:13:06.531Z,1743545586.531 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:06.534Z,1743545586.534 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8835
2025-04-01T22:13:06.568Z,1743545586.568 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-01T22:13:06.568Z,1743545586.568 [WetLabsBB2FL](INFO): Powering down
2025-04-01T22:13:06.569Z,1743545586.569 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:06.571Z,1743545586.571 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-04-01T22:13:06.572Z,1743545586.572 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:06.572Z,1743545586.572 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8836
2025-04-01T22:13:07.164Z,1743545587.164 [CTD_Seabird](INFO): Powering down
2025-04-01T22:13:07.176Z,1743545587.176 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-01T22:13:07.177Z,1743545587.177 [CTD_Seabird](INFO): Powering down
2025-04-01T22:13:07.192Z,1743545587.192 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:07.192Z,1743545587.192 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-04-01T22:13:07.192Z,1743545587.192 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:07.193Z,1743545587.193 [Radio_Surface](INFO): Join timeout helper Thread ID is 8837
2025-04-01T22:13:07.431Z,1743545587.431 [Radio_Surface](INFO): Powering down
2025-04-01T22:13:07.432Z,1743545587.432 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-01T22:13:07.433Z,1743545587.433 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:07.442Z,1743545587.442 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-04-01T22:13:07.442Z,1743545587.442 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:07.443Z,1743545587.443 [Onboard](INFO): Join timeout helper Thread ID is 8838
2025-04-01T22:13:10.219Z,1743545590.219 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-01T22:13:10.220Z,1743545590.220 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.224Z,1743545590.224 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-04-01T22:13:10.224Z,1743545590.224 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.225Z,1743545590.225 [DataOverHttps](INFO): Join timeout helper Thread ID is 8839
2025-04-01T22:13:10.304Z,1743545590.304 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-01T22:13:10.304Z,1743545590.304 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.324Z,1743545590.324 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-04-01T22:13:10.324Z,1743545590.324 [DAT ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.325Z,1743545590.325 [DAT](INFO): Join timeout helper Thread ID is 8840
2025-04-01T22:13:10.331Z,1743545590.331 [DAT](INFO): Powering down
2025-04-01T22:13:10.400Z,1743545590.400 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-01T22:13:10.400Z,1743545590.400 [DAT](INFO): Powering down
2025-04-01T22:13:10.401Z,1743545590.401 [DAT ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.408Z,1743545590.408 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-04-01T22:13:10.408Z,1743545590.408 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.409Z,1743545590.409 [BackseatComponent](INFO): Join timeout helper Thread ID is 8841
2025-04-01T22:13:10.540Z,1743545590.540 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-01T22:13:10.540Z,1743545590.540 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.548Z,1743545590.548 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-04-01T22:13:10.548Z,1743545590.548 [logger ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.549Z,1743545590.549 [logger](INFO): Join timeout helper Thread ID is 8842
2025-04-01T22:13:10.568Z,1743545590.568 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-01T22:13:10.568Z,1743545590.568 [logger ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.588Z,1743545590.588 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-04-01T22:13:10.588Z,1743545590.588 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.589Z,1743545590.589 [CommandLine](INFO): Join timeout helper Thread ID is 8843
2025-04-01T22:13:10.596Z,1743545590.596 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-01T22:13:10.596Z,1743545590.596 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.608Z,1743545590.608 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-04-01T22:13:10.608Z,1743545590.608 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.609Z,1743545590.609 [CommandExec](INFO): Join timeout helper Thread ID is 8844
2025-04-01T22:13:10.610Z,1743545590.610 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-04-01T22:13:10.610Z,1743545590.610 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.610Z,1743545590.610 [controlThread](INFO): Join timeout helper Thread ID is 8845
2025-04-01T22:13:10.824Z,1743545590.824 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-01T22:13:10.824Z,1743545590.824 [controlThread](DEBUG): Uninitializing ControlThread
2025-04-01T22:13:10.825Z,1743545590.825 [AHRS_M2](INFO): Powering down
2025-04-01T22:13:10.904Z,1743545590.904 [MultiRay](INFO): Powering down.
2025-04-01T22:13:10.905Z,1743545590.905 [NAL9602](INFO): Powering down
2025-04-01T22:13:10.907Z,1743545590.907 [Waterlinked](INFO): Powering down
2025-04-01T22:13:10.909Z,1743545590.909 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-04-01T22:13:10.909Z,1743545590.909 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-04-01T22:13:10.910Z,1743545590.910 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-04-01T22:13:10.910Z,1743545590.910 [MissionManager](INFO): Uninitializing Mission Default
2025-04-01T22:13:10.910Z,1743545590.910 [Default] Stopped
2025-04-01T22:13:10.911Z,1743545590.911 [Default](DEBUG): Aggregate::uninitialize Default
2025-04-01T22:13:10.911Z,1743545590.911 [Default:B.GoToSurface] Stopped
2025-04-01T22:13:10.911Z,1743545590.911 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-04-01T22:13:10.911Z,1743545590.911 [Default:CheckIn] Stopped
2025-04-01T22:13:10.911Z,1743545590.911 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-04-01T22:13:10.911Z,1743545590.911 [Default:CheckIn:Read_Iridium] Stopped
2025-04-01T22:13:10.914Z,1743545590.914 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-04-01T22:13:10.915Z,1743545590.915 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-04-01T22:13:10.915Z,1743545590.915 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-04-01T22:13:10.915Z,1743545590.915 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-04-01T22:13:10.916Z,1743545590.916 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-04-01T22:13:10.916Z,1743545590.916 [BuoyancyServo](INFO): Powering down
2025-04-01T22:13:10.928Z,1743545590.928 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-01T22:13:10.928Z,1743545590.928 [ElevatorServo](INFO): Powering down
2025-04-01T22:13:10.929Z,1743545590.929 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-04-01T22:13:10.929Z,1743545590.929 [MassServo](INFO): Powering down
2025-04-01T22:13:10.930Z,1743545590.930 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-04-01T22:13:10.930Z,1743545590.930 [RudderServo](INFO): Powering down
2025-04-01T22:13:10.931Z,1743545590.931 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-04-01T22:13:10.931Z,1743545590.931 [ThrusterHE](INFO): Powering down
2025-04-01T22:13:10.932Z,1743545590.932 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-04-01T22:13:10.932Z,1743545590.932 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-04-01T22:13:10.932Z,1743545590.932 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-04-01T22:13:10.932Z,1743545590.932 [CBIT](DEBUG): Powering off loads.
2025-04-01T22:13:10.943Z,1743545590.943 [CBIT](DEBUG): Disabling WDT.
2025-04-01T22:13:10.955Z,1743545590.955 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-04-01T22:13:10.956Z,1743545590.956 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-04-01T22:13:10.957Z,1743545590.957 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.987Z,1743545590.987 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.991Z,1743545590.991 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:10.999Z,1743545590.999 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:11.006Z,1743545591.006 [DAT ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:11.054Z,1743545591.054 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:11.092Z,1743545591.092 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-04-01T22:13:11.092Z,1743545591.092 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-04-01T22:13:11.108Z,1743545591.108 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:11.113Z,1743545591.113 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:11.171Z,1743545591.171 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-01T22:13:11.220Z,1743545591.220 [logger ThreadHandler](INFO): Thread cancelled.