2024-05-24T20:41:26.110Z,1716583286.110 [Supervisor](DEBUG): Initializing supervisor. 2024-05-24T20:41:26.114Z,1716583286.114 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-05-24T20:41:26.115Z,1716583286.115 [SyncHandler](INFO): Protected caller Thread ID is 3131 2024-05-24T20:41:26.115Z,1716583286.115 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-05-24T20:41:26.116Z,1716583286.116 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-05-24T20:41:26.117Z,1716583286.117 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3132 2024-05-24T20:41:26.121Z,1716583286.121 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-05-24T20:41:26.139Z,1716583286.139 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-05-24T20:41:26.140Z,1716583286.140 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-05-24T20:41:26.140Z,1716583286.140 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3133 2024-05-24T20:41:26.145Z,1716583286.145 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-05-24T20:41:26.146Z,1716583286.146 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-05-24T20:41:26.146Z,1716583286.146 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3134 2024-05-24T20:41:26.148Z,1716583286.148 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-05-24T20:41:26.149Z,1716583286.149 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-05-24T20:41:26.150Z,1716583286.150 [logger ThreadHandler](INFO): Protected caller Thread ID is 3135 2024-05-24T20:41:26.153Z,1716583286.153 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-05-24T20:41:26.154Z,1716583286.154 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-05-24T20:41:26.156Z,1716583286.156 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-05-24T20:41:26.335Z,1716583286.335 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-05-24T20:41:26.335Z,1716583286.335 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-05-24T20:41:27.540Z,1716583287.540 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-05-24T20:41:27.541Z,1716583287.541 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-05-24T20:41:27.809Z,1716583287.809 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-05-24T20:41:27.810Z,1716583287.810 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-05-24T20:41:27.906Z,1716583287.906 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-05-24T20:41:28.059Z,1716583288.059 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-05-24T20:41:28.060Z,1716583288.060 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-05-24T20:41:28.158Z,1716583288.158 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-05-24T20:41:28.286Z,1716583288.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-05-24T20:41:28.286Z,1716583288.286 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-05-24T20:41:28.738Z,1716583288.738 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-05-24T20:41:28.738Z,1716583288.738 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-05-24T20:41:29.723Z,1716583289.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-05-24T20:41:29.724Z,1716583289.724 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-05-24T20:41:30.010Z,1716583290.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-05-24T20:41:30.010Z,1716583290.010 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-05-24T20:41:30.233Z,1716583290.233 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-05-24T20:41:30.234Z,1716583290.234 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-05-24T20:41:30.834Z,1716583290.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-05-24T20:41:30.835Z,1716583290.835 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-05-24T20:41:31.168Z,1716583291.168 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-05-24T20:41:31.169Z,1716583291.169 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-05-24T20:41:31.434Z,1716583291.434 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-05-24T20:41:31.434Z,1716583291.434 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-05-24T20:41:31.858Z,1716583291.858 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-05-24T20:41:31.859Z,1716583291.859 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-05-24T20:41:32.326Z,1716583292.326 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-05-24T20:41:32.328Z,1716583292.328 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2024-05-24T20:41:32.329Z,1716583292.329 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2024-05-24T20:41:32.921Z,1716583292.921 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2024-05-24T20:41:33.277Z,1716583293.277 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg 2024-05-24T20:41:33.447Z,1716583293.447 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2024-05-24T20:41:33.576Z,1716583293.576 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2024-05-24T20:41:33.680Z,1716583293.680 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2024-05-24T20:41:33.795Z,1716583293.795 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2024-05-24T20:41:33.929Z,1716583293.929 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2024-05-24T20:41:34.216Z,1716583294.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-05-24T20:41:34.217Z,1716583294.217 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2024-05-24T20:41:34.330Z,1716583294.330 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2024-05-24T20:41:34.441Z,1716583294.441 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2024-05-24T20:41:34.609Z,1716583294.609 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2024-05-24T20:41:34.729Z,1716583294.729 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2024-05-24T20:41:34.729Z,1716583294.729 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-05-24T20:41:34.740Z,1716583294.740 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-05-24T20:41:34.852Z,1716583294.852 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-05-24T20:41:34.852Z,1716583294.852 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-05-24T20:41:34.905Z,1716583294.905 [VerticalControl](DEBUG): Construct VerticalControl. 2024-05-24T20:41:34.966Z,1716583294.966 [VerticalControl] Loaded 2024-05-24T20:41:34.966Z,1716583294.966 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-05-24T20:41:34.969Z,1716583294.969 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-05-24T20:41:35.017Z,1716583295.017 [HorizontalControl] Loaded 2024-05-24T20:41:35.018Z,1716583295.018 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-05-24T20:41:35.020Z,1716583295.020 [SpeedControl](DEBUG): Construct SpeedControl. 2024-05-24T20:41:35.023Z,1716583295.023 [SpeedControl] Loaded 2024-05-24T20:41:35.024Z,1716583295.024 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-05-24T20:41:35.026Z,1716583295.026 [LoopControl](DEBUG): Construct LoopControl. 2024-05-24T20:41:35.027Z,1716583295.027 [LoopControl] Loaded 2024-05-24T20:41:35.027Z,1716583295.027 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-05-24T20:41:35.028Z,1716583295.028 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-05-24T20:41:35.028Z,1716583295.028 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-05-24T20:41:35.078Z,1716583295.078 [DepthRateCalculator] Loaded 2024-05-24T20:41:35.078Z,1716583295.078 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-05-24T20:41:35.083Z,1716583295.083 [PitchRateCalculator] Loaded 2024-05-24T20:41:35.083Z,1716583295.083 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-05-24T20:41:35.093Z,1716583295.093 [SpeedCalculator] Loaded 2024-05-24T20:41:35.094Z,1716583295.094 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-05-24T20:41:35.098Z,1716583295.098 [YawRateCalculator] Loaded 2024-05-24T20:41:35.098Z,1716583295.098 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-05-24T20:41:35.117Z,1716583295.117 [ElevatorOffsetCalculator] Loaded 2024-05-24T20:41:35.117Z,1716583295.117 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-05-24T20:41:35.118Z,1716583295.118 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-05-24T20:41:35.118Z,1716583295.118 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-05-24T20:41:35.144Z,1716583295.144 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-05-24T20:41:35.145Z,1716583295.145 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-05-24T20:41:35.200Z,1716583295.200 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-05-24T20:41:35.201Z,1716583295.201 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-05-24T20:41:35.535Z,1716583295.535 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-05-24T20:41:35.536Z,1716583295.536 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-05-24T20:41:35.706Z,1716583295.706 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-05-24T20:41:35.707Z,1716583295.707 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-05-24T20:41:36.162Z,1716583296.162 [AHRS_M2] Loaded 2024-05-24T20:41:36.162Z,1716583296.162 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-05-24T20:41:36.201Z,1716583296.201 [BackseatComponent] Loaded 2024-05-24T20:41:36.202Z,1716583296.202 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-05-24T20:41:36.203Z,1716583296.203 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409C34E0 2024-05-24T20:41:36.203Z,1716583296.203 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3220 2024-05-24T20:41:36.206Z,1716583296.206 [LcmUniversalReporter] Loaded 2024-05-24T20:41:36.206Z,1716583296.206 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-05-24T20:41:37.033Z,1716583297.033 [BPC1] Loaded 2024-05-24T20:41:37.033Z,1716583297.033 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-05-24T20:41:37.106Z,1716583297.106 [DataOverHttps] Loaded 2024-05-24T20:41:37.107Z,1716583297.107 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-05-24T20:41:37.108Z,1716583297.108 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409F34E0 2024-05-24T20:41:37.108Z,1716583297.108 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3221 2024-05-24T20:41:37.132Z,1716583297.132 [Depth_Keller] Loaded 2024-05-24T20:41:37.132Z,1716583297.132 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-05-24T20:41:37.137Z,1716583297.137 [DropWeight] Loaded 2024-05-24T20:41:37.137Z,1716583297.137 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-05-24T20:41:37.203Z,1716583297.203 [NAL9602] Loaded 2024-05-24T20:41:37.203Z,1716583297.203 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-05-24T20:41:37.234Z,1716583297.234 [Onboard] Loaded 2024-05-24T20:41:37.234Z,1716583297.234 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-05-24T20:41:37.236Z,1716583297.236 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A234E0 2024-05-24T20:41:37.236Z,1716583297.236 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3222 2024-05-24T20:41:37.250Z,1716583297.250 [Power24vConverter] Loaded 2024-05-24T20:41:37.250Z,1716583297.250 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-05-24T20:41:37.266Z,1716583297.266 [Radio_Surface] Loaded 2024-05-24T20:41:37.266Z,1716583297.266 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-05-24T20:41:37.267Z,1716583297.267 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A534E0 2024-05-24T20:41:37.267Z,1716583297.267 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3223 2024-05-24T20:41:37.316Z,1716583297.316 [RDI_Pathfinder] Loaded 2024-05-24T20:41:37.316Z,1716583297.316 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2024-05-24T20:41:37.413Z,1716583297.413 [DAT] Loaded 2024-05-24T20:41:37.413Z,1716583297.413 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-05-24T20:41:37.414Z,1716583297.414 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A834E0 2024-05-24T20:41:37.415Z,1716583297.415 [DAT ThreadHandler](INFO): Protected caller Thread ID is 3224 2024-05-24T20:41:37.415Z,1716583297.415 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-05-24T20:41:37.416Z,1716583297.416 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-05-24T20:41:37.798Z,1716583297.798 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-05-24T20:41:37.798Z,1716583297.798 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-05-24T20:41:37.812Z,1716583297.812 [NavChart] Loaded 2024-05-24T20:41:37.813Z,1716583297.813 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-05-24T20:41:37.818Z,1716583297.818 [UniversalFixResidualReporter] Loaded 2024-05-24T20:41:37.819Z,1716583297.819 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-05-24T20:41:37.819Z,1716583297.819 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-05-24T20:41:37.820Z,1716583297.820 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-05-24T20:41:38.103Z,1716583298.103 [SBIT](DEBUG): Construct Startup Built In Test. 2024-05-24T20:41:38.112Z,1716583298.112 [SBIT] Loaded 2024-05-24T20:41:38.112Z,1716583298.112 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-05-24T20:41:38.115Z,1716583298.115 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-05-24T20:41:38.128Z,1716583298.128 [IBIT] Loaded 2024-05-24T20:41:38.129Z,1716583298.129 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-05-24T20:41:38.135Z,1716583298.135 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-05-24T20:41:38.243Z,1716583298.243 [CBIT] Loaded 2024-05-24T20:41:38.243Z,1716583298.243 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-05-24T20:41:38.244Z,1716583298.244 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-05-24T20:41:38.244Z,1716583298.244 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-05-24T20:41:38.382Z,1716583298.382 [BuoyancyServo] Loaded 2024-05-24T20:41:38.382Z,1716583298.382 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-05-24T20:41:38.406Z,1716583298.406 [ElevatorServo] Loaded 2024-05-24T20:41:38.406Z,1716583298.406 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-05-24T20:41:38.437Z,1716583298.437 [DockingStepper] Loaded 2024-05-24T20:41:38.437Z,1716583298.437 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2024-05-24T20:41:38.459Z,1716583298.459 [MassServo] Loaded 2024-05-24T20:41:38.460Z,1716583298.460 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-05-24T20:41:38.481Z,1716583298.481 [RudderServo] Loaded 2024-05-24T20:41:38.481Z,1716583298.481 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-05-24T20:41:38.500Z,1716583298.500 [ThrusterServo] Loaded 2024-05-24T20:41:38.500Z,1716583298.500 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2024-05-24T20:41:38.501Z,1716583298.501 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-05-24T20:41:38.501Z,1716583298.501 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-05-24T20:41:38.512Z,1716583298.512 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-05-24T20:41:38.512Z,1716583298.512 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-05-24T20:41:38.691Z,1716583298.691 [CTD_Seabird] Loaded 2024-05-24T20:41:38.691Z,1716583298.691 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-05-24T20:41:38.693Z,1716583298.693 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0 2024-05-24T20:41:38.693Z,1716583298.693 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3225 2024-05-24T20:41:38.716Z,1716583298.716 [PAR_Licor] Loaded 2024-05-24T20:41:38.716Z,1716583298.716 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-05-24T20:41:38.763Z,1716583298.763 [WetLabsBB2FL] Loaded 2024-05-24T20:41:38.763Z,1716583298.763 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-05-24T20:41:38.764Z,1716583298.764 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0 2024-05-24T20:41:38.765Z,1716583298.765 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3226 2024-05-24T20:41:38.786Z,1716583298.786 [WetLabsUBAT] Loaded 2024-05-24T20:41:38.787Z,1716583298.787 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2024-05-24T20:41:38.788Z,1716583298.788 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C374E0 2024-05-24T20:41:38.788Z,1716583298.788 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 3227 2024-05-24T20:41:38.789Z,1716583298.789 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-05-24T20:41:38.795Z,1716583298.795 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-05-24T20:41:38.798Z,1716583298.798 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-05-24T20:41:38.810Z,1716583298.810 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-05-24T20:41:38.811Z,1716583298.811 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C674E0 2024-05-24T20:41:38.811Z,1716583298.811 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3228 2024-05-24T20:41:38.816Z,1716583298.816 [Supervisor](INFO): Main Thread ID is 2822 2024-05-24T20:41:38.816Z,1716583298.816 [Supervisor](DEBUG): Running supervisor. 2024-05-24T20:41:38.817Z,1716583298.817 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3229 2024-05-24T20:41:38.817Z,1716583298.817 [CommandExec](INFO): Initializing the command executive. 2024-05-24T20:41:38.819Z,1716583298.819 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3230 2024-05-24T20:41:38.822Z,1716583298.822 [controlThread ThreadHandler](INFO): Handler Thread ID is 3231 2024-05-24T20:41:38.822Z,1716583298.822 [controlThread](DEBUG): Initializing ControlThread 2024-05-24T20:41:38.823Z,1716583298.823 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-05-24T20:41:38.825Z,1716583298.825 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-05-24T20:41:38.825Z,1716583298.825 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-05-24T20:41:38.826Z,1716583298.826 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-05-24T20:41:38.826Z,1716583298.826 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-05-24T20:41:38.826Z,1716583298.826 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-05-24T20:41:38.827Z,1716583298.827 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-05-24T20:41:38.827Z,1716583298.827 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-05-24T20:41:38.827Z,1716583298.827 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-05-24T20:41:38.834Z,1716583298.834 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-05-24T20:41:38.834Z,1716583298.834 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-05-24T20:41:38.834Z,1716583298.834 [SBIT](INFO): Initialize SBIT Component. 2024-05-24T20:41:38.835Z,1716583298.835 [SBIT](IMPORTANT): git: 2024-05-22-2-gd2983461b 2024-05-24T20:41:38.835Z,1716583298.835 [SBIT](INFO): git hash: d2983461b0afca1bb4c5ae8a9b5bc51c90c2e86f 2024-05-24T20:41:38.835Z,1716583298.835 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-05-24T20:41:38.836Z,1716583298.836 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-05-24T20:41:38.837Z,1716583298.837 [SBIT](INFO): Beginning SBIT in 139.000000 seconds. 2024-05-24T20:41:38.838Z,1716583298.838 [IBIT](INFO): Initialize IBIT Component. 2024-05-24T20:41:38.839Z,1716583298.839 [CBIT](DEBUG): Initialize CBIT Component. 2024-05-24T20:41:38.840Z,1716583298.840 [logger ThreadHandler](INFO): Handler Thread ID is 3232 2024-05-24T20:41:38.851Z,1716583298.851 [CBIT](DEBUG): Initialized mux pins. 2024-05-24T20:41:38.851Z,1716583298.851 [CBIT](DEBUG): Initializing the watchdog timer. 2024-05-24T20:41:38.860Z,1716583298.860 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3233 2024-05-24T20:41:38.872Z,1716583298.872 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3234 2024-05-24T20:41:38.873Z,1716583298.873 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-05-24T20:41:38.875Z,1716583298.875 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-05-24T20:41:38.876Z,1716583298.876 [CBIT](DEBUG): Initializing heartbeat. 2024-05-24T20:41:38.884Z,1716583298.884 [Onboard ThreadHandler](INFO): Handler Thread ID is 3235 2024-05-24T20:41:38.901Z,1716583298.901 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3236 2024-05-24T20:41:38.931Z,1716583298.931 [DAT ThreadHandler](INFO): Handler Thread ID is 3237 2024-05-24T20:41:38.932Z,1716583298.932 [DAT](INFO): Powering up 2024-05-24T20:41:38.932Z,1716583298.932 [DAT](DEBUG): Initializing DAT. 2024-05-24T20:41:38.940Z,1716583298.940 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3239 2024-05-24T20:41:38.940Z,1716583298.940 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-05-24T20:41:38.944Z,1716583298.944 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3241 2024-05-24T20:41:38.947Z,1716583298.947 [CBIT](DEBUG): Deactivating GF circuits. 2024-05-24T20:41:38.947Z,1716583298.947 [CBIT](DEBUG): Deactivating emergency mode. 2024-05-24T20:41:38.952Z,1716583298.952 [WetLabsBB2FL](INFO): Powering up 2024-05-24T20:41:38.953Z,1716583298.953 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 3243 2024-05-24T20:41:38.956Z,1716583298.956 [WetLabsUBAT](INFO): Powering up 2024-05-24T20:41:38.957Z,1716583298.957 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3245 2024-05-24T20:41:38.960Z,1716583298.960 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-05-24T20:41:38.960Z,1716583298.960 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-05-24T20:41:38.961Z,1716583298.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-05-24T20:41:38.961Z,1716583298.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-05-24T20:41:38.961Z,1716583298.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-05-24T20:41:38.961Z,1716583298.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-05-24T20:41:38.961Z,1716583298.961 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-05-24T20:41:38.961Z,1716583298.961 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-05-24T20:41:38.987Z,1716583298.987 [CBIT](DEBUG): Backplane powered. 2024-05-24T20:41:38.992Z,1716583298.992 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-05-24T20:41:38.993Z,1716583298.993 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl 2024-05-24T20:41:38.993Z,1716583298.993 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl 2024-05-24T20:41:38.995Z,1716583298.995 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl 2024-05-24T20:41:39.001Z,1716583299.001 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into: 2024-05-24T20:41:39.001Z,1716583299.001 [MissionManager](ERROR): Top node has no child nodes: Mission 2024-05-24T20:41:39.001Z,1716583299.001 [MissionManager](ERROR): Could not load startup mission. 2024-05-24T20:41:39.001Z,1716583299.001 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl 2024-05-24T20:41:39.002Z,1716583299.002 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl 2024-05-24T20:41:39.004Z,1716583299.004 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl 2024-05-24T20:41:39.307Z,1716583299.307 [Radio_Surface](INFO): Powering up 2024-05-24T20:41:39.514Z,1716583299.514 [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-05-24T20:41:39.554Z,1716583299.554 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min 2024-05-24T20:41:39.558Z,1716583299.558 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 30.000000 min 2024-05-24T20:41:39.578Z,1716583299.578 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 201 count 2024-05-24T20:41:39.582Z,1716583299.582 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 2.500000 s 2024-05-24T20:41:39.597Z,1716583299.597 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count 2024-05-24T20:41:39.599Z,1716583299.599 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait. 2024-05-24T20:41:39.748Z,1716583299.748 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct. 2024-05-24T20:41:39.748Z,1716583299.748 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2024-05-24T20:41:39.796Z,1716583299.796 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-05-24T20:41:39.844Z,1716583299.844 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-05-24T20:41:39.847Z,1716583299.847 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-05-24T20:41:39.873Z,1716583299.873 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute. 2024-05-24T20:41:39.878Z,1716583299.878 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission DefaultWithUndock { arguments { ElapsedSinceDefaultStarted = 0 minute DetachTimeout = 30 minute """ Maximum time duration for the vehicle to detach form the dock. """ TransponderCode = Dock:Dock.transponderCode """ Dock transponder address. """ TrackingUpdatePeriod = 2.5 second """ Time duration between acoustic range queries while the mission is detaching from the dock. """ NumberOfPings = 1 count """ Number of pings requested each time. """ } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 30 second } aggregate LeaveDock { run in sequence timeout duration=DetachTimeout { syslog important "Timed out while trying to detach from dock at current depth of " + Universal:depth~meter + "." } # Acoustic tracking directives behavior Estimation:TrackAcousticContact { run in parallel set contactLabelSetting = TransponderCode set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } # Leave the dock behavior Dock:Undock { run in sequence } } aggregate SurfaceDefault { run in sequence # This mission assumes open water so from here on it's the same old default that's always used behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P2H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-05-24T20:41:39.883Z,1716583299.883 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-05-24T20:41:39.903Z,1716583299.903 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-05-24T20:41:39.969Z,1716583299.969 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993022 m 2024-05-24T20:41:39.970Z,1716583299.970 [Power24vConverter](INFO): Powering up. 2024-05-24T20:41:39.971Z,1716583299.971 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2024-05-24T20:41:40.036Z,1716583300.036 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-05-24T20:41:40.064Z,1716583300.064 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-05-24T20:41:40.072Z,1716583300.072 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-05-24T20:41:40.073Z,1716583300.073 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-05-24T20:41:40.079Z,1716583300.079 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-05-24T20:41:40.080Z,1716583300.080 [DockingStepper](INFO): Start 2024-05-24T20:41:40.080Z,1716583300.080 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-24T20:41:40.087Z,1716583300.087 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-24T20:41:40.088Z,1716583300.088 [MassServo](DEBUG): Initializing EZServoServo. 2024-05-24T20:41:40.096Z,1716583300.096 [MassServo](DEBUG): Initializing MassServo. 2024-05-24T20:41:40.096Z,1716583300.096 [RudderServo](DEBUG): Initializing EZServoServo. 2024-05-24T20:41:40.103Z,1716583300.103 [RudderServo](DEBUG): Initializing RudderServo. 2024-05-24T20:41:40.104Z,1716583300.104 [ThrusterServo](DEBUG): Initializing EZServoServo. 2024-05-24T20:41:40.111Z,1716583300.111 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2024-05-24T20:41:40.368Z,1716583300.368 [DockingStepper](INFO): Starting 2024-05-24T20:41:40.711Z,1716583300.711 [DockingStepper](INFO): Starting 2024-05-24T20:41:41.251Z,1716583301.251 [DockingStepper](INFO): Starting 2024-05-24T20:41:41.304Z,1716583301.304 [DockingStepper](INFO): Init string: /1z16368aE13561N3m50 2024-05-24T20:41:41.559Z,1716583301.559 [WetLabsBB2FL](INFO): Powering down 2024-05-24T20:41:41.576Z,1716583301.576 [DockingStepper](INFO): Starting 2024-05-24T20:41:41.934Z,1716583301.934 [DockingStepper](INFO): Starting 2024-05-24T20:41:42.335Z,1716583302.335 [DockingStepper](INFO): Starting 2024-05-24T20:41:42.788Z,1716583302.788 [DockingStepper](INFO): Starting 2024-05-24T20:41:43.269Z,1716583303.269 [DockingStepper](INFO): Starting 2024-05-24T20:41:43.295Z,1716583303.295 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-24T20:41:43.633Z,1716583303.633 [DockingStepper](INFO): Starting 2024-05-24T20:41:44.032Z,1716583304.032 [DockingStepper](INFO): Commanding negative:9038 2024-05-24T20:41:45.435Z,1716583305.435 [DockingStepper](INFO): Pause 2024-05-24T20:41:45.972Z,1716583305.972 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-05-24T20:41:48.215Z,1716583308.215 [WetLabsUBAT](INFO): Powering down 2024-05-24T20:41:51.536Z,1716583311.536 [DAT](INFO): DAT read: 2024-05-24T20:41:51.537Z,1716583311.537 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-05-24T20:41:53.300Z,1716583313.300 [DAT](INFO): DAT read: MF Frequency Band 2024-05-24T20:41:53.301Z,1716583313.301 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2 2024-05-24T20:41:53.302Z,1716583313.302 [DAT](INFO): DAT read: May 24 2024 20:41:46 2024-05-24T20:41:54.309Z,1716583314.309 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-05-24T20:41:54.310Z,1716583314.310 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-05-24T20:41:54.310Z,1716583314.310 [DAT](INFO): commRate: 800 2024-05-24T20:41:56.375Z,1716583316.375 [DAT](INFO): entering command mode 2024-05-24T20:41:56.576Z,1716583316.576 [DAT](INFO): DAT read: 2024-05-24T20:41:56.576Z,1716583316.576 [DAT](INFO): DAT read: user:1> 2024-05-24T20:41:56.577Z,1716583316.577 [DAT](INFO): setting verbose to 3 2024-05-24T20:41:56.828Z,1716583316.828 [DAT](INFO): DAT read: user:1> 2024-05-24T20:41:56.829Z,1716583316.829 [DAT](INFO): DAT read: Verbose | 3 2024-05-24T20:41:56.829Z,1716583316.829 [DAT](INFO): set verbose to 3 2024-05-24T20:41:56.829Z,1716583316.829 [DAT](INFO): setting DatVerbose to 27440 2024-05-24T20:41:57.080Z,1716583317.080 [DAT](INFO): DAT read: user:2> 2024-05-24T20:41:57.081Z,1716583317.081 [DAT](INFO): DAT read: DatVerbose | 27440 2024-05-24T20:41:57.081Z,1716583317.081 [DAT](INFO): set DatVerbose to 27440 2024-05-24T20:41:57.081Z,1716583317.081 [DAT](INFO): setting transmit power to 8 2024-05-24T20:41:57.332Z,1716583317.332 [DAT](INFO): DAT read: user:3> 2024-05-24T20:41:57.333Z,1716583317.333 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-05-24T20:41:57.333Z,1716583317.333 [DAT](INFO): set transmit power to 8 2024-05-24T20:41:57.333Z,1716583317.333 [DAT](INFO): setting local address to 10 2024-05-24T20:41:57.584Z,1716583317.584 [DAT](INFO): DAT read: user:4> 2024-05-24T20:41:57.585Z,1716583317.585 [DAT](INFO): DAT read: LocalAddr | 10 2024-05-24T20:41:57.585Z,1716583317.585 [DAT](INFO): set local address to 10 2024-05-24T20:41:57.586Z,1716583317.586 [DAT](INFO): Setting time to: 20:41:57 And date to:5/24/2024 2024-05-24T20:41:57.836Z,1716583317.836 [DAT](INFO): DAT read: user:5> 2024-05-24T20:41:57.837Z,1716583317.837 [DAT](INFO): DAT read: Fri May 24, 2024 20:41:57 2024-05-24T20:41:57.837Z,1716583317.837 [DAT](INFO): Local DAT time set to Fri May 24, 2024 20:41:57 2024-05-24T20:42:07.553Z,1716583327.553 [NAL9602](INFO): Powering up NAL9602 2024-05-24T20:42:18.477Z,1716583338.477 [NAL9602](INFO): NAL9602 initialized 2024-05-24T20:42:19.760Z,1716583339.760 [CommandExec](IMPORTANT): got command docking arm 2024-05-24T20:42:19.760Z,1716583339.760 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-05-24T20:42:20.524Z,1716583340.524 [DockingStepper](INFO): Resume 2024-05-24T20:42:20.927Z,1716583340.927 [DockingStepper](INFO): Resuming 2024-05-24T20:42:21.320Z,1716583341.320 [DockingStepper](INFO): Start 2024-05-24T20:42:21.321Z,1716583341.321 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-24T20:42:21.436Z,1716583341.436 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-24T20:42:21.784Z,1716583341.784 [DockingStepper](INFO): Starting 2024-05-24T20:42:22.141Z,1716583342.141 [DockingStepper](INFO): Starting 2024-05-24T20:42:22.552Z,1716583342.552 [DockingStepper](INFO): Starting 2024-05-24T20:42:22.604Z,1716583342.604 [DockingStepper](INFO): Init string: /1z14959aE13561N3m50 2024-05-24T20:42:22.936Z,1716583342.936 [DockingStepper](INFO): Starting 2024-05-24T20:42:23.360Z,1716583343.360 [DockingStepper](INFO): Starting 2024-05-24T20:42:23.767Z,1716583343.767 [DockingStepper](INFO): Starting 2024-05-24T20:42:24.162Z,1716583344.162 [DockingStepper](INFO): Starting 2024-05-24T20:42:24.195Z,1716583344.195 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-24T20:42:24.545Z,1716583344.545 [DockingStepper](INFO): Starting 2024-05-24T20:42:24.958Z,1716583344.958 [DockingStepper](INFO): Changing to mode: 2 2024-05-24T20:42:24.958Z,1716583344.958 [DockingStepper](INFO): Armed mode. 2024-05-24T20:42:25.012Z,1716583345.012 [DockingStepper](INFO): Commanding negative:45924 2024-05-24T20:42:30.196Z,1716583350.196 [CommandExec](IMPORTANT): got command docking standby 2024-05-24T20:42:30.196Z,1716583350.196 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-05-24T20:42:30.237Z,1716583350.237 [DockingStepper](INFO): Changing to mode: 1 2024-05-24T20:42:30.237Z,1716583350.237 [DockingStepper](INFO): Standby mode. 2024-05-24T20:42:30.280Z,1716583350.280 [DockingStepper](INFO): Commanding positive:54900 2024-05-24T20:42:46.596Z,1716583366.596 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-24T20:42:59.201Z,1716583379.201 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-24T20:43:07.977Z,1716583387.977 [CommandExec](IMPORTANT): got command docking arm 2024-05-24T20:43:07.977Z,1716583387.977 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-05-24T20:43:08.201Z,1716583388.201 [DockingStepper](INFO): Changing to mode: 2 2024-05-24T20:43:08.202Z,1716583388.202 [DockingStepper](INFO): Armed mode. 2024-05-24T20:43:08.248Z,1716583388.248 [DockingStepper](INFO): Commanding negative:54402 2024-05-24T20:43:11.584Z,1716583391.584 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-24T20:43:21.612Z,1716583401.612 [CommandExec](IMPORTANT): got command docking standby 2024-05-24T20:43:21.612Z,1716583401.612 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-05-24T20:43:21.724Z,1716583401.724 [DockingStepper](INFO): Changing to mode: 1 2024-05-24T20:43:21.725Z,1716583401.725 [DockingStepper](INFO): Standby mode. 2024-05-24T20:43:21.768Z,1716583401.768 [DockingStepper](INFO): Commanding positive:54506 2024-05-24T20:43:25.101Z,1716583405.101 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-24T20:43:36.392Z,1716583416.392 [CommandExec](IMPORTANT): got command docking arm 2024-05-24T20:43:36.393Z,1716583416.393 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-05-24T20:43:36.635Z,1716583416.635 [DockingStepper](INFO): Changing to mode: 2 2024-05-24T20:43:36.635Z,1716583416.635 [DockingStepper](INFO): Armed mode. 2024-05-24T20:43:36.680Z,1716583416.680 [DockingStepper](INFO): Commanding negative:54593 2024-05-24T20:43:38.225Z,1716583418.225 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-24T20:43:47.108Z,1716583427.108 [CommandExec](IMPORTANT): got command docking standby 2024-05-24T20:43:47.108Z,1716583427.108 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-05-24T20:43:47.218Z,1716583427.218 [DockingStepper](INFO): Changing to mode: 1 2024-05-24T20:43:47.218Z,1716583427.218 [DockingStepper](INFO): Standby mode. 2024-05-24T20:43:47.268Z,1716583427.268 [DockingStepper](INFO): Commanding positive:54599 2024-05-24T20:43:51.396Z,1716583431.396 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-24T20:43:57.973Z,1716583437.973 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +0.00, 0.00,128.00 2024-05-24T20:43:58.453Z,1716583438.453 [CommandExec](IMPORTANT): got command show stack 2024-05-24T20:43:58.453Z,1716583438.453 [CommandExec](IMPORTANT): Behavior Stack: 2024-05-24T20:43:58.453Z,1716583438.453 [MissionManager](IMPORTANT): Mission loaded, but not running. 2024-05-24T20:43:58.518Z,1716583438.518 [SBIT](IMPORTANT): Beginning Startup BIT 2024-05-24T20:43:58.522Z,1716583438.522 [CBIT](IMPORTANT): Beginning ground fault scan 2024-05-24T20:44:02.509Z,1716583442.509 [CommandExec](IMPORTANT): got command docking arm 2024-05-24T20:44:02.509Z,1716583442.509 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-05-24T20:44:02.814Z,1716583442.814 [DockingStepper](INFO): Changing to mode: 2 2024-05-24T20:44:02.814Z,1716583442.814 [DockingStepper](INFO): Armed mode. 2024-05-24T20:44:02.862Z,1716583442.862 [DockingStepper](INFO): Commanding negative:54593 2024-05-24T20:44:04.005Z,1716583444.005 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-24T20:44:07.397Z,1716583447.397 [CommandExec](IMPORTANT): got command docking standby 2024-05-24T20:44:07.397Z,1716583447.397 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-05-24T20:44:07.746Z,1716583447.746 [DockingStepper](INFO): Changing to mode: 1 2024-05-24T20:44:07.746Z,1716583447.746 [DockingStepper](INFO): Standby mode. 2024-05-24T20:44:08.527Z,1716583448.527 [DockingStepper](INFO): Commanding positive:54599 2024-05-24T20:44:09.639Z,1716583449.639 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005707 CHAN A1 (24V): 0.005875 CHAN A2 (12V): -0.005756 CHAN A3 (5V): -0.001063 CHAN B0 (3.3V): 0.000659 CHAN B1 (3.15aV): 0.000319 CHAN B2 (3.15bV): 0.000508 CHAN B3 (GND): 0.001136 OPEN: 0.005226 Full Scale: +/- 1 mA 2024-05-24T20:44:13.220Z,1716583453.220 [CommandExec](IMPORTANT): got command docking standby 2024-05-24T20:44:13.221Z,1716583453.221 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-05-24T20:44:17.141Z,1716583457.141 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-24T20:44:17.800Z,1716583457.800 [CommandExec](IMPORTANT): got command docking arm 2024-05-24T20:44:17.800Z,1716583457.800 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-05-24T20:44:17.996Z,1716583457.996 [DockingStepper](INFO): Changing to mode: 2 2024-05-24T20:44:17.996Z,1716583457.996 [DockingStepper](INFO): Armed mode. 2024-05-24T20:44:18.048Z,1716583458.048 [DockingStepper](INFO): Commanding negative:54599 2024-05-24T20:44:25.188Z,1716583465.188 [CommandExec](IMPORTANT): got command docking standby 2024-05-24T20:44:25.188Z,1716583465.188 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-05-24T20:44:25.277Z,1716583465.277 [DockingStepper](INFO): Changing to mode: 1 2024-05-24T20:44:25.277Z,1716583465.277 [DockingStepper](INFO): Standby mode. 2024-05-24T20:44:25.327Z,1716583465.327 [DockingStepper](INFO): Commanding positive:54599 2024-05-24T20:44:30.580Z,1716583470.580 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-05-24T20:44:33.408Z,1716583473.408 [CommandExec](IMPORTANT): got command docking arm 2024-05-24T20:44:33.408Z,1716583473.408 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-05-24T20:44:33.457Z,1716583473.457 [DockingStepper](INFO): Changing to mode: 2 2024-05-24T20:44:33.457Z,1716583473.457 [DockingStepper](INFO): Armed mode. 2024-05-24T20:44:33.507Z,1716583473.507 [DockingStepper](INFO): Commanding negative:54593 2024-05-24T20:44:37.889Z,1716583477.889 [CommandExec](IMPORTANT): got command docking standby 2024-05-24T20:44:37.889Z,1716583477.889 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-05-24T20:44:38.091Z,1716583478.091 [DockingStepper](INFO): Changing to mode: 1 2024-05-24T20:44:38.091Z,1716583478.091 [DockingStepper](INFO): Standby mode. 2024-05-24T20:44:38.891Z,1716583478.891 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-05-24T20:44:38.891Z,1716583478.891 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-05-24T20:44:39.086Z,1716583479.086 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-05-24T20:44:39.355Z,1716583479.355 [DockingStepper](INFO): Commanding positive:54599 2024-05-24T20:44:39.473Z,1716583479.473 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-05-24T20:44:39.473Z,1716583479.473 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-05-24T20:44:42.595Z,1716583482.595 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004823 2024-05-24T20:44:51.641Z,1716583491.641 [SBIT](IMPORTANT): SBIT PASSED 2024-05-24T20:44:51.641Z,1716583491.641 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-05-24T20:44:51.642Z,1716583491.642 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2024-05-24T20:44:51.642Z,1716583491.642 [SBIT](IMPORTANT): Docked.detachTimeout=8 hour; 2024-05-24T20:44:51.642Z,1716583491.642 [SBIT](IMPORTANT): DockingStepper.closedValue=16500 count; 2024-05-24T20:44:51.642Z,1716583491.642 [SBIT](IMPORTANT): DockingStepper.loadAtStartup=1 bool; 2024-05-24T20:44:51.642Z,1716583491.642 [SBIT](IMPORTANT): DockingStepper.openValue=7500 count; 2024-05-24T20:44:51.642Z,1716583491.642 [SBIT](IMPORTANT): DockingStepper.simulateHardware=0 bool; 2024-05-24T20:44:51.643Z,1716583491.643 [SBIT](IMPORTANT): DockingStepper.slideValue=12000 count; 2024-05-24T20:44:51.643Z,1716583491.643 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second; 2024-05-24T20:44:51.643Z,1716583491.643 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=0 bool; 2024-05-24T20:44:51.643Z,1716583491.643 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=300 cubic_centimeter; 2024-05-24T20:44:51.643Z,1716583491.643 [SBIT](IMPORTANT): VerticalControl.massDefault=30 millimeter; 2024-05-24T20:44:51.942Z,1716583491.942 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2024-05-24T20:44:51.942Z,1716583491.942 [DefaultWithUndock] Running Loop=1 2024-05-24T20:44:51.942Z,1716583491.942 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2024-05-24T20:44:51.942Z,1716583491.942 [DefaultWithUndock:A.Wait] Running Loop=1 2024-05-24T20:44:51.942Z,1716583491.942 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2024-05-24T20:45:03.603Z,1716583503.603 [CommandExec](IMPORTANT): got command docking arm 2024-05-24T20:45:03.604Z,1716583503.604 [CommandExec](IMPORTANT): Switching docking moudle to ARM. 2024-05-24T20:45:03.768Z,1716583503.768 [DockingStepper](INFO): Changing to mode: 2 2024-05-24T20:45:03.769Z,1716583503.769 [DockingStepper](INFO): Armed mode. 2024-05-24T20:45:03.819Z,1716583503.819 [DockingStepper](INFO): Commanding negative:54593 2024-05-24T20:45:12.648Z,1716583512.648 [CommandExec](IMPORTANT): got command docking standby 2024-05-24T20:45:12.649Z,1716583512.649 [CommandExec](IMPORTANT): Switching docking moudle to STANDBY. 2024-05-24T20:45:12.728Z,1716583512.728 [DockingStepper](INFO): Changing to mode: 1 2024-05-24T20:45:12.728Z,1716583512.728 [DockingStepper](INFO): Standby mode. 2024-05-24T20:45:12.770Z,1716583512.770 [DockingStepper](INFO): Commanding positive:54599 2024-05-24T20:45:13.084Z,1716583513.084 [DockingStepper](FAULT): uart error: serial timeout moving from: 7632 to: 16500 response: 2024-05-24T20:45:13.387Z,1716583513.387 [DockingStepper](FAULT): reading after fault: 2024-05-24T20:45:13.387Z,1716583513.387 [DockingStepper] Communications Fault, FailCount= 1 2024-05-24T20:45:13.387Z,1716583513.387 [DockingStepper](ERROR): Communications Fault 2024-05-24T20:45:13.392Z,1716583513.392 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-24T20:45:13.486Z,1716583513.486 [DockingStepper](INFO): Stop 2024-05-24T20:45:13.486Z,1716583513.486 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-24T20:45:13.486Z,1716583513.486 [DockingStepper](INFO): Powering down 2024-05-24T20:45:13.874Z,1716583513.874 [DockingStepper](INFO): Stopping 2024-05-24T20:45:14.245Z,1716583514.245 [DockingStepper](INFO): Stopped 2024-05-24T20:45:14.274Z,1716583514.274 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-24T20:45:14.274Z,1716583514.274 [DockingStepper] No Fault, FailCount= 1 2024-05-24T20:45:14.749Z,1716583514.749 [DockingStepper](INFO): Start 2024-05-24T20:45:14.750Z,1716583514.750 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-24T20:45:14.868Z,1716583514.868 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-24T20:45:15.066Z,1716583515.066 [DockingStepper](INFO): Starting 2024-05-24T20:45:15.466Z,1716583515.466 [DockingStepper](INFO): Starting 2024-05-24T20:45:15.905Z,1716583515.905 [DockingStepper](INFO): Starting 2024-05-24T20:45:15.952Z,1716583515.952 [DockingStepper](INFO): Init string: /1z7631aE13561N3m50 2024-05-24T20:45:16.266Z,1716583516.266 [DockingStepper](INFO): Starting 2024-05-24T20:45:16.681Z,1716583516.681 [DockingStepper](INFO): Starting 2024-05-24T20:45:17.073Z,1716583517.073 [DockingStepper](INFO): Starting 2024-05-24T20:45:17.465Z,1716583517.465 [DockingStepper](INFO): Starting 2024-05-24T20:45:17.491Z,1716583517.491 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-24T20:45:17.890Z,1716583517.890 [DockingStepper](INFO): Starting 2024-05-24T20:45:18.270Z,1716583518.270 [DockingStepper](INFO): Changing to mode: 1 2024-05-24T20:45:18.270Z,1716583518.270 [DockingStepper](INFO): Standby mode. 2024-05-24T20:45:18.319Z,1716583518.319 [DockingStepper](INFO): Commanding positive:54506 2024-05-24T20:45:22.036Z,1716583522.036 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2024-05-24T20:45:22.036Z,1716583522.036 [DefaultWithUndock:A.Wait] Stopped 2024-05-24T20:45:22.036Z,1716583522.036 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2024-05-24T20:45:22.037Z,1716583522.037 [DefaultWithUndock:LeaveDock] Running Loop=1 2024-05-24T20:45:22.037Z,1716583522.037 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2024-05-24T20:45:22.037Z,1716583522.037 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2024-05-24T20:45:22.037Z,1716583522.037 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2024-05-24T20:45:22.037Z,1716583522.037 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2024-05-24T20:45:22.037Z,1716583522.037 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2024-05-24T20:45:22.037Z,1716583522.037 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2024-05-24T20:45:22.505Z,1716583522.505 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2024-05-24T20:45:22.512Z,1716583522.512 [DockingStepper](INFO): Changing to mode: 3 2024-05-24T20:45:22.512Z,1716583522.512 [DockingStepper](INFO): Detach mode. 2024-05-24T20:45:24.247Z,1716583524.247 [DockingStepper](INFO): Commanding negative:54414 2024-05-24T20:45:24.766Z,1716583524.766 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:45:24.784Z,1716583524.784 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:24.785Z,1716583524.785 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:24.785Z,1716583524.785 [DAT](INFO): setting remote address to 0 2024-05-24T20:45:25.036Z,1716583525.036 [DAT](INFO): DAT read: user:6> 2024-05-24T20:45:25.037Z,1716583525.037 [DAT](INFO): DAT read: RemoteAddr | 0 2024-05-24T20:45:25.037Z,1716583525.037 [DAT](INFO): set remote address to 0 2024-05-24T20:45:25.038Z,1716583525.038 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:25.038Z,1716583525.038 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:25.038Z,1716583525.038 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:25.288Z,1716583525.288 [DAT](INFO): DAT read: user:7> 2024-05-24T20:45:25.289Z,1716583525.289 [DAT](INFO): DAT read: Tx time:20:45:24.5329 2024-05-24T20:45:25.289Z,1716583525.289 [DAT](INFO): Ping request sent. 2024-05-24T20:45:25.289Z,1716583525.289 [DAT](INFO): transmitted an acoustic signal 2024-05-24T20:45:25.289Z,1716583525.289 [DAT](INFO): publishing transmit ping time 2024-05-24T20:45:25.290Z,1716583525.290 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000906 2024-05-24T20:45:25.540Z,1716583525.540 [DAT](INFO): DAT read: TxSync time:20:45:24.5321 2024-05-24T20:45:25.541Z,1716583525.541 [DAT](INFO): unknown deviceResponse_: TxSync time:20:45:24.5321 2024-05-24T20:45:25.541Z,1716583525.541 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251897 2024-05-24T20:45:25.792Z,1716583525.792 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502920 2024-05-24T20:45:26.044Z,1716583526.044 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755236 2024-05-24T20:45:26.296Z,1716583526.296 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006784 2024-05-24T20:45:26.548Z,1716583526.548 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258892 2024-05-24T20:45:26.800Z,1716583526.800 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511017 2024-05-24T20:45:27.052Z,1716583527.052 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763092 2024-05-24T20:45:27.304Z,1716583527.304 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014982 2024-05-24T20:45:27.533Z,1716583527.533 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:45:27.556Z,1716583527.556 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267107 2024-05-24T20:45:27.812Z,1716583527.812 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.521822 2024-05-24T20:45:28.060Z,1716583528.060 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770903 2024-05-24T20:45:28.312Z,1716583528.312 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022764 2024-05-24T20:45:28.564Z,1716583528.564 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274794 2024-05-24T20:45:28.816Z,1716583528.816 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526865 2024-05-24T20:45:29.068Z,1716583529.068 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778821 2024-05-24T20:45:29.322Z,1716583529.322 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.032535 2024-05-24T20:45:29.575Z,1716583529.575 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.285166 2024-05-24T20:45:29.825Z,1716583529.825 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.536048 2024-05-24T20:45:30.076Z,1716583530.076 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.787116 2024-05-24T20:45:30.124Z,1716583530.124 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock. 2024-05-24T20:45:30.125Z,1716583530.125 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:45:30.333Z,1716583530.333 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.042968 2024-05-24T20:45:30.529Z,1716583530.529 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2024-05-24T20:45:30.580Z,1716583530.580 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290966 2024-05-24T20:45:30.832Z,1716583530.832 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.543054 2024-05-24T20:45:31.084Z,1716583531.084 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.795098 2024-05-24T20:45:31.336Z,1716583531.336 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.047105 2024-05-24T20:45:31.590Z,1716583531.590 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.300875 2024-05-24T20:45:31.841Z,1716583531.841 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.551216 2024-05-24T20:45:32.092Z,1716583532.092 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802736 2024-05-24T20:45:32.345Z,1716583532.345 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.055625 2024-05-24T20:45:32.596Z,1716583532.596 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306915 2024-05-24T20:45:32.848Z,1716583532.848 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558820 2024-05-24T20:45:32.905Z,1716583532.905 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:45:33.102Z,1716583533.102 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.813266 2024-05-24T20:45:33.352Z,1716583533.352 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062846 2024-05-24T20:45:33.606Z,1716583533.606 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.316427 2024-05-24T20:45:33.856Z,1716583533.856 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566855 2024-05-24T20:45:34.111Z,1716583534.111 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.821365 2024-05-24T20:45:34.360Z,1716583534.360 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.071153 2024-05-24T20:45:34.613Z,1716583534.613 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.323449 2024-05-24T20:45:34.864Z,1716583534.864 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.575104 2024-05-24T20:45:35.116Z,1716583535.116 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826762 2024-05-24T20:45:35.045Z,1716583535.045 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.756141 2024-05-24T20:45:35.298Z,1716583535.298 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:35.298Z,1716583535.298 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:35.299Z,1716583535.299 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:35.439Z,1716583535.439 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:45:35.550Z,1716583535.550 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:35.550Z,1716583535.550 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:35.550Z,1716583535.550 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:35.801Z,1716583535.801 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:35.801Z,1716583535.801 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:35.801Z,1716583535.801 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:36.053Z,1716583536.053 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:36.053Z,1716583536.053 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:36.053Z,1716583536.053 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:36.305Z,1716583536.305 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:36.306Z,1716583536.306 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:36.306Z,1716583536.306 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:36.557Z,1716583536.557 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:36.557Z,1716583536.557 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:36.557Z,1716583536.557 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:36.809Z,1716583536.809 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:36.809Z,1716583536.809 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:36.809Z,1716583536.809 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:37.061Z,1716583537.061 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:37.061Z,1716583537.061 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:37.061Z,1716583537.061 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:37.314Z,1716583537.314 [DAT](INFO): DAT read: Response Not Received 2024-05-24T20:45:37.314Z,1716583537.314 [DAT](INFO): response not received 2024-05-24T20:45:37.314Z,1716583537.314 [DAT](INFO): DAT read: user:8> 2024-05-24T20:45:37.315Z,1716583537.315 [DAT](INFO): DAT read: Tx time:20:45:36.9831 2024-05-24T20:45:37.315Z,1716583537.315 [DAT](INFO): Ping request sent. 2024-05-24T20:45:37.315Z,1716583537.315 [DAT](INFO): transmitted an acoustic signal 2024-05-24T20:45:37.316Z,1716583537.316 [DAT](INFO): publishing transmit ping time 2024-05-24T20:45:37.316Z,1716583537.316 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000838 2024-05-24T20:45:37.565Z,1716583537.565 [DAT](INFO): DAT read: TxSync time:20:45:36.9823 2024-05-24T20:45:37.566Z,1716583537.566 [DAT](INFO): unknown deviceResponse_: TxSync time:20:45:36.9823 2024-05-24T20:45:37.566Z,1716583537.566 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250582 2024-05-24T20:45:37.817Z,1716583537.817 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501634 2024-05-24T20:45:38.069Z,1716583538.069 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753677 2024-05-24T20:45:38.251Z,1716583538.251 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:45:38.321Z,1716583538.321 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005917 2024-05-24T20:45:38.573Z,1716583538.573 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.257570 2024-05-24T20:45:38.829Z,1716583538.829 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.512870 2024-05-24T20:45:39.077Z,1716583539.077 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.761508 2024-05-24T20:45:39.329Z,1716583539.329 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013808 2024-05-24T20:45:39.581Z,1716583539.581 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.265765 2024-05-24T20:45:39.833Z,1716583539.833 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517480 2024-05-24T20:45:40.087Z,1716583540.087 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771246 2024-05-24T20:45:40.337Z,1716583540.337 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021965 2024-05-24T20:45:40.589Z,1716583540.589 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273520 2024-05-24T20:45:40.842Z,1716583540.842 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526468 2024-05-24T20:45:41.071Z,1716583541.071 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:45:41.093Z,1716583541.093 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.777976 2024-05-24T20:45:41.345Z,1716583541.345 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.029552 2024-05-24T20:45:41.599Z,1716583541.599 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.283454 2024-05-24T20:45:41.849Z,1716583541.849 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.533598 2024-05-24T20:45:42.105Z,1716583542.105 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.788470 2024-05-24T20:45:42.353Z,1716583542.353 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.037503 2024-05-24T20:45:42.605Z,1716583542.605 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.289597 2024-05-24T20:45:42.857Z,1716583542.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.541589 2024-05-24T20:45:43.119Z,1716583543.119 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.803431 2024-05-24T20:45:43.370Z,1716583543.370 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.054870 2024-05-24T20:45:43.622Z,1716583543.622 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.306037 2024-05-24T20:45:43.873Z,1716583543.873 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.557512 2024-05-24T20:45:43.909Z,1716583543.909 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:45:44.129Z,1716583544.129 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.813243 2024-05-24T20:45:44.381Z,1716583544.381 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.065844 2024-05-24T20:45:44.633Z,1716583544.633 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.317553 2024-05-24T20:45:44.886Z,1716583544.886 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.570307 2024-05-24T20:45:45.137Z,1716583545.137 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.821520 2024-05-24T20:45:45.389Z,1716583545.389 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.073969 2024-05-24T20:45:45.641Z,1716583545.641 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.325839 2024-05-24T20:45:45.893Z,1716583545.893 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.577931 2024-05-24T20:45:46.145Z,1716583546.145 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.829817 2024-05-24T20:45:46.398Z,1716583546.398 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.082476 2024-05-24T20:45:46.649Z,1716583546.649 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.333560 2024-05-24T20:45:46.730Z,1716583546.730 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:45:46.901Z,1716583546.901 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.585816 2024-05-24T20:45:47.153Z,1716583547.153 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.837729 2024-05-24T20:45:47.405Z,1716583547.405 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:47.405Z,1716583547.405 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:47.406Z,1716583547.406 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:47.658Z,1716583547.658 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:47.658Z,1716583547.658 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:47.658Z,1716583547.658 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:47.909Z,1716583547.909 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:47.909Z,1716583547.909 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:47.909Z,1716583547.909 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:48.161Z,1716583548.161 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:48.161Z,1716583548.161 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:48.161Z,1716583548.161 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:48.414Z,1716583548.414 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:48.414Z,1716583548.414 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:48.414Z,1716583548.414 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:48.665Z,1716583548.665 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:48.665Z,1716583548.665 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:48.665Z,1716583548.665 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:48.917Z,1716583548.917 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:48.917Z,1716583548.917 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:48.918Z,1716583548.918 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:49.169Z,1716583549.169 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:49.169Z,1716583549.169 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:49.169Z,1716583549.169 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:49.421Z,1716583549.421 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:49.421Z,1716583549.421 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:49.421Z,1716583549.421 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:49.615Z,1716583549.615 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:45:49.674Z,1716583549.674 [DAT](INFO): DAT read: Response Not Received 2024-05-24T20:45:49.674Z,1716583549.674 [DAT](INFO): response not received 2024-05-24T20:45:49.674Z,1716583549.674 [DAT](INFO): DAT read: user:9> 2024-05-24T20:45:49.675Z,1716583549.675 [DAT](ERROR): No response from remote modem. 2024-05-24T20:45:49.675Z,1716583549.675 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:45:49.675Z,1716583549.675 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:45:49.676Z,1716583549.676 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:45:49.926Z,1716583549.926 [DAT](INFO): DAT read: Tx time:20:45:49.4332 2024-05-24T20:45:49.926Z,1716583549.926 [DAT](INFO): Ping request sent. 2024-05-24T20:45:49.926Z,1716583549.926 [DAT](INFO): transmitted an acoustic signal 2024-05-24T20:45:49.927Z,1716583549.927 [DAT](INFO): DAT read: TxSync time:20:45:49.4324 2024-05-24T20:45:49.927Z,1716583549.927 [DAT](INFO): unknown deviceResponse_: TxSync time:20:45:49.4324 2024-05-24T20:45:49.927Z,1716583549.927 [DAT](INFO): publishing transmit ping time 2024-05-24T20:45:49.928Z,1716583549.928 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001775 2024-05-24T20:45:50.177Z,1716583550.177 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251099 2024-05-24T20:45:50.429Z,1716583550.429 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503254 2024-05-24T20:45:50.681Z,1716583550.681 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755016 2024-05-24T20:45:50.786Z,1716583550.786 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby. 2024-05-24T20:45:50.933Z,1716583550.933 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007384 2024-05-24T20:45:51.186Z,1716583551.186 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259445 2024-05-24T20:45:51.437Z,1716583551.437 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511376 2024-05-24T20:45:51.446Z,1716583551.446 [DockingStepper](INFO): Changing to mode: 1 2024-05-24T20:45:51.446Z,1716583551.446 [DockingStepper](INFO): Standby mode. 2024-05-24T20:45:51.488Z,1716583551.488 [DockingStepper](INFO): Commanding positive:54506 2024-05-24T20:45:51.689Z,1716583551.689 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763110 2024-05-24T20:45:51.801Z,1716583551.801 [DockingStepper](FAULT): uart error: serial timeout moving from: 7647 to: 16500 response: 2024-05-24T20:45:51.941Z,1716583551.941 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014982 2024-05-24T20:45:52.112Z,1716583552.112 [DockingStepper](FAULT): reading after fault: 2024-05-24T20:45:52.112Z,1716583552.112 [DockingStepper] Communications Fault, FailCount= 1 2024-05-24T20:45:52.112Z,1716583552.112 [DockingStepper](ERROR): Communications Fault 2024-05-24T20:45:52.142Z,1716583552.142 [CBIT](ERROR): Communications Fault in component: DockingStepper 2024-05-24T20:45:52.193Z,1716583552.193 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267072 2024-05-24T20:45:52.221Z,1716583552.221 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:45:52.253Z,1716583552.253 [DockingStepper](INFO): Stop 2024-05-24T20:45:52.253Z,1716583552.253 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-24T20:45:52.253Z,1716583552.253 [DockingStepper](INFO): Powering down 2024-05-24T20:45:52.445Z,1716583552.445 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518986 2024-05-24T20:45:52.617Z,1716583552.617 [DockingStepper](INFO): Stopping 2024-05-24T20:45:52.699Z,1716583552.699 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.773124 2024-05-24T20:45:52.954Z,1716583552.954 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.027893 2024-05-24T20:45:53.026Z,1716583553.026 [DockingStepper](INFO): Stopped 2024-05-24T20:45:53.057Z,1716583553.057 [CBIT](INFO): Clearing failed state for component DockingStepper 2024-05-24T20:45:53.057Z,1716583553.057 [DockingStepper] No Fault, FailCount= 1 2024-05-24T20:45:53.205Z,1716583553.205 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.279008 2024-05-24T20:45:53.437Z,1716583553.437 [DockingStepper](INFO): Start 2024-05-24T20:45:53.437Z,1716583553.437 [DockingStepper](DEBUG): Initializing EZServoServo. 2024-05-24T20:45:53.457Z,1716583553.457 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.531065 2024-05-24T20:45:53.560Z,1716583553.560 [DockingStepper](DEBUG): Initializing DockingStepper. 2024-05-24T20:45:53.709Z,1716583553.709 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.783194 2024-05-24T20:45:53.825Z,1716583553.825 [DockingStepper](INFO): Starting 2024-05-24T20:45:53.961Z,1716583553.961 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.035027 2024-05-24T20:45:54.213Z,1716583554.213 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.287361 2024-05-24T20:45:54.245Z,1716583554.245 [DockingStepper](INFO): Starting 2024-05-24T20:45:54.465Z,1716583554.465 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.538941 2024-05-24T20:45:54.633Z,1716583554.633 [DockingStepper](INFO): Starting 2024-05-24T20:45:54.681Z,1716583554.681 [DockingStepper](INFO): Init string: /1z7650aE13561N3m50 2024-05-24T20:45:54.717Z,1716583554.717 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.791204 2024-05-24T20:45:54.969Z,1716583554.969 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.042879 2024-05-24T20:45:55.068Z,1716583555.068 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:45:55.101Z,1716583555.101 [DockingStepper](INFO): Starting 2024-05-24T20:45:55.221Z,1716583555.221 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.295365 2024-05-24T20:45:55.474Z,1716583555.474 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.547450 2024-05-24T20:45:55.481Z,1716583555.481 [DockingStepper](INFO): Starting 2024-05-24T20:45:55.725Z,1716583555.725 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.798933 2024-05-24T20:45:55.877Z,1716583555.877 [DockingStepper](INFO): Starting 2024-05-24T20:45:55.977Z,1716583555.977 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.050990 2024-05-24T20:45:56.229Z,1716583556.229 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.302954 2024-05-24T20:45:56.274Z,1716583556.274 [DockingStepper](INFO): Starting 2024-05-24T20:45:56.304Z,1716583556.304 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2024-05-24T20:45:56.481Z,1716583556.481 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.554905 2024-05-24T20:45:56.684Z,1716583556.684 [DockingStepper](INFO): Starting 2024-05-24T20:45:56.733Z,1716583556.733 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.806896 2024-05-24T20:45:56.985Z,1716583556.985 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.058951 2024-05-24T20:45:57.077Z,1716583557.077 [DockingStepper](INFO): Changing to mode: 1 2024-05-24T20:45:57.078Z,1716583557.078 [DockingStepper](INFO): Standby mode. 2024-05-24T20:45:57.128Z,1716583557.128 [DockingStepper](INFO): Commanding positive:54500 2024-05-24T20:45:57.240Z,1716583557.240 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.314290 2024-05-24T20:45:57.490Z,1716583557.490 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.563236 2024-05-24T20:45:57.741Z,1716583557.741 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.814983 2024-05-24T20:45:57.830Z,1716583557.830 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:45:57.993Z,1716583557.993 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.066916 2024-05-24T20:45:58.246Z,1716583558.246 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.319631 2024-05-24T20:45:58.497Z,1716583558.497 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.570999 2024-05-24T20:45:58.749Z,1716583558.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.823096 2024-05-24T20:45:59.001Z,1716583559.001 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.075197 2024-05-24T20:45:59.255Z,1716583559.255 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.328955 2024-05-24T20:45:59.505Z,1716583559.505 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.578996 2024-05-24T20:45:59.757Z,1716583559.757 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.830930 2024-05-24T20:46:00.009Z,1716583560.009 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:00.009Z,1716583560.009 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:00.009Z,1716583560.009 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:00.261Z,1716583560.261 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:00.261Z,1716583560.261 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:00.262Z,1716583560.262 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:00.515Z,1716583560.515 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:00.515Z,1716583560.515 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:00.515Z,1716583560.515 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:00.680Z,1716583560.680 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:46:00.765Z,1716583560.765 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:00.765Z,1716583560.765 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:00.766Z,1716583560.766 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:01.017Z,1716583561.017 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:01.017Z,1716583561.017 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:01.017Z,1716583561.017 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:01.272Z,1716583561.272 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:01.272Z,1716583561.272 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:01.273Z,1716583561.273 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:01.534Z,1716583561.534 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:01.534Z,1716583561.534 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:01.534Z,1716583561.534 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:01.785Z,1716583561.785 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:01.785Z,1716583561.785 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:01.785Z,1716583561.785 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:02.037Z,1716583562.037 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:02.038Z,1716583562.038 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:02.038Z,1716583562.038 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:02.290Z,1716583562.290 [DAT](INFO): DAT read: Response Not Received 2024-05-24T20:46:02.290Z,1716583562.290 [DAT](INFO): response not received 2024-05-24T20:46:02.290Z,1716583562.290 [DAT](INFO): DAT read: user:10> 2024-05-24T20:46:02.291Z,1716583562.291 [DAT](INFO): DAT read: Tx time:20:46:01.8833 2024-05-24T20:46:02.292Z,1716583562.292 [DAT](INFO): Ping request sent. 2024-05-24T20:46:02.292Z,1716583562.292 [DAT](INFO): transmitted an acoustic signal 2024-05-24T20:46:02.292Z,1716583562.292 [DAT](INFO): publishing transmit ping time 2024-05-24T20:46:02.293Z,1716583562.293 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001028 2024-05-24T20:46:02.541Z,1716583562.541 [DAT](INFO): DAT read: TxSync time:20:46:01.8825 2024-05-24T20:46:02.542Z,1716583562.542 [DAT](INFO): unknown deviceResponse_: TxSync time:20:46:01.8825 2024-05-24T20:46:02.542Z,1716583562.542 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250484 2024-05-24T20:46:02.794Z,1716583562.794 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502137 2024-05-24T20:46:03.046Z,1716583563.046 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753913 2024-05-24T20:46:03.074Z,1716583563.074 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2024-05-24T20:46:03.301Z,1716583563.301 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.008785 2024-05-24T20:46:03.501Z,1716583563.501 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2024-05-24T20:46:03.501Z,1716583563.501 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2024-05-24T20:46:03.501Z,1716583563.501 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2024-05-24T20:46:03.501Z,1716583563.501 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2024-05-24T20:46:03.502Z,1716583563.502 [DefaultWithUndock:LeaveDock] Stopped 2024-05-24T20:46:03.502Z,1716583563.502 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2024-05-24T20:46:03.502Z,1716583563.502 [DefaultWithUndock:LeaveDock:A.] Stopped 2024-05-24T20:46:03.502Z,1716583563.502 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2024-05-24T20:46:03.502Z,1716583563.502 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2024-05-24T20:46:03.502Z,1716583563.502 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2024-05-24T20:46:03.502Z,1716583563.502 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2024-05-24T20:46:03.502Z,1716583563.502 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-05-24T20:46:03.503Z,1716583563.503 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-05-24T20:46:03.503Z,1716583563.503 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-05-24T20:46:03.503Z,1716583563.503 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-05-24T20:46:03.504Z,1716583563.504 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-05-24T20:46:03.505Z,1716583563.505 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-05-24T20:46:03.505Z,1716583563.505 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-05-24T20:46:03.550Z,1716583563.550 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258047 2024-05-24T20:46:03.801Z,1716583563.801 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509368 2024-05-24T20:46:04.053Z,1716583564.053 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.761228 2024-05-24T20:46:04.074Z,1716583564.074 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2024-05-24T20:46:04.074Z,1716583564.074 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-05-24T20:46:04.075Z,1716583564.075 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2024-05-24T20:46:04.305Z,1716583564.305 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013325 2024-05-24T20:46:04.468Z,1716583564.468 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-05-24T20:46:04.561Z,1716583564.561 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.268108 2024-05-24T20:46:04.809Z,1716583564.809 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517252 2024-05-24T20:46:05.062Z,1716583565.062 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.769803 2024-05-24T20:46:05.316Z,1716583565.316 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023376 2024-05-24T20:46:05.566Z,1716583565.566 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274159 2024-05-24T20:46:05.817Z,1716583565.817 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.525419 2024-05-24T20:46:06.071Z,1716583566.071 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778915 2024-05-24T20:46:06.322Z,1716583566.322 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.029821 2024-05-24T20:46:06.573Z,1716583566.573 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.281279 2024-05-24T20:46:06.825Z,1716583566.825 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.533323 2024-05-24T20:46:07.077Z,1716583567.077 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.785578 2024-05-24T20:46:07.329Z,1716583567.329 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.037677 2024-05-24T20:46:07.582Z,1716583567.582 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.289651 2024-05-24T20:46:07.833Z,1716583567.833 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.541318 2024-05-24T20:46:08.085Z,1716583568.085 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.793288 2024-05-24T20:46:08.337Z,1716583568.337 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.045679 2024-05-24T20:46:08.589Z,1716583568.589 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.297365 2024-05-24T20:46:08.841Z,1716583568.841 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.549332 2024-05-24T20:46:09.093Z,1716583569.093 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.801731 2024-05-24T20:46:09.346Z,1716583569.346 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.053782 2024-05-24T20:46:09.597Z,1716583569.597 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.305629 2024-05-24T20:46:09.849Z,1716583569.849 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.557347 2024-05-24T20:46:10.101Z,1716583570.101 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.809265 2024-05-24T20:46:10.353Z,1716583570.353 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.061417 2024-05-24T20:46:10.605Z,1716583570.605 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.313289 2024-05-24T20:46:10.858Z,1716583570.858 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566280 2024-05-24T20:46:11.109Z,1716583571.109 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.817285 2024-05-24T20:46:11.362Z,1716583571.362 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.069723 2024-05-24T20:46:11.613Z,1716583571.613 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.321642 2024-05-24T20:46:11.866Z,1716583571.866 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574259 2024-05-24T20:46:12.117Z,1716583572.117 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.825659 2024-05-24T20:46:12.369Z,1716583572.369 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:12.369Z,1716583572.369 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:12.369Z,1716583572.369 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:12.621Z,1716583572.621 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:12.621Z,1716583572.621 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:12.622Z,1716583572.622 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:12.873Z,1716583572.873 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:12.873Z,1716583572.873 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:12.873Z,1716583572.873 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:13.125Z,1716583573.125 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:13.125Z,1716583573.125 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:13.125Z,1716583573.125 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:13.377Z,1716583573.377 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:13.377Z,1716583573.377 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:13.377Z,1716583573.377 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:13.629Z,1716583573.629 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:13.629Z,1716583573.629 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:13.629Z,1716583573.629 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:13.881Z,1716583573.881 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:13.881Z,1716583573.881 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:13.881Z,1716583573.881 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:14.134Z,1716583574.134 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:14.134Z,1716583574.134 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:14.134Z,1716583574.134 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:14.385Z,1716583574.385 [DAT](INFO): ****** received valid address query ****** 2024-05-24T20:46:14.385Z,1716583574.385 [DAT](INFO): ****** received valid ping request ****** 2024-05-24T20:46:14.385Z,1716583574.385 [DAT](INFO): Querying Benthos address 201 with one ping in standard two-way mode. 2024-05-24T20:46:14.637Z,1716583574.637 [DAT](INFO): DAT read: Response Not Received 2024-05-24T20:46:14.637Z,1716583574.637 [DAT](INFO): response not received 2024-05-24T20:46:14.638Z,1716583574.638 [DAT](INFO): DAT read: user:11> 2024-05-24T20:46:14.639Z,1716583574.639 [DAT](INFO): DAT read: Tx time:20:46:14.3334 2024-05-24T20:46:14.639Z,1716583574.639 [DAT](INFO): Ping request sent. 2024-05-24T20:46:14.639Z,1716583574.639 [DAT](INFO): transmitted an acoustic signal 2024-05-24T20:46:14.639Z,1716583574.639 [DAT](INFO): publishing transmit ping time 2024-05-24T20:46:14.640Z,1716583574.640 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000710 2024-05-24T20:46:14.893Z,1716583574.893 [DAT](INFO): DAT read: TxSync time:20:46:14.3327 2024-05-24T20:46:14.893Z,1716583574.893 [DAT](INFO): unknown deviceResponse_: TxSync time:20:46:14.3327 2024-05-24T20:46:14.894Z,1716583574.894 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.254863 2024-05-24T20:46:15.142Z,1716583575.142 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502480 2024-05-24T20:46:15.393Z,1716583575.393 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753946 2024-05-24T20:46:15.645Z,1716583575.645 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006292 2024-05-24T20:46:15.898Z,1716583575.898 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258492 2024-05-24T20:46:16.149Z,1716583576.149 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510038 2024-05-24T20:46:16.402Z,1716583576.402 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762809 2024-05-24T20:46:16.653Z,1716583576.653 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014036 2024-05-24T20:46:16.905Z,1716583576.905 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266187 2024-05-24T20:46:17.158Z,1716583577.158 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518890 2024-05-24T20:46:17.409Z,1716583577.409 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770197 2024-05-24T20:46:17.661Z,1716583577.661 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022119 2024-05-24T20:46:17.913Z,1716583577.913 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274354 2024-05-24T20:46:18.165Z,1716583578.165 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526092 2024-05-24T20:46:18.418Z,1716583578.418 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778312 2024-05-24T20:46:18.669Z,1716583578.669 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030311 2024-05-24T20:46:18.921Z,1716583578.921 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282077 2024-05-24T20:46:19.174Z,1716583579.174 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534837 2024-05-24T20:46:19.425Z,1716583579.425 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786441 2024-05-24T20:46:19.677Z,1716583579.677 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038133 2024-05-24T20:46:19.929Z,1716583579.929 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290183 2024-05-24T20:46:20.181Z,1716583580.181 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542027 2024-05-24T20:46:20.433Z,1716583580.433 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794069 2024-05-24T20:46:20.685Z,1716583580.685 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046179 2024-05-24T20:46:20.937Z,1716583580.937 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.297986 2024-05-24T20:46:21.190Z,1716583581.190 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550560 2024-05-24T20:46:21.441Z,1716583581.441 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.801953 2024-05-24T20:46:21.694Z,1716583581.694 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054794 2024-05-24T20:46:21.945Z,1716583581.945 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.305921 2024-05-24T20:46:22.197Z,1716583582.197 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558016 2024-05-24T20:46:22.450Z,1716583582.450 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.811210 2024-05-24T20:46:22.702Z,1716583582.702 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062977 2024-05-24T20:46:22.953Z,1716583582.953 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.313995 2024-05-24T20:46:23.205Z,1716583583.205 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566077 2024-05-24T20:46:23.457Z,1716583583.457 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818125 2024-05-24T20:46:23.710Z,1716583583.710 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070594 2024-05-24T20:46:23.961Z,1716583583.961 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322271 2024-05-24T20:46:24.213Z,1716583584.213 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574117 2024-05-24T20:46:24.466Z,1716583584.466 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826357 2024-05-24T20:46:27.238Z,1716583587.238 [DAT](INFO): DAT read: Response Not Received 2024-05-24T20:46:27.238Z,1716583587.238 [DAT](INFO): response not received 2024-05-24T20:46:27.238Z,1716583587.238 [DAT](INFO): DAT read: user:12> 2024-05-24T20:46:27.239Z,1716583587.239 [DAT](INFO): DAT read: Tx time:20:46:26.7835 2024-05-24T20:46:27.240Z,1716583587.240 [DAT](INFO): Ping request sent. 2024-05-24T20:46:27.240Z,1716583587.240 [DAT](INFO): transmitted an acoustic signal 2024-05-24T20:46:27.240Z,1716583587.240 [DAT](INFO): publishing transmit ping time 2024-05-24T20:46:27.241Z,1716583587.241 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001042 2024-05-24T20:46:27.489Z,1716583587.489 [DAT](INFO): DAT read: TxSync time:20:46:26.7828 2024-05-24T20:46:27.490Z,1716583587.490 [DAT](INFO): unknown deviceResponse_: TxSync time:20:46:26.7828 2024-05-24T20:46:27.490Z,1716583587.490 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250301 2024-05-24T20:46:27.741Z,1716583587.741 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501108 2024-05-24T20:46:27.993Z,1716583587.993 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753218 2024-05-24T20:46:28.246Z,1716583588.246 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006531 2024-05-24T20:46:28.497Z,1716583588.497 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.257325 2024-05-24T20:46:28.749Z,1716583588.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509242 2024-05-24T20:46:29.005Z,1716583589.005 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.764170 2024-05-24T20:46:29.255Z,1716583589.255 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015157 2024-05-24T20:46:29.511Z,1716583589.511 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.271038 2024-05-24T20:46:29.757Z,1716583589.757 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517353 2024-05-24T20:46:30.009Z,1716583590.009 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.769196 2024-05-24T20:46:30.261Z,1716583590.261 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021552 2024-05-24T20:46:30.513Z,1716583590.513 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273378 2024-05-24T20:46:30.765Z,1716583590.765 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.525653 2024-05-24T20:46:31.018Z,1716583591.018 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.777892 2024-05-24T20:46:31.271Z,1716583591.271 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030712 2024-05-24T20:46:31.521Z,1716583591.521 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.281667 2024-05-24T20:46:31.774Z,1716583591.774 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534334 2024-05-24T20:46:32.025Z,1716583592.025 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.785229 2024-05-24T20:46:32.278Z,1716583592.278 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.037749 2024-05-24T20:46:32.529Z,1716583592.529 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.289324 2024-05-24T20:46:32.781Z,1716583592.781 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.541367 2024-05-24T20:46:33.034Z,1716583593.034 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794297 2024-05-24T20:46:33.287Z,1716583593.287 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046565 2024-05-24T20:46:33.538Z,1716583593.538 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.297687 2024-05-24T20:46:33.789Z,1716583593.789 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.549329 2024-05-24T20:46:34.041Z,1716583594.041 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.801452 2024-05-24T20:46:34.293Z,1716583594.293 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.053559 2024-05-24T20:46:34.545Z,1716583594.545 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.305264 2024-05-24T20:46:34.797Z,1716583594.797 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.557226 2024-05-24T20:46:35.049Z,1716583595.049 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.809307 2024-05-24T20:46:35.303Z,1716583595.303 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062618 2024-05-24T20:46:35.553Z,1716583595.553 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.313288 2024-05-24T20:46:35.805Z,1716583595.805 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.565248 2024-05-24T20:46:36.057Z,1716583596.057 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.817223 2024-05-24T20:46:36.309Z,1716583596.309 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.069388 2024-05-24T20:46:36.561Z,1716583596.561 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.321196 2024-05-24T20:46:36.813Z,1716583596.813 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.573456 2024-05-24T20:46:37.065Z,1716583597.065 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.825208 2024-05-24T20:46:39.586Z,1716583599.586 [DAT](INFO): DAT read: Response Not Received 2024-05-24T20:46:39.586Z,1716583599.586 [DAT](INFO): response not received 2024-05-24T20:46:39.586Z,1716583599.586 [DAT](INFO): DAT read: user:13> 2024-05-24T20:46:39.587Z,1716583599.587 [DAT](INFO): DAT read: Tx time:20:46:39.2337 2024-05-24T20:46:39.588Z,1716583599.588 [DAT](INFO): Ping request sent. 2024-05-24T20:46:39.588Z,1716583599.588 [DAT](INFO): transmitted an acoustic signal 2024-05-24T20:46:39.588Z,1716583599.588 [DAT](INFO): publishing transmit ping time 2024-05-24T20:46:39.589Z,1716583599.589 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001296 2024-05-24T20:46:39.837Z,1716583599.837 [DAT](INFO): DAT read: TxSync time:20:46:39.2330 2024-05-24T20:46:39.838Z,1716583599.838 [DAT](INFO): unknown deviceResponse_: TxSync time:20:46:39.2330 2024-05-24T20:46:39.838Z,1716583599.838 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250520 2024-05-24T20:46:40.089Z,1716583600.089 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501827 2024-05-24T20:46:40.341Z,1716583600.341 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753576 2024-05-24T20:46:40.593Z,1716583600.593 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005745 2024-05-24T20:46:40.851Z,1716583600.851 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.263299 2024-05-24T20:46:41.097Z,1716583601.097 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509522 2024-05-24T20:46:41.349Z,1716583601.349 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.761376 2024-05-24T20:46:41.602Z,1716583601.602 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014084 2024-05-24T20:46:41.853Z,1716583601.853 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.265419 2024-05-24T20:46:42.105Z,1716583602.105 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517423 2024-05-24T20:46:42.357Z,1716583602.357 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.769760 2024-05-24T20:46:42.609Z,1716583602.609 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021357 2024-05-24T20:46:42.861Z,1716583602.861 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273578 2024-05-24T20:46:43.113Z,1716583603.113 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.525287 2024-05-24T20:46:43.365Z,1716583603.365 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.777408 2024-05-24T20:46:43.617Z,1716583603.617 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.029545 2024-05-24T20:46:43.869Z,1716583603.869 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.281534 2024-05-24T20:46:44.124Z,1716583604.124 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.536038 2024-05-24T20:46:44.373Z,1716583604.373 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.785396 2024-05-24T20:46:44.625Z,1716583604.625 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.037354 2024-05-24T20:46:44.878Z,1716583604.878 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.289842 2024-05-24T20:46:45.129Z,1716583605.129 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.541739 2024-05-24T20:46:45.384Z,1716583605.384 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.796381 2024-05-24T20:46:45.639Z,1716583605.639 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.051160 2024-05-24T20:46:45.889Z,1716583605.889 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.301346 2024-05-24T20:46:46.141Z,1716583606.141 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.553644 2024-05-24T20:46:46.393Z,1716583606.393 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.805614 2024-05-24T20:46:46.645Z,1716583606.645 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.057331 2024-05-24T20:46:46.898Z,1716583606.898 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.310037 2024-05-24T20:46:47.149Z,1716583607.149 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.561401 2024-05-24T20:46:47.401Z,1716583607.401 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.813589 2024-05-24T20:46:47.654Z,1716583607.654 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.066140 2024-05-24T20:46:47.905Z,1716583607.905 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.317445 2024-05-24T20:46:48.158Z,1716583608.158 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.570137 2024-05-24T20:46:48.357Z,1716583608.357 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T20:46:48.410Z,1716583608.410 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.822131 2024-05-24T20:46:48.661Z,1716583608.661 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.073352 2024-05-24T20:46:48.913Z,1716583608.913 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.325303 2024-05-24T20:46:49.165Z,1716583609.165 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.577654 2024-05-24T20:46:49.417Z,1716583609.417 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.829402 2024-05-24T20:46:51.938Z,1716583611.938 [DAT](INFO): DAT read: Response Not Received 2024-05-24T20:46:51.938Z,1716583611.938 [DAT](INFO): response not received 2024-05-24T20:46:51.938Z,1716583611.938 [DAT](INFO): DAT read: user:14> 2024-05-24T20:46:51.939Z,1716583611.939 [DAT](ERROR): No response from remote modem. 2024-05-24T20:46:52.189Z,1716583612.189 [DAT](INFO): DAT read: Tx time:20:46:51.6838 2024-05-24T20:46:52.189Z,1716583612.189 [DAT](INFO): Ping request sent. 2024-05-24T20:46:52.190Z,1716583612.190 [DAT](INFO): transmitted an acoustic signal 2024-05-24T20:46:52.190Z,1716583612.190 [DAT](INFO): DAT read: TxSync time:20:46:51.6831 2024-05-24T20:46:52.190Z,1716583612.190 [DAT](INFO): unknown deviceResponse_: TxSync time:20:46:51.6831 2024-05-24T20:46:52.191Z,1716583612.191 [DAT](INFO): publishing transmit ping time 2024-05-24T20:46:52.191Z,1716583612.191 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001656 2024-05-24T20:46:52.441Z,1716583612.441 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251596 2024-05-24T20:46:52.693Z,1716583612.693 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503462 2024-05-24T20:46:52.946Z,1716583612.946 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.756472 2024-05-24T20:46:53.197Z,1716583613.197 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007584 2024-05-24T20:46:53.449Z,1716583613.449 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259440 2024-05-24T20:46:53.702Z,1716583613.702 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.512164 2024-05-24T20:46:53.953Z,1716583613.953 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763540 2024-05-24T20:46:54.205Z,1716583614.205 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015482 2024-05-24T20:46:54.458Z,1716583614.458 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267844 2024-05-24T20:46:54.709Z,1716583614.709 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519515 2024-05-24T20:46:54.962Z,1716583614.962 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772126 2024-05-24T20:46:55.213Z,1716583615.213 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023684 2024-05-24T20:46:55.465Z,1716583615.465 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275782 2024-05-24T20:46:55.717Z,1716583615.717 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.527549 2024-05-24T20:46:55.973Z,1716583615.973 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.782998 2024-05-24T20:46:56.221Z,1716583616.221 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031577 2024-05-24T20:46:56.473Z,1716583616.473 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.283448 2024-05-24T20:46:56.725Z,1716583616.725 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.535717 2024-05-24T20:46:56.978Z,1716583616.978 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.788208 2024-05-24T20:46:57.229Z,1716583617.229 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.039460 2024-05-24T20:46:57.482Z,1716583617.482 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.292046 2024-05-24T20:46:57.733Z,1716583617.733 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.543673 2024-05-24T20:46:57.985Z,1716583617.985 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.795466 2024-05-24T20:46:58.237Z,1716583618.237 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.047870 2024-05-24T20:46:58.489Z,1716583618.489 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.299475 2024-05-24T20:46:58.741Z,1716583618.741 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.551330 2024-05-24T20:46:58.994Z,1716583618.994 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.804095 2024-05-24T20:46:59.245Z,1716583619.245 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.055565 2024-05-24T20:46:59.497Z,1716583619.497 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.307542 2024-05-24T20:46:59.749Z,1716583619.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.559592 2024-05-24T20:47:00.001Z,1716583620.001 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.811549 2024-05-24T20:47:00.253Z,1716583620.253 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.063525 2024-05-24T20:47:00.505Z,1716583620.505 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.315809 2024-05-24T20:47:00.757Z,1716583620.757 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.567604 2024-05-24T20:47:01.009Z,1716583621.009 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.819397 2024-05-24T20:47:01.261Z,1716583621.261 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.071558 2024-05-24T20:47:01.356Z,1716583621.356 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T20:47:01.513Z,1716583621.513 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.323616 2024-05-24T20:47:01.765Z,1716583621.765 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.575362 2024-05-24T20:47:02.017Z,1716583622.017 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.827668 2024-05-24T20:47:04.537Z,1716583624.537 [DAT](INFO): DAT read: Response Not Received 2024-05-24T20:47:04.537Z,1716583624.537 [DAT](INFO): response not received 2024-05-24T20:47:04.538Z,1716583624.538 [DAT](INFO): DAT read: user:15> 2024-05-24T20:47:04.539Z,1716583624.539 [DAT](INFO): DAT read: Tx time:20:47:04.1339 2024-05-24T20:47:04.539Z,1716583624.539 [DAT](INFO): Ping request sent. 2024-05-24T20:47:04.539Z,1716583624.539 [DAT](INFO): transmitted an acoustic signal 2024-05-24T20:47:04.539Z,1716583624.539 [DAT](INFO): publishing transmit ping time 2024-05-24T20:47:04.540Z,1716583624.540 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000825 2024-05-24T20:47:04.789Z,1716583624.789 [DAT](INFO): DAT read: TxSync time:20:47:04.1332 2024-05-24T20:47:04.790Z,1716583624.790 [DAT](INFO): unknown deviceResponse_: TxSync time:20:47:04.1332 2024-05-24T20:47:04.790Z,1716583624.790 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251225 2024-05-24T20:47:05.041Z,1716583625.041 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502489 2024-05-24T20:47:05.295Z,1716583625.295 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755876 2024-05-24T20:47:05.545Z,1716583625.545 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006153 2024-05-24T20:47:05.797Z,1716583625.797 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258250 2024-05-24T20:47:06.049Z,1716583626.049 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510141 2024-05-24T20:47:06.301Z,1716583626.301 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762159 2024-05-24T20:47:06.553Z,1716583626.553 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014309 2024-05-24T20:47:06.805Z,1716583626.805 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266227 2024-05-24T20:47:07.057Z,1716583627.057 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518163 2024-05-24T20:47:07.311Z,1716583627.311 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771484 2024-05-24T20:47:07.561Z,1716583627.561 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022435 2024-05-24T20:47:07.813Z,1716583627.813 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274073 2024-05-24T20:47:08.065Z,1716583628.065 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526566 2024-05-24T20:47:08.317Z,1716583628.317 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778170 2024-05-24T20:47:08.569Z,1716583628.569 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030079 2024-05-24T20:47:08.821Z,1716583628.821 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282227 2024-05-24T20:47:09.073Z,1716583629.073 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534072 2024-05-24T20:47:09.328Z,1716583629.328 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.788026 2024-05-24T20:47:09.577Z,1716583629.577 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038223 2024-05-24T20:47:09.829Z,1716583629.829 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290130 2024-05-24T20:47:10.081Z,1716583630.081 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542333 2024-05-24T20:47:10.333Z,1716583630.333 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794362 2024-05-24T20:47:10.591Z,1716583630.591 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.051820 2024-05-24T20:47:10.837Z,1716583630.837 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298023 2024-05-24T20:47:11.089Z,1716583631.089 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550181 2024-05-24T20:47:11.353Z,1716583631.353 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.813325 2024-05-24T20:47:11.601Z,1716583631.601 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.062100 2024-05-24T20:47:11.854Z,1716583631.854 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.314810 2024-05-24T20:47:12.105Z,1716583632.105 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.566258 2024-05-24T20:47:12.357Z,1716583632.357 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.818148 2024-05-24T20:47:12.612Z,1716583632.612 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.072839 2024-05-24T20:47:12.861Z,1716583632.861 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.322211 2024-05-24T20:47:13.113Z,1716583633.113 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.574041 2024-05-24T20:47:13.365Z,1716583633.365 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.826091 2024-05-24T20:47:13.617Z,1716583633.617 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.078549 2024-05-24T20:47:13.870Z,1716583633.870 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.331190 2024-05-24T20:47:14.121Z,1716583634.121 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.582146 2024-05-24T20:47:14.373Z,1716583634.373 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.834348 2024-05-24T20:47:14.521Z,1716583634.521 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T20:47:16.894Z,1716583636.894 [DAT](INFO): DAT read: Response Not Received 2024-05-24T20:47:16.894Z,1716583636.894 [DAT](INFO): response not received 2024-05-24T20:47:16.895Z,1716583636.895 [DAT](INFO): DAT read: user:16> 2024-05-24T20:47:16.897Z,1716583636.897 [DAT](ERROR): No response from remote modem. 2024-05-24T20:47:17.145Z,1716583637.145 [DAT](INFO): DAT read: Tx time:20:47:16.5841 2024-05-24T20:47:17.145Z,1716583637.145 [DAT](INFO): Ping request sent. 2024-05-24T20:47:17.146Z,1716583637.146 [DAT](INFO): transmitted an acoustic signal 2024-05-24T20:47:17.146Z,1716583637.146 [DAT](INFO): DAT read: TxSync time:20:47:16.5833 2024-05-24T20:47:17.146Z,1716583637.146 [DAT](INFO): unknown deviceResponse_: TxSync time:20:47:16.5833 2024-05-24T20:47:17.147Z,1716583637.147 [DAT](INFO): publishing transmit ping time 2024-05-24T20:47:17.147Z,1716583637.147 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001660 2024-05-24T20:47:17.397Z,1716583637.397 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251479 2024-05-24T20:47:17.649Z,1716583637.649 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503546 2024-05-24T20:47:17.902Z,1716583637.902 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.756336 2024-05-24T20:47:18.154Z,1716583638.154 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.008359 2024-05-24T20:47:18.405Z,1716583638.405 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259547 2024-05-24T20:47:18.657Z,1716583638.657 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511468 2024-05-24T20:47:18.909Z,1716583638.909 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763697 2024-05-24T20:47:19.185Z,1716583639.185 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.039000 2024-05-24T20:47:19.413Z,1716583639.413 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267509 2024-05-24T20:47:19.665Z,1716583639.665 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519396 2024-05-24T20:47:19.918Z,1716583639.918 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772058 2024-05-24T20:47:20.169Z,1716583640.169 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023408 2024-05-24T20:47:20.421Z,1716583640.421 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275847 2024-05-24T20:47:20.673Z,1716583640.673 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.527665 2024-05-24T20:47:20.925Z,1716583640.925 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.779469 2024-05-24T20:47:21.177Z,1716583641.177 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.031666 2024-05-24T20:47:21.429Z,1716583641.429 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.283805 2024-05-24T20:47:21.681Z,1716583641.681 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.535684 2024-05-24T20:47:21.801Z,1716583641.801 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-05-24T20:47:21.937Z,1716583641.937 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.791586 2024-05-24T20:47:22.185Z,1716583642.185 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.039776 2024-05-24T20:47:22.441Z,1716583642.441 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.294815 2024-05-24T20:47:22.690Z,1716583642.690 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.543911 2024-05-24T20:47:22.941Z,1716583642.941 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.795598 2024-05-24T20:47:23.193Z,1716583643.193 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.047620 2024-05-24T20:47:23.445Z,1716583643.445 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.299316 2024-05-24T20:47:23.697Z,1716583643.697 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.551694 2024-05-24T20:47:23.949Z,1716583643.949 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.803824 2024-05-24T20:47:24.201Z,1716583644.201 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.055575 2024-05-24T20:47:24.454Z,1716583644.454 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.308013 2024-05-24T20:47:24.706Z,1716583644.706 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.560033 2024-05-24T20:47:24.957Z,1716583644.957 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.811593 2024-05-24T20:47:25.209Z,1716583645.209 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.063526 2024-05-24T20:47:25.461Z,1716583645.461 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.315645 2024-05-24T20:47:25.713Z,1716583645.713 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.567591 2024-05-24T20:47:25.965Z,1716583645.965 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.819532 2024-05-24T20:47:26.217Z,1716583646.217 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.071571 2024-05-24T20:47:26.469Z,1716583646.469 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.323592 2024-05-24T20:47:26.725Z,1716583646.725 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.579635 2024-05-24T20:47:26.978Z,1716583646.978 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.831952 2024-05-24T20:47:27.689Z,1716583647.689 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-05-24T20:47:29.506Z,1716583649.506 [DAT](INFO): DAT read: Response Not Received 2024-05-24T20:47:29.506Z,1716583649.506 [DAT](INFO): response not received 2024-05-24T20:47:29.507Z,1716583649.507 [DAT](INFO): DAT read: user:17> 2024-05-24T20:47:29.507Z,1716583649.507 [DAT](INFO): DAT read: Tx time:20:47:29.0342 2024-05-24T20:47:29.508Z,1716583649.508 [DAT](INFO): Ping request sent. 2024-05-24T20:47:29.508Z,1716583649.508 [DAT](INFO): transmitted an acoustic signal 2024-05-24T20:47:29.508Z,1716583649.508 [DAT](INFO): publishing transmit ping time 2024-05-24T20:47:29.509Z,1716583649.509 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001010 2024-05-24T20:47:29.757Z,1716583649.757 [DAT](INFO): DAT read: TxSync time:20:47:29.0334 2024-05-24T20:47:29.758Z,1716583649.758 [DAT](INFO): unknown deviceResponse_: TxSync time:20:47:29.0334 2024-05-24T20:47:29.758Z,1716583649.758 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250191 2024-05-24T20:47:30.009Z,1716583650.009 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501231 2024-05-24T20:47:30.263Z,1716583650.263 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754654 2024-05-24T20:47:30.513Z,1716583650.513 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005390 2024-05-24T20:47:30.765Z,1716583650.765 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.257434 2024-05-24T20:47:31.017Z,1716583651.017 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509340 2024-05-24T20:47:31.269Z,1716583651.269 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.761431 2024-05-24T20:47:31.524Z,1716583651.524 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015079 2024-05-24T20:47:31.773Z,1716583651.773 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.265274 2024-05-24T20:47:32.026Z,1716583652.026 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517623 2024-05-24T20:47:32.280Z,1716583652.280 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771638 2024-05-24T20:47:32.529Z,1716583652.529 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021349 2024-05-24T20:47:32.782Z,1716583652.782 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274029 2024-05-24T20:47:33.033Z,1716583653.033 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.525555 2024-05-24T20:47:33.285Z,1716583653.285 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.777576 2024-05-24T20:47:33.537Z,1716583653.537 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.029220 2024-05-24T20:47:33.789Z,1716583653.789 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.281516 2024-05-24T20:47:34.041Z,1716583654.041 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.533156 2024-05-24T20:47:34.293Z,1716583654.293 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.785419 2024-05-24T20:47:34.545Z,1716583654.545 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.037399 2024-05-24T20:47:34.797Z,1716583654.797 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.289195 2024-05-24T20:47:35.050Z,1716583655.050 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.541877 2024-05-24T20:47:35.303Z,1716583655.303 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.795248 2024-05-24T20:47:35.553Z,1716583655.553 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.045398 2024-05-24T20:47:35.805Z,1716583655.805 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.297234 2024-05-24T20:47:35.898Z,1716583655.898 [CommandExec](IMPORTANT): got command restart application 2024-05-24T20:47:36.057Z,1716583656.057 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.549143 2024-05-24T20:47:36.309Z,1716583656.309 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.801355 2024-05-24T20:47:36.561Z,1716583656.561 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.053475 2024-05-24T20:47:36.813Z,1716583656.813 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.305228 2024-05-24T20:47:36.900Z,1716583656.900 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2024-05-24T20:47:36.901Z,1716583656.901 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T20:47:36.901Z,1716583656.901 [CommandExec](INFO): Uninitializing the command executive. 2024-05-24T20:47:36.901Z,1716583656.901 [CommandExec](INFO): Uninitializing the command scheduler. 2024-05-24T20:47:36.901Z,1716583656.901 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:37.000Z,1716583657.000 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-05-24T20:47:37.000Z,1716583657.000 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-05-24T20:47:37.000Z,1716583657.000 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:37.001Z,1716583657.001 [NavChartDb](INFO): Join timeout helper Thread ID is 3277 2024-05-24T20:47:37.065Z,1716583657.065 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.557568 2024-05-24T20:47:37.118Z,1716583657.118 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T20:47:37.118Z,1716583657.118 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:37.121Z,1716583657.121 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2024-05-24T20:47:37.121Z,1716583657.121 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:37.121Z,1716583657.121 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 3278 2024-05-24T20:47:37.320Z,1716583657.320 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.811366 2024-05-24T20:47:37.337Z,1716583657.337 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T20:47:37.338Z,1716583657.338 [WetLabsUBAT](INFO): Powering down 2024-05-24T20:47:37.340Z,1716583657.340 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:37.357Z,1716583657.357 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-05-24T20:47:37.357Z,1716583657.357 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:37.358Z,1716583657.358 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3279 2024-05-24T20:47:37.569Z,1716583657.569 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.061453 2024-05-24T20:47:37.641Z,1716583657.641 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T20:47:37.641Z,1716583657.641 [WetLabsBB2FL](INFO): Powering down 2024-05-24T20:47:37.642Z,1716583657.642 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:37.660Z,1716583657.660 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-05-24T20:47:37.660Z,1716583657.660 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:37.661Z,1716583657.661 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3280 2024-05-24T20:47:37.821Z,1716583657.821 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.313274 2024-05-24T20:47:38.073Z,1716583658.073 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.565295 2024-05-24T20:47:38.120Z,1716583658.120 [CTD_Seabird](INFO): Powering down 2024-05-24T20:47:38.132Z,1716583658.132 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T20:47:38.132Z,1716583658.132 [CTD_Seabird](INFO): Powering down 2024-05-24T20:47:38.144Z,1716583658.144 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:38.156Z,1716583658.156 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-05-24T20:47:38.156Z,1716583658.156 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:38.157Z,1716583658.157 [DAT](INFO): Join timeout helper Thread ID is 3281 2024-05-24T20:47:38.325Z,1716583658.325 [DAT](INFO): Powering down 2024-05-24T20:47:38.397Z,1716583658.397 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T20:47:38.397Z,1716583658.397 [DAT](INFO): Powering down 2024-05-24T20:47:38.398Z,1716583658.398 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:38.413Z,1716583658.413 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-05-24T20:47:38.413Z,1716583658.413 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:38.414Z,1716583658.414 [Radio_Surface](INFO): Join timeout helper Thread ID is 3282 2024-05-24T20:47:38.732Z,1716583658.732 [Radio_Surface](INFO): Powering down 2024-05-24T20:47:38.733Z,1716583658.733 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T20:47:38.734Z,1716583658.734 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:38.754Z,1716583658.754 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-05-24T20:47:38.754Z,1716583658.754 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:38.754Z,1716583658.754 [Onboard](INFO): Join timeout helper Thread ID is 3283 2024-05-24T20:47:38.880Z,1716583658.880 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T20:47:38.881Z,1716583658.881 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:38.901Z,1716583658.901 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-05-24T20:47:38.901Z,1716583658.901 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:38.901Z,1716583658.901 [DataOverHttps](INFO): Join timeout helper Thread ID is 3284 2024-05-24T20:47:38.912Z,1716583658.912 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T20:47:38.915Z,1716583658.915 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:38.921Z,1716583658.921 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-05-24T20:47:38.921Z,1716583658.921 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:38.921Z,1716583658.921 [BackseatComponent](INFO): Join timeout helper Thread ID is 3285 2024-05-24T20:47:39.044Z,1716583659.044 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T20:47:39.045Z,1716583659.045 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.049Z,1716583659.049 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-05-24T20:47:39.049Z,1716583659.049 [logger ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.050Z,1716583659.050 [logger](INFO): Join timeout helper Thread ID is 3286 2024-05-24T20:47:39.086Z,1716583659.086 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T20:47:39.086Z,1716583659.086 [logger ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.105Z,1716583659.105 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-05-24T20:47:39.105Z,1716583659.105 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.106Z,1716583659.106 [CommandLine](INFO): Join timeout helper Thread ID is 3287 2024-05-24T20:47:39.173Z,1716583659.173 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T20:47:39.173Z,1716583659.173 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.185Z,1716583659.185 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-05-24T20:47:39.185Z,1716583659.185 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.186Z,1716583659.186 [CommandExec](INFO): Join timeout helper Thread ID is 3288 2024-05-24T20:47:39.187Z,1716583659.187 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-05-24T20:47:39.187Z,1716583659.187 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.188Z,1716583659.188 [controlThread](INFO): Join timeout helper Thread ID is 3289 2024-05-24T20:47:39.198Z,1716583659.198 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-05-24T20:47:39.198Z,1716583659.198 [controlThread](DEBUG): Uninitializing ControlThread 2024-05-24T20:47:39.199Z,1716583659.199 [AHRS_M2](INFO): Powering down 2024-05-24T20:47:39.270Z,1716583659.270 [NAL9602](INFO): Powering down 2024-05-24T20:47:39.348Z,1716583659.348 [RDI_Pathfinder](INFO): Powering down 2024-05-24T20:47:39.350Z,1716583659.350 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-05-24T20:47:39.351Z,1716583659.351 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-05-24T20:47:39.351Z,1716583659.351 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-05-24T20:47:39.352Z,1716583659.352 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock 2024-05-24T20:47:39.352Z,1716583659.352 [DefaultWithUndock] Stopped 2024-05-24T20:47:39.352Z,1716583659.352 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2024-05-24T20:47:39.352Z,1716583659.352 [DefaultWithUndock:SurfaceDefault] Stopped 2024-05-24T20:47:39.352Z,1716583659.352 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault 2024-05-24T20:47:39.352Z,1716583659.352 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped 2024-05-24T20:47:39.352Z,1716583659.352 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-05-24T20:47:39.352Z,1716583659.352 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2024-05-24T20:47:39.353Z,1716583659.353 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2024-05-24T20:47:39.353Z,1716583659.353 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2024-05-24T20:47:39.360Z,1716583659.360 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-05-24T20:47:39.361Z,1716583659.361 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-05-24T20:47:39.361Z,1716583659.361 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-05-24T20:47:39.361Z,1716583659.361 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-05-24T20:47:39.362Z,1716583659.362 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-05-24T20:47:39.362Z,1716583659.362 [BuoyancyServo](INFO): Powering down 2024-05-24T20:47:39.376Z,1716583659.376 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-05-24T20:47:39.376Z,1716583659.376 [ElevatorServo](INFO): Powering down 2024-05-24T20:47:39.377Z,1716583659.377 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2024-05-24T20:47:39.377Z,1716583659.377 [DockingStepper](INFO): Powering down 2024-05-24T20:47:39.378Z,1716583659.378 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-05-24T20:47:39.378Z,1716583659.378 [MassServo](INFO): Powering down 2024-05-24T20:47:39.379Z,1716583659.379 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-05-24T20:47:39.380Z,1716583659.380 [RudderServo](INFO): Powering down 2024-05-24T20:47:39.381Z,1716583659.381 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2024-05-24T20:47:39.381Z,1716583659.381 [ThrusterServo](INFO): Powering down 2024-05-24T20:47:39.382Z,1716583659.382 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-05-24T20:47:39.382Z,1716583659.382 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-05-24T20:47:39.382Z,1716583659.382 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-05-24T20:47:39.382Z,1716583659.382 [CBIT](DEBUG): Powering off loads. 2024-05-24T20:47:39.396Z,1716583659.396 [CBIT](DEBUG): Disabling WDT. 2024-05-24T20:47:39.408Z,1716583659.408 [CBIT](DEBUG): Opening all GF detection circuits. 2024-05-24T20:47:39.410Z,1716583659.410 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.474Z,1716583659.474 [DAT ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.482Z,1716583659.482 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.484Z,1716583659.484 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.490Z,1716583659.490 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.533Z,1716583659.533 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.716Z,1716583659.716 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.718Z,1716583659.718 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.723Z,1716583659.723 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.731Z,1716583659.731 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-05-24T20:47:39.798Z,1716583659.798 [logger ThreadHandler](INFO): Thread cancelled.