2024-06-12T15:44:31.824Z,1718207071.824 [Supervisor](DEBUG): Initializing supervisor.
2024-06-12T15:44:31.829Z,1718207071.829 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-06-12T15:44:31.830Z,1718207071.830 [SyncHandler](INFO): Protected caller Thread ID is 1031
2024-06-12T15:44:31.830Z,1718207071.830 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-06-12T15:44:31.831Z,1718207071.831 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-06-12T15:44:31.832Z,1718207071.832 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1032
2024-06-12T15:44:31.836Z,1718207071.836 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-06-12T15:44:31.853Z,1718207071.853 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-06-12T15:44:31.854Z,1718207071.854 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-06-12T15:44:31.855Z,1718207071.855 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1033
2024-06-12T15:44:31.859Z,1718207071.859 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-06-12T15:44:31.860Z,1718207071.860 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-06-12T15:44:31.860Z,1718207071.860 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1034
2024-06-12T15:44:31.863Z,1718207071.863 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-06-12T15:44:31.864Z,1718207071.864 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-06-12T15:44:31.864Z,1718207071.864 [logger ThreadHandler](INFO): Protected caller Thread ID is 1035
2024-06-12T15:44:31.868Z,1718207071.868 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-06-12T15:44:31.868Z,1718207071.868 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-06-12T15:44:31.870Z,1718207071.870 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-06-12T15:44:31.978Z,1718207071.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-06-12T15:44:31.979Z,1718207071.979 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-06-12T15:44:32.773Z,1718207072.773 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-06-12T15:44:32.774Z,1718207072.774 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-06-12T15:44:33.023Z,1718207073.023 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-06-12T15:44:33.024Z,1718207073.024 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-06-12T15:44:33.115Z,1718207073.115 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-06-12T15:44:33.259Z,1718207073.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-06-12T15:44:33.259Z,1718207073.259 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-06-12T15:44:33.352Z,1718207073.352 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-06-12T15:44:33.472Z,1718207073.472 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-06-12T15:44:33.473Z,1718207073.473 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-06-12T15:44:33.891Z,1718207073.891 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-06-12T15:44:33.891Z,1718207073.891 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-06-12T15:44:34.455Z,1718207074.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-06-12T15:44:34.456Z,1718207074.456 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-06-12T15:44:34.692Z,1718207074.692 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-06-12T15:44:34.692Z,1718207074.692 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-06-12T15:44:34.895Z,1718207074.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-06-12T15:44:34.895Z,1718207074.895 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-06-12T15:44:36.004Z,1718207076.004 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-06-12T15:44:36.005Z,1718207076.005 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-06-12T15:44:36.314Z,1718207076.314 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-06-12T15:44:36.314Z,1718207076.314 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-06-12T15:44:36.562Z,1718207076.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-06-12T15:44:36.562Z,1718207076.562 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-06-12T15:44:36.958Z,1718207076.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-06-12T15:44:36.958Z,1718207076.958 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-06-12T15:44:37.329Z,1718207077.329 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-06-12T15:44:37.331Z,1718207077.331 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2024-06-12T15:44:37.332Z,1718207077.332 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2024-06-12T15:44:37.428Z,1718207077.428 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2024-06-12T15:44:37.611Z,1718207077.611 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg
2024-06-12T15:44:37.718Z,1718207077.718 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2024-06-12T15:44:37.839Z,1718207077.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2024-06-12T15:44:37.937Z,1718207077.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2024-06-12T15:44:38.044Z,1718207078.044 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2024-06-12T15:44:38.170Z,1718207078.170 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2024-06-12T15:44:38.446Z,1718207078.446 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-06-12T15:44:38.446Z,1718207078.446 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2024-06-12T15:44:38.552Z,1718207078.552 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2024-06-12T15:44:38.658Z,1718207078.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2024-06-12T15:44:38.813Z,1718207078.813 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2024-06-12T15:44:38.926Z,1718207078.926 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2024-06-12T15:44:38.926Z,1718207078.926 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-06-12T15:44:38.932Z,1718207078.932 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-06-12T15:44:39.049Z,1718207079.049 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-06-12T15:44:39.049Z,1718207079.049 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-06-12T15:44:39.103Z,1718207079.103 [VerticalControl](DEBUG): Construct VerticalControl.
2024-06-12T15:44:39.163Z,1718207079.163 [VerticalControl] Loaded
2024-06-12T15:44:39.163Z,1718207079.163 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-06-12T15:44:39.166Z,1718207079.166 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-06-12T15:44:39.213Z,1718207079.213 [HorizontalControl] Loaded
2024-06-12T15:44:39.213Z,1718207079.213 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-06-12T15:44:39.215Z,1718207079.215 [SpeedControl](DEBUG): Construct SpeedControl.
2024-06-12T15:44:39.218Z,1718207079.218 [SpeedControl] Loaded
2024-06-12T15:44:39.219Z,1718207079.219 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-06-12T15:44:39.221Z,1718207079.221 [LoopControl](DEBUG): Construct LoopControl.
2024-06-12T15:44:39.222Z,1718207079.222 [LoopControl] Loaded
2024-06-12T15:44:39.222Z,1718207079.222 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-06-12T15:44:39.222Z,1718207079.222 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-06-12T15:44:39.223Z,1718207079.223 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-06-12T15:44:39.273Z,1718207079.273 [DepthRateCalculator] Loaded
2024-06-12T15:44:39.273Z,1718207079.273 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-06-12T15:44:39.278Z,1718207079.278 [PitchRateCalculator] Loaded
2024-06-12T15:44:39.278Z,1718207079.278 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-06-12T15:44:39.288Z,1718207079.288 [SpeedCalculator] Loaded
2024-06-12T15:44:39.288Z,1718207079.288 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-06-12T15:44:39.292Z,1718207079.292 [YawRateCalculator] Loaded
2024-06-12T15:44:39.293Z,1718207079.293 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-06-12T15:44:39.311Z,1718207079.311 [ElevatorOffsetCalculator] Loaded
2024-06-12T15:44:39.311Z,1718207079.311 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-06-12T15:44:39.311Z,1718207079.311 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-06-12T15:44:39.312Z,1718207079.312 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-06-12T15:44:39.339Z,1718207079.339 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-06-12T15:44:39.339Z,1718207079.339 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-06-12T15:44:39.394Z,1718207079.394 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-06-12T15:44:39.394Z,1718207079.394 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-06-12T15:44:39.735Z,1718207079.735 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-06-12T15:44:39.735Z,1718207079.735 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-06-12T15:44:39.907Z,1718207079.907 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-06-12T15:44:39.908Z,1718207079.908 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-06-12T15:44:40.371Z,1718207080.371 [AHRS_M2] Loaded
2024-06-12T15:44:40.371Z,1718207080.371 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-06-12T15:44:40.410Z,1718207080.410 [BackseatComponent] Loaded
2024-06-12T15:44:40.410Z,1718207080.410 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-06-12T15:44:40.411Z,1718207080.411 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409C54E0
2024-06-12T15:44:40.412Z,1718207080.412 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1120
2024-06-12T15:44:40.414Z,1718207080.414 [LcmUniversalReporter] Loaded
2024-06-12T15:44:40.415Z,1718207080.415 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-06-12T15:44:41.215Z,1718207081.215 [BPC1] Loaded
2024-06-12T15:44:41.215Z,1718207081.215 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-06-12T15:44:41.287Z,1718207081.287 [DataOverHttps] Loaded
2024-06-12T15:44:41.287Z,1718207081.287 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-06-12T15:44:41.288Z,1718207081.288 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F54E0
2024-06-12T15:44:41.288Z,1718207081.288 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1121
2024-06-12T15:44:41.311Z,1718207081.311 [Depth_Keller] Loaded
2024-06-12T15:44:41.311Z,1718207081.311 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-06-12T15:44:41.316Z,1718207081.316 [DropWeight] Loaded
2024-06-12T15:44:41.316Z,1718207081.316 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-06-12T15:44:41.538Z,1718207081.538 [NAL9602] Loaded
2024-06-12T15:44:41.539Z,1718207081.539 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-06-12T15:44:41.656Z,1718207081.656 [Onboard] Loaded
2024-06-12T15:44:41.656Z,1718207081.656 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-06-12T15:44:41.657Z,1718207081.657 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A254E0
2024-06-12T15:44:41.657Z,1718207081.657 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1122
2024-06-12T15:44:41.748Z,1718207081.748 [Power24vConverter] Loaded
2024-06-12T15:44:41.748Z,1718207081.748 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-06-12T15:44:41.840Z,1718207081.840 [Radio_Surface] Loaded
2024-06-12T15:44:41.841Z,1718207081.841 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-06-12T15:44:41.917Z,1718207081.917 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A554E0
2024-06-12T15:44:41.918Z,1718207081.918 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1123
2024-06-12T15:44:41.998Z,1718207081.998 [RDI_Pathfinder] Loaded
2024-06-12T15:44:41.998Z,1718207081.998 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2024-06-12T15:44:42.091Z,1718207082.091 [DAT] Loaded
2024-06-12T15:44:42.091Z,1718207082.091 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-06-12T15:44:42.092Z,1718207082.092 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0
2024-06-12T15:44:42.093Z,1718207082.093 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1124
2024-06-12T15:44:42.093Z,1718207082.093 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-06-12T15:44:42.094Z,1718207082.094 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-06-12T15:44:42.204Z,1718207082.204 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-06-12T15:44:42.204Z,1718207082.204 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-06-12T15:44:42.218Z,1718207082.218 [NavChart] Loaded
2024-06-12T15:44:42.218Z,1718207082.218 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-06-12T15:44:42.224Z,1718207082.224 [UniversalFixResidualReporter] Loaded
2024-06-12T15:44:42.224Z,1718207082.224 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-06-12T15:44:42.225Z,1718207082.225 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-06-12T15:44:42.225Z,1718207082.225 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-06-12T15:44:42.366Z,1718207082.366 [SBIT](DEBUG): Construct Startup Built In Test.
2024-06-12T15:44:42.375Z,1718207082.375 [SBIT] Loaded
2024-06-12T15:44:42.375Z,1718207082.375 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-06-12T15:44:42.378Z,1718207082.378 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-06-12T15:44:42.391Z,1718207082.391 [IBIT] Loaded
2024-06-12T15:44:42.391Z,1718207082.391 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-06-12T15:44:42.397Z,1718207082.397 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-06-12T15:44:42.503Z,1718207082.503 [CBIT] Loaded
2024-06-12T15:44:42.503Z,1718207082.503 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-06-12T15:44:42.504Z,1718207082.504 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-06-12T15:44:42.504Z,1718207082.504 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-06-12T15:44:42.638Z,1718207082.638 [BuoyancyServo] Loaded
2024-06-12T15:44:42.638Z,1718207082.638 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-06-12T15:44:42.661Z,1718207082.661 [ElevatorServo] Loaded
2024-06-12T15:44:42.661Z,1718207082.661 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-06-12T15:44:42.691Z,1718207082.691 [DockingStepper] Loaded
2024-06-12T15:44:42.691Z,1718207082.691 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2024-06-12T15:44:42.713Z,1718207082.713 [MassServo] Loaded
2024-06-12T15:44:42.713Z,1718207082.713 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-06-12T15:44:42.733Z,1718207082.733 [RudderServo] Loaded
2024-06-12T15:44:42.733Z,1718207082.733 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-06-12T15:44:42.752Z,1718207082.752 [ThrusterServo] Loaded
2024-06-12T15:44:42.752Z,1718207082.752 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2024-06-12T15:44:42.753Z,1718207082.753 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-06-12T15:44:42.753Z,1718207082.753 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-06-12T15:44:42.763Z,1718207082.763 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-06-12T15:44:42.764Z,1718207082.764 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-06-12T15:44:42.939Z,1718207082.939 [CTD_Seabird] Loaded
2024-06-12T15:44:42.939Z,1718207082.939 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-06-12T15:44:42.941Z,1718207082.941 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BD94E0
2024-06-12T15:44:42.941Z,1718207082.941 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1125
2024-06-12T15:44:42.963Z,1718207082.963 [PAR_Licor] Loaded
2024-06-12T15:44:42.964Z,1718207082.964 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-06-12T15:44:43.008Z,1718207083.008 [WetLabsBB2FL] Loaded
2024-06-12T15:44:43.008Z,1718207083.008 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-06-12T15:44:43.009Z,1718207083.009 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C094E0
2024-06-12T15:44:43.010Z,1718207083.010 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1126
2024-06-12T15:44:43.031Z,1718207083.031 [WetLabsUBAT] Loaded
2024-06-12T15:44:43.031Z,1718207083.031 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2024-06-12T15:44:43.032Z,1718207083.032 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C394E0
2024-06-12T15:44:43.032Z,1718207083.032 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1127
2024-06-12T15:44:43.033Z,1718207083.033 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-06-12T15:44:43.039Z,1718207083.039 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-06-12T15:44:43.042Z,1718207083.042 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-06-12T15:44:43.053Z,1718207083.053 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-06-12T15:44:43.054Z,1718207083.054 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0
2024-06-12T15:44:43.055Z,1718207083.055 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1128
2024-06-12T15:44:43.059Z,1718207083.059 [Supervisor](INFO): Main Thread ID is 830
2024-06-12T15:44:43.059Z,1718207083.059 [Supervisor](DEBUG): Running supervisor.
2024-06-12T15:44:43.060Z,1718207083.060 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1129
2024-06-12T15:44:43.060Z,1718207083.060 [CommandExec](INFO): Initializing the command executive.
2024-06-12T15:44:43.062Z,1718207083.062 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1130
2024-06-12T15:44:43.064Z,1718207083.064 [controlThread ThreadHandler](INFO): Handler Thread ID is 1131
2024-06-12T15:44:43.064Z,1718207083.064 [controlThread](DEBUG): Initializing ControlThread
2024-06-12T15:44:43.065Z,1718207083.065 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-06-12T15:44:43.067Z,1718207083.067 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-06-12T15:44:43.068Z,1718207083.068 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-06-12T15:44:43.068Z,1718207083.068 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-06-12T15:44:43.069Z,1718207083.069 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-06-12T15:44:43.069Z,1718207083.069 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-06-12T15:44:43.069Z,1718207083.069 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-06-12T15:44:43.070Z,1718207083.070 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-06-12T15:44:43.070Z,1718207083.070 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-06-12T15:44:43.076Z,1718207083.076 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-06-12T15:44:43.076Z,1718207083.076 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-06-12T15:44:43.077Z,1718207083.077 [SBIT](INFO): Initialize SBIT Component.
2024-06-12T15:44:43.077Z,1718207083.077 [SBIT](IMPORTANT): git: 2024-06-12_A
2024-06-12T15:44:43.077Z,1718207083.077 [SBIT](INFO): git hash: 2063aa74f31639117dffa677507b57c01ef77843
2024-06-12T15:44:43.078Z,1718207083.078 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-06-12T15:44:43.079Z,1718207083.079 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-06-12T15:44:43.080Z,1718207083.080 [SBIT](INFO): Beginning SBIT in 139.000000 seconds.
2024-06-12T15:44:43.080Z,1718207083.080 [IBIT](INFO): Initialize IBIT Component.
2024-06-12T15:44:43.081Z,1718207083.081 [CBIT](DEBUG): Initialize CBIT Component.
2024-06-12T15:44:43.082Z,1718207083.082 [logger ThreadHandler](INFO): Handler Thread ID is 1132
2024-06-12T15:44:43.094Z,1718207083.094 [CBIT](DEBUG): Initialized mux pins.
2024-06-12T15:44:43.094Z,1718207083.094 [CBIT](DEBUG): Initializing the watchdog timer.
2024-06-12T15:44:43.102Z,1718207083.102 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1133
2024-06-12T15:44:43.118Z,1718207083.118 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-06-12T15:44:43.118Z,1718207083.118 [CBIT](DEBUG): Initializing heartbeat.
2024-06-12T15:44:43.119Z,1718207083.119 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1134
2024-06-12T15:44:43.120Z,1718207083.120 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-06-12T15:44:43.123Z,1718207083.123 [Onboard ThreadHandler](INFO): Handler Thread ID is 1135
2024-06-12T15:44:43.143Z,1718207083.143 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1136
2024-06-12T15:44:43.162Z,1718207083.162 [DAT ThreadHandler](INFO): Handler Thread ID is 1137
2024-06-12T15:44:43.163Z,1718207083.163 [DAT](INFO): Powering up
2024-06-12T15:44:43.163Z,1718207083.163 [DAT](DEBUG): Initializing DAT.
2024-06-12T15:44:43.167Z,1718207083.167 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1139
2024-06-12T15:44:43.167Z,1718207083.167 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-06-12T15:44:43.171Z,1718207083.171 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1141
2024-06-12T15:44:43.174Z,1718207083.174 [WetLabsBB2FL](INFO): Powering up
2024-06-12T15:44:43.175Z,1718207083.175 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1143
2024-06-12T15:44:43.178Z,1718207083.178 [WetLabsUBAT](INFO): Powering up
2024-06-12T15:44:43.179Z,1718207083.179 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1145
2024-06-12T15:44:43.183Z,1718207083.183 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-06-12T15:44:43.183Z,1718207083.183 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-06-12T15:44:43.183Z,1718207083.183 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-06-12T15:44:43.183Z,1718207083.183 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-06-12T15:44:43.183Z,1718207083.183 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-06-12T15:44:43.184Z,1718207083.184 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-06-12T15:44:43.184Z,1718207083.184 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-06-12T15:44:43.184Z,1718207083.184 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-06-12T15:44:43.190Z,1718207083.190 [CBIT](DEBUG): Deactivating GF circuits.
2024-06-12T15:44:43.190Z,1718207083.190 [CBIT](DEBUG): Deactivating emergency mode.
2024-06-12T15:44:43.229Z,1718207083.229 [CBIT](DEBUG): Backplane powered.
2024-06-12T15:44:43.234Z,1718207083.234 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-06-12T15:44:43.235Z,1718207083.235 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl
2024-06-12T15:44:43.235Z,1718207083.235 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl
2024-06-12T15:44:43.236Z,1718207083.236 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl
2024-06-12T15:44:43.246Z,1718207083.246 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into:
2024-06-12T15:44:43.246Z,1718207083.246 [MissionManager](ERROR): Top node has no child nodes: Mission
2024-06-12T15:44:43.246Z,1718207083.246 [MissionManager](ERROR): Could not load startup mission.
2024-06-12T15:44:43.247Z,1718207083.247 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl
2024-06-12T15:44:43.247Z,1718207083.247 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl
2024-06-12T15:44:43.248Z,1718207083.248 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl
2024-06-12T15:44:43.550Z,1718207083.550 [Radio_Surface](INFO): Powering up
2024-06-12T15:44:43.802Z,1718207083.802 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/DefaultWithUndock.tl translated into:
0
30
2.5
1
30
Timed out while trying to detach from dock at current depth of
.
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-06-12T15:44:43.808Z,1718207083.808 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2024-06-12T15:44:43.832Z,1718207083.832 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min
2024-06-12T15:44:43.836Z,1718207083.836 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 201 count
2024-06-12T15:44:43.859Z,1718207083.859 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 2.500000 s
2024-06-12T15:44:43.864Z,1718207083.864 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2024-06-12T15:44:43.866Z,1718207083.866 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2024-06-12T15:44:44.025Z,1718207084.025 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2024-06-12T15:44:44.026Z,1718207084.026 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2024-06-12T15:44:44.077Z,1718207084.077 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-06-12T15:44:44.102Z,1718207084.102 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-06-12T15:44:44.105Z,1718207084.105 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-06-12T15:44:44.128Z,1718207084.128 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2024-06-12T15:44:44.148Z,1718207084.148 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission DefaultWithUndock {
arguments {
ElapsedSinceDefaultStarted = 0 minute
DetachTimeout = 30 minute
"""
Maximum time duration for the vehicle to detach form the dock.
"""
TransponderCode = Dock:Dock.transponderCode
"""
Dock transponder address.
"""
TrackingUpdatePeriod = 2.5 second
"""
Time duration between acoustic range queries while the mission is
detaching from the dock.
"""
NumberOfPings = 1 count
"""
Number of pings requested each time.
"""
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 30 second
}
aggregate LeaveDock {
run in sequence
timeout duration=DetachTimeout {
syslog important "Timed out while trying to detach from dock at current depth of "
+ Universal:depth~meter + "."
}
# Acoustic tracking directives
behavior Estimation:TrackAcousticContact {
run in parallel
set contactLabelSetting = TransponderCode
set numberOfSamplesSetting = NumberOfPings
set updatePeriodSetting = TrackingUpdatePeriod
}
# Leave the dock
behavior Dock:Undock {
run in sequence
}
}
aggregate SurfaceDefault {
run in sequence
# This mission assumes open water so from here on it's the same old default that's always used
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P2H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-06-12T15:44:44.152Z,1718207084.152 [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-06-12T15:44:44.179Z,1718207084.179 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-06-12T15:44:44.247Z,1718207084.247 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993022 m
2024-06-12T15:44:44.248Z,1718207084.248 [Power24vConverter](INFO): Powering up.
2024-06-12T15:44:44.249Z,1718207084.249 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2024-06-12T15:44:44.298Z,1718207084.298 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-06-12T15:44:44.315Z,1718207084.315 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-06-12T15:44:44.322Z,1718207084.322 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-06-12T15:44:44.323Z,1718207084.323 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-06-12T15:44:44.330Z,1718207084.330 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-06-12T15:44:44.331Z,1718207084.331 [DockingStepper](INFO): Start
2024-06-12T15:44:44.331Z,1718207084.331 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-06-12T15:44:44.338Z,1718207084.338 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-06-12T15:44:44.339Z,1718207084.339 [MassServo](DEBUG): Initializing EZServoServo.
2024-06-12T15:44:44.346Z,1718207084.346 [MassServo](DEBUG): Initializing MassServo.
2024-06-12T15:44:44.347Z,1718207084.347 [RudderServo](DEBUG): Initializing EZServoServo.
2024-06-12T15:44:44.354Z,1718207084.354 [RudderServo](DEBUG): Initializing RudderServo.
2024-06-12T15:44:44.355Z,1718207084.355 [ThrusterServo](DEBUG): Initializing EZServoServo.
2024-06-12T15:44:44.362Z,1718207084.362 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2024-06-12T15:44:44.630Z,1718207084.630 [DockingStepper](INFO): Starting
2024-06-12T15:44:44.993Z,1718207084.993 [DockingStepper](INFO): Starting
2024-06-12T15:44:45.514Z,1718207085.514 [DockingStepper](INFO): Starting
2024-06-12T15:44:45.562Z,1718207085.562 [DockingStepper](INFO): Init string: /1z16368aE13561N3m50
2024-06-12T15:44:45.781Z,1718207085.781 [DockingStepper](INFO): Starting
2024-06-12T15:44:45.790Z,1718207085.790 [WetLabsBB2FL](INFO): Powering down
2024-06-12T15:44:46.197Z,1718207086.197 [DockingStepper](INFO): Starting
2024-06-12T15:44:46.601Z,1718207086.601 [DockingStepper](INFO): Starting
2024-06-12T15:44:47.054Z,1718207087.054 [DockingStepper](INFO): Starting
2024-06-12T15:44:47.078Z,1718207087.078 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-06-12T15:44:47.517Z,1718207087.517 [DockingStepper](INFO): Starting
2024-06-12T15:44:47.966Z,1718207087.966 [DockingStepper](INFO): Commanding negative:9038
2024-06-12T15:44:48.803Z,1718207088.803 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2024-06-12T15:44:48.803Z,1718207088.803 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0
2024-06-12T15:44:49.219Z,1718207089.219 [DockingStepper](INFO): Pause
2024-06-12T15:44:52.442Z,1718207092.442 [WetLabsUBAT](INFO): Powering down
2024-06-12T15:44:55.780Z,1718207095.780 [DAT](INFO): DAT read:
2024-06-12T15:44:55.783Z,1718207095.783 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-06-12T15:44:57.543Z,1718207097.543 [DAT](INFO): DAT read: MF Frequency Band
2024-06-12T15:44:57.544Z,1718207097.544 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2
2024-06-12T15:44:57.544Z,1718207097.544 [DAT](INFO): DAT read: Jun 12 2024 15:44:50
2024-06-12T15:44:58.551Z,1718207098.551 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-06-12T15:44:58.552Z,1718207098.552 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-06-12T15:44:58.552Z,1718207098.552 [DAT](INFO): commRate: 800
2024-06-12T15:45:00.618Z,1718207100.618 [DAT](INFO): entering command mode
2024-06-12T15:45:00.818Z,1718207100.818 [DAT](INFO): DAT read:
2024-06-12T15:45:00.819Z,1718207100.819 [DAT](INFO): DAT read: user:1>
2024-06-12T15:45:00.819Z,1718207100.819 [DAT](INFO): setting verbose to 3
2024-06-12T15:45:01.072Z,1718207101.072 [DAT](INFO): DAT read: user:1>
2024-06-12T15:45:01.074Z,1718207101.074 [DAT](INFO): DAT read: Verbose | 3
2024-06-12T15:45:01.074Z,1718207101.074 [DAT](INFO): set verbose to 3
2024-06-12T15:45:01.074Z,1718207101.074 [DAT](INFO): setting DatVerbose to 27440
2024-06-12T15:45:01.322Z,1718207101.322 [DAT](INFO): DAT read: user:2>
2024-06-12T15:45:01.323Z,1718207101.323 [DAT](INFO): DAT read: DatVerbose | 27440
2024-06-12T15:45:01.324Z,1718207101.324 [DAT](INFO): set DatVerbose to 27440
2024-06-12T15:45:01.324Z,1718207101.324 [DAT](INFO): setting transmit power to 8
2024-06-12T15:45:01.575Z,1718207101.575 [DAT](INFO): DAT read: user:3>
2024-06-12T15:45:01.576Z,1718207101.576 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-06-12T15:45:01.576Z,1718207101.576 [DAT](INFO): set transmit power to 8
2024-06-12T15:45:01.576Z,1718207101.576 [DAT](INFO): setting local address to 10
2024-06-12T15:45:01.826Z,1718207101.826 [DAT](INFO): DAT read: user:4>
2024-06-12T15:45:01.827Z,1718207101.827 [DAT](INFO): DAT read: LocalAddr | 10
2024-06-12T15:45:01.828Z,1718207101.828 [DAT](INFO): set local address to 10
2024-06-12T15:45:01.829Z,1718207101.829 [DAT](INFO): Setting time to: 15:45:1 And date to:6/12/2024
2024-06-12T15:45:02.078Z,1718207102.078 [DAT](INFO): DAT read: user:5>
2024-06-12T15:45:02.079Z,1718207102.079 [DAT](INFO): DAT read: Wed Jun 12, 2024 15:45:01
2024-06-12T15:45:02.079Z,1718207102.079 [DAT](INFO): Local DAT time set to Wed Jun 12, 2024 15:45:01
2024-06-12T15:45:11.795Z,1718207111.795 [NAL9602](INFO): Powering up NAL9602
2024-06-12T15:45:14.242Z,1718207114.242 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:45:22.667Z,1718207122.667 [NAL9602](INFO): NAL9602 initialized
2024-06-12T15:45:45.370Z,1718207145.370 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:45:52.558Z,1718207152.558 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-06-12T15:46:09.527Z,1718207169.527 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-06-12T15:46:16.494Z,1718207176.494 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:46:31.859Z,1718207191.859 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-06-12T15:46:47.622Z,1718207207.622 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:46:58.064Z,1718207218.064 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-06-12T15:47:02.483Z,1718207222.483 [SBIT](IMPORTANT): Beginning Startup BIT
2024-06-12T15:47:02.487Z,1718207222.487 [CBIT](IMPORTANT): Beginning ground fault scan
2024-06-12T15:47:13.427Z,1718207233.427 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.015148
CHAN A1 (24V): 0.001826
CHAN A2 (12V): -0.007833
CHAN A3 (5V): -0.001068
CHAN B0 (3.3V): 0.000108
CHAN B1 (3.15aV): 0.000552
CHAN B2 (3.15bV): 0.000263
CHAN B3 (GND): 0.002091
OPEN: 0.004546
Full Scale: +/- 1 mA
2024-06-12T15:47:18.746Z,1718207238.746 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:47:26.448Z,1718207246.448 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004708
2024-06-12T15:47:27.446Z,1718207247.446 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:47:36.042Z,1718207256.042 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:47:43.308Z,1718207263.308 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-06-12T15:47:43.308Z,1718207263.308 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-12T15:47:43.339Z,1718207263.339 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-12T15:47:43.692Z,1718207263.692 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-12T15:47:43.693Z,1718207263.693 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-06-12T15:47:44.492Z,1718207264.492 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:47:53.026Z,1718207273.026 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:47:56.232Z,1718207276.232 [SBIT](IMPORTANT): SBIT PASSED
2024-06-12T15:47:56.232Z,1718207276.232 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-06-12T15:47:56.233Z,1718207276.233 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;
2024-06-12T15:47:56.238Z,1718207276.238 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second;
2024-06-12T15:47:56.238Z,1718207276.238 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=300 cubic_centimeter;
2024-06-12T15:47:56.238Z,1718207276.238 [SBIT](IMPORTANT): VerticalControl.massDefault=30 millimeter;
2024-06-12T15:47:56.612Z,1718207276.612 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2024-06-12T15:47:56.613Z,1718207276.613 [DefaultWithUndock] Running Loop=1
2024-06-12T15:47:56.613Z,1718207276.613 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2024-06-12T15:47:56.613Z,1718207276.613 [DefaultWithUndock:A.Wait] Running Loop=1
2024-06-12T15:47:56.613Z,1718207276.613 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2024-06-12T15:48:01.494Z,1718207281.494 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:48:05.983Z,1718207285.983 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-06-12T15:48:09.898Z,1718207289.898 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:48:18.354Z,1718207298.354 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:48:18.904Z,1718207298.904 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-06-12T15:48:26.980Z,1718207306.980 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2024-06-12T15:48:26.980Z,1718207306.980 [DefaultWithUndock:A.Wait] Stopped
2024-06-12T15:48:26.980Z,1718207306.980 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2024-06-12T15:48:26.980Z,1718207306.980 [DefaultWithUndock:LeaveDock] Running Loop=1
2024-06-12T15:48:26.980Z,1718207306.980 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2024-06-12T15:48:26.980Z,1718207306.980 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2024-06-12T15:48:26.980Z,1718207306.980 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2024-06-12T15:48:26.981Z,1718207306.981 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2024-06-12T15:48:26.981Z,1718207306.981 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2024-06-12T15:48:26.981Z,1718207306.981 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2024-06-12T15:48:27.499Z,1718207307.499 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2024-06-12T15:48:27.506Z,1718207307.506 [DockingStepper](INFO): Resume
2024-06-12T15:48:27.926Z,1718207307.926 [DockingStepper](INFO): Resuming
2024-06-12T15:48:28.202Z,1718207308.202 [DockingStepper](INFO): Start
2024-06-12T15:48:28.202Z,1718207308.202 [DockingStepper](DEBUG): Initializing EZServoServo.
2024-06-12T15:48:28.321Z,1718207308.321 [DockingStepper](DEBUG): Initializing DockingStepper.
2024-06-12T15:48:28.608Z,1718207308.608 [DockingStepper](INFO): Starting
2024-06-12T15:48:28.994Z,1718207308.994 [DockingStepper](INFO): Starting
2024-06-12T15:48:29.346Z,1718207309.346 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:48:29.467Z,1718207309.467 [DockingStepper](INFO): Starting
2024-06-12T15:48:29.518Z,1718207309.518 [DockingStepper](INFO): Init string: /1z14960aE13561N3m50
2024-06-12T15:48:29.880Z,1718207309.880 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:48:29.894Z,1718207309.894 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:29.894Z,1718207309.894 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:29.895Z,1718207309.895 [DAT](INFO): setting remote address to 0
2024-06-12T15:48:29.920Z,1718207309.920 [DockingStepper](INFO): Starting
2024-06-12T15:48:30.146Z,1718207310.146 [DAT](INFO): DAT read: user:6>
2024-06-12T15:48:30.147Z,1718207310.147 [DAT](INFO): DAT read: RemoteAddr | 0
2024-06-12T15:48:30.148Z,1718207310.148 [DAT](INFO): set remote address to 0
2024-06-12T15:48:30.148Z,1718207310.148 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:30.148Z,1718207310.148 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:30.148Z,1718207310.148 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:30.311Z,1718207310.311 [DockingStepper](INFO): Starting
2024-06-12T15:48:30.398Z,1718207310.398 [DAT](INFO): DAT read: user:7>
2024-06-12T15:48:30.400Z,1718207310.400 [DAT](INFO): DAT read: Tx time:15:48:29.5151
2024-06-12T15:48:30.400Z,1718207310.400 [DAT](INFO): Ping request sent.
2024-06-12T15:48:30.400Z,1718207310.400 [DAT](INFO): transmitted an acoustic signal
2024-06-12T15:48:30.400Z,1718207310.400 [DAT](INFO): publishing transmit ping time
2024-06-12T15:48:30.401Z,1718207310.401 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000985
2024-06-12T15:48:30.651Z,1718207310.651 [DAT](INFO): DAT read: TxSync time:15:48:29.5143
2024-06-12T15:48:30.651Z,1718207310.651 [DAT](INFO): unknown deviceResponse_: TxSync time:15:48:29.5143
2024-06-12T15:48:30.652Z,1718207310.652 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251365
2024-06-12T15:48:30.659Z,1718207310.659 [DockingStepper](INFO): Starting
2024-06-12T15:48:30.903Z,1718207310.903 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503103
2024-06-12T15:48:31.070Z,1718207311.070 [DockingStepper](INFO): Starting
2024-06-12T15:48:31.094Z,1718207311.094 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2024-06-12T15:48:31.155Z,1718207311.155 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754525
2024-06-12T15:48:31.406Z,1718207311.406 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006047
2024-06-12T15:48:31.475Z,1718207311.475 [DockingStepper](INFO): Starting
2024-06-12T15:48:31.659Z,1718207311.659 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258480
2024-06-12T15:48:31.910Z,1718207311.910 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510129
2024-06-12T15:48:31.996Z,1718207311.996 [DockingStepper](INFO): Changing to mode: 3
2024-06-12T15:48:31.996Z,1718207311.996 [DockingStepper](INFO): Detach mode.
2024-06-12T15:48:32.050Z,1718207312.050 [DockingStepper](INFO): Commanding negative:45862
2024-06-12T15:48:32.166Z,1718207312.166 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.765551
2024-06-12T15:48:32.418Z,1718207312.418 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018185
2024-06-12T15:48:32.505Z,1718207312.505 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:48:32.671Z,1718207312.671 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.271051
2024-06-12T15:48:32.923Z,1718207312.923 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.523125
2024-06-12T15:48:33.174Z,1718207313.174 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774246
2024-06-12T15:48:33.427Z,1718207313.427 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.026371
2024-06-12T15:48:33.679Z,1718207313.679 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278885
2024-06-12T15:48:33.931Z,1718207313.931 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.530681
2024-06-12T15:48:34.183Z,1718207314.183 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.783177
2024-06-12T15:48:34.434Z,1718207314.434 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.034130
2024-06-12T15:48:34.688Z,1718207314.688 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.287368
2024-06-12T15:48:34.939Z,1718207314.939 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.538608
2024-06-12T15:48:35.190Z,1718207315.190 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.790222
2024-06-12T15:48:35.193Z,1718207315.193 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:48:35.443Z,1718207315.443 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.042416
2024-06-12T15:48:35.695Z,1718207315.695 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.294278
2024-06-12T15:48:35.947Z,1718207315.947 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.546330
2024-06-12T15:48:36.199Z,1718207316.199 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.798800
2024-06-12T15:48:36.451Z,1718207316.451 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.050466
2024-06-12T15:48:36.703Z,1718207316.703 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.302388
2024-06-12T15:48:36.955Z,1718207316.955 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.554594
2024-06-12T15:48:37.206Z,1718207317.206 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806269
2024-06-12T15:48:37.209Z,1718207317.209 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock.
2024-06-12T15:48:37.459Z,1718207317.459 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.058323
2024-06-12T15:48:37.711Z,1718207317.711 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.310572
2024-06-12T15:48:37.963Z,1718207317.963 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.562290
2024-06-12T15:48:38.154Z,1718207318.154 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:48:38.215Z,1718207318.215 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.814353
2024-06-12T15:48:38.467Z,1718207318.467 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.066275
2024-06-12T15:48:38.719Z,1718207318.719 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.318414
2024-06-12T15:48:38.971Z,1718207318.971 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.570308
2024-06-12T15:48:39.223Z,1718207319.223 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.822349
2024-06-12T15:48:39.475Z,1718207319.475 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.075024
2024-06-12T15:48:39.728Z,1718207319.728 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.328204
2024-06-12T15:48:39.979Z,1718207319.979 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.578316
2024-06-12T15:48:40.231Z,1718207320.231 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.830301
2024-06-12T15:48:40.482Z,1718207320.482 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:40.482Z,1718207320.482 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:40.483Z,1718207320.483 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:40.735Z,1718207320.735 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:40.735Z,1718207320.735 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:40.735Z,1718207320.735 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:40.986Z,1718207320.986 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:40.986Z,1718207320.986 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:40.987Z,1718207320.987 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:41.183Z,1718207321.183 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:48:41.238Z,1718207321.238 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:41.238Z,1718207321.238 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:41.239Z,1718207321.239 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:41.490Z,1718207321.490 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:41.490Z,1718207321.490 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:41.491Z,1718207321.491 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:41.745Z,1718207321.745 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:41.746Z,1718207321.746 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:41.747Z,1718207321.747 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:41.994Z,1718207321.994 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:41.994Z,1718207321.994 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:41.995Z,1718207321.995 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:42.246Z,1718207322.246 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:42.246Z,1718207322.246 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:42.247Z,1718207322.247 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:42.499Z,1718207322.499 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:42.499Z,1718207322.499 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:42.499Z,1718207322.499 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:42.751Z,1718207322.751 [DAT](INFO): DAT read: Response Not Received
2024-06-12T15:48:42.751Z,1718207322.751 [DAT](INFO): response not received
2024-06-12T15:48:42.751Z,1718207322.751 [DAT](INFO): DAT read: user:8>
2024-06-12T15:48:42.752Z,1718207322.752 [DAT](INFO): DAT read: Tx time:15:48:41.9653
2024-06-12T15:48:42.752Z,1718207322.752 [DAT](INFO): Ping request sent.
2024-06-12T15:48:42.752Z,1718207322.752 [DAT](INFO): transmitted an acoustic signal
2024-06-12T15:48:42.752Z,1718207322.752 [DAT](INFO): publishing transmit ping time
2024-06-12T15:48:42.753Z,1718207322.753 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000720
2024-06-12T15:48:43.007Z,1718207323.007 [DAT](INFO): DAT read: TxSync time:15:48:41.9645
2024-06-12T15:48:43.008Z,1718207323.008 [DAT](INFO): unknown deviceResponse_: TxSync time:15:48:41.9645
2024-06-12T15:48:43.008Z,1718207323.008 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.255780
2024-06-12T15:48:43.259Z,1718207323.259 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.506806
2024-06-12T15:48:43.510Z,1718207323.510 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.758067
2024-06-12T15:48:43.768Z,1718207323.768 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.014492
2024-06-12T15:48:44.014Z,1718207324.014 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.262191
2024-06-12T15:48:44.159Z,1718207324.159 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:48:44.267Z,1718207324.267 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.514341
2024-06-12T15:48:44.519Z,1718207324.519 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.766285
2024-06-12T15:48:44.771Z,1718207324.771 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018336
2024-06-12T15:48:45.023Z,1718207325.023 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270582
2024-06-12T15:48:45.275Z,1718207325.275 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.522245
2024-06-12T15:48:45.527Z,1718207325.527 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.775084
2024-06-12T15:48:45.781Z,1718207325.781 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.028190
2024-06-12T15:48:46.031Z,1718207326.031 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278348
2024-06-12T15:48:46.054Z,1718207326.054 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:48:46.284Z,1718207326.284 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.531209
2024-06-12T15:48:46.535Z,1718207326.535 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.782101
2024-06-12T15:48:46.787Z,1718207326.787 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.034357
2024-06-12T15:48:46.890Z,1718207326.890 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:48:47.040Z,1718207327.040 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.287731
2024-06-12T15:48:47.290Z,1718207327.290 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.538148
2024-06-12T15:48:47.543Z,1718207327.543 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.790303
2024-06-12T15:48:47.798Z,1718207327.798 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.044817
2024-06-12T15:48:48.047Z,1718207328.047 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.294256
2024-06-12T15:48:48.299Z,1718207328.299 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.546596
2024-06-12T15:48:48.551Z,1718207328.551 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.798599
2024-06-12T15:48:48.803Z,1718207328.803 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.050239
2024-06-12T15:48:49.055Z,1718207329.055 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.302538
2024-06-12T15:48:49.307Z,1718207329.307 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.554230
2024-06-12T15:48:49.482Z,1718207329.482 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:48:49.559Z,1718207329.559 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806260
2024-06-12T15:48:49.728Z,1718207329.728 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-12T15:48:49.812Z,1718207329.812 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.059250
2024-06-12T15:48:50.063Z,1718207330.063 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.311022
2024-06-12T15:48:50.315Z,1718207330.315 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.562401
2024-06-12T15:48:50.566Z,1718207330.566 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.814036
2024-06-12T15:48:50.819Z,1718207330.819 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.066844
2024-06-12T15:48:51.071Z,1718207331.071 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.318211
2024-06-12T15:48:51.323Z,1718207331.323 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.570210
2024-06-12T15:48:51.579Z,1718207331.579 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.826747
2024-06-12T15:48:51.826Z,1718207331.826 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.074151
2024-06-12T15:48:52.083Z,1718207332.083 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.330573
2024-06-12T15:48:52.240Z,1718207332.240 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:48:52.331Z,1718207332.331 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.578868
2024-06-12T15:48:52.582Z,1718207332.582 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.830164
2024-06-12T15:48:52.836Z,1718207332.836 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:52.836Z,1718207332.836 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:52.836Z,1718207332.836 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:53.087Z,1718207333.087 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:53.087Z,1718207333.087 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:53.087Z,1718207333.087 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:53.339Z,1718207333.339 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:53.339Z,1718207333.339 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:53.339Z,1718207333.339 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:53.591Z,1718207333.591 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:53.591Z,1718207333.591 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:53.591Z,1718207333.591 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:53.842Z,1718207333.842 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:53.842Z,1718207333.842 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:53.843Z,1718207333.843 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:54.094Z,1718207334.094 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:54.094Z,1718207334.094 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:54.095Z,1718207334.095 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:54.347Z,1718207334.347 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:54.347Z,1718207334.347 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:54.348Z,1718207334.348 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:54.598Z,1718207334.598 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:54.598Z,1718207334.598 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:54.599Z,1718207334.599 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:54.849Z,1718207334.849 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:48:54.851Z,1718207334.851 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:54.851Z,1718207334.851 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:54.851Z,1718207334.851 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:55.103Z,1718207335.103 [DAT](INFO): DAT read: Response Not Received
2024-06-12T15:48:55.103Z,1718207335.103 [DAT](INFO): response not received
2024-06-12T15:48:55.103Z,1718207335.103 [DAT](INFO): DAT read: user:9>
2024-06-12T15:48:55.104Z,1718207335.104 [DAT](ERROR): No response from remote modem.
2024-06-12T15:48:55.104Z,1718207335.104 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:48:55.104Z,1718207335.104 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:48:55.104Z,1718207335.104 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:48:55.355Z,1718207335.355 [DAT](INFO): DAT read: Tx time:15:48:54.4155
2024-06-12T15:48:55.356Z,1718207335.356 [DAT](INFO): Ping request sent.
2024-06-12T15:48:55.356Z,1718207335.356 [DAT](INFO): transmitted an acoustic signal
2024-06-12T15:48:55.357Z,1718207335.357 [DAT](INFO): DAT read: TxSync time:15:48:54.4147
2024-06-12T15:48:55.357Z,1718207335.357 [DAT](INFO): unknown deviceResponse_: TxSync time:15:48:54.4147
2024-06-12T15:48:55.357Z,1718207335.357 [DAT](INFO): publishing transmit ping time
2024-06-12T15:48:55.358Z,1718207335.358 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.002308
2024-06-12T15:48:55.609Z,1718207335.609 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252503
2024-06-12T15:48:55.858Z,1718207335.858 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502604
2024-06-12T15:48:56.111Z,1718207336.111 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755325
2024-06-12T15:48:56.362Z,1718207336.362 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006697
2024-06-12T15:48:56.615Z,1718207336.615 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258756
2024-06-12T15:48:56.867Z,1718207336.867 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510921
2024-06-12T15:48:57.119Z,1718207337.119 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762740
2024-06-12T15:48:57.370Z,1718207337.370 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014652
2024-06-12T15:48:57.592Z,1718207337.592 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:48:57.622Z,1718207337.622 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266668
2024-06-12T15:48:57.874Z,1718207337.874 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518578
2024-06-12T15:48:58.127Z,1718207338.127 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771036
2024-06-12T15:48:58.380Z,1718207338.380 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024005
2024-06-12T15:48:58.631Z,1718207338.631 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274744
2024-06-12T15:48:58.838Z,1718207338.838 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:48:58.882Z,1718207338.882 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526684
2024-06-12T15:48:59.135Z,1718207339.135 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778989
2024-06-12T15:48:59.387Z,1718207339.387 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030803
2024-06-12T15:48:59.639Z,1718207339.639 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282850
2024-06-12T15:48:59.891Z,1718207339.891 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534851
2024-06-12T15:49:00.143Z,1718207340.143 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.787701
2024-06-12T15:49:00.394Z,1718207340.394 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038646
2024-06-12T15:49:00.416Z,1718207340.416 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:00.647Z,1718207340.647 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.291191
2024-06-12T15:49:00.899Z,1718207340.899 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.543019
2024-06-12T15:49:01.151Z,1718207341.151 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794769
2024-06-12T15:49:01.403Z,1718207341.403 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.047341
2024-06-12T15:49:01.655Z,1718207341.655 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298764
2024-06-12T15:49:01.907Z,1718207341.907 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550719
2024-06-12T15:49:02.159Z,1718207342.159 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802810
2024-06-12T15:49:02.411Z,1718207342.411 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054871
2024-06-12T15:49:02.663Z,1718207342.663 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.307016
2024-06-12T15:49:02.915Z,1718207342.915 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558910
2024-06-12T15:49:03.167Z,1718207343.167 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810824
2024-06-12T15:49:03.280Z,1718207343.280 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:03.419Z,1718207343.419 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.063197
2024-06-12T15:49:03.671Z,1718207343.671 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314739
2024-06-12T15:49:03.923Z,1718207343.923 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566826
2024-06-12T15:49:04.175Z,1718207344.175 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.819204
2024-06-12T15:49:04.426Z,1718207344.426 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070545
2024-06-12T15:49:04.679Z,1718207344.679 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322840
2024-06-12T15:49:04.931Z,1718207344.931 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.575021
2024-06-12T15:49:05.183Z,1718207345.183 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.827108
2024-06-12T15:49:05.232Z,1718207345.232 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-12T15:49:05.438Z,1718207345.438 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:05.438Z,1718207345.438 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:05.439Z,1718207345.439 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:05.686Z,1718207345.686 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:05.687Z,1718207345.687 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:05.687Z,1718207345.687 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:05.796Z,1718207345.796 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:05.939Z,1718207345.939 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:05.939Z,1718207345.939 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:05.939Z,1718207345.939 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:06.190Z,1718207346.190 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:06.190Z,1718207346.190 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:06.191Z,1718207346.191 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:06.442Z,1718207346.442 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:06.443Z,1718207346.443 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:06.443Z,1718207346.443 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:06.695Z,1718207346.695 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:06.695Z,1718207346.695 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:06.695Z,1718207346.695 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:06.946Z,1718207346.946 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:06.947Z,1718207346.947 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:06.947Z,1718207346.947 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:07.198Z,1718207347.198 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:07.198Z,1718207347.198 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:07.199Z,1718207347.199 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:07.451Z,1718207347.451 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:07.451Z,1718207347.451 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:07.451Z,1718207347.451 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:07.703Z,1718207347.703 [DAT](INFO): DAT read: Response Not Received
2024-06-12T15:49:07.703Z,1718207347.703 [DAT](INFO): response not received
2024-06-12T15:49:07.703Z,1718207347.703 [DAT](INFO): DAT read: user:10>
2024-06-12T15:49:07.704Z,1718207347.704 [DAT](INFO): DAT read: Tx time:15:49:06.8656
2024-06-12T15:49:07.704Z,1718207347.704 [DAT](INFO): Ping request sent.
2024-06-12T15:49:07.704Z,1718207347.704 [DAT](INFO): transmitted an acoustic signal
2024-06-12T15:49:07.704Z,1718207347.704 [DAT](INFO): publishing transmit ping time
2024-06-12T15:49:07.705Z,1718207347.705 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000706
2024-06-12T15:49:07.955Z,1718207347.955 [DAT](INFO): DAT read: TxSync time:15:49:06.8648
2024-06-12T15:49:07.955Z,1718207347.955 [DAT](INFO): unknown deviceResponse_: TxSync time:15:49:06.8648
2024-06-12T15:49:07.956Z,1718207347.956 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251465
2024-06-12T15:49:08.206Z,1718207348.206 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502055
2024-06-12T15:49:08.459Z,1718207348.459 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754323
2024-06-12T15:49:08.575Z,1718207348.575 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:08.711Z,1718207348.711 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006942
2024-06-12T15:49:08.963Z,1718207348.963 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258372
2024-06-12T15:49:09.214Z,1718207349.214 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510176
2024-06-12T15:49:09.467Z,1718207349.467 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762411
2024-06-12T15:49:09.718Z,1718207349.718 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014166
2024-06-12T15:49:09.970Z,1718207349.970 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266122
2024-06-12T15:49:10.225Z,1718207350.225 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.520348
2024-06-12T15:49:10.474Z,1718207350.474 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770153
2024-06-12T15:49:10.727Z,1718207350.727 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022273
2024-06-12T15:49:10.981Z,1718207350.981 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.276292
2024-06-12T15:49:11.230Z,1718207351.230 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526157
2024-06-12T15:49:11.361Z,1718207351.361 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:11.483Z,1718207351.483 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778577
2024-06-12T15:49:11.734Z,1718207351.734 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030145
2024-06-12T15:49:11.987Z,1718207351.987 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282220
2024-06-12T15:49:12.239Z,1718207352.239 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534278
2024-06-12T15:49:12.450Z,1718207352.450 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:49:12.491Z,1718207352.491 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786412
2024-06-12T15:49:12.743Z,1718207352.743 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038965
2024-06-12T15:49:12.994Z,1718207352.994 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290166
2024-06-12T15:49:13.246Z,1718207353.246 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542089
2024-06-12T15:49:13.499Z,1718207353.499 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794688
2024-06-12T15:49:13.752Z,1718207353.752 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.047784
2024-06-12T15:49:14.003Z,1718207354.003 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298818
2024-06-12T15:49:14.181Z,1718207354.181 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:14.255Z,1718207354.255 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550782
2024-06-12T15:49:14.506Z,1718207354.506 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802137
2024-06-12T15:49:14.759Z,1718207354.759 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054786
2024-06-12T15:49:15.011Z,1718207355.011 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306279
2024-06-12T15:49:15.262Z,1718207355.262 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558163
2024-06-12T15:49:15.516Z,1718207355.516 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.811258
2024-06-12T15:49:15.770Z,1718207355.770 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.064795
2024-06-12T15:49:16.019Z,1718207356.019 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314625
2024-06-12T15:49:16.271Z,1718207356.271 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566957
2024-06-12T15:49:16.523Z,1718207356.523 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818208
2024-06-12T15:49:16.779Z,1718207356.779 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.074681
2024-06-12T15:49:17.019Z,1718207357.019 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:17.027Z,1718207357.027 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322354
2024-06-12T15:49:17.279Z,1718207357.279 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574219
2024-06-12T15:49:17.531Z,1718207357.531 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826715
2024-06-12T15:49:17.784Z,1718207357.784 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:17.784Z,1718207357.784 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:17.785Z,1718207357.785 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:18.035Z,1718207358.035 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:18.035Z,1718207358.035 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:18.035Z,1718207358.035 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:18.286Z,1718207358.286 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:18.286Z,1718207358.286 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:18.286Z,1718207358.286 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:18.538Z,1718207358.538 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:18.539Z,1718207358.539 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:18.539Z,1718207358.539 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:18.791Z,1718207358.791 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:18.791Z,1718207358.791 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:18.791Z,1718207358.791 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:19.042Z,1718207359.042 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:19.042Z,1718207359.042 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:19.042Z,1718207359.042 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:19.294Z,1718207359.294 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:19.294Z,1718207359.294 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:19.295Z,1718207359.295 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:19.547Z,1718207359.547 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:19.547Z,1718207359.547 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:19.548Z,1718207359.548 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:19.800Z,1718207359.800 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:19.800Z,1718207359.800 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:19.801Z,1718207359.801 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:19.861Z,1718207359.861 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, .00
2024-06-12T15:49:19.883Z,1718207359.883 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:20.051Z,1718207360.051 [DAT](INFO): DAT read: Response Not Received
2024-06-12T15:49:20.051Z,1718207360.051 [DAT](INFO): response not received
2024-06-12T15:49:20.051Z,1718207360.051 [DAT](INFO): DAT read: user:11>
2024-06-12T15:49:20.052Z,1718207360.052 [DAT](INFO): DAT read: Tx time:15:49:19.3158
2024-06-12T15:49:20.052Z,1718207360.052 [DAT](INFO): Ping request sent.
2024-06-12T15:49:20.052Z,1718207360.052 [DAT](INFO): transmitted an acoustic signal
2024-06-12T15:49:20.053Z,1718207360.053 [DAT](INFO): publishing transmit ping time
2024-06-12T15:49:20.053Z,1718207360.053 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000751
2024-06-12T15:49:20.303Z,1718207360.303 [DAT](INFO): DAT read: TxSync time:15:49:19.3150
2024-06-12T15:49:20.303Z,1718207360.303 [DAT](INFO): unknown deviceResponse_: TxSync time:15:49:19.3150
2024-06-12T15:49:20.304Z,1718207360.304 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251127
2024-06-12T15:49:20.554Z,1718207360.554 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501952
2024-06-12T15:49:20.628Z,1718207360.628 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-12T15:49:20.807Z,1718207360.807 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754279
2024-06-12T15:49:21.058Z,1718207361.058 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006010
2024-06-12T15:49:21.312Z,1718207361.312 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259613
2024-06-12T15:49:21.563Z,1718207361.563 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510277
2024-06-12T15:49:21.814Z,1718207361.814 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762028
2024-06-12T15:49:21.926Z,1718207361.926 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:49:22.069Z,1718207362.069 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.016615
2024-06-12T15:49:22.324Z,1718207362.324 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.271268
2024-06-12T15:49:22.574Z,1718207362.574 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.521895
2024-06-12T15:49:22.677Z,1718207362.677 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:22.827Z,1718207362.827 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774363
2024-06-12T15:49:23.079Z,1718207363.079 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.026263
2024-06-12T15:49:23.330Z,1718207363.330 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278055
2024-06-12T15:49:23.583Z,1718207363.583 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.530372
2024-06-12T15:49:23.834Z,1718207363.834 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.781924
2024-06-12T15:49:24.087Z,1718207364.087 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.034325
2024-06-12T15:49:24.338Z,1718207364.338 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.285996
2024-06-12T15:49:24.590Z,1718207364.590 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.537912
2024-06-12T15:49:24.842Z,1718207364.842 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.789993
2024-06-12T15:49:25.094Z,1718207365.094 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.041979
2024-06-12T15:49:25.347Z,1718207365.347 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.294128
2024-06-12T15:49:25.528Z,1718207365.528 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:25.600Z,1718207365.600 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.547164
2024-06-12T15:49:25.850Z,1718207365.850 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.797933
2024-06-12T15:49:26.103Z,1718207366.103 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.050334
2024-06-12T15:49:26.355Z,1718207366.355 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.302125
2024-06-12T15:49:26.606Z,1718207366.606 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.553992
2024-06-12T15:49:26.859Z,1718207366.859 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806882
2024-06-12T15:49:27.110Z,1718207367.110 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.058069
2024-06-12T15:49:27.363Z,1718207367.363 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.310465
2024-06-12T15:49:27.615Z,1718207367.615 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.562082
2024-06-12T15:49:27.866Z,1718207367.866 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.813963
2024-06-12T15:49:28.119Z,1718207368.119 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.066460
2024-06-12T15:49:28.374Z,1718207368.374 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.321611
2024-06-12T15:49:28.428Z,1718207368.428 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:28.623Z,1718207368.623 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.570495
2024-06-12T15:49:28.875Z,1718207368.875 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.822312
2024-06-12T15:49:29.126Z,1718207369.126 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.074028
2024-06-12T15:49:29.379Z,1718207369.379 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.326278
2024-06-12T15:49:29.631Z,1718207369.631 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.578155
2024-06-12T15:49:29.882Z,1718207369.882 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.829990
2024-06-12T15:49:30.135Z,1718207370.135 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:30.135Z,1718207370.135 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:30.135Z,1718207370.135 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:30.386Z,1718207370.386 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:30.386Z,1718207370.386 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:30.387Z,1718207370.387 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:30.639Z,1718207370.639 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:30.640Z,1718207370.640 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:30.640Z,1718207370.640 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:30.891Z,1718207370.891 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:30.891Z,1718207370.891 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:30.891Z,1718207370.891 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:31.142Z,1718207371.142 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:31.142Z,1718207371.142 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:31.142Z,1718207371.142 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:31.198Z,1718207371.198 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:31.394Z,1718207371.394 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:31.394Z,1718207371.394 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:31.395Z,1718207371.395 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:31.647Z,1718207371.647 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:31.647Z,1718207371.647 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:31.647Z,1718207371.647 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:31.898Z,1718207371.898 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:31.898Z,1718207371.898 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:31.899Z,1718207371.899 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:32.151Z,1718207372.151 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:32.151Z,1718207372.151 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:32.151Z,1718207372.151 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:32.405Z,1718207372.405 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:32.405Z,1718207372.405 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:32.405Z,1718207372.405 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:32.532Z,1718207372.532 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:49:32.655Z,1718207372.655 [DAT](INFO): DAT read: Response Not Received
2024-06-12T15:49:32.655Z,1718207372.655 [DAT](INFO): response not received
2024-06-12T15:49:32.655Z,1718207372.655 [DAT](INFO): DAT read: user:12>
2024-06-12T15:49:32.656Z,1718207372.656 [DAT](INFO): DAT read: Tx time:15:49:31.7660
2024-06-12T15:49:32.656Z,1718207372.656 [DAT](INFO): Ping request sent.
2024-06-12T15:49:32.656Z,1718207372.656 [DAT](INFO): transmitted an acoustic signal
2024-06-12T15:49:32.657Z,1718207372.657 [DAT](INFO): publishing transmit ping time
2024-06-12T15:49:32.657Z,1718207372.657 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000711
2024-06-12T15:49:32.907Z,1718207372.907 [DAT](INFO): DAT read: TxSync time:15:49:31.7652
2024-06-12T15:49:32.908Z,1718207372.908 [DAT](INFO): unknown deviceResponse_: TxSync time:15:49:31.7652
2024-06-12T15:49:32.908Z,1718207372.908 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251525
2024-06-12T15:49:33.158Z,1718207373.158 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501895
2024-06-12T15:49:33.411Z,1718207373.411 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754165
2024-06-12T15:49:33.662Z,1718207373.662 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005748
2024-06-12T15:49:33.916Z,1718207373.916 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259232
2024-06-12T15:49:34.048Z,1718207374.048 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:34.167Z,1718207374.167 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510303
2024-06-12T15:49:34.419Z,1718207374.419 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762093
2024-06-12T15:49:34.671Z,1718207374.671 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013992
2024-06-12T15:49:34.922Z,1718207374.922 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.265935
2024-06-12T15:49:35.174Z,1718207375.174 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517880
2024-06-12T15:49:35.427Z,1718207375.427 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770845
2024-06-12T15:49:35.678Z,1718207375.678 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021865
2024-06-12T15:49:35.730Z,1718207375.730 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-06-12T15:49:35.930Z,1718207375.930 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273923
2024-06-12T15:49:36.182Z,1718207376.182 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.525806
2024-06-12T15:49:36.190Z,1718207376.190 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-12T15:49:36.435Z,1718207376.435 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778257
2024-06-12T15:49:36.640Z,1718207376.640 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:36.687Z,1718207376.687 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030115
2024-06-12T15:49:36.939Z,1718207376.939 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.281949
2024-06-12T15:49:37.190Z,1718207377.190 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.533905
2024-06-12T15:49:37.443Z,1718207377.443 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786172
2024-06-12T15:49:37.695Z,1718207377.695 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.037949
2024-06-12T15:49:37.946Z,1718207377.946 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.289920
2024-06-12T15:49:38.198Z,1718207378.198 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.541700
2024-06-12T15:49:38.450Z,1718207378.450 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.793943
2024-06-12T15:49:38.702Z,1718207378.702 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.045935
2024-06-12T15:49:38.954Z,1718207378.954 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.297900
2024-06-12T15:49:39.207Z,1718207379.207 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550093
2024-06-12T15:49:39.459Z,1718207379.459 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.801975
2024-06-12T15:49:39.461Z,1718207379.461 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:39.711Z,1718207379.711 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.053991
2024-06-12T15:49:39.963Z,1718207379.963 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306635
2024-06-12T15:49:40.215Z,1718207380.215 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.557952
2024-06-12T15:49:40.466Z,1718207380.466 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.809830
2024-06-12T15:49:40.719Z,1718207380.719 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062036
2024-06-12T15:49:40.970Z,1718207380.970 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.313807
2024-06-12T15:49:41.223Z,1718207381.223 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566308
2024-06-12T15:49:41.475Z,1718207381.475 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818067
2024-06-12T15:49:41.728Z,1718207381.728 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.071578
2024-06-12T15:49:41.978Z,1718207381.978 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.321827
2024-06-12T15:49:41.990Z,1718207381.990 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:49:42.230Z,1718207382.230 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.573926
2024-06-12T15:49:42.270Z,1718207382.270 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:42.482Z,1718207382.482 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.825872
2024-06-12T15:49:42.735Z,1718207382.735 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:42.735Z,1718207382.735 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:42.735Z,1718207382.735 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:42.986Z,1718207382.986 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:42.986Z,1718207382.986 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:42.987Z,1718207382.987 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:43.239Z,1718207383.239 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:43.239Z,1718207383.239 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:43.239Z,1718207383.239 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:43.490Z,1718207383.490 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:43.491Z,1718207383.491 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:43.491Z,1718207383.491 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:43.744Z,1718207383.744 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:43.744Z,1718207383.744 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:43.745Z,1718207383.745 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:43.997Z,1718207383.997 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:44.022Z,1718207384.022 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:44.022Z,1718207384.022 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:44.246Z,1718207384.246 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:44.246Z,1718207384.246 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:44.247Z,1718207384.247 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:44.499Z,1718207384.499 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:44.499Z,1718207384.499 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:44.499Z,1718207384.499 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:44.750Z,1718207384.750 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:44.750Z,1718207384.750 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:44.751Z,1718207384.751 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:45.003Z,1718207385.003 [DAT](INFO): DAT read: Response Not Received
2024-06-12T15:49:45.003Z,1718207385.003 [DAT](INFO): response not received
2024-06-12T15:49:45.003Z,1718207385.003 [DAT](INFO): DAT read: user:13>
2024-06-12T15:49:45.004Z,1718207385.004 [DAT](INFO): DAT read: Tx time:15:49:44.2162
2024-06-12T15:49:45.004Z,1718207385.004 [DAT](INFO): Ping request sent.
2024-06-12T15:49:45.004Z,1718207385.004 [DAT](INFO): transmitted an acoustic signal
2024-06-12T15:49:45.005Z,1718207385.005 [DAT](INFO): publishing transmit ping time
2024-06-12T15:49:45.005Z,1718207385.005 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000699
2024-06-12T15:49:45.125Z,1718207385.125 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:45.259Z,1718207385.259 [DAT](INFO): DAT read: TxSync time:15:49:44.2154
2024-06-12T15:49:45.259Z,1718207385.259 [DAT](INFO): unknown deviceResponse_: TxSync time:15:49:44.2154
2024-06-12T15:49:45.260Z,1718207385.260 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.255287
2024-06-12T15:49:45.507Z,1718207385.507 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502105
2024-06-12T15:49:45.762Z,1718207385.762 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.757473
2024-06-12T15:49:46.010Z,1718207386.010 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005997
2024-06-12T15:49:46.262Z,1718207386.262 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.257916
2024-06-12T15:49:46.515Z,1718207386.515 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510725
2024-06-12T15:49:46.766Z,1718207386.766 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762047
2024-06-12T15:49:47.019Z,1718207387.019 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014060
2024-06-12T15:49:47.271Z,1718207387.271 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266300
2024-06-12T15:49:47.524Z,1718207387.524 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519232
2024-06-12T15:49:47.777Z,1718207387.777 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771684
2024-06-12T15:49:48.026Z,1718207388.026 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022021
2024-06-12T15:49:48.046Z,1718207388.046 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:48.279Z,1718207388.279 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274211
2024-06-12T15:49:48.531Z,1718207388.531 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526696
2024-06-12T15:49:48.783Z,1718207388.783 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778195
2024-06-12T15:49:49.036Z,1718207389.036 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031452
2024-06-12T15:49:49.286Z,1718207389.286 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282012
2024-06-12T15:49:49.539Z,1718207389.539 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534058
2024-06-12T15:49:49.793Z,1718207389.793 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.787940
2024-06-12T15:49:50.042Z,1718207390.042 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.037946
2024-06-12T15:49:50.294Z,1718207390.294 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.289933
2024-06-12T15:49:50.547Z,1718207390.547 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542073
2024-06-12T15:49:50.798Z,1718207390.798 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.793934
2024-06-12T15:49:50.901Z,1718207390.901 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:51.063Z,1718207391.063 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.058138
2024-06-12T15:49:51.302Z,1718207391.302 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298009
2024-06-12T15:49:51.555Z,1718207391.555 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550559
2024-06-12T15:49:51.677Z,1718207391.677 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-12T15:49:51.809Z,1718207391.809 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.803585
2024-06-12T15:49:52.059Z,1718207392.059 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054196
2024-06-12T15:49:52.311Z,1718207392.311 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306233
2024-06-12T15:49:52.563Z,1718207392.563 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558377
2024-06-12T15:49:52.814Z,1718207392.814 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810025
2024-06-12T15:49:53.067Z,1718207393.067 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062984
2024-06-12T15:49:53.322Z,1718207393.322 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.317407
2024-06-12T15:49:53.571Z,1718207393.571 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566170
2024-06-12T15:49:53.698Z,1718207393.698 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:49:53.729Z,1718207393.729 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:53.826Z,1718207393.826 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.821882
2024-06-12T15:49:54.079Z,1718207394.079 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.074075
2024-06-12T15:49:54.331Z,1718207394.331 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.326689
2024-06-12T15:49:54.583Z,1718207394.583 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.578893
2024-06-12T15:49:54.835Z,1718207394.835 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.830180
2024-06-12T15:49:55.086Z,1718207395.086 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:55.087Z,1718207395.087 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:55.087Z,1718207395.087 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:55.338Z,1718207395.338 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:55.338Z,1718207395.338 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:55.339Z,1718207395.339 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:55.590Z,1718207395.590 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:55.590Z,1718207395.590 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:55.591Z,1718207395.591 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:55.842Z,1718207395.842 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:55.842Z,1718207395.842 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:55.843Z,1718207395.843 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:56.098Z,1718207396.098 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:56.098Z,1718207396.098 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:56.099Z,1718207396.099 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:56.351Z,1718207396.351 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:56.351Z,1718207396.351 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:56.351Z,1718207396.351 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:56.602Z,1718207396.602 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:56.602Z,1718207396.602 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:56.603Z,1718207396.603 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:56.674Z,1718207396.674 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:56.858Z,1718207396.858 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:56.858Z,1718207396.858 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:56.860Z,1718207396.860 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:57.106Z,1718207397.106 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:57.106Z,1718207397.106 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:57.107Z,1718207397.107 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:57.359Z,1718207397.359 [DAT](INFO): DAT read: Response Not Received
2024-06-12T15:49:57.359Z,1718207397.359 [DAT](INFO): response not received
2024-06-12T15:49:57.359Z,1718207397.359 [DAT](INFO): DAT read: user:14>
2024-06-12T15:49:57.360Z,1718207397.360 [DAT](ERROR): No response from remote modem.
2024-06-12T15:49:57.360Z,1718207397.360 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:49:57.360Z,1718207397.360 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:49:57.360Z,1718207397.360 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:49:57.611Z,1718207397.611 [DAT](INFO): DAT read: Tx time:15:49:56.6664
2024-06-12T15:49:57.611Z,1718207397.611 [DAT](INFO): Ping request sent.
2024-06-12T15:49:57.611Z,1718207397.611 [DAT](INFO): transmitted an acoustic signal
2024-06-12T15:49:57.612Z,1718207397.612 [DAT](INFO): DAT read: TxSync time:15:49:56.6656
2024-06-12T15:49:57.612Z,1718207397.612 [DAT](INFO): unknown deviceResponse_: TxSync time:15:49:56.6656
2024-06-12T15:49:57.612Z,1718207397.612 [DAT](INFO): publishing transmit ping time
2024-06-12T15:49:57.613Z,1718207397.613 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001689
2024-06-12T15:49:57.862Z,1718207397.862 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251373
2024-06-12T15:49:58.115Z,1718207398.115 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.504138
2024-06-12T15:49:58.366Z,1718207398.366 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755417
2024-06-12T15:49:58.618Z,1718207398.618 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007337
2024-06-12T15:49:58.871Z,1718207398.871 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.260290
2024-06-12T15:49:59.122Z,1718207399.122 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511322
2024-06-12T15:49:59.374Z,1718207399.374 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763433
2024-06-12T15:49:59.537Z,1718207399.537 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:49:59.627Z,1718207399.627 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015725
2024-06-12T15:49:59.879Z,1718207399.879 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267496
2024-06-12T15:50:00.131Z,1718207400.131 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.520294
2024-06-12T15:50:00.383Z,1718207400.383 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771807
2024-06-12T15:50:00.635Z,1718207400.635 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023493
2024-06-12T15:50:00.887Z,1718207400.887 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275607
2024-06-12T15:50:01.139Z,1718207401.139 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.527573
2024-06-12T15:50:01.391Z,1718207401.391 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779485
2024-06-12T15:50:01.643Z,1718207401.643 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031975
2024-06-12T15:50:01.894Z,1718207401.894 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.283262
2024-06-12T15:50:02.019Z,1718207402.019 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-06-12T15:50:02.128Z,1718207402.128 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:50:02.146Z,1718207402.146 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.535413
2024-06-12T15:50:02.403Z,1718207402.403 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.791682
2024-06-12T15:50:02.655Z,1718207402.655 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.043282
2024-06-12T15:50:02.903Z,1718207402.903 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.291729
2024-06-12T15:50:03.155Z,1718207403.155 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.543523
2024-06-12T15:50:03.416Z,1718207403.416 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.803651
2024-06-12T15:50:03.662Z,1718207403.662 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.051420
2024-06-12T15:50:03.914Z,1718207403.914 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.303425
2024-06-12T15:50:04.167Z,1718207404.167 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.555629
2024-06-12T15:50:04.419Z,1718207404.419 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.807508
2024-06-12T15:50:04.671Z,1718207404.671 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.059499
2024-06-12T15:50:04.872Z,1718207404.872 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:50:04.923Z,1718207404.923 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.311733
2024-06-12T15:50:05.174Z,1718207405.174 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.563402
2024-06-12T15:50:05.427Z,1718207405.427 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.816138
2024-06-12T15:50:05.679Z,1718207405.679 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.067699
2024-06-12T15:50:05.930Z,1718207405.930 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.319395
2024-06-12T15:50:06.183Z,1718207406.183 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.572338
2024-06-12T15:50:06.434Z,1718207406.434 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.823276
2024-06-12T15:50:06.689Z,1718207406.689 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.077534
2024-06-12T15:50:06.938Z,1718207406.938 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.327415
2024-06-12T15:50:07.191Z,1718207407.191 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.579635
2024-06-12T15:50:07.234Z,1718207407.234 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-12T15:50:07.443Z,1718207407.443 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.831647
2024-06-12T15:50:07.688Z,1718207407.688 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:50:07.694Z,1718207407.694 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:07.694Z,1718207407.694 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:07.695Z,1718207407.695 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:07.947Z,1718207407.947 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:07.947Z,1718207407.947 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:07.947Z,1718207407.947 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:08.198Z,1718207408.198 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:08.198Z,1718207408.198 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:08.199Z,1718207408.199 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:08.370Z,1718207408.370 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:50:08.450Z,1718207408.450 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:08.450Z,1718207408.450 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:08.450Z,1718207408.450 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:08.702Z,1718207408.702 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:08.703Z,1718207408.703 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:08.703Z,1718207408.703 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:08.955Z,1718207408.955 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:08.955Z,1718207408.955 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:08.956Z,1718207408.956 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:09.206Z,1718207409.206 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:09.207Z,1718207409.207 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:09.207Z,1718207409.207 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:09.459Z,1718207409.459 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:09.459Z,1718207409.459 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:09.459Z,1718207409.459 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:09.710Z,1718207409.710 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:09.710Z,1718207409.710 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:09.711Z,1718207409.711 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:09.963Z,1718207409.963 [DAT](INFO): DAT read: Response Not Received
2024-06-12T15:50:09.963Z,1718207409.963 [DAT](INFO): response not received
2024-06-12T15:50:09.964Z,1718207409.964 [DAT](INFO): DAT read: user:15>
2024-06-12T15:50:09.964Z,1718207409.964 [DAT](INFO): DAT read: Tx time:15:50:09.1166
2024-06-12T15:50:09.965Z,1718207409.965 [DAT](INFO): Ping request sent.
2024-06-12T15:50:09.965Z,1718207409.965 [DAT](INFO): transmitted an acoustic signal
2024-06-12T15:50:09.965Z,1718207409.965 [DAT](INFO): publishing transmit ping time
2024-06-12T15:50:09.965Z,1718207409.965 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000692
2024-06-12T15:50:10.215Z,1718207410.215 [DAT](INFO): DAT read: TxSync time:15:50:09.1158
2024-06-12T15:50:10.215Z,1718207410.215 [DAT](INFO): unknown deviceResponse_: TxSync time:15:50:09.1158
2024-06-12T15:50:10.216Z,1718207410.216 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250940
2024-06-12T15:50:10.466Z,1718207410.466 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501823
2024-06-12T15:50:10.520Z,1718207410.520 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:50:10.719Z,1718207410.719 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753999
2024-06-12T15:50:10.970Z,1718207410.970 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005823
2024-06-12T15:50:11.222Z,1718207411.222 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.257837
2024-06-12T15:50:11.314Z,1718207411.314 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2024-06-12T15:50:11.474Z,1718207411.474 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509844
2024-06-12T15:50:11.728Z,1718207411.728 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763374
2024-06-12T15:50:11.979Z,1718207411.979 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014007
2024-06-12T15:50:12.230Z,1718207412.230 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.265815
2024-06-12T15:50:12.483Z,1718207412.483 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517897
2024-06-12T15:50:12.735Z,1718207412.735 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.769877
2024-06-12T15:50:12.987Z,1718207412.987 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022208
2024-06-12T15:50:13.239Z,1718207413.239 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273942
2024-06-12T15:50:13.379Z,1718207413.379 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:50:13.491Z,1718207413.491 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526565
2024-06-12T15:50:13.745Z,1718207413.745 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779777
2024-06-12T15:50:13.995Z,1718207413.995 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030120
2024-06-12T15:50:14.246Z,1718207414.246 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.281746
2024-06-12T15:50:14.499Z,1718207414.499 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.533939
2024-06-12T15:50:14.754Z,1718207414.754 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.789072
2024-06-12T15:50:15.002Z,1718207415.002 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.037736
2024-06-12T15:50:15.255Z,1718207415.255 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.289994
2024-06-12T15:50:15.507Z,1718207415.507 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.541983
2024-06-12T15:50:15.761Z,1718207415.761 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.796099
2024-06-12T15:50:15.973Z,1718207415.973 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:50:16.011Z,1718207416.011 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046358
2024-06-12T15:50:16.262Z,1718207416.262 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.297822
2024-06-12T15:50:16.515Z,1718207416.515 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.549828
2024-06-12T15:50:16.767Z,1718207416.767 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802147
2024-06-12T15:50:17.018Z,1718207417.018 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.053644
2024-06-12T15:50:17.271Z,1718207417.271 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.305981
2024-06-12T15:50:17.523Z,1718207417.523 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558510
2024-06-12T15:50:17.777Z,1718207417.777 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.811419
2024-06-12T15:50:18.027Z,1718207418.027 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062015
2024-06-12T15:50:18.278Z,1718207418.278 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.313781
2024-06-12T15:50:18.534Z,1718207418.534 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.568533
2024-06-12T15:50:18.651Z,1718207418.651 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:50:18.783Z,1718207418.783 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818170
2024-06-12T15:50:19.034Z,1718207419.034 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.069638
2024-06-12T15:50:19.299Z,1718207419.299 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.334642
2024-06-12T15:50:19.539Z,1718207419.539 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.573985
2024-06-12T15:50:19.793Z,1718207419.793 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.827863
2024-06-12T15:50:20.042Z,1718207420.042 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:20.043Z,1718207420.043 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:20.043Z,1718207420.043 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:20.294Z,1718207420.294 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:20.295Z,1718207420.295 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:20.295Z,1718207420.295 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:20.546Z,1718207420.546 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:20.546Z,1718207420.546 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:20.547Z,1718207420.547 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:20.799Z,1718207420.799 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:20.799Z,1718207420.799 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:20.799Z,1718207420.799 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:21.050Z,1718207421.050 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:21.050Z,1718207421.050 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:21.051Z,1718207421.051 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:21.186Z,1718207421.186 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:50:21.302Z,1718207421.302 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:21.302Z,1718207421.302 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:21.303Z,1718207421.303 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:21.501Z,1718207421.501 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:50:21.554Z,1718207421.554 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:21.554Z,1718207421.554 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:21.555Z,1718207421.555 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:21.808Z,1718207421.808 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:21.809Z,1718207421.809 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:21.809Z,1718207421.809 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:21.897Z,1718207421.897 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,-32768,-3278,V
2024-06-12T15:50:22.062Z,1718207422.062 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:22.063Z,1718207422.063 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:22.063Z,1718207422.063 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:22.311Z,1718207422.311 [DAT](INFO): DAT read: Response Not Received
2024-06-12T15:50:22.311Z,1718207422.311 [DAT](INFO): response not received
2024-06-12T15:50:22.311Z,1718207422.311 [DAT](INFO): DAT read: user:16>
2024-06-12T15:50:22.312Z,1718207422.312 [DAT](INFO): DAT read: Tx time:15:50:21.5667
2024-06-12T15:50:22.312Z,1718207422.312 [DAT](INFO): Ping request sent.
2024-06-12T15:50:22.312Z,1718207422.312 [DAT](INFO): transmitted an acoustic signal
2024-06-12T15:50:22.313Z,1718207422.313 [DAT](INFO): publishing transmit ping time
2024-06-12T15:50:22.313Z,1718207422.313 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000702
2024-06-12T15:50:22.563Z,1718207422.563 [DAT](INFO): DAT read: TxSync time:15:50:21.5660
2024-06-12T15:50:22.563Z,1718207422.563 [DAT](INFO): unknown deviceResponse_: TxSync time:15:50:21.5660
2024-06-12T15:50:22.564Z,1718207422.564 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251388
2024-06-12T15:50:22.671Z,1718207422.671 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-12T15:50:22.815Z,1718207422.815 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502491
2024-06-12T15:50:23.066Z,1718207423.066 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754042
2024-06-12T15:50:23.319Z,1718207423.319 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006328
2024-06-12T15:50:23.571Z,1718207423.571 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258225
2024-06-12T15:50:23.823Z,1718207423.823 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510241
2024-06-12T15:50:24.075Z,1718207424.075 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762445
2024-06-12T15:50:24.330Z,1718207424.330 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018089
2024-06-12T15:50:24.351Z,1718207424.351 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:50:24.583Z,1718207424.583 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270641
2024-06-12T15:50:24.835Z,1718207424.835 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.522272
2024-06-12T15:50:25.086Z,1718207425.086 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774021
2024-06-12T15:50:25.339Z,1718207425.339 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.026251
2024-06-12T15:50:25.594Z,1718207425.594 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.281171
2024-06-12T15:50:25.847Z,1718207425.847 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.534219
2024-06-12T15:50:25.911Z,1718207425.911 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-06-12T15:50:26.099Z,1718207426.099 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.786267
2024-06-12T15:50:26.351Z,1718207426.351 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.038198
2024-06-12T15:50:26.603Z,1718207426.603 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.290116
2024-06-12T15:50:26.855Z,1718207426.855 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.542175
2024-06-12T15:50:26.968Z,1718207426.968 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:50:27.111Z,1718207427.111 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.798082
2024-06-12T15:50:27.358Z,1718207427.358 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.046012
2024-06-12T15:50:27.614Z,1718207427.614 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.300840
2024-06-12T15:50:27.863Z,1718207427.863 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.550551
2024-06-12T15:50:28.115Z,1718207428.115 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.802311
2024-06-12T15:50:28.367Z,1718207428.367 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.054446
2024-06-12T15:50:28.619Z,1718207428.619 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.306226
2024-06-12T15:50:28.871Z,1718207428.871 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.558128
2024-06-12T15:50:29.123Z,1718207429.123 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.810376
2024-06-12T15:50:29.374Z,1718207429.374 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.061957
2024-06-12T15:50:29.627Z,1718207429.627 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.314137
2024-06-12T15:50:29.878Z,1718207429.878 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.566089
2024-06-12T15:50:29.928Z,1718207429.928 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:50:30.131Z,1718207430.131 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.818116
2024-06-12T15:50:30.387Z,1718207430.387 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.074150
2024-06-12T15:50:30.639Z,1718207430.639 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.326549
2024-06-12T15:50:30.891Z,1718207430.891 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.578126
2024-06-12T15:50:31.143Z,1718207431.143 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.830122
2024-06-12T15:50:31.395Z,1718207431.395 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.082165
2024-06-12T15:50:31.398Z,1718207431.398 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby.
2024-06-12T15:50:31.647Z,1718207431.647 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.334235
2024-06-12T15:50:31.899Z,1718207431.899 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.586126
2024-06-12T15:50:32.006Z,1718207432.006 [DockingStepper](INFO): Changing to mode: 1
2024-06-12T15:50:32.006Z,1718207432.006 [DockingStepper](INFO): Standby mode.
2024-06-12T15:50:32.070Z,1718207432.070 [DockingStepper](INFO): Commanding positive:54900
2024-06-12T15:50:32.155Z,1718207432.155 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.841764
2024-06-12T15:50:32.402Z,1718207432.402 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:32.403Z,1718207432.403 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:32.403Z,1718207432.403 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:32.654Z,1718207432.654 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:32.654Z,1718207432.654 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:32.654Z,1718207432.654 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:32.737Z,1718207432.737 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:50:32.907Z,1718207432.907 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:32.907Z,1718207432.907 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:32.907Z,1718207432.907 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:33.162Z,1718207433.162 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:33.162Z,1718207433.162 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:33.162Z,1718207433.162 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:33.415Z,1718207433.415 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:33.415Z,1718207433.415 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:33.415Z,1718207433.415 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:33.668Z,1718207433.668 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:33.668Z,1718207433.668 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:33.668Z,1718207433.668 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:33.918Z,1718207433.918 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:33.919Z,1718207433.918 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:33.919Z,1718207433.919 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:34.170Z,1718207434.170 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:34.171Z,1718207434.171 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:34.171Z,1718207434.171 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:34.423Z,1718207434.423 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:34.423Z,1718207434.423 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:34.423Z,1718207434.423 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:34.674Z,1718207434.674 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:34.675Z,1718207434.675 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:34.675Z,1718207434.675 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:34.927Z,1718207434.927 [DAT](INFO): DAT read: Response Not Received
2024-06-12T15:50:34.927Z,1718207434.927 [DAT](INFO): response not received
2024-06-12T15:50:34.928Z,1718207434.928 [DAT](INFO): DAT read: user:17>
2024-06-12T15:50:34.929Z,1718207434.929 [DAT](INFO): DAT read: Tx time:15:50:34.0169
2024-06-12T15:50:34.929Z,1718207434.929 [DAT](INFO): Ping request sent.
2024-06-12T15:50:34.929Z,1718207434.929 [DAT](INFO): transmitted an acoustic signal
2024-06-12T15:50:34.930Z,1718207434.930 [DAT](INFO): publishing transmit ping time
2024-06-12T15:50:34.930Z,1718207434.930 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000996
2024-06-12T15:50:35.179Z,1718207435.179 [DAT](INFO): DAT read: TxSync time:15:50:34.0162
2024-06-12T15:50:35.179Z,1718207435.179 [DAT](INFO): unknown deviceResponse_: TxSync time:15:50:34.0162
2024-06-12T15:50:35.180Z,1718207435.180 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250453
2024-06-12T15:50:35.186Z,1718207435.186 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:50:35.242Z,1718207435.242 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:50:35.431Z,1718207435.431 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501567
2024-06-12T15:50:35.682Z,1718207435.682 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753197
2024-06-12T15:50:35.936Z,1718207435.936 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006575
2024-06-12T15:50:36.187Z,1718207436.187 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.257522
2024-06-12T15:50:36.439Z,1718207436.439 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509400
2024-06-12T15:50:36.692Z,1718207436.692 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762049
2024-06-12T15:50:36.942Z,1718207436.942 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013340
2024-06-12T15:50:37.195Z,1718207437.195 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.265393
2024-06-12T15:50:37.447Z,1718207437.447 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518118
2024-06-12T15:50:37.698Z,1718207437.698 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.769194
2024-06-12T15:50:37.950Z,1718207437.950 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021282
2024-06-12T15:50:38.013Z,1718207438.013 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2024-06-12T15:50:38.203Z,1718207438.203 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273375
2024-06-12T15:50:38.289Z,1718207438.289 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-12T15:50:38.381Z,1718207438.381 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby.
2024-06-12T15:50:38.456Z,1718207438.456 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526553
2024-06-12T15:50:38.707Z,1718207438.707 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.777470
2024-06-12T15:50:38.740Z,1718207438.740 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2024-06-12T15:50:38.740Z,1718207438.740 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2024-06-12T15:50:38.741Z,1718207438.741 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2024-06-12T15:50:38.741Z,1718207438.741 [DefaultWithUndock:LeaveDock] Stopped
2024-06-12T15:50:38.741Z,1718207438.741 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2024-06-12T15:50:38.741Z,1718207438.741 [DefaultWithUndock:LeaveDock:A.] Stopped
2024-06-12T15:50:38.741Z,1718207438.741 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2024-06-12T15:50:38.741Z,1718207438.741 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2024-06-12T15:50:38.778Z,1718207438.778 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2024-06-12T15:50:38.778Z,1718207438.778 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1
2024-06-12T15:50:38.778Z,1718207438.778 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-06-12T15:50:38.779Z,1718207438.779 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-06-12T15:50:38.779Z,1718207438.779 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-06-12T15:50:38.780Z,1718207438.780 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-06-12T15:50:38.780Z,1718207438.780 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-06-12T15:50:38.780Z,1718207438.780 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-06-12T15:50:38.781Z,1718207438.781 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-06-12T15:50:38.960Z,1718207438.960 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031021
2024-06-12T15:50:39.167Z,1718207439.167 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2024-06-12T15:50:39.167Z,1718207439.167 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2024-06-12T15:50:39.167Z,1718207439.167 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2024-06-12T15:50:39.210Z,1718207439.210 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.281331
2024-06-12T15:50:39.215Z,1718207439.215 [DockingStepper](INFO): Pause
2024-06-12T15:50:39.463Z,1718207439.463 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.533406
2024-06-12T15:50:39.574Z,1718207439.574 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-06-12T15:50:39.715Z,1718207439.715 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.785412
2024-06-12T15:50:39.967Z,1718207439.967 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038138
2024-06-12T15:50:40.219Z,1718207440.219 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.289374
2024-06-12T15:50:40.471Z,1718207440.471 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.541370
2024-06-12T15:50:40.722Z,1718207440.722 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.793281
2024-06-12T15:50:40.975Z,1718207440.975 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.045359
2024-06-12T15:50:41.227Z,1718207441.227 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.297816
2024-06-12T15:50:41.479Z,1718207441.479 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.549486
2024-06-12T15:50:41.731Z,1718207441.731 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.801385
2024-06-12T15:50:41.982Z,1718207441.982 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.053225
2024-06-12T15:50:42.234Z,1718207442.234 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.305292
2024-06-12T15:50:42.487Z,1718207442.487 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.557469
2024-06-12T15:50:42.739Z,1718207442.739 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.809412
2024-06-12T15:50:42.991Z,1718207442.991 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.061564
2024-06-12T15:50:43.243Z,1718207443.243 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.313384
2024-06-12T15:50:43.494Z,1718207443.494 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.565318
2024-06-12T15:50:43.748Z,1718207443.748 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818786
2024-06-12T15:50:43.998Z,1718207443.998 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.069258
2024-06-12T15:50:44.244Z,1718207444.244 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-06-12T15:50:44.244Z,1718207444.244 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-06-12T15:50:44.250Z,1718207444.250 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.321248
2024-06-12T15:50:44.336Z,1718207444.336 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-06-12T15:50:44.503Z,1718207444.503 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.573487
2024-06-12T15:50:44.667Z,1718207444.667 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-06-12T15:50:44.667Z,1718207444.667 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-06-12T15:50:44.755Z,1718207444.755 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.825470
2024-06-12T15:50:45.006Z,1718207445.006 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:45.006Z,1718207445.006 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:45.007Z,1718207445.007 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:45.258Z,1718207445.258 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:45.258Z,1718207445.258 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:45.259Z,1718207445.259 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:45.510Z,1718207445.510 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:45.511Z,1718207445.511 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:45.511Z,1718207445.511 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:45.763Z,1718207445.763 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:45.764Z,1718207445.764 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:45.764Z,1718207445.764 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:46.015Z,1718207446.015 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:46.015Z,1718207446.015 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:46.015Z,1718207446.015 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:46.266Z,1718207446.266 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:46.266Z,1718207446.266 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:46.267Z,1718207446.267 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:46.519Z,1718207446.519 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:46.519Z,1718207446.519 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:46.519Z,1718207446.519 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:46.770Z,1718207446.770 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:46.771Z,1718207446.771 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:46.771Z,1718207446.771 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:47.022Z,1718207447.022 [DAT](INFO): ****** received valid address query ******
2024-06-12T15:50:47.022Z,1718207447.022 [DAT](INFO): ****** received valid ping request ******
2024-06-12T15:50:47.023Z,1718207447.023 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode.
2024-06-12T15:50:47.275Z,1718207447.275 [DAT](INFO): DAT read: Response Not Received
2024-06-12T15:50:47.275Z,1718207447.275 [DAT](INFO): response not received
2024-06-12T15:50:47.275Z,1718207447.275 [DAT](INFO): DAT read: user:18>
2024-06-12T15:50:47.276Z,1718207447.276 [DAT](INFO): DAT read: Tx time:15:50:46.4670
2024-06-12T15:50:47.276Z,1718207447.276 [DAT](INFO): Ping request sent.
2024-06-12T15:50:47.276Z,1718207447.276 [DAT](INFO): transmitted an acoustic signal
2024-06-12T15:50:47.277Z,1718207447.277 [DAT](INFO): publishing transmit ping time
2024-06-12T15:50:47.277Z,1718207447.277 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000712
2024-06-12T15:50:47.527Z,1718207447.527 [DAT](INFO): DAT read: TxSync time:15:50:46.4663
2024-06-12T15:50:47.527Z,1718207447.527 [DAT](INFO): unknown deviceResponse_: TxSync time:15:50:46.4663
2024-06-12T15:50:47.528Z,1718207447.528 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251167
2024-06-12T15:50:47.781Z,1718207447.781 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503636
2024-06-12T15:50:48.031Z,1718207448.031 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754117
2024-06-12T15:50:48.283Z,1718207448.283 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006156
2024-06-12T15:50:48.535Z,1718207448.535 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258096
2024-06-12T15:50:48.787Z,1718207448.787 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510052
2024-06-12T15:50:49.039Z,1718207449.039 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762356
2024-06-12T15:50:49.292Z,1718207449.292 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014994
2024-06-12T15:50:49.543Z,1718207449.543 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266034
2024-06-12T15:50:49.796Z,1718207449.796 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519466
2024-06-12T15:50:50.054Z,1718207450.054 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.777902
2024-06-12T15:50:50.310Z,1718207450.310 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.033835
2024-06-12T15:50:50.563Z,1718207450.563 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.286594
2024-06-12T15:50:50.815Z,1718207450.815 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.538173
2024-06-12T15:50:50.890Z,1718207450.890 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2024-06-12T15:50:51.067Z,1718207451.067 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.790219
2024-06-12T15:50:51.319Z,1718207451.319 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.042007
2024-06-12T15:50:51.571Z,1718207451.571 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.294786
2024-06-12T15:50:51.823Z,1718207451.823 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.546340
2024-06-12T15:50:52.075Z,1718207452.075 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.798167
2024-06-12T15:50:52.326Z,1718207452.326 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.049837
2024-06-12T15:50:52.579Z,1718207452.579 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.301994
2024-06-12T15:50:52.831Z,1718207452.831 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.554205
2024-06-12T15:50:53.082Z,1718207453.082 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.805848
2024-06-12T15:50:53.335Z,1718207453.335 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.058056
2024-06-12T15:50:53.503Z,1718207453.503 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-06-12T15:50:53.586Z,1718207453.586 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.309928
2024-06-12T15:50:53.838Z,1718207453.838 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.561958
2024-06-12T15:50:54.091Z,1718207454.091 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.814111
2024-06-12T15:50:54.342Z,1718207454.342 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.065931
2024-06-12T15:50:54.594Z,1718207454.594 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.317822
2024-06-12T15:50:54.847Z,1718207454.847 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.570183
2024-06-12T15:50:55.098Z,1718207455.098 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.821926
2024-06-12T15:50:55.351Z,1718207455.351 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.074058
2024-06-12T15:50:55.602Z,1718207455.602 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.325856
2024-06-12T15:50:55.855Z,1718207455.855 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.578190
2024-06-12T15:50:56.106Z,1718207456.106 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.829828
2024-06-12T15:50:56.359Z,1718207456.359 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.082129
2024-06-12T15:50:56.610Z,1718207456.610 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.333930
2024-06-12T15:50:56.862Z,1718207456.862 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.585932
2024-06-12T15:50:57.114Z,1718207457.114 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.837846
2024-06-12T15:50:59.635Z,1718207459.635 [DAT](INFO): DAT read: Response Not Received
2024-06-12T15:50:59.635Z,1718207459.635 [DAT](INFO): response not received
2024-06-12T15:50:59.635Z,1718207459.635 [DAT](INFO): DAT read: user:19>
2024-06-12T15:50:59.636Z,1718207459.636 [DAT](ERROR): No response from remote modem.
2024-06-12T15:50:59.888Z,1718207459.888 [DAT](INFO): DAT read: Tx time:15:50:58.9172
2024-06-12T15:50:59.888Z,1718207459.888 [DAT](INFO): Ping request sent.
2024-06-12T15:50:59.888Z,1718207459.888 [DAT](INFO): transmitted an acoustic signal
2024-06-12T15:50:59.889Z,1718207459.889 [DAT](INFO): DAT read: TxSync time:15:50:58.9165
2024-06-12T15:50:59.898Z,1718207459.898 [DAT](INFO): unknown deviceResponse_: TxSync time:15:50:58.9165
2024-06-12T15:50:59.898Z,1718207459.898 [DAT](INFO): publishing transmit ping time
2024-06-12T15:50:59.899Z,1718207459.899 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.010765
2024-06-12T15:51:00.139Z,1718207460.139 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250280
2024-06-12T15:51:00.399Z,1718207460.399 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.510345
2024-06-12T15:51:00.652Z,1718207460.652 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.763572
2024-06-12T15:51:00.902Z,1718207460.902 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.014015
2024-06-12T15:51:01.157Z,1718207461.157 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.268266
2024-06-12T15:51:01.411Z,1718207461.411 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.522146
2024-06-12T15:51:01.671Z,1718207461.671 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.782457
2024-06-12T15:51:01.923Z,1718207461.923 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.034211
2024-06-12T15:51:02.174Z,1718207462.174 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.285989
2024-06-12T15:51:02.426Z,1718207462.426 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.538106
2024-06-12T15:51:02.678Z,1718207462.678 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.790069
2024-06-12T15:51:02.931Z,1718207462.931 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.042800
2024-06-12T15:51:03.182Z,1718207463.182 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.294003
2024-06-12T15:51:03.450Z,1718207463.450 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.561242
2024-06-12T15:51:03.711Z,1718207463.711 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.822748
2024-06-12T15:51:03.963Z,1718207463.963 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.074142
2024-06-12T15:51:04.214Z,1718207464.214 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.326063
2024-06-12T15:51:04.467Z,1718207464.467 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.578174
2024-06-12T15:51:04.719Z,1718207464.719 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.830254
2024-06-12T15:51:04.971Z,1718207464.971 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.082177
2024-06-12T15:51:05.222Z,1718207465.222 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.334006
2024-06-12T15:51:05.474Z,1718207465.474 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.585893
2024-06-12T15:51:05.728Z,1718207465.728 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.839602
2024-06-12T15:51:05.979Z,1718207465.979 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.090150
2024-06-12T15:51:06.231Z,1718207466.231 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.342366
2024-06-12T15:51:06.483Z,1718207466.483 [DAT](INFO): checking for ne