2025-08-22T20:50:26.056Z,1755895826.056 [CommandExec](IMPORTANT): got command restart logs 2025-08-22T20:50:26.067Z,1755895826.067 [DockingStepper](INFO): Changing to mode: 1 2025-08-22T20:50:26.368Z,1755895826.368 [OnDock:N.Docked](FAULT): Off dock, range to dock 9.900000 exceeded threshold. 2025-08-22T20:50:26.368Z,1755895826.368 [OnDock:N.Docked](IMPORTANT): State switched to DETACHED. Waiting for state timeout to act... 2025-08-22T20:50:26.418Z,1755895826.418 [DAT](INFO): Requesting device enable set for address 50. 2025-08-22T20:50:26.670Z,1755895826.670 [DAT](INFO): DAT read: user:2135> 2025-08-22T20:50:26.671Z,1755895826.671 [DAT](INFO): DAT read: Tx time:20:50:27.6938 2025-08-22T20:50:26.672Z,1755895826.672 [DAT](INFO): Ping request sent. 2025-08-22T20:50:26.672Z,1755895826.672 [DAT](INFO): transmitted an acoustic signal 2025-08-22T20:50:26.672Z,1755895826.672 [DAT](INFO): publishing transmit ping time 2025-08-22T20:50:26.672Z,1755895826.672 [DAT](INFO): Requesting device enable set for address 50. 2025-08-22T20:50:26.922Z,1755895826.922 [DAT](INFO): Requesting device enable set for address 50. 2025-08-22T20:50:27.415Z,1755895827.415 [CTD_Seabird](ERROR): Failed to parse device response: 2025-08-22T20:50:28.939Z,1755895828.939 [DAT](INFO): DAT read: Rx Time:20:50:29.5850 2025-08-22T20:50:28.939Z,1755895828.939 [DAT](INFO): Rx dataTimestamp_ set to:1755895828.939160 2025-08-22T20:50:29.699Z,1755895829.699 [DAT](INFO): DAT read: 20:50:29.5850 LVL= 30032, 25249, 13746, 30035, AGC= 54, IDX= 158, 0.04, 3.053,-1.773,-1.186,-2.088, PHS=-1.040, 0.360, 0.858, RAW= 255.4, -2.1, CAL= 252.2, -5.7, ROT= 257.8, 5.7 2025-08-22T20:50:29.700Z,1755895829.700 [DAT](INFO): got valid direction response: 20:50:29.5850 LVL= 30032, 25249, 13746, 30035, AGC= 54, IDX= 158, 0.04, 3.053,-1.773,-1.186,-2.088, PHS=-1.040, 0.360, 0.858, RAW= 255.4, -2.1, CAL= 252.2, -5.7, ROT= 257.8, 5.7 2025-08-22T20:50:29.700Z,1755895829.700 [DAT](INFO): DAT read: OK 2025-08-22T20:50:29.701Z,1755895829.701 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T20:50:29.701Z,1755895829.701 [DAT](INFO): DAT read: user:2136> 2025-08-22T20:50:29.702Z,1755895829.702 [DAT](INFO): DAT read: Tx time:20:50:30.6938 2025-08-22T20:50:29.702Z,1755895829.702 [DAT](INFO): Ping request sent. 2025-08-22T20:50:29.702Z,1755895829.702 [DAT](INFO): transmitted an acoustic signal 2025-08-22T20:50:29.703Z,1755895829.703 [DAT](INFO): #Rx 2: Read direction message, but no range. 2025-08-22T20:50:29.704Z,1755895829.704 [DAT](INFO): direction in FSK: [-0.210280,-0.972583,-0.099320] 2025-08-22T20:50:29.704Z,1755895829.704 [DAT](INFO): publishing transmit ping time 2025-08-22T20:50:29.707Z,1755895829.707 [DAT](INFO): publishing transmit ping time 2025-08-22T20:50:31.971Z,1755895831.971 [DAT](INFO): DAT read: Rx Time:20:50:32.5853 2025-08-22T20:50:31.971Z,1755895831.971 [DAT](INFO): Rx dataTimestamp_ set to:1755895831.970767 2025-08-22T20:50:32.731Z,1755895832.731 [DAT](INFO): DAT read: 20:50:32.5853 LVL= 27968, 23793, 17202, 30195, AGC= 47, IDX= 449, 0.13,-0.950, 0.436, 1.157, 0.195, PHS=-1.043, 0.286, 0.918, RAW= 251.6, -1.9, CAL= 248.1, -4.7, ROT= 261.9, 4.7 2025-08-22T20:50:32.732Z,1755895832.732 [DAT](INFO): got valid direction response: 20:50:32.5853 LVL= 27968, 23793, 17202, 30195, AGC= 47, IDX= 449, 0.13,-0.950, 0.436, 1.157, 0.195, PHS=-1.043, 0.286, 0.918, RAW= 251.6, -1.9, CAL= 248.1, -4.7, ROT= 261.9, 4.7 2025-08-22T20:50:32.732Z,1755895832.732 [DAT](INFO): DAT read: OK 2025-08-22T20:50:32.732Z,1755895832.732 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T20:50:32.733Z,1755895832.733 [DAT](INFO): DAT read: user:2137> 2025-08-22T20:50:32.734Z,1755895832.734 [DAT](INFO): DAT read: Tx time:20:50:33.6938 2025-08-22T20:50:32.734Z,1755895832.734 [DAT](INFO): Ping request sent. 2025-08-22T20:50:32.734Z,1755895832.734 [DAT](INFO): transmitted an acoustic signal 2025-08-22T20:50:32.735Z,1755895832.735 [DAT](INFO): #Rx 3: Read direction message, but no range. 2025-08-22T20:50:32.736Z,1755895832.736 [DAT](INFO): direction in FSK: [-0.140427,-0.986695,-0.081939] 2025-08-22T20:50:32.736Z,1755895832.736 [DAT](INFO): publishing transmit ping time 2025-08-22T20:50:32.745Z,1755895832.745 [DAT](INFO): publishing transmit ping time 2025-08-22T20:50:34.995Z,1755895834.995 [DAT](INFO): DAT read: Rx Time:20:50:35.5856 2025-08-22T20:50:34.995Z,1755895834.995 [DAT](INFO): Rx dataTimestamp_ set to:1755895834.994800 2025-08-22T20:50:35.502Z,1755895835.502 [DAT](INFO): DAT read: 20:50:35.5856 LVL= 32752, 27985, 20210, 32755, AGC= 50, IDX= 436,-0.11,-1.858,-0.520, 0.189,-0.686, PHS=-1.070, 0.211, 0.831, RAW= 251.4, 0.3, CAL= 247.2, -1.6, ROT= 262.8, 1.6 2025-08-22T20:50:35.503Z,1755895835.503 [DAT](INFO): got valid direction response: 20:50:35.5856 LVL= 32752, 27985, 20210, 32755, AGC= 50, IDX= 436,-0.11,-1.858,-0.520, 0.189,-0.686, PHS=-1.070, 0.211, 0.831, RAW= 251.4, 0.3, CAL= 247.2, -1.6, ROT= 262.8, 1.6 2025-08-22T20:50:35.504Z,1755895835.504 [DAT](INFO): DAT read: OK 2025-08-22T20:50:35.504Z,1755895835.504 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T20:50:35.504Z,1755895835.504 [DAT](INFO): #Rx 4: Read direction message, but no range. 2025-08-22T20:50:35.506Z,1755895835.506 [DAT](INFO): direction in FSK: [-0.125284,-0.991728,-0.027922] 2025-08-22T20:50:56.709Z,1755895856.709 [OnDock:N.Docked](IMPORTANT): VEHICLE DETACHED FROM DOCK. 2025-08-22T20:50:56.710Z,1755895856.710 [OnDock:N.Docked] Stopped 2025-08-22T20:50:56.710Z,1755895856.710 [OnDock:Detach] Running Loop=1 2025-08-22T20:50:56.710Z,1755895856.710 [OnDock:Detach](DEBUG): Aggregate::initialize OnDock:Detach 2025-08-22T20:50:56.710Z,1755895856.710 [OnDock:Detach:A] Running Loop=1 2025-08-22T20:50:56.755Z,1755895856.755 [DockingStepper](INFO): Changing to mode: 2 2025-08-22T20:50:57.107Z,1755895857.107 [OnDock:Detach:A](IMPORTANT): Mission ending, breaking off. Range: 9.835803 m . 2025-08-22T20:50:57.107Z,1755895857.107 [OnDock:Detach:A] Stopped 2025-08-22T20:50:57.107Z,1755895857.107 [OnDock:Detach:B.Undock] Running Loop=1 2025-08-22T20:50:57.107Z,1755895857.107 [OnDock:Detach:B.Undock](DEBUG): Initialize. 2025-08-22T20:50:57.107Z,1755895857.107 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-22T20:50:57.194Z,1755895857.194 [DAT](INFO): DAT read: user:2138> 2025-08-22T20:50:57.195Z,1755895857.195 [DAT](INFO): DAT read: Tx time:20:50:58.1936 2025-08-22T20:50:57.196Z,1755895857.196 [DAT](INFO): Ping request sent. 2025-08-22T20:50:57.196Z,1755895857.196 [DAT](INFO): transmitted an acoustic signal 2025-08-22T20:50:57.196Z,1755895857.196 [DAT](INFO): publishing transmit ping time 2025-08-22T20:50:57.685Z,1755895857.685 [OnDock:Detach:B.Undock](DEBUG): Initialize. 2025-08-22T20:50:57.685Z,1755895857.685 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-22T20:50:57.723Z,1755895857.723 [OnDock:Detach:B.Undock](INFO): Detaching from dock at depth 73.020966 m. 2025-08-22T20:50:58.096Z,1755895858.096 [DockingStepper](INFO): Changing to mode: 3 2025-08-22T20:50:59.463Z,1755895859.463 [DAT](INFO): DAT read: Rx Time:20:51:00.0879 2025-08-22T20:50:59.463Z,1755895859.463 [DAT](INFO): Rx dataTimestamp_ set to:1755895859.462747 2025-08-22T20:51:00.223Z,1755895860.223 [DAT](INFO): DAT read: 20:51:00.0879 LVL= 22128, 18881, 14674, 25939, AGC= 52, IDX= 446, 0.27, 1.364, 2.530,-2.924, 2.484, PHS=-1.018, 0.091, 0.831, RAW= 246.6, 1.2, CAL= 242.5, 0.8, ROT= 267.5, -0.8 2025-08-22T20:51:00.224Z,1755895860.224 [DAT](INFO): got valid direction response: 20:51:00.0879 LVL= 22128, 18881, 14674, 25939, AGC= 52, IDX= 446, 0.27, 1.364, 2.530,-2.924, 2.484, PHS=-1.018, 0.091, 0.831, RAW= 246.6, 1.2, CAL= 242.5, 0.8, ROT= 267.5, -0.8 2025-08-22T20:51:00.224Z,1755895860.224 [DAT](INFO): DAT read: OK 2025-08-22T20:51:00.225Z,1755895860.225 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T20:51:00.225Z,1755895860.225 [DAT](INFO): DAT read: user:2139> 2025-08-22T20:51:00.226Z,1755895860.226 [DAT](INFO): DAT read: Tx time:20:51:01.1936 2025-08-22T20:51:00.226Z,1755895860.226 [DAT](INFO): Ping request sent. 2025-08-22T20:51:00.226Z,1755895860.226 [DAT](INFO): transmitted an acoustic signal 2025-08-22T20:51:00.227Z,1755895860.227 [DAT](INFO): #Rx 5: Read direction message, but no range. 2025-08-22T20:51:00.229Z,1755895860.229 [DAT](INFO): direction in FSK: [-0.043615,-0.998951,0.013962] 2025-08-22T20:51:00.234Z,1755895860.234 [DAT](INFO): publishing transmit ping time 2025-08-22T20:51:00.238Z,1755895860.238 [DAT](INFO): publishing transmit ping time 2025-08-22T20:51:02.487Z,1755895862.487 [DAT](INFO): DAT read: Rx Time:20:51:03.0883 2025-08-22T20:51:02.487Z,1755895862.487 [DAT](INFO): Rx dataTimestamp_ set to:1755895862.486900 2025-08-22T20:51:02.505Z,1755895862.505 [OnDock:Detach:B.Undock](INFO): Engaging thruster for 10.0 seconds (max) to disengage. 2025-08-22T20:51:02.998Z,1755895862.998 [DAT](INFO): DAT read: 20:51:03.0883 LVL= 28896, 22609, 17010, 29491, AGC= 52, IDX= 450,-0.17,-1.598,-0.422, 0.436,-0.466, PHS=-1.029, 0.089, 0.859, RAW= 246.1, 1.0, CAL= 242.0, 0.6, ROT= 268.0, -0.6 2025-08-22T20:51:02.999Z,1755895862.999 [DAT](INFO): got valid direction response: 20:51:03.0883 LVL= 28896, 22609, 17010, 29491, AGC= 52, IDX= 450,-0.17,-1.598,-0.422, 0.436,-0.466, PHS=-1.029, 0.089, 0.859, RAW= 246.1, 1.0, CAL= 242.0, 0.6, ROT= 268.0, -0.6 2025-08-22T20:51:02.999Z,1755895862.999 [DAT](INFO): DAT read: OK 2025-08-22T20:51:02.999Z,1755895862.999 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T20:51:02.000Z,1755895863.000 [DAT](INFO): DAT read: user:2140> 2025-08-22T20:51:03.001Z,1755895863.001 [DAT](INFO): DAT read: Tx time:20:51:04.1936 2025-08-22T20:51:03.001Z,1755895863.001 [DAT](INFO): Ping request sent. 2025-08-22T20:51:03.001Z,1755895863.001 [DAT](INFO): transmitted an acoustic signal 2025-08-22T20:51:03.001Z,1755895863.001 [DAT](INFO): #Rx 6: Read direction message, but no range. 2025-08-22T20:51:03.002Z,1755895863.002 [DAT](INFO): direction in FSK: [-0.034897,-0.999336,0.010472] 2025-08-22T20:51:03.002Z,1755895863.002 [DAT](INFO): publishing transmit ping time 2025-08-22T20:51:03.006Z,1755895863.006 [DAT](INFO): publishing transmit ping time 2025-08-22T20:51:05.511Z,1755895865.511 [DAT](INFO): DAT read: Rx Time:20:51:06.0886 2025-08-22T20:51:05.512Z,1755895865.512 [DAT](INFO): Rx dataTimestamp_ set to:1755895865.511431 2025-08-22T20:51:06.019Z,1755895866.019 [DAT](INFO): DAT read: 20:51:06.0886 LVL= 23088, 20721, 15810, 26867, AGC= 51, IDX= 421, 0.17,-0.107, 1.099, 1.907, 1.023, PHS=-1.028, 0.121, 0.840, RAW= 247.6, 0.8, CAL= 243.2, 0.1, ROT= 266.8, -0.1 2025-08-22T20:51:06.020Z,1755895866.020 [DAT](INFO): got valid direction response: 20:51:06.0886 LVL= 23088, 20721, 15810, 26867, AGC= 51, IDX= 421, 0.17,-0.107, 1.099, 1.907, 1.023, PHS=-1.028, 0.121, 0.840, RAW= 247.6, 0.8, CAL= 243.2, 0.1, ROT= 266.8, -0.1 2025-08-22T20:51:06.021Z,1755895866.021 [DAT](INFO): DAT read: OK 2025-08-22T20:51:06.021Z,1755895866.021 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T20:51:06.021Z,1755895866.021 [DAT](INFO): #Rx 7: Read direction message, but no range. 2025-08-22T20:51:06.022Z,1755895866.022 [DAT](INFO): direction in FSK: [-0.055822,-0.998439,0.001745] 2025-08-22T20:51:08.540Z,1755895868.540 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 7.80, 8.82, 7.97, :WI,-32768,-32768,-32768,-32768,V 2025-08-22T20:51:12.593Z,1755895872.593 [OnDock:Detach:B.Undock](INFO): Thruster off. 2025-08-22T20:52:14.796Z,1755895934.796 [OnDock:Detach:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-08-22T20:52:23.683Z,1755895943.683 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T20:52:23.766Z,1755895943.766 [DAT](DEBUG): Acoustic response timeout 2025-08-22T20:52:23.767Z,1755895943.767 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T20:52:24.018Z,1755895944.018 [DAT](INFO): DAT read: user:2141> 2025-08-22T20:52:24.019Z,1755895944.019 [DAT](INFO): DAT read: Tx time:20:52:25.0443 2025-08-22T20:52:24.019Z,1755895944.019 [DAT](INFO): Ping request sent. 2025-08-22T20:52:24.020Z,1755895944.020 [DAT](INFO): transmitted an acoustic signal 2025-08-22T20:52:24.020Z,1755895944.020 [DAT](INFO): publishing transmit ping time 2025-08-22T20:52:24.020Z,1755895944.020 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000733 2025-08-22T20:52:24.273Z,1755895944.273 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.252819 2025-08-22T20:52:24.523Z,1755895944.523 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503009 2025-08-22T20:52:24.775Z,1755895944.775 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754955 2025-08-22T20:52:25.026Z,1755895945.026 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006887 2025-08-22T20:52:25.279Z,1755895945.279 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258942 2025-08-22T20:52:25.531Z,1755895945.531 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510942 2025-08-22T20:52:25.783Z,1755895945.783 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762933 2025-08-22T20:52:26.035Z,1755895946.035 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015021 2025-08-22T20:52:26.287Z,1755895946.287 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266954 2025-08-22T20:52:26.539Z,1755895946.539 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519162 2025-08-22T20:52:26.790Z,1755895946.790 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770902 2025-08-22T20:52:27.043Z,1755895947.043 [DAT](INFO): DAT read: Rx Time:20:52:27.4807 2025-08-22T20:52:27.043Z,1755895947.043 [DAT](INFO): Rx dataTimestamp_ set to:1755895947.042880 2025-08-22T20:52:27.044Z,1755895947.044 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024142 2025-08-22T20:52:27.298Z,1755895947.298 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278881 2025-08-22T20:52:27.556Z,1755895947.556 [DAT](INFO): DAT read: 20:52:27.4807 LVL= 32752, 26017, 32754, 32755, AGC= 57, IDX= 327,-0.31,-1.425, 0.684,-0.655,-0.558, PHS=-0.765, 1.288,-0.140, RAW= 312.7, -4.2, CAL= 311.7, -7.6, ROT= 198.3, 7.6 2025-08-22T20:52:27.557Z,1755895947.557 [DAT](INFO): got valid direction response: 20:52:27.4807 LVL= 32752, 26017, 32754, 32755, AGC= 57, IDX= 327,-0.31,-1.425, 0.684,-0.655,-0.558, PHS=-0.765, 1.288,-0.140, RAW= 312.7, -4.2, CAL= 311.7, -7.6, ROT= 198.3, 7.6 2025-08-22T20:52:27.558Z,1755895947.558 [DAT](INFO): DAT read: Bearing 265.6, -22.4 (Local) 2025-08-22T20:52:27.558Z,1755895947.558 [DAT](INFO): Local bearing/azimuth received: Bearing 265.6, -22.4 (Local) 2025-08-22T20:52:27.559Z,1755895947.559 [DAT](INFO): DAT read: Range 11 to 50 : 24.2 m (Round-trip 32.3 ms) speed 0.0 m/s 2025-08-22T20:52:27.560Z,1755895947.560 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T20:52:27.561Z,1755895947.561 [DAT](INFO): direction in FSK: [-0.941085,-0.311234,-0.132256] 2025-08-22T20:52:27.561Z,1755895947.561 [DAT](INFO): publishing direction and range info 2025-08-22T20:52:35.005Z,1755895955.005 [OnDock:Detach:B.Undock](INFO): Detached at range: 24.20 m. Transitioning docking module to standby. 2025-08-22T20:52:35.433Z,1755895955.433 [DockingStepper](INFO): Changing to mode: 1 2025-08-22T20:52:41.479Z,1755895961.479 [OnDock:Detach:B.Undock](INFO): Docking module at standby. 2025-08-22T20:52:41.875Z,1755895961.875 [OnDock:Detach:B.Undock](IMPORTANT): Undocking sequence complete. 2025-08-22T20:52:41.875Z,1755895961.875 [OnDock:Detach:B.Undock] Stopped 2025-08-22T20:52:41.875Z,1755895961.875 [OnDock:Detach](INFO): Completed OnDock:Detach 2025-08-22T20:52:41.875Z,1755895961.875 [OnDock:Detach] Stopped 2025-08-22T20:52:41.875Z,1755895961.875 [OnDock:Detach](DEBUG): Aggregate::uninitialize OnDock:Detach 2025-08-22T20:52:41.878Z,1755895961.878 [OnDock](INFO): Completed OnDock 2025-08-22T20:52:41.878Z,1755895961.878 [MissionManager](INFO): OnDock is completed. 2025-08-22T20:52:41.878Z,1755895961.878 [MissionManager](INFO): Uninitializing Mission OnDock 2025-08-22T20:52:41.878Z,1755895961.878 [OnDock] Stopped 2025-08-22T20:52:41.879Z,1755895961.879 [OnDock](DEBUG): Aggregate::uninitialize OnDock 2025-08-22T20:52:41.879Z,1755895961.879 [OnDock:StandardEnvelopes] Stopped 2025-08-22T20:52:41.879Z,1755895961.879 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes 2025-08-22T20:52:41.879Z,1755895961.879 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-08-22T20:52:41.879Z,1755895961.879 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-08-22T20:52:41.879Z,1755895961.879 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-08-22T20:52:41.879Z,1755895961.879 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-08-22T20:52:41.879Z,1755895961.879 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-08-22T20:52:41.879Z,1755895961.879 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-08-22T20:52:41.879Z,1755895961.879 [OnDock:BackseatDriver] Stopped 2025-08-22T20:52:41.879Z,1755895961.879 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver 2025-08-22T20:52:41.879Z,1755895961.879 [OnDock:BackseatDriver:A.BackseatDriver] Stopped 2025-08-22T20:52:41.879Z,1755895961.879 [OnDock:PowerOnly] Stopped 2025-08-22T20:52:41.880Z,1755895961.880 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly 2025-08-22T20:52:41.880Z,1755895961.880 [OnDock:PowerOnly:E.Wait] Stopped 2025-08-22T20:52:41.880Z,1755895961.880 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-08-22T20:52:41.880Z,1755895961.880 [OnDock:dataRead] Stopped 2025-08-22T20:52:41.880Z,1755895961.880 [OnDock:dataRead](DEBUG): Aggregate::uninitialize OnDock:dataRead 2025-08-22T20:52:41.880Z,1755895961.880 [OnDock:dataRead:A] Stopped 2025-08-22T20:52:41.880Z,1755895961.880 [OnDock:J.] Stopped 2025-08-22T20:52:41.880Z,1755895961.880 [OnDock:J.](DEBUG): Uninitializing TrackAcousticContact. 2025-08-22T20:52:41.880Z,1755895961.880 [OnDock:PeriodicCheck] Stopped 2025-08-22T20:52:41.880Z,1755895961.880 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck 2025-08-22T20:52:41.881Z,1755895961.881 [OnDock:PeriodicCheck:CheckIP] Stopped 2025-08-22T20:52:41.881Z,1755895961.881 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP 2025-08-22T20:52:41.881Z,1755895961.881 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped 2025-08-22T20:52:41.881Z,1755895961.881 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component. 2025-08-22T20:52:42.268Z,1755895962.267 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-08-22T20:52:42.268Z,1755895962.268 [DefaultWithUndock] Running Loop=1 2025-08-22T20:52:42.268Z,1755895962.268 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-08-22T20:52:42.268Z,1755895962.268 [DefaultWithUndock:A.Wait] Running Loop=1 2025-08-22T20:52:42.268Z,1755895962.268 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-08-22T20:53:12.562Z,1755895992.562 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2025-08-22T20:53:12.562Z,1755895992.562 [DefaultWithUndock:A.Wait] Stopped 2025-08-22T20:53:12.562Z,1755895992.562 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-08-22T20:53:12.563Z,1755895992.563 [DefaultWithUndock:LeaveDock] Running Loop=1 2025-08-22T20:53:12.563Z,1755895992.563 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2025-08-22T20:53:12.563Z,1755895992.563 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-08-22T20:53:12.563Z,1755895992.563 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2025-08-22T20:53:12.563Z,1755895992.563 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2025-08-22T20:53:12.563Z,1755895992.563 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-08-22T20:53:12.564Z,1755895992.564 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-22T20:53:12.987Z,1755895992.987 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-08-22T20:53:12.987Z,1755895992.987 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-22T20:53:12.988Z,1755895992.988 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth 73.732529 m. 2025-08-22T20:53:12.989Z,1755895992.989 [DefaultWithUndock:LeaveDock:A.](ERROR): Failed to read sound speed 2025-08-22T20:53:13.427Z,1755895993.427 [DockingStepper](INFO): Changing to mode: 3 2025-08-22T20:53:16.127Z,1755895996.127 [CTD_Seabird](ERROR): Failed to parse device response: 2025-08-22T20:53:19.438Z,1755895999.438 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-08-22T20:53:22.671Z,1755896002.671 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-22T20:53:22.766Z,1755896002.766 [DAT](DEBUG): Acoustic response timeout 2025-08-22T20:53:22.767Z,1755896002.767 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T20:53:23.018Z,1755896003.018 [DAT](INFO): DAT read: user:2142> 2025-08-22T20:53:23.019Z,1755896003.019 [DAT](INFO): DAT read: Tx time:20:53:24.0441 2025-08-22T20:53:23.019Z,1755896003.019 [DAT](INFO): Ping request sent. 2025-08-22T20:53:23.020Z,1755896003.020 [DAT](INFO): transmitted an acoustic signal 2025-08-22T20:53:23.020Z,1755896003.020 [DAT](INFO): publishing transmit ping time 2025-08-22T20:53:23.020Z,1755896003.020 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000731 2025-08-22T20:53:23.271Z,1755896003.271 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250943 2025-08-22T20:53:23.525Z,1755896003.525 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.504927 2025-08-22T20:53:23.775Z,1755896003.775 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754932 2025-08-22T20:53:24.026Z,1755896004.026 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006871 2025-08-22T20:53:24.279Z,1755896004.279 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259081 2025-08-22T20:53:24.531Z,1755896004.531 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510948 2025-08-22T20:53:24.783Z,1755896004.783 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763501 2025-08-22T20:53:25.034Z,1755896005.034 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014892 2025-08-22T20:53:25.287Z,1755896005.287 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266955 2025-08-22T20:53:25.539Z,1755896005.539 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518985 2025-08-22T20:53:25.791Z,1755896005.791 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770989 2025-08-22T20:53:26.045Z,1755896006.045 [DAT](INFO): DAT read: Rx Time:20:53:26.4818 2025-08-22T20:53:26.047Z,1755896006.047 [DAT](INFO): Rx dataTimestamp_ set to:1755896006.045921 2025-08-22T20:53:26.048Z,1755896006.048 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.028527 2025-08-22T20:53:26.295Z,1755896006.295 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274952 2025-08-22T20:53:26.552Z,1755896006.552 [DAT](INFO): DAT read: 20:53:26.4818 LVL= 31568, 20657, 26898, 32755, AGC= 59, IDX= 452,-0.14, 2.370,-1.826, 2.787, 3.093, PHS=-0.621, 1.409,-0.350, RAW= 322.9, -4.6, CAL= 322.1, -7.8, ROT= 187.9, 7.8 2025-08-22T20:53:26.553Z,1755896006.553 [DAT](INFO): got valid direction response: 20:53:26.4818 LVL= 31568, 20657, 26898, 32755, AGC= 59, IDX= 452,-0.14, 2.370,-1.826, 2.787, 3.093, PHS=-0.621, 1.409,-0.350, RAW= 322.9, -4.6, CAL= 322.1, -7.8, ROT= 187.9, 7.8 2025-08-22T20:53:26.554Z,1755896006.554 [DAT](INFO): DAT read: Bearing 291.8, -15.8 (Local) 2025-08-22T20:53:26.554Z,1755896006.554 [DAT](INFO): Local bearing/azimuth received: Bearing 291.8, -15.8 (Local) 2025-08-22T20:53:26.555Z,1755896006.555 [DAT](INFO): DAT read: Range 11 to 50 : 25.1 m (Round-trip 33.5 ms) speed 0.0 m/s 2025-08-22T20:53:26.556Z,1755896006.556 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T20:53:26.557Z,1755896006.557 [DAT](INFO): direction in FSK: [-0.981345,-0.136173,-0.135716] 2025-08-22T20:53:26.557Z,1755896006.557 [DAT](INFO): publishing direction and range info 2025-08-22T20:53:32.764Z,1755896012.764 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-22T20:53:32.855Z,1755896012.855 [DAT](INFO): ****** received valid address query ****** 2025-08-22T20:53:32.855Z,1755896012.855 [DAT](INFO): ****** received valid ping request ****** 2025-08-22T20:53:32.856Z,1755896012.856 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T20:53:33.108Z,1755896013.108 [DAT](INFO): DAT read: user:2143> 2025-08-22T20:53:33.111Z,1755896013.111 [DAT](INFO): DAT read: Tx time:20:53:34.0956 2025-08-22T20:53:33.112Z,1755896013.112 [DAT](INFO): Ping request sent. 2025-08-22T20:53:33.112Z,1755896013.112 [DAT](INFO): transmitted an acoustic signal 2025-08-22T20:53:33.113Z,1755896013.113 [DAT](INFO): publishing transmit ping time 2025-08-22T20:53:33.116Z,1755896013.116 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.003421 2025-08-22T20:53:33.359Z,1755896013.359 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.247223 2025-08-22T20:53:33.612Z,1755896013.612 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.499490 2025-08-22T20:53:33.862Z,1755896013.862 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.750698 2025-08-22T20:53:34.119Z,1755896014.119 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006576 2025-08-22T20:53:34.367Z,1755896014.367 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.254785 2025-08-22T20:53:34.618Z,1755896014.618 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.506654 2025-08-22T20:53:34.871Z,1755896014.871 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.759076 2025-08-22T20:53:35.125Z,1755896015.125 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.012503 2025-08-22T20:53:35.374Z,1755896015.374 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.262682 2025-08-22T20:53:35.627Z,1755896015.627 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.514843 2025-08-22T20:53:35.879Z,1755896015.879 [DAT](INFO): DAT read: Rx Time:20:53:36.5333 2025-08-22T20:53:35.879Z,1755896015.879 [DAT](INFO): Rx dataTimestamp_ set to:1755896015.878730 2025-08-22T20:53:35.880Z,1755896015.880 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.767793 2025-08-22T20:53:36.137Z,1755896016.137 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024894 2025-08-22T20:53:36.388Z,1755896016.388 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.276135 2025-08-22T20:53:36.644Z,1755896016.644 [DAT](INFO): DAT read: 20:53:36.5333 LVL= 30608, 21921, 31122, 32755, AGC= 60, IDX= 437,-0.25,-2.344,-0.241,-1.943,-1.657, PHS=-0.585, 1.461,-0.329, RAW= 323.4, -5.7, CAL= 322.8, -9.0, ROT= 187.2, 9.0 2025-08-22T20:53:36.645Z,1755896016.645 [DAT](INFO): got valid direction response: 20:53:36.5333 LVL= 30608, 21921, 31122, 32755, AGC= 60, IDX= 437,-0.25,-2.344,-0.241,-1.943,-1.657, PHS=-0.585, 1.461,-0.329, RAW= 323.4, -5.7, CAL= 322.8, -9.0, ROT= 187.2, 9.0 2025-08-22T20:53:36.646Z,1755896016.646 [DAT](INFO): DAT read: Bearing 295.8, -16.5 (Local) 2025-08-22T20:53:36.646Z,1755896016.646 [DAT](INFO): Local bearing/azimuth received: Bearing 295.8, -16.5 (Local) 2025-08-22T20:53:36.647Z,1755896016.647 [DAT](INFO): DAT read: Range 11 to 50 : 25.2 m (Round-trip 33.6 ms) speed 0.0 m/s 2025-08-22T20:53:36.648Z,1755896016.648 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T20:53:36.649Z,1755896016.649 [DAT](INFO): direction in FSK: [-0.979900,-0.123790,-0.156434] 2025-08-22T20:53:36.649Z,1755896016.649 [DAT](INFO): publishing direction and range info 2025-08-22T20:53:39.638Z,1755896019.638 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 25.20 m. Transitioning docking module to standby. 2025-08-22T20:53:40.095Z,1755896020.095 [DockingStepper](INFO): Changing to mode: 1 2025-08-22T20:53:42.874Z,1755896022.874 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-22T20:53:42.943Z,1755896022.943 [DAT](INFO): ****** received valid address query ****** 2025-08-22T20:53:42.943Z,1755896022.943 [DAT](INFO): ****** received valid ping request ****** 2025-08-22T20:53:42.944Z,1755896022.944 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T20:53:43.195Z,1755896023.195 [DAT](INFO): DAT read: user:2144> 2025-08-22T20:53:43.196Z,1755896023.196 [DAT](INFO): DAT read: Tx time:20:53:44.1940 2025-08-22T20:53:43.196Z,1755896023.196 [DAT](INFO): Ping request sent. 2025-08-22T20:53:43.196Z,1755896023.196 [DAT](INFO): transmitted an acoustic signal 2025-08-22T20:53:43.196Z,1755896023.196 [DAT](INFO): publishing transmit ping time 2025-08-22T20:53:43.197Z,1755896023.197 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000710 2025-08-22T20:53:43.447Z,1755896023.447 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250623 2025-08-22T20:53:43.699Z,1755896023.699 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502534 2025-08-22T20:53:43.950Z,1755896023.950 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754299 2025-08-22T20:53:44.203Z,1755896024.203 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006366 2025-08-22T20:53:44.454Z,1755896024.454 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258251 2025-08-22T20:53:44.706Z,1755896024.706 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510273 2025-08-22T20:53:44.959Z,1755896024.959 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762622 2025-08-22T20:53:45.210Z,1755896025.210 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014259 2025-08-22T20:53:45.463Z,1755896025.463 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266359 2025-08-22T20:53:45.715Z,1755896025.715 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518421 2025-08-22T20:53:45.967Z,1755896025.967 [DAT](INFO): DAT read: Rx Time:20:53:46.6319 2025-08-22T20:53:45.967Z,1755896025.967 [DAT](INFO): Rx dataTimestamp_ set to:1755896025.966694 2025-08-22T20:53:45.967Z,1755896025.967 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771355 2025-08-22T20:53:46.122Z,1755896026.122 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2025-08-22T20:53:46.220Z,1755896026.220 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023487 2025-08-22T20:53:46.470Z,1755896026.470 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274320 2025-08-22T20:53:46.568Z,1755896026.569 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2025-08-22T20:53:46.569Z,1755896026.569 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2025-08-22T20:53:46.569Z,1755896026.569 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2025-08-22T20:53:46.569Z,1755896026.569 [DefaultWithUndock:LeaveDock] Stopped 2025-08-22T20:53:46.569Z,1755896026.569 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2025-08-22T20:53:46.569Z,1755896026.569 [DefaultWithUndock:LeaveDock:A.] Stopped 2025-08-22T20:53:46.570Z,1755896026.570 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2025-08-22T20:53:46.570Z,1755896026.570 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2025-08-22T20:53:46.570Z,1755896026.570 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2025-08-22T20:53:46.570Z,1755896026.570 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2025-08-22T20:53:46.570Z,1755896026.570 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-08-22T20:53:46.570Z,1755896026.570 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-08-22T20:53:46.571Z,1755896026.571 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-08-22T20:53:46.571Z,1755896026.571 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-08-22T20:53:46.571Z,1755896026.571 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-08-22T20:53:46.571Z,1755896026.571 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-08-22T20:53:46.572Z,1755896026.572 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-08-22T20:53:46.728Z,1755896026.728 [DAT](INFO): DAT read: 20:53:46.6319 LVL= 27248, 27937, 30914, 29619, AGC= 64, IDX= 438, 0.42,-0.635, 1.457,-0.278, 0.082, PHS=-0.615, 1.421,-0.403, RAW= 324.6, -4.2, CAL= 323.8, -7.4, ROT= 186.2, 7.4 2025-08-22T20:53:46.729Z,1755896026.729 [DAT](INFO): got valid direction response: 20:53:46.6319 LVL= 27248, 27937, 30914, 29619, AGC= 64, IDX= 438, 0.42,-0.635, 1.457,-0.278, 0.082, PHS=-0.615, 1.421,-0.403, RAW= 324.6, -4.2, CAL= 323.8, -7.4, ROT= 186.2, 7.4 2025-08-22T20:53:46.730Z,1755896026.730 [DAT](INFO): DAT read: Bearing 296.8, -16.4 (Local) 2025-08-22T20:53:46.730Z,1755896026.730 [DAT](INFO): Local bearing/azimuth received: Bearing 296.8, -16.4 (Local) 2025-08-22T20:53:46.731Z,1755896026.731 [DAT](INFO): DAT read: Range 11 to 50 : 25.2 m (Round-trip 33.7 ms) speed 0.0 m/s 2025-08-22T20:53:46.732Z,1755896026.732 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T20:53:46.733Z,1755896026.733 [DAT](INFO): direction in FSK: [-0.985871,-0.107100,-0.128796] 2025-08-22T20:53:46.733Z,1755896026.733 [DAT](INFO): publishing direction and range info 2025-08-22T20:53:47.380Z,1755896027.380 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -24.00 mm (1 active estimators). 2025-08-22T20:53:47.642Z,1755896027.642 [Radio_Surface](INFO): Powering up 2025-08-22T20:53:52.894Z,1755896032.894 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11 2025-08-22T20:53:52.894Z,1755896032.894 [Radio_Surface](INFO): No local IP found 2025-08-22T20:54:03.599Z,1755896043.599 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00022800412361,35.0, +10.1,4 2025-08-22T20:54:49.568Z,1755896089.568 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00022800420930,74, 354.33, 229.43, 0.00 2025-08-22T20:55:03.725Z,1755896103.725 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00022800422351,35.0, +10.1, 0.4, 0 2025-08-22T20:55:23.919Z,1755896123.919 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, , +18.12, 46, 0.00 2025-08-22T20:55:33.700Z,1755896133.700 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, -0.76, +27.75 +0.58, 20.00, 0.00 2025-08-22T20:55:43.792Z,1755896143.792 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, -0.46, +37.53, +0.46, :BI, +59, -902, -86,-32768,A 2025-08-22T20:55:53.902Z,1755896153.902 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +7.54, 2532.43, 0.00 2025-08-22T20:56:14.934Z,1755896174.934 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-22T20:56:21.064Z,1755896181.064 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-22T20:56:22.066Z,1755896182.066 [DataOverHttps](INFO): Radio surface powered ON. 2025-08-22T20:56:22.066Z,1755896182.066 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:56:25.423Z,1755896185.423 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2025-08-22T20:56:25.423Z,1755896185.423 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-22T20:56:25.423Z,1755896185.423 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-22T20:56:52.190Z,1755896212.190 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-22T20:56:53.194Z,1755896213.194 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:57:23.314Z,1755896243.314 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-22T20:57:24.318Z,1755896244.318 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:57:35.230Z,1755896255.230 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003191 2025-08-22T20:57:36.230Z,1755896256.230 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:57:45.024Z,1755896265.024 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:57:54.026Z,1755896274.026 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:58:02.819Z,1755896282.819 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:58:11.615Z,1755896291.615 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:58:20.646Z,1755896300.646 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:58:23.377Z,1755896303.377 [NAL9602](INFO): SBD MO Status=2, MOMSN=988, MT Status=2, MTMSN=0 2025-08-22T20:58:23.377Z,1755896303.377 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-22T20:58:29.474Z,1755896309.474 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:58:38.386Z,1755896318.386 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:58:47.274Z,1755896327.274 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:58:55.694Z,1755896335.694 [NAL9602](INFO): SBD MO Status=2, MOMSN=988, MT Status=2, MTMSN=0 2025-08-22T20:58:55.695Z,1755896335.695 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-22T20:58:56.034Z,1755896336.034 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:59:05.078Z,1755896345.078 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:59:13.946Z,1755896353.946 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:59:22.750Z,1755896362.750 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:59:31.886Z,1755896371.886 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:59:35.288Z,1755896375.288 [NAL9602](INFO): SBD MO Status=2, MOMSN=988, MT Status=2, MTMSN=0 2025-08-22T20:59:35.288Z,1755896375.288 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-22T20:59:40.690Z,1755896380.690 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:59:49.498Z,1755896389.498 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T20:59:58.430Z,1755896398.430 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:00:09.306Z,1755896409.306 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:00:18.158Z,1755896418.158 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:00:27.162Z,1755896427.162 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:00:35.982Z,1755896435.982 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:00:40.356Z,1755896440.356 [NAL9602](INFO): SBD MO Status=2, MOMSN=988, MT Status=2, MTMSN=0 2025-08-22T21:00:40.356Z,1755896440.356 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-22T21:00:44.898Z,1755896444.898 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:00:53.822Z,1755896453.822 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:01:02.690Z,1755896462.690 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:01:11.534Z,1755896471.534 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:01:20.434Z,1755896480.434 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:01:23.168Z,1755896483.168 [NAL9602](INFO): SBD MO Status=1, MOMSN=988, MT Status=0, MTMSN=0 2025-08-22T21:01:23.168Z,1755896483.168 [NAL9602](INFO): No messages in MT queue 2025-08-22T21:01:24.381Z,1755896484.381 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210123.00,A,3646.82398,N,12151.50249,W,1.011,6.83,220825,,,D*7A 2025-08-22T21:01:24.390Z,1755896484.390 [NAL9602](INFO): GPS fix at 20250822T210123: (36.780400, -121.858375) 2025-08-22T21:01:24.416Z,1755896484.416 [UniversalFixResidualReporter](INFO): Fix residual: 0.4 %DT, over the last 976.3 m. Residual distance 4.1 m at bearing 140.9 degrees. Fix at (36.7804, -121.8584) with 126.1 m made good. 2025-08-22T21:01:24.417Z,1755896484.417 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-22T21:01:24.417Z,1755896484.417 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-22T21:01:26.840Z,1755896486.840 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250822T201209/Courier0018.lzma 2025-08-22T21:01:27.647Z,1755896487.647 [DataOverHttps](INFO): Moved sent file to Logs/20250822T201209/Courier0018.lzma.bak 2025-08-22T21:01:27.648Z,1755896487.648 [DataOverHttps](INFO): SBD MOMSN=25882856 2025-08-22T21:01:36.258Z,1755896496.258 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-22T21:01:44.430Z,1755896504.430 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250822T202250/Courier0000.lzma 2025-08-22T21:01:45.431Z,1755896505.431 [DataOverHttps](INFO): Moved sent file to Logs/20250822T202250/Courier0000.lzma.bak 2025-08-22T21:01:45.432Z,1755896505.432 [DataOverHttps](INFO): SBD MOMSN=25882859 2025-08-22T21:01:56.459Z,1755896516.459 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-22T21:02:02.227Z,1755896522.227 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250822T202250/Courier0003.lzma 2025-08-22T21:02:03.215Z,1755896523.215 [DataOverHttps](INFO): Moved sent file to Logs/20250822T202250/Courier0003.lzma.bak 2025-08-22T21:02:03.216Z,1755896523.216 [DataOverHttps](INFO): SBD MOMSN=25882862 2025-08-22T21:02:20.770Z,1755896540.770 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250822T202250/Courier0006.lzma 2025-08-22T21:02:21.771Z,1755896541.771 [DataOverHttps](INFO): Moved sent file to Logs/20250822T202250/Courier0006.lzma.bak 2025-08-22T21:02:21.772Z,1755896541.772 [DataOverHttps](INFO): SBD MOMSN=25882866 2025-08-22T21:02:38.529Z,1755896558.529 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250822T202250/Courier0009.lzma 2025-08-22T21:02:39.532Z,1755896559.532 [DataOverHttps](INFO): Moved sent file to Logs/20250822T202250/Courier0009.lzma.bak 2025-08-22T21:02:39.532Z,1755896559.532 [DataOverHttps](INFO): SBD MOMSN=25882868 2025-08-22T21:02:56.374Z,1755896576.374 [DataOverHttps](INFO): Sending 324 bytes from file Logs/20250822T205026/Courier0000.lzma 2025-08-22T21:02:57.927Z,1755896577.927 [DataOverHttps](INFO): Moved sent file to Logs/20250822T205026/Courier0000.lzma.bak 2025-08-22T21:02:57.927Z,1755896577.927 [DataOverHttps](INFO): SBD MOMSN=25882870 2025-08-22T21:03:14.203Z,1755896594.203 [DataOverHttps](INFO): Sending 308 bytes from file Logs/20250822T201209/Express0019.lzma 2025-08-22T21:03:15.203Z,1755896595.203 [DataOverHttps](INFO): Moved sent file to Logs/20250822T201209/Express0019.lzma.bak 2025-08-22T21:03:15.204Z,1755896595.204 [DataOverHttps](INFO): SBD MOMSN=25882874 2025-08-22T21:03:31.919Z,1755896611.919 [DataOverHttps](INFO): Sending 570 bytes from file Logs/20250822T202250/Express0001.lzma 2025-08-22T21:03:32.919Z,1755896612.919 [DataOverHttps](INFO): Moved sent file to Logs/20250822T202250/Express0001.lzma.bak 2025-08-22T21:03:32.920Z,1755896612.920 [DataOverHttps](INFO): SBD MOMSN=25882877 2025-08-22T21:03:49.717Z,1755896629.717 [DataOverHttps](INFO): Sending 558 bytes from file Logs/20250822T202250/Express0004.lzma 2025-08-22T21:03:50.717Z,1755896630.717 [DataOverHttps](INFO): Moved sent file to Logs/20250822T202250/Express0004.lzma.bak 2025-08-22T21:03:50.718Z,1755896630.718 [DataOverHttps](INFO): SBD MOMSN=25882880 2025-08-22T21:04:09.526Z,1755896649.526 [DataOverHttps](INFO): Sending 199 bytes from file Logs/20250822T202250/Express0007.lzma 2025-08-22T21:04:10.528Z,1755896650.528 [DataOverHttps](INFO): Moved sent file to Logs/20250822T202250/Express0007.lzma.bak 2025-08-22T21:04:10.528Z,1755896650.528 [DataOverHttps](INFO): SBD MOMSN=25882885 2025-08-22T21:04:27.583Z,1755896667.583 [DataOverHttps](INFO): Sending 731 bytes from file Logs/20250822T202250/Express0010.lzma 2025-08-22T21:04:28.571Z,1755896668.571 [DataOverHttps](INFO): Moved sent file to Logs/20250822T202250/Express0010.lzma.bak 2025-08-22T21:04:28.572Z,1755896668.572 [DataOverHttps](INFO): SBD MOMSN=25882887 2025-08-22T21:04:45.423Z,1755896685.423 [DataOverHttps](INFO): Sending 538 bytes from file Logs/20250822T202250/Express0013.lzma 2025-08-22T21:04:46.423Z,1755896686.423 [DataOverHttps](INFO): Moved sent file to Logs/20250822T202250/Express0013.lzma.bak 2025-08-22T21:04:46.423Z,1755896686.423 [DataOverHttps](INFO): SBD MOMSN=25882905 2025-08-22T21:05:03.331Z,1755896703.331 [DataOverHttps](INFO): Sending 945 bytes from file Logs/20250822T205026/Express0001.lzma 2025-08-22T21:05:04.331Z,1755896704.331 [DataOverHttps](INFO): Moved sent file to Logs/20250822T205026/Express0001.lzma.bak 2025-08-22T21:05:04.331Z,1755896704.331 [DataOverHttps](INFO): SBD MOMSN=25882911 2025-08-22T21:05:05.665Z,1755896705.665 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-22T21:05:05.666Z,1755896705.666 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-22T21:05:05.666Z,1755896705.666 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-22T21:05:14.166Z,1755896714.166 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:05:22.962Z,1755896722.962 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:05:31.953Z,1755896731.953 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:05:40.894Z,1755896740.894 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:05:49.670Z,1755896749.670 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:05:58.575Z,1755896758.575 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T21:06:08.963Z,1755896768.963 [DataOverHttps](IMPORTANT): SBD MTMSN=20250822T210608 2025-08-22T21:06:16.709Z,1755896776.709 [DataOverHttps](INFO): Received command: schedule clear;schedule resume;restart logs