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