2025-05-12T17:27:49.496Z,1747070869.496 [Supervisor](DEBUG): Initializing supervisor.
2025-05-12T17:27:49.502Z,1747070869.502 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-05-12T17:27:49.502Z,1747070869.502 [SyncHandler](INFO): Protected caller Thread ID is 7039
2025-05-12T17:27:49.502Z,1747070869.502 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-05-12T17:27:49.503Z,1747070869.503 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-05-12T17:27:49.504Z,1747070869.504 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7040
2025-05-12T17:27:49.508Z,1747070869.508 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-05-12T17:27:49.529Z,1747070869.529 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-05-12T17:27:49.530Z,1747070869.530 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-05-12T17:27:49.530Z,1747070869.530 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 7041
2025-05-12T17:27:49.534Z,1747070869.534 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-05-12T17:27:49.535Z,1747070869.535 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-05-12T17:27:49.536Z,1747070869.536 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7042
2025-05-12T17:27:49.538Z,1747070869.538 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-05-12T17:27:49.539Z,1747070869.539 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-05-12T17:27:49.539Z,1747070869.539 [logger ThreadHandler](INFO): Protected caller Thread ID is 7043
2025-05-12T17:27:49.543Z,1747070869.543 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-05-12T17:27:49.544Z,1747070869.543 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-05-12T17:27:49.545Z,1747070869.545 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-05-12T17:27:49.774Z,1747070869.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-05-12T17:27:49.774Z,1747070869.774 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-05-12T17:27:49.896Z,1747070869.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-05-12T17:27:49.896Z,1747070869.896 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-05-12T17:27:50.006Z,1747070870.006 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-05-12T17:27:50.007Z,1747070870.007 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-05-12T17:27:50.540Z,1747070870.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-05-12T17:27:50.541Z,1747070870.541 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-05-12T17:27:50.911Z,1747070870.911 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-05-12T17:27:50.912Z,1747070870.912 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-05-12T17:27:51.271Z,1747070871.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-05-12T17:27:51.272Z,1747070871.272 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-05-12T17:27:51.470Z,1747070871.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-05-12T17:27:51.470Z,1747070871.470 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-05-12T17:27:51.560Z,1747070871.560 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-05-12T17:27:51.653Z,1747070871.653 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-05-12T17:27:52.212Z,1747070872.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-05-12T17:27:52.212Z,1747070872.212 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-05-12T17:27:52.849Z,1747070872.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-05-12T17:27:52.849Z,1747070872.849 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-05-12T17:27:53.590Z,1747070873.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-05-12T17:27:53.591Z,1747070873.591 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-05-12T17:27:53.841Z,1747070873.841 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-05-12T17:27:53.841Z,1747070873.841 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-05-12T17:27:54.098Z,1747070874.098 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-05-12T17:27:54.099Z,1747070874.099 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-05-12T17:27:54.254Z,1747070874.254 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-05-12T17:27:54.255Z,1747070874.255 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-05-12T17:27:55.355Z,1747070875.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-05-12T17:27:55.357Z,1747070875.357 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2025-05-12T17:27:55.357Z,1747070875.357 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2025-05-12T17:27:55.580Z,1747070875.580 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2025-05-12T17:27:55.778Z,1747070875.778 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2025-05-12T17:27:56.059Z,1747070876.059 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-05-12T17:27:56.059Z,1747070876.059 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2025-05-12T17:27:56.198Z,1747070876.198 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2025-05-12T17:27:56.309Z,1747070876.309 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2025-05-12T17:27:56.416Z,1747070876.416 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2025-05-12T17:27:56.538Z,1747070876.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2025-05-12T17:27:56.635Z,1747070876.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2025-05-12T17:27:56.734Z,1747070876.734 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2025-05-12T17:27:56.837Z,1747070876.837 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2025-05-12T17:27:57.026Z,1747070877.026 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-05-12T17:27:57.037Z,1747070877.037 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-05-12T17:27:57.093Z,1747070877.093 [DepthRateCalculator] Loaded
2025-05-12T17:27:57.093Z,1747070877.093 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-05-12T17:27:57.099Z,1747070877.099 [PitchRateCalculator] Loaded
2025-05-12T17:27:57.099Z,1747070877.099 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-05-12T17:27:57.109Z,1747070877.109 [SpeedCalculator] Loaded
2025-05-12T17:27:57.109Z,1747070877.109 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-05-12T17:27:57.114Z,1747070877.114 [YawRateCalculator] Loaded
2025-05-12T17:27:57.115Z,1747070877.115 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-05-12T17:27:57.136Z,1747070877.136 [ElevatorOffsetCalculator] Loaded
2025-05-12T17:27:57.136Z,1747070877.136 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-05-12T17:27:57.137Z,1747070877.137 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-05-12T17:27:57.137Z,1747070877.137 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-05-12T17:27:57.169Z,1747070877.169 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-05-12T17:27:57.170Z,1747070877.170 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-05-12T17:27:57.180Z,1747070877.180 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-05-12T17:27:57.181Z,1747070877.181 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-05-12T17:27:57.694Z,1747070877.694 [AHRS_M2] Loaded
2025-05-12T17:27:57.695Z,1747070877.695 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-05-12T17:27:57.734Z,1747070877.734 [BackseatComponent] Loaded
2025-05-12T17:27:57.735Z,1747070877.735 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-05-12T17:27:57.736Z,1747070877.736 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408754E0
2025-05-12T17:27:57.736Z,1747070877.736 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 7125
2025-05-12T17:27:57.739Z,1747070877.739 [LcmUniversalReporter] Loaded
2025-05-12T17:27:57.739Z,1747070877.739 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-05-12T17:27:58.545Z,1747070878.545 [BPC1] Loaded
2025-05-12T17:27:58.545Z,1747070878.545 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-05-12T17:27:58.642Z,1747070878.642 [DAT] Loaded
2025-05-12T17:27:58.642Z,1747070878.642 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-05-12T17:27:58.643Z,1747070878.643 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408A54E0
2025-05-12T17:27:58.644Z,1747070878.644 [DAT ThreadHandler](INFO): Protected caller Thread ID is 7126
2025-05-12T17:27:58.721Z,1747070878.721 [DataOverHttps] Loaded
2025-05-12T17:27:58.721Z,1747070878.721 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-05-12T17:27:58.722Z,1747070878.722 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D54E0
2025-05-12T17:27:58.722Z,1747070878.722 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7127
2025-05-12T17:27:58.745Z,1747070878.745 [Depth_Keller] Loaded
2025-05-12T17:27:58.745Z,1747070878.745 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-05-12T17:27:58.750Z,1747070878.750 [DropWeight] Loaded
2025-05-12T17:27:58.751Z,1747070878.751 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-05-12T17:27:58.772Z,1747070878.772 [MultiRay](INFO): Found secondary power supply at: /dev/loadC7
2025-05-12T17:27:58.772Z,1747070878.772 [MultiRay] Loaded
2025-05-12T17:27:58.773Z,1747070878.773 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2025-05-12T17:27:58.838Z,1747070878.838 [NAL9602] Loaded
2025-05-12T17:27:58.838Z,1747070878.838 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-05-12T17:27:58.868Z,1747070878.868 [Onboard] Loaded
2025-05-12T17:27:58.869Z,1747070878.869 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-05-12T17:27:58.870Z,1747070878.870 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409054E0
2025-05-12T17:27:58.870Z,1747070878.870 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7128
2025-05-12T17:27:58.876Z,1747070878.876 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2025-05-12T17:27:58.890Z,1747070878.890 [PowerOnly] Loaded
2025-05-12T17:27:58.890Z,1747070878.890 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-05-12T17:27:58.904Z,1747070878.904 [Power24vConverter] Loaded
2025-05-12T17:27:58.904Z,1747070878.904 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-05-12T17:27:58.921Z,1747070878.921 [Radio_Surface] Loaded
2025-05-12T17:27:58.921Z,1747070878.921 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-05-12T17:27:58.923Z,1747070878.923 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409354E0
2025-05-12T17:27:58.923Z,1747070878.923 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7129
2025-05-12T17:27:58.924Z,1747070878.924 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-05-12T17:27:58.924Z,1747070878.924 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-05-12T17:27:59.042Z,1747070879.042 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-05-12T17:27:59.042Z,1747070879.042 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-05-12T17:27:59.056Z,1747070879.056 [NavChart] Loaded
2025-05-12T17:27:59.056Z,1747070879.056 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-05-12T17:27:59.062Z,1747070879.062 [UniversalFixResidualReporter] Loaded
2025-05-12T17:27:59.062Z,1747070879.062 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-05-12T17:27:59.063Z,1747070879.063 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-05-12T17:27:59.063Z,1747070879.063 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-05-12T17:27:59.237Z,1747070879.237 [SBIT](DEBUG): Construct Startup Built In Test.
2025-05-12T17:27:59.251Z,1747070879.251 [SBIT] Loaded
2025-05-12T17:27:59.251Z,1747070879.251 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-05-12T17:27:59.254Z,1747070879.254 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-05-12T17:27:59.267Z,1747070879.267 [IBIT] Loaded
2025-05-12T17:27:59.267Z,1747070879.267 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-05-12T17:27:59.273Z,1747070879.273 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-05-12T17:27:59.354Z,1747070879.354 [CBIT] Loaded
2025-05-12T17:27:59.354Z,1747070879.354 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-05-12T17:27:59.385Z,1747070879.385 [GFScanner] Loaded
2025-05-12T17:27:59.385Z,1747070879.385 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-05-12T17:27:59.386Z,1747070879.386 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-05-12T17:27:59.386Z,1747070879.386 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-05-12T17:27:59.564Z,1747070879.564 [CTD_Seabird] Loaded
2025-05-12T17:27:59.565Z,1747070879.565 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-05-12T17:27:59.566Z,1747070879.566 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A474E0
2025-05-12T17:27:59.566Z,1747070879.566 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7130
2025-05-12T17:27:59.587Z,1747070879.587 [PAR_Licor] Loaded
2025-05-12T17:27:59.587Z,1747070879.587 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-05-12T17:27:59.633Z,1747070879.633 [WetLabsBB2FL] Loaded
2025-05-12T17:27:59.633Z,1747070879.633 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-05-12T17:27:59.634Z,1747070879.634 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A774E0
2025-05-12T17:27:59.634Z,1747070879.634 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7131
2025-05-12T17:27:59.635Z,1747070879.635 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-05-12T17:27:59.636Z,1747070879.636 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-05-12T17:27:59.782Z,1747070879.782 [BuoyancyServo] Loaded
2025-05-12T17:27:59.782Z,1747070879.782 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-05-12T17:27:59.806Z,1747070879.806 [ElevatorServo] Loaded
2025-05-12T17:27:59.807Z,1747070879.807 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-05-12T17:27:59.830Z,1747070879.830 [MassServo] Loaded
2025-05-12T17:27:59.830Z,1747070879.830 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-05-12T17:27:59.853Z,1747070879.853 [RudderServo] Loaded
2025-05-12T17:27:59.853Z,1747070879.853 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-05-12T17:27:59.871Z,1747070879.871 [ThrusterHE] Loaded
2025-05-12T17:27:59.872Z,1747070879.872 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-05-12T17:27:59.872Z,1747070879.872 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-05-12T17:27:59.873Z,1747070879.873 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-05-12T17:27:59.930Z,1747070879.930 [VerticalControl](DEBUG): Construct VerticalControl.
2025-05-12T17:27:59.982Z,1747070879.982 [VerticalControl] Loaded
2025-05-12T17:27:59.983Z,1747070879.983 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-05-12T17:27:59.985Z,1747070879.985 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-05-12T17:28:00.029Z,1747070880.029 [HorizontalControl] Loaded
2025-05-12T17:28:00.029Z,1747070880.029 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-05-12T17:28:00.032Z,1747070880.032 [SpeedControl](DEBUG): Construct SpeedControl.
2025-05-12T17:28:00.034Z,1747070880.034 [SpeedControl] Loaded
2025-05-12T17:28:00.034Z,1747070880.034 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-05-12T17:28:00.037Z,1747070880.037 [LoopControl](DEBUG): Construct LoopControl.
2025-05-12T17:28:00.037Z,1747070880.037 [LoopControl] Loaded
2025-05-12T17:28:00.038Z,1747070880.038 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-05-12T17:28:00.038Z,1747070880.038 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-05-12T17:28:00.038Z,1747070880.038 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-05-12T17:28:00.180Z,1747070880.180 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-05-12T17:28:00.180Z,1747070880.180 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-05-12T17:28:00.371Z,1747070880.371 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-05-12T17:28:00.371Z,1747070880.371 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-05-12T17:28:00.969Z,1747070880.969 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-05-12T17:28:00.970Z,1747070880.970 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-05-12T17:28:01.034Z,1747070881.034 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-05-12T17:28:01.040Z,1747070881.040 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-05-12T17:28:01.043Z,1747070881.043 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-05-12T17:28:01.055Z,1747070881.055 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-05-12T17:28:01.056Z,1747070881.056 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0
2025-05-12T17:28:01.056Z,1747070881.056 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7132
2025-05-12T17:28:01.061Z,1747070881.061 [Supervisor](INFO): Main Thread ID is 7038
2025-05-12T17:28:01.061Z,1747070881.061 [Supervisor](DEBUG): Running supervisor.
2025-05-12T17:28:01.062Z,1747070881.062 [CommandExec ThreadHandler](INFO): Handler Thread ID is 7133
2025-05-12T17:28:01.062Z,1747070881.062 [CommandExec](INFO): Initializing the command executive.
2025-05-12T17:28:01.064Z,1747070881.064 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7134
2025-05-12T17:28:01.066Z,1747070881.066 [controlThread ThreadHandler](INFO): Handler Thread ID is 7135
2025-05-12T17:28:01.067Z,1747070881.067 [controlThread](DEBUG): Initializing ControlThread
2025-05-12T17:28:01.068Z,1747070881.068 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-05-12T17:28:01.068Z,1747070881.068 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-05-12T17:28:01.068Z,1747070881.068 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-05-12T17:28:01.069Z,1747070881.069 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-05-12T17:28:01.069Z,1747070881.069 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-05-12T17:28:01.073Z,1747070881.073 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-05-12T17:28:01.074Z,1747070881.074 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-05-12T17:28:01.074Z,1747070881.074 [SBIT](INFO): Initialize SBIT Component.
2025-05-12T17:28:01.075Z,1747070881.075 [SBIT](IMPORTANT): git: 2025-05-12_B
2025-05-12T17:28:01.075Z,1747070881.075 [SBIT](INFO): git hash: 7c5b1c07405cfe7ca09821eb029d166d25d65d57
2025-05-12T17:28:01.075Z,1747070881.075 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-05-12T17:28:01.076Z,1747070881.076 [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-12T17:28:01.077Z,1747070881.077 [SBIT](INFO): Beginning SBIT in 121.000000 seconds.
2025-05-12T17:28:01.078Z,1747070881.078 [IBIT](INFO): Initialize IBIT Component.
2025-05-12T17:28:01.079Z,1747070881.079 [CBIT](DEBUG): Initialize CBIT Component.
2025-05-12T17:28:01.080Z,1747070881.080 [logger ThreadHandler](INFO): Handler Thread ID is 7136
2025-05-12T17:28:01.093Z,1747070881.093 [CBIT](DEBUG): Initialized mux pins.
2025-05-12T17:28:01.093Z,1747070881.093 [CBIT](DEBUG): Initializing the watchdog timer.
2025-05-12T17:28:01.106Z,1747070881.106 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 7137
2025-05-12T17:28:01.117Z,1747070881.117 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-05-12T17:28:01.117Z,1747070881.117 [CBIT](DEBUG): Initializing heartbeat.
2025-05-12T17:28:01.118Z,1747070881.118 [DAT ThreadHandler](INFO): Handler Thread ID is 7138
2025-05-12T17:28:01.119Z,1747070881.119 [DAT](INFO): Powering up
2025-05-12T17:28:01.119Z,1747070881.119 [DAT](DEBUG): Initializing DAT.
2025-05-12T17:28:01.123Z,1747070881.123 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7140
2025-05-12T17:28:01.124Z,1747070881.124 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-05-12T17:28:01.146Z,1747070881.146 [Onboard ThreadHandler](INFO): Handler Thread ID is 7141
2025-05-12T17:28:01.163Z,1747070881.163 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7142
2025-05-12T17:28:01.182Z,1747070881.182 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7143
2025-05-12T17:28:01.183Z,1747070881.183 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-05-12T17:28:01.186Z,1747070881.186 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7145
2025-05-12T17:28:01.189Z,1747070881.189 [CBIT](DEBUG): Deactivating emergency mode.
2025-05-12T17:28:01.190Z,1747070881.190 [WetLabsBB2FL](INFO): Powering up
2025-05-12T17:28:01.191Z,1747070881.191 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7147
2025-05-12T17:28:01.194Z,1747070881.194 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-05-12T17:28:01.194Z,1747070881.194 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-05-12T17:28:01.194Z,1747070881.194 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-05-12T17:28:01.194Z,1747070881.194 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-05-12T17:28:01.195Z,1747070881.195 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-05-12T17:28:01.195Z,1747070881.195 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-05-12T17:28:01.195Z,1747070881.195 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-05-12T17:28:01.195Z,1747070881.195 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-05-12T17:28:01.229Z,1747070881.229 [CBIT](DEBUG): Backplane powered.
2025-05-12T17:28:01.230Z,1747070881.230 [GFScanner](DEBUG): Initializing GFScanner
2025-05-12T17:28:01.230Z,1747070881.230 [GFScanner](DEBUG): Deactivating GF circuits.
2025-05-12T17:28:01.239Z,1747070881.239 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-05-12T17:28:01.240Z,1747070881.240 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-05-12T17:28:01.241Z,1747070881.241 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-05-12T17:28:01.242Z,1747070881.242 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-05-12T17:28:01.242Z,1747070881.242 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-05-12T17:28:01.243Z,1747070881.243 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-05-12T17:28:01.243Z,1747070881.243 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-05-12T17:28:01.244Z,1747070881.244 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-05-12T17:28:01.305Z,1747070881.305 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-05-12T17:28:01.310Z,1747070881.310 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-12T17:28:01.347Z,1747070881.347 [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-12T17:28:01.347Z,1747070881.347 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-05-12T17:28:01.348Z,1747070881.348 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-05-12T17:28:01.349Z,1747070881.349 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-05-12T17:28:01.561Z,1747070881.561 [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-12T17:28:01.567Z,1747070881.567 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-05-12T17:28:01.568Z,1747070881.568 [Default:A.Wait](DEBUG): Construct Wait.
2025-05-12T17:28:01.569Z,1747070881.569 [Radio_Surface](INFO): Powering up
2025-05-12T17:28:01.574Z,1747070881.574 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-12T17:28:01.589Z,1747070881.589 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-05-12T17:28:01.591Z,1747070881.591 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-05-12T17:28:01.626Z,1747070881.626 [Default:E.Execute](DEBUG): Construct Execute.
2025-05-12T17:28:01.629Z,1747070881.629 [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-12T17:28:01.634Z,1747070881.634 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-05-12T17:28:01.652Z,1747070881.652 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-05-12T17:28:01.702Z,1747070881.702 [Depth_Keller](INFO): Initializing.
2025-05-12T17:28:01.703Z,1747070881.703 [MultiRay](INFO): Powering up MultiRay Lights
2025-05-12T17:28:01.704Z,1747070881.704 [MultiRay](INFO): Powering up secondary power supply.
2025-05-12T17:28:01.738Z,1747070881.738 [Power24vConverter](INFO): Powering up.
2025-05-12T17:28:01.750Z,1747070881.750 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-05-12T17:28:01.760Z,1747070881.760 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-05-12T17:28:01.765Z,1747070881.765 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-05-12T17:28:01.767Z,1747070881.767 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-05-12T17:28:01.773Z,1747070881.773 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-05-12T17:28:01.774Z,1747070881.774 [MassServo](DEBUG): Initializing EZServoServo.
2025-05-12T17:28:01.781Z,1747070881.781 [MassServo](DEBUG): Initializing MassServo.
2025-05-12T17:28:01.782Z,1747070881.782 [RudderServo](DEBUG): Initializing EZServoServo.
2025-05-12T17:28:01.790Z,1747070881.790 [RudderServo](DEBUG): Initializing RudderServo.
2025-05-12T17:28:01.791Z,1747070881.791 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-05-12T17:28:01.798Z,1747070881.798 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-05-12T17:28:02.858Z,1747070882.858 [MultiRay](INFO): Powering down
2025-05-12T17:28:02.977Z,1747070882.977 [MultiRay](INFO): Powering down LCB2
2025-05-12T17:28:03.801Z,1747070883.801 [WetLabsBB2FL](INFO): Powering down
2025-05-12T17:28:11.736Z,1747070891.736 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005581
2025-05-12T17:28:12.966Z,1747070892.966 [DAT](INFO): DAT read:
2025-05-12T17:28:12.967Z,1747070892.967 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-05-12T17:28:14.478Z,1747070894.478 [DAT](INFO): DAT read: MF Frequency Band
2025-05-12T17:28:14.479Z,1747070894.479 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-05-12T17:28:14.480Z,1747070894.480 [DAT](INFO): DAT read: May 12 2025 16:50:57
2025-05-12T17:28:15.742Z,1747070895.742 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-05-12T17:28:15.744Z,1747070895.744 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2025-05-12T17:28:15.744Z,1747070895.744 [DAT](INFO): commRate: 800
2025-05-12T17:28:17.809Z,1747070897.809 [DAT](INFO): entering command mode
2025-05-12T17:28:18.010Z,1747070898.010 [DAT](INFO): DAT read:
2025-05-12T17:28:18.010Z,1747070898.010 [DAT](INFO): DAT read: user:1>
2025-05-12T17:28:18.011Z,1747070898.011 [DAT](INFO): setting verbose to 3
2025-05-12T17:28:18.262Z,1747070898.262 [DAT](INFO): DAT read: user:1>
2025-05-12T17:28:18.263Z,1747070898.263 [DAT](INFO): DAT read: Verbose | 3
2025-05-12T17:28:18.263Z,1747070898.263 [DAT](INFO): set verbose to 3
2025-05-12T17:28:18.264Z,1747070898.263 [DAT](INFO): setting DatVerbose to 27440
2025-05-12T17:28:18.514Z,1747070898.514 [DAT](INFO): DAT read: user:2>
2025-05-12T17:28:18.515Z,1747070898.515 [DAT](INFO): DAT read: DatVerbose | 27440
2025-05-12T17:28:18.515Z,1747070898.515 [DAT](INFO): set DatVerbose to 27440
2025-05-12T17:28:18.515Z,1747070898.515 [DAT](INFO): setting transmit power to 8
2025-05-12T17:28:18.766Z,1747070898.766 [DAT](INFO): DAT read: user:3>
2025-05-12T17:28:18.767Z,1747070898.767 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-05-12T17:28:18.767Z,1747070898.767 [DAT](INFO): set transmit power to 8
2025-05-12T17:28:18.768Z,1747070898.768 [DAT](INFO): setting local address to 9
2025-05-12T17:28:19.018Z,1747070899.018 [DAT](INFO): DAT read: user:4>
2025-05-12T17:28:19.019Z,1747070899.019 [DAT](INFO): DAT read: LocalAddr | 9
2025-05-12T17:28:19.019Z,1747070899.019 [DAT](INFO): set local address to 9
2025-05-12T17:28:19.020Z,1747070899.020 [DAT](INFO): Setting time to: 17:28:19 And date to:5/12/2025
2025-05-12T17:28:19.270Z,1747070899.270 [DAT](INFO): DAT read: user:5>
2025-05-12T17:28:19.271Z,1747070899.271 [DAT](INFO): DAT read: Mon May 12, 2025 17:28:19
2025-05-12T17:28:19.271Z,1747070899.271 [DAT](INFO): Local DAT time set to Mon May 12, 2025 17:28:19
2025-05-12T17:28:29.223Z,1747070909.223 [NAL9602](INFO): Powering up NAL9602
2025-05-12T17:28:40.149Z,1747070920.149 [NAL9602](INFO): NAL9602 initialized
2025-05-12T17:30:02.644Z,1747071002.644 [SBIT](IMPORTANT): Beginning Startup BIT
2025-05-12T17:30:02.649Z,1747071002.649 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-05-12T17:30:07.202Z,1747071007.202 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005125
CHAN A1 (24V): 0.002529
CHAN A2 (12V): -0.004816
CHAN A3 (5V): -0.003346
CHAN B0 (3.3V): -0.002147
CHAN B1 (3.15aV): -0.002050
CHAN B2 (3.15bV): -0.001888
CHAN B3 (GND): -0.001177
OPEN: 0.005068
Full Scale: +/- 1 mA
2025-05-12T17:30:56.417Z,1747071056.417 [SBIT](IMPORTANT): SBIT PASSED
2025-05-12T17:30:56.417Z,1747071056.417 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-05-12T17:30:56.418Z,1747071056.418 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2025-05-12T17:30:56.418Z,1747071056.418 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;
2025-05-12T17:30:56.418Z,1747071056.418 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool;
2025-05-12T17:30:56.418Z,1747071056.418 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool;
2025-05-12T17:30:56.419Z,1747071056.419 [SBIT](IMPORTANT): MultiRay.brightnessWhite=88 none;
2025-05-12T17:30:56.419Z,1747071056.419 [SBIT](IMPORTANT): MultiRay.loadAtStartup=1 bool;
2025-05-12T17:30:56.419Z,1747071056.419 [SBIT](IMPORTANT): NAL9602.fastGPSFix=0 bool;
2025-05-12T17:30:56.419Z,1747071056.419 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool;
2025-05-12T17:30:56.419Z,1747071056.419 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;
2025-05-12T17:30:56.419Z,1747071056.419 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=213.529736 cubic_centimeter;
2025-05-12T17:30:56.419Z,1747071056.419 [SBIT](IMPORTANT): VerticalControl.massDefault=25.437902 millimeter;
2025-05-12T17:30:56.755Z,1747071056.755 [MissionManager](IMPORTANT): Started mission Startup
2025-05-12T17:30:56.755Z,1747071056.755 [Startup] Running Loop=1
2025-05-12T17:30:56.755Z,1747071056.755 [Startup](DEBUG): Aggregate::initialize Startup
2025-05-12T17:30:56.755Z,1747071056.755 [Startup:A.GoToSurface] Running Loop=1
2025-05-12T17:30:56.755Z,1747071056.755 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-12T17:30:56.756Z,1747071056.756 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-05-12T17:30:56.756Z,1747071056.756 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-05-12T17:30:56.757Z,1747071056.757 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-05-12T17:30:56.761Z,1747071056.761 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-05-12T17:30:56.761Z,1747071056.761 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-12T17:30:56.762Z,1747071056.762 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-12T17:30:56.764Z,1747071056.764 [Startup:StartupSatComms] Running Loop=1
2025-05-12T17:30:56.764Z,1747071056.764 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-05-12T17:30:56.764Z,1747071056.764 [Startup:StartupSatComms:A] Running Loop=1
2025-05-12T17:30:57.157Z,1747071057.157 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-05-12T17:31:01.178Z,1747071061.178 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-05-12T17:31:01.178Z,1747071061.178 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T17:31:01.189Z,1747071061.189 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T17:31:01.607Z,1747071061.607 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T17:31:01.607Z,1747071061.607 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-05-12T17:31:56.972Z,1747071116.972 [Startup:StartupSatComms:A](INFO): Timed out from 2025-05-12T17:30:56.8Z
2025-05-12T17:31:56.972Z,1747071116.972 [Startup:StartupSatComms:A] Stopped
2025-05-12T17:31:56.972Z,1747071116.972 [Startup:StartupSatComms:B] Running Loop=1
2025-05-12T17:31:57.367Z,1747071117.367 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-05-12T17:32:04.569Z,1747071124.569 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20250512T161059/Courier0013.lzma
2025-05-12T17:32:05.571Z,1747071125.571 [DataOverHttps](INFO): Moved sent file to Logs/20250512T161059/Courier0013.lzma.bak
2025-05-12T17:32:05.571Z,1747071125.571 [DataOverHttps](INFO): SBD MOMSN=24854309
2025-05-12T17:32:21.289Z,1747071141.289 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250512T172749/Courier0000.lzma
2025-05-12T17:32:22.291Z,1747071142.291 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Courier0000.lzma.bak
2025-05-12T17:32:22.291Z,1747071142.291 [DataOverHttps](INFO): SBD MOMSN=24854314
2025-05-12T17:32:37.893Z,1747071157.893 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20250512T161059/Express0014.lzma
2025-05-12T17:32:38.895Z,1747071158.895 [DataOverHttps](INFO): Moved sent file to Logs/20250512T161059/Express0014.lzma.bak
2025-05-12T17:32:38.895Z,1747071158.895 [DataOverHttps](INFO): SBD MOMSN=24854317
2025-05-12T17:32:54.747Z,1747071174.747 [DataOverHttps](INFO): Sending 890 bytes from file Logs/20250512T172749/Express0001.lzma
2025-05-12T17:32:55.747Z,1747071175.747 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Express0001.lzma.bak
2025-05-12T17:32:55.747Z,1747071175.747 [DataOverHttps](INFO): SBD MOMSN=24854367
2025-05-12T17:32:57.139Z,1747071177.139 [Startup:StartupSatComms:B](INFO): Timed out from 2025-05-12T17:31:56.0Z
2025-05-12T17:32:57.139Z,1747071177.139 [Startup:StartupSatComms:B] Stopped
2025-05-12T17:32:57.139Z,1747071177.139 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-05-12T17:32:57.139Z,1747071177.139 [Startup:StartupSatComms] Stopped
2025-05-12T17:32:57.140Z,1747071177.140 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-05-12T17:32:57.141Z,1747071177.141 [Startup](INFO): Completed Startup
2025-05-12T17:32:57.141Z,1747071177.141 [MissionManager](INFO): Startup is completed.
2025-05-12T17:32:57.141Z,1747071177.141 [MissionManager](INFO): Uninitializing Mission Startup
2025-05-12T17:32:57.141Z,1747071177.141 [Startup] Stopped
2025-05-12T17:32:57.141Z,1747071177.141 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-05-12T17:32:57.141Z,1747071177.141 [Startup:A.GoToSurface] Stopped
2025-05-12T17:32:57.141Z,1747071177.141 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-12T17:32:57.549Z,1747071177.549 [MissionManager](IMPORTANT): Started mission Default
2025-05-12T17:32:57.550Z,1747071177.550 [Default] Running Loop=1
2025-05-12T17:32:57.550Z,1747071177.550 [Default](DEBUG): Aggregate::initialize Default
2025-05-12T17:32:57.550Z,1747071177.550 [Default:B.GoToSurface] Running Loop=1
2025-05-12T17:32:57.550Z,1747071177.550 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-12T17:32:57.550Z,1747071177.550 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-05-12T17:32:57.550Z,1747071177.550 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-05-12T17:32:57.551Z,1747071177.551 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-05-12T17:32:57.551Z,1747071177.551 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-05-12T17:32:57.551Z,1747071177.551 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-12T17:32:57.552Z,1747071177.552 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-12T17:32:57.552Z,1747071177.552 [Default:A.Wait] Running Loop=1
2025-05-12T17:32:57.552Z,1747071177.552 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-05-12T17:33:10.907Z,1747071190.907 [Default:A.Wait](INFO): Done Waiting.
2025-05-12T17:33:10.907Z,1747071190.907 [Default:A.Wait] Stopped
2025-05-12T17:33:10.907Z,1747071190.907 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-05-12T17:33:11.417Z,1747071191.417 [Default:CheckIn] Running Loop=1
2025-05-12T17:33:11.437Z,1747071191.437 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-12T17:33:11.437Z,1747071191.437 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-12T17:33:11.730Z,1747071191.730 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-05-12T17:33:43.195Z,1747071223.195 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-05-12T17:34:02.223Z,1747071242.223 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-05-12T17:34:02.223Z,1747071242.223 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T17:34:02.235Z,1747071242.235 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T17:34:02.628Z,1747071242.628 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T17:34:02.628Z,1747071242.628 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-05-12T17:35:26.627Z,1747071326.627 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2025-05-12T17:35:26.635Z,1747071326.635 [BPC1](INFO): Received data from all battery sticks.
2025-05-12T17:37:03.206Z,1747071423.206 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-05-12T17:37:03.206Z,1747071423.206 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T17:37:03.216Z,1747071423.216 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T17:37:03.615Z,1747071423.615 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T17:37:03.615Z,1747071423.615 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-05-12T17:38:11.463Z,1747071491.463 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-05-12T17:33:11.4Z
2025-05-12T17:38:11.463Z,1747071491.463 [Default:CheckIn:Read_GPS] Stopped
2025-05-12T17:38:11.463Z,1747071491.463 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-12T17:38:11.869Z,1747071491.869 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-05-12T17:38:18.273Z,1747071498.273 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250512T172749/Courier0004.lzma
2025-05-12T17:38:19.271Z,1747071499.271 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Courier0004.lzma.bak
2025-05-12T17:38:19.271Z,1747071499.271 [DataOverHttps](INFO): SBD MOMSN=24854428
2025-05-12T17:38:34.970Z,1747071514.970 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20250512T172749/Express0005.lzma
2025-05-12T17:38:35.971Z,1747071515.971 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Express0005.lzma.bak
2025-05-12T17:38:35.971Z,1747071515.971 [DataOverHttps](INFO): SBD MOMSN=24854430
2025-05-12T17:38:37.351Z,1747071517.351 [Default:CheckIn:Read_Iridium] Stopped
2025-05-12T17:38:37.351Z,1747071517.351 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-12T17:38:37.351Z,1747071517.351 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-12T17:38:43.371Z,1747071523.371 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-05-12T17:38:43.371Z,1747071523.371 [NAL9602] Data Fault, FailCount= 1
2025-05-12T17:38:43.371Z,1747071523.371 [NAL9602](ERROR): Data Fault
2025-05-12T17:38:43.394Z,1747071523.394 [CBIT](ERROR): Data Fault in component: NAL9602
2025-05-12T17:38:43.775Z,1747071523.775 [NAL9602](INFO): Powering down
2025-05-12T17:38:44.620Z,1747071524.620 [CBIT](INFO): Clearing failed state for component NAL9602
2025-05-12T17:38:44.620Z,1747071524.620 [NAL9602] No Fault, FailCount= 1
2025-05-12T17:39:14.075Z,1747071554.075 [NAL9602](INFO): Powering up NAL9602
2025-05-12T17:39:24.979Z,1747071564.979 [NAL9602](INFO): NAL9602 initialized
2025-05-12T17:40:04.222Z,1747071604.222 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-05-12T17:40:04.222Z,1747071604.222 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T17:40:04.234Z,1747071604.234 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T17:40:04.650Z,1747071604.650 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T17:40:04.650Z,1747071604.650 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-05-12T17:43:05.190Z,1747071785.190 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-05-12T17:43:05.190Z,1747071785.190 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T17:43:05.201Z,1747071785.201 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T17:43:05.625Z,1747071785.625 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T17:43:05.625Z,1747071785.625 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-05-12T17:43:37.939Z,1747071817.939 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-12T17:43:37.940Z,1747071817.940 [Default:CheckIn:C.Wait] Stopped
2025-05-12T17:43:37.940Z,1747071817.940 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-12T17:43:37.940Z,1747071817.940 [Default:CheckIn:D] Running Loop=1
2025-05-12T17:43:38.330Z,1747071818.330 [Default:CheckIn:D] Stopped
2025-05-12T17:43:38.331Z,1747071818.331 [Default:CheckIn:E] Running Loop=1
2025-05-12T17:43:38.728Z,1747071818.728 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.679680 min
2025-05-12T17:43:38.728Z,1747071818.728 [Default:CheckIn:E] Stopped
2025-05-12T17:43:38.728Z,1747071818.728 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-12T17:43:38.728Z,1747071818.728 [Default:CheckIn] Stopped
2025-05-12T17:43:38.728Z,1747071818.728 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-12T17:43:38.728Z,1747071818.728 [Default:CheckIn](INFO): Running loop #2
2025-05-12T17:43:38.728Z,1747071818.728 [Default:CheckIn] Running Loop=2
2025-05-12T17:43:38.728Z,1747071818.728 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-12T17:43:38.728Z,1747071818.728 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-12T17:46:06.256Z,1747071966.256 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-05-12T17:46:06.256Z,1747071966.256 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T17:46:06.267Z,1747071966.267 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T17:46:06.678Z,1747071966.678 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T17:46:06.678Z,1747071966.678 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-05-12T17:48:38.951Z,1747072118.951 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-05-12T17:43:38.7Z
2025-05-12T17:48:38.951Z,1747072118.951 [Default:CheckIn:Read_GPS] Stopped
2025-05-12T17:48:38.951Z,1747072118.951 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-12T17:48:46.054Z,1747072126.054 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250512T172749/Courier0007.lzma
2025-05-12T17:48:47.055Z,1747072127.055 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Courier0007.lzma.bak
2025-05-12T17:48:47.055Z,1747072127.055 [DataOverHttps](INFO): SBD MOMSN=24854562
2025-05-12T17:49:02.677Z,1747072142.677 [DataOverHttps](INFO): Sending 183 bytes from file Logs/20250512T172749/Express0008.lzma
2025-05-12T17:49:03.679Z,1747072143.679 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Express0008.lzma.bak
2025-05-12T17:49:03.679Z,1747072143.679 [DataOverHttps](INFO): SBD MOMSN=24854564
2025-05-12T17:49:04.810Z,1747072144.810 [Default:CheckIn:Read_Iridium] Stopped
2025-05-12T17:49:04.811Z,1747072144.811 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-12T17:49:04.811Z,1747072144.811 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-12T17:49:07.278Z,1747072147.278 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-05-12T17:49:07.278Z,1747072147.278 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T17:49:07.321Z,1747072147.321 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T17:49:07.672Z,1747072147.672 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T17:49:07.672Z,1747072147.672 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-05-12T17:49:26.214Z,1747072166.214 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-05-12T17:49:26.214Z,1747072166.214 [NAL9602] Data Fault, FailCount= 2
2025-05-12T17:49:26.214Z,1747072166.214 [NAL9602](ERROR): Data Fault
2025-05-12T17:49:26.232Z,1747072166.232 [CBIT](ERROR): Data Fault in component: NAL9602
2025-05-12T17:49:26.615Z,1747072166.615 [NAL9602](INFO): Powering down
2025-05-12T17:49:27.443Z,1747072167.443 [CBIT](INFO): Clearing failed state for component NAL9602
2025-05-12T17:49:27.443Z,1747072167.443 [NAL9602] No Fault, FailCount= 2
2025-05-12T17:49:56.963Z,1747072196.963 [NAL9602](INFO): Powering up NAL9602
2025-05-12T17:50:07.871Z,1747072207.871 [NAL9602](INFO): NAL9602 initialized
2025-05-12T17:52:08.291Z,1747072328.291 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-05-12T17:52:08.291Z,1747072328.291 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T17:52:08.302Z,1747072328.302 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T17:52:08.712Z,1747072328.712 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T17:52:08.712Z,1747072328.712 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-05-12T17:54:05.454Z,1747072445.454 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-12T17:54:05.455Z,1747072445.455 [Default:CheckIn:C.Wait] Stopped
2025-05-12T17:54:05.455Z,1747072445.455 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-12T17:54:05.455Z,1747072445.455 [Default:CheckIn:D] Running Loop=1
2025-05-12T17:54:05.879Z,1747072445.879 [Default:CheckIn:D] Stopped
2025-05-12T17:54:05.879Z,1747072445.879 [Default:CheckIn:E] Running Loop=1
2025-05-12T17:54:06.287Z,1747072446.287 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.138831 min
2025-05-12T17:54:06.287Z,1747072446.287 [Default:CheckIn:E] Stopped
2025-05-12T17:54:06.287Z,1747072446.287 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-12T17:54:06.287Z,1747072446.287 [Default:CheckIn] Stopped
2025-05-12T17:54:06.287Z,1747072446.287 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-12T17:54:06.287Z,1747072446.287 [Default:CheckIn](INFO): Running loop #3
2025-05-12T17:54:06.287Z,1747072446.287 [Default:CheckIn] Running Loop=3
2025-05-12T17:54:06.287Z,1747072446.287 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-12T17:54:06.288Z,1747072446.288 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-12T17:55:09.319Z,1747072509.319 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-05-12T17:55:09.319Z,1747072509.319 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T17:55:09.330Z,1747072509.330 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T17:55:09.720Z,1747072509.720 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T17:55:09.720Z,1747072509.720 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-05-12T17:58:10.298Z,1747072690.298 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-05-12T17:58:10.298Z,1747072690.298 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T17:58:10.309Z,1747072690.309 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T17:58:10.715Z,1747072690.715 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T17:58:10.715Z,1747072690.715 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-05-12T17:59:06.439Z,1747072746.439 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-05-12T17:54:06.3Z
2025-05-12T17:59:06.439Z,1747072746.439 [Default:CheckIn:Read_GPS] Stopped
2025-05-12T17:59:06.439Z,1747072746.439 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-12T17:59:13.177Z,1747072753.177 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250512T172749/Courier0010.lzma
2025-05-12T17:59:14.179Z,1747072754.179 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Courier0010.lzma.bak
2025-05-12T17:59:14.179Z,1747072754.179 [DataOverHttps](INFO): SBD MOMSN=24854596
2025-05-12T17:59:29.777Z,1747072769.777 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250512T172749/Express0011.lzma
2025-05-12T17:59:30.779Z,1747072770.779 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Express0011.lzma.bak
2025-05-12T17:59:30.779Z,1747072770.779 [DataOverHttps](INFO): SBD MOMSN=24854598
2025-05-12T17:59:31.911Z,1747072771.911 [Default:CheckIn:Read_Iridium] Stopped
2025-05-12T17:59:31.911Z,1747072771.911 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-12T17:59:31.911Z,1747072771.911 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-12T18:00:09.047Z,1747072809.047 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-05-12T18:00:09.047Z,1747072809.047 [NAL9602] Data Fault, FailCount= 3
2025-05-12T18:00:09.047Z,1747072809.047 [NAL9602](ERROR): Data Fault
2025-05-12T18:00:09.064Z,1747072809.064 [CBIT](ERROR): Data Fault in component: NAL9602
2025-05-12T18:00:09.447Z,1747072809.447 [NAL9602](INFO): Powering down
2025-05-12T18:00:10.293Z,1747072810.293 [CBIT](INFO): Clearing failed state for component NAL9602
2025-05-12T18:00:10.293Z,1747072810.293 [NAL9602] No Fault, FailCount= 3
2025-05-12T18:00:39.751Z,1747072839.751 [NAL9602](INFO): Powering up NAL9602
2025-05-12T18:00:50.656Z,1747072850.656 [NAL9602](INFO): NAL9602 initialized
2025-05-12T18:01:11.270Z,1747072871.270 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2025-05-12T18:01:11.270Z,1747072871.270 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T18:01:11.280Z,1747072871.280 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T18:01:11.704Z,1747072871.704 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T18:01:11.705Z,1747072871.705 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2025-05-12T18:04:12.282Z,1747073052.282 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2025-05-12T18:04:12.282Z,1747073052.282 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T18:04:12.318Z,1747073052.318 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T18:04:12.695Z,1747073052.695 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T18:04:12.695Z,1747073052.695 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2025-05-12T18:04:32.507Z,1747073072.507 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-12T18:04:32.507Z,1747073072.507 [Default:CheckIn:C.Wait] Stopped
2025-05-12T18:04:32.507Z,1747073072.507 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-12T18:04:32.507Z,1747073072.507 [Default:CheckIn:D] Running Loop=1
2025-05-12T18:04:32.887Z,1747073072.887 [Default:CheckIn:D] Stopped
2025-05-12T18:04:32.887Z,1747073072.887 [Default:CheckIn:E] Running Loop=1
2025-05-12T18:04:33.295Z,1747073073.295 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.588951 min
2025-05-12T18:04:33.295Z,1747073073.295 [Default:CheckIn:E] Stopped
2025-05-12T18:04:33.295Z,1747073073.295 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-12T18:04:33.295Z,1747073073.295 [Default:CheckIn] Stopped
2025-05-12T18:04:33.296Z,1747073073.296 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-12T18:04:33.296Z,1747073073.296 [Default:CheckIn](INFO): Running loop #4
2025-05-12T18:04:33.296Z,1747073073.296 [Default:CheckIn] Running Loop=4
2025-05-12T18:04:33.296Z,1747073073.296 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-12T18:04:33.296Z,1747073073.296 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-12T18:07:13.275Z,1747073233.275 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2025-05-12T18:07:13.275Z,1747073233.275 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T18:07:13.286Z,1747073233.286 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T18:07:13.704Z,1747073233.704 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T18:07:13.704Z,1747073233.704 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2025-05-12T18:09:33.495Z,1747073373.495 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-05-12T18:04:33.3Z
2025-05-12T18:09:33.495Z,1747073373.495 [Default:CheckIn:Read_GPS] Stopped
2025-05-12T18:09:33.495Z,1747073373.495 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-12T18:09:40.304Z,1747073380.304 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250512T172749/Courier0013.lzma
2025-05-12T18:09:41.307Z,1747073381.307 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Courier0013.lzma.bak
2025-05-12T18:09:41.307Z,1747073381.307 [DataOverHttps](INFO): SBD MOMSN=24854675
2025-05-12T18:09:59.545Z,1747073399.545 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250512T172749/Express0014.lzma
2025-05-12T18:10:00.547Z,1747073400.547 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Express0014.lzma.bak
2025-05-12T18:10:00.547Z,1747073400.547 [DataOverHttps](INFO): SBD MOMSN=24854677
2025-05-12T18:10:01.823Z,1747073401.823 [Default:CheckIn:Read_Iridium] Stopped
2025-05-12T18:10:01.823Z,1747073401.823 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-12T18:10:01.823Z,1747073401.823 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-12T18:10:14.326Z,1747073414.326 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2025-05-12T18:10:14.326Z,1747073414.326 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T18:10:14.336Z,1747073414.336 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T18:10:14.785Z,1747073414.785 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T18:10:14.786Z,1747073414.786 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2025-05-12T18:10:51.891Z,1747073451.891 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-05-12T18:10:51.891Z,1747073451.891 [NAL9602] Data Fault, FailCount= 4
2025-05-12T18:10:51.891Z,1747073451.891 [NAL9602](ERROR): Data Fault
2025-05-12T18:10:51.933Z,1747073451.933 [CBIT](ERROR): Data Fault in component: NAL9602
2025-05-12T18:10:52.295Z,1747073452.295 [NAL9602](INFO): Powering down
2025-05-12T18:10:53.132Z,1747073453.132 [CBIT](INFO): Clearing failed state for component NAL9602
2025-05-12T18:10:53.132Z,1747073453.132 [NAL9602] No Fault, FailCount= 4
2025-05-12T18:11:22.595Z,1747073482.595 [NAL9602](INFO): Powering up NAL9602
2025-05-12T18:11:33.503Z,1747073493.503 [NAL9602](INFO): NAL9602 initialized
2025-05-12T18:13:15.332Z,1747073595.332 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2025-05-12T18:13:15.332Z,1747073595.332 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T18:13:15.343Z,1747073595.343 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T18:13:15.748Z,1747073595.748 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T18:13:15.748Z,1747073595.748 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2025-05-12T18:15:02.389Z,1747073702.389 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-12T18:15:02.389Z,1747073702.389 [Default:CheckIn:C.Wait] Stopped
2025-05-12T18:15:02.389Z,1747073702.389 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-12T18:15:02.389Z,1747073702.389 [Default:CheckIn:D] Running Loop=1
2025-05-12T18:15:02.802Z,1747073702.802 [Default:CheckIn:D] Stopped
2025-05-12T18:15:02.802Z,1747073702.802 [Default:CheckIn:E] Running Loop=1
2025-05-12T18:15:03.184Z,1747073703.184 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.087537 min
2025-05-12T18:15:03.184Z,1747073703.184 [Default:CheckIn:E] Stopped
2025-05-12T18:15:03.184Z,1747073703.184 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-12T18:15:03.184Z,1747073703.184 [Default:CheckIn] Stopped
2025-05-12T18:15:03.184Z,1747073703.184 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-12T18:15:03.184Z,1747073703.184 [Default:CheckIn](INFO): Running loop #5
2025-05-12T18:15:03.184Z,1747073703.184 [Default:CheckIn] Running Loop=5
2025-05-12T18:15:03.184Z,1747073703.184 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-12T18:15:03.184Z,1747073703.184 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-12T18:16:16.331Z,1747073776.331 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2025-05-12T18:16:16.331Z,1747073776.331 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T18:16:16.342Z,1747073776.342 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T18:16:16.732Z,1747073776.732 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T18:16:16.732Z,1747073776.732 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2025-05-12T18:19:17.303Z,1747073957.303 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2025-05-12T18:19:17.303Z,1747073957.303 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T18:19:17.314Z,1747073957.314 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T18:19:17.725Z,1747073957.725 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T18:19:17.725Z,1747073957.725 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2025-05-12T18:20:03.399Z,1747074003.399 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-05-12T18:15:03.2Z
2025-05-12T18:20:03.399Z,1747074003.399 [Default:CheckIn:Read_GPS] Stopped
2025-05-12T18:20:03.399Z,1747074003.399 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-12T18:20:10.549Z,1747074010.549 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250512T172749/Courier0016.lzma
2025-05-12T18:20:11.551Z,1747074011.551 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Courier0016.lzma.bak
2025-05-12T18:20:11.551Z,1747074011.551 [DataOverHttps](INFO): SBD MOMSN=24854903
2025-05-12T18:20:27.330Z,1747074027.330 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250512T172749/Express0017.lzma
2025-05-12T18:20:28.331Z,1747074028.331 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Express0017.lzma.bak
2025-05-12T18:20:28.331Z,1747074028.331 [DataOverHttps](INFO): SBD MOMSN=24854907
2025-05-12T18:20:29.656Z,1747074029.656 [Default:CheckIn:Read_Iridium] Stopped
2025-05-12T18:20:29.656Z,1747074029.656 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-12T18:20:29.656Z,1747074029.656 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-12T18:21:35.495Z,1747074095.495 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-05-12T18:21:35.495Z,1747074095.495 [NAL9602] Data Fault, FailCount= 5
2025-05-12T18:21:35.495Z,1747074095.495 [NAL9602](ERROR): Data Fault
2025-05-12T18:21:35.565Z,1747074095.565 [CBIT](ERROR): Data Fault in component: NAL9602
2025-05-12T18:21:35.566Z,1747074095.566 [CBIT](CRITICAL): Data Fault in component: NAL9602
2025-05-12T18:21:35.903Z,1747074095.903 [NAL9602](INFO): Powering down
2025-05-12T18:21:35.927Z,1747074095.927 [CommandExec](FAULT): Scheduling is paused
2025-05-12T18:21:35.927Z,1747074095.927 [CBIT](INFO): Critical error at 20250512T182135
2025-05-12T18:21:35.927Z,1747074095.927 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-05-12T18:22:18.350Z,1747074138.350 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2025-05-12T18:22:18.350Z,1747074138.350 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T18:22:18.370Z,1747074138.370 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T18:22:18.771Z,1747074138.771 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T18:22:18.771Z,1747074138.771 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2025-05-12T18:23:35.507Z,1747074215.507 [CBIT](INFO): Clearing failed state for component NAL9602
2025-05-12T18:23:35.507Z,1747074215.507 [NAL9602] No Fault, FailCount= 5
2025-05-12T18:23:35.887Z,1747074215.887 [NAL9602](INFO): Powering up NAL9602
2025-05-12T18:23:46.803Z,1747074226.803 [NAL9602](INFO): NAL9602 initialized
2025-05-12T18:25:19.342Z,1747074319.342 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2025-05-12T18:25:19.342Z,1747074319.342 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T18:25:19.354Z,1747074319.354 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T18:25:19.744Z,1747074319.744 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T18:25:19.744Z,1747074319.744 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2025-05-12T18:25:30.251Z,1747074330.251 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-12T18:25:30.251Z,1747074330.251 [Default:CheckIn:C.Wait] Stopped
2025-05-12T18:25:30.251Z,1747074330.251 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-12T18:25:30.252Z,1747074330.252 [Default:CheckIn:D] Running Loop=1
2025-05-12T18:25:30.687Z,1747074330.687 [Default:CheckIn:D] Stopped
2025-05-12T18:25:30.687Z,1747074330.687 [Default:CheckIn:E] Running Loop=1
2025-05-12T18:25:31.054Z,1747074331.054 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.552295 min
2025-05-12T18:25:31.054Z,1747074331.054 [Default:CheckIn:E] Stopped
2025-05-12T18:25:31.054Z,1747074331.054 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-12T18:25:31.054Z,1747074331.054 [Default:CheckIn] Stopped
2025-05-12T18:25:31.054Z,1747074331.054 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-12T18:25:31.054Z,1747074331.054 [Default:CheckIn](INFO): Running loop #6
2025-05-12T18:25:31.054Z,1747074331.054 [Default:CheckIn] Running Loop=6
2025-05-12T18:25:31.054Z,1747074331.054 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-12T18:25:31.054Z,1747074331.054 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-12T18:28:00.917Z,1747074480.917 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182759.00,A,3648.13937,N,12147.23736,W,0.194,0.00,120525,,,A*75
2025-05-12T18:28:00.920Z,1747074480.920 [NAL9602](INFO): GPS fix at 20250512T182759: (36.802323, -121.787289)
2025-05-12T18:28:00.948Z,1747074480.948 [Default:CheckIn:Read_GPS] Stopped
2025-05-12T18:28:00.948Z,1747074480.948 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-12T18:28:08.570Z,1747074488.570 [DataOverHttps](INFO): Sending 278 bytes from file Logs/20250512T172749/Courier0019.lzma
2025-05-12T18:28:09.571Z,1747074489.571 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Courier0019.lzma.bak
2025-05-12T18:28:09.571Z,1747074489.571 [DataOverHttps](INFO): SBD MOMSN=24854989
2025-05-12T18:28:25.458Z,1747074505.458 [DataOverHttps](INFO): Sending 408 bytes from file Logs/20250512T172749/Express0020.lzma
2025-05-12T18:28:26.459Z,1747074506.459 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Express0020.lzma.bak
2025-05-12T18:28:26.459Z,1747074506.459 [DataOverHttps](INFO): SBD MOMSN=24854996
2025-05-12T18:28:27.599Z,1747074507.599 [Default:CheckIn:Read_Iridium] Stopped
2025-05-12T18:28:27.600Z,1747074507.600 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-12T18:28:27.600Z,1747074507.600 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-12T18:28:33.244Z,1747074513.244 [NAL9602](INFO): Powering down
2025-05-12T18:32:08.090Z,1747074728.090 [DataOverHttps](IMPORTANT): SBD MTMSN=20250512T183207
2025-05-12T18:32:15.457Z,1747074735.457 [DataOverHttps](INFO): Received command: ibit
2025-05-12T18:32:15.530Z,1747074735.530 [CommandExec](IMPORTANT): got command ibit
2025-05-12T18:32:15.885Z,1747074735.885 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-05-12T18:32:15.885Z,1747074735.885 [IBIT](IMPORTANT): Beginning control surface checks.
2025-05-12T18:32:15.889Z,1747074735.889 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-05-12T18:32:16.273Z,1747074736.273 [NAL9602](INFO): Powering up
2025-05-12T18:32:16.458Z,1747074736.458 [DataOverHttps](IMPORTANT): SBD MTMSN=20250512T183215
2025-05-12T18:32:20.514Z,1747074740.514 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007777
CHAN A1 (24V): 0.000468
CHAN A2 (12V): -0.005878
CHAN A3 (5V): -0.002831
CHAN B0 (3.3V): -0.001616
CHAN B1 (3.15aV): -0.001067
CHAN B2 (3.15bV): -0.001712
CHAN B3 (GND): -0.000707
OPEN: 0.004690
Full Scale: +/- 1 mA
2025-05-12T18:32:24.005Z,1747074744.005 [DataOverHttps](INFO): Received command: ibit
2025-05-12T18:32:24.086Z,1747074744.086 [CommandExec](IMPORTANT): got command ibit
2025-05-12T18:32:27.183Z,1747074747.183 [NAL9602](INFO): NAL9602 initialized
2025-05-12T18:32:28.394Z,1747074748.394 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183044.00,A,3648.14026,N,12147.23000,W,0.078,0.00,120525,,,A*70
2025-05-12T18:32:28.396Z,1747074748.396 [NAL9602](INFO): GPS fix at 20250512T183044: (36.802338, -121.787167)
2025-05-12T18:33:34.367Z,1747074814.367 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 1
Latitude: 36.802338 Longitude: -121.787170
2025-05-12T18:33:34.782Z,1747074814.782 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2025-05-12T18:33:34.782Z,1747074814.782 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-05-12T18:33:34.784Z,1747074814.784 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-05-12T18:33:35.164Z,1747074815.164 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-05-12T18:33:35.165Z,1747074815.165 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-05-12T18:33:35.169Z,1747074815.169 [IBIT](IMPORTANT): Pressure:9.940605 PSI
2025-05-12T18:33:35.170Z,1747074815.170 [IBIT](IMPORTANT): Humidity:10.792333 %
2025-05-12T18:33:35.566Z,1747074815.566 [IBIT](IMPORTANT): Vehicle Pitch:-0.199144 degrees
2025-05-12T18:33:35.566Z,1747074815.566 [IBIT](IMPORTANT): Vehicle Roll:-10.275572 degrees
2025-05-12T18:33:35.566Z,1747074815.566 [IBIT](IMPORTANT): Vehicle Heading:190.141449 degrees
2025-05-12T18:33:36.006Z,1747074816.006 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-05-12T18:33:36.006Z,1747074816.006 [IBIT](IMPORTANT): buoyancyNeutral: 213.529739 cc
2025-05-12T18:33:36.006Z,1747074816.006 [IBIT](IMPORTANT): massDefault: 2.543790 cm
2025-05-12T18:33:36.007Z,1747074816.007 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2025-05-12T18:33:36.007Z,1747074816.007 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-05-12T18:33:36.007Z,1747074816.007 [IBIT](IMPORTANT): IBIT PASSED
2025-05-12T18:33:36.369Z,1747074816.369 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-12T18:33:36.369Z,1747074816.369 [Default:CheckIn:C.Wait] Stopped
2025-05-12T18:33:36.369Z,1747074816.369 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-12T18:33:36.382Z,1747074816.382 [Default:CheckIn:D] Running Loop=1
2025-05-12T18:33:36.754Z,1747074816.754 [Default:CheckIn:D] Stopped
2025-05-12T18:33:36.754Z,1747074816.754 [Default:CheckIn:E] Running Loop=1
2025-05-12T18:33:37.139Z,1747074817.139 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.653398 min
2025-05-12T18:33:37.139Z,1747074817.139 [Default:CheckIn:E] Stopped
2025-05-12T18:33:37.139Z,1747074817.139 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-12T18:33:37.139Z,1747074817.139 [Default:CheckIn] Stopped
2025-05-12T18:33:37.139Z,1747074817.139 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-12T18:33:37.139Z,1747074817.139 [Default:CheckIn](INFO): Running loop #7
2025-05-12T18:33:37.139Z,1747074817.139 [Default:CheckIn] Running Loop=7
2025-05-12T18:33:37.140Z,1747074817.140 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-12T18:33:37.140Z,1747074817.140 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-12T18:33:57.317Z,1747074837.317 [NAL9602](INFO): SBD MO Status=2, MOMSN=19433, MT Status=2, MTMSN=0
2025-05-12T18:33:57.317Z,1747074837.317 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-12T18:34:14.284Z,1747074854.284 [NAL9602](INFO): SBD MO Status=2, MOMSN=19433, MT Status=2, MTMSN=0
2025-05-12T18:34:14.284Z,1747074854.284 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-12T18:34:31.660Z,1747074871.660 [NAL9602](INFO): SBD MO Status=2, MOMSN=19433, MT Status=2, MTMSN=0
2025-05-12T18:34:31.661Z,1747074871.661 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-12T18:35:03.168Z,1747074903.168 [NAL9602](INFO): SBD MO Status=2, MOMSN=19433, MT Status=2, MTMSN=0
2025-05-12T18:35:03.168Z,1747074903.168 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-12T18:35:20.140Z,1747074920.140 [NAL9602](INFO): SBD MO Status=2, MOMSN=19433, MT Status=2, MTMSN=0
2025-05-12T18:35:20.140Z,1747074920.140 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-12T18:35:51.649Z,1747074951.649 [NAL9602](INFO): SBD MO Status=1, MOMSN=19433, MT Status=0, MTMSN=0
2025-05-12T18:35:51.649Z,1747074951.649 [NAL9602](INFO): No messages in MT queue
2025-05-12T18:35:51.650Z,1747074951.650 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2025-05-12T18:35:52.871Z,1747074952.871 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183551.00,A,3648.15220,N,12147.20897,W,0.233,0.00,120525,,,A*7C
2025-05-12T18:35:52.874Z,1747074952.874 [NAL9602](INFO): GPS fix at 20250512T183551: (36.802537, -121.786816)
2025-05-12T18:35:52.898Z,1747074952.898 [Default:CheckIn:Read_GPS] Stopped
2025-05-12T18:35:52.898Z,1747074952.898 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-12T18:35:56.477Z,1747074956.477 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250512T172749/Courier0022.lzma
2025-05-12T18:35:57.291Z,1747074957.291 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Courier0022.lzma.bak
2025-05-12T18:35:57.291Z,1747074957.291 [DataOverHttps](INFO): SBD MOMSN=24855093
2025-05-12T18:36:13.197Z,1747074973.197 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250512T172749/Courier0025.lzma
2025-05-12T18:36:14.199Z,1747074974.199 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Courier0025.lzma.bak
2025-05-12T18:36:14.199Z,1747074974.199 [DataOverHttps](INFO): SBD MOMSN=24855096
2025-05-12T18:36:25.278Z,1747074985.278 [NAL9602](INFO): Powering down
2025-05-12T18:36:30.083Z,1747074990.083 [DataOverHttps](INFO): Sending 866 bytes from file Logs/20250512T172749/Express0023.lzma
2025-05-12T18:36:31.083Z,1747074991.083 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Express0023.lzma.bak
2025-05-12T18:36:31.083Z,1747074991.083 [DataOverHttps](INFO): SBD MOMSN=24855098
2025-05-12T18:36:47.093Z,1747075007.093 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250512T172749/Express0026.lzma
2025-05-12T18:36:48.095Z,1747075008.095 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Express0026.lzma.bak
2025-05-12T18:36:48.095Z,1747075008.095 [DataOverHttps](INFO): SBD MOMSN=24855128
2025-05-12T18:36:49.261Z,1747075009.261 [Default:CheckIn:Read_Iridium] Stopped
2025-05-12T18:36:49.261Z,1747075009.261 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-12T18:36:49.261Z,1747075009.261 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-12T18:41:49.845Z,1747075309.845 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-12T18:41:49.845Z,1747075309.845 [Default:CheckIn:C.Wait] Stopped
2025-05-12T18:41:49.845Z,1747075309.845 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-12T18:41:49.846Z,1747075309.846 [Default:CheckIn:D] Running Loop=1
2025-05-12T18:41:50.260Z,1747075310.260 [Default:CheckIn:D] Stopped
2025-05-12T18:41:50.260Z,1747075310.260 [Default:CheckIn:E] Running Loop=1
2025-05-12T18:41:50.648Z,1747075310.648 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.878507 min
2025-05-12T18:41:50.648Z,1747075310.648 [Default:CheckIn:E] Stopped
2025-05-12T18:41:50.648Z,1747075310.648 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-12T18:41:50.648Z,1747075310.648 [Default:CheckIn] Stopped
2025-05-12T18:41:50.648Z,1747075310.648 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-12T18:41:50.648Z,1747075310.648 [Default:CheckIn](INFO): Running loop #8
2025-05-12T18:41:50.648Z,1747075310.648 [Default:CheckIn] Running Loop=8
2025-05-12T18:41:50.648Z,1747075310.648 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-12T18:41:50.649Z,1747075310.649 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-12T18:41:51.442Z,1747075311.442 [NAL9602](INFO): Powering up
2025-05-12T18:42:10.047Z,1747075330.047 [NAL9602](INFO): NAL9602 initialized
2025-05-12T18:42:11.284Z,1747075331.284 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184210.00,A,3648.15281,N,12147.22140,W,0.292,128.34,120525,,,A*74
2025-05-12T18:42:11.286Z,1747075331.286 [NAL9602](INFO): GPS fix at 20250512T184210: (36.802547, -121.787023)
2025-05-12T18:42:11.322Z,1747075331.322 [Default:CheckIn:Read_GPS] Stopped
2025-05-12T18:42:11.322Z,1747075331.322 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-12T18:42:15.317Z,1747075335.317 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250512T172749/Courier0028.lzma
2025-05-12T18:42:16.320Z,1747075336.320 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Courier0028.lzma.bak
2025-05-12T18:42:16.320Z,1747075336.320 [DataOverHttps](INFO): SBD MOMSN=24855149
2025-05-12T18:42:18.229Z,1747075338.229 [NAL9602](INFO): SBD MO Status=1, MOMSN=19435, MT Status=0, MTMSN=0
2025-05-12T18:42:18.229Z,1747075338.229 [NAL9602](INFO): No messages in MT queue
2025-05-12T18:42:32.329Z,1747075352.329 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250512T172749/Express0029.lzma
2025-05-12T18:42:33.331Z,1747075353.331 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Express0029.lzma.bak
2025-05-12T18:42:33.331Z,1747075353.331 [DataOverHttps](INFO): SBD MOMSN=24855152
2025-05-12T18:42:34.412Z,1747075354.412 [Default:CheckIn:Read_Iridium] Stopped
2025-05-12T18:42:34.412Z,1747075354.412 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-12T18:42:34.412Z,1747075354.412 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-12T18:42:48.930Z,1747075368.930 [NAL9602](INFO): Powering down
2025-05-12T18:42:51.760Z,1747075371.760 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2025-05-12T18:42:51.783Z,1747075371.783 [BPC1](INFO): Received data from all battery sticks.
2025-05-12T18:47:35.027Z,1747075655.027 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-12T18:47:35.027Z,1747075655.027 [Default:CheckIn:C.Wait] Stopped
2025-05-12T18:47:35.027Z,1747075655.027 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-12T18:47:35.027Z,1747075655.027 [Default:CheckIn:D] Running Loop=1
2025-05-12T18:47:35.440Z,1747075655.440 [Default:CheckIn:D] Stopped
2025-05-12T18:47:35.440Z,1747075655.440 [Default:CheckIn:E] Running Loop=1
2025-05-12T18:47:35.856Z,1747075655.856 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.631502 min
2025-05-12T18:47:35.857Z,1747075655.857 [Default:CheckIn:E] Stopped
2025-05-12T18:47:35.857Z,1747075655.857 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-12T18:47:35.857Z,1747075655.857 [Default:CheckIn] Stopped
2025-05-12T18:47:35.857Z,1747075655.857 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-12T18:47:35.858Z,1747075655.858 [Default:CheckIn](INFO): Running loop #9
2025-05-12T18:47:35.858Z,1747075655.858 [Default:CheckIn] Running Loop=9
2025-05-12T18:47:35.858Z,1747075655.858 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-12T18:47:35.858Z,1747075655.858 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-12T18:47:36.626Z,1747075656.626 [NAL9602](INFO): Powering up
2025-05-12T18:47:47.531Z,1747075667.531 [NAL9602](INFO): NAL9602 initialized
2025-05-12T18:47:48.748Z,1747075668.748 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184608.00,A,3648.11023,N,12147.15336,W,0.952,0.00,120525,,,A*7B
2025-05-12T18:47:48.751Z,1747075668.751 [NAL9602](INFO): GPS fix at 20250512T184608: (36.801837, -121.785889)
2025-05-12T18:47:48.760Z,1747075668.760 [Default:CheckIn:Read_GPS] Stopped
2025-05-12T18:47:48.760Z,1747075668.760 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-12T18:47:56.417Z,1747075676.417 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20250512T172749/Courier0031.lzma
2025-05-12T18:47:57.419Z,1747075677.419 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Courier0031.lzma.bak
2025-05-12T18:47:57.419Z,1747075677.419 [DataOverHttps](INFO): SBD MOMSN=24855170
2025-05-12T18:48:03.696Z,1747075683.696 [NAL9602](INFO): SBD MO Status=1, MOMSN=19437, MT Status=0, MTMSN=0
2025-05-12T18:48:03.696Z,1747075683.696 [NAL9602](INFO): No messages in MT queue
2025-05-12T18:48:13.385Z,1747075693.385 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250512T172749/Express0032.lzma
2025-05-12T18:48:14.387Z,1747075694.387 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Express0032.lzma.bak
2025-05-12T18:48:14.388Z,1747075694.388 [DataOverHttps](INFO): SBD MOMSN=24855179
2025-05-12T18:48:15.435Z,1747075695.435 [Default:CheckIn:Read_Iridium] Stopped
2025-05-12T18:48:15.435Z,1747075695.435 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-12T18:48:15.436Z,1747075695.436 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-12T18:48:34.403Z,1747075714.403 [NAL9602](INFO): Powering down
2025-05-12T18:48:58.411Z,1747075738.411 [DataOverHttps](IMPORTANT): SBD MTMSN=20250512T184857
2025-05-12T18:49:05.803Z,1747075745.803 [DataOverHttps](INFO): Received command: ibit
2025-05-12T18:49:05.870Z,1747075745.870 [CommandExec](IMPORTANT): got command ibit
2025-05-12T18:49:05.950Z,1747075745.950 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-05-12T18:49:05.950Z,1747075745.950 [IBIT](IMPORTANT): Beginning control surface checks.
2025-05-12T18:49:05.958Z,1747075745.958 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-05-12T18:49:06.336Z,1747075746.336 [NAL9602](INFO): Powering up
2025-05-12T18:49:10.525Z,1747075750.525 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006755
CHAN A1 (24V): -0.000243
CHAN A2 (12V): -0.005473
CHAN A3 (5V): -0.002545
CHAN B0 (3.3V): -0.001189
CHAN B1 (3.15aV): -0.001509
CHAN B2 (3.15bV): -0.001315
CHAN B3 (GND): -0.000657
OPEN: 0.004411
Full Scale: +/- 1 mA
2025-05-12T18:49:17.227Z,1747075757.227 [NAL9602](INFO): NAL9602 initialized
2025-05-12T18:50:56.633Z,1747075856.633 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2025-05-12T18:50:57.040Z,1747075857.040 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2025-05-12T18:50:57.042Z,1747075857.042 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-05-12T18:50:57.043Z,1747075857.043 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-05-12T18:50:57.453Z,1747075857.453 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-05-12T18:50:57.453Z,1747075857.453 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-05-12T18:50:57.454Z,1747075857.454 [IBIT](IMPORTANT): Pressure:9.956504 PSI
2025-05-12T18:50:57.454Z,1747075857.454 [IBIT](IMPORTANT): Humidity:10.529850 %
2025-05-12T18:50:57.867Z,1747075857.867 [IBIT](IMPORTANT): Vehicle Pitch:1.943694 degrees
2025-05-12T18:50:57.867Z,1747075857.867 [IBIT](IMPORTANT): Vehicle Roll:1.503512 degrees
2025-05-12T18:50:57.867Z,1747075857.867 [IBIT](IMPORTANT): Vehicle Heading:207.143692 degrees
2025-05-12T18:50:58.254Z,1747075858.254 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-05-12T18:50:58.254Z,1747075858.254 [IBIT](IMPORTANT): buoyancyNeutral: 213.529739 cc
2025-05-12T18:50:58.254Z,1747075858.254 [IBIT](IMPORTANT): massDefault: 2.543790 cm
2025-05-12T18:50:58.255Z,1747075858.255 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2025-05-12T18:50:58.255Z,1747075858.255 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-05-12T18:50:58.255Z,1747075858.255 [IBIT](IMPORTANT): IBIT FAILED
2025-05-12T18:51:12.784Z,1747075872.784 [NAL9602](INFO): SBD MO Status=0, MOMSN=19438, MT Status=0, MTMSN=0
2025-05-12T18:51:12.784Z,1747075872.784 [NAL9602](INFO): No messages in MT queue
2025-05-12T18:51:43.481Z,1747075903.481 [NAL9602](INFO): Powering down
2025-05-12T18:53:16.030Z,1747075996.030 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-12T18:53:16.030Z,1747075996.030 [Default:CheckIn:C.Wait] Stopped
2025-05-12T18:53:16.031Z,1747075996.031 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-12T18:53:16.031Z,1747075996.031 [Default:CheckIn:D] Running Loop=1
2025-05-12T18:53:16.445Z,1747075996.445 [Default:CheckIn:D] Stopped
2025-05-12T18:53:16.445Z,1747075996.445 [Default:CheckIn:E] Running Loop=1
2025-05-12T18:53:16.844Z,1747075996.844 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.314917 min
2025-05-12T18:53:16.844Z,1747075996.844 [Default:CheckIn:E] Stopped
2025-05-12T18:53:16.844Z,1747075996.844 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-12T18:53:16.844Z,1747075996.844 [Default:CheckIn] Stopped
2025-05-12T18:53:16.844Z,1747075996.844 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-12T18:53:16.844Z,1747075996.844 [Default:CheckIn](INFO): Running loop #10
2025-05-12T18:53:16.844Z,1747075996.844 [Default:CheckIn] Running Loop=10
2025-05-12T18:53:16.844Z,1747075996.844 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-12T18:53:16.845Z,1747075996.845 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-12T18:53:17.637Z,1747075997.637 [NAL9602](INFO): Powering up
2025-05-12T18:53:28.551Z,1747076008.551 [NAL9602](INFO): NAL9602 initialized
2025-05-12T18:53:58.449Z,1747076038.449 [NAL9602](INFO): SBD MO Status=2, MOMSN=19439, MT Status=2, MTMSN=0
2025-05-12T18:53:58.449Z,1747076038.449 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-12T18:54:17.028Z,1747076057.028 [NAL9602](INFO): SBD MO Status=2, MOMSN=19439, MT Status=2, MTMSN=0
2025-05-12T18:54:17.028Z,1747076057.028 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-12T18:54:25.936Z,1747076065.936 [NAL9602](INFO): SBD MO Status=0, MOMSN=19439, MT Status=0, MTMSN=0
2025-05-12T18:54:25.936Z,1747076065.936 [NAL9602](INFO): No messages in MT queue
2025-05-12T18:54:27.130Z,1747076067.130 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185425.00,A,3648.15479,N,12147.22130,W,0.039,0.00,120525,,,A*7C
2025-05-12T18:54:27.132Z,1747076067.132 [NAL9602](INFO): GPS fix at 20250512T185425: (36.802580, -121.787022)
2025-05-12T18:54:27.142Z,1747076067.142 [Default:CheckIn:Read_GPS] Stopped
2025-05-12T18:54:27.142Z,1747076067.142 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-12T18:54:34.553Z,1747076074.553 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250512T172749/Courier0034.lzma
2025-05-12T18:54:35.555Z,1747076075.555 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Courier0034.lzma.bak
2025-05-12T18:54:35.556Z,1747076075.556 [DataOverHttps](INFO): SBD MOMSN=24855205
2025-05-12T18:54:51.346Z,1747076091.346 [DataOverHttps](INFO): Sending 775 bytes from file Logs/20250512T172749/Express0035.lzma
2025-05-12T18:54:52.347Z,1747076092.347 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Express0035.lzma.bak
2025-05-12T18:54:52.347Z,1747076092.347 [DataOverHttps](INFO): SBD MOMSN=24855208
2025-05-12T18:54:59.449Z,1747076099.449 [NAL9602](INFO): Powering down
2025-05-12T18:55:08.325Z,1747076108.325 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250512T172749/Express0038.lzma
2025-05-12T18:55:09.327Z,1747076109.327 [DataOverHttps](INFO): Moved sent file to Logs/20250512T172749/Express0038.lzma.bak
2025-05-12T18:55:09.327Z,1747076109.327 [DataOverHttps](INFO): SBD MOMSN=24855235
2025-05-12T18:55:10.379Z,1747076110.379 [Default:CheckIn:Read_Iridium] Stopped
2025-05-12T18:55:10.379Z,1747076110.379 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-12T18:55:10.379Z,1747076110.379 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-12T18:57:41.970Z,1747076261.970 [DataOverHttps](IMPORTANT): SBD MTMSN=20250512T185741
2025-05-12T18:57:49.393Z,1747076269.393 [DataOverHttps](INFO): Received command: restart sys
2025-05-12T18:57:49.411Z,1747076269.411 [CommandExec](IMPORTANT): got command restart system
2025-05-12T18:57:51.481Z,1747076271.481 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:57:51.482Z,1747076271.482 [CommandExec](INFO): Uninitializing the command executive.
2025-05-12T18:57:51.482Z,1747076271.482 [CommandExec](INFO): Uninitializing the command scheduler.
2025-05-12T18:57:51.482Z,1747076271.482 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:57:51.633Z,1747076271.633 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-05-12T18:57:51.633Z,1747076271.633 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-05-12T18:57:51.633Z,1747076271.633 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:57:51.634Z,1747076271.634 [NavChartDb](INFO): Join timeout helper Thread ID is 7394
2025-05-12T18:57:51.661Z,1747076271.661 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:57:51.662Z,1747076271.662 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:57:51.673Z,1747076271.673 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-05-12T18:57:51.673Z,1747076271.673 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:57:51.674Z,1747076271.674 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7395
2025-05-12T18:57:52.126Z,1747076272.126 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T18:57:52.127Z,1747076272.127 [WetLabsBB2FL](INFO): Powering down
2025-05-12T18:57:52.127Z,1747076272.127 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:57:52.133Z,1747076272.133 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-05-12T18:57:52.133Z,1747076272.133 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-12T18:57:52.134Z,1747076272.134 [CTD_Seabird](INFO): Join timeout helper Thread ID