2025-08-24T05:17:03.314Z,1756012623.314 [CommandExec](IMPORTANT): got command restart logs 2025-08-24T05:17:11.048Z,1756012631.048 [CommandExec](IMPORTANT): got command configSet CBIT.runNavErrorCritical 0 bool persist 2025-08-24T05:17:12.960Z,1756012632.960 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:17:25.991Z,1756012645.991 [NAL9602](INFO): SBD MO Status=2, MOMSN=22440, MT Status=2, MTMSN=0 2025-08-24T05:17:25.991Z,1756012645.991 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T05:17:44.088Z,1756012664.088 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:18:02.954Z,1756012682.954 [NAL9602](INFO): SBD MO Status=2, MOMSN=22440, MT Status=2, MTMSN=0 2025-08-24T05:18:02.955Z,1756012682.955 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T05:18:15.228Z,1756012695.228 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:18:30.613Z,1756012710.613 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=22440, MT Status=1, MTMSN=4424 2025-08-24T05:18:30.660Z,1756012710.660 [NAL9602](INFO): Sent 40 bytes from file Logs/20250823T130106/Courier0069.lzma 2025-08-24T05:18:30.660Z,1756012710.660 [NAL9602](INFO): Packets left to send: 0 2025-08-24T05:18:31.100Z,1756012711.100 [NAL9602](INFO): Received command: schedule resume 2025-08-24T05:18:31.102Z,1756012711.102 [CommandExec](IMPORTANT): got command schedule resume 2025-08-24T05:18:46.352Z,1756012726.352 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:18:52.241Z,1756012732.241 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=22441, MT Status=1, MTMSN=4425 2025-08-24T05:18:52.296Z,1756012732.296 [NAL9602](INFO): Sent 210 bytes from file Logs/20250823T130106/Express0061.lzma 2025-08-24T05:18:52.296Z,1756012732.296 [NAL9602](INFO): Packets left to send: 0 2025-08-24T05:18:52.816Z,1756012732.816 [NAL9602](INFO): Received command: sched 20250824T13 "restart logs;configSet CBIT.runNavErrorCritical 1 bool persist" 2025-08-24T05:18:52.868Z,1756012732.868 [CommandExec](IMPORTANT): got command schedule 20250824T130000 "restart logs;configSet CBIT.runNavErrorCritical 1 bool persist" 2025-08-24T05:18:52.869Z,1756012732.869 [CommandExec](IMPORTANT): Scheduled #18: "restart logs;configSet CBIT.runNavErrorCritical 1 bool persist", AT TIME: 2025-08-24T13:00:00Z 2025-08-24T05:19:17.476Z,1756012757.476 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:19:42.588Z,1756012782.588 [NAL9602](INFO): SBD MO Status=1, MOMSN=22442, MT Status=0, MTMSN=0 2025-08-24T05:19:42.640Z,1756012782.640 [NAL9602](INFO): Sent 326 bytes from file Logs/20250823T130106/Express0064.lzma 2025-08-24T05:19:42.640Z,1756012782.640 [NAL9602](INFO): Packets left to send: 0 2025-08-24T05:19:48.600Z,1756012788.600 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:19:59.393Z,1756012799.393 [NAL9602](INFO): SBD MO Status=2, MOMSN=22443, MT Status=2, MTMSN=0 2025-08-24T05:19:59.393Z,1756012799.393 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T05:20:19.724Z,1756012819.724 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:20:29.017Z,1756012829.017 [NAL9602](INFO): SBD MO Status=1, MOMSN=22443, MT Status=0, MTMSN=0 2025-08-24T05:20:29.064Z,1756012829.064 [NAL9602](INFO): Sent 332 bytes from file Logs/20250823T130106/Express0067.lzma 2025-08-24T05:20:29.065Z,1756012829.065 [NAL9602](INFO): Packets left to send: 1 2025-08-24T05:20:50.848Z,1756012850.848 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:20:51.858Z,1756012851.858 [NAL9602](INFO): SBD MO Status=1, MOMSN=22444, MT Status=0, MTMSN=0 2025-08-24T05:20:51.912Z,1756012851.912 [NAL9602](INFO): Sent 133 bytes from file Logs/20250823T130106/Express0067.lzma 2025-08-24T05:20:51.912Z,1756012851.912 [NAL9602](INFO): Packets left to send: 0 2025-08-24T05:21:04.601Z,1756012864.601 [NAL9602](INFO): SBD MO Status=2, MOMSN=22445, MT Status=2, MTMSN=0 2025-08-24T05:21:04.601Z,1756012864.601 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T05:21:21.974Z,1756012881.974 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:21:30.692Z,1756012890.692 [NAL9602](INFO): SBD MO Status=2, MOMSN=22445, MT Status=2, MTMSN=0 2025-08-24T05:21:30.692Z,1756012890.692 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T05:21:47.891Z,1756012907.891 [NAL9602](INFO): SBD MO Status=2, MOMSN=22445, MT Status=2, MTMSN=0 2025-08-24T05:21:47.892Z,1756012907.892 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T05:21:53.141Z,1756012913.141 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:22:13.553Z,1756012933.553 [NAL9602](INFO): SBD MO Status=2, MOMSN=22445, MT Status=2, MTMSN=0 2025-08-24T05:22:13.553Z,1756012933.553 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T05:22:24.264Z,1756012944.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:22:31.150Z,1756012951.150 [NAL9602](INFO): SBD MO Status=1, MOMSN=22445, MT Status=0, MTMSN=0 2025-08-24T05:22:31.212Z,1756012951.212 [NAL9602](INFO): Sent 122 bytes from file Logs/20250823T130106/Express0070.lzma 2025-08-24T05:22:31.212Z,1756012951.212 [NAL9602](INFO): Packets left to send: 0 2025-08-24T05:22:47.314Z,1756012967.314 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T05:22:47.314Z,1756012967.314 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T05:22:55.388Z,1756012975.388 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:23:04.274Z,1756012984.274 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T05:23:04.274Z,1756012984.274 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T05:23:19.991Z,1756012999.991 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T05:23:19.991Z,1756012999.991 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T05:23:25.002Z,1756013005.002 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-08-24T05:23:26.512Z,1756013006.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:23:45.851Z,1756013025.851 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T05:23:45.851Z,1756013025.851 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T05:23:50.868Z,1756013030.868 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-08-24T05:23:57.636Z,1756013037.636 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:24:00.157Z,1756013040.157 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-08-24T05:24:02.584Z,1756013042.584 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-08-24T05:24:27.865Z,1756013067.865 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T05:24:27.865Z,1756013067.865 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T05:24:28.776Z,1756013068.776 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:24:32.874Z,1756013072.874 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-08-24T05:24:41.772Z,1756013081.772 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-08-24T05:24:45.835Z,1756013085.835 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-08-24T05:24:54.722Z,1756013094.722 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-08-24T05:24:59.912Z,1756013099.912 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:25:00.640Z,1756013100.640 [keepstation:NeedComms:D](INFO): Timed out from 2025-08-24T05:15:00.3Z 2025-08-24T05:25:00.640Z,1756013100.640 [keepstation:NeedComms:D:A_Timeout] Running Loop=1 2025-08-24T05:25:00.640Z,1756013100.640 [keepstation:NeedComms:D:A_Timeout](DEBUG): Aggregate::initialize keepstation:NeedComms:D:A_Timeout 2025-08-24T05:25:00.641Z,1756013100.641 [keepstation:NeedComms:D:A_Timeout:A] Running Loop=1 2025-08-24T05:25:00.641Z,1756013100.641 [keepstation:NeedComms:D:A_Timeout:A](IMPORTANT): Comms timed out after 10.000000 min minutes 2025-08-24T05:25:00.641Z,1756013100.641 [keepstation:NeedComms:D:A_Timeout:A] Stopped 2025-08-24T05:25:00.641Z,1756013100.641 [keepstation:NeedComms:D:A_Timeout](INFO): Completed keepstation:NeedComms:D:A_Timeout 2025-08-24T05:25:00.642Z,1756013100.642 [keepstation:NeedComms:D] Stopped 2025-08-24T05:25:00.642Z,1756013100.642 [keepstation:NeedComms:E] Running Loop=1 2025-08-24T05:25:03.202Z,1756013103.202 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2025-08-24T05:25:04.245Z,1756013104.245 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,052504.00,A,3657.91002,N,12249.73995,W,0.680,133.25,240825,,,A*7B 2025-08-24T05:25:04.248Z,1756013104.248 [NAL9602](INFO): GPS fix at 20250824T052504: (36.965167, -122.828999) 2025-08-24T05:25:04.260Z,1756013104.260 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Rest->Waypoint 2025-08-24T05:25:04.263Z,1756013104.263 [keepstation:NeedComms:E] Stopped 2025-08-24T05:25:04.264Z,1756013104.264 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-08-24T05:25:04.264Z,1756013104.264 [keepstation:NeedComms] Stopped 2025-08-24T05:25:04.264Z,1756013104.264 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-08-24T05:25:04.264Z,1756013104.264 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-08-24T05:25:04.264Z,1756013104.264 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-08-24T05:25:04.684Z,1756013104.684 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2025-08-24T05:25:04.684Z,1756013104.684 [keepstation:OnStation] Stopped 2025-08-24T05:25:04.684Z,1756013104.684 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2025-08-24T05:25:04.684Z,1756013104.684 [keepstation:KeepStation] Running Loop=1 2025-08-24T05:25:04.684Z,1756013104.684 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2025-08-24T05:25:04.684Z,1756013104.684 [keepstation:KeepStation:Dive] Running Loop=1 2025-08-24T05:25:04.685Z,1756013104.685 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-08-24T05:25:04.685Z,1756013104.685 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-08-24T05:25:04.685Z,1756013104.685 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-08-24T05:25:04.685Z,1756013104.685 [keepstation:KeepStation:B.Wait] Running Loop=1 2025-08-24T05:25:04.685Z,1756013104.685 [keepstation:KeepStation:B.Wait](DEBUG): Initialize Wait Component. 2025-08-24T05:25:05.080Z,1756013105.080 [keepstation:KeepStation:Dive] Running Loop=1 2025-08-24T05:25:31.036Z,1756013131.036 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:25:36.927Z,1756013136.927 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-24T05:26:02.160Z,1756013162.160 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:26:15.728Z,1756013175.728 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T05:26:33.285Z,1756013193.285 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:26:39.804Z,1756013199.804 [Radio_Surface](INFO): Powering down 2025-08-24T05:27:04.408Z,1756013224.408 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T05:27:05.412Z,1756013225.412 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-08-24T05:34:04.184Z,1756013644.184 [keepstation:DiveCmd:StationKeep:C.KeepStation](INFO): Waypoint->Rest 2025-08-24T05:46:16.220Z,1756014376.220 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T05:55:24.216Z,1756014924.216 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-08-24T05:55:28.647Z,1756014928.647 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002131 CHAN A1 (24V): 0.004179 CHAN A2 (12V): -0.009004 CHAN A3 (5V): -0.011258 CHAN B0 (3.3V): -0.003492 CHAN B1 (3.15aV): -0.002635 CHAN B2 (3.15bV): -0.001840 CHAN B3 (GND): -0.002311 OPEN: 0.012116 Full Scale: +/- 1 mA 2025-08-24T06:06:16.926Z,1756015576.926 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T06:06:41.965Z,1756015601.965 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-24T06:06:41.966Z,1756015601.966 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-24T06:06:41.967Z,1756015601.967 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-24T06:06:41.968Z,1756015601.968 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-08-24T06:06:41.971Z,1756015601.971 [BPC1](INFO): Received data from all battery sticks. 2025-08-24T06:16:17.258Z,1756016177.258 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T06:25:04.508Z,1756016704.508 [keepstation:SurfaceComms] Running Loop=1 2025-08-24T06:25:04.508Z,1756016704.508 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-08-24T06:25:04.508Z,1756016704.508 [keepstation:SurfaceComms:A] Running Loop=1 2025-08-24T06:25:04.509Z,1756016704.509 [keepstation:SurfaceComms:A] Stopped 2025-08-24T06:25:04.509Z,1756016704.509 [keepstation:SurfaceComms:B] Running Loop=1 2025-08-24T06:25:04.509Z,1756016704.509 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-08-24T06:25:04.894Z,1756016704.894 [keepstation:KeepStation:Dive] Stopped 2025-08-24T06:25:04.894Z,1756016704.894 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-08-24T06:25:04.894Z,1756016704.894 [keepstation:KeepStation:Dive:A] Stopped 2025-08-24T06:25:04.894Z,1756016704.894 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-08-24T06:25:04.895Z,1756016704.895 [keepstation:DiveCmd:StationKeep] Stopped 2025-08-24T06:25:04.895Z,1756016704.895 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-08-24T06:25:04.895Z,1756016704.895 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-08-24T06:25:04.895Z,1756016704.895 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-08-24T06:25:04.895Z,1756016704.895 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-08-24T06:25:04.895Z,1756016704.895 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-08-24T06:25:04.897Z,1756016704.897 [keepstation:NeedComms] Running Loop=1 2025-08-24T06:25:04.897Z,1756016704.897 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-08-24T06:25:04.897Z,1756016704.897 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-08-24T06:25:04.897Z,1756016704.897 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-08-24T06:25:04.898Z,1756016704.898 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-08-24T06:25:04.898Z,1756016704.898 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-08-24T06:25:04.898Z,1756016704.898 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-08-24T06:25:04.899Z,1756016704.899 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-08-24T06:25:04.899Z,1756016704.899 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-08-24T06:25:04.900Z,1756016704.900 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-08-24T06:25:04.900Z,1756016704.900 [keepstation:NeedComms:A] Running Loop=1 2025-08-24T06:25:04.901Z,1756016704.901 [keepstation:NeedComms:A](INFO): last time_fix was: 1756013104.000000 second since 1970/01/01T00:00:00Z 2025-08-24T06:25:04.901Z,1756016704.901 [keepstation:NeedComms:A] Stopped 2025-08-24T06:26:17.448Z,1756016777.448 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T06:26:40.494Z,1756016800.494 [keepstation:NeedComms:C] Running Loop=1 2025-08-24T06:26:41.200Z,1756016801.200 [Radio_Surface](INFO): Powering up 2025-08-24T06:26:43.728Z,1756016803.728 [NAL9602](FAULT): LCB fault: Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated. 2025-08-24T06:26:43.728Z,1756016803.728 [NAL9602] Hardware Fault, FailCount= 1 2025-08-24T06:26:43.728Z,1756016803.728 [NAL9602](ERROR): Hardware Fault 2025-08-24T06:26:43.915Z,1756016803.915 [CBIT](ERROR): Hardware Fault in component: NAL9602 2025-08-24T06:26:44.133Z,1756016804.133 [NAL9602](INFO): Powering down 2025-08-24T06:26:45.139Z,1756016805.139 [CBIT](INFO): Clearing failed state for component NAL9602 2025-08-24T06:26:45.139Z,1756016805.139 [NAL9602] No Fault, FailCount= 1 2025-08-24T06:26:47.152Z,1756016807.152 [DataOverHttps](INFO): Radio surface powered ON. 2025-08-24T06:27:14.434Z,1756016834.434 [NAL9602](INFO): Powering up NAL9602 2025-08-24T06:27:17.272Z,1756016837.272 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:27:25.344Z,1756016845.344 [NAL9602](INFO): NAL9602 initialized 2025-08-24T06:27:48.396Z,1756016868.396 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:27:56.448Z,1756016876.448 [NAL9602](ERROR): parseGSV uart error: serial timeout 2025-08-24T06:28:19.520Z,1756016899.520 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:28:50.648Z,1756016930.648 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:29:21.772Z,1756016961.772 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:29:43.913Z,1756016983.913 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T06:29:43.913Z,1756016983.913 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T06:29:52.896Z,1756016992.896 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:30:24.028Z,1756017024.028 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:30:55.152Z,1756017055.152 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:31:26.276Z,1756017086.276 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:31:38.247Z,1756017098.247 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T06:31:38.247Z,1756017098.247 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T06:31:57.400Z,1756017117.400 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:32:12.989Z,1756017132.989 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T06:32:12.989Z,1756017132.989 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T06:32:28.524Z,1756017148.524 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:32:30.357Z,1756017150.357 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T06:32:30.357Z,1756017150.357 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T06:32:59.648Z,1756017179.648 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:33:05.913Z,1756017185.913 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T06:33:05.913Z,1756017185.913 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T06:33:06.317Z,1756017186.317 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-08-24T06:33:07.534Z,1756017187.534 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,063307.00,A,3657.81127,N,12249.41467,W,0.991,188.71,240825,,,A*74 2025-08-24T06:33:07.537Z,1756017187.537 [NAL9602](INFO): GPS fix at 20250824T063307: (36.963521, -122.823578) 2025-08-24T06:33:07.549Z,1756017187.549 [UniversalFixResidualReporter](INFO): Fix residual: 139.4 %DT, over the last 607.2 m. Residual distance 846.3 m at bearing -46.9 degrees. Fix at (36.9635, -122.8236) with 515.3 m made good. 2025-08-24T06:33:07.552Z,1756017187.552 [keepstation:NeedComms:C] Stopped 2025-08-24T06:33:07.553Z,1756017187.553 [keepstation:NeedComms:D] Running Loop=1 2025-08-24T06:33:07.711Z,1756017187.711 [CBIT](IMPORTANT): Navigation error has exceeded threshold. 2025-08-24T06:33:30.793Z,1756017210.793 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:33:49.823Z,1756017229.823 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T06:33:49.833Z,1756017229.833 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T06:34:01.924Z,1756017241.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:34:33.050Z,1756017273.050 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:35:04.172Z,1756017304.172 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:35:35.304Z,1756017335.304 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:36:06.428Z,1756017366.428 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:36:17.479Z,1756017377.479 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T06:36:28.789Z,1756017388.789 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T06:36:28.789Z,1756017388.789 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T06:36:37.560Z,1756017397.560 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:37:08.700Z,1756017428.700 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:37:17.495Z,1756017437.495 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T06:37:17.495Z,1756017437.495 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T06:37:39.850Z,1756017459.850 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:38:10.980Z,1756017490.980 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:38:42.104Z,1756017522.104 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:39:13.244Z,1756017553.244 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:39:29.966Z,1756017569.966 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T06:39:29.966Z,1756017569.966 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T06:39:44.368Z,1756017584.368 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:40:00.456Z,1756017600.456 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T06:40:00.456Z,1756017600.456 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T06:40:15.492Z,1756017615.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:40:46.624Z,1756017646.624 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:40:47.093Z,1756017647.093 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T06:40:47.093Z,1756017647.093 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T06:41:17.748Z,1756017677.748 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:41:48.872Z,1756017708.872 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:41:49.165Z,1756017709.165 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T06:41:49.165Z,1756017709.165 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T06:42:19.996Z,1756017739.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:42:51.120Z,1756017771.120 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:42:54.005Z,1756017774.005 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T06:42:54.005Z,1756017774.005 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T06:43:07.782Z,1756017787.782 [keepstation:NeedComms:D](INFO): Timed out from 2025-08-24T06:33:07.6Z 2025-08-24T06:43:07.782Z,1756017787.782 [keepstation:NeedComms:D:A_Timeout] Running Loop=1 2025-08-24T06:43:07.783Z,1756017787.783 [keepstation:NeedComms:D:A_Timeout](DEBUG): Aggregate::initialize keepstation:NeedComms:D:A_Timeout 2025-08-24T06:43:07.786Z,1756017787.786 [keepstation:NeedComms:D:A_Timeout:A] Running Loop=1 2025-08-24T06:43:07.792Z,1756017787.792 [keepstation:NeedComms:D:A_Timeout:A](IMPORTANT): Comms timed out after 10.000000 min minutes 2025-08-24T06:43:07.792Z,1756017787.792 [keepstation:NeedComms:D:A_Timeout:A] Stopped 2025-08-24T06:43:07.792Z,1756017787.792 [keepstation:NeedComms:D:A_Timeout](INFO): Completed keepstation:NeedComms:D:A_Timeout 2025-08-24T06:43:07.792Z,1756017787.792 [keepstation:NeedComms:D] Stopped 2025-08-24T06:43:07.792Z,1756017787.792 [keepstation:NeedComms:E] Running Loop=1 2025-08-24T06:43:11.365Z,1756017791.365 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,064311.00,A,3657.75539,N,12249.45511,W,0.739,180.51,240825,,,D*73 2025-08-24T06:43:11.367Z,1756017791.367 [NAL9602](INFO): GPS fix at 20250824T064311: (36.962590, -122.824252) 2025-08-24T06:43:11.380Z,1756017791.380 [keepstation:NeedComms:E] Stopped 2025-08-24T06:43:11.381Z,1756017791.381 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-08-24T06:43:11.381Z,1756017791.381 [keepstation:NeedComms] Stopped 2025-08-24T06:43:11.381Z,1756017791.381 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-08-24T06:43:11.381Z,1756017791.381 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-08-24T06:43:11.381Z,1756017791.381 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-08-24T06:43:11.797Z,1756017791.797 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-08-24T06:43:11.798Z,1756017791.798 [keepstation:SurfaceComms:B] Stopped 2025-08-24T06:43:11.798Z,1756017791.798 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-08-24T06:43:11.798Z,1756017791.798 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-08-24T06:43:11.798Z,1756017791.798 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-08-24T06:43:11.798Z,1756017791.798 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-08-24T06:43:12.188Z,1756017792.188 [keepstation:SurfaceComms:setTransit] Stopped 2025-08-24T06:43:12.188Z,1756017792.188 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-08-24T06:43:12.188Z,1756017792.188 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-08-24T06:43:12.189Z,1756017792.189 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-08-24T06:43:12.189Z,1756017792.189 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-08-24T06:43:12.189Z,1756017792.189 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-08-24T06:43:12.587Z,1756017792.587 [keepstation:SurfaceComms:setStation] Stopped 2025-08-24T06:43:12.587Z,1756017792.587 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-08-24T06:43:12.587Z,1756017792.587 [keepstation:SurfaceComms:setStation:A] Stopped 2025-08-24T06:43:12.587Z,1756017792.587 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-08-24T06:43:12.587Z,1756017792.587 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-08-24T06:43:12.587Z,1756017792.587 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-08-24T06:43:12.995Z,1756017792.995 [keepstation:SurfaceComms:setSink:A] Stopped 2025-08-24T06:43:12.996Z,1756017792.996 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-08-24T06:43:12.996Z,1756017792.996 [keepstation:SurfaceComms:setSink] Stopped 2025-08-24T06:43:12.996Z,1756017792.996 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-08-24T06:43:12.996Z,1756017792.996 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-08-24T06:43:12.996Z,1756017792.996 [keepstation:SurfaceComms] Stopped 2025-08-24T06:43:12.996Z,1756017792.996 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-08-24T06:43:13.395Z,1756017793.395 [keepstation:KeepStation:Dive] Running Loop=1 2025-08-24T06:43:13.396Z,1756017793.396 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-08-24T06:43:13.396Z,1756017793.396 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-08-24T06:43:13.396Z,1756017793.396 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-08-24T06:43:13.396Z,1756017793.396 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-08-24T06:43:13.396Z,1756017793.396 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-08-24T06:43:13.397Z,1756017793.397 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-08-24T06:43:13.397Z,1756017793.397 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T06:43:13.397Z,1756017793.397 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-08-24T06:43:13.397Z,1756017793.397 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-08-24T06:43:13.397Z,1756017793.397 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-08-24T06:43:13.397Z,1756017793.397 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-08-24T06:43:13.397Z,1756017793.397 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-08-24T06:43:13.398Z,1756017793.398 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-08-24T06:43:13.398Z,1756017793.398 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-08-24T06:43:13.398Z,1756017793.398 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-08-24T06:43:13.398Z,1756017793.398 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-08-24T06:43:22.244Z,1756017802.244 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:43:43.685Z,1756017823.685 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-24T06:43:53.369Z,1756017833.369 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:44:24.508Z,1756017864.508 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:44:55.633Z,1756017895.633 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:45:26.772Z,1756017926.772 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:45:35.312Z,1756017935.312 [Radio_Surface](INFO): Powering down 2025-08-24T06:45:57.897Z,1756017957.897 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T06:45:58.902Z,1756017958.902 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-08-24T06:46:13.605Z,1756017973.605 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-08-24T06:43:13.4Z 2025-08-24T06:46:13.605Z,1756017973.605 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2025-08-24T06:46:13.605Z,1756017973.605 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2025-08-24T06:46:13.606Z,1756017973.606 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2025-08-24T06:46:13.606Z,1756017973.606 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 8.587927 m 2025-08-24T06:46:13.606Z,1756017973.606 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2025-08-24T06:46:13.606Z,1756017973.606 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2025-08-24T06:46:13.606Z,1756017973.606 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-08-24T06:46:13.606Z,1756017973.606 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-08-24T06:46:13.607Z,1756017973.607 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-08-24T06:46:13.607Z,1756017973.607 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-08-24T06:46:14.017Z,1756017974.017 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-08-24T06:46:14.017Z,1756017974.017 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-08-24T06:46:14.017Z,1756017974.017 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-08-24T06:46:14.017Z,1756017974.017 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-08-24T06:46:14.017Z,1756017974.017 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T06:46:14.017Z,1756017974.017 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-08-24T06:46:14.410Z,1756017974.410 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-08-24T06:46:14.410Z,1756017974.410 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T06:46:14.410Z,1756017974.410 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-08-24T06:46:14.410Z,1756017974.410 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T06:46:14.411Z,1756017974.411 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T06:46:14.411Z,1756017974.411 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-08-24T06:46:14.411Z,1756017974.411 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T06:46:14.411Z,1756017974.411 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-08-24T06:46:14.411Z,1756017974.411 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-08-24T06:46:14.411Z,1756017974.411 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-08-24T06:46:14.412Z,1756017974.412 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-08-24T06:46:14.412Z,1756017974.412 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-08-24T06:46:14.814Z,1756017974.814 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-08-24T06:46:14.814Z,1756017974.814 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-08-24T06:46:14.814Z,1756017974.814 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-08-24T06:46:14.814Z,1756017974.814 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-08-24T06:46:14.814Z,1756017974.814 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-08-24T06:46:14.814Z,1756017974.814 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-08-24T06:46:14.814Z,1756017974.814 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-08-24T06:46:17.634Z,1756017977.634 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T06:56:17.978Z,1756018577.978 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T07:06:18.318Z,1756019178.318 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T07:13:40.306Z,1756019620.306 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-08-24T07:14:20.705Z,1756019660.705 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-24T07:14:20.706Z,1756019660.706 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-24T07:14:20.707Z,1756019660.707 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-24T07:14:20.724Z,1756019660.724 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-08-24T07:14:20.726Z,1756019660.726 [BPC1](INFO): Received data from all battery sticks. 2025-08-24T07:16:18.670Z,1756019778.670 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T07:26:19.014Z,1756020379.014 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T07:36:19.366Z,1756020979.366 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T07:43:11.449Z,1756021391.449 [keepstation:SurfaceComms] Running Loop=1 2025-08-24T07:43:11.449Z,1756021391.449 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-08-24T07:43:11.450Z,1756021391.450 [keepstation:SurfaceComms:A] Running Loop=1 2025-08-24T07:43:11.450Z,1756021391.450 [keepstation:SurfaceComms:A] Stopped 2025-08-24T07:43:11.450Z,1756021391.450 [keepstation:SurfaceComms:B] Running Loop=1 2025-08-24T07:43:11.450Z,1756021391.450 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-08-24T07:43:11.851Z,1756021391.851 [keepstation:KeepStation:Dive] Stopped 2025-08-24T07:43:11.851Z,1756021391.851 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-08-24T07:43:11.852Z,1756021391.852 [keepstation:KeepStation:Dive:A] Stopped 2025-08-24T07:43:11.852Z,1756021391.852 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-08-24T07:43:11.852Z,1756021391.852 [keepstation:DiveCmd:StationKeep] Stopped 2025-08-24T07:43:11.852Z,1756021391.852 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-08-24T07:43:11.852Z,1756021391.852 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-08-24T07:43:11.852Z,1756021391.852 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-08-24T07:43:11.852Z,1756021391.852 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-08-24T07:43:11.852Z,1756021391.852 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-08-24T07:43:11.854Z,1756021391.854 [keepstation:NeedComms] Running Loop=1 2025-08-24T07:43:11.854Z,1756021391.854 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-08-24T07:43:11.854Z,1756021391.854 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-08-24T07:43:11.854Z,1756021391.854 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-08-24T07:43:11.855Z,1756021391.855 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-08-24T07:43:11.855Z,1756021391.855 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-08-24T07:43:11.856Z,1756021391.856 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-08-24T07:43:11.856Z,1756021391.856 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-08-24T07:43:11.856Z,1756021391.856 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-08-24T07:43:11.857Z,1756021391.857 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-08-24T07:43:11.857Z,1756021391.857 [keepstation:NeedComms:A] Running Loop=1 2025-08-24T07:43:11.859Z,1756021391.859 [keepstation:NeedComms:A](INFO): last time_fix was: 1756017791.000000 second since 1970/01/01T00:00:00Z 2025-08-24T07:43:11.859Z,1756021391.859 [keepstation:NeedComms:A] Stopped 2025-08-24T07:44:49.584Z,1756021489.584 [keepstation:NeedComms:C] Running Loop=1 2025-08-24T07:44:50.132Z,1756021490.132 [Radio_Surface](INFO): Powering up 2025-08-24T07:44:55.492Z,1756021495.492 [DataOverHttps](INFO): Radio surface powered ON. 2025-08-24T07:45:13.435Z,1756021513.435 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T07:45:13.436Z,1756021513.436 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:45:25.612Z,1756021525.612 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:45:30.810Z,1756021530.810 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T07:45:30.810Z,1756021530.810 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:45:56.744Z,1756021556.744 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:45:57.068Z,1756021557.068 [NAL9602](INFO): SBD MO Status=2, MOMSN=22446, MT Status=2, MTMSN=0 2025-08-24T07:45:57.069Z,1756021557.069 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:46:19.697Z,1756021579.697 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T07:46:27.876Z,1756021587.876 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:46:59.004Z,1756021619.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:47:30.128Z,1756021650.128 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:48:01.257Z,1756021681.257 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:48:11.597Z,1756021691.597 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=22446, MT Status=1, MTMSN=4426 2025-08-24T07:48:11.597Z,1756021691.597 [NAL9602](INFO): Data available in MT queue 2025-08-24T07:48:12.108Z,1756021692.108 [NAL9602](INFO): Received command: set keepstation.Depth 60 m 2025-08-24T07:48:13.210Z,1756021693.210 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,074813.00,A,3657.36516,N,12249.33222,W,0.778,233.42,240825,,,D*7B 2025-08-24T07:48:13.213Z,1756021693.213 [NAL9602](INFO): GPS fix at 20250824T074813: (36.956086, -122.822204) 2025-08-24T07:48:13.257Z,1756021693.257 [keepstation:NeedComms:C] Stopped 2025-08-24T07:48:13.258Z,1756021693.258 [keepstation:NeedComms:D] Running Loop=1 2025-08-24T07:48:15.593Z,1756021695.593 [CommandExec](IMPORTANT): got command set keepstation.Depth 60 meter 2025-08-24T07:48:32.380Z,1756021712.380 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:48:37.098Z,1756021717.098 [NAL9602](INFO): SBD MO Status=2, MOMSN=22447, MT Status=2, MTMSN=0 2025-08-24T07:48:37.098Z,1756021717.098 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:49:03.504Z,1756021743.504 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:49:06.405Z,1756021746.405 [NAL9602](INFO): SBD MO Status=2, MOMSN=22447, MT Status=2, MTMSN=0 2025-08-24T07:49:06.405Z,1756021746.405 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:49:31.654Z,1756021771.654 [NAL9602](INFO): SBD MO Status=2, MOMSN=22447, MT Status=2, MTMSN=0 2025-08-24T07:49:31.654Z,1756021771.654 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:49:34.632Z,1756021774.632 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:49:53.661Z,1756021793.661 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=22447, MT Status=1, MTMSN=4427 2025-08-24T07:49:53.716Z,1756021793.716 [NAL9602](INFO): Sent 332 bytes from file Logs/20250824T051703/Courier0000.lzma 2025-08-24T07:49:53.716Z,1756021793.716 [NAL9602](INFO): Packets left to send: 1 2025-08-24T07:49:54.388Z,1756021794.388 [NAL9602](INFO): Received command: sched 20250824T1302 "load Science/sci2_vtyoyo.tl;set sci2_vtyoyo.MissionTimeout 16 h;set sci2_vtyoyo.NeedCommsTime 90 min;set sci2_vtyoyo.Lat1 37.00694 degree;set sci2_vtyoyo.Lon1 -122.93152 degree;set sci2_vtyoyo.Speed .7 m/s" 18vt1 1 3 2025-08-24T07:49:54.473Z,1756021794.473 [CommandExec](IMPORTANT): got command schedule 20250824T130200 "load Science/sci2_vtyoyo.tl;set sci2_vtyoyo.MissionTimeout 16 h;set sci2_vtyoyo.NeedCommsTime 90 min;set sci2_vtyoyo.Lat1 37.00694 degree;set sci2_vtyoyo.Lon1 -122.93152 degree;set sci2_vtyoyo.Speed .7 m/s" 18vt1 1 3.000000 2025-08-24T07:49:54.474Z,1756021794.474 [CommandExec](IMPORTANT): Scheduling command #1 of 3 with id=18vt1 2025-08-24T07:49:54.475Z,1756021794.475 [CommandExec](IMPORTANT): Scheduled #19 (#1 of 3 with id='18vt1'): "load Science/sci2_vtyoyo.tl;set sci2_vtyoyo.MissionTimeout 16 h;set sci2_vtyoyo.NeedCommsTime 90 min;set sci2_vtyoyo.Lat1 37.00694 degree;set sci2_vtyoyo.Lon1 -122.93152 degree;set sci2_vtyoyo.Speed .7 m/s", AT TIME: 2025-08-24T13:02:00Z 2025-08-24T07:50:05.760Z,1756021805.760 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:50:07.296Z,1756021807.296 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=22448, MT Status=1, MTMSN=4428 2025-08-24T07:50:07.352Z,1756021807.352 [NAL9602](INFO): Sent 41 bytes from file Logs/20250824T051703/Courier0000.lzma 2025-08-24T07:50:07.352Z,1756021807.352 [NAL9602](INFO): Packets left to send: 0 2025-08-24T07:50:08.040Z,1756021808.040 [NAL9602](INFO): Received command: sched 20250824T1302 "set sci2_vtyoyo.YoYoMinDepth 3 m;set sci2_vtyoyo.YoYoMaxDepth 130 m;set sci2_vtyoyo.YoYoMinAltitude 15 m;set sci2_vtyoyo.MaxDepth 150 m;set sci2_vtyoyo.SinkOffSurface 1 bool;set sci2_vtyoyo.SinkDepthTimeout 3 min" 18vt1 2 3 2025-08-24T07:50:08.130Z,1756021808.130 [CommandExec](IMPORTANT): got command schedule 20250824T130200 "set sci2_vtyoyo.YoYoMinDepth 3 m;set sci2_vtyoyo.YoYoMaxDepth 130 m;set sci2_vtyoyo.YoYoMinAltitude 15 m;set sci2_vtyoyo.MaxDepth 150 m;set sci2_vtyoyo.SinkOffSurface 1 bool;set sci2_vtyoyo.SinkDepthTimeout 3 min" 18vt1 2 3.000000 2025-08-24T07:50:08.130Z,1756021808.130 [CommandExec](IMPORTANT): Scheduling command #2 of 3 with id=18vt1 2025-08-24T07:50:08.132Z,1756021808.132 [CommandExec](IMPORTANT): Scheduled #20 (#2 of 3 with id='18vt1'): "set sci2_vtyoyo.YoYoMinDepth 3 m;set sci2_vtyoyo.YoYoMaxDepth 130 m;set sci2_vtyoyo.YoYoMinAltitude 15 m;set sci2_vtyoyo.MaxDepth 150 m;set sci2_vtyoyo.SinkOffSurface 1 bool;set sci2_vtyoyo.SinkDepthTimeout 3 min", AT TIME: 2025-08-24T13:02:00Z 2025-08-24T07:50:27.013Z,1756021827.013 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=22449, MT Status=1, MTMSN=4429 2025-08-24T07:50:27.072Z,1756021827.072 [NAL9602](INFO): Sent 90 bytes from file Logs/20250824T051703/Courier0003.lzma 2025-08-24T07:50:27.072Z,1756021827.072 [NAL9602](INFO): Packets left to send: 0 2025-08-24T07:50:27.640Z,1756021827.640 [NAL9602](INFO): Received command: sched 20250824T1302 "set sci2_vtyoyo:NeedComms.SurfaceSpeed .8 m/s;set sci2_vtyoyo:BackseatDriver.EnableBackseat 1 bool;run" 18vt1 3 3 2025-08-24T07:50:27.716Z,1756021827.716 [CommandExec](IMPORTANT): got command schedule 20250824T130200 "set sci2_vtyoyo:NeedComms.SurfaceSpeed .8 m/s;set sci2_vtyoyo:BackseatDriver.EnableBackseat 1 bool;run" 18vt1 3 3.000000 2025-08-24T07:50:27.717Z,1756021827.717 [CommandExec](IMPORTANT): Scheduling command #3 of 3 with id=18vt1 2025-08-24T07:50:27.718Z,1756021827.718 [CommandExec](IMPORTANT): Scheduled #21 (#3 of 3 with id='18vt1'): "set sci2_vtyoyo:NeedComms.SurfaceSpeed .8 m/s;set sci2_vtyoyo:BackseatDriver.EnableBackseat 1 bool;run", AT TIME: 2025-08-24T13:02:00Z 2025-08-24T07:50:36.884Z,1756021836.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:50:46.623Z,1756021846.623 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=22450, MT Status=1, MTMSN=4430 2025-08-24T07:50:46.680Z,1756021846.680 [NAL9602](INFO): Sent 332 bytes from file Logs/20250824T051703/Express0001.lzma 2025-08-24T07:50:46.680Z,1756021846.680 [NAL9602](INFO): Packets left to send: 4 2025-08-24T07:50:47.352Z,1756021847.352 [NAL9602](INFO): Received command: sched "load Science/sci2.tl;set sci2.MissionTimeout 60 h;set sci2.Lat1 36.773325 degree;set sci2.Lon1 -122.03415 degree;set sci2.Lat2 36.797 degree;set sci2.Lon2 -121.847 degree;set sci2.Speed .8 m/s;set sci2.YoYoMinDepth 3 m" 18w7v 1 3 2025-08-24T07:50:47.419Z,1756021847.419 [CommandExec](IMPORTANT): got command schedule "load Science/sci2.tl;set sci2.MissionTimeout 60 h;set sci2.Lat1 36.773325 degree;set sci2.Lon1 -122.03415 degree;set sci2.Lat2 36.797 degree;set sci2.Lon2 -121.847 degree;set sci2.Speed .8 m/s;set sci2.YoYoMinDepth 3 m" 18w7v 1 3.000000 2025-08-24T07:50:47.419Z,1756021847.419 [CommandExec](IMPORTANT): Scheduling command #1 of 3 with id=18w7v 2025-08-24T07:50:47.420Z,1756021847.420 [CommandExec](IMPORTANT): Scheduled #22 (#1 of 3 with id='18w7v'): "load Science/sci2.tl;set sci2.MissionTimeout 60 h;set sci2.Lat1 36.773325 degree;set sci2.Lon1 -122.03415 degree;set sci2.Lat2 36.797 degree;set sci2.Lon2 -121.847 degree;set sci2.Speed .8 m/s;set sci2.YoYoMinDepth 3 m", AFTER MISSION 2025-08-24T07:51:05.973Z,1756021865.973 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=22451, MT Status=1, MTMSN=4431 2025-08-24T07:51:06.032Z,1756021866.032 [NAL9602](INFO): Sent 332 bytes from file Logs/20250824T051703/Express0001.lzma 2025-08-24T07:51:06.032Z,1756021866.032 [NAL9602](INFO): Packets left to send: 3 2025-08-24T07:51:06.720Z,1756021866.720 [NAL9602](INFO): Received command: sched "set sci2.YoYoMaxDepth 50 m;set sci2.SinkOffSurface 1 bool;set sci2.SinkDepthTimeout 3 min;set sci2.MaxDepth 70 m;set sci2:NeedComms.SurfaceSpeed .8 m/s;set sci2:NeedComms.CommsTimeout 10 min;set sci2:BackseatDriver.EnableBackseat 1 bool" 18w7v 2 3 2025-08-24T07:51:06.807Z,1756021866.807 [CommandExec](IMPORTANT): got command schedule "set sci2.YoYoMaxDepth 50 m;set sci2.SinkOffSurface 1 bool;set sci2.SinkDepthTimeout 3 min;set sci2.MaxDepth 70 m;set sci2:NeedComms.SurfaceSpeed .8 m/s;set sci2:NeedComms.CommsTimeout 10 min;set sci2:BackseatDriver.EnableBackseat 1 bool" 18w7v 2 3.000000 2025-08-24T07:51:06.808Z,1756021866.808 [CommandExec](IMPORTANT): Scheduling command #2 of 3 with id=18w7v 2025-08-24T07:51:06.809Z,1756021866.809 [CommandExec](IMPORTANT): Scheduled #23 (#2 of 3 with id='18w7v'): "set sci2.YoYoMaxDepth 50 m;set sci2.SinkOffSurface 1 bool;set sci2.SinkDepthTimeout 3 min;set sci2.MaxDepth 70 m;set sci2:NeedComms.SurfaceSpeed .8 m/s;set sci2:NeedComms.CommsTimeout 10 min;set sci2:BackseatDriver.EnableBackseat 1 bool", AFTER MISSION 2025-08-24T07:51:08.020Z,1756021868.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:51:33.488Z,1756021893.488 [NAL9602](INFO): SBD MO Status=2, MOMSN=22452, MT Status=2, MTMSN=0 2025-08-24T07:51:33.488Z,1756021893.488 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:51:39.149Z,1756021899.149 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:51:53.934Z,1756021913.934 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=22452, MT Status=1, MTMSN=4432 2025-08-24T07:51:53.988Z,1756021913.988 [NAL9602](INFO): Sent 332 bytes from file Logs/20250824T051703/Express0001.lzma 2025-08-24T07:51:53.988Z,1756021913.988 [NAL9602](INFO): Packets left to send: 2 2025-08-24T07:51:54.444Z,1756021914.444 [NAL9602](INFO): Received command: sched "run" 18w7v 3 3 2025-08-24T07:51:54.474Z,1756021914.474 [CommandExec](IMPORTANT): got command schedule "run" 18w7v 3 3.000000 2025-08-24T07:51:54.474Z,1756021914.474 [CommandExec](IMPORTANT): Scheduling command #3 of 3 with id=18w7v 2025-08-24T07:51:54.476Z,1756021914.476 [CommandExec](IMPORTANT): Scheduled #24 (#3 of 3 with id='18w7v'): "run", AFTER MISSION 2025-08-24T07:52:10.284Z,1756021930.284 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:52:15.598Z,1756021935.598 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=22453, MT Status=1, MTMSN=4433 2025-08-24T07:52:15.648Z,1756021935.648 [NAL9602](INFO): Sent 332 bytes from file Logs/20250824T051703/Express0001.lzma 2025-08-24T07:52:15.648Z,1756021935.648 [NAL9602](INFO): Packets left to send: 1 2025-08-24T07:52:16.156Z,1756021936.156 [NAL9602](INFO): Received command: sched 20250824T1310 "set sci2_vtyoyo:NeedComms.CommsTimeout 10 min" 2025-08-24T07:52:16.247Z,1756021936.247 [CommandExec](IMPORTANT): got command schedule 20250824T131000 "set sci2_vtyoyo:NeedComms.CommsTimeout 10 min" 2025-08-24T07:52:16.248Z,1756021936.248 [CommandExec](IMPORTANT): Scheduled #25: "set sci2_vtyoyo:NeedComms.CommsTimeout 10 min", AT TIME: 2025-08-24T13:10:00Z 2025-08-24T07:52:38.016Z,1756021958.016 [NAL9602](INFO): SBD MO Status=1, MOMSN=22454, MT Status=0, MTMSN=0 2025-08-24T07:52:38.068Z,1756021958.068 [NAL9602](INFO): Sent 72 bytes from file Logs/20250824T051703/Express0001.lzma 2025-08-24T07:52:38.068Z,1756021958.068 [NAL9602](INFO): Packets left to send: 0 2025-08-24T07:52:41.408Z,1756021961.408 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:52:56.443Z,1756021976.443 [NAL9602](INFO): SBD MO Status=2, MOMSN=22455, MT Status=2, MTMSN=0 2025-08-24T07:52:56.443Z,1756021976.443 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:53:12.532Z,1756021992.532 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:53:24.546Z,1756022004.546 [NAL9602](INFO): SBD MO Status=2, MOMSN=22455, MT Status=2, MTMSN=0 2025-08-24T07:53:24.546Z,1756022004.546 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:53:43.668Z,1756022023.668 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:53:57.899Z,1756022037.899 [NAL9602](INFO): SBD MO Status=2, MOMSN=22455, MT Status=2, MTMSN=0 2025-08-24T07:53:57.899Z,1756022037.899 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:54:14.792Z,1756022054.792 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:54:17.129Z,1756022057.129 [NAL9602](INFO): SBD MO Status=1, MOMSN=22455, MT Status=0, MTMSN=0 2025-08-24T07:54:17.176Z,1756022057.176 [NAL9602](INFO): Sent 332 bytes from file Logs/20250824T051703/Express0004.lzma 2025-08-24T07:54:17.176Z,1756022057.176 [NAL9602](INFO): Packets left to send: 1 2025-08-24T07:54:26.214Z,1756022066.214 [NAL9602](INFO): SBD MO Status=1, MOMSN=22456, MT Status=0, MTMSN=0 2025-08-24T07:54:26.268Z,1756022066.268 [NAL9602](INFO): Sent 227 bytes from file Logs/20250824T051703/Express0004.lzma 2025-08-24T07:54:26.268Z,1756022066.268 [NAL9602](INFO): Packets left to send: 0 2025-08-24T07:54:37.531Z,1756022077.531 [NAL9602](INFO): SBD MO Status=0, MOMSN=22457, MT Status=0, MTMSN=0 2025-08-24T07:54:45.916Z,1756022085.916 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:55:01.611Z,1756022101.611 [NAL9602](INFO): SBD MO Status=2, MOMSN=22458, MT Status=2, MTMSN=0 2025-08-24T07:55:01.611Z,1756022101.611 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:55:17.040Z,1756022117.040 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:55:18.766Z,1756022118.766 [NAL9602](INFO): SBD MO Status=2, MOMSN=22458, MT Status=2, MTMSN=0 2025-08-24T07:55:18.766Z,1756022118.766 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:55:45.214Z,1756022145.214 [NAL9602](INFO): SBD MO Status=2, MOMSN=22458, MT Status=2, MTMSN=0 2025-08-24T07:55:45.214Z,1756022145.214 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:55:48.164Z,1756022148.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:56:19.294Z,1756022179.294 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:56:19.962Z,1756022179.962 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T07:56:50.428Z,1756022210.428 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:57:06.613Z,1756022226.613 [NAL9602](INFO): SBD MO Status=2, MOMSN=22458, MT Status=2, MTMSN=0 2025-08-24T07:57:06.613Z,1756022226.613 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:57:21.552Z,1756022241.552 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:57:42.765Z,1756022262.765 [NAL9602](INFO): SBD MO Status=2, MOMSN=22458, MT Status=2, MTMSN=0 2025-08-24T07:57:42.765Z,1756022262.765 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T07:57:52.676Z,1756022272.676 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:58:01.544Z,1756022281.544 [NAL9602](INFO): SBD MO Status=1, MOMSN=22458, MT Status=0, MTMSN=0 2025-08-24T07:58:01.592Z,1756022281.592 [NAL9602](INFO): Sent 40 bytes from file Logs/20250824T051703/Courier0006.lzma 2025-08-24T07:58:01.592Z,1756022281.592 [NAL9602](INFO): Packets left to send: 0 2025-08-24T07:58:13.492Z,1756022293.492 [keepstation:NeedComms:D](INFO): Timed out from 2025-08-24T07:48:13.3Z 2025-08-24T07:58:13.492Z,1756022293.492 [keepstation:NeedComms:D:A_Timeout] Running Loop=1 2025-08-24T07:58:13.492Z,1756022293.492 [keepstation:NeedComms:D:A_Timeout](DEBUG): Aggregate::initialize keepstation:NeedComms:D:A_Timeout 2025-08-24T07:58:13.493Z,1756022293.493 [keepstation:NeedComms:D:A_Timeout:A] Running Loop=1 2025-08-24T07:58:13.496Z,1756022293.496 [keepstation:NeedComms:D:A_Timeout:A](IMPORTANT): Comms timed out after 10.000000 min minutes 2025-08-24T07:58:13.496Z,1756022293.496 [keepstation:NeedComms:D:A_Timeout:A] Stopped 2025-08-24T07:58:13.496Z,1756022293.496 [keepstation:NeedComms:D:A_Timeout](INFO): Completed keepstation:NeedComms:D:A_Timeout 2025-08-24T07:58:13.496Z,1756022293.496 [keepstation:NeedComms:D] Stopped 2025-08-24T07:58:13.496Z,1756022293.496 [keepstation:NeedComms:E] Running Loop=1 2025-08-24T07:58:21.967Z,1756022301.967 [NAL9602](INFO): SBD MO Status=1, MOMSN=22459, MT Status=0, MTMSN=0 2025-08-24T07:58:22.020Z,1756022302.020 [NAL9602](INFO): Sent 332 bytes from file Logs/20250824T051703/Express0007.lzma 2025-08-24T07:58:22.020Z,1756022302.020 [NAL9602](INFO): Packets left to send: 2 2025-08-24T07:58:23.177Z,1756022303.177 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,075823.00,A,3657.36929,N,12249.40762,W,0.447,279.06,240825,,,D*7D 2025-08-24T07:58:23.179Z,1756022303.179 [NAL9602](INFO): GPS fix at 20250824T075823: (36.956155, -122.823460) 2025-08-24T07:58:23.193Z,1756022303.193 [keepstation:NeedComms:E] Stopped 2025-08-24T07:58:23.194Z,1756022303.194 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-08-24T07:58:23.194Z,1756022303.194 [keepstation:NeedComms] Stopped 2025-08-24T07:58:23.194Z,1756022303.194 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-08-24T07:58:23.194Z,1756022303.194 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-08-24T07:58:23.194Z,1756022303.194 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-08-24T07:58:23.613Z,1756022303.613 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-08-24T07:58:23.613Z,1756022303.613 [keepstation:SurfaceComms:B] Stopped 2025-08-24T07:58:23.613Z,1756022303.613 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-08-24T07:58:23.613Z,1756022303.613 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-08-24T07:58:23.614Z,1756022303.614 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-08-24T07:58:23.614Z,1756022303.614 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-08-24T07:58:23.816Z,1756022303.816 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:58:24.010Z,1756022304.010 [keepstation:SurfaceComms:setTransit] Stopped 2025-08-24T07:58:24.010Z,1756022304.010 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-08-24T07:58:24.010Z,1756022304.010 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-08-24T07:58:24.011Z,1756022304.011 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-08-24T07:58:24.011Z,1756022304.011 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-08-24T07:58:24.011Z,1756022304.011 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-08-24T07:58:24.395Z,1756022304.395 [keepstation:SurfaceComms:setStation] Stopped 2025-08-24T07:58:24.395Z,1756022304.395 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-08-24T07:58:24.395Z,1756022304.395 [keepstation:SurfaceComms:setStation:A] Stopped 2025-08-24T07:58:24.395Z,1756022304.395 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-08-24T07:58:24.395Z,1756022304.395 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-08-24T07:58:24.395Z,1756022304.395 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-08-24T07:58:24.807Z,1756022304.807 [keepstation:SurfaceComms:setSink:A] Stopped 2025-08-24T07:58:24.807Z,1756022304.807 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-08-24T07:58:24.807Z,1756022304.807 [keepstation:SurfaceComms:setSink] Stopped 2025-08-24T07:58:24.807Z,1756022304.807 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-08-24T07:58:24.808Z,1756022304.808 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-08-24T07:58:24.808Z,1756022304.808 [keepstation:SurfaceComms] Stopped 2025-08-24T07:58:24.808Z,1756022304.808 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-08-24T07:58:25.217Z,1756022305.217 [keepstation:KeepStation:Dive] Running Loop=1 2025-08-24T07:58:25.217Z,1756022305.217 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-08-24T07:58:25.218Z,1756022305.218 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-08-24T07:58:25.218Z,1756022305.218 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-08-24T07:58:25.218Z,1756022305.218 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-08-24T07:58:25.218Z,1756022305.218 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-08-24T07:58:25.218Z,1756022305.218 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-08-24T07:58:25.219Z,1756022305.219 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T07:58:25.219Z,1756022305.219 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-08-24T07:58:25.219Z,1756022305.219 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-08-24T07:58:25.219Z,1756022305.219 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-08-24T07:58:25.219Z,1756022305.219 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-08-24T07:58:25.219Z,1756022305.219 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-08-24T07:58:25.220Z,1756022305.220 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-08-24T07:58:25.220Z,1756022305.220 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-08-24T07:58:25.220Z,1756022305.220 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-08-24T07:58:25.220Z,1756022305.220 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-08-24T07:58:54.940Z,1756022334.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:58:55.900Z,1756022335.900 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-24T07:59:26.064Z,1756022366.064 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T07:59:57.208Z,1756022397.208 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T08:00:28.336Z,1756022428.336 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T08:00:59.480Z,1756022459.480 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T08:01:25.397Z,1756022485.397 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-08-24T07:58:25.2Z 2025-08-24T08:01:25.397Z,1756022485.397 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2025-08-24T08:01:25.397Z,1756022485.397 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2025-08-24T08:01:25.397Z,1756022485.397 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2025-08-24T08:01:25.398Z,1756022485.398 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 1.323601 m 2025-08-24T08:01:25.398Z,1756022485.398 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2025-08-24T08:01:25.398Z,1756022485.398 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2025-08-24T08:01:25.398Z,1756022485.398 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-08-24T08:01:25.398Z,1756022485.398 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-08-24T08:01:25.398Z,1756022485.398 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-08-24T08:01:25.399Z,1756022485.399 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-08-24T08:01:25.794Z,1756022485.794 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-08-24T08:01:25.794Z,1756022485.794 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-08-24T08:01:25.794Z,1756022485.794 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-08-24T08:01:25.794Z,1756022485.794 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-08-24T08:01:25.794Z,1756022485.794 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T08:01:25.795Z,1756022485.795 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-08-24T08:01:26.198Z,1756022486.198 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-08-24T08:01:26.198Z,1756022486.198 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T08:01:26.198Z,1756022486.198 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-08-24T08:01:26.199Z,1756022486.199 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T08:01:26.199Z,1756022486.199 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T08:01:26.199Z,1756022486.199 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-08-24T08:01:26.200Z,1756022486.200 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T08:01:26.200Z,1756022486.200 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-08-24T08:01:26.200Z,1756022486.200 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-08-24T08:01:26.200Z,1756022486.200 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-08-24T08:01:26.200Z,1756022486.200 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-08-24T08:01:26.200Z,1756022486.200 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-08-24T08:01:26.601Z,1756022486.601 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-08-24T08:01:26.601Z,1756022486.601 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-08-24T08:01:26.601Z,1756022486.601 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-08-24T08:01:26.601Z,1756022486.601 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-08-24T08:01:26.601Z,1756022486.601 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-08-24T08:01:26.602Z,1756022486.602 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-08-24T08:01:26.602Z,1756022486.602 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-08-24T08:01:30.604Z,1756022490.604 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T08:02:01.732Z,1756022521.732 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T08:02:21.408Z,1756022541.408 [Radio_Surface](INFO): Powering down 2025-08-24T08:02:32.856Z,1756022552.856 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T08:02:33.860Z,1756022553.860 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-08-24T08:06:20.302Z,1756022780.302 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T08:16:20.558Z,1756023380.558 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T08:21:59.393Z,1756023719.393 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-24T08:21:59.394Z,1756023719.394 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-24T08:21:59.394Z,1756023719.394 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-24T08:21:59.396Z,1756023719.396 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-08-24T08:21:59.398Z,1756023719.398 [BPC1](INFO): Received data from all battery sticks. 2025-08-24T08:26:20.612Z,1756023980.612 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T08:36:20.742Z,1756024580.742 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T08:46:20.946Z,1756025180.946 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T08:56:21.281Z,1756025781.281 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T08:58:23.424Z,1756025903.424 [keepstation:SurfaceComms] Running Loop=1 2025-08-24T08:58:23.424Z,1756025903.424 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-08-24T08:58:23.425Z,1756025903.425 [keepstation:SurfaceComms:A] Running Loop=1 2025-08-24T08:58:23.425Z,1756025903.425 [keepstation:SurfaceComms:A] Stopped 2025-08-24T08:58:23.425Z,1756025903.425 [keepstation:SurfaceComms:B] Running Loop=1 2025-08-24T08:58:23.425Z,1756025903.425 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-08-24T08:58:23.827Z,1756025903.827 [keepstation:KeepStation:Dive] Stopped 2025-08-24T08:58:23.827Z,1756025903.827 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-08-24T08:58:23.827Z,1756025903.827 [keepstation:KeepStation:Dive:A] Stopped 2025-08-24T08:58:23.827Z,1756025903.827 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-08-24T08:58:23.828Z,1756025903.828 [keepstation:DiveCmd:StationKeep] Stopped 2025-08-24T08:58:23.828Z,1756025903.828 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-08-24T08:58:23.828Z,1756025903.828 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-08-24T08:58:23.828Z,1756025903.828 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-08-24T08:58:23.828Z,1756025903.828 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-08-24T08:58:23.829Z,1756025903.829 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-08-24T08:58:23.831Z,1756025903.831 [keepstation:NeedComms] Running Loop=1 2025-08-24T08:58:23.831Z,1756025903.831 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-08-24T08:58:23.831Z,1756025903.831 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-08-24T08:58:23.831Z,1756025903.831 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-08-24T08:58:23.832Z,1756025903.832 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-08-24T08:58:23.832Z,1756025903.832 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-08-24T08:58:23.833Z,1756025903.833 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-08-24T08:58:23.833Z,1756025903.833 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-08-24T08:58:23.833Z,1756025903.833 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-08-24T08:58:23.834Z,1756025903.834 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-08-24T08:58:23.834Z,1756025903.834 [keepstation:NeedComms:A] Running Loop=1 2025-08-24T08:58:23.836Z,1756025903.836 [keepstation:NeedComms:A](INFO): last time_fix was: 1756022303.000000 second since 1970/01/01T00:00:00Z 2025-08-24T08:58:23.836Z,1756025903.836 [keepstation:NeedComms:A] Stopped 2025-08-24T09:01:07.171Z,1756026067.171 [keepstation:NeedComms:C] Running Loop=1 2025-08-24T09:01:07.908Z,1756026067.908 [Radio_Surface](INFO): Powering up 2025-08-24T09:01:13.308Z,1756026073.308 [DataOverHttps](INFO): Radio surface powered ON. 2025-08-24T09:01:43.428Z,1756026103.428 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:02:14.564Z,1756026134.564 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:02:45.688Z,1756026165.688 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:03:16.812Z,1756026196.812 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:03:47.948Z,1756026227.948 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:04:19.072Z,1756026259.072 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:04:26.736Z,1756026266.736 [NAL9602](INFO): SBD MO Status=0, MOMSN=22460, MT Status=0, MTMSN=0 2025-08-24T09:04:26.736Z,1756026266.736 [NAL9602](INFO): No messages in MT queue 2025-08-24T09:04:27.949Z,1756026267.949 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,090427.00,A,3657.41983,N,12249.48969,W,0.680,343.89,240825,,,D*75 2025-08-24T09:04:27.961Z,1756026267.961 [NAL9602](INFO): GPS fix at 20250824T090427: (36.956997, -122.824828) 2025-08-24T09:04:27.975Z,1756026267.975 [keepstation:NeedComms:C] Stopped 2025-08-24T09:04:27.975Z,1756026267.975 [keepstation:NeedComms:D] Running Loop=1 2025-08-24T09:04:44.553Z,1756026284.553 [NAL9602](INFO): SBD MO Status=2, MOMSN=22461, MT Status=2, MTMSN=0 2025-08-24T09:04:44.553Z,1756026284.553 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T09:04:50.204Z,1756026290.204 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:05:21.328Z,1756026321.328 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:05:32.280Z,1756026332.280 [NAL9602](INFO): SBD MO Status=2, MOMSN=22461, MT Status=2, MTMSN=0 2025-08-24T09:05:32.281Z,1756026332.281 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T09:05:52.452Z,1756026352.452 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:06:21.573Z,1756026381.573 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T09:06:23.576Z,1756026383.576 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:06:54.720Z,1756026414.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:07:25.844Z,1756026445.844 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:07:44.486Z,1756026464.486 [NAL9602](INFO): SBD MO Status=2, MOMSN=22461, MT Status=2, MTMSN=0 2025-08-24T09:07:44.486Z,1756026464.486 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T09:07:56.976Z,1756026476.976 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:08:09.594Z,1756026489.594 [NAL9602](INFO): SBD MO Status=2, MOMSN=22461, MT Status=2, MTMSN=0 2025-08-24T09:08:09.594Z,1756026489.594 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T09:08:28.100Z,1756026508.100 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:08:59.224Z,1756026539.224 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:09:00.161Z,1756026540.161 [NAL9602](INFO): SBD MO Status=2, MOMSN=22461, MT Status=2, MTMSN=0 2025-08-24T09:09:00.161Z,1756026540.161 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T09:09:25.663Z,1756026565.663 [NAL9602](INFO): SBD MO Status=2, MOMSN=22461, MT Status=2, MTMSN=0 2025-08-24T09:09:25.664Z,1756026565.664 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T09:09:30.360Z,1756026570.360 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:09:43.090Z,1756026583.090 [NAL9602](INFO): SBD MO Status=2, MOMSN=22461, MT Status=2, MTMSN=0 2025-08-24T09:09:43.091Z,1756026583.091 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T09:10:01.496Z,1756026601.496 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:10:08.598Z,1756026608.598 [NAL9602](INFO): SBD MO Status=1, MOMSN=22461, MT Status=0, MTMSN=0 2025-08-24T09:10:08.649Z,1756026608.649 [NAL9602](INFO): Sent 90 bytes from file Logs/20250824T051703/Courier0009.lzma 2025-08-24T09:10:08.649Z,1756026608.649 [NAL9602](INFO): Packets left to send: 0 2025-08-24T09:10:19.229Z,1756026619.229 [NAL9602](INFO): SBD MO Status=1, MOMSN=22462, MT Status=0, MTMSN=0 2025-08-24T09:10:19.280Z,1756026619.280 [NAL9602](INFO): Sent 332 bytes from file Logs/20250824T051703/Express0007.lzma 2025-08-24T09:10:19.280Z,1756026619.280 [NAL9602](INFO): Packets left to send: 1 2025-08-24T09:10:32.620Z,1756026632.620 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:10:37.069Z,1756026637.069 [NAL9602](INFO): SBD MO Status=2, MOMSN=22463, MT Status=2, MTMSN=0 2025-08-24T09:10:37.069Z,1756026637.069 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T09:11:03.752Z,1756026663.752 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:11:13.901Z,1756026673.901 [NAL9602](INFO): SBD MO Status=1, MOMSN=22463, MT Status=0, MTMSN=0 2025-08-24T09:11:13.948Z,1756026673.948 [NAL9602](INFO): Sent 188 bytes from file Logs/20250824T051703/Express0007.lzma 2025-08-24T09:11:13.948Z,1756026673.948 [NAL9602](INFO): Packets left to send: 0 2025-08-24T09:11:32.162Z,1756026692.162 [NAL9602](INFO): SBD MO Status=2, MOMSN=22464, MT Status=2, MTMSN=0 2025-08-24T09:11:32.163Z,1756026692.163 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T09:11:34.876Z,1756026694.876 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:11:50.816Z,1756026710.816 [NAL9602](INFO): SBD MO Status=1, MOMSN=22464, MT Status=0, MTMSN=0 2025-08-24T09:11:50.864Z,1756026710.864 [NAL9602](INFO): Sent 332 bytes from file Logs/20250824T051703/Express0010.lzma 2025-08-24T09:11:50.864Z,1756026710.864 [NAL9602](INFO): Packets left to send: 1 2025-08-24T09:12:05.000Z,1756026726.000 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:12:12.273Z,1756026732.273 [NAL9602](INFO): SBD MO Status=1, MOMSN=22465, MT Status=0, MTMSN=0 2025-08-24T09:12:12.328Z,1756026732.328 [NAL9602](INFO): Sent 185 bytes from file Logs/20250824T051703/Express0010.lzma 2025-08-24T09:12:12.328Z,1756026732.328 [NAL9602](INFO): Packets left to send: 0 2025-08-24T09:12:31.267Z,1756026751.267 [NAL9602](INFO): SBD MO Status=2, MOMSN=22466, MT Status=2, MTMSN=0 2025-08-24T09:12:31.267Z,1756026751.267 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T09:12:37.124Z,1756026757.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:12:52.676Z,1756026772.676 [NAL9602](INFO): SBD MO Status=2, MOMSN=22466, MT Status=2, MTMSN=0 2025-08-24T09:12:52.676Z,1756026772.676 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T09:13:08.252Z,1756026788.252 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:13:10.859Z,1756026790.859 [NAL9602](INFO): SBD MO Status=2, MOMSN=22466, MT Status=2, MTMSN=0 2025-08-24T09:13:10.860Z,1756026790.860 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T09:13:27.825Z,1756026807.825 [NAL9602](INFO): SBD MO Status=0, MOMSN=22466, MT Status=0, MTMSN=0 2025-08-24T09:13:27.905Z,1756026807.905 [keepstation:NeedComms:D] Stopped 2025-08-24T09:13:27.905Z,1756026807.905 [keepstation:NeedComms:E] Running Loop=1 2025-08-24T09:13:30.249Z,1756026810.249 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,091330.00,A,3657.47049,N,12249.52038,W,0.641,261.54,240825,,,D*76 2025-08-24T09:13:30.267Z,1756026810.267 [NAL9602](INFO): GPS fix at 20250824T091330: (36.957842, -122.825340) 2025-08-24T09:13:30.288Z,1756026810.288 [keepstation:NeedComms:E] Stopped 2025-08-24T09:13:30.288Z,1756026810.288 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-08-24T09:13:30.288Z,1756026810.288 [keepstation:NeedComms] Stopped 2025-08-24T09:13:30.289Z,1756026810.289 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-08-24T09:13:30.289Z,1756026810.289 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-08-24T09:13:30.289Z,1756026810.289 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-08-24T09:13:30.684Z,1756026810.684 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-08-24T09:13:30.684Z,1756026810.684 [keepstation:SurfaceComms:B] Stopped 2025-08-24T09:13:30.684Z,1756026810.684 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-08-24T09:13:30.684Z,1756026810.684 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-08-24T09:13:30.684Z,1756026810.684 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-08-24T09:13:30.684Z,1756026810.684 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-08-24T09:13:31.110Z,1756026811.110 [keepstation:SurfaceComms:setTransit] Stopped 2025-08-24T09:13:31.110Z,1756026811.110 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-08-24T09:13:31.111Z,1756026811.111 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-08-24T09:13:31.111Z,1756026811.111 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-08-24T09:13:31.111Z,1756026811.111 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-08-24T09:13:31.111Z,1756026811.111 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-08-24T09:13:31.505Z,1756026811.505 [keepstation:SurfaceComms:setStation] Stopped 2025-08-24T09:13:31.506Z,1756026811.506 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-08-24T09:13:31.506Z,1756026811.506 [keepstation:SurfaceComms:setStation:A] Stopped 2025-08-24T09:13:31.506Z,1756026811.506 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-08-24T09:13:31.506Z,1756026811.506 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-08-24T09:13:31.506Z,1756026811.506 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-08-24T09:13:31.884Z,1756026811.884 [keepstation:SurfaceComms:setSink:A] Stopped 2025-08-24T09:13:31.884Z,1756026811.884 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-08-24T09:13:31.884Z,1756026811.884 [keepstation:SurfaceComms:setSink] Stopped 2025-08-24T09:13:31.884Z,1756026811.884 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-08-24T09:13:31.885Z,1756026811.885 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-08-24T09:13:31.885Z,1756026811.885 [keepstation:SurfaceComms] Stopped 2025-08-24T09:13:31.885Z,1756026811.885 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-08-24T09:13:32.292Z,1756026812.292 [keepstation:KeepStation:Dive] Running Loop=1 2025-08-24T09:13:32.292Z,1756026812.292 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-08-24T09:13:32.292Z,1756026812.292 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-08-24T09:13:32.292Z,1756026812.292 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-08-24T09:13:32.293Z,1756026812.293 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-08-24T09:13:32.293Z,1756026812.293 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-08-24T09:13:32.293Z,1756026812.293 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-08-24T09:13:32.293Z,1756026812.293 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T09:13:32.293Z,1756026812.293 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-08-24T09:13:32.293Z,1756026812.293 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-08-24T09:13:32.293Z,1756026812.293 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-08-24T09:13:32.293Z,1756026812.293 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-08-24T09:13:32.293Z,1756026812.293 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-08-24T09:13:32.294Z,1756026812.294 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-08-24T09:13:32.294Z,1756026812.294 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-08-24T09:13:32.294Z,1756026812.294 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-08-24T09:13:32.294Z,1756026812.294 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-08-24T09:13:39.376Z,1756026819.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:14:02.971Z,1756026842.971 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-24T09:14:10.520Z,1756026850.520 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:14:41.644Z,1756026881.644 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:15:12.768Z,1756026912.768 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:15:43.892Z,1756026943.892 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:16:15.020Z,1756026975.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:16:21.961Z,1756026981.961 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T09:16:32.463Z,1756026992.463 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-08-24T09:13:32.3Z 2025-08-24T09:16:32.463Z,1756026992.463 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2025-08-24T09:16:32.463Z,1756026992.463 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2025-08-24T09:16:32.464Z,1756026992.464 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2025-08-24T09:16:32.464Z,1756026992.464 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 5.333194 m 2025-08-24T09:16:32.464Z,1756026992.464 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2025-08-24T09:16:32.464Z,1756026992.464 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2025-08-24T09:16:32.464Z,1756026992.464 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-08-24T09:16:32.464Z,1756026992.464 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-08-24T09:16:32.465Z,1756026992.465 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-08-24T09:16:32.465Z,1756026992.465 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-08-24T09:16:32.865Z,1756026992.865 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-08-24T09:16:32.865Z,1756026992.865 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-08-24T09:16:32.865Z,1756026992.865 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-08-24T09:16:32.865Z,1756026992.865 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-08-24T09:16:32.866Z,1756026992.866 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T09:16:32.866Z,1756026992.866 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-08-24T09:16:33.278Z,1756026993.278 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-08-24T09:16:33.278Z,1756026993.278 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T09:16:33.278Z,1756026993.278 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-08-24T09:16:33.278Z,1756026993.278 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T09:16:33.278Z,1756026993.278 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T09:16:33.279Z,1756026993.279 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-08-24T09:16:33.279Z,1756026993.279 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T09:16:33.279Z,1756026993.279 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-08-24T09:16:33.279Z,1756026993.279 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-08-24T09:16:33.279Z,1756026993.279 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-08-24T09:16:33.279Z,1756026993.279 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-08-24T09:16:33.279Z,1756026993.279 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-08-24T09:16:33.671Z,1756026993.671 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-08-24T09:16:33.671Z,1756026993.671 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-08-24T09:16:33.671Z,1756026993.671 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-08-24T09:16:33.671Z,1756026993.671 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-08-24T09:16:33.671Z,1756026993.671 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-08-24T09:16:33.671Z,1756026993.671 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-08-24T09:16:33.671Z,1756026993.671 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-08-24T09:16:40.016Z,1756027000.016 [Radio_Surface](INFO): Powering down 2025-08-24T09:16:46.144Z,1756027006.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T09:16:47.148Z,1756027007.148 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-08-24T09:26:22.297Z,1756027582.297 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T09:29:34.602Z,1756027774.602 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-24T09:29:34.602Z,1756027774.602 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-24T09:29:34.603Z,1756027774.603 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-24T09:29:34.604Z,1756027774.604 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-08-24T09:29:34.607Z,1756027774.607 [BPC1](INFO): Received data from all battery sticks. 2025-08-24T09:36:22.646Z,1756028182.646 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T09:46:22.982Z,1756028782.982 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T09:56:23.320Z,1756029383.320 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T10:06:23.682Z,1756029983.682 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T10:13:30.359Z,1756030410.359 [keepstation:SurfaceComms] Running Loop=1 2025-08-24T10:13:30.359Z,1756030410.359 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-08-24T10:13:30.360Z,1756030410.360 [keepstation:SurfaceComms:A] Running Loop=1 2025-08-24T10:13:30.360Z,1756030410.360 [keepstation:SurfaceComms:A] Stopped 2025-08-24T10:13:30.360Z,1756030410.360 [keepstation:SurfaceComms:B] Running Loop=1 2025-08-24T10:13:30.360Z,1756030410.360 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-08-24T10:13:30.362Z,1756030410.362 [keepstation:NeedComms] Running Loop=1 2025-08-24T10:13:30.362Z,1756030410.362 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-08-24T10:13:30.362Z,1756030410.362 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-08-24T10:13:30.363Z,1756030410.363 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-08-24T10:13:30.363Z,1756030410.363 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-08-24T10:13:30.364Z,1756030410.364 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-08-24T10:13:30.364Z,1756030410.364 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-08-24T10:13:30.364Z,1756030410.364 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-08-24T10:13:30.365Z,1756030410.365 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-08-24T10:13:30.365Z,1756030410.365 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-08-24T10:13:30.365Z,1756030410.365 [keepstation:NeedComms:A] Running Loop=1 2025-08-24T10:13:30.367Z,1756030410.367 [keepstation:NeedComms:A](INFO): last time_fix was: 1756026810.000000 second since 1970/01/01T00:00:00Z 2025-08-24T10:13:30.367Z,1756030410.367 [keepstation:NeedComms:A] Stopped 2025-08-24T10:13:30.719Z,1756030410.719 [keepstation:KeepStation:Dive] Stopped 2025-08-24T10:13:30.719Z,1756030410.719 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-08-24T10:13:30.719Z,1756030410.719 [keepstation:KeepStation:Dive:A] Stopped 2025-08-24T10:13:30.719Z,1756030410.719 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-08-24T10:13:30.720Z,1756030410.720 [keepstation:DiveCmd:StationKeep] Stopped 2025-08-24T10:13:30.720Z,1756030410.720 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-08-24T10:13:30.720Z,1756030410.720 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-08-24T10:13:30.720Z,1756030410.720 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-08-24T10:13:30.720Z,1756030410.720 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-08-24T10:13:30.720Z,1756030410.720 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-08-24T10:16:15.669Z,1756030575.669 [keepstation:NeedComms:C] Running Loop=1 2025-08-24T10:16:16.276Z,1756030576.276 [Radio_Surface](INFO): Powering up 2025-08-24T10:16:21.964Z,1756030581.964 [DataOverHttps](INFO): Radio surface powered ON. 2025-08-24T10:16:23.889Z,1756030583.889 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T10:16:52.092Z,1756030612.092 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:17:23.216Z,1756030643.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:17:24.066Z,1756030644.066 [NAL9602](INFO): SBD MO Status=2, MOMSN=22467, MT Status=2, MTMSN=0 2025-08-24T10:17:24.067Z,1756030644.067 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T10:17:49.518Z,1756030669.518 [NAL9602](INFO): SBD MO Status=2, MOMSN=22467, MT Status=2, MTMSN=0 2025-08-24T10:17:49.518Z,1756030669.518 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T10:17:54.345Z,1756030674.345 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:18:25.468Z,1756030705.468 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:18:34.363Z,1756030714.363 [NAL9602](INFO): SBD MO Status=0, MOMSN=22467, MT Status=0, MTMSN=0 2025-08-24T10:18:34.363Z,1756030714.363 [NAL9602](INFO): No messages in MT queue 2025-08-24T10:18:35.579Z,1756030715.579 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,101835.00,A,3657.54586,N,12249.49797,W,0.408,357.00,240825,,,A*73 2025-08-24T10:18:35.581Z,1756030715.581 [NAL9602](INFO): GPS fix at 20250824T101835: (36.959098, -122.824966) 2025-08-24T10:18:35.594Z,1756030715.594 [keepstation:NeedComms:C] Stopped 2025-08-24T10:18:35.594Z,1756030715.594 [keepstation:NeedComms:D] Running Loop=1 2025-08-24T10:18:56.592Z,1756030736.592 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:19:09.573Z,1756030749.573 [NAL9602](INFO): SBD MO Status=2, MOMSN=22468, MT Status=2, MTMSN=0 2025-08-24T10:19:09.573Z,1756030749.573 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T10:19:27.720Z,1756030767.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:19:44.361Z,1756030784.361 [NAL9602](INFO): SBD MO Status=2, MOMSN=22468, MT Status=2, MTMSN=0 2025-08-24T10:19:44.361Z,1756030784.361 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T10:19:58.844Z,1756030798.844 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:20:15.125Z,1756030815.125 [NAL9602](INFO): SBD MO Status=1, MOMSN=22468, MT Status=0, MTMSN=0 2025-08-24T10:20:15.180Z,1756030815.180 [NAL9602](INFO): Sent 90 bytes from file Logs/20250824T051703/Courier0012.lzma 2025-08-24T10:20:15.180Z,1756030815.180 [NAL9602](INFO): Packets left to send: 0 2025-08-24T10:20:29.968Z,1756030829.968 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:20:36.181Z,1756030836.181 [NAL9602](INFO): SBD MO Status=1, MOMSN=22469, MT Status=0, MTMSN=0 2025-08-24T10:20:36.228Z,1756030836.228 [NAL9602](INFO): Sent 332 bytes from file Logs/20250824T051703/Express0013.lzma 2025-08-24T10:20:36.228Z,1756030836.228 [NAL9602](INFO): Packets left to send: 1 2025-08-24T10:20:45.528Z,1756030845.528 [NAL9602](INFO): SBD MO Status=1, MOMSN=22470, MT Status=0, MTMSN=0 2025-08-24T10:20:45.576Z,1756030845.576 [NAL9602](INFO): Sent 111 bytes from file Logs/20250824T051703/Express0013.lzma 2025-08-24T10:20:45.576Z,1756030845.576 [NAL9602](INFO): Packets left to send: 0 2025-08-24T10:20:53.605Z,1756030853.605 [NAL9602](INFO): SBD MO Status=0, MOMSN=22471, MT Status=0, MTMSN=0 2025-08-24T10:20:53.690Z,1756030853.690 [keepstation:NeedComms:D] Stopped 2025-08-24T10:20:53.690Z,1756030853.690 [keepstation:NeedComms:E] Running Loop=1 2025-08-24T10:20:56.029Z,1756030856.029 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,102055.00,A,3657.55577,N,12249.49700,W,0.330,28.72,240825,,,A*4D 2025-08-24T10:20:56.031Z,1756030856.031 [NAL9602](INFO): GPS fix at 20250824T102055: (36.959263, -122.824950) 2025-08-24T10:20:56.061Z,1756030856.061 [keepstation:NeedComms:E] Stopped 2025-08-24T10:20:56.062Z,1756030856.062 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-08-24T10:20:56.062Z,1756030856.062 [keepstation:NeedComms] Stopped 2025-08-24T10:20:56.062Z,1756030856.062 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-08-24T10:20:56.062Z,1756030856.062 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-08-24T10:20:56.062Z,1756030856.062 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-08-24T10:20:56.468Z,1756030856.468 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-08-24T10:20:56.468Z,1756030856.468 [keepstation:SurfaceComms:B] Stopped 2025-08-24T10:20:56.468Z,1756030856.468 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-08-24T10:20:56.468Z,1756030856.468 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-08-24T10:20:56.468Z,1756030856.468 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-08-24T10:20:56.468Z,1756030856.468 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-08-24T10:20:56.848Z,1756030856.848 [keepstation:SurfaceComms:setTransit] Stopped 2025-08-24T10:20:56.849Z,1756030856.849 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-08-24T10:20:56.849Z,1756030856.849 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-08-24T10:20:56.849Z,1756030856.849 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-08-24T10:20:56.849Z,1756030856.849 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-08-24T10:20:56.849Z,1756030856.849 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-08-24T10:20:57.258Z,1756030857.258 [keepstation:SurfaceComms:setStation] Stopped 2025-08-24T10:20:57.258Z,1756030857.258 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-08-24T10:20:57.258Z,1756030857.258 [keepstation:SurfaceComms:setStation:A] Stopped 2025-08-24T10:20:57.258Z,1756030857.258 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-08-24T10:20:57.258Z,1756030857.258 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-08-24T10:20:57.259Z,1756030857.259 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-08-24T10:20:57.650Z,1756030857.650 [keepstation:SurfaceComms:setSink:A] Stopped 2025-08-24T10:20:57.650Z,1756030857.650 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-08-24T10:20:57.651Z,1756030857.651 [keepstation:SurfaceComms:setSink] Stopped 2025-08-24T10:20:57.651Z,1756030857.651 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-08-24T10:20:57.651Z,1756030857.651 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-08-24T10:20:57.651Z,1756030857.651 [keepstation:SurfaceComms] Stopped 2025-08-24T10:20:57.651Z,1756030857.651 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-08-24T10:20:58.061Z,1756030858.061 [keepstation:KeepStation:Dive] Running Loop=1 2025-08-24T10:20:58.061Z,1756030858.061 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-08-24T10:20:58.061Z,1756030858.061 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-08-24T10:20:58.061Z,1756030858.061 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-08-24T10:20:58.062Z,1756030858.062 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-08-24T10:20:58.062Z,1756030858.062 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-08-24T10:20:58.062Z,1756030858.062 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-08-24T10:20:58.062Z,1756030858.062 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T10:20:58.062Z,1756030858.062 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-08-24T10:20:58.062Z,1756030858.062 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-08-24T10:20:58.062Z,1756030858.062 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-08-24T10:20:58.062Z,1756030858.062 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-08-24T10:20:58.063Z,1756030858.063 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-08-24T10:20:58.063Z,1756030858.063 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-08-24T10:20:58.063Z,1756030858.063 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-08-24T10:20:58.064Z,1756030858.064 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-08-24T10:20:58.064Z,1756030858.064 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-08-24T10:21:01.092Z,1756030861.092 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:21:28.751Z,1756030888.751 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-24T10:21:32.216Z,1756030892.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:22:03.348Z,1756030923.348 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:22:34.476Z,1756030954.476 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:23:05.600Z,1756030985.600 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:23:36.724Z,1756031016.724 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:23:44.392Z,1756031024.392 [Radio_Surface](INFO): Powering down 2025-08-24T10:23:58.254Z,1756031038.254 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-08-24T10:20:58.1Z 2025-08-24T10:23:58.254Z,1756031038.254 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2025-08-24T10:23:58.255Z,1756031038.255 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2025-08-24T10:23:58.255Z,1756031038.255 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2025-08-24T10:23:58.261Z,1756031038.261 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 6.801040 m 2025-08-24T10:23:58.261Z,1756031038.261 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2025-08-24T10:23:58.262Z,1756031038.262 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2025-08-24T10:23:58.262Z,1756031038.262 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-08-24T10:23:58.263Z,1756031038.263 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-08-24T10:23:58.268Z,1756031038.268 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-08-24T10:23:58.268Z,1756031038.268 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-08-24T10:23:58.646Z,1756031038.646 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-08-24T10:23:58.646Z,1756031038.646 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-08-24T10:23:58.646Z,1756031038.646 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-08-24T10:23:58.646Z,1756031038.646 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-08-24T10:23:58.646Z,1756031038.646 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T10:23:58.646Z,1756031038.646 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-08-24T10:23:59.048Z,1756031039.048 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-08-24T10:23:59.048Z,1756031039.048 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T10:23:59.048Z,1756031039.048 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-08-24T10:23:59.048Z,1756031039.048 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T10:23:59.049Z,1756031039.049 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T10:23:59.049Z,1756031039.049 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-08-24T10:23:59.049Z,1756031039.049 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T10:23:59.049Z,1756031039.049 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-08-24T10:23:59.049Z,1756031039.049 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-08-24T10:23:59.049Z,1756031039.049 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-08-24T10:23:59.049Z,1756031039.049 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-08-24T10:23:59.050Z,1756031039.050 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-08-24T10:23:59.457Z,1756031039.457 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-08-24T10:23:59.457Z,1756031039.457 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-08-24T10:23:59.457Z,1756031039.457 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-08-24T10:23:59.457Z,1756031039.457 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-08-24T10:23:59.457Z,1756031039.457 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-08-24T10:23:59.457Z,1756031039.457 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-08-24T10:23:59.457Z,1756031039.457 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-08-24T10:24:07.848Z,1756031047.848 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T10:24:08.852Z,1756031048.852 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-08-24T10:26:24.090Z,1756031184.090 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T10:36:24.435Z,1756031784.435 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T10:37:12.913Z,1756031832.913 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-24T10:37:12.914Z,1756031832.914 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-24T10:37:12.914Z,1756031832.914 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-24T10:37:12.915Z,1756031832.915 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-08-24T10:37:12.918Z,1756031832.918 [BPC1](INFO): Received data from all battery sticks. 2025-08-24T10:46:24.774Z,1756032384.774 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T10:56:25.120Z,1756032985.120 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T11:06:25.469Z,1756033585.469 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-08-24T11:20:56.104Z,1756034456.104 [keepstation:SurfaceComms] Running Loop=1 2025-08-24T11:20:56.104Z,1756034456.104 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-08-24T11:20:56.104Z,1756034456.104 [keepstation:SurfaceComms:A] Running Loop=1 2025-08-24T11:20:56.104Z,1756034456.104 [keepstation:SurfaceComms:A] Stopped 2025-08-24T11:20:56.104Z,1756034456.104 [keepstation:SurfaceComms:B] Running Loop=1 2025-08-24T11:20:56.105Z,1756034456.105 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-08-24T11:20:56.494Z,1756034456.494 [keepstation:KeepStation:Dive] Stopped 2025-08-24T11:20:56.494Z,1756034456.494 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-08-24T11:20:56.494Z,1756034456.494 [keepstation:KeepStation:Dive:A] Stopped 2025-08-24T11:20:56.494Z,1756034456.494 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-08-24T11:20:56.494Z,1756034456.494 [keepstation:DiveCmd:StationKeep] Stopped 2025-08-24T11:20:56.495Z,1756034456.495 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-08-24T11:20:56.495Z,1756034456.495 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-08-24T11:20:56.495Z,1756034456.495 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-08-24T11:20:56.495Z,1756034456.495 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-08-24T11:20:56.495Z,1756034456.495 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-08-24T11:20:56.497Z,1756034456.497 [keepstation:NeedComms] Running Loop=1 2025-08-24T11:20:56.497Z,1756034456.497 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-08-24T11:20:56.497Z,1756034456.497 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-08-24T11:20:56.498Z,1756034456.498 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-08-24T11:20:56.498Z,1756034456.498 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-08-24T11:20:56.498Z,1756034456.498 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-08-24T11:20:56.499Z,1756034456.499 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-08-24T11:20:56.499Z,1756034456.499 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-08-24T11:20:56.500Z,1756034456.500 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-08-24T11:20:56.500Z,1756034456.500 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-08-24T11:20:56.500Z,1756034456.500 [keepstation:NeedComms:A] Running Loop=1 2025-08-24T11:20:56.502Z,1756034456.502 [keepstation:NeedComms:A](INFO): last time_fix was: 1756030855.000000 second since 1970/01/01T00:00:00Z 2025-08-24T11:20:56.502Z,1756034456.502 [keepstation:NeedComms:A] Stopped 2025-08-24T11:23:41.372Z,1756034621.372 [keepstation:NeedComms:C] Running Loop=1 2025-08-24T11:23:42.076Z,1756034622.076 [Radio_Surface](INFO): Powering up 2025-08-24T11:23:47.708Z,1756034627.708 [DataOverHttps](INFO): Radio surface powered ON. 2025-08-24T11:24:17.828Z,1756034657.828 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:24:48.952Z,1756034688.952 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:25:20.076Z,1756034720.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:25:51.200Z,1756034751.200 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:26:22.324Z,1756034782.324 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:26:53.448Z,1756034813.448 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:27:24.576Z,1756034844.576 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:27:55.700Z,1756034875.700 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:28:26.832Z,1756034906.832 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:28:45.727Z,1756034925.727 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-08-24T11:28:50.173Z,1756034930.173 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,112850.00,A,3657.80399,N,12249.27156,W,0.739,0.67,240825,,,A*71 2025-08-24T11:28:50.176Z,1756034930.176 [NAL9602](INFO): GPS fix at 20250824T112850: (36.963400, -122.821193) 2025-08-24T11:28:50.189Z,1756034930.189 [keepstation:NeedComms:C] Stopped 2025-08-24T11:28:50.189Z,1756034930.189 [keepstation:NeedComms:D] Running Loop=1 2025-08-24T11:28:57.972Z,1756034937.972 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:29:20.872Z,1756034960.872 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2025-08-24T11:29:20.951Z,1756034960.951 [NAL9602](ERROR): received: +CSQ:1 OK471, 0, 0, 0, 0 OK 2025-08-24T11:29:29.096Z,1756034969.096 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:30:00.228Z,1756035000.228 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:30:26.778Z,1756035026.778 [NAL9602](INFO): SBD MO Status=2, MOMSN=22472, MT Status=2, MTMSN=0 2025-08-24T11:30:26.779Z,1756035026.779 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T11:30:31.352Z,1756035031.352 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:30:51.897Z,1756035051.897 [NAL9602](INFO): SBD MO Status=2, MOMSN=22472, MT Status=2, MTMSN=0 2025-08-24T11:30:51.897Z,1756035051.897 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T11:31:02.476Z,1756035062.476 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:31:23.061Z,1756035083.061 [NAL9602](INFO): SBD MO Status=1, MOMSN=22472, MT Status=0, MTMSN=0 2025-08-24T11:31:23.116Z,1756035083.116 [NAL9602](INFO): Sent 91 bytes from file Logs/20250824T051703/Courier0015.lzma 2025-08-24T11:31:23.116Z,1756035083.116 [NAL9602](INFO): Packets left to send: 0 2025-08-24T11:31:33.600Z,1756035093.600 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:31:35.238Z,1756035095.238 [NAL9602](INFO): SBD MO Status=1, MOMSN=22473, MT Status=0, MTMSN=0 2025-08-24T11:31:35.296Z,1756035095.296 [NAL9602](INFO): Sent 332 bytes from file Logs/20250824T051703/Express0016.lzma 2025-08-24T11:31:35.296Z,1756035095.296 [NAL9602](INFO): Packets left to send: 1 2025-08-24T11:31:55.501Z,1756035115.501 [NAL9602](INFO): SBD MO Status=1, MOMSN=22474, MT Status=0, MTMSN=0 2025-08-24T11:31:55.552Z,1756035115.552 [NAL9602](INFO): Sent 221 bytes from file Logs/20250824T051703/Express0016.lzma 2025-08-24T11:31:55.552Z,1756035115.552 [NAL9602](INFO): Packets left to send: 0 2025-08-24T11:32:04.736Z,1756035124.736 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:32:06.010Z,1756035126.010 [NAL9602](INFO): SBD MO Status=0, MOMSN=22475, MT Status=0, MTMSN=0 2025-08-24T11:32:06.098Z,1756035126.098 [keepstation:NeedComms:D] Stopped 2025-08-24T11:32:06.098Z,1756035126.098 [keepstation:NeedComms:E] Running Loop=1 2025-08-24T11:32:08.429Z,1756035128.429 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,113208.00,A,3657.81320,N,12249.25347,W,1.089,111.05,240825,,,A*7C 2025-08-24T11:32:08.431Z,1756035128.431 [NAL9602](INFO): GPS fix at 20250824T113208: (36.963553, -122.820891) 2025-08-24T11:32:08.468Z,1756035128.468 [keepstation:NeedComms:E] Stopped 2025-08-24T11:32:08.469Z,1756035128.469 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-08-24T11:32:08.469Z,1756035128.469 [keepstation:NeedComms] Stopped 2025-08-24T11:32:08.469Z,1756035128.469 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-08-24T11:32:08.469Z,1756035128.469 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-08-24T11:32:08.469Z,1756035128.469 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-08-24T11:32:08.872Z,1756035128.872 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-08-24T11:32:08.872Z,1756035128.872 [keepstation:SurfaceComms:B] Stopped 2025-08-24T11:32:08.872Z,1756035128.872 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-08-24T11:32:08.872Z,1756035128.872 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-08-24T11:32:08.872Z,1756035128.872 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-08-24T11:32:08.872Z,1756035128.872 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-08-24T11:32:09.385Z,1756035129.385 [keepstation:SurfaceComms:setTransit] Stopped 2025-08-24T11:32:09.385Z,1756035129.385 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-08-24T11:32:09.385Z,1756035129.385 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-08-24T11:32:09.385Z,1756035129.385 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-08-24T11:32:09.385Z,1756035129.385 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-08-24T11:32:09.385Z,1756035129.385 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-08-24T11:32:09.652Z,1756035129.652 [keepstation:SurfaceComms:setStation] Stopped 2025-08-24T11:32:09.653Z,1756035129.653 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-08-24T11:32:09.653Z,1756035129.653 [keepstation:SurfaceComms:setStation:A] Stopped 2025-08-24T11:32:09.653Z,1756035129.653 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-08-24T11:32:09.653Z,1756035129.653 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-08-24T11:32:09.653Z,1756035129.653 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-08-24T11:32:10.073Z,1756035130.073 [keepstation:SurfaceComms:setSink:A] Stopped 2025-08-24T11:32:10.073Z,1756035130.073 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-08-24T11:32:10.073Z,1756035130.073 [keepstation:SurfaceComms:setSink] Stopped 2025-08-24T11:32:10.073Z,1756035130.073 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-08-24T11:32:10.073Z,1756035130.073 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-08-24T11:32:10.073Z,1756035130.073 [keepstation:SurfaceComms] Stopped 2025-08-24T11:32:10.073Z,1756035130.073 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-08-24T11:32:10.462Z,1756035130.462 [keepstation:KeepStation:Dive] Running Loop=1 2025-08-24T11:32:10.462Z,1756035130.462 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-08-24T11:32:10.462Z,1756035130.462 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-08-24T11:32:10.462Z,1756035130.462 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-08-24T11:32:10.463Z,1756035130.463 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-08-24T11:32:10.463Z,1756035130.463 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-08-24T11:32:10.464Z,1756035130.464 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-08-24T11:32:10.464Z,1756035130.464 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T11:32:10.465Z,1756035130.465 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-08-24T11:32:10.465Z,1756035130.465 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-08-24T11:32:10.465Z,1756035130.465 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-08-24T11:32:10.465Z,1756035130.465 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-08-24T11:32:10.465Z,1756035130.465 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-08-24T11:32:10.465Z,1756035130.465 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-08-24T11:32:10.466Z,1756035130.466 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-08-24T11:32:10.466Z,1756035130.466 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-08-24T11:32:10.466Z,1756035130.466 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-08-24T11:32:35.861Z,1756035155.861 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:32:40.749Z,1756035160.749 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-24T11:33:06.996Z,1756035186.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:33:38.120Z,1756035218.120 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:34:09.248Z,1756035249.248 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:34:35.028Z,1756035275.028 [Radio_Surface](INFO): Powering down 2025-08-24T11:34:40.376Z,1756035280.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T11:34:41.380Z,1756035281.380 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-08-24T11:35:10.650Z,1756035310.650 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-08-24T11:32:10.5Z 2025-08-24T11:35:10.650Z,1756035310.650 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2025-08-24T11:35:10.650Z,1756035310.650 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2025-08-24T11:35:10.651Z,1756035310.651 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2025-08-24T11:35:10.653Z,1756035310.653 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 8.870183 m 2025-08-24T11:35:10.654Z,1756035310.654 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2025-08-24T11:35:10.654Z,1756035310.654 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2025-08-24T11:35:10.654Z,1756035310.654 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-08-24T11:35:10.654Z,1756035310.654 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-08-24T11:35:10.654Z,1756035310.654 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-08-24T11:35:10.654Z,1756035310.654 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-08-24T11:35:11.064Z,1756035311.064 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-08-24T11:35:11.064Z,1756035311.064 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-08-24T11:35:11.065Z,1756035311.065 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-08-24T11:35:11.065Z,1756035311.065 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-08-24T11:35:11.065Z,1756035311.065 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T11:35:11.065Z,1756035311.065 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-08-24T11:35:11.454Z,1756035311.454 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-08-24T11:35:11.454Z,1756035311.454 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T11:35:11.454Z,1756035311.454 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-08-24T11:35:11.454Z,1756035311.454 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T11:35:11.455Z,1756035311.455 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T11:35:11.455Z,1756035311.455 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-08-24T11:35:11.455Z,1756035311.455 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T11:35:11.456Z,1756035311.456 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-08-24T11:35:11.456Z,1756035311.456 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-08-24T11:35:11.456Z,1756035311.456 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-08-24T11:35:11.456Z,1756035311.456 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-08-24T11:35:11.456Z,1756035311.456 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-08-24T11:35:11.859Z,1756035311.859 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-08-24T11:35:11.859Z,1756035311.859 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-08-24T11:35:11.860Z,1756035311.860 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-08-24T11:35:11.860Z,1756035311.860 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-08-24T11:35:11.860Z,1756035311.860 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-08-24T11:35:11.860Z,1756035311.860 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-08-24T11:35:11.860Z,1756035311.860 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-08-24T11:44:50.786Z,1756035890.786 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-24T11:44:50.787Z,1756035890.787 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-24T11:44:50.788Z,1756035890.788 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-24T11:44:50.789Z,1756035890.789 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-08-24T11:44:50.792Z,1756035890.792 [BPC1](INFO): Received data from all battery sticks. 2025-08-24T11:55:24.464Z,1756036524.464 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-08-24T11:55:28.795Z,1756036528.795 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.000308 CHAN A1 (24V): 0.011171 CHAN A2 (12V): -0.024677 CHAN A3 (5V): -0.018931 CHAN B0 (3.3V): -0.009432 CHAN B1 (3.15aV): -0.008413 CHAN B2 (3.15bV): -0.006885 CHAN B3 (GND): -0.009720 OPEN: 0.008602 Full Scale: +/- 1 mA 2025-08-24T12:01:12.724Z,1756036872.724 [BuoyancyServo](FAULT): Buoyancy pump stop uart error: serial timeout 2025-08-24T12:01:12.725Z,1756036872.724 [BuoyancyServo] Communications Fault, FailCount= 1 2025-08-24T12:01:12.725Z,1756036872.725 [BuoyancyServo](ERROR): Communications Fault 2025-08-24T12:01:12.773Z,1756036872.773 [CBIT](ERROR): Communications Fault in component: BuoyancyServo 2025-08-24T12:01:14.544Z,1756036874.544 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-08-24T12:01:14.544Z,1756036874.544 [BuoyancyServo](INFO): Powering down 2025-08-24T12:01:15.380Z,1756036875.380 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2025-08-24T12:01:15.380Z,1756036875.380 [BuoyancyServo] No Fault, FailCount= 1 2025-08-24T12:01:15.752Z,1756036875.752 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-08-24T12:01:15.753Z,1756036875.753 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-08-24T12:32:08.488Z,1756038728.488 [keepstation:SurfaceComms] Running Loop=1 2025-08-24T12:32:08.488Z,1756038728.488 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-08-24T12:32:08.488Z,1756038728.488 [keepstation:SurfaceComms:A] Running Loop=1 2025-08-24T12:32:08.488Z,1756038728.488 [keepstation:SurfaceComms:A] Stopped 2025-08-24T12:32:08.488Z,1756038728.488 [keepstation:SurfaceComms:B] Running Loop=1 2025-08-24T12:32:08.489Z,1756038728.489 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-08-24T12:32:08.892Z,1756038728.892 [keepstation:KeepStation:Dive] Stopped 2025-08-24T12:32:08.892Z,1756038728.892 [keepstation:KeepStation:Dive](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive 2025-08-24T12:32:08.892Z,1756038728.892 [keepstation:KeepStation:Dive:A] Stopped 2025-08-24T12:32:08.892Z,1756038728.892 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:KeepStation:Dive:A 2025-08-24T12:32:08.893Z,1756038728.893 [keepstation:DiveCmd:StationKeep] Stopped 2025-08-24T12:32:08.893Z,1756038728.893 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-08-24T12:32:08.893Z,1756038728.893 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-08-24T12:32:08.893Z,1756038728.893 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-08-24T12:32:08.893Z,1756038728.893 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-08-24T12:32:08.893Z,1756038728.893 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-08-24T12:32:08.896Z,1756038728.896 [keepstation:NeedComms] Running Loop=1 2025-08-24T12:32:08.896Z,1756038728.896 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-08-24T12:32:08.896Z,1756038728.896 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-08-24T12:32:08.896Z,1756038728.896 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-08-24T12:32:08.896Z,1756038728.896 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-08-24T12:32:08.897Z,1756038728.897 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-08-24T12:32:08.897Z,1756038728.897 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-08-24T12:32:08.897Z,1756038728.897 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-08-24T12:32:08.898Z,1756038728.898 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-08-24T12:32:08.898Z,1756038728.898 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-08-24T12:32:08.898Z,1756038728.898 [keepstation:NeedComms:A] Running Loop=1 2025-08-24T12:32:08.900Z,1756038728.900 [keepstation:NeedComms:A](INFO): last time_fix was: 1756035128.000000 second since 1970/01/01T00:00:00Z 2025-08-24T12:32:08.900Z,1756038728.900 [keepstation:NeedComms:A] Stopped 2025-08-24T12:35:03.157Z,1756038903.157 [keepstation:NeedComms:C] Running Loop=1 2025-08-24T12:35:03.836Z,1756038903.836 [Radio_Surface](INFO): Powering up 2025-08-24T12:35:09.424Z,1756038909.424 [DataOverHttps](INFO): Radio surface powered ON. 2025-08-24T12:35:38.935Z,1756038938.935 [NAL9602](INFO): SBD MO Status=2, MOMSN=22476, MT Status=2, MTMSN=0 2025-08-24T12:35:38.935Z,1756038938.935 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T12:35:39.564Z,1756038939.564 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:36:09.231Z,1756038969.231 [NAL9602](INFO): SBD MO Status=2, MOMSN=22476, MT Status=2, MTMSN=0 2025-08-24T12:36:09.232Z,1756038969.232 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T12:36:10.709Z,1756038970.709 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:36:26.610Z,1756038986.610 [NAL9602](INFO): SBD MO Status=2, MOMSN=22476, MT Status=2, MTMSN=0 2025-08-24T12:36:26.610Z,1756038986.610 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T12:36:41.832Z,1756039001.832 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:37:12.960Z,1756039032.960 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:37:44.092Z,1756039064.092 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:38:15.216Z,1756039095.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:38:46.356Z,1756039126.356 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:39:07.801Z,1756039147.801 [NAL9602](INFO): SBD MO Status=2, MOMSN=22476, MT Status=2, MTMSN=0 2025-08-24T12:39:07.801Z,1756039147.801 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T12:39:17.492Z,1756039157.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:39:48.616Z,1756039188.616 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:40:07.587Z,1756039207.587 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-08-24T12:40:08.805Z,1756039208.805 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,124008.00,A,3657.86507,N,12248.80264,W,0.816,8.63,240825,,,A*76 2025-08-24T12:40:08.808Z,1756039208.808 [NAL9602](INFO): GPS fix at 20250824T124008: (36.964418, -122.813377) 2025-08-24T12:40:08.844Z,1756039208.844 [keepstation:NeedComms:C] Stopped 2025-08-24T12:40:08.844Z,1756039208.844 [keepstation:NeedComms:D] Running Loop=1 2025-08-24T12:40:19.744Z,1756039219.744 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:40:50.873Z,1756039250.873 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:41:21.996Z,1756039281.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:41:53.120Z,1756039313.120 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:42:15.714Z,1756039335.714 [NAL9602](INFO): SBD MO Status=2, MOMSN=22476, MT Status=2, MTMSN=0 2025-08-24T12:42:15.715Z,1756039335.715 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T12:42:24.244Z,1756039344.244 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:42:37.585Z,1756039357.585 [NAL9602](INFO): SBD MO Status=1, MOMSN=22476, MT Status=0, MTMSN=0 2025-08-24T12:42:37.640Z,1756039357.640 [NAL9602](INFO): Sent 89 bytes from file Logs/20250824T051703/Courier0018.lzma 2025-08-24T12:42:37.640Z,1756039357.640 [NAL9602](INFO): Packets left to send: 0 2025-08-24T12:42:53.002Z,1756039373.002 [NAL9602](INFO): SBD MO Status=2, MOMSN=22477, MT Status=2, MTMSN=0 2025-08-24T12:42:53.003Z,1756039373.003 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T12:42:55.376Z,1756039375.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:43:11.647Z,1756039391.647 [NAL9602](INFO): SBD MO Status=2, MOMSN=22477, MT Status=2, MTMSN=0 2025-08-24T12:43:11.647Z,1756039391.647 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T12:43:26.500Z,1756039406.500 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:43:33.526Z,1756039413.526 [NAL9602](INFO): SBD MO Status=1, MOMSN=22477, MT Status=0, MTMSN=0 2025-08-24T12:43:33.584Z,1756039413.584 [NAL9602](INFO): Sent 332 bytes from file Logs/20250824T051703/Express0019.lzma 2025-08-24T12:43:33.584Z,1756039413.584 [NAL9602](INFO): Packets left to send: 2 2025-08-24T12:43:46.126Z,1756039426.126 [NAL9602](INFO): SBD MO Status=2, MOMSN=22478, MT Status=2, MTMSN=0 2025-08-24T12:43:46.126Z,1756039426.126 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-08-24T12:43:57.628Z,1756039437.628 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:44:05.572Z,1756039445.572 [NAL9602](INFO): SBD MO Status=1, MOMSN=22478, MT Status=0, MTMSN=0 2025-08-24T12:44:05.630Z,1756039445.630 [NAL9602](INFO): Sent 332 bytes from file Logs/20250824T051703/Express0019.lzma 2025-08-24T12:44:05.630Z,1756039445.630 [NAL9602](INFO): Packets left to send: 1 2025-08-24T12:44:15.325Z,1756039455.325 [NAL9602](INFO): SBD MO Status=1, MOMSN=22479, MT Status=0, MTMSN=0 2025-08-24T12:44:15.380Z,1756039455.380 [NAL9602](INFO): Sent 170 bytes from file Logs/20250824T051703/Express0019.lzma 2025-08-24T12:44:15.380Z,1756039455.380 [NAL9602](INFO): Packets left to send: 0 2025-08-24T12:44:27.850Z,1756039467.850 [NAL9602](INFO): SBD MO Status=0, MOMSN=22480, MT Status=0, MTMSN=0 2025-08-24T12:44:27.942Z,1756039467.942 [keepstation:NeedComms:D] Stopped 2025-08-24T12:44:27.942Z,1756039467.942 [keepstation:NeedComms:E] Running Loop=1 2025-08-24T12:44:28.752Z,1756039468.752 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:44:30.269Z,1756039470.269 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,124430.00,A,3657.88117,N,12248.76283,W,0.739,41.26,240825,,,A*4C 2025-08-24T12:44:30.271Z,1756039470.271 [NAL9602](INFO): GPS fix at 20250824T124430: (36.964686, -122.812714) 2025-08-24T12:44:30.284Z,1756039470.284 [keepstation:NeedComms:E] Stopped 2025-08-24T12:44:30.285Z,1756039470.285 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-08-24T12:44:30.285Z,1756039470.285 [keepstation:NeedComms] Stopped 2025-08-24T12:44:30.285Z,1756039470.285 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-08-24T12:44:30.285Z,1756039470.285 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-08-24T12:44:30.285Z,1756039470.285 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-08-24T12:44:30.729Z,1756039470.729 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-08-24T12:44:30.729Z,1756039470.729 [keepstation:SurfaceComms:B] Stopped 2025-08-24T12:44:30.729Z,1756039470.729 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-08-24T12:44:30.729Z,1756039470.729 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-08-24T12:44:30.729Z,1756039470.729 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-08-24T12:44:30.729Z,1756039470.729 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-08-24T12:44:31.096Z,1756039471.096 [keepstation:SurfaceComms:setTransit] Stopped 2025-08-24T12:44:31.097Z,1756039471.097 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-08-24T12:44:31.097Z,1756039471.097 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-08-24T12:44:31.097Z,1756039471.097 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-08-24T12:44:31.097Z,1756039471.097 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-08-24T12:44:31.097Z,1756039471.097 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-08-24T12:44:31.494Z,1756039471.494 [keepstation:SurfaceComms:setStation] Stopped 2025-08-24T12:44:31.494Z,1756039471.494 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-08-24T12:44:31.494Z,1756039471.494 [keepstation:SurfaceComms:setStation:A] Stopped 2025-08-24T12:44:31.494Z,1756039471.494 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-08-24T12:44:31.494Z,1756039471.494 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-08-24T12:44:31.494Z,1756039471.494 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-08-24T12:44:31.895Z,1756039471.895 [keepstation:SurfaceComms:setSink:A] Stopped 2025-08-24T12:44:31.895Z,1756039471.895 [keepstation:SurfaceComms:setSink](INFO): Completed keepstation:SurfaceComms:setSink 2025-08-24T12:44:31.895Z,1756039471.895 [keepstation:SurfaceComms:setSink] Stopped 2025-08-24T12:44:31.895Z,1756039471.895 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-08-24T12:44:31.895Z,1756039471.895 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-08-24T12:44:31.896Z,1756039471.896 [keepstation:SurfaceComms] Stopped 2025-08-24T12:44:31.896Z,1756039471.896 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-08-24T12:44:32.308Z,1756039472.308 [keepstation:KeepStation:Dive] Running Loop=1 2025-08-24T12:44:32.309Z,1756039472.309 [keepstation:KeepStation:Dive](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive 2025-08-24T12:44:32.309Z,1756039472.309 [keepstation:KeepStation:Dive:A] Running Loop=1 2025-08-24T12:44:32.309Z,1756039472.309 [keepstation:KeepStation:Dive:A](DEBUG): Aggregate::initialize keepstation:KeepStation:Dive:A 2025-08-24T12:44:32.309Z,1756039472.309 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-08-24T12:44:32.309Z,1756039472.309 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-08-24T12:44:32.310Z,1756039472.310 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-08-24T12:44:32.310Z,1756039472.310 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T12:44:32.310Z,1756039472.310 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-08-24T12:44:32.310Z,1756039472.310 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-08-24T12:44:32.310Z,1756039472.310 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-08-24T12:44:32.310Z,1756039472.310 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-08-24T12:44:32.310Z,1756039472.310 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-08-24T12:44:32.311Z,1756039472.311 [keepstation:DiveCmd:SurfaceSink:sink:C](INFO): Sinking to 10.000000 m 2025-08-24T12:44:32.311Z,1756039472.311 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-08-24T12:44:32.311Z,1756039472.311 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Running Loop=1 2025-08-24T12:44:32.311Z,1756039472.311 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Initialize. 2025-08-24T12:44:59.884Z,1756039499.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:45:02.995Z,1756039502.995 [NAL9602](INFO): Not Powering down - fast GPS 2025-08-24T12:45:31.012Z,1756039531.012 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:46:02.136Z,1756039562.136 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:46:33.265Z,1756039593.265 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:46:58.632Z,1756039618.632 [Radio_Surface](INFO): Powering down 2025-08-24T12:47:04.388Z,1756039624.388 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-08-24T12:47:05.392Z,1756039625.392 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-08-24T12:47:32.483Z,1756039652.483 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](INFO): Timed out from 2025-08-24T12:44:32.3Z 2025-08-24T12:47:32.483Z,1756039652.483 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout] Running Loop=1 2025-08-24T12:47:32.484Z,1756039652.484 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2025-08-24T12:47:32.484Z,1756039652.484 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Running Loop=1 2025-08-24T12:47:32.484Z,1756039652.484 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A](IMPORTANT): Timed out while trying to reach target sink depth at current depth of 8.298937 m 2025-08-24T12:47:32.485Z,1756039652.485 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout:A] Stopped 2025-08-24T12:47:32.485Z,1756039652.485 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:D.Pitch:A_Timeout 2025-08-24T12:47:32.485Z,1756039652.485 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch] Stopped 2025-08-24T12:47:32.485Z,1756039652.485 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Running Loop=1 2025-08-24T12:47:32.485Z,1756039652.485 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-08-24T12:47:32.485Z,1756039652.485 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Running Loop=1 2025-08-24T12:47:32.951Z,1756039652.951 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit] Stopped 2025-08-24T12:47:32.951Z,1756039652.951 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit 2025-08-24T12:47:32.951Z,1756039652.951 [keepstation:DiveCmd:SurfaceSink:sink:sinkToTransit:A] Stopped 2025-08-24T12:47:32.951Z,1756039652.951 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Running Loop=1 2025-08-24T12:47:32.951Z,1756039652.951 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T12:47:32.951Z,1756039652.951 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Running Loop=1 2025-08-24T12:47:33.302Z,1756039653.302 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation:A] Stopped 2025-08-24T12:47:33.302Z,1756039653.302 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T12:47:33.302Z,1756039653.302 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation] Stopped 2025-08-24T12:47:33.302Z,1756039653.302 [keepstation:DiveCmd:SurfaceSink:sink:sinkToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink:sinkToStation 2025-08-24T12:47:33.303Z,1756039653.303 [keepstation:DiveCmd:SurfaceSink:sink](INFO): Completed keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T12:47:33.303Z,1756039653.303 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-08-24T12:47:33.303Z,1756039653.303 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-08-24T12:47:33.303Z,1756039653.303 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-08-24T12:47:33.303Z,1756039653.303 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-08-24T12:47:33.304Z,1756039653.304 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-08-24T12:47:33.304Z,1756039653.304 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-08-24T12:47:33.304Z,1756039653.304 [keepstation:DiveCmd:SurfaceSink](INFO): Completed keepstation:DiveCmd:SurfaceSink 2025-08-24T12:47:33.717Z,1756039653.717 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-08-24T12:47:33.717Z,1756039653.717 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-08-24T12:47:33.717Z,1756039653.717 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-08-24T12:47:33.717Z,1756039653.717 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-08-24T12:47:33.718Z,1756039653.718 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-08-24T12:47:33.718Z,1756039653.718 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-08-24T12:47:33.718Z,1756039653.718 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-08-24T12:52:29.417Z,1756039949.417 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-24T12:52:29.418Z,1756039949.418 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-24T12:52:29.419Z,1756039949.419 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-08-24T12:52:29.420Z,1756039949.420 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-08-24T12:52:29.422Z,1756039949.422 [BPC1](INFO): Received data from all battery sticks.