2024-08-07T22:30:52.289Z,1723069852.289 [Supervisor](DEBUG): Initializing supervisor. 2024-08-07T22:30:52.298Z,1723069852.298 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-08-07T22:30:52.299Z,1723069852.299 [SyncHandler](INFO): Protected caller Thread ID is 840 2024-08-07T22:30:52.300Z,1723069852.300 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-08-07T22:30:52.302Z,1723069852.302 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-08-07T22:30:52.303Z,1723069852.303 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 841 2024-08-07T22:30:52.312Z,1723069852.312 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-08-07T22:30:52.350Z,1723069852.350 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-08-07T22:30:52.352Z,1723069852.352 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-08-07T22:30:52.353Z,1723069852.353 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 842 2024-08-07T22:30:52.362Z,1723069852.362 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-08-07T22:30:52.365Z,1723069852.365 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-08-07T22:30:52.365Z,1723069852.365 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 843 2024-08-07T22:30:52.370Z,1723069852.370 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-08-07T22:30:52.372Z,1723069852.372 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-08-07T22:30:52.373Z,1723069852.373 [logger ThreadHandler](INFO): Protected caller Thread ID is 844 2024-08-07T22:30:52.382Z,1723069852.382 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-08-07T22:30:52.382Z,1723069852.382 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-08-07T22:30:52.388Z,1723069852.388 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-08-07T22:30:52.589Z,1723069852.589 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-08-07T22:30:52.591Z,1723069852.591 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-08-07T22:30:54.005Z,1723069854.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-08-07T22:30:54.006Z,1723069854.006 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-08-07T22:30:54.296Z,1723069854.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-08-07T22:30:54.298Z,1723069854.298 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-08-07T22:30:54.747Z,1723069854.747 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-08-07T22:30:54.957Z,1723069854.957 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-08-07T22:30:54.959Z,1723069854.959 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-08-07T22:30:55.053Z,1723069855.053 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-08-07T22:30:55.174Z,1723069855.174 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-08-07T22:30:55.176Z,1723069855.176 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-08-07T22:30:55.593Z,1723069855.593 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-08-07T22:30:55.595Z,1723069855.595 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-08-07T22:30:56.155Z,1723069856.155 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-08-07T22:30:56.157Z,1723069856.157 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-08-07T22:30:56.394Z,1723069856.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-08-07T22:30:56.396Z,1723069856.396 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-08-07T22:30:56.598Z,1723069856.598 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-08-07T22:30:56.600Z,1723069856.600 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-08-07T22:30:57.160Z,1723069857.160 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-08-07T22:30:57.162Z,1723069857.162 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-08-07T22:30:57.472Z,1723069857.472 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-08-07T22:30:57.474Z,1723069857.474 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-08-07T22:30:57.720Z,1723069857.720 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-08-07T22:30:57.721Z,1723069857.721 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-08-07T22:30:58.119Z,1723069858.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-08-07T22:30:58.120Z,1723069858.120 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-08-07T22:30:58.491Z,1723069858.491 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-08-07T22:30:58.493Z,1723069858.493 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2024-08-07T22:30:58.496Z,1723069858.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2024-08-07T22:30:58.592Z,1723069858.592 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2024-08-07T22:30:58.892Z,1723069858.892 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Dock.cfg 2024-08-07T22:30:59.195Z,1723069859.195 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg 2024-08-07T22:30:59.565Z,1723069859.565 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2024-08-07T22:30:59.948Z,1723069859.948 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2024-08-07T22:31:00.297Z,1723069860.297 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2024-08-07T22:31:00.406Z,1723069860.406 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2024-08-07T22:31:00.532Z,1723069860.532 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2024-08-07T22:31:00.808Z,1723069860.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-08-07T22:31:00.809Z,1723069860.809 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2024-08-07T22:31:00.916Z,1723069860.916 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2024-08-07T22:31:01.022Z,1723069861.022 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2024-08-07T22:31:01.178Z,1723069861.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2024-08-07T22:31:01.291Z,1723069861.291 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2024-08-07T22:31:01.291Z,1723069861.291 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-08-07T22:31:01.309Z,1723069861.309 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-08-07T22:31:01.452Z,1723069861.452 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-08-07T22:31:01.454Z,1723069861.454 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-08-07T22:31:01.525Z,1723069861.525 [VerticalControl](DEBUG): Construct VerticalControl. 2024-08-07T22:31:01.585Z,1723069861.585 [VerticalControl] Loaded 2024-08-07T22:31:01.585Z,1723069861.585 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-08-07T22:31:01.588Z,1723069861.588 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-08-07T22:31:01.635Z,1723069861.635 [HorizontalControl] Loaded 2024-08-07T22:31:01.635Z,1723069861.635 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-08-07T22:31:01.637Z,1723069861.637 [SpeedControl](DEBUG): Construct SpeedControl. 2024-08-07T22:31:01.640Z,1723069861.640 [SpeedControl] Loaded 2024-08-07T22:31:01.641Z,1723069861.641 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-08-07T22:31:01.643Z,1723069861.643 [LoopControl](DEBUG): Construct LoopControl. 2024-08-07T22:31:01.644Z,1723069861.644 [LoopControl] Loaded 2024-08-07T22:31:01.644Z,1723069861.644 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-08-07T22:31:01.644Z,1723069861.644 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-08-07T22:31:01.645Z,1723069861.645 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-08-07T22:31:01.715Z,1723069861.715 [DepthRateCalculator] Loaded 2024-08-07T22:31:01.715Z,1723069861.715 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-08-07T22:31:01.720Z,1723069861.720 [PitchRateCalculator] Loaded 2024-08-07T22:31:01.720Z,1723069861.720 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-08-07T22:31:01.730Z,1723069861.730 [SpeedCalculator] Loaded 2024-08-07T22:31:01.730Z,1723069861.730 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-08-07T22:31:01.734Z,1723069861.734 [YawRateCalculator] Loaded 2024-08-07T22:31:01.735Z,1723069861.735 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-08-07T22:31:01.753Z,1723069861.753 [ElevatorOffsetCalculator] Loaded 2024-08-07T22:31:01.753Z,1723069861.753 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-08-07T22:31:01.753Z,1723069861.753 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-08-07T22:31:01.754Z,1723069861.754 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-08-07T22:31:01.797Z,1723069861.797 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-08-07T22:31:01.797Z,1723069861.797 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-08-07T22:31:01.879Z,1723069861.879 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-08-07T22:31:01.879Z,1723069861.879 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-08-07T22:31:02.277Z,1723069862.277 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-08-07T22:31:02.278Z,1723069862.278 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-08-07T22:31:02.490Z,1723069862.490 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-08-07T22:31:02.491Z,1723069862.491 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-08-07T22:31:03.278Z,1723069863.278 [AHRS_M2] Loaded 2024-08-07T22:31:03.278Z,1723069863.278 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-08-07T22:31:03.531Z,1723069863.531 [BackseatComponent] Loaded 2024-08-07T22:31:03.531Z,1723069863.531 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-08-07T22:31:03.532Z,1723069863.532 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409C54E0 2024-08-07T22:31:03.533Z,1723069863.533 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 933 2024-08-07T22:31:03.535Z,1723069863.535 [LcmUniversalReporter] Loaded 2024-08-07T22:31:03.536Z,1723069863.536 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-08-07T22:31:04.347Z,1723069864.347 [BPC1] Loaded 2024-08-07T22:31:04.347Z,1723069864.347 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-08-07T22:31:04.615Z,1723069864.615 [DataOverHttps] Loaded 2024-08-07T22:31:04.615Z,1723069864.615 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-08-07T22:31:04.616Z,1723069864.616 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F54E0 2024-08-07T22:31:04.617Z,1723069864.617 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 934 2024-08-07T22:31:04.640Z,1723069864.640 [Depth_Keller] Loaded 2024-08-07T22:31:04.640Z,1723069864.640 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-08-07T22:31:04.645Z,1723069864.645 [DropWeight] Loaded 2024-08-07T22:31:04.645Z,1723069864.645 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-08-07T22:31:04.743Z,1723069864.743 [NAL9602] Loaded 2024-08-07T22:31:04.743Z,1723069864.743 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-08-07T22:31:04.819Z,1723069864.819 [Onboard] Loaded 2024-08-07T22:31:04.820Z,1723069864.820 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-08-07T22:31:04.821Z,1723069864.821 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A254E0 2024-08-07T22:31:04.821Z,1723069864.821 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 935 2024-08-07T22:31:04.835Z,1723069864.835 [Power24vConverter] Loaded 2024-08-07T22:31:04.835Z,1723069864.835 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-08-07T22:31:04.851Z,1723069864.851 [Radio_Surface] Loaded 2024-08-07T22:31:04.851Z,1723069864.851 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-08-07T22:31:04.852Z,1723069864.852 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A554E0 2024-08-07T22:31:04.852Z,1723069864.852 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 936 2024-08-07T22:31:04.899Z,1723069864.899 [RDI_Pathfinder] Loaded 2024-08-07T22:31:04.899Z,1723069864.899 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2024-08-07T22:31:04.914Z,1723069864.914 [Sonardyne_Nano] Loaded 2024-08-07T22:31:04.914Z,1723069864.914 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-08-07T22:31:05.009Z,1723069865.009 [DAT] Loaded 2024-08-07T22:31:05.009Z,1723069865.009 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-08-07T22:31:05.010Z,1723069865.010 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0 2024-08-07T22:31:05.010Z,1723069865.010 [DAT ThreadHandler](INFO): Protected caller Thread ID is 937 2024-08-07T22:31:05.011Z,1723069865.011 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-08-07T22:31:05.012Z,1723069865.012 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-08-07T22:31:05.148Z,1723069865.148 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-08-07T22:31:05.148Z,1723069865.148 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-08-07T22:31:05.162Z,1723069865.162 [NavChart] Loaded 2024-08-07T22:31:05.162Z,1723069865.162 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-08-07T22:31:05.168Z,1723069865.168 [UniversalFixResidualReporter] Loaded 2024-08-07T22:31:05.168Z,1723069865.168 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-08-07T22:31:05.168Z,1723069865.168 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-08-07T22:31:05.169Z,1723069865.169 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-08-07T22:31:05.338Z,1723069865.338 [SBIT](DEBUG): Construct Startup Built In Test. 2024-08-07T22:31:05.347Z,1723069865.347 [SBIT] Loaded 2024-08-07T22:31:05.347Z,1723069865.347 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-08-07T22:31:05.350Z,1723069865.350 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-08-07T22:31:05.363Z,1723069865.363 [IBIT] Loaded 2024-08-07T22:31:05.363Z,1723069865.363 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-08-07T22:31:05.369Z,1723069865.369 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-08-07T22:31:05.474Z,1723069865.474 [CBIT] Loaded 2024-08-07T22:31:05.474Z,1723069865.474 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-08-07T22:31:05.474Z,1723069865.474 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-08-07T22:31:05.476Z,1723069865.476 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-08-07T22:31:05.638Z,1723069865.638 [BuoyancyServo] Loaded 2024-08-07T22:31:05.639Z,1723069865.639 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-08-07T22:31:05.661Z,1723069865.661 [ElevatorServo] Loaded 2024-08-07T22:31:05.662Z,1723069865.662 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-08-07T22:31:05.692Z,1723069865.692 [DockingStepper] Loaded 2024-08-07T22:31:05.692Z,1723069865.692 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2024-08-07T22:31:05.713Z,1723069865.713 [MassServo] Loaded 2024-08-07T22:31:05.713Z,1723069865.713 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-08-07T22:31:05.734Z,1723069865.734 [RudderServo] Loaded 2024-08-07T22:31:05.734Z,1723069865.734 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-08-07T22:31:05.753Z,1723069865.753 [ThrusterServo] Loaded 2024-08-07T22:31:05.753Z,1723069865.753 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2024-08-07T22:31:05.754Z,1723069865.754 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-08-07T22:31:05.754Z,1723069865.754 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-08-07T22:31:05.770Z,1723069865.770 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-08-07T22:31:05.771Z,1723069865.771 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-08-07T22:31:05.994Z,1723069865.994 [CTD_Seabird] Loaded 2024-08-07T22:31:05.995Z,1723069865.995 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-08-07T22:31:05.996Z,1723069865.996 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BD94E0 2024-08-07T22:31:05.996Z,1723069865.996 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 938 2024-08-07T22:31:06.019Z,1723069866.019 [PAR_Licor] Loaded 2024-08-07T22:31:06.019Z,1723069866.019 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-08-07T22:31:06.063Z,1723069866.063 [WetLabsBB2FL] Loaded 2024-08-07T22:31:06.064Z,1723069866.064 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-08-07T22:31:06.065Z,1723069866.065 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C094E0 2024-08-07T22:31:06.065Z,1723069866.065 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 939 2024-08-07T22:31:06.086Z,1723069866.086 [WetLabsUBAT] Loaded 2024-08-07T22:31:06.086Z,1723069866.086 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2024-08-07T22:31:06.087Z,1723069866.087 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C394E0 2024-08-07T22:31:06.088Z,1723069866.088 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 940 2024-08-07T22:31:06.088Z,1723069866.088 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-08-07T22:31:06.095Z,1723069866.095 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-08-07T22:31:06.097Z,1723069866.097 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-08-07T22:31:06.109Z,1723069866.109 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-08-07T22:31:06.110Z,1723069866.110 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0 2024-08-07T22:31:06.110Z,1723069866.110 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 941 2024-08-07T22:31:06.115Z,1723069866.115 [Supervisor](INFO): Main Thread ID is 831 2024-08-07T22:31:06.115Z,1723069866.115 [Supervisor](DEBUG): Running supervisor. 2024-08-07T22:31:06.115Z,1723069866.115 [CommandExec ThreadHandler](INFO): Handler Thread ID is 942 2024-08-07T22:31:06.116Z,1723069866.116 [CommandExec](INFO): Initializing the command executive. 2024-08-07T22:31:06.117Z,1723069866.117 [CommandLine ThreadHandler](INFO): Handler Thread ID is 943 2024-08-07T22:31:06.120Z,1723069866.120 [controlThread ThreadHandler](INFO): Handler Thread ID is 944 2024-08-07T22:31:06.120Z,1723069866.120 [controlThread](DEBUG): Initializing ControlThread 2024-08-07T22:31:06.121Z,1723069866.121 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-08-07T22:31:06.122Z,1723069866.122 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-08-07T22:31:06.123Z,1723069866.123 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-08-07T22:31:06.124Z,1723069866.124 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-08-07T22:31:06.124Z,1723069866.124 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-08-07T22:31:06.124Z,1723069866.124 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-08-07T22:31:06.125Z,1723069866.125 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-08-07T22:31:06.125Z,1723069866.125 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-08-07T22:31:06.125Z,1723069866.125 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-08-07T22:31:06.130Z,1723069866.130 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-08-07T22:31:06.130Z,1723069866.130 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-08-07T22:31:06.131Z,1723069866.131 [SBIT](INFO): Initialize SBIT Component. 2024-08-07T22:31:06.131Z,1723069866.131 [SBIT](IMPORTANT): git: 2024-07-22 2024-08-07T22:31:06.131Z,1723069866.131 [SBIT](INFO): git hash: e1b6f6712780a14e9094a1a5fcaaf0d5e88f6716 2024-08-07T22:31:06.132Z,1723069866.132 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-08-07T22:31:06.133Z,1723069866.133 [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-08-07T22:31:06.134Z,1723069866.134 [SBIT](INFO): Beginning SBIT in 101.000000 seconds. 2024-08-07T22:31:06.134Z,1723069866.134 [IBIT](INFO): Initialize IBIT Component. 2024-08-07T22:31:06.135Z,1723069866.135 [CBIT](DEBUG): Initialize CBIT Component. 2024-08-07T22:31:06.136Z,1723069866.136 [logger ThreadHandler](INFO): Handler Thread ID is 945 2024-08-07T22:31:06.147Z,1723069866.147 [CBIT](DEBUG): Initialized mux pins. 2024-08-07T22:31:06.148Z,1723069866.148 [CBIT](DEBUG): Initializing the watchdog timer. 2024-08-07T22:31:06.156Z,1723069866.156 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 946 2024-08-07T22:31:06.168Z,1723069866.168 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 947 2024-08-07T22:31:06.170Z,1723069866.170 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-08-07T22:31:06.171Z,1723069866.171 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-08-07T22:31:06.172Z,1723069866.172 [CBIT](DEBUG): Initializing heartbeat. 2024-08-07T22:31:06.180Z,1723069866.180 [Onboard ThreadHandler](INFO): Handler Thread ID is 948 2024-08-07T22:31:06.198Z,1723069866.198 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 949 2024-08-07T22:31:06.216Z,1723069866.216 [DAT ThreadHandler](INFO): Handler Thread ID is 950 2024-08-07T22:31:06.217Z,1723069866.217 [DAT](INFO): Powering up 2024-08-07T22:31:06.217Z,1723069866.217 [DAT](DEBUG): Initializing DAT. 2024-08-07T22:31:06.221Z,1723069866.221 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 952 2024-08-07T22:31:06.222Z,1723069866.222 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-08-07T22:31:06.229Z,1723069866.229 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 954 2024-08-07T22:31:06.232Z,1723069866.232 [WetLabsBB2FL](INFO): Powering up 2024-08-07T22:31:06.233Z,1723069866.233 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 956 2024-08-07T22:31:06.236Z,1723069866.236 [WetLabsUBAT](INFO): Powering up 2024-08-07T22:31:06.237Z,1723069866.237 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 958 2024-08-07T22:31:06.243Z,1723069866.243 [CBIT](DEBUG): Deactivating GF circuits. 2024-08-07T22:31:06.244Z,1723069866.244 [CBIT](DEBUG): Deactivating emergency mode. 2024-08-07T22:31:06.247Z,1723069866.247 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-08-07T22:31:06.247Z,1723069866.247 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-08-07T22:31:06.248Z,1723069866.248 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-08-07T22:31:06.248Z,1723069866.248 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-08-07T22:31:06.248Z,1723069866.248 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-08-07T22:31:06.248Z,1723069866.248 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-08-07T22:31:06.248Z,1723069866.248 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-08-07T22:31:06.248Z,1723069866.248 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-08-07T22:31:06.283Z,1723069866.283 [CBIT](DEBUG): Backplane powered. 2024-08-07T22:31:06.288Z,1723069866.288 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-08-07T22:31:06.289Z,1723069866.289 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl 2024-08-07T22:31:06.289Z,1723069866.289 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl 2024-08-07T22:31:06.312Z,1723069866.312 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl 2024-08-07T22:31:06.327Z,1723069866.327 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into: 2024-08-07T22:31:06.327Z,1723069866.327 [MissionManager](ERROR): Top node has no child nodes: Mission 2024-08-07T22:31:06.328Z,1723069866.328 [MissionManager](ERROR): Could not load startup mission. 2024-08-07T22:31:06.328Z,1723069866.328 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl 2024-08-07T22:31:06.328Z,1723069866.328 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl 2024-08-07T22:31:06.331Z,1723069866.331 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl 2024-08-07T22:31:06.604Z,1723069866.604 [Radio_Surface](INFO): Powering up 2024-08-07T22:31:06.836Z,1723069866.836 [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-08-07T22:31:06.868Z,1723069866.868 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min 2024-08-07T22:31:06.872Z,1723069866.872 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min 2024-08-07T22:31:06.896Z,1723069866.896 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count 2024-08-07T22:31:06.900Z,1723069866.900 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 2.500000 s 2024-08-07T22:31:06.916Z,1723069866.916 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count 2024-08-07T22:31:06.917Z,1723069866.917 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait. 2024-08-07T22:31:07.068Z,1723069867.068 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct. 2024-08-07T22:31:07.068Z,1723069867.068 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2024-08-07T22:31:07.127Z,1723069867.127 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-08-07T22:31:07.176Z,1723069867.176 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-08-07T22:31:07.179Z,1723069867.179 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-08-07T22:31:07.201Z,1723069867.201 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute. 2024-08-07T22:31:07.205Z,1723069867.205 [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-08-07T22:31:07.220Z,1723069867.220 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-08-07T22:31:07.234Z,1723069867.234 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-08-07T22:31:07.373Z,1723069867.373 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993022 m 2024-08-07T22:31:07.374Z,1723069867.374 [Power24vConverter](INFO): Powering up. 2024-08-07T22:31:07.375Z,1723069867.375 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2024-08-07T22:31:07.392Z,1723069867.392 [Sonardyne_Nano](INFO): Initializing. 2024-08-07T22:31:07.420Z,1723069867.420 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-08-07T22:31:07.433Z,1723069867.433 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-08-07T22:31:07.440Z,1723069867.440 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-08-07T22:31:07.441Z,1723069867.441 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-08-07T22:31:07.448Z,1723069867.448 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-08-07T22:31:07.449Z,1723069867.449 [DockingStepper](INFO): Start 2024-08-07T22:31:07.449Z,1723069867.449 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-08-07T22:31:07.456Z,1723069867.456 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-08-07T22:31:07.456Z,1723069867.456 [MassServo](DEBUG): Initializing EZServoServo. 2024-08-07T22:31:07.464Z,1723069867.464 [MassServo](DEBUG): Initializing MassServo. 2024-08-07T22:31:07.465Z,1723069867.465 [RudderServo](DEBUG): Initializing EZServoServo. 2024-08-07T22:31:07.472Z,1723069867.472 [RudderServo](DEBUG): Initializing RudderServo. 2024-08-07T22:31:07.473Z,1723069867.473 [ThrusterServo](DEBUG): Initializing EZServoServo. 2024-08-07T22:31:07.480Z,1723069867.480 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2024-08-07T22:31:07.717Z,1723069867.717 [DockingStepper](INFO): Starting 2024-08-07T22:31:08.055Z,1723069868.055 [DockingStepper](INFO): Starting 2024-08-07T22:31:08.584Z,1723069868.584 [DockingStepper](INFO): Starting 2024-08-07T22:31:08.632Z,1723069868.632 [DockingStepper](INFO): Init string: /1z16368aE13561N3m50 2024-08-07T22:31:08.844Z,1723069868.844 [WetLabsBB2FL](INFO): Powering down 2024-08-07T22:31:08.868Z,1723069868.868 [DockingStepper](INFO): Starting 2024-08-07T22:31:09.271Z,1723069869.271 [DockingStepper](INFO): Starting 2024-08-07T22:31:09.655Z,1723069869.655 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-08-07T22:31:09.715Z,1723069869.715 [DockingStepper](INFO): Starting 2024-08-07T22:31:10.132Z,1723069870.132 [DockingStepper](INFO): Starting 2024-08-07T22:31:10.156Z,1723069870.156 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-08-07T22:31:10.666Z,1723069870.666 [DockingStepper](INFO): Starting 2024-08-07T22:31:10.905Z,1723069870.905 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103 2024-08-07T22:31:11.341Z,1723069871.341 [DockingStepper](INFO): Pause 2024-08-07T22:31:11.858Z,1723069871.858 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10 2024-08-07T22:31:11.861Z,1723069871.861 [Radio_Surface](INFO): No local IP found 2024-08-07T22:31:15.552Z,1723069875.552 [WetLabsUBAT](INFO): Powering down 2024-08-07T22:31:20.076Z,1723069880.076 [DAT](INFO): DAT read: 2024-08-07T22:31:20.077Z,1723069880.077 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-08-07T22:31:21.841Z,1723069881.841 [DAT](INFO): DAT read: MF Frequency Band 2024-08-07T22:31:21.842Z,1723069881.842 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2 2024-08-07T22:31:21.842Z,1723069881.842 [DAT](INFO): DAT read: Aug 7 2024 22:29:58 2024-08-07T22:31:22.849Z,1723069882.849 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-08-07T22:31:22.850Z,1723069882.850 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-08-07T22:31:22.850Z,1723069882.850 [DAT](INFO): commRate: 800 2024-08-07T22:31:24.916Z,1723069884.916 [DAT](INFO): entering command mode 2024-08-07T22:31:25.116Z,1723069885.116 [DAT](INFO): DAT read: 2024-08-07T22:31:25.117Z,1723069885.117 [DAT](INFO): DAT read: uer:1> 2024-08-07T22:31:25.117Z,1723069885.117 [DAT](INFO): unknown deviceResponse_: uer:1> 2024-08-07T22:31:25.117Z,1723069885.117 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:25.368Z,1723069885.368 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:25.620Z,1723069885.620 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:25.872Z,1723069885.872 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:26.124Z,1723069886.124 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:26.379Z,1723069886.379 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:26.628Z,1723069886.628 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:26.880Z,1723069886.880 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:27.132Z,1723069887.132 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:27.384Z,1723069887.384 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:27.636Z,1723069887.636 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:27.888Z,1723069887.888 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:28.140Z,1723069888.140 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:28.392Z,1723069888.392 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:28.644Z,1723069888.644 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:28.896Z,1723069888.896 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:29.148Z,1723069889.148 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:29.400Z,1723069889.400 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:29.652Z,1723069889.652 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:29.904Z,1723069889.904 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:30.156Z,1723069890.156 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:30.408Z,1723069890.408 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:30.660Z,1723069890.660 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:30.912Z,1723069890.912 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:31.164Z,1723069891.164 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:31.416Z,1723069891.416 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:31.668Z,1723069891.668 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:31.920Z,1723069891.920 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:32.172Z,1723069892.172 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:32.424Z,1723069892.424 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:32.676Z,1723069892.676 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:32.928Z,1723069892.928 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:33.180Z,1723069893.180 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:33.432Z,1723069893.432 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:33.684Z,1723069893.684 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:33.941Z,1723069893.941 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:34.192Z,1723069894.192 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:34.444Z,1723069894.444 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:34.696Z,1723069894.696 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:34.949Z,1723069894.949 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:35.105Z,1723069895.105 [NAL9602](INFO): Powering up NAL9602 2024-08-07T22:31:35.200Z,1723069895.200 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:35.452Z,1723069895.452 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:35.704Z,1723069895.704 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:35.958Z,1723069895.958 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:36.208Z,1723069896.208 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:36.460Z,1723069896.460 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:36.712Z,1723069896.712 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:36.965Z,1723069896.965 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:37.216Z,1723069897.216 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:37.468Z,1723069897.468 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:37.720Z,1723069897.720 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:37.972Z,1723069897.972 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:38.225Z,1723069898.225 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:38.476Z,1723069898.476 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:38.728Z,1723069898.728 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:38.981Z,1723069898.981 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:39.232Z,1723069899.232 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:39.484Z,1723069899.484 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:39.736Z,1723069899.736 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:39.988Z,1723069899.988 [DAT](DEBUG): checking for command mode acknowledgment 2024-08-07T22:31:39.988Z,1723069899.988 [DAT](FAULT): failed to enter command mode 2024-08-07T22:31:40.291Z,1723069900.291 [DAT](INFO): entering command mode 2024-08-07T22:31:40.492Z,1723069900.492 [DAT](INFO): DAT read: user:1> 2024-08-07T22:31:40.493Z,1723069900.493 [DAT](INFO): DAT read: Command '+++' not found 2024-08-07T22:31:40.494Z,1723069900.494 [DAT](INFO): DAT read: Error 2024-08-07T22:31:40.494Z,1723069900.494 [DAT](INFO): setting verbose to 3 2024-08-07T22:31:40.744Z,1723069900.744 [DAT](INFO): DAT read: user:2> 2024-08-07T22:31:40.745Z,1723069900.745 [DAT](INFO): DAT read: Verbose | 3 2024-08-07T22:31:40.745Z,1723069900.745 [DAT](INFO): set verbose to 3 2024-08-07T22:31:40.746Z,1723069900.746 [DAT](INFO): setting DatVerbose to 27440 2024-08-07T22:31:40.998Z,1723069900.998 [DAT](INFO): DAT read: user:3> 2024-08-07T22:31:41.001Z,1723069901.001 [DAT](INFO): DAT read: DatVerbose | 27440 2024-08-07T22:31:41.002Z,1723069901.002 [DAT](INFO): set DatVerbose to 27440 2024-08-07T22:31:41.003Z,1723069901.003 [DAT](INFO): setting transmit power to 8 2024-08-07T22:31:41.248Z,1723069901.248 [DAT](INFO): DAT read: user:4> 2024-08-07T22:31:41.249Z,1723069901.249 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-08-07T22:31:41.249Z,1723069901.249 [DAT](INFO): set transmit power to 8 2024-08-07T22:31:41.250Z,1723069901.250 [DAT](INFO): setting local address to 10 2024-08-07T22:31:41.500Z,1723069901.500 [DAT](INFO): DAT read: user:5> 2024-08-07T22:31:41.501Z,1723069901.501 [DAT](INFO): DAT read: LocalAddr | 10 2024-08-07T22:31:41.501Z,1723069901.501 [DAT](INFO): set local address to 10 2024-08-07T22:31:41.503Z,1723069901.503 [DAT](INFO): Setting time to: 22:31:41 And date to:8/7/2024 2024-08-07T22:31:41.752Z,1723069901.752 [DAT](INFO): DAT read: user:6> 2024-08-07T22:31:41.753Z,1723069901.753 [DAT](INFO): DAT read: Wed Aug 7, 2024 22:31:41 2024-08-07T22:31:41.753Z,1723069901.753 [DAT](INFO): Local DAT time set to Wed Aug 7, 2024 22:31:41 2024-08-07T22:31:44.565Z,1723069904.565 [CommandExec](IMPORTANT): got command strobe off 2024-08-07T22:31:44.565Z,1723069904.565 [CommandExec](IMPORTANT): Deactivating strobe 2024-08-07T22:31:46.013Z,1723069906.013 [NAL9602](INFO): NAL9602 initialized 2024-08-07T22:32:03.709Z,1723069923.709 [CommandExec](IMPORTANT): got command strobe off 2024-08-07T22:32:03.709Z,1723069923.709 [CommandExec](IMPORTANT): Deactivating strobe 2024-08-07T22:32:13.489Z,1723069933.489 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-08-07T22:32:26.821Z,1723069946.821 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-08-07T22:32:40.557Z,1723069960.557 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-08-07T22:32:47.878Z,1723069967.878 [SBIT](IMPORTANT): Beginning Startup BIT 2024-08-07T22:32:47.905Z,1723069967.905 [CBIT](IMPORTANT): Beginning ground fault scan 2024-08-07T22:32:54.699Z,1723069974.699 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-08-07T22:32:58.811Z,1723069978.811 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.026296 CHAN A1 (24V): 0.052759 CHAN A2 (12V): 0.016827 CHAN A3 (5V): 0.006053 CHAN B0 (3.3V): 0.004645 CHAN B1 (3.15aV): 0.004059 CHAN B2 (3.15bV): 0.004265 CHAN B3 (GND): 0.000443 OPEN: -0.000551 Full Scale: +/- 1 mA 2024-08-07T22:33:09.240Z,1723069989.240 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-08-07T22:33:22.998Z,1723070002.998 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-08-07T22:33:36.331Z,1723070016.331 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-08-07T22:33:41.589Z,1723070021.589 [SBIT](IMPORTANT): SBIT PASSED 2024-08-07T22:33:41.589Z,1723070021.589 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-08-07T22:33:41.590Z,1723070021.590 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count; 2024-08-07T22:33:41.590Z,1723070021.590 [SBIT](IMPORTANT): DockingStepper.slideValue=10600 count; 2024-08-07T22:33:41.590Z,1723070021.590 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 5.000000 meter; 2024-08-07T22:33:41.590Z,1723070021.590 [SBIT](IMPORTANT): Express linearApproximation _.adinos 0.750000 none; 2024-08-07T22:33:41.590Z,1723070021.590 [SBIT](IMPORTANT): Express linearApproximation _.aother 0.750000 none; 2024-08-07T22:33:41.590Z,1723070021.590 [SBIT](IMPORTANT): Express linearApproximation _.dinoflagellates 10000000000.000000 none; 2024-08-07T22:33:41.590Z,1723070021.590 [SBIT](IMPORTANT): Express linearApproximation _.predict_plankton_data_pct_dinos_sqerr 0.200000 none; 2024-08-07T22:33:41.590Z,1723070021.590 [SBIT](IMPORTANT): LineCapture.kiHeadingFinalApproach=0.02 reciprocal_second; 2024-08-07T22:33:41.591Z,1723070021.591 [SBIT](IMPORTANT): LineCapture.kiHeadingTerminalGuidance=0.02 reciprocal_second; 2024-08-07T22:33:41.591Z,1723070021.591 [SBIT](IMPORTANT): LineCapture.navigationGain=nan none; 2024-08-07T22:33:41.591Z,1723070021.591 [SBIT](IMPORTANT): LineCapture.rolloutSpeed=0.8 meter_per_second; 2024-08-07T22:33:41.591Z,1723070021.591 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=517.021969 cubic_centimeter; 2024-08-07T22:33:41.591Z,1723070021.591 [SBIT](IMPORTANT): VerticalControl.massDefault=20.291290 millimeter; 2024-08-07T22:33:41.999Z,1723070021.999 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2024-08-07T22:33:41.999Z,1723070021.999 [DefaultWithUndock] Running Loop=1 2024-08-07T22:33:41.999Z,1723070021.999 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2024-08-07T22:33:41.999Z,1723070021.999 [DefaultWithUndock:A.Wait] Running Loop=1 2024-08-07T22:33:41.000Z,1723070022.000 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2024-08-07T22:33:43.270Z,1723070023.270 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004626 2024-08-07T22:33:44.268Z,1723070024.268 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T22:33:50.044Z,1723070030.044 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-08-07T22:33:52.884Z,1723070032.884 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T22:34:01.400Z,1723070041.400 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T22:34:03.378Z,1723070043.378 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-08-07T22:34:06.244Z,1723070046.244 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-08-07T22:34:06.244Z,1723070046.244 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-08-07T22:34:06.288Z,1723070046.288 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-08-07T22:34:06.661Z,1723070046.661 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-08-07T22:34:06.661Z,1723070046.661 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-08-07T22:34:09.796Z,1723070049.796 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T22:34:12.317Z,1723070052.317 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2024-08-07T22:34:12.317Z,1723070052.317 [DefaultWithUndock:A.Wait] Stopped 2024-08-07T22:34:12.317Z,1723070052.317 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2024-08-07T22:34:12.317Z,1723070052.317 [DefaultWithUndock:LeaveDock] Running Loop=1 2024-08-07T22:34:12.317Z,1723070052.317 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2024-08-07T22:34:12.317Z,1723070052.317 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2024-08-07T22:34:12.317Z,1723070052.317 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2024-08-07T22:34:12.318Z,1723070052.318 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2024-08-07T22:34:12.318Z,1723070052.318 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2024-08-07T22:34:12.318Z,1723070052.318 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2024-08-07T22:34:12.703Z,1723070052.703 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2024-08-07T22:34:12.730Z,1723070052.730 [DockingStepper](INFO): Resume 2024-08-07T22:34:13.188Z,1723070053.188 [DockingStepper](INFO): Resuming 2024-08-07T22:34:14.828Z,1723070054.828 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-07T22:34:14.839Z,1723070054.839 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:14.839Z,1723070054.839 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:14.839Z,1723070054.839 [DAT](INFO): setting remote address to 0 2024-08-07T22:34:14.843Z,1723070054.843 [DockingStepper](INFO): Start 2024-08-07T22:34:14.844Z,1723070054.844 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-08-07T22:34:14.963Z,1723070054.963 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-08-07T22:34:15.093Z,1723070055.093 [DAT](INFO): DAT read: user:7> 2024-08-07T22:34:15.094Z,1723070055.094 [DAT](INFO): DAT read: RemoteAddr | 0 2024-08-07T22:34:15.094Z,1723070055.094 [DAT](INFO): set remote address to 0 2024-08-07T22:34:15.094Z,1723070055.094 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:15.094Z,1723070055.094 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:15.095Z,1723070055.095 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:15.311Z,1723070055.311 [DockingStepper](INFO): Starting 2024-08-07T22:34:15.348Z,1723070055.348 [DAT](INFO): DAT read: user:8> 2024-08-07T22:34:15.350Z,1723070055.350 [DAT](INFO): DAT read: Tx time:22:34:13.4742 2024-08-07T22:34:15.351Z,1723070055.351 [DAT](INFO): Ping request sent. 2024-08-07T22:34:15.351Z,1723070055.351 [DAT](INFO): transmitted an acoustic signal 2024-08-07T22:34:15.351Z,1723070055.351 [DAT](INFO): publishing transmit ping time 2024-08-07T22:34:15.352Z,1723070055.352 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000974 2024-08-07T22:34:15.595Z,1723070055.595 [DAT](INFO): DAT read: TxSync time:22:34:13.4734 2024-08-07T22:34:15.596Z,1723070055.596 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.244804 2024-08-07T22:34:15.700Z,1723070055.700 [DockingStepper](INFO): Starting 2024-08-07T22:34:15.847Z,1723070055.847 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.495836 2024-08-07T22:34:16.085Z,1723070056.085 [DockingStepper](INFO): Starting 2024-08-07T22:34:16.099Z,1723070056.099 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.747816 2024-08-07T22:34:16.134Z,1723070056.134 [DockingStepper](INFO): Init string: /1z16368aE13561N3m50 2024-08-07T22:34:16.351Z,1723070056.351 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.999813 2024-08-07T22:34:16.603Z,1723070056.603 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.251706 2024-08-07T22:34:16.642Z,1723070056.642 [DockingStepper](INFO): Starting 2024-08-07T22:34:16.855Z,1723070056.855 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.504267 2024-08-07T22:34:16.899Z,1723070056.899 [DockingStepper](INFO): Starting 2024-08-07T22:34:17.107Z,1723070057.107 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.755882 2024-08-07T22:34:17.319Z,1723070057.319 [DockingStepper](INFO): Starting 2024-08-07T22:34:17.359Z,1723070057.359 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.007753 2024-08-07T22:34:17.611Z,1723070057.611 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.259719 2024-08-07T22:34:17.705Z,1723070057.705 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-07T22:34:17.735Z,1723070057.735 [DockingStepper](INFO): Starting 2024-08-07T22:34:17.762Z,1723070057.762 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-08-07T22:34:17.863Z,1723070057.863 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.511923 2024-08-07T22:34:18.103Z,1723070058.103 [DockingStepper](INFO): Starting 2024-08-07T22:34:18.115Z,1723070058.115 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.763799 2024-08-07T22:34:18.367Z,1723070058.367 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.015750 2024-08-07T22:34:18.590Z,1723070058.590 [DockingStepper](INFO): Changing to mode: 3 2024-08-07T22:34:18.591Z,1723070058.591 [DockingStepper](INFO): Detach mode. 2024-08-07T22:34:18.619Z,1723070058.619 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.268662 2024-08-07T22:34:18.658Z,1723070058.658 [DockingStepper](INFO): Commanding negative:54599 2024-08-07T22:34:18.871Z,1723070058.871 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.519772 2024-08-07T22:34:19.124Z,1723070059.124 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.773108 2024-08-07T22:34:19.375Z,1723070059.375 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.024137 2024-08-07T22:34:19.627Z,1723070059.627 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.276639 2024-08-07T22:34:19.879Z,1723070059.879 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.527897 2024-08-07T22:34:20.131Z,1723070060.131 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.779690 2024-08-07T22:34:20.211Z,1723070060.211 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-07T22:34:20.315Z,1723070060.315 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T22:34:20.383Z,1723070060.383 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.031715 2024-08-07T22:34:20.635Z,1723070060.635 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.283761 2024-08-07T22:34:20.887Z,1723070060.887 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.535847 2024-08-07T22:34:21.139Z,1723070061.139 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.787715 2024-08-07T22:34:21.391Z,1723070061.391 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.039722 2024-08-07T22:34:21.643Z,1723070061.643 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.291772 2024-08-07T22:34:21.895Z,1723070061.895 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.544544 2024-08-07T22:34:22.147Z,1723070062.147 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.795671 2024-08-07T22:34:22.399Z,1723070062.399 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.047920 2024-08-07T22:34:22.652Z,1723070062.652 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.300735 2024-08-07T22:34:22.769Z,1723070062.769 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-07T22:34:22.903Z,1723070062.903 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.552395 2024-08-07T22:34:23.155Z,1723070063.155 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.803678 2024-08-07T22:34:23.407Z,1723070063.407 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.055762 2024-08-07T22:34:23.659Z,1723070063.659 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.308298 2024-08-07T22:34:23.911Z,1723070063.911 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.559855 2024-08-07T22:34:24.163Z,1723070064.163 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.812288 2024-08-07T22:34:24.415Z,1723070064.415 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.064132 2024-08-07T22:34:24.667Z,1723070064.667 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.315876 2024-08-07T22:34:24.775Z,1723070064.775 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock. 2024-08-07T22:34:24.923Z,1723070064.923 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.571579 2024-08-07T22:34:25.170Z,1723070065.170 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.819604 2024-08-07T22:34:25.229Z,1723070065.229 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2024-08-07T22:34:25.423Z,1723070065.423 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:25.423Z,1723070065.423 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:25.423Z,1723070065.423 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:25.587Z,1723070065.587 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-07T22:34:25.675Z,1723070065.675 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:25.675Z,1723070065.675 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:25.676Z,1723070065.676 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:25.926Z,1723070065.926 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:25.926Z,1723070065.926 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:25.927Z,1723070065.927 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:26.178Z,1723070066.178 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:26.179Z,1723070066.179 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:26.179Z,1723070066.179 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:26.432Z,1723070066.432 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:26.432Z,1723070066.432 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:26.432Z,1723070066.432 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:26.682Z,1723070066.682 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:26.683Z,1723070066.683 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:26.683Z,1723070066.683 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:26.937Z,1723070066.937 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:26.937Z,1723070066.937 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:26.937Z,1723070066.937 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:27.186Z,1723070067.186 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:27.186Z,1723070067.186 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:27.186Z,1723070067.186 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:27.439Z,1723070067.439 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:27.439Z,1723070067.439 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:27.439Z,1723070067.439 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:27.691Z,1723070067.691 [DAT](INFO): DAT read: Response Not Received 2024-08-07T22:34:27.692Z,1723070067.692 [DAT](INFO): response not received 2024-08-07T22:34:27.692Z,1723070067.692 [DAT](INFO): DAT read: user:9> 2024-08-07T22:34:27.693Z,1723070067.693 [DAT](INFO): DAT read: Tx time:22:34:25.9227 2024-08-07T22:34:27.694Z,1723070067.694 [DAT](INFO): Ping request sent. 2024-08-07T22:34:27.694Z,1723070067.694 [DAT](INFO): transmitted an acoustic signal 2024-08-07T22:34:27.694Z,1723070067.694 [DAT](INFO): publishing transmit ping time 2024-08-07T22:34:27.695Z,1723070067.695 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001043 2024-08-07T22:34:27.943Z,1723070067.943 [DAT](INFO): DAT read: TxSync time:22:34:25.9219 2024-08-07T22:34:27.944Z,1723070067.944 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.249395 2024-08-07T22:34:28.195Z,1723070068.195 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.500752 2024-08-07T22:34:28.440Z,1723070068.440 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-07T22:34:28.447Z,1723070068.447 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.752513 2024-08-07T22:34:28.699Z,1723070068.699 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.004414 2024-08-07T22:34:28.951Z,1723070068.951 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.257262 2024-08-07T22:34:29.203Z,1723070069.203 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.508561 2024-08-07T22:34:29.455Z,1723070069.455 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.760397 2024-08-07T22:34:29.707Z,1723070069.707 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.012710 2024-08-07T22:34:29.959Z,1723070069.959 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.264471 2024-08-07T22:34:30.074Z,1723070070.074 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T22:34:30.211Z,1723070070.211 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.516493 2024-08-07T22:34:30.463Z,1723070070.463 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.768726 2024-08-07T22:34:30.715Z,1723070070.715 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021229 2024-08-07T22:34:30.968Z,1723070070.968 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273686 2024-08-07T22:34:31.219Z,1723070071.219 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.524639 2024-08-07T22:34:31.252Z,1723070071.252 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-07T22:34:31.471Z,1723070071.471 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.776482 2024-08-07T22:34:31.723Z,1723070071.723 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.028903 2024-08-07T22:34:31.975Z,1723070071.975 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.280405 2024-08-07T22:34:32.227Z,1723070072.227 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.532607 2024-08-07T22:34:32.479Z,1723070072.479 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.784406 2024-08-07T22:34:32.731Z,1723070072.731 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.037021 2024-08-07T22:34:32.983Z,1723070072.983 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.288589 2024-08-07T22:34:33.235Z,1723070073.235 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.540501 2024-08-07T22:34:33.487Z,1723070073.487 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.792776 2024-08-07T22:34:33.739Z,1723070073.739 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.044568 2024-08-07T22:34:33.991Z,1723070073.991 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.296606 2024-08-07T22:34:34.081Z,1723070074.081 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-07T22:34:34.244Z,1723070074.244 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.549562 2024-08-07T22:34:34.495Z,1723070074.495 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.800684 2024-08-07T22:34:34.747Z,1723070074.747 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.052449 2024-08-07T22:34:34.999Z,1723070074.999 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.305136 2024-08-07T22:34:35.251Z,1723070075.251 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.556585 2024-08-07T22:34:35.503Z,1723070075.503 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.808460 2024-08-07T22:34:35.755Z,1723070075.755 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.061069 2024-08-07T22:34:36.007Z,1723070076.007 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.312544 2024-08-07T22:34:36.261Z,1723070076.261 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566547 2024-08-07T22:34:36.511Z,1723070076.511 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.816409 2024-08-07T22:34:36.763Z,1723070076.763 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.068478 2024-08-07T22:34:36.884Z,1723070076.884 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-07T22:34:37.015Z,1723070077.015 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.320687 2024-08-07T22:34:37.267Z,1723070077.267 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.572489 2024-08-07T22:34:37.519Z,1723070077.519 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.824629 2024-08-07T22:34:37.771Z,1723070077.771 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:37.771Z,1723070077.771 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:37.771Z,1723070077.771 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:38.022Z,1723070078.022 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:38.022Z,1723070078.022 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:38.023Z,1723070078.023 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:38.280Z,1723070078.280 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:38.281Z,1723070078.281 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:38.282Z,1723070078.282 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:38.526Z,1723070078.526 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:38.527Z,1723070078.527 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:38.527Z,1723070078.527 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:38.781Z,1723070078.781 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:38.781Z,1723070078.781 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:38.781Z,1723070078.781 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:39.030Z,1723070079.030 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:39.030Z,1723070079.030 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:39.031Z,1723070079.031 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:39.282Z,1723070079.282 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:39.282Z,1723070079.282 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:39.283Z,1723070079.283 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:39.535Z,1723070079.535 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:39.535Z,1723070079.535 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:39.536Z,1723070079.536 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:39.546Z,1723070079.546 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T22:34:39.709Z,1723070079.709 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-07T22:34:39.787Z,1723070079.787 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:39.787Z,1723070079.787 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:39.787Z,1723070079.787 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:40.039Z,1723070080.039 [DAT](INFO): DAT read: Response Not Received 2024-08-07T22:34:40.039Z,1723070080.039 [DAT](INFO): response not received 2024-08-07T22:34:40.039Z,1723070080.039 [DAT](INFO): DAT read: user:10> 2024-08-07T22:34:40.040Z,1723070080.040 [DAT](ERROR): No response from remote modem. 2024-08-07T22:34:40.040Z,1723070080.040 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:40.040Z,1723070080.040 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:40.040Z,1723070080.040 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:40.294Z,1723070080.294 [DAT](INFO): DAT read: Tx time:22:34:38.3728 2024-08-07T22:34:40.295Z,1723070080.295 [DAT](INFO): Ping request sent. 2024-08-07T22:34:40.295Z,1723070080.295 [DAT](INFO): transmitted an acoustic signal 2024-08-07T22:34:40.297Z,1723070080.297 [DAT](INFO): DAT read: TxSync time:22:34:38.3720 2024-08-07T22:34:40.299Z,1723070080.299 [DAT](INFO): publishing transmit ping time 2024-08-07T22:34:40.302Z,1723070080.302 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.006009 2024-08-07T22:34:40.543Z,1723070080.543 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.247734 2024-08-07T22:34:40.795Z,1723070080.795 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.499464 2024-08-07T22:34:41.047Z,1723070081.047 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.752082 2024-08-07T22:34:41.299Z,1723070081.299 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.003802 2024-08-07T22:34:41.551Z,1723070081.551 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.256260 2024-08-07T22:34:41.803Z,1723070081.803 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.507532 2024-08-07T22:34:42.055Z,1723070082.055 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.759621 2024-08-07T22:34:42.310Z,1723070082.310 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014781 2024-08-07T22:34:42.559Z,1723070082.559 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.263657 2024-08-07T22:34:42.705Z,1723070082.705 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-07T22:34:42.811Z,1723070082.811 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.516114 2024-08-07T22:34:43.063Z,1723070083.063 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.767565 2024-08-07T22:34:43.315Z,1723070083.315 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.019772 2024-08-07T22:34:43.571Z,1723070083.571 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275535 2024-08-07T22:34:43.819Z,1723070083.819 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.523491 2024-08-07T22:34:44.071Z,1723070084.071 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.775632 2024-08-07T22:34:44.325Z,1723070084.325 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.029583 2024-08-07T22:34:44.576Z,1723070084.576 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.280691 2024-08-07T22:34:44.827Z,1723070084.827 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.531814 2024-08-07T22:34:45.079Z,1723070085.079 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.783733 2024-08-07T22:34:45.331Z,1723070085.331 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.035497 2024-08-07T22:34:45.460Z,1723070085.460 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby. 2024-08-07T22:34:45.461Z,1723070085.461 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-07T22:34:45.583Z,1723070085.583 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.287601 2024-08-07T22:34:45.835Z,1723070085.835 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.540278 2024-08-07T22:34:45.838Z,1723070085.838 [DockingStepper](INFO): Changing to mode: 1 2024-08-07T22:34:45.838Z,1723070085.838 [DockingStepper](INFO): Standby mode. 2024-08-07T22:34:45.908Z,1723070085.908 [DockingStepper](INFO): Commanding positive:54599 2024-08-07T22:34:46.087Z,1723070086.087 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.791955 2024-08-07T22:34:46.339Z,1723070086.339 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.043919 2024-08-07T22:34:46.591Z,1723070086.591 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.295582 2024-08-07T22:34:46.843Z,1723070086.843 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.547589 2024-08-07T22:34:47.095Z,1723070087.095 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.799562 2024-08-07T22:34:47.347Z,1723070087.347 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.051591 2024-08-07T22:34:47.599Z,1723070087.599 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.303667 2024-08-07T22:34:47.851Z,1723070087.851 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.555962 2024-08-07T22:34:48.103Z,1723070088.103 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.807646 2024-08-07T22:34:48.201Z,1723070088.201 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-07T22:34:48.355Z,1723070088.355 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.059798 2024-08-07T22:34:48.607Z,1723070088.607 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.311512 2024-08-07T22:34:48.859Z,1723070088.859 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.563904 2024-08-07T22:34:49.111Z,1723070089.111 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.816031 2024-08-07T22:34:49.363Z,1723070089.363 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.067598 2024-08-07T22:34:49.615Z,1723070089.615 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.319724 2024-08-07T22:34:49.867Z,1723070089.867 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.571568 2024-08-07T22:34:50.119Z,1723070090.119 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.823754 2024-08-07T22:34:50.372Z,1723070090.372 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:50.372Z,1723070090.372 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:50.372Z,1723070090.372 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:50.625Z,1723070090.625 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:50.634Z,1723070090.634 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:50.634Z,1723070090.634 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:50.715Z,1723070090.715 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-08-07T22:34:50.875Z,1723070090.875 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:50.875Z,1723070090.875 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:50.875Z,1723070090.875 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:51.127Z,1723070091.127 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:51.127Z,1723070091.127 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:51.127Z,1723070091.127 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:51.379Z,1723070091.379 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:51.379Z,1723070091.379 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:51.379Z,1723070091.379 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:51.630Z,1723070091.630 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:51.631Z,1723070091.631 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:51.631Z,1723070091.631 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:51.825Z,1723070091.825 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2024-08-07T22:34:51.883Z,1723070091.883 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:51.883Z,1723070091.883 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:51.883Z,1723070091.883 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:52.134Z,1723070092.134 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:52.135Z,1723070092.135 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:52.135Z,1723070092.135 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:52.232Z,1723070092.232 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2024-08-07T22:34:52.232Z,1723070092.232 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2024-08-07T22:34:52.233Z,1723070092.233 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2024-08-07T22:34:52.233Z,1723070092.233 [DefaultWithUndock:LeaveDock] Stopped 2024-08-07T22:34:52.233Z,1723070092.233 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2024-08-07T22:34:52.233Z,1723070092.233 [DefaultWithUndock:LeaveDock:A.] Stopped 2024-08-07T22:34:52.233Z,1723070092.233 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2024-08-07T22:34:52.233Z,1723070092.233 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2024-08-07T22:34:52.233Z,1723070092.233 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2024-08-07T22:34:52.233Z,1723070092.233 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2024-08-07T22:34:52.233Z,1723070092.233 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-08-07T22:34:52.234Z,1723070092.234 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-08-07T22:34:52.234Z,1723070092.234 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-08-07T22:34:52.235Z,1723070092.235 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-08-07T22:34:52.235Z,1723070092.235 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-08-07T22:34:52.235Z,1723070092.235 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-08-07T22:34:52.236Z,1723070092.236 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-08-07T22:34:52.386Z,1723070092.386 [DAT](INFO): ****** received valid address query ****** 2024-08-07T22:34:52.387Z,1723070092.387 [DAT](INFO): ****** received valid ping request ****** 2024-08-07T22:34:52.387Z,1723070092.387 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2024-08-07T22:34:52.639Z,1723070092.639 [DAT](INFO): DAT read: Response Not Received 2024-08-07T22:34:52.639Z,1723070092.639 [DAT](INFO): response not received 2024-08-07T22:34:52.639Z,1723070092.639 [DAT](INFO): DAT read: user:11> 2024-08-07T22:34:52.640Z,1723070092.640 [DAT](INFO): DAT read: Tx time:22:34:50.8229 2024-08-07T22:34:52.640Z,1723070092.640 [DAT](INFO): Ping request sent. 2024-08-07T22:34:52.641Z,1723070092.641 [DAT](INFO): transmitted an acoustic signal 2024-08-07T22:34:52.641Z,1723070092.641 [DAT](INFO): publishing transmit ping time 2024-08-07T22:34:52.641Z,1723070092.641 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000722 2024-08-07T22:34:52.734Z,1723070092.734 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2024-08-07T22:34:52.734Z,1723070092.734 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-08-07T22:34:52.734Z,1723070092.734 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2024-08-07T22:34:52.831Z,1723070092.831 [DockingStepper](INFO): Pause 2024-08-07T22:34:52.895Z,1723070092.895 [DAT](INFO): DAT read: TxSync time:22:34:50.8221 2024-08-07T22:34:52.895Z,1723070092.895 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.254821 2024-08-07T22:34:53.062Z,1723070093.062 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T22:34:53.092Z,1723070093.092 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-08-07T22:34:53.148Z,1723070093.148 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.507718 2024-08-07T22:34:53.399Z,1723070093.399 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.758184 2024-08-07T22:34:53.651Z,1723070093.651 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.010156 2024-08-07T22:34:53.903Z,1723070093.903 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.262238 2024-08-07T22:34:54.155Z,1723070094.155 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.514288 2024-08-07T22:34:54.407Z,1723070094.407 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.766155 2024-08-07T22:34:54.659Z,1723070094.659 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018684 2024-08-07T22:34:54.911Z,1723070094.911 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270166 2024-08-07T22:34:55.163Z,1723070095.163 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.522026 2024-08-07T22:34:55.415Z,1723070095.415 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774203 2024-08-07T22:34:55.667Z,1723070095.667 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.026478 2024-08-07T22:34:55.919Z,1723070095.919 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.277977 2024-08-07T22:34:56.171Z,1723070096.171 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.530131 2024-08-07T22:34:56.423Z,1723070096.423 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.782173 2024-08-07T22:34:56.675Z,1723070096.675 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.034016 2024-08-07T22:34:56.927Z,1723070096.927 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.286266 2024-08-07T22:34:57.179Z,1723070097.179 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.538098 2024-08-07T22:34:57.431Z,1723070097.431 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.790003 2024-08-07T22:34:57.683Z,1723070097.683 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.042008 2024-08-07T22:34:57.935Z,1723070097.935 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.294092 2024-08-07T22:34:58.187Z,1723070098.187 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.545969 2024-08-07T22:34:58.439Z,1723070098.439 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.797922 2024-08-07T22:34:58.691Z,1723070098.691 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.049957 2024-08-07T22:34:58.943Z,1723070098.943 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.302002 2024-08-07T22:34:59.195Z,1723070099.195 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.554277 2024-08-07T22:34:59.447Z,1723070099.447 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806056 2024-08-07T22:34:59.699Z,1723070099.699 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.057968 2024-08-07T22:34:59.951Z,1723070099.951 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.310278 2024-08-07T22:35:00.203Z,1723070100.203 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.561972 2024-08-07T22:35:00.455Z,1723070100.455 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.813968 2024-08-07T22:35:00.713Z,1723070100.713 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.072574 2024-08-07T22:35:00.967Z,1723070100.967 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.326419 2024-08-07T22:35:01.219Z,1723070101.219 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.578079 2024-08-07T22:35:01.471Z,1723070101.471 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.830212 2024-08-07T22:35:01.723Z,1723070101.723 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.082183 2024-08-07T22:35:01.975Z,1723070101.975 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.334251 2024-08-07T22:35:02.227Z,1723070102.227 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.585936 2024-08-07T22:35:02.479Z,1723070102.479 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.838030 2024-08-07T22:35:02.878Z,1723070102.878 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T22:35:04.999Z,1723070104.999 [DAT](INFO): DAT read: Response Not Received 2024-08-07T22:35:04.999Z,1723070104.999 [DAT](INFO): response not received 2024-08-07T22:35:04.999Z,1723070104.999 [DAT](INFO): DAT read: user:12> 2024-08-07T22:35:05.000Z,1723070105.000 [DAT](INFO): DAT read: Tx time:22:35:03.2730 2024-08-07T22:35:05.000Z,1723070105.000 [DAT](INFO): Ping request sent. 2024-08-07T22:35:05.000Z,1723070105.000 [DAT](INFO): transmitted an acoustic signal 2024-08-07T22:35:05.001Z,1723070105.001 [DAT](INFO): publishing transmit ping time 2024-08-07T22:35:05.001Z,1723070105.001 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000716 2024-08-07T22:35:05.251Z,1723070105.251 [DAT](INFO): DAT read: TxSync time:22:35:03.2722 2024-08-07T22:35:05.252Z,1723070105.252 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251269 2024-08-07T22:35:05.503Z,1723070105.503 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502142 2024-08-07T22:35:05.755Z,1723070105.755 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754076 2024-08-07T22:35:06.007Z,1723070106.007 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006400 2024-08-07T22:35:06.261Z,1723070106.261 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.260299 2024-08-07T22:35:06.511Z,1723070106.511 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510078 2024-08-07T22:35:06.762Z,1723070106.762 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.761882 2024-08-07T22:35:07.015Z,1723070107.015 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014048 2024-08-07T22:35:07.267Z,1723070107.267 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266179 2024-08-07T22:35:07.519Z,1723070107.519 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518748 2024-08-07T22:35:07.771Z,1723070107.771 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770135 2024-08-07T22:35:08.023Z,1723070108.023 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022152 2024-08-07T22:35:08.277Z,1723070108.277 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.276194 2024-08-07T22:35:08.527Z,1723070108.527 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526126 2024-08-07T22:35:08.778Z,1723070108.778 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.777896 2024-08-07T22:35:09.031Z,1723070109.031 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030040 2024-08-07T22:35:09.283Z,1723070109.283 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282718 2024-08-07T22:35:09.535Z,1723070109.535 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534139 2024-08-07T22:35:09.787Z,1723070109.787 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786262 2024-08-07T22:35:10.040Z,1723070110.040 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.039787 2024-08-07T22:35:10.293Z,1723070110.293 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.291604 2024-08-07T22:35:10.547Z,1723070110.547 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.546026 2024-08-07T22:35:10.803Z,1723070110.803 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.802560 2024-08-07T22:35:11.055Z,1723070111.055 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.053991 2024-08-07T22:35:11.307Z,1723070111.307 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.306705 2024-08-07T22:35:11.559Z,1723070111.559 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.558056 2024-08-07T22:35:11.811Z,1723070111.811 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.809983 2024-08-07T22:35:11.858Z,1723070111.858 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T22:35:12.063Z,1723070112.063 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.062370 2024-08-07T22:35:12.317Z,1723070112.317 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.316078 2024-08-07T22:35:12.567Z,1723070112.567 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.566373 2024-08-07T22:35:12.819Z,1723070112.819 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.818035 2024-08-07T22:35:13.071Z,1723070113.071 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.070009 2024-08-07T22:35:13.323Z,1723070113.323 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.322010 2024-08-07T22:35:13.575Z,1723070113.575 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.574322 2024-08-07T22:35:13.827Z,1723070113.827 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.826088 2024-08-07T22:35:14.079Z,1723070114.079 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.078060 2024-08-07T22:35:14.331Z,1723070114.331 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.330065 2024-08-07T22:35:14.583Z,1723070114.583 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.582140 2024-08-07T22:35:14.835Z,1723070114.835 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.834107 2024-08-07T22:35:17.615Z,1723070117.615 [DAT](INFO): DAT read: Response Not Received 2024-08-07T22:35:17.615Z,1723070117.615 [DAT](INFO): response not received 2024-08-07T22:35:17.615Z,1723070117.615 [DAT](INFO): DAT read: user:13> 2024-08-07T22:35:17.616Z,1723070117.616 [DAT](INFO): DAT read: Tx time:22:35:15.7231 2024-08-07T22:35:17.616Z,1723070117.616 [DAT](INFO): Ping request sent. 2024-08-07T22:35:17.616Z,1723070117.616 [DAT](INFO): transmitted an acoustic signal 2024-08-07T22:35:17.617Z,1723070117.617 [DAT](INFO): publishing transmit ping time 2024-08-07T22:35:17.617Z,1723070117.617 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000723 2024-08-07T22:35:17.868Z,1723070117.868 [DAT](INFO): DAT read: TxSync time:22:35:15.7223 2024-08-07T22:35:17.869Z,1723070117.869 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252542 2024-08-07T22:35:18.118Z,1723070118.118 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501951 2024-08-07T22:35:18.371Z,1723070118.371 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754227 2024-08-07T22:35:18.623Z,1723070118.623 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006272 2024-08-07T22:35:18.874Z,1723070118.874 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.257958 2024-08-07T22:35:19.127Z,1723070119.127 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510037 2024-08-07T22:35:19.378Z,1723070119.378 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.761971 2024-08-07T22:35:19.631Z,1723070119.631 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014742 2024-08-07T22:35:19.883Z,1723070119.883 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266097 2024-08-07T22:35:20.135Z,1723070120.135 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518542 2024-08-07T22:35:20.387Z,1723070120.387 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770195 2024-08-07T22:35:20.582Z,1723070120.582 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T22:35:20.639Z,1723070120.639 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022055 2024-08-07T22:35:20.891Z,1723070120.891 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274106 2024-08-07T22:35:21.143Z,1723070121.143 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526134 2024-08-07T22:35:21.395Z,1723070121.395 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778881 2024-08-07T22:35:21.647Z,1723070121.647 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030046 2024-08-07T22:35:21.899Z,1723070121.899 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282330 2024-08-07T22:35:22.151Z,1723070122.151 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534530 2024-08-07T22:35:22.403Z,1723070122.403 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786035 2024-08-07T22:35:22.655Z,1723070122.655 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038174 2024-08-07T22:35:22.907Z,1723070122.907 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290004 2024-08-07T22:35:23.159Z,1723070123.159 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542080 2024-08-07T22:35:23.411Z,1723070123.411 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794731 2024-08-07T22:35:23.663Z,1723070123.663 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046152 2024-08-07T22:35:23.915Z,1723070123.915 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298092 2024-08-07T22:35:24.167Z,1723070124.167 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550207 2024-08-07T22:35:24.419Z,1723070124.419 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802146 2024-08-07T22:35:24.670Z,1723070124.670 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.053929 2024-08-07T22:35:24.923Z,1723070124.923 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306076 2024-08-07T22:35:25.176Z,1723070125.176 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.559831 2024-08-07T22:35:25.427Z,1723070125.427 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810072 2024-08-07T22:35:25.679Z,1723070125.679 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062352 2024-08-07T22:35:25.931Z,1723070125.931 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314132 2024-08-07T22:35:26.183Z,1723070126.183 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566082 2024-08-07T22:35:26.435Z,1723070126.435 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818128 2024-08-07T22:35:26.687Z,1723070126.687 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070650 2024-08-07T22:35:26.939Z,1723070126.939 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322023 2024-08-07T22:35:27.191Z,1723070127.191 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574168 2024-08-07T22:35:27.443Z,1723070127.443 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826495 2024-08-07T22:35:29.738Z,1723070129.738 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T22:35:29.975Z,1723070129.975 [DAT](INFO): DAT read: Response Not Received 2024-08-07T22:35:29.976Z,1723070129.976 [DAT](INFO): response not received 2024-08-07T22:35:29.976Z,1723070129.976 [DAT](INFO): DAT read: user:14> 2024-08-07T22:35:29.977Z,1723070129.977 [DAT](INFO): DAT read: Tx time:22:35:28.1732 2024-08-07T22:35:29.977Z,1723070129.977 [DAT](INFO): Ping request sent. 2024-08-07T22:35:29.977Z,1723070129.977 [DAT](INFO): transmitted an acoustic signal 2024-08-07T22:35:29.977Z,1723070129.977 [DAT](INFO): publishing transmit ping time 2024-08-07T22:35:29.986Z,1723070129.986 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.008155 2024-08-07T22:35:30.215Z,1723070130.215 [DAT](INFO): DAT read: TxSync time:22:35:28.1724 2024-08-07T22:35:30.216Z,1723070130.216 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.238393 2024-08-07T22:35:30.467Z,1723070130.467 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.489327 2024-08-07T22:35:30.718Z,1723070130.718 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.741230 2024-08-07T22:35:30.971Z,1723070130.971 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.993308 2024-08-07T22:35:31.223Z,1723070131.223 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.245510 2024-08-07T22:35:31.475Z,1723070131.475 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.497340 2024-08-07T22:35:31.727Z,1723070131.727 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.749631 2024-08-07T22:35:31.979Z,1723070131.979 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.001414 2024-08-07T22:35:32.231Z,1723070132.231 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.253401 2024-08-07T22:35:32.483Z,1723070132.483 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.505361 2024-08-07T22:35:32.735Z,1723070132.735 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.757430 2024-08-07T22:35:32.987Z,1723070132.987 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.009339 2024-08-07T22:35:33.239Z,1723070133.239 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.261720 2024-08-07T22:35:33.491Z,1723070133.491 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.513421 2024-08-07T22:35:33.743Z,1723070133.743 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.765370 2024-08-07T22:35:33.995Z,1723070133.995 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.017362 2024-08-07T22:35:34.247Z,1723070134.247 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.269276 2024-08-07T22:35:34.499Z,1723070134.499 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.521347 2024-08-07T22:35:34.751Z,1723070134.751 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.773307 2024-08-07T22:35:35.003Z,1723070135.003 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.025370 2024-08-07T22:35:35.255Z,1723070135.254 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.277236 2024-08-07T22:35:35.507Z,1723070135.507 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.529703 2024-08-07T22:35:35.759Z,1723070135.759 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.781623 2024-08-07T22:35:36.011Z,1723070136.011 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.033437 2024-08-07T22:35:36.265Z,1723070136.265 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.287186 2024-08-07T22:35:36.515Z,1723070136.515 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.537441 2024-08-07T22:35:36.767Z,1723070136.767 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.789347 2024-08-07T22:35:37.019Z,1723070137.019 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.041932 2024-08-07T22:35:37.271Z,1723070137.271 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.293606 2024-08-07T22:35:37.528Z,1723070137.528 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.550225 2024-08-07T22:35:37.775Z,1723070137.775 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.797600 2024-08-07T22:35:38.027Z,1723070138.027 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.049344 2024-08-07T22:35:38.281Z,1723070138.281 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.303138 2024-08-07T22:35:38.531Z,1723070138.531 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.553524 2024-08-07T22:35:38.766Z,1723070138.766 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2024-08-07T22:35:38.783Z,1723070138.783 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.805452 2024-08-07T22:35:39.035Z,1723070139.035 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.057288 2024-08-07T22:35:39.287Z,1723070139.287 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.309289 2024-08-07T22:35:39.539Z,1723070139.539 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.561363 2024-08-07T22:35:39.791Z,1723070139.791 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.813482 2024-08-07T22:35:42.313Z,1723070142.313 [DAT](INFO): DAT read: Response Not Received 2024-08-07T22:35:42.314Z,1723070142.314 [DAT](INFO): response not received 2024-08-07T22:35:42.315Z,1723070142.315 [DAT](INFO): DAT read: user:15> 2024-08-07T22:35:42.316Z,1723070142.316 [DAT](ERROR): No response from remote modem. 2024-08-07T22:35:42.563Z,1723070142.563 [DAT](INFO): DAT read: Tx time:22:35:40.6233 2024-08-07T22:35:42.563Z,1723070142.563 [DAT](INFO): Ping request sent. 2024-08-07T22:35:42.563Z,1723070142.563 [DAT](INFO): transmitted an acoustic signal 2024-08-07T22:35:42.564Z,1723070142.564 [DAT](INFO): DAT read: TxSync time:22:35:40.6225 2024-08-07T22:35:42.564Z,1723070142.564 [DAT](INFO): publishing transmit ping time 2024-08-07T22:35:42.565Z,1723070142.565 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001582 2024-08-07T22:35:42.815Z,1723070142.815 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251368 2024-08-07T22:35:42.943Z,1723070142.943 [CommandExec](IMPORTANT): got command restart application 2024-08-07T22:35:43.067Z,1723070143.067 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503331 2024-08-07T22:35:43.319Z,1723070143.319 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755512 2024-08-07T22:35:43.570Z,1723070143.570 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007186 2024-08-07T22:35:43.823Z,1723070143.823 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259752 2024-08-07T22:35:43.946Z,1723070143.946 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2024-08-07T22:35:43.946Z,1723070143.946 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-07T22:35:43.946Z,1723070143.946 [CommandExec](INFO): Uninitializing the command executive. 2024-08-07T22:35:43.946Z,1723070143.946 [CommandExec](INFO): Uninitializing the command scheduler. 2024-08-07T22:35:43.947Z,1723070143.947 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:43.982Z,1723070143.982 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-08-07T22:35:43.982Z,1723070143.982 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-08-07T22:35:43.982Z,1723070143.982 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:43.983Z,1723070143.983 [NavChartDb](INFO): Join timeout helper Thread ID is 1002 2024-08-07T22:35:44.075Z,1723070144.075 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511510 2024-08-07T22:35:44.098Z,1723070144.098 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-07T22:35:44.098Z,1723070144.098 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:44.102Z,1723070144.102 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2024-08-07T22:35:44.102Z,1723070144.102 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:44.102Z,1723070144.102 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1003 2024-08-07T22:35:44.327Z,1723070144.327 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763385 2024-08-07T22:35:44.422Z,1723070144.422 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-07T22:35:44.422Z,1723070144.422 [WetLabsUBAT](INFO): Powering down 2024-08-07T22:35:44.423Z,1723070144.423 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:44.430Z,1723070144.430 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-08-07T22:35:44.430Z,1723070144.430 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:44.430Z,1723070144.430 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1004 2024-08-07T22:35:44.579Z,1723070144.579 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015351 2024-08-07T22:35:44.831Z,1723070144.831 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267486 2024-08-07T22:35:44.890Z,1723070144.890 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-07T22:35:44.890Z,1723070144.890 [WetLabsBB2FL](INFO): Powering down 2024-08-07T22:35:44.891Z,1723070144.891 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:44.910Z,1723070144.910 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-08-07T22:35:44.910Z,1723070144.910 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:44.910Z,1723070144.910 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1005 2024-08-07T22:35:45.083Z,1723070145.083 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519256 2024-08-07T22:35:45.335Z,1723070145.335 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771269 2024-08-07T22:35:45.587Z,1723070145.587 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023456 2024-08-07T22:35:45.754Z,1723070145.754 [CTD_Seabird](INFO): Powering down 2024-08-07T22:35:45.768Z,1723070145.768 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-07T22:35:45.770Z,1723070145.770 [CTD_Seabird](INFO): Powering down 2024-08-07T22:35:45.782Z,1723070145.782 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:45.786Z,1723070145.786 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-08-07T22:35:45.786Z,1723070145.786 [DAT ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:45.786Z,1723070145.786 [DAT](INFO): Join timeout helper Thread ID is 1006 2024-08-07T22:35:45.838Z,1723070145.838 [DAT](INFO): Powering down 2024-08-07T22:35:45.910Z,1723070145.910 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-07T22:35:45.910Z,1723070145.910 [DAT](INFO): Powering down 2024-08-07T22:35:45.911Z,1723070145.911 [DAT ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:45.919Z,1723070145.919 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-08-07T22:35:45.919Z,1723070145.919 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:45.919Z,1723070145.919 [Radio_Surface](INFO): Join timeout helper Thread ID is 1007 2024-08-07T22:35:46.042Z,1723070146.042 [Radio_Surface](INFO): Powering down 2024-08-07T22:35:46.043Z,1723070146.043 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-07T22:35:46.043Z,1723070146.043 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:46.046Z,1723070146.046 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-08-07T22:35:46.047Z,1723070146.047 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:46.047Z,1723070146.047 [Onboard](INFO): Join timeout helper Thread ID is 1008 2024-08-07T22:35:47.722Z,1723070147.722 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-07T22:35:47.722Z,1723070147.722 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:47.738Z,1723070147.738 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-08-07T22:35:47.738Z,1723070147.738 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:47.739Z,1723070147.739 [DataOverHttps](INFO): Join timeout helper Thread ID is 1009 2024-08-07T22:35:47.834Z,1723070147.834 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-07T22:35:47.834Z,1723070147.834 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:47.838Z,1723070147.838 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-08-07T22:35:47.839Z,1723070147.839 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:47.839Z,1723070147.839 [BackseatComponent](INFO): Join timeout helper Thread ID is 1010 2024-08-07T22:35:47.858Z,1723070147.858 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-07T22:35:47.858Z,1723070147.858 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:47.860Z,1723070147.860 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-08-07T22:35:47.860Z,1723070147.860 [logger ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:47.860Z,1723070147.860 [logger](INFO): Join timeout helper Thread ID is 1011 2024-08-07T22:35:47.894Z,1723070147.894 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-07T22:35:47.895Z,1723070147.895 [logger ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:47.902Z,1723070147.902 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-08-07T22:35:47.903Z,1723070147.903 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:47.903Z,1723070147.903 [CommandLine](INFO): Join timeout helper Thread ID is 1012 2024-08-07T22:35:47.962Z,1723070147.962 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-07T22:35:47.962Z,1723070147.962 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:47.969Z,1723070147.969 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-08-07T22:35:47.969Z,1723070147.969 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:47.969Z,1723070147.969 [CommandExec](INFO): Join timeout helper Thread ID is 1013 2024-08-07T22:35:47.970Z,1723070147.970 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-08-07T22:35:47.971Z,1723070147.971 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:47.971Z,1723070147.971 [controlThread](INFO): Join timeout helper Thread ID is 1014 2024-08-07T22:35:48.007Z,1723070148.007 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-08-07T22:35:48.007Z,1723070148.007 [controlThread](DEBUG): Uninitializing ControlThread 2024-08-07T22:35:48.007Z,1723070148.007 [AHRS_M2](INFO): Powering down 2024-08-07T22:35:48.079Z,1723070148.079 [NAL9602](INFO): Powering down 2024-08-07T22:35:48.150Z,1723070148.150 [RDI_Pathfinder](INFO): Powering down 2024-08-07T22:35:48.151Z,1723070148.151 [Sonardyne_Nano](INFO): Powering down 2024-08-07T22:35:48.271Z,1723070148.271 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-08-07T22:35:48.271Z,1723070148.271 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-08-07T22:35:48.272Z,1723070148.272 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-08-07T22:35:48.272Z,1723070148.272 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock 2024-08-07T22:35:48.272Z,1723070148.272 [DefaultWithUndock] Stopped 2024-08-07T22:35:48.272Z,1723070148.272 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2024-08-07T22:35:48.273Z,1723070148.273 [DefaultWithUndock:SurfaceDefault] Stopped 2024-08-07T22:35:48.273Z,1723070148.273 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault 2024-08-07T22:35:48.273Z,1723070148.273 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped 2024-08-07T22:35:48.273Z,1723070148.273 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-08-07T22:35:48.273Z,1723070148.273 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2024-08-07T22:35:48.273Z,1723070148.273 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-08-07T22:35:48.273Z,1723070148.273 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2024-08-07T22:35:48.280Z,1723070148.280 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-08-07T22:35:48.280Z,1723070148.280 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-08-07T22:35:48.280Z,1723070148.280 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-08-07T22:35:48.281Z,1723070148.281 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-08-07T22:35:48.281Z,1723070148.281 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-08-07T22:35:48.281Z,1723070148.281 [BuoyancyServo](INFO): Powering down 2024-08-07T22:35:48.294Z,1723070148.294 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-08-07T22:35:48.294Z,1723070148.294 [ElevatorServo](INFO): Powering down 2024-08-07T22:35:48.295Z,1723070148.295 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-08-07T22:35:48.295Z,1723070148.295 [DockingStepper](INFO): Powering down 2024-08-07T22:35:48.296Z,1723070148.296 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-08-07T22:35:48.296Z,1723070148.296 [MassServo](INFO): Powering down 2024-08-07T22:35:48.297Z,1723070148.297 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-08-07T22:35:48.297Z,1723070148.297 [RudderServo](INFO): Powering down 2024-08-07T22:35:48.298Z,1723070148.298 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2024-08-07T22:35:48.298Z,1723070148.298 [ThrusterServo](INFO): Powering down 2024-08-07T22:35:48.298Z,1723070148.298 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-08-07T22:35:48.299Z,1723070148.299 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-08-07T22:35:48.299Z,1723070148.299 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-08-07T22:35:48.299Z,1723070148.299 [CBIT](DEBUG): Powering off loads. 2024-08-07T22:35:48.310Z,1723070148.310 [CBIT](DEBUG): Disabling WDT. 2024-08-07T22:35:48.322Z,1723070148.322 [CBIT](DEBUG): Opening all GF detection circuits. 2024-08-07T22:35:48.323Z,1723070148.323 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:48.376Z,1723070148.376 [DAT ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:48.384Z,1723070148.384 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:48.386Z,1723070148.386 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:48.392Z,1723070148.392 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:48.437Z,1723070148.437 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:48.497Z,1723070148.497 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:48.499Z,1723070148.499 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:48.504Z,1723070148.504 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:48.512Z,1723070148.512 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-08-07T22:35:48.581Z,1723070148.581 [logger ThreadHandler](INFO): Thread cancelled.