2025-12-05T02:46:33.112Z,1764902793.112 [OnDock:L.Execute](INFO): Executing command restart logs 2025-12-05T02:46:33.112Z,1764902793.112 [OnDock:L.Execute] Stopped 2025-12-05T02:46:33.112Z,1764902793.112 [OnDock:N.Docked] Running Loop=1 2025-12-05T02:46:33.112Z,1764902793.112 [OnDock:N.Docked](DEBUG): Initialize. 2025-12-05T02:46:33.116Z,1764902793.116 [OnDock:N.Docked](IMPORTANT): DHCP script made executable: /mnt/mmc/LRAUV/Tools/dhcp.sh 2025-12-05T02:46:33.116Z,1764902793.116 [OnDock:N.Docked](DEBUG): Initializing internal variables to default values. 2025-12-05T02:46:33.137Z,1764902793.137 [CommandExec](IMPORTANT): got command restart logs 2025-12-05T02:46:33.510Z,1764902793.510 [OnDock:N.Docked](FAULT): Off dock, depth 0.212317 above surface threshold 2025-12-05T02:46:33.510Z,1764902793.510 [OnDock:N.Docked](IMPORTANT): State switched to DETACHED. Waiting for state timeout to act... 2025-12-05T02:46:33.727Z,1764902793.727 [DAT](INFO): Requesting device enable set for address 50. 2025-12-05T02:46:33.941Z,1764902793.941 [CTD_Seabird](ERROR): Failed to parse device response: 2025-12-05T02:46:33.981Z,1764902793.981 [DAT](INFO): DAT read: user:22> 2025-12-05T02:46:33.982Z,1764902793.982 [DAT](INFO): DAT read: Tx time:02:46:33.8762 2025-12-05T02:46:33.982Z,1764902793.982 [DAT](INFO): Ping request sent. 2025-12-05T02:46:33.982Z,1764902793.982 [DAT](INFO): transmitted an acoustic signal 2025-12-05T02:46:33.982Z,1764902793.982 [DAT](INFO): publishing transmit ping time 2025-12-05T02:46:33.983Z,1764902793.983 [DAT](INFO): Requesting device enable set for address 50. 2025-12-05T02:46:34.232Z,1764902794.232 [DAT](INFO): Requesting device enable set for address 50. 2025-12-05T02:46:34.336Z,1764902794.336 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-12-05T02:46:34.456Z,1764902794.456 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-12-05T02:46:36.500Z,1764902796.500 [DAT](INFO): DAT read: Rx Time:02:46:35.9321 2025-12-05T02:46:36.501Z,1764902796.501 [DAT](INFO): Rx dataTimestamp_ set to:1764902796.500618 2025-12-05T02:46:37.004Z,1764902797.004 [DAT](INFO): DAT read: OK 2025-12-05T02:46:37.005Z,1764902797.005 [DAT](INFO): unknown deviceResponse_: OK 2025-12-05T02:46:37.005Z,1764902797.005 [DAT] Communications Fault, FailCount= 1 2025-12-05T02:46:37.005Z,1764902797.005 [DAT](ERROR): Communications Fault 2025-12-05T02:46:37.006Z,1764902797.006 [DAT](INFO): DAT read: user:23> 2025-12-05T02:46:37.006Z,1764902797.006 [DAT](INFO): DAT read: Tx time:02:46:37.0262 2025-12-05T02:46:37.007Z,1764902797.007 [DAT](INFO): Ping request sent. 2025-12-05T02:46:37.007Z,1764902797.007 [DAT](INFO): transmitted an acoustic signal 2025-12-05T02:46:37.007Z,1764902797.007 [DAT](INFO): publishing transmit ping time 2025-12-05T02:46:37.216Z,1764902797.216 [CBIT](ERROR): Communications Fault in component: DAT 2025-12-05T02:46:37.412Z,1764902797.412 [DAT](INFO): Powering down 2025-12-05T02:46:38.033Z,1764902798.033 [DockingStepper](INFO): Changing to mode: 1 2025-12-05T02:46:38.454Z,1764902798.454 [CBIT](INFO): Clearing failed state for component DAT 2025-12-05T02:46:38.454Z,1764902798.454 [DAT] No Fault, FailCount= 0 2025-12-05T02:46:40.460Z,1764902800.460 [DAT](INFO): Powering up 2025-12-05T02:46:40.461Z,1764902800.461 [DAT](DEBUG): Initializing DAT. 2025-12-05T02:46:52.072Z,1764902812.072 [DAT](INFO): DAT read: 2025-12-05T02:46:52.073Z,1764902812.073 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-12-05T02:46:53.584Z,1764902813.584 [DAT](INFO): DAT read: MF Frequency Band 2025-12-05T02:46:53.586Z,1764902813.586 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-12-05T02:46:53.586Z,1764902813.586 [DAT](INFO): DAT read: Dec 5 2025 02:46:49 2025-12-05T02:46:54.845Z,1764902814.845 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-12-05T02:46:54.846Z,1764902814.846 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-12-05T02:46:54.846Z,1764902814.846 [DAT](INFO): commRate: 600 2025-12-05T02:46:56.911Z,1764902816.911 [DAT](INFO): entering command mode 2025-12-05T02:46:57.112Z,1764902817.112 [DAT](INFO): DAT read: 2025-12-05T02:46:57.112Z,1764902817.112 [DAT](INFO): DAT read: user:1> 2025-12-05T02:46:57.113Z,1764902817.113 [DAT](INFO): setting verbose to 3 2025-12-05T02:46:57.364Z,1764902817.364 [DAT](INFO): DAT read: user:1> 2025-12-05T02:46:57.366Z,1764902817.365 [DAT](INFO): DAT read: Verbose | 3 2025-12-05T02:46:57.366Z,1764902817.366 [DAT](INFO): set verbose to 3 2025-12-05T02:46:57.366Z,1764902817.366 [DAT](INFO): setting DatVerbose to 27440 2025-12-05T02:46:57.616Z,1764902817.616 [DAT](INFO): DAT read: user:2> 2025-12-05T02:46:57.617Z,1764902817.617 [DAT](INFO): DAT read: DatVerbose | 27440 2025-12-05T02:46:57.617Z,1764902817.617 [DAT](INFO): set DatVerbose to 27440 2025-12-05T02:46:57.618Z,1764902817.618 [DAT](INFO): setting transmit power to 8 2025-12-05T02:46:57.868Z,1764902817.868 [DAT](INFO): DAT read: user:3> 2025-12-05T02:46:57.869Z,1764902817.869 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-12-05T02:46:57.869Z,1764902817.869 [DAT](INFO): set transmit power to 8 2025-12-05T02:46:57.870Z,1764902817.870 [DAT](INFO): setting local address to 11 2025-12-05T02:46:58.120Z,1764902818.120 [DAT](INFO): DAT read: user:4> 2025-12-05T02:46:58.121Z,1764902818.121 [DAT](INFO): DAT read: LocalAddr | 11 2025-12-05T02:46:58.121Z,1764902818.121 [DAT](INFO): set local address to 11 2025-12-05T02:46:58.122Z,1764902818.122 [DAT](INFO): Setting time to: 2:46:58 And date to:12/5/2025 2025-12-05T02:46:58.373Z,1764902818.373 [DAT](INFO): DAT read: user:5> 2025-12-05T02:46:58.374Z,1764902818.374 [DAT](INFO): DAT read: Fri Dec 5, 2025 02:46:58 2025-12-05T02:46:58.374Z,1764902818.374 [DAT](INFO): Local DAT time set to Fri Dec 5, 2025 02:46:58 2025-12-05T02:47:03.908Z,1764902823.908 [OnDock:N.Docked](IMPORTANT): VEHICLE DETACHED FROM DOCK. 2025-12-05T02:47:03.908Z,1764902823.908 [OnDock:N.Docked] Stopped 2025-12-05T02:47:03.909Z,1764902823.909 [OnDock:Detach] Running Loop=1 2025-12-05T02:47:03.909Z,1764902823.909 [OnDock:Detach](DEBUG): Aggregate::initialize OnDock:Detach 2025-12-05T02:47:03.909Z,1764902823.909 [OnDock:Detach:A] Running Loop=1 2025-12-05T02:47:04.068Z,1764902824.068 [DockingStepper](INFO): Changing to mode: 2 2025-12-05T02:47:04.172Z,1764902824.172 [DAT](INFO): DAT read: user:6> 2025-12-05T02:47:04.173Z,1764902824.173 [DAT](INFO): DAT read: Tx time:02:47:03.9654 2025-12-05T02:47:04.173Z,1764902824.173 [DAT](INFO): Ping request sent. 2025-12-05T02:47:04.173Z,1764902824.173 [DAT](INFO): transmitted an acoustic signal 2025-12-05T02:47:04.174Z,1764902824.174 [DAT](INFO): publishing transmit ping time 2025-12-05T02:47:04.279Z,1764902824.279 [OnDock:Detach:A](IMPORTANT): Mission ending, breaking off. Range: 108.900002 m . 2025-12-05T02:47:04.279Z,1764902824.279 [OnDock:Detach:A] Stopped 2025-12-05T02:47:04.280Z,1764902824.280 [OnDock:Detach:B.Undock] Running Loop=1 2025-12-05T02:47:04.280Z,1764902824.280 [OnDock:Detach:B.Undock](DEBUG): Initialize. 2025-12-05T02:47:04.280Z,1764902824.280 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2025-12-05T02:47:04.682Z,1764902824.682 [OnDock:Detach:B.Undock](DEBUG): Initialize. 2025-12-05T02:47:04.682Z,1764902824.682 [OnDock:Detach:B.Undock](DEBUG): Initializing internal variables to default values. 2025-12-05T02:47:04.683Z,1764902824.683 [OnDock:Detach:B.Undock](INFO): Detached from dock at depth 0.199351 m. 2025-12-05T02:47:05.127Z,1764902825.127 [OnDock:Detach:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-12-05T02:47:06.693Z,1764902826.693 [DAT](INFO): DAT read: Rx Time:02:47:06.0048 2025-12-05T02:47:06.693Z,1764902826.693 [DAT](INFO): Rx dataTimestamp_ set to:1764902826.693010 2025-12-05T02:47:07.198Z,1764902827.198 [DAT](INFO): DAT read: OK 2025-12-05T02:47:07.198Z,1764902827.198 [DAT](INFO): unknown deviceResponse_: OK 2025-12-05T02:47:07.198Z,1764902827.198 [DAT] Communications Fault, FailCount= 1 2025-12-05T02:47:07.198Z,1764902827.198 [DAT](ERROR): Communications Fault 2025-12-05T02:47:07.199Z,1764902827.199 [DAT](INFO): DAT read: user:7> 2025-12-05T02:47:07.200Z,1764902827.200 [DAT](INFO): DAT read: Tx time:02:47:07.1154 2025-12-05T02:47:07.200Z,1764902827.200 [DAT](INFO): Ping request sent. 2025-12-05T02:47:07.200Z,1764902827.200 [DAT](INFO): transmitted an acoustic signal 2025-12-05T02:47:07.200Z,1764902827.200 [DAT](INFO): publishing transmit ping time 2025-12-05T02:47:07.244Z,1764902827.244 [CBIT](ERROR): Communications Fault in component: DAT 2025-12-05T02:47:07.610Z,1764902827.610 [DAT](INFO): Powering down 2025-12-05T02:47:08.836Z,1764902828.836 [CBIT](INFO): Clearing failed state for component DAT 2025-12-05T02:47:08.836Z,1764902828.836 [DAT] No Fault, FailCount= 0 2025-12-05T02:47:10.378Z,1764902830.378 [DataOverHttps](INFO): Sending 572 bytes from file Logs/20251205T004645/Express0001.lzma 2025-12-05T02:47:10.656Z,1764902830.656 [DAT](INFO): Powering up 2025-12-05T02:47:10.656Z,1764902830.656 [DAT](DEBUG): Initializing DAT. 2025-12-05T02:47:11.377Z,1764902831.377 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0001.lzma.bak 2025-12-05T02:47:11.378Z,1764902831.378 [DataOverHttps](INFO): SBD MOMSN=26430455 2025-12-05T02:47:22.256Z,1764902842.256 [DAT](INFO): DAT read: 2025-12-05T02:47:22.257Z,1764902842.257 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-12-05T02:47:23.770Z,1764902843.770 [DAT](INFO): DAT read: MF Frequency Band 2025-12-05T02:47:23.774Z,1764902843.774 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-12-05T02:47:24.020Z,1764902844.020 [DAT](INFO): DAT read: Dec 5 2025 02:47:19 2025-12-05T02:47:25.029Z,1764902845.029 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-12-05T02:47:25.030Z,1764902845.030 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-12-05T02:47:25.030Z,1764902845.030 [DAT](INFO): commRate: 600 2025-12-05T02:47:25.293Z,1764902845.293 [OnDock:Detach:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby. 2025-12-05T02:47:25.813Z,1764902845.813 [DockingStepper](INFO): Changing to mode: 1 2025-12-05T02:47:27.095Z,1764902847.095 [DAT](INFO): entering command mode 2025-12-05T02:47:27.296Z,1764902847.296 [DAT](INFO): DAT read: 2025-12-05T02:47:27.296Z,1764902847.296 [DAT](INFO): DAT read: user:1> 2025-12-05T02:47:27.297Z,1764902847.297 [DAT](INFO): setting verbose to 3 2025-12-05T02:47:27.548Z,1764902847.548 [DAT](INFO): DAT read: user:1> 2025-12-05T02:47:27.549Z,1764902847.549 [DAT](INFO): DAT read: Verbose | 3 2025-12-05T02:47:27.550Z,1764902847.550 [DAT](INFO): set verbose to 3 2025-12-05T02:47:27.550Z,1764902847.550 [DAT](INFO): setting DatVerbose to 27440 2025-12-05T02:47:27.800Z,1764902847.800 [DAT](INFO): DAT read: user:2> 2025-12-05T02:47:27.801Z,1764902847.801 [DAT](INFO): DAT read: DatVerbose | 27440 2025-12-05T02:47:27.801Z,1764902847.801 [DAT](INFO): set DatVerbose to 27440 2025-12-05T02:47:27.802Z,1764902847.802 [DAT](INFO): setting transmit power to 8 2025-12-05T02:47:28.052Z,1764902848.052 [DAT](INFO): DAT read: user:3> 2025-12-05T02:47:28.053Z,1764902848.053 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-12-05T02:47:28.053Z,1764902848.053 [DAT](INFO): set transmit power to 8 2025-12-05T02:47:28.054Z,1764902848.054 [DAT](INFO): setting local address to 11 2025-12-05T02:47:28.304Z,1764902848.304 [DAT](INFO): DAT read: user:4> 2025-12-05T02:47:28.305Z,1764902848.305 [DAT](INFO): DAT read: LocalAddr | 11 2025-12-05T02:47:28.305Z,1764902848.305 [DAT](INFO): set local address to 11 2025-12-05T02:47:28.306Z,1764902848.306 [DAT](INFO): Setting time to: 2:47:28 And date to:12/5/2025 2025-12-05T02:47:28.556Z,1764902848.556 [DAT](INFO): DAT read: user:5> 2025-12-05T02:47:28.557Z,1764902848.557 [DAT](INFO): DAT read: Fri Dec 5, 2025 02:47:28 2025-12-05T02:47:28.557Z,1764902848.557 [DAT](INFO): Local DAT time set to Fri Dec 5, 2025 02:47:28 2025-12-05T02:47:28.569Z,1764902848.569 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20251205T004645/Courier0036.lzma 2025-12-05T02:47:29.569Z,1764902849.569 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Courier0036.lzma.bak 2025-12-05T02:47:29.569Z,1764902849.569 [DataOverHttps](INFO): SBD MOMSN=26430458 2025-12-05T02:47:31.804Z,1764902851.804 [OnDock:Detach:B.Undock](INFO): Docking module at standby. 2025-12-05T02:47:32.153Z,1764902852.153 [OnDock:Detach:B.Undock](IMPORTANT): Undocking sequence complete. 2025-12-05T02:47:32.153Z,1764902852.153 [OnDock:Detach:B.Undock] Stopped 2025-12-05T02:47:32.153Z,1764902852.153 [OnDock:Detach](INFO): Completed OnDock:Detach 2025-12-05T02:47:32.154Z,1764902852.154 [OnDock:Detach] Stopped 2025-12-05T02:47:32.154Z,1764902852.154 [OnDock:Detach](DEBUG): Aggregate::uninitialize OnDock:Detach 2025-12-05T02:47:32.156Z,1764902852.156 [OnDock](INFO): Completed OnDock 2025-12-05T02:47:32.157Z,1764902852.157 [MissionManager](INFO): OnDock is completed. 2025-12-05T02:47:32.157Z,1764902852.157 [MissionManager](INFO): Uninitializing Mission OnDock 2025-12-05T02:47:32.157Z,1764902852.157 [OnDock] Stopped 2025-12-05T02:47:32.157Z,1764902852.157 [OnDock](DEBUG): Aggregate::uninitialize OnDock 2025-12-05T02:47:32.157Z,1764902852.157 [OnDock:StandardEnvelopes] Stopped 2025-12-05T02:47:32.157Z,1764902852.157 [OnDock:StandardEnvelopes](DEBUG): Aggregate::uninitialize OnDock:StandardEnvelopes 2025-12-05T02:47:32.157Z,1764902852.157 [OnDock:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-12-05T02:47:32.157Z,1764902852.157 [OnDock:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-12-05T02:47:32.157Z,1764902852.157 [OnDock:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-12-05T02:47:32.157Z,1764902852.157 [OnDock:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-12-05T02:47:32.157Z,1764902852.157 [OnDock:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-12-05T02:47:32.157Z,1764902852.157 [OnDock:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-12-05T02:47:32.158Z,1764902852.158 [OnDock:BackseatDriver] Stopped 2025-12-05T02:47:32.158Z,1764902852.158 [OnDock:BackseatDriver](DEBUG): Aggregate::uninitialize OnDock:BackseatDriver 2025-12-05T02:47:32.158Z,1764902852.158 [OnDock:BackseatDriver:A.BackseatDriver] Stopped 2025-12-05T02:47:32.158Z,1764902852.158 [OnDock:PowerOnly] Stopped 2025-12-05T02:47:32.158Z,1764902852.158 [OnDock:PowerOnly](DEBUG): Aggregate::uninitialize OnDock:PowerOnly 2025-12-05T02:47:32.158Z,1764902852.158 [OnDock:PowerOnly:E.Wait] Stopped 2025-12-05T02:47:32.158Z,1764902852.158 [OnDock:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-12-05T02:47:32.158Z,1764902852.158 [OnDock:dataRead] Stopped 2025-12-05T02:47:32.158Z,1764902852.158 [OnDock:dataRead](DEBUG): Aggregate::uninitialize OnDock:dataRead 2025-12-05T02:47:32.158Z,1764902852.158 [OnDock:dataRead:A] Stopped 2025-12-05T02:47:32.159Z,1764902852.159 [OnDock:J.] Stopped 2025-12-05T02:47:32.159Z,1764902852.159 [OnDock:J.](DEBUG): Uninitializing TrackAcousticContact. 2025-12-05T02:47:32.159Z,1764902852.159 [OnDock:PeriodicCheck] Stopped 2025-12-05T02:47:32.159Z,1764902852.159 [OnDock:PeriodicCheck](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck 2025-12-05T02:47:32.159Z,1764902852.159 [OnDock:PeriodicCheck:CheckIP] Stopped 2025-12-05T02:47:32.159Z,1764902852.159 [OnDock:PeriodicCheck:CheckIP](DEBUG): Aggregate::uninitialize OnDock:PeriodicCheck:CheckIP 2025-12-05T02:47:32.159Z,1764902852.159 [OnDock:PeriodicCheck:CheckIP:B.Wait] Stopped 2025-12-05T02:47:32.159Z,1764902852.159 [OnDock:PeriodicCheck:CheckIP:B.Wait](DEBUG): Uninitialize Wait Component. 2025-12-05T02:47:32.548Z,1764902852.548 [MissionManager](IMPORTANT): Started mission DefaultWithUndock 2025-12-05T02:47:32.549Z,1764902852.549 [DefaultWithUndock] Running Loop=1 2025-12-05T02:47:32.549Z,1764902852.549 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock 2025-12-05T02:47:32.549Z,1764902852.549 [DefaultWithUndock:A.Wait] Running Loop=1 2025-12-05T02:47:32.549Z,1764902852.549 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component. 2025-12-05T02:47:45.635Z,1764902865.635 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20251205T004645/Courier0039.lzma 2025-12-05T02:47:46.637Z,1764902866.637 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Courier0039.lzma.bak 2025-12-05T02:47:46.637Z,1764902866.637 [DataOverHttps](INFO): SBD MOMSN=26430461 2025-12-05T02:47:47.640Z,1764902867.640 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-05T02:47:56.324Z,1764902876.324 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-05T02:48:02.872Z,1764902882.872 [DefaultWithUndock:A.Wait](INFO): Done Waiting. 2025-12-05T02:48:02.873Z,1764902882.873 [DefaultWithUndock:A.Wait] Stopped 2025-12-05T02:48:02.873Z,1764902882.873 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component. 2025-12-05T02:48:02.873Z,1764902882.873 [DefaultWithUndock:LeaveDock] Running Loop=1 2025-12-05T02:48:02.873Z,1764902882.873 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock 2025-12-05T02:48:02.873Z,1764902882.873 [DefaultWithUndock:LeaveDock:A.] Running Loop=1 2025-12-05T02:48:02.873Z,1764902882.873 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact. 2025-12-05T02:48:02.874Z,1764902882.874 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1 2025-12-05T02:48:02.874Z,1764902882.874 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-12-05T02:48:02.874Z,1764902882.874 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-12-05T02:48:03.293Z,1764902883.293 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize. 2025-12-05T02:48:03.293Z,1764902883.293 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-12-05T02:48:03.294Z,1764902883.294 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth 0.262018 m. 2025-12-05T02:48:03.294Z,1764902883.294 [DefaultWithUndock:LeaveDock:A.] Stopped 2025-12-05T02:48:03.295Z,1764902883.295 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact. 2025-12-05T02:48:03.697Z,1764902883.697 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer. 2025-12-05T02:48:04.207Z,1764902884.207 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-12-05T02:48:04.324Z,1764902884.324 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-12-05T02:48:05.387Z,1764902885.387 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-05T02:48:07.825Z,1764902887.825 [DockingStepper](INFO): Changing to mode: 1 2025-12-05T02:48:14.181Z,1764902894.181 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-05T02:48:23.200Z,1764902903.200 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-05T02:48:23.894Z,1764902903.894 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: 108.90 m. Transitioning docking module to standby. 2025-12-05T02:48:24.277Z,1764902904.277 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby. 2025-12-05T02:48:24.690Z,1764902904.690 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete. 2025-12-05T02:48:24.690Z,1764902904.690 [DefaultWithUndock:LeaveDock:B.Undock] Stopped 2025-12-05T02:48:24.690Z,1764902904.690 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock 2025-12-05T02:48:24.690Z,1764902904.690 [DefaultWithUndock:LeaveDock] Stopped 2025-12-05T02:48:24.691Z,1764902904.691 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock 2025-12-05T02:48:24.691Z,1764902904.691 [DefaultWithUndock:SurfaceDefault] Running Loop=1 2025-12-05T02:48:24.691Z,1764902904.691 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault 2025-12-05T02:48:24.691Z,1764902904.691 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1 2025-12-05T02:48:24.691Z,1764902904.691 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-05T02:48:24.691Z,1764902904.691 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-12-05T02:48:24.692Z,1764902904.692 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-12-05T02:48:24.692Z,1764902904.692 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-12-05T02:48:24.692Z,1764902904.692 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-12-05T02:48:24.693Z,1764902904.693 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-05T02:48:24.693Z,1764902904.693 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-05T02:48:25.085Z,1764902905.085 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1 2025-12-05T02:48:25.085Z,1764902905.085 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn 2025-12-05T02:48:25.085Z,1764902905.085 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1 2025-12-05T02:48:27.107Z,1764902907.107 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,024826.00,A,3646.70211,N,12151.61459,W,0.175,204.10,051225,,,D*7D 2025-12-05T02:48:27.109Z,1764902907.109 [NAL9602](INFO): GPS fix at 20251205T024826: (36.778368, -121.860243) 2025-12-05T02:48:27.132Z,1764902907.132 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped 2025-12-05T02:48:27.133Z,1764902907.133 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1 2025-12-05T02:48:30.184Z,1764902910.184 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20251205T024633/Courier0000.lzma 2025-12-05T02:48:31.185Z,1764902911.185 [DataOverHttps](INFO): Moved sent file to Logs/20251205T024633/Courier0000.lzma.bak 2025-12-05T02:48:31.185Z,1764902911.185 [DataOverHttps](INFO): SBD MOMSN=26430463 2025-12-05T02:48:46.306Z,1764902926.306 [NAL9602](INFO): SBD MO Status=1, MOMSN=4474, MT Status=0, MTMSN=0 2025-12-05T02:48:46.306Z,1764902926.306 [NAL9602](INFO): No messages in MT queue 2025-12-05T02:48:48.733Z,1764902928.733 [DataOverHttps](INFO): Sending 428 bytes from file Logs/20251205T004645/Express0004.lzma 2025-12-05T02:48:49.737Z,1764902929.737 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0004.lzma.bak 2025-12-05T02:48:49.737Z,1764902929.737 [DataOverHttps](INFO): SBD MOMSN=26430467 2025-12-05T02:49:05.909Z,1764902945.909 [DataOverHttps](INFO): Sending 550 bytes from file Logs/20251205T004645/Express0007.lzma 2025-12-05T02:49:06.909Z,1764902946.909 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0007.lzma.bak 2025-12-05T02:49:06.909Z,1764902946.909 [DataOverHttps](INFO): SBD MOMSN=26430477 2025-12-05T02:49:17.020Z,1764902957.020 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-05T02:49:23.113Z,1764902963.113 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20251205T004645/Express0010.lzma 2025-12-05T02:49:24.106Z,1764902964.106 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0010.lzma.bak 2025-12-05T02:49:24.106Z,1764902964.106 [DataOverHttps](INFO): SBD MOMSN=26430496 2025-12-05T02:49:41.316Z,1764902981.316 [DataOverHttps](INFO): Sending 406 bytes from file Logs/20251205T004645/Express0013.lzma 2025-12-05T02:49:42.317Z,1764902982.317 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0013.lzma.bak 2025-12-05T02:49:42.317Z,1764902982.317 [DataOverHttps](INFO): SBD MOMSN=26430498 2025-12-05T02:49:58.512Z,1764902998.512 [DataOverHttps](INFO): Sending 522 bytes from file Logs/20251205T004645/Express0016.lzma 2025-12-05T02:49:59.513Z,1764902999.513 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0016.lzma.bak 2025-12-05T02:49:59.514Z,1764902999.514 [DataOverHttps](INFO): SBD MOMSN=26430510 2025-12-05T02:50:16.972Z,1764903016.972 [DataOverHttps](INFO): Sending 434 bytes from file Logs/20251205T004645/Express0019.lzma 2025-12-05T02:50:17.974Z,1764903017.974 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0019.lzma.bak 2025-12-05T02:50:17.974Z,1764903017.974 [DataOverHttps](INFO): SBD MOMSN=26430527 2025-12-05T02:50:34.344Z,1764903034.344 [DataOverHttps](INFO): Sending 413 bytes from file Logs/20251205T004645/Express0022.lzma 2025-12-05T02:50:35.345Z,1764903035.345 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0022.lzma.bak 2025-12-05T02:50:35.346Z,1764903035.346 [DataOverHttps](INFO): SBD MOMSN=26430543 2025-12-05T02:50:51.532Z,1764903051.532 [DataOverHttps](INFO): Sending 308 bytes from file Logs/20251205T004645/Express0025.lzma 2025-12-05T02:50:52.534Z,1764903052.534 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0025.lzma.bak 2025-12-05T02:50:52.534Z,1764903052.534 [DataOverHttps](INFO): SBD MOMSN=26430558 2025-12-05T02:51:08.760Z,1764903068.760 [DataOverHttps](INFO): Sending 412 bytes from file Logs/20251205T004645/Express0028.lzma 2025-12-05T02:51:09.765Z,1764903069.765 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0028.lzma.bak 2025-12-05T02:51:09.765Z,1764903069.765 [DataOverHttps](INFO): SBD MOMSN=26430564 2025-12-05T02:51:25.956Z,1764903085.956 [DataOverHttps](INFO): Sending 588 bytes from file Logs/20251205T004645/Express0031.lzma 2025-12-05T02:51:26.957Z,1764903086.957 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0031.lzma.bak 2025-12-05T02:51:26.957Z,1764903086.957 [DataOverHttps](INFO): SBD MOMSN=26430586 2025-12-05T02:51:43.396Z,1764903103.396 [DataOverHttps](INFO): Sending 322 bytes from file Logs/20251205T004645/Express0034.lzma 2025-12-05T02:51:44.398Z,1764903104.398 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0034.lzma.bak 2025-12-05T02:51:44.398Z,1764903104.398 [DataOverHttps](INFO): SBD MOMSN=26430629 2025-12-05T02:52:00.612Z,1764903120.612 [DataOverHttps](INFO): Sending 661 bytes from file Logs/20251205T004645/Express0037.lzma 2025-12-05T02:52:01.613Z,1764903121.613 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0037.lzma.bak 2025-12-05T02:52:01.613Z,1764903121.613 [DataOverHttps](INFO): SBD MOMSN=26430633 2025-12-05T02:52:17.819Z,1764903137.819 [DataOverHttps](INFO): Sending 59 bytes from file Logs/20251205T004645/Express0038.lzma 2025-12-05T02:52:18.821Z,1764903138.821 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0038.lzma.bak 2025-12-05T02:52:18.822Z,1764903138.822 [DataOverHttps](INFO): SBD MOMSN=26430646 2025-12-05T02:52:35.008Z,1764903155.008 [DataOverHttps](INFO): Sending 465 bytes from file Logs/20251205T004645/Express0040.lzma 2025-12-05T02:52:36.010Z,1764903156.010 [DataOverHttps](INFO): Moved sent file to Logs/20251205T004645/Express0040.lzma.bak 2025-12-05T02:52:36.010Z,1764903156.010 [DataOverHttps](INFO): SBD MOMSN=26430649 2025-12-05T02:52:52.366Z,1764903172.366 [DataOverHttps](INFO): Sending 883 bytes from file Logs/20251205T024633/Express0001.lzma 2025-12-05T02:52:53.365Z,1764903173.365 [DataOverHttps](INFO): Moved sent file to Logs/20251205T024633/Express0001.lzma.bak 2025-12-05T02:52:53.365Z,1764903173.365 [DataOverHttps](INFO): SBD MOMSN=26430653 2025-12-05T02:52:54.797Z,1764903174.797 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped 2025-12-05T02:52:54.797Z,1764903174.797 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1 2025-12-05T02:52:54.797Z,1764903174.797 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-05T02:53:02.928Z,1764903182.928 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-05T02:53:11.656Z,1764903191.656 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-05T02:53:24.256Z,1764903204.256 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-05T02:53:39.888Z,1764903219.888 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-05T02:53:52.637Z,1764903232.637 [DataOverHttps](IMPORTANT): SBD MTMSN=20251205T025351 2025-12-05T02:54:06.795Z,1764903246.795 [DataOverHttps](INFO): Received command: restart sys 2025-12-05T02:54:06.885Z,1764903246.885 [CommandExec](IMPORTANT): got command restart system 2025-12-05T02:54:08.800Z,1764903248.800 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-05T02:54:09.116Z,1764903249.116 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2025-12-05T02:54:09.116Z,1764903249.116 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-05T02:54:09.116Z,1764903249.116 [CommandExec](INFO): Uninitializing the command executive. 2025-12-05T02:54:09.116Z,1764903249.116 [CommandExec](INFO): Uninitializing the command scheduler. 2025-12-05T02:54:09.117Z,1764903249.117 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-12-05T02:54:09.259Z,1764903249.259 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-12-05T02:54:09.260Z,1764903249.260 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-12-05T02:54:09.260Z,1764903249.260 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-12-05T02:54:09.261Z,1764903249.261 [NavChartDb](INFO): Join timeout helper Thread ID is 2169 2025-12-05T02:54:09.412Z,1764903249.412 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-05T02:54:09.412Z,1764903249.412 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-12-05T02:54:09.420Z,1764903249.420 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-12-05T02:54:09.420Z,1764903249.420 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-12-05T02:54:09.420Z,1764903249.420 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2170 2025-12-05T02:54:09.544Z,1764903249.544 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-05T02:54:09.544Z,1764903249.544 [WetLabsBB2FL](INFO): Powering down 2025-12-05T02:54:09.545Z,1764903249.545 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-12-05T02:54:09.551Z,1764903249.551 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-12-05T02:54:09.551Z,1764903249.551 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-12-05T02:54:09.552Z,1764903249.552 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2171 2025-12-05T02:54:09.848Z,1764903249.848 [CTD_Seabird](INFO): Powering down 2025-12-05T02:54:09.860Z,1764903249.860 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-05T02:54:09.860Z,1764903249.860 [CTD_Seabird](INFO): Powering down 2025-12-05T02:54:09.872Z,1764903249.872 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-12-05T02:54:09.876Z,1764903249.876 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-12-05T02:54:09.876Z,1764903249.876 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-12-05T02:54:09.876Z,1764903249.876 [Radio_Surface](INFO): Join timeout helper Thread ID is 2172 2025-12-05T02:54:10.268Z,1764903250.268 [Radio_Surface](INFO): Powering down 2025-12-05T02:54:10.269Z,1764903250.269 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-05T02:54:10.269Z,1764903250.269 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-12-05T02:54:10.276Z,1764903250.276 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-12-05T02:54:10.276Z,1764903250.276 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-12-05T02:54:10.276Z,1764903250.276 [Onboard](INFO): Join timeout helper Thread ID is 2173 2025-12-05T02:54:11.932Z,1764903251.932 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-05T02:54:11.932Z,1764903251.932 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-12-05T02:5