2025-06-13T22:07:59.084Z,1749852479.084 [Supervisor](DEBUG): Initializing supervisor.
2025-06-13T22:07:59.088Z,1749852479.088 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-06-13T22:07:59.089Z,1749852479.089 [SyncHandler](INFO): Protected caller Thread ID is 835
2025-06-13T22:07:59.089Z,1749852479.089 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-06-13T22:07:59.090Z,1749852479.090 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-06-13T22:07:59.090Z,1749852479.090 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836
2025-06-13T22:07:59.094Z,1749852479.094 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-06-13T22:07:59.115Z,1749852479.115 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-06-13T22:07:59.116Z,1749852479.116 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-06-13T22:07:59.116Z,1749852479.116 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 837
2025-06-13T22:07:59.120Z,1749852479.120 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-06-13T22:07:59.122Z,1749852479.122 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-06-13T22:07:59.122Z,1749852479.122 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 838
2025-06-13T22:07:59.124Z,1749852479.124 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-06-13T22:07:59.125Z,1749852479.125 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-06-13T22:07:59.126Z,1749852479.126 [logger ThreadHandler](INFO): Protected caller Thread ID is 839
2025-06-13T22:07:59.129Z,1749852479.129 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-06-13T22:07:59.130Z,1749852479.130 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-06-13T22:07:59.134Z,1749852479.134 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-06-13T22:07:59.510Z,1749852479.510 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-06-13T22:07:59.512Z,1749852479.512 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-06-13T22:07:59.808Z,1749852479.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-06-13T22:07:59.809Z,1749852479.809 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-06-13T22:08:00.359Z,1749852480.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-06-13T22:08:00.361Z,1749852480.361 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-06-13T22:08:00.628Z,1749852480.628 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-06-13T22:08:00.629Z,1749852480.629 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-06-13T22:08:00.894Z,1749852480.894 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-06-13T22:08:00.894Z,1749852480.894 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-06-13T22:08:01.775Z,1749852481.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-06-13T22:08:01.776Z,1749852481.776 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-06-13T22:08:02.160Z,1749852482.160 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-06-13T22:08:02.162Z,1749852482.162 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-06-13T22:08:02.289Z,1749852482.289 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-06-13T22:08:02.290Z,1749852482.290 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-06-13T22:08:02.439Z,1749852482.439 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-06-13T22:08:03.007Z,1749852483.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-06-13T22:08:03.008Z,1749852483.008 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-06-13T22:08:03.119Z,1749852483.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-06-13T22:08:03.121Z,1749852483.121 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-06-13T22:08:03.367Z,1749852483.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-06-13T22:08:03.368Z,1749852483.368 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-06-13T22:08:03.524Z,1749852483.524 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-06-13T22:08:03.525Z,1749852483.525 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-06-13T22:08:03.633Z,1749852483.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-06-13T22:08:03.634Z,1749852483.634 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-06-13T22:08:04.117Z,1749852484.117 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-06-13T22:08:04.118Z,1749852484.118 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-06-13T22:08:04.747Z,1749852484.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-06-13T22:08:04.750Z,1749852484.750 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/
2025-06-13T22:08:04.753Z,1749852484.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Science.cfg
2025-06-13T22:08:04.871Z,1749852484.871 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Battery.cfg
2025-06-13T22:08:05.166Z,1749852485.166 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-06-13T22:08:05.167Z,1749852485.167 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Navigation.cfg
2025-06-13T22:08:05.286Z,1749852485.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/vehicle.cfg
2025-06-13T22:08:05.398Z,1749852485.398 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/BIT.cfg
2025-06-13T22:08:05.526Z,1749852485.526 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Sensor.cfg
2025-06-13T22:08:05.682Z,1749852485.682 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Control.cfg
2025-06-13T22:08:05.796Z,1749852485.796 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/logger.cfg
2025-06-13T22:08:05.923Z,1749852485.923 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/secure.cfg
2025-06-13T22:08:06.021Z,1749852486.021 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Servo.cfg
2025-06-13T22:08:06.153Z,1749852486.153 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Simulator.cfg
2025-06-13T22:08:06.252Z,1749852486.252 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-06-13T22:08:06.255Z,1749852486.255 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-06-13T22:08:06.272Z,1749852486.272 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-06-13T22:08:06.273Z,1749852486.273 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-06-13T22:08:06.427Z,1749852486.427 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-06-13T22:08:06.427Z,1749852486.427 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-06-13T22:08:06.470Z,1749852486.470 [DeadReckonWithRespectToSeafloor] Loaded
2025-06-13T22:08:06.470Z,1749852486.470 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2025-06-13T22:08:06.484Z,1749852486.484 [NavChart] Loaded
2025-06-13T22:08:06.484Z,1749852486.484 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-06-13T22:08:06.490Z,1749852486.490 [UniversalFixResidualReporter] Loaded
2025-06-13T22:08:06.491Z,1749852486.491 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-06-13T22:08:06.491Z,1749852486.491 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-06-13T22:08:06.493Z,1749852486.493 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-06-13T22:08:07.408Z,1749852487.408 [AHRS_M2] Loaded
2025-06-13T22:08:07.408Z,1749852487.408 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-06-13T22:08:07.663Z,1749852487.663 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC3
2025-06-13T22:08:07.663Z,1749852487.663 [BackseatComponent] Loaded
2025-06-13T22:08:07.664Z,1749852487.664 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-06-13T22:08:07.665Z,1749852487.665 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4085D4E0
2025-06-13T22:08:07.665Z,1749852487.665 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921
2025-06-13T22:08:07.668Z,1749852487.668 [LcmUniversalReporter] Loaded
2025-06-13T22:08:07.668Z,1749852487.668 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-06-13T22:08:08.537Z,1749852488.537 [BPC1] Loaded
2025-06-13T22:08:08.537Z,1749852488.537 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-06-13T22:08:08.640Z,1749852488.640 [DAT] Loaded
2025-06-13T22:08:08.640Z,1749852488.640 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-06-13T22:08:08.642Z,1749852488.642 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4088D4E0
2025-06-13T22:08:08.642Z,1749852488.642 [DAT ThreadHandler](INFO): Protected caller Thread ID is 922
2025-06-13T22:08:08.802Z,1749852488.802 [DataOverHttps] Loaded
2025-06-13T22:08:08.802Z,1749852488.802 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-06-13T22:08:08.803Z,1749852488.803 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BD4E0
2025-06-13T22:08:08.803Z,1749852488.803 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 923
2025-06-13T22:08:08.827Z,1749852488.827 [DepthKeller33X] Loaded
2025-06-13T22:08:08.828Z,1749852488.828 [ComponentRegistry](DEBUG): SyncComponent "DepthKeller33X" handled in the control thread.
2025-06-13T22:08:08.833Z,1749852488.833 [DropWeight] Loaded
2025-06-13T22:08:08.833Z,1749852488.833 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-06-13T22:08:08.901Z,1749852488.901 [NAL9602] Loaded
2025-06-13T22:08:08.902Z,1749852488.902 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-06-13T22:08:08.933Z,1749852488.933 [Onboard] Loaded
2025-06-13T22:08:08.934Z,1749852488.934 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-06-13T22:08:08.935Z,1749852488.935 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0
2025-06-13T22:08:08.935Z,1749852488.935 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 924
2025-06-13T22:08:08.950Z,1749852488.950 [Power24vConverter] Loaded
2025-06-13T22:08:08.950Z,1749852488.950 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-06-13T22:08:08.969Z,1749852488.969 [Radio_Surface] Loaded
2025-06-13T22:08:08.969Z,1749852488.969 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-06-13T22:08:08.970Z,1749852488.970 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4091D4E0
2025-06-13T22:08:08.970Z,1749852488.970 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 925
2025-06-13T22:08:09.020Z,1749852489.020 [RDI_Pathfinder] Loaded
2025-06-13T22:08:09.020Z,1749852489.020 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2025-06-13T22:08:09.036Z,1749852489.036 [Sonardyne_Nano] Loaded
2025-06-13T22:08:09.037Z,1749852489.037 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-06-13T22:08:09.037Z,1749852489.037 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-06-13T22:08:09.038Z,1749852489.038 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-06-13T22:08:09.290Z,1749852489.290 [DepthRateCalculator] Loaded
2025-06-13T22:08:09.290Z,1749852489.290 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-06-13T22:08:09.368Z,1749852489.368 [PitchRateCalculator] Loaded
2025-06-13T22:08:09.389Z,1749852489.389 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-06-13T22:08:09.410Z,1749852489.410 [SpeedCalculator] Loaded
2025-06-13T22:08:09.410Z,1749852489.410 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-06-13T22:08:09.415Z,1749852489.415 [YawRateCalculator] Loaded
2025-06-13T22:08:09.416Z,1749852489.416 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-06-13T22:08:09.491Z,1749852489.491 [ElevatorOffsetCalculator] Loaded
2025-06-13T22:08:09.492Z,1749852489.492 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-06-13T22:08:09.492Z,1749852489.492 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-06-13T22:08:09.493Z,1749852489.493 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-06-13T22:08:09.830Z,1749852489.830 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-06-13T22:08:09.830Z,1749852489.830 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-06-13T22:08:10.017Z,1749852490.017 [BuoyancyServo] Loaded
2025-06-13T22:08:10.018Z,1749852490.018 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-06-13T22:08:10.043Z,1749852490.043 [ElevatorServo] Loaded
2025-06-13T22:08:10.044Z,1749852490.044 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-06-13T22:08:10.069Z,1749852490.069 [MassServo] Loaded
2025-06-13T22:08:10.069Z,1749852490.069 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-06-13T22:08:10.137Z,1749852490.137 [RudderServo] Loaded
2025-06-13T22:08:10.137Z,1749852490.137 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-06-13T22:08:10.157Z,1749852490.157 [ThrusterHE] Loaded
2025-06-13T22:08:10.157Z,1749852490.157 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-06-13T22:08:10.157Z,1749852490.157 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-06-13T22:08:10.158Z,1749852490.158 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-06-13T22:08:10.401Z,1749852490.401 [CTD_Seabird] Loaded
2025-06-13T22:08:10.402Z,1749852490.402 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-06-13T22:08:10.403Z,1749852490.403 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A514E0
2025-06-13T22:08:10.403Z,1749852490.403 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2025-06-13T22:08:10.426Z,1749852490.426 [RBRTridente] Loaded
2025-06-13T22:08:10.426Z,1749852490.426 [ComponentRegistry](DEBUG): Component "RBRTridente" handled in its own thread.
2025-06-13T22:08:10.427Z,1749852490.427 [RBRTridente ThreadHandler](DEBUG): Created PCaller Thread at 40A814E0
2025-06-13T22:08:10.427Z,1749852490.427 [RBRTridente ThreadHandler](INFO): Protected caller Thread ID is 928
2025-06-13T22:08:10.428Z,1749852490.428 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-06-13T22:08:10.429Z,1749852490.429 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-06-13T22:08:10.614Z,1749852490.614 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-06-13T22:08:10.616Z,1749852490.616 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-06-13T22:08:10.862Z,1749852490.862 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-06-13T22:08:10.862Z,1749852490.862 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-06-13T22:08:11.329Z,1749852491.329 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-06-13T22:08:11.330Z,1749852491.330 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-06-13T22:08:11.410Z,1749852491.410 [VerticalControl](DEBUG): Construct VerticalControl.
2025-06-13T22:08:11.467Z,1749852491.467 [VerticalControl] Loaded
2025-06-13T22:08:11.467Z,1749852491.467 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-06-13T22:08:11.470Z,1749852491.470 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-06-13T22:08:11.520Z,1749852491.520 [HorizontalControl] Loaded
2025-06-13T22:08:11.521Z,1749852491.521 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-06-13T22:08:11.523Z,1749852491.523 [SpeedControl](DEBUG): Construct SpeedControl.
2025-06-13T22:08:11.525Z,1749852491.525 [SpeedControl] Loaded
2025-06-13T22:08:11.525Z,1749852491.525 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-06-13T22:08:11.528Z,1749852491.528 [LoopControl](DEBUG): Construct LoopControl.
2025-06-13T22:08:11.529Z,1749852491.529 [LoopControl] Loaded
2025-06-13T22:08:11.529Z,1749852491.529 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-06-13T22:08:11.530Z,1749852491.530 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-06-13T22:08:11.530Z,1749852491.530 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-06-13T22:08:11.754Z,1749852491.754 [SBIT](DEBUG): Construct Startup Built In Test.
2025-06-13T22:08:11.764Z,1749852491.764 [SBIT] Loaded
2025-06-13T22:08:11.764Z,1749852491.764 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-06-13T22:08:11.767Z,1749852491.767 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-06-13T22:08:11.781Z,1749852491.781 [IBIT] Loaded
2025-06-13T22:08:11.781Z,1749852491.781 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-06-13T22:08:11.787Z,1749852491.787 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-06-13T22:08:11.874Z,1749852491.874 [CBIT] Loaded
2025-06-13T22:08:11.874Z,1749852491.874 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-06-13T22:08:11.909Z,1749852491.909 [GFScanner] Loaded
2025-06-13T22:08:11.910Z,1749852491.910 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-06-13T22:08:11.910Z,1749852491.910 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-06-13T22:08:11.912Z,1749852491.912 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-06-13T22:08:11.963Z,1749852491.963 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-06-13T22:08:11.969Z,1749852491.969 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-06-13T22:08:11.972Z,1749852491.972 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-06-13T22:08:11.984Z,1749852491.984 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-06-13T22:08:11.985Z,1749852491.985 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0
2025-06-13T22:08:11.985Z,1749852491.985 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2025-06-13T22:08:11.990Z,1749852491.990 [Supervisor](INFO): Main Thread ID is 826
2025-06-13T22:08:11.990Z,1749852491.990 [Supervisor](DEBUG): Running supervisor.
2025-06-13T22:08:11.991Z,1749852491.991 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2025-06-13T22:08:11.991Z,1749852491.991 [CommandExec](INFO): Initializing the command executive.
2025-06-13T22:08:11.993Z,1749852491.993 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2025-06-13T22:08:11.995Z,1749852491.995 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2025-06-13T22:08:11.995Z,1749852491.995 [controlThread](DEBUG): Initializing ControlThread
2025-06-13T22:08:11.997Z,1749852491.997 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-06-13T22:08:11.998Z,1749852491.998 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-06-13T22:08:12.001Z,1749852492.001 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-06-13T22:08:12.001Z,1749852492.001 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-06-13T22:08:12.002Z,1749852492.002 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-06-13T22:08:12.002Z,1749852492.002 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-06-13T22:08:12.002Z,1749852492.002 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-06-13T22:08:12.003Z,1749852492.003 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-06-13T22:08:12.005Z,1749852492.005 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-06-13T22:08:12.006Z,1749852492.006 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-06-13T22:08:12.006Z,1749852492.006 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-06-13T22:08:12.006Z,1749852492.006 [SBIT](INFO): Initialize SBIT Component.
2025-06-13T22:08:12.007Z,1749852492.007 [SBIT](IMPORTANT): git: 2025-06-02-12-gf31d2d2cf
2025-06-13T22:08:12.007Z,1749852492.007 [SBIT](INFO): git hash: f31d2d2cff7b1a18a5fb2309356ea947ca1213be
2025-06-13T22:08:12.007Z,1749852492.007 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-06-13T22:08:12.008Z,1749852492.008 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Mon Nov 27 01:04:18 PST 2023
2025-06-13T22:08:12.010Z,1749852492.010 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2025-06-13T22:08:12.010Z,1749852492.010 [IBIT](INFO): Initialize IBIT Component.
2025-06-13T22:08:12.011Z,1749852492.011 [CBIT](DEBUG): Initialize CBIT Component.
2025-06-13T22:08:12.012Z,1749852492.012 [logger ThreadHandler](INFO): Handler Thread ID is 933
2025-06-13T22:08:12.025Z,1749852492.025 [CBIT](DEBUG): Initialized mux pins.
2025-06-13T22:08:12.025Z,1749852492.025 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2025-06-13T22:08:12.025Z,1749852492.025 [CBIT](DEBUG): Initializing the watchdog timer.
2025-06-13T22:08:12.037Z,1749852492.037 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934
2025-06-13T22:08:12.049Z,1749852492.049 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2025-06-13T22:08:12.049Z,1749852492.049 [CBIT](DEBUG): Initializing heartbeat.
2025-06-13T22:08:12.050Z,1749852492.050 [DAT ThreadHandler](INFO): Handler Thread ID is 935
2025-06-13T22:08:12.051Z,1749852492.051 [DAT](INFO): Powering up
2025-06-13T22:08:12.051Z,1749852492.051 [DAT](DEBUG): Initializing DAT.
2025-06-13T22:08:12.054Z,1749852492.054 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 937
2025-06-13T22:08:12.056Z,1749852492.056 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-06-13T22:08:12.077Z,1749852492.077 [Onboard ThreadHandler](INFO): Handler Thread ID is 938
2025-06-13T22:08:12.095Z,1749852492.095 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 939
2025-06-13T22:08:12.110Z,1749852492.110 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 940
2025-06-13T22:08:12.110Z,1749852492.110 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-06-13T22:08:12.114Z,1749852492.114 [RBRTridente ThreadHandler](INFO): Handler Thread ID is 942
2025-06-13T22:08:12.115Z,1749852492.115 [RBRTridente](INFO): Start
2025-06-13T22:08:12.117Z,1749852492.117 [RBRTridente](INFO): Powering up
2025-06-13T22:08:12.119Z,1749852492.119 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 944
2025-06-13T22:08:12.121Z,1749852492.121 [CBIT](DEBUG): Deactivating emergency mode.
2025-06-13T22:08:12.127Z,1749852492.127 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-06-13T22:08:12.127Z,1749852492.127 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-06-13T22:08:12.127Z,1749852492.127 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-06-13T22:08:12.128Z,1749852492.128 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-06-13T22:08:12.128Z,1749852492.128 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-06-13T22:08:12.128Z,1749852492.128 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-06-13T22:08:12.128Z,1749852492.128 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-06-13T22:08:12.128Z,1749852492.128 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-06-13T22:08:12.161Z,1749852492.161 [CBIT](DEBUG): Backplane powered.
2025-06-13T22:08:12.161Z,1749852492.161 [GFScanner](DEBUG): Initializing GFScanner
2025-06-13T22:08:12.161Z,1749852492.161 [GFScanner](DEBUG): Deactivating GF circuits.
2025-06-13T22:08:12.166Z,1749852492.166 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-06-13T22:08:12.167Z,1749852492.167 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-06-13T22:08:12.167Z,1749852492.167 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-06-13T22:08:12.178Z,1749852492.178 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-06-13T22:08:12.249Z,1749852492.249 [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-06-13T22:08:12.254Z,1749852492.254 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-06-13T22:08:12.321Z,1749852492.321 [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-06-13T22:08:12.322Z,1749852492.322 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-06-13T22:08:12.322Z,1749852492.322 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-06-13T22:08:12.327Z,1749852492.327 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-06-13T22:08:12.501Z,1749852492.501 [Radio_Surface](INFO): Powering up
2025-06-13T22:08:12.517Z,1749852492.517 [RBRTridente](INFO): Starting
2025-06-13T22:08:12.644Z,1749852492.644 [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-06-13T22:08:12.670Z,1749852492.670 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-06-13T22:08:12.671Z,1749852492.671 [Default:A.Wait](DEBUG): Construct Wait.
2025-06-13T22:08:12.681Z,1749852492.681 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-06-13T22:08:12.723Z,1749852492.723 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-06-13T22:08:12.738Z,1749852492.738 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-06-13T22:08:12.744Z,1749852492.744 [Default:E.Execute](DEBUG): Construct Execute.
2025-06-13T22:08:12.763Z,1749852492.763 [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-06-13T22:08:12.768Z,1749852492.768 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,DepthKeller33X,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-06-13T22:08:12.791Z,1749852492.791 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-06-13T22:08:12.921Z,1749852492.921 [RBRTridente](INFO): Stop
2025-06-13T22:08:12.949Z,1749852492.949 [DepthKeller33X](INFO): Initializing.
2025-06-13T22:08:12.970Z,1749852492.970 [Power24vConverter](INFO): Powering up.
2025-06-13T22:08:12.971Z,1749852492.971 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-06-13T22:08:12.978Z,1749852492.978 [Sonardyne_Nano](INFO): Initializing.
2025-06-13T22:08:12.993Z,1749852492.993 [RBRTridente](INFO): Powering down
2025-06-13T22:08:12.997Z,1749852492.997 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-06-13T22:08:13.009Z,1749852493.009 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-06-13T22:08:13.017Z,1749852493.017 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-06-13T22:08:13.018Z,1749852493.018 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-13T22:08:13.025Z,1749852493.025 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-13T22:08:13.026Z,1749852493.026 [MassServo](DEBUG): Initializing EZServoServo.
2025-06-13T22:08:13.033Z,1749852493.033 [MassServo](DEBUG): Initializing MassServo.
2025-06-13T22:08:13.034Z,1749852493.034 [RudderServo](DEBUG): Initializing EZServoServo.
2025-06-13T22:08:13.041Z,1749852493.041 [RudderServo](DEBUG): Initializing RudderServo.
2025-06-13T22:08:13.042Z,1749852493.042 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-06-13T22:08:13.049Z,1749852493.049 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-06-13T22:08:13.051Z,1749852493.051 [CommandExec](FAULT): Scheduling is paused
2025-06-13T22:08:13.052Z,1749852493.052 [CBIT](INFO): Critical error at 20250613T220812
2025-06-13T22:08:13.052Z,1749852493.052 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-06-13T22:08:13.234Z,1749852493.234 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-06-13T22:08:13.325Z,1749852493.325 [RBRTridente](INFO): Stopping
2025-06-13T22:08:13.581Z,1749852493.581 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-06-13T22:08:13.987Z,1749852493.987 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-06-13T22:08:14.390Z,1749852494.390 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-06-13T22:08:14.816Z,1749852494.816 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-06-13T22:08:15.206Z,1749852495.206 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-06-13T22:08:16.450Z,1749852496.450 [Power24vConverter](FAULT): LCB fault:  Current Limiter Activated.
2025-06-13T22:08:16.450Z,1749852496.450 [Power24vConverter] Hardware Fault, FailCount= 1
2025-06-13T22:08:16.450Z,1749852496.450 [Power24vConverter](ERROR): Hardware Fault
2025-06-13T22:08:16.450Z,1749852496.450 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:16.451Z,1749852496.451 [Power24vConverter](FAULT): 24V Power is reporting not powering/powered.
2025-06-13T22:08:16.454Z,1749852496.454 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2025-06-13T22:08:16.665Z,1749852496.665 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
2025-06-13T22:08:16.866Z,1749852496.866 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:17.331Z,1749852497.331 [Power24vConverter](FAULT):  LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-06-13T22:08:17.691Z,1749852497.691 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:18.069Z,1749852498.069 [Power24vConverter](FAULT):  LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-06-13T22:08:18.474Z,1749852498.474 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:18.512Z,1749852498.512 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-06-13T22:08:18.880Z,1749852498.880 [Power24vConverter](FAULT):  LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-06-13T22:08:19.142Z,1749852499.142 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-06-13T22:08:19.294Z,1749852499.294 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:19.711Z,1749852499.711 [Power24vConverter](FAULT):  LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-06-13T22:08:20.119Z,1749852500.119 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:20.494Z,1749852500.494 [Power24vConverter](FAULT):  LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-06-13T22:08:20.897Z,1749852500.897 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:21.319Z,1749852501.319 [Power24vConverter](FAULT):  LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-06-13T22:08:21.677Z,1749852501.677 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:22.147Z,1749852502.147 [Power24vConverter](FAULT):  LCB fault:  Hardware Overcurrent Shutdown. Current Limiter Activated.
2025-06-13T22:08:22.476Z,1749852502.476 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:23.463Z,1749852503.463 [CBIT](INFO): Clearing failed state for component Power24vConverter
2025-06-13T22:08:23.463Z,1749852503.463 [Power24vConverter] No Fault, FailCount= 1
2025-06-13T22:08:23.699Z,1749852503.699 [Power24vConverter](INFO): Powering up.
2025-06-13T22:08:24.899Z,1749852504.899 [Power24vConverter](FAULT): LCB fault:  Current Limiter Activated.
2025-06-13T22:08:24.900Z,1749852504.900 [Power24vConverter] Hardware Fault, FailCount= 1
2025-06-13T22:08:24.900Z,1749852504.900 [Power24vConverter](ERROR): Hardware Fault
2025-06-13T22:08:24.900Z,1749852504.900 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:24.900Z,1749852504.900 [Power24vConverter](FAULT): 24V Power is reporting not powering/powered.
2025-06-13T22:08:24.949Z,1749852504.949 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
2025-06-13T22:08:25.306Z,1749852505.306 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:25.723Z,1749852505.723 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:26.128Z,1749852506.128 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:26.551Z,1749852506.551 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:26.919Z,1749852506.919 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:27.325Z,1749852507.325 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:27.743Z,1749852507.743 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:28.147Z,1749852508.147 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:28.570Z,1749852508.570 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:28.939Z,1749852508.939 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:29.346Z,1749852509.346 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:29.763Z,1749852509.763 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:30.163Z,1749852510.163 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:30.583Z,1749852510.583 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:30.959Z,1749852510.959 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:31.371Z,1749852511.371 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:31.782Z,1749852511.782 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:32.198Z,1749852512.198 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:32.607Z,1749852512.607 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:32.984Z,1749852512.984 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:33.390Z,1749852513.390 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:33.795Z,1749852513.795 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:34.210Z,1749852514.210 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:34.631Z,1749852514.631 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:35.006Z,1749852515.006 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:35.409Z,1749852515.409 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:35.410Z,1749852515.410 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2025-06-13T22:08:35.410Z,1749852515.410 [RDI_Pathfinder] Communications Fault, FailCount= 1
2025-06-13T22:08:35.410Z,1749852515.410 [RDI_Pathfinder](ERROR): Communications Fault
2025-06-13T22:08:35.424Z,1749852515.424 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2025-06-13T22:08:35.830Z,1749852515.830 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:35.901Z,1749852515.901 [RDI_Pathfinder](INFO): Powering down
2025-06-13T22:08:36.243Z,1749852516.243 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:36.620Z,1749852516.620 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:36.636Z,1749852516.636 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-06-13T22:08:36.636Z,1749852516.636 [RDI_Pathfinder] No Fault, FailCount= 1
2025-06-13T22:08:37.027Z,1749852517.027 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:37.028Z,1749852517.028 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-06-13T22:08:37.430Z,1749852517.430 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:37.847Z,1749852517.847 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:38.262Z,1749852518.262 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:38.641Z,1749852518.641 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:39.044Z,1749852519.044 [NAL9602](INFO): Powering up NAL9602
2025-06-13T22:08:39.053Z,1749852519.053 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:39.450Z,1749852519.450 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:39.867Z,1749852519.867 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:40.283Z,1749852520.283 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:40.658Z,1749852520.658 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:41.063Z,1749852521.063 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:41.470Z,1749852521.470 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:41.894Z,1749852521.894 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:42.299Z,1749852522.299 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:42.676Z,1749852522.676 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:43.083Z,1749852523.083 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:43.495Z,1749852523.495 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:43.914Z,1749852523.914 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:44.291Z,1749852524.291 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:44.697Z,1749852524.697 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:45.102Z,1749852525.102 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:45.515Z,1749852525.515 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:45.931Z,1749852525.931 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:46.343Z,1749852526.343 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:46.771Z,1749852526.771 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:47.122Z,1749852527.122 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:47.534Z,1749852527.534 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:47.955Z,1749852527.955 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:48.330Z,1749852528.330 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:48.737Z,1749852528.737 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:49.151Z,1749852529.151 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:49.573Z,1749852529.573 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:49.975Z,1749852529.975 [NAL9602](INFO): NAL9602 initialized
2025-06-13T22:08:49.975Z,1749852529.975 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:50.351Z,1749852530.351 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:51.201Z,1749852531.201 [CBIT](INFO): Clearing failed state for component Power24vConverter
2025-06-13T22:08:51.201Z,1749852531.201 [Power24vConverter] No Fault, FailCount= 1
2025-06-13T22:08:51.601Z,1749852531.601 [Power24vConverter](INFO): Powering up.
2025-06-13T22:08:51.615Z,1749852531.615 [SBIT](IMPORTANT): Beginning Startup BIT
2025-06-13T22:08:51.619Z,1749852531.619 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-06-13T22:08:55.259Z,1749852535.259 [Power24vConverter](FAULT): LCB fault:  Current Limiter Activated.
2025-06-13T22:08:55.259Z,1749852535.259 [Power24vConverter] Hardware Fault, FailCount= 1
2025-06-13T22:08:55.259Z,1749852535.259 [Power24vConverter](ERROR): Hardware Fault
2025-06-13T22:08:55.260Z,1749852535.260 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:55.260Z,1749852535.260 [Power24vConverter](FAULT): 24V Power is reporting not powering/powered.
2025-06-13T22:08:55.466Z,1749852535.466 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
2025-06-13T22:08:55.637Z,1749852535.637 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:56.041Z,1749852536.041 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:56.132Z,1749852536.132 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.016203
CHAN A1 (24V): -0.141169
CHAN A2 (12V): 0.002022
CHAN A3 (5V): -0.012945
CHAN B0 (3.3V): -0.000463
CHAN B1 (3.15aV): 0.000098
CHAN B2 (3.15bV): -0.000721
CHAN B3 (GND): -0.000505
OPEN: -0.005009
Full Scale: +/- 1 mA
2025-06-13T22:08:56.446Z,1749852536.446 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:56.860Z,1749852536.860 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:57.279Z,1749852537.279 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:57.669Z,1749852537.669 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:58.073Z,1749852538.073 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:58.477Z,1749852538.477 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:58.893Z,1749852538.893 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:59.288Z,1749852539.288 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:08:59.289Z,1749852539.289 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2025-06-13T22:08:59.289Z,1749852539.289 [RDI_Pathfinder] Communications Fault, FailCount= 2
2025-06-13T22:08:59.289Z,1749852539.289 [RDI_Pathfinder](ERROR): Communications Fault
2025-06-13T22:08:59.419Z,1749852539.419 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2025-06-13T22:08:59.704Z,1749852539.704 [Power24vConverter](INFO): Powering down.
2025-06-13T22:08:59.773Z,1749852539.773 [RDI_Pathfinder](INFO): Powering down
2025-06-13T22:09:00.079Z,1749852540.079 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:00.490Z,1749852540.490 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:00.522Z,1749852540.522 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-06-13T22:09:00.522Z,1749852540.522 [RDI_Pathfinder] No Fault, FailCount= 2
2025-06-13T22:09:00.899Z,1749852540.899 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:00.899Z,1749852540.899 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-06-13T22:09:01.315Z,1749852541.315 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:01.723Z,1749852541.723 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:02.107Z,1749852542.107 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:02.505Z,1749852542.505 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:02.921Z,1749852542.921 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:03.333Z,1749852543.333 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:03.753Z,1749852543.753 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:04.133Z,1749852544.133 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:04.533Z,1749852544.533 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:04.939Z,1749852544.939 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:05.348Z,1749852545.348 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:05.771Z,1749852545.771 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:06.139Z,1749852546.139 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:06.546Z,1749852546.546 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:06.959Z,1749852546.959 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:07.374Z,1749852547.374 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:07.787Z,1749852547.787 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:08.217Z,1749852548.217 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:08.589Z,1749852548.589 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:09.502Z,1749852549.502 [CBIT](INFO): Clearing failed state for component Power24vConverter
2025-06-13T22:09:09.502Z,1749852549.502 [Power24vConverter] No Fault, FailCount= 1
2025-06-13T22:09:09.807Z,1749852549.807 [Power24vConverter](INFO): Powering up.
2025-06-13T22:09:12.058Z,1749852552.058 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2025-06-13T22:09:12.058Z,1749852552.058 [DAT] Communications Fault, FailCount= 1
2025-06-13T22:09:12.058Z,1749852552.058 [DAT](ERROR): Communications Fault
2025-06-13T22:09:12.243Z,1749852552.243 [Power24vConverter](FAULT): LCB fault:  Current Limiter Activated.
2025-06-13T22:09:12.243Z,1749852552.243 [Power24vConverter] Hardware Fault, FailCount= 1
2025-06-13T22:09:12.243Z,1749852552.243 [Power24vConverter](ERROR): Hardware Fault
2025-06-13T22:09:12.244Z,1749852552.244 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:12.244Z,1749852552.244 [Power24vConverter](FAULT): 24V Power is reporting not powering/powered.
2025-06-13T22:09:12.462Z,1749852552.462 [DAT](INFO): Powering down
2025-06-13T22:09:12.566Z,1749852552.566 [CBIT](ERROR): Communications Fault in component: DAT
2025-06-13T22:09:12.566Z,1749852552.566 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
2025-06-13T22:09:12.621Z,1749852552.621 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:13.033Z,1749852553.033 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:13.495Z,1749852553.495 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:13.543Z,1749852553.543 [CBIT](INFO): Clearing failed state for component DAT
2025-06-13T22:09:13.543Z,1749852553.543 [DAT] No Fault, FailCount= 1
2025-06-13T22:09:13.869Z,1749852553.869 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:14.281Z,1749852554.281 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:14.654Z,1749852554.654 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:15.045Z,1749852555.045 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:15.509Z,1749852555.509 [DAT](INFO): Powering up
2025-06-13T22:09:15.509Z,1749852555.509 [DAT](DEBUG): Initializing DAT.
2025-06-13T22:09:15.911Z,1749852555.911 [CBIT](INFO): Clearing failed state for component Power24vConverter
2025-06-13T22:09:15.911Z,1749852555.911 [Power24vConverter] No Fault, FailCount= 1
2025-06-13T22:09:16.287Z,1749852556.287 [Power24vConverter](INFO): Powering up.
2025-06-13T22:09:17.473Z,1749852557.473 [Power24vConverter](FAULT): LCB fault:  Current Limiter Activated.
2025-06-13T22:09:17.473Z,1749852557.473 [Power24vConverter] Hardware Fault, FailCount= 1
2025-06-13T22:09:17.473Z,1749852557.473 [Power24vConverter](ERROR): Hardware Fault
2025-06-13T22:09:17.473Z,1749852557.473 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:17.474Z,1749852557.474 [Power24vConverter](FAULT): 24V Power is reporting not powering/powered.
2025-06-13T22:09:17.522Z,1749852557.522 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
2025-06-13T22:09:17.891Z,1749852557.891 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:18.303Z,1749852558.303 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:18.693Z,1749852558.693 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:19.098Z,1749852559.098 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:19.505Z,1749852559.505 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:19.974Z,1749852559.974 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:20.328Z,1749852560.328 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:20.736Z,1749852560.736 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:21.156Z,1749852561.156 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:21.982Z,1749852561.982 [CBIT](INFO): Clearing failed state for component Power24vConverter
2025-06-13T22:09:21.982Z,1749852561.982 [Power24vConverter] No Fault, FailCount= 1
2025-06-13T22:09:22.339Z,1749852562.339 [Power24vConverter](INFO): Powering up.
2025-06-13T22:09:23.127Z,1749852563.127 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2025-06-13T22:09:23.127Z,1749852563.127 [RDI_Pathfinder] Communications Fault, FailCount= 3
2025-06-13T22:09:23.127Z,1749852563.127 [RDI_Pathfinder](ERROR): Communications Fault
2025-06-13T22:09:23.171Z,1749852563.171 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2025-06-13T22:09:23.172Z,1749852563.172 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2025-06-13T22:09:23.544Z,1749852563.544 [Power24vConverter](FAULT): LCB fault:  Current Limiter Activated.
2025-06-13T22:09:23.544Z,1749852563.544 [Power24vConverter] Hardware Fault, FailCount= 1
2025-06-13T22:09:23.544Z,1749852563.544 [Power24vConverter](ERROR): Hardware Fault
2025-06-13T22:09:23.544Z,1749852563.544 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:23.545Z,1749852563.545 [Power24vConverter](FAULT): 24V Power is reporting not powering/powered.
2025-06-13T22:09:23.625Z,1749852563.625 [RDI_Pathfinder](INFO): Powering down
2025-06-13T22:09:23.641Z,1749852563.641 [CBIT](INFO): Critical error at 20250613T220923
2025-06-13T22:09:23.644Z,1749852563.644 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
2025-06-13T22:09:23.965Z,1749852563.965 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:24.374Z,1749852564.374 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:24.753Z,1749852564.753 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:25.157Z,1749852565.157 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:25.563Z,1749852565.563 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:25.967Z,1749852565.967 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:26.383Z,1749852566.383 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:26.761Z,1749852566.761 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:27.169Z,1749852567.169 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:27.576Z,1749852567.576 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:27.995Z,1749852567.995 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:28.403Z,1749852568.403 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:28.780Z,1749852568.780 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:29.201Z,1749852569.201 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:29.604Z,1749852569.604 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:30.021Z,1749852570.021 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:30.435Z,1749852570.435 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:30.831Z,1749852570.831 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:31.205Z,1749852571.205 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:31.610Z,1749852571.610 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:32.023Z,1749852572.023 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:32.435Z,1749852572.435 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:32.911Z,1749852572.911 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:33.220Z,1749852573.220 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-13T22:09:33.231Z,1749852573.231 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:33.642Z,1749852573.642 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:34.148Z,1749852574.148 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:34.444Z,1749852574.444 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-13T22:09:34.473Z,1749852574.473 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:34.881Z,1749852574.881 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:35.309Z,1749852575.309 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:36.174Z,1749852576.174 [CBIT](INFO): Clearing failed state for component Power24vConverter
2025-06-13T22:09:36.174Z,1749852576.174 [Power24vConverter] No Fault, FailCount= 1
2025-06-13T22:09:36.481Z,1749852576.481 [Power24vConverter](INFO): Powering up.
2025-06-13T22:09:37.680Z,1749852577.680 [Power24vConverter](FAULT): LCB fault:  Current Limiter Activated.
2025-06-13T22:09:37.680Z,1749852577.680 [Power24vConverter] Hardware Fault, FailCount= 1
2025-06-13T22:09:37.680Z,1749852577.680 [Power24vConverter](ERROR): Hardware Fault
2025-06-13T22:09:37.680Z,1749852577.680 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:37.702Z,1749852577.702 [Power24vConverter](FAULT): 24V Power is reporting not powering/powered.
2025-06-13T22:09:37.762Z,1749852577.762 [CBIT](ERROR): Hardware Fault in component: Power24vConverter
2025-06-13T22:09:38.086Z,1749852578.086 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:38.503Z,1749852578.503 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:38.919Z,1749852578.919 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:39.327Z,1749852579.327 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:39.717Z,1749852579.717 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:40.117Z,1749852580.117 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:40.533Z,1749852580.533 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:40.949Z,1749852580.949 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:41.316Z,1749852581.316 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:41.722Z,1749852581.722 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:42.139Z,1749852582.139 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:42.547Z,1749852582.547 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:42.959Z,1749852582.959 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:43.336Z,1749852583.336 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:43.743Z,1749852583.743 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:44.159Z,1749852584.159 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:44.564Z,1749852584.564 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:44.993Z,1749852584.993 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:45.421Z,1749852585.421 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:45.434Z,1749852585.434 [SBIT](IMPORTANT): SBIT PASSED
2025-06-13T22:09:45.434Z,1749852585.434 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-06-13T22:09:45.759Z,1749852585.759 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-13T22:09:45.781Z,1749852585.781 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:45.788Z,1749852585.788 [MissionManager](IMPORTANT): Started mission Startup
2025-06-13T22:09:45.788Z,1749852585.788 [Startup] Running Loop=1
2025-06-13T22:09:45.788Z,1749852585.788 [Startup](DEBUG): Aggregate::initialize Startup
2025-06-13T22:09:45.788Z,1749852585.788 [Startup:A.GoToSurface] Running Loop=1
2025-06-13T22:09:45.788Z,1749852585.788 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-13T22:09:45.789Z,1749852585.789 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-13T22:09:45.789Z,1749852585.789 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-13T22:09:45.790Z,1749852585.790 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-13T22:09:45.790Z,1749852585.790 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-13T22:09:45.790Z,1749852585.790 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-13T22:09:45.791Z,1749852585.791 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-13T22:09:45.792Z,1749852585.792 [Startup:StartupSatComms] Running Loop=1
2025-06-13T22:09:45.792Z,1749852585.792 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-06-13T22:09:45.793Z,1749852585.793 [Startup:StartupSatComms:A] Running Loop=1
2025-06-13T22:09:46.185Z,1749852586.185 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:46.194Z,1749852586.194 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-06-13T22:09:46.584Z,1749852586.584 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:46.999Z,1749852586.999 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:47.415Z,1749852587.415 [Power24vConverter](FAULT):  LCB fault:  Current Limiter Activated.
2025-06-13T22:09:47.782Z,1749852587.782 [Power24vConverter](INFO): Powering down.
2025-06-13T22:09:48.785Z,1749852588.785 [CBIT](INFO): Clearing failed state for component Power24vConverter
2025-06-13T22:09:48.785Z,1749852588.785 [Power24vConverter] No Fault, FailCount= 1
2025-06-13T22:09:49.083Z,1749852589.083 [Power24vConverter](INFO): Powering up.
2025-06-13T22:09:49.391Z,1749852589.391 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-13T22:09:57.875Z,1749852597.875 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-13T22:10:04.340Z,1749852604.340 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-13T22:10:10.399Z,1749852610.399 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-13T22:10:15.550Z,1749852615.550 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2025-06-13T22:10:15.550Z,1749852615.550 [DAT] Communications Fault, FailCount= 2
2025-06-13T22:10:15.550Z,1749852615.550 [DAT](ERROR): Communications Fault
2025-06-13T22:10:15.699Z,1749852615.699 [CBIT](ERROR): Communications Fault in component: DAT
2025-06-13T22:10:15.953Z,1749852615.953 [DAT](INFO): Powering down
2025-06-13T22:10:16.896Z,1749852616.896 [CBIT](INFO): Clearing failed state for component DAT
2025-06-13T22:10:16.896Z,1749852616.896 [DAT] No Fault, FailCount= 2
2025-06-13T22:10:19.009Z,1749852619.009 [DAT](INFO): Powering up
2025-06-13T22:10:19.010Z,1749852619.010 [DAT](DEBUG): Initializing DAT.
2025-06-13T22:10:20.104Z,1749852620.104 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-13T22:10:23.103Z,1749852623.103 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-13T22:10:30.852Z,1749852630.852 [DAT](INFO): DAT read: 

2025-06-13T22:10:30.853Z,1749852630.853 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-06-13T22:10:32.364Z,1749852632.364 [DAT](INFO): DAT read: MF Frequency Band

2025-06-13T22:10:32.365Z,1749852632.365 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.0

2025-06-13T22:10:32.366Z,1749852632.366 [DAT](INFO): DAT read: Jun 13 2025 22:10:27 

2025-06-13T22:10:33.372Z,1749852633.372 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-06-13T22:10:33.373Z,1749852633.373 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP

2025-06-13T22:10:33.374Z,1749852633.374 [DAT](INFO): commRate: 600
2025-06-13T22:10:35.439Z,1749852635.439 [DAT](INFO): entering command mode
2025-06-13T22:10:35.640Z,1749852635.640 [DAT](INFO): DAT read: 

2025-06-13T22:10:35.640Z,1749852635.640 [DAT](INFO): DAT read: user:1>

2025-06-13T22:10:35.641Z,1749852635.641 [DAT](INFO): setting verbose to 3
2025-06-13T22:10:35.646Z,1749852635.646 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-13T22:10:35.892Z,1749852635.892 [DAT](INFO): DAT read: user:1>

2025-06-13T22:10:35.894Z,1749852635.894 [DAT](INFO): DAT read: Verbose         | 3           

2025-06-13T22:10:35.894Z,1749852635.894 [DAT](INFO): set verbose to 3
2025-06-13T22:10:35.895Z,1749852635.895 [DAT](INFO): setting DatVerbose to 27440
2025-06-13T22:10:36.144Z,1749852636.144 [DAT](INFO): DAT read: user:2>

2025-06-13T22:10:36.145Z,1749852636.145 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-06-13T22:10:36.145Z,1749852636.145 [DAT](INFO): set DatVerbose to 27440
2025-06-13T22:10:36.145Z,1749852636.145 [DAT](INFO): setting transmit power to 8
2025-06-13T22:10:36.396Z,1749852636.396 [DAT](INFO): DAT read: user:3>

2025-06-13T22:10:36.397Z,1749852636.397 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-06-13T22:10:36.397Z,1749852636.397 [DAT](INFO): set transmit power to 8
2025-06-13T22:10:36.397Z,1749852636.397 [DAT](INFO): setting local address to 3
2025-06-13T22:10:36.453Z,1749852636.453 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-13T22:10:36.648Z,1749852636.648 [DAT](INFO): DAT read: user:4>

2025-06-13T22:10:36.649Z,1749852636.649 [DAT](INFO): DAT read: LocalAddr       | 3           

2025-06-13T22:10:36.649Z,1749852636.649 [DAT](INFO): set local address to 3
2025-06-13T22:10:36.650Z,1749852636.650 [DAT](INFO): Setting time to: 22:10:36 And date to:6/13/2025
2025-06-13T22:10:36.900Z,1749852636.900 [DAT](INFO): DAT read: user:5>

2025-06-13T22:10:36.901Z,1749852636.901 [DAT](INFO): DAT read: Fri Jun 13, 2025  22:10:36  

2025-06-13T22:10:36.901Z,1749852636.901 [DAT](INFO): Local DAT time set to Fri Jun 13, 2025  22:10:36  

2025-06-13T22:10:38.274Z,1749852638.274 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004334
2025-06-13T22:10:46.171Z,1749852646.171 [Startup:StartupSatComms:A](INFO): Timed out from 2025-06-13T22:09:45.8Z
2025-06-13T22:10:46.171Z,1749852646.171 [Startup:StartupSatComms:A] Stopped
2025-06-13T22:10:46.171Z,1749852646.171 [Startup:StartupSatComms:B] Running Loop=1
2025-06-13T22:10:46.583Z,1749852646.583 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-13T22:10:48.165Z,1749852648.165 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-13T22:10:51.402Z,1749852651.402 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-13T22:10:55.523Z,1749852655.523 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250613T202605/Courier0031.lzma
2025-06-13T22:10:58.533Z,1749852658.533 [DataOverHttps](INFO): Moved sent file to Logs/20250613T202605/Courier0031.lzma.bak
2025-06-13T22:10:58.533Z,1749852658.533 [DataOverHttps](INFO): SBD MOMSN=25210220
2025-06-13T22:11:00.286Z,1749852660.286 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-13T22:11:06.749Z,1749852666.749 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-13T22:11:12.013Z,1749852672.013 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-06-13T22:11:12.013Z,1749852672.013 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-13T22:11:12.014Z,1749852672.014 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2025-06-13T22:11:12.014Z,1749852672.014 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2025-06-13T22:11:12.025Z,1749852672.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-13T22:11:12.025Z,1749852672.025 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2025-06-13T22:11:12.543Z,1749852672.543 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-13T22:11:12.543Z,1749852672.543 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-06-13T22:11:12.544Z,1749852672.544 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2025-06-13T22:11:12.544Z,1749852672.544 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2025-06-13T22:11:12.810Z,1749852672.810 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-13T22:11:15.833Z,1749852675.833 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20250613T220759/Courier0000.lzma
2025-06-13T22:11:19.858Z,1749852679.858 [DataOverHttps](INFO): Moved sent file to Logs/20250613T220759/Courier0000.lzma.bak
2025-06-13T22:11:19.858Z,1749852679.858 [DataOverHttps](INFO): SBD MOMSN=25210225
2025-06-13T22:11:23.014Z,1749852683.014 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-13T22:11:26.152Z,1749852686.152 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-13T22:11:39.108Z,1749852699.108 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-13T22:11:39.130Z,1749852699.130 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-13T22:11:44.842Z,1749852704.842 [DepthKeller33X](ERROR): Failed to parse pressure: *0001*00011.017705
2025-06-13T22:11:46.424Z,1749852706.424 [Startup:StartupSatComms:B](INFO): Timed out from 2025-06-13T22:10:46.2Z
2025-06-13T22:11:46.424Z,1749852706.424 [Startup:StartupSatComms:B] Stopped
2025-06-13T22:11:46.424Z,1749852706.424 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-06-13T22:11:46.424Z,1749852706.424 [Startup:StartupSatComms] Stopped
2025-06-13T22:11:46.424Z,1749852706.424 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-06-13T22:11:46.425Z,1749852706.425 [Startup](INFO): Completed Startup
2025-06-13T22:11:46.425Z,1749852706.425 [MissionManager](INFO): Startup is completed.
2025-06-13T22:11:46.426Z,1749852706.426 [MissionManager](INFO): Uninitializing Mission Startup
2025-06-13T22:11:46.426Z,1749852706.426 [Startup] Stopped
2025-06-13T22:11:46.426Z,1749852706.426 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-06-13T22:11:46.426Z,1749852706.426 [Startup:A.GoToSurface] Stopped
2025-06-13T22:11:46.426Z,1749852706.426 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-13T22:11:46.793Z,1749852706.793 [MissionManager](IMPORTANT): Started mission Default
2025-06-13T22:11:46.793Z,1749852706.793 [Default] Running Loop=1
2025-06-13T22:11:46.793Z,1749852706.793 [Default](DEBUG): Aggregate::initialize Default
2025-06-13T22:11:46.793Z,1749852706.793 [Default:B.GoToSurface] Running Loop=1
2025-06-13T22:11:46.793Z,1749852706.793 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-13T22:11:46.793Z,1749852706.793 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-13T22:11:46.794Z,1749852706.794 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-13T22:11:46.794Z,1749852706.794 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-13T22:11:46.794Z,1749852706.794 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-13T22:11:46.795Z,1749852706.795 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-13T22:11:46.827Z,1749852706.827 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-13T22:11:46.828Z,1749852706.828 [Default:A.Wait] Running Loop=1
2025-06-13T22:11:46.828Z,1749852706.828 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-06-13T22:11:51.258Z,1749852711.258 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-13T22:11:54.931Z,1749852714.931 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-13T22:12:00.246Z,1749852720.246 [Default:A.Wait](INFO): Done Waiting.
2025-06-13T22:12:00.246Z,1749852720.246 [Default:A.Wait] Stopped
2025-06-13T22:12:00.246Z,1749852720.246 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-06-13T22:12:00.613Z,1749852720.613 [Default:CheckIn] Running Loop=1
2025-06-13T22:12:00.613Z,1749852720.613 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-13T22:12:00.613Z,1749852720.613 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-13T22:12:01.019Z,1749852721.019 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-06-13T22:12:04.658Z,1749852724.658 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250613T202605/Express0032.lzma
2025-06-13T22:12:05.662Z,1749852725.662 [DataOverHttps](INFO): Moved sent file to Logs/20250613T202605/Express0032.lzma.bak
2025-06-13T22:12:05.662Z,1749852725.662 [DataOverHttps](INFO): SBD MOMSN=25210230
2025-06-13T22:12:23.278Z,1749852743.278 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-06-13T22:12:23.278Z,1749852743.278 [RDI_Pathfinder] No Fault, FailCount= 3
2025-06-13T22:12:23.653Z,1749852743.653 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-06-13T22:12:36.229Z,1749852756.229 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout.
2025-06-13T22:12:36.229Z,1749852756.229 [RDI_Pathfinder] Data Fault, FailCount= 1
2025-06-13T22:12:36.229Z,1749852756.229 [RDI_Pathfinder](ERROR): Data Fault
2025-06-13T22:12:36.263Z,1749852756.263 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder
2025-06-13T22:12:36.812Z,1749852756.812 [RDI_Pathfinder](INFO): Powering down
2025-06-13T22:12:37.640Z,1749852757.640 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-06-13T22:12:37.640Z,1749852757.640 [RDI_Pathfinder] No Fault, FailCount= 1
2025-06-13T22:12:38.037Z,1749852758.037 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-06-13T22:12:56.377Z,1749852776.377 [RDI_Pathfinder](INFO): Failure count cleared after critical for RDI_Pathfinder
2025-06-13T22:13:53.009Z,1749852833.009 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-06-13T22:14:13.251Z,1749852853.251 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-06-13T22:14:13.251Z,1749852853.251 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-13T22:14:13.253Z,1749852853.253 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2025-06-13T22:14:13.253Z,1749852853.253 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2025-06-13T22:14:13.264Z,1749852853.264 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-13T22:14:13.264Z,1749852853.264 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2025-06-13T22:14:13.672Z,1749852853.672 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-13T22:14:13.672Z,1749852853.672 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-06-13T22:14:13.673Z,1749852853.673 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2025-06-13T22:14:13.673Z,1749852853.673 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2025-06-13T22:14:31.703Z,1749852871.703 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5.
2025-06-13T22:14:31.706Z,1749852871.706 [BPC1](INFO): Received data from all battery sticks.
2025-06-13T22:15:58.378Z,1749852958.378 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD,      .00,   0.00,186.00
2025-06-13T22:17:00.647Z,1749853020.647 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-13T22:12:00.6Z
2025-06-13T22:17:00.647Z,1749853020.647 [Default:CheckIn:Read_GPS] Stopped
2025-06-13T22:17:00.648Z,1749853020.648 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-13T22:17:01.043Z,1749853021.043 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-13T22:17:11.567Z,1749853031.567 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250613T220759/Courier0004.lzma
2025-06-13T22:17:12.569Z,1749853032.569 [DataOverHttps](INFO): Moved sent file to Logs/20250613T220759/Courier0004.lzma.bak
2025-06-13T22:17:12.569Z,1749853032.569 [DataOverHttps](INFO): SBD MOMSN=25210280
2025-06-13T22:17:14.475Z,1749853034.475 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-06-13T22:17:14.475Z,1749853034.475 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-13T22:17:14.477Z,1749853034.477 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2025-06-13T22:17:14.477Z,1749853034.477 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2025-06-13T22:17:14.526Z,1749853034.526 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-13T22:17:14.526Z,1749853034.526 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2025-06-13T22:17:14.788Z,1749853034.788 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-13T22:17:14.788Z,1749853034.788 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-06-13T22:17:14.789Z,1749853034.789 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2025-06-13T22:17:14.789Z,1749853034.789 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2025-06-13T22:17:47.816Z,1749853067.816 [DataOverHttps](INFO): Sending 1341 bytes from file Logs/20250613T220759/Express0001.lzma
2025-06-13T22:17:48.815Z,1749853068.815 [DataOverHttps](INFO): Moved sent file to Logs/20250613T220759/Express0001.lzma.bak
2025-06-13T22:17:48.815Z,1749853068.815 [DataOverHttps](INFO): SBD MOMSN=25210282
2025-06-13T22:18:21.861Z,1749853101.861 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20250613T220759/Express0005.lzma
2025-06-13T22:18:22.861Z,1749853102.861 [DataOverHttps](INFO): Moved sent file to Logs/20250613T220759/Express0005.lzma.bak
2025-06-13T22:18:22.861Z,1749853102.861 [DataOverHttps](INFO): SBD MOMSN=25210403
2025-06-13T22:18:25.101Z,1749853105.101 [Default:CheckIn:Read_Iridium] Stopped
2025-06-13T22:18:25.101Z,1749853105.101 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-13T22:18:25.101Z,1749853105.101 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-13T22:18:51.073Z,1749853131.073 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-06-13T22:18:51.073Z,1749853131.073 [NAL9602] Data Fault, FailCount= 1
2025-06-13T22:18:51.073Z,1749853131.073 [NAL9602](ERROR): Data Fault
2025-06-13T22:18:51.098Z,1749853131.098 [CBIT](ERROR): Data Fault in component: NAL9602
2025-06-13T22:18:51.489Z,1749853131.489 [NAL9602](INFO): Powering down
2025-06-13T22:18:53.424Z,1749853133.424 [CBIT](INFO): Clearing failed state for component NAL9602
2025-06-13T22:18:53.424Z,1749853133.424 [NAL9602] No Fault, FailCount= 1
2025-06-13T22:19:21.505Z,1749853161.505 [NAL9602](INFO): Powering up NAL9602
2025-06-13T22:19:32.433Z,1749853172.433 [NAL9602](INFO): NAL9602 initialized
2025-06-13T22:20:15.449Z,1749853215.449 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-06-13T22:20:15.449Z,1749853215.449 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-13T22:20:15.451Z,1749853215.451 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2025-06-13T22:20:15.479Z,1749853215.479 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2025-06-13T22:20:15.531Z,1749853215.531 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-13T22:20:15.555Z,1749853215.555 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2025-06-13T22:20:15.878Z,1749853215.878 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-13T22:20:15.878Z,1749853215.878 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-06-13T22:20:15.879Z,1749853215.879 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2025-06-13T22:20:15.879Z,1749853215.879 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2025-06-13T22:20:40.208Z,1749853240.208 [CommandExec](IMPORTANT): got command quit
2025-06-13T22:20:41.211Z,1749853241.211 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-13T22:20:41.211Z,1749853241.211 [CommandExec](INFO): Uninitializing the command executive.
2025-06-13T22:20:41.211Z,1749853241.211 [CommandExec](INFO): Uninitializing the command scheduler.
2025-06-13T22:20:41.212Z,1749853241.212 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:41.267Z,1749853241.267 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-06-13T22:20:41.267Z,1749853241.267 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-06-13T22:20:41.268Z,1749853241.268 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:41.268Z,1749853241.268 [NavChartDb](INFO): Join timeout helper Thread ID is 1485
2025-06-13T22:20:41.491Z,1749853241.491 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-13T22:20:41.492Z,1749853241.492 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:41.495Z,1749853241.495 [ComponentRegistry](INFO): Shutting down RBRTridente ThreadHandler
2025-06-13T22:20:41.495Z,1749853241.495 [RBRTridente ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:41.496Z,1749853241.496 [RBRTridente](INFO): Join timeout helper Thread ID is 1486
2025-06-13T22:20:41.863Z,1749853241.863 [RBRTridente ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-13T22:20:41.864Z,1749853241.864 [RBRTridente](INFO): Powering down
2025-06-13T22:20:41.864Z,1749853241.864 [RBRTridente ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:41.867Z,1749853241.867 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-06-13T22:20:41.867Z,1749853241.867 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:41.867Z,1749853241.867 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1487
2025-06-13T22:20:42.399Z,1749853242.399 [CTD_Seabird](INFO): Powering down
2025-06-13T22:20:42.411Z,1749853242.411 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-13T22:20:42.412Z,1749853242.412 [CTD_Seabird](INFO): Powering down
2025-06-13T22:20:42.427Z,1749853242.427 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:42.431Z,1749853242.431 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-06-13T22:20:42.431Z,1749853242.431 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:42.432Z,1749853242.432 [Radio_Surface](INFO): Join timeout helper Thread ID is 1488
2025-06-13T22:20:42.628Z,1749853242.628 [Radio_Surface](INFO): Powering down
2025-06-13T22:20:42.629Z,1749853242.629 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-13T22:20:42.629Z,1749853242.629 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:42.639Z,1749853242.639 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-06-13T22:20:42.639Z,1749853242.639 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:42.640Z,1749853242.640 [Onboard](INFO): Join timeout helper Thread ID is 1489
2025-06-13T22:20:43.319Z,1749853243.319 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-13T22:20:43.319Z,1749853243.319 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:43.324Z,1749853243.324 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-06-13T22:20:43.324Z,1749853243.324 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:43.324Z,1749853243.324 [DataOverHttps](INFO): Join timeout helper Thread ID is 1490
2025-06-13T22:20:43.739Z,1749853243.739 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-13T22:20:43.742Z,1749853243.742 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:43.758Z,1749853243.758 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-06-13T22:20:43.758Z,1749853243.758 [DAT ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:43.763Z,1749853243.763 [DAT](INFO): Join timeout helper Thread ID is 1491
2025-06-13T22:20:43.887Z,1749853243.887 [DAT](INFO): Powering down
2025-06-13T22:20:43.968Z,1749853243.968 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-13T22:20:43.968Z,1749853243.968 [DAT](INFO): Powering down
2025-06-13T22:20:43.969Z,1749853243.969 [DAT ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:43.984Z,1749853243.984 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-06-13T22:20:43.984Z,1749853243.984 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:43.984Z,1749853243.984 [BackseatComponent](INFO): Join timeout helper Thread ID is 1492
2025-06-13T22:20:44.131Z,1749853244.131 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-13T22:20:44.132Z,1749853244.132 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:44.148Z,1749853244.148 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-06-13T22:20:44.148Z,1749853244.148 [logger ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:44.148Z,1749853244.148 [logger](INFO): Join timeout helper Thread ID is 1493
2025-06-13T22:20:44.193Z,1749853244.193 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-13T22:20:44.211Z,1749853244.211 [logger ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:44.233Z,1749853244.233 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-06-13T22:20:44.233Z,1749853244.233 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:44.234Z,1749853244.234 [CommandLine](INFO): Join timeout helper Thread ID is 1494
2025-06-13T22:20:44.268Z,1749853244.268 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-13T22:20:44.269Z,1749853244.269 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:44.273Z,1749853244.273 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-06-13T22:20:44.273Z,1749853244.273 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:44.274Z,1749853244.274 [CommandExec](INFO): Join timeout helper Thread ID is 1495
2025-06-13T22:20:44.278Z,1749853244.278 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-06-13T22:20:44.278Z,1749853244.278 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:44.280Z,1749853244.280 [controlThread](INFO): Join timeout helper Thread ID is 1496
2025-06-13T22:20:44.559Z,1749853244.559 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-13T22:20:44.559Z,1749853244.559 [controlThread](DEBUG): Uninitializing ControlThread
2025-06-13T22:20:44.560Z,1749853244.560 [AHRS_M2](INFO): Powering down
2025-06-13T22:20:44.632Z,1749853244.632 [DepthKeller33X](INFO): Powering down
2025-06-13T22:20:44.728Z,1749853244.728 [NAL9602](INFO): Powering down
2025-06-13T22:20:44.799Z,1749853244.799 [RDI_Pathfinder](INFO): Powering down
2025-06-13T22:20:44.800Z,1749853244.800 [Sonardyne_Nano](INFO): Powering down
2025-06-13T22:20:44.919Z,1749853244.919 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-06-13T22:20:44.920Z,1749853244.920 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-06-13T22:20:44.921Z,1749853244.921 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-06-13T22:20:44.921Z,1749853244.921 [MissionManager](INFO): Uninitializing Mission Default
2025-06-13T22:20:44.921Z,1749853244.921 [Default] Stopped
2025-06-13T22:20:44.921Z,1749853244.921 [Default](DEBUG): Aggregate::uninitialize Default
2025-06-13T22:20:44.921Z,1749853244.921 [Default:B.GoToSurface] Stopped
2025-06-13T22:20:44.922Z,1749853244.922 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-13T22:20:44.922Z,1749853244.922 [Default:CheckIn] Stopped
2025-06-13T22:20:44.922Z,1749853244.922 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-13T22:20:44.922Z,1749853244.922 [Default:CheckIn:C.Wait] Stopped
2025-06-13T22:20:44.922Z,1749853244.922 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-13T22:20:44.925Z,1749853244.925 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-06-13T22:20:44.925Z,1749853244.925 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-06-13T22:20:44.925Z,1749853244.925 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-06-13T22:20:44.926Z,1749853244.926 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-06-13T22:20:44.926Z,1749853244.926 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-06-13T22:20:44.926Z,1749853244.926 [BuoyancyServo](INFO): Powering down
2025-06-13T22:20:44.939Z,1749853244.939 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-13T22:20:44.939Z,1749853244.939 [ElevatorServo](INFO): Powering down
2025-06-13T22:20:44.940Z,1749853244.940 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-06-13T22:20:44.940Z,1749853244.940 [MassServo](INFO): Powering down
2025-06-13T22:20:44.941Z,1749853244.941 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-06-13T22:20:44.941Z,1749853244.941 [RudderServo](INFO): Powering down
2025-06-13T22:20:44.942Z,1749853244.942 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-06-13T22:20:44.942Z,1749853244.942 [ThrusterHE](INFO): Powering down
2025-06-13T22:20:44.943Z,1749853244.943 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-06-13T22:20:44.943Z,1749853244.943 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-06-13T22:20:44.944Z,1749853244.944 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-06-13T22:20:44.944Z,1749853244.944 [CBIT](DEBUG): Powering off loads.
2025-06-13T22:20:44.955Z,1749853244.955 [CBIT](DEBUG): Disabling WDT.
2025-06-13T22:20:44.967Z,1749853244.967 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-06-13T22:20:44.967Z,1749853244.967 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-06-13T22:20:44.968Z,1749853244.968 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:44.996Z,1749853244.996 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:44.999Z,1749853244.999 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:45.005Z,1749853245.005 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:45.011Z,1749853245.011 [DAT ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:45.053Z,1749853245.053 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:45.101Z,1749853245.101 [RBRTridente ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:45.103Z,1749853245.103 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:45.143Z,1749853245.143 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-06-13T22:20:45.143Z,1749853245.143 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-06-13T22:20:45.162Z,1749853245.162 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-06-13T22:20:45.214Z,1749853245.214 [logger ThreadHandler](INFO): Thread cancelled.