2025-02-22T16:24:10.952Z,1740241450.952 [CommandExec](IMPORTANT): got command restart logs 2025-02-22T16:24:11.978Z,1740241451.978 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162411.00,A,3648.02933,N,12150.69291,W,0.369,38.85,220225,,,A*42 2025-02-22T16:24:11.980Z,1740241451.980 [NAL9602](INFO): GPS fix at 20250222T162411: (36.800489, -121.844882) 2025-02-22T16:24:12.035Z,1740241452.035 [keepstation:NeedComms:C] Stopped 2025-02-22T16:24:12.035Z,1740241452.035 [keepstation:NeedComms:D] Running Loop=1 2025-02-22T16:24:12.441Z,1740241452.441 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-02-22T16:24:20.884Z,1740241460.884 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20250222T060231/Courier0036.lzma 2025-02-22T16:24:21.448Z,1740241461.448 [DataOverHttps](INFO): Moved sent file to Logs/20250222T060231/Courier0036.lzma.bak 2025-02-22T16:24:21.448Z,1740241461.448 [DataOverHttps](INFO): SBD MOMSN=23972555 2025-02-22T16:24:40.801Z,1740241480.801 [DataOverHttps](INFO): Sending 1698 bytes from file Logs/20250222T060231/Express0037.lzma 2025-02-22T16:24:41.801Z,1740241481.801 [DataOverHttps](INFO): Moved sent file to Logs/20250222T060231/Express0037.lzma.bak 2025-02-22T16:24:41.801Z,1740241481.801 [DataOverHttps](INFO): SBD MOMSN=23972559 2025-02-22T16:24:44.163Z,1740241484.163 [keepstation:NeedComms:D] Stopped 2025-02-22T16:24:44.164Z,1740241484.164 [keepstation:NeedComms:E] Running Loop=1 2025-02-22T16:24:44.560Z,1740241484.560 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-22T16:24:44.589Z,1740241484.589 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-02-22T16:24:46.170Z,1740241486.170 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162445.00,A,3648.03065,N,12150.69225,W,0.603,350.62,220225,,,A*7A 2025-02-22T16:24:46.172Z,1740241486.172 [NAL9602](INFO): GPS fix at 20250222T162445: (36.800511, -121.844871) 2025-02-22T16:24:46.245Z,1740241486.245 [keepstation:NeedComms:E] Stopped 2025-02-22T16:24:46.246Z,1740241486.246 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-02-22T16:24:46.246Z,1740241486.246 [keepstation:NeedComms] Stopped 2025-02-22T16:24:46.246Z,1740241486.246 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-22T16:24:46.246Z,1740241486.246 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-22T16:24:46.246Z,1740241486.246 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-22T16:24:46.592Z,1740241486.592 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-02-22T16:24:46.592Z,1740241486.592 [keepstation:StartingMission] Stopped 2025-02-22T16:24:46.592Z,1740241486.592 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-02-22T16:24:46.593Z,1740241486.593 [keepstation:TransitToStation] Running Loop=1 2025-02-22T16:24:46.593Z,1740241486.593 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-02-22T16:24:46.593Z,1740241486.593 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2025-02-22T16:24:46.593Z,1740241486.593 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-02-22T16:24:46.593Z,1740241486.593 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2025-02-22T16:24:46.593Z,1740241486.593 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize. 2025-02-22T16:24:46.593Z,1740241486.593 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2025-02-22T16:24:46.593Z,1740241486.593 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize. 2025-02-22T16:24:46.593Z,1740241486.593 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-02-22T16:24:46.594Z,1740241486.594 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-02-22T16:24:46.594Z,1740241486.594 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2025-02-22T16:24:47.001Z,1740241487.001 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2025-02-22T16:24:47.007Z,1740241487.007 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2025-02-22T16:24:47.007Z,1740241487.007 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2025-02-22T16:24:47.008Z,1740241487.008 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2025-02-22T16:25:07.994Z,1740241507.994 [NAL9602](INFO): SBD MO Status=0, MOMSN=13169, MT Status=0, MTMSN=0 2025-02-22T16:25:07.994Z,1740241507.994 [NAL9602](INFO): No messages in MT queue 2025-02-22T16:25:38.681Z,1740241538.681 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-22T16:26:54.748Z,1740241614.748 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-22T16:27:15.399Z,1740241635.399 [Radio_Surface](INFO): Powering down 2025-02-22T16:27:25.872Z,1740241645.872 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-22T16:27:26.876Z,1740241646.876 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-02-22T16:29:02.276Z,1740241742.276 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.738525 2025-02-22T16:35:19.348Z,1740242119.348 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000 2025-02-22T16:35:19.348Z,1740242119.348 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2025-02-22T16:35:19.348Z,1740242119.348 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-02-22T16:35:19.350Z,1740242119.350 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2025-02-22T16:35:19.350Z,1740242119.350 [keepstation:TransitToStation] Stopped 2025-02-22T16:35:19.350Z,1740242119.350 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2025-02-22T16:35:19.350Z,1740242119.350 [keepstation:TransitToStation:A.Buoyancy] Stopped 2025-02-22T16:35:19.350Z,1740242119.350 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-02-22T16:35:19.350Z,1740242119.350 [keepstation:TransitToStation:B.Pitch] Stopped 2025-02-22T16:35:19.350Z,1740242119.350 [keepstation:TransitToStation:C.SetSpeed] Stopped 2025-02-22T16:35:19.350Z,1740242119.350 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize. 2025-02-22T16:35:19.350Z,1740242119.350 [keepstation:OnStation] Running Loop=1 2025-02-22T16:35:19.350Z,1740242119.350 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2025-02-22T16:35:19.745Z,1740242119.745 [keepstation:NeedComms] Running Loop=1 2025-02-22T16:35:19.745Z,1740242119.745 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-02-22T16:35:19.745Z,1740242119.745 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-02-22T16:35:19.745Z,1740242119.745 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-22T16:35:19.746Z,1740242119.746 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-02-22T16:35:19.746Z,1740242119.746 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-02-22T16:35:19.746Z,1740242119.746 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-02-22T16:35:19.747Z,1740242119.747 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-02-22T16:35:19.763Z,1740242119.763 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-22T16:35:19.764Z,1740242119.764 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-22T16:35:19.764Z,1740242119.764 [keepstation:NeedComms:A] Running Loop=1 2025-02-22T16:35:19.765Z,1740242119.765 [keepstation:NeedComms:A](INFO): last time_fix was: 1740241485.000000 second since 1970/01/01T00:00:00Z 2025-02-22T16:35:19.765Z,1740242119.765 [keepstation:NeedComms:A] Stopped 2025-02-22T16:36:10.375Z,1740242170.375 [keepstation:NeedComms:C] Running Loop=1 2025-02-22T16:36:10.835Z,1740242170.835 [Radio_Surface](INFO): Powering up 2025-02-22T16:36:17.051Z,1740242177.051 [DataOverHttps](INFO): Radio surface powered ON. 2025-02-22T16:36:47.171Z,1740242207.171 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-22T16:37:18.307Z,1740242238.307 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-22T16:37:21.137Z,1740242241.137 [NAL9602](INFO): SBD MO Status=2, MOMSN=13170, MT Status=2, MTMSN=0 2025-02-22T16:37:21.138Z,1740242241.138 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-22T16:37:36.093Z,1740242256.093 [NAL9602](INFO): SBD MO Status=2, MOMSN=13170, MT Status=2, MTMSN=0 2025-02-22T16:37:36.094Z,1740242256.094 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-22T16:37:49.432Z,1740242269.432 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-22T16:37:58.718Z,1740242278.718 [NAL9602](INFO): SBD MO Status=0, MOMSN=13170, MT Status=0, MTMSN=0 2025-02-22T16:37:58.718Z,1740242278.718 [NAL9602](INFO): No messages in MT queue 2025-02-22T16:37:59.926Z,1740242279.926 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163759.00,A,3647.80164,N,12150.81728,W,0.194,302.31,220225,,,A*77 2025-02-22T16:37:59.929Z,1740242279.929 [NAL9602](INFO): GPS fix at 20250222T163759: (36.796694, -121.846955) 2025-02-22T16:37:59.941Z,1740242279.941 [UniversalFixResidualReporter](INFO): Fix residual: 9.7 %DT, over the last 557.4 m. Residual distance 54.0 m at bearing -98.2 degrees. Fix at (36.7967, -121.8470) with 463.2 m made good. 2025-02-22T16:37:59.944Z,1740242279.944 [keepstation:NeedComms:C] Stopped 2025-02-22T16:37:59.944Z,1740242279.944 [keepstation:NeedComms:D] Running Loop=1 2025-02-22T16:38:10.638Z,1740242290.638 [NAL9602](INFO): SBD MO Status=1, MOMSN=13171, MT Status=0, MTMSN=0 2025-02-22T16:38:10.687Z,1740242290.687 [NAL9602](INFO): Sent 325 bytes from file Logs/20250222T162410/Courier0003.lzma 2025-02-22T16:38:10.688Z,1740242290.688 [NAL9602](INFO): Packets left to send: 0 2025-02-22T16:38:20.555Z,1740242300.555 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-22T16:38:21.710Z,1740242301.710 [NAL9602](INFO): SBD MO Status=1, MOMSN=13172, MT Status=0, MTMSN=0 2025-02-22T16:38:21.768Z,1740242301.768 [NAL9602](INFO): Sent 332 bytes from file Logs/20250222T162410/Express0004.lzma 2025-02-22T16:38:21.768Z,1740242301.768 [NAL9602](INFO): Packets left to send: 1 2025-02-22T16:38:28.737Z,1740242308.737 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003953 2025-02-22T16:38:29.714Z,1740242309.714 [NAL9602](INFO): SBD MO Status=1, MOMSN=13173, MT Status=0, MTMSN=0 2025-02-22T16:38:29.776Z,1740242309.776 [NAL9602](INFO): Sent 130 bytes from file Logs/20250222T162410/Express0004.lzma 2025-02-22T16:38:29.776Z,1740242309.776 [NAL9602](INFO): Packets left to send: 0 2025-02-22T16:38:30.949Z,1740242310.949 [keepstation:NeedComms:D] Stopped 2025-02-22T16:38:30.949Z,1740242310.949 [keepstation:NeedComms:E] Running Loop=1 2025-02-22T16:38:32.942Z,1740242312.942 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163832.00,A,3647.80287,N,12150.81764,W,0.175,41.71,220225,,,A*4C 2025-02-22T16:38:32.944Z,1740242312.944 [NAL9602](INFO): GPS fix at 20250222T163832: (36.796715, -121.846961) 2025-02-22T16:38:32.966Z,1740242312.966 [keepstation:NeedComms:E] Stopped 2025-02-22T16:38:32.971Z,1740242312.971 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-02-22T16:38:32.971Z,1740242312.971 [keepstation:NeedComms] Stopped 2025-02-22T16:38:32.971Z,1740242312.971 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-22T16:38:32.971Z,1740242312.971 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-22T16:38:32.972Z,1740242312.972 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-22T16:38:33.376Z,1740242313.376 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2025-02-22T16:38:33.376Z,1740242313.376 [keepstation:OnStation] Stopped 2025-02-22T16:38:33.376Z,1740242313.376 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2025-02-22T16:38:33.376Z,1740242313.376 [keepstation:KeepStation] Running Loop=1 2025-02-22T16:38:33.376Z,1740242313.376 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2025-02-22T16:38:33.377Z,1740242313.377 [keepstation:KeepStation:A] Running Loop=1 2025-02-22T16:38:33.377Z,1740242313.377 [keepstation:KeepStation:B.Pitch] Running Loop=1 2025-02-22T16:38:33.377Z,1740242313.377 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize. 2025-02-22T16:38:33.377Z,1740242313.377 [keepstation:KeepStation:C.KeepStation] Running Loop=1 2025-02-22T16:38:33.377Z,1740242313.377 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-02-22T16:38:33.377Z,1740242313.377 [keepstation:KeepStation:D.Wait] Running Loop=1 2025-02-22T16:38:33.377Z,1740242313.377 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component. 2025-02-22T16:38:33.785Z,1740242313.785 [keepstation:KeepStation:C.KeepStation] Running Loop=1 2025-02-22T16:38:33.983Z,1740242313.983 [keepstation:KeepStation:B.Pitch] Running Loop=1 2025-02-22T16:38:33.983Z,1740242313.983 [keepstation:KeepStation:A] Running Loop=1 2025-02-22T16:39:05.744Z,1740242345.744 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-22T16:40:41.916Z,1740242441.916 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-22T16:40:56.315Z,1740242456.315 [Radio_Surface](INFO): Powering down 2025-02-22T16:41:13.043Z,1740242473.043 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-22T16:41:14.047Z,1740242474.047 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-02-22T16:42:18.304Z,1740242538.304 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.263168 2025-02-22T17:09:48.805Z,1740244188.805 [keepstation:NeedComms] Running Loop=1 2025-02-22T17:09:48.805Z,1740244188.805 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-02-22T17:09:48.805Z,1740244188.805 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-02-22T17:09:48.806Z,1740244188.806 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-22T17:09:48.806Z,1740244188.806 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-02-22T17:09:48.806Z,1740244188.806 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-02-22T17:09:48.807Z,1740244188.807 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-02-22T17:09:48.823Z,1740244188.823 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-02-22T17:09:48.824Z,1740244188.824 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-22T17:09:48.824Z,1740244188.824 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-22T17:09:48.824Z,1740244188.824 [keepstation:NeedComms:A] Running Loop=1 2025-02-22T17:09:48.826Z,1740244188.826 [keepstation:NeedComms:A](INFO): last time_fix was: 1740242312.000000 second since 1970/01/01T00:00:00Z 2025-02-22T17:09:48.826Z,1740244188.826 [keepstation:NeedComms:A] Stopped 2025-02-22T17:11:21.507Z,1740244281.507 [keepstation:NeedComms:C] Running Loop=1 2025-02-22T17:11:21.999Z,1740244281.999 [Radio_Surface](INFO): Powering up 2025-02-22T17:11:27.576Z,1740244287.576 [DataOverHttps](INFO): Radio surface powered ON. 2025-02-22T17:11:37.662Z,1740244297.662 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=13174, MT Status=1, MTMSN=268 2025-02-22T17:11:37.662Z,1740244297.662 [NAL9602](INFO): Data available in MT queue 2025-02-22T17:11:38.148Z,1740244298.148 [NAL9602](INFO): Received command: stop 2025-02-22T17:11:38.232Z,1740244298.232 [CommandExec](IMPORTANT): got command stop 2025-02-22T17:11:38.237Z,1740244298.237 [CommandExec](IMPORTANT): Scheduling is paused 2025-02-22T17:11:38.237Z,1740244298.237 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2025-02-22T17:11:38.503Z,1740244298.503 [MissionManager](INFO): MissionManager is completed. 2025-02-22T17:11:38.504Z,1740244298.504 [MissionManager](INFO): Uninitializing Mission keepstation 2025-02-22T17:11:38.504Z,1740244298.504 [keepstation] Stopped 2025-02-22T17:11:38.504Z,1740244298.504 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2025-02-22T17:11:38.504Z,1740244298.504 [keepstation:NeedComms] Stopped 2025-02-22T17:11:38.504Z,1740244298.504 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-22T17:11:38.504Z,1740244298.504 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-22T17:11:38.504Z,1740244298.504 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-22T17:11:38.504Z,1740244298.504 [keepstation:NeedComms:C] Stopped 2025-02-22T17:11:38.504Z,1740244298.504 [keepstation:StandardEnvelopes] Stopped 2025-02-22T17:11:38.504Z,1740244298.504 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2025-02-22T17:11:38.504Z,1740244298.504 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-02-22T17:11:38.504Z,1740244298.504 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-02-22T17:11:38.505Z,1740244298.505 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-02-22T17:11:38.505Z,1740244298.505 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-02-22T17:11:38.505Z,1740244298.505 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-02-22T17:11:38.505Z,1740244298.505 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-02-22T17:11:38.505Z,1740244298.505 [keepstation:BackseatDriver] Stopped 2025-02-22T17:11:38.505Z,1740244298.505 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver 2025-02-22T17:11:38.505Z,1740244298.505 [keepstation:BackseatDriver:A.BackseatDriver] Stopped 2025-02-22T17:11:38.505Z,1740244298.505 [keepstation:PowerOnly] Stopped 2025-02-22T17:11:38.505Z,1740244298.505 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly 2025-02-22T17:11:38.505Z,1740244298.505 [keepstation:PowerOnly:E.Wait] Stopped 2025-02-22T17:11:38.505Z,1740244298.505 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-02-22T17:11:38.505Z,1740244298.505 [keepstation:H.Pitch] Stopped 2025-02-22T17:11:38.505Z,1740244298.505 [keepstation:KeepStation] Stopped 2025-02-22T17:11:38.506Z,1740244298.506 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation 2025-02-22T17:11:38.506Z,1740244298.506 [keepstation:KeepStation:A] Stopped 2025-02-22T17:11:38.506Z,1740244298.506 [keepstation:KeepStation:B.Pitch] Stopped 2025-02-22T17:11:38.506Z,1740244298.506 [keepstation:KeepStation:C.KeepStation] Stopped 2025-02-22T17:11:38.506Z,1740244298.506 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-02-22T17:11:38.506Z,1740244298.506 [keepstation:KeepStation:D.Wait] Stopped 2025-02-22T17:11:38.506Z,1740244298.506 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component. 2025-02-22T17:11:38.914Z,1740244298.914 [MissionManager](IMPORTANT): Started mission Default 2025-02-22T17:11:38.914Z,1740244298.914 [Default] Running Loop=1 2025-02-22T17:11:38.914Z,1740244298.914 [Default](DEBUG): Aggregate::initialize Default 2025-02-22T17:11:38.914Z,1740244298.914 [Default:B.GoToSurface] Running Loop=1 2025-02-22T17:11:38.914Z,1740244298.914 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-22T17:11:38.915Z,1740244298.915 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-02-22T17:11:38.915Z,1740244298.915 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-02-22T17:11:38.915Z,1740244298.915 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-02-22T17:11:38.916Z,1740244298.916 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-02-22T17:11:38.916Z,1740244298.916 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-22T17:11:38.916Z,1740244298.916 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-22T17:11:38.917Z,1740244298.917 [Default:A.Wait] Running Loop=1 2025-02-22T17:11:38.917Z,1740244298.917 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-02-22T17:11:39.286Z,1740244299.286 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171138.00,A,3647.82693,N,12150.73106,W,1.186,46.44,220225,,,A*4C 2025-02-22T17:11:39.288Z,1740244299.288 [NAL9602](INFO): GPS fix at 20250222T171138: (36.797115, -121.845518) 2025-02-22T17:11:52.223Z,1740244312.223 [Default:A.Wait](INFO): Done Waiting. 2025-02-22T17:11:52.223Z,1740244312.223 [Default:A.Wait] Stopped 2025-02-22T17:11:52.224Z,1740244312.224 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-02-22T17:11:52.631Z,1740244312.631 [Default:CheckIn] Running Loop=1 2025-02-22T17:11:52.631Z,1740244312.631 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-22T17:11:52.631Z,1740244312.631 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-22T17:11:54.631Z,1740244314.631 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171153.00,A,3647.82898,N,12150.72920,W,1.011,65.18,220225,,,A*4E 2025-02-22T17:11:54.633Z,1740244314.633 [NAL9602](INFO): GPS fix at 20250222T171153: (36.797150, -121.845487) 2025-02-22T17:11:54.645Z,1740244314.645 [Default:CheckIn:Read_GPS] Stopped 2025-02-22T17:11:54.646Z,1740244314.646 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-22T17:11:57.695Z,1740244317.695 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-22T17:12:09.657Z,1740244329.657 [NAL9602](INFO): SBD MO Status=1, MOMSN=13175, MT Status=0, MTMSN=0 2025-02-22T17:12:09.703Z,1740244329.703 [NAL9602](INFO): Sent 73 bytes from file Logs/20250222T162410/Courier0006.lzma 2025-02-22T17:12:09.703Z,1740244329.703 [NAL9602](INFO): Packets left to send: 0 2025-02-22T17:12:21.594Z,1740244341.594 [NAL9602](INFO): SBD MO Status=1, MOMSN=13176, MT Status=0, MTMSN=0 2025-02-22T17:12:21.646Z,1740244341.646 [NAL9602](INFO): Sent 295 bytes from file Logs/20250222T162410/Express0007.lzma 2025-02-22T17:12:21.646Z,1740244341.646 [NAL9602](INFO): Packets left to send: 0 2025-02-22T17:12:28.533Z,1740244348.533 [NAL9602](INFO): SBD MO Status=0, MOMSN=13177, MT Status=0, MTMSN=0 2025-02-22T17:12:28.832Z,1740244348.832 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-22T17:12:38.737Z,1740244358.737 [NAL9602](INFO): SBD MO Status=1, MOMSN=13178, MT Status=0, MTMSN=0 2025-02-22T17:12:38.787Z,1740244358.787 [NAL9602](INFO): Sent 39 bytes from file Logs/20250222T162410/Courier0009.lzma 2025-02-22T17:12:38.788Z,1740244358.788 [NAL9602](INFO): Packets left to send: 0 2025-02-22T17:12:47.050Z,1740244367.050 [NAL9602](INFO): SBD MO Status=1, MOMSN=13179, MT Status=0, MTMSN=0 2025-02-22T17:12:47.101Z,1740244367.101 [NAL9602](INFO): Sent 63 bytes from file Logs/20250222T162410/Express0010.lzma 2025-02-22T17:12:47.101Z,1740244367.101 [NAL9602](INFO): Packets left to send: 0 2025-02-22T17:12:52.814Z,1740244372.814 [NAL9602](INFO): SBD MO Status=0, MOMSN=13180, MT Status=0, MTMSN=0 2025-02-22T17:12:52.924Z,1740244372.924 [Default:CheckIn:Read_Iridium] Stopped 2025-02-22T17:12:52.924Z,1740244372.924 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-22T17:12:52.924Z,1740244372.924 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-22T17:12:59.959Z,1740244379.959 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-22T17:13:23.520Z,1740244403.520 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-22T17:13:31.095Z,1740244411.095 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-22T17:13:39.501Z,1740244419.501 [DataOverHttps](IMPORTANT): SBD MTMSN=20250222T171338 2025-02-22T17:13:39.505Z,1740244419.505 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004248 2025-02-22T17:13:48.555Z,1740244428.555 [DataOverHttps](INFO): Received command: restart logs