2025-08-09T17:46:16.462Z,1754761576.462 [OnDock:L.Execute](INFO): Executing command restart logs 2025-08-09T17:46:16.463Z,1754761576.463 [OnDock:L.Execute] Stopped 2025-08-09T17:46:16.463Z,1754761576.463 [OnDock:N.Docked] Running Loop=1 2025-08-09T17:46:16.463Z,1754761576.463 [OnDock:N.Docked](DEBUG): Initialize. 2025-08-09T17:46:16.463Z,1754761576.463 [OnDock:N.Docked](DEBUG): Initializing internal variables to default values. 2025-08-09T17:46:16.484Z,1754761576.484 [CommandExec](IMPORTANT): got command restart logs 2025-08-09T17:46:16.856Z,1754761576.856 [OnDock:N.Docked](INFO): No local IP found 2025-08-09T17:46:17.091Z,1754761577.091 [DAT](IMPORTANT): Requesting device enable set for address 50. 2025-08-09T17:46:17.343Z,1754761577.343 [DAT](INFO): DAT read: user:718> 2025-08-09T17:46:17.344Z,1754761577.344 [DAT](INFO): DAT read: Tx time:17:46:16.7321 2025-08-09T17:46:17.344Z,1754761577.344 [DAT](INFO): Ping request sent. 2025-08-09T17:46:17.344Z,1754761577.344 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:46:17.345Z,1754761577.345 [DAT](INFO): publishing transmit ping time 2025-08-09T17:46:17.345Z,1754761577.345 [DAT](IMPORTANT): Requesting device enable set for address 50. 2025-08-09T17:46:17.595Z,1754761577.595 [DAT](IMPORTANT): Requesting device enable set for address 50. 2025-08-09T17:46:18.143Z,1754761578.143 [DockingStepper](FAULT): Timed out moving from: 12545 to: 14700 response: ÿ 2025-08-09T17:46:18.143Z,1754761578.143 [DockingStepper] Hardware Fault, FailCount= 4 2025-08-09T17:46:18.143Z,1754761578.143 [DockingStepper](ERROR): Hardware Fault 2025-08-09T17:46:18.147Z,1754761578.147 [CBIT](ERROR): Hardware Fault in component: DockingStepper 2025-08-09T17:46:18.515Z,1754761578.515 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2025-08-09T17:46:18.515Z,1754761578.515 [DockingStepper](INFO): Powering down 2025-08-09T17:46:19.611Z,1754761579.611 [DAT](INFO): DAT read: Rx Time:17:46:18.5927 2025-08-09T17:46:19.612Z,1754761579.612 [DAT](INFO): Rx dataTimestamp_ set to:1754761579.611401 2025-08-09T17:46:20.115Z,1754761580.115 [DAT](INFO): DAT read: OK 2025-08-09T17:46:20.116Z,1754761580.116 [DAT](INFO): unknown deviceResponse_: OK 2025-08-09T17:46:20.116Z,1754761580.116 [DAT](INFO): DAT read: user:719> 2025-08-09T17:46:20.367Z,1754761580.367 [DAT](INFO): DAT read: Tx time:17:46:19.6821 2025-08-09T17:46:20.368Z,1754761580.368 [DAT](INFO): Ping request sent. 2025-08-09T17:46:20.368Z,1754761580.368 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:46:20.368Z,1754761580.368 [DAT](INFO): publishing transmit ping time 2025-08-09T17:46:22.635Z,1754761582.635 [DAT](INFO): DAT read: Rx Time:17:46:21.5428 2025-08-09T17:46:22.636Z,1754761582.636 [DAT](INFO): Rx dataTimestamp_ set to:1754761582.635557 2025-08-09T17:46:23.139Z,1754761583.139 [DAT](INFO): DAT read: OK 2025-08-09T17:46:23.140Z,1754761583.140 [DAT](INFO): unknown deviceResponse_: OK 2025-08-09T17:46:23.140Z,1754761583.140 [DAT](INFO): DAT read: user:720> 2025-08-09T17:46:23.141Z,1754761583.141 [DAT](INFO): DAT read: Tx time:17:46:22.6321 2025-08-09T17:46:23.141Z,1754761583.141 [DAT](INFO): Ping request sent. 2025-08-09T17:46:23.141Z,1754761583.141 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:46:23.141Z,1754761583.141 [DAT](INFO): publishing transmit ping time 2025-08-09T17:46:25.659Z,1754761585.659 [DAT](INFO): DAT read: Rx Time:17:46:24.4929 2025-08-09T17:46:25.660Z,1754761585.660 [DAT](INFO): Rx dataTimestamp_ set to:1754761585.659636 2025-08-09T17:46:26.163Z,1754761586.163 [DAT](INFO): DAT read: OK 2025-08-09T17:46:26.164Z,1754761586.164 [DAT](INFO): unknown deviceResponse_: OK 2025-08-09T17:46:29.359Z,1754761589.359 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-08-09T17:46:39.079Z,1754761599.079 [CBIT](INFO): Clearing failed state for component DockingStepper 2025-08-09T17:46:39.079Z,1754761599.079 [DockingStepper] No Fault, FailCount= 4 2025-08-09T17:46:39.460Z,1754761599.460 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-08-09T17:46:39.579Z,1754761599.579 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-08-09T17:46:43.127Z,1754761603.127 [DockingStepper](INFO): Changing to mode: 1 2025-08-09T17:46:51.799Z,1754761611.799 [DockingStepper](FAULT): Timed out moving from: 12549 to: 14700 response: ÿ 2025-08-09T17:46:51.799Z,1754761611.799 [DockingStepper] Hardware Fault, FailCount= 5 2025-08-09T17:46:51.799Z,1754761611.799 [DockingStepper](ERROR): Hardware Fault 2025-08-09T17:46:51.803Z,1754761611.803 [CBIT](ERROR): Hardware Fault in component: DockingStepper 2025-08-09T17:46:51.804Z,1754761611.804 [CBIT](CRITICAL): Hardware Fault in component: DockingStepper 2025-08-09T17:46:52.144Z,1754761612.144 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2025-08-09T17:46:52.145Z,1754761612.145 [DockingStepper](INFO): Powering down 2025-08-09T17:46:52.148Z,1754761612.148 [CommandExec](FAULT): Scheduling is paused 2025-08-09T17:46:52.148Z,1754761612.148 [CBIT](INFO): Critical error at 20250809T174651 2025-08-09T17:46:52.148Z,1754761612.148 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-08-09T17:46:52.470Z,1754761612.470 [MissionManager](INFO): MissionManager is completed. 2025-08-09T17:46:52.470Z,1754761612.470 [MissionManager](INFO): Uninitializing Mission OnDock 2025-08-09T17:46:52.470Z,1754761612.470 [OnDock] Stopped 2025-08-09T17:46:52.471Z,1754761612.471 [OnDock](DEBUG): Aggregate::uninitialize OnDock 2025-08-09T17:46:52.471Z,1754761612.471 [OnDock:StandardEnvelopes] Stopped 2025-08-09T17:46:52.471Z,1754761612.471 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes 2025-08-09T17:46:52.471Z,1754761612.471 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-08-09T17:46:52.471Z,1754761612.471 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-08-09T17:46:52.471Z,1754761612.471 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-08-09T17:46:52.471Z,1754761612.471 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-08-09T17:46:52.471Z,1754761612.471 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-08-09T17:46:52.471Z,1754761612.471 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-08-09T17:46:52.471Z,1754761612.471 [OnDock:BackseatDriver] Stopped 2025-08-09T17:46:52.471Z,1754761612.471 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver 2025-08-09T17:46:52.472Z,1754761612.472 [OnDock:BackseatDriver:A.BackseatDriver] Stopped 2025-08-09T17:46:52.472Z,1754761612.472 [OnDock:PowerOnly] Stopped 2025-08-09T17:46:52.472Z,1754761612.472 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly 2025-08-09T17:46:52.472Z,1754761612.472 [OnDock:PowerOnly:E.Wait] Stopped 2025-08-09T17:46:52.472Z,1754761612.472 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-08-09T17:46:52.472Z,1754761612.472 [OnDock:dataRead] Stopped 2025-08-09T17:46:52.472Z,1754761612.472 [OnDock:dataRead](DEBUG): Aggregate::uninitialize OnDock:dataRead 2025-08-09T17:46:52.472Z,1754761612.472 [OnDock:dataRead:A] Stopped 2025-08-09T17:46:52.472Z,1754761612.472 [OnDock:J.] Stopped 2025-08-09T17:46:52.472Z,1754761612.472 [OnDock:J.](DEBUG): Uninitializing TrackAcousticContact. 2025-08-09T17:46:52.472Z,1754761612.472 [OnDock:PeriodicCheck] Stopped 2025-08-09T17:46:52.473Z,1754761612.473 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck 2025-08-09T17:46:52.473Z,1754761612.473 [OnDock:PeriodicCheck:CheckIP] Stopped 2025-08-09T17:46:52.473Z,1754761612.473 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP 2025-08-09T17:46:52.473Z,1754761612.473 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped 2025-08-09T17:46:52.473Z,1754761612.473 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component. 2025-08-09T17:46:52.473Z,1754761612.473 [OnDock:N.Docked] Stopped 2025-08-09T17:46:52.647Z,1754761612.647 [DAT](IMPORTANT): Requesting device enable clr for address 50. 2025-08-09T17:46:52.897Z,1754761612.897 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-08-09T17:46:52.897Z,1754761612.897 [DefaultWithUndock] Running Loop=1 2025-08-09T17:46:52.898Z,1754761612.898 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-08-09T17:46:52.899Z,1754761612.899 [DAT](INFO): DAT read: user:721> 2025-08-09T17:46:52.900Z,1754761612.900 [DAT](INFO): DAT read: Tx time:17:46:52.2820 2025-08-09T17:46:52.900Z,1754761612.900 [DAT](INFO): Ping request sent. 2025-08-09T17:46:52.900Z,1754761612.900 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:46:52.901Z,1754761612.901 [DAT](INFO): publishing transmit ping time 2025-08-09T17:46:52.901Z,1754761612.901 [DAT](IMPORTANT): Requesting device enable clr for address 50. 2025-08-09T17:46:52.914Z,1754761612.914 [DefaultWithUndock:A.Wait] Running Loop=1 2025-08-09T17:46:52.919Z,1754761612.919 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-08-09T17:46:53.151Z,1754761613.151 [DAT](IMPORTANT): Requesting device enable clr for address 50. 2025-08-09T17:46:55.171Z,1754761615.171 [DAT](INFO): DAT read: Rx Time:17:46:54.1437 2025-08-09T17:46:55.172Z,1754761615.172 [DAT](INFO): Rx dataTimestamp_ set to:1754761615.171535 2025-08-09T17:46:55.677Z,1754761615.677 [DAT](INFO): DAT read: OK 2025-08-09T17:46:55.678Z,1754761615.678 [DAT](INFO): unknown deviceResponse_: OK 2025-08-09T17:46:55.679Z,1754761615.679 [DAT](INFO): DAT read: user:722> 2025-08-09T17:46:55.680Z,1754761615.680 [DAT](INFO): DAT read: Tx time:17:46:55.2322 2025-08-09T17:46:55.680Z,1754761615.680 [DAT](INFO): Ping request sent. 2025-08-09T17:46:55.680Z,1754761615.680 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:46:55.680Z,1754761615.680 [DAT](INFO): publishing transmit ping time 2025-08-09T17:46:58.195Z,1754761618.195 [DAT](INFO): DAT read: Rx Time:17:46:57.0938 2025-08-09T17:46:58.196Z,1754761618.196 [DAT](INFO): Rx dataTimestamp_ set to:1754761618.195481 2025-08-09T17:46:58.699Z,1754761618.699 [DAT](INFO): DAT read: OK 2025-08-09T17:46:58.700Z,1754761618.700 [DAT](INFO): unknown deviceResponse_: OK 2025-08-09T17:46:58.700Z,1754761618.700 [DAT](INFO): DAT read: user:723> 2025-08-09T17:46:58.701Z,1754761618.701 [DAT](INFO): DAT read: Tx time:17:46:58.1822 2025-08-09T17:46:58.701Z,1754761618.701 [DAT](INFO): Ping request sent. 2025-08-09T17:46:58.701Z,1754761618.701 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:46:58.701Z,1754761618.701 [DAT](INFO): publishing transmit ping time 2025-08-09T17:47:01.219Z,1754761621.219 [DAT](INFO): DAT read: Rx Time:17:47:00.0439 2025-08-09T17:47:01.220Z,1754761621.220 [DAT](INFO): Rx dataTimestamp_ set to:1754761621.219510 2025-08-09T17:47:01.723Z,1754761621.723 [DAT](INFO): DAT read: OK 2025-08-09T17:47:01.723Z,1754761621.723 [DAT](INFO): unknown deviceResponse_: OK 2025-08-09T17:47:22.032Z,1754761642.032 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-08-09T17:47:22.108Z,1754761642.108 [CBIT](INFO): Clearing failed state for component DockingStepper 2025-08-09T17:47:22.108Z,1754761642.108 [DockingStepper] No Fault, FailCount= 5 2025-08-09T17:47:22.476Z,1754761642.476 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-08-09T17:47:22.595Z,1754761642.595 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-08-09T17:47:23.177Z,1754761643.177 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2025-08-09T17:47:23.177Z,1754761643.177 [DefaultWithUndock:A.Wait] Stopped 2025-08-09T17:47:23.177Z,1754761643.177 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-08-09T17:47:23.177Z,1754761643.177 [DefaultWithUndock:LeaveDock] Running Loop=1 2025-08-09T17:47:23.177Z,1754761643.177 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2025-08-09T17:47:23.177Z,1754761643.177 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-08-09T17:47:23.177Z,1754761643.177 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2025-08-09T17:47:23.178Z,1754761643.178 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2025-08-09T17:47:23.178Z,1754761643.178 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-08-09T17:47:23.178Z,1754761643.178 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-09T17:47:23.601Z,1754761643.601 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-08-09T17:47:23.601Z,1754761643.601 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-08-09T17:47:23.603Z,1754761643.603 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detaching from dock at depth 64.000458 m. 2025-08-09T17:47:23.989Z,1754761643.989 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Engaging thruster for 10.0 seconds (max) to disengage. 2025-08-09T17:47:26.140Z,1754761646.140 [DockingStepper](INFO): Changing to mode: 3 2025-08-09T17:47:30.512Z,1754761650.512 [DockingStepper](INFO): Failure count cleared after critical for DockingStepper 2025-08-09T17:47:30.880Z,1754761650.880 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Engaging thruster for 10.0 seconds (max) to disengage. 2025-08-09T17:47:32.860Z,1754761652.860 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,0002142134410 -21,A 2025-08-09T17:47:33.274Z,1754761653.274 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-09T17:47:33.483Z,1754761653.483 [DAT](DEBUG): Acoustic response timeout 2025-08-09T17:47:33.483Z,1754761653.483 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-09T17:47:33.736Z,1754761653.736 [DAT](INFO): DAT read: user:724> 2025-08-09T17:47:33.737Z,1754761653.737 [DAT](INFO): DAT read: Tx time:17:47:33.0829 2025-08-09T17:47:33.737Z,1754761653.737 [DAT](INFO): Ping request sent. 2025-08-09T17:47:33.737Z,1754761653.737 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:47:33.738Z,1754761653.738 [DAT](INFO): publishing transmit ping time 2025-08-09T17:47:33.739Z,1754761653.739 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001160 2025-08-09T17:47:33.987Z,1754761653.987 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.249778 2025-08-09T17:47:34.240Z,1754761654.240 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502089 2025-08-09T17:47:34.494Z,1754761654.494 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.756744 2025-08-09T17:47:34.743Z,1754761654.743 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005820 2025-08-09T17:47:34.995Z,1754761654.995 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258086 2025-08-09T17:47:35.247Z,1754761655.247 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.509893 2025-08-09T17:47:35.499Z,1754761655.499 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.761767 2025-08-09T17:47:35.751Z,1754761655.751 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013963 2025-08-09T17:47:36.003Z,1754761656.003 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266035 2025-08-09T17:47:36.255Z,1754761656.255 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.517800 2025-08-09T17:47:36.508Z,1754761656.508 [DAT](INFO): DAT read: Rx Time:17:47:35.4880 2025-08-09T17:47:36.509Z,1754761656.509 [DAT](INFO): Rx dataTimestamp_ set to:1754761656.508593 2025-08-09T17:47:36.509Z,1754761656.509 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772014 2025-08-09T17:47:36.759Z,1754761656.759 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.021897 2025-08-09T17:47:37.012Z,1754761657.012 [DAT](INFO): DAT read: Range 11 to 50 : 0.7 m (Round-trip 1.0 ms) speed 0.0 m/s 2025-08-09T17:47:37.013Z,1754761657.013 [DAT](ERROR): #Rx 1: Read range message, but no direction. 2025-08-09T17:47:40.968Z,1754761660.968 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Thruster off. 2025-08-09T17:47:43.391Z,1754761663.391 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-09T17:47:43.563Z,1754761663.563 [DAT](INFO): ****** received valid address query ****** 2025-08-09T17:47:43.563Z,1754761663.563 [DAT](INFO): ****** received valid ping request ****** 2025-08-09T17:47:43.563Z,1754761663.563 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-09T17:47:43.815Z,1754761663.815 [DAT](INFO): DAT read: user:725> 2025-08-09T17:47:43.816Z,1754761663.816 [DAT](INFO): DAT read: Tx time:17:47:43.1829 2025-08-09T17:47:43.816Z,1754761663.816 [DAT](INFO): Ping request sent. 2025-08-09T17:47:43.816Z,1754761663.816 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:47:43.817Z,1754761663.817 [DAT](INFO): publishing transmit ping time 2025-08-09T17:47:43.817Z,1754761663.817 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000741 2025-08-09T17:47:44.068Z,1754761664.068 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251093 2025-08-09T17:47:44.320Z,1754761664.320 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503740 2025-08-09T17:47:44.571Z,1754761664.571 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754773 2025-08-09T17:47:44.824Z,1754761664.824 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007094 2025-08-09T17:47:45.075Z,1754761665.075 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258951 2025-08-09T17:47:45.328Z,1754761665.328 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511106 2025-08-09T17:47:45.580Z,1754761665.580 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763094 2025-08-09T17:47:45.833Z,1754761665.833 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.016328 2025-08-09T17:47:46.083Z,1754761666.083 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266815 2025-08-09T17:47:46.336Z,1754761666.336 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519541 2025-08-09T17:47:46.587Z,1754761666.587 [DAT](INFO): DAT read: Rx Time:17:47:45.5900 2025-08-09T17:47:46.588Z,1754761666.588 [DAT](INFO): Rx dataTimestamp_ set to:1754761666.587629 2025-08-09T17:47:46.588Z,1754761666.588 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772058 2025-08-09T17:47:46.839Z,1754761666.839 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022840 2025-08-09T17:47:47.095Z,1754761667.095 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.278239 2025-08-09T17:47:47.348Z,1754761667.348 [DAT](INFO): DAT read: 17:47:45.5900 LVL= 19152, 29665, 21602, 32755, AGC= 36, IDX= 460,-0.22, 0.494,-1.482, 0.124, 0.024, PHS= 0.571,-1.461, 0.056, RAW= 135.9, 9.1, CAL= 135.6, 10.2, ROT= 14.4, -10.2 2025-08-09T17:47:47.350Z,1754761667.350 [DAT](INFO): got valid direction response: 17:47:45.5900 LVL= 19152, 29665, 21602, 32755, AGC= 36, IDX= 460,-0.22, 0.494,-1.482, 0.124, 0.024, PHS= 0.571,-1.461, 0.056, RAW= 135.9, 9.1, CAL= 135.6, 10.2, ROT= 14.4, -10.2 2025-08-09T17:47:47.351Z,1754761667.351 [DAT](INFO): DAT read: Bearing 345.6, 15.5 (Local) 2025-08-09T17:47:47.351Z,1754761667.351 [DAT](INFO): Local bearing/azimuth received: Bearing 345.6, 15.5 (Local) 2025-08-09T17:47:47.352Z,1754761667.352 [DAT](INFO): DAT read: Range 11 to 50 : 2.2 m (Round-trip 3.0 ms) speed -0.2 m/s 2025-08-09T17:47:47.353Z,1754761667.353 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-09T17:47:47.354Z,1754761667.354 [DAT](INFO): direction in FSK: [0.953275,0.244759,0.177085] 2025-08-09T17:47:47.354Z,1754761667.354 [DAT](INFO): publishing direction and range info 2025-08-09T17:47:53.485Z,1754761673.485 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-09T17:47:53.643Z,1754761673.643 [DAT](INFO): ****** received valid address query ****** 2025-08-09T17:47:53.643Z,1754761673.643 [DAT](INFO): ****** received valid ping request ****** 2025-08-09T17:47:53.643Z,1754761673.643 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-09T17:47:53.895Z,1754761673.895 [DAT](INFO): DAT read: user:726> 2025-08-09T17:47:53.896Z,1754761673.896 [DAT](INFO): DAT read: Tx time:17:47:53.2828 2025-08-09T17:47:53.896Z,1754761673.896 [DAT](INFO): Ping request sent. 2025-08-09T17:47:53.896Z,1754761673.896 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:47:53.897Z,1754761673.897 [DAT](INFO): publishing transmit ping time 2025-08-09T17:47:53.897Z,1754761673.897 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000737 2025-08-09T17:47:54.147Z,1754761674.147 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250783 2025-08-09T17:47:54.399Z,1754761674.399 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502718 2025-08-09T17:47:54.652Z,1754761674.652 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755026 2025-08-09T17:47:54.903Z,1754761674.903 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006826 2025-08-09T17:47:55.155Z,1754761675.155 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258950 2025-08-09T17:47:55.407Z,1754761675.407 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510729 2025-08-09T17:47:55.659Z,1754761675.659 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762791 2025-08-09T17:47:55.914Z,1754761675.914 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.016947 2025-08-09T17:47:56.163Z,1754761676.163 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266794 2025-08-09T17:47:56.415Z,1754761676.415 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518858 2025-08-09T17:47:56.667Z,1754761676.667 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770756 2025-08-09T17:47:56.923Z,1754761676.923 [DAT](INFO): DAT read: Rx Time:17:47:55.6920 2025-08-09T17:47:56.923Z,1754761676.923 [DAT](INFO): Rx dataTimestamp_ set to:1754761676.922939 2025-08-09T17:47:56.924Z,1754761676.924 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.027318 2025-08-09T17:47:57.172Z,1754761677.172 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275526 2025-08-09T17:47:57.428Z,1754761677.428 [DAT](INFO): DAT read: 17:47:55.6920 LVL= 22896, 32753, 28610, 32755, AGC= 38, IDX= 449, 0.17,-0.171,-2.077,-0.846,-0.551, PHS= 0.482,-1.481,-0.339, RAW= 125.4, 15.5, CAL= 125.1, 16.7, ROT= 24.9, -16.7 2025-08-09T17:47:57.429Z,1754761677.429 [DAT](INFO): got valid direction response: 17:47:55.6920 LVL= 22896, 32753, 28610, 32755, AGC= 38, IDX= 449, 0.17,-0.171,-2.077,-0.846,-0.551, PHS= 0.482,-1.481,-0.339, RAW= 125.4, 15.5, CAL= 125.1, 16.7, ROT= 24.9, -16.7 2025-08-09T17:47:57.431Z,1754761677.431 [DAT](INFO): DAT read: Bearing 346.0, 25.9 (Local) 2025-08-09T17:47:57.431Z,1754761677.431 [DAT](INFO): Local bearing/azimuth received: Bearing 346.0, 25.9 (Local) 2025-08-09T17:47:57.432Z,1754761677.432 [DAT](INFO): DAT read: Range 11 to 50 : 3.8 m (Round-trip 5.1 ms) speed -0.2 m/s 2025-08-09T17:47:57.433Z,1754761677.433 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-09T17:47:57.434Z,1754761677.434 [DAT](INFO): direction in FSK: [0.868787,0.403278,0.287361] 2025-08-09T17:47:57.434Z,1754761677.434 [DAT](INFO): publishing direction and range info 2025-08-09T17:48:03.579Z,1754761683.579 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-09T17:48:03.723Z,1754761683.723 [DAT](INFO): ****** received valid address query ****** 2025-08-09T17:48:03.723Z,1754761683.723 [DAT](INFO): ****** received valid ping request ****** 2025-08-09T17:48:03.724Z,1754761683.724 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-09T17:48:03.976Z,1754761683.976 [DAT](INFO): DAT read: user:727> 2025-08-09T17:48:03.979Z,1754761683.979 [DAT](INFO): DAT read: Tx time:17:48:03.3328 2025-08-09T17:48:03.980Z,1754761683.980 [DAT](INFO): Ping request sent. 2025-08-09T17:48:03.980Z,1754761683.980 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:48:03.981Z,1754761683.981 [DAT](INFO): publishing transmit ping time 2025-08-09T17:48:03.983Z,1754761683.983 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.002987 2025-08-09T17:48:04.227Z,1754761684.227 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.247446 2025-08-09T17:48:04.480Z,1754761684.480 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.500233 2025-08-09T17:48:04.731Z,1754761684.731 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.751522 2025-08-09T17:48:04.986Z,1754761684.986 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.005826 2025-08-09T17:48:05.235Z,1754761685.235 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.255628 2025-08-09T17:48:05.487Z,1754761685.487 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.507485 2025-08-09T17:48:05.739Z,1754761685.739 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.759447 2025-08-09T17:48:05.995Z,1754761685.995 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014157 2025-08-09T17:48:06.243Z,1754761686.243 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.263483 2025-08-09T17:48:06.496Z,1754761686.496 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.515880 2025-08-09T17:48:06.748Z,1754761686.748 [DAT](INFO): DAT read: Rx Time:17:48:05.7442 2025-08-09T17:48:06.748Z,1754761686.748 [DAT](INFO): Rx dataTimestamp_ set to:1754761686.747748 2025-08-09T17:48:06.749Z,1754761686.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.768827 2025-08-09T17:48:06.999Z,1754761686.999 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.019663 2025-08-09T17:48:07.254Z,1754761687.254 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.273590 2025-08-09T17:48:07.508Z,1754761687.508 [DAT](INFO): DAT read: 17:48:05.7442 LVL= 24240, 32753, 29650, 32755, AGC= 41, IDX= 456,-0.42, 1.884,-0.011, 1.173, 1.492, PHS= 0.494,-1.458,-0.362, RAW= 124.0, 15.5, CAL= 124.0, 16.7, ROT= 26.0, -16.7 2025-08-09T17:48:07.509Z,1754761687.509 [DAT](INFO): got valid direction response: 17:48:05.7442 LVL= 24240, 32753, 29650, 32755, AGC= 41, IDX= 456,-0.42, 1.884,-0.011, 1.173, 1.492, PHS= 0.494,-1.458,-0.362, RAW= 124.0, 15.5, CAL= 124.0, 16.7, ROT= 26.0, -16.7 2025-08-09T17:48:07.511Z,1754761687.511 [DAT](INFO): DAT read: Bearing 341.9, 26.8 (Local) 2025-08-09T17:48:07.511Z,1754761687.511 [DAT](INFO): Local bearing/azimuth received: Bearing 341.9, 26.8 (Local) 2025-08-09T17:48:07.512Z,1754761687.512 [DAT](INFO): DAT read: Range 11 to 50 : 5.4 m (Round-trip 7.3 ms) speed -0.1 m/s 2025-08-09T17:48:07.513Z,1754761687.513 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-09T17:48:07.514Z,1754761687.514 [DAT](INFO): direction in FSK: [0.860885,0.419882,0.287361] 2025-08-09T17:48:07.514Z,1754761687.514 [DAT](INFO): publishing direction and range info 2025-08-09T17:48:13.672Z,1754761693.672 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-09T17:48:13.807Z,1754761693.807 [DAT](INFO): ****** received valid address query ****** 2025-08-09T17:48:13.807Z,1754761693.807 [DAT](INFO): ****** received valid ping request ****** 2025-08-09T17:48:13.807Z,1754761693.807 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-09T17:48:14.059Z,1754761694.059 [DAT](INFO): DAT read: user:728> 2025-08-09T17:48:14.060Z,1754761694.060 [DAT](INFO): DAT read: Tx time:17:48:13.4328 2025-08-09T17:48:14.060Z,1754761694.060 [DAT](INFO): Ping request sent. 2025-08-09T17:48:14.060Z,1754761694.060 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:48:14.060Z,1754761694.060 [DAT](INFO): publishing transmit ping time 2025-08-09T17:48:14.061Z,1754761694.061 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000747 2025-08-09T17:48:14.311Z,1754761694.311 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250832 2025-08-09T17:48:14.570Z,1754761694.570 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.509631 2025-08-09T17:48:14.823Z,1754761694.823 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.762890 2025-08-09T17:48:15.076Z,1754761695.076 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.015142 2025-08-09T17:48:15.328Z,1754761695.328 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.267408 2025-08-09T17:48:15.579Z,1754761695.579 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.518861 2025-08-09T17:48:15.832Z,1754761695.832 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.771180 2025-08-09T17:48:16.083Z,1754761696.083 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.023028 2025-08-09T17:48:16.335Z,1754761696.335 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.274853 2025-08-09T17:48:16.588Z,1754761696.588 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.527251 2025-08-09T17:48:16.839Z,1754761696.839 [DAT](INFO): DAT read: Rx Time:17:48:15.8457 2025-08-09T17:48:16.840Z,1754761696.840 [DAT](INFO): Rx dataTimestamp_ set to:1754761696.839516 2025-08-09T17:48:16.840Z,1754761696.840 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.780002 2025-08-09T17:48:17.091Z,1754761697.091 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.030872 2025-08-09T17:48:17.368Z,1754761697.368 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.307208 2025-08-09T17:48:17.624Z,1754761697.624 [DAT](INFO): DAT read: 17:48:15.8457 LVL= 23648, 32753, 30322, 32755, AGC= 49, IDX= 438, 0.11, 2.504, 0.609, 1.825, 2.083, PHS= 0.522,-1.429,-0.303, RAW= 125.1, 14.2, CAL= 125.0, 15.4, ROT= 25.0, -15.4 2025-08-09T17:48:17.625Z,1754761697.625 [DAT](INFO): got valid direction response: 17:48:15.8457 LVL= 23648, 32753, 30322, 32755, AGC= 49, IDX= 438, 0.11, 2.504, 0.609, 1.825, 2.083, PHS= 0.522,-1.429,-0.303, RAW= 125.1, 14.2, CAL= 125.0, 15.4, ROT= 25.0, -15.4 2025-08-09T17:48:17.626Z,1754761697.626 [DAT](INFO): DAT read: Bearing 336.6, 24.8 (Local) 2025-08-09T17:48:17.626Z,1754761697.626 [DAT](INFO): Local bearing/azimuth received: Bearing 336.6, 24.8 (Local) 2025-08-09T17:48:17.628Z,1754761697.628 [DAT](INFO): DAT read: Range 11 to 50 : 6.5 m (Round-trip 8.7 ms) speed -0.2 m/s 2025-08-09T17:48:17.628Z,1754761697.628 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-09T17:48:17.629Z,1754761697.629 [DAT](INFO): direction in FSK: [0.873767,0.407444,0.265556] 2025-08-09T17:48:17.629Z,1754761697.629 [DAT](INFO): publishing direction and range info 2025-08-09T17:48:23.787Z,1754761703.787 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-09T17:48:23.915Z,1754761703.915 [DAT](INFO): ****** received valid address query ****** 2025-08-09T17:48:23.915Z,1754761703.915 [DAT](INFO): ****** received valid ping request ****** 2025-08-09T17:48:23.915Z,1754761703.915 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-09T17:48:24.167Z,1754761704.167 [DAT](INFO): DAT read: user:729> 2025-08-09T17:48:24.168Z,1754761704.168 [DAT](INFO): DAT read: Tx time:17:48:23.5328 2025-08-09T17:48:24.168Z,1754761704.168 [DAT](INFO): Ping request sent. 2025-08-09T17:48:24.168Z,1754761704.168 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:48:24.169Z,1754761704.169 [DAT](INFO): publishing transmit ping time 2025-08-09T17:48:24.169Z,1754761704.169 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000736 2025-08-09T17:48:24.421Z,1754761704.421 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251563 2025-08-09T17:48:24.672Z,1754761704.672 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503803 2025-08-09T17:48:24.924Z,1754761704.924 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754997 2025-08-09T17:48:25.175Z,1754761705.175 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006772 2025-08-09T17:48:25.431Z,1754761705.431 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.261971 2025-08-09T17:48:25.680Z,1754761705.680 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510983 2025-08-09T17:48:25.931Z,1754761705.931 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762793 2025-08-09T17:48:26.184Z,1754761706.184 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015266 2025-08-09T17:48:26.436Z,1754761706.436 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267034 2025-08-09T17:48:26.687Z,1754761706.687 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518836 2025-08-09T17:48:26.939Z,1754761706.939 [DAT](INFO): DAT read: Rx Time:17:48:25.9472 2025-08-09T17:48:26.940Z,1754761706.940 [DAT](INFO): Rx dataTimestamp_ set to:1754761706.939394 2025-08-09T17:48:26.940Z,1754761706.940 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771724 2025-08-09T17:48:27.192Z,1754761707.192 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022986 2025-08-09T17:48:27.443Z,1754761707.443 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274882 2025-08-09T17:48:27.700Z,1754761707.700 [DAT](INFO): DAT read: 17:48:25.9472 LVL= 24288, 32753, 28658, 32755, AGC= 48, IDX= 439,-0.40, 2.854, 1.040, 2.172, 2.427, PHS= 0.528,-1.343,-0.299, RAW= 123.8, 13.6, CAL= 124.0, 14.8, ROT= 26.0, -14.8 2025-08-09T17:48:27.701Z,1754761707.701 [DAT](INFO): got valid direction response: 17:48:25.9472 LVL= 24288, 32753, 28658, 32755, AGC= 48, IDX= 439,-0.40, 2.854, 1.040, 2.172, 2.427, PHS= 0.528,-1.343,-0.299, RAW= 123.8, 13.6, CAL= 124.0, 14.8, ROT= 26.0, -14.8 2025-08-09T17:48:27.703Z,1754761707.703 [DAT](INFO): DAT read: Bearing 334.5, 26.3 (Local) 2025-08-09T17:48:27.703Z,1754761707.703 [DAT](INFO): Local bearing/azimuth received: Bearing 334.5, 26.3 (Local) 2025-08-09T17:48:27.704Z,1754761707.704 [DAT](INFO): DAT read: Range 11 to 50 : 7.7 m (Round-trip 10.3 ms) speed -0.1 m/s 2025-08-09T17:48:27.705Z,1754761707.705 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-09T17:48:27.706Z,1754761707.706 [DAT](INFO): direction in FSK: [0.868975,0.423827,0.255446] 2025-08-09T17:48:27.706Z,1754761707.706 [DAT](INFO): publishing direction and range info 2025-08-09T17:48:27.835Z,1754761707.835 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-08-09T17:48:33.893Z,1754761713.893 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-09T17:48:33.999Z,1754761713.999 [DAT](INFO): ****** received valid address query ****** 2025-08-09T17:48:33.999Z,1754761713.999 [DAT](INFO): ****** received valid ping request ****** 2025-08-09T17:48:33.999Z,1754761713.999 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-09T17:48:34.251Z,1754761714.251 [DAT](INFO): DAT read: user:730> 2025-08-09T17:48:34.252Z,1754761714.252 [DAT](INFO): DAT read: Tx time:17:48:33.6327 2025-08-09T17:48:34.252Z,1754761714.252 [DAT](INFO): Ping request sent. 2025-08-09T17:48:34.252Z,1754761714.252 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:48:34.252Z,1754761714.252 [DAT](INFO): publishing transmit ping time 2025-08-09T17:48:34.253Z,1754761714.253 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000742 2025-08-09T17:48:34.503Z,1754761714.503 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250892 2025-08-09T17:48:34.755Z,1754761714.755 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502901 2025-08-09T17:48:35.007Z,1754761715.007 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754939 2025-08-09T17:48:35.259Z,1754761715.259 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006879 2025-08-09T17:48:35.512Z,1754761715.512 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259235 2025-08-09T17:48:35.763Z,1754761715.763 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510971 2025-08-09T17:48:36.015Z,1754761716.015 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762837 2025-08-09T17:48:36.267Z,1754761716.267 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.015110 2025-08-09T17:48:36.522Z,1754761716.522 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.269127 2025-08-09T17:48:36.772Z,1754761716.772 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519282 2025-08-09T17:48:37.024Z,1754761717.024 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771151 2025-08-09T17:48:37.275Z,1754761717.275 [DAT](INFO): DAT read: Rx Time:17:48:36.0485 2025-08-09T17:48:37.276Z,1754761717.276 [DAT](INFO): Rx dataTimestamp_ set to:1754761717.275534 2025-08-09T17:48:37.276Z,1754761717.276 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.024030 2025-08-09T17:48:37.527Z,1754761717.527 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275011 2025-08-09T17:48:37.785Z,1754761717.785 [DAT](INFO): DAT read: 17:48:36.0485 LVL= 20896, 32753, 30642, 32755, AGC= 45, IDX= 436, 0.04, 0.914,-1.039, 0.203, 0.401, PHS= 0.615,-1.395,-0.242, RAW= 124.8, 11.7, CAL= 125.1, 12.9, ROT= 24.9, -12.9 2025-08-09T17:48:37.786Z,1754761717.786 [DAT](INFO): got valid direction response: 17:48:36.0485 LVL= 20896, 32753, 30642, 32755, AGC= 45, IDX= 436, 0.04, 0.914,-1.039, 0.203, 0.401, PHS= 0.615,-1.395,-0.242, RAW= 124.8, 11.7, CAL= 125.1, 12.9, ROT= 24.9, -12.9 2025-08-09T17:48:37.787Z,1754761717.787 [DAT](INFO): DAT read: Bearing 334.4, 25.5 (Local) 2025-08-09T17:48:37.787Z,1754761717.787 [DAT](INFO): Local bearing/azimuth received: Bearing 334.4, 25.5 (Local) 2025-08-09T17:48:37.788Z,1754761717.788 [DAT](INFO): DAT read: Range 11 to 50 : 8.7 m (Round-trip 11.7 ms) speed 0.0 m/s 2025-08-09T17:48:37.789Z,1754761717.789 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-09T17:48:37.790Z,1754761717.790 [DAT](INFO): direction in FSK: [0.884151,0.410409,0.223250] 2025-08-09T17:48:37.804Z,1754761717.804 [DAT](INFO): publishing direction and range info 2025-08-09T17:48:44.006Z,1754761724.006 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-09T17:48:44.079Z,1754761724.079 [DAT](INFO): ****** received valid address query ****** 2025-08-09T17:48:44.079Z,1754761724.079 [DAT](INFO): ****** received valid ping request ****** 2025-08-09T17:48:44.079Z,1754761724.079 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-09T17:48:44.331Z,1754761724.331 [DAT](INFO): DAT read: user:731> 2025-08-09T17:48:44.332Z,1754761724.332 [DAT](INFO): DAT read: Tx time:17:48:43.6827 2025-08-09T17:48:44.332Z,1754761724.332 [DAT](INFO): Ping request sent. 2025-08-09T17:48:44.332Z,1754761724.332 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:48:44.332Z,1754761724.332 [DAT](INFO): publishing transmit ping time 2025-08-09T17:48:44.333Z,1754761724.333 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000747 2025-08-09T17:48:44.587Z,1754761724.587 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.253807 2025-08-09T17:48:44.836Z,1754761724.836 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503428 2025-08-09T17:48:45.087Z,1754761725.087 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754914 2025-08-09T17:48:45.340Z,1754761725.340 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007599 2025-08-09T17:48:45.595Z,1754761725.595 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.261542 2025-08-09T17:48:45.843Z,1754761725.843 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510911 2025-08-09T17:48:46.096Z,1754761726.096 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763305 2025-08-09T17:48:46.347Z,1754761726.347 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014968 2025-08-09T17:48:46.599Z,1754761726.599 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266977 2025-08-09T17:48:46.852Z,1754761726.852 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519573 2025-08-09T17:48:47.103Z,1754761727.103 [DAT](INFO): DAT read: Rx Time:17:48:46.1000 2025-08-09T17:48:47.104Z,1754761727.104 [DAT](INFO): Rx dataTimestamp_ set to:1754761727.103532 2025-08-09T17:48:47.104Z,1754761727.104 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772061 2025-08-09T17:48:47.355Z,1754761727.355 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022942 2025-08-09T17:48:47.613Z,1754761727.613 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.280188 2025-08-09T17:48:47.864Z,1754761727.864 [DAT](INFO): DAT read: 17:48:46.1000 LVL= 22144, 32753, 29682, 32755, AGC= 50, IDX= 452, 0.36,-1.650, 2.688,-2.341,-2.187, PHS= 0.640,-1.363,-0.198, RAW= 125.4, 10.6, CAL= 125.7, 11.8, ROT= 24.3, -11.8 2025-08-09T17:48:47.865Z,1754761727.865 [DAT](INFO): got valid direction response: 17:48:46.1000 LVL= 22144, 32753, 29682, 32755, AGC= 50, IDX= 452, 0.36,-1.650, 2.688,-2.341,-2.187, PHS= 0.640,-1.363,-0.198, RAW= 125.4, 10.6, CAL= 125.7, 11.8, ROT= 24.3, -11.8 2025-08-09T17:48:47.866Z,1754761727.866 [DAT](INFO): DAT read: Bearing 335.0, 24.5 (Local) 2025-08-09T17:48:47.867Z,1754761727.867 [DAT](INFO): Local bearing/azimuth received: Bearing 335.0, 24.5 (Local) 2025-08-09T17:48:47.868Z,1754761727.868 [DAT](INFO): DAT read: Range 11 to 50 : 9.9 m (Round-trip 13.2 ms) speed 0.0 m/s 2025-08-09T17:48:47.869Z,1754761727.869 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-09T17:48:47.870Z,1754761727.870 [DAT](INFO): direction in FSK: [0.892143,0.402818,0.204496] 2025-08-09T17:48:47.870Z,1754761727.870 [DAT](INFO): publishing direction and range info 2025-08-09T17:48:48.045Z,1754761728.045 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 9.90 m. Transitioning docking module to standby. 2025-08-09T17:48:48.440Z,1754761728.440 [DockingStepper](INFO): Changing to mode: 1 2025-08-09T17:48:54.103Z,1754761734.103 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact *** 2025-08-09T17:48:54.167Z,1754761734.167 [DAT](INFO): ****** received valid address query ****** 2025-08-09T17:48:54.167Z,1754761734.167 [DAT](INFO): ****** received valid ping request ****** 2025-08-09T17:48:54.167Z,1754761734.167 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode. 2025-08-09T17:48:54.419Z,1754761734.419 [DAT](INFO): DAT read: user:732> 2025-08-09T17:48:54.420Z,1754761734.420 [DAT](INFO): DAT read: Tx time:17:48:53.7827 2025-08-09T17:48:54.420Z,1754761734.420 [DAT](INFO): Ping request sent. 2025-08-09T17:48:54.420Z,1754761734.420 [DAT](INFO): transmitted an acoustic signal 2025-08-09T17:48:54.420Z,1754761734.420 [DAT](INFO): publishing transmit ping time 2025-08-09T17:48:54.421Z,1754761734.421 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000759 2025-08-09T17:48:54.502Z,1754761734.502 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2025-08-09T17:48:54.671Z,1754761734.671 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250976 2025-08-09T17:48:54.923Z,1754761734.923 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503036 2025-08-09T17:48:54.925Z,1754761734.925 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2025-08-09T17:48:54.925Z,1754761734.925 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2025-08-09T17:48:54.925Z,1754761734.925 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2025-08-09T17:48:54.925Z,1754761734.925 [DefaultWithUndock:LeaveDock] Stopped 2025-08-09T17:48:54.926Z,1754761734.926 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2025-08-09T17:48:54.926Z,1754761734.926 [DefaultWithUndock:LeaveDock:A.] Stopped 2025-08-09T17:48:54.926Z,1754761734.926 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2025-08-09T17:48:54.926Z,1754761734.926 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2025-08-09T17:48:54.926Z,1754761734.926 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2025-08-09T17:48:54.926Z,1754761734.926 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2025-08-09T17:48:54.926Z,1754761734.926 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-08-09T17:48:54.927Z,1754761734.927 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-08-09T17:48:54.927Z,1754761734.927 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-08-09T17:48:54.927Z,1754761734.927 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-08-09T17:48:54.928Z,1754761734.928 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-08-09T17:48:54.928Z,1754761734.928 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-08-09T17:48:54.928Z,1754761734.928 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-08-09T17:48:55.175Z,1754761735.175 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.755146 2025-08-09T17:48:55.427Z,1754761735.427 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.007033 2025-08-09T17:48:55.679Z,1754761735.679 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.259035 2025-08-09T17:48:55.854Z,1754761735.854 [Radio_Surface](INFO): Powering up 2025-08-09T17:48:55.931Z,1754761735.931 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.511079 2025-08-09T17:48:56.183Z,1754761736.183 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762978 2025-08-09T17:48:56.435Z,1754761736.435 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014928 2025-08-09T17:48:56.688Z,1754761736.688 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.267396 2025-08-09T17:48:56.939Z,1754761736.939 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519049 2025-08-09T17:48:57.191Z,1754761737.191 [DAT](INFO): DAT read: Rx Time:17:48:56.2009 2025-08-09T17:48:57.192Z,1754761737.192 [DAT](INFO): Rx dataTimestamp_ set to:1754761737.191571 2025-08-09T17:48:57.192Z,1754761737.192 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.772102 2025-08-09T17:48:57.444Z,1754761737.444 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023794 2025-08-09T17:48:57.695Z,1754761737.695 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274979 2025-08-09T17:48:57.953Z,1754761737.953 [DAT](INFO): DAT read: 17:48:56.2009 LVL= 22192, 31297, 27746, 32755, AGC= 52, IDX= 445,-0.25,-2.376, 2.030,-2.927,-2.891, PHS= 0.617,-1.318,-0.080, RAW= 129.2, 9.2, CAL= 129.2, 10.5, ROT= 20.8, -10.5 2025-08-09T17:48:57.956Z,1754761737.956 [DAT](INFO): got valid direction response: 17:48:56.2009 LVL= 22192, 31297, 27746, 32755, AGC= 52, IDX= 445,-0.25,-2.376, 2.030,-2.927,-2.891, PHS= 0.617,-1.318,-0.080, RAW= 129.2, 9.2, CAL= 129.2, 10.5, ROT= 20.8, -10.5 2025-08-09T17:48:57.957Z,1754761737.957 [DAT](INFO): DAT read: Bearing 334.7, 21.2 (Local) 2025-08-09T17:48:57.959Z,1754761737.959 [DAT](INFO): Local bearing/azimuth received: Bearing 334.7, 21.2 (Local) 2025-08-09T17:48:57.960Z,1754761737.960 [DAT](INFO): DAT read: Range 11 to 50 : 10.5 m (Round-trip 14.1 ms) speed -0.1 m/s 2025-08-09T17:48:57.961Z,1754761737.961 [DAT](INFO): #Rx 1: Read range and direction messages. 2025-08-09T17:48:57.963Z,1754761737.963 [DAT](INFO): direction in FSK: [0.919172,0.349161,0.182236] 2025-08-09T17:48:57.963Z,1754761737.963 [DAT](INFO): publishing direction and range info 2025-08-09T17:49:01.547Z,1754761741.547 [DataOverHttps](INFO): Radio surface powered ON. 2025-08-09T17:50:55.322Z,1754761855.322 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS,+1008, +16,A 2025-08-09T17:51:12.083Z,1754761872.083 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-09T17:51:28.058Z,1754761888.058 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2025-08-09T17:51:28.058Z,1754761888.058 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-08-09T17:51:28.058Z,1754761888.058 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-08-09T17:51:47.227Z,1754761907.227 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-09T17:52:02.371Z,1754761922.371 [NAL9602](INFO): SBD MO Status=2, MOMSN=613, MT Status=2, MTMSN=0 2025-08-09T17:52:02.371Z,1754761922.371 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-09T17:52:08.022Z,1754761928.022 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-08-09T17:52:22.408Z,1754761942.408 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-09T17:52:22.589Z,1754761942.589 [NAL9602](INFO): SBD MO Status=0, MOMSN=613, MT Status=0, MTMSN=0 2025-08-09T17:52:22.589Z,1754761942.589 [NAL9602](INFO): No messages in MT queue 2025-08-09T17:52:23.782Z,1754761943.782 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175223.00,A,3654.30464,N,12206.97093,W,1.089,179.47,090825,,,A*74 2025-08-09T17:52:23.784Z,1754761943.784 [NAL9602](INFO): GPS fix at 20250809T175223: (36.905077, -122.116182) 2025-08-09T17:52:23.798Z,1754761943.798 [UniversalFixResidualReporter](INFO): Fix residual: 0.7 %DT, over the last 1859.2 m. Residual distance 13.4 m at bearing -14.5 degrees. Fix at (36.9051, -122.1162) with 517.4 m made good. 2025-08-09T17:52:23.799Z,1754761943.799 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-08-09T17:52:23.799Z,1754761943.799 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-08-09T17:52:44.159Z,1754761964.159 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002672 2025-08-09T17:52:45.718Z,1754761965.718 [NAL9602](INFO): SBD MO Status=2, MOMSN=614, MT Status=2, MTMSN=0 2025-08-09T17:52:45.720Z,1754761965.720 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-09T17:52:51.718Z,1754761971.718 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250809T070052/Courier0088.lzma 2025-08-09T17:52:52.721Z,1754761972.721 [DataOverHttps](INFO): Moved sent file to Logs/20250809T070052/Courier0088.lzma.bak 2025-08-09T17:52:52.721Z,1754761972.721 [DataOverHttps](INFO): SBD MOMSN=25821750 2025-08-09T17:53:09.590Z,1754761989.590 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250809T163717/Courier0000.lzma 2025-08-09T17:53:10.592Z,1754761990.592 [DataOverHttps](INFO): Moved sent file to Logs/20250809T163717/Courier0000.lzma.bak 2025-08-09T17:53:10.592Z,1754761990.592 [DataOverHttps](INFO): SBD MOMSN=25821753 2025-08-09T17:53:16.408Z,1754761996.408 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-09T17:53:27.206Z,1754762007.206 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250809T163717/Courier0003.lzma 2025-08-09T17:53:28.208Z,1754762008.208 [DataOverHttps](INFO): Moved sent file to Logs/20250809T163717/Courier0003.lzma.bak 2025-08-09T17:53:28.208Z,1754762008.208 [DataOverHttps](INFO): SBD MOMSN=25821756 2025-08-09T17:53:44.766Z,1754762024.766 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250809T163717/Courier0006.lzma 2025-08-09T17:53:45.768Z,1754762025.768 [DataOverHttps](INFO): Moved sent file to Logs/20250809T163717/Courier0006.lzma.bak 2025-08-09T17:53:45.768Z,1754762025.768 [DataOverHttps](INFO): SBD MOMSN=25821758 2025-08-09T17:54:01.807Z,1754762041.807 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250809T163717/Courier0009.lzma 2025-08-09T17:54:02.808Z,1754762042.808 [DataOverHttps](INFO): Moved sent file to Logs/20250809T163717/Courier0009.lzma.bak 2025-08-09T17:54:02.809Z,1754762042.809 [DataOverHttps](INFO): SBD MOMSN=25821760 2025-08-09T17:54:19.250Z,1754762059.250 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20250809T163717/Courier0012.lzma 2025-08-09T17:54:20.252Z,1754762060.252 [DataOverHttps](INFO): Moved sent file to Logs/20250809T163717/Courier0012.lzma.bak 2025-08-09T17:54:20.252Z,1754762060.252 [DataOverHttps](INFO): SBD MOMSN=25821762 2025-08-09T17:54:36.522Z,1754762076.522 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250809T163717/Courier0015.lzma 2025-08-09T17:54:37.525Z,1754762077.525 [DataOverHttps](INFO): Moved sent file to Logs/20250809T163717/Courier0015.lzma.bak 2025-08-09T17:54:37.525Z,1754762077.525 [DataOverHttps](INFO): SBD MOMSN=25821764 2025-08-09T17:54:53.850Z,1754762093.850 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20250809T163717/Courier0018.lzma 2025-08-09T17:54:54.852Z,1754762094.852 [DataOverHttps](INFO): Moved sent file to Logs/20250809T163717/Courier0018.lzma.bak 2025-08-09T17:54:54.852Z,1754762094.852 [DataOverHttps](INFO): SBD MOMSN=25821766 2025-08-09T17:55:12.330Z,1754762112.330 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250809T163717/Courier0021.lzma 2025-08-09T17:55:13.338Z,1754762113.338 [DataOverHttps](INFO): Moved sent file to Logs/20250809T163717/Courier0021.lzma.bak 2025-08-09T17:55:13.338Z,1754762113.338 [DataOverHttps](INFO): SBD MOMSN=25821768 2025-08-09T17:55:29.482Z,1754762129.482 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250809T163717/Courier0024.lzma 2025-08-09T17:55:30.485Z,1754762130.485 [DataOverHttps](INFO): Moved sent file to Logs/20250809T163717/Courier0024.lzma.bak 2025-08-09T17:55:30.485Z,1754762130.485 [DataOverHttps](INFO): SBD MOMSN=25821770 2025-08-09T17:55:47.015Z,1754762147.015 [DataOverHttps](INFO): Sending 387 bytes from file Logs/20250809T174616/Courier0000.lzma 2025-08-09T17:55:48.016Z,1754762148.016 [DataOverHttps](INFO): Moved sent file to Logs/20250809T174616/Courier0000.lzma.bak 2025-08-09T17:55:48.016Z,1754762148.016 [DataOverHttps](INFO): SBD MOMSN=25821772 2025-08-09T17:55:56.573Z,1754762156.573 [DataOverHttps](IMPORTANT): SBD MTMSN=20250809T175555 2025-08-09T17:56:05.807Z,1754762165.807 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20250809T070052/Express0089.lzma 2025-08-09T17:56:05.810Z,1754762165.810 [DataOverHttps](INFO): Received command: restart logs