2025-09-15T01:09:58.773Z,1757898598.773 [Supervisor](DEBUG): Initializing supervisor. 2025-09-15T01:09:58.778Z,1757898598.778 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-09-15T01:09:58.778Z,1757898598.778 [SyncHandler](INFO): Protected caller Thread ID is 652 2025-09-15T01:09:58.779Z,1757898598.779 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-09-15T01:09:58.780Z,1757898598.780 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-09-15T01:09:58.780Z,1757898598.780 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 653 2025-09-15T01:09:58.784Z,1757898598.784 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-09-15T01:09:58.804Z,1757898598.804 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-09-15T01:09:58.805Z,1757898598.805 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-09-15T01:09:58.805Z,1757898598.805 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 654 2025-09-15T01:09:58.810Z,1757898598.810 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-09-15T01:09:58.811Z,1757898598.811 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-09-15T01:09:58.811Z,1757898598.811 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 655 2025-09-15T01:09:58.813Z,1757898598.813 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-09-15T01:09:58.814Z,1757898598.814 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-09-15T01:09:58.815Z,1757898598.815 [logger ThreadHandler](INFO): Protected caller Thread ID is 656 2025-09-15T01:09:58.819Z,1757898598.819 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-09-15T01:09:58.819Z,1757898598.819 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-09-15T01:09:58.823Z,1757898598.823 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-09-15T01:09:59.226Z,1757898599.226 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-09-15T01:09:59.228Z,1757898599.228 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-09-15T01:09:59.990Z,1757898599.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-09-15T01:09:59.990Z,1757898599.990 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-09-15T01:10:00.108Z,1757898600.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-09-15T01:10:00.109Z,1757898600.109 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-09-15T01:10:00.702Z,1757898600.702 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-09-15T01:10:00.981Z,1757898600.981 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-09-15T01:10:01.207Z,1757898601.207 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-09-15T01:10:01.211Z,1757898601.211 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-09-15T01:10:01.311Z,1757898601.311 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-09-15T01:10:01.312Z,1757898601.312 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-09-15T01:10:01.733Z,1757898601.733 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-09-15T01:10:01.735Z,1757898601.735 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-09-15T01:10:01.965Z,1757898601.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-09-15T01:10:01.965Z,1757898601.965 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-09-15T01:10:02.113Z,1757898602.113 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-09-15T01:10:02.114Z,1757898602.114 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-09-15T01:10:02.371Z,1757898602.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-09-15T01:10:02.371Z,1757898602.371 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-09-15T01:10:02.940Z,1757898602.940 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-09-15T01:10:02.940Z,1757898602.940 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-09-15T01:10:03.339Z,1757898603.339 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-09-15T01:10:03.341Z,1757898603.341 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-09-15T01:10:03.430Z,1757898603.430 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-09-15T01:10:03.621Z,1757898603.621 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-09-15T01:10:03.622Z,1757898603.622 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-09-15T01:10:03.882Z,1757898603.882 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-09-15T01:10:03.882Z,1757898603.882 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-09-15T01:10:04.197Z,1757898604.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-09-15T01:10:04.200Z,1757898604.200 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2025-09-15T01:10:04.203Z,1757898604.203 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2025-09-15T01:10:04.322Z,1757898604.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2025-09-15T01:10:04.486Z,1757898604.486 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2025-09-15T01:10:04.584Z,1757898604.584 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2025-09-15T01:10:04.678Z,1757898604.678 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2025-09-15T01:10:04.800Z,1757898604.800 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg 2025-09-15T01:10:04.905Z,1757898604.905 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Dock.cfg 2025-09-15T01:10:05.028Z,1757898605.028 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2025-09-15T01:10:05.141Z,1757898605.141 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2025-09-15T01:10:05.428Z,1757898605.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-09-15T01:10:05.430Z,1757898605.430 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2025-09-15T01:10:05.572Z,1757898605.572 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2025-09-15T01:10:05.675Z,1757898605.675 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2025-09-15T01:10:06.216Z,1757898606.216 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2025-09-15T01:10:06.312Z,1757898606.312 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-09-15T01:10:06.323Z,1757898606.323 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-09-15T01:10:06.492Z,1757898606.492 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-09-15T01:10:06.493Z,1757898606.493 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-09-15T01:10:06.507Z,1757898606.507 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-09-15T01:10:06.507Z,1757898606.507 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-09-15T01:10:06.575Z,1757898606.575 [DepthRateCalculator] Loaded 2025-09-15T01:10:06.576Z,1757898606.576 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-09-15T01:10:06.582Z,1757898606.582 [PitchRateCalculator] Loaded 2025-09-15T01:10:06.582Z,1757898606.582 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-09-15T01:10:06.592Z,1757898606.592 [SpeedCalculator] Loaded 2025-09-15T01:10:06.593Z,1757898606.593 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-09-15T01:10:06.598Z,1757898606.598 [YawRateCalculator] Loaded 2025-09-15T01:10:06.598Z,1757898606.598 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-09-15T01:10:06.620Z,1757898606.620 [ElevatorOffsetCalculator] Loaded 2025-09-15T01:10:06.620Z,1757898606.620 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-09-15T01:10:06.621Z,1757898606.621 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-09-15T01:10:06.621Z,1757898606.621 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-09-15T01:10:06.688Z,1757898606.688 [VerticalControl](DEBUG): Construct VerticalControl. 2025-09-15T01:10:06.745Z,1757898606.745 [VerticalControl] Loaded 2025-09-15T01:10:06.746Z,1757898606.746 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-09-15T01:10:06.749Z,1757898606.749 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-09-15T01:10:06.797Z,1757898606.797 [HorizontalControl] Loaded 2025-09-15T01:10:06.797Z,1757898606.797 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-09-15T01:10:06.799Z,1757898606.799 [SpeedControl](DEBUG): Construct SpeedControl. 2025-09-15T01:10:06.802Z,1757898606.802 [SpeedControl] Loaded 2025-09-15T01:10:06.802Z,1757898606.802 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-09-15T01:10:06.805Z,1757898606.805 [LoopControl](DEBUG): Construct LoopControl. 2025-09-15T01:10:06.806Z,1757898606.806 [LoopControl] Loaded 2025-09-15T01:10:06.806Z,1757898606.806 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-09-15T01:10:06.806Z,1757898606.806 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-09-15T01:10:06.807Z,1757898606.807 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-09-15T01:10:06.944Z,1757898606.944 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-09-15T01:10:06.944Z,1757898606.944 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-09-15T01:10:06.958Z,1757898606.958 [NavChart] Loaded 2025-09-15T01:10:06.958Z,1757898606.958 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-09-15T01:10:06.964Z,1757898606.964 [UniversalFixResidualReporter] Loaded 2025-09-15T01:10:06.964Z,1757898606.964 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-09-15T01:10:06.965Z,1757898606.965 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-09-15T01:10:06.965Z,1757898606.965 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-09-15T01:10:07.178Z,1757898607.178 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-09-15T01:10:07.179Z,1757898607.179 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-09-15T01:10:07.838Z,1757898607.838 [AHRS_M2] Loaded 2025-09-15T01:10:07.838Z,1757898607.838 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-09-15T01:10:07.877Z,1757898607.877 [BackseatComponent] Loaded 2025-09-15T01:10:07.877Z,1757898607.877 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-09-15T01:10:07.878Z,1757898607.878 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409404E0 2025-09-15T01:10:07.879Z,1757898607.879 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 744 2025-09-15T01:10:07.881Z,1757898607.881 [LcmUniversalReporter] Loaded 2025-09-15T01:10:07.882Z,1757898607.882 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-09-15T01:10:08.738Z,1757898608.738 [BPC1] Loaded 2025-09-15T01:10:08.738Z,1757898608.738 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-09-15T01:10:08.840Z,1757898608.840 [DAT] Loaded 2025-09-15T01:10:08.841Z,1757898608.841 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-09-15T01:10:08.842Z,1757898608.842 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409704E0 2025-09-15T01:10:08.842Z,1757898608.842 [DAT ThreadHandler](INFO): Protected caller Thread ID is 745 2025-09-15T01:10:08.917Z,1757898608.917 [DataOverHttps] Loaded 2025-09-15T01:10:08.917Z,1757898608.917 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-09-15T01:10:08.918Z,1757898608.918 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409A04E0 2025-09-15T01:10:08.918Z,1757898608.918 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 746 2025-09-15T01:10:08.942Z,1757898608.942 [Depth_Keller] Loaded 2025-09-15T01:10:08.942Z,1757898608.942 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-09-15T01:10:09.010Z,1757898609.010 [NAL9602] Loaded 2025-09-15T01:10:09.010Z,1757898609.010 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-09-15T01:10:09.040Z,1757898609.040 [Onboard] Loaded 2025-09-15T01:10:09.041Z,1757898609.041 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-09-15T01:10:09.042Z,1757898609.042 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409D04E0 2025-09-15T01:10:09.042Z,1757898609.042 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 747 2025-09-15T01:10:09.057Z,1757898609.057 [Power24vConverter] Loaded 2025-09-15T01:10:09.057Z,1757898609.057 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-09-15T01:10:09.073Z,1757898609.073 [Radio_Surface] Loaded 2025-09-15T01:10:09.073Z,1757898609.073 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-09-15T01:10:09.075Z,1757898609.075 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A004E0 2025-09-15T01:10:09.075Z,1757898609.075 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 748 2025-09-15T01:10:09.122Z,1757898609.122 [RDI_Pathfinder] Loaded 2025-09-15T01:10:09.122Z,1757898609.122 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2025-09-15T01:10:09.138Z,1757898609.138 [Sonardyne_Nano] Loaded 2025-09-15T01:10:09.138Z,1757898609.138 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-09-15T01:10:09.139Z,1757898609.139 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-09-15T01:10:09.139Z,1757898609.139 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-09-15T01:10:09.304Z,1757898609.304 [BuoyancyServo] Loaded 2025-09-15T01:10:09.304Z,1757898609.304 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-09-15T01:10:09.328Z,1757898609.328 [ElevatorServo] Loaded 2025-09-15T01:10:09.328Z,1757898609.328 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-09-15T01:10:09.359Z,1757898609.359 [DockingStepper] Loaded 2025-09-15T01:10:09.359Z,1757898609.359 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2025-09-15T01:10:09.379Z,1757898609.379 [MassServo] Loaded 2025-09-15T01:10:09.379Z,1757898609.379 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-09-15T01:10:09.401Z,1757898609.401 [RudderServo] Loaded 2025-09-15T01:10:09.401Z,1757898609.401 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-09-15T01:10:09.418Z,1757898609.418 [ThrusterHE] Loaded 2025-09-15T01:10:09.418Z,1757898609.418 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-09-15T01:10:09.419Z,1757898609.419 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-09-15T01:10:09.419Z,1757898609.419 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-09-15T01:10:09.511Z,1757898609.511 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-09-15T01:10:09.511Z,1757898609.511 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-09-15T01:10:09.555Z,1757898609.555 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-09-15T01:10:09.555Z,1757898609.555 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-09-15T01:10:09.961Z,1757898609.961 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-09-15T01:10:09.961Z,1757898609.961 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-09-15T01:10:10.179Z,1757898610.179 [CTD_Seabird] Loaded 2025-09-15T01:10:10.179Z,1757898610.179 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-09-15T01:10:10.181Z,1757898610.181 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BC24E0 2025-09-15T01:10:10.181Z,1757898610.181 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 749 2025-09-15T01:10:10.218Z,1757898610.218 [ESPComponent] Loaded 2025-09-15T01:10:10.219Z,1757898610.219 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-09-15T01:10:10.242Z,1757898610.242 [PAR_Licor] Loaded 2025-09-15T01:10:10.242Z,1757898610.242 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-09-15T01:10:10.288Z,1757898610.288 [WetLabsBB2FL] Loaded 2025-09-15T01:10:10.289Z,1757898610.289 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-09-15T01:10:10.290Z,1757898610.290 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BF24E0 2025-09-15T01:10:10.290Z,1757898610.290 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 750 2025-09-15T01:10:10.291Z,1757898610.291 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-09-15T01:10:10.291Z,1757898610.291 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-09-15T01:10:10.489Z,1757898610.489 [SBIT](DEBUG): Construct Startup Built In Test. 2025-09-15T01:10:10.499Z,1757898610.499 [SBIT] Loaded 2025-09-15T01:10:10.499Z,1757898610.499 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-09-15T01:10:10.502Z,1757898610.502 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-09-15T01:10:10.515Z,1757898610.515 [IBIT] Loaded 2025-09-15T01:10:10.515Z,1757898610.515 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-09-15T01:10:10.521Z,1757898610.521 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-09-15T01:10:10.605Z,1757898610.605 [CBIT] Loaded 2025-09-15T01:10:10.605Z,1757898610.605 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-09-15T01:10:10.638Z,1757898610.638 [GFScanner] Loaded 2025-09-15T01:10:10.639Z,1757898610.639 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-09-15T01:10:10.639Z,1757898610.639 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-09-15T01:10:10.646Z,1757898610.646 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-09-15T01:10:10.649Z,1757898610.649 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-09-15T01:10:10.660Z,1757898610.660 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-09-15T01:10:10.661Z,1757898610.661 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0 2025-09-15T01:10:10.662Z,1757898610.662 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 751 2025-09-15T01:10:10.666Z,1757898610.666 [Supervisor](INFO): Main Thread ID is 825 2025-09-15T01:10:10.666Z,1757898610.666 [Supervisor](DEBUG): Running supervisor. 2025-09-15T01:10:10.667Z,1757898610.667 [CommandExec ThreadHandler](INFO): Handler Thread ID is 752 2025-09-15T01:10:10.667Z,1757898610.667 [CommandExec](INFO): Initializing the command executive. 2025-09-15T01:10:10.669Z,1757898610.669 [CommandLine ThreadHandler](INFO): Handler Thread ID is 753 2025-09-15T01:10:10.671Z,1757898610.671 [controlThread ThreadHandler](INFO): Handler Thread ID is 754 2025-09-15T01:10:10.671Z,1757898610.671 [controlThread](DEBUG): Initializing ControlThread 2025-09-15T01:10:10.672Z,1757898610.672 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-09-15T01:10:10.673Z,1757898610.673 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-09-15T01:10:10.673Z,1757898610.673 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-09-15T01:10:10.673Z,1757898610.673 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-09-15T01:10:10.674Z,1757898610.674 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-09-15T01:10:10.674Z,1757898610.674 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-09-15T01:10:10.676Z,1757898610.676 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-09-15T01:10:10.677Z,1757898610.677 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-09-15T01:10:10.677Z,1757898610.677 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-09-15T01:10:10.678Z,1757898610.678 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-09-15T01:10:10.679Z,1757898610.679 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-09-15T01:10:10.687Z,1757898610.687 [SBIT](INFO): Initialize SBIT Component. 2025-09-15T01:10:10.687Z,1757898610.687 [SBIT](IMPORTANT): git: 2025-09-02 2025-09-15T01:10:10.688Z,1757898610.688 [SBIT](INFO): git hash: 79ab46e6183b98c19ab2829623a4adc5bf79cefb 2025-09-15T01:10:10.688Z,1757898610.688 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-09-15T01:10:10.689Z,1757898610.689 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2025-09-15T01:10:10.690Z,1757898610.690 [SBIT](INFO): Beginning SBIT in 79.000000 seconds. 2025-09-15T01:10:10.691Z,1757898610.691 [IBIT](INFO): Initialize IBIT Component. 2025-09-15T01:10:10.692Z,1757898610.692 [CBIT](DEBUG): Initialize CBIT Component. 2025-09-15T01:10:10.693Z,1757898610.693 [logger ThreadHandler](INFO): Handler Thread ID is 755 2025-09-15T01:10:10.703Z,1757898610.703 [CBIT](DEBUG): Initialized mux pins. 2025-09-15T01:10:10.703Z,1757898610.703 [CBIT](DEBUG): Initializing the watchdog timer. 2025-09-15T01:10:10.711Z,1757898610.711 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 756 2025-09-15T01:10:10.723Z,1757898610.723 [DAT ThreadHandler](INFO): Handler Thread ID is 757 2025-09-15T01:10:10.724Z,1757898610.724 [DAT](INFO): Powering up 2025-09-15T01:10:10.724Z,1757898610.724 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:10:10.727Z,1757898610.727 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-09-15T01:10:10.727Z,1757898610.727 [CBIT](DEBUG): Initializing heartbeat. 2025-09-15T01:10:10.735Z,1757898610.735 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 758 2025-09-15T01:10:10.737Z,1757898610.737 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-09-15T01:10:10.755Z,1757898610.755 [Onboard ThreadHandler](INFO): Handler Thread ID is 759 2025-09-15T01:10:10.773Z,1757898610.773 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 760 2025-09-15T01:10:10.791Z,1757898610.791 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 762 2025-09-15T01:10:10.792Z,1757898610.792 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-09-15T01:10:10.799Z,1757898610.799 [CBIT](DEBUG): Deactivating emergency mode. 2025-09-15T01:10:10.807Z,1757898610.807 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 763 2025-09-15T01:10:10.809Z,1757898610.809 [WetLabsBB2FL](INFO): Powering up 2025-09-15T01:10:10.827Z,1757898610.827 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 764 2025-09-15T01:10:10.836Z,1757898610.836 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-09-15T01:10:10.836Z,1757898610.836 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-09-15T01:10:10.837Z,1757898610.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-09-15T01:10:10.837Z,1757898610.837 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-09-15T01:10:10.837Z,1757898610.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-09-15T01:10:10.837Z,1757898610.837 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-09-15T01:10:10.837Z,1757898610.837 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-09-15T01:10:10.837Z,1757898610.837 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-09-15T01:10:10.839Z,1757898610.839 [CBIT](DEBUG): Backplane powered. 2025-09-15T01:10:10.839Z,1757898610.839 [GFScanner](DEBUG): Initializing GFScanner 2025-09-15T01:10:10.839Z,1757898610.839 [GFScanner](DEBUG): Deactivating GF circuits. 2025-09-15T01:10:10.844Z,1757898610.844 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-09-15T01:10:10.845Z,1757898610.845 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl 2025-09-15T01:10:10.845Z,1757898610.845 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl 2025-09-15T01:10:10.851Z,1757898610.851 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl 2025-09-15T01:10:10.857Z,1757898610.857 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into: 2025-09-15T01:10:10.857Z,1757898610.857 [MissionManager](ERROR): Top node has no child nodes: Mission 2025-09-15T01:10:10.857Z,1757898610.857 [MissionManager](ERROR): Could not load startup mission. 2025-09-15T01:10:10.858Z,1757898610.858 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl 2025-09-15T01:10:10.858Z,1757898610.858 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl 2025-09-15T01:10:10.864Z,1757898610.864 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl 2025-09-15T01:10:11.191Z,1757898611.191 [Radio_Surface](INFO): Powering up 2025-09-15T01:10:11.472Z,1757898611.472 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/DefaultWithUndock.tl translated into: 0 6 10 1 3 30 Timed out while trying to detach from dock at current depth of . Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2025-09-15T01:10:11.491Z,1757898611.491 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min 2025-09-15T01:10:11.515Z,1757898611.515 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h 2025-09-15T01:10:11.520Z,1757898611.520 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count 2025-09-15T01:10:11.547Z,1757898611.547 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s 2025-09-15T01:10:11.551Z,1757898611.551 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count 2025-09-15T01:10:11.571Z,1757898611.571 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min 2025-09-15T01:10:11.572Z,1757898611.572 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait. 2025-09-15T01:10:11.748Z,1757898611.748 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct. 2025-09-15T01:10:11.748Z,1757898611.748 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-09-15T01:10:11.805Z,1757898611.805 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-09-15T01:10:11.859Z,1757898611.859 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-09-15T01:10:11.862Z,1757898611.862 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-09-15T01:10:11.884Z,1757898611.884 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute. 2025-09-15T01:10:11.897Z,1757898611.897 [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 DefaultWithUndock { arguments { ElapsedSinceDefaultStarted = 0 minute DetachTimeout = 6 hour """ Maximum time duration for the vehicle to detach form the dock. """ TransponderCode = Dock:Dock.transponderCode """ Dock transponder address. """ TrackingUpdatePeriod = 10 second """ Time duration between acoustic range queries while the mission is detaching from the dock. """ NumberOfPings = 1 count """ Number of pings requested each time. """ DriftTimeout = 3 minute """ How long to allow vehicle to drift away from dock before retrying reverse """ } 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 = 30 second } aggregate LeaveDock { run in sequence timeout duration=DetachTimeout { syslog important "Timed out while trying to detach from dock at current depth of " + Universal:depth~meter + "." } # Acoustic tracking directives behavior Estimation:TrackAcousticContact { run while ( Universal:depth > Control:VerticalControl.surfaceThreshold ) set contactLabelSetting = TransponderCode set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } # Leave the dock behavior Dock:Undock { run in sequence set driftTimeout = DriftTimeout } } aggregate SurfaceDefault { run in sequence # This mission assumes open water so from here on it's the same old default that's always used 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=P2H { 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-09-15T01:10:11.902Z,1757898611.902 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-09-15T01:10:11.925Z,1757898611.925 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-09-15T01:10:11.987Z,1757898611.987 [Depth_Keller](INFO): Initializing. 2025-09-15T01:10:11.988Z,1757898611.988 [Power24vConverter](INFO): Powering up. 2025-09-15T01:10:11.989Z,1757898611.989 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:10:11.990Z,1757898611.990 [Sonardyne_Nano](INFO): Initializing. 2025-09-15T01:10:12.011Z,1757898612.011 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-09-15T01:10:12.038Z,1757898612.038 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-09-15T01:10:12.038Z,1757898612.038 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-09-15T01:10:12.047Z,1757898612.047 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-09-15T01:10:12.055Z,1757898612.055 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-09-15T01:10:12.056Z,1757898612.056 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-09-15T01:10:12.063Z,1757898612.063 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-09-15T01:10:12.064Z,1757898612.064 [MassServo](DEBUG): Initializing EZServoServo. 2025-09-15T01:10:12.071Z,1757898612.071 [MassServo](DEBUG): Initializing MassServo. 2025-09-15T01:10:12.072Z,1757898612.072 [RudderServo](DEBUG): Initializing EZServoServo. 2025-09-15T01:10:12.079Z,1757898612.079 [RudderServo](DEBUG): Initializing RudderServo. 2025-09-15T01:10:12.080Z,1757898612.080 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-09-15T01:10:12.087Z,1757898612.087 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-09-15T01:10:12.823Z,1757898612.823 [WetLabsBB2FL](INFO): Powering down 2025-09-15T01:10:13.929Z,1757898613.929 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-09-15T01:10:15.587Z,1757898615.587 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207 2025-09-15T01:10:16.443Z,1757898616.443 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11 2025-09-15T01:10:16.444Z,1757898616.444 [Radio_Surface](INFO): Found local IP address 10.89.11.2 for eth0 2025-09-15T01:10:17.684Z,1757898617.684 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-09-15T01:10:17.823Z,1757898617.823 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2025-09-15T01:10:34.132Z,1757898634.132 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-09-15T01:10:34.132Z,1757898634.132 [RDI_Pathfinder] Communications Fault, FailCount= 1 2025-09-15T01:10:34.132Z,1757898634.132 [RDI_Pathfinder](ERROR): Communications Fault 2025-09-15T01:10:34.220Z,1757898634.220 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:10:34.537Z,1757898634.537 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:10:35.407Z,1757898635.407 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:10:35.407Z,1757898635.407 [RDI_Pathfinder] No Fault, FailCount= 1 2025-09-15T01:10:35.748Z,1757898635.748 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:10:39.384Z,1757898639.384 [NAL9602](INFO): Powering up NAL9602 2025-09-15T01:10:41.863Z,1757898641.863 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:10:50.296Z,1757898650.296 [NAL9602](INFO): NAL9602 initialized 2025-09-15T01:10:57.977Z,1757898657.977 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-09-15T01:10:57.977Z,1757898657.977 [RDI_Pathfinder] Communications Fault, FailCount= 2 2025-09-15T01:10:57.977Z,1757898657.977 [RDI_Pathfinder](ERROR): Communications Fault 2025-09-15T01:10:57.995Z,1757898657.995 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:10:58.374Z,1757898658.374 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:10:59.200Z,1757898659.200 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:10:59.200Z,1757898659.200 [RDI_Pathfinder] No Fault, FailCount= 2 2025-09-15T01:10:59.603Z,1757898659.603 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:11:05.649Z,1757898665.649 [NAL9602](INFO): SBD MO Status=0, MOMSN=1708, MT Status=0, MTMSN=0 2025-09-15T01:11:05.650Z,1757898665.650 [NAL9602](INFO): No messages in MT queue 2025-09-15T01:11:10.739Z,1757898670.739 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:11:10.739Z,1757898670.739 [DAT] Communications Fault, FailCount= 1 2025-09-15T01:11:10.739Z,1757898670.739 [DAT](ERROR): Communications Fault 2025-09-15T01:11:10.916Z,1757898670.916 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:11:11.143Z,1757898671.143 [DAT](INFO): Powering down 2025-09-15T01:11:11.547Z,1757898671.547 [DAT](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2025-09-15T01:11:11.547Z,1757898671.547 [DAT] Hardware Fault, FailCount= 1 2025-09-15T01:11:11.547Z,1757898671.547 [DAT](ERROR): Hardware Fault 2025-09-15T01:11:12.128Z,1757898672.128 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:11:12.128Z,1757898672.128 [DAT] No Fault, FailCount= 1 2025-09-15T01:11:12.987Z,1757898672.987 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:11:14.375Z,1757898674.375 [DAT](INFO): Powering up 2025-09-15T01:11:14.375Z,1757898674.375 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:11:21.819Z,1757898681.819 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-09-15T01:11:21.819Z,1757898681.819 [RDI_Pathfinder] Communications Fault, FailCount= 3 2025-09-15T01:11:21.819Z,1757898681.819 [RDI_Pathfinder](ERROR): Communications Fault 2025-09-15T01:11:21.858Z,1757898681.858 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:11:21.858Z,1757898681.858 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:11:22.223Z,1757898682.223 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:11:22.261Z,1757898682.261 [CommandExec](FAULT): Scheduling is paused 2025-09-15T01:11:22.263Z,1757898682.263 [CBIT](INFO): Critical error at 20250915T011121 2025-09-15T01:11:22.263Z,1757898682.263 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-09-15T01:11:30.305Z,1757898690.305 [SBIT](IMPORTANT): Beginning Startup BIT 2025-09-15T01:11:30.330Z,1757898690.330 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-09-15T01:11:34.821Z,1757898694.821 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.004988 CHAN A1 (24V): -0.169805 CHAN A2 (12V): -0.003011 CHAN A3 (5V): -0.002747 CHAN B0 (3.3V): -0.000194 CHAN B1 (3.15aV): 0.000724 CHAN B2 (3.15bV): 0.000272 CHAN B3 (GND): -0.006611 OPEN: 0.005394 Full Scale: +/- 1 mA 2025-09-15T01:11:44.112Z,1757898704.112 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:12:14.407Z,1757898734.407 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:12:14.407Z,1757898734.407 [DAT] Communications Fault, FailCount= 2 2025-09-15T01:12:14.407Z,1757898734.407 [DAT](ERROR): Communications Fault 2025-09-15T01:12:14.519Z,1757898734.519 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:12:14.816Z,1757898734.816 [DAT](INFO): Powering down 2025-09-15T01:12:15.235Z,1757898735.235 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:12:15.947Z,1757898735.947 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:12:15.947Z,1757898735.947 [DAT] No Fault, FailCount= 2 2025-09-15T01:12:18.051Z,1757898738.051 [DAT](INFO): Powering up 2025-09-15T01:12:18.051Z,1757898738.051 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:12:24.533Z,1757898744.533 [SBIT](IMPORTANT): SBIT PASSED 2025-09-15T01:12:24.533Z,1757898744.533 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-09-15T01:12:24.534Z,1757898744.534 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=26 count; 2025-09-15T01:12:24.534Z,1757898744.534 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2025-09-15T01:12:24.535Z,1757898744.535 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=5 minute; 2025-09-15T01:12:24.535Z,1757898744.535 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool; 2025-09-15T01:12:24.535Z,1757898744.535 [SBIT](IMPORTANT): Express linearApproximation DAT.acoustic_contact_range 15.000000 meter; 2025-09-15T01:12:24.535Z,1757898744.535 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=-0.5 ampere_hour; 2025-09-15T01:12:24.535Z,1757898744.535 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=9 volt; 2025-09-15T01:12:24.535Z,1757898744.535 [SBIT](IMPORTANT): SpeedControl.rollOptimum=0.800000 m/s; 2025-09-15T01:12:24.535Z,1757898744.535 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=90 cubic_centimeter; 2025-09-15T01:12:24.535Z,1757898744.535 [SBIT](IMPORTANT): VerticalControl.massDefault=-1.5 centimeter; 2025-09-15T01:12:24.945Z,1757898744.945 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-09-15T01:12:24.945Z,1757898744.945 [DefaultWithUndock] Running Loop=1 2025-09-15T01:12:24.945Z,1757898744.945 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-09-15T01:12:24.945Z,1757898744.945 [DefaultWithUndock:A.Wait] Running Loop=1 2025-09-15T01:12:24.945Z,1757898744.945 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-09-15T01:12:30.970Z,1757898750.970 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011227.00,A,3646.18156,N,12152.11594,W,0.719,171.24,150925,,,A*7E 2025-09-15T01:12:30.982Z,1757898750.982 [NAL9602](INFO): GPS fix at 20250915T011227: (36.769693, -121.868599) 2025-09-15T01:12:46.375Z,1757898766.375 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:12:53.916Z,1757898773.916 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003907 2025-09-15T01:12:54.915Z,1757898774.915 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:12:55.225Z,1757898775.225 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2025-09-15T01:12:55.226Z,1757898775.226 [DefaultWithUndock:A.Wait] Stopped 2025-09-15T01:12:55.226Z,1757898775.226 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-09-15T01:12:55.226Z,1757898775.226 [DefaultWithUndock:LeaveDock] Running Loop=1 2025-09-15T01:12:55.226Z,1757898775.226 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2025-09-15T01:12:55.226Z,1757898775.226 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-09-15T01:12:55.226Z,1757898775.226 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2025-09-15T01:12:55.227Z,1757898775.227 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2025-09-15T01:12:55.227Z,1757898775.227 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-09-15T01:12:55.227Z,1757898775.227 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-09-15T01:12:55.643Z,1757898775.643 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-09-15T01:12:55.644Z,1757898775.644 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-09-15T01:12:55.645Z,1757898775.645 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth 0.075745 m. 2025-09-15T01:12:55.645Z,1757898775.645 [DefaultWithUndock:LeaveDock:A.] Stopped 2025-09-15T01:12:55.645Z,1757898775.645 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2025-09-15T01:12:56.048Z,1757898776.048 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-09-15T01:12:56.460Z,1757898776.460 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-09-15T01:12:56.580Z,1757898776.580 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-09-15T01:13:03.289Z,1757898783.289 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-15T01:13:03.527Z,1757898783.527 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:13:16.232Z,1757898796.232 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby. 2025-09-15T01:13:16.639Z,1757898796.639 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2025-09-15T01:13:17.045Z,1757898797.045 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2025-09-15T01:13:17.045Z,1757898797.045 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2025-09-15T01:13:17.045Z,1757898797.045 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2025-09-15T01:13:17.046Z,1757898797.046 [DefaultWithUndock:LeaveDock] Stopped 2025-09-15T01:13:17.046Z,1757898797.046 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2025-09-15T01:13:17.046Z,1757898797.046 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2025-09-15T01:13:17.046Z,1757898797.046 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2025-09-15T01:13:17.046Z,1757898797.046 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2025-09-15T01:13:17.046Z,1757898797.046 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-09-15T01:13:17.047Z,1757898797.047 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-09-15T01:13:17.047Z,1757898797.047 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-09-15T01:13:17.047Z,1757898797.047 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-09-15T01:13:17.048Z,1757898797.048 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-09-15T01:13:17.048Z,1757898797.048 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-09-15T01:13:17.048Z,1757898797.048 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-09-15T01:13:17.460Z,1757898797.460 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2025-09-15T01:13:17.460Z,1757898797.460 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-09-15T01:13:17.460Z,1757898797.460 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-09-15T01:13:17.845Z,1757898797.845 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-09-15T01:13:18.063Z,1757898798.063 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:13:18.063Z,1757898798.063 [DAT] Communications Fault, FailCount= 3 2025-09-15T01:13:18.063Z,1757898798.063 [DAT](ERROR): Communications Fault 2025-09-15T01:13:18.308Z,1757898798.308 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:13:18.467Z,1757898798.467 [DAT](INFO): Powering down 2025-09-15T01:13:19.452Z,1757898799.452 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011315.00,A,3646.18282,N,12152.11235,W,0.641,94.81,150925,,,A*41 2025-09-15T01:13:19.455Z,1757898799.455 [NAL9602](INFO): GPS fix at 20250915T011315: (36.769714, -121.868539) 2025-09-15T01:13:19.466Z,1757898799.466 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-09-15T01:13:19.466Z,1757898799.466 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-09-15T01:13:19.520Z,1757898799.520 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:13:19.520Z,1757898799.520 [DAT] No Fault, FailCount= 3 2025-09-15T01:13:19.941Z,1757898799.941 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-09-15T01:13:21.695Z,1757898801.695 [DAT](INFO): Powering up 2025-09-15T01:13:21.695Z,1757898801.695 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:13:29.149Z,1757898809.149 [NAL9602](INFO): SBD MO Status=0, MOMSN=1709, MT Status=0, MTMSN=0 2025-09-15T01:13:29.149Z,1757898809.149 [NAL9602](INFO): No messages in MT queue 2025-09-15T01:13:34.395Z,1757898814.395 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-15T01:13:42.158Z,1757898822.158 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20250914T080941/Courier0099.lzma 2025-09-15T01:13:43.161Z,1757898823.161 [DataOverHttps](INFO): Moved sent file to Logs/20250914T080941/Courier0099.lzma.bak 2025-09-15T01:13:43.161Z,1757898823.161 [DataOverHttps](INFO): SBD MOMSN=25989371 2025-09-15T01:13:59.907Z,1757898839.907 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-15T01:14:21.751Z,1757898861.751 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:14:21.751Z,1757898861.751 [DAT] Communications Fault, FailCount= 4 2025-09-15T01:14:21.751Z,1757898861.751 [DAT](ERROR): Communications Fault 2025-09-15T01:14:22.111Z,1757898862.111 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:14:22.111Z,1757898862.111 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:14:22.111Z,1757898862.111 [RDI_Pathfinder] No Fault, FailCount= 3 2025-09-15T01:14:22.155Z,1757898862.155 [DAT](INFO): Powering down 2025-09-15T01:14:22.492Z,1757898862.492 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:14:23.316Z,1757898863.316 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:14:23.316Z,1757898863.316 [DAT] No Fault, FailCount= 4 2025-09-15T01:14:25.383Z,1757898865.383 [DAT](INFO): Powering up 2025-09-15T01:14:25.383Z,1757898865.383 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:14:35.003Z,1757898875.003 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2025-09-15T01:14:35.003Z,1757898875.003 [RDI_Pathfinder] Data Fault, FailCount= 1 2025-09-15T01:14:35.003Z,1757898875.003 [RDI_Pathfinder](ERROR): Data Fault 2025-09-15T01:14:35.071Z,1757898875.071 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2025-09-15T01:14:35.404Z,1757898875.404 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:14:36.311Z,1757898876.311 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:14:36.311Z,1757898876.311 [RDI_Pathfinder] No Fault, FailCount= 1 2025-09-15T01:14:36.636Z,1757898876.636 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:14:48.891Z,1757898888.891 [DataOverHttps](INFO): Sending 336 bytes from file Logs/20250915T010958/Courier0000.lzma 2025-09-15T01:14:49.892Z,1757898889.892 [DataOverHttps](INFO): Moved sent file to Logs/20250915T010958/Courier0000.lzma.bak 2025-09-15T01:14:49.893Z,1757898889.893 [DataOverHttps](INFO): SBD MOMSN=25989375 2025-09-15T01:14:58.833Z,1757898898.833 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-09-15T01:14:58.833Z,1757898898.833 [RDI_Pathfinder] Communications Fault, FailCount= 2 2025-09-15T01:14:58.833Z,1757898898.833 [RDI_Pathfinder](ERROR): Communications Fault 2025-09-15T01:14:58.853Z,1757898898.853 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:14:59.244Z,1757898899.244 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:15:00.088Z,1757898900.088 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:15:00.088Z,1757898900.088 [RDI_Pathfinder] No Fault, FailCount= 2 2025-09-15T01:15:00.452Z,1757898900.452 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:15:08.952Z,1757898908.952 [DataOverHttps](INFO): Sending 609 bytes from file Logs/20250914T080941/Express0100.lzma 2025-09-15T01:15:11.659Z,1757898911.659 [DataOverHttps](INFO): Moved sent file to Logs/20250914T080941/Express0100.lzma.bak 2025-09-15T01:15:11.659Z,1757898911.659 [DataOverHttps](INFO): SBD MOMSN=25989380 2025-09-15T01:15:22.673Z,1757898922.673 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-09-15T01:15:22.673Z,1757898922.673 [RDI_Pathfinder] Communications Fault, FailCount= 3 2025-09-15T01:15:22.673Z,1757898922.673 [RDI_Pathfinder](ERROR): Communications Fault 2025-09-15T01:15:22.722Z,1757898922.722 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:15:22.722Z,1757898922.722 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:15:23.088Z,1757898923.088 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:15:23.115Z,1757898923.115 [CBIT](INFO): Critical error at 20250915T011522 2025-09-15T01:15:25.423Z,1757898925.423 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:15:25.423Z,1757898925.423 [DAT] Communications Fault, FailCount= 5 2025-09-15T01:15:25.423Z,1757898925.423 [DAT](ERROR): Communications Fault 2025-09-15T01:15:25.528Z,1757898925.528 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:15:25.827Z,1757898925.827 [DAT](INFO): Powering down 2025-09-15T01:15:26.735Z,1757898926.735 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:15:26.735Z,1757898926.735 [DAT] No Fault, FailCount= 5 2025-09-15T01:15:29.064Z,1757898929.064 [DAT](INFO): Powering up 2025-09-15T01:15:29.064Z,1757898929.064 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:15:40.034Z,1757898940.034 [DataOverHttps](INFO): Sending 1391 bytes from file Logs/20250915T010958/Express0001.lzma 2025-09-15T01:16:01.111Z,1757898961.111 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-15T01:16:17.661Z,1757898977.661 [DataOverHttps](INFO): Sending 1391 bytes from file Logs/20250915T010958/Express0001.lzma 2025-09-15T01:16:29.079Z,1757898989.079 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:16:29.079Z,1757898989.079 [DAT] Communications Fault, FailCount= 6 2025-09-15T01:16:29.079Z,1757898989.079 [DAT](ERROR): Communications Fault 2025-09-15T01:16:29.392Z,1757898989.392 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:16:29.483Z,1757898989.483 [DAT](INFO): Powering down 2025-09-15T01:16:29.706Z,1757898989.706 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.185905 2025-09-15T01:16:30.694Z,1757898990.694 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:16:30.694Z,1757898990.694 [DAT] No Fault, FailCount= 6 2025-09-15T01:16:32.711Z,1757898992.711 [DAT](INFO): Powering up 2025-09-15T01:16:32.711Z,1757898992.711 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:16:47.085Z,1757899007.085 [NAL9602](INFO): SBD MO Status=1, MOMSN=1710, MT Status=0, MTMSN=0 2025-09-15T01:16:47.147Z,1757899007.147 [NAL9602](INFO): Sent 332 bytes from file Logs/20250915T010958/Express0001.lzma 2025-09-15T01:16:47.147Z,1757899007.147 [NAL9602](INFO): Packets left to send: 4 2025-09-15T01:17:00.835Z,1757899020.835 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-15T01:17:01.839Z,1757899021.839 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:17:02.037Z,1757899022.037 [NAL9602](INFO): SBD MO Status=2, MOMSN=1711, MT Status=2, MTMSN=0 2025-09-15T01:17:02.037Z,1757899022.037 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-15T01:17:05.339Z,1757899025.339 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-15T01:17:05.340Z,1757899025.340 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-15T01:17:05.340Z,1757899025.340 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-15T01:17:05.341Z,1757899025.341 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-15T01:17:05.342Z,1757899025.342 [BPC1](ERROR): Battery stick #44 (s/n: 00E4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-15T01:17:05.343Z,1757899025.343 [BPC1](ERROR): Battery stick #54 (s/n: 009C) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-15T01:17:05.343Z,1757899025.343 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-15T01:17:05.344Z,1757899025.344 [BPC1](INFO): Calculating totals. Valid battery stick count: 28. Valid reserve battery stick count: 2. 2025-09-15T01:17:05.347Z,1757899025.347 [BPC1](INFO): Received data from all battery sticks. 2025-09-15T01:17:21.858Z,1757899041.858 [NAL9602](INFO): SBD MO Status=1, MOMSN=1711, MT Status=0, MTMSN=0 2025-09-15T01:17:21.919Z,1757899041.919 [NAL9602](INFO): Sent 332 bytes from file Logs/20250915T010958/Express0001.lzma 2025-09-15T01:17:21.919Z,1757899041.919 [NAL9602](INFO): Packets left to send: 3 2025-09-15T01:17:30.118Z,1757899050.118 [NAL9602](INFO): SBD MO Status=1, MOMSN=1712, MT Status=0, MTMSN=0 2025-09-15T01:17:30.171Z,1757899050.171 [NAL9602](INFO): Sent 332 bytes from file Logs/20250915T010958/Express0001.lzma 2025-09-15T01:17:30.171Z,1757899050.171 [NAL9602](INFO): Packets left to send: 2 2025-09-15T01:17:31.987Z,1757899051.987 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-15T01:17:32.743Z,1757899052.743 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:17:32.743Z,1757899052.743 [DAT] Communications Fault, FailCount= 7 2025-09-15T01:17:32.743Z,1757899052.743 [DAT](ERROR): Communications Fault 2025-09-15T01:17:32.991Z,1757899052.991 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:17:33.147Z,1757899053.147 [DAT](INFO): Powering down 2025-09-15T01:17:33.688Z,1757899053.688 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:17:34.151Z,1757899054.151 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:17:34.151Z,1757899054.151 [DAT] No Fault, FailCount= 7 2025-09-15T01:17:36.375Z,1757899056.375 [DAT](INFO): Powering up 2025-09-15T01:17:36.375Z,1757899056.375 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:17:42.594Z,1757899062.594 [NAL9602](INFO): SBD MO Status=1, MOMSN=1713, MT Status=0, MTMSN=0 2025-09-15T01:17:42.647Z,1757899062.647 [NAL9602](INFO): Sent 332 bytes from file Logs/20250915T010958/Express0001.lzma 2025-09-15T01:17:42.647Z,1757899062.647 [NAL9602](INFO): Packets left to send: 1 2025-09-15T01:17:53.701Z,1757899073.701 [NAL9602](INFO): SBD MO Status=1, MOMSN=1714, MT Status=0, MTMSN=0 2025-09-15T01:17:53.759Z,1757899073.759 [NAL9602](INFO): Sent 63 bytes from file Logs/20250915T010958/Express0001.lzma 2025-09-15T01:17:53.759Z,1757899073.759 [NAL9602](INFO): Packets left to send: 0 2025-09-15T01:18:03.111Z,1757899083.111 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-15T01:18:04.115Z,1757899084.115 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:18:04.249Z,1757899084.249 [NAL9602](INFO): SBD MO Status=0, MOMSN=1715, MT Status=0, MTMSN=0 2025-09-15T01:18:04.339Z,1757899084.339 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-09-15T01:18:04.339Z,1757899084.339 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-09-15T01:18:04.339Z,1757899084.339 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-15T01:18:22.852Z,1757899102.852 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:18:22.852Z,1757899102.852 [RDI_Pathfinder] No Fault, FailCount= 3 2025-09-15T01:18:23.232Z,1757899103.232 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:18:34.243Z,1757899114.243 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-15T01:18:34.955Z,1757899114.955 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-15T01:18:35.247Z,1757899115.247 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:18:35.760Z,1757899115.760 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2025-09-15T01:18:35.761Z,1757899115.761 [RDI_Pathfinder] Data Fault, FailCount= 1 2025-09-15T01:18:35.761Z,1757899115.761 [RDI_Pathfinder](ERROR): Data Fault 2025-09-15T01:18:35.821Z,1757899115.821 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2025-09-15T01:18:36.164Z,1757899116.164 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:18:36.455Z,1757899116.455 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:18:36.455Z,1757899116.455 [DAT] Communications Fault, FailCount= 8 2025-09-15T01:18:36.455Z,1757899116.455 [DAT](ERROR): Communications Fault 2025-09-15T01:18:36.592Z,1757899116.592 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:18:36.593Z,1757899116.593 [CBIT](FAULT): Communications Fault in component: DAT 2025-09-15T01:18:36.859Z,1757899116.859 [DAT](INFO): Powering down 2025-09-15T01:18:37.012Z,1757899117.012 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:18:37.012Z,1757899117.012 [RDI_Pathfinder] No Fault, FailCount= 1 2025-09-15T01:18:37.377Z,1757899117.377 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:18:59.596Z,1757899139.596 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-09-15T01:18:59.597Z,1757899139.597 [RDI_Pathfinder] Communications Fault, FailCount= 2 2025-09-15T01:18:59.597Z,1757899139.597 [RDI_Pathfinder](ERROR): Communications Fault 2025-09-15T01:18:59.636Z,1757899139.636 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:19:00.004Z,1757899140.004 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:19:00.835Z,1757899140.835 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:19:00.835Z,1757899140.835 [RDI_Pathfinder] No Fault, FailCount= 2 2025-09-15T01:19:01.235Z,1757899141.235 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:19:05.367Z,1757899145.367 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-15T01:19:06.371Z,1757899146.371 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:19:23.435Z,1757899163.435 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-09-15T01:19:23.435Z,1757899163.435 [RDI_Pathfinder] Communications Fault, FailCount= 3 2025-09-15T01:19:23.435Z,1757899163.435 [RDI_Pathfinder](ERROR): Communications Fault 2025-09-15T01:19:23.455Z,1757899163.455 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:19:23.455Z,1757899163.455 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:19:23.838Z,1757899163.838 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:19:23.856Z,1757899163.856 [CBIT](INFO): Critical error at 20250915T011923 2025-09-15T01:19:36.535Z,1757899176.535 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-15T01:19:37.539Z,1757899177.539 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:20:07.675Z,1757899207.675 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-15T01:20:08.679Z,1757899208.679 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:20:38.799Z,1757899238.799 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-09-15T01:20:39.803Z,1757899239.803 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:21:01.315Z,1757899261.315 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002770 2025-09-15T01:21:02.315Z,1757899262.315 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:21:25.883Z,1757899285.883 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:21:34.368Z,1757899294.368 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:21:43.751Z,1757899303.751 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:21:57.079Z,1757899317.079 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:22:15.223Z,1757899335.223 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:22:15.544Z,1757899335.544 [Power24vConverter](INFO): Powering down. 2025-09-15T01:22:23.647Z,1757899343.647 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:22:23.647Z,1757899343.647 [RDI_Pathfinder] No Fault, FailCount= 3 2025-09-15T01:22:24.052Z,1757899344.052 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:22:24.841Z,1757899344.841 [Power24vConverter](INFO): Powering up. 2025-09-15T01:22:25.607Z,1757899345.607 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:22:34.115Z,1757899354.115 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:22:36.552Z,1757899356.552 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2025-09-15T01:22:36.552Z,1757899356.552 [RDI_Pathfinder] Data Fault, FailCount= 1 2025-09-15T01:22:36.552Z,1757899356.552 [RDI_Pathfinder](ERROR): Data Fault 2025-09-15T01:22:36.633Z,1757899356.633 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2025-09-15T01:22:36.956Z,1757899356.956 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:22:37.816Z,1757899357.816 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:22:37.816Z,1757899357.816 [RDI_Pathfinder] No Fault, FailCount= 1 2025-09-15T01:22:38.169Z,1757899358.169 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:22:43.619Z,1757899363.619 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:22:51.955Z,1757899371.955 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:23:00.388Z,1757899380.388 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-09-15T01:23:00.388Z,1757899380.388 [RDI_Pathfinder] Communications Fault, FailCount= 2 2025-09-15T01:23:00.388Z,1757899380.388 [RDI_Pathfinder](ERROR): Communications Fault 2025-09-15T01:23:00.408Z,1757899380.408 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:23:00.547Z,1757899380.547 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:23:00.800Z,1757899380.800 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:23:01.640Z,1757899381.640 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:23:01.640Z,1757899381.640 [RDI_Pathfinder] No Fault, FailCount= 2 2025-09-15T01:23:02.006Z,1757899382.006 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:23:04.854Z,1757899384.854 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-15T01:23:04.887Z,1757899384.887 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-09-15T01:23:04.887Z,1757899384.887 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-15T01:23:04.887Z,1757899384.887 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-09-15T01:23:05.258Z,1757899385.258 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-09-15T01:23:05.258Z,1757899385.258 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-09-15T01:23:05.676Z,1757899385.676 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 10.671884 min 2025-09-15T01:23:05.677Z,1757899385.677 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-09-15T01:23:05.677Z,1757899385.677 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-09-15T01:23:05.678Z,1757899385.678 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-09-15T01:23:05.678Z,1757899385.678 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-09-15T01:23:05.683Z,1757899385.683 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2 2025-09-15T01:23:05.683Z,1757899385.683 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2 2025-09-15T01:23:05.683Z,1757899385.683 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-09-15T01:23:05.683Z,1757899385.683 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-09-15T01:23:07.661Z,1757899387.661 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012307.00,A,3646.12817,N,12152.05493,W,1.225,185.67,150925,,,A*7C 2025-09-15T01:23:07.664Z,1757899387.664 [NAL9602](INFO): GPS fix at 20250915T012307: (36.768803, -121.867582) 2025-09-15T01:23:07.677Z,1757899387.677 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-09-15T01:23:07.677Z,1757899387.677 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-09-15T01:23:15.391Z,1757899395.391 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20250915T010958/Courier0004.lzma 2025-09-15T01:23:16.392Z,1757899396.392 [DataOverHttps](INFO): Moved sent file to Logs/20250915T010958/Courier0004.lzma.bak 2025-09-15T01:23:16.393Z,1757899396.393 [DataOverHttps](INFO): SBD MOMSN=25989447 2025-09-15T01:23:23.824Z,1757899403.824 [NAL9602](INFO): SBD MO Status=0, MOMSN=1716, MT Status=0, MTMSN=0 2025-09-15T01:23:23.824Z,1757899403.824 [NAL9602](INFO): No messages in MT queue 2025-09-15T01:23:24.237Z,1757899404.237 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-09-15T01:23:24.237Z,1757899404.237 [RDI_Pathfinder] Communications Fault, FailCount= 3 2025-09-15T01:23:24.237Z,1757899404.237 [RDI_Pathfinder](ERROR): Communications Fault 2025-09-15T01:23:24.257Z,1757899404.257 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:23:24.257Z,1757899404.257 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:23:24.629Z,1757899404.629 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:23:24.664Z,1757899404.664 [CBIT](INFO): Critical error at 20250915T012324 2025-09-15T01:23:32.279Z,1757899412.279 [DataOverHttps](INFO): Sending 463 bytes from file Logs/20250915T010958/Express0005.lzma 2025-09-15T01:23:33.281Z,1757899413.281 [DataOverHttps](INFO): Moved sent file to Logs/20250915T010958/Express0005.lzma.bak 2025-09-15T01:23:33.281Z,1757899413.281 [DataOverHttps](INFO): SBD MOMSN=25989462 2025-09-15T01:23:35.150Z,1757899415.150 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-09-15T01:23:35.150Z,1757899415.150 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-09-15T01:23:35.150Z,1757899415.150 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-15T01:23:36.791Z,1757899416.791 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:23:36.791Z,1757899416.791 [DAT] No Fault, FailCount= 8 2025-09-15T01:23:37.043Z,1757899417.043 [DAT](INFO): Powering up 2025-09-15T01:23:37.044Z,1757899417.044 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:23:43.791Z,1757899423.791 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:23:53.203Z,1757899433.203 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:23:54.545Z,1757899434.545 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-15T01:24:03.547Z,1757899443.547 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:24:18.043Z,1757899458.043 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:24:28.523Z,1757899468.523 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:24:37.075Z,1757899477.075 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:24:37.075Z,1757899477.075 [DAT] Communications Fault, FailCount= 1 2025-09-15T01:24:37.075Z,1757899477.075 [DAT](ERROR): Communications Fault 2025-09-15T01:24:37.402Z,1757899477.402 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:24:37.479Z,1757899477.479 [DAT](INFO): Powering down 2025-09-15T01:24:37.899Z,1757899477.899 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:24:38.604Z,1757899478.604 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:24:38.604Z,1757899478.604 [DAT] No Fault, FailCount= 1 2025-09-15T01:24:40.707Z,1757899480.707 [DAT](INFO): Powering up 2025-09-15T01:24:40.707Z,1757899480.707 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:24:47.279Z,1757899487.279 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:24:55.671Z,1757899495.671 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:25:05.907Z,1757899505.907 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:25:14.275Z,1757899514.275 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:25:22.703Z,1757899522.703 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:25:33.023Z,1757899533.023 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:25:40.715Z,1757899540.715 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:25:40.715Z,1757899540.715 [DAT] Communications Fault, FailCount= 2 2025-09-15T01:25:40.715Z,1757899540.715 [DAT](ERROR): Communications Fault 2025-09-15T01:25:40.815Z,1757899540.815 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:25:41.119Z,1757899541.119 [DAT](INFO): Powering down 2025-09-15T01:25:42.023Z,1757899542.023 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:25:42.023Z,1757899542.023 [DAT] No Fault, FailCount= 2 2025-09-15T01:25:42.735Z,1757899542.735 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:25:44.355Z,1757899544.355 [DAT](INFO): Powering up 2025-09-15T01:25:44.355Z,1757899544.355 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:25:57.179Z,1757899557.179 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:26:06.663Z,1757899566.663 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:26:16.132Z,1757899576.132 [DataOverHttps](IMPORTANT): SBD MTMSN=20250915T012615 2025-09-15T01:26:23.589Z,1757899583.589 [DataOverHttps](INFO): Received command: schedule resume 2025-09-15T01:26:23.641Z,1757899583.641 [CommandExec](IMPORTANT): got command schedule resume 2025-09-15T01:26:23.641Z,1757899583.641 [CommandExec](IMPORTANT): Scheduling is resumed 2025-09-15T01:26:24.453Z,1757899584.453 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:26:24.453Z,1757899584.453 [RDI_Pathfinder] No Fault, FailCount= 3 2025-09-15T01:26:24.813Z,1757899584.813 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:26:25.587Z,1757899585.587 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:26:37.348Z,1757899597.348 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2025-09-15T01:26:37.348Z,1757899597.348 [RDI_Pathfinder] Data Fault, FailCount= 1 2025-09-15T01:26:37.348Z,1757899597.348 [RDI_Pathfinder](ERROR): Data Fault 2025-09-15T01:26:37.417Z,1757899597.417 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2025-09-15T01:26:37.761Z,1757899597.761 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:26:38.602Z,1757899598.602 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:26:38.602Z,1757899598.602 [RDI_Pathfinder] No Fault, FailCount= 1 2025-09-15T01:26:38.956Z,1757899598.956 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:26:44.451Z,1757899604.451 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:26:44.451Z,1757899604.451 [DAT] Communications Fault, FailCount= 3 2025-09-15T01:26:44.451Z,1757899604.451 [DAT](ERROR): Communications Fault 2025-09-15T01:26:44.651Z,1757899604.651 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:26:44.855Z,1757899604.855 [DAT](INFO): Powering down 2025-09-15T01:26:45.840Z,1757899605.840 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:26:45.841Z,1757899605.841 [DAT] No Fault, FailCount= 3 2025-09-15T01:26:47.003Z,1757899607.003 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:26:48.091Z,1757899608.091 [DAT](INFO): Powering up 2025-09-15T01:26:48.091Z,1757899608.091 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:26:55.423Z,1757899615.423 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:27:01.189Z,1757899621.189 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-09-15T01:27:01.189Z,1757899621.189 [RDI_Pathfinder] Communications Fault, FailCount= 2 2025-09-15T01:27:01.189Z,1757899621.189 [RDI_Pathfinder](ERROR): Communications Fault 2025-09-15T01:27:01.276Z,1757899621.276 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:27:01.627Z,1757899621.627 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:27:02.420Z,1757899622.420 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:27:02.420Z,1757899622.420 [RDI_Pathfinder] No Fault, FailCount= 2 2025-09-15T01:27:02.788Z,1757899622.788 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:27:05.075Z,1757899625.075 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:27:13.444Z,1757899633.444 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:27:22.871Z,1757899642.871 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:27:25.008Z,1757899645.008 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-09-15T01:27:25.008Z,1757899645.008 [RDI_Pathfinder] Communications Fault, FailCount= 3 2025-09-15T01:27:25.008Z,1757899645.008 [RDI_Pathfinder](ERROR): Communications Fault 2025-09-15T01:27:25.064Z,1757899645.064 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:27:25.065Z,1757899645.065 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:27:25.413Z,1757899645.413 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:27:25.431Z,1757899645.431 [CommandExec](FAULT): Scheduling is paused 2025-09-15T01:27:25.432Z,1757899645.432 [CBIT](INFO): Critical error at 20250915T012725 2025-09-15T01:27:32.347Z,1757899652.347 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:27:40.743Z,1757899660.743 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:27:48.151Z,1757899668.151 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:27:48.151Z,1757899668.151 [DAT] Communications Fault, FailCount= 4 2025-09-15T01:27:48.151Z,1757899668.151 [DAT](ERROR): Communications Fault 2025-09-15T01:27:48.464Z,1757899668.464 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:27:48.555Z,1757899668.555 [DAT](INFO): Powering down 2025-09-15T01:27:49.207Z,1757899669.207 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:27:49.675Z,1757899669.675 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:27:49.675Z,1757899669.675 [DAT] No Fault, FailCount= 4 2025-09-15T01:27:51.783Z,1757899671.783 [DAT](INFO): Powering up 2025-09-15T01:27:51.783Z,1757899671.783 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:27:59.703Z,1757899679.703 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:28:10.987Z,1757899690.987 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:28:20.339Z,1757899700.339 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:28:28.871Z,1757899708.871 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:28:35.734Z,1757899715.734 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-15T01:28:35.734Z,1757899715.734 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-09-15T01:28:35.734Z,1757899715.734 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-15T01:28:35.734Z,1757899715.734 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-09-15T01:28:36.142Z,1757899716.142 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-09-15T01:28:36.142Z,1757899716.142 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-09-15T01:28:36.546Z,1757899716.546 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 16.186611 min 2025-09-15T01:28:36.546Z,1757899716.546 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-09-15T01:28:36.546Z,1757899716.546 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-09-15T01:28:36.546Z,1757899716.546 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-09-15T01:28:36.546Z,1757899716.546 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-09-15T01:28:36.547Z,1757899716.547 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #3 2025-09-15T01:28:36.547Z,1757899716.547 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=3 2025-09-15T01:28:36.547Z,1757899716.547 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-09-15T01:28:36.547Z,1757899716.547 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-09-15T01:28:37.331Z,1757899717.331 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:28:38.553Z,1757899718.553 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012838.00,A,3646.09529,N,12152.02770,W,0.505,119.82,150925,,,A*72 2025-09-15T01:28:38.555Z,1757899718.555 [NAL9602](INFO): GPS fix at 20250915T012838: (36.768255, -121.867128) 2025-09-15T01:28:38.583Z,1757899718.583 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-09-15T01:28:38.583Z,1757899718.583 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-09-15T01:28:45.422Z,1757899725.422 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20250915T010958/Courier0007.lzma 2025-09-15T01:28:46.425Z,1757899726.425 [DataOverHttps](INFO): Moved sent file to Logs/20250915T010958/Courier0007.lzma.bak 2025-09-15T01:28:46.425Z,1757899726.425 [DataOverHttps](INFO): SBD MOMSN=25989499 2025-09-15T01:28:51.795Z,1757899731.795 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:28:51.795Z,1757899731.795 [DAT] Communications Fault, FailCount= 5 2025-09-15T01:28:51.795Z,1757899731.795 [DAT](ERROR): Communications Fault 2025-09-15T01:28:51.951Z,1757899731.951 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:28:52.199Z,1757899732.199 [DAT](INFO): Powering down 2025-09-15T01:28:53.137Z,1757899733.137 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:28:53.137Z,1757899733.137 [DAT] No Fault, FailCount= 5 2025-09-15T01:28:54.713Z,1757899734.713 [NAL9602](INFO): SBD MO Status=0, MOMSN=1717, MT Status=0, MTMSN=0 2025-09-15T01:28:54.713Z,1757899734.713 [NAL9602](INFO): No messages in MT queue 2025-09-15T01:28:55.427Z,1757899735.427 [DAT](INFO): Powering up 2025-09-15T01:28:55.427Z,1757899735.427 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:29:04.307Z,1757899744.307 [DataOverHttps](INFO): Sending 433 bytes from file Logs/20250915T010958/Express0008.lzma 2025-09-15T01:29:05.309Z,1757899745.309 [DataOverHttps](INFO): Moved sent file to Logs/20250915T010958/Express0008.lzma.bak 2025-09-15T01:29:05.309Z,1757899745.309 [DataOverHttps](INFO): SBD MOMSN=25989504 2025-09-15T01:29:21.338Z,1757899761.338 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20250915T010958/Express0011.lzma 2025-09-15T01:29:22.340Z,1757899762.340 [DataOverHttps](INFO): Moved sent file to Logs/20250915T010958/Express0011.lzma.bak 2025-09-15T01:29:22.341Z,1757899762.341 [DataOverHttps](INFO): SBD MOMSN=25989519 2025-09-15T01:29:24.222Z,1757899764.222 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-09-15T01:29:24.222Z,1757899764.222 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-09-15T01:29:24.222Z,1757899764.222 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-15T01:29:25.420Z,1757899765.420 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-15T01:29:32.683Z,1757899772.683 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:29:41.191Z,1757899781.191 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:29:49.559Z,1757899789.559 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:29:55.679Z,1757899795.679 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:29:55.679Z,1757899795.679 [DAT] Communications Fault, FailCount= 6 2025-09-15T01:29:55.679Z,1757899795.679 [DAT](ERROR): Communications Fault 2025-09-15T01:29:55.768Z,1757899795.768 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:29:56.083Z,1757899796.083 [DAT](INFO): Powering down 2025-09-15T01:29:56.957Z,1757899796.957 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:29:56.957Z,1757899796.957 [DAT] No Fault, FailCount= 6 2025-09-15T01:29:59.311Z,1757899799.311 [DAT](INFO): Powering up 2025-09-15T01:29:59.311Z,1757899799.311 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:30:01.107Z,1757899801.107 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:30:10.775Z,1757899810.775 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:30:19.131Z,1757899819.131 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:30:25.225Z,1757899825.225 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:30:25.225Z,1757899825.225 [RDI_Pathfinder] No Fault, FailCount= 3 2025-09-15T01:30:25.621Z,1757899825.621 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:30:27.583Z,1757899827.583 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:30:36.979Z,1757899836.979 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:30:38.141Z,1757899838.141 [RDI_Pathfinder](FAULT): DVL failed to acquire valid data within timeout. 2025-09-15T01:30:38.141Z,1757899838.141 [RDI_Pathfinder] Data Fault, FailCount= 1 2025-09-15T01:30:38.141Z,1757899838.141 [RDI_Pathfinder](ERROR): Data Fault 2025-09-15T01:30:38.162Z,1757899838.162 [CBIT](ERROR): Data Fault in component: RDI_Pathfinder 2025-09-15T01:30:38.544Z,1757899838.544 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:30:39.394Z,1757899839.394 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:30:39.394Z,1757899839.394 [RDI_Pathfinder] No Fault, FailCount= 1 2025-09-15T01:30:39.748Z,1757899839.748 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:30:45.999Z,1757899845.999 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:30:54.347Z,1757899854.347 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:30:59.339Z,1757899859.339 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:30:59.339Z,1757899859.339 [DAT] Communications Fault, FailCount= 7 2025-09-15T01:30:59.339Z,1757899859.339 [DAT](ERROR): Communications Fault 2025-09-15T01:30:59.607Z,1757899859.607 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:30:59.743Z,1757899859.743 [DAT](INFO): Powering down 2025-09-15T01:31:00.791Z,1757899860.791 [CBIT](INFO): Clearing failed state for component DAT 2025-09-15T01:31:00.791Z,1757899860.791 [DAT] No Fault, FailCount= 7 2025-09-15T01:31:01.969Z,1757899861.969 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-09-15T01:31:01.969Z,1757899861.969 [RDI_Pathfinder] Communications Fault, FailCount= 2 2025-09-15T01:31:01.969Z,1757899861.969 [RDI_Pathfinder](ERROR): Communications Fault 2025-09-15T01:31:02.019Z,1757899862.019 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:31:02.376Z,1757899862.376 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:31:02.835Z,1757899862.835 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:31:02.971Z,1757899862.971 [DAT](INFO): Powering up 2025-09-15T01:31:02.971Z,1757899862.971 [DAT](DEBUG): Initializing DAT. 2025-09-15T01:31:03.211Z,1757899863.211 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-09-15T01:31:03.211Z,1757899863.211 [RDI_Pathfinder] No Fault, FailCount= 2 2025-09-15T01:31:03.588Z,1757899863.588 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-09-15T01:31:12.195Z,1757899872.195 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:31:20.611Z,1757899880.611 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:31:25.804Z,1757899885.804 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-09-15T01:31:25.804Z,1757899885.804 [RDI_Pathfinder] Communications Fault, FailCount= 3 2025-09-15T01:31:25.804Z,1757899885.804 [RDI_Pathfinder](ERROR): Communications Fault 2025-09-15T01:31:25.905Z,1757899885.905 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:31:25.905Z,1757899885.905 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2025-09-15T01:31:26.220Z,1757899886.220 [RDI_Pathfinder](INFO): Powering down 2025-09-15T01:31:26.280Z,1757899886.280 [CBIT](INFO): Critical error at 20250915T013125 2025-09-15T01:31:30.911Z,1757899890.911 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:31:40.447Z,1757899900.447 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:31:48.799Z,1757899908.799 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:31:59.223Z,1757899919.223 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:32:03.211Z,1757899923.211 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2025-09-15T01:32:03.211Z,1757899923.211 [DAT] Communications Fault, FailCount= 8 2025-09-15T01:32:03.211Z,1757899923.211 [DAT](ERROR): Communications Fault 2025-09-15T01:32:03.492Z,1757899923.492 [CBIT](ERROR): Communications Fault in component: DAT 2025-09-15T01:32:03.492Z,1757899923.492 [CBIT](FAULT): Communications Fault in component: DAT 2025-09-15T01:32:03.615Z,1757899923.615 [DAT](INFO): Powering down 2025-09-15T01:32:09.663Z,1757899929.663 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:32:17.020Z,1757899937.020 [DataOverHttps](IMPORTANT): SBD MTMSN=20250915T013216 2025-09-15T01:32:24.562Z,1757899944.562 [DataOverHttps](INFO): Received command: restart sys 2025-09-15T01:32:24.571Z,1757899944.571 [CommandExec](IMPORTANT): got command restart system 2025-09-15T01:32:26.567Z,1757899946.567 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-09-15T01:32:26.716Z,1757899946.716 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-15T01:32:26.716Z,1757899946.716 [CommandExec](INFO): Uninitializing the command executive. 2025-09-15T01:32:26.716Z,1757899946.716 [CommandExec](INFO): Uninitializing the command scheduler. 2025-09-15T01:32:26.717Z,1757899946.717 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-09-15T01:32:26.783Z,1757899946.783 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-09-15T01:32:26.783Z,1757899946.783 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-09-15T01:32:26.783Z,1757899946.783 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-09-15T01:32:26.784Z,1757899946.784 [NavChartDb](INFO): Join timeout helper Thread ID is 880 2025-09-15T01:32:26.927Z,175789994