2025-04-09T21:04:03.676Z,1744232643.676 [CommandExec](IMPORTANT): got command restart logs 2025-04-09T21:04:13.772Z,1744232653.772 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:04:13.772Z,1744232653.772 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:04:13.772Z,1744232653.772 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:04:13.772Z,1744232653.772 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:04:14.168Z,1744232654.168 [Default:CheckIn:D] Stopped 2025-04-09T21:04:14.168Z,1744232654.168 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:04:14.579Z,1744232654.579 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.337998 min 2025-04-09T21:04:14.579Z,1744232654.579 [Default:CheckIn:E] Stopped 2025-04-09T21:04:14.580Z,1744232654.580 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:04:14.580Z,1744232654.580 [Default:CheckIn] Stopped 2025-04-09T21:04:14.580Z,1744232654.580 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:04:14.580Z,1744232654.580 [Default:CheckIn](INFO): Running loop #5 2025-04-09T21:04:14.580Z,1744232654.580 [Default:CheckIn] Running Loop=5 2025-04-09T21:04:14.580Z,1744232654.580 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:04:14.580Z,1744232654.580 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:04:16.578Z,1744232656.578 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210415.00,A,3647.31444,N,12150.90044,W,1.030,131.96,090425,,,D*70 2025-04-09T21:04:16.580Z,1744232656.580 [NAL9602](INFO): GPS fix at 20250409T210415: (36.788574, -121.848341) 2025-04-09T21:04:16.621Z,1744232656.621 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:04:16.621Z,1744232656.621 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:04:27.298Z,1744232667.298 [DataOverHttps](INFO): Sending 49 bytes from file Logs/20250409T014322/Courier0066.lzma 2025-04-09T21:04:27.960Z,1744232667.960 [NAL9602](INFO): SBD MO Status=0, MOMSN=63096, MT Status=0, MTMSN=0 2025-04-09T21:04:27.960Z,1744232667.960 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:04:28.301Z,1744232668.301 [DataOverHttps](INFO): Moved sent file to Logs/20250409T014322/Courier0066.lzma.bak 2025-04-09T21:04:28.301Z,1744232668.301 [DataOverHttps](INFO): SBD MOMSN=24599772 2025-04-09T21:04:44.487Z,1744232684.487 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250409T210403/Courier0000.lzma 2025-04-09T21:04:45.489Z,1744232685.489 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0000.lzma.bak 2025-04-09T21:04:45.489Z,1744232685.489 [DataOverHttps](INFO): SBD MOMSN=24599774 2025-04-09T21:04:58.733Z,1744232698.733 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:05:04.711Z,1744232704.711 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20250409T014322/Express0067.lzma 2025-04-09T21:05:05.713Z,1744232705.713 [DataOverHttps](INFO): Moved sent file to Logs/20250409T014322/Express0067.lzma.bak 2025-04-09T21:05:05.713Z,1744232705.713 [DataOverHttps](INFO): SBD MOMSN=24599779 2025-04-09T21:05:21.696Z,1744232721.696 [DataOverHttps](INFO): Sending 490 bytes from file Logs/20250409T210403/Express0001.lzma 2025-04-09T21:05:22.696Z,1744232722.696 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0001.lzma.bak 2025-04-09T21:05:22.697Z,1744232722.697 [DataOverHttps](INFO): SBD MOMSN=24599783 2025-04-09T21:05:38.670Z,1744232738.670 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250409T210403/Express0004.lzma 2025-04-09T21:05:39.673Z,1744232739.673 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0004.lzma.bak 2025-04-09T21:05:39.673Z,1744232739.673 [DataOverHttps](INFO): SBD MOMSN=24599787 2025-04-09T21:05:41.194Z,1744232741.194 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:05:41.194Z,1744232741.194 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:05:41.194Z,1744232741.194 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:10:41.877Z,1744233041.877 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:10:41.877Z,1744233041.877 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:10:41.877Z,1744233041.877 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:10:41.878Z,1744233041.878 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:10:42.278Z,1744233042.278 [Default:CheckIn:D] Stopped 2025-04-09T21:10:42.278Z,1744233042.278 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:10:42.684Z,1744233042.684 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.806498 min 2025-04-09T21:10:42.685Z,1744233042.685 [Default:CheckIn:E] Stopped 2025-04-09T21:10:42.685Z,1744233042.685 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:10:42.685Z,1744233042.685 [Default:CheckIn] Stopped 2025-04-09T21:10:42.685Z,1744233042.685 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:10:42.685Z,1744233042.685 [Default:CheckIn](INFO): Running loop #6 2025-04-09T21:10:42.685Z,1744233042.685 [Default:CheckIn] Running Loop=6 2025-04-09T21:10:42.685Z,1744233042.685 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:10:42.685Z,1744233042.685 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:10:44.698Z,1744233044.698 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211043.00,A,3647.22986,N,12150.80142,W,0.700,185.19,090425,,,D*7C 2025-04-09T21:10:44.710Z,1744233044.710 [NAL9602](INFO): GPS fix at 20250409T211043: (36.787164, -121.846690) 2025-04-09T21:10:44.721Z,1744233044.721 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:10:44.721Z,1744233044.721 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:10:55.079Z,1744233055.079 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T210403/Courier0006.lzma 2025-04-09T21:10:56.081Z,1744233056.081 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0006.lzma.bak 2025-04-09T21:10:56.081Z,1744233056.081 [DataOverHttps](INFO): SBD MOMSN=24599843 2025-04-09T21:11:06.565Z,1744233066.565 [NAL9602](INFO): SBD MO Status=0, MOMSN=63097, MT Status=0, MTMSN=0 2025-04-09T21:11:06.566Z,1744233066.566 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:11:12.371Z,1744233072.371 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250409T210403/Express0007.lzma 2025-04-09T21:11:13.372Z,1744233073.372 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0007.lzma.bak 2025-04-09T21:11:13.373Z,1744233073.373 [DataOverHttps](INFO): SBD MOMSN=24599849 2025-04-09T21:11:14.659Z,1744233074.659 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:11:14.660Z,1744233074.660 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:11:14.660Z,1744233074.660 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:11:37.272Z,1744233097.272 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:16:15.476Z,1744233375.476 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:16:15.476Z,1744233375.476 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:16:15.476Z,1744233375.476 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:16:15.476Z,1744233375.476 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:16:15.867Z,1744233375.867 [Default:CheckIn:D] Stopped 2025-04-09T21:16:15.867Z,1744233375.867 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:16:16.331Z,1744233376.331 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.366313 min 2025-04-09T21:16:16.331Z,1744233376.331 [Default:CheckIn:E] Stopped 2025-04-09T21:16:16.331Z,1744233376.331 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:16:16.331Z,1744233376.331 [Default:CheckIn] Stopped 2025-04-09T21:16:16.331Z,1744233376.331 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:16:16.332Z,1744233376.332 [Default:CheckIn](INFO): Running loop #7 2025-04-09T21:16:16.332Z,1744233376.332 [Default:CheckIn] Running Loop=7 2025-04-09T21:16:16.332Z,1744233376.332 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:16:16.332Z,1744233376.332 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:16:18.287Z,1744233378.287 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211617.00,A,3647.15486,N,12150.68736,W,1.341,124.38,090425,,,D*79 2025-04-09T21:16:18.289Z,1744233378.289 [NAL9602](INFO): GPS fix at 20250409T211617: (36.785914, -121.844789) 2025-04-09T21:16:18.300Z,1744233378.300 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:16:18.300Z,1744233378.300 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:16:20.143Z,1744233380.143 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-09T21:16:40.494Z,1744233400.494 [NAL9602](INFO): SBD MO Status=0, MOMSN=63098, MT Status=0, MTMSN=0 2025-04-09T21:16:40.494Z,1744233400.494 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:16:51.267Z,1744233411.267 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-09T21:17:11.205Z,1744233431.205 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:17:22.391Z,1744233442.391 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-04-09T21:17:33.894Z,1744233453.894 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250409T210403/Courier0009.lzma 2025-04-09T21:17:34.896Z,1744233454.896 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0009.lzma.bak 2025-04-09T21:17:34.897Z,1744233454.897 [DataOverHttps](INFO): SBD MOMSN=24599954 2025-04-09T21:17:51.259Z,1744233471.259 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250409T210403/Express0010.lzma 2025-04-09T21:17:52.261Z,1744233472.261 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0010.lzma.bak 2025-04-09T21:17:52.261Z,1744233472.261 [DataOverHttps](INFO): SBD MOMSN=24599959 2025-04-09T21:17:53.635Z,1744233473.635 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:17:53.636Z,1744233473.636 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:17:53.636Z,1744233473.636 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:18:47.807Z,1744233527.807 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:19:00.738Z,1744233540.738 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:19:14.880Z,1744233554.880 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:19:28.210Z,1744233568.210 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:19:41.542Z,1744233581.542 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:19:54.875Z,1744233594.875 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:20:07.804Z,1744233607.804 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:20:20.743Z,1744233620.743 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:20:34.079Z,1744233634.079 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:20:47.411Z,1744233647.411 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:21:00.743Z,1744233660.743 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:22:54.294Z,1744233774.294 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:22:54.294Z,1744233774.294 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:22:54.294Z,1744233774.294 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:22:54.303Z,1744233774.303 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:22:54.690Z,1744233774.690 [Default:CheckIn:D] Stopped 2025-04-09T21:22:54.690Z,1744233774.690 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:22:55.103Z,1744233775.103 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.013363 min 2025-04-09T21:22:55.103Z,1744233775.103 [Default:CheckIn:E] Stopped 2025-04-09T21:22:55.104Z,1744233775.104 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:22:55.104Z,1744233775.104 [Default:CheckIn] Stopped 2025-04-09T21:22:55.104Z,1744233775.104 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:22:55.104Z,1744233775.104 [Default:CheckIn](INFO): Running loop #8 2025-04-09T21:22:55.104Z,1744233775.104 [Default:CheckIn] Running Loop=8 2025-04-09T21:22:55.104Z,1744233775.104 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:22:55.104Z,1744233775.104 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:22:57.130Z,1744233777.130 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212256.00,A,3647.48714,N,12151.20361,W,1.225,193.73,090425,,,D*70 2025-04-09T21:22:57.132Z,1744233777.132 [NAL9602](INFO): GPS fix at 20250409T212256: (36.791452, -121.853393) 2025-04-09T21:22:57.143Z,1744233777.143 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:22:57.143Z,1744233777.143 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:23:12.859Z,1744233792.859 [NAL9602](INFO): SBD MO Status=0, MOMSN=63099, MT Status=0, MTMSN=0 2025-04-09T21:23:12.859Z,1744233792.859 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:23:14.074Z,1744233794.074 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250409T210403/Courier0012.lzma 2025-04-09T21:23:15.076Z,1744233795.076 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0012.lzma.bak 2025-04-09T21:23:15.077Z,1744233795.077 [DataOverHttps](INFO): SBD MOMSN=24600053 2025-04-09T21:23:31.411Z,1744233811.411 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250409T210403/Express0013.lzma 2025-04-09T21:23:32.412Z,1744233812.412 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0013.lzma.bak 2025-04-09T21:23:32.413Z,1744233812.413 [DataOverHttps](INFO): SBD MOMSN=24600061 2025-04-09T21:23:33.880Z,1744233813.880 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:23:33.880Z,1744233813.880 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:23:33.880Z,1744233813.880 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:23:43.553Z,1744233823.553 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:28:25.950Z,1744234105.950 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:28:34.456Z,1744234114.456 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:28:34.456Z,1744234114.456 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:28:34.456Z,1744234114.456 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:28:34.457Z,1744234114.457 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:28:34.854Z,1744234114.854 [Default:CheckIn:D] Stopped 2025-04-09T21:28:34.854Z,1744234114.854 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:28:35.255Z,1744234115.255 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.682764 min 2025-04-09T21:28:35.255Z,1744234115.255 [Default:CheckIn:E] Stopped 2025-04-09T21:28:35.255Z,1744234115.255 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:28:35.255Z,1744234115.255 [Default:CheckIn] Stopped 2025-04-09T21:28:35.256Z,1744234115.256 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:28:35.256Z,1744234115.256 [Default:CheckIn](INFO): Running loop #9 2025-04-09T21:28:35.256Z,1744234115.256 [Default:CheckIn] Running Loop=9 2025-04-09T21:28:35.256Z,1744234115.256 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:28:35.256Z,1744234115.256 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:28:37.268Z,1744234117.268 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212836.00,A,3647.43517,N,12150.48263,W,20.663,86.14,090425,,,D*7B 2025-04-09T21:28:37.270Z,1744234117.270 [NAL9602](INFO): GPS fix at 20250409T212836: (36.790586, -121.841377) 2025-04-09T21:28:37.281Z,1744234117.281 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:28:37.281Z,1744234117.281 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:28:41.704Z,1744234121.704 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:28:44.298Z,1744234124.298 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250409T210403/Courier0015.lzma 2025-04-09T21:28:45.300Z,1744234125.300 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0015.lzma.bak 2025-04-09T21:28:45.301Z,1744234125.301 [DataOverHttps](INFO): SBD MOMSN=24600131 2025-04-09T21:28:53.902Z,1744234133.902 [NAL9602](INFO): SBD MO Status=0, MOMSN=63100, MT Status=0, MTMSN=0 2025-04-09T21:28:53.902Z,1744234133.902 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:28:57.127Z,1744234137.127 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:29:04.394Z,1744234144.395 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250409T210403/Express0016.lzma 2025-04-09T21:29:05.396Z,1744234145.396 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0016.lzma.bak 2025-04-09T21:29:05.397Z,1744234145.397 [DataOverHttps](INFO): SBD MOMSN=24600134 2025-04-09T21:29:06.843Z,1744234146.843 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:29:06.843Z,1744234146.843 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:29:06.843Z,1744234146.843 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:29:13.287Z,1744234153.287 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:29:24.602Z,1744234164.602 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:29:29.049Z,1744234169.049 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:29:44.798Z,1744234184.798 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:30:00.554Z,1744234200.554 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:30:15.098Z,1744234215.098 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:30:29.657Z,1744234229.657 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:34:07.434Z,1744234447.434 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:34:07.434Z,1744234447.434 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:34:07.434Z,1744234447.434 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:34:07.434Z,1744234447.434 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:34:07.843Z,1744234447.843 [Default:CheckIn:D] Stopped 2025-04-09T21:34:07.843Z,1744234447.843 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:34:08.241Z,1744234448.241 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.232585 min 2025-04-09T21:34:08.241Z,1744234448.241 [Default:CheckIn:E] Stopped 2025-04-09T21:34:08.241Z,1744234448.241 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:34:08.241Z,1744234448.241 [Default:CheckIn] Stopped 2025-04-09T21:34:08.241Z,1744234448.241 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:34:08.241Z,1744234448.241 [Default:CheckIn](INFO): Running loop #10 2025-04-09T21:34:08.241Z,1744234448.241 [Default:CheckIn] Running Loop=10 2025-04-09T21:34:08.241Z,1744234448.241 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:34:08.241Z,1744234448.241 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:34:10.254Z,1744234450.254 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213409.00,A,3648.06532,N,12147.88670,W,24.998,55.46,090425,,,D*79 2025-04-09T21:34:10.266Z,1744234450.266 [NAL9602](INFO): GPS fix at 20250409T213409: (36.801089, -121.798112) 2025-04-09T21:34:10.277Z,1744234450.277 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:34:10.277Z,1744234450.277 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:34:18.671Z,1744234458.671 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T210403/Courier0018.lzma 2025-04-09T21:34:19.673Z,1744234459.673 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0018.lzma.bak 2025-04-09T21:34:19.673Z,1744234459.673 [DataOverHttps](INFO): SBD MOMSN=24600176 2025-04-09T21:34:38.759Z,1744234478.759 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20250409T210403/Express0019.lzma 2025-04-09T21:34:39.760Z,1744234479.760 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0019.lzma.bak 2025-04-09T21:34:39.761Z,1744234479.761 [DataOverHttps](INFO): SBD MOMSN=24600186 2025-04-09T21:34:41.035Z,1744234481.035 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:34:41.035Z,1744234481.035 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:34:41.035Z,1744234481.035 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:34:57.578Z,1744234497.578 [NAL9602](INFO): SBD MO Status=2, MOMSN=63101, MT Status=2, MTMSN=0 2025-04-09T21:34:57.578Z,1744234497.578 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T21:35:14.945Z,1744234514.945 [NAL9602](INFO): SBD MO Status=0, MOMSN=63101, MT Status=0, MTMSN=0 2025-04-09T21:35:14.945Z,1744234514.945 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:35:45.652Z,1744234545.652 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:38:01.027Z,1744234681.027 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:38:14.360Z,1744234694.360 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:38:28.152Z,1744234708.152 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:38:41.482Z,1744234721.482 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:38:54.863Z,1744234734.863 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:39:08.194Z,1744234748.194 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:39:21.122Z,1744234761.122 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:39:34.474Z,1744234774.474 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:39:41.760Z,1744234781.760 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:39:41.760Z,1744234781.760 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:39:41.760Z,1744234781.760 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:39:41.760Z,1744234781.760 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:39:42.179Z,1744234782.179 [Default:CheckIn:D] Stopped 2025-04-09T21:39:42.180Z,1744234782.180 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:39:42.574Z,1744234782.574 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.804854 min 2025-04-09T21:39:42.574Z,1744234782.574 [Default:CheckIn:E] Stopped 2025-04-09T21:39:42.574Z,1744234782.574 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:39:42.574Z,1744234782.574 [Default:CheckIn] Stopped 2025-04-09T21:39:42.574Z,1744234782.574 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:39:42.575Z,1744234782.575 [Default:CheckIn](INFO): Running loop #11 2025-04-09T21:39:42.575Z,1744234782.575 [Default:CheckIn] Running Loop=11 2025-04-09T21:39:42.575Z,1744234782.575 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:39:42.575Z,1744234782.575 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:39:44.583Z,1744234784.583 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213943.00,A,3648.38243,N,12147.12123,W,4.315,183.87,090425,,,D*7E 2025-04-09T21:39:44.585Z,1744234784.585 [NAL9602](INFO): GPS fix at 20250409T213943: (36.806374, -121.785354) 2025-04-09T21:39:44.597Z,1744234784.597 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:39:44.597Z,1744234784.597 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:39:47.811Z,1744234787.811 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:39:52.654Z,1744234792.654 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T210403/Courier0021.lzma 2025-04-09T21:39:53.657Z,1744234793.657 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0021.lzma.bak 2025-04-09T21:39:53.657Z,1744234793.657 [DataOverHttps](INFO): SBD MOMSN=24600241 2025-04-09T21:39:57.965Z,1744234797.965 [NAL9602](INFO): SBD MO Status=0, MOMSN=63102, MT Status=0, MTMSN=0 2025-04-09T21:39:57.965Z,1744234797.965 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:40:01.142Z,1744234801.142 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:40:09.739Z,1744234809.739 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250409T210403/Express0022.lzma 2025-04-09T21:40:10.740Z,1744234810.740 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0022.lzma.bak 2025-04-09T21:40:10.741Z,1744234810.741 [DataOverHttps](INFO): SBD MOMSN=24600246 2025-04-09T21:40:12.084Z,1744234812.084 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:40:12.084Z,1744234812.084 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:40:12.084Z,1744234812.084 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:40:14.474Z,1744234814.474 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T21:40:28.616Z,1744234828.616 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:44:48.356Z,1744235088.356 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4. 2025-04-09T21:44:48.364Z,1744235088.364 [BPC1](FAULT): Failed to receive data from 11 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 34, 35, 36, 37, 38, 39, 50, 51, 54. 2025-04-09T21:45:12.475Z,1744235112.475 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:45:12.475Z,1744235112.475 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:45:12.475Z,1744235112.475 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:45:12.476Z,1744235112.476 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:45:12.882Z,1744235112.882 [Default:CheckIn:D] Stopped 2025-04-09T21:45:12.883Z,1744235112.883 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:45:13.304Z,1744235113.304 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.316569 min 2025-04-09T21:45:13.304Z,1744235113.304 [Default:CheckIn:E] Stopped 2025-04-09T21:45:13.304Z,1744235113.304 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:45:13.304Z,1744235113.304 [Default:CheckIn] Stopped 2025-04-09T21:45:13.304Z,1744235113.304 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:45:13.304Z,1744235113.304 [Default:CheckIn](INFO): Running loop #12 2025-04-09T21:45:13.304Z,1744235113.304 [Default:CheckIn] Running Loop=12 2025-04-09T21:45:13.304Z,1744235113.304 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:45:13.304Z,1744235113.304 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:45:15.304Z,1744235115.304 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214514.00,A,3648.15264,N,12147.21851,W,0.000,223.45,090425,,,D*75 2025-04-09T21:45:15.306Z,1744235115.306 [NAL9602](INFO): GPS fix at 20250409T214514: (36.802544, -121.786975) 2025-04-09T21:45:15.317Z,1744235115.317 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:45:15.317Z,1744235115.317 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:45:22.711Z,1744235122.711 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20250409T210403/Courier0024.lzma 2025-04-09T21:45:23.713Z,1744235123.713 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0024.lzma.bak 2025-04-09T21:45:23.713Z,1744235123.713 [DataOverHttps](INFO): SBD MOMSN=24600288 2025-04-09T21:45:30.251Z,1744235130.251 [NAL9602](INFO): SBD MO Status=0, MOMSN=63103, MT Status=0, MTMSN=0 2025-04-09T21:45:30.251Z,1744235130.251 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:45:39.679Z,1744235139.679 [DataOverHttps](INFO): Sending 312 bytes from file Logs/20250409T210403/Express0025.lzma 2025-04-09T21:45:40.680Z,1744235140.680 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0025.lzma.bak 2025-04-09T21:45:40.681Z,1744235140.681 [DataOverHttps](INFO): SBD MOMSN=24600291 2025-04-09T21:45:41.987Z,1744235141.987 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:45:41.987Z,1744235141.987 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:45:41.987Z,1744235141.987 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:46:00.944Z,1744235160.944 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:50:42.714Z,1744235442.714 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:50:42.714Z,1744235442.714 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:50:42.714Z,1744235442.714 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:50:42.714Z,1744235442.714 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:50:43.123Z,1744235443.123 [Default:CheckIn:D] Stopped 2025-04-09T21:50:43.123Z,1744235443.123 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:50:43.540Z,1744235443.540 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.820573 min 2025-04-09T21:50:43.540Z,1744235443.540 [Default:CheckIn:E] Stopped 2025-04-09T21:50:43.540Z,1744235443.540 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:50:43.540Z,1744235443.540 [Default:CheckIn] Stopped 2025-04-09T21:50:43.540Z,1744235443.540 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:50:43.540Z,1744235443.540 [Default:CheckIn](INFO): Running loop #13 2025-04-09T21:50:43.541Z,1744235443.541 [Default:CheckIn] Running Loop=13 2025-04-09T21:50:43.541Z,1744235443.541 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:50:43.541Z,1744235443.541 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:50:45.515Z,1744235445.515 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215044.00,A,3648.15382,N,12147.21953,W,0.019,223.45,090425,,,D*76 2025-04-09T21:50:45.517Z,1744235445.517 [NAL9602](INFO): GPS fix at 20250409T215044: (36.802564, -121.786992) 2025-04-09T21:50:45.528Z,1744235445.528 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:50:45.528Z,1744235445.528 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:50:56.186Z,1744235456.186 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250409T210403/Courier0027.lzma 2025-04-09T21:50:57.189Z,1744235457.189 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0027.lzma.bak 2025-04-09T21:50:57.189Z,1744235457.189 [DataOverHttps](INFO): SBD MOMSN=24600342 2025-04-09T21:51:13.391Z,1744235473.391 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250409T210403/Express0028.lzma 2025-04-09T21:51:14.393Z,1744235474.393 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0028.lzma.bak 2025-04-09T21:51:14.393Z,1744235474.393 [DataOverHttps](INFO): SBD MOMSN=24600345 2025-04-09T21:51:15.875Z,1744235475.875 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:51:15.875Z,1744235475.875 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:51:15.875Z,1744235475.875 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:51:29.191Z,1744235489.191 [NAL9602](INFO): SBD MO Status=2, MOMSN=63104, MT Status=2, MTMSN=0 2025-04-09T21:51:29.191Z,1744235489.191 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T21:51:59.489Z,1744235519.489 [NAL9602](INFO): SBD MO Status=2, MOMSN=63104, MT Status=2, MTMSN=0 2025-04-09T21:51:59.489Z,1744235519.489 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T21:52:17.275Z,1744235537.275 [NAL9602](INFO): SBD MO Status=0, MOMSN=63104, MT Status=0, MTMSN=0 2025-04-09T21:52:17.275Z,1744235537.275 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:52:48.037Z,1744235568.037 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:56:16.291Z,1744235776.291 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:56:16.291Z,1744235776.291 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:56:16.291Z,1744235776.291 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:56:16.292Z,1744235776.292 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:56:16.708Z,1744235776.708 [Default:CheckIn:D] Stopped 2025-04-09T21:56:16.708Z,1744235776.708 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:56:17.088Z,1744235777.088 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.380330 min 2025-04-09T21:56:17.088Z,1744235777.088 [Default:CheckIn:E] Stopped 2025-04-09T21:56:17.088Z,1744235777.088 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:56:17.088Z,1744235777.088 [Default:CheckIn] Stopped 2025-04-09T21:56:17.088Z,1744235777.088 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:56:17.089Z,1744235777.089 [Default:CheckIn](INFO): Running loop #14 2025-04-09T21:56:17.089Z,1744235777.089 [Default:CheckIn] Running Loop=14 2025-04-09T21:56:17.089Z,1744235777.089 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:56:17.089Z,1744235777.089 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:56:19.102Z,1744235779.102 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215618.00,A,3648.15290,N,12147.21890,W,0.097,223.45,090425,,,D*73 2025-04-09T21:56:19.121Z,1744235779.121 [NAL9602](INFO): GPS fix at 20250409T215618: (36.802548, -121.786982) 2025-04-09T21:56:19.144Z,1744235779.144 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:56:19.144Z,1744235779.144 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:56:30.282Z,1744235790.282 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T210403/Courier0030.lzma 2025-04-09T21:56:31.285Z,1744235791.285 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0030.lzma.bak 2025-04-09T21:56:31.285Z,1744235791.285 [DataOverHttps](INFO): SBD MOMSN=24600429 2025-04-09T21:56:44.727Z,1744235804.727 [NAL9602](INFO): SBD MO Status=0, MOMSN=63105, MT Status=0, MTMSN=0 2025-04-09T21:56:44.727Z,1744235804.727 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:56:47.511Z,1744235807.511 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250409T210403/Express0031.lzma 2025-04-09T21:56:48.513Z,1744235808.513 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0031.lzma.bak 2025-04-09T21:56:48.513Z,1744235808.513 [DataOverHttps](INFO): SBD MOMSN=24600435 2025-04-09T21:56:50.134Z,1744235810.134 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:56:50.134Z,1744235810.134 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:56:50.135Z,1744235810.135 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:57:15.525Z,1744235835.525 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:59:49.879Z,1744235989.879 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:00:04.828Z,1744236004.828 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:00:19.371Z,1744236019.371 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:00:33.916Z,1744236033.916 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:00:49.711Z,1744236049.711 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:01:05.447Z,1744236065.447 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:01:20.786Z,1744236080.786 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:01:36.546Z,1744236096.546 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:01:50.728Z,1744236110.728 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:01:50.728Z,1744236110.728 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:01:50.728Z,1744236110.728 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:01:50.728Z,1744236110.728 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:01:51.111Z,1744236111.111 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:01:51.141Z,1744236111.141 [Default:CheckIn:D] Stopped 2025-04-09T22:01:51.141Z,1744236111.141 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:01:51.515Z,1744236111.515 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.954207 min 2025-04-09T22:01:51.515Z,1744236111.515 [Default:CheckIn:E] Stopped 2025-04-09T22:01:51.515Z,1744236111.515 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:01:51.515Z,1744236111.515 [Default:CheckIn] Stopped 2025-04-09T22:01:51.515Z,1744236111.515 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:01:51.516Z,1744236111.516 [Default:CheckIn](INFO): Running loop #15 2025-04-09T22:01:51.516Z,1744236111.516 [Default:CheckIn] Running Loop=15 2025-04-09T22:01:51.516Z,1744236111.516 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:01:51.516Z,1744236111.516 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:01:53.516Z,1744236113.516 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220152.00,A,3648.14073,N,12147.23094,W,0.350,212.29,090425,,,D*7C 2025-04-09T22:01:53.518Z,1744236113.518 [NAL9602](INFO): GPS fix at 20250409T220152: (36.802346, -121.787182) 2025-04-09T22:01:53.530Z,1744236113.530 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:01:53.530Z,1744236113.530 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:02:01.250Z,1744236121.250 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250409T210403/Courier0033.lzma 2025-04-09T22:02:02.253Z,1744236122.253 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0033.lzma.bak 2025-04-09T22:02:02.253Z,1744236122.253 [DataOverHttps](INFO): SBD MOMSN=24600525 2025-04-09T22:02:05.643Z,1744236125.643 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:02:18.495Z,1744236138.495 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250409T210403/Express0034.lzma 2025-04-09T22:02:19.497Z,1744236139.497 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0034.lzma.bak 2025-04-09T22:02:19.497Z,1744236139.497 [DataOverHttps](INFO): SBD MOMSN=24600560 2025-04-09T22:02:21.008Z,1744236141.008 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:02:21.008Z,1744236141.008 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:02:21.008Z,1744236141.008 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:02:21.393Z,1744236141.393 [NAL9602](INFO): SBD MO Status=0, MOMSN=63106, MT Status=0, MTMSN=0 2025-04-09T22:02:21.393Z,1744236141.393 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:02:52.160Z,1744236172.160 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:07:21.706Z,1744236441.706 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:07:21.706Z,1744236441.706 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:07:21.706Z,1744236441.706 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:07:21.706Z,1744236441.706 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:07:22.106Z,1744236442.106 [Default:CheckIn:D] Stopped 2025-04-09T22:07:22.106Z,1744236442.106 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:07:22.514Z,1744236442.514 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.470288 min 2025-04-09T22:07:22.514Z,1744236442.514 [Default:CheckIn:E] Stopped 2025-04-09T22:07:22.515Z,1744236442.515 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:07:22.515Z,1744236442.515 [Default:CheckIn] Stopped 2025-04-09T22:07:22.515Z,1744236442.515 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:07:22.515Z,1744236442.515 [Default:CheckIn](INFO): Running loop #16 2025-04-09T22:07:22.515Z,1744236442.515 [Default:CheckIn] Running Loop=16 2025-04-09T22:07:22.515Z,1744236442.515 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:07:22.515Z,1744236442.515 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:07:24.501Z,1744236444.501 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220723.00,A,3648.17629,N,12147.28119,W,0.019,260.92,090425,,,D*72 2025-04-09T22:07:24.504Z,1744236444.504 [NAL9602](INFO): GPS fix at 20250409T220723: (36.802938, -121.788020) 2025-04-09T22:07:24.515Z,1744236444.515 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:07:24.515Z,1744236444.515 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:07:32.102Z,1744236452.102 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250409T210403/Courier0036.lzma 2025-04-09T22:07:33.105Z,1744236453.105 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0036.lzma.bak 2025-04-09T22:07:33.105Z,1744236453.105 [DataOverHttps](INFO): SBD MOMSN=24600577 2025-04-09T22:07:39.049Z,1744236459.049 [NAL9602](INFO): SBD MO Status=0, MOMSN=63107, MT Status=0, MTMSN=0 2025-04-09T22:07:39.049Z,1744236459.049 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:07:49.299Z,1744236469.299 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250409T210403/Express0037.lzma 2025-04-09T22:07:50.301Z,1744236470.301 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0037.lzma.bak 2025-04-09T22:07:50.301Z,1744236470.301 [DataOverHttps](INFO): SBD MOMSN=24600584 2025-04-09T22:07:51.587Z,1744236471.587 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:07:51.587Z,1744236471.587 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:07:51.587Z,1744236471.587 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:08:09.764Z,1744236489.764 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:09:22.955Z,1744236562.955 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:09:36.678Z,1744236576.678 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:09:50.012Z,1744236590.012 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:10:03.343Z,1744236603.343 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:10:16.717Z,1744236616.717 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:10:30.006Z,1744236630.006 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:10:43.742Z,1744236643.742 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:10:57.893Z,1744236657.893 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:11:11.618Z,1744236671.618 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:11:26.162Z,1744236686.162 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:11:40.303Z,1744236700.303 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:12:52.246Z,1744236772.246 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:12:52.246Z,1744236772.246 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:12:52.246Z,1744236772.246 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:12:52.255Z,1744236772.255 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:12:52.640Z,1744236772.640 [Default:CheckIn:D] Stopped 2025-04-09T22:12:52.640Z,1744236772.640 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:12:53.054Z,1744236773.054 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.979191 min 2025-04-09T22:12:53.054Z,1744236773.054 [Default:CheckIn:E] Stopped 2025-04-09T22:12:53.063Z,1744236773.063 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:12:53.063Z,1744236773.063 [Default:CheckIn] Stopped 2025-04-09T22:12:53.063Z,1744236773.063 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:12:53.063Z,1744236773.063 [Default:CheckIn](INFO): Running loop #17 2025-04-09T22:12:53.063Z,1744236773.063 [Default:CheckIn] Running Loop=17 2025-04-09T22:12:53.063Z,1744236773.063 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:12:53.063Z,1744236773.063 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:12:55.061Z,1744236775.061 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221254.00,A,3648.17837,N,12147.27954,W,0.175,260.92,090425,,,D*72 2025-04-09T22:12:55.063Z,1744236775.063 [NAL9602](INFO): GPS fix at 20250409T221254: (36.802973, -121.787992) 2025-04-09T22:12:55.074Z,1744236775.074 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:12:55.074Z,1744236775.074 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:13:02.902Z,1744236782.902 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T210403/Courier0039.lzma 2025-04-09T22:13:03.904Z,1744236783.904 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0039.lzma.bak 2025-04-09T22:13:03.905Z,1744236783.905 [DataOverHttps](INFO): SBD MOMSN=24600655 2025-04-09T22:13:09.789Z,1744236789.789 [NAL9602](INFO): SBD MO Status=2, MOMSN=63108, MT Status=2, MTMSN=0 2025-04-09T22:13:09.789Z,1744236789.789 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T22:13:19.995Z,1744236799.995 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250409T210403/Express0040.lzma 2025-04-09T22:13:20.997Z,1744236800.997 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0040.lzma.bak 2025-04-09T22:13:20.997Z,1744236800.997 [DataOverHttps](INFO): SBD MOMSN=24600658 2025-04-09T22:13:22.348Z,1744236802.348 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:13:22.348Z,1744236802.348 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:13:22.349Z,1744236802.349 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:13:34.432Z,1744236814.432 [NAL9602](INFO): SBD MO Status=0, MOMSN=63108, MT Status=0, MTMSN=0 2025-04-09T22:13:34.432Z,1744236814.432 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:14:05.129Z,1744236845.129 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:17:32.402Z,1744237052.402 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-04-09T22:18:22.962Z,1744237102.962 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:18:22.962Z,1744237102.962 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:18:22.962Z,1744237102.962 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:18:22.962Z,1744237102.962 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:18:23.336Z,1744237103.336 [Default:CheckIn:D] Stopped 2025-04-09T22:18:23.336Z,1744237103.336 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:18:23.759Z,1744237103.759 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.490788 min 2025-04-09T22:18:23.759Z,1744237103.759 [Default:CheckIn:E] Stopped 2025-04-09T22:18:23.759Z,1744237103.759 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:18:23.759Z,1744237103.759 [Default:CheckIn] Stopped 2025-04-09T22:18:23.759Z,1744237103.759 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:18:23.759Z,1744237103.759 [Default:CheckIn](INFO): Running loop #18 2025-04-09T22:18:23.759Z,1744237103.759 [Default:CheckIn] Running Loop=18 2025-04-09T22:18:23.759Z,1744237103.759 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:18:23.760Z,1744237103.760 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:18:25.749Z,1744237105.749 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221824.00,A,3648.17764,N,12147.28132,W,0.039,260.92,090425,,,D*78 2025-04-09T22:18:25.752Z,1744237105.752 [NAL9602](INFO): GPS fix at 20250409T221824: (36.802961, -121.788022) 2025-04-09T22:18:25.763Z,1744237105.763 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:18:25.763Z,1744237105.763 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:18:33.422Z,1744237113.422 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250409T210403/Courier0042.lzma 2025-04-09T22:18:34.425Z,1744237114.425 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0042.lzma.bak 2025-04-09T22:18:34.425Z,1744237114.425 [DataOverHttps](INFO): SBD MOMSN=24600679 2025-04-09T22:18:52.551Z,1744237132.551 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250409T210403/Express0043.lzma 2025-04-09T22:18:53.553Z,1744237133.553 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0043.lzma.bak 2025-04-09T22:18:53.553Z,1744237133.553 [DataOverHttps](INFO): SBD MOMSN=24600686 2025-04-09T22:18:54.919Z,1744237134.919 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:18:54.919Z,1744237134.919 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:18:54.919Z,1744237134.919 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:19:02.964Z,1744237142.964 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:19:15.082Z,1744237155.082 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:19:27.203Z,1744237167.203 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:19:27.229Z,1744237167.229 [NAL9602](INFO): SBD MO Status=0, MOMSN=63109, MT Status=0, MTMSN=0 2025-04-09T22:19:27.230Z,1744237167.230 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:19:38.918Z,1744237178.918 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:19:39.003Z,1744237179.003 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-09T22:19:43.408Z,1744237183.408 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002422 CHAN A1 (24V): 0.000677 CHAN A2 (12V): -0.002476 CHAN A3 (5V): -0.001666 CHAN B0 (3.3V): -0.000492 CHAN B1 (3.15aV): -0.000647 CHAN B2 (3.15bV): -0.000120 CHAN B3 (GND): -0.000318 OPEN: -0.000507 Full Scale: +/- 1 mA 2025-04-09T22:19:50.635Z,1744237190.635 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:19:57.912Z,1744237197.912 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:20:02.755Z,1744237202.755 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:20:14.875Z,1744237214.875 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:20:26.603Z,1744237226.603 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:20:38.710Z,1744237238.710 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:20:50.839Z,1744237250.839 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:21:02.554Z,1744237262.554 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:21:14.674Z,1744237274.674 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:23:55.494Z,1744237435.494 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:23:55.494Z,1744237435.494 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:23:55.494Z,1744237435.494 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:23:55.494Z,1744237435.494 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:23:55.883Z,1744237435.883 [Default:CheckIn:D] Stopped 2025-04-09T22:23:55.883Z,1744237435.883 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:23:56.291Z,1744237436.291 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.033244 min 2025-04-09T22:23:56.291Z,1744237436.291 [Default:CheckIn:E] Stopped 2025-04-09T22:23:56.291Z,1744237436.291 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:23:56.291Z,1744237436.291 [Default:CheckIn] Stopped 2025-04-09T22:23:56.291Z,1744237436.291 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:23:56.292Z,1744237436.292 [Default:CheckIn](INFO): Running loop #19 2025-04-09T22:23:56.292Z,1744237436.292 [Default:CheckIn] Running Loop=19 2025-04-09T22:23:56.292Z,1744237436.292 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:23:56.292Z,1744237436.292 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:23:58.303Z,1744237438.303 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222357.00,A,3648.17271,N,12147.28389,W,0.078,260.92,090425,,,D*72 2025-04-09T22:23:58.305Z,1744237438.305 [NAL9602](INFO): GPS fix at 20250409T222357: (36.802878, -121.788065) 2025-04-09T22:23:58.316Z,1744237438.316 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:23:58.316Z,1744237438.316 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:24:05.670Z,1744237445.670 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T210403/Courier0045.lzma 2025-04-09T22:24:06.672Z,1744237446.672 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0045.lzma.bak 2025-04-09T22:24:06.673Z,1744237446.673 [DataOverHttps](INFO): SBD MOMSN=24600766 2025-04-09T22:24:11.633Z,1744237451.633 [NAL9602](INFO): SBD MO Status=0, MOMSN=63110, MT Status=0, MTMSN=0 2025-04-09T22:24:11.633Z,1744237451.633 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:24:25.716Z,1744237465.716 [DataOverHttps](INFO): Sending 351 bytes from file Logs/20250409T210403/Express0046.lzma 2025-04-09T22:24:26.717Z,1744237466.717 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0046.lzma.bak 2025-04-09T22:24:26.717Z,1744237466.717 [DataOverHttps](INFO): SBD MOMSN=24600770 2025-04-09T22:24:28.263Z,1744237468.263 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:24:28.263Z,1744237468.263 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:24:28.263Z,1744237468.263 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:24:42.384Z,1744237482.384 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:25:50.254Z,1744237550.254 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4. 2025-04-09T22:25:50.258Z,1744237550.258 [BPC1](FAULT): Failed to receive data from 11 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 34, 35, 36, 37, 38, 39, 50, 51, 54. 2025-04-09T22:29:28.867Z,1744237768.867 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:29:28.867Z,1744237768.867 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:29:28.867Z,1744237768.867 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:29:28.868Z,1744237768.868 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:29:29.254Z,1744237769.254 [Default:CheckIn:D] Stopped 2025-04-09T22:29:29.254Z,1744237769.254 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:29:29.656Z,1744237769.656 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.589421 min 2025-04-09T22:29:29.656Z,1744237769.656 [Default:CheckIn:E] Stopped 2025-04-09T22:29:29.656Z,1744237769.656 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:29:29.656Z,1744237769.656 [Default:CheckIn] Stopped 2025-04-09T22:29:29.656Z,1744237769.656 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:29:29.656Z,1744237769.656 [Default:CheckIn](INFO): Running loop #20 2025-04-09T22:29:29.656Z,1744237769.656 [Default:CheckIn] Running Loop=20 2025-04-09T22:29:29.657Z,1744237769.657 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:29:29.657Z,1744237769.657 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:29:31.675Z,1744237771.675 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222930.00,A,3648.17455,N,12147.27920,W,0.097,260.92,090425,,,D*7E 2025-04-09T22:29:31.677Z,1744237771.677 [NAL9602](INFO): GPS fix at 20250409T222930: (36.802909, -121.787987) 2025-04-09T22:29:31.688Z,1744237771.688 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:29:31.688Z,1744237771.688 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:29:39.034Z,1744237779.034 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250409T210403/Courier0048.lzma 2025-04-09T22:29:40.037Z,1744237780.037 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0048.lzma.bak 2025-04-09T22:29:40.037Z,1744237780.037 [DataOverHttps](INFO): SBD MOMSN=24600792 2025-04-09T22:29:56.039Z,1744237796.039 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20250409T210403/Express0049.lzma 2025-04-09T22:29:57.041Z,1744237797.041 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0049.lzma.bak 2025-04-09T22:29:57.041Z,1744237797.041 [DataOverHttps](INFO): SBD MOMSN=24600835 2025-04-09T22:29:58.340Z,1744237798.340 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:29:58.340Z,1744237798.340 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:29:58.340Z,1744237798.340 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:29:59.541Z,1744237799.541 [NAL9602](INFO): SBD MO Status=0, MOMSN=63111, MT Status=0, MTMSN=0 2025-04-09T22:29:59.541Z,1744237799.541 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:30:30.260Z,1744237830.260 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:34:58.935Z,1744238098.935 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:34:58.935Z,1744238098.935 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:34:58.935Z,1744238098.935 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:34:58.936Z,1744238098.936 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:34:59.343Z,1744238099.343 [Default:CheckIn:D] Stopped 2025-04-09T22:34:59.343Z,1744238099.343 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:34:59.740Z,1744238099.740 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.090902 min 2025-04-09T22:34:59.740Z,1744238099.740 [Default:CheckIn:E] Stopped 2025-04-09T22:34:59.740Z,1744238099.740 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:34:59.740Z,1744238099.740 [Default:CheckIn] Stopped 2025-04-09T22:34:59.740Z,1744238099.740 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:34:59.740Z,1744238099.740 [Default:CheckIn](INFO): Running loop #21 2025-04-09T22:34:59.740Z,1744238099.740 [Default:CheckIn] Running Loop=21 2025-04-09T22:34:59.740Z,1744238099.740 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:34:59.740Z,1744238099.740 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:35:01.749Z,1744238101.749 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223500.00,A,3648.17578,N,12147.27581,W,0.097,0.00,090425,,,D*76 2025-04-09T22:35:01.752Z,1744238101.752 [NAL9602](INFO): GPS fix at 20250409T223500: (36.802930, -121.787930) 2025-04-09T22:35:01.763Z,1744238101.763 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:35:01.763Z,1744238101.763 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:35:10.502Z,1744238110.502 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250409T210403/Courier0051.lzma 2025-04-09T22:35:11.505Z,1744238111.505 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0051.lzma.bak 2025-04-09T22:35:11.505Z,1744238111.505 [DataOverHttps](INFO): SBD MOMSN=24600876 2025-04-09T22:35:21.947Z,1744238121.947 [NAL9602](INFO): SBD MO Status=2, MOMSN=63112, MT Status=2, MTMSN=0 2025-04-09T22:35:21.947Z,1744238121.947 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T22:35:27.546Z,1744238127.546 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20250409T210403/Express0052.lzma 2025-04-09T22:35:28.549Z,1744238128.549 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0052.lzma.bak 2025-04-09T22:35:28.549Z,1744238128.549 [DataOverHttps](INFO): SBD MOMSN=24600879 2025-04-09T22:35:30.038Z,1744238130.038 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:35:30.038Z,1744238130.038 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:35:30.038Z,1744238130.038 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:37:38.971Z,1744238258.971 [NAL9602](INFO): SBD MO Status=2, MOMSN=63112, MT Status=2, MTMSN=0 2025-04-09T22:37:38.972Z,1744238258.972 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T22:37:55.474Z,1744238275.474 [NAL9602](INFO): SBD MO Status=0, MOMSN=63112, MT Status=0, MTMSN=0 2025-04-09T22:37:55.474Z,1744238275.474 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:38:19.325Z,1744238299.325 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-04-09T22:38:24.983Z,1744238304.983 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-04-09T22:38:26.172Z,1744238306.172 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:40:30.626Z,1744238430.626 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:40:30.626Z,1744238430.626 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:40:30.626Z,1744238430.626 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:40:30.626Z,1744238430.626 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:40:31.035Z,1744238431.035 [Default:CheckIn:D] Stopped 2025-04-09T22:40:31.036Z,1744238431.036 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:40:31.434Z,1744238431.434 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 119.619124 min 2025-04-09T22:40:31.450Z,1744238431.450 [Default:CheckIn:E] Stopped 2025-04-09T22:40:31.451Z,1744238431.451 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:40:31.451Z,1744238431.451 [Default:CheckIn] Stopped 2025-04-09T22:40:31.451Z,1744238431.451 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:40:31.451Z,1744238431.451 [Default:CheckIn](INFO): Running loop #22 2025-04-09T22:40:31.451Z,1744238431.451 [Default:CheckIn] Running Loop=22 2025-04-09T22:40:31.451Z,1744238431.451 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:40:31.451Z,1744238431.451 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:40:33.446Z,1744238433.446 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224032.00,A,3648.18034,N,12147.27755,W,0.253,169.77,090425,,,A*7D 2025-04-09T22:40:33.448Z,1744238433.448 [NAL9602](INFO): GPS fix at 20250409T224032: (36.803006, -121.787959) 2025-04-09T22:40:33.460Z,1744238433.460 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:40:33.460Z,1744238433.460 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:40:40.722Z,1744238440.722 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250409T210403/Courier0054.lzma 2025-04-09T22:40:41.725Z,1744238441.725 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0054.lzma.bak 2025-04-09T22:40:41.725Z,1744238441.725 [DataOverHttps](INFO): SBD MOMSN=24600901 2025-04-09T22:40:52.075Z,1744238452.075 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:40:54.902Z,1744238454.902 [NAL9602](INFO): SBD MO Status=0, MOMSN=63113, MT Status=0, MTMSN=0 2025-04-09T22:40:54.902Z,1744238454.902 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:41:01.635Z,1744238461.635 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250409T210403/Express0055.lzma 2025-04-09T22:41:02.637Z,1744238462.637 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0055.lzma.bak 2025-04-09T22:41:02.637Z,1744238462.637 [DataOverHttps](INFO): SBD MOMSN=24600937 2025-04-09T22:41:03.815Z,1744238463.815 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:41:03.816Z,1744238463.816 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:41:03.816Z,1744238463.816 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:41:05.411Z,1744238465.411 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:41:18.743Z,1744238478.743 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:41:25.613Z,1744238485.613 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:41:32.479Z,1744238492.479 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:41:45.816Z,1744238505.816 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:41:59.145Z,1744238519.145 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:42:12.474Z,1744238532.474 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:42:25.806Z,1744238545.806 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:42:39.141Z,1744238559.141 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:42:53.280Z,1744238573.280 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:43:06.610Z,1744238586.610 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:46:04.396Z,1744238764.396 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:46:04.396Z,1744238764.396 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:46:04.396Z,1744238764.396 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:46:04.396Z,1744238764.396 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:46:04.806Z,1744238764.806 [Default:CheckIn:D] Stopped 2025-04-09T22:46:04.806Z,1744238764.806 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:46:05.211Z,1744238765.211 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.181958 min 2025-04-09T22:46:05.211Z,1744238765.211 [Default:CheckIn:E] Stopped 2025-04-09T22:46:05.211Z,1744238765.211 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:46:05.211Z,1744238765.211 [Default:CheckIn] Stopped 2025-04-09T22:46:05.211Z,1744238765.211 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:46:05.211Z,1744238765.211 [Default:CheckIn](INFO): Running loop #23 2025-04-09T22:46:05.211Z,1744238765.211 [Default:CheckIn] Running Loop=23 2025-04-09T22:46:05.211Z,1744238765.211 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:46:05.212Z,1744238765.212 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:46:07.221Z,1744238767.221 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224606.00,A,3648.17498,N,12147.27617,W,0.019,169.77,090425,,,A*7A 2025-04-09T22:46:07.224Z,1744238767.224 [NAL9602](INFO): GPS fix at 20250409T224606: (36.802916, -121.787936) 2025-04-09T22:46:07.252Z,1744238767.252 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:46:07.252Z,1744238767.252 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:46:15.398Z,1744238775.398 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T210403/Courier0057.lzma 2025-04-09T22:46:16.401Z,1744238776.401 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0057.lzma.bak 2025-04-09T22:46:16.401Z,1744238776.401 [DataOverHttps](INFO): SBD MOMSN=24600973 2025-04-09T22:46:37.586Z,1744238797.586 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250409T210403/Express0058.lzma 2025-04-09T22:46:38.589Z,1744238798.589 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0058.lzma.bak 2025-04-09T22:46:38.589Z,1744238798.589 [DataOverHttps](INFO): SBD MOMSN=24600976 2025-04-09T22:46:40.031Z,1744238800.031 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:46:40.031Z,1744238800.031 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:46:40.032Z,1744238800.032 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:47:26.051Z,1744238846.051 [NAL9602](INFO): SBD MO Status=2, MOMSN=63114, MT Status=2, MTMSN=0 2025-04-09T22:47:26.051Z,1744238846.051 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T22:48:28.267Z,1744238908.267 [NAL9602](INFO): SBD MO Status=0, MOMSN=63114, MT Status=0, MTMSN=0 2025-04-09T22:48:28.268Z,1744238908.268 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:48:59.008Z,1744238939.008 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:50:25.495Z,1744239025.495 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:50:39.635Z,1744239039.635 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:50:53.830Z,1744239053.830 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:51:07.977Z,1744239067.977 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:51:21.414Z,1744239081.414 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:51:35.103Z,1744239095.103 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:51:40.706Z,1744239100.706 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:51:40.706Z,1744239100.706 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:51:40.706Z,1744239100.706 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:51:40.706Z,1744239100.706 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:51:41.113Z,1744239101.113 [Default:CheckIn:D] Stopped 2025-04-09T22:51:41.113Z,1744239101.113 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:51:41.526Z,1744239101.526 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 130.787085 min 2025-04-09T22:51:41.526Z,1744239101.526 [Default:CheckIn:E] Stopped 2025-04-09T22:51:41.526Z,1744239101.526 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:51:41.526Z,1744239101.526 [Default:CheckIn] Stopped 2025-04-09T22:51:41.526Z,1744239101.526 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:51:41.526Z,1744239101.526 [Default:CheckIn](INFO): Running loop #24 2025-04-09T22:51:41.526Z,1744239101.526 [Default:CheckIn] Running Loop=24 2025-04-09T22:51:41.527Z,1744239101.527 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:51:41.527Z,1744239101.527 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:51:43.526Z,1744239103.526 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225142.00,A,3648.18037,N,12147.27679,W,0.097,20.23,090425,,,A*41 2025-04-09T22:51:43.529Z,1744239103.529 [NAL9602](INFO): GPS fix at 20250409T225142: (36.803006, -121.787947) 2025-04-09T22:51:43.556Z,1744239103.556 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:51:43.556Z,1744239103.556 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:51:48.582Z,1744239108.582 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:51:50.718Z,1744239110.718 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T210403/Courier0060.lzma 2025-04-09T22:51:51.721Z,1744239111.721 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0060.lzma.bak 2025-04-09T22:51:51.721Z,1744239111.721 [DataOverHttps](INFO): SBD MOMSN=24601033 2025-04-09T22:51:52.918Z,1744239112.918 [NAL9602](INFO): SBD MO Status=0, MOMSN=63115, MT Status=0, MTMSN=0 2025-04-09T22:51:52.929Z,1744239112.929 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:52:02.210Z,1744239122.210 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:52:07.999Z,1744239127.999 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250409T210403/Express0061.lzma 2025-04-09T22:52:09.001Z,1744239129.001 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0061.lzma.bak 2025-04-09T22:52:09.001Z,1744239129.001 [DataOverHttps](INFO): SBD MOMSN=24601036 2025-04-09T22:52:10.301Z,1744239130.301 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:52:10.301Z,1744239130.301 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:52:10.301Z,1744239130.301 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:52:11.501Z,1744239131.501 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-09T22:52:11.501Z,1744239131.501 [DropWeight] Hardware Fault, FailCount= 1 2025-04-09T22:52:11.501Z,1744239131.501 [DropWeight](ERROR): Hardware Fault 2025-04-09T22:52:11.552Z,1744239131.552 [CommandExec](FAULT): Scheduling is paused 2025-04-09T22:52:11.552Z,1744239131.552 [CBIT](INFO): Critical error at 20250409T225211 2025-04-09T22:52:11.552Z,1744239131.552 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-04-09T22:52:11.559Z,1744239131.559 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-09T22:52:11.560Z,1744239131.560 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-09T22:52:11.945Z,1744239131.945 [CBIT](INFO): Critical error at 20250409T225211 2025-04-09T22:52:15.951Z,1744239135.951 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:52:23.616Z,1744239143.616 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:52:29.292Z,1744239149.292 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:52:42.602Z,1744239162.602 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T22:57:11.035Z,1744239431.035 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:57:11.035Z,1744239431.035 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:57:11.035Z,1744239431.035 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:57:11.036Z,1744239431.036 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:57:11.454Z,1744239431.454 [Default:CheckIn:D] Stopped 2025-04-09T22:57:11.454Z,1744239431.454 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:57:11.842Z,1744239431.842 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.292757 min 2025-04-09T22:57:11.842Z,1744239431.842 [Default:CheckIn:E] Stopped 2025-04-09T22:57:11.842Z,1744239431.842 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:57:11.842Z,1744239431.842 [Default:CheckIn] Stopped 2025-04-09T22:57:11.842Z,1744239431.842 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:57:11.843Z,1744239431.843 [Default:CheckIn](INFO): Running loop #25 2025-04-09T22:57:11.843Z,1744239431.843 [Default:CheckIn] Running Loop=25 2025-04-09T22:57:11.843Z,1744239431.843 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:57:11.843Z,1744239431.843 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:57:13.853Z,1744239433.853 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225712.00,A,3648.17815,N,12147.27725,W,0.000,20.23,090425,,,A*43 2025-04-09T22:57:13.856Z,1744239433.856 [NAL9602](INFO): GPS fix at 20250409T225712: (36.802969, -121.787954) 2025-04-09T22:57:13.867Z,1744239433.867 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:57:13.867Z,1744239433.867 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:57:21.155Z,1744239441.155 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20250409T210403/Courier0063.lzma 2025-04-09T22:57:22.156Z,1744239442.156 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0063.lzma.bak 2025-04-09T22:57:22.157Z,1744239442.157 [DataOverHttps](INFO): SBD MOMSN=24601077 2025-04-09T22:57:35.261Z,1744239455.261 [NAL9602](INFO): SBD MO Status=2, MOMSN=63116, MT Status=2, MTMSN=0 2025-04-09T22:57:35.261Z,1744239455.261 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T22:57:38.231Z,1744239458.231 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20250409T210403/Express0064.lzma 2025-04-09T22:57:39.233Z,1744239459.233 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0064.lzma.bak 2025-04-09T22:57:39.233Z,1744239459.233 [DataOverHttps](INFO): SBD MOMSN=24601082 2025-04-09T22:57:40.531Z,1744239460.531 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:57:40.531Z,1744239460.531 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:57:40.532Z,1744239460.532 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:57:57.497Z,1744239477.497 [NAL9602](INFO): SBD MO Status=0, MOMSN=63116, MT Status=0, MTMSN=0 2025-04-09T22:57:57.497Z,1744239477.497 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:58:28.186Z,1744239508.186 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:59:28.785Z,1744239568.785 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-04-09T23:00:01.911Z,1744239601.911 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:00:16.051Z,1744239616.051 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:00:29.407Z,1744239629.407 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:00:43.144Z,1744239643.144 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:00:57.303Z,1744239657.303 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:01:10.630Z,1744239670.630 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:01:24.772Z,1744239684.772 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:01:38.102Z,1744239698.102 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:01:51.438Z,1744239711.438 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:01:58.364Z,1744239718.364 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-04-09T23:02:04.773Z,1744239724.773 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:02:18.098Z,1744239738.098 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:02:41.217Z,1744239761.217 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:02:41.218Z,1744239761.218 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:02:41.218Z,1744239761.218 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:02:41.218Z,1744239761.218 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:02:41.623Z,1744239761.623 [Default:CheckIn:D] Stopped 2025-04-09T23:02:41.623Z,1744239761.623 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:02:42.012Z,1744239762.012 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 141.795589 min 2025-04-09T23:02:42.012Z,1744239762.012 [Default:CheckIn:E] Stopped 2025-04-09T23:02:42.012Z,1744239762.012 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:02:42.012Z,1744239762.012 [Default:CheckIn] Stopped 2025-04-09T23:02:42.012Z,1744239762.012 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:02:42.012Z,1744239762.012 [Default:CheckIn](INFO): Running loop #26 2025-04-09T23:02:42.012Z,1744239762.012 [Default:CheckIn] Running Loop=26 2025-04-09T23:02:42.012Z,1744239762.012 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:02:42.013Z,1744239762.013 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:02:44.037Z,1744239764.037 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230243.00,A,3648.18271,N,12147.27567,W,0.078,20.23,090425,,,A*4A 2025-04-09T23:02:44.040Z,1744239764.040 [NAL9602](INFO): GPS fix at 20250409T230243: (36.803045, -121.787928) 2025-04-09T23:02:44.050Z,1744239764.050 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:02:44.050Z,1744239764.050 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:02:55.030Z,1744239775.030 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T210403/Courier0066.lzma 2025-04-09T23:02:56.034Z,1744239776.034 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0066.lzma.bak 2025-04-09T23:02:56.034Z,1744239776.034 [DataOverHttps](INFO): SBD MOMSN=24601145 2025-04-09T23:03:12.367Z,1744239792.367 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250409T210403/Express0067.lzma 2025-04-09T23:03:13.370Z,1744239793.370 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0067.lzma.bak 2025-04-09T23:03:13.370Z,1744239793.370 [DataOverHttps](INFO): SBD MOMSN=24601149 2025-04-09T23:03:14.802Z,1744239794.802 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:03:14.802Z,1744239794.802 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:03:14.802Z,1744239794.802 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:04:30.014Z,1744239870.014 [NAL9602](INFO): SBD MO Status=2, MOMSN=63117, MT Status=2, MTMSN=0 2025-04-09T23:04:30.015Z,1744239870.015 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T23:05:51.677Z,1744239951.677 [NAL9602](INFO): SBD MO Status=0, MOMSN=63117, MT Status=0, MTMSN=0 2025-04-09T23:05:51.677Z,1744239951.677 [NAL9602](INFO): No messages in MT queue 2025-04-09T23:06:22.411Z,1744239982.411 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T23:06:52.348Z,1744240012.348 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4. 2025-04-09T23:06:52.352Z,1744240012.352 [BPC1](FAULT): Failed to receive data from 11 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 34, 35, 36, 37, 38, 39, 50, 51, 54. 2025-04-09T23:07:11.812Z,1744240031.812 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-09T23:07:11.812Z,1744240031.812 [DropWeight] No Fault, FailCount= 1 2025-04-09T23:08:15.227Z,1744240095.227 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:08:15.227Z,1744240095.227 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:08:15.227Z,1744240095.227 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:08:15.227Z,1744240095.227 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:08:15.626Z,1744240095.626 [Default:CheckIn:D] Stopped 2025-04-09T23:08:15.626Z,1744240095.626 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:08:16.030Z,1744240096.030 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.362288 min 2025-04-09T23:08:16.030Z,1744240096.030 [Default:CheckIn:E] Stopped 2025-04-09T23:08:16.031Z,1744240096.031 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:08:16.031Z,1744240096.031 [Default:CheckIn] Stopped 2025-04-09T23:08:16.031Z,1744240096.031 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:08:16.031Z,1744240096.031 [Default:CheckIn](INFO): Running loop #27 2025-04-09T23:08:16.031Z,1744240096.031 [Default:CheckIn] Running Loop=27 2025-04-09T23:08:16.031Z,1744240096.031 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:08:16.031Z,1744240096.031 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:08:18.043Z,1744240098.043 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230817.00,A,3648.18553,N,12147.27243,W,0.078,20.23,090425,,,A*47 2025-04-09T23:08:18.045Z,1744240098.045 [NAL9602](INFO): GPS fix at 20250409T230817: (36.803092, -121.787874) 2025-04-09T23:08:18.064Z,1744240098.064 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:08:18.064Z,1744240098.064 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:08:28.855Z,1744240108.855 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250409T210403/Courier0069.lzma 2025-04-09T23:08:29.857Z,1744240109.857 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0069.lzma.bak 2025-04-09T23:08:29.857Z,1744240109.857 [DataOverHttps](INFO): SBD MOMSN=24601214 2025-04-09T23:08:41.555Z,1744240121.555 [NAL9602](INFO): SBD MO Status=2, MOMSN=63118, MT Status=2, MTMSN=0 2025-04-09T23:08:41.555Z,1744240121.555 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T23:08:46.051Z,1744240126.051 [DataOverHttps](INFO): Sending 274 bytes from file Logs/20250409T210403/Express0070.lzma 2025-04-09T23:08:47.060Z,1744240127.060 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0070.lzma.bak 2025-04-09T23:08:47.061Z,1744240127.061 [DataOverHttps](INFO): SBD MOMSN=24601217 2025-04-09T23:08:48.427Z,1744240128.427 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:08:48.427Z,1744240128.427 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:08:48.428Z,1744240128.428 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:09:41.805Z,1744240181.805 [NAL9602](INFO): SBD MO Status=2, MOMSN=63118, MT Status=2, MTMSN=0 2025-04-09T23:09:41.806Z,1744240181.806 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T23:10:17.337Z,1744240217.337 [NAL9602](INFO): SBD MO Status=0, MOMSN=63118, MT Status=0, MTMSN=0 2025-04-09T23:10:17.337Z,1744240217.337 [NAL9602](INFO): No messages in MT queue 2025-04-09T23:10:48.110Z,1744240248.110 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T23:13:49.165Z,1744240429.165 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:13:49.166Z,1744240429.166 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:13:49.166Z,1744240429.166 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:13:49.166Z,1744240429.166 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:13:49.579Z,1744240429.579 [Default:CheckIn:D] Stopped 2025-04-09T23:13:49.579Z,1744240429.579 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:13:49.988Z,1744240429.988 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 152.928174 min 2025-04-09T23:13:49.989Z,1744240429.989 [Default:CheckIn:E] Stopped 2025-04-09T23:13:49.989Z,1744240429.989 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:13:49.989Z,1744240429.989 [Default:CheckIn] Stopped 2025-04-09T23:13:49.989Z,1744240429.989 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:13:49.989Z,1744240429.989 [Default:CheckIn](INFO): Running loop #28 2025-04-09T23:13:49.989Z,1744240429.989 [Default:CheckIn] Running Loop=28 2025-04-09T23:13:49.989Z,1744240429.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:13:49.989Z,1744240429.989 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:13:51.989Z,1744240431.989 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231351.00,A,3648.17753,N,12147.27584,W,0.039,256.60,090425,,,A*7F 2025-04-09T23:13:51.992Z,1744240431.992 [NAL9602](INFO): GPS fix at 20250409T231351: (36.802959, -121.787931) 2025-04-09T23:13:52.003Z,1744240432.003 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:13:52.003Z,1744240432.003 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:13:59.798Z,1744240439.798 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T210403/Courier0072.lzma 2025-04-09T23:14:00.801Z,1744240440.801 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0072.lzma.bak 2025-04-09T23:14:00.801Z,1744240440.801 [DataOverHttps](INFO): SBD MOMSN=24601276 2025-04-09T23:14:16.758Z,1744240456.758 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250409T210403/Express0073.lzma 2025-04-09T23:14:17.761Z,1744240457.761 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0073.lzma.bak 2025-04-09T23:14:17.761Z,1744240457.761 [DataOverHttps](INFO): SBD MOMSN=24601279 2025-04-09T23:14:19.123Z,1744240459.123 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:14:19.123Z,1744240459.123 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:14:19.124Z,1744240459.124 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:14:38.129Z,1744240478.129 [NAL9602](INFO): SBD MO Status=0, MOMSN=63119, MT Status=0, MTMSN=0 2025-04-09T23:14:38.129Z,1744240478.129 [NAL9602](INFO): No messages in MT queue 2025-04-09T23:15:08.892Z,1744240508.892 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T23:17:58.658Z,1744240678.658 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:18:10.375Z,1744240690.375 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:18:22.499Z,1744240702.499 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:18:34.210Z,1744240714.210 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:18:45.522Z,1744240725.522 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:18:56.835Z,1744240736.835 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:19:08.154Z,1744240748.154 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:19:19.458Z,1744240759.458 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:19:19.502Z,1744240759.502 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:19:19.502Z,1744240759.502 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:19:19.502Z,1744240759.502 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:19:19.503Z,1744240759.503 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:19:19.894Z,1744240759.894 [Default:CheckIn:D] Stopped 2025-04-09T23:19:19.894Z,1744240759.894 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:19:20.298Z,1744240760.298 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 158.433431 min 2025-04-09T23:19:20.298Z,1744240760.298 [Default:CheckIn:E] Stopped 2025-04-09T23:19:20.298Z,1744240760.298 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:19:20.298Z,1744240760.298 [Default:CheckIn] Stopped 2025-04-09T23:19:20.298Z,1744240760.298 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:19:20.299Z,1744240760.299 [Default:CheckIn](INFO): Running loop #29 2025-04-09T23:19:20.299Z,1744240760.299 [Default:CheckIn] Running Loop=29 2025-04-09T23:19:20.299Z,1744240760.299 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:19:20.299Z,1744240760.299 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:19:22.300Z,1744240762.300 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231921.00,A,3648.16520,N,12147.28380,W,0.058,308.88,090425,,,A*73 2025-04-09T23:19:22.302Z,1744240762.302 [NAL9602](INFO): GPS fix at 20250409T231921: (36.802753, -121.788063) 2025-04-09T23:19:22.313Z,1744240762.313 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:19:22.313Z,1744240762.313 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:19:30.294Z,1744240770.294 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T210403/Courier0075.lzma 2025-04-09T23:19:31.297Z,1744240771.297 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0075.lzma.bak 2025-04-09T23:19:31.297Z,1744240771.297 [DataOverHttps](INFO): SBD MOMSN=24601319 2025-04-09T23:19:47.431Z,1744240787.431 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20250409T210403/Express0076.lzma 2025-04-09T23:19:48.433Z,1744240788.433 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0076.lzma.bak 2025-04-09T23:19:48.433Z,1744240788.433 [DataOverHttps](INFO): SBD MOMSN=24601327 2025-04-09T23:19:49.792Z,1744240789.792 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:19:49.792Z,1744240789.792 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:19:49.792Z,1744240789.792 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:21:53.472Z,1744240913.472 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:22:06.804Z,1744240926.804 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:22:20.135Z,1744240940.135 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:22:33.486Z,1744240953.486 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:22:47.224Z,1744240967.224 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:23:00.571Z,1744240980.571 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:23:14.308Z,1744240994.308 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:23:27.638Z,1744241007.638 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:23:40.569Z,1744241020.569 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:23:53.903Z,1744241033.903 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:24:02.047Z,1744241042.047 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-04-09T23:24:07.235Z,1744241047.235 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-04-09T23:24:13.705Z,1744241053.705 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-04-09T23:24:13.708Z,1744241053.708 [BPC1](INFO): Received data from all battery sticks. 2025-04-09T23:24:24.621Z,1744241064.621 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-09T23:24:50.498Z,1744241090.498 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:24:50.499Z,1744241090.499 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:24:50.499Z,1744241090.499 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:24:50.499Z,1744241090.499 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:24:50.906Z,1744241090.906 [Default:CheckIn:D] Stopped 2025-04-09T23:24:50.906Z,1744241090.906 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:24:51.318Z,1744241091.318 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 163.950293 min 2025-04-09T23:24:51.327Z,1744241091.327 [Default:CheckIn:E] Stopped 2025-04-09T23:24:51.327Z,1744241091.327 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:24:51.327Z,1744241091.327 [Default:CheckIn] Stopped 2025-04-09T23:24:51.327Z,1744241091.327 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:24:51.327Z,1744241091.327 [Default:CheckIn](INFO): Running loop #30 2025-04-09T23:24:51.327Z,1744241091.327 [Default:CheckIn] Running Loop=30 2025-04-09T23:24:51.327Z,1744241091.327 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:24:51.327Z,1744241091.327 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:24:53.306Z,1744241093.306 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232452.00,A,3648.16809,N,12147.29018,W,0.875,189.15,090425,,,A*74 2025-04-09T23:24:53.308Z,1744241093.308 [NAL9602](INFO): GPS fix at 20250409T232452: (36.802802, -121.788170) 2025-04-09T23:24:53.327Z,1744241093.327 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:24:53.327Z,1744241093.327 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:25:02.154Z,1744241102.154 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20250409T210403/Courier0078.lzma 2025-04-09T23:25:03.157Z,1744241103.157 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0078.lzma.bak 2025-04-09T23:25:03.157Z,1744241103.157 [DataOverHttps](INFO): SBD MOMSN=24601378 2025-04-09T23:25:19.195Z,1744241119.195 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20250409T210403/Express0079.lzma 2025-04-09T23:25:20.197Z,1744241120.197 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0079.lzma.bak 2025-04-09T23:25:20.197Z,1744241120.197 [DataOverHttps](INFO): SBD MOMSN=24601381 2025-04-09T23:25:21.618Z,1744241121.618 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:25:21.618Z,1744241121.618 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:25:21.618Z,1744241121.618 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:25:25.640Z,1744241125.640 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T23:30:22.204Z,1744241422.204 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:30:22.204Z,1744241422.204 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:30:22.204Z,1744241422.204 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:30:22.204Z,1744241422.204 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:30:22.610Z,1744241422.610 [Default:CheckIn:D] Stopped 2025-04-09T23:30:22.611Z,1744241422.611 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:30:23.014Z,1744241423.014 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 169.478695 min 2025-04-09T23:30:23.014Z,1744241423.014 [Default:CheckIn:E] Stopped 2025-04-09T23:30:23.015Z,1744241423.015 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:30:23.015Z,1744241423.015 [Default:CheckIn] Stopped 2025-04-09T23:30:23.015Z,1744241423.015 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:30:23.015Z,1744241423.015 [Default:CheckIn](INFO): Running loop #31 2025-04-09T23:30:23.015Z,1744241423.015 [Default:CheckIn] Running Loop=31 2025-04-09T23:30:23.015Z,1744241423.015 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:30:23.015Z,1744241423.015 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:30:25.014Z,1744241425.014 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233024.00,A,3648.16922,N,12147.26931,W,1.244,112.17,090425,,,A*7C 2025-04-09T23:30:25.016Z,1744241425.016 [NAL9602](INFO): GPS fix at 20250409T233024: (36.802820, -121.787822) 2025-04-09T23:30:25.064Z,1744241425.064 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:30:25.064Z,1744241425.064 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:30:32.926Z,1744241432.926 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250409T210403/Courier0081.lzma 2025-04-09T23:30:33.929Z,1744241433.929 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0081.lzma.bak 2025-04-09T23:30:33.929Z,1744241433.929 [DataOverHttps](INFO): SBD MOMSN=24601425 2025-04-09T23:30:51.656Z,1744241451.656 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20250409T210403/Express0082.lzma 2025-04-09T23:30:52.657Z,1744241452.657 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0082.lzma.bak 2025-04-09T23:30:52.657Z,1744241452.657 [DataOverHttps](INFO): SBD MOMSN=24601428 2025-04-09T23:30:54.156Z,1744241454.156 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:30:54.157Z,1744241454.157 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:30:54.157Z,1744241454.157 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:30:55.761Z,1744241455.761 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-09T23:30:55.855Z,1744241455.855 [NAL9602](ERROR): received: +CSQ:0 OK119, 0, 0, 0, 0 OK 2025-04-09T23:35:27.733Z,1744241727.733 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-09T23:35:54.770Z,1744241754.770 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:35:54.770Z,1744241754.770 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:35:54.770Z,1744241754.770 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:35:54.771Z,1744241754.771 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:35:55.182Z,1744241755.182 [Default:CheckIn:D] Stopped 2025-04-09T23:35:55.182Z,1744241755.182 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:35:55.580Z,1744241755.580 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 175.021566 min 2025-04-09T23:35:55.580Z,1744241755.580 [Default:CheckIn:E] Stopped 2025-04-09T23:35:55.580Z,1744241755.580 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:35:55.580Z,1744241755.580 [Default:CheckIn] Stopped 2025-04-09T23:35:55.580Z,1744241755.580 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:35:55.581Z,1744241755.581 [Default:CheckIn](INFO): Running loop #32 2025-04-09T23:35:55.581Z,1744241755.581 [Default:CheckIn] Running Loop=32 2025-04-09T23:35:55.581Z,1744241755.581 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:35:55.581Z,1744241755.581 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:35:57.590Z,1744241757.590 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233556.00,A,3648.17356,N,12147.27376,W,0.214,160.55,090425,,,A*7B 2025-04-09T23:35:57.592Z,1744241757.592 [NAL9602](INFO): GPS fix at 20250409T233556: (36.802893, -121.787896) 2025-04-09T23:35:57.623Z,1744241757.623 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:35:57.623Z,1744241757.623 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:36:05.678Z,1744241765.678 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T210403/Courier0084.lzma 2025-04-09T23:36:06.683Z,1744241766.683 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0084.lzma.bak 2025-04-09T23:36:06.684Z,1744241766.684 [DataOverHttps](INFO): SBD MOMSN=24601476 2025-04-09T23:36:25.591Z,1744241785.591 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250409T210403/Express0085.lzma 2025-04-09T23:36:26.593Z,1744241786.593 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0085.lzma.bak 2025-04-09T23:36:26.593Z,1744241786.593 [DataOverHttps](INFO): SBD MOMSN=24601504 2025-04-09T23:36:27.995Z,1744241787.995 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:36:27.996Z,1744241787.996 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:36:27.996Z,1744241787.996 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:36:29.584Z,1744241789.584 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T23:37:13.220Z,1744241833.220 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-09T23:37:13.220Z,1744241833.220 [DropWeight] Hardware Fault, FailCount= 1 2025-04-09T23:37:13.220Z,1744241833.220 [DropWeight](ERROR): Hardware Fault 2025-04-09T23:37:13.260Z,1744241833.260 [CBIT](INFO): Critical error at 20250409T233713 2025-04-09T23:37:13.264Z,1744241833.264 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-09T23:37:13.265Z,1744241833.265 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-09T23:37:13.638Z,1744241833.638 [CBIT](INFO): Critical error at 20250409T233713 2025-04-09T23:41:28.566Z,1744242088.566 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:41:28.566Z,1744242088.566 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:41:28.566Z,1744242088.566 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:41:28.566Z,1744242088.566 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:41:28.963Z,1744242088.963 [Default:CheckIn:D] Stopped 2025-04-09T23:41:28.963Z,1744242088.963 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:41:29.374Z,1744242089.374 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 180.584587 min 2025-04-09T23:41:29.374Z,1744242089.374 [Default:CheckIn:E] Stopped 2025-04-09T23:41:29.374Z,1744242089.374 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:41:29.374Z,1744242089.374 [Default:CheckIn] Stopped 2025-04-09T23:41:29.374Z,1744242089.374 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:41:29.374Z,1744242089.374 [Default:CheckIn](INFO): Running loop #33 2025-04-09T23:41:29.374Z,1744242089.374 [Default:CheckIn] Running Loop=33 2025-04-09T23:41:29.375Z,1744242089.375 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:41:29.375Z,1744242089.375 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:41:31.373Z,1744242091.373 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234130.00,A,3648.16994,N,12147.27567,W,1.069,29.27,090425,,,A*4B 2025-04-09T23:41:31.376Z,1744242091.376 [NAL9602](INFO): GPS fix at 20250409T234130: (36.802832, -121.787928) 2025-04-09T23:41:31.403Z,1744242091.403 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:41:31.403Z,1744242091.403 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:41:38.814Z,1744242098.814 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250409T210403/Courier0087.lzma 2025-04-09T23:41:39.817Z,1744242099.817 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0087.lzma.bak 2025-04-09T23:41:39.817Z,1744242099.817 [DataOverHttps](INFO): SBD MOMSN=24601539 2025-04-09T23:41:55.783Z,1744242115.783 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250409T210403/Express0088.lzma 2025-04-09T23:41:56.785Z,1744242116.785 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0088.lzma.bak 2025-04-09T23:41:56.785Z,1744242116.785 [DataOverHttps](INFO): SBD MOMSN=24601544 2025-04-09T23:41:58.056Z,1744242118.056 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:41:58.056Z,1744242118.056 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:41:58.056Z,1744242118.056 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:42:02.076Z,1744242122.076 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-09T23:42:02.159Z,1744242122.159 [NAL9602](ERROR): received: +CSQ:0 OK119, 0, 0, 0, 0 OK 2025-04-09T23:46:33.568Z,1744242393.568 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-09T23:46:58.623Z,1744242418.623 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:46:58.624Z,1744242418.624 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:46:58.624Z,1744242418.624 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:46:58.624Z,1744242418.624 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:46:59.037Z,1744242419.037 [Default:CheckIn:D] Stopped 2025-04-09T23:46:59.037Z,1744242419.037 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:46:59.430Z,1744242419.430 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 186.085824 min 2025-04-09T23:46:59.430Z,1744242419.430 [Default:CheckIn:E] Stopped 2025-04-09T23:46:59.431Z,1744242419.431 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:46:59.431Z,1744242419.431 [Default:CheckIn] Stopped 2025-04-09T23:46:59.431Z,1744242419.431 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:46:59.431Z,1744242419.431 [Default:CheckIn](INFO): Running loop #34 2025-04-09T23:46:59.431Z,1744242419.431 [Default:CheckIn] Running Loop=34 2025-04-09T23:46:59.431Z,1744242419.431 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:46:59.431Z,1744242419.431 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:47:01.442Z,1744242421.442 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234700.00,A,3648.15959,N,12147.27624,W,0.564,29.27,090425,,,A*41 2025-04-09T23:47:01.444Z,1744242421.444 [NAL9602](INFO): GPS fix at 20250409T234700: (36.802660, -121.787937) 2025-04-09T23:47:01.455Z,1744242421.455 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:47:01.455Z,1744242421.455 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:47:09.446Z,1744242429.446 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250409T210403/Courier0090.lzma 2025-04-09T23:47:10.449Z,1744242430.449 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Courier0090.lzma.bak 2025-04-09T23:47:10.449Z,1744242430.449 [DataOverHttps](INFO): SBD MOMSN=24601621 2025-04-09T23:47:26.503Z,1744242446.503 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20250409T210403/Express0091.lzma 2025-04-09T23:47:27.505Z,1744242447.505 [DataOverHttps](INFO): Moved sent file to Logs/20250409T210403/Express0091.lzma.bak 2025-04-09T23:47:27.505Z,1744242447.505 [DataOverHttps](INFO): SBD MOMSN=24601624 2025-04-09T23:47:28.933Z,1744242448.933 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:47:28.934Z,1744242448.934 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:47:28.934Z,1744242448.934 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:47:33.775Z,1744242453.775 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T23:48:41.559Z,1744242521.559 [CommandExec](IMPORTANT): got command quit 2025-04-09T23:48:42.563Z,1744242522.563 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:48:42.563Z,1744242522.563 [CommandExec](INFO): Uninitializing the command executive. 2025-04-09T23:48:42.563Z,1744242522.563 [CommandExec](INFO): Uninitializing the command scheduler. 2025-04-09T23:48:42.563Z,1744242522.563 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:42.730Z,1744242522.730 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-04-09T23:48:42.731Z,1744242522.731 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-04-09T23:48:42.731Z,1744242522.731 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:42.731Z,1744242522.731 [NavChartDb](INFO): Join timeout helper Thread ID is 2106 2025-04-09T23:48:42.983Z,1744242522.983 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:48:42.983Z,1744242522.983 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:42.995Z,1744242522.995 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-04-09T23:48:42.995Z,1744242522.995 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:42.995Z,1744242522.995 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2107 2025-04-09T23:48:43.307Z,1744242523.307 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:48:43.307Z,1744242523.307 [WetLabsBB2FL](INFO): Powering down 2025-04-09T23:48:43.308Z,1744242523.308 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:43.323Z,1744242523.323 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-04-09T23:48:43.323Z,1744242523.323 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:43.323Z,1744242523.323 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2108 2025-04-09T23:48:43.531Z,1744242523.531 [CTD_Seabird](INFO): Powering down 2025-04-09T23:48:43.543Z,1744242523.543 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:48:43.543Z,1744242523.543 [CTD_Seabird](INFO): Powering down 2025-04-09T23:48:43.555Z,1744242523.555 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:43.563Z,1744242523.563 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-04-09T23:48:43.563Z,1744242523.563 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:43.563Z,1744242523.563 [Radio_Surface](INFO): Join timeout helper Thread ID is 2109 2025-04-09T23:48:43.899Z,1744242523.899 [Radio_Surface](INFO): Powering down 2025-04-09T23:48:43.899Z,1744242523.899 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:48:43.900Z,1744242523.900 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:43.903Z,1744242523.903 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-04-09T23:48:43.903Z,1744242523.903 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:43.903Z,1744242523.903 [Onboard](INFO): Join timeout helper Thread ID is 2110 2025-04-09T23:48:44.311Z,1744242524.311 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-09T23:48:44.675Z,1744242524.675 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:48:44.675Z,1744242524.675 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:44.694Z,1744242524.694 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-04-09T23:48:44.694Z,1744242524.694 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:44.694Z,1744242524.694 [DataOverHttps](INFO): Join timeout helper Thread ID is 2111 2025-04-09T23:48:45.315Z,1744242525.315 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:48:45.315Z,1744242525.315 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:45.331Z,1744242525.331 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-04-09T23:48:45.332Z,1744242525.332 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:45.332Z,1744242525.332 [DAT](INFO): Join timeout helper Thread ID is 2112 2025-04-09T23:48:45.359Z,1744242525.359 [DAT](INFO): Powering down 2025-04-09T23:48:45.431Z,1744242525.431 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:48:45.431Z,1744242525.431 [DAT](INFO): Powering down 2025-04-09T23:48:45.432Z,1744242525.432 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:45.439Z,1744242525.439 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-04-09T23:48:45.439Z,1744242525.439 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:45.440Z,1744242525.440 [logger](INFO): Join timeout helper Thread ID is 2113 2025-04-09T23:48:45.459Z,1744242525.459 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:48:45.459Z,1744242525.459 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:45.460Z,1744242525.460 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-04-09T23:48:45.461Z,1744242525.461 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:45.461Z,1744242525.461 [CommandLine](INFO): Join timeout helper Thread ID is 2114 2025-04-09T23:48:45.519Z,1744242525.519 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:48:45.519Z,1744242525.519 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:45.523Z,1744242525.523 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-04-09T23:48:45.523Z,1744242525.523 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:45.524Z,1744242525.524 [CommandExec](INFO): Join timeout helper Thread ID is 2115 2025-04-09T23:48:45.525Z,1744242525.525 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-04-09T23:48:45.525Z,1744242525.525 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:45.526Z,1744242525.526 [controlThread](INFO): Join timeout helper Thread ID is 2116 2025-04-09T23:48:45.655Z,1744242525.655 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:48:45.655Z,1744242525.655 [controlThread](DEBUG): Uninitializing ControlThread 2025-04-09T23:48:45.656Z,1744242525.656 [AHRS_M2](INFO): Powering down 2025-04-09T23:48:45.735Z,1744242525.735 [NAL9602](INFO): Powering down 2025-04-09T23:48:45.736Z,1744242525.736 [Sonardyne_Nano](INFO): Powering down 2025-04-09T23:48:45.943Z,1744242525.943 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-04-09T23:48:45.944Z,1744242525.944 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-04-09T23:48:45.944Z,1744242525.944 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-04-09T23:48:45.945Z,1744242525.945 [MissionManager](INFO): Uninitializing Mission Default 2025-04-09T23:48:45.945Z,1744242525.945 [Default] Stopped 2025-04-09T23:48:45.945Z,1744242525.945 [Default](DEBUG): Aggregate::uninitialize Default 2025-04-09T23:48:45.945Z,1744242525.945 [Default:B.GoToSurface] Stopped 2025-04-09T23:48:45.945Z,1744242525.945 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-09T23:48:45.945Z,1744242525.945 [Default:CheckIn] Stopped 2025-04-09T23:48:45.945Z,1744242525.945 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:48:45.945Z,1744242525.945 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:48:45.945Z,1744242525.945 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:48:45.948Z,1744242525.948 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-04-09T23:48:45.949Z,1744242525.949 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-04-09T23:48:45.949Z,1744242525.949 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-04-09T23:48:45.949Z,1744242525.949 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-04-09T23:48:45.949Z,1744242525.949 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-04-09T23:48:45.950Z,1744242525.950 [BuoyancyServo](INFO): Powering down 2025-04-09T23:48:45.963Z,1744242525.963 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-04-09T23:48:45.963Z,1744242525.963 [ElevatorServo](INFO): Powering down 2025-04-09T23:48:45.964Z,1744242525.964 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-04-09T23:48:45.964Z,1744242525.964 [MassServo](INFO): Powering down 2025-04-09T23:48:45.965Z,1744242525.965 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-04-09T23:48:45.965Z,1744242525.965 [RudderServo](INFO): Powering down 2025-04-09T23:48:45.965Z,1744242525.965 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-04-09T23:48:45.966Z,1744242525.966 [ThrusterHE](INFO): Powering down 2025-04-09T23:48:45.966Z,1744242525.966 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-04-09T23:48:45.967Z,1744242525.967 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-04-09T23:48:45.967Z,1744242525.967 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-04-09T23:48:45.967Z,1744242525.967 [CBIT](DEBUG): Powering off loads. 2025-04-09T23:48:45.978Z,1744242525.978 [CBIT](DEBUG): Disabling WDT. 2025-04-09T23:48:45.991Z,1744242525.991 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-04-09T23:48:45.991Z,1744242525.991 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-04-09T23:48:45.992Z,1744242525.992 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:46.049Z,1744242526.049 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:46.052Z,1744242526.052 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:46.058Z,1744242526.058 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:46.065Z,1744242526.065 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:46.136Z,1744242526.136 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:46.141Z,1744242526.141 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:46.164Z,1744242526.164 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-04-09T23:48:46.164Z,1744242526.164 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-04-09T23:48:46.185Z,1744242526.185 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:48:46.309Z,1744242526.309 [logger ThreadHandler](INFO): Thread cancelled.