2025-12-11T23:32:29.835Z,1765495949.835 [CommandExec](IMPORTANT): got command restart logs 2025-12-11T23:32:30.154Z,1765495950.154 [OnDock:N.Docked](FAULT): Off dock, range timeout exceeded 2025-12-11T23:32:30.154Z,1765495950.154 [OnDock:N.Docked](IMPORTANT): State switched to DETACHED. Waiting for state timeout to act... 2025-12-11T23:32:30.964Z,1765495950.964 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-12-11T23:32:31.078Z,1765495951.078 [CTD_Seabird](ERROR): Failed to parse device response: 2025-12-11T23:32:31.082Z,1765495951.082 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-12-11T23:32:31.112Z,1765495951.112 [DAT](INFO): DAT read: MF Frequency Band 2025-12-11T23:32:31.114Z,1765495951.114 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-12-11T23:32:31.115Z,1765495951.115 [DAT](INFO): DAT read: Dec 11 2025 23:32:25 2025-12-11T23:32:32.370Z,1765495952.370 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-12-11T23:32:32.371Z,1765495952.371 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-12-11T23:32:32.372Z,1765495952.372 [DAT](INFO): commRate: 600 2025-12-11T23:32:34.438Z,1765495954.438 [DAT](INFO): entering command mode 2025-12-11T23:32:34.638Z,1765495954.638 [DAT](INFO): DAT read: 2025-12-11T23:32:34.638Z,1765495954.638 [DAT](INFO): DAT read: use:> 2025-12-11T23:32:34.638Z,1765495954.638 [DAT](INFO): unknown deviceResponse_: use:> 2025-12-11T23:32:34.639Z,1765495954.639 [DAT] Communications Fault, FailCount= 1 2025-12-11T23:32:34.639Z,1765495954.639 [DAT](ERROR): Communications Fault 2025-12-11T23:32:34.639Z,1765495954.639 [DAT](DEBUG): checking for command mode acknowledgment 2025-12-11T23:32:34.718Z,1765495954.718 [DockingStepper](INFO): Changing to mode: 1 2025-12-11T23:32:34.774Z,1765495954.774 [CBIT](ERROR): Communications Fault in component: DAT 2025-12-11T23:32:35.041Z,1765495955.041 [DAT](INFO): Powering down 2025-12-11T23:32:35.938Z,1765495955.938 [CBIT](INFO): Clearing failed state for component DAT 2025-12-11T23:32:35.938Z,1765495955.938 [DAT] No Fault, FailCount= 1 2025-12-11T23:32:38.269Z,1765495958.269 [DAT](INFO): Powering up 2025-12-11T23:32:38.270Z,1765495958.270 [DAT](DEBUG): Initializing DAT. 2025-12-11T23:32:49.132Z,1765495969.132 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,-32768,32768,V 2025-12-11T23:32:50.118Z,1765495970.118 [DAT](INFO): DAT read: 2025-12-11T23:32:50.119Z,1765495970.119 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-12-11T23:32:51.632Z,1765495971.632 [DAT](INFO): DAT read: MF Frequency Band 2025-12-11T23:32:51.882Z,1765495971.882 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-12-11T23:32:51.883Z,1765495971.883 [DAT](INFO): DAT read: Dec 11 2025 23:32:45 2025-12-11T23:32:52.890Z,1765495972.890 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-12-11T23:32:52.891Z,1765495972.891 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-12-11T23:32:52.892Z,1765495972.892 [DAT](INFO): commRate: 600 2025-12-11T23:32:54.961Z,1765495974.961 [DAT](INFO): entering command mode 2025-12-11T23:32:55.157Z,1765495975.157 [DAT](INFO): DAT read: 2025-12-11T23:32:55.158Z,1765495975.158 [DAT](INFO): DAT read: user:1> 2025-12-11T23:32:55.158Z,1765495975.158 [DAT](INFO): setting verbose to 3 2025-12-11T23:32:55.410Z,1765495975.410 [DAT](INFO): DAT read: user:1> 2025-12-11T23:32:55.411Z,1765495975.411 [DAT](INFO): DAT read: Verbose | 3 2025-12-11T23:32:55.411Z,1765495975.411 [DAT](INFO): set verbose to 3 2025-12-11T23:32:55.411Z,1765495975.411 [DAT](INFO): setting DatVerbose to 27440 2025-12-11T23:32:55.662Z,1765495975.662 [DAT](INFO): DAT read: user:2> 2025-12-11T23:32:55.663Z,1765495975.663 [DAT](INFO): DAT read: DatVerbose | 27440 2025-12-11T23:32:55.663Z,1765495975.663 [DAT](INFO): set DatVerbose to 27440 2025-12-11T23:32:55.663Z,1765495975.663 [DAT](INFO): setting transmit power to 8 2025-12-11T23:32:55.914Z,1765495975.914 [DAT](INFO): DAT read: user:3> 2025-12-11T23:32:55.915Z,1765495975.915 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-12-11T23:32:55.915Z,1765495975.915 [DAT](INFO): set transmit power to 8 2025-12-11T23:32:55.915Z,1765495975.915 [DAT](INFO): setting local address to 11 2025-12-11T23:32:56.166Z,1765495976.166 [DAT](INFO): DAT read: user:4> 2025-12-11T23:32:56.167Z,1765495976.167 [DAT](INFO): DAT read: LocalAddr | 11 2025-12-11T23:32:56.167Z,1765495976.167 [DAT](INFO): set local address to 11 2025-12-11T23:32:56.168Z,1765495976.168 [DAT](INFO): Setting time to: 23:32:56 And date to:12/11/2025 2025-12-11T23:32:56.418Z,1765495976.418 [DAT](INFO): DAT read: user:5> 2025-12-11T23:32:56.419Z,1765495976.419 [DAT](INFO): DAT read: Thu Dec 11, 2025 23:32:56 2025-12-11T23:32:56.419Z,1765495976.419 [DAT](INFO): Local DAT time set to Thu Dec 11, 2025 23:32:56 2025-12-11T23:32:56.420Z,1765495976.420 [DAT](INFO): Requesting device enable set for address 50. 2025-12-11T23:32:56.670Z,1765495976.670 [DAT](INFO): DAT read: user:6> 2025-12-11T23:32:56.671Z,1765495976.671 [DAT](INFO): DAT read: Tx time:23:32:56.4390 2025-12-11T23:32:56.671Z,1765495976.671 [DAT](INFO): Ping request sent. 2025-12-11T23:32:56.671Z,1765495976.671 [DAT](INFO): transmitted an acoustic signal 2025-12-11T23:32:56.671Z,1765495976.671 [DAT](INFO): publishing transmit ping time 2025-12-11T23:32:56.672Z,1765495976.672 [DAT](INFO): Requesting device enable set for address 50. 2025-12-11T23:32:56.926Z,1765495976.926 [DAT](INFO): Requesting device enable set for address 50. 2025-12-11T23:32:58.941Z,1765495978.941 [DAT](INFO): DAT read: Rx Time:23:32:58.3252 2025-12-11T23:32:58.943Z,1765495978.943 [DAT](INFO): Rx dataTimestamp_ set to:1765495978.942056 2025-12-11T23:32:59.231Z,1765495979.231 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-11T23:32:59.694Z,1765495979.694 [DAT](INFO): DAT read: OK 2025-12-11T23:32:59.694Z,1765495979.694 [DAT](INFO): unknown deviceResponse_: OK 2025-12-11T23:32:59.695Z,1765495979.695 [DAT] Communications Fault, FailCount= 1 2025-12-11T23:32:59.695Z,1765495979.695 [DAT](ERROR): Communications Fault 2025-12-11T23:32:59.695Z,1765495979.695 [DAT](INFO): DAT read: user:7> 2025-12-11T23:32:59.696Z,1765495979.696 [DAT](INFO): DAT read: Tx time:23:32:59.4390 2025-12-11T23:32:59.696Z,1765495979.696 [DAT](INFO): Ping request sent. 2025-12-11T23:32:59.696Z,1765495979.696 [DAT](INFO): transmitted an acoustic signal 2025-12-11T23:32:59.697Z,1765495979.697 [DAT](INFO): publishing transmit ping time 2025-12-11T23:32:59.706Z,1765495979.706 [CBIT](ERROR): Communications Fault in component: DAT 2025-12-11T23:33:00.110Z,1765495980.110 [DAT](INFO): Powering down 2025-12-11T23:33:00.453Z,1765495980.453 [OnDock:N.Docked](IMPORTANT): VEHICLE DETACHED FROM DOCK. 2025-12-11T23:33:00.454Z,1765495980.454 [OnDock:N.Docked] Stopped 2025-12-11T23:33:00.455Z,1765495980.455 [OnDock:Detach] Running Loop=1 2025-12-11T23:33:00.455Z,1765495980.455 [OnDock:Detach](DEBUG): Aggregate::initialize OnDock:Detach 2025-12-11T23:33:00.455Z,1765495980.455 [OnDock:Detach:A] Running Loop=1 2025-12-11T23:33:00.512Z,1765495980.512 [DockingStepper](INFO): Changing to mode: 2 2025-12-11T23:33:00.865Z,1765495980.865 [OnDock:Detach:A](IMPORTANT): Mission ending, breaking off. Range: 0.598428 m . 2025-12-11T23:33:00.865Z,1765495980.865 [OnDock:Detach:A] Stopped 2025-12-11T23:33:00.865Z,1765495980.865 [OnDock:Detach:B.Undock] Running Loop=1 2025-12-11T23:33:00.865Z,1765495980.865 [OnDock:Detach:B.Undock](DEBUG): Initialize. 2025-12-11T23:33:00.865Z,1765495980.865 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2025-12-11T23:33:00.928Z,1765495980.928 [CBIT](INFO): Clearing failed state for component DAT 2025-12-11T23:33:00.928Z,1765495980.928 [DAT] No Fault, FailCount= 0 2025-12-11T23:33:01.282Z,1765495981.282 [OnDock:Detach:B.Undock](DEBUG): Initialize. 2025-12-11T23:33:01.282Z,1765495981.282 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2025-12-11T23:33:01.283Z,1765495981.283 [OnDock:Detach:B.Undock](INFO): Detaching from dock at depth 66.630203 m. 2025-12-11T23:33:01.673Z,1765495981.673 [DockingStepper](INFO): Changing to mode: 3 2025-12-11T23:33:03.337Z,1765495983.337 [DAT](INFO): Powering up 2025-12-11T23:33:03.338Z,1765495983.338 [DAT](DEBUG): Initializing DAT. 2025-12-11T23:33:06.511Z,1765495986.511 [OnDock:Detach:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-12-11T23:33:06.511Z,1765495986.511 [OnDock:Detach:B.Undock](INFO): Engaging thruster for 10.0 seconds (max) to disengage. 2025-12-11T23:33:06.922Z,1765495986.922 [OnDock:Detach:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-12-11T23:33:07.345Z,1765495987.345 [OnDock:Detach:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-12-11T23:33:07.726Z,1765495987.726 [OnDock:Detach:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-12-11T23:33:08.154Z,1765495988.154 [OnDock:Detach:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-12-11T23:33:08.554Z,1765495988.554 [OnDock:Detach:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-12-11T23:33:08.942Z,1765495988.942 [OnDock:Detach:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-12-11T23:33:09.364Z,1765495989.364 [OnDock:Detach:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-12-11T23:33:09.751Z,1765495989.751 [OnDock:Detach:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-12-11T23:33:10.173Z,1765495990.173 [OnDock:Detach:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-12-11T23:33:10.554Z,1765495990.554 [OnDock:Detach:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-12-11T23:33:10.958Z,1765495990.958 [OnDock:Detach:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-12-11T23:33:11.382Z,1765495991.382 [OnDock:Detach:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-12-11T23:33:11.382Z,1765495991.382 [OnDock:Detach:B.Undock](INFO): Thruster off. 2025-12-11T23:33:11.786Z,1765495991.786 [OnDock:Detach:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-12-11T23:33:15.200Z,1765495995.200 [DAT](INFO): DAT read: 2025-12-11T23:33:15.203Z,1765495995.203 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-12-11T23:33:16.710Z,1765495996.710 [DAT](INFO): DAT read: MF Frequency Band 2025-12-11T23:33:16.711Z,1765495996.711 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-12-11T23:33:16.712Z,1765495996.712 [DAT](INFO): DAT read: Dec 11 2025 23:33:11 2025-12-11T23:33:17.974Z,1765495997.974 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-12-11T23:33:17.975Z,1765495997.975 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-12-11T23:33:17.976Z,1765495997.976 [DAT](INFO): commRate: 600 2025-12-11T23:33:20.041Z,1765496000.041 [DAT](INFO): entering command mode 2025-12-11T23:33:20.243Z,1765496000.243 [DAT](INFO): DAT read: 2025-12-11T23:33:20.245Z,1765496000.245 [DAT](INFO): DAT read: user:1> 2025-12-11T23:33:20.247Z,1765496000.247 [DAT](INFO): setting verbose to 3 2025-12-11T23:33:20.494Z,1765496000.494 [DAT](INFO): DAT read: user:1> 2025-12-11T23:33:20.495Z,1765496000.495 [DAT](INFO): DAT read: Verbose | 3 2025-12-11T23:33:20.495Z,1765496000.495 [DAT](INFO): set verbose to 3 2025-12-11T23:33:20.495Z,1765496000.495 [DAT](INFO): setting DatVerbose to 27440 2025-12-11T23:33:20.746Z,1765496000.746 [DAT](INFO): DAT read: user:2> 2025-12-11T23:33:20.747Z,1765496000.747 [DAT](INFO): DAT read: DatVerbose | 27440 2025-12-11T23:33:20.747Z,1765496000.747 [DAT](INFO): set DatVerbose to 27440 2025-12-11T23:33:20.747Z,1765496000.747 [DAT](INFO): setting transmit power to 8 2025-12-11T23:33:20.998Z,1765496000.998 [DAT](INFO): DAT read: user:3> 2025-12-11T23:33:20.999Z,1765496000.999 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-12-11T23:33:20.999Z,1765496000.999 [DAT](INFO): set transmit power to 8 2025-12-11T23:33:20.999Z,1765496000.999 [DAT](INFO): setting local address to 11 2025-12-11T23:33:21.252Z,1765496001.252 [DAT](INFO): DAT read: user:4> 2025-12-11T23:33:21.256Z,1765496001.256 [DAT](INFO): DAT read: LocalAddr | 11 2025-12-11T23:33:21.257Z,1765496001.257 [DAT](INFO): set local address to 11 2025-12-11T23:33:21.261Z,1765496001.261 [DAT](INFO): Setting time to: 23:33:21 And date to:12/11/2025 2025-12-11T23:33:21.502Z,1765496001.502 [DAT](INFO): DAT read: user:5> 2025-12-11T23:33:21.502Z,1765496001.502 [DAT](INFO): DAT read: Thu Dec 11, 2025 23:33:21 2025-12-11T23:33:21.503Z,1765496001.503 [DAT](INFO): Local DAT time set to Thu Dec 11, 2025 23:33:21 2025-12-11T23:33:21.754Z,1765496001.754 [DAT](INFO): DAT read: user:6> 2025-12-11T23:33:21.755Z,1765496001.755 [DAT](INFO): DAT read: Tx time:23:33:21.4171 2025-12-11T23:33:21.755Z,1765496001.755 [DAT](INFO): Ping request sent. 2025-12-11T23:33:21.755Z,1765496001.755 [DAT](INFO): transmitted an acoustic signal 2025-12-11T23:33:21.755Z,1765496001.755 [DAT](INFO): publishing transmit ping time 2025-12-11T23:33:21.870Z,1765496001.870 [OnDock:Detach:B.Undock](INFO): Detected possible reattachment, resetting timer. 2025-12-11T23:33:24.026Z,1765496004.026 [DAT](INFO): DAT read: Rx Time:23:33:23.2871 2025-12-11T23:33:24.026Z,1765496004.026 [DAT](INFO): Rx dataTimestamp_ set to:1765496004.026196 2025-12-11T23:33:24.786Z,1765496004.786 [DAT](INFO): DAT read: 23:33:23.2871 LVL= 32752, 32753, 26370, 32755, AGC= 45, IDX= 341,-0.10,-2.773, 1.576, 2.899,-2.251, PHS=-0.420,-2.411,-1.177, RAW= 127.9, 39.1, CAL= 130.8, 48.7, ROT= 19.2, -48.7 2025-12-11T23:33:24.787Z,1765496004.787 [DAT](INFO): got valid direction response: 23:33:23.2871 LVL= 32752, 32753, 26370, 32755, AGC= 45, IDX= 341,-0.10,-2.773, 1.576, 2.899,-2.251, PHS=-0.420,-2.411,-1.177, RAW= 127.9, 39.1, CAL= 130.8, 48.7, ROT= 19.2, -48.7 2025-12-11T23:33:24.787Z,1765496004.787 [DAT](INFO): DAT read: OK 2025-12-11T23:33:24.788Z,1765496004.788 [DAT](INFO): unknown deviceResponse_: OK 2025-12-11T23:33:24.788Z,1765496004.788 [DAT] Communications Fault, FailCount= 1 2025-12-11T23:33:24.788Z,1765496004.788 [DAT](ERROR): Communications Fault 2025-12-11T23:33:24.789Z,1765496004.789 [DAT](INFO): DAT read: user:7> 2025-12-11T23:33:24.790Z,1765496004.790 [DAT](INFO): DAT read: Tx time:23:33:24.3671 2025-12-11T23:33:24.790Z,1765496004.790 [DAT](INFO): Ping request sent. 2025-12-11T23:33:24.790Z,1765496004.790 [DAT](INFO): transmitted an acoustic signal 2025-12-11T23:33:24.790Z,1765496004.790 [DAT](INFO): #Rx 2: Read direction message, but no range. 2025-12-11T23:33:24.791Z,1765496004.791 [DAT](INFO): direction in FSK: [0.623290,0.217053,0.751264] 2025-12-11T23:33:24.791Z,1765496004.791 [DAT](INFO): publishing transmit ping time 2025-12-11T23:33:24.794Z,1765496004.794 [DAT](INFO): publishing transmit ping time 2025-12-11T23:33:25.098Z,1765496005.098 [OnDock:Detach:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-12-11T23:33:25.154Z,1765496005.154 [CBIT](ERROR): Communications Fault in component: DAT 2025-12-11T23:33:25.229Z,1765496005.229 [DAT](INFO): Powering down 2025-12-11T23:33:26.450Z,1765496006.450 [CBIT](INFO): Clearing failed state for component DAT 2025-12-11T23:33:26.450Z,1765496006.450 [DAT] No Fault, FailCount= 0 2025-12-11T23:33:28.362Z,1765496008.362 [DAT](INFO): Powering up 2025-12-11T23:33:28.363Z,1765496008.363 [DAT](DEBUG): Initializing DAT. 2025-12-11T23:33:40.218Z,1765496020.218 [DAT](INFO): DAT read: 2025-12-11T23:33:40.218Z,1765496020.218 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-12-11T23:33:41.731Z,1765496021.731 [DAT](INFO): DAT read: MF Frequency Band 2025-12-11T23:33:41.982Z,1765496021.982 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-12-11T23:33:41.983Z,1765496021.983 [DAT](INFO): DAT read: Dec 11 2025 23:33:36 2025-12-11T23:33:42.990Z,1765496022.990 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-12-11T23:33:42.991Z,1765496022.991 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-12-11T23:33:42.992Z,1765496022.992 [DAT](INFO): commRate: 600 2025-12-11T23:33:45.057Z,1765496025.057 [DAT](INFO): entering command mode 2025-12-11T23:33:45.258Z,1765496025.258 [DAT](INFO): DAT read: 2025-12-11T23:33:45.258Z,1765496025.258 [DAT](INFO): DAT read: user:1> 2025-12-11T23:33:45.259Z,1765496025.259 [DAT](INFO): setting verbose to 3 2025-12-11T23:33:45.298Z,1765496025.298 [OnDock:Detach:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby. 2025-12-11T23:33:45.510Z,1765496025.510 [DAT](INFO): DAT read: user:1> 2025-12-11T23:33:45.511Z,1765496025.511 [DAT](INFO): DAT read: Verbose | 3 2025-12-11T23:33:45.511Z,1765496025.511 [DAT](INFO): set verbose to 3 2025-12-11T23:33:45.511Z,1765496025.511 [DAT](INFO): setting DatVerbose to 27440 2025-12-11T23:33:45.727Z,1765496025.727 [DockingStepper](INFO): Changing to mode: 1 2025-12-11T23:33:45.762Z,1765496025.762 [DAT](INFO): DAT read: user:2> 2025-12-11T23:33:45.763Z,1765496025.763 [DAT](INFO): DAT read: DatVerbose | 27440 2025-12-11T23:33:45.764Z,1765496025.764 [DAT](INFO): set DatVerbose to 27440 2025-12-11T23:33:45.764Z,1765496025.764 [DAT](INFO): setting transmit power to 8 2025-12-11T23:33:46.014Z,1765496026.014 [DAT](INFO): DAT read: user:3> 2025-12-11T23:33:46.015Z,1765496026.015 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-12-11T23:33:46.015Z,1765496026.015 [DAT](INFO): set transmit power to 8 2025-12-11T23:33:46.015Z,1765496026.015 [DAT](INFO): setting local address to 11 2025-12-11T23:33:46.266Z,1765496026.266 [DAT](INFO): DAT read: user:4> 2025-12-11T23:33:46.267Z,1765496026.267 [DAT](INFO): DAT read: LocalAddr | 11 2025-12-11T23:33:46.267Z,1765496026.267 [DAT](INFO): set local address to 11 2025-12-11T23:33:46.268Z,1765496026.268 [DAT](INFO): Setting time to: 23:33:46 And date to:12/11/2025 2025-12-11T23:33:46.518Z,1765496026.518 [DAT](INFO): DAT read: user:5> 2025-12-11T23:33:46.519Z,1765496026.519 [DAT](INFO): DAT read: Thu Dec 11, 2025 23:33:46 2025-12-11T23:33:46.519Z,1765496026.519 [DAT](INFO): Local DAT time set to Thu Dec 11, 2025 23:33:46 2025-12-11T23:33:51.827Z,1765496031.827 [OnDock:Detach:B.Undock](INFO): Docking module at standby. 2025-12-11T23:33:52.186Z,1765496032.186 [OnDock:Detach:B.Undock](IMPORTANT): Undocking sequence complete. 2025-12-11T23:33:52.186Z,1765496032.186 [OnDock:Detach:B.Undock] Stopped 2025-12-11T23:33:52.187Z,1765496032.187 [OnDock:Detach](INFO): Completed OnDock:Detach 2025-12-11T23:33:52.187Z,1765496032.187 [OnDock:Detach] Stopped 2025-12-11T23:33:52.187Z,1765496032.187 [OnDock:Detach](DEBUG): Aggregate::uninitialize OnDock:Detach 2025-12-11T23:33:52.189Z,1765496032.189 [OnDock](INFO): Completed OnDock 2025-12-11T23:33:52.189Z,1765496032.189 [MissionManager](INFO): OnDock is completed. 2025-12-11T23:33:52.189Z,1765496032.189 [MissionManager](INFO): Uninitializing Mission OnDock 2025-12-11T23:33:52.189Z,1765496032.189 [OnDock] Stopped 2025-12-11T23:33:52.190Z,1765496032.190 [OnDock](DEBUG): Aggregate::uninitialize OnDock 2025-12-11T23:33:52.190Z,1765496032.190 [OnDock:StandardEnvelopes] Stopped 2025-12-11T23:33:52.190Z,1765496032.190 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes 2025-12-11T23:33:52.190Z,1765496032.190 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-12-11T23:33:52.190Z,1765496032.190 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-12-11T23:33:52.190Z,1765496032.190 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-12-11T23:33:52.190Z,1765496032.190 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-12-11T23:33:52.190Z,1765496032.190 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-12-11T23:33:52.190Z,1765496032.190 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-12-11T23:33:52.190Z,1765496032.190 [OnDock:BackseatDriver] Stopped 2025-12-11T23:33:52.191Z,1765496032.191 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver 2025-12-11T23:33:52.191Z,1765496032.191 [OnDock:BackseatDriver:A.BackseatDriver] Stopped 2025-12-11T23:33:52.191Z,1765496032.191 [OnDock:PowerOnly] Stopped 2025-12-11T23:33:52.191Z,1765496032.191 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly 2025-12-11T23:33:52.191Z,1765496032.191 [OnDock:PowerOnly:E.Wait] Stopped 2025-12-11T23:33:52.191Z,1765496032.191 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-12-11T23:33:52.191Z,1765496032.191 [OnDock:dataRead] Stopped 2025-12-11T23:33:52.191Z,1765496032.191 [OnDock:dataRead](DEBUG): Aggregate::uninitialize OnDock:dataRead 2025-12-11T23:33:52.191Z,1765496032.191 [OnDock:dataRead:A] Stopped 2025-12-11T23:33:52.191Z,1765496032.191 [OnDock:J.] Stopped 2025-12-11T23:33:52.191Z,1765496032.191 [OnDock:J.](DEBUG): Uninitializing TrackAcousticContact. 2025-12-11T23:33:52.191Z,1765496032.191 [OnDock:PeriodicCheck] Stopped 2025-12-11T23:33:52.192Z,1765496032.192 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck 2025-12-11T23:33:52.192Z,1765496032.192 [OnDock:PeriodicCheck:CheckIP] Stopped 2025-12-11T23:33:52.192Z,1765496032.192 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP 2025-12-11T23:33:52.192Z,1765496032.192 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped 2025-12-11T23:33:52.192Z,1765496032.192 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component. 2025-12-11T23:33:52.575Z,1765496032.575 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-12-11T23:33:52.575Z,1765496032.575 [DefaultWithUndock] Running Loop=1 2025-12-11T23:33:52.575Z,1765496032.575 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-12-11T23:33:52.575Z,1765496032.575 [DefaultWithUndock:A.Wait] Running Loop=1 2025-12-11T23:33:52.575Z,1765496032.575 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-12-11T23:34:22.867Z,1765496062.867 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2025-12-11T23:34:22.867Z,1765496062.867 [DefaultWithUndock:A.Wait] Stopped 2025-12-11T23:34:22.867Z,1765496062.867 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-12-11T23:34:22.867Z,1765496062.867 [DefaultWithUndock:LeaveDock] Running Loop=1 2025-12-11T23:34:22.868Z,1765496062.868 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2025-12-11T23:34:22.868Z,1765496062.868 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-12-11T23:34:22.868Z,1765496062.868 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2025-12-11T23:34:22.868Z,1765496062.868 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2025-12-11T23:34:22.868Z,1765496062.868 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-12-11T23:34:22.868Z,1765496062.868 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-12-11T23:34:23.307Z,1765496063.307 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-12-11T23:34:23.307Z,1765496063.307 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-12-11T23:34:23.308Z,1765496063.308 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth 65.906097 m. 2025-12-11T23:34:23.308Z,1765496063.308 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-12-11T23:34:24.087Z,1765496064.087 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-12-11T23:34:24.207Z,1765496064.207 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-12-11T23:34:26.551Z,1765496066.551 [CTD_Seabird](ERROR): Failed to parse device response: 2025-12-11T23:34:27.766Z,1765496067.766 [DockingStepper](INFO): Changing to mode: 3 2025-12-11T23:34:32.974Z,1765496072.974 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-12-11T23:34:33.186Z,1765496073.186 [DAT](DEBUG): Acoustic response timeout 2025-12-11T23:34:33.186Z,1765496073.186 [DAT](INFO): setting remote address to 8 2025-12-11T23:34:33.438Z,1765496073.438 [DAT](INFO): DAT read: user:6> 2025-12-11T23:34:33.439Z,1765496073.439 [DAT](INFO): DAT read: RemoteAddr | 8 2025-12-11T23:34:33.439Z,1765496073.439 [DAT](INFO): set remote address to 8 2025-12-11T23:34:33.439Z,1765496073.439 [DAT](DEBUG): Acoustic response timeout 2025-12-11T23:34:33.440Z,1765496073.440 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-12-11T23:34:33.690Z,1765496073.690 [DAT](INFO): DAT read: user:7> 2025-12-11T23:34:33.691Z,1765496073.691 [DAT](INFO): DAT read: Tx time:23:34:33.3384 2025-12-11T23:34:33.691Z,1765496073.691 [DAT](INFO): Ping request sent. 2025-12-11T23:34:33.691Z,1765496073.691 [DAT](INFO): transmitted an acoustic signal 2025-12-11T23:34:33.691Z,1765496073.691 [DAT](INFO): publishing transmit ping time 2025-12-11T23:34:33.692Z,1765496073.692 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000738 2025-12-11T23:34:33.790Z,1765496073.790 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-12-11T23:34:33.942Z,1765496073.942 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250904 2025-12-11T23:34:34.194Z,1765496074.194 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503184 2025-12-11T23:34:34.446Z,1765496074.446 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754979 2025-12-11T23:34:34.698Z,1765496074.698 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006803 2025-12-11T23:34:34.950Z,1765496074.950 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259134 2025-12-11T23:34:35.202Z,1765496075.202 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510894 2025-12-11T23:34:35.454Z,1765496075.454 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762938 2025-12-11T23:34:35.706Z,1765496075.706 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015139 2025-12-11T23:34:35.958Z,1765496075.958 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266898 2025-12-11T23:34:36.210Z,1765496076.210 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518893 2025-12-11T23:34:36.462Z,1765496076.462 [DAT](INFO): DAT read: Rx Time:23:34:35.7478 2025-12-11T23:34:36.462Z,1765496076.462 [DAT](INFO): Rx dataTimestamp_ set to:1765496076.462166 2025-12-11T23:34:36.463Z,1765496076.463 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771908 2025-12-11T23:34:36.714Z,1765496076.714 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022932 2025-12-11T23:34:36.968Z,1765496076.968 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.276643 2025-12-11T23:34:37.223Z,1765496077.223 [DAT](INFO): DAT read: 23:34:35.7478 LVL= 21120, 21313, 31842, 24563, AGC= 46, IDX= 224, 0.05,-1.505, 2.375,-2.493,-2.390, PHS= 0.987,-1.474,-0.147, RAW= 122.6, 6.0, CAL= 123.6, 7.2, ROT= 26.4, -7.2 2025-12-11T23:34:37.224Z,1765496077.224 [DAT](INFO): got valid direction response: 23:34:35.7478 LVL= 21120, 21313, 31842, 24563, AGC= 46, IDX= 224, 0.05,-1.505, 2.375,-2.493,-2.390, PHS= 0.987,-1.474,-0.147, RAW= 122.6, 6.0, CAL= 123.6, 7.2, ROT= 26.4, -7.2 2025-12-11T23:34:37.225Z,1765496077.225 [DAT](INFO): DAT read: Bearing 183.2, 29.5 (Local) 2025-12-11T23:34:37.225Z,1765496077.225 [DAT](INFO): Local bearing/azimuth received: Bearing 183.2, 29.5 (Local) 2025-12-11T23:34:37.226Z,1765496077.226 [DAT](INFO): DAT read: Range 11 to 50 : 3.9 m (Round-trip 5.2 ms) speed 0.0 m/s 2025-12-11T23:34:37.227Z,1765496077.227 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-12-11T23:34:37.228Z,1765496077.228 [DAT](INFO): direction in FSK: [0.888649,0.441129,0.125333] 2025-12-11T23:34:37.228Z,1765496077.228 [DAT](INFO): publishing direction and range info 2025-12-11T23:34:37.484Z,1765496077.484 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible reattachment, resetting timer. 2025-12-11T23:34:37.484Z,1765496077.484 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected reattachement and haven't tried reverse, restarting undock sequence 2025-12-11T23:34:37.837Z,1765496077.837 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Engaging thruster for 10.0 seconds (max) to disengage. 2025-12-11T23:34:43.082Z,1765496083.082 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-12-11T23:34:43.270Z,1765496083.270 [DAT](INFO): ****** received valid address query ****** 2025-12-11T23:34:43.270Z,1765496083.270 [DAT](INFO): ****** received valid ping request ****** 2025-12-11T23:34:43.270Z,1765496083.270 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-12-11T23:34:43.522Z,1765496083.522 [DAT](INFO): DAT read: user:8> 2025-12-11T23:34:43.523Z,1765496083.523 [DAT](INFO): DAT read: Tx time:23:34:43.1869 2025-12-11T23:34:43.523Z,1765496083.523 [DAT](INFO): Ping request sent. 2025-12-11T23:34:43.523Z,1765496083.523 [DAT](INFO): transmitted an acoustic signal 2025-12-11T23:34:43.523Z,1765496083.523 [DAT](INFO): publishing transmit ping time 2025-12-11T23:34:43.524Z,1765496083.524 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000737 2025-12-11T23:34:43.774Z,1765496083.774 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250861 2025-12-11T23:34:44.026Z,1765496084.026 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503310 2025-12-11T23:34:44.278Z,1765496084.278 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755039 2025-12-11T23:34:44.531Z,1765496084.531 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007810 2025-12-11T23:34:44.783Z,1765496084.783 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259718 2025-12-11T23:34:45.034Z,1765496085.034 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510966 2025-12-11T23:34:45.286Z,1765496085.286 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763205 2025-12-11T23:34:45.538Z,1765496085.538 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014961 2025-12-11T23:34:45.790Z,1765496085.790 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266891 2025-12-11T23:34:46.043Z,1765496086.043 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519613 2025-12-11T23:34:46.294Z,1765496086.294 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771255 2025-12-11T23:34:46.549Z,1765496086.549 [DAT](INFO): DAT read: Rx Time:23:34:45.5975 2025-12-11T23:34:46.550Z,1765496086.550 [DAT](INFO): Rx dataTimestamp_ set to:1765496086.549141 2025-12-11T23:34:46.552Z,1765496086.552 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.028238 2025-12-11T23:34:46.798Z,1765496086.798 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275129 2025-12-11T23:34:47.055Z,1765496087.055 [DAT](INFO): DAT read: 23:34:45.5975 LVL= 25232, 25201, 32754, 31043, AGC= 49, IDX= 445,-0.35,-1.939, 1.939,-2.974,-2.753, PHS= 0.916,-1.546,-0.265, RAW= 121.4, 8.4, CAL= 122.3, 9.6, ROT= 27.7, -9.6 2025-12-11T23:34:47.056Z,1765496087.056 [DAT](INFO): got valid direction response: 23:34:45.5975 LVL= 25232, 25201, 32754, 31043, AGC= 49, IDX= 445,-0.35,-1.939, 1.939,-2.974,-2.753, PHS= 0.916,-1.546,-0.265, RAW= 121.4, 8.4, CAL= 122.3, 9.6, ROT= 27.7, -9.6 2025-12-11T23:34:47.057Z,1765496087.057 [DAT](INFO): DAT read: Bearing 185.4, 27.3 (Local) 2025-12-11T23:34:47.057Z,1765496087.057 [DAT](INFO): Local bearing/azimuth received: Bearing 185.4, 27.3 (Local) 2025-12-11T23:34:47.058Z,1765496087.058 [DAT](INFO): DAT read: Range 11 to 50 : 4.8 m (Round-trip 6.5 ms) speed -0.1 m/s 2025-12-11T23:34:47.059Z,1765496087.059 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-12-11T23:34:47.060Z,1765496087.060 [DAT](INFO): direction in FSK: [0.872995,0.458332,0.166769] 2025-12-11T23:34:47.060Z,1765496087.060 [DAT](INFO): publishing direction and range info 2025-12-11T23:34:47.933Z,1765496087.933 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Thruster off. 2025-12-11T23:34:48.778Z,1765496088.778 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00061803234890,35.0, +1.0,1496.8, 0 2025-12-11T23:34:51.953Z,1765496091.953 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,000687.82, 90.54 2025-12-11T23:34:53.172Z,1765496093.172 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-12-11T23:34:53.354Z,1765496093.354 [DAT](INFO): ****** received valid address query ****** 2025-12-11T23:34:53.354Z,1765496093.354 [DAT](INFO): ****** received valid ping request ****** 2025-12-11T23:34:53.354Z,1765496093.354 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-12-11T23:34:53.608Z,1765496093.608 [DAT](INFO): DAT read: user:9> 2025-12-11T23:34:53.611Z,1765496093.611 [DAT](INFO): DAT read: Tx time:23:34:53.2369 2025-12-11T23:34:53.612Z,1765496093.612 [DAT](INFO): Ping request sent. 2025-12-11T23:34:53.612Z,1765496093.612 [DAT](INFO): transmitted an acoustic signal 2025-12-11T23:34:53.613Z,1765496093.613 [DAT](INFO): publishing transmit ping time 2025-12-11T23:34:53.616Z,1765496093.616 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.002993 2025-12-11T23:34:53.858Z,1765496093.858 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.245951 2025-12-11T23:34:54.110Z,1765496094.110 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.498116 2025-12-11T23:34:54.362Z,1765496094.362 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.750048 2025-12-11T23:34:54.618Z,1765496094.618 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005601 2025-12-11T23:34:54.873Z,1765496094.873 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.260658 2025-12-11T23:34:55.126Z,1765496095.126 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.514162 2025-12-11T23:34:55.378Z,1765496095.378 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.766013 2025-12-11T23:34:55.633Z,1765496095.633 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.020420 2025-12-11T23:34:55.882Z,1765496095.882 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.269977 2025-12-11T23:34:56.135Z,1765496096.135 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.522654 2025-12-11T23:34:56.386Z,1765496096.386 [DAT](INFO): DAT read: Rx Time:23:34:55.6500 2025-12-11T23:34:56.387Z,1765496096.387 [DAT](INFO): Rx dataTimestamp_ set to:1765496096.386295 2025-12-11T23:34:56.387Z,1765496096.387 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.775179 2025-12-11T23:34:56.638Z,1765496096.638 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.025983 2025-12-11T23:34:56.895Z,1765496096.895 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.280678 2025-12-11T23:34:57.147Z,1765496097.147 [DAT](INFO): DAT read: 23:34:55.6500 LVL= 12720, 27633, 16258, 25715, AGC= 50, IDX= 456,-0.09, 2.350, 0.385, 1.498, 1.848, PHS= 0.605,-1.418,-0.394, RAW= 120.4, 13.7, CAL= 121.0, 14.9, ROT= 29.0, -14.9 2025-12-11T23:34:57.148Z,1765496097.148 [DAT](INFO): got valid direction response: 23:34:55.6500 LVL= 12720, 27633, 16258, 25715, AGC= 50, IDX= 456,-0.09, 2.350, 0.385, 1.498, 1.848, PHS= 0.605,-1.418,-0.394, RAW= 120.4, 13.7, CAL= 121.0, 14.9, ROT= 29.0, -14.9 2025-12-11T23:34:57.149Z,1765496097.149 [DAT](INFO): DAT read: Bearing 191.7, 30.1 (Local) 2025-12-11T23:34:57.149Z,1765496097.149 [DAT](INFO): Local bearing/azimuth received: Bearing 191.7, 30.1 (Local) 2025-12-11T23:34:57.151Z,1765496097.151 [DAT](INFO): DAT read: Range 11 to 50 : 6.6 m (Round-trip 8.9 ms) speed -0.2 m/s 2025-12-11T23:34:57.151Z,1765496097.151 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-12-11T23:34:57.152Z,1765496097.152 [DAT](INFO): direction in FSK: [0.845212,0.468508,0.257133] 2025-12-11T23:34:57.152Z,1765496097.152 [DAT](INFO): publishing direction and range info 2025-12-11T23:35:03.336Z,1765496103.336 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-12-11T23:35:03.451Z,1765496103.451 [DAT](INFO): ****** received valid address query ****** 2025-12-11T23:35:03.451Z,1765496103.451 [DAT](INFO): ****** received valid ping request ****** 2025-12-11T23:35:03.451Z,1765496103.451 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-12-11T23:35:03.702Z,1765496103.702 [DAT](INFO): DAT read: user:10> 2025-12-11T23:35:03.703Z,1765496103.703 [DAT](INFO): DAT read: Tx time:23:35:03.3369 2025-12-11T23:35:03.703Z,1765496103.703 [DAT](INFO): Ping request sent. 2025-12-11T23:35:03.703Z,1765496103.703 [DAT](INFO): transmitted an acoustic signal 2025-12-11T23:35:03.703Z,1765496103.703 [DAT](INFO): publishing transmit ping time 2025-12-11T23:35:03.704Z,1765496103.704 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000725 2025-12-11T23:35:03.954Z,1765496103.954 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250819 2025-12-11T23:35:04.206Z,1765496104.206 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502720 2025-12-11T23:35:04.458Z,1765496104.458 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755100 2025-12-11T23:35:04.710Z,1765496104.710 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006827 2025-12-11T23:35:04.962Z,1765496104.962 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258798 2025-12-11T23:35:05.214Z,1765496105.214 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510744 2025-12-11T23:35:05.466Z,1765496105.466 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762784 2025-12-11T23:35:05.720Z,1765496105.720 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.017273 2025-12-11T23:35:05.970Z,1765496105.970 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266757 2025-12-11T23:35:06.222Z,1765496106.222 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518777 2025-12-11T23:35:06.478Z,1765496106.478 [DAT](INFO): DAT read: Rx Time:23:35:05.7532 2025-12-11T23:35:06.478Z,1765496106.478 [DAT](INFO): Rx dataTimestamp_ set to:1765496106.478135 2025-12-11T23:35:06.479Z,1765496106.479 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.775765 2025-12-11T23:35:06.730Z,1765496106.730 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.027011 2025-12-11T23:35:06.984Z,1765496106.984 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.280593 2025-12-11T23:35:07.239Z,1765496107.239 [DAT](INFO): DAT read: 23:35:05.7532 LVL= 13504, 29649, 19826, 32755, AGC= 51, IDX= 457,-0.29,-1.721, 2.753,-2.468,-2.090, PHS= 0.471,-1.395,-0.422, RAW= 121.4, 16.4, CAL= 121.6, 17.7, ROT= 28.4, -17.7 2025-12-11T23:35:07.240Z,1765496107.240 [DAT](INFO): got valid direction response: 23:35:05.7532 LVL= 13504, 29649, 19826, 32755, AGC= 51, IDX= 457,-0.29,-1.721, 2.753,-2.468,-2.090, PHS= 0.471,-1.395,-0.422, RAW= 121.4, 16.4, CAL= 121.6, 17.7, ROT= 28.4, -17.7 2025-12-11T23:35:07.241Z,1765496107.241 [DAT](INFO): DAT read: Bearing 193.0, 30.2 (Local) 2025-12-11T23:35:07.241Z,1765496107.241 [DAT](INFO): Local bearing/azimuth received: Bearing 193.0, 30.2 (Local) 2025-12-11T23:35:07.243Z,1765496107.243 [DAT](INFO): DAT read: Range 11 to 50 : 9.1 m (Round-trip 12.2 ms) speed -0.2 m/s 2025-12-11T23:35:07.243Z,1765496107.243 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-12-11T23:35:07.244Z,1765496107.244 [DAT](INFO): direction in FSK: [0.838007,0.453109,0.304033] 2025-12-11T23:35:07.244Z,1765496107.244 [DAT](INFO): publishing direction and range info 2025-12-11T23:35:07.338Z,1765496107.338 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-12-11T23:35:13.367Z,1765496113.367 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-12-11T23:35:13.538Z,1765496113.538 [DAT](INFO): ****** received valid address query ****** 2025-12-11T23:35:13.538Z,1765496113.538 [DAT](INFO): ****** received valid ping request ****** 2025-12-11T23:35:13.538Z,1765496113.538 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-12-11T23:35:13.790Z,1765496113.790 [DAT](INFO): DAT read: user:11> 2025-12-11T23:35:13.791Z,1765496113.791 [DAT](INFO): DAT read: Tx time:23:35:13.4369 2025-12-11T23:35:13.791Z,1765496113.791 [DAT](INFO): Ping request sent. 2025-12-11T23:35:13.791Z,1765496113.791 [DAT](INFO): transmitted an acoustic signal 2025-12-11T23:35:13.791Z,1765496113.791 [DAT](INFO): publishing transmit ping time 2025-12-11T23:35:13.792Z,1765496113.792 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000730 2025-12-11T23:35:14.042Z,1765496114.042 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250980 2025-12-11T23:35:14.294Z,1765496114.294 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503093 2025-12-11T23:35:14.546Z,1765496114.546 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755035 2025-12-11T23:35:14.798Z,1765496114.798 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006800 2025-12-11T23:35:15.050Z,1765496115.050 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258952 2025-12-11T23:35:15.302Z,1765496115.302 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510871 2025-12-11T23:35:15.554Z,1765496115.554 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762811 2025-12-11T23:35:15.806Z,1765496115.806 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015008 2025-12-11T23:35:16.058Z,1765496116.058 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266854 2025-12-11T23:35:16.310Z,1765496116.310 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518762 2025-12-11T23:35:16.562Z,1765496116.562 [DAT](INFO): DAT read: Rx Time:23:35:15.8558 2025-12-11T23:35:16.563Z,1765496116.563 [DAT](INFO): Rx dataTimestamp_ set to:1765496116.562487 2025-12-11T23:35:16.563Z,1765496116.563 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772199 2025-12-11T23:35:16.815Z,1765496116.815 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023424 2025-12-11T23:35:17.066Z,1765496117.066 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274896 2025-12-11T23:35:17.323Z,1765496117.323 [DAT](INFO): DAT read: 23:35:15.8558 LVL= 16096, 29809, 22642, 32755, AGC= 53, IDX= 451, 0.28, 0.989,-0.873, 0.386, 0.603, PHS= 0.489,-1.430,-0.261, RAW= 127.2, 14.2, CAL= 126.8, 15.4, ROT= 23.2, -15.4 2025-12-11T23:35:17.324Z,1765496117.324 [DAT](INFO): got valid direction response: 23:35:15.8558 LVL= 16096, 29809, 22642, 32755, AGC= 53, IDX= 451, 0.28, 0.989,-0.873, 0.386, 0.603, PHS= 0.489,-1.430,-0.261, RAW= 127.2, 14.2, CAL= 126.8, 15.4, ROT= 23.2, -15.4 2025-12-11T23:35:17.325Z,1765496117.325 [DAT](INFO): DAT read: Bearing 190.2, 23.8 (Local) 2025-12-11T23:35:17.325Z,1765496117.325 [DAT](INFO): Local bearing/azimuth received: Bearing 190.2, 23.8 (Local) 2025-12-11T23:35:17.332Z,1765496117.332 [DAT](INFO): DAT read: Range 11 to 50 : 11.1 m (Round-trip 14.8 ms) speed -0.2 m/s 2025-12-11T23:35:17.332Z,1765496117.332 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-12-11T23:35:17.333Z,1765496117.333 [DAT](INFO): direction in FSK: [0.886134,0.379798,0.265556] 2025-12-11T23:35:17.334Z,1765496117.334 [DAT](INFO): publishing direction and range info 2025-12-11T23:35:23.510Z,1765496123.510 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-12-11T23:35:23.622Z,1765496123.622 [DAT](INFO): ****** received valid address query ****** 2025-12-11T23:35:23.622Z,1765496123.622 [DAT](INFO): ****** received valid ping request ****** 2025-12-11T23:35:23.622Z,1765496123.622 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-12-11T23:35:23.874Z,1765496123.874 [DAT](INFO): DAT read: user:12> 2025-12-11T23:35:23.875Z,1765496123.875 [DAT](INFO): DAT read: Tx time:23:35:23.5369 2025-12-11T23:35:23.875Z,1765496123.875 [DAT](INFO): Ping request sent. 2025-12-11T23:35:23.875Z,1765496123.875 [DAT](INFO): transmitted an acoustic signal 2025-12-11T23:35:23.875Z,1765496123.875 [DAT](INFO): publishing transmit ping time 2025-12-11T23:35:23.876Z,1765496123.876 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000729 2025-12-11T23:35:24.126Z,1765496124.126 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251136 2025-12-11T23:35:24.378Z,1765496124.378 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503049 2025-12-11T23:35:24.630Z,1765496124.630 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754815 2025-12-11T23:35:24.882Z,1765496124.882 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006856 2025-12-11T23:35:25.134Z,1765496125.134 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259017 2025-12-11T23:35:25.386Z,1765496125.386 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510819 2025-12-11T23:35:25.638Z,1765496125.638 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762859 2025-12-11T23:35:25.890Z,1765496125.890 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014799 2025-12-11T23:35:26.142Z,1765496126.142 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266889 2025-12-11T23:35:26.394Z,1765496126.394 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519046 2025-12-11T23:35:26.646Z,1765496126.646 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770881 2025-12-11T23:35:26.898Z,1765496126.898 [DAT](INFO): DAT read: Rx Time:23:35:25.9583 2025-12-11T23:35:26.899Z,1765496126.899 [DAT](INFO): Rx dataTimestamp_ set to:1765496126.898645 2025-12-11T23:35:26.899Z,1765496126.899 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024377 2025-12-11T23:35:27.152Z,1765496127.152 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.276812 2025-12-11T23:35:27.408Z,1765496127.408 [DAT](INFO): DAT read: 23:35:25.9583 LVL= 17888, 32177, 25122, 32755, AGC= 55, IDX= 445, 0.02, 0.446,-1.383, 0.036, 0.075, PHS= 0.473,-1.413,-0.084, RAW= 133.3, 12.2, CAL= 132.7, 13.3, ROT= 17.3, -13.3 2025-12-11T23:35:27.409Z,1765496127.409 [DAT](INFO): got valid direction response: 23:35:25.9583 LVL= 17888, 32177, 25122, 32755, AGC= 55, IDX= 445, 0.02, 0.446,-1.383, 0.036, 0.075, PHS= 0.473,-1.413,-0.084, RAW= 133.3, 12.2, CAL= 132.7, 13.3, ROT= 17.3, -13.3 2025-12-11T23:35:27.410Z,1765496127.410 [DAT](INFO): DAT read: Bearing 188.7, 19.0 (Local) 2025-12-11T23:35:27.410Z,1765496127.410 [DAT](INFO): Local bearing/azimuth received: Bearing 188.7, 19.0 (Local) 2025-12-11T23:35:27.411Z,1765496127.411 [DAT](INFO): DAT read: Range 11 to 50 : 12.9 m (Round-trip 17.3 ms) speed -0.2 m/s 2025-12-11T23:35:27.412Z,1765496127.412 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-12-11T23:35:27.413Z,1765496127.413 [DAT](INFO): direction in FSK: [0.929153,0.289399,0.230050] 2025-12-11T23:35:27.413Z,1765496127.413 [DAT](INFO): publishing direction and range info 2025-12-11T23:35:27.538Z,1765496127.538 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 12.90 m. Transitioning docking module to standby. 2025-12-11T23:35:27.924Z,1765496127.924 [DockingStepper](INFO): Changing to mode: 1 2025-12-11T23:35:33.641Z,1765496133.641 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-12-11T23:35:33.714Z,1765496133.714 [DAT](INFO): ****** received valid address query ****** 2025-12-11T23:35:33.714Z,1765496133.714 [DAT](INFO): ****** received valid ping request ****** 2025-12-11T23:35:33.714Z,1765496133.714 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-12-11T23:35:33.970Z,1765496133.970 [DAT](INFO): DAT read: user:13> 2025-12-11T23:35:33.971Z,1765496133.971 [DAT](INFO): DAT read: Tx time:23:35:33.6369 2025-12-11T23:35:33.971Z,1765496133.971 [DAT](INFO): Ping request sent. 2025-12-11T23:35:33.971Z,1765496133.971 [DAT](INFO): transmitted an acoustic signal 2025-12-11T23:35:33.971Z,1765496133.971 [DAT](INFO): publishing transmit ping time 2025-12-11T23:35:33.972Z,1765496133.972 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000736 2025-12-11T23:35:34.048Z,1765496134.048 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2025-12-11T23:35:34.225Z,1765496134.225 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.253576 2025-12-11T23:35:34.413Z,1765496134.413 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2025-12-11T23:35:34.413Z,1765496134.413 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2025-12-11T23:35:34.414Z,1765496134.414 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2025-12-11T23:35:34.414Z,1765496134.414 [DefaultWithUndock:LeaveDock] Stopped 2025-12-11T23:35:34.414Z,1765496134.414 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2025-12-11T23:35:34.414Z,1765496134.414 [DefaultWithUndock:LeaveDock:A.] Stopped 2025-12-11T23:35:34.414Z,1765496134.414 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2025-12-11T23:35:34.414Z,1765496134.414 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2025-12-11T23:35:34.414Z,1765496134.414 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2025-12-11T23:35:34.414Z,1765496134.414 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2025-12-11T23:35:34.414Z,1765496134.414 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-11T23:35:34.415Z,1765496134.415 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-12-11T23:35:34.415Z,1765496134.415 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-12-11T23:35:34.415Z,1765496134.415 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-12-11T23:35:34.415Z,1765496134.415 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-12-11T23:35:34.416Z,1765496134.416 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-11T23:35:34.416Z,1765496134.416 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-11T23:35:34.474Z,1765496134.474 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503054 2025-12-11T23:35:34.726Z,1765496134.726 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755162 2025-12-11T23:35:34.978Z,1765496134.978 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006989 2025-12-11T23:35:35.181Z,1765496135.181 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -22.00 mm (3 active estimators). 2025-12-11T23:35:35.233Z,1765496135.233 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.261401 2025-12-11T23:35:35.301Z,1765496135.301 [Radio_Surface](INFO): Powering up 2025-12-11T23:35:35.482Z,1765496135.482 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510946 2025-12-11T23:35:35.734Z,1765496135.734 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762939 2025-12-11T23:35:35.986Z,1765496135.986 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014861 2025-12-11T23:35:36.241Z,1765496136.241 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.269716 2025-12-11T23:35:36.491Z,1765496136.491 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519639 2025-12-11T23:35:36.742Z,1765496136.742 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770935 2025-12-11T23:35:36.994Z,1765496136.994 [DAT](INFO): DAT read: Rx Time:23:35:36.0607 2025-12-11T23:35:36.995Z,1765496136.995 [DAT](INFO): Rx dataTimestamp_ set to:1765496136.994382 2025-12-11T23:35:36.995Z,1765496136.995 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024224 2025-12-11T23:35:37.251Z,1765496137.251 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278998 2025-12-11T23:35:37.503Z,1765496137.503 [DAT](INFO): DAT read: 23:35:36.0607 LVL= 20368, 32753, 24754, 32755, AGC= 57, IDX= 454,-0.08,-2.910, 1.556, 2.914, 3.051, PHS= 0.424,-1.450,-0.181, RAW= 131.6, 14.4, CAL= 130.6, 15.5, ROT= 19.4, -15.5 2025-12-11T23:35:37.504Z,1765496137.504 [DAT](INFO): got valid direction response: 23:35:36.0607 LVL= 20368, 32753, 24754, 32755, AGC= 57, IDX= 454,-0.08,-2.910, 1.556, 2.914, 3.051, PHS= 0.424,-1.450,-0.181, RAW= 131.6, 14.4, CAL= 130.6, 15.5, ROT= 19.4, -15.5 2025-12-11T23:35:37.505Z,1765496137.505 [DAT](INFO): DAT read: Bearing 191.7, 21.5 (Local) 2025-12-11T23:35:37.505Z,1765496137.505 [DAT](INFO): Local bearing/azimuth received: Bearing 191.7, 21.5 (Local) 2025-12-11T23:35:37.507Z,1765496137.507 [DAT](INFO): DAT read: Range 11 to 50 : 14.7 m (Round-trip 19.7 ms) speed -0.2 m/s 2025-12-11T23:35:37.507Z,1765496137.507 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-12-11T23:35:37.508Z,1765496137.508 [DAT](INFO): direction in FSK: [0.908918,0.320081,0.267238] 2025-12-11T23:35:37.509Z,1765496137.509 [DAT](INFO): publishing direction and range info 2025-12-11T23:35:40.559Z,1765496140.559 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11 2025-12-11T23:35:40.560Z,1765496140.560 [Radio_Surface](INFO): No local IP found 2025-12-11T23:35:58.668Z,1765496158.668 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +25 +411.34, +5.33, 13.12, 0.00 2025-12-11T23:37:51.505Z,1765496271.505 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T23:37:52.509Z,1765496272.509 [DataOverHttps](INFO): Radio surface powered ON. 2025-12-11T23:37:52.509Z,1765496272.509 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:38:06.280Z,1765496286.280 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2025-12-11T23:38:06.280Z,1765496286.280 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-12-11T23:38:06.281Z,1765496286.281 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-12-11T23:38:22.633Z,1765496302.633 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T23:38:23.637Z,1765496303.637 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:38:53.757Z,1765496333.757 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T23:38:54.760Z,1765496334.760 [NAL9602](INFO): SBD MO Status=0, MOMSN=5385, MT Status=0, MTMSN=0 2025-12-11T23:38:54.760Z,1765496334.760 [NAL9602](INFO): No messages in MT queue 2025-12-11T23:38:54.761Z,1765496334.761 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:38:55.974Z,1765496335.974 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233855.00,A,3646.75769,N,12151.51159,W,0.525,146.68,111225,,,D*7A 2025-12-11T23:38:55.976Z,1765496335.976 [NAL9602](INFO): GPS fix at 20251211T233855: (36.779295, -121.858526) 2025-12-11T23:38:55.990Z,1765496335.990 [UniversalFixResidualReporter](INFO): Fix residual: 0.9 %DT, over the last 2543.1 m. Residual distance 22.1 m at bearing 11.2 degrees. Fix at (36.7793, -121.8585) with 513.4 m made good. 2025-12-11T23:38:55.990Z,1765496335.990 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-12-11T23:38:55.991Z,1765496335.991 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-12-11T23:39:13.840Z,1765496353.840 [NAL9602](INFO): SBD MO Status=1, MOMSN=5386, MT Status=0, MTMSN=0 2025-12-11T23:39:13.893Z,1765496353.893 [NAL9602](INFO): Sent 74 bytes from file Logs/20251211T213510/Courier0021.lzma 2025-12-11T23:39:13.893Z,1765496353.893 [NAL9602](INFO): Packets left to send: 0 2025-12-11T23:39:24.881Z,1765496364.881 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-12-11T23:39:25.885Z,1765496365.885 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:39:27.632Z,1765496367.632 [NAL9602](INFO): SBD MO Status=1, MOMSN=5387, MT Status=0, MTMSN=0 2025-12-11T23:39:27.693Z,1765496367.693 [NAL9602](INFO): Sent 194 bytes from file Logs/20251211T215818/Courier0000.lzma 2025-12-11T23:39:27.693Z,1765496367.693 [NAL9602](INFO): Packets left to send: 0 2025-12-11T23:39:33.342Z,1765496373.342 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003392 2025-12-11T23:39:37.483Z,1765496377.483 [NAL9602](INFO): SBD MO Status=1, MOMSN=5388, MT Status=0, MTMSN=0 2025-12-11T23:39:37.533Z,1765496377.533 [NAL9602](INFO): Sent 39 bytes from file Logs/20251211T215818/Courier0003.lzma 2025-12-11T23:39:37.534Z,1765496377.534 [NAL9602](INFO): Packets left to send: 0 2025-12-11T23:39:40.230Z,1765496380.230 [DataOverHttps](INFO): Sending 325 bytes from file Logs/20251211T233229/Courier0000.lzma 2025-12-11T23:39:41.139Z,1765496381.139 [DataOverHttps](INFO): Moved sent file to Logs/20251211T233229/Courier0000.lzma.bak 2025-12-11T23:39:41.139Z,1765496381.139 [DataOverHttps](INFO): SBD MOMSN=26491373 2025-12-11T23:39:57.397Z,1765496397.397 [DataOverHttps](INFO): Sending 193 bytes from file Logs/20251211T213510/Express0022.lzma 2025-12-11T23:39:58.399Z,1765496398.399 [DataOverHttps](INFO): Moved sent file to Logs/20251211T213510/Express0022.lzma.bak 2025-12-11T23:39:58.399Z,1765496398.399 [DataOverHttps](INFO): SBD MOMSN=26491377 2025-12-11T23:40:08.262Z,1765496408.262 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-11T23:40:17.263Z,1765496417.263 [DataOverHttps](INFO): Sending 3129 bytes from file Logs/20251211T215818/Express0001.lzma 2025-12-11T23:40:18.259Z,1765496418.259 [DataOverHttps](INFO): Moved sent file to Logs/20251211T215818/Express0001.lzma.bak 2025-12-11T23:40:18.259Z,1765496418.259 [DataOverHttps](INFO): SBD MOMSN=26491386 2025-12-11T23:40:34.685Z,1765496434.685 [DataOverHttps](INFO): Sending 468 bytes from file Logs/20251211T215818/Express0004.lzma 2025-12-11T23:40:35.687Z,1765496435.687 [DataOverHttps](INFO): Moved sent file to Logs/20251211T215818/Express0004.lzma.bak 2025-12-11T23:40:35.687Z,1765496435.687 [DataOverHttps](INFO): SBD MOMSN=26491480 2025-12-11T23:40:51.959Z,1765496451.959 [DataOverHttps](INFO): Sending 899 bytes from file Logs/20251211T233229/Express0001.lzma 2025-12-11T23:40:52.959Z,1765496452.959 [DataOverHttps](INFO): Moved sent file to Logs/20251211T233229/Express0001.lzma.bak 2025-12-11T23:40:52.959Z,1765496452.959 [DataOverHttps](INFO): SBD MOMSN=26491487 2025-12-11T23:40:54.310Z,1765496454.310 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-12-11T23:40:54.310Z,1765496454.310 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-12-11T23:40:54.310Z,1765496454.310 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-11T23:40:57.124Z,1765496457.124 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-11T23:41:02.533Z,1765496462.533 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:41:11.181Z,1765496471.181 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:41:19.825Z,1765496479.825 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:41:28.277Z,1765496488.277 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:41:36.997Z,1765496496.997 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:41:45.597Z,1765496505.597 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:41:54.201Z,1765496514.201 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:42:02.853Z,1765496522.853 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:42:11.410Z,1765496531.410 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:42:20.121Z,1765496540.121 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:42:28.785Z,1765496548.785 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:42:37.438Z,1765496557.438 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:42:46.929Z,1765496566.929 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:42:55.557Z,1765496575.557 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:43:04.081Z,1765496584.081 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:43:12.814Z,1765496592.814 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:43:21.357Z,1765496601.357 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:43:30.109Z,1765496610.109 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:43:38.761Z,1765496618.761 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:43:47.545Z,1765496627.545 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:43:56.173Z,1765496636.173 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:44:05.157Z,1765496645.157 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:44:13.745Z,1765496653.745 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:44:22.341Z,1765496662.341 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:44:30.990Z,1765496670.990 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:44:39.685Z,1765496679.685 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:44:48.322Z,1765496688.322 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:44:56.833Z,1765496696.833 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:45:05.565Z,1765496705.565 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:45:14.265Z,1765496714.265 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:45:22.829Z,1765496722.829 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:45:31.478Z,1765496731.478 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:45:40.029Z,1765496740.029 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:45:48.597Z,1765496748.597 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:45:54.929Z,1765496754.929 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-11T23:45:54.929Z,1765496754.929 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-12-11T23:45:54.929Z,1765496754.929 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-11T23:45:54.930Z,1765496754.930 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-12-11T23:45:55.335Z,1765496755.335 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-12-11T23:45:55.335Z,1765496755.335 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-12-11T23:45:55.757Z,1765496755.757 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 12.046001 min 2025-12-11T23:45:55.757Z,1765496755.757 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-12-11T23:45:55.757Z,1765496755.757 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-12-11T23:45:55.757Z,1765496755.757 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-12-11T23:45:55.757Z,1765496755.757 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-12-11T23:45:55.757Z,1765496755.757 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2 2025-12-11T23:45:55.758Z,1765496755.758 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2 2025-12-11T23:45:55.758Z,1765496755.758 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-12-11T23:45:55.758Z,1765496755.758 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-12-11T23:45:57.353Z,1765496757.353 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:45:57.741Z,1765496757.741 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234557.00,A,3646.71176,N,12151.50087,W,0.389,153.65,111225,,,D*74 2025-12-11T23:45:57.743Z,1765496757.743 [NAL9602](INFO): GPS fix at 20251211T234557: (36.778529, -121.858348) 2025-12-11T23:45:57.773Z,1765496757.773 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-12-11T23:45:57.773Z,1765496757.773 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-12-11T23:46:05.353Z,1765496765.353 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20251211T233229/Courier0003.lzma 2025-12-11T23:46:06.355Z,1765496766.355 [DataOverHttps](INFO): Moved sent file to Logs/20251211T233229/Courier0003.lzma.bak 2025-12-11T23:46:06.355Z,1765496766.355 [DataOverHttps](INFO): SBD MOMSN=26491497 2025-12-11T23:46:15.128Z,1765496775.128 [NAL9602](INFO): SBD MO Status=0, MOMSN=5389, MT Status=0, MTMSN=0 2025-12-11T23:46:15.128Z,1765496775.128 [NAL9602](INFO): No messages in MT queue 2025-12-11T23:46:22.753Z,1765496782.753 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20251211T233229/Express0004.lzma 2025-12-11T23:46:23.755Z,1765496783.755 [DataOverHttps](INFO): Moved sent file to Logs/20251211T233229/Express0004.lzma.bak 2025-12-11T23:46:23.755Z,1765496783.755 [DataOverHttps](INFO): SBD MOMSN=26491500 2025-12-11T23:46:25.246Z,1765496785.246 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-12-11T23:46:25.246Z,1765496785.246 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-12-11T23:46:25.246Z,1765496785.246 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-11T23:46:33.613Z,1765496793.613 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:46:42.129Z,1765496802.129 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:46:45.827Z,1765496805.827 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-11T23:46:50.861Z,1765496810.861 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:46:59.525Z,1765496819.525 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:47:08.553Z,1765496828.553 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:47:17.365Z,1765496837.365 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:47:26.029Z,1765496846.029 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:47:34.633Z,1765496854.633 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:47:43.345Z,1765496863.345 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:47:51.985Z,1765496871.985 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:48:00.561Z,1765496880.561 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:48:10.661Z,1765496890.661 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:48:19.377Z,1765496899.377 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:48:27.989Z,1765496907.989 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:48:36.689Z,1765496916.689 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:48:45.297Z,1765496925.297 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:48:54.057Z,1765496934.057 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:49:02.741Z,1765496942.741 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:49:11.321Z,1765496951.321 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:49:20.269Z,1765496960.269 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:49:33.846Z,1765496973.846 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:49:47.570Z,1765496987.570 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:50:01.162Z,1765497001.162 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:50:16.410Z,1765497016.410 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:50:29.981Z,1765497029.981 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:50:43.674Z,1765497043.674 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:50:57.350Z,1765497057.350 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:51:10.994Z,1765497070.994 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:51:24.522Z,1765497084.522 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:51:25.908Z,1765497085.908 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-11T23:51:25.908Z,1765497085.908 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-12-11T23:51:25.908Z,1765497085.908 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-11T23:51:25.908Z,1765497085.908 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1 2025-12-11T23:51:26.256Z,1765497086.256 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped 2025-12-11T23:51:26.256Z,1765497086.256 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1 2025-12-11T23:51:26.675Z,1765497086.675 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 17.561357 min 2025-12-11T23:51:26.675Z,1765497086.675 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped 2025-12-11T23:51:26.675Z,1765497086.675 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn 2025-12-11T23:51:26.675Z,1765497086.675 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-12-11T23:51:26.675Z,1765497086.675 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-12-11T23:51:26.676Z,1765497086.676 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #3 2025-12-11T23:51:26.676Z,1765497086.676 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=3 2025-12-11T23:51:26.676Z,1765497086.676 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-12-11T23:51:26.676Z,1765497086.676 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-12-11T23:51:28.648Z,1765497088.648 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235127.00,A,3646.67373,N,12151.49594,W,0.855,272.00,111225,,,D*70 2025-12-11T23:51:28.650Z,1765497088.650 [NAL9602](INFO): GPS fix at 20251211T235127: (36.777895, -121.858266) 2025-12-11T23:51:28.760Z,1765497088.760 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-12-11T23:51:28.760Z,1765497088.760 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-12-11T23:51:35.796Z,1765497095.796 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251211T233229/Courier0006.lzma 2025-12-11T23:51:36.575Z,1765497096.575 [DataOverHttps](INFO): Moved sent file to Logs/20251211T233229/Courier0006.lzma.bak 2025-12-11T23:51:36.575Z,1765497096.575 [DataOverHttps](INFO): SBD MOMSN=26491504 2025-12-11T23:51:37.560Z,1765497097.560 [NAL9602](INFO): SBD MO Status=0, MOMSN=5390, MT Status=0, MTMSN=0 2025-12-11T23:51:37.560Z,1765497097.560 [NAL9602](INFO): No messages in MT queue 2025-12-11T23:51:59.775Z,1765497119.775 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00061803405967,3.1, 0.0,1503.8, 0 2025-12-11T23:52:02.605Z,1765497122.605 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20251211T233229/Express0007.lzma 2025-12-11T23:52:03.611Z,1765497123.611 [DataOverHttps](INFO): Moved sent file to Logs/20251211T233229/Express0007.lzma.bak 2025-12-11T23:52:03.611Z,1765497123.611 [DataOverHttps](INFO): SBD MOMSN=26491507 2025-12-11T23:52:05.596Z,1765497125.596 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-12-11T23:52:05.596Z,1765497125.596 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-12-11T23:52:05.596Z,1765497125.596 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-11T23:52:08.290Z,1765497128.290 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-11T23:52:09.547Z,1765497129.547 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00061803410967,35.0, +140,1503.8, 0 2025-12-11T23:52:18.105Z,1765497138.105 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:52:19.623Z,1765497139.623 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +74.06, +49 +5.14, 20.00, 0.00 2025-12-11T23:52:29.711Z,1765497149.711 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, + +49.34, +5.13, 0.00 2025-12-11T23:52:31.294Z,1765497151.294 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:52:39.816Z,1765497159.816 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS, +87, -12,A 2025-12-11T23:52:39.873Z,1765497159.873 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:52:48.773Z,1765497168.773 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:52:56.318Z,1765497176.318 [DataOverHttps](IMPORTANT): SBD MTMSN=20251211T235255 2025-12-11T23:53:03.921Z,1765497183.921 [DataOverHttps](INFO): Received command: restart sys 2025-12-11T23:53:03.934Z,1765497183.934 [CommandExec](IMPORTANT): got command restart system 2025-12-11T23:53:05.926Z,1765497185.926 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-11T23:53:06.009Z,1765497186.009 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2025-12-11T23:53:06.010Z,1765497186.010 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-11T23:53:06.010Z,1765497186.010 [CommandExec](INFO): Uninitializing the command executive. 2025-12-11T23:53:06.010Z,1765497186.010 [CommandExec](INFO): Uninitializing the command scheduler. 2025-12-11T23:53:06.010Z,1765497186.010 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-12-11T23:53:06.209Z,1765497186.209 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-12-11T23:53:06.209Z,1765497186.209 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-12-11T23:53:06.209Z,1765497186.209 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-12-11T23:53:06.210Z,1765497186.210 [NavChartDb](INFO): Join timeout helper Thread ID is 5723 2025-12-11T23:53:06.261Z,1765497186.261 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-11T23:53:06.262Z,1765497186.262 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-12-11T23:53:06.269Z,1765497186.269 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-12-11T23:53:06.269Z,1765497186.269 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-12-11T23:53:06.270Z,1765497186.270 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5724 2025-12-11T23:53:06.533Z,1765497186.533 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-11T23:53:06.534Z,1765497186.534 [WetLabsBB2FL](INFO): Powering down 2025-12-11T23:53:06.534Z,1765497186.534 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-12-11T23:53:06.545Z,1765497186.545 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-12-11T23:53:06.545Z,1765497186.545 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-12-11T23:53:06.545Z,1765497186.545 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5725 2025-12-11T23:53:07.533Z,1765497187.533 [CTD_Seabird](INFO): Powering down 2025-12-11T23:53:07.545Z,1765497187.545 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-11T23:53:07.546Z,1765497187.546 [CTD_Seabird](INFO): Powering down 2025-12-11T23:53:07.557Z,1765497187.557 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-12-11T23:53:07.565Z,1765497187.565 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-12-11T23:53:07.565Z,1765497187.565 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-12-11T23:53:07.566Z,1765497187.566 [Radio_Surface](INFO): Join timeout helper Thread ID is 5726 2025-12-11T23:53:07.949Z,1765497187.949 [Radio_Surface](INFO): Powering down 2025-12-11T23:53:07.950Z,1765497187.950 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-11T23:53:07.951Z,1765497187.951 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-12-11T23:53:07.957Z,1765497187.957 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-12-11T23:53:07.957Z,1765497187.957 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-12-11T23:53:07.958Z,1765497187.958 [Onboard](INFO): Join timeout helper Thread ID is 5727 2025-12-11T23:53:09.466Z,17654971