2025-06-24T19:29:50.135Z,1750793390.135 [CommandExec](IMPORTANT): got command restart logs 2025-06-24T19:30:28.890Z,1750793428.890 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T19:30:59.578Z,1750793459.578 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T19:31:26.664Z,1750793486.664 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T19:31:26.664Z,1750793486.664 [Default:CheckIn:C.Wait] Stopped 2025-06-24T19:31:26.664Z,1750793486.664 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T19:31:26.664Z,1750793486.664 [Default:CheckIn:D] Running Loop=1 2025-06-24T19:31:27.060Z,1750793487.060 [Default:CheckIn:D] Stopped 2025-06-24T19:31:27.061Z,1750793487.061 [Default:CheckIn:E] Running Loop=1 2025-06-24T19:31:27.467Z,1750793487.467 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.570176 min 2025-06-24T19:31:27.467Z,1750793487.467 [Default:CheckIn:E] Stopped 2025-06-24T19:31:27.467Z,1750793487.467 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T19:31:27.468Z,1750793487.468 [Default:CheckIn] Stopped 2025-06-24T19:31:27.468Z,1750793487.468 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T19:31:27.468Z,1750793487.468 [Default:CheckIn](INFO): Running loop #3 2025-06-24T19:31:27.468Z,1750793487.468 [Default:CheckIn] Running Loop=3 2025-06-24T19:31:27.468Z,1750793487.468 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T19:31:27.468Z,1750793487.468 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T19:31:29.475Z,1750793489.475 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193130.00,A,3648.43255,N,12149.40761,W,13.724,212.19,240625,,,D*4A 2025-06-24T19:31:29.477Z,1750793489.477 [NAL9602](INFO): GPS fix at 20250624T193130: (36.807209, -121.823460) 2025-06-24T19:31:29.487Z,1750793489.487 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T19:31:29.487Z,1750793489.487 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T19:31:36.157Z,1750793496.157 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250623T183209/Courier0141.lzma 2025-06-24T19:31:47.199Z,1750793507.199 [DataOverHttps](INFO): Moved sent file to Logs/20250623T183209/Courier0141.lzma.bak 2025-06-24T19:31:47.200Z,1750793507.200 [DataOverHttps](INFO): SBD MOMSN=25243206 2025-06-24T19:32:00.184Z,1750793520.184 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T19:32:00.257Z,1750793520.257 [NAL9602](ERROR): received: +CSQ:0 OKúÇcáÜ Wm®¶ï%rˆ3“BüœhÖ59¼eztÿÌÝÛé”: $}&ssú’ 4 45 NaN NaN 30 15 4 0.75 200 10 10 45 2 0 0 1 2 3 NaN 0 Sinking to Timed out while trying to reach target sink depth at current depth of 2025-06-24T19:49:01.471Z,1750794541.471 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2025-06-24T19:49:01.483Z,1750794541.483 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2025-06-24T19:49:01.486Z,1750794541.486 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2025-06-24T19:49:01.488Z,1750794541.488 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2025-06-24T19:49:01.490Z,1750794541.490 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2025-06-24T19:49:01.492Z,1750794541.492 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2025-06-24T19:49:01.495Z,1750794541.495 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2025-06-24T19:49:01.498Z,1750794541.498 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2025-06-24T19:49:01.500Z,1750794541.500 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2025-06-24T19:49:01.503Z,1750794541.503 [MissionManager](INFO): DefineArg keepstation.SinkOffSurface = 0 bool 2025-06-24T19:49:01.505Z,1750794541.505 [MissionManager](INFO): DefineArg keepstation.SinkDepth = 10.000000 m 2025-06-24T19:49:01.507Z,1750794541.507 [MissionManager](INFO): DefineArg keepstation.SinkDepthTimeout = 10.000000 min 2025-06-24T19:49:01.510Z,1750794541.510 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2025-06-24T19:49:01.512Z,1750794541.512 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2025-06-24T19:49:01.516Z,1750794541.516 [MissionManager](INFO): DefineOutput keepstation.DiveMode = 0.000000 count 2025-06-24T19:49:01.522Z,1750794541.522 [MissionManager](INFO): DefineOutput keepstation.DoingComms = 0.000000 count 2025-06-24T19:49:01.524Z,1750794541.524 [MissionManager](INFO): DefineOutput keepstation.SurfaceSink = 1.000000 count 2025-06-24T19:49:01.551Z,1750794541.551 [MissionManager](INFO): DefineOutput keepstation.TransitToStation = 2.000000 count 2025-06-24T19:49:01.559Z,1750794541.559 [MissionManager](INFO): DefineOutput keepstation.StationKeep = 3.000000 count 2025-06-24T19:49:01.561Z,1750794541.561 [MissionManager](INFO): DefineOutput keepstation.OnStation = 0 bool 2025-06-24T19:49:01.562Z,1750794541.562 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-06-24T19:49:01.562Z,1750794541.562 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-06-24T19:49:01.563Z,1750794541.563 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-06-24T19:49:01.905Z,1750794541.905 [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-06-24T19:49:01.909Z,1750794541.909 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2025-06-24T19:49:01.912Z,1750794541.912 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2025-06-24T19:49:01.918Z,1750794541.918 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-06-24T19:49:01.921Z,1750794541.921 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2025-06-24T19:49:01.924Z,1750794541.924 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-06-24T19:49:01.926Z,1750794541.926 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s 2025-06-24T19:49:01.928Z,1750794541.928 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2025-06-24T19:49:01.969Z,1750794541.969 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2025-06-24T19:49:01.974Z,1750794541.974 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-06-24T19:49:02.038Z,1750794542.038 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-06-24T19:49:02.038Z,1750794542.038 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-06-24T19:49:02.039Z,1750794542.039 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-06-24T19:49:02.136Z,1750794542.136 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 1.5 200 2000 2025-06-24T19:49:02.140Z,1750794542.140 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2025-06-24T19:49:02.142Z,1750794542.142 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 1.500000 m 2025-06-24T19:49:02.144Z,1750794542.144 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2025-06-24T19:49:02.147Z,1750794542.147 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-06-24T19:49:02.148Z,1750794542.148 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-06-24T19:49:02.178Z,1750794542.178 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-06-24T19:49:02.185Z,1750794542.185 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-06-24T19:49:02.192Z,1750794542.192 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-06-24T19:49:02.192Z,1750794542.192 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-06-24T19:49:02.195Z,1750794542.195 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-06-24T19:49:02.228Z,1750794542.228 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-06-24T19:49:02.231Z,1750794542.231 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2025-06-24T19:49:02.233Z,1750794542.233 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-06-24T19:49:02.235Z,1750794542.235 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-06-24T19:49:02.236Z,1750794542.236 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-06-24T19:49:02.237Z,1750794542.237 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-06-24T19:49:02.359Z,1750794542.359 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-06-24T19:49:02.362Z,1750794542.362 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2025-06-24T19:49:02.364Z,1750794542.364 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2025-06-24T19:49:02.366Z,1750794542.366 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2025-06-24T19:49:02.368Z,1750794542.368 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2025-06-24T19:49:02.371Z,1750794542.371 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 1 bool 2025-06-24T19:49:02.377Z,1750794542.377 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-06-24T19:49:02.379Z,1750794542.379 [keepstation:H.Pitch](DEBUG): Construct. 2025-06-24T19:49:02.479Z,1750794542.479 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2025-06-24T19:49:02.486Z,1750794542.486 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Construct. 2025-06-24T19:49:02.506Z,1750794542.506 [keepstation:DiveCmd:SurfaceSink:sink:D.Pitch](DEBUG): Construct. 2025-06-24T19:49:02.528Z,1750794542.528 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Construct Buoyancy. 2025-06-24T19:49:02.531Z,1750794542.531 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Construct. 2025-06-24T19:49:02.536Z,1750794542.536 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Construct. 2025-06-24T19:49:02.542Z,1750794542.542 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Construct. 2025-06-24T19:49:02.546Z,1750794542.546 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Construct KeepStation. 2025-06-24T19:49:02.554Z,1750794542.554 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-06-24T19:49:02.566Z,1750794542.566 [keepstation:KeepStation:B.Wait](DEBUG): Construct Wait. 2025-06-24T19:49:02.593Z,1750794542.593 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission keepstation { """ Vehicle transits to desired waypoint (or stays put if none is commanded) and stays within the specified radius. """ arguments { MissionTimeout = 4 hour """ Maximum duration of mission """ NeedCommsTime = 45 minute """ How often to surface for communications """ Latitude = NaN degree """ Latitude of waypoint to hold. If NaN, hold the latitude at start of mission. """ Longitude = NaN degree """ Longitude of waypoint to hold. If NaN, hold the longitude at start of mission. """ Depth = 30 meter """ Depth held during drift mode """ ApproachDepth = 15 meter """ Depth for initial approach to station. """ DepthDeadband = 4 meter """ How much vertical drift from the specified depth is allowed in drift mode """ Speed = 0.75 meter_per_second """ Vehicle transit speed. """ Radius = 200 meter """ Radius of circle around waypoint to hold. """ SinkOffSurface = false """ If true, use buoyancy only to decend to SinkDepth before driving to first target depth """ SinkDepth = 10 meter """ Initial depth to decend using only buoyancy before driving """ SinkDepthTimeout = 10 minute """ Max wait time for the vehicle to reach the target depth """ MaxDepth = 45 meter """ Maximum allowable depth during the mission. """ MinOffshore = 2 kilometer """ Minimum distance from the shoreline to maintain """ } output { DiveMode = 0 count """ Mission variable (don't change). The mission sets this variable to switch between dive modes. Initialized to DoingComms. """ DoingComms = 0 count """ Mission variable (don't change). The mission will run in this mode when doing surface comms. """ SurfaceSink = 1 count """ Mission variable (don't change). The mission will run in this mode when sinking from the surface using only buoyancy. """ TransitToStation = 2 count """ Mission variable (don't change). The mission will run in this mode when performing the transit to station """ StationKeep = 3 count """ Mission variable (don't change). The mission will run in this mode when keeping station """ OnStation = false """ Mission variable (don't change). Determines last mode (Transit or StationKeep) to resume after needcomms """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl behavior Guidance:Pitch { run in parallel set massPosition = Control:VerticalControl.massDefault } aggregate SurfaceComms { run when ( called or ( elapsed ( Universal:time_fix ) > NeedCommsTime ) ) assign in sequence DiveMode = DoingComms call priorityHere=false refId="NeedComms" aggregate setTransit { run in sequence break if ( SinkOffSurface or OnStation ) assign in sequence DiveMode = TransitToStation } aggregate setStation { run in sequence break if ( SinkOffSurface or not OnStation ) assign in sequence DiveMode = StationKeep } aggregate setSink { run in sequence break if ( not SinkOffSurface ) assign in sequence DiveMode = SurfaceSink } } call id="StartingMission" priorityHere=false refId="SurfaceComms" aggregate DiveCmd { run when ( called ) aggregate SurfaceSink { run while ( DiveMode == SurfaceSink ) aggregate sink { run in sequence behavior Guidance:Buoyancy id="BuoyancyHold" { run in parallel set position = NaN cc } behavior Guidance:SetSpeed { run in parallel set speed = 0 m/s } syslog info "Sinking to " + SinkDepth~meter behavior Guidance:Pitch { run in sequence timeout duration=SinkDepthTimeout { syslog important "Timed out while trying to reach target sink depth at current depth of " + Universal:depth~meter } set depth = SinkDepth } # Once we've sunk, either resume transit or station keeping depths and speeds aggregate sinkToTransit { run in sequence break if ( OnStation ) assign in sequence DiveMode = TransitToStation } aggregate sinkToStation { run in sequence break if ( not OnStation ) assign in sequence DiveMode = StationKeep } } } aggregate TransitToStation { run while ( DiveMode == TransitToStation ) aggregate dive { run in sequence behavior Guidance:Buoyancy { run in parallel set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch { run in parallel set depth = ApproachDepth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } } } aggregate StationKeep { run while ( DiveMode == StationKeep ) assign in parallel Control:VerticalControl.depthDeadband = DepthDeadband behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:KeepStation { run in parallel set latitude = Latitude set longitude = Longitude set radius = Radius set speed = Speed } } } aggregate TransitToStation { """ Need a separate aggregate for transit if we want to specify a different depth for the approach. """ run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false refId="DiveCmd" } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude } assign in sequence DiveMode = StationKeep assign in sequence OnStation = true } call id="OnStation" priorityHere=false refId="NeedComms" aggregate KeepStation { run in sequence aggregate Dive { run while ( not ( DiveMode == DoingComms ) ) call priorityHere=false refId="DiveCmd" } behavior Guidance:Wait { """ Due to the way the KeepStation behavior is currently implemented, we must run it in parallel and use a Wait to keep from completing the mission before the timeout expires. However, this new version of the mission uses a top-level timeout so that the entire mission will not run for longer then MissionTimeout. """ run in sequence set duration = MissionTimeout } } } 2025-06-24T19:49:02.594Z,1750794542.594 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2025-06-24T19:49:15.373Z,1750794555.373 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250624T192950/Express0013.lzma 2025-06-24T19:49:25.954Z,1750794565.954 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 12 hour 2025-06-24T19:49:25.955Z,1750794565.955 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 60 minute 2025-06-24T19:49:25.956Z,1750794565.956 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.797 degree 2025-06-24T19:49:25.956Z,1750794565.956 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.847 degree 2025-06-24T19:49:25.957Z,1750794565.957 [CommandExec](IMPORTANT): got command set keepstation.Speed 1 meter_per_second 2025-06-24T19:49:26.415Z,1750794566.415 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0013.lzma.bak 2025-06-24T19:49:26.415Z,1750794566.415 [DataOverHttps](INFO): SBD MOMSN=25243264 2025-06-24T19:49:27.878Z,1750794567.878 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T19:49:27.878Z,1750794567.878 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T19:49:27.878Z,1750794567.878 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T19:49:30.682Z,1750794570.682 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T19:49:34.953Z,1750794574.953 [CommandExec](IMPORTANT): got command set keepstation:NeedComms.CommsTimeout 7 minute 2025-06-24T19:49:34.954Z,1750794574.954 [CommandExec](IMPORTANT): got command run 2025-06-24T19:49:34.975Z,1750794574.975 [CommandExec](IMPORTANT): Running 2025-06-24T19:49:35.152Z,1750794575.152 [Default] Stopped 2025-06-24T19:49:35.152Z,1750794575.152 [Default](DEBUG): Aggregate::uninitialize Default 2025-06-24T19:49:35.152Z,1750794575.152 [Default:B.GoToSurface] Stopped 2025-06-24T19:49:35.152Z,1750794575.152 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-24T19:49:35.152Z,1750794575.152 [Default:CheckIn] Stopped 2025-06-24T19:49:35.152Z,1750794575.152 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T19:49:35.152Z,1750794575.152 [Default:CheckIn:C.Wait] Stopped 2025-06-24T19:49:35.152Z,1750794575.152 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T19:49:35.153Z,1750794575.153 [MissionManager](IMPORTANT): Started mission keepstation 2025-06-24T19:49:35.157Z,1750794575.157 [keepstation] Running Loop=1 2025-06-24T19:49:35.157Z,1750794575.157 [keepstation](DEBUG): Aggregate::initialize keepstation 2025-06-24T19:49:35.157Z,1750794575.157 [keepstation:StandardEnvelopes] Running Loop=1 2025-06-24T19:49:35.157Z,1750794575.157 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2025-06-24T19:49:35.157Z,1750794575.157 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-06-24T19:49:35.158Z,1750794575.158 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-06-24T19:49:35.158Z,1750794575.158 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-06-24T19:49:35.158Z,1750794575.158 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-06-24T19:49:35.158Z,1750794575.158 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-06-24T19:49:35.158Z,1750794575.158 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-06-24T19:49:35.158Z,1750794575.158 [keepstation:BackseatDriver] Running Loop=1 2025-06-24T19:49:35.158Z,1750794575.158 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2025-06-24T19:49:35.158Z,1750794575.158 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-06-24T19:49:35.158Z,1750794575.158 [keepstation:PowerOnly] Running Loop=1 2025-06-24T19:49:35.159Z,1750794575.159 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2025-06-24T19:49:35.159Z,1750794575.159 [keepstation:PowerOnly:A] Running Loop=1 2025-06-24T19:49:35.159Z,1750794575.159 [keepstation:PowerOnly:B] Running Loop=1 2025-06-24T19:49:35.159Z,1750794575.159 [keepstation:PowerOnly:C] Running Loop=1 2025-06-24T19:49:35.159Z,1750794575.159 [keepstation:PowerOnly:D] Running Loop=1 2025-06-24T19:49:35.159Z,1750794575.159 [keepstation:PowerOnly:E.Wait] Running Loop=1 2025-06-24T19:49:35.159Z,1750794575.159 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-06-24T19:49:35.159Z,1750794575.159 [keepstation:H.Pitch] Running Loop=1 2025-06-24T19:49:35.159Z,1750794575.159 [keepstation:H.Pitch](DEBUG): Initialize. 2025-06-24T19:49:35.159Z,1750794575.159 [keepstation:B] Running Loop=1 2025-06-24T19:49:35.160Z,1750794575.160 [keepstation:H.Pitch] Running Loop=1 2025-06-24T19:49:35.160Z,1750794575.160 [keepstation:PowerOnly] Running Loop=1 2025-06-24T19:49:35.165Z,1750794575.165 [keepstation:PowerOnly:D] Stopped 2025-06-24T19:49:35.165Z,1750794575.165 [keepstation:PowerOnly:C] Stopped 2025-06-24T19:49:35.165Z,1750794575.165 [keepstation:PowerOnly:B] Stopped 2025-06-24T19:49:35.165Z,1750794575.165 [keepstation:PowerOnly:A] Stopped 2025-06-24T19:49:35.165Z,1750794575.165 [keepstation:BackseatDriver] Running Loop=1 2025-06-24T19:49:35.165Z,1750794575.165 [keepstation:StandardEnvelopes] Running Loop=1 2025-06-24T19:49:35.166Z,1750794575.166 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-06-24T19:49:35.166Z,1750794575.166 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-06-24T19:49:35.166Z,1750794575.166 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-06-24T19:49:35.167Z,1750794575.167 [keepstation:B] Stopped 2025-06-24T19:49:35.167Z,1750794575.167 [keepstation:D] Running Loop=1 2025-06-24T19:49:35.560Z,1750794575.560 [keepstation:D] Stopped 2025-06-24T19:49:35.560Z,1750794575.560 [keepstation:E] Running Loop=1 2025-06-24T19:49:35.968Z,1750794575.968 [keepstation:E] Stopped 2025-06-24T19:49:35.968Z,1750794575.968 [keepstation:StartingMission] Running Loop=1 2025-06-24T19:49:35.968Z,1750794575.968 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2025-06-24T19:49:36.345Z,1750794576.345 [keepstation:SurfaceComms] Running Loop=1 2025-06-24T19:49:36.345Z,1750794576.345 [keepstation:SurfaceComms](DEBUG): Aggregate::initialize keepstation:SurfaceComms 2025-06-24T19:49:36.345Z,1750794576.345 [keepstation:SurfaceComms:A] Running Loop=1 2025-06-24T19:49:36.346Z,1750794576.346 [keepstation:SurfaceComms:A] Stopped 2025-06-24T19:49:36.346Z,1750794576.346 [keepstation:SurfaceComms:B] Running Loop=1 2025-06-24T19:49:36.346Z,1750794576.346 [keepstation:SurfaceComms:B](DEBUG): Aggregate::initialize keepstation:SurfaceComms:B 2025-06-24T19:49:36.752Z,1750794576.752 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2025-06-24T19:49:36.752Z,1750794576.752 [keepstation:NeedComms] Running Loop=1 2025-06-24T19:49:36.752Z,1750794576.752 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-06-24T19:49:36.752Z,1750794576.752 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-06-24T19:49:36.752Z,1750794576.752 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-24T19:49:36.753Z,1750794576.753 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-06-24T19:49:36.757Z,1750794576.757 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-06-24T19:49:36.757Z,1750794576.757 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-06-24T19:49:36.758Z,1750794576.758 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-06-24T19:49:36.758Z,1750794576.758 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-24T19:49:36.758Z,1750794576.758 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-24T19:49:36.759Z,1750794576.759 [keepstation:NeedComms:A] Running Loop=1 2025-06-24T19:49:36.760Z,1750794576.760 [keepstation:NeedComms:A](INFO): last time_fix was: 1750794434.000000 second since 1970/01/01T00:00:00Z 2025-06-24T19:49:36.760Z,1750794576.760 [keepstation:NeedComms:A] Stopped 2025-06-24T19:49:37.171Z,1750794577.171 [keepstation:NeedComms:C] Running Loop=1 2025-06-24T19:49:37.562Z,1750794577.562 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-06-24T19:49:39.164Z,1750794579.164 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194940.00,A,3648.27195,N,12147.14037,W,4.374,194.03,240625,,,D*75 2025-06-24T19:49:39.167Z,1750794579.167 [NAL9602](INFO): GPS fix at 20250624T194940: (36.804533, -121.785673) 2025-06-24T19:49:39.179Z,1750794579.179 [keepstation:NeedComms:C] Stopped 2025-06-24T19:49:39.179Z,1750794579.179 [keepstation:NeedComms:D] Running Loop=1 2025-06-24T19:49:39.571Z,1750794579.571 [NAL9602](FAULT): LCB fault: Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated. 2025-06-24T19:49:39.571Z,1750794579.571 [NAL9602] Hardware Fault, FailCount= 1 2025-06-24T19:49:39.571Z,1750794579.571 [NAL9602](ERROR): Hardware Fault 2025-06-24T19:49:39.652Z,1750794579.652 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-06-24T19:49:39.661Z,1750794579.661 [CBIT](ERROR): Hardware Fault in component: NAL9602 2025-06-24T19:49:40.411Z,1750794580.411 [NAL9602](INFO): Powering down 2025-06-24T19:49:40.803Z,1750794580.803 [NAL9602](FAULT): LCB fault: Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated. 2025-06-24T19:49:41.278Z,1750794581.278 [CBIT](INFO): Clearing failed state for component NAL9602 2025-06-24T19:49:41.278Z,1750794581.278 [NAL9602] No Fault, FailCount= 1 2025-06-24T19:49:55.313Z,1750794595.313 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0015.lzma 2025-06-24T19:50:06.355Z,1750794606.355 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0015.lzma.bak 2025-06-24T19:50:06.355Z,1750794606.355 [DataOverHttps](INFO): SBD MOMSN=25243268 2025-06-24T19:50:10.706Z,1750794610.706 [NAL9602](INFO): Powering up NAL9602 2025-06-24T19:50:21.614Z,1750794621.614 [NAL9602](INFO): NAL9602 initialized 2025-06-24T19:50:32.274Z,1750794632.274 [DataOverHttps](INFO): Sending 577 bytes from file Logs/20250624T192950/Express0016.lzma 2025-06-24T19:50:43.315Z,1750794643.315 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0016.lzma.bak 2025-06-24T19:50:43.315Z,1750794643.315 [DataOverHttps](INFO): SBD MOMSN=25243271 2025-06-24T19:50:44.659Z,1750794644.659 [keepstation:NeedComms:D] Stopped 2025-06-24T19:50:44.659Z,1750794644.659 [keepstation:NeedComms:E] Running Loop=1 2025-06-24T19:50:45.060Z,1750794645.060 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-06-24T19:55:48.892Z,1750794948.892 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T19:55:50.109Z,1750794950.109 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195550.00,A,3648.15248,N,12147.22207,W,0.194,200.37,240625,,,A*7B 2025-06-24T19:55:50.112Z,1750794950.112 [NAL9602](INFO): GPS fix at 20250624T195550: (36.802541, -121.787035) 2025-06-24T19:55:50.198Z,1750794950.198 [keepstation:NeedComms:E] Stopped 2025-06-24T19:55:50.199Z,1750794950.199 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-06-24T19:55:50.199Z,1750794950.199 [keepstation:NeedComms] Stopped 2025-06-24T19:55:50.199Z,1750794950.199 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-06-24T19:55:50.199Z,1750794950.199 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-06-24T19:55:50.200Z,1750794950.200 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-24T19:55:50.546Z,1750794950.546 [keepstation:SurfaceComms:B](INFO): Completed keepstation:SurfaceComms:B 2025-06-24T19:55:50.546Z,1750794950.546 [keepstation:SurfaceComms:B] Stopped 2025-06-24T19:55:50.546Z,1750794950.546 [keepstation:SurfaceComms:B](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:B 2025-06-24T19:55:50.547Z,1750794950.547 [keepstation:SurfaceComms:setTransit] Running Loop=1 2025-06-24T19:55:50.547Z,1750794950.547 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setTransit 2025-06-24T19:55:50.547Z,1750794950.547 [keepstation:SurfaceComms:setTransit:A] Running Loop=1 2025-06-24T19:55:50.931Z,1750794950.931 [keepstation:SurfaceComms:setTransit:A] Stopped 2025-06-24T19:55:50.931Z,1750794950.931 [keepstation:SurfaceComms:setTransit](INFO): Completed keepstation:SurfaceComms:setTransit 2025-06-24T19:55:50.932Z,1750794950.931 [keepstation:SurfaceComms:setTransit] Stopped 2025-06-24T19:55:50.932Z,1750794950.932 [keepstation:SurfaceComms:setTransit](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setTransit 2025-06-24T19:55:50.932Z,1750794950.932 [keepstation:SurfaceComms:setStation] Running Loop=1 2025-06-24T19:55:50.932Z,1750794950.932 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setStation 2025-06-24T19:55:50.932Z,1750794950.932 [keepstation:SurfaceComms:setStation:A] Running Loop=1 2025-06-24T19:55:51.328Z,1750794951.328 [keepstation:SurfaceComms:setStation] Stopped 2025-06-24T19:55:51.329Z,1750794951.329 [keepstation:SurfaceComms:setStation](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setStation 2025-06-24T19:55:51.329Z,1750794951.329 [keepstation:SurfaceComms:setStation:A] Stopped 2025-06-24T19:55:51.341Z,1750794951.341 [keepstation:SurfaceComms:setSink] Running Loop=1 2025-06-24T19:55:51.341Z,1750794951.341 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::initialize keepstation:SurfaceComms:setSink 2025-06-24T19:55:51.341Z,1750794951.341 [keepstation:SurfaceComms:setSink:A] Running Loop=1 2025-06-24T19:55:51.729Z,1750794951.729 [keepstation:SurfaceComms:setSink] Stopped 2025-06-24T19:55:51.729Z,1750794951.729 [keepstation:SurfaceComms:setSink](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms:setSink 2025-06-24T19:55:51.729Z,1750794951.729 [keepstation:SurfaceComms:setSink:A] Stopped 2025-06-24T19:55:51.729Z,1750794951.729 [keepstation:SurfaceComms](INFO): Completed keepstation:SurfaceComms 2025-06-24T19:55:51.729Z,1750794951.729 [keepstation:SurfaceComms] Stopped 2025-06-24T19:55:51.729Z,1750794951.729 [keepstation:SurfaceComms](DEBUG): Aggregate::uninitialize keepstation:SurfaceComms 2025-06-24T19:55:52.142Z,1750794952.142 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-06-24T19:55:52.142Z,1750794952.142 [keepstation:StartingMission] Stopped 2025-06-24T19:55:52.142Z,1750794952.142 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-06-24T19:55:52.142Z,1750794952.142 [keepstation:TransitToStation] Running Loop=1 2025-06-24T19:55:52.142Z,1750794952.142 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-06-24T19:55:52.142Z,1750794952.142 [keepstation:TransitToStation:Dive] Running Loop=1 2025-06-24T19:55:52.142Z,1750794952.142 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive 2025-06-24T19:55:52.143Z,1750794952.143 [keepstation:TransitToStation:Dive:A] Running Loop=1 2025-06-24T19:55:52.143Z,1750794952.143 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::initialize keepstation:TransitToStation:Dive:A 2025-06-24T19:55:52.143Z,1750794952.143 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-06-24T19:55:52.143Z,1750794952.143 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-06-24T19:55:52.144Z,1750794952.144 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2025-06-24T19:55:52.546Z,1750794952.546 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2025-06-24T19:55:52.546Z,1750794952.546 [keepstation:TransitToStation:Dive] Running Loop=1 2025-06-24T19:55:52.546Z,1750794952.546 [keepstation:DiveCmd] Running Loop=1 2025-06-24T19:55:52.546Z,1750794952.546 [keepstation:DiveCmd](DEBUG): Aggregate::initialize keepstation:DiveCmd 2025-06-24T19:55:52.546Z,1750794952.546 [keepstation:DiveCmd:SurfaceSink] Running Loop=1 2025-06-24T19:55:52.547Z,1750794952.547 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink 2025-06-24T19:55:52.547Z,1750794952.547 [keepstation:DiveCmd:SurfaceSink:sink] Running Loop=1 2025-06-24T19:55:52.547Z,1750794952.547 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::initialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-24T19:55:52.547Z,1750794952.547 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Running Loop=1 2025-06-24T19:55:52.547Z,1750794952.547 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-24T19:55:52.547Z,1750794952.547 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Running Loop=1 2025-06-24T19:55:52.547Z,1750794952.547 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Initialize. 2025-06-24T19:55:52.547Z,1750794952.547 [keepstation:DiveCmd:SurfaceSink:sink:C] Running Loop=1 2025-06-24T19:55:52.547Z,1750794952.547 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-06-24T19:55:52.547Z,1750794952.547 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation 2025-06-24T19:55:52.548Z,1750794952.548 [keepstation:DiveCmd:TransitToStation:dive] Running Loop=1 2025-06-24T19:55:52.548Z,1750794952.548 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::initialize keepstation:DiveCmd:TransitToStation:dive 2025-06-24T19:55:52.548Z,1750794952.548 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-06-24T19:55:52.548Z,1750794952.548 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-06-24T19:55:52.548Z,1750794952.548 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-06-24T19:55:52.548Z,1750794952.548 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch](DEBUG): Initialize. 2025-06-24T19:55:52.548Z,1750794952.548 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-06-24T19:55:52.548Z,1750794952.548 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Initialize. 2025-06-24T19:55:52.548Z,1750794952.548 [keepstation:DiveCmd:StationKeep] Running Loop=1 2025-06-24T19:55:52.548Z,1750794952.548 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::initialize keepstation:DiveCmd:StationKeep 2025-06-24T19:55:52.549Z,1750794952.549 [keepstation:DiveCmd:StationKeep:A] Running Loop=1 2025-06-24T19:55:52.549Z,1750794952.549 [keepstation:DiveCmd:StationKeep:B.Pitch] Running Loop=1 2025-06-24T19:55:52.549Z,1750794952.549 [keepstation:DiveCmd:StationKeep:B.Pitch](DEBUG): Initialize. 2025-06-24T19:55:52.549Z,1750794952.549 [keepstation:DiveCmd:StationKeep:C.KeepStation] Running Loop=1 2025-06-24T19:55:52.549Z,1750794952.549 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-06-24T19:55:52.550Z,1750794952.550 [keepstation:DiveCmd:StationKeep] Stopped 2025-06-24T19:55:52.550Z,1750794952.550 [keepstation:DiveCmd:StationKeep](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:StationKeep 2025-06-24T19:55:52.550Z,1750794952.550 [keepstation:DiveCmd:StationKeep:A] Stopped 2025-06-24T19:55:52.550Z,1750794952.550 [keepstation:DiveCmd:StationKeep:B.Pitch] Stopped 2025-06-24T19:55:52.550Z,1750794952.550 [keepstation:DiveCmd:StationKeep:C.KeepStation] Stopped 2025-06-24T19:55:52.550Z,1750794952.550 [keepstation:DiveCmd:StationKeep:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-06-24T19:55:52.550Z,1750794952.550 [keepstation:DiveCmd:TransitToStation] Running Loop=1 2025-06-24T19:55:52.550Z,1750794952.550 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Running Loop=1 2025-06-24T19:55:52.551Z,1750794952.551 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Running Loop=1 2025-06-24T19:55:52.551Z,1750794952.551 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Running Loop=1 2025-06-24T19:55:52.551Z,1750794952.551 [keepstation:DiveCmd:SurfaceSink] Stopped 2025-06-24T19:55:52.551Z,1750794952.551 [keepstation:DiveCmd:SurfaceSink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink 2025-06-24T19:55:52.552Z,1750794952.552 [keepstation:DiveCmd:SurfaceSink:sink] Stopped 2025-06-24T19:55:52.552Z,1750794952.552 [keepstation:DiveCmd:SurfaceSink:sink](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:SurfaceSink:sink 2025-06-24T19:55:52.552Z,1750794952.552 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy] Stopped 2025-06-24T19:55:52.552Z,1750794952.552 [keepstation:DiveCmd:SurfaceSink:sink:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-24T19:55:52.552Z,1750794952.552 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed] Stopped 2025-06-24T19:55:52.552Z,1750794952.552 [keepstation:DiveCmd:SurfaceSink:sink:B.SetSpeed](DEBUG): Uninitialize. 2025-06-24T19:55:52.552Z,1750794952.552 [keepstation:DiveCmd:SurfaceSink:sink:C] Stopped 2025-06-24T19:56:22.541Z,1750794982.541 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T19:56:57.434Z,1750795017.434 [DataOverHttps](IMPORTANT): SBD MTMSN=20250624T195658 2025-06-24T19:57:05.353Z,1750795025.353 [DataOverHttps](INFO): Received command: stop;strobe off 2025-06-24T19:57:05.439Z,1750795025.439 [CommandExec](IMPORTANT): got command stop 2025-06-24T19:57:05.439Z,1750795025.439 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2025-06-24T19:57:05.439Z,1750795025.439 [CommandExec](IMPORTANT): got command strobe off 2025-06-24T19:57:05.440Z,1750795025.440 [CommandExec](IMPORTANT): Deactivating strobe 2025-06-24T19:57:05.763Z,1750795025.763 [MissionManager](INFO): MissionManager is completed. 2025-06-24T19:57:05.763Z,1750795025.763 [MissionManager](INFO): Uninitializing Mission keepstation 2025-06-24T19:57:05.764Z,1750795025.764 [keepstation] Stopped 2025-06-24T19:57:05.764Z,1750795025.764 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2025-06-24T19:57:05.764Z,1750795025.764 [keepstation:StandardEnvelopes] Stopped 2025-06-24T19:57:05.764Z,1750795025.764 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2025-06-24T19:57:05.764Z,1750795025.764 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-06-24T19:57:05.764Z,1750795025.764 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-06-24T19:57:05.764Z,1750795025.764 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-06-24T19:57:05.764Z,1750795025.764 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-06-24T19:57:05.764Z,1750795025.764 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-06-24T19:57:05.764Z,1750795025.764 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-06-24T19:57:05.764Z,1750795025.764 [keepstation:BackseatDriver] Stopped 2025-06-24T19:57:05.764Z,1750795025.764 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver 2025-06-24T19:57:05.764Z,1750795025.764 [keepstation:BackseatDriver:A.BackseatDriver] Stopped 2025-06-24T19:57:05.765Z,1750795025.765 [keepstation:PowerOnly] Stopped 2025-06-24T19:57:05.765Z,1750795025.765 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly 2025-06-24T19:57:05.765Z,1750795025.765 [keepstation:PowerOnly:E.Wait] Stopped 2025-06-24T19:57:05.765Z,1750795025.765 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T19:57:05.765Z,1750795025.765 [keepstation:H.Pitch] Stopped 2025-06-24T19:57:05.765Z,1750795025.765 [keepstation:DiveCmd] Stopped 2025-06-24T19:57:05.765Z,1750795025.765 [keepstation:DiveCmd](DEBUG): Aggregate::uninitialize keepstation:DiveCmd 2025-06-24T19:57:05.765Z,1750795025.765 [keepstation:DiveCmd:TransitToStation] Stopped 2025-06-24T19:57:05.765Z,1750795025.765 [keepstation:DiveCmd:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation 2025-06-24T19:57:05.766Z,1750795025.766 [keepstation:DiveCmd:TransitToStation:dive] Stopped 2025-06-24T19:57:05.766Z,1750795025.766 [keepstation:DiveCmd:TransitToStation:dive](DEBUG): Aggregate::uninitialize keepstation:DiveCmd:TransitToStation:dive 2025-06-24T19:57:05.766Z,1750795025.766 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy] Stopped 2025-06-24T19:57:05.766Z,1750795025.766 [keepstation:DiveCmd:TransitToStation:dive:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-06-24T19:57:05.766Z,1750795025.766 [keepstation:DiveCmd:TransitToStation:dive:B.Pitch] Stopped 2025-06-24T19:57:05.766Z,1750795025.766 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed] Stopped 2025-06-24T19:57:05.766Z,1750795025.766 [keepstation:DiveCmd:TransitToStation:dive:C.SetSpeed](DEBUG): Uninitialize. 2025-06-24T19:57:05.766Z,1750795025.766 [keepstation:TransitToStation] Stopped 2025-06-24T19:57:05.766Z,1750795025.766 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2025-06-24T19:57:05.766Z,1750795025.766 [keepstation:TransitToStation:Dive] Stopped 2025-06-24T19:57:05.766Z,1750795025.766 [keepstation:TransitToStation:Dive](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive 2025-06-24T19:57:05.766Z,1750795025.766 [keepstation:TransitToStation:Dive:A] Stopped 2025-06-24T19:57:05.766Z,1750795025.766 [keepstation:TransitToStation:Dive:A](DEBUG): Aggregate::uninitialize keepstation:TransitToStation:Dive:A 2025-06-24T19:57:05.767Z,1750795025.767 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2025-06-24T19:57:05.767Z,1750795025.767 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-06-24T19:57:06.172Z,1750795026.172 [MissionManager](IMPORTANT): Started mission Default 2025-06-24T19:57:06.172Z,1750795026.172 [Default] Running Loop=1 2025-06-24T19:57:06.172Z,1750795026.172 [Default](DEBUG): Aggregate::initialize Default 2025-06-24T19:57:06.172Z,1750795026.172 [Default:B.GoToSurface] Running Loop=1 2025-06-24T19:57:06.172Z,1750795026.172 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-06-24T19:57:06.173Z,1750795026.173 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-06-24T19:57:06.173Z,1750795026.173 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-06-24T19:57:06.174Z,1750795026.174 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-06-24T19:57:06.174Z,1750795026.174 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-06-24T19:57:06.174Z,1750795026.174 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-06-24T19:57:06.174Z,1750795026.174 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-06-24T19:57:06.175Z,1750795026.175 [Default:A.Wait] Running Loop=1 2025-06-24T19:57:06.175Z,1750795026.175 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-06-24T19:57:19.492Z,1750795039.492 [Default:A.Wait](INFO): Done Waiting. 2025-06-24T19:57:19.493Z,1750795039.493 [Default:A.Wait] Stopped 2025-06-24T19:57:19.493Z,1750795039.493 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T19:57:19.900Z,1750795039.900 [Default:CheckIn] Running Loop=1 2025-06-24T19:57:19.900Z,1750795039.900 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T19:57:19.900Z,1750795039.900 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T19:57:21.911Z,1750795041.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195722.00,A,3648.15179,N,12147.22098,W,0.272,200.37,240625,,,A*72 2025-06-24T19:57:21.913Z,1750795041.913 [NAL9602](INFO): GPS fix at 20250624T195722: (36.802530, -121.787016) 2025-06-24T19:57:21.923Z,1750795041.923 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T19:57:21.923Z,1750795041.923 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T19:57:28.933Z,1750795048.933 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0018.lzma 2025-06-24T19:57:39.975Z,1750795059.975 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0018.lzma.bak 2025-06-24T19:57:39.975Z,1750795059.975 [DataOverHttps](INFO): SBD MOMSN=25243303 2025-06-24T19:58:08.903Z,1750795088.903 [DataOverHttps](INFO): Sending 467 bytes from file Logs/20250624T192950/Express0019.lzma 2025-06-24T19:58:19.968Z,1750795099.968 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0019.lzma.bak 2025-06-24T19:58:19.970Z,1750795099.970 [DataOverHttps](INFO): SBD MOMSN=25243306 2025-06-24T19:58:21.380Z,1750795101.380 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T19:58:21.380Z,1750795101.380 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T19:58:21.380Z,1750795101.380 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T20:02:23.742Z,1750795343.742 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T20:02:54.444Z,1750795374.444 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T20:03:21.926Z,1750795401.926 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T20:03:21.926Z,1750795401.926 [Default:CheckIn:C.Wait] Stopped 2025-06-24T20:03:21.926Z,1750795401.926 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T20:03:21.926Z,1750795401.926 [Default:CheckIn:D] Running Loop=1 2025-06-24T20:03:22.338Z,1750795402.338 [Default:CheckIn:D] Stopped 2025-06-24T20:03:22.338Z,1750795402.338 [Default:CheckIn:E] Running Loop=1 2025-06-24T20:03:22.732Z,1750795402.732 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.269427 min 2025-06-24T20:03:22.732Z,1750795402.732 [Default:CheckIn:E] Stopped 2025-06-24T20:03:22.733Z,1750795402.733 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T20:03:22.733Z,1750795402.733 [Default:CheckIn] Stopped 2025-06-24T20:03:22.733Z,1750795402.733 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T20:03:22.735Z,1750795402.735 [Default:CheckIn](INFO): Running loop #2 2025-06-24T20:03:22.735Z,1750795402.735 [Default:CheckIn] Running Loop=2 2025-06-24T20:03:22.735Z,1750795402.735 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T20:03:22.735Z,1750795402.735 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T20:03:24.744Z,1750795404.744 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200325.00,A,3648.14487,N,12147.22702,W,0.156,205.25,240625,,,A*7C 2025-06-24T20:03:24.747Z,1750795404.747 [NAL9602](INFO): GPS fix at 20250624T200325: (36.802414, -121.787117) 2025-06-24T20:03:24.756Z,1750795404.756 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T20:03:24.756Z,1750795404.756 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T20:03:32.405Z,1750795412.405 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0021.lzma 2025-06-24T20:03:43.447Z,1750795423.447 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0021.lzma.bak 2025-06-24T20:03:43.447Z,1750795423.447 [DataOverHttps](INFO): SBD MOMSN=25243325 2025-06-24T20:03:55.464Z,1750795435.464 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T20:03:55.541Z,1750795435.541 [NAL9602](ERROR): received: +CSQ:0 OK,ÍÐ`/:8JRç7­o)ØaýW¥åthm7Ûº$µ(OLjõœ®ŠÐªNès•´‚V™•Ð.n²8[ŽNÓ,ôÕ$ŽQ‘ 2025-06-24T20:04:11.209Z,1750795451.209 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20250624T192950/Express0023.lzma 2025-06-24T20:04:22.252Z,1750795462.252 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0023.lzma.bak 2025-06-24T20:04:22.253Z,1750795462.253 [DataOverHttps](INFO): SBD MOMSN=25243328 2025-06-24T20:04:23.368Z,1750795463.368 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T20:04:23.369Z,1750795463.369 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T20:04:23.369Z,1750795463.369 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T20:08:26.955Z,1750795706.955 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T20:08:57.662Z,1750795737.662 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T20:09:23.937Z,1750795763.937 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T20:09:23.937Z,1750795763.937 [Default:CheckIn:C.Wait] Stopped 2025-06-24T20:09:23.937Z,1750795763.937 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T20:09:23.937Z,1750795763.937 [Default:CheckIn:D] Running Loop=1 2025-06-24T20:09:24.328Z,1750795764.328 [Default:CheckIn:D] Stopped 2025-06-24T20:09:24.329Z,1750795764.329 [Default:CheckIn:E] Running Loop=1 2025-06-24T20:09:24.732Z,1750795764.732 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.302604 min 2025-06-24T20:09:24.733Z,1750795764.733 [Default:CheckIn:E] Stopped 2025-06-24T20:09:24.733Z,1750795764.733 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T20:09:24.733Z,1750795764.733 [Default:CheckIn] Stopped 2025-06-24T20:09:24.733Z,1750795764.733 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T20:09:24.733Z,1750795764.733 [Default:CheckIn](INFO): Running loop #3 2025-06-24T20:09:24.733Z,1750795764.733 [Default:CheckIn] Running Loop=3 2025-06-24T20:09:24.733Z,1750795764.733 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T20:09:24.733Z,1750795764.733 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T20:09:26.750Z,1750795766.750 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200927.00,A,3648.18637,N,12147.27322,W,0.175,315.77,240625,,,A*74 2025-06-24T20:09:26.752Z,1750795766.752 [NAL9602](INFO): GPS fix at 20250624T200927: (36.803106, -121.787887) 2025-06-24T20:09:26.762Z,1750795766.762 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T20:09:26.762Z,1750795766.762 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T20:09:34.345Z,1750795774.345 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0027.lzma 2025-06-24T20:09:45.387Z,1750795785.387 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0027.lzma.bak 2025-06-24T20:09:45.387Z,1750795785.387 [DataOverHttps](INFO): SBD MOMSN=25243339 2025-06-24T20:10:11.422Z,1750795811.422 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250624T192950/Express0028.lzma 2025-06-24T20:10:22.463Z,1750795822.463 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0028.lzma.bak 2025-06-24T20:10:22.463Z,1750795822.463 [DataOverHttps](INFO): SBD MOMSN=25243342 2025-06-24T20:10:23.724Z,1750795823.724 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T20:10:23.724Z,1750795823.724 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T20:10:23.724Z,1750795823.724 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T20:14:28.943Z,1750796068.943 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T20:14:59.642Z,1750796099.642 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T20:15:24.303Z,1750796124.303 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T20:15:24.303Z,1750796124.303 [Default:CheckIn:C.Wait] Stopped 2025-06-24T20:15:24.304Z,1750796124.304 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T20:15:24.304Z,1750796124.304 [Default:CheckIn:D] Running Loop=1 2025-06-24T20:15:24.703Z,1750796124.703 [Default:CheckIn:D] Stopped 2025-06-24T20:15:24.703Z,1750796124.703 [Default:CheckIn:E] Running Loop=1 2025-06-24T20:15:25.101Z,1750796125.101 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.308852 min 2025-06-24T20:15:25.101Z,1750796125.101 [Default:CheckIn:E] Stopped 2025-06-24T20:15:25.101Z,1750796125.101 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T20:15:25.101Z,1750796125.101 [Default:CheckIn] Stopped 2025-06-24T20:15:25.102Z,1750796125.102 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T20:15:25.102Z,1750796125.102 [Default:CheckIn](INFO): Running loop #4 2025-06-24T20:15:25.102Z,1750796125.102 [Default:CheckIn] Running Loop=4 2025-06-24T20:15:25.102Z,1750796125.102 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T20:15:25.102Z,1750796125.102 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T20:15:27.114Z,1750796127.114 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201527.00,A,3648.19114,N,12147.27368,W,0.078,315.77,240625,,,A*7C 2025-06-24T20:15:27.117Z,1750796127.117 [NAL9602](INFO): GPS fix at 20250624T201527: (36.803186, -121.787895) 2025-06-24T20:15:27.127Z,1750796127.127 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T20:15:27.127Z,1750796127.127 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T20:15:34.025Z,1750796134.025 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0030.lzma 2025-06-24T20:15:45.067Z,1750796145.067 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0030.lzma.bak 2025-06-24T20:15:45.067Z,1750796145.067 [DataOverHttps](INFO): SBD MOMSN=25243352 2025-06-24T20:16:11.322Z,1750796171.322 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250624T192950/Express0031.lzma 2025-06-24T20:16:22.363Z,1750796182.363 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0031.lzma.bak 2025-06-24T20:16:22.363Z,1750796182.363 [DataOverHttps](INFO): SBD MOMSN=25243355 2025-06-24T20:16:23.686Z,1750796183.686 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T20:16:23.687Z,1750796183.687 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T20:16:23.687Z,1750796183.687 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T20:20:29.714Z,1750796429.714 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T20:21:00.414Z,1750796460.414 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T20:21:24.309Z,1750796484.309 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T20:21:24.309Z,1750796484.309 [Default:CheckIn:C.Wait] Stopped 2025-06-24T20:21:24.309Z,1750796484.309 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T20:21:24.310Z,1750796484.310 [Default:CheckIn:D] Running Loop=1 2025-06-24T20:21:24.669Z,1750796484.669 [Default:CheckIn:D] Stopped 2025-06-24T20:21:24.669Z,1750796484.669 [Default:CheckIn:E] Running Loop=1 2025-06-24T20:21:25.075Z,1750796485.075 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.308276 min 2025-06-24T20:21:25.076Z,1750796485.076 [Default:CheckIn:E] Stopped 2025-06-24T20:21:25.076Z,1750796485.076 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T20:21:25.076Z,1750796485.076 [Default:CheckIn] Stopped 2025-06-24T20:21:25.076Z,1750796485.076 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T20:21:25.076Z,1750796485.076 [Default:CheckIn](INFO): Running loop #5 2025-06-24T20:21:25.076Z,1750796485.076 [Default:CheckIn] Running Loop=5 2025-06-24T20:21:25.076Z,1750796485.076 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T20:21:25.076Z,1750796485.076 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T20:21:27.085Z,1750796487.085 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202127.00,A,3648.18435,N,12147.27291,W,0.117,315.77,240625,,,A*73 2025-06-24T20:21:27.095Z,1750796487.095 [NAL9602](INFO): GPS fix at 20250624T202127: (36.803072, -121.787882) 2025-06-24T20:21:27.104Z,1750796487.104 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T20:21:27.104Z,1750796487.104 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T20:21:34.031Z,1750796494.031 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0033.lzma 2025-06-24T20:21:45.091Z,1750796505.091 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0033.lzma.bak 2025-06-24T20:21:45.091Z,1750796505.091 [DataOverHttps](INFO): SBD MOMSN=25243365 2025-06-24T20:21:57.786Z,1750796517.786 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T20:21:57.865Z,1750796517.865 [NAL9602](ERROR): received: +CSQ:0 OK,ÍÐ`/:8JRç7­o)ØaýW¥åthm7Ûº$µ(OLjõœ®ŠÐªNès•´‚V™•Ð.n²8[ŽNÓ,ôÕ$ŽQ‘ 2025-06-24T20:22:11.229Z,1750796531.229 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250624T192950/Express0034.lzma 2025-06-24T20:22:22.272Z,1750796542.272 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0034.lzma.bak 2025-06-24T20:22:22.273Z,1750796542.273 [DataOverHttps](INFO): SBD MOMSN=25243368 2025-06-24T20:22:23.650Z,1750796543.650 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T20:22:23.650Z,1750796543.650 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T20:22:23.650Z,1750796543.650 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T20:26:29.680Z,1750796789.680 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T20:27:00.391Z,1750796820.391 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T20:27:24.239Z,1750796844.239 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T20:27:24.239Z,1750796844.239 [Default:CheckIn:C.Wait] Stopped 2025-06-24T20:27:24.239Z,1750796844.239 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T20:27:24.240Z,1750796844.240 [Default:CheckIn:D] Running Loop=1 2025-06-24T20:27:24.635Z,1750796844.635 [Default:CheckIn:D] Stopped 2025-06-24T20:27:24.635Z,1750796844.635 [Default:CheckIn:E] Running Loop=1 2025-06-24T20:27:25.037Z,1750796845.037 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.307721 min 2025-06-24T20:27:25.037Z,1750796845.037 [Default:CheckIn:E] Stopped 2025-06-24T20:27:25.038Z,1750796845.038 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T20:27:25.038Z,1750796845.038 [Default:CheckIn] Stopped 2025-06-24T20:27:25.038Z,1750796845.038 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T20:27:25.038Z,1750796845.038 [Default:CheckIn](INFO): Running loop #6 2025-06-24T20:27:25.038Z,1750796845.038 [Default:CheckIn] Running Loop=6 2025-06-24T20:27:25.038Z,1750796845.038 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T20:27:25.038Z,1750796845.038 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T20:27:27.051Z,1750796847.051 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202727.00,A,3648.19457,N,12147.26927,W,0.136,0.00,240625,,,A*73 2025-06-24T20:27:27.053Z,1750796847.053 [NAL9602](INFO): GPS fix at 20250624T202727: (36.803243, -121.787821) 2025-06-24T20:27:27.063Z,1750796847.063 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T20:27:27.063Z,1750796847.063 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T20:27:34.185Z,1750796854.185 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0036.lzma 2025-06-24T20:27:45.231Z,1750796865.231 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0036.lzma.bak 2025-06-24T20:27:45.231Z,1750796865.231 [DataOverHttps](INFO): SBD MOMSN=25243378 2025-06-24T20:27:57.792Z,1750796877.792 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T20:27:57.865Z,1750796877.865 [NAL9602](ERROR): received: +CSQ:0 OK,ÍÐ`/:8JRç7­o)ØaýW¥åthm7Ûº$µ(OLjõœ®ŠÐªNès•´‚V™•Ð.n²8[ŽNÓ,ôÕ$ŽQ‘ 2025-06-24T20:28:11.205Z,1750796891.205 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250624T192950/Express0037.lzma 2025-06-24T20:28:22.247Z,1750796902.247 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0037.lzma.bak 2025-06-24T20:28:22.247Z,1750796902.247 [DataOverHttps](INFO): SBD MOMSN=25243385 2025-06-24T20:28:23.633Z,1750796903.633 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T20:28:23.633Z,1750796903.633 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T20:28:23.634Z,1750796903.634 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T20:32:29.649Z,1750797149.649 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T20:33:00.351Z,1750797180.351 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T20:33:24.195Z,1750797204.195 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T20:33:24.196Z,1750797204.196 [Default:CheckIn:C.Wait] Stopped 2025-06-24T20:33:24.196Z,1750797204.196 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T20:33:24.196Z,1750797204.196 [Default:CheckIn:D] Running Loop=1 2025-06-24T20:33:24.601Z,1750797204.601 [Default:CheckIn:D] Stopped 2025-06-24T20:33:24.601Z,1750797204.601 [Default:CheckIn:E] Running Loop=1 2025-06-24T20:33:25.016Z,1750797205.016 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.307149 min 2025-06-24T20:33:25.017Z,1750797205.017 [Default:CheckIn:E] Stopped 2025-06-24T20:33:25.017Z,1750797205.017 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T20:33:25.017Z,1750797205.017 [Default:CheckIn] Stopped 2025-06-24T20:33:25.017Z,1750797205.017 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T20:33:25.017Z,1750797205.017 [Default:CheckIn](INFO): Running loop #7 2025-06-24T20:33:25.017Z,1750797205.017 [Default:CheckIn] Running Loop=7 2025-06-24T20:33:25.017Z,1750797205.017 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T20:33:25.017Z,1750797205.017 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T20:33:27.020Z,1750797207.020 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203327.00,A,3648.18708,N,12147.27356,W,0.214,0.00,240625,,,A*70 2025-06-24T20:33:27.022Z,1750797207.022 [NAL9602](INFO): GPS fix at 20250624T203327: (36.803118, -121.787893) 2025-06-24T20:33:27.032Z,1750797207.032 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T20:33:27.032Z,1750797207.032 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T20:33:34.233Z,1750797214.233 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0039.lzma 2025-06-24T20:33:45.275Z,1750797225.275 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0039.lzma.bak 2025-06-24T20:33:45.275Z,1750797225.275 [DataOverHttps](INFO): SBD MOMSN=25243409 2025-06-24T20:33:57.726Z,1750797237.726 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T20:33:57.801Z,1750797237.801 [NAL9602](ERROR): received: +CSQ:0 OK,ÍÐ`/:8JRç7­o)ØaýW¥åthm7Ûº$µ(OLjõœ®ŠÐªNès•´‚V™•Ð.n²8[ŽNÓ,ôÕ$ŽQ‘ 2025-06-24T20:34:11.161Z,1750797251.161 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20250624T192950/Express0040.lzma 2025-06-24T20:34:22.220Z,1750797262.220 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0040.lzma.bak 2025-06-24T20:34:22.220Z,1750797262.220 [DataOverHttps](INFO): SBD MOMSN=25243416 2025-06-24T20:34:23.596Z,1750797263.596 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T20:34:23.596Z,1750797263.596 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T20:34:23.596Z,1750797263.596 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T20:38:28.811Z,1750797508.811 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T20:38:59.515Z,1750797539.515 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T20:39:24.167Z,1750797564.167 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T20:39:24.167Z,1750797564.167 [Default:CheckIn:C.Wait] Stopped 2025-06-24T20:39:24.167Z,1750797564.167 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T20:39:24.167Z,1750797564.167 [Default:CheckIn:D] Running Loop=1 2025-06-24T20:39:24.579Z,1750797564.579 [Default:CheckIn:D] Stopped 2025-06-24T20:39:24.579Z,1750797564.579 [Default:CheckIn:E] Running Loop=1 2025-06-24T20:39:24.976Z,1750797564.976 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.306775 min 2025-06-24T20:39:24.976Z,1750797564.976 [Default:CheckIn:E] Stopped 2025-06-24T20:39:24.976Z,1750797564.976 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T20:39:24.976Z,1750797564.976 [Default:CheckIn] Stopped 2025-06-24T20:39:24.976Z,1750797564.976 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T20:39:24.976Z,1750797564.976 [Default:CheckIn](INFO): Running loop #8 2025-06-24T20:39:24.976Z,1750797564.976 [Default:CheckIn] Running Loop=8 2025-06-24T20:39:24.977Z,1750797564.977 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T20:39:24.977Z,1750797564.977 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T20:39:26.981Z,1750797566.981 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203927.00,A,3648.16966,N,12147.27741,W,0.078,0.00,240625,,,A*78 2025-06-24T20:39:26.983Z,1750797566.983 [NAL9602](INFO): GPS fix at 20250624T203927: (36.802828, -121.787957) 2025-06-24T20:39:26.994Z,1750797566.994 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T20:39:26.994Z,1750797566.994 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T20:39:33.905Z,1750797573.905 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0042.lzma 2025-06-24T20:39:44.955Z,1750797584.955 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0042.lzma.bak 2025-06-24T20:39:44.955Z,1750797584.955 [DataOverHttps](INFO): SBD MOMSN=25243480 2025-06-24T20:39:57.683Z,1750797597.683 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T20:39:57.757Z,1750797597.757 [NAL9602](ERROR): received: +CSQ:0 OK,ÍÐ`/:8JRç7­o)ØaýW¥åthm7Ûº$µ(OLjõœ®ŠÐªNès•´‚V™•Ð.n²8[ŽNÓ,ôÕ$ŽQ‘ 2025-06-24T20:40:11.001Z,1750797611.001 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20250624T192950/Express0043.lzma 2025-06-24T20:40:22.043Z,1750797622.043 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0043.lzma.bak 2025-06-24T20:40:22.043Z,1750797622.043 [DataOverHttps](INFO): SBD MOMSN=25243488 2025-06-24T20:40:23.164Z,1750797623.164 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T20:40:23.165Z,1750797623.165 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T20:40:23.165Z,1750797623.165 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T20:41:10.812Z,1750797670.812 [NAL9602](INFO): SBD MO Status=2, MOMSN=19715, MT Status=2, MTMSN=0 2025-06-24T20:41:10.812Z,1750797670.812 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T20:41:38.288Z,1750797698.288 [NAL9602](INFO): SBD MO Status=2, MOMSN=19715, MT Status=2, MTMSN=0 2025-06-24T20:41:38.288Z,1750797698.288 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T20:42:04.952Z,1750797724.952 [NAL9602](INFO): SBD MO Status=0, MOMSN=19715, MT Status=0, MTMSN=0 2025-06-24T20:42:04.952Z,1750797724.952 [NAL9602](INFO): No messages in MT queue 2025-06-24T20:42:35.652Z,1750797755.652 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T20:45:23.732Z,1750797923.732 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T20:45:23.732Z,1750797923.732 [Default:CheckIn:C.Wait] Stopped 2025-06-24T20:45:23.732Z,1750797923.732 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T20:45:23.732Z,1750797923.732 [Default:CheckIn:D] Running Loop=1 2025-06-24T20:45:24.148Z,1750797924.148 [Default:CheckIn:D] Stopped 2025-06-24T20:45:24.148Z,1750797924.148 [Default:CheckIn:E] Running Loop=1 2025-06-24T20:45:24.533Z,1750797924.533 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.299593 min 2025-06-24T20:45:24.534Z,1750797924.534 [Default:CheckIn:E] Stopped 2025-06-24T20:45:24.534Z,1750797924.534 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T20:45:24.534Z,1750797924.534 [Default:CheckIn] Stopped 2025-06-24T20:45:24.534Z,1750797924.534 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T20:45:24.534Z,1750797924.534 [Default:CheckIn](INFO): Running loop #9 2025-06-24T20:45:24.534Z,1750797924.534 [Default:CheckIn] Running Loop=9 2025-06-24T20:45:24.534Z,1750797924.534 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T20:45:24.534Z,1750797924.534 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T20:45:26.547Z,1750797926.547 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204527.00,A,3648.19543,N,12147.26037,W,0.136,0.00,240625,,,A*7B 2025-06-24T20:45:26.550Z,1750797926.550 [NAL9602](INFO): GPS fix at 20250624T204527: (36.803257, -121.787673) 2025-06-24T20:45:26.559Z,1750797926.559 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T20:45:26.559Z,1750797926.559 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T20:45:34.325Z,1750797934.325 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0045.lzma 2025-06-24T20:45:45.375Z,1750797945.375 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0045.lzma.bak 2025-06-24T20:45:45.375Z,1750797945.375 [DataOverHttps](INFO): SBD MOMSN=25243517 2025-06-24T20:46:11.505Z,1750797971.505 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250624T192950/Express0046.lzma 2025-06-24T20:46:22.560Z,1750797982.560 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0046.lzma.bak 2025-06-24T20:46:22.560Z,1750797982.560 [DataOverHttps](INFO): SBD MOMSN=25243520 2025-06-24T20:46:23.940Z,1750797983.940 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T20:46:23.940Z,1750797983.940 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T20:46:23.940Z,1750797983.940 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T20:50:34.396Z,1750798234.396 [NAL9602](INFO): SBD MO Status=2, MOMSN=19716, MT Status=2, MTMSN=0 2025-06-24T20:50:34.397Z,1750798234.397 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T20:50:34.803Z,1750798234.803 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T20:51:05.506Z,1750798265.506 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T20:51:06.316Z,1750798266.316 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2025-06-24T20:51:06.319Z,1750798266.319 [BPC1](INFO): Received data from all battery sticks. 2025-06-24T20:51:24.509Z,1750798284.509 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T20:51:24.510Z,1750798284.510 [Default:CheckIn:C.Wait] Stopped 2025-06-24T20:51:24.510Z,1750798284.510 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T20:51:24.511Z,1750798284.511 [Default:CheckIn:D] Running Loop=1 2025-06-24T20:51:24.907Z,1750798284.907 [Default:CheckIn:D] Stopped 2025-06-24T20:51:24.907Z,1750798284.907 [Default:CheckIn:E] Running Loop=1 2025-06-24T20:51:25.309Z,1750798285.309 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.312252 min 2025-06-24T20:51:25.309Z,1750798285.309 [Default:CheckIn:E] Stopped 2025-06-24T20:51:25.309Z,1750798285.309 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T20:51:25.309Z,1750798285.309 [Default:CheckIn] Stopped 2025-06-24T20:51:25.310Z,1750798285.310 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T20:51:25.310Z,1750798285.310 [Default:CheckIn](INFO): Running loop #10 2025-06-24T20:51:25.310Z,1750798285.310 [Default:CheckIn] Running Loop=10 2025-06-24T20:51:25.310Z,1750798285.310 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T20:51:25.310Z,1750798285.310 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T20:51:27.325Z,1750798287.325 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205128.00,A,3648.17288,N,12147.27543,W,0.311,0.00,240625,,,A*7F 2025-06-24T20:51:27.327Z,1750798287.327 [NAL9602](INFO): GPS fix at 20250624T205128: (36.802881, -121.787924) 2025-06-24T20:51:27.336Z,1750798287.336 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T20:51:27.337Z,1750798287.337 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T20:51:34.333Z,1750798294.333 [DataOverHttps](INFO): Sending 83 bytes from file Logs/20250624T192950/Courier0048.lzma 2025-06-24T20:51:45.375Z,1750798305.375 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0048.lzma.bak 2025-06-24T20:51:45.375Z,1750798305.375 [DataOverHttps](INFO): SBD MOMSN=25243529 2025-06-24T20:51:54.794Z,1750798314.794 [NAL9602](INFO): SBD MO Status=2, MOMSN=19716, MT Status=2, MTMSN=0 2025-06-24T20:51:54.794Z,1750798314.794 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T20:52:11.377Z,1750798331.377 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20250624T192950/Express0049.lzma 2025-06-24T20:52:22.419Z,1750798342.419 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0049.lzma.bak 2025-06-24T20:52:22.419Z,1750798342.419 [DataOverHttps](INFO): SBD MOMSN=25243532 2025-06-24T20:52:23.490Z,1750798343.490 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T20:52:23.490Z,1750798343.490 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T20:52:23.490Z,1750798343.490 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T20:53:10.748Z,1750798390.748 [NAL9602](INFO): SBD MO Status=2, MOMSN=19716, MT Status=2, MTMSN=0 2025-06-24T20:53:10.748Z,1750798390.748 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T20:56:29.919Z,1750798589.919 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T20:57:00.621Z,1750798620.621 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T20:57:24.060Z,1750798644.060 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T20:57:24.060Z,1750798644.060 [Default:CheckIn:C.Wait] Stopped 2025-06-24T20:57:24.060Z,1750798644.060 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T20:57:24.060Z,1750798644.060 [Default:CheckIn:D] Running Loop=1 2025-06-24T20:57:24.468Z,1750798644.468 [Default:CheckIn:D] Stopped 2025-06-24T20:57:24.468Z,1750798644.468 [Default:CheckIn:E] Running Loop=1 2025-06-24T20:57:24.876Z,1750798644.876 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.304924 min 2025-06-24T20:57:24.876Z,1750798644.876 [Default:CheckIn:E] Stopped 2025-06-24T20:57:24.876Z,1750798644.876 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T20:57:24.876Z,1750798644.876 [Default:CheckIn] Stopped 2025-06-24T20:57:24.876Z,1750798644.876 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T20:57:24.877Z,1750798644.877 [Default:CheckIn](INFO): Running loop #11 2025-06-24T20:57:24.877Z,1750798644.877 [Default:CheckIn] Running Loop=11 2025-06-24T20:57:24.877Z,1750798644.877 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T20:57:24.877Z,1750798644.877 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T20:57:26.880Z,1750798646.880 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205727.00,A,3648.17303,N,12147.27526,W,0.272,0.00,240625,,,A*73 2025-06-24T20:57:26.890Z,1750798646.890 [NAL9602](INFO): GPS fix at 20250624T205727: (36.802884, -121.787921) 2025-06-24T20:57:26.900Z,1750798646.900 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T20:57:26.900Z,1750798646.900 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T20:57:34.169Z,1750798654.169 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0051.lzma 2025-06-24T20:57:45.211Z,1750798665.211 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0051.lzma.bak 2025-06-24T20:57:45.211Z,1750798665.211 [DataOverHttps](INFO): SBD MOMSN=25243542 2025-06-24T20:57:57.592Z,1750798677.592 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T20:57:57.669Z,1750798677.669 [NAL9602](ERROR): received: +CSQ:0 OK716, 2, 0, 0, 0 OK 2025-06-24T20:58:11.265Z,1750798691.265 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250624T192950/Express0052.lzma 2025-06-24T20:58:22.307Z,1750798702.307 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0052.lzma.bak 2025-06-24T20:58:22.307Z,1750798702.307 [DataOverHttps](INFO): SBD MOMSN=25243545 2025-06-24T20:58:23.456Z,1750798703.456 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T20:58:23.456Z,1750798703.456 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T20:58:23.456Z,1750798703.456 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T20:58:48.892Z,1750798728.892 [NAL9602](INFO): SBD MO Status=0, MOMSN=19716, MT Status=0, MTMSN=0 2025-06-24T20:58:48.892Z,1750798728.892 [NAL9602](INFO): No messages in MT queue 2025-06-24T20:59:19.606Z,1750798759.606 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T21:03:24.030Z,1750799004.030 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T21:03:24.030Z,1750799004.030 [Default:CheckIn:C.Wait] Stopped 2025-06-24T21:03:24.030Z,1750799004.030 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T21:03:24.030Z,1750799004.030 [Default:CheckIn:D] Running Loop=1 2025-06-24T21:03:24.442Z,1750799004.442 [Default:CheckIn:D] Stopped 2025-06-24T21:03:24.442Z,1750799004.442 [Default:CheckIn:E] Running Loop=1 2025-06-24T21:03:24.856Z,1750799004.856 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.304488 min 2025-06-24T21:03:24.857Z,1750799004.857 [Default:CheckIn:E] Stopped 2025-06-24T21:03:24.857Z,1750799004.857 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T21:03:24.857Z,1750799004.857 [Default:CheckIn] Stopped 2025-06-24T21:03:24.857Z,1750799004.857 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T21:03:24.857Z,1750799004.857 [Default:CheckIn](INFO): Running loop #12 2025-06-24T21:03:24.857Z,1750799004.857 [Default:CheckIn] Running Loop=12 2025-06-24T21:03:24.857Z,1750799004.857 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T21:03:24.858Z,1750799004.858 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T21:03:26.849Z,1750799006.849 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210327.00,A,3648.17164,N,12147.27170,W,0.039,0.00,240625,,,A*7A 2025-06-24T21:03:26.851Z,1750799006.851 [NAL9602](INFO): GPS fix at 20250624T210327: (36.802861, -121.787862) 2025-06-24T21:03:26.861Z,1750799006.861 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T21:03:26.861Z,1750799006.861 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T21:03:34.641Z,1750799014.641 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250624T192950/Courier0054.lzma 2025-06-24T21:03:45.687Z,1750799025.687 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0054.lzma.bak 2025-06-24T21:03:45.687Z,1750799025.687 [DataOverHttps](INFO): SBD MOMSN=25243556 2025-06-24T21:04:11.610Z,1750799051.610 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250624T192950/Express0055.lzma 2025-06-24T21:04:22.659Z,1750799062.659 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0055.lzma.bak 2025-06-24T21:04:22.659Z,1750799062.659 [DataOverHttps](INFO): SBD MOMSN=25243559 2025-06-24T21:04:23.845Z,1750799063.845 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T21:04:23.845Z,1750799063.845 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T21:04:23.845Z,1750799063.845 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T21:07:21.200Z,1750799241.200 [NAL9602](INFO): SBD MO Status=2, MOMSN=19717, MT Status=2, MTMSN=0 2025-06-24T21:07:21.200Z,1750799241.200 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T21:07:30.482Z,1750799250.482 [NAL9602](INFO): SBD MO Status=0, MOMSN=19717, MT Status=0, MTMSN=0 2025-06-24T21:07:30.482Z,1750799250.482 [NAL9602](INFO): No messages in MT queue 2025-06-24T21:08:01.186Z,1750799281.186 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T21:09:24.415Z,1750799364.415 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T21:09:24.416Z,1750799364.416 [Default:CheckIn:C.Wait] Stopped 2025-06-24T21:09:24.416Z,1750799364.416 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T21:09:24.417Z,1750799364.417 [Default:CheckIn:D] Running Loop=1 2025-06-24T21:09:24.829Z,1750799364.829 [Default:CheckIn:D] Stopped 2025-06-24T21:09:24.829Z,1750799364.829 [Default:CheckIn:E] Running Loop=1 2025-06-24T21:09:25.231Z,1750799365.231 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.310937 min 2025-06-24T21:09:25.231Z,1750799365.231 [Default:CheckIn:E] Stopped 2025-06-24T21:09:25.231Z,1750799365.231 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T21:09:25.231Z,1750799365.231 [Default:CheckIn] Stopped 2025-06-24T21:09:25.232Z,1750799365.232 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T21:09:25.232Z,1750799365.232 [Default:CheckIn](INFO): Running loop #13 2025-06-24T21:09:25.232Z,1750799365.232 [Default:CheckIn] Running Loop=13 2025-06-24T21:09:25.232Z,1750799365.232 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T21:09:25.232Z,1750799365.232 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T21:09:27.240Z,1750799367.240 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210928.00,A,3648.18214,N,12147.27232,W,0.175,0.00,240625,,,A*78 2025-06-24T21:09:27.243Z,1750799367.243 [NAL9602](INFO): GPS fix at 20250624T210928: (36.803036, -121.787872) 2025-06-24T21:09:27.281Z,1750799367.281 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T21:09:27.281Z,1750799367.281 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T21:09:34.761Z,1750799374.761 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0057.lzma 2025-06-24T21:09:45.811Z,1750799385.811 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0057.lzma.bak 2025-06-24T21:09:45.811Z,1750799385.811 [DataOverHttps](INFO): SBD MOMSN=25243569 2025-06-24T21:09:57.952Z,1750799397.952 [NAL9602](INFO): SBD MO Status=2, MOMSN=19718, MT Status=2, MTMSN=0 2025-06-24T21:09:57.952Z,1750799397.952 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T21:10:11.697Z,1750799411.697 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250624T192950/Express0058.lzma 2025-06-24T21:10:22.739Z,1750799422.739 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0058.lzma.bak 2025-06-24T21:10:22.739Z,1750799422.739 [DataOverHttps](INFO): SBD MOMSN=25243572 2025-06-24T21:10:23.816Z,1750799423.816 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T21:10:23.817Z,1750799423.817 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T21:10:23.817Z,1750799423.817 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T21:10:33.900Z,1750799433.900 [NAL9602](INFO): SBD MO Status=2, MOMSN=19718, MT Status=2, MTMSN=0 2025-06-24T21:10:33.900Z,1750799433.900 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T21:11:08.652Z,1750799468.652 [NAL9602](INFO): SBD MO Status=2, MOMSN=19718, MT Status=2, MTMSN=0 2025-06-24T21:11:08.652Z,1750799468.652 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T21:14:29.855Z,1750799669.855 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T21:15:00.564Z,1750799700.564 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T21:15:24.399Z,1750799724.399 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T21:15:24.399Z,1750799724.399 [Default:CheckIn:C.Wait] Stopped 2025-06-24T21:15:24.400Z,1750799724.400 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T21:15:24.400Z,1750799724.400 [Default:CheckIn:D] Running Loop=1 2025-06-24T21:15:24.807Z,1750799724.807 [Default:CheckIn:D] Stopped 2025-06-24T21:15:24.807Z,1750799724.807 [Default:CheckIn:E] Running Loop=1 2025-06-24T21:15:25.215Z,1750799725.215 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.310588 min 2025-06-24T21:15:25.215Z,1750799725.215 [Default:CheckIn:E] Stopped 2025-06-24T21:15:25.215Z,1750799725.215 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T21:15:25.215Z,1750799725.215 [Default:CheckIn] Stopped 2025-06-24T21:15:25.215Z,1750799725.215 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T21:15:25.215Z,1750799725.215 [Default:CheckIn](INFO): Running loop #14 2025-06-24T21:15:25.215Z,1750799725.215 [Default:CheckIn] Running Loop=14 2025-06-24T21:15:25.215Z,1750799725.215 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T21:15:25.215Z,1750799725.215 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T21:15:27.216Z,1750799727.216 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211528.00,A,3648.17478,N,12147.27803,W,0.136,0.00,240625,,,A*79 2025-06-24T21:15:27.219Z,1750799727.219 [NAL9602](INFO): GPS fix at 20250624T211528: (36.802913, -121.787967) 2025-06-24T21:15:27.229Z,1750799727.229 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T21:15:27.229Z,1750799727.229 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T21:15:34.145Z,1750799734.145 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0060.lzma 2025-06-24T21:15:45.191Z,1750799745.191 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0060.lzma.bak 2025-06-24T21:15:45.191Z,1750799745.191 [DataOverHttps](INFO): SBD MOMSN=25243581 2025-06-24T21:15:57.921Z,1750799757.921 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T21:15:57.997Z,1750799757.997 [NAL9602](ERROR): received: +CSQ:0 OK718, 2, 0, 0, 0 OK 2025-06-24T21:16:11.162Z,1750799771.162 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250624T192950/Express0061.lzma 2025-06-24T21:16:22.203Z,1750799782.203 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0061.lzma.bak 2025-06-24T21:16:22.203Z,1750799782.203 [DataOverHttps](INFO): SBD MOMSN=25243590 2025-06-24T21:16:23.386Z,1750799783.386 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T21:16:23.387Z,1750799783.387 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T21:16:23.387Z,1750799783.387 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T21:17:46.224Z,1750799866.224 [NAL9602](INFO): SBD MO Status=2, MOMSN=19718, MT Status=2, MTMSN=0 2025-06-24T21:17:46.224Z,1750799866.224 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T21:18:27.028Z,1750799907.028 [NAL9602](INFO): SBD MO Status=0, MOMSN=19718, MT Status=0, MTMSN=0 2025-06-24T21:18:27.028Z,1750799907.028 [NAL9602](INFO): No messages in MT queue 2025-06-24T21:18:57.729Z,1750799937.729 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T21:21:24.005Z,1750800084.005 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T21:21:24.005Z,1750800084.005 [Default:CheckIn:C.Wait] Stopped 2025-06-24T21:21:24.005Z,1750800084.005 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T21:21:24.005Z,1750800084.005 [Default:CheckIn:D] Running Loop=1 2025-06-24T21:21:24.395Z,1750800084.395 [Default:CheckIn:D] Stopped 2025-06-24T21:21:24.396Z,1750800084.396 [Default:CheckIn:E] Running Loop=1 2025-06-24T21:21:24.803Z,1750800084.803 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.303719 min 2025-06-24T21:21:24.803Z,1750800084.803 [Default:CheckIn:E] Stopped 2025-06-24T21:21:24.804Z,1750800084.804 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T21:21:24.804Z,1750800084.804 [Default:CheckIn] Stopped 2025-06-24T21:21:24.804Z,1750800084.804 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T21:21:24.804Z,1750800084.804 [Default:CheckIn](INFO): Running loop #15 2025-06-24T21:21:24.804Z,1750800084.804 [Default:CheckIn] Running Loop=15 2025-06-24T21:21:24.804Z,1750800084.804 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T21:21:24.804Z,1750800084.804 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T21:21:26.810Z,1750800086.810 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212127.00,A,3648.17921,N,12147.27411,W,0.097,22.47,240625,,,A*46 2025-06-24T21:21:26.812Z,1750800086.812 [NAL9602](INFO): GPS fix at 20250624T212127: (36.802987, -121.787902) 2025-06-24T21:21:26.822Z,1750800086.822 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T21:21:26.822Z,1750800086.822 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T21:21:33.653Z,1750800093.653 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0063.lzma 2025-06-24T21:21:44.695Z,1750800104.695 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0063.lzma.bak 2025-06-24T21:21:44.695Z,1750800104.695 [DataOverHttps](INFO): SBD MOMSN=25243601 2025-06-24T21:22:10.817Z,1750800130.817 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250624T192950/Express0064.lzma 2025-06-24T21:22:21.859Z,1750800141.859 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0064.lzma.bak 2025-06-24T21:22:21.859Z,1750800141.859 [DataOverHttps](INFO): SBD MOMSN=25243604 2025-06-24T21:22:22.989Z,1750800142.989 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T21:22:22.989Z,1750800142.989 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T21:22:22.989Z,1750800142.989 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T21:25:50.236Z,1750800350.236 [NAL9602](INFO): SBD MO Status=2, MOMSN=19719, MT Status=2, MTMSN=0 2025-06-24T21:25:50.236Z,1750800350.236 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T21:26:09.635Z,1750800369.635 [NAL9602](INFO): SBD MO Status=0, MOMSN=19719, MT Status=0, MTMSN=0 2025-06-24T21:26:09.635Z,1750800369.635 [NAL9602](INFO): No messages in MT queue 2025-06-24T21:26:40.360Z,1750800400.360 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T21:27:23.600Z,1750800443.600 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T21:27:23.601Z,1750800443.601 [Default:CheckIn:C.Wait] Stopped 2025-06-24T21:27:23.601Z,1750800443.601 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T21:27:23.601Z,1750800443.601 [Default:CheckIn:D] Running Loop=1 2025-06-24T21:27:24.001Z,1750800444.001 [Default:CheckIn:D] Stopped 2025-06-24T21:27:24.001Z,1750800444.001 [Default:CheckIn:E] Running Loop=1 2025-06-24T21:27:24.412Z,1750800444.412 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.297135 min 2025-06-24T21:27:24.412Z,1750800444.412 [Default:CheckIn:E] Stopped 2025-06-24T21:27:24.412Z,1750800444.412 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T21:27:24.412Z,1750800444.412 [Default:CheckIn] Stopped 2025-06-24T21:27:24.412Z,1750800444.412 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T21:27:24.412Z,1750800444.412 [Default:CheckIn](INFO): Running loop #16 2025-06-24T21:27:24.412Z,1750800444.412 [Default:CheckIn] Running Loop=16 2025-06-24T21:27:24.412Z,1750800444.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T21:27:24.412Z,1750800444.412 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T21:27:26.416Z,1750800446.416 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212727.00,A,3648.17216,N,12147.27911,W,0.058,22.47,240625,,,A*41 2025-06-24T21:27:26.427Z,1750800446.427 [NAL9602](INFO): GPS fix at 20250624T212727: (36.802869, -121.787985) 2025-06-24T21:27:26.438Z,1750800446.438 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T21:27:26.439Z,1750800446.439 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T21:27:33.293Z,1750800453.293 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0066.lzma 2025-06-24T21:27:44.343Z,1750800464.343 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0066.lzma.bak 2025-06-24T21:27:44.343Z,1750800464.343 [DataOverHttps](INFO): SBD MOMSN=25243614 2025-06-24T21:27:57.528Z,1750800477.528 [NAL9602](INFO): SBD MO Status=0, MOMSN=19720, MT Status=0, MTMSN=0 2025-06-24T21:27:57.528Z,1750800477.528 [NAL9602](INFO): No messages in MT queue 2025-06-24T21:28:10.389Z,1750800490.389 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250624T192950/Express0067.lzma 2025-06-24T21:28:21.432Z,1750800501.432 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0067.lzma.bak 2025-06-24T21:28:21.432Z,1750800501.432 [DataOverHttps](INFO): SBD MOMSN=25243618 2025-06-24T21:28:22.593Z,1750800502.593 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T21:28:22.594Z,1750800502.594 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T21:28:22.594Z,1750800502.594 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T21:28:28.236Z,1750800508.236 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T21:33:23.178Z,1750800803.178 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T21:33:23.178Z,1750800803.178 [Default:CheckIn:C.Wait] Stopped 2025-06-24T21:33:23.178Z,1750800803.178 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T21:33:23.179Z,1750800803.179 [Default:CheckIn:D] Running Loop=1 2025-06-24T21:33:23.579Z,1750800803.579 [Default:CheckIn:D] Stopped 2025-06-24T21:33:23.579Z,1750800803.579 [Default:CheckIn:E] Running Loop=1 2025-06-24T21:33:23.975Z,1750800803.975 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.290112 min 2025-06-24T21:33:23.975Z,1750800803.975 [Default:CheckIn:E] Stopped 2025-06-24T21:33:23.975Z,1750800803.975 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T21:33:23.975Z,1750800803.975 [Default:CheckIn] Stopped 2025-06-24T21:33:23.975Z,1750800803.975 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T21:33:23.976Z,1750800803.976 [Default:CheckIn](INFO): Running loop #17 2025-06-24T21:33:23.976Z,1750800803.976 [Default:CheckIn] Running Loop=17 2025-06-24T21:33:23.976Z,1750800803.976 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T21:33:23.976Z,1750800803.976 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T21:33:25.987Z,1750800805.987 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213326.00,A,3648.17390,N,12147.27987,W,0.117,22.47,240625,,,A*4F 2025-06-24T21:33:25.989Z,1750800805.989 [NAL9602](INFO): GPS fix at 20250624T213326: (36.802898, -121.787998) 2025-06-24T21:33:25.999Z,1750800805.999 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T21:33:25.999Z,1750800805.999 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T21:33:33.633Z,1750800813.633 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0069.lzma 2025-06-24T21:33:44.680Z,1750800824.680 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0069.lzma.bak 2025-06-24T21:33:44.680Z,1750800824.680 [DataOverHttps](INFO): SBD MOMSN=25243628 2025-06-24T21:34:11.237Z,1750800851.237 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250624T192950/Express0070.lzma 2025-06-24T21:34:22.285Z,1750800862.285 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0070.lzma.bak 2025-06-24T21:34:22.285Z,1750800862.285 [DataOverHttps](INFO): SBD MOMSN=25243631 2025-06-24T21:34:23.367Z,1750800863.367 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T21:34:23.367Z,1750800863.367 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T21:34:23.367Z,1750800863.367 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T21:35:21.525Z,1750800921.525 [NAL9602](INFO): SBD MO Status=0, MOMSN=19721, MT Status=0, MTMSN=0 2025-06-24T21:35:21.526Z,1750800921.526 [NAL9602](INFO): No messages in MT queue 2025-06-24T21:35:52.231Z,1750800952.231 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T21:39:23.944Z,1750801163.944 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T21:39:23.944Z,1750801163.944 [Default:CheckIn:C.Wait] Stopped 2025-06-24T21:39:23.944Z,1750801163.944 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T21:39:23.944Z,1750801163.944 [Default:CheckIn:D] Running Loop=1 2025-06-24T21:39:24.339Z,1750801164.339 [Default:CheckIn:D] Stopped 2025-06-24T21:39:24.339Z,1750801164.339 [Default:CheckIn:E] Running Loop=1 2025-06-24T21:39:24.741Z,1750801164.741 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.302783 min 2025-06-24T21:39:24.741Z,1750801164.741 [Default:CheckIn:E] Stopped 2025-06-24T21:39:24.741Z,1750801164.741 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T21:39:24.741Z,1750801164.741 [Default:CheckIn] Stopped 2025-06-24T21:39:24.742Z,1750801164.742 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T21:39:24.742Z,1750801164.742 [Default:CheckIn](INFO): Running loop #18 2025-06-24T21:39:24.742Z,1750801164.742 [Default:CheckIn] Running Loop=18 2025-06-24T21:39:24.742Z,1750801164.742 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T21:39:24.742Z,1750801164.742 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T21:39:26.760Z,1750801166.760 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213927.00,A,3648.17050,N,12147.27654,W,0.311,0.00,240625,,,A*7D 2025-06-24T21:39:26.762Z,1750801166.762 [NAL9602](INFO): GPS fix at 20250624T213927: (36.802842, -121.787942) 2025-06-24T21:39:26.772Z,1750801166.772 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T21:39:26.772Z,1750801166.772 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T21:39:34.328Z,1750801174.328 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0072.lzma 2025-06-24T21:39:45.375Z,1750801185.375 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0072.lzma.bak 2025-06-24T21:39:45.375Z,1750801185.375 [DataOverHttps](INFO): SBD MOMSN=25243641 2025-06-24T21:40:11.277Z,1750801211.277 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250624T192950/Express0073.lzma 2025-06-24T21:40:22.319Z,1750801222.319 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0073.lzma.bak 2025-06-24T21:40:22.319Z,1750801222.319 [DataOverHttps](INFO): SBD MOMSN=25243646 2025-06-24T21:40:23.778Z,1750801223.778 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T21:40:23.778Z,1750801223.778 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T21:40:23.779Z,1750801223.779 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T21:44:35.472Z,1750801475.472 [NAL9602](INFO): SBD MO Status=0, MOMSN=19722, MT Status=0, MTMSN=0 2025-06-24T21:44:35.472Z,1750801475.472 [NAL9602](INFO): No messages in MT queue 2025-06-24T21:45:06.170Z,1750801506.170 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T21:45:24.359Z,1750801524.359 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T21:45:24.359Z,1750801524.359 [Default:CheckIn:C.Wait] Stopped 2025-06-24T21:45:24.359Z,1750801524.359 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T21:45:24.360Z,1750801524.360 [Default:CheckIn:D] Running Loop=1 2025-06-24T21:45:24.769Z,1750801524.769 [Default:CheckIn:D] Stopped 2025-06-24T21:45:24.769Z,1750801524.769 [Default:CheckIn:E] Running Loop=1 2025-06-24T21:45:25.172Z,1750801525.172 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.309945 min 2025-06-24T21:45:25.172Z,1750801525.172 [Default:CheckIn:E] Stopped 2025-06-24T21:45:25.173Z,1750801525.173 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T21:45:25.173Z,1750801525.173 [Default:CheckIn] Stopped 2025-06-24T21:45:25.173Z,1750801525.173 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T21:45:25.173Z,1750801525.173 [Default:CheckIn](INFO): Running loop #19 2025-06-24T21:45:25.173Z,1750801525.173 [Default:CheckIn] Running Loop=19 2025-06-24T21:45:25.173Z,1750801525.173 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T21:45:25.173Z,1750801525.173 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T21:45:27.181Z,1750801527.181 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214528.00,A,3648.17362,N,12147.27587,W,0.097,0.00,240625,,,A*7B 2025-06-24T21:45:27.184Z,1750801527.184 [NAL9602](INFO): GPS fix at 20250624T214528: (36.802894, -121.787931) 2025-06-24T21:45:27.218Z,1750801527.218 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T21:45:27.218Z,1750801527.218 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T21:45:34.324Z,1750801534.324 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0075.lzma 2025-06-24T21:45:45.375Z,1750801545.375 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0075.lzma.bak 2025-06-24T21:45:45.375Z,1750801545.375 [DataOverHttps](INFO): SBD MOMSN=25243656 2025-06-24T21:46:08.788Z,1750801568.788 [NAL9602](INFO): SBD MO Status=2, MOMSN=19723, MT Status=2, MTMSN=0 2025-06-24T21:46:08.788Z,1750801568.788 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T21:46:11.217Z,1750801571.217 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250624T192950/Express0076.lzma 2025-06-24T21:46:22.259Z,1750801582.259 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0076.lzma.bak 2025-06-24T21:46:22.259Z,1750801582.259 [DataOverHttps](INFO): SBD MOMSN=25243659 2025-06-24T21:46:23.348Z,1750801583.348 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T21:46:23.348Z,1750801583.348 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T21:46:23.348Z,1750801583.348 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T21:50:29.782Z,1750801829.782 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T21:51:00.485Z,1750801860.485 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T21:51:23.928Z,1750801883.928 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T21:51:23.928Z,1750801883.928 [Default:CheckIn:C.Wait] Stopped 2025-06-24T21:51:23.929Z,1750801883.929 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T21:51:23.929Z,1750801883.929 [Default:CheckIn:D] Running Loop=1 2025-06-24T21:51:24.335Z,1750801884.335 [Default:CheckIn:D] Stopped 2025-06-24T21:51:24.336Z,1750801884.336 [Default:CheckIn:E] Running Loop=1 2025-06-24T21:51:24.735Z,1750801884.735 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.302718 min 2025-06-24T21:51:24.735Z,1750801884.735 [Default:CheckIn:E] Stopped 2025-06-24T21:51:24.735Z,1750801884.735 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T21:51:24.735Z,1750801884.735 [Default:CheckIn] Stopped 2025-06-24T21:51:24.735Z,1750801884.735 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T21:51:24.735Z,1750801884.735 [Default:CheckIn](INFO): Running loop #20 2025-06-24T21:51:24.735Z,1750801884.735 [Default:CheckIn] Running Loop=20 2025-06-24T21:51:24.736Z,1750801884.736 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T21:51:24.736Z,1750801884.736 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T21:51:26.744Z,1750801886.744 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215127.00,A,3648.17110,N,12147.27444,W,0.078,0.00,240625,,,A*79 2025-06-24T21:51:26.761Z,1750801886.761 [NAL9602](INFO): GPS fix at 20250624T215127: (36.802852, -121.787907) 2025-06-24T21:51:26.779Z,1750801886.779 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T21:51:26.779Z,1750801886.779 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T21:51:34.337Z,1750801894.337 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0078.lzma 2025-06-24T21:51:45.380Z,1750801905.380 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0078.lzma.bak 2025-06-24T21:51:45.380Z,1750801905.380 [DataOverHttps](INFO): SBD MOMSN=25243668 2025-06-24T21:51:57.463Z,1750801917.463 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T21:51:57.541Z,1750801917.541 [NAL9602](ERROR): received: +CSQ:0 OK723, 2, 0, 0, 0 OK 2025-06-24T21:52:11.325Z,1750801931.325 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20250624T192950/Express0079.lzma 2025-06-24T21:52:22.367Z,1750801942.367 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0079.lzma.bak 2025-06-24T21:52:22.367Z,1750801942.367 [DataOverHttps](INFO): SBD MOMSN=25243671 2025-06-24T21:52:23.743Z,1750801943.743 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T21:52:23.743Z,1750801943.743 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T21:52:23.744Z,1750801943.744 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T21:53:59.070Z,1750802039.070 [NAL9602](INFO): SBD MO Status=2, MOMSN=19723, MT Status=2, MTMSN=0 2025-06-24T21:53:59.070Z,1750802039.070 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T21:54:30.601Z,1750802070.601 [NAL9602](INFO): SBD MO Status=2, MOMSN=19723, MT Status=2, MTMSN=0 2025-06-24T21:54:30.601Z,1750802070.601 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T21:54:56.028Z,1750802096.028 [NAL9602](INFO): SBD MO Status=2, MOMSN=19723, MT Status=2, MTMSN=0 2025-06-24T21:54:56.028Z,1750802096.028 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T21:55:23.099Z,1750802123.099 [NAL9602](INFO): SBD MO Status=2, MOMSN=19723, MT Status=2, MTMSN=0 2025-06-24T21:55:23.099Z,1750802123.099 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T21:56:28.563Z,1750802188.563 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T21:56:59.258Z,1750802219.258 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T21:57:24.388Z,1750802244.388 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T21:57:24.388Z,1750802244.388 [Default:CheckIn:C.Wait] Stopped 2025-06-24T21:57:24.388Z,1750802244.388 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T21:57:24.389Z,1750802244.389 [Default:CheckIn:D] Running Loop=1 2025-06-24T21:57:24.744Z,1750802244.744 [Default:CheckIn:D] Stopped 2025-06-24T21:57:24.744Z,1750802244.744 [Default:CheckIn:E] Running Loop=1 2025-06-24T21:57:25.137Z,1750802245.137 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.309521 min 2025-06-24T21:57:25.137Z,1750802245.137 [Default:CheckIn:E] Stopped 2025-06-24T21:57:25.137Z,1750802245.137 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T21:57:25.137Z,1750802245.137 [Default:CheckIn] Stopped 2025-06-24T21:57:25.137Z,1750802245.137 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T21:57:25.137Z,1750802245.137 [Default:CheckIn](INFO): Running loop #21 2025-06-24T21:57:25.138Z,1750802245.138 [Default:CheckIn] Running Loop=21 2025-06-24T21:57:25.138Z,1750802245.138 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T21:57:25.138Z,1750802245.138 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T21:57:27.125Z,1750802247.125 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215728.00,A,3648.16849,N,12147.27865,W,0.078,0.00,240625,,,A*7B 2025-06-24T21:57:27.128Z,1750802247.128 [NAL9602](INFO): GPS fix at 20250624T215728: (36.802808, -121.787977) 2025-06-24T21:57:27.138Z,1750802247.138 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T21:57:27.138Z,1750802247.138 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T21:57:34.329Z,1750802254.329 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0081.lzma 2025-06-24T21:57:45.379Z,1750802265.379 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0081.lzma.bak 2025-06-24T21:57:45.379Z,1750802265.379 [DataOverHttps](INFO): SBD MOMSN=25243681 2025-06-24T21:57:57.847Z,1750802277.847 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T21:57:57.929Z,1750802277.929 [NAL9602](ERROR): received: +CSQ:0 OK723, 2, 0, 0, 0 OK 2025-06-24T21:58:11.557Z,1750802291.557 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20250624T192950/Express0082.lzma 2025-06-24T21:58:22.599Z,1750802302.599 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0082.lzma.bak 2025-06-24T21:58:22.599Z,1750802302.599 [DataOverHttps](INFO): SBD MOMSN=25243684 2025-06-24T21:58:23.709Z,1750802303.709 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T21:58:23.709Z,1750802303.709 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T21:58:23.709Z,1750802303.709 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T21:58:31.370Z,1750802311.370 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2025-06-24T21:58:31.373Z,1750802311.373 [BPC1](INFO): Received data from all battery sticks. 2025-06-24T22:02:29.735Z,1750802549.735 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T22:03:00.435Z,1750802580.435 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T22:03:24.287Z,1750802604.287 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T22:03:24.287Z,1750802604.287 [Default:CheckIn:C.Wait] Stopped 2025-06-24T22:03:24.288Z,1750802604.288 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T22:03:24.288Z,1750802604.288 [Default:CheckIn:D] Running Loop=1 2025-06-24T22:03:24.692Z,1750802604.692 [Default:CheckIn:D] Stopped 2025-06-24T22:03:24.692Z,1750802604.692 [Default:CheckIn:E] Running Loop=1 2025-06-24T22:03:25.096Z,1750802605.096 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.308659 min 2025-06-24T22:03:25.096Z,1750802605.096 [Default:CheckIn:E] Stopped 2025-06-24T22:03:25.096Z,1750802605.096 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T22:03:25.096Z,1750802605.096 [Default:CheckIn] Stopped 2025-06-24T22:03:25.096Z,1750802605.096 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T22:03:25.096Z,1750802605.096 [Default:CheckIn](INFO): Running loop #22 2025-06-24T22:03:25.096Z,1750802605.096 [Default:CheckIn] Running Loop=22 2025-06-24T22:03:25.096Z,1750802605.096 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T22:03:25.096Z,1750802605.096 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T22:03:27.101Z,1750802607.101 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220328.00,A,3648.16948,N,12147.27911,W,0.311,0.00,240625,,,A*77 2025-06-24T22:03:27.104Z,1750802607.104 [NAL9602](INFO): GPS fix at 20250624T220328: (36.802825, -121.787985) 2025-06-24T22:03:27.114Z,1750802607.114 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T22:03:27.114Z,1750802607.114 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T22:03:34.269Z,1750802614.269 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250624T192950/Courier0084.lzma 2025-06-24T22:03:45.319Z,1750802625.319 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0084.lzma.bak 2025-06-24T22:03:45.319Z,1750802625.319 [DataOverHttps](INFO): SBD MOMSN=25243694 2025-06-24T22:03:57.835Z,1750802637.835 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T22:03:57.909Z,1750802637.909 [NAL9602](ERROR): received: +CSQ:0 OK723, 2, 0, 0, 0 OK 2025-06-24T22:04:11.129Z,1750802651.129 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250624T192950/Express0085.lzma 2025-06-24T22:04:22.171Z,1750802662.171 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0085.lzma.bak 2025-06-24T22:04:22.171Z,1750802662.171 [DataOverHttps](INFO): SBD MOMSN=25243703 2025-06-24T22:04:23.280Z,1750802663.280 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T22:04:23.280Z,1750802663.280 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T22:04:23.280Z,1750802663.280 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T22:07:34.778Z,1750802854.778 [NAL9602](INFO): SBD MO Status=2, MOMSN=19723, MT Status=2, MTMSN=0 2025-06-24T22:07:34.779Z,1750802854.779 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T22:07:52.156Z,1750802872.156 [NAL9602](INFO): SBD MO Status=2, MOMSN=19723, MT Status=2, MTMSN=0 2025-06-24T22:07:52.156Z,1750802872.156 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T22:08:29.719Z,1750802909.719 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T22:09:00.431Z,1750802940.431 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T22:09:23.866Z,1750802963.866 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T22:09:23.866Z,1750802963.866 [Default:CheckIn:C.Wait] Stopped 2025-06-24T22:09:23.866Z,1750802963.866 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T22:09:23.867Z,1750802963.867 [Default:CheckIn:D] Running Loop=1 2025-06-24T22:09:24.275Z,1750802964.275 [Default:CheckIn:D] Stopped 2025-06-24T22:09:24.276Z,1750802964.276 [Default:CheckIn:E] Running Loop=1 2025-06-24T22:09:24.683Z,1750802964.683 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.301717 min 2025-06-24T22:09:24.683Z,1750802964.683 [Default:CheckIn:E] Stopped 2025-06-24T22:09:24.684Z,1750802964.684 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T22:09:24.684Z,1750802964.684 [Default:CheckIn] Stopped 2025-06-24T22:09:24.684Z,1750802964.684 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T22:09:24.684Z,1750802964.684 [Default:CheckIn](INFO): Running loop #23 2025-06-24T22:09:24.684Z,1750802964.684 [Default:CheckIn] Running Loop=23 2025-06-24T22:09:24.684Z,1750802964.684 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T22:09:24.684Z,1750802964.684 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T22:09:26.708Z,1750802966.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220927.00,A,3648.17381,N,12147.27750,W,0.292,164.31,240625,,,A*7C 2025-06-24T22:09:26.711Z,1750802966.711 [NAL9602](INFO): GPS fix at 20250624T220927: (36.802897, -121.787958) 2025-06-24T22:09:26.721Z,1750802966.721 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T22:09:26.721Z,1750802966.721 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T22:09:33.621Z,1750802973.621 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0087.lzma 2025-06-24T22:09:44.671Z,1750802984.671 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0087.lzma.bak 2025-06-24T22:09:44.671Z,1750802984.671 [DataOverHttps](INFO): SBD MOMSN=25243713 2025-06-24T22:09:57.390Z,1750802997.390 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T22:09:57.473Z,1750802997.473 [NAL9602](ERROR): received: +CSQ:0 OK723, 2, 0, 0, 0 OK 2025-06-24T22:10:10.657Z,1750803010.657 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250624T192950/Express0088.lzma 2025-06-24T22:10:21.699Z,1750803021.699 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0088.lzma.bak 2025-06-24T22:10:21.699Z,1750803021.699 [DataOverHttps](INFO): SBD MOMSN=25243716 2025-06-24T22:10:22.860Z,1750803022.860 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T22:10:22.860Z,1750803022.860 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T22:10:22.860Z,1750803022.860 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T22:13:32.385Z,1750803212.385 [NAL9602](INFO): SBD MO Status=2, MOMSN=19723, MT Status=2, MTMSN=0 2025-06-24T22:13:32.385Z,1750803212.385 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T22:14:14.800Z,1750803254.800 [NAL9602](INFO): SBD MO Status=2, MOMSN=19723, MT Status=2, MTMSN=0 2025-06-24T22:14:14.800Z,1750803254.800 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T22:14:28.537Z,1750803268.537 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T22:14:59.238Z,1750803299.238 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T22:15:23.500Z,1750803323.500 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T22:15:23.501Z,1750803323.501 [Default:CheckIn:C.Wait] Stopped 2025-06-24T22:15:23.501Z,1750803323.501 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T22:15:23.501Z,1750803323.501 [Default:CheckIn:D] Running Loop=1 2025-06-24T22:15:23.896Z,1750803323.896 [Default:CheckIn:D] Stopped 2025-06-24T22:15:23.896Z,1750803323.896 [Default:CheckIn:E] Running Loop=1 2025-06-24T22:15:24.296Z,1750803324.296 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.295394 min 2025-06-24T22:15:24.296Z,1750803324.296 [Default:CheckIn:E] Stopped 2025-06-24T22:15:24.296Z,1750803324.296 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T22:15:24.296Z,1750803324.296 [Default:CheckIn] Stopped 2025-06-24T22:15:24.296Z,1750803324.296 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T22:15:24.297Z,1750803324.297 [Default:CheckIn](INFO): Running loop #24 2025-06-24T22:15:24.297Z,1750803324.297 [Default:CheckIn] Running Loop=24 2025-06-24T22:15:24.297Z,1750803324.297 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T22:15:24.297Z,1750803324.297 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T22:15:26.312Z,1750803326.312 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221527.00,A,3648.17414,N,12147.27572,W,0.039,62.37,240625,,,A*4A 2025-06-24T22:15:26.314Z,1750803326.314 [NAL9602](INFO): GPS fix at 20250624T221527: (36.802902, -121.787929) 2025-06-24T22:15:26.324Z,1750803326.324 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T22:15:26.324Z,1750803326.324 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T22:15:33.880Z,1750803333.880 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0090.lzma 2025-06-24T22:15:44.947Z,1750803344.947 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0090.lzma.bak 2025-06-24T22:15:44.947Z,1750803344.947 [DataOverHttps](INFO): SBD MOMSN=25243726 2025-06-24T22:15:57.027Z,1750803357.027 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T22:15:57.101Z,1750803357.101 [NAL9602](ERROR): received: +CSQ:0 OK723, 2, 0, 0, 0 OK 2025-06-24T22:16:11.077Z,1750803371.077 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250624T192950/Express0091.lzma 2025-06-24T22:16:22.121Z,1750803382.121 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0091.lzma.bak 2025-06-24T22:16:22.122Z,1750803382.122 [DataOverHttps](INFO): SBD MOMSN=25243729 2025-06-24T22:16:23.289Z,1750803383.289 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T22:16:23.289Z,1750803383.289 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T22:16:23.289Z,1750803383.289 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T22:16:56.404Z,1750803416.404 [NAL9602](INFO): SBD MO Status=2, MOMSN=19723, MT Status=2, MTMSN=0 2025-06-24T22:16:56.404Z,1750803416.404 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T22:17:48.136Z,1750803468.136 [NAL9602](INFO): SBD MO Status=2, MOMSN=19723, MT Status=2, MTMSN=0 2025-06-24T22:17:48.136Z,1750803468.136 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T22:20:28.559Z,1750803628.559 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T22:20:59.264Z,1750803659.264 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T22:21:23.925Z,1750803683.925 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T22:21:23.925Z,1750803683.925 [Default:CheckIn:C.Wait] Stopped 2025-06-24T22:21:23.925Z,1750803683.925 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T22:21:23.926Z,1750803683.926 [Default:CheckIn:D] Running Loop=1 2025-06-24T22:21:24.320Z,1750803684.320 [Default:CheckIn:D] Stopped 2025-06-24T22:21:24.320Z,1750803684.320 [Default:CheckIn:E] Running Loop=1 2025-06-24T22:21:24.728Z,1750803684.728 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 144.302458 min 2025-06-24T22:21:24.728Z,1750803684.728 [Default:CheckIn:E] Stopped 2025-06-24T22:21:24.728Z,1750803684.728 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T22:21:24.728Z,1750803684.728 [Default:CheckIn] Stopped 2025-06-24T22:21:24.728Z,1750803684.728 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T22:21:24.728Z,1750803684.728 [Default:CheckIn](INFO): Running loop #25 2025-06-24T22:21:24.728Z,1750803684.728 [Default:CheckIn] Running Loop=25 2025-06-24T22:21:24.729Z,1750803684.729 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T22:21:24.729Z,1750803684.729 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T22:21:26.735Z,1750803686.735 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222127.00,A,3648.17315,N,12147.27926,W,0.078,62.37,240625,,,A*43 2025-06-24T22:21:26.737Z,1750803686.737 [NAL9602](INFO): GPS fix at 20250624T222127: (36.802886, -121.787988) 2025-06-24T22:21:26.747Z,1750803686.747 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T22:21:26.748Z,1750803686.748 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T22:21:34.128Z,1750803694.128 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0093.lzma 2025-06-24T22:21:45.171Z,1750803705.171 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0093.lzma.bak 2025-06-24T22:21:45.171Z,1750803705.171 [DataOverHttps](INFO): SBD MOMSN=25243739 2025-06-24T22:21:57.440Z,1750803717.440 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T22:21:57.517Z,1750803717.517 [NAL9602](ERROR): received: +CSQ:0 OK723, 2, 0, 0, 0 OK 2025-06-24T22:22:11.173Z,1750803731.173 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20250624T192950/Express0094.lzma 2025-06-24T22:22:22.215Z,1750803742.215 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0094.lzma.bak 2025-06-24T22:22:22.216Z,1750803742.216 [DataOverHttps](INFO): SBD MOMSN=25243742 2025-06-24T22:22:23.305Z,1750803743.305 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T22:22:23.305Z,1750803743.305 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T22:22:23.305Z,1750803743.305 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T22:23:55.820Z,1750803835.820 [NAL9602](INFO): SBD MO Status=0, MOMSN=19723, MT Status=0, MTMSN=0 2025-06-24T22:23:55.820Z,1750803835.820 [NAL9602](INFO): No messages in MT queue 2025-06-24T22:24:26.526Z,1750803866.526 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T22:27:23.885Z,1750804043.885 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T22:27:23.886Z,1750804043.886 [Default:CheckIn:C.Wait] Stopped 2025-06-24T22:27:23.886Z,1750804043.886 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T22:27:23.886Z,1750804043.886 [Default:CheckIn:D] Running Loop=1 2025-06-24T22:27:24.296Z,1750804044.296 [Default:CheckIn:D] Stopped 2025-06-24T22:27:24.296Z,1750804044.296 [Default:CheckIn:E] Running Loop=1 2025-06-24T22:27:24.708Z,1750804044.708 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 150.302051 min 2025-06-24T22:27:24.708Z,1750804044.708 [Default:CheckIn:E] Stopped 2025-06-24T22:27:24.708Z,1750804044.708 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T22:27:24.708Z,1750804044.708 [Default:CheckIn] Stopped 2025-06-24T22:27:24.708Z,1750804044.708 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T22:27:24.708Z,1750804044.708 [Default:CheckIn](INFO): Running loop #26 2025-06-24T22:27:24.708Z,1750804044.708 [Default:CheckIn] Running Loop=26 2025-06-24T22:27:24.708Z,1750804044.708 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T22:27:24.709Z,1750804044.709 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T22:27:26.708Z,1750804046.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222727.00,A,3648.17507,N,12147.28089,W,0.194,0.00,240625,,,A*70 2025-06-24T22:27:26.718Z,1750804046.718 [NAL9602](INFO): GPS fix at 20250624T222727: (36.802918, -121.788015) 2025-06-24T22:27:26.728Z,1750804046.728 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T22:27:26.728Z,1750804046.728 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T22:27:34.546Z,1750804054.546 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0096.lzma 2025-06-24T22:27:45.587Z,1750804065.587 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0096.lzma.bak 2025-06-24T22:27:45.588Z,1750804065.588 [DataOverHttps](INFO): SBD MOMSN=25243753 2025-06-24T22:28:11.699Z,1750804091.699 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250624T192950/Express0097.lzma 2025-06-24T22:28:22.739Z,1750804102.739 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0097.lzma.bak 2025-06-24T22:28:22.739Z,1750804102.739 [DataOverHttps](INFO): SBD MOMSN=25243756 2025-06-24T22:28:24.084Z,1750804104.084 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T22:28:24.085Z,1750804104.085 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T22:28:24.085Z,1750804104.085 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T22:32:28.499Z,1750804348.499 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T22:32:59.198Z,1750804379.198 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T22:33:24.659Z,1750804404.659 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T22:33:24.659Z,1750804404.659 [Default:CheckIn:C.Wait] Stopped 2025-06-24T22:33:24.659Z,1750804404.659 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T22:33:24.660Z,1750804404.660 [Default:CheckIn:D] Running Loop=1 2025-06-24T22:33:25.060Z,1750804405.060 [Default:CheckIn:D] Stopped 2025-06-24T22:33:25.060Z,1750804405.060 [Default:CheckIn:E] Running Loop=1 2025-06-24T22:33:25.472Z,1750804405.472 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 156.314795 min 2025-06-24T22:33:25.472Z,1750804405.472 [Default:CheckIn:E] Stopped 2025-06-24T22:33:25.472Z,1750804405.472 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T22:33:25.472Z,1750804405.472 [Default:CheckIn] Stopped 2025-06-24T22:33:25.472Z,1750804405.472 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T22:33:25.472Z,1750804405.472 [Default:CheckIn](INFO): Running loop #27 2025-06-24T22:33:25.472Z,1750804405.472 [Default:CheckIn] Running Loop=27 2025-06-24T22:33:25.472Z,1750804405.472 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T22:33:25.473Z,1750804405.473 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T22:33:27.476Z,1750804407.476 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223328.00,A,3648.17798,N,12147.27371,W,0.136,0.00,240625,,,A*7D 2025-06-24T22:33:27.479Z,1750804407.479 [NAL9602](INFO): GPS fix at 20250624T223328: (36.802966, -121.787895) 2025-06-24T22:33:27.510Z,1750804407.510 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T22:33:27.510Z,1750804407.510 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T22:33:35.061Z,1750804415.061 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0099.lzma 2025-06-24T22:33:46.111Z,1750804426.111 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0099.lzma.bak 2025-06-24T22:33:46.111Z,1750804426.111 [DataOverHttps](INFO): SBD MOMSN=25243765 2025-06-24T22:33:58.179Z,1750804438.179 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T22:33:58.261Z,1750804438.261 [NAL9602](ERROR): received: +CSQ:0 OK723, 0, 0, 0, 0 OK 2025-06-24T22:34:11.917Z,1750804451.917 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250624T192950/Express0100.lzma 2025-06-24T22:34:22.960Z,1750804462.960 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0100.lzma.bak 2025-06-24T22:34:22.960Z,1750804462.960 [DataOverHttps](INFO): SBD MOMSN=25243768 2025-06-24T22:34:24.054Z,1750804464.054 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T22:34:24.055Z,1750804464.055 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T22:34:24.055Z,1750804464.055 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T22:35:05.254Z,1750804505.254 [NAL9602](INFO): SBD MO Status=2, MOMSN=19724, MT Status=2, MTMSN=0 2025-06-24T22:35:05.254Z,1750804505.254 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T22:35:23.046Z,1750804523.046 [NAL9602](INFO): SBD MO Status=0, MOMSN=19724, MT Status=0, MTMSN=0 2025-06-24T22:35:23.046Z,1750804523.046 [NAL9602](INFO): No messages in MT queue 2025-06-24T22:35:53.746Z,1750804553.746 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T22:36:41.009Z,1750804601.009 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-06-24T22:36:54.398Z,1750804614.398 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.911266 2025-06-24T22:37:38.894Z,1750804658.894 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003777 2025-06-24T22:39:24.639Z,1750804764.639 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T22:39:24.639Z,1750804764.639 [Default:CheckIn:C.Wait] Stopped 2025-06-24T22:39:24.640Z,1750804764.640 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T22:39:24.640Z,1750804764.640 [Default:CheckIn:D] Running Loop=1 2025-06-24T22:39:25.044Z,1750804765.044 [Default:CheckIn:D] Stopped 2025-06-24T22:39:25.044Z,1750804765.044 [Default:CheckIn:E] Running Loop=1 2025-06-24T22:39:25.446Z,1750804765.446 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 162.314518 min 2025-06-24T22:39:25.446Z,1750804765.446 [Default:CheckIn:E] Stopped 2025-06-24T22:39:25.446Z,1750804765.446 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T22:39:25.446Z,1750804765.446 [Default:CheckIn] Stopped 2025-06-24T22:39:25.446Z,1750804765.446 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T22:39:25.446Z,1750804765.446 [Default:CheckIn](INFO): Running loop #28 2025-06-24T22:39:25.446Z,1750804765.446 [Default:CheckIn] Running Loop=28 2025-06-24T22:39:25.446Z,1750804765.446 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T22:39:25.447Z,1750804765.447 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T22:39:27.465Z,1750804767.465 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223928.00,A,3648.17285,N,12147.28065,W,0.097,189.91,240625,,,A*75 2025-06-24T22:39:27.468Z,1750804767.468 [NAL9602](INFO): GPS fix at 20250624T223928: (36.802881, -121.788011) 2025-06-24T22:39:27.478Z,1750804767.478 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T22:39:27.478Z,1750804767.478 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T22:39:34.873Z,1750804774.873 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0102.lzma 2025-06-24T22:39:45.915Z,1750804785.915 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0102.lzma.bak 2025-06-24T22:39:45.916Z,1750804785.916 [DataOverHttps](INFO): SBD MOMSN=25243779 2025-06-24T22:40:11.781Z,1750804811.781 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250624T192950/Express0103.lzma 2025-06-24T22:40:22.823Z,1750804822.823 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0103.lzma.bak 2025-06-24T22:40:22.823Z,1750804822.823 [DataOverHttps](INFO): SBD MOMSN=25243782 2025-06-24T22:40:24.045Z,1750804824.045 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T22:40:24.046Z,1750804824.046 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T22:40:24.046Z,1750804824.046 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T22:44:10.664Z,1750805050.664 [NAL9602](INFO): SBD MO Status=2, MOMSN=19725, MT Status=2, MTMSN=0 2025-06-24T22:44:10.664Z,1750805050.664 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T22:44:28.841Z,1750805068.841 [NAL9602](INFO): SBD MO Status=0, MOMSN=19725, MT Status=0, MTMSN=0 2025-06-24T22:44:28.841Z,1750805068.841 [NAL9602](INFO): No messages in MT queue 2025-06-24T22:44:59.542Z,1750805099.542 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T22:45:24.613Z,1750805124.613 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T22:45:24.613Z,1750805124.613 [Default:CheckIn:C.Wait] Stopped 2025-06-24T22:45:24.613Z,1750805124.613 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T22:45:24.614Z,1750805124.614 [Default:CheckIn:D] Running Loop=1 2025-06-24T22:45:25.008Z,1750805125.008 [Default:CheckIn:D] Stopped 2025-06-24T22:45:25.008Z,1750805125.008 [Default:CheckIn:E] Running Loop=1 2025-06-24T22:45:25.409Z,1750805125.409 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 168.313932 min 2025-06-24T22:45:25.409Z,1750805125.409 [Default:CheckIn:E] Stopped 2025-06-24T22:45:25.409Z,1750805125.409 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T22:45:25.409Z,1750805125.409 [Default:CheckIn] Stopped 2025-06-24T22:45:25.409Z,1750805125.409 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T22:45:25.410Z,1750805125.410 [Default:CheckIn](INFO): Running loop #29 2025-06-24T22:45:25.410Z,1750805125.410 [Default:CheckIn] Running Loop=29 2025-06-24T22:45:25.410Z,1750805125.410 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T22:45:25.410Z,1750805125.410 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T22:45:27.423Z,1750805127.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224528.00,A,3648.17519,N,12147.28114,W,0.175,37.13,240625,,,A*48 2025-06-24T22:45:27.425Z,1750805127.425 [NAL9602](INFO): GPS fix at 20250624T224528: (36.802920, -121.788019) 2025-06-24T22:45:27.435Z,1750805127.435 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T22:45:27.435Z,1750805127.435 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T22:45:34.917Z,1750805134.917 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0105.lzma 2025-06-24T22:45:45.959Z,1750805145.959 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0105.lzma.bak 2025-06-24T22:45:45.959Z,1750805145.959 [DataOverHttps](INFO): SBD MOMSN=25243792 2025-06-24T22:46:09.474Z,1750805169.474 [NAL9602](INFO): SBD MO Status=0, MOMSN=19726, MT Status=0, MTMSN=0 2025-06-24T22:46:09.474Z,1750805169.474 [NAL9602](INFO): No messages in MT queue 2025-06-24T22:46:12.277Z,1750805172.277 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250624T192950/Express0106.lzma 2025-06-24T22:46:23.319Z,1750805183.319 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0106.lzma.bak 2025-06-24T22:46:23.319Z,1750805183.319 [DataOverHttps](INFO): SBD MOMSN=25243795 2025-06-24T22:46:24.425Z,1750805184.425 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T22:46:24.425Z,1750805184.425 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T22:46:24.425Z,1750805184.425 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T22:46:40.163Z,1750805200.163 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T22:51:25.048Z,1750805485.048 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T22:51:25.049Z,1750805485.049 [Default:CheckIn:C.Wait] Stopped 2025-06-24T22:51:25.049Z,1750805485.049 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T22:51:25.050Z,1750805485.050 [Default:CheckIn:D] Running Loop=1 2025-06-24T22:51:25.459Z,1750805485.459 [Default:CheckIn:D] Stopped 2025-06-24T22:51:25.460Z,1750805485.460 [Default:CheckIn:E] Running Loop=1 2025-06-24T22:51:25.867Z,1750805485.867 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 174.321452 min 2025-06-24T22:51:25.867Z,1750805485.867 [Default:CheckIn:E] Stopped 2025-06-24T22:51:25.867Z,1750805485.867 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T22:51:25.867Z,1750805485.867 [Default:CheckIn] Stopped 2025-06-24T22:51:25.867Z,1750805485.867 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T22:51:25.868Z,1750805485.868 [Default:CheckIn](INFO): Running loop #30 2025-06-24T22:51:25.868Z,1750805485.868 [Default:CheckIn] Running Loop=30 2025-06-24T22:51:25.868Z,1750805485.868 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T22:51:25.868Z,1750805485.868 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T22:51:27.868Z,1750805487.868 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225128.00,A,3648.17279,N,12147.28055,W,0.078,47.51,240625,,,A*45 2025-06-24T22:51:27.871Z,1750805487.871 [NAL9602](INFO): GPS fix at 20250624T225128: (36.802880, -121.788009) 2025-06-24T22:51:27.881Z,1750805487.881 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T22:51:27.881Z,1750805487.881 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T22:51:35.016Z,1750805495.016 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0108.lzma 2025-06-24T22:51:46.063Z,1750805506.063 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0108.lzma.bak 2025-06-24T22:51:46.063Z,1750805506.063 [DataOverHttps](INFO): SBD MOMSN=25243809 2025-06-24T22:52:11.945Z,1750805531.945 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250624T192950/Express0109.lzma 2025-06-24T22:52:22.987Z,1750805542.987 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0109.lzma.bak 2025-06-24T22:52:22.987Z,1750805542.987 [DataOverHttps](INFO): SBD MOMSN=25243818 2025-06-24T22:52:24.438Z,1750805544.438 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T22:52:24.438Z,1750805544.438 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T22:52:24.438Z,1750805544.438 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T22:53:23.412Z,1750805603.412 [NAL9602](INFO): SBD MO Status=2, MOMSN=19727, MT Status=2, MTMSN=0 2025-06-24T22:53:23.412Z,1750805603.412 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T22:54:16.780Z,1750805656.780 [NAL9602](INFO): SBD MO Status=0, MOMSN=19727, MT Status=0, MTMSN=0 2025-06-24T22:54:16.780Z,1750805656.780 [NAL9602](INFO): No messages in MT queue 2025-06-24T22:54:47.474Z,1750805687.474 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T22:55:52.746Z,1750805752.746 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-06-24T22:56:04.794Z,1750805764.794 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.703342 2025-06-24T22:56:50.390Z,1750805810.390 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003807 2025-06-24T22:57:25.056Z,1750805845.056 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T22:57:25.056Z,1750805845.056 [Default:CheckIn:C.Wait] Stopped 2025-06-24T22:57:25.056Z,1750805845.056 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T22:57:25.056Z,1750805845.056 [Default:CheckIn:D] Running Loop=1 2025-06-24T22:57:25.451Z,1750805845.451 [Default:CheckIn:D] Stopped 2025-06-24T22:57:25.452Z,1750805845.452 [Default:CheckIn:E] Running Loop=1 2025-06-24T22:57:25.852Z,1750805845.852 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 180.321322 min 2025-06-24T22:57:25.852Z,1750805845.852 [Default:CheckIn:E] Stopped 2025-06-24T22:57:25.852Z,1750805845.852 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T22:57:25.852Z,1750805845.852 [Default:CheckIn] Stopped 2025-06-24T22:57:25.852Z,1750805845.852 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T22:57:25.853Z,1750805845.853 [Default:CheckIn](INFO): Running loop #31 2025-06-24T22:57:25.853Z,1750805845.853 [Default:CheckIn] Running Loop=31 2025-06-24T22:57:25.853Z,1750805845.853 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T22:57:25.853Z,1750805845.853 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T22:57:27.867Z,1750805847.867 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225728.00,A,3648.17649,N,12147.27846,W,0.194,47.51,240625,,,A*42 2025-06-24T22:57:27.869Z,1750805847.869 [NAL9602](INFO): GPS fix at 20250624T225728: (36.802942, -121.787974) 2025-06-24T22:57:27.879Z,1750805847.879 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T22:57:27.879Z,1750805847.879 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T22:57:35.417Z,1750805855.417 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0111.lzma 2025-06-24T22:57:46.492Z,1750805866.492 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0111.lzma.bak 2025-06-24T22:57:46.492Z,1750805866.492 [DataOverHttps](INFO): SBD MOMSN=25243861 2025-06-24T22:58:12.845Z,1750805892.845 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250624T192950/Express0112.lzma 2025-06-24T22:58:23.892Z,1750805903.892 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0112.lzma.bak 2025-06-24T22:58:23.892Z,1750805903.892 [DataOverHttps](INFO): SBD MOMSN=25243864 2025-06-24T22:58:25.280Z,1750805905.280 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T22:58:25.281Z,1750805905.281 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T22:58:25.281Z,1750805905.281 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T23:01:23.844Z,1750806083.844 [NAL9602](INFO): SBD MO Status=2, MOMSN=19728, MT Status=2, MTMSN=0 2025-06-24T23:01:23.844Z,1750806083.844 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T23:01:45.250Z,1750806105.250 [NAL9602](INFO): SBD MO Status=2, MOMSN=19728, MT Status=2, MTMSN=0 2025-06-24T23:01:45.250Z,1750806105.250 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T23:01:55.010Z,1750806115.010 [NAL9602](INFO): SBD MO Status=0, MOMSN=19728, MT Status=0, MTMSN=0 2025-06-24T23:01:55.010Z,1750806115.010 [NAL9602](INFO): No messages in MT queue 2025-06-24T23:02:25.649Z,1750806145.649 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T23:03:25.862Z,1750806205.862 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T23:03:25.862Z,1750806205.862 [Default:CheckIn:C.Wait] Stopped 2025-06-24T23:03:25.862Z,1750806205.862 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T23:03:25.862Z,1750806205.862 [Default:CheckIn:D] Running Loop=1 2025-06-24T23:03:26.275Z,1750806206.275 [Default:CheckIn:D] Stopped 2025-06-24T23:03:26.276Z,1750806206.276 [Default:CheckIn:E] Running Loop=1 2025-06-24T23:03:26.685Z,1750806206.685 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 186.335059 min 2025-06-24T23:03:26.685Z,1750806206.685 [Default:CheckIn:E] Stopped 2025-06-24T23:03:26.686Z,1750806206.686 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T23:03:26.686Z,1750806206.686 [Default:CheckIn] Stopped 2025-06-24T23:03:26.686Z,1750806206.686 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T23:03:26.686Z,1750806206.686 [Default:CheckIn](INFO): Running loop #32 2025-06-24T23:03:26.686Z,1750806206.686 [Default:CheckIn] Running Loop=32 2025-06-24T23:03:26.686Z,1750806206.686 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T23:03:26.686Z,1750806206.686 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T23:03:28.673Z,1750806208.673 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230329.00,A,3648.17488,N,12147.27588,W,0.369,47.51,240625,,,A*43 2025-06-24T23:03:28.675Z,1750806208.675 [NAL9602](INFO): GPS fix at 20250624T230329: (36.802915, -121.787931) 2025-06-24T23:03:28.685Z,1750806208.685 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T23:03:28.685Z,1750806208.685 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T23:03:36.337Z,1750806216.337 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0114.lzma 2025-06-24T23:03:47.379Z,1750806227.379 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0114.lzma.bak 2025-06-24T23:03:47.379Z,1750806227.379 [DataOverHttps](INFO): SBD MOMSN=25243874 2025-06-24T23:03:55.740Z,1750806235.740 [NAL9602](INFO): SBD MO Status=0, MOMSN=19729, MT Status=0, MTMSN=0 2025-06-24T23:03:55.740Z,1750806235.740 [NAL9602](INFO): No messages in MT queue 2025-06-24T23:04:13.501Z,1750806253.501 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250624T192950/Express0115.lzma 2025-06-24T23:04:24.543Z,1750806264.543 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0115.lzma.bak 2025-06-24T23:04:24.543Z,1750806264.543 [DataOverHttps](INFO): SBD MOMSN=25243878 2025-06-24T23:04:25.657Z,1750806265.657 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T23:04:25.657Z,1750806265.657 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T23:04:25.657Z,1750806265.657 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T23:04:26.447Z,1750806266.447 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T23:05:57.416Z,1750806357.416 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2025-06-24T23:05:57.418Z,1750806357.418 [BPC1](INFO): Received data from all battery sticks. 2025-06-24T23:09:26.276Z,1750806566.276 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T23:09:26.276Z,1750806566.276 [Default:CheckIn:C.Wait] Stopped 2025-06-24T23:09:26.276Z,1750806566.276 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T23:09:26.276Z,1750806566.276 [Default:CheckIn:D] Running Loop=1 2025-06-24T23:09:26.680Z,1750806566.680 [Default:CheckIn:D] Stopped 2025-06-24T23:09:26.680Z,1750806566.680 [Default:CheckIn:E] Running Loop=1 2025-06-24T23:09:27.090Z,1750806567.090 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 192.341797 min 2025-06-24T23:09:27.090Z,1750806567.090 [Default:CheckIn:E] Stopped 2025-06-24T23:09:27.090Z,1750806567.090 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T23:09:27.090Z,1750806567.090 [Default:CheckIn] Stopped 2025-06-24T23:09:27.090Z,1750806567.090 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T23:09:27.090Z,1750806567.090 [Default:CheckIn](INFO): Running loop #33 2025-06-24T23:09:27.090Z,1750806567.090 [Default:CheckIn] Running Loop=33 2025-06-24T23:09:27.090Z,1750806567.090 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T23:09:27.090Z,1750806567.090 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T23:09:29.096Z,1750806569.096 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230930.00,A,3648.19987,N,12147.27026,W,1.419,17.58,240625,,,A*41 2025-06-24T23:09:29.099Z,1750806569.099 [NAL9602](INFO): GPS fix at 20250624T230930: (36.803331, -121.787838) 2025-06-24T23:09:29.128Z,1750806569.128 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T23:09:29.129Z,1750806569.129 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T23:09:36.725Z,1750806576.725 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20250624T192950/Courier0117.lzma 2025-06-24T23:09:47.767Z,1750806587.767 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0117.lzma.bak 2025-06-24T23:09:47.767Z,1750806587.767 [DataOverHttps](INFO): SBD MOMSN=25243926 2025-06-24T23:10:13.885Z,1750806613.885 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250624T192950/Express0118.lzma 2025-06-24T23:10:24.927Z,1750806624.927 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0118.lzma.bak 2025-06-24T23:10:24.927Z,1750806624.927 [DataOverHttps](INFO): SBD MOMSN=25243929 2025-06-24T23:10:26.092Z,1750806626.092 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T23:10:26.092Z,1750806626.092 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T23:10:26.092Z,1750806626.092 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T23:10:41.832Z,1750806641.832 [NAL9602](INFO): SBD MO Status=0, MOMSN=19730, MT Status=0, MTMSN=0 2025-06-24T23:10:41.832Z,1750806641.832 [NAL9602](INFO): No messages in MT queue 2025-06-24T23:11:12.542Z,1750806672.542 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T23:15:26.665Z,1750806926.665 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T23:15:26.665Z,1750806926.665 [Default:CheckIn:C.Wait] Stopped 2025-06-24T23:15:26.666Z,1750806926.666 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T23:15:26.666Z,1750806926.666 [Default:CheckIn:D] Running Loop=1 2025-06-24T23:15:27.076Z,1750806927.076 [Default:CheckIn:D] Stopped 2025-06-24T23:15:27.076Z,1750806927.076 [Default:CheckIn:E] Running Loop=1 2025-06-24T23:15:27.472Z,1750806927.472 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 198.348405 min 2025-06-24T23:15:27.472Z,1750806927.472 [Default:CheckIn:E] Stopped 2025-06-24T23:15:27.472Z,1750806927.472 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T23:15:27.472Z,1750806927.472 [Default:CheckIn] Stopped 2025-06-24T23:15:27.472Z,1750806927.472 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T23:15:27.472Z,1750806927.472 [Default:CheckIn](INFO): Running loop #34 2025-06-24T23:15:27.472Z,1750806927.472 [Default:CheckIn] Running Loop=34 2025-06-24T23:15:27.472Z,1750806927.472 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T23:15:27.472Z,1750806927.472 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T23:15:29.492Z,1750806929.492 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231530.00,A,3648.19106,N,12147.28128,W,0.369,308.29,240625,,,A*77 2025-06-24T23:15:29.495Z,1750806929.495 [NAL9602](INFO): GPS fix at 20250624T231530: (36.803184, -121.788021) 2025-06-24T23:15:29.505Z,1750806929.505 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T23:15:29.505Z,1750806929.505 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T23:15:38.385Z,1750806938.385 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0120.lzma 2025-06-24T23:15:49.427Z,1750806949.427 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0120.lzma.bak 2025-06-24T23:15:49.428Z,1750806949.428 [DataOverHttps](INFO): SBD MOMSN=25243939 2025-06-24T23:16:16.366Z,1750806976.366 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-06-24T23:16:16.366Z,1750806976.366 [DropWeight] Hardware Fault, FailCount= 1 2025-06-24T23:16:16.366Z,1750806976.366 [DropWeight](ERROR): Hardware Fault 2025-06-24T23:16:16.396Z,1750806976.396 [CommandExec](FAULT): Scheduling is paused 2025-06-24T23:16:16.401Z,1750806976.401 [CBIT](INFO): Critical error at 20250624T231616 2025-06-24T23:16:16.404Z,1750806976.404 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-06-24T23:16:16.404Z,1750806976.404 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-06-24T23:16:16.812Z,1750806976.812 [CBIT](INFO): Critical error at 20250624T231616 2025-06-24T23:16:18.446Z,1750806978.446 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250624T192950/Express0121.lzma 2025-06-24T23:16:28.089Z,1750806988.089 [NAL9602](INFO): SBD MO Status=2, MOMSN=19731, MT Status=2, MTMSN=0 2025-06-24T23:16:28.089Z,1750806988.089 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T23:16:29.479Z,1750806989.479 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0121.lzma.bak 2025-06-24T23:16:29.479Z,1750806989.479 [DataOverHttps](INFO): SBD MOMSN=25243942 2025-06-24T23:16:30.914Z,1750806990.914 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T23:16:30.914Z,1750806990.914 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T23:16:30.914Z,1750806990.914 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T23:20:03.860Z,1750807203.860 [NAL9602](INFO): SBD MO Status=0, MOMSN=19731, MT Status=0, MTMSN=0 2025-06-24T23:20:03.860Z,1750807203.860 [NAL9602](INFO): No messages in MT queue 2025-06-24T23:20:34.559Z,1750807234.559 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T23:21:31.531Z,1750807291.531 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T23:21:31.531Z,1750807291.531 [Default:CheckIn:C.Wait] Stopped 2025-06-24T23:21:31.531Z,1750807291.531 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T23:21:31.532Z,1750807291.532 [Default:CheckIn:D] Running Loop=1 2025-06-24T23:21:31.942Z,1750807291.942 [Default:CheckIn:D] Stopped 2025-06-24T23:21:31.942Z,1750807291.942 [Default:CheckIn:E] Running Loop=1 2025-06-24T23:21:32.342Z,1750807292.342 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 204.429492 min 2025-06-24T23:21:32.342Z,1750807292.342 [Default:CheckIn:E] Stopped 2025-06-24T23:21:32.342Z,1750807292.342 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T23:21:32.342Z,1750807292.342 [Default:CheckIn] Stopped 2025-06-24T23:21:32.342Z,1750807292.342 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T23:21:32.343Z,1750807292.343 [Default:CheckIn](INFO): Running loop #35 2025-06-24T23:21:32.343Z,1750807292.343 [Default:CheckIn] Running Loop=35 2025-06-24T23:21:32.343Z,1750807292.343 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T23:21:32.343Z,1750807292.343 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T23:21:34.352Z,1750807294.352 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232135.00,A,3648.18964,N,12147.27635,W,0.369,352.21,240625,,,A*7B 2025-06-24T23:21:34.363Z,1750807294.363 [NAL9602](INFO): GPS fix at 20250624T232135: (36.803161, -121.787939) 2025-06-24T23:21:34.373Z,1750807294.373 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T23:21:34.373Z,1750807294.373 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T23:21:43.249Z,1750807303.249 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20250624T192950/Courier0123.lzma 2025-06-24T23:21:46.548Z,1750807306.548 [NAL9602](INFO): SBD MO Status=0, MOMSN=19732, MT Status=0, MTMSN=0 2025-06-24T23:21:46.548Z,1750807306.548 [NAL9602](INFO): No messages in MT queue 2025-06-24T23:21:54.331Z,1750807314.331 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0123.lzma.bak 2025-06-24T23:21:54.331Z,1750807314.331 [DataOverHttps](INFO): SBD MOMSN=25243952 2025-06-24T23:22:17.323Z,1750807337.323 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T23:22:21.133Z,1750807341.133 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20250624T192950/Express0124.lzma 2025-06-24T23:22:32.176Z,1750807352.176 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0124.lzma.bak 2025-06-24T23:22:32.176Z,1750807352.176 [DataOverHttps](INFO): SBD MOMSN=25243958 2025-06-24T23:22:33.499Z,1750807353.499 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T23:22:33.499Z,1750807353.499 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T23:22:33.499Z,1750807353.499 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T23:27:34.154Z,1750807654.154 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T23:27:34.154Z,1750807654.154 [Default:CheckIn:C.Wait] Stopped 2025-06-24T23:27:34.154Z,1750807654.154 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T23:27:34.154Z,1750807654.154 [Default:CheckIn:D] Running Loop=1 2025-06-24T23:27:34.545Z,1750807654.545 [Default:CheckIn:D] Stopped 2025-06-24T23:27:34.545Z,1750807654.545 [Default:CheckIn:E] Running Loop=1 2025-06-24T23:27:34.951Z,1750807654.951 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 210.472868 min 2025-06-24T23:27:34.951Z,1750807654.951 [Default:CheckIn:E] Stopped 2025-06-24T23:27:34.951Z,1750807654.951 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T23:27:34.951Z,1750807654.951 [Default:CheckIn] Stopped 2025-06-24T23:27:34.951Z,1750807654.951 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T23:27:34.951Z,1750807654.951 [Default:CheckIn](INFO): Running loop #36 2025-06-24T23:27:34.951Z,1750807654.951 [Default:CheckIn] Running Loop=36 2025-06-24T23:27:34.951Z,1750807654.951 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T23:27:34.952Z,1750807654.952 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T23:27:36.964Z,1750807656.964 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232737.00,A,3648.17501,N,12147.27681,W,0.253,358.24,240625,,,A*77 2025-06-24T23:27:36.966Z,1750807656.966 [NAL9602](INFO): GPS fix at 20250624T232737: (36.802917, -121.787947) 2025-06-24T23:27:36.976Z,1750807656.976 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T23:27:36.976Z,1750807656.976 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T23:27:47.425Z,1750807667.425 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0126.lzma 2025-06-24T23:27:58.475Z,1750807678.475 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0126.lzma.bak 2025-06-24T23:27:58.475Z,1750807678.475 [DataOverHttps](INFO): SBD MOMSN=25243968 2025-06-24T23:28:24.617Z,1750807704.617 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250624T192950/Express0127.lzma 2025-06-24T23:28:35.660Z,1750807715.660 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0127.lzma.bak 2025-06-24T23:28:35.660Z,1750807715.660 [DataOverHttps](INFO): SBD MOMSN=25243971 2025-06-24T23:28:36.829Z,1750807716.829 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T23:28:36.830Z,1750807716.830 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T23:28:36.830Z,1750807716.830 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T23:30:24.672Z,1750807824.672 [NAL9602](INFO): SBD MO Status=2, MOMSN=19733, MT Status=2, MTMSN=0 2025-06-24T23:30:24.672Z,1750807824.672 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T23:30:50.128Z,1750807850.128 [NAL9602](INFO): SBD MO Status=0, MOMSN=19733, MT Status=0, MTMSN=0 2025-06-24T23:30:50.128Z,1750807850.128 [NAL9602](INFO): No messages in MT queue 2025-06-24T23:31:16.423Z,1750807876.423 [CBIT](INFO): Clearing failed state for component DropWeight 2025-06-24T23:31:16.423Z,1750807876.423 [DropWeight] No Fault, FailCount= 1 2025-06-24T23:31:20.839Z,1750807880.839 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T23:33:37.400Z,1750808017.400 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T23:33:37.400Z,1750808017.400 [Default:CheckIn:C.Wait] Stopped 2025-06-24T23:33:37.400Z,1750808017.400 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T23:33:37.400Z,1750808017.400 [Default:CheckIn:D] Running Loop=1 2025-06-24T23:33:37.804Z,1750808017.804 [Default:CheckIn:D] Stopped 2025-06-24T23:33:37.804Z,1750808017.804 [Default:CheckIn:E] Running Loop=1 2025-06-24T23:33:38.211Z,1750808018.211 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 216.527197 min 2025-06-24T23:33:38.211Z,1750808018.211 [Default:CheckIn:E] Stopped 2025-06-24T23:33:38.211Z,1750808018.211 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T23:33:38.211Z,1750808018.211 [Default:CheckIn] Stopped 2025-06-24T23:33:38.211Z,1750808018.211 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T23:33:38.212Z,1750808018.212 [Default:CheckIn](INFO): Running loop #37 2025-06-24T23:33:38.212Z,1750808018.212 [Default:CheckIn] Running Loop=37 2025-06-24T23:33:38.212Z,1750808018.212 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T23:33:38.212Z,1750808018.212 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T23:33:40.220Z,1750808020.220 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233341.00,A,3648.16918,N,12147.28021,W,0.894,208.32,240625,,,A*77 2025-06-24T23:33:40.223Z,1750808020.223 [NAL9602](INFO): GPS fix at 20250624T233341: (36.802820, -121.788004) 2025-06-24T23:33:40.241Z,1750808020.241 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T23:33:40.241Z,1750808020.241 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T23:33:50.816Z,1750808030.816 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250624T192950/Courier0129.lzma 2025-06-24T23:34:01.859Z,1750808041.859 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0129.lzma.bak 2025-06-24T23:34:01.859Z,1750808041.859 [DataOverHttps](INFO): SBD MOMSN=25243981 2025-06-24T23:34:27.865Z,1750808067.865 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250624T192950/Express0130.lzma 2025-06-24T23:34:38.907Z,1750808078.907 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0130.lzma.bak 2025-06-24T23:34:38.908Z,1750808078.908 [DataOverHttps](INFO): SBD MOMSN=25243990 2025-06-24T23:34:40.118Z,1750808080.118 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T23:34:40.118Z,1750808080.118 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T23:34:40.118Z,1750808080.118 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T23:38:11.501Z,1750808291.501 [NAL9602](INFO): SBD MO Status=2, MOMSN=19734, MT Status=2, MTMSN=0 2025-06-24T23:38:11.501Z,1750808291.501 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T23:38:42.590Z,1750808322.590 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T23:39:13.292Z,1750808353.292 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T23:39:40.801Z,1750808380.801 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T23:39:40.801Z,1750808380.801 [Default:CheckIn:C.Wait] Stopped 2025-06-24T23:39:40.801Z,1750808380.801 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T23:39:40.802Z,1750808380.802 [Default:CheckIn:D] Running Loop=1 2025-06-24T23:39:41.195Z,1750808381.195 [Default:CheckIn:D] Stopped 2025-06-24T23:39:41.195Z,1750808381.195 [Default:CheckIn:E] Running Loop=1 2025-06-24T23:39:41.592Z,1750808381.592 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 222.583708 min 2025-06-24T23:39:41.592Z,1750808381.592 [Default:CheckIn:E] Stopped 2025-06-24T23:39:41.592Z,1750808381.592 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T23:39:41.592Z,1750808381.592 [Default:CheckIn] Stopped 2025-06-24T23:39:41.592Z,1750808381.592 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T23:39:41.592Z,1750808381.592 [Default:CheckIn](INFO): Running loop #38 2025-06-24T23:39:41.592Z,1750808381.592 [Default:CheckIn] Running Loop=38 2025-06-24T23:39:41.593Z,1750808381.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T23:39:41.593Z,1750808381.593 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T23:39:43.593Z,1750808383.593 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233944.00,A,3648.17755,N,12147.27771,W,0.039,9.11,240625,,,A*7E 2025-06-24T23:39:43.595Z,1750808383.595 [NAL9602](INFO): GPS fix at 20250624T233944: (36.802959, -121.787962) 2025-06-24T23:39:43.644Z,1750808383.644 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T23:39:43.644Z,1750808383.644 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T23:39:52.197Z,1750808392.197 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250624T192950/Courier0132.lzma 2025-06-24T23:40:03.349Z,1750808403.349 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0132.lzma.bak 2025-06-24T23:40:03.349Z,1750808403.349 [DataOverHttps](INFO): SBD MOMSN=25243999 2025-06-24T23:40:14.295Z,1750808414.295 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T23:40:14.369Z,1750808414.369 [NAL9602](ERROR): received: +CSQ:0 OK734, 2, 0, 0, 0 OK 2025-06-24T23:40:29.230Z,1750808429.230 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20250624T192950/Express0133.lzma 2025-06-24T23:40:40.267Z,1750808440.267 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0133.lzma.bak 2025-06-24T23:40:40.268Z,1750808440.268 [DataOverHttps](INFO): SBD MOMSN=25244002 2025-06-24T23:40:41.392Z,1750808441.392 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T23:40:41.392Z,1750808441.392 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T23:40:41.393Z,1750808441.393 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T23:40:51.064Z,1750808451.064 [NAL9602](INFO): SBD MO Status=2, MOMSN=19734, MT Status=2, MTMSN=0 2025-06-24T23:40:51.064Z,1750808451.064 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T23:41:38.334Z,1750808498.334 [NAL9602](INFO): SBD MO Status=2, MOMSN=19734, MT Status=2, MTMSN=0 2025-06-24T23:41:38.334Z,1750808498.334 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T23:42:13.884Z,1750808533.884 [NAL9602](INFO): SBD MO Status=2, MOMSN=19734, MT Status=2, MTMSN=0 2025-06-24T23:42:13.884Z,1750808533.884 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-06-24T23:44:45.442Z,1750808685.442 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T23:44:53.157Z,1750808693.157 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-06-24T23:44:57.584Z,1750808697.584 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.006641 CHAN A1 (24V): 0.000466 CHAN A2 (12V): -0.005731 CHAN A3 (5V): -0.002872 CHAN B0 (3.3V): -0.002003 CHAN B1 (3.15aV): -0.001481 CHAN B2 (3.15bV): -0.001629 CHAN B3 (GND): -0.000495 OPEN: 0.004682 Full Scale: +/- 1 mA 2025-06-24T23:45:16.139Z,1750808716.139 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T23:45:42.014Z,1750808742.014 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T23:45:42.014Z,1750808742.014 [Default:CheckIn:C.Wait] Stopped 2025-06-24T23:45:42.014Z,1750808742.014 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T23:45:42.014Z,1750808742.014 [Default:CheckIn:D] Running Loop=1 2025-06-24T23:45:42.418Z,1750808742.418 [Default:CheckIn:D] Stopped 2025-06-24T23:45:42.418Z,1750808742.418 [Default:CheckIn:E] Running Loop=1 2025-06-24T23:45:42.828Z,1750808742.828 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 228.604102 min 2025-06-24T23:45:42.828Z,1750808742.828 [Default:CheckIn:E] Stopped 2025-06-24T23:45:42.828Z,1750808742.828 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T23:45:42.828Z,1750808742.828 [Default:CheckIn] Stopped 2025-06-24T23:45:42.828Z,1750808742.828 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T23:45:42.828Z,1750808742.828 [Default:CheckIn](INFO): Running loop #39 2025-06-24T23:45:42.828Z,1750808742.828 [Default:CheckIn] Running Loop=39 2025-06-24T23:45:42.828Z,1750808742.828 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T23:45:42.829Z,1750808742.829 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T23:46:15.531Z,1750808775.531 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-06-24T23:46:15.605Z,1750808775.605 [NAL9602](ERROR): received: +CSQ:0 OK734, 2, 0, 0, 0 OK 2025-06-24T23:50:43.008Z,1750809043.008 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-24T23:45:42.8Z 2025-06-24T23:50:43.008Z,1750809043.008 [Default:CheckIn:Read_GPS] Stopped 2025-06-24T23:50:43.009Z,1750809043.009 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-24T23:50:46.639Z,1750809046.639 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-24T23:50:50.326Z,1750809050.326 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250624T192950/Courier0135.lzma 2025-06-24T23:51:01.355Z,1750809061.355 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0135.lzma.bak 2025-06-24T23:51:01.355Z,1750809061.355 [DataOverHttps](INFO): SBD MOMSN=25244018 2025-06-24T23:51:17.340Z,1750809077.340 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-24T23:51:27.168Z,1750809087.168 [DataOverHttps](INFO): Sending 402 bytes from file Logs/20250624T192950/Express0136.lzma 2025-06-24T23:51:38.207Z,1750809098.207 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0136.lzma.bak 2025-06-24T23:51:38.207Z,1750809098.207 [DataOverHttps](INFO): SBD MOMSN=25244020 2025-06-24T23:51:39.584Z,1750809099.584 [Default:CheckIn:Read_Iridium] Stopped 2025-06-24T23:51:39.584Z,1750809099.584 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-24T23:51:39.584Z,1750809099.584 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-24T23:56:40.196Z,1750809400.195 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-24T23:56:40.196Z,1750809400.196 [Default:CheckIn:C.Wait] Stopped 2025-06-24T23:56:40.196Z,1750809400.196 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-24T23:56:40.196Z,1750809400.196 [Default:CheckIn:D] Running Loop=1 2025-06-24T23:56:40.605Z,1750809400.605 [Default:CheckIn:D] Stopped 2025-06-24T23:56:40.605Z,1750809400.605 [Default:CheckIn:E] Running Loop=1 2025-06-24T23:56:41.019Z,1750809401.019 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 239.573877 min 2025-06-24T23:56:41.019Z,1750809401.019 [Default:CheckIn:E] Stopped 2025-06-24T23:56:41.019Z,1750809401.019 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-24T23:56:41.020Z,1750809401.020 [Default:CheckIn] Stopped 2025-06-24T23:56:41.020Z,1750809401.020 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-24T23:56:41.020Z,1750809401.020 [Default:CheckIn](INFO): Running loop #40 2025-06-24T23:56:41.020Z,1750809401.020 [Default:CheckIn] Running Loop=40 2025-06-24T23:56:41.020Z,1750809401.020 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-24T23:56:41.020Z,1750809401.020 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-24T23:56:42.611Z,1750809402.611 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-06-24T23:56:42.611Z,1750809402.611 [NAL9602] Data Fault, FailCount= 1 2025-06-24T23:56:42.611Z,1750809402.611 [NAL9602](ERROR): Data Fault 2025-06-24T23:56:42.658Z,1750809402.658 [CBIT](ERROR): Data Fault in component: NAL9602 2025-06-24T23:56:43.010Z,1750809403.010 [NAL9602](INFO): Powering down 2025-06-24T23:56:43.848Z,1750809403.848 [CBIT](INFO): Clearing failed state for component NAL9602 2025-06-24T23:56:43.848Z,1750809403.848 [NAL9602] No Fault, FailCount= 1 2025-06-24T23:57:13.314Z,1750809433.314 [NAL9602](INFO): Powering up NAL9602 2025-06-24T23:57:24.219Z,1750809444.219 [NAL9602](INFO): NAL9602 initialized 2025-06-25T00:01:17.742Z,1750809677.742 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-06-25T00:01:17.742Z,1750809677.742 [DropWeight] Hardware Fault, FailCount= 1 2025-06-25T00:01:17.742Z,1750809677.742 [DropWeight](ERROR): Hardware Fault 2025-06-25T00:01:17.788Z,1750809677.788 [CBIT](INFO): Critical error at 20250625T000117 2025-06-25T00:01:17.795Z,1750809677.795 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-06-25T00:01:17.795Z,1750809677.795 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-06-25T00:01:18.160Z,1750809678.160 [CBIT](INFO): Critical error at 20250625T000117 2025-06-25T00:01:41.215Z,1750809701.215 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-24T23:56:41.0Z 2025-06-25T00:01:41.216Z,1750809701.216 [Default:CheckIn:Read_GPS] Stopped 2025-06-25T00:01:41.216Z,1750809701.216 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-25T00:01:51.409Z,1750809711.409 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20250624T192950/Courier0138.lzma 2025-06-25T00:02:02.451Z,1750809722.451 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0138.lzma.bak 2025-06-25T00:02:02.451Z,1750809722.451 [DataOverHttps](INFO): SBD MOMSN=25244038 2025-06-25T00:02:27.295Z,1750809747.295 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-25T00:02:28.473Z,1750809748.473 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250624T192950/Express0139.lzma 2025-06-25T00:02:39.515Z,1750809759.515 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0139.lzma.bak 2025-06-25T00:02:39.516Z,1750809759.516 [DataOverHttps](INFO): SBD MOMSN=25244042 2025-06-25T00:02:40.647Z,1750809760.647 [Default:CheckIn:Read_Iridium] Stopped 2025-06-25T00:02:40.648Z,1750809760.648 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-25T00:02:40.648Z,1750809760.648 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-25T00:02:58.006Z,1750809778.006 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-25T00:07:41.307Z,1750810061.307 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-06-25T00:07:41.307Z,1750810061.307 [Default:CheckIn:C.Wait] Stopped 2025-06-25T00:07:41.307Z,1750810061.307 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-25T00:07:41.308Z,1750810061.308 [Default:CheckIn:D] Running Loop=1 2025-06-25T00:07:41.717Z,1750810061.717 [Default:CheckIn:D] Stopped 2025-06-25T00:07:41.717Z,1750810061.717 [Default:CheckIn:E] Running Loop=1 2025-06-25T00:07:42.116Z,1750810062.116 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 250.592415 min 2025-06-25T00:07:42.116Z,1750810062.116 [Default:CheckIn:E] Stopped 2025-06-25T00:07:42.117Z,1750810062.117 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-06-25T00:07:42.117Z,1750810062.117 [Default:CheckIn] Stopped 2025-06-25T00:07:42.117Z,1750810062.117 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-25T00:07:42.117Z,1750810062.117 [Default:CheckIn](INFO): Running loop #41 2025-06-25T00:07:42.117Z,1750810062.117 [Default:CheckIn] Running Loop=41 2025-06-25T00:07:42.117Z,1750810062.117 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-06-25T00:07:42.117Z,1750810062.117 [Default:CheckIn:Read_GPS] Running Loop=1 2025-06-25T00:07:43.713Z,1750810063.713 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-06-25T00:07:43.713Z,1750810063.713 [NAL9602] Data Fault, FailCount= 2 2025-06-25T00:07:43.713Z,1750810063.713 [NAL9602](ERROR): Data Fault 2025-06-25T00:07:43.749Z,1750810063.749 [CBIT](ERROR): Data Fault in component: NAL9602 2025-06-25T00:07:44.135Z,1750810064.135 [NAL9602](INFO): Powering down 2025-06-25T00:07:45.091Z,1750810065.091 [CBIT](INFO): Clearing failed state for component NAL9602 2025-06-25T00:07:45.091Z,1750810065.091 [NAL9602] No Fault, FailCount= 2 2025-06-25T00:08:14.422Z,1750810094.422 [NAL9602](INFO): Powering up NAL9602 2025-06-25T00:08:25.447Z,1750810105.447 [NAL9602](INFO): NAL9602 initialized 2025-06-25T00:12:42.435Z,1750810362.435 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-25T00:07:42.1Z 2025-06-25T00:12:42.435Z,1750810362.435 [Default:CheckIn:Read_GPS] Stopped 2025-06-25T00:12:42.435Z,1750810362.435 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-06-25T00:12:49.912Z,1750810369.912 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250624T192950/Courier0141.lzma 2025-06-25T00:13:00.955Z,1750810380.955 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Courier0141.lzma.bak 2025-06-25T00:13:00.955Z,1750810380.955 [DataOverHttps](INFO): SBD MOMSN=25244059 2025-06-25T00:13:08.605Z,1750810388.605 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-06-25T00:13:22.285Z,1750810402.285 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2025-06-25T00:13:22.288Z,1750810402.288 [BPC1](INFO): Received data from all battery sticks. 2025-06-25T00:13:26.857Z,1750810406.857 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250624T192950/Express0142.lzma 2025-06-25T00:13:28.338Z,1750810408.338 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-06-25T00:13:37.900Z,1750810417.900 [DataOverHttps](INFO): Moved sent file to Logs/20250624T192950/Express0142.lzma.bak 2025-06-25T00:13:37.900Z,1750810417.900 [DataOverHttps](INFO): SBD MOMSN=25244061 2025-06-25T00:13:39.274Z,1750810419.274 [Default:CheckIn:Read_Iridium] Stopped 2025-06-25T00:13:39.274Z,1750810419.274 [Default:CheckIn:C.Wait] Running Loop=1 2025-06-25T00:13:39.274Z,1750810419.274 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-06-25T00:13:59.090Z,1750810439.090 [NAL9602](INFO): Not Powering down - fast GPS 2025-06-25T00:16:18.156Z,1750810578.156 [CBIT](INFO): Clearing failed state for component DropWeight 2025-06-25T00:16:18.156Z,1750810578.156 [DropWeight] No Fault, FailCount= 1 2025-06-25T00:18:12.650Z,1750810692.650 [CommandExec](IMPORTANT): got command quit 2025-06-25T00:18:13.653Z,1750810693.653 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-25T00:18:13.653Z,1750810693.653 [CommandExec](INFO): Uninitializing the command executive. 2025-06-25T00:18:13.654Z,1750810693.654 [CommandExec](INFO): Uninitializing the command scheduler. 2025-06-25T00:18:13.654Z,1750810693.654 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:13.657Z,1750810693.657 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-06-25T00:18:13.657Z,1750810693.657 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-06-25T00:18:13.657Z,1750810693.657 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:13.658Z,1750810693.658 [NavChartDb](INFO): Join timeout helper Thread ID is 2606 2025-06-25T00:18:13.785Z,1750810693.785 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-25T00:18:13.785Z,1750810693.785 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:13.805Z,1750810693.805 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-06-25T00:18:13.805Z,1750810693.805 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:13.806Z,1750810693.806 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2607 2025-06-25T00:18:14.245Z,1750810694.245 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-25T00:18:14.245Z,1750810694.245 [WetLabsBB2FL](INFO): Powering down 2025-06-25T00:18:14.246Z,1750810694.246 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:14.249Z,1750810694.249 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-06-25T00:18:14.249Z,1750810694.249 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:14.249Z,1750810694.249 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2608 2025-06-25T00:18:14.737Z,1750810694.737 [CTD_Seabird](INFO): Powering down 2025-06-25T00:18:14.749Z,1750810694.749 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-25T00:18:14.749Z,1750810694.749 [CTD_Seabird](INFO): Powering down 2025-06-25T00:18:14.761Z,1750810694.761 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:14.761Z,1750810694.761 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-06-25T00:18:14.761Z,1750810694.761 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:14.762Z,1750810694.762 [Radio_Surface](INFO): Join timeout helper Thread ID is 2609 2025-06-25T00:18:15.002Z,1750810695.002 [Radio_Surface](INFO): Powering down 2025-06-25T00:18:15.003Z,1750810695.003 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-25T00:18:15.003Z,1750810695.003 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:15.009Z,1750810695.009 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-06-25T00:18:15.009Z,1750810695.009 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:15.009Z,1750810695.009 [Onboard](INFO): Join timeout helper Thread ID is 2610 2025-06-25T00:18:15.805Z,1750810695.805 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-06-25T00:18:18.193Z,1750810698.193 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-25T00:18:18.193Z,1750810698.193 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:18.202Z,1750810698.202 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-06-25T00:18:18.202Z,1750810698.202 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:18.203Z,1750810698.203 [DataOverHttps](INFO): Join timeout helper Thread ID is 2611 2025-06-25T00:18:18.817Z,1750810698.817 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-25T00:18:18.817Z,1750810698.817 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:18.822Z,1750810698.822 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-06-25T00:18:18.822Z,1750810698.822 [DAT ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:18.822Z,1750810698.822 [DAT](INFO): Join timeout helper Thread ID is 2612 2025-06-25T00:18:18.865Z,1750810698.865 [DAT](INFO): Powering down 2025-06-25T00:18:18.938Z,1750810698.938 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-25T00:18:18.938Z,1750810698.938 [DAT](INFO): Powering down 2025-06-25T00:18:18.939Z,1750810698.939 [DAT ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:18.946Z,1750810698.946 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-06-25T00:18:18.946Z,1750810698.946 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:18.946Z,1750810698.946 [BackseatComponent](INFO): Join timeout helper Thread ID is 2613 2025-06-25T00:18:19.121Z,1750810699.121 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-25T00:18:19.121Z,1750810699.121 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.138Z,1750810699.138 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-06-25T00:18:19.138Z,1750810699.138 [logger ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.138Z,1750810699.138 [logger](INFO): Join timeout helper Thread ID is 2614 2025-06-25T00:18:19.169Z,1750810699.169 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-25T00:18:19.169Z,1750810699.169 [logger ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.178Z,1750810699.178 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-06-25T00:18:19.178Z,1750810699.178 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.178Z,1750810699.178 [CommandLine](INFO): Join timeout helper Thread ID is 2615 2025-06-25T00:18:19.269Z,1750810699.269 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-25T00:18:19.270Z,1750810699.270 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.274Z,1750810699.274 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-06-25T00:18:19.274Z,1750810699.274 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.274Z,1750810699.274 [CommandExec](INFO): Join timeout helper Thread ID is 2616 2025-06-25T00:18:19.288Z,1750810699.288 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-06-25T00:18:19.288Z,1750810699.288 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.289Z,1750810699.289 [controlThread](INFO): Join timeout helper Thread ID is 2617 2025-06-25T00:18:19.290Z,1750810699.290 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-06-25T00:18:19.291Z,1750810699.291 [controlThread](DEBUG): Uninitializing ControlThread 2025-06-25T00:18:19.291Z,1750810699.291 [AHRS_M2](INFO): Powering down 2025-06-25T00:18:19.369Z,1750810699.369 [MultiRay](INFO): Powering down LCB2 2025-06-25T00:18:19.370Z,1750810699.370 [MultiRay](INFO): Powering down. 2025-06-25T00:18:19.371Z,1750810699.371 [NAL9602](INFO): Powering down 2025-06-25T00:18:19.373Z,1750810699.373 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-06-25T00:18:19.374Z,1750810699.374 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-06-25T00:18:19.374Z,1750810699.374 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-06-25T00:18:19.375Z,1750810699.375 [MissionManager](INFO): Uninitializing Mission Default 2025-06-25T00:18:19.375Z,1750810699.375 [Default] Stopped 2025-06-25T00:18:19.375Z,1750810699.375 [Default](DEBUG): Aggregate::uninitialize Default 2025-06-25T00:18:19.375Z,1750810699.375 [Default:B.GoToSurface] Stopped 2025-06-25T00:18:19.375Z,1750810699.375 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-06-25T00:18:19.375Z,1750810699.375 [Default:CheckIn] Stopped 2025-06-25T00:18:19.375Z,1750810699.375 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-06-25T00:18:19.376Z,1750810699.376 [Default:CheckIn:C.Wait] Stopped 2025-06-25T00:18:19.376Z,1750810699.376 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-06-25T00:18:19.379Z,1750810699.379 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-06-25T00:18:19.379Z,1750810699.379 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-06-25T00:18:19.379Z,1750810699.379 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-06-25T00:18:19.380Z,1750810699.380 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-06-25T00:18:19.380Z,1750810699.380 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-06-25T00:18:19.380Z,1750810699.380 [BuoyancyServo](INFO): Powering down 2025-06-25T00:18:19.393Z,1750810699.393 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-06-25T00:18:19.393Z,1750810699.393 [ElevatorServo](INFO): Powering down 2025-06-25T00:18:19.394Z,1750810699.394 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-06-25T00:18:19.394Z,1750810699.394 [MassServo](INFO): Powering down 2025-06-25T00:18:19.395Z,1750810699.395 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-06-25T00:18:19.395Z,1750810699.395 [RudderServo](INFO): Powering down 2025-06-25T00:18:19.396Z,1750810699.396 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-06-25T00:18:19.396Z,1750810699.396 [ThrusterHE](INFO): Powering down 2025-06-25T00:18:19.397Z,1750810699.397 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-06-25T00:18:19.397Z,1750810699.397 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-06-25T00:18:19.398Z,1750810699.398 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-06-25T00:18:19.398Z,1750810699.398 [CBIT](DEBUG): Powering off loads. 2025-06-25T00:18:19.409Z,1750810699.409 [CBIT](DEBUG): Disabling WDT. 2025-06-25T00:18:19.421Z,1750810699.421 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-06-25T00:18:19.421Z,1750810699.421 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-06-25T00:18:19.422Z,1750810699.422 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.449Z,1750810699.449 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.454Z,1750810699.454 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.461Z,1750810699.461 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.468Z,1750810699.468 [DAT ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.509Z,1750810699.509 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.553Z,1750810699.553 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-06-25T00:18:19.553Z,1750810699.553 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-06-25T00:18:19.568Z,1750810699.568 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.573Z,1750810699.573 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.628Z,1750810699.628 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-06-25T00:18:19.679Z,1750810699.679 [logger ThreadHandler](INFO): Thread cancelled.