2025-09-11T16:20:08.739Z,1757607608.739 [Supervisor](DEBUG): Initializing supervisor.
2025-09-11T16:20:08.744Z,1757607608.744 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-09-11T16:20:08.744Z,1757607608.744 [SyncHandler](INFO): Protected caller Thread ID is 8115
2025-09-11T16:20:08.745Z,1757607608.745 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-09-11T16:20:08.746Z,1757607608.746 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-09-11T16:20:08.746Z,1757607608.746 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8116
2025-09-11T16:20:08.750Z,1757607608.750 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-09-11T16:20:08.771Z,1757607608.771 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-09-11T16:20:08.772Z,1757607608.772 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-09-11T16:20:08.772Z,1757607608.772 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8117
2025-09-11T16:20:08.777Z,1757607608.777 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-09-11T16:20:08.778Z,1757607608.778 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-09-11T16:20:08.778Z,1757607608.778 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8118
2025-09-11T16:20:08.780Z,1757607608.780 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-09-11T16:20:08.781Z,1757607608.781 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-09-11T16:20:08.782Z,1757607608.782 [logger ThreadHandler](INFO): Protected caller Thread ID is 8119
2025-09-11T16:20:08.785Z,1757607608.785 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-09-11T16:20:08.786Z,1757607608.786 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-09-11T16:20:08.788Z,1757607608.788 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-09-11T16:20:09.356Z,1757607609.356 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-09-11T16:20:09.357Z,1757607609.357 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-09-11T16:20:09.585Z,1757607609.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-09-11T16:20:09.585Z,1757607609.585 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-09-11T16:20:09.690Z,1757607609.690 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-09-11T16:20:09.691Z,1757607609.691 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-09-11T16:20:09.945Z,1757607609.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-09-11T16:20:09.946Z,1757607609.946 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-09-11T16:20:10.039Z,1757607610.039 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-09-11T16:20:10.151Z,1757607610.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-09-11T16:20:10.152Z,1757607610.152 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-09-11T16:20:10.528Z,1757607610.528 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-09-11T16:20:10.528Z,1757607610.528 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-09-11T16:20:10.678Z,1757607610.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-09-11T16:20:10.679Z,1757607610.679 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-09-11T16:20:11.425Z,1757607611.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-09-11T16:20:11.426Z,1757607611.426 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-09-11T16:20:11.624Z,1757607611.624 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-09-11T16:20:11.624Z,1757607611.624 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-09-11T16:20:12.065Z,1757607612.065 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-09-11T16:20:12.066Z,1757607612.066 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-09-11T16:20:12.602Z,1757607612.602 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-09-11T16:20:12.602Z,1757607612.602 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-09-11T16:20:12.907Z,1757607612.907 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-09-11T16:20:12.908Z,1757607612.908 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-09-11T16:20:13.787Z,1757607613.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-09-11T16:20:13.788Z,1757607613.788 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-09-11T16:20:14.158Z,1757607614.158 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-09-11T16:20:14.159Z,1757607614.159 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-09-11T16:20:14.422Z,1757607614.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-09-11T16:20:14.424Z,1757607614.424 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2025-09-11T16:20:14.425Z,1757607614.425 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2025-09-11T16:20:14.561Z,1757607614.561 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2025-09-11T16:20:14.659Z,1757607614.659 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2025-09-11T16:20:14.810Z,1757607614.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2025-09-11T16:20:14.909Z,1757607614.909 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2025-09-11T16:20:15.196Z,1757607615.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-09-11T16:20:15.196Z,1757607615.196 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2025-09-11T16:20:15.315Z,1757607615.315 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2025-09-11T16:20:15.435Z,1757607615.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2025-09-11T16:20:15.548Z,1757607615.548 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2025-09-11T16:20:15.717Z,1757607615.717 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2025-09-11T16:20:15.828Z,1757607615.828 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2025-09-11T16:20:15.929Z,1757607615.929 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2025-09-11T16:20:15.930Z,1757607615.930 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-09-11T16:20:15.941Z,1757607615.941 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-09-11T16:20:15.952Z,1757607615.952 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-09-11T16:20:15.953Z,1757607615.953 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-09-11T16:20:16.097Z,1757607616.097 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-09-11T16:20:16.098Z,1757607616.098 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-09-11T16:20:16.155Z,1757607616.155 [VerticalControl](DEBUG): Construct VerticalControl.
2025-09-11T16:20:16.217Z,1757607616.217 [VerticalControl] Loaded
2025-09-11T16:20:16.217Z,1757607616.217 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-09-11T16:20:16.220Z,1757607616.220 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-09-11T16:20:16.268Z,1757607616.268 [HorizontalControl] Loaded
2025-09-11T16:20:16.268Z,1757607616.268 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-09-11T16:20:16.270Z,1757607616.270 [SpeedControl](DEBUG): Construct SpeedControl.
2025-09-11T16:20:16.274Z,1757607616.274 [SpeedControl] Loaded
2025-09-11T16:20:16.274Z,1757607616.274 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-09-11T16:20:16.276Z,1757607616.276 [LoopControl](DEBUG): Construct LoopControl.
2025-09-11T16:20:16.277Z,1757607616.277 [LoopControl] Loaded
2025-09-11T16:20:16.277Z,1757607616.277 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-09-11T16:20:16.278Z,1757607616.278 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-09-11T16:20:16.278Z,1757607616.278 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-09-11T16:20:16.420Z,1757607616.420 [BuoyancyServo] Loaded
2025-09-11T16:20:16.420Z,1757607616.420 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-09-11T16:20:16.443Z,1757607616.443 [ElevatorServo] Loaded
2025-09-11T16:20:16.444Z,1757607616.444 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-09-11T16:20:16.465Z,1757607616.465 [MassServo] Loaded
2025-09-11T16:20:16.466Z,1757607616.466 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-09-11T16:20:16.486Z,1757607616.486 [RudderServo] Loaded
2025-09-11T16:20:16.487Z,1757607616.487 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-09-11T16:20:16.503Z,1757607616.503 [ThrusterHE] Loaded
2025-09-11T16:20:16.504Z,1757607616.504 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-09-11T16:20:16.504Z,1757607616.504 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-09-11T16:20:16.505Z,1757607616.505 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-09-11T16:20:16.560Z,1757607616.560 [DepthRateCalculator] Loaded
2025-09-11T16:20:16.560Z,1757607616.560 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-09-11T16:20:16.565Z,1757607616.565 [PitchRateCalculator] Loaded
2025-09-11T16:20:16.565Z,1757607616.565 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-09-11T16:20:16.575Z,1757607616.575 [SpeedCalculator] Loaded
2025-09-11T16:20:16.575Z,1757607616.575 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-09-11T16:20:16.580Z,1757607616.580 [YawRateCalculator] Loaded
2025-09-11T16:20:16.580Z,1757607616.580 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-09-11T16:20:16.599Z,1757607616.599 [ElevatorOffsetCalculator] Loaded
2025-09-11T16:20:16.599Z,1757607616.599 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-09-11T16:20:16.600Z,1757607616.600 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-09-11T16:20:16.600Z,1757607616.600 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-09-11T16:20:16.720Z,1757607616.720 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-09-11T16:20:16.720Z,1757607616.720 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-09-11T16:20:16.734Z,1757607616.734 [NavChart] Loaded
2025-09-11T16:20:16.735Z,1757607616.735 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-09-11T16:20:16.740Z,1757607616.740 [UniversalFixResidualReporter] Loaded
2025-09-11T16:20:16.740Z,1757607616.740 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-09-11T16:20:16.741Z,1757607616.741 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-09-11T16:20:16.741Z,1757607616.741 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-09-11T16:20:17.468Z,1757607617.468 [AHRS_M2] Loaded
2025-09-11T16:20:17.468Z,1757607617.468 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-09-11T16:20:17.727Z,1757607617.727 [BackseatComponent] Loaded
2025-09-11T16:20:17.727Z,1757607617.727 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-09-11T16:20:17.728Z,1757607617.728 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4092B4E0
2025-09-11T16:20:17.729Z,1757607617.729 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8201
2025-09-11T16:20:17.731Z,1757607617.731 [LcmUniversalReporter] Loaded
2025-09-11T16:20:17.732Z,1757607617.732 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-09-11T16:20:17.738Z,1757607617.738 [BioacousticsDataBridge] Loaded
2025-09-11T16:20:17.738Z,1757607617.738 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2025-09-11T16:20:18.942Z,1757607618.942 [BPC1] Loaded
2025-09-11T16:20:18.942Z,1757607618.942 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-09-11T16:20:19.091Z,1757607619.091 [DAT] Loaded
2025-09-11T16:20:19.091Z,1757607619.091 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-09-11T16:20:19.092Z,1757607619.092 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4095B4E0
2025-09-11T16:20:19.093Z,1757607619.093 [DAT ThreadHandler](INFO): Protected caller Thread ID is 8202
2025-09-11T16:20:19.402Z,1757607619.402 [DataOverHttps] Loaded
2025-09-11T16:20:19.402Z,1757607619.402 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-09-11T16:20:19.403Z,1757607619.403 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4098B4E0
2025-09-11T16:20:19.404Z,1757607619.404 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8203
2025-09-11T16:20:19.428Z,1757607619.428 [Depth_Keller] Loaded
2025-09-11T16:20:19.428Z,1757607619.428 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-09-11T16:20:19.434Z,1757607619.434 [DropWeight] Loaded
2025-09-11T16:20:19.434Z,1757607619.434 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-09-11T16:20:19.500Z,1757607619.500 [NAL9602] Loaded
2025-09-11T16:20:19.501Z,1757607619.501 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-09-11T16:20:19.531Z,1757607619.531 [Onboard] Loaded
2025-09-11T16:20:19.532Z,1757607619.532 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-09-11T16:20:19.533Z,1757607619.533 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409BB4E0
2025-09-11T16:20:19.533Z,1757607619.533 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8204
2025-09-11T16:20:19.548Z,1757607619.548 [Power24vConverter] Loaded
2025-09-11T16:20:19.548Z,1757607619.548 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-09-11T16:20:19.566Z,1757607619.566 [Radio_Surface] Loaded
2025-09-11T16:20:19.566Z,1757607619.566 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-09-11T16:20:19.567Z,1757607619.567 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409EB4E0
2025-09-11T16:20:19.568Z,1757607619.568 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8205
2025-09-11T16:20:19.582Z,1757607619.582 [Sonardyne_Nano] Loaded
2025-09-11T16:20:19.582Z,1757607619.582 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-09-11T16:20:19.583Z,1757607619.583 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-09-11T16:20:19.583Z,1757607619.583 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-09-11T16:20:19.650Z,1757607619.650 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-09-11T16:20:19.651Z,1757607619.651 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-09-11T16:20:19.833Z,1757607619.833 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-09-11T16:20:19.834Z,1757607619.834 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-09-11T16:20:19.865Z,1757607619.865 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-09-11T16:20:19.865Z,1757607619.865 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-09-11T16:20:20.048Z,1757607620.048 [CTD_Seabird] Loaded
2025-09-11T16:20:20.048Z,1757607620.048 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-09-11T16:20:20.049Z,1757607620.049 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B344E0
2025-09-11T16:20:20.050Z,1757607620.050 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8206
2025-09-11T16:20:20.073Z,1757607620.073 [PAR_Licor] Loaded
2025-09-11T16:20:20.073Z,1757607620.073 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-09-11T16:20:20.118Z,1757607620.118 [WetLabsBB2FL] Loaded
2025-09-11T16:20:20.119Z,1757607620.119 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-09-11T16:20:20.120Z,1757607620.120 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B644E0
2025-09-11T16:20:20.120Z,1757607620.120 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8207
2025-09-11T16:20:20.121Z,1757607620.121 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-09-11T16:20:20.121Z,1757607620.121 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-09-11T16:20:20.474Z,1757607620.474 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-09-11T16:20:20.475Z,1757607620.475 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-09-11T16:20:20.650Z,1757607620.650 [SBIT](DEBUG): Construct Startup Built In Test.
2025-09-11T16:20:20.659Z,1757607620.659 [SBIT] Loaded
2025-09-11T16:20:20.659Z,1757607620.659 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-09-11T16:20:20.670Z,1757607620.670 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-09-11T16:20:20.683Z,1757607620.683 [IBIT] Loaded
2025-09-11T16:20:20.683Z,1757607620.683 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-09-11T16:20:20.689Z,1757607620.689 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-09-11T16:20:20.771Z,1757607620.771 [CBIT] Loaded
2025-09-11T16:20:20.771Z,1757607620.771 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-09-11T16:20:20.804Z,1757607620.804 [GFScanner] Loaded
2025-09-11T16:20:20.804Z,1757607620.804 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-09-11T16:20:20.805Z,1757607620.805 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-09-11T16:20:20.811Z,1757607620.811 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-09-11T16:20:20.814Z,1757607620.814 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-09-11T16:20:20.826Z,1757607620.826 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-09-11T16:20:20.827Z,1757607620.827 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0
2025-09-11T16:20:20.828Z,1757607620.828 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8208
2025-09-11T16:20:20.832Z,1757607620.832 [Supervisor](INFO): Main Thread ID is 8113
2025-09-11T16:20:20.832Z,1757607620.832 [Supervisor](DEBUG): Running supervisor.
2025-09-11T16:20:20.833Z,1757607620.833 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8209
2025-09-11T16:20:20.834Z,1757607620.834 [CommandExec](INFO): Initializing the command executive.
2025-09-11T16:20:20.836Z,1757607620.836 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8210
2025-09-11T16:20:20.839Z,1757607620.839 [controlThread ThreadHandler](INFO): Handler Thread ID is 8211
2025-09-11T16:20:20.840Z,1757607620.840 [controlThread](DEBUG): Initializing ControlThread
2025-09-11T16:20:20.840Z,1757607620.840 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-09-11T16:20:20.842Z,1757607620.842 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-09-11T16:20:20.843Z,1757607620.843 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-09-11T16:20:20.843Z,1757607620.843 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-09-11T16:20:20.845Z,1757607620.845 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-09-11T16:20:20.845Z,1757607620.845 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-09-11T16:20:20.846Z,1757607620.846 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-09-11T16:20:20.846Z,1757607620.846 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-09-11T16:20:20.846Z,1757607620.846 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-09-11T16:20:20.847Z,1757607620.847 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-09-11T16:20:20.848Z,1757607620.848 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-09-11T16:20:20.853Z,1757607620.853 [SBIT](INFO): Initialize SBIT Component.
2025-09-11T16:20:20.853Z,1757607620.853 [SBIT](IMPORTANT): git: 2025-09-04-1-gf23ca45e4
2025-09-11T16:20:20.854Z,1757607620.854 [SBIT](INFO): git hash: f23ca45e43ac75619dec41dfb725120fbb894329
2025-09-11T16:20:20.854Z,1757607620.854 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-09-11T16:20:20.855Z,1757607620.855 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-09-11T16:20:20.856Z,1757607620.856 [SBIT](INFO): Beginning SBIT in 35.000000 seconds.
2025-09-11T16:20:20.857Z,1757607620.857 [IBIT](INFO): Initialize IBIT Component.
2025-09-11T16:20:20.858Z,1757607620.858 [CBIT](DEBUG): Initialize CBIT Component.
2025-09-11T16:20:20.859Z,1757607620.859 [logger ThreadHandler](INFO): Handler Thread ID is 8212
2025-09-11T16:20:20.869Z,1757607620.869 [CBIT](DEBUG): Initialized mux pins.
2025-09-11T16:20:20.869Z,1757607620.869 [CBIT](DEBUG): Initializing the watchdog timer.
2025-09-11T16:20:20.877Z,1757607620.877 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8213
2025-09-11T16:20:20.889Z,1757607620.889 [DAT ThreadHandler](INFO): Handler Thread ID is 8214
2025-09-11T16:20:20.890Z,1757607620.890 [DAT](INFO): Powering up
2025-09-11T16:20:20.890Z,1757607620.890 [DAT](DEBUG): Initializing DAT.
2025-09-11T16:20:20.893Z,1757607620.893 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-09-11T16:20:20.893Z,1757607620.893 [CBIT](DEBUG): Initializing heartbeat.
2025-09-11T16:20:20.898Z,1757607620.898 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8216
2025-09-11T16:20:20.899Z,1757607620.899 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-09-11T16:20:20.921Z,1757607620.921 [Onboard ThreadHandler](INFO): Handler Thread ID is 8217
2025-09-11T16:20:20.939Z,1757607620.939 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8218
2025-09-11T16:20:20.961Z,1757607620.961 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8219
2025-09-11T16:20:20.965Z,1757607620.965 [CBIT](DEBUG): Deactivating emergency mode.
2025-09-11T16:20:20.965Z,1757607620.965 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-09-11T16:20:20.969Z,1757607620.969 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8221
2025-09-11T16:20:20.973Z,1757607620.973 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8223
2025-09-11T16:20:20.975Z,1757607620.975 [WetLabsBB2FL](INFO): Powering up
2025-09-11T16:20:20.985Z,1757607620.985 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000
2025-09-11T16:20:20.985Z,1757607620.985 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000
2025-09-11T16:20:20.986Z,1757607620.986 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000
2025-09-11T16:20:20.986Z,1757607620.986 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000
2025-09-11T16:20:20.986Z,1757607620.986 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000
2025-09-11T16:20:20.986Z,1757607620.986 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000
2025-09-11T16:20:20.986Z,1757607620.986 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000
2025-09-11T16:20:20.986Z,1757607620.986 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000
2025-09-11T16:20:20.987Z,1757607620.987 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000
2025-09-11T16:20:20.987Z,1757607620.987 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000
2025-09-11T16:20:20.987Z,1757607620.987 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000
2025-09-11T16:20:20.987Z,1757607620.987 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000
2025-09-11T16:20:20.987Z,1757607620.987 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000
2025-09-11T16:20:20.987Z,1757607620.987 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000
2025-09-11T16:20:20.988Z,1757607620.988 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000
2025-09-11T16:20:20.988Z,1757607620.988 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000
2025-09-11T16:20:20.988Z,1757607620.988 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000
2025-09-11T16:20:20.988Z,1757607620.988 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000
2025-09-11T16:20:20.988Z,1757607620.988 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000
2025-09-11T16:20:20.988Z,1757607620.988 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000
2025-09-11T16:20:21.005Z,1757607621.005 [CBIT](DEBUG): Backplane powered.
2025-09-11T16:20:21.005Z,1757607621.005 [GFScanner](DEBUG): Initializing GFScanner
2025-09-11T16:20:21.005Z,1757607621.005 [GFScanner](DEBUG): Deactivating GF circuits.
2025-09-11T16:20:21.010Z,1757607621.010 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-09-11T16:20:21.011Z,1757607621.011 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-09-11T16:20:21.011Z,1757607621.011 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-09-11T16:20:21.013Z,1757607621.013 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-09-11T16:20:21.078Z,1757607621.078 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-09-11T16:20:21.091Z,1757607621.091 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-09-11T16:20:21.116Z,1757607621.116 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2025-09-11T16:20:21.142Z,1757607621.142 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-09-11T16:20:21.142Z,1757607621.142 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-09-11T16:20:21.162Z,1757607621.162 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-09-11T16:20:21.345Z,1757607621.345 [Radio_Surface](INFO): Powering up
2025-09-11T16:20:21.495Z,1757607621.495 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-09-11T16:20:21.553Z,1757607621.553 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-09-11T16:20:21.554Z,1757607621.554 [Default:A.Wait](DEBUG): Construct Wait.
2025-09-11T16:20:21.556Z,1757607621.556 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-09-11T16:20:21.614Z,1757607621.614 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-09-11T16:20:21.616Z,1757607621.616 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-09-11T16:20:21.639Z,1757607621.639 [Default:E.Execute](DEBUG): Construct Execute.
2025-09-11T16:20:21.642Z,1757607621.642 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2025-09-11T16:20:21.650Z,1757607621.650 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-09-11T16:20:21.673Z,1757607621.673 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-11T16:20:21.750Z,1757607621.750 [Depth_Keller](INFO): Initializing.
2025-09-11T16:20:21.751Z,1757607621.751 [Power24vConverter](INFO): Powering up.
2025-09-11T16:20:21.752Z,1757607621.752 [Sonardyne_Nano](INFO): Initializing.
2025-09-11T16:20:21.789Z,1757607621.789 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-09-11T16:20:21.840Z,1757607621.840 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-09-11T16:20:21.845Z,1757607621.845 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-09-11T16:20:21.846Z,1757607621.846 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-09-11T16:20:21.853Z,1757607621.853 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-09-11T16:20:21.854Z,1757607621.854 [MassServo](DEBUG): Initializing EZServoServo.
2025-09-11T16:20:21.861Z,1757607621.861 [MassServo](DEBUG): Initializing MassServo.
2025-09-11T16:20:21.862Z,1757607621.862 [RudderServo](DEBUG): Initializing EZServoServo.
2025-09-11T16:20:21.869Z,1757607621.869 [RudderServo](DEBUG): Initializing RudderServo.
2025-09-11T16:20:21.870Z,1757607621.870 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-09-11T16:20:21.878Z,1757607621.878 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-09-11T16:20:22.097Z,1757607622.097 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2025-09-11T16:20:22.097Z,1757607622.097 [DropWeight] Hardware Fault, FailCount= 1
2025-09-11T16:20:22.097Z,1757607622.097 [DropWeight](ERROR): Hardware Fault
2025-09-11T16:20:22.125Z,1757607622.125 [CommandExec](FAULT): Scheduling is paused
2025-09-11T16:20:22.126Z,1757607622.126 [CBIT](INFO): Critical error at 20250911T162022
2025-09-11T16:20:22.126Z,1757607622.126 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-09-11T16:20:22.128Z,1757607622.128 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-09-11T16:20:22.137Z,1757607622.137 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-09-11T16:20:22.487Z,1757607622.487 [CBIT](INFO): Critical error at 20250911T162022
2025-09-11T16:20:23.585Z,1757607623.585 [WetLabsBB2FL](INFO): Powering down
2025-09-11T16:20:23.679Z,1757607623.679 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-09-11T16:20:24.966Z,1757607624.966 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2025-09-11T16:20:27.542Z,1757607627.542 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-09-11T16:20:33.238Z,1757607633.238 [DAT](INFO): DAT read:
2025-09-11T16:20:33.239Z,1757607633.239 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-09-11T16:20:35.002Z,1757607635.002 [DAT](INFO): DAT read: MF Frequency Band
2025-09-11T16:20:35.003Z,1757607635.003 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-09-11T16:20:35.003Z,1757607635.003 [DAT](INFO): DAT read: Sep 9 2025 23:40:39
2025-09-11T16:20:35.227Z,1757607635.227 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004683
2025-09-11T16:20:36.011Z,1757607636.011 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-09-11T16:20:36.013Z,1757607636.013 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2025-09-11T16:20:36.013Z,1757607636.013 [DAT](INFO): commRate: 800
2025-09-11T16:20:38.077Z,1757607638.077 [DAT](INFO): entering command mode
2025-09-11T16:20:38.278Z,1757607638.278 [DAT](INFO): DAT read:
2025-09-11T16:20:38.278Z,1757607638.278 [DAT](INFO): DAT read: user:1>
2025-09-11T16:20:38.278Z,1757607638.278 [DAT](INFO): setting verbose to 3
2025-09-11T16:20:38.530Z,1757607638.530 [DAT](INFO): DAT read: user:1>
2025-09-11T16:20:38.531Z,1757607638.531 [DAT](INFO): DAT read: Verbose | 3
2025-09-11T16:20:38.531Z,1757607638.531 [DAT](INFO): set verbose to 3
2025-09-11T16:20:38.531Z,1757607638.531 [DAT](INFO): setting DatVerbose to 27440
2025-09-11T16:20:38.782Z,1757607638.782 [DAT](INFO): DAT read: user:2>
2025-09-11T16:20:38.782Z,1757607638.782 [DAT](INFO): DAT read: DatVerbose | 27440
2025-09-11T16:20:38.783Z,1757607638.783 [DAT](INFO): set DatVerbose to 27440
2025-09-11T16:20:38.783Z,1757607638.783 [DAT](INFO): setting transmit power to 8
2025-09-11T16:20:39.033Z,1757607639.033 [DAT](INFO): DAT read: user:3>
2025-09-11T16:20:39.034Z,1757607639.034 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-09-11T16:20:39.035Z,1757607639.035 [DAT](INFO): set transmit power to 8
2025-09-11T16:20:39.035Z,1757607639.035 [DAT](INFO): setting local address to 12
2025-09-11T16:20:39.286Z,1757607639.286 [DAT](INFO): DAT read: user:4>
2025-09-11T16:20:39.287Z,1757607639.287 [DAT](INFO): DAT read: LocalAddr | 12
2025-09-11T16:20:39.287Z,1757607639.287 [DAT](INFO): set local address to 12
2025-09-11T16:20:39.288Z,1757607639.288 [DAT](INFO): Setting time to: 16:20:39 And date to:9/11/2025
2025-09-11T16:20:39.538Z,1757607639.538 [DAT](INFO): DAT read: user:5>
2025-09-11T16:20:39.538Z,1757607639.538 [DAT](INFO): DAT read: Thu Sep 11, 2025 16:20:39
2025-09-11T16:20:39.539Z,1757607639.539 [DAT](INFO): Local DAT time set to Thu Sep 11, 2025 16:20:39
2025-09-11T16:20:39.540Z,1757607639.540 [DAT](INFO): setting remote address to 0
2025-09-11T16:20:39.790Z,1757607639.790 [DAT](INFO): DAT read: user:6>
2025-09-11T16:20:39.791Z,1757607639.791 [DAT](INFO): DAT read: RemoteAddr | 0
2025-09-11T16:20:39.791Z,1757607639.791 [DAT](INFO): set remote address to 0
2025-09-11T16:20:39.791Z,1757607639.791 [DAT](INFO): Querying Benthos address 0 with one ping in standard two-way mode.
2025-09-11T16:20:40.042Z,1757607640.042 [DAT](INFO): DAT read: user:7>
2025-09-11T16:20:40.042Z,1757607640.042 [DAT](INFO): DAT read: Tx time:16:20:39.6933
2025-09-11T16:20:40.043Z,1757607640.043 [DAT](INFO): Ping request sent.
2025-09-11T16:20:40.043Z,1757607640.043 [DAT](INFO): transmitted an acoustic signal
2025-09-11T16:20:40.043Z,1757607640.043 [DAT](INFO): publishing transmit ping time
2025-09-11T16:20:40.044Z,1757607640.044 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000917
2025-09-11T16:20:40.294Z,1757607640.294 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251034
2025-09-11T16:20:40.546Z,1757607640.546 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502885
2025-09-11T16:20:40.798Z,1757607640.798 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755387
2025-09-11T16:20:41.050Z,1757607641.050 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006927
2025-09-11T16:20:41.302Z,1757607641.302 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258878
2025-09-11T16:20:41.554Z,1757607641.554 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511353
2025-09-11T16:20:41.806Z,1757607641.806 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762917
2025-09-11T16:20:42.058Z,1757607642.058 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015052
2025-09-11T16:20:42.310Z,1757607642.310 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267163
2025-09-11T16:20:42.562Z,1757607642.562 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518898
2025-09-11T16:20:42.814Z,1757607642.814 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770844
2025-09-11T16:20:43.066Z,1757607643.066 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023152
2025-09-11T16:20:43.318Z,1757607643.318 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274871
2025-09-11T16:20:43.570Z,1757607643.570 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526910
2025-09-11T16:20:43.822Z,1757607643.822 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779008
2025-09-11T16:20:44.074Z,1757607644.074 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030866
2025-09-11T16:20:44.326Z,1757607644.326 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282825
2025-09-11T16:20:44.578Z,1757607644.578 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.535151
2025-09-11T16:20:44.830Z,1757607644.830 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786918
2025-09-11T16:20:45.082Z,1757607645.082 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038765
2025-09-11T16:20:45.334Z,1757607645.334 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.291134
2025-09-11T16:20:45.586Z,1757607645.586 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542836
2025-09-11T16:20:45.838Z,1757607645.838 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794918
2025-09-11T16:20:46.090Z,1757607646.090 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.047064
2025-09-11T16:20:46.342Z,1757607646.342 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298889
2025-09-11T16:20:46.594Z,1757607646.594 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550788
2025-09-11T16:20:46.846Z,1757607646.846 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.803084
2025-09-11T16:20:47.098Z,1757607647.098 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054836
2025-09-11T16:20:47.350Z,1757607647.350 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306802
2025-09-11T16:20:47.602Z,1757607647.602 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.559083
2025-09-11T16:20:47.854Z,1757607647.854 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810987
2025-09-11T16:20:48.106Z,1757607648.106 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062801
2025-09-11T16:20:48.358Z,1757607648.358 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.315100
2025-09-11T16:20:48.610Z,1757607648.610 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566814
2025-09-11T16:20:48.862Z,1757607648.862 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818790
2025-09-11T16:20:49.114Z,1757607649.114 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.071070
2025-09-11T16:20:49.366Z,1757607649.366 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322883
2025-09-11T16:20:49.542Z,1757607649.542 [NAL9602](INFO): Powering up NAL9602
2025-09-11T16:20:49.618Z,1757607649.618 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574809
2025-09-11T16:20:49.870Z,1757607649.870 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.827004
2025-09-11T16:20:50.121Z,1757607650.121 [DAT](INFO): Reached modem response timeout
2025-09-11T16:20:50.122Z,1757607650.122 [DAT](INFO): Querying Benthos address 0 with one ping in standard two-way mode.
2025-09-11T16:20:50.373Z,1757607650.373 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:20:50.626Z,1757607650.626 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:20:50.878Z,1757607650.878 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:20:51.129Z,1757607651.129 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:20:51.382Z,1757607651.382 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:20:51.634Z,1757607651.634 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:20:51.885Z,1757607651.885 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:20:52.138Z,1757607652.138 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:20:52.390Z,1757607652.390 [DAT](INFO): DAT read: Response Not Received
2025-09-11T16:20:52.390Z,1757607652.390 [DAT](INFO): response not received
2025-09-11T16:20:52.391Z,1757607652.391 [DAT](INFO): DAT read: user:8>
2025-09-11T16:20:52.391Z,1757607652.391 [DAT](INFO): DAT read: Tx time:16:20:52.1433
2025-09-11T16:20:52.392Z,1757607652.392 [DAT](INFO): Ping request sent.
2025-09-11T16:20:52.392Z,1757607652.392 [DAT](INFO): transmitted an acoustic signal
2025-09-11T16:20:52.392Z,1757607652.392 [DAT](INFO): publishing transmit ping time
2025-09-11T16:20:52.392Z,1757607652.392 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000707
2025-09-11T16:20:52.642Z,1757607652.642 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250024
2025-09-11T16:20:52.894Z,1757607652.894 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502304
2025-09-11T16:20:53.146Z,1757607653.146 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754090
2025-09-11T16:20:53.399Z,1757607653.399 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006930
2025-09-11T16:20:53.650Z,1757607653.650 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258321
2025-09-11T16:20:53.902Z,1757607653.902 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510093
2025-09-11T16:20:54.154Z,1757607654.154 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762010
2025-09-11T16:20:54.406Z,1757607654.406 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014421
2025-09-11T16:20:54.658Z,1757607654.658 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266039
2025-09-11T16:20:54.910Z,1757607654.910 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518027
2025-09-11T16:20:55.162Z,1757607655.162 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770307
2025-09-11T16:20:55.414Z,1757607655.414 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022097
2025-09-11T16:20:55.666Z,1757607655.666 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274127
2025-09-11T16:20:55.918Z,1757607655.918 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526224
2025-09-11T16:20:56.170Z,1757607656.170 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778124
2025-09-11T16:20:56.426Z,1757607656.426 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.034088
2025-09-11T16:20:56.448Z,1757607656.448 [SBIT](IMPORTANT): Beginning Startup BIT
2025-09-11T16:20:56.457Z,1757607656.457 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-09-11T16:20:56.678Z,1757607656.678 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.286244
2025-09-11T16:20:56.930Z,1757607656.930 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.538172
2025-09-11T16:20:57.182Z,1757607657.182 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.790021
2025-09-11T16:20:57.434Z,1757607657.434 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.042052
2025-09-11T16:20:57.686Z,1757607657.686 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.294335
2025-09-11T16:20:57.938Z,1757607657.938 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.545985
2025-09-11T16:20:58.190Z,1757607658.190 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.798121
2025-09-11T16:20:58.442Z,1757607658.442 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.050209
2025-09-11T16:20:58.694Z,1757607658.694 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.302077
2025-09-11T16:20:58.946Z,1757607658.946 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.554157
2025-09-11T16:20:59.198Z,1757607659.198 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806108
2025-09-11T16:20:59.450Z,1757607659.450 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.057885
2025-09-11T16:20:59.702Z,1757607659.702 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.309948
2025-09-11T16:20:59.954Z,1757607659.954 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.562047
2025-09-11T16:21:00.206Z,1757607660.206 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.814088
2025-09-11T16:21:00.452Z,1757607660.452 [NAL9602](INFO): NAL9602 initialized
2025-09-11T16:21:00.458Z,1757607660.458 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.066165
2025-09-11T16:21:00.710Z,1757607660.710 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.318094
2025-09-11T16:21:00.949Z,1757607660.949 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000981
CHAN A1 (24V): -0.007424
CHAN A2 (12V): -0.004458
CHAN A3 (5V): -0.003602
CHAN B0 (3.3V): -0.001281
CHAN B1 (3.15aV): -0.001003
CHAN B2 (3.15bV): -0.001384
CHAN B3 (GND): -0.000209
OPEN: 0.004779
Full Scale: +/- 1 mA
2025-09-11T16:21:00.963Z,1757607660.963 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.570964
2025-09-11T16:21:01.214Z,1757607661.214 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.822068
2025-09-11T16:21:01.466Z,1757607661.466 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.074529
2025-09-11T16:21:01.718Z,1757607661.718 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.326203
2025-09-11T16:21:01.970Z,1757607661.970 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.578314
2025-09-11T16:21:02.222Z,1757607662.222 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.830048
2025-09-11T16:21:02.473Z,1757607662.473 [DAT](INFO): Reached modem response timeout
2025-09-11T16:21:02.474Z,1757607662.474 [DAT](INFO): Querying Benthos address 0 with one ping in standard two-way mode.
2025-09-11T16:21:02.725Z,1757607662.725 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:02.977Z,1757607662.977 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:03.231Z,1757607663.231 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:03.481Z,1757607663.481 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:03.734Z,1757607663.734 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:03.985Z,1757607663.985 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:04.237Z,1757607664.237 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:04.489Z,1757607664.489 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:04.742Z,1757607664.742 [DAT](INFO): DAT read: Response Not Received
2025-09-11T16:21:04.742Z,1757607664.742 [DAT](INFO): response not received
2025-09-11T16:21:04.742Z,1757607664.742 [DAT](INFO): DAT read: user:9>
2025-09-11T16:21:04.743Z,1757607664.743 [DAT](ERROR): No response from remote modem.
2025-09-11T16:21:04.743Z,1757607664.743 [DAT](INFO): Querying Benthos address 0 with one ping in standard two-way mode.
2025-09-11T16:21:04.994Z,1757607664.994 [DAT](INFO): DAT read: Tx time:16:21:04.5933
2025-09-11T16:21:04.994Z,1757607664.994 [DAT](INFO): Ping request sent.
2025-09-11T16:21:04.994Z,1757607664.994 [DAT](INFO): transmitted an acoustic signal
2025-09-11T16:21:04.994Z,1757607664.994 [DAT](INFO): publishing transmit ping time
2025-09-11T16:21:04.995Z,1757607664.995 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000734
2025-09-11T16:21:05.246Z,1757607665.246 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251427
2025-09-11T16:21:05.498Z,1757607665.498 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503504
2025-09-11T16:21:05.750Z,1757607665.750 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755734
2025-09-11T16:21:06.002Z,1757607666.002 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007348
2025-09-11T16:21:06.254Z,1757607666.254 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259973
2025-09-11T16:21:06.506Z,1757607666.506 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511458
2025-09-11T16:21:06.758Z,1757607666.758 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763418
2025-09-11T16:21:07.010Z,1757607667.010 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015379
2025-09-11T16:21:07.262Z,1757607667.262 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267374
2025-09-11T16:21:07.514Z,1757607667.514 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519459
2025-09-11T16:21:07.766Z,1757607667.766 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771808
2025-09-11T16:21:08.018Z,1757607668.018 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023383
2025-09-11T16:21:08.270Z,1757607668.270 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275431
2025-09-11T16:21:08.524Z,1757607668.524 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.529394
2025-09-11T16:21:08.778Z,1757607668.778 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.783388
2025-09-11T16:21:09.030Z,1757607669.030 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.035449
2025-09-11T16:21:09.282Z,1757607669.282 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.287322
2025-09-11T16:21:09.534Z,1757607669.534 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.539386
2025-09-11T16:21:09.786Z,1757607669.786 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.791802
2025-09-11T16:21:10.038Z,1757607670.038 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.043357
2025-09-11T16:21:10.290Z,1757607670.290 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.295824
2025-09-11T16:21:10.542Z,1757607670.542 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.547514
2025-09-11T16:21:10.794Z,1757607670.794 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.799300
2025-09-11T16:21:11.046Z,1757607671.046 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.051349
2025-09-11T16:21:11.298Z,1757607671.298 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.303424
2025-09-11T16:21:11.550Z,1757607671.550 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.555853
2025-09-11T16:21:11.802Z,1757607671.802 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.807749
2025-09-11T16:21:12.054Z,1757607672.054 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.059482
2025-09-11T16:21:12.306Z,1757607672.306 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.311350
2025-09-11T16:21:12.558Z,1757607672.558 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.563324
2025-09-11T16:21:12.810Z,1757607672.810 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.815583
2025-09-11T16:21:13.062Z,1757607673.062 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.067741
2025-09-11T16:21:13.314Z,1757607673.314 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.319800
2025-09-11T16:21:13.566Z,1757607673.566 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.571407
2025-09-11T16:21:13.818Z,1757607673.818 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.823473
2025-09-11T16:21:14.070Z,1757607674.070 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.075637
2025-09-11T16:21:14.322Z,1757607674.322 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.327518
2025-09-11T16:21:14.574Z,1757607674.574 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.579357
2025-09-11T16:21:14.826Z,1757607674.826 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.831416
2025-09-11T16:21:15.078Z,1757607675.078 [DAT](INFO): Reached modem response timeout
2025-09-11T16:21:15.078Z,1757607675.078 [DAT](INFO): Querying Benthos address 0 with one ping in standard two-way mode.
2025-09-11T16:21:15.329Z,1757607675.329 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:15.583Z,1757607675.583 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:15.835Z,1757607675.835 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:16.085Z,1757607676.085 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:16.337Z,1757607676.337 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:16.590Z,1757607676.590 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:16.841Z,1757607676.841 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:17.093Z,1757607677.093 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:17.346Z,1757607677.346 [DAT](INFO): DAT read: Response Not Received
2025-09-11T16:21:17.346Z,1757607677.346 [DAT](INFO): response not received
2025-09-11T16:21:17.346Z,1757607677.346 [DAT](INFO): DAT read: user:10>
2025-09-11T16:21:17.347Z,1757607677.347 [DAT](INFO): DAT read: Tx time:16:21:17.0433
2025-09-11T16:21:17.347Z,1757607677.347 [DAT](INFO): Ping request sent.
2025-09-11T16:21:17.348Z,1757607677.348 [DAT](INFO): transmitted an acoustic signal
2025-09-11T16:21:17.348Z,1757607677.348 [DAT](INFO): publishing transmit ping time
2025-09-11T16:21:17.348Z,1757607677.348 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000718
2025-09-11T16:21:17.598Z,1757607677.598 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250103
2025-09-11T16:21:17.850Z,1757607677.850 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502359
2025-09-11T16:21:18.102Z,1757607678.102 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754163
2025-09-11T16:21:18.354Z,1757607678.354 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006098
2025-09-11T16:21:18.606Z,1757607678.606 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258124
2025-09-11T16:21:18.858Z,1757607678.858 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510184
2025-09-11T16:21:19.110Z,1757607679.110 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762153
2025-09-11T16:21:19.363Z,1757607679.363 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015033
2025-09-11T16:21:19.614Z,1757607679.614 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266189
2025-09-11T16:21:19.866Z,1757607679.866 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518172
2025-09-11T16:21:20.118Z,1757607680.118 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770418
2025-09-11T16:21:20.370Z,1757607680.370 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022177
2025-09-11T16:21:20.622Z,1757607680.622 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274117
2025-09-11T16:21:20.874Z,1757607680.874 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526338
2025-09-11T16:21:21.126Z,1757607681.126 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778188
2025-09-11T16:21:21.378Z,1757607681.378 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030099
2025-09-11T16:21:21.630Z,1757607681.630 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282124
2025-09-11T16:21:21.882Z,1757607681.882 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534182
2025-09-11T16:21:22.134Z,1757607682.134 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786048
2025-09-11T16:21:22.386Z,1757607682.386 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038385
2025-09-11T16:21:22.638Z,1757607682.638 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290163
2025-09-11T16:21:22.890Z,1757607682.890 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542110
2025-09-11T16:21:23.142Z,1757607683.142 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794228
2025-09-11T16:21:23.394Z,1757607683.394 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046141
2025-09-11T16:21:23.646Z,1757607683.646 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298172
2025-09-11T16:21:23.898Z,1757607683.898 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550205
2025-09-11T16:21:24.150Z,1757607684.150 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802164
2025-09-11T16:21:24.402Z,1757607684.402 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054161
2025-09-11T16:21:24.654Z,1757607684.654 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306141
2025-09-11T16:21:24.906Z,1757607684.906 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558183
2025-09-11T16:21:25.158Z,1757607685.158 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810188
2025-09-11T16:21:25.410Z,1757607685.410 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062120
2025-09-11T16:21:25.662Z,1757607685.662 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314577
2025-09-11T16:21:25.914Z,1757607685.914 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566245
2025-09-11T16:21:26.166Z,1757607686.166 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818274
2025-09-11T16:21:26.418Z,1757607686.418 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070164
2025-09-11T16:21:26.429Z,1757607686.429 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-11T16:21:26.670Z,1757607686.670 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322078
2025-09-11T16:21:26.922Z,1757607686.922 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574379
2025-09-11T16:21:27.174Z,1757607687.174 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826195
2025-09-11T16:21:27.425Z,1757607687.425 [DAT](INFO): Reached modem response timeout
2025-09-11T16:21:27.426Z,1757607687.426 [DAT](INFO): Querying Benthos address 0 with one ping in standard two-way mode.
2025-09-11T16:21:27.678Z,1757607687.678 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:27.930Z,1757607687.930 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:28.181Z,1757607688.181 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:28.434Z,1757607688.434 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:28.686Z,1757607688.686 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:28.937Z,1757607688.937 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:29.190Z,1757607689.190 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:29.441Z,1757607689.441 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:29.694Z,1757607689.694 [DAT](INFO): DAT read: Response Not Received
2025-09-11T16:21:29.694Z,1757607689.694 [DAT](INFO): response not received
2025-09-11T16:21:29.695Z,1757607689.695 [DAT](INFO): DAT read: user:11>
2025-09-11T16:21:29.695Z,1757607689.695 [DAT](INFO): DAT read: Tx time:16:21:29.4932
2025-09-11T16:21:29.696Z,1757607689.696 [DAT](INFO): Ping request sent.
2025-09-11T16:21:29.696Z,1757607689.696 [DAT](INFO): transmitted an acoustic signal
2025-09-11T16:21:29.696Z,1757607689.696 [DAT](INFO): publishing transmit ping time
2025-09-11T16:21:29.696Z,1757607689.696 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000725
2025-09-11T16:21:29.946Z,1757607689.946 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.249927
2025-09-11T16:21:30.198Z,1757607690.198 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502304
2025-09-11T16:21:30.450Z,1757607690.450 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754084
2025-09-11T16:21:30.702Z,1757607690.702 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006581
2025-09-11T16:21:30.954Z,1757607690.954 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258155
2025-09-11T16:21:31.206Z,1757607691.206 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510196
2025-09-11T16:21:31.458Z,1757607691.458 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762377
2025-09-11T16:21:31.710Z,1757607691.710 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014107
2025-09-11T16:21:31.962Z,1757607691.962 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266490
2025-09-11T16:21:32.214Z,1757607692.214 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518366
2025-09-11T16:21:32.466Z,1757607692.466 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770147
2025-09-11T16:21:32.719Z,1757607692.719 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023430
2025-09-11T16:21:32.847Z,1757607692.847 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-11T16:21:32.970Z,1757607692.970 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274321
2025-09-11T16:21:33.222Z,1757607693.222 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526182
2025-09-11T16:21:33.474Z,1757607693.474 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778050
2025-09-11T16:21:33.726Z,1757607693.726 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030367
2025-09-11T16:21:33.978Z,1757607693.978 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282201
2025-09-11T16:21:34.230Z,1757607694.230 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534056
2025-09-11T16:21:34.482Z,1757607694.482 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786097
2025-09-11T16:21:34.734Z,1757607694.734 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038151
2025-09-11T16:21:34.986Z,1757607694.986 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290190
2025-09-11T16:21:35.238Z,1757607695.238 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542141
2025-09-11T16:21:35.465Z,1757607695.465 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.243069
2025-09-11T16:21:35.490Z,1757607695.490 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794136
2025-09-11T16:21:35.742Z,1757607695.742 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046053
2025-09-11T16:21:36.000Z,1757607696.000 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.304479
2025-09-11T16:21:36.250Z,1757607696.250 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.554111
2025-09-11T16:21:36.502Z,1757607696.502 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806057
2025-09-11T16:21:36.754Z,1757607696.754 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.058341
2025-09-11T16:21:37.006Z,1757607697.006 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.310120
2025-09-11T16:21:37.258Z,1757607697.258 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.561962
2025-09-11T16:21:37.510Z,1757607697.510 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.814068
2025-09-11T16:21:37.762Z,1757607697.762 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.066461
2025-09-11T16:21:38.018Z,1757607698.018 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.322164
2025-09-11T16:21:38.270Z,1757607698.270 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.574334
2025-09-11T16:21:38.522Z,1757607698.522 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.826446
2025-09-11T16:21:38.774Z,1757607698.774 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.078043
2025-09-11T16:21:39.026Z,1757607699.026 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.330024
2025-09-11T16:21:39.278Z,1757607699.278 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.582241
2025-09-11T16:21:39.530Z,1757607699.530 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.834069
2025-09-11T16:21:39.781Z,1757607699.781 [DAT](INFO): Reached modem response timeout
2025-09-11T16:21:39.782Z,1757607699.782 [DAT](INFO): Querying Benthos address 0 with one ping in standard two-way mode.
2025-09-11T16:21:40.033Z,1757607700.033 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:40.288Z,1757607700.288 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:40.538Z,1757607700.538 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:40.790Z,1757607700.790 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:41.041Z,1757607701.041 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:41.294Z,1757607701.294 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:41.546Z,1757607701.546 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:41.797Z,1757607701.797 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:42.050Z,1757607702.050 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:42.302Z,1757607702.302 [DAT](INFO): DAT read: Response Not Received
2025-09-11T16:21:42.302Z,1757607702.302 [DAT](INFO): response not received
2025-09-11T16:21:42.303Z,1757607702.303 [DAT](INFO): DAT read: user:12>
2025-09-11T16:21:42.303Z,1757607702.303 [DAT](INFO): DAT read: Tx time:16:21:41.9432
2025-09-11T16:21:42.304Z,1757607702.304 [DAT](INFO): Ping request sent.
2025-09-11T16:21:42.304Z,1757607702.304 [DAT](INFO): transmitted an acoustic signal
2025-09-11T16:21:42.304Z,1757607702.304 [DAT](INFO): publishing transmit ping time
2025-09-11T16:21:42.304Z,1757607702.304 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000755
2025-09-11T16:21:42.555Z,1757607702.555 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250847
2025-09-11T16:21:42.806Z,1757607702.806 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502535
2025-09-11T16:21:43.058Z,1757607703.058 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754113
2025-09-11T16:21:43.310Z,1757607703.310 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006169
2025-09-11T16:21:43.562Z,1757607703.562 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258345
2025-09-11T16:21:43.766Z,1757607703.766 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-11T16:21:43.814Z,1757607703.814 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510558
2025-09-11T16:21:44.066Z,1757607704.066 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762049
2025-09-11T16:21:44.318Z,1757607704.318 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014097
2025-09-11T16:21:44.570Z,1757607704.570 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266162
2025-09-11T16:21:44.822Z,1757607704.822 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518064
2025-09-11T16:21:45.074Z,1757607705.074 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770131
2025-09-11T16:21:45.322Z,1757607705.322 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-11T16:21:45.330Z,1757607705.330 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.026174
2025-09-11T16:21:45.582Z,1757607705.582 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278007
2025-09-11T16:21:45.834Z,1757607705.834 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.530156
2025-09-11T16:21:45.848Z,1757607705.848 [NAL9602](ERROR): received:
2025-09-11T16:21:46.086Z,1757607706.086 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.782110
2025-09-11T16:21:46.338Z,1757607706.338 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.034165
2025-09-11T16:21:46.590Z,1757607706.590 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.286059
2025-09-11T16:21:46.842Z,1757607706.842 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.538169
2025-09-11T16:21:47.094Z,1757607707.094 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.789999
2025-09-11T16:21:47.346Z,1757607707.346 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.042085
2025-09-11T16:21:47.598Z,1757607707.598 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.294117
2025-09-11T16:21:47.850Z,1757607707.850 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.546049
2025-09-11T16:21:48.102Z,1757607708.102 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.798080
2025-09-11T16:21:48.354Z,1757607708.354 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.050192
2025-09-11T16:21:48.606Z,1757607708.606 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.302001
2025-09-11T16:21:48.858Z,1757607708.858 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.554095
2025-09-11T16:21:49.110Z,1757607709.110 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806123
2025-09-11T16:21:49.362Z,1757607709.362 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.058029
2025-09-11T16:21:49.614Z,1757607709.614 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.310330
2025-09-11T16:21:49.866Z,1757607709.866 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.562138
2025-09-11T16:21:49.946Z,1757607709.946 [SBIT](IMPORTANT): SBIT PASSED
2025-09-11T16:21:49.947Z,1757607709.947 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-09-11T16:21:49.948Z,1757607709.948 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;
2025-09-11T16:21:49.948Z,1757607709.948 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;
2025-09-11T16:21:49.948Z,1757607709.948 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature;
2025-09-11T16:21:49.948Z,1757607709.948 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;
2025-09-11T16:21:49.981Z,1757607709.981 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 1.000000 count_per_second;
2025-09-11T16:21:49.981Z,1757607709.981 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=-1 ampere_hour;
2025-09-11T16:21:49.981Z,1757607709.981 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=10 volt;
2025-09-11T16:21:49.981Z,1757607709.981 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=217.372209 cubic_centimeter;
2025-09-11T16:21:49.981Z,1757607709.981 [SBIT](IMPORTANT): VerticalControl.massDefault=3.965159 millimeter;
2025-09-11T16:21:49.981Z,1757607709.981 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool;
2025-09-11T16:21:50.118Z,1757607710.118 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.814292
2025-09-11T16:21:50.370Z,1757607710.370 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.066223
2025-09-11T16:21:50.378Z,1757607710.378 [MissionManager](IMPORTANT): Started mission Startup
2025-09-11T16:21:50.378Z,1757607710.378 [Startup] Running Loop=1
2025-09-11T16:21:50.378Z,1757607710.378 [Startup](DEBUG): Aggregate::initialize Startup
2025-09-11T16:21:50.379Z,1757607710.379 [Startup:A.GoToSurface] Running Loop=1
2025-09-11T16:21:50.379Z,1757607710.379 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-11T16:21:50.379Z,1757607710.379 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-09-11T16:21:50.380Z,1757607710.380 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-09-11T16:21:50.380Z,1757607710.380 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-09-11T16:21:50.380Z,1757607710.380 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-09-11T16:21:50.381Z,1757607710.381 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-11T16:21:50.381Z,1757607710.381 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-11T16:21:50.383Z,1757607710.383 [Startup:StartupSatComms] Running Loop=1
2025-09-11T16:21:50.383Z,1757607710.383 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-09-11T16:21:50.383Z,1757607710.383 [Startup:StartupSatComms:A] Running Loop=1
2025-09-11T16:21:50.622Z,1757607710.622 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.318119
2025-09-11T16:21:50.760Z,1757607710.760 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-09-11T16:21:50.874Z,1757607710.874 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.570026
2025-09-11T16:21:51.126Z,1757607711.126 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.822317
2025-09-11T16:21:51.378Z,1757607711.378 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.074143
2025-09-11T16:21:51.630Z,1757607711.630 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.326061
2025-09-11T16:21:51.882Z,1757607711.882 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.578337
2025-09-11T16:21:52.134Z,1757607712.134 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.830117
2025-09-11T16:21:52.385Z,1757607712.385 [DAT](INFO): Reached modem response timeout
2025-09-11T16:21:52.386Z,1757607712.386 [DAT](INFO): Querying Benthos address 0 with one ping in standard two-way mode.
2025-09-11T16:21:52.640Z,1757607712.640 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:52.889Z,1757607712.889 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:53.141Z,1757607713.141 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:53.393Z,1757607713.393 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:53.646Z,1757607713.646 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:53.898Z,1757607713.898 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:54.149Z,1757607714.149 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:54.403Z,1757607714.403 [DAT](INFO): received new query, but waiting for acoustic response period to elapse
2025-09-11T16:21:54.453Z,1757607714.453 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-11T16:21:54.654Z,1757607714.654 [DAT](INFO): DAT read: Response Not Received
2025-09-11T16:21:54.654Z,1757607714.654 [DAT](INFO): response not received
2025-09-11T16: