2025-05-12T16:10:59.700Z,1747066259.700 [Supervisor](DEBUG): Initializing supervisor.
2025-05-12T16:10:59.704Z,1747066259.704 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-05-12T16:10:59.705Z,1747066259.705 [SyncHandler](INFO): Protected caller Thread ID is 837
2025-05-12T16:10:59.705Z,1747066259.705 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-05-12T16:10:59.706Z,1747066259.706 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-05-12T16:10:59.707Z,1747066259.707 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2025-05-12T16:10:59.711Z,1747066259.711 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-05-12T16:10:59.731Z,1747066259.731 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-05-12T16:10:59.732Z,1747066259.732 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-05-12T16:10:59.732Z,1747066259.732 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2025-05-12T16:10:59.737Z,1747066259.737 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-05-12T16:10:59.738Z,1747066259.738 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-05-12T16:10:59.738Z,1747066259.738 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2025-05-12T16:10:59.740Z,1747066259.740 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-05-12T16:10:59.741Z,1747066259.741 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-05-12T16:10:59.742Z,1747066259.742 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2025-05-12T16:10:59.746Z,1747066259.746 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-05-12T16:10:59.746Z,1747066259.746 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-05-12T16:10:59.750Z,1747066259.750 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-05-12T16:10:59.980Z,1747066259.980 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-05-12T16:10:59.980Z,1747066259.980 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-05-12T16:11:00.101Z,1747066260.101 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-05-12T16:11:00.103Z,1747066260.103 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-05-12T16:11:00.210Z,1747066260.210 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-05-12T16:11:00.212Z,1747066260.212 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-05-12T16:11:00.749Z,1747066260.749 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-05-12T16:11:00.751Z,1747066260.751 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-05-12T16:11:01.132Z,1747066261.132 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-05-12T16:11:01.132Z,1747066261.132 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-05-12T16:11:01.591Z,1747066261.591 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-05-12T16:11:01.592Z,1747066261.592 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-05-12T16:11:01.798Z,1747066261.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-05-12T16:11:01.799Z,1747066261.799 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-05-12T16:11:01.890Z,1747066261.890 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-05-12T16:11:01.985Z,1747066261.985 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-05-12T16:11:02.430Z,1747066262.430 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-05-12T16:11:02.430Z,1747066262.430 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-05-12T16:11:03.107Z,1747066263.107 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-05-12T16:11:03.108Z,1747066263.108 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-05-12T16:11:03.697Z,1747066263.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-05-12T16:11:03.698Z,1747066263.698 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-05-12T16:11:03.954Z,1747066263.954 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-05-12T16:11:03.956Z,1747066263.956 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-05-12T16:11:04.219Z,1747066264.219 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-05-12T16:11:04.220Z,1747066264.220 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-05-12T16:11:04.376Z,1747066264.376 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-05-12T16:11:04.378Z,1747066264.378 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-05-12T16:11:05.376Z,1747066265.376 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-05-12T16:11:05.378Z,1747066265.378 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/
2025-05-12T16:11:05.381Z,1747066265.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg
2025-05-12T16:11:05.478Z,1747066265.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg
2025-05-12T16:11:05.625Z,1747066265.625 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg
2025-05-12T16:11:05.912Z,1747066265.912 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-05-12T16:11:05.914Z,1747066265.914 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg
2025-05-12T16:11:06.055Z,1747066266.055 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg
2025-05-12T16:11:06.167Z,1747066266.167 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg
2025-05-12T16:11:06.275Z,1747066266.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg
2025-05-12T16:11:06.397Z,1747066266.397 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg
2025-05-12T16:11:06.494Z,1747066266.494 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg
2025-05-12T16:11:06.593Z,1747066266.593 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg
2025-05-12T16:11:06.697Z,1747066266.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg
2025-05-12T16:11:06.887Z,1747066266.887 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/
2025-05-12T16:11:06.887Z,1747066266.887 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-05-12T16:11:06.903Z,1747066266.903 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-05-12T16:11:06.979Z,1747066266.979 [DepthRateCalculator] Loaded
2025-05-12T16:11:06.980Z,1747066266.980 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-05-12T16:11:06.986Z,1747066266.986 [PitchRateCalculator] Loaded
2025-05-12T16:11:06.986Z,1747066266.986 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-05-12T16:11:06.997Z,1747066266.997 [SpeedCalculator] Loaded
2025-05-12T16:11:06.997Z,1747066266.997 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-05-12T16:11:07.002Z,1747066267.002 [YawRateCalculator] Loaded
2025-05-12T16:11:07.003Z,1747066267.003 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-05-12T16:11:07.025Z,1747066267.025 [ElevatorOffsetCalculator] Loaded
2025-05-12T16:11:07.025Z,1747066267.025 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-05-12T16:11:07.026Z,1747066267.026 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-05-12T16:11:07.026Z,1747066267.026 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-05-12T16:11:07.075Z,1747066267.075 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-05-12T16:11:07.075Z,1747066267.075 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-05-12T16:11:07.091Z,1747066267.091 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-05-12T16:11:07.092Z,1747066267.092 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-05-12T16:11:07.961Z,1747066267.961 [AHRS_M2] Loaded
2025-05-12T16:11:07.961Z,1747066267.961 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-05-12T16:11:08.334Z,1747066268.334 [BackseatComponent] Loaded
2025-05-12T16:11:08.335Z,1747066268.335 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-05-12T16:11:08.336Z,1747066268.336 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408754E0
2025-05-12T16:11:08.336Z,1747066268.336 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 925
2025-05-12T16:11:08.339Z,1747066268.339 [LcmUniversalReporter] Loaded
2025-05-12T16:11:08.339Z,1747066268.339 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-05-12T16:11:09.564Z,1747066269.564 [BPC1] Loaded
2025-05-12T16:11:09.564Z,1747066269.564 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-05-12T16:11:09.662Z,1747066269.662 [DAT] Loaded
2025-05-12T16:11:09.662Z,1747066269.662 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-05-12T16:11:09.663Z,1747066269.663 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408A54E0
2025-05-12T16:11:09.664Z,1747066269.664 [DAT ThreadHandler](INFO): Protected caller Thread ID is 926
2025-05-12T16:11:09.812Z,1747066269.812 [DataOverHttps] Loaded
2025-05-12T16:11:09.812Z,1747066269.812 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-05-12T16:11:09.813Z,1747066269.813 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408D54E0
2025-05-12T16:11:09.814Z,1747066269.814 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 927
2025-05-12T16:11:09.837Z,1747066269.837 [Depth_Keller] Loaded
2025-05-12T16:11:09.837Z,1747066269.837 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-05-12T16:11:09.842Z,1747066269.842 [DropWeight] Loaded
2025-05-12T16:11:09.843Z,1747066269.843 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-05-12T16:11:09.864Z,1747066269.864 [MultiRay](INFO): Found secondary power supply at: /dev/loadC7
2025-05-12T16:11:09.865Z,1747066269.865 [MultiRay] Loaded
2025-05-12T16:11:09.865Z,1747066269.865 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread.
2025-05-12T16:11:09.931Z,1747066269.931 [NAL9602] Loaded
2025-05-12T16:11:09.931Z,1747066269.931 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-05-12T16:11:09.962Z,1747066269.962 [Onboard] Loaded
2025-05-12T16:11:09.962Z,1747066269.962 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-05-12T16:11:09.963Z,1747066269.963 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409054E0
2025-05-12T16:11:09.964Z,1747066269.964 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 928
2025-05-12T16:11:09.970Z,1747066269.970 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2025-05-12T16:11:09.983Z,1747066269.983 [PowerOnly] Loaded
2025-05-12T16:11:09.984Z,1747066269.984 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-05-12T16:11:09.998Z,1747066269.998 [Power24vConverter] Loaded
2025-05-12T16:11:09.998Z,1747066269.998 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-05-12T16:11:10.016Z,1747066270.016 [Radio_Surface] Loaded
2025-05-12T16:11:10.016Z,1747066270.016 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-05-12T16:11:10.017Z,1747066270.017 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409354E0
2025-05-12T16:11:10.018Z,1747066270.018 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 929
2025-05-12T16:11:10.043Z,1747066270.043 [Waterlinked] Loaded
2025-05-12T16:11:10.044Z,1747066270.044 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread.
2025-05-12T16:11:10.044Z,1747066270.044 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-05-12T16:11:10.045Z,1747066270.045 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-05-12T16:11:10.190Z,1747066270.190 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-05-12T16:11:10.190Z,1747066270.190 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-05-12T16:11:10.204Z,1747066270.204 [NavChart] Loaded
2025-05-12T16:11:10.204Z,1747066270.204 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-05-12T16:11:10.210Z,1747066270.210 [UniversalFixResidualReporter] Loaded
2025-05-12T16:11:10.211Z,1747066270.211 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-05-12T16:11:10.211Z,1747066270.211 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-05-12T16:11:10.212Z,1747066270.212 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-05-12T16:11:10.420Z,1747066270.420 [SBIT](DEBUG): Construct Startup Built In Test.
2025-05-12T16:11:10.435Z,1747066270.435 [SBIT] Loaded
2025-05-12T16:11:10.436Z,1747066270.436 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-05-12T16:11:10.439Z,1747066270.439 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-05-12T16:11:10.452Z,1747066270.452 [IBIT] Loaded
2025-05-12T16:11:10.452Z,1747066270.452 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-05-12T16:11:10.458Z,1747066270.458 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-05-12T16:11:10.541Z,1747066270.541 [CBIT] Loaded
2025-05-12T16:11:10.541Z,1747066270.541 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-05-12T16:11:10.574Z,1747066270.574 [GFScanner] Loaded
2025-05-12T16:11:10.574Z,1747066270.574 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-05-12T16:11:10.575Z,1747066270.575 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-05-12T16:11:10.575Z,1747066270.575 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-05-12T16:11:10.801Z,1747066270.801 [CTD_Seabird] Loaded
2025-05-12T16:11:10.801Z,1747066270.801 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-05-12T16:11:10.802Z,1747066270.802 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A474E0
2025-05-12T16:11:10.803Z,1747066270.803 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 930
2025-05-12T16:11:10.826Z,1747066270.826 [PAR_Licor] Loaded
2025-05-12T16:11:10.826Z,1747066270.826 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-05-12T16:11:10.874Z,1747066270.874 [WetLabsBB2FL] Loaded
2025-05-12T16:11:10.875Z,1747066270.875 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-05-12T16:11:10.876Z,1747066270.876 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A774E0
2025-05-12T16:11:10.876Z,1747066270.876 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 931
2025-05-12T16:11:10.877Z,1747066270.877 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-05-12T16:11:10.878Z,1747066270.878 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-05-12T16:11:11.056Z,1747066271.056 [BuoyancyServo] Loaded
2025-05-12T16:11:11.056Z,1747066271.056 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-05-12T16:11:11.081Z,1747066271.081 [ElevatorServo] Loaded
2025-05-12T16:11:11.081Z,1747066271.081 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-05-12T16:11:11.105Z,1747066271.105 [MassServo] Loaded
2025-05-12T16:11:11.105Z,1747066271.105 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-05-12T16:11:11.129Z,1747066271.129 [RudderServo] Loaded
2025-05-12T16:11:11.129Z,1747066271.129 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-05-12T16:11:11.148Z,1747066271.148 [ThrusterHE] Loaded
2025-05-12T16:11:11.148Z,1747066271.148 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-05-12T16:11:11.149Z,1747066271.149 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-05-12T16:11:11.149Z,1747066271.149 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-05-12T16:11:11.227Z,1747066271.227 [VerticalControl](DEBUG): Construct VerticalControl.
2025-05-12T16:11:11.281Z,1747066271.281 [VerticalControl] Loaded
2025-05-12T16:11:11.282Z,1747066271.282 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-05-12T16:11:11.284Z,1747066271.284 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-05-12T16:11:11.330Z,1747066271.330 [HorizontalControl] Loaded
2025-05-12T16:11:11.330Z,1747066271.330 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-05-12T16:11:11.333Z,1747066271.333 [SpeedControl](DEBUG): Construct SpeedControl.
2025-05-12T16:11:11.334Z,1747066271.334 [SpeedControl] Loaded
2025-05-12T16:11:11.335Z,1747066271.335 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-05-12T16:11:11.338Z,1747066271.338 [LoopControl](DEBUG): Construct LoopControl.
2025-05-12T16:11:11.338Z,1747066271.338 [LoopControl] Loaded
2025-05-12T16:11:11.339Z,1747066271.339 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-05-12T16:11:11.339Z,1747066271.339 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-05-12T16:11:11.340Z,1747066271.340 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-05-12T16:11:11.512Z,1747066271.512 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-05-12T16:11:11.513Z,1747066271.513 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-05-12T16:11:11.745Z,1747066271.745 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-05-12T16:11:11.746Z,1747066271.746 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-05-12T16:11:12.182Z,1747066272.182 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-05-12T16:11:12.182Z,1747066272.182 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-05-12T16:11:12.281Z,1747066272.281 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-05-12T16:11:12.287Z,1747066272.287 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-05-12T16:11:12.290Z,1747066272.290 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-05-12T16:11:12.302Z,1747066272.302 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-05-12T16:11:12.303Z,1747066272.303 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0
2025-05-12T16:11:12.304Z,1747066272.304 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 932
2025-05-12T16:11:12.308Z,1747066272.308 [Supervisor](INFO): Main Thread ID is 829
2025-05-12T16:11:12.308Z,1747066272.308 [Supervisor](DEBUG): Running supervisor.
2025-05-12T16:11:12.309Z,1747066272.309 [CommandExec ThreadHandler](INFO): Handler Thread ID is 933
2025-05-12T16:11:12.309Z,1747066272.309 [CommandExec](INFO): Initializing the command executive.
2025-05-12T16:11:12.311Z,1747066272.311 [CommandLine ThreadHandler](INFO): Handler Thread ID is 934
2025-05-12T16:11:12.313Z,1747066272.313 [controlThread ThreadHandler](INFO): Handler Thread ID is 935
2025-05-12T16:11:12.314Z,1747066272.314 [controlThread](DEBUG): Initializing ControlThread
2025-05-12T16:11:12.315Z,1747066272.315 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-05-12T16:11:12.315Z,1747066272.315 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-05-12T16:11:12.315Z,1747066272.315 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-05-12T16:11:12.316Z,1747066272.316 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-05-12T16:11:12.316Z,1747066272.316 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-05-12T16:11:12.320Z,1747066272.320 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-05-12T16:11:12.320Z,1747066272.320 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-05-12T16:11:12.321Z,1747066272.321 [SBIT](INFO): Initialize SBIT Component.
2025-05-12T16:11:12.321Z,1747066272.321 [SBIT](IMPORTANT): git: 2025-05-09_A
2025-05-12T16:11:12.321Z,1747066272.321 [SBIT](INFO): git hash: 6b5522692da198ea3f5d9839d63b9535ae041ee0
2025-05-12T16:11:12.322Z,1747066272.322 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-05-12T16:11:12.323Z,1747066272.323 [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-12T16:11:12.324Z,1747066272.324 [SBIT](INFO): Beginning SBIT in 121.000000 seconds.
2025-05-12T16:11:12.324Z,1747066272.324 [IBIT](INFO): Initialize IBIT Component.
2025-05-12T16:11:12.325Z,1747066272.325 [CBIT](DEBUG): Initialize CBIT Component.
2025-05-12T16:11:12.326Z,1747066272.326 [logger ThreadHandler](INFO): Handler Thread ID is 936
2025-05-12T16:11:12.337Z,1747066272.337 [CBIT](DEBUG): Initialized mux pins.
2025-05-12T16:11:12.337Z,1747066272.337 [CBIT](DEBUG): Initializing the watchdog timer.
2025-05-12T16:11:12.346Z,1747066272.346 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 937
2025-05-12T16:11:12.361Z,1747066272.361 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-05-12T16:11:12.362Z,1747066272.362 [CBIT](DEBUG): Initializing heartbeat.
2025-05-12T16:11:12.362Z,1747066272.362 [DAT ThreadHandler](INFO): Handler Thread ID is 938
2025-05-12T16:11:12.363Z,1747066272.363 [DAT](INFO): Powering up
2025-05-12T16:11:12.363Z,1747066272.363 [DAT](DEBUG): Initializing DAT.
2025-05-12T16:11:12.367Z,1747066272.367 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 940
2025-05-12T16:11:12.368Z,1747066272.368 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-05-12T16:11:12.390Z,1747066272.390 [Onboard ThreadHandler](INFO): Handler Thread ID is 941
2025-05-12T16:11:12.407Z,1747066272.407 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 942
2025-05-12T16:11:12.430Z,1747066272.430 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 943
2025-05-12T16:11:12.430Z,1747066272.430 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-05-12T16:11:12.433Z,1747066272.433 [CBIT](DEBUG): Deactivating emergency mode.
2025-05-12T16:11:12.435Z,1747066272.435 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 945
2025-05-12T16:11:12.438Z,1747066272.438 [WetLabsBB2FL](INFO): Powering up
2025-05-12T16:11:12.439Z,1747066272.439 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 947
2025-05-12T16:11:12.446Z,1747066272.446 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-05-12T16:11:12.446Z,1747066272.446 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-05-12T16:11:12.446Z,1747066272.446 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-05-12T16:11:12.447Z,1747066272.447 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-05-12T16:11:12.447Z,1747066272.447 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-05-12T16:11:12.447Z,1747066272.447 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-05-12T16:11:12.447Z,1747066272.447 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-05-12T16:11:12.447Z,1747066272.447 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-05-12T16:11:12.473Z,1747066272.473 [CBIT](DEBUG): Backplane powered.
2025-05-12T16:11:12.473Z,1747066272.473 [GFScanner](DEBUG): Initializing GFScanner
2025-05-12T16:11:12.474Z,1747066272.474 [GFScanner](DEBUG): Deactivating GF circuits.
2025-05-12T16:11:12.483Z,1747066272.483 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-05-12T16:11:12.484Z,1747066272.484 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-05-12T16:11:12.485Z,1747066272.485 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-05-12T16:11:12.486Z,1747066272.486 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-05-12T16:11:12.486Z,1747066272.486 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-05-12T16:11:12.487Z,1747066272.487 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-05-12T16:11:12.487Z,1747066272.487 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-05-12T16:11:12.491Z,1747066272.491 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-05-12T16:11:12.564Z,1747066272.564 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-05-12T16:11:12.568Z,1747066272.568 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-12T16:11:12.592Z,1747066272.592 [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-12T16:11:12.593Z,1747066272.593 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-05-12T16:11:12.593Z,1747066272.593 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-05-12T16:11:12.599Z,1747066272.599 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-05-12T16:11:12.813Z,1747066272.813 [Radio_Surface](INFO): Powering up
2025-05-12T16:11:12.817Z,1747066272.817 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2025-05-12T16:11:12.832Z,1747066272.832 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-05-12T16:11:12.833Z,1747066272.833 [Default:A.Wait](DEBUG): Construct Wait.
2025-05-12T16:11:12.835Z,1747066272.835 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-12T16:11:12.848Z,1747066272.848 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-05-12T16:11:12.867Z,1747066272.867 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-05-12T16:11:12.885Z,1747066272.885 [Default:E.Execute](DEBUG): Construct Execute.
2025-05-12T16:11:12.888Z,1747066272.888 [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-12T16:11:12.893Z,1747066272.893 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,Waterlinked,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-05-12T16:11:12.900Z,1747066272.900 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-05-12T16:11:13.026Z,1747066273.026 [Depth_Keller](INFO): Initializing.
2025-05-12T16:11:13.027Z,1747066273.027 [MultiRay](INFO): Powering up MultiRay Lights
2025-05-12T16:11:13.027Z,1747066273.027 [MultiRay](INFO): Powering up secondary power supply.
2025-05-12T16:11:13.067Z,1747066273.067 [Power24vConverter](INFO): Powering up.
2025-05-12T16:11:13.086Z,1747066273.086 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-05-12T16:11:13.104Z,1747066273.104 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-05-12T16:11:13.110Z,1747066273.110 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-05-12T16:11:13.111Z,1747066273.111 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-05-12T16:11:13.118Z,1747066273.118 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-05-12T16:11:13.119Z,1747066273.119 [MassServo](DEBUG): Initializing EZServoServo.
2025-05-12T16:11:13.125Z,1747066273.125 [MassServo](DEBUG): Initializing MassServo.
2025-05-12T16:11:13.126Z,1747066273.126 [RudderServo](DEBUG): Initializing EZServoServo.
2025-05-12T16:11:13.137Z,1747066273.137 [RudderServo](DEBUG): Initializing RudderServo.
2025-05-12T16:11:13.138Z,1747066273.138 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-05-12T16:11:13.146Z,1747066273.146 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-05-12T16:11:14.114Z,1747066274.114 [MultiRay](INFO): Powering down
2025-05-12T16:11:14.233Z,1747066274.233 [MultiRay](INFO): Powering down LCB2
2025-05-12T16:11:15.049Z,1747066275.049 [WetLabsBB2FL](INFO): Powering down
2025-05-12T16:11:18.818Z,1747066278.818 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-05-12T16:11:24.718Z,1747066284.718 [DAT](INFO): DAT read: 

2025-05-12T16:11:24.720Z,1747066284.720 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-05-12T16:11:26.234Z,1747066286.234 [DAT](INFO): DAT read: MF Frequency Band

2025-05-12T16:11:26.235Z,1747066286.235 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-05-12T16:11:26.236Z,1747066286.236 [DAT](INFO): DAT read: May  9 2025 18:23:45 

2025-05-12T16:11:27.242Z,1747066287.242 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-05-12T16:11:27.244Z,1747066287.244 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2025-05-12T16:11:27.244Z,1747066287.244 [DAT](INFO): commRate: 800
2025-05-12T16:11:29.313Z,1747066289.313 [DAT](INFO): entering command mode
2025-05-12T16:11:29.516Z,1747066289.516 [DAT](INFO): DAT read: 

2025-05-12T16:11:29.516Z,1747066289.516 [DAT](INFO): DAT read: user:1>

2025-05-12T16:11:29.517Z,1747066289.517 [DAT](INFO): setting verbose to 3
2025-05-12T16:11:29.770Z,1747066289.770 [DAT](INFO): DAT read: user:1>

2025-05-12T16:11:29.771Z,1747066289.771 [DAT](INFO): DAT read: Verbose         | 3           

2025-05-12T16:11:29.771Z,1747066289.771 [DAT](INFO): set verbose to 3
2025-05-12T16:11:29.771Z,1747066289.771 [DAT](INFO): setting DatVerbose to 27440
2025-05-12T16:11:30.022Z,1747066290.022 [DAT](INFO): DAT read: user:2>

2025-05-12T16:11:30.023Z,1747066290.023 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-05-12T16:11:30.023Z,1747066290.023 [DAT](INFO): set DatVerbose to 27440
2025-05-12T16:11:30.023Z,1747066290.023 [DAT](INFO): setting transmit power to 8
2025-05-12T16:11:30.274Z,1747066290.274 [DAT](INFO): DAT read: user:3>

2025-05-12T16:11:30.275Z,1747066290.275 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-05-12T16:11:30.275Z,1747066290.275 [DAT](INFO): set transmit power to 8
2025-05-12T16:11:30.276Z,1747066290.276 [DAT](INFO): setting local address to 9
2025-05-12T16:11:30.526Z,1747066290.526 [DAT](INFO): DAT read: user:4>

2025-05-12T16:11:30.527Z,1747066290.527 [DAT](INFO): DAT read: LocalAddr       | 9           

2025-05-12T16:11:30.527Z,1747066290.527 [DAT](INFO): set local address to 9
2025-05-12T16:11:30.528Z,1747066290.528 [DAT](INFO): Setting time to: 16:11:30 And date to:5/12/2025
2025-05-12T16:11:30.778Z,1747066290.778 [DAT](INFO): DAT read: user:5>

2025-05-12T16:11:30.779Z,1747066290.779 [DAT](INFO): DAT read: Mon May 12, 2025  16:11:30  

2025-05-12T16:11:30.780Z,1747066290.780 [DAT](INFO): Local DAT time set to Mon May 12, 2025  16:11:30  

2025-05-12T16:11:40.031Z,1747066300.031 [NAL9602](INFO): Powering up NAL9602
2025-05-12T16:11:41.244Z,1747066301.244 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:11:41.244Z,1747066301.244 [Waterlinked] Communications Fault, FailCount= 1
2025-05-12T16:11:41.244Z,1747066301.244 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:11:41.298Z,1747066301.298 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:11:41.733Z,1747066301.733 [Waterlinked](INFO): Powering down
2025-05-12T16:11:42.505Z,1747066302.505 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:11:42.506Z,1747066302.506 [Waterlinked] No Fault, FailCount= 1
2025-05-12T16:11:50.939Z,1747066310.939 [NAL9602](INFO): NAL9602 initialized
2025-05-12T16:12:11.143Z,1747066331.143 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:12:11.143Z,1747066331.143 [Waterlinked] Communications Fault, FailCount= 2
2025-05-12T16:12:11.143Z,1747066331.143 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:12:11.198Z,1747066331.198 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:12:11.625Z,1747066331.625 [Waterlinked](INFO): Powering down
2025-05-12T16:12:12.401Z,1747066332.401 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:12:12.402Z,1747066332.402 [Waterlinked] No Fault, FailCount= 2
2025-05-12T16:12:35.354Z,1747066355.354 [CommandExec](IMPORTANT): got command failComponent
2025-05-12T16:12:35.354Z,1747066355.354 [CommandExec](IMPORTANT): Failed components:
2025-05-12T16:12:35.354Z,1747066355.354 [CommandExec](IMPORTANT): No failed Components.
2025-05-12T16:12:41.058Z,1747066361.058 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:12:41.058Z,1747066361.058 [Waterlinked] Communications Fault, FailCount= 3
2025-05-12T16:12:41.058Z,1747066361.058 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:12:41.111Z,1747066361.111 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:12:41.112Z,1747066361.112 [CBIT](CRITICAL): Communications Fault in component: Waterlinked
2025-05-12T16:12:41.553Z,1747066361.553 [Waterlinked](INFO): Powering down
2025-05-12T16:12:41.573Z,1747066361.573 [CommandExec](FAULT): Scheduling is paused
2025-05-12T16:12:41.573Z,1747066361.573 [CBIT](INFO): Critical error at 20250512T161241
2025-05-12T16:12:41.574Z,1747066361.574 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-05-12T16:13:13.776Z,1747066393.776 [SBIT](IMPORTANT): Beginning Startup BIT
2025-05-12T16:13:13.805Z,1747066393.805 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-05-12T16:13:18.350Z,1747066398.350 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001785
CHAN A1 (24V): 0.002532
CHAN A2 (12V): -0.005962
CHAN A3 (5V): -0.004097
CHAN B0 (3.3V): -0.001601
CHAN B1 (3.15aV): -0.002452
CHAN B2 (3.15bV): -0.001584
CHAN B3 (GND): -0.000919
OPEN: 0.004744
Full Scale: +/- 1 mA
2025-05-12T16:13:43.833Z,1747066423.833 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-05-12T16:13:55.182Z,1747066435.182 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-05-12T16:13:58.640Z,1747066438.640 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005598
2025-05-12T16:14:07.640Z,1747066447.640 [SBIT](IMPORTANT): SBIT PASSED
2025-05-12T16:14:07.640Z,1747066447.640 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-05-12T16:14:07.641Z,1747066447.641 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;

2025-05-12T16:14:07.642Z,1747066447.642 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;

2025-05-12T16:14:07.642Z,1747066447.642 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool;

2025-05-12T16:14:07.642Z,1747066447.642 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool;

2025-05-12T16:14:07.643Z,1747066447.643 [SBIT](IMPORTANT): MultiRay.brightnessWhite=88 none;

2025-05-12T16:14:07.643Z,1747066447.643 [SBIT](IMPORTANT): MultiRay.loadAtStartup=1 bool;

2025-05-12T16:14:07.643Z,1747066447.643 [SBIT](IMPORTANT): NAL9602.fastGPSFix=0 bool;

2025-05-12T16:14:07.643Z,1747066447.643 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool;

2025-05-12T16:14:07.643Z,1747066447.643 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;

2025-05-12T16:14:07.643Z,1747066447.643 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=213.529736 cubic_centimeter;

2025-05-12T16:14:07.643Z,1747066447.643 [SBIT](IMPORTANT): VerticalControl.massDefault=25.437902 millimeter;

2025-05-12T16:14:08.043Z,1747066448.043 [MissionManager](IMPORTANT): Started mission Startup
2025-05-12T16:14:08.043Z,1747066448.043 [Startup] Running Loop=1
2025-05-12T16:14:08.043Z,1747066448.043 [Startup](DEBUG): Aggregate::initialize Startup
2025-05-12T16:14:08.043Z,1747066448.043 [Startup:A.GoToSurface] Running Loop=1
2025-05-12T16:14:08.043Z,1747066448.043 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-12T16:14:08.044Z,1747066448.044 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-05-12T16:14:08.044Z,1747066448.044 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-05-12T16:14:08.044Z,1747066448.044 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-05-12T16:14:08.045Z,1747066448.045 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-05-12T16:14:08.046Z,1747066448.046 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-12T16:14:08.046Z,1747066448.046 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-12T16:14:08.048Z,1747066448.048 [Startup:StartupSatComms] Running Loop=1
2025-05-12T16:14:08.048Z,1747066448.048 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-05-12T16:14:08.048Z,1747066448.048 [Startup:StartupSatComms:A] Running Loop=1
2025-05-12T16:14:08.461Z,1747066448.461 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-05-12T16:14:12.483Z,1747066452.483 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-05-12T16:14:12.483Z,1747066452.483 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T16:14:12.493Z,1747066452.493 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T16:14:12.900Z,1747066452.900 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T16:14:12.901Z,1747066452.901 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-05-12T16:14:54.134Z,1747066494.134 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-05-12T16:15:08.255Z,1747066508.255 [Startup:StartupSatComms:A](INFO): Timed out from 2025-05-12T16:14:08.0Z
2025-05-12T16:15:08.256Z,1747066508.256 [Startup:StartupSatComms:A] Stopped
2025-05-12T16:15:08.256Z,1747066508.256 [Startup:StartupSatComms:B] Running Loop=1
2025-05-12T16:15:08.667Z,1747066508.667 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-05-12T16:15:17.029Z,1747066517.029 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20250512T161059/Courier0000.lzma
2025-05-12T16:15:18.032Z,1747066518.032 [DataOverHttps](INFO): Moved sent file to Logs/20250512T161059/Courier0000.lzma.bak
2025-05-12T16:15:18.032Z,1747066518.032 [DataOverHttps](INFO): SBD MOMSN=24854034
2025-05-12T16:15:25.402Z,1747066525.402 [CommandExec](IMPORTANT): got command strobe off
2025-05-12T16:15:25.403Z,1747066525.403 [CommandExec](IMPORTANT): Deactivating strobe
2025-05-12T16:15:33.767Z,1747066533.767 [DataOverHttps](INFO): Sending 1018 bytes from file Logs/20250512T161059/Express0001.lzma
2025-05-12T16:15:34.767Z,1747066534.767 [DataOverHttps](INFO): Moved sent file to Logs/20250512T161059/Express0001.lzma.bak
2025-05-12T16:15:34.767Z,1747066534.767 [DataOverHttps](INFO): SBD MOMSN=24854038
2025-05-12T16:15:36.106Z,1747066536.106 [Startup:StartupSatComms:B] Stopped
2025-05-12T16:15:36.106Z,1747066536.106 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-05-12T16:15:36.106Z,1747066536.106 [Startup:StartupSatComms] Stopped
2025-05-12T16:15:36.106Z,1747066536.106 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-05-12T16:15:36.107Z,1747066536.107 [Startup](INFO): Completed Startup
2025-05-12T16:15:36.107Z,1747066536.107 [MissionManager](INFO): Startup is completed.
2025-05-12T16:15:36.107Z,1747066536.107 [MissionManager](INFO): Uninitializing Mission Startup
2025-05-12T16:15:36.107Z,1747066536.107 [Startup] Stopped
2025-05-12T16:15:36.107Z,1747066536.107 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-05-12T16:15:36.108Z,1747066536.108 [Startup:A.GoToSurface] Stopped
2025-05-12T16:15:36.108Z,1747066536.108 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-12T16:15:36.516Z,1747066536.516 [MissionManager](IMPORTANT): Started mission Default
2025-05-12T16:15:36.516Z,1747066536.516 [Default] Running Loop=1
2025-05-12T16:15:36.516Z,1747066536.516 [Default](DEBUG): Aggregate::initialize Default
2025-05-12T16:15:36.516Z,1747066536.516 [Default:B.GoToSurface] Running Loop=1
2025-05-12T16:15:36.516Z,1747066536.516 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-12T16:15:36.516Z,1747066536.516 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-05-12T16:15:36.517Z,1747066536.517 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-05-12T16:15:36.517Z,1747066536.517 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-05-12T16:15:36.517Z,1747066536.517 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-05-12T16:15:36.518Z,1747066536.518 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-12T16:15:36.518Z,1747066536.518 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-12T16:15:36.518Z,1747066536.518 [Default:A.Wait] Running Loop=1
2025-05-12T16:15:36.518Z,1747066536.518 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-05-12T16:15:41.377Z,1747066541.377 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:15:41.377Z,1747066541.377 [Waterlinked] No Fault, FailCount= 3
2025-05-12T16:15:49.868Z,1747066549.868 [Default:A.Wait](INFO): Done Waiting.
2025-05-12T16:15:49.868Z,1747066549.868 [Default:A.Wait] Stopped
2025-05-12T16:15:49.868Z,1747066549.868 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-05-12T16:15:50.280Z,1747066550.280 [Default:CheckIn] Running Loop=1
2025-05-12T16:15:50.280Z,1747066550.280 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-12T16:15:50.280Z,1747066550.280 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-12T16:15:50.676Z,1747066550.676 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-05-12T16:16:10.040Z,1747066570.040 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:16:10.040Z,1747066570.040 [Waterlinked] Communications Fault, FailCount= 1
2025-05-12T16:16:10.040Z,1747066570.040 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:16:10.106Z,1747066570.106 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:16:10.525Z,1747066570.525 [Waterlinked](INFO): Powering down
2025-05-12T16:16:11.289Z,1747066571.289 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:16:11.289Z,1747066571.289 [Waterlinked] No Fault, FailCount= 1
2025-05-12T16:16:12.464Z,1747066572.464 [CommandExec](IMPORTANT): got command get VerticalControl.massPositionLimitFwd
2025-05-12T16:16:12.465Z,1747066572.465 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitFwd 38.000000 mm
2025-05-12T16:16:15.790Z,1747066575.790 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-05-12T16:16:30.294Z,1747066590.294 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-05-12T16:16:39.936Z,1747066599.936 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:16:39.937Z,1747066599.937 [Waterlinked] Communications Fault, FailCount= 2
2025-05-12T16:16:39.937Z,1747066599.937 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:16:40.005Z,1747066600.005 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:16:40.425Z,1747066600.425 [Waterlinked](INFO): Powering down
2025-05-12T16:16:41.192Z,1747066601.192 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:16:41.192Z,1747066601.192 [Waterlinked] No Fault, FailCount= 2
2025-05-12T16:16:41.718Z,1747066601.718 [CommandExec](IMPORTANT): got command get VerticalControl.massPositionLimitFwd
2025-05-12T16:16:41.719Z,1747066601.719 [CommandExec](IMPORTANT): VerticalControl.massPositionLimitFwd 38.000000 mm
2025-05-12T16:16:54.075Z,1747066614.075 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-05-12T16:17:09.846Z,1747066629.846 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:17:09.846Z,1747066629.846 [Waterlinked] Communications Fault, FailCount= 3
2025-05-12T16:17:09.846Z,1747066629.846 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:17:09.868Z,1747066629.868 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:17:09.869Z,1747066629.869 [CBIT](CRITICAL): Communications Fault in component: Waterlinked
2025-05-12T16:17:10.337Z,1747066630.337 [Waterlinked](INFO): Powering down
2025-05-12T16:17:10.368Z,1747066630.368 [CBIT](INFO): Critical error at 20250512T161709
2025-05-12T16:17:12.794Z,1747066632.794 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-05-12T16:17:13.502Z,1747066633.502 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-05-12T16:17:13.502Z,1747066633.502 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T16:17:13.513Z,1747066633.513 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T16:17:13.910Z,1747066633.910 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T16:17:13.910Z,1747066633.910 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-05-12T16:17:22.027Z,1747066642.027 [CommandExec](IMPORTANT): got command schedule resume
2025-05-12T16:17:22.027Z,1747066642.027 [CommandExec](IMPORTANT): Scheduling is resumed
2025-05-12T16:17:57.158Z,1747066677.158 [CommandExec](IMPORTANT): got command burn on
2025-05-12T16:17:57.158Z,1747066677.158 [CommandExec](IMPORTANT): Activating dropweight wire
2025-05-12T16:18:43.555Z,1747066723.555 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-05-12T16:19:18.346Z,1747066758.346 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-05-12T16:19:34.061Z,1747066774.061 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2025-05-12T16:19:34.065Z,1747066774.065 [BPC1](INFO): Received data from all battery sticks.
2025-05-12T16:20:10.041Z,1747066810.041 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:20:10.041Z,1747066810.041 [Waterlinked] No Fault, FailCount= 3
2025-05-12T16:20:14.482Z,1747066814.482 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-05-12T16:20:14.482Z,1747066814.482 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T16:20:14.493Z,1747066814.493 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T16:20:14.899Z,1747066814.899 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T16:20:14.899Z,1747066814.899 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-05-12T16:20:35.746Z,1747066835.746 [CommandExec](IMPORTANT): got command burn off
2025-05-12T16:20:35.746Z,1747066835.746 [CommandExec](IMPORTANT): Deactivating dropweight wire
2025-05-12T16:20:38.704Z,1747066838.704 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:20:38.704Z,1747066838.704 [Waterlinked] Communications Fault, FailCount= 1
2025-05-12T16:20:38.704Z,1747066838.704 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:20:38.720Z,1747066838.720 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:20:39.205Z,1747066839.205 [Waterlinked](INFO): Powering down
2025-05-12T16:20:39.965Z,1747066839.965 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:20:39.965Z,1747066839.965 [Waterlinked] No Fault, FailCount= 1
2025-05-12T16:20:50.432Z,1747066850.432 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-05-12T16:15:50.3Z
2025-05-12T16:20:50.432Z,1747066850.432 [Default:CheckIn:Read_GPS] Stopped
2025-05-12T16:20:50.432Z,1747066850.432 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-12T16:20:50.827Z,1747066850.827 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-05-12T16:20:57.449Z,1747066857.449 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20250512T161059/Courier0004.lzma
2025-05-12T16:20:58.451Z,1747066858.451 [DataOverHttps](INFO): Moved sent file to Logs/20250512T161059/Courier0004.lzma.bak
2025-05-12T16:20:58.451Z,1747066858.451 [DataOverHttps](INFO): SBD MOMSN=24854074
2025-05-12T16:21:08.644Z,1747066868.644 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:21:08.644Z,1747066868.644 [Waterlinked] Communications Fault, FailCount= 2
2025-05-12T16:21:08.644Z,1747066868.644 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:21:08.720Z,1747066868.720 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:21:09.134Z,1747066869.134 [Waterlinked](INFO): Powering down
2025-05-12T16:21:09.901Z,1747066869.901 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:21:09.901Z,1747066869.901 [Waterlinked] No Fault, FailCount= 2
2025-05-12T16:21:16.226Z,1747066876.226 [DataOverHttps](INFO): Sending 417 bytes from file Logs/20250512T161059/Express0005.lzma
2025-05-12T16:21:17.227Z,1747066877.227 [DataOverHttps](INFO): Moved sent file to Logs/20250512T161059/Express0005.lzma.bak
2025-05-12T16:21:17.228Z,1747066877.228 [DataOverHttps](INFO): SBD MOMSN=24854077
2025-05-12T16:21:18.376Z,1747066878.376 [Default:CheckIn:Read_Iridium] Stopped
2025-05-12T16:21:18.376Z,1747066878.376 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-12T16:21:18.376Z,1747066878.376 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-12T16:21:38.549Z,1747066898.549 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:21:38.549Z,1747066898.549 [Waterlinked] Communications Fault, FailCount= 3
2025-05-12T16:21:38.549Z,1747066898.549 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:21:38.566Z,1747066898.566 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:21:38.566Z,1747066898.566 [CBIT](CRITICAL): Communications Fault in component: Waterlinked
2025-05-12T16:21:39.025Z,1747066899.025 [Waterlinked](INFO): Powering down
2025-05-12T16:21:39.047Z,1747066899.047 [CommandExec](FAULT): Scheduling is paused
2025-05-12T16:21:39.048Z,1747066899.048 [CBIT](INFO): Critical error at 20250512T162138
2025-05-12T16:21:53.089Z,1747066913.089 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-05-12T16:21:53.089Z,1747066913.089 [NAL9602] Data Fault, FailCount= 1
2025-05-12T16:21:53.089Z,1747066913.089 [NAL9602](ERROR): Data Fault
2025-05-12T16:21:53.106Z,1747066913.106 [CBIT](ERROR): Data Fault in component: NAL9602
2025-05-12T16:21:53.487Z,1747066913.487 [NAL9602](INFO): Powering down
2025-05-12T16:21:54.320Z,1747066914.320 [CBIT](INFO): Clearing failed state for component NAL9602
2025-05-12T16:21:54.320Z,1747066914.320 [NAL9602] No Fault, FailCount= 1
2025-05-12T16:22:23.791Z,1747066943.791 [NAL9602](INFO): Powering up NAL9602
2025-05-12T16:22:34.695Z,1747066954.695 [NAL9602](INFO): NAL9602 initialized
2025-05-12T16:23:15.546Z,1747066995.546 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-05-12T16:23:15.547Z,1747066995.547 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T16:23:15.558Z,1747066995.558 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T16:23:15.923Z,1747066995.923 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T16:23:15.923Z,1747066995.923 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-05-12T16:24:38.753Z,1747067078.753 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:24:38.753Z,1747067078.753 [Waterlinked] No Fault, FailCount= 3
2025-05-12T16:25:07.420Z,1747067107.420 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:25:07.420Z,1747067107.420 [Waterlinked] Communications Fault, FailCount= 1
2025-05-12T16:25:07.420Z,1747067107.420 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:25:07.467Z,1747067107.467 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:25:07.838Z,1747067107.838 [Waterlinked](INFO): Powering down
2025-05-12T16:25:08.663Z,1747067108.663 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:25:08.663Z,1747067108.663 [Waterlinked] No Fault, FailCount= 1
2025-05-12T16:25:37.316Z,1747067137.316 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:25:37.317Z,1747067137.317 [Waterlinked] Communications Fault, FailCount= 2
2025-05-12T16:25:37.317Z,1747067137.317 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:25:37.351Z,1747067137.351 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:25:37.716Z,1747067137.716 [Waterlinked](INFO): Powering down
2025-05-12T16:25:38.544Z,1747067138.544 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:25:38.544Z,1747067138.544 [Waterlinked] No Fault, FailCount= 2
2025-05-12T16:26:07.229Z,1747067167.229 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:26:07.229Z,1747067167.229 [Waterlinked] Communications Fault, FailCount= 3
2025-05-12T16:26:07.229Z,1747067167.229 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:26:07.271Z,1747067167.271 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:26:07.271Z,1747067167.271 [CBIT](CRITICAL): Communications Fault in component: Waterlinked
2025-05-12T16:26:07.618Z,1747067167.618 [Waterlinked](INFO): Powering down
2025-05-12T16:26:07.632Z,1747067167.632 [CBIT](INFO): Critical error at 20250512T162607
2025-05-12T16:26:16.534Z,1747067176.534 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-05-12T16:26:16.534Z,1747067176.534 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T16:26:16.545Z,1747067176.545 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T16:26:16.920Z,1747067176.920 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T16:26:16.921Z,1747067176.921 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-05-12T16:26:18.955Z,1747067178.955 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-12T16:26:18.955Z,1747067178.955 [Default:CheckIn:C.Wait] Stopped
2025-05-12T16:26:18.955Z,1747067178.955 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-12T16:26:18.955Z,1747067178.955 [Default:CheckIn:D] Running Loop=1
2025-05-12T16:26:19.359Z,1747067179.359 [Default:CheckIn:D] Stopped
2025-05-12T16:26:19.359Z,1747067179.359 [Default:CheckIn:E] Running Loop=1
2025-05-12T16:26:19.771Z,1747067179.771 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  10.714057 min 
2025-05-12T16:26:19.771Z,1747067179.771 [Default:CheckIn:E] Stopped
2025-05-12T16:26:19.771Z,1747067179.771 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-12T16:26:19.771Z,1747067179.771 [Default:CheckIn] Stopped
2025-05-12T16:26:19.771Z,1747067179.771 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-12T16:26:19.771Z,1747067179.771 [Default:CheckIn](INFO): Running loop #2
2025-05-12T16:26:19.772Z,1747067179.772 [Default:CheckIn] Running Loop=2
2025-05-12T16:26:19.772Z,1747067179.772 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-12T16:26:19.772Z,1747067179.772 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-12T16:29:07.440Z,1747067347.440 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:29:07.440Z,1747067347.440 [Waterlinked] No Fault, FailCount= 3
2025-05-12T16:29:17.535Z,1747067357.535 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-05-12T16:29:17.535Z,1747067357.535 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T16:29:17.546Z,1747067357.546 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T16:29:17.916Z,1747067357.916 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T16:29:17.916Z,1747067357.916 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-05-12T16:29:36.102Z,1747067376.102 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:29:36.102Z,1747067376.102 [Waterlinked] Communications Fault, FailCount= 1
2025-05-12T16:29:36.102Z,1747067376.102 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:29:36.124Z,1747067376.124 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:29:36.502Z,1747067376.502 [Waterlinked](INFO): Powering down
2025-05-12T16:29:37.348Z,1747067377.348 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:29:37.348Z,1747067377.348 [Waterlinked] No Fault, FailCount= 1
2025-05-12T16:30:06.002Z,1747067406.002 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:30:06.002Z,1747067406.002 [Waterlinked] Communications Fault, FailCount= 2
2025-05-12T16:30:06.002Z,1747067406.002 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:30:06.025Z,1747067406.025 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:30:06.398Z,1747067406.398 [Waterlinked](INFO): Powering down
2025-05-12T16:30:07.216Z,1747067407.216 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:30:07.216Z,1747067407.216 [Waterlinked] No Fault, FailCount= 2
2025-05-12T16:30:35.881Z,1747067435.881 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:30:35.881Z,1747067435.881 [Waterlinked] Communications Fault, FailCount= 3
2025-05-12T16:30:35.881Z,1747067435.881 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:30:35.898Z,1747067435.898 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:30:35.898Z,1747067435.898 [CBIT](CRITICAL): Communications Fault in component: Waterlinked
2025-05-12T16:30:36.280Z,1747067436.280 [Waterlinked](INFO): Powering down
2025-05-12T16:30:36.319Z,1747067436.319 [CBIT](INFO): Critical error at 20250512T163035
2025-05-12T16:31:19.923Z,1747067479.923 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-05-12T16:26:19.8Z
2025-05-12T16:31:19.923Z,1747067479.923 [Default:CheckIn:Read_GPS] Stopped
2025-05-12T16:31:19.923Z,1747067479.923 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-12T16:31:30.197Z,1747067490.197 [DataOverHttps](INFO): Sending 102 bytes from file Logs/20250512T161059/Courier0007.lzma
2025-05-12T16:31:31.191Z,1747067491.191 [DataOverHttps](INFO): Moved sent file to Logs/20250512T161059/Courier0007.lzma.bak
2025-05-12T16:31:31.191Z,1747067491.191 [DataOverHttps](INFO): SBD MOMSN=24854109
2025-05-12T16:31:47.277Z,1747067507.277 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20250512T161059/Express0008.lzma
2025-05-12T16:31:48.305Z,1747067508.305 [DataOverHttps](INFO): Moved sent file to Logs/20250512T161059/Express0008.lzma.bak
2025-05-12T16:31:48.305Z,1747067508.305 [DataOverHttps](INFO): SBD MOMSN=24854114
2025-05-12T16:31:49.555Z,1747067509.555 [Default:CheckIn:Read_Iridium] Stopped
2025-05-12T16:31:49.555Z,1747067509.555 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-12T16:31:49.555Z,1747067509.555 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-12T16:32:18.654Z,1747067538.654 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-05-12T16:32:18.654Z,1747067538.654 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T16:32:18.666Z,1747067538.666 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T16:32:19.058Z,1747067539.058 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T16:32:19.058Z,1747067539.058 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-05-12T16:32:38.023Z,1747067558.023 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-05-12T16:32:38.023Z,1747067558.023 [NAL9602] Data Fault, FailCount= 2
2025-05-12T16:32:38.023Z,1747067558.023 [NAL9602](ERROR): Data Fault
2025-05-12T16:32:38.040Z,1747067558.040 [CBIT](ERROR): Data Fault in component: NAL9602
2025-05-12T16:32:38.423Z,1747067558.423 [NAL9602](INFO): Powering down
2025-05-12T16:32:39.252Z,1747067559.252 [CBIT](INFO): Clearing failed state for component NAL9602
2025-05-12T16:32:39.252Z,1747067559.252 [NAL9602] No Fault, FailCount= 2
2025-05-12T16:33:08.732Z,1747067588.732 [NAL9602](INFO): Powering up NAL9602
2025-05-12T16:33:19.635Z,1747067599.635 [NAL9602](INFO): NAL9602 initialized
2025-05-12T16:33:36.217Z,1747067616.217 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:33:36.217Z,1747067616.217 [Waterlinked] No Fault, FailCount= 3
2025-05-12T16:34:04.886Z,1747067644.886 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:34:04.886Z,1747067644.886 [Waterlinked] Communications Fault, FailCount= 1
2025-05-12T16:34:04.886Z,1747067644.886 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:34:04.903Z,1747067644.903 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:34:05.289Z,1747067645.289 [Waterlinked](INFO): Powering down
2025-05-12T16:34:06.140Z,1747067646.140 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:34:06.140Z,1747067646.140 [Waterlinked] No Fault, FailCount= 1
2025-05-12T16:34:34.780Z,1747067674.780 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:34:34.780Z,1747067674.780 [Waterlinked] Communications Fault, FailCount= 2
2025-05-12T16:34:34.780Z,1747067674.780 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:34:34.821Z,1747067674.821 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:34:35.185Z,1747067675.185 [Waterlinked](INFO): Powering down
2025-05-12T16:34:36.028Z,1747067676.028 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:34:36.028Z,1747067676.028 [Waterlinked] No Fault, FailCount= 2
2025-05-12T16:35:04.690Z,1747067704.690 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:35:04.691Z,1747067704.691 [Waterlinked] Communications Fault, FailCount= 3
2025-05-12T16:35:04.691Z,1747067704.691 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:35:04.713Z,1747067704.713 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:35:04.713Z,1747067704.713 [CBIT](CRITICAL): Communications Fault in component: Waterlinked
2025-05-12T16:35:05.080Z,1747067705.080 [Waterlinked](INFO): Powering down
2025-05-12T16:35:05.108Z,1747067705.108 [CBIT](INFO): Critical error at 20250512T163504
2025-05-12T16:35:19.644Z,1747067719.644 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-05-12T16:35:19.644Z,1747067719.644 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T16:35:19.654Z,1747067719.654 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T16:35:20.060Z,1747067720.060 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T16:35:20.061Z,1747067720.061 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-05-12T16:36:50.127Z,1747067810.127 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-05-12T16:36:50.128Z,1747067810.128 [Default:CheckIn:C.Wait] Stopped
2025-05-12T16:36:50.128Z,1747067810.128 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-12T16:36:50.128Z,1747067810.128 [Default:CheckIn:D] Running Loop=1
2025-05-12T16:36:50.527Z,1747067810.527 [Default:CheckIn:D] Stopped
2025-05-12T16:36:50.527Z,1747067810.527 [Default:CheckIn:E] Running Loop=1
2025-05-12T16:36:50.956Z,1747067810.956 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  21.233525 min 
2025-05-12T16:36:50.956Z,1747067810.956 [Default:CheckIn:E] Stopped
2025-05-12T16:36:50.956Z,1747067810.956 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-05-12T16:36:50.956Z,1747067810.956 [Default:CheckIn] Stopped
2025-05-12T16:36:50.956Z,1747067810.956 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-12T16:36:50.956Z,1747067810.956 [Default:CheckIn](INFO): Running loop #3
2025-05-12T16:36:50.956Z,1747067810.956 [Default:CheckIn] Running Loop=3
2025-05-12T16:36:50.956Z,1747067810.956 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-05-12T16:36:50.956Z,1747067810.956 [Default:CheckIn:Read_GPS] Running Loop=1
2025-05-12T16:38:04.876Z,1747067884.876 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:38:04.876Z,1747067884.876 [Waterlinked] No Fault, FailCount= 3
2025-05-12T16:38:20.638Z,1747067900.638 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-05-12T16:38:20.638Z,1747067900.638 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T16:38:20.649Z,1747067900.649 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T16:38:21.052Z,1747067901.052 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T16:38:21.052Z,1747067901.052 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-05-12T16:38:33.544Z,1747067913.544 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:38:33.544Z,1747067913.544 [Waterlinked] Communications Fault, FailCount= 1
2025-05-12T16:38:33.544Z,1747067913.544 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:38:33.560Z,1747067913.560 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:38:34.029Z,1747067914.029 [Waterlinked](INFO): Powering down
2025-05-12T16:38:34.784Z,1747067914.784 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:38:34.784Z,1747067914.784 [Waterlinked] No Fault, FailCount= 1
2025-05-12T16:39:03.440Z,1747067943.440 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:39:03.440Z,1747067943.440 [Waterlinked] Communications Fault, FailCount= 2
2025-05-12T16:39:03.440Z,1747067943.440 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:39:03.482Z,1747067943.482 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:39:03.864Z,1747067943.864 [Waterlinked](INFO): Powering down
2025-05-12T16:39:04.685Z,1747067944.685 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:39:04.685Z,1747067944.685 [Waterlinked] No Fault, FailCount= 2
2025-05-12T16:39:33.348Z,1747067973.348 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:39:33.348Z,1747067973.348 [Waterlinked] Communications Fault, FailCount= 3
2025-05-12T16:39:33.348Z,1747067973.348 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:39:33.377Z,1747067973.377 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:39:33.397Z,1747067973.397 [CBIT](CRITICAL): Communications Fault in component: Waterlinked
2025-05-12T16:39:33.740Z,1747067973.740 [Waterlinked](INFO): Powering down
2025-05-12T16:39:33.790Z,1747067973.790 [CBIT](INFO): Critical error at 20250512T163933
2025-05-12T16:41:21.629Z,1747068081.629 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-05-12T16:41:21.629Z,1747068081.629 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-12T16:41:21.664Z,1747068081.664 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-12T16:41:22.056Z,1747068082.056 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-12T16:41:22.056Z,1747068082.056 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-05-12T16:41:44.750Z,1747068104.750 [CommandExec](IMPORTANT): got command failComponent
2025-05-12T16:41:44.750Z,1747068104.750 [CommandExec](IMPORTANT): Failed components:
2025-05-12T16:41:44.750Z,1747068104.750 [CommandExec](IMPORTANT): Waterlinked: Communications Fault
2025-05-12T16:41:51.139Z,1747068111.139 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-05-12T16:36:50.0Z
2025-05-12T16:41:51.139Z,1747068111.139 [Default:CheckIn:Read_GPS] Stopped
2025-05-12T16:41:51.139Z,1747068111.139 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-05-12T16:41:59.561Z,1747068119.561 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20250512T161059/Courier0010.lzma
2025-05-12T16:42:00.563Z,1747068120.563 [DataOverHttps](INFO): Moved sent file to Logs/20250512T161059/Courier0010.lzma.bak
2025-05-12T16:42:00.563Z,1747068120.563 [DataOverHttps](INFO): SBD MOMSN=24854139
2025-05-12T16:42:14.391Z,1747068134.391 [CommandExec](IMPORTANT): got command report touch Waterlinked.component_avgCurrent
2025-05-12T16:42:14.392Z,1747068134.392 [CommandExec](IMPORTANT): got command report touch Waterlinked.component_current
2025-05-12T16:42:15.961Z,1747068135.961 [CommandExec](IMPORTANT): got command failComponent
2025-05-12T16:42:15.961Z,1747068135.961 [CommandExec](IMPORTANT): Failed components:
2025-05-12T16:42:15.961Z,1747068135.961 [CommandExec](IMPORTANT): Waterlinked: Communications Fault
2025-05-12T16:42:17.737Z,1747068137.737 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20250512T161059/Express0011.lzma
2025-05-12T16:42:18.739Z,1747068138.739 [DataOverHttps](INFO): Moved sent file to Logs/20250512T161059/Express0011.lzma.bak
2025-05-12T16:42:18.739Z,1747068138.739 [DataOverHttps](INFO): SBD MOMSN=24854143
2025-05-12T16:42:19.816Z,1747068139.816 [Default:CheckIn:Read_Iridium] Stopped
2025-05-12T16:42:19.816Z,1747068139.816 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-12T16:42:19.816Z,1747068139.816 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-12T16:42:33.542Z,1747068153.542 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:42:33.542Z,1747068153.542 [Waterlinked] No Fault, FailCount= 3
2025-05-12T16:43:02.212Z,1747068182.212 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:43:02.212Z,1747068182.212 [Waterlinked] Communications Fault, FailCount= 1
2025-05-12T16:43:02.212Z,1747068182.212 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:43:02.229Z,1747068182.229 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:43:02.718Z,1747068182.718 [Waterlinked](INFO): Powering down
2025-05-12T16:43:02.742Z,1747068182.742 [Reporter](INFO): Waterlinked.component_avgCurrent 0.000000 mA
2025-05-12T16:43:02.742Z,1747068182.742 [Reporter](INFO): Waterlinked.component_current 0.000000 mA
2025-05-12T16:43:03.461Z,1747068183.461 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:43:03.461Z,1747068183.461 [Waterlinked] No Fault, FailCount= 1
2025-05-12T16:43:22.811Z,1747068202.811 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-05-12T16:43:22.811Z,1747068202.811 [NAL9602] Data Fault, FailCount= 3
2025-05-12T16:43:22.811Z,1747068202.811 [NAL9602](ERROR): Data Fault
2025-05-12T16:43:22.852Z,1747068202.852 [CBIT](ERROR): Data Fault in component: NAL9602
2025-05-12T16:43:23.215Z,1747068203.215 [NAL9602](INFO): Powering down
2025-05-12T16:43:24.042Z,1747068204.042 [CBIT](INFO): Clearing failed state for component NAL9602
2025-05-12T16:43:24.042Z,1747068204.042 [NAL9602] No Fault, FailCount= 3
2025-05-12T16:43:32.107Z,1747068212.107 [Waterlinked](FAULT): Could not initialize.
2025-05-12T16:43:32.107Z,1747068212.107 [Waterlinked] Communications Fault, FailCount= 2
2025-05-12T16:43:32.108Z,1747068212.108 [Waterlinked](ERROR): Communications Fault
2025-05-12T16:43:32.129Z,1747068212.129 [CBIT](ERROR): Communications Fault in component: Waterlinked
2025-05-12T16:43:32.530Z,1747068212.530 [CommandExec](IMPORTANT): got command quit
2025-05-12T16:43:32.581Z,1747068212.581 [Waterlinked](INFO): Powering down
2025-05-12T16:43:32.606Z,1747068212.606 [Reporter](INFO): Waterlinked.component_avgCurrent 0.000000 mA
2025-05-12T16:43:32.606Z,1747068212.606 [Reporter](INFO): Waterlinked.component_current 0.000000 mA
2025-05-12T16:43:33.368Z,1747068213.368 [CBIT](INFO): Clearing failed state for component Waterlinked
2025-05-12T16:43:33.368Z,1747068213.368 [Waterlinked] No Fault, FailCount= 2
2025-05-12T16:43:33.533Z,1747068213.533 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T16:43:33.533Z,1747068213.533 [CommandExec](INFO): Uninitializing the command executive.
2025-05-12T16:43:33.533Z,1747068213.533 [CommandExec](INFO): Uninitializing the command scheduler.
2025-05-12T16:43:33.534Z,1747068213.534 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:33.654Z,1747068213.654 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-05-12T16:43:33.654Z,1747068213.654 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-05-12T16:43:33.654Z,1747068213.654 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:33.655Z,1747068213.655 [NavChartDb](INFO): Join timeout helper Thread ID is 1038
2025-05-12T16:43:33.909Z,1747068213.909 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T16:43:33.910Z,1747068213.910 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:33.919Z,1747068213.919 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-05-12T16:43:33.919Z,1747068213.919 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:33.920Z,1747068213.920 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1040
2025-05-12T16:43:34.226Z,1747068214.226 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T16:43:34.227Z,1747068214.227 [WetLabsBB2FL](INFO): Powering down
2025-05-12T16:43:34.227Z,1747068214.227 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:34.237Z,1747068214.237 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-05-12T16:43:34.237Z,1747068214.237 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:34.238Z,1747068214.238 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1041
2025-05-12T16:43:34.521Z,1747068214.521 [CTD_Seabird](INFO): Powering down
2025-05-12T16:43:34.533Z,1747068214.533 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T16:43:34.533Z,1747068214.533 [CTD_Seabird](INFO): Powering down
2025-05-12T16:43:34.545Z,1747068214.545 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:34.546Z,1747068214.546 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-05-12T16:43:34.546Z,1747068214.546 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:34.546Z,1747068214.546 [Radio_Surface](INFO): Join timeout helper Thread ID is 1042
2025-05-12T16:43:34.641Z,1747068214.641 [Radio_Surface](INFO): Powering down
2025-05-12T16:43:34.642Z,1747068214.642 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T16:43:34.642Z,1747068214.642 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:34.649Z,1747068214.649 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-05-12T16:43:34.649Z,1747068214.649 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:34.650Z,1747068214.650 [Onboard](INFO): Join timeout helper Thread ID is 1043
2025-05-12T16:43:39.085Z,1747068219.085 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T16:43:39.086Z,1747068219.086 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:39.098Z,1747068219.098 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-05-12T16:43:39.098Z,1747068219.098 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:39.099Z,1747068219.099 [DataOverHttps](INFO): Join timeout helper Thread ID is 1044
2025-05-12T16:43:39.261Z,1747068219.261 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T16:43:39.265Z,1747068219.265 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:39.278Z,1747068219.278 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-05-12T16:43:39.278Z,1747068219.278 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:39.278Z,1747068219.278 [DAT](INFO): Join timeout helper Thread ID is 1045
2025-05-12T16:43:39.350Z,1747068219.350 [DAT](INFO): Powering down
2025-05-12T16:43:39.418Z,1747068219.418 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T16:43:39.418Z,1747068219.418 [DAT](INFO): Powering down
2025-05-12T16:43:39.419Z,1747068219.419 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:39.434Z,1747068219.434 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-05-12T16:43:39.434Z,1747068219.434 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:39.434Z,1747068219.434 [BackseatComponent](INFO): Join timeout helper Thread ID is 1046
2025-05-12T16:43:39.569Z,1747068219.569 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T16:43:39.570Z,1747068219.570 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:39.583Z,1747068219.583 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-05-12T16:43:39.583Z,1747068219.583 [logger ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:39.583Z,1747068219.583 [logger](INFO): Join timeout helper Thread ID is 1047
2025-05-12T16:43:39.608Z,1747068219.608 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T16:43:39.608Z,1747068219.608 [logger ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:39.618Z,1747068219.618 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-05-12T16:43:39.618Z,1747068219.618 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:39.618Z,1747068219.618 [CommandLine](INFO): Join timeout helper Thread ID is 1048
2025-05-12T16:43:39.669Z,1747068219.669 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T16:43:39.670Z,1747068219.670 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:39.678Z,1747068219.678 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-05-12T16:43:39.678Z,1747068219.678 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:39.678Z,1747068219.678 [CommandExec](INFO): Join timeout helper Thread ID is 1049
2025-05-12T16:43:39.679Z,1747068219.679 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-05-12T16:43:39.679Z,1747068219.679 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:39.680Z,1747068219.680 [controlThread](INFO): Join timeout helper Thread ID is 1050
2025-05-12T16:43:39.761Z,1747068219.761 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-12T16:43:39.762Z,1747068219.762 [controlThread](DEBUG): Uninitializing ControlThread
2025-05-12T16:43:39.762Z,1747068219.762 [AHRS_M2](INFO): Powering down
2025-05-12T16:43:39.842Z,1747068219.842 [MultiRay](INFO): Powering down LCB2
2025-05-12T16:43:39.842Z,1747068219.842 [MultiRay](INFO): Powering down.
2025-05-12T16:43:39.843Z,1747068219.843 [NAL9602](INFO): Powering down
2025-05-12T16:43:39.929Z,1747068219.929 [Waterlinked](INFO): Powering down
2025-05-12T16:43:39.938Z,1747068219.938 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-05-12T16:43:39.938Z,1747068219.938 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-05-12T16:43:39.939Z,1747068219.939 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-05-12T16:43:39.939Z,1747068219.939 [MissionManager](INFO): Uninitializing Mission Default
2025-05-12T16:43:39.939Z,1747068219.939 [Default] Stopped
2025-05-12T16:43:39.939Z,1747068219.939 [Default](DEBUG): Aggregate::uninitialize Default
2025-05-12T16:43:39.940Z,1747068219.940 [Default:B.GoToSurface] Stopped
2025-05-12T16:43:39.940Z,1747068219.940 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-12T16:43:39.940Z,1747068219.940 [Default:CheckIn] Stopped
2025-05-12T16:43:39.940Z,1747068219.940 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-12T16:43:39.940Z,1747068219.940 [Default:CheckIn:C.Wait] Stopped
2025-05-12T16:43:39.940Z,1747068219.940 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-12T16:43:39.943Z,1747068219.943 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-05-12T16:43:39.944Z,1747068219.944 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-05-12T16:43:39.944Z,1747068219.944 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-05-12T16:43:39.944Z,1747068219.944 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-05-12T16:43:39.944Z,1747068219.944 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-05-12T16:43:39.945Z,1747068219.945 [BuoyancyServo](INFO): Powering down
2025-05-12T16:43:39.957Z,1747068219.957 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-05-12T16:43:39.957Z,1747068219.957 [ElevatorServo](INFO): Powering down
2025-05-12T16:43:39.958Z,1747068219.958 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-05-12T16:43:39.958Z,1747068219.958 [MassServo](INFO): Powering down
2025-05-12T16:43:39.959Z,1747068219.959 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-05-12T16:43:39.959Z,1747068219.959 [RudderServo](INFO): Powering down
2025-05-12T16:43:39.960Z,1747068219.960 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-05-12T16:43:39.960Z,1747068219.960 [ThrusterHE](INFO): Powering down
2025-05-12T16:43:39.961Z,1747068219.961 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-05-12T16:43:39.962Z,1747068219.962 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-05-12T16:43:39.962Z,1747068219.962 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-05-12T16:43:39.962Z,1747068219.962 [CBIT](DEBUG): Powering off loads.
2025-05-12T16:43:39.973Z,1747068219.973 [CBIT](DEBUG): Disabling WDT.
2025-05-12T16:43:39.985Z,1747068219.985 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-05-12T16:43:39.985Z,1747068219.985 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-05-12T16:43:39.987Z,1747068219.987 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:40.016Z,1747068220.016 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:40.021Z,1747068220.021 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:40.027Z,1747068220.027 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:40.034Z,1747068220.034 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:40.077Z,1747068220.077 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:40.112Z,1747068220.112 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-05-12T16:43:40.112Z,1747068220.112 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-05-12T16:43:40.127Z,1747068220.127 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:40.132Z,1747068220.132 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:40.187Z,1747068220.187 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-12T16:43:40.236Z,1747068220.236 [logger ThreadHandler](INFO): Thread cancelled.