2024-05-28T20:01:15.266Z,1716926475.266 [Supervisor](DEBUG): Initializing supervisor.
2024-05-28T20:01:15.318Z,1716926475.318 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-05-28T20:01:15.319Z,1716926475.319 [SyncHandler](INFO): Protected caller Thread ID is 8698
2024-05-28T20:01:15.319Z,1716926475.319 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-05-28T20:01:15.320Z,1716926475.320 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-05-28T20:01:15.320Z,1716926475.320 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8699
2024-05-28T20:01:15.324Z,1716926475.324 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-05-28T20:01:15.343Z,1716926475.343 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-05-28T20:01:15.344Z,1716926475.344 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-05-28T20:01:15.344Z,1716926475.344 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8700
2024-05-28T20:01:15.348Z,1716926475.348 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-05-28T20:01:15.349Z,1716926475.349 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-05-28T20:01:15.350Z,1716926475.350 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8701
2024-05-28T20:01:15.352Z,1716926475.352 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-05-28T20:01:15.353Z,1716926475.353 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-05-28T20:01:15.353Z,1716926475.353 [logger ThreadHandler](INFO): Protected caller Thread ID is 8702
2024-05-28T20:01:15.357Z,1716926475.357 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-05-28T20:01:15.357Z,1716926475.357 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-05-28T20:01:15.360Z,1716926475.360 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-05-28T20:01:15.638Z,1716926475.638 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-05-28T20:01:15.640Z,1716926475.640 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-05-28T20:01:16.460Z,1716926476.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-05-28T20:01:16.461Z,1716926476.461 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-05-28T20:01:17.209Z,1716926477.209 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-05-28T20:01:17.210Z,1716926477.210 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-05-28T20:01:17.365Z,1716926477.365 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-05-28T20:01:17.518Z,1716926477.518 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-05-28T20:01:17.519Z,1716926477.519 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-05-28T20:01:17.618Z,1716926477.618 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-05-28T20:01:17.744Z,1716926477.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-05-28T20:01:17.745Z,1716926477.745 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-05-28T20:01:18.269Z,1716926478.269 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-05-28T20:01:18.271Z,1716926478.271 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-05-28T20:01:18.893Z,1716926478.893 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-05-28T20:01:18.894Z,1716926478.894 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-05-28T20:01:19.351Z,1716926479.351 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-05-28T20:01:19.352Z,1716926479.352 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-05-28T20:01:19.566Z,1716926479.566 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-05-28T20:01:19.567Z,1716926479.567 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-05-28T20:01:20.147Z,1716926480.147 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-05-28T20:01:20.148Z,1716926480.148 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-05-28T20:01:20.470Z,1716926480.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-05-28T20:01:20.470Z,1716926480.470 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-05-28T20:01:20.727Z,1716926480.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-05-28T20:01:20.728Z,1716926480.728 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-05-28T20:01:21.144Z,1716926481.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-05-28T20:01:21.144Z,1716926481.144 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-05-28T20:01:21.531Z,1716926481.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-05-28T20:01:21.533Z,1716926481.533 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2024-05-28T20:01:21.534Z,1716926481.534 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2024-05-28T20:01:21.635Z,1716926481.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2024-05-28T20:01:21.828Z,1716926481.828 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg
2024-05-28T20:01:21.941Z,1716926481.941 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2024-05-28T20:01:22.067Z,1716926482.067 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2024-05-28T20:01:22.169Z,1716926482.169 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2024-05-28T20:01:22.282Z,1716926482.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2024-05-28T20:01:22.414Z,1716926482.414 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2024-05-28T20:01:22.696Z,1716926482.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-05-28T20:01:22.696Z,1716926482.696 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2024-05-28T20:01:22.808Z,1716926482.808 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2024-05-28T20:01:22.919Z,1716926482.919 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2024-05-28T20:01:23.082Z,1716926483.082 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2024-05-28T20:01:23.200Z,1716926483.200 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2024-05-28T20:01:23.200Z,1716926483.200 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-05-28T20:01:23.215Z,1716926483.215 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-05-28T20:01:23.353Z,1716926483.353 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-05-28T20:01:23.354Z,1716926483.354 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-05-28T20:01:23.422Z,1716926483.422 [VerticalControl](DEBUG): Construct VerticalControl.
2024-05-28T20:01:23.482Z,1716926483.482 [VerticalControl] Loaded
2024-05-28T20:01:23.483Z,1716926483.483 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-05-28T20:01:23.485Z,1716926483.485 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-05-28T20:01:23.533Z,1716926483.533 [HorizontalControl] Loaded
2024-05-28T20:01:23.533Z,1716926483.533 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-05-28T20:01:23.536Z,1716926483.536 [SpeedControl](DEBUG): Construct SpeedControl.
2024-05-28T20:01:23.539Z,1716926483.539 [SpeedControl] Loaded
2024-05-28T20:01:23.539Z,1716926483.539 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-05-28T20:01:23.542Z,1716926483.542 [LoopControl](DEBUG): Construct LoopControl.
2024-05-28T20:01:23.542Z,1716926483.542 [LoopControl] Loaded
2024-05-28T20:01:23.543Z,1716926483.543 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-05-28T20:01:23.543Z,1716926483.543 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-05-28T20:01:23.544Z,1716926483.544 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-05-28T20:01:23.612Z,1716926483.612 [DepthRateCalculator] Loaded
2024-05-28T20:01:23.612Z,1716926483.612 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-05-28T20:01:23.617Z,1716926483.617 [PitchRateCalculator] Loaded
2024-05-28T20:01:23.617Z,1716926483.617 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-05-28T20:01:23.627Z,1716926483.627 [SpeedCalculator] Loaded
2024-05-28T20:01:23.627Z,1716926483.627 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-05-28T20:01:23.631Z,1716926483.631 [YawRateCalculator] Loaded
2024-05-28T20:01:23.632Z,1716926483.632 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-05-28T20:01:23.650Z,1716926483.650 [ElevatorOffsetCalculator] Loaded
2024-05-28T20:01:23.650Z,1716926483.650 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-05-28T20:01:23.650Z,1716926483.650 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-05-28T20:01:23.651Z,1716926483.651 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-05-28T20:01:23.695Z,1716926483.695 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-05-28T20:01:23.696Z,1716926483.696 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-05-28T20:01:23.777Z,1716926483.777 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-05-28T20:01:23.777Z,1716926483.777 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-05-28T20:01:24.178Z,1716926484.178 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-05-28T20:01:24.179Z,1716926484.179 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-05-28T20:01:24.587Z,1716926484.587 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-05-28T20:01:24.588Z,1716926484.588 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-05-28T20:01:25.390Z,1716926485.390 [AHRS_M2] Loaded
2024-05-28T20:01:25.390Z,1716926485.390 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-05-28T20:01:25.652Z,1716926485.652 [BackseatComponent] Loaded
2024-05-28T20:01:25.652Z,1716926485.652 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-05-28T20:01:25.657Z,1716926485.657 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409C34E0
2024-05-28T20:01:25.657Z,1716926485.657 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8788
2024-05-28T20:01:25.660Z,1716926485.660 [LcmUniversalReporter] Loaded
2024-05-28T20:01:25.660Z,1716926485.660 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-05-28T20:01:26.486Z,1716926486.486 [BPC1] Loaded
2024-05-28T20:01:26.486Z,1716926486.486 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-05-28T20:01:26.584Z,1716926486.584 [DataOverHttps] Loaded
2024-05-28T20:01:26.585Z,1716926486.585 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-05-28T20:01:26.586Z,1716926486.586 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F34E0
2024-05-28T20:01:26.586Z,1716926486.586 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8789
2024-05-28T20:01:26.609Z,1716926486.609 [Depth_Keller] Loaded
2024-05-28T20:01:26.609Z,1716926486.609 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-05-28T20:01:26.614Z,1716926486.614 [DropWeight] Loaded
2024-05-28T20:01:26.615Z,1716926486.615 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-05-28T20:01:26.679Z,1716926486.679 [NAL9602] Loaded
2024-05-28T20:01:26.679Z,1716926486.679 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-05-28T20:01:26.709Z,1716926486.709 [Onboard] Loaded
2024-05-28T20:01:26.709Z,1716926486.709 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-05-28T20:01:26.710Z,1716926486.710 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A234E0
2024-05-28T20:01:26.711Z,1716926486.711 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8790
2024-05-28T20:01:26.725Z,1716926486.725 [Power24vConverter] Loaded
2024-05-28T20:01:26.725Z,1716926486.725 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-05-28T20:01:26.740Z,1716926486.740 [Radio_Surface] Loaded
2024-05-28T20:01:26.740Z,1716926486.740 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-05-28T20:01:26.741Z,1716926486.741 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A534E0
2024-05-28T20:01:26.742Z,1716926486.742 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8791
2024-05-28T20:01:26.789Z,1716926486.789 [RDI_Pathfinder] Loaded
2024-05-28T20:01:26.789Z,1716926486.789 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2024-05-28T20:01:26.885Z,1716926486.885 [DAT] Loaded
2024-05-28T20:01:26.885Z,1716926486.885 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-05-28T20:01:26.886Z,1716926486.886 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A834E0
2024-05-28T20:01:26.887Z,1716926486.887 [DAT ThreadHandler](INFO): Protected caller Thread ID is 8792
2024-05-28T20:01:26.887Z,1716926486.887 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-05-28T20:01:26.888Z,1716926486.888 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-05-28T20:01:27.023Z,1716926487.023 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-05-28T20:01:27.023Z,1716926487.023 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-05-28T20:01:27.037Z,1716926487.037 [NavChart] Loaded
2024-05-28T20:01:27.037Z,1716926487.037 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-05-28T20:01:27.042Z,1716926487.042 [UniversalFixResidualReporter] Loaded
2024-05-28T20:01:27.043Z,1716926487.043 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-05-28T20:01:27.043Z,1716926487.043 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-05-28T20:01:27.044Z,1716926487.044 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-05-28T20:01:27.207Z,1716926487.207 [SBIT](DEBUG): Construct Startup Built In Test.
2024-05-28T20:01:27.216Z,1716926487.216 [SBIT] Loaded
2024-05-28T20:01:27.217Z,1716926487.217 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-05-28T20:01:27.220Z,1716926487.220 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-05-28T20:01:27.232Z,1716926487.232 [IBIT] Loaded
2024-05-28T20:01:27.232Z,1716926487.232 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-05-28T20:01:27.238Z,1716926487.238 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-05-28T20:01:27.343Z,1716926487.343 [CBIT] Loaded
2024-05-28T20:01:27.344Z,1716926487.344 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-05-28T20:01:27.344Z,1716926487.344 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-05-28T20:01:27.345Z,1716926487.345 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-05-28T20:01:27.511Z,1716926487.511 [BuoyancyServo] Loaded
2024-05-28T20:01:27.511Z,1716926487.511 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-05-28T20:01:27.535Z,1716926487.535 [ElevatorServo] Loaded
2024-05-28T20:01:27.535Z,1716926487.535 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-05-28T20:01:27.569Z,1716926487.569 [DockingStepper] Loaded
2024-05-28T20:01:27.569Z,1716926487.569 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2024-05-28T20:01:27.592Z,1716926487.592 [MassServo] Loaded
2024-05-28T20:01:27.592Z,1716926487.592 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-05-28T20:01:27.612Z,1716926487.612 [RudderServo] Loaded
2024-05-28T20:01:27.613Z,1716926487.613 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-05-28T20:01:27.631Z,1716926487.631 [ThrusterServo] Loaded
2024-05-28T20:01:27.632Z,1716926487.632 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2024-05-28T20:01:27.632Z,1716926487.632 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-05-28T20:01:27.633Z,1716926487.633 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-05-28T20:01:27.648Z,1716926487.648 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-05-28T20:01:27.649Z,1716926487.649 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-05-28T20:01:27.879Z,1716926487.879 [CTD_Seabird] Loaded
2024-05-28T20:01:27.879Z,1716926487.879 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-05-28T20:01:27.889Z,1716926487.889 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0
2024-05-28T20:01:27.889Z,1716926487.889 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8793
2024-05-28T20:01:27.913Z,1716926487.913 [PAR_Licor] Loaded
2024-05-28T20:01:27.913Z,1716926487.913 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-05-28T20:01:27.958Z,1716926487.958 [WetLabsBB2FL] Loaded
2024-05-28T20:01:27.958Z,1716926487.958 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-05-28T20:01:27.960Z,1716926487.960 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-05-28T20:01:27.960Z,1716926487.960 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8794
2024-05-28T20:01:27.981Z,1716926487.981 [WetLabsUBAT] Loaded
2024-05-28T20:01:27.981Z,1716926487.981 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2024-05-28T20:01:27.986Z,1716926487.986 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C374E0
2024-05-28T20:01:27.986Z,1716926487.986 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 8795
2024-05-28T20:01:27.987Z,1716926487.987 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-05-28T20:01:27.993Z,1716926487.993 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-05-28T20:01:27.997Z,1716926487.997 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-05-28T20:01:28.008Z,1716926488.008 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-05-28T20:01:28.009Z,1716926488.009 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C674E0
2024-05-28T20:01:28.009Z,1716926488.009 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8796
2024-05-28T20:01:28.014Z,1716926488.014 [Supervisor](INFO): Main Thread ID is 8697
2024-05-28T20:01:28.014Z,1716926488.014 [Supervisor](DEBUG): Running supervisor.
2024-05-28T20:01:28.015Z,1716926488.015 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8797
2024-05-28T20:01:28.015Z,1716926488.015 [CommandExec](INFO): Initializing the command executive.
2024-05-28T20:01:28.017Z,1716926488.017 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8798
2024-05-28T20:01:28.020Z,1716926488.020 [controlThread ThreadHandler](INFO): Handler Thread ID is 8799
2024-05-28T20:01:28.021Z,1716926488.021 [controlThread](DEBUG): Initializing ControlThread
2024-05-28T20:01:28.022Z,1716926488.022 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-05-28T20:01:28.023Z,1716926488.023 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-05-28T20:01:28.024Z,1716926488.024 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-05-28T20:01:28.024Z,1716926488.024 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-05-28T20:01:28.025Z,1716926488.025 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-05-28T20:01:28.025Z,1716926488.025 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-05-28T20:01:28.025Z,1716926488.025 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-05-28T20:01:28.026Z,1716926488.026 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-05-28T20:01:28.026Z,1716926488.026 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-05-28T20:01:28.033Z,1716926488.033 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-05-28T20:01:28.033Z,1716926488.033 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-05-28T20:01:28.034Z,1716926488.034 [SBIT](INFO): Initialize SBIT Component.
2024-05-28T20:01:28.034Z,1716926488.034 [SBIT](IMPORTANT): git: 2024-05-22-2-gd2983461b
2024-05-28T20:01:28.034Z,1716926488.034 [SBIT](INFO): git hash: d2983461b0afca1bb4c5ae8a9b5bc51c90c2e86f
2024-05-28T20:01:28.035Z,1716926488.035 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-05-28T20:01:28.036Z,1716926488.036 [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-05-28T20:01:28.037Z,1716926488.037 [SBIT](INFO): Beginning SBIT in 139.000000 seconds.
2024-05-28T20:01:28.037Z,1716926488.037 [IBIT](INFO): Initialize IBIT Component.
2024-05-28T20:01:28.038Z,1716926488.038 [CBIT](DEBUG): Initialize CBIT Component.
2024-05-28T20:01:28.039Z,1716926488.039 [logger ThreadHandler](INFO): Handler Thread ID is 8800
2024-05-28T20:01:28.051Z,1716926488.050 [CBIT](DEBUG): Initialized mux pins.
2024-05-28T20:01:28.051Z,1716926488.051 [CBIT](DEBUG): Initializing the watchdog timer.
2024-05-28T20:01:28.059Z,1716926488.059 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8801
2024-05-28T20:01:28.071Z,1716926488.071 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8802
2024-05-28T20:01:28.073Z,1716926488.073 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-05-28T20:01:28.075Z,1716926488.075 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-05-28T20:01:28.076Z,1716926488.076 [CBIT](DEBUG): Initializing heartbeat.
2024-05-28T20:01:28.085Z,1716926488.085 [Onboard ThreadHandler](INFO): Handler Thread ID is 8803
2024-05-28T20:01:28.101Z,1716926488.101 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8804
2024-05-28T20:01:28.120Z,1716926488.120 [DAT ThreadHandler](INFO): Handler Thread ID is 8805
2024-05-28T20:01:28.120Z,1716926488.120 [DAT](INFO): Powering up
2024-05-28T20:01:28.120Z,1716926488.120 [DAT](DEBUG): Initializing DAT.
2024-05-28T20:01:28.124Z,1716926488.124 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8807
2024-05-28T20:01:28.124Z,1716926488.124 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-05-28T20:01:28.128Z,1716926488.128 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8809
2024-05-28T20:01:28.131Z,1716926488.131 [WetLabsBB2FL](INFO): Powering up
2024-05-28T20:01:28.132Z,1716926488.132 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 8811
2024-05-28T20:01:28.135Z,1716926488.135 [WetLabsUBAT](INFO): Powering up
2024-05-28T20:01:28.136Z,1716926488.136 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8813
2024-05-28T20:01:28.144Z,1716926488.144 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-05-28T20:01:28.144Z,1716926488.144 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-05-28T20:01:28.144Z,1716926488.144 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-05-28T20:01:28.144Z,1716926488.144 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-05-28T20:01:28.145Z,1716926488.145 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-05-28T20:01:28.145Z,1716926488.145 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-05-28T20:01:28.145Z,1716926488.145 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-05-28T20:01:28.145Z,1716926488.145 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-05-28T20:01:28.147Z,1716926488.147 [CBIT](DEBUG): Deactivating GF circuits.
2024-05-28T20:01:28.147Z,1716926488.147 [CBIT](DEBUG): Deactivating emergency mode.
2024-05-28T20:01:28.186Z,1716926488.186 [CBIT](DEBUG): Backplane powered.
2024-05-28T20:01:28.191Z,1716926488.191 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-05-28T20:01:28.192Z,1716926488.192 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl
2024-05-28T20:01:28.192Z,1716926488.192 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl
2024-05-28T20:01:28.195Z,1716926488.195 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl
2024-05-28T20:01:28.210Z,1716926488.210 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="StartupUnder" />

2024-05-28T20:01:28.211Z,1716926488.211 [MissionManager](ERROR): Top node has no child nodes: Mission
2024-05-28T20:01:28.211Z,1716926488.211 [MissionManager](ERROR): Could not load startup mission.
2024-05-28T20:01:28.211Z,1716926488.211 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl
2024-05-28T20:01:28.211Z,1716926488.211 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl
2024-05-28T20:01:28.218Z,1716926488.218 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl
2024-05-28T20:01:28.503Z,1716926488.503 [Radio_Surface](INFO): Powering up
2024-05-28T20:01:28.749Z,1716926488.749 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/DefaultWithUndock.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="DefaultWithUndock">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="DetachTimeout">
        <Units:minute />
        <Value>30</Value>
    </DefineArg>
    <DefineArg Name="TransponderCode">
        <Dock:Dock.transponderCode />
    </DefineArg>
    <DefineArg Name="TrackingUpdatePeriod">
        <Units:second />
        <Value>2.5</Value>
    </DefineArg>
    <DefineArg Name="NumberOfPings">
        <Units:count />
        <Value>1</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>30</Value>
        </Setting>
    </Guidance:Wait>
    <Aggregate Id="LeaveDock">
        <Sequence />
        <Timeout Duration="DetachTimeout">
            <Syslog Severity="Important">
                Timed out while trying to detach from dock at current depth of 
                <Universal:depth />
                <Units:meter />
                .
            </Syslog>
        </Timeout>
        <Estimation:TrackAcousticContact>
            <Parallel />
            <Setting>
                <Estimation:TrackAcousticContact.contactLabelSetting />
                <Arg Name="TransponderCode" />
            </Setting>
            <Setting>
                <Estimation:TrackAcousticContact.numberOfSamplesSetting />
                <Arg Name="NumberOfPings" />
            </Setting>
            <Setting>
                <Estimation:TrackAcousticContact.updatePeriodSetting />
                <Arg Name="TrackingUpdatePeriod" />
            </Setting>
        </Estimation:TrackAcousticContact>
        <Dock:Undock>
            <Sequence />
        </Dock:Undock>
    </Aggregate>
    <Aggregate Id="SurfaceDefault">
        <Sequence />
        <Guidance:GoToSurface>
            <Progression />
        </Guidance:GoToSurface>
        <Aggregate Id="CheckIn">
            <Sequence Repeat="288" />
            <ReadDatum Id="Read_GPS">
                <Timeout Duration="P5M" />
                <Universal:time_fix />
            </ReadDatum>
            <ReadDatum Id="Read_Iridium">
                <Timeout Duration="P2H">
                    <Guidance:Execute>
                        <Sequence />
                        <Setting>
                            <Guidance:Execute.command />
                            <String>Burn on</String>
                        </Setting>
                    </Guidance:Execute>
                    <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
                </Timeout>
                <Universal:platform_communications />
            </ReadDatum>
            <Guidance:Wait>
                <Sequence />
                <Setting>
                    <Guidance:Wait.duration />
                    <Units:minute />
                    <Value>5</Value>
                </Setting>
            </Guidance:Wait>
            <Assign>
                <Sequence />
                <Arg Name="ElapsedSinceDefaultStarted" />
                <Elapsed>
                    <Universal:mission_started />
                </Elapsed>
            </Assign>
            <Syslog Severity="Important">
                Default mission has been running for 
                <Arg Name="ElapsedSinceDefaultStarted" />
                <Units:minute />
            </Syslog>
        </Aggregate>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2024-05-28T20:01:28.772Z,1716926488.772 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2024-05-28T20:01:28.788Z,1716926488.788 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min
2024-05-28T20:01:28.792Z,1716926488.792 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 201 count
2024-05-28T20:01:28.812Z,1716926488.812 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 2.500000 s
2024-05-28T20:01:28.816Z,1716926488.816 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2024-05-28T20:01:28.827Z,1716926488.827 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2024-05-28T20:01:28.969Z,1716926488.969 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2024-05-28T20:01:28.970Z,1716926488.970 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2024-05-28T20:01:29.025Z,1716926489.025 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-05-28T20:01:29.066Z,1716926489.066 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-05-28T20:01:29.068Z,1716926489.068 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-05-28T20:01:29.106Z,1716926489.106 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2024-05-28T20:01:29.110Z,1716926489.110 [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-05-28T20:01:29.132Z,1716926489.132 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,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-05-28T20:01:29.146Z,1716926489.146 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-05-28T20:01:29.227Z,1716926489.227 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1650.297119 decibar, 0.000000 m
2024-05-28T20:01:29.229Z,1716926489.229 [Power24vConverter](INFO): Powering up.
2024-05-28T20:01:29.229Z,1716926489.229 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2024-05-28T20:01:29.245Z,1716926489.245 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-05-28T20:01:29.288Z,1716926489.288 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-05-28T20:01:29.295Z,1716926489.295 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-05-28T20:01:29.296Z,1716926489.296 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-05-28T20:01:29.303Z,1716926489.303 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-05-28T20:01:29.304Z,1716926489.304 [DockingStepper](INFO): Start
2024-05-28T20:01:29.304Z,1716926489.304 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-05-28T20:01:29.311Z,1716926489.311 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-05-28T20:01:29.312Z,1716926489.312 [MassServo](DEBUG): Initializing EZServoServo.
2024-05-28T20:01:29.319Z,1716926489.319 [MassServo](DEBUG): Initializing MassServo.
2024-05-28T20:01:29.320Z,1716926489.320 [RudderServo](DEBUG): Initializing EZServoServo.
2024-05-28T20:01:29.327Z,1716926489.327 [RudderServo](DEBUG): Initializing RudderServo.
2024-05-28T20:01:29.328Z,1716926489.328 [ThrusterServo](DEBUG): Initializing EZServoServo.
2024-05-28T20:01:29.335Z,1716926489.335 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2024-05-28T20:01:29.603Z,1716926489.603 [DockingStepper](INFO): Starting
2024-05-28T20:01:29.966Z,1716926489.966 [DockingStepper](INFO): Starting
2024-05-28T20:01:30.495Z,1716926490.495 [DockingStepper](INFO): Starting
2024-05-28T20:01:30.543Z,1716926490.543 [DockingStepper](INFO): Init string: /1z14896aE13561N3m50
2024-05-28T20:01:30.743Z,1716926490.743 [WetLabsBB2FL](INFO): Powering down
2024-05-28T20:01:30.798Z,1716926490.798 [DockingStepper](INFO): Starting
2024-05-28T20:01:31.218Z,1716926491.218 [DockingStepper](INFO): Starting
2024-05-28T20:01:31.600Z,1716926491.600 [DockingStepper](INFO): Starting
2024-05-28T20:01:32.067Z,1716926492.067 [DockingStepper](INFO): Starting
2024-05-28T20:01:32.091Z,1716926492.091 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-05-28T20:01:32.526Z,1716926492.526 [DockingStepper](INFO): Starting
2024-05-28T20:01:33.251Z,1716926493.251 [DockingStepper](INFO): Pause
2024-05-28T20:01:33.840Z,1716926493.840 [CommandExec](IMPORTANT): got command docking arm
2024-05-28T20:01:33.841Z,1716926493.841 [CommandExec](IMPORTANT): Switching docking moudle to ARM.
2024-05-28T20:01:34.468Z,1716926494.468 [DockingStepper](INFO): Resume
2024-05-28T20:01:34.883Z,1716926494.883 [DockingStepper](INFO): Resuming
2024-05-28T20:01:35.155Z,1716926495.155 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-05-28T20:01:35.323Z,1716926495.323 [DockingStepper](INFO): Start
2024-05-28T20:01:35.324Z,1716926495.324 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-05-28T20:01:35.447Z,1716926495.447 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-05-28T20:01:35.688Z,1716926495.688 [DockingStepper](INFO): Starting
2024-05-28T20:01:36.153Z,1716926496.153 [DockingStepper](INFO): Starting
2024-05-28T20:01:36.520Z,1716926496.520 [DockingStepper](INFO): Starting
2024-05-28T20:01:36.568Z,1716926496.568 [DockingStepper](INFO): Init string: /1z14895aE13561N3m50
2024-05-28T20:01:36.936Z,1716926496.936 [DockingStepper](INFO): Starting
2024-05-28T20:01:37.399Z,1716926497.399 [WetLabsUBAT](INFO): Powering down
2024-05-28T20:01:37.663Z,1716926497.663 [BuoyancyServo](ERROR): getPosition uart error serial timeout
2024-05-28T20:01:37.663Z,1716926497.663 [BuoyancyServo](FAULT): Buoyancy getPosition uart error.serial timeout
2024-05-28T20:01:37.663Z,1716926497.663 [BuoyancyServo] Communications Fault, FailCount= 1
2024-05-28T20:01:37.663Z,1716926497.663 [BuoyancyServo](ERROR): Communications Fault
2024-05-28T20:01:37.664Z,1716926497.664 [DockingStepper](INFO): Starting
2024-05-28T20:01:37.696Z,1716926497.696 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2024-05-28T20:01:37.774Z,1716926497.774 [DockingStepper](INFO): Starting
2024-05-28T20:01:37.800Z,1716926497.800 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-05-28T20:01:38.173Z,1716926498.173 [DockingStepper](INFO): Starting
2024-05-28T20:01:38.579Z,1716926498.579 [DockingStepper](INFO): Changing to mode: 2
2024-05-28T20:01:38.580Z,1716926498.580 [DockingStepper](INFO): Armed mode.
2024-05-28T20:01:38.639Z,1716926498.639 [DockingStepper](INFO): Commanding negative:45363
2024-05-28T20:01:39.368Z,1716926499.368 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-05-28T20:01:39.368Z,1716926499.368 [BuoyancyServo](INFO): Powering down
2024-05-28T20:01:39.803Z,1716926499.803 [BuoyancyServo](FAULT): LCB fault:  Current Limiter Activated.
2024-05-28T20:01:39.803Z,1716926499.803 [BuoyancyServo] Hardware Fault, FailCount= 1
2024-05-28T20:01:39.803Z,1716926499.803 [BuoyancyServo](ERROR): Hardware Fault
2024-05-28T20:01:40.618Z,1716926500.618 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2024-05-28T20:01:40.618Z,1716926500.618 [BuoyancyServo] No Fault, FailCount= 1
2024-05-28T20:01:40.643Z,1716926500.643 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-05-28T20:01:40.647Z,1716926500.647 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-05-28T20:01:40.971Z,1716926500.971 [DAT](INFO): DAT read: 

2024-05-28T20:01:40.973Z,1716926500.973 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2024-05-28T20:01:42.738Z,1716926502.738 [DAT](INFO): DAT read: MF Frequency Band

2024-05-28T20:01:42.739Z,1716926502.739 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2

2024-05-28T20:01:42.739Z,1716926502.739 [DAT](INFO): DAT read: May 24 2024 20:54:38 

2024-05-28T20:01:42.841Z,1716926502.841 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004800
2024-05-28T20:01:43.744Z,1716926503.744 [DAT](INFO): DAT read: Features enabled [Bearing]

2024-05-28T20:01:43.745Z,1716926503.745 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2024-05-28T20:01:43.745Z,1716926503.745 [DAT](INFO): commRate: 800
2024-05-28T20:01:45.811Z,1716926505.811 [DAT](INFO): entering command mode
2024-05-28T20:01:46.011Z,1716926506.011 [DAT](INFO): DAT read: 

2024-05-28T20:01:46.012Z,1716926506.012 [DAT](INFO): DAT read: user:1>

2024-05-28T20:01:46.013Z,1716926506.013 [DAT](INFO): setting verbose to 3
2024-05-28T20:01:46.264Z,1716926506.264 [DAT](INFO): DAT read: user:1>

2024-05-28T20:01:46.265Z,1716926506.265 [DAT](INFO): DAT read: Verbose         | 3           

2024-05-28T20:01:46.265Z,1716926506.265 [DAT](INFO): set verbose to 3
2024-05-28T20:01:46.265Z,1716926506.265 [DAT](INFO): setting DatVerbose to 27440
2024-05-28T20:01:46.515Z,1716926506.515 [DAT](INFO): DAT read: user:2>

2024-05-28T20:01:46.516Z,1716926506.516 [DAT](INFO): DAT read: DatVerbose      | 27440       

2024-05-28T20:01:46.517Z,1716926506.517 [DAT](INFO): set DatVerbose to 27440
2024-05-28T20:01:46.517Z,1716926506.517 [DAT](INFO): setting transmit power to 8
2024-05-28T20:01:46.769Z,1716926506.769 [DAT](INFO): DAT read: user:3>

2024-05-28T20:01:46.773Z,1716926506.773 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2024-05-28T20:01:46.774Z,1716926506.774 [DAT](INFO): set transmit power to 8
2024-05-28T20:01:46.775Z,1716926506.775 [DAT](INFO): setting local address to 10
2024-05-28T20:01:47.019Z,1716926507.019 [DAT](INFO): DAT read: user:4>

2024-05-28T20:01:47.020Z,1716926507.020 [DAT](INFO): DAT read: LocalAddr       | 10          

2024-05-28T20:01:47.021Z,1716926507.021 [DAT](INFO): set local address to 10
2024-05-28T20:01:47.022Z,1716926507.022 [DAT](INFO): Setting time to: 20:1:47 And date to:5/28/2024
2024-05-28T20:01:47.272Z,1716926507.272 [DAT](INFO): DAT read: user:5>

2024-05-28T20:01:47.273Z,1716926507.273 [DAT](INFO): DAT read: Tue May 28, 2024  20:01:47  

2024-05-28T20:01:47.273Z,1716926507.273 [DAT](INFO): Local DAT time set to Tue May 28, 2024  20:01:47  

2024-05-28T20:01:56.832Z,1716926516.832 [NAL9602](INFO): Powering up NAL9602
2024-05-28T20:02:07.717Z,1716926527.717 [NAL9602](INFO): NAL9602 initialized
2024-05-28T20:02:36.800Z,1716926556.800 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-28T20:02:51.748Z,1716926571.748 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-28T20:03:07.100Z,1716926587.100 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-28T20:03:22.458Z,1716926602.458 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-28T20:03:38.615Z,1716926618.615 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-28T20:03:47.646Z,1716926627.646 [SBIT](IMPORTANT): Beginning Startup BIT
2024-05-28T20:03:47.650Z,1716926627.650 [CBIT](IMPORTANT): Beginning ground fault scan
2024-05-28T20:03:53.561Z,1716926633.561 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-05-28T20:03:58.614Z,1716926638.614 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.017903
CHAN A1 (24V): 0.004223
CHAN A2 (12V): -0.005305
CHAN A3 (5V): -0.001630
CHAN B0 (3.3V): 0.000211
CHAN B1 (3.15aV): -0.000355
CHAN B2 (3.15bV): 0.000657
CHAN B3 (GND): 0.001436
OPEN: 0.004732
Full Scale: +/- 1 mA
2024-05-28T20:04:28.407Z,1716926668.407 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-05-28T20:04:28.407Z,1716926668.407 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-28T20:04:28.577Z,1716926668.577 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-28T20:04:29.005Z,1716926669.005 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-28T20:04:29.005Z,1716926669.005 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-05-28T20:04:41.493Z,1716926681.493 [SBIT](IMPORTANT): SBIT PASSED
2024-05-28T20:04:41.493Z,1716926681.493 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-05-28T20:04:41.494Z,1716926681.494 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2024-05-28T20:04:41.494Z,1716926681.494 [SBIT](IMPORTANT): Docked.detachTimeout=8 hour;

2024-05-28T20:04:41.494Z,1716926681.494 [SBIT](IMPORTANT): DockingStepper.closedValue=16500 count;

2024-05-28T20:04:41.494Z,1716926681.494 [SBIT](IMPORTANT): DockingStepper.loadAtStartup=1 bool;

2024-05-28T20:04:41.494Z,1716926681.494 [SBIT](IMPORTANT): DockingStepper.openValue=7500 count;

2024-05-28T20:04:41.495Z,1716926681.495 [SBIT](IMPORTANT): DockingStepper.simulateHardware=0 bool;

2024-05-28T20:04:41.495Z,1716926681.495 [SBIT](IMPORTANT): DockingStepper.slideValue=12000 count;

2024-05-28T20:04:41.495Z,1716926681.495 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;

2024-05-28T20:04:41.495Z,1716926681.495 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool;

2024-05-28T20:04:41.495Z,1716926681.495 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=300 cubic_centimeter;

2024-05-28T20:04:41.495Z,1716926681.495 [SBIT](IMPORTANT): VerticalControl.massDefault=30 millimeter;

2024-05-28T20:04:41.773Z,1716926681.773 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2024-05-28T20:04:41.773Z,1716926681.773 [DefaultWithUndock] Running Loop=1
2024-05-28T20:04:41.774Z,1716926681.774 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2024-05-28T20:04:41.774Z,1716926681.774 [DefaultWithUndock:A.Wait] Running Loop=1
2024-05-28T20:04:41.774Z,1716926681.774 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2024-05-28T20:05:12.065Z,1716926712.065 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2024-05-28T20:05:12.065Z,1716926712.065 [DefaultWithUndock:A.Wait] Stopped
2024-05-28T20:05:12.065Z,1716926712.065 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2024-05-28T20:05:12.065Z,1716926712.065 [DefaultWithUndock:LeaveDock] Running Loop=1
2024-05-28T20:05:12.065Z,1716926712.065 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2024-05-28T20:05:12.065Z,1716926712.065 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2024-05-28T20:05:12.065Z,1716926712.065 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2024-05-28T20:05:12.066Z,1716926712.066 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2024-05-28T20:05:12.066Z,1716926712.066 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2024-05-28T20:05:12.066Z,1716926712.066 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2024-05-28T20:05:12.487Z,1716926712.487 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2024-05-28T20:05:12.493Z,1716926712.493 [DockingStepper](INFO): Changing to mode: 3
2024-05-28T20:05:12.494Z,1716926712.494 [DockingStepper](INFO): Detach mode.
2024-05-28T20:05:12.909Z,1716926712.909 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock.
2024-05-28T20:05:14.952Z,1716926714.952 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:14.964Z,1716926714.964 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:14.964Z,1716926714.964 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:14.964Z,1716926714.964 [DAT](INFO): setting remote address to 0
2024-05-28T20:05:15.216Z,1716926715.216 [DAT](INFO): DAT read: user:6>

2024-05-28T20:05:15.217Z,1716926715.217 [DAT](INFO): DAT read: RemoteAddr      | 0           

2024-05-28T20:05:15.217Z,1716926715.217 [DAT](INFO): set remote address to 0
2024-05-28T20:05:15.217Z,1716926715.217 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:15.218Z,1716926715.218 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:15.218Z,1716926715.218 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:15.468Z,1716926715.468 [DAT](INFO): DAT read: user:7>

2024-05-28T20:05:15.469Z,1716926715.469 [DAT](INFO): DAT read: Tx time:20:05:15.5640

2024-05-28T20:05:15.469Z,1716926715.469 [DAT](INFO): Ping request sent.
2024-05-28T20:05:15.469Z,1716926715.469 [DAT](INFO): transmitted an acoustic signal
2024-05-28T20:05:15.469Z,1716926715.469 [DAT](INFO): publishing transmit ping time
2024-05-28T20:05:15.470Z,1716926715.470 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000910
2024-05-28T20:05:15.720Z,1716926715.720 [DAT](INFO): DAT read: TxSync time:20:05:15.5632

2024-05-28T20:05:15.721Z,1716926715.721 [DAT](INFO): unknown deviceResponse_: TxSync time:20:05:15.5632

2024-05-28T20:05:15.721Z,1716926715.721 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251999
2024-05-28T20:05:15.972Z,1716926715.972 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502831
2024-05-28T20:05:16.225Z,1716926716.225 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755397
2024-05-28T20:05:16.476Z,1716926716.476 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006797
2024-05-28T20:05:16.729Z,1716926716.729 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259097
2024-05-28T20:05:16.980Z,1716926716.980 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510781
2024-05-28T20:05:17.232Z,1716926717.232 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762813
2024-05-28T20:05:17.484Z,1716926717.484 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015154
2024-05-28T20:05:17.736Z,1716926717.736 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266836
2024-05-28T20:05:17.744Z,1716926717.744 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:17.988Z,1716926717.988 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518824
2024-05-28T20:05:18.240Z,1716926718.240 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770855
2024-05-28T20:05:18.492Z,1716926718.492 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022874
2024-05-28T20:05:18.744Z,1716926718.744 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275072
2024-05-28T20:05:18.996Z,1716926718.996 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.527299
2024-05-28T20:05:19.248Z,1716926719.248 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778850
2024-05-28T20:05:19.500Z,1716926719.500 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031313
2024-05-28T20:05:19.752Z,1716926719.752 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.283046
2024-05-28T20:05:20.004Z,1716926720.004 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534881
2024-05-28T20:05:20.256Z,1716926720.256 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786811
2024-05-28T20:05:20.508Z,1716926720.508 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.039196
2024-05-28T20:05:20.605Z,1716926720.605 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:20.761Z,1716926720.761 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.291363
2024-05-28T20:05:21.012Z,1716926721.012 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.543070
2024-05-28T20:05:21.264Z,1716926721.264 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794823
2024-05-28T20:05:21.517Z,1716926721.517 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.047535
2024-05-28T20:05:21.768Z,1716926721.768 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298713
2024-05-28T20:05:22.022Z,1716926722.022 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.552714
2024-05-28T20:05:22.272Z,1716926722.272 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802869
2024-05-28T20:05:22.527Z,1716926722.527 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.057401
2024-05-28T20:05:22.776Z,1716926722.776 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306831
2024-05-28T20:05:23.028Z,1716926723.028 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.559246
2024-05-28T20:05:23.280Z,1716926723.280 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810808
2024-05-28T20:05:23.394Z,1716926723.394 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:23.537Z,1716926723.537 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.067515
2024-05-28T20:05:23.784Z,1716926723.784 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314879
2024-05-28T20:05:24.036Z,1716926724.036 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566847
2024-05-28T20:05:24.289Z,1716926724.289 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.819561
2024-05-28T20:05:24.540Z,1716926724.540 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070870
2024-05-28T20:05:24.792Z,1716926724.792 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322777
2024-05-28T20:05:25.045Z,1716926725.045 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.575336
2024-05-28T20:05:25.296Z,1716926725.296 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826908
2024-05-28T20:05:25.548Z,1716926725.548 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:25.549Z,1716926725.549 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:25.549Z,1716926725.549 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:25.800Z,1716926725.800 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:25.800Z,1716926725.800 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:25.800Z,1716926725.800 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:26.052Z,1716926726.052 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:26.052Z,1716926726.052 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:26.052Z,1716926726.052 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:26.210Z,1716926726.210 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:26.304Z,1716926726.304 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:26.304Z,1716926726.304 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:26.304Z,1716926726.304 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:26.556Z,1716926726.556 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:26.556Z,1716926726.556 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:26.556Z,1716926726.556 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:26.808Z,1716926726.808 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:26.808Z,1716926726.808 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:26.808Z,1716926726.808 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:27.061Z,1716926727.061 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:27.061Z,1716926727.061 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:27.062Z,1716926727.062 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:27.312Z,1716926727.312 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:27.312Z,1716926727.312 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:27.312Z,1716926727.312 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:27.565Z,1716926727.565 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:27.565Z,1716926727.565 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:27.566Z,1716926727.566 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:27.816Z,1716926727.816 [DAT](INFO): DAT read: Response Not Received

2024-05-28T20:05:27.816Z,1716926727.816 [DAT](INFO): response not received
2024-05-28T20:05:27.817Z,1716926727.817 [DAT](INFO): DAT read: user:8>

2024-05-28T20:05:27.817Z,1716926727.817 [DAT](INFO): DAT read: Tx time:20:05:18.0144

2024-05-28T20:05:27.818Z,1716926727.818 [DAT](INFO): Ping request sent.
2024-05-28T20:05:27.818Z,1716926727.818 [DAT](INFO): transmitted an acoustic signal
2024-05-28T20:05:27.818Z,1716926727.818 [DAT](INFO): publishing transmit ping time
2024-05-28T20:05:27.819Z,1716926727.819 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000695
2024-05-28T20:05:28.069Z,1716926728.069 [DAT](INFO): DAT read: TxSync time:20:05:18.0136

2024-05-28T20:05:28.069Z,1716926728.069 [DAT](INFO): unknown deviceResponse_: TxSync time:20:05:18.0136

2024-05-28T20:05:28.070Z,1716926728.070 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251873
2024-05-28T20:05:28.320Z,1716926728.320 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502495
2024-05-28T20:05:28.572Z,1716926728.572 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754175
2024-05-28T20:05:28.717Z,1716926728.717 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:28.824Z,1716926728.824 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006171
2024-05-28T20:05:29.076Z,1716926729.076 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258146
2024-05-28T20:05:29.328Z,1716926729.328 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510126
2024-05-28T20:05:29.580Z,1716926729.580 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762150
2024-05-28T20:05:29.832Z,1716926729.832 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014192
2024-05-28T20:05:30.084Z,1716926730.084 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266108
2024-05-28T20:05:30.337Z,1716926730.337 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518979
2024-05-28T20:05:30.590Z,1716926730.590 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771800
2024-05-28T20:05:30.840Z,1716926730.840 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022182
2024-05-28T20:05:31.092Z,1716926731.092 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274238
2024-05-28T20:05:31.344Z,1716926731.344 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526214
2024-05-28T20:05:31.478Z,1716926731.478 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:31.596Z,1716926731.596 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778539
2024-05-28T20:05:31.848Z,1716926731.848 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030424
2024-05-28T20:05:32.101Z,1716926732.101 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282522
2024-05-28T20:05:32.352Z,1716926732.352 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534231
2024-05-28T20:05:32.606Z,1716926732.606 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.788083
2024-05-28T20:05:32.857Z,1716926732.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.039037
2024-05-28T20:05:33.108Z,1716926733.108 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290188
2024-05-28T20:05:33.360Z,1716926733.360 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542153
2024-05-28T20:05:33.613Z,1716926733.613 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794774
2024-05-28T20:05:33.864Z,1716926733.864 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046069
2024-05-28T20:05:34.116Z,1716926734.116 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298189
2024-05-28T20:05:34.313Z,1716926734.313 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:34.369Z,1716926734.369 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550873
2024-05-28T20:05:34.622Z,1716926734.622 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.803816
2024-05-28T20:05:34.872Z,1716926734.872 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054498
2024-05-28T20:05:35.126Z,1716926735.126 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.308218
2024-05-28T20:05:35.376Z,1716926735.376 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558123
2024-05-28T20:05:35.632Z,1716926735.632 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.812943
2024-05-28T20:05:35.880Z,1716926735.880 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062169
2024-05-28T20:05:36.133Z,1716926736.133 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314885
2024-05-28T20:05:36.384Z,1716926736.384 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566492
2024-05-28T20:05:36.638Z,1716926736.638 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.819983
2024-05-28T20:05:36.888Z,1716926736.888 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070484
2024-05-28T20:05:37.142Z,1716926737.142 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.324036
2024-05-28T20:05:37.143Z,1716926737.143 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:37.392Z,1716926737.392 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574172
2024-05-28T20:05:37.644Z,1716926737.644 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826135
2024-05-28T20:05:37.896Z,1716926737.896 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:37.896Z,1716926737.896 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:37.896Z,1716926737.896 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:38.149Z,1716926738.149 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:38.149Z,1716926738.149 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:38.149Z,1716926738.149 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:38.400Z,1716926738.400 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:38.400Z,1716926738.400 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:38.401Z,1716926738.401 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:38.653Z,1716926738.653 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:38.654Z,1716926738.654 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:38.654Z,1716926738.654 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:38.904Z,1716926738.904 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:38.904Z,1716926738.904 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:38.904Z,1716926738.904 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:39.156Z,1716926739.156 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:39.156Z,1716926739.156 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:39.156Z,1716926739.156 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:39.410Z,1716926739.410 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:39.411Z,1716926739.411 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:39.412Z,1716926739.412 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:39.660Z,1716926739.660 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:39.660Z,1716926739.660 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:39.661Z,1716926739.661 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:39.912Z,1716926739.912 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:39.912Z,1716926739.912 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:39.912Z,1716926739.912 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:40.005Z,1716926740.005 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:40.166Z,1716926740.166 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:40.166Z,1716926740.166 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:40.167Z,1716926740.167 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:40.418Z,1716926740.418 [DAT](INFO): DAT read: Response Not Received

2024-05-28T20:05:40.418Z,1716926740.418 [DAT](INFO): response not received
2024-05-28T20:05:40.419Z,1716926740.419 [DAT](INFO): DAT read: usr:9>
Tx time:20:05:30.4645

2024-05-28T20:05:40.419Z,1716926740.419 [DAT](INFO): unknown deviceResponse_: usr:9>
Tx time:20:05:30.4645

2024-05-28T20:05:40.420Z,1716926740.420 [DAT](INFO): DAT read: TxSync time:20:05:30.4637

2024-05-28T20:05:40.420Z,1716926740.420 [DAT](INFO): unknown deviceResponse_: TxSync time:20:05:30.4637

2024-05-28T20:05:40.420Z,1716926740.420 [DAT](ERROR): No response from remote modem.
2024-05-28T20:05:40.421Z,1716926740.421 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:40.421Z,1716926740.421 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:40.421Z,1716926740.421 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:40.668Z,1716926740.668 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:40.668Z,1716926740.668 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:40.668Z,1716926740.668 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:40.920Z,1716926740.920 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:40.920Z,1716926740.920 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:40.920Z,1716926740.920 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:41.172Z,1716926741.172 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:41.172Z,1716926741.172 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:41.172Z,1716926741.172 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:41.424Z,1716926741.424 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:41.424Z,1716926741.424 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:41.424Z,1716926741.424 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:41.678Z,1716926741.678 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:41.678Z,1716926741.678 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:41.678Z,1716926741.678 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:41.928Z,1716926741.928 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:41.928Z,1716926741.928 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:41.928Z,1716926741.928 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:42.181Z,1716926742.181 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:42.181Z,1716926742.181 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:42.181Z,1716926742.181 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:42.432Z,1716926742.432 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:42.432Z,1716926742.432 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:42.432Z,1716926742.432 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:42.684Z,1716926742.684 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:42.684Z,1716926742.684 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:42.684Z,1716926742.684 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:42.853Z,1716926742.853 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:42.936Z,1716926742.936 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:42.936Z,1716926742.936 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:42.936Z,1716926742.936 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:43.188Z,1716926743.188 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:43.188Z,1716926743.188 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:43.188Z,1716926743.188 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:43.440Z,1716926743.440 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:43.440Z,1716926743.440 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:43.440Z,1716926743.440 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:43.692Z,1716926743.692 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:43.692Z,1716926743.692 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:43.693Z,1716926743.693 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:43.944Z,1716926743.944 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:43.944Z,1716926743.944 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:43.944Z,1716926743.944 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:44.197Z,1716926744.197 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:44.197Z,1716926744.197 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:44.197Z,1716926744.197 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:44.448Z,1716926744.448 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:44.448Z,1716926744.448 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:44.448Z,1716926744.448 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:44.700Z,1716926744.700 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:44.700Z,1716926744.700 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:44.700Z,1716926744.700 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:44.952Z,1716926744.952 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:44.952Z,1716926744.952 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:44.953Z,1716926744.953 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:45.204Z,1716926745.204 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:45.204Z,1716926745.204 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:45.204Z,1716926745.204 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:45.456Z,1716926745.456 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:45.456Z,1716926745.456 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:45.456Z,1716926745.456 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:45.696Z,1716926745.696 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:45.708Z,1716926745.708 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:45.708Z,1716926745.708 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:45.708Z,1716926745.708 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:45.960Z,1716926745.960 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:45.960Z,1716926745.960 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:45.960Z,1716926745.960 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:46.212Z,1716926746.212 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:46.212Z,1716926746.212 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:46.212Z,1716926746.212 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:46.465Z,1716926746.465 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:46.466Z,1716926746.466 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:46.466Z,1716926746.466 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:46.716Z,1716926746.716 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:46.716Z,1716926746.716 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:46.716Z,1716926746.716 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:46.968Z,1716926746.968 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:46.968Z,1716926746.968 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:46.968Z,1716926746.968 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:47.220Z,1716926747.220 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:47.220Z,1716926747.220 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:47.220Z,1716926747.220 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:47.472Z,1716926747.472 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:47.472Z,1716926747.472 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:47.472Z,1716926747.472 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:47.724Z,1716926747.724 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:47.724Z,1716926747.724 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:47.725Z,1716926747.725 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:47.976Z,1716926747.976 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:47.976Z,1716926747.976 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:47.976Z,1716926747.976 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:48.229Z,1716926748.229 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:48.229Z,1716926748.229 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:48.229Z,1716926748.229 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:48.480Z,1716926748.480 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:48.480Z,1716926748.480 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:48.480Z,1716926748.480 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:48.489Z,1716926748.489 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:48.732Z,1716926748.732 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:48.732Z,1716926748.732 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:48.732Z,1716926748.732 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:48.984Z,1716926748.984 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:48.984Z,1716926748.984 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:48.984Z,1716926748.984 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:49.236Z,1716926749.236 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:49.236Z,1716926749.236 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:49.236Z,1716926749.236 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:49.488Z,1716926749.488 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:49.488Z,1716926749.488 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:49.489Z,1716926749.489 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:49.740Z,1716926749.740 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:49.740Z,1716926749.740 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:49.740Z,1716926749.740 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:49.992Z,1716926749.992 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:49.992Z,1716926749.992 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:49.992Z,1716926749.992 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:50.244Z,1716926750.244 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:50.244Z,1716926750.244 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:50.244Z,1716926750.244 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:50.496Z,1716926750.496 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:50.496Z,1716926750.496 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:50.496Z,1716926750.496 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:50.748Z,1716926750.748 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:50.748Z,1716926750.748 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:50.748Z,1716926750.748 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:51.003Z,1716926751.003 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:51.004Z,1716926751.004 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:51.004Z,1716926751.004 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:51.252Z,1716926751.252 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:51.252Z,1716926751.252 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:51.252Z,1716926751.252 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:51.318Z,1716926751.318 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:51.504Z,1716926751.504 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:51.504Z,1716926751.504 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:51.505Z,1716926751.505 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:51.758Z,1716926751.758 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:51.758Z,1716926751.758 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:51.758Z,1716926751.758 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:52.008Z,1716926752.008 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:52.008Z,1716926752.008 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:52.008Z,1716926752.008 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:52.261Z,1716926752.261 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:52.261Z,1716926752.261 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:52.261Z,1716926752.261 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:52.512Z,1716926752.512 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:05:52.512Z,1716926752.512 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:05:52.512Z,1716926752.512 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:05:52.764Z,1716926752.764 [DAT](INFO): DAT read: Response Not Received

2024-05-28T20:05:52.764Z,1716926752.764 [DAT](INFO): response not received
2024-05-28T20:05:52.765Z,1716926752.765 [DAT](INFO): DAT read: user:10>

2024-05-28T20:05:52.765Z,1716926752.765 [DAT](INFO): DAT read: Tx time:20:05:42.9146

2024-05-28T20:05:52.766Z,1716926752.766 [DAT](INFO): Ping request sent.
2024-05-28T20:05:52.766Z,1716926752.766 [DAT](INFO): transmitted an acoustic signal
2024-05-28T20:05:52.766Z,1716926752.766 [DAT](INFO): publishing transmit ping time
2024-05-28T20:05:52.767Z,1716926752.767 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000696
2024-05-28T20:05:53.017Z,1716926753.017 [DAT](INFO): DAT read: TxSync time:20:05:42.9138

2024-05-28T20:05:53.017Z,1716926753.017 [DAT](INFO): unknown deviceResponse_: TxSync time:20:05:42.9138

2024-05-28T20:05:53.018Z,1716926753.018 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251880
2024-05-28T20:05:53.268Z,1716926753.268 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502214
2024-05-28T20:05:53.524Z,1716926753.524 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.756886
2024-05-28T20:05:53.772Z,1716926753.772 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006483
2024-05-28T20:05:54.024Z,1716926754.024 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258240
2024-05-28T20:05:54.146Z,1716926754.146 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:54.277Z,1716926754.277 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511412
2024-05-28T20:05:54.528Z,1716926754.528 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762128
2024-05-28T20:05:54.780Z,1716926754.780 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014211
2024-05-28T20:05:55.034Z,1716926755.034 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.268456
2024-05-28T20:05:55.284Z,1716926755.284 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518195
2024-05-28T20:05:55.541Z,1716926755.541 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774120
2024-05-28T20:05:55.788Z,1716926755.788 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022248
2024-05-28T20:05:56.040Z,1716926756.040 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274446
2024-05-28T20:05:56.293Z,1716926756.293 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526763
2024-05-28T20:05:56.544Z,1716926756.544 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778278
2024-05-28T20:05:56.796Z,1716926756.796 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030161
2024-05-28T20:05:56.966Z,1716926756.966 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:57.048Z,1716926757.048 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282259
2024-05-28T20:05:57.300Z,1716926757.300 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534173
2024-05-28T20:05:57.552Z,1716926757.552 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786579
2024-05-28T20:05:57.805Z,1716926757.805 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038532
2024-05-28T20:05:58.056Z,1716926758.056 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290529
2024-05-28T20:05:58.308Z,1716926758.308 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542230
2024-05-28T20:05:58.560Z,1716926758.560 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794360
2024-05-28T20:05:58.812Z,1716926758.812 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046133
2024-05-28T20:05:59.064Z,1716926759.064 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298272
2024-05-28T20:05:59.316Z,1716926759.316 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550243
2024-05-28T20:05:59.568Z,1716926759.568 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802230
2024-05-28T20:05:59.816Z,1716926759.816 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:05:59.820Z,1716926759.820 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054546
2024-05-28T20:06:00.072Z,1716926760.072 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306205
2024-05-28T20:06:00.324Z,1716926760.324 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558571
2024-05-28T20:06:00.578Z,1716926760.578 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.811713
2024-05-28T20:06:00.829Z,1716926760.829 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062895
2024-05-28T20:06:01.080Z,1716926761.080 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314344
2024-05-28T20:06:01.332Z,1716926761.332 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566154
2024-05-28T20:06:01.586Z,1716926761.586 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.820522
2024-05-28T20:06:01.836Z,1716926761.836 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070234
2024-05-28T20:06:02.088Z,1716926762.088 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322432
2024-05-28T20:06:02.344Z,1716926762.344 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.577492
2024-05-28T20:06:02.592Z,1716926762.592 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826104
2024-05-28T20:06:02.668Z,1716926762.668 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:02.844Z,1716926762.844 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:02.845Z,1716926762.845 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:02.845Z,1716926762.845 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:03.096Z,1716926763.096 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:03.096Z,1716926763.096 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:03.097Z,1716926763.097 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:03.348Z,1716926763.348 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:03.348Z,1716926763.348 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:03.348Z,1716926763.348 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:03.602Z,1716926763.602 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:03.603Z,1716926763.603 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:03.604Z,1716926763.604 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:03.857Z,1716926763.857 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:03.857Z,1716926763.857 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:03.857Z,1716926763.857 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:04.108Z,1716926764.108 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:04.108Z,1716926764.108 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:04.108Z,1716926764.108 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:04.360Z,1716926764.360 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:04.360Z,1716926764.360 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:04.360Z,1716926764.360 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:04.613Z,1716926764.613 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:04.614Z,1716926764.614 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:04.615Z,1716926764.615 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:04.865Z,1716926764.865 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:04.865Z,1716926764.865 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:04.865Z,1716926764.865 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:05.116Z,1716926765.116 [DAT](INFO): DAT read: Response Not Received

2024-05-28T20:06:05.117Z,1716926765.117 [DAT](INFO): response not received
2024-05-28T20:06:05.117Z,1716926765.117 [DAT](INFO): DAT read: user:11>

2024-05-28T20:06:05.118Z,1716926765.118 [DAT](ERROR): No response from remote modem.
2024-05-28T20:06:05.118Z,1716926765.118 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:05.118Z,1716926765.118 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:05.118Z,1716926765.118 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:05.368Z,1716926765.368 [DAT](INFO): DAT read: Tx time:20:05:55.3648

2024-05-28T20:06:05.368Z,1716926765.368 [DAT](INFO): Ping request sent.
2024-05-28T20:06:05.369Z,1716926765.369 [DAT](INFO): transmitted an acoustic signal
2024-05-28T20:06:05.369Z,1716926765.369 [DAT](INFO): DAT read: TxSync time:20:05:55.3640

2024-05-28T20:06:05.370Z,1716926765.370 [DAT](INFO): unknown deviceResponse_: TxSync time:20:05:55.3640

2024-05-28T20:06:05.370Z,1716926765.370 [DAT](INFO): publishing transmit ping time
2024-05-28T20:06:05.370Z,1716926765.370 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001691
2024-05-28T20:06:05.478Z,1716926765.478 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:05.621Z,1716926765.621 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252175
2024-05-28T20:06:05.872Z,1716926765.872 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503795
2024-05-28T20:06:06.124Z,1716926766.124 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755438
2024-05-28T20:06:06.377Z,1716926766.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.008059
2024-05-28T20:06:06.630Z,1716926766.630 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.260908
2024-05-28T20:06:06.881Z,1716926766.881 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511954
2024-05-28T20:06:07.132Z,1716926767.132 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763416
2024-05-28T20:06:07.384Z,1716926767.384 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015400
2024-05-28T20:06:07.640Z,1716926767.640 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.271730
2024-05-28T20:06:07.888Z,1716926767.888 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519631
2024-05-28T20:06:08.140Z,1716926768.140 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771393
2024-05-28T20:06:08.323Z,1716926768.323 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:08.393Z,1716926768.393 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024102
2024-05-28T20:06:08.647Z,1716926768.647 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.277595
2024-05-28T20:06:08.896Z,1716926768.896 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.527323
2024-05-28T20:06:09.148Z,1716926769.148 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779615
2024-05-28T20:06:09.400Z,1716926769.400 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031407
2024-05-28T20:06:09.652Z,1716926769.652 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.283609
2024-05-28T20:06:09.906Z,1716926769.906 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.537670
2024-05-28T20:06:10.156Z,1716926770.156 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.787422
2024-05-28T20:06:10.408Z,1716926770.408 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.039769
2024-05-28T20:06:10.663Z,1716926770.663 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.293804
2024-05-28T20:06:10.912Z,1716926770.912 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.543431
2024-05-28T20:06:11.090Z,1716926771.090 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:11.164Z,1716926771.164 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.795440
2024-05-28T20:06:11.416Z,1716926771.416 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.047362
2024-05-28T20:06:11.668Z,1716926771.668 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.299511
2024-05-28T20:06:11.920Z,1716926771.920 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.551446
2024-05-28T20:06:12.172Z,1716926772.172 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.803380
2024-05-28T20:06:12.425Z,1716926772.425 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.056302
2024-05-28T20:06:12.676Z,1716926772.676 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.307355
2024-05-28T20:06:12.928Z,1716926772.928 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.559439
2024-05-28T20:06:13.181Z,1716926773.181 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.811904
2024-05-28T20:06:13.432Z,1716926773.432 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.063509
2024-05-28T20:06:13.684Z,1716926773.684 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.315357
2024-05-28T20:06:13.921Z,1716926773.921 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:13.936Z,1716926773.936 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.567509
2024-05-28T20:06:14.188Z,1716926774.188 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.819343
2024-05-28T20:06:14.441Z,1716926774.441 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.072184
2024-05-28T20:06:14.692Z,1716926774.692 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.323501
2024-05-28T20:06:14.945Z,1716926774.945 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.576330
2024-05-28T20:06:15.196Z,1716926775.196 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.827506
2024-05-28T20:06:15.448Z,1716926775.448 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:15.448Z,1716926775.448 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:15.448Z,1716926775.448 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:15.700Z,1716926775.700 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:15.700Z,1716926775.700 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:15.701Z,1716926775.701 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:15.952Z,1716926775.952 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:15.952Z,1716926775.952 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:15.952Z,1716926775.952 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:16.204Z,1716926776.204 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:16.204Z,1716926776.204 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:16.204Z,1716926776.204 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:16.456Z,1716926776.456 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:16.456Z,1716926776.456 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:16.456Z,1716926776.456 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:16.708Z,1716926776.708 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:16.708Z,1716926776.708 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:16.709Z,1716926776.709 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:16.845Z,1716926776.845 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:16.960Z,1716926776.960 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:16.960Z,1716926776.960 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:16.960Z,1716926776.960 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:17.212Z,1716926777.212 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:17.212Z,1716926777.212 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:17.212Z,1716926777.212 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:17.464Z,1716926777.464 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:17.464Z,1716926777.464 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:17.464Z,1716926777.464 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:17.717Z,1716926777.717 [DAT](INFO): DAT read: Response Not Received

2024-05-28T20:06:17.717Z,1716926777.717 [DAT](INFO): response not received
2024-05-28T20:06:17.718Z,1716926777.718 [DAT](INFO): DAT read: user:12>

2024-05-28T20:06:17.719Z,1716926777.719 [DAT](INFO): DAT read: Tx time:20:06:07.8149

2024-05-28T20:06:17.719Z,1716926777.719 [DAT](INFO): Ping request sent.
2024-05-28T20:06:17.719Z,1716926777.719 [DAT](INFO): transmitted an acoustic signal
2024-05-28T20:06:17.719Z,1716926777.719 [DAT](INFO): publishing transmit ping time
2024-05-28T20:06:17.720Z,1716926777.720 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000969
2024-05-28T20:06:17.968Z,1716926777.968 [DAT](INFO): DAT read: TxSync time:20:06:07.8141

2024-05-28T20:06:17.969Z,1716926777.969 [DAT](INFO): unknown deviceResponse_: TxSync time:20:06:07.8141

2024-05-28T20:06:17.969Z,1716926777.969 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250058
2024-05-28T20:06:18.221Z,1716926778.221 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501903
2024-05-28T20:06:18.472Z,1716926778.472 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753004
2024-05-28T20:06:18.724Z,1716926778.724 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.004891
2024-05-28T20:06:18.976Z,1716926778.976 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.257239
2024-05-28T20:06:19.228Z,1716926779.228 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509021
2024-05-28T20:06:19.480Z,1716926779.480 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.760853
2024-05-28T20:06:19.577Z,1716926779.577 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:19.732Z,1716926779.732 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013239
2024-05-28T20:06:19.984Z,1716926779.984 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.264921
2024-05-28T20:06:20.236Z,1716926780.236 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.516839
2024-05-28T20:06:20.488Z,1716926780.488 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.769139
2024-05-28T20:06:20.740Z,1716926780.740 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.020927
2024-05-28T20:06:20.992Z,1716926780.992 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273041
2024-05-28T20:06:21.244Z,1716926781.244 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.525242
2024-05-28T20:06:21.496Z,1716926781.496 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.776916
2024-05-28T20:06:21.748Z,1716926781.748 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.028875
2024-05-28T20:06:22.000Z,1716926782.000 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.280987
2024-05-28T20:06:22.252Z,1716926782.252 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.532945
2024-05-28T20:06:22.418Z,1716926782.418 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:22.504Z,1716926782.504 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.785203
2024-05-28T20:06:22.756Z,1716926782.756 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.037076
2024-05-28T20:06:23.009Z,1716926783.009 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.289836
2024-05-28T20:06:23.260Z,1716926783.260 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.540861
2024-05-28T20:06:23.512Z,1716926783.512 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.792848
2024-05-28T20:06:23.765Z,1716926783.765 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.045256
2024-05-28T20:06:24.016Z,1716926784.016 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.296986
2024-05-28T20:06:24.270Z,1716926784.270 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550740
2024-05-28T20:06:24.520Z,1716926784.520 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.800917
2024-05-28T20:06:24.772Z,1716926784.772 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.052833
2024-05-28T20:06:25.028Z,1716926785.028 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.309121
2024-05-28T20:06:25.254Z,1716926785.254 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:25.276Z,1716926785.276 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.557254
2024-05-28T20:06:25.528Z,1716926785.528 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.808826
2024-05-28T20:06:25.781Z,1716926785.781 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.061397
2024-05-28T20:06:26.032Z,1716926786.032 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.312851
2024-05-28T20:06:26.284Z,1716926786.284 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.564848
2024-05-28T20:06:26.536Z,1716926786.536 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.816838
2024-05-28T20:06:26.788Z,1716926786.788 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.068920
2024-05-28T20:06:27.040Z,1716926787.040 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.321074
2024-05-28T20:06:27.292Z,1716926787.292 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.572988
2024-05-28T20:06:27.544Z,1716926787.544 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.824873
2024-05-28T20:06:27.797Z,1716926787.797 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:27.797Z,1716926787.797 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:27.797Z,1716926787.797 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:28.048Z,1716926788.048 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:28.048Z,1716926788.048 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:28.048Z,1716926788.048 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:28.060Z,1716926788.060 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:28.300Z,1716926788.300 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:28.300Z,1716926788.300 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:28.300Z,1716926788.300 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:28.552Z,1716926788.552 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:28.552Z,1716926788.552 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:28.552Z,1716926788.552 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:28.804Z,1716926788.804 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:28.804Z,1716926788.804 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:28.804Z,1716926788.804 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:29.058Z,1716926789.058 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:29.058Z,1716926789.058 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:29.058Z,1716926789.058 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:29.308Z,1716926789.308 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:29.308Z,1716926789.308 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:29.308Z,1716926789.308 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:29.560Z,1716926789.560 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:29.560Z,1716926789.560 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:29.561Z,1716926789.561 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:29.664Z,1716926789.664 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-28T20:06:29.812Z,1716926789.812 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:29.812Z,1716926789.812 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:29.812Z,1716926789.812 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:30.067Z,1716926790.067 [DAT](INFO): DAT read: Response Not Received

2024-05-28T20:06:30.068Z,1716926790.068 [DAT](INFO): response not received
2024-05-28T20:06:30.069Z,1716926790.069 [DAT](INFO): DAT read: user:13>

2024-05-28T20:06:30.070Z,1716926790.070 [DAT](INFO): DAT read: Tx time:20:06:20.2650

2024-05-28T20:06:30.070Z,1716926790.070 [DAT](INFO): Ping request sent.
2024-05-28T20:06:30.070Z,1716926790.070 [DAT](INFO): transmitted an acoustic signal
2024-05-28T20:06:30.071Z,1716926790.071 [DAT](INFO): publishing transmit ping time
2024-05-28T20:06:30.072Z,1716926790.072 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001034
2024-05-28T20:06:30.317Z,1716926790.317 [DAT](INFO): DAT read: TxSync time:20:06:20.2642

2024-05-28T20:06:30.317Z,1716926790.317 [DAT](INFO): unknown deviceResponse_: TxSync time:20:06:20.2642

2024-05-28T20:06:30.318Z,1716926790.318 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.247261
2024-05-28T20:06:30.568Z,1716926790.568 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.497522
2024-05-28T20:06:30.820Z,1716926790.820 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.749511
2024-05-28T20:06:30.937Z,1716926790.937 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:31.072Z,1716926791.072 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.001586
2024-05-28T20:06:31.327Z,1716926791.327 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.256700
2024-05-28T20:06:31.576Z,1716926791.576 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.505652
2024-05-28T20:06:31.828Z,1716926791.828 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.757747
2024-05-28T20:06:32.080Z,1716926792.080 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.009651
2024-05-28T20:06:32.332Z,1716926792.332 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.261512
2024-05-28T20:06:32.584Z,1716926792.584 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.513569
2024-05-28T20:06:32.836Z,1716926792.836 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.765659
2024-05-28T20:06:33.088Z,1716926793.088 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.017614
2024-05-28T20:06:33.340Z,1716926793.340 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.269609
2024-05-28T20:06:33.592Z,1716926793.592 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.521550
2024-05-28T20:06:33.717Z,1716926793.717 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:33.845Z,1716926793.845 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.774433
2024-05-28T20:06:34.096Z,1716926794.096 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.025896
2024-05-28T20:06:34.348Z,1716926794.348 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.277566
2024-05-28T20:06:34.600Z,1716926794.600 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.529936
2024-05-28T20:06:34.852Z,1716926794.852 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.781514
2024-05-28T20:06:35.105Z,1716926795.105 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.034153
2024-05-28T20:06:35.356Z,1716926795.356 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.285395
2024-05-28T20:06:35.608Z,1716926795.608 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.537535
2024-05-28T20:06:35.860Z,1716926795.860 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.789987
2024-05-28T20:06:36.112Z,1716926796.112 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.041483
2024-05-28T20:06:36.366Z,1716926796.366 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.295946
2024-05-28T20:06:36.529Z,1716926796.529 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-28T20:06:36.589Z,1716926796.589 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:36.619Z,1716926796.619 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.547722
2024-05-28T20:06:36.868Z,1716926796.868 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.797491
2024-05-28T20:06:37.121Z,1716926797.121 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.050224
2024-05-28T20:06:37.372Z,1716926797.372 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.301682
2024-05-28T20:06:37.624Z,1716926797.624 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.553537
2024-05-28T20:06:37.876Z,1716926797.876 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.805850
2024-05-28T20:06:38.128Z,1716926798.128 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.057572
2024-05-28T20:06:38.380Z,1716926798.380 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.309526
2024-05-28T20:06:38.634Z,1716926798.634 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.563544
2024-05-28T20:06:38.884Z,1716926798.884 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.813561
2024-05-28T20:06:39.137Z,1716926799.137 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.066385
2024-05-28T20:06:39.377Z,1716926799.377 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:39.388Z,1716926799.388 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.317832
2024-05-28T20:06:39.640Z,1716926799.640 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.569584
2024-05-28T20:06:39.896Z,1716926799.896 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.824753
2024-05-28T20:06:40.144Z,1716926800.144 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:40.144Z,1716926800.144 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:40.144Z,1716926800.144 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:40.397Z,1716926800.397 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:40.398Z,1716926800.398 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:40.398Z,1716926800.398 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:40.650Z,1716926800.650 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:40.650Z,1716926800.650 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:40.651Z,1716926800.651 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:40.900Z,1716926800.900 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:40.900Z,1716926800.900 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:40.900Z,1716926800.900 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:41.152Z,1716926801.152 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:41.152Z,1716926801.152 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:41.152Z,1716926801.152 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:41.404Z,1716926801.404 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:41.404Z,1716926801.404 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:41.404Z,1716926801.404 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:41.656Z,1716926801.656 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:41.656Z,1716926801.656 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:41.656Z,1716926801.656 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:41.908Z,1716926801.908 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:41.908Z,1716926801.908 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:41.909Z,1716926801.909 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:42.160Z,1716926802.160 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:42.160Z,1716926802.160 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:42.160Z,1716926802.160 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:42.245Z,1716926802.245 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:42.412Z,1716926802.412 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:42.412Z,1716926802.412 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:42.412Z,1716926802.412 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:42.668Z,1716926802.668 [DAT](INFO): DAT read: Response Not Received

2024-05-28T20:06:42.669Z,1716926802.669 [DAT](INFO): response not received
2024-05-28T20:06:42.670Z,1716926802.670 [DAT](INFO): DAT read: user:14>

2024-05-28T20:06:42.672Z,1716926802.672 [DAT](INFO): DAT read: Tx time:20:06:32.7151

2024-05-28T20:06:42.673Z,1716926802.673 [DAT](INFO): Ping request sent.
2024-05-28T20:06:42.673Z,1716926802.673 [DAT](INFO): transmitted an acoustic signal
2024-05-28T20:06:42.674Z,1716926802.674 [DAT](INFO): publishing transmit ping time
2024-05-28T20:06:42.678Z,1716926802.678 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.002134
2024-05-28T20:06:42.916Z,1716926802.916 [DAT](INFO): DAT read: TxSync time:20:06:32.7143

2024-05-28T20:06:42.917Z,1716926802.917 [DAT](INFO): unknown deviceResponse_: TxSync time:20:06:32.7143

2024-05-28T20:06:42.917Z,1716926802.917 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.244216
2024-05-28T20:06:43.168Z,1716926803.168 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.495286
2024-05-28T20:06:43.420Z,1716926803.420 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.747346
2024-05-28T20:06:43.672Z,1716926803.672 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.999202
2024-05-28T20:06:43.925Z,1716926803.925 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.251974
2024-05-28T20:06:44.176Z,1716926804.176 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.503248
2024-05-28T20:06:44.428Z,1716926804.428 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.755169
2024-05-28T20:06:44.684Z,1716926804.684 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.010982
2024-05-28T20:06:44.932Z,1716926804.932 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.259234
2024-05-28T20:06:45.055Z,1716926805.055 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:45.184Z,1716926805.184 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.511235
2024-05-28T20:06:45.436Z,1716926805.436 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.763294
2024-05-28T20:06:45.688Z,1716926805.688 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.015230
2024-05-28T20:06:45.940Z,1716926805.940 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.267147
2024-05-28T20:06:46.192Z,1716926806.192 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.519191
2024-05-28T20:06:46.448Z,1716926806.448 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.774560
2024-05-28T20:06:46.696Z,1716926806.696 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.023203
2024-05-28T20:06:46.948Z,1716926806.948 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.275132
2024-05-28T20:06:47.200Z,1716926807.200 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.527583
2024-05-28T20:06:47.452Z,1716926807.452 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.779237
2024-05-28T20:06:47.704Z,1716926807.704 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.031223
2024-05-28T20:06:47.862Z,1716926807.862 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:47.957Z,1716926807.957 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.284044
2024-05-28T20:06:48.208Z,1716926808.208 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.535166
2024-05-28T20:06:48.461Z,1716926808.461 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.788374
2024-05-28T20:06:48.712Z,1716926808.712 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.039330
2024-05-28T20:06:48.964Z,1716926808.964 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.291162
2024-05-28T20:06:49.217Z,1716926809.217 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.543777
2024-05-28T20:06:49.472Z,1716926809.472 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.799434
2024-05-28T20:06:49.724Z,1716926809.724 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.051208
2024-05-28T20:06:49.976Z,1716926809.976 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.303428
2024-05-28T20:06:50.228Z,1716926810.228 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.555238
2024-05-28T20:06:50.481Z,1716926810.481 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.807486
2024-05-28T20:06:50.732Z,1716926810.732 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.059289
2024-05-28T20:06:50.754Z,1716926810.754 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:50.984Z,1716926810.984 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.311307
2024-05-28T20:06:51.236Z,1716926811.236 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.563664
2024-05-28T20:06:51.488Z,1716926811.488 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.815378
2024-05-28T20:06:51.740Z,1716926811.740 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.067397
2024-05-28T20:06:51.880Z,1716926811.880 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-28T20:06:51.993Z,1716926811.993 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.319685
2024-05-28T20:06:52.244Z,1716926812.244 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.571249
2024-05-28T20:06:52.497Z,1716926812.497 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.823811
2024-05-28T20:06:52.748Z,1716926812.748 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:52.748Z,1716926812.748 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:52.748Z,1716926812.748 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:53.000Z,1716926813.000 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:53.001Z,1716926813.001 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:53.001Z,1716926813.001 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:53.252Z,1716926813.252 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:53.252Z,1716926813.252 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:53.253Z,1716926813.253 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:53.504Z,1716926813.504 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:53.504Z,1716926813.504 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:53.504Z,1716926813.504 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:53.577Z,1716926813.577 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:53.756Z,1716926813.756 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:53.756Z,1716926813.756 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:53.756Z,1716926813.756 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:54.008Z,1716926814.008 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:54.008Z,1716926814.008 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:54.008Z,1716926814.008 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:54.260Z,1716926814.260 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:54.261Z,1716926814.261 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:54.261Z,1716926814.261 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:54.512Z,1716926814.512 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:54.513Z,1716926814.513 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:54.513Z,1716926814.513 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:54.764Z,1716926814.764 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:06:54.764Z,1716926814.764 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:06:54.764Z,1716926814.764 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:06:55.016Z,1716926815.016 [DAT](INFO): DAT read: Response Not Received

2024-05-28T20:06:55.016Z,1716926815.016 [DAT](INFO): response not received
2024-05-28T20:06:55.017Z,1716926815.017 [DAT](INFO): DAT read: user:15>

2024-05-28T20:06:55.017Z,1716926815.017 [DAT](INFO): DAT read: Tx time:20:06:45.1652

2024-05-28T20:06:55.018Z,1716926815.018 [DAT](INFO): Ping request sent.
2024-05-28T20:06:55.018Z,1716926815.018 [DAT](INFO): transmitted an acoustic signal
2024-05-28T20:06:55.018Z,1716926815.018 [DAT](INFO): publishing transmit ping time
2024-05-28T20:06:55.019Z,1716926815.019 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000715
2024-05-28T20:06:55.268Z,1716926815.268 [DAT](INFO): DAT read: TxSync time:20:06:45.1644

2024-05-28T20:06:55.269Z,1716926815.269 [DAT](INFO): unknown deviceResponse_: TxSync time:20:06:45.1644

2024-05-28T20:06:55.269Z,1716926815.269 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251150
2024-05-28T20:06:55.520Z,1716926815.520 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502151
2024-05-28T20:06:55.773Z,1716926815.773 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754918
2024-05-28T20:06:56.024Z,1716926816.024 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006545
2024-05-28T20:06:56.276Z,1716926816.276 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258156
2024-05-28T20:06:56.341Z,1716926816.341 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:56.532Z,1716926816.532 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.512984
2024-05-28T20:06:56.780Z,1716926816.780 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762167
2024-05-28T20:06:57.032Z,1716926817.032 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014305
2024-05-28T20:06:57.285Z,1716926817.285 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266880
2024-05-28T20:06:57.536Z,1716926817.536 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518152
2024-05-28T20:06:57.788Z,1716926817.788 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770117
2024-05-28T20:06:58.040Z,1716926818.040 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022076
2024-05-28T20:06:58.292Z,1716926818.292 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274320
2024-05-28T20:06:58.544Z,1716926818.544 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526145
2024-05-28T20:06:58.796Z,1716926818.796 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.777957
2024-05-28T20:06:59.048Z,1716926819.048 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030414
2024-05-28T20:06:59.170Z,1716926819.170 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:06:59.300Z,1716926819.300 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282471
2024-05-28T20:06:59.552Z,1716926819.552 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534479
2024-05-28T20:06:59.804Z,1716926819.804 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786079
2024-05-28T20:07:00.056Z,1716926820.056 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038138
2024-05-28T20:07:00.308Z,1716926820.308 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290057
2024-05-28T20:07:00.563Z,1716926820.563 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.545097
2024-05-28T20:07:00.818Z,1716926820.818 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.800369
2024-05-28T20:07:01.068Z,1716926821.068 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.050003
2024-05-28T20:07:01.320Z,1716926821.320 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.302409
2024-05-28T20:07:01.572Z,1716926821.572 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.554212
2024-05-28T20:07:01.673Z,1716926821.673 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:01.824Z,1716926821.824 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806082
2024-05-28T20:07:02.077Z,1716926822.077 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.058878
2024-05-28T20:07:02.328Z,1716926822.328 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.310078
2024-05-28T20:07:02.580Z,1716926822.580 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.562153
2024-05-28T20:07:02.832Z,1716926822.832 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.814389
2024-05-28T20:07:03.084Z,1716926823.084 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.066127
2024-05-28T20:07:03.340Z,1716926823.340 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.321419
2024-05-28T20:07:03.588Z,1716926823.588 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.570059
2024-05-28T20:07:03.842Z,1716926823.842 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.823708
2024-05-28T20:07:04.092Z,1716926824.092 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.074562
2024-05-28T20:07:04.344Z,1716926824.344 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.326100
2024-05-28T20:07:04.426Z,1716926824.426 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:04.596Z,1716926824.596 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.578128
2024-05-28T20:07:04.848Z,1716926824.848 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.830127
2024-05-28T20:07:05.100Z,1716926825.100 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:05.100Z,1716926825.100 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:05.100Z,1716926825.100 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:05.352Z,1716926825.352 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:05.352Z,1716926825.352 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:05.352Z,1716926825.352 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:05.604Z,1716926825.604 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:05.604Z,1716926825.604 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:05.604Z,1716926825.604 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:05.856Z,1716926825.856 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:05.856Z,1716926825.856 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:05.856Z,1716926825.856 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:06.111Z,1716926826.111 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:06.111Z,1716926826.111 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:06.112Z,1716926826.112 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:06.360Z,1716926826.360 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:06.360Z,1716926826.360 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:06.360Z,1716926826.360 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:06.612Z,1716926826.612 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:06.612Z,1716926826.612 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:06.613Z,1716926826.613 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:06.832Z,1716926826.832 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-28T20:07:06.864Z,1716926826.864 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:06.864Z,1716926826.864 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:06.864Z,1716926826.864 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:07.116Z,1716926827.116 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:07.116Z,1716926827.116 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:07.116Z,1716926827.116 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:07.282Z,1716926827.282 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:07.369Z,1716926827.369 [DAT](INFO): DAT read: Response Not Received

2024-05-28T20:07:07.369Z,1716926827.369 [DAT](INFO): response not received
2024-05-28T20:07:07.370Z,1716926827.370 [DAT](INFO): DAT read: user:16>

2024-05-28T20:07:07.370Z,1716926827.370 [DAT](ERROR): No response from remote modem.
2024-05-28T20:07:07.370Z,1716926827.370 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:07.370Z,1716926827.370 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:07.371Z,1716926827.371 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:07.620Z,1716926827.620 [DAT](INFO): DAT read: Tx time:20:06:57.6154

2024-05-28T20:07:07.621Z,1716926827.621 [DAT](INFO): Ping request sent.
2024-05-28T20:07:07.621Z,1716926827.621 [DAT](INFO): transmitted an acoustic signal
2024-05-28T20:07:07.621Z,1716926827.621 [DAT](INFO): DAT read: TxSync time:20:06:57.6146

2024-05-28T20:07:07.622Z,1716926827.622 [DAT](INFO): unknown deviceResponse_: TxSync time:20:06:57.6146

2024-05-28T20:07:07.622Z,1716926827.622 [DAT](INFO): publishing transmit ping time
2024-05-28T20:07:07.622Z,1716926827.622 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001685
2024-05-28T20:07:07.872Z,1716926827.872 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251672
2024-05-28T20:07:08.125Z,1716926828.125 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.504467
2024-05-28T20:07:08.376Z,1716926828.376 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755370
2024-05-28T20:07:08.630Z,1716926828.630 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.008520
2024-05-28T20:07:08.880Z,1716926828.880 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259669
2024-05-28T20:07:09.132Z,1716926829.132 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511407
2024-05-28T20:07:09.385Z,1716926829.385 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.764580
2024-05-28T20:07:09.636Z,1716926829.636 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015301
2024-05-28T20:07:09.888Z,1716926829.888 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267388
2024-05-28T20:07:10.081Z,1716926830.081 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:10.140Z,1716926830.140 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519443
2024-05-28T20:07:10.392Z,1716926830.392 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771303
2024-05-28T20:07:10.647Z,1716926830.647 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.025233
2024-05-28T20:07:10.881Z,1716926830.881 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-05-28T20:07:10.900Z,1716926830.900 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.279462
2024-05-28T20:07:11.152Z,1716926831.152 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.531325
2024-05-28T20:07:11.408Z,1716926831.408 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.786790
2024-05-28T20:07:11.656Z,1716926831.656 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.035333
2024-05-28T20:07:11.908Z,1716926831.908 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.287356
2024-05-28T20:07:12.160Z,1716926832.160 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.539377
2024-05-28T20:07:12.412Z,1716926832.412 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.791328
2024-05-28T20:07:12.667Z,1716926832.667 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.045726
2024-05-28T20:07:12.916Z,1716926832.916 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.295459
2024-05-28T20:07:12.954Z,1716926832.954 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:13.168Z,1716926833.168 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.547324
2024-05-28T20:07:13.421Z,1716926833.421 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.799982
2024-05-28T20:07:13.672Z,1716926833.672 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.051386
2024-05-28T20:07:13.924Z,1716926833.924 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.303348
2024-05-28T20:07:14.177Z,1716926834.177 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.555945
2024-05-28T20:07:14.428Z,1716926834.428 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.807379
2024-05-28T20:07:14.682Z,1716926834.682 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.061105
2024-05-28T20:07:14.932Z,1716926834.932 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.311810
2024-05-28T20:07:15.184Z,1716926835.184 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.563388
2024-05-28T20:07:15.436Z,1716926835.436 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.815587
2024-05-28T20:07:15.688Z,1716926835.688 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.067351
2024-05-28T20:07:15.791Z,1716926835.791 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:15.943Z,1716926835.943 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.322574
2024-05-28T20:07:16.196Z,1716926836.196 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.575788
2024-05-28T20:07:16.448Z,1716926836.448 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.827341
2024-05-28T20:07:16.701Z,1716926836.701 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.079688
2024-05-28T20:07:16.952Z,1716926836.952 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.331387
2024-05-28T20:07:17.204Z,1716926837.204 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.583295
2024-05-28T20:07:17.457Z,1716926837.457 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.835708
2024-05-28T20:07:17.708Z,1716926837.708 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:17.708Z,1716926837.708 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:17.708Z,1716926837.708 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:17.960Z,1716926837.960 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:17.960Z,1716926837.960 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:17.960Z,1716926837.960 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:18.212Z,1716926838.212 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:18.212Z,1716926838.212 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:18.213Z,1716926838.213 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:18.464Z,1716926838.464 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:18.464Z,1716926838.464 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:18.464Z,1716926838.464 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:18.578Z,1716926838.578 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:18.716Z,1716926838.716 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:18.716Z,1716926838.716 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:18.717Z,1716926838.717 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:18.968Z,1716926838.968 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:18.968Z,1716926838.968 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:18.968Z,1716926838.968 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:19.220Z,1716926839.220 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:19.220Z,1716926839.220 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:19.220Z,1716926839.220 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:19.473Z,1716926839.473 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:19.473Z,1716926839.473 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:19.473Z,1716926839.473 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:19.724Z,1716926839.724 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:19.724Z,1716926839.724 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:19.724Z,1716926839.724 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:19.976Z,1716926839.976 [DAT](INFO): DAT read: Response Not Received

2024-05-28T20:07:19.976Z,1716926839.976 [DAT](INFO): response not received
2024-05-28T20:07:19.977Z,1716926839.977 [DAT](INFO): DAT read: user:17>

2024-05-28T20:07:19.978Z,1716926839.978 [DAT](INFO): DAT read: Tx time:20:07:10.0655

2024-05-28T20:07:19.978Z,1716926839.978 [DAT](INFO): Ping request sent.
2024-05-28T20:07:19.978Z,1716926839.978 [DAT](INFO): transmitted an acoustic signal
2024-05-28T20:07:19.978Z,1716926839.978 [DAT](INFO): publishing transmit ping time
2024-05-28T20:07:19.979Z,1716926839.979 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000694
2024-05-28T20:07:20.232Z,1716926840.232 [DAT](INFO): DAT read: TxSync time:20:07:10.0647

2024-05-28T20:07:20.233Z,1716926840.233 [DAT](INFO): unknown deviceResponse_: TxSync time:20:07:10.0647

2024-05-28T20:07:20.233Z,1716926840.233 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.255451
2024-05-28T20:07:20.480Z,1716926840.480 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502124
2024-05-28T20:07:20.732Z,1716926840.732 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754090
2024-05-28T20:07:20.984Z,1716926840.984 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006375
2024-05-28T20:07:21.236Z,1716926841.236 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258124
2024-05-28T20:07:21.423Z,1716926841.423 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:21.488Z,1716926841.488 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510017
2024-05-28T20:07:21.740Z,1716926841.740 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762020
2024-05-28T20:07:21.994Z,1716926841.994 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.016412
2024-05-28T20:07:22.244Z,1716926842.244 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266071
2024-05-28T20:07:22.306Z,1716926842.306 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-28T20:07:22.499Z,1716926842.499 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.520388
2024-05-28T20:07:22.748Z,1716926842.748 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770215
2024-05-28T20:07:23.000Z,1716926843.000 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022101
2024-05-28T20:07:23.252Z,1716926843.252 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274289
2024-05-28T20:07:23.504Z,1716926843.504 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526118
2024-05-28T20:07:23.756Z,1716926843.756 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778057
2024-05-28T20:07:23.946Z,1716926843.946 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:24.012Z,1716926844.012 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.034331
2024-05-28T20:07:24.260Z,1716926844.260 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282264
2024-05-28T20:07:24.512Z,1716926844.512 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534045
2024-05-28T20:07:24.764Z,1716926844.764 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786425
2024-05-28T20:07:25.016Z,1716926845.016 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038114
2024-05-28T20:07:25.268Z,1716926845.268 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290300
2024-05-28T20:07:25.520Z,1716926845.520 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542352
2024-05-28T20:07:25.772Z,1716926845.772 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794137
2024-05-28T20:07:26.028Z,1716926846.028 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.048976
2024-05-28T20:07:26.276Z,1716926846.276 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298400
2024-05-28T20:07:26.528Z,1716926846.528 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550229
2024-05-28T20:07:26.780Z,1716926846.780 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802168
2024-05-28T20:07:26.807Z,1716926846.807 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:27.034Z,1716926847.034 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.055971
2024-05-28T20:07:27.285Z,1716926847.285 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306462
2024-05-28T20:07:27.536Z,1716926847.536 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558116
2024-05-28T20:07:27.793Z,1716926847.793 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.813880
2024-05-28T20:07:28.040Z,1716926848.040 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062469
2024-05-28T20:07:28.292Z,1716926848.292 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314110
2024-05-28T20:07:28.548Z,1716926848.548 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.570308
2024-05-28T20:07:28.800Z,1716926848.800 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.822202
2024-05-28T20:07:29.052Z,1716926849.052 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.074227
2024-05-28T20:07:29.296Z,1716926849.296 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-05-28T20:07:29.296Z,1716926849.296 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-05-28T20:07:29.304Z,1716926849.304 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.326149
2024-05-28T20:07:29.507Z,1716926849.507 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-05-28T20:07:29.556Z,1716926849.556 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.578288
2024-05-28T20:07:29.633Z,1716926849.633 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:29.809Z,1716926849.809 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.830665
2024-05-28T20:07:29.860Z,1716926849.860 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-05-28T20:07:29.860Z,1716926849.860 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-05-28T20:07:30.060Z,1716926850.060 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:30.060Z,1716926850.060 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:30.061Z,1716926850.061 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:30.312Z,1716926850.312 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:30.312Z,1716926850.312 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:30.312Z,1716926850.312 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:30.564Z,1716926850.564 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:30.564Z,1716926850.564 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:30.565Z,1716926850.565 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:30.816Z,1716926850.816 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:30.816Z,1716926850.816 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:30.816Z,1716926850.816 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:31.071Z,1716926851.071 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:31.071Z,1716926851.071 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:31.072Z,1716926851.072 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:31.320Z,1716926851.320 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:31.320Z,1716926851.320 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:31.320Z,1716926851.320 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:31.572Z,1716926851.572 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:31.572Z,1716926851.572 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:31.572Z,1716926851.572 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:31.824Z,1716926851.824 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:31.824Z,1716926851.824 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:31.824Z,1716926851.824 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:32.077Z,1716926852.077 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:32.077Z,1716926852.077 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:32.079Z,1716926852.079 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:32.328Z,1716926852.328 [DAT](INFO): DAT read: Response Not Received

2024-05-28T20:07:32.328Z,1716926852.328 [DAT](INFO): response not received
2024-05-28T20:07:32.329Z,1716926852.329 [DAT](INFO): DAT read: user:18>

2024-05-28T20:07:32.329Z,1716926852.329 [DAT](INFO): DAT read: Tx time:20:07:22.5157

2024-05-28T20:07:32.330Z,1716926852.330 [DAT](INFO): Ping request sent.
2024-05-28T20:07:32.330Z,1716926852.330 [DAT](INFO): transmitted an acoustic signal
2024-05-28T20:07:32.330Z,1716926852.330 [DAT](INFO): publishing transmit ping time
2024-05-28T20:07:32.331Z,1716926852.331 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000693
2024-05-28T20:07:32.435Z,1716926852.435 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:32.580Z,1716926852.580 [DAT](INFO): DAT read: TxSync time:20:07:22.5149

2024-05-28T20:07:32.581Z,1716926852.581 [DAT](INFO): unknown deviceResponse_: TxSync time:20:07:22.5149

2024-05-28T20:07:32.581Z,1716926852.581 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251439
2024-05-28T20:07:32.832Z,1716926852.832 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502060
2024-05-28T20:07:33.085Z,1716926853.085 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754748
2024-05-28T20:07:33.337Z,1716926853.337 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006957
2024-05-28T20:07:33.588Z,1716926853.588 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258090
2024-05-28T20:07:33.840Z,1716926853.840 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510082
2024-05-28T20:07:34.092Z,1716926854.092 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762267
2024-05-28T20:07:34.344Z,1716926854.344 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014023
2024-05-28T20:07:34.596Z,1716926854.596 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266407
2024-05-28T20:07:34.848Z,1716926854.848 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518299
2024-05-28T20:07:35.100Z,1716926855.100 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770076
2024-05-28T20:07:35.281Z,1716926855.281 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:35.353Z,1716926855.353 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022875
2024-05-28T20:07:35.604Z,1716926855.604 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274136
2024-05-28T20:07:35.856Z,1716926855.856 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526518
2024-05-28T20:07:36.108Z,1716926856.108 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778296
2024-05-28T20:07:36.360Z,1716926856.360 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030155
2024-05-28T20:07:36.612Z,1716926856.612 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282187
2024-05-28T20:07:36.864Z,1716926856.864 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534443
2024-05-28T20:07:37.116Z,1716926857.116 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786142
2024-05-28T20:07:37.369Z,1716926857.369 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038628
2024-05-28T20:07:37.620Z,1716926857.620 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290068
2024-05-28T20:07:37.872Z,1716926857.872 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542288
2024-05-28T20:07:38.124Z,1716926858.124 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794022
2024-05-28T20:07:38.174Z,1716926858.174 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:38.376Z,1716926858.376 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046128
2024-05-28T20:07:38.465Z,1716926858.465 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-28T20:07:38.629Z,1716926858.629 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298823
2024-05-28T20:07:38.880Z,1716926858.880 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550089
2024-05-28T20:07:39.133Z,1716926859.133 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802923
2024-05-28T20:07:39.384Z,1716926859.384 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054111
2024-05-28T20:07:39.636Z,1716926859.636 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306067
2024-05-28T20:07:39.889Z,1716926859.889 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558974
2024-05-28T20:07:40.140Z,1716926860.140 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810446
2024-05-28T20:07:40.392Z,1716926860.392 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062030
2024-05-28T20:07:40.647Z,1716926860.647 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.316435
2024-05-28T20:07:40.896Z,1716926860.896 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566059
2024-05-28T20:07:40.928Z,1716926860.928 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:41.148Z,1716926861.148 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818087
2024-05-28T20:07:41.401Z,1716926861.401 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070627
2024-05-28T20:07:41.652Z,1716926861.652 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322150
2024-05-28T20:07:41.904Z,1716926861.904 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574053
2024-05-28T20:07:42.156Z,1716926862.156 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826421
2024-05-28T20:07:42.408Z,1716926862.408 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:42.408Z,1716926862.408 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:42.408Z,1716926862.408 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:42.661Z,1716926862.661 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:42.662Z,1716926862.662 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:42.662Z,1716926862.662 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:42.912Z,1716926862.912 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:42.912Z,1716926862.912 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:42.912Z,1716926862.912 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:43.167Z,1716926863.167 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:43.168Z,1716926863.168 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:43.168Z,1716926863.168 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:43.416Z,1716926863.416 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:43.416Z,1716926863.416 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:43.417Z,1716926863.417 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:43.668Z,1716926863.668 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:43.668Z,1716926863.668 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:43.668Z,1716926863.668 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:43.774Z,1716926863.774 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:43.920Z,1716926863.920 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:43.920Z,1716926863.920 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:43.920Z,1716926863.920 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:44.184Z,1716926864.184 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:44.184Z,1716926864.184 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:44.185Z,1716926864.185 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:44.436Z,1716926864.436 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:44.436Z,1716926864.436 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:44.436Z,1716926864.436 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:44.692Z,1716926864.692 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:44.692Z,1716926864.692 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:44.693Z,1716926864.693 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:44.940Z,1716926864.940 [DAT](INFO): DAT read: Response Not Received

2024-05-28T20:07:44.940Z,1716926864.940 [DAT](INFO): response not received
2024-05-28T20:07:44.941Z,1716926864.941 [DAT](INFO): DAT read: user:19>

2024-05-28T20:07:44.942Z,1716926864.942 [DAT](INFO): DAT read: Tx time:20:07:34.9658

2024-05-28T20:07:44.942Z,1716926864.942 [DAT](INFO): Ping request sent.
2024-05-28T20:07:44.942Z,1716926864.942 [DAT](INFO): transmitted an acoustic signal
2024-05-28T20:07:44.942Z,1716926864.942 [DAT](INFO): publishing transmit ping time
2024-05-28T20:07:44.943Z,1716926864.943 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000694
2024-05-28T20:07:45.196Z,1716926865.196 [DAT](INFO): DAT read: TxSync time:20:07:34.9650

2024-05-28T20:07:45.197Z,1716926865.197 [DAT](INFO): unknown deviceResponse_: TxSync time:20:07:34.9650

2024-05-28T20:07:45.197Z,1716926865.197 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.255116
2024-05-28T20:07:45.445Z,1716926865.445 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502906
2024-05-28T20:07:45.696Z,1716926865.696 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754055
2024-05-28T20:07:45.949Z,1716926865.949 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007312
2024-05-28T20:07:46.200Z,1716926866.200 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258296
2024-05-28T20:07:46.452Z,1716926866.452 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510056
2024-05-28T20:07:46.562Z,1716926866.562 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:46.707Z,1716926866.707 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.765332
2024-05-28T20:07:46.956Z,1716926866.956 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014105
2024-05-28T20:07:47.208Z,1716926867.208 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266061
2024-05-28T20:07:47.461Z,1716926867.461 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518775
2024-05-28T20:07:47.712Z,1716926867.712 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770107
2024-05-28T20:07:47.965Z,1716926867.965 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022661
2024-05-28T20:07:48.216Z,1716926868.216 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274125
2024-05-28T20:07:48.468Z,1716926868.468 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526116
2024-05-28T20:07:48.721Z,1716926868.721 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778867
2024-05-28T20:07:48.972Z,1716926868.972 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030294
2024-05-28T20:07:49.224Z,1716926869.224 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282085
2024-05-28T20:07:49.394Z,1716926869.394 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:49.476Z,1716926869.476 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534005
2024-05-28T20:07:49.728Z,1716926869.728 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786085
2024-05-28T20:07:49.980Z,1716926869.980 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038111
2024-05-28T20:07:50.232Z,1716926870.232 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290101
2024-05-28T20:07:50.484Z,1716926870.484 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542079
2024-05-28T20:07:50.737Z,1716926870.737 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794991
2024-05-28T20:07:50.988Z,1716926870.988 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046168
2024-05-28T20:07:51.240Z,1716926871.240 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298333
2024-05-28T20:07:51.492Z,1716926871.492 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550124
2024-05-28T20:07:51.744Z,1716926871.744 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802082
2024-05-28T20:07:51.996Z,1716926871.996 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054119
2024-05-28T20:07:52.214Z,1716926872.214 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:52.248Z,1716926872.248 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306401
2024-05-28T20:07:52.500Z,1716926872.500 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558055
2024-05-28T20:07:52.753Z,1716926872.753 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.811003
2024-05-28T20:07:53.004Z,1716926873.004 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062200
2024-05-28T20:07:53.256Z,1716926873.256 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314011
2024-05-28T20:07:53.508Z,1716926873.508 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566418
2024-05-28T20:07:53.760Z,1716926873.760 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818155
2024-05-28T20:07:53.828Z,1716926873.828 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-05-28T20:07:54.012Z,1716926874.012 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070163
2024-05-28T20:07:54.264Z,1716926874.264 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322435
2024-05-28T20:07:54.516Z,1716926874.516 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574128
2024-05-28T20:07:54.768Z,1716926874.768 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826123
2024-05-28T20:07:55.020Z,1716926875.020 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:55.020Z,1716926875.020 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:55.020Z,1716926875.020 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:55.120Z,1716926875.120 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:55.272Z,1716926875.272 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:55.272Z,1716926875.272 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:55.272Z,1716926875.272 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:55.524Z,1716926875.524 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:55.524Z,1716926875.524 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:55.524Z,1716926875.524 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:55.778Z,1716926875.778 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:55.779Z,1716926875.779 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:55.780Z,1716926875.780 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:56.029Z,1716926876.029 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:56.029Z,1716926876.029 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:56.029Z,1716926876.029 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:56.280Z,1716926876.280 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:56.280Z,1716926876.280 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:56.280Z,1716926876.280 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:56.532Z,1716926876.532 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:56.532Z,1716926876.532 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:56.532Z,1716926876.532 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:56.784Z,1716926876.784 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:56.784Z,1716926876.784 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:56.784Z,1716926876.784 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:57.036Z,1716926877.036 [DAT](INFO): ****** received valid address query ******
2024-05-28T20:07:57.036Z,1716926877.036 [DAT](INFO): ****** received valid ping request ******
2024-05-28T20:07:57.036Z,1716926877.036 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-05-28T20:07:57.291Z,1716926877.291 [DAT](INFO): DAT read: Response Not Received

2024-05-28T20:07:57.291Z,1716926877.291 [DAT](INFO): response not received
2024-05-28T20:07:57.291Z,1716926877.291 [DAT](INFO): DAT read: user:20>

2024-05-28T20:07:57.292Z,1716926877.292 [DAT](INFO): DAT read: Tx time:20:07:47.4159

2024-05-28T20:07:57.292Z,1716926877.292 [DAT](INFO): Ping request sent.
2024-05-28T20:07:57.292Z,1716926877.292 [DAT](INFO): transmitted an acoustic signal
2024-05-28T20:07:57.293Z,1716926877.293 [DAT](INFO): publishing transmit ping time
2024-05-28T20:07:57.293Z,1716926877.293 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000701
2024-05-28T20:07:57.540Z,1716926877.540 [DAT](INFO): DAT read: TxSync time:20:07:47.4151

2024-05-28T20:07:57.541Z,1716926877.541 [DAT](INFO): unknown deviceResponse_: TxSync time:20:07:47.4151

2024-05-28T20:07:57.541Z,1716926877.541 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.248764
2024-05-28T20:07:57.792Z,1716926877.792 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.499553
2024-05-28T20:07:57.868Z,1716926877.868 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:07:58.046Z,1716926878.046 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753884
2024-05-28T20:07:58.296Z,1716926878.296 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.003611
2024-05-28T20:07:58.548Z,1716926878.548 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.255620
2024-05-28T20:07:58.800Z,1716926878.800 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.508022
2024-05-28T20:07:59.052Z,1716926879.052 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.759611
2024-05-28T20:07:59.304Z,1716926879.304 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.011590
2024-05-28T20:07:59.556Z,1716926879.556 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.263753
2024-05-28T20:07:59.808Z,1716926879.808 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.515594
2024-05-28T20:08:00.060Z,1716926880.060 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.767917
2024-05-28T20:08:00.312Z,1716926880.312 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.019623
2024-05-28T20:08:00.564Z,1716926880.564 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.271761
2024-05-28T20:08:00.772Z,1716926880.772 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:08:00.796Z,1716926880.796 [CommandExec](IMPORTANT): got command quit
2024-05-28T20:08:00.817Z,1716926880.817 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.524071
2024-05-28T20:08:01.068Z,1716926881.068 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.775569
2024-05-28T20:08:01.320Z,1716926881.320 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.027625
2024-05-28T20:08:01.572Z,1716926881.572 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.279494
2024-05-28T20:08:01.799Z,1716926881.799 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2024-05-28T20:08:01.800Z,1716926881.800 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-28T20:08:01.800Z,1716926881.800 [CommandExec](INFO): Uninitializing the command executive.
2024-05-28T20:08:01.800Z,1716926881.800 [CommandExec](INFO): Uninitializing the command scheduler.
2024-05-28T20:08:01.800Z,1716926881.800 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:01.824Z,1716926881.824 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.531638
2024-05-28T20:08:01.968Z,1716926881.968 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2024-05-28T20:08:01.968Z,1716926881.968 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-05-28T20:08:01.969Z,1716926881.969 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:01.969Z,1716926881.969 [NavChartDb](INFO): Join timeout helper Thread ID is 8847
2024-05-28T20:08:02.020Z,1716926882.020 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-28T20:08:02.020Z,1716926882.020 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:02.026Z,1716926882.026 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2024-05-28T20:08:02.026Z,1716926882.026 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:02.026Z,1716926882.026 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 8848
2024-05-28T20:08:02.076Z,1716926882.076 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.783886
2024-05-28T20:08:02.328Z,1716926882.328 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.035520
2024-05-28T20:08:02.388Z,1716926882.388 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-28T20:08:02.388Z,1716926882.388 [WetLabsUBAT](INFO): Powering down
2024-05-28T20:08:02.389Z,1716926882.389 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:02.389Z,1716926882.389 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-05-28T20:08:02.389Z,1716926882.389 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:02.390Z,1716926882.390 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8849
2024-05-28T20:08:02.580Z,1716926882.580 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.287648
2024-05-28T20:08:02.763Z,1716926882.763 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-28T20:08:02.764Z,1716926882.764 [WetLabsBB2FL](INFO): Powering down
2024-05-28T20:08:02.768Z,1716926882.768 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:02.775Z,1716926882.775 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-05-28T20:08:02.775Z,1716926882.775 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:02.776Z,1716926882.776 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8850
2024-05-28T20:08:02.833Z,1716926882.833 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.540156
2024-05-28T20:08:03.084Z,1716926883.084 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.791602
2024-05-28T20:08:03.336Z,1716926883.336 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.043660
2024-05-28T20:08:03.546Z,1716926883.546 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:08:03.555Z,1716926883.555 [CTD_Seabird](INFO): Powering down
2024-05-28T20:08:03.567Z,1716926883.567 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-28T20:08:03.567Z,1716926883.567 [CTD_Seabird](INFO): Powering down
2024-05-28T20:08:03.580Z,1716926883.580 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:03.589Z,1716926883.589 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.296223
2024-05-28T20:08:03.589Z,1716926883.589 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2024-05-28T20:08:03.589Z,1716926883.589 [DAT ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:03.590Z,1716926883.590 [DAT](INFO): Join timeout helper Thread ID is 8851
2024-05-28T20:08:03.839Z,1716926883.839 [DAT](INFO): Powering down
2024-05-28T20:08:03.912Z,1716926883.912 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-28T20:08:03.912Z,1716926883.912 [DAT](INFO): Powering down
2024-05-28T20:08:03.913Z,1716926883.913 [DAT ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:03.919Z,1716926883.919 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-05-28T20:08:03.920Z,1716926883.920 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:03.920Z,1716926883.920 [Radio_Surface](INFO): Join timeout helper Thread ID is 8852
2024-05-28T20:08:03.996Z,1716926883.996 [Radio_Surface](INFO): Powering down
2024-05-28T20:08:03.997Z,1716926883.997 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-28T20:08:03.997Z,1716926883.997 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:04.009Z,1716926884.009 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-05-28T20:08:04.009Z,1716926884.009 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:04.010Z,1716926884.010 [Onboard](INFO): Join timeout helper Thread ID is 8853
2024-05-28T20:08:06.381Z,1716926886.381 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-05-28T20:08:08.155Z,1716926888.155 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-28T20:08:08.156Z,1716926888.156 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:08.172Z,1716926888.172 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-05-28T20:08:08.172Z,1716926888.172 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:08.172Z,1716926888.172 [DataOverHttps](INFO): Join timeout helper Thread ID is 8854
2024-05-28T20:08:08.563Z,1716926888.563 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-28T20:08:08.566Z,1716926888.566 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:08.580Z,1716926888.580 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-05-28T20:08:08.580Z,1716926888.580 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:08.580Z,1716926888.580 [BackseatComponent](INFO): Join timeout helper Thread ID is 8856
2024-05-28T20:08:08.700Z,1716926888.700 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-28T20:08:08.701Z,1716926888.701 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:08.720Z,1716926888.720 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-05-28T20:08:08.720Z,1716926888.720 [logger ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:08.721Z,1716926888.721 [logger](INFO): Join timeout helper Thread ID is 8857
2024-05-28T20:08:08.784Z,1716926888.784 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-28T20:08:08.784Z,1716926888.784 [logger ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:08.796Z,1716926888.796 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-05-28T20:08:08.796Z,1716926888.796 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:08.796Z,1716926888.796 [CommandLine](INFO): Join timeout helper Thread ID is 8858
2024-05-28T20:08:08.851Z,1716926888.851 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-28T20:08:08.852Z,1716926888.852 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:08.864Z,1716926888.864 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-05-28T20:08:08.864Z,1716926888.864 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:08.864Z,1716926888.864 [CommandExec](INFO): Join timeout helper Thread ID is 8859
2024-05-28T20:08:08.876Z,1716926888.876 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-05-28T20:08:08.876Z,1716926888.876 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:08.877Z,1716926888.877 [controlThread](INFO): Join timeout helper Thread ID is 8860
2024-05-28T20:08:09.001Z,1716926889.001 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-05-28T20:08:09.001Z,1716926889.001 [controlThread](DEBUG): Uninitializing ControlThread
2024-05-28T20:08:09.002Z,1716926889.002 [AHRS_M2](INFO): Powering down
2024-05-28T20:08:09.073Z,1716926889.073 [NAL9602](INFO): Powering down
2024-05-28T20:08:09.143Z,1716926889.143 [RDI_Pathfinder](INFO): Powering down
2024-05-28T20:08:09.145Z,1716926889.145 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-05-28T20:08:09.145Z,1716926889.145 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-05-28T20:08:09.146Z,1716926889.146 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-05-28T20:08:09.146Z,1716926889.146 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2024-05-28T20:08:09.146Z,1716926889.146 [DefaultWithUndock] Stopped
2024-05-28T20:08:09.147Z,1716926889.147 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2024-05-28T20:08:09.147Z,1716926889.147 [DefaultWithUndock:LeaveDock] Stopped
2024-05-28T20:08:09.147Z,1716926889.147 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2024-05-28T20:08:09.147Z,1716926889.147 [DefaultWithUndock:LeaveDock:A.] Stopped
2024-05-28T20:08:09.147Z,1716926889.147 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2024-05-28T20:08:09.147Z,1716926889.147 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2024-05-28T20:08:09.154Z,1716926889.154 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-05-28T20:08:09.154Z,1716926889.154 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-05-28T20:08:09.154Z,1716926889.154 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-05-28T20:08:09.155Z,1716926889.155 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-05-28T20:08:09.155Z,1716926889.155 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-05-28T20:08:09.155Z,1716926889.155 [BuoyancyServo](INFO): Powering down
2024-05-28T20:08:09.167Z,1716926889.167 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-05-28T20:08:09.167Z,1716926889.167 [ElevatorServo](INFO): Powering down
2024-05-28T20:08:09.168Z,1716926889.168 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2024-05-28T20:08:09.168Z,1716926889.168 [DockingStepper](INFO): Powering down
2024-05-28T20:08:09.169Z,1716926889.169 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-05-28T20:08:09.169Z,1716926889.169 [MassServo](INFO): Powering down
2024-05-28T20:08:09.170Z,1716926889.170 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-05-28T20:08:09.170Z,1716926889.170 [RudderServo](INFO): Powering down
2024-05-28T20:08:09.171Z,1716926889.171 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2024-05-28T20:08:09.171Z,1716926889.171 [ThrusterServo](INFO): Powering down
2024-05-28T20:08:09.172Z,1716926889.172 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-05-28T20:08:09.172Z,1716926889.172 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-05-28T20:08:09.172Z,1716926889.172 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-05-28T20:08:09.172Z,1716926889.172 [CBIT](DEBUG): Powering off loads.
2024-05-28T20:08:09.183Z,1716926889.183 [CBIT](DEBUG): Disabling WDT.
2024-05-28T20:08:09.195Z,1716926889.195 [CBIT](DEBUG): Opening all GF detection circuits.
2024-05-28T20:08:09.196Z,1716926889.196 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:09.262Z,1716926889.262 [DAT ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:09.269Z,1716926889.269 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:09.271Z,1716926889.271 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:09.276Z,1716926889.276 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:09.318Z,1716926889.318 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:09.614Z,1716926889.614 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:09.616Z,1716926889.616 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:09.620Z,1716926889.620 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:09.628Z,1716926889.628 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-05-28T20:08:09.692Z,1716926889.692 [logger ThreadHandler](INFO): Thread cancelled.