2025-02-19T23:05:01.132Z,1740006301.132 [CommandExec](IMPORTANT): got command configSet Express none PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water persist
2025-02-19T23:05:01.139Z,1740006301.139 [CommandExec](IMPORTANT): got command restart logs
2025-02-19T23:05:05.878Z,1740006305.878 [NAL9602](INFO): SBD MO Status=1, MOMSN=12817, MT Status=0, MTMSN=0
2025-02-19T23:05:05.928Z,1740006305.928 [NAL9602](INFO): Sent 82 bytes from file Logs/20250219T180327/Courier0069.lzma
2025-02-19T23:05:05.928Z,1740006305.928 [NAL9602](INFO): Packets left to send: 0
2025-02-19T23:05:15.255Z,1740006315.255 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:05:25.354Z,1740006325.354 [NAL9602](INFO): SBD MO Status=1, MOMSN=12818, MT Status=0, MTMSN=0
2025-02-19T23:05:25.403Z,1740006325.403 [NAL9602](INFO): Sent 39 bytes from file Logs/20250219T180327/Courier0072.lzma
2025-02-19T23:05:25.404Z,1740006325.404 [NAL9602](INFO): Packets left to send: 0
2025-02-19T23:05:35.414Z,1740006335.414 [NAL9602](INFO): SBD MO Status=1, MOMSN=12819, MT Status=0, MTMSN=0
2025-02-19T23:05:35.476Z,1740006335.476 [NAL9602](INFO): Sent 332 bytes from file Logs/20250219T180327/Express0070.lzma
2025-02-19T23:05:35.476Z,1740006335.476 [NAL9602](INFO): Packets left to send: 3
2025-02-19T23:05:45.970Z,1740006345.970 [NAL9602](INFO): SBD MO Status=1, MOMSN=12820, MT Status=0, MTMSN=0
2025-02-19T23:05:46.035Z,1740006346.035 [NAL9602](INFO): Sent 332 bytes from file Logs/20250219T180327/Express0070.lzma
2025-02-19T23:05:46.036Z,1740006346.036 [NAL9602](INFO): Packets left to send: 2
2025-02-19T23:05:46.384Z,1740006346.384 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:05:57.738Z,1740006357.738 [NAL9602](INFO): SBD MO Status=1, MOMSN=12821, MT Status=0, MTMSN=0
2025-02-19T23:05:57.795Z,1740006357.795 [NAL9602](INFO): Sent 332 bytes from file Logs/20250219T180327/Express0070.lzma
2025-02-19T23:05:57.796Z,1740006357.796 [NAL9602](INFO): Packets left to send: 1
2025-02-19T23:06:07.885Z,1740006367.885 [NAL9602](INFO): SBD MO Status=1, MOMSN=12822, MT Status=0, MTMSN=0
2025-02-19T23:06:07.935Z,1740006367.935 [NAL9602](INFO): Sent 263 bytes from file Logs/20250219T180327/Express0070.lzma
2025-02-19T23:06:07.936Z,1740006367.936 [NAL9602](INFO): Packets left to send: 0
2025-02-19T23:06:14.430Z,1740006374.430 [NAL9602](INFO): SBD MO Status=1, MOMSN=12823, MT Status=0, MTMSN=0
2025-02-19T23:06:14.488Z,1740006374.488 [NAL9602](INFO): Sent 70 bytes from file Logs/20250219T180327/Express0073.lzma
2025-02-19T23:06:14.488Z,1740006374.488 [NAL9602](INFO): Packets left to send: 0
2025-02-19T23:06:17.508Z,1740006377.508 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:06:24.530Z,1740006384.530 [NAL9602](INFO): SBD MO Status=0, MOMSN=12824, MT Status=0, MTMSN=0
2025-02-19T23:06:26.052Z,1740006386.052 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003223
2025-02-19T23:06:34.783Z,1740006394.783 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250219T230501/Courier0000.lzma
2025-02-19T23:06:35.785Z,1740006395.785 [DataOverHttps](INFO): Moved sent file to Logs/20250219T230501/Courier0000.lzma.bak
2025-02-19T23:06:35.785Z,1740006395.785 [DataOverHttps](INFO): SBD MOMSN=23967827
2025-02-19T23:06:51.808Z,1740006411.808 [DataOverHttps](INFO): Sending 387 bytes from file Logs/20250219T230501/Express0001.lzma
2025-02-19T23:06:52.102Z,1740006412.102 [NAL9602](INFO): SBD MO Status=1, MOMSN=12825, MT Status=0, MTMSN=0
2025-02-19T23:06:52.155Z,1740006412.155 [NAL9602](INFO): Sent 151 bytes from file Logs/20250219T230501/Courier0000.lzma
2025-02-19T23:06:52.156Z,1740006412.156 [NAL9602](INFO): Packets left to send: 0
2025-02-19T23:06:52.810Z,1740006412.810 [DataOverHttps](INFO): Moved sent file to Logs/20250219T230501/Express0001.lzma.bak
2025-02-19T23:06:52.810Z,1740006412.810 [DataOverHttps](INFO): SBD MOMSN=23967846
2025-02-19T23:06:54.142Z,1740006414.142 [keepstation:NeedComms:D] Stopped
2025-02-19T23:06:54.142Z,1740006414.142 [keepstation:NeedComms:E] Running Loop=1
2025-02-19T23:06:54.551Z,1740006414.551 [keepstation:NeedComms:E](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-19T23:06:56.142Z,1740006416.142 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230655.00,A,3647.74847,N,12150.80940,W,0.486,174.53,190225,,,D*71
2025-02-19T23:06:56.145Z,1740006416.145 [NAL9602](INFO): GPS fix at 20250219T230655: (36.795808, -121.846823)
2025-02-19T23:06:56.198Z,1740006416.198 [keepstation:NeedComms:E] Stopped
2025-02-19T23:06:56.211Z,1740006416.211 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-19T23:06:56.211Z,1740006416.211 [keepstation:NeedComms] Stopped
2025-02-19T23:06:56.211Z,1740006416.211 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-19T23:06:56.212Z,1740006416.212 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-19T23:06:56.212Z,1740006416.212 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-19T23:06:56.595Z,1740006416.595 [keepstation:StartingMission](INFO): Completed keepstation:StartingMission
2025-02-19T23:06:56.595Z,1740006416.595 [keepstation:StartingMission] Stopped
2025-02-19T23:06:56.595Z,1740006416.595 [keepstation:StartingMission](DEBUG): Aggregate::uninitialize keepstation:StartingMission
2025-02-19T23:06:56.595Z,1740006416.595 [keepstation:TransitToStation] Running Loop=1
2025-02-19T23:06:56.596Z,1740006416.596 [keepstation:TransitToStation](DEBUG): Aggregate::initialize keepstation:TransitToStation
2025-02-19T23:06:56.596Z,1740006416.596 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-02-19T23:06:56.596Z,1740006416.596 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-02-19T23:06:56.596Z,1740006416.596 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-02-19T23:06:56.596Z,1740006416.596 [keepstation:TransitToStation:B.Pitch](DEBUG): Initialize.
2025-02-19T23:06:56.596Z,1740006416.596 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-02-19T23:06:56.596Z,1740006416.596 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Initialize.
2025-02-19T23:06:56.596Z,1740006416.596 [keepstation:TransitToStation:Wpt1.Waypoint] Running Loop=1
2025-02-19T23:06:56.596Z,1740006416.596 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-02-19T23:06:56.597Z,1740006416.597 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-02-19T23:06:56.977Z,1740006416.977 [keepstation:TransitToStation:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2025-02-19T23:06:56.977Z,1740006416.977 [keepstation:TransitToStation:C.SetSpeed] Running Loop=1
2025-02-19T23:06:56.977Z,1740006416.977 [keepstation:TransitToStation:B.Pitch] Running Loop=1
2025-02-19T23:06:56.978Z,1740006416.978 [keepstation:TransitToStation:A.Buoyancy] Running Loop=1
2025-02-19T23:07:28.549Z,1740006448.549 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-19T23:07:54.763Z,1740006474.763 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:09:27.340Z,1740006567.340 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:09:41.475Z,1740006581.475 [Radio_Surface](INFO): Powering down
2025-02-19T23:09:58.464Z,1740006598.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:09:59.468Z,1740006599.468 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-19T23:11:26.447Z,1740006686.447 [keepstation:TransitToStation:Wpt1.Waypoint](IMPORTANT): Reached waypoint: 36.797001,-121.847000
2025-02-19T23:11:26.448Z,1740006686.448 [keepstation:TransitToStation:Wpt1.Waypoint] Stopped
2025-02-19T23:11:26.448Z,1740006686.448 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-02-19T23:11:26.449Z,1740006686.449 [keepstation:TransitToStation](INFO): Completed keepstation:TransitToStation
2025-02-19T23:11:26.449Z,1740006686.449 [keepstation:TransitToStation] Stopped
2025-02-19T23:11:26.449Z,1740006686.449 [keepstation:TransitToStation](DEBUG): Aggregate::uninitialize keepstation:TransitToStation
2025-02-19T23:11:26.449Z,1740006686.449 [keepstation:TransitToStation:A.Buoyancy] Stopped
2025-02-19T23:11:26.449Z,1740006686.449 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-02-19T23:11:26.449Z,1740006686.449 [keepstation:TransitToStation:B.Pitch] Stopped
2025-02-19T23:11:26.449Z,1740006686.449 [keepstation:TransitToStation:C.SetSpeed] Stopped
2025-02-19T23:11:26.449Z,1740006686.449 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Uninitialize.
2025-02-19T23:11:26.450Z,1740006686.450 [keepstation:OnStation] Running Loop=1
2025-02-19T23:11:26.450Z,1740006686.450 [keepstation:OnStation](DEBUG): Aggregate::initialize keepstation:OnStation
2025-02-19T23:11:26.845Z,1740006686.845 [keepstation:NeedComms] Running Loop=1
2025-02-19T23:11:26.846Z,1740006686.846 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-19T23:11:26.846Z,1740006686.846 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-19T23:11:26.846Z,1740006686.846 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-19T23:11:26.846Z,1740006686.846 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-19T23:11:26.846Z,1740006686.846 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-19T23:11:26.847Z,1740006686.847 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-19T23:11:26.863Z,1740006686.863 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-19T23:11:26.864Z,1740006686.864 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-19T23:11:26.864Z,1740006686.864 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-19T23:11:26.864Z,1740006686.864 [keepstation:NeedComms:A] Running Loop=1
2025-02-19T23:11:26.866Z,1740006686.866 [keepstation:NeedComms:A](INFO): last time_fix was: 1740006415.000000 second since 1970/01/01T00:00:00Z
2025-02-19T23:11:26.866Z,1740006686.866 [keepstation:NeedComms:A] Stopped
2025-02-19T23:11:38.885Z,1740006698.885 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.756912
2025-02-19T23:12:21.850Z,1740006741.850 [keepstation:NeedComms:C] Running Loop=1
2025-02-19T23:12:22.308Z,1740006742.308 [Radio_Surface](INFO): Powering up
2025-02-19T23:12:28.088Z,1740006748.088 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-19T23:12:40.821Z,1740006760.821 [NAL9602](INFO): SBD MO Status=2, MOMSN=12826, MT Status=2, MTMSN=0
2025-02-19T23:12:40.821Z,1740006760.821 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-19T23:12:58.208Z,1740006778.208 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:13:20.445Z,1740006800.445 [NAL9602](INFO): SBD MO Status=0, MOMSN=12826, MT Status=0, MTMSN=0
2025-02-19T23:13:20.445Z,1740006800.445 [NAL9602](INFO): No messages in MT queue
2025-02-19T23:13:21.654Z,1740006801.654 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231320.00,A,3647.81645,N,12150.80301,W,1.302,65.03,190225,,,D*40
2025-02-19T23:13:21.657Z,1740006801.657 [NAL9602](INFO): GPS fix at 20250219T231320: (36.796941, -121.846717)
2025-02-19T23:13:21.670Z,1740006801.670 [keepstation:NeedComms:C] Stopped
2025-02-19T23:13:21.670Z,1740006801.670 [keepstation:NeedComms:D] Running Loop=1
2025-02-19T23:13:29.332Z,1740006809.332 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:13:38.246Z,1740006818.246 [NAL9602](INFO): SBD MO Status=1, MOMSN=12827, MT Status=0, MTMSN=0
2025-02-19T23:13:38.299Z,1740006818.299 [NAL9602](INFO): Sent 224 bytes from file Logs/20250219T230501/Courier0003.lzma
2025-02-19T23:13:38.300Z,1740006818.300 [NAL9602](INFO): Packets left to send: 0
2025-02-19T23:14:00.129Z,1740006840.129 [NAL9602](INFO): SBD MO Status=2, MOMSN=12828, MT Status=2, MTMSN=0
2025-02-19T23:14:00.129Z,1740006840.129 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-19T23:14:00.456Z,1740006840.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:14:20.804Z,1740006860.804 [NAL9602](INFO): SBD MO Status=1, MOMSN=12828, MT Status=0, MTMSN=0
2025-02-19T23:14:20.852Z,1740006860.852 [NAL9602](INFO): Sent 234 bytes from file Logs/20250219T230501/Express0004.lzma
2025-02-19T23:14:20.852Z,1740006860.852 [NAL9602](INFO): Packets left to send: 0
2025-02-19T23:14:29.402Z,1740006869.402 [NAL9602](INFO): SBD MO Status=0, MOMSN=12829, MT Status=0, MTMSN=0
2025-02-19T23:14:29.488Z,1740006869.488 [keepstation:NeedComms:D] Stopped
2025-02-19T23:14:29.488Z,1740006869.488 [keepstation:NeedComms:E] Running Loop=1
2025-02-19T23:14:31.593Z,1740006871.593 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:14:31.775Z,1740006871.775 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231431.00,A,3647.81513,N,12150.79037,W,0.486,180.05,190225,,,D*71
2025-02-19T23:14:31.777Z,1740006871.777 [NAL9602](INFO): GPS fix at 20250219T231431: (36.796919, -121.846506)
2025-02-19T23:14:31.790Z,1740006871.790 [keepstation:NeedComms:E] Stopped
2025-02-19T23:14:31.796Z,1740006871.796 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-19T23:14:31.796Z,1740006871.796 [keepstation:NeedComms] Stopped
2025-02-19T23:14:31.796Z,1740006871.796 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-19T23:14:31.796Z,1740006871.796 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-19T23:14:31.796Z,1740006871.796 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-19T23:14:32.213Z,1740006872.213 [keepstation:OnStation](INFO): Completed keepstation:OnStation
2025-02-19T23:14:32.213Z,1740006872.213 [keepstation:OnStation] Stopped
2025-02-19T23:14:32.213Z,1740006872.213 [keepstation:OnStation](DEBUG): Aggregate::uninitialize keepstation:OnStation
2025-02-19T23:14:32.213Z,1740006872.213 [keepstation:KeepStation] Running Loop=1
2025-02-19T23:14:32.213Z,1740006872.213 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-02-19T23:14:32.213Z,1740006872.213 [keepstation:KeepStation:A] Running Loop=1
2025-02-19T23:14:32.213Z,1740006872.213 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-02-19T23:14:32.213Z,1740006872.213 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize.
2025-02-19T23:14:32.214Z,1740006872.214 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-02-19T23:14:32.214Z,1740006872.214 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-02-19T23:14:32.214Z,1740006872.214 [keepstation:KeepStation:D.Wait] Running Loop=1
2025-02-19T23:14:32.214Z,1740006872.214 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component.
2025-02-19T23:14:32.612Z,1740006872.612 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-02-19T23:14:32.613Z,1740006872.613 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-02-19T23:14:32.613Z,1740006872.613 [keepstation:KeepStation:A] Running Loop=1
2025-02-19T23:14:40.204Z,1740006880.204 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003275
2025-02-19T23:15:04.497Z,1740006904.497 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-19T23:16:03.972Z,1740006963.972 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:16:35.096Z,1740006995.096 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:16:42.591Z,1740007002.591 [Radio_Surface](INFO): Powering down
2025-02-19T23:17:06.219Z,1740007026.219 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:17:07.223Z,1740007027.223 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-19T23:18:14.492Z,1740007094.492 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.723175
2025-02-19T23:35:39.559Z,1740008139.559 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-19T23:35:53.296Z,1740008153.296 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-19T23:36:06.223Z,1740008166.223 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-19T23:36:19.152Z,1740008179.152 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-19T23:36:32.482Z,1740008192.482 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-19T23:36:45.022Z,1740008205.022 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-19T23:36:57.936Z,1740008217.936 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-19T23:42:02.955Z,1740008522.955 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-19T23:42:04.187Z,1740008524.187 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-19T23:42:04.187Z,1740008524.187 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-19T23:42:04.190Z,1740008524.190 [BPC1](INFO): Received data from all battery sticks.
2025-02-19T23:46:12.250Z,1740008772.250 [keepstation:NeedComms] Running Loop=1
2025-02-19T23:46:12.250Z,1740008772.250 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-19T23:46:12.250Z,1740008772.250 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-19T23:46:12.250Z,1740008772.250 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-19T23:46:12.250Z,1740008772.250 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-19T23:46:12.251Z,1740008772.251 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-19T23:46:12.251Z,1740008772.251 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-19T23:46:12.252Z,1740008772.252 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-19T23:46:12.252Z,1740008772.252 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-19T23:46:12.252Z,1740008772.252 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-19T23:46:12.253Z,1740008772.253 [keepstation:NeedComms:A] Running Loop=1
2025-02-19T23:46:12.254Z,1740008772.254 [keepstation:NeedComms:A](INFO): last time_fix was: 1740006871.000000 second since 1970/01/01T00:00:00Z
2025-02-19T23:46:12.254Z,1740008772.254 [keepstation:NeedComms:A] Stopped
2025-02-19T23:47:43.426Z,1740008863.426 [keepstation:NeedComms:C] Running Loop=1
2025-02-19T23:47:43.880Z,1740008863.880 [Radio_Surface](INFO): Powering up
2025-02-19T23:47:49.916Z,1740008869.916 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-19T23:48:18.966Z,1740008898.966 [NAL9602](INFO): SBD MO Status=0, MOMSN=12830, MT Status=0, MTMSN=0
2025-02-19T23:48:18.966Z,1740008898.966 [NAL9602](INFO): No messages in MT queue
2025-02-19T23:48:20.035Z,1740008900.035 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:48:20.178Z,1740008900.178 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234819.00,A,3647.74616,N,12150.89858,W,0.583,213.29,190225,,,A*76
2025-02-19T23:48:20.181Z,1740008900.181 [NAL9602](INFO): GPS fix at 20250219T234819: (36.795769, -121.848310)
2025-02-19T23:48:20.195Z,1740008900.195 [keepstation:NeedComms:C] Stopped
2025-02-19T23:48:20.195Z,1740008900.195 [keepstation:NeedComms:D] Running Loop=1
2025-02-19T23:48:35.622Z,1740008915.622 [NAL9602](INFO): SBD MO Status=2, MOMSN=12831, MT Status=2, MTMSN=0
2025-02-19T23:48:35.623Z,1740008915.623 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-19T23:48:51.160Z,1740008931.160 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:48:55.090Z,1740008935.090 [NAL9602](INFO): SBD MO Status=1, MOMSN=12831, MT Status=0, MTMSN=0
2025-02-19T23:48:55.143Z,1740008935.143 [NAL9602](INFO): Sent 204 bytes from file Logs/20250219T230501/Courier0006.lzma
2025-02-19T23:48:55.144Z,1740008935.144 [NAL9602](INFO): Packets left to send: 0
2025-02-19T23:49:04.870Z,1740008944.870 [NAL9602](INFO): SBD MO Status=1, MOMSN=12832, MT Status=0, MTMSN=0
2025-02-19T23:49:04.921Z,1740008944.921 [NAL9602](INFO): Sent 129 bytes from file Logs/20250219T230501/Express0007.lzma
2025-02-19T23:49:04.921Z,1740008944.921 [NAL9602](INFO): Packets left to send: 0
2025-02-19T23:49:14.970Z,1740008954.970 [NAL9602](INFO): SBD MO Status=0, MOMSN=12833, MT Status=0, MTMSN=0
2025-02-19T23:49:15.059Z,1740008955.059 [keepstation:NeedComms:D] Stopped
2025-02-19T23:49:15.059Z,1740008955.059 [keepstation:NeedComms:E] Running Loop=1
2025-02-19T23:49:17.386Z,1740008957.386 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234916.00,A,3647.73839,N,12150.90243,W,0.117,204.45,190225,,,A*71
2025-02-19T23:49:17.397Z,1740008957.397 [NAL9602](INFO): GPS fix at 20250219T234916: (36.795640, -121.848374)
2025-02-19T23:49:17.413Z,1740008957.413 [keepstation:NeedComms:E] Stopped
2025-02-19T23:49:17.414Z,1740008957.414 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-19T23:49:17.414Z,1740008957.414 [keepstation:NeedComms] Stopped
2025-02-19T23:49:17.414Z,1740008957.414 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-19T23:49:17.414Z,1740008957.414 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-19T23:49:17.414Z,1740008957.414 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-19T23:49:22.304Z,1740008962.304 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:49:49.713Z,1740008989.713 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-19T23:49:53.436Z,1740008993.436 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:50:05.053Z,1740009005.053 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.007826
2025-02-19T23:50:55.243Z,1740009055.243 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:51:14.007Z,1740009074.007 [Radio_Surface](INFO): Powering down
2025-02-19T23:51:26.368Z,1740009086.368 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-19T23:51:27.372Z,1740009087.372 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-19T23:53:05.776Z,1740009185.776 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.730835
2025-02-20T00:19:17.654Z,1740010757.654 [keepstation:NeedComms] Running Loop=1
2025-02-20T00:19:17.655Z,1740010757.655 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-20T00:19:17.655Z,1740010757.655 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-20T00:19:17.655Z,1740010757.655 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-20T00:19:17.655Z,1740010757.655 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-20T00:19:17.656Z,1740010757.656 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-20T00:19:17.656Z,1740010757.656 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-20T00:19:17.656Z,1740010757.656 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-20T00:19:17.657Z,1740010757.657 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-20T00:19:17.657Z,1740010757.657 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-20T00:19:17.658Z,1740010757.658 [keepstation:NeedComms:A] Running Loop=1
2025-02-20T00:19:17.660Z,1740010757.660 [keepstation:NeedComms:A](INFO): last time_fix was: 1740008956.000000 second since 1970/01/01T00:00:00Z
2025-02-20T00:19:17.660Z,1740010757.660 [keepstation:NeedComms:A] Stopped
2025-02-20T00:19:30.472Z,1740010770.472 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint
2025-02-20T00:21:20.431Z,1740010880.431 [keepstation:NeedComms:C] Running Loop=1
2025-02-20T00:21:21.108Z,1740010881.108 [Radio_Surface](INFO): Powering up
2025-02-20T00:21:26.905Z,1740010886.905 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-20T00:21:42.244Z,1740010902.244 [NAL9602](INFO): SBD MO Status=2, MOMSN=12834, MT Status=2, MTMSN=0
2025-02-20T00:21:42.244Z,1740010902.244 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T00:21:57.024Z,1740010917.024 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T00:22:28.147Z,1740010948.147 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T00:22:28.289Z,1740010948.289 [NAL9602](INFO): SBD MO Status=0, MOMSN=12834, MT Status=0, MTMSN=0
2025-02-20T00:22:28.289Z,1740010948.289 [NAL9602](INFO): No messages in MT queue
2025-02-20T00:22:29.504Z,1740010949.504 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002228.00,A,3647.73240,N,12151.04360,W,0.525,338.72,200225,,,A*7D
2025-02-20T00:22:29.506Z,1740010949.506 [NAL9602](INFO): GPS fix at 20250220T002228: (36.795540, -121.850727)
2025-02-20T00:22:29.521Z,1740010949.521 [keepstation:NeedComms:C] Stopped
2025-02-20T00:22:29.522Z,1740010949.522 [keepstation:NeedComms:D] Running Loop=1
2025-02-20T00:22:44.542Z,1740010964.542 [NAL9602](INFO): SBD MO Status=1, MOMSN=12835, MT Status=0, MTMSN=0
2025-02-20T00:22:44.591Z,1740010964.591 [NAL9602](INFO): Sent 73 bytes from file Logs/20250219T230501/Courier0009.lzma
2025-02-20T00:22:44.592Z,1740010964.592 [NAL9602](INFO): Packets left to send: 0
2025-02-20T00:22:56.730Z,1740010976.730 [NAL9602](INFO): SBD MO Status=1, MOMSN=12836, MT Status=0, MTMSN=0
2025-02-20T00:22:56.779Z,1740010976.779 [NAL9602](INFO): Sent 192 bytes from file Logs/20250219T230501/Express0010.lzma
2025-02-20T00:22:56.780Z,1740010976.780 [NAL9602](INFO): Packets left to send: 0
2025-02-20T00:22:59.279Z,1740010979.279 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T00:23:18.986Z,1740010998.986 [NAL9602](INFO): SBD MO Status=2, MOMSN=12837, MT Status=2, MTMSN=0
2025-02-20T00:23:18.986Z,1740010998.986 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T00:23:23.828Z,1740011003.828 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2025-02-20T00:23:30.424Z,1740011010.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T00:23:35.398Z,1740011015.398 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2025-02-20T00:23:38.553Z,1740011018.553 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0
2025-02-20T00:23:43.304Z,1740011023.304 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003167
2025-02-20T00:23:44.754Z,1740011024.754 [keepstation:NeedComms:D] Stopped
2025-02-20T00:23:44.755Z,1740011024.755 [keepstation:NeedComms:E] Running Loop=1
2025-02-20T00:24:06.202Z,1740011046.202 [NAL9602](INFO): SBD MO Status=2, MOMSN=12837, MT Status=2, MTMSN=0
2025-02-20T00:24:06.202Z,1740011046.202 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T00:24:07.414Z,1740011047.414 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002406.00,A,3647.73005,N,12151.04770,W,0.253,210.57,200225,,,A*7B
2025-02-20T00:24:07.424Z,1740011047.424 [NAL9602](INFO): GPS fix at 20250220T002406: (36.795501, -121.850795)
2025-02-20T00:24:07.438Z,1740011047.438 [keepstation:NeedComms:E] Stopped
2025-02-20T00:24:07.440Z,1740011047.440 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-20T00:24:07.440Z,1740011047.440 [keepstation:NeedComms] Stopped
2025-02-20T00:24:07.440Z,1740011047.440 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-20T00:24:07.440Z,1740011047.440 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-20T00:24:07.440Z,1740011047.440 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-20T00:24:39.335Z,1740011079.335 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-20T00:25:15.897Z,1740011115.897 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T00:25:32.931Z,1740011132.931 [Radio_Surface](INFO): Powering down
2025-02-20T00:25:47.020Z,1740011147.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T00:25:48.024Z,1740011148.024 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-20T00:27:25.424Z,1740011245.424 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.743408
2025-02-20T00:36:28.396Z,1740011788.396 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest
2025-02-20T00:36:28.790Z,1740011788.790 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 20.00 deg, mass-position 11.65 mm.
2025-02-20T00:43:11.570Z,1740012191.570 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T00:43:23.691Z,1740012203.691 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T00:43:36.619Z,1740012216.619 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T00:43:49.547Z,1740012229.547 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T00:44:02.070Z,1740012242.070 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T00:44:14.596Z,1740012254.596 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T00:44:27.117Z,1740012267.117 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T00:49:34.159Z,1740012574.159 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-20T00:49:36.589Z,1740012576.589 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-20T00:49:36.589Z,1740012576.589 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-20T00:49:36.592Z,1740012576.592 [BPC1](INFO): Received data from all battery sticks.
2025-02-20T00:54:07.690Z,1740012847.690 [keepstation:NeedComms] Running Loop=1
2025-02-20T00:54:07.690Z,1740012847.690 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-20T00:54:07.691Z,1740012847.691 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-20T00:54:07.691Z,1740012847.691 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-20T00:54:07.691Z,1740012847.691 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-20T00:54:07.692Z,1740012847.692 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-20T00:54:07.692Z,1740012847.692 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-20T00:54:07.692Z,1740012847.692 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-20T00:54:07.693Z,1740012847.693 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-20T00:54:07.693Z,1740012847.693 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-20T00:54:07.693Z,1740012847.693 [keepstation:NeedComms:A] Running Loop=1
2025-02-20T00:54:07.695Z,1740012847.695 [keepstation:NeedComms:A](INFO): last time_fix was: 1740011046.000000 second since 1970/01/01T00:00:00Z
2025-02-20T00:54:07.695Z,1740012847.695 [keepstation:NeedComms:A] Stopped
2025-02-20T00:55:37.000Z,1740012938.000 [keepstation:NeedComms:C] Running Loop=1
2025-02-20T00:55:38.459Z,1740012938.459 [Radio_Surface](INFO): Powering up
2025-02-20T00:55:44.556Z,1740012944.556 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-20T00:56:14.676Z,1740012974.676 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T00:56:45.800Z,1740013005.800 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T00:56:48.314Z,1740013008.314 [NAL9602](INFO): SBD MO Status=2, MOMSN=12837, MT Status=2, MTMSN=0
2025-02-20T00:56:48.314Z,1740013008.314 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T00:56:59.222Z,1740013019.222 [NAL9602](INFO): SBD MO Status=0, MOMSN=12837, MT Status=0, MTMSN=0
2025-02-20T00:56:59.222Z,1740013019.222 [NAL9602](INFO): No messages in MT queue
2025-02-20T00:57:00.429Z,1740013020.429 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005659.00,A,3647.79010,N,12150.85098,W,0.641,183.99,200225,,,A*7F
2025-02-20T00:57:00.432Z,1740013020.432 [NAL9602](INFO): GPS fix at 20250220T005659: (36.796502, -121.847516)
2025-02-20T00:57:00.460Z,1740013020.460 [UniversalFixResidualReporter](INFO): Fix residual: 29.3 %DT, over the last 669.2 m. Residual distance 196.0 m at bearing 61.5 degrees. Fix at (36.7965, -121.8475) with 312.4 m made good.
2025-02-20T00:57:00.463Z,1740013020.463 [keepstation:NeedComms:C] Stopped
2025-02-20T00:57:00.464Z,1740013020.464 [keepstation:NeedComms:D] Running Loop=1
2025-02-20T00:57:16.924Z,1740013036.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T00:57:21.490Z,1740013041.490 [NAL9602](INFO): SBD MO Status=2, MOMSN=12838, MT Status=2, MTMSN=0
2025-02-20T00:57:21.490Z,1740013041.490 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T00:57:40.526Z,1740013060.526 [NAL9602](INFO): SBD MO Status=1, MOMSN=12838, MT Status=0, MTMSN=0
2025-02-20T00:57:40.578Z,1740013060.578 [NAL9602](INFO): Sent 218 bytes from file Logs/20250219T230501/Courier0012.lzma
2025-02-20T00:57:40.578Z,1740013060.578 [NAL9602](INFO): Packets left to send: 0
2025-02-20T00:57:48.052Z,1740013068.052 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T00:57:57.696Z,1740013077.696 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003167
2025-02-20T00:58:00.558Z,1740013080.558 [NAL9602](INFO): SBD MO Status=1, MOMSN=12839, MT Status=0, MTMSN=0
2025-02-20T00:58:00.608Z,1740013080.608 [NAL9602](INFO): Sent 171 bytes from file Logs/20250219T230501/Express0013.lzma
2025-02-20T00:58:00.608Z,1740013080.608 [NAL9602](INFO): Packets left to send: 0
2025-02-20T00:58:00.995Z,1740013080.995 [keepstation:NeedComms:D] Stopped
2025-02-20T00:58:00.996Z,1740013080.996 [keepstation:NeedComms:E] Running Loop=1
2025-02-20T00:58:02.982Z,1740013082.982 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005802.00,A,3647.78203,N,12150.85498,W,0.486,200.25,200225,,,A*7C
2025-02-20T00:58:02.984Z,1740013082.984 [NAL9602](INFO): GPS fix at 20250220T005802: (36.796367, -121.847583)
2025-02-20T00:58:03.036Z,1740013083.036 [keepstation:NeedComms:E] Stopped
2025-02-20T00:58:03.037Z,1740013083.037 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-20T00:58:03.037Z,1740013083.037 [keepstation:NeedComms] Stopped
2025-02-20T00:58:03.037Z,1740013083.037 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-20T00:58:03.037Z,1740013083.037 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-20T00:58:03.037Z,1740013083.037 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-20T00:58:35.308Z,1740013115.308 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-20T01:00:10.756Z,1740013210.756 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T01:00:17.768Z,1740013217.768 [Radio_Surface](INFO): Powering down
2025-02-20T01:00:41.880Z,1740013241.880 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T01:00:42.884Z,1740013242.884 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-20T01:01:46.173Z,1740013306.173 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.560089
2025-02-20T01:28:03.258Z,1740014883.258 [keepstation:NeedComms] Running Loop=1
2025-02-20T01:28:03.258Z,1740014883.258 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-20T01:28:03.258Z,1740014883.258 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-20T01:28:03.258Z,1740014883.258 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-20T01:28:03.259Z,1740014883.259 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-20T01:28:03.259Z,1740014883.259 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-20T01:28:03.260Z,1740014883.260 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-20T01:28:03.260Z,1740014883.260 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-20T01:28:03.260Z,1740014883.260 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-20T01:28:03.261Z,1740014883.261 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-20T01:28:03.261Z,1740014883.261 [keepstation:NeedComms:A] Running Loop=1
2025-02-20T01:28:03.263Z,1740014883.263 [keepstation:NeedComms:A](INFO): last time_fix was: 1740013082.000000 second since 1970/01/01T00:00:00Z
2025-02-20T01:28:03.263Z,1740014883.263 [keepstation:NeedComms:A] Stopped
2025-02-20T01:30:00.744Z,1740015000.744 [keepstation:NeedComms:C] Running Loop=1
2025-02-20T01:30:01.183Z,1740015001.183 [Radio_Surface](INFO): Powering up
2025-02-20T01:30:07.286Z,1740015007.286 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-20T01:30:33.054Z,1740015033.054 [NAL9602](INFO): SBD MO Status=2, MOMSN=12840, MT Status=2, MTMSN=0
2025-02-20T01:30:33.054Z,1740015033.054 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T01:30:37.412Z,1740015037.412 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T01:30:56.081Z,1740015056.081 [NAL9602](INFO): SBD MO Status=2, MOMSN=12840, MT Status=2, MTMSN=0
2025-02-20T01:30:56.081Z,1740015056.081 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T01:31:08.536Z,1740015068.536 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T01:31:20.322Z,1740015080.322 [NAL9602](INFO): SBD MO Status=2, MOMSN=12840, MT Status=2, MTMSN=0
2025-02-20T01:31:20.322Z,1740015080.322 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T01:31:36.078Z,1740015096.078 [NAL9602](INFO): SBD MO Status=0, MOMSN=12840, MT Status=0, MTMSN=0
2025-02-20T01:31:36.078Z,1740015096.078 [NAL9602](INFO): No messages in MT queue
2025-02-20T01:31:37.292Z,1740015097.292 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013136.00,A,3647.73046,N,12151.00652,W,0.039,195.66,200225,,,A*7D
2025-02-20T01:31:37.294Z,1740015097.294 [NAL9602](INFO): GPS fix at 20250220T013136: (36.795508, -121.850109)
2025-02-20T01:31:37.306Z,1740015097.306 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint
2025-02-20T01:31:37.309Z,1740015097.309 [keepstation:NeedComms:C] Stopped
2025-02-20T01:31:37.310Z,1740015097.310 [keepstation:NeedComms:D] Running Loop=1
2025-02-20T01:31:39.680Z,1740015099.680 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T01:31:58.002Z,1740015118.002 [NAL9602](INFO): SBD MO Status=2, MOMSN=12841, MT Status=2, MTMSN=0
2025-02-20T01:31:58.002Z,1740015118.002 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T01:32:10.816Z,1740015130.816 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T01:32:26.630Z,1740015146.630 [NAL9602](INFO): SBD MO Status=1, MOMSN=12841, MT Status=0, MTMSN=0
2025-02-20T01:32:26.688Z,1740015146.688 [NAL9602](INFO): Sent 73 bytes from file Logs/20250219T230501/Courier0015.lzma
2025-02-20T01:32:26.688Z,1740015146.688 [NAL9602](INFO): Packets left to send: 0
2025-02-20T01:32:30.524Z,1740015150.524 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003189
2025-02-20T01:32:36.746Z,1740015156.746 [NAL9602](INFO): SBD MO Status=1, MOMSN=12842, MT Status=0, MTMSN=0
2025-02-20T01:32:36.796Z,1740015156.796 [NAL9602](INFO): Sent 206 bytes from file Logs/20250219T230501/Express0016.lzma
2025-02-20T01:32:36.796Z,1740015156.796 [NAL9602](INFO): Packets left to send: 0
2025-02-20T01:32:38.788Z,1740015158.788 [keepstation:NeedComms:D] Stopped
2025-02-20T01:32:38.788Z,1740015158.788 [keepstation:NeedComms:E] Running Loop=1
2025-02-20T01:32:40.795Z,1740015160.795 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013240.00,A,3647.72350,N,12151.00484,W,0.719,190.20,200225,,,A*71
2025-02-20T01:32:40.813Z,1740015160.813 [NAL9602](INFO): GPS fix at 20250220T013240: (36.795392, -121.850081)
2025-02-20T01:32:40.829Z,1740015160.829 [keepstation:NeedComms:E] Stopped
2025-02-20T01:32:40.830Z,1740015160.830 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-20T01:32:40.830Z,1740015160.830 [keepstation:NeedComms] Stopped
2025-02-20T01:32:40.830Z,1740015160.830 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-20T01:32:40.830Z,1740015160.830 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-20T01:32:40.830Z,1740015160.830 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-20T01:33:13.580Z,1740015193.580 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-20T01:33:21.260Z,1740015201.260 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 0.80 m/s, pitch 20.00 deg, mass-position 11.65 mm (4 active estimators).
2025-02-20T01:34:10.880Z,1740015250.880 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T01:34:21.623Z,1740015261.623 [Radio_Surface](INFO): Powering down
2025-02-20T01:34:42.004Z,1740015282.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T01:34:43.008Z,1740015283.008 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-20T01:36:19.436Z,1740015379.436 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.779358
2025-02-20T01:40:35.999Z,1740015635.999 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest
2025-02-20T01:40:36.400Z,1740015636.400 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm.
2025-02-20T01:40:36.400Z,1740015636.400 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position 11.65 mm.
2025-02-20T01:50:43.606Z,1740016243.606 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T01:50:56.938Z,1740016256.938 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T01:51:10.271Z,1740016270.271 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T01:51:22.794Z,1740016282.794 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T01:51:35.723Z,1740016295.723 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T01:51:49.460Z,1740016309.460 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T01:52:01.983Z,1740016321.983 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T01:57:05.387Z,1740016625.387 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-20T01:57:10.646Z,1740016630.646 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-20T01:57:10.646Z,1740016630.646 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-20T01:57:10.661Z,1740016630.661 [BPC1](INFO): Received data from all battery sticks.
2025-02-20T02:02:41.127Z,1740016961.127 [keepstation:NeedComms] Running Loop=1
2025-02-20T02:02:41.127Z,1740016961.127 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-20T02:02:41.127Z,1740016961.127 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-20T02:02:41.127Z,1740016961.127 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-20T02:02:41.128Z,1740016961.128 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-20T02:02:41.128Z,1740016961.128 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-20T02:02:41.129Z,1740016961.129 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-20T02:02:41.129Z,1740016961.129 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-20T02:02:41.129Z,1740016961.129 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-20T02:02:41.130Z,1740016961.130 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-20T02:02:41.130Z,1740016961.130 [keepstation:NeedComms:A] Running Loop=1
2025-02-20T02:02:41.132Z,1740016961.132 [keepstation:NeedComms:A](INFO): last time_fix was: 1740015160.000000 second since 1970/01/01T00:00:00Z
2025-02-20T02:02:41.132Z,1740016961.132 [keepstation:NeedComms:A] Stopped
2025-02-20T02:04:44.432Z,1740017084.432 [keepstation:NeedComms:C] Running Loop=1
2025-02-20T02:04:44.883Z,1740017084.883 [Radio_Surface](INFO): Powering up
2025-02-20T02:04:50.576Z,1740017090.576 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-20T02:05:05.013Z,1740017105.013 [NAL9602](INFO): SBD MO Status=2, MOMSN=12843, MT Status=2, MTMSN=0
2025-02-20T02:05:05.013Z,1740017105.013 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T02:05:20.696Z,1740017120.696 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T02:05:51.820Z,1740017151.820 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T02:05:58.822Z,1740017158.822 [NAL9602](INFO): SBD MO Status=2, MOMSN=12843, MT Status=2, MTMSN=0
2025-02-20T02:05:58.823Z,1740017158.823 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T02:06:22.948Z,1740017182.948 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T02:06:50.935Z,1740017210.935 [NAL9602](INFO): SBD MO Status=0, MOMSN=12843, MT Status=0, MTMSN=0
2025-02-20T02:06:50.935Z,1740017210.935 [NAL9602](INFO): No messages in MT queue
2025-02-20T02:06:52.145Z,1740017212.145 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,020651.00,A,3647.78909,N,12150.95186,W,0.058,204.41,200225,,,A*78
2025-02-20T02:06:52.148Z,1740017212.148 [NAL9602](INFO): GPS fix at 20250220T020651: (36.796485, -121.849198)
2025-02-20T02:06:52.169Z,1740017212.169 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint
2025-02-20T02:06:52.196Z,1740017212.196 [keepstation:NeedComms:C] Stopped
2025-02-20T02:06:52.196Z,1740017212.196 [keepstation:NeedComms:D] Running Loop=1
2025-02-20T02:06:54.072Z,1740017214.072 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T02:07:05.124Z,1740017225.124 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003239
2025-02-20T02:07:08.038Z,1740017228.038 [NAL9602](INFO): SBD MO Status=1, MOMSN=12844, MT Status=0, MTMSN=0
2025-02-20T02:07:08.092Z,1740017228.092 [NAL9602](INFO): Sent 86 bytes from file Logs/20250219T230501/Courier0018.lzma
2025-02-20T02:07:08.092Z,1740017228.092 [NAL9602](INFO): Packets left to send: 0
2025-02-20T02:07:17.471Z,1740017237.471 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20250219T230501/Express0019.lzma
2025-02-20T02:07:18.473Z,1740017238.473 [DataOverHttps](INFO): Moved sent file to Logs/20250219T230501/Express0019.lzma.bak
2025-02-20T02:07:18.474Z,1740017238.474 [DataOverHttps](INFO): SBD MOMSN=23967884
2025-02-20T02:07:19.898Z,1740017239.898 [keepstation:NeedComms:D] Stopped
2025-02-20T02:07:19.898Z,1740017239.898 [keepstation:NeedComms:E] Running Loop=1
2025-02-20T02:07:21.846Z,1740017241.846 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,020721.00,A,3647.78654,N,12150.95055,W,0.603,137.37,200225,,,A*7C
2025-02-20T02:07:21.848Z,1740017241.848 [NAL9602](INFO): GPS fix at 20250220T020721: (36.796442, -121.849176)
2025-02-20T02:07:21.884Z,1740017241.884 [keepstation:NeedComms:E] Stopped
2025-02-20T02:07:21.885Z,1740017241.885 [keepstation:NeedComms](INFO): Completed keepstation:NeedComms
2025-02-20T02:07:21.885Z,1740017241.885 [keepstation:NeedComms] Stopped
2025-02-20T02:07:21.885Z,1740017241.885 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-20T02:07:21.885Z,1740017241.885 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-20T02:07:21.885Z,1740017241.885 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-20T02:07:54.580Z,1740017274.580 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-20T02:08:26.488Z,1740017306.488 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-02-20T02:08:51.572Z,1740017331.572 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T02:08:54.679Z,1740017334.679 [Radio_Surface](INFO): Powering down
2025-02-20T02:09:22.704Z,1740017362.704 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T02:09:23.708Z,1740017363.708 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-20T02:10:27.964Z,1740017427.964 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.678894
2025-02-20T02:15:43.271Z,1740017743.271 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest
2025-02-20T02:37:43.938Z,1740019063.938 [keepstation:NeedComms] Running Loop=1
2025-02-20T02:37:43.938Z,1740019063.938 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-20T02:37:43.938Z,1740019063.938 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-20T02:37:43.938Z,1740019063.938 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-20T02:37:43.939Z,1740019063.939 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-20T02:37:43.939Z,1740019063.939 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-20T02:37:43.940Z,1740019063.940 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-20T02:37:43.940Z,1740019063.940 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-20T02:37:43.940Z,1740019063.940 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-20T02:37:43.941Z,1740019063.941 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-20T02:37:43.941Z,1740019063.941 [keepstation:NeedComms:A] Running Loop=1
2025-02-20T02:37:43.943Z,1740019063.943 [keepstation:NeedComms:A](INFO): last time_fix was: 1740017241.000000 second since 1970/01/01T00:00:00Z
2025-02-20T02:37:43.943Z,1740019063.943 [keepstation:NeedComms:A] Stopped
2025-02-20T02:38:46.480Z,1740019126.480 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint
2025-02-20T02:39:16.459Z,1740019156.459 [keepstation:NeedComms:C] Running Loop=1
2025-02-20T02:39:17.135Z,1740019157.135 [Radio_Surface](INFO): Powering up
2025-02-20T02:39:23.204Z,1740019163.204 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-20T02:39:34.680Z,1740019174.680 [CBIT](IMPORTANT): Beginning ground fault scan
2025-02-20T02:39:45.588Z,1740019185.588 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000847
CHAN A1 (24V): -0.000231
CHAN A2 (12V): 0.000301
CHAN A3 (5V): -0.001943
CHAN B0 (3.3V): -0.001117
CHAN B1 (3.15aV): -0.001325
CHAN B2 (3.15bV): -0.001343
CHAN B3 (GND): -0.002022
OPEN: 0.004752
Full Scale: +/- 1 mA
2025-02-20T02:39:53.324Z,1740019193.324 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T02:39:58.854Z,1740019198.854 [NAL9602](INFO): SBD MO Status=2, MOMSN=12845, MT Status=2, MTMSN=0
2025-02-20T02:39:58.854Z,1740019198.854 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T02:40:24.460Z,1740019224.460 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T02:40:29.962Z,1740019229.962 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=12845, MT Status=1, MTMSN=249
2025-02-20T02:40:29.962Z,1740019229.962 [NAL9602](INFO): Data available in MT queue
2025-02-20T02:40:30.456Z,1740019230.456 [NAL9602](INFO): Received command: stop
2025-02-20T02:40:30.500Z,1740019230.500 [CommandExec](IMPORTANT): got command stop
2025-02-20T02:40:30.500Z,1740019230.500 [CommandExec](IMPORTANT): Scheduling is paused for 1 commands
2025-02-20T02:40:30.500Z,1740019230.500 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-02-20T02:40:30.793Z,1740019230.793 [MissionManager](INFO): MissionManager is completed.
2025-02-20T02:40:30.793Z,1740019230.793 [MissionManager](INFO): Uninitializing Mission keepstation
2025-02-20T02:40:30.794Z,1740019230.794 [keepstation] Stopped
2025-02-20T02:40:30.794Z,1740019230.794 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-02-20T02:40:30.794Z,1740019230.794 [keepstation:NeedComms] Stopped
2025-02-20T02:40:30.794Z,1740019230.794 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-20T02:40:30.794Z,1740019230.794 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-20T02:40:30.794Z,1740019230.794 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-20T02:40:30.794Z,1740019230.794 [keepstation:NeedComms:C] Stopped
2025-02-20T02:40:30.794Z,1740019230.794 [keepstation:StandardEnvelopes] Stopped
2025-02-20T02:40:30.794Z,1740019230.794 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-02-20T02:40:30.794Z,1740019230.794 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-02-20T02:40:30.794Z,1740019230.794 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-02-20T02:40:30.794Z,1740019230.794 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-02-20T02:40:30.795Z,1740019230.795 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-02-20T02:40:30.795Z,1740019230.795 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-02-20T02:40:30.795Z,1740019230.795 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-02-20T02:40:30.795Z,1740019230.795 [keepstation:BackseatDriver] Stopped
2025-02-20T02:40:30.795Z,1740019230.795 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-02-20T02:40:30.795Z,1740019230.795 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-02-20T02:40:30.795Z,1740019230.795 [keepstation:PowerOnly] Stopped
2025-02-20T02:40:30.795Z,1740019230.795 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-02-20T02:40:30.795Z,1740019230.795 [keepstation:PowerOnly:E.Wait] Stopped
2025-02-20T02:40:30.795Z,1740019230.795 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-02-20T02:40:30.796Z,1740019230.796 [keepstation:H.Pitch] Stopped
2025-02-20T02:40:30.796Z,1740019230.796 [keepstation:KeepStation] Stopped
2025-02-20T02:40:30.796Z,1740019230.796 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2025-02-20T02:40:30.796Z,1740019230.796 [keepstation:KeepStation:A] Stopped
2025-02-20T02:40:30.796Z,1740019230.796 [keepstation:KeepStation:B.Pitch] Stopped
2025-02-20T02:40:30.796Z,1740019230.796 [keepstation:KeepStation:C.KeepStation] Stopped
2025-02-20T02:40:30.796Z,1740019230.796 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-02-20T02:40:30.796Z,1740019230.796 [keepstation:KeepStation:D.Wait] Stopped
2025-02-20T02:40:30.796Z,1740019230.796 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component.
2025-02-20T02:40:31.208Z,1740019231.208 [MissionManager](IMPORTANT): Started mission Default
2025-02-20T02:40:31.208Z,1740019231.208 [Default] Running Loop=1
2025-02-20T02:40:31.208Z,1740019231.208 [Default](DEBUG): Aggregate::initialize Default
2025-02-20T02:40:31.209Z,1740019231.209 [Default:B.GoToSurface] Running Loop=1
2025-02-20T02:40:31.209Z,1740019231.209 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-20T02:40:31.209Z,1740019231.209 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-20T02:40:31.209Z,1740019231.209 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-20T02:40:31.209Z,1740019231.209 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-20T02:40:31.210Z,1740019231.210 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-20T02:40:31.210Z,1740019231.210 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-20T02:40:31.210Z,1740019231.210 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-20T02:40:31.212Z,1740019231.212 [Default:A.Wait] Running Loop=1
2025-02-20T02:40:31.212Z,1740019231.212 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-20T02:40:31.586Z,1740019231.586 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,024030.00,A,3647.84071,N,12150.67599,W,0.933,112.26,200225,,,A*7E
2025-02-20T02:40:31.595Z,1740019231.595 [NAL9602](INFO): GPS fix at 20250220T024030: (36.797345, -121.844600)
2025-02-20T02:40:44.520Z,1740019244.520 [Default:A.Wait](INFO): Done Waiting.
2025-02-20T02:40:44.520Z,1740019244.520 [Default:A.Wait] Stopped
2025-02-20T02:40:44.520Z,1740019244.520 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-20T02:40:44.935Z,1740019244.935 [Default:CheckIn] Running Loop=1
2025-02-20T02:40:44.935Z,1740019244.935 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-20T02:40:44.936Z,1740019244.936 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-20T02:40:46.930Z,1740019246.930 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,024046.00,A,3647.83873,N,12150.67513,W,0.914,178.63,200225,,,A*78
2025-02-20T02:40:46.933Z,1740019246.933 [NAL9602](INFO): GPS fix at 20250220T024046: (36.797312, -121.844585)
2025-02-20T02:40:46.944Z,1740019246.944 [Default:CheckIn:Read_GPS] Stopped
2025-02-20T02:40:46.944Z,1740019246.944 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-20T02:40:55.583Z,1740019255.583 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T02:41:00.726Z,1740019260.726 [NAL9602](INFO): SBD MO Status=1, MOMSN=12846, MT Status=0, MTMSN=0
2025-02-20T02:41:00.780Z,1740019260.780 [NAL9602](INFO): Sent 73 bytes from file Logs/20250219T230501/Courier0021.lzma
2025-02-20T02:41:00.780Z,1740019260.780 [NAL9602](INFO): Packets left to send: 0
2025-02-20T02:41:11.043Z,1740019271.043 [NAL9602](INFO): SBD MO Status=1, MOMSN=12847, MT Status=0, MTMSN=0
2025-02-20T02:41:11.104Z,1740019271.104 [NAL9602](INFO): Sent 332 bytes from file Logs/20250219T230501/Express0022.lzma
2025-02-20T02:41:11.104Z,1740019271.104 [NAL9602](INFO): Packets left to send: 1
2025-02-20T02:41:22.558Z,1740019282.558 [NAL9602](INFO): SBD MO Status=1, MOMSN=12848, MT Status=0, MTMSN=0
2025-02-20T02:41:22.607Z,1740019282.607 [NAL9602](INFO): Sent 204 bytes from file Logs/20250219T230501/Express0022.lzma
2025-02-20T02:41:22.608Z,1740019282.608 [NAL9602](INFO): Packets left to send: 0
2025-02-20T02:41:26.716Z,1740019286.716 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T02:41:35.752Z,1740019295.752 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003209
2025-02-20T02:41:36.294Z,1740019296.294 [NAL9602](INFO): SBD MO Status=2, MOMSN=12849, MT Status=2, MTMSN=0
2025-02-20T02:41:36.294Z,1740019296.294 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T02:41:37.112Z,1740019297.112 [Default:CheckIn:Read_Iridium] Stopped
2025-02-20T02:41:37.112Z,1740019297.112 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-20T02:41:37.112Z,1740019297.112 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-20T02:42:06.997Z,1740019326.997 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-20T02:46:37.856Z,1740019597.856 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-20T02:46:37.856Z,1740019597.856 [Default:CheckIn:C.Wait] Stopped
2025-02-20T02:46:37.856Z,1740019597.856 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-20T02:46:37.857Z,1740019597.857 [Default:CheckIn:D] Running Loop=1
2025-02-20T02:46:38.280Z,1740019598.280 [Default:CheckIn:D] Stopped
2025-02-20T02:46:38.280Z,1740019598.280 [Default:CheckIn:E] Running Loop=1
2025-02-20T02:46:38.659Z,1740019598.659 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.117864 min
2025-02-20T02:46:38.659Z,1740019598.659 [Default:CheckIn:E] Stopped
2025-02-20T02:46:38.659Z,1740019598.659 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-20T02:46:38.659Z,1740019598.659 [Default:CheckIn] Stopped
2025-02-20T02:46:38.659Z,1740019598.659 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-20T02:46:38.660Z,1740019598.660 [Default:CheckIn](INFO): Running loop #2
2025-02-20T02:46:38.660Z,1740019598.660 [Default:CheckIn] Running Loop=2
2025-02-20T02:46:38.660Z,1740019598.660 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-20T02:46:38.660Z,1740019598.660 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-20T02:46:40.675Z,1740019600.675 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,024639.00,A,3647.80487,N,12150.65271,W,0.408,160.34,200225,,,D*7D
2025-02-20T02:46:40.677Z,1740019600.677 [NAL9602](INFO): GPS fix at 20250220T024639: (36.796748, -121.844212)
2025-02-20T02:46:40.688Z,1740019600.688 [Default:CheckIn:Read_GPS] Stopped
2025-02-20T02:46:40.689Z,1740019600.689 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-20T02:46:48.839Z,1740019608.839 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250219T230501/Courier0024.lzma
2025-02-20T02:46:49.842Z,1740019609.842 [DataOverHttps](INFO): Moved sent file to Logs/20250219T230501/Courier0024.lzma.bak
2025-02-20T02:46:49.842Z,1740019609.842 [DataOverHttps](IMPORTANT): SBD MOMSN=23967906, MTMSN=20250220T024649
2025-02-20T02:46:52.384Z,1740019612.384 [NAL9602](INFO): SBD MO Status=0, MOMSN=12849, MT Status=0, MTMSN=0
2025-02-20T02:46:52.385Z,1740019612.385 [NAL9602](INFO): No messages in MT queue
2025-02-20T02:46:57.380Z,1740019617.380 [DataOverHttps](INFO): Received command: schedule clear;schedule resume
2025-02-20T02:46:57.464Z,1740019617.464 [CommandExec](IMPORTANT): got command schedule clear
2025-02-20T02:46:57.464Z,1740019617.464 [CommandExec](IMPORTANT): Cleared 1 scheduled commands.
2025-02-20T02:46:57.464Z,1740019617.464 [CommandExec](IMPORTANT): got command schedule resume
2025-02-20T02:46:57.464Z,1740019617.464 [CommandExec](IMPORTANT): Scheduling is resumed
2025-02-20T02:47:08.617Z,1740019628.617 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20250219T230501/Express0025.lzma
2025-02-20T02:47:09.612Z,1740019629.612 [DataOverHttps](INFO): Moved sent file to Logs/20250219T230501/Express0025.lzma.bak
2025-02-20T02:47:09.612Z,1740019629.612 [DataOverHttps](INFO): SBD MOMSN=23967911
2025-02-20T02:47:23.120Z,1740019643.120 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-20T02:47:26.827Z,1740019646.827 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250219T230501/Courier0027.lzma
2025-02-20T02:47:27.829Z,1740019647.829 [DataOverHttps](INFO): Moved sent file to Logs/20250219T230501/Courier0027.lzma.bak
2025-02-20T02:47:27.830Z,1740019647.830 [DataOverHttps](IMPORTANT): SBD MOMSN=23967915, MTMSN=20250220T024727
2025-02-20T02:47:35.291Z,1740019655.291 [DataOverHttps](INFO): Received command: resume
2025-02-20T02:47:35.345Z,1740019655.345 [CommandExec](IMPORTANT): got command resume
2025-02-20T02:47:35.346Z,1740019655.346 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/keepstation.tl
2025-02-20T02:47:35.346Z,1740019655.346 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/keepstation.tl
2025-02-20T02:47:35.347Z,1740019655.347 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/keepstation.tl
2025-02-20T02:47:36.103Z,1740019656.103 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/keepstation.tl translated into:
4
45
NaN
NaN
30
15
4
0.75
200
45
2
2025-02-20T02:47:36.124Z,1740019656.124 [MissionManager](INFO): DefineArg keepstation.MissionTimeout = 4.000000 h
2025-02-20T02:47:36.126Z,1740019656.126 [MissionManager](INFO): DefineArg keepstation.NeedCommsTime = 45.000000 min
2025-02-20T02:47:36.133Z,1740019656.133 [MissionManager](INFO): DefineArg keepstation.Latitude = nan arcdeg
2025-02-20T02:47:36.148Z,1740019656.148 [MissionManager](INFO): DefineArg keepstation.Longitude = nan arcdeg
2025-02-20T02:47:36.150Z,1740019656.150 [MissionManager](INFO): DefineArg keepstation.Depth = 30.000000 m
2025-02-20T02:47:36.152Z,1740019656.152 [MissionManager](INFO): DefineArg keepstation.ApproachDepth = 15.000000 m
2025-02-20T02:47:36.155Z,1740019656.155 [MissionManager](INFO): DefineArg keepstation.DepthDeadband = 4.000000 m
2025-02-20T02:47:36.161Z,1740019656.161 [MissionManager](INFO): DefineArg keepstation.Speed = 0.750000 m/s
2025-02-20T02:47:36.167Z,1740019656.167 [MissionManager](INFO): DefineArg keepstation.Radius = 200.000000 m
2025-02-20T02:47:36.170Z,1740019656.170 [MissionManager](INFO): DefineArg keepstation.MaxDepth = 45.000000 m
2025-02-20T02:47:36.192Z,1740019656.192 [MissionManager](INFO): DefineArg keepstation.MinOffshore = 2.000000 km
2025-02-20T02:47:36.192Z,1740019656.192 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-02-20T02:47:36.192Z,1740019656.192 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-02-20T02:47:36.194Z,1740019656.194 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-02-20T02:47:36.640Z,1740019656.640 [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-20T02:47:36.649Z,1740019656.649 [MissionManager](INFO): DefineArg keepstation:NeedComms.DiveInterval = 1.000000 h
2025-02-20T02:47:36.656Z,1740019656.656 [MissionManager](INFO): DefineArg keepstation:NeedComms.WaitForPitchUp = 10.000000 min
2025-02-20T02:47:36.658Z,1740019656.658 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-02-20T02:47:36.664Z,1740019656.664 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceDepthRate = nan m/s
2025-02-20T02:47:36.666Z,1740019656.666 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-02-20T02:47:36.673Z,1740019656.673 [MissionManager](INFO): DefineArg keepstation:NeedComms.SurfacingTimeout = 1000.000000 s
2025-02-20T02:47:36.675Z,1740019656.675 [MissionManager](INFO): DefineArg keepstation:NeedComms.GPSTimeout = 7.000000 min
2025-02-20T02:47:36.681Z,1740019656.681 [MissionManager](INFO): DefineArg keepstation:NeedComms.CommsTimeout = 30.000000 min
2025-02-20T02:47:36.728Z,1740019656.728 [keepstation:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-20T02:47:36.759Z,1740019656.759 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-02-20T02:47:36.759Z,1740019656.759 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-02-20T02:47:36.762Z,1740019656.762 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-02-20T02:47:36.828Z,1740019656.828 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2025-02-20T02:47:36.832Z,1740019656.832 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinAltitude = 5.000000 m
2025-02-20T02:47:36.834Z,1740019656.834 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-02-20T02:47:36.837Z,1740019656.837 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MaxDepth = 200.000000 m
2025-02-20T02:47:36.883Z,1740019656.883 [MissionManager](INFO): DefineArg keepstation:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-02-20T02:47:36.884Z,1740019656.884 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-02-20T02:47:36.902Z,1740019656.902 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-02-20T02:47:36.925Z,1740019656.925 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-02-20T02:47:36.947Z,1740019656.947 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-02-20T02:47:36.947Z,1740019656.947 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-02-20T02:47:36.949Z,1740019656.949 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-02-20T02:47:36.984Z,1740019656.984 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-02-20T02:47:36.994Z,1740019656.994 [MissionManager](INFO): DefineArg keepstation:BackseatDriver.EnableBackseat = 0 bool
2025-02-20T02:47:36.997Z,1740019656.997 [keepstation:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-02-20T02:47:36.000Z,1740019657.000 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-02-20T02:47:37.000Z,1740019657.000 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-02-20T02:47:37.001Z,1740019657.001 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-02-20T02:47:37.135Z,1740019657.135 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-02-20T02:47:37.139Z,1740019657.139 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleAll = 0 bool
2025-02-20T02:47:37.141Z,1740019657.141 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad1 = 0 bool
2025-02-20T02:47:37.143Z,1740019657.143 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad2 = 0 bool
2025-02-20T02:47:37.145Z,1740019657.145 [MissionManager](INFO): DefineArg keepstation:PowerOnly.SampleLoad3 = 0 bool
2025-02-20T02:47:37.147Z,1740019657.147 [MissionManager](INFO): DefineArg keepstation:PowerOnly.EnabledPowerOnly = 0 bool
2025-02-20T02:47:37.154Z,1740019657.154 [keepstation:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-02-20T02:47:37.156Z,1740019657.156 [keepstation:H.Pitch](DEBUG): Construct.
2025-02-20T02:47:37.162Z,1740019657.162 [keepstation:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy.
2025-02-20T02:47:37.194Z,1740019657.194 [keepstation:TransitToStation:B.Pitch](DEBUG): Construct.
2025-02-20T02:47:37.202Z,1740019657.202 [keepstation:TransitToStation:C.SetSpeed](DEBUG): Construct.
2025-02-20T02:47:37.207Z,1740019657.207 [keepstation:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-02-20T02:47:37.215Z,1740019657.215 [keepstation:KeepStation:B.Pitch](DEBUG): Construct.
2025-02-20T02:47:37.224Z,1740019657.224 [keepstation:KeepStation:C.KeepStation](DEBUG): Construct KeepStation.
2025-02-20T02:47:37.229Z,1740019657.229 [keepstation:KeepStation:D.Wait](DEBUG): Construct Wait.
2025-02-20T02:47:37.286Z,1740019657.286 [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-20T02:47:37.300Z,1740019657.300 [MissionManager](IMPORTANT): Resuming from info in Logs/20250219T180327/Transport_keepstation_tl.state
2025-02-20T02:47:37.307Z,1740019657.307 [MissionManager](INFO): Default->Default:CheckIn/0
2025-02-20T02:47:37.308Z,1740019657.308 [MissionManager](INFO): Default:A.Wait->null/0
2025-02-20T02:47:37.308Z,1740019657.308 [MissionManager](INFO): Default:B.GoToSurface->null/0
2025-02-20T02:47:37.308Z,1740019657.308 [MissionManager](INFO): Default:CheckIn->Default:CheckIn:Read_Iridium/1
2025-02-20T02:47:37.309Z,1740019657.309 [MissionManager](INFO): Default:CheckIn:Read_GPS->null/0
2025-02-20T02:47:37.309Z,1740019657.309 [MissionManager](INFO): Default:CheckIn:Read_Iridium->null/0
2025-02-20T02:47:37.309Z,1740019657.309 [MissionManager](INFO): Default:CheckIn:C.Wait->null/0
2025-02-20T02:47:37.310Z,1740019657.310 [MissionManager](INFO): Default:CheckIn:D->null/0
2025-02-20T02:47:37.310Z,1740019657.310 [MissionManager](INFO): Default:CheckIn:E->null/0
2025-02-20T02:47:37.311Z,1740019657.311 [MissionManager](INFO): keepstation->keepstation:KeepStation/0
2025-02-20T02:47:37.358Z,1740019657.358 [MissionManager](INFO): keepstation:NeedComms->keepstation:NeedComms:C/0
2025-02-20T02:47:37.358Z,1740019657.358 [MissionManager](INFO): keepstation:NeedComms:A->null/0
2025-02-20T02:47:37.359Z,1740019657.359 [MissionManager](INFO): keepstation:NeedComms:B.GoToSurface->null/0
2025-02-20T02:47:37.359Z,1740019657.359 [MissionManager](INFO): keepstation:NeedComms:C->null/0
2025-02-20T02:47:37.360Z,1740019657.360 [MissionManager](INFO): keepstation:NeedComms:D->null/0
2025-02-20T02:47:37.360Z,1740019657.360 [MissionManager](INFO): keepstation:NeedComms:E->null/0
2025-02-20T02:47:37.360Z,1740019657.360 [MissionManager](INFO): keepstation:B->null/0
2025-02-20T02:47:37.361Z,1740019657.361 [MissionManager](INFO): keepstation:D->null/0
2025-02-20T02:47:37.361Z,1740019657.361 [MissionManager](INFO): keepstation:E->null/0
2025-02-20T02:47:37.361Z,1740019657.361 [MissionManager](INFO): keepstation:BackseatDriver->keepstation:BackseatDriver:A.BackseatDriver/0
2025-02-20T02:47:37.362Z,1740019657.362 [MissionManager](INFO): keepstation:BackseatDriver:A.BackseatDriver->null/0
2025-02-20T02:47:37.362Z,1740019657.362 [MissionManager](INFO): keepstation:PowerOnly->keepstation:PowerOnly:E.Wait/0
2025-02-20T02:47:37.363Z,1740019657.363 [MissionManager](INFO): keepstation:PowerOnly:E.Wait->null/0
2025-02-20T02:47:37.384Z,1740019657.384 [MissionManager](INFO): keepstation:StartingMission->null/0
2025-02-20T02:47:37.385Z,1740019657.385 [MissionManager](INFO): keepstation:TransitToStation->null/0
2025-02-20T02:47:37.385Z,1740019657.385 [MissionManager](INFO): keepstation:TransitToStation:Wpt1.Waypoint->null/0
2025-02-20T02:47:37.386Z,1740019657.386 [MissionManager](INFO): keepstation:OnStation->null/0
2025-02-20T02:47:37.386Z,1740019657.386 [MissionManager](INFO): keepstation:KeepStation->keepstation:KeepStation:D.Wait/0
2025-02-20T02:47:37.387Z,1740019657.387 [MissionManager](INFO): keepstation:KeepStation:D.Wait->null/0
2025-02-20T02:47:37.387Z,1740019657.387 [CommandExec](IMPORTANT): Resuming mission and schedule
2025-02-20T02:47:37.701Z,1740019657.701 [Default] Stopped
2025-02-20T02:47:37.702Z,1740019657.702 [Default](DEBUG): Aggregate::uninitialize Default
2025-02-20T02:47:37.702Z,1740019657.702 [Default:B.GoToSurface] Stopped
2025-02-20T02:47:37.702Z,1740019657.702 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-20T02:47:37.702Z,1740019657.702 [Default:CheckIn] Stopped
2025-02-20T02:47:37.702Z,1740019657.702 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-20T02:47:37.702Z,1740019657.702 [Default:CheckIn:Read_Iridium] Stopped
2025-02-20T02:47:37.702Z,1740019657.702 [MissionManager](IMPORTANT): Started mission keepstation
2025-02-20T02:47:37.702Z,1740019657.702 [keepstation] Running Loop=1
2025-02-20T02:47:37.703Z,1740019657.703 [keepstation](DEBUG): Aggregate::initialize keepstation
2025-02-20T02:47:37.703Z,1740019657.703 [keepstation:StandardEnvelopes] Running Loop=1
2025-02-20T02:47:37.703Z,1740019657.703 [keepstation:StandardEnvelopes](DEBUG): Aggregate::initialize keepstation:StandardEnvelopes
2025-02-20T02:47:37.703Z,1740019657.703 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-20T02:47:37.704Z,1740019657.704 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-02-20T02:47:37.704Z,1740019657.704 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-20T02:47:37.704Z,1740019657.704 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-02-20T02:47:37.704Z,1740019657.704 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-20T02:47:37.704Z,1740019657.704 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-02-20T02:47:37.704Z,1740019657.704 [keepstation:BackseatDriver] Running Loop=1
2025-02-20T02:47:37.704Z,1740019657.704 [keepstation:BackseatDriver](DEBUG): Aggregate::initialize keepstation:BackseatDriver
2025-02-20T02:47:37.704Z,1740019657.704 [keepstation:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-02-20T02:47:37.705Z,1740019657.705 [keepstation:PowerOnly] Running Loop=1
2025-02-20T02:47:37.705Z,1740019657.705 [keepstation:PowerOnly](DEBUG): Aggregate::initialize keepstation:PowerOnly
2025-02-20T02:47:37.705Z,1740019657.705 [keepstation:PowerOnly:A] Running Loop=1
2025-02-20T02:47:37.705Z,1740019657.705 [keepstation:PowerOnly:B] Running Loop=1
2025-02-20T02:47:37.705Z,1740019657.705 [keepstation:PowerOnly:C] Running Loop=1
2025-02-20T02:47:37.705Z,1740019657.705 [keepstation:PowerOnly:D] Running Loop=1
2025-02-20T02:47:37.705Z,1740019657.705 [keepstation:PowerOnly:E.Wait] Running Loop=1
2025-02-20T02:47:37.705Z,1740019657.705 [keepstation:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-02-20T02:47:37.705Z,1740019657.705 [keepstation:H.Pitch] Running Loop=1
2025-02-20T02:47:37.705Z,1740019657.705 [keepstation:H.Pitch](DEBUG): Initialize.
2025-02-20T02:47:37.705Z,1740019657.705 [keepstation:KeepStation] Running Loop=1
2025-02-20T02:47:37.706Z,1740019657.706 [keepstation:KeepStation](DEBUG): Aggregate::initialize keepstation:KeepStation
2025-02-20T02:47:37.706Z,1740019657.706 [keepstation:KeepStation:A] Running Loop=1
2025-02-20T02:47:37.706Z,1740019657.706 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-02-20T02:47:37.706Z,1740019657.706 [keepstation:KeepStation:B.Pitch](DEBUG): Initialize.
2025-02-20T02:47:37.706Z,1740019657.706 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-02-20T02:47:37.706Z,1740019657.706 [keepstation:KeepStation:C.KeepStation](DEBUG): Initialize KeepStationComponent.
2025-02-20T02:47:37.706Z,1740019657.706 [keepstation:KeepStation:D.Wait] Running Loop=1
2025-02-20T02:47:37.706Z,1740019657.706 [keepstation:KeepStation:D.Wait](DEBUG): Initialize Wait Component.
2025-02-20T02:47:37.707Z,1740019657.707 [keepstation:KeepStation:C.KeepStation] Running Loop=1
2025-02-20T02:47:37.708Z,1740019657.708 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint
2025-02-20T02:47:37.709Z,1740019657.709 [keepstation:KeepStation:B.Pitch] Running Loop=1
2025-02-20T02:47:37.709Z,1740019657.709 [keepstation:KeepStation:A] Running Loop=1
2025-02-20T02:47:37.710Z,1740019657.710 [keepstation:H.Pitch] Running Loop=1
2025-02-20T02:47:37.710Z,1740019657.710 [keepstation:PowerOnly] Running Loop=1
2025-02-20T02:47:37.710Z,1740019657.710 [keepstation:PowerOnly:D] Stopped
2025-02-20T02:47:37.710Z,1740019657.710 [keepstation:PowerOnly:C] Stopped
2025-02-20T02:47:37.710Z,1740019657.710 [keepstation:PowerOnly:B] Stopped
2025-02-20T02:47:37.710Z,1740019657.710 [keepstation:PowerOnly:A] Stopped
2025-02-20T02:47:37.710Z,1740019657.710 [keepstation:BackseatDriver] Running Loop=1
2025-02-20T02:47:37.711Z,1740019657.711 [keepstation:StandardEnvelopes] Running Loop=1
2025-02-20T02:47:37.711Z,1740019657.711 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-20T02:47:37.711Z,1740019657.711 [keepstation:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-20T02:47:37.712Z,1740019657.712 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-20T02:47:39.331Z,1740019659.331 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-02-20T02:47:44.444Z,1740019664.444 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250219T230501/Express0028.lzma
2025-02-20T02:47:45.445Z,1740019665.445 [DataOverHttps](INFO): Moved sent file to Logs/20250219T230501/Express0028.lzma.bak
2025-02-20T02:47:45.445Z,1740019665.445 [DataOverHttps](INFO): SBD MOMSN=23967918
2025-02-20T02:48:37.673Z,1740019717.673 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T02:49:08.800Z,1740019748.800 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T02:49:09.804Z,1740019749.804 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-02-20T02:49:09.999Z,1740019749.999 [Radio_Surface](INFO): Powering down
2025-02-20T02:50:46.192Z,1740019846.192 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.747147
2025-02-20T02:57:20.696Z,1740020240.696 [keepstation:KeepStation:C.KeepStation](INFO): Waypoint->Rest
2025-02-20T02:57:21.079Z,1740020241.079 [ElevatorOffsetCalculator](INFO): Removing expired estimator for commanded vars: speed 1.00 m/s, pitch -20.00 deg, mass-position 11.65 mm.
2025-02-20T02:58:18.047Z,1740020298.047 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T02:58:30.557Z,1740020310.557 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T02:58:43.482Z,1740020323.482 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T02:58:56.814Z,1740020336.814 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T02:59:10.168Z,1740020350.168 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T02:59:23.478Z,1740020363.478 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T02:59:35.598Z,1740020375.598 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-20T03:04:41.426Z,1740020681.426 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-02-20T03:04:43.053Z,1740020683.053 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-20T03:04:43.053Z,1740020683.053 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6.
2025-02-20T03:04:43.056Z,1740020683.056 [BPC1](INFO): Received data from all battery sticks.
2025-02-20T03:18:28.835Z,1740021508.835 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2025-02-20T03:18:52.285Z,1740021532.285 [keepstation:NeedComms] Running Loop=1
2025-02-20T03:18:52.285Z,1740021532.285 [keepstation:NeedComms](DEBUG): Aggregate::initialize keepstation:NeedComms
2025-02-20T03:18:52.286Z,1740021532.286 [keepstation:NeedComms:B.GoToSurface] Running Loop=1
2025-02-20T03:18:52.286Z,1740021532.286 [keepstation:NeedComms:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-20T03:18:52.286Z,1740021532.286 [keepstation:NeedComms:B.GoToSurface](INFO): Received depth rate setting nan m/s.
2025-02-20T03:18:52.286Z,1740021532.286 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch setting 19.999999 degrees.
2025-02-20T03:18:52.287Z,1740021532.287 [keepstation:NeedComms:B.GoToSurface](INFO): Received speed setting 1.000000 m/s.
2025-02-20T03:18:52.287Z,1740021532.287 [keepstation:NeedComms:B.GoToSurface](INFO): Received surface timeout setting 1000.000000 seconds.
2025-02-20T03:18:52.288Z,1740021532.288 [keepstation:NeedComms:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-20T03:18:52.288Z,1740021532.288 [keepstation:NeedComms:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-20T03:18:52.288Z,1740021532.288 [keepstation:NeedComms:A] Running Loop=1
2025-02-20T03:18:52.290Z,1740021532.290 [keepstation:NeedComms:A](INFO): last time_fix was: 1740019599.000000 second since 1970/01/01T00:00:00Z
2025-02-20T03:18:52.290Z,1740021532.290 [keepstation:NeedComms:A] Stopped
2025-02-20T03:20:07.877Z,1740021607.877 [keepstation:KeepStation:C.KeepStation](INFO): Rest->Waypoint
2025-02-20T03:20:24.064Z,1740021624.064 [keepstation:NeedComms:C] Running Loop=1
2025-02-20T03:20:24.451Z,1740021624.451 [keepstation:NeedComms:C](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-20T03:20:24.571Z,1740021624.571 [Radio_Surface](INFO): Powering up
2025-02-20T03:20:30.736Z,1740021630.736 [DataOverHttps](INFO): Radio surface powered ON.
2025-02-20T03:20:45.450Z,1740021645.450 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=12850, MT Status=1, MTMSN=250
2025-02-20T03:20:45.450Z,1740021645.450 [NAL9602](INFO): Data available in MT queue
2025-02-20T03:20:45.932Z,1740021645.932 [NAL9602](INFO): Received command: stop
2025-02-20T03:20:45.992Z,1740021645.992 [CommandExec](IMPORTANT): got command stop
2025-02-20T03:20:45.992Z,1740021645.992 [CommandExec](IMPORTANT): Scheduling is paused
2025-02-20T03:20:45.992Z,1740021645.992 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-02-20T03:20:46.269Z,1740021646.269 [MissionManager](INFO): MissionManager is completed.
2025-02-20T03:20:46.269Z,1740021646.269 [MissionManager](INFO): Uninitializing Mission keepstation
2025-02-20T03:20:46.269Z,1740021646.269 [keepstation] Stopped
2025-02-20T03:20:46.269Z,1740021646.269 [keepstation](DEBUG): Aggregate::uninitialize keepstation
2025-02-20T03:20:46.270Z,1740021646.270 [keepstation:NeedComms] Stopped
2025-02-20T03:20:46.270Z,1740021646.270 [keepstation:NeedComms](DEBUG): Aggregate::uninitialize keepstation:NeedComms
2025-02-20T03:20:46.270Z,1740021646.270 [keepstation:NeedComms:B.GoToSurface] Stopped
2025-02-20T03:20:46.270Z,1740021646.270 [keepstation:NeedComms:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-20T03:20:46.270Z,1740021646.270 [keepstation:NeedComms:C] Stopped
2025-02-20T03:20:46.270Z,1740021646.270 [keepstation:StandardEnvelopes] Stopped
2025-02-20T03:20:46.270Z,1740021646.270 [keepstation:StandardEnvelopes](DEBUG): Aggregate::uninitialize keepstation:StandardEnvelopes
2025-02-20T03:20:46.270Z,1740021646.270 [keepstation:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-02-20T03:20:46.270Z,1740021646.270 [keepstation:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-02-20T03:20:46.270Z,1740021646.270 [keepstation:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-02-20T03:20:46.270Z,1740021646.270 [keepstation:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-02-20T03:20:46.270Z,1740021646.270 [keepstation:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-02-20T03:20:46.270Z,1740021646.270 [keepstation:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-02-20T03:20:46.271Z,1740021646.271 [keepstation:BackseatDriver] Stopped
2025-02-20T03:20:46.271Z,1740021646.271 [keepstation:BackseatDriver](DEBUG): Aggregate::uninitialize keepstation:BackseatDriver
2025-02-20T03:20:46.271Z,1740021646.271 [keepstation:BackseatDriver:A.BackseatDriver] Stopped
2025-02-20T03:20:46.271Z,1740021646.271 [keepstation:PowerOnly] Stopped
2025-02-20T03:20:46.271Z,1740021646.271 [keepstation:PowerOnly](DEBUG): Aggregate::uninitialize keepstation:PowerOnly
2025-02-20T03:20:46.271Z,1740021646.271 [keepstation:PowerOnly:E.Wait] Stopped
2025-02-20T03:20:46.271Z,1740021646.271 [keepstation:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-02-20T03:20:46.271Z,1740021646.271 [keepstation:H.Pitch] Stopped
2025-02-20T03:20:46.271Z,1740021646.271 [keepstation:KeepStation] Stopped
2025-02-20T03:20:46.271Z,1740021646.271 [keepstation:KeepStation](DEBUG): Aggregate::uninitialize keepstation:KeepStation
2025-02-20T03:20:46.272Z,1740021646.272 [keepstation:KeepStation:A] Stopped
2025-02-20T03:20:46.272Z,1740021646.272 [keepstation:KeepStation:B.Pitch] Stopped
2025-02-20T03:20:46.272Z,1740021646.272 [keepstation:KeepStation:C.KeepStation] Stopped
2025-02-20T03:20:46.272Z,1740021646.272 [keepstation:KeepStation:C.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2025-02-20T03:20:46.272Z,1740021646.272 [keepstation:KeepStation:D.Wait] Stopped
2025-02-20T03:20:46.272Z,1740021646.272 [keepstation:KeepStation:D.Wait](DEBUG): Uninitialize Wait Component.
2025-02-20T03:20:46.693Z,1740021646.693 [MissionManager](IMPORTANT): Started mission Default
2025-02-20T03:20:46.693Z,1740021646.693 [Default] Running Loop=1
2025-02-20T03:20:46.693Z,1740021646.693 [Default](DEBUG): Aggregate::initialize Default
2025-02-20T03:20:46.693Z,1740021646.693 [Default:B.GoToSurface] Running Loop=1
2025-02-20T03:20:46.693Z,1740021646.693 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-20T03:20:46.694Z,1740021646.694 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-20T03:20:46.694Z,1740021646.694 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-20T03:20:46.694Z,1740021646.694 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-20T03:20:46.694Z,1740021646.694 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-20T03:20:46.695Z,1740021646.695 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-20T03:20:46.695Z,1740021646.695 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-20T03:20:46.696Z,1740021646.696 [Default:A.Wait] Running Loop=1
2025-02-20T03:20:46.696Z,1740021646.696 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-20T03:20:47.066Z,1740021647.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,032046.00,A,3647.76377,N,12150.82378,W,0.467,289.07,200225,,,A*7C
2025-02-20T03:20:47.069Z,1740021647.069 [NAL9602](INFO): GPS fix at 20250220T032046: (36.796063, -121.847063)
2025-02-20T03:20:47.102Z,1740021647.102 [UniversalFixResidualReporter](INFO): Fix residual: 42.6 %DT, over the last 528.6 m. Residual distance 225.3 m at bearing -67.5 degrees. Fix at (36.7961, -121.8471) with 265.1 m made good.
2025-02-20T03:20:55.954Z,1740021655.954 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -13.00 mm (2 active estimators).
2025-02-20T03:21:00.005Z,1740021660.005 [Default:A.Wait](INFO): Done Waiting.
2025-02-20T03:21:00.005Z,1740021660.005 [Default:A.Wait] Stopped
2025-02-20T03:21:00.005Z,1740021660.005 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-20T03:21:00.864Z,1740021660.864 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T03:21:19.380Z,1740021679.380 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-20T03:21:30.299Z,1740021690.299 [Default:CheckIn] Running Loop=1
2025-02-20T03:21:30.300Z,1740021690.300 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-20T03:21:30.300Z,1740021690.300 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-20T03:21:31.992Z,1740021691.992 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T03:21:43.217Z,1740021703.217 [NAL9602](INFO): SBD MO Status=0, MOMSN=12851, MT Status=0, MTMSN=0
2025-02-20T03:21:43.217Z,1740021703.217 [NAL9602](INFO): No messages in MT queue
2025-02-20T03:21:44.430Z,1740021704.430 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,032143.00,A,3647.76419,N,12150.83632,W,0.175,282.28,200225,,,A*7D
2025-02-20T03:21:44.432Z,1740021704.432 [NAL9602](INFO): GPS fix at 20250220T032143: (36.796070, -121.847272)
2025-02-20T03:21:44.463Z,1740021704.463 [Default:CheckIn:Read_GPS] Stopped
2025-02-20T03:21:44.463Z,1740021704.463 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-20T03:21:53.797Z,1740021713.796 [NAL9602](INFO): SBD MO Status=1, MOMSN=12852, MT Status=0, MTMSN=0
2025-02-20T03:21:53.851Z,1740021713.851 [NAL9602](INFO): Sent 39 bytes from file Logs/20250219T230501/Courier0030.lzma
2025-02-20T03:21:53.852Z,1740021713.852 [NAL9602](INFO): Packets left to send: 0
2025-02-20T03:21:59.541Z,1740021719.541 [NAL9602](INFO): SBD MO Status=1, MOMSN=12853, MT Status=0, MTMSN=0
2025-02-20T03:21:59.591Z,1740021719.591 [NAL9602](INFO): Sent 98 bytes from file Logs/20250219T230501/Courier0033.lzma
2025-02-20T03:21:59.592Z,1740021719.592 [NAL9602](INFO): Packets left to send: 0
2025-02-20T03:22:03.132Z,1740021723.132 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T03:22:08.505Z,1740021728.505 [NAL9602](INFO): SBD MO Status=1, MOMSN=12854, MT Status=0, MTMSN=0
2025-02-20T03:22:08.555Z,1740021728.555 [NAL9602](INFO): Sent 132 bytes from file Logs/20250219T230501/Express0031.lzma
2025-02-20T03:22:08.556Z,1740021728.556 [NAL9602](INFO): Packets left to send: 0
2025-02-20T03:22:20.274Z,1740021740.274 [NAL9602](INFO): SBD MO Status=1, MOMSN=12855, MT Status=0, MTMSN=0
2025-02-20T03:22:20.332Z,1740021740.332 [NAL9602](INFO): Sent 332 bytes from file Logs/20250219T230501/Express0034.lzma
2025-02-20T03:22:20.332Z,1740021740.332 [NAL9602](INFO): Packets left to send: 1
2025-02-20T03:22:29.210Z,1740021749.210 [NAL9602](INFO): SBD MO Status=1, MOMSN=12856, MT Status=0, MTMSN=0
2025-02-20T03:22:29.268Z,1740021749.268 [NAL9602](INFO): Sent 65 bytes from file Logs/20250219T230501/Express0034.lzma
2025-02-20T03:22:29.268Z,1740021749.268 [NAL9602](INFO): Packets left to send: 0
2025-02-20T03:22:34.272Z,1740021754.272 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-02-20T03:22:36.554Z,1740021756.554 [NAL9602](INFO): SBD MO Status=0, MOMSN=12857, MT Status=0, MTMSN=0
2025-02-20T03:22:36.645Z,1740021756.645 [Default:CheckIn:Read_Iridium] Stopped
2025-02-20T03:22:36.646Z,1740021756.646 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-20T03:22:36.646Z,1740021756.646 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-20T03:22:43.240Z,1740021763.240 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003198
2025-02-20T03:23:07.289Z,1740021787.289 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-20T03:27:37.294Z,1740022057.294 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-20T03:27:37.294Z,1740022057.294 [Default:CheckIn:C.Wait] Stopped
2025-02-20T03:27:37.294Z,1740022057.294 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-20T03:27:37.295Z,1740022057.295 [Default:CheckIn:D] Running Loop=1
2025-02-20T03:27:37.706Z,1740022057.706 [Default:CheckIn:D] Stopped
2025-02-20T03:27:37.706Z,1740022057.706 [Default:CheckIn:E] Running Loop=1
2025-02-20T03:27:38.118Z,1740022058.118 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.850216 min
2025-02-20T03:27:38.118Z,1740022058.118 [Default:CheckIn:E] Stopped
2025-02-20T03:27:38.118Z,1740022058.118 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-20T03:27:38.118Z,1740022058.118 [Default:CheckIn] Stopped
2025-02-20T03:27:38.118Z,1740022058.118 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-20T03:27:38.118Z,1740022058.118 [Default:CheckIn](INFO): Running loop #2
2025-02-20T03:27:38.118Z,1740022058.118 [Default:CheckIn] Running Loop=2
2025-02-20T03:27:38.118Z,1740022058.118 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-20T03:27:38.119Z,1740022058.119 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-20T03:27:40.115Z,1740022060.115 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,032739.00,A,3647.76673,N,12150.80553,W,0.700,94.65,200225,,,D*42
2025-02-20T03:27:40.117Z,1740022060.117 [NAL9602](INFO): GPS fix at 20250220T032739: (36.796112, -121.846759)
2025-02-20T03:27:40.129Z,1740022060.129 [Default:CheckIn:Read_GPS] Stopped
2025-02-20T03:27:40.129Z,1740022060.129 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-20T03:27:46.195Z,1740022066.195 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250219T230501/Courier0036.lzma
2025-02-20T03:27:47.197Z,1740022067.197 [DataOverHttps](INFO): Moved sent file to Logs/20250219T230501/Courier0036.lzma.bak
2025-02-20T03:27:47.197Z,1740022067.197 [DataOverHttps](INFO): SBD MOMSN=23967948
2025-02-20T03:27:53.042Z,1740022073.042 [NAL9602](INFO): SBD MO Status=0, MOMSN=12858, MT Status=0, MTMSN=0
2025-02-20T03:27:53.042Z,1740022073.042 [NAL9602](INFO): No messages in MT queue
2025-02-20T03:28:03.603Z,1740022083.603 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20250219T230501/Express0037.lzma
2025-02-20T03:28:04.605Z,1740022084.605 [DataOverHttps](INFO): Moved sent file to Logs/20250219T230501/Express0037.lzma.bak
2025-02-20T03:28:04.605Z,1740022084.605 [DataOverHttps](INFO): SBD MOMSN=23967952
2025-02-20T03:28:05.996Z,1740022085.996 [Default:CheckIn:Read_Iridium] Stopped
2025-02-20T03:28:05.997Z,1740022085.997 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-20T03:28:05.997Z,1740022085.997 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-20T03:28:23.745Z,1740022103.745 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-20T03:31:47.281Z,1740022307.281 [DataOverHttps](IMPORTANT): SBD MTMSN=20250220T033146
2025-02-20T03:31:56.264Z,1740022316.264 [DataOverHttps](INFO): Received command: schedule clear;schedule resume
2025-02-20T03:31:56.327Z,1740022316.327 [CommandExec](IMPORTANT): got command schedule clear
2025-02-20T03:31:56.327Z,1740022316.327 [CommandExec](IMPORTANT): Cleared 0 scheduled commands.
2025-02-20T03:31:56.328Z,1740022316.328 [CommandExec](IMPORTANT): got command schedule resume
2025-02-20T03:31:56.328Z,1740022316.328 [CommandExec](IMPORTANT): Scheduling is resumed
2025-02-20T03:33:06.676Z,1740022386.676 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-20T03:33:06.676Z,1740022386.676 [Default:CheckIn:C.Wait] Stopped
2025-02-20T03:33:06.677Z,1740022386.677 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-20T03:33:06.677Z,1740022386.677 [Default:CheckIn:D] Running Loop=1
2025-02-20T03:33:07.085Z,1740022387.085 [Default:CheckIn:D] Stopped
2025-02-20T03:33:07.085Z,1740022387.085 [Default:CheckIn:E] Running Loop=1
2025-02-20T03:33:07.505Z,1740022387.505 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.339864 min
2025-02-20T03:33:07.505Z,1740022387.505 [Default:CheckIn:E] Stopped
2025-02-20T03:33:07.505Z,1740022387.505 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-20T03:33:07.505Z,1740022387.505 [Default:CheckIn] Stopped
2025-02-20T03:33:07.505Z,1740022387.505 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-20T03:33:07.505Z,1740022387.505 [Default:CheckIn](INFO): Running loop #3
2025-02-20T03:33:07.505Z,1740022387.505 [Default:CheckIn] Running Loop=3
2025-02-20T03:33:07.505Z,1740022387.505 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-20T03:33:07.506Z,1740022387.506 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-20T03:33:09.483Z,1740022389.483 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,033308.00,A,3647.76685,N,12150.76404,W,0.136,99.83,200225,,,D*40
2025-02-20T03:33:09.485Z,1740022389.485 [NAL9602](INFO): GPS fix at 20250220T033308: (36.796114, -121.846067)
2025-02-20T03:33:09.505Z,1740022389.505 [Default:CheckIn:Read_GPS] Stopped
2025-02-20T03:33:09.505Z,1740022389.505 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-20T03:33:17.235Z,1740022397.235 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250219T230501/Courier0039.lzma
2025-02-20T03:33:18.237Z,1740022398.237 [DataOverHttps](INFO): Moved sent file to Logs/20250219T230501/Courier0039.lzma.bak
2025-02-20T03:33:18.237Z,1740022398.237 [DataOverHttps](INFO): SBD MOMSN=23967957
2025-02-20T03:33:37.646Z,1740022417.646 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20250219T230501/Express0040.lzma
2025-02-20T03:33:38.653Z,1740022418.653 [DataOverHttps](INFO): Moved sent file to Logs/20250219T230501/Express0040.lzma.bak
2025-02-20T03:33:38.653Z,1740022418.653 [DataOverHttps](INFO): SBD MOMSN=23967960
2025-02-20T03:33:39.087Z,1740022419.087 [NAL9602](INFO): SBD MO Status=2, MOMSN=12859, MT Status=2, MTMSN=0
2025-02-20T03:33:39.088Z,1740022419.088 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T03:33:54.969Z,1740022434.969 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250219T230501/Express0043.lzma
2025-02-20T03:33:55.969Z,1740022435.969 [DataOverHttps](INFO): Moved sent file to Logs/20250219T230501/Express0043.lzma.bak
2025-02-20T03:33:55.969Z,1740022435.969 [DataOverHttps](INFO): SBD MOMSN=23967968
2025-02-20T03:33:57.288Z,1740022437.288 [Default:CheckIn:Read_Iridium] Stopped
2025-02-20T03:33:57.288Z,1740022437.288 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-20T03:33:57.288Z,1740022437.288 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-20T03:33:57.664Z,1740022437.664 [NAL9602](INFO): SBD MO Status=2, MOMSN=12859, MT Status=2, MTMSN=0
2025-02-20T03:33:57.664Z,1740022437.664 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-20T03:34:10.190Z,1740022450.190 [NAL9602](INFO): SBD MO Status=0, MOMSN=12859, MT Status=0, MTMSN=0
2025-02-20T03:34:10.190Z,1740022450.190 [NAL9602](INFO): No messages in MT queue
2025-02-20T03:34:40.895Z,1740022480.895 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-20T03:36:37.745Z,1740022597.745 [DataOverHttps](IMPORTANT): SBD MTMSN=20250220T033636
2025-02-20T03:36:45.855Z,1740022605.855 [DataOverHttps](INFO): Received command: restart logs