2025-02-21T19:40:51.740Z,1740166851.740 [CommandExec](IMPORTANT): got command restart logs 2025-02-21T19:42:32.247Z,1740166952.247 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-21T19:42:32.247Z,1740166952.247 [Default:CheckIn:C.Wait] Stopped 2025-02-21T19:42:32.247Z,1740166952.247 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-21T19:42:32.247Z,1740166952.247 [Default:CheckIn:D] Running Loop=1 2025-02-21T19:42:32.657Z,1740166952.657 [Default:CheckIn:D] Stopped 2025-02-21T19:42:32.657Z,1740166952.657 [Default:CheckIn:E] Running Loop=1 2025-02-21T19:42:33.066Z,1740166953.066 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.304233 min 2025-02-21T19:42:33.066Z,1740166953.066 [Default:CheckIn:E] Stopped 2025-02-21T19:42:33.066Z,1740166953.066 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-21T19:42:33.066Z,1740166953.066 [Default:CheckIn] Stopped 2025-02-21T19:42:33.067Z,1740166953.067 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-21T19:42:33.067Z,1740166953.067 [Default:CheckIn](INFO): Running loop #2 2025-02-21T19:42:33.075Z,1740166953.075 [Default:CheckIn] Running Loop=2 2025-02-21T19:42:33.075Z,1740166953.075 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-21T19:42:33.075Z,1740166953.075 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-21T19:42:35.058Z,1740166955.058 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194234.00,A,3647.81104,N,12150.70298,W,0.875,89.42,210225,,,D*42 2025-02-21T19:42:35.060Z,1740166955.060 [NAL9602](INFO): GPS fix at 20250221T194234: (36.796851, -121.845050) 2025-02-21T19:42:35.114Z,1740166955.114 [Default:CheckIn:Read_GPS] Stopped 2025-02-21T19:42:35.114Z,1740166955.114 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-21T19:42:43.603Z,1740166963.603 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250221T163145/Courier0045.lzma 2025-02-21T19:42:44.281Z,1740166964.281 [DataOverHttps](INFO): Moved sent file to Logs/20250221T163145/Courier0045.lzma.bak 2025-02-21T19:42:44.281Z,1740166964.281 [DataOverHttps](INFO): SBD MOMSN=23971193 2025-02-21T19:42:56.958Z,1740166976.958 [NAL9602](INFO): SBD MO Status=0, MOMSN=13056, MT Status=0, MTMSN=0 2025-02-21T19:42:56.958Z,1740166976.958 [NAL9602](INFO): No messages in MT queue 2025-02-21T19:43:00.551Z,1740166980.551 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250221T194051/Courier0000.lzma 2025-02-21T19:43:01.560Z,1740166981.560 [DataOverHttps](INFO): Moved sent file to Logs/20250221T194051/Courier0000.lzma.bak 2025-02-21T19:43:01.560Z,1740166981.560 [DataOverHttps](INFO): SBD MOMSN=23971195 2025-02-21T19:43:21.331Z,1740167001.331 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20250221T163145/Express0046.lzma 2025-02-21T19:43:22.333Z,1740167002.333 [DataOverHttps](INFO): Moved sent file to Logs/20250221T163145/Express0046.lzma.bak 2025-02-21T19:43:22.334Z,1740167002.334 [DataOverHttps](INFO): SBD MOMSN=23971200 2025-02-21T19:43:27.720Z,1740167007.720 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-21T19:43:38.781Z,1740167018.781 [DataOverHttps](INFO): Sending 381 bytes from file Logs/20250221T194051/Express0001.lzma 2025-02-21T19:43:39.782Z,1740167019.782 [DataOverHttps](INFO): Moved sent file to Logs/20250221T194051/Express0001.lzma.bak 2025-02-21T19:43:39.782Z,1740167019.782 [DataOverHttps](INFO): SBD MOMSN=23971203 2025-02-21T19:43:56.655Z,1740167036.655 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20250221T194051/Express0004.lzma 2025-02-21T19:43:57.657Z,1740167037.657 [DataOverHttps](INFO): Moved sent file to Logs/20250221T194051/Express0004.lzma.bak 2025-02-21T19:43:57.658Z,1740167037.658 [DataOverHttps](INFO): SBD MOMSN=23971207 2025-02-21T19:43:59.245Z,1740167039.245 [Default:CheckIn:Read_Iridium] Stopped 2025-02-21T19:43:59.245Z,1740167039.245 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-21T19:43:59.245Z,1740167039.245 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-21T19:45:38.785Z,1740167138.785 [DataOverHttps](IMPORTANT): SBD MTMSN=20250221T194537 2025-02-21T19:45:46.647Z,1740167146.647 [DataOverHttps](INFO): Received command: sched asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 30 min;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.DepthDeadband 1 m" 23ljb 1 2 2025-02-21T19:45:46.653Z,1740167146.653 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 30 min;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.DepthDeadband 1 m" 23ljb 1 2.000000 2025-02-21T19:45:46.653Z,1740167146.653 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=23ljb 2025-02-21T19:45:46.654Z,1740167146.654 [CommandExec](IMPORTANT): Scheduled #41 (#1 of 2 with id='23ljb'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 30 min;set keepstation.NeedCommsTime 60 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.DepthDeadband 1 m", ASAP 2025-02-21T19:45:47.649Z,1740167147.649 [DataOverHttps](IMPORTANT): SBD MTMSN=20250221T194546 2025-02-21T19:45:55.603Z,1740167155.603 [DataOverHttps](INFO): Received command: sched asap "set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 23ljb 2 2 2025-02-21T19:45:55.634Z,1740167155.634 [CommandExec](IMPORTANT): got command schedule asap "set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 23ljb 2 2.000000 2025-02-21T19:45:55.635Z,1740167155.635 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=23ljb 2025-02-21T19:45:55.636Z,1740167155.636 [CommandExec](IMPORTANT): Scheduled #42 (#2 of 2 with id='23ljb'): "set keepstation:BackseatDriver.EnableBackseat 1 bool;run", ASAP 2025-02-21T19:45:55.756Z,1740167155.756 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl 2025-02-21T19:45:55.757Z,1740167155.757 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl 2025-02-21T19:45:55.757Z,1740167155.757 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl 2025-02-21T19:45:55.758Z,1740167155.758 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl 2025-02-21T19:45:56.532Z,1740167156.532 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into: 4 45 NaN NaN 30 15 4 0.75 200 45 2 2025-02-21T19:45:56.537Z,1740167156.537 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2025-02-21T19:45:56.541Z,1740167156.541 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2025-02-21T19:45:56.561Z,1740167156.561 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2025-02-21T19:45:56.564Z,1740167156.564 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2025-02-21T19:45:56.566Z,1740167156.566 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2025-02-21T19:45:56.569Z,1740167156.569 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2025-02-21T19:45:56.571Z,1740167156.571 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2025-02-21T19:45:56.573Z,1740167156.573 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2025-02-21T19:45:56.576Z,1740167156.576 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2025-02-21T19:45:56.578Z,1740167156.578 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2025-02-21T19:45:56.580Z,1740167156.580 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2025-02-21T19:45:56.581Z,1740167156.581 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-02-21T19:45:56.581Z,1740167156.581 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-02-21T19:45:56.582Z,1740167156.582 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-02-21T19:45:56.856Z,1740167156.856 [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-02-21T19:45:56.873Z,1740167156.873 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2025-02-21T19:45:56.876Z,1740167156.876 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2025-02-21T19:45:56.878Z,1740167156.878 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-02-21T19:45:56.889Z,1740167156.889 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2025-02-21T19:45:56.894Z,1740167156.894 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-02-21T19:45:56.897Z,1740167156.897 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s 2025-02-21T19:45:56.900Z,1740167156.900 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2025-02-21T19:45:56.902Z,1740167156.902 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2025-02-21T19:45:56.910Z,1740167156.910 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-02-21T19:45:56.970Z,1740167156.970 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-02-21T19:45:56.970Z,1740167156.970 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-02-21T19:45:56.975Z,1740167156.975 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-02-21T19:45:57.076Z,1740167157.076 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2025-02-21T19:45:57.080Z,1740167157.080 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2025-02-21T19:45:57.082Z,1740167157.082 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2025-02-21T19:45:57.085Z,1740167157.085 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2025-02-21T19:45:57.088Z,1740167157.088 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-02-21T19:45:57.089Z,1740167157.089 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-02-21T19:45:57.095Z,1740167157.095 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-02-21T19:45:57.102Z,1740167157.102 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-02-21T19:45:57.109Z,1740167157.109 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-02-21T19:45:57.109Z,1740167157.109 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-02-21T19:45:57.110Z,1740167157.110 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-02-21T19:45:57.153Z,1740167157.153 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-02-21T19:45:57.165Z,1740167157.165 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2025-02-21T19:45:57.171Z,1740167157.171 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-02-21T19:45:57.173Z,1740167157.173 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-02-21T19:45:57.174Z,1740167157.174 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-02-21T19:45:57.175Z,1740167157.175 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-02-21T19:45:57.356Z,1740167157.356 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-02-21T19:45:57.360Z,1740167157.360 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2025-02-21T19:45:57.362Z,1740167157.362 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2025-02-21T19:45:57.364Z,1740167157.364 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2025-02-21T19:45:57.366Z,1740167157.366 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2025-02-21T19:45:57.368Z,1740167157.368 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool 2025-02-21T19:45:57.375Z,1740167157.375 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-02-21T19:45:57.377Z,1740167157.377 [keepstation:H.Pitch](DEBUG): Construct. 2025-02-21T19:45:57.386Z,1740167157.386 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy. 2025-02-21T19:45:57.414Z,1740167157.414 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct. 2025-02-21T19:45:57.433Z,1740167157.433 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct. 2025-02-21T19:45:57.440Z,1740167157.440 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-02-21T19:45:57.456Z,1740167157.456 [keepstation:KeepStation:B.Pitch](DEBUG): Construct. 2025-02-21T19:45:57.464Z,1740167157.464 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation. 2025-02-21T19:45:57.473Z,1740167157.473 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait. 2025-02-21T19:45:57.486Z,1740167157.486 [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-02-21T19:45:57.486Z,1740167157.486 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2025-02-21T19:46:25.028Z,1740167185.028 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 30 minute 2025-02-21T19:46:25.029Z,1740167185.029 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 60 minute 2025-02-21T19:46:25.029Z,1740167185.029 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.797 degree 2025-02-21T19:46:25.030Z,1740167185.030 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.847 degree 2025-02-21T19:46:25.031Z,1740167185.031 [CommandExec](IMPORTANT): got command set keepstation.DepthDeadband 1 meter 2025-02-21T19:46:35.521Z,1740167195.521 [CommandExec](IMPORTANT): got command set keepstation:BackseatDriver.EnableBackseat 1 bool 2025-02-21T19:46:35.522Z,1740167195.522 [CommandExec](IMPORTANT): got command run 2025-02-21T19:46:35.524Z,1740167195.524 [CommandExec](IMPORTANT): Running 2025-02-21T19:46:35.644Z,1740167195.644 [Default] Stopped 2025-02-21T19:46:35.644Z,1740167195.644 [Default](DEBUG): Aggregate::uninitialize Default 2025-02-21T19:46:35.644Z,1740167195.644 [Default:B.GoToSurface] Stopped 2025-02-21T19:46:35.644Z,1740167195.644 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-21T19:46:35.644Z,1740167195.644 [Default:CheckIn] Stopped 2025-02-21T19:46:35.645Z,1740167195.645 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-21T19:46:35.645Z,1740167195.645 [Default:CheckIn:C.Wait] Stopped 2025-02-21T19:46:35.645Z,1740167195.645 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-21T19:46:35.645Z,1740167195.645 [MissionManager](IMPORTANT): Started mission keepstation 2025-02-21T19:46:35.645Z,1740167195.645 [keepstation] Running Loop=1 2025-02-21T19:46:35.645Z,1740167195.645 [keepstation](DEBUG): Aggregate::initialize keepstation 2025-02-21T19:46:35.645Z,1740167195.645 [keepstation:StandardEnvelopes] Running Loop=1 2025-02-21T19:46:35.645Z,1740167195.645 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2025-02-21T19:46:35.646Z,1740167195.646 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-02-21T19:46:35.646Z,1740167195.646 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-02-21T19:46:35.646Z,1740167195.646 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-02-21T19:46:35.646Z,1740167195.646 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-02-21T19:46:35.646Z,1740167195.646 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-02-21T19:46:35.646Z,1740167195.646 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-02-21T19:46:35.646Z,1740167195.646 [keepstation:BackseatDriver] Running Loop=1 2025-02-21T19:46:35.646Z,1740167195.646 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2025-02-21T19:46:35.646Z,1740167195.646 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-02-21T19:46:35.647Z,1740167195.647 [keepstation:PowerOnly] Running Loop=1 2025-02-21T19:46:35.647Z,1740167195.647 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2025-02-21T19:46:35.647Z,1740167195.647 [keepstation:PowerOnly:A] Running Loop=1 2025-02-21T19:46:35.647Z,1740167195.647 [keepstation:PowerOnly:B] Running Loop=1 2025-02-21T19:46:35.647Z,1740167195.647 [keepstation:PowerOnly:C] Running Loop=1 2025-02-21T19:46:35.647Z,1740167195.647 [keepstation:PowerOnly:D] Running Loop=1 2025-02-21T19:46:35.647Z,1740167195.647 [keepstation:PowerOnly:E.Wait] Running Loop=1 2025-02-21T19:46:35.647Z,1740167195.647 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-02-21T19:46:35.647Z,1740167195.647 [keepstation:H.Pitch] Running Loop=1 2025-02-21T19:46:35.648Z,1740167195.648 [keepstation:H.Pitch](DEBUG): Initialize. 2025-02-21T19:46:35.648Z,1740167195.648 [keepstation:B] Running Loop=1 2025-02-21T19:46:35.648Z,1740167195.648 [keepstation:H.Pitch] Running Loop=1 2025-02-21T19:46:35.649Z,1740167195.649 [keepstation:PowerOnly] Running Loop=1 2025-02-21T19:46:35.649Z,1740167195.649 [keepstation:PowerOnly:D] Stopped 2025-02-21T19:46:35.649Z,1740167195.649 [keepstation:PowerOnly:C] Stopped 2025-02-21T19:46:35.649Z,1740167195.649 [keepstation:PowerOnly:B] Stopped 2025-02-21T19:46:35.649Z,1740167195.649 [keepstation:PowerOnly:A] Stopped 2025-02-21T19:46:35.649Z,1740167195.649 [keepstation:BackseatDriver] Running Loop=1 2025-02-21T19:46:35.649Z,1740167195.649 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat 2025-02-21T19:46:35.706Z,1740167195.706 [keepstation:StandardEnvelopes] Running Loop=1 2025-02-21T19:46:35.706Z,1740167195.706 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-02-21T19:46:35.706Z,1740167195.706 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-02-21T19:46:35.707Z,1740167195.707 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-02-21T19:46:35.711Z,1740167195.711 [keepstation:B] Stopped 2025-02-21T19:46:35.712Z,1740167195.712 [keepstation:D] Running Loop=1 2025-02-21T19:46:35.771Z,1740167195.771 [BackseatComponent](INFO): Powering up 2025-02-21T19:46:35.772Z,1740167195.772 [BackseatComponent](INFO): Subscribing to LCM channels. 2025-02-21T19:46:36.047Z,1740167196.047 [keepstation:D] Stopped 2025-02-21T19:46:36.047Z,1740167196.047 [keepstation:E] Running Loop=1 2025-02-21T19:46:36.451Z,1740167196.451 [keepstation:E] Stopped 2025-02-21T19:46:36.451Z,1740167196.451 [keepstation:StartingMission] Running Loop=1 2025-02-21T19:46:36.452Z,1740167196.452 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2025-02-21T19:46:36.852Z,1740167196.852 [keepstation:NeedComms] Running Loop=1 2025-02-21T19:46:36.852Z,1740167196.852 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-02-21T19:46:36.852Z,1740167196.852 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-02-21T19:46:36.852Z,1740167196.852 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-21T19:46:36.853Z,1740167196.853 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-02-21T19:46:36.853Z,1740167196.853 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-02-21T19:46:36.853Z,1740167196.853 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-02-21T19:46:36.854Z,1740167196.854 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-02-21T19:46:36.854Z,1740167196.854 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-21T19:46:36.854Z,1740167196.854 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-21T19:46:36.855Z,1740167196.855 [keepstation:NeedComms:A] Running Loop=1 2025-02-21T19:46:36.872Z,1740167196.872 [keepstation:NeedComms:A](INFO): last time_fix was: 1740166954.000000 second since 1970/01/01T00:00:00Z 2025-02-21T19:46:36.873Z,1740167196.873 [keepstation:NeedComms:A] Stopped 2025-02-21T19:46:37.264Z,1740167197.264 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2025-02-21T19:46:37.265Z,1740167197.265 [keepstation:NeedComms:C] Running Loop=1 2025-02-21T19:46:37.668Z,1740167197.668 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-02-21T19:46:39.264Z,1740167199.264 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194638.00,A,3647.80774,N,12150.69283,W,0.544,109.86,210225,,,D*76 2025-02-21T19:46:39.267Z,1740167199.267 [NAL9602](INFO): GPS fix at 20250221T194638: (36.796796, -121.844881) 2025-02-21T19:46:39.288Z,1740167199.288 [keepstation:NeedComms:C] Stopped 2025-02-21T19:46:39.288Z,1740167199.288 [keepstation:NeedComms:D] Running Loop=1 2025-02-21T19:46:39.708Z,1740167199.708 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-02-21T19:46:47.101Z,1740167207.101 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250221T194051/Courier0006.lzma 2025-02-21T19:46:48.041Z,1740167208.041 [DataOverHttps](INFO): Moved sent file to Logs/20250221T194051/Courier0006.lzma.bak 2025-02-21T19:46:48.041Z,1740167208.041 [DataOverHttps](INFO): SBD MOMSN=23971212 2025-02-21T19:46:59.874Z,1740167219.874 [NAL9602](INFO): SBD MO Status=0, MOMSN=13057, MT Status=0, MTMSN=0 2025-02-21T19:46:59.874Z,1740167219.874 [NAL9602](INFO): No messages in MT queue 2025-02-21T19:47:05.608Z,1740167225.608 [DataOverHttps](INFO): Sending 579 bytes from file Logs/20250221T194051/Express0007.lzma 2025-02-21T19:47:06.610Z,1740167226.610 [DataOverHttps](INFO): Moved sent file to Logs/20250221T194051/Express0007.lzma.bak 2025-02-21T19:47:06.610Z,1740167226.610 [DataOverHttps](INFO): SBD MOMSN=23971215 2025-02-21T19:47:11.724Z,1740167231.724 [keepstation:NeedComms:D] Stopped 2025-02-21T19:47:11.725Z,1740167231.725 [keepstation:NeedComms:E] Running Loop=1 2025-02-21T19:47:12.105Z,1740167232.105 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-02-21T19:47:13.638Z,1740167233.638 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194712.00,A,3647.80683,N,12150.69119,W,0.486,195.61,210225,,,D*75 2025-02-21T19:47:13.641Z,1740167233.641 [NAL9602](INFO): GPS fix at 20250221T194712: (36.796780, -121.844853) 2025-02-21T19:47:13.660Z,1740167233.660 [keepstation:NeedComms:E] Stopped 2025-02-21T19:47:13.661Z,1740167233.661 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-02-21T19:47:13.661Z,1740167233.661 [keepstation:NeedComms] Stopped 2025-02-21T19:47:13.661Z,1740167233.661 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-21T19:47:13.661Z,1740167233.661 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-21T19:47:13.661Z,1740167233.661 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-21T19:47:14.085Z,1740167234.085 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-02-21T19:47:14.085Z,1740167234.085 [keepstation:StartingMission] Stopped 2025-02-21T19:47:14.085Z,1740167234.085 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-02-21T19:47:14.085Z,1740167234.085 [keepstation:TransitToStation] Running Loop=1 2025-02-21T19:47:14.085Z,1740167234.085 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-02-21T19:47:14.085Z,1740167234.085 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2025-02-21T19:47:14.085Z,1740167234.085 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-02-21T19:47:14.085Z,1740167234.085 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2025-02-21T19:47:14.085Z,1740167234.085 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize. 2025-02-21T19:47:14.086Z,1740167234.086 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2025-02-21T19:47:14.086Z,1740167234.086 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize. 2025-02-21T19:47:14.086Z,1740167234.086 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-02-21T19:47:14.086Z,1740167234.086 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-02-21T19:47:14.087Z,1740167234.087 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2025-02-21T19:47:14.482Z,1740167234.482 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2025-02-21T19:47:14.487Z,1740167234.487 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2025-02-21T19:47:14.487Z,1740167234.487 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2025-02-21T19:47:14.488Z,1740167234.488 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2025-02-21T19:47:43.111Z,1740167263.111 [BackseatComponent](IMPORTANT): IBASE811F-02: Running bioacoustics backseat app. Logging to: 20250221T194747 2025-02-21T19:47:46.511Z,1740167266.511 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-21T19:48:42.039Z,1740167322.039 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.height_above_sea_floor with code: 1975 2025-02-21T19:49:23.184Z,1740167363.184 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-21T19:49:40.551Z,1740167380.551 [Radio_Surface](INFO): Powering down 2025-02-21T19:49:54.307Z,1740167394.307 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-21T19:49:55.311Z,1740167395.311 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-02-21T19:51:31.732Z,1740167491.732 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.755280 2025-02-21T19:52:03.365Z,1740167523.365 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000 2025-02-21T19:52:03.365Z,1740167523.365 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2025-02-21T19:52:03.366Z,1740167523.366 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-02-21T19:52:03.367Z,1740167523.367 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2025-02-21T19:52:03.367Z,1740167523.367 [keepstation:TransitToStation] Stopped 2025-02-21T19:52:03.367Z,1740167523.367 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2025-02-21T19:52:03.367Z,1740167523.367 [keepstation:TransitToStation:A.Buoyancy] Stopped 2025-02-21T19:52:03.367Z,1740167523.367 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-02-21T19:52:03.368Z,1740167523.368 [keepstation:TransitToStation:B.Pitch] Stopped 2025-02-21T19:52:03.368Z,1740167523.368 [keepstation:TransitToStation:C.SetSpeed] Stopped 2025-02-21T19:52:03.368Z,1740167523.368 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize. 2025-02-21T19:52:03.368Z,1740167523.368 [keepstation:OnStation] Running Loop=1 2025-02-21T19:52:03.368Z,1740167523.368 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2025-02-21T19:52:03.780Z,1740167523.780 [keepstation:NeedComms] Running Loop=1 2025-02-21T19:52:03.780Z,1740167523.780 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-02-21T19:52:03.780Z,1740167523.780 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-02-21T19:52:03.780Z,1740167523.780 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-21T19:52:03.781Z,1740167523.781 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-02-21T19:52:03.781Z,1740167523.781 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-02-21T19:52:03.781Z,1740167523.781 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-02-21T19:52:03.782Z,1740167523.782 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-02-21T19:52:03.782Z,1740167523.782 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-21T19:52:03.782Z,1740167523.782 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-21T19:52:03.783Z,1740167523.783 [keepstation:NeedComms:A] Running Loop=1 2025-02-21T19:52:03.784Z,1740167523.784 [keepstation:NeedComms:A](INFO): last time_fix was: 1740167232.000000 second since 1970/01/01T00:00:00Z 2025-02-21T19:52:03.784Z,1740167523.784 [keepstation:NeedComms:A] Stopped 2025-02-21T19:52:44.089Z,1740167564.089 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.simrad_data_logging_rate with code: 1976 2025-02-21T19:52:54.253Z,1740167574.253 [keepstation:NeedComms:C] Running Loop=1 2025-02-21T19:52:54.947Z,1740167574.947 [Radio_Surface](INFO): Powering up 2025-02-21T19:53:01.136Z,1740167581.136 [DataOverHttps](INFO): Radio surface powered ON. 2025-02-21T19:53:21.298Z,1740167601.298 [NAL9602](INFO): SBD MO Status=2, MOMSN=13058, MT Status=2, MTMSN=0 2025-02-21T19:53:21.298Z,1740167601.298 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-21T19:53:31.257Z,1740167611.257 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-21T19:53:33.826Z,1740167613.826 [NAL9602](INFO): SBD MO Status=0, MOMSN=13058, MT Status=0, MTMSN=0 2025-02-21T19:53:33.826Z,1740167613.826 [NAL9602](INFO): No messages in MT queue 2025-02-21T19:53:35.034Z,1740167615.034 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195334.00,A,3647.81800,N,12150.83084,W,0.447,311.25,210225,,,D*72 2025-02-21T19:53:35.036Z,1740167615.036 [NAL9602](INFO): GPS fix at 20250221T195334: (36.796967, -121.847181) 2025-02-21T19:53:35.056Z,1740167615.056 [keepstation:NeedComms:C] Stopped 2025-02-21T19:53:35.056Z,1740167615.056 [keepstation:NeedComms:D] Running Loop=1 2025-02-21T19:54:02.392Z,1740167642.392 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-21T19:54:06.074Z,1740167646.074 [NAL9602](INFO): SBD MO Status=1, MOMSN=13059, MT Status=0, MTMSN=0 2025-02-21T19:54:06.124Z,1740167646.124 [NAL9602](INFO): Sent 73 bytes from file Logs/20250221T194051/Courier0012.lzma 2025-02-21T19:54:06.125Z,1740167646.125 [NAL9602](INFO): Packets left to send: 0 2025-02-21T19:54:28.386Z,1740167668.386 [NAL9602](INFO): SBD MO Status=1, MOMSN=13060, MT Status=0, MTMSN=0 2025-02-21T19:54:28.435Z,1740167668.435 [NAL9602](INFO): Sent 330 bytes from file Logs/20250221T194051/Express0013.lzma 2025-02-21T19:54:28.436Z,1740167668.436 [NAL9602](INFO): Packets left to send: 0 2025-02-21T19:54:33.516Z,1740167673.516 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-21T19:54:48.741Z,1740167688.741 [NAL9602](INFO): SBD MO Status=2, MOMSN=13061, MT Status=2, MTMSN=0 2025-02-21T19:54:48.741Z,1740167688.741 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-21T19:55:04.660Z,1740167704.660 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-21T19:55:09.710Z,1740167709.710 [NAL9602](INFO): SBD MO Status=0, MOMSN=13061, MT Status=0, MTMSN=0 2025-02-21T19:55:09.963Z,1740167709.963 [keepstation:NeedComms:D] Stopped 2025-02-21T19:55:11.344Z,1740167711.344 [keepstation:NeedComms:E] Running Loop=1 2025-02-21T19:55:14.556Z,1740167714.556 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195513.00,A,3647.81926,N,12150.83124,W,0.194,100.48,210225,,,D*7D 2025-02-21T19:55:14.558Z,1740167714.558 [NAL9602](INFO): GPS fix at 20250221T195513: (36.796988, -121.847187) 2025-02-21T19:55:14.608Z,1740167714.608 [keepstation:NeedComms:E] Stopped 2025-02-21T19:55:14.609Z,1740167714.609 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-02-21T19:55:14.609Z,1740167714.609 [keepstation:NeedComms] Stopped 2025-02-21T19:55:14.609Z,1740167714.609 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-21T19:55:14.609Z,1740167714.609 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-21T19:55:14.609Z,1740167714.609 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-21T19:55:15.037Z,1740167715.037 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2025-02-21T19:55:15.037Z,1740167715.037 [keepstation:OnStation] Stopped 2025-02-21T19:55:15.037Z,1740167715.037 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2025-02-21T19:55:15.037Z,1740167715.037 [keepstation:KeepStation] Running Loop=1 2025-02-21T19:55:15.038Z,1740167715.038 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2025-02-21T19:55:15.038Z,1740167715.038 [keepstation:KeepStation:A] Running Loop=1 2025-02-21T19:55:15.038Z,1740167715.038 [keepstation:KeepStation:B.Pitch] Running Loop=1 2025-02-21T19:55:15.038Z,1740167715.038 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize. 2025-02-21T19:55:15.038Z,1740167715.038 [keepstation:KeepStation:C.KeepStation] Running Loop=1 2025-02-21T19:55:15.038Z,1740167715.038 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-02-21T19:55:15.038Z,1740167715.038 [keepstation:KeepStation:D.Wait] Running Loop=1 2025-02-21T19:55:15.038Z,1740167715.038 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component. 2025-02-21T19:55:15.456Z,1740167715.456 [keepstation:KeepStation:C.KeepStation] Running Loop=1 2025-02-21T19:55:15.456Z,1740167715.456 [keepstation:KeepStation:B.Pitch] Running Loop=1 2025-02-21T19:55:15.457Z,1740167715.457 [keepstation:KeepStation:A] Running Loop=1 2025-02-21T19:55:27.981Z,1740167727.981 [DataOverHttps](IMPORTANT): SBD MTMSN=20250221T195527 2025-02-21T19:55:27.994Z,1740167727.994 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.013487 2025-02-21T19:55:40.739Z,1740167740.739 [DataOverHttps](INFO): Received command: set keepstation.missiontimeout 40 minute 2025-02-21T19:55:46.422Z,1740167746.422 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-21T19:55:56.095Z,1740167756.095 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 40 minute 2025-02-21T19:57:00.008Z,1740167820.008 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-21T19:57:31.131Z,1740167851.131 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-21T19:57:33.203Z,1740167853.203 [Radio_Surface](INFO): Powering down 2025-02-21T19:58:02.256Z,1740167882.256 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-21T19:58:03.259Z,1740167883.259 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-02-21T19:59:08.524Z,1740167948.524 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.723434 2025-02-21T20:26:35.991Z,1740169595.991 [keepstation](INFO): Timed out from 2025-02-21T19:46:35.6Z 2025-02-21T20:26:35.991Z,1740169595.991 [MissionManager](INFO): keepstation is completed. 2025-02-21T20:26:35.991Z,1740169595.991 [MissionManager](INFO): Uninitializing Mission keepstation 2025-02-21T20:26:35.991Z,1740169595.991 [keepstation] Stopped 2025-02-21T20:26:35.992Z,1740169595.992 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2025-02-21T20:26:35.992Z,1740169595.992 [keepstation:StandardEnvelopes] Stopped 2025-02-21T20:26:35.992Z,1740169595.992 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2025-02-21T20:26:35.992Z,1740169595.992 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-02-21T20:26:35.992Z,1740169595.992 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-02-21T20:26:35.992Z,1740169595.992 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-02-21T20:26:35.992Z,1740169595.992 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-02-21T20:26:35.992Z,1740169595.992 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-02-21T20:26:35.992Z,1740169595.992 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-02-21T20:26:35.992Z,1740169595.992 [keepstation:BackseatDriver] Stopped 2025-02-21T20:26:35.992Z,1740169595.992 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver 2025-02-21T20:26:35.992Z,1740169595.992 [keepstation:BackseatDriver:A.BackseatDriver] Stopped 2025-02-21T20:26:35.993Z,1740169595.993 [keepstation:PowerOnly] Stopped 2025-02-21T20:26:35.993Z,1740169595.993 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly 2025-02-21T20:26:35.993Z,1740169595.993 [keepstation:PowerOnly:E.Wait] Stopped 2025-02-21T20:26:35.993Z,1740169595.993 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-02-21T20:26:35.993Z,1740169595.993 [keepstation:H.Pitch] Stopped 2025-02-21T20:26:35.993Z,1740169595.993 [keepstation:KeepStation] Stopped 2025-02-21T20:26:35.993Z,1740169595.993 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation 2025-02-21T20:26:35.993Z,1740169595.993 [keepstation:KeepStation:A] Stopped 2025-02-21T20:26:35.993Z,1740169595.993 [keepstation:KeepStation:B.Pitch] Stopped 2025-02-21T20:26:35.993Z,1740169595.993 [keepstation:KeepStation:C.KeepStation] Stopped 2025-02-21T20:26:35.993Z,1740169595.993 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-02-21T20:26:35.993Z,1740169595.993 [keepstation:KeepStation:D.Wait] Stopped 2025-02-21T20:26:35.993Z,1740169595.993 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component. 2025-02-21T20:26:36.391Z,1740169596.391 [MissionManager](IMPORTANT): Started mission Default 2025-02-21T20:26:36.391Z,1740169596.391 [Default] Running Loop=1 2025-02-21T20:26:36.391Z,1740169596.391 [Default](DEBUG): Aggregate::initialize Default 2025-02-21T20:26:36.391Z,1740169596.391 [Default:B.GoToSurface] Running Loop=1 2025-02-21T20:26:36.392Z,1740169596.392 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-21T20:26:36.392Z,1740169596.392 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-02-21T20:26:36.392Z,1740169596.392 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-02-21T20:26:36.392Z,1740169596.392 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-02-21T20:26:36.393Z,1740169596.393 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-02-21T20:26:36.393Z,1740169596.393 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-21T20:26:36.393Z,1740169596.393 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-21T20:26:36.394Z,1740169596.394 [Default:A.Wait] Running Loop=1 2025-02-21T20:26:36.394Z,1740169596.394 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-02-21T20:26:36.883Z,1740169596.883 [Radio_Surface](INFO): Powering up 2025-02-21T20:26:38.340Z,1740169598.340 [BackseatComponent](INFO): Sent LCM shutdown request. 2025-02-21T20:26:38.340Z,1740169598.340 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.). 2025-02-21T20:26:42.375Z,1740169602.375 [DataOverHttps](INFO): Radio surface powered ON. 2025-02-21T20:26:49.491Z,1740169609.491 [Default:A.Wait](INFO): Done Waiting. 2025-02-21T20:26:49.508Z,1740169609.508 [Default:A.Wait] Stopped 2025-02-21T20:26:49.508Z,1740169609.508 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-02-21T20:26:56.495Z,1740169616.495 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec). 2025-02-21T20:26:56.496Z,1740169616.496 [BackseatComponent](INFO): Unsubscribing from LCM channels. 2025-02-21T20:26:56.496Z,1740169616.496 [LcmUniversalReporter](INFO): Deactivating messaging. 2025-02-21T20:27:12.498Z,1740169632.498 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-21T20:27:42.375Z,1740169662.375 [Default:CheckIn] Running Loop=1 2025-02-21T20:27:42.375Z,1740169662.375 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-21T20:27:42.375Z,1740169662.375 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-21T20:27:43.632Z,1740169663.632 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-21T20:28:09.022Z,1740169689.022 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-21T20:28:14.760Z,1740169694.760 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-21T20:28:20.345Z,1740169700.345 [NAL9602](INFO): SBD MO Status=0, MOMSN=13062, MT Status=0, MTMSN=0 2025-02-21T20:28:20.345Z,1740169700.345 [NAL9602](INFO): No messages in MT queue 2025-02-21T20:28:21.547Z,1740169701.547 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-21T20:28:21.574Z,1740169701.574 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202820.00,A,3647.76218,N,12150.77783,W,0.855,31.90,210225,,,A*44 2025-02-21T20:28:21.581Z,1740169701.581 [NAL9602](INFO): GPS fix at 20250221T202820: (36.796036, -121.846297) 2025-02-21T20:28:21.624Z,1740169701.624 [Default:CheckIn:Read_GPS] Stopped 2025-02-21T20:28:21.624Z,1740169701.624 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-21T20:28:34.550Z,1740169714.550 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-21T20:28:40.634Z,1740169720.634 [NAL9602](INFO): SBD MO Status=1, MOMSN=13063, MT Status=0, MTMSN=0 2025-02-21T20:28:40.712Z,1740169720.712 [NAL9602](INFO): Sent 72 bytes from file Logs/20250221T194051/Courier0015.lzma 2025-02-21T20:28:40.712Z,1740169720.712 [NAL9602](INFO): Packets left to send: 0 2025-02-21T20:28:45.907Z,1740169725.907 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-21T20:28:47.814Z,1740169727.814 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-21T20:28:52.418Z,1740169732.418 [NAL9602](INFO): SBD MO Status=1, MOMSN=13064, MT Status=0, MTMSN=0 2025-02-21T20:28:52.468Z,1740169732.468 [NAL9602](INFO): Sent 246 bytes from file Logs/20250221T194051/Express0016.lzma 2025-02-21T20:28:52.468Z,1740169732.468 [NAL9602](INFO): Packets left to send: 0 2025-02-21T20:29:00.039Z,1740169740.039 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-21T20:29:00.502Z,1740169740.502 [NAL9602](INFO): SBD MO Status=0, MOMSN=13065, MT Status=0, MTMSN=0 2025-02-21T20:29:00.680Z,1740169740.680 [Default:CheckIn:Read_Iridium] Stopped 2025-02-21T20:29:00.680Z,1740169740.680 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-21T20:29:00.680Z,1740169740.680 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-21T20:29:10.106Z,1740169750.106 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004364 2025-02-21T20:29:13.202Z,1740169753.202 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-21T20:29:26.131Z,1740169766.131 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-21T20:29:31.050Z,1740169771.050 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-21T20:29:55.909Z,1740169795.909 [DataOverHttps](IMPORTANT): SBD MTMSN=20250221T202954 2025-02-21T20:30:03.727Z,1740169803.727 [DataOverHttps](INFO): Received command: restart logs