2025-04-11T17:33:15.670Z,1744392795.670 [CommandExec](IMPORTANT): got command restart logs 2025-04-11T17:34:16.724Z,1744392856.724 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-11T17:34:16.724Z,1744392856.724 [Default:CheckIn:C.Wait] Stopped 2025-04-11T17:34:16.724Z,1744392856.724 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-11T17:34:16.724Z,1744392856.724 [Default:CheckIn:D] Running Loop=1 2025-04-11T17:34:17.129Z,1744392857.129 [Default:CheckIn:D] Stopped 2025-04-11T17:34:17.129Z,1744392857.129 [Default:CheckIn:E] Running Loop=1 2025-04-11T17:34:17.574Z,1744392857.574 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.656951 min 2025-04-11T17:34:17.574Z,1744392857.574 [Default:CheckIn:E] Stopped 2025-04-11T17:34:17.575Z,1744392857.575 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-11T17:34:17.575Z,1744392857.575 [Default:CheckIn] Stopped 2025-04-11T17:34:17.575Z,1744392857.575 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-11T17:34:17.575Z,1744392857.575 [Default:CheckIn](INFO): Running loop #3 2025-04-11T17:34:17.575Z,1744392857.575 [Default:CheckIn] Running Loop=3 2025-04-11T17:34:17.575Z,1744392857.575 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-11T17:34:17.575Z,1744392857.575 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-11T17:34:19.528Z,1744392859.528 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173419.00,A,3647.88257,N,12151.02654,W,1.341,252.59,110425,,,A*7D 2025-04-11T17:34:19.530Z,1744392859.530 [NAL9602](INFO): GPS fix at 20250411T173419: (36.798043, -121.850442) 2025-04-11T17:34:19.586Z,1744392859.586 [Default:CheckIn:Read_GPS] Stopped 2025-04-11T17:34:19.586Z,1744392859.586 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-11T17:34:32.788Z,1744392872.788 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250411T023602/Courier0099.lzma 2025-04-11T17:34:33.792Z,1744392873.792 [DataOverHttps](INFO): Moved sent file to Logs/20250411T023602/Courier0099.lzma.bak 2025-04-11T17:34:33.792Z,1744392873.792 [DataOverHttps](INFO): SBD MOMSN=24616753 2025-04-11T17:34:35.644Z,1744392875.644 [NAL9602](INFO): SBD MO Status=0, MOMSN=19427, MT Status=0, MTMSN=0 2025-04-11T17:34:35.644Z,1744392875.644 [NAL9602](INFO): No messages in MT queue 2025-04-11T17:35:01.609Z,1744392901.609 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20250411T173315/Courier0000.lzma 2025-04-11T17:35:02.613Z,1744392902.613 [DataOverHttps](INFO): Moved sent file to Logs/20250411T173315/Courier0000.lzma.bak 2025-04-11T17:35:02.613Z,1744392902.613 [DataOverHttps](INFO): SBD MOMSN=24616762 2025-04-11T17:35:06.099Z,1744392906.099 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-11T17:35:30.014Z,1744392930.014 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20250411T023602/Express0100.lzma 2025-04-11T17:35:31.015Z,1744392931.015 [DataOverHttps](INFO): Moved sent file to Logs/20250411T023602/Express0100.lzma.bak 2025-04-11T17:35:31.015Z,1744392931.015 [DataOverHttps](INFO): SBD MOMSN=24616766 2025-04-11T17:36:00.615Z,1744392960.615 [DataOverHttps](INFO): Sending 509 bytes from file Logs/20250411T173315/Express0001.lzma 2025-04-11T17:36:01.625Z,1744392961.625 [DataOverHttps](INFO): Moved sent file to Logs/20250411T173315/Express0001.lzma.bak 2025-04-11T17:36:01.625Z,1744392961.625 [DataOverHttps](INFO): SBD MOMSN=24616770 2025-04-11T17:36:03.015Z,1744392963.015 [Default:CheckIn:Read_Iridium] Stopped 2025-04-11T17:36:03.016Z,1744392963.016 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-11T17:36:03.016Z,1744392963.016 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-11T17:41:03.653Z,1744393263.653 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-11T17:41:03.653Z,1744393263.653 [Default:CheckIn:C.Wait] Stopped 2025-04-11T17:41:03.653Z,1744393263.653 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-11T17:41:03.653Z,1744393263.653 [Default:CheckIn:D] Running Loop=1 2025-04-11T17:41:04.110Z,1744393264.110 [Default:CheckIn:D] Stopped 2025-04-11T17:41:04.110Z,1744393264.110 [Default:CheckIn:E] Running Loop=1 2025-04-11T17:41:04.460Z,1744393264.460 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.439964 min 2025-04-11T17:41:04.460Z,1744393264.460 [Default:CheckIn:E] Stopped 2025-04-11T17:41:04.460Z,1744393264.460 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-11T17:41:04.460Z,1744393264.460 [Default:CheckIn] Stopped 2025-04-11T17:41:04.460Z,1744393264.460 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-11T17:41:04.465Z,1744393264.465 [Default:CheckIn](INFO): Running loop #4 2025-04-11T17:41:04.465Z,1744393264.465 [Default:CheckIn] Running Loop=4 2025-04-11T17:41:04.465Z,1744393264.465 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-11T17:41:04.465Z,1744393264.465 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-11T17:41:06.465Z,1744393266.465 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174106.00,A,3647.87359,N,12151.08357,W,1.652,241.11,110425,,,D*71 2025-04-11T17:41:06.468Z,1744393266.468 [NAL9602](INFO): GPS fix at 20250411T174106: (36.797893, -121.851393) 2025-04-11T17:41:06.488Z,1744393266.488 [Default:CheckIn:Read_GPS] Stopped 2025-04-11T17:41:06.493Z,1744393266.493 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-11T17:41:19.986Z,1744393279.986 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250411T173315/Courier0006.lzma 2025-04-11T17:41:20.939Z,1744393280.939 [DataOverHttps](INFO): Moved sent file to Logs/20250411T173315/Courier0006.lzma.bak 2025-04-11T17:41:20.939Z,1744393280.939 [DataOverHttps](INFO): SBD MOMSN=24616827 2025-04-11T17:41:22.616Z,1744393282.616 [NAL9602](INFO): SBD MO Status=0, MOMSN=19428, MT Status=0, MTMSN=0 2025-04-11T17:41:22.616Z,1744393282.616 [NAL9602](INFO): No messages in MT queue 2025-04-11T17:41:49.482Z,1744393309.482 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20250411T173315/Express0007.lzma 2025-04-11T17:41:50.646Z,1744393310.646 [DataOverHttps](INFO): Moved sent file to Logs/20250411T173315/Express0007.lzma.bak 2025-04-11T17:41:50.646Z,1744393310.646 [DataOverHttps](INFO): SBD MOMSN=24616833 2025-04-11T17:41:51.805Z,1744393311.805 [Default:CheckIn:Read_Iridium] Stopped 2025-04-11T17:41:51.806Z,1744393311.806 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-11T17:41:51.806Z,1744393311.806 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-11T17:41:53.375Z,1744393313.375 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-11T17:45:05.389Z,1744393505.389 [DVL_micro](ERROR): Failed to parse: :SA,-01.94,-03.02,106.3 2025-04-11T17:45:35.275Z,1744393535.275 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,0000.20,000.00 2025-04-11T17:46:52.459Z,1744393612.459 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-11T17:46:52.459Z,1744393612.459 [Default:CheckIn:C.Wait] Stopped 2025-04-11T17:46:52.459Z,1744393612.459 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-11T17:46:52.459Z,1744393612.459 [Default:CheckIn:D] Running Loop=1 2025-04-11T17:46:52.847Z,1744393612.847 [Default:CheckIn:D] Stopped 2025-04-11T17:46:52.847Z,1744393612.847 [Default:CheckIn:E] Running Loop=1 2025-04-11T17:46:53.263Z,1744393613.263 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.252238 min 2025-04-11T17:46:53.263Z,1744393613.263 [Default:CheckIn:E] Stopped 2025-04-11T17:46:53.263Z,1744393613.263 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-11T17:46:53.263Z,1744393613.263 [Default:CheckIn] Stopped 2025-04-11T17:46:53.263Z,1744393613.263 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-11T17:46:53.263Z,1744393613.263 [Default:CheckIn](INFO): Running loop #5 2025-04-11T17:46:53.263Z,1744393613.263 [Default:CheckIn] Running Loop=5 2025-04-11T17:46:53.264Z,1744393613.264 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-11T17:46:53.264Z,1744393613.264 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-11T17:46:55.264Z,1744393615.264 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174654.00,A,3647.86465,N,12151.13280,W,0.272,240.56,110425,,,A*79 2025-04-11T17:46:55.266Z,1744393615.266 [NAL9602](INFO): GPS fix at 20250411T174654: (36.797744, -121.852213) 2025-04-11T17:46:55.276Z,1744393615.276 [Default:CheckIn:Read_GPS] Stopped 2025-04-11T17:46:55.276Z,1744393615.276 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-11T17:47:02.444Z,1744393622.444 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250411T173315/Courier0009.lzma 2025-04-11T17:47:03.446Z,1744393623.446 [DataOverHttps](INFO): Moved sent file to Logs/20250411T173315/Courier0009.lzma.bak 2025-04-11T17:47:03.447Z,1744393623.447 [DataOverHttps](INFO): SBD MOMSN=24616869 2025-04-11T17:47:14.252Z,1744393634.252 [NAL9602](INFO): SBD MO Status=0, MOMSN=19429, MT Status=0, MTMSN=0 2025-04-11T17:47:14.252Z,1744393634.252 [NAL9602](INFO): No messages in MT queue 2025-04-11T17:47:20.457Z,1744393640.457 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20250411T173315/Express0010.lzma 2025-04-11T17:47:21.454Z,1744393641.454 [DataOverHttps](INFO): Moved sent file to Logs/20250411T173315/Express0010.lzma.bak 2025-04-11T17:47:21.455Z,1744393641.455 [DataOverHttps](INFO): SBD MOMSN=24616872 2025-04-11T17:47:22.746Z,1744393642.746 [Default:CheckIn:Read_Iridium] Stopped 2025-04-11T17:47:22.746Z,1744393642.746 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-11T17:47:22.746Z,1744393642.746 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-11T17:47:44.964Z,1744393664.964 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-11T17:48:31.523Z,1744393711.523 [DataOverHttps](IMPORTANT): SBD MTMSN=20250411T174830 2025-04-11T17:48:39.649Z,1744393719.649 [DataOverHttps](INFO): Received command: load Transport/keepstation.tl;set keepstation.MissionTimeout 1 h;set keepstation.NeedCommsTime 20 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.DepthDeadband .5 m;run 2025-04-11T17:48:39.689Z,1744393719.689 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl 2025-04-11T17:48:39.690Z,1744393719.690 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl 2025-04-11T17:48:39.690Z,1744393719.690 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl 2025-04-11T17:48:39.700Z,1744393719.700 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl 2025-04-11T17:48:40.536Z,1744393720.536 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into: 4 45 NaN NaN 30 15 4 0.75 200 45 2 2025-04-11T17:48:40.541Z,1744393720.541 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2025-04-11T17:48:40.543Z,1744393720.543 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2025-04-11T17:48:40.545Z,1744393720.545 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2025-04-11T17:48:40.547Z,1744393720.547 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2025-04-11T17:48:40.550Z,1744393720.550 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2025-04-11T17:48:40.552Z,1744393720.552 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2025-04-11T17:48:40.554Z,1744393720.554 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2025-04-11T17:48:40.556Z,1744393720.556 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2025-04-11T17:48:40.559Z,1744393720.559 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2025-04-11T17:48:40.561Z,1744393720.561 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2025-04-11T17:48:40.563Z,1744393720.563 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2025-04-11T17:48:40.563Z,1744393720.563 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-04-11T17:48:40.564Z,1744393720.564 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-04-11T17:48:40.605Z,1744393720.605 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-04-11T17:48:40.896Z,1744393720.896 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2025-04-11T17:48:40.901Z,1744393720.901 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2025-04-11T17:48:40.903Z,1744393720.903 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2025-04-11T17:48:40.906Z,1744393720.906 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-04-11T17:48:40.908Z,1744393720.908 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2025-04-11T17:48:40.910Z,1744393720.910 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-04-11T17:48:40.912Z,1744393720.912 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s 2025-04-11T17:48:40.915Z,1744393720.915 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2025-04-11T17:48:40.917Z,1744393720.917 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2025-04-11T17:48:40.923Z,1744393720.923 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-11T17:48:40.000Z,1744393721.000 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-04-11T17:48:40.000Z,1744393721.000 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-04-11T17:48:41.006Z,1744393721.006 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-04-11T17:48:41.205Z,1744393721.205 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2025-04-11T17:48:41.209Z,1744393721.209 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2025-04-11T17:48:41.211Z,1744393721.211 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2025-04-11T17:48:41.214Z,1744393721.214 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2025-04-11T17:48:41.216Z,1744393721.216 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-04-11T17:48:41.217Z,1744393721.217 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-04-11T17:48:41.223Z,1744393721.223 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-04-11T17:48:41.230Z,1744393721.230 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-04-11T17:48:41.238Z,1744393721.238 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-04-11T17:48:41.238Z,1744393721.238 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-04-11T17:48:41.239Z,1744393721.239 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-04-11T17:48:41.302Z,1744393721.302 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-04-11T17:48:41.305Z,1744393721.305 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2025-04-11T17:48:41.308Z,1744393721.308 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-04-11T17:48:41.311Z,1744393721.311 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-04-11T17:48:41.311Z,1744393721.311 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-04-11T17:48:41.316Z,1744393721.316 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-04-11T17:48:41.450Z,1744393721.450 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-04-11T17:48:41.454Z,1744393721.454 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2025-04-11T17:48:41.455Z,1744393721.455 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2025-04-11T17:48:41.471Z,1744393721.471 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2025-04-11T17:48:41.474Z,1744393721.474 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2025-04-11T17:48:41.476Z,1744393721.476 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool 2025-04-11T17:48:41.483Z,1744393721.483 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-04-11T17:48:41.484Z,1744393721.484 [keepstation:H.Pitch](DEBUG): Construct. 2025-04-11T17:48:41.533Z,1744393721.533 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy. 2025-04-11T17:48:41.536Z,1744393721.536 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct. 2025-04-11T17:48:41.548Z,1744393721.548 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct. 2025-04-11T17:48:41.559Z,1744393721.559 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-04-11T17:48:41.583Z,1744393721.583 [keepstation:KeepStation:B.Pitch](DEBUG): Construct. 2025-04-11T17:48:41.601Z,1744393721.601 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation. 2025-04-11T17:48:41.619Z,1744393721.619 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait. 2025-04-11T17:48:41.629Z,1744393721.629 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission keepstation { """ Vehicle transits to desired waypoint (or stays put if none is commanded) and stays within the specified radius. """ arguments { MissionTimeout = 4 hour """ Maximum duration of mission """ NeedCommsTime = 45 minute """ How often to surface for communications """ Latitude = NaN degree """ Latitude of waypoint to hold. If NaN, hold the latitude at start of mission. """ Longitude = NaN degree """ Longitude of waypoint to hold. If NaN, hold the longitude at start of mission. """ Depth = 30 meter """ Depth held during drift mode """ ApproachDepth = 15 meter """ Depth for initial approach to station. """ DepthDeadband = 4 meter """ How much vertical drift from the specified depth is allowed in drift mode """ Speed = 0.75 meter_per_second """ Vehicle transit speed. """ Radius = 200 meter """ Radius of circle around waypoint to hold. """ MaxDepth = 45 meter """ Maximum allowable depth during the mission. """ MinOffshore = 2 kilometer """ Minimum distance from the shoreline to maintain """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl behavior Guidance:Pitch { run in parallel set massPosition = Control:VerticalControl.massDefault } call id="StartingMission" refId="NeedComms" aggregate TransitToStation { """ Need a separate aggregate for transit if we want to specify a different depth for the approach. """ run in sequence behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch { run in parallel set depth = ApproachDepth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude } } call id="OnStation" refId="NeedComms" aggregate KeepStation { run in sequence assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:KeepStation { run in parallel set latitude = Latitude set longitude = Longitude set radius = Radius set speed = Speed } behavior Guidance:Wait { """ Due to the way the KeepStation behavior is currently implemented, we must run it in parallel and use a Wait to keep from completing the mission before the timeout expires. However, this new version of the mission uses a top-level timeout so that the entire mission will not run for longer then MissionTimeout. """ run in sequence set duration = MissionTimeout } } } 2025-04-11T17:48:41.630Z,1744393721.630 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2025-04-11T17:49:14.999Z,1744393754.999 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 1 hour 2025-04-11T17:49:14.000Z,1744393755.000 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 20 minute 2025-04-11T17:49:15.001Z,1744393755.001 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.797 degree 2025-04-11T17:49:15.002Z,1744393755.002 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.847 degree 2025-04-11T17:49:15.002Z,1744393755.002 [CommandExec](IMPORTANT): got command set keepstation.DepthDeadband .5 meter 2025-04-11T17:49:15.003Z,1744393755.003 [CommandExec](IMPORTANT): got command run 2025-04-11T17:49:15.005Z,1744393755.005 [CommandExec](IMPORTANT): Running 2025-04-11T17:49:15.113Z,1744393755.113 [Default] Stopped 2025-04-11T17:49:15.113Z,1744393755.113 [Default](DEBUG): Aggregate::uninitialize Default 2025-04-11T17:49:15.113Z,1744393755.113 [Default:B.GoToSurface] Stopped 2025-04-11T17:49:15.113Z,1744393755.113 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-11T17:49:15.113Z,1744393755.113 [Default:CheckIn] Stopped 2025-04-11T17:49:15.114Z,1744393755.114 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-11T17:49:15.114Z,1744393755.114 [Default:CheckIn:C.Wait] Stopped 2025-04-11T17:49:15.114Z,1744393755.114 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-11T17:49:15.114Z,1744393755.114 [MissionManager](IMPORTANT): Started mission keepstation 2025-04-11T17:49:15.114Z,1744393755.114 [keepstation] Running Loop=1 2025-04-11T17:49:15.114Z,1744393755.114 [keepstation](DEBUG): Aggregate::initialize keepstation 2025-04-11T17:49:15.114Z,1744393755.114 [keepstation:StandardEnvelopes] Running Loop=1 2025-04-11T17:49:15.114Z,1744393755.114 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2025-04-11T17:49:15.114Z,1744393755.114 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-04-11T17:49:15.115Z,1744393755.115 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-04-11T17:49:15.115Z,1744393755.115 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-04-11T17:49:15.115Z,1744393755.115 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-04-11T17:49:15.115Z,1744393755.115 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-04-11T17:49:15.115Z,1744393755.115 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-04-11T17:49:15.115Z,1744393755.115 [keepstation:BackseatDriver] Running Loop=1 2025-04-11T17:49:15.115Z,1744393755.115 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2025-04-11T17:49:15.115Z,1744393755.115 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-04-11T17:49:15.115Z,1744393755.115 [keepstation:PowerOnly] Running Loop=1 2025-04-11T17:49:15.116Z,1744393755.116 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2025-04-11T17:49:15.116Z,1744393755.116 [keepstation:PowerOnly:A] Running Loop=1 2025-04-11T17:49:15.116Z,1744393755.116 [keepstation:PowerOnly:B] Running Loop=1 2025-04-11T17:49:15.116Z,1744393755.116 [keepstation:PowerOnly:C] Running Loop=1 2025-04-11T17:49:15.116Z,1744393755.116 [keepstation:PowerOnly:D] Running Loop=1 2025-04-11T17:49:15.116Z,1744393755.116 [keepstation:PowerOnly:E.Wait] Running Loop=1 2025-04-11T17:49:15.116Z,1744393755.116 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-04-11T17:49:15.116Z,1744393755.116 [keepstation:H.Pitch] Running Loop=1 2025-04-11T17:49:15.116Z,1744393755.116 [keepstation:H.Pitch](DEBUG): Initialize. 2025-04-11T17:49:15.121Z,1744393755.121 [keepstation:B] Running Loop=1 2025-04-11T17:49:15.121Z,1744393755.121 [keepstation:H.Pitch] Running Loop=1 2025-04-11T17:49:15.121Z,1744393755.121 [keepstation:PowerOnly] Running Loop=1 2025-04-11T17:49:15.122Z,1744393755.122 [keepstation:PowerOnly:D] Stopped 2025-04-11T17:49:15.122Z,1744393755.122 [keepstation:PowerOnly:C] Stopped 2025-04-11T17:49:15.122Z,1744393755.122 [keepstation:PowerOnly:B] Stopped 2025-04-11T17:49:15.122Z,1744393755.122 [keepstation:PowerOnly:A] Stopped 2025-04-11T17:49:15.122Z,1744393755.122 [keepstation:BackseatDriver] Running Loop=1 2025-04-11T17:49:15.122Z,1744393755.122 [keepstation:StandardEnvelopes] Running Loop=1 2025-04-11T17:49:15.122Z,1744393755.122 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-04-11T17:49:15.123Z,1744393755.123 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-04-11T17:49:15.123Z,1744393755.123 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-04-11T17:49:15.123Z,1744393755.123 [keepstation:B] Stopped 2025-04-11T17:49:15.124Z,1744393755.124 [keepstation:D] Running Loop=1 2025-04-11T17:49:15.459Z,1744393755.459 [keepstation:D] Stopped 2025-04-11T17:49:15.460Z,1744393755.460 [keepstation:E] Running Loop=1 2025-04-11T17:49:15.900Z,1744393755.900 [keepstation:E] Stopped 2025-04-11T17:49:15.900Z,1744393755.900 [keepstation:StartingMission] Running Loop=1 2025-04-11T17:49:15.900Z,1744393755.900 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2025-04-11T17:49:16.281Z,1744393756.281 [keepstation:NeedComms] Running Loop=1 2025-04-11T17:49:16.281Z,1744393756.281 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-04-11T17:49:16.282Z,1744393756.282 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-04-11T17:49:16.282Z,1744393756.282 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-11T17:49:16.282Z,1744393756.282 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-11T17:49:16.282Z,1744393756.282 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-11T17:49:16.283Z,1744393756.283 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-11T17:49:16.283Z,1744393756.283 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-11T17:49:16.283Z,1744393756.283 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-11T17:49:16.284Z,1744393756.284 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-11T17:49:16.284Z,1744393756.284 [keepstation:NeedComms:A] Running Loop=1 2025-04-11T17:49:16.286Z,1744393756.286 [keepstation:NeedComms:A](INFO): last time_fix was: 1744393614.000000 second since 1970/01/01T00:00:00Z 2025-04-11T17:49:16.286Z,1744393756.286 [keepstation:NeedComms:A] Stopped 2025-04-11T17:49:16.703Z,1744393756.703 [keepstation:NeedComms:C] Running Loop=1 2025-04-11T17:49:17.102Z,1744393757.102 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-11T17:49:17.945Z,1744393757.945 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE. Altitude:0.530000m. At depth:0.101188m. 2025-04-11T17:49:18.683Z,1744393758.683 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174918.00,A,3647.86117,N,12151.15221,W,0.680,210.96,110425,,,A*73 2025-04-11T17:49:18.686Z,1744393758.686 [NAL9602](INFO): GPS fix at 20250411T174918: (36.797686, -121.852537) 2025-04-11T17:49:18.699Z,1744393758.699 [keepstation:NeedComms:C] Stopped 2025-04-11T17:49:18.699Z,1744393758.699 [keepstation:NeedComms:D] Running Loop=1 2025-04-11T17:49:19.144Z,1744393759.144 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-04-11T17:49:26.860Z,1744393766.860 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250411T173315/Courier0012.lzma 2025-04-11T17:49:27.862Z,1744393767.862 [DataOverHttps](INFO): Moved sent file to Logs/20250411T173315/Courier0012.lzma.bak 2025-04-11T17:49:27.863Z,1744393767.863 [DataOverHttps](INFO): SBD MOMSN=24616878 2025-04-11T17:49:45.388Z,1744393785.388 [NAL9602](INFO): SBD MO Status=0, MOMSN=19430, MT Status=0, MTMSN=0 2025-04-11T17:49:45.388Z,1744393785.388 [NAL9602](INFO): No messages in MT queue 2025-04-11T17:49:47.873Z,1744393787.873 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250411T173315/Courier0015.lzma 2025-04-11T17:49:48.874Z,1744393788.874 [DataOverHttps](INFO): Moved sent file to Logs/20250411T173315/Courier0015.lzma.bak 2025-04-11T17:49:48.875Z,1744393788.875 [DataOverHttps](INFO): SBD MOMSN=24616888 2025-04-11T17:50:06.210Z,1744393806.210 [DataOverHttps](INFO): Sending 518 bytes from file Logs/20250411T173315/Express0013.lzma 2025-04-11T17:50:07.213Z,1744393807.213 [DataOverHttps](INFO): Moved sent file to Logs/20250411T173315/Express0013.lzma.bak 2025-04-11T17:50:07.213Z,1744393807.213 [DataOverHttps](INFO): SBD MOMSN=24616943 2025-04-11T17:50:16.080Z,1744393816.080 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-11T17:50:24.285Z,1744393824.285 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20250411T173315/Express0016.lzma 2025-04-11T17:50:25.286Z,1744393825.286 [DataOverHttps](INFO): Moved sent file to Logs/20250411T173315/Express0016.lzma.bak 2025-04-11T17:50:25.287Z,1744393825.287 [DataOverHttps](INFO): SBD MOMSN=24616957 2025-04-11T17:50:26.603Z,1744393826.603 [keepstation:NeedComms:D] Stopped 2025-04-11T17:50:26.604Z,1744393826.604 [keepstation:NeedComms:E] Running Loop=1 2025-04-11T17:50:27.017Z,1744393827.017 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-11T17:50:28.604Z,1744393828.604 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175028.00,A,3647.86033,N,12151.16198,W,0.505,260.06,110425,,,A*7D 2025-04-11T17:50:28.614Z,1744393828.614 [NAL9602](INFO): GPS fix at 20250411T175028: (36.797672, -121.852700) 2025-04-11T17:50:28.635Z,1744393828.635 [keepstation:NeedComms:E] Stopped 2025-04-11T17:50:28.636Z,1744393828.636 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-04-11T17:50:28.636Z,1744393828.636 [keepstation:NeedComms] Stopped 2025-04-11T17:50:28.636Z,1744393828.636 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-04-11T17:50:28.636Z,1744393828.636 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-04-11T17:50:28.636Z,1744393828.636 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-11T17:50:29.073Z,1744393829.073 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-04-11T17:50:29.073Z,1744393829.073 [keepstation:StartingMission] Stopped 2025-04-11T17:50:29.073Z,1744393829.073 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-04-11T17:50:29.074Z,1744393829.074 [keepstation:TransitToStation] Running Loop=1 2025-04-11T17:50:29.074Z,1744393829.074 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-04-11T17:50:29.074Z,1744393829.074 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2025-04-11T17:50:29.074Z,1744393829.074 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-04-11T17:50:29.074Z,1744393829.074 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2025-04-11T17:50:29.074Z,1744393829.074 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize. 2025-04-11T17:50:29.074Z,1744393829.074 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2025-04-11T17:50:29.074Z,1744393829.074 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize. 2025-04-11T17:50:29.074Z,1744393829.074 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-04-11T17:50:29.075Z,1744393829.075 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-04-11T17:50:29.075Z,1744393829.075 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2025-04-11T17:50:29.436Z,1744393829.436 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2025-04-11T17:50:29.462Z,1744393829.462 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2025-04-11T17:50:29.462Z,1744393829.462 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2025-04-11T17:50:29.463Z,1744393829.463 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2025-04-11T17:50:52.750Z,1744393852.750 [NAL9602](INFO): SBD MO Status=0, MOMSN=19431, MT Status=0, MTMSN=0 2025-04-11T17:50:52.750Z,1744393852.750 [NAL9602](INFO): No messages in MT queue 2025-04-11T17:50:56.680Z,1744393856.680 [DVL_micro](ERROR): Failed to parse: :BI,-01954,-00176,+00042,+00000,A 2025-04-11T17:50:57.891Z,1744393857.891 [DVL_micro](ERROR): Failed to parse: :SA,-01.80,+09.59,154.7 2025-04-11T17:51:23.546Z,1744393883.546 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-11T17:52:14.407Z,1744393934.407 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 30.00 deg, mass-position 11.90 mm (3 active estimators). 2025-04-11T17:52:58.173Z,1744393978.173 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T17:53:14.713Z,1744393994.713 [Radio_Surface](INFO): Powering down 2025-04-11T17:53:16.982Z,1744393996.982 [DVL_micro](ERROR): Failed to parse: :BI,-00067,+00030,+00111,+00000,A 2025-04-11T17:53:29.297Z,1744394009.297 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T17:53:30.301Z,1744394010.301 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-11T17:53:45.265Z,1744394025.265 [ElevatorServo](FAULT): Overload Error 2025-04-11T17:53:45.265Z,1744394025.265 [ElevatorServo] Hardware Fault, FailCount= 1 2025-04-11T17:53:45.265Z,1744394025.265 [ElevatorServo](ERROR): Hardware Fault 2025-04-11T17:53:45.608Z,1744394025.608 [CBIT](ERROR): Hardware Fault in component: ElevatorServo 2025-04-11T17:53:45.697Z,1744394025.697 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-04-11T17:53:45.697Z,1744394025.697 [ElevatorServo](INFO): Powering down 2025-04-11T17:53:46.476Z,1744394026.476 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-04-11T17:53:46.594Z,1744394026.594 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-04-11T17:53:46.752Z,1744394026.752 [CBIT](INFO): Clearing failed state for component ElevatorServo 2025-04-11T17:53:46.752Z,1744394026.752 [ElevatorServo] No Fault, FailCount= 1 2025-04-11T17:55:06.689Z,1744394106.689 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.723297 2025-04-11T18:00:02.834Z,1744394402.834 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-04-11T18:00:02.834Z,1744394402.834 [ThrusterHE](INFO): Powering down 2025-04-11T18:00:03.655Z,1744394403.655 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-04-11T18:00:03.774Z,1744394403.774 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-04-11T18:01:46.687Z,1744394506.687 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6. 2025-04-11T18:01:46.689Z,1744394506.689 [BPC1](INFO): Received data from all battery sticks. 2025-04-11T18:02:37.313Z,1744394557.313 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+11.3,I,-00022,+00281,+00032,+00000,I 2025-04-11T18:02:37.313Z,1744394557.313 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+11.3,I,-00022,+00281,+00032,+00000,I 2025-04-11T18:03:28.446Z,1744394608.446 [DVL_micro](ERROR): Failed to parse: :BI,-00102,-00385,+00105,+00000,I 2025-04-11T18:03:31.287Z,1744394611.287 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000 2025-04-11T18:03:31.287Z,1744394611.287 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2025-04-11T18:03:31.288Z,1744394611.288 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-04-11T18:03:31.293Z,1744394611.293 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2025-04-11T18:03:31.294Z,1744394611.294 [keepstation:TransitToStation] Stopped 2025-04-11T18:03:31.294Z,1744394611.294 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2025-04-11T18:03:31.294Z,1744394611.294 [keepstation:TransitToStation:A.Buoyancy] Stopped 2025-04-11T18:03:31.295Z,1744394611.295 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-04-11T18:03:31.295Z,1744394611.295 [keepstation:TransitToStation:B.Pitch] Stopped 2025-04-11T18:03:31.295Z,1744394611.295 [keepstation:TransitToStation:C.SetSpeed] Stopped 2025-04-11T18:03:31.295Z,1744394611.295 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize. 2025-04-11T18:03:31.295Z,1744394611.295 [keepstation:OnStation] Running Loop=1 2025-04-11T18:03:31.295Z,1744394611.295 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2025-04-11T18:03:31.698Z,1744394611.698 [keepstation:NeedComms] Running Loop=1 2025-04-11T18:03:31.698Z,1744394611.698 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-04-11T18:03:31.698Z,1744394611.698 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-04-11T18:03:31.698Z,1744394611.698 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-11T18:03:31.698Z,1744394611.698 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-11T18:03:31.699Z,1744394611.699 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-11T18:03:31.699Z,1744394611.699 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-11T18:03:31.699Z,1744394611.699 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-11T18:03:31.700Z,1744394611.700 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-11T18:03:31.700Z,1744394611.700 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-11T18:03:31.700Z,1744394611.700 [keepstation:NeedComms:A] Running Loop=1 2025-04-11T18:03:31.702Z,1744394611.702 [keepstation:NeedComms:A](INFO): last time_fix was: 1744393828.000000 second since 1970/01/01T00:00:00Z 2025-04-11T18:03:31.702Z,1744394611.702 [keepstation:NeedComms:A] Stopped 2025-04-11T18:04:17.894Z,1744394657.894 [keepstation:NeedComms:C] Running Loop=1 2025-04-11T18:04:18.597Z,1744394658.597 [Radio_Surface](INFO): Powering up 2025-04-11T18:04:23.977Z,1744394663.977 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-11T18:04:48.170Z,1744394688.170 [NAL9602](INFO): SBD MO Status=2, MOMSN=19432, MT Status=2, MTMSN=0 2025-04-11T18:04:48.170Z,1744394688.170 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-11T18:04:54.101Z,1744394694.101 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:05:25.225Z,1744394725.225 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:05:37.422Z,1744394737.422 [DVL_micro](ERROR): Failed to parse: :WI,+00066,-00233,-00261,+00000,A 2025-04-11T18:05:45.917Z,1744394745.917 [NAL9602](INFO): SBD MO Status=0, MOMSN=19432, MT Status=0, MTMSN=0 2025-04-11T18:05:45.918Z,1744394745.918 [NAL9602](INFO): No messages in MT queue 2025-04-11T18:05:47.128Z,1744394747.128 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180546.00,A,3647.79167,N,12150.83147,W,0.389,251.59,110425,,,A*7F 2025-04-11T18:05:47.131Z,1744394747.131 [NAL9602](INFO): GPS fix at 20250411T180546: (36.796528, -121.847191) 2025-04-11T18:05:47.158Z,1744394747.158 [UniversalFixResidualReporter](INFO): Fix residual: 15.0 %DT, over the last 656.3 m. Residual distance 98.1 m at bearing 40.9 degrees. Fix at (36.7965, -121.8472) with 506.7 m made good. 2025-04-11T18:05:47.165Z,1744394747.165 [keepstation:NeedComms:C] Stopped 2025-04-11T18:05:47.165Z,1744394747.165 [keepstation:NeedComms:D] Running Loop=1 2025-04-11T18:05:56.349Z,1744394756.349 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:06:02.696Z,1744394762.696 [NAL9602](INFO): SBD MO Status=1, MOMSN=19433, MT Status=0, MTMSN=0 2025-04-11T18:06:02.750Z,1744394762.750 [NAL9602](INFO): Sent 266 bytes from file Logs/20250411T173315/Courier0018.lzma 2025-04-11T18:06:02.750Z,1744394762.750 [NAL9602](INFO): Packets left to send: 0 2025-04-11T18:06:10.171Z,1744394770.171 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,34,0000.0,1489.0,000 2025-04-11T18:06:10.171Z,1744394770.171 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,34,0000.0,1489.0,000 2025-04-11T18:06:19.462Z,1744394779.462 [NAL9602](INFO): SBD MO Status=1, MOMSN=19434, MT Status=0, MTMSN=0 2025-04-11T18:06:19.513Z,1744394779.513 [NAL9602](INFO): Sent 332 bytes from file Logs/20250411T173315/Express0019.lzma 2025-04-11T18:06:19.513Z,1744394779.513 [NAL9602](INFO): Packets left to send: 1 2025-04-11T18:06:27.474Z,1744394787.474 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:06:29.350Z,1744394789.350 [NAL9602](INFO): SBD MO Status=1, MOMSN=19435, MT Status=0, MTMSN=0 2025-04-11T18:06:29.397Z,1744394789.397 [NAL9602](INFO): Sent 115 bytes from file Logs/20250411T173315/Express0019.lzma 2025-04-11T18:06:29.397Z,1744394789.397 [NAL9602](INFO): Packets left to send: 0 2025-04-11T18:06:36.698Z,1744394796.698 [NAL9602](INFO): SBD MO Status=0, MOMSN=19436, MT Status=0, MTMSN=0 2025-04-11T18:06:36.787Z,1744394796.787 [keepstation:NeedComms:D] Stopped 2025-04-11T18:06:36.787Z,1744394796.787 [keepstation:NeedComms:E] Running Loop=1 2025-04-11T18:06:39.064Z,1744394799.064 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180638.00,A,3647.78804,N,12150.83745,W,0.194,250.39,110425,,,A*75 2025-04-11T18:06:39.066Z,1744394799.066 [NAL9602](INFO): GPS fix at 20250411T180638: (36.796467, -121.847291) 2025-04-11T18:06:39.078Z,1744394799.078 [keepstation:NeedComms:E] Stopped 2025-04-11T18:06:39.079Z,1744394799.079 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-04-11T18:06:39.079Z,1744394799.079 [keepstation:NeedComms] Stopped 2025-04-11T18:06:39.079Z,1744394799.079 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-04-11T18:06:39.079Z,1744394799.079 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-04-11T18:06:39.079Z,1744394799.079 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-11T18:06:39.257Z,1744394799.257 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003447 2025-04-11T18:06:39.501Z,1744394799.501 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2025-04-11T18:06:39.502Z,1744394799.502 [keepstation:OnStation] Stopped 2025-04-11T18:06:39.502Z,1744394799.502 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2025-04-11T18:06:39.502Z,1744394799.502 [keepstation:KeepStation] Running Loop=1 2025-04-11T18:06:39.502Z,1744394799.502 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2025-04-11T18:06:39.502Z,1744394799.502 [keepstation:KeepStation:A] Running Loop=1 2025-04-11T18:06:39.502Z,1744394799.502 [keepstation:KeepStation:B.Pitch] Running Loop=1 2025-04-11T18:06:39.502Z,1744394799.502 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize. 2025-04-11T18:06:39.502Z,1744394799.502 [keepstation:KeepStation:C.KeepStation] Running Loop=1 2025-04-11T18:06:39.502Z,1744394799.502 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-04-11T18:06:39.503Z,1744394799.503 [keepstation:KeepStation:D.Wait] Running Loop=1 2025-04-11T18:06:39.503Z,1744394799.503 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component. 2025-04-11T18:06:39.903Z,1744394799.903 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 11.90 mm. 2025-04-11T18:06:39.909Z,1744394799.909 [keepstation:KeepStation:C.KeepStation] Running Loop=1 2025-04-11T18:06:39.909Z,1744394799.909 [keepstation:KeepStation:B.Pitch] Running Loop=1 2025-04-11T18:06:39.910Z,1744394799.910 [keepstation:KeepStation:A] Running Loop=1 2025-04-11T18:07:11.403Z,1744394831.403 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-11T18:09:31.101Z,1744394971.101 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:09:57.645Z,1744394997.645 [Radio_Surface](INFO): Powering down 2025-04-11T18:10:02.225Z,1744395002.225 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:10:03.230Z,1744395003.230 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-11T18:11:08.489Z,1744395068.489 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.695007 2025-04-11T18:18:08.016Z,1744395488.016 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TSI,+00026,+00068,+00106,+00000,A 2025-04-11T18:18:08.016Z,1744395488.016 [DVL_micro](ERROR): Failed to parse: :TSI,+00026,+00068,+00106,+00000,A 2025-04-11T18:21:28.021Z,1744395688.021 [DVL_micro](ERROR): Failed to parse: :RD,+0000.32,+9999.99,+0000.34,+0001.95 2025-04-11T18:21:29.226Z,1744395689.226 [DVL_micro](ERROR): Failed to parse: :BI,+00998,+00000,+00000,+00000,I 2025-04-11T18:25:19.099Z,1744395919.099 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+100,1489.0,000 2025-04-11T18:25:19.099Z,1744395919.099 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+100,1489.0,000 2025-04-11T18:29:37.663Z,1744396177.663 [DVL_micro](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BI,-00064,-00397,-00031,+0000 2025-04-11T18:29:37.663Z,1744396177.663 [DVL_micro](ERROR): Failed to parse: :BI,-00064,-00397,-00031,+0000 2025-04-11T18:31:57.074Z,1744396317.074 [keepstation:NeedComms] Running Loop=1 2025-04-11T18:31:57.074Z,1744396317.074 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-04-11T18:31:57.074Z,1744396317.074 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-04-11T18:31:57.074Z,1744396317.074 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-11T18:31:57.074Z,1744396317.074 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-04-11T18:31:57.075Z,1744396317.075 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-04-11T18:31:57.075Z,1744396317.075 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-04-11T18:31:57.075Z,1744396317.075 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-04-11T18:31:57.076Z,1744396317.076 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-11T18:31:57.076Z,1744396317.076 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-11T18:31:57.077Z,1744396317.077 [keepstation:NeedComms:A] Running Loop=1 2025-04-11T18:31:57.081Z,1744396317.081 [keepstation:NeedComms:A](INFO): last time_fix was: 1744394798.000000 second since 1970/01/01T00:00:00Z 2025-04-11T18:31:57.081Z,1744396317.081 [keepstation:NeedComms:A] Stopped 2025-04-11T18:33:13.798Z,1744396393.798 [keepstation:NeedComms:C] Running Loop=1 2025-04-11T18:33:14.337Z,1744396394.337 [Radio_Surface](INFO): Powering up 2025-04-11T18:33:20.025Z,1744396400.025 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-11T18:33:36.421Z,1744396416.421 [NAL9602](INFO): SBD MO Status=2, MOMSN=19437, MT Status=2, MTMSN=0 2025-04-11T18:33:36.422Z,1744396416.422 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-11T18:33:46.094Z,1744396426.094 [NAL9602](INFO): SBD MO Status=0, MOMSN=19437, MT Status=0, MTMSN=0 2025-04-11T18:33:46.094Z,1744396426.094 [NAL9602](INFO): No messages in MT queue 2025-04-11T18:33:47.306Z,1744396427.306 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183346.00,A,3647.82565,N,12150.88679,W,0.486,308.19,110425,,,A*78 2025-04-11T18:33:47.309Z,1744396427.309 [NAL9602](INFO): GPS fix at 20250411T183346: (36.797094, -121.848113) 2025-04-11T18:33:47.342Z,1744396427.342 [keepstation:NeedComms:C] Stopped 2025-04-11T18:33:47.342Z,1744396427.342 [keepstation:NeedComms:D] Running Loop=1 2025-04-11T18:33:50.145Z,1744396430.145 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:34:07.347Z,1744396447.347 [NAL9602](INFO): SBD MO Status=1, MOMSN=19438, MT Status=0, MTMSN=0 2025-04-11T18:34:07.402Z,1744396447.402 [NAL9602](INFO): Sent 75 bytes from file Logs/20250411T173315/Courier0021.lzma 2025-04-11T18:34:07.402Z,1744396447.402 [NAL9602](INFO): Packets left to send: 0 2025-04-11T18:34:21.281Z,1744396461.281 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:34:25.399Z,1744396465.399 [NAL9602](INFO): SBD MO Status=2, MOMSN=19439, MT Status=2, MTMSN=0 2025-04-11T18:34:25.399Z,1744396465.399 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-11T18:34:51.516Z,1744396491.516 [NAL9602](INFO): SBD MO Status=2, MOMSN=19439, MT Status=2, MTMSN=0 2025-04-11T18:34:51.517Z,1744396491.517 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-11T18:34:52.409Z,1744396492.409 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:35:15.226Z,1744396515.226 [NAL9602](INFO): SBD MO Status=2, MOMSN=19439, MT Status=2, MTMSN=0 2025-04-11T18:35:15.226Z,1744396515.226 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-11T18:35:23.542Z,1744396523.542 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:35:32.557Z,1744396532.557 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003401 2025-04-11T18:35:40.589Z,1744396540.589 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20250411T173315/Express0022.lzma 2025-04-11T18:35:41.590Z,1744396541.590 [DataOverHttps](INFO): Moved sent file to Logs/20250411T173315/Express0022.lzma.bak 2025-04-11T18:35:41.591Z,1744396541.591 [DataOverHttps](INFO): SBD MOMSN=24617378 2025-04-11T18:35:42.144Z,1744396542.144 [NAL9602](INFO): SBD MO Status=2, MOMSN=19439, MT Status=2, MTMSN=0 2025-04-11T18:35:42.144Z,1744396542.144 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-11T18:35:42.951Z,1744396542.951 [keepstation:NeedComms:D] Stopped 2025-04-11T18:35:42.952Z,1744396542.952 [keepstation:NeedComms:E] Running Loop=1 2025-04-11T18:35:44.956Z,1744396544.956 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183544.00,A,3647.83009,N,12150.90294,W,0.194,277.34,110425,,,A*7C 2025-04-11T18:35:44.966Z,1744396544.966 [NAL9602](INFO): GPS fix at 20250411T183544: (36.797168, -121.848382) 2025-04-11T18:35:44.993Z,1744396544.993 [keepstation:NeedComms:E] Stopped 2025-04-11T18:35:44.994Z,1744396544.994 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-04-11T18:35:44.994Z,1744396544.994 [keepstation:NeedComms] Stopped 2025-04-11T18:35:44.994Z,1744396544.994 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-04-11T18:35:44.994Z,1744396544.994 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-04-11T18:35:44.994Z,1744396544.994 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-11T18:36:17.296Z,1744396577.296 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-11T18:37:43.797Z,1744396663.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:38:05.693Z,1744396685.693 [Radio_Surface](INFO): Powering down 2025-04-11T18:38:14.925Z,1744396694.925 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:38:15.929Z,1744396695.929 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-11T18:38:44.841Z,1744396724.841 [DVL_micro](ERROR): DVL uart error: serial timeout 2025-04-11T18:38:44.841Z,1744396724.841 [DVL_micro] Communications Fault, FailCount= 1 2025-04-11T18:38:44.841Z,1744396724.841 [DVL_micro](ERROR): Communications Fault 2025-04-11T18:38:44.841Z,1744396724.841 [DVL_micro](ERROR): Failed to parse: 2025-04-11T18:38:45.033Z,1744396725.033 [CBIT](ERROR): Communications Fault in component: DVL_micro 2025-04-11T18:38:45.249Z,1744396725.249 [DVL_micro](INFO): Powering down 2025-04-11T18:38:46.141Z,1744396726.141 [CBIT](INFO): Clearing failed state for component DVL_micro 2025-04-11T18:38:46.141Z,1744396726.141 [DVL_micro] No Fault, FailCount= 1 2025-04-11T18:39:52.337Z,1744396792.337 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.747238 2025-04-11T18:49:15.423Z,1744397355.423 [keepstation](INFO): Timed out from 2025-04-11T17:49:15.1Z 2025-04-11T18:49:15.423Z,1744397355.423 [MissionManager](INFO): keepstation is completed. 2025-04-11T18:49:15.423Z,1744397355.423 [MissionManager](INFO): Uninitializing Mission keepstation 2025-04-11T18:49:15.423Z,1744397355.423 [keepstation] Stopped 2025-04-11T18:49:15.423Z,1744397355.423 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2025-04-11T18:49:15.424Z,1744397355.424 [keepstation:StandardEnvelopes] Stopped 2025-04-11T18:49:15.424Z,1744397355.424 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2025-04-11T18:49:15.424Z,1744397355.424 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-04-11T18:49:15.424Z,1744397355.424 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-04-11T18:49:15.424Z,1744397355.424 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-04-11T18:49:15.424Z,1744397355.424 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-04-11T18:49:15.424Z,1744397355.424 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-04-11T18:49:15.424Z,1744397355.424 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-04-11T18:49:15.424Z,1744397355.424 [keepstation:BackseatDriver] Stopped 2025-04-11T18:49:15.424Z,1744397355.424 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver 2025-04-11T18:49:15.425Z,1744397355.425 [keepstation:BackseatDriver:A.BackseatDriver] Stopped 2025-04-11T18:49:15.425Z,1744397355.425 [keepstation:PowerOnly] Stopped 2025-04-11T18:49:15.425Z,1744397355.425 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly 2025-04-11T18:49:15.425Z,1744397355.425 [keepstation:PowerOnly:E.Wait] Stopped 2025-04-11T18:49:15.425Z,1744397355.425 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-04-11T18:49:15.425Z,1744397355.425 [keepstation:H.Pitch] Stopped 2025-04-11T18:49:15.425Z,1744397355.425 [keepstation:KeepStation] Stopped 2025-04-11T18:49:15.425Z,1744397355.425 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation 2025-04-11T18:49:15.425Z,1744397355.425 [keepstation:KeepStation:A] Stopped 2025-04-11T18:49:15.425Z,1744397355.425 [keepstation:KeepStation:B.Pitch] Stopped 2025-04-11T18:49:15.425Z,1744397355.425 [keepstation:KeepStation:C.KeepStation] Stopped 2025-04-11T18:49:15.425Z,1744397355.425 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-04-11T18:49:15.425Z,1744397355.425 [keepstation:KeepStation:D.Wait] Stopped 2025-04-11T18:49:15.426Z,1744397355.425 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component. 2025-04-11T18:49:15.811Z,1744397355.811 [MissionManager](IMPORTANT): Started mission Default 2025-04-11T18:49:15.811Z,1744397355.811 [Default] Running Loop=1 2025-04-11T18:49:15.811Z,1744397355.811 [Default](DEBUG): Aggregate::initialize Default 2025-04-11T18:49:15.811Z,1744397355.811 [Default:B.GoToSurface] Running Loop=1 2025-04-11T18:49:15.811Z,1744397355.811 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-11T18:49:15.812Z,1744397355.812 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-11T18:49:15.812Z,1744397355.812 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-11T18:49:15.812Z,1744397355.812 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-11T18:49:15.829Z,1744397355.829 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-11T18:49:15.829Z,1744397355.829 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-11T18:49:15.829Z,1744397355.829 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-11T18:49:15.830Z,1744397355.830 [Default:A.Wait] Running Loop=1 2025-04-11T18:49:15.830Z,1744397355.830 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-04-11T18:49:16.381Z,1744397356.381 [Radio_Surface](INFO): Powering up 2025-04-11T18:49:21.669Z,1744397361.669 [DataOverHttps](INFO): Radio surface powered ON. 2025-04-11T18:49:29.142Z,1744397369.142 [Default:A.Wait](INFO): Done Waiting. 2025-04-11T18:49:29.142Z,1744397369.142 [Default:A.Wait] Stopped 2025-04-11T18:49:29.142Z,1744397369.142 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-04-11T18:49:51.790Z,1744397391.790 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:50:19.209Z,1744397419.209 [Default:CheckIn] Running Loop=1 2025-04-11T18:50:19.209Z,1744397419.209 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-11T18:50:19.209Z,1744397419.209 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-11T18:50:22.917Z,1744397422.917 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:50:29.692Z,1744397429.692 [DVL_micro](ERROR): Failed to parse: :BD,+00000000.00,+00000000.00,+00000000.00,0000.20,000.00 2025-04-11T18:50:36.964Z,1744397436.964 [NAL9602](INFO): SBD MO Status=1, MOMSN=19439, MT Status=0, MTMSN=0 2025-04-11T18:50:36.964Z,1744397436.964 [NAL9602](INFO): No messages in MT queue 2025-04-11T18:50:38.178Z,1744397438.178 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185037.00,A,3647.84359,N,12150.92183,W,0.836,341.39,110425,,,A*75 2025-04-11T18:50:38.181Z,1744397438.181 [NAL9602](INFO): GPS fix at 20250411T185037: (36.797393, -121.848697) 2025-04-11T18:50:38.191Z,1744397438.191 [Default:CheckIn:Read_GPS] Stopped 2025-04-11T18:50:38.191Z,1744397438.191 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-11T18:50:54.045Z,1744397454.045 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:51:13.988Z,1744397473.988 [NAL9602](INFO): SBD MO Status=2, MOMSN=19440, MT Status=2, MTMSN=0 2025-04-11T18:51:13.988Z,1744397473.988 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-11T18:51:25.169Z,1744397485.169 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-11T18:51:34.806Z,1744397494.806 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003525 2025-04-11T18:51:39.703Z,1744397499.703 [NAL9602](INFO): SBD MO Status=2, MOMSN=19440, MT Status=2, MTMSN=0 2025-04-11T18:51:39.703Z,1744397499.703 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-11T18:51:42.932Z,1744397502.932 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250411T173315/Courier0024.lzma 2025-04-11T18:51:43.935Z,1744397503.935 [DataOverHttps](INFO): Moved sent file to Logs/20250411T173315/Courier0024.lzma.bak 2025-04-11T18:51:43.935Z,1744397503.935 [DataOverHttps](INFO): SBD MOMSN=24617488 2025-04-11T18:52:01.241Z,1744397521.241 [DataOverHttps](INFO): Sending 257 bytes from file Logs/20250411T173315/Express0025.lzma 2025-04-11T18:52:02.242Z,1744397522.242 [DataOverHttps](INFO): Moved sent file to Logs/20250411T173315/Express0025.lzma.bak 2025-04-11T18:52:02.243Z,1744397522.243 [DataOverHttps](INFO): SBD MOMSN=24617491 2025-04-11T18:52:03.544Z,1744397523.544 [Default:CheckIn:Read_Iridium] Stopped 2025-04-11T18:52:03.545Z,1744397523.545 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-11T18:52:03.545Z,1744397523.545 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-11T18:52:10.407Z,1744397530.407 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-11T18:55:30.390Z,1744397730.390 [DVL_micro](ERROR): Failed to parse: :BI,+00000,+00000,+00000,+00000,I 2025-04-11T18:55:31.599Z,1744397731.599 [DVL_micro](ERROR): Failed to parse: :SA,-03.59,-08.01,243.8 2025-04-11T18:56:12.674Z,1744397772.674 [DataOverHttps](IMPORTANT): SBD MTMSN=20250411T185611 2025-04-11T18:56:20.495Z,1744397780.495 [DataOverHttps](INFO): Received command: restart logs