2025-02-20T04:49:36.756Z,1740026976.756 [DataOverHttps](INFO): Received command: restart logs 2025-02-20T04:49:36.773Z,1740026976.773 [CommandExec](IMPORTANT): got command restart logs 2025-02-20T04:52:42.591Z,1740027162.591 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-20T04:52:42.591Z,1740027162.591 [Default:CheckIn:C.Wait] Stopped 2025-02-20T04:52:42.591Z,1740027162.591 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-20T04:52:42.592Z,1740027162.592 [Default:CheckIn:D] Running Loop=1 2025-02-20T04:52:42.986Z,1740027162.986 [Default:CheckIn:D] Stopped 2025-02-20T04:52:42.986Z,1740027162.986 [Default:CheckIn:E] Running Loop=1 2025-02-20T04:52:43.391Z,1740027163.391 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.854776 min 2025-02-20T04:52:43.391Z,1740027163.391 [Default:CheckIn:E] Stopped 2025-02-20T04:52:43.391Z,1740027163.391 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-20T04:52:43.391Z,1740027163.391 [Default:CheckIn] Stopped 2025-02-20T04:52:43.391Z,1740027163.391 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-20T04:52:43.391Z,1740027163.391 [Default:CheckIn](INFO): Running loop #2 2025-02-20T04:52:43.391Z,1740027163.391 [Default:CheckIn] Running Loop=2 2025-02-20T04:52:43.391Z,1740027163.391 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-20T04:52:43.392Z,1740027163.392 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-20T04:52:45.406Z,1740027165.406 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,045244.00,A,3647.80969,N,12150.68291,W,0.272,166.18,200225,,,D*79 2025-02-20T04:52:45.409Z,1740027165.409 [NAL9602](INFO): GPS fix at 20250220T045244: (36.796828, -121.844715) 2025-02-20T04:52:45.420Z,1740027165.420 [Default:CheckIn:Read_GPS] Stopped 2025-02-20T04:52:45.420Z,1740027165.420 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-20T04:52:52.720Z,1740027172.720 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250220T033645/Courier0021.lzma 2025-02-20T04:52:53.722Z,1740027173.722 [DataOverHttps](INFO): Moved sent file to Logs/20250220T033645/Courier0021.lzma.bak 2025-02-20T04:52:53.722Z,1740027173.722 [DataOverHttps](INFO): SBD MOMSN=23968087 2025-02-20T04:53:16.117Z,1740027196.117 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20250220T044936/Courier0000.lzma 2025-02-20T04:53:17.118Z,1740027197.118 [DataOverHttps](INFO): Moved sent file to Logs/20250220T044936/Courier0000.lzma.bak 2025-02-20T04:53:17.118Z,1740027197.118 [DataOverHttps](INFO): SBD MOMSN=23968089 2025-02-20T04:53:39.211Z,1740027219.211 [NAL9602](INFO): SBD MO Status=2, MOMSN=12871, MT Status=2, MTMSN=0 2025-02-20T04:53:39.211Z,1740027219.211 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-20T04:53:43.789Z,1740027223.789 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20250220T033645/Express0022.lzma 2025-02-20T04:53:44.790Z,1740027224.790 [DataOverHttps](INFO): Moved sent file to Logs/20250220T033645/Express0022.lzma.bak 2025-02-20T04:53:44.790Z,1740027224.790 [DataOverHttps](INFO): SBD MOMSN=23968093 2025-02-20T04:53:47.295Z,1740027227.295 [NAL9602](INFO): SBD MO Status=0, MOMSN=12871, MT Status=0, MTMSN=0 2025-02-20T04:53:47.295Z,1740027227.295 [NAL9602](INFO): No messages in MT queue 2025-02-20T04:54:10.549Z,1740027250.549 [DataOverHttps](INFO): Sending 391 bytes from file Logs/20250220T044936/Express0001.lzma 2025-02-20T04:54:11.550Z,1740027251.550 [DataOverHttps](INFO): Moved sent file to Logs/20250220T044936/Express0001.lzma.bak 2025-02-20T04:54:11.550Z,1740027251.550 [DataOverHttps](INFO): SBD MOMSN=23968097 2025-02-20T04:54:13.211Z,1740027253.211 [Default:CheckIn:Read_Iridium] Stopped 2025-02-20T04:54:13.212Z,1740027253.212 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-20T04:54:13.212Z,1740027253.212 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-20T04:54:18.005Z,1740027258.005 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-20T04:56:23.470Z,1740027383.470 [DataOverHttps](IMPORTANT): SBD MTMSN=20250220T045622 2025-02-20T04:56:31.079Z,1740027391.079 [DataOverHttps](INFO): Received command: load Transport/keepstation.tl;set keepstation.NeedCommsTime 30 min;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.Speed 0.8 m/s;run 2025-02-20T04:56:31.103Z,1740027391.103 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl 2025-02-20T04:56:31.120Z,1740027391.120 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl 2025-02-20T04:56:31.120Z,1740027391.120 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl 2025-02-20T04:56:31.122Z,1740027391.122 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl 2025-02-20T04:56:31.951Z,1740027391.951 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into: 4 45 NaN NaN 30 15 4 0.75 200 45 2 2025-02-20T04:56:31.957Z,1740027391.957 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2025-02-20T04:56:31.960Z,1740027391.960 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2025-02-20T04:56:31.962Z,1740027391.962 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2025-02-20T04:56:31.964Z,1740027391.964 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2025-02-20T04:56:31.966Z,1740027391.966 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2025-02-20T04:56:31.969Z,1740027391.969 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2025-02-20T04:56:31.971Z,1740027391.971 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2025-02-20T04:56:31.973Z,1740027391.973 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2025-02-20T04:56:31.975Z,1740027391.975 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2025-02-20T04:56:31.978Z,1740027391.978 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2025-02-20T04:56:31.980Z,1740027391.980 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2025-02-20T04:56:31.981Z,1740027391.981 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-02-20T04:56:31.981Z,1740027391.981 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-02-20T04:56:31.982Z,1740027391.982 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-02-20T04:56:32.323Z,1740027392.323 [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-20T04:56:32.328Z,1740027392.328 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2025-02-20T04:56:32.333Z,1740027392.333 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2025-02-20T04:56:32.338Z,1740027392.338 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-02-20T04:56:32.341Z,1740027392.341 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2025-02-20T04:56:32.343Z,1740027392.343 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-02-20T04:56:32.346Z,1740027392.346 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s 2025-02-20T04:56:32.348Z,1740027392.348 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2025-02-20T04:56:32.350Z,1740027392.350 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2025-02-20T04:56:32.387Z,1740027392.387 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-02-20T04:56:32.423Z,1740027392.423 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-02-20T04:56:32.423Z,1740027392.423 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-02-20T04:56:32.426Z,1740027392.426 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-02-20T04:56:32.496Z,1740027392.496 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2025-02-20T04:56:32.500Z,1740027392.500 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2025-02-20T04:56:32.502Z,1740027392.502 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2025-02-20T04:56:32.505Z,1740027392.505 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2025-02-20T04:56:32.507Z,1740027392.507 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-02-20T04:56:32.509Z,1740027392.509 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-02-20T04:56:32.540Z,1740027392.540 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-02-20T04:56:32.546Z,1740027392.546 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-02-20T04:56:32.553Z,1740027392.553 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-02-20T04:56:32.554Z,1740027392.554 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-02-20T04:56:32.555Z,1740027392.555 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-02-20T04:56:32.631Z,1740027392.631 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-02-20T04:56:32.649Z,1740027392.649 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2025-02-20T04:56:32.651Z,1740027392.651 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-02-20T04:56:32.655Z,1740027392.655 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-02-20T04:56:32.655Z,1740027392.655 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-02-20T04:56:32.673Z,1740027392.673 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-02-20T04:56:32.783Z,1740027392.783 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-02-20T04:56:32.787Z,1740027392.787 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2025-02-20T04:56:32.789Z,1740027392.789 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2025-02-20T04:56:32.795Z,1740027392.795 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2025-02-20T04:56:32.797Z,1740027392.797 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2025-02-20T04:56:32.799Z,1740027392.799 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool 2025-02-20T04:56:32.806Z,1740027392.806 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-02-20T04:56:32.808Z,1740027392.808 [keepstation:H.Pitch](DEBUG): Construct. 2025-02-20T04:56:32.814Z,1740027392.814 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy. 2025-02-20T04:56:32.817Z,1740027392.817 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct. 2025-02-20T04:56:32.821Z,1740027392.821 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct. 2025-02-20T04:56:32.824Z,1740027392.824 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-02-20T04:56:32.877Z,1740027392.877 [keepstation:KeepStation:B.Pitch](DEBUG): Construct. 2025-02-20T04:56:32.882Z,1740027392.882 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation. 2025-02-20T04:56:32.892Z,1740027392.892 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait. 2025-02-20T04:56:32.902Z,1740027392.902 [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-20T04:56:32.903Z,1740027392.903 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2025-02-20T04:57:00.787Z,1740027420.787 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 30 minute 2025-02-20T04:57:00.787Z,1740027420.787 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.797 degree 2025-02-20T04:57:00.792Z,1740027420.792 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.847 degree 2025-02-20T04:57:00.793Z,1740027420.793 [CommandExec](IMPORTANT): got command set keepstation.Speed 0.8 meter_per_second 2025-02-20T04:57:00.794Z,1740027420.794 [CommandExec](IMPORTANT): got command run 2025-02-20T04:57:00.795Z,1740027420.795 [CommandExec](IMPORTANT): Running 2025-02-20T04:57:00.905Z,1740027420.905 [Default] Stopped 2025-02-20T04:57:00.905Z,1740027420.905 [Default](DEBUG): Aggregate::uninitialize Default 2025-02-20T04:57:00.905Z,1740027420.905 [Default:B.GoToSurface] Stopped 2025-02-20T04:57:00.905Z,1740027420.905 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-20T04:57:00.906Z,1740027420.906 [Default:CheckIn] Stopped 2025-02-20T04:57:00.906Z,1740027420.906 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-20T04:57:00.906Z,1740027420.906 [Default:CheckIn:C.Wait] Stopped 2025-02-20T04:57:00.906Z,1740027420.906 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-20T04:57:00.906Z,1740027420.906 [MissionManager](IMPORTANT): Started mission keepstation 2025-02-20T04:57:00.906Z,1740027420.906 [keepstation] Running Loop=1 2025-02-20T04:57:00.906Z,1740027420.906 [keepstation](DEBUG): Aggregate::initialize keepstation 2025-02-20T04:57:00.906Z,1740027420.906 [keepstation:StandardEnvelopes] Running Loop=1 2025-02-20T04:57:00.906Z,1740027420.906 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2025-02-20T04:57:00.907Z,1740027420.907 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-02-20T04:57:00.907Z,1740027420.907 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-02-20T04:57:00.907Z,1740027420.907 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-02-20T04:57:00.907Z,1740027420.907 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-02-20T04:57:00.907Z,1740027420.907 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-02-20T04:57:00.907Z,1740027420.907 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-02-20T04:57:00.907Z,1740027420.907 [keepstation:BackseatDriver] Running Loop=1 2025-02-20T04:57:00.907Z,1740027420.907 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2025-02-20T04:57:00.907Z,1740027420.907 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-02-20T04:57:00.908Z,1740027420.908 [keepstation:PowerOnly] Running Loop=1 2025-02-20T04:57:00.908Z,1740027420.908 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2025-02-20T04:57:00.908Z,1740027420.908 [keepstation:PowerOnly:A] Running Loop=1 2025-02-20T04:57:00.908Z,1740027420.908 [keepstation:PowerOnly:B] Running Loop=1 2025-02-20T04:57:00.916Z,1740027420.916 [keepstation:PowerOnly:C] Running Loop=1 2025-02-20T04:57:00.916Z,1740027420.916 [keepstation:PowerOnly:D] Running Loop=1 2025-02-20T04:57:00.916Z,1740027420.916 [keepstation:PowerOnly:E.Wait] Running Loop=1 2025-02-20T04:57:00.916Z,1740027420.916 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-02-20T04:57:00.917Z,1740027420.917 [keepstation:H.Pitch] Running Loop=1 2025-02-20T04:57:00.917Z,1740027420.917 [keepstation:H.Pitch](DEBUG): Initialize. 2025-02-20T04:57:00.917Z,1740027420.917 [keepstation:B] Running Loop=1 2025-02-20T04:57:00.917Z,1740027420.917 [keepstation:H.Pitch] Running Loop=1 2025-02-20T04:57:00.918Z,1740027420.918 [keepstation:PowerOnly] Running Loop=1 2025-02-20T04:57:00.918Z,1740027420.918 [keepstation:PowerOnly:D] Stopped 2025-02-20T04:57:00.918Z,1740027420.918 [keepstation:PowerOnly:C] Stopped 2025-02-20T04:57:00.918Z,1740027420.918 [keepstation:PowerOnly:B] Stopped 2025-02-20T04:57:00.918Z,1740027420.918 [keepstation:PowerOnly:A] Stopped 2025-02-20T04:57:00.918Z,1740027420.918 [keepstation:BackseatDriver] Running Loop=1 2025-02-20T04:57:00.919Z,1740027420.919 [keepstation:StandardEnvelopes] Running Loop=1 2025-02-20T04:57:00.919Z,1740027420.919 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-02-20T04:57:00.919Z,1740027420.919 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-02-20T04:57:00.919Z,1740027420.919 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-02-20T04:57:00.920Z,1740027420.920 [keepstation:B] Stopped 2025-02-20T04:57:00.928Z,1740027420.928 [keepstation:D] Running Loop=1 2025-02-20T04:57:01.322Z,1740027421.322 [keepstation:D] Stopped 2025-02-20T04:57:01.322Z,1740027421.322 [keepstation:E] Running Loop=1 2025-02-20T04:57:01.706Z,1740027421.706 [keepstation:E] Stopped 2025-02-20T04:57:01.706Z,1740027421.706 [keepstation:StartingMission] Running Loop=1 2025-02-20T04:57:01.706Z,1740027421.706 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2025-02-20T04:57:02.116Z,1740027422.116 [keepstation:NeedComms] Running Loop=1 2025-02-20T04:57:02.124Z,1740027422.124 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-02-20T04:57:02.124Z,1740027422.124 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-02-20T04:57:02.124Z,1740027422.124 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-20T04:57:02.125Z,1740027422.125 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-02-20T04:57:02.125Z,1740027422.125 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-02-20T04:57:02.125Z,1740027422.125 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-02-20T04:57:02.126Z,1740027422.126 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-02-20T04:57:02.126Z,1740027422.126 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-20T04:57:02.126Z,1740027422.126 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-20T04:57:02.127Z,1740027422.127 [keepstation:NeedComms:A] Running Loop=1 2025-02-20T04:57:02.172Z,1740027422.172 [keepstation:NeedComms:A](INFO): last time_fix was: 1740027164.000000 second since 1970/01/01T00:00:00Z 2025-02-20T04:57:02.172Z,1740027422.172 [keepstation:NeedComms:A] Stopped 2025-02-20T04:57:02.542Z,1740027422.542 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2025-02-20T04:57:02.543Z,1740027422.543 [keepstation:NeedComms:C] Running Loop=1 2025-02-20T04:57:02.943Z,1740027422.943 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-02-20T04:57:04.558Z,1740027424.558 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,045703.00,A,3647.81293,N,12150.67291,W,0.389,134.82,200225,,,D*7E 2025-02-20T04:57:04.564Z,1740027424.564 [NAL9602](INFO): GPS fix at 20250220T045703: (36.796882, -121.844549) 2025-02-20T04:57:04.619Z,1740027424.619 [keepstation:NeedComms:C] Stopped 2025-02-20T04:57:04.619Z,1740027424.619 [keepstation:NeedComms:D] Running Loop=1 2025-02-20T04:57:04.947Z,1740027424.947 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-02-20T04:57:06.395Z,1740027426.395 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250220T044936/Courier0003.lzma 2025-02-20T04:57:06.578Z,1740027426.578 [DataOverHttps](INFO): Moved sent file to Logs/20250220T044936/Courier0003.lzma.bak 2025-02-20T04:57:06.578Z,1740027426.578 [DataOverHttps](INFO): SBD MOMSN=23968105 2025-02-20T04:57:07.664Z,1740027427.664 [RudderServo](ERROR): getPosition uart error serial timeout 2025-02-20T04:57:07.664Z,1740027427.664 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout 2025-02-20T04:57:07.665Z,1740027427.665 [RudderServo] Communications Fault, FailCount= 1 2025-02-20T04:57:07.665Z,1740027427.665 [RudderServo](ERROR): Communications Fault 2025-02-20T04:57:07.669Z,1740027427.669 [CBIT](ERROR): Communications Fault in component: RudderServo 2025-02-20T04:57:08.048Z,1740027428.048 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-02-20T04:57:08.049Z,1740027428.049 [RudderServo](INFO): Powering down 2025-02-20T04:57:08.786Z,1740027428.786 [RudderServo](DEBUG): Initializing EZServoServo. 2025-02-20T04:57:08.914Z,1740027428.914 [RudderServo](DEBUG): Initializing RudderServo. 2025-02-20T04:57:08.918Z,1740027428.918 [CBIT](INFO): Clearing failed state for component RudderServo 2025-02-20T04:57:08.918Z,1740027428.918 [RudderServo] No Fault, FailCount= 1 2025-02-20T04:57:12.419Z,1740027432.419 [NAL9602](INFO): SBD MO Status=0, MOMSN=12872, MT Status=0, MTMSN=0 2025-02-20T04:57:12.419Z,1740027432.419 [NAL9602](INFO): No messages in MT queue 2025-02-20T04:57:26.245Z,1740027446.245 [DataOverHttps](INFO): Sending 367 bytes from file Logs/20250220T044936/Express0005.lzma 2025-02-20T04:57:27.246Z,1740027447.246 [DataOverHttps](INFO): Moved sent file to Logs/20250220T044936/Express0005.lzma.bak 2025-02-20T04:57:27.246Z,1740027447.246 [DataOverHttps](INFO): SBD MOMSN=23968108 2025-02-20T04:57:43.198Z,1740027463.198 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-20T04:57:44.980Z,1740027464.980 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20250220T044936/Express0010.lzma 2025-02-20T04:57:46.823Z,1740027466.823 [DataOverHttps](INFO): Moved sent file to Logs/20250220T044936/Express0010.lzma.bak 2025-02-20T04:57:46.823Z,1740027466.823 [DataOverHttps](INFO): SBD MOMSN=23968120 2025-02-20T04:57:47.244Z,1740027467.244 [keepstation:NeedComms:D] Stopped 2025-02-20T04:57:47.245Z,1740027467.245 [keepstation:NeedComms:E] Running Loop=1 2025-02-20T04:57:47.657Z,1740027467.657 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-02-20T04:57:49.255Z,1740027469.255 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,045748.00,A,3647.81332,N,12150.67160,W,0.680,232.98,200225,,,D*74 2025-02-20T04:57:49.264Z,1740027469.264 [NAL9602](INFO): GPS fix at 20250220T045748: (36.796889, -121.844527) 2025-02-20T04:57:49.296Z,1740027469.296 [keepstation:NeedComms:E] Stopped 2025-02-20T04:57:49.297Z,1740027469.297 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-02-20T04:57:49.297Z,1740027469.297 [keepstation:NeedComms] Stopped 2025-02-20T04:57:49.297Z,1740027469.297 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-20T04:57:49.298Z,1740027469.298 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-20T04:57:49.298Z,1740027469.298 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-20T04:57:49.705Z,1740027469.705 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-02-20T04:57:49.705Z,1740027469.705 [keepstation:StartingMission] Stopped 2025-02-20T04:57:49.705Z,1740027469.705 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-02-20T04:57:49.705Z,1740027469.705 [keepstation:TransitToStation] Running Loop=1 2025-02-20T04:57:49.705Z,1740027469.705 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-02-20T04:57:49.705Z,1740027469.705 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2025-02-20T04:57:49.705Z,1740027469.705 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-02-20T04:57:49.705Z,1740027469.705 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2025-02-20T04:57:49.705Z,1740027469.705 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize. 2025-02-20T04:57:49.706Z,1740027469.706 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2025-02-20T04:57:49.706Z,1740027469.706 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize. 2025-02-20T04:57:49.706Z,1740027469.706 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-02-20T04:57:49.706Z,1740027469.706 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-02-20T04:57:49.707Z,1740027469.707 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2025-02-20T04:57:50.087Z,1740027470.087 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2025-02-20T04:57:50.087Z,1740027470.087 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2025-02-20T04:57:50.087Z,1740027470.087 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2025-02-20T04:57:50.112Z,1740027470.112 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2025-02-20T04:57:55.763Z,1740027475.763 [NAL9602](INFO): SBD MO Status=0, MOMSN=12873, MT Status=0, MTMSN=0 2025-02-20T04:57:55.763Z,1740027475.763 [NAL9602](INFO): No messages in MT queue 2025-02-20T04:58:21.645Z,1740027501.645 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.80 m/s, pitch 30.00 deg, mass-position 11.65 mm (4 active estimators). 2025-02-20T04:58:26.485Z,1740027506.485 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-20T04:59:18.541Z,1740027558.541 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-20T04:59:49.664Z,1740027589.664 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-20T05:00:20.788Z,1740027620.788 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-20T05:00:27.896Z,1740027627.896 [Radio_Surface](INFO): Powering down 2025-02-20T05:00:51.913Z,1740027651.913 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-20T05:00:52.917Z,1740027652.917 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-02-20T05:00:55.929Z,1740027655.929 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.750443 2025-02-20T05:03:40.826Z,1740027820.826 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000 2025-02-20T05:03:40.826Z,1740027820.826 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2025-02-20T05:03:40.826Z,1740027820.826 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-02-20T05:03:40.827Z,1740027820.827 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2025-02-20T05:03:40.827Z,1740027820.827 [keepstation:TransitToStation] Stopped 2025-02-20T05:03:40.827Z,1740027820.827 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2025-02-20T05:03:40.828Z,1740027820.828 [keepstation:TransitToStation:A.Buoyancy] Stopped 2025-02-20T05:03:40.828Z,1740027820.828 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-02-20T05:03:40.828Z,1740027820.828 [keepstation:TransitToStation:B.Pitch] Stopped 2025-02-20T05:03:40.828Z,1740027820.828 [keepstation:TransitToStation:C.SetSpeed] Stopped 2025-02-20T05:03:40.828Z,1740027820.828 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize. 2025-02-20T05:03:40.828Z,1740027820.828 [keepstation:OnStation] Running Loop=1 2025-02-20T05:03:40.828Z,1740027820.828 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2025-02-20T05:03:41.241Z,1740027821.241 [keepstation:NeedComms] Running Loop=1 2025-02-20T05:03:41.241Z,1740027821.241 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-02-20T05:03:41.241Z,1740027821.241 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-02-20T05:03:41.242Z,1740027821.242 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-20T05:03:41.242Z,1740027821.242 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-02-20T05:03:41.242Z,1740027821.242 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-02-20T05:03:41.243Z,1740027821.243 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-02-20T05:03:41.243Z,1740027821.243 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-02-20T05:03:41.243Z,1740027821.243 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-20T05:03:41.244Z,1740027821.244 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-20T05:03:41.244Z,1740027821.244 [keepstation:NeedComms:A] Running Loop=1 2025-02-20T05:03:41.246Z,1740027821.246 [keepstation:NeedComms:A](INFO): last time_fix was: 1740027468.000000 second since 1970/01/01T00:00:00Z 2025-02-20T05:03:41.246Z,1740027821.246 [keepstation:NeedComms:A] Stopped 2025-02-20T05:04:33.754Z,1740027873.754 [keepstation:NeedComms:C] Running Loop=1 2025-02-20T05:04:34.336Z,1740027874.336 [Radio_Surface](INFO): Powering up 2025-02-20T05:04:39.844Z,1740027879.844 [DataOverHttps](INFO): Radio surface powered ON. 2025-02-20T05:05:09.965Z,1740027909.965 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-20T05:05:28.502Z,1740027928.502 [NAL9602](INFO): SBD MO Status=0, MOMSN=12874, MT Status=0, MTMSN=0 2025-02-20T05:05:28.502Z,1740027928.502 [NAL9602](INFO): No messages in MT queue 2025-02-20T05:05:29.714Z,1740027929.714 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,050529.00,A,3647.84636,N,12150.82746,W,0.836,11.26,200225,,,D*4D 2025-02-20T05:05:29.726Z,1740027929.726 [NAL9602](INFO): GPS fix at 20250220T050529: (36.797439, -121.847124) 2025-02-20T05:05:29.739Z,1740027929.739 [keepstation:NeedComms:C] Stopped 2025-02-20T05:05:29.739Z,1740027929.739 [keepstation:NeedComms:D] Running Loop=1 2025-02-20T05:05:39.099Z,1740027939.099 [NAL9602](INFO): SBD MO Status=1, MOMSN=12875, MT Status=0, MTMSN=0 2025-02-20T05:05:39.150Z,1740027939.150 [NAL9602](INFO): Sent 74 bytes from file Logs/20250220T044936/Courier0012.lzma 2025-02-20T05:05:39.150Z,1740027939.150 [NAL9602](INFO): Packets left to send: 0 2025-02-20T05:05:41.088Z,1740027941.088 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-20T05:05:51.723Z,1740027951.723 [NAL9602](INFO): SBD MO Status=1, MOMSN=12876, MT Status=0, MTMSN=0 2025-02-20T05:05:51.780Z,1740027951.780 [NAL9602](INFO): Sent 302 bytes from file Logs/20250220T044936/Express0013.lzma 2025-02-20T05:05:51.781Z,1740027951.781 [NAL9602](INFO): Packets left to send: 0 2025-02-20T05:06:07.359Z,1740027967.359 [NAL9602](INFO): SBD MO Status=0, MOMSN=12877, MT Status=0, MTMSN=0 2025-02-20T05:06:07.458Z,1740027967.458 [keepstation:NeedComms:D] Stopped 2025-02-20T05:06:07.458Z,1740027967.458 [keepstation:NeedComms:E] Running Loop=1 2025-02-20T05:06:09.787Z,1740027969.787 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,050609.00,A,3647.85161,N,12150.82989,W,0.369,345.67,200225,,,D*73 2025-02-20T05:06:09.797Z,1740027969.797 [NAL9602](INFO): GPS fix at 20250220T050609: (36.797527, -121.847165) 2025-02-20T05:06:09.810Z,1740027969.810 [keepstation:NeedComms:E] Stopped 2025-02-20T05:06:09.811Z,1740027969.811 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-02-20T05:06:09.811Z,1740027969.811 [keepstation:NeedComms] Stopped 2025-02-20T05:06:09.811Z,1740027969.811 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-20T05:06:09.811Z,1740027969.811 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-20T05:06:09.811Z,1740027969.811 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-20T05:06:10.225Z,1740027970.225 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2025-02-20T05:06:10.225Z,1740027970.225 [keepstation:OnStation] Stopped 2025-02-20T05:06:10.225Z,1740027970.225 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2025-02-20T05:06:10.225Z,1740027970.225 [keepstation:KeepStation] Running Loop=1 2025-02-20T05:06:10.225Z,1740027970.225 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2025-02-20T05:06:10.225Z,1740027970.225 [keepstation:KeepStation:A] Running Loop=1 2025-02-20T05:06:10.225Z,1740027970.225 [keepstation:KeepStation:B.Pitch] Running Loop=1 2025-02-20T05:06:10.225Z,1740027970.225 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize. 2025-02-20T05:06:10.226Z,1740027970.226 [keepstation:KeepStation:C.KeepStation] Running Loop=1 2025-02-20T05:06:10.226Z,1740027970.226 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-02-20T05:06:10.226Z,1740027970.226 [keepstation:KeepStation:D.Wait] Running Loop=1 2025-02-20T05:06:10.226Z,1740027970.226 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component. 2025-02-20T05:06:10.600Z,1740027970.600 [keepstation:KeepStation:C.KeepStation] Running Loop=1 2025-02-20T05:06:10.600Z,1740027970.600 [keepstation:KeepStation:B.Pitch] Running Loop=1 2025-02-20T05:06:10.601Z,1740027970.601 [keepstation:KeepStation:A] Running Loop=1 2025-02-20T05:06:12.212Z,1740027972.212 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-20T05:06:42.505Z,1740028002.505 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-20T05:06:43.336Z,1740028003.336 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-20T05:06:55.653Z,1740028015.653 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003461 2025-02-20T05:07:44.852Z,1740028064.852 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-20T05:08:15.984Z,1740028095.984 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-20T05:08:16.164Z,1740028096.164 [Radio_Surface](INFO): Powering down 2025-02-20T05:08:16.988Z,1740028096.988 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-02-20T05:09:56.413Z,1740028196.413 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.763092 2025-02-20T05:13:22.483Z,1740028402.483 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-20T05:13:35.815Z,1740028415.815 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-20T05:13:48.742Z,1740028428.742 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-20T05:14:02.076Z,1740028442.076 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-20T05:14:15.408Z,1740028455.408 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-20T05:14:28.335Z,1740028468.335 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-20T05:14:41.265Z,1740028481.265 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-20T05:19:43.051Z,1740028783.051 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-02-20T05:19:49.515Z,1740028789.515 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-02-20T05:19:49.529Z,1740028789.529 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-02-20T05:19:49.529Z,1740028789.529 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-02-20T05:19:49.532Z,1740028789.532 [BPC1](INFO): Received data from all battery sticks. 2025-02-20T05:36:10.043Z,1740029770.043 [keepstation:NeedComms] Running Loop=1 2025-02-20T05:36:10.043Z,1740029770.043 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-02-20T05:36:10.043Z,1740029770.043 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-02-20T05:36:10.043Z,1740029770.043 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-20T05:36:10.043Z,1740029770.043 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-02-20T05:36:10.044Z,1740029770.044 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-02-20T05:36:10.044Z,1740029770.044 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-02-20T05:36:10.045Z,1740029770.045 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-02-20T05:36:10.045Z,1740029770.045 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-20T05:36:10.045Z,1740029770.045 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-20T05:36:10.046Z,1740029770.046 [keepstation:NeedComms:A] Running Loop=1 2025-02-20T05:36:10.047Z,1740029770.047 [keepstation:NeedComms:A](INFO): last time_fix was: 1740027969.000000 second since 1970/01/01T00:00:00Z 2025-02-20T05:36:10.048Z,1740029770.048 [keepstation:NeedComms:A] Stopped 2025-02-20T05:38:16.003Z,1740029896.003 [keepstation:NeedComms:C] Running Loop=1 2025-02-20T05:38:16.436Z,1740029896.436 [Radio_Surface](INFO): Powering up 2025-02-20T05:38:22.486Z,1740029902.486 [DataOverHttps](INFO): Radio surface powered ON. 2025-02-20T05:38:52.609Z,1740029932.609 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-20T05:39:17.226Z,1740029957.226 [NAL9602](INFO): SBD MO Status=2, MOMSN=12878, MT Status=2, MTMSN=0 2025-02-20T05:39:17.226Z,1740029957.226 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-20T05:39:23.733Z,1740029963.733 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-20T05:39:33.790Z,1740029973.790 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=12878, MT Status=1, MTMSN=251 2025-02-20T05:39:33.791Z,1740029973.791 [NAL9602](INFO): Data available in MT queue 2025-02-20T05:39:34.277Z,1740029974.277 [NAL9602](INFO): Received command: stop 2025-02-20T05:39:34.353Z,1740029974.353 [CommandExec](IMPORTANT): got command stop 2025-02-20T05:39:34.353Z,1740029974.353 [CommandExec](IMPORTANT): Scheduling is paused 2025-02-20T05:39:34.353Z,1740029974.353 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2025-02-20T05:39:34.616Z,1740029974.616 [MissionManager](INFO): MissionManager is completed. 2025-02-20T05:39:34.616Z,1740029974.616 [MissionManager](INFO): Uninitializing Mission keepstation 2025-02-20T05:39:34.616Z,1740029974.616 [keepstation] Stopped 2025-02-20T05:39:34.616Z,1740029974.616 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2025-02-20T05:39:34.616Z,1740029974.616 [keepstation:NeedComms] Stopped 2025-02-20T05:39:34.617Z,1740029974.617 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-20T05:39:34.617Z,1740029974.617 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-20T05:39:34.617Z,1740029974.617 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-20T05:39:34.617Z,1740029974.617 [keepstation:NeedComms:C] Stopped 2025-02-20T05:39:34.617Z,1740029974.617 [keepstation:StandardEnvelopes] Stopped 2025-02-20T05:39:34.617Z,1740029974.617 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2025-02-20T05:39:34.617Z,1740029974.617 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-02-20T05:39:34.617Z,1740029974.617 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-02-20T05:39:34.617Z,1740029974.617 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-02-20T05:39:34.617Z,1740029974.617 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-02-20T05:39:34.617Z,1740029974.617 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-02-20T05:39:34.617Z,1740029974.617 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-02-20T05:39:34.617Z,1740029974.617 [keepstation:BackseatDriver] Stopped 2025-02-20T05:39:34.618Z,1740029974.618 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver 2025-02-20T05:39:34.618Z,1740029974.618 [keepstation:BackseatDriver:A.BackseatDriver] Stopped 2025-02-20T05:39:34.618Z,1740029974.618 [keepstation:PowerOnly] Stopped 2025-02-20T05:39:34.618Z,1740029974.618 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly 2025-02-20T05:39:34.618Z,1740029974.618 [keepstation:PowerOnly:E.Wait] Stopped 2025-02-20T05:39:34.618Z,1740029974.618 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-02-20T05:39:34.618Z,1740029974.618 [keepstation:H.Pitch] Stopped 2025-02-20T05:39:34.618Z,1740029974.618 [keepstation:KeepStation] Stopped 2025-02-20T05:39:34.618Z,1740029974.618 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation 2025-02-20T05:39:34.618Z,1740029974.618 [keepstation:KeepStation:A] Stopped 2025-02-20T05:39:34.618Z,1740029974.618 [keepstation:KeepStation:B.Pitch] Stopped 2025-02-20T05:39:34.618Z,1740029974.618 [keepstation:KeepStation:C.KeepStation] Stopped 2025-02-20T05:39:34.618Z,1740029974.618 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-02-20T05:39:34.619Z,1740029974.619 [keepstation:KeepStation:D.Wait] Stopped 2025-02-20T05:39:34.619Z,1740029974.619 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component. 2025-02-20T05:39:35.037Z,1740029975.037 [MissionManager](IMPORTANT): Started mission Default 2025-02-20T05:39:35.037Z,1740029975.037 [Default] Running Loop=1 2025-02-20T05:39:35.037Z,1740029975.037 [Default](DEBUG): Aggregate::initialize Default 2025-02-20T05:39:35.037Z,1740029975.037 [Default:B.GoToSurface] Running Loop=1 2025-02-20T05:39:35.038Z,1740029975.038 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-20T05:39:35.038Z,1740029975.038 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-02-20T05:39:35.038Z,1740029975.038 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-02-20T05:39:35.038Z,1740029975.038 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-02-20T05:39:35.039Z,1740029975.039 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-02-20T05:39:35.039Z,1740029975.039 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-20T05:39:35.039Z,1740029975.039 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-20T05:39:35.040Z,1740029975.040 [Default:A.Wait] Running Loop=1 2025-02-20T05:39:35.040Z,1740029975.040 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-02-20T05:39:35.411Z,1740029975.411 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,053934.00,A,3647.85085,N,12150.72498,W,0.194,291.79,200225,,,A*7A 2025-02-20T05:39:35.413Z,1740029975.413 [NAL9602](INFO): GPS fix at 20250220T053934: (36.797514, -121.845416) 2025-02-20T05:39:48.352Z,1740029988.352 [Default:A.Wait](INFO): Done Waiting. 2025-02-20T05:39:48.353Z,1740029988.353 [Default:A.Wait] Stopped 2025-02-20T05:39:48.353Z,1740029988.353 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-02-20T05:39:48.754Z,1740029988.754 [Default:CheckIn] Running Loop=1 2025-02-20T05:39:48.754Z,1740029988.754 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-20T05:39:48.755Z,1740029988.755 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-20T05:39:50.758Z,1740029990.758 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,053950.00,A,3647.85119,N,12150.72550,W,0.117,316.24,200225,,,A*74 2025-02-20T05:39:50.761Z,1740029990.761 [NAL9602](INFO): GPS fix at 20250220T053950: (36.797520, -121.845425) 2025-02-20T05:39:50.788Z,1740029990.788 [Default:CheckIn:Read_GPS] Stopped 2025-02-20T05:39:50.788Z,1740029990.788 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-20T05:39:54.865Z,1740029994.865 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-20T05:40:06.351Z,1740030006.351 [NAL9602](INFO): SBD MO Status=1, MOMSN=12879, MT Status=0, MTMSN=0 2025-02-20T05:40:06.404Z,1740030006.404 [NAL9602](INFO): Sent 203 bytes from file Logs/20250220T044936/Courier0015.lzma 2025-02-20T05:40:06.405Z,1740030006.405 [NAL9602](INFO): Packets left to send: 0 2025-02-20T05:40:18.591Z,1740030018.591 [NAL9602](INFO): SBD MO Status=1, MOMSN=12880, MT Status=0, MTMSN=0 2025-02-20T05:40:18.641Z,1740030018.641 [NAL9602](INFO): Sent 332 bytes from file Logs/20250220T044936/Express0016.lzma 2025-02-20T05:40:18.641Z,1740030018.641 [NAL9602](INFO): Packets left to send: 1 2025-02-20T05:40:26.017Z,1740030026.017 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-20T05:40:27.079Z,1740030027.079 [NAL9602](INFO): SBD MO Status=1, MOMSN=12881, MT Status=0, MTMSN=0 2025-02-20T05:40:27.129Z,1740030027.129 [NAL9602](INFO): Sent 9 bytes from file Logs/20250220T044936/Express0016.lzma 2025-02-20T05:40:27.129Z,1740030027.129 [NAL9602](INFO): Packets left to send: 0 2025-02-20T05:40:33.873Z,1740030033.873 [NAL9602](INFO): SBD MO Status=0, MOMSN=12882, MT Status=0, MTMSN=0 2025-02-20T05:40:46.067Z,1740030046.067 [NAL9602](INFO): SBD MO Status=1, MOMSN=12883, MT Status=0, MTMSN=0 2025-02-20T05:40:46.125Z,1740030046.125 [NAL9602](INFO): Sent 39 bytes from file Logs/20250220T044936/Courier0018.lzma 2025-02-20T05:40:46.125Z,1740030046.125 [NAL9602](INFO): Packets left to send: 0 2025-02-20T05:40:47.941Z,1740030047.941 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003426 2025-02-20T05:40:57.415Z,1740030057.415 [NAL9602](INFO): SBD MO Status=1, MOMSN=12884, MT Status=0, MTMSN=0 2025-02-20T05:40:57.466Z,1740030057.466 [NAL9602](INFO): Sent 72 bytes from file Logs/20250220T044936/Express0019.lzma 2025-02-20T05:40:57.466Z,1740030057.466 [NAL9602](INFO): Packets left to send: 0 2025-02-20T05:40:59.116Z,1740030059.116 [Default:CheckIn:Read_Iridium] Stopped 2025-02-20T05:40:59.121Z,1740030059.121 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-20T05:40:59.121Z,1740030059.121 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-20T05:41:28.157Z,1740030088.157 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-20T05:42:08.282Z,1740030128.282 [DataOverHttps](IMPORTANT): SBD MTMSN=20250220T054207 2025-02-20T05:42:17.500Z,1740030137.500 [DataOverHttps](INFO): Received command: schedule resume;restart logs 2025-02-20T05:42:17.587Z,1740030137.587 [CommandExec](IMPORTANT): got command schedule resume