2025-04-09T18:24:55.044Z,1744223095.044 [DataOverHttps](INFO): Received command: restart logs 2025-04-09T18:24:55.057Z,1744223095.057 [CommandExec](IMPORTANT): got command restart logs 2025-04-09T18:25:37.043Z,1744223137.043 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T18:25:37.043Z,1744223137.043 [Default:CheckIn:C.Wait] Stopped 2025-04-09T18:25:37.043Z,1744223137.043 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T18:25:37.043Z,1744223137.043 [Default:CheckIn:D] Running Loop=1 2025-04-09T18:25:37.453Z,1744223137.453 [Default:CheckIn:D] Stopped 2025-04-09T18:25:37.453Z,1744223137.453 [Default:CheckIn:E] Running Loop=1 2025-04-09T18:25:37.868Z,1744223137.868 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.955525 min 2025-04-09T18:25:37.868Z,1744223137.868 [Default:CheckIn:E] Stopped 2025-04-09T18:25:37.869Z,1744223137.869 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T18:25:37.869Z,1744223137.869 [Default:CheckIn] Stopped 2025-04-09T18:25:37.869Z,1744223137.869 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T18:25:37.869Z,1744223137.869 [Default:CheckIn](INFO): Running loop #2 2025-04-09T18:25:37.869Z,1744223137.869 [Default:CheckIn] Running Loop=2 2025-04-09T18:25:37.869Z,1744223137.869 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T18:25:37.869Z,1744223137.869 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T18:25:39.871Z,1744223139.871 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182542.00,A,3647.94509,N,12150.76050,W,8.436,14.58,090425,,,D*4E 2025-04-09T18:25:39.873Z,1744223139.873 [NAL9602](INFO): GPS fix at 20250409T182542: (36.799085, -121.846008) 2025-04-09T18:25:39.900Z,1744223139.900 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T18:25:39.900Z,1744223139.900 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T18:25:47.519Z,1744223147.519 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250408T175421/Courier0082.lzma 2025-04-09T18:25:48.523Z,1744223148.523 [DataOverHttps](INFO): Moved sent file to Logs/20250408T175421/Courier0082.lzma.bak 2025-04-09T18:25:48.524Z,1744223148.524 [DataOverHttps](INFO): SBD MOMSN=24598099 2025-04-09T18:25:50.771Z,1744223150.771 [NAL9602](INFO): SBD MO Status=0, MOMSN=22848, MT Status=0, MTMSN=0 2025-04-09T18:25:50.771Z,1744223150.771 [NAL9602](INFO): No messages in MT queue 2025-04-09T18:26:04.520Z,1744223164.520 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20250409T182455/Courier0000.lzma 2025-04-09T18:26:05.522Z,1744223165.522 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0000.lzma.bak 2025-04-09T18:26:05.522Z,1744223165.522 [DataOverHttps](INFO): SBD MOMSN=24598105 2025-04-09T18:26:21.473Z,1744223181.473 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T18:26:21.496Z,1744223181.496 [DataOverHttps](INFO): Sending 109 bytes from file Logs/20250408T175421/Express0083.lzma 2025-04-09T18:26:22.498Z,1744223182.498 [DataOverHttps](INFO): Moved sent file to Logs/20250408T175421/Express0083.lzma.bak 2025-04-09T18:26:22.498Z,1744223182.498 [DataOverHttps](INFO): SBD MOMSN=24598113 2025-04-09T18:26:41.854Z,1744223201.854 [DataOverHttps](INFO): Sending 501 bytes from file Logs/20250409T182455/Express0001.lzma 2025-04-09T18:26:42.857Z,1744223202.857 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0001.lzma.bak 2025-04-09T18:26:42.857Z,1744223202.857 [DataOverHttps](INFO): SBD MOMSN=24598117 2025-04-09T18:26:58.746Z,1744223218.746 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20250409T182455/Express0004.lzma 2025-04-09T18:26:59.746Z,1744223219.746 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0004.lzma.bak 2025-04-09T18:26:59.746Z,1744223219.746 [DataOverHttps](INFO): SBD MOMSN=24598121 2025-04-09T18:27:01.132Z,1744223221.132 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T18:27:01.133Z,1744223221.133 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T18:27:01.133Z,1744223221.133 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T18:27:14.044Z,1744223234.044 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:27:29.800Z,1744223249.800 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:27:44.747Z,1744223264.747 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:27:59.701Z,1744223279.701 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:27:59.707Z,1744223279.707 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-04-09T18:28:15.048Z,1744223295.048 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:28:30.399Z,1744223310.399 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:28:45.412Z,1744223325.412 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:29:01.635Z,1744223341.635 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:29:16.532Z,1744223356.532 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:30:21.621Z,1744223421.621 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-04-09T18:32:01.838Z,1744223521.838 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T18:32:01.838Z,1744223521.838 [Default:CheckIn:C.Wait] Stopped 2025-04-09T18:32:01.838Z,1744223521.838 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T18:32:01.838Z,1744223521.838 [Default:CheckIn:D] Running Loop=1 2025-04-09T18:32:02.253Z,1744223522.253 [Default:CheckIn:D] Stopped 2025-04-09T18:32:02.253Z,1744223522.253 [Default:CheckIn:E] Running Loop=1 2025-04-09T18:32:02.651Z,1744223522.651 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.368853 min 2025-04-09T18:32:02.651Z,1744223522.651 [Default:CheckIn:E] Stopped 2025-04-09T18:32:02.668Z,1744223522.668 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T18:32:02.669Z,1744223522.669 [Default:CheckIn] Stopped 2025-04-09T18:32:02.669Z,1744223522.669 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T18:32:02.670Z,1744223522.670 [Default:CheckIn](INFO): Running loop #3 2025-04-09T18:32:02.670Z,1744223522.670 [Default:CheckIn] Running Loop=3 2025-04-09T18:32:02.671Z,1744223522.671 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T18:32:02.671Z,1744223522.671 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T18:32:03.442Z,1744223523.442 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:32:04.727Z,1744223524.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183207.00,A,3648.18379,N,12149.30809,W,3.168,141.57,090425,,,D*76 2025-04-09T18:32:04.730Z,1744223524.730 [NAL9602](INFO): GPS fix at 20250409T183207: (36.803063, -121.821802) 2025-04-09T18:32:04.757Z,1744223524.757 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T18:32:04.757Z,1744223524.757 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T18:32:12.075Z,1744223532.075 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T182455/Courier0006.lzma 2025-04-09T18:32:13.078Z,1744223533.078 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0006.lzma.bak 2025-04-09T18:32:13.078Z,1744223533.078 [DataOverHttps](INFO): SBD MOMSN=24598169 2025-04-09T18:32:17.182Z,1744223537.182 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:32:29.220Z,1744223549.220 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20250409T182455/Express0007.lzma 2025-04-09T18:32:30.118Z,1744223550.118 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:32:30.222Z,1744223550.222 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0007.lzma.bak 2025-04-09T18:32:30.222Z,1744223550.222 [DataOverHttps](INFO): SBD MOMSN=24598172 2025-04-09T18:32:31.349Z,1744223551.349 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T18:32:31.349Z,1744223551.349 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T18:32:31.349Z,1744223551.349 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T18:32:41.152Z,1744223561.152 [NAL9602](INFO): SBD MO Status=0, MOMSN=22849, MT Status=0, MTMSN=0 2025-04-09T18:32:41.152Z,1744223561.152 [NAL9602](INFO): No messages in MT queue 2025-04-09T18:32:43.893Z,1744223563.893 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:32:56.820Z,1744223576.820 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:33:10.959Z,1744223590.959 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:33:11.790Z,1744223591.790 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T18:33:24.294Z,1744223604.294 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:33:38.027Z,1744223618.027 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:33:51.360Z,1744223631.360 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:34:04.702Z,1744223644.702 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T18:36:32.629Z,1744223792.629 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-09T18:36:32.630Z,1744223792.630 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-09T18:36:32.631Z,1744223792.631 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-09T18:36:32.631Z,1744223792.631 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 5. 2025-04-09T18:36:32.650Z,1744223792.650 [BPC1](INFO): Received data from all battery sticks. 2025-04-09T18:37:32.078Z,1744223852.078 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T18:37:32.078Z,1744223852.078 [Default:CheckIn:C.Wait] Stopped 2025-04-09T18:37:32.078Z,1744223852.078 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T18:37:32.078Z,1744223852.078 [Default:CheckIn:D] Running Loop=1 2025-04-09T18:37:32.478Z,1744223852.478 [Default:CheckIn:D] Stopped 2025-04-09T18:37:32.478Z,1744223852.478 [Default:CheckIn:E] Running Loop=1 2025-04-09T18:37:32.875Z,1744223852.875 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.872611 min 2025-04-09T18:37:32.875Z,1744223852.875 [Default:CheckIn:E] Stopped 2025-04-09T18:37:32.875Z,1744223852.875 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T18:37:32.875Z,1744223852.875 [Default:CheckIn] Stopped 2025-04-09T18:37:32.875Z,1744223852.875 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T18:37:32.876Z,1744223852.876 [Default:CheckIn](INFO): Running loop #4 2025-04-09T18:37:32.876Z,1744223852.876 [Default:CheckIn] Running Loop=4 2025-04-09T18:37:32.876Z,1744223852.876 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T18:37:32.876Z,1744223852.876 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T18:37:34.888Z,1744223854.888 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183737.00,A,3648.15519,N,12149.30013,W,1.905,171.20,090425,,,D*7C 2025-04-09T18:37:34.890Z,1744223854.890 [NAL9602](INFO): GPS fix at 20250409T183737: (36.802586, -121.821669) 2025-04-09T18:37:34.902Z,1744223854.902 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T18:37:34.902Z,1744223854.902 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T18:37:42.536Z,1744223862.536 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20250409T182455/Courier0009.lzma 2025-04-09T18:37:43.539Z,1744223863.539 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0009.lzma.bak 2025-04-09T18:37:43.539Z,1744223863.539 [DataOverHttps](INFO): SBD MOMSN=24598224 2025-04-09T18:37:55.091Z,1744223875.091 [NAL9602](INFO): SBD MO Status=0, MOMSN=22850, MT Status=0, MTMSN=0 2025-04-09T18:37:55.091Z,1744223875.091 [NAL9602](INFO): No messages in MT queue 2025-04-09T18:37:59.500Z,1744223879.500 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250409T182455/Express0010.lzma 2025-04-09T18:38:00.502Z,1744223880.502 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0010.lzma.bak 2025-04-09T18:38:00.502Z,1744223880.502 [DataOverHttps](INFO): SBD MOMSN=24598227 2025-04-09T18:38:01.974Z,1744223881.974 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T18:38:01.974Z,1744223881.974 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T18:38:01.974Z,1744223881.974 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T18:38:25.793Z,1744223905.793 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T18:43:02.670Z,1744224182.670 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T18:43:02.670Z,1744224182.670 [Default:CheckIn:C.Wait] Stopped 2025-04-09T18:43:02.670Z,1744224182.670 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T18:43:02.670Z,1744224182.670 [Default:CheckIn:D] Running Loop=1 2025-04-09T18:43:03.066Z,1744224183.066 [Default:CheckIn:D] Stopped 2025-04-09T18:43:03.066Z,1744224183.066 [Default:CheckIn:E] Running Loop=1 2025-04-09T18:43:03.472Z,1744224183.472 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.382404 min 2025-04-09T18:43:03.472Z,1744224183.472 [Default:CheckIn:E] Stopped 2025-04-09T18:43:03.472Z,1744224183.472 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T18:43:03.472Z,1744224183.472 [Default:CheckIn] Stopped 2025-04-09T18:43:03.472Z,1744224183.472 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T18:43:03.473Z,1744224183.473 [Default:CheckIn](INFO): Running loop #5 2025-04-09T18:43:03.473Z,1744224183.473 [Default:CheckIn] Running Loop=5 2025-04-09T18:43:03.473Z,1744224183.473 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T18:43:03.473Z,1744224183.473 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T18:43:05.481Z,1744224185.481 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184307.00,A,3648.08098,N,12148.96084,W,3.693,116.54,090425,,,D*7F 2025-04-09T18:43:05.483Z,1744224185.483 [NAL9602](INFO): GPS fix at 20250409T184307: (36.801350, -121.816014) 2025-04-09T18:43:05.495Z,1744224185.495 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T18:43:05.495Z,1744224185.495 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T18:43:14.239Z,1744224194.239 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T182455/Courier0012.lzma 2025-04-09T18:43:15.242Z,1744224195.242 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0012.lzma.bak 2025-04-09T18:43:15.242Z,1744224195.242 [DataOverHttps](INFO): SBD MOMSN=24598280 2025-04-09T18:43:21.642Z,1744224201.642 [NAL9602](INFO): SBD MO Status=0, MOMSN=22851, MT Status=0, MTMSN=0 2025-04-09T18:43:21.643Z,1744224201.643 [NAL9602](INFO): No messages in MT queue 2025-04-09T18:43:31.224Z,1744224211.224 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20250409T182455/Express0013.lzma 2025-04-09T18:43:32.226Z,1744224212.226 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0013.lzma.bak 2025-04-09T18:43:32.226Z,1744224212.226 [DataOverHttps](INFO): SBD MOMSN=24598304 2025-04-09T18:43:33.371Z,1744224213.371 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T18:43:33.371Z,1744224213.371 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T18:43:33.372Z,1744224213.372 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T18:43:52.341Z,1744224232.341 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T18:48:34.022Z,1744224514.022 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T18:48:34.022Z,1744224514.022 [Default:CheckIn:C.Wait] Stopped 2025-04-09T18:48:34.022Z,1744224514.022 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T18:48:34.024Z,1744224514.024 [Default:CheckIn:D] Running Loop=1 2025-04-09T18:48:34.429Z,1744224514.429 [Default:CheckIn:D] Stopped 2025-04-09T18:48:34.429Z,1744224514.429 [Default:CheckIn:E] Running Loop=1 2025-04-09T18:48:34.845Z,1744224514.845 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.905119 min 2025-04-09T18:48:34.845Z,1744224514.845 [Default:CheckIn:E] Stopped 2025-04-09T18:48:34.846Z,1744224514.846 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T18:48:34.846Z,1744224514.846 [Default:CheckIn] Stopped 2025-04-09T18:48:34.846Z,1744224514.846 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T18:48:34.846Z,1744224514.846 [Default:CheckIn](INFO): Running loop #6 2025-04-09T18:48:34.846Z,1744224514.846 [Default:CheckIn] Running Loop=6 2025-04-09T18:48:34.846Z,1744224514.846 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T18:48:34.846Z,1744224514.846 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T18:48:36.843Z,1744224516.843 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184839.00,A,3648.04930,N,12148.45529,W,4.704,90.68,090425,,,D*4A 2025-04-09T18:48:36.846Z,1744224516.846 [NAL9602](INFO): GPS fix at 20250409T184839: (36.800822, -121.807588) 2025-04-09T18:48:36.865Z,1744224516.865 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T18:48:36.865Z,1744224516.865 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T18:48:44.871Z,1744224524.871 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T182455/Courier0015.lzma 2025-04-09T18:48:45.874Z,1744224525.874 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0015.lzma.bak 2025-04-09T18:48:45.874Z,1744224525.874 [DataOverHttps](INFO): SBD MOMSN=24598321 2025-04-09T18:48:50.974Z,1744224530.974 [NAL9602](INFO): SBD MO Status=0, MOMSN=22852, MT Status=0, MTMSN=0 2025-04-09T18:48:50.975Z,1744224530.975 [NAL9602](INFO): No messages in MT queue 2025-04-09T18:49:05.920Z,1744224545.920 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20250409T182455/Express0016.lzma 2025-04-09T18:49:06.922Z,1744224546.922 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0016.lzma.bak 2025-04-09T18:49:06.922Z,1744224546.922 [DataOverHttps](INFO): SBD MOMSN=24598324 2025-04-09T18:49:08.353Z,1744224548.353 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T18:49:08.353Z,1744224548.353 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T18:49:08.353Z,1744224548.353 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T18:49:21.678Z,1744224561.678 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T18:54:08.959Z,1744224848.959 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T18:54:08.959Z,1744224848.959 [Default:CheckIn:C.Wait] Stopped 2025-04-09T18:54:08.959Z,1744224848.959 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T18:54:08.959Z,1744224848.959 [Default:CheckIn:D] Running Loop=1 2025-04-09T18:54:09.365Z,1744224849.365 [Default:CheckIn:D] Stopped 2025-04-09T18:54:09.365Z,1744224849.365 [Default:CheckIn:E] Running Loop=1 2025-04-09T18:54:09.781Z,1744224849.781 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.487398 min 2025-04-09T18:54:09.781Z,1744224849.781 [Default:CheckIn:E] Stopped 2025-04-09T18:54:09.781Z,1744224849.781 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T18:54:09.781Z,1744224849.781 [Default:CheckIn] Stopped 2025-04-09T18:54:09.781Z,1744224849.781 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T18:54:09.781Z,1744224849.781 [Default:CheckIn](INFO): Running loop #7 2025-04-09T18:54:09.781Z,1744224849.781 [Default:CheckIn] Running Loop=7 2025-04-09T18:54:09.781Z,1744224849.781 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T18:54:09.781Z,1744224849.781 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T18:54:11.779Z,1744224851.779 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185414.00,A,3648.11162,N,12147.93354,W,4.821,66.76,090425,,,D*45 2025-04-09T18:54:11.782Z,1744224851.782 [NAL9602](INFO): GPS fix at 20250409T185414: (36.801860, -121.798892) 2025-04-09T18:54:11.797Z,1744224851.797 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T18:54:11.797Z,1744224851.797 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T18:54:20.419Z,1744224860.419 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T182455/Courier0018.lzma 2025-04-09T18:54:21.422Z,1744224861.422 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0018.lzma.bak 2025-04-09T18:54:21.422Z,1744224861.422 [DataOverHttps](INFO): SBD MOMSN=24598402 2025-04-09T18:54:21.892Z,1744224861.892 [NAL9602](INFO): SBD MO Status=0, MOMSN=22853, MT Status=0, MTMSN=0 2025-04-09T18:54:21.892Z,1744224861.892 [NAL9602](INFO): No messages in MT queue 2025-04-09T18:54:42.060Z,1744224882.060 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250409T182455/Express0019.lzma 2025-04-09T18:54:43.062Z,1744224883.062 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0019.lzma.bak 2025-04-09T18:54:43.062Z,1744224883.062 [DataOverHttps](INFO): SBD MOMSN=24598405 2025-04-09T18:54:44.205Z,1744224884.205 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T18:54:44.205Z,1744224884.205 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T18:54:44.205Z,1744224884.205 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T18:54:52.638Z,1744224892.638 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T18:59:44.741Z,1744225184.741 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T18:59:44.741Z,1744225184.741 [Default:CheckIn:C.Wait] Stopped 2025-04-09T18:59:44.741Z,1744225184.741 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T18:59:44.742Z,1744225184.742 [Default:CheckIn:D] Running Loop=1 2025-04-09T18:59:45.155Z,1744225185.155 [Default:CheckIn:D] Stopped 2025-04-09T18:59:45.155Z,1744225185.155 [Default:CheckIn:E] Running Loop=1 2025-04-09T18:59:45.552Z,1744225185.552 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.083887 min 2025-04-09T18:59:45.552Z,1744225185.552 [Default:CheckIn:E] Stopped 2025-04-09T18:59:45.552Z,1744225185.552 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T18:59:45.552Z,1744225185.552 [Default:CheckIn] Stopped 2025-04-09T18:59:45.553Z,1744225185.553 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T18:59:45.553Z,1744225185.553 [Default:CheckIn](INFO): Running loop #8 2025-04-09T18:59:45.553Z,1744225185.553 [Default:CheckIn] Running Loop=8 2025-04-09T18:59:45.553Z,1744225185.553 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T18:59:45.553Z,1744225185.553 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T18:59:47.560Z,1744225187.560 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185949.00,A,3648.30937,N,12147.51860,W,4.413,56.31,090425,,,D*44 2025-04-09T18:59:47.562Z,1744225187.562 [NAL9602](INFO): GPS fix at 20250409T185949: (36.805156, -121.791977) 2025-04-09T18:59:47.573Z,1744225187.573 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T18:59:47.573Z,1744225187.573 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T18:59:55.519Z,1744225195.519 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T182455/Courier0021.lzma 2025-04-09T18:59:57.526Z,1744225197.526 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0021.lzma.bak 2025-04-09T18:59:57.526Z,1744225197.526 [DataOverHttps](INFO): SBD MOMSN=24598424 2025-04-09T18:59:59.276Z,1744225199.276 [NAL9602](INFO): SBD MO Status=0, MOMSN=22854, MT Status=0, MTMSN=0 2025-04-09T18:59:59.276Z,1744225199.276 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:00:13.460Z,1744225213.460 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250409T182455/Express0022.lzma 2025-04-09T19:00:14.462Z,1744225214.462 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0022.lzma.bak 2025-04-09T19:00:14.462Z,1744225214.462 [DataOverHttps](INFO): SBD MOMSN=24598427 2025-04-09T19:00:15.867Z,1744225215.867 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:00:15.867Z,1744225215.867 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:00:15.867Z,1744225215.867 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:00:29.978Z,1744225229.978 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:05:16.426Z,1744225516.426 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:05:16.427Z,1744225516.427 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:05:16.427Z,1744225516.427 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:05:16.427Z,1744225516.427 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:05:16.829Z,1744225516.829 [Default:CheckIn:D] Stopped 2025-04-09T19:05:16.829Z,1744225516.829 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:05:17.233Z,1744225517.233 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.611800 min 2025-04-09T19:05:17.233Z,1744225517.233 [Default:CheckIn:E] Stopped 2025-04-09T19:05:17.233Z,1744225517.233 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:05:17.233Z,1744225517.233 [Default:CheckIn] Stopped 2025-04-09T19:05:17.234Z,1744225517.234 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:05:17.234Z,1744225517.234 [Default:CheckIn](INFO): Running loop #9 2025-04-09T19:05:17.234Z,1744225517.234 [Default:CheckIn] Running Loop=9 2025-04-09T19:05:17.234Z,1744225517.234 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:05:17.234Z,1744225517.234 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:05:19.243Z,1744225519.243 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190521.00,A,3648.45847,N,12147.28088,W,2.780,48.54,090425,,,D*45 2025-04-09T19:05:19.245Z,1744225519.245 [NAL9602](INFO): GPS fix at 20250409T190521: (36.807641, -121.788015) 2025-04-09T19:05:19.272Z,1744225519.272 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:05:19.272Z,1744225519.272 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:05:26.687Z,1744225526.687 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T182455/Courier0024.lzma 2025-04-09T19:05:27.690Z,1744225527.690 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0024.lzma.bak 2025-04-09T19:05:27.690Z,1744225527.690 [DataOverHttps](INFO): SBD MOMSN=24598535 2025-04-09T19:05:43.576Z,1744225543.576 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250409T182455/Express0025.lzma 2025-04-09T19:05:44.578Z,1744225544.578 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0025.lzma.bak 2025-04-09T19:05:44.578Z,1744225544.578 [DataOverHttps](INFO): SBD MOMSN=24598538 2025-04-09T19:05:45.927Z,1744225545.927 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:05:45.927Z,1744225545.927 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:05:45.927Z,1744225545.927 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:05:46.719Z,1744225546.719 [NAL9602](INFO): SBD MO Status=0, MOMSN=22855, MT Status=0, MTMSN=0 2025-04-09T19:05:46.719Z,1744225546.719 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:06:17.418Z,1744225577.418 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:10:46.518Z,1744225846.518 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:10:46.518Z,1744225846.518 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:10:46.518Z,1744225846.518 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:10:46.519Z,1744225846.519 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:10:46.915Z,1744225846.915 [Default:CheckIn:D] Stopped 2025-04-09T19:10:46.915Z,1744225846.915 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:10:47.356Z,1744225847.356 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.113224 min 2025-04-09T19:10:47.356Z,1744225847.356 [Default:CheckIn:E] Stopped 2025-04-09T19:10:47.357Z,1744225847.357 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:10:47.357Z,1744225847.357 [Default:CheckIn] Stopped 2025-04-09T19:10:47.357Z,1744225847.357 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:10:47.357Z,1744225847.357 [Default:CheckIn](INFO): Running loop #10 2025-04-09T19:10:47.357Z,1744225847.357 [Default:CheckIn] Running Loop=10 2025-04-09T19:10:47.357Z,1744225847.357 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:10:47.357Z,1744225847.357 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:10:49.334Z,1744225849.334 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191051.00,A,3648.32360,N,12147.12631,W,3.985,188.49,090425,,,D*7E 2025-04-09T19:10:49.336Z,1744225849.336 [NAL9602](INFO): GPS fix at 20250409T191051: (36.805393, -121.785438) 2025-04-09T19:10:49.347Z,1744225849.347 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:10:49.347Z,1744225849.347 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:10:57.019Z,1744225857.019 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T182455/Courier0027.lzma 2025-04-09T19:10:58.022Z,1744225858.022 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0027.lzma.bak 2025-04-09T19:10:58.022Z,1744225858.022 [DataOverHttps](INFO): SBD MOMSN=24598556 2025-04-09T19:11:12.368Z,1744225872.368 [NAL9602](INFO): SBD MO Status=0, MOMSN=22856, MT Status=0, MTMSN=0 2025-04-09T19:11:12.368Z,1744225872.368 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:11:14.012Z,1744225874.012 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20250409T182455/Express0028.lzma 2025-04-09T19:11:15.014Z,1744225875.014 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0028.lzma.bak 2025-04-09T19:11:15.014Z,1744225875.014 [DataOverHttps](INFO): SBD MOMSN=24598559 2025-04-09T19:11:16.415Z,1744225876.415 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:11:16.415Z,1744225876.415 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:11:16.415Z,1744225876.415 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:11:43.061Z,1744225903.061 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:16:17.008Z,1744226177.008 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:16:17.008Z,1744226177.008 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:16:17.008Z,1744226177.008 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:16:17.009Z,1744226177.009 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:16:17.428Z,1744226177.428 [Default:CheckIn:D] Stopped 2025-04-09T19:16:17.428Z,1744226177.428 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:16:17.807Z,1744226177.807 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.621777 min 2025-04-09T19:16:17.807Z,1744226177.807 [Default:CheckIn:E] Stopped 2025-04-09T19:16:17.807Z,1744226177.807 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:16:17.807Z,1744226177.807 [Default:CheckIn] Stopped 2025-04-09T19:16:17.807Z,1744226177.807 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:16:17.808Z,1744226177.808 [Default:CheckIn](INFO): Running loop #11 2025-04-09T19:16:17.808Z,1744226177.808 [Default:CheckIn] Running Loop=11 2025-04-09T19:16:17.808Z,1744226177.808 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:16:17.808Z,1744226177.808 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:16:19.821Z,1744226179.821 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191622.00,A,3648.15938,N,12147.21451,W,0.311,241.09,090425,,,D*7C 2025-04-09T19:16:19.823Z,1744226179.823 [NAL9602](INFO): GPS fix at 20250409T191622: (36.802656, -121.786908) 2025-04-09T19:16:19.834Z,1744226179.834 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:16:19.834Z,1744226179.834 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:16:27.235Z,1744226187.235 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T182455/Courier0030.lzma 2025-04-09T19:16:28.238Z,1744226188.238 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0030.lzma.bak 2025-04-09T19:16:28.238Z,1744226188.238 [DataOverHttps](INFO): SBD MOMSN=24598631 2025-04-09T19:16:44.914Z,1744226204.914 [NAL9602](INFO): SBD MO Status=2, MOMSN=22857, MT Status=2, MTMSN=0 2025-04-09T19:16:44.915Z,1744226204.915 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T19:16:48.956Z,1744226208.956 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20250409T182455/Express0031.lzma 2025-04-09T19:16:49.958Z,1744226209.958 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0031.lzma.bak 2025-04-09T19:16:49.958Z,1744226209.958 [DataOverHttps](INFO): SBD MOMSN=24598634 2025-04-09T19:16:51.391Z,1744226211.391 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:16:51.391Z,1744226211.391 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:16:51.408Z,1744226211.408 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:17:16.826Z,1744226236.826 [NAL9602](INFO): SBD MO Status=0, MOMSN=22857, MT Status=0, MTMSN=0 2025-04-09T19:17:16.827Z,1744226236.827 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:17:47.529Z,1744226267.529 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:21:51.962Z,1744226511.962 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:21:51.962Z,1744226511.962 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:21:51.962Z,1744226511.962 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:21:51.962Z,1744226511.962 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:21:52.375Z,1744226512.375 [Default:CheckIn:D] Stopped 2025-04-09T19:21:52.375Z,1744226512.375 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:21:52.768Z,1744226512.768 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.204224 min 2025-04-09T19:21:52.768Z,1744226512.768 [Default:CheckIn:E] Stopped 2025-04-09T19:21:52.768Z,1744226512.768 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:21:52.768Z,1744226512.768 [Default:CheckIn] Stopped 2025-04-09T19:21:52.769Z,1744226512.769 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:21:52.769Z,1744226512.769 [Default:CheckIn](INFO): Running loop #12 2025-04-09T19:21:52.769Z,1744226512.769 [Default:CheckIn] Running Loop=12 2025-04-09T19:21:52.769Z,1744226512.769 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:21:52.769Z,1744226512.769 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:21:54.779Z,1744226514.779 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192157.00,A,3648.14791,N,12147.22256,W,0.039,206.71,090425,,,D*71 2025-04-09T19:21:54.781Z,1744226514.781 [NAL9602](INFO): GPS fix at 20250409T192157: (36.802465, -121.787043) 2025-04-09T19:21:54.797Z,1744226514.797 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:21:54.797Z,1744226514.797 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:22:01.987Z,1744226521.987 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T182455/Courier0033.lzma 2025-04-09T19:22:02.994Z,1744226522.994 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0033.lzma.bak 2025-04-09T19:22:02.994Z,1744226522.994 [DataOverHttps](INFO): SBD MOMSN=24598657 2025-04-09T19:22:18.972Z,1744226538.972 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250409T182455/Express0034.lzma 2025-04-09T19:22:19.974Z,1744226539.974 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0034.lzma.bak 2025-04-09T19:22:19.974Z,1744226539.974 [DataOverHttps](INFO): SBD MOMSN=24598660 2025-04-09T19:22:21.455Z,1744226541.455 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:22:21.455Z,1744226541.455 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:22:21.455Z,1744226541.455 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:22:22.655Z,1744226542.655 [NAL9602](INFO): SBD MO Status=0, MOMSN=22858, MT Status=0, MTMSN=0 2025-04-09T19:22:22.655Z,1744226542.655 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:22:53.377Z,1744226573.377 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:27:22.029Z,1744226842.029 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:27:22.029Z,1744226842.029 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:27:22.029Z,1744226842.029 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:27:22.029Z,1744226842.029 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:27:22.433Z,1744226842.433 [Default:CheckIn:D] Stopped 2025-04-09T19:27:22.433Z,1744226842.433 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:27:22.845Z,1744226842.845 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.705192 min 2025-04-09T19:27:22.845Z,1744226842.845 [Default:CheckIn:E] Stopped 2025-04-09T19:27:22.845Z,1744226842.845 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:27:22.845Z,1744226842.845 [Default:CheckIn] Stopped 2025-04-09T19:27:22.845Z,1744226842.845 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:27:22.846Z,1744226842.846 [Default:CheckIn](INFO): Running loop #13 2025-04-09T19:27:22.846Z,1744226842.846 [Default:CheckIn] Running Loop=13 2025-04-09T19:27:22.846Z,1744226842.846 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:27:22.846Z,1744226842.846 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:27:24.847Z,1744226844.847 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192727.00,A,3648.14879,N,12147.22198,W,0.369,206.71,090425,,,D*7E 2025-04-09T19:27:24.850Z,1744226844.850 [NAL9602](INFO): GPS fix at 20250409T192727: (36.802480, -121.787033) 2025-04-09T19:27:24.861Z,1744226844.861 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:27:24.861Z,1744226844.861 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:27:32.247Z,1744226852.247 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250409T182455/Courier0036.lzma 2025-04-09T19:27:33.250Z,1744226853.250 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0036.lzma.bak 2025-04-09T19:27:33.251Z,1744226853.251 [DataOverHttps](INFO): SBD MOMSN=24598741 2025-04-09T19:27:41.008Z,1744226861.008 [NAL9602](INFO): SBD MO Status=0, MOMSN=22859, MT Status=0, MTMSN=0 2025-04-09T19:27:41.008Z,1744226861.008 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:27:49.080Z,1744226869.080 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250409T182455/Express0037.lzma 2025-04-09T19:27:50.082Z,1744226870.082 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0037.lzma.bak 2025-04-09T19:27:50.082Z,1744226870.082 [DataOverHttps](INFO): SBD MOMSN=24598744 2025-04-09T19:27:51.523Z,1744226871.523 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:27:51.523Z,1744226871.523 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:27:51.523Z,1744226871.523 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:28:11.713Z,1744226891.713 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:32:52.117Z,1744227172.117 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:32:52.117Z,1744227172.117 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:32:52.117Z,1744227172.117 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:32:52.119Z,1744227172.119 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:32:52.509Z,1744227172.509 [Default:CheckIn:D] Stopped 2025-04-09T19:32:52.509Z,1744227172.509 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:32:52.914Z,1744227172.914 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.206462 min 2025-04-09T19:32:52.914Z,1744227172.914 [Default:CheckIn:E] Stopped 2025-04-09T19:32:52.914Z,1744227172.914 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:32:52.914Z,1744227172.914 [Default:CheckIn] Stopped 2025-04-09T19:32:52.914Z,1744227172.914 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:32:52.914Z,1744227172.914 [Default:CheckIn](INFO): Running loop #14 2025-04-09T19:32:52.914Z,1744227172.914 [Default:CheckIn] Running Loop=14 2025-04-09T19:32:52.914Z,1744227172.914 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:32:52.914Z,1744227172.914 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:32:54.929Z,1744227174.929 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193257.00,A,3648.15173,N,12147.21946,W,0.058,206.71,090425,,,D*76 2025-04-09T19:32:54.931Z,1744227174.931 [NAL9602](INFO): GPS fix at 20250409T193257: (36.802529, -121.786991) 2025-04-09T19:32:54.942Z,1744227174.942 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:32:54.942Z,1744227174.942 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:33:02.223Z,1744227182.223 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T182455/Courier0039.lzma 2025-04-09T19:33:03.226Z,1744227183.226 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0039.lzma.bak 2025-04-09T19:33:03.226Z,1744227183.226 [DataOverHttps](INFO): SBD MOMSN=24598766 2025-04-09T19:33:12.698Z,1744227192.698 [NAL9602](INFO): SBD MO Status=0, MOMSN=22860, MT Status=0, MTMSN=0 2025-04-09T19:33:12.698Z,1744227192.698 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:33:19.126Z,1744227199.126 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250409T182455/Express0040.lzma 2025-04-09T19:33:20.126Z,1744227200.126 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0040.lzma.bak 2025-04-09T19:33:20.126Z,1744227200.126 [DataOverHttps](INFO): SBD MOMSN=24598769 2025-04-09T19:33:21.595Z,1744227201.595 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:33:21.595Z,1744227201.595 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:33:21.595Z,1744227201.595 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:33:43.397Z,1744227223.397 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:37:46.638Z,1744227466.638 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-04-09T19:37:53.499Z,1744227473.499 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:38:06.832Z,1744227486.832 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:38:20.568Z,1744227500.568 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:38:22.204Z,1744227502.204 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:38:22.204Z,1744227502.204 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:38:22.204Z,1744227502.204 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:38:22.204Z,1744227502.204 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:38:22.600Z,1744227502.600 [Default:CheckIn:D] Stopped 2025-04-09T19:38:22.600Z,1744227502.600 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:38:23.008Z,1744227503.008 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.707975 min 2025-04-09T19:38:23.008Z,1744227503.008 [Default:CheckIn:E] Stopped 2025-04-09T19:38:23.008Z,1744227503.008 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:38:23.008Z,1744227503.008 [Default:CheckIn] Stopped 2025-04-09T19:38:23.008Z,1744227503.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:38:23.009Z,1744227503.009 [Default:CheckIn](INFO): Running loop #15 2025-04-09T19:38:23.009Z,1744227503.009 [Default:CheckIn] Running Loop=15 2025-04-09T19:38:23.009Z,1744227503.009 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:38:23.009Z,1744227503.009 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:38:25.017Z,1744227505.017 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193827.00,A,3648.15185,N,12147.22641,W,0.039,279.32,090425,,,D*71 2025-04-09T19:38:25.019Z,1744227505.019 [NAL9602](INFO): GPS fix at 20250409T193827: (36.802531, -121.787107) 2025-04-09T19:38:25.031Z,1744227505.031 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:38:25.031Z,1744227505.031 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:38:32.852Z,1744227512.852 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T182455/Courier0042.lzma 2025-04-09T19:38:33.854Z,1744227513.854 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0042.lzma.bak 2025-04-09T19:38:33.854Z,1744227513.854 [DataOverHttps](INFO): SBD MOMSN=24598887 2025-04-09T19:38:34.707Z,1744227514.707 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:38:42.439Z,1744227522.439 [NAL9602](INFO): SBD MO Status=0, MOMSN=22861, MT Status=0, MTMSN=0 2025-04-09T19:38:42.439Z,1744227522.439 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:38:48.487Z,1744227528.487 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:38:52.892Z,1744227532.892 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250409T182455/Express0043.lzma 2025-04-09T19:38:53.894Z,1744227533.894 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0043.lzma.bak 2025-04-09T19:38:53.894Z,1744227533.894 [DataOverHttps](INFO): SBD MOMSN=24598894 2025-04-09T19:38:55.371Z,1744227535.371 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:38:55.371Z,1744227535.371 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:38:55.371Z,1744227535.371 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:39:02.224Z,1744227542.224 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:39:13.137Z,1744227553.137 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:39:15.961Z,1744227555.961 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:39:29.697Z,1744227569.697 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:39:43.029Z,1744227583.029 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:39:57.181Z,1744227597.181 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:40:10.507Z,1744227610.507 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:42:32.728Z,1744227752.728 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:42:39.603Z,1744227759.603 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:42:54.551Z,1744227774.551 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:43:09.501Z,1744227789.501 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:43:24.447Z,1744227804.447 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:43:39.799Z,1744227819.799 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:43:54.747Z,1744227834.747 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:43:55.982Z,1744227835.982 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:43:55.982Z,1744227835.982 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:43:55.982Z,1744227835.982 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:43:55.983Z,1744227835.983 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:43:56.376Z,1744227836.376 [Default:CheckIn:D] Stopped 2025-04-09T19:43:56.377Z,1744227836.377 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:43:56.781Z,1744227836.781 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.270915 min 2025-04-09T19:43:56.781Z,1744227836.781 [Default:CheckIn:E] Stopped 2025-04-09T19:43:56.782Z,1744227836.782 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:43:56.782Z,1744227836.782 [Default:CheckIn] Stopped 2025-04-09T19:43:56.782Z,1744227836.782 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:43:56.782Z,1744227836.782 [Default:CheckIn](INFO): Running loop #16 2025-04-09T19:43:56.782Z,1744227836.782 [Default:CheckIn] Running Loop=16 2025-04-09T19:43:56.782Z,1744227836.782 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:43:56.782Z,1744227836.782 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:43:58.791Z,1744227838.791 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194401.00,A,3648.15297,N,12147.22711,W,0.019,279.32,090425,,,D*78 2025-04-09T19:43:58.801Z,1744227838.801 [NAL9602](INFO): GPS fix at 20250409T194401: (36.802549, -121.787119) 2025-04-09T19:43:58.813Z,1744227838.813 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:43:58.813Z,1744227838.813 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:44:06.764Z,1744227846.764 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T182455/Courier0045.lzma 2025-04-09T19:44:07.766Z,1744227847.766 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0045.lzma.bak 2025-04-09T19:44:07.766Z,1744227847.766 [DataOverHttps](INFO): SBD MOMSN=24598905 2025-04-09T19:44:09.695Z,1744227849.695 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:44:10.128Z,1744227850.128 [NAL9602](INFO): SBD MO Status=0, MOMSN=22862, MT Status=0, MTMSN=0 2025-04-09T19:44:10.128Z,1744227850.128 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:44:23.756Z,1744227863.756 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250409T182455/Express0046.lzma 2025-04-09T19:44:24.758Z,1744227864.758 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0046.lzma.bak 2025-04-09T19:44:24.758Z,1744227864.758 [DataOverHttps](INFO): SBD MOMSN=24598910 2025-04-09T19:44:25.063Z,1744227865.063 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:44:25.903Z,1744227865.903 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:44:25.903Z,1744227865.903 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:44:25.903Z,1744227865.903 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:44:40.012Z,1744227880.012 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:44:40.845Z,1744227880.845 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:44:54.159Z,1744227894.159 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-04-09T19:47:21.640Z,1744228041.640 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:47:35.780Z,1744228055.780 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:47:49.919Z,1744228069.919 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:48:04.473Z,1744228084.473 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:48:18.610Z,1744228098.610 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:48:32.341Z,1744228112.341 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:48:46.146Z,1744228126.146 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:49:00.280Z,1744228140.280 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:49:14.420Z,1744228154.420 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:49:26.563Z,1744228166.563 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:49:26.563Z,1744228166.563 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:49:26.563Z,1744228166.563 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:49:26.564Z,1744228166.564 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:49:26.957Z,1744228166.957 [Default:CheckIn:D] Stopped 2025-04-09T19:49:26.957Z,1744228166.957 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:49:27.371Z,1744228167.371 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.780591 min 2025-04-09T19:49:27.371Z,1744228167.371 [Default:CheckIn:E] Stopped 2025-04-09T19:49:27.371Z,1744228167.371 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:49:27.371Z,1744228167.371 [Default:CheckIn] Stopped 2025-04-09T19:49:27.371Z,1744228167.371 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:49:27.371Z,1744228167.371 [Default:CheckIn](INFO): Running loop #17 2025-04-09T19:49:27.371Z,1744228167.371 [Default:CheckIn] Running Loop=17 2025-04-09T19:49:27.371Z,1744228167.371 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:49:27.371Z,1744228167.371 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:49:28.156Z,1744228168.156 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:49:29.407Z,1744228169.407 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194931.00,A,3648.17647,N,12147.27806,W,0.330,283.85,090425,,,D*70 2025-04-09T19:49:29.409Z,1744228169.409 [NAL9602](INFO): GPS fix at 20250409T194931: (36.802941, -121.787968) 2025-04-09T19:49:29.420Z,1744228169.420 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:49:29.420Z,1744228169.420 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:49:40.687Z,1744228180.687 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250409T182455/Courier0048.lzma 2025-04-09T19:49:40.690Z,1744228180.690 [NAL9602](INFO): SBD MO Status=0, MOMSN=22863, MT Status=0, MTMSN=0 2025-04-09T19:49:40.691Z,1744228180.691 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:49:43.698Z,1744228183.698 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0048.lzma.bak 2025-04-09T19:49:43.698Z,1744228183.698 [DataOverHttps](INFO): SBD MOMSN=24598995 2025-04-09T19:50:11.441Z,1744228211.441 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:50:20.756Z,1744228220.756 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20250409T182455/Express0049.lzma 2025-04-09T19:50:21.758Z,1744228221.758 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0049.lzma.bak 2025-04-09T19:50:21.758Z,1744228221.758 [DataOverHttps](INFO): SBD MOMSN=24598999 2025-04-09T19:50:23.188Z,1744228223.188 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:50:23.188Z,1744228223.188 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:50:23.188Z,1744228223.188 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:52:05.312Z,1744228325.312 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:52:20.663Z,1744228340.663 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:52:36.421Z,1744228356.421 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:52:51.772Z,1744228371.772 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:53:06.719Z,1744228386.719 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:53:22.073Z,1744228402.073 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:53:37.019Z,1744228417.019 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:53:52.372Z,1744228432.372 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:54:07.320Z,1744228447.320 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:54:21.865Z,1744228461.865 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-04-09T19:55:23.748Z,1744228523.748 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T19:55:23.748Z,1744228523.748 [Default:CheckIn:C.Wait] Stopped 2025-04-09T19:55:23.748Z,1744228523.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T19:55:23.748Z,1744228523.748 [Default:CheckIn:D] Running Loop=1 2025-04-09T19:55:24.141Z,1744228524.141 [Default:CheckIn:D] Stopped 2025-04-09T19:55:24.141Z,1744228524.141 [Default:CheckIn:E] Running Loop=1 2025-04-09T19:55:24.551Z,1744228524.551 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.733667 min 2025-04-09T19:55:24.551Z,1744228524.551 [Default:CheckIn:E] Stopped 2025-04-09T19:55:24.551Z,1744228524.551 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T19:55:24.551Z,1744228524.551 [Default:CheckIn] Stopped 2025-04-09T19:55:24.551Z,1744228524.551 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T19:55:24.551Z,1744228524.551 [Default:CheckIn](INFO): Running loop #18 2025-04-09T19:55:24.551Z,1744228524.551 [Default:CheckIn] Running Loop=18 2025-04-09T19:55:24.551Z,1744228524.551 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T19:55:24.552Z,1744228524.552 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T19:55:26.533Z,1744228526.533 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195528.00,A,3648.17528,N,12147.27762,W,0.019,283.85,090425,,,D*7A 2025-04-09T19:55:26.535Z,1744228526.535 [NAL9602](INFO): GPS fix at 20250409T195528: (36.802921, -121.787960) 2025-04-09T19:55:26.551Z,1744228526.551 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T19:55:26.551Z,1744228526.551 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T19:55:33.955Z,1744228533.955 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250409T182455/Courier0051.lzma 2025-04-09T19:55:34.958Z,1744228534.958 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0051.lzma.bak 2025-04-09T19:55:34.958Z,1744228534.958 [DataOverHttps](INFO): SBD MOMSN=24599013 2025-04-09T19:55:39.866Z,1744228539.866 [NAL9602](INFO): SBD MO Status=0, MOMSN=22864, MT Status=0, MTMSN=0 2025-04-09T19:55:39.866Z,1744228539.866 [NAL9602](INFO): No messages in MT queue 2025-04-09T19:55:51.112Z,1744228551.112 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250409T182455/Express0052.lzma 2025-04-09T19:55:52.114Z,1744228552.114 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0052.lzma.bak 2025-04-09T19:55:52.114Z,1744228552.114 [DataOverHttps](INFO): SBD MOMSN=24599055 2025-04-09T19:55:53.226Z,1744228553.226 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T19:55:53.226Z,1744228553.226 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T19:55:53.226Z,1744228553.226 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T19:56:10.571Z,1744228570.571 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T19:56:51.369Z,1744228611.369 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:57:05.507Z,1744228625.507 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:57:19.244Z,1744228639.244 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:57:32.980Z,1744228652.980 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:57:46.313Z,1744228666.313 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:58:00.047Z,1744228680.047 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:58:14.188Z,1744228694.188 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:58:27.923Z,1744228707.923 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:58:42.116Z,1744228722.116 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T19:58:55.847Z,1744228735.847 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:00:53.836Z,1744228853.836 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T20:00:53.837Z,1744228853.837 [Default:CheckIn:C.Wait] Stopped 2025-04-09T20:00:53.837Z,1744228853.837 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T20:00:53.837Z,1744228853.837 [Default:CheckIn:D] Running Loop=1 2025-04-09T20:00:54.252Z,1744228854.252 [Default:CheckIn:D] Stopped 2025-04-09T20:00:54.252Z,1744228854.252 [Default:CheckIn:E] Running Loop=1 2025-04-09T20:00:54.643Z,1744228854.643 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.235514 min 2025-04-09T20:00:54.643Z,1744228854.643 [Default:CheckIn:E] Stopped 2025-04-09T20:00:54.644Z,1744228854.644 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T20:00:54.644Z,1744228854.644 [Default:CheckIn] Stopped 2025-04-09T20:00:54.644Z,1744228854.644 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T20:00:54.644Z,1744228854.644 [Default:CheckIn](INFO): Running loop #19 2025-04-09T20:00:54.644Z,1744228854.644 [Default:CheckIn] Running Loop=19 2025-04-09T20:00:54.644Z,1744228854.644 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T20:00:54.644Z,1744228854.644 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T20:00:56.651Z,1744228856.651 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200059.00,A,3648.17318,N,12147.27643,W,0.136,283.85,090425,,,D*7D 2025-04-09T20:00:56.654Z,1744228856.654 [NAL9602](INFO): GPS fix at 20250409T200059: (36.802886, -121.787941) 2025-04-09T20:00:56.665Z,1744228856.665 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T20:00:56.665Z,1744228856.665 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T20:01:04.703Z,1744228864.703 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T182455/Courier0054.lzma 2025-04-09T20:01:05.706Z,1744228865.706 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0054.lzma.bak 2025-04-09T20:01:05.706Z,1744228865.706 [DataOverHttps](INFO): SBD MOMSN=24599097 2025-04-09T20:01:11.202Z,1744228871.202 [NAL9602](INFO): SBD MO Status=0, MOMSN=22865, MT Status=0, MTMSN=0 2025-04-09T20:01:11.202Z,1744228871.202 [NAL9602](INFO): No messages in MT queue 2025-04-09T20:01:21.848Z,1744228881.848 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250409T182455/Express0055.lzma 2025-04-09T20:01:26.866Z,1744228886.866 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0055.lzma.bak 2025-04-09T20:01:26.866Z,1744228886.866 [DataOverHttps](INFO): SBD MOMSN=24599100 2025-04-09T20:01:28.187Z,1744228888.187 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T20:01:28.187Z,1744228888.187 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T20:01:28.188Z,1744228888.188 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T20:01:32.303Z,1744228892.303 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:01:41.897Z,1744228901.897 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T20:01:47.547Z,1744228907.547 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:02:02.092Z,1744228922.092 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:02:17.039Z,1744228937.039 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:02:32.392Z,1744228952.392 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:02:47.353Z,1744228967.353 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:03:02.287Z,1744228982.287 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:03:17.235Z,1744228997.235 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:03:32.587Z,1744229012.587 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:03:47.156Z,1744229027.156 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-04-09T20:06:14.675Z,1744229174.675 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:06:28.815Z,1744229188.815 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:06:28.845Z,1744229188.845 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T20:06:28.845Z,1744229188.845 [Default:CheckIn:C.Wait] Stopped 2025-04-09T20:06:28.845Z,1744229188.845 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T20:06:28.845Z,1744229188.845 [Default:CheckIn:D] Running Loop=1 2025-04-09T20:06:29.257Z,1744229189.257 [Default:CheckIn:D] Stopped 2025-04-09T20:06:29.257Z,1744229189.257 [Default:CheckIn:E] Running Loop=1 2025-04-09T20:06:29.644Z,1744229189.644 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.818921 min 2025-04-09T20:06:29.644Z,1744229189.644 [Default:CheckIn:E] Stopped 2025-04-09T20:06:29.644Z,1744229189.644 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T20:06:29.645Z,1744229189.645 [Default:CheckIn] Stopped 2025-04-09T20:06:29.645Z,1744229189.645 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T20:06:29.645Z,1744229189.645 [Default:CheckIn](INFO): Running loop #20 2025-04-09T20:06:29.645Z,1744229189.645 [Default:CheckIn] Running Loop=20 2025-04-09T20:06:29.645Z,1744229189.645 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T20:06:29.645Z,1744229189.645 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T20:06:31.655Z,1744229191.655 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200634.00,A,3648.17501,N,12147.26931,W,0.078,298.00,090425,,,D*79 2025-04-09T20:06:31.657Z,1744229191.657 [NAL9602](INFO): GPS fix at 20250409T200634: (36.802917, -121.787822) 2025-04-09T20:06:31.669Z,1744229191.669 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T20:06:31.669Z,1744229191.669 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T20:06:42.958Z,1744229202.958 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:06:49.955Z,1744229209.955 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T182455/Courier0057.lzma 2025-04-09T20:06:50.958Z,1744229210.958 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0057.lzma.bak 2025-04-09T20:06:50.958Z,1744229210.958 [DataOverHttps](INFO): SBD MOMSN=24599121 2025-04-09T20:06:57.114Z,1744229217.114 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:07:01.559Z,1744229221.559 [NAL9602](INFO): SBD MO Status=2, MOMSN=22866, MT Status=2, MTMSN=0 2025-04-09T20:07:01.560Z,1744229221.560 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T20:07:07.188Z,1744229227.188 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20250409T182455/Express0058.lzma 2025-04-09T20:07:08.190Z,1744229228.190 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0058.lzma.bak 2025-04-09T20:07:08.190Z,1744229228.190 [DataOverHttps](INFO): SBD MOMSN=24599151 2025-04-09T20:07:09.649Z,1744229229.649 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T20:07:09.649Z,1744229229.649 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T20:07:09.649Z,1744229229.649 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T20:07:11.253Z,1744229231.253 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:07:25.391Z,1744229245.391 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-09T20:07:33.474Z,1744229253.474 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-09T20:07:33.475Z,1744229253.475 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-09T20:07:33.475Z,1744229253.475 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-09T20:07:33.476Z,1744229253.476 [BPC1](INFO): Calculating totals. Valid battery stick count: 43. Valid reserve battery stick count: 5. 2025-04-09T20:07:33.479Z,1744229253.479 [BPC1](FAULT): Failed to receive data from 10 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 9, 10, 11, 12, 13, 14, 29, 35, 53, 59. 2025-04-09T20:07:34.286Z,1744229254.286 [NAL9602](INFO): SBD MO Status=0, MOMSN=22866, MT Status=0, MTMSN=0 2025-04-09T20:07:34.287Z,1744229254.287 [NAL9602](INFO): No messages in MT queue 2025-04-09T20:08:05.006Z,1744229285.006 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T20:12:10.268Z,1744229530.268 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T20:12:10.268Z,1744229530.268 [Default:CheckIn:C.Wait] Stopped 2025-04-09T20:12:10.268Z,1744229530.268 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T20:12:10.269Z,1744229530.269 [Default:CheckIn:D] Running Loop=1 2025-04-09T20:12:10.665Z,1744229530.665 [Default:CheckIn:D] Stopped 2025-04-09T20:12:10.665Z,1744229530.665 [Default:CheckIn:E] Running Loop=1 2025-04-09T20:12:11.072Z,1744229531.072 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.509066 min 2025-04-09T20:12:11.072Z,1744229531.072 [Default:CheckIn:E] Stopped 2025-04-09T20:12:11.072Z,1744229531.072 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T20:12:11.073Z,1744229531.073 [Default:CheckIn] Stopped 2025-04-09T20:12:11.073Z,1744229531.073 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T20:12:11.073Z,1744229531.073 [Default:CheckIn](INFO): Running loop #21 2025-04-09T20:12:11.073Z,1744229531.073 [Default:CheckIn] Running Loop=21 2025-04-09T20:12:11.073Z,1744229531.073 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T20:12:11.073Z,1744229531.073 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T20:12:13.082Z,1744229533.082 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201215.00,A,3648.16679,N,12147.29127,W,0.603,166.02,090425,,,D*78 2025-04-09T20:12:13.084Z,1744229533.084 [NAL9602](INFO): GPS fix at 20250409T201215: (36.802780, -121.788188) 2025-04-09T20:12:13.095Z,1744229533.095 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T20:12:13.096Z,1744229533.096 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T20:12:20.667Z,1744229540.667 [DataOverHttps](INFO): Sending 87 bytes from file Logs/20250409T182455/Courier0060.lzma 2025-04-09T20:12:21.670Z,1744229541.670 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Courier0060.lzma.bak 2025-04-09T20:12:21.670Z,1744229541.670 [DataOverHttps](INFO): SBD MOMSN=24599171 2025-04-09T20:12:36.975Z,1744229556.975 [NAL9602](INFO): SBD MO Status=2, MOMSN=22867, MT Status=2, MTMSN=0 2025-04-09T20:12:36.975Z,1744229556.975 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T20:12:40.540Z,1744229560.540 [DataOverHttps](INFO): Sending 314 bytes from file Logs/20250409T182455/Express0061.lzma 2025-04-09T20:12:41.542Z,1744229561.542 [DataOverHttps](INFO): Moved sent file to Logs/20250409T182455/Express0061.lzma.bak 2025-04-09T20:12:41.542Z,1744229561.542 [DataOverHttps](INFO): SBD MOMSN=24599174 2025-04-09T20:12:43.066Z,1744229563.066 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T20:12:43.066Z,1744229563.066 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T20:12:43.066Z,1744229563.066 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T20:12:43.859Z,1744229563.859 [NAL9602](INFO): SBD MO Status=0, MOMSN=22867, MT Status=0, MTMSN=0 2025-04-09T20:12:43.859Z,1744229563.859 [NAL9602](INFO): No messages in MT queue 2025-04-09T20:13:14.561Z,1744229594.561 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T20:17:22.761Z,1744229842.761 [CommandExec](IMPORTANT): got command quit 2025-04-09T20:17:23.764Z,1744229843.764 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T20:17:23.764Z,1744229843.764 [CommandExec](INFO): Uninitializing the command executive. 2025-04-09T20:17:23.764Z,1744229843.764 [CommandExec](INFO): Uninitializing the command scheduler. 2025-04-09T20:17:23.764Z,1744229843.764 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:23.924Z,1744229843.924 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-04-09T20:17:23.924Z,1744229843.924 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-04-09T20:17:23.924Z,1744229843.924 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:23.924Z,1744229843.924 [NavChartDb](INFO): Join timeout helper Thread ID is 3715 2025-04-09T20:17:24.032Z,1744229844.032 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T20:17:24.032Z,1744229844.032 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:24.044Z,1744229844.044 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-04-09T20:17:24.044Z,1744229844.044 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:24.044Z,1744229844.044 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3716 2025-04-09T20:17:24.456Z,1744229844.456 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T20:17:24.456Z,1744229844.456 [WetLabsBB2FL](INFO): Powering down 2025-04-09T20:17:24.457Z,1744229844.457 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:24.475Z,1744229844.475 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-04-09T20:17:24.475Z,1744229844.475 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:24.475Z,1744229844.475 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3717 2025-04-09T20:17:24.869Z,1744229844.869 [CTD_Seabird](INFO): Powering down 2025-04-09T20:17:24.887Z,1744229844.887 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T20:17:24.887Z,1744229844.887 [CTD_Seabird](INFO): Powering down 2025-04-09T20:17:24.900Z,1744229844.900 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:24.904Z,1744229844.904 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-04-09T20:17:24.904Z,1744229844.904 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:24.904Z,1744229844.904 [Radio_Surface](INFO): Join timeout helper Thread ID is 3718 2025-04-09T20:17:25.292Z,1744229845.292 [Radio_Surface](INFO): Powering down 2025-04-09T20:17:25.293Z,1744229845.293 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T20:17:25.293Z,1744229845.293 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:25.317Z,1744229845.317 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-04-09T20:17:25.317Z,1744229845.317 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:25.318Z,1744229845.318 [Onboard](INFO): Join timeout helper Thread ID is 3719 2025-04-09T20:17:25.788Z,1744229845.788 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T20:17:25.788Z,1744229845.788 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:25.805Z,1744229845.805 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-04-09T20:17:25.805Z,1744229845.805 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:25.805Z,1744229845.805 [DataOverHttps](INFO): Join timeout helper Thread ID is 3720 2025-04-09T20:17:26.200Z,1744229846.200 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T20:17:26.204Z,1744229846.204 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:26.209Z,1744229846.209 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-04-09T20:17:26.209Z,1744229846.209 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:26.209Z,1744229846.209 [DAT](INFO): Join timeout helper Thread ID is 3721 2025-04-09T20:17:26.380Z,1744229846.380 [DAT](INFO): Powering down 2025-04-09T20:17:26.453Z,1744229846.453 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T20:17:26.453Z,1744229846.453 [DAT](INFO): Powering down 2025-04-09T20:17:26.454Z,1744229846.454 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:26.472Z,1744229846.472 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-04-09T20:17:26.473Z,1744229846.473 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:26.473Z,1744229846.473 [BackseatComponent](INFO): Join timeout helper Thread ID is 3722 2025-04-09T20:17:26.488Z,1744229846.488 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T20:17:26.488Z,1744229846.488 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:26.493Z,1744229846.493 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-04-09T20:17:26.493Z,1744229846.493 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:26.493Z,1744229846.493 [logger](INFO): Join timeout helper Thread ID is 3723 2025-04-09T20:17:26.500Z,1744229846.500 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T20:17:26.500Z,1744229846.500 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:26.513Z,1744229846.513 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-04-09T20:17:26.513Z,1744229846.513 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:26.513Z,1744229846.513 [CommandLine](INFO): Join timeout helper Thread ID is 3724 2025-04-09T20:17:26.612Z,1744229846.612 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T20:17:26.612Z,1744229846.612 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:26.632Z,1744229846.632 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-04-09T20:17:26.633Z,1744229846.633 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:26.633Z,1744229846.633 [CommandExec](INFO): Join timeout helper Thread ID is 3725 2025-04-09T20:17:26.634Z,1744229846.634 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-04-09T20:17:26.634Z,1744229846.634 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:26.635Z,1744229846.635 [controlThread](INFO): Join timeout helper Thread ID is 3726 2025-04-09T20:17:26.648Z,1744229846.648 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T20:17:26.648Z,1744229846.648 [controlThread](DEBUG): Uninitializing ControlThread 2025-04-09T20:17:26.649Z,1744229846.649 [AHRS_M2](INFO): Powering down 2025-04-09T20:17:26.728Z,1744229846.728 [NAL9602](INFO): Powering down 2025-04-09T20:17:26.729Z,1744229846.729 [Sonardyne_Nano](INFO): Powering down 2025-04-09T20:17:26.936Z,1744229846.936 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-04-09T20:17:26.937Z,1744229846.937 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-04-09T20:17:26.937Z,1744229846.937 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-04-09T20:17:26.938Z,1744229846.938 [MissionManager](INFO): Uninitializing Mission Default 2025-04-09T20:17:26.938Z,1744229846.938 [Default] Stopped 2025-04-09T20:17:26.938Z,1744229846.938 [Default](DEBUG): Aggregate::uninitialize Default 2025-04-09T20:17:26.938Z,1744229846.938 [Default:B.GoToSurface] Stopped 2025-04-09T20:17:26.938Z,1744229846.938 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-09T20:17:26.938Z,1744229846.938 [Default:CheckIn] Stopped 2025-04-09T20:17:26.938Z,1744229846.938 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T20:17:26.939Z,1744229846.939 [Default:CheckIn:C.Wait] Stopped 2025-04-09T20:17:26.939Z,1744229846.939 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T20:17:26.942Z,1744229846.942 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-04-09T20:17:26.942Z,1744229846.942 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-04-09T20:17:26.942Z,1744229846.942 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-04-09T20:17:26.942Z,1744229846.942 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-04-09T20:17:26.943Z,1744229846.943 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-04-09T20:17:26.943Z,1744229846.943 [BuoyancyServo](INFO): Powering down 2025-04-09T20:17:26.956Z,1744229846.956 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-04-09T20:17:26.956Z,1744229846.956 [ElevatorServo](INFO): Powering down 2025-04-09T20:17:26.957Z,1744229846.957 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-04-09T20:17:26.957Z,1744229846.957 [MassServo](INFO): Powering down 2025-04-09T20:17:26.958Z,1744229846.958 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-04-09T20:17:26.958Z,1744229846.958 [RudderServo](INFO): Powering down 2025-04-09T20:17:26.959Z,1744229846.959 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-04-09T20:17:26.959Z,1744229846.959 [ThrusterHE](INFO): Powering down 2025-04-09T20:17:26.960Z,1744229846.960 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-04-09T20:17:26.960Z,1744229846.960 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-04-09T20:17:26.960Z,1744229846.960 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-04-09T20:17:26.960Z,1744229846.960 [CBIT](DEBUG): Powering off loads. 2025-04-09T20:17:26.972Z,1744229846.972 [CBIT](DEBUG): Disabling WDT. 2025-04-09T20:17:26.984Z,1744229846.984 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-04-09T20:17:26.984Z,1744229846.984 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-04-09T20:17:26.985Z,1744229846.985 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:27.025Z,1744229847.025 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:27.028Z,1744229847.028 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:27.034Z,1744229847.034 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:27.040Z,1744229847.040 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:27.081Z,1744229847.081 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:27.115Z,1744229847.115 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-04-09T20:17:27.115Z,1744229847.115 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-04-09T20:17:27.171Z,1744229847.171 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:27.177Z,1744229847.177 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:27.185Z,1744229847.185 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-09T20:17:27.282Z,1744229847.282 [logger ThreadHandler](INFO): Thread cancelled.