2025-05-29T18:07:02.094Z,1748542022.094 [CommandExec](IMPORTANT): got command restart logs 2025-05-29T18:09:08.009Z,1748542148.009 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-05-29T18:09:14.873Z,1748542154.873 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:09:29.012Z,1748542169.012 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:09:42.749Z,1748542182.749 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:09:56.492Z,1748542196.492 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:10:11.027Z,1748542211.027 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:10:25.172Z,1748542225.172 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:10:38.519Z,1748542238.519 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:10:52.255Z,1748542252.255 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:11:05.991Z,1748542265.991 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:11:17.334Z,1748542277.334 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T18:11:17.334Z,1748542277.334 [Default:CheckIn:C.Wait] Stopped 2025-05-29T18:11:17.334Z,1748542277.334 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T18:11:17.334Z,1748542277.334 [Default:CheckIn:D] Running Loop=1 2025-05-29T18:11:17.732Z,1748542277.732 [Default:CheckIn:D] Stopped 2025-05-29T18:11:17.732Z,1748542277.732 [Default:CheckIn:E] Running Loop=1 2025-05-29T18:11:18.123Z,1748542278.123 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.960197 min 2025-05-29T18:11:18.124Z,1748542278.124 [Default:CheckIn:E] Stopped 2025-05-29T18:11:18.124Z,1748542278.124 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T18:11:18.124Z,1748542278.124 [Default:CheckIn] Stopped 2025-05-29T18:11:18.124Z,1748542278.124 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T18:11:18.124Z,1748542278.124 [Default:CheckIn](INFO): Running loop #4 2025-05-29T18:11:18.124Z,1748542278.124 [Default:CheckIn] Running Loop=4 2025-05-29T18:11:18.124Z,1748542278.124 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T18:11:18.124Z,1748542278.124 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T18:11:20.131Z,1748542280.131 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:11:20.140Z,1748542280.140 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181119.00,A,3648.44482,N,12149.35381,W,4.199,67.31,290525,,,D*43 2025-05-29T18:11:20.142Z,1748542280.142 [NAL9602](INFO): GPS fix at 20250529T181119: (36.807414, -121.822564) 2025-05-29T18:11:20.153Z,1748542280.153 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T18:11:20.153Z,1748542280.153 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T18:11:27.883Z,1748542287.883 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250529T052821/Courier0072.lzma 2025-05-29T18:11:28.885Z,1748542288.885 [DataOverHttps](INFO): Moved sent file to Logs/20250529T052821/Courier0072.lzma.bak 2025-05-29T18:11:28.885Z,1748542288.885 [DataOverHttps](INFO): SBD MOMSN=25032662 2025-05-29T18:11:44.919Z,1748542304.919 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20250529T180702/Courier0000.lzma 2025-05-29T18:11:45.921Z,1748542305.921 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0000.lzma.bak 2025-05-29T18:11:45.921Z,1748542305.921 [DataOverHttps](INFO): SBD MOMSN=25032671 2025-05-29T18:11:47.233Z,1748542307.233 [NAL9602](INFO): SBD MO Status=2, MOMSN=25133, MT Status=2, MTMSN=0 2025-05-29T18:11:47.234Z,1748542307.234 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T18:11:56.896Z,1748542316.896 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-05-29T18:12:04.174Z,1748542324.174 [NAL9602](INFO): SBD MO Status=0, MOMSN=25133, MT Status=0, MTMSN=0 2025-05-29T18:12:04.175Z,1748542324.175 [NAL9602](INFO): No messages in MT queue 2025-05-29T18:12:34.886Z,1748542354.886 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T18:12:48.639Z,1748542368.639 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-29T18:13:50.420Z,1748542430.420 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:13:50.887Z,1748542430.887 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-29T18:13:57.288Z,1748542437.288 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:14:12.638Z,1748542452.638 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:14:22.020Z,1748542462.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-29T18:14:27.587Z,1748542467.587 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:14:42.132Z,1748542482.132 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:14:46.116Z,1748542486.116 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.195312 2025-05-29T18:14:54.543Z,1748542494.543 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003211 2025-05-29T18:14:58.375Z,1748542498.375 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:15:02.083Z,1748542502.083 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20250529T052821/Express0073.lzma 2025-05-29T18:15:03.085Z,1748542503.085 [DataOverHttps](INFO): Moved sent file to Logs/20250529T052821/Express0073.lzma.bak 2025-05-29T18:15:03.085Z,1748542503.085 [DataOverHttps](INFO): SBD MOMSN=25032746 2025-05-29T18:15:14.188Z,1748542514.188 [NAL9602](INFO): SBD MO Status=2, MOMSN=25134, MT Status=2, MTMSN=0 2025-05-29T18:15:14.188Z,1748542514.188 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T18:15:14.624Z,1748542514.624 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:15:19.120Z,1748542519.120 [DataOverHttps](INFO): Sending 503 bytes from file Logs/20250529T180702/Express0001.lzma 2025-05-29T18:15:20.121Z,1748542520.121 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0001.lzma.bak 2025-05-29T18:15:20.121Z,1748542520.121 [DataOverHttps](INFO): SBD MOMSN=25032749 2025-05-29T18:15:30.291Z,1748542530.291 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:15:36.091Z,1748542536.091 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20250529T180702/Express0004.lzma 2025-05-29T18:15:37.093Z,1748542537.093 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0004.lzma.bak 2025-05-29T18:15:37.093Z,1748542537.093 [DataOverHttps](INFO): SBD MOMSN=25032753 2025-05-29T18:15:38.394Z,1748542538.394 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T18:15:38.394Z,1748542538.394 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T18:15:38.394Z,1748542538.394 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T18:15:44.836Z,1748542544.836 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T18:15:45.646Z,1748542545.646 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:16:00.000Z,1748542561.000 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:16:38.787Z,1748542598.787 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-29T18:17:14.182Z,1748542634.182 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-05-29T18:18:28.455Z,1748542708.455 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:18:34.514Z,1748542714.514 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:18:47.041Z,1748542727.041 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:18:58.756Z,1748542738.756 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:19:11.281Z,1748542751.281 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:19:23.803Z,1748542763.803 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:19:36.327Z,1748542776.327 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:19:48.851Z,1748542788.851 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:20:01.375Z,1748542801.375 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:20:13.103Z,1748542813.103 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:20:25.680Z,1748542825.680 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:20:38.139Z,1748542838.139 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:20:39.093Z,1748542839.093 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T18:20:39.093Z,1748542839.093 [Default:CheckIn:C.Wait] Stopped 2025-05-29T18:20:39.093Z,1748542839.093 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T18:20:39.093Z,1748542839.093 [Default:CheckIn:D] Running Loop=1 2025-05-29T18:20:39.410Z,1748542839.410 [Default:CheckIn:D] Stopped 2025-05-29T18:20:39.410Z,1748542839.410 [Default:CheckIn:E] Running Loop=1 2025-05-29T18:20:39.786Z,1748542839.786 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.321507 min 2025-05-29T18:20:39.786Z,1748542839.786 [Default:CheckIn:E] Stopped 2025-05-29T18:20:39.787Z,1748542839.787 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T18:20:39.787Z,1748542839.787 [Default:CheckIn] Stopped 2025-05-29T18:20:39.787Z,1748542839.787 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T18:20:39.787Z,1748542839.787 [Default:CheckIn](INFO): Running loop #5 2025-05-29T18:20:39.787Z,1748542839.787 [Default:CheckIn] Running Loop=5 2025-05-29T18:20:39.787Z,1748542839.787 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T18:20:39.788Z,1748542839.788 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T18:20:41.778Z,1748542841.778 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182041.00,A,3648.48986,N,12149.20656,W,0.875,33.33,290525,,,D*4E 2025-05-29T18:20:41.781Z,1748542841.781 [NAL9602](INFO): GPS fix at 20250529T182041: (36.808164, -121.820109) 2025-05-29T18:20:41.825Z,1748542841.825 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T18:20:41.825Z,1748542841.825 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T18:20:49.263Z,1748542849.263 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0006.lzma 2025-05-29T18:20:50.266Z,1748542850.266 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0006.lzma.bak 2025-05-29T18:20:50.266Z,1748542850.266 [DataOverHttps](INFO): SBD MOMSN=25032798 2025-05-29T18:21:06.083Z,1748542866.083 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20250529T180702/Express0007.lzma 2025-05-29T18:21:07.085Z,1748542867.085 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0007.lzma.bak 2025-05-29T18:21:07.085Z,1748542867.085 [DataOverHttps](INFO): SBD MOMSN=25032804 2025-05-29T18:21:08.512Z,1748542868.512 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T18:21:08.512Z,1748542868.512 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T18:21:08.512Z,1748542868.512 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T18:21:31.535Z,1748542891.535 [NAL9602](INFO): SBD MO Status=1, MOMSN=25134, MT Status=0, MTMSN=0 2025-05-29T18:21:31.535Z,1748542891.535 [NAL9602](INFO): No messages in MT queue 2025-05-29T18:22:02.236Z,1748542922.236 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T18:23:12.956Z,1748542992.956 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:23:27.903Z,1748543007.903 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:23:42.853Z,1748543022.853 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:23:57.800Z,1748543037.800 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:24:12.748Z,1748543052.748 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:24:28.099Z,1748543068.099 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:24:43.049Z,1748543083.049 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:24:58.398Z,1748543098.398 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:25:12.816Z,1748543112.816 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-05-29T18:25:13.348Z,1748543113.348 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:25:27.911Z,1748543127.911 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-05-29T18:26:09.150Z,1748543169.150 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T18:26:09.150Z,1748543169.150 [Default:CheckIn:C.Wait] Stopped 2025-05-29T18:26:09.150Z,1748543169.150 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T18:26:09.151Z,1748543169.151 [Default:CheckIn:D] Running Loop=1 2025-05-29T18:26:09.573Z,1748543169.573 [Default:CheckIn:D] Stopped 2025-05-29T18:26:09.573Z,1748543169.573 [Default:CheckIn:E] Running Loop=1 2025-05-29T18:26:10.058Z,1748543170.058 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.824215 min 2025-05-29T18:26:10.058Z,1748543170.058 [Default:CheckIn:E] Stopped 2025-05-29T18:26:10.058Z,1748543170.058 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T18:26:10.058Z,1748543170.058 [Default:CheckIn] Stopped 2025-05-29T18:26:10.059Z,1748543170.059 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T18:26:10.059Z,1748543170.059 [Default:CheckIn](INFO): Running loop #6 2025-05-29T18:26:10.059Z,1748543170.059 [Default:CheckIn] Running Loop=6 2025-05-29T18:26:10.059Z,1748543170.059 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T18:26:10.059Z,1748543170.059 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T18:26:11.954Z,1748543171.954 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182611.00,A,3648.51123,N,12148.92123,W,5.151,100.50,290525,,,D*71 2025-05-29T18:26:11.957Z,1748543171.957 [NAL9602](INFO): GPS fix at 20250529T182611: (36.808521, -121.815354) 2025-05-29T18:26:11.992Z,1748543171.992 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T18:26:11.992Z,1748543171.992 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T18:26:25.563Z,1748543185.563 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0009.lzma 2025-05-29T18:26:26.565Z,1748543186.565 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0009.lzma.bak 2025-05-29T18:26:26.565Z,1748543186.565 [DataOverHttps](INFO): SBD MOMSN=25032878 2025-05-29T18:26:45.738Z,1748543205.738 [NAL9602](INFO): SBD MO Status=1, MOMSN=25135, MT Status=0, MTMSN=0 2025-05-29T18:26:45.738Z,1748543205.738 [NAL9602](INFO): No messages in MT queue 2025-05-29T18:26:46.695Z,1748543206.695 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250529T180702/Express0010.lzma 2025-05-29T18:26:47.697Z,1748543207.697 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0010.lzma.bak 2025-05-29T18:26:47.697Z,1748543207.697 [DataOverHttps](INFO): SBD MOMSN=25032883 2025-05-29T18:26:49.202Z,1748543209.202 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T18:26:49.202Z,1748543209.202 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T18:26:49.202Z,1748543209.202 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T18:27:16.272Z,1748543236.272 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T18:27:53.423Z,1748543273.423 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:28:07.160Z,1748543287.160 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:28:21.295Z,1748543301.295 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:28:35.435Z,1748543315.435 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:28:49.574Z,1748543329.574 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:29:03.312Z,1748543343.312 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:29:16.645Z,1748543356.645 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:29:30.379Z,1748543370.379 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:29:43.711Z,1748543383.711 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:29:57.043Z,1748543397.043 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:30:10.375Z,1748543410.375 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:31:49.785Z,1748543509.785 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T18:31:49.785Z,1748543509.785 [Default:CheckIn:C.Wait] Stopped 2025-05-29T18:31:49.785Z,1748543509.785 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T18:31:49.785Z,1748543509.785 [Default:CheckIn:D] Running Loop=1 2025-05-29T18:31:50.182Z,1748543510.182 [Default:CheckIn:D] Stopped 2025-05-29T18:31:50.182Z,1748543510.182 [Default:CheckIn:E] Running Loop=1 2025-05-29T18:31:50.644Z,1748543510.644 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.501034 min 2025-05-29T18:31:50.644Z,1748543510.644 [Default:CheckIn:E] Stopped 2025-05-29T18:31:50.644Z,1748543510.644 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T18:31:50.644Z,1748543510.644 [Default:CheckIn] Stopped 2025-05-29T18:31:50.645Z,1748543510.645 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T18:31:50.645Z,1748543510.645 [Default:CheckIn](INFO): Running loop #7 2025-05-29T18:31:50.645Z,1748543510.645 [Default:CheckIn] Running Loop=7 2025-05-29T18:31:50.645Z,1748543510.645 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T18:31:50.645Z,1748543510.645 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T18:31:52.594Z,1748543512.594 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183151.00,A,3648.43568,N,12148.36674,W,4.432,101.73,290525,,,D*71 2025-05-29T18:31:52.597Z,1748543512.597 [NAL9602](INFO): GPS fix at 20250529T183151: (36.807261, -121.806112) 2025-05-29T18:31:52.608Z,1748543512.608 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T18:31:52.608Z,1748543512.608 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T18:31:59.739Z,1748543519.739 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0012.lzma 2025-05-29T18:32:00.741Z,1748543520.741 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0012.lzma.bak 2025-05-29T18:32:00.741Z,1748543520.741 [DataOverHttps](INFO): SBD MOMSN=25032914 2025-05-29T18:32:16.428Z,1748543536.428 [NAL9602](INFO): SBD MO Status=1, MOMSN=25136, MT Status=0, MTMSN=0 2025-05-29T18:32:16.428Z,1748543536.428 [NAL9602](INFO): No messages in MT queue 2025-05-29T18:32:16.727Z,1748543536.727 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20250529T180702/Express0013.lzma 2025-05-29T18:32:17.752Z,1748543537.752 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0013.lzma.bak 2025-05-29T18:32:17.752Z,1748543537.752 [DataOverHttps](INFO): SBD MOMSN=25032917 2025-05-29T18:32:18.867Z,1748543538.867 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T18:32:18.868Z,1748543538.868 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T18:32:18.868Z,1748543538.868 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T18:32:38.239Z,1748543558.239 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:32:47.132Z,1748543567.132 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T18:32:53.187Z,1748543573.187 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:33:08.540Z,1748543588.540 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:33:24.699Z,1748543604.699 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:33:40.858Z,1748543620.858 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:33:56.212Z,1748543636.212 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:34:11.564Z,1748543651.564 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:34:26.511Z,1748543666.511 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:34:42.368Z,1748543682.368 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:36:23.280Z,1748543783.280 [BPC1](ERROR): Battery stick #13 (s/n: 015A) reported TERMINATE_CHARGE_ALARM. Status code: 0x42D0. 2025-05-29T18:36:23.281Z,1748543783.281 [BPC1](ERROR): Battery stick #16 (s/n: 00F8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T18:36:23.282Z,1748543783.282 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T18:36:23.283Z,1748543783.283 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-05-29T18:36:23.284Z,1748543783.284 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T18:36:23.284Z,1748543783.284 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T18:36:23.285Z,1748543783.285 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T18:36:23.286Z,1748543783.286 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T18:36:23.286Z,1748543783.286 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 4. 2025-05-29T18:36:23.291Z,1748543783.291 [BPC1](FAULT): Failed to receive data from 14 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 10, 11, 12, 13, 14, 16, 24, 29, 35, 51, 52, 53, 57, 59. 2025-05-29T18:37:19.434Z,1748543839.434 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T18:37:19.434Z,1748543839.434 [Default:CheckIn:C.Wait] Stopped 2025-05-29T18:37:19.434Z,1748543839.434 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T18:37:19.434Z,1748543839.434 [Default:CheckIn:D] Running Loop=1 2025-05-29T18:37:19.840Z,1748543839.840 [Default:CheckIn:D] Stopped 2025-05-29T18:37:19.840Z,1748543839.840 [Default:CheckIn:E] Running Loop=1 2025-05-29T18:37:20.249Z,1748543840.249 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.995329 min 2025-05-29T18:37:20.249Z,1748543840.249 [Default:CheckIn:E] Stopped 2025-05-29T18:37:20.249Z,1748543840.249 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T18:37:20.249Z,1748543840.249 [Default:CheckIn] Stopped 2025-05-29T18:37:20.250Z,1748543840.250 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T18:37:20.250Z,1748543840.250 [Default:CheckIn](INFO): Running loop #8 2025-05-29T18:37:20.250Z,1748543840.250 [Default:CheckIn] Running Loop=8 2025-05-29T18:37:20.250Z,1748543840.250 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T18:37:20.250Z,1748543840.250 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T18:37:22.254Z,1748543842.254 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183721.00,A,3648.36155,N,12147.82095,W,4.646,101.23,290525,,,D*75 2025-05-29T18:37:22.256Z,1748543842.256 [NAL9602](INFO): GPS fix at 20250529T183721: (36.806026, -121.797016) 2025-05-29T18:37:22.287Z,1748543842.287 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T18:37:22.287Z,1748543842.287 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T18:37:32.755Z,1748543852.755 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20250529T180702/Courier0015.lzma 2025-05-29T18:37:33.757Z,1748543853.757 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0015.lzma.bak 2025-05-29T18:37:33.757Z,1748543853.757 [DataOverHttps](INFO): SBD MOMSN=25032991 2025-05-29T18:37:45.322Z,1748543865.322 [NAL9602](INFO): SBD MO Status=1, MOMSN=25137, MT Status=0, MTMSN=0 2025-05-29T18:37:45.323Z,1748543865.323 [NAL9602](INFO): No messages in MT queue 2025-05-29T18:37:49.771Z,1748543869.771 [DataOverHttps](INFO): Sending 329 bytes from file Logs/20250529T180702/Express0016.lzma 2025-05-29T18:37:50.773Z,1748543870.773 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0016.lzma.bak 2025-05-29T18:37:50.773Z,1748543870.773 [DataOverHttps](INFO): SBD MOMSN=25032994 2025-05-29T18:37:52.188Z,1748543872.188 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T18:37:52.188Z,1748543872.188 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T18:37:52.188Z,1748543872.188 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T18:38:16.004Z,1748543896.004 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T18:42:52.766Z,1748544172.766 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T18:42:52.766Z,1748544172.766 [Default:CheckIn:C.Wait] Stopped 2025-05-29T18:42:52.766Z,1748544172.766 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T18:42:52.767Z,1748544172.767 [Default:CheckIn:D] Running Loop=1 2025-05-29T18:42:53.162Z,1748544173.162 [Default:CheckIn:D] Stopped 2025-05-29T18:42:53.163Z,1748544173.163 [Default:CheckIn:E] Running Loop=1 2025-05-29T18:42:53.574Z,1748544173.574 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.550708 min 2025-05-29T18:42:53.574Z,1748544173.574 [Default:CheckIn:E] Stopped 2025-05-29T18:42:53.575Z,1748544173.575 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T18:42:53.575Z,1748544173.575 [Default:CheckIn] Stopped 2025-05-29T18:42:53.575Z,1748544173.575 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T18:42:53.575Z,1748544173.575 [Default:CheckIn](INFO): Running loop #9 2025-05-29T18:42:53.575Z,1748544173.575 [Default:CheckIn] Running Loop=9 2025-05-29T18:42:53.575Z,1748544173.575 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T18:42:53.576Z,1748544173.576 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T18:42:55.578Z,1748544175.578 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184254.00,A,3648.36109,N,12147.42772,W,4.102,56.26,290525,,,D*4F 2025-05-29T18:42:55.580Z,1748544175.580 [NAL9602](INFO): GPS fix at 20250529T184254: (36.806018, -121.790462) 2025-05-29T18:42:55.610Z,1748544175.610 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T18:42:55.610Z,1748544175.610 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T18:43:02.603Z,1748544182.603 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0018.lzma 2025-05-29T18:43:03.605Z,1748544183.605 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0018.lzma.bak 2025-05-29T18:43:03.605Z,1748544183.605 [DataOverHttps](INFO): SBD MOMSN=25033012 2025-05-29T18:43:16.218Z,1748544196.218 [NAL9602](INFO): SBD MO Status=2, MOMSN=25138, MT Status=2, MTMSN=0 2025-05-29T18:43:16.218Z,1748544196.218 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T18:43:19.463Z,1748544199.463 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250529T180702/Express0019.lzma 2025-05-29T18:43:20.465Z,1748544200.465 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0019.lzma.bak 2025-05-29T18:43:20.465Z,1748544200.465 [DataOverHttps](INFO): SBD MOMSN=25033017 2025-05-29T18:43:21.885Z,1748544201.885 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T18:43:21.885Z,1748544201.885 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T18:43:21.885Z,1748544201.885 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T18:45:31.961Z,1748544331.961 [NAL9602](INFO): SBD MO Status=1, MOMSN=25138, MT Status=0, MTMSN=0 2025-05-29T18:45:31.962Z,1748544331.962 [NAL9602](INFO): No messages in MT queue 2025-05-29T18:46:02.662Z,1748544362.662 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T18:47:31.539Z,1748544451.539 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:47:44.871Z,1748544464.871 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:47:59.011Z,1748544479.011 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:48:12.752Z,1748544492.752 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:48:22.455Z,1748544502.455 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T18:48:22.455Z,1748544502.455 [Default:CheckIn:C.Wait] Stopped 2025-05-29T18:48:22.455Z,1748544502.455 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T18:48:22.455Z,1748544502.455 [Default:CheckIn:D] Running Loop=1 2025-05-29T18:48:22.864Z,1748544502.864 [Default:CheckIn:D] Stopped 2025-05-29T18:48:22.864Z,1748544502.864 [Default:CheckIn:E] Running Loop=1 2025-05-29T18:48:23.272Z,1748544503.272 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.045728 min 2025-05-29T18:48:23.272Z,1748544503.272 [Default:CheckIn:E] Stopped 2025-05-29T18:48:23.272Z,1748544503.272 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T18:48:23.272Z,1748544503.272 [Default:CheckIn] Stopped 2025-05-29T18:48:23.272Z,1748544503.272 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T18:48:23.272Z,1748544503.272 [Default:CheckIn](INFO): Running loop #10 2025-05-29T18:48:23.272Z,1748544503.272 [Default:CheckIn] Running Loop=10 2025-05-29T18:48:23.272Z,1748544503.272 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T18:48:23.272Z,1748544503.272 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T18:48:25.282Z,1748544505.282 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184824.00,A,3648.41840,N,12147.12103,W,3.752,186.81,290525,,,D*76 2025-05-29T18:48:25.284Z,1748544505.284 [NAL9602](INFO): GPS fix at 20250529T184824: (36.806973, -121.785351) 2025-05-29T18:48:25.316Z,1748544505.316 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T18:48:25.316Z,1748544505.316 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T18:48:26.899Z,1748544506.899 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:48:33.015Z,1748544513.015 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250529T180702/Courier0021.lzma 2025-05-29T18:48:34.017Z,1748544514.017 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0021.lzma.bak 2025-05-29T18:48:34.017Z,1748544514.017 [DataOverHttps](INFO): SBD MOMSN=25033095 2025-05-29T18:48:40.633Z,1748544520.633 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:48:53.567Z,1748544533.567 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250529T180702/Express0022.lzma 2025-05-29T18:48:53.587Z,1748544533.587 [NAL9602](INFO): SBD MO Status=2, MOMSN=25139, MT Status=2, MTMSN=0 2025-05-29T18:48:53.587Z,1748544533.587 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T18:48:54.375Z,1748544534.375 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:48:54.569Z,1748544534.569 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0022.lzma.bak 2025-05-29T18:48:54.569Z,1748544534.569 [DataOverHttps](INFO): SBD MOMSN=25033098 2025-05-29T18:48:56.037Z,1748544536.037 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T18:48:56.037Z,1748544536.037 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T18:48:56.037Z,1748544536.037 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T18:49:11.782Z,1748544551.782 [NAL9602](INFO): SBD MO Status=1, MOMSN=25139, MT Status=0, MTMSN=0 2025-05-29T18:49:11.782Z,1748544551.782 [NAL9602](INFO): No messages in MT queue 2025-05-29T18:49:42.490Z,1748544582.490 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T18:51:24.295Z,1748544684.295 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:51:31.163Z,1748544691.163 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:51:46.117Z,1748544706.117 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:52:01.060Z,1748544721.060 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:52:16.817Z,1748544736.817 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:52:32.571Z,1748544752.571 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:52:48.327Z,1748544768.327 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:53:03.679Z,1748544783.679 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:53:18.628Z,1748544798.628 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:53:33.575Z,1748544813.575 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:53:56.616Z,1748544836.616 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T18:53:56.616Z,1748544836.616 [Default:CheckIn:C.Wait] Stopped 2025-05-29T18:53:56.616Z,1748544836.616 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T18:53:56.617Z,1748544836.617 [Default:CheckIn:D] Running Loop=1 2025-05-29T18:53:57.034Z,1748544837.034 [Default:CheckIn:D] Stopped 2025-05-29T18:53:57.035Z,1748544837.035 [Default:CheckIn:E] Running Loop=1 2025-05-29T18:53:57.423Z,1748544837.423 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.615238 min 2025-05-29T18:53:57.423Z,1748544837.423 [Default:CheckIn:E] Stopped 2025-05-29T18:53:57.424Z,1748544837.424 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T18:53:57.424Z,1748544837.424 [Default:CheckIn] Stopped 2025-05-29T18:53:57.424Z,1748544837.424 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T18:53:57.424Z,1748544837.424 [Default:CheckIn](INFO): Running loop #11 2025-05-29T18:53:57.424Z,1748544837.424 [Default:CheckIn] Running Loop=11 2025-05-29T18:53:57.424Z,1748544837.424 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T18:53:57.424Z,1748544837.424 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T18:53:59.494Z,1748544839.494 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185358.00,A,3648.16587,N,12147.21068,W,1.438,238.47,290525,,,D*7E 2025-05-29T18:53:59.496Z,1748544839.496 [NAL9602](INFO): GPS fix at 20250529T185358: (36.802765, -121.786845) 2025-05-29T18:53:59.507Z,1748544839.507 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T18:53:59.507Z,1748544839.507 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T18:54:06.450Z,1748544846.450 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0024.lzma 2025-05-29T18:54:07.453Z,1748544847.453 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0024.lzma.bak 2025-05-29T18:54:07.453Z,1748544847.453 [DataOverHttps](INFO): SBD MOMSN=25033119 2025-05-29T18:54:20.886Z,1748544860.886 [NAL9602](INFO): SBD MO Status=1, MOMSN=25140, MT Status=0, MTMSN=0 2025-05-29T18:54:20.886Z,1748544860.886 [NAL9602](INFO): No messages in MT queue 2025-05-29T18:54:23.439Z,1748544863.439 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250529T180702/Express0025.lzma 2025-05-29T18:54:24.442Z,1748544864.442 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0025.lzma.bak 2025-05-29T18:54:24.442Z,1748544864.442 [DataOverHttps](INFO): SBD MOMSN=25033125 2025-05-29T18:54:25.734Z,1748544865.734 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T18:54:25.734Z,1748544865.734 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T18:54:25.734Z,1748544865.734 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T18:54:51.573Z,1748544891.573 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T18:56:07.523Z,1748544967.523 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:56:21.664Z,1748544981.664 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:56:35.805Z,1748544995.805 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:56:49.545Z,1748545009.545 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:57:03.275Z,1748545023.275 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:57:17.415Z,1748545037.415 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:57:30.747Z,1748545050.747 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:57:44.080Z,1748545064.080 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:57:57.815Z,1748545077.815 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:58:11.147Z,1748545091.147 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:58:24.479Z,1748545104.479 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T18:59:26.326Z,1748545166.326 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T18:59:26.326Z,1748545166.326 [Default:CheckIn:C.Wait] Stopped 2025-05-29T18:59:26.326Z,1748545166.326 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T18:59:26.326Z,1748545166.326 [Default:CheckIn:D] Running Loop=1 2025-05-29T18:59:26.733Z,1748545166.733 [Default:CheckIn:D] Stopped 2025-05-29T18:59:26.734Z,1748545166.734 [Default:CheckIn:E] Running Loop=1 2025-05-29T18:59:27.130Z,1748545167.130 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.110221 min 2025-05-29T18:59:27.130Z,1748545167.130 [Default:CheckIn:E] Stopped 2025-05-29T18:59:27.131Z,1748545167.131 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T18:59:27.131Z,1748545167.131 [Default:CheckIn] Stopped 2025-05-29T18:59:27.131Z,1748545167.131 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T18:59:27.131Z,1748545167.131 [Default:CheckIn](INFO): Running loop #12 2025-05-29T18:59:27.131Z,1748545167.131 [Default:CheckIn] Running Loop=12 2025-05-29T18:59:27.131Z,1748545167.131 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T18:59:27.131Z,1748545167.131 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T18:59:29.138Z,1748545169.138 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185928.00,A,3648.14524,N,12147.22383,W,0.019,201.92,290525,,,D*79 2025-05-29T18:59:29.148Z,1748545169.148 [NAL9602](INFO): GPS fix at 20250529T185928: (36.802421, -121.787064) 2025-05-29T18:59:29.158Z,1748545169.158 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T18:59:29.159Z,1748545169.159 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T18:59:37.191Z,1748545177.191 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250529T180702/Courier0027.lzma 2025-05-29T18:59:38.193Z,1748545178.193 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0027.lzma.bak 2025-05-29T18:59:38.193Z,1748545178.193 [DataOverHttps](INFO): SBD MOMSN=25033192 2025-05-29T18:59:54.252Z,1748545194.252 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250529T180702/Express0028.lzma 2025-05-29T18:59:55.253Z,1748545195.253 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0028.lzma.bak 2025-05-29T18:59:55.253Z,1748545195.253 [DataOverHttps](INFO): SBD MOMSN=25033195 2025-05-29T18:59:56.630Z,1748545196.630 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T18:59:56.630Z,1748545196.630 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T18:59:56.630Z,1748545196.630 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T19:00:37.027Z,1748545237.027 [NAL9602](INFO): SBD MO Status=2, MOMSN=25141, MT Status=2, MTMSN=0 2025-05-29T19:00:37.027Z,1748545237.027 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T19:00:52.363Z,1748545252.363 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:01:07.324Z,1748545267.324 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:01:22.663Z,1748545282.663 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:01:24.294Z,1748545284.294 [NAL9602](INFO): SBD MO Status=2, MOMSN=25141, MT Status=2, MTMSN=0 2025-05-29T19:01:24.295Z,1748545284.295 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T19:01:38.419Z,1748545298.419 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:01:53.368Z,1748545313.368 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:02:08.315Z,1748545328.315 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:02:23.668Z,1748545343.668 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:02:39.020Z,1748545359.020 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:02:42.254Z,1748545362.254 [NAL9602](INFO): SBD MO Status=2, MOMSN=25141, MT Status=2, MTMSN=0 2025-05-29T19:02:42.254Z,1748545362.254 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T19:02:53.967Z,1748545373.967 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:03:08.511Z,1748545388.511 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-05-29T19:03:52.166Z,1748545432.166 [NAL9602](INFO): SBD MO Status=1, MOMSN=25141, MT Status=0, MTMSN=0 2025-05-29T19:03:52.166Z,1748545432.166 [NAL9602](INFO): No messages in MT queue 2025-05-29T19:04:22.850Z,1748545462.850 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T19:04:57.227Z,1748545497.227 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T19:04:57.227Z,1748545497.227 [Default:CheckIn:C.Wait] Stopped 2025-05-29T19:04:57.227Z,1748545497.227 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T19:04:57.227Z,1748545497.227 [Default:CheckIn:D] Running Loop=1 2025-05-29T19:04:57.644Z,1748545497.644 [Default:CheckIn:D] Stopped 2025-05-29T19:04:57.644Z,1748545497.644 [Default:CheckIn:E] Running Loop=1 2025-05-29T19:04:58.026Z,1748545498.026 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.625399 min 2025-05-29T19:04:58.026Z,1748545498.026 [Default:CheckIn:E] Stopped 2025-05-29T19:04:58.027Z,1748545498.027 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T19:04:58.027Z,1748545498.027 [Default:CheckIn] Stopped 2025-05-29T19:04:58.027Z,1748545498.027 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T19:04:58.027Z,1748545498.027 [Default:CheckIn](INFO): Running loop #13 2025-05-29T19:04:58.027Z,1748545498.027 [Default:CheckIn] Running Loop=13 2025-05-29T19:04:58.027Z,1748545498.027 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T19:04:58.027Z,1748545498.027 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T19:05:00.039Z,1748545500.039 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190459.00,A,3648.14714,N,12147.22127,W,0.019,201.92,290525,,,D*7B 2025-05-29T19:05:00.041Z,1748545500.041 [NAL9602](INFO): GPS fix at 20250529T190459: (36.802452, -121.787021) 2025-05-29T19:05:00.053Z,1748545500.053 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T19:05:00.053Z,1748545500.053 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T19:05:08.139Z,1748545508.139 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250529T180702/Courier0030.lzma 2025-05-29T19:05:09.141Z,1748545509.141 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0030.lzma.bak 2025-05-29T19:05:09.141Z,1748545509.141 [DataOverHttps](INFO): SBD MOMSN=25033224 2025-05-29T19:05:25.083Z,1748545525.083 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250529T180702/Express0031.lzma 2025-05-29T19:05:26.085Z,1748545526.085 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0031.lzma.bak 2025-05-29T19:05:26.085Z,1748545526.085 [DataOverHttps](INFO): SBD MOMSN=25033233 2025-05-29T19:05:27.530Z,1748545527.530 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T19:05:27.530Z,1748545527.530 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T19:05:27.530Z,1748545527.530 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T19:05:29.242Z,1748545529.242 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-05-29T19:05:29.250Z,1748545529.250 [NAL9602](INFO): SBD MO Status=1, MOMSN=25142, MT Status=0, MTMSN=0 2025-05-29T19:05:29.250Z,1748545529.250 [NAL9602](INFO): No messages in MT queue 2025-05-29T19:05:35.195Z,1748545535.195 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:05:48.516Z,1748545548.516 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:05:59.853Z,1748545559.853 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T19:06:01.847Z,1748545561.847 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:06:15.583Z,1748545575.583 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:06:29.319Z,1748545589.319 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:06:43.459Z,1748545603.459 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:06:57.195Z,1748545617.195 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:07:10.932Z,1748545630.932 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:07:24.275Z,1748545644.275 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:07:37.999Z,1748545657.999 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:10:14.357Z,1748545814.357 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:10:28.102Z,1748545828.102 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T19:10:28.102Z,1748545828.102 [Default:CheckIn:C.Wait] Stopped 2025-05-29T19:10:28.103Z,1748545828.103 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T19:10:28.103Z,1748545828.103 [Default:CheckIn:D] Running Loop=1 2025-05-29T19:10:28.514Z,1748545828.514 [Default:CheckIn:D] Stopped 2025-05-29T19:10:28.514Z,1748545828.514 [Default:CheckIn:E] Running Loop=1 2025-05-29T19:10:28.961Z,1748545828.961 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.139901 min 2025-05-29T19:10:28.961Z,1748545828.961 [Default:CheckIn:E] Stopped 2025-05-29T19:10:28.962Z,1748545828.962 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T19:10:28.962Z,1748545828.962 [Default:CheckIn] Stopped 2025-05-29T19:10:28.962Z,1748545828.962 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T19:10:28.962Z,1748545828.962 [Default:CheckIn](INFO): Running loop #14 2025-05-29T19:10:28.962Z,1748545828.962 [Default:CheckIn] Running Loop=14 2025-05-29T19:10:28.962Z,1748545828.962 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T19:10:28.962Z,1748545828.962 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T19:10:29.304Z,1748545829.304 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:10:30.929Z,1748545830.929 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191030.00,A,3648.14956,N,12147.22557,W,0.058,303.20,290525,,,D*75 2025-05-29T19:10:30.932Z,1748545830.932 [NAL9602](INFO): GPS fix at 20250529T191030: (36.802493, -121.787093) 2025-05-29T19:10:30.942Z,1748545830.942 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T19:10:30.942Z,1748545830.942 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T19:10:40.663Z,1748545840.663 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0033.lzma 2025-05-29T19:10:41.665Z,1748545841.665 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0033.lzma.bak 2025-05-29T19:10:41.665Z,1748545841.665 [DataOverHttps](INFO): SBD MOMSN=25033292 2025-05-29T19:10:44.256Z,1748545844.256 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:10:59.624Z,1748545859.624 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:10:59.946Z,1748545859.946 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250529T180702/Express0034.lzma 2025-05-29T19:11:00.945Z,1748545860.945 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0034.lzma.bak 2025-05-29T19:11:00.945Z,1748545860.945 [DataOverHttps](INFO): SBD MOMSN=25033295 2025-05-29T19:11:02.466Z,1748545862.466 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T19:11:02.466Z,1748545862.466 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T19:11:02.466Z,1748545862.466 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T19:11:02.878Z,1748545862.878 [NAL9602](INFO): SBD MO Status=1, MOMSN=25143, MT Status=0, MTMSN=0 2025-05-29T19:11:02.878Z,1748545862.878 [NAL9602](INFO): No messages in MT queue 2025-05-29T19:11:14.975Z,1748545874.975 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:11:31.135Z,1748545891.135 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:11:33.562Z,1748545893.562 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T19:11:46.084Z,1748545906.084 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:12:01.031Z,1748545921.031 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:12:15.981Z,1748545935.981 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:12:30.524Z,1748545950.524 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-05-29T19:14:56.883Z,1748546096.883 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:15:11.027Z,1748546111.027 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:15:24.357Z,1748546124.357 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:15:38.092Z,1748546138.092 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:15:52.238Z,1748546152.238 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:16:03.157Z,1748546163.157 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T19:16:03.158Z,1748546163.158 [Default:CheckIn:C.Wait] Stopped 2025-05-29T19:16:03.158Z,1748546163.158 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T19:16:03.158Z,1748546163.158 [Default:CheckIn:D] Running Loop=1 2025-05-29T19:16:03.569Z,1748546163.569 [Default:CheckIn:D] Stopped 2025-05-29T19:16:03.569Z,1748546163.569 [Default:CheckIn:E] Running Loop=1 2025-05-29T19:16:03.983Z,1748546163.983 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.724146 min 2025-05-29T19:16:03.983Z,1748546163.983 [Default:CheckIn:E] Stopped 2025-05-29T19:16:03.983Z,1748546163.983 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T19:16:03.983Z,1748546163.983 [Default:CheckIn] Stopped 2025-05-29T19:16:03.983Z,1748546163.983 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T19:16:03.984Z,1748546163.984 [Default:CheckIn](INFO): Running loop #15 2025-05-29T19:16:03.984Z,1748546163.984 [Default:CheckIn] Running Loop=15 2025-05-29T19:16:03.984Z,1748546163.984 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T19:16:03.984Z,1748546163.984 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T19:16:05.967Z,1748546165.967 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:16:05.981Z,1748546165.981 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191605.00,A,3648.16744,N,12147.25668,W,2.935,295.25,290525,,,D*79 2025-05-29T19:16:05.984Z,1748546165.984 [NAL9602](INFO): GPS fix at 20250529T191605: (36.802791, -121.787611) 2025-05-29T19:16:05.994Z,1748546165.994 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T19:16:05.994Z,1748546165.994 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T19:16:12.950Z,1748546172.950 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250529T180702/Courier0036.lzma 2025-05-29T19:16:13.953Z,1748546173.953 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0036.lzma.bak 2025-05-29T19:16:13.953Z,1748546173.953 [DataOverHttps](INFO): SBD MOMSN=25033379 2025-05-29T19:16:19.331Z,1748546179.331 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:16:29.940Z,1748546189.940 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250529T180702/Express0037.lzma 2025-05-29T19:16:30.941Z,1748546190.941 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0037.lzma.bak 2025-05-29T19:16:30.941Z,1748546190.941 [DataOverHttps](INFO): SBD MOMSN=25033382 2025-05-29T19:16:32.280Z,1748546192.280 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T19:16:32.280Z,1748546192.280 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T19:16:32.280Z,1748546192.280 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T19:16:33.067Z,1748546193.067 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:16:43.576Z,1748546203.576 [NAL9602](INFO): SBD MO Status=1, MOMSN=25144, MT Status=0, MTMSN=0 2025-05-29T19:16:43.576Z,1748546203.576 [NAL9602](INFO): No messages in MT queue 2025-05-29T19:16:47.207Z,1748546207.207 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:17:01.349Z,1748546221.349 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:17:14.278Z,1748546234.278 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T19:17:15.487Z,1748546235.487 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:17:25.184Z,1748546245.184 [BPC1](ERROR): Battery stick #13 (s/n: 015A) reported TERMINATE_CHARGE_ALARM. Status code: 0x42D0. 2025-05-29T19:17:25.185Z,1748546245.185 [BPC1](ERROR): Battery stick #16 (s/n: 00F8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T19:17:25.186Z,1748546245.186 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T19:17:25.186Z,1748546245.186 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-05-29T19:17:25.188Z,1748546245.188 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T19:17:25.188Z,1748546245.188 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T19:17:25.189Z,1748546245.189 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T19:17:25.190Z,1748546245.190 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T19:17:25.190Z,1748546245.190 [BPC1](INFO): Calculating totals. Valid battery stick count: 41. Valid reserve battery stick count: 4. 2025-05-29T19:17:25.194Z,1748546245.194 [BPC1](FAULT): Failed to receive data from 15 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 10, 11, 12, 13, 14, 15, 16, 24, 29, 35, 51, 52, 53, 57, 59. 2025-05-29T19:21:32.902Z,1748546492.902 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T19:21:32.902Z,1748546492.902 [Default:CheckIn:C.Wait] Stopped 2025-05-29T19:21:32.902Z,1748546492.902 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T19:21:32.902Z,1748546492.902 [Default:CheckIn:D] Running Loop=1 2025-05-29T19:21:33.307Z,1748546493.307 [Default:CheckIn:D] Stopped 2025-05-29T19:21:33.307Z,1748546493.307 [Default:CheckIn:E] Running Loop=1 2025-05-29T19:21:33.714Z,1748546493.714 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.219775 min 2025-05-29T19:21:33.714Z,1748546493.714 [Default:CheckIn:E] Stopped 2025-05-29T19:21:33.715Z,1748546493.715 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T19:21:33.715Z,1748546493.715 [Default:CheckIn] Stopped 2025-05-29T19:21:33.715Z,1748546493.715 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T19:21:33.715Z,1748546493.715 [Default:CheckIn](INFO): Running loop #16 2025-05-29T19:21:33.715Z,1748546493.715 [Default:CheckIn] Running Loop=16 2025-05-29T19:21:33.715Z,1748546493.715 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T19:21:33.715Z,1748546493.715 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T19:21:35.722Z,1748546495.722 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192135.00,A,3648.18123,N,12147.27873,W,0.156,287.87,290525,,,D*75 2025-05-29T19:21:35.724Z,1748546495.724 [NAL9602](INFO): GPS fix at 20250529T192135: (36.803021, -121.787979) 2025-05-29T19:21:35.752Z,1748546495.752 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T19:21:35.753Z,1748546495.753 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T19:21:43.495Z,1748546503.495 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250529T180702/Courier0039.lzma 2025-05-29T19:21:44.497Z,1748546504.497 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0039.lzma.bak 2025-05-29T19:21:44.497Z,1748546504.497 [DataOverHttps](INFO): SBD MOMSN=25033400 2025-05-29T19:21:54.707Z,1748546514.707 [NAL9602](INFO): SBD MO Status=1, MOMSN=25145, MT Status=0, MTMSN=0 2025-05-29T19:21:54.707Z,1748546514.707 [NAL9602](INFO): No messages in MT queue 2025-05-29T19:22:00.327Z,1748546520.327 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20250529T180702/Express0040.lzma 2025-05-29T19:22:01.329Z,1748546521.329 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0040.lzma.bak 2025-05-29T19:22:01.329Z,1748546521.329 [DataOverHttps](INFO): SBD MOMSN=25033403 2025-05-29T19:22:02.799Z,1748546522.799 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T19:22:02.799Z,1748546522.799 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T19:22:02.799Z,1748546522.799 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T19:22:25.416Z,1748546545.416 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T19:27:03.584Z,1748546823.584 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T19:27:03.584Z,1748546823.584 [Default:CheckIn:C.Wait] Stopped 2025-05-29T19:27:03.584Z,1748546823.584 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T19:27:03.585Z,1748546823.585 [Default:CheckIn:D] Running Loop=1 2025-05-29T19:27:03.946Z,1748546823.946 [Default:CheckIn:D] Stopped 2025-05-29T19:27:03.946Z,1748546823.946 [Default:CheckIn:E] Running Loop=1 2025-05-29T19:27:04.343Z,1748546824.343 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.730428 min 2025-05-29T19:27:04.343Z,1748546824.343 [Default:CheckIn:E] Stopped 2025-05-29T19:27:04.343Z,1748546824.343 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T19:27:04.344Z,1748546824.344 [Default:CheckIn] Stopped 2025-05-29T19:27:04.344Z,1748546824.344 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T19:27:04.344Z,1748546824.344 [Default:CheckIn](INFO): Running loop #17 2025-05-29T19:27:04.344Z,1748546824.344 [Default:CheckIn] Running Loop=17 2025-05-29T19:27:04.344Z,1748546824.344 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T19:27:04.344Z,1748546824.344 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T19:27:06.355Z,1748546826.355 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192705.00,A,3648.17618,N,12147.27707,W,0.156,287.87,290525,,,A*79 2025-05-29T19:27:06.357Z,1748546826.357 [NAL9602](INFO): GPS fix at 20250529T192705: (36.802936, -121.787951) 2025-05-29T19:27:06.368Z,1748546826.368 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T19:27:06.368Z,1748546826.368 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T19:27:13.834Z,1748546833.834 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0042.lzma 2025-05-29T19:27:14.837Z,1748546834.837 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0042.lzma.bak 2025-05-29T19:27:14.837Z,1748546834.837 [DataOverHttps](INFO): SBD MOMSN=25033475 2025-05-29T19:27:30.675Z,1748546850.675 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250529T180702/Express0043.lzma 2025-05-29T19:27:31.677Z,1748546851.677 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0043.lzma.bak 2025-05-29T19:27:31.677Z,1748546851.677 [DataOverHttps](INFO): SBD MOMSN=25033478 2025-05-29T19:27:33.045Z,1748546853.045 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T19:27:33.045Z,1748546853.045 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T19:27:33.045Z,1748546853.045 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T19:29:06.394Z,1748546946.394 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:29:21.747Z,1748546961.747 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:29:36.694Z,1748546976.694 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:29:52.051Z,1748546992.051 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:30:07.405Z,1748547007.405 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:30:09.042Z,1748547009.042 [NAL9602](INFO): SBD MO Status=2, MOMSN=25146, MT Status=2, MTMSN=0 2025-05-29T19:30:09.042Z,1748547009.042 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T19:30:23.159Z,1748547023.159 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:30:32.461Z,1748547032.461 [NAL9602](INFO): SBD MO Status=1, MOMSN=25146, MT Status=0, MTMSN=0 2025-05-29T19:30:32.462Z,1748547032.462 [NAL9602](INFO): No messages in MT queue 2025-05-29T19:30:38.519Z,1748547038.519 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:30:53.522Z,1748547053.522 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:31:03.232Z,1748547063.232 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T19:31:08.888Z,1748547068.888 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:31:24.229Z,1748547084.229 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-05-29T19:32:33.734Z,1748547153.734 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T19:32:33.734Z,1748547153.734 [Default:CheckIn:C.Wait] Stopped 2025-05-29T19:32:33.734Z,1748547153.734 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T19:32:33.734Z,1748547153.734 [Default:CheckIn:D] Running Loop=1 2025-05-29T19:32:34.141Z,1748547154.141 [Default:CheckIn:D] Stopped 2025-05-29T19:32:34.141Z,1748547154.141 [Default:CheckIn:E] Running Loop=1 2025-05-29T19:32:34.535Z,1748547154.535 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.233683 min 2025-05-29T19:32:34.535Z,1748547154.535 [Default:CheckIn:E] Stopped 2025-05-29T19:32:34.535Z,1748547154.535 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T19:32:34.535Z,1748547154.535 [Default:CheckIn] Stopped 2025-05-29T19:32:34.535Z,1748547154.535 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T19:32:34.536Z,1748547154.536 [Default:CheckIn](INFO): Running loop #18 2025-05-29T19:32:34.536Z,1748547154.536 [Default:CheckIn] Running Loop=18 2025-05-29T19:32:34.536Z,1748547154.536 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T19:32:34.536Z,1748547154.536 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T19:32:36.550Z,1748547156.550 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193235.00,A,3648.17632,N,12147.27966,W,0.039,343.95,290525,,,A*7D 2025-05-29T19:32:36.560Z,1748547156.560 [NAL9602](INFO): GPS fix at 20250529T193235: (36.802939, -121.787994) 2025-05-29T19:32:36.572Z,1748547156.572 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T19:32:36.572Z,1748547156.572 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T19:32:46.916Z,1748547166.916 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0045.lzma 2025-05-29T19:32:47.917Z,1748547167.917 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0045.lzma.bak 2025-05-29T19:32:47.917Z,1748547167.917 [DataOverHttps](INFO): SBD MOMSN=25033496 2025-05-29T19:32:53.642Z,1748547173.642 [NAL9602](INFO): SBD MO Status=1, MOMSN=25147, MT Status=0, MTMSN=0 2025-05-29T19:32:53.642Z,1748547173.642 [NAL9602](INFO): No messages in MT queue 2025-05-29T19:33:03.751Z,1748547183.751 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250529T180702/Express0046.lzma 2025-05-29T19:33:04.753Z,1748547184.753 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0046.lzma.bak 2025-05-29T19:33:04.753Z,1748547184.753 [DataOverHttps](INFO): SBD MOMSN=25033499 2025-05-29T19:33:06.179Z,1748547186.179 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T19:33:06.180Z,1748547186.180 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T19:33:06.180Z,1748547186.180 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T19:33:24.349Z,1748547204.349 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T19:33:51.811Z,1748547231.811 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:34:05.971Z,1748547245.971 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:34:20.103Z,1748547260.103 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:34:34.243Z,1748547274.243 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:34:47.614Z,1748547287.614 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:35:01.350Z,1748547301.350 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:35:15.083Z,1748547315.083 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:35:28.831Z,1748547328.831 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:35:42.555Z,1748547342.555 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:35:56.696Z,1748547356.696 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:38:06.845Z,1748547486.845 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T19:38:06.845Z,1748547486.845 [Default:CheckIn:C.Wait] Stopped 2025-05-29T19:38:06.845Z,1748547486.845 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T19:38:06.845Z,1748547486.845 [Default:CheckIn:D] Running Loop=1 2025-05-29T19:38:07.246Z,1748547487.246 [Default:CheckIn:D] Stopped 2025-05-29T19:38:07.246Z,1748547487.246 [Default:CheckIn:E] Running Loop=1 2025-05-29T19:38:07.644Z,1748547487.644 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.785441 min 2025-05-29T19:38:07.644Z,1748547487.644 [Default:CheckIn:E] Stopped 2025-05-29T19:38:07.644Z,1748547487.644 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T19:38:07.645Z,1748547487.645 [Default:CheckIn] Stopped 2025-05-29T19:38:07.645Z,1748547487.645 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T19:38:07.645Z,1748547487.645 [Default:CheckIn](INFO): Running loop #19 2025-05-29T19:38:07.645Z,1748547487.645 [Default:CheckIn] Running Loop=19 2025-05-29T19:38:07.645Z,1748547487.645 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T19:38:07.645Z,1748547487.645 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T19:38:09.657Z,1748547489.657 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193808.00,A,3648.17869,N,12147.27869,W,0.019,343.95,290525,,,A*75 2025-05-29T19:38:09.659Z,1748547489.659 [NAL9602](INFO): GPS fix at 20250529T193808: (36.802978, -121.787978) 2025-05-29T19:38:09.670Z,1748547489.670 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T19:38:09.670Z,1748547489.670 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T19:38:16.859Z,1748547496.859 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250529T180702/Courier0048.lzma 2025-05-29T19:38:17.861Z,1748547497.861 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0048.lzma.bak 2025-05-29T19:38:17.861Z,1748547497.861 [DataOverHttps](INFO): SBD MOMSN=25033572 2025-05-29T19:38:33.759Z,1748547513.759 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250529T180702/Express0049.lzma 2025-05-29T19:38:34.699Z,1748547514.699 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:38:34.761Z,1748547514.761 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0049.lzma.bak 2025-05-29T19:38:34.761Z,1748547514.761 [DataOverHttps](INFO): SBD MOMSN=25033575 2025-05-29T19:38:35.949Z,1748547515.949 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T19:38:35.949Z,1748547515.949 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T19:38:35.949Z,1748547515.949 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T19:38:39.610Z,1748547519.610 [NAL9602](INFO): SBD MO Status=2, MOMSN=25148, MT Status=2, MTMSN=0 2025-05-29T19:38:39.610Z,1748547519.610 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T19:38:50.119Z,1748547530.119 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:39:05.133Z,1748547545.133 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:39:20.027Z,1748547560.027 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:39:22.846Z,1748547562.846 [NAL9602](INFO): SBD MO Status=1, MOMSN=25148, MT Status=0, MTMSN=0 2025-05-29T19:39:22.846Z,1748547562.846 [NAL9602](INFO): No messages in MT queue 2025-05-29T19:39:35.367Z,1748547575.367 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:39:50.721Z,1748547590.721 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:39:53.549Z,1748547593.549 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T19:40:05.667Z,1748547605.667 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:40:20.630Z,1748547620.630 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:40:35.579Z,1748547635.579 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:40:50.167Z,1748547650.167 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-05-29T19:42:07.742Z,1748547727.742 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-05-29T19:42:07.742Z,1748547727.742 [DropWeight] Hardware Fault, FailCount= 1 2025-05-29T19:42:07.742Z,1748547727.742 [DropWeight](ERROR): Hardware Fault 2025-05-29T19:42:07.760Z,1748547727.760 [CommandExec](FAULT): Scheduling is paused 2025-05-29T19:42:07.761Z,1748547727.761 [CBIT](INFO): Critical error at 20250529T194207 2025-05-29T19:42:07.763Z,1748547727.763 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-05-29T19:42:07.764Z,1748547727.764 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-05-29T19:42:08.166Z,1748547728.166 [CBIT](INFO): Critical error at 20250529T194207 2025-05-29T19:43:17.749Z,1748547797.749 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:43:31.086Z,1748547811.086 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:43:36.694Z,1748547816.694 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T19:43:36.694Z,1748547816.694 [Default:CheckIn:C.Wait] Stopped 2025-05-29T19:43:36.694Z,1748547816.694 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T19:43:36.695Z,1748547816.695 [Default:CheckIn:D] Running Loop=1 2025-05-29T19:43:37.111Z,1748547817.111 [Default:CheckIn:D] Stopped 2025-05-29T19:43:37.111Z,1748547817.111 [Default:CheckIn:E] Running Loop=1 2025-05-29T19:43:37.494Z,1748547817.494 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.283187 min 2025-05-29T19:43:37.494Z,1748547817.494 [Default:CheckIn:E] Stopped 2025-05-29T19:43:37.495Z,1748547817.495 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T19:43:37.495Z,1748547817.495 [Default:CheckIn] Stopped 2025-05-29T19:43:37.495Z,1748547817.495 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T19:43:37.495Z,1748547817.495 [Default:CheckIn](INFO): Running loop #20 2025-05-29T19:43:37.495Z,1748547817.495 [Default:CheckIn] Running Loop=20 2025-05-29T19:43:37.495Z,1748547817.495 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T19:43:37.495Z,1748547817.495 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T19:43:39.526Z,1748547819.526 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194338.00,A,3648.17895,N,12147.27941,W,0.039,343.95,290525,,,A*70 2025-05-29T19:43:39.528Z,1748547819.528 [NAL9602](INFO): GPS fix at 20250529T194338: (36.802982, -121.787990) 2025-05-29T19:43:39.539Z,1748547819.539 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T19:43:39.539Z,1748547819.539 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T19:43:44.762Z,1748547824.762 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:43:47.095Z,1748547827.095 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20250529T180702/Courier0051.lzma 2025-05-29T19:43:48.097Z,1748547828.097 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0051.lzma.bak 2025-05-29T19:43:48.097Z,1748547828.097 [DataOverHttps](INFO): SBD MOMSN=25033593 2025-05-29T19:43:58.907Z,1748547838.907 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:44:02.133Z,1748547842.133 [NAL9602](INFO): SBD MO Status=2, MOMSN=25149, MT Status=2, MTMSN=0 2025-05-29T19:44:02.134Z,1748547842.134 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T19:44:04.063Z,1748547844.063 [DataOverHttps](INFO): Sending 183 bytes from file Logs/20250529T180702/Express0052.lzma 2025-05-29T19:44:05.065Z,1748547845.065 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0052.lzma.bak 2025-05-29T19:44:05.065Z,1748547845.065 [DataOverHttps](INFO): SBD MOMSN=25033598 2025-05-29T19:44:06.203Z,1748547846.203 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T19:44:06.203Z,1748547846.203 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T19:44:06.203Z,1748547846.203 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T19:44:09.006Z,1748547849.006 [NAL9602](INFO): SBD MO Status=1, MOMSN=25149, MT Status=0, MTMSN=0 2025-05-29T19:44:09.006Z,1748547849.006 [NAL9602](INFO): No messages in MT queue 2025-05-29T19:44:12.239Z,1748547852.239 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:44:25.975Z,1748547865.975 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:44:39.724Z,1748547879.724 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T19:44:40.522Z,1748547880.522 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:44:54.709Z,1748547894.709 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:45:08.443Z,1748547908.443 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:45:22.181Z,1748547922.181 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:45:35.916Z,1748547935.916 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:47:58.995Z,1748548078.995 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:48:05.863Z,1748548085.863 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:48:22.024Z,1748548102.024 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:48:37.387Z,1748548117.387 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:48:52.359Z,1748548132.359 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:49:06.922Z,1748548146.922 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T19:49:06.922Z,1748548146.922 [Default:CheckIn:C.Wait] Stopped 2025-05-29T19:49:06.922Z,1748548146.922 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T19:49:06.939Z,1748548146.939 [Default:CheckIn:D] Running Loop=1 2025-05-29T19:49:07.368Z,1748548147.368 [Default:CheckIn:D] Stopped 2025-05-29T19:49:07.368Z,1748548147.368 [Default:CheckIn:E] Running Loop=1 2025-05-29T19:49:07.715Z,1748548147.715 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:49:07.733Z,1748548147.733 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.787459 min 2025-05-29T19:49:07.733Z,1748548147.733 [Default:CheckIn:E] Stopped 2025-05-29T19:49:07.733Z,1748548147.733 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T19:49:07.733Z,1748548147.733 [Default:CheckIn] Stopped 2025-05-29T19:49:07.733Z,1748548147.733 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T19:49:07.733Z,1748548147.733 [Default:CheckIn](INFO): Running loop #21 2025-05-29T19:49:07.733Z,1748548147.733 [Default:CheckIn] Running Loop=21 2025-05-29T19:49:07.733Z,1748548147.733 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T19:49:07.733Z,1748548147.733 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T19:49:09.770Z,1748548149.770 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194909.00,A,3648.17657,N,12147.27769,W,0.039,343.95,290525,,,A*7C 2025-05-29T19:49:09.772Z,1748548149.772 [NAL9602](INFO): GPS fix at 20250529T194909: (36.802943, -121.787961) 2025-05-29T19:49:09.783Z,1748548149.783 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T19:49:09.783Z,1748548149.783 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T19:49:16.971Z,1748548156.971 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250529T180702/Courier0054.lzma 2025-05-29T19:49:17.973Z,1748548157.973 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0054.lzma.bak 2025-05-29T19:49:17.973Z,1748548157.973 [DataOverHttps](INFO): SBD MOMSN=25033673 2025-05-29T19:49:22.659Z,1748548162.659 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:49:25.086Z,1748548165.086 [NAL9602](INFO): SBD MO Status=1, MOMSN=25150, MT Status=0, MTMSN=0 2025-05-29T19:49:25.086Z,1748548165.086 [NAL9602](INFO): No messages in MT queue 2025-05-29T19:49:33.976Z,1748548173.976 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250529T180702/Express0055.lzma 2025-05-29T19:49:34.977Z,1748548174.977 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0055.lzma.bak 2025-05-29T19:49:34.977Z,1748548174.977 [DataOverHttps](INFO): SBD MOMSN=25033676 2025-05-29T19:49:36.414Z,1748548176.414 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T19:49:36.414Z,1748548176.414 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T19:49:36.414Z,1748548176.414 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T19:49:38.035Z,1748548178.035 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:49:52.959Z,1748548192.959 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:49:55.796Z,1748548195.796 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T19:50:08.316Z,1748548208.316 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:52:52.439Z,1748548372.439 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:53:06.176Z,1748548386.176 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:53:20.315Z,1748548400.315 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:53:34.051Z,1748548414.051 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:53:48.190Z,1748548428.190 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:54:02.331Z,1748548442.331 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:54:15.663Z,1748548455.663 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:54:29.411Z,1748548469.411 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:54:37.100Z,1748548477.100 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T19:54:37.100Z,1748548477.100 [Default:CheckIn:C.Wait] Stopped 2025-05-29T19:54:37.100Z,1748548477.100 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T19:54:37.101Z,1748548477.101 [Default:CheckIn:D] Running Loop=1 2025-05-29T19:54:37.498Z,1748548477.498 [Default:CheckIn:D] Stopped 2025-05-29T19:54:37.498Z,1748548477.498 [Default:CheckIn:E] Running Loop=1 2025-05-29T19:54:37.894Z,1748548477.894 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.289640 min 2025-05-29T19:54:37.894Z,1748548477.894 [Default:CheckIn:E] Stopped 2025-05-29T19:54:37.895Z,1748548477.895 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T19:54:37.895Z,1748548477.895 [Default:CheckIn] Stopped 2025-05-29T19:54:37.895Z,1748548477.895 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T19:54:37.895Z,1748548477.895 [Default:CheckIn](INFO): Running loop #22 2025-05-29T19:54:37.895Z,1748548477.895 [Default:CheckIn] Running Loop=22 2025-05-29T19:54:37.895Z,1748548477.895 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T19:54:37.895Z,1748548477.895 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T19:54:39.920Z,1748548479.920 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195439.00,A,3648.17352,N,12147.28039,W,0.078,343.95,290525,,,A*7B 2025-05-29T19:54:39.922Z,1748548479.922 [NAL9602](INFO): GPS fix at 20250529T195439: (36.802892, -121.788006) 2025-05-29T19:54:39.933Z,1748548479.933 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T19:54:39.934Z,1748548479.934 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T19:54:42.727Z,1748548482.727 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:54:50.387Z,1748548490.387 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0057.lzma 2025-05-29T19:54:51.389Z,1748548491.389 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0057.lzma.bak 2025-05-29T19:54:51.389Z,1748548491.389 [DataOverHttps](INFO): SBD MOMSN=25033698 2025-05-29T19:54:56.517Z,1748548496.517 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:55:07.523Z,1748548507.523 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250529T180702/Express0058.lzma 2025-05-29T19:55:08.525Z,1748548508.525 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0058.lzma.bak 2025-05-29T19:55:08.525Z,1748548508.525 [DataOverHttps](INFO): SBD MOMSN=25033701 2025-05-29T19:55:09.866Z,1748548509.866 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T19:55:09.866Z,1748548509.866 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T19:55:09.866Z,1748548509.866 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T19:56:44.204Z,1748548604.204 [NAL9602](INFO): SBD MO Status=2, MOMSN=25151, MT Status=2, MTMSN=0 2025-05-29T19:56:44.205Z,1748548604.205 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T19:57:07.913Z,1748548627.913 [CBIT](INFO): Clearing failed state for component DropWeight 2025-05-29T19:57:07.913Z,1748548627.913 [DropWeight] No Fault, FailCount= 1 2025-05-29T19:57:09.923Z,1748548629.923 [NAL9602](INFO): SBD MO Status=2, MOMSN=25151, MT Status=2, MTMSN=0 2025-05-29T19:57:09.923Z,1748548629.923 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T19:57:26.071Z,1748548646.071 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:57:27.286Z,1748548647.286 [NAL9602](INFO): SBD MO Status=2, MOMSN=25151, MT Status=2, MTMSN=0 2025-05-29T19:57:27.298Z,1748548647.298 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T19:57:32.939Z,1748548652.939 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:57:47.887Z,1748548667.887 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:57:53.149Z,1748548673.149 [NAL9602](INFO): SBD MO Status=2, MOMSN=25151, MT Status=2, MTMSN=0 2025-05-29T19:57:53.150Z,1748548673.150 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T19:58:03.242Z,1748548683.242 [NAL9602](INFO): SBD MO Status=1, MOMSN=25151, MT Status=0, MTMSN=0 2025-05-29T19:58:03.242Z,1748548683.242 [NAL9602](INFO): No messages in MT queue 2025-05-29T19:58:04.047Z,1748548684.047 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:58:18.996Z,1748548698.996 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T19:58:27.075Z,1748548707.075 [BPC1](ERROR): Battery stick #13 (s/n: 015A) reported TERMINATE_CHARGE_ALARM. Status code: 0x42D0. 2025-05-29T19:58:27.076Z,1748548707.076 [BPC1](ERROR): Battery stick #16 (s/n: 00F8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T19:58:27.077Z,1748548707.077 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T19:58:27.078Z,1748548707.078 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-05-29T19:58:27.079Z,1748548707.079 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T19:58:27.079Z,1748548707.079 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T19:58:27.080Z,1748548707.080 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T19:58:27.081Z,1748548707.081 [BPC1](ERROR): Battery stick #58 (s/n: 00C1) reported OVER_TEMP_ALARM. Status code: 0x1717. 2025-05-29T19:58:27.082Z,1748548707.082 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T19:58:27.082Z,1748548707.082 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 4. 2025-05-29T19:58:27.107Z,1748548707.107 [BPC1](FAULT): Failed to receive data from 15 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 10, 11, 12, 13, 14, 15, 16, 24, 29, 35, 51, 52, 53, 57, 59. 2025-05-29T19:58:33.944Z,1748548713.944 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T20:00:10.530Z,1748548810.530 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T20:00:10.530Z,1748548810.530 [Default:CheckIn:C.Wait] Stopped 2025-05-29T20:00:10.530Z,1748548810.530 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T20:00:10.530Z,1748548810.530 [Default:CheckIn:D] Running Loop=1 2025-05-29T20:00:10.932Z,1748548810.932 [Default:CheckIn:D] Stopped 2025-05-29T20:00:10.932Z,1748548810.932 [Default:CheckIn:E] Running Loop=1 2025-05-29T20:00:11.342Z,1748548811.342 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 129.846867 min 2025-05-29T20:00:11.342Z,1748548811.342 [Default:CheckIn:E] Stopped 2025-05-29T20:00:11.342Z,1748548811.342 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T20:00:11.342Z,1748548811.342 [Default:CheckIn] Stopped 2025-05-29T20:00:11.342Z,1748548811.342 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T20:00:11.342Z,1748548811.342 [Default:CheckIn](INFO): Running loop #23 2025-05-29T20:00:11.342Z,1748548811.342 [Default:CheckIn] Running Loop=23 2025-05-29T20:00:11.342Z,1748548811.342 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T20:00:11.343Z,1748548811.343 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T20:00:13.354Z,1748548813.354 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200012.00,A,3648.17469,N,12147.27828,W,0.058,0.00,290525,,,A*7B 2025-05-29T20:00:13.356Z,1748548813.356 [NAL9602](INFO): GPS fix at 20250529T200012: (36.802912, -121.787971) 2025-05-29T20:00:13.384Z,1748548813.384 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T20:00:13.384Z,1748548813.384 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T20:00:20.851Z,1748548820.851 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20250529T180702/Courier0060.lzma 2025-05-29T20:00:21.853Z,1748548821.853 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0060.lzma.bak 2025-05-29T20:00:21.853Z,1748548821.853 [DataOverHttps](INFO): SBD MOMSN=25033770 2025-05-29T20:00:37.627Z,1748548837.627 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20250529T180702/Express0061.lzma 2025-05-29T20:00:38.629Z,1748548838.629 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0061.lzma.bak 2025-05-29T20:00:38.630Z,1748548838.630 [DataOverHttps](INFO): SBD MOMSN=25033773 2025-05-29T20:00:39.202Z,1748548839.202 [NAL9602](INFO): SBD MO Status=1, MOMSN=25152, MT Status=0, MTMSN=0 2025-05-29T20:00:39.202Z,1748548839.202 [NAL9602](INFO): No messages in MT queue 2025-05-29T20:00:40.031Z,1748548840.031 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T20:00:40.031Z,1748548840.031 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T20:00:40.031Z,1748548840.031 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T20:01:09.948Z,1748548869.948 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T20:05:40.696Z,1748549140.696 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T20:05:40.696Z,1748549140.696 [Default:CheckIn:C.Wait] Stopped 2025-05-29T20:05:40.696Z,1748549140.696 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T20:05:40.696Z,1748549140.696 [Default:CheckIn:D] Running Loop=1 2025-05-29T20:05:41.111Z,1748549141.111 [Default:CheckIn:D] Stopped 2025-05-29T20:05:41.111Z,1748549141.111 [Default:CheckIn:E] Running Loop=1 2025-05-29T20:05:41.506Z,1748549141.506 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 135.349837 min 2025-05-29T20:05:41.506Z,1748549141.506 [Default:CheckIn:E] Stopped 2025-05-29T20:05:41.506Z,1748549141.506 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T20:05:41.507Z,1748549141.507 [Default:CheckIn] Stopped 2025-05-29T20:05:41.507Z,1748549141.507 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T20:05:41.507Z,1748549141.507 [Default:CheckIn](INFO): Running loop #24 2025-05-29T20:05:41.507Z,1748549141.507 [Default:CheckIn] Running Loop=24 2025-05-29T20:05:41.507Z,1748549141.507 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T20:05:41.507Z,1748549141.507 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T20:05:43.522Z,1748549143.522 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200542.00,A,3648.17817,N,12147.27888,W,0.039,0.00,290525,,,A*73 2025-05-29T20:05:43.524Z,1748549143.524 [NAL9602](INFO): GPS fix at 20250529T200542: (36.802970, -121.787981) 2025-05-29T20:05:43.534Z,1748549143.534 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T20:05:43.535Z,1748549143.535 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T20:05:51.510Z,1748549151.510 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0063.lzma 2025-05-29T20:05:52.513Z,1748549152.513 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0063.lzma.bak 2025-05-29T20:05:52.513Z,1748549152.513 [DataOverHttps](INFO): SBD MOMSN=25033792 2025-05-29T20:06:08.472Z,1748549168.472 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250529T180702/Express0064.lzma 2025-05-29T20:06:09.473Z,1748549169.473 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0064.lzma.bak 2025-05-29T20:06:09.473Z,1748549169.473 [DataOverHttps](INFO): SBD MOMSN=25033795 2025-05-29T20:06:10.632Z,1748549170.632 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T20:06:10.632Z,1748549170.632 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T20:06:10.632Z,1748549170.632 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T20:06:13.410Z,1748549173.410 [NAL9602](INFO): SBD MO Status=1, MOMSN=25153, MT Status=0, MTMSN=0 2025-05-29T20:06:13.410Z,1748549173.410 [NAL9602](INFO): No messages in MT queue 2025-05-29T20:06:44.144Z,1748549204.144 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T20:11:11.281Z,1748549471.281 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T20:11:11.281Z,1748549471.281 [Default:CheckIn:C.Wait] Stopped 2025-05-29T20:11:11.281Z,1748549471.281 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T20:11:11.282Z,1748549471.282 [Default:CheckIn:D] Running Loop=1 2025-05-29T20:11:11.691Z,1748549471.691 [Default:CheckIn:D] Stopped 2025-05-29T20:11:11.692Z,1748549471.692 [Default:CheckIn:E] Running Loop=1 2025-05-29T20:11:12.087Z,1748549472.087 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 140.859521 min 2025-05-29T20:11:12.087Z,1748549472.087 [Default:CheckIn:E] Stopped 2025-05-29T20:11:12.087Z,1748549472.087 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T20:11:12.087Z,1748549472.087 [Default:CheckIn] Stopped 2025-05-29T20:11:12.087Z,1748549472.087 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T20:11:12.088Z,1748549472.088 [Default:CheckIn](INFO): Running loop #25 2025-05-29T20:11:12.088Z,1748549472.088 [Default:CheckIn] Running Loop=25 2025-05-29T20:11:12.088Z,1748549472.088 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T20:11:12.088Z,1748549472.088 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T20:11:14.100Z,1748549474.100 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201113.00,A,3648.18658,N,12147.27013,W,0.175,0.00,290525,,,A*7B 2025-05-29T20:11:14.102Z,1748549474.102 [NAL9602](INFO): GPS fix at 20250529T201113: (36.803110, -121.787835) 2025-05-29T20:11:14.113Z,1748549474.113 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T20:11:14.113Z,1748549474.113 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T20:11:22.095Z,1748549482.095 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0066.lzma 2025-05-29T20:11:23.097Z,1748549483.097 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0066.lzma.bak 2025-05-29T20:11:23.097Z,1748549483.097 [DataOverHttps](INFO): SBD MOMSN=25033873 2025-05-29T20:11:27.446Z,1748549487.446 [NAL9602](INFO): SBD MO Status=1, MOMSN=25154, MT Status=0, MTMSN=0 2025-05-29T20:11:27.446Z,1748549487.446 [NAL9602](INFO): No messages in MT queue 2025-05-29T20:11:38.335Z,1748549498.335 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-05-29T20:11:39.163Z,1748549499.163 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250529T180702/Express0067.lzma 2025-05-29T20:11:40.165Z,1748549500.165 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0067.lzma.bak 2025-05-29T20:11:40.165Z,1748549500.165 [DataOverHttps](INFO): SBD MOMSN=25033876 2025-05-29T20:11:41.590Z,1748549501.590 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T20:11:41.590Z,1748549501.590 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T20:11:41.590Z,1748549501.590 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T20:11:44.799Z,1748549504.799 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:11:57.323Z,1748549517.323 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:11:58.133Z,1748549518.133 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T20:12:10.252Z,1748549530.252 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:12:22.780Z,1748549542.780 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:12:35.315Z,1748549555.315 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:12:47.446Z,1748549567.446 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:13:00.373Z,1748549580.373 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:13:12.491Z,1748549592.491 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:13:25.015Z,1748549605.015 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:13:36.731Z,1748549616.731 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:13:40.829Z,1748549620.829 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-05-29T20:13:49.255Z,1748549629.255 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:16:26.411Z,1748549786.411 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:16:41.376Z,1748549801.376 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:16:42.206Z,1748549802.206 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T20:16:42.206Z,1748549802.206 [Default:CheckIn:C.Wait] Stopped 2025-05-29T20:16:42.206Z,1748549802.206 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T20:16:42.206Z,1748549802.206 [Default:CheckIn:D] Running Loop=1 2025-05-29T20:16:42.593Z,1748549802.593 [Default:CheckIn:D] Stopped 2025-05-29T20:16:42.593Z,1748549802.593 [Default:CheckIn:E] Running Loop=1 2025-05-29T20:16:43.000Z,1748549803.000 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 146.374544 min 2025-05-29T20:16:43.000Z,1748549803.000 [Default:CheckIn:E] Stopped 2025-05-29T20:16:43.000Z,1748549803.000 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T20:16:43.000Z,1748549803.000 [Default:CheckIn] Stopped 2025-05-29T20:16:43.000Z,1748549803.000 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T20:16:43.001Z,1748549803.001 [Default:CheckIn](INFO): Running loop #26 2025-05-29T20:16:43.001Z,1748549803.001 [Default:CheckIn] Running Loop=26 2025-05-29T20:16:43.001Z,1748549803.001 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T20:16:43.001Z,1748549803.001 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T20:16:44.998Z,1748549804.998 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201644.00,A,3648.18376,N,12147.27063,W,0.019,0.00,290525,,,A*7B 2025-05-29T20:16:45.001Z,1748549805.001 [NAL9602](INFO): GPS fix at 20250529T201644: (36.803063, -121.787844) 2025-05-29T20:16:45.044Z,1748549805.044 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T20:16:45.045Z,1748549805.045 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T20:16:52.203Z,1748549812.203 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250529T180702/Courier0069.lzma 2025-05-29T20:16:53.205Z,1748549813.205 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0069.lzma.bak 2025-05-29T20:16:53.205Z,1748549813.205 [DataOverHttps](INFO): SBD MOMSN=25033894 2025-05-29T20:16:56.151Z,1748549816.151 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:17:09.151Z,1748549829.151 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250529T180702/Express0070.lzma 2025-05-29T20:17:09.898Z,1748549829.898 [NAL9602](INFO): SBD MO Status=2, MOMSN=25155, MT Status=2, MTMSN=0 2025-05-29T20:17:09.898Z,1748549829.898 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T20:17:10.154Z,1748549830.154 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0070.lzma.bak 2025-05-29T20:17:10.154Z,1748549830.154 [DataOverHttps](INFO): SBD MOMSN=25033897 2025-05-29T20:17:11.098Z,1748549831.098 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:17:11.530Z,1748549831.530 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T20:17:11.530Z,1748549831.530 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T20:17:11.531Z,1748549831.531 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T20:17:19.989Z,1748549839.989 [NAL9602](INFO): SBD MO Status=1, MOMSN=25155, MT Status=0, MTMSN=0 2025-05-29T20:17:19.989Z,1748549839.989 [NAL9602](INFO): No messages in MT queue 2025-05-29T20:17:26.045Z,1748549846.045 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:17:41.008Z,1748549861.008 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:17:50.695Z,1748549870.695 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T20:17:56.343Z,1748549876.343 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:18:12.100Z,1748549892.100 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:18:27.855Z,1748549907.855 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:18:41.999Z,1748549921.999 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-05-29T20:21:10.682Z,1748550070.682 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:21:24.421Z,1748550084.421 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:21:38.155Z,1748550098.155 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:21:52.295Z,1748550112.295 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:22:06.031Z,1748550126.031 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:22:12.110Z,1748550132.110 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T20:22:12.110Z,1748550132.110 [Default:CheckIn:C.Wait] Stopped 2025-05-29T20:22:12.110Z,1748550132.110 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T20:22:12.110Z,1748550132.110 [Default:CheckIn:D] Running Loop=1 2025-05-29T20:22:12.515Z,1748550132.515 [Default:CheckIn:D] Stopped 2025-05-29T20:22:12.515Z,1748550132.515 [Default:CheckIn:E] Running Loop=1 2025-05-29T20:22:12.930Z,1748550132.930 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 151.873242 min 2025-05-29T20:22:12.930Z,1748550132.930 [Default:CheckIn:E] Stopped 2025-05-29T20:22:12.930Z,1748550132.930 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T20:22:12.931Z,1748550132.931 [Default:CheckIn] Stopped 2025-05-29T20:22:12.931Z,1748550132.931 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T20:22:12.931Z,1748550132.931 [Default:CheckIn](INFO): Running loop #27 2025-05-29T20:22:12.931Z,1748550132.931 [Default:CheckIn] Running Loop=27 2025-05-29T20:22:12.931Z,1748550132.931 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T20:22:12.931Z,1748550132.931 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T20:22:14.922Z,1748550134.922 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202214.00,A,3648.18378,N,12147.27506,W,0.039,0.00,290525,,,A*73 2025-05-29T20:22:14.925Z,1748550134.925 [NAL9602](INFO): GPS fix at 20250529T202214: (36.803063, -121.787918) 2025-05-29T20:22:14.936Z,1748550134.936 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T20:22:14.936Z,1748550134.936 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T20:22:19.831Z,1748550139.831 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:22:22.127Z,1748550142.127 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0072.lzma 2025-05-29T20:22:23.129Z,1748550143.129 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0072.lzma.bak 2025-05-29T20:22:23.129Z,1748550143.129 [DataOverHttps](INFO): SBD MOMSN=25033975 2025-05-29T20:22:33.971Z,1748550153.971 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:22:41.235Z,1748550161.235 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250529T180702/Express0073.lzma 2025-05-29T20:22:42.238Z,1748550162.238 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0073.lzma.bak 2025-05-29T20:22:42.238Z,1748550162.238 [DataOverHttps](INFO): SBD MOMSN=25033978 2025-05-29T20:22:43.746Z,1748550163.746 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T20:22:43.746Z,1748550163.746 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T20:22:43.746Z,1748550163.746 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T20:22:47.756Z,1748550167.756 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:23:01.493Z,1748550181.493 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:23:15.632Z,1748550195.632 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:24:55.078Z,1748550295.078 [NAL9602](INFO): SBD MO Status=2, MOMSN=25156, MT Status=2, MTMSN=0 2025-05-29T20:24:55.078Z,1748550295.078 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T20:25:20.130Z,1748550320.130 [NAL9602](INFO): SBD MO Status=1, MOMSN=25156, MT Status=0, MTMSN=0 2025-05-29T20:25:20.130Z,1748550320.130 [NAL9602](INFO): No messages in MT queue 2025-05-29T20:25:50.820Z,1748550350.820 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T20:25:52.836Z,1748550352.836 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:26:08.591Z,1748550368.591 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:26:23.539Z,1748550383.539 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:26:38.893Z,1748550398.893 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:26:53.893Z,1748550413.893 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:27:09.267Z,1748550429.267 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:27:09.280Z,1748550429.280 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-05-29T20:27:09.280Z,1748550429.280 [DropWeight] Hardware Fault, FailCount= 1 2025-05-29T20:27:09.280Z,1748550429.280 [DropWeight](ERROR): Hardware Fault 2025-05-29T20:27:09.298Z,1748550429.298 [CBIT](INFO): Critical error at 20250529T202709 2025-05-29T20:27:09.301Z,1748550429.301 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-05-29T20:27:09.301Z,1748550429.301 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-05-29T20:27:09.713Z,1748550429.713 [CBIT](INFO): Critical error at 20250529T202709 2025-05-29T20:27:24.203Z,1748550444.203 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:27:39.151Z,1748550459.151 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:27:44.416Z,1748550464.416 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T20:27:44.416Z,1748550464.416 [Default:CheckIn:C.Wait] Stopped 2025-05-29T20:27:44.416Z,1748550464.416 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T20:27:44.417Z,1748550464.417 [Default:CheckIn:D] Running Loop=1 2025-05-29T20:27:44.826Z,1748550464.826 [Default:CheckIn:D] Stopped 2025-05-29T20:27:44.826Z,1748550464.826 [Default:CheckIn:E] Running Loop=1 2025-05-29T20:27:45.223Z,1748550465.223 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 157.411768 min 2025-05-29T20:27:45.223Z,1748550465.223 [Default:CheckIn:E] Stopped 2025-05-29T20:27:45.223Z,1748550465.223 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T20:27:45.223Z,1748550465.223 [Default:CheckIn] Stopped 2025-05-29T20:27:45.223Z,1748550465.223 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T20:27:45.224Z,1748550465.224 [Default:CheckIn](INFO): Running loop #28 2025-05-29T20:27:45.224Z,1748550465.224 [Default:CheckIn] Running Loop=28 2025-05-29T20:27:45.224Z,1748550465.224 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T20:27:45.224Z,1748550465.224 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T20:27:47.242Z,1748550467.242 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202746.00,A,3648.17786,N,12147.27755,W,0.097,0.00,290525,,,A*7B 2025-05-29T20:27:47.244Z,1748550467.244 [NAL9602](INFO): GPS fix at 20250529T202746: (36.802964, -121.787959) 2025-05-29T20:27:47.254Z,1748550467.254 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T20:27:47.254Z,1748550467.254 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T20:27:54.102Z,1748550474.102 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:27:54.412Z,1748550474.412 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20250529T180702/Courier0075.lzma 2025-05-29T20:27:55.413Z,1748550475.413 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0075.lzma.bak 2025-05-29T20:27:55.413Z,1748550475.413 [DataOverHttps](INFO): SBD MOMSN=25033990 2025-05-29T20:28:08.715Z,1748550488.715 [NAL9602](INFO): SBD MO Status=2, MOMSN=25157, MT Status=2, MTMSN=0 2025-05-29T20:28:08.716Z,1748550488.716 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T20:28:09.053Z,1748550489.053 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-05-29T20:28:11.471Z,1748550491.471 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250529T180702/Express0076.lzma 2025-05-29T20:28:12.473Z,1748550492.473 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0076.lzma.bak 2025-05-29T20:28:12.473Z,1748550492.473 [DataOverHttps](INFO): SBD MOMSN=25033995 2025-05-29T20:28:13.926Z,1748550493.926 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T20:28:13.926Z,1748550493.926 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T20:28:13.926Z,1748550493.926 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T20:28:27.646Z,1748550507.646 [NAL9602](INFO): SBD MO Status=1, MOMSN=25157, MT Status=0, MTMSN=0 2025-05-29T20:28:27.646Z,1748550507.646 [NAL9602](INFO): No messages in MT queue 2025-05-29T20:28:58.344Z,1748550538.344 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T20:30:36.915Z,1748550636.915 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:30:50.652Z,1748550650.652 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:31:04.387Z,1748550664.387 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:31:18.123Z,1748550678.123 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:31:31.886Z,1748550691.886 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:31:45.999Z,1748550705.999 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:31:59.736Z,1748550719.736 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:32:13.877Z,1748550733.877 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:32:27.611Z,1748550747.611 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:32:41.347Z,1748550761.347 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:33:14.565Z,1748550794.565 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T20:33:14.565Z,1748550794.565 [Default:CheckIn:C.Wait] Stopped 2025-05-29T20:33:14.565Z,1748550794.565 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T20:33:14.565Z,1748550794.565 [Default:CheckIn:D] Running Loop=1 2025-05-29T20:33:14.977Z,1748550794.977 [Default:CheckIn:D] Stopped 2025-05-29T20:33:14.977Z,1748550794.977 [Default:CheckIn:E] Running Loop=1 2025-05-29T20:33:15.381Z,1748550795.381 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 162.914290 min 2025-05-29T20:33:15.381Z,1748550795.381 [Default:CheckIn:E] Stopped 2025-05-29T20:33:15.381Z,1748550795.381 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T20:33:15.381Z,1748550795.381 [Default:CheckIn] Stopped 2025-05-29T20:33:15.381Z,1748550795.381 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T20:33:15.381Z,1748550795.381 [Default:CheckIn](INFO): Running loop #29 2025-05-29T20:33:15.381Z,1748550795.381 [Default:CheckIn] Running Loop=29 2025-05-29T20:33:15.382Z,1748550795.382 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T20:33:15.382Z,1748550795.382 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T20:33:17.366Z,1748550797.366 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203316.00,A,3648.17608,N,12147.27744,W,0.117,0.00,290525,,,A*75 2025-05-29T20:33:17.369Z,1748550797.369 [NAL9602](INFO): GPS fix at 20250529T203316: (36.802935, -121.787957) 2025-05-29T20:33:17.380Z,1748550797.380 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T20:33:17.380Z,1748550797.380 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T20:33:28.042Z,1748550808.042 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250529T180702/Courier0078.lzma 2025-05-29T20:33:29.045Z,1748550809.045 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0078.lzma.bak 2025-05-29T20:33:29.045Z,1748550809.045 [DataOverHttps](INFO): SBD MOMSN=25034061 2025-05-29T20:33:36.362Z,1748550816.362 [NAL9602](INFO): SBD MO Status=1, MOMSN=25158, MT Status=0, MTMSN=0 2025-05-29T20:33:36.362Z,1748550816.362 [NAL9602](INFO): No messages in MT queue 2025-05-29T20:33:44.903Z,1748550824.903 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250529T180702/Express0079.lzma 2025-05-29T20:33:45.905Z,1748550825.905 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0079.lzma.bak 2025-05-29T20:33:45.905Z,1748550825.905 [DataOverHttps](INFO): SBD MOMSN=25034064 2025-05-29T20:33:47.284Z,1748550827.284 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T20:33:47.284Z,1748550827.284 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T20:33:47.284Z,1748550827.284 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T20:34:07.064Z,1748550847.064 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T20:35:29.471Z,1748550929.471 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:35:44.419Z,1748550944.419 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:35:58.964Z,1748550958.964 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:36:13.912Z,1748550973.912 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:36:28.860Z,1748550988.860 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:36:44.211Z,1748551004.211 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:36:59.564Z,1748551019.564 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:37:14.577Z,1748551034.577 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:37:29.864Z,1748551049.864 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:38:47.850Z,1748551127.850 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T20:38:47.851Z,1748551127.851 [Default:CheckIn:C.Wait] Stopped 2025-05-29T20:38:47.851Z,1748551127.851 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T20:38:47.851Z,1748551127.851 [Default:CheckIn:D] Running Loop=1 2025-05-29T20:38:48.262Z,1748551128.262 [Default:CheckIn:D] Stopped 2025-05-29T20:38:48.262Z,1748551128.262 [Default:CheckIn:E] Running Loop=1 2025-05-29T20:38:48.656Z,1748551128.656 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 168.469027 min 2025-05-29T20:38:48.656Z,1748551128.656 [Default:CheckIn:E] Stopped 2025-05-29T20:38:48.656Z,1748551128.656 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T20:38:48.656Z,1748551128.656 [Default:CheckIn] Stopped 2025-05-29T20:38:48.656Z,1748551128.656 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T20:38:48.657Z,1748551128.657 [Default:CheckIn](INFO): Running loop #30 2025-05-29T20:38:48.657Z,1748551128.657 [Default:CheckIn] Running Loop=30 2025-05-29T20:38:48.657Z,1748551128.657 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T20:38:48.657Z,1748551128.657 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T20:38:50.674Z,1748551130.674 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203849.00,A,3648.17619,N,12147.27890,W,0.039,0.00,290525,,,A*7F 2025-05-29T20:38:50.676Z,1748551130.676 [NAL9602](INFO): GPS fix at 20250529T203849: (36.802937, -121.787982) 2025-05-29T20:38:50.687Z,1748551130.687 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T20:38:50.687Z,1748551130.687 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T20:38:58.699Z,1748551138.699 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0081.lzma 2025-05-29T20:38:59.701Z,1748551139.701 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0081.lzma.bak 2025-05-29T20:38:59.701Z,1748551139.701 [DataOverHttps](INFO): SBD MOMSN=25034075 2025-05-29T20:39:15.651Z,1748551155.651 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250529T180702/Express0082.lzma 2025-05-29T20:39:16.653Z,1748551156.653 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0082.lzma.bak 2025-05-29T20:39:16.653Z,1748551156.653 [DataOverHttps](INFO): SBD MOMSN=25034078 2025-05-29T20:39:18.157Z,1748551158.157 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T20:39:18.157Z,1748551158.157 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T20:39:18.157Z,1748551158.157 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T20:39:28.640Z,1748551168.640 [BPC1](ERROR): Battery stick #13 (s/n: 015A) reported TERMINATE_CHARGE_ALARM. Status code: 0x42D0. 2025-05-29T20:39:28.640Z,1748551168.640 [BPC1](ERROR): Battery stick #16 (s/n: 00F8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T20:39:28.641Z,1748551168.641 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T20:39:28.642Z,1748551168.642 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-05-29T20:39:28.643Z,1748551168.643 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T20:39:28.644Z,1748551168.644 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T20:39:28.644Z,1748551168.644 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T20:39:28.645Z,1748551168.645 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T20:39:28.646Z,1748551168.646 [BPC1](INFO): Calculating totals. Valid battery stick count: 41. Valid reserve battery stick count: 4. 2025-05-29T20:39:28.666Z,1748551168.666 [BPC1](FAULT): Failed to receive data from 16 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 9, 10, 11, 12, 13, 14, 15, 16, 24, 29, 35, 51, 52, 53, 57, 59. 2025-05-29T20:41:01.608Z,1748551261.608 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-05-29T20:41:06.039Z,1748551266.039 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.000573 CHAN A1 (24V): 0.002411 CHAN A2 (12V): 0.000789 CHAN A3 (5V): -0.001380 CHAN B0 (3.3V): -0.000083 CHAN B1 (3.15aV): 0.000098 CHAN B2 (3.15bV): 0.000183 CHAN B3 (GND): 0.000212 OPEN: 0.006937 Full Scale: +/- 1 mA 2025-05-29T20:42:01.761Z,1748551321.761 [NAL9602](INFO): SBD MO Status=1, MOMSN=25159, MT Status=0, MTMSN=0 2025-05-29T20:42:01.761Z,1748551321.761 [NAL9602](INFO): No messages in MT queue 2025-05-29T20:42:09.460Z,1748551329.460 [CBIT](INFO): Clearing failed state for component DropWeight 2025-05-29T20:42:09.460Z,1748551329.460 [DropWeight] No Fault, FailCount= 1 2025-05-29T20:42:32.485Z,1748551352.485 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T20:44:18.724Z,1748551458.724 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T20:44:18.724Z,1748551458.724 [Default:CheckIn:C.Wait] Stopped 2025-05-29T20:44:18.724Z,1748551458.724 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T20:44:18.725Z,1748551458.725 [Default:CheckIn:D] Running Loop=1 2025-05-29T20:44:19.136Z,1748551459.136 [Default:CheckIn:D] Stopped 2025-05-29T20:44:19.136Z,1748551459.136 [Default:CheckIn:E] Running Loop=1 2025-05-29T20:44:19.530Z,1748551459.530 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 173.983594 min 2025-05-29T20:44:19.530Z,1748551459.530 [Default:CheckIn:E] Stopped 2025-05-29T20:44:19.531Z,1748551459.531 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T20:44:19.531Z,1748551459.531 [Default:CheckIn] Stopped 2025-05-29T20:44:19.531Z,1748551459.531 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T20:44:19.531Z,1748551459.531 [Default:CheckIn](INFO): Running loop #31 2025-05-29T20:44:19.531Z,1748551459.531 [Default:CheckIn] Running Loop=31 2025-05-29T20:44:19.531Z,1748551459.531 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T20:44:19.531Z,1748551459.531 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T20:44:21.543Z,1748551461.543 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204420.00,A,3648.17704,N,12147.27917,W,0.078,0.00,290525,,,A*7D 2025-05-29T20:44:21.545Z,1748551461.545 [NAL9602](INFO): GPS fix at 20250529T204420: (36.802951, -121.787986) 2025-05-29T20:44:21.556Z,1748551461.556 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T20:44:21.556Z,1748551461.556 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T20:44:28.991Z,1748551468.991 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20250529T180702/Courier0084.lzma 2025-05-29T20:44:29.993Z,1748551469.993 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0084.lzma.bak 2025-05-29T20:44:29.993Z,1748551469.993 [DataOverHttps](INFO): SBD MOMSN=25034240 2025-05-29T20:44:49.404Z,1748551489.404 [DataOverHttps](INFO): Sending 475 bytes from file Logs/20250529T180702/Express0085.lzma 2025-05-29T20:44:50.405Z,1748551490.405 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0085.lzma.bak 2025-05-29T20:44:50.405Z,1748551490.405 [DataOverHttps](INFO): SBD MOMSN=25034246 2025-05-29T20:44:51.887Z,1748551491.887 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T20:44:51.887Z,1748551491.887 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T20:44:51.887Z,1748551491.887 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T20:47:47.219Z,1748551667.219 [NAL9602](INFO): SBD MO Status=2, MOMSN=25160, MT Status=2, MTMSN=0 2025-05-29T20:47:47.220Z,1748551667.220 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T20:49:23.376Z,1748551763.376 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-05-29T20:49:52.475Z,1748551792.475 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T20:49:52.475Z,1748551792.475 [Default:CheckIn:C.Wait] Stopped 2025-05-29T20:49:52.475Z,1748551792.475 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T20:49:52.476Z,1748551792.476 [Default:CheckIn:D] Running Loop=1 2025-05-29T20:49:52.885Z,1748551792.885 [Default:CheckIn:D] Stopped 2025-05-29T20:49:52.885Z,1748551792.885 [Default:CheckIn:E] Running Loop=1 2025-05-29T20:49:53.282Z,1748551793.282 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 179.546094 min 2025-05-29T20:49:53.283Z,1748551793.283 [Default:CheckIn:E] Stopped 2025-05-29T20:49:53.295Z,1748551793.295 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T20:49:53.295Z,1748551793.295 [Default:CheckIn] Stopped 2025-05-29T20:49:53.296Z,1748551793.296 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T20:49:53.296Z,1748551793.296 [Default:CheckIn](INFO): Running loop #32 2025-05-29T20:49:53.296Z,1748551793.296 [Default:CheckIn] Running Loop=32 2025-05-29T20:49:53.296Z,1748551793.296 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T20:49:53.296Z,1748551793.296 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T20:49:55.298Z,1748551795.298 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204954.00,A,3648.17355,N,12147.28014,W,0.058,0.00,290525,,,A*74 2025-05-29T20:49:55.301Z,1748551795.301 [NAL9602](INFO): GPS fix at 20250529T204954: (36.802892, -121.788002) 2025-05-29T20:49:55.325Z,1748551795.325 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T20:49:55.325Z,1748551795.325 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T20:50:02.874Z,1748551802.874 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0087.lzma 2025-05-29T20:50:03.877Z,1748551803.877 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0087.lzma.bak 2025-05-29T20:50:03.877Z,1748551803.877 [DataOverHttps](INFO): SBD MOMSN=25034264 2025-05-29T20:50:19.843Z,1748551819.843 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20250529T180702/Express0088.lzma 2025-05-29T20:50:20.845Z,1748551820.845 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0088.lzma.bak 2025-05-29T20:50:20.845Z,1748551820.845 [DataOverHttps](INFO): SBD MOMSN=25034277 2025-05-29T20:50:22.379Z,1748551822.379 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T20:50:22.379Z,1748551822.379 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T20:50:22.379Z,1748551822.379 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T20:50:27.613Z,1748551827.613 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T20:50:44.191Z,1748551844.191 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:50:58.315Z,1748551858.315 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:51:11.648Z,1748551871.648 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:51:25.787Z,1748551885.787 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:51:39.522Z,1748551899.522 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:54:15.871Z,1748552055.871 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:54:31.223Z,1748552071.223 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:54:46.575Z,1748552086.575 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:55:01.523Z,1748552101.523 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:55:16.875Z,1748552116.875 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:55:22.953Z,1748552122.953 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T20:55:22.953Z,1748552122.953 [Default:CheckIn:C.Wait] Stopped 2025-05-29T20:55:22.953Z,1748552122.953 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T20:55:22.953Z,1748552122.953 [Default:CheckIn:D] Running Loop=1 2025-05-29T20:55:23.370Z,1748552123.370 [Default:CheckIn:D] Stopped 2025-05-29T20:55:23.370Z,1748552123.370 [Default:CheckIn:E] Running Loop=1 2025-05-29T20:55:23.755Z,1748552123.755 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 185.054167 min 2025-05-29T20:55:23.756Z,1748552123.756 [Default:CheckIn:E] Stopped 2025-05-29T20:55:23.756Z,1748552123.756 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T20:55:23.756Z,1748552123.756 [Default:CheckIn] Stopped 2025-05-29T20:55:23.756Z,1748552123.756 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T20:55:23.756Z,1748552123.756 [Default:CheckIn](INFO): Running loop #33 2025-05-29T20:55:23.756Z,1748552123.756 [Default:CheckIn] Running Loop=33 2025-05-29T20:55:23.756Z,1748552123.756 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T20:55:23.756Z,1748552123.756 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T20:55:25.766Z,1748552125.766 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205525.00,A,3648.17381,N,12147.27938,W,0.039,0.00,290525,,,A*79 2025-05-29T20:55:25.768Z,1748552125.768 [NAL9602](INFO): GPS fix at 20250529T205525: (36.802897, -121.787990) 2025-05-29T20:55:25.796Z,1748552125.796 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T20:55:25.796Z,1748552125.796 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T20:55:32.631Z,1748552132.631 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:55:33.602Z,1748552133.602 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0090.lzma 2025-05-29T20:55:34.605Z,1748552134.605 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0090.lzma.bak 2025-05-29T20:55:34.605Z,1748552134.605 [DataOverHttps](INFO): SBD MOMSN=25034342 2025-05-29T20:55:47.985Z,1748552147.985 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:55:50.523Z,1748552150.523 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250529T180702/Express0091.lzma 2025-05-29T20:55:51.526Z,1748552151.526 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0091.lzma.bak 2025-05-29T20:55:51.526Z,1748552151.526 [DataOverHttps](INFO): SBD MOMSN=25034345 2025-05-29T20:55:52.851Z,1748552152.851 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T20:55:52.851Z,1748552152.851 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T20:55:52.851Z,1748552152.851 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T20:55:56.473Z,1748552156.473 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-05-29T20:55:56.547Z,1748552156.547 [NAL9602](ERROR): received: +CSQ:0 OK160, 2, 0, 0, 0 OK 2025-05-29T20:56:04.152Z,1748552164.152 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:56:20.303Z,1748552180.303 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:59:05.139Z,1748552345.139 [NAL9602](INFO): SBD MO Status=1, MOMSN=25160, MT Status=0, MTMSN=0 2025-05-29T20:59:05.139Z,1748552345.139 [NAL9602](INFO): No messages in MT queue 2025-05-29T20:59:06.752Z,1748552346.752 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:59:20.892Z,1748552360.892 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:59:34.627Z,1748552374.627 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T20:59:35.844Z,1748552375.844 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T20:59:48.363Z,1748552388.363 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:00:02.099Z,1748552402.099 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:00:16.240Z,1748552416.240 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:00:29.983Z,1748552429.983 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:00:43.723Z,1748552443.723 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:00:53.425Z,1748552453.425 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T21:00:53.425Z,1748552453.425 [Default:CheckIn:C.Wait] Stopped 2025-05-29T21:00:53.425Z,1748552453.425 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T21:00:53.425Z,1748552453.425 [Default:CheckIn:D] Running Loop=1 2025-05-29T21:00:53.835Z,1748552453.835 [Default:CheckIn:D] Stopped 2025-05-29T21:00:53.835Z,1748552453.835 [Default:CheckIn:E] Running Loop=1 2025-05-29T21:00:54.251Z,1748552454.251 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 190.561914 min 2025-05-29T21:00:54.252Z,1748552454.252 [Default:CheckIn:E] Stopped 2025-05-29T21:00:54.252Z,1748552454.252 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T21:00:54.252Z,1748552454.252 [Default:CheckIn] Stopped 2025-05-29T21:00:54.252Z,1748552454.252 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T21:00:54.252Z,1748552454.252 [Default:CheckIn](INFO): Running loop #34 2025-05-29T21:00:54.252Z,1748552454.252 [Default:CheckIn] Running Loop=34 2025-05-29T21:00:54.252Z,1748552454.252 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T21:00:54.252Z,1748552454.252 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T21:00:56.258Z,1748552456.258 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210055.00,A,3648.17545,N,12147.27849,W,0.078,0.00,290525,,,A*73 2025-05-29T21:00:56.260Z,1748552456.260 [NAL9602](INFO): GPS fix at 20250529T210055: (36.802924, -121.787975) 2025-05-29T21:00:56.287Z,1748552456.287 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T21:00:56.287Z,1748552456.287 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T21:00:58.257Z,1748552458.257 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:01:03.531Z,1748552463.531 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0093.lzma 2025-05-29T21:01:04.533Z,1748552464.533 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0093.lzma.bak 2025-05-29T21:01:04.533Z,1748552464.533 [DataOverHttps](INFO): SBD MOMSN=25034368 2025-05-29T21:01:12.396Z,1748552472.396 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:01:17.250Z,1748552477.250 [NAL9602](INFO): SBD MO Status=1, MOMSN=25161, MT Status=0, MTMSN=0 2025-05-29T21:01:17.250Z,1748552477.250 [NAL9602](INFO): No messages in MT queue 2025-05-29T21:01:20.579Z,1748552480.579 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250529T180702/Express0094.lzma 2025-05-29T21:01:21.581Z,1748552481.581 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0094.lzma.bak 2025-05-29T21:01:21.581Z,1748552481.581 [DataOverHttps](INFO): SBD MOMSN=25034418 2025-05-29T21:01:22.926Z,1748552482.926 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T21:01:22.926Z,1748552482.926 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T21:01:22.926Z,1748552482.926 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T21:01:47.951Z,1748552507.951 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T21:03:41.483Z,1748552621.483 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:03:56.419Z,1748552636.419 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:04:11.367Z,1748552651.367 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:04:25.912Z,1748552665.912 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:04:41.264Z,1748552681.264 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:04:56.212Z,1748552696.212 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:05:11.159Z,1748552711.159 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:05:26.511Z,1748552726.511 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:05:41.459Z,1748552741.459 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:05:56.407Z,1748552756.407 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:06:23.488Z,1748552783.488 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T21:06:23.488Z,1748552783.488 [Default:CheckIn:C.Wait] Stopped 2025-05-29T21:06:23.488Z,1748552783.488 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T21:06:23.488Z,1748552783.488 [Default:CheckIn:D] Running Loop=1 2025-05-29T21:06:23.896Z,1748552783.896 [Default:CheckIn:D] Stopped 2025-05-29T21:06:23.896Z,1748552783.896 [Default:CheckIn:E] Running Loop=1 2025-05-29T21:06:24.310Z,1748552784.310 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 196.062923 min 2025-05-29T21:06:24.310Z,1748552784.310 [Default:CheckIn:E] Stopped 2025-05-29T21:06:24.310Z,1748552784.310 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T21:06:24.310Z,1748552784.310 [Default:CheckIn] Stopped 2025-05-29T21:06:24.310Z,1748552784.310 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T21:06:24.310Z,1748552784.310 [Default:CheckIn](INFO): Running loop #35 2025-05-29T21:06:24.310Z,1748552784.310 [Default:CheckIn] Running Loop=35 2025-05-29T21:06:24.311Z,1748552784.311 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T21:06:24.311Z,1748552784.311 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T21:06:26.307Z,1748552786.307 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210625.00,A,3648.17456,N,12147.27824,W,0.078,0.00,290525,,,A*7A 2025-05-29T21:06:26.310Z,1748552786.310 [NAL9602](INFO): GPS fix at 20250529T210625: (36.802909, -121.787971) 2025-05-29T21:06:26.321Z,1748552786.321 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T21:06:26.321Z,1748552786.321 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T21:06:34.151Z,1748552794.151 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0096.lzma 2025-05-29T21:06:35.161Z,1748552795.161 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0096.lzma.bak 2025-05-29T21:06:35.161Z,1748552795.161 [DataOverHttps](INFO): SBD MOMSN=25034440 2025-05-29T21:06:54.291Z,1748552814.291 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250529T180702/Express0097.lzma 2025-05-29T21:06:55.293Z,1748552815.293 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0097.lzma.bak 2025-05-29T21:06:55.293Z,1748552815.293 [DataOverHttps](INFO): SBD MOMSN=25034443 2025-05-29T21:06:56.687Z,1748552816.687 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T21:06:56.687Z,1748552816.687 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T21:06:56.687Z,1748552816.687 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T21:08:31.597Z,1748552911.597 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:08:34.427Z,1748552914.427 [NAL9602](INFO): SBD MO Status=2, MOMSN=25162, MT Status=2, MTMSN=0 2025-05-29T21:08:34.427Z,1748552914.427 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T21:08:45.734Z,1748552925.734 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:08:59.471Z,1748552939.471 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:09:13.207Z,1748552953.207 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:09:26.943Z,1748552966.943 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:09:40.275Z,1748552980.275 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:09:45.533Z,1748552985.533 [NAL9602](INFO): SBD MO Status=1, MOMSN=25162, MT Status=0, MTMSN=0 2025-05-29T21:09:45.533Z,1748552985.533 [NAL9602](INFO): No messages in MT queue 2025-05-29T21:09:54.011Z,1748552994.011 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:10:07.747Z,1748553007.747 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:10:16.252Z,1748553016.252 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T21:10:21.483Z,1748553021.483 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:10:34.815Z,1748553034.815 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:11:57.278Z,1748553117.278 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T21:11:57.279Z,1748553117.279 [Default:CheckIn:C.Wait] Stopped 2025-05-29T21:11:57.279Z,1748553117.279 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T21:11:57.280Z,1748553117.280 [Default:CheckIn:D] Running Loop=1 2025-05-29T21:11:57.664Z,1748553117.664 [Default:CheckIn:D] Stopped 2025-05-29T21:11:57.664Z,1748553117.664 [Default:CheckIn:E] Running Loop=1 2025-05-29T21:11:58.071Z,1748553118.071 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 201.625732 min 2025-05-29T21:11:58.071Z,1748553118.071 [Default:CheckIn:E] Stopped 2025-05-29T21:11:58.071Z,1748553118.071 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T21:11:58.071Z,1748553118.071 [Default:CheckIn] Stopped 2025-05-29T21:11:58.072Z,1748553118.072 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T21:11:58.072Z,1748553118.072 [Default:CheckIn](INFO): Running loop #36 2025-05-29T21:11:58.072Z,1748553118.072 [Default:CheckIn] Running Loop=36 2025-05-29T21:11:58.072Z,1748553118.072 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T21:11:58.072Z,1748553118.072 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T21:12:00.078Z,1748553120.078 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211159.00,A,3648.17291,N,12147.27906,W,0.019,0.00,290525,,,A*7C 2025-05-29T21:12:00.080Z,1748553120.080 [NAL9602](INFO): GPS fix at 20250529T211159: (36.802882, -121.787984) 2025-05-29T21:12:00.107Z,1748553120.107 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T21:12:00.107Z,1748553120.107 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T21:12:07.467Z,1748553127.467 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0099.lzma 2025-05-29T21:12:08.469Z,1748553128.469 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0099.lzma.bak 2025-05-29T21:12:08.470Z,1748553128.470 [DataOverHttps](INFO): SBD MOMSN=25034520 2025-05-29T21:12:10.993Z,1748553130.993 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-05-29T21:12:10.993Z,1748553130.993 [DropWeight] Hardware Fault, FailCount= 1 2025-05-29T21:12:10.993Z,1748553130.993 [DropWeight](ERROR): Hardware Fault 2025-05-29T21:12:11.012Z,1748553131.012 [CBIT](INFO): Critical error at 20250529T211210 2025-05-29T21:12:11.014Z,1748553131.014 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-05-29T21:12:11.014Z,1748553131.014 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-05-29T21:12:11.446Z,1748553131.446 [CBIT](INFO): Critical error at 20250529T211211 2025-05-29T21:12:24.467Z,1748553144.467 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250529T180702/Express0100.lzma 2025-05-29T21:12:25.469Z,1748553145.469 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0100.lzma.bak 2025-05-29T21:12:25.469Z,1748553145.469 [DataOverHttps](INFO): SBD MOMSN=25034526 2025-05-29T21:12:26.757Z,1748553146.757 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T21:12:26.757Z,1748553146.757 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T21:12:26.757Z,1748553146.757 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T21:12:29.569Z,1748553149.569 [NAL9602](INFO): SBD MO Status=1, MOMSN=25163, MT Status=0, MTMSN=0 2025-05-29T21:12:29.570Z,1748553149.570 [NAL9602](INFO): No messages in MT queue 2025-05-29T21:13:00.348Z,1748553180.348 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T21:13:09.231Z,1748553189.231 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:13:24.583Z,1748553204.583 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:13:39.531Z,1748553219.531 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:13:54.884Z,1748553234.884 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:14:09.831Z,1748553249.831 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:14:24.802Z,1748553264.802 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:14:40.552Z,1748553280.552 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:14:55.893Z,1748553295.893 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:15:11.647Z,1748553311.647 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:15:26.192Z,1748553326.192 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-05-29T21:17:27.418Z,1748553447.418 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T21:17:27.418Z,1748553447.418 [Default:CheckIn:C.Wait] Stopped 2025-05-29T21:17:27.419Z,1748553447.419 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T21:17:27.420Z,1748553447.420 [Default:CheckIn:D] Running Loop=1 2025-05-29T21:17:27.810Z,1748553447.810 [Default:CheckIn:D] Stopped 2025-05-29T21:17:27.810Z,1748553447.810 [Default:CheckIn:E] Running Loop=1 2025-05-29T21:17:28.222Z,1748553448.222 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 207.128174 min 2025-05-29T21:17:28.222Z,1748553448.222 [Default:CheckIn:E] Stopped 2025-05-29T21:17:28.222Z,1748553448.222 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T21:17:28.222Z,1748553448.222 [Default:CheckIn] Stopped 2025-05-29T21:17:28.223Z,1748553448.223 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T21:17:28.223Z,1748553448.223 [Default:CheckIn](INFO): Running loop #37 2025-05-29T21:17:28.223Z,1748553448.223 [Default:CheckIn] Running Loop=37 2025-05-29T21:17:28.223Z,1748553448.223 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T21:17:28.223Z,1748553448.223 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T21:17:30.228Z,1748553450.228 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211729.00,A,3648.17250,N,12147.27864,W,0.097,0.00,290525,,,A*73 2025-05-29T21:17:30.230Z,1748553450.230 [NAL9602](INFO): GPS fix at 20250529T211729: (36.802875, -121.787977) 2025-05-29T21:17:30.241Z,1748553450.241 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T21:17:30.241Z,1748553450.241 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T21:17:37.539Z,1748553457.539 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250529T180702/Courier0102.lzma 2025-05-29T21:17:38.541Z,1748553458.541 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0102.lzma.bak 2025-05-29T21:17:38.541Z,1748553458.541 [DataOverHttps](INFO): SBD MOMSN=25034544 2025-05-29T21:17:51.635Z,1748553471.635 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-05-29T21:17:54.487Z,1748553474.487 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250529T180702/Express0103.lzma 2025-05-29T21:17:55.489Z,1748553475.489 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0103.lzma.bak 2025-05-29T21:17:55.489Z,1748553475.489 [DataOverHttps](INFO): SBD MOMSN=25034549 2025-05-29T21:17:56.918Z,1748553476.918 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T21:17:56.918Z,1748553476.918 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T21:17:56.918Z,1748553476.918 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T21:17:57.290Z,1748553477.290 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:17:58.538Z,1748553478.538 [NAL9602](INFO): SBD MO Status=2, MOMSN=25164, MT Status=2, MTMSN=0 2025-05-29T21:17:58.538Z,1748553478.538 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T21:18:09.411Z,1748553489.411 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:18:15.882Z,1748553495.882 [NAL9602](INFO): SBD MO Status=2, MOMSN=25164, MT Status=2, MTMSN=0 2025-05-29T21:18:15.882Z,1748553495.882 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-29T21:18:22.339Z,1748553502.339 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:18:34.460Z,1748553514.460 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:18:46.579Z,1748553526.579 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:18:49.814Z,1748553529.814 [NAL9602](INFO): SBD MO Status=1, MOMSN=25164, MT Status=0, MTMSN=0 2025-05-29T21:18:49.814Z,1748553529.814 [NAL9602](INFO): No messages in MT queue 2025-05-29T21:18:59.507Z,1748553539.507 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:19:11.629Z,1748553551.629 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:19:20.524Z,1748553560.524 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T21:19:23.747Z,1748553563.747 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:19:36.296Z,1748553576.296 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:19:49.228Z,1748553589.228 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:20:02.152Z,1748553602.152 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:20:30.838Z,1748553630.838 [BPC1](ERROR): Battery stick #13 (s/n: 015A) reported TERMINATE_CHARGE_ALARM. Status code: 0x42D0. 2025-05-29T21:20:30.839Z,1748553630.839 [BPC1](ERROR): Battery stick #16 (s/n: 00F8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T21:20:30.840Z,1748553630.840 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T21:20:30.840Z,1748553630.840 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-05-29T21:20:30.841Z,1748553630.841 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T21:20:30.842Z,1748553630.842 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T21:20:30.843Z,1748553630.843 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T21:20:30.844Z,1748553630.844 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-29T21:20:30.844Z,1748553630.844 [BPC1](INFO): Calculating totals. Valid battery stick count: 41. Valid reserve battery stick count: 4. 2025-05-29T21:20:30.848Z,1748553630.848 [BPC1](FAULT): Failed to receive data from 16 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 9, 10, 11, 12, 13, 14, 15, 16, 24, 29, 35, 51, 52, 53, 57, 59. 2025-05-29T21:22:57.522Z,1748553777.522 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T21:22:57.522Z,1748553777.522 [Default:CheckIn:C.Wait] Stopped 2025-05-29T21:22:57.522Z,1748553777.522 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T21:22:57.523Z,1748553777.523 [Default:CheckIn:D] Running Loop=1 2025-05-29T21:22:57.930Z,1748553777.930 [Default:CheckIn:D] Stopped 2025-05-29T21:22:57.930Z,1748553777.930 [Default:CheckIn:E] Running Loop=1 2025-05-29T21:22:58.333Z,1748553778.333 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 212.630160 min 2025-05-29T21:22:58.333Z,1748553778.333 [Default:CheckIn:E] Stopped 2025-05-29T21:22:58.333Z,1748553778.333 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T21:22:58.333Z,1748553778.333 [Default:CheckIn] Stopped 2025-05-29T21:22:58.333Z,1748553778.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T21:22:58.333Z,1748553778.333 [Default:CheckIn](INFO): Running loop #38 2025-05-29T21:22:58.333Z,1748553778.333 [Default:CheckIn] Running Loop=38 2025-05-29T21:22:58.333Z,1748553778.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T21:22:58.334Z,1748553778.334 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T21:23:00.346Z,1748553780.346 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212259.00,A,3648.17246,N,12147.28327,W,1.302,318.96,290525,,,A*7D 2025-05-29T21:23:00.358Z,1748553780.358 [NAL9602](INFO): GPS fix at 20250529T212259: (36.802874, -121.788055) 2025-05-29T21:23:00.369Z,1748553780.369 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T21:23:00.369Z,1748553780.369 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T21:23:08.079Z,1748553788.079 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250529T180702/Courier0105.lzma 2025-05-29T21:23:09.081Z,1748553789.081 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0105.lzma.bak 2025-05-29T21:23:09.081Z,1748553789.081 [DataOverHttps](INFO): SBD MOMSN=25034618 2025-05-29T21:23:25.539Z,1748553805.539 [DataOverHttps](INFO): Sending 290 bytes from file Logs/20250529T180702/Express0106.lzma 2025-05-29T21:23:26.541Z,1748553806.541 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0106.lzma.bak 2025-05-29T21:23:26.541Z,1748553806.541 [DataOverHttps](INFO): SBD MOMSN=25034621 2025-05-29T21:23:27.836Z,1748553807.836 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T21:23:27.836Z,1748553807.836 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T21:23:27.836Z,1748553807.836 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T21:27:11.290Z,1748554031.290 [CBIT](INFO): Clearing failed state for component DropWeight 2025-05-29T21:27:11.290Z,1748554031.290 [DropWeight] No Fault, FailCount= 1 2025-05-29T21:28:02.581Z,1748554082.581 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-05-29T21:28:28.455Z,1748554108.455 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T21:28:28.455Z,1748554108.455 [Default:CheckIn:C.Wait] Stopped 2025-05-29T21:28:28.455Z,1748554108.455 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T21:28:28.455Z,1748554108.455 [Default:CheckIn:D] Running Loop=1 2025-05-29T21:28:28.851Z,1748554108.851 [Default:CheckIn:D] Stopped 2025-05-29T21:28:28.851Z,1748554108.851 [Default:CheckIn:E] Running Loop=1 2025-05-29T21:28:29.251Z,1748554109.251 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 218.145508 min 2025-05-29T21:28:29.251Z,1748554109.251 [Default:CheckIn:E] Stopped 2025-05-29T21:28:29.251Z,1748554109.251 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T21:28:29.251Z,1748554109.251 [Default:CheckIn] Stopped 2025-05-29T21:28:29.251Z,1748554109.251 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T21:28:29.251Z,1748554109.251 [Default:CheckIn](INFO): Running loop #39 2025-05-29T21:28:29.252Z,1748554109.252 [Default:CheckIn] Running Loop=39 2025-05-29T21:28:29.252Z,1748554109.252 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T21:28:29.252Z,1748554109.252 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T21:28:31.264Z,1748554111.264 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212830.00,A,3648.17311,N,12147.30986,W,1.769,233.38,290525,,,A*76 2025-05-29T21:28:31.266Z,1748554111.266 [NAL9602](INFO): GPS fix at 20250529T212830: (36.802885, -121.788498) 2025-05-29T21:28:31.277Z,1748554111.277 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T21:28:31.277Z,1748554111.277 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T21:28:41.127Z,1748554121.127 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0108.lzma 2025-05-29T21:28:42.129Z,1748554122.129 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0108.lzma.bak 2025-05-29T21:28:42.129Z,1748554122.129 [DataOverHttps](INFO): SBD MOMSN=25034638 2025-05-29T21:28:59.940Z,1748554139.940 [DataOverHttps](INFO): Sending 231 bytes from file Logs/20250529T180702/Express0109.lzma 2025-05-29T21:29:00.943Z,1748554140.943 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0109.lzma.bak 2025-05-29T21:29:00.944Z,1748554140.944 [DataOverHttps](INFO): SBD MOMSN=25034641 2025-05-29T21:29:02.417Z,1748554142.417 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T21:29:02.417Z,1748554142.417 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T21:29:02.417Z,1748554142.417 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T21:29:03.620Z,1748554143.620 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-29T21:32:06.263Z,1748554326.263 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:32:21.616Z,1748554341.616 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:32:36.564Z,1748554356.564 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:32:51.193Z,1748554371.193 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:33:06.532Z,1748554386.532 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:33:21.480Z,1748554401.480 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:33:36.828Z,1748554416.828 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:33:51.777Z,1748554431.777 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:34:03.100Z,1748554443.100 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-29T21:34:03.100Z,1748554443.100 [Default:CheckIn:C.Wait] Stopped 2025-05-29T21:34:03.100Z,1748554443.100 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T21:34:03.100Z,1748554443.100 [Default:CheckIn:D] Running Loop=1 2025-05-29T21:34:03.510Z,1748554443.510 [Default:CheckIn:D] Stopped 2025-05-29T21:34:03.510Z,1748554443.510 [Default:CheckIn:E] Running Loop=1 2025-05-29T21:34:03.913Z,1748554443.913 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 223.723161 min 2025-05-29T21:34:03.913Z,1748554443.913 [Default:CheckIn:E] Stopped 2025-05-29T21:34:03.913Z,1748554443.913 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-29T21:34:03.913Z,1748554443.913 [Default:CheckIn] Stopped 2025-05-29T21:34:03.913Z,1748554443.913 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T21:34:03.913Z,1748554443.913 [Default:CheckIn](INFO): Running loop #40 2025-05-29T21:34:03.914Z,1748554443.914 [Default:CheckIn] Running Loop=40 2025-05-29T21:34:03.914Z,1748554443.914 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-29T21:34:03.914Z,1748554443.914 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-29T21:34:05.938Z,1748554445.938 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213405.00,A,3648.16682,N,12147.26765,W,2.896,325.65,290525,,,A*75 2025-05-29T21:34:05.940Z,1748554445.940 [NAL9602](INFO): GPS fix at 20250529T213405: (36.802780, -121.787794) 2025-05-29T21:34:05.951Z,1748554445.951 [Default:CheckIn:Read_GPS] Stopped 2025-05-29T21:34:05.951Z,1748554445.951 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-29T21:34:07.126Z,1748554447.126 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-29T21:34:13.923Z,1748554453.923 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250529T180702/Courier0111.lzma 2025-05-29T21:34:14.925Z,1748554454.925 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Courier0111.lzma.bak 2025-05-29T21:34:14.925Z,1748554454.925 [DataOverHttps](INFO): SBD MOMSN=25034715 2025-05-29T21:34:22.072Z,1748554462.072 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-05-29T21:34:22.076Z,1748554462.076 [BPC1](ERROR): BPC1B: No match for serial number FFDF in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-05-29T21:34:30.983Z,1748554470.983 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250529T180702/Express0112.lzma 2025-05-29T21:34:31.985Z,1748554471.985 [DataOverHttps](INFO): Moved sent file to Logs/20250529T180702/Express0112.lzma.bak 2025-05-29T21:34:31.985Z,1748554471.985 [DataOverHttps](INFO): SBD MOMSN=25034718 2025-05-29T21:34:33.414Z,1748554473.414 [Default:CheckIn:Read_Iridium] Stopped 2025-05-29T21:34:33.414Z,1748554473.414 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-29T21:34:33.415Z,1748554473.415 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-29T21:34:34.524Z,1748554474.524 [CommandExec](IMPORTANT): got command quit 2025-05-29T21:34:35.527Z,1748554475.527 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-29T21:34:35.527Z,1748554475.527 [CommandExec](INFO): Uninitializing the command executive. 2025-05-29T21:34:35.527Z,1748554475.527 [CommandExec](INFO): Uninitializing the command scheduler. 2025-05-29T21:34:35.528Z,1748554475.528 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:35.715Z,1748554475.715 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-05-29T21:34:35.715Z,1748554475.715 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-05-29T21:34:35.715Z,1748554475.715 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:35.716Z,1748554475.716 [NavChartDb](INFO): Join timeout helper Thread ID is 8315 2025-05-29T21:34:36.099Z,1748554476.099 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-29T21:34:36.100Z,1748554476.100 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:36.119Z,1748554476.119 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-05-29T21:34:36.119Z,1748554476.119 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:36.119Z,1748554476.119 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8316 2025-05-29T21:34:36.499Z,1748554476.499 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-29T21:34:36.499Z,1748554476.499 [WetLabsBB2FL](INFO): Powering down 2025-05-29T21:34:36.500Z,1748554476.500 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:36.515Z,1748554476.515 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-05-29T21:34:36.515Z,1748554476.515 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:36.515Z,1748554476.515 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8317 2025-05-29T21:34:36.621Z,1748554476.621 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-05-29T21:34:36.695Z,1748554476.695 [NAL9602](ERROR): received: +CSQ:0 OK164, 0, 0, 0, 0 OK 2025-05-29T21:34:36.755Z,1748554476.755 [CTD_Seabird](INFO): Powering down 2025-05-29T21:34:36.767Z,1748554476.767 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-29T21:34:36.767Z,1748554476.767 [CTD_Seabird](INFO): Powering down 2025-05-29T21:34:36.779Z,1748554476.779 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:36.783Z,1748554476.783 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-05-29T21:34:36.783Z,1748554476.783 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:36.784Z,1748554476.784 [Radio_Surface](INFO): Join timeout helper Thread ID is 8318 2025-05-29T21:34:36.939Z,1748554476.939 [Radio_Surface](INFO): Powering down 2025-05-29T21:34:36.940Z,1748554476.940 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-29T21:34:36.940Z,1748554476.940 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:36.943Z,1748554476.943 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-05-29T21:34:36.943Z,1748554476.943 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:36.944Z,1748554476.944 [Onboard](INFO): Join timeout helper Thread ID is 8319 2025-05-29T21:34:40.043Z,1748554480.043 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-29T21:34:40.043Z,1748554480.043 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:40.060Z,1748554480.060 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-05-29T21:34:40.060Z,1748554480.060 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:40.060Z,1748554480.060 [DataOverHttps](INFO): Join timeout helper Thread ID is 8320 2025-05-29T21:34:40.815Z,1748554480.815 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-29T21:34:40.818Z,1748554480.818 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:40.836Z,1748554480.836 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-05-29T21:34:40.836Z,1748554480.836 [DAT ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:40.837Z,1748554480.837 [DAT](INFO): Join timeout helper Thread ID is 8321 2025-05-29T21:34:40.855Z,1748554480.855 [DAT](INFO): Powering down 2025-05-29T21:34:40.928Z,1748554480.928 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-29T21:34:40.928Z,1748554480.928 [DAT](INFO): Powering down 2025-05-29T21:34:40.929Z,1748554480.929 [DAT ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:40.932Z,1748554480.932 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-05-29T21:34:40.932Z,1748554480.932 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:40.932Z,1748554480.932 [BackseatComponent](INFO): Join timeout helper Thread ID is 8322 2025-05-29T21:34:41.119Z,1748554481.119 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-29T21:34:41.120Z,1748554481.120 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.128Z,1748554481.128 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-05-29T21:34:41.128Z,1748554481.128 [logger ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.128Z,1748554481.128 [logger](INFO): Join timeout helper Thread ID is 8323 2025-05-29T21:34:41.134Z,1748554481.134 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-29T21:34:41.134Z,1748554481.134 [logger ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.148Z,1748554481.148 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-05-29T21:34:41.148Z,1748554481.148 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.148Z,1748554481.148 [CommandLine](INFO): Join timeout helper Thread ID is 8324 2025-05-29T21:34:41.171Z,1748554481.171 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-29T21:34:41.171Z,1748554481.171 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.188Z,1748554481.188 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-05-29T21:34:41.188Z,1748554481.188 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.188Z,1748554481.188 [CommandExec](INFO): Join timeout helper Thread ID is 8325 2025-05-29T21:34:41.189Z,1748554481.189 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-05-29T21:34:41.189Z,1748554481.189 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.190Z,1748554481.190 [controlThread](INFO): Join timeout helper Thread ID is 8326 2025-05-29T21:34:41.447Z,1748554481.447 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-29T21:34:41.448Z,1748554481.448 [controlThread](DEBUG): Uninitializing ControlThread 2025-05-29T21:34:41.448Z,1748554481.448 [AHRS_M2](INFO): Powering down 2025-05-29T21:34:41.527Z,1748554481.527 [NAL9602](INFO): Powering down 2025-05-29T21:34:41.529Z,1748554481.529 [Sonardyne_Nano](INFO): Powering down 2025-05-29T21:34:41.731Z,1748554481.731 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-05-29T21:34:41.732Z,1748554481.732 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-05-29T21:34:41.733Z,1748554481.733 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-05-29T21:34:41.733Z,1748554481.733 [MissionManager](INFO): Uninitializing Mission Default 2025-05-29T21:34:41.733Z,1748554481.733 [Default] Stopped 2025-05-29T21:34:41.733Z,1748554481.733 [Default](DEBUG): Aggregate::uninitialize Default 2025-05-29T21:34:41.733Z,1748554481.733 [Default:B.GoToSurface] Stopped 2025-05-29T21:34:41.733Z,1748554481.733 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-05-29T21:34:41.734Z,1748554481.734 [Default:CheckIn] Stopped 2025-05-29T21:34:41.734Z,1748554481.734 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-29T21:34:41.734Z,1748554481.734 [Default:CheckIn:C.Wait] Stopped 2025-05-29T21:34:41.734Z,1748554481.734 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-29T21:34:41.737Z,1748554481.737 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-05-29T21:34:41.737Z,1748554481.737 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-05-29T21:34:41.738Z,1748554481.738 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-05-29T21:34:41.738Z,1748554481.738 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-05-29T21:34:41.738Z,1748554481.738 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-05-29T21:34:41.738Z,1748554481.738 [BuoyancyServo](INFO): Powering down 2025-05-29T21:34:41.751Z,1748554481.751 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-05-29T21:34:41.751Z,1748554481.751 [ElevatorServo](INFO): Powering down 2025-05-29T21:34:41.752Z,1748554481.752 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-05-29T21:34:41.752Z,1748554481.752 [MassServo](INFO): Powering down 2025-05-29T21:34:41.753Z,1748554481.753 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-05-29T21:34:41.753Z,1748554481.753 [RudderServo](INFO): Powering down 2025-05-29T21:34:41.754Z,1748554481.754 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-29T21:34:41.754Z,1748554481.754 [ThrusterHE](INFO): Powering down 2025-05-29T21:34:41.755Z,1748554481.755 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-05-29T21:34:41.755Z,1748554481.755 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-05-29T21:34:41.756Z,1748554481.756 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-05-29T21:34:41.756Z,1748554481.756 [CBIT](DEBUG): Powering off loads. 2025-05-29T21:34:41.767Z,1748554481.767 [CBIT](DEBUG): Disabling WDT. 2025-05-29T21:34:41.779Z,1748554481.779 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-05-29T21:34:41.779Z,1748554481.779 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-05-29T21:34:41.780Z,1748554481.780 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.817Z,1748554481.817 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.820Z,1748554481.820 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.826Z,1748554481.826 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.833Z,1748554481.833 [DAT ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.875Z,1748554481.875 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.909Z,1748554481.909 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-05-29T21:34:41.909Z,1748554481.909 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-05-29T21:34:41.965Z,1748554481.965 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.971Z,1748554481.971 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:41.979Z,1748554481.979 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-05-29T21:34:42.055Z,1748554482.055 [logger ThreadHandler](INFO): Thread cancelled.