2025-05-29T00:15:59.688Z,1748477759.688 [Supervisor](DEBUG): Initializing supervisor.
2025-05-29T00:15:59.692Z,1748477759.692 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-05-29T00:15:59.693Z,1748477759.693 [SyncHandler](INFO): Protected caller Thread ID is 940
2025-05-29T00:15:59.693Z,1748477759.693 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-05-29T00:15:59.694Z,1748477759.694 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-05-29T00:15:59.695Z,1748477759.695 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 941
2025-05-29T00:15:59.699Z,1748477759.699 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-05-29T00:15:59.719Z,1748477759.719 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-05-29T00:15:59.720Z,1748477759.720 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-05-29T00:15:59.721Z,1748477759.721 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 942
2025-05-29T00:15:59.725Z,1748477759.725 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-05-29T00:15:59.726Z,1748477759.726 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-05-29T00:15:59.726Z,1748477759.726 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 943
2025-05-29T00:15:59.729Z,1748477759.729 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-05-29T00:15:59.730Z,1748477759.730 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-05-29T00:15:59.730Z,1748477759.730 [logger ThreadHandler](INFO): Protected caller Thread ID is 944
2025-05-29T00:15:59.734Z,1748477759.734 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-05-29T00:15:59.734Z,1748477759.734 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-05-29T00:15:59.736Z,1748477759.736 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-05-29T00:15:59.907Z,1748477759.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-05-29T00:15:59.908Z,1748477759.908 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-05-29T00:16:00.146Z,1748477760.146 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-05-29T00:16:00.147Z,1748477760.147 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-05-29T00:16:00.563Z,1748477760.563 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-05-29T00:16:00.563Z,1748477760.563 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-05-29T00:16:00.685Z,1748477760.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-05-29T00:16:00.685Z,1748477760.685 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-05-29T00:16:01.543Z,1748477761.543 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-05-29T00:16:01.543Z,1748477761.543 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-05-29T00:16:02.073Z,1748477762.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-05-29T00:16:02.074Z,1748477762.074 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-05-29T00:16:02.643Z,1748477762.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-05-29T00:16:02.644Z,1748477762.644 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-05-29T00:16:03.079Z,1748477763.079 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-05-29T00:16:03.080Z,1748477763.080 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-05-29T00:16:03.451Z,1748477763.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-05-29T00:16:03.451Z,1748477763.451 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-05-29T00:16:03.849Z,1748477763.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-05-29T00:16:03.850Z,1748477763.850 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-05-29T00:16:04.089Z,1748477764.089 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-05-29T00:16:04.090Z,1748477764.090 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-05-29T00:16:04.245Z,1748477764.245 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-05-29T00:16:04.245Z,1748477764.245 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-05-29T00:16:04.341Z,1748477764.341 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-05-29T00:16:04.434Z,1748477764.434 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-05-29T00:16:04.638Z,1748477764.638 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-05-29T00:16:04.638Z,1748477764.638 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-05-29T00:16:04.902Z,1748477764.902 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-05-29T00:16:04.904Z,1748477764.904 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2025-05-29T00:16:04.905Z,1748477764.905 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2025-05-29T00:16:05.002Z,1748477765.002 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2025-05-29T00:16:05.281Z,1748477765.281 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-05-29T00:16:05.282Z,1748477765.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2025-05-29T00:16:05.379Z,1748477765.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2025-05-29T00:16:05.833Z,1748477765.833 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2025-05-29T00:16:06.927Z,1748477766.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2025-05-29T00:16:07.050Z,1748477767.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2025-05-29T00:16:07.175Z,1748477767.175 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2025-05-29T00:16:07.282Z,1748477767.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2025-05-29T00:16:07.455Z,1748477767.455 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2025-05-29T00:16:07.564Z,1748477767.564 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2025-05-29T00:16:07.661Z,1748477767.661 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2025-05-29T00:16:07.771Z,1748477767.771 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2025-05-29T00:16:07.871Z,1748477767.871 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2025-05-29T00:16:07.872Z,1748477767.872 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-05-29T00:16:07.883Z,1748477767.883 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-05-29T00:16:08.064Z,1748477768.064 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-05-29T00:16:08.065Z,1748477768.065 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-05-29T00:16:08.204Z,1748477768.204 [BuoyancyServo] Loaded
2025-05-29T00:16:08.205Z,1748477768.205 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-05-29T00:16:08.228Z,1748477768.228 [ElevatorServo] Loaded
2025-05-29T00:16:08.229Z,1748477768.229 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-05-29T00:16:08.251Z,1748477768.251 [MassServo] Loaded
2025-05-29T00:16:08.251Z,1748477768.251 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-05-29T00:16:08.273Z,1748477768.273 [RudderServo] Loaded
2025-05-29T00:16:08.274Z,1748477768.274 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-05-29T00:16:08.291Z,1748477768.291 [ThrusterHE] Loaded
2025-05-29T00:16:08.292Z,1748477768.292 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-05-29T00:16:08.292Z,1748477768.292 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-05-29T00:16:08.293Z,1748477768.293 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-05-29T00:16:08.459Z,1748477768.459 [SBIT](DEBUG): Construct Startup Built In Test.
2025-05-29T00:16:08.474Z,1748477768.474 [SBIT] Loaded
2025-05-29T00:16:08.475Z,1748477768.475 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-05-29T00:16:08.477Z,1748477768.477 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-05-29T00:16:08.499Z,1748477768.499 [IBIT] Loaded
2025-05-29T00:16:08.499Z,1748477768.499 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-05-29T00:16:08.504Z,1748477768.504 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-05-29T00:16:08.698Z,1748477768.698 [CBIT] Loaded
2025-05-29T00:16:08.698Z,1748477768.698 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-05-29T00:16:08.729Z,1748477768.729 [GFScanner] Loaded
2025-05-29T00:16:08.729Z,1748477768.729 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-05-29T00:16:08.729Z,1748477768.729 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-05-29T00:16:08.730Z,1748477768.730 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-05-29T00:16:08.895Z,1748477768.895 [CTD_Seabird] Loaded
2025-05-29T00:16:08.896Z,1748477768.896 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-05-29T00:16:08.897Z,1748477768.897 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406834E0
2025-05-29T00:16:08.897Z,1748477768.897 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1033
2025-05-29T00:16:08.932Z,1748477768.932 [ESPComponent] Loaded
2025-05-29T00:16:08.932Z,1748477768.932 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-05-29T00:16:08.954Z,1748477768.954 [PAR_Licor] Loaded
2025-05-29T00:16:08.954Z,1748477768.954 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-05-29T00:16:08.000Z,1748477769.000 [WetLabsBB2FL] Loaded
2025-05-29T00:16:09.000Z,1748477769.000 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-05-29T00:16:09.001Z,1748477769.001 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B34E0
2025-05-29T00:16:09.002Z,1748477769.002 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1034
2025-05-29T00:16:09.002Z,1748477769.002 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-05-29T00:16:09.003Z,1748477769.003 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-05-29T00:16:09.055Z,1748477769.055 [DepthRateCalculator] Loaded
2025-05-29T00:16:09.055Z,1748477769.055 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-05-29T00:16:09.061Z,1748477769.061 [PitchRateCalculator] Loaded
2025-05-29T00:16:09.061Z,1748477769.061 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-05-29T00:16:09.071Z,1748477769.071 [SpeedCalculator] Loaded
2025-05-29T00:16:09.071Z,1748477769.071 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-05-29T00:16:09.076Z,1748477769.076 [YawRateCalculator] Loaded
2025-05-29T00:16:09.076Z,1748477769.076 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-05-29T00:16:09.096Z,1748477769.096 [ElevatorOffsetCalculator] Loaded
2025-05-29T00:16:09.097Z,1748477769.097 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-05-29T00:16:09.097Z,1748477769.097 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-05-29T00:16:09.098Z,1748477769.098 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-05-29T00:16:09.235Z,1748477769.235 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-05-29T00:16:09.235Z,1748477769.235 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-05-29T00:16:09.265Z,1748477769.265 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-05-29T00:16:09.265Z,1748477769.265 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-05-29T00:16:09.379Z,1748477769.379 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-05-29T00:16:09.380Z,1748477769.380 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-05-29T00:16:09.393Z,1748477769.393 [NavChart] Loaded
2025-05-29T00:16:09.394Z,1748477769.394 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-05-29T00:16:09.399Z,1748477769.399 [UniversalFixResidualReporter] Loaded
2025-05-29T00:16:09.400Z,1748477769.400 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-05-29T00:16:09.400Z,1748477769.400 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-05-29T00:16:09.401Z,1748477769.401 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-05-29T00:16:09.880Z,1748477769.880 [AHRS_M2] Loaded
2025-05-29T00:16:09.881Z,1748477769.881 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-05-29T00:16:09.919Z,1748477769.919 [BackseatComponent] Loaded
2025-05-29T00:16:09.920Z,1748477769.920 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-05-29T00:16:09.921Z,1748477769.921 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A7F4E0
2025-05-29T00:16:09.921Z,1748477769.921 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1035
2025-05-29T00:16:09.924Z,1748477769.924 [LcmUniversalReporter] Loaded
2025-05-29T00:16:09.924Z,1748477769.924 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-05-29T00:16:10.911Z,1748477770.911 [BPC1] Loaded
2025-05-29T00:16:10.911Z,1748477770.911 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-05-29T00:16:11.011Z,1748477771.011 [DAT] Loaded
2025-05-29T00:16:11.011Z,1748477771.011 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-05-29T00:16:11.012Z,1748477771.012 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0
2025-05-29T00:16:11.013Z,1748477771.013 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1036
2025-05-29T00:16:11.088Z,1748477771.088 [DataOverHttps] Loaded
2025-05-29T00:16:11.088Z,1748477771.088 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-05-29T00:16:11.090Z,1748477771.090 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2025-05-29T00:16:11.090Z,1748477771.090 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1037
2025-05-29T00:16:11.113Z,1748477771.113 [Depth_Keller] Loaded
2025-05-29T00:16:11.114Z,1748477771.114 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-05-29T00:16:11.119Z,1748477771.119 [DropWeight] Loaded
2025-05-29T00:16:11.119Z,1748477771.119 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-05-29T00:16:11.160Z,1748477771.160 [DVL_micro] Loaded
2025-05-29T00:16:11.161Z,1748477771.161 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2025-05-29T00:16:11.223Z,1748477771.223 [NAL9602] Loaded
2025-05-29T00:16:11.224Z,1748477771.224 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-05-29T00:16:11.250Z,1748477771.250 [Onboard] Loaded
2025-05-29T00:16:11.250Z,1748477771.250 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-05-29T00:16:11.251Z,1748477771.251 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B0F4E0
2025-05-29T00:16:11.252Z,1748477771.252 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1038
2025-05-29T00:16:11.258Z,1748477771.258 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1
2025-05-29T00:16:11.272Z,1748477771.272 [PowerOnly] Loaded
2025-05-29T00:16:11.272Z,1748477771.272 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-05-29T00:16:11.286Z,1748477771.286 [Power24vConverter] Loaded
2025-05-29T00:16:11.286Z,1748477771.286 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-05-29T00:16:11.302Z,1748477771.302 [Radio_Surface] Loaded
2025-05-29T00:16:11.303Z,1748477771.303 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-05-29T00:16:11.304Z,1748477771.304 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B3F4E0
2025-05-29T00:16:11.304Z,1748477771.304 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1039
2025-05-29T00:16:11.320Z,1748477771.320 [Sonardyne_Nano] Loaded
2025-05-29T00:16:11.320Z,1748477771.320 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-05-29T00:16:11.321Z,1748477771.321 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-05-29T00:16:11.321Z,1748477771.321 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-05-29T00:16:11.510Z,1748477771.510 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-05-29T00:16:11.511Z,1748477771.511 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-05-29T00:16:11.625Z,1748477771.625 [VerticalControl](DEBUG): Construct VerticalControl.
2025-05-29T00:16:11.678Z,1748477771.678 [VerticalControl] Loaded
2025-05-29T00:16:11.678Z,1748477771.678 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-05-29T00:16:11.681Z,1748477771.681 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-05-29T00:16:11.725Z,1748477771.725 [HorizontalControl] Loaded
2025-05-29T00:16:11.726Z,1748477771.726 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-05-29T00:16:11.728Z,1748477771.728 [SpeedControl](DEBUG): Construct SpeedControl.
2025-05-29T00:16:11.730Z,1748477771.730 [SpeedControl] Loaded
2025-05-29T00:16:11.730Z,1748477771.730 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-05-29T00:16:11.733Z,1748477771.733 [LoopControl](DEBUG): Construct LoopControl.
2025-05-29T00:16:11.734Z,1748477771.734 [LoopControl] Loaded
2025-05-29T00:16:11.734Z,1748477771.734 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-05-29T00:16:11.734Z,1748477771.734 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-05-29T00:16:11.735Z,1748477771.735 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-05-29T00:16:11.745Z,1748477771.745 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-05-29T00:16:11.746Z,1748477771.746 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-05-29T00:16:12.101Z,1748477772.101 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-05-29T00:16:12.107Z,1748477772.107 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-05-29T00:16:12.110Z,1748477772.110 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-05-29T00:16:12.122Z,1748477772.122 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-05-29T00:16:12.123Z,1748477772.123 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0
2025-05-29T00:16:12.123Z,1748477772.123 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1040
2025-05-29T00:16:12.128Z,1748477772.128 [Supervisor](INFO): Main Thread ID is 939
2025-05-29T00:16:12.128Z,1748477772.128 [Supervisor](DEBUG): Running supervisor.
2025-05-29T00:16:12.128Z,1748477772.128 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1041
2025-05-29T00:16:12.129Z,1748477772.129 [CommandExec](INFO): Initializing the command executive.
2025-05-29T00:16:12.131Z,1748477772.131 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1042
2025-05-29T00:16:12.134Z,1748477772.134 [controlThread ThreadHandler](INFO): Handler Thread ID is 1043
2025-05-29T00:16:12.134Z,1748477772.134 [controlThread](DEBUG): Initializing ControlThread
2025-05-29T00:16:12.136Z,1748477772.136 [SBIT](INFO): Initialize SBIT Component.
2025-05-29T00:16:12.137Z,1748477772.137 [SBIT](IMPORTANT): git: 2025-05-12_B
2025-05-29T00:16:12.137Z,1748477772.137 [SBIT](INFO): git hash: 7c5b1c07405cfe7ca09821eb029d166d25d65d57
2025-05-29T00:16:12.137Z,1748477772.137 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-05-29T00:16:12.139Z,1748477772.139 [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-05-29T00:16:12.140Z,1748477772.140 [SBIT](INFO): Beginning SBIT in 164.000000 seconds.
2025-05-29T00:16:12.140Z,1748477772.140 [IBIT](INFO): Initialize IBIT Component.
2025-05-29T00:16:12.141Z,1748477772.141 [CBIT](DEBUG): Initialize CBIT Component.
2025-05-29T00:16:12.142Z,1748477772.142 [logger ThreadHandler](INFO): Handler Thread ID is 1044
2025-05-29T00:16:12.153Z,1748477772.153 [CBIT](DEBUG): Initialized mux pins.
2025-05-29T00:16:12.153Z,1748477772.153 [CBIT](DEBUG): Initializing the watchdog timer.
2025-05-29T00:16:12.161Z,1748477772.161 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1045
2025-05-29T00:16:12.162Z,1748477772.162 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-05-29T00:16:12.166Z,1748477772.166 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1047
2025-05-29T00:16:12.169Z,1748477772.169 [WetLabsBB2FL](INFO): Powering up
2025-05-29T00:16:12.170Z,1748477772.170 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1049
2025-05-29T00:16:12.176Z,1748477772.176 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-05-29T00:16:12.177Z,1748477772.177 [CBIT](DEBUG): Initializing heartbeat.
2025-05-29T00:16:12.178Z,1748477772.178 [DAT ThreadHandler](INFO): Handler Thread ID is 1050
2025-05-29T00:16:12.179Z,1748477772.179 [DAT](INFO): Powering up
2025-05-29T00:16:12.179Z,1748477772.179 [DAT](DEBUG): Initializing DAT.
2025-05-29T00:16:12.182Z,1748477772.182 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1052
2025-05-29T00:16:12.183Z,1748477772.183 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-05-29T00:16:12.193Z,1748477772.193 [Onboard ThreadHandler](INFO): Handler Thread ID is 1053
2025-05-29T00:16:12.213Z,1748477772.213 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1054
2025-05-29T00:16:12.234Z,1748477772.234 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1055
2025-05-29T00:16:12.237Z,1748477772.237 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-05-29T00:16:12.237Z,1748477772.237 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-05-29T00:16:12.237Z,1748477772.237 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-05-29T00:16:12.237Z,1748477772.237 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-05-29T00:16:12.237Z,1748477772.237 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-05-29T00:16:12.237Z,1748477772.237 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-05-29T00:16:12.238Z,1748477772.238 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-05-29T00:16:12.238Z,1748477772.238 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-05-29T00:16:12.249Z,1748477772.249 [CBIT](DEBUG): Deactivating emergency mode.
2025-05-29T00:16:12.289Z,1748477772.289 [CBIT](DEBUG): Backplane powered.
2025-05-29T00:16:12.289Z,1748477772.289 [GFScanner](DEBUG): Initializing GFScanner
2025-05-29T00:16:12.289Z,1748477772.289 [GFScanner](DEBUG): Deactivating GF circuits.
2025-05-29T00:16:12.297Z,1748477772.297 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-05-29T00:16:12.297Z,1748477772.297 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-05-29T00:16:12.298Z,1748477772.298 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-05-29T00:16:12.298Z,1748477772.298 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-05-29T00:16:12.298Z,1748477772.298 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-05-29T00:16:12.300Z,1748477772.300 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-05-29T00:16:12.300Z,1748477772.300 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-05-29T00:16:12.304Z,1748477772.304 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-05-29T00:16:12.310Z,1748477772.310 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-05-29T00:16:12.311Z,1748477772.311 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-05-29T00:16:12.312Z,1748477772.312 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-05-29T00:16:12.317Z,1748477772.317 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-05-29T00:16:12.317Z,1748477772.317 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-05-29T00:16:12.318Z,1748477772.318 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-05-29T00:16:12.319Z,1748477772.319 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-05-29T00:16:12.394Z,1748477772.394 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-05-29T00:16:12.419Z,1748477772.419 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-29T00:16:12.452Z,1748477772.452 [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-05-29T00:16:12.452Z,1748477772.452 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-05-29T00:16:12.481Z,1748477772.481 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-05-29T00:16:12.482Z,1748477772.482 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-05-29T00:16:12.621Z,1748477772.621 [Radio_Surface](INFO): Powering up
2025-05-29T00:16:12.822Z,1748477772.822 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-05-29T00:16:12.841Z,1748477772.841 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-05-29T00:16:12.842Z,1748477772.842 [Default:A.Wait](DEBUG): Construct Wait.
2025-05-29T00:16:12.844Z,1748477772.844 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-29T00:16:12.893Z,1748477772.893 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-05-29T00:16:12.896Z,1748477772.896 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-05-29T00:16:12.915Z,1748477772.915 [Default:E.Execute](DEBUG): Construct Execute.
2025-05-29T00:16:12.935Z,1748477772.935 [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-05-29T00:16:12.941Z,1748477772.941 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-05-29T00:16:12.982Z,1748477772.982 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-05-29T00:16:13.041Z,1748477773.041 [Depth_Keller](INFO): Initializing.
2025-05-29T00:16:13.050Z,1748477773.050 [PowerOnly](INFO): Powering up loadControl
2025-05-29T00:16:13.069Z,1748477773.069 [Power24vConverter](INFO): Powering up.
2025-05-29T00:16:13.070Z,1748477773.070 [Sonardyne_Nano](INFO): Initializing.
2025-05-29T00:16:13.097Z,1748477773.097 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-05-29T00:16:13.120Z,1748477773.120 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-05-29T00:16:13.125Z,1748477773.125 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-05-29T00:16:13.126Z,1748477773.126 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-05-29T00:16:13.133Z,1748477773.133 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-05-29T00:16:13.134Z,1748477773.134 [MassServo](DEBUG): Initializing EZServoServo.
2025-05-29T00:16:13.149Z,1748477773.149 [MassServo](DEBUG): Initializing MassServo.
2025-05-29T00:16:13.150Z,1748477773.150 [RudderServo](DEBUG): Initializing EZServoServo.
2025-05-29T00:16:13.157Z,1748477773.157 [RudderServo](DEBUG): Initializing RudderServo.
2025-05-29T00:16:13.158Z,1748477773.158 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-05-29T00:16:13.165Z,1748477773.165 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-05-29T00:16:14.781Z,1748477774.781 [WetLabsBB2FL](INFO): Powering down
2025-05-29T00:16:15.380Z,1748477775.380 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-05-29T00:16:16.630Z,1748477776.630 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2025-05-29T00:16:19.193Z,1748477779.193 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-05-29T00:16:24.277Z,1748477784.277 [DAT](INFO): DAT read:
2025-05-29T00:16:24.278Z,1748477784.278 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-05-29T00:16:26.042Z,1748477786.042 [DAT](INFO): DAT read: MF Frequency Band
2025-05-29T00:16:26.043Z,1748477786.043 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-05-29T00:16:26.043Z,1748477786.043 [DAT](INFO): DAT read: May 28 2025 21:39:44
2025-05-29T00:16:26.759Z,1748477786.759 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004418
2025-05-29T00:16:27.051Z,1748477787.051 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-05-29T00:16:27.052Z,1748477787.052 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2025-05-29T00:16:27.053Z,1748477787.053 [DAT](INFO): commRate: 1200
2025-05-29T00:16:29.117Z,1748477789.117 [DAT](INFO): entering command mode
2025-05-29T00:16:29.317Z,1748477789.317 [DAT](INFO): DAT read:
2025-05-29T00:16:29.318Z,1748477789.318 [DAT](INFO): DAT read: user:1>
2025-05-29T00:16:29.318Z,1748477789.318 [DAT](INFO): setting verbose to 3
2025-05-29T00:16:29.569Z,1748477789.569 [DAT](INFO): DAT read: user:1>
2025-05-29T00:16:29.570Z,1748477789.570 [DAT](INFO): DAT read: Verbose | 3
2025-05-29T00:16:29.571Z,1748477789.571 [DAT](INFO): set verbose to 3
2025-05-29T00:16:29.571Z,1748477789.571 [DAT](INFO): setting DatVerbose to 27440
2025-05-29T00:16:29.822Z,1748477789.822 [DAT](INFO): DAT read: user:2>
2025-05-29T00:16:29.822Z,1748477789.822 [DAT](INFO): DAT read: DatVerbose | 27440
2025-05-29T00:16:29.823Z,1748477789.823 [DAT](INFO): set DatVerbose to 27440
2025-05-29T00:16:29.823Z,1748477789.823 [DAT](INFO): setting transmit power to 8
2025-05-29T00:16:30.074Z,1748477790.074 [DAT](INFO): DAT read: user:3>
2025-05-29T00:16:30.075Z,1748477790.075 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-05-29T00:16:30.075Z,1748477790.075 [DAT](INFO): set transmit power to 8
2025-05-29T00:16:30.076Z,1748477790.076 [DAT](INFO): setting local address to 5
2025-05-29T00:16:30.329Z,1748477790.329 [DAT](INFO): DAT read: user:4>
2025-05-29T00:16:30.330Z,1748477790.330 [DAT](INFO): DAT read: LocalAddr | 5
2025-05-29T00:16:30.331Z,1748477790.331 [DAT](INFO): set local address to 5
2025-05-29T00:16:30.332Z,1748477790.332 [DAT](INFO): Setting time to: 0:16:30 And date to:5/29/2025
2025-05-29T00:16:30.586Z,1748477790.586 [DAT](INFO): DAT read: user:5>
2025-05-29T00:16:30.587Z,1748477790.587 [DAT](INFO): DAT read: Thu May 29, 2025 00:16:30
2025-05-29T00:16:30.588Z,1748477790.588 [DAT](INFO): Local DAT time set to Thu May 29, 2025 00:16:30
2025-05-29T00:16:41.291Z,1748477801.291 [NAL9602](INFO): Powering up NAL9602
2025-05-29T00:16:52.199Z,1748477812.199 [NAL9602](INFO): NAL9602 initialized
2025-05-29T00:17:24.403Z,1748477844.403 [CommandExec](IMPORTANT): got command failComponent
2025-05-29T00:17:24.403Z,1748477844.403 [CommandExec](IMPORTANT): Failed components:
2025-05-29T00:17:24.403Z,1748477844.403 [CommandExec](IMPORTANT): No failed Components.
2025-05-29T00:17:26.601Z,1748477846.601 [CommandExec](IMPORTANT): got command gfscan
2025-05-29T00:17:26.610Z,1748477846.610 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-05-29T00:17:31.046Z,1748477851.046 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001572
CHAN A1 (24V): 0.004249
CHAN A2 (12V): -0.010139
CHAN A3 (5V): -0.014329
CHAN B0 (3.3V): -0.003553
CHAN B1 (3.15aV): -0.001861
CHAN B2 (3.15bV): -0.001829
CHAN B3 (GND): -0.003573
OPEN: 0.012148
Full Scale: +/- 1 mA
2025-05-29T00:17:41.895Z,1748477861.895 [NAL9602](INFO): SBD MO Status=2, MOMSN=20724, MT Status=2, MTMSN=0
2025-05-29T00:17:41.895Z,1748477861.895 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-29T00:17:54.238Z,1748477874.238 [CommandExec](IMPORTANT): got command report touch platform_buoyancy_position
2025-05-29T00:17:58.066Z,1748477878.066 [NAL9602](INFO): SBD MO Status=2, MOMSN=20724, MT Status=2, MTMSN=0
2025-05-29T00:17:58.066Z,1748477878.066 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-29T00:18:19.541Z,1748477899.541 [CommandExec](IMPORTANT): got command get platform_mass_position
2025-05-29T00:18:19.541Z,1748477899.541 [CommandExec](IMPORTANT): platform_mass_position 0.036180 m
2025-05-29T00:18:27.190Z,1748477907.190 [CommandExec](IMPORTANT): got command get platform_mass_position millimeter
2025-05-29T00:18:27.191Z,1748477907.191 [CommandExec](IMPORTANT): platform_mass_position 36.180046 mm
2025-05-29T00:18:30.462Z,1748477910.462 [NAL9602](INFO): SBD MO Status=2, MOMSN=20724, MT Status=2, MTMSN=0
2025-05-29T00:18:30.462Z,1748477910.462 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-29T00:18:56.655Z,1748477936.655 [SBIT](IMPORTANT): Beginning Startup BIT
2025-05-29T00:18:56.660Z,1748477936.660 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-05-29T00:19:00.706Z,1748477940.706 [NAL9602](INFO): SBD MO Status=2, MOMSN=20724, MT Status=2, MTMSN=0
2025-05-29T00:19:00.706Z,1748477940.706 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-29T00:19:01.213Z,1748477941.213 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.004675
CHAN A1 (24V): 0.003796
CHAN A2 (12V): -0.005766
CHAN A3 (5V): -0.023215
CHAN B0 (3.3V): -0.003636
CHAN B1 (3.15aV): -0.002486
CHAN B2 (3.15bV): -0.002043
CHAN B3 (GND): -0.007283
OPEN: 0.012044
Full Scale: +/- 1 mA
2025-05-29T00:19:12.518Z,1748477952.518 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-05-29T00:19:12.518Z,1748477952.518 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-29T00:19:12.631Z,1748477952.631 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-29T00:19:12.961Z,1748477952.961 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-29T00:19:12.962Z,1748477952.962 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-05-29T00:19:50.556Z,1748477990.556 [SBIT](IMPORTANT): SBIT PASSED
2025-05-29T00:19:50.585Z,1748477990.585 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-05-29T00:19:50.586Z,1748477990.586 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;
2025-05-29T00:19:50.586Z,1748477990.586 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;
2025-05-29T00:19:50.586Z,1748477990.586 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=1 bool;
2025-05-29T00:19:50.586Z,1748477990.586 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;
2025-05-29T00:19:50.586Z,1748477990.586 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=5 minute;
2025-05-29T00:19:50.586Z,1748477990.586 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;
2025-05-29T00:19:50.586Z,1748477990.586 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;
2025-05-29T00:19:50.586Z,1748477990.586 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool;
2025-05-29T00:19:50.586Z,1748477990.586 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool;
2025-05-29T00:19:50.587Z,1748477990.587 [SBIT](IMPORTANT): ThrusterHE.bestEffortMode=0 bool;
2025-05-29T00:19:50.587Z,1748477990.587 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=245.826411 cubic_centimeter;
2025-05-29T00:19:50.587Z,1748477990.587 [SBIT](IMPORTANT): VerticalControl.massDefault=36.203649 millimeter;
2025-05-29T00:19:50.963Z,1748477990.963 [MissionManager](IMPORTANT): Started mission Startup
2025-05-29T00:19:50.963Z,1748477990.963 [Startup] Running Loop=1
2025-05-29T00:19:50.963Z,1748477990.963 [Startup](DEBUG): Aggregate::initialize Startup
2025-05-29T00:19:50.963Z,1748477990.963 [Startup:A.GoToSurface] Running Loop=1
2025-05-29T00:19:50.963Z,1748477990.963 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-29T00:19:50.964Z,1748477990.964 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-05-29T00:19:50.969Z,1748477990.969 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-05-29T00:19:50.969Z,1748477990.969 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-05-29T00:19:50.969Z,1748477990.969 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-05-29T00:19:50.970Z,1748477990.970 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-29T00:19:50.970Z,1748477990.970 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-29T00:19:50.972Z,1748477990.972 [Startup:StartupSatComms] Running Loop=1
2025-05-29T00:19:50.972Z,1748477990.972 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-05-29T00:19:50.972Z,1748477990.972 [Startup:StartupSatComms:A] Running Loop=1
2025-05-29T00:19:51.374Z,1748477991.374 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-05-29T00:20:30.445Z,1748478030.445 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 25 millimeter
2025-05-29T00:20:30.457Z,1748478030.457 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2025-05-29T00:20:30.583Z,1748478030.583 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-05-29T00:20:44.676Z,1748478044.676 [NAL9602](INFO): SBD MO Status=2, MOMSN=20724, MT Status=2, MTMSN=0
2025-05-29T00:20:44.676Z,1748478044.676 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-29T00:20:51.151Z,1748478051.151 [Startup:StartupSatComms:A](INFO): Timed out from 2025-05-29T00:19:50.0Z
2025-05-29T00:20:51.151Z,1748478051.151 [Startup:StartupSatComms:A] Stopped
2025-05-29T00:20:51.151Z,1748478051.151 [Startup:StartupSatComms:B] Running Loop=1
2025-05-29T00:20:51.568Z,1748478051.568 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-05-29T00:20:54.356Z,1748478054.356 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250528T171320/Courier0076.lzma
2025-05-29T00:20:55.359Z,1748478055.359 [DataOverHttps](INFO): Moved sent file to Logs/20250528T171320/Courier0076.lzma.bak
2025-05-29T00:20:55.360Z,1748478055.360 [DataOverHttps](INFO): SBD MOMSN=25023442
2025-05-29T00:21:11.508Z,1748478071.508 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250529T001559/Courier0000.lzma
2025-05-29T00:21:12.510Z,1748478072.510 [DataOverHttps](INFO): Moved sent file to Logs/20250529T001559/Courier0000.lzma.bak
2025-05-29T00:21:12.511Z,1748478072.511 [DataOverHttps](INFO): SBD MOMSN=25023486
2025-05-29T00:21:28.721Z,1748478088.721 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20250528T171320/Express0077.lzma
2025-05-29T00:21:29.723Z,1748478089.723 [DataOverHttps](INFO): Moved sent file to Logs/20250528T171320/Express0077.lzma.bak
2025-05-29T00:21:29.723Z,1748478089.723 [DataOverHttps](INFO): SBD MOMSN=25023500
2025-05-29T00:21:45.720Z,1748478105.720 [DataOverHttps](INFO): Sending 1238 bytes from file Logs/20250529T001559/Express0001.lzma
2025-05-29T00:21:46.718Z,1748478106.718 [DataOverHttps](INFO): Moved sent file to Logs/20250529T001559/Express0001.lzma.bak
2025-05-29T00:21:46.719Z,1748478106.719 [DataOverHttps](INFO): SBD MOMSN=25023506
2025-05-29T00:21:48.233Z,1748478108.233 [Startup:StartupSatComms:B] Stopped
2025-05-29T00:21:48.233Z,1748478108.233 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-05-29T00:21:48.233Z,1748478108.233 [Startup:StartupSatComms] Stopped
2025-05-29T00:21:48.233Z,1748478108.233 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-05-29T00:21:48.234Z,1748478108.234 [Startup](INFO): Completed Startup
2025-05-29T00:21:48.234Z,1748478108.234 [MissionManager](INFO): Startup is completed.
2025-05-29T00:21:48.234Z,1748478108.234 [MissionManager](INFO): Uninitializing Mission Startup
2025-05-29T00:21:48.234Z,1748478108.234 [Startup] Stopped
2025-05-29T00:21:48.234Z,1748478108.234 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-05-29T00:21:48.234Z,1748478108.234 [Startup:A.GoToSurface] Stopped
2025-05-29T00:21:48.235Z,1748478108.235 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-29T00:21:48.649Z,1748478108.649 [MissionManager](IMPORTANT): Started mission Default
2025-05-29T00:21:48.649Z,1748478108.649 [Default] Running Loop=1
2025-05-29T00:21:48.649Z,1748478108.649 [Default](DEBUG): Aggregate::initialize Default
2025-05-29T00:21:48.649Z,1748478108.649 [Default:B.GoToSurface] Running Loop=1
2025-05-29T00:21:48.649Z,1748478108.649 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-29T00:21:48.649Z,1748478108.649 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-05-29T00:21:48.650Z,1748478108.650 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-05-29T00:21:48.650Z,1748478108.650 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-05-29T00:21:48.650Z,1748478108.650 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-05-29T00:21:48.651Z,1748478108.651 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-29T00:21:48.651Z,1748478108.651 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-29T00:21:48.651Z,1748478108.651 [Default:A.Wait] Running Loop=1
2025-05-29T00:21:48.651Z,1748478108.651 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-05-29T00:21:55.106Z,1748478115.106 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-05-29T00:21:56.314Z,1748478116.314 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002154.00,A,3648.16366,N,12147.28366,W,1.244,144.05,290525,,,A*75
2025-05-29T00:21:56.360Z,1748478116.360 [NAL9602](INFO): GPS fix at 20250529T002154: (36.802728, -121.788061)
2025-05-29T00:22:01.981Z,1748478121.981 [Default:A.Wait](INFO): Done Waiting.
2025-05-29T00:22:01.981Z,1748478121.981 [Default:A.Wait] Stopped
2025-05-29T00:22:01.982Z,1748478121.982 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-05-29T00:22:02.394Z,1748478122.394 [Default:CheckIn] Running Loop=1
2025-05-29T00:22:02.395Z,1748478122.395 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-29T00:22:02.395Z,1748478122.395 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-29T00:22:02.784Z,1748478122.784 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-05-29T00:22:04.408Z,1748478124.408 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002202.00,A,3648.16355,N,12147.28274,W,0.369,144.05,290525,,,A*78
2025-05-29T00:22:04.411Z,1748478124.411 [NAL9602](INFO): GPS fix at 20250529T002202: (36.802726, -121.788046)
2025-05-29T00:22:04.446Z,1748478124.446 [Default:CheckIn:Read_GPS] Stopped
2025-05-29T00:22:04.446Z,1748478124.446 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-29T00:22:04.871Z,1748478124.871 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-05-29T00:22:13.181Z,1748478133.181 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250529T001559/Courier0004.lzma
2025-05-29T00:22:14.182Z,1748478134.182 [DataOverHttps](INFO): Moved sent file to Logs/20250529T001559/Courier0004.lzma.bak
2025-05-29T00:22:14.183Z,1748478134.183 [DataOverHttps](INFO): SBD MOMSN=25023548
2025-05-29T00:22:19.950Z,1748478139.950 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 200 cubic_centimeter
2025-05-29T00:22:19.986Z,1748478139.986 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2025-05-29T00:22:20.031Z,1748478140.031 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-05-29T00:22:26.147Z,1748478146.147 [Reporter](INFO): platform_buoyancy_position 950.162476 cc
2025-05-29T00:22:26.444Z,1748478146.444 [Reporter](INFO): platform_buoyancy_position 950.162476 cc
2025-05-29T00:22:26.860Z,1748478146.860 [Reporter](INFO): platform_buoyancy_position 950.028198 cc
2025-05-29T00:22:27.256Z,1748478147.256 [Reporter](INFO): platform_buoyancy_position 939.284180 cc
2025-05-29T00:22:27.690Z,1748478147.690 [Reporter](INFO): platform_buoyancy_position 937.135376 cc
2025-05-29T00:22:28.074Z,1748478148.074 [Reporter](INFO): platform_buoyancy_position 932.972046 cc
2025-05-29T00:22:28.487Z,1748478148.487 [Reporter](INFO): platform_buoyancy_position 928.540161 cc
2025-05-29T00:22:28.930Z,1748478148.930 [Reporter](INFO): platform_buoyancy_position 924.242554 cc
2025-05-29T00:22:29.319Z,1748478149.319 [Reporter](INFO): platform_buoyancy_position 920.079224 cc
2025-05-29T00:22:29.693Z,1748478149.693 [Reporter](INFO): platform_buoyancy_position 914.841553 cc
2025-05-29T00:22:30.372Z,1748478150.372 [Reporter](INFO): platform_buoyancy_position 905.037659 cc
2025-05-29T00:22:30.596Z,1748478150.596 [Reporter](INFO): platform_buoyancy_position 902.754517 cc
2025-05-29T00:22:31.042Z,1748478151.042 [Reporter](INFO): platform_buoyancy_position 896.576721 cc
2025-05-29T00:22:31.432Z,1748478151.432 [Reporter](INFO): platform_buoyancy_position 892.144836 cc
2025-05-29T00:22:31.822Z,1748478151.822 [Reporter](INFO): platform_buoyancy_position 887.712952 cc
2025-05-29T00:22:32.194Z,1748478152.194 [Reporter](INFO): platform_buoyancy_position 882.475220 cc
2025-05-29T00:22:32.650Z,1748478152.650 [Reporter](INFO): platform_buoyancy_position 876.968933 cc
2025-05-29T00:22:33.079Z,1748478153.079 [Reporter](INFO): platform_buoyancy_position 870.656860 cc
2025-05-29T00:22:33.375Z,1748478153.375 [Reporter](INFO): platform_buoyancy_position 866.359253 cc
2025-05-29T00:22:33.641Z,1748478153.641 [DataOverHttps](INFO): Sending 447 bytes from file Logs/20250529T001559/Express0005.lzma
2025-05-29T00:22:33.803Z,1748478153.803 [Reporter](INFO): platform_buoyancy_position 861.927368 cc
2025-05-29T00:22:34.191Z,1748478154.191 [Reporter](INFO): platform_buoyancy_position 855.615234 cc
2025-05-29T00:22:34.535Z,1748478154.535 [Reporter](INFO): platform_buoyancy_position 851.317627 cc
2025-05-29T00:22:34.642Z,1748478154.642 [DataOverHttps](INFO): Moved sent file to Logs/20250529T001559/Express0005.lzma.bak
2025-05-29T00:22:34.643Z,1748478154.643 [DataOverHttps](INFO): SBD MOMSN=25023551
2025-05-29T00:22:34.943Z,1748478154.943 [Reporter](INFO): platform_buoyancy_position 846.885742 cc
2025-05-29T00:22:35.351Z,1748478155.351 [Reporter](INFO): platform_buoyancy_position 840.573669 cc
2025-05-29T00:22:35.765Z,1748478155.765 [Reporter](INFO): platform_buoyancy_position 836.007446 cc
2025-05-29T00:22:36.098Z,1748478156.098 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-29T00:22:36.111Z,1748478156.111 [Default:CheckIn:Read_Iridium] Stopped
2025-05-29T00:22:36.111Z,1748478156.111 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-29T00:22:36.111Z,1748478156.111 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-29T00:22:36.159Z,1748478156.159 [Reporter](INFO): platform_buoyancy_position 829.829651 cc
2025-05-29T00:22:36.572Z,1748478156.572 [Reporter](INFO): platform_buoyancy_position 825.532043 cc
2025-05-29T00:22:36.980Z,1748478156.980 [Reporter](INFO): platform_buoyancy_position 819.219971 cc
2025-05-29T00:22:37.354Z,1748478157.354 [Reporter](INFO): platform_buoyancy_position 814.653748 cc
2025-05-29T00:22:37.779Z,1748478157.779 [Reporter](INFO): platform_buoyancy_position 808.341614 cc
2025-05-29T00:22:38.194Z,1748478158.194 [Reporter](INFO): platform_buoyancy_position 804.044067 cc
2025-05-29T00:22:38.603Z,1748478158.603 [Reporter](INFO): platform_buoyancy_position 797.731934 cc
2025-05-29T00:22:39.015Z,1748478159.015 [Reporter](INFO): platform_buoyancy_position 793.165710 cc
2025-05-29T00:22:39.407Z,1748478159.407 [Reporter](INFO): platform_buoyancy_position 786.853638 cc
2025-05-29T00:22:39.811Z,1748478159.811 [Reporter](INFO): platform_buoyancy_position 782.556030 cc
2025-05-29T00:22:40.232Z,1748478160.232 [Reporter](INFO): platform_buoyancy_position 776.109619 cc
2025-05-29T00:22:40.692Z,1748478160.692 [Reporter](INFO): platform_buoyancy_position 771.543457 cc
2025-05-29T00:22:41.031Z,1748478161.031 [Reporter](INFO): platform_buoyancy_position 766.574341 cc
2025-05-29T00:22:41.429Z,1748478161.429 [Reporter](INFO): platform_buoyancy_position 760.933716 cc
2025-05-29T00:22:41.818Z,1748478161.818 [Reporter](INFO): platform_buoyancy_position 756.636108 cc
2025-05-29T00:22:42.219Z,1748478162.219 [Reporter](INFO): platform_buoyancy_position 750.189758 cc
2025-05-29T00:22:42.657Z,1748478162.657 [Reporter](INFO): platform_buoyancy_position 745.892151 cc
2025-05-29T00:22:43.020Z,1748478163.020 [Reporter](INFO): platform_buoyancy_position 739.580017 cc
2025-05-29T00:22:43.427Z,1748478163.427 [Reporter](INFO): platform_buoyancy_position 735.148132 cc
2025-05-29T00:22:43.895Z,1748478163.895 [Reporter](INFO): platform_buoyancy_position 728.836060 cc
2025-05-29T00:22:44.263Z,1748478164.263 [Reporter](INFO): platform_buoyancy_position 724.135559 cc
2025-05-29T00:22:44.663Z,1748478164.663 [Reporter](INFO): platform_buoyancy_position 718.092041 cc
2025-05-29T00:22:45.042Z,1748478165.042 [Reporter](INFO): platform_buoyancy_position 713.794434 cc
2025-05-29T00:22:45.477Z,1748478165.477 [Reporter](INFO): platform_buoyancy_position 708.825317 cc
2025-05-29T00:22:45.987Z,1748478165.987 [Reporter](INFO): platform_buoyancy_position 701.035950 cc
2025-05-29T00:22:46.247Z,1748478166.247 [Reporter](INFO): platform_buoyancy_position 698.618530 cc
2025-05-29T00:22:46.723Z,1748478166.723 [Reporter](INFO): platform_buoyancy_position 692.172119 cc
2025-05-29T00:22:47.088Z,1748478167.088 [Reporter](INFO): platform_buoyancy_position 687.740234 cc
2025-05-29T00:22:47.514Z,1748478167.514 [Reporter](INFO): platform_buoyancy_position 681.562439 cc
2025-05-29T00:22:47.879Z,1748478167.879 [Reporter](INFO): platform_buoyancy_position 677.264832 cc
2025-05-29T00:22:48.280Z,1748478168.280 [Reporter](INFO): platform_buoyancy_position 670.818420 cc
2025-05-29T00:22:48.674Z,1748478168.674 [Reporter](INFO): platform_buoyancy_position 666.386536 cc
2025-05-29T00:22:49.087Z,1748478169.087 [Reporter](INFO): platform_buoyancy_position 662.088928 cc
2025-05-29T00:22:49.502Z,1748478169.502 [Reporter](INFO): platform_buoyancy_position 655.776855 cc
2025-05-29T00:22:49.881Z,1748478169.881 [Reporter](INFO): platform_buoyancy_position 651.210632 cc
2025-05-29T00:22:50.287Z,1748478170.287 [Reporter](INFO): platform_buoyancy_position 644.898499 cc
2025-05-29T00:22:50.703Z,1748478170.703 [Reporter](INFO): platform_buoyancy_position 640.197998 cc
2025-05-29T00:22:51.119Z,1748478171.119 [Reporter](INFO): platform_buoyancy_position 634.154541 cc
2025-05-29T00:22:51.516Z,1748478171.516 [Reporter](INFO): platform_buoyancy_position 629.588318 cc
2025-05-29T00:22:51.903Z,1748478171.903 [Reporter](INFO): platform_buoyancy_position 623.544800 cc
2025-05-29T00:22:52.318Z,1748478172.318 [Reporter](INFO): platform_buoyancy_position 618.844299 cc
2025-05-29T00:22:52.731Z,1748478172.731 [Reporter](INFO): platform_buoyancy_position 612.800842 cc
2025-05-29T00:22:53.147Z,1748478173.147 [Reporter](INFO): platform_buoyancy_position 608.368896 cc
2025-05-29T00:22:53.523Z,1748478173.523 [Reporter](INFO): platform_buoyancy_position 602.056824 cc
2025-05-29T00:22:53.939Z,1748478173.939 [Reporter](INFO): platform_buoyancy_position 597.759216 cc
2025-05-29T00:22:54.347Z,1748478174.347 [Reporter](INFO): platform_buoyancy_position 591.850037 cc
2025-05-29T00:22:54.767Z,1748478174.767 [Reporter](INFO): platform_buoyancy_position 586.880920 cc
2025-05-29T00:22:55.147Z,1748478175.147 [Reporter](INFO): platform_buoyancy_position 580.568848 cc
2025-05-29T00:22:55.551Z,1748478175.551 [Reporter](INFO): platform_buoyancy_position 576.136902 cc
2025-05-29T00:22:55.964Z,1748478175.964 [Reporter](INFO): platform_buoyancy_position 570.362000 cc
2025-05-29T00:22:56.351Z,1748478176.351 [Reporter](INFO): platform_buoyancy_position 565.527222 cc
2025-05-29T00:22:56.796Z,1748478176.796 [Reporter](INFO): platform_buoyancy_position 559.080811 cc
2025-05-29T00:22:57.178Z,1748478177.178 [Reporter](INFO): platform_buoyancy_position 554.648926 cc
2025-05-29T00:22:57.585Z,1748478177.585 [Reporter](INFO): platform_buoyancy_position 550.217041 cc
2025-05-29T00:22:58.047Z,1748478178.047 [Reporter](INFO): platform_buoyancy_position 543.904907 cc
2025-05-29T00:22:58.384Z,1748478178.384 [Reporter](INFO): platform_buoyancy_position 538.935791 cc
2025-05-29T00:22:58.847Z,1748478178.847 [Reporter](INFO): platform_buoyancy_position 532.892334 cc
2025-05-29T00:22:59.215Z,1748478179.215 [Reporter](INFO): platform_buoyancy_position 528.057495 cc
2025-05-29T00:22:59.595Z,1748478179.595 [Reporter](INFO): platform_buoyancy_position 522.551208 cc
2025-05-29T00:23:00.047Z,1748478180.047 [Reporter](INFO): platform_buoyancy_position 516.104797 cc
2025-05-29T00:23:00.415Z,1748478180.415 [Reporter](INFO): platform_buoyancy_position 511.807220 cc
2025-05-29T00:23:00.839Z,1748478180.839 [Reporter](INFO): platform_buoyancy_position 505.495178 cc
2025-05-29T00:23:01.211Z,1748478181.211 [Reporter](INFO): platform_buoyancy_position 501.063232 cc
2025-05-29T00:23:01.611Z,1748478181.611 [Reporter](INFO): platform_buoyancy_position 496.631378 cc
2025-05-29T00:23:02.016Z,1748478182.016 [Reporter](INFO): platform_buoyancy_position 490.453491 cc
2025-05-29T00:23:02.399Z,1748478182.399 [Reporter](INFO): platform_buoyancy_position 485.887360 cc
2025-05-29T00:23:02.840Z,1748478182.840 [Reporter](INFO): platform_buoyancy_position 479.709564 cc
2025-05-29T00:23:03.231Z,1748478183.231 [Reporter](INFO): platform_buoyancy_position 475.143341 cc
2025-05-29T00:23:03.640Z,1748478183.640 [Reporter](INFO): platform_buoyancy_position 470.442841 cc
2025-05-29T00:23:04.023Z,1748478184.023 [Reporter](INFO): platform_buoyancy_position 464.399323 cc
2025-05-29T00:23:04.431Z,1748478184.431 [Reporter](INFO): platform_buoyancy_position 460.101715 cc
2025-05-29T00:23:04.851Z,1748478184.851 [Reporter](INFO): platform_buoyancy_position 454.192535 cc
2025-05-29T00:23:05.259Z,1748478185.259 [Reporter](INFO): platform_buoyancy_position 449.492035 cc
2025-05-29T00:23:05.635Z,1748478185.635 [Reporter](INFO): platform_buoyancy_position 445.060150 cc
2025-05-29T00:23:06.044Z,1748478186.044 [Reporter](INFO): platform_buoyancy_position 438.613739 cc
2025-05-29T00:23:06.459Z,1748478186.459 [Reporter](INFO): platform_buoyancy_position 432.301666 cc
2025-05-29T00:23:06.871Z,1748478186.871 [Reporter](INFO): platform_buoyancy_position 427.869720 cc
2025-05-29T00:23:07.255Z,1748478187.255 [Reporter](INFO): platform_buoyancy_position 423.572113 cc
2025-05-29T00:23:07.671Z,1748478187.671 [Reporter](INFO): platform_buoyancy_position 417.125763 cc
2025-05-29T00:23:08.082Z,1748478188.082 [Reporter](INFO): platform_buoyancy_position 412.693848 cc
2025-05-29T00:23:08.455Z,1748478188.455 [Reporter](INFO): platform_buoyancy_position 406.381744 cc
2025-05-29T00:23:08.923Z,1748478188.923 [Reporter](INFO): platform_buoyancy_position 400.069641 cc
2025-05-29T00:23:09.316Z,1748478189.316 [Reporter](INFO): platform_buoyancy_position 395.637726 cc
2025-05-29T00:23:09.704Z,1748478189.704 [Reporter](INFO): platform_buoyancy_position 389.728546 cc
2025-05-29T00:23:10.123Z,1748478190.123 [Reporter](INFO): platform_buoyancy_position 384.893738 cc
2025-05-29T00:23:10.499Z,1748478190.499 [Reporter](INFO): platform_buoyancy_position 380.596130 cc
2025-05-29T00:23:10.935Z,1748478190.935 [Reporter](INFO): platform_buoyancy_position 374.149750 cc
2025-05-29T00:23:11.431Z,1748478191.431 [Reporter](INFO): platform_buoyancy_position 367.703339 cc
2025-05-29T00:23:11.755Z,1748478191.755 [Reporter](INFO): platform_buoyancy_position 363.405731 cc
2025-05-29T00:23:12.158Z,1748478192.158 [Reporter](INFO): platform_buoyancy_position 359.108124 cc
2025-05-29T00:23:12.559Z,1748478192.559 [Reporter](INFO): platform_buoyancy_position 352.796051 cc
2025-05-29T00:23:12.942Z,1748478192.942 [Reporter](INFO): platform_buoyancy_position 348.364136 cc
2025-05-29T00:23:13.331Z,1748478193.331 [Reporter](INFO): platform_buoyancy_position 342.052032 cc
2025-05-29T00:23:13.783Z,1748478193.783 [Reporter](INFO): platform_buoyancy_position 337.620148 cc
2025-05-29T00:23:14.151Z,1748478194.151 [Reporter](INFO): platform_buoyancy_position 331.173737 cc
2025-05-29T00:23:14.567Z,1748478194.567 [Reporter](INFO): platform_buoyancy_position 326.876129 cc
2025-05-29T00:23:14.979Z,1748478194.979 [Reporter](INFO): platform_buoyancy_position 320.698334 cc
2025-05-29T00:23:15.354Z,1748478195.354 [Reporter](INFO): platform_buoyancy_position 316.400726 cc
2025-05-29T00:23:15.760Z,1748478195.760 [Reporter](INFO): platform_buoyancy_position 311.968842 cc
2025-05-29T00:23:16.143Z,1748478196.143 [Reporter](INFO): platform_buoyancy_position 305.522430 cc
2025-05-29T00:23:16.555Z,1748478196.555 [Reporter](INFO): platform_buoyancy_position 300.956238 cc
2025-05-29T00:23:16.971Z,1748478196.971 [Reporter](INFO): platform_buoyancy_position 294.912720 cc
2025-05-29T00:23:17.368Z,1748478197.368 [Reporter](INFO): platform_buoyancy_position 290.346527 cc
2025-05-29T00:23:17.747Z,1748478197.747 [Reporter](INFO): platform_buoyancy_position 285.377441 cc
2025-05-29T00:23:18.171Z,1748478198.171 [Reporter](INFO): platform_buoyancy_position 279.602539 cc
2025-05-29T00:23:18.591Z,1748478198.591 [Reporter](INFO): platform_buoyancy_position 273.424744 cc
2025-05-29T00:23:18.987Z,1748478198.987 [Reporter](INFO): platform_buoyancy_position 268.858521 cc
2025-05-29T00:23:19.383Z,1748478199.383 [Reporter](INFO): platform_buoyancy_position 263.217926 cc
2025-05-29T00:23:19.798Z,1748478199.798 [Reporter](INFO): platform_buoyancy_position 258.383118 cc
2025-05-29T00:23:20.196Z,1748478200.196 [Reporter](INFO): platform_buoyancy_position 251.802444 cc
2025-05-29T00:23:20.583Z,1748478200.583 [Reporter](INFO): platform_buoyancy_position 247.639145 cc
2025-05-29T00:23:21.007Z,1748478201.007 [Reporter](INFO): platform_buoyancy_position 243.072937 cc
2025-05-29T00:23:21.403Z,1748478201.403 [Reporter](INFO): platform_buoyancy_position 236.760834 cc
2025-05-29T00:23:21.800Z,1748478201.800 [Reporter](INFO): platform_buoyancy_position 232.328918 cc
2025-05-29T00:23:22.207Z,1748478202.207 [Reporter](INFO): platform_buoyancy_position 226.016815 cc
2025-05-29T00:23:22.679Z,1748478202.679 [Reporter](INFO): platform_buoyancy_position 219.704742 cc
2025-05-29T00:23:23.035Z,1748478203.035 [Reporter](INFO): platform_buoyancy_position 215.138519 cc
2025-05-29T00:23:23.523Z,1748478203.523 [Reporter](INFO): platform_buoyancy_position 210.975220 cc
2025-05-29T00:23:23.927Z,1748478203.927 [Reporter](INFO): platform_buoyancy_position 206.677612 cc
2025-05-29T00:23:51.246Z,1748478231.246 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-05-29T00:23:51.247Z,1748478231.247 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-05-29T00:23:51.250Z,1748478231.250 [BPC1](INFO): Received data from all battery sticks.
2025-05-29T00:27:10.785Z,1748478430.785 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:27:36.713Z,1748478456.713 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-29T00:27:36.713Z,1748478456.713 [Default:CheckIn:C.Wait] Stopped
2025-05-29T00:27:36.713Z,1748478456.713 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-29T00:27:36.713Z,1748478456.713 [Default:CheckIn:D] Running Loop=1
2025-05-29T00:27:37.116Z,1748478457.116 [Default:CheckIn:D] Stopped
2025-05-29T00:27:37.125Z,1748478457.125 [Default:CheckIn:E] Running Loop=1
2025-05-29T00:27:37.519Z,1748478457.519 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.807790 min
2025-05-29T00:27:37.519Z,1748478457.519 [Default:CheckIn:E] Stopped
2025-05-29T00:27:37.520Z,1748478457.520 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-29T00:27:37.520Z,1748478457.520 [Default:CheckIn] Stopped
2025-05-29T00:27:37.520Z,1748478457.520 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-29T00:27:37.520Z,1748478457.520 [Default:CheckIn](INFO): Running loop #2
2025-05-29T00:27:37.520Z,1748478457.520 [Default:CheckIn] Running Loop=2
2025-05-29T00:27:37.520Z,1748478457.520 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-29T00:27:37.520Z,1748478457.520 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-29T00:27:39.504Z,1748478459.504 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002740.00,A,3648.17250,N,12147.28917,W,0.583,239.93,290525,,,A*74
2025-05-29T00:27:39.507Z,1748478459.507 [NAL9602](INFO): GPS fix at 20250529T002740: (36.802875, -121.788153)
2025-05-29T00:27:39.560Z,1748478459.560 [Default:CheckIn:Read_GPS] Stopped
2025-05-29T00:27:39.576Z,1748478459.576 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-29T00:27:42.105Z,1748478462.105 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20250529T001559/Courier0007.lzma
2025-05-29T00:27:43.107Z,1748478463.107 [DataOverHttps](INFO): Moved sent file to Logs/20250529T001559/Courier0007.lzma.bak
2025-05-29T00:27:43.107Z,1748478463.107 [DataOverHttps](INFO): SBD MOMSN=25023598
2025-05-29T00:28:22.741Z,1748478502.741 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:28:53.866Z,1748478533.866 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:29:12.429Z,1748478552.429 [DataOverHttps](INFO): Sending 408 bytes from file Logs/20250529T001559/Express0008.lzma
2025-05-29T00:29:13.431Z,1748478553.431 [DataOverHttps](INFO): Moved sent file to Logs/20250529T001559/Express0008.lzma.bak
2025-05-29T00:29:13.431Z,1748478553.431 [DataOverHttps](INFO): SBD MOMSN=25023601
2025-05-29T00:29:14.961Z,1748478554.961 [Default:CheckIn:Read_Iridium] Stopped
2025-05-29T00:29:14.961Z,1748478554.961 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-29T00:29:14.961Z,1748478554.961 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-29T00:30:08.214Z,1748478608.214 [CommandLine](INFO): End of History
2025-05-29T00:30:08.218Z,1748478608.218 [CommandLine](INFO): End of History
2025-05-29T00:30:08.222Z,1748478608.222 [CommandLine](INFO): End of History
2025-05-29T00:30:08.226Z,1748478608.226 [CommandLine](INFO): End of History
2025-05-29T00:30:08.231Z,1748478608.231 [CommandLine](INFO): End of History
2025-05-29T00:30:08.234Z,1748478608.234 [CommandLine](INFO): End of History
2025-05-29T00:30:08.238Z,1748478608.238 [CommandLine](INFO): End of History
2025-05-29T00:30:08.242Z,1748478608.242 [CommandLine](INFO): End of History
2025-05-29T00:30:08.246Z,1748478608.246 [CommandLine](INFO): End of History
2025-05-29T00:30:08.250Z,1748478608.250 [CommandLine](INFO): End of History
2025-05-29T00:30:08.254Z,1748478608.254 [CommandLine](INFO): End of History
2025-05-29T00:30:08.259Z,1748478608.259 [CommandLine](INFO): End of History
2025-05-29T00:30:08.318Z,1748478608.318 [CommandLine](INFO): End of History
2025-05-29T00:30:08.319Z,1748478608.319 [CommandLine](INFO): End of History
2025-05-29T00:30:08.320Z,1748478608.320 [CommandLine](INFO): End of History
2025-05-29T00:30:08.320Z,1748478608.320 [CommandLine](INFO): End of History
2025-05-29T00:30:08.325Z,1748478608.325 [CommandLine](INFO): End of History
2025-05-29T00:30:08.326Z,1748478608.326 [CommandLine](INFO): End of History
2025-05-29T00:30:08.327Z,1748478608.327 [CommandLine](INFO): End of History
2025-05-29T00:30:08.327Z,1748478608.327 [CommandLine](INFO): End of History
2025-05-29T00:30:08.328Z,1748478608.328 [CommandLine](INFO): End of History
2025-05-29T00:30:08.338Z,1748478608.338 [CommandLine](INFO): End of History
2025-05-29T00:30:08.339Z,1748478608.339 [CommandLine](INFO): End of History
2025-05-29T00:30:08.339Z,1748478608.339 [CommandLine](INFO): End of History
2025-05-29T00:30:08.340Z,1748478608.340 [CommandLine](INFO): End of History
2025-05-29T00:30:08.362Z,1748478608.362 [CommandLine](INFO): End of History
2025-05-29T00:30:08.362Z,1748478608.362 [CommandLine](INFO): End of History
2025-05-29T00:30:08.363Z,1748478608.363 [CommandLine](INFO): End of History
2025-05-29T00:30:08.364Z,1748478608.364 [CommandLine](INFO): End of History
2025-05-29T00:30:08.377Z,1748478608.377 [CommandLine](INFO): End of History
2025-05-29T00:30:08.378Z,1748478608.378 [CommandLine](INFO): End of History
2025-05-29T00:30:08.378Z,1748478608.378 [CommandLine](INFO): End of History
2025-05-29T00:30:08.379Z,1748478608.379 [CommandLine](INFO): End of History
2025-05-29T00:30:08.380Z,1748478608.380 [CommandLine](INFO): End of History
2025-05-29T00:30:08.397Z,1748478608.397 [CommandLine](INFO): End of History
2025-05-29T00:30:08.402Z,1748478608.402 [CommandLine](INFO): End of History
2025-05-29T00:30:08.403Z,1748478608.403 [CommandLine](INFO): End of History
2025-05-29T00:30:08.403Z,1748478608.403 [CommandLine](INFO): End of History
2025-05-29T00:30:08.404Z,1748478608.404 [CommandLine](INFO): End of History
2025-05-29T00:30:08.413Z,1748478608.413 [CommandLine](INFO): End of History
2025-05-29T00:30:08.414Z,1748478608.414 [CommandLine](INFO): End of History
2025-05-29T00:30:08.415Z,1748478608.415 [CommandLine](INFO): End of History
2025-05-29T00:30:08.415Z,1748478608.415 [CommandLine](INFO): End of History
2025-05-29T00:31:48.913Z,1748478708.913 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:32:41.053Z,1748478761.053 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-05-29T00:33:11.754Z,1748478791.754 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-29T00:33:22.493Z,1748478802.493 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:33:53.617Z,1748478833.617 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:34:15.583Z,1748478855.583 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-29T00:34:15.583Z,1748478855.583 [Default:CheckIn:C.Wait] Stopped
2025-05-29T00:34:15.583Z,1748478855.583 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-29T00:34:15.583Z,1748478855.583 [Default:CheckIn:D] Running Loop=1
2025-05-29T00:34:15.987Z,1748478855.987 [Default:CheckIn:D] Stopped
2025-05-29T00:34:15.987Z,1748478855.987 [Default:CheckIn:E] Running Loop=1
2025-05-29T00:34:16.402Z,1748478856.402 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.455627 min
2025-05-29T00:34:16.402Z,1748478856.402 [Default:CheckIn:E] Stopped
2025-05-29T00:34:16.402Z,1748478856.402 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-29T00:34:16.402Z,1748478856.402 [Default:CheckIn] Stopped
2025-05-29T00:34:16.402Z,1748478856.402 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-29T00:34:16.403Z,1748478856.403 [Default:CheckIn](INFO): Running loop #3
2025-05-29T00:34:16.403Z,1748478856.403 [Default:CheckIn] Running Loop=3
2025-05-29T00:34:16.403Z,1748478856.403 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-29T00:34:16.403Z,1748478856.403 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-29T00:34:24.741Z,1748478864.741 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:34:55.865Z,1748478895.865 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:35:00.888Z,1748478900.888 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.341751
2025-05-29T00:35:45.701Z,1748478945.701 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002956
2025-05-29T00:38:00.641Z,1748479080.641 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:38:31.769Z,1748479111.769 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:39:16.588Z,1748479156.588 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-05-29T00:34:16.4Z
2025-05-29T00:39:16.588Z,1748479156.588 [Default:CheckIn:Read_GPS] Stopped
2025-05-29T00:39:16.588Z,1748479156.588 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-29T00:39:20.226Z,1748479160.226 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-05-29T00:39:34.017Z,1748479174.017 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:39:47.069Z,1748479187.069 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.374908
2025-05-29T00:40:18.206Z,1748479218.206 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:40:49.329Z,1748479249.329 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:41:19.241Z,1748479279.241 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002960
2025-05-29T00:41:27.836Z,1748479287.836 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250529T001559/Courier0010.lzma
2025-05-29T00:41:28.838Z,1748479288.838 [DataOverHttps](INFO): Moved sent file to Logs/20250529T001559/Courier0010.lzma.bak
2025-05-29T00:41:28.839Z,1748479288.839 [DataOverHttps](INFO): SBD MOMSN=25023675
2025-05-29T00:41:39.960Z,1748479299.960 [CommandExec](IMPORTANT): got command maintain control VerticalControl.massPositionAction 28 millimeter
2025-05-29T00:41:47.797Z,1748479307.797 [DataOverHttps](INFO): Sending 398 bytes from file Logs/20250529T001559/Express0011.lzma
2025-05-29T00:41:48.062Z,1748479308.062 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2025-05-29T00:41:48.063Z,1748479308.063 [CommandExec](IMPORTANT): platform_buoyancy_position 206.677612 cc
2025-05-29T00:41:48.798Z,1748479308.798 [DataOverHttps](INFO): Moved sent file to Logs/20250529T001559/Express0011.lzma.bak
2025-05-29T00:41:48.799Z,1748479308.799 [DataOverHttps](INFO): SBD MOMSN=25023686
2025-05-29T00:41:50.142Z,1748479310.142 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-29T00:41:50.155Z,1748479310.155 [Default:CheckIn:Read_Iridium] Stopped
2025-05-29T00:41:50.155Z,1748479310.155 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-29T00:41:50.155Z,1748479310.155 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-29T00:43:21.289Z,1748479401.289 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:43:52.413Z,1748479432.413 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:44:23.537Z,1748479463.537 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:44:54.661Z,1748479494.661 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:46:50.783Z,1748479610.783 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-29T00:46:50.783Z,1748479610.783 [Default:CheckIn:C.Wait] Stopped
2025-05-29T00:46:50.783Z,1748479610.783 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-29T00:46:50.784Z,1748479610.784 [Default:CheckIn:D] Running Loop=1
2025-05-29T00:46:51.186Z,1748479611.186 [Default:CheckIn:D] Stopped
2025-05-29T00:46:51.186Z,1748479611.186 [Default:CheckIn:E] Running Loop=1
2025-05-29T00:46:51.593Z,1748479611.593 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.042277 min
2025-05-29T00:46:51.594Z,1748479611.594 [Default:CheckIn:E] Stopped
2025-05-29T00:46:51.594Z,1748479611.594 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-29T00:46:51.594Z,1748479611.594 [Default:CheckIn] Stopped
2025-05-29T00:46:51.594Z,1748479611.594 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-29T00:46:51.594Z,1748479611.594 [Default:CheckIn](INFO): Running loop #4
2025-05-29T00:46:51.594Z,1748479611.594 [Default:CheckIn] Running Loop=4
2025-05-29T00:46:51.594Z,1748479611.594 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-29T00:46:51.594Z,1748479611.594 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-29T00:46:53.182Z,1748479613.182 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-05-29T00:46:53.182Z,1748479613.182 [NAL9602] Data Fault, FailCount= 1
2025-05-29T00:46:53.182Z,1748479613.182 [NAL9602](ERROR): Data Fault
2025-05-29T00:46:53.239Z,1748479613.239 [CBIT](ERROR): Data Fault in component: NAL9602
2025-05-29T00:46:53.585Z,1748479613.585 [NAL9602](INFO): Powering down
2025-05-29T00:46:54.421Z,1748479614.421 [CBIT](INFO): Clearing failed state for component NAL9602
2025-05-29T00:46:54.421Z,1748479614.421 [NAL9602] No Fault, FailCount= 1
2025-05-29T00:47:23.891Z,1748479643.891 [NAL9602](INFO): Powering up NAL9602
2025-05-29T00:47:34.802Z,1748479654.802 [NAL9602](INFO): NAL9602 initialized
2025-05-29T00:47:59.213Z,1748479679.213 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:49:01.269Z,1748479741.269 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-29T00:49:20.822Z,1748479760.822 [CommandExec](IMPORTANT): got command maintain clear
2025-05-29T00:49:21.110Z,1748479761.110 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-05-29T00:49:23.424Z,1748479763.424 [Reporter](INFO): platform_buoyancy_position 198.351013 cc
2025-05-29T00:49:23.603Z,1748479763.603 [Reporter](INFO): platform_buoyancy_position 200.096924 cc
2025-05-29T00:49:24.003Z,1748479764.003 [Reporter](INFO): platform_buoyancy_position 200.096924 cc
2025-05-29T00:49:24.391Z,1748479764.391 [Reporter](INFO): platform_buoyancy_position 200.499817 cc
2025-05-29T00:49:24.838Z,1748479764.838 [Reporter](INFO): platform_buoyancy_position 206.543335 cc
2025-05-29T00:49:25.219Z,1748479765.219 [Reporter](INFO): platform_buoyancy_position 210.840912 cc
2025-05-29T00:49:25.634Z,1748479765.634 [Reporter](INFO): platform_buoyancy_position 215.138519 cc
2025-05-29T00:49:26.028Z,1748479766.028 [Reporter](INFO): platform_buoyancy_position 221.584930 cc
2025-05-29T00:49:26.474Z,1748479766.474 [Reporter](INFO): platform_buoyancy_position 227.091217 cc
2025-05-29T00:49:26.826Z,1748479766.826 [Reporter](INFO): platform_buoyancy_position 232.328918 cc
2025-05-29T00:49:27.216Z,1748479767.216 [Reporter](INFO): platform_buoyancy_position 237.029419 cc
2025-05-29T00:49:27.626Z,1748479767.626 [Reporter](INFO): platform_buoyancy_position 243.072937 cc
2025-05-29T00:49:28.030Z,1748479768.030 [Reporter](INFO): platform_buoyancy_position 247.370544 cc
2025-05-29T00:49:28.377Z,1748479768.377 [DVL_micro](ERROR): Failed to parse:
:WI,+00065,+00028,+00012,+00000,A
2025-05-29T00:49:28.474Z,1748479768.474 [Reporter](INFO): platform_buoyancy_position 253.816925 cc
2025-05-29T00:49:28.838Z,1748479768.838 [Reporter](INFO): platform_buoyancy_position 258.248840 cc
2025-05-29T00:49:29.251Z,1748479769.251 [Reporter](INFO): platform_buoyancy_position 262.815033 cc
2025-05-29T00:49:29.715Z,1748479769.715 [Reporter](INFO): platform_buoyancy_position 269.798645 cc
2025-05-29T00:49:30.094Z,1748479770.094 [Reporter](INFO): platform_buoyancy_position 275.439240 cc
2025-05-29T00:49:30.403Z,1748479770.403 [DVL_micro](ERROR): Failed to parse:
:BI,-00025,-00026,-00018,+00000,A
2025-05-29T00:49:30.586Z,1748479770.586 [Reporter](INFO): platform_buoyancy_position 281.751343 cc
2025-05-29T00:49:30.894Z,1748479770.894 [Reporter](INFO): platform_buoyancy_position 285.511719 cc
2025-05-29T00:49:31.286Z,1748479771.286 [Reporter](INFO): platform_buoyancy_position 290.480835 cc
2025-05-29T00:49:31.658Z,1748479771.658 [Reporter](INFO): platform_buoyancy_position 294.778442 cc
2025-05-29T00:49:32.099Z,1748479772.099 [Reporter](INFO): platform_buoyancy_position 301.224823 cc
2025-05-29T00:49:32.411Z,1748479772.411 [DVL_micro](ERROR): Failed to parse:
:RD,+0010.93,+0010.40,+9999.99,+0011.25
2025-05-29T00:49:32.523Z,1748479772.523 [Reporter](INFO): platform_buoyancy_position 306.462524 cc
2025-05-29T00:49:32.895Z,1748479772.895 [Reporter](INFO): platform_buoyancy_position 311.834534 cc
2025-05-29T00:49:33.294Z,1748479773.294 [Reporter](INFO): platform_buoyancy_position 316.132141 cc
2025-05-29T00:49:33.694Z,1748479773.694 [Reporter](INFO): platform_buoyancy_position 322.578522 cc
2025-05-29T00:49:34.083Z,1748479774.083 [Reporter](INFO): platform_buoyancy_position 326.876129 cc
2025-05-29T00:49:34.421Z,1748479774.421 [DVL_micro](ERROR): Failed to parse:
:RD,+0010.65,+0010.43,+9999.99,+0011.07
2025-05-29T00:49:34.634Z,1748479774.634 [Reporter](INFO): platform_buoyancy_position 333.456848 cc
2025-05-29T00:49:34.827Z,1748479774.827 [DVL_micro](ERROR): No DVL communication! Re-initializing
2025-05-29T00:49:34.827Z,1748479774.827 [DVL_micro] Communications Fault, FailCount= 1
2025-05-29T00:49:34.827Z,1748479774.827 [DVL_micro](ERROR): Communications Fault
2025-05-29T00:49:34.934Z,1748479774.934 [CBIT](ERROR): Communications Fault in component: DVL_micro
2025-05-29T00:49:34.935Z,1748479774.935 [Reporter](INFO): platform_buoyancy_position 338.694550 cc
2025-05-29T00:49:35.309Z,1748479775.309 [DVL_micro](INFO): Powering down
2025-05-29T00:49:35.407Z,1748479775.407 [Reporter](INFO): platform_buoyancy_position 344.066528 cc
2025-05-29T00:49:35.711Z,1748479775.711 [Reporter](INFO): platform_buoyancy_position 348.498444 cc
2025-05-29T00:49:36.093Z,1748479776.093 [CBIT](INFO): Clearing failed state for component DVL_micro
2025-05-29T00:49:36.093Z,1748479776.093 [DVL_micro] No Fault, FailCount= 1
2025-05-29T00:49:36.095Z,1748479776.095 [Reporter](INFO): platform_buoyancy_position 354.676239 cc
2025-05-29T00:49:36.515Z,1748479776.515 [Reporter](INFO): platform_buoyancy_position 359.376740 cc
2025-05-29T00:49:36.911Z,1748479776.911 [Reporter](INFO): platform_buoyancy_position 365.554535 cc
2025-05-29T00:49:37.296Z,1748479777.296 [Reporter](INFO): platform_buoyancy_position 369.852142 cc
2025-05-29T00:49:37.715Z,1748479777.715 [Reporter](INFO): platform_buoyancy_position 374.284027 cc
2025-05-29T00:49:38.111Z,1748479778.111 [Reporter](INFO): platform_buoyancy_position 380.596130 cc
2025-05-29T00:49:38.499Z,1748479778.499 [Reporter](INFO): platform_buoyancy_position 385.162354 cc
2025-05-29T00:49:38.931Z,1748479778.931 [Reporter](INFO): platform_buoyancy_position 391.340149 cc
2025-05-29T00:49:39.327Z,1748479779.327 [Reporter](INFO): platform_buoyancy_position 395.637726 cc
2025-05-29T00:49:39.748Z,1748479779.748 [Reporter](INFO): platform_buoyancy_position 402.218445 cc
2025-05-29T00:49:40.135Z,1748479780.135 [Reporter](INFO): platform_buoyancy_position 406.650330 cc
2025-05-29T00:49:40.546Z,1748479780.546 [Reporter](INFO): platform_buoyancy_position 412.828156 cc
2025-05-29T00:49:40.955Z,1748479780.955 [Reporter](INFO): platform_buoyancy_position 417.260040 cc
2025-05-29T00:49:41.323Z,1748479781.323 [Reporter](INFO): platform_buoyancy_position 422.363434 cc
2025-05-29T00:49:41.751Z,1748479781.751 [Reporter](INFO): platform_buoyancy_position 427.869720 cc
2025-05-29T00:49:42.158Z,1748479782.158 [Reporter](INFO): platform_buoyancy_position 434.181854 cc
2025-05-29T00:49:42.543Z,1748479782.543 [Reporter](INFO): platform_buoyancy_position 438.613739 cc
2025-05-29T00:49:42.949Z,1748479782.949 [Reporter](INFO): platform_buoyancy_position 444.120026 cc
2025-05-29T00:49:43.363Z,1748479783.363 [Reporter](INFO): platform_buoyancy_position 449.357758 cc
2025-05-29T00:49:43.771Z,1748479783.771 [Reporter](INFO): platform_buoyancy_position 453.923920 cc
2025-05-29T00:49:44.175Z,1748479784.175 [Reporter](INFO): platform_buoyancy_position 460.101715 cc
2025-05-29T00:49:44.570Z,1748479784.570 [Reporter](INFO): platform_buoyancy_position 464.399323 cc
2025-05-29T00:49:44.987Z,1748479784.987 [Reporter](INFO): platform_buoyancy_position 470.980072 cc
2025-05-29T00:49:45.402Z,1748479785.402 [Reporter](INFO): platform_buoyancy_position 476.620636 cc
2025-05-29T00:49:45.811Z,1748479785.811 [Reporter](INFO): platform_buoyancy_position 481.589752 cc
2025-05-29T00:49:46.215Z,1748479786.215 [Reporter](INFO): platform_buoyancy_position 486.021637 cc
2025-05-29T00:49:46.601Z,1748479786.601 [Reporter](INFO): platform_buoyancy_position 492.333771 cc
2025-05-29T00:49:47.000Z,1748479787.000 [Reporter](INFO): platform_buoyancy_position 496.899933 cc
2025-05-29T00:49:47.391Z,1748479787.391 [Reporter](INFO): platform_buoyancy_position 502.809174 cc
2025-05-29T00:49:47.806Z,1748479787.806 [Reporter](INFO): platform_buoyancy_position 507.509674 cc
2025-05-29T00:49:48.215Z,1748479788.215 [Reporter](INFO): platform_buoyancy_position 513.687439 cc
2025-05-29T00:49:48.611Z,1748479788.611 [Reporter](INFO): platform_buoyancy_position 518.253601 cc
2025-05-29T00:49:49.002Z,1748479789.002 [Reporter](INFO): platform_buoyancy_position 524.565735 cc
2025-05-29T00:49:49.416Z,1748479789.416 [Reporter](INFO): platform_buoyancy_position 528.863342 cc
2025-05-29T00:49:49.811Z,1748479789.811 [Reporter](INFO): platform_buoyancy_position 533.429504 cc
2025-05-29T00:49:50.251Z,1748479790.251 [Reporter](INFO): platform_buoyancy_position 539.741638 cc
2025-05-29T00:49:50.651Z,1748479790.651 [Reporter](INFO): platform_buoyancy_position 544.710693 cc
2025-05-29T00:49:51.039Z,1748479791.039 [Reporter](INFO): platform_buoyancy_position 550.351318 cc
2025-05-29T00:49:51.497Z,1748479791.497 [Reporter](INFO): platform_buoyancy_position 556.797729 cc
2025-05-29T00:49:51.853Z,1748479791.853 [Reporter](INFO): platform_buoyancy_position 561.095337 cc
2025-05-29T00:49:52.255Z,1748479792.255 [Reporter](INFO): platform_buoyancy_position 565.661499 cc
2025-05-29T00:49:52.643Z,1748479792.643 [Reporter](INFO): platform_buoyancy_position 571.839294 cc
2025-05-29T00:49:53.055Z,1748479793.055 [Reporter](INFO): platform_buoyancy_position 576.136902 cc
2025-05-29T00:49:53.468Z,1748479793.468 [Reporter](INFO): platform_buoyancy_position 582.046143 cc
2025-05-29T00:49:53.847Z,1748479793.847 [Reporter](INFO): platform_buoyancy_position 586.880920 cc
2025-05-29T00:49:54.263Z,1748479794.263 [Reporter](INFO): platform_buoyancy_position 591.447144 cc
2025-05-29T00:49:54.671Z,1748479794.671 [Reporter](INFO): platform_buoyancy_position 597.759216 cc
2025-05-29T00:49:55.080Z,1748479795.080 [Reporter](INFO): platform_buoyancy_position 602.191101 cc
2025-05-29T00:49:55.514Z,1748479795.514 [Reporter](INFO): platform_buoyancy_position 608.368896 cc
2025-05-29T00:49:55.891Z,1748479795.891 [Reporter](INFO): platform_buoyancy_position 613.069397 cc
2025-05-29T00:49:56.307Z,1748479796.307 [Reporter](INFO): platform_buoyancy_position 619.247253 cc
2025-05-29T00:49:56.712Z,1748479796.712 [Reporter](INFO): platform_buoyancy_position 623.679138 cc
2025-05-29T00:49:57.087Z,1748479797.087 [Reporter](INFO): platform_buoyancy_position 629.856934 cc
2025-05-29T00:49:57.499Z,1748479797.499 [Reporter](INFO): platform_buoyancy_position 634.154541 cc
2025-05-29T00:49:57.891Z,1748479797.891 [Reporter](INFO): platform_buoyancy_position 640.600952 cc
2025-05-29T00:49:58.327Z,1748479798.327 [Reporter](INFO): platform_buoyancy_position 645.032837 cc
2025-05-29T00:49:58.691Z,1748479798.691 [Reporter](INFO): platform_buoyancy_position 650.807739 cc
2025-05-29T00:49:59.118Z,1748479799.118 [Reporter](INFO): platform_buoyancy_position 655.776855 cc
2025-05-29T00:49:59.527Z,1748479799.527 [Reporter](INFO): platform_buoyancy_position 660.208740 cc
2025-05-29T00:49:59.929Z,1748479799.929 [Reporter](INFO): platform_buoyancy_position 666.520813 cc
2025-05-29T00:50:00.370Z,1748479800.370 [Reporter](INFO): platform_buoyancy_position 671.624207 cc
2025-05-29T00:50:00.743Z,1748479800.743 [Reporter](INFO): platform_buoyancy_position 677.130554 cc
2025-05-29T00:50:01.146Z,1748479801.146 [Reporter](INFO): platform_buoyancy_position 681.831055 cc
2025-05-29T00:50:01.539Z,1748479801.539 [Reporter](INFO): platform_buoyancy_position 687.874512 cc
2025-05-29T00:50:01.942Z,1748479801.942 [Reporter](INFO): platform_buoyancy_position 692.306458 cc
2025-05-29T00:50:02.343Z,1748479802.343 [Reporter](INFO): platform_buoyancy_position 698.618530 cc
2025-05-29T00:50:02.755Z,1748479802.755 [Reporter](INFO): platform_buoyancy_position 703.050415 cc
2025-05-29T00:50:03.165Z,1748479803.165 [Reporter](INFO): platform_buoyancy_position 709.362549 cc
2025-05-29T00:50:03.592Z,1748479803.592 [Reporter](INFO): platform_buoyancy_position 713.660156 cc
2025-05-29T00:50:04.015Z,1748479804.015 [Reporter](INFO): platform_buoyancy_position 720.106506 cc
2025-05-29T00:50:04.380Z,1748479804.380 [Reporter](INFO): platform_buoyancy_position 724.538452 cc
2025-05-29T00:50:04.770Z,1748479804.770 [Reporter](INFO): platform_buoyancy_position 729.776123 cc
2025-05-29T00:50:05.171Z,1748479805.171 [Reporter](INFO): platform_buoyancy_position 735.282410 cc
2025-05-29T00:50:05.619Z,1748479805.619 [Reporter](INFO): platform_buoyancy_position 741.594543 cc
2025-05-29T00:50:06.051Z,1748479806.051 [Reporter](INFO): platform_buoyancy_position 746.026428 cc
2025-05-29T00:50:06.427Z,1748479806.427 [Reporter](INFO): platform_buoyancy_position 752.338562 cc
2025-05-29T00:50:06.816Z,1748479806.816 [Reporter](INFO): platform_buoyancy_position 756.636108 cc
2025-05-29T00:50:07.203Z,1748479807.203 [Reporter](INFO): platform_buoyancy_position 761.068054 cc
2025-05-29T00:50:07.611Z,1748479807.611 [Reporter](INFO): platform_buoyancy_position 767.380127 cc
2025-05-29T00:50:08.056Z,1748479808.056 [Reporter](INFO): platform_buoyancy_position 773.826538 cc
2025-05-29T00:50:08.444Z,1748479808.444 [Reporter](INFO): platform_buoyancy_position 778.258423 cc
2025-05-29T00:50:08.823Z,1748479808.823 [Reporter](INFO): platform_buoyancy_position 782.556030 cc
2025-05-29T00:50:09.227Z,1748479809.227 [Reporter](INFO): platform_buoyancy_position 788.868164 cc
2025-05-29T00:50:09.628Z,1748479809.628 [Reporter](INFO): platform_buoyancy_position 793.300049 cc
2025-05-29T00:50:10.051Z,1748479810.051 [Reporter](INFO): platform_buoyancy_position 799.612122 cc
2025-05-29T00:50:10.451Z,1748479810.451 [Reporter](INFO): platform_buoyancy_position 804.044067 cc
2025-05-29T00:50:10.838Z,1748479810.838 [Reporter](INFO): platform_buoyancy_position 808.744568 cc
2025-05-29T00:50:11.243Z,1748479811.243 [Reporter](INFO): platform_buoyancy_position 814.653748 cc
2025-05-29T00:50:11.643Z,1748479811.643 [Reporter](INFO): platform_buoyancy_position 820.965820 cc
2025-05-29T00:50:12.091Z,1748479812.091 [Reporter](INFO): platform_buoyancy_position 825.532043 cc
2025-05-29T00:50:12.467Z,1748479812.467 [Reporter](INFO): platform_buoyancy_position 831.844116 cc
2025-05-29T00:50:12.863Z,1748479812.863 [Reporter](INFO): platform_buoyancy_position 836.141724 cc
2025-05-29T00:50:13.247Z,1748479813.247 [Reporter](INFO): platform_buoyancy_position 840.573669 cc
2025-05-29T00:50:13.655Z,1748479813.655 [Reporter](INFO): platform_buoyancy_position 846.885742 cc
2025-05-29T00:50:14.071Z,1748479814.071 [Reporter](INFO): platform_buoyancy_position 851.183350 cc
2025-05-29T00:50:14.479Z,1748479814.479 [Reporter](INFO): platform_buoyancy_position 857.629761 cc
2025-05-29T00:50:14.863Z,1748479814.863 [Reporter](INFO): platform_buoyancy_position 861.927368 cc
2025-05-29T00:50:15.279Z,1748479815.279 [Reporter](INFO): platform_buoyancy_position 868.373718 cc
2025-05-29T00:50:15.679Z,1748479815.679 [Reporter](INFO): platform_buoyancy_position 872.671326 cc
2025-05-29T00:50:16.102Z,1748479816.102 [Reporter](INFO): platform_buoyancy_position 877.237549 cc
2025-05-29T00:50:16.515Z,1748479816.515 [Reporter](INFO): platform_buoyancy_position 883.415344 cc
2025-05-29T00:50:16.899Z,1748479816.899 [Reporter](INFO): platform_buoyancy_position 888.921631 cc
2025-05-29T00:50:17.299Z,1748479817.299 [Reporter](INFO): platform_buoyancy_position 894.293640 cc
2025-05-29T00:50:17.707Z,1748479817.707 [Reporter](INFO): platform_buoyancy_position 898.591248 cc
2025-05-29T00:50:18.107Z,1748479818.107 [Reporter](INFO): platform_buoyancy_position 904.903320 cc
2025-05-29T00:50:18.506Z,1748479818.506 [Reporter](INFO): platform_buoyancy_position 909.335266 cc
2025-05-29T00:50:18.931Z,1748479818.931 [Reporter](INFO): platform_buoyancy_position 915.781677 cc
2025-05-29T00:50:19.316Z,1748479819.316 [Reporter](INFO): platform_buoyancy_position 920.079224 cc
2025-05-29T00:50:19.703Z,1748479819.703 [Reporter](INFO): platform_buoyancy_position 926.391357 cc
2025-05-29T00:50:20.123Z,1748479820.123 [Reporter](INFO): platform_buoyancy_position 930.823242 cc
2025-05-29T00:50:20.536Z,1748479820.536 [Reporter](INFO): platform_buoyancy_position 937.135376 cc
2025-05-29T00:50:20.951Z,1748479820.951 [Reporter](INFO): platform_buoyancy_position 941.432983 cc
2025-05-29T00:50:21.463Z,1748479821.463 [Reporter](INFO): platform_buoyancy_position 946.402100 cc
2025-05-29T00:51:51.807Z,1748479911.807 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-05-29T00:46:51.6Z
2025-05-29T00:51:51.807Z,1748479911.807 [Default:CheckIn:Read_GPS] Stopped
2025-05-29T00:51:51.807Z,1748479911.807 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-29T00:51:59.124Z,1748479919.124 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250529T001559/Courier0013.lzma
2025-05-29T00:52:00.126Z,1748479920.126 [DataOverHttps](INFO): Moved sent file to Logs/20250529T001559/Courier0013.lzma.bak
2025-05-29T00:52:00.127Z,1748479920.127 [DataOverHttps](INFO): SBD MOMSN=25023757
2025-05-29T00:52:07.125Z,1748479927.125 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 28 millimeter
2025-05-29T00:52:11.930Z,1748479931.930 [CommandExec](IMPORTANT): got command restart application
2025-05-29T00:52:12.933Z,1748479932.933 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2025-05-29T00:52:12.933Z,1748479932.933 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-29T00:52:12.933Z,1748479932.933 [CommandExec](INFO): Uninitializing the command executive.
2025-05-29T00:52:12.933Z,1748479932.933 [CommandExec](INFO): Uninitializing the command scheduler.
2025-05-29T00:52:12.934Z,1748479932.934 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:12.945Z,1748479932.945 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-05-29T00:52:12.945Z,1748479932.945 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-05-29T00:52:12.945Z,1748479932.945 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:12.946Z,1748479932.946 [NavChartDb](INFO): Join timeout helper Thread ID is 1232
2025-05-29T00:52:12.965Z,1748479932.965 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-29T00:52:12.965Z,1748479932.965 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:12.985Z,1748479932.985 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-05-29T00:52:12.985Z,1748479932.985 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:12.985Z,1748479932.985 [Radio_Surface](INFO): Join timeout helper Thread ID is 1233
2025-05-29T00:52:13.322Z,1748479933.322 [Radio_Surface](INFO): Powering down
2025-05-29T00:52:13.323Z,1748479933.323 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-29T00:52:13.323Z,1748479933.323 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:13.325Z,1748479933.325 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-05-29T00:52:13.325Z,1748479933.325 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:13.325Z,1748479933.325 [Onboard](INFO): Join timeout helper Thread ID is 1234
2025-05-29T00:52:14.017Z,1748479934.017 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-29T00:52:14.017Z,1748479934.017 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:14.021Z,1748479934.021 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-05-29T00:52:14.021Z,1748479934.021 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:14.021Z,1748479934.021 [DataOverHttps](INFO): Join timeout helper Thread ID is 1235
2025-05-29T00:52:15.815Z,1748479935.815 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-29T00:52:15.819Z,1748479935.819 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:15.835Z,1748479935.835 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-05-29T00:52:15.835Z,1748479935.835 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:15.835Z,1748479935.835 [DAT](INFO): Join timeout helper Thread ID is 1236
2025-05-29T00:52:16.025Z,1748479936.025 [DAT](INFO): Powering down
2025-05-29T00:52:16.097Z,1748479936.097 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-29T00:52:16.098Z,1748479936.098 [DAT](INFO): Powering down
2025-05-29T00:52:16.098Z,1748479936.098 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:16.102Z,1748479936.102 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-05-29T00:52:16.102Z,1748479936.102 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:16.102Z,1748479936.102 [BackseatComponent](INFO): Join timeout helper Thread ID is 1237
2025-05-29T00:52:16.157Z,1748479936.157 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-29T00:52:16.157Z,1748479936.157 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:16.170Z,1748479936.170 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-05-29T00:52:16.170Z,1748479936.170 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:16.170Z,1748479936.170 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1238
2025-05-29T00:52:16.449Z,1748479936.449 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-29T00:52:16.449Z,1748479936.449 [WetLabsBB2FL](INFO): Powering down
2025-05-29T00:52:16.450Z,1748479936.450 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:16.461Z,1748479936.461 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-05-29T00:52:16.461Z,1748479936.461 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:16.462Z,1748479936.462 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1239
2025-05-29T00:52:17.157Z,1748479937.157 [CTD_Seabird](INFO): Powering down
2025-05-29T00:52:17.169Z,1748479937.169 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-29T00:52:17.169Z,1748479937.169 [CTD_Seabird](INFO): Powering down
2025-05-29T00:52:17.181Z,1748479937.181 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:17.193Z,1748479937.193 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-05-29T00:52:17.193Z,1748479937.193 [logger ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:17.205Z,1748479937.205 [logger](INFO): Join timeout helper Thread ID is 1240
2025-05-29T00:52:17.244Z,1748479937.244 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-29T00:52:17.244Z,1748479937.244 [logger ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:17.261Z,1748479937.261 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-05-29T00:52:17.262Z,1748479937.262 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:17.262Z,1748479937.262 [CommandLine](INFO): Join timeout helper Thread ID is 1241
2025-05-29T00:52:17.329Z,1748479937.329 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-29T00:52:17.329Z,1748479937.329 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:17.345Z,1748479937.345 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-05-29T00:52:17.345Z,1748479937.345 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:17.346Z,1748479937.346 [CommandExec](INFO): Join timeout helper Thread ID is 1242
2025-05-29T00:52:17.347Z,1748479937.347 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-05-29T00:52:17.347Z,1748479937.347 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:17.347Z,1748479937.347 [controlThread](INFO): Join timeout helper Thread ID is 1243
2025-05-29T00:52:17.601Z,1748479937.601 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-29T00:52:17.601Z,1748479937.601 [controlThread](DEBUG): Uninitializing ControlThread
2025-05-29T00:52:17.602Z,1748479937.602 [AHRS_M2](INFO): Powering down
2025-05-29T00:52:17.777Z,1748479937.777 [DVL_micro](INFO): Powering down
2025-05-29T00:52:17.778Z,1748479937.778 [NAL9602](INFO): Powering down
2025-05-29T00:52:17.779Z,1748479937.779 [Sonardyne_Nano](INFO): Powering down
2025-05-29T00:52:17.985Z,1748479937.985 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-05-29T00:52:17.986Z,1748479937.986 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-05-29T00:52:17.986Z,1748479937.986 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-05-29T00:52:17.987Z,1748479937.987 [MissionManager](INFO): Uninitializing Mission Default
2025-05-29T00:52:17.987Z,1748479937.987 [Default] Stopped
2025-05-29T00:52:17.987Z,1748479937.987 [Default](DEBUG): Aggregate::uninitialize Default
2025-05-29T00:52:17.987Z,1748479937.987 [Default:B.GoToSurface] Stopped
2025-05-29T00:52:17.987Z,1748479937.987 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-29T00:52:17.987Z,1748479937.987 [Default:CheckIn] Stopped
2025-05-29T00:52:17.987Z,1748479937.987 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-29T00:52:17.987Z,1748479937.987 [Default:CheckIn:Read_Iridium] Stopped
2025-05-29T00:52:17.991Z,1748479937.991 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-05-29T00:52:17.991Z,1748479937.991 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-05-29T00:52:17.991Z,1748479937.991 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-05-29T00:52:17.991Z,1748479937.991 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-05-29T00:52:17.992Z,1748479937.992 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-05-29T00:52:17.992Z,1748479937.992 [BuoyancyServo](INFO): Powering down
2025-05-29T00:52:18.005Z,1748479938.005 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-05-29T00:52:18.005Z,1748479938.005 [ElevatorServo](INFO): Powering down
2025-05-29T00:52:18.006Z,1748479938.006 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-05-29T00:52:18.006Z,1748479938.006 [MassServo](INFO): Powering down
2025-05-29T00:52:18.007Z,1748479938.007 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-05-29T00:52:18.007Z,1748479938.007 [RudderServo](INFO): Powering down
2025-05-29T00:52:18.008Z,1748479938.008 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-05-29T00:52:18.008Z,1748479938.008 [ThrusterHE](INFO): Powering down
2025-05-29T00:52:18.009Z,1748479938.009 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-05-29T00:52:18.009Z,1748479938.009 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-05-29T00:52:18.009Z,1748479938.009 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-05-29T00:52:18.009Z,1748479938.009 [CBIT](DEBUG): Powering off loads.
2025-05-29T00:52:18.020Z,1748479938.020 [CBIT](DEBUG): Disabling WDT.
2025-05-29T00:52:18.033Z,1748479938.033 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-05-29T00:52:18.033Z,1748479938.033 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-05-29T00:52:18.034Z,1748479938.034 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:18.181Z,1748479938.181 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-05-29T00:52:18.181Z,1748479938.181 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-05-29T00:52:18.196Z,1748479938.196 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:18.203Z,1748479938.203 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:18.235Z,1748479938.235 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:18.239Z,1748479938.239 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:18.247Z,1748479938.247 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:18.253Z,1748479938.253 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:18.296Z,1748479938.296 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:18.350Z,1748479938.350 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-29T00:52:18.424Z,1748479938.424 [logger ThreadHandler](INFO): Thread cancelled.