2025-08-26T21:32:57.475Z,1756243977.475 [CommandExec](IMPORTANT): got command restart logs
2025-08-26T21:33:12.486Z,1756243992.486 [DataOverHttps](IMPORTANT): SBD MTMSN=20250826T213308
2025-08-26T21:33:20.244Z,1756244000.244 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.MissionTimeout 120 min;set transit.NeedCommsTime 20 min;set transit.Latitude 36.771341 degree;set transit.Longitude -121.863349 degree;run
2025-08-26T21:33:20.263Z,1756244000.263 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2025-08-26T21:33:20.265Z,1756244000.265 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2025-08-26T21:33:20.265Z,1756244000.265 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2025-08-26T21:33:20.270Z,1756244000.270 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2025-08-26T21:33:20.998Z,1756244000.998 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2025-08-26T21:33:21.004Z,1756244001.004 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2025-08-26T21:33:21.007Z,1756244001.007 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2025-08-26T21:33:21.010Z,1756244001.010 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2025-08-26T21:33:21.029Z,1756244001.029 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2025-08-26T21:33:21.031Z,1756244001.031 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2025-08-26T21:33:21.039Z,1756244001.039 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2025-08-26T21:33:21.050Z,1756244001.050 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2025-08-26T21:33:21.053Z,1756244001.053 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2025-08-26T21:33:21.060Z,1756244001.060 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2025-08-26T21:33:21.063Z,1756244001.063 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2025-08-26T21:33:21.070Z,1756244001.070 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2025-08-26T21:33:21.077Z,1756244001.077 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2025-08-26T21:33:21.079Z,1756244001.079 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2025-08-26T21:33:21.116Z,1756244001.116 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-08-26T21:33:21.116Z,1756244001.116 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-08-26T21:33:21.118Z,1756244001.118 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-08-26T21:33:21.480Z,1756244001.480 [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-08-26T21:33:21.486Z,1756244001.486 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2025-08-26T21:33:21.526Z,1756244001.526 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2025-08-26T21:33:21.533Z,1756244001.533 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-08-26T21:33:21.540Z,1756244001.540 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2025-08-26T21:33:21.543Z,1756244001.543 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-08-26T21:33:21.550Z,1756244001.550 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2025-08-26T21:33:21.569Z,1756244001.569 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2025-08-26T21:33:21.580Z,1756244001.580 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2025-08-26T21:33:21.595Z,1756244001.595 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-08-26T21:33:21.663Z,1756244001.663 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-08-26T21:33:21.663Z,1756244001.663 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-08-26T21:33:21.672Z,1756244001.672 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-08-26T21:33:21.801Z,1756244001.801 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
1.5
200
2000
2025-08-26T21:33:21.810Z,1756244001.810 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2025-08-26T21:33:21.814Z,1756244001.814 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-08-26T21:33:21.825Z,1756244001.825 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2025-08-26T21:33:21.832Z,1756244001.832 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-08-26T21:33:21.833Z,1756244001.833 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-08-26T21:33:21.849Z,1756244001.849 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-08-26T21:33:21.867Z,1756244001.867 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-08-26T21:33:21.891Z,1756244001.891 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-08-26T21:33:21.891Z,1756244001.891 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-08-26T21:33:21.924Z,1756244001.924 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-08-26T21:33:21.998Z,1756244001.998 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-08-26T21:33:22.007Z,1756244002.007 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2025-08-26T21:33:22.013Z,1756244002.013 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-08-26T21:33:22.020Z,1756244002.020 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-08-26T21:33:22.021Z,1756244002.021 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-08-26T21:33:22.022Z,1756244002.022 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-08-26T21:33:22.241Z,1756244002.241 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-08-26T21:33:22.251Z,1756244002.251 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2025-08-26T21:33:22.254Z,1756244002.254 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2025-08-26T21:33:22.265Z,1756244002.265 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2025-08-26T21:33:22.267Z,1756244002.267 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2025-08-26T21:33:22.275Z,1756244002.275 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2025-08-26T21:33:22.290Z,1756244002.290 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-08-26T21:33:22.372Z,1756244002.372 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-08-26T21:33:22.383Z,1756244002.383 [transit:MassHold.Pitch](DEBUG): Construct.
2025-08-26T21:33:22.419Z,1756244002.419 [transit:Transit:A.Pitch](DEBUG): Construct.
2025-08-26T21:33:22.452Z,1756244002.452 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2025-08-26T21:33:22.455Z,1756244002.455 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-08-26T21:33:22.498Z,1756244002.498 [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 transit {
"""
Vehicle dives immediately with no comms/GPS and transits to desired waypoint and can be commanded to use/hold
mass or buoyancy.
"""
arguments {
MissionTimeout = 60 minute
"""
Maximum duration of mission
"""
NeedCommsTime = 30 minute
"""
How often to surface for commumications
"""
Latitude = NaN degree
"""
Latitude of waypoint to seek. If set to NaN, uses latitude at mission
initialization.
"""
Longitude = NaN degree
"""
Longitude of waypoint to seek. If set to NaN, uses longitude at mission
initialization.
"""
Northings = 0 meter
"""
Northward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Eastings = 0 meter
"""
Eastward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Depth = 10 meter
"""
Depth of flat and level flight during the mission.
"""
Speed = 1 meter_per_second
"""
Speed of vehicle (relative to water) during the mission.
"""
MaxDepth = 20 meter
"""
Maximum depth for the entire mission.
"""
MinOffshore = 1 kilometer
"""
Minimum distance offshore for the entire mission.
"""
MinAltitude = 7 meter
"""
Minimum height above the sea floor for the entire mission.
"""
MassHold = true
"""
Set to True in order to hold mass at default position, False to allow
mass to run on its own.
"""
BuoyancyHold = true
"""
Set to True in order to hold buoyancy at neutral position, False to
allow buoyancy to run on its own.
"""
}
timeout duration=MissionTimeout
insert id="NeedComms" Insert/NeedComms.tl
assign in sequence NeedComms:DiveInterval = NeedCommsTime
assign in sequence NeedComms:WaitForPitchUp = 1 second
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MinAltitude = MinAltitude
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
insert Insert/BackseatDriver.tl
insert Insert/PowerOnly.tl
behavior Guidance:Buoyancy id="BuoyancyHold" {
run while ( BuoyancyHold )
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch id="MassHold" {
run while ( MassHold )
set massPosition = Control:VerticalControl.massDefault
}
aggregate Transit {
run in sequence
behavior Guidance:Pitch {
run in parallel
set depth = Depth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = Speed
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
set northingsDelta = Northings
set eastingsDelta = Eastings
}
call id="PhoneHome" priorityHere=false refId="NeedComms"
}
}
2025-08-26T21:33:22.498Z,1756244002.498 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2025-08-26T21:33:32.288Z,1756244012.288 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:33:34.509Z,1756244014.509 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 120 minute
2025-08-26T21:33:34.510Z,1756244014.510 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 20 minute
2025-08-26T21:33:34.511Z,1756244014.511 [CommandExec](IMPORTANT): got command set transit.Latitude 36.771341 degree
2025-08-26T21:33:34.514Z,1756244014.514 [CommandExec](IMPORTANT): got command set transit.Longitude -121.863349 degree
2025-08-26T21:33:34.514Z,1756244014.514 [CommandExec](IMPORTANT): got command run
2025-08-26T21:33:34.516Z,1756244014.516 [CommandExec](IMPORTANT): Running
2025-08-26T21:33:34.859Z,1756244014.859 [DefaultWithUndock] Stopped
2025-08-26T21:33:34.859Z,1756244014.859 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2025-08-26T21:33:34.859Z,1756244014.859 [DefaultWithUndock:SurfaceDefault] Stopped
2025-08-26T21:33:34.859Z,1756244014.859 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2025-08-26T21:33:34.859Z,1756244014.859 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped
2025-08-26T21:33:34.859Z,1756244014.859 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-26T21:33:34.859Z,1756244014.859 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-08-26T21:33:34.859Z,1756244014.859 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-08-26T21:33:34.860Z,1756244014.860 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-08-26T21:33:34.860Z,1756244014.860 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-26T21:33:34.860Z,1756244014.860 [MissionManager](IMPORTANT): Started mission transit
2025-08-26T21:33:34.860Z,1756244014.860 [transit] Running Loop=1
2025-08-26T21:33:34.860Z,1756244014.860 [transit](DEBUG): Aggregate::initialize transit
2025-08-26T21:33:34.860Z,1756244014.860 [transit:StandardEnvelopes] Running Loop=1
2025-08-26T21:33:34.861Z,1756244014.861 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2025-08-26T21:33:34.861Z,1756244014.861 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-08-26T21:33:34.861Z,1756244014.861 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-08-26T21:33:34.861Z,1756244014.861 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-08-26T21:33:34.861Z,1756244014.861 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-08-26T21:33:34.861Z,1756244014.861 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-08-26T21:33:34.861Z,1756244014.861 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-08-26T21:33:34.861Z,1756244014.861 [transit:BackseatDriver] Running Loop=1
2025-08-26T21:33:34.862Z,1756244014.862 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2025-08-26T21:33:34.862Z,1756244014.862 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-08-26T21:33:34.862Z,1756244014.862 [transit:PowerOnly] Running Loop=1
2025-08-26T21:33:34.862Z,1756244014.862 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2025-08-26T21:33:34.862Z,1756244014.862 [transit:PowerOnly:A] Running Loop=1
2025-08-26T21:33:34.862Z,1756244014.862 [transit:PowerOnly:B] Running Loop=1
2025-08-26T21:33:34.862Z,1756244014.862 [transit:PowerOnly:C] Running Loop=1
2025-08-26T21:33:34.862Z,1756244014.862 [transit:PowerOnly:D] Running Loop=1
2025-08-26T21:33:34.862Z,1756244014.862 [transit:PowerOnly:E.Wait] Running Loop=1
2025-08-26T21:33:34.862Z,1756244014.862 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-08-26T21:33:34.862Z,1756244014.862 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-26T21:33:34.863Z,1756244014.863 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-26T21:33:34.863Z,1756244014.863 [transit:MassHold.Pitch] Running Loop=1
2025-08-26T21:33:34.863Z,1756244014.863 [transit:MassHold.Pitch](DEBUG): Initialize.
2025-08-26T21:33:34.863Z,1756244014.863 [transit:B] Running Loop=1
2025-08-26T21:33:34.864Z,1756244014.864 [transit:MassHold.Pitch] Running Loop=1
2025-08-26T21:33:34.864Z,1756244014.864 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-26T21:33:34.864Z,1756244014.864 [transit:PowerOnly] Running Loop=1
2025-08-26T21:33:34.865Z,1756244014.865 [transit:PowerOnly:D] Stopped
2025-08-26T21:33:34.865Z,1756244014.865 [transit:PowerOnly:C] Stopped
2025-08-26T21:33:34.865Z,1756244014.865 [transit:PowerOnly:B] Stopped
2025-08-26T21:33:34.865Z,1756244014.865 [transit:PowerOnly:A] Stopped
2025-08-26T21:33:34.865Z,1756244014.865 [transit:BackseatDriver] Running Loop=1
2025-08-26T21:33:34.865Z,1756244014.865 [transit:StandardEnvelopes] Running Loop=1
2025-08-26T21:33:34.866Z,1756244014.866 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-08-26T21:33:34.866Z,1756244014.866 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-08-26T21:33:34.867Z,1756244014.867 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-08-26T21:33:34.867Z,1756244014.867 [transit:B] Stopped
2025-08-26T21:33:34.868Z,1756244014.868 [transit:C] Running Loop=1
2025-08-26T21:33:35.278Z,1756244015.278 [transit:C] Stopped
2025-08-26T21:33:35.278Z,1756244015.278 [transit:E] Running Loop=1
2025-08-26T21:33:35.705Z,1756244015.705 [transit:E] Stopped
2025-08-26T21:33:35.705Z,1756244015.705 [transit:F] Running Loop=1
2025-08-26T21:33:36.062Z,1756244016.062 [transit:F] Stopped
2025-08-26T21:33:36.062Z,1756244016.062 [transit:G] Running Loop=1
2025-08-26T21:33:36.474Z,1756244016.474 [transit:G] Stopped
2025-08-26T21:33:36.475Z,1756244016.475 [transit:Transit] Running Loop=1
2025-08-26T21:33:36.475Z,1756244016.475 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2025-08-26T21:33:36.475Z,1756244016.475 [transit:Transit:A.Pitch] Running Loop=1
2025-08-26T21:33:36.475Z,1756244016.475 [transit:Transit:A.Pitch](DEBUG): Initialize.
2025-08-26T21:33:36.475Z,1756244016.475 [transit:Transit:B.SetSpeed] Running Loop=1
2025-08-26T21:33:36.475Z,1756244016.475 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2025-08-26T21:33:36.475Z,1756244016.475 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2025-08-26T21:33:36.476Z,1756244016.476 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-08-26T21:33:36.477Z,1756244016.477 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.771339,-121.863350
2025-08-26T21:33:36.888Z,1756244016.888 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.771339,-121.863350
2025-08-26T21:33:36.889Z,1756244016.889 [transit:Transit:B.SetSpeed] Running Loop=1
2025-08-26T21:33:36.889Z,1756244016.889 [transit:Transit:A.Pitch] Running Loop=1
2025-08-26T21:33:36.898Z,1756244016.898 [HorizontalControl](DEBUG): kpHeading = 0.400000
2025-08-26T21:33:36.898Z,1756244016.898 [HorizontalControl](DEBUG): kiHeading = 0.001000
2025-08-26T21:33:36.898Z,1756244016.898 [HorizontalControl](DEBUG): kdHeading = 0.050000
2025-08-26T21:33:55.048Z,1756244035.048 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:34:14.592Z,1756244054.592 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:34:45.240Z,1756244085.240 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-08-26T21:34:46.245Z,1756244086.245 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:35:14.365Z,1756244114.365 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.772865
2025-08-26T21:35:15.369Z,1756244115.369 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:35:45.496Z,1756244145.496 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:35:46.501Z,1756244146.501 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:35:47.652Z,1756244147.652 [Radio_Surface](INFO): Powering down
2025-08-26T21:36:16.620Z,1756244176.620 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:36:17.624Z,1756244177.624 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-26T21:36:17.624Z,1756244177.624 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:36:47.744Z,1756244207.744 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:36:48.748Z,1756244208.748 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:37:18.884Z,1756244238.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:37:19.888Z,1756244239.888 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:37:50.008Z,1756244270.008 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:37:51.012Z,1756244271.012 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:38:21.132Z,1756244301.132 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:38:22.136Z,1756244302.136 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:38:52.260Z,1756244332.260 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:38:53.264Z,1756244333.264 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:39:23.386Z,1756244363.386 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:39:24.389Z,1756244364.389 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:39:54.524Z,1756244394.524 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:39:55.528Z,1756244395.528 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:40:25.648Z,1756244425.648 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:40:26.652Z,1756244426.652 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:40:56.793Z,1756244456.793 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:40:57.796Z,1756244457.796 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:41:27.932Z,1756244487.932 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:41:28.936Z,1756244488.936 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:41:59.056Z,1756244519.056 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:42:00.060Z,1756244520.060 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:42:30.196Z,1756244550.196 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:42:31.200Z,1756244551.200 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:43:01.320Z,1756244581.320 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:43:02.324Z,1756244582.324 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:43:28.716Z,1756244608.716 [CBIT](INFO): Clearing failed state for component DropWeight
2025-08-26T21:43:28.716Z,1756244608.716 [DropWeight] No Fault, FailCount= 1
2025-08-26T21:43:32.447Z,1756244612.447 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:43:33.448Z,1756244613.448 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:44:03.568Z,1756244643.568 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:44:04.572Z,1756244644.572 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:44:34.692Z,1756244674.692 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:44:35.696Z,1756244675.696 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:45:05.816Z,1756244705.816 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:45:06.820Z,1756244706.820 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:45:36.940Z,1756244736.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:45:37.944Z,1756244737.944 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:46:08.064Z,1756244768.064 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:46:09.068Z,1756244769.068 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:46:39.188Z,1756244799.188 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:46:40.193Z,1756244800.193 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:47:10.348Z,1756244830.348 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:47:11.353Z,1756244831.353 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:47:41.476Z,1756244861.476 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:47:42.480Z,1756244862.480 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:48:12.606Z,1756244892.606 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:48:13.608Z,1756244893.608 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:48:30.439Z,1756244910.439 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2025-08-26T21:48:43.728Z,1756244923.728 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:48:44.732Z,1756244924.732 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:49:14.852Z,1756244954.852 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:49:15.856Z,1756244955.856 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:49:45.976Z,1756244985.976 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:49:46.980Z,1756244986.980 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:50:17.100Z,1756245017.100 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:50:18.104Z,1756245018.104 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:50:48.228Z,1756245048.228 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:50:49.232Z,1756245049.232 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:50:55.467Z,1756245055.467 [transit:NeedComms] Running Loop=1
2025-08-26T21:50:55.467Z,1756245055.467 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2025-08-26T21:50:55.467Z,1756245055.467 [transit:NeedComms:B.GoToSurface] Running Loop=1
2025-08-26T21:50:55.467Z,1756245055.467 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-26T21:50:55.467Z,1756245055.467 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-26T21:50:55.468Z,1756245055.468 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-26T21:50:55.468Z,1756245055.468 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-26T21:50:55.469Z,1756245055.469 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-26T21:50:55.469Z,1756245055.469 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-26T21:50:55.469Z,1756245055.469 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-26T21:50:55.470Z,1756245055.470 [transit:NeedComms:A] Running Loop=1
2025-08-26T21:50:55.488Z,1756245055.488 [transit:NeedComms:A](INFO): last time_fix was: 1756243854.000000 second since 1970/01/01T00:00:00Z
2025-08-26T21:50:55.488Z,1756245055.488 [transit:NeedComms:A] Stopped
2025-08-26T21:51:19.353Z,1756245079.353 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:51:20.357Z,1756245080.357 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:51:34.158Z,1756245094.158 [transit:NeedComms:C] Running Loop=1
2025-08-26T21:51:34.577Z,1756245094.577 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-08-26T21:51:34.788Z,1756245094.788 [Radio_Surface](INFO): Powering up
2025-08-26T21:51:40.040Z,1756245100.040 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2025-08-26T21:51:40.041Z,1756245100.041 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0
2025-08-26T21:51:49.897Z,1756245109.897 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=49624, MT Status=1, MTMSN=2700
2025-08-26T21:51:49.897Z,1756245109.897 [NAL9602](INFO): Data available in MT queue
2025-08-26T21:51:50.476Z,1756245110.476 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:51:50.560Z,1756245110.560 [NAL9602](INFO): Received command: load Transport/transit.tl;set transit.MissionTimeout 120 min;set transit.NeedCommsTime 20 min;set transit.Latitude 36.771341 degree;set transit.Longitude -121.863349 degree;run
2025-08-26T21:51:50.617Z,1756245110.617 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2025-08-26T21:51:50.617Z,1756245110.617 [MissionManager](INFO): Uninitializing Mission transit
2025-08-26T21:51:50.617Z,1756245110.617 [transit] Stopped
2025-08-26T21:51:50.618Z,1756245110.618 [transit](DEBUG): Aggregate::uninitialize transit
2025-08-26T21:51:50.618Z,1756245110.618 [transit:NeedComms] Stopped
2025-08-26T21:51:50.618Z,1756245110.618 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms
2025-08-26T21:51:50.618Z,1756245110.618 [transit:NeedComms:B.GoToSurface] Stopped
2025-08-26T21:51:50.618Z,1756245110.618 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-26T21:51:50.618Z,1756245110.618 [transit:NeedComms:C] Stopped
2025-08-26T21:51:50.618Z,1756245110.618 [transit:StandardEnvelopes] Stopped
2025-08-26T21:51:50.618Z,1756245110.618 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2025-08-26T21:51:50.618Z,1756245110.618 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-08-26T21:51:50.618Z,1756245110.618 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-08-26T21:51:50.618Z,1756245110.618 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-08-26T21:51:50.618Z,1756245110.618 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-08-26T21:51:50.618Z,1756245110.618 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-08-26T21:51:50.618Z,1756245110.618 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-08-26T21:51:50.619Z,1756245110.619 [transit:BackseatDriver] Stopped
2025-08-26T21:51:50.619Z,1756245110.619 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2025-08-26T21:51:50.619Z,1756245110.619 [transit:BackseatDriver:A.BackseatDriver] Stopped
2025-08-26T21:51:50.619Z,1756245110.619 [transit:PowerOnly] Stopped
2025-08-26T21:51:50.619Z,1756245110.619 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2025-08-26T21:51:50.619Z,1756245110.619 [transit:PowerOnly:E.Wait] Stopped
2025-08-26T21:51:50.619Z,1756245110.619 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-08-26T21:51:50.619Z,1756245110.619 [transit:BuoyancyHold.Buoyancy] Stopped
2025-08-26T21:51:50.619Z,1756245110.619 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-26T21:51:50.619Z,1756245110.619 [transit:MassHold.Pitch] Stopped
2025-08-26T21:51:50.619Z,1756245110.619 [transit:Transit] Stopped
2025-08-26T21:51:50.619Z,1756245110.619 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2025-08-26T21:51:50.619Z,1756245110.619 [transit:Transit:A.Pitch] Stopped
2025-08-26T21:51:50.640Z,1756245110.640 [transit:Transit:B.SetSpeed] Stopped
2025-08-26T21:51:50.640Z,1756245110.640 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2025-08-26T21:51:50.640Z,1756245110.640 [transit:Transit:Wpt1.Waypoint] Stopped
2025-08-26T21:51:50.640Z,1756245110.640 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-08-26T21:51:50.692Z,1756245110.692 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2025-08-26T21:51:50.692Z,1756245110.692 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2025-08-26T21:51:50.694Z,1756245110.694 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2025-08-26T21:51:51.480Z,1756245111.480 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-26T21:51:51.480Z,1756245111.480 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:51:51.574Z,1756245111.574 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2025-08-26T21:51:51.583Z,1756245111.583 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2025-08-26T21:51:51.590Z,1756245111.590 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2025-08-26T21:51:51.596Z,1756245111.596 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2025-08-26T21:51:51.598Z,1756245111.598 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2025-08-26T21:51:51.604Z,1756245111.604 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2025-08-26T21:51:51.606Z,1756245111.606 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2025-08-26T21:51:51.613Z,1756245111.613 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2025-08-26T21:51:51.615Z,1756245111.615 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2025-08-26T21:51:51.641Z,1756245111.641 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2025-08-26T21:51:51.652Z,1756245111.652 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2025-08-26T21:51:51.654Z,1756245111.654 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2025-08-26T21:51:51.672Z,1756245111.672 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2025-08-26T21:51:51.674Z,1756245111.674 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2025-08-26T21:51:51.674Z,1756245111.674 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-08-26T21:51:51.675Z,1756245111.675 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-08-26T21:51:51.684Z,1756245111.684 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-08-26T21:51:52.041Z,1756245112.041 [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-08-26T21:51:52.046Z,1756245112.046 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2025-08-26T21:51:52.048Z,1756245112.048 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2025-08-26T21:51:52.050Z,1756245112.050 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-08-26T21:51:52.068Z,1756245112.068 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2025-08-26T21:51:52.071Z,1756245112.071 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-08-26T21:51:52.077Z,1756245112.077 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2025-08-26T21:51:52.079Z,1756245112.079 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2025-08-26T21:51:52.100Z,1756245112.100 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2025-08-26T21:51:52.127Z,1756245112.127 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-08-26T21:51:52.159Z,1756245112.159 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-08-26T21:51:52.159Z,1756245112.159 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-08-26T21:51:52.162Z,1756245112.162 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-08-26T21:51:52.229Z,1756245112.229 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
1.5
200
2000
2025-08-26T21:51:52.233Z,1756245112.233 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2025-08-26T21:51:52.235Z,1756245112.235 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 1.500000 m
2025-08-26T21:51:52.238Z,1756245112.238 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2025-08-26T21:51:52.240Z,1756245112.240 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-08-26T21:51:52.241Z,1756245112.241 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-08-26T21:51:52.246Z,1756245112.246 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-08-26T21:51:52.253Z,1756245112.253 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-08-26T21:51:52.288Z,1756245112.288 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-08-26T21:51:52.288Z,1756245112.288 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-08-26T21:51:52.289Z,1756245112.289 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-08-26T21:51:52.373Z,1756245112.373 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-08-26T21:51:52.394Z,1756245112.394 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2025-08-26T21:51:52.415Z,1756245112.415 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-08-26T21:51:52.434Z,1756245112.434 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-08-26T21:51:52.434Z,1756245112.434 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-08-26T21:51:52.448Z,1756245112.448 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-08-26T21:51:52.582Z,1756245112.582 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-08-26T21:51:52.586Z,1756245112.586 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2025-08-26T21:51:52.588Z,1756245112.588 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2025-08-26T21:51:52.590Z,1756245112.590 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2025-08-26T21:51:52.591Z,1756245112.591 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2025-08-26T21:51:52.594Z,1756245112.594 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2025-08-26T21:51:52.601Z,1756245112.601 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-08-26T21:51:52.603Z,1756245112.603 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-08-26T21:51:52.606Z,1756245112.606 [transit:MassHold.Pitch](DEBUG): Construct.
2025-08-26T21:51:52.612Z,1756245112.612 [transit:Transit:A.Pitch](DEBUG): Construct.
2025-08-26T21:51:52.616Z,1756245112.616 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2025-08-26T21:51:52.618Z,1756245112.618 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-08-26T21:51:52.671Z,1756245112.671 [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 transit {
"""
Vehicle dives immediately with no comms/GPS and transits to desired waypoint and can be commanded to use/hold
mass or buoyancy.
"""
arguments {
MissionTimeout = 60 minute
"""
Maximum duration of mission
"""
NeedCommsTime = 30 minute
"""
How often to surface for commumications
"""
Latitude = NaN degree
"""
Latitude of waypoint to seek. If set to NaN, uses latitude at mission
initialization.
"""
Longitude = NaN degree
"""
Longitude of waypoint to seek. If set to NaN, uses longitude at mission
initialization.
"""
Northings = 0 meter
"""
Northward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Eastings = 0 meter
"""
Eastward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Depth = 10 meter
"""
Depth of flat and level flight during the mission.
"""
Speed = 1 meter_per_second
"""
Speed of vehicle (relative to water) during the mission.
"""
MaxDepth = 20 meter
"""
Maximum depth for the entire mission.
"""
MinOffshore = 1 kilometer
"""
Minimum distance offshore for the entire mission.
"""
MinAltitude = 7 meter
"""
Minimum height above the sea floor for the entire mission.
"""
MassHold = true
"""
Set to True in order to hold mass at default position, False to allow
mass to run on its own.
"""
BuoyancyHold = true
"""
Set to True in order to hold buoyancy at neutral position, False to
allow buoyancy to run on its own.
"""
}
timeout duration=MissionTimeout
insert id="NeedComms" Insert/NeedComms.tl
assign in sequence NeedComms:DiveInterval = NeedCommsTime
assign in sequence NeedComms:WaitForPitchUp = 1 second
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MinAltitude = MinAltitude
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
insert Insert/BackseatDriver.tl
insert Insert/PowerOnly.tl
behavior Guidance:Buoyancy id="BuoyancyHold" {
run while ( BuoyancyHold )
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch id="MassHold" {
run while ( MassHold )
set massPosition = Control:VerticalControl.massDefault
}
aggregate Transit {
run in sequence
behavior Guidance:Pitch {
run in parallel
set depth = Depth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = Speed
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
set northingsDelta = Northings
set eastingsDelta = Eastings
}
call id="PhoneHome" priorityHere=false refId="NeedComms"
}
}
2025-08-26T21:51:52.672Z,1756245112.672 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2025-08-26T21:51:52.802Z,1756245112.802 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2025-08-26T21:51:52.802Z,1756245112.802 [DefaultWithUndock] Running Loop=1
2025-08-26T21:51:52.802Z,1756245112.802 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2025-08-26T21:51:52.802Z,1756245112.802 [DefaultWithUndock:A.Wait] Running Loop=1
2025-08-26T21:51:52.802Z,1756245112.802 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2025-08-26T21:52:03.903Z,1756245123.903 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 120 minute
2025-08-26T21:52:03.904Z,1756245123.904 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 20 minute
2025-08-26T21:52:03.905Z,1756245123.905 [CommandExec](IMPORTANT): got command set transit.Latitude 36.771341 degree
2025-08-26T21:52:03.905Z,1756245123.905 [CommandExec](IMPORTANT): got command set transit.Longitude -121.863349 degree
2025-08-26T21:52:03.906Z,1756245123.906 [CommandExec](IMPORTANT): got command run
2025-08-26T21:52:03.908Z,1756245123.908 [CommandExec](IMPORTANT): Running
2025-08-26T21:52:04.067Z,1756245124.067 [DefaultWithUndock] Stopped
2025-08-26T21:52:04.067Z,1756245124.067 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2025-08-26T21:52:04.068Z,1756245124.068 [DefaultWithUndock:A.Wait] Stopped
2025-08-26T21:52:04.068Z,1756245124.068 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2025-08-26T21:52:04.068Z,1756245124.068 [MissionManager](IMPORTANT): Started mission transit
2025-08-26T21:52:04.068Z,1756245124.068 [transit] Running Loop=1
2025-08-26T21:52:04.068Z,1756245124.068 [transit](DEBUG): Aggregate::initialize transit
2025-08-26T21:52:04.068Z,1756245124.068 [transit:StandardEnvelopes] Running Loop=1
2025-08-26T21:52:04.069Z,1756245124.069 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2025-08-26T21:52:04.069Z,1756245124.069 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-08-26T21:52:04.069Z,1756245124.069 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-08-26T21:52:04.069Z,1756245124.069 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-08-26T21:52:04.069Z,1756245124.069 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-08-26T21:52:04.069Z,1756245124.069 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-08-26T21:52:04.069Z,1756245124.069 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-08-26T21:52:04.069Z,1756245124.069 [transit:BackseatDriver] Running Loop=1
2025-08-26T21:52:04.069Z,1756245124.069 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2025-08-26T21:52:04.070Z,1756245124.070 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-08-26T21:52:04.070Z,1756245124.070 [transit:PowerOnly] Running Loop=1
2025-08-26T21:52:04.070Z,1756245124.070 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2025-08-26T21:52:04.070Z,1756245124.070 [transit:PowerOnly:A] Running Loop=1
2025-08-26T21:52:04.070Z,1756245124.070 [transit:PowerOnly:B] Running Loop=1
2025-08-26T21:52:04.070Z,1756245124.070 [transit:PowerOnly:C] Running Loop=1
2025-08-26T21:52:04.070Z,1756245124.070 [transit:PowerOnly:D] Running Loop=1
2025-08-26T21:52:04.070Z,1756245124.070 [transit:PowerOnly:E.Wait] Running Loop=1
2025-08-26T21:52:04.070Z,1756245124.070 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-08-26T21:52:04.070Z,1756245124.070 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-26T21:52:04.070Z,1756245124.070 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-08-26T21:52:04.070Z,1756245124.070 [transit:MassHold.Pitch] Running Loop=1
2025-08-26T21:52:04.070Z,1756245124.070 [transit:MassHold.Pitch](DEBUG): Initialize.
2025-08-26T21:52:04.071Z,1756245124.071 [transit:B] Running Loop=1
2025-08-26T21:52:04.071Z,1756245124.071 [transit:MassHold.Pitch] Running Loop=1
2025-08-26T21:52:04.072Z,1756245124.072 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-08-26T21:52:04.072Z,1756245124.072 [transit:PowerOnly] Running Loop=1
2025-08-26T21:52:04.072Z,1756245124.072 [transit:PowerOnly:D] Stopped
2025-08-26T21:52:04.072Z,1756245124.072 [transit:PowerOnly:C] Stopped
2025-08-26T21:52:04.073Z,1756245124.073 [transit:PowerOnly:B] Stopped
2025-08-26T21:52:04.073Z,1756245124.073 [transit:PowerOnly:A] Stopped
2025-08-26T21:52:04.073Z,1756245124.073 [transit:BackseatDriver] Running Loop=1
2025-08-26T21:52:04.073Z,1756245124.073 [transit:StandardEnvelopes] Running Loop=1
2025-08-26T21:52:04.073Z,1756245124.073 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-08-26T21:52:04.073Z,1756245124.073 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-08-26T21:52:04.074Z,1756245124.074 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-08-26T21:52:04.074Z,1756245124.074 [transit:B] Stopped
2025-08-26T21:52:04.074Z,1756245124.074 [transit:C] Running Loop=1
2025-08-26T21:52:04.493Z,1756245124.493 [transit:C] Stopped
2025-08-26T21:52:04.493Z,1756245124.493 [transit:E] Running Loop=1
2025-08-26T21:52:04.914Z,1756245124.914 [transit:E] Stopped
2025-08-26T21:52:04.914Z,1756245124.914 [transit:F] Running Loop=1
2025-08-26T21:52:04.915Z,1756245124.915 [transit:NeedComms] Running Loop=1
2025-08-26T21:52:04.915Z,1756245124.915 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2025-08-26T21:52:04.915Z,1756245124.915 [transit:NeedComms:B.GoToSurface] Running Loop=1
2025-08-26T21:52:04.916Z,1756245124.916 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-26T21:52:04.916Z,1756245124.916 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-08-26T21:52:04.917Z,1756245124.917 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-08-26T21:52:04.917Z,1756245124.917 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-08-26T21:52:04.917Z,1756245124.917 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-08-26T21:52:04.918Z,1756245124.918 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-26T21:52:04.918Z,1756245124.918 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-26T21:52:04.918Z,1756245124.918 [transit:NeedComms:A] Running Loop=1
2025-08-26T21:52:04.919Z,1756245124.919 [transit:NeedComms:A](INFO): last time_fix was: 1756243854.000000 second since 1970/01/01T00:00:00Z
2025-08-26T21:52:04.919Z,1756245124.919 [transit:NeedComms:A] Stopped
2025-08-26T21:52:05.293Z,1756245125.293 [transit:F] Stopped
2025-08-26T21:52:05.294Z,1756245125.294 [transit:G] Running Loop=1
2025-08-26T21:52:05.295Z,1756245125.295 [transit:NeedComms:C] Running Loop=1
2025-08-26T21:52:05.685Z,1756245125.685 [transit:G] Stopped
2025-08-26T21:52:05.685Z,1756245125.685 [transit:Transit] Running Loop=1
2025-08-26T21:52:05.685Z,1756245125.685 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2025-08-26T21:52:05.686Z,1756245125.686 [transit:Transit:A.Pitch] Running Loop=1
2025-08-26T21:52:05.686Z,1756245125.686 [transit:Transit:A.Pitch](DEBUG): Initialize.
2025-08-26T21:52:05.686Z,1756245125.686 [transit:Transit:B.SetSpeed] Running Loop=1
2025-08-26T21:52:05.686Z,1756245125.686 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2025-08-26T21:52:05.686Z,1756245125.686 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2025-08-26T21:52:05.686Z,1756245125.686 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-08-26T21:52:05.687Z,1756245125.687 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.771339,-121.863350
2025-08-26T21:52:05.689Z,1756245125.689 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-08-26T21:52:06.081Z,1756245126.081 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.771339,-121.863350
2025-08-26T21:52:06.081Z,1756245126.081 [transit:Transit:B.SetSpeed] Running Loop=1
2025-08-26T21:52:06.081Z,1756245126.081 [transit:Transit:A.Pitch] Running Loop=1
2025-08-26T21:52:07.267Z,1756245127.267 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215207.00,A,3646.06684,N,12151.16928,W,0.816,279.70,260825,,,A*79
2025-08-26T21:52:07.269Z,1756245127.269 [NAL9602](INFO): GPS fix at 20250826T215207: (36.767781, -121.852821)
2025-08-26T21:52:07.299Z,1756245127.299 [UniversalFixResidualReporter](INFO): Fix residual: 9.2 %DT, over the last 1161.3 m. Residual distance 106.9 m at bearing 18.0 degrees. Fix at (36.7678, -121.8528) with 1110.3 m made good.
2025-08-26T21:52:07.309Z,1756245127.309 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.771339,-121.863350
2025-08-26T21:52:07.315Z,1756245127.315 [transit:NeedComms:C] Stopped
2025-08-26T21:52:07.315Z,1756245127.315 [transit:NeedComms:D] Running Loop=1
2025-08-26T21:52:07.713Z,1756245127.713 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-08-26T21:52:21.616Z,1756245141.616 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:52:22.620Z,1756245142.620 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:52:32.033Z,1756245152.033 [NAL9602](INFO): SBD MO Status=1, MOMSN=49625, MT Status=0, MTMSN=0
2025-08-26T21:52:32.080Z,1756245152.080 [NAL9602](INFO): Sent 39 bytes from file Logs/20250826T195810/Courier0046.lzma
2025-08-26T21:52:32.080Z,1756245152.080 [NAL9602](INFO): Packets left to send: 0
2025-08-26T21:52:47.376Z,1756245167.376 [NAL9602](INFO): SBD MO Status=1, MOMSN=49626, MT Status=0, MTMSN=0
2025-08-26T21:52:47.426Z,1756245167.426 [NAL9602](INFO): Sent 326 bytes from file Logs/20250826T213257/Courier0000.lzma
2025-08-26T21:52:47.427Z,1756245167.427 [NAL9602](INFO): Packets left to send: 0
2025-08-26T21:52:52.740Z,1756245172.740 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:52:53.744Z,1756245173.744 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:53:00.317Z,1756245180.317 [NAL9602](INFO): SBD MO Status=1, MOMSN=49627, MT Status=0, MTMSN=0
2025-08-26T21:53:00.369Z,1756245180.369 [NAL9602](INFO): Sent 104 bytes from file Logs/20250826T195810/Express0047.lzma
2025-08-26T21:53:00.369Z,1756245180.369 [NAL9602](INFO): Packets left to send: 0
2025-08-26T21:53:18.753Z,1756245198.753 [NAL9602](INFO): SBD MO Status=2, MOMSN=49628, MT Status=2, MTMSN=0
2025-08-26T21:53:18.753Z,1756245198.753 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-26T21:53:23.877Z,1756245203.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:53:24.882Z,1756245204.882 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:53:44.742Z,1756245224.742 [NAL9602](INFO): SBD MO Status=2, MOMSN=49628, MT Status=2, MTMSN=0
2025-08-26T21:53:44.742Z,1756245224.742 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-26T21:53:55.012Z,1756245235.012 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:53:56.016Z,1756245236.016 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:54:10.030Z,1756245250.030 [NAL9602](INFO): SBD MO Status=2, MOMSN=49628, MT Status=2, MTMSN=0
2025-08-26T21:54:10.031Z,1756245250.031 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-26T21:54:26.136Z,1756245266.136 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:54:27.140Z,1756245267.140 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:54:37.221Z,1756245277.221 [DAT](INFO): DAT read: user:6>Rx Time:21:54:35.7791
2025-08-26T21:54:37.222Z,1756245277.222 [DAT](INFO): Rx dataTimestamp_ set to:1756245277.221385
2025-08-26T21:54:37.222Z,1756245277.222 [DAT](INFO): DAT read: $Low SNR acquisition
2025-08-26T21:54:37.223Z,1756245277.223 [DAT](INFO): Received low SNR in chirp
2025-08-26T21:54:57.261Z,1756245297.261 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:54:58.264Z,1756245298.264 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:55:29.391Z,1756245329.391 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:56:00.928Z,1756245360.928 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:56:31.048Z,1756245391.048 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:56:32.052Z,1756245392.052 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:56:37.266Z,1756245397.266 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-08-26T21:57:02.172Z,1756245422.172 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:57:03.176Z,1756245423.176 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:57:33.332Z,1756245453.332 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:57:34.336Z,1756245454.336 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:58:04.492Z,1756245484.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:58:05.496Z,1756245485.496 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:58:07.544Z,1756245487.544 [NAL9602](INFO): SBD MO Status=2, MOMSN=49628, MT Status=2, MTMSN=0
2025-08-26T21:58:07.544Z,1756245487.544 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-26T21:58:35.616Z,1756245515.616 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:58:36.620Z,1756245516.620 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:58:44.441Z,1756245524.441 [NAL9602](INFO): SBD MO Status=1, MOMSN=49628, MT Status=0, MTMSN=0
2025-08-26T21:58:44.489Z,1756245524.489 [NAL9602](INFO): Sent 332 bytes from file Logs/20250826T213257/Express0001.lzma
2025-08-26T21:58:44.489Z,1756245524.489 [NAL9602](INFO): Packets left to send: 2
2025-08-26T21:59:06.742Z,1756245546.742 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:59:07.166Z,1756245547.166 [NAL9602](INFO): SBD MO Status=2, MOMSN=49629, MT Status=2, MTMSN=0
2025-08-26T21:59:07.167Z,1756245547.167 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-26T21:59:07.744Z,1756245547.744 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:59:31.489Z,1756245571.489 [NAL9602](INFO): SBD MO Status=1, MOMSN=49629, MT Status=0, MTMSN=0
2025-08-26T21:59:31.536Z,1756245571.536 [NAL9602](INFO): Sent 332 bytes from file Logs/20250826T213257/Express0001.lzma
2025-08-26T21:59:31.536Z,1756245571.536 [NAL9602](INFO): Packets left to send: 1
2025-08-26T21:59:37.865Z,1756245577.865 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T21:59:38.868Z,1756245578.868 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T21:59:44.439Z,1756245584.439 [NAL9602](INFO): SBD MO Status=1, MOMSN=49630, MT Status=0, MTMSN=0
2025-08-26T21:59:44.488Z,1756245584.488 [NAL9602](INFO): Sent 222 bytes from file Logs/20250826T213257/Express0001.lzma
2025-08-26T21:59:44.488Z,1756245584.488 [NAL9602](INFO): Packets left to send: 0
2025-08-26T22:00:03.021Z,1756245603.021 [NAL9602](INFO): SBD MO Status=0, MOMSN=49631, MT Status=0, MTMSN=0
2025-08-26T22:00:08.988Z,1756245608.988 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:00:09.992Z,1756245609.992 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:00:25.261Z,1756245625.261 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-08-26T22:00:25.264Z,1756245625.264 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-08-26T22:00:29.708Z,1756245629.708 [NAL9602](INFO): SBD MO Status=1, MOMSN=49632, MT Status=0, MTMSN=0
2025-08-26T22:00:29.764Z,1756245629.764 [NAL9602](INFO): Sent 39 bytes from file Logs/20250826T213257/Courier0003.lzma
2025-08-26T22:00:29.764Z,1756245629.764 [NAL9602](INFO): Packets left to send: 0
2025-08-26T22:00:40.124Z,1756245640.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:00:40.950Z,1756245640.950 [NAL9602](INFO): SBD MO Status=1, MOMSN=49633, MT Status=0, MTMSN=0
2025-08-26T22:00:41.012Z,1756245641.012 [NAL9602](INFO): Sent 73 bytes from file Logs/20250826T213257/Express0004.lzma
2025-08-26T22:00:41.012Z,1756245641.012 [NAL9602](INFO): Packets left to send: 0
2025-08-26T22:00:41.128Z,1756245641.128 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:00:52.675Z,1756245652.675 [NAL9602](INFO): SBD MO Status=0, MOMSN=49634, MT Status=0, MTMSN=0
2025-08-26T22:00:52.794Z,1756245652.794 [transit:NeedComms:D] Stopped
2025-08-26T22:00:52.795Z,1756245652.795 [transit:NeedComms:E] Running Loop=1
2025-08-26T22:00:53.104Z,1756245653.104 [transit:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-08-26T22:00:55.103Z,1756245655.103 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220055.00,A,3646.07902,N,12151.22331,W,0.583,1.36,260825,,,D*74
2025-08-26T22:00:55.105Z,1756245655.105 [NAL9602](INFO): GPS fix at 20250826T220055: (36.767984, -121.853722)
2025-08-26T22:00:55.138Z,1756245655.138 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.771339,-121.863350
2025-08-26T22:00:55.145Z,1756245655.145 [transit:NeedComms:E] Stopped
2025-08-26T22:00:55.146Z,1756245655.146 [transit:NeedComms](INFO): Completed transit:NeedComms
2025-08-26T22:00:55.146Z,1756245655.146 [transit:NeedComms] Stopped
2025-08-26T22:00:55.146Z,1756245655.146 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms
2025-08-26T22:00:55.146Z,1756245655.146 [transit:NeedComms:B.GoToSurface] Stopped
2025-08-26T22:00:55.146Z,1756245655.146 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-26T22:01:11.264Z,1756245671.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:01:12.268Z,1756245672.268 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:01:27.450Z,1756245687.450 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-26T22:01:43.405Z,1756245703.405 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:02:14.880Z,1756245734.880 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:02:44.814Z,1756245764.814 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 18.47 mm (1 active estimators).
2025-08-26T22:02:46.020Z,1756245766.020 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:03:16.140Z,1756245796.140 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:03:17.144Z,1756245797.144 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:03:45.372Z,1756245825.372 [Radio_Surface](INFO): Powering down
2025-08-26T22:03:47.265Z,1756245827.265 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:03:48.268Z,1756245828.268 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-26T22:03:48.268Z,1756245828.268 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:04:18.392Z,1756245858.392 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:04:19.392Z,1756245859.392 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:04:49.532Z,1756245889.532 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:04:50.537Z,1756245890.537 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:05:20.656Z,1756245920.656 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:05:21.660Z,1756245921.660 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:05:51.780Z,1756245951.780 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:05:52.784Z,1756245952.784 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:06:22.925Z,1756245982.925 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:06:23.928Z,1756245983.928 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:06:54.056Z,1756246014.056 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:06:55.060Z,1756246015.060 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:07:25.180Z,1756246045.180 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:07:26.184Z,1756246046.184 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:07:56.304Z,1756246076.304 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:07:57.308Z,1756246077.308 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:08:27.432Z,1756246107.432 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:08:28.436Z,1756246108.436 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:08:58.556Z,1756246138.556 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:08:59.561Z,1756246139.561 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:09:29.696Z,1756246169.696 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:09:30.700Z,1756246170.700 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:10:00.821Z,1756246200.821 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:10:01.824Z,1756246201.824 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:10:31.948Z,1756246231.948 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:10:32.953Z,1756246232.953 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:11:03.072Z,1756246263.072 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:11:04.076Z,1756246264.076 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:11:34.196Z,1756246294.196 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:11:35.200Z,1756246295.200 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:12:05.321Z,1756246325.321 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:12:06.324Z,1756246326.324 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:12:36.456Z,1756246356.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:12:37.460Z,1756246357.460 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:13:07.588Z,1756246387.588 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:13:08.592Z,1756246388.592 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:13:30.013Z,1756246410.013 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2025-08-26T22:13:30.013Z,1756246410.013 [DropWeight] Hardware Fault, FailCount= 1
2025-08-26T22:13:30.013Z,1756246410.013 [DropWeight](ERROR): Hardware Fault
2025-08-26T22:13:30.094Z,1756246410.094 [CBIT](INFO): Critical error at 20250826T221330
2025-08-26T22:13:30.094Z,1756246410.094 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-08-26T22:13:30.096Z,1756246410.096 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-08-26T22:13:30.097Z,1756246410.097 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-08-26T22:13:30.481Z,1756246410.481 [MissionManager](INFO): MissionManager is completed.
2025-08-26T22:13:30.481Z,1756246410.481 [MissionManager](INFO): Uninitializing Mission transit
2025-08-26T22:13:30.481Z,1756246410.481 [transit] Stopped
2025-08-26T22:13:30.481Z,1756246410.481 [transit](DEBUG): Aggregate::uninitialize transit
2025-08-26T22:13:30.481Z,1756246410.481 [transit:StandardEnvelopes] Stopped
2025-08-26T22:13:30.481Z,1756246410.481 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2025-08-26T22:13:30.482Z,1756246410.482 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-08-26T22:13:30.482Z,1756246410.482 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-08-26T22:13:30.482Z,1756246410.482 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-08-26T22:13:30.482Z,1756246410.482 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-08-26T22:13:30.482Z,1756246410.482 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-08-26T22:13:30.482Z,1756246410.482 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-08-26T22:13:30.482Z,1756246410.482 [transit:BackseatDriver] Stopped
2025-08-26T22:13:30.482Z,1756246410.482 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2025-08-26T22:13:30.482Z,1756246410.482 [transit:BackseatDriver:A.BackseatDriver] Stopped
2025-08-26T22:13:30.482Z,1756246410.482 [transit:PowerOnly] Stopped
2025-08-26T22:13:30.482Z,1756246410.482 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2025-08-26T22:13:30.482Z,1756246410.482 [transit:PowerOnly:E.Wait] Stopped
2025-08-26T22:13:30.482Z,1756246410.482 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-08-26T22:13:30.483Z,1756246410.483 [transit:BuoyancyHold.Buoyancy] Stopped
2025-08-26T22:13:30.483Z,1756246410.483 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-08-26T22:13:30.483Z,1756246410.483 [transit:MassHold.Pitch] Stopped
2025-08-26T22:13:30.483Z,1756246410.483 [transit:Transit] Stopped
2025-08-26T22:13:30.483Z,1756246410.483 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2025-08-26T22:13:30.483Z,1756246410.483 [transit:Transit:A.Pitch] Stopped
2025-08-26T22:13:30.483Z,1756246410.483 [transit:Transit:B.SetSpeed] Stopped
2025-08-26T22:13:30.483Z,1756246410.483 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2025-08-26T22:13:30.483Z,1756246410.483 [transit:Transit:Wpt1.Waypoint] Stopped
2025-08-26T22:13:30.483Z,1756246410.483 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-08-26T22:13:30.558Z,1756246410.558 [CBIT](INFO): Critical error at 20250826T221330
2025-08-26T22:13:30.841Z,1756246410.841 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2025-08-26T22:13:30.841Z,1756246410.841 [DefaultWithUndock] Running Loop=1
2025-08-26T22:13:30.841Z,1756246410.841 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2025-08-26T22:13:30.841Z,1756246410.841 [DefaultWithUndock:A.Wait] Running Loop=1
2025-08-26T22:13:30.841Z,1756246410.841 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2025-08-26T22:13:38.724Z,1756246418.724 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:13:39.729Z,1756246419.729 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:14:01.137Z,1756246441.137 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2025-08-26T22:14:01.137Z,1756246441.137 [DefaultWithUndock:A.Wait] Stopped
2025-08-26T22:14:01.137Z,1756246441.137 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2025-08-26T22:14:01.137Z,1756246441.137 [DefaultWithUndock:LeaveDock] Running Loop=1
2025-08-26T22:14:01.137Z,1756246441.137 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2025-08-26T22:14:01.137Z,1756246441.137 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-08-26T22:14:01.137Z,1756246441.137 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2025-08-26T22:14:01.138Z,1756246441.138 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2025-08-26T22:14:01.138Z,1756246441.138 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-08-26T22:14:01.138Z,1756246441.138 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-08-26T22:14:01.545Z,1756246441.545 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-08-26T22:14:01.545Z,1756246441.545 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-08-26T22:14:01.546Z,1756246441.546 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth 9.362103 m.
2025-08-26T22:14:01.547Z,1756246441.547 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-08-26T22:14:02.423Z,1756246442.423 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-08-26T22:14:02.549Z,1756246442.549 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-08-26T22:14:04.330Z,1756246444.330 [CTD_Seabird](ERROR): Failed to parse device response:
2025-08-26T22:14:06.017Z,1756246446.017 [DockingStepper](INFO): Changing to mode: 3
2025-08-26T22:14:09.848Z,1756246449.848 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:14:10.852Z,1756246450.852 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:14:11.235Z,1756246451.235 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-08-26T22:14:11.457Z,1756246451.457 [DAT](DEBUG): Acoustic response timeout
2025-08-26T22:14:11.457Z,1756246451.457 [DAT](INFO): setting remote address to 0
2025-08-26T22:14:11.709Z,1756246451.709 [DAT](INFO): DAT read:
2025-08-26T22:14:11.710Z,1756246451.710 [DAT](INFO): DAT read: RemoteAddr | 0
2025-08-26T22:14:11.710Z,1756246451.710 [DAT](INFO): set remote address to 0
2025-08-26T22:14:11.710Z,1756246451.710 [DAT](DEBUG): Acoustic response timeout
2025-08-26T22:14:11.711Z,1756246451.711 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-08-26T22:14:11.961Z,1756246451.961 [DAT](INFO): DAT read: user:7>
2025-08-26T22:14:11.961Z,1756246451.961 [DAT](INFO): DAT read: Tx time:22:14:11.0474
2025-08-26T22:14:11.962Z,1756246451.962 [DAT](INFO): Ping request sent.
2025-08-26T22:14:11.962Z,1756246451.962 [DAT](INFO): transmitted an acoustic signal
2025-08-26T22:14:11.962Z,1756246451.962 [DAT](INFO): publishing transmit ping time
2025-08-26T22:14:11.963Z,1756246451.963 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000930
2025-08-26T22:14:12.063Z,1756246452.063 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2025-08-26T22:14:12.213Z,1756246452.213 [DAT](INFO): DAT read: TxSync time:22:14:11.0466
2025-08-26T22:14:12.214Z,1756246452.214 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251886
2025-08-26T22:14:12.465Z,1756246452.465 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503052
2025-08-26T22:14:12.717Z,1756246452.717 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754959
2025-08-26T22:14:12.969Z,1756246452.969 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006924
2025-08-26T22:14:13.221Z,1756246453.221 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258968
2025-08-26T22:14:13.473Z,1756246453.473 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510908
2025-08-26T22:14:13.725Z,1756246453.725 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.763187
2025-08-26T22:14:13.977Z,1756246453.977 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014919
2025-08-26T22:14:14.229Z,1756246454.229 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266903
2025-08-26T22:14:14.481Z,1756246454.481 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519291
2025-08-26T22:14:14.733Z,1756246454.733 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.771041
2025-08-26T22:14:14.985Z,1756246454.985 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.023056
2025-08-26T22:14:15.237Z,1756246455.237 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.275261
2025-08-26T22:14:15.489Z,1756246455.489 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.527001
2025-08-26T22:14:15.741Z,1756246455.741 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778998
2025-08-26T22:14:15.993Z,1756246455.993 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030808
2025-08-26T22:14:16.245Z,1756246456.245 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282956
2025-08-26T22:14:16.497Z,1756246456.497 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.535003
2025-08-26T22:14:16.749Z,1756246456.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786961
2025-08-26T22:14:17.001Z,1756246457.001 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038965
2025-08-26T22:14:17.253Z,1756246457.253 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290879
2025-08-26T22:14:17.505Z,1756246457.505 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542946
2025-08-26T22:14:17.757Z,1756246457.757 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.795158
2025-08-26T22:14:18.009Z,1756246458.009 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046935
2025-08-26T22:14:18.261Z,1756246458.261 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298915
2025-08-26T22:14:18.513Z,1756246458.513 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.551042
2025-08-26T22:14:18.765Z,1756246458.765 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802938
2025-08-26T22:14:19.017Z,1756246459.017 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054925
2025-08-26T22:14:19.269Z,1756246459.269 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306962
2025-08-26T22:14:19.521Z,1756246459.521 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558906
2025-08-26T22:14:19.774Z,1756246459.774 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.812014
2025-08-26T22:14:20.025Z,1756246460.025 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062995
2025-08-26T22:14:20.277Z,1756246460.277 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314861
2025-08-26T22:14:20.529Z,1756246460.529 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566999
2025-08-26T22:14:20.781Z,1756246460.781 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818949
2025-08-26T22:14:21.033Z,1756246461.033 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070888
2025-08-26T22:14:21.285Z,1756246461.285 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322908
2025-08-26T22:14:21.333Z,1756246461.333 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-08-26T22:14:21.537Z,1756246461.537 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574964
2025-08-26T22:14:21.792Z,1756246461.792 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.830333
2025-08-26T22:14:22.040Z,1756246462.040 [DAT](INFO): Reached modem response timeout
2025-08-26T22:14:22.041Z,1756246462.041 [DAT](DEBUG): Acoustic response timeout
2025-08-26T22:14:22.041Z,1756246462.041 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-08-26T22:14:22.293Z,1756246462.293 [DAT](INFO): Reached modem response timeout
2025-08-26T22:14:22.293Z,1756246462.293 [DAT](DEBUG): Acoustic response timeout
2025-08-26T22:14:22.293Z,1756246462.293 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-08-26T22:14:22.545Z,1756246462.545 [DAT](INFO): Reached modem response timeout
2025-08-26T22:14:22.545Z,1756246462.545 [DAT](DEBUG): Acoustic response timeout
2025-08-26T22:14:22.545Z,1756246462.545 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-08-26T22:14:22.797Z,1756246462.797 [DAT](INFO): DAT read: Response Not Received
2025-08-26T22:14:22.797Z,1756246462.797 [DAT](INFO): response not received
2025-08-26T22:14:22.798Z,1756246462.798 [DAT](INFO): DAT read: user:8>
2025-08-26T22:14:22.798Z,1756246462.798 [DAT](INFO): DAT read: Tx time:22:14:21.9975
2025-08-26T22:14:22.799Z,1756246462.799 [DAT](INFO): Ping request sent.
2025-08-26T22:14:22.799Z,1756246462.799 [DAT](INFO): transmitted an acoustic signal
2025-08-26T22:14:22.799Z,1756246462.799 [DAT](INFO): publishing transmit ping time
2025-08-26T22:14:22.799Z,1756246462.799 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000735
2025-08-26T22:14:23.049Z,1756246463.049 [DAT](INFO): DAT read: TxSync time:22:14:21.9967
2025-08-26T22:14:23.050Z,1756246463.050 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251070
2025-08-26T22:14:23.301Z,1756246463.301 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502110
2025-08-26T22:14:23.553Z,1756246463.553 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754558
2025-08-26T22:14:23.805Z,1756246463.805 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006412
2025-08-26T22:14:24.057Z,1756246464.057 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258082
2025-08-26T22:14:24.309Z,1756246464.309 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510105
2025-08-26T22:14:24.562Z,1756246464.562 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762822
2025-08-26T22:14:24.814Z,1756246464.814 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014914
2025-08-26T22:14:25.065Z,1756246465.065 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266074
2025-08-26T22:14:25.317Z,1756246465.317 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.518191
2025-08-26T22:14:25.569Z,1756246465.569 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770392
2025-08-26T22:14:25.821Z,1756246465.821 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022478
2025-08-26T22:14:26.073Z,1756246466.073 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274159
2025-08-26T22:14:26.325Z,1756246466.325 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526086
2025-08-26T22:14:26.577Z,1756246466.577 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778566
2025-08-26T22:14:26.829Z,1756246466.829 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030311
2025-08-26T22:14:27.081Z,1756246467.081 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282105
2025-08-26T22:14:27.333Z,1756246467.333 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.534355
2025-08-26T22:14:27.585Z,1756246467.585 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786239
2025-08-26T22:14:27.837Z,1756246467.837 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038450
2025-08-26T22:14:28.089Z,1756246468.089 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290111
2025-08-26T22:14:28.343Z,1756246468.343 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.544242
2025-08-26T22:14:28.593Z,1756246468.593 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794011
2025-08-26T22:14:28.845Z,1756246468.845 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046128
2025-08-26T22:14:29.097Z,1756246469.097 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298153
2025-08-26T22:14:29.349Z,1756246469.349 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550142
2025-08-26T22:14:29.602Z,1756246469.602 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.803275
2025-08-26T22:14:29.853Z,1756246469.853 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054254
2025-08-26T22:14:30.105Z,1756246470.105 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306086
2025-08-26T22:14:30.357Z,1756246470.357 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558141
2025-08-26T22:14:30.609Z,1756246470.609 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810168
2025-08-26T22:14:30.861Z,1756246470.861 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.062096
2025-08-26T22:14:31.114Z,1756246471.114 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314836
2025-08-26T22:14:31.365Z,1756246471.365 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566194
2025-08-26T22:14:31.461Z,1756246471.461 [DefaultWithUndock:LeaveDock:A.](INFO): *** querying acoustic contact ***
2025-08-26T22:14:31.617Z,1756246471.617 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818094
2025-08-26T22:14:31.869Z,1756246471.869 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070214
2025-08-26T22:14:32.121Z,1756246472.121 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322160
2025-08-26T22:14:32.240Z,1756246472.240 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby.
2025-08-26T22:14:32.375Z,1756246472.375 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.575837
2025-08-26T22:14:32.625Z,1756246472.625 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826232
2025-08-26T22:14:32.682Z,1756246472.682 [DockingStepper](INFO): Changing to mode: 1
2025-08-26T22:14:32.877Z,1756246472.877 [DAT](INFO): Reached modem response timeout
2025-08-26T22:14:32.877Z,1756246472.877 [DAT](DEBUG): Acoustic response timeout
2025-08-26T22:14:32.877Z,1756246472.877 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-08-26T22:14:33.129Z,1756246473.129 [DAT](INFO): Reached modem response timeout
2025-08-26T22:14:33.129Z,1756246473.129 [DAT](DEBUG): Acoustic response timeout
2025-08-26T22:14:33.129Z,1756246473.129 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-08-26T22:14:33.380Z,1756246473.380 [DAT](INFO): Reached modem response timeout
2025-08-26T22:14:33.381Z,1756246473.381 [DAT](DEBUG): Acoustic response timeout
2025-08-26T22:14:33.381Z,1756246473.381 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-08-26T22:14:33.635Z,1756246473.635 [DAT](INFO): Reached modem response timeout
2025-08-26T22:14:33.635Z,1756246473.635 [DAT](DEBUG): Acoustic response timeout
2025-08-26T22:14:33.635Z,1756246473.635 [DAT](INFO): Querying Benthos address 50 with one ping in standard two-way mode.
2025-08-26T22:14:33.885Z,1756246473.885 [DAT](INFO): DAT read: Response Not Received
2025-08-26T22:14:33.886Z,1756246473.886 [DAT](INFO): response not received
2025-08-26T22:14:33.886Z,1756246473.886 [DAT](INFO): DAT read: user:9>
2025-08-26T22:14:33.887Z,1756246473.887 [DAT](INFO): DAT read: Tx time:22:14:32.9476
2025-08-26T22:14:33.888Z,1756246473.888 [DAT](INFO): Ping request sent.
2025-08-26T22:14:33.888Z,1756246473.888 [DAT](INFO): transmitted an acoustic signal
2025-08-26T22:14:33.888Z,1756246473.888 [DAT](INFO): publishing transmit ping time
2025-08-26T22:14:33.889Z,1756246473.889 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001103
2025-08-26T22:14:34.137Z,1756246474.137 [DAT](INFO): DAT read: TxSync time:22:14:32.9468
2025-08-26T22:14:34.138Z,1756246474.138 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250034
2025-08-26T22:14:34.391Z,1756246474.391 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.503291
2025-08-26T22:14:34.645Z,1756246474.645 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.756993
2025-08-26T22:14:34.897Z,1756246474.897 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.009108
2025-08-26T22:14:35.149Z,1756246475.149 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.261208
2025-08-26T22:14:35.401Z,1756246475.401 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.513046
2025-08-26T22:14:35.653Z,1756246475.653 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.765128
2025-08-26T22:14:35.905Z,1756246475.905 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.017163
2025-08-26T22:14:36.157Z,1756246476.157 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.269089
2025-08-26T22:14:36.411Z,1756246476.411 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.522958
2025-08-26T22:14:36.665Z,1756246476.665 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.777078
2025-08-26T22:14:36.917Z,1756246476.917 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.029120
2025-08-26T22:14:37.169Z,1756246477.169 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.281134
2025-08-26T22:14:37.421Z,1756246477.421 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.533148
2025-08-26T22:14:37.673Z,1756246477.673 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.785097
2025-08-26T22:14:37.925Z,1756246477.925 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.037180
2025-08-26T22:14:38.177Z,1756246478.177 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.289177
2025-08-26T22:14:38.430Z,1756246478.430 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.541993
2025-08-26T22:14:38.685Z,1756246478.685 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.797015
2025-08-26T22:14:38.738Z,1756246478.738 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby.
2025-08-26T22:14:38.937Z,1756246478.937 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.049102
2025-08-26T22:14:39.119Z,1756246479.119 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2025-08-26T22:14:39.120Z,1756246479.120 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2025-08-26T22:14:39.120Z,1756246479.120 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2025-08-26T22:14:39.120Z,1756246479.120 [DefaultWithUndock:LeaveDock] Stopped
2025-08-26T22:14:39.121Z,1756246479.121 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2025-08-26T22:14:39.121Z,1756246479.121 [DefaultWithUndock:LeaveDock:A.] Stopped
2025-08-26T22:14:39.121Z,1756246479.121 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2025-08-26T22:14:39.121Z,1756246479.121 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2025-08-26T22:14:39.121Z,1756246479.121 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2025-08-26T22:14:39.121Z,1756246479.121 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1
2025-08-26T22:14:39.121Z,1756246479.121 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-26T22:14:39.121Z,1756246479.121 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-08-26T22:14:39.122Z,1756246479.122 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-08-26T22:14:39.122Z,1756246479.122 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-08-26T22:14:39.122Z,1756246479.122 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-08-26T22:14:39.123Z,1756246479.123 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-26T22:14:39.123Z,1756246479.123 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-26T22:14:39.189Z,1756246479.189 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.301323
2025-08-26T22:14:39.441Z,1756246479.441 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.553110
2025-08-26T22:14:39.693Z,1756246479.693 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.805158
2025-08-26T22:14:39.913Z,1756246479.913 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -22.00 mm (2 active estimators).
2025-08-26T22:14:39.945Z,1756246479.945 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.057082
2025-08-26T22:14:40.197Z,1756246480.197 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.309481
2025-08-26T22:14:40.264Z,1756246480.264 [Radio_Surface](INFO): Powering up
2025-08-26T22:14:40.449Z,1756246480.449 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.561146
2025-08-26T22:14:40.709Z,1756246480.709 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.821143
2025-08-26T22:14:40.961Z,1756246480.961 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.073464
2025-08-26T22:14:40.972Z,1756246480.972 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:14:41.213Z,1756246481.213 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.325532
2025-08-26T22:14:41.465Z,1756246481.465 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.577082
2025-08-26T22:14:41.717Z,1756246481.717 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.829375
2025-08-26T22:14:41.976Z,1756246481.976 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:14:41.982Z,1756246481.982 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.094119
2025-08-26T22:14:42.233Z,1756246482.233 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.345126
2025-08-26T22:14:42.485Z,1756246482.485 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.597708
2025-08-26T22:14:42.737Z,1756246482.737 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.849229
2025-08-26T22:14:42.989Z,1756246482.989 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.101095
2025-08-26T22:14:43.241Z,1756246483.241 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.353381
2025-08-26T22:14:43.493Z,1756246483.493 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.605158
2025-08-26T22:14:43.745Z,1756246483.745 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.857086
2025-08-26T22:14:43.997Z,1756246483.997 [DAT](INFO): Reached modem response timeout
2025-08-26T22:14:44.753Z,1756246484.753 [DAT](INFO): DAT read: Response Not Received
2025-08-26T22:14:44.753Z,1756246484.753 [DAT](INFO): response not received
2025-08-26T22:14:44.754Z,1756246484.754 [DAT](INFO): DAT read: user:10>
2025-08-26T22:14:44.754Z,1756246484.754 [DAT](INFO): DAT read: Tx time:22:14:43.8977
2025-08-26T22:14:44.755Z,1756246484.755 [DAT](INFO): Ping request sent.
2025-08-26T22:14:44.755Z,1756246484.755 [DAT](INFO): transmitted an acoustic signal
2025-08-26T22:14:44.755Z,1756246484.755 [DAT](INFO): publishing transmit ping time
2025-08-26T22:14:44.756Z,1756246484.756 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000735
2025-08-26T22:14:45.005Z,1756246485.005 [DAT](INFO): DAT read: TxSync time:22:14:43.8969
2025-08-26T22:14:45.006Z,1756246485.006 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251338
2025-08-26T22:14:45.257Z,1756246485.257 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.501948
2025-08-26T22:14:45.509Z,1756246485.509 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.753962
2025-08-26T22:14:45.516Z,1756246485.516 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2025-08-26T22:14:45.517Z,1756246485.517 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0
2025-08-26T22:14:45.761Z,1756246485.761 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006010
2025-08-26T22:14:46.019Z,1756246486.019 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.263977
2025-08-26T22:14:46.268Z,1756246486.268 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.512841
2025-08-26T22:14:46.517Z,1756246486.517 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.762314
2025-08-26T22:14:46.769Z,1756246486.769 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.013969
2025-08-26T22:14:47.021Z,1756246487.021 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266318
2025-08-26T22:14:47.274Z,1756246487.274 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.519356
2025-08-26T22:14:47.525Z,1756246487.525 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.769946
2025-08-26T22:14:47.777Z,1756246487.777 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022210
2025-08-26T22:14:48.029Z,1756246488.029 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274022
2025-08-26T22:14:48.281Z,1756246488.281 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.525968
2025-08-26T22:14:48.533Z,1756246488.533 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778536
2025-08-26T22:14:48.785Z,1756246488.785 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030019
2025-08-26T22:14:49.037Z,1756246489.037 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.281945
2025-08-26T22:14:49.289Z,1756246489.289 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.533969
2025-08-26T22:14:49.541Z,1756246489.541 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.785951
2025-08-26T22:14:49.793Z,1756246489.793 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.037990
2025-08-26T22:14:50.045Z,1756246490.045 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.290222
2025-08-26T22:14:50.298Z,1756246490.298 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542891
2025-08-26T22:14:50.549Z,1756246490.549 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.793933
2025-08-26T22:14:50.801Z,1756246490.801 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046001
2025-08-26T22:14:51.053Z,1756246491.053 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298005
2025-08-26T22:14:51.305Z,1756246491.305 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.550038
2025-08-26T22:14:51.557Z,1756246491.557 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802174
2025-08-26T22:14:51.809Z,1756246491.809 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054090
2025-08-26T22:14:52.061Z,1756246492.061 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306144
2025-08-26T22:14:52.313Z,1756246492.313 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.557847
2025-08-26T22:14:52.565Z,1756246492.565 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810326
2025-08-26T22:14:52.817Z,1756246492.817 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.061900
2025-08-26T22:14:53.069Z,1756246493.069 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.313968
2025-08-26T22:14:53.321Z,1756246493.321 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.566001
2025-08-26T22:14:53.573Z,1756246493.573 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.817996
2025-08-26T22:14:53.825Z,1756246493.825 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070360
2025-08-26T22:14:54.077Z,1756246494.077 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322004
2025-08-26T22:14:54.329Z,1756246494.329 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574095
2025-08-26T22:14:54.581Z,1756246494.581 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826065
2025-08-26T22:14:55.589Z,1756246495.589 [DAT](INFO): DAT read: Response Not Received
2025-08-26T22:14:55.589Z,1756246495.589 [DAT](INFO): response not received
2025-08-26T22:14:55.590Z,1756246495.590 [DAT](INFO): DAT read: user:11>
2025-08-26T22:14:55.591Z,1756246495.591 [DAT](ERROR): No response from remote modem.
2025-08-26T22:14:55.842Z,1756246495.842 [DAT](INFO): DAT read: Tx time:22:14:54.8478
2025-08-26T22:14:55.842Z,1756246495.842 [DAT](INFO): Ping request sent.
2025-08-26T22:14:55.842Z,1756246495.842 [DAT](INFO): transmitted an acoustic signal
2025-08-26T22:14:55.843Z,1756246495.843 [DAT](INFO): DAT read: TxSync time:22:14:54.8470
2025-08-26T22:14:55.844Z,1756246495.844 [DAT](INFO): publishing transmit ping time
2025-08-26T22:14:55.845Z,1756246495.845 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.002354
2025-08-26T22:14:56.093Z,1756246496.093 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250699
2025-08-26T22:14:56.346Z,1756246496.346 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.504109
2025-08-26T22:14:56.597Z,1756246496.597 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754630
2025-08-26T22:14:56.849Z,1756246496.849 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006429
2025-08-26T22:14:57.101Z,1756246497.101 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258797
2025-08-26T22:14:57.353Z,1756246497.353 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510461
2025-08-26T22:14:57.607Z,1756246497.607 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.764152
2025-08-26T22:14:57.858Z,1756246497.858 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014983
2025-08-26T22:14:58.110Z,1756246498.110 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.268052
2025-08-26T22:14:58.364Z,1756246498.364 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.520743
2025-08-26T22:14:58.613Z,1756246498.613 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.770615
2025-08-26T22:14:58.865Z,1756246498.865 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.022417
2025-08-26T22:14:59.117Z,1756246499.117 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.274458
2025-08-26T22:14:59.369Z,1756246499.369 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.526498
2025-08-26T22:14:59.621Z,1756246499.621 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.778436
2025-08-26T22:14:59.873Z,1756246499.873 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.030548
2025-08-26T22:15:00.125Z,1756246500.125 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.282494
2025-08-26T22:15:00.380Z,1756246500.380 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.536625
2025-08-26T22:15:00.629Z,1756246500.629 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.786520
2025-08-26T22:15:00.881Z,1756246500.881 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.038497
2025-08-26T22:15:01.134Z,1756246501.134 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.291428
2025-08-26T22:15:01.385Z,1756246501.385 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.542674
2025-08-26T22:15:01.637Z,1756246501.637 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.794504
2025-08-26T22:15:01.889Z,1756246501.889 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.046508
2025-08-26T22:15:02.141Z,1756246502.141 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.298444
2025-08-26T22:15:02.395Z,1756246502.395 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.552365
2025-08-26T22:15:02.645Z,1756246502.645 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.802495
2025-08-26T22:15:02.897Z,1756246502.897 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.054694
2025-08-26T22:15:03.149Z,1756246503.149 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.306592
2025-08-26T22:15:03.401Z,1756246503.401 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.558504
2025-08-26T22:15:03.653Z,1756246503.653 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.810490
2025-08-26T22:15:03.906Z,1756246503.906 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.063278
2025-08-26T22:15:04.157Z,1756246504.157 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.314499
2025-08-26T22:15:04.412Z,1756246504.412 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.568696
2025-08-26T22:15:04.661Z,1756246504.661 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.818490
2025-08-26T22:15:04.913Z,1756246504.913 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.070407
2025-08-26T22:15:05.165Z,1756246505.165 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.322492
2025-08-26T22:15:05.417Z,1756246505.417 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.574494
2025-08-26T22:15:05.669Z,1756246505.669 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.826388
2025-08-26T22:15:06.677Z,1756246506.677 [DAT](INFO): DAT read: Response Not Received
2025-08-26T22:15:06.677Z,1756246506.677 [DAT](INFO): response not received
2025-08-26T22:15:06.677Z,1756246506.677 [DAT](INFO): DAT read: user:12>
2025-08-26T22:15:06.678Z,1756246506.678 [DAT](INFO): DAT read: Tx time:22:15:05.7995
2025-08-26T22:15:06.679Z,1756246506.679 [DAT](INFO): Ping request sent.
2025-08-26T22:15:06.679Z,1756246506.679 [DAT](INFO): transmitted an acoustic signal
2025-08-26T22:15:06.679Z,1756246506.679 [DAT](INFO): publishing transmit ping time
2025-08-26T22:15:06.679Z,1756246506.679 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000734
2025-08-26T22:15:06.786Z,1756246506.786 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2025-08-26T22:15:06.786Z,1756246506.786 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-08-26T22:15:06.786Z,1756246506.786 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-08-26T22:15:06.929Z,1756246506.929 [DAT](INFO): DAT read: TxSync time:22:15:05.7987
2025-08-26T22:15:06.930Z,1756246506.930 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.251123
2025-08-26T22:15:07.181Z,1756246507.181 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502697
2025-08-26T22:15:07.433Z,1756246507.433 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754201
2025-08-26T22:15:07.685Z,1756246507.685 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006236
2025-08-26T22:15:07.937Z,1756246507.937 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.258173
2025-08-26T22:15:08.189Z,1756246508.189 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.510079
2025-08-26T22:15:08.445Z,1756246508.445 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.766325
2025-08-26T22:15:08.693Z,1756246508.693 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.014097
2025-08-26T22:15:08.945Z,1756246508.945 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.266156
2025-08-26T22:15:09.210Z,1756246509.210 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.531015
2025-08-26T22:15:09.461Z,1756246509.461 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.782146
2025-08-26T22:15:09.713Z,1756246509.713 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.034145
2025-08-26T22:15:09.966Z,1756246509.966 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.286839
2025-08-26T22:15:10.217Z,1756246510.217 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.538256
2025-08-26T22:15:10.469Z,1756246510.469 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.790635
2025-08-26T22:15:10.721Z,1756246510.721 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.042126
2025-08-26T22:15:10.973Z,1756246510.973 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.294052
2025-08-26T22:15:11.229Z,1756246511.229 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.550119
2025-08-26T22:15:11.481Z,1756246511.481 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.802166
2025-08-26T22:15:11.733Z,1756246511.733 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.054124
2025-08-26T22:15:11.985Z,1756246511.985 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.306138
2025-08-26T22:15:12.096Z,1756246512.096 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:15:12.237Z,1756246512.237 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.558190
2025-08-26T22:15:12.489Z,1756246512.489 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.810411
2025-08-26T22:15:12.741Z,1756246512.741 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.062348
2025-08-26T22:15:12.993Z,1756246512.993 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.314064
2025-08-26T22:15:13.102Z,1756246513.102 [DataOverHttps](INFO): Radio surface powered ON.
2025-08-26T22:15:13.102Z,1756246513.102 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:15:13.245Z,1756246513.245 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.566226
2025-08-26T22:15:13.497Z,1756246513.497 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.818112
2025-08-26T22:15:13.749Z,1756246513.749 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.070171
2025-08-26T22:15:14.001Z,1756246514.001 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.322099
2025-08-26T22:15:14.253Z,1756246514.253 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.574073
2025-08-26T22:15:14.507Z,1756246514.507 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.828286
2025-08-26T22:15:14.766Z,1756246514.766 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.087460
2025-08-26T22:15:15.017Z,1756246515.017 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.338424
2025-08-26T22:15:15.275Z,1756246515.275 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.596695
2025-08-26T22:15:15.529Z,1756246515.529 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.850122
2025-08-26T22:15:15.781Z,1756246515.781 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.102119
2025-08-26T22:15:16.033Z,1756246516.033 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.354090
2025-08-26T22:15:16.285Z,1756246516.285 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.606141
2025-08-26T22:15:16.537Z,1756246516.537 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.858239
2025-08-26T22:15:17.545Z,1756246517.545 [DAT](INFO): DAT read: Response Not Received
2025-08-26T22:15:17.545Z,1756246517.545 [DAT](INFO): response not received
2025-08-26T22:15:17.546Z,1756246517.546 [DAT](INFO): DAT read: user:13>
2025-08-26T22:15:17.546Z,1756246517.546 [DAT](INFO): DAT read: Tx time:22:15:16.7480
2025-08-26T22:15:17.547Z,1756246517.547 [DAT](INFO): Ping request sent.
2025-08-26T22:15:17.547Z,1756246517.547 [DAT](INFO): transmitted an acoustic signal
2025-08-26T22:15:17.547Z,1756246517.547 [DAT](INFO): publishing transmit ping time
2025-08-26T22:15:17.548Z,1756246517.547 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.000729
2025-08-26T22:15:17.797Z,1756246517.797 [DAT](INFO): DAT read: TxSync time:22:15:16.7472
2025-08-26T22:15:17.798Z,1756246517.798 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.250960
2025-08-26T22:15:18.049Z,1756246518.049 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.502708
2025-08-26T22:15:18.301Z,1756246518.301 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.754084
2025-08-26T22:15:18.553Z,1756246518.553 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.006013
2025-08-26T22:15:18.813Z,1756246518.813 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.266135
2025-08-26T22:15:19.065Z,1756246519.065 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.518063
2025-08-26T22:15:19.317Z,1756246519.317 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.769983
2025-08-26T22:15:19.569Z,1756246519.569 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.021973
2025-08-26T22:15:19.822Z,1756246519.822 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.275111
2025-08-26T22:15:20.073Z,1756246520.073 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.526119
2025-08-26T22:15:20.325Z,1756246520.325 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.778044
2025-08-26T22:15:20.577Z,1756246520.577 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.030111
2025-08-26T22:15:20.829Z,1756246520.829 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.282014
2025-08-26T22:15:21.081Z,1756246521.081 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.534026
2025-08-26T22:15:21.334Z,1756246521.334 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.786718
2025-08-26T22:15:21.585Z,1756246521.585 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.037987
2025-08-26T22:15:21.837Z,1756246521.837 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.289997
2025-08-26T22:15:22.089Z,1756246522.089 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.542154
2025-08-26T22:15:22.343Z,1756246522.343 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.795754
2025-08-26T22:15:22.593Z,1756246522.593 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.046029
2025-08-26T22:15:22.853Z,1756246522.853 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.306061
2025-08-26T22:15:23.105Z,1756246523.105 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.558037
2025-08-26T22:15:23.357Z,1756246523.357 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.810035
2025-08-26T22:15:23.609Z,1756246523.609 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.062061
2025-08-26T22:15:23.861Z,1756246523.861 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.314068
2025-08-26T22:15:24.113Z,1756246524.113 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.566320
2025-08-26T22:15:24.367Z,1756246524.367 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.819917
2025-08-26T22:15:24.617Z,1756246524.617 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.069999
2025-08-26T22:15:24.876Z,1756246524.876 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.328766
2025-08-26T22:15:25.125Z,1756246525.125 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.578053
2025-08-26T22:15:25.377Z,1756246525.377 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.830019
2025-08-26T22:15:25.630Z,1756246525.630 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.082900
2025-08-26T22:15:25.881Z,1756246525.881 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.334050
2025-08-26T22:15:26.137Z,1756246526.137 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.590667
2025-08-26T22:15:26.387Z,1756246526.387 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.839790
2025-08-26T22:15:26.637Z,1756246526.637 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.090096
2025-08-26T22:15:26.889Z,1756246526.889 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.342011
2025-08-26T22:15:27.141Z,1756246527.141 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.594000
2025-08-26T22:15:27.393Z,1756246527.393 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.846137
2025-08-26T22:15:28.403Z,1756246528.403 [DAT](INFO): DAT read: Response Not Received
2025-08-26T22:15:28.404Z,1756246528.404 [DAT](INFO): response not received
2025-08-26T22:15:28.405Z,1756246528.405 [DAT](INFO): DAT read: user:14>
2025-08-26T22:15:28.406Z,1756246528.406 [DAT](ERROR): No response from remote modem.
2025-08-26T22:15:28.653Z,1756246528.653 [DAT](INFO): DAT read: Tx time:22:15:27.6981
2025-08-26T22:15:28.653Z,1756246528.653 [DAT](INFO): Ping request sent.
2025-08-26T22:15:28.653Z,1756246528.653 [DAT](INFO): transmitted an acoustic signal
2025-08-26T22:15:28.654Z,1756246528.654 [DAT](INFO): DAT read: TxSync time:22:15:27.6973
2025-08-26T22:15:28.654Z,1756246528.654 [DAT](INFO): publishing transmit ping time
2025-08-26T22:15:28.655Z,1756246528.655 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.001577
2025-08-26T22:15:28.909Z,1756246528.909 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.255350
2025-08-26T22:15:29.161Z,1756246529.161 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.507344
2025-08-26T22:15:29.413Z,1756246529.413 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=0.759878
2025-08-26T22:15:29.665Z,1756246529.665 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.011425
2025-08-26T22:15:29.917Z,1756246529.917 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.263341
2025-08-26T22:15:30.169Z,1756246530.169 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.515468
2025-08-26T22:15:30.423Z,1756246530.423 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=1.769338
2025-08-26T22:15:30.673Z,1756246530.673 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.019404
2025-08-26T22:15:30.925Z,1756246530.925 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.271697
2025-08-26T22:15:31.177Z,1756246531.177 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.523449
2025-08-26T22:15:31.429Z,1756246531.429 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=2.775432
2025-08-26T22:15:31.681Z,1756246531.681 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.027397
2025-08-26T22:15:31.933Z,1756246531.933 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.279407
2025-08-26T22:15:32.185Z,1756246532.185 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.531506
2025-08-26T22:15:32.437Z,1756246532.437 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=3.783388
2025-08-26T22:15:32.689Z,1756246532.689 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.035419
2025-08-26T22:15:32.941Z,1756246532.941 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.287296
2025-08-26T22:15:33.193Z,1756246533.193 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.539625
2025-08-26T22:15:33.445Z,1756246533.445 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=4.791425
2025-08-26T22:15:33.697Z,1756246533.697 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.043347
2025-08-26T22:15:33.949Z,1756246533.949 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.295531
2025-08-26T22:15:34.217Z,1756246534.217 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.563484
2025-08-26T22:15:34.469Z,1756246534.469 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=5.815355
2025-08-26T22:15:34.721Z,1756246534.721 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.067369
2025-08-26T22:15:34.973Z,1756246534.973 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.319334
2025-08-26T22:15:35.225Z,1756246535.225 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.571348
2025-08-26T22:15:35.477Z,1756246535.477 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=6.823618
2025-08-26T22:15:35.729Z,1756246535.729 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.075389
2025-08-26T22:15:35.981Z,1756246535.981 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.327348
2025-08-26T22:15:36.242Z,1756246536.242 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.588539
2025-08-26T22:15:36.493Z,1756246536.493 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=7.839455
2025-08-26T22:15:36.745Z,1756246536.745 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.091326
2025-08-26T22:15:36.997Z,1756246536.997 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.343342
2025-08-26T22:15:37.249Z,1756246537.249 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.595362
2025-08-26T22:15:37.501Z,1756246537.501 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=8.847307
2025-08-26T22:15:37.753Z,1756246537.753 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.099607
2025-08-26T22:15:38.005Z,1756246538.005 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.351411
2025-08-26T22:15:38.257Z,1756246538.257 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.603324
2025-08-26T22:15:38.509Z,1756246538.509 [DAT](INFO): checking for new query: numPingsReceived=0, elapsed TxPingTime=9.855353
2025-08-26T22:15:39.517Z,1756246539.517 [DAT](INFO): DAT read: Response Not Received
2025-08-26T22:15:39.517Z,1756246539.517 [DAT](INFO): response not received
2025-08-26T22:15:39.517Z,1756246539.517 [DAT](ERROR): No response from remote modem.
2025-08-26T22:15:43.220Z,1756246543.220 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:15:44.224Z,1756246544.224 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:16:10.938Z,1756246570.938 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-26T22:16:14.345Z,1756246574.345 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:16:15.348Z,1756246575.348 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:16:15.786Z,1756246575.786 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221616.00,A,3646.21985,N,12151.74644,W,0.253,51.17,260825,,,D*47
2025-08-26T22:16:15.800Z,1756246575.800 [NAL9602](INFO): GPS fix at 20250826T221616: (36.770331, -121.862441)
2025-08-26T22:16:15.812Z,1756246575.812 [UniversalFixResidualReporter](INFO): Fix residual: 8.9 %DT, over the last 859.6 m. Residual distance 76.4 m at bearing 20.1 degrees. Fix at (36.7703, -121.8624) with 819.3 m made good.
2025-08-26T22:16:15.813Z,1756246575.813 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-08-26T22:16:15.813Z,1756246575.813 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-08-26T22:16:45.484Z,1756246605.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:16:46.488Z,1756246606.488 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:16:46.490Z,1756246606.490 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response.
2025-08-26T22:16:46.572Z,1756246606.572 [NAL9602](ERROR): received:
+CSQ:0
OK634, 0, 0, 0, 0
OK
2025-08-26T22:17:16.612Z,1756246636.612 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:17:16.698Z,1756246636.698 [NAL9602](INFO): SBD MO Status=2, MOMSN=49635, MT Status=2, MTMSN=0
2025-08-26T22:17:16.699Z,1756246636.699 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-26T22:17:17.616Z,1756246637.616 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:17:41.787Z,1756246661.787 [NAL9602](INFO): SBD MO Status=2, MOMSN=49635, MT Status=2, MTMSN=0
2025-08-26T22:17:41.787Z,1756246661.787 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-26T22:17:47.736Z,1756246667.736 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:17:48.740Z,1756246668.740 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:18:15.842Z,1756246695.842 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=49635, MT Status=1, MTMSN=2701
2025-08-26T22:18:15.900Z,1756246695.900 [NAL9602](INFO): Sent 301 bytes from file Logs/20250826T213257/Courier0006.lzma
2025-08-26T22:18:15.900Z,1756246695.900 [NAL9602](INFO): Packets left to send: 0
2025-08-26T22:18:16.398Z,1756246696.398 [NAL9602](INFO): Received command: stop;configSet dropweight.loadAtStartup 0 bool persist;restart app
2025-08-26T22:18:16.849Z,1756246696.849 [CommandExec](IMPORTANT): got command stop
2025-08-26T22:18:16.849Z,1756246696.849 [CommandExec](IMPORTANT): Scheduling is paused
2025-08-26T22:18:16.849Z,1756246696.849 [CommandExec](IMPORTANT): got command configSet DropWeight.loadAtStartup 0 bool persist
2025-08-26T22:18:16.850Z,1756246696.850 [CommandExec](IMPORTANT): configSet DropWeight.loadAtStartup requires a restart to take effect.
2025-08-26T22:18:16.856Z,1756246696.856 [CommandExec](IMPORTANT): got command restart application
2025-08-26T22:18:17.860Z,1756246697.860 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-26T22:18:17.860Z,1756246697.860 [CommandExec](INFO): Uninitializing the command executive.
2025-08-26T22:18:17.860Z,1756246697.860 [CommandExec](INFO): Uninitializing the command scheduler.
2025-08-26T22:18:17.861Z,1756246697.861 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:17.980Z,1756246697.980 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-08-26T22:18:17.980Z,1756246697.980 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-08-26T22:18:17.980Z,1756246697.980 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:17.981Z,1756246697.981 [NavChartDb](INFO): Join timeout helper Thread ID is 1810
2025-08-26T22:18:18.120Z,1756246698.120 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-26T22:18:18.120Z,1756246698.120 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:18.124Z,1756246698.124 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2025-08-26T22:18:18.124Z,1756246698.124 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:18.124Z,1756246698.124 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1811
2025-08-26T22:18:18.292Z,1756246698.292 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-26T22:18:18.292Z,1756246698.292 [WetLabsUBAT](INFO): Powering down
2025-08-26T22:18:18.293Z,1756246698.293 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:18.308Z,1756246698.308 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-08-26T22:18:18.308Z,1756246698.308 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:18.308Z,1756246698.308 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1812
2025-08-26T22:18:18.588Z,1756246698.588 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-26T22:18:18.588Z,1756246698.588 [WetLabsBB2FL](INFO): Powering down
2025-08-26T22:18:18.589Z,1756246698.589 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:18.608Z,1756246698.608 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-08-26T22:18:18.608Z,1756246698.608 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:18.608Z,1756246698.608 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1813
2025-08-26T22:18:18.868Z,1756246698.868 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-08-26T22:18:19.380Z,1756246699.380 [CTD_Seabird](INFO): Powering down
2025-08-26T22:18:19.392Z,1756246699.392 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-26T22:18:19.392Z,1756246699.392 [CTD_Seabird](INFO): Powering down
2025-08-26T22:18:19.404Z,1756246699.404 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:19.420Z,1756246699.420 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-08-26T22:18:19.420Z,1756246699.420 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:19.420Z,1756246699.420 [Radio_Surface](INFO): Join timeout helper Thread ID is 1814
2025-08-26T22:18:19.649Z,1756246699.649 [Radio_Surface](INFO): Powering down
2025-08-26T22:18:19.650Z,1756246699.650 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-26T22:18:19.650Z,1756246699.650 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:19.657Z,1756246699.657 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-08-26T22:18:19.657Z,1756246699.657 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:19.657Z,1756246699.657 [Onboard](INFO): Join timeout helper Thread ID is 1815
2025-08-26T22:18:19.872Z,1756246699.872 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-26T22:18:19.872Z,1756246699.872 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-08-26T22:18:19.992Z,1756246699.992 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-26T22:18:19.992Z,1756246699.992 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:20.001Z,1756246700.001 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-08-26T22:18:20.001Z,1756246700.001 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:20.001Z,1756246700.001 [DataOverHttps](INFO): Join timeout helper Thread ID is 1816
2025-08-26T22:18:20.876Z,1756246700.876 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-26T22:18:20.877Z,1756246700.877 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:20.897Z,1756246700.897 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-08-26T22:18:20.897Z,1756246700.897 [DAT ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:20.897Z,1756246700.897 [DAT](INFO): Join timeout helper Thread ID is 1817
2025-08-26T22:18:20.908Z,1756246700.908 [DAT](INFO): Powering down
2025-08-26T22:18:20.980Z,1756246700.980 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-26T22:18:20.981Z,1756246700.981 [DAT](INFO): Powering down
2025-08-26T22:18:20.982Z,1756246700.982 [DAT ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:20.997Z,1756246700.997 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-08-26T22:18:20.997Z,1756246700.997 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:20.997Z,1756246700.997 [BackseatComponent](INFO): Join timeout helper Thread ID is 1818
2025-08-26T22:18:21.009Z,1756246701.009 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-26T22:18:21.011Z,1756246701.011 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:21.013Z,1756246701.013 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-08-26T22:18:21.013Z,1756246701.013 [logger ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:21.013Z,1756246701.013 [logger](INFO): Join timeout helper Thread ID is 1819
2025-08-26T22:18:21.016Z,1756246701.016 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-26T22:18:21.016Z,1756246701.016 [logger ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:21.025Z,1756246701.025 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-08-26T22:18:21.025Z,1756246701.025 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:21.025Z,1756246701.025 [CommandLine](INFO): Join timeout helper Thread ID is 1820
2025-08-26T22:18:21.092Z,1756246701.092 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-26T22:18:21.093Z,1756246701.093 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:21.113Z,1756246701.113 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-08-26T22:18:21.113Z,1756246701.113 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:21.113Z,1756246701.113 [CommandExec](INFO): Join timeout helper Thread ID is 1821
2025-08-26T22:18:21.121Z,1756246701.121 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-08-26T22:18:21.121Z,1756246701.121 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:21.121Z,1756246701.121 [controlThread](INFO): Join timeout helper Thread ID is 1822
2025-08-26T22:18:22.288Z,1756246702.288 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-26T22:18:22.288Z,1756246702.288 [controlThread](DEBUG): Uninitializing ControlThread
2025-08-26T22:18:22.288Z,1756246702.288 [AHRS_M2](INFO): Powering down
2025-08-26T22:18:22.377Z,1756246702.377 [NAL9602](INFO): Powering down
2025-08-26T22:18:22.500Z,1756246702.500 [RDI_Pathfinder](INFO): Powering down
2025-08-26T22:18:22.501Z,1756246702.501 [Sonardyne_Nano](INFO): Powering down
2025-08-26T22:18:22.628Z,1756246702.628 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-08-26T22:18:22.629Z,1756246702.629 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-08-26T22:18:22.629Z,1756246702.629 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-08-26T22:18:22.630Z,1756246702.630 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2025-08-26T22:18:22.630Z,1756246702.630 [DefaultWithUndock] Stopped
2025-08-26T22:18:22.630Z,1756246702.630 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2025-08-26T22:18:22.630Z,1756246702.630 [DefaultWithUndock:SurfaceDefault] Stopped
2025-08-26T22:18:22.630Z,1756246702.630 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2025-08-26T22:18:22.630Z,1756246702.630 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped
2025-08-26T22:18:22.630Z,1756246702.630 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-26T22:18:22.631Z,1756246702.631 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-08-26T22:18:22.631Z,1756246702.631 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-08-26T22:18:22.631Z,1756246702.631 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-08-26T22:18:22.639Z,1756246702.639 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-08-26T22:18:22.639Z,1756246702.639 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-08-26T22:18:22.639Z,1756246702.639 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-08-26T22:18:22.640Z,1756246702.640 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-08-26T22:18:22.640Z,1756246702.640 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-08-26T22:18:22.640Z,1756246702.640 [BuoyancyServo](INFO): Powering down
2025-08-26T22:18:22.652Z,1756246702.652 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-08-26T22:18:22.652Z,1756246702.652 [ElevatorServo](INFO): Powering down
2025-08-26T22:18:22.653Z,1756246702.653 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2025-08-26T22:18:22.653Z,1756246702.653 [DockingStepper](INFO): Powering down
2025-08-26T22:18:22.654Z,1756246702.654 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-08-26T22:18:22.654Z,1756246702.654 [MassServo](INFO): Powering down
2025-08-26T22:18:22.655Z,1756246702.655 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-08-26T22:18:22.655Z,1756246702.655 [RudderServo](INFO): Powering down
2025-08-26T22:18:22.656Z,1756246702.656 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2025-08-26T22:18:22.656Z,1756246702.656 [ThrusterServo](INFO): Powering down
2025-08-26T22:18:22.657Z,1756246702.657 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-08-26T22:18:22.657Z,1756246702.657 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-08-26T22:18:22.657Z,1756246702.657 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-08-26T22:18:22.657Z,1756246702.657 [CBIT](DEBUG): Powering off loads.
2025-08-26T22:18:22.672Z,1756246702.672 [CBIT](DEBUG): Disabling WDT.
2025-08-26T22:18:22.684Z,1756246702.684 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-08-26T22:18:22.684Z,1756246702.684 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-08-26T22:18:22.685Z,1756246702.685 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:22.738Z,1756246702.738 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:22.741Z,1756246702.741 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:22.747Z,1756246702.747 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:22.754Z,1756246702.754 [DAT ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:22.795Z,1756246702.795 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:22.831Z,1756246702.831 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-08-26T22:18:22.831Z,1756246702.831 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-08-26T22:18:22.859Z,1756246702.859 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:22.861Z,1756246702.861 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:22.866Z,1756246702.866 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:22.874Z,1756246702.874 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-08-26T22:18:22.955Z,1756246702.955 [logger ThreadHandler](INFO): Thread cancelled.