2025-10-16T20:44:19.914Z,1760647459.914 [CommandExec](IMPORTANT): got command restart logs 2025-10-16T20:48:00.947Z,1760647680.947 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T20:48:00.948Z,1760647680.948 [Default:CheckIn:C.Wait] Stopped 2025-10-16T20:48:00.948Z,1760647680.948 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T20:48:00.948Z,1760647680.948 [Default:CheckIn:D] Running Loop=1 2025-10-16T20:48:01.366Z,1760647681.366 [Default:CheckIn:D] Stopped 2025-10-16T20:48:01.366Z,1760647681.366 [Default:CheckIn:E] Running Loop=1 2025-10-16T20:48:01.755Z,1760647681.755 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.817784 min 2025-10-16T20:48:01.755Z,1760647681.755 [Default:CheckIn:E] Stopped 2025-10-16T20:48:01.755Z,1760647681.755 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T20:48:01.755Z,1760647681.755 [Default:CheckIn] Stopped 2025-10-16T20:48:01.755Z,1760647681.755 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T20:48:01.755Z,1760647681.755 [Default:CheckIn](INFO): Running loop #4 2025-10-16T20:48:01.755Z,1760647681.755 [Default:CheckIn] Running Loop=4 2025-10-16T20:48:01.756Z,1760647681.756 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T20:48:01.756Z,1760647681.756 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T20:48:03.762Z,1760647683.762 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204757.00,A,3648.21635,N,12149.46531,W,28.050,63.92,161025,,,D*7A 2025-10-16T20:48:03.765Z,1760647683.765 [NAL9602](INFO): GPS fix at 20251016T204757: (36.803606, -121.824422) 2025-10-16T20:48:03.775Z,1760647683.775 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T20:48:03.775Z,1760647683.775 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T20:48:11.727Z,1760647691.727 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20251016T141248/Courier0042.lzma 2025-10-16T20:48:12.730Z,1760647692.730 [DataOverHttps](INFO): Moved sent file to Logs/20251016T141248/Courier0042.lzma.bak 2025-10-16T20:48:12.730Z,1760647692.730 [DataOverHttps](INFO): SBD MOMSN=26207390 2025-10-16T20:48:18.310Z,1760647698.310 [NAL9602](INFO): SBD MO Status=1, MOMSN=23859, MT Status=0, MTMSN=0 2025-10-16T20:48:18.310Z,1760647698.310 [NAL9602](INFO): No messages in MT queue 2025-10-16T20:48:28.708Z,1760647708.708 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20251016T204419/Courier0000.lzma 2025-10-16T20:48:29.710Z,1760647709.710 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0000.lzma.bak 2025-10-16T20:48:29.710Z,1760647709.710 [DataOverHttps](INFO): SBD MOMSN=26207393 2025-10-16T20:48:45.704Z,1760647725.704 [DataOverHttps](INFO): Sending 110 bytes from file Logs/20251016T141248/Express0043.lzma 2025-10-16T20:48:46.706Z,1760647726.706 [DataOverHttps](INFO): Moved sent file to Logs/20251016T141248/Express0043.lzma.bak 2025-10-16T20:48:46.706Z,1760647726.706 [DataOverHttps](INFO): SBD MOMSN=26207400 2025-10-16T20:48:49.015Z,1760647729.015 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T20:49:02.689Z,1760647742.689 [DataOverHttps](INFO): Sending 501 bytes from file Logs/20251016T204419/Express0001.lzma 2025-10-16T20:49:03.690Z,1760647743.690 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0001.lzma.bak 2025-10-16T20:49:03.690Z,1760647743.690 [DataOverHttps](INFO): SBD MOMSN=26207415 2025-10-16T20:49:24.244Z,1760647764.244 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20251016T204419/Express0004.lzma 2025-10-16T20:49:25.246Z,1760647765.246 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0004.lzma.bak 2025-10-16T20:49:25.246Z,1760647765.246 [DataOverHttps](INFO): SBD MOMSN=26207419 2025-10-16T20:49:26.600Z,1760647766.600 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T20:49:26.600Z,1760647766.600 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T20:49:26.600Z,1760647766.600 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T20:54:27.309Z,1760648067.309 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T20:54:27.310Z,1760648067.310 [Default:CheckIn:C.Wait] Stopped 2025-10-16T20:54:27.310Z,1760648067.310 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T20:54:27.310Z,1760648067.310 [Default:CheckIn:D] Running Loop=1 2025-10-16T20:54:27.602Z,1760648067.602 [Default:CheckIn:D] Stopped 2025-10-16T20:54:27.602Z,1760648067.602 [Default:CheckIn:E] Running Loop=1 2025-10-16T20:54:28.027Z,1760648068.027 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.255054 min 2025-10-16T20:54:28.028Z,1760648068.028 [Default:CheckIn:E] Stopped 2025-10-16T20:54:28.028Z,1760648068.028 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T20:54:28.028Z,1760648068.028 [Default:CheckIn] Stopped 2025-10-16T20:54:28.028Z,1760648068.028 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T20:54:28.028Z,1760648068.028 [Default:CheckIn](INFO): Running loop #5 2025-10-16T20:54:28.028Z,1760648068.028 [Default:CheckIn] Running Loop=5 2025-10-16T20:54:28.028Z,1760648068.028 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T20:54:28.029Z,1760648068.029 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T20:54:30.002Z,1760648070.002 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205423.00,A,3648.47951,N,12148.73036,W,1.458,78.91,161025,,,D*4D 2025-10-16T20:54:30.004Z,1760648070.004 [NAL9602](INFO): GPS fix at 20251016T205423: (36.807992, -121.812173) 2025-10-16T20:54:30.031Z,1760648070.031 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T20:54:30.031Z,1760648070.031 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T20:54:41.472Z,1760648081.472 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251016T204419/Courier0006.lzma 2025-10-16T20:54:43.478Z,1760648083.478 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0006.lzma.bak 2025-10-16T20:54:43.478Z,1760648083.478 [DataOverHttps](INFO): SBD MOMSN=26207421 2025-10-16T20:54:59.528Z,1760648099.528 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20251016T204419/Express0007.lzma 2025-10-16T20:55:00.530Z,1760648100.530 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0007.lzma.bak 2025-10-16T20:55:00.530Z,1760648100.530 [DataOverHttps](INFO): SBD MOMSN=26207424 2025-10-16T20:55:01.926Z,1760648101.926 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T20:55:01.926Z,1760648101.926 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T20:55:01.926Z,1760648101.926 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T20:55:58.075Z,1760648158.075 [NAL9602](INFO): SBD MO Status=2, MOMSN=23860, MT Status=2, MTMSN=0 2025-10-16T20:55:58.075Z,1760648158.075 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T20:57:44.335Z,1760648264.335 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-10-16T20:57:54.434Z,1760648274.434 [NAL9602](INFO): SBD MO Status=2, MOMSN=23860, MT Status=2, MTMSN=0 2025-10-16T20:57:54.434Z,1760648274.434 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T20:58:09.085Z,1760648289.085 [NAL9602](INFO): SBD MO Status=1, MOMSN=23860, MT Status=0, MTMSN=0 2025-10-16T20:58:09.086Z,1760648289.086 [NAL9602](INFO): No messages in MT queue 2025-10-16T20:58:39.677Z,1760648319.677 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T21:00:02.507Z,1760648402.507 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T21:00:02.507Z,1760648402.507 [Default:CheckIn:C.Wait] Stopped 2025-10-16T21:00:02.507Z,1760648402.507 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T21:00:02.508Z,1760648402.508 [Default:CheckIn:D] Running Loop=1 2025-10-16T21:00:02.923Z,1760648402.923 [Default:CheckIn:D] Stopped 2025-10-16T21:00:02.923Z,1760648402.923 [Default:CheckIn:E] Running Loop=1 2025-10-16T21:00:03.314Z,1760648403.314 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.843724 min 2025-10-16T21:00:03.314Z,1760648403.314 [Default:CheckIn:E] Stopped 2025-10-16T21:00:03.314Z,1760648403.314 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T21:00:03.314Z,1760648403.314 [Default:CheckIn] Stopped 2025-10-16T21:00:03.315Z,1760648403.315 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T21:00:03.315Z,1760648403.315 [Default:CheckIn](INFO): Running loop #6 2025-10-16T21:00:03.315Z,1760648403.315 [Default:CheckIn] Running Loop=6 2025-10-16T21:00:03.315Z,1760648403.315 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T21:00:03.315Z,1760648403.315 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T21:00:05.334Z,1760648405.334 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205958.00,A,3648.50589,N,12148.66494,W,4.685,232.35,161025,,,D*7E 2025-10-16T21:00:05.336Z,1760648405.336 [NAL9602](INFO): GPS fix at 20251016T205958: (36.808431, -121.811082) 2025-10-16T21:00:05.347Z,1760648405.347 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T21:00:05.347Z,1760648405.347 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T21:00:12.501Z,1760648412.501 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251016T204419/Courier0009.lzma 2025-10-16T21:00:13.502Z,1760648413.502 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0009.lzma.bak 2025-10-16T21:00:13.502Z,1760648413.502 [DataOverHttps](INFO): SBD MOMSN=26207452 2025-10-16T21:00:34.400Z,1760648434.400 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20251016T204419/Express0010.lzma 2025-10-16T21:00:35.402Z,1760648435.402 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0010.lzma.bak 2025-10-16T21:00:35.402Z,1760648435.402 [DataOverHttps](INFO): SBD MOMSN=26207455 2025-10-16T21:00:36.916Z,1760648436.916 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T21:00:36.916Z,1760648436.916 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T21:00:36.916Z,1760648436.916 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T21:00:50.625Z,1760648450.625 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-10-16T21:01:45.157Z,1760648505.157 [NAL9602](INFO): SBD MO Status=2, MOMSN=23861, MT Status=2, MTMSN=0 2025-10-16T21:01:45.157Z,1760648505.157 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T21:02:06.574Z,1760648526.574 [NAL9602](INFO): SBD MO Status=1, MOMSN=23861, MT Status=0, MTMSN=0 2025-10-16T21:02:06.574Z,1760648526.574 [NAL9602](INFO): No messages in MT queue 2025-10-16T21:02:37.277Z,1760648557.277 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T21:05:37.465Z,1760648737.465 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T21:05:37.465Z,1760648737.465 [Default:CheckIn:C.Wait] Stopped 2025-10-16T21:05:37.465Z,1760648737.465 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T21:05:37.466Z,1760648737.466 [Default:CheckIn:D] Running Loop=1 2025-10-16T21:05:37.877Z,1760648737.877 [Default:CheckIn:D] Stopped 2025-10-16T21:05:37.877Z,1760648737.877 [Default:CheckIn:E] Running Loop=1 2025-10-16T21:05:38.274Z,1760648738.274 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.426298 min 2025-10-16T21:05:38.274Z,1760648738.274 [Default:CheckIn:E] Stopped 2025-10-16T21:05:38.274Z,1760648738.274 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T21:05:38.274Z,1760648738.274 [Default:CheckIn] Stopped 2025-10-16T21:05:38.274Z,1760648738.274 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T21:05:38.274Z,1760648738.274 [Default:CheckIn](INFO): Running loop #7 2025-10-16T21:05:38.275Z,1760648738.275 [Default:CheckIn] Running Loop=7 2025-10-16T21:05:38.275Z,1760648738.275 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T21:05:38.275Z,1760648738.275 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T21:05:40.289Z,1760648740.289 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210533.00,A,3648.47470,N,12148.59552,W,0.447,274.78,161025,,,A*7B 2025-10-16T21:05:40.291Z,1760648740.291 [NAL9602](INFO): GPS fix at 20251016T210533: (36.807912, -121.809925) 2025-10-16T21:05:40.302Z,1760648740.302 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T21:05:40.302Z,1760648740.302 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T21:05:42.340Z,1760648742.340 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-16T21:06:13.464Z,1760648773.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-10-16T21:06:17.055Z,1760648777.055 [NAL9602](INFO): SBD MO Status=2, MOMSN=23862, MT Status=2, MTMSN=0 2025-10-16T21:06:17.056Z,1760648777.056 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T21:06:36.846Z,1760648796.846 [NAL9602](INFO): SBD MO Status=1, MOMSN=23862, MT Status=0, MTMSN=0 2025-10-16T21:06:36.846Z,1760648796.846 [NAL9602](INFO): No messages in MT queue 2025-10-16T21:06:43.294Z,1760648803.294 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251016T204419/Courier0012.lzma 2025-10-16T21:06:44.294Z,1760648804.294 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0012.lzma.bak 2025-10-16T21:06:44.295Z,1760648804.295 [DataOverHttps](INFO): SBD MOMSN=26207467 2025-10-16T21:07:00.316Z,1760648820.316 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20251016T204419/Express0013.lzma 2025-10-16T21:07:01.318Z,1760648821.318 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0013.lzma.bak 2025-10-16T21:07:01.318Z,1760648821.318 [DataOverHttps](INFO): SBD MOMSN=26207470 2025-10-16T21:07:02.742Z,1760648822.742 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T21:07:02.743Z,1760648822.743 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T21:07:02.743Z,1760648822.743 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T21:07:07.573Z,1760648827.573 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T21:10:27.244Z,1760649027.244 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-10-16T21:12:03.347Z,1760649123.347 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T21:12:03.347Z,1760649123.347 [Default:CheckIn:C.Wait] Stopped 2025-10-16T21:12:03.347Z,1760649123.347 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T21:12:03.347Z,1760649123.347 [Default:CheckIn:D] Running Loop=1 2025-10-16T21:12:03.756Z,1760649123.756 [Default:CheckIn:D] Stopped 2025-10-16T21:12:03.756Z,1760649123.756 [Default:CheckIn:E] Running Loop=1 2025-10-16T21:12:04.172Z,1760649124.172 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.857617 min 2025-10-16T21:12:04.172Z,1760649124.172 [Default:CheckIn:E] Stopped 2025-10-16T21:12:04.172Z,1760649124.172 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T21:12:04.172Z,1760649124.172 [Default:CheckIn] Stopped 2025-10-16T21:12:04.173Z,1760649124.173 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T21:12:04.173Z,1760649124.173 [Default:CheckIn](INFO): Running loop #8 2025-10-16T21:12:04.173Z,1760649124.173 [Default:CheckIn] Running Loop=8 2025-10-16T21:12:04.173Z,1760649124.173 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T21:12:04.173Z,1760649124.173 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T21:12:06.166Z,1760649126.166 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211159.00,A,3648.37975,N,12148.30581,W,4.801,119.00,161025,,,D*74 2025-10-16T21:12:06.168Z,1760649126.168 [NAL9602](INFO): GPS fix at 20251016T211159: (36.806329, -121.805097) 2025-10-16T21:12:06.178Z,1760649126.178 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T21:12:06.178Z,1760649126.178 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T21:12:14.312Z,1760649134.312 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251016T204419/Courier0015.lzma 2025-10-16T21:12:16.330Z,1760649136.330 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0015.lzma.bak 2025-10-16T21:12:16.330Z,1760649136.330 [DataOverHttps](INFO): SBD MOMSN=26207484 2025-10-16T21:12:36.236Z,1760649156.236 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20251016T204419/Express0016.lzma 2025-10-16T21:12:37.238Z,1760649157.238 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0016.lzma.bak 2025-10-16T21:12:37.238Z,1760649157.238 [DataOverHttps](INFO): SBD MOMSN=26207487 2025-10-16T21:12:38.907Z,1760649158.907 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T21:12:38.907Z,1760649158.907 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T21:12:38.907Z,1760649158.907 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T21:14:26.357Z,1760649266.357 [NAL9602](INFO): SBD MO Status=2, MOMSN=23863, MT Status=2, MTMSN=0 2025-10-16T21:14:26.357Z,1760649266.357 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T21:14:42.524Z,1760649282.524 [NAL9602](INFO): SBD MO Status=1, MOMSN=23863, MT Status=0, MTMSN=0 2025-10-16T21:14:42.524Z,1760649282.524 [NAL9602](INFO): No messages in MT queue 2025-10-16T21:15:13.229Z,1760649313.229 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T21:17:39.536Z,1760649459.536 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T21:17:39.536Z,1760649459.536 [Default:CheckIn:C.Wait] Stopped 2025-10-16T21:17:39.536Z,1760649459.536 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T21:17:39.537Z,1760649459.537 [Default:CheckIn:D] Running Loop=1 2025-10-16T21:17:39.906Z,1760649459.906 [Default:CheckIn:D] Stopped 2025-10-16T21:17:39.906Z,1760649459.906 [Default:CheckIn:E] Running Loop=1 2025-10-16T21:17:40.303Z,1760649460.303 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.460128 min 2025-10-16T21:17:40.303Z,1760649460.303 [Default:CheckIn:E] Stopped 2025-10-16T21:17:40.305Z,1760649460.305 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T21:17:40.305Z,1760649460.305 [Default:CheckIn] Stopped 2025-10-16T21:17:40.305Z,1760649460.305 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T21:17:40.305Z,1760649460.305 [Default:CheckIn](INFO): Running loop #9 2025-10-16T21:17:40.305Z,1760649460.305 [Default:CheckIn] Running Loop=9 2025-10-16T21:17:40.305Z,1760649460.305 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T21:17:40.305Z,1760649460.305 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T21:17:42.294Z,1760649462.294 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211735.00,A,3648.26673,N,12147.76955,W,4.976,92.75,161025,,,D*48 2025-10-16T21:17:42.296Z,1760649462.296 [NAL9602](INFO): GPS fix at 20251016T211735: (36.804445, -121.796159) 2025-10-16T21:17:42.306Z,1760649462.306 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T21:17:42.306Z,1760649462.306 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T21:17:49.804Z,1760649469.804 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251016T204419/Courier0018.lzma 2025-10-16T21:17:50.806Z,1760649470.806 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0018.lzma.bak 2025-10-16T21:17:50.806Z,1760649470.806 [DataOverHttps](INFO): SBD MOMSN=26207491 2025-10-16T21:17:58.054Z,1760649478.054 [NAL9602](INFO): SBD MO Status=1, MOMSN=23864, MT Status=0, MTMSN=0 2025-10-16T21:17:58.055Z,1760649478.055 [NAL9602](INFO): No messages in MT queue 2025-10-16T21:18:06.640Z,1760649486.640 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20251016T204419/Express0019.lzma 2025-10-16T21:18:07.642Z,1760649487.642 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0019.lzma.bak 2025-10-16T21:18:07.642Z,1760649487.642 [DataOverHttps](INFO): SBD MOMSN=26207494 2025-10-16T21:18:08.976Z,1760649488.976 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T21:18:08.976Z,1760649488.976 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T21:18:08.977Z,1760649488.977 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T21:18:28.753Z,1760649508.753 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T21:19:38.240Z,1760649578.240 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-10-16T21:19:38.244Z,1760649578.244 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-10-16T21:23:09.544Z,1760649789.544 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T21:23:09.545Z,1760649789.545 [Default:CheckIn:C.Wait] Stopped 2025-10-16T21:23:09.545Z,1760649789.545 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T21:23:09.545Z,1760649789.545 [Default:CheckIn:D] Running Loop=1 2025-10-16T21:23:09.956Z,1760649789.956 [Default:CheckIn:D] Stopped 2025-10-16T21:23:09.956Z,1760649789.956 [Default:CheckIn:E] Running Loop=1 2025-10-16T21:23:10.371Z,1760649790.371 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.960962 min 2025-10-16T21:23:10.371Z,1760649790.371 [Default:CheckIn:E] Stopped 2025-10-16T21:23:10.371Z,1760649790.371 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T21:23:10.371Z,1760649790.371 [Default:CheckIn] Stopped 2025-10-16T21:23:10.371Z,1760649790.371 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T21:23:10.372Z,1760649790.372 [Default:CheckIn](INFO): Running loop #10 2025-10-16T21:23:10.372Z,1760649790.372 [Default:CheckIn] Running Loop=10 2025-10-16T21:23:10.372Z,1760649790.372 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T21:23:10.372Z,1760649790.372 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T21:23:12.361Z,1760649792.361 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212305.00,A,3648.43138,N,12147.33363,W,1.438,217.27,161025,,,D*73 2025-10-16T21:23:12.363Z,1760649792.363 [NAL9602](INFO): GPS fix at 20251016T212305: (36.807190, -121.788894) 2025-10-16T21:23:12.374Z,1760649792.374 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T21:23:12.374Z,1760649792.374 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T21:23:20.312Z,1760649800.312 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20251016T204419/Courier0021.lzma 2025-10-16T21:23:21.314Z,1760649801.314 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0021.lzma.bak 2025-10-16T21:23:21.314Z,1760649801.314 [DataOverHttps](INFO): SBD MOMSN=26207541 2025-10-16T21:23:37.128Z,1760649817.128 [DataOverHttps](INFO): Sending 317 bytes from file Logs/20251016T204419/Express0022.lzma 2025-10-16T21:23:38.130Z,1760649818.130 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0022.lzma.bak 2025-10-16T21:23:38.130Z,1760649818.130 [DataOverHttps](INFO): SBD MOMSN=26207544 2025-10-16T21:23:39.449Z,1760649819.449 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T21:23:39.450Z,1760649819.450 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T21:23:39.450Z,1760649819.450 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T21:23:59.228Z,1760649839.228 [NAL9602](INFO): SBD MO Status=2, MOMSN=23865, MT Status=2, MTMSN=0 2025-10-16T21:23:59.229Z,1760649839.229 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T21:24:36.813Z,1760649876.813 [NAL9602](INFO): SBD MO Status=1, MOMSN=23865, MT Status=0, MTMSN=0 2025-10-16T21:24:36.813Z,1760649876.813 [NAL9602](INFO): No messages in MT queue 2025-10-16T21:25:07.509Z,1760649907.509 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T21:28:40.034Z,1760650120.034 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T21:28:40.035Z,1760650120.035 [Default:CheckIn:C.Wait] Stopped 2025-10-16T21:28:40.035Z,1760650120.035 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T21:28:40.035Z,1760650120.035 [Default:CheckIn:D] Running Loop=1 2025-10-16T21:28:40.442Z,1760650120.442 [Default:CheckIn:D] Stopped 2025-10-16T21:28:40.443Z,1760650120.443 [Default:CheckIn:E] Running Loop=1 2025-10-16T21:28:40.839Z,1760650120.839 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.469063 min 2025-10-16T21:28:40.839Z,1760650120.839 [Default:CheckIn:E] Stopped 2025-10-16T21:28:40.839Z,1760650120.839 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T21:28:40.856Z,1760650120.856 [Default:CheckIn] Stopped 2025-10-16T21:28:40.856Z,1760650120.856 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T21:28:40.856Z,1760650120.856 [Default:CheckIn](INFO): Running loop #11 2025-10-16T21:28:40.856Z,1760650120.856 [Default:CheckIn] Running Loop=11 2025-10-16T21:28:40.856Z,1760650120.856 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T21:28:40.856Z,1760650120.856 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T21:28:42.845Z,1760650122.845 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212836.00,A,3648.33009,N,12147.11801,W,4.549,184.58,161025,,,D*70 2025-10-16T21:28:42.847Z,1760650122.847 [NAL9602](INFO): GPS fix at 20251016T212836: (36.805501, -121.785300) 2025-10-16T21:28:42.858Z,1760650122.858 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T21:28:42.859Z,1760650122.859 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T21:28:50.792Z,1760650130.792 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20251016T204419/Courier0024.lzma 2025-10-16T21:28:51.794Z,1760650131.794 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0024.lzma.bak 2025-10-16T21:28:51.794Z,1760650131.794 [DataOverHttps](INFO): SBD MOMSN=26207549 2025-10-16T21:29:07.784Z,1760650147.784 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20251016T204419/Express0025.lzma 2025-10-16T21:29:08.701Z,1760650148.701 [NAL9602](INFO): SBD MO Status=1, MOMSN=23866, MT Status=0, MTMSN=0 2025-10-16T21:29:08.701Z,1760650148.701 [NAL9602](INFO): No messages in MT queue 2025-10-16T21:29:08.786Z,1760650148.786 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0025.lzma.bak 2025-10-16T21:29:08.786Z,1760650148.786 [DataOverHttps](INFO): SBD MOMSN=26207552 2025-10-16T21:29:10.326Z,1760650150.326 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T21:29:10.326Z,1760650150.326 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T21:29:10.326Z,1760650150.326 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T21:29:39.405Z,1760650179.405 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T21:34:10.911Z,1760650450.911 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T21:34:10.911Z,1760650450.911 [Default:CheckIn:C.Wait] Stopped 2025-10-16T21:34:10.911Z,1760650450.911 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T21:34:10.912Z,1760650450.912 [Default:CheckIn:D] Running Loop=1 2025-10-16T21:34:11.319Z,1760650451.319 [Default:CheckIn:D] Stopped 2025-10-16T21:34:11.319Z,1760650451.319 [Default:CheckIn:E] Running Loop=1 2025-10-16T21:34:11.706Z,1760650451.706 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.983679 min 2025-10-16T21:34:11.706Z,1760650451.706 [Default:CheckIn:E] Stopped 2025-10-16T21:34:11.707Z,1760650451.707 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T21:34:11.707Z,1760650451.707 [Default:CheckIn] Stopped 2025-10-16T21:34:11.707Z,1760650451.707 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T21:34:11.707Z,1760650451.707 [Default:CheckIn](INFO): Running loop #12 2025-10-16T21:34:11.707Z,1760650451.707 [Default:CheckIn] Running Loop=12 2025-10-16T21:34:11.707Z,1760650451.707 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T21:34:11.707Z,1760650451.707 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T21:34:13.720Z,1760650453.720 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213407.00,A,3648.14920,N,12147.22053,W,0.700,203.98,161025,,,D*7C 2025-10-16T21:34:13.723Z,1760650453.723 [NAL9602](INFO): GPS fix at 20251016T213407: (36.802487, -121.787009) 2025-10-16T21:34:13.733Z,1760650453.733 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T21:34:13.733Z,1760650453.733 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T21:34:21.516Z,1760650461.516 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20251016T204419/Courier0027.lzma 2025-10-16T21:34:22.518Z,1760650462.518 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0027.lzma.bak 2025-10-16T21:34:22.518Z,1760650462.518 [DataOverHttps](INFO): SBD MOMSN=26207570 2025-10-16T21:34:39.116Z,1760650479.116 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20251016T204419/Express0028.lzma 2025-10-16T21:34:40.118Z,1760650480.118 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0028.lzma.bak 2025-10-16T21:34:40.118Z,1760650480.118 [DataOverHttps](INFO): SBD MOMSN=26207573 2025-10-16T21:34:41.616Z,1760650481.616 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T21:34:41.616Z,1760650481.616 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T21:34:41.616Z,1760650481.616 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T21:35:55.126Z,1760650555.126 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-10-16T21:36:01.593Z,1760650561.593 [NAL9602](INFO): SBD MO Status=2, MOMSN=23867, MT Status=2, MTMSN=0 2025-10-16T21:36:01.593Z,1760650561.593 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T21:36:29.470Z,1760650589.470 [NAL9602](INFO): SBD MO Status=1, MOMSN=23867, MT Status=0, MTMSN=0 2025-10-16T21:36:29.470Z,1760650589.470 [NAL9602](INFO): No messages in MT queue 2025-10-16T21:37:00.172Z,1760650620.172 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T21:39:07.121Z,1760650747.121 [CBIT](FAULT): Main Battery Failure 2025-10-16T21:39:42.199Z,1760650782.199 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T21:39:42.200Z,1760650782.200 [Default:CheckIn:C.Wait] Stopped 2025-10-16T21:39:42.200Z,1760650782.200 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T21:39:42.201Z,1760650782.201 [Default:CheckIn:D] Running Loop=1 2025-10-16T21:39:42.586Z,1760650782.586 [Default:CheckIn:D] Stopped 2025-10-16T21:39:42.586Z,1760650782.586 [Default:CheckIn:E] Running Loop=1 2025-10-16T21:39:42.991Z,1760650782.991 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.504785 min 2025-10-16T21:39:42.991Z,1760650782.991 [Default:CheckIn:E] Stopped 2025-10-16T21:39:42.991Z,1760650782.991 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T21:39:42.991Z,1760650782.991 [Default:CheckIn] Stopped 2025-10-16T21:39:42.992Z,1760650782.992 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T21:39:42.992Z,1760650782.992 [Default:CheckIn](INFO): Running loop #13 2025-10-16T21:39:42.992Z,1760650782.992 [Default:CheckIn] Running Loop=13 2025-10-16T21:39:42.992Z,1760650782.992 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T21:39:42.992Z,1760650782.992 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T21:39:45.007Z,1760650785.007 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213938.00,A,3648.14212,N,12147.21743,W,0.350,249.21,161025,,,A*7A 2025-10-16T21:39:45.010Z,1760650785.010 [NAL9602](INFO): GPS fix at 20251016T213938: (36.802369, -121.786957) 2025-10-16T21:39:45.020Z,1760650785.020 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T21:39:45.020Z,1760650785.020 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T21:39:52.839Z,1760650792.839 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251016T204419/Courier0030.lzma 2025-10-16T21:39:53.842Z,1760650793.842 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0030.lzma.bak 2025-10-16T21:39:53.842Z,1760650793.842 [DataOverHttps](INFO): SBD MOMSN=26207577 2025-10-16T21:40:09.732Z,1760650809.732 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20251016T204419/Express0031.lzma 2025-10-16T21:40:10.734Z,1760650810.734 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0031.lzma.bak 2025-10-16T21:40:10.734Z,1760650810.734 [DataOverHttps](INFO): SBD MOMSN=26207580 2025-10-16T21:40:12.082Z,1760650812.082 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T21:40:12.083Z,1760650812.083 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T21:40:12.083Z,1760650812.083 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T21:40:25.812Z,1760650825.812 [NAL9602](INFO): SBD MO Status=1, MOMSN=23868, MT Status=0, MTMSN=0 2025-10-16T21:40:25.812Z,1760650825.812 [NAL9602](INFO): No messages in MT queue 2025-10-16T21:40:56.509Z,1760650856.509 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T21:41:55.498Z,1760650915.498 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-10-16T21:45:12.667Z,1760651112.667 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T21:45:12.667Z,1760651112.667 [Default:CheckIn:C.Wait] Stopped 2025-10-16T21:45:12.668Z,1760651112.668 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T21:45:12.668Z,1760651112.668 [Default:CheckIn:D] Running Loop=1 2025-10-16T21:45:13.085Z,1760651113.085 [Default:CheckIn:D] Stopped 2025-10-16T21:45:13.085Z,1760651113.085 [Default:CheckIn:E] Running Loop=1 2025-10-16T21:45:13.470Z,1760651113.470 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.013102 min 2025-10-16T21:45:13.470Z,1760651113.470 [Default:CheckIn:E] Stopped 2025-10-16T21:45:13.470Z,1760651113.470 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T21:45:13.470Z,1760651113.470 [Default:CheckIn] Stopped 2025-10-16T21:45:13.470Z,1760651113.470 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T21:45:13.470Z,1760651113.470 [Default:CheckIn](INFO): Running loop #14 2025-10-16T21:45:13.470Z,1760651113.470 [Default:CheckIn] Running Loop=14 2025-10-16T21:45:13.470Z,1760651113.470 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T21:45:13.471Z,1760651113.471 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T21:45:15.483Z,1760651115.483 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214508.00,A,3648.14800,N,12147.22179,W,0.078,7.85,161025,,,A*78 2025-10-16T21:45:15.486Z,1760651115.486 [NAL9602](INFO): GPS fix at 20251016T214508: (36.802467, -121.787030) 2025-10-16T21:45:15.496Z,1760651115.496 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T21:45:15.496Z,1760651115.496 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T21:45:23.324Z,1760651123.324 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20251016T204419/Courier0033.lzma 2025-10-16T21:45:24.326Z,1760651124.326 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0033.lzma.bak 2025-10-16T21:45:24.326Z,1760651124.326 [DataOverHttps](INFO): SBD MOMSN=26207599 2025-10-16T21:45:40.472Z,1760651140.472 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20251016T204419/Express0034.lzma 2025-10-16T21:45:41.474Z,1760651141.474 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0034.lzma.bak 2025-10-16T21:45:41.474Z,1760651141.474 [DataOverHttps](INFO): SBD MOMSN=26207602 2025-10-16T21:45:42.979Z,1760651142.979 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T21:45:42.979Z,1760651142.979 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T21:45:42.979Z,1760651142.979 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T21:46:00.729Z,1760651160.729 [NAL9602](INFO): SBD MO Status=1, MOMSN=23869, MT Status=0, MTMSN=0 2025-10-16T21:46:00.729Z,1760651160.729 [NAL9602](INFO): No messages in MT queue 2025-10-16T21:46:31.461Z,1760651191.461 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T21:50:43.719Z,1760651443.719 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T21:50:43.719Z,1760651443.719 [Default:CheckIn:C.Wait] Stopped 2025-10-16T21:50:43.719Z,1760651443.719 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T21:50:43.719Z,1760651443.719 [Default:CheckIn:D] Running Loop=1 2025-10-16T21:50:44.128Z,1760651444.128 [Default:CheckIn:D] Stopped 2025-10-16T21:50:44.128Z,1760651444.128 [Default:CheckIn:E] Running Loop=1 2025-10-16T21:50:44.547Z,1760651444.547 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.530485 min 2025-10-16T21:50:44.547Z,1760651444.547 [Default:CheckIn:E] Stopped 2025-10-16T21:50:44.547Z,1760651444.547 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T21:50:44.547Z,1760651444.547 [Default:CheckIn] Stopped 2025-10-16T21:50:44.547Z,1760651444.547 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T21:50:44.547Z,1760651444.547 [Default:CheckIn](INFO): Running loop #15 2025-10-16T21:50:44.547Z,1760651444.547 [Default:CheckIn] Running Loop=15 2025-10-16T21:50:44.547Z,1760651444.547 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T21:50:44.548Z,1760651444.548 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T21:50:46.538Z,1760651446.538 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215039.00,A,3648.13728,N,12147.23794,W,0.039,283.05,161025,,,A*7B 2025-10-16T21:50:46.540Z,1760651446.540 [NAL9602](INFO): GPS fix at 20251016T215039: (36.802288, -121.787299) 2025-10-16T21:50:46.551Z,1760651446.551 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T21:50:46.551Z,1760651446.551 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T21:50:54.908Z,1760651454.908 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251016T204419/Courier0036.lzma 2025-10-16T21:50:55.910Z,1760651455.910 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0036.lzma.bak 2025-10-16T21:50:55.910Z,1760651455.910 [DataOverHttps](INFO): SBD MOMSN=26207606 2025-10-16T21:51:12.860Z,1760651472.860 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20251016T204419/Express0037.lzma 2025-10-16T21:51:13.862Z,1760651473.862 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0037.lzma.bak 2025-10-16T21:51:13.862Z,1760651473.862 [DataOverHttps](INFO): SBD MOMSN=26207609 2025-10-16T21:51:15.236Z,1760651475.236 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T21:51:15.236Z,1760651475.236 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T21:51:15.236Z,1760651475.236 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T21:51:22.085Z,1760651482.085 [NAL9602](INFO): SBD MO Status=1, MOMSN=23870, MT Status=0, MTMSN=0 2025-10-16T21:51:22.085Z,1760651482.085 [NAL9602](INFO): No messages in MT queue 2025-10-16T21:51:52.787Z,1760651512.787 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T21:56:15.816Z,1760651775.816 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T21:56:15.816Z,1760651775.816 [Default:CheckIn:C.Wait] Stopped 2025-10-16T21:56:15.816Z,1760651775.816 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T21:56:15.816Z,1760651775.816 [Default:CheckIn:D] Running Loop=1 2025-10-16T21:56:16.238Z,1760651776.238 [Default:CheckIn:D] Stopped 2025-10-16T21:56:16.238Z,1760651776.238 [Default:CheckIn:E] Running Loop=1 2025-10-16T21:56:16.623Z,1760651776.623 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.065658 min 2025-10-16T21:56:16.623Z,1760651776.623 [Default:CheckIn:E] Stopped 2025-10-16T21:56:16.624Z,1760651776.624 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T21:56:16.624Z,1760651776.624 [Default:CheckIn] Stopped 2025-10-16T21:56:16.625Z,1760651776.625 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T21:56:16.626Z,1760651776.626 [Default:CheckIn](INFO): Running loop #16 2025-10-16T21:56:16.626Z,1760651776.626 [Default:CheckIn] Running Loop=16 2025-10-16T21:56:16.626Z,1760651776.626 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T21:56:16.627Z,1760651776.627 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T21:56:18.636Z,1760651778.636 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215611.00,A,3648.17386,N,12147.27792,W,0.039,266.48,161025,,,D*76 2025-10-16T21:56:18.638Z,1760651778.638 [NAL9602](INFO): GPS fix at 20251016T215611: (36.802898, -121.787965) 2025-10-16T21:56:18.649Z,1760651778.649 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T21:56:18.649Z,1760651778.649 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T21:56:26.784Z,1760651786.784 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251016T204419/Courier0039.lzma 2025-10-16T21:56:27.786Z,1760651787.786 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0039.lzma.bak 2025-10-16T21:56:27.786Z,1760651787.786 [DataOverHttps](INFO): SBD MOMSN=26207622 2025-10-16T21:56:44.368Z,1760651804.368 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20251016T204419/Express0040.lzma 2025-10-16T21:56:45.371Z,1760651805.371 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0040.lzma.bak 2025-10-16T21:56:45.371Z,1760651805.371 [DataOverHttps](INFO): SBD MOMSN=26207625 2025-10-16T21:56:46.924Z,1760651806.924 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T21:56:46.924Z,1760651806.924 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T21:56:46.924Z,1760651806.924 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T21:56:50.957Z,1760651810.957 [NAL9602](INFO): SBD MO Status=1, MOMSN=23871, MT Status=0, MTMSN=0 2025-10-16T21:56:50.957Z,1760651810.957 [NAL9602](INFO): No messages in MT queue 2025-10-16T21:57:21.653Z,1760651841.653 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T22:00:40.435Z,1760652040.435 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-10-16T22:00:40.442Z,1760652040.442 [BPC1](FAULT): Failed to receive data from 8 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 1, 2, 3, 4, 5, 6, 7, 8. 2025-10-16T22:01:47.502Z,1760652107.502 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T22:01:47.503Z,1760652107.503 [Default:CheckIn:C.Wait] Stopped 2025-10-16T22:01:47.503Z,1760652107.503 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T22:01:47.503Z,1760652107.503 [Default:CheckIn:D] Running Loop=1 2025-10-16T22:01:47.909Z,1760652107.909 [Default:CheckIn:D] Stopped 2025-10-16T22:01:47.909Z,1760652107.909 [Default:CheckIn:E] Running Loop=1 2025-10-16T22:01:48.302Z,1760652108.302 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.593506 min 2025-10-16T22:01:48.302Z,1760652108.302 [Default:CheckIn:E] Stopped 2025-10-16T22:01:48.302Z,1760652108.302 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T22:01:48.302Z,1760652108.302 [Default:CheckIn] Stopped 2025-10-16T22:01:48.302Z,1760652108.302 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T22:01:48.302Z,1760652108.302 [Default:CheckIn](INFO): Running loop #17 2025-10-16T22:01:48.302Z,1760652108.302 [Default:CheckIn] Running Loop=17 2025-10-16T22:01:48.302Z,1760652108.302 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T22:01:48.303Z,1760652108.303 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T22:01:50.314Z,1760652110.314 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220143.00,A,3648.17220,N,12147.27936,W,0.058,346.50,161025,,,D*70 2025-10-16T22:01:50.316Z,1760652110.316 [NAL9602](INFO): GPS fix at 20251016T220143: (36.802870, -121.787989) 2025-10-16T22:01:50.328Z,1760652110.328 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T22:01:50.328Z,1760652110.328 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T22:01:57.666Z,1760652117.666 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20251016T204419/Courier0042.lzma 2025-10-16T22:01:58.546Z,1760652118.546 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0042.lzma.bak 2025-10-16T22:01:58.546Z,1760652118.546 [DataOverHttps](INFO): SBD MOMSN=26207629 2025-10-16T22:02:14.528Z,1760652134.528 [DataOverHttps](INFO): Sending 274 bytes from file Logs/20251016T204419/Express0043.lzma 2025-10-16T22:02:15.530Z,1760652135.530 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0043.lzma.bak 2025-10-16T22:02:15.530Z,1760652135.530 [DataOverHttps](INFO): SBD MOMSN=26207632 2025-10-16T22:02:17.003Z,1760652137.003 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T22:02:17.003Z,1760652137.003 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T22:02:17.003Z,1760652137.003 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T22:03:08.697Z,1760652188.697 [NAL9602](INFO): SBD MO Status=1, MOMSN=23872, MT Status=0, MTMSN=0 2025-10-16T22:03:08.697Z,1760652188.697 [NAL9602](INFO): No messages in MT queue 2025-10-16T22:03:39.391Z,1760652219.391 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T22:07:17.573Z,1760652437.573 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T22:07:17.573Z,1760652437.573 [Default:CheckIn:C.Wait] Stopped 2025-10-16T22:07:17.573Z,1760652437.573 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T22:07:17.574Z,1760652437.574 [Default:CheckIn:D] Running Loop=1 2025-10-16T22:07:17.974Z,1760652437.974 [Default:CheckIn:D] Stopped 2025-10-16T22:07:17.975Z,1760652437.975 [Default:CheckIn:E] Running Loop=1 2025-10-16T22:07:18.384Z,1760652438.384 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.094596 min 2025-10-16T22:07:18.384Z,1760652438.384 [Default:CheckIn:E] Stopped 2025-10-16T22:07:18.384Z,1760652438.384 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T22:07:18.384Z,1760652438.384 [Default:CheckIn] Stopped 2025-10-16T22:07:18.384Z,1760652438.384 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T22:07:18.385Z,1760652438.385 [Default:CheckIn](INFO): Running loop #18 2025-10-16T22:07:18.385Z,1760652438.385 [Default:CheckIn] Running Loop=18 2025-10-16T22:07:18.385Z,1760652438.385 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T22:07:18.385Z,1760652438.385 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T22:07:20.394Z,1760652440.394 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220713.00,A,3648.18662,N,12147.27316,W,0.000,346.50,161025,,,A*7E 2025-10-16T22:07:20.397Z,1760652440.397 [NAL9602](INFO): GPS fix at 20251016T220713: (36.803110, -121.787886) 2025-10-16T22:07:20.425Z,1760652440.425 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T22:07:20.425Z,1760652440.425 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T22:07:28.176Z,1760652448.176 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20251016T204419/Courier0045.lzma 2025-10-16T22:07:29.178Z,1760652449.178 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0045.lzma.bak 2025-10-16T22:07:29.178Z,1760652449.178 [DataOverHttps](INFO): SBD MOMSN=26207650 2025-10-16T22:07:38.171Z,1760652458.171 [NAL9602](INFO): SBD MO Status=1, MOMSN=23873, MT Status=0, MTMSN=0 2025-10-16T22:07:38.171Z,1760652458.171 [NAL9602](INFO): No messages in MT queue 2025-10-16T22:07:45.016Z,1760652465.016 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20251016T204419/Express0046.lzma 2025-10-16T22:07:46.018Z,1760652466.018 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0046.lzma.bak 2025-10-16T22:07:46.018Z,1760652466.018 [DataOverHttps](INFO): SBD MOMSN=26207653 2025-10-16T22:07:47.478Z,1760652467.478 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T22:07:47.478Z,1760652467.478 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T22:07:47.479Z,1760652467.479 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T22:08:08.871Z,1760652488.871 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T22:12:48.058Z,1760652768.058 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T22:12:48.058Z,1760652768.058 [Default:CheckIn:C.Wait] Stopped 2025-10-16T22:12:48.058Z,1760652768.058 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T22:12:48.058Z,1760652768.058 [Default:CheckIn:D] Running Loop=1 2025-10-16T22:12:48.464Z,1760652768.464 [Default:CheckIn:D] Stopped 2025-10-16T22:12:48.464Z,1760652768.464 [Default:CheckIn:E] Running Loop=1 2025-10-16T22:12:48.863Z,1760652768.863 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.602751 min 2025-10-16T22:12:48.863Z,1760652768.863 [Default:CheckIn:E] Stopped 2025-10-16T22:12:48.863Z,1760652768.863 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T22:12:48.863Z,1760652768.863 [Default:CheckIn] Stopped 2025-10-16T22:12:48.863Z,1760652768.863 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T22:12:48.863Z,1760652768.863 [Default:CheckIn](INFO): Running loop #19 2025-10-16T22:12:48.863Z,1760652768.863 [Default:CheckIn] Running Loop=19 2025-10-16T22:12:48.884Z,1760652768.884 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T22:12:48.884Z,1760652768.884 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T22:12:50.874Z,1760652770.874 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221244.00,A,3648.18442,N,12147.27347,W,0.117,346.50,161025,,,A*7B 2025-10-16T22:12:50.876Z,1760652770.876 [NAL9602](INFO): GPS fix at 20251016T221244: (36.803074, -121.787891) 2025-10-16T22:12:50.887Z,1760652770.887 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T22:12:50.887Z,1760652770.887 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T22:13:01.924Z,1760652781.924 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20251016T204419/Courier0048.lzma 2025-10-16T22:13:02.926Z,1760652782.926 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0048.lzma.bak 2025-10-16T22:13:02.926Z,1760652782.926 [DataOverHttps](INFO): SBD MOMSN=26207657 2025-10-16T22:13:28.035Z,1760652808.035 [NAL9602](INFO): SBD MO Status=2, MOMSN=23874, MT Status=2, MTMSN=0 2025-10-16T22:13:28.035Z,1760652808.035 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T22:13:35.864Z,1760652815.864 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20251016T204419/Express0049.lzma 2025-10-16T22:13:36.969Z,1760652816.969 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0049.lzma.bak 2025-10-16T22:13:36.969Z,1760652816.969 [DataOverHttps](INFO): SBD MOMSN=26207664 2025-10-16T22:13:38.180Z,1760652818.180 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T22:13:38.180Z,1760652818.180 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T22:13:38.180Z,1760652818.180 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T22:14:02.378Z,1760652842.378 [NAL9602](INFO): SBD MO Status=2, MOMSN=23874, MT Status=2, MTMSN=0 2025-10-16T22:14:02.379Z,1760652842.379 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T22:14:55.705Z,1760652895.705 [NAL9602](INFO): SBD MO Status=2, MOMSN=23874, MT Status=2, MTMSN=0 2025-10-16T22:14:55.706Z,1760652895.706 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T22:17:00.558Z,1760653020.558 [NAL9602](INFO): SBD MO Status=2, MOMSN=23874, MT Status=2, MTMSN=0 2025-10-16T22:17:00.558Z,1760653020.558 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T22:17:53.473Z,1760653073.473 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-10-16T22:18:24.165Z,1760653104.165 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T22:18:38.725Z,1760653118.725 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T22:18:38.725Z,1760653118.725 [Default:CheckIn:C.Wait] Stopped 2025-10-16T22:18:38.725Z,1760653118.725 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T22:18:38.725Z,1760653118.725 [Default:CheckIn:D] Running Loop=1 2025-10-16T22:18:39.164Z,1760653119.164 [Default:CheckIn:D] Stopped 2025-10-16T22:18:39.164Z,1760653119.164 [Default:CheckIn:E] Running Loop=1 2025-10-16T22:18:39.538Z,1760653119.538 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.447762 min 2025-10-16T22:18:39.538Z,1760653119.538 [Default:CheckIn:E] Stopped 2025-10-16T22:18:39.538Z,1760653119.538 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T22:18:39.538Z,1760653119.538 [Default:CheckIn] Stopped 2025-10-16T22:18:39.539Z,1760653119.539 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T22:18:39.539Z,1760653119.539 [Default:CheckIn](INFO): Running loop #20 2025-10-16T22:18:39.539Z,1760653119.539 [Default:CheckIn] Running Loop=20 2025-10-16T22:18:39.539Z,1760653119.539 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T22:18:39.539Z,1760653119.539 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T22:18:41.553Z,1760653121.553 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221834.00,A,3648.16589,N,12147.27953,W,1.244,159.40,161025,,,A*78 2025-10-16T22:18:41.555Z,1760653121.555 [NAL9602](INFO): GPS fix at 20251016T221834: (36.802765, -121.787992) 2025-10-16T22:18:41.566Z,1760653121.566 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T22:18:41.566Z,1760653121.566 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T22:18:48.680Z,1760653128.680 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20251016T204419/Courier0051.lzma 2025-10-16T22:18:49.682Z,1760653129.682 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0051.lzma.bak 2025-10-16T22:18:49.682Z,1760653129.682 [DataOverHttps](INFO): SBD MOMSN=26207683 2025-10-16T22:19:06.532Z,1760653146.532 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20251016T204419/Express0052.lzma 2025-10-16T22:19:07.534Z,1760653147.534 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0052.lzma.bak 2025-10-16T22:19:07.535Z,1760653147.535 [DataOverHttps](INFO): SBD MOMSN=26207686 2025-10-16T22:19:09.060Z,1760653149.060 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T22:19:09.060Z,1760653149.060 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T22:19:09.060Z,1760653149.060 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T22:19:12.292Z,1760653152.292 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-10-16T22:19:12.376Z,1760653152.376 [NAL9602](ERROR): received: +CSQ:0 OK874, 2, 0, 0, 0 OK 2025-10-16T22:22:33.882Z,1760653353.882 [NAL9602](INFO): SBD MO Status=2, MOMSN=23874, MT Status=2, MTMSN=0 2025-10-16T22:22:33.882Z,1760653353.882 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T22:22:58.527Z,1760653378.527 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-10-16T22:23:44.176Z,1760653424.176 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-10-16T22:24:09.641Z,1760653449.641 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T22:24:09.641Z,1760653449.641 [Default:CheckIn:C.Wait] Stopped 2025-10-16T22:24:09.641Z,1760653449.641 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T22:24:09.643Z,1760653449.643 [Default:CheckIn:D] Running Loop=1 2025-10-16T22:24:10.043Z,1760653450.043 [Default:CheckIn:D] Stopped 2025-10-16T22:24:10.043Z,1760653450.043 [Default:CheckIn:E] Running Loop=1 2025-10-16T22:24:10.457Z,1760653450.457 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.962410 min 2025-10-16T22:24:10.457Z,1760653450.457 [Default:CheckIn:E] Stopped 2025-10-16T22:24:10.457Z,1760653450.457 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T22:24:10.457Z,1760653450.457 [Default:CheckIn] Stopped 2025-10-16T22:24:10.457Z,1760653450.457 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T22:24:10.457Z,1760653450.457 [Default:CheckIn](INFO): Running loop #21 2025-10-16T22:24:10.457Z,1760653450.457 [Default:CheckIn] Running Loop=21 2025-10-16T22:24:10.457Z,1760653450.457 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T22:24:10.457Z,1760653450.457 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T22:24:12.476Z,1760653452.476 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222405.00,A,3648.16883,N,12147.28632,W,0.292,190.24,161025,,,A*78 2025-10-16T22:24:12.479Z,1760653452.479 [NAL9602](INFO): GPS fix at 20251016T222405: (36.802814, -121.788105) 2025-10-16T22:24:12.490Z,1760653452.490 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T22:24:12.490Z,1760653452.490 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T22:24:20.072Z,1760653460.072 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251016T204419/Courier0054.lzma 2025-10-16T22:24:21.074Z,1760653461.074 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0054.lzma.bak 2025-10-16T22:24:21.074Z,1760653461.074 [DataOverHttps](INFO): SBD MOMSN=26207692 2025-10-16T22:24:37.204Z,1760653477.204 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20251016T204419/Express0055.lzma 2025-10-16T22:24:38.206Z,1760653478.206 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0055.lzma.bak 2025-10-16T22:24:38.206Z,1760653478.206 [DataOverHttps](INFO): SBD MOMSN=26207699 2025-10-16T22:24:39.942Z,1760653479.942 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T22:24:39.942Z,1760653479.942 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T22:24:39.942Z,1760653479.942 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T22:24:44.377Z,1760653484.377 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T22:29:40.517Z,1760653780.517 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T22:29:40.517Z,1760653780.517 [Default:CheckIn:C.Wait] Stopped 2025-10-16T22:29:40.517Z,1760653780.517 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T22:29:40.518Z,1760653780.518 [Default:CheckIn:D] Running Loop=1 2025-10-16T22:29:40.919Z,1760653780.919 [Default:CheckIn:D] Stopped 2025-10-16T22:29:40.919Z,1760653780.919 [Default:CheckIn:E] Running Loop=1 2025-10-16T22:29:41.328Z,1760653781.328 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 119.477002 min 2025-10-16T22:29:41.328Z,1760653781.328 [Default:CheckIn:E] Stopped 2025-10-16T22:29:41.328Z,1760653781.328 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T22:29:41.328Z,1760653781.328 [Default:CheckIn] Stopped 2025-10-16T22:29:41.329Z,1760653781.329 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T22:29:41.329Z,1760653781.329 [Default:CheckIn](INFO): Running loop #22 2025-10-16T22:29:41.329Z,1760653781.329 [Default:CheckIn] Running Loop=22 2025-10-16T22:29:41.329Z,1760653781.329 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T22:29:41.329Z,1760653781.329 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T22:29:43.334Z,1760653783.334 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222936.00,A,3648.16735,N,12147.28542,W,1.205,186.08,161025,,,A*75 2025-10-16T22:29:43.336Z,1760653783.336 [NAL9602](INFO): GPS fix at 20251016T222936: (36.802789, -121.788090) 2025-10-16T22:29:43.359Z,1760653783.359 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T22:29:43.359Z,1760653783.359 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T22:29:51.396Z,1760653791.396 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20251016T204419/Courier0057.lzma 2025-10-16T22:29:52.398Z,1760653792.398 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0057.lzma.bak 2025-10-16T22:29:52.398Z,1760653792.398 [DataOverHttps](INFO): SBD MOMSN=26207716 2025-10-16T22:30:08.404Z,1760653808.404 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20251016T204419/Express0058.lzma 2025-10-16T22:30:09.406Z,1760653809.406 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0058.lzma.bak 2025-10-16T22:30:09.406Z,1760653809.406 [DataOverHttps](INFO): SBD MOMSN=26207719 2025-10-16T22:30:10.834Z,1760653810.834 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T22:30:10.834Z,1760653810.834 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T22:30:10.834Z,1760653810.834 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T22:30:14.041Z,1760653814.041 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-10-16T22:30:14.124Z,1760653814.124 [NAL9602](ERROR): received: +CSQ:0 OK874, 2, 0, 0, 0 OK 2025-10-16T22:32:37.479Z,1760653957.479 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-10-16T22:34:31.159Z,1760654071.159 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-10-16T22:34:45.177Z,1760654085.177 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-10-16T22:34:48.554Z,1760654088.554 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-10-16T22:35:01.897Z,1760654101.897 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-10-16T22:35:11.594Z,1760654111.594 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T22:35:11.594Z,1760654111.594 [Default:CheckIn:C.Wait] Stopped 2025-10-16T22:35:11.594Z,1760654111.594 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T22:35:11.594Z,1760654111.594 [Default:CheckIn:D] Running Loop=1 2025-10-16T22:35:11.831Z,1760654111.831 [Default:CheckIn:D] Stopped 2025-10-16T22:35:11.831Z,1760654111.831 [Default:CheckIn:E] Running Loop=1 2025-10-16T22:35:12.247Z,1760654112.247 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.992204 min 2025-10-16T22:35:12.247Z,1760654112.247 [Default:CheckIn:E] Stopped 2025-10-16T22:35:12.247Z,1760654112.247 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T22:35:12.247Z,1760654112.247 [Default:CheckIn] Stopped 2025-10-16T22:35:12.268Z,1760654112.268 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T22:35:12.268Z,1760654112.268 [Default:CheckIn](INFO): Running loop #23 2025-10-16T22:35:12.268Z,1760654112.268 [Default:CheckIn] Running Loop=23 2025-10-16T22:35:12.268Z,1760654112.268 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T22:35:12.268Z,1760654112.268 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T22:35:14.235Z,1760654114.235 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223507.00,A,3648.16179,N,12147.28778,W,0.097,107.58,161025,,,A*7B 2025-10-16T22:35:14.246Z,1760654114.246 [NAL9602](INFO): GPS fix at 20251016T223507: (36.802697, -121.788130) 2025-10-16T22:35:14.276Z,1760654114.276 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T22:35:14.276Z,1760654114.276 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T22:35:26.000Z,1760654127.000 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251016T204419/Courier0060.lzma 2025-10-16T22:35:28.002Z,1760654128.002 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0060.lzma.bak 2025-10-16T22:35:28.002Z,1760654128.002 [DataOverHttps](INFO): SBD MOMSN=26207738 2025-10-16T22:35:28.389Z,1760654128.389 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-10-16T22:35:46.413Z,1760654146.413 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T22:35:54.301Z,1760654154.301 [DataOverHttps](INFO): Sending 247 bytes from file Logs/20251016T204419/Express0061.lzma 2025-10-16T22:35:55.341Z,1760654155.341 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0061.lzma.bak 2025-10-16T22:35:55.341Z,1760654155.341 [DataOverHttps](INFO): SBD MOMSN=26207741 2025-10-16T22:35:57.435Z,1760654157.435 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T22:35:57.435Z,1760654157.435 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T22:35:57.436Z,1760654157.436 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T22:36:59.255Z,1760654219.255 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-10-16T22:37:17.488Z,1760654237.488 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-10-16T22:37:49.397Z,1760654269.397 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-10-16T22:38:34.583Z,1760654314.583 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-10-16T22:39:07.265Z,1760654347.265 [CBIT](FAULT): Main Battery Failure 2025-10-16T22:39:53.702Z,1760654393.702 [BPC1](ERROR): Battery stick #1 (s/n: 018D) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-16T22:39:53.703Z,1760654393.703 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-16T22:39:53.712Z,1760654393.712 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-10-16T22:39:53.713Z,1760654393.713 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-10-16T22:39:53.714Z,1760654393.714 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6. 2025-10-16T22:39:53.753Z,1760654393.753 [BPC1](INFO): Received data from all battery sticks. 2025-10-16T22:40:57.924Z,1760654457.924 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T22:40:57.925Z,1760654457.925 [Default:CheckIn:C.Wait] Stopped 2025-10-16T22:40:57.925Z,1760654457.925 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T22:40:57.926Z,1760654457.926 [Default:CheckIn:D] Running Loop=1 2025-10-16T22:40:58.353Z,1760654458.353 [Default:CheckIn:D] Stopped 2025-10-16T22:40:58.353Z,1760654458.353 [Default:CheckIn:E] Running Loop=1 2025-10-16T22:40:58.766Z,1760654458.766 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 130.767570 min 2025-10-16T22:40:58.766Z,1760654458.766 [Default:CheckIn:E] Stopped 2025-10-16T22:40:58.766Z,1760654458.766 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T22:40:58.766Z,1760654458.766 [Default:CheckIn] Stopped 2025-10-16T22:40:58.766Z,1760654458.766 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T22:40:58.766Z,1760654458.766 [Default:CheckIn](INFO): Running loop #24 2025-10-16T22:40:58.766Z,1760654458.766 [Default:CheckIn] Running Loop=24 2025-10-16T22:40:58.766Z,1760654458.766 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T22:40:58.766Z,1760654458.766 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T22:41:00.772Z,1760654460.772 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224054.00,A,3648.16634,N,12147.28545,W,0.330,117.68,161025,,,A*71 2025-10-16T22:41:00.774Z,1760654460.774 [NAL9602](INFO): GPS fix at 20251016T224054: (36.802772, -121.788091) 2025-10-16T22:41:00.789Z,1760654460.789 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T22:41:00.789Z,1760654460.789 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T22:41:12.777Z,1760654472.777 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20251016T204419/Courier0063.lzma 2025-10-16T22:41:13.774Z,1760654473.774 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0063.lzma.bak 2025-10-16T22:41:13.774Z,1760654473.774 [DataOverHttps](INFO): SBD MOMSN=26207778 2025-10-16T22:41:31.243Z,1760654491.243 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-10-16T22:41:31.316Z,1760654491.316 [NAL9602](ERROR): received: +CSQ:0 OK874, 2, 0, 0, 0 OK 2025-10-16T22:41:39.360Z,1760654499.360 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20251016T204419/Express0064.lzma 2025-10-16T22:41:40.364Z,1760654500.364 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0064.lzma.bak 2025-10-16T22:41:40.364Z,1760654500.364 [DataOverHttps](INFO): SBD MOMSN=26207781 2025-10-16T22:41:42.213Z,1760654502.213 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T22:41:42.213Z,1760654502.213 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T22:41:42.213Z,1760654502.213 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T22:42:41.130Z,1760654561.130 [NAL9602](INFO): SBD MO Status=2, MOMSN=23874, MT Status=2, MTMSN=0 2025-10-16T22:42:41.130Z,1760654561.130 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T22:44:34.258Z,1760654674.258 [NAL9602](INFO): SBD MO Status=1, MOMSN=23874, MT Status=0, MTMSN=0 2025-10-16T22:44:34.258Z,1760654674.258 [NAL9602](INFO): No messages in MT queue 2025-10-16T22:45:04.975Z,1760654704.975 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T22:46:42.769Z,1760654802.769 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T22:46:42.770Z,1760654802.770 [Default:CheckIn:C.Wait] Stopped 2025-10-16T22:46:42.770Z,1760654802.770 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T22:46:42.771Z,1760654802.771 [Default:CheckIn:D] Running Loop=1 2025-10-16T22:46:43.156Z,1760654803.156 [Default:CheckIn:D] Stopped 2025-10-16T22:46:43.157Z,1760654803.157 [Default:CheckIn:E] Running Loop=1 2025-10-16T22:46:43.569Z,1760654803.569 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.514299 min 2025-10-16T22:46:43.569Z,1760654803.569 [Default:CheckIn:E] Stopped 2025-10-16T22:46:43.569Z,1760654803.569 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T22:46:43.569Z,1760654803.569 [Default:CheckIn] Stopped 2025-10-16T22:46:43.569Z,1760654803.569 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T22:46:43.569Z,1760654803.569 [Default:CheckIn](INFO): Running loop #25 2025-10-16T22:46:43.569Z,1760654803.569 [Default:CheckIn] Running Loop=25 2025-10-16T22:46:43.570Z,1760654803.570 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T22:46:43.570Z,1760654803.570 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T22:46:45.559Z,1760654805.559 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224638.00,A,3648.16640,N,12147.28246,W,0.292,31.53,161025,,,A*4E 2025-10-16T22:46:45.561Z,1760654805.561 [NAL9602](INFO): GPS fix at 20251016T224638: (36.802773, -121.788041) 2025-10-16T22:46:45.612Z,1760654805.612 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T22:46:45.612Z,1760654805.612 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T22:46:58.288Z,1760654818.288 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251016T204419/Courier0066.lzma 2025-10-16T22:46:59.290Z,1760654819.290 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0066.lzma.bak 2025-10-16T22:46:59.290Z,1760654819.290 [DataOverHttps](INFO): SBD MOMSN=26207800 2025-10-16T22:47:24.701Z,1760654844.701 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20251016T204419/Express0067.lzma 2025-10-16T22:47:25.678Z,1760654845.678 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0067.lzma.bak 2025-10-16T22:47:25.678Z,1760654845.678 [DataOverHttps](INFO): SBD MOMSN=26207803 2025-10-16T22:47:27.752Z,1760654847.752 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T22:47:27.752Z,1760654847.752 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T22:47:27.752Z,1760654847.752 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T22:51:50.336Z,1760655110.336 [NAL9602](INFO): SBD MO Status=2, MOMSN=23875, MT Status=2, MTMSN=0 2025-10-16T22:51:50.336Z,1760655110.336 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T22:51:50.702Z,1760655110.702 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-10-16T22:52:21.443Z,1760655141.443 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T22:52:28.301Z,1760655148.301 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T22:52:28.302Z,1760655148.302 [Default:CheckIn:C.Wait] Stopped 2025-10-16T22:52:28.302Z,1760655148.302 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T22:52:28.302Z,1760655148.302 [Default:CheckIn:D] Running Loop=1 2025-10-16T22:52:28.711Z,1760655148.711 [Default:CheckIn:D] Stopped 2025-10-16T22:52:28.711Z,1760655148.711 [Default:CheckIn:E] Running Loop=1 2025-10-16T22:52:29.127Z,1760655149.127 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.273551 min 2025-10-16T22:52:29.127Z,1760655149.127 [Default:CheckIn:E] Stopped 2025-10-16T22:52:29.127Z,1760655149.127 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T22:52:29.127Z,1760655149.127 [Default:CheckIn] Stopped 2025-10-16T22:52:29.127Z,1760655149.127 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T22:52:29.132Z,1760655149.132 [Default:CheckIn](INFO): Running loop #26 2025-10-16T22:52:29.132Z,1760655149.132 [Default:CheckIn] Running Loop=26 2025-10-16T22:52:29.132Z,1760655149.132 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T22:52:29.132Z,1760655149.132 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T22:52:31.123Z,1760655151.123 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225224.00,A,3648.16508,N,12147.28489,W,0.175,135.03,161025,,,A*76 2025-10-16T22:52:31.129Z,1760655151.129 [NAL9602](INFO): GPS fix at 20251016T225224: (36.802751, -121.788082) 2025-10-16T22:52:31.168Z,1760655151.168 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T22:52:31.168Z,1760655151.168 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T22:52:43.220Z,1760655163.220 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20251016T204419/Courier0069.lzma 2025-10-16T22:52:44.223Z,1760655164.223 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0069.lzma.bak 2025-10-16T22:52:44.223Z,1760655164.223 [DataOverHttps](INFO): SBD MOMSN=26207812 2025-10-16T22:53:09.505Z,1760655189.505 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20251016T204419/Express0070.lzma 2025-10-16T22:53:10.506Z,1760655190.506 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0070.lzma.bak 2025-10-16T22:53:10.506Z,1760655190.506 [DataOverHttps](INFO): SBD MOMSN=26207815 2025-10-16T22:53:12.695Z,1760655192.695 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T22:53:12.695Z,1760655192.695 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T22:53:12.695Z,1760655192.695 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T22:53:19.478Z,1760655199.478 [NAL9602](INFO): SBD MO Status=2, MOMSN=23875, MT Status=2, MTMSN=0 2025-10-16T22:53:19.478Z,1760655199.478 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T22:54:21.731Z,1760655261.731 [NAL9602](INFO): SBD MO Status=1, MOMSN=23875, MT Status=0, MTMSN=0 2025-10-16T22:54:21.731Z,1760655261.731 [NAL9602](INFO): No messages in MT queue 2025-10-16T22:54:52.397Z,1760655292.397 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T22:58:13.233Z,1760655493.233 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T22:58:13.233Z,1760655493.233 [Default:CheckIn:C.Wait] Stopped 2025-10-16T22:58:13.233Z,1760655493.233 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T22:58:13.234Z,1760655493.234 [Default:CheckIn:D] Running Loop=1 2025-10-16T22:58:13.638Z,1760655493.638 [Default:CheckIn:D] Stopped 2025-10-16T22:58:13.638Z,1760655493.638 [Default:CheckIn:E] Running Loop=1 2025-10-16T22:58:14.050Z,1760655494.050 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 148.022314 min 2025-10-16T22:58:14.050Z,1760655494.050 [Default:CheckIn:E] Stopped 2025-10-16T22:58:14.050Z,1760655494.050 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T22:58:14.050Z,1760655494.050 [Default:CheckIn] Stopped 2025-10-16T22:58:14.051Z,1760655494.051 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T22:58:14.051Z,1760655494.051 [Default:CheckIn](INFO): Running loop #27 2025-10-16T22:58:14.051Z,1760655494.051 [Default:CheckIn] Running Loop=27 2025-10-16T22:58:14.051Z,1760655494.051 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T22:58:14.051Z,1760655494.051 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T22:58:16.031Z,1760655496.031 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225809.00,A,3648.16946,N,12147.28566,W,0.194,95.29,161025,,,A*49 2025-10-16T22:58:16.046Z,1760655496.046 [NAL9602](INFO): GPS fix at 20251016T225809: (36.802824, -121.788094) 2025-10-16T22:58:16.069Z,1760655496.069 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T22:58:16.069Z,1760655496.069 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T22:58:28.316Z,1760655508.316 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251016T204419/Courier0072.lzma 2025-10-16T22:58:29.321Z,1760655509.321 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0072.lzma.bak 2025-10-16T22:58:29.322Z,1760655509.322 [DataOverHttps](INFO): SBD MOMSN=26207827 2025-10-16T22:58:54.160Z,1760655534.160 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20251016T204419/Express0073.lzma 2025-10-16T22:58:55.163Z,1760655535.163 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0073.lzma.bak 2025-10-16T22:58:55.163Z,1760655535.163 [DataOverHttps](INFO): SBD MOMSN=26207830 2025-10-16T22:58:57.003Z,1760655537.003 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T22:58:57.003Z,1760655537.003 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T22:58:57.003Z,1760655537.003 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T23:02:37.982Z,1760655757.982 [NAL9602](INFO): SBD MO Status=1, MOMSN=23876, MT Status=0, MTMSN=0 2025-10-16T23:02:37.982Z,1760655757.982 [NAL9602](INFO): No messages in MT queue 2025-10-16T23:03:08.681Z,1760655788.681 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T23:03:57.609Z,1760655837.609 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T23:03:57.609Z,1760655837.609 [Default:CheckIn:C.Wait] Stopped 2025-10-16T23:03:57.609Z,1760655837.609 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T23:03:57.611Z,1760655837.611 [Default:CheckIn:D] Running Loop=1 2025-10-16T23:03:57.997Z,1760655837.997 [Default:CheckIn:D] Stopped 2025-10-16T23:03:57.997Z,1760655837.997 [Default:CheckIn:E] Running Loop=1 2025-10-16T23:03:58.400Z,1760655838.400 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 153.761637 min 2025-10-16T23:03:58.401Z,1760655838.401 [Default:CheckIn:E] Stopped 2025-10-16T23:03:58.401Z,1760655838.401 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T23:03:58.401Z,1760655838.401 [Default:CheckIn] Stopped 2025-10-16T23:03:58.401Z,1760655838.401 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T23:03:58.401Z,1760655838.401 [Default:CheckIn](INFO): Running loop #28 2025-10-16T23:03:58.401Z,1760655838.401 [Default:CheckIn] Running Loop=28 2025-10-16T23:03:58.401Z,1760655838.401 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T23:03:58.401Z,1760655838.401 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T23:04:00.399Z,1760655840.399 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230353.00,A,3648.16388,N,12147.28725,W,0.292,140.08,161025,,,A*7B 2025-10-16T23:04:00.401Z,1760655840.401 [NAL9602](INFO): GPS fix at 20251016T230353: (36.802731, -121.788121) 2025-10-16T23:04:00.450Z,1760655840.450 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T23:04:00.450Z,1760655840.450 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T23:04:11.162Z,1760655851.162 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20251016T204419/Courier0075.lzma 2025-10-16T23:04:12.150Z,1760655852.150 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0075.lzma.bak 2025-10-16T23:04:12.150Z,1760655852.150 [DataOverHttps](INFO): SBD MOMSN=26207841 2025-10-16T23:04:37.341Z,1760655877.341 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20251016T204419/Express0076.lzma 2025-10-16T23:04:38.342Z,1760655878.342 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0076.lzma.bak 2025-10-16T23:04:38.342Z,1760655878.342 [DataOverHttps](INFO): SBD MOMSN=26207844 2025-10-16T23:04:40.203Z,1760655880.203 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T23:04:40.203Z,1760655880.203 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T23:04:40.203Z,1760655880.203 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T23:04:43.782Z,1760655883.782 [NAL9602](INFO): SBD MO Status=1, MOMSN=23877, MT Status=0, MTMSN=0 2025-10-16T23:04:43.782Z,1760655883.782 [NAL9602](INFO): No messages in MT queue 2025-10-16T23:05:14.493Z,1760655914.493 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T23:09:40.739Z,1760656180.739 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T23:09:40.739Z,1760656180.739 [Default:CheckIn:C.Wait] Stopped 2025-10-16T23:09:40.739Z,1760656180.739 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T23:09:40.740Z,1760656180.740 [Default:CheckIn:D] Running Loop=1 2025-10-16T23:09:41.143Z,1760656181.143 [Default:CheckIn:D] Stopped 2025-10-16T23:09:41.148Z,1760656181.148 [Default:CheckIn:E] Running Loop=1 2025-10-16T23:09:41.532Z,1760656181.532 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.480745 min 2025-10-16T23:09:41.532Z,1760656181.532 [Default:CheckIn:E] Stopped 2025-10-16T23:09:41.532Z,1760656181.532 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T23:09:41.532Z,1760656181.532 [Default:CheckIn] Stopped 2025-10-16T23:09:41.532Z,1760656181.532 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T23:09:41.532Z,1760656181.532 [Default:CheckIn](INFO): Running loop #29 2025-10-16T23:09:41.533Z,1760656181.533 [Default:CheckIn] Running Loop=29 2025-10-16T23:09:41.533Z,1760656181.533 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T23:09:41.533Z,1760656181.533 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T23:09:43.566Z,1760656183.566 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230936.00,A,3648.16605,N,12147.28501,W,0.194,140.08,161025,,,A*73 2025-10-16T23:09:43.584Z,1760656183.584 [NAL9602](INFO): GPS fix at 20251016T230936: (36.802768, -121.788083) 2025-10-16T23:09:43.611Z,1760656183.611 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T23:09:43.611Z,1760656183.611 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T23:09:55.081Z,1760656195.081 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20251016T204419/Courier0078.lzma 2025-10-16T23:09:56.082Z,1760656196.082 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0078.lzma.bak 2025-10-16T23:09:56.082Z,1760656196.082 [DataOverHttps](INFO): SBD MOMSN=26207862 2025-10-16T23:10:21.168Z,1760656221.168 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20251016T204419/Express0079.lzma 2025-10-16T23:10:22.170Z,1760656222.170 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0079.lzma.bak 2025-10-16T23:10:22.170Z,1760656222.170 [DataOverHttps](INFO): SBD MOMSN=26207865 2025-10-16T23:10:24.271Z,1760656224.271 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T23:10:24.271Z,1760656224.271 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T23:10:24.271Z,1760656224.271 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T23:10:31.942Z,1760656231.942 [NAL9602](INFO): SBD MO Status=2, MOMSN=23878, MT Status=2, MTMSN=0 2025-10-16T23:10:31.942Z,1760656231.942 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-10-16T23:11:00.182Z,1760656260.182 [NAL9602](INFO): SBD MO Status=1, MOMSN=23878, MT Status=0, MTMSN=0 2025-10-16T23:11:00.182Z,1760656260.182 [NAL9602](INFO): No messages in MT queue 2025-10-16T23:11:30.901Z,1760656290.901 [NAL9602](INFO): Not Powering down - fast GPS 2025-10-16T23:15:24.839Z,1760656524.839 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-10-16T23:15:24.839Z,1760656524.839 [Default:CheckIn:C.Wait] Stopped 2025-10-16T23:15:24.839Z,1760656524.839 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T23:15:24.846Z,1760656524.846 [Default:CheckIn:D] Running Loop=1 2025-10-16T23:15:25.247Z,1760656525.247 [Default:CheckIn:D] Stopped 2025-10-16T23:15:25.247Z,1760656525.247 [Default:CheckIn:E] Running Loop=1 2025-10-16T23:15:25.638Z,1760656525.638 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 165.215804 min 2025-10-16T23:15:25.638Z,1760656525.638 [Default:CheckIn:E] Stopped 2025-10-16T23:15:25.639Z,1760656525.639 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-10-16T23:15:25.639Z,1760656525.639 [Default:CheckIn] Stopped 2025-10-16T23:15:25.639Z,1760656525.639 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T23:15:25.639Z,1760656525.639 [Default:CheckIn](INFO): Running loop #30 2025-10-16T23:15:25.639Z,1760656525.639 [Default:CheckIn] Running Loop=30 2025-10-16T23:15:25.639Z,1760656525.639 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-10-16T23:15:25.640Z,1760656525.640 [Default:CheckIn:Read_GPS] Running Loop=1 2025-10-16T23:15:27.640Z,1760656527.640 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231521.00,A,3648.16562,N,12147.28800,W,0.330,140.08,161025,,,A*7A 2025-10-16T23:15:27.642Z,1760656527.642 [NAL9602](INFO): GPS fix at 20251016T231521: (36.802760, -121.788133) 2025-10-16T23:15:27.669Z,1760656527.669 [Default:CheckIn:Read_GPS] Stopped 2025-10-16T23:15:27.669Z,1760656527.669 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-10-16T23:15:39.608Z,1760656539.608 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20251016T204419/Courier0081.lzma 2025-10-16T23:15:40.610Z,1760656540.610 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Courier0081.lzma.bak 2025-10-16T23:15:40.610Z,1760656540.610 [DataOverHttps](INFO): SBD MOMSN=26207877 2025-10-16T23:16:06.388Z,1760656566.388 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20251016T204419/Express0082.lzma 2025-10-16T23:16:07.390Z,1760656567.390 [DataOverHttps](INFO): Moved sent file to Logs/20251016T204419/Express0082.lzma.bak 2025-10-16T23:16:07.390Z,1760656567.390 [DataOverHttps](INFO): SBD MOMSN=26207880 2025-10-16T23:16:09.147Z,1760656569.147 [Default:CheckIn:Read_Iridium] Stopped 2025-10-16T23:16:09.147Z,1760656569.147 [Default:CheckIn:C.Wait] Running Loop=1 2025-10-16T23:16:09.147Z,1760656569.147 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-10-16T23:16:20.589Z,1760656580.589 [CommandExec](IMPORTANT): got command quit 2025-10-16T23:16:21.596Z,1760656581.596 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-16T23:16:21.596Z,1760656581.596 [CommandExec](INFO): Uninitializing the command executive. 2025-10-16T23:16:21.597Z,1760656581.597 [CommandExec](INFO): Uninitializing the command scheduler. 2025-10-16T23:16:21.597Z,1760656581.597 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:21.752Z,1760656581.752 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-10-16T23:16:21.753Z,1760656581.753 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-10-16T23:16:21.753Z,1760656581.753 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:21.773Z,1760656581.773 [NavChartDb](INFO): Join timeout helper Thread ID is 5104 2025-10-16T23:16:22.068Z,1760656582.068 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-16T23:16:22.068Z,1760656582.068 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:22.076Z,1760656582.076 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-10-16T23:16:22.076Z,1760656582.076 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:22.076Z,1760656582.076 [Radio_Surface](INFO): Join timeout helper Thread ID is 5105 2025-10-16T23:16:22.328Z,1760656582.328 [Radio_Surface](INFO): Powering down 2025-10-16T23:16:22.330Z,1760656582.330 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-16T23:16:22.331Z,1760656582.331 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:22.332Z,1760656582.332 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-10-16T23:16:22.332Z,1760656582.332 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:22.333Z,1760656582.333 [Onboard](INFO): Join timeout helper Thread ID is 5106 2025-10-16T23:16:23.172Z,1760656583.172 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-10-16T23:16:23.892Z,1760656583.892 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-16T23:16:23.892Z,1760656583.892 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:23.908Z,1760656583.908 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-10-16T23:16:23.908Z,1760656583.908 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:23.908Z,1760656583.908 [DataOverHttps](INFO): Join timeout helper Thread ID is 5107 2025-10-16T23:16:24.176Z,1760656584.176 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-16T23:16:24.176Z,1760656584.176 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:24.192Z,1760656584.192 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-10-16T23:16:24.192Z,1760656584.192 [DAT ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:24.192Z,1760656584.192 [DAT](INFO): Join timeout helper Thread ID is 5108 2025-10-16T23:16:24.312Z,1760656584.312 [DAT](INFO): Powering down 2025-10-16T23:16:24.381Z,1760656584.381 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-16T23:16:24.381Z,1760656584.381 [DAT](INFO): Powering down 2025-10-16T23:16:24.382Z,1760656584.382 [DAT ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:24.406Z,1760656584.406 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-10-16T23:16:24.406Z,1760656584.406 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:24.407Z,1760656584.407 [BackseatComponent](INFO): Join timeout helper Thread ID is 5109 2025-10-16T23:16:24.608Z,1760656584.608 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-16T23:16:24.609Z,1760656584.609 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:24.620Z,1760656584.620 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-10-16T23:16:24.621Z,1760656584.621 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:24.621Z,1760656584.621 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5110 2025-10-16T23:16:24.896Z,1760656584.896 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-16T23:16:24.896Z,1760656584.896 [WetLabsBB2FL](INFO): Powering down 2025-10-16T23:16:24.897Z,1760656584.897 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:24.901Z,1760656584.901 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-10-16T23:16:24.901Z,1760656584.901 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:24.901Z,1760656584.901 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5111 2025-10-16T23:16:25.048Z,1760656585.048 [CTD_Seabird](INFO): Powering down 2025-10-16T23:16:25.060Z,1760656585.060 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-16T23:16:25.060Z,1760656585.060 [CTD_Seabird](INFO): Powering down 2025-10-16T23:16:25.072Z,1760656585.072 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.085Z,1760656585.085 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-10-16T23:16:25.085Z,1760656585.085 [logger ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.085Z,1760656585.085 [logger](INFO): Join timeout helper Thread ID is 5112 2025-10-16T23:16:25.121Z,1760656585.121 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-16T23:16:25.121Z,1760656585.121 [logger ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.133Z,1760656585.133 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-10-16T23:16:25.133Z,1760656585.133 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.133Z,1760656585.133 [CommandLine](INFO): Join timeout helper Thread ID is 5113 2025-10-16T23:16:25.204Z,1760656585.204 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-16T23:16:25.204Z,1760656585.204 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.213Z,1760656585.213 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-10-16T23:16:25.213Z,1760656585.213 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.213Z,1760656585.213 [CommandExec](INFO): Join timeout helper Thread ID is 5114 2025-10-16T23:16:25.214Z,1760656585.214 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-10-16T23:16:25.215Z,1760656585.215 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.215Z,1760656585.215 [controlThread](INFO): Join timeout helper Thread ID is 5115 2025-10-16T23:16:25.244Z,1760656585.244 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-10-16T23:16:25.244Z,1760656585.244 [controlThread](DEBUG): Uninitializing ControlThread 2025-10-16T23:16:25.245Z,1760656585.245 [AHRS_M2](INFO): Powering down 2025-10-16T23:16:25.324Z,1760656585.324 [NAL9602](INFO): Powering down 2025-10-16T23:16:25.325Z,1760656585.325 [Sonardyne_Nano](INFO): Powering down 2025-10-16T23:16:25.532Z,1760656585.532 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-10-16T23:16:25.533Z,1760656585.533 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-10-16T23:16:25.533Z,1760656585.533 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-10-16T23:16:25.534Z,1760656585.534 [MissionManager](INFO): Uninitializing Mission Default 2025-10-16T23:16:25.534Z,1760656585.534 [Default] Stopped 2025-10-16T23:16:25.534Z,1760656585.534 [Default](DEBUG): Aggregate::uninitialize Default 2025-10-16T23:16:25.534Z,1760656585.534 [Default:B.GoToSurface] Stopped 2025-10-16T23:16:25.534Z,1760656585.534 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-10-16T23:16:25.534Z,1760656585.534 [Default:CheckIn] Stopped 2025-10-16T23:16:25.534Z,1760656585.534 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-10-16T23:16:25.534Z,1760656585.534 [Default:CheckIn:C.Wait] Stopped 2025-10-16T23:16:25.534Z,1760656585.534 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-10-16T23:16:25.538Z,1760656585.538 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-10-16T23:16:25.538Z,1760656585.538 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-10-16T23:16:25.538Z,1760656585.538 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-10-16T23:16:25.539Z,1760656585.539 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-10-16T23:16:25.539Z,1760656585.539 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-10-16T23:16:25.539Z,1760656585.539 [BuoyancyServo](INFO): Powering down 2025-10-16T23:16:25.552Z,1760656585.552 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-10-16T23:16:25.552Z,1760656585.552 [ElevatorServo](INFO): Powering down 2025-10-16T23:16:25.553Z,1760656585.553 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-10-16T23:16:25.553Z,1760656585.553 [MassServo](INFO): Powering down 2025-10-16T23:16:25.554Z,1760656585.554 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-10-16T23:16:25.554Z,1760656585.554 [RudderServo](INFO): Powering down 2025-10-16T23:16:25.555Z,1760656585.555 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-10-16T23:16:25.555Z,1760656585.555 [ThrusterHE](INFO): Powering down 2025-10-16T23:16:25.556Z,1760656585.556 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-10-16T23:16:25.556Z,1760656585.556 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-10-16T23:16:25.556Z,1760656585.556 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-10-16T23:16:25.556Z,1760656585.556 [CBIT](DEBUG): Powering off loads. 2025-10-16T23:16:25.568Z,1760656585.568 [CBIT](DEBUG): Disabling WDT. 2025-10-16T23:16:25.580Z,1760656585.580 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-10-16T23:16:25.580Z,1760656585.580 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-10-16T23:16:25.581Z,1760656585.581 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.620Z,1760656585.620 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-10-16T23:16:25.620Z,1760656585.620 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-10-16T23:16:25.637Z,1760656585.637 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.644Z,1760656585.644 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.681Z,1760656585.681 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.685Z,1760656585.685 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.691Z,1760656585.691 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.698Z,1760656585.698 [DAT ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.739Z,1760656585.739 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.795Z,1760656585.795 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-10-16T23:16:25.865Z,1760656585.865 [logger ThreadHandler](INFO): Thread cancelled.