2024-06-24T19:08:17.278Z,1719256097.278 [Supervisor](DEBUG): Initializing supervisor.
2024-06-24T19:08:17.284Z,1719256097.284 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-06-24T19:08:17.284Z,1719256097.284 [SyncHandler](INFO): Protected caller Thread ID is 2461
2024-06-24T19:08:17.285Z,1719256097.285 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-06-24T19:08:17.286Z,1719256097.286 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-06-24T19:08:17.286Z,1719256097.286 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2462
2024-06-24T19:08:17.291Z,1719256097.291 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-06-24T19:08:17.309Z,1719256097.309 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-06-24T19:08:17.310Z,1719256097.310 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-06-24T19:08:17.310Z,1719256097.310 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2463
2024-06-24T19:08:17.315Z,1719256097.315 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-06-24T19:08:17.316Z,1719256097.316 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-06-24T19:08:17.316Z,1719256097.316 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2464
2024-06-24T19:08:17.318Z,1719256097.318 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-06-24T19:08:17.319Z,1719256097.319 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-06-24T19:08:17.319Z,1719256097.319 [logger ThreadHandler](INFO): Protected caller Thread ID is 2465
2024-06-24T19:08:17.323Z,1719256097.323 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-06-24T19:08:17.324Z,1719256097.324 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-06-24T19:08:17.325Z,1719256097.325 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-06-24T19:08:17.511Z,1719256097.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-06-24T19:08:17.512Z,1719256097.512 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-06-24T19:08:18.360Z,1719256098.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-06-24T19:08:18.360Z,1719256098.360 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-06-24T19:08:18.627Z,1719256098.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-06-24T19:08:18.628Z,1719256098.628 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-06-24T19:08:18.723Z,1719256098.723 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-06-24T19:08:18.873Z,1719256098.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-06-24T19:08:18.874Z,1719256098.874 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-06-24T19:08:18.971Z,1719256098.971 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-06-24T19:08:19.097Z,1719256099.097 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-06-24T19:08:19.098Z,1719256099.098 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-06-24T19:08:19.544Z,1719256099.544 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-06-24T19:08:19.545Z,1719256099.545 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-06-24T19:08:20.142Z,1719256100.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-06-24T19:08:20.143Z,1719256100.143 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-06-24T19:08:20.393Z,1719256100.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-06-24T19:08:20.394Z,1719256100.394 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-06-24T19:08:20.613Z,1719256100.613 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-06-24T19:08:20.614Z,1719256100.614 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-06-24T19:08:21.265Z,1719256101.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-06-24T19:08:21.266Z,1719256101.266 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-06-24T19:08:21.972Z,1719256101.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-06-24T19:08:21.973Z,1719256101.973 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-06-24T19:08:22.233Z,1719256102.233 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-06-24T19:08:22.233Z,1719256102.233 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-06-24T19:08:22.651Z,1719256102.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-06-24T19:08:22.651Z,1719256102.651 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-06-24T19:08:23.047Z,1719256103.047 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-06-24T19:08:23.049Z,1719256103.049 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2024-06-24T19:08:23.050Z,1719256103.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2024-06-24T19:08:23.151Z,1719256103.151 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2024-06-24T19:08:23.346Z,1719256103.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg
2024-06-24T19:08:23.459Z,1719256103.459 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2024-06-24T19:08:23.586Z,1719256103.586 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2024-06-24T19:08:23.689Z,1719256103.689 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2024-06-24T19:08:23.802Z,1719256103.802 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2024-06-24T19:08:23.935Z,1719256103.935 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2024-06-24T19:08:24.217Z,1719256104.217 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-06-24T19:08:24.218Z,1719256104.218 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2024-06-24T19:08:24.330Z,1719256104.330 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2024-06-24T19:08:24.440Z,1719256104.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2024-06-24T19:08:24.605Z,1719256104.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2024-06-24T19:08:24.724Z,1719256104.724 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2024-06-24T19:08:24.724Z,1719256104.724 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-06-24T19:08:24.740Z,1719256104.740 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-06-24T19:08:24.855Z,1719256104.855 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-06-24T19:08:24.856Z,1719256104.856 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-06-24T19:08:24.910Z,1719256104.910 [VerticalControl](DEBUG): Construct VerticalControl.
2024-06-24T19:08:24.970Z,1719256104.970 [VerticalControl] Loaded
2024-06-24T19:08:24.970Z,1719256104.970 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-06-24T19:08:24.973Z,1719256104.973 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-06-24T19:08:25.020Z,1719256105.020 [HorizontalControl] Loaded
2024-06-24T19:08:25.020Z,1719256105.020 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-06-24T19:08:25.022Z,1719256105.022 [SpeedControl](DEBUG): Construct SpeedControl.
2024-06-24T19:08:25.025Z,1719256105.025 [SpeedControl] Loaded
2024-06-24T19:08:25.026Z,1719256105.026 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-06-24T19:08:25.028Z,1719256105.028 [LoopControl](DEBUG): Construct LoopControl.
2024-06-24T19:08:25.029Z,1719256105.029 [LoopControl] Loaded
2024-06-24T19:08:25.029Z,1719256105.029 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-06-24T19:08:25.029Z,1719256105.029 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-06-24T19:08:25.030Z,1719256105.030 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-06-24T19:08:25.080Z,1719256105.080 [DepthRateCalculator] Loaded
2024-06-24T19:08:25.081Z,1719256105.081 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-06-24T19:08:25.085Z,1719256105.085 [PitchRateCalculator] Loaded
2024-06-24T19:08:25.086Z,1719256105.086 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-06-24T19:08:25.096Z,1719256105.096 [SpeedCalculator] Loaded
2024-06-24T19:08:25.096Z,1719256105.096 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-06-24T19:08:25.100Z,1719256105.100 [YawRateCalculator] Loaded
2024-06-24T19:08:25.100Z,1719256105.100 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-06-24T19:08:25.118Z,1719256105.118 [ElevatorOffsetCalculator] Loaded
2024-06-24T19:08:25.119Z,1719256105.119 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-06-24T19:08:25.119Z,1719256105.119 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-06-24T19:08:25.120Z,1719256105.120 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-06-24T19:08:25.149Z,1719256105.149 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-06-24T19:08:25.149Z,1719256105.149 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-06-24T19:08:25.206Z,1719256105.206 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-06-24T19:08:25.206Z,1719256105.206 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-06-24T19:08:25.547Z,1719256105.547 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-06-24T19:08:25.547Z,1719256105.547 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-06-24T19:08:25.723Z,1719256105.723 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-06-24T19:08:25.723Z,1719256105.723 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-06-24T19:08:26.200Z,1719256106.200 [AHRS_M2] Loaded
2024-06-24T19:08:26.200Z,1719256106.200 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-06-24T19:08:26.239Z,1719256106.239 [BackseatComponent] Loaded
2024-06-24T19:08:26.239Z,1719256106.239 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-06-24T19:08:26.240Z,1719256106.240 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409C54E0
2024-06-24T19:08:26.240Z,1719256106.240 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2550
2024-06-24T19:08:26.243Z,1719256106.243 [LcmUniversalReporter] Loaded
2024-06-24T19:08:26.244Z,1719256106.244 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-06-24T19:08:27.567Z,1719256107.567 [BPC1] Loaded
2024-06-24T19:08:27.567Z,1719256107.567 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-06-24T19:08:27.640Z,1719256107.640 [DataOverHttps] Loaded
2024-06-24T19:08:27.640Z,1719256107.640 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-06-24T19:08:27.641Z,1719256107.641 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F54E0
2024-06-24T19:08:27.641Z,1719256107.641 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2551
2024-06-24T19:08:27.664Z,1719256107.664 [Depth_Keller] Loaded
2024-06-24T19:08:27.665Z,1719256107.665 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-06-24T19:08:27.669Z,1719256107.669 [DropWeight] Loaded
2024-06-24T19:08:27.670Z,1719256107.670 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-06-24T19:08:27.733Z,1719256107.733 [NAL9602] Loaded
2024-06-24T19:08:27.733Z,1719256107.733 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-06-24T19:08:27.763Z,1719256107.763 [Onboard] Loaded
2024-06-24T19:08:27.763Z,1719256107.763 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-06-24T19:08:27.764Z,1719256107.764 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A254E0
2024-06-24T19:08:27.765Z,1719256107.765 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2552
2024-06-24T19:08:27.778Z,1719256107.778 [Power24vConverter] Loaded
2024-06-24T19:08:27.779Z,1719256107.779 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-06-24T19:08:27.795Z,1719256107.795 [Radio_Surface] Loaded
2024-06-24T19:08:27.795Z,1719256107.795 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-06-24T19:08:27.796Z,1719256107.796 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A554E0
2024-06-24T19:08:27.796Z,1719256107.796 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2553
2024-06-24T19:08:27.843Z,1719256107.843 [RDI_Pathfinder] Loaded
2024-06-24T19:08:27.843Z,1719256107.843 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2024-06-24T19:08:27.858Z,1719256107.858 [Sonardyne_Nano] Loaded
2024-06-24T19:08:27.859Z,1719256107.859 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-06-24T19:08:27.952Z,1719256107.952 [DAT] Loaded
2024-06-24T19:08:27.953Z,1719256107.953 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-06-24T19:08:27.954Z,1719256107.954 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0
2024-06-24T19:08:27.954Z,1719256107.954 [DAT ThreadHandler](INFO): Protected caller Thread ID is 2554
2024-06-24T19:08:27.955Z,1719256107.955 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-06-24T19:08:27.955Z,1719256107.955 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-06-24T19:08:28.068Z,1719256108.068 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-06-24T19:08:28.068Z,1719256108.068 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-06-24T19:08:28.081Z,1719256108.081 [NavChart] Loaded
2024-06-24T19:08:28.082Z,1719256108.082 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-06-24T19:08:28.087Z,1719256108.087 [UniversalFixResidualReporter] Loaded
2024-06-24T19:08:28.088Z,1719256108.088 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-06-24T19:08:28.088Z,1719256108.088 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-06-24T19:08:28.089Z,1719256108.089 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-06-24T19:08:28.232Z,1719256108.232 [SBIT](DEBUG): Construct Startup Built In Test.
2024-06-24T19:08:28.241Z,1719256108.241 [SBIT] Loaded
2024-06-24T19:08:28.241Z,1719256108.241 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-06-24T19:08:28.244Z,1719256108.244 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-06-24T19:08:28.256Z,1719256108.256 [IBIT] Loaded
2024-06-24T19:08:28.257Z,1719256108.257 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-06-24T19:08:28.263Z,1719256108.263 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-06-24T19:08:28.368Z,1719256108.368 [CBIT] Loaded
2024-06-24T19:08:28.368Z,1719256108.368 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-06-24T19:08:28.368Z,1719256108.368 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-06-24T19:08:28.369Z,1719256108.369 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-06-24T19:08:28.505Z,1719256108.505 [BuoyancyServo] Loaded
2024-06-24T19:08:28.505Z,1719256108.505 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-06-24T19:08:28.528Z,1719256108.528 [ElevatorServo] Loaded
2024-06-24T19:08:28.528Z,1719256108.528 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-06-24T19:08:28.558Z,1719256108.558 [DockingStepper] Loaded
2024-06-24T19:08:28.559Z,1719256108.559 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2024-06-24T19:08:28.580Z,1719256108.580 [MassServo] Loaded
2024-06-24T19:08:28.580Z,1719256108.580 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-06-24T19:08:28.601Z,1719256108.601 [RudderServo] Loaded
2024-06-24T19:08:28.601Z,1719256108.601 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-06-24T19:08:28.620Z,1719256108.620 [ThrusterServo] Loaded
2024-06-24T19:08:28.620Z,1719256108.620 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2024-06-24T19:08:28.621Z,1719256108.621 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-06-24T19:08:28.621Z,1719256108.621 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-06-24T19:08:28.632Z,1719256108.632 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-06-24T19:08:28.632Z,1719256108.632 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-06-24T19:08:28.811Z,1719256108.811 [CTD_Seabird] Loaded
2024-06-24T19:08:28.811Z,1719256108.811 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-06-24T19:08:28.812Z,1719256108.812 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BD94E0
2024-06-24T19:08:28.813Z,1719256108.813 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2555
2024-06-24T19:08:28.835Z,1719256108.835 [PAR_Licor] Loaded
2024-06-24T19:08:28.835Z,1719256108.835 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-06-24T19:08:28.880Z,1719256108.880 [WetLabsBB2FL] Loaded
2024-06-24T19:08:28.880Z,1719256108.880 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-06-24T19:08:28.881Z,1719256108.881 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C094E0
2024-06-24T19:08:28.882Z,1719256108.882 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2556
2024-06-24T19:08:28.903Z,1719256108.903 [WetLabsUBAT] Loaded
2024-06-24T19:08:28.904Z,1719256108.904 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2024-06-24T19:08:28.905Z,1719256108.905 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C394E0
2024-06-24T19:08:28.905Z,1719256108.905 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 2557
2024-06-24T19:08:28.906Z,1719256108.906 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-06-24T19:08:28.912Z,1719256108.912 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-06-24T19:08:28.915Z,1719256108.915 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-06-24T19:08:28.926Z,1719256108.926 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-06-24T19:08:28.927Z,1719256108.927 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0
2024-06-24T19:08:28.928Z,1719256108.928 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2558
2024-06-24T19:08:28.932Z,1719256108.932 [Supervisor](INFO): Main Thread ID is 2131
2024-06-24T19:08:28.932Z,1719256108.932 [Supervisor](DEBUG): Running supervisor.
2024-06-24T19:08:28.933Z,1719256108.933 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2559
2024-06-24T19:08:28.934Z,1719256108.934 [CommandExec](INFO): Initializing the command executive.
2024-06-24T19:08:28.935Z,1719256108.935 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2560
2024-06-24T19:08:28.937Z,1719256108.937 [controlThread ThreadHandler](INFO): Handler Thread ID is 2561
2024-06-24T19:08:28.938Z,1719256108.938 [controlThread](DEBUG): Initializing ControlThread
2024-06-24T19:08:28.939Z,1719256108.939 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-06-24T19:08:28.940Z,1719256108.940 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-06-24T19:08:28.941Z,1719256108.941 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-06-24T19:08:28.941Z,1719256108.941 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-06-24T19:08:28.942Z,1719256108.942 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-06-24T19:08:28.942Z,1719256108.942 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-06-24T19:08:28.943Z,1719256108.943 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-06-24T19:08:28.943Z,1719256108.943 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-06-24T19:08:28.943Z,1719256108.943 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-06-24T19:08:28.949Z,1719256108.949 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-06-24T19:08:28.949Z,1719256108.949 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-06-24T19:08:28.949Z,1719256108.949 [SBIT](INFO): Initialize SBIT Component.
2024-06-24T19:08:28.950Z,1719256108.950 [SBIT](IMPORTANT): git: 2024-06-14_A
2024-06-24T19:08:28.950Z,1719256108.950 [SBIT](INFO): git hash: 90384bcf3876045306bf8b12c7409f70f96e52e4
2024-06-24T19:08:28.950Z,1719256108.950 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-06-24T19:08:28.951Z,1719256108.951 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-06-24T19:08:28.952Z,1719256108.952 [SBIT](INFO): Beginning SBIT in 107.000000 seconds.
2024-06-24T19:08:28.953Z,1719256108.953 [IBIT](INFO): Initialize IBIT Component.
2024-06-24T19:08:28.954Z,1719256108.954 [CBIT](DEBUG): Initialize CBIT Component.
2024-06-24T19:08:28.955Z,1719256108.955 [logger ThreadHandler](INFO): Handler Thread ID is 2562
2024-06-24T19:08:28.966Z,1719256108.966 [CBIT](DEBUG): Initialized mux pins.
2024-06-24T19:08:28.966Z,1719256108.966 [CBIT](DEBUG): Initializing the watchdog timer.
2024-06-24T19:08:28.974Z,1719256108.974 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2563
2024-06-24T19:08:28.986Z,1719256108.986 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2564
2024-06-24T19:08:28.988Z,1719256108.988 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-06-24T19:08:28.990Z,1719256108.990 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-06-24T19:08:28.990Z,1719256108.990 [CBIT](DEBUG): Initializing heartbeat.
2024-06-24T19:08:28.998Z,1719256108.998 [Onboard ThreadHandler](INFO): Handler Thread ID is 2565
2024-06-24T19:08:29.016Z,1719256109.016 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2566
2024-06-24T19:08:29.034Z,1719256109.034 [DAT ThreadHandler](INFO): Handler Thread ID is 2567
2024-06-24T19:08:29.035Z,1719256109.035 [DAT](INFO): Powering up
2024-06-24T19:08:29.035Z,1719256109.035 [DAT](DEBUG): Initializing DAT.
2024-06-24T19:08:29.040Z,1719256109.040 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2569
2024-06-24T19:08:29.040Z,1719256109.040 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-06-24T19:08:29.047Z,1719256109.047 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2571
2024-06-24T19:08:29.051Z,1719256109.051 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 2573
2024-06-24T19:08:29.052Z,1719256109.052 [WetLabsBB2FL](INFO): Powering up
2024-06-24T19:08:29.054Z,1719256109.054 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2575
2024-06-24T19:08:29.058Z,1719256109.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-06-24T19:08:29.058Z,1719256109.058 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-06-24T19:08:29.058Z,1719256109.058 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-06-24T19:08:29.059Z,1719256109.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-06-24T19:08:29.059Z,1719256109.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-06-24T19:08:29.059Z,1719256109.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-06-24T19:08:29.059Z,1719256109.059 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-06-24T19:08:29.059Z,1719256109.059 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-06-24T19:08:29.062Z,1719256109.062 [CBIT](DEBUG): Deactivating GF circuits.
2024-06-24T19:08:29.062Z,1719256109.062 [CBIT](DEBUG): Deactivating emergency mode.
2024-06-24T19:08:29.074Z,1719256109.074 [WetLabsUBAT](INFO): Powering up
2024-06-24T19:08:29.102Z,1719256109.102 [CBIT](DEBUG): Backplane powered.
2024-06-24T19:08:29.107Z,1719256109.107 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-06-24T19:08:29.107Z,1719256109.107 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl
2024-06-24T19:08:29.108Z,1719256109.108 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl
2024-06-24T19:08:29.109Z,1719256109.109 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl
2024-06-24T19:08:29.127Z,1719256109.127 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into:
2024-06-24T19:08:29.127Z,1719256109.127 [MissionManager](ERROR): Top node has no child nodes: Mission
2024-06-24T19:08:29.128Z,1719256109.128 [MissionManager](ERROR): Could not load startup mission.
2024-06-24T19:08:29.128Z,1719256109.128 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl
2024-06-24T19:08:29.128Z,1719256109.128 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl
2024-06-24T19:08:29.129Z,1719256109.129 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl
2024-06-24T19:08:29.422Z,1719256109.422 [Radio_Surface](INFO): Powering up
2024-06-24T19:08:29.653Z,1719256109.653 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/DefaultWithUndock.tl translated into:
0
30
2.5
1
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
2024-06-24T19:08:29.676Z,1719256109.676 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2024-06-24T19:08:29.700Z,1719256109.700 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min
2024-06-24T19:08:29.704Z,1719256109.704 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count
2024-06-24T19:08:29.727Z,1719256109.727 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 2.500000 s
2024-06-24T19:08:29.731Z,1719256109.731 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2024-06-24T19:08:29.732Z,1719256109.732 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2024-06-24T19:08:29.882Z,1719256109.882 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2024-06-24T19:08:29.882Z,1719256109.882 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2024-06-24T19:08:29.921Z,1719256109.921 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-06-24T19:08:29.963Z,1719256109.963 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-06-24T19:08:29.965Z,1719256109.965 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-06-24T19:08:29.987Z,1719256109.987 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2024-06-24T19:08:30.011Z,1719256110.011 [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 = 30 minute
"""
Maximum time duration for the vehicle to detach form the dock.
"""
TransponderCode = Dock:Dock.transponderCode
"""
Dock transponder address.
"""
TrackingUpdatePeriod = 2.5 second
"""
Time duration between acoustic range queries while the mission is
detaching from the dock.
"""
NumberOfPings = 1 count
"""
Number of pings requested each time.
"""
}
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 in parallel
set contactLabelSetting = TransponderCode
set numberOfSamplesSetting = NumberOfPings
set updatePeriodSetting = TrackingUpdatePeriod
}
# Leave the dock
behavior Dock:Undock {
run in sequence
}
}
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"
}
}
2024-06-24T19:08:30.030Z,1719256110.030 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-06-24T19:08:30.037Z,1719256110.037 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-06-24T19:08:30.121Z,1719256110.121 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993022 m
2024-06-24T19:08:30.122Z,1719256110.122 [Power24vConverter](INFO): Powering up.
2024-06-24T19:08:30.123Z,1719256110.123 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2024-06-24T19:08:30.131Z,1719256110.131 [Sonardyne_Nano](INFO): Initializing.
2024-06-24T19:08:30.162Z,1719256110.162 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-06-24T19:08:30.185Z,1719256110.185 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-06-24T19:08:30.194Z,1719256110.194 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-06-24T19:08:30.195Z,1719256110.195 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-24T19:08:30.202Z,1719256110.202 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-24T19:08:30.203Z,1719256110.203 [DockingStepper](INFO): Start
2024-06-24T19:08:30.203Z,1719256110.203 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-06-24T19:08:30.210Z,1719256110.210 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-06-24T19:08:30.211Z,1719256110.211 [MassServo](DEBUG): Initializing EZServoServo.
2024-06-24T19:08:30.218Z,1719256110.218 [MassServo](DEBUG): Initializing MassServo.
2024-06-24T19:08:30.219Z,1719256110.219 [RudderServo](DEBUG): Initializing EZServoServo.
2024-06-24T19:08:30.226Z,1719256110.226 [RudderServo](DEBUG): Initializing RudderServo.
2024-06-24T19:08:30.227Z,1719256110.227 [ThrusterServo](DEBUG): Initializing EZServoServo.
2024-06-24T19:08:30.234Z,1719256110.234 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2024-06-24T19:08:30.487Z,1719256110.487 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-06-24T19:08:30.488Z,1719256110.488 [DropWeight] Hardware Fault, FailCount= 1
2024-06-24T19:08:30.488Z,1719256110.488 [DropWeight](ERROR): Hardware Fault
2024-06-24T19:08:30.523Z,1719256110.523 [DockingStepper](INFO): Starting
2024-06-24T19:08:30.525Z,1719256110.525 [CommandExec](FAULT): Scheduling is paused
2024-06-24T19:08:30.525Z,1719256110.525 [CBIT](INFO): Critical error at 20240624T190830
2024-06-24T19:08:30.525Z,1719256110.525 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-06-24T19:08:30.536Z,1719256110.536 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-06-24T19:08:30.537Z,1719256110.537 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-06-24T19:08:30.866Z,1719256110.866 [DockingStepper](INFO): Starting
2024-06-24T19:08:30.868Z,1719256110.868 [CBIT](INFO): Critical error at 20240624T190830
2024-06-24T19:08:31.390Z,1719256111.390 [DockingStepper](INFO): Starting
2024-06-24T19:08:31.439Z,1719256111.439 [DockingStepper](INFO): Init string: /1z7603aE13561N3m50
2024-06-24T19:08:31.662Z,1719256111.662 [WetLabsBB2FL](INFO): Powering down
2024-06-24T19:08:31.695Z,1719256111.695 [DockingStepper](INFO): Starting
2024-06-24T19:08:32.059Z,1719256112.059 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-06-24T19:08:32.091Z,1719256112.091 [DockingStepper](INFO): Starting
2024-06-24T19:08:32.481Z,1719256112.481 [DockingStepper](INFO): Starting
2024-06-24T19:08:32.963Z,1719256112.963 [DockingStepper](INFO): Starting
2024-06-24T19:08:32.987Z,1719256112.987 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-06-24T19:08:33.409Z,1719256113.409 [DockingStepper](INFO): Starting
2024-06-24T19:08:33.714Z,1719256113.714 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103
2024-06-24T19:08:33.882Z,1719256113.882 [DockingStepper](INFO): Commanding positive:44846
2024-06-24T19:08:33.987Z,1719256113.987 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:08:34.279Z,1719256114.279 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:08:34.677Z,1719256114.677 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2024-06-24T19:08:34.680Z,1719256114.680 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0
2024-06-24T19:08:34.851Z,1719256114.851 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:08:35.259Z,1719256115.259 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:08:35.718Z,1719256115.718 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:08:36.080Z,1719256116.080 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-06-24T19:08:36.394Z,1719256116.394 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:08:36.742Z,1719256116.742 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:08:37.099Z,1719256117.099 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:08:37.595Z,1719256117.595 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:08:37.883Z,1719256117.883 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:08:38.342Z,1719256118.342 [WetLabsUBAT](INFO): Powering down
2024-06-24T19:08:38.443Z,1719256118.443 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:08:38.593Z,1719256118.593 [DockingStepper](INFO): Pause
2024-06-24T19:08:41.636Z,1719256121.636 [DAT](INFO): DAT read:
2024-06-24T19:08:41.640Z,1719256121.640 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-06-24T19:08:43.399Z,1719256123.399 [DAT](INFO): DAT read: MF Frequency Band
2024-06-24T19:08:43.400Z,1719256123.400 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2
2024-06-24T19:08:43.401Z,1719256123.401 [DAT](INFO): DAT read: Jun 24 2024 19:08:31
2024-06-24T19:08:44.659Z,1719256124.659 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-06-24T19:08:44.660Z,1719256124.660 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-06-24T19:08:44.661Z,1719256124.661 [DAT](INFO): commRate: 800
2024-06-24T19:08:46.726Z,1719256126.726 [DAT](INFO): entering command mode
2024-06-24T19:08:46.926Z,1719256126.926 [DAT](INFO): DAT read:
2024-06-24T19:08:46.927Z,1719256126.927 [DAT](INFO): DAT read: user:1>
2024-06-24T19:08:46.927Z,1719256126.927 [DAT](INFO): setting verbose to 3
2024-06-24T19:08:47.179Z,1719256127.179 [DAT](INFO): DAT read: user:1>
2024-06-24T19:08:47.180Z,1719256127.180 [DAT](INFO): DAT read: Verbose | 3
2024-06-24T19:08:47.180Z,1719256127.180 [DAT](INFO): set verbose to 3
2024-06-24T19:08:47.180Z,1719256127.180 [DAT](INFO): setting DatVerbose to 27440
2024-06-24T19:08:47.431Z,1719256127.431 [DAT](INFO): DAT read: user:2>
2024-06-24T19:08:47.432Z,1719256127.432 [DAT](INFO): DAT read: DatVerbose | 27440
2024-06-24T19:08:47.432Z,1719256127.432 [DAT](INFO): set DatVerbose to 27440
2024-06-24T19:08:47.432Z,1719256127.432 [DAT](INFO): setting transmit power to 8
2024-06-24T19:08:47.683Z,1719256127.683 [DAT](INFO): DAT read: user:3>
2024-06-24T19:08:47.684Z,1719256127.684 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-06-24T19:08:47.684Z,1719256127.684 [DAT](INFO): set transmit power to 8
2024-06-24T19:08:47.684Z,1719256127.684 [DAT](INFO): setting local address to 10
2024-06-24T19:08:47.935Z,1719256127.935 [DAT](INFO): DAT read: user:4>
2024-06-24T19:08:47.936Z,1719256127.936 [DAT](INFO): DAT read: LocalAddr | 10
2024-06-24T19:08:47.936Z,1719256127.936 [DAT](INFO): set local address to 10
2024-06-24T19:08:47.937Z,1719256127.937 [DAT](INFO): Setting time to: 19:8:47 And date to:6/24/2024
2024-06-24T19:08:48.187Z,1719256128.187 [DAT](INFO): DAT read: user:5>
2024-06-24T19:08:48.188Z,1719256128.188 [DAT](INFO): DAT read: Mon Jun 24, 2024 19:08:47
2024-06-24T19:08:48.188Z,1719256128.188 [DAT](INFO): Local DAT time set to Mon Jun 24, 2024 19:08:47
2024-06-24T19:08:57.887Z,1719256137.887 [NAL9602](INFO): Powering up NAL9602
2024-06-24T19:09:00.118Z,1719256140.118 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:09:05.343Z,1719256145.343 [CommandExec](IMPORTANT): got command docking arm
2024-06-24T19:09:05.343Z,1719256145.343 [CommandExec](IMPORTANT): Switching docking moudle to ARM.
2024-06-24T19:09:05.589Z,1719256145.589 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:06.016Z,1719256146.016 [DockingStepper](INFO): Resume
2024-06-24T19:09:06.408Z,1719256146.408 [DockingStepper](INFO): Resuming
2024-06-24T19:09:06.820Z,1719256146.820 [DockingStepper](INFO): Start
2024-06-24T19:09:06.821Z,1719256146.821 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-06-24T19:09:06.939Z,1719256146.939 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-06-24T19:09:07.211Z,1719256147.211 [DockingStepper](INFO): Starting
2024-06-24T19:09:07.673Z,1719256147.673 [DockingStepper](INFO): Starting
2024-06-24T19:09:08.050Z,1719256148.050 [DockingStepper](INFO): Starting
2024-06-24T19:09:08.103Z,1719256148.103 [DockingStepper](INFO): Init string: /1z14799aE13561N3m50
2024-06-24T19:09:08.428Z,1719256148.428 [DockingStepper](INFO): Starting
2024-06-24T19:09:08.808Z,1719256148.808 [NAL9602](INFO): NAL9602 initialized
2024-06-24T19:09:08.828Z,1719256148.828 [DockingStepper](INFO): Starting
2024-06-24T19:09:09.235Z,1719256149.235 [DockingStepper](INFO): Starting
2024-06-24T19:09:09.695Z,1719256149.695 [DockingStepper](INFO): Starting
2024-06-24T19:09:09.722Z,1719256149.722 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-06-24T19:09:10.069Z,1719256150.069 [DockingStepper](INFO): Starting
2024-06-24T19:09:10.457Z,1719256150.457 [DockingStepper](INFO): Changing to mode: 2
2024-06-24T19:09:10.457Z,1719256150.457 [DockingStepper](INFO): Armed mode.
2024-06-24T19:09:10.518Z,1719256150.518 [DockingStepper](INFO): Commanding negative:44939
2024-06-24T19:09:10.671Z,1719256150.671 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:10.671Z,1719256150.671 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:11.042Z,1719256151.042 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:11.043Z,1719256151.043 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:11.454Z,1719256151.454 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:11.455Z,1719256151.455 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:11.946Z,1719256151.946 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:11.947Z,1719256151.947 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:12.259Z,1719256152.259 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:12.259Z,1719256152.259 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:12.910Z,1719256152.910 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:12.911Z,1719256152.911 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:13.194Z,1719256153.194 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:13.195Z,1719256153.195 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:13.826Z,1719256153.826 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:13.826Z,1719256153.826 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:14.123Z,1719256154.123 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:14.123Z,1719256154.123 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:14.483Z,1719256154.483 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:14.483Z,1719256154.483 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:14.867Z,1719256154.867 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:14.867Z,1719256154.867 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:15.271Z,1719256155.271 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:15.271Z,1719256155.271 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:15.687Z,1719256155.687 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:15.687Z,1719256155.687 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:16.119Z,1719256156.119 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:16.119Z,1719256156.119 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:16.475Z,1719256156.475 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:16.475Z,1719256156.475 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:16.922Z,1719256156.922 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:16.923Z,1719256156.923 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:17.303Z,1719256157.303 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:17.303Z,1719256157.303 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:17.703Z,1719256157.703 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:17.703Z,1719256157.703 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:18.147Z,1719256158.147 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:18.147Z,1719256158.147 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:18.499Z,1719256158.499 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:18.499Z,1719256158.499 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:18.915Z,1719256158.915 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:18.915Z,1719256158.915 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:19.315Z,1719256159.315 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:09:19.315Z,1719256159.315 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:19.347Z,1719256159.347 [CommandExec](IMPORTANT): got command docking unrequest
2024-06-24T19:09:19.347Z,1719256159.347 [CommandExec](IMPORTANT): Setting data request false.
2024-06-24T19:09:19.747Z,1719256159.747 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:20.187Z,1719256160.187 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:20.531Z,1719256160.531 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:20.935Z,1719256160.935 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:21.331Z,1719256161.331 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:21.775Z,1719256161.775 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:22.167Z,1719256162.167 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:22.559Z,1719256162.559 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:22.947Z,1719256162.947 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:23.359Z,1719256163.359 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:23.823Z,1719256163.823 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:24.223Z,1719256164.223 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:24.563Z,1719256164.563 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:24.963Z,1719256164.963 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:25.375Z,1719256165.375 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:25.407Z,1719256165.407 [CommandExec](IMPORTANT): got command strobe off
2024-06-24T19:09:25.408Z,1719256165.408 [CommandExec](IMPORTANT): Deactivating strobe
2024-06-24T19:09:25.831Z,1719256165.831 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:26.215Z,1719256166.215 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:26.583Z,1719256166.583 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:26.995Z,1719256166.995 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:27.419Z,1719256167.419 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:27.883Z,1719256167.883 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:28.231Z,1719256168.231 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:28.615Z,1719256168.615 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:29.003Z,1719256169.003 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:29.407Z,1719256169.407 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:29.839Z,1719256169.839 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:29.903Z,1719256169.903 [CommandExec](IMPORTANT): got command failComponent hardware DockingStepper
2024-06-24T19:09:29.903Z,1719256169.903 [DockingStepper] Hardware Fault, FailCount= 1
2024-06-24T19:09:29.903Z,1719256169.903 [DockingStepper](ERROR): Hardware Fault
2024-06-24T19:09:29.903Z,1719256169.903 [CommandExec](IMPORTANT): DockingStepper failureMode is Hardware Fault
2024-06-24T19:09:29.963Z,1719256169.963 [CBIT](ERROR): Hardware Fault in component: DockingStepper
2024-06-24T19:09:30.072Z,1719256170.072 [DockingStepper](INFO): Stop
2024-06-24T19:09:30.073Z,1719256170.073 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2024-06-24T19:09:30.073Z,1719256170.073 [DockingStepper](INFO): Powering down
2024-06-24T19:09:30.476Z,1719256170.476 [DockingStepper](INFO): Stopping
2024-06-24T19:09:30.476Z,1719256170.476 [DockingStepper](FAULT): LCB fault: LCB Watchdog Reset. Invalid Command.
2024-06-24T19:09:30.900Z,1719256170.900 [DockingStepper](INFO): Stopped
2024-06-24T19:09:30.900Z,1719256170.900 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:30.905Z,1719256170.905 [CBIT](INFO): Clearing failed state for component DockingStepper
2024-06-24T19:09:30.905Z,1719256170.905 [DockingStepper] No Fault, FailCount= 1
2024-06-24T19:09:31.282Z,1719256171.282 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:09:31.308Z,1719256171.308 [DockingStepper](INFO): Start
2024-06-24T19:09:31.308Z,1719256171.308 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-06-24T19:09:31.425Z,1719256171.425 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-06-24T19:09:31.715Z,1719256171.715 [DockingStepper](INFO): Starting
2024-06-24T19:09:32.092Z,1719256172.092 [DockingStepper](INFO): Starting
2024-06-24T19:09:32.516Z,1719256172.516 [DockingStepper](INFO): Starting
2024-06-24T19:09:32.559Z,1719256172.559 [DockingStepper](INFO): Init string: /1z7584aE13561N3m50
2024-06-24T19:09:32.634Z,1719256172.634 [DockingStepper](ERROR): Initialization uart error serial timeout
2024-06-24T19:09:32.634Z,1719256172.634 [DockingStepper](FAULT): Failed to initialize
2024-06-24T19:09:32.911Z,1719256172.911 [DockingStepper](INFO): Stop
2024-06-24T19:09:32.911Z,1719256172.911 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2024-06-24T19:09:32.911Z,1719256172.911 [DockingStepper](INFO): Powering down
2024-06-24T19:09:33.337Z,1719256173.337 [DockingStepper](INFO): Stopping
2024-06-24T19:09:33.744Z,1719256173.744 [DockingStepper](INFO): Stopped
2024-06-24T19:09:33.744Z,1719256173.744 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:34.117Z,1719256174.117 [DockingStepper](INFO): Start
2024-06-24T19:09:34.117Z,1719256174.117 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-06-24T19:09:34.235Z,1719256174.235 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-06-24T19:09:34.529Z,1719256174.529 [DockingStepper](INFO): Starting
2024-06-24T19:09:34.920Z,1719256174.920 [DockingStepper](INFO): Starting
2024-06-24T19:09:35.324Z,1719256175.324 [DockingStepper](INFO): Starting
2024-06-24T19:09:35.368Z,1719256175.368 [DockingStepper](INFO): Init string: /1z7584aE13561N3m50
2024-06-24T19:09:35.751Z,1719256175.751 [DockingStepper](INFO): Starting
2024-06-24T19:09:36.139Z,1719256176.139 [DockingStepper](INFO): Starting
2024-06-24T19:09:36.535Z,1719256176.535 [DockingStepper](INFO): Starting
2024-06-24T19:09:36.952Z,1719256176.952 [DockingStepper](INFO): Starting
2024-06-24T19:09:36.983Z,1719256176.983 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-06-24T19:09:37.324Z,1719256177.324 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-06-24T19:09:37.416Z,1719256177.416 [DockingStepper](INFO): Starting
2024-06-24T19:09:37.951Z,1719256177.951 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:38.327Z,1719256178.327 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:38.723Z,1719256178.723 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:39.103Z,1719256179.103 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:39.495Z,1719256179.495 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:39.927Z,1719256179.927 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:40.303Z,1719256180.303 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:40.711Z,1719256180.711 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:41.118Z,1719256181.118 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:41.507Z,1719256181.507 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:41.963Z,1719256181.963 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:42.327Z,1719256182.327 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:42.731Z,1719256182.731 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:43.159Z,1719256183.159 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:43.562Z,1719256183.562 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:43.991Z,1719256183.991 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:44.347Z,1719256184.347 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:44.739Z,1719256184.739 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:45.147Z,1719256185.147 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:45.555Z,1719256185.555 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:45.975Z,1719256185.975 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:46.363Z,1719256186.363 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:46.771Z,1719256186.771 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:47.171Z,1719256187.171 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:47.591Z,1719256187.591 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:48.011Z,1719256188.011 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:48.411Z,1719256188.411 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:48.799Z,1719256188.799 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:49.223Z,1719256189.223 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:49.612Z,1719256189.612 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:50.047Z,1719256190.047 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:50.403Z,1719256190.403 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:50.863Z,1719256190.863 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:51.203Z,1719256191.203 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:51.620Z,1719256191.620 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:52.075Z,1719256192.075 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:52.470Z,1719256192.470 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:52.675Z,1719256192.675 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-06-24T19:09:52.863Z,1719256192.863 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:53.267Z,1719256193.267 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:53.671Z,1719256193.671 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:54.095Z,1719256194.095 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:54.463Z,1719256194.463 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:54.843Z,1719256194.843 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:55.255Z,1719256195.255 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:55.665Z,1719256195.665 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:56.079Z,1719256196.079 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:56.459Z,1719256196.459 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:56.859Z,1719256196.859 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:57.270Z,1719256197.270 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:57.686Z,1719256197.686 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:58.094Z,1719256198.094 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:58.515Z,1719256198.515 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:58.899Z,1719256198.899 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:59.303Z,1719256199.303 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:09:59.723Z,1719256199.723 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:00.131Z,1719256200.131 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:00.503Z,1719256200.503 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:00.903Z,1719256200.903 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:01.319Z,1719256201.319 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:01.711Z,1719256201.711 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:02.155Z,1719256202.155 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:02.406Z,1719256202.406 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:10:02.522Z,1719256202.523 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:02.927Z,1719256202.927 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:03.323Z,1719256203.323 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:03.811Z,1719256203.811 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:04.197Z,1719256204.197 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:04.615Z,1719256204.615 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:04.985Z,1719256204.985 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:05.355Z,1719256205.355 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:05.811Z,1719256205.811 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:06.179Z,1719256206.179 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:06.560Z,1719256206.560 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:06.960Z,1719256206.960 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:07.379Z,1719256207.379 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:07.871Z,1719256207.871 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:08.027Z,1719256208.027 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-06-24T19:10:08.183Z,1719256208.183 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:08.599Z,1719256208.599 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:09.018Z,1719256209.018 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:09.411Z,1719256209.411 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:09.843Z,1719256209.843 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:10.219Z,1719256210.219 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:10.599Z,1719256210.599 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:11.019Z,1719256211.019 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:11.403Z,1719256211.403 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:11.867Z,1719256211.867 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:12.227Z,1719256212.227 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:12.638Z,1719256212.638 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:13.019Z,1719256213.019 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:13.423Z,1719256213.423 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:13.851Z,1719256213.851 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:14.283Z,1719256214.283 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:14.667Z,1719256214.667 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:15.082Z,1719256215.082 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:15.475Z,1719256215.475 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:15.883Z,1719256215.883 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:16.267Z,1719256216.267 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:16.663Z,1719256216.663 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:16.665Z,1719256216.665 [SBIT](IMPORTANT): Beginning Startup BIT
2024-06-24T19:10:16.669Z,1719256216.669 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-24T19:10:17.123Z,1719256217.123 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:17.499Z,1719256217.499 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:17.971Z,1719256217.971 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:18.311Z,1719256218.311 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:18.727Z,1719256218.727 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:19.143Z,1719256219.143 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:19.551Z,1719256219.551 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:19.985Z,1719256219.985 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:20.435Z,1719256220.435 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:20.727Z,1719256220.727 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:21.118Z,1719256221.118 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:21.531Z,1719256221.531 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:21.927Z,1719256221.927 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:22.315Z,1719256222.315 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:22.735Z,1719256222.735 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:23.174Z,1719256223.174 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:23.379Z,1719256223.379 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-06-24T19:10:23.535Z,1719256223.535 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:23.987Z,1719256223.987 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:24.359Z,1719256224.359 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:24.763Z,1719256224.763 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:25.155Z,1719256225.155 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:25.571Z,1719256225.571 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:25.987Z,1719256225.987 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:26.347Z,1719256226.347 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:26.763Z,1719256226.763 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:27.171Z,1719256227.171 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:27.574Z,1719256227.574 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:27.635Z,1719256227.635 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.001066
CHAN A1 (24V): 0.015438
CHAN A2 (12V): -0.001246
CHAN A3 (5V): -0.001259
CHAN B0 (3.3V): 0.000399
CHAN B1 (3.15aV): 0.000154
CHAN B2 (3.15bV): -0.000473
CHAN B3 (GND): -0.000097
OPEN: 0.004484
Full Scale: +/- 1 mA
2024-06-24T19:10:28.019Z,1719256228.019 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:28.371Z,1719256228.371 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:28.791Z,1719256228.791 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:29.203Z,1719256229.203 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:29.604Z,1719256229.604 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:30.031Z,1719256230.031 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:30.423Z,1719256230.423 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:30.807Z,1719256230.807 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:31.223Z,1719256231.223 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:31.727Z,1719256231.727 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:32.071Z,1719256232.071 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:32.466Z,1719256232.466 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:32.859Z,1719256232.859 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:33.263Z,1719256233.263 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:33.534Z,1719256233.534 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:10:33.670Z,1719256233.670 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:34.083Z,1719256234.083 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:34.475Z,1719256234.475 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:34.859Z,1719256234.859 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:35.287Z,1719256235.287 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:35.707Z,1719256235.707 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:36.115Z,1719256236.115 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:36.511Z,1719256236.511 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:36.927Z,1719256236.927 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:37.387Z,1719256237.387 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:37.678Z,1719256237.678 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:38.103Z,1719256238.103 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:38.475Z,1719256238.475 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:38.927Z,1719256238.927 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:39.138Z,1719256239.138 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-06-24T19:10:39.319Z,1719256239.319 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:39.714Z,1719256239.714 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:40.127Z,1719256240.127 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:40.518Z,1719256240.518 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:40.927Z,1719256240.927 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:41.334Z,1719256241.334 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:41.763Z,1719256241.763 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:42.126Z,1719256242.126 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:42.519Z,1719256242.519 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:42.939Z,1719256242.939 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:43.323Z,1719256243.323 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:43.731Z,1719256243.731 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:44.155Z,1719256244.155 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:44.543Z,1719256244.543 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:45.011Z,1719256245.011 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:45.351Z,1719256245.351 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:45.807Z,1719256245.807 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:46.219Z,1719256246.219 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:46.587Z,1719256246.587 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:46.991Z,1719256246.991 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:47.367Z,1719256247.367 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:47.826Z,1719256247.826 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:48.191Z,1719256248.191 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:48.571Z,1719256248.571 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:48.979Z,1719256248.979 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:49.390Z,1719256249.390 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:49.839Z,1719256249.839 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:50.191Z,1719256250.191 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:50.599Z,1719256250.599 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:51.027Z,1719256251.027 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:51.431Z,1719256251.431 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:51.851Z,1719256251.851 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:52.231Z,1719256252.231 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:52.623Z,1719256252.623 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:53.035Z,1719256253.035 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:53.419Z,1719256253.419 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:53.867Z,1719256253.866 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:54.239Z,1719256254.239 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:54.691Z,1719256254.691 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:54.892Z,1719256254.892 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-06-24T19:10:55.059Z,1719256255.059 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:55.451Z,1719256255.451 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:55.899Z,1719256255.899 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:56.271Z,1719256256.271 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:56.687Z,1719256256.687 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:57.101Z,1719256257.101 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:57.483Z,1719256257.483 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:57.892Z,1719256257.892 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:58.395Z,1719256258.395 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:58.715Z,1719256258.715 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:59.119Z,1719256259.119 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:59.535Z,1719256259.535 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:10:59.958Z,1719256259.958 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:00.315Z,1719256260.315 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:00.739Z,1719256260.739 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:01.131Z,1719256261.131 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:01.649Z,1719256261.649 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:02.007Z,1719256262.007 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:02.375Z,1719256262.375 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:02.799Z,1719256262.799 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:03.191Z,1719256263.191 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:03.575Z,1719256263.575 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:03.999Z,1719256263.999 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:04.387Z,1719256264.387 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:04.666Z,1719256264.666 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:11:04.783Z,1719256264.783 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:05.191Z,1719256265.191 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:05.599Z,1719256265.599 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:06.027Z,1719256266.027 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:06.395Z,1719256266.395 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:06.867Z,1719256266.867 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:07.231Z,1719256267.231 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:07.644Z,1719256267.644 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:08.091Z,1719256268.091 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:08.439Z,1719256268.439 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:08.863Z,1719256268.863 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:09.251Z,1719256269.251 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:09.650Z,1719256269.650 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:10.099Z,1719256270.099 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:10.459Z,1719256270.459 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:10.460Z,1719256270.460 [SBIT](IMPORTANT): SBIT PASSED
2024-06-24T19:11:10.460Z,1719256270.460 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-06-24T19:11:10.461Z,1719256270.461 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;
2024-06-24T19:11:10.461Z,1719256270.461 [SBIT](IMPORTANT): Dock.lat=36.9033 degree;
2024-06-24T19:11:10.461Z,1719256270.461 [SBIT](IMPORTANT): Dock.lon=-122.119 degree;
2024-06-24T19:11:10.461Z,1719256270.461 [SBIT](IMPORTANT): Dock.transponderCode=50 count;
2024-06-24T19:11:10.461Z,1719256270.461 [SBIT](IMPORTANT): Docked.verbose=1 bool;
2024-06-24T19:11:10.462Z,1719256270.462 [SBIT](IMPORTANT): DockingStepper.slideValue=9700 count;
2024-06-24T19:11:10.462Z,1719256270.462 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;
2024-06-24T19:11:10.462Z,1719256270.462 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=25 ampere_hour;
2024-06-24T19:11:10.462Z,1719256270.462 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;
2024-06-24T19:11:10.462Z,1719256270.462 [SBIT](IMPORTANT): LineCapture.kiHeadingFinalApproach=0.004 reciprocal_second;
2024-06-24T19:11:10.462Z,1719256270.462 [SBIT](IMPORTANT): LineCapture.kiHeadingTerminalGuidance=0.004 reciprocal_second;
2024-06-24T19:11:10.462Z,1719256270.462 [SBIT](IMPORTANT): LineCapture.navigationGain=3 none;
2024-06-24T19:11:10.462Z,1719256270.462 [SBIT](IMPORTANT): LineCapture.rolloutSpeed=0 meter_per_second;
2024-06-24T19:11:10.463Z,1719256270.463 [SBIT](IMPORTANT): LineCapture.rolloutTimeout=10 minute;
2024-06-24T19:11:10.463Z,1719256270.463 [SBIT](IMPORTANT): LineCapture.searchTimeout=10 minute;
2024-06-24T19:11:10.463Z,1719256270.463 [SBIT](IMPORTANT): LineCapture.shortFinalRange=10 meter;
2024-06-24T19:11:10.463Z,1719256270.463 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;
2024-06-24T19:11:10.463Z,1719256270.463 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=439.628257 cubic_centimeter;
2024-06-24T19:11:10.463Z,1719256270.463 [SBIT](IMPORTANT): VerticalControl.massDefault=21.880764 millimeter;
2024-06-24T19:11:10.716Z,1719256270.716 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2024-06-24T19:11:10.716Z,1719256270.716 [DefaultWithUndock] Running Loop=1
2024-06-24T19:11:10.716Z,1719256270.716 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2024-06-24T19:11:10.716Z,1719256270.716 [DefaultWithUndock:A.Wait] Running Loop=1
2024-06-24T19:11:10.716Z,1719256270.716 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2024-06-24T19:11:10.867Z,1719256270.867 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:11.251Z,1719256271.251 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:11.711Z,1719256271.711 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:12.110Z,1719256272.110 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:12.479Z,1719256272.479 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:12.818Z,1719256272.818 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.006018
2024-06-24T19:11:12.911Z,1719256272.911 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:13.327Z,1719256273.327 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:13.685Z,1719256273.685 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:13.814Z,1719256273.814 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:11:14.115Z,1719256274.115 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:14.483Z,1719256274.483 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:14.895Z,1719256274.895 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:15.399Z,1719256275.399 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:15.727Z,1719256275.727 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:16.263Z,1719256276.263 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:16.587Z,1719256276.587 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:16.935Z,1719256276.935 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:17.355Z,1719256277.355 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:17.847Z,1719256277.847 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:18.267Z,1719256278.267 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:18.591Z,1719256278.591 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:19.011Z,1719256279.011 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:19.419Z,1719256279.419 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:19.839Z,1719256279.839 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:20.319Z,1719256280.319 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:20.607Z,1719256280.607 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:21.039Z,1719256281.039 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:21.459Z,1719256281.459 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:22.023Z,1719256282.023 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:22.263Z,1719256282.263 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:22.683Z,1719256282.683 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:23.091Z,1719256283.091 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:23.455Z,1719256283.455 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:23.879Z,1719256283.879 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:24.235Z,1719256284.235 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:24.631Z,1719256284.631 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:25.051Z,1719256285.051 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:25.547Z,1719256285.547 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:25.925Z,1719256285.925 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:26.227Z,1719256286.227 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:11:26.260Z,1719256286.260 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:26.679Z,1719256286.679 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:27.063Z,1719256287.063 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:27.479Z,1719256287.479 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:27.935Z,1719256287.935 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:28.295Z,1719256288.295 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:28.811Z,1719256288.811 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:28.995Z,1719256288.995 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-06-24T19:11:28.995Z,1719256288.995 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-24T19:11:29.132Z,1719256289.132 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:29.137Z,1719256289.137 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-24T19:11:29.547Z,1719256289.547 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:29.551Z,1719256289.551 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-24T19:11:29.551Z,1719256289.551 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-06-24T19:11:29.991Z,1719256289.991 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:30.303Z,1719256290.303 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:30.711Z,1719256290.711 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:31.147Z,1719256291.147 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:31.547Z,1719256291.547 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:32.015Z,1719256292.015 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:32.375Z,1719256292.375 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:32.731Z,1719256292.731 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:33.187Z,1719256293.187 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:33.551Z,1719256293.551 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:34.075Z,1719256294.075 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:34.395Z,1719256294.395 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:34.775Z,1719256294.775 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:35.167Z,1719256295.167 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:35.584Z,1719256295.584 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:36.003Z,1719256296.003 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:36.383Z,1719256296.383 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:36.759Z,1719256296.759 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:37.163Z,1719256297.163 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:37.579Z,1719256297.579 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:37.995Z,1719256297.995 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:38.395Z,1719256298.395 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:38.791Z,1719256298.791 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:39.250Z,1719256299.250 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:39.624Z,1719256299.624 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:39.782Z,1719256299.782 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:11:40.015Z,1719256300.015 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:40.399Z,1719256300.399 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:40.791Z,1719256300.791 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:41.066Z,1719256301.066 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2024-06-24T19:11:41.067Z,1719256301.067 [DefaultWithUndock:A.Wait] Stopped
2024-06-24T19:11:41.067Z,1719256301.067 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2024-06-24T19:11:41.067Z,1719256301.067 [DefaultWithUndock:LeaveDock] Running Loop=1
2024-06-24T19:11:41.067Z,1719256301.067 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2024-06-24T19:11:41.067Z,1719256301.067 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2024-06-24T19:11:41.067Z,1719256301.067 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2024-06-24T19:11:41.067Z,1719256301.067 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2024-06-24T19:11:41.067Z,1719256301.067 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2024-06-24T19:11:41.068Z,1719256301.068 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2024-06-24T19:11:41.207Z,1719256301.207 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:41.207Z,1719256301.207 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:11:41.464Z,1719256301.464 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2024-06-24T19:11:41.471Z,1719256301.471 [DockingStepper](INFO): Changing to mode: 3
2024-06-24T19:11:41.471Z,1719256301.471 [DockingStepper](INFO): Detach mode.
2024-06-24T19:11:41.594Z,1719256301.594 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:41.595Z,1719256301.595 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:42.023Z,1719256302.023 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock.
2024-06-24T19:11:42.170Z,1719256302.170 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:42.171Z,1719256302.171 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:42.284Z,1719256302.284 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2024-06-24T19:11:42.471Z,1719256302.471 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:42.471Z,1719256302.471 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:42.839Z,1719256302.839 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:42.839Z,1719256302.839 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:43.247Z,1719256303.247 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:43.247Z,1719256303.247 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:43.714Z,1719256303.714 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:43.715Z,1719256303.715 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:43.955Z,1719256303.955 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-24T19:11:43.995Z,1719256303.995 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:11:43.995Z,1719256303.995 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:11:43.995Z,1719256303.995 [DAT](INFO): setting remote address to 0
2024-06-24T19:11:44.123Z,1719256304.123 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:44.123Z,1719256304.123 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:44.247Z,1719256304.247 [DAT](INFO): DAT read: user:6>
2024-06-24T19:11:44.248Z,1719256304.248 [DAT](INFO): DAT read: RemoteAddr | 0
2024-06-24T19:11:44.248Z,1719256304.248 [DAT](INFO): set remote address to 0
2024-06-24T19:11:44.248Z,1719256304.248 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:11:44.248Z,1719256304.248 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:11:44.249Z,1719256304.249 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:11:44.500Z,1719256304.500 [DAT](INFO): DAT read: user:7>
2024-06-24T19:11:44.501Z,1719256304.501 [DAT](INFO): DAT read: Tx time:19:11:43.5021
2024-06-24T19:11:44.501Z,1719256304.501 [DAT](INFO): Ping request sent.
2024-06-24T19:11:44.501Z,1719256304.501 [DAT](INFO): transmitted an acoustic signal
2024-06-24T19:11:44.501Z,1719256304.501 [DAT](INFO): publishing transmit ping time
2024-06-24T19:11:44.506Z,1719256304.506 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.005403
2024-06-24T19:11:44.511Z,1719256304.511 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:44.511Z,1719256304.511 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:44.751Z,1719256304.751 [DAT](INFO): DAT read: TxSync time:19:11:43.5013
2024-06-24T19:11:44.752Z,1719256304.752 [DAT](INFO): unknown deviceResponse_: TxSync time:19:11:43.5013
2024-06-24T19:11:44.752Z,1719256304.752 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251075
2024-06-24T19:11:44.911Z,1719256304.911 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:44.911Z,1719256304.911 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:45.003Z,1719256305.003 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501807
2024-06-24T19:11:45.257Z,1719256305.257 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755212
2024-06-24T19:11:45.295Z,1719256305.295 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:45.295Z,1719256305.295 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:45.507Z,1719256305.507 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005612
2024-06-24T19:11:45.707Z,1719256305.707 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:45.707Z,1719256305.707 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:45.759Z,1719256305.759 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258157
2024-06-24T19:11:46.013Z,1719256306.013 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511574
2024-06-24T19:11:46.135Z,1719256306.135 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:46.135Z,1719256306.135 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:46.263Z,1719256306.263 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.761806
2024-06-24T19:11:46.515Z,1719256306.515 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013878
2024-06-24T19:11:46.519Z,1719256306.519 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:46.519Z,1719256306.519 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:46.741Z,1719256306.741 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-24T19:11:46.767Z,1719256306.767 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266215
2024-06-24T19:11:46.919Z,1719256306.919 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:46.919Z,1719256306.919 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:47.019Z,1719256307.019 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517776
2024-06-24T19:11:47.275Z,1719256307.275 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774111
2024-06-24T19:11:47.295Z,1719256307.295 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:47.295Z,1719256307.295 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:47.523Z,1719256307.523 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022245
2024-06-24T19:11:47.710Z,1719256307.710 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:47.711Z,1719256307.711 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:47.775Z,1719256307.775 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273821
2024-06-24T19:11:48.028Z,1719256308.028 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526636
2024-06-24T19:11:48.131Z,1719256308.131 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:48.131Z,1719256308.131 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:48.279Z,1719256308.279 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.777819
2024-06-24T19:11:48.494Z,1719256308.494 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:48.495Z,1719256308.495 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:48.531Z,1719256308.531 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.029880
2024-06-24T19:11:48.783Z,1719256308.783 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.281876
2024-06-24T19:11:48.930Z,1719256308.930 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:48.931Z,1719256308.931 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:49.035Z,1719256309.035 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.533998
2024-06-24T19:11:49.287Z,1719256309.287 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786017
2024-06-24T19:11:49.326Z,1719256309.326 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:49.327Z,1719256309.327 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:49.539Z,1719256309.539 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038092
2024-06-24T19:11:49.581Z,1719256309.581 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-24T19:11:49.746Z,1719256309.746 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:49.747Z,1719256309.747 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:49.791Z,1719256309.791 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.289999
2024-06-24T19:11:50.002Z,1719256310.002 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:11:50.043Z,1719256310.043 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.541746
2024-06-24T19:11:50.191Z,1719256310.191 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:50.191Z,1719256310.191 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:50.295Z,1719256310.295 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.793829
2024-06-24T19:11:50.534Z,1719256310.534 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:50.535Z,1719256310.535 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:50.547Z,1719256310.547 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.045824
2024-06-24T19:11:50.800Z,1719256310.800 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298641
2024-06-24T19:11:50.930Z,1719256310.930 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:50.931Z,1719256310.931 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:51.051Z,1719256311.051 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.549991
2024-06-24T19:11:51.303Z,1719256311.303 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.801894
2024-06-24T19:11:51.322Z,1719256311.322 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:51.323Z,1719256311.323 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:51.555Z,1719256311.555 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.053670
2024-06-24T19:11:51.759Z,1719256311.759 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:51.759Z,1719256311.759 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:51.807Z,1719256311.807 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306379
2024-06-24T19:11:52.059Z,1719256312.059 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558291
2024-06-24T19:11:52.167Z,1719256312.167 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:52.167Z,1719256312.167 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:52.311Z,1719256312.311 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.809803
2024-06-24T19:11:52.499Z,1719256312.499 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-24T19:11:52.564Z,1719256312.564 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062677
2024-06-24T19:11:52.691Z,1719256312.691 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:52.691Z,1719256312.691 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:52.815Z,1719256312.815 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.313900
2024-06-24T19:11:53.023Z,1719256313.023 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:53.023Z,1719256313.023 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:53.067Z,1719256313.067 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.565848
2024-06-24T19:11:53.320Z,1719256313.320 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818640
2024-06-24T19:11:53.387Z,1719256313.387 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:53.387Z,1719256313.387 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:53.571Z,1719256313.571 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.069893
2024-06-24T19:11:53.824Z,1719256313.824 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322699
2024-06-24T19:11:53.859Z,1719256313.859 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:53.859Z,1719256313.859 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:54.077Z,1719256314.077 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.575909
2024-06-24T19:11:54.227Z,1719256314.227 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:54.227Z,1719256314.227 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:54.327Z,1719256314.327 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.825871
2024-06-24T19:11:54.581Z,1719256314.581 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:11:54.581Z,1719256314.581 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:11:54.582Z,1719256314.582 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:11:54.623Z,1719256314.623 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:54.623Z,1719256314.623 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:54.811Z,1719256314.811 [CommandExec](IMPORTANT): got command show stack
2024-06-24T19:11:54.811Z,1719256314.811 [CommandExec](IMPORTANT): Behavior Stack:
2024-06-24T19:11:54.812Z,1719256314.812 [DefaultWithUndock:LeaveDock](IMPORTANT): Priority 0: DefaultWithUndock:LeaveDock:A.
2024-06-24T19:11:54.812Z,1719256314.812 [DefaultWithUndock:LeaveDock](IMPORTANT): Priority 1: DefaultWithUndock:LeaveDock:B.Undock
2024-06-24T19:11:54.831Z,1719256314.831 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:11:54.831Z,1719256314.831 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:11:54.831Z,1719256314.831 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:11:55.051Z,1719256315.051 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:55.051Z,1719256315.051 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:55.083Z,1719256315.083 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:11:55.083Z,1719256315.083 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:11:55.083Z,1719256315.083 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:11:55.248Z,1719256315.248 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-24T19:11:55.336Z,1719256315.336 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:11:55.336Z,1719256315.336 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:11:55.337Z,1719256315.337 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:11:55.435Z,1719256315.435 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:55.435Z,1719256315.435 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:55.588Z,1719256315.588 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:11:55.588Z,1719256315.588 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:11:55.588Z,1719256315.588 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:11:55.839Z,1719256315.839 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:11:55.839Z,1719256315.839 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:11:55.839Z,1719256315.839 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:11:56.063Z,1719256316.063 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:56.063Z,1719256316.063 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:56.091Z,1719256316.091 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:11:56.091Z,1719256316.091 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:11:56.091Z,1719256316.091 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:11:56.343Z,1719256316.343 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:11:56.343Z,1719256316.343 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:11:56.344Z,1719256316.344 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:11:56.375Z,1719256316.375 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:56.375Z,1719256316.375 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:56.595Z,1719256316.595 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:11:56.595Z,1719256316.595 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:11:56.595Z,1719256316.595 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:11:56.779Z,1719256316.779 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:56.779Z,1719256316.779 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:56.847Z,1719256316.847 [DAT](INFO): DAT read: Response Not Received
2024-06-24T19:11:56.847Z,1719256316.847 [DAT](INFO): response not received
2024-06-24T19:11:56.848Z,1719256316.848 [DAT](INFO): DAT read: user:8>
2024-06-24T19:11:56.848Z,1719256316.848 [DAT](INFO): DAT read: Tx time:19:11:55.9523
2024-06-24T19:11:56.848Z,1719256316.848 [DAT](INFO): Ping request sent.
2024-06-24T19:11:56.849Z,1719256316.849 [DAT](INFO): transmitted an acoustic signal
2024-06-24T19:11:56.849Z,1719256316.849 [DAT](INFO): publishing transmit ping time
2024-06-24T19:11:56.849Z,1719256316.849 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000716
2024-06-24T19:11:57.099Z,1719256317.099 [DAT](INFO): DAT read: TxSync time:19:11:55.9515
2024-06-24T19:11:57.099Z,1719256317.099 [DAT](INFO): unknown deviceResponse_: TxSync time:19:11:55.9515
2024-06-24T19:11:57.100Z,1719256317.100 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251336
2024-06-24T19:11:57.163Z,1719256317.163 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:57.163Z,1719256317.163 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:57.351Z,1719256317.351 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502229
2024-06-24T19:11:57.526Z,1719256317.526 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:57.527Z,1719256317.527 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:57.605Z,1719256317.605 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.756081
2024-06-24T19:11:57.783Z,1719256317.783 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-24T19:11:57.855Z,1719256317.855 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006702
2024-06-24T19:11:57.954Z,1719256317.954 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:57.955Z,1719256317.955 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:58.107Z,1719256318.107 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258171
2024-06-24T19:11:58.334Z,1719256318.334 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:58.335Z,1719256318.335 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:58.360Z,1719256318.360 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510842
2024-06-24T19:11:58.611Z,1719256318.611 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762229
2024-06-24T19:11:58.774Z,1719256318.774 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:58.775Z,1719256318.775 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:58.863Z,1719256318.863 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014176
2024-06-24T19:11:59.116Z,1719256319.116 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267235
2024-06-24T19:11:59.180Z,1719256319.180 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:59.180Z,1719256319.180 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:59.367Z,1719256319.367 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518247
2024-06-24T19:11:59.575Z,1719256319.575 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:59.575Z,1719256319.575 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:11:59.623Z,1719256319.623 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.773967
2024-06-24T19:11:59.871Z,1719256319.871 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022363
2024-06-24T19:11:59.986Z,1719256319.986 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:11:59.987Z,1719256319.987 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:12:00.123Z,1719256320.123 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274123
2024-06-24T19:12:00.267Z,1719256320.267 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:12:00.375Z,1719256320.375 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526418
2024-06-24T19:12:00.446Z,1719256320.446 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:00.447Z,1719256320.447 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:12:00.597Z,1719256320.597 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-24T19:12:00.627Z,1719256320.627 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778164
2024-06-24T19:12:00.767Z,1719256320.767 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:00.767Z,1719256320.767 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:12:00.879Z,1719256320.879 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030142
2024-06-24T19:12:01.084Z,1719256321.084 [CommandExec](IMPORTANT): got command show stack
2024-06-24T19:12:01.084Z,1719256321.084 [CommandExec](IMPORTANT): Behavior Stack:
2024-06-24T19:12:01.084Z,1719256321.084 [DefaultWithUndock:LeaveDock](IMPORTANT): Priority 0: DefaultWithUndock:LeaveDock:A.
2024-06-24T19:12:01.084Z,1719256321.084 [DefaultWithUndock:LeaveDock](IMPORTANT): Priority 1: DefaultWithUndock:LeaveDock:B.Undock
2024-06-24T19:12:01.132Z,1719256321.132 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.283163
2024-06-24T19:12:01.182Z,1719256321.182 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:01.183Z,1719256321.183 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:12:01.383Z,1719256321.383 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534195
2024-06-24T19:12:01.566Z,1719256321.566 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:01.567Z,1719256321.567 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:12:01.637Z,1719256321.637 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.788318
2024-06-24T19:12:01.888Z,1719256321.888 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038968
2024-06-24T19:12:02.014Z,1719256322.014 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:02.015Z,1719256322.015 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:12:02.139Z,1719256322.139 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290060
2024-06-24T19:12:02.383Z,1719256322.383 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:02.383Z,1719256322.383 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:12:02.391Z,1719256322.391 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542524
2024-06-24T19:12:02.631Z,1719256322.631 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby.
2024-06-24T19:12:02.644Z,1719256322.644 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.795660
2024-06-24T19:12:02.807Z,1719256322.807 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:02.807Z,1719256322.807 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:12:02.895Z,1719256322.895 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046193
2024-06-24T19:12:03.086Z,1719256323.086 [DockingStepper](INFO): Changing to mode: 1
2024-06-24T19:12:03.086Z,1719256323.086 [DockingStepper](INFO): Standby mode.
2024-06-24T19:12:03.147Z,1719256323.147 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298190
2024-06-24T19:12:03.150Z,1719256323.150 [DockingStepper](INFO): Commanding positive:54894
2024-06-24T19:12:03.255Z,1719256323.255 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:03.255Z,1719256323.255 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:12:03.399Z,1719256323.399 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550192
2024-06-24T19:12:03.457Z,1719256323.457 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-24T19:12:03.641Z,1719256323.641 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:03.641Z,1719256323.641 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:12:03.666Z,1719256323.666 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.816857
2024-06-24T19:12:03.915Z,1719256323.915 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.066545
2024-06-24T19:12:04.051Z,1719256324.051 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:04.051Z,1719256324.051 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:12:04.167Z,1719256324.167 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.318246
2024-06-24T19:12:04.419Z,1719256324.419 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.570227
2024-06-24T19:12:04.423Z,1719256324.423 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:04.423Z,1719256324.423 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:12:04.671Z,1719256324.671 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.822171
2024-06-24T19:12:04.895Z,1719256324.895 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:04.895Z,1719256324.895 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:12:04.928Z,1719256324.928 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.079300
2024-06-24T19:12:05.179Z,1719256325.179 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.330204
2024-06-24T19:12:05.294Z,1719256325.294 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:05.295Z,1719256325.295 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:12:05.431Z,1719256325.431 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.582049
2024-06-24T19:12:05.669Z,1719256325.669 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:05.669Z,1719256325.669 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:12:05.683Z,1719256325.683 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.834344
2024-06-24T19:12:05.935Z,1719256325.935 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.086190
2024-06-24T19:12:06.021Z,1719256326.021 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-24T19:12:06.187Z,1719256326.187 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.338604
2024-06-24T19:12:06.223Z,1719256326.223 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:06.223Z,1719256326.223 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:12:06.439Z,1719256326.439 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.590815
2024-06-24T19:12:06.582Z,1719256326.582 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:06.583Z,1719256326.583 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:12:06.691Z,1719256326.691 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.842192
2024-06-24T19:12:06.943Z,1719256326.943 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:12:06.943Z,1719256326.943 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:12:06.943Z,1719256326.943 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:12:06.962Z,1719256326.962 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:06.963Z,1719256326.963 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:12:07.195Z,1719256327.195 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:12:07.195Z,1719256327.195 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:12:07.195Z,1719256327.195 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:12:07.447Z,1719256327.447 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:12:07.447Z,1719256327.447 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:12:07.447Z,1719256327.447 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:12:07.598Z,1719256327.598 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:07.599Z,1719256327.599 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:12:07.699Z,1719256327.699 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:12:07.699Z,1719256327.699 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:12:07.699Z,1719256327.699 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:12:07.951Z,1719256327.951 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:12:07.951Z,1719256327.951 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:12:07.951Z,1719256327.951 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:12:08.174Z,1719256328.174 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:08.175Z,1719256328.175 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:12:08.202Z,1719256328.202 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:12:08.203Z,1719256328.203 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:12:08.203Z,1719256328.203 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:12:08.455Z,1719256328.455 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:12:08.455Z,1719256328.455 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:12:08.455Z,1719256328.455 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:12:08.522Z,1719256328.522 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:08.522Z,1719256328.522 [DockingStepper](INFO): isNeeded returning:1
2024-06-24T19:12:08.665Z,1719256328.665 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-24T19:12:08.707Z,1719256328.707 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:12:08.707Z,1719256328.707 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:12:08.707Z,1719256328.707 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:12:08.886Z,1719256328.886 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:08.887Z,1719256328.887 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:12:08.961Z,1719256328.961 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:12:08.961Z,1719256328.961 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:12:08.962Z,1719256328.962 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:12:09.068Z,1719256329.068 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby.
2024-06-24T19:12:09.211Z,1719256329.211 [DAT](INFO): DAT read: Response Not Received
2024-06-24T19:12:09.211Z,1719256329.211 [DAT](INFO): response not received
2024-06-24T19:12:09.212Z,1719256329.212 [DAT](INFO): DAT read: user:9>
2024-06-24T19:12:09.212Z,1719256329.212 [DAT](ERROR): No response from remote modem.
2024-06-24T19:12:09.212Z,1719256329.212 [DAT](INFO): ****** received valid address query ******
2024-06-24T19:12:09.212Z,1719256329.212 [DAT](INFO): ****** received valid ping request ******
2024-06-24T19:12:09.212Z,1719256329.212 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2024-06-24T19:12:09.239Z,1719256329.239 [DockingStepper](INFO): isDataRequested returning true
2024-06-24T19:12:09.239Z,1719256329.239 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:12:09.463Z,1719256329.463 [DAT](INFO): DAT read: Tx time:19:12:08.4024
2024-06-24T19:12:09.463Z,1719256329.463 [DAT](INFO): Ping request sent.
2024-06-24T19:12:09.463Z,1719256329.463 [DAT](INFO): transmitted an acoustic signal
2024-06-24T19:12:09.464Z,1719256329.464 [DAT](INFO): DAT read: TxSync time:19:12:08.4016
2024-06-24T19:12:09.464Z,1719256329.464 [DAT](INFO): unknown deviceResponse_: TxSync time:19:12:08.4016
2024-06-24T19:12:09.465Z,1719256329.465 [DAT](INFO): publishing transmit ping time
2024-06-24T19:12:09.465Z,1719256329.465 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001690
2024-06-24T19:12:09.671Z,1719256329.671 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2024-06-24T19:12:09.671Z,1719256329.671 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2024-06-24T19:12:09.672Z,1719256329.672 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2024-06-24T19:12:09.672Z,1719256329.672 [DefaultWithUndock:LeaveDock] Stopped
2024-06-24T19:12:09.672Z,1719256329.672 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2024-06-24T19:12:09.672Z,1719256329.672 [DefaultWithUndock:LeaveDock:A.] Stopped
2024-06-24T19:12:09.672Z,1719256329.672 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2024-06-24T19:12:09.672Z,1719256329.672 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2024-06-24T19:12:09.672Z,1719256329.672 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2024-06-24T19:12:09.672Z,1719256329.672 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1
2024-06-24T19:12:09.673Z,1719256329.673 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-06-24T19:12:09.673Z,1719256329.673 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-06-24T19:12:09.673Z,1719256329.673 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-06-24T19:12:09.674Z,1719256329.674 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-06-24T19:12:09.674Z,1719256329.674 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-06-24T19:12:09.675Z,1719256329.675 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-06-24T19:12:09.675Z,1719256329.675 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-06-24T19:12:09.715Z,1719256329.715 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251444
2024-06-24T19:12:09.871Z,1719256329.871 [DockingStepper](INFO): isNeeded returning:0
2024-06-24T19:12:09.930Z,1719256329.930 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:12:09.967Z,1719256329.967 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503716
2024-06-24T19:12:10.020Z,1719256330.020 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2024-06-24T19:12:10.020Z,1719256330.020 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2024-06-24T19:12:10.020Z,1719256330.020 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2024-06-24T19:12:10.087Z,1719256330.087 [DockingStepper](INFO): Pause
2024-06-24T19:12:10.219Z,1719256330.219 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755579
2024-06-24T19:12:10.393Z,1719256330.393 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-06-24T19:12:10.471Z,1719256330.471 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007375
2024-06-24T19:12:10.723Z,1719256330.723 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259291
2024-06-24T19:12:10.975Z,1719256330.975 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511368
2024-06-24T19:12:11.227Z,1719256331.227 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763268
2024-06-24T19:12:11.479Z,1719256331.479 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015285
2024-06-24T19:12:11.731Z,1719256331.731 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267373
2024-06-24T19:12:11.983Z,1719256331.983 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519338
2024-06-24T19:12:12.235Z,1719256332.235 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771379
2024-06-24T19:12:12.487Z,1719256332.487 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023510
2024-06-24T19:12:12.739Z,1719256332.739 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275327
2024-06-24T19:12:12.991Z,1719256332.991 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.527412
2024-06-24T19:12:13.244Z,1719256333.244 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.780799
2024-06-24T19:12:13.495Z,1719256333.495 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031355
2024-06-24T19:12:13.753Z,1719256333.753 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.290007
2024-06-24T19:12:14.007Z,1719256334.007 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.543252
2024-06-24T19:12:14.259Z,1719256334.259 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.795369
2024-06-24T19:12:14.511Z,1719256334.511 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.047631
2024-06-24T19:12:14.763Z,1719256334.763 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.299396
2024-06-24T19:12:15.015Z,1719256335.015 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.551345
2024-06-24T19:12:15.283Z,1719256335.283 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.819490
2024-06-24T19:12:15.535Z,1719256335.535 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.071240
2024-06-24T19:12:15.789Z,1719256335.789 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.325343
2024-06-24T19:12:16.043Z,1719256336.043 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.579624
2024-06-24T19:12:16.295Z,1719256336.295 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.831338
2024-06-24T19:12:16.548Z,1719256336.548 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.084056
2024-06-24T19:12:16.799Z,1719256336.799 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.335237
2024-06-24T19:12:17.051Z,1719256337.051 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.587347
2024-06-24T19:12:17.303Z,1719256337.303 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.839469
2024-06-24T19:12:17.555Z,1719256337.555 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.091289
2024-06-24T19:12:17.807Z,1719256337.807 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.343295
2024-06-24T19:12:18.059Z,1719256338.059 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.595369
2024-06-24T19:12:18.311Z,1719256338.311 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.847285
2024-06-24T19:12:18.563Z,1719256338.563 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.099304
2024-06-24T19:12:18.810Z,1719256338.810 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:12:18.817Z,1719256338.817 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.353124
2024-06-24T19:12:19.067Z,1719256339.067 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.603271
2024-06-24T19:12:19.321Z,1719256339.321 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.857461
2024-06-24T19:12:21.843Z,1719256341.843 [DAT](INFO): DAT read: Response Not Received
2024-06-24T19:12:21.843Z,1719256341.843 [DAT](INFO): response not received
2024-06-24T19:12:21.844Z,1719256341.844 [DAT](INFO): DAT read: user:10>
2024-06-24T19:12:21.844Z,1719256341.844 [DAT](INFO): DAT read: Tx time:19:12:20.8525
2024-06-24T19:12:21.845Z,1719256341.845 [DAT](INFO): Ping request sent.
2024-06-24T19:12:21.845Z,1719256341.845 [DAT](INFO): transmitted an acoustic signal
2024-06-24T19:12:21.845Z,1719256341.845 [DAT](INFO): publishing transmit ping time
2024-06-24T19:12:21.845Z,1719256341.845 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000733
2024-06-24T19:12:22.095Z,1719256342.095 [DAT](INFO): DAT read: TxSync time:19:12:20.8517
2024-06-24T19:12:22.095Z,1719256342.095 [DAT](INFO): unknown deviceResponse_: TxSync time:19:12:20.8517
2024-06-24T19:12:22.096Z,1719256342.096 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251259
2024-06-24T19:12:22.347Z,1719256342.347 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502141
2024-06-24T19:12:22.599Z,1719256342.599 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754064
2024-06-24T19:12:22.851Z,1719256342.851 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005932
2024-06-24T19:12:23.103Z,1719256343.103 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258163
2024-06-24T19:12:23.355Z,1719256343.355 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510036
2024-06-24T19:12:23.609Z,1719256343.609 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763774
2024-06-24T19:12:23.859Z,1719256343.859 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014024
2024-06-24T19:12:24.111Z,1719256344.111 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266039
2024-06-24T19:12:24.363Z,1719256344.363 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518122
2024-06-24T19:12:24.617Z,1719256344.617 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772582
2024-06-24T19:12:24.871Z,1719256344.871 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.026165
2024-06-24T19:12:25.123Z,1719256345.123 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278044
2024-06-24T19:12:25.375Z,1719256345.375 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.530292
2024-06-24T19:12:25.629Z,1719256345.629 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.784103
2024-06-24T19:12:25.879Z,1719256345.879 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.034201
2024-06-24T19:12:26.131Z,1719256346.131 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.286138
2024-06-24T19:12:26.383Z,1719256346.383 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.538393
2024-06-24T19:12:26.635Z,1719256346.635 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.790287
2024-06-24T19:12:26.887Z,1719256346.887 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.042083
2024-06-24T19:12:27.139Z,1719256347.139 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.293997
2024-06-24T19:12:27.391Z,1719256347.391 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.546099
2024-06-24T19:12:27.645Z,1719256347.645 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.799885
2024-06-24T19:12:27.798Z,1719256347.798 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:12:27.895Z,1719256347.895 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.049965
2024-06-24T19:12:28.147Z,1719256348.147 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.302294
2024-06-24T19:12:28.399Z,1719256348.399 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.554501
2024-06-24T19:12:28.651Z,1719256348.651 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806213
2024-06-24T19:12:28.903Z,1719256348.903 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.058281
2024-06-24T19:12:29.155Z,1719256349.155 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.310240
2024-06-24T19:12:29.407Z,1719256349.407 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.562146
2024-06-24T19:12:29.661Z,1719256349.661 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.815937
2024-06-24T19:12:29.912Z,1719256349.912 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.066928
2024-06-24T19:12:30.167Z,1719256350.167 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.321972
2024-06-24T19:12:30.419Z,1719256350.419 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.574088
2024-06-24T19:12:30.676Z,1719256350.676 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.831111
2024-06-24T19:12:30.935Z,1719256350.935 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.090065
2024-06-24T19:12:31.187Z,1719256351.187 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.342052
2024-06-24T19:12:31.439Z,1719256351.439 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.594095
2024-06-24T19:12:31.691Z,1719256351.691 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.846211
2024-06-24T19:12:34.212Z,1719256354.212 [DAT](INFO): DAT read: Response Not Received
2024-06-24T19:12:34.212Z,1719256354.212 [DAT](INFO): response not received
2024-06-24T19:12:34.212Z,1719256354.212 [DAT](INFO): DAT read: user:11>
2024-06-24T19:12:34.213Z,1719256354.213 [DAT](INFO): DAT read: Tx time:19:12:33.3026
2024-06-24T19:12:34.213Z,1719256354.213 [DAT](INFO): Ping request sent.
2024-06-24T19:12:34.213Z,1719256354.213 [DAT](INFO): transmitted an acoustic signal
2024-06-24T19:12:34.214Z,1719256354.214 [DAT](INFO): publishing transmit ping time
2024-06-24T19:12:34.222Z,1719256354.222 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.008912
2024-06-24T19:12:34.467Z,1719256354.467 [DAT](INFO): DAT read: TxSync time:19:12:33.3018
2024-06-24T19:12:34.467Z,1719256354.467 [DAT](INFO): unknown deviceResponse_: TxSync time:19:12:33.3018
2024-06-24T19:12:34.468Z,1719256354.468 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.254437
2024-06-24T19:12:34.727Z,1719256354.727 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.513563
2024-06-24T19:12:34.979Z,1719256354.979 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.765327
2024-06-24T19:12:35.231Z,1719256355.231 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.017326
2024-06-24T19:12:35.483Z,1719256355.483 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.269131
2024-06-24T19:12:35.735Z,1719256355.735 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.521264
2024-06-24T19:12:35.987Z,1719256355.987 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.773337
2024-06-24T19:12:36.239Z,1719256356.239 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.025234
2024-06-24T19:12:36.491Z,1719256356.491 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.277714
2024-06-24T19:12:36.743Z,1719256356.743 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.529343
2024-06-24T19:12:36.995Z,1719256356.995 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.781494
2024-06-24T19:12:37.247Z,1719256357.247 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.033718
2024-06-24T19:12:37.507Z,1719256357.507 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.293466
2024-06-24T19:12:37.759Z,1719256357.759 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.545445
2024-06-24T19:12:38.011Z,1719256358.011 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.797709
2024-06-24T19:12:38.263Z,1719256358.263 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.049363
2024-06-24T19:12:38.515Z,1719256358.515 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.301435
2024-06-24T19:12:38.767Z,1719256358.767 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.553423
2024-06-24T19:12:39.019Z,1719256359.019 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.805468
2024-06-24T19:12:39.271Z,1719256359.271 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.057421
2024-06-24T19:12:39.531Z,1719256359.531 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.317232
2024-06-24T19:12:39.783Z,1719256359.783 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.569494
2024-06-24T19:12:39.858Z,1719256359.858 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:12:40.035Z,1719256360.035 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.821478
2024-06-24T19:12:40.287Z,1719256360.287 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.073713
2024-06-24T19:12:40.539Z,1719256360.539 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.325740
2024-06-24T19:12:40.791Z,1719256360.791 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.577606
2024-06-24T19:12:41.043Z,1719256361.043 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.829519
2024-06-24T19:12:41.296Z,1719256361.296 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.082035
2024-06-24T19:12:41.547Z,1719256361.547 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.333466
2024-06-24T19:12:41.799Z,1719256361.799 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.585760
2024-06-24T19:12:42.051Z,1719256362.051 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.837531
2024-06-24T19:12:42.303Z,1719256362.303 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.089418
2024-06-24T19:12:42.555Z,1719256362.555 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.341620
2024-06-24T19:12:42.807Z,1719256362.807 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.593878
2024-06-24T19:12:43.059Z,1719256363.059 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.845482
2024-06-24T19:12:43.312Z,1719256363.312 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.098317
2024-06-24T19:12:43.563Z,1719256363.563 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.349252
2024-06-24T19:12:43.815Z,1719256363.815 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.601313
2024-06-24T19:12:44.067Z,1719256364.067 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.853631
2024-06-24T19:12:45.586Z,1719256365.586 [CommandExec](IMPORTANT): got command restart application
2024-06-24T19:12:46.589Z,1719256366.589 [DAT](INFO): DAT read: Response Not Received
2024-06-24T19:12:46.589Z,1719256366.589 [DAT](INFO): response not received
2024-06-24T19:12:46.590Z,1719256366.590 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:12:46.590Z,1719256366.590 [CommandExec](INFO): Uninitializing the command executive.
2024-06-24T19:12:46.590Z,1719256366.590 [CommandExec](INFO): Uninitializing the command scheduler.
2024-06-24T19:12:46.591Z,1719256366.591 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:46.591Z,1719256366.591 [DAT](INFO): DAT read: user:12>
2024-06-24T19:12:46.592Z,1719256366.592 [DAT](INFO): DAT read: Tx time:19:12:45.7527
2024-06-24T19:12:46.593Z,1719256366.593 [DAT](INFO): Ping request sent.
2024-06-24T19:12:46.593Z,1719256366.593 [DAT](INFO): transmitted an acoustic signal
2024-06-24T19:12:46.593Z,1719256366.593 [DAT](INFO): publishing transmit ping time
2024-06-24T19:12:46.593Z,1719256366.593 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000740
2024-06-24T19:12:46.730Z,1719256366.730 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-06-24T19:12:46.730Z,1719256366.730 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-06-24T19:12:46.730Z,1719256366.730 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:46.731Z,1719256366.731 [NavChartDb](INFO): Join timeout helper Thread ID is 2613
2024-06-24T19:12:46.839Z,1719256366.839 [DAT](INFO): DAT read: TxSync time:19:12:45.7519
2024-06-24T19:12:46.840Z,1719256366.840 [DAT](INFO): unknown deviceResponse_: TxSync time:19:12:45.7519
2024-06-24T19:12:46.840Z,1719256366.840 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.247428
2024-06-24T19:12:46.891Z,1719256366.891 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:12:46.891Z,1719256366.891 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:46.898Z,1719256366.898 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2024-06-24T19:12:46.898Z,1719256366.898 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:46.899Z,1719256366.899 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 2614
2024-06-24T19:12:47.091Z,1719256367.091 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.498078
2024-06-24T19:12:47.295Z,1719256367.295 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:12:47.295Z,1719256367.295 [WetLabsUBAT](INFO): Powering down
2024-06-24T19:12:47.296Z,1719256367.296 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:47.314Z,1719256367.314 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-06-24T19:12:47.314Z,1719256367.314 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:47.315Z,1719256367.315 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2615
2024-06-24T19:12:47.343Z,1719256367.343 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.750165
2024-06-24T19:12:47.595Z,1719256367.595 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.002156
2024-06-24T19:12:47.782Z,1719256367.782 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:12:47.782Z,1719256367.782 [WetLabsBB2FL](INFO): Powering down
2024-06-24T19:12:47.783Z,1719256367.783 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:47.798Z,1719256367.798 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-06-24T19:12:47.798Z,1719256367.798 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:47.798Z,1719256367.798 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2616
2024-06-24T19:12:47.847Z,1719256367.847 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.254375
2024-06-24T19:12:48.099Z,1719256368.099 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.506229
2024-06-24T19:12:48.166Z,1719256368.166 [CTD_Seabird](INFO): Powering down
2024-06-24T19:12:48.178Z,1719256368.178 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:12:48.178Z,1719256368.178 [CTD_Seabird](INFO): Powering down
2024-06-24T19:12:48.190Z,1719256368.190 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:48.199Z,1719256368.199 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-06-24T19:12:48.199Z,1719256368.199 [DAT ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:48.199Z,1719256368.199 [DAT](INFO): Join timeout helper Thread ID is 2617
2024-06-24T19:12:48.350Z,1719256368.350 [DAT](INFO): Powering down
2024-06-24T19:12:48.418Z,1719256368.418 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-24T19:12:48.427Z,1719256368.427 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:12:48.427Z,1719256368.427 [DAT](INFO): Powering down
2024-06-24T19:12:48.428Z,1719256368.428 [DAT ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:48.444Z,1719256368.444 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-06-24T19:12:48.444Z,1719256368.444 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:48.445Z,1719256368.445 [Radio_Surface](INFO): Join timeout helper Thread ID is 2618
2024-06-24T19:12:48.530Z,1719256368.530 [Radio_Surface](INFO): Powering down
2024-06-24T19:12:48.531Z,1719256368.531 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:12:48.531Z,1719256368.531 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:48.543Z,1719256368.543 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-06-24T19:12:48.543Z,1719256368.543 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:48.543Z,1719256368.543 [Onboard](INFO): Join timeout helper Thread ID is 2619
2024-06-24T19:12:49.214Z,1719256369.214 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:12:49.215Z,1719256369.215 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.227Z,1719256369.227 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-06-24T19:12:49.227Z,1719256369.227 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.227Z,1719256369.227 [DataOverHttps](INFO): Join timeout helper Thread ID is 2620
2024-06-24T19:12:49.422Z,1719256369.422 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:12:49.423Z,1719256369.423 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.435Z,1719256369.435 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-06-24T19:12:49.435Z,1719256369.435 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.435Z,1719256369.435 [BackseatComponent](INFO): Join timeout helper Thread ID is 2621
2024-06-24T19:12:49.458Z,1719256369.458 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:12:49.458Z,1719256369.458 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.460Z,1719256369.460 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-06-24T19:12:49.460Z,1719256369.460 [logger ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.460Z,1719256369.460 [logger](INFO): Join timeout helper Thread ID is 2622
2024-06-24T19:12:49.478Z,1719256369.478 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:12:49.479Z,1719256369.479 [logger ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.483Z,1719256369.483 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-06-24T19:12:49.483Z,1719256369.483 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.483Z,1719256369.483 [CommandLine](INFO): Join timeout helper Thread ID is 2623
2024-06-24T19:12:49.514Z,1719256369.514 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:12:49.514Z,1719256369.514 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.523Z,1719256369.523 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-06-24T19:12:49.523Z,1719256369.523 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.523Z,1719256369.523 [CommandExec](INFO): Join timeout helper Thread ID is 2624
2024-06-24T19:12:49.524Z,1719256369.524 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-06-24T19:12:49.524Z,1719256369.524 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.525Z,1719256369.525 [controlThread](INFO): Join timeout helper Thread ID is 2625
2024-06-24T19:12:49.578Z,1719256369.578 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-06-24T19:12:49.579Z,1719256369.579 [controlThread](DEBUG): Uninitializing ControlThread
2024-06-24T19:12:49.579Z,1719256369.579 [AHRS_M2](INFO): Powering down
2024-06-24T19:12:49.655Z,1719256369.655 [NAL9602](INFO): Powering down
2024-06-24T19:12:49.730Z,1719256369.730 [RDI_Pathfinder](INFO): Powering down
2024-06-24T19:12:49.731Z,1719256369.731 [Sonardyne_Nano](INFO): Powering down
2024-06-24T19:12:49.851Z,1719256369.851 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-06-24T19:12:49.851Z,1719256369.851 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-06-24T19:12:49.852Z,1719256369.852 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-06-24T19:12:49.852Z,1719256369.852 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2024-06-24T19:12:49.853Z,1719256369.853 [DefaultWithUndock] Stopped
2024-06-24T19:12:49.853Z,1719256369.853 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2024-06-24T19:12:49.853Z,1719256369.853 [DefaultWithUndock:SurfaceDefault] Stopped
2024-06-24T19:12:49.853Z,1719256369.853 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2024-06-24T19:12:49.853Z,1719256369.853 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped
2024-06-24T19:12:49.853Z,1719256369.853 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-06-24T19:12:49.853Z,1719256369.853 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2024-06-24T19:12:49.853Z,1719256369.853 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2024-06-24T19:12:49.853Z,1719256369.853 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2024-06-24T19:12:49.861Z,1719256369.861 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-06-24T19:12:49.861Z,1719256369.861 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-06-24T19:12:49.861Z,1719256369.861 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-06-24T19:12:49.861Z,1719256369.861 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-06-24T19:12:49.862Z,1719256369.862 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-06-24T19:12:49.862Z,1719256369.862 [BuoyancyServo](INFO): Powering down
2024-06-24T19:12:49.874Z,1719256369.874 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-06-24T19:12:49.874Z,1719256369.874 [ElevatorServo](INFO): Powering down
2024-06-24T19:12:49.875Z,1719256369.875 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2024-06-24T19:12:49.875Z,1719256369.875 [DockingStepper](INFO): Powering down
2024-06-24T19:12:49.876Z,1719256369.876 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-06-24T19:12:49.876Z,1719256369.876 [MassServo](INFO): Powering down
2024-06-24T19:12:49.877Z,1719256369.877 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-06-24T19:12:49.877Z,1719256369.877 [RudderServo](INFO): Powering down
2024-06-24T19:12:49.878Z,1719256369.878 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2024-06-24T19:12:49.878Z,1719256369.878 [ThrusterServo](INFO): Powering down
2024-06-24T19:12:49.879Z,1719256369.879 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-06-24T19:12:49.879Z,1719256369.879 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-06-24T19:12:49.879Z,1719256369.879 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-06-24T19:12:49.880Z,1719256369.880 [CBIT](DEBUG): Powering off loads.
2024-06-24T19:12:49.894Z,1719256369.894 [CBIT](DEBUG): Disabling WDT.
2024-06-24T19:12:49.906Z,1719256369.906 [CBIT](DEBUG): Opening all GF detection circuits.
2024-06-24T19:12:49.907Z,1719256369.907 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.972Z,1719256369.972 [DAT ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.979Z,1719256369.979 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.981Z,1719256369.981 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:49.987Z,1719256369.987 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:50.034Z,1719256370.034 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:50.097Z,1719256370.097 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:50.099Z,1719256370.099 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:50.103Z,1719256370.103 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:50.111Z,1719256370.111 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-06-24T19:12:50.179Z,1719256370.179 [logger ThreadHandler](INFO): Thread cancelled.