2025-05-13T15:55:26.773Z,1747151726.773 [DataOverHttps](INFO): Sending 2136 bytes from file Logs/20250512T234619/Express0025.lzma
2025-05-13T15:55:26.781Z,1747151726.781 [DataOverHttps](INFO): Received command: restart logs
2025-05-13T15:55:26.798Z,1747151726.798 [CommandExec](IMPORTANT): got command restart logs
2025-05-13T15:55:28.172Z,1747151728.172 [DataOverHttps](INFO): Moved sent file to Logs/20250512T234619/Express0025.lzma.bak
2025-05-13T15:55:28.172Z,1747151728.172 [DataOverHttps](INFO): SBD MOMSN=24860664
2025-05-13T15:55:34.355Z,1747151734.355 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-13T15:55:49.788Z,1747151749.788 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250512T234619/Courier0027.lzma
2025-05-13T15:55:50.790Z,1747151750.790 [DataOverHttps](INFO): Moved sent file to Logs/20250512T234619/Courier0027.lzma.bak
2025-05-13T15:55:50.791Z,1747151750.791 [DataOverHttps](INFO): SBD MOMSN=24860739
2025-05-13T15:56:06.793Z,1747151766.793 [DataOverHttps](INFO): Sending 97 bytes from file Logs/20250512T234619/Express0028.lzma
2025-05-13T15:56:07.795Z,1747151767.795 [DataOverHttps](INFO): Moved sent file to Logs/20250512T234619/Express0028.lzma.bak
2025-05-13T15:56:07.795Z,1747151767.795 [DataOverHttps](INFO): SBD MOMSN=24860741
2025-05-13T15:56:09.096Z,1747151769.096 [Default:CheckIn:Read_Iridium] Stopped
2025-05-13T15:56:09.097Z,1747151769.097 [Default:CheckIn:C.Wait] Running Loop=1
2025-05-13T15:56:09.097Z,1747151769.097 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-13T15:57:48.130Z,1747151868.130 [DataOverHttps](IMPORTANT): SBD MTMSN=20250513T155747
2025-05-13T15:57:55.597Z,1747151875.597 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;run
2025-05-13T15:57:55.614Z,1747151875.614 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2025-05-13T15:57:55.616Z,1747151875.616 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2025-05-13T15:57:55.616Z,1747151875.616 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2025-05-13T15:57:55.619Z,1747151875.619 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2025-05-13T15:57:56.360Z,1747151876.360 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2025-05-13T15:57:56.366Z,1747151876.366 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2025-05-13T15:57:56.371Z,1747151876.371 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2025-05-13T15:57:56.391Z,1747151876.391 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2025-05-13T15:57:56.409Z,1747151876.409 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2025-05-13T15:57:56.416Z,1747151876.416 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2025-05-13T15:57:56.420Z,1747151876.420 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2025-05-13T15:57:56.435Z,1747151876.435 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2025-05-13T15:57:56.442Z,1747151876.442 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2025-05-13T15:57:56.449Z,1747151876.449 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2025-05-13T15:57:56.452Z,1747151876.452 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2025-05-13T15:57:56.459Z,1747151876.459 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2025-05-13T15:57:56.463Z,1747151876.463 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2025-05-13T15:57:56.478Z,1747151876.478 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2025-05-13T15:57:56.478Z,1747151876.478 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-05-13T15:57:56.479Z,1747151876.479 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-05-13T15:57:56.480Z,1747151876.480 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-05-13T15:57:56.796Z,1747151876.796 [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-05-13T15:57:56.802Z,1747151876.802 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2025-05-13T15:57:56.806Z,1747151876.806 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2025-05-13T15:57:56.809Z,1747151876.809 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-05-13T15:57:56.812Z,1747151876.812 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2025-05-13T15:57:56.815Z,1747151876.815 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-05-13T15:57:56.818Z,1747151876.818 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2025-05-13T15:57:56.821Z,1747151876.821 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2025-05-13T15:57:56.824Z,1747151876.824 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2025-05-13T15:57:56.831Z,1747151876.831 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-13T15:57:56.910Z,1747151876.910 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-05-13T15:57:56.910Z,1747151876.910 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-05-13T15:57:56.911Z,1747151876.911 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-05-13T15:57:57.092Z,1747151877.092 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2025-05-13T15:57:57.129Z,1747151877.129 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2025-05-13T15:57:57.141Z,1747151877.141 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-05-13T15:57:57.144Z,1747151877.144 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2025-05-13T15:57:57.151Z,1747151877.151 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-05-13T15:57:57.152Z,1747151877.152 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-05-13T15:57:57.181Z,1747151877.181 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-05-13T15:57:57.204Z,1747151877.204 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-05-13T15:57:57.233Z,1747151877.233 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-05-13T15:57:57.233Z,1747151877.233 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-05-13T15:57:57.234Z,1747151877.234 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-05-13T15:57:57.273Z,1747151877.273 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-05-13T15:57:57.278Z,1747151877.278 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2025-05-13T15:57:57.289Z,1747151877.289 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-05-13T15:57:57.292Z,1747151877.292 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-05-13T15:57:57.292Z,1747151877.292 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-05-13T15:57:57.297Z,1747151877.297 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-05-13T15:57:57.544Z,1747151877.544 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-05-13T15:57:57.557Z,1747151877.557 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2025-05-13T15:57:57.562Z,1747151877.562 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2025-05-13T15:57:57.566Z,1747151877.566 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2025-05-13T15:57:57.573Z,1747151877.573 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2025-05-13T15:57:57.576Z,1747151877.576 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 1 bool
2025-05-13T15:57:57.592Z,1747151877.592 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-05-13T15:57:57.598Z,1747151877.598 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-05-13T15:57:57.606Z,1747151877.606 [transit:MassHold.Pitch](DEBUG): Construct.
2025-05-13T15:57:57.619Z,1747151877.619 [transit:Transit:A.Pitch](DEBUG): Construct.
2025-05-13T15:57:57.641Z,1747151877.641 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2025-05-13T15:57:57.649Z,1747151877.649 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-05-13T15:57:57.699Z,1747151877.699 [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-05-13T15:57:57.700Z,1747151877.700 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2025-05-13T15:58:04.809Z,1747151884.809 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree
2025-05-13T15:58:04.809Z,1747151884.809 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree
2025-05-13T15:58:04.810Z,1747151884.810 [CommandExec](IMPORTANT): got command run
2025-05-13T15:58:04.812Z,1747151884.812 [CommandExec](IMPORTANT): Running
2025-05-13T15:58:05.052Z,1747151885.052 [Default] Stopped
2025-05-13T15:58:05.052Z,1747151885.052 [Default](DEBUG): Aggregate::uninitialize Default
2025-05-13T15:58:05.052Z,1747151885.052 [Default:B.GoToSurface] Stopped
2025-05-13T15:58:05.052Z,1747151885.052 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-13T15:58:05.052Z,1747151885.052 [Default:CheckIn] Stopped
2025-05-13T15:58:05.052Z,1747151885.052 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-05-13T15:58:05.052Z,1747151885.052 [Default:CheckIn:C.Wait] Stopped
2025-05-13T15:58:05.052Z,1747151885.052 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-13T15:58:05.052Z,1747151885.052 [MissionManager](IMPORTANT): Started mission transit
2025-05-13T15:58:05.053Z,1747151885.053 [transit] Running Loop=1
2025-05-13T15:58:05.053Z,1747151885.053 [transit](DEBUG): Aggregate::initialize transit
2025-05-13T15:58:05.053Z,1747151885.053 [transit:StandardEnvelopes] Running Loop=1
2025-05-13T15:58:05.053Z,1747151885.053 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2025-05-13T15:58:05.053Z,1747151885.053 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-05-13T15:58:05.053Z,1747151885.053 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-05-13T15:58:05.053Z,1747151885.053 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-05-13T15:58:05.054Z,1747151885.054 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-05-13T15:58:05.054Z,1747151885.054 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-05-13T15:58:05.054Z,1747151885.054 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-05-13T15:58:05.054Z,1747151885.054 [transit:BackseatDriver] Running Loop=1
2025-05-13T15:58:05.054Z,1747151885.054 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2025-05-13T15:58:05.054Z,1747151885.054 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-05-13T15:58:05.054Z,1747151885.054 [transit:PowerOnly] Running Loop=1
2025-05-13T15:58:05.054Z,1747151885.054 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2025-05-13T15:58:05.054Z,1747151885.054 [transit:PowerOnly:A] Running Loop=1
2025-05-13T15:58:05.054Z,1747151885.054 [transit:PowerOnly:B] Running Loop=1
2025-05-13T15:58:05.054Z,1747151885.054 [transit:PowerOnly:C] Running Loop=1
2025-05-13T15:58:05.055Z,1747151885.055 [transit:PowerOnly:D] Running Loop=1
2025-05-13T15:58:05.055Z,1747151885.055 [transit:PowerOnly:E.Wait] Running Loop=1
2025-05-13T15:58:05.055Z,1747151885.055 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-05-13T15:58:05.055Z,1747151885.055 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-05-13T15:58:05.055Z,1747151885.055 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-05-13T15:58:05.055Z,1747151885.055 [transit:MassHold.Pitch] Running Loop=1
2025-05-13T15:58:05.055Z,1747151885.055 [transit:MassHold.Pitch](DEBUG): Initialize.
2025-05-13T15:58:05.055Z,1747151885.055 [transit:B] Running Loop=1
2025-05-13T15:58:05.056Z,1747151885.056 [transit:MassHold.Pitch] Running Loop=1
2025-05-13T15:58:05.056Z,1747151885.056 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-05-13T15:58:05.057Z,1747151885.057 [transit:PowerOnly] Running Loop=1
2025-05-13T15:58:05.057Z,1747151885.057 [transit:PowerOnly:D] Stopped
2025-05-13T15:58:05.057Z,1747151885.057 [transit:PowerOnly:C] Stopped
2025-05-13T15:58:05.057Z,1747151885.057 [transit:PowerOnly:B] Stopped
2025-05-13T15:58:05.057Z,1747151885.057 [transit:PowerOnly:A] Stopped
2025-05-13T15:58:05.057Z,1747151885.057 [transit:BackseatDriver] Running Loop=1
2025-05-13T15:58:05.058Z,1747151885.058 [transit:StandardEnvelopes] Running Loop=1
2025-05-13T15:58:05.058Z,1747151885.058 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-05-13T15:58:05.058Z,1747151885.058 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-05-13T15:58:05.058Z,1747151885.058 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-05-13T15:58:05.059Z,1747151885.059 [transit:B] Stopped
2025-05-13T15:58:05.059Z,1747151885.059 [transit:C] Running Loop=1
2025-05-13T15:58:05.466Z,1747151885.466 [transit:C] Stopped
2025-05-13T15:58:05.466Z,1747151885.466 [transit:E] Running Loop=1
2025-05-13T15:58:05.855Z,1747151885.855 [transit:E] Stopped
2025-05-13T15:58:05.856Z,1747151885.856 [transit:F] Running Loop=1
2025-05-13T15:58:06.264Z,1747151886.264 [transit:F] Stopped
2025-05-13T15:58:06.264Z,1747151886.264 [transit:G] Running Loop=1
2025-05-13T15:58:06.670Z,1747151886.670 [transit:G] Stopped
2025-05-13T15:58:06.670Z,1747151886.670 [transit:Transit] Running Loop=1
2025-05-13T15:58:06.671Z,1747151886.671 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2025-05-13T15:58:06.671Z,1747151886.671 [transit:Transit:A.Pitch] Running Loop=1
2025-05-13T15:58:06.671Z,1747151886.671 [transit:Transit:A.Pitch](DEBUG): Initialize.
2025-05-13T15:58:06.671Z,1747151886.671 [transit:Transit:B.SetSpeed] Running Loop=1
2025-05-13T15:58:06.671Z,1747151886.671 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2025-05-13T15:58:06.671Z,1747151886.671 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2025-05-13T15:58:06.671Z,1747151886.671 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-05-13T15:58:06.673Z,1747151886.673 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-05-13T15:58:06.674Z,1747151886.674 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-05-13T15:58:07.083Z,1747151887.083 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2025-05-13T15:58:07.083Z,1747151887.083 [transit:Transit:B.SetSpeed] Running Loop=1
2025-05-13T15:58:07.084Z,1747151887.084 [transit:Transit:A.Pitch] Running Loop=1
2025-05-13T15:58:33.397Z,1747151913.397 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 36.20 mm (2 active estimators).
2025-05-13T15:59:22.993Z,1747151962.993 [ElevatorServo](FAULT): Overload Error
2025-05-13T15:59:22.993Z,1747151962.993 [ElevatorServo] Hardware Fault, FailCount= 1
2025-05-13T15:59:22.993Z,1747151962.993 [ElevatorServo](ERROR): Hardware Fault
2025-05-13T15:59:23.312Z,1747151963.312 [CBIT](ERROR): Hardware Fault in component: ElevatorServo
2025-05-13T15:59:23.415Z,1747151963.415 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-05-13T15:59:23.415Z,1747151963.415 [ElevatorServo](INFO): Powering down
2025-05-13T15:59:24.201Z,1747151964.201 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-05-13T15:59:24.322Z,1747151964.322 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-05-13T15:59:24.428Z,1747151964.428 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-05-13T15:59:24.428Z,1747151964.428 [ElevatorServo] No Fault, FailCount= 1
2025-05-13T16:00:22.149Z,1747152022.149 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-13T16:00:32.709Z,1747152032.709 [Radio_Surface](INFO): Powering down
2025-05-13T16:00:53.273Z,1747152053.273 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-13T16:00:54.277Z,1747152054.277 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-05-13T16:01:28.413Z,1747152088.413 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.767273
2025-05-13T16:25:03.095Z,1747153503.095 [transit:NeedComms] Running Loop=1
2025-05-13T16:25:03.095Z,1747153503.095 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2025-05-13T16:25:03.095Z,1747153503.095 [transit:NeedComms:B.GoToSurface] Running Loop=1
2025-05-13T16:25:03.095Z,1747153503.095 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-13T16:25:03.096Z,1747153503.096 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-05-13T16:25:03.096Z,1747153503.096 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-05-13T16:25:03.097Z,1747153503.097 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-05-13T16:25:03.097Z,1747153503.097 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-05-13T16:25:03.097Z,1747153503.097 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-13T16:25:03.098Z,1747153503.098 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-13T16:25:03.098Z,1747153503.098 [transit:NeedComms:A] Running Loop=1
2025-05-13T16:25:03.100Z,1747153503.100 [transit:NeedComms:A](INFO): last time_fix was: 1747151701.000000 second since 1970/01/01T00:00:00Z
2025-05-13T16:25:03.100Z,1747153503.100 [transit:NeedComms:A] Stopped
2025-05-13T16:25:41.138Z,1747153541.138 [transit:NeedComms:C] Running Loop=1
2025-05-13T16:25:41.541Z,1747153541.541 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-05-13T16:25:41.658Z,1747153541.658 [Radio_Surface](INFO): Powering up
2025-05-13T16:25:47.425Z,1747153547.425 [DataOverHttps](INFO): Radio surface powered ON.
2025-05-13T16:26:03.382Z,1747153563.382 [NAL9602](INFO): SBD MO Status=2, MOMSN=20638, MT Status=2, MTMSN=0
2025-05-13T16:26:03.382Z,1747153563.382 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-13T16:26:17.549Z,1747153577.549 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-13T16:26:32.876Z,1747153592.876 [NAL9602](INFO): SBD MO Status=1, MOMSN=20638, MT Status=0, MTMSN=0
2025-05-13T16:26:32.876Z,1747153592.876 [NAL9602](INFO): No messages in MT queue
2025-05-13T16:26:34.080Z,1747153594.080 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162633.00,A,3648.41411,N,12150.86811,W,1.497,184.21,130525,,,A*73
2025-05-13T16:26:34.082Z,1747153594.082 [NAL9602](INFO): GPS fix at 20250513T162633: (36.806902, -121.847802)
2025-05-13T16:26:34.094Z,1747153594.094 [UniversalFixResidualReporter](INFO): Fix residual: 7.0 %DT, over the last 1626.5 m. Residual distance 114.1 m at bearing 72.4 degrees. Fix at (36.8069, -121.8478) with 1646.7 m made good.
2025-05-13T16:26:34.098Z,1747153594.098 [transit:NeedComms:C] Stopped
2025-05-13T16:26:34.098Z,1747153594.098 [transit:NeedComms:D] Running Loop=1
2025-05-13T16:26:34.556Z,1747153594.556 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-05-13T16:26:48.673Z,1747153608.673 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-13T16:26:51.281Z,1747153611.281 [NAL9602](INFO): SBD MO Status=1, MOMSN=20639, MT Status=0, MTMSN=0
2025-05-13T16:26:51.341Z,1747153611.341 [NAL9602](INFO): Sent 326 bytes from file Logs/20250513T155526/Courier0003.lzma
2025-05-13T16:26:51.341Z,1747153611.341 [NAL9602](INFO): Packets left to send: 0
2025-05-13T16:27:09.113Z,1747153629.113 [NAL9602](INFO): SBD MO Status=1, MOMSN=20640, MT Status=0, MTMSN=0
2025-05-13T16:27:09.162Z,1747153629.162 [NAL9602](INFO): Sent 332 bytes from file Logs/20250513T155526/Express0004.lzma
2025-05-13T16:27:09.162Z,1747153629.162 [NAL9602](INFO): Packets left to send: 2
2025-05-13T16:27:19.797Z,1747153639.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-13T16:27:22.078Z,1747153642.078 [NAL9602](INFO): SBD MO Status=1, MOMSN=20641, MT Status=0, MTMSN=0
2025-05-13T16:27:22.133Z,1747153642.133 [NAL9602](INFO): Sent 332 bytes from file Logs/20250513T155526/Express0004.lzma
2025-05-13T16:27:22.133Z,1747153642.133 [NAL9602](INFO): Packets left to send: 1
2025-05-13T16:27:34.655Z,1747153654.655 [NAL9602](INFO): SBD MO Status=1, MOMSN=20642, MT Status=0, MTMSN=0
2025-05-13T16:27:34.713Z,1747153654.713 [NAL9602](INFO): Sent 173 bytes from file Logs/20250513T155526/Express0004.lzma
2025-05-13T16:27:34.713Z,1747153654.713 [NAL9602](INFO): Packets left to send: 0
2025-05-13T16:27:50.921Z,1747153670.921 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-13T16:27:58.494Z,1747153678.494 [NAL9602](INFO): SBD MO Status=0, MOMSN=20643, MT Status=0, MTMSN=0
2025-05-13T16:27:58.592Z,1747153678.592 [transit:NeedComms:D] Stopped
2025-05-13T16:27:58.592Z,1747153678.592 [transit:NeedComms:E] Running Loop=1
2025-05-13T16:27:58.915Z,1747153678.915 [transit:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-05-13T16:27:59.517Z,1747153679.517 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003151
2025-05-13T16:28:00.917Z,1747153680.917 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162800.00,A,3648.40750,N,12150.86307,W,0.350,217.96,130525,,,A*7E
2025-05-13T16:28:00.919Z,1747153680.919 [NAL9602](INFO): GPS fix at 20250513T162800: (36.806792, -121.847718)
2025-05-13T16:28:00.935Z,1747153680.935 [transit:NeedComms:E] Stopped
2025-05-13T16:28:00.936Z,1747153680.936 [transit:NeedComms](INFO): Completed transit:NeedComms
2025-05-13T16:28:00.936Z,1747153680.936 [transit:NeedComms] Stopped
2025-05-13T16:28:00.936Z,1747153680.936 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms
2025-05-13T16:28:00.936Z,1747153680.936 [transit:NeedComms:B.GoToSurface] Stopped
2025-05-13T16:28:00.936Z,1747153680.936 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-13T16:28:33.275Z,1747153713.275 [NAL9602](INFO): Not Powering down - fast GPS
2025-05-13T16:28:39.356Z,1747153719.356 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 36.20 mm (3 active estimators).
2025-05-13T16:29:23.697Z,1747153763.697 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-13T16:29:39.741Z,1747153779.741 [Radio_Surface](INFO): Powering down
2025-05-13T16:29:54.821Z,1747153794.821 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-13T16:29:55.825Z,1747153795.825 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-05-13T16:30:46.421Z,1747153846.421 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-05-13T16:30:50.837Z,1747153850.837 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004147
CHAN A1 (24V): 0.002548
CHAN A2 (12V): -0.009742
CHAN A3 (5V): -0.013125
CHAN B0 (3.3V): -0.005954
CHAN B1 (3.15aV): -0.002273
CHAN B2 (3.15bV): -0.001794
CHAN B3 (GND): -0.004030
OPEN: 0.012007
Full Scale: +/- 1 mA
2025-05-13T16:31:33.217Z,1747153893.217 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.738968
2025-05-13T16:40:17.642Z,1747154417.642 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-05-13T16:40:17.643Z,1747154417.643 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-05-13T16:40:17.645Z,1747154417.645 [BPC1](INFO): Received data from all battery sticks.
2025-05-13T16:47:27.116Z,1747154847.116 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000
2025-05-13T16:47:27.116Z,1747154847.116 [transit:Transit:Wpt1.Waypoint] Stopped
2025-05-13T16:47:27.116Z,1747154847.116 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-05-13T16:47:27.133Z,1747154847.133 [transit:Transit:PhoneHome] Running Loop=1
2025-05-13T16:47:27.133Z,1747154847.133 [transit:Transit:PhoneHome](DEBUG): Aggregate::initialize transit:Transit:PhoneHome
2025-05-13T16:47:27.526Z,1747154847.526 [transit:NeedComms] Running Loop=1
2025-05-13T16:47:27.526Z,1747154847.526 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms
2025-05-13T16:47:27.526Z,1747154847.526 [transit:NeedComms:B.GoToSurface] Running Loop=1
2025-05-13T16:47:27.526Z,1747154847.526 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-13T16:47:27.527Z,1747154847.527 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-05-13T16:47:27.527Z,1747154847.527 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-05-13T16:47:27.527Z,1747154847.527 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-05-13T16:47:27.528Z,1747154847.528 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-05-13T16:47:27.528Z,1747154847.528 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-13T16:47:27.529Z,1747154847.529 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-13T16:47:27.529Z,1747154847.529 [transit:NeedComms:A] Running Loop=1
2025-05-13T16:47:27.530Z,1747154847.530 [transit:NeedComms:A](INFO): last time_fix was: 1747153680.000000 second since 1970/01/01T00:00:00Z
2025-05-13T16:47:27.530Z,1747154847.530 [transit:NeedComms:A] Stopped
2025-05-13T16:48:02.306Z,1747154882.306 [transit:NeedComms:C] Running Loop=1
2025-05-13T16:48:02.781Z,1747154882.781 [Radio_Surface](INFO): Powering up
2025-05-13T16:48:08.265Z,1747154888.265 [DataOverHttps](INFO): Radio surface powered ON.
2025-05-13T16:48:38.393Z,1747154918.393 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-13T16:49:09.529Z,1747154949.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-13T16:49:40.653Z,1747154980.653 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-13T16:49:44.910Z,1747154984.910 [NAL9602](INFO): SBD MO Status=2, MOMSN=20644, MT Status=2, MTMSN=0
2025-05-13T16:49:44.910Z,1747154984.910 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-05-13T16:50:11.777Z,1747155011.777 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-05-13T16:50:20.293Z,1747155020.293 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003165
2025-05-13T16:50:32.987Z,1747155032.987 [NAL9602](INFO): SBD MO Status=0, MOMSN=20644, MT Status=0, MTMSN=0
2025-05-13T16:50:32.987Z,1747155032.987 [NAL9602](INFO): No messages in MT queue
2025-05-13T16:50:34.203Z,1747155034.203 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165033.00,A,3647.76770,N,12150.83441,W,2.041,109.96,130525,,,A*74
2025-05-13T16:50:34.205Z,1747155034.205 [NAL9602](INFO): GPS fix at 20250513T165033: (36.796128, -121.847240)
2025-05-13T16:50:34.217Z,1747155034.217 [UniversalFixResidualReporter](INFO): Fix residual: 5.9 %DT, over the last 1179.1 m. Residual distance 69.4 m at bearing 23.0 degrees. Fix at (36.7961, -121.8472) with 1186.5 m made good.
2025-05-13T16:50:34.221Z,1747155034.221 [transit:NeedComms:C] Stopped
2025-05-13T16:50:34.221Z,1747155034.221 [transit:NeedComms:D] Running Loop=1
2025-05-13T16:50:41.993Z,1747155041.993 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20250513T155526/Courier0006.lzma
2025-05-13T16:50:42.995Z,1747155042.995 [DataOverHttps](INFO): Moved sent file to Logs/20250513T155526/Courier0006.lzma.bak
2025-05-13T16:50:42.995Z,1747155042.995 [DataOverHttps](IMPORTANT): SBD MOMSN=24861094, MTMSN=20250513T165042
2025-05-13T16:50:50.421Z,1747155050.421 [DataOverHttps](INFO): Received command: configSet DVL_micro.loadAtStartup 1 bool persist;restart app
2025-05-13T16:50:50.891Z,1747155050.891 [CommandExec](IMPORTANT): got command configSet DVL_micro.loadAtStartup 1 bool persist
2025-05-13T16:50:50.892Z,1747155050.892 [CommandExec](IMPORTANT): configSet DVL_micro.loadAtStartup requires a restart to take effect.
2025-05-13T16:50:50.894Z,1747155050.894 [CommandExec](IMPORTANT): got command restart application
2025-05-13T16:50:51.897Z,1747155051.897 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2025-05-13T16:50:51.897Z,1747155051.897 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-13T16:50:51.897Z,1747155051.897 [CommandExec](INFO): Uninitializing the command executive.
2025-05-13T16:50:51.897Z,1747155051.897 [CommandExec](INFO): Uninitializing the command scheduler.
2025-05-13T16:50:51.897Z,1747155051.897 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:51.908Z,1747155051.908 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-05-13T16:50:51.909Z,1747155051.909 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-05-13T16:50:51.909Z,1747155051.909 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:51.909Z,1747155051.909 [NavChartDb](INFO): Join timeout helper Thread ID is 6515
2025-05-13T16:50:51.986Z,1747155051.986 [MissionManager](INFO): MissionManager is completed.
2025-05-13T16:50:51.986Z,1747155051.986 [MissionManager](INFO): Uninitializing Mission transit
2025-05-13T16:50:51.986Z,1747155051.986 [transit] Stopped
2025-05-13T16:50:51.986Z,1747155051.986 [transit](DEBUG): Aggregate::uninitialize transit
2025-05-13T16:50:51.986Z,1747155051.986 [transit:NeedComms] Stopped
2025-05-13T16:50:51.986Z,1747155051.986 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms
2025-05-13T16:50:51.986Z,1747155051.986 [transit:NeedComms:B.GoToSurface] Stopped
2025-05-13T16:50:51.986Z,1747155051.986 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-13T16:50:51.986Z,1747155051.986 [transit:NeedComms:D] Stopped
2025-05-13T16:50:51.986Z,1747155051.986 [transit:StandardEnvelopes] Stopped
2025-05-13T16:50:51.986Z,1747155051.986 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2025-05-13T16:50:51.987Z,1747155051.987 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-05-13T16:50:51.987Z,1747155051.987 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-05-13T16:50:51.987Z,1747155051.987 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-05-13T16:50:51.987Z,1747155051.987 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-05-13T16:50:51.987Z,1747155051.987 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-05-13T16:50:51.987Z,1747155051.987 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-05-13T16:50:51.987Z,1747155051.987 [transit:BackseatDriver] Stopped
2025-05-13T16:50:51.987Z,1747155051.987 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2025-05-13T16:50:51.987Z,1747155051.987 [transit:BackseatDriver:A.BackseatDriver] Stopped
2025-05-13T16:50:51.987Z,1747155051.987 [transit:PowerOnly] Stopped
2025-05-13T16:50:51.987Z,1747155051.987 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2025-05-13T16:50:51.987Z,1747155051.987 [transit:PowerOnly:E.Wait] Stopped
2025-05-13T16:50:51.987Z,1747155051.987 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-05-13T16:50:51.988Z,1747155051.988 [transit:BuoyancyHold.Buoyancy] Stopped
2025-05-13T16:50:51.988Z,1747155051.988 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-05-13T16:50:51.988Z,1747155051.988 [transit:MassHold.Pitch] Stopped
2025-05-13T16:50:51.988Z,1747155051.988 [transit:Transit] Stopped
2025-05-13T16:50:51.988Z,1747155051.988 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2025-05-13T16:50:51.988Z,1747155051.988 [transit:Transit:A.Pitch] Stopped
2025-05-13T16:50:51.988Z,1747155051.988 [transit:Transit:B.SetSpeed] Stopped
2025-05-13T16:50:51.988Z,1747155051.988 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2025-05-13T16:50:51.988Z,1747155051.988 [transit:Transit:PhoneHome] Stopped
2025-05-13T16:50:51.988Z,1747155051.988 [transit:Transit:PhoneHome](DEBUG): Aggregate::uninitialize transit:Transit:PhoneHome
2025-05-13T16:50:52.233Z,1747155052.233 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-13T16:50:52.233Z,1747155052.233 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:52.249Z,1747155052.249 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-05-13T16:50:52.249Z,1747155052.249 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:52.249Z,1747155052.249 [Radio_Surface](INFO): Join timeout helper Thread ID is 6516
2025-05-13T16:50:52.393Z,1747155052.393 [MissionManager](IMPORTANT): Started mission Default
2025-05-13T16:50:52.393Z,1747155052.393 [Default] Running Loop=1
2025-05-13T16:50:52.393Z,1747155052.393 [Default](DEBUG): Aggregate::initialize Default
2025-05-13T16:50:52.393Z,1747155052.393 [Default:B.GoToSurface] Running Loop=1
2025-05-13T16:50:52.393Z,1747155052.393 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-13T16:50:52.393Z,1747155052.393 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-05-13T16:50:52.394Z,1747155052.394 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-05-13T16:50:52.394Z,1747155052.394 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-05-13T16:50:52.395Z,1747155052.395 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-05-13T16:50:52.395Z,1747155052.395 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-13T16:50:52.395Z,1747155052.395 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-13T16:50:52.395Z,1747155052.395 [Default:A.Wait] Running Loop=1
2025-05-13T16:50:52.396Z,1747155052.396 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-05-13T16:50:52.505Z,1747155052.505 [Radio_Surface](INFO): Powering down
2025-05-13T16:50:52.506Z,1747155052.506 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-13T16:50:52.506Z,1747155052.506 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:52.513Z,1747155052.513 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-05-13T16:50:52.513Z,1747155052.513 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:52.513Z,1747155052.513 [Onboard](INFO): Join timeout helper Thread ID is 6517
2025-05-13T16:50:55.649Z,1747155055.649 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-13T16:50:55.649Z,1747155055.649 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:55.657Z,1747155055.657 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-05-13T16:50:55.657Z,1747155055.657 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:55.657Z,1747155055.657 [DataOverHttps](INFO): Join timeout helper Thread ID is 6518
2025-05-13T16:50:56.441Z,1747155056.441 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-13T16:50:56.442Z,1747155056.442 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:56.442Z,1747155056.442 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-05-13T16:50:56.443Z,1747155056.443 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:56.443Z,1747155056.443 [DAT](INFO): Join timeout helper Thread ID is 6519
2025-05-13T16:50:56.581Z,1747155056.581 [DAT](INFO): Powering down
2025-05-13T16:50:56.653Z,1747155056.653 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-13T16:50:56.653Z,1747155056.653 [DAT](INFO): Powering down
2025-05-13T16:50:56.654Z,1747155056.654 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:56.657Z,1747155056.657 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-05-13T16:50:56.658Z,1747155056.658 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:56.658Z,1747155056.658 [BackseatComponent](INFO): Join timeout helper Thread ID is 6520
2025-05-13T16:50:56.849Z,1747155056.849 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-13T16:50:56.849Z,1747155056.849 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:56.857Z,1747155056.857 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-05-13T16:50:56.858Z,1747155056.858 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:56.858Z,1747155056.858 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6521
2025-05-13T16:50:57.117Z,1747155057.117 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-13T16:50:57.117Z,1747155057.117 [WetLabsBB2FL](INFO): Powering down
2025-05-13T16:50:57.118Z,1747155057.118 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:57.125Z,1747155057.125 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-05-13T16:50:57.125Z,1747155057.125 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:57.126Z,1747155057.126 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6522
2025-05-13T16:50:57.797Z,1747155057.797 [CTD_Seabird](INFO): Powering down
2025-05-13T16:50:57.809Z,1747155057.809 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-13T16:50:57.809Z,1747155057.809 [CTD_Seabird](INFO): Powering down
2025-05-13T16:50:57.821Z,1747155057.821 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:57.825Z,1747155057.825 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-05-13T16:50:57.825Z,1747155057.825 [logger ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:57.826Z,1747155057.826 [logger](INFO): Join timeout helper Thread ID is 6523
2025-05-13T16:50:57.841Z,1747155057.841 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-13T16:50:57.841Z,1747155057.841 [logger ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:57.845Z,1747155057.845 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-05-13T16:50:57.845Z,1747155057.845 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:57.846Z,1747155057.846 [CommandLine](INFO): Join timeout helper Thread ID is 6524
2025-05-13T16:50:57.849Z,1747155057.849 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-13T16:50:57.849Z,1747155057.849 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:57.865Z,1747155057.865 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-05-13T16:50:57.865Z,1747155057.865 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:57.866Z,1747155057.866 [CommandExec](INFO): Join timeout helper Thread ID is 6525
2025-05-13T16:50:57.867Z,1747155057.867 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-05-13T16:50:57.867Z,1747155057.867 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:57.867Z,1747155057.867 [controlThread](INFO): Join timeout helper Thread ID is 6526
2025-05-13T16:50:58.009Z,1747155058.009 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-13T16:50:58.009Z,1747155058.009 [controlThread](DEBUG): Uninitializing ControlThread
2025-05-13T16:50:58.010Z,1747155058.010 [AHRS_M2](INFO): Powering down
2025-05-13T16:50:58.089Z,1747155058.089 [NAL9602](INFO): Powering down
2025-05-13T16:50:58.090Z,1747155058.090 [Sonardyne_Nano](INFO): Powering down
2025-05-13T16:50:58.293Z,1747155058.293 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-05-13T16:50:58.294Z,1747155058.294 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-05-13T16:50:58.294Z,1747155058.294 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-05-13T16:50:58.295Z,1747155058.295 [MissionManager](INFO): Uninitializing Mission Default
2025-05-13T16:50:58.295Z,1747155058.295 [Default] Stopped
2025-05-13T16:50:58.295Z,1747155058.295 [Default](DEBUG): Aggregate::uninitialize Default
2025-05-13T16:50:58.295Z,1747155058.295 [Default:A.Wait] Stopped
2025-05-13T16:50:58.295Z,1747155058.295 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-05-13T16:50:58.295Z,1747155058.295 [Default:B.GoToSurface] Stopped
2025-05-13T16:50:58.295Z,1747155058.295 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-13T16:50:58.298Z,1747155058.298 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-05-13T16:50:58.298Z,1747155058.298 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-05-13T16:50:58.299Z,1747155058.299 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-05-13T16:50:58.299Z,1747155058.299 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-05-13T16:50:58.299Z,1747155058.299 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-05-13T16:50:58.299Z,1747155058.299 [BuoyancyServo](INFO): Powering down
2025-05-13T16:50:58.313Z,1747155058.313 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-05-13T16:50:58.313Z,1747155058.313 [ElevatorServo](INFO): Powering down
2025-05-13T16:50:58.314Z,1747155058.314 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-05-13T16:50:58.314Z,1747155058.314 [MassServo](INFO): Powering down
2025-05-13T16:50:58.314Z,1747155058.314 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-05-13T16:50:58.315Z,1747155058.315 [RudderServo](INFO): Powering down
2025-05-13T16:50:58.315Z,1747155058.315 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-05-13T16:50:58.315Z,1747155058.315 [ThrusterHE](INFO): Powering down
2025-05-13T16:50:58.316Z,1747155058.316 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-05-13T16:50:58.317Z,1747155058.317 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-05-13T16:50:58.317Z,1747155058.317 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-05-13T16:50:58.317Z,1747155058.317 [CBIT](DEBUG): Powering off loads.
2025-05-13T16:50:58.328Z,1747155058.328 [CBIT](DEBUG): Disabling WDT.
2025-05-13T16:50:58.341Z,1747155058.341 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-05-13T16:50:58.341Z,1747155058.341 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-05-13T16:50:58.342Z,1747155058.342 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:58.367Z,1747155058.367 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-05-13T16:50:58.367Z,1747155058.367 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-05-13T16:50:58.381Z,1747155058.381 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:58.388Z,1747155058.388 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:58.419Z,1747155058.419 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:58.422Z,1747155058.422 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:58.429Z,1747155058.429 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:58.435Z,1747155058.435 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:58.477Z,1747155058.477 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:58.531Z,1747155058.531 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-13T16:50:58.607Z,1747155058.607 [logger ThreadHandler](INFO): Thread cancelled.