2025-09-25T15:18:17.590Z,1758813497.590 [CommandExec](IMPORTANT): got command restart logs 2025-09-25T15:18:28.380Z,1758813508.380 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T15:18:31.211Z,1758813511.211 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T15:18:36.173Z,1758813516.173 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250923T170748/Express0187.lzma 2025-09-25T15:18:42.117Z,1758813522.117 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T15:18:47.216Z,1758813527.216 [DataOverHttps](INFO): Moved sent file to Logs/20250923T170748/Express0187.lzma.bak 2025-09-25T15:18:47.216Z,1758813527.216 [DataOverHttps](INFO): SBD MOMSN=26074658 2025-09-25T15:18:56.310Z,1758813536.310 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T15:19:10.045Z,1758813550.045 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T15:19:16.180Z,1758813556.180 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250923T170748/Courier0189.lzma 2025-09-25T15:19:24.187Z,1758813564.187 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T15:19:27.223Z,1758813567.223 [DataOverHttps](INFO): Moved sent file to Logs/20250923T170748/Courier0189.lzma.bak 2025-09-25T15:19:27.223Z,1758813567.223 [DataOverHttps](INFO): SBD MOMSN=26074662 2025-09-25T15:19:37.918Z,1758813577.918 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T15:19:51.245Z,1758813591.245 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T15:19:56.588Z,1758813596.588 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20250923T170748/Express0190.lzma 2025-09-25T15:20:04.982Z,1758813604.982 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T15:20:07.631Z,1758813607.631 [DataOverHttps](INFO): Moved sent file to Logs/20250923T170748/Express0190.lzma.bak 2025-09-25T15:20:07.631Z,1758813607.631 [DataOverHttps](INFO): SBD MOMSN=26074664 2025-09-25T15:20:09.033Z,1758813609.033 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:20:09.033Z,1758813609.033 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:20:09.033Z,1758813609.033 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:20:19.123Z,1758813619.123 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T15:20:32.857Z,1758813632.857 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T15:22:56.681Z,1758813776.681 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T15:23:03.144Z,1758813783.144 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T15:23:03.151Z,1758813783.151 [BPC1](ERROR): Battery stick #16 (s/n: 00F8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T15:23:03.152Z,1758813783.152 [BPC1](ERROR): Battery stick #17 (s/n: 0084) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T15:23:03.153Z,1758813783.153 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T15:23:03.153Z,1758813783.153 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-25T15:23:03.154Z,1758813783.154 [BPC1](ERROR): Battery stick #38 (s/n: 01BC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48E0. 2025-09-25T15:23:03.155Z,1758813783.155 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T15:23:03.156Z,1758813783.156 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T15:23:03.157Z,1758813783.157 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T15:23:03.158Z,1758813783.158 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-25T15:23:03.158Z,1758813783.158 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 4. 2025-09-25T15:23:03.161Z,1758813783.161 [BPC1](INFO): Received data from all battery sticks. 2025-09-25T15:25:09.676Z,1758813909.676 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T15:25:09.676Z,1758813909.676 [Default:CheckIn:C.Wait] Stopped 2025-09-25T15:25:09.677Z,1758813909.677 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T15:25:09.677Z,1758813909.677 [Default:CheckIn:D] Running Loop=1 2025-09-25T15:25:10.086Z,1758813910.086 [Default:CheckIn:D] Stopped 2025-09-25T15:25:10.086Z,1758813910.086 [Default:CheckIn:E] Running Loop=1 2025-09-25T15:25:10.487Z,1758813910.487 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.916166 min 2025-09-25T15:25:10.487Z,1758813910.487 [Default:CheckIn:E] Stopped 2025-09-25T15:25:10.488Z,1758813910.488 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T15:25:10.488Z,1758813910.488 [Default:CheckIn] Stopped 2025-09-25T15:25:10.488Z,1758813910.488 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T15:25:10.488Z,1758813910.488 [Default:CheckIn](INFO): Running loop #7 2025-09-25T15:25:10.488Z,1758813910.488 [Default:CheckIn] Running Loop=7 2025-09-25T15:25:10.488Z,1758813910.488 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T15:25:10.488Z,1758813910.488 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T15:25:12.497Z,1758813912.497 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,152511.00,A,3648.39848,N,12147.37563,W,6.765,51.49,250925,,,D*4A 2025-09-25T15:25:12.499Z,1758813912.499 [NAL9602](INFO): GPS fix at 20250925T152511: (36.806641, -121.789594) 2025-09-25T15:25:12.535Z,1758813912.535 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T15:25:12.535Z,1758813912.535 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T15:25:21.277Z,1758813921.277 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20250925T151817/Courier0003.lzma 2025-09-25T15:25:27.453Z,1758813927.453 [NAL9602](INFO): SBD MO Status=0, MOMSN=28314, MT Status=0, MTMSN=0 2025-09-25T15:25:27.453Z,1758813927.453 [NAL9602](INFO): No messages in MT queue 2025-09-25T15:25:32.334Z,1758813932.334 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0003.lzma.bak 2025-09-25T15:25:32.335Z,1758813932.335 [DataOverHttps](INFO): SBD MOMSN=26074674 2025-09-25T15:25:58.154Z,1758813958.154 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T15:25:58.170Z,1758813958.170 [DataOverHttps](INFO): Sending 504 bytes from file Logs/20250925T151817/Express0004.lzma 2025-09-25T15:26:09.211Z,1758813969.211 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0004.lzma.bak 2025-09-25T15:26:09.212Z,1758813969.212 [DataOverHttps](INFO): SBD MOMSN=26074679 2025-09-25T15:26:10.297Z,1758813970.297 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:26:10.297Z,1758813970.297 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:26:10.297Z,1758813970.297 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:31:10.989Z,1758814270.989 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T15:31:10.989Z,1758814270.989 [Default:CheckIn:C.Wait] Stopped 2025-09-25T15:31:10.989Z,1758814270.989 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T15:31:10.990Z,1758814270.990 [Default:CheckIn:D] Running Loop=1 2025-09-25T15:31:11.398Z,1758814271.398 [Default:CheckIn:D] Stopped 2025-09-25T15:31:11.398Z,1758814271.398 [Default:CheckIn:E] Running Loop=1 2025-09-25T15:31:11.792Z,1758814271.792 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.938053 min 2025-09-25T15:31:11.792Z,1758814271.792 [Default:CheckIn:E] Stopped 2025-09-25T15:31:11.793Z,1758814271.793 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T15:31:11.793Z,1758814271.793 [Default:CheckIn] Stopped 2025-09-25T15:31:11.793Z,1758814271.793 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T15:31:11.793Z,1758814271.793 [Default:CheckIn](INFO): Running loop #8 2025-09-25T15:31:11.793Z,1758814271.793 [Default:CheckIn] Running Loop=8 2025-09-25T15:31:11.793Z,1758814271.793 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T15:31:11.793Z,1758814271.793 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T15:31:13.805Z,1758814273.805 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153113.00,A,3648.20383,N,12147.16202,W,3.479,241.55,250925,,,D*7F 2025-09-25T15:31:13.807Z,1758814273.807 [NAL9602](INFO): GPS fix at 20250925T153113: (36.803397, -121.786034) 2025-09-25T15:31:13.820Z,1758814273.820 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T15:31:13.821Z,1758814273.821 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T15:31:21.140Z,1758814281.140 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0006.lzma 2025-09-25T15:31:29.155Z,1758814289.155 [NAL9602](INFO): SBD MO Status=0, MOMSN=28315, MT Status=0, MTMSN=0 2025-09-25T15:31:29.155Z,1758814289.155 [NAL9602](INFO): No messages in MT queue 2025-09-25T15:31:32.182Z,1758814292.182 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0006.lzma.bak 2025-09-25T15:31:32.183Z,1758814292.183 [DataOverHttps](INFO): SBD MOMSN=26074708 2025-09-25T15:31:58.153Z,1758814318.153 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250925T151817/Express0007.lzma 2025-09-25T15:31:59.862Z,1758814319.862 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T15:32:01.903Z,1758814321.903 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-09-25T15:32:06.366Z,1758814326.366 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.000234 CHAN A1 (24V): 0.000924 CHAN A2 (12V): 0.000549 CHAN A3 (5V): -0.001883 CHAN B0 (3.3V): 0.000634 CHAN B1 (3.15aV): 0.000166 CHAN B2 (3.15bV): 0.000072 CHAN B3 (GND): -0.000863 OPEN: 0.006319 Full Scale: +/- 1 mA 2025-09-25T15:32:09.207Z,1758814329.207 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0007.lzma.bak 2025-09-25T15:32:09.207Z,1758814329.207 [DataOverHttps](INFO): SBD MOMSN=26074712 2025-09-25T15:32:10.391Z,1758814330.391 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:32:10.392Z,1758814330.392 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:32:10.392Z,1758814330.392 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:37:11.132Z,1758814631.132 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T15:37:11.132Z,1758814631.132 [Default:CheckIn:C.Wait] Stopped 2025-09-25T15:37:11.132Z,1758814631.132 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T15:37:11.132Z,1758814631.132 [Default:CheckIn:D] Running Loop=1 2025-09-25T15:37:11.539Z,1758814631.539 [Default:CheckIn:D] Stopped 2025-09-25T15:37:11.539Z,1758814631.539 [Default:CheckIn:E] Running Loop=1 2025-09-25T15:37:11.951Z,1758814631.951 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.940405 min 2025-09-25T15:37:11.951Z,1758814631.951 [Default:CheckIn:E] Stopped 2025-09-25T15:37:11.951Z,1758814631.951 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T15:37:11.951Z,1758814631.951 [Default:CheckIn] Stopped 2025-09-25T15:37:11.951Z,1758814631.951 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T15:37:11.951Z,1758814631.951 [Default:CheckIn](INFO): Running loop #9 2025-09-25T15:37:11.952Z,1758814631.952 [Default:CheckIn] Running Loop=9 2025-09-25T15:37:11.952Z,1758814631.952 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T15:37:11.952Z,1758814631.952 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T15:37:13.956Z,1758814633.956 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153713.00,A,3648.15272,N,12147.21859,W,0.292,211.49,250925,,,D*78 2025-09-25T15:37:13.958Z,1758814633.958 [NAL9602](INFO): GPS fix at 20250925T153713: (36.802545, -121.786976) 2025-09-25T15:37:13.998Z,1758814633.998 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T15:37:13.998Z,1758814633.998 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T15:37:21.356Z,1758814641.356 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0009.lzma 2025-09-25T15:37:32.398Z,1758814652.398 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0009.lzma.bak 2025-09-25T15:37:32.399Z,1758814652.399 [DataOverHttps](INFO): SBD MOMSN=26074760 2025-09-25T15:37:32.543Z,1758814652.543 [NAL9602](INFO): SBD MO Status=0, MOMSN=28316, MT Status=0, MTMSN=0 2025-09-25T15:37:32.543Z,1758814652.543 [NAL9602](INFO): No messages in MT queue 2025-09-25T15:37:58.313Z,1758814678.313 [DataOverHttps](INFO): Sending 375 bytes from file Logs/20250925T151817/Express0010.lzma 2025-09-25T15:38:03.238Z,1758814683.238 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T15:38:09.355Z,1758814689.355 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0010.lzma.bak 2025-09-25T15:38:09.355Z,1758814689.355 [DataOverHttps](INFO): SBD MOMSN=26074779 2025-09-25T15:38:10.538Z,1758814690.538 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:38:10.539Z,1758814690.539 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:38:10.539Z,1758814690.539 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:43:11.249Z,1758814991.249 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T15:43:11.249Z,1758814991.249 [Default:CheckIn:C.Wait] Stopped 2025-09-25T15:43:11.250Z,1758814991.250 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T15:43:11.250Z,1758814991.250 [Default:CheckIn:D] Running Loop=1 2025-09-25T15:43:11.649Z,1758814991.649 [Default:CheckIn:D] Stopped 2025-09-25T15:43:11.649Z,1758814991.649 [Default:CheckIn:E] Running Loop=1 2025-09-25T15:43:12.060Z,1758814992.060 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.942232 min 2025-09-25T15:43:12.060Z,1758814992.060 [Default:CheckIn:E] Stopped 2025-09-25T15:43:12.061Z,1758814992.061 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T15:43:12.061Z,1758814992.061 [Default:CheckIn] Stopped 2025-09-25T15:43:12.061Z,1758814992.061 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T15:43:12.061Z,1758814992.061 [Default:CheckIn](INFO): Running loop #10 2025-09-25T15:43:12.061Z,1758814992.061 [Default:CheckIn] Running Loop=10 2025-09-25T15:43:12.061Z,1758814992.061 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T15:43:12.061Z,1758814992.061 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T15:43:14.064Z,1758814994.064 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154313.00,A,3648.15199,N,12147.21374,W,0.039,291.54,250925,,,D*7E 2025-09-25T15:43:14.066Z,1758814994.066 [NAL9602](INFO): GPS fix at 20250925T154313: (36.802533, -121.786896) 2025-09-25T15:43:14.094Z,1758814994.094 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T15:43:14.094Z,1758814994.094 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T15:43:21.236Z,1758815001.236 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0012.lzma 2025-09-25T15:43:32.278Z,1758815012.278 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0012.lzma.bak 2025-09-25T15:43:32.278Z,1758815012.278 [DataOverHttps](INFO): SBD MOMSN=26074819 2025-09-25T15:43:41.947Z,1758815021.947 [NAL9602](INFO): SBD MO Status=2, MOMSN=28317, MT Status=2, MTMSN=0 2025-09-25T15:43:41.947Z,1758815021.947 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T15:43:58.149Z,1758815038.149 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250925T151817/Express0013.lzma 2025-09-25T15:44:09.202Z,1758815049.202 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0013.lzma.bak 2025-09-25T15:44:09.203Z,1758815049.203 [DataOverHttps](INFO): SBD MOMSN=26074822 2025-09-25T15:44:10.632Z,1758815050.632 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:44:10.632Z,1758815050.632 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:44:10.632Z,1758815050.632 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:44:22.748Z,1758815062.748 [NAL9602](INFO): SBD MO Status=0, MOMSN=28317, MT Status=0, MTMSN=0 2025-09-25T15:44:22.748Z,1758815062.748 [NAL9602](INFO): No messages in MT queue 2025-09-25T15:44:53.510Z,1758815093.510 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T15:49:11.376Z,1758815351.376 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T15:49:11.376Z,1758815351.376 [Default:CheckIn:C.Wait] Stopped 2025-09-25T15:49:11.376Z,1758815351.376 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T15:49:11.376Z,1758815351.376 [Default:CheckIn:D] Running Loop=1 2025-09-25T15:49:11.784Z,1758815351.784 [Default:CheckIn:D] Stopped 2025-09-25T15:49:11.785Z,1758815351.785 [Default:CheckIn:E] Running Loop=1 2025-09-25T15:49:12.188Z,1758815352.188 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.944486 min 2025-09-25T15:49:12.188Z,1758815352.188 [Default:CheckIn:E] Stopped 2025-09-25T15:49:12.189Z,1758815352.189 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T15:49:12.189Z,1758815352.189 [Default:CheckIn] Stopped 2025-09-25T15:49:12.189Z,1758815352.189 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T15:49:12.189Z,1758815352.189 [Default:CheckIn](INFO): Running loop #11 2025-09-25T15:49:12.189Z,1758815352.189 [Default:CheckIn] Running Loop=11 2025-09-25T15:49:12.189Z,1758815352.189 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T15:49:12.189Z,1758815352.189 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T15:49:14.192Z,1758815354.192 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154913.00,A,3648.15746,N,12147.25184,W,1.050,33.41,250925,,,D*49 2025-09-25T15:49:14.194Z,1758815354.194 [NAL9602](INFO): GPS fix at 20250925T154913: (36.802624, -121.787531) 2025-09-25T15:49:14.222Z,1758815354.222 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T15:49:14.222Z,1758815354.222 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T15:49:21.088Z,1758815361.088 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0015.lzma 2025-09-25T15:49:32.131Z,1758815372.131 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0015.lzma.bak 2025-09-25T15:49:32.132Z,1758815372.132 [DataOverHttps](INFO): SBD MOMSN=26074865 2025-09-25T15:49:55.807Z,1758815395.807 [NAL9602](INFO): SBD MO Status=0, MOMSN=28318, MT Status=0, MTMSN=0 2025-09-25T15:49:55.807Z,1758815395.807 [NAL9602](INFO): No messages in MT queue 2025-09-25T15:49:58.177Z,1758815398.177 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250925T151817/Express0016.lzma 2025-09-25T15:50:09.219Z,1758815409.219 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0016.lzma.bak 2025-09-25T15:50:09.219Z,1758815409.219 [DataOverHttps](INFO): SBD MOMSN=26074868 2025-09-25T15:50:10.360Z,1758815410.360 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:50:10.360Z,1758815410.360 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:50:10.361Z,1758815410.361 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:50:26.507Z,1758815426.507 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T15:55:11.075Z,1758815711.075 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T15:55:11.075Z,1758815711.075 [Default:CheckIn:C.Wait] Stopped 2025-09-25T15:55:11.075Z,1758815711.075 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T15:55:11.075Z,1758815711.075 [Default:CheckIn:D] Running Loop=1 2025-09-25T15:55:11.439Z,1758815711.439 [Default:CheckIn:D] Stopped 2025-09-25T15:55:11.439Z,1758815711.439 [Default:CheckIn:E] Running Loop=1 2025-09-25T15:55:11.844Z,1758815711.844 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.938737 min 2025-09-25T15:55:11.844Z,1758815711.844 [Default:CheckIn:E] Stopped 2025-09-25T15:55:11.845Z,1758815711.845 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T15:55:11.845Z,1758815711.845 [Default:CheckIn] Stopped 2025-09-25T15:55:11.845Z,1758815711.845 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T15:55:11.845Z,1758815711.845 [Default:CheckIn](INFO): Running loop #12 2025-09-25T15:55:11.845Z,1758815711.845 [Default:CheckIn] Running Loop=12 2025-09-25T15:55:11.845Z,1758815711.845 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T15:55:11.845Z,1758815711.845 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T15:55:13.845Z,1758815713.845 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155513.00,A,3648.17397,N,12147.28101,W,0.019,317.96,250925,,,D*7D 2025-09-25T15:55:13.847Z,1758815713.847 [NAL9602](INFO): GPS fix at 20250925T155513: (36.802900, -121.788017) 2025-09-25T15:55:13.858Z,1758815713.858 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T15:55:13.858Z,1758815713.858 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T15:55:21.600Z,1758815721.600 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0018.lzma 2025-09-25T15:55:32.654Z,1758815732.654 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0018.lzma.bak 2025-09-25T15:55:32.655Z,1758815732.655 [DataOverHttps](INFO): SBD MOMSN=26074881 2025-09-25T15:55:39.703Z,1758815739.703 [NAL9602](INFO): SBD MO Status=2, MOMSN=28319, MT Status=2, MTMSN=0 2025-09-25T15:55:39.703Z,1758815739.703 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T15:55:58.621Z,1758815758.621 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20250925T151817/Express0019.lzma 2025-09-25T15:56:05.961Z,1758815765.961 [NAL9602](INFO): SBD MO Status=2, MOMSN=28319, MT Status=2, MTMSN=0 2025-09-25T15:56:05.961Z,1758815765.961 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T15:56:09.663Z,1758815769.663 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0019.lzma.bak 2025-09-25T15:56:09.663Z,1758815769.663 [DataOverHttps](INFO): SBD MOMSN=26074890 2025-09-25T15:56:10.833Z,1758815770.833 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T15:56:10.833Z,1758815770.833 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T15:56:10.833Z,1758815770.833 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T15:56:16.464Z,1758815776.464 [NAL9602](INFO): SBD MO Status=0, MOMSN=28319, MT Status=0, MTMSN=0 2025-09-25T15:56:16.464Z,1758815776.464 [NAL9602](INFO): No messages in MT queue 2025-09-25T15:56:47.206Z,1758815807.206 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:01:11.542Z,1758816071.542 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:01:11.542Z,1758816071.542 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:01:11.542Z,1758816071.542 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:01:11.542Z,1758816071.542 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:01:11.954Z,1758816071.954 [Default:CheckIn:D] Stopped 2025-09-25T16:01:11.954Z,1758816071.954 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:01:12.359Z,1758816072.359 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.947323 min 2025-09-25T16:01:12.359Z,1758816072.359 [Default:CheckIn:E] Stopped 2025-09-25T16:01:12.359Z,1758816072.359 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:01:12.360Z,1758816072.360 [Default:CheckIn] Stopped 2025-09-25T16:01:12.360Z,1758816072.360 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:01:12.360Z,1758816072.360 [Default:CheckIn](INFO): Running loop #13 2025-09-25T16:01:12.360Z,1758816072.360 [Default:CheckIn] Running Loop=13 2025-09-25T16:01:12.360Z,1758816072.360 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:01:12.360Z,1758816072.360 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:01:14.360Z,1758816074.360 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160113.00,A,3648.17603,N,12147.28163,W,0.097,317.96,250925,,,D*75 2025-09-25T16:01:14.362Z,1758816074.362 [NAL9602](INFO): GPS fix at 20250925T160113: (36.802934, -121.788027) 2025-09-25T16:01:14.373Z,1758816074.373 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:01:14.374Z,1758816074.374 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:01:21.476Z,1758816081.476 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0021.lzma 2025-09-25T16:01:32.530Z,1758816092.530 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0021.lzma.bak 2025-09-25T16:01:32.531Z,1758816092.531 [DataOverHttps](INFO): SBD MOMSN=26074922 2025-09-25T16:01:53.961Z,1758816113.961 [NAL9602](INFO): SBD MO Status=2, MOMSN=28320, MT Status=2, MTMSN=0 2025-09-25T16:01:53.961Z,1758816113.961 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T16:01:58.461Z,1758816118.461 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250925T151817/Express0022.lzma 2025-09-25T16:02:09.503Z,1758816129.503 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0022.lzma.bak 2025-09-25T16:02:09.503Z,1758816129.503 [DataOverHttps](INFO): SBD MOMSN=26074926 2025-09-25T16:02:10.962Z,1758816130.962 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:02:10.962Z,1758816130.962 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:02:10.962Z,1758816130.962 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:02:32.737Z,1758816152.737 [NAL9602](INFO): SBD MO Status=2, MOMSN=28320, MT Status=2, MTMSN=0 2025-09-25T16:02:32.737Z,1758816152.737 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T16:03:39.047Z,1758816219.047 [NAL9602](INFO): SBD MO Status=2, MOMSN=28320, MT Status=2, MTMSN=0 2025-09-25T16:03:39.047Z,1758816219.047 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T16:05:21.731Z,1758816321.731 [NAL9602](INFO): SBD MO Status=0, MOMSN=28320, MT Status=0, MTMSN=0 2025-09-25T16:05:21.731Z,1758816321.731 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:05:52.432Z,1758816352.432 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:07:11.678Z,1758816431.678 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:07:11.678Z,1758816431.678 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:07:11.679Z,1758816431.679 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:07:11.679Z,1758816431.679 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:07:12.072Z,1758816432.072 [Default:CheckIn:D] Stopped 2025-09-25T16:07:12.073Z,1758816432.073 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:07:12.473Z,1758816432.473 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.949284 min 2025-09-25T16:07:12.473Z,1758816432.473 [Default:CheckIn:E] Stopped 2025-09-25T16:07:12.473Z,1758816432.473 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:07:12.473Z,1758816432.473 [Default:CheckIn] Stopped 2025-09-25T16:07:12.474Z,1758816432.474 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:07:12.474Z,1758816432.474 [Default:CheckIn](INFO): Running loop #14 2025-09-25T16:07:12.474Z,1758816432.474 [Default:CheckIn] Running Loop=14 2025-09-25T16:07:12.474Z,1758816432.474 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:07:12.474Z,1758816432.474 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:07:14.486Z,1758816434.486 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160713.00,A,3648.17517,N,12147.27981,W,0.000,317.96,250925,,,D*70 2025-09-25T16:07:14.488Z,1758816434.488 [NAL9602](INFO): GPS fix at 20250925T160713: (36.802920, -121.787997) 2025-09-25T16:07:14.499Z,1758816434.499 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:07:14.499Z,1758816434.499 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:07:21.748Z,1758816441.748 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0024.lzma 2025-09-25T16:07:32.791Z,1758816452.791 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0024.lzma.bak 2025-09-25T16:07:32.791Z,1758816452.791 [DataOverHttps](INFO): SBD MOMSN=26074936 2025-09-25T16:07:37.515Z,1758816457.515 [NAL9602](INFO): SBD MO Status=0, MOMSN=28321, MT Status=0, MTMSN=0 2025-09-25T16:07:37.515Z,1758816457.515 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:07:58.757Z,1758816478.757 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250925T151817/Express0025.lzma 2025-09-25T16:08:08.222Z,1758816488.222 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:08:09.798Z,1758816489.798 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0025.lzma.bak 2025-09-25T16:08:09.799Z,1758816489.799 [DataOverHttps](INFO): SBD MOMSN=26074940 2025-09-25T16:08:11.061Z,1758816491.061 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:08:11.061Z,1758816491.061 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:08:11.061Z,1758816491.061 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:13:11.793Z,1758816791.793 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:13:11.793Z,1758816791.793 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:13:11.793Z,1758816791.793 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:13:11.794Z,1758816791.794 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:13:12.206Z,1758816792.206 [Default:CheckIn:D] Stopped 2025-09-25T16:13:12.206Z,1758816792.206 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:13:12.622Z,1758816792.622 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.951522 min 2025-09-25T16:13:12.622Z,1758816792.622 [Default:CheckIn:E] Stopped 2025-09-25T16:13:12.622Z,1758816792.622 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:13:12.622Z,1758816792.622 [Default:CheckIn] Stopped 2025-09-25T16:13:12.622Z,1758816792.622 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:13:12.622Z,1758816792.622 [Default:CheckIn](INFO): Running loop #15 2025-09-25T16:13:12.622Z,1758816792.622 [Default:CheckIn] Running Loop=15 2025-09-25T16:13:12.623Z,1758816792.623 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:13:12.623Z,1758816792.623 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:13:14.619Z,1758816794.619 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161313.00,A,3648.17666,N,12147.27954,W,0.078,317.96,250925,,,D*77 2025-09-25T16:13:14.622Z,1758816794.622 [NAL9602](INFO): GPS fix at 20250925T161313: (36.802944, -121.787992) 2025-09-25T16:13:14.633Z,1758816794.633 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:13:14.633Z,1758816794.633 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:13:22.060Z,1758816802.060 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0027.lzma 2025-09-25T16:13:33.102Z,1758816813.102 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0027.lzma.bak 2025-09-25T16:13:33.103Z,1758816813.103 [DataOverHttps](INFO): SBD MOMSN=26074976 2025-09-25T16:13:59.057Z,1758816839.057 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250925T151817/Express0028.lzma 2025-09-25T16:14:10.099Z,1758816850.099 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0028.lzma.bak 2025-09-25T16:14:10.099Z,1758816850.099 [DataOverHttps](INFO): SBD MOMSN=26074979 2025-09-25T16:14:11.605Z,1758816851.605 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:14:11.605Z,1758816851.605 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:14:11.605Z,1758816851.605 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:14:46.363Z,1758816886.363 [NAL9602](INFO): SBD MO Status=0, MOMSN=28322, MT Status=0, MTMSN=0 2025-09-25T16:14:46.363Z,1758816886.363 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:15:17.090Z,1758816917.090 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:19:12.322Z,1758817152.322 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:19:12.322Z,1758817152.322 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:19:12.322Z,1758817152.322 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:19:12.322Z,1758817152.322 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:19:12.725Z,1758817152.725 [Default:CheckIn:D] Stopped 2025-09-25T16:19:12.725Z,1758817152.725 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:19:13.133Z,1758817153.133 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.960164 min 2025-09-25T16:19:13.133Z,1758817153.133 [Default:CheckIn:E] Stopped 2025-09-25T16:19:13.133Z,1758817153.133 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:19:13.133Z,1758817153.133 [Default:CheckIn] Stopped 2025-09-25T16:19:13.133Z,1758817153.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:19:13.133Z,1758817153.133 [Default:CheckIn](INFO): Running loop #16 2025-09-25T16:19:13.134Z,1758817153.134 [Default:CheckIn] Running Loop=16 2025-09-25T16:19:13.134Z,1758817153.134 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:19:13.134Z,1758817153.134 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:19:15.148Z,1758817155.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161914.00,A,3648.17360,N,12147.27995,W,0.272,287.86,250925,,,D*75 2025-09-25T16:19:15.151Z,1758817155.151 [NAL9602](INFO): GPS fix at 20250925T161914: (36.802893, -121.787999) 2025-09-25T16:19:15.162Z,1758817155.162 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:19:15.162Z,1758817155.162 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:19:22.528Z,1758817162.528 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0030.lzma 2025-09-25T16:19:33.571Z,1758817173.571 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0030.lzma.bak 2025-09-25T16:19:33.571Z,1758817173.571 [DataOverHttps](INFO): SBD MOMSN=26074997 2025-09-25T16:19:35.752Z,1758817175.752 [NAL9602](INFO): SBD MO Status=2, MOMSN=28323, MT Status=2, MTMSN=0 2025-09-25T16:19:35.752Z,1758817175.752 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T16:19:44.631Z,1758817184.631 [NAL9602](INFO): SBD MO Status=0, MOMSN=28323, MT Status=0, MTMSN=0 2025-09-25T16:19:44.632Z,1758817184.632 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:19:59.533Z,1758817199.533 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250925T151817/Express0031.lzma 2025-09-25T16:20:10.574Z,1758817210.574 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0031.lzma.bak 2025-09-25T16:20:10.575Z,1758817210.575 [DataOverHttps](INFO): SBD MOMSN=26075025 2025-09-25T16:20:11.725Z,1758817211.725 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:20:11.725Z,1758817211.725 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:20:11.725Z,1758817211.725 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:20:15.338Z,1758817215.338 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:24:10.988Z,1758817450.988 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:24:24.320Z,1758817464.320 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:24:38.058Z,1758817478.058 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:24:51.830Z,1758817491.830 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:25:05.221Z,1758817505.221 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:25:12.449Z,1758817512.449 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:25:12.449Z,1758817512.449 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:25:12.449Z,1758817512.449 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:25:12.449Z,1758817512.449 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:25:12.865Z,1758817512.865 [Default:CheckIn:D] Stopped 2025-09-25T16:25:12.865Z,1758817512.865 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:25:13.271Z,1758817513.271 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.962492 min 2025-09-25T16:25:13.271Z,1758817513.271 [Default:CheckIn:E] Stopped 2025-09-25T16:25:13.271Z,1758817513.271 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:25:13.271Z,1758817513.271 [Default:CheckIn] Stopped 2025-09-25T16:25:13.271Z,1758817513.271 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:25:13.271Z,1758817513.271 [Default:CheckIn](INFO): Running loop #17 2025-09-25T16:25:13.271Z,1758817513.271 [Default:CheckIn] Running Loop=17 2025-09-25T16:25:13.271Z,1758817513.271 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:25:13.271Z,1758817513.271 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:25:15.269Z,1758817515.269 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162514.00,A,3648.17386,N,12147.27597,W,0.058,154.46,250925,,,D*77 2025-09-25T16:25:15.271Z,1758817515.271 [NAL9602](INFO): GPS fix at 20250925T162514: (36.802898, -121.787933) 2025-09-25T16:25:15.302Z,1758817515.302 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:25:15.302Z,1758817515.302 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:25:18.900Z,1758817518.900 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:25:23.216Z,1758817523.216 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0033.lzma 2025-09-25T16:25:32.234Z,1758817532.234 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:25:34.267Z,1758817534.267 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0033.lzma.bak 2025-09-25T16:25:34.267Z,1758817534.267 [DataOverHttps](INFO): SBD MOMSN=26075034 2025-09-25T16:25:38.003Z,1758817538.003 [NAL9602](INFO): SBD MO Status=0, MOMSN=28324, MT Status=0, MTMSN=0 2025-09-25T16:25:38.003Z,1758817538.003 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:25:46.373Z,1758817546.373 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:26:00.289Z,1758817560.289 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250925T151817/Express0034.lzma 2025-09-25T16:26:00.515Z,1758817560.515 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:26:08.596Z,1758817568.596 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:26:12.339Z,1758817572.339 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0034.lzma.bak 2025-09-25T16:26:12.339Z,1758817572.339 [DataOverHttps](INFO): SBD MOMSN=26075038 2025-09-25T16:26:13.461Z,1758817573.461 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:26:13.461Z,1758817573.461 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:26:13.462Z,1758817573.462 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:26:14.249Z,1758817574.249 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:28:43.806Z,1758817723.806 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:28:50.716Z,1758817730.716 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:29:06.106Z,1758817746.106 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:29:21.052Z,1758817761.052 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:29:36.001Z,1758817776.001 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:29:51.354Z,1758817791.354 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:30:06.704Z,1758817806.704 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:30:22.058Z,1758817822.058 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:30:37.410Z,1758817837.410 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:30:51.955Z,1758817851.955 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T16:31:06.918Z,1758817866.918 [BPC1](ERROR): Battery stick #16 (s/n: 00F8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T16:31:06.919Z,1758817866.919 [BPC1](ERROR): Battery stick #17 (s/n: 0084) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T16:31:06.919Z,1758817866.919 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T16:31:06.920Z,1758817866.920 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-25T16:31:06.922Z,1758817866.922 [BPC1](ERROR): Battery stick #38 (s/n: 01BC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48E0. 2025-09-25T16:31:06.923Z,1758817866.923 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T16:31:06.923Z,1758817866.923 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T16:31:06.924Z,1758817866.924 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T16:31:06.929Z,1758817866.929 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-25T16:31:06.930Z,1758817866.930 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 4. 2025-09-25T16:31:06.932Z,1758817866.932 [BPC1](INFO): Received data from all battery sticks. 2025-09-25T16:31:14.186Z,1758817874.186 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:31:14.186Z,1758817874.186 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:31:14.186Z,1758817874.186 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:31:14.186Z,1758817874.186 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:31:14.595Z,1758817874.595 [Default:CheckIn:D] Stopped 2025-09-25T16:31:14.595Z,1758817874.595 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:31:15.012Z,1758817875.012 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.991341 min 2025-09-25T16:31:15.012Z,1758817875.012 [Default:CheckIn:E] Stopped 2025-09-25T16:31:15.012Z,1758817875.012 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:31:15.012Z,1758817875.012 [Default:CheckIn] Stopped 2025-09-25T16:31:15.012Z,1758817875.012 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:31:15.013Z,1758817875.013 [Default:CheckIn](INFO): Running loop #18 2025-09-25T16:31:15.013Z,1758817875.013 [Default:CheckIn] Running Loop=18 2025-09-25T16:31:15.013Z,1758817875.013 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:31:15.013Z,1758817875.013 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:31:17.005Z,1758817877.005 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163116.00,A,3648.17215,N,12147.27924,W,0.039,274.04,250925,,,D*7F 2025-09-25T16:31:17.007Z,1758817877.007 [NAL9602](INFO): GPS fix at 20250925T163116: (36.802869, -121.787987) 2025-09-25T16:31:17.018Z,1758817877.018 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:31:17.018Z,1758817877.018 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:31:24.096Z,1758817884.096 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20250925T151817/Courier0036.lzma 2025-09-25T16:31:34.375Z,1758817894.375 [NAL9602](INFO): SBD MO Status=0, MOMSN=28325, MT Status=0, MTMSN=0 2025-09-25T16:31:34.375Z,1758817894.375 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:31:36.143Z,1758817896.143 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0036.lzma.bak 2025-09-25T16:31:36.143Z,1758817896.143 [DataOverHttps](INFO): SBD MOMSN=26075078 2025-09-25T16:32:02.153Z,1758817922.153 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250925T151817/Express0037.lzma 2025-09-25T16:32:05.082Z,1758817925.082 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:32:13.207Z,1758817933.207 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0037.lzma.bak 2025-09-25T16:32:13.207Z,1758817933.207 [DataOverHttps](INFO): SBD MOMSN=26075082 2025-09-25T16:32:14.401Z,1758817934.401 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:32:14.401Z,1758817934.401 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:32:14.401Z,1758817934.401 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:37:15.072Z,1758818235.072 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:37:15.072Z,1758818235.072 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:37:15.073Z,1758818235.073 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:37:15.073Z,1758818235.073 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:37:15.485Z,1758818235.485 [Default:CheckIn:D] Stopped 2025-09-25T16:37:15.485Z,1758818235.485 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:37:15.869Z,1758818235.869 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.006160 min 2025-09-25T16:37:15.869Z,1758818235.869 [Default:CheckIn:E] Stopped 2025-09-25T16:37:15.869Z,1758818235.869 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:37:15.869Z,1758818235.869 [Default:CheckIn] Stopped 2025-09-25T16:37:15.869Z,1758818235.869 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:37:15.870Z,1758818235.870 [Default:CheckIn](INFO): Running loop #19 2025-09-25T16:37:15.870Z,1758818235.870 [Default:CheckIn] Running Loop=19 2025-09-25T16:37:15.870Z,1758818235.870 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:37:15.870Z,1758818235.870 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:37:17.878Z,1758818237.878 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163717.00,A,3648.16922,N,12147.27876,W,0.078,274.04,250925,,,A*70 2025-09-25T16:37:17.880Z,1758818237.880 [NAL9602](INFO): GPS fix at 20250925T163717: (36.802820, -121.787979) 2025-09-25T16:37:17.891Z,1758818237.891 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:37:17.891Z,1758818237.891 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:37:25.880Z,1758818245.880 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0039.lzma 2025-09-25T16:37:29.595Z,1758818249.595 [NAL9602](INFO): SBD MO Status=0, MOMSN=28326, MT Status=0, MTMSN=0 2025-09-25T16:37:29.595Z,1758818249.595 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:37:36.923Z,1758818256.923 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0039.lzma.bak 2025-09-25T16:37:36.923Z,1758818256.923 [DataOverHttps](INFO): SBD MOMSN=26075092 2025-09-25T16:38:00.298Z,1758818280.298 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:38:02.777Z,1758818282.777 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250925T151817/Express0040.lzma 2025-09-25T16:38:14.834Z,1758818294.834 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0040.lzma.bak 2025-09-25T16:38:14.835Z,1758818294.835 [DataOverHttps](INFO): SBD MOMSN=26075095 2025-09-25T16:38:16.071Z,1758818296.071 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:38:16.071Z,1758818296.071 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:38:16.071Z,1758818296.071 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:41:26.397Z,1758818486.397 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-09-25T16:41:26.398Z,1758818486.398 [DropWeight] Hardware Fault, FailCount= 1 2025-09-25T16:41:26.398Z,1758818486.398 [DropWeight](ERROR): Hardware Fault 2025-09-25T16:41:26.435Z,1758818486.435 [CommandExec](FAULT): Scheduling is paused 2025-09-25T16:41:26.437Z,1758818486.437 [CBIT](INFO): Critical error at 20250925T164126 2025-09-25T16:41:26.439Z,1758818486.439 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-09-25T16:41:26.440Z,1758818486.440 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-09-25T16:41:26.839Z,1758818486.839 [CBIT](INFO): Critical error at 20250925T164126 2025-09-25T16:43:16.728Z,1758818596.728 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:43:16.728Z,1758818596.728 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:43:16.728Z,1758818596.728 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:43:16.728Z,1758818596.728 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:43:17.135Z,1758818597.135 [Default:CheckIn:D] Stopped 2025-09-25T16:43:17.135Z,1758818597.135 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:43:17.533Z,1758818597.533 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.033667 min 2025-09-25T16:43:17.533Z,1758818597.533 [Default:CheckIn:E] Stopped 2025-09-25T16:43:17.533Z,1758818597.533 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:43:17.533Z,1758818597.533 [Default:CheckIn] Stopped 2025-09-25T16:43:17.533Z,1758818597.533 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:43:17.534Z,1758818597.534 [Default:CheckIn](INFO): Running loop #20 2025-09-25T16:43:17.534Z,1758818597.534 [Default:CheckIn] Running Loop=20 2025-09-25T16:43:17.534Z,1758818597.534 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:43:17.534Z,1758818597.534 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:43:19.541Z,1758818599.541 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164318.00,A,3648.17705,N,12147.28052,W,0.447,189.60,250925,,,A*7C 2025-09-25T16:43:19.544Z,1758818599.544 [NAL9602](INFO): GPS fix at 20250925T164318: (36.802951, -121.788009) 2025-09-25T16:43:19.603Z,1758818599.603 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:43:19.603Z,1758818599.603 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:43:27.133Z,1758818607.133 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20250925T151817/Courier0042.lzma 2025-09-25T16:43:38.175Z,1758818618.175 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0042.lzma.bak 2025-09-25T16:43:38.175Z,1758818618.175 [DataOverHttps](INFO): SBD MOMSN=26075148 2025-09-25T16:44:04.129Z,1758818644.129 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20250925T151817/Express0043.lzma 2025-09-25T16:44:15.171Z,1758818655.171 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0043.lzma.bak 2025-09-25T16:44:15.171Z,1758818655.171 [DataOverHttps](INFO): SBD MOMSN=26075153 2025-09-25T16:44:16.533Z,1758818656.533 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:44:16.533Z,1758818656.533 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:44:16.534Z,1758818656.534 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:44:33.076Z,1758818673.076 [NAL9602](INFO): SBD MO Status=0, MOMSN=28327, MT Status=0, MTMSN=0 2025-09-25T16:44:33.076Z,1758818673.076 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:45:03.825Z,1758818703.825 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:49:17.161Z,1758818957.161 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:49:17.161Z,1758818957.161 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:49:17.161Z,1758818957.161 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:49:17.161Z,1758818957.161 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:49:17.566Z,1758818957.566 [Default:CheckIn:D] Stopped 2025-09-25T16:49:17.566Z,1758818957.566 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:49:17.981Z,1758818957.981 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.040853 min 2025-09-25T16:49:17.981Z,1758818957.981 [Default:CheckIn:E] Stopped 2025-09-25T16:49:17.981Z,1758818957.981 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:49:17.981Z,1758818957.981 [Default:CheckIn] Stopped 2025-09-25T16:49:17.981Z,1758818957.981 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:49:17.982Z,1758818957.982 [Default:CheckIn](INFO): Running loop #21 2025-09-25T16:49:17.982Z,1758818957.982 [Default:CheckIn] Running Loop=21 2025-09-25T16:49:17.982Z,1758818957.982 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:49:17.982Z,1758818957.982 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:49:19.983Z,1758818959.983 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164919.00,A,3648.18961,N,12147.27705,W,0.039,86.29,250925,,,A*40 2025-09-25T16:49:19.986Z,1758818959.986 [NAL9602](INFO): GPS fix at 20250925T164919: (36.803160, -121.787951) 2025-09-25T16:49:20.017Z,1758818960.017 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:49:20.017Z,1758818960.017 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:49:29.232Z,1758818969.232 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0045.lzma 2025-09-25T16:49:32.107Z,1758818972.107 [NAL9602](INFO): SBD MO Status=0, MOMSN=28328, MT Status=0, MTMSN=0 2025-09-25T16:49:32.108Z,1758818972.108 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:49:40.275Z,1758818980.275 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0045.lzma.bak 2025-09-25T16:49:40.275Z,1758818980.275 [DataOverHttps](INFO): SBD MOMSN=26075186 2025-09-25T16:50:02.805Z,1758819002.805 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:50:06.333Z,1758819006.333 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20250925T151817/Express0046.lzma 2025-09-25T16:50:17.375Z,1758819017.375 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0046.lzma.bak 2025-09-25T16:50:17.375Z,1758819017.375 [DataOverHttps](INFO): SBD MOMSN=26075191 2025-09-25T16:50:18.577Z,1758819018.577 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:50:18.577Z,1758819018.577 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:50:18.578Z,1758819018.578 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:55:19.207Z,1758819319.207 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T16:55:19.207Z,1758819319.207 [Default:CheckIn:C.Wait] Stopped 2025-09-25T16:55:19.207Z,1758819319.207 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T16:55:19.208Z,1758819319.208 [Default:CheckIn:D] Running Loop=1 2025-09-25T16:55:19.640Z,1758819319.640 [Default:CheckIn:D] Stopped 2025-09-25T16:55:19.640Z,1758819319.640 [Default:CheckIn:E] Running Loop=1 2025-09-25T16:55:20.025Z,1758819320.025 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 129.075415 min 2025-09-25T16:55:20.025Z,1758819320.025 [Default:CheckIn:E] Stopped 2025-09-25T16:55:20.025Z,1758819320.025 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T16:55:20.025Z,1758819320.025 [Default:CheckIn] Stopped 2025-09-25T16:55:20.025Z,1758819320.025 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T16:55:20.025Z,1758819320.025 [Default:CheckIn](INFO): Running loop #22 2025-09-25T16:55:20.026Z,1758819320.026 [Default:CheckIn] Running Loop=22 2025-09-25T16:55:20.026Z,1758819320.026 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T16:55:20.026Z,1758819320.026 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T16:55:22.024Z,1758819322.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165521.00,A,3648.18164,N,12147.27701,W,0.486,197.20,250925,,,A*77 2025-09-25T16:55:22.027Z,1758819322.027 [NAL9602](INFO): GPS fix at 20250925T165521: (36.803027, -121.787950) 2025-09-25T16:55:22.038Z,1758819322.038 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T16:55:22.038Z,1758819322.038 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T16:55:30.372Z,1758819330.372 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0048.lzma 2025-09-25T16:55:34.948Z,1758819334.948 [NAL9602](INFO): SBD MO Status=0, MOMSN=28329, MT Status=0, MTMSN=0 2025-09-25T16:55:34.948Z,1758819334.948 [NAL9602](INFO): No messages in MT queue 2025-09-25T16:55:41.414Z,1758819341.414 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0048.lzma.bak 2025-09-25T16:55:41.415Z,1758819341.415 [DataOverHttps](INFO): SBD MOMSN=26075251 2025-09-25T16:56:05.682Z,1758819365.682 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T16:56:11.073Z,1758819371.073 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250925T151817/Express0049.lzma 2025-09-25T16:56:22.115Z,1758819382.115 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0049.lzma.bak 2025-09-25T16:56:22.115Z,1758819382.115 [DataOverHttps](INFO): SBD MOMSN=26075255 2025-09-25T16:56:23.457Z,1758819383.457 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T16:56:23.458Z,1758819383.458 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T16:56:23.458Z,1758819383.458 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T16:56:26.729Z,1758819386.729 [CBIT](INFO): Clearing failed state for component DropWeight 2025-09-25T16:56:26.729Z,1758819386.729 [DropWeight] No Fault, FailCount= 1 2025-09-25T17:01:24.030Z,1758819684.030 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:01:24.030Z,1758819684.030 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:01:24.030Z,1758819684.030 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:01:24.031Z,1758819684.031 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:01:24.433Z,1758819684.433 [Default:CheckIn:D] Stopped 2025-09-25T17:01:24.433Z,1758819684.433 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:01:24.849Z,1758819684.849 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 135.155298 min 2025-09-25T17:01:24.849Z,1758819684.849 [Default:CheckIn:E] Stopped 2025-09-25T17:01:24.850Z,1758819684.850 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:01:24.850Z,1758819684.850 [Default:CheckIn] Stopped 2025-09-25T17:01:24.850Z,1758819684.850 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:01:24.850Z,1758819684.850 [Default:CheckIn](INFO): Running loop #23 2025-09-25T17:01:24.850Z,1758819684.850 [Default:CheckIn] Running Loop=23 2025-09-25T17:01:24.850Z,1758819684.850 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:01:24.850Z,1758819684.850 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:01:26.849Z,1758819686.849 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170126.00,A,3648.17936,N,12147.27734,W,0.078,294.40,250925,,,A*75 2025-09-25T17:01:26.851Z,1758819686.851 [NAL9602](INFO): GPS fix at 20250925T170126: (36.802989, -121.787956) 2025-09-25T17:01:26.862Z,1758819686.862 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:01:26.862Z,1758819686.862 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:01:34.608Z,1758819694.608 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250925T151817/Courier0051.lzma 2025-09-25T17:01:45.651Z,1758819705.651 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0051.lzma.bak 2025-09-25T17:01:45.651Z,1758819705.651 [DataOverHttps](INFO): SBD MOMSN=26075265 2025-09-25T17:02:11.681Z,1758819731.681 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250925T151817/Express0052.lzma 2025-09-25T17:02:23.727Z,1758819743.727 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0052.lzma.bak 2025-09-25T17:02:23.727Z,1758819743.727 [DataOverHttps](INFO): SBD MOMSN=26075302 2025-09-25T17:02:25.042Z,1758819745.042 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:02:25.042Z,1758819745.042 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:02:25.042Z,1758819745.042 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:03:16.752Z,1758819796.752 [NAL9602](INFO): SBD MO Status=0, MOMSN=28330, MT Status=0, MTMSN=0 2025-09-25T17:03:16.752Z,1758819796.752 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:03:47.455Z,1758819827.455 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:07:25.625Z,1758820045.625 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:07:25.626Z,1758820045.626 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:07:25.626Z,1758820045.626 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:07:25.626Z,1758820045.626 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:07:26.041Z,1758820046.041 [Default:CheckIn:D] Stopped 2025-09-25T17:07:26.041Z,1758820046.041 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:07:26.429Z,1758820046.429 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 141.182096 min 2025-09-25T17:07:26.429Z,1758820046.429 [Default:CheckIn:E] Stopped 2025-09-25T17:07:26.429Z,1758820046.429 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:07:26.429Z,1758820046.429 [Default:CheckIn] Stopped 2025-09-25T17:07:26.429Z,1758820046.429 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:07:26.429Z,1758820046.429 [Default:CheckIn](INFO): Running loop #24 2025-09-25T17:07:26.429Z,1758820046.429 [Default:CheckIn] Running Loop=24 2025-09-25T17:07:26.430Z,1758820046.430 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:07:26.430Z,1758820046.430 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:07:28.438Z,1758820048.438 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170727.00,A,3648.18734,N,12147.27411,W,0.019,294.40,250925,,,A*72 2025-09-25T17:07:28.440Z,1758820048.440 [NAL9602](INFO): GPS fix at 20250925T170727: (36.803122, -121.787902) 2025-09-25T17:07:28.451Z,1758820048.451 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:07:28.451Z,1758820048.451 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:07:36.000Z,1758820056.000 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0054.lzma 2025-09-25T17:07:43.788Z,1758820063.788 [NAL9602](INFO): SBD MO Status=0, MOMSN=28331, MT Status=0, MTMSN=0 2025-09-25T17:07:43.788Z,1758820063.788 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:07:48.047Z,1758820068.047 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0054.lzma.bak 2025-09-25T17:07:48.047Z,1758820068.047 [DataOverHttps](INFO): SBD MOMSN=26075318 2025-09-25T17:08:14.490Z,1758820094.490 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:08:15.029Z,1758820095.029 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250925T151817/Express0055.lzma 2025-09-25T17:08:28.095Z,1758820108.095 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0055.lzma.bak 2025-09-25T17:08:28.095Z,1758820108.095 [DataOverHttps](INFO): SBD MOMSN=26075324 2025-09-25T17:08:29.450Z,1758820109.450 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:08:29.450Z,1758820109.450 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:08:29.450Z,1758820109.450 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:13:30.061Z,1758820410.061 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:13:30.061Z,1758820410.061 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:13:30.061Z,1758820410.061 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:13:30.061Z,1758820410.061 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:13:30.492Z,1758820410.492 [Default:CheckIn:D] Stopped 2025-09-25T17:13:30.492Z,1758820410.492 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:13:30.864Z,1758820410.864 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.256283 min 2025-09-25T17:13:30.864Z,1758820410.864 [Default:CheckIn:E] Stopped 2025-09-25T17:13:30.864Z,1758820410.864 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:13:30.865Z,1758820410.865 [Default:CheckIn] Stopped 2025-09-25T17:13:30.865Z,1758820410.865 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:13:30.865Z,1758820410.865 [Default:CheckIn](INFO): Running loop #25 2025-09-25T17:13:30.865Z,1758820410.865 [Default:CheckIn] Running Loop=25 2025-09-25T17:13:30.865Z,1758820410.865 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:13:30.865Z,1758820410.865 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:13:32.870Z,1758820412.870 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171332.00,A,3648.19720,N,12147.27742,W,0.039,2.42,250925,,,A*7F 2025-09-25T17:13:32.872Z,1758820412.872 [NAL9602](INFO): GPS fix at 20250925T171332: (36.803287, -121.787957) 2025-09-25T17:13:32.883Z,1758820412.883 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:13:32.883Z,1758820412.883 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:13:40.020Z,1758820420.020 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250925T151817/Courier0057.lzma 2025-09-25T17:13:46.603Z,1758820426.603 [NAL9602](INFO): SBD MO Status=0, MOMSN=28332, MT Status=0, MTMSN=0 2025-09-25T17:13:46.604Z,1758820426.604 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:13:52.068Z,1758820432.068 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0057.lzma.bak 2025-09-25T17:13:52.068Z,1758820432.068 [DataOverHttps](INFO): SBD MOMSN=26075418 2025-09-25T17:14:17.311Z,1758820457.311 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:14:18.109Z,1758820458.109 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250925T151817/Express0058.lzma 2025-09-25T17:14:30.155Z,1758820470.155 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0058.lzma.bak 2025-09-25T17:14:30.155Z,1758820470.155 [DataOverHttps](INFO): SBD MOMSN=26075422 2025-09-25T17:14:31.463Z,1758820471.463 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:14:31.463Z,1758820471.463 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:14:31.463Z,1758820471.463 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:19:32.032Z,1758820772.032 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:19:32.032Z,1758820772.032 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:19:32.032Z,1758820772.032 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:19:32.032Z,1758820772.032 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:19:32.446Z,1758820772.446 [Default:CheckIn:D] Stopped 2025-09-25T17:19:32.446Z,1758820772.446 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:19:32.847Z,1758820772.847 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 153.288835 min 2025-09-25T17:19:32.848Z,1758820772.848 [Default:CheckIn:E] Stopped 2025-09-25T17:19:32.848Z,1758820772.848 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:19:32.848Z,1758820772.848 [Default:CheckIn] Stopped 2025-09-25T17:19:32.848Z,1758820772.848 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:19:32.848Z,1758820772.848 [Default:CheckIn](INFO): Running loop #26 2025-09-25T17:19:32.848Z,1758820772.848 [Default:CheckIn] Running Loop=26 2025-09-25T17:19:32.848Z,1758820772.848 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:19:32.849Z,1758820772.849 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:19:34.856Z,1758820774.856 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171934.00,A,3648.17775,N,12147.27677,W,0.194,2.42,250925,,,A*7C 2025-09-25T17:19:34.859Z,1758820774.859 [NAL9602](INFO): GPS fix at 20250925T171934: (36.802962, -121.787946) 2025-09-25T17:19:34.890Z,1758820774.890 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:19:34.890Z,1758820774.890 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:19:42.256Z,1758820782.256 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0060.lzma 2025-09-25T17:19:53.299Z,1758820793.299 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0060.lzma.bak 2025-09-25T17:19:53.299Z,1758820793.299 [DataOverHttps](INFO): SBD MOMSN=26075432 2025-09-25T17:20:19.233Z,1758820819.233 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250925T151817/Express0061.lzma 2025-09-25T17:20:30.275Z,1758820830.275 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0061.lzma.bak 2025-09-25T17:20:30.275Z,1758820830.275 [DataOverHttps](INFO): SBD MOMSN=26075439 2025-09-25T17:20:31.431Z,1758820831.431 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:20:31.431Z,1758820831.431 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:20:31.431Z,1758820831.431 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:21:57.485Z,1758820917.485 [NAL9602](INFO): SBD MO Status=0, MOMSN=28333, MT Status=0, MTMSN=0 2025-09-25T17:21:57.485Z,1758820917.485 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:22:28.190Z,1758820948.190 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:25:32.177Z,1758821132.177 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:25:32.177Z,1758821132.177 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:25:32.177Z,1758821132.177 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:25:32.178Z,1758821132.178 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:25:32.593Z,1758821132.593 [Default:CheckIn:D] Stopped 2025-09-25T17:25:32.593Z,1758821132.593 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:25:32.978Z,1758821132.978 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 159.291016 min 2025-09-25T17:25:32.978Z,1758821132.978 [Default:CheckIn:E] Stopped 2025-09-25T17:25:32.978Z,1758821132.978 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:25:32.978Z,1758821132.978 [Default:CheckIn] Stopped 2025-09-25T17:25:32.978Z,1758821132.978 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:25:32.978Z,1758821132.978 [Default:CheckIn](INFO): Running loop #27 2025-09-25T17:25:32.978Z,1758821132.978 [Default:CheckIn] Running Loop=27 2025-09-25T17:25:32.979Z,1758821132.979 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:25:32.979Z,1758821132.979 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:25:34.992Z,1758821134.992 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172534.00,A,3648.18071,N,12147.27640,W,0.019,2.42,250925,,,A*7F 2025-09-25T17:25:34.994Z,1758821134.994 [NAL9602](INFO): GPS fix at 20250925T172534: (36.803012, -121.787940) 2025-09-25T17:25:35.005Z,1758821135.005 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:25:35.006Z,1758821135.006 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:25:42.736Z,1758821142.736 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0063.lzma 2025-09-25T17:25:53.779Z,1758821153.779 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0063.lzma.bak 2025-09-25T17:25:53.779Z,1758821153.779 [DataOverHttps](INFO): SBD MOMSN=26075509 2025-09-25T17:26:05.693Z,1758821165.693 [NAL9602](INFO): SBD MO Status=2, MOMSN=28334, MT Status=2, MTMSN=0 2025-09-25T17:26:05.693Z,1758821165.693 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T17:26:19.781Z,1758821179.781 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250925T151817/Express0064.lzma 2025-09-25T17:26:27.912Z,1758821187.912 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-09-25T17:26:27.912Z,1758821187.912 [DropWeight] Hardware Fault, FailCount= 1 2025-09-25T17:26:27.912Z,1758821187.912 [DropWeight](ERROR): Hardware Fault 2025-09-25T17:26:27.931Z,1758821187.931 [CBIT](INFO): Critical error at 20250925T172627 2025-09-25T17:26:27.933Z,1758821187.933 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-09-25T17:26:27.934Z,1758821187.934 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-09-25T17:26:28.340Z,1758821188.340 [CBIT](INFO): Critical error at 20250925T172627 2025-09-25T17:26:30.823Z,1758821190.823 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0064.lzma.bak 2025-09-25T17:26:30.823Z,1758821190.823 [DataOverHttps](INFO): SBD MOMSN=26075512 2025-09-25T17:26:31.984Z,1758821191.984 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:26:31.984Z,1758821191.984 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:26:31.984Z,1758821191.984 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:26:42.059Z,1758821202.059 [NAL9602](INFO): SBD MO Status=0, MOMSN=28334, MT Status=0, MTMSN=0 2025-09-25T17:26:42.059Z,1758821202.059 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:27:12.757Z,1758821232.757 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:31:32.554Z,1758821492.554 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:31:32.554Z,1758821492.554 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:31:32.554Z,1758821492.554 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:31:32.554Z,1758821492.554 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:31:32.961Z,1758821492.961 [Default:CheckIn:D] Stopped 2025-09-25T17:31:32.961Z,1758821492.961 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:31:33.350Z,1758821493.350 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 165.297428 min 2025-09-25T17:31:33.350Z,1758821493.350 [Default:CheckIn:E] Stopped 2025-09-25T17:31:33.350Z,1758821493.350 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:31:33.350Z,1758821493.350 [Default:CheckIn] Stopped 2025-09-25T17:31:33.350Z,1758821493.350 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:31:33.351Z,1758821493.351 [Default:CheckIn](INFO): Running loop #28 2025-09-25T17:31:33.351Z,1758821493.351 [Default:CheckIn] Running Loop=28 2025-09-25T17:31:33.351Z,1758821493.351 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:31:33.351Z,1758821493.351 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:31:35.361Z,1758821495.361 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173134.00,A,3648.18499,N,12147.27303,W,0.039,2.42,250925,,,A*78 2025-09-25T17:31:35.364Z,1758821495.364 [NAL9602](INFO): GPS fix at 20250925T173134: (36.803083, -121.787884) 2025-09-25T17:31:35.375Z,1758821495.375 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:31:35.375Z,1758821495.375 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:31:45.573Z,1758821505.573 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20250925T151817/Courier0066.lzma 2025-09-25T17:31:47.079Z,1758821507.079 [NAL9602](INFO): SBD MO Status=0, MOMSN=28335, MT Status=0, MTMSN=0 2025-09-25T17:31:47.080Z,1758821507.080 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:31:56.615Z,1758821516.615 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0066.lzma.bak 2025-09-25T17:31:56.615Z,1758821516.615 [DataOverHttps](INFO): SBD MOMSN=26075529 2025-09-25T17:32:14.541Z,1758821534.541 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:32:17.781Z,1758821537.781 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:32:22.789Z,1758821542.789 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250925T151817/Express0067.lzma 2025-09-25T17:32:33.831Z,1758821553.831 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0067.lzma.bak 2025-09-25T17:32:33.831Z,1758821553.831 [DataOverHttps](INFO): SBD MOMSN=26075535 2025-09-25T17:32:35.187Z,1758821555.187 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:32:35.188Z,1758821555.188 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:32:35.188Z,1758821555.188 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:34:42.405Z,1758821682.405 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:34:57.757Z,1758821697.757 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:35:13.918Z,1758821713.918 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:35:29.269Z,1758821729.269 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:35:45.024Z,1758821745.024 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:35:59.972Z,1758821759.972 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:36:15.333Z,1758821775.333 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:36:30.676Z,1758821790.676 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:36:45.628Z,1758821805.628 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:37:00.177Z,1758821820.177 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-09-25T17:37:35.742Z,1758821855.742 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:37:35.742Z,1758821855.742 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:37:35.742Z,1758821855.742 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:37:35.743Z,1758821855.743 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:37:36.152Z,1758821856.152 [Default:CheckIn:D] Stopped 2025-09-25T17:37:36.152Z,1758821856.152 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:37:36.552Z,1758821856.552 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 171.350618 min 2025-09-25T17:37:36.552Z,1758821856.552 [Default:CheckIn:E] Stopped 2025-09-25T17:37:36.569Z,1758821856.569 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:37:36.569Z,1758821856.569 [Default:CheckIn] Stopped 2025-09-25T17:37:36.569Z,1758821856.569 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:37:36.569Z,1758821856.569 [Default:CheckIn](INFO): Running loop #29 2025-09-25T17:37:36.569Z,1758821856.569 [Default:CheckIn] Running Loop=29 2025-09-25T17:37:36.569Z,1758821856.569 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:37:36.569Z,1758821856.569 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:37:38.563Z,1758821858.563 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173737.00,A,3648.17911,N,12147.27288,W,0.078,2.42,250925,,,A*78 2025-09-25T17:37:38.566Z,1758821858.566 [NAL9602](INFO): GPS fix at 20250925T173737: (36.802985, -121.787881) 2025-09-25T17:37:38.577Z,1758821858.577 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:37:38.577Z,1758821858.577 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:37:48.432Z,1758821868.432 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0069.lzma 2025-09-25T17:38:00.479Z,1758821880.479 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0069.lzma.bak 2025-09-25T17:38:00.479Z,1758821880.479 [DataOverHttps](INFO): SBD MOMSN=26075572 2025-09-25T17:38:26.637Z,1758821906.637 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250925T151817/Express0070.lzma 2025-09-25T17:38:37.679Z,1758821917.679 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0070.lzma.bak 2025-09-25T17:38:37.679Z,1758821917.679 [DataOverHttps](INFO): SBD MOMSN=26075575 2025-09-25T17:38:38.776Z,1758821918.776 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:38:38.776Z,1758821918.776 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:38:38.776Z,1758821918.776 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:39:21.185Z,1758821961.185 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-09-25T17:39:21.191Z,1758821961.191 [BPC1](ERROR): Battery stick #16 (s/n: 00F8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T17:39:21.192Z,1758821961.192 [BPC1](ERROR): Battery stick #17 (s/n: 0084) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T17:39:21.193Z,1758821961.193 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T17:39:21.194Z,1758821961.194 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-25T17:39:21.195Z,1758821961.195 [BPC1](ERROR): Battery stick #38 (s/n: 01BC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48E0. 2025-09-25T17:39:21.195Z,1758821961.195 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T17:39:21.196Z,1758821961.196 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T17:39:21.197Z,1758821961.197 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-09-25T17:39:21.198Z,1758821961.198 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-09-25T17:39:21.198Z,1758821961.198 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 3. 2025-09-25T17:39:21.201Z,1758821961.201 [BPC1](INFO): Received data from all battery sticks. 2025-09-25T17:40:18.964Z,1758822018.964 [NAL9602](INFO): SBD MO Status=2, MOMSN=28336, MT Status=2, MTMSN=0 2025-09-25T17:40:18.964Z,1758822018.964 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T17:40:49.327Z,1758822049.327 [NAL9602](INFO): SBD MO Status=0, MOMSN=28336, MT Status=0, MTMSN=0 2025-09-25T17:40:49.327Z,1758822049.327 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:41:20.019Z,1758822080.019 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:41:28.130Z,1758822088.130 [CBIT](INFO): Clearing failed state for component DropWeight 2025-09-25T17:41:28.130Z,1758822088.130 [DropWeight] No Fault, FailCount= 1 2025-09-25T17:43:39.465Z,1758822219.465 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:43:39.466Z,1758822219.466 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:43:39.466Z,1758822219.466 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:43:39.466Z,1758822219.466 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:43:39.870Z,1758822219.870 [Default:CheckIn:D] Stopped 2025-09-25T17:43:39.870Z,1758822219.870 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:43:40.280Z,1758822220.280 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 177.412581 min 2025-09-25T17:43:40.280Z,1758822220.280 [Default:CheckIn:E] Stopped 2025-09-25T17:43:40.281Z,1758822220.281 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:43:40.281Z,1758822220.281 [Default:CheckIn] Stopped 2025-09-25T17:43:40.281Z,1758822220.281 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:43:40.281Z,1758822220.281 [Default:CheckIn](INFO): Running loop #30 2025-09-25T17:43:40.281Z,1758822220.281 [Default:CheckIn] Running Loop=30 2025-09-25T17:43:40.281Z,1758822220.281 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:43:40.281Z,1758822220.281 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:43:42.283Z,1758822222.283 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174341.00,A,3648.18176,N,12147.27800,W,0.136,2.42,250925,,,A*7D 2025-09-25T17:43:42.286Z,1758822222.286 [NAL9602](INFO): GPS fix at 20250925T174341: (36.803029, -121.787967) 2025-09-25T17:43:42.297Z,1758822222.297 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:43:42.297Z,1758822222.297 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:43:44.577Z,1758822224.577 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-09-25T17:43:52.996Z,1758822232.996 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20250925T151817/Courier0072.lzma 2025-09-25T17:44:04.039Z,1758822244.039 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0072.lzma.bak 2025-09-25T17:44:04.039Z,1758822244.039 [DataOverHttps](INFO): SBD MOMSN=26075586 2025-09-25T17:44:05.311Z,1758822245.311 [NAL9602](INFO): SBD MO Status=2, MOMSN=28337, MT Status=2, MTMSN=0 2025-09-25T17:44:05.311Z,1758822245.311 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T17:44:30.061Z,1758822270.061 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250925T151817/Express0073.lzma 2025-09-25T17:44:41.112Z,1758822281.112 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0073.lzma.bak 2025-09-25T17:44:41.112Z,1758822281.112 [DataOverHttps](INFO): SBD MOMSN=26075610 2025-09-25T17:44:42.510Z,1758822282.510 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:44:42.510Z,1758822282.510 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:44:42.510Z,1758822282.510 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:45:18.499Z,1758822318.499 [NAL9602](INFO): SBD MO Status=0, MOMSN=28337, MT Status=0, MTMSN=0 2025-09-25T17:45:18.500Z,1758822318.500 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:45:49.202Z,1758822349.202 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:49:43.198Z,1758822583.198 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:49:43.198Z,1758822583.198 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:49:43.199Z,1758822583.199 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:49:43.199Z,1758822583.199 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:49:43.610Z,1758822583.610 [Default:CheckIn:D] Stopped 2025-09-25T17:49:43.610Z,1758822583.610 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:49:44.008Z,1758822584.008 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 183.474902 min 2025-09-25T17:49:44.008Z,1758822584.008 [Default:CheckIn:E] Stopped 2025-09-25T17:49:44.025Z,1758822584.025 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:49:44.025Z,1758822584.025 [Default:CheckIn] Stopped 2025-09-25T17:49:44.025Z,1758822584.025 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:49:44.025Z,1758822584.025 [Default:CheckIn](INFO): Running loop #31 2025-09-25T17:49:44.025Z,1758822584.025 [Default:CheckIn] Running Loop=31 2025-09-25T17:49:44.025Z,1758822584.025 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:49:44.025Z,1758822584.025 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:49:46.023Z,1758822586.023 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174945.00,A,3648.17756,N,12147.27928,W,0.117,0.00,250925,,,A*74 2025-09-25T17:49:46.026Z,1758822586.026 [NAL9602](INFO): GPS fix at 20250925T174945: (36.802959, -121.787988) 2025-09-25T17:49:46.037Z,1758822586.037 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:49:46.037Z,1758822586.037 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:49:53.392Z,1758822593.392 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0075.lzma 2025-09-25T17:49:59.752Z,1758822599.752 [NAL9602](INFO): SBD MO Status=0, MOMSN=28338, MT Status=0, MTMSN=0 2025-09-25T17:49:59.753Z,1758822599.753 [NAL9602](INFO): No messages in MT queue 2025-09-25T17:50:04.435Z,1758822604.435 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0075.lzma.bak 2025-09-25T17:50:04.435Z,1758822604.435 [DataOverHttps](INFO): SBD MOMSN=26075621 2025-09-25T17:50:30.458Z,1758822630.458 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T17:50:31.353Z,1758822631.353 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250925T151817/Express0076.lzma 2025-09-25T17:50:42.395Z,1758822642.395 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0076.lzma.bak 2025-09-25T17:50:42.395Z,1758822642.395 [DataOverHttps](INFO): SBD MOMSN=26075625 2025-09-25T17:50:43.806Z,1758822643.806 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:50:43.806Z,1758822643.806 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:50:43.806Z,1758822643.806 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:55:44.528Z,1758822944.528 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T17:55:44.528Z,1758822944.528 [Default:CheckIn:C.Wait] Stopped 2025-09-25T17:55:44.528Z,1758822944.528 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T17:55:44.528Z,1758822944.528 [Default:CheckIn:D] Running Loop=1 2025-09-25T17:55:44.929Z,1758822944.929 [Default:CheckIn:D] Stopped 2025-09-25T17:55:44.929Z,1758822944.929 [Default:CheckIn:E] Running Loop=1 2025-09-25T17:55:45.337Z,1758822945.337 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 189.496908 min 2025-09-25T17:55:45.337Z,1758822945.337 [Default:CheckIn:E] Stopped 2025-09-25T17:55:45.337Z,1758822945.337 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T17:55:45.337Z,1758822945.337 [Default:CheckIn] Stopped 2025-09-25T17:55:45.337Z,1758822945.337 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T17:55:45.338Z,1758822945.338 [Default:CheckIn](INFO): Running loop #32 2025-09-25T17:55:45.338Z,1758822945.338 [Default:CheckIn] Running Loop=32 2025-09-25T17:55:45.338Z,1758822945.338 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T17:55:45.338Z,1758822945.338 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T17:55:47.347Z,1758822947.347 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175546.00,A,3648.18399,N,12147.27565,W,0.078,0.00,250925,,,A*7F 2025-09-25T17:55:47.358Z,1758822947.358 [NAL9602](INFO): GPS fix at 20250925T175546: (36.803066, -121.787927) 2025-09-25T17:55:47.370Z,1758822947.370 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T17:55:47.370Z,1758822947.370 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T17:55:54.164Z,1758822954.164 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250925T151817/Courier0078.lzma 2025-09-25T17:56:05.207Z,1758822965.207 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0078.lzma.bak 2025-09-25T17:56:05.207Z,1758822965.207 [DataOverHttps](INFO): SBD MOMSN=26075663 2025-09-25T17:56:06.735Z,1758822966.735 [NAL9602](INFO): SBD MO Status=2, MOMSN=28339, MT Status=2, MTMSN=0 2025-09-25T17:56:06.735Z,1758822966.735 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T17:56:32.209Z,1758822992.209 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250925T151817/Express0079.lzma 2025-09-25T17:56:43.255Z,1758823003.255 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0079.lzma.bak 2025-09-25T17:56:43.255Z,1758823003.255 [DataOverHttps](INFO): SBD MOMSN=26075667 2025-09-25T17:56:44.741Z,1758823004.741 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T17:56:44.741Z,1758823004.741 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T17:56:44.741Z,1758823004.741 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T17:57:14.681Z,1758823034.681 [NAL9602](INFO): SBD MO Status=2, MOMSN=28339, MT Status=2, MTMSN=0 2025-09-25T17:57:14.681Z,1758823034.681 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T17:59:04.622Z,1758823144.622 [NAL9602](INFO): SBD MO Status=2, MOMSN=28339, MT Status=2, MTMSN=0 2025-09-25T17:59:04.622Z,1758823144.622 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T17:59:32.911Z,1758823172.911 [NAL9602](INFO): SBD MO Status=2, MOMSN=28339, MT Status=2, MTMSN=0 2025-09-25T17:59:32.911Z,1758823172.911 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-09-25T18:00:49.674Z,1758823249.674 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-25T18:01:20.379Z,1758823280.379 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T18:01:45.443Z,1758823305.443 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T18:01:45.443Z,1758823305.443 [Default:CheckIn:C.Wait] Stopped 2025-09-25T18:01:45.444Z,1758823305.444 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T18:01:45.444Z,1758823305.444 [Default:CheckIn:D] Running Loop=1 2025-09-25T18:01:45.860Z,1758823305.860 [Default:CheckIn:D] Stopped 2025-09-25T18:01:45.860Z,1758823305.860 [Default:CheckIn:E] Running Loop=1 2025-09-25T18:01:46.253Z,1758823306.253 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 195.512419 min 2025-09-25T18:01:46.253Z,1758823306.253 [Default:CheckIn:E] Stopped 2025-09-25T18:01:46.254Z,1758823306.254 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T18:01:46.254Z,1758823306.254 [Default:CheckIn] Stopped 2025-09-25T18:01:46.254Z,1758823306.254 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T18:01:46.254Z,1758823306.254 [Default:CheckIn](INFO): Running loop #33 2025-09-25T18:01:46.254Z,1758823306.254 [Default:CheckIn] Running Loop=33 2025-09-25T18:01:46.254Z,1758823306.254 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T18:01:46.254Z,1758823306.254 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T18:01:48.258Z,1758823308.258 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180147.00,A,3648.17060,N,12147.28064,W,0.175,213.98,250925,,,A*7C 2025-09-25T18:01:48.260Z,1758823308.260 [NAL9602](INFO): GPS fix at 20250925T180147: (36.802843, -121.788011) 2025-09-25T18:01:48.272Z,1758823308.272 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T18:01:48.272Z,1758823308.272 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T18:01:55.828Z,1758823315.828 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250925T151817/Courier0081.lzma 2025-09-25T18:02:06.871Z,1758823326.871 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0081.lzma.bak 2025-09-25T18:02:06.871Z,1758823326.871 [DataOverHttps](INFO): SBD MOMSN=26075711 2025-09-25T18:02:18.959Z,1758823338.959 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-25T18:02:19.033Z,1758823339.033 [NAL9602](ERROR): received: +CSQ:0 OK339, 2, 0, 0, 0 OK 2025-09-25T18:02:32.761Z,1758823352.761 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20250925T151817/Express0082.lzma 2025-09-25T18:02:43.803Z,1758823363.803 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0082.lzma.bak 2025-09-25T18:02:43.803Z,1758823363.803 [DataOverHttps](INFO): SBD MOMSN=26075717 2025-09-25T18:02:45.244Z,1758823365.244 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T18:02:45.245Z,1758823365.245 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T18:02:45.245Z,1758823365.245 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T18:06:50.587Z,1758823610.587 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-25T18:07:21.314Z,1758823641.314 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T18:07:45.967Z,1758823665.967 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T18:07:45.967Z,1758823665.967 [Default:CheckIn:C.Wait] Stopped 2025-09-25T18:07:45.967Z,1758823665.967 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T18:07:45.967Z,1758823665.967 [Default:CheckIn:D] Running Loop=1 2025-09-25T18:07:46.378Z,1758823666.378 [Default:CheckIn:D] Stopped 2025-09-25T18:07:46.378Z,1758823666.378 [Default:CheckIn:E] Running Loop=1 2025-09-25T18:07:46.794Z,1758823666.794 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 201.521045 min 2025-09-25T18:07:46.794Z,1758823666.794 [Default:CheckIn:E] Stopped 2025-09-25T18:07:46.794Z,1758823666.794 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T18:07:46.794Z,1758823666.794 [Default:CheckIn] Stopped 2025-09-25T18:07:46.794Z,1758823666.794 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T18:07:46.795Z,1758823666.795 [Default:CheckIn](INFO): Running loop #34 2025-09-25T18:07:46.795Z,1758823666.795 [Default:CheckIn] Running Loop=34 2025-09-25T18:07:46.795Z,1758823666.795 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T18:07:46.795Z,1758823666.795 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T18:07:48.783Z,1758823668.783 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180748.00,A,3648.15834,N,12147.28471,W,0.389,213.98,250925,,,A*7F 2025-09-25T18:07:48.786Z,1758823668.786 [NAL9602](INFO): GPS fix at 20250925T180748: (36.802639, -121.788078) 2025-09-25T18:07:48.797Z,1758823668.797 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T18:07:48.797Z,1758823668.797 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T18:07:56.276Z,1758823676.276 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250925T151817/Courier0084.lzma 2025-09-25T18:08:07.319Z,1758823687.319 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0084.lzma.bak 2025-09-25T18:08:07.319Z,1758823687.319 [DataOverHttps](INFO): SBD MOMSN=26075773 2025-09-25T18:08:19.486Z,1758823699.486 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-25T18:08:19.561Z,1758823699.561 [NAL9602](ERROR): received: +CSQ:0 OK339, 2, 0, 0, 0 OK 2025-09-25T18:08:33.237Z,1758823713.237 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20250925T151817/Express0085.lzma 2025-09-25T18:08:44.279Z,1758823724.279 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0085.lzma.bak 2025-09-25T18:08:44.279Z,1758823724.279 [DataOverHttps](INFO): SBD MOMSN=26075782 2025-09-25T18:08:45.787Z,1758823725.787 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T18:08:45.787Z,1758823725.787 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T18:08:45.787Z,1758823725.787 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T18:11:29.417Z,1758823889.417 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-09-25T18:11:29.418Z,1758823889.418 [DropWeight] Hardware Fault, FailCount= 1 2025-09-25T18:11:29.418Z,1758823889.418 [DropWeight](ERROR): Hardware Fault 2025-09-25T18:11:29.436Z,1758823889.436 [CBIT](INFO): Critical error at 20250925T181129 2025-09-25T18:11:29.438Z,1758823889.438 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-09-25T18:11:29.439Z,1758823889.439 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-09-25T18:11:29.841Z,1758823889.841 [CBIT](INFO): Critical error at 20250925T181129 2025-09-25T18:12:51.434Z,1758823971.434 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-25T18:13:22.138Z,1758824002.138 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T18:13:46.392Z,1758824026.392 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-09-25T18:13:46.412Z,1758824026.412 [Default:CheckIn:C.Wait] Stopped 2025-09-25T18:13:46.413Z,1758824026.413 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T18:13:46.413Z,1758824026.413 [Default:CheckIn:D] Running Loop=1 2025-09-25T18:13:46.790Z,1758824026.790 [Default:CheckIn:D] Stopped 2025-09-25T18:13:46.790Z,1758824026.790 [Default:CheckIn:E] Running Loop=1 2025-09-25T18:13:47.198Z,1758824027.198 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 207.527913 min 2025-09-25T18:13:47.198Z,1758824027.198 [Default:CheckIn:E] Stopped 2025-09-25T18:13:47.198Z,1758824027.198 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-09-25T18:13:47.199Z,1758824027.199 [Default:CheckIn] Stopped 2025-09-25T18:13:47.199Z,1758824027.199 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T18:13:47.199Z,1758824027.199 [Default:CheckIn](INFO): Running loop #35 2025-09-25T18:13:47.199Z,1758824027.199 [Default:CheckIn] Running Loop=35 2025-09-25T18:13:47.199Z,1758824027.199 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-09-25T18:13:47.199Z,1758824027.199 [Default:CheckIn:Read_GPS] Running Loop=1 2025-09-25T18:14:19.906Z,1758824059.906 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-09-25T18:14:19.989Z,1758824059.989 [NAL9602](ERROR): received: +CSQ:0 OK339, 2, 0, 0, 0 OK 2025-09-25T18:18:47.430Z,1758824327.430 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-09-25T18:13:47.2Z 2025-09-25T18:18:47.430Z,1758824327.430 [Default:CheckIn:Read_GPS] Stopped 2025-09-25T18:18:47.431Z,1758824327.431 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-09-25T18:18:51.526Z,1758824331.526 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-09-25T18:19:22.257Z,1758824362.257 [NAL9602](INFO): Not Powering down - fast GPS 2025-09-25T18:19:25.872Z,1758824365.872 [DataOverHttps](INFO): Sending 114 bytes from file Logs/20250925T151817/Courier0087.lzma 2025-09-25T18:19:26.875Z,1758824366.875 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Courier0087.lzma.bak 2025-09-25T18:19:26.875Z,1758824366.875 [DataOverHttps](INFO): SBD MOMSN=26075831 2025-09-25T18:19:42.897Z,1758824382.897 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20250925T151817/Express0088.lzma 2025-09-25T18:19:43.900Z,1758824383.900 [DataOverHttps](INFO): Moved sent file to Logs/20250925T151817/Express0088.lzma.bak 2025-09-25T18:19:43.900Z,1758824383.900 [DataOverHttps](INFO): SBD MOMSN=26075839 2025-09-25T18:19:45.308Z,1758824385.308 [Default:CheckIn:Read_Iridium] Stopped 2025-09-25T18:19:45.309Z,1758824385.309 [Default:CheckIn:C.Wait] Running Loop=1 2025-09-25T18:19:45.309Z,1758824385.309 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-09-25T18:21:24.202Z,1758824484.202 [CommandExec](IMPORTANT): got command quit 2025-09-25T18:21:25.209Z,1758824485.209 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:21:25.209Z,1758824485.209 [CommandExec](INFO): Uninitializing the command executive. 2025-09-25T18:21:25.209Z,1758824485.209 [CommandExec](INFO): Uninitializing the command scheduler. 2025-09-25T18:21:25.209Z,1758824485.209 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:25.232Z,1758824485.232 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-09-25T18:21:25.233Z,1758824485.233 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-09-25T18:21:25.233Z,1758824485.233 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:25.233Z,1758824485.233 [NavChartDb](INFO): Join timeout helper Thread ID is 1921 2025-09-25T18:21:25.269Z,1758824485.269 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:21:25.269Z,1758824485.269 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:25.273Z,1758824485.273 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-09-25T18:21:25.273Z,1758824485.273 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:25.273Z,1758824485.273 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1922 2025-09-25T18:21:25.489Z,1758824485.489 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:21:25.489Z,1758824485.489 [WetLabsBB2FL](INFO): Powering down 2025-09-25T18:21:25.490Z,1758824485.490 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:25.492Z,1758824485.492 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-09-25T18:21:25.492Z,1758824485.492 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:25.492Z,1758824485.492 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1923 2025-09-25T18:21:26.305Z,1758824486.305 [CTD_Seabird](INFO): Powering down 2025-09-25T18:21:26.317Z,1758824486.317 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:21:26.317Z,1758824486.317 [CTD_Seabird](INFO): Powering down 2025-09-25T18:21:26.329Z,1758824486.329 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:26.341Z,1758824486.341 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-09-25T18:21:26.341Z,1758824486.341 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:26.341Z,1758824486.341 [Radio_Surface](INFO): Join timeout helper Thread ID is 1924 2025-09-25T18:21:26.385Z,1758824486.385 [Radio_Surface](INFO): Powering down 2025-09-25T18:21:26.386Z,1758824486.386 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:21:26.386Z,1758824486.386 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:26.393Z,1758824486.393 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-09-25T18:21:26.393Z,1758824486.393 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:26.393Z,1758824486.393 [Onboard](INFO): Join timeout helper Thread ID is 1925 2025-09-25T18:21:26.453Z,1758824486.453 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-09-25T18:21:29.001Z,1758824489.001 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:21:29.001Z,1758824489.001 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:29.005Z,1758824489.005 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-09-25T18:21:29.006Z,1758824489.006 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:29.006Z,1758824489.006 [DataOverHttps](INFO): Join timeout helper Thread ID is 1926 2025-09-25T18:21:29.467Z,1758824489.467 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:21:29.467Z,1758824489.467 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:29.485Z,1758824489.485 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-09-25T18:21:29.485Z,1758824489.485 [DAT ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:29.486Z,1758824489.486 [DAT](INFO): Join timeout helper Thread ID is 1927 2025-09-25T18:21:29.524Z,1758824489.524 [DAT](INFO): Powering down 2025-09-25T18:21:29.597Z,1758824489.597 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:21:29.597Z,1758824489.597 [DAT](INFO): Powering down 2025-09-25T18:21:29.598Z,1758824489.598 [DAT ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:29.601Z,1758824489.601 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-09-25T18:21:29.602Z,1758824489.602 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:29.602Z,1758824489.602 [BackseatComponent](INFO): Join timeout helper Thread ID is 1928 2025-09-25T18:21:29.709Z,1758824489.709 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:21:29.709Z,1758824489.709 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:29.714Z,1758824489.714 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-09-25T18:21:29.714Z,1758824489.714 [logger ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:29.714Z,1758824489.714 [logger](INFO): Join timeout helper Thread ID is 1929 2025-09-25T18:21:29.733Z,1758824489.733 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:21:29.733Z,1758824489.733 [logger ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:29.741Z,1758824489.741 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-09-25T18:21:29.741Z,1758824489.741 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:29.742Z,1758824489.742 [CommandLine](INFO): Join timeout helper Thread ID is 1930 2025-09-25T18:21:29.742Z,1758824489.742 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:21:29.743Z,1758824489.743 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:29.744Z,1758824489.744 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-09-25T18:21:29.744Z,1758824489.744 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:29.745Z,1758824489.745 [CommandExec](INFO): Join timeout helper Thread ID is 1931 2025-09-25T18:21:29.746Z,1758824489.746 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-09-25T18:21:29.746Z,1758824489.746 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:29.746Z,1758824489.746 [controlThread](INFO): Join timeout helper Thread ID is 1932 2025-09-25T18:21:29.917Z,1758824489.917 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-09-25T18:21:29.917Z,1758824489.917 [controlThread](DEBUG): Uninitializing ControlThread 2025-09-25T18:21:29.917Z,1758824489.917 [AHRS_M2](INFO): Powering down 2025-09-25T18:21:29.997Z,1758824489.997 [NAL9602](INFO): Powering down 2025-09-25T18:21:29.998Z,1758824489.998 [Sonardyne_Nano](INFO): Powering down 2025-09-25T18:21:30.205Z,1758824490.205 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-09-25T18:21:30.206Z,1758824490.206 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-09-25T18:21:30.206Z,1758824490.206 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-09-25T18:21:30.207Z,1758824490.207 [MissionManager](INFO): Uninitializing Mission Default 2025-09-25T18:21:30.207Z,1758824490.207 [Default] Stopped 2025-09-25T18:21:30.207Z,1758824490.207 [Default](DEBUG): Aggregate::uninitialize Default 2025-09-25T18:21:30.207Z,1758824490.207 [Default:B.GoToSurface] Stopped 2025-09-25T18:21:30.207Z,1758824490.207 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-09-25T18:21:30.207Z,1758824490.207 [Default:CheckIn] Stopped 2025-09-25T18:21:30.207Z,1758824490.207 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-09-25T18:21:30.207Z,1758824490.207 [Default:CheckIn:C.Wait] Stopped 2025-09-25T18:21:30.207Z,1758824490.207 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-09-25T18:21:30.210Z,1758824490.210 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-09-25T18:21:30.211Z,1758824490.211 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-09-25T18:21:30.211Z,1758824490.211 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-09-25T18:21:30.211Z,1758824490.211 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-09-25T18:21:30.212Z,1758824490.212 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-09-25T18:21:30.212Z,1758824490.212 [BuoyancyServo](INFO): Powering down 2025-09-25T18:21:30.225Z,1758824490.225 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-09-25T18:21:30.225Z,1758824490.225 [ElevatorServo](INFO): Powering down 2025-09-25T18:21:30.226Z,1758824490.226 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-09-25T18:21:30.226Z,1758824490.226 [MassServo](INFO): Powering down 2025-09-25T18:21:30.227Z,1758824490.227 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-09-25T18:21:30.227Z,1758824490.227 [RudderServo](INFO): Powering down 2025-09-25T18:21:30.227Z,1758824490.227 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-09-25T18:21:30.228Z,1758824490.228 [ThrusterHE](INFO): Powering down 2025-09-25T18:21:30.229Z,1758824490.229 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-09-25T18:21:30.229Z,1758824490.229 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-09-25T18:21:30.229Z,1758824490.229 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-09-25T18:21:30.229Z,1758824490.229 [CBIT](DEBUG): Powering off loads. 2025-09-25T18:21:30.240Z,1758824490.240 [CBIT](DEBUG): Disabling WDT. 2025-09-25T18:21:30.253Z,1758824490.253 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-09-25T18:21:30.253Z,1758824490.253 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-09-25T18:21:30.254Z,1758824490.254 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:30.300Z,1758824490.300 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:30.303Z,1758824490.303 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:30.309Z,1758824490.309 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:30.316Z,1758824490.316 [DAT ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:30.358Z,1758824490.358 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:30.392Z,1758824490.392 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-09-25T18:21:30.392Z,1758824490.392 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-09-25T18:21:30.450Z,1758824490.450 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:30.456Z,1758824490.456 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:30.464Z,1758824490.464 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-09-25T18:21:30.565Z,1758824490.565 [logger ThreadHandler](INFO): Thread cancelled.