2025-05-14T18:27:57.511Z,1747247277.511 [DataOverHttps](INFO): Received command: restart logs 2025-05-14T18:27:57.521Z,1747247277.521 [CommandExec](IMPORTANT): got command restart logs 2025-05-14T18:28:32.614Z,1747247312.614 [DataOverHttps](IMPORTANT): SBD MTMSN=20250514T182830 2025-05-14T18:28:33.128Z,1747247313.128 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-14T18:28:33.128Z,1747247313.128 [Default:CheckIn:C.Wait] Stopped 2025-05-14T18:28:33.128Z,1747247313.128 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-14T18:28:33.129Z,1747247313.129 [Default:CheckIn:D] Running Loop=1 2025-05-14T18:28:33.531Z,1747247313.531 [Default:CheckIn:D] Stopped 2025-05-14T18:28:33.531Z,1747247313.531 [Default:CheckIn:E] Running Loop=1 2025-05-14T18:28:33.998Z,1747247313.998 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.451978 min 2025-05-14T18:28:33.998Z,1747247313.998 [Default:CheckIn:E] Stopped 2025-05-14T18:28:33.998Z,1747247313.998 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-14T18:28:33.998Z,1747247313.998 [Default:CheckIn] Stopped 2025-05-14T18:28:33.998Z,1747247313.998 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-14T18:28:33.998Z,1747247313.998 [Default:CheckIn](INFO): Running loop #9 2025-05-14T18:28:33.998Z,1747247313.998 [Default:CheckIn] Running Loop=9 2025-05-14T18:28:33.999Z,1747247313.999 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-14T18:28:33.999Z,1747247313.999 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-14T18:28:35.958Z,1747247315.958 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182834.00,A,3648.26323,N,12149.87400,W,1.808,104.29,140525,,,A*7B 2025-05-14T18:28:35.961Z,1747247315.961 [NAL9602](INFO): GPS fix at 20250514T182834: (36.804387, -121.831233) 2025-05-14T18:28:36.071Z,1747247316.071 [Default:CheckIn:Read_GPS] Stopped 2025-05-14T18:28:36.071Z,1747247316.071 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-14T18:28:44.916Z,1747247324.916 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250514T161756/Courier0085.lzma 2025-05-14T18:28:44.919Z,1747247324.919 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 5 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Speed 1.5 m/s;run 2025-05-14T18:28:44.996Z,1747247324.996 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl 2025-05-14T18:28:44.997Z,1747247324.997 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl 2025-05-14T18:28:44.997Z,1747247324.997 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl 2025-05-14T18:28:44.998Z,1747247324.998 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl 2025-05-14T18:28:46.173Z,1747247326.173 [DataOverHttps](INFO): Moved sent file to Logs/20250514T161756/Courier0085.lzma.bak 2025-05-14T18:28:46.174Z,1747247326.174 [DataOverHttps](INFO): SBD MOMSN=24874624 2025-05-14T18:28:47.511Z,1747247327.511 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into: 60 30 NaN NaN 0 0 10 1 20 1 7 1 2025-05-14T18:28:47.523Z,1747247327.523 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min 2025-05-14T18:28:47.526Z,1747247327.526 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min 2025-05-14T18:28:47.612Z,1747247327.612 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg 2025-05-14T18:28:47.632Z,1747247327.632 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg 2025-05-14T18:28:47.635Z,1747247327.635 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m 2025-05-14T18:28:47.697Z,1747247327.697 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m 2025-05-14T18:28:47.710Z,1747247327.710 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m 2025-05-14T18:28:47.723Z,1747247327.723 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s 2025-05-14T18:28:47.741Z,1747247327.741 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m 2025-05-14T18:28:47.752Z,1747247327.752 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km 2025-05-14T18:28:47.754Z,1747247327.754 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m 2025-05-14T18:28:47.765Z,1747247327.765 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool 2025-05-14T18:28:47.767Z,1747247327.767 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool 2025-05-14T18:28:47.780Z,1747247327.780 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-05-14T18:28:47.780Z,1747247327.780 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-05-14T18:28:47.781Z,1747247327.781 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-05-14T18:28:48.892Z,1747247328.892 [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-14T18:28:48.913Z,1747247328.913 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h 2025-05-14T18:28:48.963Z,1747247328.963 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min 2025-05-14T18:28:48.965Z,1747247328.965 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-05-14T18:28:49.015Z,1747247329.015 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s 2025-05-14T18:28:49.017Z,1747247329.017 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-05-14T18:28:49.052Z,1747247329.052 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s 2025-05-14T18:28:49.054Z,1747247329.054 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min 2025-05-14T18:28:49.062Z,1747247329.062 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min 2025-05-14T18:28:49.129Z,1747247329.129 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-05-14T18:28:49.324Z,1747247329.324 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-05-14T18:28:49.325Z,1747247329.325 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-05-14T18:28:49.327Z,1747247329.327 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-05-14T18:28:49.803Z,1747247329.803 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2025-05-14T18:28:49.824Z,1747247329.824 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m 2025-05-14T18:28:49.826Z,1747247329.826 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2025-05-14T18:28:49.833Z,1747247329.833 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m 2025-05-14T18:28:49.848Z,1747247329.848 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-05-14T18:28:49.849Z,1747247329.849 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-05-14T18:28:49.900Z,1747247329.900 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-05-14T18:28:49.954Z,1747247329.954 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-05-14T18:28:50.010Z,1747247330.010 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-05-14T18:28:50.011Z,1747247330.011 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-05-14T18:28:50.056Z,1747247330.056 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-05-14T18:28:50.248Z,1747247330.248 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-05-14T18:28:50.264Z,1747247330.264 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool 2025-05-14T18:28:50.266Z,1747247330.266 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-05-14T18:28:50.289Z,1747247330.289 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-05-14T18:28:50.289Z,1747247330.289 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-05-14T18:28:50.291Z,1747247330.291 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-05-14T18:28:51.013Z,1747247331.013 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-05-14T18:28:51.021Z,1747247331.021 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool 2025-05-14T18:28:51.036Z,1747247331.036 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool 2025-05-14T18:28:51.038Z,1747247331.038 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool 2025-05-14T18:28:51.045Z,1747247331.045 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool 2025-05-14T18:28:51.059Z,1747247331.059 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool 2025-05-14T18:28:51.140Z,1747247331.140 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-05-14T18:28:51.141Z,1747247331.141 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2025-05-14T18:28:51.161Z,1747247331.161 [transit:MassHold.Pitch](DEBUG): Construct. 2025-05-14T18:28:51.241Z,1747247331.241 [transit:Transit:A.Pitch](DEBUG): Construct. 2025-05-14T18:28:51.271Z,1747247331.271 [transit:Transit:B.SetSpeed](DEBUG): Construct. 2025-05-14T18:28:51.297Z,1747247331.297 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-05-14T18:28:51.393Z,1747247331.393 [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-14T18:28:51.394Z,1747247331.394 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit 2025-05-14T18:28:54.933Z,1747247334.933 [NAL9602](INFO): SBD MO Status=0, MOMSN=10228, MT Status=0, MTMSN=0 2025-05-14T18:28:54.933Z,1747247334.933 [NAL9602](INFO): No messages in MT queue 2025-05-14T18:29:18.802Z,1747247358.802 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250514T182757/Courier0000.lzma 2025-05-14T18:29:19.801Z,1747247359.801 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Courier0000.lzma.bak 2025-05-14T18:29:19.801Z,1747247359.801 [DataOverHttps](INFO): SBD MOMSN=24874627 2025-05-14T18:29:20.237Z,1747247360.237 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 30 minute 2025-05-14T18:29:20.238Z,1747247360.238 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 5 minute 2025-05-14T18:29:20.251Z,1747247360.251 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree 2025-05-14T18:29:20.252Z,1747247360.252 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree 2025-05-14T18:29:20.253Z,1747247360.253 [CommandExec](IMPORTANT): got command set transit.Speed 1.5 meter_per_second 2025-05-14T18:29:20.253Z,1747247360.253 [CommandExec](IMPORTANT): got command run 2025-05-14T18:29:20.284Z,1747247360.284 [CommandExec](IMPORTANT): Running 2025-05-14T18:29:20.619Z,1747247360.619 [Default] Stopped 2025-05-14T18:29:20.619Z,1747247360.619 [Default](DEBUG): Aggregate::uninitialize Default 2025-05-14T18:29:20.619Z,1747247360.619 [Default:B.GoToSurface] Stopped 2025-05-14T18:29:20.619Z,1747247360.619 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-05-14T18:29:20.619Z,1747247360.619 [Default:CheckIn] Stopped 2025-05-14T18:29:20.619Z,1747247360.619 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-14T18:29:20.619Z,1747247360.619 [Default:CheckIn:Read_Iridium] Stopped 2025-05-14T18:29:20.620Z,1747247360.620 [MissionManager](IMPORTANT): Started mission transit 2025-05-14T18:29:20.620Z,1747247360.620 [transit] Running Loop=1 2025-05-14T18:29:20.620Z,1747247360.620 [transit](DEBUG): Aggregate::initialize transit 2025-05-14T18:29:20.620Z,1747247360.620 [transit:StandardEnvelopes] Running Loop=1 2025-05-14T18:29:20.620Z,1747247360.620 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes 2025-05-14T18:29:20.620Z,1747247360.620 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-05-14T18:29:20.620Z,1747247360.620 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-05-14T18:29:20.620Z,1747247360.620 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-05-14T18:29:20.621Z,1747247360.621 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-05-14T18:29:20.621Z,1747247360.621 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-05-14T18:29:20.621Z,1747247360.621 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-05-14T18:29:20.621Z,1747247360.621 [transit:BackseatDriver] Running Loop=1 2025-05-14T18:29:20.621Z,1747247360.621 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver 2025-05-14T18:29:20.621Z,1747247360.621 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-05-14T18:29:20.621Z,1747247360.621 [transit:PowerOnly] Running Loop=1 2025-05-14T18:29:20.621Z,1747247360.621 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly 2025-05-14T18:29:20.621Z,1747247360.621 [transit:PowerOnly:A] Running Loop=1 2025-05-14T18:29:20.621Z,1747247360.621 [transit:PowerOnly:B] Running Loop=1 2025-05-14T18:29:20.621Z,1747247360.621 [transit:PowerOnly:C] Running Loop=1 2025-05-14T18:29:20.622Z,1747247360.622 [transit:PowerOnly:D] Running Loop=1 2025-05-14T18:29:20.622Z,1747247360.622 [transit:PowerOnly:E.Wait] Running Loop=1 2025-05-14T18:29:20.622Z,1747247360.622 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-05-14T18:29:20.622Z,1747247360.622 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2025-05-14T18:29:20.622Z,1747247360.622 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-05-14T18:29:20.622Z,1747247360.622 [transit:MassHold.Pitch] Running Loop=1 2025-05-14T18:29:20.622Z,1747247360.622 [transit:MassHold.Pitch](DEBUG): Initialize. 2025-05-14T18:29:20.622Z,1747247360.622 [transit:B] Running Loop=1 2025-05-14T18:29:20.627Z,1747247360.627 [transit:MassHold.Pitch] Running Loop=1 2025-05-14T18:29:20.628Z,1747247360.628 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2025-05-14T18:29:20.628Z,1747247360.628 [transit:PowerOnly] Running Loop=1 2025-05-14T18:29:20.628Z,1747247360.628 [transit:PowerOnly:D] Stopped 2025-05-14T18:29:20.628Z,1747247360.628 [transit:PowerOnly:C] Stopped 2025-05-14T18:29:20.628Z,1747247360.628 [transit:PowerOnly:B] Stopped 2025-05-14T18:29:20.628Z,1747247360.628 [transit:PowerOnly:A] Stopped 2025-05-14T18:29:20.628Z,1747247360.628 [transit:BackseatDriver] Running Loop=1 2025-05-14T18:29:20.629Z,1747247360.629 [transit:StandardEnvelopes] Running Loop=1 2025-05-14T18:29:20.629Z,1747247360.629 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-05-14T18:29:20.629Z,1747247360.629 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-05-14T18:29:20.629Z,1747247360.629 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-05-14T18:29:20.631Z,1747247360.631 [transit:B] Stopped 2025-05-14T18:29:20.635Z,1747247360.635 [transit:C] Running Loop=1 2025-05-14T18:29:21.018Z,1747247361.018 [transit:C] Stopped 2025-05-14T18:29:21.018Z,1747247361.018 [transit:E] Running Loop=1 2025-05-14T18:29:21.446Z,1747247361.446 [transit:E] Stopped 2025-05-14T18:29:21.446Z,1747247361.446 [transit:F] Running Loop=1 2025-05-14T18:29:21.888Z,1747247361.888 [transit:F] Stopped 2025-05-14T18:29:21.888Z,1747247361.888 [transit:G] Running Loop=1 2025-05-14T18:29:22.242Z,1747247362.242 [transit:G] Stopped 2025-05-14T18:29:22.242Z,1747247362.242 [transit:Transit] Running Loop=1 2025-05-14T18:29:22.242Z,1747247362.242 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit 2025-05-14T18:29:22.242Z,1747247362.242 [transit:Transit:A.Pitch] Running Loop=1 2025-05-14T18:29:22.242Z,1747247362.242 [transit:Transit:A.Pitch](DEBUG): Initialize. 2025-05-14T18:29:22.243Z,1747247362.243 [transit:Transit:B.SetSpeed] Running Loop=1 2025-05-14T18:29:22.243Z,1747247362.243 [transit:Transit:B.SetSpeed](DEBUG): Initialize. 2025-05-14T18:29:22.243Z,1747247362.243 [transit:Transit:Wpt1.Waypoint] Running Loop=1 2025-05-14T18:29:22.243Z,1747247362.243 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-05-14T18:29:22.244Z,1747247362.244 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2025-05-14T18:29:22.633Z,1747247362.634 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2025-05-14T18:29:22.634Z,1747247362.634 [transit:Transit:B.SetSpeed] Running Loop=1 2025-05-14T18:29:22.634Z,1747247362.634 [transit:Transit:A.Pitch] Running Loop=1 2025-05-14T18:29:25.452Z,1747247365.452 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-14T18:30:21.875Z,1747247421.875 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T18:30:44.111Z,1747247444.111 [Radio_Surface](INFO): Powering down 2025-05-14T18:30:53.007Z,1747247453.007 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T18:30:54.011Z,1747247454.011 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-05-14T18:32:31.443Z,1747247551.443 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.771240 2025-05-14T18:33:36.095Z,1747247616.095 [transit:NeedComms] Running Loop=1 2025-05-14T18:33:36.095Z,1747247616.095 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms 2025-05-14T18:33:36.095Z,1747247616.095 [transit:NeedComms:B.GoToSurface] Running Loop=1 2025-05-14T18:33:36.095Z,1747247616.095 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-05-14T18:33:36.095Z,1747247616.095 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-05-14T18:33:36.096Z,1747247616.096 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-05-14T18:33:36.096Z,1747247616.096 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-05-14T18:33:36.097Z,1747247616.097 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-05-14T18:33:36.097Z,1747247616.097 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-05-14T18:33:36.097Z,1747247616.097 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-05-14T18:33:36.098Z,1747247616.098 [transit:NeedComms:A] Running Loop=1 2025-05-14T18:33:36.100Z,1747247616.100 [transit:NeedComms:A](INFO): last time_fix was: 1747247314.000000 second since 1970/01/01T00:00:00Z 2025-05-14T18:33:36.100Z,1747247616.100 [transit:NeedComms:A] Stopped 2025-05-14T18:33:40.491Z,1747247620.491 [ThrusterHE](FAULT): Could not get status:serial timeout 2025-05-14T18:33:40.587Z,1747247620.587 [ThrusterHE](FAULT): getSpeed error serial timeout 2025-05-14T18:33:40.587Z,1747247620.587 [ThrusterHE](FAULT): Thruster uart error: serial timeout 2025-05-14T18:33:40.587Z,1747247620.587 [ThrusterHE] Communications Fault, FailCount= 1 2025-05-14T18:33:40.587Z,1747247620.587 [ThrusterHE](ERROR): Communications Fault 2025-05-14T18:33:40.591Z,1747247620.591 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-05-14T18:33:40.772Z,1747247620.772 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-14T18:33:40.772Z,1747247620.772 [ThrusterHE](INFO): Powering down 2025-05-14T18:33:41.641Z,1747247621.641 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-14T18:33:41.761Z,1747247621.761 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-14T18:33:41.766Z,1747247621.766 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-05-14T18:33:41.766Z,1747247621.766 [ThrusterHE] No Fault, FailCount= 1 2025-05-14T18:33:43.772Z,1747247623.772 [ThrusterHE](FAULT): Thruster failed to initialize 2025-05-14T18:33:43.772Z,1747247623.772 [ThrusterHE] Communications Fault, FailCount= 2 2025-05-14T18:33:43.772Z,1747247623.772 [ThrusterHE](ERROR): Communications Fault 2025-05-14T18:33:43.781Z,1747247623.781 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-05-14T18:33:44.084Z,1747247624.084 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-14T18:33:44.084Z,1747247624.084 [ThrusterHE](INFO): Powering down 2025-05-14T18:33:44.888Z,1747247624.888 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-14T18:33:45.008Z,1747247625.008 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-14T18:33:45.013Z,1747247625.013 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-05-14T18:33:45.013Z,1747247625.013 [ThrusterHE] No Fault, FailCount= 2 2025-05-14T18:33:47.253Z,1747247627.253 [ThrusterHE](FAULT): Thruster failed to initialize 2025-05-14T18:33:47.253Z,1747247627.253 [ThrusterHE] Communications Fault, FailCount= 3 2025-05-14T18:33:47.253Z,1747247627.253 [ThrusterHE](ERROR): Communications Fault 2025-05-14T18:33:47.258Z,1747247627.258 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-05-14T18:33:47.661Z,1747247627.661 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-14T18:33:47.661Z,1747247627.661 [ThrusterHE](INFO): Powering down 2025-05-14T18:33:48.100Z,1747247628.100 [ThrusterHE](FAULT): LCB fault: Current Limiter Activated. 2025-05-14T18:33:48.100Z,1747247628.100 [ThrusterHE] Hardware Fault, FailCount= 3 2025-05-14T18:33:48.100Z,1747247628.100 [ThrusterHE](ERROR): Hardware Fault 2025-05-14T18:33:48.496Z,1747247628.496 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-14T18:33:48.616Z,1747247628.616 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-14T18:33:48.620Z,1747247628.620 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-05-14T18:33:48.620Z,1747247628.620 [ThrusterHE] No Fault, FailCount= 3 2025-05-14T18:34:15.504Z,1747247655.504 [transit:NeedComms:C] Running Loop=1 2025-05-14T18:34:15.967Z,1747247655.967 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-05-14T18:34:16.223Z,1747247656.223 [Radio_Surface](INFO): Powering up 2025-05-14T18:34:21.895Z,1747247661.895 [DataOverHttps](INFO): Radio surface powered ON. 2025-05-14T18:34:36.122Z,1747247676.122 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-05-14T18:34:36.122Z,1747247676.122 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-05-14T18:34:36.207Z,1747247676.207 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-05-14T18:34:36.606Z,1747247676.606 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-05-14T18:34:36.606Z,1747247676.606 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-05-14T18:34:52.015Z,1747247692.015 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T18:34:55.070Z,1747247695.070 [NAL9602](INFO): SBD MO Status=2, MOMSN=10229, MT Status=2, MTMSN=0 2025-05-14T18:34:55.070Z,1747247695.070 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T18:35:23.151Z,1747247723.151 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T18:35:54.288Z,1747247754.288 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T18:36:18.296Z,1747247778.296 [NAL9602](INFO): SBD MO Status=2, MOMSN=10229, MT Status=2, MTMSN=0 2025-05-14T18:36:18.296Z,1747247778.296 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T18:36:25.423Z,1747247785.423 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T18:36:34.668Z,1747247794.668 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003038 2025-05-14T18:39:00.232Z,1747247940.232 [CommandExec](IMPORTANT): got command show stack 2025-05-14T18:39:00.232Z,1747247940.232 [CommandExec](IMPORTANT): Behavior Stack: 2025-05-14T18:39:00.233Z,1747247940.233 [transit:NeedComms](IMPORTANT): Priority 0: transit:NeedComms:B.GoToSurface 2025-05-14T18:39:00.233Z,1747247940.233 [transit:NeedComms](IMPORTANT): Priority 1: transit:NeedComms:C 2025-05-14T18:39:00.233Z,1747247940.233 [transit:StandardEnvelopes](IMPORTANT): Priority 2: transit:StandardEnvelopes:A.AltitudeEnvelope 2025-05-14T18:39:00.234Z,1747247940.234 [transit:StandardEnvelopes](IMPORTANT): Priority 3: transit:StandardEnvelopes:B.DepthEnvelope 2025-05-14T18:39:00.234Z,1747247940.234 [transit:StandardEnvelopes](IMPORTANT): Priority 4: transit:StandardEnvelopes:C.OffshoreEnvelope 2025-05-14T18:39:00.234Z,1747247940.234 [transit:BackseatDriver](IMPORTANT): Priority 5: transit:BackseatDriver:A.BackseatDriver 2025-05-14T18:39:00.235Z,1747247940.235 [transit:PowerOnly](IMPORTANT): Priority 6: transit:PowerOnly:E.Wait 2025-05-14T18:39:00.236Z,1747247940.236 [transit](IMPORTANT): Priority 7: transit:BuoyancyHold.Buoyancy 2025-05-14T18:39:00.236Z,1747247940.236 [transit](IMPORTANT): Priority 8: transit:MassHold.Pitch 2025-05-14T18:39:00.237Z,1747247940.237 [transit:Transit](IMPORTANT): Priority 9: transit:Transit:A.Pitch 2025-05-14T18:39:00.237Z,1747247940.237 [transit:Transit](IMPORTANT): Priority 10: transit:Transit:B.SetSpeed 2025-05-14T18:39:00.238Z,1747247940.238 [transit:Transit](IMPORTANT): Priority 11: transit:Transit:Wpt1.Waypoint 2025-05-14T18:39:14.200Z,1747247954.200 [CommandExec](IMPORTANT): got command failComponent 2025-05-14T18:39:14.200Z,1747247954.200 [CommandExec](IMPORTANT): Failed components: 2025-05-14T18:39:14.201Z,1747247954.201 [CommandExec](IMPORTANT): No failed Components. 2025-05-14T18:39:24.752Z,1747247964.752 [CommandExec](IMPORTANT): got command stop 2025-05-14T18:39:24.752Z,1747247964.752 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2025-05-14T18:39:25.038Z,1747247965.038 [MissionManager](INFO): MissionManager is completed. 2025-05-14T18:39:25.038Z,1747247965.038 [MissionManager](INFO): Uninitializing Mission transit 2025-05-14T18:39:25.038Z,1747247965.038 [transit] Stopped 2025-05-14T18:39:25.038Z,1747247965.038 [transit](DEBUG): Aggregate::uninitialize transit 2025-05-14T18:39:25.039Z,1747247965.039 [transit:NeedComms] Stopped 2025-05-14T18:39:25.039Z,1747247965.039 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms 2025-05-14T18:39:25.039Z,1747247965.039 [transit:NeedComms:B.GoToSurface] Stopped 2025-05-14T18:39:25.039Z,1747247965.039 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-05-14T18:39:25.039Z,1747247965.039 [transit:NeedComms:C] Stopped 2025-05-14T18:39:25.039Z,1747247965.039 [transit:StandardEnvelopes] Stopped 2025-05-14T18:39:25.039Z,1747247965.039 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2025-05-14T18:39:25.039Z,1747247965.039 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-05-14T18:39:25.039Z,1747247965.039 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-05-14T18:39:25.039Z,1747247965.039 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-05-14T18:39:25.040Z,1747247965.040 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-05-14T18:39:25.040Z,1747247965.040 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-05-14T18:39:25.040Z,1747247965.040 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-05-14T18:39:25.040Z,1747247965.040 [transit:BackseatDriver] Stopped 2025-05-14T18:39:25.040Z,1747247965.040 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver 2025-05-14T18:39:25.040Z,1747247965.040 [transit:BackseatDriver:A.BackseatDriver] Stopped 2025-05-14T18:39:25.040Z,1747247965.040 [transit:PowerOnly] Stopped 2025-05-14T18:39:25.040Z,1747247965.040 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly 2025-05-14T18:39:25.040Z,1747247965.040 [transit:PowerOnly:E.Wait] Stopped 2025-05-14T18:39:25.040Z,1747247965.040 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-05-14T18:39:25.040Z,1747247965.040 [transit:BuoyancyHold.Buoyancy] Stopped 2025-05-14T18:39:25.040Z,1747247965.040 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-05-14T18:39:25.040Z,1747247965.040 [transit:MassHold.Pitch] Stopped 2025-05-14T18:39:25.041Z,1747247965.041 [transit:Transit] Stopped 2025-05-14T18:39:25.041Z,1747247965.041 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2025-05-14T18:39:25.041Z,1747247965.041 [transit:Transit:A.Pitch] Stopped 2025-05-14T18:39:25.041Z,1747247965.041 [transit:Transit:B.SetSpeed] Stopped 2025-05-14T18:39:25.041Z,1747247965.041 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2025-05-14T18:39:25.041Z,1747247965.041 [transit:Transit:Wpt1.Waypoint] Stopped 2025-05-14T18:39:25.041Z,1747247965.041 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-05-14T18:39:25.437Z,1747247965.437 [MissionManager](IMPORTANT): Started mission Default 2025-05-14T18:39:25.437Z,1747247965.437 [Default] Running Loop=1 2025-05-14T18:39:25.437Z,1747247965.437 [Default](DEBUG): Aggregate::initialize Default 2025-05-14T18:39:25.437Z,1747247965.437 [Default:B.GoToSurface] Running Loop=1 2025-05-14T18:39:25.438Z,1747247965.438 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-05-14T18:39:25.438Z,1747247965.438 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-05-14T18:39:25.438Z,1747247965.438 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-05-14T18:39:25.438Z,1747247965.438 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-05-14T18:39:25.443Z,1747247965.443 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-05-14T18:39:25.443Z,1747247965.443 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-05-14T18:39:25.444Z,1747247965.444 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-05-14T18:39:25.444Z,1747247965.444 [Default:A.Wait] Running Loop=1 2025-05-14T18:39:25.444Z,1747247965.444 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-05-14T18:39:26.206Z,1747247966.206 [NAL9602](INFO): SBD MO Status=2, MOMSN=10229, MT Status=2, MTMSN=0 2025-05-14T18:39:26.206Z,1747247966.206 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T18:39:26.609Z,1747247966.609 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-05-14T18:39:28.632Z,1747247968.632 [CommandExec](IMPORTANT): got command ibit 2025-05-14T18:39:28.723Z,1747247968.723 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-05-14T18:39:28.727Z,1747247968.727 [IBIT](IMPORTANT): Beginning control surface checks. 2025-05-14T18:39:28.731Z,1747247968.731 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-05-14T18:39:30.249Z,1747247970.249 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183928.00,A,3648.15542,N,12150.01736,W,1.341,114.02,140525,,,A*79 2025-05-14T18:39:30.252Z,1747247970.252 [NAL9602](INFO): GPS fix at 20250514T183928: (36.802590, -121.833623) 2025-05-14T18:39:33.181Z,1747247973.181 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183931.00,A,3648.15509,N,12150.01717,W,0.330,124.69,140525,,,A*74 2025-05-14T18:39:33.187Z,1747247973.187 [NAL9602](INFO): GPS fix at 20250514T183931: (36.802585, -121.833619) 2025-05-14T18:39:33.429Z,1747247973.429 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000262 CHAN A1 (24V): 0.002006 CHAN A2 (12V): 0.000898 CHAN A3 (5V): -0.011503 CHAN B0 (3.3V): -0.000329 CHAN B1 (3.15aV): -0.000390 CHAN B2 (3.15bV): -0.000600 CHAN B3 (GND): -0.001893 OPEN: 0.008414 Full Scale: +/- 1 mA 2025-05-14T18:39:36.038Z,1747247976.038 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183934.00,A,3648.15491,N,12150.01690,W,0.525,127.47,140525,,,A*72 2025-05-14T18:39:36.040Z,1747247976.040 [NAL9602](INFO): GPS fix at 20250514T183934: (36.802582, -121.833615) 2025-05-14T18:39:39.278Z,1747247979.278 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183937.00,A,3648.15494,N,12150.01679,W,0.719,122.57,140525,,,A*7A 2025-05-14T18:39:39.336Z,1747247979.336 [NAL9602](INFO): GPS fix at 20250514T183937: (36.802582, -121.833613) 2025-05-14T18:39:41.160Z,1747247981.160 [DAT](INFO): DAT read: Rx Time:18:39:37.4644 2025-05-14T18:39:41.161Z,1747247981.161 [DAT](INFO): Rx dataTimestamp_ set to:1747247981.160380 2025-05-14T18:39:41.664Z,1747247981.664 [DAT](INFO): DAT read: $Error in header 2025-05-14T18:39:41.664Z,1747247981.664 [DAT](INFO): Received a bad header 2025-05-14T18:39:42.097Z,1747247982.097 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183940.00,A,3648.15497,N,12150.01712,W,0.525,122.57,140525,,,A*78 2025-05-14T18:39:42.099Z,1747247982.099 [NAL9602](INFO): GPS fix at 20250514T183940: (36.802583, -121.833619) 2025-05-14T18:39:45.338Z,1747247985.338 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183943.00,A,3648.15516,N,12150.01676,W,0.467,122.57,140525,,,A*77 2025-05-14T18:39:45.348Z,1747247985.348 [NAL9602](INFO): GPS fix at 20250514T183943: (36.802586, -121.833613) 2025-05-14T18:39:48.178Z,1747247988.178 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183946.00,A,3648.15496,N,12150.01619,W,0.467,111.58,140525,,,A*7D 2025-05-14T18:39:48.180Z,1747247988.180 [NAL9602](INFO): GPS fix at 20250514T183946: (36.802583, -121.833603) 2025-05-14T18:39:50.982Z,1747247990.982 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183949.00,A,3648.15530,N,12150.01667,W,1.244,324.50,140525,,,A*7C 2025-05-14T18:39:50.984Z,1747247990.984 [NAL9602](INFO): GPS fix at 20250514T183949: (36.802588, -121.833611) 2025-05-14T18:39:54.226Z,1747247994.226 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183952.00,A,3648.15533,N,12150.01645,W,1.127,94.04,140525,,,A*4A 2025-05-14T18:39:54.228Z,1747247994.228 [NAL9602](INFO): GPS fix at 20250514T183952: (36.802589, -121.833607) 2025-05-14T18:39:57.070Z,1747247997.070 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183955.00,A,3648.15535,N,12150.01587,W,0.194,84.51,140525,,,A*4E 2025-05-14T18:39:57.073Z,1747247997.073 [NAL9602](INFO): GPS fix at 20250514T183955: (36.802589, -121.833598) 2025-05-14T18:40:00.290Z,1747248000.290 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183958.00,A,3648.15536,N,12150.01601,W,0.836,60.26,140525,,,A*46 2025-05-14T18:40:00.293Z,1747248000.293 [NAL9602](INFO): GPS fix at 20250514T183958: (36.802589, -121.833600) 2025-05-14T18:40:03.132Z,1747248003.132 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184001.00,A,3648.15552,N,12150.01620,W,0.700,60.26,140525,,,A*4F 2025-05-14T18:40:03.134Z,1747248003.134 [NAL9602](INFO): GPS fix at 20250514T184001: (36.802592, -121.833603) 2025-05-14T18:40:05.932Z,1747248005.932 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184004.00,A,3648.15556,N,12150.01575,W,0.875,83.82,140525,,,A*43 2025-05-14T18:40:05.934Z,1747248005.934 [NAL9602](INFO): GPS fix at 20250514T184004: (36.802593, -121.833596) 2025-05-14T18:40:09.174Z,1747248009.174 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184007.00,A,3648.15554,N,12150.01511,W,0.311,71.93,140525,,,A*44 2025-05-14T18:40:09.177Z,1747248009.177 [NAL9602](INFO): GPS fix at 20250514T184007: (36.802592, -121.833585) 2025-05-14T18:40:12.034Z,1747248012.034 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184010.00,A,3648.15541,N,12150.01557,W,0.233,326.81,140525,,,A*77 2025-05-14T18:40:12.037Z,1747248012.037 [NAL9602](INFO): GPS fix at 20250514T184010: (36.802590, -121.833593) 2025-05-14T18:40:14.890Z,1747248014.890 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 2 Latitude: 36.802589 Longitude: -121.833595 2025-05-14T18:40:15.227Z,1747248015.227 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184013.00,A,3648.15545,N,12150.01547,W,0.175,326.81,140525,,,A*70 2025-05-14T18:40:15.229Z,1747248015.229 [NAL9602](INFO): GPS fix at 20250514T184013: (36.802591, -121.833591) 2025-05-14T18:40:15.249Z,1747248015.249 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active. 2025-05-14T18:40:15.669Z,1747248015.669 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-05-14T18:40:15.670Z,1747248015.670 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-05-14T18:40:15.670Z,1747248015.670 [IBIT](IMPORTANT): Pressure:9.518610 PSI 2025-05-14T18:40:15.670Z,1747248015.670 [IBIT](IMPORTANT): Humidity:1.440606 % 2025-05-14T18:40:16.102Z,1747248016.102 [IBIT](IMPORTANT): Vehicle Pitch:-3.799277 degrees 2025-05-14T18:40:16.103Z,1747248016.103 [IBIT](IMPORTANT): Vehicle Roll:-3.565332 degrees 2025-05-14T18:40:16.139Z,1747248016.139 [IBIT](IMPORTANT): Vehicle Heading:261.598999 degrees 2025-05-14T18:40:16.479Z,1747248016.479 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-05-14T18:40:16.480Z,1747248016.480 [IBIT](IMPORTANT): buoyancyNeutral: 1500.000000 cc 2025-05-14T18:40:16.480Z,1747248016.480 [IBIT](IMPORTANT): massDefault: -0.350000 cm 2025-05-14T18:40:16.480Z,1747248016.480 [IBIT](IMPORTANT): stopDepth: 975.000000 m 2025-05-14T18:40:16.481Z,1747248016.481 [IBIT](IMPORTANT): abortDepth: 990.000000 m 2025-05-14T18:40:16.481Z,1747248016.481 [IBIT](IMPORTANT): IBIT PASSED 2025-05-14T18:40:16.860Z,1747248016.860 [Default:A.Wait](INFO): Done Waiting. 2025-05-14T18:40:16.861Z,1747248016.861 [Default:A.Wait] Stopped 2025-05-14T18:40:16.861Z,1747248016.861 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-05-14T18:40:17.277Z,1747248017.277 [Default:CheckIn] Running Loop=1 2025-05-14T18:40:17.277Z,1747248017.277 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-14T18:40:17.278Z,1747248017.278 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-14T18:40:19.263Z,1747248019.263 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184017.00,A,3648.15542,N,12150.01443,W,0.330,46.11,140525,,,A*49 2025-05-14T18:40:19.266Z,1747248019.266 [NAL9602](INFO): GPS fix at 20250514T184017: (36.802590, -121.833574) 2025-05-14T18:40:19.281Z,1747248019.281 [Default:CheckIn:Read_GPS] Stopped 2025-05-14T18:40:19.281Z,1747248019.281 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-14T18:40:30.098Z,1747248030.098 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250514T182757/Courier0003.lzma 2025-05-14T18:40:31.101Z,1747248031.101 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Courier0003.lzma.bak 2025-05-14T18:40:31.101Z,1747248031.101 [DataOverHttps](INFO): SBD MOMSN=24874711 2025-05-14T18:40:51.176Z,1747248051.176 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20250514T182757/Courier0006.lzma 2025-05-14T18:40:51.769Z,1747248051.769 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-14T18:40:52.178Z,1747248052.178 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Courier0006.lzma.bak 2025-05-14T18:40:52.178Z,1747248052.178 [DataOverHttps](INFO): SBD MOMSN=24874713 2025-05-14T18:41:12.253Z,1747248072.253 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250514T182757/Courier0009.lzma 2025-05-14T18:41:14.257Z,1747248074.257 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Courier0009.lzma.bak 2025-05-14T18:41:14.257Z,1747248074.257 [DataOverHttps](INFO): SBD MOMSN=24874715 2025-05-14T18:41:34.711Z,1747248094.711 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20250514T161756/Express0087.lzma 2025-05-14T18:41:35.713Z,1747248095.713 [DataOverHttps](INFO): Moved sent file to Logs/20250514T161756/Express0087.lzma.bak 2025-05-14T18:41:35.714Z,1747248095.714 [DataOverHttps](INFO): SBD MOMSN=24874718 2025-05-14T18:41:53.256Z,1747248113.256 [DataOverHttps](INFO): Sending 528 bytes from file Logs/20250514T182757/Express0001.lzma 2025-05-14T18:41:55.262Z,1747248115.262 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Express0001.lzma.bak 2025-05-14T18:41:55.262Z,1747248115.262 [DataOverHttps](INFO): SBD MOMSN=24874722 2025-05-14T18:42:17.915Z,1747248137.915 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250514T182757/Express0004.lzma 2025-05-14T18:42:18.917Z,1747248138.917 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Express0004.lzma.bak 2025-05-14T18:42:18.917Z,1747248138.917 [DataOverHttps](INFO): SBD MOMSN=24874727 2025-05-14T18:42:24.125Z,1747248144.125 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-05-14T18:42:24.125Z,1747248144.125 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-05-14T18:42:24.165Z,1747248144.165 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-05-14T18:42:24.472Z,1747248144.472 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-05-14T18:42:24.472Z,1747248144.472 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-05-14T18:42:46.123Z,1747248166.123 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20250514T182757/Express0007.lzma 2025-05-14T18:42:47.143Z,1747248167.143 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Express0007.lzma.bak 2025-05-14T18:42:47.143Z,1747248167.143 [DataOverHttps](INFO): SBD MOMSN=24874735 2025-05-14T18:43:04.153Z,1747248184.153 [DataOverHttps](INFO): Sending 1895 bytes from file Logs/20250514T182757/Express0010.lzma 2025-05-14T18:43:05.149Z,1747248185.149 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Express0010.lzma.bak 2025-05-14T18:43:05.149Z,1747248185.149 [DataOverHttps](INFO): SBD MOMSN=24874738 2025-05-14T18:43:06.538Z,1747248186.538 [Default:CheckIn:Read_Iridium] Stopped 2025-05-14T18:43:06.538Z,1747248186.538 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-14T18:43:06.538Z,1747248186.538 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-14T18:43:49.493Z,1747248229.493 [DataOverHttps](IMPORTANT): SBD MTMSN=20250514T184347 2025-05-14T18:43:59.281Z,1747248239.281 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 15 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Speed 1.4 m/s;run 2025-05-14T18:43:59.307Z,1747248239.307 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl 2025-05-14T18:43:59.308Z,1747248239.308 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl 2025-05-14T18:43:59.308Z,1747248239.308 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl 2025-05-14T18:43:59.311Z,1747248239.311 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl 2025-05-14T18:44:00.511Z,1747248240.511 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into: 60 30 NaN NaN 0 0 10 1 20 1 7 1 2025-05-14T18:44:00.522Z,1747248240.522 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min 2025-05-14T18:44:00.525Z,1747248240.525 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min 2025-05-14T18:44:00.531Z,1747248240.531 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg 2025-05-14T18:44:00.534Z,1747248240.534 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg 2025-05-14T18:44:00.537Z,1747248240.537 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m 2025-05-14T18:44:00.596Z,1747248240.596 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m 2025-05-14T18:44:00.605Z,1747248240.605 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m 2025-05-14T18:44:00.608Z,1747248240.608 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s 2025-05-14T18:44:00.610Z,1747248240.610 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m 2025-05-14T18:44:00.621Z,1747248240.621 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km 2025-05-14T18:44:00.625Z,1747248240.625 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m 2025-05-14T18:44:00.628Z,1747248240.628 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool 2025-05-14T18:44:00.629Z,1747248240.629 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool 2025-05-14T18:44:00.630Z,1747248240.630 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-05-14T18:44:00.630Z,1747248240.630 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-05-14T18:44:00.632Z,1747248240.632 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-05-14T18:44:01.152Z,1747248241.152 [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-14T18:44:01.161Z,1747248241.161 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h 2025-05-14T18:44:01.163Z,1747248241.163 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min 2025-05-14T18:44:01.166Z,1747248241.166 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-05-14T18:44:01.169Z,1747248241.169 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s 2025-05-14T18:44:01.171Z,1747248241.171 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-05-14T18:44:01.174Z,1747248241.174 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s 2025-05-14T18:44:01.176Z,1747248241.176 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min 2025-05-14T18:44:01.178Z,1747248241.178 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min 2025-05-14T18:44:01.198Z,1747248241.198 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-05-14T18:44:01.393Z,1747248241.393 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-05-14T18:44:01.393Z,1747248241.393 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-05-14T18:44:01.400Z,1747248241.400 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-05-14T18:44:01.580Z,1747248241.580 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2025-05-14T18:44:01.592Z,1747248241.592 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m 2025-05-14T18:44:01.594Z,1747248241.594 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2025-05-14T18:44:01.596Z,1747248241.596 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m 2025-05-14T18:44:01.600Z,1747248241.600 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-05-14T18:44:01.602Z,1747248241.602 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-05-14T18:44:01.610Z,1747248241.610 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-05-14T18:44:01.635Z,1747248241.635 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-05-14T18:44:01.674Z,1747248241.674 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-05-14T18:44:01.674Z,1747248241.674 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-05-14T18:44:01.675Z,1747248241.675 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-05-14T18:44:01.714Z,1747248241.714 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-05-14T18:44:01.717Z,1747248241.717 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool 2025-05-14T18:44:01.720Z,1747248241.720 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-05-14T18:44:01.722Z,1747248241.722 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-05-14T18:44:01.723Z,1747248241.723 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-05-14T18:44:01.724Z,1747248241.724 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-05-14T18:44:01.895Z,1747248241.895 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-05-14T18:44:01.927Z,1747248241.927 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool 2025-05-14T18:44:01.929Z,1747248241.929 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool 2025-05-14T18:44:01.936Z,1747248241.936 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool 2025-05-14T18:44:01.944Z,1747248241.944 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool 2025-05-14T18:44:01.975Z,1747248241.975 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool 2025-05-14T18:44:02.011Z,1747248242.011 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-05-14T18:44:02.013Z,1747248242.013 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2025-05-14T18:44:02.035Z,1747248242.035 [transit:MassHold.Pitch](DEBUG): Construct. 2025-05-14T18:44:02.046Z,1747248242.046 [transit:Transit:A.Pitch](DEBUG): Construct. 2025-05-14T18:44:02.061Z,1747248242.061 [transit:Transit:B.SetSpeed](DEBUG): Construct. 2025-05-14T18:44:02.078Z,1747248242.078 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-05-14T18:44:02.161Z,1747248242.161 [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-14T18:44:02.161Z,1747248242.161 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit 2025-05-14T18:44:10.448Z,1747248250.448 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, -45 +352.18, +11.60, 54.53, 0.00 2025-05-14T18:44:18.550Z,1747248258.550 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 30 minute 2025-05-14T18:44:18.550Z,1747248258.550 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 15 minute 2025-05-14T18:44:18.551Z,1747248258.551 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree 2025-05-14T18:44:18.552Z,1747248258.552 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree 2025-05-14T18:44:18.553Z,1747248258.553 [CommandExec](IMPORTANT): got command set transit.Speed 1.4 meter_per_second 2025-05-14T18:44:18.553Z,1747248258.553 [CommandExec](IMPORTANT): got command run 2025-05-14T18:44:18.556Z,1747248258.556 [CommandExec](IMPORTANT): Running 2025-05-14T18:44:18.922Z,1747248258.922 [Default] Stopped 2025-05-14T18:44:18.922Z,1747248258.922 [Default](DEBUG): Aggregate::uninitialize Default 2025-05-14T18:44:18.922Z,1747248258.922 [Default:B.GoToSurface] Stopped 2025-05-14T18:44:18.922Z,1747248258.922 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-05-14T18:44:18.922Z,1747248258.922 [Default:CheckIn] Stopped 2025-05-14T18:44:18.922Z,1747248258.922 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-14T18:44:18.923Z,1747248258.923 [Default:CheckIn:C.Wait] Stopped 2025-05-14T18:44:18.927Z,1747248258.927 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-14T18:44:18.927Z,1747248258.927 [MissionManager](IMPORTANT): Started mission transit 2025-05-14T18:44:18.928Z,1747248258.928 [transit] Running Loop=1 2025-05-14T18:44:18.928Z,1747248258.928 [transit](DEBUG): Aggregate::initialize transit 2025-05-14T18:44:18.928Z,1747248258.928 [transit:StandardEnvelopes] Running Loop=1 2025-05-14T18:44:18.928Z,1747248258.928 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes 2025-05-14T18:44:18.928Z,1747248258.928 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-05-14T18:44:18.929Z,1747248258.929 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-05-14T18:44:18.929Z,1747248258.929 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-05-14T18:44:18.929Z,1747248258.929 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-05-14T18:44:18.929Z,1747248258.929 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-05-14T18:44:18.929Z,1747248258.929 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-05-14T18:44:18.929Z,1747248258.929 [transit:BackseatDriver] Running Loop=1 2025-05-14T18:44:18.930Z,1747248258.930 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver 2025-05-14T18:44:18.930Z,1747248258.930 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-05-14T18:44:18.930Z,1747248258.930 [transit:PowerOnly] Running Loop=1 2025-05-14T18:44:18.930Z,1747248258.930 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly 2025-05-14T18:44:18.930Z,1747248258.930 [transit:PowerOnly:A] Running Loop=1 2025-05-14T18:44:18.930Z,1747248258.930 [transit:PowerOnly:B] Running Loop=1 2025-05-14T18:44:18.930Z,1747248258.930 [transit:PowerOnly:C] Running Loop=1 2025-05-14T18:44:18.930Z,1747248258.930 [transit:PowerOnly:D] Running Loop=1 2025-05-14T18:44:18.930Z,1747248258.930 [transit:PowerOnly:E.Wait] Running Loop=1 2025-05-14T18:44:18.930Z,1747248258.930 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-05-14T18:44:18.930Z,1747248258.930 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2025-05-14T18:44:18.931Z,1747248258.931 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-05-14T18:44:18.931Z,1747248258.931 [transit:MassHold.Pitch] Running Loop=1 2025-05-14T18:44:18.932Z,1747248258.932 [transit:MassHold.Pitch](DEBUG): Initialize. 2025-05-14T18:44:18.932Z,1747248258.932 [transit:B] Running Loop=1 2025-05-14T18:44:18.932Z,1747248258.932 [transit:MassHold.Pitch] Running Loop=1 2025-05-14T18:44:18.933Z,1747248258.933 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2025-05-14T18:44:18.933Z,1747248258.933 [transit:PowerOnly] Running Loop=1 2025-05-14T18:44:18.933Z,1747248258.933 [transit:PowerOnly:D] Stopped 2025-05-14T18:44:18.933Z,1747248258.933 [transit:PowerOnly:C] Stopped 2025-05-14T18:44:18.933Z,1747248258.933 [transit:PowerOnly:B] Stopped 2025-05-14T18:44:18.933Z,1747248258.933 [transit:PowerOnly:A] Stopped 2025-05-14T18:44:18.933Z,1747248258.933 [transit:BackseatDriver] Running Loop=1 2025-05-14T18:44:18.934Z,1747248258.934 [transit:StandardEnvelopes] Running Loop=1 2025-05-14T18:44:18.934Z,1747248258.934 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-05-14T18:44:18.934Z,1747248258.934 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-05-14T18:44:18.934Z,1747248258.934 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-05-14T18:44:18.951Z,1747248258.951 [transit:B] Stopped 2025-05-14T18:44:18.951Z,1747248258.951 [transit:C] Running Loop=1 2025-05-14T18:44:19.302Z,1747248259.302 [transit:C] Stopped 2025-05-14T18:44:19.303Z,1747248259.303 [transit:E] Running Loop=1 2025-05-14T18:44:19.719Z,1747248259.719 [transit:E] Stopped 2025-05-14T18:44:19.719Z,1747248259.719 [transit:F] Running Loop=1 2025-05-14T18:44:20.284Z,1747248260.284 [transit:F] Stopped 2025-05-14T18:44:20.284Z,1747248260.284 [transit:G] Running Loop=1 2025-05-14T18:44:20.533Z,1747248260.533 [transit:G] Stopped 2025-05-14T18:44:20.533Z,1747248260.533 [transit:Transit] Running Loop=1 2025-05-14T18:44:20.533Z,1747248260.533 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit 2025-05-14T18:44:20.533Z,1747248260.533 [transit:Transit:A.Pitch] Running Loop=1 2025-05-14T18:44:20.533Z,1747248260.533 [transit:Transit:A.Pitch](DEBUG): Initialize. 2025-05-14T18:44:20.534Z,1747248260.534 [transit:Transit:B.SetSpeed] Running Loop=1 2025-05-14T18:44:20.534Z,1747248260.534 [transit:Transit:B.SetSpeed](DEBUG): Initialize. 2025-05-14T18:44:20.534Z,1747248260.534 [transit:Transit:Wpt1.Waypoint] Running Loop=1 2025-05-14T18:44:20.534Z,1747248260.534 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-05-14T18:44:20.541Z,1747248260.541 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2025-05-14T18:44:20.921Z,1747248260.921 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2025-05-14T18:44:20.921Z,1747248260.921 [transit:Transit:B.SetSpeed] Running Loop=1 2025-05-14T18:44:20.921Z,1747248260.921 [transit:Transit:A.Pitch] Running Loop=1 2025-05-14T18:44:34.365Z,1747248274.365 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 538.24, 545.92, 530.92 +69, +131, -159, +16,A 2025-05-14T18:44:43.397Z,1747248283.397 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.40 m/s, pitch 20.00 deg, mass-position -3.50 mm (1 active estimators). 2025-05-14T18:45:10.544Z,1747248310.544 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, -4 3, 0.15, 50.41, 0.00 2025-05-14T18:45:21.951Z,1747248321.951 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T18:45:30.406Z,1747248330.406 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, -46.51, +388.92, 0.00 2025-05-14T18:45:30.416Z,1747248330.416 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-05-14T18:45:30.416Z,1747248330.416 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-05-14T18:45:30.647Z,1747248330.647 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-05-14T18:45:31.050Z,1747248331.050 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-05-14T18:45:31.050Z,1747248331.050 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-05-14T18:45:43.804Z,1747248343.804 [Radio_Surface](INFO): Powering down 2025-05-14T18:45:53.075Z,1747248353.075 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T18:45:54.079Z,1747248354.079 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-05-14T18:46:44.537Z,1747248404.537 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, -21.30, +502.82, +4.+49,A 2025-05-14T18:47:35.484Z,1747248455.484 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.723434 2025-05-14T18:48:53.951Z,1747248533.951 [RudderServo](FAULT): Reading outside of valid range:0.000000 2025-05-14T18:48:53.952Z,1747248533.952 [RudderServo](ERROR): Rudder reporting null position 2025-05-14T18:48:54.369Z,1747248534.369 [RudderServo](FAULT): Command Overflow Error 2025-05-14T18:48:54.369Z,1747248534.369 [RudderServo](ERROR): Rudder reporting null position 2025-05-14T18:48:54.737Z,1747248534.737 [RudderServo](FAULT): Invalid EZ Servo response:"769\r" 2025-05-14T18:48:54.737Z,1747248534.737 [RudderServo] Communications Fault, FailCount= 1 2025-05-14T18:48:54.737Z,1747248534.737 [RudderServo](ERROR): Communications Fault 2025-05-14T18:48:54.737Z,1747248534.737 [RudderServo](ERROR): Rudder reporting null position 2025-05-14T18:48:54.811Z,1747248534.811 [CBIT](ERROR): Communications Fault in component: RudderServo 2025-05-14T18:48:55.124Z,1747248535.124 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-05-14T18:48:55.124Z,1747248535.124 [RudderServo](INFO): Powering down 2025-05-14T18:48:55.945Z,1747248535.945 [RudderServo](DEBUG): Initializing EZServoServo. 2025-05-14T18:48:56.064Z,1747248536.064 [RudderServo](DEBUG): Initializing RudderServo. 2025-05-14T18:48:56.082Z,1747248536.082 [CBIT](INFO): Clearing failed state for component RudderServo 2025-05-14T18:48:56.082Z,1747248536.082 [RudderServo] No Fault, FailCount= 1 2025-05-14T18:50:58.559Z,1747248658.559 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-05-14T18:50:58.559Z,1747248658.559 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-05-14T18:50:58.662Z,1747248658.662 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-05-14T18:50:59.059Z,1747248659.059 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-05-14T18:50:59.059Z,1747248659.059 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-05-14T18:54:04.587Z,1747248844.587 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-05-14T18:54:04.588Z,1747248844.588 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-05-14T18:54:04.810Z,1747248844.810 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-05-14T18:54:05.138Z,1747248845.138 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-05-14T18:54:05.138Z,1747248845.138 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-05-14T18:54:32.412Z,1747248872.412 [DAT](INFO): DAT read: Rx Time:18:54:28.4586 2025-05-14T18:54:32.413Z,1747248872.413 [DAT](INFO): Rx dataTimestamp_ set to:1747248872.412490 2025-05-14T18:54:33.175Z,1747248873.175 [DAT](INFO): DAT read: 18:54:28.4586 LVL= 29584, 23457, 23666, 27251, AGC= 76, IDX= 505, 0.11,-0.071, 1.757, 0.503, 0.491, PHS=-0.528, 1.295, 0.084, RAW= 310.7, -10.6, CAL= 311.4, -14.9, ROT= 198.6, 14.9 2025-05-14T18:54:33.176Z,1747248873.176 [DAT](INFO): got valid direction response: 18:54:28.4586 LVL= 29584, 23457, 23666, 27251, AGC= 76, IDX= 505, 0.11,-0.071, 1.757, 0.503, 0.491, PHS=-0.528, 1.295, 0.084, RAW= 310.7, -10.6, CAL= 311.4, -14.9, ROT= 198.6, 14.9 2025-05-14T18:54:33.176Z,1747248873.176 [DAT](INFO): DAT read: 2025-05-14T18:54:33.177Z,1747248873.177 [DAT](INFO): DAT read: $Packet for address 5 2025-05-14T18:54:33.177Z,1747248873.177 [DAT](INFO): received a packet notification 2025-05-14T18:54:33.196Z,1747248873.196 [DAT](INFO): #Rx 1: Read direction message, but no range. 2025-05-14T18:54:33.199Z,1747248873.199 [DAT](INFO): direction in FSK: [-0.915901,-0.308235,-0.257133] 2025-05-14T18:55:03.202Z,1747248903.202 [DAT](INFO): DAT read: Rx Time:18:54:59.0758 2025-05-14T18:55:03.202Z,1747248903.202 [DAT](INFO): Rx dataTimestamp_ set to:1747248903.201904 2025-05-14T18:55:03.709Z,1747248903.709 [DAT](INFO): DAT read: 18:54:59.0758 LVL= 20848, 18417, 15490, 16675, AGC= 73, IDX= 123, 0.23,-0.304, 1.552, 0.810, 0.485, PHS=-0.755, 1.096, 0.397, RAW= 292.0, -9.2, CAL= 293.0, -13.8, ROT= 217.0, 13.8 2025-05-14T18:55:03.710Z,1747248903.710 [DAT](INFO): got valid direction response: 18:54:59.0758 LVL= 20848, 18417, 15490, 16675, AGC= 73, IDX= 123, 0.23,-0.304, 1.552, 0.810, 0.485, PHS=-0.755, 1.096, 0.397, RAW= 292.0, -9.2, CAL= 293.0, -13.8, ROT= 217.0, 13.8 2025-05-14T18:55:03.710Z,1747248903.710 [DAT](INFO): DAT read: 2025-05-14T18:55:03.711Z,1747248903.711 [DAT](INFO): DAT read: $Packet for address 5 2025-05-14T18:55:03.711Z,1747248903.711 [DAT](INFO): received a packet notification 2025-05-14T18:55:03.723Z,1747248903.723 [DAT](INFO): #Rx 2: Read direction message, but no range. 2025-05-14T18:55:03.724Z,1747248903.724 [DAT](INFO): direction in FSK: [-0.775582,-0.584443,-0.238533] 2025-05-14T18:55:20.399Z,1747248920.399 [transit:NeedComms] Running Loop=1 2025-05-14T18:55:20.399Z,1747248920.399 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms 2025-05-14T18:55:20.399Z,1747248920.399 [transit:NeedComms:B.GoToSurface] Running Loop=1 2025-05-14T18:55:20.399Z,1747248920.399 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-05-14T18:55:20.400Z,1747248920.400 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-05-14T18:55:20.400Z,1747248920.400 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-05-14T18:55:20.400Z,1747248920.400 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-05-14T18:55:20.401Z,1747248920.401 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-05-14T18:55:20.401Z,1747248920.401 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-05-14T18:55:20.401Z,1747248920.401 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-05-14T18:55:20.402Z,1747248920.402 [transit:NeedComms:A] Running Loop=1 2025-05-14T18:55:20.406Z,1747248920.406 [transit:NeedComms:A](INFO): last time_fix was: 1747248017.000000 second since 1970/01/01T00:00:00Z 2025-05-14T18:55:20.406Z,1747248920.406 [transit:NeedComms:A] Stopped 2025-05-14T18:55:22.843Z,1747248922.843 [ThrusterHE](FAULT): Could not get status:serial timeout 2025-05-14T18:55:22.939Z,1747248922.939 [ThrusterHE](FAULT): getSpeed error serial timeout 2025-05-14T18:55:22.939Z,1747248922.939 [ThrusterHE](FAULT): Thruster uart error: serial timeout 2025-05-14T18:55:22.939Z,1747248922.939 [ThrusterHE] Communications Fault, FailCount= 1 2025-05-14T18:55:22.939Z,1747248922.939 [ThrusterHE](ERROR): Communications Fault 2025-05-14T18:55:22.945Z,1747248922.945 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-05-14T18:55:23.248Z,1747248923.248 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-14T18:55:23.248Z,1747248923.248 [ThrusterHE](INFO): Powering down 2025-05-14T18:55:23.964Z,1747248923.964 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-14T18:55:24.084Z,1747248924.084 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-14T18:55:24.088Z,1747248924.088 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-05-14T18:55:24.088Z,1747248924.088 [ThrusterHE] No Fault, FailCount= 1 2025-05-14T18:55:26.538Z,1747248926.538 [ThrusterHE](FAULT): Thruster failed to initialize 2025-05-14T18:55:26.538Z,1747248926.538 [ThrusterHE] Communications Fault, FailCount= 2 2025-05-14T18:55:26.539Z,1747248926.539 [ThrusterHE](ERROR): Communications Fault 2025-05-14T18:55:26.548Z,1747248926.548 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-05-14T18:55:26.868Z,1747248926.868 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-14T18:55:26.868Z,1747248926.868 [ThrusterHE](INFO): Powering down 2025-05-14T18:55:27.344Z,1747248927.344 [ThrusterHE](FAULT): LCB fault: Current Limiter Activated. 2025-05-14T18:55:27.344Z,1747248927.344 [ThrusterHE] Hardware Fault, FailCount= 2 2025-05-14T18:55:27.344Z,1747248927.344 [ThrusterHE](ERROR): Hardware Fault 2025-05-14T18:55:27.772Z,1747248927.772 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-14T18:55:27.888Z,1747248927.888 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-14T18:55:27.891Z,1747248927.891 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-05-14T18:55:27.891Z,1747248927.891 [ThrusterHE] No Fault, FailCount= 2 2025-05-14T18:55:28.148Z,1747248928.148 [DAT](INFO): DAT read: Rx Time:18:55:24.0499 2025-05-14T18:55:28.148Z,1747248928.148 [DAT](INFO): Rx dataTimestamp_ set to:1747248928.148104 2025-05-14T18:55:28.659Z,1747248928.659 [DAT](INFO): DAT read: 18:55:24.0499 LVL= 17904, 16865, 15922, 12675, AGC= 75, IDX= 191,-0.02,-2.096,-0.483,-1.058,-1.673, PHS=-0.389, 1.219, 0.687, RAW= 289.0, -20.7, CAL= 289.4, -25.0, ROT= 220.6, 25.0 2025-05-14T18:55:28.660Z,1747248928.660 [DAT](INFO): got valid direction response: 18:55:24.0499 LVL= 17904, 16865, 15922, 12675, AGC= 75, IDX= 191,-0.02,-2.096,-0.483,-1.058,-1.673, PHS=-0.389, 1.219, 0.687, RAW= 289.0, -20.7, CAL= 289.4, -25.0, ROT= 220.6, 25.0 2025-05-14T18:55:28.665Z,1747248928.665 [DAT](INFO): DAT read: PEAK , IDX= 196, 0.15, 1.791,-2.829, 2.790, 2.085, PHS=-0.260, 1.398, 0.777, RAW= 291.8, -25.0, CAL= 291.0, -29.1, ROT= 219.0, 29.1, FLG=0x0001 2025-05-14T18:55:28.666Z,1747248928.666 [DAT](INFO): unknown deviceResponse_: PEAK , IDX= 196, 0.15, 1.791,-2.829, 2.790, 2.085, PHS=-0.260, 1.398, 0.777, RAW= 291.8, -25.0, CAL= 291.0, -29.1, ROT= 219.0, 29.1, FLG=0x0001 2025-05-14T18:55:28.667Z,1747248928.667 [DAT](INFO): DAT read: Tx time:18:55:25.2500 2025-05-14T18:55:28.667Z,1747248928.667 [DAT](INFO): Ping request sent. 2025-05-14T18:55:28.667Z,1747248928.667 [DAT](INFO): transmitted an acoustic signal 2025-05-14T18:55:28.667Z,1747248928.667 [DAT](INFO): #Rx 3: Read direction message, but no range. 2025-05-14T18:55:28.668Z,1747248928.668 [DAT](INFO): direction in FSK: [-0.688133,-0.589802,-0.422618] 2025-05-14T18:55:28.669Z,1747248928.669 [DAT](INFO): publishing transmit ping time 2025-05-14T18:55:28.696Z,1747248928.696 [DAT](INFO): publishing transmit ping time 2025-05-14T18:55:30.121Z,1747248930.121 [ThrusterHE](FAULT): Thruster failed to initialize 2025-05-14T18:55:30.121Z,1747248930.121 [ThrusterHE] Communications Fault, FailCount= 3 2025-05-14T18:55:30.121Z,1747248930.121 [ThrusterHE](ERROR): Communications Fault 2025-05-14T18:55:30.126Z,1747248930.126 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-05-14T18:55:30.537Z,1747248930.537 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-14T18:55:30.537Z,1747248930.537 [ThrusterHE](INFO): Powering down 2025-05-14T18:55:31.220Z,1747248931.220 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-14T18:55:31.340Z,1747248931.340 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-14T18:55:31.345Z,1747248931.345 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-05-14T18:55:31.345Z,1747248931.345 [ThrusterHE] No Fault, FailCount= 3 2025-05-14T18:55:42.012Z,1747248942.012 [DAT](INFO): DAT read: Rx Time:18:55:37.9019 2025-05-14T18:55:42.012Z,1747248942.012 [DAT](INFO): Rx dataTimestamp_ set to:1747248942.012123 2025-05-14T18:55:42.560Z,1747248942.560 [DAT](INFO): DAT read: 18:55:37.9019 LVL= 22992, 20401, 17634, 23251, AGC= 78, IDX= 60,-0.30, 3.097,-1.319,-1.224,-2.271, PHS=-0.881, 0.981, 1.120, RAW= 266.5, -12.6, CAL= 267.2, -17.3, ROT= 242.8, 17.3 2025-05-14T18:55:42.582Z,1747248942.582 [DAT](INFO): got valid direction response: 18:55:37.9019 LVL= 22992, 20401, 17634, 23251, AGC= 78, IDX= 60,-0.30, 3.097,-1.319,-1.224,-2.271, PHS=-0.881, 0.981, 1.120, RAW= 266.5, -12.6, CAL= 267.2, -17.3, ROT= 242.8, 17.3 2025-05-14T18:55:42.591Z,1747248942.591 [DAT](INFO): DAT read: PEAK , IDX= 193,-0.18,-3.084,-1.399,-1.399,-2.200, PHS=-0.850, 0.830, 0.873, RAW= 268.7, -10.0, CAL= 269.2, -14.8, ROT= 240.8, 14.8, FLG=0x0001 2025-05-14T18:55:42.592Z,1747248942.592 [DAT](INFO): unknown deviceResponse_: PEAK , IDX= 193,-0.18,-3.084,-1.399,-1.399,-2.200, PHS=-0.850, 0.830, 0.873, RAW= 268.7, -10.0, CAL= 269.2, -14.8, ROT= 240.8, 14.8, FLG=0x0001 2025-05-14T18:55:42.592Z,1747248942.592 [DAT](INFO): DAT read: range request 2025-05-14T18:55:42.592Z,1747248942.592 [DAT](INFO): received a range request message 2025-05-14T18:55:42.593Z,1747248942.593 [DAT](INFO): DAT read: Tx time:18:55:39.5047 2025-05-14T18:55:42.593Z,1747248942.593 [DAT](INFO): Ping request sent. 2025-05-14T18:55:42.593Z,1747248942.593 [DAT](INFO): transmitted an acoustic signal 2025-05-14T18:55:42.594Z,1747248942.594 [DAT](INFO): #Rx 4: Read direction message, but no range. 2025-05-14T18:55:42.595Z,1747248942.595 [DAT](INFO): direction in FSK: [-0.436419,-0.849180,-0.297375] 2025-05-14T18:55:42.599Z,1747248942.599 [DAT](INFO): publishing transmit ping time 2025-05-14T18:55:42.600Z,1747248942.600 [DAT](INFO): publishing range request flag 2025-05-14T18:55:42.608Z,1747248942.608 [DAT](INFO): publishing transmit ping time 2025-05-14T18:55:42.608Z,1747248942.608 [DAT](INFO): publishing range request flag 2025-05-14T18:56:01.875Z,1747248961.875 [transit:NeedComms:C] Running Loop=1 2025-05-14T18:56:02.280Z,1747248962.280 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-05-14T18:56:02.562Z,1747248962.562 [Radio_Surface](INFO): Powering up 2025-05-14T18:56:08.651Z,1747248968.651 [DataOverHttps](INFO): Radio surface powered ON. 2025-05-14T18:56:38.771Z,1747248998.771 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T18:57:05.368Z,1747249025.368 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-05-14T18:57:05.368Z,1747249025.368 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-05-14T18:57:05.384Z,1747249025.384 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-05-14T18:57:05.818Z,1747249025.818 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-05-14T18:57:05.818Z,1747249025.818 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-05-14T18:57:09.903Z,1747249029.903 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T18:57:20.298Z,1747249040.298 [NAL9602](INFO): SBD MO Status=2, MOMSN=10229, MT Status=2, MTMSN=0 2025-05-14T18:57:20.298Z,1747249040.298 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T18:57:41.039Z,1747249061.039 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T18:57:46.144Z,1747249066.144 [NAL9602](INFO): SBD MO Status=2, MOMSN=10229, MT Status=2, MTMSN=0 2025-05-14T18:57:46.144Z,1747249066.144 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T18:58:12.179Z,1747249092.179 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T18:58:12.408Z,1747249092.408 [NAL9602](INFO): SBD MO Status=2, MOMSN=10229, MT Status=2, MTMSN=0 2025-05-14T18:58:12.408Z,1747249092.408 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T18:58:21.452Z,1747249101.452 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003567 2025-05-14T18:58:33.836Z,1747249113.836 [NAL9602](INFO): SBD MO Status=0, MOMSN=10229, MT Status=0, MTMSN=0 2025-05-14T18:58:33.836Z,1747249113.836 [NAL9602](INFO): No messages in MT queue 2025-05-14T18:58:35.032Z,1747249115.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185832.00,A,3647.91824,N,12150.49746,W,0.680,13.00,140525,,,A*4D 2025-05-14T18:58:35.035Z,1747249115.035 [NAL9602](INFO): GPS fix at 20250514T185832: (36.798637, -121.841624) 2025-05-14T18:58:35.076Z,1747249115.076 [UniversalFixResidualReporter](INFO): Fix residual: 9.0 %DT, over the last 968.0 m. Residual distance 87.0 m at bearing 45.3 degrees. Fix at (36.7986, -121.8416) with 840.8 m made good. 2025-05-14T18:58:35.084Z,1747249115.084 [transit:NeedComms:C] Stopped 2025-05-14T18:58:35.085Z,1747249115.085 [transit:NeedComms:D] Running Loop=1 2025-05-14T18:58:35.470Z,1747249115.470 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-05-14T18:58:43.031Z,1747249123.031 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20250514T182757/Courier0012.lzma 2025-05-14T18:58:44.033Z,1747249124.033 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Courier0012.lzma.bak 2025-05-14T18:58:44.033Z,1747249124.033 [DataOverHttps](INFO): SBD MOMSN=24874944 2025-05-14T18:59:01.042Z,1747249141.042 [DataOverHttps](INFO): Sending 828 bytes from file Logs/20250514T182757/Express0013.lzma 2025-05-14T18:59:02.041Z,1747249142.041 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Express0013.lzma.bak 2025-05-14T18:59:02.041Z,1747249142.041 [DataOverHttps](INFO): SBD MOMSN=24874948 2025-05-14T18:59:03.570Z,1747249143.570 [transit:NeedComms:D] Stopped 2025-05-14T18:59:03.570Z,1747249143.570 [transit:NeedComms:E] Running Loop=1 2025-05-14T18:59:03.969Z,1747249143.969 [transit:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-05-14T18:59:05.598Z,1747249145.598 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185903.00,A,3647.91844,N,12150.49205,W,0.952,98.52,140525,,,D*4B 2025-05-14T18:59:05.600Z,1747249145.600 [NAL9602](INFO): GPS fix at 20250514T185903: (36.798641, -121.841534) 2025-05-14T18:59:05.625Z,1747249145.625 [transit:NeedComms:E] Stopped 2025-05-14T18:59:05.626Z,1747249145.626 [transit:NeedComms](INFO): Completed transit:NeedComms 2025-05-14T18:59:05.626Z,1747249145.626 [transit:NeedComms] Stopped 2025-05-14T18:59:05.626Z,1747249145.626 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms 2025-05-14T18:59:05.626Z,1747249145.626 [transit:NeedComms:B.GoToSurface] Stopped 2025-05-14T18:59:05.626Z,1747249145.626 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-05-14T18:59:17.788Z,1747249157.788 [DAT](INFO): DAT read: Rx Time:18:59:13.6655 2025-05-14T18:59:17.788Z,1747249157.788 [DAT](INFO): Rx dataTimestamp_ set to:1747249157.788145 2025-05-14T18:59:18.297Z,1747249158.297 [DAT](INFO): DAT read: 18:59:13.6654 LVL= 20464, 24033, 15858, 15683, AGC= 76, IDX= 285, 0.17,-1.134,-2.286,-0.565,-1.682, PHS= 0.583,-0.575, 1.189, RAW= 169.8, -15.2, CAL= 172.1, -14.7, ROT= 337.9, 14.7 2025-05-14T18:59:18.298Z,1747249158.298 [DAT](INFO): got valid direction response: 18:59:13.6654 LVL= 20464, 24033, 15858, 15683, AGC= 76, IDX= 285, 0.17,-1.134,-2.286,-0.565,-1.682, PHS= 0.583,-0.575, 1.189, RAW= 169.8, -15.2, CAL= 172.1, -14.7, ROT= 337.9, 14.7 2025-05-14T18:59:18.298Z,1747249158.298 [DAT](INFO): DAT read: 2025-05-14T18:59:18.299Z,1747249158.299 [DAT](INFO): DAT read: $Packet for address 5 2025-05-14T18:59:18.300Z,1747249158.300 [DAT](INFO): received a packet notification 2025-05-14T18:59:18.312Z,1747249158.312 [DAT](INFO): #Rx 5: Read direction message, but no range. 2025-05-14T18:59:18.313Z,1747249158.313 [DAT](INFO): direction in FSK: [0.896201,-0.363910,-0.253758] 2025-05-14T18:59:38.065Z,1747249178.065 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-14T18:59:54.120Z,1747249194.120 [DAT](INFO): DAT read: Rx Time:18:59:49.9374 2025-05-14T18:59:54.121Z,1747249194.121 [DAT](INFO): Rx dataTimestamp_ set to:1747249194.120430 2025-05-14T18:59:54.630Z,1747249194.630 [DAT](INFO): DAT read: 18:59:49.9374 LVL= 25024, 28913, 32754, 27171, AGC= 77, IDX= 196,-0.31,-2.300,-1.419, 2.752,-2.440, PHS= 0.174, 1.050,-1.020, RAW= 5.1, -2.3, CAL= 5.8, -6.6, ROT= 144.2, 6.6 2025-05-14T18:59:54.635Z,1747249194.635 [DAT](INFO): got valid direction response: 18:59:49.9374 LVL= 25024, 28913, 32754, 27171, AGC= 77, IDX= 196,-0.31,-2.300,-1.419, 2.752,-2.440, PHS= 0.174, 1.050,-1.020, RAW= 5.1, -2.3, CAL= 5.8, -6.6, ROT= 144.2, 6.6 2025-05-14T18:59:54.636Z,1747249194.636 [DAT](INFO): DAT read: 2025-05-14T18:59:54.636Z,1747249194.636 [DAT](INFO): DAT read: $Packet for address 5 2025-05-14T18:59:54.637Z,1747249194.637 [DAT](INFO): received a packet notification 2025-05-14T18:59:54.657Z,1747249194.657 [DAT](INFO): #Rx 6: Read direction message, but no range. 2025-05-14T18:59:54.658Z,1747249194.658 [DAT](INFO): direction in FSK: [-0.805689,0.581081,-0.114937] 2025-05-14T19:00:03.879Z,1747249203.879 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:00:06.421Z,1747249206.421 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-05-14T19:00:06.421Z,1747249206.421 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-05-14T19:00:06.626Z,1747249206.626 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-05-14T19:00:06.958Z,1747249206.958 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-05-14T19:00:06.958Z,1747249206.958 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-05-14T19:00:24.411Z,1747249224.411 [Radio_Surface](INFO): Powering down 2025-05-14T19:00:35.011Z,1747249235.011 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:00:36.015Z,1747249236.015 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-05-14T19:02:12.476Z,1747249332.476 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.803741 2025-05-14T19:03:07.472Z,1747249387.472 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-05-14T19:03:07.472Z,1747249387.472 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-05-14T19:03:07.687Z,1747249387.687 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-05-14T19:03:07.938Z,1747249387.938 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-05-14T19:03:07.938Z,1747249387.938 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-05-14T19:04:03.793Z,1747249443.793 [DAT](INFO): DAT read: Rx Time:19:03:59.4763 2025-05-14T19:04:03.794Z,1747249443.794 [DAT](INFO): Rx dataTimestamp_ set to:1747249443.793285 2025-05-14T19:04:04.304Z,1747249444.304 [DAT](INFO): DAT read: 19:03:59.4763 LVL= 32224, 21969, 32754, 29891, AGC= 86, IDX= 366, 0.05,-0.066, 1.656, 0.003, 0.198, PHS=-0.230, 1.487,-0.123, RAW= 326.8, -13.5, CAL= 327.7, -18.0, ROT= 182.3, 18.0 2025-05-14T19:04:04.305Z,1747249444.305 [DAT](INFO): got valid direction response: 19:03:59.4763 LVL= 32224, 21969, 32754, 29891, AGC= 86, IDX= 366, 0.05,-0.066, 1.656, 0.003, 0.198, PHS=-0.230, 1.487,-0.123, RAW= 326.8, -13.5, CAL= 327.7, -18.0, ROT= 182.3, 18.0 2025-05-14T19:04:04.306Z,1747249444.306 [DAT](INFO): DAT read: $Error in header 2025-05-14T19:04:04.306Z,1747249444.306 [DAT](INFO): Received a bad header 2025-05-14T19:04:04.319Z,1747249444.319 [DAT](INFO): #Rx 7: Read direction message, but no range. 2025-05-14T19:04:04.320Z,1747249444.320 [DAT](INFO): direction in FSK: [-0.950290,-0.038168,-0.309017] 2025-05-14T19:04:44.393Z,1747249484.393 [DAT](INFO): DAT read: Rx Time:19:04:39.9942 2025-05-14T19:04:44.394Z,1747249484.394 [DAT](INFO): Rx dataTimestamp_ set to:1747249484.393451 2025-05-14T19:04:44.902Z,1747249484.902 [DAT](INFO): DAT read: 19:04:39.9942 LVL= 26592, 23233, 23666, 26195, AGC= 88, IDX= 136, 0.39, 0.537, 2.162, 0.555, 0.763, PHS=-0.193, 1.428,-0.136, RAW= 328.3, -13.7, CAL= 329.2, -18.2, ROT= 180.8, 18.2 2025-05-14T19:04:44.904Z,1747249484.904 [DAT](INFO): got valid direction response: 19:04:39.9942 LVL= 26592, 23233, 23666, 26195, AGC= 88, IDX= 136, 0.39, 0.537, 2.162, 0.555, 0.763, PHS=-0.193, 1.428,-0.136, RAW= 328.3, -13.7, CAL= 329.2, -18.2, ROT= 180.8, 18.2 2025-05-14T19:04:44.904Z,1747249484.904 [DAT](INFO): DAT read: $Error in header 2025-05-14T19:04:44.905Z,1747249484.905 [DAT](INFO): Received a bad header 2025-05-14T19:04:44.919Z,1747249484.919 [DAT](INFO): #Rx 8: Read direction message, but no range. 2025-05-14T19:04:44.920Z,1747249484.920 [DAT](INFO): direction in FSK: [-0.949879,-0.013264,-0.312335] 2025-05-14T19:05:16.281Z,1747249516.281 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, -43.14, +996.69,4.12 2025-05-14T19:05:22.347Z,1747249522.347 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000 2025-05-14T19:05:22.347Z,1747249522.347 [transit:Transit:Wpt1.Waypoint] Stopped 2025-05-14T19:05:22.347Z,1747249522.347 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-05-14T19:05:22.347Z,1747249522.347 [transit:Transit:PhoneHome] Running Loop=1 2025-05-14T19:05:22.347Z,1747249522.347 [transit:Transit:PhoneHome](DEBUG): Aggregate::initialize transit:Transit:PhoneHome 2025-05-14T19:05:22.741Z,1747249522.741 [transit:NeedComms] Running Loop=1 2025-05-14T19:05:22.741Z,1747249522.741 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms 2025-05-14T19:05:22.742Z,1747249522.742 [transit:NeedComms:B.GoToSurface] Running Loop=1 2025-05-14T19:05:22.742Z,1747249522.742 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-05-14T19:05:22.742Z,1747249522.742 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-05-14T19:05:22.742Z,1747249522.742 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-05-14T19:05:22.743Z,1747249522.743 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-05-14T19:05:22.743Z,1747249522.743 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-05-14T19:05:22.744Z,1747249522.744 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-05-14T19:05:22.744Z,1747249522.744 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-05-14T19:05:22.744Z,1747249522.744 [transit:NeedComms:A] Running Loop=1 2025-05-14T19:05:22.747Z,1747249522.747 [transit:NeedComms:A](INFO): last time_fix was: 1747249143.000000 second since 1970/01/01T00:00:00Z 2025-05-14T19:05:22.747Z,1747249522.747 [transit:NeedComms:A] Stopped 2025-05-14T19:05:26.579Z,1747249526.579 [ThrusterHE](FAULT): Could not get status:serial timeout 2025-05-14T19:05:26.667Z,1747249526.667 [ThrusterHE](FAULT): getSpeed error serial timeout 2025-05-14T19:05:26.667Z,1747249526.667 [ThrusterHE](FAULT): Thruster uart error: serial timeout 2025-05-14T19:05:26.667Z,1747249526.667 [ThrusterHE] Communications Fault, FailCount= 1 2025-05-14T19:05:26.667Z,1747249526.667 [ThrusterHE](ERROR): Communications Fault 2025-05-14T19:05:26.671Z,1747249526.671 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-05-14T19:05:26.860Z,1747249526.860 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-14T19:05:26.860Z,1747249526.860 [ThrusterHE](INFO): Powering down 2025-05-14T19:05:27.812Z,1747249527.812 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-14T19:05:27.932Z,1747249527.932 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-14T19:05:27.939Z,1747249527.939 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-05-14T19:05:27.939Z,1747249527.939 [ThrusterHE] No Fault, FailCount= 1 2025-05-14T19:05:30.260Z,1747249530.260 [ThrusterHE](FAULT): Thruster failed to initialize 2025-05-14T19:05:30.260Z,1747249530.260 [ThrusterHE] Communications Fault, FailCount= 2 2025-05-14T19:05:30.260Z,1747249530.260 [ThrusterHE](ERROR): Communications Fault 2025-05-14T19:05:30.264Z,1747249530.264 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-05-14T19:05:30.548Z,1747249530.548 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-14T19:05:30.548Z,1747249530.548 [ThrusterHE](INFO): Powering down 2025-05-14T19:05:31.046Z,1747249531.046 [ThrusterHE](FAULT): LCB fault: Current Limiter Activated. 2025-05-14T19:05:31.046Z,1747249531.046 [ThrusterHE] Hardware Fault, FailCount= 2 2025-05-14T19:05:31.046Z,1747249531.046 [ThrusterHE](ERROR): Hardware Fault 2025-05-14T19:05:31.361Z,1747249531.361 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-14T19:05:31.476Z,1747249531.476 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-14T19:05:31.480Z,1747249531.480 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-05-14T19:05:31.480Z,1747249531.480 [ThrusterHE] No Fault, FailCount= 2 2025-05-14T19:05:33.732Z,1747249533.732 [ThrusterHE](FAULT): Thruster failed to initialize 2025-05-14T19:05:33.732Z,1747249533.732 [ThrusterHE] Communications Fault, FailCount= 3 2025-05-14T19:05:33.732Z,1747249533.732 [ThrusterHE](ERROR): Communications Fault 2025-05-14T19:05:33.736Z,1747249533.736 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-05-14T19:05:34.236Z,1747249534.236 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-14T19:05:34.236Z,1747249534.236 [ThrusterHE](INFO): Powering down 2025-05-14T19:05:34.933Z,1747249534.933 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-05-14T19:05:35.052Z,1747249535.052 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-05-14T19:05:35.056Z,1747249535.056 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-05-14T19:05:35.056Z,1747249535.056 [ThrusterHE] No Fault, FailCount= 3 2025-05-14T19:05:35.320Z,1747249535.320 [DAT](INFO): DAT read: Rx Time:19:05:31.0663 2025-05-14T19:05:35.320Z,1747249535.320 [DAT](INFO): Rx dataTimestamp_ set to:1747249535.320124 2025-05-14T19:05:36.120Z,1747249536.120 [DAT](INFO): DAT read: 19:05:31.0663 LVL= 20512, 18977, 19266, 22387, AGC= 82, IDX= 194,-0.23,-1.533, 0.366,-0.972,-1.156, PHS=-0.343, 1.551, 0.256, RAW= 312.0, -17.2, CAL= 313.3, -21.6, ROT= 196.7, 21.6 2025-05-14T19:05:36.122Z,1747249536.122 [DAT](INFO): got valid direction response: 19:05:31.0663 LVL= 20512, 18977, 19266, 22387, AGC= 82, IDX= 194,-0.23,-1.533, 0.366,-0.972,-1.156, PHS=-0.343, 1.551, 0.256, RAW= 312.0, -17.2, CAL= 313.3, -21.6, ROT= 196.7, 21.6 2025-05-14T19:05:36.122Z,1747249536.122 [DAT](INFO): DAT read: 2025-05-14T19:05:36.127Z,1747249536.127 [DAT](INFO): DAT read: $Packet for address 5 2025-05-14T19:05:36.128Z,1747249536.128 [DAT](INFO): received a packet notification 2025-05-14T19:05:36.139Z,1747249536.139 [DAT](INFO): #Rx 9: Read direction message, but no range. 2025-05-14T19:05:36.141Z,1747249536.141 [DAT](INFO): direction in FSK: [-0.890561,-0.267181,-0.368125] 2025-05-14T19:05:54.111Z,1747249554.111 [ElevatorServo](FAULT): Elevator uart error: serial timeout 2025-05-14T19:05:54.111Z,1747249554.111 [ElevatorServo] Communications Fault, FailCount= 1 2025-05-14T19:05:54.111Z,1747249554.111 [ElevatorServo](ERROR): Communications Fault 2025-05-14T19:05:54.214Z,1747249554.214 [CBIT](ERROR): Communications Fault in component: ElevatorServo 2025-05-14T19:05:54.339Z,1747249554.339 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-05-14T19:05:54.340Z,1747249554.340 [ElevatorServo](INFO): Powering down 2025-05-14T19:05:55.500Z,1747249555.500 [CBIT](INFO): Clearing failed state for component ElevatorServo 2025-05-14T19:05:55.500Z,1747249555.500 [ElevatorServo] No Fault, FailCount= 1 2025-05-14T19:05:55.609Z,1747249555.609 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-05-14T19:05:55.610Z,1747249555.610 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-05-14T19:06:05.283Z,1747249565.283 [transit:NeedComms:C] Running Loop=1 2025-05-14T19:06:05.876Z,1747249565.876 [Radio_Surface](INFO): Powering up 2025-05-14T19:06:08.511Z,1747249568.511 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-05-14T19:06:08.511Z,1747249568.511 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-05-14T19:06:08.705Z,1747249568.705 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-05-14T19:06:09.038Z,1747249569.038 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-05-14T19:06:09.038Z,1747249569.038 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-05-14T19:06:11.439Z,1747249571.439 [DataOverHttps](INFO): Radio surface powered ON. 2025-05-14T19:06:41.559Z,1747249601.559 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:07:12.683Z,1747249632.683 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:07:41.475Z,1747249661.475 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, -46 +998.05, +28.49, 0.00,116.06 2025-05-14T19:07:43.811Z,1747249663.811 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:07:51.164Z,1747249671.164 [NAL9602](INFO): SBD MO Status=2, MOMSN=10230, MT Status=2, MTMSN=0 2025-05-14T19:07:51.165Z,1747249671.165 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T19:08:14.939Z,1747249694.939 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-14T19:08:23.106Z,1747249703.106 [NAL9602](INFO): SBD MO Status=2, MOMSN=10230, MT Status=2, MTMSN=0 2025-05-14T19:08:23.106Z,1747249703.106 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T19:08:26.979Z,1747249706.979 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002955 2025-05-14T19:08:43.314Z,1747249723.314 [NAL9602](INFO): SBD MO Status=0, MOMSN=10230, MT Status=0, MTMSN=0 2025-05-14T19:08:43.314Z,1747249723.314 [NAL9602](INFO): No messages in MT queue 2025-05-14T19:08:44.550Z,1747249724.550 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190841.00,A,3647.78714,N,12150.71870,W,0.330,72.89,140525,,,A*4F 2025-05-14T19:08:44.552Z,1747249724.552 [NAL9602](INFO): GPS fix at 20250514T190841: (36.796452, -121.845312) 2025-05-14T19:08:44.570Z,1747249724.570 [UniversalFixResidualReporter](INFO): Fix residual: 16.9 %DT, over the last 726.1 m. Residual distance 122.5 m at bearing -91.8 degrees. Fix at (36.7965, -121.8453) with 415.1 m made good. 2025-05-14T19:08:44.573Z,1747249724.573 [transit:NeedComms:C] Stopped 2025-05-14T19:08:44.573Z,1747249724.573 [transit:NeedComms:D] Running Loop=1 2025-05-14T19:08:52.918Z,1747249732.918 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20250514T182757/Courier0015.lzma 2025-05-14T19:08:53.921Z,1747249733.921 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Courier0015.lzma.bak 2025-05-14T19:08:53.921Z,1747249733.921 [DataOverHttps](INFO): SBD MOMSN=24875043 2025-05-14T19:09:06.836Z,1747249746.836 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,32768,-32768,V 2025-05-14T19:09:09.612Z,1747249749.612 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-05-14T19:09:09.612Z,1747249749.612 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-05-14T19:09:09.632Z,1747249749.632 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-05-14T19:09:10.054Z,1747249750.054 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-05-14T19:09:10.054Z,1747249750.054 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-05-14T19:09:13.869Z,1747249753.869 [DataOverHttps](INFO): Sending 492 bytes from file Logs/20250514T182757/Express0019.lzma 2025-05-14T19:09:14.873Z,1747249754.873 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Express0019.lzma.bak 2025-05-14T19:09:14.873Z,1747249754.873 [DataOverHttps](INFO): SBD MOMSN=24875049 2025-05-14T19:09:16.525Z,1747249756.525 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-14T19:09:17.105Z,1747249757.105 [transit:NeedComms:D] Stopped 2025-05-14T19:09:17.105Z,1747249757.105 [transit:NeedComms:E] Running Loop=1 2025-05-14T19:09:18.954Z,1747249758.954 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190916.00,A,3647.78711,N,12150.71364,W,0.544,82.68,140525,,,A*42 2025-05-14T19:09:18.957Z,1747249758.957 [NAL9602](INFO): GPS fix at 20250514T190916: (36.796452, -121.845227) 2025-05-14T19:09:19.029Z,1747249759.029 [transit:NeedComms:E] Stopped 2025-05-14T19:09:19.030Z,1747249759.030 [transit:NeedComms](INFO): Completed transit:NeedComms 2025-05-14T19:09:19.030Z,1747249759.030 [transit:NeedComms] Stopped 2025-05-14T19:09:19.030Z,1747249759.030 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms 2025-05-14T19:09:19.030Z,1747249759.030 [transit:NeedComms:B.GoToSurface] Stopped 2025-05-14T19:09:19.030Z,1747249759.030 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-05-14T19:09:19.460Z,1747249759.460 [transit:Transit:PhoneHome](INFO): Completed transit:Transit:PhoneHome 2025-05-14T19:09:19.460Z,1747249759.460 [transit:Transit:PhoneHome] Stopped 2025-05-14T19:09:19.460Z,1747249759.460 [transit:Transit:PhoneHome](DEBUG): Aggregate::uninitialize transit:Transit:PhoneHome 2025-05-14T19:09:19.462Z,1747249759.462 [transit:Transit](INFO): Completed transit:Transit 2025-05-14T19:09:19.462Z,1747249759.462 [transit:Transit] Stopped 2025-05-14T19:09:19.462Z,1747249759.462 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2025-05-14T19:09:19.462Z,1747249759.462 [transit:Transit:A.Pitch] Stopped 2025-05-14T19:09:19.463Z,1747249759.463 [transit:Transit:B.SetSpeed] Stopped 2025-05-14T19:09:19.483Z,1747249759.483 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2025-05-14T19:09:19.485Z,1747249759.485 [transit](INFO): Completed transit 2025-05-14T19:09:19.485Z,1747249759.485 [MissionManager](INFO): transit is completed. 2025-05-14T19:09:19.485Z,1747249759.485 [MissionManager](INFO): Uninitializing Mission transit 2025-05-14T19:09:19.485Z,1747249759.485 [transit] Stopped 2025-05-14T19:09:19.485Z,1747249759.485 [transit](DEBUG): Aggregate::uninitialize transit 2025-05-14T19:09:19.485Z,1747249759.485 [transit:StandardEnvelopes] Stopped 2025-05-14T19:09:19.486Z,1747249759.486 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2025-05-14T19:09:19.486Z,1747249759.486 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-05-14T19:09:19.486Z,1747249759.486 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-05-14T19:09:19.486Z,1747249759.486 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-05-14T19:09:19.486Z,1747249759.486 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-05-14T19:09:19.486Z,1747249759.486 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-05-14T19:09:19.486Z,1747249759.486 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-05-14T19:09:19.486Z,1747249759.486 [transit:BackseatDriver] Stopped 2025-05-14T19:09:19.486Z,1747249759.486 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver 2025-05-14T19:09:19.486Z,1747249759.486 [transit:BackseatDriver:A.BackseatDriver] Stopped 2025-05-14T19:09:19.486Z,1747249759.486 [transit:PowerOnly] Stopped 2025-05-14T19:09:19.486Z,1747249759.486 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly 2025-05-14T19:09:19.486Z,1747249759.486 [transit:PowerOnly:E.Wait] Stopped 2025-05-14T19:09:19.487Z,1747249759.487 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-05-14T19:09:19.487Z,1747249759.487 [transit:BuoyancyHold.Buoyancy] Stopped 2025-05-14T19:09:19.527Z,1747249759.527 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-05-14T19:09:19.528Z,1747249759.528 [transit:MassHold.Pitch] Stopped 2025-05-14T19:09:19.937Z,1747249759.937 [MissionManager](IMPORTANT): Started mission Default 2025-05-14T19:09:19.951Z,1747249759.951 [Default] Running Loop=1 2025-05-14T19:09:19.951Z,1747249759.951 [Default](DEBUG): Aggregate::initialize Default 2025-05-14T19:09:19.951Z,1747249759.951 [Default:B.GoToSurface] Running Loop=1 2025-05-14T19:09:19.951Z,1747249759.951 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-05-14T19:09:19.952Z,1747249759.952 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-05-14T19:09:19.952Z,1747249759.952 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-05-14T19:09:19.952Z,1747249759.952 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-05-14T19:09:19.952Z,1747249759.952 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-05-14T19:09:19.953Z,1747249759.953 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-05-14T19:09:19.953Z,1747249759.953 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-05-14T19:09:19.953Z,1747249759.953 [Default:A.Wait] Running Loop=1 2025-05-14T19:09:19.953Z,1747249759.953 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-05-14T19:09:33.233Z,1747249773.233 [Default:A.Wait](INFO): Done Waiting. 2025-05-14T19:09:33.233Z,1747249773.233 [Default:A.Wait] Stopped 2025-05-14T19:09:33.233Z,1747249773.233 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-05-14T19:09:33.714Z,1747249773.714 [Default:CheckIn] Running Loop=1 2025-05-14T19:09:33.714Z,1747249773.714 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-14T19:09:33.715Z,1747249773.715 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-14T19:09:56.532Z,1747249796.532 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, -46.21, 05, + 0.0 2025-05-14T19:09:58.890Z,1747249798.890 [NAL9602](INFO): SBD MO Status=2, MOMSN=10231, MT Status=2, MTMSN=0 2025-05-14T19:09:58.890Z,1747249798.890 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T19:10:33.629Z,1747249833.629 [NAL9602](INFO): SBD MO Status=2, MOMSN=10231, MT Status=2, MTMSN=0 2025-05-14T19:10:33.629Z,1747249833.629 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T19:10:51.011Z,1747249851.011 [NAL9602](INFO): SBD MO Status=2, MOMSN=10231, MT Status=2, MTMSN=0 2025-05-14T19:10:51.011Z,1747249851.011 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T19:11:09.201Z,1747249869.201 [NAL9602](INFO): SBD MO Status=2, MOMSN=10231, MT Status=2, MTMSN=0 2025-05-14T19:11:09.201Z,1747249869.201 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T19:11:25.774Z,1747249885.774 [NAL9602](INFO): SBD MO Status=2, MOMSN=10231, MT Status=2, MTMSN=0 2025-05-14T19:11:25.774Z,1747249885.774 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-14T19:11:26.625Z,1747249886.625 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, 38, , +20.56, 20.00, 0.00 2025-05-14T19:11:35.500Z,1747249895.500 [NAL9602](INFO): SBD MO Status=0, MOMSN=10231, MT Status=0, MTMSN=0 2025-05-14T19:11:35.500Z,1747249895.500 [NAL9602](INFO): No messages in MT queue 2025-05-14T19:11:36.700Z,1747249896.700 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191134.00,A,3647.79028,N,12150.69166,W,0.661,82.27,140525,,,A*41 2025-05-14T19:11:36.702Z,1747249896.702 [NAL9602](INFO): GPS fix at 20250514T191134: (36.796505, -121.844861) 2025-05-14T19:11:36.824Z,1747249896.824 [Default:CheckIn:Read_GPS] Stopped 2025-05-14T19:11:36.870Z,1747249896.870 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-14T19:11:40.329Z,1747249900.329 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250514T182757/Courier0021.lzma 2025-05-14T19:11:41.198Z,1747249901.198 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Courier0021.lzma.bak 2025-05-14T19:11:41.199Z,1747249901.199 [DataOverHttps](INFO): SBD MOMSN=24875065 2025-05-14T19:11:41.812Z,1747249901.812 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00 2025-05-14T19:11:59.096Z,1747249919.096 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20250514T182757/Express0022.lzma 2025-05-14T19:12:00.097Z,1747249920.097 [DataOverHttps](INFO): Moved sent file to Logs/20250514T182757/Express0022.lzma.bak 2025-05-14T19:12:00.097Z,1747249920.097 [DataOverHttps](INFO): SBD MOMSN=24875072 2025-05-14T19:12:01.674Z,1747249921.674 [Default:CheckIn:Read_Iridium] Stopped 2025-05-14T19:12:01.674Z,1747249921.674 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-14T19:12:01.674Z,1747249921.674 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-14T19:12:09.706Z,1747249929.706 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-14T19:12:10.901Z,1747249930.901 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-05-14T19:12:10.901Z,1747249930.901 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-05-14T19:12:10.913Z,1747249930.913 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-05-14T19:12:11.286Z,1747249931.286 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-05-14T19:12:11.286Z,1747249931.286 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-05-14T19:12:11.711Z,1747249931.711 [RDI_Pathfinder](ERROR): only read 0 of 4 data items for bottom velocity. Device response is::BS-32768,-32768,V 2025-05-14T19:12:21.808Z,1747249941.808 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,-32768, 2025-05-14T19:12:51.368Z,1747249971.368 [DAT](INFO): DAT read: Rx Time:19:12:46.7812 2025-05-14T19:12:51.368Z,1747249971.368 [DAT](INFO): Rx dataTimestamp_ set to:1747249971.368175 2025-05-14T19:12:51.692Z,1747249971.692 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for bottom velocity. Device response is::BS,-32768,-32768,-327BE,-32768,-32768,-32768,V 2025-05-14T19:12:51.882Z,1747249971.882 [DAT](INFO): DAT read: 19:12:46.7812 LVL= 9136, 7377, 6866, 5827, AGC= 75, IDX= 140, 0.27,-0.301, 1.525,-0.287,-0.026, PHS=-0.241, 1.580,-0.189, RAW= 328.6, -12.8, CAL= 329.3, -17.2, ROT= 180.7, 17.2 2025-05-14T19:12:51.883Z,1747249971.883 [DAT](INFO): got valid direction response: 19:12:46.7812 LVL= 9136, 7377, 6866, 5827, AGC= 75, IDX= 140, 0.27,-0.301, 1.525,-0.287,-0.026, PHS=-0.241, 1.580,-0.189, RAW= 328.6, -12.8, CAL= 329.3, -17.2, ROT= 180.7, 17.2 2025-05-14T19:12:51.886Z,1747249971.886 [DAT](INFO): DAT read: PEAK , IDX= 154,-0.29, 2.787,-1.109, 3.026,-2.798, PHS=-0.664, 1.718,-0.387, FLG=0x0001 2025-05-14T19:12:51.886Z,1747249971.886 [DAT](INFO): unknown deviceResponse_: PEAK , IDX= 154,-0.29, 2.787,-1.109, 3.026,-2.798, PHS=-0.664, 1.718,-0.387, FLG=0x0001 2025-05-14T19:12:51.886Z,1747249971.886 [DAT](INFO): DAT read: 2025-05-14T19:12:51.887Z,1747249971.887 [DAT](INFO): DAT read: $Packet for address 5 2025-05-14T19:12:51.887Z,1747249971.887 [DAT](INFO): received a packet notification 2025-05-14T19:12:51.899Z,1747249971.899 [DAT](INFO): #Rx 10: Read direction message, but no range. 2025-05-14T19:12:51.900Z,1747249971.900 [DAT](INFO): direction in FSK: [-0.955207,-0.011671,-0.295708] 2025-05-14T19:13:33.602Z,1747250013.602 [DAT](INFO): DAT read: Rx Time:19:13:29.1089 2025-05-14T19:13:33.603Z,1747250013.603 [DAT](INFO): Rx dataTimestamp_ set to:1747250013.601917 2025-05-14T19:13:34.108Z,1747250014.108 [DAT](INFO): DAT read: 2025-05-14T19:13:34.109Z,1747250014.109 [DAT](INFO): DAT read: $Packet for address 5 2025-05-14T19:13:34.109Z,1747250014.109 [DAT](INFO): received a packet notification 2025-05-14T19:13:42.119Z,1747250022.119 [DataOverHttps](IMPORTANT): SBD MTMSN=20250514T191339 2025-05-14T19:13:49.772Z,1747250029.772 [DAT](INFO): DAT read: Rx Time:19:13:45.1018 2025-05-14T19:13:49.772Z,1747250029.772 [DAT](INFO): Rx dataTimestamp_ set to:1747250029.772145 2025-05-14T19:13:50.285Z,1747250030.285 [DAT](INFO): DAT read: 2025-05-14T19:13:50.286Z,1747250030.286 [DAT](INFO): DAT read: $Packet for address 5 2025-05-14T19:13:50.286Z,1747250030.286 [DAT](INFO): received a packet notification 2025-05-14T19:13:50.303Z,1747250030.303 [DataOverHttps](INFO): Received command: schedule clear;schedule resume 2025-05-14T19:13:50.402Z,1747250030.402 [CommandExec](IMPORTANT): got command schedule clear 2025-05-14T19:13:50.402Z,1747250030.402 [CommandExec](IMPORTANT): Cleared 0 scheduled commands. 2025-05-14T19:13:50.403Z,1747250030.403 [CommandExec](IMPORTANT): got command schedule resume 2025-05-14T19:13:50.403Z,1747250030.403 [CommandExec](IMPORTANT): Scheduling is resumed 2025-05-14T19:14:27.091Z,1747250067.091 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for bottom velocity. Device response is::BS,-32768,-3768,V 2025-05-14T19:15:07.075Z,1747250107.075 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2025-05-14T19:15:07.158Z,1747250107.158 [CommandExec](IMPORTANT): Onboard.Pressure 9.443670 psi 2025-05-14T19:15:07.158Z,1747250107.158 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2025-05-14T19:15:07.159Z,1747250107.159 [CommandExec](IMPORTANT): Onboard.Humidity 1.281895 % 2025-05-14T19:15:11.991Z,1747250111.991 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2025-05-14T19:15:11.991Z,1747250111.991 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-05-14T19:15:12.003Z,1747250112.003 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-05-14T19:15:12.405Z,1747250112.405 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-05-14T19:15:12.405Z,1747250112.405 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2025-05-14T19:15:24.166Z,1747250124.166 [CommandExec](IMPORTANT): got command get Onboard.Temperature celsius 2025-05-14T19:15:24.297Z,1747250124.297 [CommandExec](IMPORTANT): Onboard.Temperature 21.358820 degC 2025-05-14T19:15:37.203Z,1747250137.203 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00, 2025-05-14T19:15:56.377Z,1747250156.377 [DAT](INFO): DAT read: sponse Not Received 2025-05-14T19:15:56.392Z,1747250156.392 [DAT](INFO): unknown deviceResponse_: sponse Not Received 2025-05-14T19:15:56.997Z,1747250156.997 [RDI_Pathfinder](ERROR): only read 2 of 4 data items for water velocity. Device response is::WS, +8, +517,A 2025-05-14T19:16:22.327Z,1747250182.327 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00 2025-05-14T19:16:55.117Z,1747250215.117 [DataOverHttps](IMPORTANT): SBD MTMSN=20250514T191651 2025-05-14T19:17:02.246Z,1747250222.246 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-14T19:17:02.246Z,1747250222.246 [Default:CheckIn:C.Wait] Stopped 2025-05-14T19:17:02.246Z,1747250222.246 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-14T19:17:02.249Z,1747250222.249 [Default:CheckIn:D] Running Loop=1 2025-05-14T19:17:02.625Z,1747250222.625 [Default:CheckIn:D] Stopped 2025-05-14T19:17:02.625Z,1747250222.625 [Default:CheckIn:E] Running Loop=1 2025-05-14T19:17:03.123Z,1747250223.123 [RDI_Pathfinder](ERROR): only read -1 of 1 data item for altitude. Device response is::BD, 2025-05-14T19:17:03.140Z,1747250223.140 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.711463 min 2025-05-14T19:17:03.140Z,1747250223.140 [Default:CheckIn:E] Stopped 2025-05-14T19:17:03.140Z,1747250223.140 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-14T19:17:03.140Z,1747250223.140 [Default:CheckIn] Stopped 2025-05-14T19:17:03.140Z,1747250223.140 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-14T19:17:03.140Z,1747250223.141 [Default:CheckIn](INFO): Running loop #2 2025-05-14T19:17:03.141Z,1747250223.141 [Default:CheckIn] Running Loop=2 2025-05-14T19:17:03.141Z,1747250223.141 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-14T19:17:03.141Z,1747250223.141 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-14T19:17:03.872Z,1747250223.872 [DataOverHttps](INFO): Received command: load Maintenance/ballast_and_trim.tl;set ballast_and_trim.ApproachSpeed 1.2 m/s;run 2025-05-14T19:17:04.060Z,1747250224.060 [CommandExec](IMPORTANT): got command load ./Missions/Maintenance/ballast_and_trim.tl 2025-05-14T19:17:04.061Z,1747250224.061 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/ballast_and_trim.tl 2025-05-14T19:17:04.061Z,1747250224.061 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Maintenance/ballast_and_trim.tl 2025-05-14T19:17:04.082Z,1747250224.082 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Maintenance/ballast_and_trim.tl 2025-05-14T19:17:04.536Z,1747250224.536 [DAT](INFO): DAT read: user:8>Rx Time:19:16:59.9715 2025-05-14T19:17:04.552Z,1747250224.552 [DAT](INFO): Rx dataTimestamp_ set to:1747250224.551843 2025-05-14T19:17:05.119Z,1747250225.119 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191702.00,A,3647.80176,N,12150.65959,W,0.641,32.70,140525,,,A*4D 2025-05-14T19:17:05.121Z,1747250225.121 [NAL9602](INFO): GPS fix at 20250514T191702: (36.796696, -121.844326) 2025-05-14T19:17:05.298Z,1747250225.298 [DAT](INFO): DAT read: 19:16:59.9715 LVL= 13488, 12977, 16290, 8803, AGC= 72, IDX= 177, 0.08, 1.928, 2.623, 0.603, 1.609, PHS= 0.353, 1.044,-0.933, RAW= 9.9, -5.4, CAL= 11.1, -9.6, ROT= 138.9, 9.6 2025-05-14T19:17:05.299Z,1747250225.299 [DAT](INFO): got valid direction response: 19:16:59.9715 LVL= 13488, 12977, 16290, 8803, AGC= 72, IDX= 177, 0.08, 1.928, 2.623, 0.603, 1.609, PHS= 0.353, 1.044,-0.933, RAW= 9.9, -5.4, CAL= 11.1, -9.6, ROT= 138.9, 9.6 2025-05-14T19:17:05.299Z,1747250225.299 [DAT](INFO): DAT read: 2025-05-14T19:17:05.300Z,1747250225.300 [DAT](INFO): DAT read: $Packet for address 5 2025-05-14T19:17:05.300Z,1747250225.300 [DAT](INFO): received a packet notification 2025-05-14T19:17:05.311Z,1747250225.311 [DAT](INFO): #Rx 11: Read direction message, but no range. 2025-05-14T19:17:05.312Z,1747250225.312 [DAT](INFO): direction in FSK: [-0.743010,0.648169,-0.166769] 2025-05-14T19:17:10.243Z,1747250230.243 [MissionManager](DEBUG): TethyslAPI: ./Missions/Maintenance/ballast_and_trim.tl translated into: 90 35 NaN 0.1 1 20 0.4 20 0 10 45 10 0.5 25 98.0 0.25 5 52 2 1 Aborting ballast and trim mission. Target depth Depth1 exceeds the mission's MaxDepth setting (review mission parameters). stop Aborting ballast and trim mission. Target depth Depth2 exceeds the mission's MaxDepth setting (review mission parameters). stop 0 Checking for additional instructions before submerging. 2025-05-14T19:17:10.264Z,1747250230.264 [MissionManager](INFO): DefineArg ballast_and_trim.MissionTimeout = 90.000000 min 2025-05-14T19:17:10.332Z,1747250230.332 [MissionManager](INFO): DefineArg ballast_and_trim.SkipComms = 0 bool 2025-05-14T19:17:10.336Z,1747250230.336 [MissionManager](INFO): DefineArg ballast_and_trim.Depth1 = 35.000000 m 2025-05-14T19:17:10.341Z,1747250230.341 [MissionManager](INFO): DefineArg ballast_and_trim.Depth2 = nan m 2025-05-14T19:17:10.345Z,1747250230.345 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 0.100000 m 2025-05-14T19:17:10.348Z,1747250230.348 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachSpeed = 1.000000 m/s 2025-05-14T19:17:10.379Z,1747250230.379 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachDepthTimeout = 20.000000 min 2025-05-14T19:17:10.383Z,1747250230.383 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachDepthRate = 0.400000 m/s 2025-05-14T19:17:10.396Z,1747250230.396 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachPitchLimit = 20.000000 arcdeg 2025-05-14T19:17:10.415Z,1747250230.415 [MissionManager](INFO): DefineArg ballast_and_trim.ApproachSettleTimePreDive = 0.000000 min 2025-05-14T19:17:10.418Z,1747250230.418 [MissionManager](INFO): DefineArg ballast_and_trim.SettleTime = 10.000000 min 2025-05-14T19:17:10.426Z,1747250230.426 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationTimeout = 45.000000 min 2025-05-14T19:17:10.433Z,1747250230.433 [MissionManager](INFO): DefineArg ballast_and_trim.MinEstimationTime = 10.000000 min 2025-05-14T19:17:10.466Z,1747250230.466 [MissionManager](INFO): DefineArg ballast_and_trim.MassEstimationErrorBound = 0.500000 mm 2025-05-14T19:17:10.481Z,1747250230.481 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyEstimationErrorBound = 25.000000 cc 2025-05-14T19:17:10.484Z,1747250230.484 [MissionManager](INFO): DefineArg ballast_and_trim.EstimationConfidence = 98.000000 % 2025-05-14T19:17:10.502Z,1747250230.502 [MissionManager](INFO): DefineArg ballast_and_trim.massDeadband = 0.250000 mm 2025-05-14T19:17:10.510Z,1747250230.510 [MissionManager](INFO): DefineArg ballast_and_trim.MinAltitude = 5.000000 m 2025-05-14T19:17:10.518Z,1747250230.518 [MissionManager](INFO): DefineArg ballast_and_trim.MaxDepth = 52.000000 m 2025-05-14T19:17:10.533Z,1747250230.533 [MissionManager](INFO): DefineArg ballast_and_trim.MinOffshore = 2.000000 km 2025-05-14T19:17:10.537Z,1747250230.537 [MissionManager](INFO): DefineArg ballast_and_trim.CheckDepths = 1.000000 bool 2025-05-14T19:17:10.537Z,1747250230.537 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.tl 2025-05-14T19:17:10.538Z,1747250230.538 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/Science.tl 2025-05-14T19:17:10.554Z,1747250230.554 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/Science.tl 2025-05-14T19:17:14.722Z,1747250234.722 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/Science.tl translated into: NaN 3 100 80 20 5 NaN NaN 2.0 NaN 0 NaN NaN 5 0 0 0 0 NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN NaN PeakDetectVsDepth EdgeDetectVsDepth GoDown, ChlPeakThresh, PeakChl, PeakChlDepth, EdgeChl, EdgeChlDepth = , , , , , PeakDetectVsDepth PeakDetectVsDepth PeakDetectVsDepth PeakDetectVsDepth PeakDetectVsDepth _.planktivore_LM_AvgRois EdgeDetectVsDepth _.planktivore_LM_AvgRois GoDown, PlanktivoreLMavgROIPeakThresh, PeakPlanktivoreLMavgROI, PeakPlanktivoreLMavgROIDepth, EdgePlanktivoreLMavgROI, EdgePlanktivoreLMavgROIDepth = , , , , , PeakDetectVsDepth _.planktivore_HM_AvgRois EdgeDetectVsDepth _.planktivore_HM_AvgRois GoDown, PlanktivoreHMavgROIPeakThresh, PeakPlanktivoreHMavgROI, PeakPlanktivoreHMavgROIDepth, EdgePlanktivoreHMavgROI, EdgePlanktivoreHMavgROIDepth = , , , , , PeakDetectVsDepth _.planktivore_diatoms EdgeDetectVsDepth _.planktivore_diatoms GoDown, PlanktivoreDiatomsPeakThresh, PeakPlanktivoreDiatoms, PeakPlanktivoreDiatomsDepth, EdgePlanktivoreDiatoms, EdgePlanktivoreDiatomsDepth = , , , , , PeakDetectVsDepth _.planktivore_dinoflagellates EdgeDetectVsDepth _.planktivore_dinoflagellates GoDown, PlanktivoreDinoflagellatesPeakThresh, PeakPlanktivoreDinoflagellates, PeakPlanktivoreDinoflagellatesDepth, EdgePlanktivoreDinoflagellates, EdgePlanktivoreDinoflagellatesDepth = , , , , , Triggered. PeakChl values on consecutive yo-yo profiles fall in [ ] (NaN bound is ignored). 2025-05-14T19:17:14.821Z,1747250234.821 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectChlActive = 0 bool 2025-05-14T19:17:14.877Z,1747250234.877 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EdgeDetectChlActive = 0 bool 2025-05-14T19:17:14.881Z,1747250234.881 [MissionManager](INFO): DefineArg ballast_and_trim:Science.TimeWindowPeakReport = nan min 2025-05-14T19:17:14.936Z,1747250234.936 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestChlPeakReportActive = 0 bool 2025-05-14T19:17:14.946Z,1747250234.946 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestSaltPeakReportActive = 0 bool 2025-05-14T19:17:14.958Z,1747250234.958 [MissionManager](INFO): DefineArg ballast_and_trim:Science.HighestOilPeakReportActive = 0 bool 2025-05-14T19:17:14.965Z,1747250234.965 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PatchTracking = 0 bool 2025-05-14T19:17:14.969Z,1747250234.969 [MissionManager](INFO): DefineArg ballast_and_trim:Science.FilterWidthHorizontal = 3.000000 count 2025-05-14T19:17:14.980Z,1747250234.980 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesSlidingwindow = 100.000000 count 2025-05-14T19:17:14.986Z,1747250234.986 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionHorizontal = 80.000000 % 2025-05-14T19:17:15.002Z,1747250235.002 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectNO3Active = 0 bool 2025-05-14T19:17:15.010Z,1747250235.010 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectOilActive = 0 bool 2025-05-14T19:17:15.013Z,1747250235.013 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectFDOMActive = 0 bool 2025-05-14T19:17:15.028Z,1747250235.028 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectSalinityActive = 0 bool 2025-05-14T19:17:15.031Z,1747250235.031 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectPlanktivoreLMavgROIActive = 0 bool 2025-05-14T19:17:15.038Z,1747250235.038 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EdgeDetectPlanktivoreLMavgROIActive = 0 bool 2025-05-14T19:17:15.041Z,1747250235.041 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectPlanktivoreHMavgROIActive = 0 bool 2025-05-14T19:17:15.056Z,1747250235.056 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EdgeDetectPlanktivoreHMavgROIActive = 0 bool 2025-05-14T19:17:15.061Z,1747250235.061 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectPlanktivoreDiatomsActive = 0 bool 2025-05-14T19:17:15.072Z,1747250235.072 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EdgeDetectPlanktivoreDiatomsActive = 0 bool 2025-05-14T19:17:15.074Z,1747250235.074 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PeakDetectPlanktivoreDinoflagellatesActive = 0 bool 2025-05-14T19:17:15.081Z,1747250235.081 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EdgeDetectPlanktivoreDinoflagellatesActive = 0 bool 2025-05-14T19:17:15.086Z,1747250235.086 [MissionManager](INFO): DefineArg ballast_and_trim:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2025-05-14T19:17:15.098Z,1747250235.098 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledAanderaaO2 = 0 bool 2025-05-14T19:17:15.115Z,1747250235.115 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledNeilBrown = 0 bool 2025-05-14T19:17:15.124Z,1747250235.124 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledRBRTridente = 1 bool 2025-05-14T19:17:15.148Z,1747250235.148 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledSeabird = 1 bool 2025-05-14T19:17:15.154Z,1747250235.154 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsBB2FL = 0 bool 2025-05-14T19:17:15.166Z,1747250235.166 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool 2025-05-14T19:17:15.171Z,1747250235.171 [MissionManager](INFO): DefineArg ballast_and_trim:Science.EnabledWetLabsUBAT = 0 bool 2025-05-14T19:17:15.206Z,1747250235.206 [MissionManager](INFO): DefineArg ballast_and_trim:Science.LowPassWindowLength = 20.000000 count 2025-05-14T19:17:15.221Z,1747250235.221 [MissionManager](INFO): DefineArg ballast_and_trim:Science.MedianFilterLen = 5.000000 count 2025-05-14T19:17:15.234Z,1747250235.234 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ShallowBound = nan m 2025-05-14T19:17:15.242Z,1747250235.242 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DeepBound = nan m 2025-05-14T19:17:15.264Z,1747250235.264 [MissionManager](INFO): DefineArg ballast_and_trim:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2025-05-14T19:17:15.272Z,1747250235.272 [MissionManager](INFO): DefineArg ballast_and_trim:Science.OffPeakFractionVertical = nan % 2025-05-14T19:17:15.279Z,1747250235.279 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlPeakThresh = 0.000000 ug/l 2025-05-14T19:17:15.282Z,1747250235.282 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlThreshTriggerActive = 0 bool 2025-05-14T19:17:15.289Z,1747250235.289 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlLowerThresh = nan ug/l 2025-05-14T19:17:15.297Z,1747250235.297 [MissionManager](INFO): DefineArg ballast_and_trim:Science.ChlUpperThresh = nan ug/l 2025-05-14T19:17:15.300Z,1747250235.300 [MissionManager](INFO): DefineArg ballast_and_trim:Science.NumProfilesThresh = 5.000000 count 2025-05-14T19:17:15.307Z,1747250235.307 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PlanktivoreLMavgROIPeakThresh = 0.000000 count/s 2025-05-14T19:17:15.310Z,1747250235.310 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PlanktivoreHMavgROIPeakThresh = 0.000000 count/s 2025-05-14T19:17:15.318Z,1747250235.318 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PlanktivoreDiatomsPeakThresh = 0.000000 count/ml 2025-05-14T19:17:15.331Z,1747250235.331 [MissionManager](INFO): DefineArg ballast_and_trim:Science.PlanktivoreDinoflagellatesPeakThresh = 0.000000 count/ml 2025-05-14T19:17:15.334Z,1747250235.334 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.GoDown = 1 bool 2025-05-14T19:17:15.342Z,1747250235.342 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChl = nan ug/l 2025-05-14T19:17:15.350Z,1747250235.350 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlDepth = nan m 2025-05-14T19:17:15.353Z,1747250235.353 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlTemperature = nan degC 2025-05-14T19:17:15.369Z,1747250235.369 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLatitude = nan arcdeg 2025-05-14T19:17:15.377Z,1747250235.377 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakChlLongitude = nan arcdeg 2025-05-14T19:17:15.381Z,1747250235.381 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgeChl = nan ug/l 2025-05-14T19:17:15.392Z,1747250235.392 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgeChlDepth = nan m 2025-05-14T19:17:15.400Z,1747250235.400 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgeChlTemperature = nan degC 2025-05-14T19:17:15.408Z,1747250235.408 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgeChlLatitude = nan arcdeg 2025-05-14T19:17:15.416Z,1747250235.416 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgeChlLongitude = nan arcdeg 2025-05-14T19:17:15.421Z,1747250235.421 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3 = nan umol/l 2025-05-14T19:17:15.437Z,1747250235.437 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Depth = nan m 2025-05-14T19:17:15.444Z,1747250235.444 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Latitude = nan arcdeg 2025-05-14T19:17:15.452Z,1747250235.452 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakNO3Longitude = nan arcdeg 2025-05-14T19:17:15.464Z,1747250235.464 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOil = nan kg/m3 2025-05-14T19:17:15.475Z,1747250235.475 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilDepth = nan m 2025-05-14T19:17:15.478Z,1747250235.478 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLatitude = nan arcdeg 2025-05-14T19:17:15.485Z,1747250235.485 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakOilLongitude = nan arcdeg 2025-05-14T19:17:15.489Z,1747250235.489 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOM = nan ppb 2025-05-14T19:17:15.501Z,1747250235.501 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMDepth = nan m 2025-05-14T19:17:15.505Z,1747250235.505 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMTemperature = nan degC 2025-05-14T19:17:15.516Z,1747250235.516 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLatitude = nan arcdeg 2025-05-14T19:17:15.524Z,1747250235.524 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakFDOMLongitude = nan arcdeg 2025-05-14T19:17:15.552Z,1747250235.552 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSalt = nan psu 2025-05-14T19:17:15.559Z,1747250235.559 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltDepth = nan m 2025-05-14T19:17:15.562Z,1747250235.562 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltTemperature = nan degC 2025-05-14T19:17:15.569Z,1747250235.569 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLatitude = nan arcdeg 2025-05-14T19:17:15.597Z,1747250235.597 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakSaltLongitude = nan arcdeg 2025-05-14T19:17:15.608Z,1747250235.608 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreLMavgROI = nan count/s 2025-05-14T19:17:15.615Z,1747250235.615 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreLMavgROIDepth = nan m 2025-05-14T19:17:15.621Z,1747250235.621 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreLMavgROITemperature = nan degC 2025-05-14T19:17:15.626Z,1747250235.626 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreLMavgROILatitude = nan arcdeg 2025-05-14T19:17:15.634Z,1747250235.634 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreLMavgROILongitude = nan arcdeg 2025-05-14T19:17:15.653Z,1747250235.653 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreLMavgROI = nan count/s 2025-05-14T19:17:15.662Z,1747250235.662 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreLMavgROIDepth = nan m 2025-05-14T19:17:15.669Z,1747250235.669 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreLMavgROITemperature = nan degC 2025-05-14T19:17:15.673Z,1747250235.673 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreLMavgROILatitude = nan arcdeg 2025-05-14T19:17:15.684Z,1747250235.684 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreLMavgROILongitude = nan arcdeg 2025-05-14T19:17:15.692Z,1747250235.692 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreHMavgROI = nan count/s 2025-05-14T19:17:15.699Z,1747250235.699 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreHMavgROIDepth = nan m 2025-05-14T19:17:15.702Z,1747250235.702 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreHMavgROITemperature = nan degC 2025-05-14T19:17:15.706Z,1747250235.706 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreHMavgROILatitude = nan arcdeg 2025-05-14T19:17:15.717Z,1747250235.717 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreHMavgROILongitude = nan arcdeg 2025-05-14T19:17:15.722Z,1747250235.722 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreHMavgROI = nan count/s 2025-05-14T19:17:15.733Z,1747250235.733 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreHMavgROIDepth = nan m 2025-05-14T19:17:15.749Z,1747250235.749 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreHMavgROITemperature = nan degC 2025-05-14T19:17:15.752Z,1747250235.752 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreHMavgROILatitude = nan arcdeg 2025-05-14T19:17:15.760Z,1747250235.760 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreHMavgROILongitude = nan arcdeg 2025-05-14T19:17:15.767Z,1747250235.767 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDiatoms = nan count/ml 2025-05-14T19:17:15.770Z,1747250235.770 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDiatomsDepth = nan m 2025-05-14T19:17:15.778Z,1747250235.778 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDiatomsTemperature = nan degC 2025-05-14T19:17:15.786Z,1747250235.786 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDiatomsLatitude = nan arcdeg 2025-05-14T19:17:15.794Z,1747250235.794 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDiatomsLongitude = nan arcdeg 2025-05-14T19:17:15.801Z,1747250235.801 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreDiatoms = nan count/ml 2025-05-14T19:17:15.816Z,1747250235.816 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreDiatomsDepth = nan m 2025-05-14T19:17:15.823Z,1747250235.823 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreDiatomsTemperature = nan degC 2025-05-14T19:17:15.830Z,1747250235.830 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreDiatomsLatitude = nan arcdeg 2025-05-14T19:17:15.839Z,1747250235.839 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreDiatomsLongitude = nan arcdeg 2025-05-14T19:17:15.842Z,1747250235.842 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDinoflagellates = nan count/ml 2025-05-14T19:17:15.854Z,1747250235.854 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDinoflagellatesDepth = nan m 2025-05-14T19:17:15.857Z,1747250235.857 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDinoflagellatesTemperature = nan degC 2025-05-14T19:17:15.869Z,1747250235.869 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDinoflagellatesLatitude = nan arcdeg 2025-05-14T19:17:15.876Z,1747250235.876 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PeakPlanktivoreDinoflagellatesLongitude = nan arcdeg 2025-05-14T19:17:15.884Z,1747250235.884 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreDinoflagellates = nan count/ml 2025-05-14T19:17:15.897Z,1747250235.897 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreDinoflagellatesDepth = nan m 2025-05-14T19:17:15.901Z,1747250235.901 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreDinoflagellatesTemperature = nan degC 2025-05-14T19:17:15.912Z,1747250235.912 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreDinoflagellatesLatitude = nan arcdeg 2025-05-14T19:17:15.920Z,1747250235.920 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.EdgePlanktivoreDinoflagellatesLongitude = nan arcdeg 2025-05-14T19:17:15.924Z,1747250235.924 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChl = nan ug/l 2025-05-14T19:17:15.979Z,1747250235.979 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDepth = nan m 2025-05-14T19:17:15.982Z,1747250235.982 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLatitude = nan arcdeg 2025-05-14T19:17:15.996Z,1747250235.996 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlLongitude = nan arcdeg 2025-05-14T19:17:16.002Z,1747250236.002 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchChlDistance = nan m 2025-05-14T19:17:16.033Z,1747250236.033 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSalt = nan psu 2025-05-14T19:17:16.061Z,1747250236.061 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDepth = nan m 2025-05-14T19:17:16.079Z,1747250236.079 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLatitude = nan arcdeg 2025-05-14T19:17:16.086Z,1747250236.086 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltLongitude = nan arcdeg 2025-05-14T19:17:16.099Z,1747250236.099 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchSaltDistance = nan m 2025-05-14T19:17:16.102Z,1747250236.102 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOil = nan psu 2025-05-14T19:17:16.114Z,1747250236.114 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDepth = nan m 2025-05-14T19:17:16.122Z,1747250236.122 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLatitude = nan arcdeg 2025-05-14T19:17:16.130Z,1747250236.130 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilLongitude = nan arcdeg 2025-05-14T19:17:16.137Z,1747250236.137 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.PatchOilDistance = nan m 2025-05-14T19:17:16.142Z,1747250236.142 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.TriggeredOnChl = 0 bool 2025-05-14T19:17:16.153Z,1747250236.153 [MissionManager](INFO): DefineOutput ballast_and_trim:Science.ChlTriggering = nan ug/l 2025-05-14T19:17:16.742Z,1747250236.742 [ballast_and_trim:Science:PeakDetectChl:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-05-14T19:17:16.831Z,1747250236.831 [ballast_and_trim:Science:EdgeDetectChl:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-05-14T19:17:16.902Z,1747250236.902 [ballast_and_trim:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2025-05-14T19:17:16.959Z,1747250236.959 [ballast_and_trim:Science:PeakDetectNO3:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-05-14T19:17:16.993Z,1747250236.993 [ballast_and_trim:Science:PeakDetectOil:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-05-14T19:17:17.030Z,1747250237.030 [ballast_and_trim:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2025-05-14T19:17:17.065Z,1747250237.065 [ballast_and_trim:Science:PeakDetectFDOM:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-05-14T19:17:17.096Z,1747250237.096 [ballast_and_trim:Science:PeakDetectSalinity:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-05-14T19:17:17.141Z,1747250237.141 [ballast_and_trim:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2025-05-14T19:17:17.228Z,1747250237.228 [ballast_and_trim:Science:PeakDetectPlanktivoreLMavgROI:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-05-14T19:17:17.278Z,1747250237.278 [ballast_and_trim:Science:EdgeDetectPlanktivoreLMavgROI:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-05-14T19:17:17.343Z,1747250237.343 [ballast_and_trim:Science:PeakDetectPlanktivoreHMavgROI:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-05-14T19:17:17.369Z,1747250237.369 [ballast_and_trim:Science:EdgeDetectPlanktivoreHMavgROI:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-05-14T19:17:17.428Z,1747250237.428 [ballast_and_trim:Science:PeakDetectPlanktivoreDiatoms:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-05-14T19:17:17.468Z,1747250237.468 [ballast_and_trim:Science:EdgeDetectPlanktivoreDiatoms:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-05-14T19:17:17.532Z,1747250237.532 [ballast_and_trim:Science:PeakDetectPlanktivoreDinoflagellates:B.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2025-05-14T19:17:17.610Z,1747250237.610 [ballast_and_trim:Science:EdgeDetectPlanktivoreDinoflagellates:B.EdgeDetectVsDepth](DEBUG): Construct EdgeDetectVsDepth. 2025-05-14T19:17:17.670Z,1747250237.670 [ballast_and_trim:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect. 2025-05-14T19:17:17.686Z,1747250237.686 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-05-14T19:17:17.686Z,1747250237.686 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-05-14T19:17:17.692Z,1747250237.692 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-05-14T19:17:18.954Z,1747250238.954 [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-14T19:17:19.300Z,1747250239.300 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.DiveInterval = 1.000000 h 2025-05-14T19:17:19.342Z,1747250239.342 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.WaitForPitchUp = 10.000000 min 2025-05-14T19:17:19.412Z,1747250239.412 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-05-14T19:17:20.242Z,1747250240.242 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfaceDepthRate = nan m/s 2025-05-14T19:17:20.245Z,1747250240.245 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-05-14T19:17:20.266Z,1747250240.266 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.SurfacingTimeout = 1000.000000 s 2025-05-14T19:17:20.270Z,1747250240.270 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.GPSTimeout = 7.000000 min 2025-05-14T19:17:20.345Z,1747250240.345 [MissionManager](INFO): DefineArg ballast_and_trim:NeedComms.CommsTimeout = 30.000000 min 2025-05-14T19:17:20.484Z,1747250240.484 [ballast_and_trim:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-05-14T19:17:20.826Z,1747250240.826 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-05-14T19:17:20.866Z,1747250240.866 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-05-14T19:17:21.725Z,1747250241.725 [DAT](INFO): DAT read: Rx Time:19:17:16.9135 2025-05-14T19:17:47.762Z,1747250267.762 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-05-14T19:17:47.899Z,1747250267.899 [DAT](INFO): Rx dataTimestamp_ set to:1747250266.791528 2025-05-14T19:17:47.914Z,1747250267.914 [DAT](INFO): DAT read: 19:17:16.9135 LVL= 11680, 14577, 10610, 10739, AGC= 74, IDX= 385,-0.04, 1.672, 1.994, 0.556, 1.550, PHS= 0.156, 0.473,-0.922, RAW= 17.5, 4.7, CAL= 18.2, 0.4, ROT= 131.8, -0.4 2025-05-14T19:17:52.705Z,1747250272.705 [DAT](INFO): got valid direction response: 19:17:16.9135 LVL= 11680, 14577, 10610, 10739, AGC= 74, IDX= 385,-0.04, 1.672, 1.994, 0.556, 1.550, PHS= 0.156, 0.473,-0.922, RAW= 17.5, 4.7, CAL= 18.2, 0.4, ROT= 131.8, -0.4 2025-05-14T19:17:52.717Z,1747250272.717 [DAT](INFO): DAT read: 2025-05-14T19:17:52.718Z,1747250272.718 [DAT](INFO): DAT read: $Packet for address 5 2025-05-14T19:17:52.728Z,1747250272.728 [DAT](INFO): received a packet notification 2025-05-14T19:17:52.740Z,1747250272.740 [DAT](INFO): #Rx 12: Read direction message, but no range. 2025-05-14T19:17:52.795Z,1747250272.795 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2025-05-14T19:17:52.796Z,1747250272.796 [RDI_Pathfinder] Communications Fault, FailCount= 1 2025-05-14T19:17:52.796Z,1747250272.796 [RDI_Pathfinder](ERROR): Communications Fault 2025-05-14T19:17:53.038Z,1747250273.038 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000