2025-08-28T18:00:31.249Z,1756404031.249 [CommandExec](IMPORTANT): got command restart logs 2025-08-28T18:00:31.714Z,1756404031.714 [DAT](INFO): Requesting device enable set for address 50. 2025-08-28T18:00:31.964Z,1756404031.964 [DAT](INFO): DAT read: user:4549> 2025-08-28T18:00:31.965Z,1756404031.965 [DAT](INFO): DAT read: Tx time:18:00:30.8759 2025-08-28T18:00:31.966Z,1756404031.966 [DAT](INFO): Ping request sent. 2025-08-28T18:00:31.966Z,1756404031.966 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:00:31.966Z,1756404031.966 [DAT](INFO): publishing transmit ping time 2025-08-28T18:00:31.966Z,1756404031.966 [DAT](INFO): Requesting device enable set for address 50. 2025-08-28T18:00:32.217Z,1756404032.217 [DAT](INFO): Requesting device enable set for address 50. 2025-08-28T18:00:32.317Z,1756404032.317 [CTD_Seabird](ERROR): Failed to parse device response: 2025-08-28T18:00:34.232Z,1756404034.232 [DAT](INFO): DAT read: Rx Time:18:00:32.7574 2025-08-28T18:00:34.233Z,1756404034.233 [DAT](INFO): Rx dataTimestamp_ set to:1756404034.232570 2025-08-28T18:00:34.993Z,1756404034.993 [DAT](INFO): DAT read: 18:00:32.7574 LVL= 19024, 12449, 20034, 29411, AGC= 41, IDX= 442, 0.27,-1.269,-1.278,-2.251,-0.072, PHS=-1.095,-1.161,-2.223, RAW= 33.0, 55.3, CAL= 31.6, 59.2, ROT= 118.4, -59.2 2025-08-28T18:00:34.994Z,1756404034.994 [DAT](INFO): got valid direction response: 18:00:32.7574 LVL= 19024, 12449, 20034, 29411, AGC= 41, IDX= 442, 0.27,-1.269,-1.278,-2.251,-0.072, PHS=-1.095,-1.161,-2.223, RAW= 33.0, 55.3, CAL= 31.6, 59.2, ROT= 118.4, -59.2 2025-08-28T18:00:34.994Z,1756404034.994 [DAT](INFO): DAT read: OK 2025-08-28T18:00:34.994Z,1756404034.994 [DAT](INFO): unknown deviceResponse_: OK 2025-08-28T18:00:34.995Z,1756404034.995 [DAT](INFO): DAT read: user:4550> 2025-08-28T18:00:34.996Z,1756404034.996 [DAT](INFO): DAT read: Tx time:18:00:33.8759 2025-08-28T18:00:34.996Z,1756404034.996 [DAT](INFO): Ping request sent. 2025-08-28T18:00:34.996Z,1756404034.996 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:00:34.997Z,1756404034.997 [DAT](INFO): #Rx 5: Read direction message, but no range. 2025-08-28T18:00:34.998Z,1756404034.998 [DAT](INFO): direction in FSK: [-0.243540,0.450418,0.858960] 2025-08-28T18:00:34.998Z,1756404034.998 [DAT](INFO): publishing transmit ping time 2025-08-28T18:00:35.001Z,1756404035.001 [DAT](INFO): publishing transmit ping time 2025-08-28T18:00:37.257Z,1756404037.257 [DAT](INFO): DAT read: Rx Time:18:00:35.7575 2025-08-28T18:00:37.257Z,1756404037.257 [DAT](INFO): Rx dataTimestamp_ set to:1756404037.256770 2025-08-28T18:00:38.017Z,1756404038.017 [DAT](INFO): DAT read: 18:00:35.7575 LVL= 19264, 14977, 17586, 27251, AGC= 41, IDX= 443, 0.26, 3.064, 3.113, 2.213,-2.010, PHS=-1.107,-1.115,-2.104, RAW= 30.4, 57.0, CAL= 29.1, 60.7, ROT= 120.9, -60.7 2025-08-28T18:00:38.018Z,1756404038.018 [DAT](INFO): got valid direction response: 18:00:35.7575 LVL= 19264, 14977, 17586, 27251, AGC= 41, IDX= 443, 0.26, 3.064, 3.113, 2.213,-2.010, PHS=-1.107,-1.115,-2.104, RAW= 30.4, 57.0, CAL= 29.1, 60.7, ROT= 120.9, -60.7 2025-08-28T18:00:38.018Z,1756404038.018 [DAT](INFO): DAT read: OK 2025-08-28T18:00:38.018Z,1756404038.018 [DAT](INFO): unknown deviceResponse_: OK 2025-08-28T18:00:38.019Z,1756404038.019 [DAT](INFO): DAT read: user:4551> 2025-08-28T18:00:38.020Z,1756404038.020 [DAT](INFO): DAT read: Tx time:18:00:36.8759 2025-08-28T18:00:38.020Z,1756404038.020 [DAT](INFO): Ping request sent. 2025-08-28T18:00:38.020Z,1756404038.020 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:00:38.020Z,1756404038.020 [DAT](INFO): #Rx 6: Read direction message, but no range. 2025-08-28T18:00:38.021Z,1756404038.021 [DAT](INFO): direction in FSK: [-0.251318,0.419922,0.872069] 2025-08-28T18:00:38.022Z,1756404038.022 [DAT](INFO): publishing transmit ping time 2025-08-28T18:00:38.025Z,1756404038.025 [DAT](INFO): publishing transmit ping time 2025-08-28T18:00:39.738Z,1756404039.738 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:00:40.281Z,1756404040.281 [DAT](INFO): DAT read: Rx Time:18:00:38.7576 2025-08-28T18:00:40.282Z,1756404040.282 [DAT](INFO): Rx dataTimestamp_ set to:1756404040.281378 2025-08-28T18:00:40.788Z,1756404040.788 [DAT](INFO): DAT read: 18:00:38.7576 LVL= 19040, 15137, 18610, 25651, AGC= 41, IDX= 444, 0.17, 2.981, 3.045, 2.180,-2.092, PHS=-1.108,-1.102,-2.055, RAW= 29.7, 57.8, CAL= 28.4, 61.3, ROT= 121.6, -61.3 2025-08-28T18:00:40.789Z,1756404040.789 [DAT](INFO): got valid direction response: 18:00:38.7576 LVL= 19040, 15137, 18610, 25651, AGC= 41, IDX= 444, 0.17, 2.981, 3.045, 2.180,-2.092, PHS=-1.108,-1.102,-2.055, RAW= 29.7, 57.8, CAL= 28.4, 61.3, ROT= 121.6, -61.3 2025-08-28T18:00:40.790Z,1756404040.790 [DAT](INFO): DAT read: OK 2025-08-28T18:00:40.790Z,1756404040.790 [DAT](INFO): unknown deviceResponse_: OK 2025-08-28T18:00:40.790Z,1756404040.790 [DAT](INFO): #Rx 7: Read direction message, but no range. 2025-08-28T18:00:40.792Z,1756404040.792 [DAT](INFO): direction in FSK: [-0.251630,0.409019,0.877146] 2025-08-28T18:00:44.077Z,1756404044.077 [BPC1](ERROR): Battery stick #10 (s/n: 00BE) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48E0. 2025-08-28T18:00:44.078Z,1756404044.078 [BPC1](ERROR): Battery stick #14 (s/n: 0129) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-28T18:00:44.078Z,1756404044.078 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-28T18:00:44.079Z,1756404044.079 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-28T18:00:44.084Z,1756404044.084 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-28T18:00:44.085Z,1756404044.085 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-28T18:00:44.086Z,1756404044.086 [BPC1](ERROR): Battery stick #37 (s/n: 00A3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-28T18:00:44.087Z,1756404044.087 [BPC1](ERROR): Battery stick #44 (s/n: 00E4) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-28T18:00:44.092Z,1756404044.092 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-28T18:00:44.092Z,1756404044.092 [BPC1](INFO): Calculating totals. Valid battery stick count: 29. Valid reserve battery stick count: 2. 2025-08-28T18:00:44.101Z,1756404044.101 [BPC1](FAULT): Failed to receive data from 28 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 2, 3, 4, 5, 6, 8, 9, 10, 11, 12, 13, 14, 15, 16, 17, 18, 24, 26, 27, 32, 33, 34, 36, 37, 38, 39, 44, 58. 2025-08-28T18:01:31.811Z,1756404091.811 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 81.28, 81.62, 80.94 2025-08-28T18:02:29.110Z,1756404149.110 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-28T18:02:29.312Z,1756404149.312 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:02:29.312Z,1756404149.312 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:02:29.567Z,1756404149.567 [DAT](INFO): DAT read: user:4552> 2025-08-28T18:02:29.570Z,1756404149.570 [DAT](INFO): DAT read: Tx time:18:02:28.4766 2025-08-28T18:02:29.571Z,1756404149.571 [DAT](INFO): Ping request sent. 2025-08-28T18:02:29.571Z,1756404149.571 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:02:29.571Z,1756404149.571 [DAT](INFO): publishing transmit ping time 2025-08-28T18:02:29.573Z,1756404149.573 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.002205 2025-08-28T18:02:29.817Z,1756404149.817 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.246059 2025-08-28T18:02:30.068Z,1756404150.068 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.497894 2025-08-28T18:02:30.321Z,1756404150.321 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.750107 2025-08-28T18:02:30.573Z,1756404150.573 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.002029 2025-08-28T18:02:30.824Z,1756404150.824 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.253932 2025-08-28T18:02:31.077Z,1756404151.077 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.506260 2025-08-28T18:02:31.328Z,1756404151.328 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.758026 2025-08-28T18:02:31.584Z,1756404151.583 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.012362 2025-08-28T18:02:31.832Z,1756404151.832 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.261981 2025-08-28T18:02:32.085Z,1756404152.085 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.514025 2025-08-28T18:02:32.337Z,1756404152.337 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.766070 2025-08-28T18:02:32.588Z,1756404152.588 [DAT](INFO): DAT read: Rx Time:18:02:30.8831 2025-08-28T18:02:32.589Z,1756404152.589 [DAT](INFO): Rx dataTimestamp_ set to:1756404152.588673 2025-08-28T18:02:32.589Z,1756404152.589 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.019021 2025-08-28T18:02:32.841Z,1756404152.841 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.270089 2025-08-28T18:02:33.097Z,1756404153.097 [DAT](INFO): DAT read: 18:02:30.8831 LVL= 18656, 10321, 18898, 29523, AGC= 43, IDX= 181,-0.23, 3.140, 3.109, 2.150,-2.000, PHS=-1.041,-1.129,-2.177, RAW= 34.0, 54.5, CAL= 32.5, 58.5, ROT= 117.5, -58.5 2025-08-28T18:02:33.098Z,1756404153.098 [DAT](INFO): got valid direction response: 18:02:30.8831 LVL= 18656, 10321, 18898, 29523, AGC= 43, IDX= 181,-0.23, 3.140, 3.109, 2.150,-2.000, PHS=-1.041,-1.129,-2.177, RAW= 34.0, 54.5, CAL= 32.5, 58.5, ROT= 117.5, -58.5 2025-08-28T18:02:33.099Z,1756404153.099 [DAT](INFO): DAT read: Bearing 92.9, 28.5 (Local) 2025-08-28T18:02:33.100Z,1756404153.100 [DAT](INFO): Local bearing/azimuth received: Bearing 92.9, 28.5 (Local) 2025-08-28T18:02:33.101Z,1756404153.101 [DAT](INFO): DAT read: Range 11 to 50 : 1.7 m (Round-trip 2.3 ms) speed 0.0 m/s 2025-08-28T18:02:33.102Z,1756404153.102 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:02:33.102Z,1756404153.102 [DAT](INFO): direction in FSK: [-0.241263,0.463462,0.852640] 2025-08-28T18:02:33.103Z,1756404153.103 [DAT](INFO): publishing direction and range info 2025-08-28T18:02:42.061Z,1756404162.061 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD +2.26, +60.82, 0.03, 8.14, 0.00 2025-08-28T18:03:51.944Z,1756404231.944 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD +2.15, +60.79, +0.06, 0.00 2025-08-28T18:03:57.188Z,1756404237.188 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00030421512200,3.3, 0.0 0 2025-08-28T18:04:29.118Z,1756404269.118 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-28T18:04:29.157Z,1756404269.157 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:04:29.157Z,1756404269.157 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:04:29.409Z,1756404269.409 [DAT](INFO): DAT read: user:4553> 2025-08-28T18:04:29.410Z,1756404269.410 [DAT](INFO): DAT read: Tx time:18:04:28.2762 2025-08-28T18:04:29.410Z,1756404269.410 [DAT](INFO): Ping request sent. 2025-08-28T18:04:29.410Z,1756404269.410 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:04:29.411Z,1756404269.411 [DAT](INFO): publishing transmit ping time 2025-08-28T18:04:29.412Z,1756404269.412 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001089 2025-08-28T18:04:29.660Z,1756404269.660 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.249926 2025-08-28T18:04:29.913Z,1756404269.913 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502480 2025-08-28T18:04:30.165Z,1756404270.165 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753993 2025-08-28T18:04:30.417Z,1756404270.417 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006275 2025-08-28T18:04:30.669Z,1756404270.669 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258232 2025-08-28T18:04:30.920Z,1756404270.920 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509968 2025-08-28T18:04:31.173Z,1756404271.173 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762317 2025-08-28T18:04:31.425Z,1756404271.425 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014661 2025-08-28T18:04:31.677Z,1756404271.677 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266015 2025-08-28T18:04:31.928Z,1756404271.928 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517891 2025-08-28T18:04:32.181Z,1756404272.181 [DAT](INFO): DAT read: Rx Time:18:04:30.6828 2025-08-28T18:04:32.181Z,1756404272.181 [DAT](INFO): Rx dataTimestamp_ set to:1756404272.180691 2025-08-28T18:04:32.181Z,1756404272.181 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771000 2025-08-28T18:04:32.436Z,1756404272.436 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.025471 2025-08-28T18:04:32.687Z,1756404272.687 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275971 2025-08-28T18:04:32.942Z,1756404272.942 [DAT](INFO): DAT read: 18:04:30.6828 LVL= 19568, 13905, 18082, 26707, AGC= 41, IDX= 437, 0.25, 1.995, 1.988, 1.105,-3.108, PHS=-1.078,-1.142,-2.114, RAW= 33.2, 56.7, CAL= 31.8, 60.4, ROT= 118.2, -60.4 2025-08-28T18:04:32.943Z,1756404272.943 [DAT](INFO): got valid direction response: 18:04:30.6828 LVL= 19568, 13905, 18082, 26707, AGC= 41, IDX= 437, 0.25, 1.995, 1.988, 1.105,-3.108, PHS=-1.078,-1.142,-2.114, RAW= 33.2, 56.7, CAL= 31.8, 60.4, ROT= 118.2, -60.4 2025-08-28T18:04:32.944Z,1756404272.944 [DAT](INFO): DAT read: Bearing 91.2, 27.1 (Local) 2025-08-28T18:04:32.944Z,1756404272.944 [DAT](INFO): Local bearing/azimuth received: Bearing 91.2, 27.1 (Local) 2025-08-28T18:04:32.945Z,1756404272.945 [DAT](INFO): DAT read: Range 11 to 50 : 1.8 m (Round-trip 2.4 ms) speed 0.0 m/s 2025-08-28T18:04:32.946Z,1756404272.946 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:04:32.947Z,1756404272.947 [DAT](INFO): direction in FSK: [-0.233413,0.435313,0.869495] 2025-08-28T18:04:32.947Z,1756404272.947 [DAT](INFO): publishing direction and range info 2025-08-28T18:05:07.085Z,1756404307.085 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00030421523205,35.0, +11.3, 0.0,1494 2025-08-28T18:06:22.228Z,1756404382.228 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 80.94, 81.62, 80.62 2025-08-28T18:06:29.157Z,1756404389.157 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-28T18:06:29.220Z,1756404389.220 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:06:29.221Z,1756404389.221 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:06:29.472Z,1756404389.472 [DAT](INFO): DAT read: user:4554> 2025-08-28T18:06:29.473Z,1756404389.473 [DAT](INFO): DAT read: Tx time:18:06:28.3757 2025-08-28T18:06:29.473Z,1756404389.473 [DAT](INFO): Ping request sent. 2025-08-28T18:06:29.473Z,1756404389.473 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:06:29.474Z,1756404389.474 [DAT](INFO): publishing transmit ping time 2025-08-28T18:06:29.474Z,1756404389.474 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000722 2025-08-28T18:06:29.725Z,1756404389.725 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251281 2025-08-28T18:06:29.977Z,1756404389.977 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503365 2025-08-28T18:06:30.228Z,1756404390.228 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754960 2025-08-28T18:06:30.481Z,1756404390.481 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007288 2025-08-28T18:06:30.733Z,1756404390.733 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259066 2025-08-28T18:06:30.984Z,1756404390.984 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510991 2025-08-28T18:06:31.237Z,1756404391.237 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763365 2025-08-28T18:06:31.489Z,1756404391.489 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015064 2025-08-28T18:06:31.740Z,1756404391.740 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267004 2025-08-28T18:06:31.994Z,1756404391.994 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.520102 2025-08-28T18:06:32.244Z,1756404392.244 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771047 2025-08-28T18:06:32.496Z,1756404392.496 [DAT](INFO): DAT read: Rx Time:18:06:30.7823 2025-08-28T18:06:32.497Z,1756404392.497 [DAT](INFO): Rx dataTimestamp_ set to:1756404392.496640 2025-08-28T18:06:32.497Z,1756404392.497 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024019 2025-08-28T18:06:32.749Z,1756404392.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275141 2025-08-28T18:06:33.006Z,1756404393.006 [DAT](INFO): DAT read: 18:06:30.7823 LVL= 17008, 11409, 17698, 24179, AGC= 40, IDX= 437, 0.10, 1.738, 1.666, 0.748, 2.910, PHS=-1.070,-1.199,-2.207, RAW= 35.9, 55.7, CAL= 34.4, 59.7, ROT= 115.6, -59.7 2025-08-28T18:06:33.007Z,1756404393.007 [DAT](INFO): got valid direction response: 18:06:30.7823 LVL= 17008, 11409, 17698, 24179, AGC= 40, IDX= 437, 0.10, 1.738, 1.666, 0.748, 2.910, PHS=-1.070,-1.199,-2.207, RAW= 35.9, 55.7, CAL= 34.4, 59.7, ROT= 115.6, -59.7 2025-08-28T18:06:33.008Z,1756404393.008 [DAT](INFO): DAT read: Bearing 99.2, 29.1 (Local) 2025-08-28T18:06:33.008Z,1756404393.008 [DAT](INFO): Local bearing/azimuth received: Bearing 99.2, 29.1 (Local) 2025-08-28T18:06:33.009Z,1756404393.009 [DAT](INFO): DAT read: Range 11 to 50 : 1.8 m (Round-trip 2.4 ms) speed 0.0 m/s 2025-08-28T18:06:33.010Z,1756404393.010 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:06:33.011Z,1756404393.011 [DAT](INFO): direction in FSK: [-0.217999,0.454999,0.863396] 2025-08-28T18:06:33.011Z,1756404393.011 [DAT](INFO): publishing direction and range info 2025-08-28T18:08:29.489Z,1756404509.489 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-28T18:08:29.505Z,1756404509.505 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:08:29.505Z,1756404509.505 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:08:29.756Z,1756404509.756 [DAT](INFO): DAT read: user:4555> 2025-08-28T18:08:29.757Z,1756404509.757 [DAT](INFO): DAT read: Tx time:18:08:28.6753 2025-08-28T18:08:29.758Z,1756404509.758 [DAT](INFO): Ping request sent. 2025-08-28T18:08:29.758Z,1756404509.758 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:08:29.758Z,1756404509.758 [DAT](INFO): publishing transmit ping time 2025-08-28T18:08:29.758Z,1756404509.758 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000732 2025-08-28T18:08:30.009Z,1756404510.009 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251372 2025-08-28T18:08:30.261Z,1756404510.261 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503456 2025-08-28T18:08:30.512Z,1756404510.512 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754834 2025-08-28T18:08:30.765Z,1756404510.765 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006955 2025-08-28T18:08:31.016Z,1756404511.016 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258812 2025-08-28T18:08:31.268Z,1756404511.268 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510828 2025-08-28T18:08:31.521Z,1756404511.521 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762905 2025-08-28T18:08:31.772Z,1756404511.772 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014758 2025-08-28T18:08:32.026Z,1756404512.026 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267774 2025-08-28T18:08:32.276Z,1756404512.276 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518733 2025-08-28T18:08:32.529Z,1756404512.529 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771086 2025-08-28T18:08:32.781Z,1756404512.781 [DAT](INFO): DAT read: Rx Time:18:08:31.0818 2025-08-28T18:08:32.782Z,1756404512.782 [DAT](INFO): Rx dataTimestamp_ set to:1756404512.781246 2025-08-28T18:08:32.782Z,1756404512.782 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024705 2025-08-28T18:08:33.032Z,1756404513.032 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274785 2025-08-28T18:08:33.290Z,1756404513.290 [DAT](INFO): DAT read: 18:08:31.0818 LVL= 19296, 15217, 18770, 25635, AGC= 41, IDX= 437,-0.08, 2.382, 2.387, 1.591,-2.731, PHS=-1.068,-1.120,-2.005, RAW= 32.8, 58.4, CAL= 31.5, 61.9, ROT= 118.5, -61.9 2025-08-28T18:08:33.291Z,1756404513.291 [DAT](INFO): got valid direction response: 18:08:31.0818 LVL= 19296, 15217, 18770, 25635, AGC= 41, IDX= 437,-0.08, 2.382, 2.387, 1.591,-2.731, PHS=-1.068,-1.120,-2.005, RAW= 32.8, 58.4, CAL= 31.5, 61.9, ROT= 118.5, -61.9 2025-08-28T18:08:33.292Z,1756404513.292 [DAT](INFO): DAT read: Bearing 94.6, 25.6 (Local) 2025-08-28T18:08:33.292Z,1756404513.292 [DAT](INFO): Local bearing/azimuth received: Bearing 94.6, 25.6 (Local) 2025-08-28T18:08:33.294Z,1756404513.294 [DAT](INFO): DAT read: Range 11 to 50 : 1.7 m (Round-trip 2.3 ms) speed 0.0 m/s 2025-08-28T18:08:33.294Z,1756404513.294 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:08:33.295Z,1756404513.295 [DAT](INFO): direction in FSK: [-0.224747,0.413933,0.882127] 2025-08-28T18:08:33.296Z,1756404513.296 [DAT](INFO): publishing direction and range info 2025-08-28T18:10:29.511Z,1756404629.511 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-28T18:10:29.513Z,1756404629.513 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:10:29.513Z,1756404629.513 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:10:29.764Z,1756404629.764 [DAT](INFO): DAT read: user:4556> 2025-08-28T18:10:29.765Z,1756404629.765 [DAT](INFO): DAT read: Tx time:18:10:28.6750 2025-08-28T18:10:29.765Z,1756404629.765 [DAT](INFO): Ping request sent. 2025-08-28T18:10:29.766Z,1756404629.766 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:10:29.766Z,1756404629.766 [DAT](INFO): publishing transmit ping time 2025-08-28T18:10:29.766Z,1756404629.766 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000728 2025-08-28T18:10:30.016Z,1756404630.016 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250895 2025-08-28T18:10:30.269Z,1756404630.269 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503098 2025-08-28T18:10:30.521Z,1756404630.521 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754922 2025-08-28T18:10:30.773Z,1756404630.773 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006961 2025-08-28T18:10:31.025Z,1756404631.025 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259150 2025-08-28T18:10:31.277Z,1756404631.277 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510947 2025-08-28T18:10:31.529Z,1756404631.529 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762968 2025-08-28T18:10:31.781Z,1756404631.781 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015155 2025-08-28T18:10:32.033Z,1756404632.033 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266955 2025-08-28T18:10:32.285Z,1756404632.285 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518991 2025-08-28T18:10:32.537Z,1756404632.537 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771181 2025-08-28T18:10:32.789Z,1756404632.789 [DAT](INFO): DAT read: Rx Time:18:10:31.0813 2025-08-28T18:10:32.789Z,1756404632.789 [DAT](INFO): Rx dataTimestamp_ set to:1756404632.788851 2025-08-28T18:10:32.790Z,1756404632.790 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024102 2025-08-28T18:10:33.040Z,1756404633.040 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274898 2025-08-28T18:10:33.298Z,1756404633.298 [DAT](INFO): DAT read: 18:10:31.0813 LVL= 19616, 13905, 17826, 26563, AGC= 41, IDX= 437,-0.06, 0.493, 0.454,-0.367, 1.649, PHS=-1.054,-1.151,-2.060, RAW= 35.0, 57.5, CAL= 33.6, 61.2, ROT= 116.4, -61.2 2025-08-28T18:10:33.299Z,1756404633.299 [DAT](INFO): got valid direction response: 18:10:31.0813 LVL= 19616, 13905, 17826, 26563, AGC= 41, IDX= 437,-0.06, 0.493, 0.454,-0.367, 1.649, PHS=-1.054,-1.151,-2.060, RAW= 35.0, 57.5, CAL= 33.6, 61.2, ROT= 116.4, -61.2 2025-08-28T18:10:33.300Z,1756404633.300 [DAT](INFO): DAT read: Bearing 91.9, 26.6 (Local) 2025-08-28T18:10:33.300Z,1756404633.300 [DAT](INFO): Local bearing/azimuth received: Bearing 91.9, 26.6 (Local) 2025-08-28T18:10:33.301Z,1756404633.301 [DAT](INFO): DAT read: Range 11 to 50 : 1.7 m (Round-trip 2.3 ms) speed 0.0 m/s 2025-08-28T18:10:33.302Z,1756404633.302 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:10:33.303Z,1756404633.303 [DAT](INFO): direction in FSK: [-0.214205,0.431512,0.876307] 2025-08-28T18:10:33.303Z,1756404633.303 [DAT](INFO): publishing direction and range info 2025-08-28T18:11:08.312Z,1756404668.312 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.95, 80.93, 80.59, 82.97 2025-08-28T18:12:29.532Z,1756404749.532 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-28T18:12:29.557Z,1756404749.557 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:12:29.557Z,1756404749.557 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:12:29.809Z,1756404749.809 [DAT](INFO): DAT read: user:4557> 2025-08-28T18:12:29.809Z,1756404749.809 [DAT](INFO): DAT read: Tx time:18:12:28.6745 2025-08-28T18:12:29.810Z,1756404749.810 [DAT](INFO): Ping request sent. 2025-08-28T18:12:29.810Z,1756404749.810 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:12:29.810Z,1756404749.810 [DAT](INFO): publishing transmit ping time 2025-08-28T18:12:29.810Z,1756404749.810 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000718 2025-08-28T18:12:30.061Z,1756404750.061 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250741 2025-08-28T18:12:30.313Z,1756404750.313 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502756 2025-08-28T18:12:30.565Z,1756404750.565 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754950 2025-08-28T18:12:30.816Z,1756404750.816 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006725 2025-08-28T18:12:31.068Z,1756404751.068 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258728 2025-08-28T18:12:31.321Z,1756404751.321 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510975 2025-08-28T18:12:31.576Z,1756404751.576 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.765380 2025-08-28T18:12:31.824Z,1756404751.824 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014649 2025-08-28T18:12:32.076Z,1756404752.076 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266691 2025-08-28T18:12:32.329Z,1756404752.329 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518854 2025-08-28T18:12:32.580Z,1756404752.580 [DAT](INFO): DAT read: Rx Time:18:12:31.0810 2025-08-28T18:12:32.581Z,1756404752.581 [DAT](INFO): Rx dataTimestamp_ set to:1756404752.580649 2025-08-28T18:12:32.581Z,1756404752.581 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771714 2025-08-28T18:12:32.832Z,1756404752.832 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022697 2025-08-28T18:12:33.087Z,1756404753.087 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.276927 2025-08-28T18:12:33.342Z,1756404753.342 [DAT](INFO): DAT read: 18:12:31.0810 LVL= 17376, 11985, 18498, 24899, AGC= 40, IDX= 438,-0.31, 1.139, 1.044, 0.159, 2.268, PHS=-1.027,-1.179,-2.152, RAW= 37.1, 55.5, CAL= 35.6, 59.5, ROT= 114.4, -59.5 2025-08-28T18:12:33.343Z,1756404753.343 [DAT](INFO): got valid direction response: 18:12:31.0810 LVL= 17376, 11985, 18498, 24899, AGC= 40, IDX= 438,-0.31, 1.139, 1.044, 0.159, 2.268, PHS=-1.027,-1.179,-2.152, RAW= 37.1, 55.5, CAL= 35.6, 59.5, ROT= 114.4, -59.5 2025-08-28T18:12:33.347Z,1756404753.347 [DAT](INFO): DAT read: Bearing 97.2, 29.2 (Local) 2025-08-28T18:12:33.348Z,1756404753.348 [DAT](INFO): Local bearing/azimuth received: Bearing 97.2, 29.2 (Local) 2025-08-28T18:12:33.349Z,1756404753.349 [DAT](INFO): DAT read: Range 11 to 50 : 1.8 m (Round-trip 2.4 ms) speed 0.0 m/s 2025-08-28T18:12:33.350Z,1756404753.350 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:12:33.351Z,1756404753.351 [DAT](INFO): direction in FSK: [-0.209666,0.462207,0.861629] 2025-08-28T18:12:33.351Z,1756404753.351 [DAT](INFO): publishing direction and range info 2025-08-28T18:13:27.676Z,1756404807.676 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, -17, +2, :7, +2, +0,A 2025-08-28T18:13:44.628Z,1756404824.628 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:13:57.613Z,1756404837.613 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:14:09.337Z,1756404849.337 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:14:21.050Z,1756404861.050 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:14:29.888Z,1756404869.888 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-28T18:14:29.926Z,1756404869.926 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:14:29.926Z,1756404869.926 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:14:30.177Z,1756404870.177 [DAT](INFO): DAT read: user:4558> 2025-08-28T18:14:30.178Z,1756404870.178 [DAT](INFO): DAT read: Tx time:18:14:29.0741 2025-08-28T18:14:30.178Z,1756404870.178 [DAT](INFO): Ping request sent. 2025-08-28T18:14:30.178Z,1756404870.178 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:14:30.178Z,1756404870.178 [DAT](INFO): publishing transmit ping time 2025-08-28T18:14:30.180Z,1756404870.180 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001911 2025-08-28T18:14:30.429Z,1756404870.429 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250889 2025-08-28T18:14:30.681Z,1756404870.681 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502719 2025-08-28T18:14:30.933Z,1756404870.933 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754572 2025-08-28T18:14:31.185Z,1756404871.185 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006616 2025-08-28T18:14:31.437Z,1756404871.437 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259086 2025-08-28T18:14:31.688Z,1756404871.688 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510538 2025-08-28T18:14:31.941Z,1756404871.941 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762760 2025-08-28T18:14:32.192Z,1756404872.192 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014511 2025-08-28T18:14:32.444Z,1756404872.444 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266561 2025-08-28T18:14:32.697Z,1756404872.697 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518678 2025-08-28T18:14:32.949Z,1756404872.949 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770734 2025-08-28T18:14:33.170Z,1756404873.170 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:14:33.201Z,1756404873.201 [DAT](INFO): DAT read: Rx Time:18:14:31.4806 2025-08-28T18:14:33.201Z,1756404873.201 [DAT](INFO): Rx dataTimestamp_ set to:1756404873.200866 2025-08-28T18:14:33.202Z,1756404873.202 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023757 2025-08-28T18:14:33.452Z,1756404873.452 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274550 2025-08-28T18:14:33.710Z,1756404873.710 [DAT](INFO): DAT read: 18:14:31.4806 LVL= 19184, 12769, 19714, 27059, AGC= 41, IDX= 438,-0.40,-0.586,-0.709,-1.573, 0.532, PHS=-1.016,-1.196,-2.149, RAW= 38.5, 55.6, CAL= 36.9, 59.6, ROT= 113.1, -59.6 2025-08-28T18:14:33.711Z,1756404873.711 [DAT](INFO): got valid direction response: 18:14:31.4806 LVL= 19184, 12769, 19714, 27059, AGC= 41, IDX= 438,-0.40,-0.586,-0.709,-1.573, 0.532, PHS=-1.016,-1.196,-2.149, RAW= 38.5, 55.6, CAL= 36.9, 59.6, ROT= 113.1, -59.6 2025-08-28T18:14:33.712Z,1756404873.712 [DAT](INFO): DAT read: Bearing 97.1, 29.7 (Local) 2025-08-28T18:14:33.712Z,1756404873.712 [DAT](INFO): Local bearing/azimuth received: Bearing 97.1, 29.7 (Local) 2025-08-28T18:14:33.713Z,1756404873.713 [DAT](INFO): DAT read: Range 11 to 50 : 1.8 m (Round-trip 2.4 ms) speed 0.0 m/s 2025-08-28T18:14:33.714Z,1756404873.714 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:14:33.715Z,1756404873.715 [DAT](INFO): direction in FSK: [-0.198536,0.465461,0.862514] 2025-08-28T18:14:33.715Z,1756404873.715 [DAT](INFO): publishing direction and range info 2025-08-28T18:14:37.568Z,1756404877.568 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, 03, +61.08, , 8.14, 0.00 2025-08-28T18:14:42.463Z,1756404882.463 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00030422020754,35.0, +11.3,494.6, 0 2025-08-28T18:14:44.896Z,1756404884.896 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:14:56.656Z,1756404896.656 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:15:08.323Z,1756404908.323 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:15:20.088Z,1756404920.088 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:15:31.760Z,1756404931.760 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:15:43.874Z,1756404943.874 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:15:55.604Z,1756404955.604 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:16:07.756Z,1756404967.756 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:16:19.440Z,1756404979.440 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:16:30.305Z,1756404990.305 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-28T18:16:30.325Z,1756404990.325 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:16:30.325Z,1756404990.325 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:16:30.576Z,1756404990.576 [DAT](INFO): DAT read: user:4559> 2025-08-28T18:16:30.577Z,1756404990.577 [DAT](INFO): DAT read: Tx time:18:16:29.4737 2025-08-28T18:16:30.577Z,1756404990.577 [DAT](INFO): Ping request sent. 2025-08-28T18:16:30.577Z,1756404990.577 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:16:30.578Z,1756404990.578 [DAT](INFO): publishing transmit ping time 2025-08-28T18:16:30.578Z,1756404990.578 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000727 2025-08-28T18:16:30.829Z,1756404990.829 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251051 2025-08-28T18:16:31.081Z,1756404991.081 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503062 2025-08-28T18:16:31.332Z,1756404991.332 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754946 2025-08-28T18:16:31.548Z,1756404991.548 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:16:31.585Z,1756404991.585 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007547 2025-08-28T18:16:31.837Z,1756404991.837 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259187 2025-08-28T18:16:32.088Z,1756404992.088 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510981 2025-08-28T18:16:32.341Z,1756404992.341 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763055 2025-08-28T18:16:32.592Z,1756404992.592 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014948 2025-08-28T18:16:32.844Z,1756404992.844 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266963 2025-08-28T18:16:33.097Z,1756404993.097 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519026 2025-08-28T18:16:33.349Z,1756404993.349 [DAT](INFO): DAT read: Rx Time:18:16:31.8802 2025-08-28T18:16:33.349Z,1756404993.349 [DAT](INFO): Rx dataTimestamp_ set to:1756404993.348764 2025-08-28T18:16:33.350Z,1756404993.350 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772086 2025-08-28T18:16:33.600Z,1756404993.600 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022989 2025-08-28T18:16:33.853Z,1756404993.853 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275265 2025-08-28T18:16:34.110Z,1756404994.110 [DAT](INFO): DAT read: 18:16:31.8802 LVL= 17232, 11249, 19266, 26083, AGC= 40, IDX= 437, 0.01,-1.246,-1.301,-2.247,-0.089, PHS=-1.055,-1.168,-2.203, RAW= 35.1, 55.0, CAL= 33.6, 59.0, ROT= 116.4, -59.0 2025-08-28T18:16:34.111Z,1756404994.111 [DAT](INFO): got valid direction response: 18:16:31.8802 LVL= 17232, 11249, 19266, 26083, AGC= 40, IDX= 437, 0.01,-1.246,-1.301,-2.247,-0.089, PHS=-1.055,-1.168,-2.203, RAW= 35.1, 55.0, CAL= 33.6, 59.0, ROT= 116.4, -59.0 2025-08-28T18:16:34.112Z,1756404994.112 [DAT](INFO): DAT read: Bearing 98.3, 28.7 (Local) 2025-08-28T18:16:34.112Z,1756404994.112 [DAT](INFO): Local bearing/azimuth received: Bearing 98.3, 28.7 (Local) 2025-08-28T18:16:34.113Z,1756404994.113 [DAT](INFO): DAT read: Range 11 to 50 : 1.8 m (Round-trip 2.4 ms) speed 0.0 m/s 2025-08-28T18:16:34.114Z,1756404994.114 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:16:34.115Z,1756404994.115 [DAT](INFO): direction in FSK: [-0.229004,0.461326,0.857167] 2025-08-28T18:16:34.115Z,1756404994.115 [DAT](INFO): publishing direction and range info 2025-08-28T18:16:43.308Z,1756405003.308 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:16:55.443Z,1756405015.443 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:17:02.612Z,1756405022.612 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, ,61.35, 8.15, 0.00 2025-08-28T18:17:07.103Z,1756405027.103 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:17:19.222Z,1756405039.222 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:17:32.150Z,1756405052.150 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:17:39.382Z,1756405059.382 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:17:39.383Z,1756405059.383 [BPC1](FAULT): Charging on dock, ignoring failure to receieve battery data and resetting batt data check 2025-08-28T18:17:44.270Z,1756405064.270 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:17:50.323Z,1756405070.323 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:17:55.987Z,1756405075.987 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:18:01.227Z,1756405081.227 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:18:07.697Z,1756405087.697 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:18:12.132Z,1756405092.132 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:18:19.425Z,1756405099.425 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:18:22.713Z,1756405102.713 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:18:30.680Z,1756405110.680 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-28T18:18:30.844Z,1756405110.844 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:18:30.844Z,1756405110.844 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:18:31.096Z,1756405111.096 [DAT](INFO): DAT read: user:4560> 2025-08-28T18:18:31.097Z,1756405111.097 [DAT](INFO): DAT read: Tx time:18:18:29.9734 2025-08-28T18:18:31.097Z,1756405111.097 [DAT](INFO): Ping request sent. 2025-08-28T18:18:31.098Z,1756405111.098 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:18:31.098Z,1756405111.098 [DAT](INFO): publishing transmit ping time 2025-08-28T18:18:31.098Z,1756405111.098 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000734 2025-08-28T18:18:31.348Z,1756405111.348 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250813 2025-08-28T18:18:31.542Z,1756405111.542 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:18:31.610Z,1756405111.610 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.511807 2025-08-28T18:18:31.861Z,1756405111.861 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.763421 2025-08-28T18:18:32.112Z,1756405112.112 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.014782 2025-08-28T18:18:32.372Z,1756405112.372 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.273936 2025-08-28T18:18:32.624Z,1756405112.624 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.526842 2025-08-28T18:18:32.876Z,1756405112.876 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.778774 2025-08-28T18:18:33.129Z,1756405113.129 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.031033 2025-08-28T18:18:33.381Z,1756405113.381 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.282866 2025-08-28T18:18:33.552Z,1756405113.552 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:18:33.632Z,1756405113.632 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.534827 2025-08-28T18:18:33.885Z,1756405113.885 [DAT](INFO): DAT read: Rx Time:18:18:32.3798 2025-08-28T18:18:33.885Z,1756405113.885 [DAT](INFO): Rx dataTimestamp_ set to:1756405113.884946 2025-08-28T18:18:33.886Z,1756405113.886 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.788118 2025-08-28T18:18:34.137Z,1756405114.137 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.038983 2025-08-28T18:18:34.392Z,1756405114.392 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.293310 2025-08-28T18:18:34.646Z,1756405114.646 [DAT](INFO): DAT read: 18:18:32.3798 LVL= 19632, 12705, 17618, 26163, AGC= 41, IDX= 438,-0.31, 1.858, 1.709, 0.901, 2.967, PHS=-1.007,-1.213,-2.110, RAW= 40.1, 56.4, CAL= 38.5, 60.4, ROT= 111.5, -60.4 2025-08-28T18:18:34.647Z,1756405114.647 [DAT](INFO): got valid direction response: 18:18:32.3798 LVL= 19632, 12705, 17618, 26163, AGC= 41, IDX= 438,-0.31, 1.858, 1.709, 0.901, 2.967, PHS=-1.007,-1.213,-2.110, RAW= 40.1, 56.4, CAL= 38.5, 60.4, ROT= 111.5, -60.4 2025-08-28T18:18:34.648Z,1756405114.648 [DAT](INFO): DAT read: Bearing 94.3, 29.2 (Local) 2025-08-28T18:18:34.648Z,1756405114.648 [DAT](INFO): Local bearing/azimuth received: Bearing 94.3, 29.2 (Local) 2025-08-28T18:18:34.649Z,1756405114.649 [DAT](INFO): DAT read: Range 11 to 50 : 1.8 m (Round-trip 2.4 ms) speed 0.0 m/s 2025-08-28T18:18:34.650Z,1756405114.650 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:18:34.651Z,1756405114.651 [DAT](INFO): direction in FSK: [-0.181030,0.459572,0.869495] 2025-08-28T18:18:34.651Z,1756405114.651 [DAT](INFO): publishing direction and range info 2025-08-28T18:18:43.264Z,1756405123.264 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:18:44.472Z,1756405124.472 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:18:55.371Z,1756405135.371 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:19:06.301Z,1756405146.301 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:19:16.834Z,1756405156.834 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:19:27.738Z,1756405167.738 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:19:27.746Z,1756405167.746 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00030422065277,35.0, +0.0,1494.6, 0 2025-08-28T18:19:38.598Z,1756405178.598 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:19:49.508Z,1756405189.508 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:20:00.422Z,1756405200.422 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:20:18.586Z,1756405218.586 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:20:30.694Z,1756405230.694 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-28T18:20:30.912Z,1756405230.912 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:20:30.913Z,1756405230.913 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:20:31.164Z,1756405231.164 [DAT](INFO): DAT read: user:4561> 2025-08-28T18:20:31.165Z,1756405231.165 [DAT](INFO): DAT read: Tx time:18:20:30.0729 2025-08-28T18:20:31.165Z,1756405231.165 [DAT](INFO): Ping request sent. 2025-08-28T18:20:31.165Z,1756405231.165 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:20:31.166Z,1756405231.166 [DAT](INFO): publishing transmit ping time 2025-08-28T18:20:31.166Z,1756405231.166 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000735 2025-08-28T18:20:31.418Z,1756405231.418 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251993 2025-08-28T18:20:31.669Z,1756405231.669 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503088 2025-08-28T18:20:31.921Z,1756405231.921 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755037 2025-08-28T18:20:32.173Z,1756405232.173 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007233 2025-08-28T18:20:32.425Z,1756405232.425 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259650 2025-08-28T18:20:32.676Z,1756405232.676 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510836 2025-08-28T18:20:32.929Z,1756405232.929 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763223 2025-08-28T18:20:33.181Z,1756405233.181 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015022 2025-08-28T18:20:33.433Z,1756405233.433 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267267 2025-08-28T18:20:33.684Z,1756405233.684 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518939 2025-08-28T18:20:33.937Z,1756405233.937 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771084 2025-08-28T18:20:34.188Z,1756405234.188 [DAT](INFO): DAT read: Rx Time:18:20:32.4794 2025-08-28T18:20:34.189Z,1756405234.189 [DAT](INFO): Rx dataTimestamp_ set to:1756405234.188666 2025-08-28T18:20:34.189Z,1756405234.189 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023983 2025-08-28T18:20:34.441Z,1756405234.441 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275672 2025-08-28T18:20:34.698Z,1756405234.698 [DAT](INFO): DAT read: 18:20:32.4794 LVL= 19552, 14241, 18914, 25795, AGC= 41, IDX= 437, 0.13,-0.233,-0.307,-1.137, 0.924, PHS=-1.056,-1.186,-2.105, RAW= 36.5, 57.2, CAL= 35.1, 61.0, ROT= 114.9, -61.0 2025-08-28T18:20:34.699Z,1756405234.699 [DAT](INFO): got valid direction response: 18:20:32.4794 LVL= 19552, 14241, 18914, 25795, AGC= 41, IDX= 437, 0.13,-0.233,-0.307,-1.137, 0.924, PHS=-1.056,-1.186,-2.105, RAW= 36.5, 57.2, CAL= 35.1, 61.0, ROT= 114.9, -61.0 2025-08-28T18:20:34.704Z,1756405234.704 [DAT](INFO): DAT read: Bearing 97.8, 27.8 (Local) 2025-08-28T18:20:34.704Z,1756405234.704 [DAT](INFO): Local bearing/azimuth received: Bearing 97.8, 27.8 (Local) 2025-08-28T18:20:34.705Z,1756405234.705 [DAT](INFO): DAT read: Range 11 to 50 : 1.8 m (Round-trip 2.4 ms) speed 0.0 m/s 2025-08-28T18:20:34.706Z,1756405234.706 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:20:34.707Z,1756405234.707 [DAT](INFO): direction in FSK: [-0.204122,0.439744,0.874620] 2025-08-28T18:20:34.707Z,1756405234.707 [DAT](INFO): publishing direction and range info 2025-08-28T18:20:48.503Z,1756405248.503 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:20:57.375Z,1756405257.375 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:22:16.971Z,1756405336.971 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-28T18:22:30.695Z,1756405350.695 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-08-28T18:22:30.920Z,1756405350.920 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:22:30.921Z,1756405350.921 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:22:31.172Z,1756405351.172 [DAT](INFO): DAT read: user:4562> 2025-08-28T18:22:31.173Z,1756405351.173 [DAT](INFO): DAT read: Tx time:18:22:30.0724 2025-08-28T18:22:31.173Z,1756405351.173 [DAT](INFO): Ping request sent. 2025-08-28T18:22:31.173Z,1756405351.173 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:22:31.174Z,1756405351.174 [DAT](INFO): publishing transmit ping time 2025-08-28T18:22:31.174Z,1756405351.174 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000743 2025-08-28T18:22:31.425Z,1756405351.425 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251752 2025-08-28T18:22:31.677Z,1756405351.677 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503001 2025-08-28T18:22:31.929Z,1756405351.929 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755189 2025-08-28T18:22:32.181Z,1756405352.181 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007183 2025-08-28T18:22:32.433Z,1756405352.433 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259652 2025-08-28T18:22:32.685Z,1756405352.685 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511162 2025-08-28T18:22:32.937Z,1756405352.937 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763168 2025-08-28T18:22:33.189Z,1756405353.189 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015035 2025-08-28T18:22:33.441Z,1756405353.441 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267300 2025-08-28T18:22:33.692Z,1756405353.692 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518909 2025-08-28T18:22:33.945Z,1756405353.945 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771624 2025-08-28T18:22:34.196Z,1756405354.196 [DAT](INFO): DAT read: Rx Time:18:22:32.4790 2025-08-28T18:22:34.197Z,1756405354.197 [DAT](INFO): Rx dataTimestamp_ set to:1756405354.196632 2025-08-28T18:22:34.197Z,1756405354.197 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023961 2025-08-28T18:22:34.448Z,1756405354.448 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274985 2025-08-28T18:22:34.706Z,1756405354.706 [DAT](INFO): DAT read: 18:22:32.4790 LVL= 19584, 13761, 18914, 27379, AGC= 41, IDX= 438,-0.23,-1.663,-1.743,-2.619,-0.530, PHS=-1.031,-1.168,-2.133, RAW= 36.6, 55.8, CAL= 35.0, 59.7, ROT= 115.0, -59.7 2025-08-28T18:22:34.707Z,1756405354.707 [DAT](INFO): got valid direction response: 18:22:32.4790 LVL= 19584, 13761, 18914, 27379, AGC= 41, IDX= 438,-0.23,-1.663,-1.743,-2.619,-0.530, PHS=-1.031,-1.168,-2.133, RAW= 36.6, 55.8, CAL= 35.0, 59.7, ROT= 115.0, -59.7 2025-08-28T18:22:34.708Z,1756405354.708 [DAT](INFO): DAT read: Bearing 97.7, 28.7 (Local) 2025-08-28T18:22:34.708Z,1756405354.708 [DAT](INFO): Local bearing/azimuth received: Bearing 97.7, 28.7 (Local) 2025-08-28T18:22:34.709Z,1756405354.709 [DAT](INFO): DAT read: Range 11 to 50 : 1.8 m (Round-trip 2.4 ms) speed 0.0 m/s 2025-08-28T18:22:34.710Z,1756405354.710 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:22:34.711Z,1756405354.711 [DAT](INFO): direction in FSK: [-0.213223,0.457257,0.863396] 2025-08-28T18:22:34.711Z,1756405354.711 [DAT](INFO): publishing direction and range info 2025-08-28T18:23:02.993Z,1756405382.993 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 81.27, 80.59, 82.2-32768,-32768,-32768,-32768,V 2025-08-28T18:23:41.810Z,1756405421.810 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:23:53.580Z,1756405433.580 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:24:04.846Z,1756405444.846 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:24:06.022Z,1756405446.022 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, +29, +0,A 2025-08-28T18:24:10.553Z,1756405450.553 [DAT](INFO): DAT read: user:4563>Rx Time:18:24:08.8973 2025-08-28T18:24:10.553Z,1756405450.553 [DAT](INFO): Rx dataTimestamp_ set to:1756405450.552803 2025-08-28T18:24:11.061Z,1756405451.061 [DAT](INFO): DAT read: 18:24:08.8973 LVL= 17600, 9713, 15922, 24307, AGC= 42, IDX= 149,-0.16,-0.600,-0.750,-1.619, 0.514, PHS=-1.011,-1.219,-2.176, RAW= 39.6, 55.4, CAL= 38.0, 59.4, ROT= 112.0, -59.4 2025-08-28T18:24:11.062Z,1756405451.062 [DAT](INFO): got valid direction response: 18:24:08.8973 LVL= 17600, 9713, 15922, 24307, AGC= 42, IDX= 149,-0.16,-0.600,-0.750,-1.619, 0.514, PHS=-1.011,-1.219,-2.176, RAW= 39.6, 55.4, CAL= 38.0, 59.4, ROT= 112.0, -59.4 2025-08-28T18:24:11.062Z,1756405451.062 [DAT](INFO): DAT read: 2025-08-28T18:24:11.064Z,1756405451.064 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-08-28T18:24:11.064Z,1756405451.064 [DAT](INFO): commRate: 600 2025-08-28T18:24:11.065Z,1756405451.065 [DAT](INFO): DAT read: Tx time:18:24:09.9709 2025-08-28T18:24:11.065Z,1756405451.065 [DAT](INFO): Ping request sent. 2025-08-28T18:24:11.065Z,1756405451.065 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:24:11.065Z,1756405451.065 [DAT](INFO): #Rx 2: Read direction message, but no range. 2025-08-28T18:24:11.066Z,1756405451.066 [DAT](INFO): direction in FSK: [-0.190690,0.471975,0.860742] 2025-08-28T18:24:11.067Z,1756405451.067 [DAT](INFO): publishing transmit ping time 2025-08-28T18:24:11.074Z,1756405451.074 [DAT](INFO): publishing transmit ping time 2025-08-28T18:24:16.570Z,1756405456.570 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:24:18.616Z,1756405458.616 [DAT](INFO): DAT read: Rx Time:18:24:16.9474 2025-08-28T18:24:18.617Z,1756405458.617 [DAT](INFO): Rx dataTimestamp_ set to:1756405458.616649 2025-08-28T18:24:19.124Z,1756405459.124 [DAT](INFO): DAT read: 18:24:16.9473 LVL= 20080, 15425, 18866, 26051, AGC= 41, IDX= 454,-0.48, 1.135, 1.041, 0.253, 2.265, PHS=-1.029,-1.179,-2.056, RAW= 37.8, 57.5, CAL= 36.3, 61.2, ROT= 113.7, -61.2 2025-08-28T18:24:19.125Z,1756405459.125 [DAT](INFO): got valid direction response: 18:24:16.9473 LVL= 20080, 15425, 18866, 26051, AGC= 41, IDX= 454,-0.48, 1.135, 1.041, 0.253, 2.265, PHS=-1.029,-1.179,-2.056, RAW= 37.8, 57.5, CAL= 36.3, 61.2, ROT= 113.7, -61.2 2025-08-28T18:24:19.125Z,1756405459.125 [DAT](INFO): #Rx 3: Read direction message, but no range. 2025-08-28T18:24:19.126Z,1756405459.126 [DAT](INFO): direction in FSK: [-0.193640,0.441124,0.876307] 2025-08-28T18:24:19.374Z,1756405459.374 [DAT](INFO): DAT read: 2025-08-28T18:24:19.374Z,1756405459.374 [DAT](INFO): DAT read: DATA(0005):stop 2025-08-28T18:24:19.375Z,1756405459.375 [DAT](INFO): Got DATA 5 2025-08-28T18:24:19.375Z,1756405459.375 [DAT](INFO): DAT read: 2025-08-28T18:24:19.376Z,1756405459.376 [DAT](INFO): DAT read: Source:050 Destination:011 2025-08-28T18:24:19.376Z,1756405459.376 [DAT](INFO): Got Src/Dest after DATA 2025-08-28T18:24:19.376Z,1756405459.376 [DAT](INFO): DATA Src=50, Dst=11 2025-08-28T18:24:19.377Z,1756405459.377 [DAT](INFO): DAT read: CRC:Pass MPD:03.3 PSNR:17.9 AGC:41 SPD:-0.1 CCERR:013 2025-08-28T18:24:19.377Z,1756405459.377 [DAT](INFO): Got CRC:Pass 2025-08-28T18:24:19.378Z,1756405459.378 [DAT](INFO): Got CRC:Pass 2025-08-28T18:24:19.378Z,1756405459.378 [DAT](INFO): Incoming data is intended for us 2025-08-28T18:24:19.378Z,1756405459.378 [DAT](INFO): Received command: stop 2025-08-28T18:24:19.378Z,1756405459.378 [DAT](INFO): #Outgoing data=1 2025-08-28T18:24:19.378Z,1756405459.378 [DAT](INFO): Sending ack 2025-08-28T18:24:19.378Z,1756405459.378 [DAT](INFO): DAT read: 2025-08-28T18:24:19.379Z,1756405459.379 [DAT](INFO): DAT read: 2025-08-28T18:24:19.379Z,1756405459.379 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2025-08-28T18:24:19.393Z,1756405459.393 [CommandExec](IMPORTANT): got command stop 2025-08-28T18:24:19.393Z,1756405459.393 [CommandExec](IMPORTANT): Scheduling is paused 2025-08-28T18:24:19.393Z,1756405459.393 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2025-08-28T18:24:19.624Z,1756405459.624 [DAT](INFO): setting remote address to 50 2025-08-28T18:24:19.755Z,1756405459.755 [MissionManager](INFO): MissionManager is completed. 2025-08-28T18:24:19.756Z,1756405459.756 [MissionManager](INFO): Uninitializing Mission OnDock 2025-08-28T18:24:19.756Z,1756405459.756 [OnDock] Stopped 2025-08-28T18:24:19.756Z,1756405459.756 [OnDock](DEBUG): Aggregate::uninitialize OnDock 2025-08-28T18:24:19.756Z,1756405459.756 [OnDock:StandardEnvelopes] Stopped 2025-08-28T18:24:19.756Z,1756405459.756 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes 2025-08-28T18:24:19.756Z,1756405459.756 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-08-28T18:24:19.756Z,1756405459.756 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-08-28T18:24:19.756Z,1756405459.756 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-08-28T18:24:19.756Z,1756405459.756 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-08-28T18:24:19.756Z,1756405459.756 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-08-28T18:24:19.756Z,1756405459.756 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-08-28T18:24:19.757Z,1756405459.757 [OnDock:BackseatDriver] Stopped 2025-08-28T18:24:19.757Z,1756405459.757 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver 2025-08-28T18:24:19.757Z,1756405459.757 [OnDock:BackseatDriver:A.BackseatDriver] Stopped 2025-08-28T18:24:19.757Z,1756405459.757 [OnDock:PowerOnly] Stopped 2025-08-28T18:24:19.757Z,1756405459.757 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly 2025-08-28T18:24:19.757Z,1756405459.757 [OnDock:PowerOnly:E.Wait] Stopped 2025-08-28T18:24:19.757Z,1756405459.757 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-08-28T18:24:19.757Z,1756405459.757 [OnDock:dataRead] Stopped 2025-08-28T18:24:19.757Z,1756405459.757 [OnDock:dataRead](DEBUG): Aggregate::uninitialize OnDock:dataRead 2025-08-28T18:24:19.757Z,1756405459.757 [OnDock:dataRead:A] Stopped 2025-08-28T18:24:19.757Z,1756405459.757 [OnDock:J.] Stopped 2025-08-28T18:24:19.757Z,1756405459.757 [OnDock:J.](DEBUG): Uninitializing TrackAcousticContact. 2025-08-28T18:24:19.758Z,1756405459.758 [OnDock:PeriodicCheck] Stopped 2025-08-28T18:24:19.758Z,1756405459.758 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck 2025-08-28T18:24:19.758Z,1756405459.758 [OnDock:PeriodicCheck:CheckIP] Stopped 2025-08-28T18:24:19.758Z,1756405459.758 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP 2025-08-28T18:24:19.758Z,1756405459.758 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped 2025-08-28T18:24:19.758Z,1756405459.758 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component. 2025-08-28T18:24:19.758Z,1756405459.758 [OnDock:N.Docked] Stopped 2025-08-28T18:24:19.876Z,1756405459.876 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:20.128Z,1756405460.128 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:20.179Z,1756405460.179 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-08-28T18:24:20.179Z,1756405460.179 [DefaultWithUndock] Running Loop=1 2025-08-28T18:24:20.180Z,1756405460.180 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-08-28T18:24:20.180Z,1756405460.180 [DefaultWithUndock:A.Wait] Running Loop=1 2025-08-28T18:24:20.180Z,1756405460.180 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-08-28T18:24:20.380Z,1756405460.380 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:20.633Z,1756405460.633 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:20.884Z,1756405460.884 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:21.136Z,1756405461.136 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:21.388Z,1756405461.388 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:21.640Z,1756405461.640 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:21.892Z,1756405461.892 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:22.144Z,1756405462.144 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:22.396Z,1756405462.396 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:22.648Z,1756405462.648 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:22.900Z,1756405462.900 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:23.152Z,1756405463.152 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:23.404Z,1756405463.404 [DAT](DEBUG): checking for remote address setting acknowledgment 2025-08-28T18:24:23.657Z,1756405463.657 [DAT](INFO): DAT read: Forwarding Delay UpTx time:18:24:22.6208 2025-08-28T18:24:23.657Z,1756405463.657 [DAT](DEBUG): Re-entering command mode due to unexpected online mode. 2025-08-28T18:24:23.712Z,1756405463.712 [DAT](INFO): entering command mode 2025-08-28T18:24:23.712Z,1756405463.712 [DAT](ERROR): Failure setting remote address to 50 2025-08-28T18:24:23.908Z,1756405463.908 [DAT](INFO): DAT read: 2025-08-28T18:24:23.909Z,1756405463.909 [DAT](INFO): DAT read: user:4563> 2025-08-28T18:24:23.909Z,1756405463.909 [DAT](INFO): setting remote address to 50 2025-08-28T18:24:24.161Z,1756405464.161 [DAT](INFO): DAT read: user:4563> 2025-08-28T18:24:24.161Z,1756405464.161 [DAT](INFO): DAT read: RemoteAddr | 50 2025-08-28T18:24:24.162Z,1756405464.162 [DAT](INFO): set remote address to 50 2025-08-28T18:24:24.162Z,1756405464.162 [DAT](INFO): entering online mode 2025-08-28T18:24:24.412Z,1756405464.412 [DAT](INFO): DAT read: user:4564> 2025-08-28T18:24:24.413Z,1756405464.413 [DAT](INFO): DAT read: 2025-08-28T18:24:24.414Z,1756405464.414 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-08-28T18:24:24.414Z,1756405464.414 [DAT](INFO): commRate: 600 2025-08-28T18:24:24.414Z,1756405464.414 [DAT](INFO): online mode acknowledged 2025-08-28T18:24:24.415Z,1756405464.415 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2025-08-28T18:24:27.689Z,1756405467.689 [DAT](INFO): DAT read: Forwarding Delay UpTx time:18:24:26.6208 2025-08-28T18:24:27.689Z,1756405467.689 [DAT](DEBUG): In parseResponses, sent ack so set commsState_ = SENDING_VERIFIED 2025-08-28T18:24:27.689Z,1756405467.689 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2025-08-28T18:24:28.292Z,1756405468.292 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:24:50.498Z,1756405490.498 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2025-08-28T18:24:50.498Z,1756405490.498 [DefaultWithUndock:A.Wait] Stopped 2025-08-28T18:24:50.499Z,1756405490.499 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-08-28T18:24:50.499Z,1756405490.499 [DefaultWithUndock:LeaveDock] Running Loop=1 2025-08-28T18:24:50.499Z,1756405490.499 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2025-08-28T18:24:50.499Z,1756405490.499 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-08-28T18:24:50.504Z,1756405490.504 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2025-08-28T18:24:50.504Z,1756405490.504 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2025-08-28T18:24:50.504Z,1756405490.504 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-08-28T18:24:50.505Z,1756405490.505 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-28T18:24:50.866Z,1756405490.866 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-08-28T18:24:50.866Z,1756405490.866 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-28T18:24:50.867Z,1756405490.867 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock at depth 65.886223 m. 2025-08-28T18:24:50.868Z,1756405490.868 [DefaultWithUndock:LeaveDock:A.](ERROR): Failed to read sound speed 2025-08-28T18:24:51.321Z,1756405491.321 [DockingStepper](INFO): Changing to mode: 3 2025-08-28T18:24:54.377Z,1756405494.377 [CTD_Seabird](ERROR): Failed to parse device response: 2025-08-28T18:24:57.337Z,1756405497.337 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:24:57.338Z,1756405497.338 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Engaging thruster for 10.0 seconds (max) to disengage. 2025-08-28T18:24:57.763Z,1756405497.763 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:24:58.131Z,1756405498.131 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:24:58.602Z,1756405498.602 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:24:58.938Z,1756405498.938 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:24:59.336Z,1756405499.336 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:24:59.780Z,1756405499.780 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:25:00.146Z,1756405500.146 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:25:00.621Z,1756405500.621 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:25:00.623Z,1756405500.623 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-28T18:25:00.728Z,1756405500.728 [DAT](INFO): ****** received valid address query ****** 2025-08-28T18:25:00.728Z,1756405500.728 [DAT](INFO): ****** received valid ping request ****** 2025-08-28T18:25:00.780Z,1756405500.780 [DAT](INFO): entering command mode 2025-08-28T18:25:00.970Z,1756405500.970 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:25:00.980Z,1756405500.980 [DAT](INFO): DAT read: 2025-08-28T18:25:00.981Z,1756405500.981 [DAT](INFO): DAT read: user:4565> 2025-08-28T18:25:00.981Z,1756405500.981 [DAT](INFO): ****** received valid address query ****** 2025-08-28T18:25:00.981Z,1756405500.981 [DAT](INFO): ****** received valid ping request ****** 2025-08-28T18:25:00.982Z,1756405500.982 [DAT](INFO): setting remote address to 0 2025-08-28T18:25:01.232Z,1756405501.232 [DAT](INFO): DAT read: user:4565> 2025-08-28T18:25:01.233Z,1756405501.233 [DAT](INFO): DAT read: RemoteAddr | 0 2025-08-28T18:25:01.234Z,1756405501.234 [DAT](INFO): set remote address to 0 2025-08-28T18:25:01.234Z,1756405501.234 [DAT](INFO): ****** received valid address query ****** 2025-08-28T18:25:01.234Z,1756405501.234 [DAT](INFO): ****** received valid ping request ****** 2025-08-28T18:25:01.234Z,1756405501.234 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:01.365Z,1756405501.365 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:25:01.484Z,1756405501.484 [DAT](INFO): DAT read: user:4566> 2025-08-28T18:25:01.485Z,1756405501.485 [DAT](INFO): DAT read: Tx time:18:25:00.3719 2025-08-28T18:25:01.485Z,1756405501.485 [DAT](INFO): Ping request sent. 2025-08-28T18:25:01.486Z,1756405501.486 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:25:01.486Z,1756405501.486 [DAT](INFO): publishing transmit ping time 2025-08-28T18:25:01.486Z,1756405501.486 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000734 2025-08-28T18:25:01.737Z,1756405501.737 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250978 2025-08-28T18:25:01.793Z,1756405501.793 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:25:01.988Z,1756405501.988 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502850 2025-08-28T18:25:02.178Z,1756405502.178 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:25:02.241Z,1756405502.241 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755008 2025-08-28T18:25:02.492Z,1756405502.492 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006846 2025-08-28T18:25:02.629Z,1756405502.629 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:25:02.744Z,1756405502.744 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258752 2025-08-28T18:25:02.994Z,1756405502.994 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:25:03.005Z,1756405503.005 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.518973 2025-08-28T18:25:03.257Z,1756405503.257 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.770977 2025-08-28T18:25:03.384Z,1756405503.384 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:25:03.509Z,1756405503.509 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.023487 2025-08-28T18:25:03.761Z,1756405503.761 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.275001 2025-08-28T18:25:03.817Z,1756405503.817 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:25:04.012Z,1756405504.012 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.526831 2025-08-28T18:25:04.185Z,1756405504.185 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:25:04.264Z,1756405504.264 [DAT](INFO): DAT read: Rx Time:18:25:02.7786 2025-08-28T18:25:04.265Z,1756405504.265 [DAT](INFO): Rx dataTimestamp_ set to:1756405504.264663 2025-08-28T18:25:04.265Z,1756405504.265 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.779893 2025-08-28T18:25:04.517Z,1756405504.517 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.030910 2025-08-28T18:25:04.653Z,1756405504.653 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:25:04.773Z,1756405504.773 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.286554 2025-08-28T18:25:05.017Z,1756405505.017 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Either camera/lights unpowered or charge off not requested, but continuing with undock 2025-08-28T18:25:05.026Z,1756405505.026 [DAT](INFO): DAT read: 18:25:02.7786 LVL= 14464, 9505, 21826, 17555, AGC= 44, IDX= 166,-0.15, 0.125, 0.897,-0.815, 1.283, PHS=-1.056,-0.341,-2.142, RAW= 6.8, 38.5, CAL= 5.2, 43.9, ROT= 144.8, -43.9 2025-08-28T18:25:05.027Z,1756405505.027 [DAT](INFO): got valid direction response: 18:25:02.7786 LVL= 14464, 9505, 21826, 17555, AGC= 44, IDX= 166,-0.15, 0.125, 0.897,-0.815, 1.283, PHS=-1.056,-0.341,-2.142, RAW= 6.8, 38.5, CAL= 5.2, 43.9, ROT= 144.8, -43.9 2025-08-28T18:25:05.028Z,1756405505.028 [DAT](INFO): DAT read: Bearing 127.4, 30.7 (Local) 2025-08-28T18:25:05.028Z,1756405505.028 [DAT](INFO): Local bearing/azimuth received: Bearing 127.4, 30.7 (Local) 2025-08-28T18:25:05.030Z,1756405505.030 [DAT](INFO): DAT read: Range 11 to 50 : 1.8 m (Round-trip 2.5 ms) speed 0.0 m/s 2025-08-28T18:25:05.030Z,1756405505.030 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:25:05.031Z,1756405505.031 [DAT](INFO): direction in FSK: [-0.588795,0.415349,0.693402] 2025-08-28T18:25:05.032Z,1756405505.032 [DAT](INFO): publishing direction and range info 2025-08-28T18:25:07.425Z,1756405507.425 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Thruster off. 2025-08-28T18:25:10.658Z,1756405510.658 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-28T18:25:10.832Z,1756405510.832 [DAT](INFO): ****** received valid address query ****** 2025-08-28T18:25:10.832Z,1756405510.832 [DAT](INFO): ****** received valid ping request ****** 2025-08-28T18:25:10.833Z,1756405510.833 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:11.084Z,1756405511.084 [DAT](INFO): DAT read: user:4567> 2025-08-28T18:25:11.085Z,1756405511.085 [DAT](INFO): DAT read: Tx time:18:25:09.9719 2025-08-28T18:25:11.085Z,1756405511.085 [DAT](INFO): Ping request sent. 2025-08-28T18:25:11.085Z,1756405511.085 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:25:11.086Z,1756405511.086 [DAT](INFO): publishing transmit ping time 2025-08-28T18:25:11.086Z,1756405511.086 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000732 2025-08-28T18:25:11.336Z,1756405511.336 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250954 2025-08-28T18:25:11.588Z,1756405511.588 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502921 2025-08-28T18:25:11.841Z,1756405511.841 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755304 2025-08-28T18:25:12.092Z,1756405512.092 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006969 2025-08-28T18:25:12.345Z,1756405512.345 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258997 2025-08-28T18:25:12.597Z,1756405512.597 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511126 2025-08-28T18:25:12.849Z,1756405512.849 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763039 2025-08-28T18:25:13.101Z,1756405513.101 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015045 2025-08-28T18:25:13.353Z,1756405513.353 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267188 2025-08-28T18:25:13.605Z,1756405513.605 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519031 2025-08-28T18:25:13.861Z,1756405513.861 [DAT](INFO): DAT read: Rx Time:18:25:12.3795 2025-08-28T18:25:13.861Z,1756405513.861 [DAT](INFO): Rx dataTimestamp_ set to:1756405513.861070 2025-08-28T18:25:13.862Z,1756405513.862 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.776368 2025-08-28T18:25:14.112Z,1756405514.112 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.026916 2025-08-28T18:25:14.371Z,1756405514.371 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.284916 2025-08-28T18:25:14.617Z,1756405514.617 [DAT](INFO): DAT read: Range 11 to 50 : 2.6 m (Round-trip 3.5 ms) speed -0.2 m/s 2025-08-28T18:25:14.618Z,1756405514.618 [DAT](ERROR): #Rx 1: Read range message, but no direction. 2025-08-28T18:25:20.756Z,1756405520.756 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-28T18:25:20.920Z,1756405520.920 [DAT](INFO): ****** received valid address query ****** 2025-08-28T18:25:20.920Z,1756405520.920 [DAT](INFO): ****** received valid ping request ****** 2025-08-28T18:25:20.921Z,1756405520.921 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:21.172Z,1756405521.172 [DAT](INFO): DAT read: user:4568> 2025-08-28T18:25:21.173Z,1756405521.173 [DAT](INFO): DAT read: Tx time:18:25:20.0719 2025-08-28T18:25:21.173Z,1756405521.173 [DAT](INFO): Ping request sent. 2025-08-28T18:25:21.174Z,1756405521.174 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:25:21.174Z,1756405521.174 [DAT](INFO): publishing transmit ping time 2025-08-28T18:25:21.174Z,1756405521.174 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000715 2025-08-28T18:25:21.425Z,1756405521.425 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251501 2025-08-28T18:25:21.677Z,1756405521.677 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502926 2025-08-28T18:25:21.928Z,1756405521.928 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754892 2025-08-28T18:25:22.180Z,1756405522.180 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006847 2025-08-28T18:25:22.433Z,1756405522.433 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259560 2025-08-28T18:25:22.684Z,1756405522.684 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510828 2025-08-28T18:25:22.937Z,1756405522.937 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763202 2025-08-28T18:25:23.189Z,1756405523.189 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014976 2025-08-28T18:25:23.200Z,1756405523.200 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +1.16, +58.35, , 0.00 2025-08-28T18:25:23.441Z,1756405523.441 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267073 2025-08-28T18:25:23.693Z,1756405523.693 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519068 2025-08-28T18:25:23.948Z,1756405523.948 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774018 2025-08-28T18:25:24.196Z,1756405524.196 [DAT](INFO): DAT read: Rx Time:18:25:22.4815 2025-08-28T18:25:24.197Z,1756405524.197 [DAT](INFO): Rx dataTimestamp_ set to:1756405524.196656 2025-08-28T18:25:24.197Z,1756405524.197 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023886 2025-08-28T18:25:24.449Z,1756405524.449 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275261 2025-08-28T18:25:24.706Z,1756405524.706 [DAT](INFO): DAT read: 18:25:22.4815 LVL= 20896, 25921, 29026, 28643, AGC= 46, IDX= 453, 0.20, 2.837, 0.483, 2.365, 2.281, PHS= 0.658,-1.753, 0.039, RAW= 135.7, 9.8, CAL= 135.4, 10.8, ROT= 14.6, -10.8 2025-08-28T18:25:24.707Z,1756405524.707 [DAT](INFO): got valid direction response: 18:25:22.4815 LVL= 20896, 25921, 29026, 28643, AGC= 46, IDX= 453, 0.20, 2.837, 0.483, 2.365, 2.281, PHS= 0.658,-1.753, 0.039, RAW= 135.7, 9.8, CAL= 135.4, 10.8, ROT= 14.6, -10.8 2025-08-28T18:25:24.708Z,1756405524.708 [DAT](INFO): DAT read: Bearing 353.9, 12.1 (Local) 2025-08-28T18:25:24.708Z,1756405524.708 [DAT](INFO): Local bearing/azimuth received: Bearing 353.9, 12.1 (Local) 2025-08-28T18:25:24.709Z,1756405524.709 [DAT](INFO): DAT read: Range 11 to 50 : 4.1 m (Round-trip 5.5 ms) speed -0.2 m/s 2025-08-28T18:25:24.710Z,1756405524.710 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:25:24.711Z,1756405524.711 [DAT](INFO): direction in FSK: [0.950568,0.247605,0.187381] 2025-08-28T18:25:24.711Z,1756405524.711 [DAT](INFO): publishing direction and range info 2025-08-28T18:25:30.875Z,1756405530.875 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-28T18:25:31.002Z,1756405531.002 [DAT](INFO): ****** received valid address query ****** 2025-08-28T18:25:31.003Z,1756405531.003 [DAT](INFO): ****** received valid ping request ****** 2025-08-28T18:25:31.004Z,1756405531.004 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:31.252Z,1756405531.252 [DAT](INFO): DAT read: user:4569> 2025-08-28T18:25:31.253Z,1756405531.253 [DAT](INFO): DAT read: Tx time:18:25:30.1218 2025-08-28T18:25:31.253Z,1756405531.253 [DAT](INFO): Ping request sent. 2025-08-28T18:25:31.253Z,1756405531.253 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:25:31.254Z,1756405531.254 [DAT](INFO): publishing transmit ping time 2025-08-28T18:25:31.254Z,1756405531.254 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000725 2025-08-28T18:25:31.505Z,1756405531.505 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250995 2025-08-28T18:25:31.756Z,1756405531.756 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502946 2025-08-28T18:25:32.012Z,1756405532.012 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.757664 2025-08-28T18:25:32.261Z,1756405532.261 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007005 2025-08-28T18:25:32.513Z,1756405532.513 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259628 2025-08-28T18:25:32.764Z,1756405532.764 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510962 2025-08-28T18:25:33.019Z,1756405533.019 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.765005 2025-08-28T18:25:33.269Z,1756405533.269 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015048 2025-08-28T18:25:33.521Z,1756405533.521 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267199 2025-08-28T18:25:33.773Z,1756405533.773 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519005 2025-08-28T18:25:34.027Z,1756405534.027 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.773256 2025-08-28T18:25:34.277Z,1756405534.277 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023244 2025-08-28T18:25:34.529Z,1756405534.529 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275502 2025-08-28T18:25:34.780Z,1756405534.780 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526895 2025-08-28T18:25:35.036Z,1756405535.036 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.782269 2025-08-28T18:25:35.284Z,1756405535.284 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030874 2025-08-28T18:25:35.537Z,1756405535.537 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.283206 2025-08-28T18:25:35.789Z,1756405535.789 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.535224 2025-08-28T18:25:36.043Z,1756405536.043 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.789215 2025-08-28T18:25:36.292Z,1756405536.292 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038953 2025-08-28T18:25:36.545Z,1756405536.545 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.291418 2025-08-28T18:25:36.797Z,1756405536.797 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.543134 2025-08-28T18:25:37.050Z,1756405537.050 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.796878 2025-08-28T18:25:37.301Z,1756405537.301 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.047025 2025-08-28T18:25:37.553Z,1756405537.553 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.299072 2025-08-28T18:25:37.804Z,1756405537.804 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550953 2025-08-28T18:25:38.057Z,1756405538.057 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.803156 2025-08-28T18:25:38.309Z,1756405538.309 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.055043 2025-08-28T18:25:38.561Z,1756405538.561 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.307056 2025-08-28T18:25:38.813Z,1756405538.813 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.559241 2025-08-28T18:25:39.065Z,1756405539.065 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810999 2025-08-28T18:25:39.316Z,1756405539.316 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062976 2025-08-28T18:25:39.569Z,1756405539.569 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.315301 2025-08-28T18:25:39.821Z,1756405539.821 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.567099 2025-08-28T18:25:40.072Z,1756405540.072 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818930 2025-08-28T18:25:40.324Z,1756405540.324 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070927 2025-08-28T18:25:40.577Z,1756405540.577 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.323116 2025-08-28T18:25:40.828Z,1756405540.828 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574958 2025-08-28T18:25:40.968Z,1756405540.968 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-28T18:25:41.080Z,1756405541.080 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826947 2025-08-28T18:25:41.332Z,1756405541.332 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:41.332Z,1756405541.332 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:41.333Z,1756405541.333 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:41.584Z,1756405541.584 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:41.584Z,1756405541.584 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:41.585Z,1756405541.585 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:41.837Z,1756405541.837 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:41.837Z,1756405541.837 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:41.838Z,1756405541.838 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:42.088Z,1756405542.088 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:42.088Z,1756405542.088 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:42.089Z,1756405542.089 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:42.340Z,1756405542.340 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:42.340Z,1756405542.340 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:42.341Z,1756405542.341 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:42.592Z,1756405542.592 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:42.592Z,1756405542.592 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:42.593Z,1756405542.593 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:42.844Z,1756405542.844 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:42.844Z,1756405542.844 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:42.845Z,1756405542.845 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:43.096Z,1756405543.096 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:43.096Z,1756405543.096 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:43.097Z,1756405543.097 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:43.350Z,1756405543.350 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:43.350Z,1756405543.350 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:43.351Z,1756405543.351 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:43.605Z,1756405543.605 [DAT](INFO): DAT read: Response Not Received 2025-08-28T18:25:43.605Z,1756405543.605 [DAT](INFO): response not received 2025-08-28T18:25:43.605Z,1756405543.605 [DAT](INFO): DAT read: user:4570> 2025-08-28T18:25:43.606Z,1756405543.606 [DAT](INFO): DAT read: Tx time:18:25:42.5718 2025-08-28T18:25:43.606Z,1756405543.606 [DAT](INFO): Ping request sent. 2025-08-28T18:25:43.606Z,1756405543.606 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:25:43.607Z,1756405543.607 [DAT](INFO): publishing transmit ping time 2025-08-28T18:25:43.607Z,1756405543.607 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000715 2025-08-28T18:25:43.857Z,1756405543.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250151 2025-08-28T18:25:44.109Z,1756405544.109 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502227 2025-08-28T18:25:44.360Z,1756405544.360 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754057 2025-08-28T18:25:44.615Z,1756405544.615 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.008657 2025-08-28T18:25:44.865Z,1756405544.865 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258547 2025-08-28T18:25:45.116Z,1756405545.116 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510055 2025-08-28T18:25:45.368Z,1756405545.368 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762033 2025-08-28T18:25:45.621Z,1756405545.621 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014332 2025-08-28T18:25:45.873Z,1756405545.873 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266141 2025-08-28T18:25:46.125Z,1756405546.125 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518221 2025-08-28T18:25:46.376Z,1756405546.376 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770088 2025-08-28T18:25:46.629Z,1756405546.629 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022230 2025-08-28T18:25:46.880Z,1756405546.880 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274075 2025-08-28T18:25:47.132Z,1756405547.132 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526073 2025-08-28T18:25:47.384Z,1756405547.384 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778057 2025-08-28T18:25:47.636Z,1756405547.636 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030046 2025-08-28T18:25:47.889Z,1756405547.889 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282326 2025-08-28T18:25:48.140Z,1756405548.140 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534109 2025-08-28T18:25:48.392Z,1756405548.392 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786054 2025-08-28T18:25:48.653Z,1756405548.653 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.046122 2025-08-28T18:25:48.905Z,1756405548.905 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.298215 2025-08-28T18:25:49.156Z,1756405549.156 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.550037 2025-08-28T18:25:49.413Z,1756405549.413 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.806464 2025-08-28T18:25:49.664Z,1756405549.664 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.058065 2025-08-28T18:25:49.916Z,1756405549.916 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.310039 2025-08-28T18:25:50.169Z,1756405550.169 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.562288 2025-08-28T18:25:50.421Z,1756405550.421 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.814384 2025-08-28T18:25:50.673Z,1756405550.673 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.066115 2025-08-28T18:25:50.924Z,1756405550.924 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.318074 2025-08-28T18:25:51.064Z,1756405551.064 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-28T18:25:51.177Z,1756405551.177 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.570176 2025-08-28T18:25:51.434Z,1756405551.434 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.827177 2025-08-28T18:25:51.685Z,1756405551.685 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.078337 2025-08-28T18:25:51.937Z,1756405551.937 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.330158 2025-08-28T18:25:52.188Z,1756405552.188 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.582041 2025-08-28T18:25:52.441Z,1756405552.441 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.834571 2025-08-28T18:25:52.693Z,1756405552.693 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.086228 2025-08-28T18:25:52.945Z,1756405552.945 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.338123 2025-08-28T18:25:53.196Z,1756405553.196 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.590089 2025-08-28T18:25:53.451Z,1756405553.451 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.844206 2025-08-28T18:25:53.704Z,1756405553.704 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:53.704Z,1756405553.704 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:53.705Z,1756405553.705 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:53.956Z,1756405553.956 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:53.956Z,1756405553.956 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:53.957Z,1756405553.957 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:54.208Z,1756405554.208 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:54.208Z,1756405554.208 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:54.209Z,1756405554.209 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:54.460Z,1756405554.460 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:54.461Z,1756405554.461 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:54.461Z,1756405554.461 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:54.712Z,1756405554.712 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:54.712Z,1756405554.712 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:54.713Z,1756405554.713 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:54.964Z,1756405554.964 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:54.964Z,1756405554.964 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:54.965Z,1756405554.965 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:55.216Z,1756405555.216 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:55.216Z,1756405555.216 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:55.217Z,1756405555.217 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:55.472Z,1756405555.472 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:55.472Z,1756405555.472 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:55.473Z,1756405555.473 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:55.724Z,1756405555.724 [DAT](INFO): Reached modem response timeout 2025-08-28T18:25:55.724Z,1756405555.724 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:25:55.725Z,1756405555.725 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:25:55.979Z,1756405555.979 [DAT](INFO): DAT read: Response Not Received 2025-08-28T18:25:55.979Z,1756405555.979 [DAT](INFO): response not received 2025-08-28T18:25:55.980Z,1756405555.980 [DAT](INFO): DAT read: user:4571> 2025-08-28T18:25:55.981Z,1756405555.981 [DAT](INFO): DAT read: Tx time:18:25:55.0218 2025-08-28T18:25:55.981Z,1756405555.981 [DAT](INFO): Ping request sent. 2025-08-28T18:25:55.981Z,1756405555.981 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:25:55.981Z,1756405555.981 [DAT](INFO): publishing transmit ping time 2025-08-28T18:25:55.982Z,1756405555.982 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000725 2025-08-28T18:25:56.229Z,1756405556.229 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.247719 2025-08-28T18:25:56.481Z,1756405556.481 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.499554 2025-08-28T18:25:56.733Z,1756405556.733 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.751579 2025-08-28T18:25:56.985Z,1756405556.985 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.003753 2025-08-28T18:25:57.236Z,1756405557.236 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.255520 2025-08-28T18:25:57.491Z,1756405557.491 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509628 2025-08-28T18:25:57.744Z,1756405557.744 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763536 2025-08-28T18:25:57.997Z,1756405557.997 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015653 2025-08-28T18:25:58.248Z,1756405558.248 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267453 2025-08-28T18:25:58.500Z,1756405558.500 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519498 2025-08-28T18:25:58.753Z,1756405558.753 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771639 2025-08-28T18:25:59.004Z,1756405559.004 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023475 2025-08-28T18:25:59.257Z,1756405559.257 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.276351 2025-08-28T18:25:59.510Z,1756405559.510 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.529513 2025-08-28T18:25:59.764Z,1756405559.764 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.783454 2025-08-28T18:26:00.016Z,1756405560.016 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.035510 2025-08-28T18:26:00.268Z,1756405560.268 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.287522 2025-08-28T18:26:00.521Z,1756405560.521 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.539650 2025-08-28T18:26:00.773Z,1756405560.773 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.792029 2025-08-28T18:26:01.025Z,1756405561.025 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.043555 2025-08-28T18:26:01.153Z,1756405561.153 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-28T18:26:01.276Z,1756405561.276 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.295453 2025-08-28T18:26:01.537Z,1756405561.537 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.555601 2025-08-28T18:26:01.788Z,1756405561.788 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.807531 2025-08-28T18:26:02.041Z,1756405562.041 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.059667 2025-08-28T18:26:02.292Z,1756405562.292 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.311516 2025-08-28T18:26:02.544Z,1756405562.544 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.563500 2025-08-28T18:26:02.797Z,1756405562.797 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.815571 2025-08-28T18:26:03.048Z,1756405563.048 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.067490 2025-08-28T18:26:03.301Z,1756405563.301 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.319554 2025-08-28T18:26:03.553Z,1756405563.553 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.571572 2025-08-28T18:26:03.804Z,1756405563.804 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.823495 2025-08-28T18:26:04.057Z,1756405564.057 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.075544 2025-08-28T18:26:04.309Z,1756405564.309 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.328275 2025-08-28T18:26:04.561Z,1756405564.561 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.579767 2025-08-28T18:26:04.813Z,1756405564.813 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.831662 2025-08-28T18:26:05.064Z,1756405565.064 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.083470 2025-08-28T18:26:05.317Z,1756405565.317 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.335756 2025-08-28T18:26:05.571Z,1756405565.571 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.589585 2025-08-28T18:26:05.825Z,1756405565.825 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.843529 2025-08-28T18:26:06.076Z,1756405566.076 [DAT](INFO): Reached modem response timeout 2025-08-28T18:26:06.077Z,1756405566.077 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:26:06.077Z,1756405566.077 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:26:06.328Z,1756405566.328 [DAT](INFO): Reached modem response timeout 2025-08-28T18:26:06.328Z,1756405566.328 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:26:06.329Z,1756405566.329 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:26:06.580Z,1756405566.580 [DAT](INFO): Reached modem response timeout 2025-08-28T18:26:06.580Z,1756405566.580 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:26:06.581Z,1756405566.581 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:26:06.832Z,1756405566.832 [DAT](INFO): Reached modem response timeout 2025-08-28T18:26:06.833Z,1756405566.833 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:26:06.833Z,1756405566.833 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:26:07.084Z,1756405567.084 [DAT](INFO): Reached modem response timeout 2025-08-28T18:26:07.084Z,1756405567.084 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:26:07.085Z,1756405567.085 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:26:07.336Z,1756405567.336 [DAT](INFO): Reached modem response timeout 2025-08-28T18:26:07.336Z,1756405567.336 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:26:07.337Z,1756405567.337 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:26:07.592Z,1756405567.592 [DAT](INFO): Reached modem response timeout 2025-08-28T18:26:07.592Z,1756405567.592 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:26:07.593Z,1756405567.593 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:26:07.844Z,1756405567.844 [DAT](INFO): Reached modem response timeout 2025-08-28T18:26:07.844Z,1756405567.844 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:26:07.845Z,1756405567.845 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:26:08.096Z,1756405568.096 [DAT](INFO): Reached modem response timeout 2025-08-28T18:26:08.096Z,1756405568.096 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:26:08.097Z,1756405568.097 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:26:08.350Z,1756405568.350 [DAT](INFO): Reached modem response timeout 2025-08-28T18:26:08.351Z,1756405568.351 [DAT](DEBUG): Acoustic response timeout 2025-08-28T18:26:08.352Z,1756405568.352 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:26:08.601Z,1756405568.601 [DAT](INFO): DAT read: Response Not Received 2025-08-28T18:26:08.601Z,1756405568.601 [DAT](INFO): response not received 2025-08-28T18:26:08.601Z,1756405568.601 [DAT](INFO): DAT read: uer:472> 2025-08-28T18:26:08.602Z,1756405568.602 [DAT](INFO): unknown deviceResponse_: uer:472> 2025-08-28T18:26:08.602Z,1756405568.602 [DAT](INFO): DAT read: Tx time:18:26:07.4717 2025-08-28T18:26:08.602Z,1756405568.602 [DAT](INFO): Ping request sent. 2025-08-28T18:26:08.602Z,1756405568.602 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:08.603Z,1756405568.603 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:08.607Z,1756405568.607 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000722 2025-08-28T18:26:08.852Z,1756405568.852 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.249980 2025-08-28T18:26:09.105Z,1756405569.105 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502244 2025-08-28T18:26:09.357Z,1756405569.357 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754039 2025-08-28T18:26:09.613Z,1756405569.613 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.010065 2025-08-28T18:26:09.865Z,1756405569.865 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.262195 2025-08-28T18:26:10.118Z,1756405570.118 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.515159 2025-08-28T18:26:10.368Z,1756405570.368 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.765961 2025-08-28T18:26:10.621Z,1756405570.621 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.018045 2025-08-28T18:26:10.873Z,1756405570.873 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.270128 2025-08-28T18:26:11.124Z,1756405571.124 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.521948 2025-08-28T18:26:11.260Z,1756405571.260 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-28T18:26:11.376Z,1756405571.376 [DAT](INFO): DAT read: Rx Time:18:26:09.8928 2025-08-28T18:26:11.377Z,1756405571.377 [DAT](INFO): Rx dataTimestamp_ set to:1756405571.376609 2025-08-28T18:26:11.377Z,1756405571.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774965 2025-08-28T18:26:11.631Z,1756405571.631 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.027868 2025-08-28T18:26:11.883Z,1756405571.883 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.279931 2025-08-28T18:26:12.138Z,1756405572.138 [DAT](INFO): DAT read: 18:26:09.8928 LVL= 23360, 32753, 31106, 32755, AGC= 50, IDX= 442, 0.49, 1.946,-0.187, 0.913, 1.236, PHS= 0.812,-1.378,-0.367, RAW= 117.4, 9.9, CAL= 118.6, 11.0, ROT= 31.4, -11.0 2025-08-28T18:26:12.139Z,1756405572.139 [DAT](INFO): got valid direction response: 18:26:09.8928 LVL= 23360, 32753, 31106, 32755, AGC= 50, IDX= 442, 0.49, 1.946,-0.187, 0.913, 1.236, PHS= 0.812,-1.378,-0.367, RAW= 117.4, 9.9, CAL= 118.6, 11.0, ROT= 31.4, -11.0 2025-08-28T18:26:12.140Z,1756405572.140 [DAT](INFO): DAT read: Bearing 332.4, 27.8 (Local) 2025-08-28T18:26:12.140Z,1756405572.140 [DAT](INFO): Local bearing/azimuth received: Bearing 332.4, 27.8 (Local) 2025-08-28T18:26:12.142Z,1756405572.142 [DAT](INFO): DAT read: Range 11 to 50 : 12.6 m (Round-trip 16.9 ms) speed -0.1 m/s 2025-08-28T18:26:12.143Z,1756405572.143 [DAT](INFO): DAT read: user:4573> 2025-08-28T18:26:12.143Z,1756405572.143 [DAT](INFO): DAT read: Tx time:18:26:11.0217 2025-08-28T18:26:12.144Z,1756405572.144 [DAT](INFO): Ping request sent. 2025-08-28T18:26:12.144Z,1756405572.144 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:12.144Z,1756405572.144 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:26:12.145Z,1756405572.145 [DAT](INFO): direction in FSK: [0.837869,0.511437,0.190809] 2025-08-28T18:26:12.145Z,1756405572.145 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:12.145Z,1756405572.145 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:12.149Z,1756405572.149 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:12.149Z,1756405572.149 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:12.472Z,1756405572.472 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-08-28T18:26:14.909Z,1756405574.909 [DAT](INFO): DAT read: Rx Time:18:26:13.4436 2025-08-28T18:26:14.909Z,1756405574.909 [DAT](INFO): Rx dataTimestamp_ set to:1756405574.908783 2025-08-28T18:26:15.697Z,1756405575.697 [DAT](INFO): DAT read: 18:26:13.4436 LVL= 22400, 32753, 32754, 32755, AGC= 50, IDX= 435,-0.23,-2.529, 1.589, 2.598, 2.945, PHS= 0.912,-1.311,-0.391, RAW= 114.3, 8.2, CAL= 115.7, 9.3, ROT= 34.3, -9.3 2025-08-28T18:26:15.698Z,1756405575.698 [DAT](INFO): got valid direction response: 18:26:13.4436 LVL= 22400, 32753, 32754, 32755, AGC= 50, IDX= 435,-0.23,-2.529, 1.589, 2.598, 2.945, PHS= 0.912,-1.311,-0.391, RAW= 114.3, 8.2, CAL= 115.7, 9.3, ROT= 34.3, -9.3 2025-08-28T18:26:15.699Z,1756405575.699 [DAT](INFO): DAT read: Bearing 329.2, 30.3 (Local) 2025-08-28T18:26:15.704Z,1756405575.704 [DAT](INFO): Local bearing/azimuth received: Bearing 329.2, 30.3 (Local) 2025-08-28T18:26:15.705Z,1756405575.705 [DAT](INFO): DAT read: Range 11 to 50 : 13.2 m (Round-trip 17.7 ms) speed -0.2 m/s 2025-08-28T18:26:15.706Z,1756405575.706 [DAT](INFO): DAT read: user:4574> 2025-08-28T18:26:15.707Z,1756405575.707 [DAT](INFO): DAT read: Tx time:18:26:14.5717 2025-08-28T18:26:15.707Z,1756405575.707 [DAT](INFO): Ping request sent. 2025-08-28T18:26:15.707Z,1756405575.707 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:15.711Z,1756405575.711 [DAT](INFO): #Rx 2: Read range and direction messages. 2025-08-28T18:26:15.712Z,1756405575.712 [DAT](INFO): direction in FSK: [0.815240,0.556119,0.161604] 2025-08-28T18:26:15.713Z,1756405575.713 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:15.713Z,1756405575.713 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:15.720Z,1756405575.720 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:15.720Z,1756405575.720 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:18.691Z,1756405578.691 [DAT](INFO): DAT read: Rx Time:18:26:16.9945 2025-08-28T18:26:18.693Z,1756405578.693 [DAT](INFO): Rx dataTimestamp_ set to:1756405578.691785 2025-08-28T18:26:19.202Z,1756405579.202 [DAT](INFO): DAT read: 18:26:16.9945 LVL= 20768, 32753, 32754, 32755, AGC= 51, IDX= 427, 0.26, 3.131, 0.974, 1.922, 2.300, PHS= 0.933,-1.281,-0.422, RAW= 112.6, 8.0, CAL= 114.0, 9.1, ROT= 36.0, -9.1 2025-08-28T18:26:19.211Z,1756405579.211 [DAT](INFO): got valid direction response: 18:26:16.9945 LVL= 20768, 32753, 32754, 32755, AGC= 51, IDX= 427, 0.26, 3.131, 0.974, 1.922, 2.300, PHS= 0.933,-1.281,-0.422, RAW= 112.6, 8.0, CAL= 114.0, 9.1, ROT= 36.0, -9.1 2025-08-28T18:26:19.212Z,1756405579.212 [DAT](INFO): DAT read: Bearing 327.6, 31.7 (Local) 2025-08-28T18:26:19.212Z,1756405579.212 [DAT](INFO): Local bearing/azimuth received: Bearing 327.6, 31.7 (Local) 2025-08-28T18:26:19.214Z,1756405579.214 [DAT](INFO): DAT read: Range 11 to 50 : 13.9 m (Round-trip 18.6 ms) speed -0.2 m/s 2025-08-28T18:26:19.214Z,1756405579.214 [DAT](INFO): DAT read: user:4575> 2025-08-28T18:26:19.215Z,1756405579.215 [DAT](INFO): DAT read: Tx time:18:26:18.1217 2025-08-28T18:26:19.216Z,1756405579.216 [DAT](INFO): Ping request sent. 2025-08-28T18:26:19.216Z,1756405579.216 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:19.216Z,1756405579.216 [DAT](INFO): #Rx 3: Read range and direction messages. 2025-08-28T18:26:19.217Z,1756405579.217 [DAT](INFO): direction in FSK: [0.798835,0.580387,0.158158] 2025-08-28T18:26:19.217Z,1756405579.217 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:19.217Z,1756405579.217 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:19.221Z,1756405579.221 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:19.221Z,1756405579.221 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:21.356Z,1756405581.356 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-28T18:26:21.460Z,1756405581.460 [DAT](INFO): ****** received valid address query ****** 2025-08-28T18:26:21.460Z,1756405581.460 [DAT](INFO): ****** received valid ping request ****** 2025-08-28T18:26:21.461Z,1756405581.461 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:26:21.712Z,1756405581.713 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.496698 2025-08-28T18:26:21.965Z,1756405581.965 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.748959 2025-08-28T18:26:22.218Z,1756405582.218 [DAT](INFO): DAT read: Rx Time:18:26:20.5454 2025-08-28T18:26:22.218Z,1756405582.218 [DAT](INFO): Rx dataTimestamp_ set to:1756405582.217901 2025-08-28T18:26:22.219Z,1756405582.219 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.003212 2025-08-28T18:26:22.468Z,1756405582.468 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.252648 2025-08-28T18:26:22.776Z,1756405582.776 [DAT](INFO): DAT read: 18:26:20.5454 LVL= 23648, 32753, 32754, 32755, AGC= 50, IDX= 436, 0.47, 1.520,-0.636, 0.281, 0.676, PHS= 0.946,-1.267,-0.439, RAW= 111.7, 7.9, CAL= 113.2, 9.0, ROT= 36.8, -9.0 2025-08-28T18:26:22.784Z,1756405582.784 [DAT](INFO): got valid direction response: 18:26:20.5454 LVL= 23648, 32753, 32754, 32755, AGC= 50, IDX= 436, 0.47, 1.520,-0.636, 0.281, 0.676, PHS= 0.946,-1.267,-0.439, RAW= 111.7, 7.9, CAL= 113.2, 9.0, ROT= 36.8, -9.0 2025-08-28T18:26:22.787Z,1756405582.787 [DAT](INFO): DAT read: Bearing 326.5, 32.4 (Local) 2025-08-28T18:26:22.796Z,1756405582.796 [DAT](INFO): Local bearing/azimuth received: Bearing 326.5, 32.4 (Local) 2025-08-28T18:26:22.797Z,1756405582.797 [DAT](INFO): DAT read: Range 11 to 50 : 14.6 m (Round-trip 19.5 ms) speed -0.1 m/s 2025-08-28T18:26:22.798Z,1756405582.798 [DAT](INFO): DAT read: user:4576> 2025-08-28T18:26:22.798Z,1756405582.798 [DAT](INFO): DAT read: Tx time:18:26:21.6717 2025-08-28T18:26:22.799Z,1756405582.799 [DAT](INFO): Ping request sent. 2025-08-28T18:26:22.799Z,1756405582.799 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:22.804Z,1756405582.804 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:26:22.806Z,1756405582.806 [DAT](INFO): direction in FSK: [0.790873,0.591649,0.156434] 2025-08-28T18:26:22.806Z,1756405582.806 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:22.806Z,1756405582.806 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:22.817Z,1756405582.817 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:22.818Z,1756405582.818 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:25.745Z,1756405585.745 [DAT](INFO): DAT read: Rx Time:18:26:24.0961 2025-08-28T18:26:25.745Z,1756405585.745 [DAT](INFO): Rx dataTimestamp_ set to:1756405585.745126 2025-08-28T18:26:26.257Z,1756405586.257 [DAT](INFO): DAT read: 18:26:24.0961 LVL= 19824, 32753, 29010, 32755, AGC= 52, IDX= 428,-0.04,-2.404, 1.713, 2.632, 3.016, PHS= 0.966,-1.258,-0.428, RAW= 111.7, 7.5, CAL= 113.1, 8.4, ROT= 36.9, -8.4 2025-08-28T18:26:26.258Z,1756405586.258 [DAT](INFO): got valid direction response: 18:26:24.0961 LVL= 19824, 32753, 29010, 32755, AGC= 52, IDX= 428,-0.04,-2.404, 1.713, 2.632, 3.016, PHS= 0.966,-1.258,-0.428, RAW= 111.7, 7.5, CAL= 113.1, 8.4, ROT= 36.9, -8.4 2025-08-28T18:26:26.261Z,1756405586.261 [DAT](INFO): DAT read: Bearing 325.1, 32.3 (Local) 2025-08-28T18:26:26.261Z,1756405586.261 [DAT](INFO): Local bearing/azimuth received: Bearing 325.1, 32.3 (Local) 2025-08-28T18:26:26.263Z,1756405586.263 [DAT](INFO): DAT read: Range 11 to 50 : 15.2 m (Round-trip 20.3 ms) speed -0.1 m/s 2025-08-28T18:26:26.265Z,1756405586.265 [DAT](INFO): DAT read: user:4577> 2025-08-28T18:26:26.266Z,1756405586.266 [DAT](INFO): DAT read: Tx time:18:26:25.2217 2025-08-28T18:26:26.266Z,1756405586.266 [DAT](INFO): Ping request sent. 2025-08-28T18:26:26.266Z,1756405586.266 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:26.267Z,1756405586.267 [DAT](INFO): #Rx 2: Read range and direction messages. 2025-08-28T18:26:26.268Z,1756405586.268 [DAT](INFO): direction in FSK: [0.791106,0.593979,0.146083] 2025-08-28T18:26:26.268Z,1756405586.268 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:26.268Z,1756405586.268 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:26.279Z,1756405586.279 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:26.279Z,1756405586.279 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:29.272Z,1756405589.272 [DAT](INFO): DAT read: Rx Time:18:26:27.6467 2025-08-28T18:26:29.273Z,1756405589.273 [DAT](INFO): Rx dataTimestamp_ set to:1756405589.272644 2025-08-28T18:26:29.782Z,1756405589.782 [DAT](INFO): DAT read: 18:26:27.6467 LVL= 22624, 32753, 31874, 32755, AGC= 51, IDX= 435,-0.41,-0.121,-2.259,-1.350,-0.977, PHS= 0.958,-1.237,-0.417, RAW= 111.7, 7.3, CAL= 113.2, 8.3, ROT= 36.8, -8.3 2025-08-28T18:26:29.783Z,1756405589.783 [DAT](INFO): got valid direction response: 18:26:27.6467 LVL= 22624, 32753, 31874, 32755, AGC= 51, IDX= 435,-0.41,-0.121,-2.259,-1.350,-0.977, PHS= 0.958,-1.237,-0.417, RAW= 111.7, 7.3, CAL= 113.2, 8.3, ROT= 36.8, -8.3 2025-08-28T18:26:29.784Z,1756405589.784 [DAT](INFO): DAT read: Bearing 324.3, 32.1 (Local) 2025-08-28T18:26:29.785Z,1756405589.785 [DAT](INFO): Local bearing/azimuth received: Bearing 324.3, 32.1 (Local) 2025-08-28T18:26:29.786Z,1756405589.786 [DAT](INFO): DAT read: Range 11 to 50 : 15.6 m (Round-trip 20.8 ms) speed -0.2 m/s 2025-08-28T18:26:29.787Z,1756405589.787 [DAT](INFO): DAT read: user:4578> 2025-08-28T18:26:29.787Z,1756405589.787 [DAT](INFO): DAT read: Tx time:18:26:28.7717 2025-08-28T18:26:29.788Z,1756405589.788 [DAT](INFO): Ping request sent. 2025-08-28T18:26:29.788Z,1756405589.788 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:29.788Z,1756405589.788 [DAT](INFO): #Rx 3: Read range and direction messages. 2025-08-28T18:26:29.789Z,1756405589.789 [DAT](INFO): direction in FSK: [0.792344,0.592749,0.144356] 2025-08-28T18:26:29.789Z,1756405589.789 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:29.789Z,1756405589.789 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:29.793Z,1756405589.793 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:29.793Z,1756405589.793 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:31.458Z,1756405591.458 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-28T18:26:31.540Z,1756405591.540 [DAT](INFO): ****** received valid address query ****** 2025-08-28T18:26:31.540Z,1756405591.540 [DAT](INFO): ****** received valid ping request ****** 2025-08-28T18:26:31.541Z,1756405591.541 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-28T18:26:31.792Z,1756405591.792 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.004646 2025-08-28T18:26:32.045Z,1756405592.045 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.256773 2025-08-28T18:26:32.297Z,1756405592.297 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.508767 2025-08-28T18:26:32.548Z,1756405592.548 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.760695 2025-08-28T18:26:32.666Z,1756405592.666 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 15.60 m. Transitioning docking module to standby. 2025-08-28T18:26:32.800Z,1756405592.800 [DAT](INFO): DAT read: Rx Time:18:26:31.1976 2025-08-28T18:26:32.801Z,1756405592.801 [DAT](INFO): Rx dataTimestamp_ set to:1756405592.800625 2025-08-28T18:26:32.801Z,1756405592.801 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.013676 2025-08-28T18:26:33.052Z,1756405593.052 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.264714 2025-08-28T18:26:33.100Z,1756405593.100 [DockingStepper](INFO): Changing to mode: 1 2025-08-28T18:26:33.310Z,1756405593.310 [DAT](INFO): DAT read: 18:26:31.1976 LVL= 21744, 32753, 29010, 32755, AGC= 53, IDX= 427, 0.04,-1.754, 2.429,-2.965,-2.591, PHS= 0.939,-1.218,-0.418, RAW= 111.5, 7.4, CAL= 113.0, 8.4, ROT= 37.0, -8.4 2025-08-28T18:26:33.311Z,1756405593.311 [DAT](INFO): got valid direction response: 18:26:31.1976 LVL= 21744, 32753, 29010, 32755, AGC= 53, IDX= 427, 0.04,-1.754, 2.429,-2.965,-2.591, PHS= 0.939,-1.218,-0.418, RAW= 111.5, 7.4, CAL= 113.0, 8.4, ROT= 37.0, -8.4 2025-08-28T18:26:33.312Z,1756405593.312 [DAT](INFO): DAT read: Bearing 324.1, 32.4 (Local) 2025-08-28T18:26:33.313Z,1756405593.313 [DAT](INFO): Local bearing/azimuth received: Bearing 324.1, 32.4 (Local) 2025-08-28T18:26:33.314Z,1756405593.314 [DAT](INFO): DAT read: Range 11 to 50 : 16.2 m (Round-trip 21.7 ms) speed -0.2 m/s 2025-08-28T18:26:33.315Z,1756405593.315 [DAT](INFO): DAT read: user:4579> 2025-08-28T18:26:33.316Z,1756405593.316 [DAT](INFO): DAT read: Tx time:18:26:32.3217 2025-08-28T18:26:33.316Z,1756405593.316 [DAT](INFO): Ping request sent. 2025-08-28T18:26:33.316Z,1756405593.316 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:33.316Z,1756405593.316 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-28T18:26:33.317Z,1756405593.317 [DAT](INFO): direction in FSK: [0.790068,0.595359,0.146083] 2025-08-28T18:26:33.317Z,1756405593.317 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:33.318Z,1756405593.318 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:33.337Z,1756405593.337 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:33.338Z,1756405593.338 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:36.340Z,1756405596.340 [DAT](INFO): DAT read: Rx Time:18:26:34.7483 2025-08-28T18:26:36.341Z,1756405596.341 [DAT](INFO): Rx dataTimestamp_ set to:1756405596.340673 2025-08-28T18:26:36.850Z,1756405596.850 [DAT](INFO): DAT read: 18:26:34.7483 LVL= 21968, 32753, 29010, 32755, AGC= 52, IDX= 435, 0.28, 2.190, 0.106, 0.979, 1.381, PHS= 0.910,-1.230,-0.446, RAW= 111.2, 8.2, CAL= 112.7, 9.3, ROT= 37.3, -9.3 2025-08-28T18:26:36.851Z,1756405596.851 [DAT](INFO): got valid direction response: 18:26:34.7483 LVL= 21968, 32753, 29010, 32755, AGC= 52, IDX= 435, 0.28, 2.190, 0.106, 0.979, 1.381, PHS= 0.910,-1.230,-0.446, RAW= 111.2, 8.2, CAL= 112.7, 9.3, ROT= 37.3, -9.3 2025-08-28T18:26:36.852Z,1756405596.852 [DAT](INFO): DAT read: Bearing 325.2, 32.8 (Local) 2025-08-28T18:26:36.853Z,1756405596.853 [DAT](INFO): Local bearing/azimuth received: Bearing 325.2, 32.8 (Local) 2025-08-28T18:26:36.854Z,1756405596.854 [DAT](INFO): DAT read: Range 11 to 50 : 16.8 m (Round-trip 22.5 ms) speed -0.2 m/s 2025-08-28T18:26:36.855Z,1756405596.855 [DAT](INFO): DAT read: user:4580> 2025-08-28T18:26:36.856Z,1756405596.856 [DAT](INFO): DAT read: Tx time:18:26:35.8716 2025-08-28T18:26:36.856Z,1756405596.856 [DAT](INFO): Ping request sent. 2025-08-28T18:26:36.856Z,1756405596.856 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:36.856Z,1756405596.856 [DAT](INFO): #Rx 2: Read range and direction messages. 2025-08-28T18:26:36.857Z,1756405596.857 [DAT](INFO): direction in FSK: [0.785018,0.598023,0.161604] 2025-08-28T18:26:36.857Z,1756405596.857 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:36.858Z,1756405596.858 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:36.861Z,1756405596.861 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:36.862Z,1756405596.862 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:38.331Z,1756405598.331 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 67.67, 67.67, 74.47 2025-08-28T18:26:39.150Z,1756405599.150 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2025-08-28T18:26:39.551Z,1756405599.551 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2025-08-28T18:26:39.551Z,1756405599.551 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2025-08-28T18:26:39.552Z,1756405599.552 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2025-08-28T18:26:39.552Z,1756405599.552 [DefaultWithUndock:LeaveDock] Stopped 2025-08-28T18:26:39.552Z,1756405599.552 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2025-08-28T18:26:39.552Z,1756405599.552 [DefaultWithUndock:LeaveDock:A.] Stopped 2025-08-28T18:26:39.552Z,1756405599.552 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2025-08-28T18:26:39.552Z,1756405599.552 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2025-08-28T18:26:39.552Z,1756405599.552 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2025-08-28T18:26:39.553Z,1756405599.553 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2025-08-28T18:26:39.553Z,1756405599.553 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-08-28T18:26:39.553Z,1756405599.553 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-08-28T18:26:39.553Z,1756405599.553 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-08-28T18:26:39.553Z,1756405599.553 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-08-28T18:26:39.554Z,1756405599.554 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-08-28T18:26:39.554Z,1756405599.554 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-08-28T18:26:39.554Z,1756405599.554 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-08-28T18:26:39.869Z,1756405599.869 [DAT](INFO): DAT read: Rx Time:18:26:38.2990 2025-08-28T18:26:39.869Z,1756405599.869 [DAT](INFO): Rx dataTimestamp_ set to:1756405599.868803 2025-08-28T18:26:40.381Z,1756405600.381 [DAT](INFO): DAT read: 18:26:38.2990 LVL= 17760, 30561, 22914, 32755, AGC= 51, IDX= 427,-0.23,-2.819, 1.383, 2.299, 2.636, PHS= 0.931,-1.208,-0.381, RAW= 112.5, 7.1, CAL= 114.0, 8.1, ROT= 36.0, -8.1 2025-08-28T18:26:40.382Z,1756405600.382 [DAT](INFO): got valid direction response: 18:26:38.2990 LVL= 17760, 30561, 22914, 32755, AGC= 51, IDX= 427,-0.23,-2.819, 1.383, 2.299, 2.636, PHS= 0.931,-1.208,-0.381, RAW= 112.5, 7.1, CAL= 114.0, 8.1, ROT= 36.0, -8.1 2025-08-28T18:26:40.383Z,1756405600.383 [DAT](INFO): DAT read: Bearing 323.9, 31.4 (Local) 2025-08-28T18:26:40.383Z,1756405600.383 [DAT](INFO): Local bearing/azimuth received: Bearing 323.9, 31.4 (Local) 2025-08-28T18:26:40.385Z,1756405600.385 [DAT](INFO): DAT read: Range 11 to 50 : 17.4 m (Round-trip 23.2 ms) speed -0.1 m/s 2025-08-28T18:26:40.385Z,1756405600.385 [DAT](INFO): DAT read: user:4581> 2025-08-28T18:26:40.386Z,1756405600.386 [DAT](INFO): DAT read: Tx time:18:26:39.4216 2025-08-28T18:26:40.386Z,1756405600.386 [DAT](INFO): Ping request sent. 2025-08-28T18:26:40.387Z,1756405600.387 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:40.387Z,1756405600.387 [DAT](INFO): #Rx 3: Read range and direction messages. 2025-08-28T18:26:40.388Z,1756405600.388 [DAT](INFO): direction in FSK: [0.800946,0.581921,0.140901] 2025-08-28T18:26:40.388Z,1756405600.388 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:40.388Z,1756405600.388 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:40.396Z,1756405600.396 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:40.396Z,1756405600.396 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:40.544Z,1756405600.544 [Radio_Surface](INFO): Powering up 2025-08-28T18:26:43.397Z,1756405603.397 [DAT](INFO): DAT read: Rx Time:18:26:41.8497 2025-08-28T18:26:43.398Z,1756405603.398 [DAT](INFO): Rx dataTimestamp_ set to:1756405603.397591 2025-08-28T18:26:43.908Z,1756405603.908 [DAT](INFO): DAT read: 18:26:41.8497 LVL= 23232, 32753, 29810, 32755, AGC= 52, IDX= 433, 0.24, 2.586, 0.528, 1.406, 1.777, PHS= 0.911,-1.204,-0.415, RAW= 111.7, 7.7, CAL= 113.1, 8.7, ROT= 36.9, -8.7 2025-08-28T18:26:43.909Z,1756405603.909 [DAT](INFO): got valid direction response: 18:26:41.8497 LVL= 23232, 32753, 29810, 32755, AGC= 52, IDX= 433, 0.24, 2.586, 0.528, 1.406, 1.777, PHS= 0.911,-1.204,-0.415, RAW= 111.7, 7.7, CAL= 113.1, 8.7, ROT= 36.9, -8.7 2025-08-28T18:26:43.910Z,1756405603.910 [DAT](INFO): DAT read: Bearing 324.7, 32.3 (Local) 2025-08-28T18:26:43.910Z,1756405603.910 [DAT](INFO): Local bearing/azimuth received: Bearing 324.7, 32.3 (Local) 2025-08-28T18:26:43.912Z,1756405603.912 [DAT](INFO): DAT read: Range 11 to 50 : 17.9 m (Round-trip 23.9 ms) speed -0.2 m/s 2025-08-28T18:26:43.913Z,1756405603.913 [DAT](INFO): DAT read: user:4582> 2025-08-28T18:26:43.913Z,1756405603.913 [DAT](INFO): DAT read: Tx time:18:26:42.9716 2025-08-28T18:26:43.914Z,1756405603.914 [DAT](INFO): Ping request sent. 2025-08-28T18:26:43.914Z,1756405603.914 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:43.914Z,1756405603.914 [DAT](INFO): #Rx 4: Read range and direction messages. 2025-08-28T18:26:43.915Z,1756405603.915 [DAT](INFO): direction in FSK: [0.790483,0.593512,0.151261] 2025-08-28T18:26:43.915Z,1756405603.915 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:43.916Z,1756405603.916 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:43.919Z,1756405603.919 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:43.919Z,1756405603.919 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:45.797Z,1756405605.797 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11 2025-08-28T18:26:45.797Z,1756405605.797 [Radio_Surface](INFO): No local IP found 2025-08-28T18:26:46.926Z,1756405606.926 [DAT](INFO): DAT read: Rx Time:18:26:45.4004 2025-08-28T18:26:46.926Z,1756405606.926 [DAT](INFO): Rx dataTimestamp_ set to:1756405606.925708 2025-08-28T18:26:47.445Z,1756405607.445 [DAT](INFO): DAT read: 18:26:45.4004 LVL= 20544, 32753, 29394, 32755, AGC= 54, IDX= 441,-0.11,-1.462, 2.662,-2.674,-2.319, PHS= 0.959,-1.257,-0.399, RAW= 112.6, 7.3, CAL= 114.0, 8.2, ROT= 36.0, -8.2 2025-08-28T18:26:47.446Z,1756405607.446 [DAT](INFO): got valid direction response: 18:26:45.4004 LVL= 20544, 32753, 29394, 32755, AGC= 54, IDX= 441,-0.11,-1.462, 2.662,-2.674,-2.319, PHS= 0.959,-1.257,-0.399, RAW= 112.6, 7.3, CAL= 114.0, 8.2, ROT= 36.0, -8.2 2025-08-28T18:26:47.447Z,1756405607.447 [DAT](INFO): DAT read: Bearing 326.0, 30.7 (Local) 2025-08-28T18:26:47.447Z,1756405607.447 [DAT](INFO): Local bearing/azimuth received: Bearing 326.0, 30.7 (Local) 2025-08-28T18:26:47.451Z,1756405607.451 [DAT](INFO): DAT read: Range 11 to 50 : 18.5 m (Round-trip 24.7 ms) speed -0.1 m/s 2025-08-28T18:26:47.453Z,1756405607.453 [DAT](INFO): DAT read: user:4583> 2025-08-28T18:26:47.454Z,1756405607.454 [DAT](INFO): DAT read: Tx time:18:26:46.5216 2025-08-28T18:26:47.454Z,1756405607.454 [DAT](INFO): Ping request sent. 2025-08-28T18:26:47.454Z,1756405607.454 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:47.454Z,1756405607.454 [DAT](INFO): #Rx 5: Read range and direction messages. 2025-08-28T18:26:47.455Z,1756405607.455 [DAT](INFO): direction in FSK: [0.800746,0.581776,0.142629] 2025-08-28T18:26:47.461Z,1756405607.461 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:47.462Z,1756405607.462 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:47.465Z,1756405607.465 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:47.465Z,1756405607.465 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:50.453Z,1756405610.453 [DAT](INFO): DAT read: Rx Time:18:26:48.9506 2025-08-28T18:26:50.453Z,1756405610.453 [DAT](INFO): Rx dataTimestamp_ set to:1756405610.452721 2025-08-28T18:26:51.214Z,1756405611.214 [DAT](INFO): DAT read: 18:26:48.9506 LVL= 20256, 32753, 29154, 32755, AGC= 54, IDX= 427, 0.01, 2.855, 0.616, 1.625, 1.941, PHS= 1.015,-1.280,-0.360, RAW= 113.5, 6.3, CAL= 114.9, 7.2, ROT= 35.1, -7.2 2025-08-28T18:26:51.215Z,1756405611.215 [DAT](INFO): got valid direction response: 18:26:48.9506 LVL= 20256, 32753, 29154, 32755, AGC= 54, IDX= 427, 0.01, 2.855, 0.616, 1.625, 1.941, PHS= 1.015,-1.280,-0.360, RAW= 113.5, 6.3, CAL= 114.9, 7.2, ROT= 35.1, -7.2 2025-08-28T18:26:51.216Z,1756405611.216 [DAT](INFO): DAT read: Bearing 325.8, 27.4 (Local) 2025-08-28T18:26:51.217Z,1756405611.217 [DAT](INFO): Local bearing/azimuth received: Bearing 325.8, 27.4 (Local) 2025-08-28T18:26:51.218Z,1756405611.218 [DAT](INFO): DAT read: Range 11 to 50 : 18.6 m (Round-trip 24.9 ms) speed 0.0 m/s 2025-08-28T18:26:51.219Z,1756405611.219 [DAT](INFO): DAT read: user:4584> 2025-08-28T18:26:51.219Z,1756405611.219 [DAT](INFO): DAT read: Tx time:18:26:50.0716 2025-08-28T18:26:51.220Z,1756405611.220 [DAT](INFO): Ping request sent. 2025-08-28T18:26:51.220Z,1756405611.220 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:51.220Z,1756405611.220 [DAT](INFO): #Rx 6: Read range and direction messages. 2025-08-28T18:26:51.221Z,1756405611.221 [DAT](INFO): direction in FSK: [0.811698,0.570471,0.125333] 2025-08-28T18:26:51.221Z,1756405611.221 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:51.222Z,1756405611.222 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:51.234Z,1756405611.234 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:51.234Z,1756405611.234 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:53.980Z,1756405613.980 [DAT](INFO): DAT read: Rx Time:18:26:52.5002 2025-08-28T18:26:53.981Z,1756405613.981 [DAT](INFO): Rx dataTimestamp_ set to:1756405613.980595 2025-08-28T18:26:54.744Z,1756405614.744 [DAT](INFO): DAT read: 18:26:52.5002 LVL= 21728, 26785, 25282, 31555, AGC= 51, IDX= 439,-0.08,-0.161,-2.412,-1.415,-0.973, PHS= 0.914,-1.393,-0.486, RAW= 113.0, 9.6, CAL= 114.4, 10.9, ROT= 35.6, -10.9 2025-08-28T18:26:54.745Z,1756405614.745 [DAT](INFO): got valid direction response: 18:26:52.5002 LVL= 21728, 26785, 25282, 31555, AGC= 51, IDX= 439,-0.08,-0.161,-2.412,-1.415,-0.973, PHS= 0.914,-1.393,-0.486, RAW= 113.0, 9.6, CAL= 114.4, 10.9, ROT= 35.6, -10.9 2025-08-28T18:26:54.746Z,1756405614.746 [DAT](INFO): DAT read: Bearing 330.5, 23.3 (Local) 2025-08-28T18:26:54.747Z,1756405614.747 [DAT](INFO): Local bearing/azimuth received: Bearing 330.5, 23.3 (Local) 2025-08-28T18:26:54.749Z,1756405614.749 [DAT](INFO): DAT read: Range 11 to 50 : 18.3 m (Round-trip 24.5 ms) speed 0.1 m/s 2025-08-28T18:26:54.749Z,1756405614.749 [DAT](INFO): DAT read: user:4585> 2025-08-28T18:26:54.750Z,1756405614.750 [DAT](INFO): DAT read: Tx time:18:26:53.6216 2025-08-28T18:26:54.750Z,1756405614.750 [DAT](INFO): Ping request sent. 2025-08-28T18:26:54.750Z,1756405614.750 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:54.751Z,1756405614.751 [DAT](INFO): #Rx 7: Read range and direction messages. 2025-08-28T18:26:54.752Z,1756405614.752 [DAT](INFO): direction in FSK: [0.798431,0.571621,0.189095] 2025-08-28T18:26:54.752Z,1756405614.752 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:54.752Z,1756405614.752 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:54.755Z,1756405614.755 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:54.756Z,1756405614.756 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:57.509Z,1756405617.509 [DAT](INFO): DAT read: Rx Time:18:26:56.0493 2025-08-28T18:26:57.509Z,1756405617.509 [DAT](INFO): Rx dataTimestamp_ set to:1756405617.509178 2025-08-28T18:26:58.314Z,1756405618.314 [DAT](INFO): DAT read: 18:26:56.0493 LVL= 20128, 25841, 32402, 32755, AGC= 52, IDX= 429, 0.29, 2.436, 0.480, 0.883, 1.838, PHS= 0.700,-1.312,-0.999, RAW= 98.3, 16.9, CAL= 99.1, 20.5, ROT= 50.9, -20.5 2025-08-28T18:26:58.318Z,1756405618.318 [DAT](INFO): got valid direction response: 18:26:56.0493 LVL= 20128, 25841, 32402, 32755, AGC= 52, IDX= 429, 0.29, 2.436, 0.480, 0.883, 1.838, PHS= 0.700,-1.312,-0.999, RAW= 98.3, 16.9, CAL= 99.1, 20.5, ROT= 50.9, -20.5 2025-08-28T18:26:58.331Z,1756405618.331 [DAT](INFO): DAT read: Bearing 343.8, 29.7 (Local) 2025-08-28T18:26:58.332Z,1756405618.332 [DAT](INFO): Local bearing/azimuth received: Bearing 343.8, 29.7 (Local) 2025-08-28T18:26:58.333Z,1756405618.333 [DAT](INFO): DAT read: Range 11 to 50 : 17.6 m (Round-trip 23.5 ms) speed 0.2 m/s 2025-08-28T18:26:58.334Z,1756405618.334 [DAT](INFO): DAT read: user:4586> 2025-08-28T18:26:58.334Z,1756405618.334 [DAT](INFO): DAT read: Tx time:18:26:57.1716 2025-08-28T18:26:58.335Z,1756405618.335 [DAT](INFO): Ping request sent. 2025-08-28T18:26:58.335Z,1756405618.335 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:26:58.335Z,1756405618.335 [DAT](INFO): #Rx 8: Read range and direction messages. 2025-08-28T18:26:58.336Z,1756405618.336 [DAT](INFO): direction in FSK: [0.590736,0.726901,0.350207] 2025-08-28T18:26:58.336Z,1756405618.336 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:58.337Z,1756405618.337 [DAT](INFO): publishing direction and range info 2025-08-28T18:26:58.340Z,1756405618.340 [DAT](INFO): publishing transmit ping time 2025-08-28T18:26:58.340Z,1756405618.340 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:01.049Z,1756405621.049 [DAT](INFO): DAT read: Rx Time:18:26:59.5982 2025-08-28T18:27:01.049Z,1756405621.049 [DAT](INFO): Rx dataTimestamp_ set to:1756405621.048787 2025-08-28T18:27:01.811Z,1756405621.811 [DAT](INFO): DAT read: 18:26:59.5982 LVL= 21168, 27073, 30930, 32755, AGC= 52, IDX= 434,-0.39, 0.843,-1.278,-1.101, 0.076, PHS= 0.869,-1.309,-1.221, RAW= 92.1, 15.4, CAL= 92.3, 18.8, ROT= 57.7, -18.8 2025-08-28T18:27:01.812Z,1756405621.812 [DAT](INFO): got valid direction response: 18:26:59.5982 LVL= 21168, 27073, 30930, 32755, AGC= 52, IDX= 434,-0.39, 0.843,-1.278,-1.101, 0.076, PHS= 0.869,-1.309,-1.221, RAW= 92.1, 15.4, CAL= 92.3, 18.8, ROT= 57.7, -18.8 2025-08-28T18:27:01.814Z,1756405621.814 [DAT](INFO): DAT read: Bearing 342.5, 30.2 (Local) 2025-08-28T18:27:01.814Z,1756405621.814 [DAT](INFO): Local bearing/azimuth received: Bearing 342.5, 30.2 (Local) 2025-08-28T18:27:01.824Z,1756405621.824 [DAT](INFO): DAT read: Range 11 to 50 : 16.8 m (Round-trip 22.4 ms) speed 0.1 m/s 2025-08-28T18:27:01.825Z,1756405621.825 [DAT](INFO): DAT read: user:4587> 2025-08-28T18:27:01.826Z,1756405621.826 [DAT](INFO): DAT read: Tx time:18:27:00.7216 2025-08-28T18:27:01.826Z,1756405621.826 [DAT](INFO): Ping request sent. 2025-08-28T18:27:01.826Z,1756405621.826 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:27:01.826Z,1756405621.826 [DAT](INFO): #Rx 9: Read range and direction messages. 2025-08-28T18:27:01.828Z,1756405621.828 [DAT](INFO): direction in FSK: [0.505844,0.800167,0.322266] 2025-08-28T18:27:01.828Z,1756405621.828 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:01.829Z,1756405621.829 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:01.832Z,1756405621.832 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:01.832Z,1756405621.832 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:04.833Z,1756405624.833 [DAT](INFO): DAT read: Rx Time:18:27:03.1470 2025-08-28T18:27:04.834Z,1756405624.834 [DAT](INFO): Rx dataTimestamp_ set to:1756405624.833412 2025-08-28T18:27:05.345Z,1756405625.345 [DAT](INFO): DAT read: 18:27:03.1470 LVL= 20000, 21217, 26738, 29939, AGC= 53, IDX= 423, 0.43,-1.907, 2.474, 2.461,-2.440, PHS= 0.635,-1.324,-1.426, RAW= 87.5, 20.4, CAL= 86.9, 26.0, ROT= 63.1, -26.0 2025-08-28T18:27:05.346Z,1756405625.346 [DAT](INFO): got valid direction response: 18:27:03.1470 LVL= 20000, 21217, 26738, 29939, AGC= 53, IDX= 423, 0.43,-1.907, 2.474, 2.461,-2.440, PHS= 0.635,-1.324,-1.426, RAW= 87.5, 20.4, CAL= 86.9, 26.0, ROT= 63.1, -26.0 2025-08-28T18:27:05.347Z,1756405625.347 [DAT](INFO): DAT read: Bearing 351.6, 27.9 (Local) 2025-08-28T18:27:05.347Z,1756405625.347 [DAT](INFO): Local bearing/azimuth received: Bearing 351.6, 27.9 (Local) 2025-08-28T18:27:05.349Z,1756405625.349 [DAT](INFO): DAT read: Range 11 to 50 : 15.9 m (Round-trip 21.3 ms) speed 0.0 m/s 2025-08-28T18:27:05.350Z,1756405625.350 [DAT](INFO): DAT read: user:4588> 2025-08-28T18:27:05.355Z,1756405625.355 [DAT](INFO): DAT read: Tx time:18:27:04.2715 2025-08-28T18:27:05.357Z,1756405625.357 [DAT](INFO): Ping request sent. 2025-08-28T18:27:05.357Z,1756405625.357 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:27:05.358Z,1756405625.358 [DAT](INFO): #Rx 10: Read range and direction messages. 2025-08-28T18:27:05.362Z,1756405625.362 [DAT](INFO): direction in FSK: [0.406646,0.801542,0.438371] 2025-08-28T18:27:05.363Z,1756405625.363 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:05.364Z,1756405625.364 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:05.377Z,1756405625.377 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:05.378Z,1756405625.378 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:08.361Z,1756405628.361 [DAT](INFO): DAT read: Rx Time:18:27:06.6965 2025-08-28T18:27:08.361Z,1756405628.361 [DAT](INFO): Rx dataTimestamp_ set to:1756405628.360779 2025-08-28T18:27:08.866Z,1756405628.866 [DAT](INFO): DAT read: Range 11 to 50 : 15.6 m (Round-trip 20.8 ms) speed 0.0 m/s 2025-08-28T18:27:08.866Z,1756405628.866 [DAT](INFO): DAT read: user:4589> 2025-08-28T18:27:08.867Z,1756405628.867 [DAT](INFO): DAT read: Tx time:18:27:07.8215 2025-08-28T18:27:08.867Z,1756405628.867 [DAT](INFO): Ping request sent. 2025-08-28T18:27:08.867Z,1756405628.867 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:27:08.868Z,1756405628.868 [DAT](ERROR): #Rx 11: Read range message, but no direction. 2025-08-28T18:27:08.868Z,1756405628.868 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:08.868Z,1756405628.868 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:11.889Z,1756405631.889 [DAT](INFO): DAT read: Rx Time:18:27:10.2464 2025-08-28T18:27:11.889Z,1756405631.889 [DAT](INFO): Rx dataTimestamp_ set to:1756405631.888867 2025-08-28T18:27:12.399Z,1756405632.399 [DAT](INFO): DAT read: 18:27:10.2464 LVL= 20432, 22705, 31378, 32627, AGC= 53, IDX= 434,-0.42,-3.012, 2.088, 1.266, 3.101, PHS= 0.273,-0.968,-1.879, RAW= 65.1, 25.9, CAL= 60.8, 32.5, ROT= 89.2, -32.5 2025-08-28T18:27:12.410Z,1756405632.410 [DAT](INFO): got valid direction response: 18:27:10.2464 LVL= 20432, 22705, 31378, 32627, AGC= 53, IDX= 434,-0.42,-3.012, 2.088, 1.266, 3.101, PHS= 0.273,-0.968,-1.879, RAW= 65.1, 25.9, CAL= 60.8, 32.5, ROT= 89.2, -32.5 2025-08-28T18:27:12.411Z,1756405632.411 [DAT](INFO): DAT read: Bearing 9.7, 39.0 (Local) 2025-08-28T18:27:12.412Z,1756405632.412 [DAT](INFO): Local bearing/azimuth received: Bearing 9.7, 39.0 (Local) 2025-08-28T18:27:12.413Z,1756405632.413 [DAT](INFO): DAT read: Range 11 to 50 : 15.6 m (Round-trip 20.8 ms) speed 0.0 m/s 2025-08-28T18:27:12.414Z,1756405632.414 [DAT](INFO): DAT read: user:4590> 2025-08-28T18:27:12.415Z,1756405632.415 [DAT](INFO): DAT read: Tx time:18:27:11.3715 2025-08-28T18:27:12.415Z,1756405632.415 [DAT](INFO): Ping request sent. 2025-08-28T18:27:12.415Z,1756405632.415 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:27:12.427Z,1756405632.427 [DAT](INFO): #Rx 12: Read range and direction messages. 2025-08-28T18:27:12.432Z,1756405632.432 [DAT](INFO): direction in FSK: [0.011776,0.843309,0.537300] 2025-08-28T18:27:12.433Z,1756405632.433 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:12.433Z,1756405632.433 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:12.440Z,1756405632.440 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:12.440Z,1756405632.440 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:15.417Z,1756405635.417 [DAT](INFO): DAT read: Rx Time:18:27:13.7969 2025-08-28T18:27:15.418Z,1756405635.418 [DAT](INFO): Rx dataTimestamp_ set to:1756405635.417356 2025-08-28T18:27:15.926Z,1756405635.926 [DAT](INFO): DAT read: 18:27:13.7969 LVL= 16736, 23313, 32754, 32755, AGC= 51, IDX= 455,-0.31,-1.161,-1.513,-2.845,-0.767, PHS=-0.292,-0.701,-2.122, RAW= 42.3, 33.5, CAL= 39.6, 40.2, ROT= 110.4, -40.2 2025-08-28T18:27:15.927Z,1756405635.927 [DAT](INFO): got valid direction response: 18:27:13.7969 LVL= 16736, 23313, 32754, 32755, AGC= 51, IDX= 455,-0.31,-1.161,-1.513,-2.845,-0.767, PHS=-0.292,-0.701,-2.122, RAW= 42.3, 33.5, CAL= 39.6, 40.2, ROT= 110.4, -40.2 2025-08-28T18:27:15.928Z,1756405635.928 [DAT](INFO): DAT read: Bearing 33.1, 44.6 (Local) 2025-08-28T18:27:15.928Z,1756405635.928 [DAT](INFO): Local bearing/azimuth received: Bearing 33.1, 44.6 (Local) 2025-08-28T18:27:15.930Z,1756405635.930 [DAT](INFO): DAT read: Range 11 to 50 : 15.9 m (Round-trip 21.2 ms) speed -0.2 m/s 2025-08-28T18:27:15.930Z,1756405635.930 [DAT](INFO): DAT read: user:4591> 2025-08-28T18:27:15.931Z,1756405635.931 [DAT](INFO): DAT read: Tx time:18:27:14.9215 2025-08-28T18:27:15.950Z,1756405635.950 [DAT](INFO): Ping request sent. 2025-08-28T18:27:15.951Z,1756405635.951 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:27:15.951Z,1756405635.951 [DAT](INFO): #Rx 13: Read range and direction messages. 2025-08-28T18:27:15.957Z,1756405635.957 [DAT](INFO): direction in FSK: [-0.266238,0.715892,0.645458] 2025-08-28T18:27:15.957Z,1756405635.957 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:15.957Z,1756405635.957 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:15.976Z,1756405635.976 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:15.977Z,1756405635.977 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:18.946Z,1756405638.946 [DAT](INFO): DAT read: Rx Time:18:27:17.3482 2025-08-28T18:27:18.946Z,1756405638.946 [DAT](INFO): Rx dataTimestamp_ set to:1756405638.945838 2025-08-28T18:27:19.454Z,1756405639.454 [DAT](INFO): DAT read: 18:27:17.3482 LVL= 18992, 21617, 32754, 32755, AGC= 52, IDX= 436,-0.30, 2.854, 2.614, 1.350,-2.947, PHS=-0.380,-0.678,-2.030, RAW= 39.7, 35.6, CAL= 37.3, 42.0, ROT= 112.7, -42.0 2025-08-28T18:27:19.455Z,1756405639.455 [DAT](INFO): got valid direction response: 18:27:17.3482 LVL= 18992, 21617, 32754, 32755, AGC= 52, IDX= 436,-0.30, 2.854, 2.614, 1.350,-2.947, PHS=-0.380,-0.678,-2.030, RAW= 39.7, 35.6, CAL= 37.3, 42.0, ROT= 112.7, -42.0 2025-08-28T18:27:19.456Z,1756405639.456 [DAT](INFO): DAT read: Bearing 37.8, 43.6 (Local) 2025-08-28T18:27:19.456Z,1756405639.456 [DAT](INFO): Local bearing/azimuth received: Bearing 37.8, 43.6 (Local) 2025-08-28T18:27:19.458Z,1756405639.458 [DAT](INFO): DAT read: Range 11 to 50 : 16.8 m (Round-trip 22.5 ms) speed -0.3 m/s 2025-08-28T18:27:19.458Z,1756405639.458 [DAT](INFO): DAT read: user:4592> 2025-08-28T18:27:19.459Z,1756405639.459 [DAT](INFO): DAT read: Tx time:18:27:18.4715 2025-08-28T18:27:19.459Z,1756405639.459 [DAT](INFO): Ping request sent. 2025-08-28T18:27:19.460Z,1756405639.460 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:27:19.460Z,1756405639.460 [DAT](INFO): #Rx 14: Read range and direction messages. 2025-08-28T18:27:19.461Z,1756405639.461 [DAT](INFO): direction in FSK: [-0.286784,0.685579,0.669131] 2025-08-28T18:27:19.461Z,1756405639.461 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:19.461Z,1756405639.461 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:19.464Z,1756405639.464 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:19.465Z,1756405639.465 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:19.706Z,1756405639.706 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-08-28T18:27:22.473Z,1756405642.473 [DAT](INFO): DAT read: Rx Time:18:27:20.9000 2025-08-28T18:27:22.473Z,1756405642.473 [DAT](INFO): Rx dataTimestamp_ set to:1756405642.472699 2025-08-28T18:27:22.989Z,1756405642.989 [DAT](INFO): DAT read: 18:27:20.9000 LVL= 19120, 17825, 31730, 32387, AGC= 55, IDX= 437,-0.48,-0.248,-0.596,-1.965, 0.033, PHS=-0.179,-0.584,-2.042, RAW= 41.9, 30.3, CAL= 39.0, 36.2, ROT= 111.0, -36.2 2025-08-28T18:27:22.990Z,1756405642.990 [DAT](INFO): got valid direction response: 18:27:20.9000 LVL= 19120, 17825, 31730, 32387, AGC= 55, IDX= 437,-0.48,-0.248,-0.596,-1.965, 0.033, PHS=-0.179,-0.584,-2.042, RAW= 41.9, 30.3, CAL= 39.0, 36.2, ROT= 111.0, -36.2 2025-08-28T18:27:22.991Z,1756405642.991 [DAT](INFO): DAT read: Bearing 34.4, 48.2 (Local) 2025-08-28T18:27:22.991Z,1756405642.991 [DAT](INFO): Local bearing/azimuth received: Bearing 34.4, 48.2 (Local) 2025-08-28T18:27:22.993Z,1756405642.993 [DAT](INFO): DAT read: Range 11 to 50 : 18.2 m (Round-trip 24.3 ms) speed -0.4 m/s 2025-08-28T18:27:22.994Z,1756405642.994 [DAT](INFO): DAT read: user:4593> 2025-08-28T18:27:22.994Z,1756405642.994 [DAT](INFO): DAT read: Tx time:18:27:22.0215 2025-08-28T18:27:22.995Z,1756405642.995 [DAT](INFO): Ping request sent. 2025-08-28T18:27:22.995Z,1756405642.995 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:27:22.995Z,1756405642.995 [DAT](INFO): #Rx 15: Read range and direction messages. 2025-08-28T18:27:23.034Z,1756405643.034 [DAT](INFO): direction in FSK: [-0.289189,0.753362,0.590606] 2025-08-28T18:27:23.034Z,1756405643.034 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:23.035Z,1756405643.035 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:23.070Z,1756405643.070 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:23.070Z,1756405643.070 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:26.000Z,1756405646.000 [DAT](INFO): DAT read: Rx Time:18:27:24.4520 2025-08-28T18:27:26.001Z,1756405646.001 [DAT](INFO): Rx dataTimestamp_ set to:1756405646.000667 2025-08-28T18:27:26.513Z,1756405646.513 [DAT](INFO): DAT read: 18:27:24.4520 LVL= 27120, 27249, 32754, 32755, AGC= 59, IDX= 442,-0.28,-3.040,-2.970, 1.829,-2.651, PHS=-0.287,-0.274,-1.847, RAW= 29.6, 28.5, CAL= 27.3, 33.6, ROT= 122.7, -33.6 2025-08-28T18:27:26.514Z,1756405646.514 [DAT](INFO): got valid direction response: 18:27:24.4520 LVL= 27120, 27249, 32754, 32755, AGC= 59, IDX= 442,-0.28,-3.040,-2.970, 1.829,-2.651, PHS=-0.287,-0.274,-1.847, RAW= 29.6, 28.5, CAL= 27.3, 33.6, ROT= 122.7, -33.6 2025-08-28T18:27:26.515Z,1756405646.515 [DAT](INFO): DAT read: Bearing 50.0, 53.5 (Local) 2025-08-28T18:27:26.515Z,1756405646.515 [DAT](INFO): Local bearing/azimuth received: Bearing 50.0, 53.5 (Local) 2025-08-28T18:27:26.516Z,1756405646.516 [DAT](INFO): DAT read: Range 11 to 50 : 19.7 m (Round-trip 26.3 ms) speed -0.5 m/s 2025-08-28T18:27:26.517Z,1756405646.517 [DAT](INFO): DAT read: user:4594> 2025-08-28T18:27:26.518Z,1756405646.518 [DAT](INFO): DAT read: Tx time:18:27:25.5715 2025-08-28T18:27:26.518Z,1756405646.518 [DAT](INFO): Ping request sent. 2025-08-28T18:27:26.518Z,1756405646.518 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:27:26.518Z,1756405646.518 [DAT](INFO): #Rx 16: Read range and direction messages. 2025-08-28T18:27:26.520Z,1756405646.520 [DAT](INFO): direction in FSK: [-0.449978,0.700912,0.553392] 2025-08-28T18:27:26.520Z,1756405646.520 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:26.520Z,1756405646.520 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:26.524Z,1756405646.524 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:26.524Z,1756405646.524 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:29.529Z,1756405649.529 [DAT](INFO): DAT read: Rx Time:18:27:28.0043 2025-08-28T18:27:29.530Z,1756405649.530 [DAT](INFO): Rx dataTimestamp_ set to:1756405649.529492 2025-08-28T18:27:30.047Z,1756405650.047 [DAT](INFO): DAT read: 18:27:28.0043 LVL= 23280, 23857, 32754, 32755, AGC= 60, IDX= 450, 0.13,-0.897,-0.703,-2.411,-0.534, PHS=-0.261,-0.124,-1.921, RAW= 26.1, 25.2, CAL= 24.0, 29.8, ROT= 126.0, -29.8 2025-08-28T18:27:30.048Z,1756405650.048 [DAT](INFO): got valid direction response: 18:27:28.0043 LVL= 23280, 23857, 32754, 32755, AGC= 60, IDX= 450, 0.13,-0.897,-0.703,-2.411,-0.534, PHS=-0.261,-0.124,-1.921, RAW= 26.1, 25.2, CAL= 24.0, 29.8, ROT= 126.0, -29.8 2025-08-28T18:27:30.050Z,1756405650.050 [DAT](INFO): DAT read: Bearing 56.9, 59.4 (Local) 2025-08-28T18:27:30.059Z,1756405650.059 [DAT](INFO): Local bearing/azimuth received: Bearing 56.9, 59.4 (Local) 2025-08-28T18:27:30.061Z,1756405650.061 [DAT](INFO): DAT read: Range 11 to 50 : 21.5 m (Round-trip 28.7 ms) speed -0.5 m/s 2025-08-28T18:27:30.061Z,1756405650.061 [DAT](INFO): DAT read: user:4595> 2025-08-28T18:27:30.062Z,1756405650.062 [DAT](INFO): DAT read: Tx time:18:27:29.1215 2025-08-28T18:27:30.062Z,1756405650.062 [DAT](INFO): Ping request sent. 2025-08-28T18:27:30.062Z,1756405650.062 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:27:30.063Z,1756405650.063 [DAT](INFO): #Rx 17: Read range and direction messages. 2025-08-28T18:27:30.064Z,1756405650.064 [DAT](INFO): direction in FSK: [-0.510060,0.702037,0.496974] 2025-08-28T18:27:30.064Z,1756405650.064 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:30.064Z,1756405650.064 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:30.073Z,1756405650.073 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:30.073Z,1756405650.073 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:33.061Z,1756405653.061 [DAT](INFO): DAT read: Rx Time:18:27:31.5568 2025-08-28T18:27:33.061Z,1756405653.061 [DAT](INFO): Rx dataTimestamp_ set to:1756405653.060993 2025-08-28T18:27:33.843Z,1756405653.843 [DAT](INFO): DAT read: 18:27:31.5568 LVL= 30160, 27441, 32754, 32755, AGC= 61, IDX= 443, 0.32,-1.615,-1.176,-2.954,-1.214, PHS=-0.299, 0.083,-1.784, RAW= 18.8, 22.5, CAL= 17.4, 26.8, ROT= 132.6, -26.8 2025-08-28T18:27:33.844Z,1756405653.844 [DAT](INFO): got valid direction response: 18:27:31.5568 LVL= 30160, 27441, 32754, 32755, AGC= 61, IDX= 443, 0.32,-1.615,-1.176,-2.954,-1.214, PHS=-0.299, 0.083,-1.784, RAW= 18.8, 22.5, CAL= 17.4, 26.8, ROT= 132.6, -26.8 2025-08-28T18:27:33.845Z,1756405653.845 [DAT](INFO): DAT read: Bearing 73.4, 61.7 (Local) 2025-08-28T18:27:33.846Z,1756405653.846 [DAT](INFO): Local bearing/azimuth received: Bearing 73.4, 61.7 (Local) 2025-08-28T18:27:33.847Z,1756405653.847 [DAT](INFO): DAT read: Range 11 to 50 : 23.4 m (Round-trip 31.2 ms) speed -0.5 m/s 2025-08-28T18:27:33.852Z,1756405653.852 [DAT](INFO): DAT read: user:4596> 2025-08-28T18:27:33.853Z,1756405653.853 [DAT](INFO): DAT read: Tx time:18:27:32.6714 2025-08-28T18:27:33.853Z,1756405653.853 [DAT](INFO): Ping request sent. 2025-08-28T18:27:33.853Z,1756405653.853 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:27:33.853Z,1756405653.853 [DAT](INFO): #Rx 18: Read range and direction messages. 2025-08-28T18:27:33.854Z,1756405653.854 [DAT](INFO): direction in FSK: [-0.604170,0.657030,0.450878] 2025-08-28T18:27:33.855Z,1756405653.855 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:33.855Z,1756405653.855 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:33.863Z,1756405653.863 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:33.868Z,1756405653.868 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:35.921Z,1756405655.921 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +71.84, 29.11, 0.00 2025-08-28T18:27:36.592Z,1756405656.592 [DAT](INFO): DAT read: Rx Time:18:27:35.1094 2025-08-28T18:27:36.593Z,1756405656.593 [DAT](INFO): Rx dataTimestamp_ set to:1756405656.592688 2025-08-28T18:27:37.355Z,1756405657.355 [DAT](INFO): DAT read: 18:27:35.1094 LVL= 24400, 23409, 32754, 32755, AGC= 62, IDX= 455, 0.13,-1.330,-0.702,-2.674,-0.849, PHS=-0.380, 0.191,-1.870, RAW= 14.4, 21.5, CAL= 13.4, 25.9, ROT= 136.6, -25.9 2025-08-28T18:27:37.356Z,1756405657.356 [DAT](INFO): got valid direction response: 18:27:35.1094 LVL= 24400, 23409, 32754, 32755, AGC= 62, IDX= 455, 0.13,-1.330,-0.702,-2.674,-0.849, PHS=-0.380, 0.191,-1.870, RAW= 14.4, 21.5, CAL= 13.4, 25.9, ROT= 136.6, -25.9 2025-08-28T18:27:37.357Z,1756405657.357 [DAT](INFO): DAT read: Bearing 85.1, 62.5 (Local) 2025-08-28T18:27:37.357Z,1756405657.357 [DAT](INFO): Local bearing/azimuth received: Bearing 85.1, 62.5 (Local) 2025-08-28T18:27:37.359Z,1756405657.359 [DAT](INFO): DAT read: Range 11 to 50 : 25.3 m (Round-trip 33.8 ms) speed -0.6 m/s 2025-08-28T18:27:37.359Z,1756405657.359 [DAT](INFO): DAT read: user:4597> 2025-08-28T18:27:37.360Z,1756405657.360 [DAT](INFO): DAT read: Tx time:18:27:36.2214 2025-08-28T18:27:37.360Z,1756405657.360 [DAT](INFO): Ping request sent. 2025-08-28T18:27:37.360Z,1756405657.360 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:27:37.361Z,1756405657.361 [DAT](INFO): #Rx 19: Read range and direction messages. 2025-08-28T18:27:37.362Z,1756405657.362 [DAT](INFO): direction in FSK: [-0.653596,0.618075,0.436802] 2025-08-28T18:27:37.362Z,1756405657.362 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:37.362Z,1756405657.362 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:37.365Z,1756405657.365 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:37.366Z,1756405657.366 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:40.121Z,1756405660.121 [DAT](INFO): DAT read: Rx Time:18:27:38.6622 2025-08-28T18:27:40.121Z,1756405660.121 [DAT](INFO): Rx dataTimestamp_ set to:1756405660.120763 2025-08-28T18:27:40.903Z,1756405660.903 [DAT](INFO): DAT read: 18:27:38.6622 LVL= 29072, 24561, 32754, 32755, AGC= 64, IDX= 451,-0.44,-2.759,-1.912, 2.387,-2.192, PHS=-0.465, 0.325,-1.748, RAW= 7.8, 20.2, CAL= 7.4, 24.6, ROT= 142.6, -24.6 2025-08-28T18:27:40.907Z,1756405660.907 [DAT](INFO): got valid direction response: 18:27:38.6622 LVL= 29072, 24561, 32754, 32755, AGC= 64, IDX= 451,-0.44,-2.759,-1.912, 2.387,-2.192, PHS=-0.465, 0.325,-1.748, RAW= 7.8, 20.2, CAL= 7.4, 24.6, ROT= 142.6, -24.6 2025-08-28T18:27:40.910Z,1756405660.910 [DAT](INFO): DAT read: Bearing 99.0, 60.7 (Local) 2025-08-28T18:27:40.913Z,1756405660.913 [DAT](INFO): Local bearing/azimuth received: Bearing 99.0, 60.7 (Local) 2025-08-28T18:27:40.918Z,1756405660.918 [DAT](INFO): DAT read: Range 11 to 50 : 27.4 m (Round-trip 36.6 ms) speed -0.5 m/s 2025-08-28T18:27:40.921Z,1756405660.921 [DAT](INFO): DAT read: user:4598> 2025-08-28T18:27:40.925Z,1756405660.925 [DAT](INFO): DAT read: Tx time:18:27:39.7714 2025-08-28T18:27:40.926Z,1756405660.926 [DAT](INFO): Ping request sent. 2025-08-28T18:27:40.927Z,1756405660.927 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:27:40.928Z,1756405660.928 [DAT](INFO): #Rx 20: Read range and direction messages. 2025-08-28T18:27:40.934Z,1756405660.934 [DAT](INFO): direction in FSK: [-0.722311,0.552248,0.416281] 2025-08-28T18:27:40.934Z,1756405660.934 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:40.984Z,1756405660.984 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:40.987Z,1756405660.987 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:40.987Z,1756405660.987 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:43.653Z,1756405663.653 [DAT](INFO): DAT read: Rx Time:18:27:42.2150 2025-08-28T18:27:43.653Z,1756405663.653 [DAT](INFO): Rx dataTimestamp_ set to:1756405663.653001 2025-08-28T18:27:44.414Z,1756405664.414 [DAT](INFO): DAT read: 18:27:42.2150 LVL= 24800, 22289, 32754, 32755, AGC= 65, IDX= 448, 0.33,-2.271,-1.419, 2.891,-1.731, PHS=-0.438, 0.357,-1.705, RAW= 7.5, 19.3, CAL= 7.2, 23.6, ROT= 142.8, -23.6 2025-08-28T18:27:44.415Z,1756405664.415 [DAT](INFO): got valid direction response: 18:27:42.2150 LVL= 24800, 22289, 32754, 32755, AGC= 65, IDX= 448, 0.33,-2.271,-1.419, 2.891,-1.731, PHS=-0.438, 0.357,-1.705, RAW= 7.5, 19.3, CAL= 7.2, 23.6, ROT= 142.8, -23.6 2025-08-28T18:27:44.416Z,1756405664.416 [DAT](INFO): DAT read: Bearing 103.0, 61.8 (Local) 2025-08-28T18:27:44.417Z,1756405664.417 [DAT](INFO): Local bearing/azimuth received: Bearing 103.0, 61.8 (Local) 2025-08-28T18:27:44.418Z,1756405664.418 [DAT](INFO): DAT read: Range 11 to 50 : 29.5 m (Round-trip 39.4 ms) speed -0.7 m/s 2025-08-28T18:27:44.419Z,1756405664.419 [DAT](INFO): DAT read: user:4599> 2025-08-28T18:27:44.419Z,1756405664.419 [DAT](INFO): DAT read: Tx time:18:27:43.3214 2025-08-28T18:27:44.420Z,1756405664.420 [DAT](INFO): Ping request sent. 2025-08-28T18:27:44.420Z,1756405664.420 [DAT](INFO): transmitted an acoustic signal 2025-08-28T18:27:44.420Z,1756405664.420 [DAT](INFO): #Rx 21: Read range and direction messages. 2025-08-28T18:27:44.421Z,1756405664.421 [DAT](INFO): direction in FSK: [-0.729910,0.554032,0.400349] 2025-08-28T18:27:44.421Z,1756405664.421 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:44.422Z,1756405664.422 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:44.425Z,1756405664.425 [DAT](INFO): publishing transmit ping time 2025-08-28T18:27:44.425Z,1756405664.425 [DAT](INFO): publishing direction and range info 2025-08-28T18:27:47.442Z,1756405667.442 [DAT](INFO): DAT read: Rx Time:18:27:45.7678 2025-08-28T18:27:47.442Z,1756405667.442 [DAT](INFO): Rx dataTimestamp_ set to:1756405667.441951 2025-08-28T18:27:47.950Z,1756405667.950 [DAT](INFO): DAT read: 18:27:45.7678 LVL= 32752, 25697, 32754, 32755, AGC= 69, IDX= 461, 0.14, 0.783, 1.721,-0.298, 1.373, PHS=-0.488, 0.393,-1.716, RAW= 5.4, 19.2, CAL= 5.3, 23.6, ROT= 144.7, -23.6 2025-08-28T18:27:47.951Z,1756405667.951 [DAT](INFO): got valid direction response: 18:27:45.7678 LVL= 32752, 25697, 32754, 32755, AGC= 69, IDX= 461, 0.14, 0.783, 1.721,-0.298, 1.373, PHS=-0.488, 0.393,-1.716, RAW= 5.4, 19.2, CAL= 5.3, 23.6, ROT= 144.7, -23.6 2025-08-28T18:27:47.952Z,1756405667.952 [DAT](INFO): DAT read: Bearing 108.0, 60.7 (Local) 2025-08-28T18:27:47.952Z,1756405667.952 [DAT](INFO): Local bearing/azimuth received: Bearing 108.0, 60.7 (Local) 2025-08-28T18:27:47.953Z,1756405667.953 [DAT](INFO): DAT read: Range 11 to 50 : 31.6 m (Round-trip 42.2 ms) speed -0.7 m/s 2025-08-28T18:27:47.954Z,1756405667.954 [DAT](INFO): #Rx 22: Read range and direction messages. 2025-08-28T18:27:47.955Z,1756405667.955 [DAT](INFO): direction in FSK: [-0.747878,0.529527,0.400349] 2025-08-28T18:27:47.955Z,1756405667.955 [DAT](INFO): publishing direction and range info 2025-08-28T18:28:31.844Z,1756405711.844 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-28T18:28:52.508Z,1756405732.508 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-28T18:28:53.485Z,1756405733.485 [DAT](INFO): DAT read: user:4600>Rx Time:18:28:51.9106 2025-08-28T18:28:53.485Z,1756405733.485 [DAT](INFO): Rx dataTimestamp_ set to:1756405733.484828 2025-08-28T18:28:53.512Z,1756405733.512 [DataOverHttps](INFO): Radio surface powered ON. 2025-08-28T18:28:53.512Z,1756405733.512 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-28T18:28:54.250Z,1756405734.250 [DAT](INFO): DAT read: 18:28:51.9106 LVL= 32752, 27457, 32754, 32755, AGC= 69, IDX= 390, 0.08, 1.221, 2.463, 0.222, 1.808, PHS=-0.484, 0.700,-1.630, RAW= 359.5, 13.9, CAL= 359.9, 17.1, ROT= 150.1, -17.1 2025-08-28T18:28:54.251Z,1756405734.251 [DAT](INFO): got valid direction response: 18:28:51.9106 LVL= 32752, 27457, 32754, 32755, AGC= 69, IDX= 390, 0.08, 1.221, 2.463, 0.222, 1.808, PHS=-0.484, 0.700,-1.630, RAW= 359.5, 13.9, CAL= 359.9, 17.1, ROT= 150.1, -17.1 2025-08-28T18:28:54.251Z,1756405734.251 [DAT](INFO): DAT read: 2025-08-28T18:28:54.252Z,1756405734.252 [DAT](INFO): DAT read: DATA(0003): 2025-08-28T18:28:54.252Z,1756405734.252 [DAT](INFO): Got DATA 3 2025-08-28T18:28:54.252Z,1756405734.252 [DAT](INFO): DAT read: 2025-08-28T18:28:54.253Z,1756405734.253 [DAT](INFO): Got DATA 0 2025-08-28T18:28:54.253Z,1756405734.253 [DAT](INFO): DAT read: 2025-08-28T18:28:54.253Z,1756405734.253 [DAT](INFO): DAT read: 2025-08-28T18:28:54.254Z,1756405734.254 [DAT](INFO): DAT read: Source:050 Destination:011 2025-08-28T18:28:54.254Z,1756405734.254 [DAT](INFO): Got Src/Dest after DATA 2025-08-28T18:28:54.255Z,1756405734.255 [DAT](INFO): DATA Src=50, Dst=11 2025-08-28T18:28:54.256Z,1756405734.256 [DAT](INFO): DAT read: CRC:Pass MPD:09.0 PSNR:11.2 AGC:70 SPD:-0.8 CCERR:009 2025-08-28T18:28:54.256Z,1756405734.256 [DAT](INFO): Got CRC:Pass 2025-08-28T18:28:54.256Z,1756405734.256 [DAT](INFO): Got CRC:Pass 2025-08-28T18:28:54.256Z,1756405734.256 [DAT](INFO): Incoming data is intended for us 2025-08-28T18:28:54.256Z,1756405734.256 [DAT](INFO): Received command: 2025-08-28T18:28:54.256Z,1756405734.256 [DAT](INFO): #Outgoing data=1 2025-08-28T18:28:54.257Z,1756405734.257 [DAT](INFO): Sending ack 2025-08-28T18:28:54.257Z,1756405734.257 [DAT](INFO): DAT read: 2025-08-28T18:28:54.257Z,1756405734.257 [DAT](INFO): DAT read: 2025-08-28T18:28:54.258Z,1756405734.258 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2025-08-28T18:28:54.258Z,1756405734.258 [DAT](INFO): #Rx 23: Read direction message, but no range. 2025-08-28T18:28:54.259Z,1756405734.259 [DAT](INFO): direction in FSK: [-0.828574,0.476451,0.294040] 2025-08-28T18:28:54.492Z,1756405734.492 [DAT](INFO): setting remote address to 50 2025-08-28T18:28:54.744Z,1756405734.744 [DAT](INFO): DAT read: 2025-08-28T18:28:54.745Z,1756405734.745 [DAT](INFO): DAT read: RemoteAddr | 50 2025-08-28T18:28:54.745Z,1756405734.745 [DAT](INFO): set remote address to 50 2025-08-28T18:28:54.746Z,1756405734.746 [DAT](INFO): entering online mode 2025-08-28T18:28:54.996Z,1756405734.996 [DAT](INFO): DAT read: user:4601> 2025-08-28T18:28:54.997Z,1756405734.997 [DAT](INFO): DAT read: 2025-08-28T18:28:54.998Z,1756405734.998 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-08-28T18:28:54.998Z,1756405734.998 [DAT](INFO): commRate: 600 2025-08-28T18:28:54.998Z,1756405734.998 [DAT](INFO): online mode acknowledged 2025-08-28T18:28:54.999Z,1756405734.999 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2025-08-28T18:28:57.516Z,1756405737.516 [DAT](INFO): DAT read: Rx Time:18:28:56.0628 2025-08-28T18:28:57.517Z,1756405737.517 [DAT](INFO): Rx dataTimestamp_ set to:1756405737.516674 2025-08-28T18:28:58.277Z,1756405738.277 [DAT](INFO): DAT read: 18:28:56.0628 LVL= 32752, 32753, 32754, 32755, AGC= 69, IDX= 349,-0.02,-0.401, 0.874,-1.414, 0.078, PHS=-0.377, 0.841,-1.536, RAW= 359.2, 10.4, CAL= 359.4, 11.7, ROT= 150.6, -11.7 2025-08-28T18:28:58.280Z,1756405738.280 [DAT](INFO): got valid direction response: 18:28:56.0628 LVL= 32752, 32753, 32754, 32755, AGC= 69, IDX= 349,-0.02,-0.401, 0.874,-1.414, 0.078, PHS=-0.377, 0.841,-1.536, RAW= 359.2, 10.4, CAL= 359.4, 11.7, ROT= 150.6, -11.7 2025-08-28T18:28:58.285Z,1756405738.285 [DAT](INFO): DAT read: Forwarding Delay UpTx time:18:28:57.3200 2025-08-28T18:28:58.286Z,1756405738.286 [DAT](DEBUG): In parseResponses, sent ack so set commsState_ = SENDING_VERIFIED 2025-08-28T18:28:58.287Z,1756405738.287 [DAT](INFO): DAT read: 2025-08-28T18:28:58.289Z,1756405738.289 [DAT](INFO): DAT read: DATA(0005):++++ 2025-08-28T18:28:58.290Z,1756405738.290 [DAT](INFO): Got DATA 5 2025-08-28T18:28:58.292Z,1756405738.292 [DAT](INFO): DAT read: 2025-08-28T18:28:58.297Z,1756405738.297 [DAT](INFO): DAT read: Source:050 Destination:011 2025-08-28T18:28:58.298Z,1756405738.298 [DAT](INFO): Got Src/Dest after DATA 2025-08-28T18:28:58.301Z,1756405738.301 [DAT](INFO): DATA Src=50, Dst=11 2025-08-28T18:28:58.304Z,1756405738.304 [DAT](INFO): DAT read: CRC:Pass MPD:08.8 PSNR:11.8 AGC:65 SPD:-0.8 CCERR:009 2025-08-28T18:28:58.304Z,1756405738.304 [DAT](INFO): Got CRC:Pass 2025-08-28T18:28:58.304Z,1756405738.304 [DAT](INFO): Got CRC:Pass 2025-08-28T18:28:58.304Z,1756405738.304 [DAT](INFO): Incoming data is intended for us 2025-08-28T18:28:58.305Z,1756405738.305 [DAT](INFO): Received command: ++++ 2025-08-28T18:28:58.305Z,1756405738.305 [DAT](INFO): Sending ack 2025-08-28T18:28:58.306Z,1756405738.306 [DAT](INFO): DAT read: 2025-08-28T18:28:58.306Z,1756405738.306 [DAT](INFO): DAT read: 2025-08-28T18:28:58.307Z,1756405738.307 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2025-08-28T18:28:58.307Z,1756405738.307 [DAT](INFO): #Rx 24: Read direction message, but no range. 2025-08-28T18:28:58.308Z,1756405738.308 [DAT](INFO): direction in FSK: [-0.853112,0.480704,0.202787] 2025-08-28T18:29:01.549Z,1756405741.549 [DAT](INFO): DAT read: Forwarding Delay UpTx time:18:29:00.5200 2025-08-28T18:29:08.976Z,1756405748.976 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2025-08-28T18:29:08.976Z,1756405748.976 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-28T18:29:08.976Z,1756405748.976 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-28T18:29:23.636Z,1756405763.636 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-28T18:29:24.640Z,1756405764.640 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-28T18:29:50.613Z,1756405790.613 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD-1.74, +85.26, +2.02, 20.00, 0.00 2025-08-28T18:29:54.764Z,1756405794.764 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-28T18:29:55.764Z,1756405795.764 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-28T18:30:25.884Z,1756405825.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-28T18:30:26.888Z,1756405826.888 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-08-28T18:30:34.873Z,1756405834.873 [DataOverHttps](IMPORTANT): SBD MTMSN=20250828T183033 2025-08-28T18:30:34.876Z,1756405834.876 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003323 2025-08-28T18:30:42.855Z,1756405842.855 [DataOverHttps](INFO): Received command: restart logs