2025-06-09T01:13:15.961Z,1749431595.961 [CommandExec](IMPORTANT): got command restart logs 2025-06-09T01:13:16.345Z,1749431596.345 [DAT](INFO): Requesting device enable set for address 50. 2025-06-09T01:13:16.409Z,1749431596.409 [DockingStepper](INFO): Resume 2025-06-09T01:13:16.599Z,1749431596.599 [DAT](INFO): Requesting device enable set for address 50. 2025-06-09T01:13:16.737Z,1749431596.737 [DockingStepper](INFO): Resuming 2025-06-09T01:13:16.849Z,1749431596.849 [DAT](INFO): Requesting device enable set for address 50. 2025-06-09T01:13:17.084Z,1749431597.084 [DockingStepper](INFO): Start 2025-06-09T01:13:17.085Z,1749431597.085 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-06-09T01:13:17.205Z,1749431597.205 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-06-09T01:13:17.476Z,1749431597.476 [DockingStepper](INFO): Starting 2025-06-09T01:13:17.569Z,1749431597.569 [CTD_Seabird](ERROR): Failed to parse device response: 2025-06-09T01:13:17.857Z,1749431597.857 [DAT](INFO): DAT read: Rx Time:01:13:17.8400 2025-06-09T01:13:17.857Z,1749431597.857 [DAT](INFO): Rx dataTimestamp_ set to:1749431597.857021 2025-06-09T01:13:18.001Z,1749431598.001 [DockingStepper](INFO): Starting 2025-06-09T01:13:18.291Z,1749431598.291 [DockingStepper](INFO): Starting 2025-06-09T01:13:18.344Z,1749431598.344 [DockingStepper](INFO): Init string: /1z16335aE13561N3m50 2025-06-09T01:13:18.366Z,1749431598.366 [DAT](INFO): DAT read: Range 10 to 50 : 2.1 m (Round-trip 2.8 ms) speed 0.0 m/s 2025-06-09T01:13:18.367Z,1749431598.367 [DAT](INFO): DAT read: user:1054> 2025-06-09T01:13:18.368Z,1749431598.368 [DAT](INFO): DAT read: Tx time:01:13:18.9331 2025-06-09T01:13:18.369Z,1749431598.369 [DAT](INFO): Ping request sent. 2025-06-09T01:13:18.370Z,1749431598.370 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:13:18.371Z,1749431598.371 [DAT](ERROR): #Rx 24: Read range message, but no direction. 2025-06-09T01:13:18.371Z,1749431598.371 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:18.372Z,1749431598.372 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:18.616Z,1749431598.616 [DAT](INFO): DAT read: TxSync time:01:13:18.9323 2025-06-09T01:13:18.752Z,1749431598.752 [DockingStepper](INFO): Starting 2025-06-09T01:13:19.102Z,1749431599.102 [DockingStepper](INFO): Starting 2025-06-09T01:13:19.528Z,1749431599.528 [DockingStepper](INFO): Starting 2025-06-09T01:13:19.949Z,1749431599.949 [DockingStepper](INFO): Starting 2025-06-09T01:13:19.976Z,1749431599.976 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@ 2025-06-09T01:13:20.445Z,1749431600.445 [DockingStepper](INFO): Starting 2025-06-09T01:13:20.806Z,1749431600.806 [DockingStepper](INFO): Changing to mode: 1 2025-06-09T01:13:20.806Z,1749431600.806 [DockingStepper](INFO): Standby mode. 2025-06-09T01:13:21.133Z,1749431601.133 [DAT](INFO): DAT read: Rx Time:01:13:21.3399 2025-06-09T01:13:21.134Z,1749431601.134 [DAT](INFO): Rx dataTimestamp_ set to:1749431601.133646 2025-06-09T01:13:21.890Z,1749431601.890 [DAT](INFO): DAT read: Range 10 to 50 : 2.0 m (Round-trip 2.7 ms) speed 0.0 m/s 2025-06-09T01:13:21.891Z,1749431601.891 [DAT](INFO): DAT read: user:1055> 2025-06-09T01:13:21.892Z,1749431601.892 [DAT](INFO): DAT read: Tx time:01:13:22.4331 2025-06-09T01:13:21.892Z,1749431601.892 [DAT](INFO): Ping request sent. 2025-06-09T01:13:21.892Z,1749431601.892 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:13:21.893Z,1749431601.893 [DAT](INFO): DAT read: TxSync time:01:13:22.4324 2025-06-09T01:13:21.893Z,1749431601.893 [DAT](ERROR): #Rx 25: Read range message, but no direction. 2025-06-09T01:13:21.894Z,1749431601.894 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:21.894Z,1749431601.894 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:24.661Z,1749431604.661 [DAT](INFO): DAT read: Rx Time:01:13:24.8401 2025-06-09T01:13:24.662Z,1749431604.662 [DAT](INFO): Rx dataTimestamp_ set to:1749431604.661276 2025-06-09T01:13:25.170Z,1749431605.170 [DAT](INFO): DAT read: Range 10 to 50 : 2.1 m (Round-trip 2.8 ms) speed 0.0 m/s 2025-06-09T01:13:25.170Z,1749431605.170 [DAT](INFO): DAT read: user:1056> 2025-06-09T01:13:25.171Z,1749431605.171 [DAT](ERROR): #Rx 26: Read range message, but no direction. 2025-06-09T01:13:25.421Z,1749431605.421 [DAT](INFO): DAT read: Tx time:01:13:25.9332 2025-06-09T01:13:25.422Z,1749431605.422 [DAT](INFO): Ping request sent. 2025-06-09T01:13:25.422Z,1749431605.422 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:13:25.423Z,1749431605.423 [DAT](INFO): DAT read: TxSync time:01:13:25.9324 2025-06-09T01:13:25.423Z,1749431605.423 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:28.193Z,1749431608.193 [DAT](INFO): DAT read: Rx Time:01:13:28.3401 2025-06-09T01:13:28.193Z,1749431608.193 [DAT](INFO): Rx dataTimestamp_ set to:1749431608.193066 2025-06-09T01:13:28.698Z,1749431608.698 [DAT](INFO): DAT read: Range 10 to 50 : 2.1 m (Round-trip 2.8 ms) speed 0.0 m/s 2025-06-09T01:13:28.699Z,1749431608.699 [DAT](INFO): DAT read: user:1057> 2025-06-09T01:13:28.699Z,1749431608.699 [DAT](INFO): DAT read: Tx time:01:13:29.4332 2025-06-09T01:13:28.700Z,1749431608.700 [DAT](INFO): Ping request sent. 2025-06-09T01:13:28.700Z,1749431608.700 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:13:28.700Z,1749431608.700 [DAT](ERROR): #Rx 27: Read range message, but no direction. 2025-06-09T01:13:28.700Z,1749431608.700 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:28.701Z,1749431608.701 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:28.949Z,1749431608.949 [DAT](INFO): DAT read: TxSync time:01:13:29.4324 2025-06-09T01:13:30.844Z,1749431610.844 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,25060817145023,35.0, +10.6, 0.0,149 2025-06-09T01:13:31.721Z,1749431611.721 [DAT](INFO): DAT read: Rx Time:01:13:31.8400 2025-06-09T01:13:31.721Z,1749431611.721 [DAT](INFO): Rx dataTimestamp_ set to:1749431611.721162 2025-06-09T01:13:32.226Z,1749431612.226 [DAT](INFO): DAT read: Range 10 to 50 : 2.0 m (Round-trip 2.7 ms) speed 0.0 m/s 2025-06-09T01:13:32.226Z,1749431612.226 [DAT](INFO): DAT read: user:1058> 2025-06-09T01:13:32.227Z,1749431612.227 [DAT](INFO): DAT read: Tx time:01:13:32.9332 2025-06-09T01:13:32.227Z,1749431612.227 [DAT](INFO): Ping request sent. 2025-06-09T01:13:32.227Z,1749431612.227 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:13:32.228Z,1749431612.228 [DAT](ERROR): #Rx 28: Read range message, but no direction. 2025-06-09T01:13:32.228Z,1749431612.228 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:32.229Z,1749431612.229 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:32.477Z,1749431612.477 [DAT](INFO): DAT read: TxSync time:01:13:32.9324 2025-06-09T01:13:35.249Z,1749431615.249 [DAT](INFO): DAT read: Rx Time:01:13:35.3405 2025-06-09T01:13:35.249Z,1749431615.249 [DAT](INFO): Rx dataTimestamp_ set to:1749431615.249086 2025-06-09T01:13:35.758Z,1749431615.758 [DAT](INFO): DAT read: Range 10 to 50 : 2.3 m (Round-trip 3.1 ms) speed 0.0 m/s 2025-06-09T01:13:35.759Z,1749431615.759 [DAT](INFO): DAT read: user:1059> 2025-06-09T01:13:35.761Z,1749431615.761 [DAT](INFO): DAT read: Tx time:01:13:36.4333 2025-06-09T01:13:35.761Z,1749431615.761 [DAT](INFO): Ping request sent. 2025-06-09T01:13:35.761Z,1749431615.761 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:13:35.762Z,1749431615.762 [DAT](ERROR): #Rx 29: Read range message, but no direction. 2025-06-09T01:13:35.762Z,1749431615.762 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:35.763Z,1749431615.763 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:36.009Z,1749431616.009 [DAT](INFO): DAT read: TxSync time:01:13:36.4325 2025-06-09T01:13:38.785Z,1749431618.785 [DAT](INFO): DAT read: Rx Time:01:13:38.8404 2025-06-09T01:13:38.785Z,1749431618.785 [DAT](INFO): Rx dataTimestamp_ set to:1749431618.784969 2025-06-09T01:13:39.290Z,1749431619.290 [DAT](INFO): DAT read: Range 10 to 50 : 2.2 m (Round-trip 3.0 ms) speed 0.0 m/s 2025-06-09T01:13:39.290Z,1749431619.290 [DAT](INFO): DAT read: user:1060> 2025-06-09T01:13:39.291Z,1749431619.291 [DAT](INFO): DAT read: Tx time:01:13:39.9333 2025-06-09T01:13:39.292Z,1749431619.292 [DAT](INFO): Ping request sent. 2025-06-09T01:13:39.292Z,1749431619.292 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:13:39.292Z,1749431619.292 [DAT](ERROR): #Rx 30: Read range message, but no direction. 2025-06-09T01:13:39.292Z,1749431619.292 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:39.293Z,1749431619.293 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:39.541Z,1749431619.541 [DAT](INFO): DAT read: TxSync time:01:13:39.9325 2025-06-09T01:13:42.313Z,1749431622.313 [DAT](INFO): DAT read: Rx Time:01:13:42.3401 2025-06-09T01:13:42.313Z,1749431622.313 [DAT](INFO): Rx dataTimestamp_ set to:1749431622.312997 2025-06-09T01:13:42.818Z,1749431622.818 [DAT](INFO): DAT read: Range 10 to 50 : 2.0 m (Round-trip 2.7 ms) speed 0.0 m/s 2025-06-09T01:13:42.818Z,1749431622.818 [DAT](INFO): DAT read: user:1061> 2025-06-09T01:13:42.819Z,1749431622.819 [DAT](INFO): DAT read: Tx time:01:13:43.4333 2025-06-09T01:13:42.819Z,1749431622.819 [DAT](INFO): Ping request sent. 2025-06-09T01:13:42.820Z,1749431622.820 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:13:42.820Z,1749431622.820 [DAT](ERROR): #Rx 31: Read range message, but no direction. 2025-06-09T01:13:42.820Z,1749431622.820 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:42.821Z,1749431622.821 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:43.069Z,1749431623.069 [DAT](INFO): DAT read: TxSync time:01:13:43.4325 2025-06-09T01:13:45.845Z,1749431625.845 [DAT](INFO): DAT read: Rx Time:01:13:45.8403 2025-06-09T01:13:45.845Z,1749431625.845 [DAT](INFO): Rx dataTimestamp_ set to:1749431625.845037 2025-06-09T01:13:46.350Z,1749431626.350 [DAT](INFO): DAT read: Range 10 to 50 : 2.1 m (Round-trip 2.8 ms) speed 0.0 m/s 2025-06-09T01:13:46.351Z,1749431626.351 [DAT](INFO): DAT read: user:1062> 2025-06-09T01:13:46.351Z,1749431626.351 [DAT](INFO): DAT read: Tx time:01:13:46.9333 2025-06-09T01:13:46.352Z,1749431626.352 [DAT](INFO): Ping request sent. 2025-06-09T01:13:46.352Z,1749431626.352 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:13:46.353Z,1749431626.353 [DAT](ERROR): #Rx 32: Read range message, but no direction. 2025-06-09T01:13:46.353Z,1749431626.353 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:46.353Z,1749431626.353 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:46.601Z,1749431626.601 [DAT](INFO): DAT read: TxSync time:01:13:46.9326 2025-06-09T01:13:49.375Z,1749431629.375 [DAT](INFO): DAT read: Rx Time:01:13:49.3402 2025-06-09T01:13:49.376Z,1749431629.376 [DAT](INFO): Rx dataTimestamp_ set to:1749431629.375724 2025-06-09T01:13:49.882Z,1749431629.882 [DAT](INFO): DAT read: Range 10 to 50 : 2.0 m (Round-trip 2.7 ms) speed 0.0 m/s 2025-06-09T01:13:49.883Z,1749431629.883 [DAT](INFO): DAT read: user:1063> 2025-06-09T01:13:49.883Z,1749431629.883 [DAT](INFO): DAT read: Tx time:01:13:50.4333 2025-06-09T01:13:49.884Z,1749431629.884 [DAT](INFO): Ping request sent. 2025-06-09T01:13:49.884Z,1749431629.884 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:13:49.884Z,1749431629.884 [DAT](INFO): DAT read: TxSync time:01:13:50.4326 2025-06-09T01:13:49.885Z,1749431629.885 [DAT](ERROR): #Rx 33: Read range message, but no direction. 2025-06-09T01:13:49.885Z,1749431629.885 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:49.886Z,1749431629.886 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:52.653Z,1749431632.653 [DAT](INFO): DAT read: Rx Time:01:13:52.8403 2025-06-09T01:13:52.654Z,1749431632.654 [DAT](INFO): Rx dataTimestamp_ set to:1749431632.653616 2025-06-09T01:13:53.158Z,1749431633.158 [DAT](INFO): DAT read: Range 10 to 50 : 2.1 m (Round-trip 2.8 ms) speed 0.0 m/s 2025-06-09T01:13:53.158Z,1749431633.158 [DAT](INFO): DAT read: user:1064> 2025-06-09T01:13:53.159Z,1749431633.159 [DAT](ERROR): #Rx 34: Read range message, but no direction. 2025-06-09T01:13:53.413Z,1749431633.413 [DAT](INFO): DAT read: Tx time:01:13:53.9333 2025-06-09T01:13:53.414Z,1749431633.414 [DAT](INFO): Ping request sent. 2025-06-09T01:13:53.414Z,1749431633.414 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:13:53.415Z,1749431633.415 [DAT](INFO): DAT read: TxSync time:01:13:53.9326 2025-06-09T01:13:53.415Z,1749431633.415 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:56.183Z,1749431636.183 [DAT](INFO): DAT read: Rx Time:01:13:56.3405 2025-06-09T01:13:56.185Z,1749431636.185 [DAT](INFO): Rx dataTimestamp_ set to:1749431636.183988 2025-06-09T01:13:56.686Z,1749431636.686 [DAT](INFO): DAT read: Range 10 to 50 : 2.2 m (Round-trip 3.0 ms) speed 0.0 m/s 2025-06-09T01:13:56.686Z,1749431636.686 [DAT](INFO): DAT read: user:1065> 2025-06-09T01:13:56.687Z,1749431636.687 [DAT](INFO): DAT read: Tx time:01:13:57.4333 2025-06-09T01:13:56.688Z,1749431636.688 [DAT](INFO): Ping request sent. 2025-06-09T01:13:56.688Z,1749431636.688 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:13:56.688Z,1749431636.688 [DAT](ERROR): #Rx 35: Read range message, but no direction. 2025-06-09T01:13:56.688Z,1749431636.688 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:56.689Z,1749431636.689 [DAT](INFO): publishing transmit ping time 2025-06-09T01:13:56.937Z,1749431636.937 [DAT](INFO): DAT read: TxSync time:01:13:57.4327 2025-06-09T01:13:59.709Z,1749431639.709 [DAT](INFO): DAT read: Rx Time:01:13:59.8403 2025-06-09T01:13:59.709Z,1749431639.709 [DAT](INFO): Rx dataTimestamp_ set to:1749431639.709116 2025-06-09T01:14:00.218Z,1749431640.218 [DAT](INFO): DAT read: Range 10 to 50 : 2.0 m (Round-trip 2.7 ms) speed 0.0 m/s 2025-06-09T01:14:00.220Z,1749431640.220 [DAT](INFO): DAT read: user:1066> 2025-06-09T01:14:00.223Z,1749431640.223 [DAT](INFO): DAT read: Tx time:01:14:00.9334 2025-06-09T01:14:00.224Z,1749431640.224 [DAT](INFO): Ping request sent. 2025-06-09T01:14:00.224Z,1749431640.224 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:00.225Z,1749431640.225 [DAT](ERROR): #Rx 36: Read range message, but no direction. 2025-06-09T01:14:00.226Z,1749431640.226 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:00.229Z,1749431640.229 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:00.466Z,1749431640.466 [DAT](INFO): DAT read: TxSync time:01:14:00.9327 2025-06-09T01:14:03.243Z,1749431643.243 [DAT](INFO): DAT read: Rx Time:01:14:03.3404 2025-06-09T01:14:03.245Z,1749431643.245 [DAT](INFO): Rx dataTimestamp_ set to:1749431643.243753 2025-06-09T01:14:03.746Z,1749431643.746 [DAT](INFO): DAT read: Range 10 to 50 : 2.1 m (Round-trip 2.8 ms) speed -0.1 m/s 2025-06-09T01:14:03.746Z,1749431643.746 [DAT](INFO): DAT read: user:1067> 2025-06-09T01:14:03.747Z,1749431643.747 [DAT](INFO): DAT read: Tx time:01:14:04.4334 2025-06-09T01:14:03.747Z,1749431643.747 [DAT](INFO): Ping request sent. 2025-06-09T01:14:03.748Z,1749431643.748 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:03.748Z,1749431643.748 [DAT](ERROR): #Rx 37: Read range message, but no direction. 2025-06-09T01:14:03.748Z,1749431643.748 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:03.749Z,1749431643.749 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:03.997Z,1749431643.997 [DAT](INFO): DAT read: TxSync time:01:14:04.4327 2025-06-09T01:14:06.769Z,1749431646.769 [DAT](INFO): DAT read: Rx Time:01:14:06.8403 2025-06-09T01:14:06.769Z,1749431646.769 [DAT](INFO): Rx dataTimestamp_ set to:1749431646.768943 2025-06-09T01:14:07.274Z,1749431647.274 [DAT](INFO): DAT read: Range 10 to 50 : 2.0 m (Round-trip 2.7 ms) speed 0.0 m/s 2025-06-09T01:14:07.274Z,1749431647.274 [DAT](INFO): DAT read: user:1068> 2025-06-09T01:14:07.276Z,1749431647.276 [DAT](INFO): DAT read: Tx time:01:14:07.9334 2025-06-09T01:14:07.277Z,1749431647.277 [DAT](INFO): Ping request sent. 2025-06-09T01:14:07.278Z,1749431647.278 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:07.279Z,1749431647.279 [DAT](ERROR): #Rx 38: Read range message, but no direction. 2025-06-09T01:14:07.279Z,1749431647.279 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:07.281Z,1749431647.281 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:07.525Z,1749431647.525 [DAT](INFO): DAT read: TxSync time:01:14:07.9327 2025-06-09T01:14:10.297Z,1749431650.297 [DAT](INFO): DAT read: Rx Time:01:14:10.3403 2025-06-09T01:14:10.297Z,1749431650.297 [DAT](INFO): Rx dataTimestamp_ set to:1749431650.296948 2025-06-09T01:14:10.804Z,1749431650.804 [DAT](INFO): DAT read: Range 10 to 50 : 2.0 m (Round-trip 2.7 ms) speed 0.0 m/s 2025-06-09T01:14:10.805Z,1749431650.805 [DAT](INFO): DAT read: user:1069> 2025-06-09T01:14:10.805Z,1749431650.805 [DAT](INFO): DAT read: Tx time:01:14:11.4334 2025-06-09T01:14:10.806Z,1749431650.806 [DAT](INFO): Ping request sent. 2025-06-09T01:14:10.806Z,1749431650.806 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:10.806Z,1749431650.806 [DAT](ERROR): #Rx 39: Read range message, but no direction. 2025-06-09T01:14:10.806Z,1749431650.806 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:10.807Z,1749431650.807 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:11.053Z,1749431651.053 [DAT](INFO): DAT read: TxSync time:01:14:11.4327 2025-06-09T01:14:13.825Z,1749431653.825 [DAT](INFO): DAT read: Rx Time:01:14:13.8405 2025-06-09T01:14:13.825Z,1749431653.825 [DAT](INFO): Rx dataTimestamp_ set to:1749431653.824929 2025-06-09T01:14:14.333Z,1749431654.333 [DAT](INFO): DAT read: Range 10 to 50 : 2.1 m (Round-trip 2.8 ms) speed 0.0 m/s 2025-06-09T01:14:14.334Z,1749431654.334 [DAT](INFO): DAT read: user:1070> 2025-06-09T01:14:14.335Z,1749431654.335 [DAT](INFO): DAT read: Tx time:01:14:14.9335 2025-06-09T01:14:14.335Z,1749431654.335 [DAT](INFO): Ping request sent. 2025-06-09T01:14:14.335Z,1749431654.335 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:14.336Z,1749431654.336 [DAT](ERROR): #Rx 40: Read range message, but no direction. 2025-06-09T01:14:14.336Z,1749431654.336 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:14.337Z,1749431654.337 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:14.581Z,1749431654.581 [DAT](INFO): DAT read: TxSync time:01:14:14.9328 2025-06-09T01:14:17.353Z,1749431657.353 [DAT](INFO): DAT read: Rx Time:01:14:17.3411 2025-06-09T01:14:17.353Z,1749431657.353 [DAT](INFO): Rx dataTimestamp_ set to:1749431657.353008 2025-06-09T01:14:17.858Z,1749431657.858 [DAT](INFO): DAT read: Range 10 to 50 : 2.5 m (Round-trip 3.4 ms) speed 0.0 m/s 2025-06-09T01:14:17.858Z,1749431657.858 [DAT](INFO): DAT read: user:1071> 2025-06-09T01:14:17.859Z,1749431657.859 [DAT](INFO): DAT read: Tx time:01:14:18.4335 2025-06-09T01:14:17.859Z,1749431657.859 [DAT](INFO): Ping request sent. 2025-06-09T01:14:17.859Z,1749431657.859 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:17.860Z,1749431657.860 [DAT](ERROR): #Rx 41: Read range message, but no direction. 2025-06-09T01:14:17.860Z,1749431657.860 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:17.861Z,1749431657.861 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:18.116Z,1749431658.116 [DAT](INFO): DAT read: TxSync time:01:14:18.4328 2025-06-09T01:14:20.637Z,1749431660.637 [DAT](INFO): DAT read: Rx Time:01:14:20.8411 2025-06-09T01:14:20.637Z,1749431660.637 [DAT](INFO): Rx dataTimestamp_ set to:1749431660.637030 2025-06-09T01:14:21.395Z,1749431661.395 [DAT](INFO): DAT read: Range 10 to 50 : 2.5 m (Round-trip 3.4 ms) speed 0.0 m/s 2025-06-09T01:14:21.396Z,1749431661.396 [DAT](INFO): DAT read: user:1072> 2025-06-09T01:14:21.397Z,1749431661.397 [DAT](INFO): DAT read: Tx time:01:14:21.9335 2025-06-09T01:14:21.397Z,1749431661.397 [DAT](INFO): Ping request sent. 2025-06-09T01:14:21.397Z,1749431661.397 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:21.398Z,1749431661.398 [DAT](INFO): DAT read: TxSync time:01:14:21.9328 2025-06-09T01:14:21.399Z,1749431661.399 [DAT](ERROR): #Rx 42: Read range message, but no direction. 2025-06-09T01:14:21.399Z,1749431661.399 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:21.400Z,1749431661.400 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:24.165Z,1749431664.165 [DAT](INFO): DAT read: Rx Time:01:14:24.3405 2025-06-09T01:14:24.166Z,1749431664.166 [DAT](INFO): Rx dataTimestamp_ set to:1749431664.165645 2025-06-09T01:14:24.670Z,1749431664.670 [DAT](INFO): DAT read: Range 10 to 50 : 2.0 m (Round-trip 2.7 ms) speed 0.0 m/s 2025-06-09T01:14:24.670Z,1749431664.670 [DAT](INFO): DAT read: user:1073> 2025-06-09T01:14:24.671Z,1749431664.671 [DAT](ERROR): #Rx 43: Read range message, but no direction. 2025-06-09T01:14:24.921Z,1749431664.921 [DAT](INFO): DAT read: Tx time:01:14:25.4336 2025-06-09T01:14:24.921Z,1749431664.921 [DAT](INFO): Ping request sent. 2025-06-09T01:14:24.921Z,1749431664.921 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:24.922Z,1749431664.922 [DAT](INFO): DAT read: TxSync time:01:14:25.4329 2025-06-09T01:14:24.922Z,1749431664.922 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:27.693Z,1749431667.693 [DAT](INFO): DAT read: Rx Time:01:14:27.8405 2025-06-09T01:14:27.693Z,1749431667.693 [DAT](INFO): Rx dataTimestamp_ set to:1749431667.692953 2025-06-09T01:14:28.199Z,1749431668.199 [DAT](INFO): DAT read: Range 10 to 50 : 2.0 m (Round-trip 2.7 ms) speed 0.0 m/s 2025-06-09T01:14:28.200Z,1749431668.200 [DAT](INFO): DAT read: user:1074> 2025-06-09T01:14:28.201Z,1749431668.201 [DAT](INFO): DAT read: Tx time:01:14:28.9336 2025-06-09T01:14:28.201Z,1749431668.201 [DAT](INFO): Ping request sent. 2025-06-09T01:14:28.201Z,1749431668.201 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:28.202Z,1749431668.202 [DAT](ERROR): #Rx 44: Read range message, but no direction. 2025-06-09T01:14:28.202Z,1749431668.202 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:28.203Z,1749431668.203 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:28.449Z,1749431668.449 [DAT](INFO): DAT read: TxSync time:01:14:28.9329 2025-06-09T01:14:31.221Z,1749431671.221 [DAT](INFO): DAT read: Rx Time:01:14:31.3406 2025-06-09T01:14:31.221Z,1749431671.221 [DAT](INFO): Rx dataTimestamp_ set to:1749431671.221181 2025-06-09T01:14:31.726Z,1749431671.726 [DAT](INFO): DAT read: Range 10 to 50 : 2.1 m (Round-trip 2.8 ms) speed 0.0 m/s 2025-06-09T01:14:31.727Z,1749431671.727 [DAT](INFO): DAT read: user:1075> 2025-06-09T01:14:31.727Z,1749431671.727 [DAT](INFO): DAT read: Tx time:01:14:32.4336 2025-06-09T01:14:31.728Z,1749431671.728 [DAT](INFO): Ping request sent. 2025-06-09T01:14:31.728Z,1749431671.728 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:31.728Z,1749431671.728 [DAT](ERROR): #Rx 45: Read range message, but no direction. 2025-06-09T01:14:31.728Z,1749431671.728 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:31.729Z,1749431671.729 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:31.977Z,1749431671.977 [DAT](INFO): DAT read: TxSync time:01:14:32.4329 2025-06-09T01:14:34.755Z,1749431674.755 [DAT](INFO): DAT read: Rx Time:01:14:34.8406 2025-06-09T01:14:34.757Z,1749431674.757 [DAT](INFO): Rx dataTimestamp_ set to:1749431674.755917 2025-06-09T01:14:35.258Z,1749431675.258 [DAT](INFO): DAT read: Range 10 to 50 : 2.0 m (Round-trip 2.7 ms) speed 0.0 m/s 2025-06-09T01:14:35.258Z,1749431675.258 [DAT](INFO): DAT read: user:1076> 2025-06-09T01:14:35.259Z,1749431675.259 [DAT](INFO): DAT read: Tx time:01:14:35.9337 2025-06-09T01:14:35.259Z,1749431675.259 [DAT](INFO): Ping request sent. 2025-06-09T01:14:35.259Z,1749431675.259 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:35.260Z,1749431675.260 [DAT](ERROR): #Rx 46: Read range message, but no direction. 2025-06-09T01:14:35.260Z,1749431675.260 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:35.261Z,1749431675.261 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:35.517Z,1749431675.517 [DAT](INFO): DAT read: TxSync time:01:14:35.9330 2025-06-09T01:14:38.285Z,1749431678.285 [DAT](INFO): DAT read: Rx Time:01:14:38.3413 2025-06-09T01:14:38.285Z,1749431678.285 [DAT](INFO): Rx dataTimestamp_ set to:1749431678.285062 2025-06-09T01:14:38.794Z,1749431678.794 [DAT](INFO): DAT read: Range 10 to 50 : 2.5 m (Round-trip 3.4 ms) speed 0.0 m/s 2025-06-09T01:14:38.799Z,1749431678.799 [DAT](INFO): DAT read: user:1077> 2025-06-09T01:14:38.814Z,1749431678.814 [DAT](INFO): DAT read: Tx time:01:14:39.4337 2025-06-09T01:14:38.815Z,1749431678.815 [DAT](INFO): Ping request sent. 2025-06-09T01:14:38.815Z,1749431678.815 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:38.848Z,1749431678.848 [DAT](ERROR): #Rx 47: Read range message, but no direction. 2025-06-09T01:14:38.848Z,1749431678.848 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:38.849Z,1749431678.849 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:39.041Z,1749431679.041 [DAT](INFO): DAT read: TxSync time:01:14:39.4330 2025-06-09T01:14:41.087Z,1749431681.087 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,-3268,-32768,V 2025-06-09T01:14:41.825Z,1749431681.825 [DAT](INFO): DAT read: Rx Time:01:14:41.8409 2025-06-09T01:14:41.827Z,1749431681.827 [DAT](INFO): Rx dataTimestamp_ set to:1749431681.825901 2025-06-09T01:14:42.329Z,1749431682.329 [DAT](INFO): DAT read: Range 10 to 50 : 2.2 m (Round-trip 3.0 ms) speed 0.0 m/s 2025-06-09T01:14:42.330Z,1749431682.330 [DAT](INFO): DAT read: user:1078> 2025-06-09T01:14:42.331Z,1749431682.331 [DAT](INFO): DAT read: Tx time:01:14:42.9337 2025-06-09T01:14:42.331Z,1749431682.331 [DAT](INFO): Ping request sent. 2025-06-09T01:14:42.331Z,1749431682.331 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:42.331Z,1749431682.331 [DAT](ERROR): #Rx 48: Read range message, but no direction. 2025-06-09T01:14:42.332Z,1749431682.332 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:42.333Z,1749431682.333 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:42.577Z,1749431682.577 [DAT](INFO): DAT read: TxSync time:01:14:42.9330 2025-06-09T01:14:45.365Z,1749431685.365 [DAT](INFO): DAT read: Rx Time:01:14:45.3407 2025-06-09T01:14:45.366Z,1749431685.366 [DAT](INFO): Rx dataTimestamp_ set to:1749431685.365543 2025-06-09T01:14:45.875Z,1749431685.875 [DAT](INFO): DAT read: Range 10 to 50 : 2.0 m (Round-trip 2.7 ms) speed 0.0 m/s 2025-06-09T01:14:45.878Z,1749431685.878 [DAT](INFO): DAT read: user:1079> 2025-06-09T01:14:45.881Z,1749431685.881 [DAT](INFO): DAT read: Tx time:01:14:46.4338 2025-06-09T01:14:45.882Z,1749431685.882 [DAT](INFO): Ping request sent. 2025-06-09T01:14:45.882Z,1749431685.882 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:45.885Z,1749431685.885 [DAT](INFO): DAT read: TxSync time:01:14:46.4331 2025-06-09T01:14:45.886Z,1749431685.886 [DAT](ERROR): #Rx 49: Read range message, but no direction. 2025-06-09T01:14:45.887Z,1749431685.887 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:45.889Z,1749431685.889 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:48.641Z,1749431688.641 [DAT](INFO): DAT read: Rx Time:01:14:48.8408 2025-06-09T01:14:48.641Z,1749431688.641 [DAT](INFO): Rx dataTimestamp_ set to:1749431688.641030 2025-06-09T01:14:49.149Z,1749431689.149 [DAT](INFO): DAT read: Range 10 to 50 : 2.1 m (Round-trip 2.8 ms) speed 0.0 m/s 2025-06-09T01:14:49.150Z,1749431689.150 [DAT](INFO): DAT read: user:1080> 2025-06-09T01:14:49.150Z,1749431689.150 [DAT](ERROR): #Rx 50: Read range message, but no direction. 2025-06-09T01:14:49.401Z,1749431689.401 [DAT](INFO): DAT read: Tx time:01:14:49.9338 2025-06-09T01:14:49.402Z,1749431689.402 [DAT](INFO): Ping request sent. 2025-06-09T01:14:49.402Z,1749431689.402 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:49.403Z,1749431689.403 [DAT](INFO): DAT read: TxSync time:01:14:49.9331 2025-06-09T01:14:49.403Z,1749431689.403 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:52.173Z,1749431692.173 [DAT](INFO): DAT read: Rx Time:01:14:52.3407 2025-06-09T01:14:52.173Z,1749431692.173 [DAT](INFO): Rx dataTimestamp_ set to:1749431692.173035 2025-06-09T01:14:52.678Z,1749431692.678 [DAT](INFO): DAT read: Range 10 to 50 : 2.0 m (Round-trip 2.7 ms) speed 0.0 m/s 2025-06-09T01:14:52.679Z,1749431692.679 [DAT](INFO): DAT read: user:1081> 2025-06-09T01:14:52.680Z,1749431692.680 [DAT](INFO): DAT read: Tx time:01:14:53.4338 2025-06-09T01:14:52.680Z,1749431692.680 [DAT](INFO): Ping request sent. 2025-06-09T01:14:52.680Z,1749431692.680 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:52.681Z,1749431692.681 [DAT](ERROR): #Rx 51: Read range message, but no direction. 2025-06-09T01:14:52.681Z,1749431692.681 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:52.681Z,1749431692.681 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:52.929Z,1749431692.929 [DAT](INFO): DAT read: TxSync time:01:14:53.4331 2025-06-09T01:14:55.705Z,1749431695.705 [DAT](INFO): DAT read: Rx Time:01:14:55.8411 2025-06-09T01:14:55.705Z,1749431695.705 [DAT](INFO): Rx dataTimestamp_ set to:1749431695.704994 2025-06-09T01:14:56.210Z,1749431696.210 [DAT](INFO): DAT read: Range 10 to 50 : 2.3 m (Round-trip 3.1 ms) speed 0.0 m/s 2025-06-09T01:14:56.211Z,1749431696.211 [DAT](INFO): DAT read: user:1082> 2025-06-09T01:14:56.211Z,1749431696.211 [DAT](INFO): DAT read: Tx time:01:14:56.9339 2025-06-09T01:14:56.212Z,1749431696.212 [DAT](INFO): Ping request sent. 2025-06-09T01:14:56.212Z,1749431696.212 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:56.212Z,1749431696.212 [DAT](ERROR): #Rx 52: Read range message, but no direction. 2025-06-09T01:14:56.212Z,1749431696.212 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:56.213Z,1749431696.213 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:56.461Z,1749431696.461 [DAT](INFO): DAT read: TxSync time:01:14:56.9332 2025-06-09T01:14:59.237Z,1749431699.237 [DAT](INFO): DAT read: Rx Time:01:14:59.3408 2025-06-09T01:14:59.237Z,1749431699.237 [DAT](INFO): Rx dataTimestamp_ set to:1749431699.237097 2025-06-09T01:14:59.749Z,1749431699.749 [DAT](INFO): DAT read: 01:14:59.3408 LVL= 9200, 19665, 13858, 13507, AGC= 45, IDX= 440, 0.07,-3.076,-1.342,-2.070,-0.690, PHS=-2.299,-0.605,-1.383, RAW= 297.3, 45.9, CAL= 306.0, 46.6, ROT= 204.0, -46.6 2025-06-09T01:14:59.750Z,1749431699.750 [DAT](INFO): got valid direction response: 01:14:59.3408 LVL= 9200, 19665, 13858, 13507, AGC= 45, IDX= 440, 0.07,-3.076,-1.342,-2.070,-0.690, PHS=-2.299,-0.605,-1.383, RAW= 297.3, 45.9, CAL= 306.0, 46.6, ROT= 204.0, -46.6 2025-06-09T01:14:59.751Z,1749431699.751 [DAT](INFO): DAT read: Bearing 204.0, -46.6 (Local) 2025-06-09T01:14:59.752Z,1749431699.752 [DAT](INFO): Local bearing/azimuth received: Bearing 204.0, -46.6 (Local) 2025-06-09T01:14:59.754Z,1749431699.754 [DAT](INFO): DAT read: Range 10 to 50 : 2.0 m (Round-trip 2.7 ms) speed 0.0 m/s 2025-06-09T01:14:59.755Z,1749431699.755 [DAT](INFO): DAT read: user:1083> 2025-06-09T01:14:59.756Z,1749431699.756 [DAT](INFO): DAT read: Tx time:01:15:00.4330 2025-06-09T01:14:59.756Z,1749431699.756 [DAT](INFO): Ping request sent. 2025-06-09T01:14:59.756Z,1749431699.756 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:14:59.757Z,1749431699.757 [DAT](INFO): #Rx 53: Read range and direction messages. 2025-06-09T01:14:59.758Z,1749431699.758 [DAT](INFO): direction in FSK: [-0.627686,-0.279464,0.726575] 2025-06-09T01:14:59.759Z,1749431699.759 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:59.759Z,1749431699.759 [DAT](INFO): publishing direction and range info 2025-06-09T01:14:59.763Z,1749431699.763 [DAT](INFO): publishing transmit ping time 2025-06-09T01:14:59.768Z,1749431699.768 [DAT](INFO): publishing direction and range info 2025-06-09T01:15:02.261Z,1749431702.261 [DAT](INFO): DAT read: Rx Time:01:15:02.3097 2025-06-09T01:15:02.261Z,1749431702.261 [DAT](INFO): Rx dataTimestamp_ set to:1749431702.261007 2025-06-09T01:15:02.765Z,1749431702.765 [DAT](INFO): DAT read: OK 2025-06-09T01:15:02.765Z,1749431702.765 [DAT](INFO): unknown deviceResponse_: OK 2025-06-09T01:15:02.765Z,1749431702.765 [DAT](INFO): DAT read: user:1084> 2025-06-09T01:15:02.766Z,1749431702.766 [DAT](INFO): DAT read: Tx time:01:15:03.3830 2025-06-09T01:15:02.766Z,1749431702.766 [DAT](INFO): Ping request sent. 2025-06-09T01:15:02.766Z,1749431702.766 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:15:02.767Z,1749431702.767 [DAT](INFO): publishing transmit ping time 2025-06-09T01:15:05.033Z,1749431705.033 [DAT](INFO): DAT read: Rx Time:01:15:05.2601 2025-06-09T01:15:05.033Z,1749431705.033 [DAT](INFO): Rx dataTimestamp_ set to:1749431705.032875 2025-06-09T01:15:05.789Z,1749431705.789 [DAT](INFO): DAT read: OK 2025-06-09T01:15:05.789Z,1749431705.789 [DAT](INFO): unknown deviceResponse_: OK 2025-06-09T01:15:05.789Z,1749431705.789 [DAT](INFO): DAT read: user:1085> 2025-06-09T01:15:05.790Z,1749431705.790 [DAT](INFO): DAT read: Tx time:01:15:06.3330 2025-06-09T01:15:05.790Z,1749431705.790 [DAT](INFO): Ping request sent. 2025-06-09T01:15:05.790Z,1749431705.790 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:15:05.791Z,1749431705.791 [DAT](INFO): publishing transmit ping time 2025-06-09T01:15:08.065Z,1749431708.065 [DAT](INFO): DAT read: Rx Time:01:15:08.2099 2025-06-09T01:15:08.065Z,1749431708.065 [DAT](INFO): Rx dataTimestamp_ set to:1749431708.064942 2025-06-09T01:15:08.569Z,1749431708.569 [DAT](INFO): DAT read: OK 2025-06-09T01:15:08.569Z,1749431708.569 [DAT](INFO): unknown deviceResponse_: OK 2025-06-09T01:15:08.569Z,1749431708.569 [DAT](INFO): DAT read: user:1086> 2025-06-09T01:15:08.570Z,1749431708.570 [DAT](INFO): DAT read: Tx time:01:15:09.2831 2025-06-09T01:15:08.570Z,1749431708.570 [DAT](INFO): Ping request sent. 2025-06-09T01:15:08.570Z,1749431708.570 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:15:08.571Z,1749431708.571 [DAT](INFO): publishing transmit ping time 2025-06-09T01:15:11.089Z,1749431711.089 [DAT](INFO): DAT read: Rx Time:01:15:11.1600 2025-06-09T01:15:11.089Z,1749431711.089 [DAT](INFO): Rx dataTimestamp_ set to:1749431711.088965 2025-06-09T01:15:11.592Z,1749431711.592 [DAT](INFO): DAT read: OK 2025-06-09T01:15:11.593Z,1749431711.593 [DAT](INFO): unknown deviceResponse_: OK 2025-06-09T01:15:11.593Z,1749431711.593 [DAT](INFO): DAT read: user:1087> 2025-06-09T01:15:11.594Z,1749431711.594 [DAT](INFO): DAT read: Tx time:01:15:12.2331 2025-06-09T01:15:11.594Z,1749431711.594 [DAT](INFO): Ping request sent. 2025-06-09T01:15:11.594Z,1749431711.594 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:15:11.595Z,1749431711.595 [DAT](INFO): publishing transmit ping time 2025-06-09T01:15:13.829Z,1749431713.829 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-06-09T01:15:13.861Z,1749431713.861 [DAT](INFO): ****** received valid address query ****** 2025-06-09T01:15:13.861Z,1749431713.861 [DAT](INFO): ****** received valid ping request ****** 2025-06-09T01:15:13.862Z,1749431713.862 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-06-09T01:15:14.113Z,1749431714.113 [DAT](INFO): DAT read: Rx Time:01:15:14.1100 2025-06-09T01:15:14.113Z,1749431714.113 [DAT](INFO): Rx dataTimestamp_ set to:1749431714.112970 2025-06-09T01:15:14.114Z,1749431714.114 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519379 2025-06-09T01:15:14.365Z,1749431714.365 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770319 2025-06-09T01:15:14.616Z,1749431714.616 [DAT](INFO): DAT read: OK 2025-06-09T01:15:14.617Z,1749431714.617 [DAT](INFO): unknown deviceResponse_: OK 2025-06-09T01:15:14.617Z,1749431714.617 [DAT](INFO): DAT read: user:1088> 2025-06-09T01:15:14.618Z,1749431714.618 [DAT](INFO): DAT read: Tx time:01:15:15.1831 2025-06-09T01:15:14.618Z,1749431714.618 [DAT](INFO): Ping request sent. 2025-06-09T01:15:14.618Z,1749431714.618 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:15:14.618Z,1749431714.618 [DAT](INFO): publishing transmit ping time 2025-06-09T01:15:14.619Z,1749431714.619 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000713 2025-06-09T01:15:14.869Z,1749431714.869 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250343 2025-06-09T01:15:15.121Z,1749431715.121 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502447 2025-06-09T01:15:15.375Z,1749431715.375 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.756288 2025-06-09T01:15:15.625Z,1749431715.625 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006385 2025-06-09T01:15:15.877Z,1749431715.877 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258919 2025-06-09T01:15:16.129Z,1749431716.129 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510333 2025-06-09T01:15:16.383Z,1749431716.383 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.764561 2025-06-09T01:15:16.639Z,1749431716.639 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.020208 2025-06-09T01:15:16.885Z,1749431716.885 [DAT](INFO): DAT read: Rx Time:01:15:17.0604 2025-06-09T01:15:16.885Z,1749431716.885 [DAT](INFO): Rx dataTimestamp_ set to:1749431716.884969 2025-06-09T01:15:16.886Z,1749431716.886 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267436 2025-06-09T01:15:17.137Z,1749431717.137 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518349 2025-06-09T01:15:17.391Z,1749431717.391 [DAT](INFO): DAT read: OK 2025-06-09T01:15:17.392Z,1749431717.392 [DAT](INFO): unknown deviceResponse_: OK 2025-06-09T01:15:17.394Z,1749431717.394 [DAT](INFO): DAT read: user:1089> 2025-06-09T01:15:17.397Z,1749431717.397 [DAT](INFO): DAT read: Tx time:01:15:18.1340 2025-06-09T01:15:17.397Z,1749431717.397 [DAT](INFO): Ping request sent. 2025-06-09T01:15:17.398Z,1749431717.398 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:15:17.398Z,1749431717.398 [DAT](INFO): publishing transmit ping time 2025-06-09T01:15:17.403Z,1749431717.403 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.002187 2025-06-09T01:15:17.641Z,1749431717.641 [DAT](INFO): DAT read: TxSync time:01:15:18.1333 2025-06-09T01:15:17.642Z,1749431717.642 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.244197 2025-06-09T01:15:17.893Z,1749431717.893 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.495491 2025-06-09T01:15:18.145Z,1749431718.145 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.747202 2025-06-09T01:15:18.400Z,1749431718.400 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.001854 2025-06-09T01:15:18.649Z,1749431718.649 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.251101 2025-06-09T01:15:18.901Z,1749431718.901 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.503267 2025-06-09T01:15:19.154Z,1749431719.154 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.756609 2025-06-09T01:15:19.407Z,1749431719.407 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.009189 2025-06-09T01:15:19.657Z,1749431719.657 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.259268 2025-06-09T01:15:19.909Z,1749431719.909 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.511678 2025-06-09T01:15:20.161Z,1749431720.161 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.763269 2025-06-09T01:15:20.416Z,1749431720.416 [DAT](INFO): DAT read: Rx Time:01:15:20.5412 2025-06-09T01:15:20.417Z,1749431720.417 [DAT](INFO): Rx dataTimestamp_ set to:1749431720.416752 2025-06-09T01:15:20.419Z,1749431720.419 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021499 2025-06-09T01:15:20.665Z,1749431720.665 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.267187 2025-06-09T01:15:20.917Z,1749431720.917 [DAT](INFO): DAT read: Range 10 to 50 : 2.2 m (Round-trip 3.0 ms) speed 0.0 m/s 2025-06-09T01:15:20.918Z,1749431720.918 [DAT](ERROR): #Rx 1: Read range message, but no direction. 2025-06-09T01:15:48.954Z,1749431748.954 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 92.38, 80.83, 85.59 2025-06-09T01:15:52.984Z,1749431752.984 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-06-09T01:16:05.929Z,1749431765.929 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-06-09T01:16:44.702Z,1749431804.702 [DAT](INFO): DAT read: user:1090>Rx Time:01:16:44.8625 2025-06-09T01:16:44.703Z,1749431804.703 [DAT](INFO): Rx dataTimestamp_ set to:1749431804.702635 2025-06-09T01:16:45.709Z,1749431805.709 [DAT](INFO): DAT read: 2025-06-09T01:16:45.710Z,1749431805.710 [DAT](INFO): DAT read: DATA(0023):! echo 1 > /dev/loadB1 2025-06-09T01:16:45.710Z,1749431805.710 [DAT](INFO): Got DATA 23 2025-06-09T01:16:45.710Z,1749431805.710 [DAT](INFO): DAT read: 2025-06-09T01:16:45.711Z,1749431805.711 [DAT](INFO): DAT read: Source:050 Destination:010 2025-06-09T01:16:45.711Z,1749431805.711 [DAT](INFO): Got Src/Dest after DATA 2025-06-09T01:16:45.712Z,1749431805.712 [DAT](INFO): DATA Src=50, Dst=10 2025-06-09T01:16:45.713Z,1749431805.713 [DAT](INFO): DAT read: CRC:Pass MPD:03.7 PSNR:13.7 AGC:49 SPD:-0.1 CCERR:011 2025-06-09T01:16:45.713Z,1749431805.713 [DAT](INFO): Got CRC:Pass 2025-06-09T01:16:45.713Z,1749431805.713 [DAT](INFO): Got CRC:Pass 2025-06-09T01:16:45.713Z,1749431805.713 [DAT](INFO): Incoming data is intended for us 2025-06-09T01:16:45.714Z,1749431805.714 [DAT](INFO): Received command: ! echo 1 > /dev/loadB1 2025-06-09T01:16:45.714Z,1749431805.714 [DAT](INFO): #Outgoing data=1 2025-06-09T01:16:45.714Z,1749431805.714 [DAT](INFO): Sending ack 2025-06-09T01:16:45.714Z,1749431805.714 [DAT](INFO): DAT read: 2025-06-09T01:16:45.715Z,1749431805.715 [DAT](INFO): DAT read: 2025-06-09T01:16:45.715Z,1749431805.715 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2025-06-09T01:16:45.798Z,1749431805.798 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadB1 2025-06-09T01:16:45.961Z,1749431805.961 [DAT](INFO): setting remote address to 50 2025-06-09T01:16:46.027Z,1749431806.027 [OnDock:dataRead:A] Stopped 2025-06-09T01:16:46.036Z,1749431806.036 [OnDock:dataRead:B.Wait] Running Loop=1 2025-06-09T01:16:46.036Z,1749431806.036 [OnDock:dataRead:B.Wait](DEBUG): Initialize Wait Component. 2025-06-09T01:16:46.213Z,1749431806.213 [DAT](INFO): DAT read: 2025-06-09T01:16:46.213Z,1749431806.213 [DAT](INFO): DAT read: RemoteAddr | 50 2025-06-09T01:16:46.214Z,1749431806.214 [DAT](INFO): set remote address to 50 2025-06-09T01:16:46.214Z,1749431806.214 [DAT](INFO): entering online mode 2025-06-09T01:16:46.465Z,1749431806.465 [DAT](INFO): DAT read: user:1091> 2025-06-09T01:16:46.465Z,1749431806.465 [DAT](INFO): DAT read: 2025-06-09T01:16:46.466Z,1749431806.466 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-06-09T01:16:46.467Z,1749431806.467 [DAT](INFO): commRate: 600 2025-06-09T01:16:46.467Z,1749431806.467 [DAT](INFO): online mode acknowledged 2025-06-09T01:16:46.467Z,1749431806.467 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2025-06-09T01:16:49.749Z,1749431809.749 [DAT](INFO): DAT read: Forwarding Delay UpTx time:01:16:50.3838 2025-06-09T01:16:49.750Z,1749431809.750 [DAT](DEBUG): In parseResponses, sent ack so set commsState_ = SENDING_VERIFIED 2025-06-09T01:16:49.751Z,1749431809.751 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2025-06-09T01:16:59.851Z,1749431819.851 [DAT](INFO): DAT read: Rx Time:01:17:00.0130 2025-06-09T01:16:59.853Z,1749431819.853 [DAT](INFO): Rx dataTimestamp_ set to:1749431819.852397 2025-06-09T01:17:00.865Z,1749431820.865 [DAT](INFO): DAT read: 2025-06-09T01:17:00.866Z,1749431820.866 [DAT](INFO): DAT read: DATA(0024):! echo 1 > /dev/loadB1 2025-06-09T01:17:00.867Z,1749431820.867 [DAT](INFO): Got DATA 24 2025-06-09T01:17:00.867Z,1749431820.867 [DAT](INFO): DAT read: 2025-06-09T01:17:00.867Z,1749431820.867 [DAT](INFO): Got DATA 0 2025-06-09T01:17:00.867Z,1749431820.867 [DAT](INFO): DAT read: 2025-06-09T01:17:00.868Z,1749431820.868 [DAT](INFO): DAT read: Source:050 Destination:010 2025-06-09T01:17:00.868Z,1749431820.868 [DAT](INFO): Got Src/Dest after DATA 2025-06-09T01:17:00.869Z,1749431820.869 [DAT](INFO): DATA Src=50, Dst=10 2025-06-09T01:17:00.870Z,1749431820.870 [DAT](INFO): DAT read: CRC:Pass MPD:03.7 PSNR:16.1 AGC:50 SPD:+0.0 CCERR:012 2025-06-09T01:17:00.870Z,1749431820.870 [DAT](INFO): Got CRC:Pass 2025-06-09T01:17:00.870Z,1749431820.870 [DAT](INFO): Got CRC:Pass 2025-06-09T01:17:00.870Z,1749431820.870 [DAT](INFO): Incoming data is intended for us 2025-06-09T01:17:00.870Z,1749431820.870 [DAT](INFO): Received command: ! echo 1 > /dev/loadB1 ! 2025-06-09T01:17:00.871Z,1749431820.871 [DAT](INFO): Sending ack 2025-06-09T01:17:00.871Z,1749431820.871 [DAT](INFO): DAT read: 2025-06-09T01:17:00.877Z,1749431820.877 [DAT](INFO): DAT read: 2025-06-09T01:17:00.891Z,1749431820.891 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadB1 2025-06-09T01:17:01.149Z,1749431821.149 [CommandExec](IMPORTANT): got command ! 2025-06-09T01:17:01.150Z,1749431821.150 [CommandExec](FAULT): Incomplete syntax. Try: help ! 2025-06-09T01:17:04.137Z,1749431824.137 [DAT](INFO): DAT read: Forwarding Delay UpTx time:01:17:04.7839 2025-06-09T01:17:14.234Z,1749431834.234 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-06-09T01:17:14.472Z,1749431834.472 [DAT](DEBUG): Acoustic response timeout 2025-06-09T01:17:14.524Z,1749431834.524 [DAT](INFO): entering command mode 2025-06-09T01:17:14.725Z,1749431834.725 [DAT](INFO): DAT read: 2025-06-09T01:17:14.725Z,1749431834.725 [DAT](INFO): DAT read: user:1092> 2025-06-09T01:17:14.726Z,1749431834.726 [DAT](DEBUG): Acoustic response timeout 2025-06-09T01:17:14.726Z,1749431834.726 [DAT](INFO): setting remote address to 0 2025-06-09T01:17:14.977Z,1749431834.977 [DAT](INFO): DAT read: user:1092> 2025-06-09T01:17:14.978Z,1749431834.978 [DAT](INFO): DAT read: RemoteAddr | 0 2025-06-09T01:17:14.978Z,1749431834.978 [DAT](INFO): set remote address to 0 2025-06-09T01:17:14.978Z,1749431834.978 [DAT](DEBUG): Acoustic response timeout 2025-06-09T01:17:14.978Z,1749431834.978 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-06-09T01:17:15.231Z,1749431835.231 [DAT](INFO): DAT read: user:1093> 2025-06-09T01:17:15.233Z,1749431835.233 [DAT](INFO): DAT read: Tx time:01:17:15.7852 2025-06-09T01:17:15.233Z,1749431835.233 [DAT](INFO): Ping request sent. 2025-06-09T01:17:15.233Z,1749431835.233 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:17:15.234Z,1749431835.234 [DAT](INFO): DAT read: TxSync time:01:17:15.7844 2025-06-09T01:17:15.234Z,1749431835.234 [DAT](INFO): publishing transmit ping time 2025-06-09T01:17:15.235Z,1749431835.235 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001555 2025-06-09T01:17:15.481Z,1749431835.481 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.248096 2025-06-09T01:17:15.733Z,1749431835.733 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.499357 2025-06-09T01:17:15.985Z,1749431835.985 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.751404 2025-06-09T01:17:16.237Z,1749431836.237 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.003519 2025-06-09T01:17:16.489Z,1749431836.489 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.255337 2025-06-09T01:17:16.741Z,1749431836.741 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.507359 2025-06-09T01:17:16.993Z,1749431836.993 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.759388 2025-06-09T01:17:17.245Z,1749431837.245 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.011362 2025-06-09T01:17:17.500Z,1749431837.500 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266239 2025-06-09T01:17:17.749Z,1749431837.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.515405 2025-06-09T01:17:18.001Z,1749431838.001 [DAT](INFO): DAT read: Rx Time:01:17:18.1920 2025-06-09T01:17:18.001Z,1749431838.001 [DAT](INFO): Rx dataTimestamp_ set to:1749431838.000973 2025-06-09T01:17:18.002Z,1749431838.002 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.768434 2025-06-09T01:17:18.253Z,1749431838.253 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.019436 2025-06-09T01:17:18.506Z,1749431838.506 [DAT](INFO): DAT read: Range 10 to 50 : 2.0 m (Round-trip 2.7 ms) speed -0.1 m/s 2025-06-09T01:17:18.506Z,1749431838.506 [DAT](ERROR): #Rx 1: Read range message, but no direction. 2025-06-09T01:17:54.594Z,1749431874.594 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 4. 2025-06-09T01:17:54.597Z,1749431874.597 [BPC1](FAULT): Failed to receive data from 5 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 8, 43, 44, 45, 46. 2025-06-09T01:18:16.074Z,1749431896.074 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 93.40, 87.63, 85.25 2025-06-09T01:19:14.601Z,1749431954.601 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-06-09T01:19:14.828Z,1749431954.828 [DAT](DEBUG): Acoustic response timeout 2025-06-09T01:19:14.829Z,1749431954.829 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-06-09T01:19:15.081Z,1749431955.081 [DAT](INFO): DAT read: user:1094> 2025-06-09T01:19:15.082Z,1749431955.082 [DAT](INFO): DAT read: Tx time:01:19:15.6365 2025-06-09T01:19:15.083Z,1749431955.083 [DAT](INFO): Ping request sent. 2025-06-09T01:19:15.083Z,1749431955.083 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:19:15.084Z,1749431955.084 [DAT](INFO): DAT read: TxSync time:01:19:15.6357 2025-06-09T01:19:15.084Z,1749431955.084 [DAT](INFO): publishing transmit ping time 2025-06-09T01:19:15.085Z,1749431955.085 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.002265 2025-06-09T01:19:15.333Z,1749431955.333 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.249931 2025-06-09T01:19:15.585Z,1749431955.585 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501861 2025-06-09T01:19:15.838Z,1749431955.838 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755600 2025-06-09T01:19:16.089Z,1749431956.089 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005933 2025-06-09T01:19:16.341Z,1749431956.341 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258018 2025-06-09T01:19:16.595Z,1749431956.595 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511657 2025-06-09T01:19:16.845Z,1749431956.845 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.761961 2025-06-09T01:19:17.097Z,1749431957.097 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014389 2025-06-09T01:19:17.349Z,1749431957.349 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.265896 2025-06-09T01:19:17.601Z,1749431957.601 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517934 2025-06-09T01:19:17.856Z,1749431957.856 [DAT](INFO): DAT read: Rx Time:01:19:18.0437 2025-06-09T01:19:17.857Z,1749431957.857 [DAT](INFO): Rx dataTimestamp_ set to:1749431957.856578 2025-06-09T01:19:17.857Z,1749431957.857 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.774571 2025-06-09T01:19:18.105Z,1749431958.105 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021877 2025-06-09T01:19:18.359Z,1749431958.359 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275867 2025-06-09T01:19:18.614Z,1749431958.614 [DAT](INFO): DAT read: 01:19:18.0437 LVL= 10480, 17521, 13890, 13811, AGC= 45, IDX= 428,-0.16,-2.115,-0.097,-1.126, 0.171, PHS=-2.197,-0.220,-1.299, RAW= 303.0, 37.5, CAL= 311.0, 38.2, ROT= 199.0, -38.2 2025-06-09T01:19:18.615Z,1749431958.615 [DAT](INFO): got valid direction response: 01:19:18.0437 LVL= 10480, 17521, 13890, 13811, AGC= 45, IDX= 428,-0.16,-2.115,-0.097,-1.126, 0.171, PHS=-2.197,-0.220,-1.299, RAW= 303.0, 37.5, CAL= 311.0, 38.2, ROT= 199.0, -38.2 2025-06-09T01:19:18.616Z,1749431958.616 [DAT](INFO): DAT read: Bearing 199.0, -38.2 (Local) 2025-06-09T01:19:18.617Z,1749431958.617 [DAT](INFO): Local bearing/azimuth received: Bearing 199.0, -38.2 (Local) 2025-06-09T01:19:18.618Z,1749431958.618 [DAT](INFO): DAT read: Range 10 to 50 : 2.3 m (Round-trip 3.1 ms) speed 0.0 m/s 2025-06-09T01:19:18.619Z,1749431958.619 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-06-09T01:19:18.620Z,1749431958.620 [DAT](INFO): direction in FSK: [-0.743042,-0.255850,0.618408] 2025-06-09T01:19:18.620Z,1749431958.620 [DAT](INFO): publishing direction and range info 2025-06-09T01:19:23.086Z,1749431963.086 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 94.42, 79.48, 85.93 2025-06-09T01:19:26.313Z,1749431966.313 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, 38, -15,A 2025-06-09T01:20:41.475Z,1749432041.475 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS, -41, +37, 2025-06-09T01:20:48.901Z,1749432048.901 [DAT](INFO): DAT read: user:1095>Rx Time:01:20:49.0699 2025-06-09T01:20:48.901Z,1749432048.901 [DAT](INFO): Rx dataTimestamp_ set to:1749432048.901216 2025-06-09T01:20:50.167Z,1749432050.167 [DAT](INFO): DAT read: 01:20:49.0699 LVL= 12896, 15537, 16130, 18067, AGC= 49, IDX= 202, 0.21,-1.997,-0.189,-1.145, 0.221, PHS=-2.131,-0.364,-1.369, RAW= 304.6, 41.7, CAL= 312.4, 41.7, ROT= 197.6, -41.7 2025-06-09T01:20:50.168Z,1749432050.168 [DAT](INFO): got valid direction response: 01:20:49.0699 LVL= 12896, 15537, 16130, 18067, AGC= 49, IDX= 202, 0.21,-1.997,-0.189,-1.145, 0.221, PHS=-2.131,-0.364,-1.369, RAW= 304.6, 41.7, CAL= 312.4, 41.7, ROT= 197.6, -41.7 2025-06-09T01:20:50.169Z,1749432050.169 [DAT](INFO): DAT read: 2025-06-09T01:20:50.171Z,1749432050.171 [DAT](INFO): DAT read: DATA(0057):configset dockingstepper.closedvalue 10700 count persist 2025-06-09T01:20:50.171Z,1749432050.171 [DAT](INFO): Got DATA 57 2025-06-09T01:20:50.171Z,1749432050.171 [DAT](INFO): DAT read: 2025-06-09T01:20:50.172Z,1749432050.172 [DAT](INFO): DAT read: Source:050 Destination:010 2025-06-09T01:20:50.172Z,1749432050.172 [DAT](INFO): Got Src/Dest after DATA 2025-06-09T01:20:50.173Z,1749432050.173 [DAT](INFO): DATA Src=50, Dst=10 2025-06-09T01:20:50.174Z,1749432050.174 [DAT](INFO): DAT read: CRC:Pass MPD:03.3 PSNR:14.7 AGC:49 SPD:+0.1 CCERR:011 2025-06-09T01:20:50.174Z,1749432050.174 [DAT](INFO): Got CRC:Pass 2025-06-09T01:20:50.174Z,1749432050.174 [DAT](INFO): Got CRC:Pass 2025-06-09T01:20:50.174Z,1749432050.174 [DAT](INFO): Incoming data is intended for us 2025-06-09T01:20:50.174Z,1749432050.174 [DAT](INFO): Received command: configset dockingstepper.closedvalue 10700 count persist 2025-06-09T01:20:50.175Z,1749432050.175 [DAT](INFO): #Outgoing data=1 2025-06-09T01:20:50.175Z,1749432050.175 [DAT](INFO): Sending ack 2025-06-09T01:20:50.175Z,1749432050.175 [DAT](INFO): DAT read: 2025-06-09T01:20:50.175Z,1749432050.175 [DAT](INFO): DAT read: 2025-06-09T01:20:50.176Z,1749432050.176 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2025-06-09T01:20:50.177Z,1749432050.177 [DAT](INFO): #Rx 2: Read direction message, but no range. 2025-06-09T01:20:50.177Z,1749432050.177 [DAT](INFO): direction in FSK: [-0.711688,-0.225761,0.665230] 2025-06-09T01:20:50.413Z,1749432050.413 [DAT](INFO): setting remote address to 50 2025-06-09T01:20:50.665Z,1749432050.665 [DAT](INFO): DAT read: 2025-06-09T01:20:50.665Z,1749432050.665 [DAT](INFO): DAT read: RemoteAddr | 50 2025-06-09T01:20:50.666Z,1749432050.666 [DAT](INFO): set remote address to 50 2025-06-09T01:20:50.666Z,1749432050.666 [DAT](INFO): entering online mode 2025-06-09T01:20:50.917Z,1749432050.917 [DAT](INFO): DAT read: user:1096> 2025-06-09T01:20:50.917Z,1749432050.917 [DAT](INFO): DAT read: 2025-06-09T01:20:50.918Z,1749432050.918 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-06-09T01:20:50.919Z,1749432050.919 [DAT](INFO): commRate: 600 2025-06-09T01:20:50.919Z,1749432050.919 [DAT](INFO): online mode acknowledged 2025-06-09T01:20:50.919Z,1749432050.919 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2025-06-09T01:20:50.997Z,1749432050.997 [CommandExec](IMPORTANT): got command configSet DockingStepper.closedValue 10700 count persist 2025-06-09T01:20:54.194Z,1749432054.194 [DAT](INFO): DAT read: Forwarding Delay UpTx time:01:20:54.8364 2025-06-09T01:20:54.194Z,1749432054.194 [DAT](DEBUG): In parseResponses, sent ack so set commsState_ = SENDING_VERIFIED 2025-06-09T01:20:54.195Z,1749432054.195 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2025-06-09T01:21:07.551Z,1749432067.551 [DAT](INFO): DAT read: Rx Time:01:21:07.7701 2025-06-09T01:21:07.555Z,1749432067.555 [DAT](INFO): Rx dataTimestamp_ set to:1749432067.553960 2025-06-09T01:21:08.826Z,1749432068.826 [DAT](INFO): DAT read: 01:21:07.7701 LVL= 9584, 12657, 12162, 13075, AGC= 48, IDX= 78,-0.23, 1.908,-2.397, 2.905,-2.063, PHS=-2.224,-0.287,-1.319, RAW= 302.2, 38.9, CAL= 310.5, 39.3, ROT= 199.5, -39.3 2025-06-09T01:21:08.830Z,1749432068.830 [DAT](INFO): got valid direction response: 01:21:07.7701 LVL= 9584, 12657, 12162, 13075, AGC= 48, IDX= 78,-0.23, 1.908,-2.397, 2.905,-2.063, PHS=-2.224,-0.287,-1.319, RAW= 302.2, 38.9, CAL= 310.5, 39.3, ROT= 199.5, -39.3 2025-06-09T01:21:08.831Z,1749432068.831 [DAT](INFO): DAT read: 2025-06-09T01:21:08.835Z,1749432068.835 [DAT](INFO): DAT read: DATA(0057):configset dockingstepper.closedvalue 10700 count persist 2025-06-09T01:21:08.835Z,1749432068.835 [DAT](INFO): Got DATA 57 2025-06-09T01:21:08.836Z,1749432068.836 [DAT](INFO): DAT read: 2025-06-09T01:21:08.837Z,1749432068.837 [DAT](INFO): DAT read: Source:050 Destination:010 2025-06-09T01:21:08.837Z,1749432068.837 [DAT](INFO): Got Src/Dest after DATA 2025-06-09T01:21:08.837Z,1749432068.837 [DAT](INFO): DATA Src=50, Dst=10 2025-06-09T01:21:08.838Z,1749432068.838 [DAT](INFO): DAT read: CRC:Pass MPD:03.7 PSNR:15.2 AGC:48 SPD:+0.1 CCERR:011 2025-06-09T01:21:08.839Z,1749432068.839 [DAT](INFO): Got CRC:Pass 2025-06-09T01:21:08.839Z,1749432068.839 [DAT](INFO): Got CRC:Pass 2025-06-09T01:21:08.839Z,1749432068.839 [DAT](INFO): Incoming data is intended for us 2025-06-09T01:21:08.839Z,1749432068.839 [DAT](INFO): Received command: configset dockingstepper.closedvalue 10700 count persist 2025-06-09T01:21:08.840Z,1749432068.840 [DAT](INFO): Sending ack 2025-06-09T01:21:08.840Z,1749432068.840 [DAT](INFO): DAT read: 2025-06-09T01:21:08.840Z,1749432068.840 [DAT](INFO): DAT read: 2025-06-09T01:21:08.841Z,1749432068.841 [DAT](INFO): #Rx 3: Read direction message, but no range. 2025-06-09T01:21:08.842Z,1749432068.842 [DAT](INFO): direction in FSK: [-0.729454,-0.258313,0.633381] 2025-06-09T01:21:09.469Z,1749432069.469 [CommandExec](IMPORTANT): got command configSet DockingStepper.closedValue 10700 count persist 2025-06-09T01:21:12.085Z,1749432072.085 [DAT](INFO): DAT read: Forwarding Delay UpTx time:01:21:12.7366 2025-06-09T01:21:14.637Z,1749432074.637 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-06-09T01:21:14.856Z,1749432074.856 [DAT](DEBUG): Acoustic response timeout 2025-06-09T01:21:14.908Z,1749432074.908 [DAT](INFO): entering command mode 2025-06-09T01:21:15.109Z,1749432075.109 [DAT](INFO): DAT read: 2025-06-09T01:21:15.109Z,1749432075.109 [DAT](INFO): DAT read: user:1097> 2025-06-09T01:21:15.110Z,1749432075.110 [DAT](DEBUG): Acoustic response timeout 2025-06-09T01:21:15.110Z,1749432075.110 [DAT](INFO): setting remote address to 0 2025-06-09T01:21:15.361Z,1749432075.361 [DAT](INFO): DAT read: user:1097> 2025-06-09T01:21:15.362Z,1749432075.362 [DAT](INFO): DAT read: RemoteAddr | 0 2025-06-09T01:21:15.362Z,1749432075.362 [DAT](INFO): set remote address to 0 2025-06-09T01:21:15.362Z,1749432075.362 [DAT](DEBUG): Acoustic response timeout 2025-06-09T01:21:15.362Z,1749432075.362 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-06-09T01:21:15.613Z,1749432075.613 [DAT](INFO): DAT read: user:1098> 2025-06-09T01:21:15.613Z,1749432075.613 [DAT](INFO): DAT read: Tx time:01:21:16.1877 2025-06-09T01:21:15.614Z,1749432075.614 [DAT](INFO): Ping request sent. 2025-06-09T01:21:15.614Z,1749432075.614 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:21:15.614Z,1749432075.614 [DAT](INFO): publishing transmit ping time 2025-06-09T01:21:15.615Z,1749432075.615 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000735 2025-06-09T01:21:15.867Z,1749432075.867 [DAT](INFO): DAT read: TxSync time:01:21:16.1870 2025-06-09T01:21:15.870Z,1749432075.870 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.255789 2025-06-09T01:21:16.117Z,1749432076.117 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502825 2025-06-09T01:21:16.369Z,1749432076.369 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754802 2025-06-09T01:21:16.621Z,1749432076.621 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006903 2025-06-09T01:21:16.873Z,1749432076.873 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258892 2025-06-09T01:21:17.009Z,1749432077.009 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,25060817223613,35.0, +10.6, .0, 0 2025-06-09T01:21:17.125Z,1749432077.125 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510805 2025-06-09T01:21:17.377Z,1749432077.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763122 2025-06-09T01:21:17.629Z,1749432077.629 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014886 2025-06-09T01:21:17.881Z,1749432077.881 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267403 2025-06-09T01:21:18.133Z,1749432078.133 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518827 2025-06-09T01:21:18.385Z,1749432078.385 [DAT](INFO): DAT read: Rx Time:01:21:18.5950 2025-06-09T01:21:18.386Z,1749432078.386 [DAT](INFO): Rx dataTimestamp_ set to:1749432078.385649 2025-06-09T01:21:18.387Z,1749432078.387 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772843 2025-06-09T01:21:18.637Z,1749432078.637 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022943 2025-06-09T01:21:18.891Z,1749432078.891 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.276796 2025-06-09T01:21:19.146Z,1749432079.146 [DAT](INFO): DAT read: 01:21:18.5950 LVL= 9264, 16049, 16882, 17795, AGC= 45, IDX= 268,-0.22,-2.321,-0.812,-1.864,-0.468, PHS=-1.765,-0.297,-1.399, RAW= 316.1, 42.8, CAL= 321.3, 42.3, ROT= 188.7, -42.3 2025-06-09T01:21:19.147Z,1749432079.147 [DAT](INFO): got valid direction response: 01:21:18.5950 LVL= 9264, 16049, 16882, 17795, AGC= 45, IDX= 268,-0.22,-2.321,-0.812,-1.864,-0.468, PHS=-1.765,-0.297,-1.399, RAW= 316.1, 42.8, CAL= 321.3, 42.3, ROT= 188.7, -42.3 2025-06-09T01:21:19.149Z,1749432079.149 [DAT](INFO): DAT read: Bearing 188.7, -42.3 (Local) 2025-06-09T01:21:19.149Z,1749432079.149 [DAT](INFO): Local bearing/azimuth received: Bearing 188.7, -42.3 (Local) 2025-06-09T01:21:19.150Z,1749432079.150 [DAT](INFO): DAT read: Range 10 to 50 : 2.3 m (Round-trip 3.1 ms) speed 0.0 m/s 2025-06-09T01:21:19.151Z,1749432079.151 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-06-09T01:21:19.152Z,1749432079.152 [DAT](INFO): direction in FSK: [-0.731121,-0.111877,0.673013] 2025-06-09T01:21:19.152Z,1749432079.152 [DAT](INFO): publishing direction and range info 2025-06-09T01:21:34.269Z,1749432094.269 [DAT](INFO): DAT read: user:1099>Rx Time:01:21:34.4708 2025-06-09T01:21:34.269Z,1749432094.269 [DAT](INFO): Rx dataTimestamp_ set to:1749432094.269181 2025-06-09T01:21:35.034Z,1749432095.034 [DAT](INFO): DAT read: 01:21:34.4708 LVL= 11200, 21585, 17746, 16083, AGC= 48, IDX= 227,-0.49, 2.647,-1.829,-2.792,-1.427, PHS=-2.121,-0.355,-1.368, RAW= 304.9, 41.5, CAL= 312.6, 41.5, ROT= 197.4, -41.5 2025-06-09T01:21:35.038Z,1749432095.038 [DAT](INFO): got valid direction response: 01:21:34.4708 LVL= 11200, 21585, 17746, 16083, AGC= 48, IDX= 227,-0.49, 2.647,-1.829,-2.792,-1.427, PHS=-2.121,-0.355,-1.368, RAW= 304.9, 41.5, CAL= 312.6, 41.5, ROT= 197.4, -41.5 2025-06-09T01:21:35.038Z,1749432095.038 [DAT](INFO): DAT read: 2025-06-09T01:21:35.041Z,1749432095.041 [DAT](INFO): DAT read: DATA(0011):dock slide 2025-06-09T01:21:35.042Z,1749432095.042 [DAT](INFO): Got DATA 11 2025-06-09T01:21:35.043Z,1749432095.043 [DAT](INFO): DAT read: 2025-06-09T01:21:35.053Z,1749432095.053 [DAT](INFO): DAT read: Source:050 Destination:010 2025-06-09T01:21:35.053Z,1749432095.053 [DAT](INFO): Got Src/Dest after DATA 2025-06-09T01:21:35.054Z,1749432095.054 [DAT](INFO): DATA Src=50, Dst=10 2025-06-09T01:21:35.055Z,1749432095.055 [DAT](INFO): DAT read: CRC:Pass MPD:03.8 PSNR:13.3 AGC:51 SPD:-0.3 CCERR:010 2025-06-09T01:21:35.055Z,1749432095.055 [DAT](INFO): Got CRC:Pass 2025-06-09T01:21:35.055Z,1749432095.055 [DAT](INFO): Got CRC:Pass 2025-06-09T01:21:35.055Z,1749432095.055 [DAT](INFO): Incoming data is intended for us 2025-06-09T01:21:35.055Z,1749432095.055 [DAT](INFO): Received command: dock slide 2025-06-09T01:21:35.056Z,1749432095.056 [DAT](INFO): #Outgoing data=1 2025-06-09T01:21:35.056Z,1749432095.056 [DAT](INFO): Sending ack 2025-06-09T01:21:35.056Z,1749432095.056 [DAT](INFO): DAT read: 2025-06-09T01:21:35.056Z,1749432095.056 [DAT](INFO): DAT read: 2025-06-09T01:21:35.057Z,1749432095.057 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2025-06-09T01:21:35.057Z,1749432095.057 [DAT](INFO): #Rx 2: Read direction message, but no range. 2025-06-09T01:21:35.058Z,1749432095.058 [DAT](INFO): direction in FSK: [-0.714684,-0.223968,0.662620] 2025-06-09T01:21:35.101Z,1749432095.101 [CommandExec](IMPORTANT): got command docking slide 2025-06-09T01:21:35.102Z,1749432095.102 [CommandExec](IMPORTANT): Switching docking module to SLIDE. 2025-06-09T01:21:35.277Z,1749432095.277 [DAT](INFO): setting remote address to 50 2025-06-09T01:21:35.529Z,1749432095.529 [DAT](INFO): DAT read: 2025-06-09T01:21:35.529Z,1749432095.529 [DAT](INFO): DAT read: RemoteAddr | 50 2025-06-09T01:21:35.530Z,1749432095.530 [DAT](INFO): set remote address to 50 2025-06-09T01:21:35.530Z,1749432095.530 [DAT](INFO): entering online mode 2025-06-09T01:21:35.781Z,1749432095.781 [DAT](INFO): DAT read: user:1100> 2025-06-09T01:21:35.781Z,1749432095.781 [DAT](INFO): DAT read: 2025-06-09T01:21:35.782Z,1749432095.782 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-06-09T01:21:35.783Z,1749432095.783 [DAT](INFO): commRate: 600 2025-06-09T01:21:35.783Z,1749432095.783 [DAT](INFO): online mode acknowledged 2025-06-09T01:21:35.783Z,1749432095.783 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2025-06-09T01:21:39.065Z,1749432099.065 [DAT](INFO): DAT read: Forwarding Delay UpTx time:01:21:39.6868 2025-06-09T01:21:39.065Z,1749432099.065 [DAT](DEBUG): In parseResponses, sent ack so set commsState_ = SENDING_VERIFIED 2025-06-09T01:21:39.066Z,1749432099.066 [DAT](DEBUG): In sendingVerified, data done so set commsState_ = SENDING_FILL_BUFFER 2025-06-09T01:21:46.551Z,1749432106.551 [OnDock:dataRead:B.Wait](INFO): Done Waiting. 2025-06-09T01:21:46.551Z,1749432106.551 [OnDock:dataRead:B.Wait] Stopped 2025-06-09T01:21:46.551Z,1749432106.551 [OnDock:dataRead:B.Wait](DEBUG): Uninitialize Wait Component. 2025-06-09T01:21:46.551Z,1749432106.551 [OnDock:dataRead](INFO): Completed OnDock:dataRead 2025-06-09T01:21:46.921Z,1749432106.921 [OnDock:dataRead] Running Loop=1 2025-06-09T01:21:46.921Z,1749432106.921 [OnDock:dataRead](DEBUG): Aggregate::initialize OnDock:dataRead 2025-06-09T01:21:46.921Z,1749432106.921 [OnDock:dataRead:A] Running Loop=1 2025-06-09T01:22:06.297Z,1749432126.297 [DAT](INFO): DAT read: Rx Time:01:22:06.3717 2025-06-09T01:22:06.297Z,1749432126.297 [DAT](INFO): Rx dataTimestamp_ set to:1749432126.296925 2025-06-09T01:22:07.309Z,1749432127.309 [DAT](INFO): DAT read: 01:22:06.3717 LVL= 8560, 10385, 10178, 13667, AGC= 47, IDX= 56,-0.08, 2.721,-1.763,-2.815,-1.439, PHS=-2.035,-0.277,-1.379, RAW= 308.3, 40.3, CAL= 315.5, 40.1, ROT= 194.5, -40.1 2025-06-09T01:22:07.310Z,1749432127.310 [DAT](INFO): got valid direction response: 01:22:06.3717 LVL= 8560, 10385, 10178, 13667, AGC= 47, IDX= 56,-0.08, 2.721,-1.763,-2.815,-1.439, PHS=-2.035,-0.277,-1.379, RAW= 308.3, 40.3, CAL= 315.5, 40.1, ROT= 194.5, -40.1 2025-06-09T01:22:07.310Z,1749432127.310 [DAT](INFO): #Rx 3: Read direction message, but no range. 2025-06-09T01:22:07.311Z,1749432127.311 [DAT](INFO): direction in FSK: [-0.740557,-0.191521,0.644124] 2025-06-09T01:22:07.569Z,1749432127.569 [DAT](INFO): DAT read: 2025-06-09T01:22:07.574Z,1749432127.574 [DAT](INFO): DAT read: DATA(0047):failc h dockingstepper; failc n dockingstepper 2025-06-09T01:22:07.575Z,1749432127.575 [DAT](INFO): Got DATA 47 2025-06-09T01:22:07.577Z,1749432127.577 [DAT](INFO): DAT read: 2025-06-09T01:22:07.578Z,1749432127.578 [DAT](INFO): DAT read: Source:050 Destination:010 2025-06-09T01:22:07.578Z,1749432127.578 [DAT](INFO): Got Src/Dest after DATA 2025-06-09T01:22:07.579Z,1749432127.579 [DAT](INFO): DATA Src=50, Dst=10 2025-06-09T01:22:07.581Z,1749432127.581 [DAT](INFO): DAT read: CRC:Pass MPD:03.7 PSNR:14.9 AGC:49 SPD:+0.0 CCERR:011 2025-06-09T01:22:07.581Z,1749432127.581 [DAT](INFO): Got CRC:Pass 2025-06-09T01:22:07.581Z,1749432127.581 [DAT](INFO): Got CRC:Pass 2025-06-09T01:22:07.581Z,1749432127.581 [DAT](INFO): Incoming data is intended for us 2025-06-09T01:22:07.581Z,1749432127.581 [DAT](INFO): Received command: failc h dockingstepper; failc n dockingstepper 2025-06-09T01:22:07.582Z,1749432127.582 [DAT](INFO): Sending ack 2025-06-09T01:22:07.582Z,1749432127.582 [DAT](INFO): DAT read: 2025-06-09T01:22:07.583Z,1749432127.583 [DAT](INFO): DAT read: 2025-06-09T01:22:08.015Z,1749432128.015 [CommandExec](IMPORTANT): got command failComponent hardware DockingStepper 2025-06-09T01:22:08.015Z,1749432128.015 [DockingStepper] Hardware Fault, FailCount= 1 2025-06-09T01:22:08.015Z,1749432128.015 [DockingStepper](ERROR): Hardware Fault 2025-06-09T01:22:08.021Z,1749432128.021 [CBIT](ERROR): Hardware Fault in component: DockingStepper 2025-06-09T01:22:08.023Z,1749432128.023 [CommandExec](IMPORTANT): DockingStepper failureMode is Hardware Fault 2025-06-09T01:22:08.023Z,1749432128.023 [CommandExec](IMPORTANT): got command failComponent none DockingStepper 2025-06-09T01:22:08.023Z,1749432128.023 [DockingStepper] No Fault, FailCount= 0 2025-06-09T01:22:08.023Z,1749432128.023 [CommandExec](IMPORTANT): DockingStepper failureMode is No Fault 2025-06-09T01:22:10.855Z,1749432130.855 [DAT](INFO): DAT read: Forwarding Delay UpTx time:01:22:11.4872 2025-06-09T01:22:53.509Z,1749432173.509 [DAT](INFO): DAT read: Rx Time:01:22:53.6730 2025-06-09T01:22:53.509Z,1749432173.509 [DAT](INFO): Rx dataTimestamp_ set to:1749432173.508977 2025-06-09T01:22:54.775Z,1749432174.775 [DAT](INFO): DAT read: 01:22:53.6730 LVL= 9872, 15169, 14706, 15123, AGC= 46, IDX= 320, 0.15,-2.095,-0.567,-1.519,-0.100, PHS=-1.907,-0.420,-1.422, RAW= 311.3, 45.3, CAL= 317.4, 45.0, ROT= 192.6, -45.0 2025-06-09T01:22:54.776Z,1749432174.776 [DAT](INFO): got valid direction response: 01:22:53.6730 LVL= 9872, 15169, 14706, 15123, AGC= 46, IDX= 320, 0.15,-2.095,-0.567,-1.519,-0.100, PHS=-1.907,-0.420,-1.422, RAW= 311.3, 45.3, CAL= 317.4, 45.0, ROT= 192.6, -45.0 2025-06-09T01:22:54.777Z,1749432174.777 [DAT](INFO): DAT read: 2025-06-09T01:22:54.778Z,1749432174.778 [DAT](INFO): DAT read: DATA(0047):failc h dockingstepper; failc n dockingstepper 2025-06-09T01:22:54.779Z,1749432174.779 [DAT](INFO): Got DATA 47 2025-06-09T01:22:54.779Z,1749432174.779 [DAT](INFO): DAT read: 2025-06-09T01:22:54.780Z,1749432174.780 [DAT](INFO): DAT read: Source:050 Destination:010 2025-06-09T01:22:54.780Z,1749432174.780 [DAT](INFO): Got Src/Dest after DATA 2025-06-09T01:22:54.781Z,1749432174.781 [DAT](INFO): DATA Src=50, Dst=10 2025-06-09T01:22:54.782Z,1749432174.782 [DAT](INFO): DAT read: CRC:Pass MPD:03.7 PSNR:14.2 AGC:48 SPD:+0.0 CCERR:011 2025-06-09T01:22:54.782Z,1749432174.782 [DAT](INFO): Got CRC:Pass 2025-06-09T01:22:54.782Z,1749432174.782 [DAT](INFO): Got CRC:Pass 2025-06-09T01:22:54.782Z,1749432174.782 [DAT](INFO): Incoming data is intended for us 2025-06-09T01:22:54.782Z,1749432174.782 [DAT](INFO): Received command: failc h dockingstepper; failc n dockingstepper 2025-06-09T01:22:54.783Z,1749432174.783 [DAT](INFO): Sending ack 2025-06-09T01:22:54.783Z,1749432174.783 [DAT](INFO): DAT read: 2025-06-09T01:22:54.792Z,1749432174.792 [DAT](INFO): DAT read: 2025-06-09T01:22:54.793Z,1749432174.793 [DAT](INFO): #Rx 4: Read direction message, but no range. 2025-06-09T01:22:54.794Z,1749432174.794 [DAT](INFO): direction in FSK: [-0.690077,-0.154251,0.707107] 2025-06-09T01:22:55.419Z,1749432175.419 [CommandExec](IMPORTANT): got command failComponent hardware DockingStepper 2025-06-09T01:22:55.419Z,1749432175.419 [DockingStepper] Hardware Fault, FailCount= 1 2025-06-09T01:22:55.419Z,1749432175.419 [DockingStepper](ERROR): Hardware Fault 2025-06-09T01:22:55.419Z,1749432175.419 [CommandExec](IMPORTANT): DockingStepper failureMode is Hardware Fault 2025-06-09T01:22:55.420Z,1749432175.420 [CommandExec](IMPORTANT): got command failComponent none DockingStepper 2025-06-09T01:22:55.420Z,1749432175.420 [DockingStepper] No Fault, FailCount= 1 2025-06-09T01:22:55.420Z,1749432175.420 [CommandExec](IMPORTANT): DockingStepper failureMode is No Fault 2025-06-09T01:22:58.046Z,1749432178.046 [DAT](INFO): DAT read: Forwarding Delay UpTx time:01:22:58.6877 2025-06-09T01:23:14.985Z,1749432194.985 [OnDock:J.](INFO): *** querying acoustic contact *** 2025-06-09T01:23:15.204Z,1749432195.204 [DAT](DEBUG): Acoustic response timeout 2025-06-09T01:23:15.256Z,1749432195.256 [DAT](INFO): entering command mode 2025-06-09T01:23:15.459Z,1749432195.459 [DAT](INFO): DAT read: 2025-06-09T01:23:15.462Z,1749432195.462 [DAT](INFO): DAT read: user:1101> 2025-06-09T01:23:15.465Z,1749432195.465 [DAT](DEBUG): Acoustic response timeout 2025-06-09T01:23:15.465Z,1749432195.465 [DAT](INFO): setting remote address to 0 2025-06-09T01:23:15.709Z,1749432195.709 [DAT](INFO): DAT read: user:1101> 2025-06-09T01:23:15.710Z,1749432195.710 [DAT](INFO): DAT read: RemoteAddr | 0 2025-06-09T01:23:15.710Z,1749432195.710 [DAT](INFO): set remote address to 0 2025-06-09T01:23:15.710Z,1749432195.710 [DAT](DEBUG): Acoustic response timeout 2025-06-09T01:23:15.711Z,1749432195.711 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-06-09T01:23:15.803Z,1749432195.803 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, , +0.00, +0.00, 20.00,3119.74 2025-06-09T01:23:15.961Z,1749432195.961 [DAT](INFO): DAT read: user:1102> 2025-06-09T01:23:15.961Z,1749432195.961 [DAT](INFO): DAT read: Tx time:01:23:16.5391 2025-06-09T01:23:15.962Z,1749432195.962 [DAT](INFO): Ping request sent. 2025-06-09T01:23:15.962Z,1749432195.962 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:23:15.962Z,1749432195.962 [DAT](INFO): publishing transmit ping time 2025-06-09T01:23:15.963Z,1749432195.963 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000743 2025-06-09T01:23:16.213Z,1749432196.213 [DAT](INFO): DAT read: TxSync time:01:23:16.5383 2025-06-09T01:23:16.214Z,1749432196.214 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251890 2025-06-09T01:23:16.467Z,1749432196.467 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.504752 2025-06-09T01:23:16.717Z,1749432196.717 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754869 2025-06-09T01:23:16.969Z,1749432196.969 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006919 2025-06-09T01:23:17.221Z,1749432197.221 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258950 2025-06-09T01:23:17.475Z,1749432197.475 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.513206 2025-06-09T01:23:17.725Z,1749432197.725 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762895 2025-06-09T01:23:17.977Z,1749432197.977 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014951 2025-06-09T01:23:18.229Z,1749432198.229 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267184 2025-06-09T01:23:18.484Z,1749432198.484 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.521766 2025-06-09T01:23:18.734Z,1749432198.734 [DAT](INFO): DAT read: Rx Time:01:23:18.9462 2025-06-09T01:23:18.735Z,1749432198.735 [DAT](INFO): Rx dataTimestamp_ set to:1749432198.734256 2025-06-09T01:23:18.735Z,1749432198.735 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.773246 2025-06-09T01:23:18.985Z,1749432198.985 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022811 2025-06-09T01:23:19.237Z,1749432199.237 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274918 2025-06-09T01:23:19.521Z,1749432199.521 [DAT](INFO): DAT read: 01:23:18.9462 LVL= 10016, 16849, 15954, 14483, AGC= 45, IDX= 508,-0.37,-2.547,-0.670,-1.745,-0.386, PHS=-2.073,-0.237,-1.362, RAW= 307.4, 39.0, CAL= 314.7, 39.2, ROT= 195.3, -39.2 2025-06-09T01:23:19.525Z,1749432199.525 [DAT](INFO): got valid direction response: 01:23:18.9462 LVL= 10016, 16849, 15954, 14483, AGC= 45, IDX= 508,-0.37,-2.547,-0.670,-1.745,-0.386, PHS=-2.073,-0.237,-1.362, RAW= 307.4, 39.0, CAL= 314.7, 39.2, ROT= 195.3, -39.2 2025-06-09T01:23:19.544Z,1749432199.544 [DAT](INFO): DAT read: Bearing 195.3, -39.2 (Local) 2025-06-09T01:23:19.545Z,1749432199.545 [DAT](INFO): Local bearing/azimuth received: Bearing 195.3, -39.2 (Local) 2025-06-09T01:23:19.561Z,1749432199.561 [DAT](INFO): DAT read: Range 10 to 50 : 2.2 m (Round-trip 3.0 ms) speed 0.0 m/s 2025-06-09T01:23:19.561Z,1749432199.561 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-06-09T01:23:19.562Z,1749432199.562 [DAT](INFO): direction in FSK: [-0.747478,-0.204487,0.632029] 2025-06-09T01:23:19.563Z,1749432199.563 [DAT](INFO): publishing direction and range info 2025-06-09T01:23:31.586Z,1749432211.586 [DAT](INFO): DAT read: user:1103>Rx Time:01:23:31.6241 2025-06-09T01:23:31.586Z,1749432211.586 [DAT](INFO): Rx dataTimestamp_ set to:1749432211.585880 2025-06-09T01:23:32.367Z,1749432212.367 [DAT](INFO): DAT read: 01:23:31.6242 LVL= 9840, 16881, 15698, 14035, AGC= 46, IDX= 231,-0.17, 1.897,-2.521, 2.685,-2.232, PHS=-2.065,-0.242,-1.369, RAW= 307.8, 39.2, CAL= 315.0, 39.3, ROT= 195.0, -39.3 2025-06-09T01:23:32.368Z,1749432212.368 [DAT](INFO): got valid direction response: 01:23:31.6242 LVL= 9840, 16881, 15698, 14035, AGC= 46, IDX= 231,-0.17, 1.897,-2.521, 2.685,-2.232, PHS=-2.065,-0.242,-1.369, RAW= 307.8, 39.2, CAL= 315.0, 39.3, ROT= 195.0, -39.3 2025-06-09T01:23:32.369Z,1749432212.369 [DAT](INFO): DAT read: 2025-06-09T01:23:32.370Z,1749432212.370 [DAT](INFO): DAT read: DATA(0026):run Engineering/OnDock.tl 2025-06-09T01:23:32.370Z,1749432212.370 [DAT](INFO): Got DATA 26 2025-06-09T01:23:32.371Z,1749432212.371 [DAT](INFO): DAT read: 2025-06-09T01:23:32.372Z,1749432212.372 [DAT](INFO): DAT read: Source:050 Destination:010 2025-06-09T01:23:32.372Z,1749432212.372 [DAT](INFO): Got Src/Dest after DATA 2025-06-09T01:23:32.373Z,1749432212.373 [DAT](INFO): DATA Src=50, Dst=10 2025-06-09T01:23:32.374Z,1749432212.374 [DAT](INFO): DAT read: CRC:Pass MPD:03.7 PSNR:15.6 AGC:47 SPD:-0.1 CCERR:011 2025-06-09T01:23:32.375Z,1749432212.375 [DAT](INFO): Got CRC:Pass 2025-06-09T01:23:32.375Z,1749432212.375 [DAT](INFO): Got CRC:Pass 2025-06-09T01:23:32.375Z,1749432212.375 [DAT](INFO): Incoming data is intended for us 2025-06-09T01:23:32.375Z,1749432212.375 [DAT](INFO): Received command: run Engineering/OnDock.tl 2025-06-09T01:23:32.375Z,1749432212.375 [DAT](INFO): #Outgoing data=1 2025-06-09T01:23:32.376Z,1749432212.376 [DAT](INFO): Sending ack 2025-06-09T01:23:32.376Z,1749432212.376 [DAT](INFO): DAT read: 2025-06-09T01:23:32.377Z,1749432212.377 [DAT](INFO): DAT read: 2025-06-09T01:23:32.378Z,1749432212.378 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2025-06-09T01:23:32.378Z,1749432212.378 [DAT](INFO): #Rx 2: Read direction message, but no range. 2025-06-09T01:23:32.379Z,1749432212.379 [DAT](INFO): direction in FSK: [-0.747472,-0.200285,0.633381] 2025-06-09T01:23:32.466Z,1749432212.466 [CommandExec](IMPORTANT): got command run ./Missions/Engineering/OnDock.tl 2025-06-09T01:23:32.466Z,1749432212.466 [MissionManager](INFO): Uninitializing Mission OnDock 2025-06-09T01:23:32.469Z,1749432212.469 [OnDock:dataRead:A] Stopped 2025-06-09T01:23:32.469Z,1749432212.469 [OnDock:dataRead:B.Wait] Running Loop=1 2025-06-09T01:23:32.469Z,1749432212.469 [OnDock:dataRead:B.Wait](DEBUG): Initialize Wait Component. 2025-06-09T01:23:32.471Z,1749432212.471 [OnDock] Stopped 2025-06-09T01:23:32.471Z,1749432212.471 [OnDock](DEBUG): Aggregate::uninitialize OnDock 2025-06-09T01:23:32.471Z,1749432212.471 [OnDock:StandardEnvelopes] Stopped 2025-06-09T01:23:32.471Z,1749432212.471 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes 2025-06-09T01:23:32.471Z,1749432212.471 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-06-09T01:23:32.471Z,1749432212.471 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-06-09T01:23:32.471Z,1749432212.471 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-06-09T01:23:32.471Z,1749432212.471 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-06-09T01:23:32.471Z,1749432212.471 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-06-09T01:23:32.471Z,1749432212.471 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-06-09T01:23:32.476Z,1749432212.476 [OnDock:BackseatDriver] Stopped 2025-06-09T01:23:32.476Z,1749432212.476 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver 2025-06-09T01:23:32.476Z,1749432212.476 [OnDock:BackseatDriver:A.BackseatDriver] Stopped 2025-06-09T01:23:32.476Z,1749432212.476 [OnDock:PowerOnly] Stopped 2025-06-09T01:23:32.476Z,1749432212.476 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly 2025-06-09T01:23:32.476Z,1749432212.476 [OnDock:PowerOnly:E.Wait] Stopped 2025-06-09T01:23:32.476Z,1749432212.476 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-06-09T01:23:32.476Z,1749432212.476 [OnDock:dataRead] Stopped 2025-06-09T01:23:32.477Z,1749432212.477 [OnDock:dataRead](DEBUG): Aggregate::uninitialize OnDock:dataRead 2025-06-09T01:23:32.477Z,1749432212.477 [OnDock:dataRead:B.Wait] Stopped 2025-06-09T01:23:32.477Z,1749432212.477 [OnDock:dataRead:B.Wait](DEBUG): Uninitialize Wait Component. 2025-06-09T01:23:32.477Z,1749432212.477 [OnDock:J.] Stopped 2025-06-09T01:23:32.477Z,1749432212.477 [OnDock:J.](DEBUG): Uninitializing TrackAcousticContact. 2025-06-09T01:23:32.477Z,1749432212.477 [OnDock:PeriodicCheck] Stopped 2025-06-09T01:23:32.477Z,1749432212.477 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck 2025-06-09T01:23:32.477Z,1749432212.477 [OnDock:PeriodicCheck:CheckIP] Stopped 2025-06-09T01:23:32.477Z,1749432212.477 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP 2025-06-09T01:23:32.477Z,1749432212.477 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped 2025-06-09T01:23:32.477Z,1749432212.477 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component. 2025-06-09T01:23:32.478Z,1749432212.478 [OnDock:N.Docked] Stopped 2025-06-09T01:23:32.593Z,1749432212.593 [DAT](INFO): setting remote address to 50 2025-06-09T01:23:32.798Z,1749432212.798 [MissionManager](INFO): Loading Mission from file: ./Missions/Engineering/OnDock.tl 2025-06-09T01:23:32.799Z,1749432212.799 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Engineering/OnDock.tl 2025-06-09T01:23:32.815Z,1749432212.815 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Engineering/OnDock.tl 2025-06-09T01:23:32.844Z,1749432212.844 [DAT](INFO): DAT read: 2025-06-09T01:23:32.845Z,1749432212.845 [DAT](INFO): DAT read: RemoteAddr | 50 2025-06-09T01:23:32.846Z,1749432212.846 [DAT](INFO): set remote address to 50 2025-06-09T01:23:32.846Z,1749432212.846 [DAT](INFO): DAT read: user:1104> 2025-06-09T01:23:32.847Z,1749432212.847 [DAT](INFO): DAT read: Tx time:01:23:33.4383 2025-06-09T01:23:32.847Z,1749432212.847 [DAT](INFO): Ping request sent. 2025-06-09T01:23:32.847Z,1749432212.847 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:23:32.848Z,1749432212.848 [DAT](INFO): entering online mode 2025-06-09T01:23:33.096Z,1749432213.096 [DAT](DEBUG): checking for online mode acknowledgment 2025-06-09T01:23:33.348Z,1749432213.348 [DAT](DEBUG): checking for online mode acknowledgment 2025-06-09T01:23:33.601Z,1749432213.601 [DAT](DEBUG): checking for online mode acknowledgment 2025-06-09T01:23:33.852Z,1749432213.852 [DAT](DEBUG): checking for online mode acknowledgment 2025-06-09T01:23:34.105Z,1749432214.105 [DAT](DEBUG): checking for online mode acknowledgment 2025-06-09T01:23:34.356Z,1749432214.356 [DAT](DEBUG): checking for online mode acknowledgment 2025-06-09T01:23:34.608Z,1749432214.608 [DAT](DEBUG): checking for online mode acknowledgment 2025-06-09T01:23:34.860Z,1749432214.860 [DAT](DEBUG): checking for online mode acknowledgment 2025-06-09T01:23:35.113Z,1749432215.113 [DAT](INFO): DAT read: Rx Time:01:23:35.3242 2025-06-09T01:23:35.113Z,1749432215.113 [DAT](INFO): Rx dataTimestamp_ set to:1749432215.113080 2025-06-09T01:23:35.114Z,1749432215.114 [DAT](DEBUG): checking for online mode acknowledgment 2025-06-09T01:23:35.364Z,1749432215.364 [DAT](DEBUG): checking for online mode acknowledgment 2025-06-09T01:23:35.618Z,1749432215.618 [DAT](DEBUG): checking for online mode acknowledgment 2025-06-09T01:23:35.874Z,1749432215.874 [DAT](INFO): DAT read: 01:23:35.3242 LVL= 10432, 11361, 12050, 14611, AGC= 48, IDX= 71, 0.38,-2.312,-0.938,-1.947,-0.553, PHS=-1.672,-0.338,-1.397, RAW= 318.8, 44.6, CAL= 323.0, 44.1, ROT= 187.0, -44.1 2025-06-09T01:23:35.875Z,1749432215.875 [DAT](INFO): got valid direction response: 01:23:35.3242 LVL= 10432, 11361, 12050, 14611, AGC= 48, IDX= 71, 0.38,-2.312,-0.938,-1.947,-0.553, PHS=-1.672,-0.338,-1.397, RAW= 318.8, 44.6, CAL= 323.0, 44.1, ROT= 187.0, -44.1 2025-06-09T01:23:35.875Z,1749432215.875 [DAT](INFO): DAT read: OK 2025-06-09T01:23:35.876Z,1749432215.876 [DAT](INFO): unknown deviceResponse_: OK 2025-06-09T01:23:35.876Z,1749432215.876 [DAT](INFO): DAT read: user:1105> 2025-06-09T01:23:35.877Z,1749432215.877 [DAT](INFO): DAT read: 2025-06-09T01:23:35.878Z,1749432215.878 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-06-09T01:23:35.878Z,1749432215.878 [DAT](INFO): commRate: 600 2025-06-09T01:23:35.878Z,1749432215.878 [DAT](INFO): online mode acknowledged 2025-06-09T01:23:35.879Z,1749432215.879 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2025-06-09T01:23:35.879Z,1749432215.879 [DAT](INFO): #Rx 3: Read direction message, but no range. 2025-06-09T01:23:35.880Z,1749432215.880 [DAT](INFO): direction in FSK: [-0.712774,-0.087518,0.695913] 2025-06-09T01:23:37.936Z,1749432217.936 [DAT](INFO): entering command mode 2025-06-09T01:23:38.137Z,1749432218.137 [DAT](INFO): DAT read: 2025-06-09T01:23:38.137Z,1749432218.137 [DAT](INFO): DAT read: user:1106> 2025-06-09T01:23:38.225Z,1749432218.225 [MissionManager](DEBUG): TethyslAPI: ./Missions/Engineering/OnDock.tl translated into: 8 2 1 5 2 2 5 5 Restarting logs restart logs ! ifconfig Mission ending, breaking off. Range: . 2025-06-09T01:23:38.232Z,1749432218.232 [MissionManager](INFO): DefineArg OnDock.DockedTime = 8.000000 h 2025-06-09T01:23:38.237Z,1749432218.237 [MissionManager](INFO): DefineArg OnDock.DetachedTimeout = 30.000000 s 2025-06-09T01:23:38.241Z,1749432218.241 [MissionManager](INFO): DefineArg OnDock.RangeTimeout = 600.000000 s 2025-06-09T01:23:38.245Z,1749432218.245 [MissionManager](INFO): DefineArg OnDock.TrackingUpdatePeriod = 2.000000 min 2025-06-09T01:23:38.248Z,1749432218.248 [MissionManager](INFO): DefineArg OnDock.NumberOfPings = 1.000000 count 2025-06-09T01:23:38.253Z,1749432218.253 [MissionManager](INFO): DefineArg OnDock.TransponderCode = 50 count 2025-06-09T01:23:38.257Z,1749432218.257 [MissionManager](INFO): DefineArg OnDock.MaxDepth = 215.000000 m 2025-06-09T01:23:38.260Z,1749432218.260 [MissionManager](INFO): DefineArg OnDock.MinAltitude = 5.000000 m 2025-06-09T01:23:38.262Z,1749432218.262 [MissionManager](INFO): DefineArg OnDock.MinOffshore = 2.000000 km 2025-06-09T01:23:38.296Z,1749432218.296 [MissionManager](INFO): DefineArg OnDock.StayOnDock = 0 bool 2025-06-09T01:23:38.298Z,1749432218.298 [MissionManager](INFO): DefineArg OnDock.IfconfigInterval = 2.000000 h 2025-06-09T01:23:38.301Z,1749432218.301 [MissionManager](INFO): DefineArg OnDock.RunScience = 0 bool 2025-06-09T01:23:38.303Z,1749432218.303 [MissionManager](INFO): DefineArg OnDock.MissionTimeout = 8.000000 h 2025-06-09T01:23:38.316Z,1749432218.316 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-06-09T01:23:38.316Z,1749432218.316 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-06-09T01:23:38.317Z,1749432218.317 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-06-09T01:23:38.389Z,1749432218.389 [DAT](INFO): DAT read: user:1106> 2025-06-09T01:23:38.390Z,1749432218.390 [DAT](INFO): DAT read: Tx time:01:23:38.9883 2025-06-09T01:23:38.390Z,1749432218.390 [DAT](INFO): Ping request sent. 2025-06-09T01:23:38.390Z,1749432218.390 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:23:38.390Z,1749432218.390 [DAT](INFO): publishing transmit ping time 2025-06-09T01:23:38.554Z,1749432218.554 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 1.5 200 2000 2025-06-09T01:23:38.558Z,1749432218.558 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinAltitude = 5.000000 m 2025-06-09T01:23:38.560Z,1749432218.560 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepthIgnore = 1.500000 m 2025-06-09T01:23:38.563Z,1749432218.563 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MaxDepth = 200.000000 m 2025-06-09T01:23:38.565Z,1749432218.565 [MissionManager](INFO): DefineArg OnDock:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-06-09T01:23:38.566Z,1749432218.566 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-06-09T01:23:38.572Z,1749432218.572 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-06-09T01:23:38.579Z,1749432218.579 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-06-09T01:23:38.590Z,1749432218.590 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-06-09T01:23:38.590Z,1749432218.590 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-06-09T01:23:38.591Z,1749432218.591 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-06-09T01:23:38.654Z,1749432218.654 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-06-09T01:23:38.657Z,1749432218.657 [MissionManager](INFO): DefineArg OnDock:BackseatDriver.EnableBackseat = 0 bool 2025-06-09T01:23:38.659Z,1749432218.659 [OnDock:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-06-09T01:23:38.662Z,1749432218.662 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-06-09T01:23:38.662Z,1749432218.662 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-06-09T01:23:38.663Z,1749432218.663 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-06-09T01:23:38.871Z,1749432218.871 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-06-09T01:23:38.879Z,1749432218.879 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleAll = 0 bool 2025-06-09T01:23:38.885Z,1749432218.885 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad1 = 0 bool 2025-06-09T01:23:38.887Z,1749432218.887 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad2 = 0 bool 2025-06-09T01:23:38.934Z,1749432218.934 [MissionManager](INFO): DefineArg OnDock:PowerOnly.SampleLoad3 = 0 bool 2025-06-09T01:23:38.937Z,1749432218.937 [MissionManager](INFO): DefineArg OnDock:PowerOnly.EnabledPowerOnly = 0 bool 2025-06-09T01:23:38.953Z,1749432218.953 [OnDock:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-06-09T01:23:38.965Z,1749432218.965 [OnDock:dataRead:B.Wait](DEBUG): Construct Wait. 2025-06-09T01:23:38.967Z,1749432218.967 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl 2025-06-09T01:23:38.967Z,1749432218.967 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/Science.tl 2025-06-09T01:23:38.982Z,1749432218.982 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/Science.tl 2025-06-09T01:23:40.657Z,1749432220.657 [DAT](INFO): DAT read: Rx Time:01:23:40.8747 2025-06-09T01:23:40.657Z,1749432220.657 [DAT](INFO): Rx dataTimestamp_ set to:1749432220.657064 2025-06-09T01:23:41.418Z,1749432221.418 [DAT](INFO): DAT read: 01:23:40.8747 LVL= 11760, 15921, 15202, 15955, AGC= 46, IDX= 424, 0.34,-2.000,-0.268,-1.222, 0.139, PHS=-2.052,-0.360,-1.364, RAW= 306.2, 42.2, CAL= 313.6, 42.1, ROT= 196.4, -42.1 2025-06-09T01:23:41.419Z,1749432221.419 [DAT](INFO): got valid direction response: 01:23:40.8747 LVL= 11760, 15921, 15202, 15955, AGC= 46, IDX= 424, 0.34,-2.000,-0.268,-1.222, 0.139, PHS=-2.052,-0.360,-1.364, RAW= 306.2, 42.2, CAL= 313.6, 42.1, ROT= 196.4, -42.1 2025-06-09T01:23:41.419Z,1749432221.419 [DAT](INFO): DAT read: OK 2025-06-09T01:23:41.419Z,1749432221.419 [DAT](INFO): unknown deviceResponse_: OK 2025-06-09T01:23:41.420Z,1749432221.420 [DAT](INFO): DAT read: user:1107> 2025-06-09T01:23:41.421Z,1749432221.421 [DAT](INFO): DAT read: Tx time:01:23:41.9883 2025-06-09T01:23:41.421Z,1749432221.421 [DAT](INFO): Ping request sent. 2025-06-09T01:23:41.421Z,1749432221.421 [DAT](INFO): transmitted an acoustic signal 2025-06-09T01:23:41.421Z,1749432221.421 [DAT](INFO): #Rx 4: Read direction message, but no range. 2025-06-09T01:23:41.422Z,1749432221.422 [DAT](INFO): direction in FSK: [-0.711788,-0.209491,0.670427] 2025-06-09T01:23:41.423Z,1749432221.423 [DAT](INFO): publishing transmit ping time 2025-06-09T01:23:41.426Z,1749432221.426 [DAT](INFO): publishing transmit ping time 2025-06-09T01:23:43.552Z,1749432223.552 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/Science.tl translated into: NaN 3 100 80 20 5 NaN NaN 2.0 NaN NaN 0 NaN NaN 5 0 0 0 0 NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN PeakDetectVsDepth EdgeDetectVsDepth GoDown, ChlPeakThresh, PeakChl, PeakChlDepth, EdgeChl, EdgeChlDepth = , , , , , PeakDetectVsDepth PeakDetectVsDepth PeakDetectVsDepth PeakDetectVsDepth PeakDetectVsDepth _.planktivore_LM_AvgRois EdgeDetectVsDepth _.planktivore_LM_AvgRois GoDown, PlanktivoreLMavgROIPeakThresh, PeakPlanktivoreLMavgROI, PeakPlanktivoreLMavgROIDepth, EdgePlanktivoreLMavgROI, EdgePlanktivoreLMavgROIDepth = , , , , , PeakDetectVsDepth _.planktivore_HM_AvgRois EdgeDetectVsDepth _.planktivore_HM_AvgRois GoDown, PlanktivoreHMavgROIPeakThresh, PeakPlanktivoreHMavgROI, PeakPlanktivoreHMavgROIDepth, EdgePlanktivoreHMavgROI, EdgePlanktivoreHMavgROIDepth = , , , , , PeakDetectVsDepth _.planktivore_diatoms EdgeDetectVsDepth _.planktivore_diatoms GoDown, PlanktivoreDiatomsPeakThresh, PeakPlanktivoreDiatoms, PeakPlanktivoreDiatomsDepth, EdgePlanktivoreDiatoms, EdgePlanktivoreDiatomsDepth = , , , , , PeakDetectVsDepth _.planktivore_dinoflagellates EdgeDetectVsDepth _.planktivore_dinoflagellates GoDown, PlanktivoreDinoflagellatesPeakThresh, PeakPlanktivoreDinoflagellates, PeakPlanktivoreDinoflagellatesDepth, EdgePlanktivoreDinoflagellates, EdgePlanktivoreDinoflagellatesDepth = , , , , , Triggered. PeakChl values on consecutive yo-yo profiles fall in [ ] (NaN bound is ignored). 2025-06-09T01:23:43.578Z,1749432223.578 [MissionManager](INFO): DefineArg OnDock:science:Science.PeakDetectChlActive = 0 bool 2025-06-09T01:23:43.581Z,1749432223.581 [MissionManager](INFO): DefineArg OnDock:science:Science.EdgeDetectChlActive = 0 bool 2025-06-09T01:23:43.583Z,1749432223.583 [MissionManager](INFO): DefineArg OnDock:science:Science.TimeWindowPeakReport = nan min 2025-06-09T01:23:43.585Z,1749432223.585 [MissionManager](INFO): DefineArg OnDock:science:Science.HighestChlPeakReportActive = 0 bool 2025-06-09T01:23:43.587Z,1749432223.587 [MissionManager](INFO): DefineArg OnDock:science:Science.HighestSaltPeakReportActive = 0 bool 2025-06-09T01:23:43.589Z,1749432223.589 [MissionManager](INFO): DefineArg OnDock:science:Science.HighestOilPeakReportActive = 0 bool 2025-06-09T01:23:43.591Z,1749432223.591 [MissionManager](INFO): DefineArg OnDock:science:Science.PatchTracking = 0 bool 2025-06-09T01:23:43.613Z,1749432223.613 [MissionManager](INFO): DefineArg OnDock:science:Science.FilterWidthHorizontal = 3.000000 count 2025-06-09T01:23:43.615Z,1749432223.615 [MissionManager](INFO): DefineArg OnDock:science:Science.NumProfilesSlidingwindow = 100.000000 count 2025-06-09T01:23:43.627Z,1749432223.627 [MissionManager](INFO): DefineArg OnDock:science:Science.OffPeakFractionHorizontal = 80.000000 % 2025-06-09T01:23:43.634Z,1749432223.634 [MissionManager](INFO): DefineArg OnDock:science:Science.PeakDetectNO3Active = 0 bool 2025-06-09T01:23:43.681Z,1749432223.681 [DAT](INFO): DAT read: Rx Time:01:23:43.8744 2025-06-09T01:23:43.681Z,1749432223.681 [DAT](INFO): Rx dataTimestamp_ set to:1749432223.681030 2025-06-09T01:23:43.682Z,1749432223.682 [MissionManager](INFO): DefineArg OnDock:science:Science.PeakDetectOilActive = 0 bool 2025-06-09T01:23:43.696Z,1749432223.696 [MissionManager](INFO): DefineArg OnDock:science:Science.PeakDetectFDOMActive = 0 bool 2025-06-09T01:23:43.698Z,1749432223.698 [MissionManager](INFO): DefineArg OnDock:science:Science.PeakDetectSalinityActive = 0 bool 2025-06-09T01:23:43.716Z,1749432223.716 [MissionManager](INFO): DefineArg OnDock:science:Science.PeakDetectPlanktivoreLMavgROIActive = 0 bool 2025-06-09T01:23:43.719Z,1749432223.719 [MissionManager](INFO): DefineArg OnDock:science:Science.EdgeDetectPlanktivoreLMavgROIActive = 0 bool 2025-06-09T01:23:43.726Z,1749432223.726 [MissionManager](INFO): DefineArg OnDock:science:Science.PeakDetectPlanktivoreHMavgROIActive = 0 bool 2025-06-09T01:23:43.734Z,1749432223.734 [MissionManager](INFO): DefineArg OnDock:science:Science.EdgeDetectPlanktivoreHMavgROIActive = 0 bool 2025-06-09T01:23:43.737Z,1749432223.737 [MissionManager](INFO): DefineArg OnDock:science:Science.PeakDetectPlanktivoreDiatomsActive = 0 bool 2025-06-09T01:23:43.739Z,1749432223.739 [MissionManager](INFO): DefineArg OnDock:science:Science.EdgeDetectPlanktivoreDiatomsActive = 0 bool 2025-06-09T01:23:43.758Z,1749432223.758 [MissionManager](INFO): DefineArg OnDock:science:Science.PeakDetectPlanktivoreDinoflagellatesActive = 0 bool 2025-06-09T01:23:43.769Z,1749432223.769 [MissionManager](INFO): DefineArg OnDock:science:Science.EdgeDetectPlanktivoreDinoflagellatesActive = 0 bool 2025-06-09T01:23:43.788Z,1749432223.788 [MissionManager](INFO): DefineArg OnDock:science:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2025-06-09T01:23:43.790Z,1749432223.790 [MissionManager](INFO): DefineArg OnDock:science:Science.EnabledAanderaaO2 = 0 bool 2025-06-09T01:23:43.793Z,1749432223.793 [MissionManager](INFO): DefineArg OnDock:science:Science.EnabledNeilBrown = 0 bool 2025-06-09T01:23:43.804Z,1749432223.804 [MissionManager](INFO): DefineArg OnDock:science:Science.EnabledRBRTridente = 0 bool 2025-06-09T01:23:43.807Z,1749432223.807 [MissionManager](INFO): DefineArg OnDock:science:Science.EnabledSeabird = 1 bool 2025-06-09T01:23:43.814Z,1749432223.814 [MissionManager](INFO): DefineArg OnDock:science:Science.EnabledWetLabsBB2FL = 1 bool 2025-06-09T01:23:43.821Z,1749432223.821 [MissionManager](INFO): DefineArg OnDock:science:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool 2025-06-09T01:23:43.823Z,1749432223.823 [MissionManager](INFO): DefineArg OnDock:science:Science.EnabledWetLabsUBAT = 1 bool 2025-06-09T01:23:43.830Z,1749432223.830 [MissionManager](INFO): DefineArg OnDock:science:Science.LowPassWindowLength = 20.000000 count 2025-06-09T01:23:43.844Z,1749432223.844 [MissionManager](INFO): DefineArg OnDock:science:Science.MedianFilterLen = 5.000000 count 2025-06-09T01:23:43.846Z,1749432223.846 [MissionManager](INFO): DefineArg OnDock:science:Science.ShallowBound = nan m 2025-06-09T01:23:43.849Z,1749432223.849 [MissionManager](INFO): DefineArg OnDock:science:Science.DeepBound = nan m 2025-06-09T01:23:43.881Z,1749432223.881 [MissionManager](INFO): DefineArg OnDock:science:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2025-06-09T01:23:43.911Z,1749432223.911 [MissionManager](INFO): DefineArg OnDock:science:Science.OffPeakFractionVerticalAscent = nan % 2025-06-09T01:23:43.929Z,1749432223.929 [MissionManager](INFO): DefineArg OnDock:science:Science.OffPeakFractionVerticalDescent = nan % 2025-06-09T01:23:43.931Z,1749432223.931 [MissionManager](INFO): DefineArg OnDock:science:Science.ChlPeakThresh = 0.000000 ug/l 2025-06-09T01:23:43.937Z,1749432223.937 [MissionManager](INFO): DefineArg OnDock:science:Science.ChlThreshTriggerActive = 0 bool 2025-06-09T01:23:43.939Z,1749432223.939 [MissionManager](INFO): DefineArg OnDock:science:Science.ChlLowerThresh = nan ug/l 2025-06-09T01:23:43.950Z,1749432223.950 [MissionManager](INFO): DefineArg OnDock:science:Science.ChlUpperThresh = nan ug/l 2025-06-09T01:23:43.956Z,1749432223.956 [MissionManager](INFO): DefineArg OnDock:science:Science.NumProfilesThresh = 5.000000 count 2025-06-09T01:23:43.959Z,1749432223.959 [MissionManager](INFO): DefineArg OnDock:science:Science.PlanktivoreLMavgROIPeakThresh = 0.000000 count/s 2025-06-09T01:23:43.965Z,1749432223.965 [MissionManager](INFO): DefineArg OnDock:science:Science.PlanktivoreHMavgROIPeakThresh = 0.000000 count/s 2025-06-09T01:23:43.967Z,1749432223.967 [MissionManager](INFO): DefineArg OnDock:science:Science.PlanktivoreDiatomsPeakThresh = 0.000000 count/ml 2025-06-09T01:23:43.971Z,1749432223.971 [MissionManager](INFO): DefineArg OnDock:science:Science.PlanktivoreDinoflagellatesPeakThresh = 0.000000 count/ml 2025-06-09T01:23:43.986Z,1749432223.986 [MissionManager](INFO): DefineOutput OnDock:science:Science.GoDown = 1 bool 2025-06-09T01:23:44.001Z,1749432224.001 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakChl = nan ug/l 2025-06-09T01:23:44.003Z,1749432224.003 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakChlDepth = nan m 2025-06-09T01:23:44.010Z,1749432224.010 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakChlTemperature = nan degC 2025-06-09T01:23:44.016Z,1749432224.016 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakChlLatitude = nan arcdeg 2025-06-09T01:23:44.018Z,1749432224.018 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakChlLongitude = nan arcdeg 2025-06-09T01:23:44.021Z,1749432224.021 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgeChl = nan ug/l 2025-06-09T01:23:44.036Z,1749432224.036 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgeChlDepth = nan m 2025-06-09T01:23:44.038Z,1749432224.038 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgeChlTemperature = nan degC 2025-06-09T01:23:44.097Z,1749432224.097 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgeChlLatitude = nan arcdeg 2025-06-09T01:23:44.099Z,1749432224.099 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgeChlLongitude = nan arcdeg 2025-06-09T01:23:44.106Z,1749432224.106 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakNO3 = nan umol/l 2025-06-09T01:23:44.113Z,1749432224.113 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakNO3Depth = nan m 2025-06-09T01:23:44.117Z,1749432224.117 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakNO3Latitude = nan arcdeg 2025-06-09T01:23:44.119Z,1749432224.119 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakNO3Longitude = nan arcdeg 2025-06-09T01:23:44.130Z,1749432224.130 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakOil = nan kg/m3 2025-06-09T01:23:44.137Z,1749432224.137 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakOilDepth = nan m 2025-06-09T01:23:44.139Z,1749432224.139 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakOilLatitude = nan arcdeg 2025-06-09T01:23:44.155Z,1749432224.155 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakOilLongitude = nan arcdeg 2025-06-09T01:23:44.169Z,1749432224.169 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakFDOM = nan ppb 2025-06-09T01:23:44.201Z,1749432224.201 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakFDOMDepth = nan m 2025-06-09T01:23:44.223Z,1749432224.223 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakFDOMTemperature = nan degC 2025-06-09T01:23:44.245Z,1749432224.245 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakFDOMLatitude = nan arcdeg 2025-06-09T01:23:44.247Z,1749432224.247 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakFDOMLongitude = nan arcdeg 2025-06-09T01:23:44.255Z,1749432224.255 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakSalt = nan psu 2025-06-09T01:23:44.270Z,1749432224.270 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakSaltDepth = nan m 2025-06-09T01:23:44.278Z,1749432224.278 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakSaltTemperature = nan degC 2025-06-09T01:23:44.293Z,1749432224.293 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakSaltLatitude = nan arcdeg 2025-06-09T01:23:44.295Z,1749432224.295 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakSaltLongitude = nan arcdeg 2025-06-09T01:23:44.302Z,1749432224.302 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreLMavgROI = nan count/s 2025-06-09T01:23:44.312Z,1749432224.312 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreLMavgROIDepth = nan m 2025-06-09T01:23:44.315Z,1749432224.315 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreLMavgROITemperature = nan degC 2025-06-09T01:23:44.321Z,1749432224.321 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreLMavgROILatitude = nan arcdeg 2025-06-09T01:23:44.324Z,1749432224.324 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreLMavgROILongitude = nan arcdeg 2025-06-09T01:23:44.326Z,1749432224.326 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreLMavgROI = nan count/s 2025-06-09T01:23:44.341Z,1749432224.341 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreLMavgROIDepth = nan m 2025-06-09T01:23:44.343Z,1749432224.343 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreLMavgROITemperature = nan degC 2025-06-09T01:23:44.351Z,1749432224.351 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreLMavgROILatitude = nan arcdeg 2025-06-09T01:23:44.357Z,1749432224.357 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreLMavgROILongitude = nan arcdeg 2025-06-09T01:23:44.364Z,1749432224.364 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreHMavgROI = nan count/s 2025-06-09T01:23:44.366Z,1749432224.366 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreHMavgROIDepth = nan m 2025-06-09T01:23:44.373Z,1749432224.373 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreHMavgROITemperature = nan degC 2025-06-09T01:23:44.375Z,1749432224.375 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreHMavgROILatitude = nan arcdeg 2025-06-09T01:23:44.382Z,1749432224.382 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreHMavgROILongitude = nan arcdeg 2025-06-09T01:23:44.400Z,1749432224.400 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreHMavgROI = nan count/s 2025-06-09T01:23:44.403Z,1749432224.403 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreHMavgROIDepth = nan m 2025-06-09T01:23:44.409Z,1749432224.409 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreHMavgROITemperature = nan degC 2025-06-09T01:23:44.416Z,1749432224.416 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreHMavgROILatitude = nan arcdeg 2025-06-09T01:23:44.418Z,1749432224.418 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreHMavgROILongitude = nan arcdeg 2025-06-09T01:23:44.425Z,1749432224.425 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreDiatoms = nan count/ml 2025-06-09T01:23:44.427Z,1749432224.427 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreDiatomsDepth = nan m 2025-06-09T01:23:44.434Z,1749432224.434 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreDiatomsTemperature = nan degC 2025-06-09T01:23:44.441Z,1749432224.441 [DAT](INFO): DAT read: 01:23:43.8744 LVL= 9376, 16689, 15218, 13811, AGC= 44, IDX= 441, 0.03,-2.758,-1.165,-2.179,-0.813, PHS=-1.857,-0.305,-1.369, RAW= 312.1, 42.3, CAL= 318.3, 41.9, ROT= 191.7, -41.9 2025-06-09T01:23:44.442Z,1749432224.442 [DAT](INFO): got valid direction response: 01:23:43.8744 LVL= 9376, 16689, 15218, 13811, AGC= 44, IDX= 441, 0.03,-2.758,-1.165,-2.179,-0.813, PHS=-1.857,-0.305,-1.369, RAW= 312.1, 42.3, CAL= 318.3, 41.9, ROT= 191.7, -41.9 2025-06-09T01:23:44.442Z,1749432224.442 [DAT](INFO): DAT read: OK 2025-06-09T01:23:44.442Z,1749432224.442 [DAT](INFO): unknown deviceResponse_: OK 2025-06-09T01:23:44.443Z,1749432224.443 [DAT](INFO): #Rx 5: Read direction message, but no range. 2025-06-09T01:23:44.444Z,1749432224.444 [DAT](INFO): direction in FSK: [-0.728847,-0.150937,0.667833] 2025-06-09T01:23:44.480Z,1749432224.480 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreDiatomsLatitude = nan arcdeg 2025-06-09T01:23:44.483Z,1749432224.483 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreDiatomsLongitude = nan arcdeg 2025-06-09T01:23:44.493Z,1749432224.493 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreDiatoms = nan count/ml 2025-06-09T01:23:44.504Z,1749432224.504 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreDiatomsDepth = nan m 2025-06-09T01:23:44.506Z,1749432224.506 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreDiatomsTemperature = nan degC 2025-06-09T01:23:44.528Z,1749432224.528 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreDiatomsLatitude = nan arcdeg 2025-06-09T01:23:44.531Z,1749432224.531 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreDiatomsLongitude = nan arcdeg 2025-06-09T01:23:44.542Z,1749432224.542 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreDinoflagellates = nan count/ml 2025-06-09T01:23:44.551Z,1749432224.551 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreDinoflagellatesDepth = nan m 2025-06-09T01:23:44.558Z,1749432224.558 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreDinoflagellatesTemperature = nan degC 2025-06-09T01:23:44.564Z,1749432224.564 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreDinoflagellatesLatitude = nan arcdeg 2025-06-09T01:23:44.572Z,1749432224.572 [MissionManager](INFO): DefineOutput OnDock:science:Science.PeakPlanktivoreDinoflagellatesLongitude = nan arcdeg 2025-06-09T01:23:44.575Z,1749432224.575 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreDinoflagellates = nan count/ml 2025-06-09T01:23:44.599Z,1749432224.599 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreDinoflagellatesDepth = nan m 2025-06-09T01:23:44.611Z,1749432224.611 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreDinoflagellatesTemperature = nan degC 2025-06-09T01:23:44.626Z,1749432224.626 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreDinoflagellatesLatitude = nan arcdeg 2025-06-09T01:23:44.632Z,1749432224.632 [MissionManager](INFO): DefineOutput OnDock:science:Science.EdgePlanktivoreDinoflagellatesLongitude = nan arcdeg 2025-06-09T01:23:44.634Z,1749432224.634 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchChl = nan ug/l 2025-06-09T01:23:44.637Z,1749432224.637 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchChlDepth = nan m 2025-06-09T01:23:44.639Z,1749432224.639 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchChlLatitude = nan arcdeg 2025-06-09T01:23:44.654Z,1749432224.654 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchChlLongitude = nan arcdeg 2025-06-09T01:23:44.675Z,1749432224.675 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchChlDistance = nan m 2025-06-09T01:23:44.677Z,1749432224.677 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchSalt = nan psu 2025-06-09T01:23:44.689Z,1749432224.689 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchSaltDepth = nan m 2025-06-09T01:23:44.691Z,1749432224.691 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchSaltLatitude = nan arcdeg 2025-06-09T01:23:44.702Z,1749432224.702 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchSaltLongitude = nan arcdeg 2025-06-09T01:23:44.708Z,1749432224.708 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchSaltDistance = nan m 2025-06-09T01:23:44.711Z,1749432224.711 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchOil = nan psu 2025-06-09T01:23:44.717Z,1749432224.717 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchOilDepth = nan m 2025-06-09T01:23:44.728Z,1749432224.728 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchOilLatitude = nan arcdeg 2025-06-09T01:23:44.731Z,1749432224.731 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchOilLongitude = nan arcdeg 2025-06-09T01:23:44.749Z,1749432224.749 [MissionManager](INFO): DefineOutput OnDock:science:Science.PatchOilDistance = nan m 2025-06-09T01:23:44.751Z,1749432224.751 [MissionManager](INFO): DefineOutput OnDock:science:Science.TriggeredOnChl = 0 bool 2025-06-09T01:23:44.758Z,1749432224.758 [MissionManager](INFO): DefineOutput OnDock:science:Science.ChlTriggering = nan ug/l 2025-06-09T01:23:44.804Z,1749432224.804 [OnDock:science:Science:PeakDetectChl:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-06-09T01:23:44.823Z,1749432224.823 [OnDock:science:Science:EdgeDetectChl:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-06-09T01:23:44.954Z,1749432224.954 [OnDock:science:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2025-06-09T01:23:44.999Z,1749432224.999 [OnDock:science:Science:PeakDetectNO3:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-06-09T01:23:45.046Z,1749432225.046 [OnDock:science:Science:PeakDetectOil:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-06-09T01:23:45.093Z,1749432225.093 [OnDock:science:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2025-06-09T01:23:45.134Z,1749432225.134 [OnDock:science:Science:PeakDetectFDOM:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-06-09T01:23:45.167Z,1749432225.167 [OnDock:science:Science:PeakDetectSalinity:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-06-09T01:23:45.187Z,1749432225.187 [OnDock:science:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2025-06-09T01:23:45.226Z,1749432225.226 [OnDock:science:Science:PeakDetectPlanktivoreLMavgROI:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-06-09T01:23:45.251Z,1749432225.251 [OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-06-09T01:23:45.360Z,1749432225.360 [OnDock:science:Science:PeakDetectPlanktivoreHMavgROI:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-06-09T01:23:45.397Z,1749432225.397 [OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-06-09T01:23:45.454Z,1749432225.454 [OnDock:science:Science:PeakDetectPlanktivoreDiatoms:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-06-09T01:23:45.483Z,1749432225.483 [OnDock:science:Science:EdgeDetectPlanktivoreDiatoms:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-06-09T01:23:45.549Z,1749432225.549 [OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-06-09T01:23:45.574Z,1749432225.574 [OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-06-09T01:23:45.623Z,1749432225.623 [OnDock:science:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect. 2025-06-09T01:23:45.716Z,1749432225.716 [OnDock:L.Execute](DEBUG): Construct Execute. 2025-06-09T01:23:45.719Z,1749432225.719 [OnDock:PeriodicCheck:CheckIP:A.Execute](DEBUG): Construct Execute. 2025-06-09T01:23:45.733Z,1749432225.733 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Construct Wait. 2025-06-09T01:23:45.734Z,1749432225.734 [OnDock:N.Docked](DEBUG): Construct. 2025-06-09T01:23:45.735Z,1749432225.735 [OnDock:N.Docked](DEBUG): Initializing internal variables to default values. 2025-06-09T01:23:45.780Z,1749432225.780 [OnDock:Detach:B.Undock](DEBUG): Construct. 2025-06-09T01:23:45.780Z,1749432225.780 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2025-06-09T01:23:45.950Z,1749432225.950 [DAT](FAULT): Buffer send receipt timeout failure. 2025-06-09T01:23:45.950Z,1749432225.950 [DAT](DEBUG): In sendingTransmitVerify, timeout so go online and set commsState_ = SENDING_FILL_BUFFER 2025-06-09T01:23:45.958Z,1749432225.958 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission { """ Wait on dock, communicating when possible """ arguments { DockedTime = 8 hour """ Time to stay on dock. Mission timeout will be five minutes longer. """ DetachedTimeout = Dock:Docked.detachTimeout """ Time duration limit for determining if the vehicle is detached from the dock. """ RangeTimeout = Dock:Docked.rangeTimeout """ Maximum allowed time without acoustic range. """ TrackingUpdatePeriod = 2 minute """ How long to wait between acoustic queries. """ NumberOfPings = 1 count """ Number of pings requested each time. """ TransponderCode = Dock:Dock.transponderCode """ Transponder Address. """ MaxDepth = BIT:CBIT.stopDepth """ Maximum depth while docked. """ MinAltitude = 5 meter """ Minimum altitude """ MinOffshore = 2 km """ Minimum offshore """ StayOnDock = false """ If true, don't detach from dock at end of mission """ IfconfigInterval = 2 hour """ How long to wait between ifconfig runs """ RunScience = false """ If true, run Science while on the dock. """ # Mission variables (do not change) MissionTimeout = DockedTime """ Mission timeout (local variable, do not change) """ } timeout duration=MissionTimeout insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore # End docking 5 minutes before end of mission to allow clean undock assign in sequence MissionTimeout = DockedTime + 5 minute insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl # Send data when connection exists aggregate dataRead { run while ( true ) readDatum { Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } } aggregate science { run in parallel break if ( not RunScience ) insert Insert/Science.tl } behavior Estimation:TrackAcousticContact { run in parallel set contactLabelSetting = TransponderCode set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } syslog important "Restarting logs" behavior Guidance:Execute { run in sequence set command = "restart logs" } aggregate PeriodicCheck { run in parallel aggregate CheckIP { run in sequence behavior Guidance:Execute { run in sequence set command = "! ifconfig" } behavior Guidance:Wait { run in sequence set duration = IfconfigInterval } } } behavior Dock:Docked { run in sequence timeout duration=DockedTime set detachTimeout = DetachedTimeout set rangeTimeout = RangeTimeout } # Undock cleanly, instead of relying on DefaultWithUndock to come next aggregate Detach { run in sequence break if ( StayOnDock == true ) syslog important "Mission ending, breaking off. Range: " + Estimation:TrackAcousticContact.range_to_contact~meter + "." behavior Dock:Undock { run in sequence } } } 2025-06-09T01:23:45.965Z,1749432225.965 [CommandExec](IMPORTANT): Running ./Missions/Engineering/OnDock.tl 2025-06-09T01:23:46.119Z,1749432226.119 [MissionManager](IMPORTANT): Started mission OnDock 2025-06-09T01:23:46.119Z,1749432226.119 [OnDock] Running Loop=1 2025-06-09T01:23:46.119Z,1749432226.119 [OnDock](DEBUG): Aggregate::initialize OnDock 2025-06-09T01:23:46.119Z,1749432226.119 [OnDock:StandardEnvelopes] Running Loop=1 2025-06-09T01:23:46.119Z,1749432226.119 [OnDock:StandardEnvelopes](DEBUG): Aggregate::initialize OnDock:StandardEnvelopes 2025-06-09T01:23:46.119Z,1749432226.119 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-06-09T01:23:46.119Z,1749432226.119 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-06-09T01:23:46.120Z,1749432226.120 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-06-09T01:23:46.120Z,1749432226.120 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-06-09T01:23:46.120Z,1749432226.120 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-06-09T01:23:46.120Z,1749432226.120 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-06-09T01:23:46.120Z,1749432226.120 [OnDock:BackseatDriver] Running Loop=1 2025-06-09T01:23:46.120Z,1749432226.120 [OnDock:BackseatDriver](DEBUG): Aggregate::initialize OnDock:BackseatDriver 2025-06-09T01:23:46.121Z,1749432226.121 [OnDock:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-06-09T01:23:46.121Z,1749432226.121 [OnDock:PowerOnly] Running Loop=1 2025-06-09T01:23:46.121Z,1749432226.121 [OnDock:PowerOnly](DEBUG): Aggregate::initialize OnDock:PowerOnly 2025-06-09T01:23:46.121Z,1749432226.121 [OnDock:PowerOnly:A] Running Loop=1 2025-06-09T01:23:46.121Z,1749432226.121 [OnDock:PowerOnly:B] Running Loop=1 2025-06-09T01:23:46.121Z,1749432226.121 [OnDock:PowerOnly:C] Running Loop=1 2025-06-09T01:23:46.121Z,1749432226.121 [OnDock:PowerOnly:D] Running Loop=1 2025-06-09T01:23:46.121Z,1749432226.121 [OnDock:PowerOnly:E.Wait] Running Loop=1 2025-06-09T01:23:46.121Z,1749432226.121 [OnDock:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-06-09T01:23:46.121Z,1749432226.121 [OnDock:dataRead] Running Loop=1 2025-06-09T01:23:46.121Z,1749432226.121 [OnDock:dataRead](DEBUG): Aggregate::initialize OnDock:dataRead 2025-06-09T01:23:46.122Z,1749432226.122 [OnDock:dataRead:A] Running Loop=1 2025-06-09T01:23:46.122Z,1749432226.122 [OnDock:science] Running Loop=1 2025-06-09T01:23:46.122Z,1749432226.122 [OnDock:science](DEBUG): Aggregate::initialize OnDock:science 2025-06-09T01:23:46.122Z,1749432226.122 [OnDock:science:Science] Running Loop=1 2025-06-09T01:23:46.122Z,1749432226.122 [OnDock:science:Science](DEBUG): Aggregate::initialize OnDock:science:Science 2025-06-09T01:23:46.122Z,1749432226.122 [OnDock:science:Science:A] Running Loop=1 2025-06-09T01:23:46.122Z,1749432226.122 [OnDock:science:Science:B] Running Loop=1 2025-06-09T01:23:46.122Z,1749432226.122 [OnDock:science:Science:C] Running Loop=1 2025-06-09T01:23:46.122Z,1749432226.122 [OnDock:science:Science:D] Running Loop=1 2025-06-09T01:23:46.122Z,1749432226.122 [OnDock:science:Science:E] Running Loop=1 2025-06-09T01:23:46.122Z,1749432226.122 [OnDock:science:Science:F] Running Loop=1 2025-06-09T01:23:46.122Z,1749432226.122 [OnDock:science:Science:Read_Oil] Running Loop=1 2025-06-09T01:23:46.122Z,1749432226.122 [OnDock:science:Science:PeakDetectChl] Running Loop=1 2025-06-09T01:23:46.123Z,1749432226.123 [OnDock:science:Science:PeakDetectChl](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectChl 2025-06-09T01:23:46.123Z,1749432226.123 [OnDock:science:Science:PeakDetectChl:A] Running Loop=1 2025-06-09T01:23:46.123Z,1749432226.123 [OnDock:science:Science:EdgeDetectChl] Running Loop=1 2025-06-09T01:23:46.123Z,1749432226.123 [OnDock:science:Science:EdgeDetectChl](DEBUG): Aggregate::initialize OnDock:science:Science:EdgeDetectChl 2025-06-09T01:23:46.123Z,1749432226.123 [OnDock:science:Science:EdgeDetectChl:A] Running Loop=1 2025-06-09T01:23:46.123Z,1749432226.123 [OnDock:science:Science:HighestChlPeakReport] Running Loop=1 2025-06-09T01:23:46.123Z,1749432226.123 [OnDock:science:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize OnDock:science:Science:HighestChlPeakReport 2025-06-09T01:23:46.123Z,1749432226.123 [OnDock:science:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2025-06-09T01:23:46.123Z,1749432226.123 [OnDock:science:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2025-06-09T01:23:46.124Z,1749432226.124 [OnDock:science:Science:PeakDetectNO3] Running Loop=1 2025-06-09T01:23:46.124Z,1749432226.124 [OnDock:science:Science:PeakDetectNO3](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectNO3 2025-06-09T01:23:46.124Z,1749432226.124 [OnDock:science:Science:PeakDetectNO3:A] Running Loop=1 2025-06-09T01:23:46.124Z,1749432226.124 [OnDock:science:Science:PeakDetectOil] Running Loop=1 2025-06-09T01:23:46.124Z,1749432226.124 [OnDock:science:Science:PeakDetectOil](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectOil 2025-06-09T01:23:46.125Z,1749432226.125 [OnDock:science:Science:PeakDetectOil:A] Running Loop=1 2025-06-09T01:23:46.125Z,1749432226.125 [OnDock:science:Science:HighestOilPeakReport] Running Loop=1 2025-06-09T01:23:46.125Z,1749432226.125 [OnDock:science:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize OnDock:science:Science:HighestOilPeakReport 2025-06-09T01:23:46.125Z,1749432226.125 [OnDock:science:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2025-06-09T01:23:46.125Z,1749432226.125 [OnDock:science:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2025-06-09T01:23:46.125Z,1749432226.125 [OnDock:science:Science:PeakDetectFDOM] Running Loop=1 2025-06-09T01:23:46.125Z,1749432226.125 [OnDock:science:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectFDOM 2025-06-09T01:23:46.126Z,1749432226.126 [OnDock:science:Science:PeakDetectFDOM:A] Running Loop=1 2025-06-09T01:23:46.126Z,1749432226.126 [OnDock:science:Science:PeakDetectSalinity] Running Loop=1 2025-06-09T01:23:46.126Z,1749432226.126 [OnDock:science:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectSalinity 2025-06-09T01:23:46.126Z,1749432226.126 [OnDock:science:Science:PeakDetectSalinity:A] Running Loop=1 2025-06-09T01:23:46.126Z,1749432226.126 [OnDock:science:Science:HighestSaltPeakReport] Running Loop=1 2025-06-09T01:23:46.126Z,1749432226.126 [OnDock:science:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize OnDock:science:Science:HighestSaltPeakReport 2025-06-09T01:23:46.126Z,1749432226.126 [OnDock:science:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2025-06-09T01:23:46.126Z,1749432226.126 [OnDock:science:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2025-06-09T01:23:46.127Z,1749432226.127 [OnDock:science:Science:PeakDetectPlanktivoreLMavgROI] Running Loop=1 2025-06-09T01:23:46.127Z,1749432226.127 [OnDock:science:Science:PeakDetectPlanktivoreLMavgROI](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectPlanktivoreLMavgROI 2025-06-09T01:23:46.127Z,1749432226.127 [OnDock:science:Science:PeakDetectPlanktivoreLMavgROI:A] Running Loop=1 2025-06-09T01:23:46.127Z,1749432226.127 [OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI] Running Loop=1 2025-06-09T01:23:46.127Z,1749432226.127 [OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI](DEBUG): Aggregate::initialize OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI 2025-06-09T01:23:46.127Z,1749432226.127 [OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI:A] Running Loop=1 2025-06-09T01:23:46.127Z,1749432226.127 [OnDock:science:Science:PeakDetectPlanktivoreHMavgROI] Running Loop=1 2025-06-09T01:23:46.127Z,1749432226.127 [OnDock:science:Science:PeakDetectPlanktivoreHMavgROI](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectPlanktivoreHMavgROI 2025-06-09T01:23:46.127Z,1749432226.127 [OnDock:science:Science:PeakDetectPlanktivoreHMavgROI:A] Running Loop=1 2025-06-09T01:23:46.128Z,1749432226.128 [OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI] Running Loop=1 2025-06-09T01:23:46.128Z,1749432226.128 [OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI](DEBUG): Aggregate::initialize OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI 2025-06-09T01:23:46.128Z,1749432226.128 [OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI:A] Running Loop=1 2025-06-09T01:23:46.128Z,1749432226.128 [OnDock:science:Science:PeakDetectPlanktivoreDiatoms] Running Loop=1 2025-06-09T01:23:46.128Z,1749432226.128 [OnDock:science:Science:PeakDetectPlanktivoreDiatoms](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectPlanktivoreDiatoms 2025-06-09T01:23:46.128Z,1749432226.128 [OnDock:science:Science:PeakDetectPlanktivoreDiatoms:A] Running Loop=1 2025-06-09T01:23:46.128Z,1749432226.128 [OnDock:science:Science:EdgeDetectPlanktivoreDiatoms] Running Loop=1 2025-06-09T01:23:46.129Z,1749432226.129 [OnDock:science:Science:EdgeDetectPlanktivoreDiatoms](DEBUG): Aggregate::initialize OnDock:science:Science:EdgeDetectPlanktivoreDiatoms 2025-06-09T01:23:46.129Z,1749432226.129 [OnDock:science:Science:EdgeDetectPlanktivoreDiatoms:A] Running Loop=1 2025-06-09T01:23:46.129Z,1749432226.129 [OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates] Running Loop=1 2025-06-09T01:23:46.129Z,1749432226.129 [OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::initialize OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates 2025-06-09T01:23:46.129Z,1749432226.129 [OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates:A] Running Loop=1 2025-06-09T01:23:46.129Z,1749432226.129 [OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates] Running Loop=1 2025-06-09T01:23:46.129Z,1749432226.129 [OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::initialize OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates 2025-06-09T01:23:46.130Z,1749432226.130 [OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates:A] Running Loop=1 2025-06-09T01:23:46.130Z,1749432226.130 [OnDock:science:Science:ChlThreshTrigger] Running Loop=1 2025-06-09T01:23:46.130Z,1749432226.130 [OnDock:science:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize OnDock:science:Science:ChlThreshTrigger 2025-06-09T01:23:46.130Z,1749432226.130 [OnDock:science:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1 2025-06-09T01:23:46.130Z,1749432226.130 [OnDock:science:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize. 2025-06-09T01:23:46.130Z,1749432226.130 [OnDock:science:Science:OceanCurrent] Running Loop=1 2025-06-09T01:23:46.131Z,1749432226.131 [OnDock:science:Science:OceanCurrent](DEBUG): Aggregate::initialize OnDock:science:Science:OceanCurrent 2025-06-09T01:23:46.131Z,1749432226.131 [OnDock:science:Science:OceanCurrent:A.] Running Loop=1 2025-06-09T01:23:46.131Z,1749432226.131 [OnDock:science:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2025-06-09T01:23:46.131Z,1749432226.131 [OnDock:J.] Running Loop=1 2025-06-09T01:23:46.131Z,1749432226.131 [OnDock:J.](INFO): Initializing TrackAcousticContact. 2025-06-09T01:23:46.131Z,1749432226.131 [OnDock:PeriodicCheck] Running Loop=1 2025-06-09T01:23:46.131Z,1749432226.131 [OnDock:PeriodicCheck](DEBUG): Aggregate::initialize OnDock:PeriodicCheck 2025-06-09T01:23:46.131Z,1749432226.131 [OnDock:PeriodicCheck:CheckIP] Running Loop=1 2025-06-09T01:23:46.131Z,1749432226.131 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::initialize OnDock:PeriodicCheck:CheckIP 2025-06-09T01:23:46.131Z,1749432226.131 [OnDock:PeriodicCheck:CheckIP:A.Execute] Running Loop=1 2025-06-09T01:23:46.132Z,1749432226.132 [OnDock:B] Running Loop=1 2025-06-09T01:23:46.132Z,1749432226.132 [OnDock:PeriodicCheck] Running Loop=1 2025-06-09T01:23:46.132Z,1749432226.132 [OnDock:PeriodicCheck:CheckIP:A.Execute](INFO): Executing command ! ifconfig 2025-06-09T01:23:46.133Z,1749432226.133 [OnDock:PeriodicCheck:CheckIP:A.Execute] Stopped 2025-06-09T01:23:46.133Z,1749432226.133 [OnDock:PeriodicCheck:CheckIP:B.Wait] Running Loop=1 2025-06-09T01:23:46.133Z,1749432226.133 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Initialize Wait Component. 2025-06-09T01:23:46.133Z,1749432226.133 [OnDock:J.] Running Loop=1 2025-06-09T01:23:46.133Z,1749432226.133 [OnDock:J.](ERROR): Failed to read sound speed 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science] Stopped 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science](DEBUG): Aggregate::uninitialize OnDock:science 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science:Science] Stopped 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science:Science](DEBUG): Aggregate::uninitialize OnDock:science:Science 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science:Science:A] Stopped 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science:Science:B] Stopped 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science:Science:C] Stopped 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science:Science:D] Stopped 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science:Science:E] Stopped 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science:Science:F] Stopped 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science:Science:Read_Oil] Stopped 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science:Science:OceanCurrent] Stopped 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science:Science:OceanCurrent](DEBUG): Aggregate::uninitialize OnDock:science:Science:OceanCurrent 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science:Science:OceanCurrent:A.] Stopped 2025-06-09T01:23:46.134Z,1749432226.134 [OnDock:science:Science:PeakDetectChl] Stopped 2025-06-09T01:23:46.135Z,1749432226.135 [OnDock:science:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectChl 2025-06-09T01:23:46.135Z,1749432226.135 [OnDock:science:Science:PeakDetectChl:A] Stopped 2025-06-09T01:23:46.135Z,1749432226.135 [OnDock:science:Science:EdgeDetectChl] Stopped 2025-06-09T01:23:46.135Z,1749432226.135 [OnDock:science:Science:EdgeDetectChl](DEBUG): Aggregate::uninitialize OnDock:science:Science:EdgeDetectChl 2025-06-09T01:23:46.135Z,1749432226.135 [OnDock:science:Science:EdgeDetectChl:A] Stopped 2025-06-09T01:23:46.135Z,1749432226.135 [OnDock:science:Science:HighestChlPeakReport] Stopped 2025-06-09T01:23:46.135Z,1749432226.135 [OnDock:science:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize OnDock:science:Science:HighestChlPeakReport 2025-06-09T01:23:46.135Z,1749432226.135 [OnDock:science:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2025-06-09T01:23:46.135Z,1749432226.135 [OnDock:science:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2025-06-09T01:23:46.135Z,1749432226.135 [OnDock:science:Science:PeakDetectNO3] Stopped 2025-06-09T01:23:46.135Z,1749432226.135 [OnDock:science:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectNO3 2025-06-09T01:23:46.135Z,1749432226.135 [OnDock:science:Science:PeakDetectNO3:A] Stopped 2025-06-09T01:23:46.135Z,1749432226.135 [OnDock:science:Science:PeakDetectOil] Stopped 2025-06-09T01:23:46.135Z,1749432226.135 [OnDock:science:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectOil 2025-06-09T01:23:46.156Z,1749432226.156 [OnDock:science:Science:PeakDetectOil:A] Stopped 2025-06-09T01:23:46.156Z,1749432226.156 [OnDock:science:Science:HighestOilPeakReport] Stopped 2025-06-09T01:23:46.156Z,1749432226.156 [OnDock:science:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize OnDock:science:Science:HighestOilPeakReport 2025-06-09T01:23:46.156Z,1749432226.156 [OnDock:science:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2025-06-09T01:23:46.156Z,1749432226.156 [OnDock:science:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2025-06-09T01:23:46.156Z,1749432226.156 [OnDock:science:Science:PeakDetectFDOM] Stopped 2025-06-09T01:23:46.156Z,1749432226.156 [OnDock:science:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectFDOM 2025-06-09T01:23:46.156Z,1749432226.156 [OnDock:science:Science:PeakDetectFDOM:A] Stopped 2025-06-09T01:23:46.156Z,1749432226.156 [OnDock:science:Science:PeakDetectSalinity] Stopped 2025-06-09T01:23:46.157Z,1749432226.157 [OnDock:science:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectSalinity 2025-06-09T01:23:46.157Z,1749432226.157 [OnDock:science:Science:PeakDetectSalinity:A] Stopped 2025-06-09T01:23:46.157Z,1749432226.157 [OnDock:science:Science:HighestSaltPeakReport] Stopped 2025-06-09T01:23:46.157Z,1749432226.157 [OnDock:science:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize OnDock:science:Science:HighestSaltPeakReport 2025-06-09T01:23:46.157Z,1749432226.157 [OnDock:science:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2025-06-09T01:23:46.157Z,1749432226.157 [OnDock:science:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2025-06-09T01:23:46.157Z,1749432226.157 [OnDock:science:Science:PeakDetectPlanktivoreLMavgROI] Stopped 2025-06-09T01:23:46.157Z,1749432226.157 [OnDock:science:Science:PeakDetectPlanktivoreLMavgROI](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectPlanktivoreLMavgROI 2025-06-09T01:23:46.157Z,1749432226.157 [OnDock:science:Science:PeakDetectPlanktivoreLMavgROI:A] Stopped 2025-06-09T01:23:46.157Z,1749432226.157 [OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI] Stopped 2025-06-09T01:23:46.157Z,1749432226.157 [OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI](DEBUG): Aggregate::uninitialize OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI 2025-06-09T01:23:46.157Z,1749432226.157 [OnDock:science:Science:EdgeDetectPlanktivoreLMavgROI:A] Stopped 2025-06-09T01:23:46.157Z,1749432226.157 [OnDock:science:Science:PeakDetectPlanktivoreHMavgROI] Stopped 2025-06-09T01:23:46.158Z,1749432226.158 [OnDock:science:Science:PeakDetectPlanktivoreHMavgROI](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectPlanktivoreHMavgROI 2025-06-09T01:23:46.158Z,1749432226.158 [OnDock:science:Science:PeakDetectPlanktivoreHMavgROI:A] Stopped 2025-06-09T01:23:46.158Z,1749432226.158 [OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI] Stopped 2025-06-09T01:23:46.158Z,1749432226.158 [OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI](DEBUG): Aggregate::uninitialize OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI 2025-06-09T01:23:46.158Z,1749432226.158 [OnDock:science:Science:EdgeDetectPlanktivoreHMavgROI:A] Stopped 2025-06-09T01:23:46.158Z,1749432226.158 [OnDock:science:Science:PeakDetectPlanktivoreDiatoms] Stopped 2025-06-09T01:23:46.158Z,1749432226.158 [OnDock:science:Science:PeakDetectPlanktivoreDiatoms](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectPlanktivoreDiatoms 2025-06-09T01:23:46.158Z,1749432226.158 [OnDock:science:Science:PeakDetectPlanktivoreDiatoms:A] Stopped 2025-06-09T01:23:46.158Z,1749432226.158 [OnDock:science:Science:EdgeDetectPlanktivoreDiatoms] Stopped 2025-06-09T01:23:46.158Z,1749432226.158 [OnDock:science:Science:EdgeDetectPlanktivoreDiatoms](DEBUG): Aggregate::uninitialize OnDock:science:Science:EdgeDetectPlanktivoreDiatoms 2025-06-09T01:23:46.158Z,1749432226.158 [OnDock:science:Science:EdgeDetectPlanktivoreDiatoms:A] Stopped 2025-06-09T01:23:46.158Z,1749432226.158 [OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates] Stopped 2025-06-09T01:23:46.158Z,1749432226.158 [OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::uninitialize OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates 2025-06-09T01:23:46.159Z,1749432226.159 [OnDock:science:Science:PeakDetectPlanktivoreDinoflagellates:A] Stopped 2025-06-09T01:23:46.159Z,1749432226.159 [OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates] Stopped 2025-06-09T01:23:46.159Z,1749432226.159 [OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates](DEBUG): Aggregate::uninitialize OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates 2025-06-09T01:23:46.159Z,1749432226.159 [OnDock:science:Science:EdgeDetectPlanktivoreDinoflagellates:A] Stopped 2025-06-09T01:23:46.159Z,1749432226.159 [OnDock:science:Science:ChlThreshTrigger] Stopped 2025-06-09T01:23:46.159Z,1749432226.159 [OnDock:science:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize OnDock:science:Science:ChlThreshTrigger 2025-06-09T01:23:46.159Z,1749432226.159 [OnDock:science:Science:ChlThreshTrigger:A.ValueDetect] Stopped 2025-06-09T01:23:46.159Z,1749432226.159 [OnDock:science:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize. 2025-06-09T01:23:46.159Z,1749432226.159 [OnDock:dataRead] Running Loop=1 2025-06-09T01:23:46.160Z,1749432226.160 [OnDock:dataRead:A](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-06-09T01:23:46.160Z,1749432226.160 [OnDock:PowerOnly] Running Loop=1 2025-06-09T01:23:46.161Z,1749432226.161 [OnDock:PowerOnly:D] Stopped 2025-06-09T01:23:46.161Z,1749432226.161 [OnDock:PowerOnly:C] Stopped 2025-06-09T01:23:46.161Z,1749432226.161 [OnDock:PowerOnly:B] Stopped 2025-06-09T01:23:46.161Z,1749432226.161 [OnDock:PowerOnly:A] Stopped 2025-06-09T01:23:46.161Z,1749432226.161 [OnDock:BackseatDriver] Running Loop=1 2025-06-09T01:23:46.161Z,1749432226.161 [OnDock:B] Stopped 2025-06-09T01:23:46.161Z,1749432226.161 [OnDock:C] Running Loop=1 2025-06-09T01:23:46.161Z,1749432226.161 [OnDock:StandardEnvelopes] Running Loop=1 2025-06-09T01:23:46.161Z,1749432226.161 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-06-09T01:23:46.162Z,1749432226.162 [OnDock:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-06-09T01:23:46.162Z,1749432226.162 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-06-09T01:23:46.173Z,1749432226.173 [CommandExec](IMPORTANT): got command ! ifconfig 2025-06-09T01:23:46.202Z,1749432226.202 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2025-06-09T01:23:46.424Z,1749432226.424 [CommandExec](IMPORTANT): eth0 Link encap:Ethernet HWaddr 50:2D:F4:07:45:4B UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:11054 errors:0 dropped:0 overruns:0 frame:0 TX packets:21165 errors:0 dropped:0 overruns:0 carrier:0 collisions:151 txqueuelen:1000 RX bytes:4657480 (4.4 Mb) TX bytes:3204382 (3.0 Mb) Interrupt:29 lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 UP LOOPBACK RUNNING MULTICAST MTU:16436 Metric:1 RX packets:4499 errors:0 dropped:0 overruns:0 frame:0 TX packets:4499 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:0 RX bytes:404408 (394.9 Kb) TX bytes:404408 (394.9 Kb) 2025-06-09T01:23:46.453Z,1749432226.453 [DAT](INFO): entering online mode 2025-06-09T01:23:46.705Z,1749432226.705 [DAT](INFO): DAT read: user:1108> 2025-06-09T01:23:46.705Z,1749432226.705 [DAT](INFO): DAT read: 2025-06-09T01:23:46.707Z,1749432226.707 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-06-09T01:23:46.707Z,1749432226.707 [DAT](INFO): commRate: 600 2025-06-09T01:23:46.707Z,1749432226.707 [DAT](INFO): online mode acknowledged 2025-06-09T01:23:46.707Z,1749432226.707 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2025-06-09T01:23:46.810Z,1749432226.810 [OnDock:dataRead:A] Stopped 2025-06-09T01:23:46.810Z,1749432226.810 [OnDock:dataRead:B.Wait] Running Loop=1 2025-06-09T01:23:46.810Z,1749432226.810 [OnDock:dataRead:B.Wait](DEBUG): Initialize Wait Component. 2025-06-09T01:23:46.811Z,1749432226.811 [OnDock:C] Stopped 2025-06-09T01:23:46.816Z,1749432226.816 [OnDock:D] Running Loop=1 2025-06-09T01:23:47.237Z,1749432227.237 [OnDock:D] Stopped 2025-06-09T01:23:47.237Z,1749432227.237 [OnDock:E] Running Loop=1 2025-06-09T01:23:47.590Z,1749432227.590 [OnDock:E] Stopped 2025-06-09T01:23:47.590Z,1749432227.590 [OnDock:K] Running Loop=1 2025-06-09T01:23:48.025Z,1749432228.025 [OnDock:K](IMPORTANT): Restarting logs 2025-06-09T01:23:48.025Z,1749432228.025 [OnDock:K] Stopped 2025-06-09T01:23:48.025Z,1749432228.025 [OnDock:L.Execute] Running Loop=1 2025-06-09T01:23:48.393Z,1749432228.393 [OnDock:L.Execute](INFO): Executing command restart logs 2025-06-09T01:23:48.394Z,1749432228.394 [OnDock:L.Execute] Stopped 2025-06-09T01:23:48.394Z,1749432228.394 [OnDock:N.Docked] Running Loop=1 2025-06-09T01:23:48.394Z,1749432228.394 [OnDock:N.Docked](DEBUG): Initialize.