2025-02-20T16:55:15.204Z,1740070515.204 [NAL9602](INFO): Received command: restart logs
2025-02-20T16:55:15.221Z,1740070515.221 [CommandExec](IMPORTANT): got command restart logs
2025-02-20T16:55:16.334Z,1740070516.334 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165515.00,A,3647.94868,N,12150.80824,W,0.525,115.52,200225,,,A*74
2025-02-20T16:55:16.337Z,1740070516.337 [NAL9602](INFO): GPS fix at 20250220T165515: (36.799145, -121.846804)
2025-02-20T16:55:16.366Z,1740070516.366 [UniversalFixResidualReporter](INFO): Fix residual: 32.8 %DT, over the last 518.3 m. Residual distance 169.9 m at bearing 165.9 degrees. Fix at (36.7991, -121.8468) with 318.2 m made good.
2025-02-20T16:55:16.372Z,1740070516.372 [keepstation:NeedComms:C] Stopped
2025-02-20T16:55:16.373Z,1740070516.373 [keepstation:NeedComms:D] Running Loop=1
2025-02-20T16:55:16.782Z,1740070516.782 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-20T16:55:29.790Z,1740070529.790 [NAL9602](INFO): SBD MO Status=1, MOMSN=12900, MT Status=0, MTMSN=0
2025-02-20T16:55:29.844Z,1740070529.844 [NAL9602](INFO): Sent 86 bytes from file Logs/20250220T082925/Courier0031.lzma
2025-02-20T16:55:29.844Z,1740070529.844 [NAL9602](INFO): Packets left to send: 0
2025-02-20T16:55:38.079Z,1740070538.079 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T16:55:38.521Z,1740070538.521 [NAL9602](INFO): SBD MO Status=1, MOMSN=12901, MT Status=0, MTMSN=0
2025-02-20T16:55:38.575Z,1740070538.575 [NAL9602](INFO): Sent 332 bytes from file Logs/20250220T082925/Express0032.lzma
2025-02-20T16:55:38.576Z,1740070538.576 [NAL9602](INFO): Packets left to send: 5
2025-02-20T16:55:46.608Z,1740070546.608 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003558
2025-02-20T16:55:47.174Z,1740070547.174 [NAL9602](INFO): SBD MO Status=1, MOMSN=12902, MT Status=0, MTMSN=0
2025-02-20T16:55:47.244Z,1740070547.244 [NAL9602](INFO): Sent 332 bytes from file Logs/20250220T082925/Express0032.lzma
2025-02-20T16:55:47.245Z,1740070547.245 [NAL9602](INFO): Packets left to send: 4
2025-02-20T16:55:57.573Z,1740070557.573 [DataOverHttps](INFO): Sending 324 bytes from file Logs/20250220T165515/Courier0003.lzma
2025-02-20T16:55:58.573Z,1740070558.573 [DataOverHttps](INFO): Moved sent file to Logs/20250220T165515/Courier0003.lzma.bak
2025-02-20T16:55:58.573Z,1740070558.573 [DataOverHttps](INFO): SBD MOMSN=23968529
2025-02-20T16:56:18.010Z,1740070578.010 [DataOverHttps](INFO): Sending 1861 bytes from file Logs/20250220T082925/Express0032.lzma
2025-02-20T16:56:18.485Z,1740070578.485 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-20T16:56:19.009Z,1740070579.009 [DataOverHttps](INFO): Moved sent file to Logs/20250220T082925/Express0032.lzma.bak
2025-02-20T16:56:19.009Z,1740070579.009 [DataOverHttps](INFO): SBD MOMSN=23968533
2025-02-20T16:56:35.051Z,1740070595.051 [DataOverHttps](INFO): Sending 257 bytes from file Logs/20250220T165515/Express0004.lzma
2025-02-20T16:56:36.053Z,1740070596.053 [DataOverHttps](INFO): Moved sent file to Logs/20250220T165515/Express0004.lzma.bak
2025-02-20T16:56:36.053Z,1740070596.053 [DataOverHttps](INFO): SBD MOMSN=23968587
2025-02-20T16:56:37.553Z,1740070597.553 [keepstation:NeedComms:D] Stopped
2025-02-20T16:56:37.553Z,1740070597.553 [keepstation:NeedComms:E] Running Loop=1
2025-02-20T16:56:37.941Z,1740070597.941 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-20T16:56:39.526Z,1740070599.526 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165639.00,A,3647.94522,N,12150.79352,W,0.058,93.37,200225,,,D*40
2025-02-20T16:56:39.528Z,1740070599.528 [NAL9602](INFO): GPS fix at 20250220T165639: (36.799087, -121.846559)
2025-02-20T16:56:39.578Z,1740070599.578 [keepstation:NeedComms:E] Stopped
2025-02-20T16:56:39.583Z,1740070599.583 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-20T16:56:39.583Z,1740070599.583 [keepstation:NeedComms] Stopped
2025-02-20T16:56:39.583Z,1740070599.583 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-20T16:56:39.583Z,1740070599.583 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-20T16:56:39.584Z,1740070599.584 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-20T16:56:39.982Z,1740070599.982 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-02-20T16:56:39.983Z,1740070599.983 [keepstation:StartingMission] Stopped
2025-02-20T16:56:39.983Z,1740070599.983 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-02-20T16:56:39.987Z,1740070599.987 [keepstation:TransitToStation] Running Loop=1
2025-02-20T16:56:39.987Z,1740070599.987 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-02-20T16:56:39.987Z,1740070599.987 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-02-20T16:56:39.987Z,1740070599.987 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-02-20T16:56:39.987Z,1740070599.987 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-02-20T16:56:39.987Z,1740070599.987 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize.
2025-02-20T16:56:39.988Z,1740070599.988 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-02-20T16:56:39.988Z,1740070599.988 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize.
2025-02-20T16:56:39.988Z,1740070599.988 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-02-20T16:56:39.988Z,1740070599.988 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-02-20T16:56:39.989Z,1740070599.989 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-02-20T16:56:40.368Z,1740070600.368 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2025-02-20T16:56:40.368Z,1740070600.368 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-02-20T16:56:40.369Z,1740070600.369 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-02-20T16:56:40.369Z,1740070600.369 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-02-20T16:56:58.282Z,1740070618.282 [NAL9602](INFO): SBD MO Status=2, MOMSN=12903, MT Status=2, MTMSN=0
2025-02-20T16:56:58.282Z,1740070618.282 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T16:57:05.946Z,1740070625.946 [NAL9602](INFO): SBD MO Status=0, MOMSN=12903, MT Status=0, MTMSN=0
2025-02-20T16:57:05.946Z,1740070625.946 [NAL9602](INFO): No messages in MT queue
2025-02-20T16:57:36.541Z,1740070656.541 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-20T16:58:40.103Z,1740070720.103 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T16:59:11.248Z,1740070751.248 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T16:59:14.735Z,1740070754.735 [Radio_Surface](INFO): Powering down
2025-02-20T16:59:42.371Z,1740070782.371 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T16:59:43.376Z,1740070783.376 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-20T17:01:05.704Z,1740070865.704 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739319
2025-02-20T17:02:21.079Z,1740070941.079 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000
2025-02-20T17:02:21.079Z,1740070941.079 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-02-20T17:02:21.079Z,1740070941.079 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-02-20T17:02:21.080Z,1740070941.080 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-02-20T17:02:21.080Z,1740070941.080 [keepstation:TransitToStation] Stopped
2025-02-20T17:02:21.081Z,1740070941.081 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-02-20T17:02:21.081Z,1740070941.081 [keepstation:TransitToStation:A.Buoyancy] Stopped
2025-02-20T17:02:21.081Z,1740070941.081 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-02-20T17:02:21.081Z,1740070941.081 [keepstation:TransitToStation:B.Pitch] Stopped
2025-02-20T17:02:21.081Z,1740070941.081 [keepstation:TransitToStation:C.SetSpeed] Stopped
2025-02-20T17:02:21.081Z,1740070941.081 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize.
2025-02-20T17:02:21.081Z,1740070941.081 [keepstation:OnStation] Running Loop=1
2025-02-20T17:02:21.081Z,1740070941.081 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-02-20T17:02:21.486Z,1740070941.486 [keepstation:NeedComms] Running Loop=1
2025-02-20T17:02:21.486Z,1740070941.486 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-20T17:02:21.486Z,1740070941.486 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-20T17:02:21.486Z,1740070941.486 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-20T17:02:21.487Z,1740070941.487 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-20T17:02:21.487Z,1740070941.487 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-20T17:02:21.488Z,1740070941.488 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-20T17:02:21.488Z,1740070941.488 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-20T17:02:21.488Z,1740070941.488 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-20T17:02:21.489Z,1740070941.489 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-20T17:02:21.489Z,1740070941.489 [keepstation:NeedComms:A] Running Loop=1
2025-02-20T17:02:21.491Z,1740070941.491 [keepstation:NeedComms:A](INFO): last time_fix was:  1740070599.000000 second since 1970/01/01T00:00:00Z 
2025-02-20T17:02:21.491Z,1740070941.491 [keepstation:NeedComms:A] Stopped
2025-02-20T17:03:12.931Z,1740070992.931 [keepstation:NeedComms:C] Running Loop=1
2025-02-20T17:03:13.499Z,1740070993.499 [Radio_Surface](INFO): Powering up
2025-02-20T17:03:19.251Z,1740070999.251 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-20T17:03:44.458Z,1740071024.458 [NAL9602](INFO): SBD MO Status=2, MOMSN=12904, MT Status=2, MTMSN=0
2025-02-20T17:03:44.458Z,1740071024.458 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T17:03:49.391Z,1740071029.391 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T17:03:55.764Z,1740071035.764 [NAL9602](INFO): SBD MO Status=2, MOMSN=12904, MT Status=2, MTMSN=0
2025-02-20T17:03:55.764Z,1740071035.764 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T17:03:59.798Z,1740071039.798 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T17:04:12.726Z,1740071052.726 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T17:04:20.523Z,1740071060.523 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T17:04:26.058Z,1740071066.058 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T17:04:34.548Z,1740071074.548 [NAL9602](INFO): SBD MO Status=2, MOMSN=12904, MT Status=2, MTMSN=0
2025-02-20T17:04:34.548Z,1740071074.548 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T17:04:39.391Z,1740071079.391 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T17:04:42.646Z,1740071082.646 [NAL9602](INFO): SBD MO Status=0, MOMSN=12904, MT Status=0, MTMSN=0
2025-02-20T17:04:42.646Z,1740071082.646 [NAL9602](INFO): No messages in MT queue
2025-02-20T17:04:43.842Z,1740071083.842 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170443.00,A,3647.78842,N,12150.79542,W,1.283,129.90,200225,,,D*7D
2025-02-20T17:04:43.845Z,1740071083.845 [NAL9602](INFO): GPS fix at 20250220T170443: (36.796474, -121.846590)
2025-02-20T17:04:43.857Z,1740071083.857 [keepstation:NeedComms:C] Stopped
2025-02-20T17:04:43.858Z,1740071083.858 [keepstation:NeedComms:D] Running Loop=1
2025-02-20T17:04:51.594Z,1740071091.594 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T17:04:51.657Z,1740071091.657 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T17:05:01.302Z,1740071101.302 [NAL9602](INFO): SBD MO Status=2, MOMSN=12905, MT Status=2, MTMSN=0
2025-02-20T17:05:01.302Z,1740071101.302 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T17:05:04.523Z,1740071104.523 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T17:05:17.619Z,1740071117.619 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T17:05:22.780Z,1740071122.780 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T17:05:24.490Z,1740071124.490 [NAL9602](INFO): SBD MO Status=1, MOMSN=12905, MT Status=0, MTMSN=0
2025-02-20T17:05:24.543Z,1740071124.543 [NAL9602](INFO): Sent 74 bytes from file Logs/20250220T165515/Courier0006.lzma
2025-02-20T17:05:24.544Z,1740071124.544 [NAL9602](INFO): Packets left to send: 0
2025-02-20T17:05:31.936Z,1740071131.936 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003192
2025-02-20T17:05:39.692Z,1740071139.692 [DataOverHttps](INFO): Sending 336 bytes from file Logs/20250220T165515/Express0007.lzma
2025-02-20T17:05:40.693Z,1740071140.693 [DataOverHttps](INFO): Moved sent file to Logs/20250220T165515/Express0007.lzma.bak
2025-02-20T17:05:40.693Z,1740071140.693 [DataOverHttps](INFO): SBD MOMSN=23968596
2025-02-20T17:05:42.287Z,1740071142.287 [keepstation:NeedComms:D] Stopped
2025-02-20T17:05:42.288Z,1740071142.288 [keepstation:NeedComms:E] Running Loop=1
2025-02-20T17:05:44.642Z,1740071144.642 [NAL9602](INFO): SBD MO Status=2, MOMSN=12906, MT Status=2, MTMSN=0
2025-02-20T17:05:44.642Z,1740071144.642 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T17:05:45.854Z,1740071145.854 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170545.00,A,3647.78638,N,12150.78810,W,0.292,110.43,200225,,,D*77
2025-02-20T17:05:45.857Z,1740071145.857 [NAL9602](INFO): GPS fix at 20250220T170545: (36.796440, -121.846468)
2025-02-20T17:05:45.952Z,1740071145.952 [keepstation:NeedComms:E] Stopped
2025-02-20T17:05:45.956Z,1740071145.956 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-20T17:05:45.956Z,1740071145.956 [keepstation:NeedComms] Stopped
2025-02-20T17:05:45.956Z,1740071145.956 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-20T17:05:45.956Z,1740071145.956 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-20T17:05:45.956Z,1740071145.956 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-20T17:05:46.289Z,1740071146.289 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-02-20T17:05:46.289Z,1740071146.289 [keepstation:OnStation] Stopped
2025-02-20T17:05:46.289Z,1740071146.289 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-02-20T17:05:46.290Z,1740071146.290 [keepstation:KeepStation] Running Loop=1
2025-02-20T17:05:46.290Z,1740071146.290 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-02-20T17:05:46.290Z,1740071146.290 [keepstation:KeepStation:A] Running Loop=1
2025-02-20T17:05:46.290Z,1740071146.290 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-02-20T17:05:46.290Z,1740071146.290 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize.
2025-02-20T17:05:46.290Z,1740071146.290 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-02-20T17:05:46.290Z,1740071146.290 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-02-20T17:05:46.343Z,1740071146.343 [keepstation:KeepStation:D.Wait] Running Loop=1
2025-02-20T17:05:46.343Z,1740071146.343 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component.
2025-02-20T17:05:46.705Z,1740071146.705 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-02-20T17:05:46.706Z,1740071146.706 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-02-20T17:05:46.706Z,1740071146.706 [keepstation:KeepStation:A] Running Loop=1
2025-02-20T17:06:18.584Z,1740071178.584 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-20T17:07:42.983Z,1740071262.983 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T17:08:07.783Z,1740071287.783 [Radio_Surface](INFO): Powering down
2025-02-20T17:08:14.107Z,1740071294.107 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T17:08:15.111Z,1740071295.111 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-20T17:09:51.548Z,1740071391.548 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.795319
2025-02-20T17:10:17.776Z,1740071417.776 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-20T17:10:24.660Z,1740071424.660 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-20T17:10:24.660Z,1740071424.660 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-20T17:10:24.663Z,1740071424.663 [BPC1](INFO): Received data from all battery sticks.
2025-02-20T17:30:14.833Z,1740072614.833 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2025-02-20T17:37:19.460Z,1740073039.460 [keepstation:NeedComms] Running Loop=1
2025-02-20T17:37:19.461Z,1740073039.461 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-20T17:37:19.461Z,1740073039.461 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-20T17:37:19.461Z,1740073039.461 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-20T17:37:19.461Z,1740073039.461 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-20T17:37:19.461Z,1740073039.461 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-20T17:37:19.462Z,1740073039.462 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-20T17:37:19.462Z,1740073039.462 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-20T17:37:19.462Z,1740073039.462 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-20T17:37:19.463Z,1740073039.463 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-20T17:37:19.464Z,1740073039.464 [keepstation:NeedComms:A] Running Loop=1
2025-02-20T17:37:19.465Z,1740073039.465 [keepstation:NeedComms:A](INFO): last time_fix was:  1740071145.000000 second since 1970/01/01T00:00:00Z 
2025-02-20T17:37:19.465Z,1740073039.465 [keepstation:NeedComms:A] Stopped
2025-02-20T17:38:49.919Z,1740073129.919 [keepstation:NeedComms:C] Running Loop=1
2025-02-20T17:38:50.455Z,1740073130.455 [Radio_Surface](INFO): Powering up
2025-02-20T17:38:56.628Z,1740073136.628 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-20T17:39:17.382Z,1740073157.382 [NAL9602](INFO): SBD MO Status=2, MOMSN=12906, MT Status=2, MTMSN=0
2025-02-20T17:39:17.382Z,1740073157.382 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T17:39:26.760Z,1740073166.760 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T17:39:31.522Z,1740073171.522 [NAL9602](INFO): SBD MO Status=2, MOMSN=12906, MT Status=2, MTMSN=0
2025-02-20T17:39:31.522Z,1740073171.522 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T17:39:56.566Z,1740073196.566 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=12906, MT Status=1, MTMSN=253
2025-02-20T17:39:56.566Z,1740073196.566 [NAL9602](INFO): Data available in MT queue
2025-02-20T17:39:57.248Z,1740073197.248 [NAL9602](INFO): Received command: stop
2025-02-20T17:39:57.280Z,1740073197.280 [CommandExec](IMPORTANT): got command stop
2025-02-20T17:39:57.283Z,1740073197.283 [CommandExec](IMPORTANT): Scheduling is paused
2025-02-20T17:39:57.283Z,1740073197.283 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-02-20T17:39:57.398Z,1740073197.398 [MissionManager](INFO): MissionManager is completed.
2025-02-20T17:39:57.398Z,1740073197.398 [MissionManager](INFO): Uninitializing Mission keepstation
2025-02-20T17:39:57.399Z,1740073197.399 [keepstation] Stopped
2025-02-20T17:39:57.399Z,1740073197.399 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-02-20T17:39:57.399Z,1740073197.399 [keepstation:NeedComms] Stopped
2025-02-20T17:39:57.399Z,1740073197.399 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-20T17:39:57.399Z,1740073197.399 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-20T17:39:57.399Z,1740073197.399 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-20T17:39:57.399Z,1740073197.399 [keepstation:NeedComms:C] Stopped
2025-02-20T17:39:57.399Z,1740073197.399 [keepstation:StandardEnvelopes] Stopped
2025-02-20T17:39:57.399Z,1740073197.399 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-02-20T17:39:57.400Z,1740073197.400 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-02-20T17:39:57.400Z,1740073197.400 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-02-20T17:39:57.400Z,1740073197.400 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-02-20T17:39:57.400Z,1740073197.400 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-02-20T17:39:57.400Z,1740073197.400 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-02-20T17:39:57.400Z,1740073197.400 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-02-20T17:39:57.400Z,1740073197.400 [keepstation:BackseatDriver] Stopped
2025-02-20T17:39:57.400Z,1740073197.400 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-02-20T17:39:57.400Z,1740073197.400 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-02-20T17:39:57.400Z,1740073197.400 [keepstation:PowerOnly] Stopped
2025-02-20T17:39:57.400Z,1740073197.400 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-02-20T17:39:57.400Z,1740073197.400 [keepstation:PowerOnly:E.Wait] Stopped
2025-02-20T17:39:57.401Z,1740073197.401 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-02-20T17:39:57.401Z,1740073197.401 [keepstation:H.Pitch] Stopped
2025-02-20T17:39:57.401Z,1740073197.401 [keepstation:KeepStation] Stopped
2025-02-20T17:39:57.401Z,1740073197.401 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2025-02-20T17:39:57.401Z,1740073197.401 [keepstation:KeepStation:A] Stopped
2025-02-20T17:39:57.401Z,1740073197.401 [keepstation:KeepStation:B.Pitch] Stopped
2025-02-20T17:39:57.401Z,1740073197.401 [keepstation:KeepStation:C.KeepStation] Stopped
2025-02-20T17:39:57.401Z,1740073197.401 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-02-20T17:39:57.401Z,1740073197.401 [keepstation:KeepStation:D.Wait] Stopped
2025-02-20T17:39:57.401Z,1740073197.401 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component.
2025-02-20T17:39:57.815Z,1740073197.815 [MissionManager](IMPORTANT): Started mission Default
2025-02-20T17:39:57.816Z,1740073197.816 [Default] Running Loop=1
2025-02-20T17:39:57.816Z,1740073197.816 [Default](DEBUG): Aggregate::initialize Default
2025-02-20T17:39:57.816Z,1740073197.816 [Default:B.GoToSurface] Running Loop=1
2025-02-20T17:39:57.816Z,1740073197.816 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-20T17:39:57.816Z,1740073197.816 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-20T17:39:57.816Z,1740073197.816 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-20T17:39:57.817Z,1740073197.817 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-20T17:39:57.817Z,1740073197.817 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-20T17:39:57.817Z,1740073197.817 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-20T17:39:57.818Z,1740073197.818 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-20T17:39:57.818Z,1740073197.818 [Default:A.Wait] Running Loop=1
2025-02-20T17:39:57.818Z,1740073197.818 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-20T17:39:57.884Z,1740073197.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T17:39:58.182Z,1740073198.182 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173957.00,A,3647.75532,N,12150.65649,W,0.428,187.84,200225,,,A*76
2025-02-20T17:39:58.185Z,1740073198.185 [NAL9602](INFO): GPS fix at 20250220T173957: (36.795922, -121.844275)
2025-02-20T17:40:11.119Z,1740073211.119 [Default:A.Wait](INFO): Done Waiting.
2025-02-20T17:40:11.119Z,1740073211.119 [Default:A.Wait] Stopped
2025-02-20T17:40:11.119Z,1740073211.119 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-20T17:40:11.531Z,1740073211.531 [Default:CheckIn] Running Loop=1
2025-02-20T17:40:11.532Z,1740073211.532 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-20T17:40:11.532Z,1740073211.532 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-20T17:40:13.534Z,1740073213.534 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174012.00,A,3647.75401,N,12150.65570,W,0.428,149.13,200225,,,A*7D
2025-02-20T17:40:13.537Z,1740073213.537 [NAL9602](INFO): GPS fix at 20250220T174012: (36.795900, -121.844262)
2025-02-20T17:40:13.572Z,1740073213.572 [Default:CheckIn:Read_GPS] Stopped
2025-02-20T17:40:13.572Z,1740073213.572 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-20T17:40:29.007Z,1740073229.007 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T17:40:35.924Z,1740073235.924 [NAL9602](INFO): SBD MO Status=1, MOMSN=12907, MT Status=0, MTMSN=0
2025-02-20T17:40:35.972Z,1740073235.972 [NAL9602](INFO): Sent 202 bytes from file Logs/20250220T165515/Courier0009.lzma
2025-02-20T17:40:35.972Z,1740073235.972 [NAL9602](INFO): Packets left to send: 0
2025-02-20T17:40:49.034Z,1740073249.034 [NAL9602](INFO): SBD MO Status=1, MOMSN=12908, MT Status=0, MTMSN=0
2025-02-20T17:40:49.093Z,1740073249.093 [NAL9602](INFO): Sent 332 bytes from file Logs/20250220T165515/Express0010.lzma
2025-02-20T17:40:49.094Z,1740073249.094 [NAL9602](INFO): Packets left to send: 1
2025-02-20T17:41:00.131Z,1740073260.131 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T17:41:06.734Z,1740073266.734 [NAL9602](INFO): SBD MO Status=2, MOMSN=12909, MT Status=2, MTMSN=0
2025-02-20T17:41:06.734Z,1740073266.734 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T17:41:11.948Z,1740073271.948 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003211
2025-02-20T17:41:22.388Z,1740073282.388 [DataOverHttps](INFO): Sending 343 bytes from file Logs/20250220T165515/Express0010.lzma
2025-02-20T17:41:23.389Z,1740073283.389 [DataOverHttps](INFO): Moved sent file to Logs/20250220T165515/Express0010.lzma.bak
2025-02-20T17:41:23.389Z,1740073283.389 [DataOverHttps](INFO): SBD MOMSN=23968610
2025-02-20T17:41:25.388Z,1740073285.388 [Default:CheckIn:Read_Iridium] Stopped
2025-02-20T17:41:25.389Z,1740073285.389 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-20T17:41:25.389Z,1740073285.389 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-20T17:41:33.853Z,1740073293.853 [NAL9602](INFO): SBD MO Status=1, MOMSN=12909, MT Status=0, MTMSN=0
2025-02-20T17:41:33.903Z,1740073293.903 [NAL9602](INFO): Sent 11 bytes from file Logs/20250220T165515/Express0010.lzma
2025-02-20T17:41:33.904Z,1740073293.904 [NAL9602](INFO): Packets left to send: 0
2025-02-20T17:42:04.966Z,1740073324.966 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-20T17:45:36.152Z,1740073536.152 [DataOverHttps](IMPORTANT): SBD MTMSN=20250220T174535
2025-02-20T17:45:43.615Z,1740073543.615 [DataOverHttps](INFO): Received command: restart logs