2025-02-23T17:05:50.465Z,1740330350.465 [CommandExec](IMPORTANT): got command restart logs 2025-02-23T17:06:03.256Z,1740330363.256 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170602.00,A,3648.37415,N,12151.09040,W,0.292,0.00,230225,,,A*75 2025-02-23T17:06:03.258Z,1740330363.258 [NAL9602](INFO): GPS fix at 20250223T170602: (36.806236, -121.851507) 2025-02-23T17:06:03.334Z,1740330363.334 [UniversalFixResidualReporter](INFO): Fix residual: 104.9 %DT, over the last 1146.4 m. Residual distance 1202.4 m at bearing 158.6 degrees. Fix at (36.8062, -121.8515) with 2115.3 m made good. 2025-02-23T17:06:03.360Z,1740330363.360 [keepstation:NeedComms:C] Stopped 2025-02-23T17:06:03.360Z,1740330363.360 [keepstation:NeedComms:D] Running Loop=1 2025-02-23T17:06:03.388Z,1740330363.388 [CBIT](IMPORTANT): Navigation error has exceeded threshold. 2025-02-23T17:06:03.698Z,1740330363.698 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-02-23T17:06:34.648Z,1740330394.648 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003705 2025-02-23T17:06:34.938Z,1740330394.938 [NAL9602](INFO): SBD MO Status=1, MOMSN=13239, MT Status=0, MTMSN=0 2025-02-23T17:06:34.000Z,1740330395.000 [NAL9602](INFO): Sent 87 bytes from file Logs/20250223T055025/Courier0021.lzma 2025-02-23T17:06:34.000Z,1740330395.000 [NAL9602](INFO): Packets left to send: 0 2025-02-23T17:06:43.631Z,1740330403.631 [DataOverHttps](INFO): Sending 325 bytes from file Logs/20250223T170550/Courier0003.lzma 2025-02-23T17:06:44.633Z,1740330404.633 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Courier0003.lzma.bak 2025-02-23T17:06:44.633Z,1740330404.633 [DataOverHttps](INFO): SBD MOMSN=23973276 2025-02-23T17:07:00.961Z,1740330420.961 [DataOverHttps](INFO): Sending 1147 bytes from file Logs/20250223T055025/Express0022.lzma 2025-02-23T17:07:01.961Z,1740330421.961 [DataOverHttps](INFO): Moved sent file to Logs/20250223T055025/Express0022.lzma.bak 2025-02-23T17:07:01.961Z,1740330421.961 [DataOverHttps](INFO): SBD MOMSN=23973283 2025-02-23T17:07:06.108Z,1740330426.108 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-23T17:07:22.048Z,1740330442.048 [DataOverHttps](INFO): Sending 379 bytes from file Logs/20250223T170550/Express0004.lzma 2025-02-23T17:07:23.049Z,1740330443.049 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Express0004.lzma.bak 2025-02-23T17:07:23.049Z,1740330443.049 [DataOverHttps](INFO): SBD MOMSN=23973310 2025-02-23T17:07:25.198Z,1740330445.198 [keepstation:NeedComms:D] Stopped 2025-02-23T17:07:25.199Z,1740330445.199 [keepstation:NeedComms:E] Running Loop=1 2025-02-23T17:07:25.676Z,1740330445.676 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-02-23T17:07:27.183Z,1740330447.183 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170726.00,A,3648.37376,N,12151.08807,W,0.816,5.30,230225,,,A*7A 2025-02-23T17:07:27.185Z,1740330447.185 [NAL9602](INFO): GPS fix at 20250223T170726: (36.806229, -121.851468) 2025-02-23T17:07:27.227Z,1740330447.227 [keepstation:NeedComms:E] Stopped 2025-02-23T17:07:27.228Z,1740330447.228 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-02-23T17:07:27.229Z,1740330447.229 [keepstation:NeedComms] Stopped 2025-02-23T17:07:27.229Z,1740330447.229 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-23T17:07:27.229Z,1740330447.229 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-23T17:07:27.229Z,1740330447.229 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-23T17:07:27.662Z,1740330447.662 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-02-23T17:07:27.662Z,1740330447.662 [keepstation:StartingMission] Stopped 2025-02-23T17:07:27.662Z,1740330447.662 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-02-23T17:07:27.662Z,1740330447.662 [keepstation:TransitToStation] Running Loop=1 2025-02-23T17:07:27.663Z,1740330447.663 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-02-23T17:07:27.663Z,1740330447.663 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2025-02-23T17:07:27.663Z,1740330447.663 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-02-23T17:07:27.695Z,1740330447.695 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2025-02-23T17:07:27.695Z,1740330447.695 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize. 2025-02-23T17:07:27.696Z,1740330447.696 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2025-02-23T17:07:27.696Z,1740330447.696 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize. 2025-02-23T17:07:27.696Z,1740330447.696 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-02-23T17:07:27.696Z,1740330447.696 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-02-23T17:07:27.697Z,1740330447.697 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2025-02-23T17:07:28.118Z,1740330448.118 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2025-02-23T17:07:28.119Z,1740330448.119 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2025-02-23T17:07:28.135Z,1740330448.135 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2025-02-23T17:07:28.136Z,1740330448.136 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2025-02-23T17:07:41.758Z,1740330461.758 [NAL9602](INFO): SBD MO Status=0, MOMSN=13240, MT Status=0, MTMSN=0 2025-02-23T17:07:41.758Z,1740330461.758 [NAL9602](INFO): No messages in MT queue 2025-02-23T17:08:12.453Z,1740330492.453 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-23T17:09:37.597Z,1740330577.597 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-02-23T17:09:43.250Z,1740330583.250 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-02-23T17:09:45.277Z,1740330585.277 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4BD0. 2025-02-23T17:09:45.278Z,1740330585.278 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6. 2025-02-23T17:09:45.281Z,1740330585.281 [BPC1](INFO): Received data from all battery sticks. 2025-02-23T17:09:49.959Z,1740330589.959 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T17:10:18.683Z,1740330618.683 [Radio_Surface](INFO): Powering down 2025-02-23T17:10:21.083Z,1740330621.083 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T17:10:22.088Z,1740330622.088 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-02-23T17:11:58.480Z,1740330718.480 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.767242 2025-02-23T17:26:21.351Z,1740331581.351 [CBIT](IMPORTANT): Beginning ground fault scan 2025-02-23T17:26:32.406Z,1740331592.406 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.000985 CHAN A1 (24V): -0.000533 CHAN A2 (12V): 0.000092 CHAN A3 (5V): -0.002395 CHAN B0 (3.3V): -0.000928 CHAN B1 (3.15aV): -0.001403 CHAN B2 (3.15bV): -0.001197 CHAN B3 (GND): -0.002131 OPEN: 0.005049 Full Scale: +/- 1 mA 2025-02-23T17:32:47.952Z,1740331967.952 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000 2025-02-23T17:32:47.952Z,1740331967.952 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2025-02-23T17:32:47.952Z,1740331967.952 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-02-23T17:32:47.954Z,1740331967.954 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2025-02-23T17:32:47.954Z,1740331967.954 [keepstation:TransitToStation] Stopped 2025-02-23T17:32:47.954Z,1740331967.954 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2025-02-23T17:32:47.954Z,1740331967.954 [keepstation:TransitToStation:A.Buoyancy] Stopped 2025-02-23T17:32:47.954Z,1740331967.954 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-02-23T17:32:47.954Z,1740331967.954 [keepstation:TransitToStation:B.Pitch] Stopped 2025-02-23T17:32:47.954Z,1740331967.954 [keepstation:TransitToStation:C.SetSpeed] Stopped 2025-02-23T17:32:47.954Z,1740331967.954 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize. 2025-02-23T17:32:47.954Z,1740331967.954 [keepstation:OnStation] Running Loop=1 2025-02-23T17:32:47.954Z,1740331967.954 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2025-02-23T17:32:48.362Z,1740331968.362 [keepstation:NeedComms] Running Loop=1 2025-02-23T17:32:48.363Z,1740331968.363 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-02-23T17:32:48.363Z,1740331968.363 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-02-23T17:32:48.363Z,1740331968.363 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-23T17:32:48.364Z,1740331968.364 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-02-23T17:32:48.364Z,1740331968.364 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-02-23T17:32:48.365Z,1740331968.365 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-02-23T17:32:48.365Z,1740331968.365 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-02-23T17:32:48.365Z,1740331968.365 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-23T17:32:48.366Z,1740331968.366 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-23T17:32:48.366Z,1740331968.366 [keepstation:NeedComms:A] Running Loop=1 2025-02-23T17:32:48.368Z,1740331968.368 [keepstation:NeedComms:A](INFO): last time_fix was: 1740330446.000000 second since 1970/01/01T00:00:00Z 2025-02-23T17:32:48.368Z,1740331968.368 [keepstation:NeedComms:A] Stopped 2025-02-23T17:33:40.329Z,1740332020.329 [keepstation:NeedComms:C] Running Loop=1 2025-02-23T17:33:40.980Z,1740332020.980 [Radio_Surface](INFO): Powering up 2025-02-23T17:33:46.952Z,1740332026.952 [DataOverHttps](INFO): Radio surface powered ON. 2025-02-23T17:33:55.265Z,1740332035.265 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=13241, MT Status=1, MTMSN=271 2025-02-23T17:33:55.265Z,1740332035.265 [NAL9602](INFO): Data available in MT queue 2025-02-23T17:33:55.756Z,1740332035.756 [NAL9602](INFO): Received command: stop 2025-02-23T17:33:55.825Z,1740332035.825 [CommandExec](IMPORTANT): got command stop 2025-02-23T17:33:55.825Z,1740332035.825 [CommandExec](IMPORTANT): Scheduling is paused 2025-02-23T17:33:55.825Z,1740332035.825 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2025-02-23T17:33:56.097Z,1740332036.097 [MissionManager](INFO): MissionManager is completed. 2025-02-23T17:33:56.097Z,1740332036.097 [MissionManager](INFO): Uninitializing Mission keepstation 2025-02-23T17:33:56.097Z,1740332036.097 [keepstation] Stopped 2025-02-23T17:33:56.097Z,1740332036.097 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2025-02-23T17:33:56.097Z,1740332036.097 [keepstation:NeedComms] Stopped 2025-02-23T17:33:56.097Z,1740332036.097 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-23T17:33:56.097Z,1740332036.097 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-23T17:33:56.097Z,1740332036.097 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-23T17:33:56.097Z,1740332036.097 [keepstation:NeedComms:C] Stopped 2025-02-23T17:33:56.097Z,1740332036.097 [keepstation:StandardEnvelopes] Stopped 2025-02-23T17:33:56.098Z,1740332036.098 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2025-02-23T17:33:56.098Z,1740332036.098 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-02-23T17:33:56.098Z,1740332036.098 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-02-23T17:33:56.098Z,1740332036.098 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-02-23T17:33:56.098Z,1740332036.098 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-02-23T17:33:56.098Z,1740332036.098 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-02-23T17:33:56.098Z,1740332036.098 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-02-23T17:33:56.098Z,1740332036.098 [keepstation:BackseatDriver] Stopped 2025-02-23T17:33:56.098Z,1740332036.098 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver 2025-02-23T17:33:56.098Z,1740332036.098 [keepstation:BackseatDriver:A.BackseatDriver] Stopped 2025-02-23T17:33:56.098Z,1740332036.098 [keepstation:PowerOnly] Stopped 2025-02-23T17:33:56.098Z,1740332036.098 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly 2025-02-23T17:33:56.098Z,1740332036.098 [keepstation:PowerOnly:E.Wait] Stopped 2025-02-23T17:33:56.099Z,1740332036.099 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-02-23T17:33:56.099Z,1740332036.099 [keepstation:H.Pitch] Stopped 2025-02-23T17:33:56.099Z,1740332036.099 [keepstation:OnStation] Stopped 2025-02-23T17:33:56.099Z,1740332036.099 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2025-02-23T17:33:56.509Z,1740332036.509 [MissionManager](IMPORTANT): Started mission Default 2025-02-23T17:33:56.510Z,1740332036.510 [Default] Running Loop=1 2025-02-23T17:33:56.510Z,1740332036.510 [Default](DEBUG): Aggregate::initialize Default 2025-02-23T17:33:56.510Z,1740332036.510 [Default:B.GoToSurface] Running Loop=1 2025-02-23T17:33:56.510Z,1740332036.510 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-23T17:33:56.510Z,1740332036.510 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-02-23T17:33:56.510Z,1740332036.510 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-02-23T17:33:56.511Z,1740332036.511 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-02-23T17:33:56.511Z,1740332036.511 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-02-23T17:33:56.512Z,1740332036.512 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-23T17:33:56.512Z,1740332036.512 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-23T17:33:56.512Z,1740332036.512 [Default:A.Wait] Running Loop=1 2025-02-23T17:33:56.512Z,1740332036.512 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-02-23T17:33:56.886Z,1740332036.886 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173356.00,A,3647.84375,N,12150.85197,W,0.700,217.98,230225,,,A*71 2025-02-23T17:33:56.889Z,1740332036.889 [NAL9602](INFO): GPS fix at 20250223T173356: (36.797396, -121.847533) 2025-02-23T17:33:56.901Z,1740332036.901 [UniversalFixResidualReporter](INFO): Fix residual: 8.3 %DT, over the last 1241.2 m. Residual distance 103.3 m at bearing 152.0 degrees. Fix at (36.7974, -121.8475) with 1042.9 m made good. 2025-02-23T17:34:09.819Z,1740332049.819 [Default:A.Wait](INFO): Done Waiting. 2025-02-23T17:34:09.819Z,1740332049.819 [Default:A.Wait] Stopped 2025-02-23T17:34:09.819Z,1740332049.819 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-02-23T17:34:10.228Z,1740332050.228 [Default:CheckIn] Running Loop=1 2025-02-23T17:34:10.228Z,1740332050.228 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-23T17:34:10.228Z,1740332050.228 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-23T17:34:12.242Z,1740332052.242 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173411.00,A,3647.84146,N,12150.85259,W,0.505,193.56,230225,,,A*7C 2025-02-23T17:34:12.245Z,1740332052.245 [NAL9602](INFO): GPS fix at 20250223T173411: (36.797358, -121.847543) 2025-02-23T17:34:12.257Z,1740332052.257 [Default:CheckIn:Read_GPS] Stopped 2025-02-23T17:34:12.257Z,1740332052.257 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-23T17:34:17.096Z,1740332057.096 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T17:34:30.514Z,1740332070.514 [NAL9602](INFO): SBD MO Status=1, MOMSN=13242, MT Status=0, MTMSN=0 2025-02-23T17:34:30.579Z,1740332070.579 [NAL9602](INFO): Sent 213 bytes from file Logs/20250223T170550/Courier0006.lzma 2025-02-23T17:34:30.580Z,1740332070.580 [NAL9602](INFO): Packets left to send: 0 2025-02-23T17:34:48.219Z,1740332088.219 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T17:34:58.650Z,1740332098.650 [NAL9602](INFO): SBD MO Status=1, MOMSN=13243, MT Status=0, MTMSN=0 2025-02-23T17:34:58.699Z,1740332098.699 [NAL9602](INFO): Sent 332 bytes from file Logs/20250223T170550/Express0007.lzma 2025-02-23T17:34:58.700Z,1740332098.700 [NAL9602](INFO): Packets left to send: 1 2025-02-23T17:35:11.769Z,1740332111.769 [NAL9602](INFO): SBD MO Status=1, MOMSN=13244, MT Status=0, MTMSN=0 2025-02-23T17:35:11.819Z,1740332111.819 [NAL9602](INFO): Sent 222 bytes from file Logs/20250223T170550/Express0007.lzma 2025-02-23T17:35:11.820Z,1740332111.820 [NAL9602](INFO): Packets left to send: 0 2025-02-23T17:35:19.344Z,1740332119.344 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T17:35:30.484Z,1740332130.484 [NAL9602](INFO): SBD MO Status=0, MOMSN=13245, MT Status=0, MTMSN=0 2025-02-23T17:35:49.790Z,1740332149.790 [NAL9602](INFO): SBD MO Status=1, MOMSN=13246, MT Status=0, MTMSN=0 2025-02-23T17:35:49.843Z,1740332149.843 [NAL9602](INFO): Sent 39 bytes from file Logs/20250223T170550/Courier0009.lzma 2025-02-23T17:35:49.844Z,1740332149.844 [NAL9602](INFO): Packets left to send: 0 2025-02-23T17:35:50.472Z,1740332150.472 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T17:35:57.366Z,1740332157.366 [NAL9602](INFO): SBD MO Status=1, MOMSN=13247, MT Status=0, MTMSN=0 2025-02-23T17:35:57.420Z,1740332157.420 [NAL9602](INFO): Sent 74 bytes from file Logs/20250223T170550/Express0010.lzma 2025-02-23T17:35:57.420Z,1740332157.420 [NAL9602](INFO): Packets left to send: 0 2025-02-23T17:35:59.000Z,1740332159.000 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003719 2025-02-23T17:36:00.737Z,1740332160.737 [Default:CheckIn:Read_Iridium] Stopped 2025-02-23T17:36:00.738Z,1740332160.738 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-23T17:36:00.738Z,1740332160.738 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-23T17:36:05.170Z,1740332165.170 [NAL9602](INFO): SBD MO Status=0, MOMSN=13248, MT Status=0, MTMSN=0 2025-02-23T17:36:35.904Z,1740332195.904 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-23T17:40:10.456Z,1740332410.456 [DAT](INFO): DAT read: user:6>Rx Time:17:40:09.4744 2025-02-23T17:40:10.473Z,1740332410.473 [DAT](INFO): Rx dataTimestamp_ set to:1740332410.472258 2025-02-23T17:40:10.473Z,1740332410.473 [DAT](INFO): DAT read: 2025-02-23T17:40:10.474Z,1740332410.474 [DAT](INFO): DAT read: $Packet for address 0 2025-02-23T17:40:10.474Z,1740332410.474 [DAT](INFO): received a packet notification 2025-02-23T17:41:01.175Z,1740332461.175 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-23T17:41:01.175Z,1740332461.175 [Default:CheckIn:C.Wait] Stopped 2025-02-23T17:41:01.175Z,1740332461.175 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-23T17:41:01.190Z,1740332461.190 [Default:CheckIn:D] Running Loop=1 2025-02-23T17:41:01.583Z,1740332461.583 [Default:CheckIn:D] Stopped 2025-02-23T17:41:01.583Z,1740332461.583 [Default:CheckIn:E] Running Loop=1 2025-02-23T17:41:01.988Z,1740332461.988 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.084549 min 2025-02-23T17:41:01.988Z,1740332461.988 [Default:CheckIn:E] Stopped 2025-02-23T17:41:01.988Z,1740332461.988 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-23T17:41:01.988Z,1740332461.988 [Default:CheckIn] Stopped 2025-02-23T17:41:01.988Z,1740332461.988 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-23T17:41:01.989Z,1740332461.989 [Default:CheckIn](INFO): Running loop #2 2025-02-23T17:41:01.989Z,1740332461.989 [Default:CheckIn] Running Loop=2 2025-02-23T17:41:01.989Z,1740332461.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-23T17:41:01.989Z,1740332461.989 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-23T17:41:03.994Z,1740332463.994 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174103.00,A,3647.82292,N,12150.82744,W,0.719,114.91,230225,,,A*74 2025-02-23T17:41:03.997Z,1740332463.997 [NAL9602](INFO): GPS fix at 20250223T174103: (36.797049, -121.847124) 2025-02-23T17:41:04.041Z,1740332464.041 [Default:CheckIn:Read_GPS] Stopped 2025-02-23T17:41:04.041Z,1740332464.041 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-23T17:41:14.799Z,1740332474.799 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250223T170550/Courier0012.lzma 2025-02-23T17:41:15.805Z,1740332475.805 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Courier0012.lzma.bak 2025-02-23T17:41:15.805Z,1740332475.805 [DataOverHttps](INFO): SBD MOMSN=23973340 2025-02-23T17:41:24.122Z,1740332484.122 [NAL9602](INFO): SBD MO Status=0, MOMSN=13249, MT Status=0, MTMSN=0 2025-02-23T17:41:24.122Z,1740332484.122 [NAL9602](INFO): No messages in MT queue 2025-02-23T17:41:39.943Z,1740332499.943 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20250223T170550/Express0013.lzma 2025-02-23T17:41:40.945Z,1740332500.945 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Express0013.lzma.bak 2025-02-23T17:41:40.945Z,1740332500.945 [DataOverHttps](INFO): SBD MOMSN=23973344 2025-02-23T17:41:43.144Z,1740332503.144 [Default:CheckIn:Read_Iridium] Stopped 2025-02-23T17:41:43.144Z,1740332503.144 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-23T17:41:43.144Z,1740332503.144 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-23T17:41:54.808Z,1740332514.808 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-23T17:46:43.676Z,1740332803.676 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-23T17:46:43.676Z,1740332803.676 [Default:CheckIn:C.Wait] Stopped 2025-02-23T17:46:43.677Z,1740332803.677 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-23T17:46:43.677Z,1740332803.677 [Default:CheckIn:D] Running Loop=1 2025-02-23T17:46:43.963Z,1740332803.963 [Default:CheckIn:D] Stopped 2025-02-23T17:46:43.963Z,1740332803.963 [Default:CheckIn:E] Running Loop=1 2025-02-23T17:46:44.362Z,1740332804.362 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.790883 min 2025-02-23T17:46:44.362Z,1740332804.362 [Default:CheckIn:E] Stopped 2025-02-23T17:46:44.362Z,1740332804.362 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-23T17:46:44.362Z,1740332804.362 [Default:CheckIn] Stopped 2025-02-23T17:46:44.363Z,1740332804.363 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-23T17:46:44.363Z,1740332804.363 [Default:CheckIn](INFO): Running loop #3 2025-02-23T17:46:44.363Z,1740332804.363 [Default:CheckIn] Running Loop=3 2025-02-23T17:46:44.364Z,1740332804.364 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-23T17:46:44.364Z,1740332804.364 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-23T17:46:46.374Z,1740332806.374 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174645.00,A,3647.80913,N,12150.80244,W,0.641,112.36,230225,,,A*71 2025-02-23T17:46:46.377Z,1740332806.377 [NAL9602](INFO): GPS fix at 20250223T174645: (36.796819, -121.846707) 2025-02-23T17:46:46.389Z,1740332806.389 [Default:CheckIn:Read_GPS] Stopped 2025-02-23T17:46:46.389Z,1740332806.389 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-23T17:46:54.029Z,1740332814.029 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250223T170550/Courier0015.lzma 2025-02-23T17:46:54.673Z,1740332814.673 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Courier0015.lzma.bak 2025-02-23T17:46:54.673Z,1740332814.673 [DataOverHttps](INFO): SBD MOMSN=23973347 2025-02-23T17:47:11.902Z,1740332831.902 [NAL9602](INFO): SBD MO Status=0, MOMSN=13250, MT Status=0, MTMSN=0 2025-02-23T17:47:11.902Z,1740332831.902 [NAL9602](INFO): No messages in MT queue 2025-02-23T17:47:14.823Z,1740332834.823 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20250223T170550/Express0016.lzma 2025-02-23T17:47:15.821Z,1740332835.821 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Express0016.lzma.bak 2025-02-23T17:47:15.821Z,1740332835.821 [DataOverHttps](INFO): SBD MOMSN=23973350 2025-02-23T17:47:17.563Z,1740332837.563 [Default:CheckIn:Read_Iridium] Stopped 2025-02-23T17:47:17.563Z,1740332837.563 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-23T17:47:17.563Z,1740332837.563 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-23T17:47:42.588Z,1740332862.588 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-23T17:50:28.757Z,1740333028.757 [DataOverHttps](IMPORTANT): SBD MTMSN=20250223T175027 2025-02-23T17:50:36.097Z,1740333036.097 [DataOverHttps](INFO): Received command: configset verticalcontrol.kpdepthbuoy 30 ratio persist; 2025-02-23T17:50:36.539Z,1740333036.539 [CommandExec](IMPORTANT): got command configSet VerticalControl.kpDepthBuoy 30 ratio persist 2025-02-23T17:50:37.098Z,1740333037.098 [DataOverHttps](IMPORTANT): SBD MTMSN=20250223T175036 2025-02-23T17:50:45.371Z,1740333045.371 [DataOverHttps](INFO): Received command: sched asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 8 h;set keepstation.NeedCommsTime 3 h;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree" 275k8 1 2 2025-02-23T17:50:45.440Z,1740333045.440 [CommandExec](IMPORTANT): got command schedule asap "load Transport/keepstation.tl;set keepstation.MissionTimeout 8 h;set keepstation.NeedCommsTime 3 h;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree" 275k8 1 2.000000 2025-02-23T17:50:45.440Z,1740333045.440 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=275k8 2025-02-23T17:50:45.441Z,1740333045.441 [CommandExec](IMPORTANT): Scheduled #92 (#1 of 2 with id='275k8'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 8 h;set keepstation.NeedCommsTime 3 h;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree", ASAP 2025-02-23T17:50:46.373Z,1740333046.373 [DataOverHttps](IMPORTANT): SBD MTMSN=20250223T175045 2025-02-23T17:50:53.863Z,1740333053.863 [DataOverHttps](INFO): Received command: sched asap "set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 275k8 2 2 2025-02-23T17:50:53.916Z,1740333053.916 [CommandExec](IMPORTANT): got command schedule asap "set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 275k8 2 2.000000 2025-02-23T17:50:53.916Z,1740333053.916 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=275k8 2025-02-23T17:50:53.917Z,1740333053.917 [CommandExec](IMPORTANT): Scheduled #93 (#2 of 2 with id='275k8'): "set keepstation:BackseatDriver.EnableBackseat 1 bool;run", ASAP 2025-02-23T17:50:54.028Z,1740333054.028 [CommandExec](IMPORTANT): got command load ./Missions/Transport/keepstation.tl 2025-02-23T17:50:54.029Z,1740333054.029 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl 2025-02-23T17:50:54.029Z,1740333054.029 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl 2025-02-23T17:50:54.032Z,1740333054.032 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl 2025-02-23T17:50:54.788Z,1740333054.788 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into: 4 45 NaN NaN 30 15 4 0.75 200 45 2 2025-02-23T17:50:54.808Z,1740333054.808 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h 2025-02-23T17:50:54.812Z,1740333054.812 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min 2025-02-23T17:50:54.814Z,1740333054.814 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg 2025-02-23T17:50:54.817Z,1740333054.817 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg 2025-02-23T17:50:54.819Z,1740333054.819 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m 2025-02-23T17:50:54.821Z,1740333054.821 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m 2025-02-23T17:50:54.823Z,1740333054.823 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m 2025-02-23T17:50:54.825Z,1740333054.825 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s 2025-02-23T17:50:54.827Z,1740333054.827 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m 2025-02-23T17:50:54.829Z,1740333054.829 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m 2025-02-23T17:50:54.831Z,1740333054.831 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km 2025-02-23T17:50:54.832Z,1740333054.832 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2025-02-23T17:50:54.832Z,1740333054.832 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2025-02-23T17:50:54.833Z,1740333054.833 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2025-02-23T17:50:55.141Z,1740333055.141 [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-02-23T17:50:55.146Z,1740333055.146 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h 2025-02-23T17:50:55.148Z,1740333055.148 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min 2025-02-23T17:50:55.150Z,1740333055.150 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg 2025-02-23T17:50:55.153Z,1740333055.153 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s 2025-02-23T17:50:55.155Z,1740333055.155 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s 2025-02-23T17:50:55.157Z,1740333055.157 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s 2025-02-23T17:50:55.159Z,1740333055.159 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min 2025-02-23T17:50:55.161Z,1740333055.161 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min 2025-02-23T17:50:55.173Z,1740333055.173 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-02-23T17:50:55.217Z,1740333055.217 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2025-02-23T17:50:55.217Z,1740333055.217 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2025-02-23T17:50:55.218Z,1740333055.218 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2025-02-23T17:50:55.378Z,1740333055.378 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2025-02-23T17:50:55.383Z,1740333055.383 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m 2025-02-23T17:50:55.386Z,1740333055.386 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2025-02-23T17:50:55.389Z,1740333055.389 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m 2025-02-23T17:50:55.391Z,1740333055.391 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m 2025-02-23T17:50:55.392Z,1740333055.392 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2025-02-23T17:50:55.418Z,1740333055.418 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2025-02-23T17:50:55.432Z,1740333055.432 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2025-02-23T17:50:55.440Z,1740333055.440 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2025-02-23T17:50:55.440Z,1740333055.440 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2025-02-23T17:50:55.441Z,1740333055.441 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2025-02-23T17:50:55.475Z,1740333055.475 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2025-02-23T17:50:55.478Z,1740333055.478 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool 2025-02-23T17:50:55.483Z,1740333055.483 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2025-02-23T17:50:55.486Z,1740333055.486 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2025-02-23T17:50:55.486Z,1740333055.486 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2025-02-23T17:50:55.492Z,1740333055.492 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2025-02-23T17:50:55.625Z,1740333055.625 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2025-02-23T17:50:55.628Z,1740333055.628 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool 2025-02-23T17:50:55.630Z,1740333055.630 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool 2025-02-23T17:50:55.632Z,1740333055.632 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool 2025-02-23T17:50:55.634Z,1740333055.634 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool 2025-02-23T17:50:55.637Z,1740333055.637 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool 2025-02-23T17:50:55.643Z,1740333055.643 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait. 2025-02-23T17:50:55.645Z,1740333055.645 [keepstation:H.Pitch](DEBUG): Construct. 2025-02-23T17:50:55.656Z,1740333055.656 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy. 2025-02-23T17:50:55.659Z,1740333055.659 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct. 2025-02-23T17:50:55.731Z,1740333055.731 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct. 2025-02-23T17:50:55.734Z,1740333055.734 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2025-02-23T17:50:55.763Z,1740333055.763 [keepstation:KeepStation:B.Pitch](DEBUG): Construct. 2025-02-23T17:50:55.776Z,1740333055.776 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation. 2025-02-23T17:50:55.782Z,1740333055.782 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait. 2025-02-23T17:50:55.814Z,1740333055.814 [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. """ MaxDepth = 45 meter """ Maximum allowable depth during the mission. """ MinOffshore = 2 kilometer """ Minimum distance from the shoreline to maintain """ } 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 } call id="StartingMission" refId="NeedComms" aggregate TransitToStation { """ Need a separate aggregate for transit if we want to specify a different depth for the approach. """ 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 } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude } } call id="OnStation" refId="NeedComms" aggregate KeepStation { run in sequence 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 } 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-02-23T17:50:55.815Z,1740333055.815 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/keepstation.tl id=keepstation 2025-02-23T17:51:30.317Z,1740333090.317 [DataOverHttps](IMPORTANT): SBD MTMSN=20250223T175129 2025-02-23T17:51:38.176Z,1740333098.176 [CommandExec](IMPORTANT): got command set keepstation.MissionTimeout 8 hour 2025-02-23T17:51:38.176Z,1740333098.176 [CommandExec](IMPORTANT): got command set keepstation.NeedCommsTime 3 hour 2025-02-23T17:51:38.177Z,1740333098.177 [CommandExec](IMPORTANT): got command set keepstation.Latitude 36.797 degree 2025-02-23T17:51:38.178Z,1740333098.178 [CommandExec](IMPORTANT): got command set keepstation.Longitude -121.847 degree 2025-02-23T17:51:41.603Z,1740333101.603 [DataOverHttps](INFO): Received command: sched 20250223T2155 "set keepstation.depthdeadband 1 meter;" 2025-02-23T17:51:42.605Z,1740333102.605 [DataOverHttps](IMPORTANT): SBD MTMSN=20250223T175141 2025-02-23T17:51:53.731Z,1740333113.731 [DataOverHttps](INFO): Received command: sched "restart logs" 2025-02-23T17:51:54.583Z,1740333114.583 [CommandExec](IMPORTANT): got command set keepstation:BackseatDriver.EnableBackseat 1 bool 2025-02-23T17:51:54.583Z,1740333114.583 [CommandExec](IMPORTANT): got command run 2025-02-23T17:51:54.585Z,1740333114.585 [CommandExec](IMPORTANT): Running 2025-02-23T17:51:54.589Z,1740333114.589 [CommandExec](IMPORTANT): got command schedule 20250223T215500 "set keepstation.depthdeadband 1 meter;" 2025-02-23T17:51:54.590Z,1740333114.590 [CommandExec](IMPORTANT): Scheduled #94: "set keepstation.depthdeadband 1 meter;", AT TIME: 2025-02-23T21:55:00Z 2025-02-23T17:51:54.590Z,1740333114.590 [CommandExec](FAULT): Scheduling was paused by a command at 2025-02-23T17:33:55Z 2025-02-23T17:51:54.592Z,1740333114.592 [CommandExec](IMPORTANT): got command schedule "restart logs" 2025-02-23T17:51:54.593Z,1740333114.593 [CommandExec](IMPORTANT): Scheduled #95: "restart logs", AFTER MISSION 2025-02-23T17:51:54.593Z,1740333114.593 [CommandExec](FAULT): Scheduling was paused by a command at 2025-02-23T17:33:55Z 2025-02-23T17:51:54.808Z,1740333114.808 [Default] Stopped 2025-02-23T17:51:54.808Z,1740333114.808 [Default](DEBUG): Aggregate::uninitialize Default 2025-02-23T17:51:54.808Z,1740333114.808 [Default:B.GoToSurface] Stopped 2025-02-23T17:51:54.809Z,1740333114.809 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-23T17:51:54.809Z,1740333114.809 [Default:CheckIn] Stopped 2025-02-23T17:51:54.809Z,1740333114.809 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-23T17:51:54.809Z,1740333114.809 [Default:CheckIn:C.Wait] Stopped 2025-02-23T17:51:54.809Z,1740333114.809 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-23T17:51:54.809Z,1740333114.809 [MissionManager](IMPORTANT): Started mission keepstation 2025-02-23T17:51:54.809Z,1740333114.809 [keepstation] Running Loop=1 2025-02-23T17:51:54.809Z,1740333114.809 [keepstation](DEBUG): Aggregate::initialize keepstation 2025-02-23T17:51:54.810Z,1740333114.810 [keepstation:StandardEnvelopes] Running Loop=1 2025-02-23T17:51:54.810Z,1740333114.810 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes 2025-02-23T17:51:54.810Z,1740333114.810 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-02-23T17:51:54.810Z,1740333114.810 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2025-02-23T17:51:54.810Z,1740333114.810 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-02-23T17:51:54.810Z,1740333114.810 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2025-02-23T17:51:54.810Z,1740333114.810 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-02-23T17:51:54.810Z,1740333114.810 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2025-02-23T17:51:54.810Z,1740333114.810 [keepstation:BackseatDriver] Running Loop=1 2025-02-23T17:51:54.811Z,1740333114.811 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver 2025-02-23T17:51:54.811Z,1740333114.811 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1 2025-02-23T17:51:54.811Z,1740333114.811 [keepstation:PowerOnly] Running Loop=1 2025-02-23T17:51:54.811Z,1740333114.811 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly 2025-02-23T17:51:54.811Z,1740333114.811 [keepstation:PowerOnly:A] Running Loop=1 2025-02-23T17:51:54.811Z,1740333114.811 [keepstation:PowerOnly:B] Running Loop=1 2025-02-23T17:51:54.811Z,1740333114.811 [keepstation:PowerOnly:C] Running Loop=1 2025-02-23T17:51:54.811Z,1740333114.811 [keepstation:PowerOnly:D] Running Loop=1 2025-02-23T17:51:54.812Z,1740333114.812 [keepstation:PowerOnly:E.Wait] Running Loop=1 2025-02-23T17:51:54.814Z,1740333114.814 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2025-02-23T17:51:54.814Z,1740333114.814 [keepstation:H.Pitch] Running Loop=1 2025-02-23T17:51:54.814Z,1740333114.814 [keepstation:H.Pitch](DEBUG): Initialize. 2025-02-23T17:51:54.814Z,1740333114.814 [keepstation:B] Running Loop=1 2025-02-23T17:51:54.815Z,1740333114.815 [keepstation:H.Pitch] Running Loop=1 2025-02-23T17:51:54.815Z,1740333114.815 [keepstation:PowerOnly] Running Loop=1 2025-02-23T17:51:54.816Z,1740333114.816 [keepstation:PowerOnly:D] Stopped 2025-02-23T17:51:54.816Z,1740333114.816 [keepstation:PowerOnly:C] Stopped 2025-02-23T17:51:54.816Z,1740333114.816 [keepstation:PowerOnly:B] Stopped 2025-02-23T17:51:54.816Z,1740333114.816 [keepstation:PowerOnly:A] Stopped 2025-02-23T17:51:54.816Z,1740333114.816 [keepstation:BackseatDriver] Running Loop=1 2025-02-23T17:51:54.816Z,1740333114.816 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat 2025-02-23T17:51:54.835Z,1740333114.835 [BackseatComponent](INFO): Powering up 2025-02-23T17:51:54.836Z,1740333114.836 [BackseatComponent](INFO): Subscribing to LCM channels. 2025-02-23T17:51:54.865Z,1740333114.865 [keepstation:StandardEnvelopes] Running Loop=1 2025-02-23T17:51:54.865Z,1740333114.865 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2025-02-23T17:51:54.866Z,1740333114.866 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2025-02-23T17:51:54.866Z,1740333114.866 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2025-02-23T17:51:54.866Z,1740333114.866 [keepstation:B] Stopped 2025-02-23T17:51:54.867Z,1740333114.867 [keepstation:D] Running Loop=1 2025-02-23T17:51:55.213Z,1740333115.213 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 11.65 mm. 2025-02-23T17:51:55.219Z,1740333115.219 [keepstation:D] Stopped 2025-02-23T17:51:55.219Z,1740333115.219 [keepstation:E] Running Loop=1 2025-02-23T17:51:55.635Z,1740333115.635 [keepstation:E] Stopped 2025-02-23T17:51:55.635Z,1740333115.635 [keepstation:StartingMission] Running Loop=1 2025-02-23T17:51:55.637Z,1740333115.637 [keepstation:StartingMission](DEBUG): Aggregate::initialize keepstation:StartingMission 2025-02-23T17:51:56.020Z,1740333116.020 [keepstation:NeedComms] Running Loop=1 2025-02-23T17:51:56.020Z,1740333116.020 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-02-23T17:51:56.020Z,1740333116.020 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-02-23T17:51:56.020Z,1740333116.020 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-23T17:51:56.021Z,1740333116.021 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-02-23T17:51:56.024Z,1740333116.024 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-02-23T17:51:56.025Z,1740333116.025 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-02-23T17:51:56.025Z,1740333116.025 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-02-23T17:51:56.025Z,1740333116.025 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-23T17:51:56.026Z,1740333116.026 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-23T17:51:56.026Z,1740333116.026 [keepstation:NeedComms:A] Running Loop=1 2025-02-23T17:51:56.028Z,1740333116.028 [keepstation:NeedComms:A](INFO): last time_fix was: 1740332805.000000 second since 1970/01/01T00:00:00Z 2025-02-23T17:51:56.028Z,1740333116.028 [keepstation:NeedComms:A] Stopped 2025-02-23T17:51:56.424Z,1740333116.424 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2025-02-23T17:51:56.425Z,1740333116.425 [keepstation:NeedComms:C] Running Loop=1 2025-02-23T17:51:56.836Z,1740333116.836 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-02-23T17:51:58.431Z,1740333118.431 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175157.00,A,3647.79653,N,12150.78626,W,0.311,117.99,230225,,,A*7E 2025-02-23T17:51:58.433Z,1740333118.433 [NAL9602](INFO): GPS fix at 20250223T175157: (36.796609, -121.846438) 2025-02-23T17:51:58.448Z,1740333118.448 [keepstation:NeedComms:C] Stopped 2025-02-23T17:51:58.448Z,1740333118.448 [keepstation:NeedComms:D] Running Loop=1 2025-02-23T17:51:58.864Z,1740333118.864 [keepstation:NeedComms:D](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-02-23T17:52:08.664Z,1740333128.664 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250223T170550/Courier0018.lzma 2025-02-23T17:52:09.669Z,1740333129.669 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Courier0018.lzma.bak 2025-02-23T17:52:09.669Z,1740333129.669 [DataOverHttps](INFO): SBD MOMSN=23973363 2025-02-23T17:52:12.170Z,1740333132.170 [NAL9602](INFO): SBD MO Status=0, MOMSN=13251, MT Status=0, MTMSN=0 2025-02-23T17:52:12.170Z,1740333132.170 [NAL9602](INFO): No messages in MT queue 2025-02-23T17:52:25.872Z,1740333145.872 [DataOverHttps](INFO): Sending 755 bytes from file Logs/20250223T170550/Express0019.lzma 2025-02-23T17:52:26.875Z,1740333146.875 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Express0019.lzma.bak 2025-02-23T17:52:26.875Z,1740333146.875 [DataOverHttps](INFO): SBD MOMSN=23973366 2025-02-23T17:52:42.868Z,1740333162.868 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-23T17:52:43.207Z,1740333163.207 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20250223T170550/Express0022.lzma 2025-02-23T17:52:46.311Z,1740333166.311 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Express0022.lzma.bak 2025-02-23T17:52:46.312Z,1740333166.312 [DataOverHttps](INFO): SBD MOMSN=23973396 2025-02-23T17:52:50.176Z,1740333170.176 [keepstation:NeedComms:D] Stopped 2025-02-23T17:52:50.177Z,1740333170.177 [keepstation:NeedComms:E] Running Loop=1 2025-02-23T17:52:50.565Z,1740333170.565 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-02-23T17:52:52.166Z,1740333172.166 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175251.00,A,3647.79500,N,12150.78437,W,0.680,209.93,230225,,,A*77 2025-02-23T17:52:52.168Z,1740333172.168 [NAL9602](INFO): GPS fix at 20250223T175251: (36.796583, -121.846406) 2025-02-23T17:52:52.220Z,1740333172.220 [keepstation:NeedComms:E] Stopped 2025-02-23T17:52:52.221Z,1740333172.221 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-02-23T17:52:52.221Z,1740333172.221 [keepstation:NeedComms] Stopped 2025-02-23T17:52:52.221Z,1740333172.221 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-23T17:52:52.221Z,1740333172.221 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-23T17:52:52.221Z,1740333172.221 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-23T17:52:52.625Z,1740333172.625 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission 2025-02-23T17:52:52.625Z,1740333172.625 [keepstation:StartingMission] Stopped 2025-02-23T17:52:52.625Z,1740333172.625 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission 2025-02-23T17:52:52.625Z,1740333172.625 [keepstation:TransitToStation] Running Loop=1 2025-02-23T17:52:52.625Z,1740333172.625 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation 2025-02-23T17:52:52.625Z,1740333172.625 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2025-02-23T17:52:52.625Z,1740333172.625 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component. 2025-02-23T17:52:52.625Z,1740333172.625 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2025-02-23T17:52:52.625Z,1740333172.625 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize. 2025-02-23T17:52:52.626Z,1740333172.626 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2025-02-23T17:52:52.626Z,1740333172.626 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize. 2025-02-23T17:52:52.626Z,1740333172.626 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1 2025-02-23T17:52:52.626Z,1740333172.626 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2025-02-23T17:52:52.631Z,1740333172.631 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2025-02-23T17:52:53.008Z,1740333173.008 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2025-02-23T17:52:53.009Z,1740333173.009 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1 2025-02-23T17:52:53.009Z,1740333173.009 [keepstation:TransitToStation:B.Pitch] Running Loop=1 2025-02-23T17:52:53.009Z,1740333173.009 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1 2025-02-23T17:53:00.519Z,1740333180.519 [BackseatComponent](IMPORTANT): IBASE811F-02: Running bioacoustics backseat app. Logging to: 20250223T175259 2025-02-23T17:53:04.326Z,1740333184.326 [NAL9602](INFO): SBD MO Status=0, MOMSN=13252, MT Status=0, MTMSN=0 2025-02-23T17:53:04.326Z,1740333184.326 [NAL9602](INFO): No messages in MT queue 2025-02-23T17:53:34.969Z,1740333214.969 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-23T17:54:02.886Z,1740333242.886 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.height_above_sea_floor with code: 1975 2025-02-23T17:54:33.714Z,1740333273.714 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 11.65 mm (2 active estimators). 2025-02-23T17:54:35.312Z,1740333275.312 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000 2025-02-23T17:54:35.312Z,1740333275.312 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped 2025-02-23T17:54:35.312Z,1740333275.312 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2025-02-23T17:54:35.313Z,1740333275.313 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation 2025-02-23T17:54:35.314Z,1740333275.314 [keepstation:TransitToStation] Stopped 2025-02-23T17:54:35.314Z,1740333275.314 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation 2025-02-23T17:54:35.314Z,1740333275.314 [keepstation:TransitToStation:A.Buoyancy] Stopped 2025-02-23T17:54:35.314Z,1740333275.314 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2025-02-23T17:54:35.314Z,1740333275.314 [keepstation:TransitToStation:B.Pitch] Stopped 2025-02-23T17:54:35.314Z,1740333275.314 [keepstation:TransitToStation:C.SetSpeed] Stopped 2025-02-23T17:54:35.314Z,1740333275.314 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize. 2025-02-23T17:54:35.314Z,1740333275.314 [keepstation:OnStation] Running Loop=1 2025-02-23T17:54:35.314Z,1740333275.314 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation 2025-02-23T17:54:35.716Z,1740333275.716 [keepstation:NeedComms] Running Loop=1 2025-02-23T17:54:35.716Z,1740333275.716 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-02-23T17:54:35.716Z,1740333275.716 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-02-23T17:54:35.716Z,1740333275.716 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-23T17:54:35.717Z,1740333275.717 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-02-23T17:54:35.717Z,1740333275.717 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-02-23T17:54:35.717Z,1740333275.717 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-02-23T17:54:35.718Z,1740333275.718 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-02-23T17:54:35.718Z,1740333275.718 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-23T17:54:35.718Z,1740333275.718 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-23T17:54:35.719Z,1740333275.719 [keepstation:NeedComms:A] Running Loop=1 2025-02-23T17:54:35.720Z,1740333275.720 [keepstation:NeedComms:A](INFO): last time_fix was: 1740333171.000000 second since 1970/01/01T00:00:00Z 2025-02-23T17:54:35.720Z,1740333275.720 [keepstation:NeedComms:A] Stopped 2025-02-23T17:55:19.309Z,1740333319.309 [keepstation:NeedComms:C] Running Loop=1 2025-02-23T17:55:30.439Z,1740333330.439 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T17:56:01.571Z,1740333361.571 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T17:56:08.590Z,1740333368.590 [NAL9602](INFO): SBD MO Status=2, MOMSN=13253, MT Status=2, MTMSN=0 2025-02-23T17:56:08.590Z,1740333368.590 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-23T17:56:23.129Z,1740333383.129 [NAL9602](INFO): SBD MO Status=0, MOMSN=13253, MT Status=0, MTMSN=0 2025-02-23T17:56:23.129Z,1740333383.129 [NAL9602](INFO): No messages in MT queue 2025-02-23T17:56:24.343Z,1740333384.343 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175623.00,A,3647.82973,N,12150.81466,W,0.505,0.45,230225,,,A*76 2025-02-23T17:56:24.345Z,1740333384.345 [NAL9602](INFO): GPS fix at 20250223T175623: (36.797162, -121.846911) 2025-02-23T17:56:24.360Z,1740333384.360 [keepstation:NeedComms:C] Stopped 2025-02-23T17:56:24.360Z,1740333384.360 [keepstation:NeedComms:D] Running Loop=1 2025-02-23T17:56:32.696Z,1740333392.696 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T17:56:43.815Z,1740333403.815 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250223T170550/Courier0024.lzma 2025-02-23T17:56:44.817Z,1740333404.817 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Courier0024.lzma.bak 2025-02-23T17:56:44.817Z,1740333404.817 [DataOverHttps](INFO): SBD MOMSN=23973401 2025-02-23T17:56:56.700Z,1740333416.700 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-23T17:57:06.007Z,1740333426.007 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20250223T170550/Express0025.lzma 2025-02-23T17:57:07.009Z,1740333427.009 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Express0025.lzma.bak 2025-02-23T17:57:07.009Z,1740333427.009 [DataOverHttps](INFO): SBD MOMSN=23973406 2025-02-23T17:57:09.328Z,1740333429.328 [keepstation:NeedComms:D] Stopped 2025-02-23T17:57:09.329Z,1740333429.329 [keepstation:NeedComms:E] Running Loop=1 2025-02-23T17:57:11.350Z,1740333431.350 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175710.00,A,3647.82891,N,12150.81220,W,0.680,126.06,230225,,,A*72 2025-02-23T17:57:11.369Z,1740333431.369 [NAL9602](INFO): GPS fix at 20250223T175710: (36.797148, -121.846870) 2025-02-23T17:57:11.561Z,1740333431.561 [keepstation:NeedComms:E] Stopped 2025-02-23T17:57:11.562Z,1740333431.562 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-02-23T17:57:11.562Z,1740333431.562 [keepstation:NeedComms] Stopped 2025-02-23T17:57:11.562Z,1740333431.562 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-23T17:57:11.562Z,1740333431.562 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-23T17:57:11.562Z,1740333431.562 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-23T17:57:11.899Z,1740333431.899 [keepstation:OnStation](INFO): Completed keepstation:OnStation 2025-02-23T17:57:11.899Z,1740333431.899 [keepstation:OnStation] Stopped 2025-02-23T17:57:11.899Z,1740333431.899 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation 2025-02-23T17:57:11.900Z,1740333431.900 [keepstation:KeepStation] Running Loop=1 2025-02-23T17:57:11.900Z,1740333431.900 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation 2025-02-23T17:57:11.900Z,1740333431.900 [keepstation:KeepStation:A] Running Loop=1 2025-02-23T17:57:11.900Z,1740333431.900 [keepstation:KeepStation:B.Pitch] Running Loop=1 2025-02-23T17:57:11.900Z,1740333431.900 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize. 2025-02-23T17:57:11.900Z,1740333431.900 [keepstation:KeepStation:C.KeepStation] Running Loop=1 2025-02-23T17:57:11.900Z,1740333431.900 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent. 2025-02-23T17:57:11.901Z,1740333431.901 [keepstation:KeepStation:D.Wait] Running Loop=1 2025-02-23T17:57:11.901Z,1740333431.901 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component. 2025-02-23T17:57:12.247Z,1740333432.247 [keepstation:KeepStation:C.KeepStation] Running Loop=1 2025-02-23T17:57:12.264Z,1740333432.264 [keepstation:KeepStation:B.Pitch] Running Loop=1 2025-02-23T17:57:12.264Z,1740333432.264 [keepstation:KeepStation:A] Running Loop=1 2025-02-23T17:57:43.331Z,1740333463.331 [NAL9602](INFO): SBD MO Status=2, MOMSN=13254, MT Status=2, MTMSN=0 2025-02-23T17:57:43.331Z,1740333463.331 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-23T17:58:01.753Z,1740333481.753 [keepstation:BackseatDriver:A.BackseatDriver](INFO): Created data writer for _.simrad_data_logging_rate with code: 1976 2025-02-23T17:58:25.110Z,1740333505.110 [NAL9602](INFO): SBD MO Status=2, MOMSN=13254, MT Status=2, MTMSN=0 2025-02-23T17:58:25.110Z,1740333505.110 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-23T17:58:48.950Z,1740333528.950 [NAL9602](INFO): SBD MO Status=2, MOMSN=13254, MT Status=2, MTMSN=0 2025-02-23T17:58:48.950Z,1740333528.950 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-23T17:59:17.302Z,1740333557.302 [NAL9602](INFO): SBD MO Status=2, MOMSN=13254, MT Status=2, MTMSN=0 2025-02-23T17:59:17.302Z,1740333557.302 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-23T17:59:58.019Z,1740333598.019 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T18:00:29.143Z,1740333629.143 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T18:00:36.383Z,1740333636.383 [Radio_Surface](INFO): Powering down 2025-02-23T18:01:00.271Z,1740333660.271 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T18:01:01.276Z,1740333661.276 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-02-23T18:02:08.544Z,1740333728.544 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.747421 2025-02-23T18:02:13.869Z,1740333733.869 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-02-23T18:02:44.568Z,1740333764.568 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-23T18:10:52.610Z,1740334252.610 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T18:11:04.714Z,1740334264.714 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T18:11:18.047Z,1740334278.047 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T18:11:30.570Z,1740334290.570 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T18:11:43.497Z,1740334303.497 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T18:11:55.618Z,1740334315.618 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T18:12:08.142Z,1740334328.142 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T18:17:15.585Z,1740334635.585 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-02-23T18:17:16.402Z,1740334636.402 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-02-23T18:17:16.403Z,1740334636.403 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-02-23T18:17:16.406Z,1740334636.406 [BPC1](INFO): Received data from all battery sticks. 2025-02-23T19:18:23.525Z,1740338303.525 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T19:18:36.060Z,1740338316.060 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T19:18:48.590Z,1740338328.590 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T19:19:01.516Z,1740338341.516 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T19:19:14.026Z,1740338354.026 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T19:19:26.954Z,1740338366.954 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T19:19:39.882Z,1740338379.882 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T19:24:36.886Z,1740338676.886 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-02-23T19:24:42.482Z,1740338682.482 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-02-23T19:24:47.756Z,1740338687.756 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4BD0. 2025-02-23T19:24:47.757Z,1740338687.757 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-02-23T19:24:47.759Z,1740338687.759 [BPC1](INFO): Received data from all battery sticks. 2025-02-23T20:25:54.446Z,1740342354.446 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T20:26:07.779Z,1740342367.779 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T20:26:19.898Z,1740342379.898 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T20:26:33.230Z,1740342393.230 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T20:26:46.562Z,1740342406.562 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T20:26:59.490Z,1740342419.490 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T20:27:12.419Z,1740342432.419 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T20:32:12.591Z,1740342732.591 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-02-23T20:32:21.081Z,1740342741.081 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4BD0. 2025-02-23T20:32:21.081Z,1740342741.081 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-02-23T20:32:21.096Z,1740342741.096 [BPC1](INFO): Received data from all battery sticks. 2025-02-23T21:03:42.120Z,1740344622.120 [keepstation:NeedComms] Running Loop=1 2025-02-23T21:03:42.120Z,1740344622.120 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-02-23T21:03:42.120Z,1740344622.120 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-02-23T21:03:42.120Z,1740344622.120 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-23T21:03:42.121Z,1740344622.121 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-02-23T21:03:42.121Z,1740344622.121 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-02-23T21:03:42.121Z,1740344622.121 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-02-23T21:03:42.122Z,1740344622.122 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-02-23T21:03:42.122Z,1740344622.122 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-23T21:03:42.122Z,1740344622.122 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-23T21:03:42.123Z,1740344622.123 [keepstation:NeedComms:A] Running Loop=1 2025-02-23T21:03:42.124Z,1740344622.124 [keepstation:NeedComms:A](INFO): last time_fix was: 1740333430.000000 second since 1970/01/01T00:00:00Z 2025-02-23T21:03:42.124Z,1740344622.124 [keepstation:NeedComms:A] Stopped 2025-02-23T21:05:12.233Z,1740344712.233 [keepstation:NeedComms:C] Running Loop=1 2025-02-23T21:05:12.887Z,1740344712.887 [Radio_Surface](INFO): Powering up 2025-02-23T21:05:18.916Z,1740344718.916 [DataOverHttps](INFO): Radio surface powered ON. 2025-02-23T21:05:44.940Z,1740344744.940 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-02-23T21:05:45.023Z,1740344745.023 [NAL9602](ERROR): received: +CSQ:0 OK254, 2, 0, 0, 0 OK 2025-02-23T21:05:49.036Z,1740344749.036 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T21:06:06.910Z,1740344766.910 [NAL9602](INFO): SBD MO Status=0, MOMSN=13254, MT Status=0, MTMSN=0 2025-02-23T21:06:06.910Z,1740344766.910 [NAL9602](INFO): No messages in MT queue 2025-02-23T21:06:20.175Z,1740344780.175 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T21:06:51.299Z,1740344811.299 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T21:07:22.456Z,1740344842.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T21:07:36.236Z,1740344856.236 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003566 2025-02-23T21:08:19.934Z,1740344899.934 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210819.00,A,3648.13435,N,12150.84209,W,0.778,157.20,230225,,,A*7B 2025-02-23T21:08:19.936Z,1740344899.936 [NAL9602](INFO): GPS fix at 20250223T210819: (36.802239, -121.847368) 2025-02-23T21:08:19.971Z,1740344899.971 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2025-02-23T21:08:19.976Z,1740344899.976 [keepstation:NeedComms:C] Stopped 2025-02-23T21:08:19.976Z,1740344899.976 [keepstation:NeedComms:D] Running Loop=1 2025-02-23T21:08:29.323Z,1740344909.323 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250223T170550/Courier0027.lzma 2025-02-23T21:08:30.325Z,1740344910.325 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Courier0027.lzma.bak 2025-02-23T21:08:30.325Z,1740344910.325 [DataOverHttps](INFO): SBD MOMSN=23973412 2025-02-23T21:08:48.367Z,1740344928.367 [DataOverHttps](INFO): Sending 449 bytes from file Logs/20250223T170550/Express0028.lzma 2025-02-23T21:08:49.369Z,1740344929.369 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Express0028.lzma.bak 2025-02-23T21:08:49.369Z,1740344929.369 [DataOverHttps](INFO): SBD MOMSN=23973415 2025-02-23T21:08:51.128Z,1740344931.128 [keepstation:NeedComms:D] Stopped 2025-02-23T21:08:51.128Z,1740344931.128 [keepstation:NeedComms:E] Running Loop=1 2025-02-23T21:08:53.122Z,1740344933.122 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210852.00,A,3648.13049,N,12150.83766,W,0.389,83.73,230225,,,A*44 2025-02-23T21:08:53.124Z,1740344933.124 [NAL9602](INFO): GPS fix at 20250223T210852: (36.802175, -121.847294) 2025-02-23T21:08:53.176Z,1740344933.176 [keepstation:NeedComms:E] Stopped 2025-02-23T21:08:53.177Z,1740344933.177 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-02-23T21:08:53.177Z,1740344933.177 [keepstation:NeedComms] Stopped 2025-02-23T21:08:53.177Z,1740344933.177 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-23T21:08:53.177Z,1740344933.177 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-23T21:08:53.177Z,1740344933.177 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-23T21:09:25.552Z,1740344965.552 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-23T21:10:22.195Z,1740345022.195 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T21:10:24.695Z,1740345024.695 [Radio_Surface](INFO): Powering down 2025-02-23T21:10:53.319Z,1740345053.319 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-23T21:10:54.324Z,1740345054.324 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-02-23T21:12:02.604Z,1740345122.604 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.777725 2025-02-23T21:22:27.968Z,1740345747.968 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2025-02-23T21:22:28.363Z,1740345748.363 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm. 2025-02-23T21:33:27.682Z,1740346407.682 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T21:33:40.610Z,1740346420.610 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T21:33:53.537Z,1740346433.537 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T21:34:06.870Z,1740346446.870 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T21:34:19.394Z,1740346459.394 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T21:34:32.321Z,1740346472.321 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T21:34:44.846Z,1740346484.846 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T21:39:48.253Z,1740346788.253 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-02-23T21:39:54.322Z,1740346794.322 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4BD0. 2025-02-23T21:39:54.323Z,1740346794.323 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-02-23T21:39:54.325Z,1740346794.325 [BPC1](INFO): Received data from all battery sticks. 2025-02-23T21:55:00.011Z,1740347700.011 [CommandExec](FAULT): Schedule is paused. Ignoring time-scheduled command: set keepstation.depthdeadband 1 meter; 2025-02-23T22:41:01.027Z,1740350461.027 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T22:41:13.955Z,1740350473.955 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T22:41:27.287Z,1740350487.287 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T22:41:40.214Z,1740350500.214 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T22:41:53.142Z,1740350513.142 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T22:42:06.071Z,1740350526.071 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T22:42:18.999Z,1740350538.999 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T22:47:19.977Z,1740350839.977 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-02-23T22:47:25.229Z,1740350845.229 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-02-23T22:47:25.655Z,1740350845.655 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4BD0. 2025-02-23T22:47:25.655Z,1740350845.655 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6. 2025-02-23T22:47:25.658Z,1740350845.658 [BPC1](INFO): Received data from all battery sticks. 2025-02-23T23:26:32.520Z,1740353192.520 [CBIT](IMPORTANT): Beginning ground fault scan 2025-02-23T23:26:43.416Z,1740353203.416 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.001268 CHAN A1 (24V): -0.000292 CHAN A2 (12V): 0.000616 CHAN A3 (5V): -0.001757 CHAN B0 (3.3V): -0.001051 CHAN B1 (3.15aV): -0.001614 CHAN B2 (3.15bV): -0.001161 CHAN B3 (GND): -0.002246 OPEN: 0.004677 Full Scale: +/- 1 mA 2025-02-23T23:48:31.942Z,1740354511.942 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T23:48:45.276Z,1740354525.276 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T23:48:58.203Z,1740354538.203 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T23:49:11.533Z,1740354551.533 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T23:49:24.469Z,1740354564.469 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T23:49:37.793Z,1740354577.793 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T23:49:50.318Z,1740354590.318 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-23T23:54:51.701Z,1740354891.701 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-02-23T23:54:59.386Z,1740354899.386 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4BD0. 2025-02-23T23:54:59.386Z,1740354899.386 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-02-23T23:54:59.390Z,1740354899.390 [BPC1](INFO): Received data from all battery sticks. 2025-02-24T00:08:53.252Z,1740355733.252 [keepstation:NeedComms] Running Loop=1 2025-02-24T00:08:53.252Z,1740355733.252 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms 2025-02-24T00:08:53.252Z,1740355733.252 [keepstation:NeedComms:B.GoToSurface] Running Loop=1 2025-02-24T00:08:53.252Z,1740355733.252 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-24T00:08:53.253Z,1740355733.253 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s. 2025-02-24T00:08:53.253Z,1740355733.253 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees. 2025-02-24T00:08:53.253Z,1740355733.253 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s. 2025-02-24T00:08:53.254Z,1740355733.254 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds. 2025-02-24T00:08:53.254Z,1740355733.254 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-24T00:08:53.254Z,1740355733.254 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-24T00:08:53.255Z,1740355733.255 [keepstation:NeedComms:A] Running Loop=1 2025-02-24T00:08:53.257Z,1740355733.257 [keepstation:NeedComms:A](INFO): last time_fix was: 1740344932.000000 second since 1970/01/01T00:00:00Z 2025-02-24T00:08:53.257Z,1740355733.257 [keepstation:NeedComms:A] Stopped 2025-02-24T00:10:53.565Z,1740355853.565 [keepstation:NeedComms:C] Running Loop=1 2025-02-24T00:10:54.028Z,1740355854.028 [Radio_Surface](INFO): Powering up 2025-02-24T00:10:59.891Z,1740355859.891 [DataOverHttps](INFO): Radio surface powered ON. 2025-02-24T00:11:30.014Z,1740355890.014 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-24T00:12:01.136Z,1740355921.136 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-24T00:12:32.259Z,1740355952.259 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-24T00:12:46.681Z,1740355966.681 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=13255, MT Status=1, MTMSN=272 2025-02-24T00:12:46.681Z,1740355966.681 [NAL9602](INFO): Data available in MT queue 2025-02-24T00:12:47.396Z,1740355967.396 [NAL9602](INFO): Received command: sched "load Transport/keepstation.tl;set keepstation.MissionTimeout 3.5 h;set keepstation.NeedCommsTime 3 h;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.Depth 5 m" 27jjg 1 2 2025-02-24T00:12:47.497Z,1740355967.497 [CommandExec](IMPORTANT): got command schedule "load Transport/keepstation.tl;set keepstation.MissionTimeout 3.5 h;set keepstation.NeedCommsTime 3 h;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.Depth 5 m" 27jjg 1 2.000000 2025-02-24T00:12:47.498Z,1740355967.498 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=27jjg 2025-02-24T00:12:47.499Z,1740355967.499 [CommandExec](IMPORTANT): Scheduled #96 (#1 of 2 with id='27jjg'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 3.5 h;set keepstation.NeedCommsTime 3 h;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.Depth 5 m", AFTER MISSION 2025-02-24T00:12:47.499Z,1740355967.499 [CommandExec](FAULT): Scheduling was paused by a command at 2025-02-23T17:33:55Z 2025-02-24T00:13:03.396Z,1740355983.396 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-24T00:13:16.485Z,1740355996.485 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004167 2025-02-24T00:13:32.871Z,1740356012.871 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001332.00,A,3647.99092,N,12150.53922,W,0.214,112.12,240225,,,A*7F 2025-02-24T00:13:32.873Z,1740356012.873 [NAL9602](INFO): GPS fix at 20250224T001332: (36.799849, -121.842320) 2025-02-24T00:13:32.886Z,1740356012.886 [UniversalFixResidualReporter](INFO): Fix residual: 85.9 %DT, over the last 741.7 m. Residual distance 636.8 m at bearing -124.1 degrees. Fix at (36.7999, -121.8423) with 512.9 m made good. 2025-02-24T00:13:32.887Z,1740356012.887 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint 2025-02-24T00:13:32.892Z,1740356012.892 [keepstation:NeedComms:C] Stopped 2025-02-24T00:13:32.893Z,1740356012.893 [keepstation:NeedComms:D] Running Loop=1 2025-02-24T00:13:40.315Z,1740356020.315 [DataOverHttps](INFO): Sending 93 bytes from file Logs/20250223T170550/Courier0030.lzma 2025-02-24T00:13:41.317Z,1740356021.317 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Courier0030.lzma.bak 2025-02-24T00:13:41.317Z,1740356021.317 [DataOverHttps](INFO): SBD MOMSN=23973430 2025-02-24T00:13:57.597Z,1740356037.597 [DataOverHttps](INFO): Sending 1033 bytes from file Logs/20250223T170550/Express0031.lzma 2025-02-24T00:13:58.597Z,1740356038.597 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Express0031.lzma.bak 2025-02-24T00:13:58.597Z,1740356038.597 [DataOverHttps](INFO): SBD MOMSN=23973433 2025-02-24T00:14:00.376Z,1740356040.376 [keepstation:NeedComms:D] Stopped 2025-02-24T00:14:00.376Z,1740356040.376 [keepstation:NeedComms:E] Running Loop=1 2025-02-24T00:14:02.383Z,1740356042.383 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001401.00,A,3647.98811,N,12150.53705,W,0.641,137.91,240225,,,A*79 2025-02-24T00:14:02.386Z,1740356042.386 [NAL9602](INFO): GPS fix at 20250224T001401: (36.799802, -121.842284) 2025-02-24T00:14:02.472Z,1740356042.472 [keepstation:NeedComms:E] Stopped 2025-02-24T00:14:02.473Z,1740356042.473 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms 2025-02-24T00:14:02.473Z,1740356042.473 [keepstation:NeedComms] Stopped 2025-02-24T00:14:02.473Z,1740356042.473 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms 2025-02-24T00:14:02.473Z,1740356042.473 [keepstation:NeedComms:B.GoToSurface] Stopped 2025-02-24T00:14:02.473Z,1740356042.473 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-24T00:14:34.352Z,1740356074.352 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-24T00:15:32.187Z,1740356132.187 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-24T00:15:47.591Z,1740356147.591 [Radio_Surface](INFO): Powering down 2025-02-24T00:16:03.311Z,1740356163.311 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-24T00:16:04.316Z,1740356164.316 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-02-24T00:17:40.700Z,1740356260.700 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.719421 2025-02-24T00:26:09.504Z,1740356769.504 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest 2025-02-24T00:26:09.884Z,1740356769.884 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 0.75 m/s, pitch 20.00 deg, mass-position 11.65 mm. 2025-02-24T00:56:06.062Z,1740358566.062 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-24T00:56:19.394Z,1740358579.394 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-24T00:56:32.322Z,1740358592.322 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-24T00:56:44.846Z,1740358604.846 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-24T00:56:58.177Z,1740358618.177 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-24T00:57:11.106Z,1740358631.106 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-24T00:57:24.033Z,1740358644.033 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-02-24T01:02:25.830Z,1740358945.830 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-02-24T01:02:31.893Z,1740358951.893 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4BD0. 2025-02-24T01:02:31.894Z,1740358951.894 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-02-24T01:02:31.896Z,1740358951.896 [BPC1](INFO): Received data from all battery sticks. 2025-02-24T01:51:54.853Z,1740361914.853 [keepstation](INFO): Timed out from 2025-02-23T17:51:54.8Z 2025-02-24T01:51:54.854Z,1740361914.854 [MissionManager](INFO): keepstation is completed. 2025-02-24T01:51:54.854Z,1740361914.854 [MissionManager](INFO): Uninitializing Mission keepstation 2025-02-24T01:51:54.854Z,1740361914.854 [keepstation] Stopped 2025-02-24T01:51:54.854Z,1740361914.854 [keepstation](DEBUG): Aggregate::uninitialize keepstation 2025-02-24T01:51:54.854Z,1740361914.854 [keepstation:StandardEnvelopes] Stopped 2025-02-24T01:51:54.854Z,1740361914.854 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes 2025-02-24T01:51:54.854Z,1740361914.854 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2025-02-24T01:51:54.854Z,1740361914.854 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2025-02-24T01:51:54.854Z,1740361914.854 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped 2025-02-24T01:51:54.854Z,1740361914.854 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2025-02-24T01:51:54.855Z,1740361914.855 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2025-02-24T01:51:54.855Z,1740361914.855 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2025-02-24T01:51:54.855Z,1740361914.855 [keepstation:BackseatDriver] Stopped 2025-02-24T01:51:54.855Z,1740361914.855 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver 2025-02-24T01:51:54.855Z,1740361914.855 [keepstation:BackseatDriver:A.BackseatDriver] Stopped 2025-02-24T01:51:54.855Z,1740361914.855 [keepstation:PowerOnly] Stopped 2025-02-24T01:51:54.855Z,1740361914.855 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly 2025-02-24T01:51:54.855Z,1740361914.855 [keepstation:PowerOnly:E.Wait] Stopped 2025-02-24T01:51:54.855Z,1740361914.855 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2025-02-24T01:51:54.855Z,1740361914.855 [keepstation:H.Pitch] Stopped 2025-02-24T01:51:54.856Z,1740361914.856 [keepstation:KeepStation] Stopped 2025-02-24T01:51:54.856Z,1740361914.856 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation 2025-02-24T01:51:54.856Z,1740361914.856 [keepstation:KeepStation:A] Stopped 2025-02-24T01:51:54.856Z,1740361914.856 [keepstation:KeepStation:B.Pitch] Stopped 2025-02-24T01:51:54.856Z,1740361914.856 [keepstation:KeepStation:C.KeepStation] Stopped 2025-02-24T01:51:54.856Z,1740361914.856 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent. 2025-02-24T01:51:54.856Z,1740361914.856 [keepstation:KeepStation:D.Wait] Stopped 2025-02-24T01:51:54.856Z,1740361914.856 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component. 2025-02-24T01:51:54.933Z,1740361914.933 [BackseatComponent](INFO): Sent LCM shutdown request. 2025-02-24T01:51:54.933Z,1740361914.933 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.). 2025-02-24T01:51:55.248Z,1740361915.248 [MissionManager](IMPORTANT): Started mission Default 2025-02-24T01:51:55.248Z,1740361915.248 [Default] Running Loop=1 2025-02-24T01:51:55.248Z,1740361915.248 [Default](DEBUG): Aggregate::initialize Default 2025-02-24T01:51:55.248Z,1740361915.248 [Default:B.GoToSurface] Running Loop=1 2025-02-24T01:51:55.248Z,1740361915.248 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-24T01:51:55.249Z,1740361915.249 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-02-24T01:51:55.249Z,1740361915.249 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-02-24T01:51:55.249Z,1740361915.249 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-02-24T01:51:55.250Z,1740361915.250 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-02-24T01:51:55.250Z,1740361915.250 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-24T01:51:55.250Z,1740361915.250 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-24T01:51:55.251Z,1740361915.251 [Default:A.Wait] Running Loop=1 2025-02-24T01:51:55.251Z,1740361915.251 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-02-24T01:51:55.647Z,1740361915.647 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm (1 active estimators). 2025-02-24T01:51:55.907Z,1740361915.907 [Radio_Surface](INFO): Powering up 2025-02-24T01:52:02.120Z,1740361922.120 [DataOverHttps](INFO): Radio surface powered ON. 2025-02-24T01:52:08.304Z,1740361928.304 [Default:A.Wait](INFO): Done Waiting. 2025-02-24T01:52:08.305Z,1740361928.305 [Default:A.Wait] Stopped 2025-02-24T01:52:08.305Z,1740361928.305 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-02-24T01:52:13.088Z,1740361933.088 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec). 2025-02-24T01:52:13.088Z,1740361933.088 [BackseatComponent](INFO): Unsubscribing from LCM channels. 2025-02-24T01:52:13.089Z,1740361933.089 [LcmUniversalReporter](INFO): Deactivating messaging. 2025-02-24T01:52:32.252Z,1740361952.252 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-24T01:52:58.372Z,1740361978.372 [Default:CheckIn] Running Loop=1 2025-02-24T01:52:58.372Z,1740361978.372 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-24T01:52:58.372Z,1740361978.372 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-24T01:53:03.376Z,1740361983.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-24T01:53:19.546Z,1740361999.546 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=13256, MT Status=1, MTMSN=273 2025-02-24T01:53:19.546Z,1740361999.546 [NAL9602](INFO): Data available in MT queue 2025-02-24T01:53:20.136Z,1740362000.136 [NAL9602](INFO): Received command: sched "set keepstation.DepthDeadband 1 m;set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 27jjg 2 2 2025-02-24T01:53:20.158Z,1740362000.158 [CommandExec](IMPORTANT): got command schedule "set keepstation.DepthDeadband 1 m;set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 27jjg 2 2.000000 2025-02-24T01:53:20.159Z,1740362000.159 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=27jjg 2025-02-24T01:53:20.160Z,1740362000.160 [CommandExec](IMPORTANT): Scheduled #97 (#2 of 2 with id='27jjg'): "set keepstation.DepthDeadband 1 m;set keepstation:BackseatDriver.EnableBackseat 1 bool;run", AFTER MISSION 2025-02-24T01:53:20.161Z,1740362000.161 [CommandExec](FAULT): Scheduling was paused by a command at 2025-02-23T17:33:55Z 2025-02-24T01:53:27.221Z,1740362007.221 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015326.00,A,3647.64007,N,12150.67569,W,0.019,351.70,240225,,,A*7B 2025-02-24T01:53:27.224Z,1740362007.224 [NAL9602](INFO): GPS fix at 20250224T015326: (36.794001, -121.844595) 2025-02-24T01:53:27.254Z,1740362007.254 [UniversalFixResidualReporter](INFO): Fix residual: 74.8 %DT, over the last 573.0 m. Residual distance 428.7 m at bearing -28.4 degrees. Fix at (36.7940, -121.8446) with 677.0 m made good. 2025-02-24T01:53:27.255Z,1740362007.255 [Default:CheckIn:Read_GPS] Stopped 2025-02-24T01:53:27.255Z,1740362007.255 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-24T01:53:34.500Z,1740362014.500 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-24T01:53:45.482Z,1740362025.482 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=13257, MT Status=1, MTMSN=274 2025-02-24T01:53:45.543Z,1740362025.543 [NAL9602](INFO): Sent 92 bytes from file Logs/20250223T170550/Courier0033.lzma 2025-02-24T01:53:45.544Z,1740362025.544 [NAL9602](INFO): Packets left to send: 0 2025-02-24T01:53:46.200Z,1740362026.200 [NAL9602](INFO): Received command: sched "load Transport/keepstation.tl;set keepstation.MissionTimeout 3.5 h;set keepstation.NeedCommsTime 3 h;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.DepthDeadband 20 m" 27jm8 1 2 2025-02-24T01:53:46.221Z,1740362026.221 [CommandExec](IMPORTANT): got command schedule "load Transport/keepstation.tl;set keepstation.MissionTimeout 3.5 h;set keepstation.NeedCommsTime 3 h;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.DepthDeadband 20 m" 27jm8 1 2.000000 2025-02-24T01:53:46.221Z,1740362026.221 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=27jm8 2025-02-24T01:53:46.222Z,1740362026.222 [CommandExec](IMPORTANT): Scheduled #98 (#1 of 2 with id='27jm8'): "load Transport/keepstation.tl;set keepstation.MissionTimeout 3.5 h;set keepstation.NeedCommsTime 3 h;set keepstation.Latitude 36.797 degree;set keepstation.Longitude -121.847 degree;set keepstation.DepthDeadband 20 m", AFTER MISSION 2025-02-24T01:53:46.223Z,1740362026.223 [CommandExec](FAULT): Scheduling was paused by a command at 2025-02-23T17:33:55Z 2025-02-24T01:54:05.628Z,1740362045.628 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-02-24T01:54:08.570Z,1740362048.570 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=13258, MT Status=1, MTMSN=275 2025-02-24T01:54:08.628Z,1740362048.628 [NAL9602](INFO): Sent 332 bytes from file Logs/20250223T170550/Express0034.lzma 2025-02-24T01:54:08.629Z,1740362048.629 [NAL9602](INFO): Packets left to send: 1 2025-02-24T01:54:09.252Z,1740362049.252 [NAL9602](INFO): Received command: sched "set keepstation.MaxDepth 60 m;set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 27jm8 2 2 2025-02-24T01:54:09.286Z,1740362049.286 [CommandExec](IMPORTANT): got command schedule "set keepstation.MaxDepth 60 m;set keepstation:BackseatDriver.EnableBackseat 1 bool;run" 27jm8 2 2.000000 2025-02-24T01:54:09.287Z,1740362049.287 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=27jm8 2025-02-24T01:54:09.288Z,1740362049.288 [CommandExec](IMPORTANT): Scheduled #99 (#2 of 2 with id='27jm8'): "set keepstation.MaxDepth 60 m;set keepstation:BackseatDriver.EnableBackseat 1 bool;run", AFTER MISSION 2025-02-24T01:54:09.288Z,1740362049.288 [CommandExec](FAULT): Scheduling was paused by a command at 2025-02-23T17:33:55Z 2025-02-24T01:54:17.628Z,1740362057.628 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003295 2025-02-24T01:54:28.480Z,1740362068.480 [DataOverHttps](INFO): Sending 584 bytes from file Logs/20250223T170550/Express0034.lzma 2025-02-24T01:54:29.497Z,1740362069.497 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Express0034.lzma.bak 2025-02-24T01:54:29.497Z,1740362069.497 [DataOverHttps](INFO): SBD MOMSN=23973448 2025-02-24T01:54:31.664Z,1740362071.664 [Default:CheckIn:Read_Iridium] Stopped 2025-02-24T01:54:31.664Z,1740362071.664 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-24T01:54:31.664Z,1740362071.664 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-24T01:54:32.046Z,1740362072.046 [NAL9602](INFO): SBD MO Status=2, MOMSN=13259, MT Status=2, MTMSN=0 2025-02-24T01:54:32.046Z,1740362072.046 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-24T01:55:02.739Z,1740362102.739 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-24T01:59:32.274Z,1740362372.274 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-02-24T01:59:32.274Z,1740362372.274 [Default:CheckIn:C.Wait] Stopped 2025-02-24T01:59:32.274Z,1740362372.274 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-24T01:59:32.274Z,1740362372.274 [Default:CheckIn:D] Running Loop=1 2025-02-24T01:59:32.663Z,1740362372.663 [Default:CheckIn:D] Stopped 2025-02-24T01:59:32.663Z,1740362372.663 [Default:CheckIn:E] Running Loop=1 2025-02-24T01:59:33.072Z,1740362373.072 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.623580 min 2025-02-24T01:59:33.072Z,1740362373.072 [Default:CheckIn:E] Stopped 2025-02-24T01:59:33.073Z,1740362373.073 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-02-24T01:59:33.073Z,1740362373.073 [Default:CheckIn] Stopped 2025-02-24T01:59:33.073Z,1740362373.073 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-24T01:59:33.073Z,1740362373.073 [Default:CheckIn](INFO): Running loop #2 2025-02-24T01:59:33.073Z,1740362373.073 [Default:CheckIn] Running Loop=2 2025-02-24T01:59:33.073Z,1740362373.073 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-24T01:59:33.073Z,1740362373.073 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-24T01:59:35.085Z,1740362375.085 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015934.00,A,3647.59550,N,12150.66615,W,0.680,188.42,240225,,,A*73 2025-02-24T01:59:35.087Z,1740362375.087 [NAL9602](INFO): GPS fix at 20250224T015934: (36.793258, -121.844436) 2025-02-24T01:59:35.105Z,1740362375.105 [Default:CheckIn:Read_GPS] Stopped 2025-02-24T01:59:35.105Z,1740362375.105 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-24T01:59:43.175Z,1740362383.175 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250223T170550/Courier0036.lzma 2025-02-24T01:59:44.177Z,1740362384.177 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Courier0036.lzma.bak 2025-02-24T01:59:44.177Z,1740362384.177 [DataOverHttps](INFO): SBD MOMSN=23973456 2025-02-24T01:59:52.858Z,1740362392.858 [NAL9602](INFO): SBD MO Status=1, MOMSN=13259, MT Status=0, MTMSN=0 2025-02-24T01:59:52.858Z,1740362392.858 [NAL9602](INFO): No messages in MT queue 2025-02-24T02:00:00.208Z,1740362400.208 [DataOverHttps](INFO): Sending 525 bytes from file Logs/20250223T170550/Express0037.lzma 2025-02-24T02:00:01.209Z,1740362401.209 [DataOverHttps](INFO): Moved sent file to Logs/20250223T170550/Express0037.lzma.bak 2025-02-24T02:00:01.209Z,1740362401.209 [DataOverHttps](INFO): SBD MOMSN=23973459 2025-02-24T02:00:03.823Z,1740362403.823 [Default:CheckIn:Read_Iridium] Stopped 2025-02-24T02:00:03.823Z,1740362403.823 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-24T02:00:03.823Z,1740362403.823 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-24T02:00:23.559Z,1740362423.559 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-24T02:00:50.316Z,1740362450.316 [DataOverHttps](IMPORTANT): SBD MTMSN=20250224T020049 2025-02-24T02:00:57.703Z,1740362457.703 [DataOverHttps](INFO): Received command: schedule resume 2025-02-24T02:00:57.725Z,1740362457.725 [CommandExec](IMPORTANT): got command schedule resume 2025-02-24T02:00:57.725Z,1740362457.725 [CommandExec](IMPORTANT): Scheduling is resumed