2025-08-22T23:00:17.630Z,1755903617.630 [OnDock:L.Execute](INFO): Executing command restart logs 2025-08-22T23:00:17.630Z,1755903617.630 [OnDock:L.Execute] Stopped 2025-08-22T23:00:17.631Z,1755903617.631 [OnDock:N.Docked] Running Loop=1 2025-08-22T23:00:17.631Z,1755903617.631 [OnDock:N.Docked](DEBUG): Initialize. 2025-08-22T23:00:17.631Z,1755903617.631 [OnDock:N.Docked](DEBUG): Initializing internal variables to default values. 2025-08-22T23:00:17.690Z,1755903617.690 [CommandExec](IMPORTANT): got command restart logs 2025-08-22T23:00:18.130Z,1755903618.130 [DAT](INFO): Requesting device enable set for address 50. 2025-08-22T23:00:18.383Z,1755903618.383 [DAT](INFO): DAT read: user:2902> 2025-08-22T23:00:18.383Z,1755903618.383 [DAT](INFO): DAT read: Tx time:23:00:19.3687 2025-08-22T23:00:18.384Z,1755903618.384 [DAT](INFO): Ping request sent. 2025-08-22T23:00:18.384Z,1755903618.384 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:00:18.384Z,1755903618.384 [DAT](INFO): publishing transmit ping time 2025-08-22T23:00:18.385Z,1755903618.385 [DAT](INFO): Requesting device enable set for address 50. 2025-08-22T23:00:18.636Z,1755903618.636 [DAT](INFO): Requesting device enable set for address 50. 2025-08-22T23:00:19.267Z,1755903619.267 [CTD_Seabird](ERROR): Failed to parse device response: 2025-08-22T23:00:20.651Z,1755903620.651 [DAT](INFO): DAT read: Rx Time:23:00:21.2354 2025-08-22T23:00:20.651Z,1755903620.651 [DAT](INFO): Rx dataTimestamp_ set to:1755903620.650749 2025-08-22T23:00:21.160Z,1755903621.160 [DAT](INFO): DAT read: 23:00:21.2354 LVL= 18656, 19537, 32754, 27059, AGC= 32, IDX= 437,-0.35,-2.137,-0.954,-0.673,-0.246, PHS=-1.790,-0.664,-0.471, RAW= 262.2, 40.0, CAL= 264.3, 47.8, ROT= 245.7, -47.8 2025-08-22T23:00:21.161Z,1755903621.161 [DAT](INFO): got valid direction response: 23:00:21.2354 LVL= 18656, 19537, 32754, 27059, AGC= 32, IDX= 437,-0.35,-2.137,-0.954,-0.673,-0.246, PHS=-1.790,-0.664,-0.471, RAW= 262.2, 40.0, CAL= 264.3, 47.8, ROT= 245.7, -47.8 2025-08-22T23:00:21.162Z,1755903621.162 [DAT](INFO): DAT read: OK 2025-08-22T23:00:21.163Z,1755903621.163 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T23:00:21.163Z,1755903621.163 [DAT](INFO): DAT read: user:2903> 2025-08-22T23:00:21.164Z,1755903621.164 [DAT](INFO): DAT read: Tx time:23:00:22.3195 2025-08-22T23:00:21.165Z,1755903621.165 [DAT](INFO): Ping request sent. 2025-08-22T23:00:21.165Z,1755903621.165 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:00:21.165Z,1755903621.165 [DAT](INFO): #Rx 10: Read direction message, but no range. 2025-08-22T23:00:21.167Z,1755903621.167 [DAT](INFO): direction in FSK: [-0.276423,-0.612208,0.740805] 2025-08-22T23:00:21.167Z,1755903621.167 [DAT](INFO): publishing transmit ping time 2025-08-22T23:00:21.175Z,1755903621.175 [DAT](INFO): publishing transmit ping time 2025-08-22T23:00:23.675Z,1755903623.675 [DAT](INFO): DAT read: Rx Time:23:00:24.1854 2025-08-22T23:00:23.675Z,1755903623.675 [DAT](INFO): Rx dataTimestamp_ set to:1755903623.674782 2025-08-22T23:00:24.179Z,1755903624.179 [DAT](INFO): DAT read: OK 2025-08-22T23:00:24.179Z,1755903624.179 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T23:00:24.180Z,1755903624.180 [DAT](INFO): DAT read: user:2904> 2025-08-22T23:00:24.181Z,1755903624.181 [DAT](INFO): DAT read: Tx time:23:00:25.2686 2025-08-22T23:00:24.181Z,1755903624.181 [DAT](INFO): Ping request sent. 2025-08-22T23:00:24.182Z,1755903624.182 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:00:24.182Z,1755903624.182 [DAT](INFO): publishing transmit ping time 2025-08-22T23:00:26.699Z,1755903626.699 [DAT](INFO): DAT read: Rx Time:23:00:27.1354 2025-08-22T23:00:26.699Z,1755903626.699 [DAT](INFO): Rx dataTimestamp_ set to:1755903626.698766 2025-08-22T23:00:27.206Z,1755903627.206 [DAT](INFO): DAT read: 23:00:27.1354 LVL= 14128, 16417, 32754, 26099, AGC= 34, IDX= 421, 0.40, 0.464,-0.957,-1.442, 0.137, PHS= 0.429,-1.049,-1.623, RAW= 74.3, 23.4, CAL= 71.2, 30.6, ROT= 78.8, -30.6 2025-08-22T23:00:27.207Z,1755903627.207 [DAT](INFO): got valid direction response: 23:00:27.1354 LVL= 14128, 16417, 32754, 26099, AGC= 34, IDX= 421, 0.40, 0.464,-0.957,-1.442, 0.137, PHS= 0.429,-1.049,-1.623, RAW= 74.3, 23.4, CAL= 71.2, 30.6, ROT= 78.8, -30.6 2025-08-22T23:00:27.208Z,1755903627.208 [DAT](INFO): DAT read: OK 2025-08-22T23:00:27.208Z,1755903627.208 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T23:00:27.208Z,1755903627.208 [DAT](INFO): #Rx 11: Read direction message, but no range. 2025-08-22T23:00:27.210Z,1755903627.210 [DAT](INFO): direction in FSK: [0.167186,0.844349,0.509041] 2025-08-22T23:01:39.243Z,1755903699.243 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-22T23:01:55.409Z,1755903715.409 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 69.61, 69.95, 70.29 2025-08-22T23:02:15.631Z,1755903735.631 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:02:15.671Z,1755903735.671 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:02:15.671Z,1755903735.671 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:02:15.922Z,1755903735.922 [DAT](INFO): DAT read: user:2905> 2025-08-22T23:02:15.924Z,1755903735.924 [DAT](INFO): DAT read: Tx time:23:02:16.9193 2025-08-22T23:02:15.926Z,1755903735.926 [DAT](INFO): Ping request sent. 2025-08-22T23:02:15.926Z,1755903735.926 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:02:15.927Z,1755903735.927 [DAT](INFO): publishing transmit ping time 2025-08-22T23:02:15.929Z,1755903735.929 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.003785 2025-08-22T23:02:16.175Z,1755903736.175 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.249529 2025-08-22T23:02:16.429Z,1755903736.429 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503295 2025-08-22T23:02:16.679Z,1755903736.679 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753274 2025-08-22T23:02:16.930Z,1755903736.930 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005231 2025-08-22T23:02:17.188Z,1755903737.188 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.262074 2025-08-22T23:02:17.437Z,1755903737.437 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511255 2025-08-22T23:02:17.687Z,1755903737.687 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.761665 2025-08-22T23:02:17.939Z,1755903737.939 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013256 2025-08-22T23:02:18.191Z,1755903738.191 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.265773 2025-08-22T23:02:18.445Z,1755903738.445 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519329 2025-08-22T23:02:18.695Z,1755903738.695 [DAT](INFO): DAT read: Rx Time:23:02:19.3247 2025-08-22T23:02:18.695Z,1755903738.695 [DAT](INFO): Rx dataTimestamp_ set to:1755903738.694713 2025-08-22T23:02:18.696Z,1755903738.696 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770296 2025-08-22T23:02:18.947Z,1755903738.947 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021274 2025-08-22T23:02:19.201Z,1755903739.201 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275242 2025-08-22T23:02:19.463Z,1755903739.463 [DAT](INFO): DAT read: 23:02:19.3247 LVL= 15920, 13297, 32754, 30659, AGC= 35, IDX= 298, 0.18, 2.362, 0.978, 0.407, 1.862, PHS= 0.602,-0.838,-1.499, RAW= 72.1, 18.2, CAL= 69.8, 25.0, ROT= 80.2, -25.0 2025-08-22T23:02:19.467Z,1755903739.467 [DAT](INFO): got valid direction response: 23:02:19.3247 LVL= 15920, 13297, 32754, 30659, AGC= 35, IDX= 298, 0.18, 2.362, 0.978, 0.407, 1.862, PHS= 0.602,-0.838,-1.499, RAW= 72.1, 18.2, CAL= 69.8, 25.0, ROT= 80.2, -25.0 2025-08-22T23:02:19.471Z,1755903739.471 [DAT](INFO): DAT read: Range 11 to 50 : 0.9 m (Round-trip 1.2 ms) speed 0.0 m/s 2025-08-22T23:02:19.473Z,1755903739.473 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:02:19.477Z,1755903739.477 [DAT](INFO): direction in FSK: [0.154262,0.893083,0.422618] 2025-08-22T23:02:19.478Z,1755903739.478 [DAT](INFO): publishing direction and range info 2025-08-22T23:02:49.993Z,1755903769.993 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, -15.8+771.02, -4.83, 7.02, 0.00 2025-08-22T23:04:15.990Z,1755903855.990 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:04:16.212Z,1755903856.212 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:04:16.213Z,1755903856.213 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:04:16.463Z,1755903856.463 [DAT](INFO): DAT read: user:2906> 2025-08-22T23:04:16.464Z,1755903856.464 [DAT](INFO): DAT read: Tx time:23:04:17.4690 2025-08-22T23:04:16.464Z,1755903856.464 [DAT](INFO): Ping request sent. 2025-08-22T23:04:16.465Z,1755903856.465 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:04:16.465Z,1755903856.465 [DAT](INFO): publishing transmit ping time 2025-08-22T23:04:16.466Z,1755903856.466 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001425 2025-08-22T23:04:16.715Z,1755903856.715 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250138 2025-08-22T23:04:16.967Z,1755903856.967 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501929 2025-08-22T23:04:17.222Z,1755903857.222 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.756323 2025-08-22T23:04:17.471Z,1755903857.471 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006010 2025-08-22T23:04:17.722Z,1755903857.722 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.257930 2025-08-22T23:04:17.974Z,1755903857.974 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509943 2025-08-22T23:04:18.229Z,1755903858.229 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763692 2025-08-22T23:04:18.479Z,1755903858.479 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014431 2025-08-22T23:04:18.731Z,1755903858.731 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.265985 2025-08-22T23:04:18.983Z,1755903858.983 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517994 2025-08-22T23:04:19.237Z,1755903859.237 [DAT](INFO): DAT read: Rx Time:23:04:19.8744 2025-08-22T23:04:19.239Z,1755903859.239 [DAT](INFO): Rx dataTimestamp_ set to:1755903859.237705 2025-08-22T23:04:19.240Z,1755903859.240 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.775341 2025-08-22T23:04:19.487Z,1755903859.487 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021951 2025-08-22T23:04:19.739Z,1755903859.739 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274029 2025-08-22T23:04:19.996Z,1755903859.996 [DAT](INFO): DAT read: 23:04:19.8744 LVL= 14992, 14593, 32754, 32755, AGC= 34, IDX= 426, 0.05, 1.376,-0.039,-0.594, 0.771, PHS= 0.707,-0.765,-1.409, RAW= 72.7, 15.4, CAL= 71.2, 21.9, ROT= 78.8, -21.9 2025-08-22T23:04:19.997Z,1755903859.997 [DAT](INFO): got valid direction response: 23:04:19.8744 LVL= 14992, 14593, 32754, 32755, AGC= 34, IDX= 426, 0.05, 1.376,-0.039,-0.594, 0.771, PHS= 0.707,-0.765,-1.409, RAW= 72.7, 15.4, CAL= 71.2, 21.9, ROT= 78.8, -21.9 2025-08-22T23:04:20.002Z,1755903860.002 [DAT](INFO): DAT read: Bearing 18.0, 67.5 (Local) 2025-08-22T23:04:20.002Z,1755903860.002 [DAT](INFO): Local bearing/azimuth received: Bearing 18.0, 67.5 (Local) 2025-08-22T23:04:20.004Z,1755903860.004 [DAT](INFO): DAT read: Range 11 to 50 : 0.9 m (Round-trip 1.2 ms) speed 0.0 m/s 2025-08-22T23:04:20.004Z,1755903860.004 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:04:20.009Z,1755903860.009 [DAT](INFO): direction in FSK: [0.180218,0.910166,0.372988] 2025-08-22T23:04:20.010Z,1755903860.010 [DAT](INFO): publishing direction and range info 2025-08-22T23:04:20.440Z,1755903860.440 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for beam range. Device response is::RA, 0.00, 69.62 2025-08-22T23:06:16.055Z,1755903976.055 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:06:16.258Z,1755903976.258 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:06:16.259Z,1755903976.259 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:06:16.510Z,1755903976.510 [DAT](INFO): DAT read: user:2907> 2025-08-22T23:06:16.511Z,1755903976.511 [DAT](INFO): DAT read: Tx time:23:06:17.5188 2025-08-22T23:06:16.511Z,1755903976.511 [DAT](INFO): Ping request sent. 2025-08-22T23:06:16.512Z,1755903976.512 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:06:16.512Z,1755903976.512 [DAT](INFO): publishing transmit ping time 2025-08-22T23:06:16.512Z,1755903976.512 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000763 2025-08-22T23:06:16.763Z,1755903976.763 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250944 2025-08-22T23:06:17.017Z,1755903977.017 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.504648 2025-08-22T23:06:17.267Z,1755903977.267 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755529 2025-08-22T23:06:17.518Z,1755903977.518 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006777 2025-08-22T23:06:17.771Z,1755903977.771 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258913 2025-08-22T23:06:18.028Z,1755903978.028 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.515886 2025-08-22T23:06:18.278Z,1755903978.278 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.766776 2025-08-22T23:06:18.531Z,1755903978.531 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.019002 2025-08-22T23:06:18.783Z,1755903978.783 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270994 2025-08-22T23:06:19.037Z,1755903979.037 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.524961 2025-08-22T23:06:19.287Z,1755903979.287 [DAT](INFO): DAT read: Rx Time:23:06:19.9242 2025-08-22T23:06:19.288Z,1755903979.288 [DAT](INFO): Rx dataTimestamp_ set to:1755903979.287414 2025-08-22T23:06:19.289Z,1755903979.289 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.776898 2025-08-22T23:06:19.539Z,1755903979.539 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.026914 2025-08-22T23:06:19.793Z,1755903979.793 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.280827 2025-08-22T23:06:20.062Z,1755903980.062 [DAT](INFO): DAT read: 23:06:19.9242 LVL= 14496, 11057, 32754, 27683, AGC= 34, IDX= 426,-0.42, 2.091, 1.127, 0.412, 1.977, PHS= 0.216,-0.805,-1.609, RAW= 63.9, 26.1, CAL= 59.6, 32.6, ROT= 90.4, -32.6 2025-08-22T23:06:20.063Z,1755903980.063 [DAT](INFO): got valid direction response: 23:06:19.9242 LVL= 14496, 11057, 32754, 27683, AGC= 34, IDX= 426,-0.42, 2.091, 1.127, 0.412, 1.977, PHS= 0.216,-0.805,-1.609, RAW= 63.9, 26.1, CAL= 59.6, 32.6, ROT= 90.4, -32.6 2025-08-22T23:06:20.064Z,1755903980.064 [DAT](INFO): DAT read: Bearing 45.6, 59.6 (Local) 2025-08-22T23:06:20.064Z,1755903980.064 [DAT](INFO): Local bearing/azimuth received: Bearing 45.6, 59.6 (Local) 2025-08-22T23:06:20.094Z,1755903980.094 [DAT](INFO): DAT read: Range 11 to 50 : 0.9 m (Round-trip 1.2 ms) speed 0.0 m/s 2025-08-22T23:06:20.094Z,1755903980.094 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:06:20.095Z,1755903980.095 [DAT](INFO): direction in FSK: [-0.005881,0.842432,0.538771] 2025-08-22T23:06:20.095Z,1755903980.095 [DAT](INFO): publishing direction and range info 2025-08-22T23:06:40.635Z,1755904000.635 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2025-08-22T23:06:40.635Z,1755904000.635 [RDI_Pathfinder] Communications Fault, FailCount= 1 2025-08-22T23:06:40.635Z,1755904000.635 [RDI_Pathfinder](ERROR): Communications Fault 2025-08-22T23:06:40.715Z,1755904000.715 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-08-22T23:06:41.015Z,1755904001.015 [RDI_Pathfinder](INFO): Powering down 2025-08-22T23:06:41.906Z,1755904001.907 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-08-22T23:06:41.907Z,1755904001.907 [RDI_Pathfinder] No Fault, FailCount= 1 2025-08-22T23:06:42.240Z,1755904002.240 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-08-22T23:07:23.483Z,1755904043.483 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:07:23.484Z,1755904043.484 [BPC1](FAULT): Charging on dock, ignoring failure to receieve battery data and resetting batt data check 2025-08-22T23:07:25.115Z,1755904045.115 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 69.95, 71.31, 69.95, 2025-08-22T23:07:29.906Z,1755904049.906 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:07:40.866Z,1755904060.866 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:07:50.510Z,1755904070.510 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00022710.4, 91.6, 0 2025-08-22T23:07:51.769Z,1755904071.769 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:08:02.718Z,1755904082.718 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:08:13.585Z,1755904093.585 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:08:16.369Z,1755904096.369 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:08:16.570Z,1755904096.570 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:08:16.571Z,1755904096.571 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:08:16.826Z,1755904096.826 [DAT](INFO): DAT read: user:2908> 2025-08-22T23:08:16.828Z,1755904096.828 [DAT](INFO): DAT read: Tx time:23:08:17.8187 2025-08-22T23:08:16.829Z,1755904096.829 [DAT](INFO): Ping request sent. 2025-08-22T23:08:16.829Z,1755904096.829 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:08:16.831Z,1755904096.831 [DAT](INFO): publishing transmit ping time 2025-08-22T23:08:16.833Z,1755904096.833 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.003104 2025-08-22T23:08:17.078Z,1755904097.078 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.248940 2025-08-22T23:08:17.326Z,1755904097.326 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.497478 2025-08-22T23:08:17.579Z,1755904097.579 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.749609 2025-08-22T23:08:17.834Z,1755904097.834 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.003912 2025-08-22T23:08:18.082Z,1755904098.082 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.253420 2025-08-22T23:08:18.335Z,1755904098.335 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.505528 2025-08-22T23:08:18.587Z,1755904098.587 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.757503 2025-08-22T23:08:18.842Z,1755904098.842 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.012009 2025-08-22T23:08:19.091Z,1755904099.091 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.261508 2025-08-22T23:08:19.343Z,1755904099.343 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.513533 2025-08-22T23:08:19.595Z,1755904099.595 [DAT](INFO): DAT read: Rx Time:23:08:20.2240 2025-08-22T23:08:19.595Z,1755904099.595 [DAT](INFO): Rx dataTimestamp_ set to:1755904099.594807 2025-08-22T23:08:19.596Z,1755904099.596 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.766638 2025-08-22T23:08:19.849Z,1755904099.849 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.019854 2025-08-22T23:08:20.101Z,1755904100.101 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.271855 2025-08-22T23:08:20.356Z,1755904100.356 [DAT](INFO): DAT read: 23:08:20.2240 LVL= 13248, 12753, 32754, 28659, AGC= 33, IDX= 442,-0.36, 1.834, 0.687, 0.137, 1.564, PHS= 0.372,-0.832,-1.471, RAW= 70.0, 22.8, CAL= 66.6, 29.8, ROT= 83.4, -29.8 2025-08-22T23:08:20.357Z,1755904100.357 [DAT](INFO): got valid direction response: 23:08:20.2240 LVL= 13248, 12753, 32754, 28659, AGC= 33, IDX= 442,-0.36, 1.834, 0.687, 0.137, 1.564, PHS= 0.372,-0.832,-1.471, RAW= 70.0, 22.8, CAL= 66.6, 29.8, ROT= 83.4, -29.8 2025-08-22T23:08:20.358Z,1755904100.358 [DAT](INFO): DAT read: Bearing 30.6, 61.5 (Local) 2025-08-22T23:08:20.358Z,1755904100.358 [DAT](INFO): Local bearing/azimuth received: Bearing 30.6, 61.5 (Local) 2025-08-22T23:08:20.359Z,1755904100.359 [DAT](INFO): DAT read: Range 11 to 50 : 0.8 m (Round-trip 1.1 ms) speed 0.0 m/s 2025-08-22T23:08:20.360Z,1755904100.360 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:08:20.361Z,1755904100.361 [DAT](INFO): direction in FSK: [0.099738,0.862015,0.496974] 2025-08-22T23:08:20.361Z,1755904100.361 [DAT](INFO): publishing direction and range info 2025-08-22T23:08:24.498Z,1755904104.498 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:08:35.407Z,1755904115.407 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:08:46.352Z,1755904126.352 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:08:57.228Z,1755904137.228 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:09:08.130Z,1755904148.129 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:09:19.041Z,1755904159.041 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:09:23.025Z,1755904163.025 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 69.61, 71.31, 70.63, 69.61 2025-08-22T23:09:28.265Z,1755904168.265 [BPC1](ERROR): BPC1A got IPBS message with 4 sticks (min is 7). 2025-08-22T23:09:28.268Z,1755904168.268 [BPC1](ERROR): BPC1A failed to parse battery stick message. 2025-08-22T23:09:39.241Z,1755904179.241 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:09:50.584Z,1755904190.584 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:10:16.801Z,1755904216.801 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:10:16.890Z,1755904216.890 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:10:16.891Z,1755904216.891 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:10:17.143Z,1755904217.143 [DAT](INFO): DAT read: user:2909> 2025-08-22T23:10:17.144Z,1755904217.144 [DAT](INFO): DAT read: Tx time:23:10:18.1685 2025-08-22T23:10:17.144Z,1755904217.144 [DAT](INFO): Ping request sent. 2025-08-22T23:10:17.144Z,1755904217.144 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:10:17.144Z,1755904217.144 [DAT](INFO): publishing transmit ping time 2025-08-22T23:10:17.145Z,1755904217.145 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000740 2025-08-22T23:10:17.395Z,1755904217.395 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250407 2025-08-22T23:10:17.647Z,1755904217.647 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502520 2025-08-22T23:10:17.898Z,1755904217.898 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754313 2025-08-22T23:10:18.150Z,1755904218.150 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006341 2025-08-22T23:10:18.403Z,1755904218.403 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258460 2025-08-22T23:10:18.655Z,1755904218.655 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510361 2025-08-22T23:10:18.907Z,1755904218.907 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762373 2025-08-22T23:10:19.163Z,1755904219.163 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018915 2025-08-22T23:10:19.414Z,1755904219.414 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270329 2025-08-22T23:10:19.667Z,1755904219.667 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.522367 2025-08-22T23:10:19.919Z,1755904219.919 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774407 2025-08-22T23:10:20.171Z,1755904220.171 [DAT](INFO): DAT read: Rx Time:23:10:20.5739 2025-08-22T23:10:20.172Z,1755904220.172 [DAT](INFO): Rx dataTimestamp_ set to:1755904220.171307 2025-08-22T23:10:20.172Z,1755904220.172 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.028282 2025-08-22T23:10:20.423Z,1755904220.423 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278476 2025-08-22T23:10:20.687Z,1755904220.687 [DAT](INFO): DAT read: 23:10:20.5739 LVL= 12688, 10977, 32754, 27219, AGC= 32, IDX= 426, 0.02,-0.324,-1.417,-2.106,-0.524, PHS= 0.303,-0.847,-1.626, RAW= 66.3, 24.5, CAL= 62.3, 31.2, ROT= 87.7, -31.2 2025-08-22T23:10:20.688Z,1755904220.688 [DAT](INFO): got valid direction response: 23:10:20.5739 LVL= 12688, 10977, 32754, 27219, AGC= 32, IDX= 426, 0.02,-0.324,-1.417,-2.106,-0.524, PHS= 0.303,-0.847,-1.626, RAW= 66.3, 24.5, CAL= 62.3, 31.2, ROT= 87.7, -31.2 2025-08-22T23:10:20.689Z,1755904220.689 [DAT](INFO): DAT read: Bearing 41.7, 60.9 (Local) 2025-08-22T23:10:20.689Z,1755904220.689 [DAT](INFO): Local bearing/azimuth received: Bearing 41.7, 60.9 (Local) 2025-08-22T23:10:20.691Z,1755904220.691 [DAT](INFO): DAT read: Range 11 to 50 : 0.9 m (Round-trip 1.2 ms) speed 0.0 m/s 2025-08-22T23:10:20.692Z,1755904220.692 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:10:20.693Z,1755904220.693 [DAT](INFO): direction in FSK: [0.034327,0.854675,0.518027] 2025-08-22T23:10:20.693Z,1755904220.693 [DAT](INFO): publishing direction and range info 2025-08-22T23:11:25.842Z,1755904285.842 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00022802584575,35.0, +10.41491.6, 0 2025-08-22T23:11:39.572Z,1755904299.572 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-22T23:11:56.195Z,1755904316.195 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:12:17.151Z,1755904337.151 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:12:17.226Z,1755904337.226 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:12:17.227Z,1755904337.227 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:12:17.478Z,1755904337.478 [DAT](INFO): DAT read: user:2910> 2025-08-22T23:12:17.479Z,1755904337.479 [DAT](INFO): DAT read: Tx time:23:12:18.4683 2025-08-22T23:12:17.479Z,1755904337.479 [DAT](INFO): Ping request sent. 2025-08-22T23:12:17.480Z,1755904337.480 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:12:17.480Z,1755904337.480 [DAT](INFO): publishing transmit ping time 2025-08-22T23:12:17.480Z,1755904337.480 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000757 2025-08-22T23:12:17.730Z,1755904337.730 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250897 2025-08-22T23:12:17.984Z,1755904337.984 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.504550 2025-08-22T23:12:18.234Z,1755904338.234 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754907 2025-08-22T23:12:18.486Z,1755904338.486 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006942 2025-08-22T23:12:18.739Z,1755904338.739 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259059 2025-08-22T23:12:18.991Z,1755904338.991 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511083 2025-08-22T23:12:19.254Z,1755904339.254 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.769732 2025-08-22T23:12:19.503Z,1755904339.503 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.023035 2025-08-22T23:12:19.754Z,1755904339.754 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.274917 2025-08-22T23:12:20.010Z,1755904340.010 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.530681 2025-08-22T23:12:20.263Z,1755904340.263 [DAT](INFO): DAT read: Rx Time:23:12:20.8737 2025-08-22T23:12:20.263Z,1755904340.263 [DAT](INFO): Rx dataTimestamp_ set to:1755904340.262778 2025-08-22T23:12:20.264Z,1755904340.264 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.784069 2025-08-22T23:12:20.514Z,1755904340.514 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.034845 2025-08-22T23:12:20.767Z,1755904340.767 [DAT](INFO): DAT read: Range 11 to 50 : 0.9 m (Round-trip 1.2 ms) speed 0.0 m/s 2025-08-22T23:12:20.768Z,1755904340.768 [DAT](ERROR): #Rx 1: Read range message, but no direction. 2025-08-22T23:12:28.865Z,1755904348.865 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 69.96, 70.98, 69.62 2025-08-22T23:13:50.907Z,1755904430.907 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2025-08-22T23:13:50.907Z,1755904430.907 [RDI_Pathfinder] Communications Fault, FailCount= 1 2025-08-22T23:13:50.907Z,1755904430.907 [RDI_Pathfinder](ERROR): Communications Fault 2025-08-22T23:13:50.984Z,1755904430.984 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-08-22T23:13:51.287Z,1755904431.287 [RDI_Pathfinder](INFO): Powering down 2025-08-22T23:13:52.184Z,1755904432.184 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-08-22T23:13:52.184Z,1755904432.184 [RDI_Pathfinder] No Fault, FailCount= 1 2025-08-22T23:13:52.491Z,1755904432.491 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-08-22T23:14:17.188Z,1755904457.188 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:14:17.282Z,1755904457.282 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:14:17.283Z,1755904457.283 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:14:17.534Z,1755904457.534 [DAT](INFO): DAT read: user:2911> 2025-08-22T23:14:17.535Z,1755904457.535 [DAT](INFO): DAT read: Tx time:23:14:18.5182 2025-08-22T23:14:17.535Z,1755904457.535 [DAT](INFO): Ping request sent. 2025-08-22T23:14:17.536Z,1755904457.536 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:14:17.536Z,1755904457.536 [DAT](INFO): publishing transmit ping time 2025-08-22T23:14:17.536Z,1755904457.536 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000746 2025-08-22T23:14:17.787Z,1755904457.787 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250950 2025-08-22T23:14:18.041Z,1755904458.041 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.504917 2025-08-22T23:14:18.291Z,1755904458.291 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754975 2025-08-22T23:14:18.542Z,1755904458.542 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006864 2025-08-22T23:14:18.796Z,1755904458.796 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.260368 2025-08-22T23:14:19.049Z,1755904459.049 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.512996 2025-08-22T23:14:19.298Z,1755904459.298 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762867 2025-08-22T23:14:19.551Z,1755904459.551 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015161 2025-08-22T23:14:19.802Z,1755904459.802 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266896 2025-08-22T23:14:20.057Z,1755904460.057 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.520889 2025-08-22T23:14:20.307Z,1755904460.307 [DAT](INFO): DAT read: Rx Time:23:14:20.9236 2025-08-22T23:14:20.307Z,1755904460.307 [DAT](INFO): Rx dataTimestamp_ set to:1755904460.306687 2025-08-22T23:14:20.307Z,1755904460.307 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771926 2025-08-22T23:14:20.559Z,1755904460.559 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022914 2025-08-22T23:14:20.813Z,1755904460.813 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.276953 2025-08-22T23:14:21.079Z,1755904461.079 [DAT](INFO): DAT read: 23:14:20.9236 LVL= 12592, 11793, 32754, 27187, AGC= 33, IDX= 426, 0.08, 2.440, 1.275, 0.617, 2.198, PHS= 0.344,-0.878,-1.624, RAW= 67.9, 23.9, CAL= 64.1, 30.7, ROT= 85.9, -30.7 2025-08-22T23:14:21.087Z,1755904461.087 [DAT](INFO): got valid direction response: 23:14:20.9236 LVL= 12592, 11793, 32754, 27187, AGC= 33, IDX= 426, 0.08, 2.440, 1.275, 0.617, 2.198, PHS= 0.344,-0.878,-1.624, RAW= 67.9, 23.9, CAL= 64.1, 30.7, ROT= 85.9, -30.7 2025-08-22T23:14:21.089Z,1755904461.089 [DAT](INFO): DAT read: Bearing 38.8, 61.1 (Local) 2025-08-22T23:14:21.099Z,1755904461.099 [DAT](INFO): Local bearing/azimuth received: Bearing 38.8, 61.1 (Local) 2025-08-22T23:14:21.103Z,1755904461.103 [DAT](INFO): DAT read: Range 11 to 50 : 0.9 m (Round-trip 1.2 ms) speed 0.0 m/s 2025-08-22T23:14:21.105Z,1755904461.105 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:14:21.126Z,1755904461.126 [DAT](INFO): direction in FSK: [0.061477,0.857652,0.510543] 2025-08-22T23:14:21.126Z,1755904461.126 [DAT](INFO): publishing direction and range info 2025-08-22T23:16:17.548Z,1755904577.548 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:16:17.658Z,1755904577.658 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:16:17.659Z,1755904577.659 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:16:17.911Z,1755904577.911 [DAT](INFO): DAT read: user:2912> 2025-08-22T23:16:17.911Z,1755904577.911 [DAT](INFO): DAT read: Tx time:23:16:18.9181 2025-08-22T23:16:17.912Z,1755904577.912 [DAT](INFO): Ping request sent. 2025-08-22T23:16:17.912Z,1755904577.912 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:16:17.912Z,1755904577.912 [DAT](INFO): publishing transmit ping time 2025-08-22T23:16:17.913Z,1755904577.913 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000748 2025-08-22T23:16:18.163Z,1755904578.163 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251227 2025-08-22T23:16:18.415Z,1755904578.415 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503069 2025-08-22T23:16:18.666Z,1755904578.666 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754614 2025-08-22T23:16:18.918Z,1755904578.918 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006638 2025-08-22T23:16:19.171Z,1755904579.171 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259059 2025-08-22T23:16:19.422Z,1755904579.422 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510569 2025-08-22T23:16:19.674Z,1755904579.674 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762628 2025-08-22T23:16:19.927Z,1755904579.927 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014673 2025-08-22T23:16:20.179Z,1755904580.179 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266980 2025-08-22T23:16:20.431Z,1755904580.431 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518781 2025-08-22T23:16:20.683Z,1755904580.683 [DAT](INFO): DAT read: Rx Time:23:16:21.3235 2025-08-22T23:16:20.683Z,1755904580.683 [DAT](INFO): Rx dataTimestamp_ set to:1755904580.682810 2025-08-22T23:16:20.684Z,1755904580.684 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771792 2025-08-22T23:16:20.934Z,1755904580.934 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022609 2025-08-22T23:16:21.190Z,1755904581.190 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.277262 2025-08-22T23:16:21.191Z,1755904581.191 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 69.62, 70.30, 70.30 2025-08-22T23:16:21.444Z,1755904581.444 [DAT](INFO): DAT read: 23:16:21.3235 LVL= 15168, 14977, 32754, 32355, AGC= 32, IDX= 426,-0.18, 1.482, 0.139,-0.399, 0.943, PHS= 0.641,-0.760,-1.386, RAW= 72.4, 16.5, CAL= 70.6, 23.1, ROT= 79.4, -23.1 2025-08-22T23:16:21.445Z,1755904581.445 [DAT](INFO): got valid direction response: 23:16:21.3235 LVL= 15168, 14977, 32754, 32355, AGC= 32, IDX= 426,-0.18, 1.482, 0.139,-0.399, 0.943, PHS= 0.641,-0.760,-1.386, RAW= 72.4, 16.5, CAL= 70.6, 23.1, ROT= 79.4, -23.1 2025-08-22T23:16:21.446Z,1755904581.446 [DAT](INFO): DAT read: Bearing 22.1, 66.4 (Local) 2025-08-22T23:16:21.446Z,1755904581.446 [DAT](INFO): Local bearing/azimuth received: Bearing 22.1, 66.4 (Local) 2025-08-22T23:16:21.447Z,1755904581.447 [DAT](INFO): DAT read: Range 11 to 50 : 0.9 m (Round-trip 1.2 ms) speed 0.0 m/s 2025-08-22T23:16:21.448Z,1755904581.448 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:16:21.449Z,1755904581.449 [DAT](INFO): direction in FSK: [0.169202,0.904125,0.392337] 2025-08-22T23:16:21.449Z,1755904581.449 [DAT](INFO): publishing direction and range info 2025-08-22T23:17:09.643Z,1755904629.643 [BPC1](FAULT): Charging on dock, ignoring failure to receieve battery data and resetting batt data check 2025-08-22T23:17:10.517Z,1755904630.517 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:17:22.245Z,1755904642.245 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:17:33.996Z,1755904653.996 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:17:44.386Z,1755904664.386 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-08-22T23:17:44.390Z,1755904664.390 [BPC1](ERROR): BPC1A failed to parse battery stick message. 2025-08-22T23:17:56.181Z,1755904676.181 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:18:08.290Z,1755904688.290 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:18:17.963Z,1755904697.963 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:18:18.004Z,1755904698.004 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:18:18.005Z,1755904698.005 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:18:18.254Z,1755904698.254 [DAT](INFO): DAT read: user:2913> 2025-08-22T23:18:18.255Z,1755904698.255 [DAT](INFO): DAT read: Tx time:23:18:19.2680 2025-08-22T23:18:18.255Z,1755904698.255 [DAT](INFO): Ping request sent. 2025-08-22T23:18:18.256Z,1755904698.256 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:18:18.256Z,1755904698.256 [DAT](INFO): publishing transmit ping time 2025-08-22T23:18:18.256Z,1755904698.256 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000752 2025-08-22T23:18:18.506Z,1755904698.506 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250882 2025-08-22T23:18:18.759Z,1755904698.759 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503061 2025-08-22T23:18:19.010Z,1755904699.010 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754922 2025-08-22T23:18:19.263Z,1755904699.263 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007005 2025-08-22T23:18:19.515Z,1755904699.515 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259022 2025-08-22T23:18:19.766Z,1755904699.766 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510922 2025-08-22T23:18:20.009Z,1755904700.009 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:18:20.019Z,1755904700.019 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763270 2025-08-22T23:18:20.271Z,1755904700.271 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014975 2025-08-22T23:18:20.522Z,1755904700.522 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266913 2025-08-22T23:18:20.774Z,1755904700.774 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518924 2025-08-22T23:18:21.027Z,1755904701.027 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771024 2025-08-22T23:18:21.278Z,1755904701.278 [DAT](INFO): DAT read: Rx Time:23:18:21.6734 2025-08-22T23:18:21.279Z,1755904701.279 [DAT](INFO): Rx dataTimestamp_ set to:1755904701.278679 2025-08-22T23:18:21.279Z,1755904701.279 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023972 2025-08-22T23:18:21.530Z,1755904701.530 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274936 2025-08-22T23:18:21.788Z,1755904701.788 [DAT](INFO): DAT read: 23:18:21.6734 LVL= 15504, 14369, 32754, 29299, AGC= 33, IDX= 427,-0.40,-2.916, 1.746, 1.292, 2.521, PHS= 0.948,-0.730,-1.274, RAW= 76.4, 10.5, CAL= 75.7, 12.9, ROT= 74.3, -12.9 2025-08-22T23:18:21.789Z,1755904701.789 [DAT](INFO): got valid direction response: 23:18:21.6734 LVL= 15504, 14369, 32754, 29299, AGC= 33, IDX= 427,-0.40,-2.916, 1.746, 1.292, 2.521, PHS= 0.948,-0.730,-1.274, RAW= 76.4, 10.5, CAL= 75.7, 12.9, ROT= 74.3, -12.9 2025-08-22T23:18:21.790Z,1755904701.790 [DAT](INFO): DAT read: Bearing 353.3, 71.0 (Local) 2025-08-22T23:18:21.790Z,1755904701.790 [DAT](INFO): Local bearing/azimuth received: Bearing 353.3, 71.0 (Local) 2025-08-22T23:18:21.791Z,1755904701.791 [DAT](INFO): DAT read: Range 11 to 50 : 0.9 m (Round-trip 1.2 ms) speed 0.0 m/s 2025-08-22T23:18:21.792Z,1755904701.792 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:18:21.793Z,1755904701.793 [DAT](INFO): direction in FSK: [0.263771,0.938395,0.223250] 2025-08-22T23:18:21.793Z,1755904701.793 [DAT](INFO): publishing direction and range info 2025-08-22T23:18:31.732Z,1755904711.732 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:18:41.019Z,1755904721.019 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +0.08, -0 7.02, 0.00 2025-08-22T23:18:43.860Z,1755904723.860 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:18:55.564Z,1755904735.564 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:19:07.321Z,1755904747.321 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:19:19.010Z,1755904759.010 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:19:31.129Z,1755904771.129 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:19:51.272Z,1755904791.272 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +0.05, ,0.10, 7.05, 0.00 2025-08-22T23:19:56.107Z,1755904796.107 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,0002280307161710.5, 0.0,10 2025-08-22T23:20:18.400Z,1755904818.400 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:20:18.578Z,1755904818.578 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:20:18.579Z,1755904818.579 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:20:18.830Z,1755904818.830 [DAT](INFO): DAT read: user:2914> 2025-08-22T23:20:18.831Z,1755904818.831 [DAT](INFO): DAT read: Tx time:23:20:19.8179 2025-08-22T23:20:18.831Z,1755904818.831 [DAT](INFO): Ping request sent. 2025-08-22T23:20:18.832Z,1755904818.832 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:20:18.832Z,1755904818.832 [DAT](INFO): publishing transmit ping time 2025-08-22T23:20:18.832Z,1755904818.832 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000745 2025-08-22T23:20:19.082Z,1755904819.082 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250806 2025-08-22T23:20:19.334Z,1755904819.334 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502870 2025-08-22T23:20:19.587Z,1755904819.587 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754929 2025-08-22T23:20:19.838Z,1755904819.838 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006855 2025-08-22T23:20:20.091Z,1755904820.091 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259144 2025-08-22T23:20:20.343Z,1755904820.343 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511047 2025-08-22T23:20:20.594Z,1755904820.594 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762868 2025-08-22T23:20:20.847Z,1755904820.847 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014986 2025-08-22T23:20:21.098Z,1755904821.098 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266868 2025-08-22T23:20:21.350Z,1755904821.350 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518850 2025-08-22T23:20:21.603Z,1755904821.603 [DAT](INFO): DAT read: Rx Time:23:20:22.2233 2025-08-22T23:20:21.603Z,1755904821.603 [DAT](INFO): Rx dataTimestamp_ set to:1755904821.602802 2025-08-22T23:20:21.604Z,1755904821.604 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772019 2025-08-22T23:20:21.855Z,1755904821.855 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022915 2025-08-22T23:20:22.107Z,1755904822.107 [DAT](INFO): DAT read: Range 11 to 50 : 0.9 m (Round-trip 1.2 ms) speed 0.0 m/s 2025-08-22T23:20:22.108Z,1755904822.108 [DAT](ERROR): #Rx 1: Read range message, but no direction. 2025-08-22T23:20:30.082Z,1755904830.082 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:20:43.364Z,1755904843.364 [BPC1](ERROR): BPC1A failed to parse battery stick message. 2025-08-22T23:21:06.398Z,1755904866.398 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00022803082622,35.0, +10.6, 0.0,1492.0, , 0.00, 69.29, 79.63, 69.97 2025-08-22T23:21:39.943Z,1755904899.943 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-22T23:21:41.958Z,1755904901.958 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS6,A 2025-08-22T23:22:16.305Z,1755904936.305 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD,0.06, +0.0 -0.18, 7.04, 0.00 2025-08-22T23:22:18.722Z,1755904938.722 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:22:18.898Z,1755904938.898 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:22:18.898Z,1755904938.898 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:22:19.152Z,1755904939.152 [DAT](INFO): DAT read: user:2915> 2025-08-22T23:22:19.167Z,1755904939.167 [DAT](INFO): DAT read: Tx time:23:22:20.1678 2025-08-22T23:22:19.169Z,1755904939.169 [DAT](INFO): Ping request sent. 2025-08-22T23:22:19.169Z,1755904939.169 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:22:19.170Z,1755904939.170 [DAT](INFO): publishing transmit ping time 2025-08-22T23:22:19.172Z,1755904939.172 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.002663 2025-08-22T23:22:19.403Z,1755904939.403 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.234169 2025-08-22T23:22:19.654Z,1755904939.654 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.485975 2025-08-22T23:22:19.909Z,1755904939.909 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.740092 2025-08-22T23:22:20.165Z,1755904940.165 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.996083 2025-08-22T23:22:20.416Z,1755904940.416 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.246852 2025-08-22T23:22:20.666Z,1755904940.666 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.497979 2025-08-22T23:22:20.919Z,1755904940.919 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.750102 2025-08-22T23:22:21.174Z,1755904941.174 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.004291 2025-08-22T23:22:21.422Z,1755904941.422 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.253971 2025-08-22T23:22:21.675Z,1755904941.675 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.506016 2025-08-22T23:22:21.931Z,1755904941.931 [DAT](INFO): DAT read: Rx Time:23:22:22.5732 2025-08-22T23:22:21.931Z,1755904941.931 [DAT](INFO): Rx dataTimestamp_ set to:1755904941.930970 2025-08-22T23:22:21.932Z,1755904941.932 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.763301 2025-08-22T23:22:22.185Z,1755904942.185 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.016373 2025-08-22T23:22:22.435Z,1755904942.435 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.266100 2025-08-22T23:22:22.696Z,1755904942.696 [DAT](INFO): DAT read: 23:22:22.5732 LVL= 14224, 13745, 32754, 29651, AGC= 33, IDX= 426, 0.45, 0.402,-1.074,-1.576,-0.240, PHS= 0.744,-0.789,-1.380, RAW= 74.4, 14.9, CAL= 73.3, 21.0, ROT= 76.7, -21.0 2025-08-22T23:22:22.697Z,1755904942.697 [DAT](INFO): got valid direction response: 23:22:22.5732 LVL= 14224, 13745, 32754, 29651, AGC= 33, IDX= 426, 0.45, 0.402,-1.074,-1.576,-0.240, PHS= 0.744,-0.789,-1.380, RAW= 74.4, 14.9, CAL= 73.3, 21.0, ROT= 76.7, -21.0 2025-08-22T23:22:22.699Z,1755904942.699 [DAT](INFO): DAT read: Bearing 19.1, 67.0 (Local) 2025-08-22T23:22:22.700Z,1755904942.700 [DAT](INFO): Local bearing/azimuth received: Bearing 19.1, 67.0 (Local) 2025-08-22T23:22:22.701Z,1755904942.701 [DAT](INFO): DAT read: Range 11 to 50 : 0.9 m (Round-trip 1.2 ms) speed 0.0 m/s 2025-08-22T23:22:22.702Z,1755904942.702 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:22:22.703Z,1755904942.703 [DAT](INFO): direction in FSK: [0.214770,0.908541,0.358368] 2025-08-22T23:22:22.703Z,1755904942.703 [DAT](INFO): publishing direction and range info 2025-08-22T23:23:26.242Z,1755905006.242 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout 2025-08-22T23:23:26.242Z,1755905006.242 [RDI_Pathfinder] Communications Fault, FailCount= 1 2025-08-22T23:23:26.242Z,1755905006.242 [RDI_Pathfinder](ERROR): Communications Fault 2025-08-22T23:23:26.311Z,1755905006.311 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2025-08-22T23:23:26.580Z,1755905006.580 [RDI_Pathfinder](INFO): Powering down 2025-08-22T23:23:27.459Z,1755905007.459 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2025-08-22T23:23:27.459Z,1755905007.459 [RDI_Pathfinder] No Fault, FailCount= 1 2025-08-22T23:23:27.804Z,1755905007.804 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-08-22T23:24:18.765Z,1755905058.765 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:24:18.954Z,1755905058.954 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:24:18.955Z,1755905058.955 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:24:19.210Z,1755905059.210 [DAT](INFO): DAT read: user:2916> 2025-08-22T23:24:19.211Z,1755905059.211 [DAT](INFO): DAT read: Tx time:23:24:20.2177 2025-08-22T23:24:19.211Z,1755905059.211 [DAT](INFO): Ping request sent. 2025-08-22T23:24:19.211Z,1755905059.211 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:24:19.211Z,1755905059.211 [DAT](INFO): publishing transmit ping time 2025-08-22T23:24:19.212Z,1755905059.212 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000755 2025-08-22T23:24:19.459Z,1755905059.459 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.247281 2025-08-22T23:24:19.711Z,1755905059.711 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.499259 2025-08-22T23:24:19.963Z,1755905059.963 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.751563 2025-08-22T23:24:20.214Z,1755905060.214 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.003216 2025-08-22T23:24:20.466Z,1755905060.466 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.255146 2025-08-22T23:24:20.719Z,1755905060.719 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.507266 2025-08-22T23:24:20.971Z,1755905060.971 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.759226 2025-08-22T23:24:21.224Z,1755905061.224 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.012620 2025-08-22T23:24:21.474Z,1755905061.474 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.263173 2025-08-22T23:24:21.727Z,1755905061.727 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.515281 2025-08-22T23:24:21.979Z,1755905061.979 [DAT](INFO): DAT read: Rx Time:23:24:22.6231 2025-08-22T23:24:21.980Z,1755905061.980 [DAT](INFO): Rx dataTimestamp_ set to:1755905061.979292 2025-08-22T23:24:21.980Z,1755905061.980 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.768851 2025-08-22T23:24:22.231Z,1755905062.231 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.019222 2025-08-22T23:24:22.484Z,1755905062.484 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.272778 2025-08-22T23:24:22.744Z,1755905062.744 [DAT](INFO): DAT read: 23:24:22.6231 LVL= 15184, 15473, 32754, 32755, AGC= 34, IDX= 427,-0.36, 1.195,-0.176,-0.675, 0.572, PHS= 0.725,-0.704,-1.291, RAW= 73.5, 14.0, CAL= 72.5, 20.2, ROT= 77.5, -20.2 2025-08-22T23:24:22.745Z,1755905062.745 [DAT](INFO): got valid direction response: 23:24:22.6231 LVL= 15184, 15473, 32754, 32755, AGC= 34, IDX= 427,-0.36, 1.195,-0.176,-0.675, 0.572, PHS= 0.725,-0.704,-1.291, RAW= 73.5, 14.0, CAL= 72.5, 20.2, ROT= 77.5, -20.2 2025-08-22T23:24:22.746Z,1755905062.746 [DAT](INFO): DAT read: Bearing 15.4, 68.2 (Local) 2025-08-22T23:24:22.746Z,1755905062.746 [DAT](INFO): Local bearing/azimuth received: Bearing 15.4, 68.2 (Local) 2025-08-22T23:24:22.747Z,1755905062.747 [DAT](INFO): DAT read: Range 11 to 50 : 0.9 m (Round-trip 1.2 ms) speed 0.0 m/s 2025-08-22T23:24:22.748Z,1755905062.748 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:24:22.749Z,1755905062.749 [DAT](INFO): direction in FSK: [0.203127,0.916247,0.345298] 2025-08-22T23:24:22.755Z,1755905062.755 [DAT](INFO): publishing direction and range info 2025-08-22T23:26:06.577Z,1755905166.577 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for beam range. Device response is::RA, 61.32, 70.31, 70.64 2025-08-22T23:26:19.100Z,1755905179.100 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:26:19.306Z,1755905179.306 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:26:19.306Z,1755905179.306 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:26:19.559Z,1755905179.559 [DAT](INFO): DAT read: user:2917> 2025-08-22T23:26:19.559Z,1755905179.559 [DAT](INFO): DAT read: Tx time:23:26:20.5676 2025-08-22T23:26:19.560Z,1755905179.560 [DAT](INFO): Ping request sent. 2025-08-22T23:26:19.560Z,1755905179.560 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:26:19.560Z,1755905179.560 [DAT](INFO): publishing transmit ping time 2025-08-22T23:26:19.561Z,1755905179.561 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000746 2025-08-22T23:26:19.811Z,1755905179.811 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250788 2025-08-22T23:26:20.063Z,1755905180.063 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502729 2025-08-22T23:26:20.315Z,1755905180.315 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754873 2025-08-22T23:26:20.567Z,1755905180.567 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006838 2025-08-22T23:26:20.819Z,1755905180.819 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258771 2025-08-22T23:26:21.070Z,1755905181.070 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510716 2025-08-22T23:26:21.328Z,1755905181.328 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.767897 2025-08-22T23:26:21.577Z,1755905181.577 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.016831 2025-08-22T23:26:21.826Z,1755905181.826 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266734 2025-08-22T23:26:22.079Z,1755905182.079 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518764 2025-08-22T23:26:22.331Z,1755905182.331 [DAT](INFO): DAT read: Rx Time:23:26:22.9731 2025-08-22T23:26:22.331Z,1755905182.331 [DAT](INFO): Rx dataTimestamp_ set to:1755905182.331182 2025-08-22T23:26:22.332Z,1755905182.332 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772259 2025-08-22T23:26:22.585Z,1755905182.585 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024570 2025-08-22T23:26:22.837Z,1755905182.837 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.276792 2025-08-22T23:26:23.087Z,1755905183.087 [DAT](INFO): DAT read: Range 11 to 50 : 0.9 m (Round-trip 1.3 ms) speed 0.0 m/s 2025-08-22T23:26:23.088Z,1755905183.088 [DAT](ERROR): #Rx 1: Read range message, but no direction. 2025-08-22T23:26:42.933Z,1755905202.933 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:26:50.203Z,1755905210.203 [BPC1](FAULT): Charging on dock, ignoring failure to receieve battery data and resetting batt data check 2025-08-22T23:26:53.891Z,1755905213.891 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:27:04.800Z,1755905224.800 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:27:15.704Z,1755905235.704 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:27:26.650Z,1755905246.650 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:27:37.520Z,1755905257.520 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:27:48.438Z,1755905268.438 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:27:59.340Z,1755905279.340 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:28:08.575Z,1755905288.575 [BPC1](ERROR): BPC1A got IPBS message with 4 sticks (min is 7). 2025-08-22T23:28:08.579Z,1755905288.579 [BPC1](ERROR): BPC1A failed to parse battery stick message. 2025-08-22T23:28:19.540Z,1755905299.540 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:28:19.586Z,1755905299.586 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:28:19.639Z,1755905299.639 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:28:19.639Z,1755905299.639 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:28:19.890Z,1755905299.890 [DAT](INFO): DAT read: user:2918> 2025-08-22T23:28:19.891Z,1755905299.891 [DAT](INFO): DAT read: Tx time:23:28:20.8675 2025-08-22T23:28:19.892Z,1755905299.892 [DAT](INFO): Ping request sent. 2025-08-22T23:28:19.892Z,1755905299.892 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:28:19.892Z,1755905299.892 [DAT](INFO): publishing transmit ping time 2025-08-22T23:28:19.892Z,1755905299.892 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000748 2025-08-22T23:28:20.143Z,1755905300.143 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250927 2025-08-22T23:28:20.395Z,1755905300.395 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502884 2025-08-22T23:28:20.647Z,1755905300.647 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754932 2025-08-22T23:28:20.898Z,1755905300.898 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006838 2025-08-22T23:28:21.151Z,1755905301.151 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259037 2025-08-22T23:28:21.403Z,1755905301.403 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510900 2025-08-22T23:28:21.654Z,1755905301.654 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762832 2025-08-22T23:28:21.906Z,1755905301.906 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014728 2025-08-22T23:28:22.159Z,1755905302.159 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267408 2025-08-22T23:28:22.411Z,1755905302.411 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519030 2025-08-22T23:28:22.663Z,1755905302.663 [DAT](INFO): DAT read: Rx Time:23:28:23.2729 2025-08-22T23:28:22.663Z,1755905302.663 [DAT](INFO): Rx dataTimestamp_ set to:1755905302.662906 2025-08-22T23:28:22.664Z,1755905302.664 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772085 2025-08-22T23:28:22.915Z,1755905302.915 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022852 2025-08-22T23:28:23.170Z,1755905303.170 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.277102 2025-08-22T23:28:23.424Z,1755905303.424 [DAT](INFO): DAT read: 23:28:23.2729 LVL= 15392, 14305, 32754, 29171, AGC= 32, IDX= 442, 0.05,-1.794, 2.726, 2.286,-2.739, PHS= 1.047,-0.772,-1.302, RAW= 77.6, 9.7, CAL= 76.8, 11.0, ROT= 73.2, -11.0 2025-08-22T23:28:23.425Z,1755905303.425 [DAT](INFO): got valid direction response: 23:28:23.2729 LVL= 15392, 14305, 32754, 29171, AGC= 32, IDX= 442, 0.05,-1.794, 2.726, 2.286,-2.739, PHS= 1.047,-0.772,-1.302, RAW= 77.6, 9.7, CAL= 76.8, 11.0, ROT= 73.2, -11.0 2025-08-22T23:28:23.426Z,1755905303.426 [DAT](INFO): DAT read: Bearing 352.4, 70.6 (Local) 2025-08-22T23:28:23.426Z,1755905303.426 [DAT](INFO): Local bearing/azimuth received: Bearing 352.4, 70.6 (Local) 2025-08-22T23:28:23.427Z,1755905303.427 [DAT](INFO): DAT read: Range 11 to 50 : 0.9 m (Round-trip 1.2 ms) speed 0.0 m/s 2025-08-22T23:28:23.428Z,1755905303.428 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:28:23.429Z,1755905303.429 [DAT](INFO): direction in FSK: [0.283722,0.939731,0.190809] 2025-08-22T23:28:23.429Z,1755905303.429 [DAT](INFO): publishing direction and range info 2025-08-22T23:28:30.504Z,1755905310.504 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:28:41.363Z,1755905321.363 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:28:52.269Z,1755905332.269 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:29:03.178Z,1755905343.178 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-22T23:29:24.515Z,1755905364.515 [CTD_Seabird](ERROR): Failed to parse device response: .45 2025-08-22T23:30:19.895Z,1755905419.895 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-22T23:30:19.966Z,1755905419.966 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:30:19.966Z,1755905419.966 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:30:20.214Z,1755905420.214 [DAT](INFO): DAT read: user:2919> 2025-08-22T23:30:20.215Z,1755905420.215 [DAT](INFO): DAT read: Tx time:23:30:21.2174 2025-08-22T23:30:20.215Z,1755905420.215 [DAT](INFO): Ping request sent. 2025-08-22T23:30:20.216Z,1755905420.216 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:30:20.216Z,1755905420.216 [DAT](INFO): publishing transmit ping time 2025-08-22T23:30:20.216Z,1755905420.216 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000751 2025-08-22T23:30:20.467Z,1755905420.467 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250959 2025-08-22T23:30:20.719Z,1755905420.719 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503000 2025-08-22T23:30:20.970Z,1755905420.970 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754828 2025-08-22T23:30:21.223Z,1755905421.223 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006954 2025-08-22T23:30:21.474Z,1755905421.474 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258865 2025-08-22T23:30:21.726Z,1755905421.726 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510908 2025-08-22T23:30:21.979Z,1755905421.979 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763014 2025-08-22T23:30:22.230Z,1755905422.230 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014880 2025-08-22T23:30:22.482Z,1755905422.482 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266901 2025-08-22T23:30:22.735Z,1755905422.735 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519041 2025-08-22T23:30:22.987Z,1755905422.987 [DAT](INFO): DAT read: Rx Time:23:30:23.6231 2025-08-22T23:30:22.987Z,1755905422.987 [DAT](INFO): Rx dataTimestamp_ set to:1755905422.986714 2025-08-22T23:30:22.988Z,1755905422.988 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771968 2025-08-22T23:30:23.239Z,1755905423.239 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023779 2025-08-22T23:30:23.502Z,1755905423.502 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.277371 2025-08-22T23:30:23.748Z,1755905423.748 [DAT](INFO): DAT read: 23:30:23.6231 LVL= 15568, 14785, 32754, 29779, AGC= 35, IDX= 427,-0.33,-0.489,-1.720,-2.208,-0.916, PHS= 0.528,-0.759,-1.336, RAW= 72.4, 18.5, CAL= 70.2, 25.3, ROT= 79.8, -25.3 2025-08-22T23:30:23.749Z,1755905423.749 [DAT](INFO): got valid direction response: 23:30:23.6231 LVL= 15568, 14785, 32754, 29779, AGC= 35, IDX= 427,-0.33,-0.489,-1.720,-2.208,-0.916, PHS= 0.528,-0.759,-1.336, RAW= 72.4, 18.5, CAL= 70.2, 25.3, ROT= 79.8, -25.3 2025-08-22T23:30:23.750Z,1755905423.750 [DAT](INFO): DAT read: Bearing 29.6, 64.7 (Local) 2025-08-22T23:30:23.750Z,1755905423.750 [DAT](INFO): Local bearing/azimuth received: Bearing 29.6, 64.7 (Local) 2025-08-22T23:30:23.751Z,1755905423.751 [DAT](INFO): DAT read: Range 11 to 50 : 1.2 m (Round-trip 1.6 ms) speed 0.0 m/s 2025-08-22T23:30:23.752Z,1755905423.752 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:30:23.753Z,1755905423.753 [DAT](INFO): direction in FSK: [0.160099,0.889794,0.427358] 2025-08-22T23:30:23.753Z,1755905423.753 [DAT](INFO): publishing direction and range info 2025-08-22T23:31:40.300Z,1755905500.300 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-22T23:31:56.835Z,1755905516.835 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-22T23:31:56.836Z,1755905516.836 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-22T23:31:56.837Z,1755905516.837 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-22T23:31:56.837Z,1755905516.837 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-22T23:31:56.838Z,1755905516.838 [BPC1](ERROR): Battery stick #44 (s/n: 00E4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-22T23:31:56.839Z,1755905516.839 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-22T23:31:56.840Z,1755905516.840 [BPC1](INFO): Calculating totals. Valid battery stick count: 7. Valid reserve battery stick count: 0. 2025-08-22T23:31:56.843Z,1755905516.843 [BPC1](CRITICAL): Battery Capacity Below Threshold. 27.690000 Ah. 2025-08-22T23:31:56.846Z,1755905516.846 [BPC1](FAULT): Failed to receive data from 29 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 26, 27, 32, 33, 34, 35, 36, 37, 38, 39, 44, 58. 2025-08-22T23:31:56.988Z,1755905516.988 [CommandExec](FAULT): Scheduling is paused 2025-08-22T23:31:56.989Z,1755905516.989 [CBIT](INFO): Critical error at 20250822T233156 2025-08-22T23:31:56.989Z,1755905516.989 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-08-22T23:31:57.266Z,1755905517.266 [MissionManager](INFO): MissionManager is completed. 2025-08-22T23:31:57.266Z,1755905517.266 [MissionManager](INFO): Uninitializing Mission OnDock 2025-08-22T23:31:57.266Z,1755905517.266 [OnDock] Stopped 2025-08-22T23:31:57.266Z,1755905517.266 [OnDock](DEBUG): Aggregate::uninitialize OnDock 2025-08-22T23:31:57.266Z,1755905517.266 [OnDock:StandardEnvelopes] Stopped 2025-08-22T23:31:57.266Z,1755905517.266 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes 2025-08-22T23:31:57.266Z,1755905517.266 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-08-22T23:31:57.266Z,1755905517.266 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-08-22T23:31:57.266Z,1755905517.266 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-08-22T23:31:57.267Z,1755905517.267 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-08-22T23:31:57.267Z,1755905517.267 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-08-22T23:31:57.267Z,1755905517.267 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-08-22T23:31:57.267Z,1755905517.267 [OnDock:BackseatDriver] Stopped 2025-08-22T23:31:57.267Z,1755905517.267 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver 2025-08-22T23:31:57.267Z,1755905517.267 [OnDock:BackseatDriver:A.BackseatDriver] Stopped 2025-08-22T23:31:57.267Z,1755905517.267 [OnDock:PowerOnly] Stopped 2025-08-22T23:31:57.267Z,1755905517.267 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly 2025-08-22T23:31:57.267Z,1755905517.267 [OnDock:PowerOnly:E.Wait] Stopped 2025-08-22T23:31:57.267Z,1755905517.267 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-08-22T23:31:57.267Z,1755905517.267 [OnDock:dataRead] Stopped 2025-08-22T23:31:57.267Z,1755905517.267 [OnDock:dataRead](DEBUG): Aggregate::uninitialize OnDock:dataRead 2025-08-22T23:31:57.268Z,1755905517.268 [OnDock:dataRead:A] Stopped 2025-08-22T23:31:57.268Z,1755905517.268 [OnDock:J.] Stopped 2025-08-22T23:31:57.268Z,1755905517.268 [OnDock:J.](DEBUG): Uninitializing TrackAcousticContact. 2025-08-22T23:31:57.268Z,1755905517.268 [OnDock:PeriodicCheck] Stopped 2025-08-22T23:31:57.268Z,1755905517.268 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck 2025-08-22T23:31:57.268Z,1755905517.268 [OnDock:PeriodicCheck:CheckIP] Stopped 2025-08-22T23:31:57.268Z,1755905517.268 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP 2025-08-22T23:31:57.268Z,1755905517.268 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped 2025-08-22T23:31:57.268Z,1755905517.268 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component. 2025-08-22T23:31:57.268Z,1755905517.268 [OnDock:N.Docked] Stopped 2025-08-22T23:31:57.554Z,1755905517.554 [DAT](INFO): DAT read: user:2920> 2025-08-22T23:31:57.555Z,1755905517.555 [DAT](INFO): DAT read: Tx time:23:31:58.5663 2025-08-22T23:31:57.556Z,1755905517.556 [DAT](INFO): Ping request sent. 2025-08-22T23:31:57.556Z,1755905517.556 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:31:57.556Z,1755905517.556 [DAT](INFO): publishing transmit ping time 2025-08-22T23:31:57.688Z,1755905517.688 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-08-22T23:31:57.688Z,1755905517.688 [DefaultWithUndock] Running Loop=1 2025-08-22T23:31:57.689Z,1755905517.689 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-08-22T23:31:57.689Z,1755905517.689 [DefaultWithUndock:A.Wait] Running Loop=1 2025-08-22T23:31:57.689Z,1755905517.689 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-08-22T23:31:59.823Z,1755905519.823 [DAT](INFO): DAT read: Rx Time:23:32:00.4391 2025-08-22T23:31:59.823Z,1755905519.823 [DAT](INFO): Rx dataTimestamp_ set to:1755905519.822742 2025-08-22T23:32:00.584Z,1755905520.584 [DAT](INFO): DAT read: 23:32:00.4391 LVL= 13488, 13249, 32754, 22867, AGC= 35, IDX= 115,-0.08,-1.781, 2.889, 2.455,-2.610, PHS= 0.930,-0.740,-1.262, RAW= 76.8, 10.8, CAL= 76.1, 13.3, ROT= 73.9, -13.3 2025-08-22T23:32:00.586Z,1755905520.586 [DAT](INFO): got valid direction response: 23:32:00.4391 LVL= 13488, 13249, 32754, 22867, AGC= 35, IDX= 115,-0.08,-1.781, 2.889, 2.455,-2.610, PHS= 0.930,-0.740,-1.262, RAW= 76.8, 10.8, CAL= 76.1, 13.3, ROT= 73.9, -13.3 2025-08-22T23:32:00.587Z,1755905520.587 [DAT](INFO): DAT read: OK 2025-08-22T23:32:00.587Z,1755905520.587 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T23:32:00.588Z,1755905520.588 [DAT](INFO): DAT read: user:2921> 2025-08-22T23:32:00.589Z,1755905520.589 [DAT](INFO): DAT read: Tx time:23:32:01.5663 2025-08-22T23:32:00.589Z,1755905520.589 [DAT](INFO): Ping request sent. 2025-08-22T23:32:00.590Z,1755905520.590 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:32:00.590Z,1755905520.590 [DAT](INFO): #Rx 2: Read direction message, but no range. 2025-08-22T23:32:00.591Z,1755905520.591 [DAT](INFO): direction in FSK: [0.269877,0.935010,0.230050] 2025-08-22T23:32:00.592Z,1755905520.592 [DAT](INFO): publishing transmit ping time 2025-08-22T23:32:00.627Z,1755905520.627 [DAT](INFO): publishing transmit ping time 2025-08-22T23:32:02.859Z,1755905522.859 [DAT](INFO): DAT read: Rx Time:23:32:03.4392 2025-08-22T23:32:02.859Z,1755905522.859 [DAT](INFO): Rx dataTimestamp_ set to:1755905522.859089 2025-08-22T23:32:03.358Z,1755905523.358 [DAT](INFO): DAT read: OK 2025-08-22T23:32:03.359Z,1755905523.359 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T23:32:03.359Z,1755905523.359 [DAT](INFO): DAT read: user:2922> 2025-08-22T23:32:03.611Z,1755905523.611 [DAT](INFO): DAT read: Tx time:23:32:04.5662 2025-08-22T23:32:03.611Z,1755905523.611 [DAT](INFO): Ping request sent. 2025-08-22T23:32:03.611Z,1755905523.611 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:32:03.611Z,1755905523.611 [DAT](INFO): publishing transmit ping time 2025-08-22T23:32:05.883Z,1755905525.883 [DAT](INFO): DAT read: Rx Time:23:32:06.4391 2025-08-22T23:32:05.883Z,1755905525.883 [DAT](INFO): Rx dataTimestamp_ set to:1755905525.882742 2025-08-22T23:32:06.390Z,1755905526.390 [DAT](INFO): DAT read: 23:32:06.4391 LVL= 16672, 16721, 32754, 32755, AGC= 35, IDX= 421,-0.31,-2.562, 2.019, 1.618, 2.729, PHS= 1.094,-0.665,-1.155, RAW= 78.0, 7.1, CAL= 77.1, 6.1, ROT= 72.9, -6.1 2025-08-22T23:32:06.391Z,1755905526.391 [DAT](INFO): got valid direction response: 23:32:06.4391 LVL= 16672, 16721, 32754, 32755, AGC= 35, IDX= 421,-0.31,-2.562, 2.019, 1.618, 2.729, PHS= 1.094,-0.665,-1.155, RAW= 78.0, 7.1, CAL= 77.1, 6.1, ROT= 72.9, -6.1 2025-08-22T23:32:06.392Z,1755905526.392 [DAT](INFO): DAT read: OK 2025-08-22T23:32:06.392Z,1755905526.392 [DAT](INFO): unknown deviceResponse_: OK 2025-08-22T23:32:06.392Z,1755905526.392 [DAT](INFO): #Rx 3: Read direction message, but no range. 2025-08-22T23:32:06.394Z,1755905526.394 [DAT](INFO): direction in FSK: [0.292375,0.950381,0.106264] 2025-08-22T23:32:27.989Z,1755905547.989 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2025-08-22T23:32:27.989Z,1755905547.989 [DefaultWithUndock:A.Wait] Stopped 2025-08-22T23:32:27.990Z,1755905547.990 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-08-22T23:32:27.990Z,1755905547.990 [DefaultWithUndock:LeaveDock] Running Loop=1 2025-08-22T23:32:27.990Z,1755905547.990 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2025-08-22T23:32:27.990Z,1755905547.990 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-08-22T23:32:27.990Z,1755905547.990 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2025-08-22T23:32:27.990Z,1755905547.990 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2025-08-22T23:32:27.990Z,1755905547.990 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-08-22T23:32:27.990Z,1755905547.990 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-22T23:32:28.358Z,1755905548.358 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-08-22T23:32:28.358Z,1755905548.358 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-22T23:32:28.359Z,1755905548.359 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock at depth 66.465599 m. 2025-08-22T23:32:28.360Z,1755905548.360 [DefaultWithUndock:LeaveDock:A.](ERROR): Failed to read sound speed 2025-08-22T23:32:28.816Z,1755905548.816 [DockingStepper](INFO): Changing to mode: 3 2025-08-22T23:32:31.287Z,1755905551.287 [CTD_Seabird](ERROR): Failed to parse device response: 2025-08-22T23:32:34.435Z,1755905554.435 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:34.435Z,1755905554.435 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Engaging thruster for 10.0 seconds (max) to disengage. 2025-08-22T23:32:34.827Z,1755905554.827 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:35.245Z,1755905555.245 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:35.638Z,1755905555.638 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:36.117Z,1755905556.117 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:36.469Z,1755905556.469 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:36.843Z,1755905556.843 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:37.269Z,1755905557.269 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:37.671Z,1755905557.671 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:38.138Z,1755905558.138 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:38.139Z,1755905558.139 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-22T23:32:38.152Z,1755905558.152 [DAT](DEBUG): Acoustic response timeout 2025-08-22T23:32:38.152Z,1755905558.152 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:32:38.402Z,1755905558.402 [DAT](INFO): DAT read: user:2923> 2025-08-22T23:32:38.403Z,1755905558.403 [DAT](INFO): DAT read: Tx time:23:32:39.4171 2025-08-22T23:32:38.403Z,1755905558.403 [DAT](INFO): Ping request sent. 2025-08-22T23:32:38.404Z,1755905558.404 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:32:38.404Z,1755905558.404 [DAT](INFO): publishing transmit ping time 2025-08-22T23:32:38.404Z,1755905558.404 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000767 2025-08-22T23:32:38.497Z,1755905558.497 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:38.655Z,1755905558.655 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251601 2025-08-22T23:32:38.874Z,1755905558.874 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:38.907Z,1755905558.907 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503392 2025-08-22T23:32:39.160Z,1755905559.160 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755819 2025-08-22T23:32:39.304Z,1755905559.304 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:39.413Z,1755905559.413 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.009135 2025-08-22T23:32:39.663Z,1755905559.663 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259013 2025-08-22T23:32:39.673Z,1755905559.673 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:39.915Z,1755905559.915 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510909 2025-08-22T23:32:40.135Z,1755905560.135 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:40.167Z,1755905560.167 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763151 2025-08-22T23:32:40.419Z,1755905560.419 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014897 2025-08-22T23:32:40.521Z,1755905560.521 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:40.671Z,1755905560.671 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266924 2025-08-22T23:32:40.882Z,1755905560.882 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:40.923Z,1755905560.923 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518946 2025-08-22T23:32:41.175Z,1755905561.175 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771548 2025-08-22T23:32:41.312Z,1755905561.312 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:41.427Z,1755905561.427 [DAT](INFO): DAT read: Rx Time:23:32:41.8222 2025-08-22T23:32:41.427Z,1755905561.427 [DAT](INFO): Rx dataTimestamp_ set to:1755905561.426730 2025-08-22T23:32:41.428Z,1755905561.428 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023975 2025-08-22T23:32:41.679Z,1755905561.679 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274945 2025-08-22T23:32:41.749Z,1755905561.749 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-22T23:32:41.939Z,1755905561.939 [DAT](INFO): DAT read: 23:32:41.8222 LVL= 32752, 24401, 32754, 32755, AGC= 36, IDX= 230, 0.33,-2.409,-2.099,-2.780,-1.314, PHS=-0.993,-0.740,-1.510, RAW= 11.2, 59.3, CAL= 10.3, 62.6, ROT= 139.7, -62.6 2025-08-22T23:32:41.940Z,1755905561.940 [DAT](INFO): got valid direction response: 23:32:41.8222 LVL= 32752, 24401, 32754, 32755, AGC= 36, IDX= 230, 0.33,-2.409,-2.099,-2.780,-1.314, PHS=-0.993,-0.740,-1.510, RAW= 11.2, 59.3, CAL= 10.3, 62.6, ROT= 139.7, -62.6 2025-08-22T23:32:41.941Z,1755905561.941 [DAT](INFO): DAT read: Bearing 74.4, 24.7 (Local) 2025-08-22T23:32:41.942Z,1755905561.942 [DAT](INFO): Local bearing/azimuth received: Bearing 74.4, 24.7 (Local) 2025-08-22T23:32:41.943Z,1755905561.943 [DAT](INFO): DAT read: Range 11 to 50 : 0.6 m (Round-trip 0.9 ms) speed 0.0 m/s 2025-08-22T23:32:41.944Z,1755905561.944 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:32:41.945Z,1755905561.945 [DAT](INFO): direction in FSK: [-0.350980,0.297653,0.887815] 2025-08-22T23:32:41.945Z,1755905561.945 [DAT](INFO): publishing direction and range info 2025-08-22T23:32:44.547Z,1755905564.547 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Thruster off. 2025-08-22T23:32:48.154Z,1755905568.154 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-22T23:32:48.237Z,1755905568.237 [DAT](INFO): ****** received valid address query ****** 2025-08-22T23:32:48.237Z,1755905568.237 [DAT](INFO): ****** received valid ping request ****** 2025-08-22T23:32:48.238Z,1755905568.238 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:32:48.486Z,1755905568.486 [DAT](INFO): DAT read: user:2924> 2025-08-22T23:32:48.487Z,1755905568.487 [DAT](INFO): DAT read: Tx time:23:32:49.4671 2025-08-22T23:32:48.487Z,1755905568.487 [DAT](INFO): Ping request sent. 2025-08-22T23:32:48.488Z,1755905568.488 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:32:48.488Z,1755905568.488 [DAT](INFO): publishing transmit ping time 2025-08-22T23:32:48.488Z,1755905568.488 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000749 2025-08-22T23:32:48.738Z,1755905568.738 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250871 2025-08-22T23:32:48.991Z,1755905568.991 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503032 2025-08-22T23:32:49.245Z,1755905569.245 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.757039 2025-08-22T23:32:49.494Z,1755905569.494 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006866 2025-08-22T23:32:49.747Z,1755905569.747 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259053 2025-08-22T23:32:49.999Z,1755905569.999 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511360 2025-08-22T23:32:50.255Z,1755905570.255 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.765404 2025-08-22T23:32:50.506Z,1755905570.506 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.017948 2025-08-22T23:32:50.754Z,1755905570.754 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266853 2025-08-22T23:32:51.007Z,1755905571.007 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519012 2025-08-22T23:32:51.261Z,1755905571.261 [DAT](INFO): DAT read: Rx Time:23:32:51.8735 2025-08-22T23:32:51.262Z,1755905571.262 [DAT](INFO): Rx dataTimestamp_ set to:1755905571.261536 2025-08-22T23:32:51.264Z,1755905571.264 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.775959 2025-08-22T23:32:51.510Z,1755905571.510 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022897 2025-08-22T23:32:51.765Z,1755905571.765 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.277001 2025-08-22T23:32:52.020Z,1755905572.020 [DAT](INFO): DAT read: 23:32:51.8735 LVL= 17600, 28209, 19378, 32755, AGC= 34, IDX= 436,-0.24, 2.032, 0.144, 1.693, 1.687, PHS= 0.446,-1.498,-0.038, RAW= 136.2, 12.4, CAL= 135.5, 13.5, ROT= 14.5, -13.5 2025-08-22T23:32:52.021Z,1755905572.021 [DAT](INFO): got valid direction response: 23:32:51.8735 LVL= 17600, 28209, 19378, 32755, AGC= 34, IDX= 436,-0.24, 2.032, 0.144, 1.693, 1.687, PHS= 0.446,-1.498,-0.038, RAW= 136.2, 12.4, CAL= 135.5, 13.5, ROT= 14.5, -13.5 2025-08-22T23:32:52.022Z,1755905572.022 [DAT](INFO): DAT read: Bearing 324.8, 15.3 (Local) 2025-08-22T23:32:52.022Z,1755905572.022 [DAT](INFO): Local bearing/azimuth received: Bearing 324.8, 15.3 (Local) 2025-08-22T23:32:52.023Z,1755905572.023 [DAT](INFO): DAT read: Range 11 to 50 : 1.6 m (Round-trip 2.2 ms) speed -0.2 m/s 2025-08-22T23:32:52.024Z,1755905572.024 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:32:52.025Z,1755905572.025 [DAT](INFO): direction in FSK: [0.941398,0.243462,0.233445] 2025-08-22T23:32:52.025Z,1755905572.025 [DAT](INFO): publishing direction and range info 2025-08-22T23:32:58.259Z,1755905578.259 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-22T23:32:58.314Z,1755905578.314 [DAT](INFO): ****** received valid address query ****** 2025-08-22T23:32:58.315Z,1755905578.315 [DAT](INFO): ****** received valid ping request ****** 2025-08-22T23:32:58.315Z,1755905578.315 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:32:58.566Z,1755905578.566 [DAT](INFO): DAT read: user:2925> 2025-08-22T23:32:58.567Z,1755905578.567 [DAT](INFO): DAT read: Tx time:23:32:59.5671 2025-08-22T23:32:58.567Z,1755905578.567 [DAT](INFO): Ping request sent. 2025-08-22T23:32:58.568Z,1755905578.568 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:32:58.568Z,1755905578.568 [DAT](INFO): publishing transmit ping time 2025-08-22T23:32:58.568Z,1755905578.568 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000741 2025-08-22T23:32:58.819Z,1755905578.819 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250983 2025-08-22T23:32:59.079Z,1755905579.079 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.510958 2025-08-22T23:32:59.330Z,1755905579.330 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.762890 2025-08-22T23:32:59.583Z,1755905579.583 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.014971 2025-08-22T23:32:59.834Z,1755905579.834 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.266908 2025-08-22T23:33:00.086Z,1755905580.086 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.518921 2025-08-22T23:33:00.340Z,1755905580.340 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.772053 2025-08-22T23:33:00.590Z,1755905580.590 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.022915 2025-08-22T23:33:00.842Z,1755905580.842 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.274883 2025-08-22T23:33:01.095Z,1755905581.095 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.527090 2025-08-22T23:33:01.346Z,1755905581.346 [DAT](INFO): DAT read: Rx Time:23:33:01.9759 2025-08-22T23:33:01.347Z,1755905581.347 [DAT](INFO): Rx dataTimestamp_ set to:1755905581.346677 2025-08-22T23:33:01.347Z,1755905581.347 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.779950 2025-08-22T23:33:01.599Z,1755905581.599 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.031029 2025-08-22T23:33:01.853Z,1755905581.853 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.284792 2025-08-22T23:33:02.108Z,1755905582.108 [DAT](INFO): DAT read: 23:33:01.9759 LVL= 22752, 27153, 30322, 32755, AGC= 37, IDX= 444,-0.08,-0.501,-2.527,-1.272,-0.991, PHS= 0.592,-1.491,-0.325, RAW= 124.0, 13.5, CAL= 124.1, 14.7, ROT= 25.9, -14.7 2025-08-22T23:33:02.109Z,1755905582.109 [DAT](INFO): got valid direction response: 23:33:01.9759 LVL= 22752, 27153, 30322, 32755, AGC= 37, IDX= 444,-0.08,-0.501,-2.527,-1.272,-0.991, PHS= 0.592,-1.491,-0.325, RAW= 124.0, 13.5, CAL= 124.1, 14.7, ROT= 25.9, -14.7 2025-08-22T23:33:02.110Z,1755905582.110 [DAT](INFO): DAT read: Bearing 320.7, 27.0 (Local) 2025-08-22T23:33:02.110Z,1755905582.110 [DAT](INFO): Local bearing/azimuth received: Bearing 320.7, 27.0 (Local) 2025-08-22T23:33:02.114Z,1755905582.114 [DAT](INFO): DAT read: Range 11 to 50 : 3.4 m (Round-trip 4.6 ms) speed -0.2 m/s 2025-08-22T23:33:02.116Z,1755905582.116 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:33:02.118Z,1755905582.118 [DAT](INFO): direction in FSK: [0.870113,0.422504,0.253758] 2025-08-22T23:33:02.118Z,1755905582.118 [DAT](INFO): publishing direction and range info 2025-08-22T23:33:08.360Z,1755905588.360 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-22T23:33:08.410Z,1755905588.410 [DAT](INFO): ****** received valid address query ****** 2025-08-22T23:33:08.411Z,1755905588.411 [DAT](INFO): ****** received valid ping request ****** 2025-08-22T23:33:08.411Z,1755905588.411 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:33:08.662Z,1755905588.662 [DAT](INFO): DAT read: user:2926> 2025-08-22T23:33:08.663Z,1755905588.663 [DAT](INFO): DAT read: Tx time:23:33:09.6670 2025-08-22T23:33:08.663Z,1755905588.663 [DAT](INFO): Ping request sent. 2025-08-22T23:33:08.664Z,1755905588.664 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:33:08.664Z,1755905588.664 [DAT](INFO): publishing transmit ping time 2025-08-22T23:33:08.664Z,1755905588.664 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000749 2025-08-22T23:33:08.915Z,1755905588.915 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250895 2025-08-22T23:33:09.167Z,1755905589.167 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503566 2025-08-22T23:33:09.419Z,1755905589.419 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754898 2025-08-22T23:33:09.671Z,1755905589.671 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006962 2025-08-22T23:33:09.922Z,1755905589.922 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258898 2025-08-22T23:33:10.175Z,1755905590.175 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511519 2025-08-22T23:33:10.427Z,1755905590.427 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763514 2025-08-22T23:33:10.679Z,1755905590.679 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014921 2025-08-22T23:33:10.931Z,1755905590.931 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266952 2025-08-22T23:33:11.183Z,1755905591.183 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519525 2025-08-22T23:33:11.435Z,1755905591.435 [DAT](INFO): DAT read: Rx Time:23:33:12.0782 2025-08-22T23:33:11.435Z,1755905591.435 [DAT](INFO): Rx dataTimestamp_ set to:1755905591.434744 2025-08-22T23:33:11.436Z,1755905591.436 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771984 2025-08-22T23:33:11.687Z,1755905591.687 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022971 2025-08-22T23:33:11.938Z,1755905591.938 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274813 2025-08-22T23:33:12.197Z,1755905592.197 [DAT](INFO): DAT read: 23:33:12.0782 LVL= 19504, 24033, 28146, 32755, AGC= 39, IDX= 452, 0.09,-0.156,-2.152,-0.793,-0.734, PHS= 0.680,-1.374,-0.104, RAW= 127.8, 8.9, CAL= 128.0, 10.2, ROT= 22.0, -10.2 2025-08-22T23:33:12.198Z,1755905592.198 [DAT](INFO): got valid direction response: 23:33:12.0782 LVL= 19504, 24033, 28146, 32755, AGC= 39, IDX= 452, 0.09,-0.156,-2.152,-0.793,-0.734, PHS= 0.680,-1.374,-0.104, RAW= 127.8, 8.9, CAL= 128.0, 10.2, ROT= 22.0, -10.2 2025-08-22T23:33:12.199Z,1755905592.199 [DAT](INFO): DAT read: Bearing 311.7, 24.6 (Local) 2025-08-22T23:33:12.199Z,1755905592.199 [DAT](INFO): Local bearing/azimuth received: Bearing 311.7, 24.6 (Local) 2025-08-22T23:33:12.200Z,1755905592.200 [DAT](INFO): DAT read: Range 11 to 50 : 5.2 m (Round-trip 7.0 ms) speed -0.2 m/s 2025-08-22T23:33:12.201Z,1755905592.201 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:33:12.210Z,1755905592.210 [DAT](INFO): direction in FSK: [0.912530,0.368686,0.177085] 2025-08-22T23:33:12.210Z,1755905592.210 [DAT](INFO): publishing direction and range info 2025-08-22T23:33:18.473Z,1755905598.473 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-22T23:33:18.490Z,1755905598.490 [DAT](INFO): ****** received valid address query ****** 2025-08-22T23:33:18.491Z,1755905598.491 [DAT](INFO): ****** received valid ping request ****** 2025-08-22T23:33:18.491Z,1755905598.491 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:33:18.742Z,1755905598.742 [DAT](INFO): DAT read: user:2927> 2025-08-22T23:33:18.743Z,1755905598.743 [DAT](INFO): DAT read: Tx time:23:33:19.7670 2025-08-22T23:33:18.743Z,1755905598.743 [DAT](INFO): Ping request sent. 2025-08-22T23:33:18.743Z,1755905598.743 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:33:18.744Z,1755905598.744 [DAT](INFO): publishing transmit ping time 2025-08-22T23:33:18.744Z,1755905598.744 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000742 2025-08-22T23:33:18.994Z,1755905598.994 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250938 2025-08-22T23:33:19.247Z,1755905599.247 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503088 2025-08-22T23:33:19.498Z,1755905599.498 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754886 2025-08-22T23:33:19.752Z,1755905599.752 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.008062 2025-08-22T23:33:20.003Z,1755905600.003 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259008 2025-08-22T23:33:20.254Z,1755905600.254 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510868 2025-08-22T23:33:20.508Z,1755905600.508 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.764368 2025-08-22T23:33:20.759Z,1755905600.759 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014994 2025-08-22T23:33:21.010Z,1755905601.010 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266930 2025-08-22T23:33:21.265Z,1755905601.265 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.521197 2025-08-22T23:33:21.519Z,1755905601.519 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.775017 2025-08-22T23:33:21.773Z,1755905601.773 [DAT](INFO): DAT read: Rx Time:23:33:22.1807 2025-08-22T23:33:21.774Z,1755905601.774 [DAT](INFO): Rx dataTimestamp_ set to:1755905601.773713 2025-08-22T23:33:21.775Z,1755905601.775 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.031044 2025-08-22T23:33:22.022Z,1755905602.022 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278956 2025-08-22T23:33:22.280Z,1755905602.280 [DAT](INFO): DAT read: 23:33:22.1807 LVL= 20464, 25201, 30322, 32755, AGC= 41, IDX= 446,-0.20,-2.136, 2.169,-2.802,-2.705, PHS= 0.671,-1.364,-0.141, RAW= 126.7, 9.4, CAL= 126.9, 10.7, ROT= 23.1, -10.7 2025-08-22T23:33:22.281Z,1755905602.281 [DAT](INFO): got valid direction response: 23:33:22.1807 LVL= 20464, 25201, 30322, 32755, AGC= 41, IDX= 446,-0.20,-2.136, 2.169,-2.802,-2.705, PHS= 0.671,-1.364,-0.141, RAW= 126.7, 9.4, CAL= 126.9, 10.7, ROT= 23.1, -10.7 2025-08-22T23:33:22.282Z,1755905602.282 [DAT](INFO): DAT read: Bearing 310.7, 23.3 (Local) 2025-08-22T23:33:22.282Z,1755905602.282 [DAT](INFO): Local bearing/azimuth received: Bearing 310.7, 23.3 (Local) 2025-08-22T23:33:22.283Z,1755905602.283 [DAT](INFO): DAT read: Range 11 to 50 : 7.1 m (Round-trip 9.5 ms) speed -0.2 m/s 2025-08-22T23:33:22.284Z,1755905602.284 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:33:22.285Z,1755905602.285 [DAT](INFO): direction in FSK: [0.903828,0.385515,0.185667] 2025-08-22T23:33:22.285Z,1755905602.285 [DAT](INFO): publishing direction and range info 2025-08-22T23:33:22.515Z,1755905602.515 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-08-22T23:33:28.583Z,1755905608.583 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-22T23:33:28.586Z,1755905608.586 [DAT](INFO): ****** received valid address query ****** 2025-08-22T23:33:28.586Z,1755905608.586 [DAT](INFO): ****** received valid ping request ****** 2025-08-22T23:33:28.587Z,1755905608.587 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:33:28.841Z,1755905608.841 [DAT](INFO): DAT read: user:2928> 2025-08-22T23:33:28.842Z,1755905608.842 [DAT](INFO): DAT read: Tx time:23:33:29.8170 2025-08-22T23:33:28.842Z,1755905608.842 [DAT](INFO): Ping request sent. 2025-08-22T23:33:28.842Z,1755905608.842 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:33:28.843Z,1755905608.843 [DAT](INFO): publishing transmit ping time 2025-08-22T23:33:28.843Z,1755905608.843 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000755 2025-08-22T23:33:29.091Z,1755905609.091 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.248186 2025-08-22T23:33:29.350Z,1755905609.350 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.508004 2025-08-22T23:33:29.602Z,1755905609.602 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.760116 2025-08-22T23:33:29.857Z,1755905609.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.014207 2025-08-22T23:33:30.106Z,1755905610.106 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.264097 2025-08-22T23:33:30.358Z,1755905610.358 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.516129 2025-08-22T23:33:30.611Z,1755905610.611 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.768633 2025-08-22T23:33:30.867Z,1755905610.867 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.024577 2025-08-22T23:33:31.114Z,1755905611.114 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.272115 2025-08-22T23:33:31.367Z,1755905611.367 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.524271 2025-08-22T23:33:31.618Z,1755905611.618 [DAT](INFO): DAT read: Rx Time:23:33:32.2331 2025-08-22T23:33:31.619Z,1755905611.619 [DAT](INFO): Rx dataTimestamp_ set to:1755905611.618682 2025-08-22T23:33:31.619Z,1755905611.619 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.777154 2025-08-22T23:33:31.873Z,1755905611.873 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.030233 2025-08-22T23:33:32.125Z,1755905612.125 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.281850 2025-08-22T23:33:32.381Z,1755905612.381 [DAT](INFO): DAT read: 23:33:32.2331 LVL= 20208, 27121, 30706, 32755, AGC= 46, IDX= 454,-0.02,-3.064, 1.187, 2.648, 2.634, PHS= 0.687,-1.402,-0.030, RAW= 130.3, 8.1, CAL= 130.4, 9.4, ROT= 19.6, -9.4 2025-08-22T23:33:32.382Z,1755905612.382 [DAT](INFO): got valid direction response: 23:33:32.2331 LVL= 20208, 27121, 30706, 32755, AGC= 46, IDX= 454,-0.02,-3.064, 1.187, 2.648, 2.634, PHS= 0.687,-1.402,-0.030, RAW= 130.3, 8.1, CAL= 130.4, 9.4, ROT= 19.6, -9.4 2025-08-22T23:33:32.383Z,1755905612.383 [DAT](INFO): DAT read: Bearing 313.4, 19.3 (Local) 2025-08-22T23:33:32.383Z,1755905612.383 [DAT](INFO): Local bearing/azimuth received: Bearing 313.4, 19.3 (Local) 2025-08-22T23:33:32.384Z,1755905612.384 [DAT](INFO): DAT read: Range 11 to 50 : 8.9 m (Round-trip 11.9 ms) speed -0.2 m/s 2025-08-22T23:33:32.385Z,1755905612.385 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:33:32.387Z,1755905612.387 [DAT](INFO): direction in FSK: [0.929408,0.330947,0.163326] 2025-08-22T23:33:32.387Z,1755905612.387 [DAT](INFO): publishing direction and range info 2025-08-22T23:33:38.659Z,1755905618.659 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-22T23:33:38.683Z,1755905618.683 [DAT](INFO): ****** received valid address query ****** 2025-08-22T23:33:38.683Z,1755905618.683 [DAT](INFO): ****** received valid ping request ****** 2025-08-22T23:33:38.683Z,1755905618.683 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:33:38.937Z,1755905618.937 [DAT](INFO): DAT read: user:2929> 2025-08-22T23:33:38.939Z,1755905618.939 [DAT](INFO): DAT read: Tx time:23:33:39.9170 2025-08-22T23:33:38.940Z,1755905618.940 [DAT](INFO): Ping request sent. 2025-08-22T23:33:38.941Z,1755905618.941 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:33:38.941Z,1755905618.941 [DAT](INFO): publishing transmit ping time 2025-08-22T23:33:38.943Z,1755905618.943 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.002548 2025-08-22T23:33:39.187Z,1755905619.187 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.246846 2025-08-22T23:33:39.438Z,1755905619.438 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.498097 2025-08-22T23:33:39.690Z,1755905619.690 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.750143 2025-08-22T23:33:39.944Z,1755905619.944 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.003608 2025-08-22T23:33:40.195Z,1755905620.195 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.254206 2025-08-22T23:33:40.446Z,1755905620.446 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.506162 2025-08-22T23:33:40.699Z,1755905620.699 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.758270 2025-08-22T23:33:40.955Z,1755905620.955 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013499 2025-08-22T23:33:41.203Z,1755905621.203 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.262173 2025-08-22T23:33:41.455Z,1755905621.455 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.514209 2025-08-22T23:33:41.708Z,1755905621.708 [DAT](INFO): DAT read: Rx Time:23:33:42.3354 2025-08-22T23:33:41.708Z,1755905621.708 [DAT](INFO): Rx dataTimestamp_ set to:1755905621.707842 2025-08-22T23:33:41.709Z,1755905621.709 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.768345 2025-08-22T23:33:41.959Z,1755905621.959 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.018229 2025-08-22T23:33:42.214Z,1755905622.214 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.272576 2025-08-22T23:33:42.468Z,1755905622.468 [DAT](INFO): DAT read: 23:33:42.3354 LVL= 26400, 31649, 32754, 32755, AGC= 49, IDX= 446, 0.45, 1.864,-0.114, 1.534, 1.385, PHS= 0.581,-1.455, 0.104, RAW= 137.1, 8.4, CAL= 136.8, 9.4, ROT= 13.2, -9.4 2025-08-22T23:33:42.469Z,1755905622.469 [DAT](INFO): got valid direction response: 23:33:42.3354 LVL= 26400, 31649, 32754, 32755, AGC= 49, IDX= 446, 0.45, 1.864,-0.114, 1.534, 1.385, PHS= 0.581,-1.455, 0.104, RAW= 137.1, 8.4, CAL= 136.8, 9.4, ROT= 13.2, -9.4 2025-08-22T23:33:42.470Z,1755905622.470 [DAT](INFO): DAT read: Bearing 320.4, 15.1 (Local) 2025-08-22T23:33:42.470Z,1755905622.470 [DAT](INFO): Local bearing/azimuth received: Bearing 320.4, 15.1 (Local) 2025-08-22T23:33:42.471Z,1755905622.471 [DAT](INFO): DAT read: Range 11 to 50 : 10.6 m (Round-trip 14.2 ms) speed -0.2 m/s 2025-08-22T23:33:42.472Z,1755905622.472 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:33:42.473Z,1755905622.473 [DAT](INFO): direction in FSK: [0.960506,0.225285,0.163326] 2025-08-22T23:33:42.473Z,1755905622.473 [DAT](INFO): publishing direction and range info 2025-08-22T23:33:42.703Z,1755905622.703 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 10.60 m. Transitioning docking module to standby. 2025-08-22T23:33:43.135Z,1755905623.135 [DockingStepper](INFO): Changing to mode: 1 2025-08-22T23:33:48.762Z,1755905628.762 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-22T23:33:48.772Z,1755905628.772 [DAT](INFO): ****** received valid address query ****** 2025-08-22T23:33:48.772Z,1755905628.772 [DAT](INFO): ****** received valid ping request ****** 2025-08-22T23:33:48.773Z,1755905628.773 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-22T23:33:49.022Z,1755905629.022 [DAT](INFO): DAT read: user:2930> 2025-08-22T23:33:49.023Z,1755905629.023 [DAT](INFO): DAT read: Tx time:23:33:50.0169 2025-08-22T23:33:49.023Z,1755905629.023 [DAT](INFO): Ping request sent. 2025-08-22T23:33:49.024Z,1755905629.024 [DAT](INFO): transmitted an acoustic signal 2025-08-22T23:33:49.024Z,1755905629.024 [DAT](INFO): publishing transmit ping time 2025-08-22T23:33:49.024Z,1755905629.024 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000753 2025-08-22T23:33:49.213Z,1755905629.213 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2025-08-22T23:33:49.275Z,1755905629.275 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251094 2025-08-22T23:33:49.526Z,1755905629.526 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502814 2025-08-22T23:33:49.576Z,1755905629.576 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2025-08-22T23:33:49.576Z,1755905629.576 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2025-08-22T23:33:49.577Z,1755905629.577 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2025-08-22T23:33:49.577Z,1755905629.577 [DefaultWithUndock:LeaveDock] Stopped 2025-08-22T23:33:49.577Z,1755905629.577 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2025-08-22T23:33:49.577Z,1755905629.577 [DefaultWithUndock:LeaveDock:A.] Stopped 2025-08-22T23:33:49.577Z,1755905629.577 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2025-08-22T23:33:49.577Z,1755905629.577 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2025-08-22T23:33:49.578Z,1755905629.578 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2025-08-22T23:33:49.578Z,1755905629.578 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2025-08-22T23:33:49.578Z,1755905629.578 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-08-22T23:33:49.578Z,1755905629.578 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-08-22T23:33:49.578Z,1755905629.578 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-08-22T23:33:49.579Z,1755905629.579 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-08-22T23:33:49.579Z,1755905629.579 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-08-22T23:33:49.579Z,1755905629.579 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-08-22T23:33:49.579Z,1755905629.579 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-08-22T23:33:49.779Z,1755905629.779 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754957 2025-08-22T23:33:50.032Z,1755905630.032 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007801 2025-08-22T23:33:50.283Z,1755905630.283 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259027 2025-08-22T23:33:50.535Z,1755905630.535 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510923 2025-08-22T23:33:50.566Z,1755905630.566 [Radio_Surface](INFO): Powering up 2025-08-22T23:33:50.787Z,1755905630.787 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762936 2025-08-22T23:33:51.038Z,1755905631.039 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014889 2025-08-22T23:33:51.291Z,1755905631.291 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266953 2025-08-22T23:33:51.542Z,1755905631.542 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518811 2025-08-22T23:33:51.795Z,1755905631.795 [DAT](INFO): DAT read: Rx Time:23:33:52.4378 2025-08-22T23:33:51.795Z,1755905631.795 [DAT](INFO): Rx dataTimestamp_ set to:1755905631.794813 2025-08-22T23:33:51.796Z,1755905631.796 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772050 2025-08-22T23:33:52.047Z,1755905632.047 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023778 2025-08-22T23:33:52.298Z,1755905632.298 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274874 2025-08-22T23:33:52.556Z,1755905632.556 [DAT](INFO): DAT read: 23:33:52.4378 LVL= 30240, 32753, 32754, 32755, AGC= 52, IDX= 456, 0.11, 1.998, 0.039, 1.902, 1.575, PHS= 0.525,-1.491, 0.282, RAW= 143.7, 7.2, CAL= 143.3, 7.9, ROT= 6.7, -7.9 2025-08-22T23:33:52.558Z,1755905632.558 [DAT](INFO): got valid direction response: 23:33:52.4378 LVL= 30240, 32753, 32754, 32755, AGC= 52, IDX= 456, 0.11, 1.998, 0.039, 1.902, 1.575, PHS= 0.525,-1.491, 0.282, RAW= 143.7, 7.2, CAL= 143.3, 7.9, ROT= 6.7, -7.9 2025-08-22T23:33:52.559Z,1755905632.559 [DAT](INFO): DAT read: Bearing 326.1, 7.7 (Local) 2025-08-22T23:33:52.559Z,1755905632.559 [DAT](INFO): Local bearing/azimuth received: Bearing 326.1, 7.7 (Local) 2025-08-22T23:33:52.563Z,1755905632.563 [DAT](INFO): DAT read: Range 11 to 50 : 12.5 m (Round-trip 16.7 ms) speed -0.2 m/s 2025-08-22T23:33:52.568Z,1755905632.568 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-22T23:33:52.573Z,1755905632.573 [DAT](INFO): direction in FSK: [0.983745,0.115563,0.137445] 2025-08-22T23:33:52.574Z,1755905632.574 [DAT](INFO): publishing direction and range info 2025-08-22T23:33:54.424Z,1755905634.424 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00022803211435,35.0, 0.0,1492.4, 0 2025-08-22T23:33:55.818Z,1755905635.818 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11 2025-08-22T23:33:55.818Z,1755905635.818 [Radio_Surface](INFO): No local IP found 2025-08-22T23:34:18.809Z,1755905658.809 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, -2.71, -7.36, , 10.41, 0.00 2025-08-22T23:36:03.218Z,1755905763.218 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-22T23:36:04.222Z,1755905764.222 [DataOverHttps](INFO): Radio surface powered ON. 2025-08-22T23:36:04.222Z,1755905764.222 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:36:23.683Z,1755905783.683 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2025-08-22T23:36:23.683Z,1755905783.683 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-22T23:36:23.683Z,1755905783.683 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-22T23:36:34.346Z,1755905794.346 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-22T23:36:35.350Z,1755905795.350 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:37:05.470Z,1755905825.470 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-22T23:37:06.474Z,1755905826.474 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:37:36.594Z,1755905856.594 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-22T23:37:37.598Z,1755905857.598 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:37:47.534Z,1755905867.534 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003038 2025-08-22T23:37:48.534Z,1755905868.534 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:38:01.522Z,1755905881.522 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:38:15.974Z,1755905895.974 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:38:24.798Z,1755905904.798 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:38:33.770Z,1755905913.770 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:38:42.754Z,1755905922.754 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:38:51.534Z,1755905931.534 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:39:00.495Z,1755905940.495 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:39:09.314Z,1755905949.314 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:39:18.190Z,1755905958.190 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:39:23.056Z,1755905963.056 [NAL9602](INFO): SBD MO Status=2, MOMSN=993, MT Status=2, MTMSN=0 2025-08-22T23:39:23.056Z,1755905963.056 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-22T23:39:27.226Z,1755905967.226 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:39:36.058Z,1755905976.058 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:39:38.106Z,1755905978.106 [DockingStepper](ERROR): getADC uart error serial timeout 2025-08-22T23:39:38.106Z,1755905978.106 [DockingStepper](FAULT): uart error - getPosition..serial timeout 2025-08-22T23:39:38.106Z,1755905978.106 [DockingStepper] Communications Fault, FailCount= 1 2025-08-22T23:39:38.106Z,1755905978.106 [DockingStepper](ERROR): Communications Fault 2025-08-22T23:39:38.111Z,1755905978.111 [CBIT](ERROR): Communications Fault in component: DockingStepper 2025-08-22T23:39:38.463Z,1755905978.463 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2025-08-22T23:39:38.463Z,1755905978.463 [DockingStepper](INFO): Powering down 2025-08-22T23:39:44.614Z,1755905984.614 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:39:53.316Z,1755905993.316 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:39:55.439Z,1755905995.439 [NAL9602](INFO): SBD MO Status=2, MOMSN=993, MT Status=2, MTMSN=0 2025-08-22T23:39:55.439Z,1755905995.439 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-22T23:39:59.049Z,1755905999.049 [CBIT](INFO): Clearing failed state for component DockingStepper 2025-08-22T23:39:59.049Z,1755905999.049 [DockingStepper] No Fault, FailCount= 1 2025-08-22T23:39:59.468Z,1755905999.468 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-08-22T23:39:59.468Z,1755905999.468 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-08-22T23:40:01.942Z,1755906001.942 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:40:03.096Z,1755906003.096 [DockingStepper](INFO): Changing to mode: 1 2025-08-22T23:40:11.066Z,1755906011.066 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:40:21.236Z,1755906021.236 [NAL9602](INFO): SBD MO Status=2, MOMSN=993, MT Status=2, MTMSN=0 2025-08-22T23:40:21.236Z,1755906021.236 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-22T23:40:27.962Z,1755906027.962 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:40:36.954Z,1755906036.954 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:40:45.874Z,1755906045.874 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:40:54.670Z,1755906054.670 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:41:03.650Z,1755906063.650 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:41:12.510Z,1755906072.510 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:41:21.381Z,1755906081.381 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:41:23.903Z,1755906083.903 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](INFO): Timed out from 2025-08-22T23:36:23.7Z 2025-08-22T23:41:23.903Z,1755906083.903 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-22T23:41:23.903Z,1755906083.903 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-22T23:41:27.794Z,1755906087.794 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250822T220638/Courier0018.lzma 2025-08-22T23:41:28.457Z,1755906088.457 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-08-22T23:41:28.551Z,1755906088.551 [DataOverHttps](INFO): Moved sent file to Logs/20250822T220638/Courier0018.lzma.bak 2025-08-22T23:41:28.552Z,1755906088.552 [DataOverHttps](INFO): SBD MOMSN=25883321 2025-08-22T23:41:40.577Z,1755906100.577 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-22T23:41:45.367Z,1755906105.367 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250822T221449/Courier0000.lzma 2025-08-22T23:41:46.359Z,1755906106.359 [DataOverHttps](INFO): Moved sent file to Logs/20250822T221449/Courier0000.lzma.bak 2025-08-22T23:41:46.360Z,1755906106.360 [DataOverHttps](INFO): SBD MOMSN=25883324 2025-08-22T23:41:59.158Z,1755906119.158 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-22T23:42:03.550Z,1755906123.550 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250822T221449/Courier0003.lzma 2025-08-22T23:42:04.551Z,1755906124.551 [DataOverHttps](INFO): Moved sent file to Logs/20250822T221449/Courier0003.lzma.bak 2025-08-22T23:42:04.552Z,1755906124.552 [DataOverHttps](INFO): SBD MOMSN=25883327 2025-08-22T23:42:21.429Z,1755906141.429 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250822T221449/Courier0006.lzma 2025-08-22T23:42:23.226Z,1755906143.226 [DataOverHttps](INFO): Moved sent file to Logs/20250822T221449/Courier0006.lzma.bak 2025-08-22T23:42:23.226Z,1755906143.226 [DataOverHttps](INFO): SBD MOMSN=25883329 2025-08-22T23:42:39.166Z,1755906159.166 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250822T221449/Courier0009.lzma 2025-08-22T23:42:40.167Z,1755906160.167 [DataOverHttps](INFO): Moved sent file to Logs/20250822T221449/Courier0009.lzma.bak 2025-08-22T23:42:40.168Z,1755906160.168 [DataOverHttps](INFO): SBD MOMSN=25883331 2025-08-22T23:42:56.910Z,1755906176.910 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250822T221449/Courier0012.lzma 2025-08-22T23:42:57.911Z,1755906177.911 [DataOverHttps](INFO): Moved sent file to Logs/20250822T221449/Courier0012.lzma.bak 2025-08-22T23:42:57.912Z,1755906177.912 [DataOverHttps](INFO): SBD MOMSN=25883333 2025-08-22T23:43:14.741Z,1755906194.741 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250822T221449/Courier0015.lzma 2025-08-22T23:43:15.743Z,1755906195.743 [DataOverHttps](INFO): Moved sent file to Logs/20250822T221449/Courier0015.lzma.bak 2025-08-22T23:43:15.744Z,1755906195.744 [DataOverHttps](INFO): SBD MOMSN=25883335 2025-08-22T23:43:32.810Z,1755906212.810 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20250822T230017/Courier0000.lzma 2025-08-22T23:43:33.811Z,1755906213.811 [DataOverHttps](INFO): Moved sent file to Logs/20250822T230017/Courier0000.lzma.bak 2025-08-22T23:43:33.812Z,1755906213.812 [DataOverHttps](INFO): SBD MOMSN=25883337 2025-08-22T23:43:50.720Z,1755906230.720 [DataOverHttps](INFO): Sending 377 bytes from file Logs/20250822T220638/Express0019.lzma 2025-08-22T23:43:51.720Z,1755906231.720 [DataOverHttps](INFO): Moved sent file to Logs/20250822T220638/Express0019.lzma.bak 2025-08-22T23:43:51.720Z,1755906231.720 [DataOverHttps](INFO): SBD MOMSN=25883341 2025-08-22T23:44:08.867Z,1755906248.867 [DataOverHttps](INFO): Sending 599 bytes from file Logs/20250822T221449/Express0001.lzma 2025-08-22T23:44:09.867Z,1755906249.867 [DataOverHttps](INFO): Moved sent file to Logs/20250822T221449/Express0001.lzma.bak 2025-08-22T23:44:09.867Z,1755906249.867 [DataOverHttps](INFO): SBD MOMSN=25883345 2025-08-22T23:44:26.607Z,1755906266.607 [DataOverHttps](INFO): Sending 455 bytes from file Logs/20250822T221449/Express0004.lzma 2025-08-22T23:44:27.607Z,1755906267.607 [DataOverHttps](INFO): Moved sent file to Logs/20250822T221449/Express0004.lzma.bak 2025-08-22T23:44:27.608Z,1755906267.608 [DataOverHttps](INFO): SBD MOMSN=25883348 2025-08-22T23:44:44.483Z,1755906284.483 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250822T221449/Express0007.lzma 2025-08-22T23:44:45.483Z,1755906285.483 [DataOverHttps](INFO): Moved sent file to Logs/20250822T221449/Express0007.lzma.bak 2025-08-22T23:44:45.484Z,1755906285.484 [DataOverHttps](INFO): SBD MOMSN=25883354 2025-08-22T23:45:02.970Z,1755906302.970 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20250822T221449/Express0010.lzma 2025-08-22T23:45:03.971Z,1755906303.971 [DataOverHttps](INFO): Moved sent file to Logs/20250822T221449/Express0010.lzma.bak 2025-08-22T23:45:03.971Z,1755906303.971 [DataOverHttps](INFO): SBD MOMSN=25883356 2025-08-22T23:45:20.727Z,1755906320.727 [DataOverHttps](INFO): Sending 713 bytes from file Logs/20250822T221449/Express0013.lzma 2025-08-22T23:45:21.727Z,1755906321.727 [DataOverHttps](INFO): Moved sent file to Logs/20250822T221449/Express0013.lzma.bak 2025-08-22T23:45:21.728Z,1755906321.728 [DataOverHttps](INFO): SBD MOMSN=25883358 2025-08-22T23:45:38.743Z,1755906338.743 [DataOverHttps](INFO): Sending 603 bytes from file Logs/20250822T221449/Express0016.lzma 2025-08-22T23:45:39.744Z,1755906339.744 [DataOverHttps](INFO): Moved sent file to Logs/20250822T221449/Express0016.lzma.bak 2025-08-22T23:45:39.744Z,1755906339.744 [DataOverHttps](INFO): SBD MOMSN=25883375 2025-08-22T23:45:56.452Z,1755906356.452 [DataOverHttps](INFO): Sending 466 bytes from file Logs/20250822T221449/Express0019.lzma 2025-08-22T23:45:57.451Z,1755906357.451 [DataOverHttps](INFO): Moved sent file to Logs/20250822T221449/Express0019.lzma.bak 2025-08-22T23:45:57.452Z,1755906357.452 [DataOverHttps](INFO): SBD MOMSN=25883394 2025-08-22T23:46:16.524Z,1755906376.524 [DataOverHttps](INFO): Sending 986 bytes from file Logs/20250822T230017/Express0001.lzma 2025-08-22T23:46:17.523Z,1755906377.523 [DataOverHttps](INFO): Moved sent file to Logs/20250822T230017/Express0001.lzma.bak 2025-08-22T23:46:17.523Z,1755906377.523 [DataOverHttps](INFO): SBD MOMSN=25883399 2025-08-22T23:46:19.112Z,1755906379.112 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-08-22T23:46:19.112Z,1755906379.112 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-08-22T23:46:19.112Z,1755906379.112 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-22T23:46:27.677Z,1755906387.677 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:46:36.482Z,1755906396.482 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:46:44.451Z,1755906404.451 [DataOverHttps](IMPORTANT): SBD MTMSN=20250822T234643 2025-08-22T23:46:52.263Z,1755906412.263 [DataOverHttps](INFO): Received command: configSet IBIT.batteryCapacityThreshold -0.1 ampere_hour persist;configSet IBIT.batteryVoltageThreshold 12 volt persist;restart app 2025-08-22T23:46:54.068Z,1755906414.068 [CommandExec](IMPORTANT): got command configSet IBIT.batteryCapacityThreshold -0.1 ampere_hour persist 2025-08-22T23:46:54.073Z,1755906414.073 [CommandExec](IMPORTANT): got command configSet IBIT.batteryVoltageThreshold 12 volt persist 2025-08-22T23:46:54.076Z,1755906414.076 [CommandExec](IMPORTANT): got command restart application 2025-08-22T23:46:54.279Z,1755906414.279 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-22T23:46:55.078Z,1755906415.078 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-22T23:46:55.078Z,1755906415.078 [CommandExec](INFO): Uninitializing the command executive. 2025-08-22T23:46:55.078Z,1755906415.078 [CommandExec](INFO): Uninitializing the command scheduler. 2025-08-22T23:46:55.078Z,1755906415.078 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:46:55.209Z,1755906415.209 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-08-22T23:46:55.210Z,1755906415.210 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-08-22T23:46:55.210Z,1755906415.210 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:46:55.211Z,1755906415.211 [NavChartDb](INFO): Join timeout helper Thread ID is 3283 2025-08-22T23:46:55.482Z,1755906415.482 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-22T23:46:55.482Z,1755906415.482 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:46:55.486Z,1755906415.486 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-08-22T23:46:55.486Z,1755906415.486 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:46:55.486Z,1755906415.486 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3284 2025-08-22T23:46:55.498Z,1755906415.498 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-22T23:46:55.498Z,1755906415.498 [WetLabsBB2FL](INFO): Powering down 2025-08-22T23:46:55.499Z,1755906415.499 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:46:55.506Z,1755906415.506 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-08-22T23:46:55.506Z,1755906415.506 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:46:55.506Z,1755906415.506 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3285 2025-08-22T23:46:55.906Z,1755906415.906 [CTD_Seabird](INFO): Powering down 2025-08-22T23:46:55.918Z,1755906415.918 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-22T23:46:55.919Z,1755906415.919 [CTD_Seabird](INFO): Powering down 2025-08-22T23:46:55.937Z,1755906415.937 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:46:55.946Z,1755906415.946 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-08-22T23:46:55.946Z,1755906415.946 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:46:55.946Z,1755906415.946 [Radio_Surface](INFO): Join timeout helper Thread ID is 3286 2025-08-22T23:46:56.346Z,1755906416.346 [Radio_Surface](INFO): Powering down 2025-08-22T23:46:56.347Z,1755906416.347 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-22T23:46:56.347Z,1755906416.347 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:46:56.354Z,1755906416.354 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-08-22T23:46:56.354Z,1755906416.354 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:46:56.354Z,1755906416.354 [Onboard](INFO): Join timeout helper Thread ID is 3287 2025-08-22T23:46:57.318Z,1755906417.318 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-22T23:46:57.318Z,1755906417.318 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:46:57.322Z,1755906417.322 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-08-22T23:46:57.323Z,1755906417.323 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:46:57.323Z,1755906417.323 [DataOverHttps](INFO): Join timeout helper Thread ID is 3288 2025-08-22T23:47:00.051Z,1755906420.051 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-22T23:47:00.054Z,1755906420.054 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.066Z,1755906420.066 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-08-22T23:47:00.066Z,1755906420.066 [DAT ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.067Z,1755906420.067 [DAT](INFO): Join timeout helper Thread ID is 3289 2025-08-22T23:47:00.202Z,1755906420.202 [DAT](INFO): Powering down 2025-08-22T23:47:00.203Z,1755906420.203 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-22T23:47:00.203Z,1755906420.203 [DAT](INFO): Powering down 2025-08-22T23:47:00.204Z,1755906420.204 [DAT ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.206Z,1755906420.206 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-08-22T23:47:00.206Z,1755906420.206 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.207Z,1755906420.207 [BackseatComponent](INFO): Join timeout helper Thread ID is 3290 2025-08-22T23:47:00.286Z,1755906420.286 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-22T23:47:00.286Z,1755906420.286 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.294Z,1755906420.294 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-08-22T23:47:00.294Z,1755906420.294 [logger ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.295Z,1755906420.295 [logger](INFO): Join timeout helper Thread ID is 3291 2025-08-22T23:47:00.338Z,1755906420.338 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-22T23:47:00.339Z,1755906420.339 [logger ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.342Z,1755906420.342 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-08-22T23:47:00.342Z,1755906420.342 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.343Z,1755906420.343 [CommandLine](INFO): Join timeout helper Thread ID is 3292 2025-08-22T23:47:00.362Z,1755906420.362 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-22T23:47:00.363Z,1755906420.363 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.383Z,1755906420.383 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-08-22T23:47:00.383Z,1755906420.383 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.384Z,1755906420.384 [CommandExec](INFO): Join timeout helper Thread ID is 3293 2025-08-22T23:47:00.391Z,1755906420.391 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-08-22T23:47:00.392Z,1755906420.392 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.392Z,1755906420.392 [controlThread](INFO): Join timeout helper Thread ID is 3294 2025-08-22T23:47:00.678Z,1755906420.678 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-22T23:47:00.678Z,1755906420.678 [controlThread](DEBUG): Uninitializing ControlThread 2025-08-22T23:47:00.678Z,1755906420.678 [AHRS_M2](INFO): Powering down 2025-08-22T23:47:00.682Z,1755906420.682 [NAL9602](INFO): Powering down 2025-08-22T23:47:00.683Z,1755906420.683 [RDI_Pathfinder](INFO): Powering down 2025-08-22T23:47:00.684Z,1755906420.684 [Sonardyne_Nano](INFO): Powering down 2025-08-22T23:47:00.770Z,1755906420.770 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-08-22T23:47:00.771Z,1755906420.771 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-08-22T23:47:00.771Z,1755906420.771 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-08-22T23:47:00.772Z,1755906420.772 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock 2025-08-22T23:47:00.772Z,1755906420.772 [DefaultWithUndock] Stopped 2025-08-22T23:47:00.772Z,1755906420.772 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock 2025-08-22T23:47:00.772Z,1755906420.772 [DefaultWithUndock:SurfaceDefault] Stopped 2025-08-22T23:47:00.772Z,1755906420.772 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault 2025-08-22T23:47:00.772Z,1755906420.772 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped 2025-08-22T23:47:00.772Z,1755906420.772 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-08-22T23:47:00.772Z,1755906420.772 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped 2025-08-22T23:47:00.772Z,1755906420.772 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-22T23:47:00.772Z,1755906420.772 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped 2025-08-22T23:47:00.773Z,1755906420.773 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-22T23:47:00.780Z,1755906420.780 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-08-22T23:47:00.781Z,1755906420.781 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-08-22T23:47:00.781Z,1755906420.781 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-08-22T23:47:00.781Z,1755906420.781 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-08-22T23:47:00.782Z,1755906420.782 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-08-22T23:47:00.782Z,1755906420.782 [BuoyancyServo](INFO): Powering down 2025-08-22T23:47:00.794Z,1755906420.794 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-08-22T23:47:00.794Z,1755906420.794 [ElevatorServo](INFO): Powering down 2025-08-22T23:47:00.795Z,1755906420.795 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2025-08-22T23:47:00.795Z,1755906420.795 [DockingStepper](INFO): Powering down 2025-08-22T23:47:00.795Z,1755906420.795 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-08-22T23:47:00.796Z,1755906420.796 [MassServo](INFO): Powering down 2025-08-22T23:47:00.796Z,1755906420.796 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-08-22T23:47:00.796Z,1755906420.796 [RudderServo](INFO): Powering down 2025-08-22T23:47:00.797Z,1755906420.797 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-08-22T23:47:00.798Z,1755906420.798 [ThrusterHE](INFO): Powering down 2025-08-22T23:47:00.798Z,1755906420.798 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-08-22T23:47:00.799Z,1755906420.799 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-08-22T23:47:00.799Z,1755906420.799 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-08-22T23:47:00.799Z,1755906420.799 [CBIT](DEBUG): Powering off loads. 2025-08-22T23:47:00.813Z,1755906420.813 [CBIT](DEBUG): Disabling WDT. 2025-08-22T23:47:00.826Z,1755906420.826 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-08-22T23:47:00.826Z,1755906420.826 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-08-22T23:47:00.827Z,1755906420.827 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.879Z,1755906420.879 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.882Z,1755906420.882 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.888Z,1755906420.888 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.895Z,1755906420.895 [DAT ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:00.936Z,1755906420.936 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:01.004Z,1755906421.004 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:01.011Z,1755906421.011 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:01.019Z,1755906421.019 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-08-22T23:47:01.019Z,1755906421.019 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-08-22T23:47:01.035Z,1755906421.035 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-08-22T23:47:01.116Z,1755906421.116 [logger ThreadHandler](INFO): Thread cancelled.