2025-03-26T22:50:09.459Z,1743029409.459 [DataOverHttps](INFO): Received command: restart logs 2025-03-26T22:50:09.461Z,1743029409.461 [CommandExec](IMPORTANT): got command restart logs 2025-03-26T22:52:10.654Z,1743029530.654 [transit:StandardEnvelopes:A.AltitudeEnvelope](IMPORTANT): Altitude envelope ACTIVE 2025-03-26T22:52:16.171Z,1743029536.171 [ThrusterHE](FAULT): Could not get status:serial timeout 2025-03-26T22:52:16.260Z,1743029536.260 [ThrusterHE](FAULT): getSpeed error serial timeout 2025-03-26T22:52:16.260Z,1743029536.260 [ThrusterHE](FAULT): Thruster uart error: serial timeout 2025-03-26T22:52:16.260Z,1743029536.260 [ThrusterHE] Communications Fault, FailCount= 1 2025-03-26T22:52:16.260Z,1743029536.260 [ThrusterHE](ERROR): Communications Fault 2025-03-26T22:52:16.264Z,1743029536.264 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-03-26T22:52:16.634Z,1743029536.634 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-03-26T22:52:16.634Z,1743029536.634 [ThrusterHE](INFO): Powering down 2025-03-26T22:52:17.400Z,1743029537.400 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-03-26T22:52:17.520Z,1743029537.520 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-03-26T22:52:17.524Z,1743029537.524 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-03-26T22:52:17.524Z,1743029537.524 [ThrusterHE] No Fault, FailCount= 1 2025-03-26T22:52:24.699Z,1743029544.699 [ThrusterHE](FAULT): Could not get status:serial timeout 2025-03-26T22:52:24.799Z,1743029544.799 [ThrusterHE](FAULT): getSpeed error serial timeout 2025-03-26T22:52:24.799Z,1743029544.799 [ThrusterHE](FAULT): Thruster uart error: serial timeout 2025-03-26T22:52:24.799Z,1743029544.799 [ThrusterHE] Communications Fault, FailCount= 2 2025-03-26T22:52:24.799Z,1743029544.799 [ThrusterHE](ERROR): Communications Fault 2025-03-26T22:52:24.803Z,1743029544.803 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-03-26T22:52:25.023Z,1743029545.023 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-03-26T22:52:25.023Z,1743029545.023 [ThrusterHE](INFO): Powering down 2025-03-26T22:52:25.905Z,1743029545.905 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-03-26T22:52:26.025Z,1743029546.025 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-03-26T22:52:26.029Z,1743029546.029 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-03-26T22:52:26.029Z,1743029546.029 [ThrusterHE] No Fault, FailCount= 2 2025-03-26T22:54:08.436Z,1743029648.436 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.254112 2025-03-26T22:54:52.745Z,1743029692.745 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004398 2025-03-26T22:55:09.997Z,1743029709.997 [transit:NeedComms] Running Loop=1 2025-03-26T22:55:09.997Z,1743029709.997 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms 2025-03-26T22:55:09.997Z,1743029709.997 [transit:NeedComms:B.GoToSurface] Running Loop=1 2025-03-26T22:55:09.997Z,1743029709.997 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-26T22:55:09.998Z,1743029709.998 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-03-26T22:55:09.998Z,1743029709.998 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-03-26T22:55:09.999Z,1743029709.999 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-03-26T22:55:09.999Z,1743029709.999 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-03-26T22:55:09.000Z,1743029710.000 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-26T22:55:09.000Z,1743029710.000 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-26T22:55:10.001Z,1743029710.001 [transit:NeedComms:A] Running Loop=1 2025-03-26T22:55:10.005Z,1743029710.005 [transit:NeedComms:A](INFO): last time_fix was: 1743028989.000000 second since 1970/01/01T00:00:00Z 2025-03-26T22:55:10.005Z,1743029710.005 [transit:NeedComms:A] Stopped 2025-03-26T22:55:10.425Z,1743029710.425 [transit:NeedComms:C] Running Loop=1 2025-03-26T22:55:10.858Z,1743029710.858 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-03-26T22:55:12.403Z,1743029712.403 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225511.00,A,3648.33448,N,12147.63027,W,1.458,252.37,260325,,,A*75 2025-03-26T22:55:12.405Z,1743029712.405 [NAL9602](INFO): GPS fix at 20250326T225511: (36.805575, -121.793838) 2025-03-26T22:55:12.468Z,1743029712.468 [transit:NeedComms:C] Stopped 2025-03-26T22:55:12.468Z,1743029712.468 [transit:NeedComms:D] Running Loop=1 2025-03-26T22:55:12.873Z,1743029712.873 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-03-26T22:55:22.939Z,1743029722.939 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250326T192539/Courier0121.lzma 2025-03-26T22:55:23.941Z,1743029723.941 [DataOverHttps](INFO): Moved sent file to Logs/20250326T192539/Courier0121.lzma.bak 2025-03-26T22:55:23.942Z,1743029723.942 [DataOverHttps](INFO): SBD MOMSN=24462874 2025-03-26T22:55:30.994Z,1743029730.994 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2025-03-26T22:55:30.997Z,1743029730.997 [BPC1](INFO): Received data from all battery sticks. 2025-03-26T22:55:44.458Z,1743029744.458 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20250326T225009/Courier0000.lzma 2025-03-26T22:55:45.453Z,1743029745.453 [DataOverHttps](INFO): Moved sent file to Logs/20250326T225009/Courier0000.lzma.bak 2025-03-26T22:55:45.453Z,1743029745.453 [DataOverHttps](INFO): SBD MOMSN=24462876 2025-03-26T22:56:02.265Z,1743029762.265 [DataOverHttps](INFO): Sending 650 bytes from file Logs/20250326T192539/Express0122.lzma 2025-03-26T22:56:03.265Z,1743029763.265 [DataOverHttps](INFO): Moved sent file to Logs/20250326T192539/Express0122.lzma.bak 2025-03-26T22:56:03.266Z,1743029763.266 [DataOverHttps](INFO): SBD MOMSN=24462880 2025-03-26T22:56:12.703Z,1743029772.703 [transit:NeedComms:D](INFO): Timed out from 2025-03-26T22:55:12.5Z 2025-03-26T22:56:12.703Z,1743029772.703 [transit:NeedComms:D:A_Timeout] Running Loop=1 2025-03-26T22:56:12.703Z,1743029772.703 [transit:NeedComms:D:A_Timeout](DEBUG): Aggregate::initialize transit:NeedComms:D:A_Timeout 2025-03-26T22:56:12.704Z,1743029772.704 [transit:NeedComms:D:A_Timeout:A] Running Loop=1 2025-03-26T22:56:12.704Z,1743029772.704 [transit:NeedComms:D:A_Timeout:A](IMPORTANT): Comms timed out after 1.000000 min minutes 2025-03-26T22:56:12.704Z,1743029772.704 [transit:NeedComms:D:A_Timeout:A] Stopped 2025-03-26T22:56:12.704Z,1743029772.704 [transit:NeedComms:D:A_Timeout](INFO): Completed transit:NeedComms:D:A_Timeout 2025-03-26T22:56:12.704Z,1743029772.704 [transit:NeedComms:D] Stopped 2025-03-26T22:56:12.705Z,1743029772.705 [transit:NeedComms:E] Running Loop=1 2025-03-26T22:56:13.155Z,1743029773.155 [transit:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-03-26T22:56:20.377Z,1743029780.377 [DataOverHttps](INFO): Sending 568 bytes from file Logs/20250326T225009/Express0001.lzma 2025-03-26T22:56:21.382Z,1743029781.382 [DataOverHttps](INFO): Moved sent file to Logs/20250326T225009/Express0001.lzma.bak 2025-03-26T22:56:21.382Z,1743029781.382 [DataOverHttps](INFO): SBD MOMSN=24462906 2025-03-26T22:59:53.554Z,1743029993.554 [DataOverHttps](IMPORTANT): SBD MTMSN=20250326T225952 2025-03-26T23:00:01.567Z,1743030001.567 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 15 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 2 m;set transit.Speed 1.2 m/s" 3syj2 1 2 2025-03-26T23:00:01.584Z,1743030001.584 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 15 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 2 m;set transit.Speed 1.2 m/s" 3syj2 1 2.000000 2025-03-26T23:00:01.585Z,1743030001.585 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=3syj2 2025-03-26T23:00:01.586Z,1743030001.586 [CommandExec](IMPORTANT): Scheduled #5 (#1 of 2 with id='3syj2'): "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 15 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 2 m;set transit.Speed 1.2 m/s", ASAP 2025-03-26T23:00:02.569Z,1743030002.569 [DataOverHttps](IMPORTANT): SBD MTMSN=20250326T230001 2025-03-26T23:00:10.655Z,1743030010.655 [DataOverHttps](INFO): Received command: sched asap "set transit.MaxDepth 5 m;set transit.MinOffshore 0.0001 km;set transit:NeedComms.CommsTimeout 1 min;run" 3syj2 2 2 2025-03-26T23:00:10.663Z,1743030010.663 [CommandExec](IMPORTANT): got command schedule asap "set transit.MaxDepth 5 m;set transit.MinOffshore 0.0001 km;set transit:NeedComms.CommsTimeout 1 min;run" 3syj2 2 2.000000 2025-03-26T23:00:10.663Z,1743030010.663 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=3syj2 2025-03-26T23:00:10.664Z,1743030010.664 [CommandExec](IMPORTANT): Scheduled #6 (#2 of 2 with id='3syj2'): "set transit.MaxDepth 5 m;set transit.MinOffshore 0.0001 km;set transit:NeedComms.CommsTimeout 1 min;run", ASAP 2025-03-26T23:00:10.786Z,1743030010.786 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl 2025-03-26T23:00:10.787Z,1743030010.787 [MissionManager](INFO): Uninitializing Mission transit 2025-03-26T23:00:10.787Z,1743030010.787 [transit] Stopped 2025-03-26T23:00:10.788Z,1743030010.788 [transit](DEBUG): Aggregate::uninitialize transit 2025-03-26T23:00:10.788Z,1743030010.788 [transit:NeedComms] Stopped 2025-03-26T23:00:10.788Z,1743030010.788 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms 2025-03-26T23:00:10.788Z,1743030010.788 [transit:NeedComms:B.GoToSurface] Stopped 2025-03-26T23:00:10.788Z,1743030010.788 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-26T23:00:10.788Z,1743030010.788 [transit:NeedComms:E] Stopped 2025-03-26T23:00:10.788Z,1743030010.788 [transit:StandardEnvelopes] Stopped 2025-03-26T23:00:10.789Z,1743030010.789 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2025-03-26T23:00:10.789Z,1743030010.789 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-03-26T23:00:10.789Z,1743030010.789 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-03-26T23:00:10.789Z,1743030010.789 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-03-26T23:00:10.789Z,1743030010.789 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-03-26T23:00:10.789Z,1743030010.789 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-03-26T23:00:10.789Z,1743030010.789 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-03-26T23:00:10.790Z,1743030010.790 [transit:BackseatDriver] Stopped 2025-03-26T23:00:10.790Z,1743030010.790 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver 2025-03-26T23:00:10.790Z,1743030010.790 [transit:BackseatDriver:A.BackseatDriver] Stopped 2025-03-26T23:00:10.790Z,1743030010.790 [transit:PowerOnly] Stopped 2025-03-26T23:00:10.790Z,1743030010.790 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly 2025-03-26T23:00:10.790Z,1743030010.790 [transit:PowerOnly:E.Wait] Stopped 2025-03-26T23:00:10.790Z,1743030010.790 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-03-26T23:00:10.791Z,1743030010.791 [transit:BuoyancyHold.Buoyancy] Stopped 2025-03-26T23:00:10.791Z,1743030010.791 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-03-26T23:00:10.791Z,1743030010.791 [transit:MassHold.Pitch] Stopped 2025-03-26T23:00:10.791Z,1743030010.791 [transit:Transit] Stopped 2025-03-26T23:00:10.792Z,1743030010.792 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2025-03-26T23:00:10.792Z,1743030010.792 [transit:Transit:A.Pitch] Stopped 2025-03-26T23:00:10.792Z,1743030010.792 [transit:Transit:B.SetSpeed] Stopped 2025-03-26T23:00:10.792Z,1743030010.792 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2025-03-26T23:00:10.792Z,1743030010.792 [transit:Transit:Wpt1.Waypoint] Stopped 2025-03-26T23:00:10.792Z,1743030010.792 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-03-26T23:00:10.864Z,1743030010.864 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl 2025-03-26T23:00:10.864Z,1743030010.864 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl 2025-03-26T23:00:10.866Z,1743030010.866 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl 2025-03-26T23:00:11.725Z,1743030011.725 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into: 60 30 NaN NaN 0 0 10 1 20 1 7 1 2025-03-26T23:00:11.732Z,1743030011.732 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min 2025-03-26T23:00:11.734Z,1743030011.734 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min 2025-03-26T23:00:11.737Z,1743030011.737 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg 2025-03-26T23:00:11.739Z,1743030011.739 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg 2025-03-26T23:00:11.741Z,1743030011.741 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m 2025-03-26T23:00:11.743Z,1743030011.743 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m 2025-03-26T23:00:11.746Z,1743030011.746 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m 2025-03-26T23:00:11.748Z,1743030011.748 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s 2025-03-26T23:00:11.788Z,1743030011.788 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m 2025-03-26T23:00:11.790Z,1743030011.790 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km 2025-03-26T23:00:11.797Z,1743030011.797 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m 2025-03-26T23:00:11.799Z,1743030011.799 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool 2025-03-26T23:00:11.809Z,1743030011.809 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool 2025-03-26T23:00:11.809Z,1743030011.809 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-03-26T23:00:11.810Z,1743030011.810 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-03-26T23:00:11.811Z,1743030011.811 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-03-26T23:00:12.161Z,1743030012.161 [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-03-26T23:00:12.166Z,1743030012.166 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h 2025-03-26T23:00:12.169Z,1743030012.169 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min 2025-03-26T23:00:12.171Z,1743030012.171 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-03-26T23:00:12.173Z,1743030012.173 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s 2025-03-26T23:00:12.200Z,1743030012.200 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-03-26T23:00:12.202Z,1743030012.202 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s 2025-03-26T23:00:12.212Z,1743030012.212 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min 2025-03-26T23:00:12.214Z,1743030012.214 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min 2025-03-26T23:00:12.277Z,1743030012.277 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-03-26T23:00:12.339Z,1743030012.339 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-03-26T23:00:12.340Z,1743030012.340 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-03-26T23:00:12.341Z,1743030012.341 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-03-26T23:00:12.426Z,1743030012.426 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2025-03-26T23:00:12.430Z,1743030012.430 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m 2025-03-26T23:00:12.432Z,1743030012.432 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2025-03-26T23:00:12.434Z,1743030012.434 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m 2025-03-26T23:00:12.443Z,1743030012.443 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-03-26T23:00:12.444Z,1743030012.444 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-03-26T23:00:12.450Z,1743030012.450 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-03-26T23:00:12.458Z,1743030012.458 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-03-26T23:00:12.473Z,1743030012.473 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-03-26T23:00:12.473Z,1743030012.473 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-03-26T23:00:12.474Z,1743030012.474 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-03-26T23:00:12.533Z,1743030012.533 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-03-26T23:00:12.538Z,1743030012.538 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool 2025-03-26T23:00:12.541Z,1743030012.541 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-03-26T23:00:12.543Z,1743030012.543 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-03-26T23:00:12.543Z,1743030012.543 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-03-26T23:00:12.544Z,1743030012.544 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-03-26T23:00:12.790Z,1743030012.790 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-03-26T23:00:12.793Z,1743030012.793 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool 2025-03-26T23:00:12.796Z,1743030012.796 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool 2025-03-26T23:00:12.797Z,1743030012.797 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool 2025-03-26T23:00:12.820Z,1743030012.820 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool 2025-03-26T23:00:12.822Z,1743030012.822 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 1 bool 2025-03-26T23:00:12.829Z,1743030012.829 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-03-26T23:00:12.831Z,1743030012.831 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2025-03-26T23:00:12.834Z,1743030012.834 [transit:MassHold.Pitch](DEBUG): Construct. 2025-03-26T23:00:12.840Z,1743030012.840 [transit:Transit:A.Pitch](DEBUG): Construct. 2025-03-26T23:00:12.844Z,1743030012.844 [transit:Transit:B.SetSpeed](DEBUG): Construct. 2025-03-26T23:00:12.846Z,1743030012.846 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-03-26T23:00:12.883Z,1743030012.883 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission transit { """ Vehicle transits to desired waypoint and can be commanded to use/hold mass or buoyancy. """ arguments { MissionTimeout = 60 minute """ Maximum duration of mission """ NeedCommsTime = 30 minute """ How often to surface for commumications """ Latitude = NaN degree """ Latitude of waypoint to seek. If set to NaN, uses latitude at mission initialization. """ Longitude = NaN degree """ Longitude of waypoint to seek. If set to NaN, uses longitude at mission initialization. """ Northings = 0 meter """ Northward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Eastings = 0 meter """ Eastward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Depth = 10 meter """ Depth of flat and level flight during the mission. """ Speed = 1 meter_per_second """ Speed of vehicle (relative to water) during the mission. """ MaxDepth = 20 meter """ Maximum depth for the entire mission. """ MinOffshore = 1 kilometer """ Minimum distance offshore for the entire mission. """ MinAltitude = 7 meter """ Minimum height above the sea floor for the entire mission. """ MassHold = true """ Set to True in order to hold mass at default position, False to allow mass to run on its own. """ BuoyancyHold = true """ Set to True in order to hold buoyancy at neutral position, False to allow buoyancy to run on its own. """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime assign in sequence NeedComms:WaitForPitchUp = 1 second insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl behavior Guidance:Buoyancy id="BuoyancyHold" { run while ( BuoyancyHold ) set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch id="MassHold" { run while ( MassHold ) set massPosition = Control:VerticalControl.massDefault } aggregate Transit { run in sequence behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude set northingsDelta = Northings set eastingsDelta = Eastings } call id="PhoneHome" refId="NeedComms" } } 2025-03-26T23:00:12.885Z,1743030012.885 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit 2025-03-26T23:00:13.095Z,1743030013.095 [MissionManager](IMPORTANT): Started mission Default 2025-03-26T23:00:13.096Z,1743030013.096 [Default] Running Loop=1 2025-03-26T23:00:13.096Z,1743030013.096 [Default](DEBUG): Aggregate::initialize Default 2025-03-26T23:00:13.096Z,1743030013.096 [Default:B.GoToSurface] Running Loop=1 2025-03-26T23:00:13.096Z,1743030013.096 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-26T23:00:13.096Z,1743030013.096 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-26T23:00:13.097Z,1743030013.097 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-26T23:00:13.097Z,1743030013.097 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-26T23:00:13.097Z,1743030013.097 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-26T23:00:13.097Z,1743030013.097 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-26T23:00:13.098Z,1743030013.098 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-26T23:00:13.098Z,1743030013.098 [Default:A.Wait] Running Loop=1 2025-03-26T23:00:13.098Z,1743030013.098 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-03-26T23:00:15.473Z,1743030015.473 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-26T23:00:26.429Z,1743030026.429 [Default:A.Wait](INFO): Done Waiting. 2025-03-26T23:00:26.429Z,1743030026.429 [Default:A.Wait] Stopped 2025-03-26T23:00:26.429Z,1743030026.429 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-03-26T23:00:26.877Z,1743030026.877 [Default:CheckIn] Running Loop=1 2025-03-26T23:00:26.877Z,1743030026.877 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-26T23:00:26.877Z,1743030026.877 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-26T23:00:28.810Z,1743030028.810 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230028.00,A,3648.31313,N,12147.60423,W,0.875,131.04,260325,,,A*72 2025-03-26T23:00:28.813Z,1743030028.813 [NAL9602](INFO): GPS fix at 20250326T230028: (36.805219, -121.793404) 2025-03-26T23:00:28.877Z,1743030028.877 [Default:CheckIn:Read_GPS] Stopped 2025-03-26T23:00:28.877Z,1743030028.877 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-26T23:00:30.414Z,1743030030.414 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 30 minute 2025-03-26T23:00:30.421Z,1743030030.421 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 15 minute 2025-03-26T23:00:30.422Z,1743030030.422 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree 2025-03-26T23:00:30.424Z,1743030030.424 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree 2025-03-26T23:00:30.424Z,1743030030.424 [CommandExec](IMPORTANT): got command set transit.Depth 2 meter 2025-03-26T23:00:30.425Z,1743030030.425 [CommandExec](IMPORTANT): got command set transit.Speed 1.2 meter_per_second 2025-03-26T23:00:40.364Z,1743030040.364 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20250326T225009/Courier0006.lzma 2025-03-26T23:00:41.370Z,1743030041.370 [DataOverHttps](INFO): Moved sent file to Logs/20250326T225009/Courier0006.lzma.bak 2025-03-26T23:00:41.370Z,1743030041.370 [DataOverHttps](INFO): SBD MOMSN=24462934 2025-03-26T23:00:45.716Z,1743030045.716 [CommandExec](IMPORTANT): got command set transit.MaxDepth 5 meter 2025-03-26T23:00:45.717Z,1743030045.717 [CommandExec](IMPORTANT): got command set transit.MinOffshore 0.0001 kilometer 2025-03-26T23:00:45.717Z,1743030045.717 [CommandExec](IMPORTANT): got command set transit:NeedComms.CommsTimeout 1 minute 2025-03-26T23:00:45.718Z,1743030045.718 [CommandExec](IMPORTANT): got command run 2025-03-26T23:00:45.725Z,1743030045.725 [CommandExec](IMPORTANT): Running 2025-03-26T23:00:46.008Z,1743030046.008 [Default] Stopped 2025-03-26T23:00:46.008Z,1743030046.008 [Default](DEBUG): Aggregate::uninitialize Default 2025-03-26T23:00:46.008Z,1743030046.008 [Default:B.GoToSurface] Stopped 2025-03-26T23:00:46.008Z,1743030046.008 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-26T23:00:46.008Z,1743030046.008 [Default:CheckIn] Stopped 2025-03-26T23:00:46.008Z,1743030046.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-03-26T23:00:46.008Z,1743030046.008 [Default:CheckIn:Read_Iridium] Stopped 2025-03-26T23:00:46.008Z,1743030046.008 [MissionManager](IMPORTANT): Started mission transit 2025-03-26T23:00:46.009Z,1743030046.009 [transit] Running Loop=1 2025-03-26T23:00:46.009Z,1743030046.009 [transit](DEBUG): Aggregate::initialize transit 2025-03-26T23:00:46.009Z,1743030046.009 [transit:StandardEnvelopes] Running Loop=1 2025-03-26T23:00:46.009Z,1743030046.009 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes 2025-03-26T23:00:46.009Z,1743030046.009 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-03-26T23:00:46.009Z,1743030046.009 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-03-26T23:00:46.009Z,1743030046.009 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-03-26T23:00:46.009Z,1743030046.009 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-03-26T23:00:46.010Z,1743030046.010 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-03-26T23:00:46.010Z,1743030046.010 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-03-26T23:00:46.010Z,1743030046.010 [transit:BackseatDriver] Running Loop=1 2025-03-26T23:00:46.010Z,1743030046.010 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver 2025-03-26T23:00:46.010Z,1743030046.010 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-03-26T23:00:46.010Z,1743030046.010 [transit:PowerOnly] Running Loop=1 2025-03-26T23:00:46.010Z,1743030046.010 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly 2025-03-26T23:00:46.010Z,1743030046.010 [transit:PowerOnly:A] Running Loop=1 2025-03-26T23:00:46.010Z,1743030046.010 [transit:PowerOnly:B] Running Loop=1 2025-03-26T23:00:46.010Z,1743030046.010 [transit:PowerOnly:C] Running Loop=1 2025-03-26T23:00:46.010Z,1743030046.010 [transit:PowerOnly:D] Running Loop=1 2025-03-26T23:00:46.011Z,1743030046.011 [transit:PowerOnly:E.Wait] Running Loop=1 2025-03-26T23:00:46.011Z,1743030046.011 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-03-26T23:00:46.011Z,1743030046.011 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2025-03-26T23:00:46.011Z,1743030046.011 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-03-26T23:00:46.015Z,1743030046.015 [transit:MassHold.Pitch] Running Loop=1 2025-03-26T23:00:46.015Z,1743030046.015 [transit:MassHold.Pitch](DEBUG): Initialize. 2025-03-26T23:00:46.015Z,1743030046.015 [transit:B] Running Loop=1 2025-03-26T23:00:46.016Z,1743030046.016 [transit:MassHold.Pitch] Running Loop=1 2025-03-26T23:00:46.016Z,1743030046.016 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2025-03-26T23:00:46.016Z,1743030046.016 [transit:PowerOnly] Running Loop=1 2025-03-26T23:00:46.017Z,1743030046.017 [transit:PowerOnly:D] Stopped 2025-03-26T23:00:46.017Z,1743030046.017 [transit:PowerOnly:C] Stopped 2025-03-26T23:00:46.017Z,1743030046.017 [transit:PowerOnly:B] Stopped 2025-03-26T23:00:46.017Z,1743030046.017 [transit:PowerOnly:A] Stopped 2025-03-26T23:00:46.017Z,1743030046.017 [transit:BackseatDriver] Running Loop=1 2025-03-26T23:00:46.017Z,1743030046.017 [transit:StandardEnvelopes] Running Loop=1 2025-03-26T23:00:46.017Z,1743030046.017 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-03-26T23:00:46.018Z,1743030046.018 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-03-26T23:00:46.018Z,1743030046.018 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-03-26T23:00:46.018Z,1743030046.018 [transit:B] Stopped 2025-03-26T23:00:46.019Z,1743030046.019 [transit:C] Running Loop=1 2025-03-26T23:00:46.421Z,1743030046.421 [transit:C] Stopped 2025-03-26T23:00:46.421Z,1743030046.421 [transit:E] Running Loop=1 2025-03-26T23:00:46.842Z,1743030046.842 [transit:E] Stopped 2025-03-26T23:00:46.843Z,1743030046.843 [transit:F] Running Loop=1 2025-03-26T23:00:47.301Z,1743030047.301 [transit:F] Stopped 2025-03-26T23:00:47.301Z,1743030047.301 [transit:G] Running Loop=1 2025-03-26T23:00:47.621Z,1743030047.621 [transit:G] Stopped 2025-03-26T23:00:47.621Z,1743030047.621 [transit:Transit] Running Loop=1 2025-03-26T23:00:47.622Z,1743030047.622 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit 2025-03-26T23:00:47.622Z,1743030047.622 [transit:Transit:A.Pitch] Running Loop=1 2025-03-26T23:00:47.622Z,1743030047.622 [transit:Transit:A.Pitch](DEBUG): Initialize. 2025-03-26T23:00:47.627Z,1743030047.627 [transit:Transit:B.SetSpeed] Running Loop=1 2025-03-26T23:00:47.627Z,1743030047.627 [transit:Transit:B.SetSpeed](DEBUG): Initialize. 2025-03-26T23:00:47.627Z,1743030047.627 [transit:Transit:Wpt1.Waypoint] Running Loop=1 2025-03-26T23:00:47.627Z,1743030047.627 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-03-26T23:00:47.629Z,1743030047.629 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2025-03-26T23:00:48.053Z,1743030048.053 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2025-03-26T23:00:48.054Z,1743030048.054 [transit:Transit:B.SetSpeed] Running Loop=1 2025-03-26T23:00:48.054Z,1743030048.054 [transit:Transit:A.Pitch] Running Loop=1 2025-03-26T23:01:00.155Z,1743030060.155 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250326T225009/Express0004.lzma 2025-03-26T23:01:01.161Z,1743030061.161 [DataOverHttps](INFO): Moved sent file to Logs/20250326T225009/Express0004.lzma.bak 2025-03-26T23:01:01.162Z,1743030061.162 [DataOverHttps](INFO): SBD MOMSN=24462937 2025-03-26T23:01:01.216Z,1743030061.216 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-26T23:02:02.060Z,1743030122.060 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.900875 2025-03-26T23:02:34.204Z,1743030154.204 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:02:56.247Z,1743030176.247 [Radio_Surface](INFO): Powering down 2025-03-26T23:03:09.348Z,1743030189.348 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:03:10.351Z,1743030190.351 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-03-26T23:05:46.662Z,1743030346.662 [Waterlinked](ERROR): only read 0 of 1 data item for altitude. Device response::BD, +0.000 2025-03-26T23:15:29.328Z,1743030929.328 [transit:NeedComms] Running Loop=1 2025-03-26T23:15:29.329Z,1743030929.329 [transit:NeedComms](DEBUG): Aggregate::initialize transit:NeedComms 2025-03-26T23:15:29.329Z,1743030929.329 [transit:NeedComms:B.GoToSurface] Running Loop=1 2025-03-26T23:15:29.329Z,1743030929.329 [transit:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-26T23:15:29.330Z,1743030929.330 [transit:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-03-26T23:15:29.336Z,1743030929.336 [transit:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-03-26T23:15:29.337Z,1743030929.337 [transit:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-03-26T23:15:29.338Z,1743030929.338 [transit:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-03-26T23:15:29.339Z,1743030929.339 [transit:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-26T23:15:29.344Z,1743030929.344 [transit:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-26T23:15:29.346Z,1743030929.346 [transit:NeedComms:A] Running Loop=1 2025-03-26T23:15:29.353Z,1743030929.353 [transit:NeedComms:A](INFO): last time_fix was: 1743030028.000000 second since 1970/01/01T00:00:00Z 2025-03-26T23:15:29.353Z,1743030929.353 [transit:NeedComms:A] Stopped 2025-03-26T23:15:31.571Z,1743030931.571 [ThrusterHE](FAULT): Could not get status:serial timeout 2025-03-26T23:15:31.661Z,1743030931.661 [ThrusterHE](FAULT): getSpeed error serial timeout 2025-03-26T23:15:31.662Z,1743030931.662 [ThrusterHE](FAULT): Thruster uart error: serial timeout 2025-03-26T23:15:31.662Z,1743030931.662 [ThrusterHE] Communications Fault, FailCount= 1 2025-03-26T23:15:31.662Z,1743030931.662 [ThrusterHE](ERROR): Communications Fault 2025-03-26T23:15:31.676Z,1743030931.676 [CBIT](ERROR): Communications Fault in component: ThrusterHE 2025-03-26T23:15:32.108Z,1743030932.108 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-03-26T23:15:32.108Z,1743030932.108 [ThrusterHE](INFO): Powering down 2025-03-26T23:15:32.631Z,1743030932.631 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-03-26T23:15:32.748Z,1743030932.748 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-03-26T23:15:32.752Z,1743030932.752 [CBIT](INFO): Clearing failed state for component ThrusterHE 2025-03-26T23:15:32.752Z,1743030932.752 [ThrusterHE] No Fault, FailCount= 1 2025-03-26T23:15:42.431Z,1743030942.431 [transit:NeedComms:C] Running Loop=1 2025-03-26T23:15:42.824Z,1743030942.824 [Waterlinked](INFO): Powering down 2025-03-26T23:15:42.846Z,1743030942.846 [transit:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-03-26T23:15:43.023Z,1743030943.023 [Radio_Surface](INFO): Powering up 2025-03-26T23:15:48.463Z,1743030948.463 [DataOverHttps](INFO): Radio surface powered ON. 2025-03-26T23:16:15.077Z,1743030975.077 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-03-26T23:16:15.159Z,1743030975.159 [NAL9602](ERROR): received: +CSQ:0 OK754, 2, 0, 0, 0 OK 2025-03-26T23:16:18.583Z,1743030978.583 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:16:53.723Z,1743031013.723 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:17:28.867Z,1743031048.867 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:18:04.007Z,1743031084.007 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:18:39.167Z,1743031119.167 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:19:11.816Z,1743031151.816 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003686 2025-03-26T23:20:46.953Z,1743031246.953 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-26T23:20:55.436Z,1743031255.436 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232055.00,A,3648.19759,N,12148.04474,W,1.011,86.13,260325,,,A*45 2025-03-26T23:20:55.438Z,1743031255.438 [NAL9602](INFO): GPS fix at 20250326T232055: (36.803293, -121.800746) 2025-03-26T23:20:55.451Z,1743031255.451 [UniversalFixResidualReporter](INFO): Fix residual: 21.3 %DT, over the last 870.2 m. Residual distance 185.6 m at bearing -74.1 degrees. Fix at (36.8033, -121.8007) with 687.8 m made good. 2025-03-26T23:20:55.454Z,1743031255.454 [transit:NeedComms:C] Stopped 2025-03-26T23:20:55.455Z,1743031255.455 [transit:NeedComms:D] Running Loop=1 2025-03-26T23:20:55.871Z,1743031255.871 [transit:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-03-26T23:21:03.203Z,1743031263.203 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20250326T225009/Courier0011.lzma 2025-03-26T23:21:04.205Z,1743031264.205 [DataOverHttps](INFO): Moved sent file to Logs/20250326T225009/Courier0011.lzma.bak 2025-03-26T23:21:04.205Z,1743031264.205 [DataOverHttps](INFO): SBD MOMSN=24463042 2025-03-26T23:21:20.129Z,1743031280.129 [DataOverHttps](INFO): Sending 682 bytes from file Logs/20250326T225009/Express0007.lzma 2025-03-26T23:21:21.132Z,1743031281.132 [DataOverHttps](INFO): Moved sent file to Logs/20250326T225009/Express0007.lzma.bak 2025-03-26T23:21:21.132Z,1743031281.132 [DataOverHttps](INFO): SBD MOMSN=24463045 2025-03-26T23:21:28.157Z,1743031288.157 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-26T23:21:40.037Z,1743031300.037 [DataOverHttps](INFO): Sending 631 bytes from file Logs/20250326T225009/Express0013.lzma 2025-03-26T23:21:41.037Z,1743031301.037 [DataOverHttps](INFO): Moved sent file to Logs/20250326T225009/Express0013.lzma.bak 2025-03-26T23:21:41.037Z,1743031301.037 [DataOverHttps](INFO): SBD MOMSN=24463060 2025-03-26T23:21:42.385Z,1743031302.385 [transit:NeedComms:D] Stopped 2025-03-26T23:21:42.385Z,1743031302.385 [transit:NeedComms:E] Running Loop=1 2025-03-26T23:21:42.786Z,1743031302.786 [transit:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-03-26T23:21:44.403Z,1743031304.403 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232144.00,A,3648.19700,N,12148.03685,W,2.138,142.54,260325,,,A*70 2025-03-26T23:21:44.405Z,1743031304.405 [NAL9602](INFO): GPS fix at 20250326T232144: (36.803283, -121.800614) 2025-03-26T23:21:44.427Z,1743031304.427 [transit:NeedComms:E] Stopped 2025-03-26T23:21:44.432Z,1743031304.432 [transit:NeedComms](INFO): Completed transit:NeedComms 2025-03-26T23:21:44.432Z,1743031304.432 [transit:NeedComms] Stopped 2025-03-26T23:21:44.432Z,1743031304.432 [transit:NeedComms](DEBUG): Aggregate::uninitialize transit:NeedComms 2025-03-26T23:21:44.432Z,1743031304.432 [transit:NeedComms:B.GoToSurface] Stopped 2025-03-26T23:21:44.432Z,1743031304.432 [transit:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-03-26T23:22:15.152Z,1743031335.152 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-03-26T23:22:15.231Z,1743031335.231 [NAL9602](ERROR): received: +CSQ:0 OK754, 2, 0, 0, 0 OK 2025-03-26T23:22:17.604Z,1743031337.604 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.20 m/s, pitch 20.00 deg, mass-position 20.00 mm (1 active estimators). 2025-03-26T23:22:48.164Z,1743031368.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:22:50.173Z,1743031370.173 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.255058 2025-03-26T23:23:18.012Z,1743031398.012 [Radio_Surface](INFO): Powering down 2025-03-26T23:23:23.315Z,1743031403.315 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:23:24.320Z,1743031404.320 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-03-26T23:24:44.003Z,1743031484.003 [Waterlinked](INFO): Powering down 2025-03-26T23:24:44.069Z,1743031484.069 [Radio_Surface](INFO): Powering up 2025-03-26T23:24:49.692Z,1743031489.692 [DataOverHttps](INFO): Radio surface powered ON. 2025-03-26T23:24:50.128Z,1743031490.128 [Radio_Surface](INFO): Powering down 2025-03-26T23:25:19.811Z,1743031519.811 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:25:20.816Z,1743031520.816 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-03-26T23:26:47.101Z,1743031607.101 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-26T23:27:17.869Z,1743031637.869 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-26T23:30:46.400Z,1743031846.400 [transit](INFO): Timed out from 2025-03-26T23:00:46.0Z 2025-03-26T23:30:46.400Z,1743031846.400 [MissionManager](INFO): transit is completed. 2025-03-26T23:30:46.400Z,1743031846.400 [MissionManager](INFO): Uninitializing Mission transit 2025-03-26T23:30:46.400Z,1743031846.400 [transit] Stopped 2025-03-26T23:30:46.400Z,1743031846.400 [transit](DEBUG): Aggregate::uninitialize transit 2025-03-26T23:30:46.400Z,1743031846.400 [transit:StandardEnvelopes] Stopped 2025-03-26T23:30:46.400Z,1743031846.400 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2025-03-26T23:30:46.401Z,1743031846.401 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-03-26T23:30:46.401Z,1743031846.401 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-03-26T23:30:46.401Z,1743031846.401 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-03-26T23:30:46.401Z,1743031846.401 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-03-26T23:30:46.401Z,1743031846.401 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-03-26T23:30:46.401Z,1743031846.401 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-03-26T23:30:46.401Z,1743031846.401 [transit:BackseatDriver] Stopped 2025-03-26T23:30:46.401Z,1743031846.401 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver 2025-03-26T23:30:46.401Z,1743031846.401 [transit:BackseatDriver:A.BackseatDriver] Stopped 2025-03-26T23:30:46.401Z,1743031846.401 [transit:PowerOnly] Stopped 2025-03-26T23:30:46.401Z,1743031846.401 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly 2025-03-26T23:30:46.401Z,1743031846.401 [transit:PowerOnly:E.Wait] Stopped 2025-03-26T23:30:46.401Z,1743031846.401 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-03-26T23:30:46.402Z,1743031846.402 [transit:BuoyancyHold.Buoyancy] Stopped 2025-03-26T23:30:46.402Z,1743031846.402 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-03-26T23:30:46.402Z,1743031846.402 [transit:MassHold.Pitch] Stopped 2025-03-26T23:30:46.402Z,1743031846.402 [transit:Transit] Stopped 2025-03-26T23:30:46.402Z,1743031846.402 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2025-03-26T23:30:46.402Z,1743031846.402 [transit:Transit:A.Pitch] Stopped 2025-03-26T23:30:46.402Z,1743031846.402 [transit:Transit:B.SetSpeed] Stopped 2025-03-26T23:30:46.402Z,1743031846.402 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2025-03-26T23:30:46.402Z,1743031846.402 [transit:Transit:Wpt1.Waypoint] Stopped 2025-03-26T23:30:46.402Z,1743031846.402 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-03-26T23:30:46.782Z,1743031846.782 [MissionManager](IMPORTANT): Started mission Default 2025-03-26T23:30:46.782Z,1743031846.782 [Default] Running Loop=1 2025-03-26T23:30:46.782Z,1743031846.782 [Default](DEBUG): Aggregate::initialize Default 2025-03-26T23:30:46.782Z,1743031846.782 [Default:B.GoToSurface] Running Loop=1 2025-03-26T23:30:46.782Z,1743031846.782 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-03-26T23:30:46.783Z,1743031846.783 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-03-26T23:30:46.783Z,1743031846.783 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-03-26T23:30:46.783Z,1743031846.783 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-03-26T23:30:46.784Z,1743031846.784 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-03-26T23:30:46.784Z,1743031846.784 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-03-26T23:30:46.784Z,1743031846.784 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-03-26T23:30:46.785Z,1743031846.785 [Default:A.Wait] Running Loop=1 2025-03-26T23:30:46.785Z,1743031846.785 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-03-26T23:30:47.137Z,1743031847.137 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -25.00 mm (2 active estimators). 2025-03-26T23:30:47.363Z,1743031847.363 [Radio_Surface](INFO): Powering up 2025-03-26T23:30:53.183Z,1743031853.183 [DataOverHttps](INFO): Radio surface powered ON. 2025-03-26T23:30:58.855Z,1743031858.855 [Waterlinked](INFO): Powering down 2025-03-26T23:31:00.006Z,1743031860.006 [Default:A.Wait](INFO): Done Waiting. 2025-03-26T23:31:00.006Z,1743031860.006 [Default:A.Wait] Stopped 2025-03-26T23:31:00.006Z,1743031860.006 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-03-26T23:31:00.411Z,1743031860.411 [Default:CheckIn] Running Loop=1 2025-03-26T23:31:00.412Z,1743031860.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-03-26T23:31:00.412Z,1743031860.412 [Default:CheckIn:Read_GPS] Running Loop=1 2025-03-26T23:31:23.305Z,1743031883.305 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:31:33.126Z,1743031893.126 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-03-26T23:31:33.199Z,1743031893.199 [NAL9602](ERROR): received: +CSQ:0 OK754, 2, 0, 0, 0 OK 2025-03-26T23:31:58.443Z,1743031918.443 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:32:33.583Z,1743031953.583 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:32:40.602Z,1743031960.602 [NAL9602](INFO): SBD MO Status=2, MOMSN=18754, MT Status=2, MTMSN=0 2025-03-26T23:32:40.603Z,1743031960.603 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-03-26T23:33:08.743Z,1743031988.743 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:33:43.891Z,1743032023.891 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-03-26T23:34:17.449Z,1743032057.449 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004464 2025-03-26T23:36:00.616Z,1743032160.616 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-26T23:31:00.4Z 2025-03-26T23:36:00.616Z,1743032160.616 [Default:CheckIn:Read_GPS] Stopped 2025-03-26T23:36:00.616Z,1743032160.616 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-03-26T23:36:05.053Z,1743032165.053 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-03-26T23:36:08.051Z,1743032168.051 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250326T225009/Courier0015.lzma 2025-03-26T23:36:09.055Z,1743032169.055 [DataOverHttps](INFO): Moved sent file to Logs/20250326T225009/Courier0015.lzma.bak 2025-03-26T23:36:09.055Z,1743032169.055 [DataOverHttps](INFO): SBD MOMSN=24463175 2025-03-26T23:36:24.983Z,1743032184.983 [DataOverHttps](INFO): Sending 302 bytes from file Logs/20250326T225009/Express0016.lzma 2025-03-26T23:36:25.985Z,1743032185.985 [DataOverHttps](INFO): Moved sent file to Logs/20250326T225009/Express0016.lzma.bak 2025-03-26T23:36:25.985Z,1743032185.985 [DataOverHttps](INFO): SBD MOMSN=24463178 2025-03-26T23:36:27.290Z,1743032187.290 [Default:CheckIn:Read_Iridium] Stopped 2025-03-26T23:36:27.290Z,1743032187.290 [Default:CheckIn:C.Wait] Running Loop=1 2025-03-26T23:36:27.290Z,1743032187.290 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-03-26T23:36:35.758Z,1743032195.758 [NAL9602](INFO): Not Powering down - fast GPS 2025-03-26T23:36:39.324Z,1743032199.324 [CommandExec](IMPORTANT): got command ibit 2025-03-26T23:36:39.414Z,1743032199.414 [IBIT](IMPORTANT): Beginning Initia