2025-04-22T15:07:19.142Z,1745334439.142 [CommandExec](IMPORTANT): got command restart logs 2025-04-22T15:07:19.481Z,1745334439.481 [MissionManager](IMPORTANT): Started mission Default 2025-04-22T15:07:19.481Z,1745334439.481 [Default] Running Loop=1 2025-04-22T15:07:19.481Z,1745334439.481 [Default](DEBUG): Aggregate::initialize Default 2025-04-22T15:07:19.481Z,1745334439.481 [Default:B.GoToSurface] Running Loop=1 2025-04-22T15:07:19.481Z,1745334439.481 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-22T15:07:19.482Z,1745334439.482 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-22T15:07:19.482Z,1745334439.482 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-22T15:07:19.482Z,1745334439.482 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-22T15:07:19.483Z,1745334439.483 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-22T15:07:19.483Z,1745334439.483 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-22T15:07:19.483Z,1745334439.483 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-22T15:07:19.483Z,1745334439.483 [Default:A.Wait] Running Loop=1 2025-04-22T15:07:19.483Z,1745334439.483 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-04-22T15:07:32.818Z,1745334452.818 [Default:A.Wait](INFO): Done Waiting. 2025-04-22T15:07:32.818Z,1745334452.818 [Default:A.Wait] Stopped 2025-04-22T15:07:32.818Z,1745334452.818 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T15:07:33.200Z,1745334453.200 [Default:CheckIn] Running Loop=1 2025-04-22T15:07:33.200Z,1745334453.200 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T15:07:33.200Z,1745334453.200 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T15:07:34.805Z,1745334454.805 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T15:07:34.805Z,1745334454.805 [NAL9602] Data Fault, FailCount= 4 2025-04-22T15:07:34.805Z,1745334454.805 [NAL9602](ERROR): Data Fault 2025-04-22T15:07:34.965Z,1745334454.965 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T15:07:35.209Z,1745334455.209 [NAL9602](INFO): Powering down 2025-04-22T15:07:36.253Z,1745334456.253 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T15:07:36.253Z,1745334456.253 [NAL9602] No Fault, FailCount= 4 2025-04-22T15:08:05.520Z,1745334485.520 [NAL9602](INFO): Powering up NAL9602 2025-04-22T15:08:16.421Z,1745334496.421 [NAL9602](INFO): NAL9602 initialized 2025-04-22T15:12:33.446Z,1745334753.446 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T15:07:33.2Z 2025-04-22T15:12:33.446Z,1745334753.446 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T15:12:33.447Z,1745334753.447 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T15:12:42.421Z,1745334762.421 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250420T194722/Courier0864.lzma 2025-04-22T15:12:43.423Z,1745334763.423 [DataOverHttps](INFO): Moved sent file to Logs/20250420T194722/Courier0864.lzma.bak 2025-04-22T15:12:43.423Z,1745334763.423 [DataOverHttps](INFO): SBD MOMSN=24709722 2025-04-22T15:12:59.477Z,1745334779.477 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250422T150719/Courier0000.lzma 2025-04-22T15:13:00.479Z,1745334780.479 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0000.lzma.bak 2025-04-22T15:13:00.479Z,1745334780.479 [DataOverHttps](INFO): SBD MOMSN=24709724 2025-04-22T15:13:18.709Z,1745334798.709 [DataOverHttps](INFO): Sending 258 bytes from file Logs/20250420T194722/Express0865.lzma 2025-04-22T15:13:19.711Z,1745334799.711 [DataOverHttps](INFO): Moved sent file to Logs/20250420T194722/Express0865.lzma.bak 2025-04-22T15:13:19.712Z,1745334799.712 [DataOverHttps](INFO): SBD MOMSN=24709727 2025-04-22T15:13:19.874Z,1745334799.874 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T15:13:37.937Z,1745334817.937 [DataOverHttps](INFO): Sending 410 bytes from file Logs/20250422T150719/Express0001.lzma 2025-04-22T15:13:38.939Z,1745334818.939 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0001.lzma.bak 2025-04-22T15:13:38.939Z,1745334818.939 [DataOverHttps](INFO): SBD MOMSN=24709732 2025-04-22T15:13:41.729Z,1745334821.729 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T15:13:41.729Z,1745334821.729 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T15:13:41.729Z,1745334821.729 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T15:13:50.573Z,1745334830.573 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T15:18:42.274Z,1745335122.274 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T15:18:42.274Z,1745335122.274 [Default:CheckIn:C.Wait] Stopped 2025-04-22T15:18:42.274Z,1745335122.274 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T15:18:42.274Z,1745335122.274 [Default:CheckIn:D] Running Loop=1 2025-04-22T15:18:42.676Z,1745335122.676 [Default:CheckIn:D] Stopped 2025-04-22T15:18:42.676Z,1745335122.676 [Default:CheckIn:E] Running Loop=1 2025-04-22T15:18:43.081Z,1745335123.081 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.386582 min 2025-04-22T15:18:43.081Z,1745335123.081 [Default:CheckIn:E] Stopped 2025-04-22T15:18:43.081Z,1745335123.081 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T15:18:43.081Z,1745335123.081 [Default:CheckIn] Stopped 2025-04-22T15:18:43.081Z,1745335123.081 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T15:18:43.081Z,1745335123.081 [Default:CheckIn](INFO): Running loop #2 2025-04-22T15:18:43.081Z,1745335123.081 [Default:CheckIn] Running Loop=2 2025-04-22T15:18:43.082Z,1745335123.082 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T15:18:43.082Z,1745335123.082 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T15:18:44.691Z,1745335124.691 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T15:18:44.691Z,1745335124.691 [NAL9602] Data Fault, FailCount= 5 2025-04-22T15:18:44.691Z,1745335124.691 [NAL9602](ERROR): Data Fault 2025-04-22T15:18:44.858Z,1745335124.858 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T15:18:44.858Z,1745335124.858 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-04-22T15:18:45.098Z,1745335125.098 [NAL9602](INFO): Powering down 2025-04-22T15:18:45.243Z,1745335125.243 [CBIT](INFO): Critical error at 20250422T151844 2025-04-22T15:20:13.176Z,1745335213.176 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-04-22T15:20:44.846Z,1745335244.846 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T15:20:44.846Z,1745335244.846 [NAL9602] No Fault, FailCount= 5 2025-04-22T15:20:45.102Z,1745335245.102 [NAL9602](INFO): Powering up NAL9602 2025-04-22T15:20:56.003Z,1745335256.003 [NAL9602](INFO): NAL9602 initialized 2025-04-22T15:23:43.290Z,1745335423.290 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T15:18:43.1Z 2025-04-22T15:23:43.290Z,1745335423.290 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T15:23:43.290Z,1745335423.290 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T15:23:53.480Z,1745335433.480 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20250422T150719/Courier0003.lzma 2025-04-22T15:23:54.483Z,1745335434.483 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0003.lzma.bak 2025-04-22T15:23:54.483Z,1745335434.483 [DataOverHttps](INFO): SBD MOMSN=24709783 2025-04-22T15:24:12.493Z,1745335452.493 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20250422T150719/Express0004.lzma 2025-04-22T15:24:13.495Z,1745335453.495 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0004.lzma.bak 2025-04-22T15:24:13.495Z,1745335453.495 [DataOverHttps](INFO): SBD MOMSN=24709786 2025-04-22T15:24:16.429Z,1745335456.429 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T15:24:16.429Z,1745335456.429 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T15:24:16.429Z,1745335456.429 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T15:25:59.406Z,1745335559.406 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T15:26:30.109Z,1745335590.109 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T15:29:16.984Z,1745335756.984 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T15:29:16.984Z,1745335756.984 [Default:CheckIn:C.Wait] Stopped 2025-04-22T15:29:16.984Z,1745335756.984 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T15:29:16.985Z,1745335756.985 [Default:CheckIn:D] Running Loop=1 2025-04-22T15:29:17.380Z,1745335757.380 [Default:CheckIn:D] Stopped 2025-04-22T15:29:17.380Z,1745335757.380 [Default:CheckIn:E] Running Loop=1 2025-04-22T15:29:17.784Z,1745335757.784 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.964976 min 2025-04-22T15:29:17.784Z,1745335757.784 [Default:CheckIn:E] Stopped 2025-04-22T15:29:17.785Z,1745335757.785 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T15:29:17.785Z,1745335757.785 [Default:CheckIn] Stopped 2025-04-22T15:29:17.785Z,1745335757.785 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T15:29:17.785Z,1745335757.785 [Default:CheckIn](INFO): Running loop #3 2025-04-22T15:29:17.785Z,1745335757.785 [Default:CheckIn] Running Loop=3 2025-04-22T15:29:17.785Z,1745335757.785 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T15:29:17.785Z,1745335757.785 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T15:29:50.494Z,1745335790.494 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T15:29:50.577Z,1745335790.577 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T15:33:02.852Z,1745335982.852 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-22T15:33:02.852Z,1745335982.852 [DropWeight] Hardware Fault, FailCount= 1 2025-04-22T15:33:02.852Z,1745335982.852 [DropWeight](ERROR): Hardware Fault 2025-04-22T15:33:03.051Z,1745335983.051 [CBIT](INFO): Critical error at 20250422T153302 2025-04-22T15:33:03.054Z,1745335983.054 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-22T15:33:03.054Z,1745335983.054 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-22T15:33:03.435Z,1745335983.435 [CBIT](INFO): Critical error at 20250422T153303 2025-04-22T15:33:16.758Z,1745335996.758 [CBIT](IMPORTANT): Beginning ground fault scan 2025-04-22T15:33:27.662Z,1745336007.662 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.012228 CHAN A1 (24V): -0.190862 CHAN A2 (12V): 0.000207 CHAN A3 (5V): -0.002113 CHAN B0 (3.3V): -0.001404 CHAN B1 (3.15aV): -0.001718 CHAN B2 (3.15bV): -0.001359 CHAN B3 (GND): -0.002552 OPEN: 0.005224 Full Scale: +/- 1 mA 2025-04-22T15:34:18.013Z,1745336058.013 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T15:29:17.8Z 2025-04-22T15:34:18.013Z,1745336058.013 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T15:34:18.014Z,1745336058.014 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T15:34:21.638Z,1745336061.638 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T15:34:26.553Z,1745336066.553 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20250422T150719/Courier0006.lzma 2025-04-22T15:34:27.555Z,1745336067.555 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0006.lzma.bak 2025-04-22T15:34:27.555Z,1745336067.555 [DataOverHttps](INFO): SBD MOMSN=24709835 2025-04-22T15:34:45.503Z,1745336085.503 [DataOverHttps](INFO): Sending 405 bytes from file Logs/20250422T150719/Express0007.lzma 2025-04-22T15:34:46.503Z,1745336086.503 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0007.lzma.bak 2025-04-22T15:34:46.503Z,1745336086.503 [DataOverHttps](INFO): SBD MOMSN=24709839 2025-04-22T15:34:49.532Z,1745336089.532 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T15:34:49.537Z,1745336089.537 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T15:34:49.537Z,1745336089.537 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T15:34:52.338Z,1745336092.338 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T15:39:50.098Z,1745336390.098 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T15:39:50.099Z,1745336390.099 [Default:CheckIn:C.Wait] Stopped 2025-04-22T15:39:50.099Z,1745336390.099 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T15:39:50.100Z,1745336390.100 [Default:CheckIn:D] Running Loop=1 2025-04-22T15:39:50.506Z,1745336390.506 [Default:CheckIn:D] Stopped 2025-04-22T15:39:50.507Z,1745336390.507 [Default:CheckIn:E] Running Loop=1 2025-04-22T15:39:50.918Z,1745336390.918 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.517088 min 2025-04-22T15:39:50.918Z,1745336390.918 [Default:CheckIn:E] Stopped 2025-04-22T15:39:50.918Z,1745336390.918 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T15:39:50.918Z,1745336390.918 [Default:CheckIn] Stopped 2025-04-22T15:39:50.918Z,1745336390.918 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T15:39:50.918Z,1745336390.918 [Default:CheckIn](INFO): Running loop #4 2025-04-22T15:39:50.919Z,1745336390.919 [Default:CheckIn] Running Loop=4 2025-04-22T15:39:50.919Z,1745336390.919 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T15:39:50.919Z,1745336390.919 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T15:39:52.518Z,1745336392.518 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T15:39:52.518Z,1745336392.518 [NAL9602] Data Fault, FailCount= 1 2025-04-22T15:39:52.518Z,1745336392.518 [NAL9602](ERROR): Data Fault 2025-04-22T15:39:52.665Z,1745336392.665 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T15:39:52.914Z,1745336392.914 [NAL9602](INFO): Powering down 2025-04-22T15:39:53.885Z,1745336393.885 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T15:39:53.885Z,1745336393.885 [NAL9602] No Fault, FailCount= 1 2025-04-22T15:40:23.214Z,1745336423.214 [NAL9602](INFO): Powering up NAL9602 2025-04-22T15:40:34.126Z,1745336434.126 [NAL9602](INFO): NAL9602 initialized 2025-04-22T15:44:51.140Z,1745336691.140 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T15:39:50.9Z 2025-04-22T15:44:51.140Z,1745336691.140 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T15:44:51.141Z,1745336691.141 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T15:44:59.409Z,1745336699.409 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0009.lzma 2025-04-22T15:45:00.411Z,1745336700.411 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0009.lzma.bak 2025-04-22T15:45:00.411Z,1745336700.411 [DataOverHttps](INFO): SBD MOMSN=24709982 2025-04-22T15:45:18.909Z,1745336718.909 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250422T150719/Express0010.lzma 2025-04-22T15:45:19.911Z,1745336719.911 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0010.lzma.bak 2025-04-22T15:45:19.911Z,1745336719.911 [DataOverHttps](INFO): SBD MOMSN=24709984 2025-04-22T15:45:23.930Z,1745336723.930 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T15:45:23.930Z,1745336723.930 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T15:45:23.930Z,1745336723.930 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T15:45:37.226Z,1745336737.226 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T15:46:07.926Z,1745336767.926 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T15:48:03.233Z,1745336883.233 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-22T15:48:03.233Z,1745336883.233 [DropWeight] No Fault, FailCount= 1 2025-04-22T15:50:24.477Z,1745337024.477 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T15:50:24.477Z,1745337024.477 [Default:CheckIn:C.Wait] Stopped 2025-04-22T15:50:24.477Z,1745337024.477 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T15:50:24.478Z,1745337024.478 [Default:CheckIn:D] Running Loop=1 2025-04-22T15:50:24.885Z,1745337024.885 [Default:CheckIn:D] Stopped 2025-04-22T15:50:24.885Z,1745337024.885 [Default:CheckIn:E] Running Loop=1 2025-04-22T15:50:25.284Z,1745337025.284 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.090068 min 2025-04-22T15:50:25.284Z,1745337025.284 [Default:CheckIn:E] Stopped 2025-04-22T15:50:25.284Z,1745337025.284 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T15:50:25.284Z,1745337025.284 [Default:CheckIn] Stopped 2025-04-22T15:50:25.284Z,1745337025.284 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T15:50:25.284Z,1745337025.284 [Default:CheckIn](INFO): Running loop #5 2025-04-22T15:50:25.284Z,1745337025.284 [Default:CheckIn] Running Loop=5 2025-04-22T15:50:25.284Z,1745337025.284 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T15:50:25.285Z,1745337025.285 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T15:52:06.718Z,1745337126.718 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T15:52:19.628Z,1745337139.628 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T15:52:32.960Z,1745337152.960 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T15:52:45.484Z,1745337165.484 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T15:52:58.816Z,1745337178.816 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T15:53:11.340Z,1745337191.340 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T15:53:24.671Z,1745337204.671 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T15:55:25.546Z,1745337325.546 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T15:50:25.3Z 2025-04-22T15:55:25.546Z,1745337325.546 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T15:55:25.546Z,1745337325.546 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T15:55:29.107Z,1745337329.107 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T15:55:34.492Z,1745337334.492 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0012.lzma 2025-04-22T15:55:35.495Z,1745337335.495 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0012.lzma.bak 2025-04-22T15:55:35.495Z,1745337335.495 [DataOverHttps](INFO): SBD MOMSN=24710047 2025-04-22T15:55:54.421Z,1745337354.421 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20250422T150719/Express0013.lzma 2025-04-22T15:55:55.423Z,1745337355.423 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0013.lzma.bak 2025-04-22T15:55:55.423Z,1745337355.423 [DataOverHttps](INFO): SBD MOMSN=24710049 2025-04-22T15:55:58.649Z,1745337358.649 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T15:55:58.649Z,1745337358.649 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T15:55:58.650Z,1745337358.650 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T15:55:59.842Z,1745337359.842 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T15:58:28.103Z,1745337508.103 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-04-22T15:58:31.751Z,1745337511.751 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-22T15:58:31.751Z,1745337511.751 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-04-22T15:58:31.759Z,1745337511.759 [BPC1](INFO): Received data from all battery sticks. 2025-04-22T16:00:59.310Z,1745337659.310 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T16:00:59.311Z,1745337659.311 [Default:CheckIn:C.Wait] Stopped 2025-04-22T16:00:59.311Z,1745337659.311 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T16:00:59.311Z,1745337659.311 [Default:CheckIn:D] Running Loop=1 2025-04-22T16:00:59.700Z,1745337659.700 [Default:CheckIn:D] Stopped 2025-04-22T16:00:59.700Z,1745337659.700 [Default:CheckIn:E] Running Loop=1 2025-04-22T16:01:00.111Z,1745337660.111 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.670304 min 2025-04-22T16:01:00.112Z,1745337660.112 [Default:CheckIn:E] Stopped 2025-04-22T16:01:00.112Z,1745337660.112 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T16:01:00.112Z,1745337660.112 [Default:CheckIn] Stopped 2025-04-22T16:01:00.112Z,1745337660.112 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T16:01:00.112Z,1745337660.112 [Default:CheckIn](INFO): Running loop #6 2025-04-22T16:01:00.112Z,1745337660.112 [Default:CheckIn] Running Loop=6 2025-04-22T16:01:00.112Z,1745337660.112 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T16:01:00.112Z,1745337660.112 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T16:01:01.690Z,1745337661.690 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T16:01:01.690Z,1745337661.690 [NAL9602] Data Fault, FailCount= 2 2025-04-22T16:01:01.690Z,1745337661.690 [NAL9602](ERROR): Data Fault 2025-04-22T16:01:01.853Z,1745337661.853 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T16:01:02.098Z,1745337662.098 [NAL9602](INFO): Powering down 2025-04-22T16:01:03.057Z,1745337663.057 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T16:01:03.057Z,1745337663.057 [NAL9602] No Fault, FailCount= 2 2025-04-22T16:01:32.402Z,1745337692.402 [NAL9602](INFO): Powering up NAL9602 2025-04-22T16:01:43.302Z,1745337703.302 [NAL9602](INFO): NAL9602 initialized 2025-04-22T16:06:00.270Z,1745337960.270 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T16:01:00.1Z 2025-04-22T16:06:00.270Z,1745337960.270 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T16:06:00.271Z,1745337960.271 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T16:06:08.549Z,1745337968.549 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250422T150719/Courier0015.lzma 2025-04-22T16:06:09.551Z,1745337969.551 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0015.lzma.bak 2025-04-22T16:06:09.551Z,1745337969.551 [DataOverHttps](INFO): SBD MOMSN=24710108 2025-04-22T16:06:27.625Z,1745337987.625 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250422T150719/Express0016.lzma 2025-04-22T16:06:28.627Z,1745337988.627 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0016.lzma.bak 2025-04-22T16:06:28.627Z,1745337988.627 [DataOverHttps](INFO): SBD MOMSN=24710110 2025-04-22T16:06:31.526Z,1745337991.526 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T16:06:31.526Z,1745337991.526 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T16:06:31.526Z,1745337991.526 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T16:06:46.846Z,1745338006.846 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T16:07:17.554Z,1745338037.554 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T16:11:32.153Z,1745338292.153 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T16:11:32.153Z,1745338292.153 [Default:CheckIn:C.Wait] Stopped 2025-04-22T16:11:32.153Z,1745338292.153 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T16:11:32.153Z,1745338292.153 [Default:CheckIn:D] Running Loop=1 2025-04-22T16:11:32.560Z,1745338292.560 [Default:CheckIn:D] Stopped 2025-04-22T16:11:32.560Z,1745338292.560 [Default:CheckIn:E] Running Loop=1 2025-04-22T16:11:32.960Z,1745338292.960 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.217989 min 2025-04-22T16:11:32.960Z,1745338292.960 [Default:CheckIn:E] Stopped 2025-04-22T16:11:32.960Z,1745338292.960 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T16:11:32.960Z,1745338292.960 [Default:CheckIn] Stopped 2025-04-22T16:11:32.960Z,1745338292.960 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T16:11:32.960Z,1745338292.960 [Default:CheckIn](INFO): Running loop #7 2025-04-22T16:11:32.961Z,1745338292.961 [Default:CheckIn] Running Loop=7 2025-04-22T16:11:33.006Z,1745338293.006 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T16:11:33.006Z,1745338293.006 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T16:12:05.679Z,1745338325.679 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T16:12:05.761Z,1745338325.761 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T16:16:33.136Z,1745338593.136 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T16:11:33.0Z 2025-04-22T16:16:33.136Z,1745338593.136 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T16:16:33.136Z,1745338593.136 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T16:16:37.170Z,1745338597.170 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T16:16:40.792Z,1745338600.792 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0018.lzma 2025-04-22T16:16:41.795Z,1745338601.795 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0018.lzma.bak 2025-04-22T16:16:41.795Z,1745338601.795 [DataOverHttps](INFO): SBD MOMSN=24710160 2025-04-22T16:17:00.109Z,1745338620.109 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20250422T150719/Express0019.lzma 2025-04-22T16:17:01.111Z,1745338621.111 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0019.lzma.bak 2025-04-22T16:17:01.111Z,1745338621.111 [DataOverHttps](INFO): SBD MOMSN=24710162 2025-04-22T16:17:04.258Z,1745338624.258 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T16:17:04.258Z,1745338624.258 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T16:17:04.258Z,1745338624.258 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T16:17:07.870Z,1745338627.870 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T16:17:11.229Z,1745338631.229 [MassServo](ERROR): getPosition uart error serial timeout 2025-04-22T16:17:11.229Z,1745338631.229 [MassServo](FAULT): Invalid EZ Servo response:"\377" 2025-04-22T16:17:11.229Z,1745338631.229 [MassServo] Communications Fault, FailCount= 4 2025-04-22T16:17:11.229Z,1745338631.229 [MassServo](ERROR): Communications Fault 2025-04-22T16:17:11.234Z,1745338631.234 [CBIT](ERROR): Communications Fault in component: MassServo 2025-04-22T16:17:11.527Z,1745338631.527 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-04-22T16:17:11.527Z,1745338631.527 [MassServo](INFO): Powering down 2025-04-22T16:17:12.340Z,1745338632.340 [MassServo](DEBUG): Initializing EZServoServo. 2025-04-22T16:17:12.458Z,1745338632.458 [MassServo](DEBUG): Initializing MassServo. 2025-04-22T16:17:12.462Z,1745338632.462 [CBIT](INFO): Clearing failed state for component MassServo 2025-04-22T16:17:12.462Z,1745338632.462 [MassServo] No Fault, FailCount= 4 2025-04-22T16:18:04.430Z,1745338684.430 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-22T16:18:04.430Z,1745338684.430 [DropWeight] Hardware Fault, FailCount= 1 2025-04-22T16:18:04.430Z,1745338684.430 [DropWeight](ERROR): Hardware Fault 2025-04-22T16:18:04.629Z,1745338684.629 [CBIT](INFO): Critical error at 20250422T161804 2025-04-22T16:18:04.632Z,1745338684.632 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-22T16:18:04.632Z,1745338684.632 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-22T16:18:04.983Z,1745338684.983 [CBIT](INFO): Critical error at 20250422T161804 2025-04-22T16:22:04.821Z,1745338924.821 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T16:22:04.821Z,1745338924.821 [Default:CheckIn:C.Wait] Stopped 2025-04-22T16:22:04.821Z,1745338924.821 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T16:22:04.822Z,1745338924.822 [Default:CheckIn:D] Running Loop=1 2025-04-22T16:22:05.229Z,1745338925.229 [Default:CheckIn:D] Stopped 2025-04-22T16:22:05.229Z,1745338925.229 [Default:CheckIn:E] Running Loop=1 2025-04-22T16:22:05.628Z,1745338925.628 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.762459 min 2025-04-22T16:22:05.628Z,1745338925.628 [Default:CheckIn:E] Stopped 2025-04-22T16:22:05.628Z,1745338925.628 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T16:22:05.628Z,1745338925.628 [Default:CheckIn] Stopped 2025-04-22T16:22:05.628Z,1745338925.628 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T16:22:05.628Z,1745338925.628 [Default:CheckIn](INFO): Running loop #8 2025-04-22T16:22:05.628Z,1745338925.628 [Default:CheckIn] Running Loop=8 2025-04-22T16:22:05.628Z,1745338925.628 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T16:22:05.645Z,1745338925.645 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T16:22:07.233Z,1745338927.233 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T16:22:07.233Z,1745338927.233 [NAL9602] Data Fault, FailCount= 3 2025-04-22T16:22:07.233Z,1745338927.233 [NAL9602](ERROR): Data Fault 2025-04-22T16:22:07.429Z,1745338927.429 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T16:22:07.636Z,1745338927.636 [NAL9602](INFO): Powering down 2025-04-22T16:22:08.613Z,1745338928.613 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T16:22:08.613Z,1745338928.613 [NAL9602] No Fault, FailCount= 3 2025-04-22T16:22:37.962Z,1745338957.962 [NAL9602](INFO): Powering up NAL9602 2025-04-22T16:22:48.850Z,1745338968.850 [NAL9602](INFO): NAL9602 initialized 2025-04-22T16:27:05.811Z,1745339225.811 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T16:22:05.6Z 2025-04-22T16:27:05.811Z,1745339225.811 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T16:27:05.811Z,1745339225.811 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T16:27:14.497Z,1745339234.497 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20250422T150719/Courier0021.lzma 2025-04-22T16:27:15.499Z,1745339235.499 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0021.lzma.bak 2025-04-22T16:27:15.499Z,1745339235.499 [DataOverHttps](INFO): SBD MOMSN=24710212 2025-04-22T16:27:33.661Z,1745339253.661 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20250422T150719/Express0022.lzma 2025-04-22T16:27:34.663Z,1745339254.663 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0022.lzma.bak 2025-04-22T16:27:34.663Z,1745339254.663 [DataOverHttps](INFO): SBD MOMSN=24710236 2025-04-22T16:27:37.738Z,1745339257.738 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T16:27:37.739Z,1745339257.739 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T16:27:37.739Z,1745339257.739 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T16:27:52.250Z,1745339272.250 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T16:28:22.954Z,1745339302.954 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T16:32:38.291Z,1745339558.291 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T16:32:38.291Z,1745339558.291 [Default:CheckIn:C.Wait] Stopped 2025-04-22T16:32:38.291Z,1745339558.291 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T16:32:38.291Z,1745339558.291 [Default:CheckIn:D] Running Loop=1 2025-04-22T16:32:38.697Z,1745339558.697 [Default:CheckIn:D] Stopped 2025-04-22T16:32:38.698Z,1745339558.698 [Default:CheckIn:E] Running Loop=1 2025-04-22T16:32:39.105Z,1745339559.105 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.320272 min 2025-04-22T16:32:39.105Z,1745339559.105 [Default:CheckIn:E] Stopped 2025-04-22T16:32:39.105Z,1745339559.105 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T16:32:39.105Z,1745339559.105 [Default:CheckIn] Stopped 2025-04-22T16:32:39.106Z,1745339559.106 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T16:32:39.106Z,1745339559.106 [Default:CheckIn](INFO): Running loop #9 2025-04-22T16:32:39.106Z,1745339559.106 [Default:CheckIn] Running Loop=9 2025-04-22T16:32:39.106Z,1745339559.106 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T16:32:39.106Z,1745339559.106 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T16:33:04.709Z,1745339584.709 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-22T16:33:04.709Z,1745339584.709 [DropWeight] No Fault, FailCount= 1 2025-04-22T16:33:11.819Z,1745339591.819 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T16:33:11.901Z,1745339591.901 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T16:36:54.824Z,1745339814.824 [NAL9602](INFO): SBD MO Status=2, MOMSN=13659, MT Status=2, MTMSN=0 2025-04-22T16:36:54.824Z,1745339814.824 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-22T16:37:39.272Z,1745339859.272 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T16:32:39.1Z 2025-04-22T16:37:39.272Z,1745339859.272 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T16:37:39.273Z,1745339859.273 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T16:37:43.303Z,1745339863.303 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T16:37:48.208Z,1745339868.208 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0024.lzma 2025-04-22T16:37:49.211Z,1745339869.211 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0024.lzma.bak 2025-04-22T16:37:49.211Z,1745339869.211 [DataOverHttps](INFO): SBD MOMSN=24710288 2025-04-22T16:38:08.841Z,1745339888.841 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20250422T150719/Express0025.lzma 2025-04-22T16:38:09.843Z,1745339889.843 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0025.lzma.bak 2025-04-22T16:38:09.843Z,1745339889.843 [DataOverHttps](INFO): SBD MOMSN=24710295 2025-04-22T16:38:13.642Z,1745339893.642 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T16:38:13.642Z,1745339893.642 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T16:38:13.642Z,1745339893.642 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T16:38:14.006Z,1745339894.006 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T16:43:14.195Z,1745340194.195 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T16:43:14.195Z,1745340194.195 [Default:CheckIn:C.Wait] Stopped 2025-04-22T16:43:14.195Z,1745340194.195 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T16:43:14.195Z,1745340194.195 [Default:CheckIn:D] Running Loop=1 2025-04-22T16:43:14.607Z,1745340194.607 [Default:CheckIn:D] Stopped 2025-04-22T16:43:14.607Z,1745340194.607 [Default:CheckIn:E] Running Loop=1 2025-04-22T16:43:14.996Z,1745340194.996 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.918758 min 2025-04-22T16:43:14.996Z,1745340194.996 [Default:CheckIn:E] Stopped 2025-04-22T16:43:14.996Z,1745340194.996 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T16:43:14.996Z,1745340194.996 [Default:CheckIn] Stopped 2025-04-22T16:43:14.996Z,1745340194.996 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T16:43:14.996Z,1745340194.996 [Default:CheckIn](INFO): Running loop #10 2025-04-22T16:43:14.996Z,1745340194.996 [Default:CheckIn] Running Loop=10 2025-04-22T16:43:14.996Z,1745340194.996 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T16:43:14.997Z,1745340194.997 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T16:43:16.602Z,1745340196.602 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T16:43:16.602Z,1745340196.602 [NAL9602] Data Fault, FailCount= 4 2025-04-22T16:43:16.602Z,1745340196.602 [NAL9602](ERROR): Data Fault 2025-04-22T16:43:16.769Z,1745340196.769 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T16:43:17.010Z,1745340197.010 [NAL9602](INFO): Powering down 2025-04-22T16:43:17.982Z,1745340197.982 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T16:43:17.982Z,1745340197.982 [NAL9602] No Fault, FailCount= 4 2025-04-22T16:43:47.307Z,1745340227.307 [NAL9602](INFO): Powering up NAL9602 2025-04-22T16:43:58.219Z,1745340238.219 [NAL9602](INFO): NAL9602 initialized 2025-04-22T16:48:15.170Z,1745340495.170 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T16:43:14.0Z 2025-04-22T16:48:15.170Z,1745340495.170 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T16:48:15.170Z,1745340495.170 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T16:48:23.776Z,1745340503.776 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0027.lzma 2025-04-22T16:48:24.779Z,1745340504.779 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0027.lzma.bak 2025-04-22T16:48:24.779Z,1745340504.779 [DataOverHttps](INFO): SBD MOMSN=24710402 2025-04-22T16:48:43.601Z,1745340523.601 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250422T150719/Express0028.lzma 2025-04-22T16:48:44.603Z,1745340524.603 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0028.lzma.bak 2025-04-22T16:48:44.603Z,1745340524.603 [DataOverHttps](INFO): SBD MOMSN=24710408 2025-04-22T16:48:47.905Z,1745340527.905 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T16:48:47.905Z,1745340527.905 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T16:48:47.905Z,1745340527.905 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T16:49:01.623Z,1745340541.623 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T16:49:32.350Z,1745340572.350 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T16:53:48.493Z,1745340828.493 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T16:53:48.493Z,1745340828.493 [Default:CheckIn:C.Wait] Stopped 2025-04-22T16:53:48.493Z,1745340828.493 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T16:53:48.494Z,1745340828.494 [Default:CheckIn:D] Running Loop=1 2025-04-22T16:53:48.902Z,1745340828.902 [Default:CheckIn:D] Stopped 2025-04-22T16:53:48.902Z,1745340828.902 [Default:CheckIn:E] Running Loop=1 2025-04-22T16:53:49.309Z,1745340829.309 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.490348 min 2025-04-22T16:53:49.309Z,1745340829.309 [Default:CheckIn:E] Stopped 2025-04-22T16:53:49.310Z,1745340829.310 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T16:53:49.310Z,1745340829.310 [Default:CheckIn] Stopped 2025-04-22T16:53:49.310Z,1745340829.310 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T16:53:49.310Z,1745340829.310 [Default:CheckIn](INFO): Running loop #11 2025-04-22T16:53:49.310Z,1745340829.310 [Default:CheckIn] Running Loop=11 2025-04-22T16:53:49.310Z,1745340829.310 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T16:53:49.310Z,1745340829.310 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T16:54:22.018Z,1745340862.018 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T16:54:22.093Z,1745340862.093 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T16:58:49.472Z,1745341129.472 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T16:53:49.3Z 2025-04-22T16:58:49.472Z,1745341129.472 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T16:58:49.473Z,1745341129.473 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T16:58:53.094Z,1745341133.094 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T16:58:57.020Z,1745341137.020 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0030.lzma 2025-04-22T16:58:58.023Z,1745341138.023 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0030.lzma.bak 2025-04-22T16:58:58.023Z,1745341138.023 [DataOverHttps](INFO): SBD MOMSN=24710454 2025-04-22T16:59:16.189Z,1745341156.189 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20250422T150719/Express0031.lzma 2025-04-22T16:59:22.212Z,1745341162.212 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0031.lzma.bak 2025-04-22T16:59:22.212Z,1745341162.212 [DataOverHttps](INFO): SBD MOMSN=24710460 2025-04-22T16:59:23.798Z,1745341163.798 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T16:59:24.626Z,1745341164.626 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T16:59:24.627Z,1745341164.627 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T16:59:24.627Z,1745341164.627 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T16:59:39.148Z,1745341179.148 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T16:59:52.076Z,1745341192.076 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:00:05.409Z,1745341205.409 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:00:18.740Z,1745341218.740 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:00:31.669Z,1745341231.669 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:00:44.650Z,1745341244.650 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:00:57.632Z,1745341257.632 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T17:03:06.106Z,1745341386.106 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-22T17:03:06.106Z,1745341386.106 [DropWeight] Hardware Fault, FailCount= 1 2025-04-22T17:03:06.106Z,1745341386.106 [DropWeight](ERROR): Hardware Fault 2025-04-22T17:03:06.267Z,1745341386.267 [CBIT](INFO): Critical error at 20250422T170306 2025-04-22T17:03:06.270Z,1745341386.270 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-22T17:03:06.270Z,1745341386.270 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-22T17:03:06.679Z,1745341386.679 [CBIT](INFO): Critical error at 20250422T170306 2025-04-22T17:04:25.300Z,1745341465.300 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T17:04:25.317Z,1745341465.317 [Default:CheckIn:C.Wait] Stopped 2025-04-22T17:04:25.317Z,1745341465.317 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T17:04:25.317Z,1745341465.317 [Default:CheckIn:D] Running Loop=1 2025-04-22T17:04:25.704Z,1745341465.704 [Default:CheckIn:D] Stopped 2025-04-22T17:04:25.704Z,1745341465.704 [Default:CheckIn:E] Running Loop=1 2025-04-22T17:04:26.112Z,1745341466.112 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.103711 min 2025-04-22T17:04:26.112Z,1745341466.112 [Default:CheckIn:E] Stopped 2025-04-22T17:04:26.113Z,1745341466.113 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T17:04:26.113Z,1745341466.113 [Default:CheckIn] Stopped 2025-04-22T17:04:26.113Z,1745341466.113 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T17:04:26.113Z,1745341466.113 [Default:CheckIn](INFO): Running loop #12 2025-04-22T17:04:26.113Z,1745341466.113 [Default:CheckIn] Running Loop=12 2025-04-22T17:04:26.113Z,1745341466.113 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T17:04:26.113Z,1745341466.113 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T17:04:27.714Z,1745341467.714 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T17:04:27.714Z,1745341467.714 [NAL9602] Data Fault, FailCount= 5 2025-04-22T17:04:27.714Z,1745341467.714 [NAL9602](ERROR): Data Fault 2025-04-22T17:04:27.877Z,1745341467.877 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T17:04:27.878Z,1745341467.878 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-04-22T17:04:28.118Z,1745341468.118 [NAL9602](INFO): Powering down 2025-04-22T17:04:28.275Z,1745341468.275 [CBIT](INFO): Critical error at 20250422T170427 2025-04-22T17:06:00.631Z,1745341560.631 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-04-22T17:06:05.107Z,1745341565.107 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-22T17:06:05.107Z,1745341565.107 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-04-22T17:06:05.110Z,1745341565.110 [BPC1](INFO): Received data from all battery sticks. 2025-04-22T17:06:27.882Z,1745341587.882 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T17:06:27.882Z,1745341587.882 [NAL9602] No Fault, FailCount= 5 2025-04-22T17:06:28.122Z,1745341588.122 [NAL9602](INFO): Powering up NAL9602 2025-04-22T17:06:39.013Z,1745341599.013 [NAL9602](INFO): NAL9602 initialized 2025-04-22T17:09:26.286Z,1745341766.286 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T17:04:26.1Z 2025-04-22T17:09:26.286Z,1745341766.286 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T17:09:26.286Z,1745341766.286 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T17:09:35.277Z,1745341775.277 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250422T150719/Courier0033.lzma 2025-04-22T17:09:36.283Z,1745341776.283 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0033.lzma.bak 2025-04-22T17:09:36.283Z,1745341776.283 [DataOverHttps](INFO): SBD MOMSN=24710504 2025-04-22T17:09:54.757Z,1745341794.757 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250422T150719/Express0034.lzma 2025-04-22T17:09:55.759Z,1745341795.759 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0034.lzma.bak 2025-04-22T17:09:55.759Z,1745341795.759 [DataOverHttps](INFO): SBD MOMSN=24710512 2025-04-22T17:09:59.037Z,1745341799.037 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T17:09:59.038Z,1745341799.038 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T17:09:59.038Z,1745341799.038 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T17:11:42.482Z,1745341902.482 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T17:12:13.190Z,1745341933.190 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T17:14:59.640Z,1745342099.640 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T17:14:59.640Z,1745342099.640 [Default:CheckIn:C.Wait] Stopped 2025-04-22T17:14:59.640Z,1745342099.640 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T17:14:59.641Z,1745342099.641 [Default:CheckIn:D] Running Loop=1 2025-04-22T17:15:00.051Z,1745342100.051 [Default:CheckIn:D] Stopped 2025-04-22T17:15:00.051Z,1745342100.051 [Default:CheckIn:E] Running Loop=1 2025-04-22T17:15:00.448Z,1745342100.448 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.676164 min 2025-04-22T17:15:00.448Z,1745342100.448 [Default:CheckIn:E] Stopped 2025-04-22T17:15:00.449Z,1745342100.449 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T17:15:00.449Z,1745342100.449 [Default:CheckIn] Stopped 2025-04-22T17:15:00.449Z,1745342100.449 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T17:15:00.449Z,1745342100.449 [Default:CheckIn](INFO): Running loop #13 2025-04-22T17:15:00.449Z,1745342100.449 [Default:CheckIn] Running Loop=13 2025-04-22T17:15:00.449Z,1745342100.449 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T17:15:00.449Z,1745342100.449 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T17:15:33.166Z,1745342133.166 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T17:15:33.245Z,1745342133.245 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T17:18:06.445Z,1745342286.445 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-22T17:18:06.445Z,1745342286.445 [DropWeight] No Fault, FailCount= 1 2025-04-22T17:20:00.631Z,1745342400.631 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T17:15:00.4Z 2025-04-22T17:20:00.631Z,1745342400.631 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T17:20:00.631Z,1745342400.631 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T17:20:04.454Z,1745342404.454 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T17:20:08.941Z,1745342408.941 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0036.lzma 2025-04-22T17:20:09.943Z,1745342409.943 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0036.lzma.bak 2025-04-22T17:20:09.943Z,1745342409.943 [DataOverHttps](INFO): SBD MOMSN=24710580 2025-04-22T17:20:28.437Z,1745342428.437 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20250422T150719/Express0037.lzma 2025-04-22T17:20:29.439Z,1745342429.439 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0037.lzma.bak 2025-04-22T17:20:29.439Z,1745342429.439 [DataOverHttps](INFO): SBD MOMSN=24710587 2025-04-22T17:20:32.350Z,1745342432.350 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T17:20:32.350Z,1745342432.350 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T17:20:32.350Z,1745342432.350 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T17:20:35.153Z,1745342435.153 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T17:25:32.948Z,1745342732.948 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T17:25:32.948Z,1745342732.948 [Default:CheckIn:C.Wait] Stopped 2025-04-22T17:25:32.948Z,1745342732.948 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T17:25:32.948Z,1745342732.948 [Default:CheckIn:D] Running Loop=1 2025-04-22T17:25:33.352Z,1745342733.352 [Default:CheckIn:D] Stopped 2025-04-22T17:25:33.352Z,1745342733.352 [Default:CheckIn:E] Running Loop=1 2025-04-22T17:25:33.765Z,1745342733.765 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.231185 min 2025-04-22T17:25:33.766Z,1745342733.766 [Default:CheckIn:E] Stopped 2025-04-22T17:25:33.766Z,1745342733.766 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T17:25:33.766Z,1745342733.766 [Default:CheckIn] Stopped 2025-04-22T17:25:33.766Z,1745342733.766 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T17:25:33.766Z,1745342733.766 [Default:CheckIn](INFO): Running loop #14 2025-04-22T17:25:33.766Z,1745342733.766 [Default:CheckIn] Running Loop=14 2025-04-22T17:25:33.766Z,1745342733.766 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T17:25:33.766Z,1745342733.766 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T17:25:35.369Z,1745342735.369 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T17:25:35.369Z,1745342735.369 [NAL9602] Data Fault, FailCount= 1 2025-04-22T17:25:35.369Z,1745342735.369 [NAL9602](ERROR): Data Fault 2025-04-22T17:25:35.541Z,1745342735.541 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T17:25:35.770Z,1745342735.770 [NAL9602](INFO): Powering down 2025-04-22T17:25:36.741Z,1745342736.741 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T17:25:36.741Z,1745342736.741 [NAL9602] No Fault, FailCount= 1 2025-04-22T17:26:06.065Z,1745342766.065 [NAL9602](INFO): Powering up NAL9602 2025-04-22T17:26:16.982Z,1745342776.982 [NAL9602](INFO): NAL9602 initialized 2025-04-22T17:30:33.947Z,1745343033.947 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T17:25:33.8Z 2025-04-22T17:30:33.947Z,1745343033.947 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T17:30:33.947Z,1745343033.947 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T17:30:45.957Z,1745343045.957 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0039.lzma 2025-04-22T17:30:46.959Z,1745343046.959 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0039.lzma.bak 2025-04-22T17:30:46.959Z,1745343046.959 [DataOverHttps](INFO): SBD MOMSN=24710679 2025-04-22T17:31:20.529Z,1745343080.529 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T17:31:26.239Z,1745343086.239 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250422T150719/Express0040.lzma 2025-04-22T17:31:27.247Z,1745343087.247 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0040.lzma.bak 2025-04-22T17:31:27.247Z,1745343087.247 [DataOverHttps](INFO): SBD MOMSN=24710704 2025-04-22T17:31:30.263Z,1745343090.263 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T17:31:30.263Z,1745343090.263 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T17:31:30.263Z,1745343090.263 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T17:31:51.235Z,1745343111.235 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T17:36:30.814Z,1745343390.814 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T17:36:30.814Z,1745343390.814 [Default:CheckIn:C.Wait] Stopped 2025-04-22T17:36:30.814Z,1745343390.814 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T17:36:30.815Z,1745343390.815 [Default:CheckIn:D] Running Loop=1 2025-04-22T17:36:31.228Z,1745343391.228 [Default:CheckIn:D] Stopped 2025-04-22T17:36:31.228Z,1745343391.228 [Default:CheckIn:E] Running Loop=1 2025-04-22T17:36:31.620Z,1745343391.620 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.195768 min 2025-04-22T17:36:31.620Z,1745343391.620 [Default:CheckIn:E] Stopped 2025-04-22T17:36:31.620Z,1745343391.620 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T17:36:31.620Z,1745343391.620 [Default:CheckIn] Stopped 2025-04-22T17:36:31.620Z,1745343391.620 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T17:36:31.620Z,1745343391.620 [Default:CheckIn](INFO): Running loop #15 2025-04-22T17:36:31.620Z,1745343391.620 [Default:CheckIn] Running Loop=15 2025-04-22T17:36:31.620Z,1745343391.620 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T17:36:31.621Z,1745343391.621 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T17:36:33.230Z,1745343393.230 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T17:36:33.230Z,1745343393.230 [NAL9602] Data Fault, FailCount= 2 2025-04-22T17:36:33.230Z,1745343393.230 [NAL9602](ERROR): Data Fault 2025-04-22T17:36:33.402Z,1745343393.402 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T17:36:33.634Z,1745343393.634 [NAL9602](INFO): Powering down 2025-04-22T17:36:34.605Z,1745343394.605 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T17:36:34.605Z,1745343394.605 [NAL9602] No Fault, FailCount= 2 2025-04-22T17:37:03.929Z,1745343423.929 [NAL9602](INFO): Powering up NAL9602 2025-04-22T17:37:14.838Z,1745343434.838 [NAL9602](INFO): NAL9602 initialized 2025-04-22T17:41:31.825Z,1745343691.825 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T17:36:31.6Z 2025-04-22T17:41:31.825Z,1745343691.825 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T17:41:45.270Z,1745343705.270 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T17:41:53.856Z,1745343713.856 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0042.lzma 2025-04-22T17:41:54.859Z,1745343714.859 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0042.lzma.bak 2025-04-22T17:41:54.859Z,1745343714.859 [DataOverHttps](INFO): SBD MOMSN=24710793 2025-04-22T17:42:13.873Z,1745343733.873 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250422T150719/Express0043.lzma 2025-04-22T17:42:14.875Z,1745343734.875 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0043.lzma.bak 2025-04-22T17:42:14.875Z,1745343734.875 [DataOverHttps](INFO): SBD MOMSN=24710795 2025-04-22T17:42:18.182Z,1745343738.182 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T17:42:19.829Z,1745343739.829 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T17:42:19.829Z,1745343739.829 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T17:42:19.830Z,1745343739.830 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T17:42:48.891Z,1745343768.891 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T17:47:20.388Z,1745344040.388 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T17:47:20.389Z,1745344040.389 [Default:CheckIn:C.Wait] Stopped 2025-04-22T17:47:20.389Z,1745344040.389 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T17:47:20.389Z,1745344040.389 [Default:CheckIn:D] Running Loop=1 2025-04-22T17:47:20.792Z,1745344040.792 [Default:CheckIn:D] Stopped 2025-04-22T17:47:20.792Z,1745344040.792 [Default:CheckIn:E] Running Loop=1 2025-04-22T17:47:21.194Z,1745344041.194 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 160.021859 min 2025-04-22T17:47:21.194Z,1745344041.194 [Default:CheckIn:E] Stopped 2025-04-22T17:47:21.194Z,1745344041.194 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T17:47:21.194Z,1745344041.194 [Default:CheckIn] Stopped 2025-04-22T17:47:21.195Z,1745344041.195 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T17:47:21.195Z,1745344041.195 [Default:CheckIn](INFO): Running loop #16 2025-04-22T17:47:21.195Z,1745344041.195 [Default:CheckIn] Running Loop=16 2025-04-22T17:47:21.195Z,1745344041.195 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T17:47:21.195Z,1745344041.195 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T17:47:22.799Z,1745344042.799 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T17:47:22.799Z,1745344042.799 [NAL9602] Data Fault, FailCount= 3 2025-04-22T17:47:22.799Z,1745344042.799 [NAL9602](ERROR): Data Fault 2025-04-22T17:47:22.949Z,1745344042.949 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T17:47:23.201Z,1745344043.201 [NAL9602](INFO): Powering down 2025-04-22T17:47:24.166Z,1745344044.166 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T17:47:24.166Z,1745344044.166 [NAL9602] No Fault, FailCount= 3 2025-04-22T17:47:53.506Z,1745344073.506 [NAL9602](INFO): Powering up NAL9602 2025-04-22T17:48:04.411Z,1745344084.411 [NAL9602](INFO): NAL9602 initialized 2025-04-22T17:48:07.646Z,1745344087.646 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-22T17:48:07.646Z,1745344087.646 [DropWeight] Hardware Fault, FailCount= 1 2025-04-22T17:48:07.646Z,1745344087.646 [DropWeight](ERROR): Hardware Fault 2025-04-22T17:48:07.875Z,1745344087.875 [CBIT](INFO): Critical error at 20250422T174807 2025-04-22T17:48:07.878Z,1745344087.878 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-22T17:48:07.878Z,1745344087.878 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-22T17:48:08.231Z,1745344088.231 [CBIT](INFO): Critical error at 20250422T174807 2025-04-22T17:52:21.391Z,1745344341.391 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T17:47:21.2Z 2025-04-22T17:52:21.391Z,1745344341.391 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T17:52:21.392Z,1745344341.392 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T17:52:30.343Z,1745344350.343 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20250422T150719/Courier0045.lzma 2025-04-22T17:52:31.343Z,1745344351.343 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0045.lzma.bak 2025-04-22T17:52:31.343Z,1745344351.343 [DataOverHttps](INFO): SBD MOMSN=24710868 2025-04-22T17:52:49.754Z,1745344369.754 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250422T150719/Express0046.lzma 2025-04-22T17:52:50.755Z,1745344370.755 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0046.lzma.bak 2025-04-22T17:52:50.755Z,1745344370.755 [DataOverHttps](INFO): SBD MOMSN=24710872 2025-04-22T17:52:53.706Z,1745344373.706 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T17:52:53.706Z,1745344373.706 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T17:52:53.706Z,1745344373.706 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T17:53:07.818Z,1745344387.818 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T17:53:38.526Z,1745344418.526 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T17:57:54.261Z,1745344674.261 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T17:57:54.261Z,1745344674.261 [Default:CheckIn:C.Wait] Stopped 2025-04-22T17:57:54.261Z,1745344674.261 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T17:57:54.261Z,1745344674.261 [Default:CheckIn:D] Running Loop=1 2025-04-22T17:57:54.669Z,1745344674.669 [Default:CheckIn:D] Stopped 2025-04-22T17:57:54.669Z,1745344674.669 [Default:CheckIn:E] Running Loop=1 2025-04-22T17:57:55.084Z,1745344675.084 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 170.586458 min 2025-04-22T17:57:55.084Z,1745344675.084 [Default:CheckIn:E] Stopped 2025-04-22T17:57:55.084Z,1745344675.084 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T17:57:55.084Z,1745344675.084 [Default:CheckIn] Stopped 2025-04-22T17:57:55.084Z,1745344675.084 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T17:57:55.084Z,1745344675.084 [Default:CheckIn](INFO): Running loop #17 2025-04-22T17:57:55.084Z,1745344675.084 [Default:CheckIn] Running Loop=17 2025-04-22T17:57:55.085Z,1745344675.085 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T17:57:55.085Z,1745344675.085 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T17:58:27.793Z,1745344707.793 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T17:58:27.877Z,1745344707.877 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T18:02:55.245Z,1745344975.245 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T17:57:55.1Z 2025-04-22T18:02:55.261Z,1745344975.261 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T18:02:55.261Z,1745344975.261 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T18:02:59.270Z,1745344979.270 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T18:03:04.161Z,1745344984.161 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0048.lzma 2025-04-22T18:03:05.163Z,1745344985.163 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0048.lzma.bak 2025-04-22T18:03:05.163Z,1745344985.163 [DataOverHttps](INFO): SBD MOMSN=24710922 2025-04-22T18:03:07.933Z,1745344987.933 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-22T18:03:07.934Z,1745344987.934 [DropWeight] No Fault, FailCount= 1 2025-04-22T18:03:23.141Z,1745345003.141 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20250422T150719/Express0049.lzma 2025-04-22T18:03:24.143Z,1745345004.143 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0049.lzma.bak 2025-04-22T18:03:24.143Z,1745345004.143 [DataOverHttps](INFO): SBD MOMSN=24710927 2025-04-22T18:03:27.174Z,1745345007.174 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T18:03:27.174Z,1745345007.174 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T18:03:27.174Z,1745345007.174 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T18:03:29.987Z,1745345009.987 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T18:07:12.576Z,1745345232.576 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T18:07:25.918Z,1745345245.918 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T18:07:38.836Z,1745345258.836 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T18:07:51.360Z,1745345271.360 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T18:08:03.884Z,1745345283.884 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T18:08:16.812Z,1745345296.812 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T18:08:27.736Z,1745345307.736 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T18:08:27.737Z,1745345307.737 [Default:CheckIn:C.Wait] Stopped 2025-04-22T18:08:27.737Z,1745345307.737 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T18:08:27.737Z,1745345307.737 [Default:CheckIn:D] Running Loop=1 2025-04-22T18:08:28.151Z,1745345308.151 [Default:CheckIn:D] Stopped 2025-04-22T18:08:28.151Z,1745345308.151 [Default:CheckIn:E] Running Loop=1 2025-04-22T18:08:28.545Z,1745345308.545 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 181.144499 min 2025-04-22T18:08:28.545Z,1745345308.545 [Default:CheckIn:E] Stopped 2025-04-22T18:08:28.545Z,1745345308.545 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T18:08:28.545Z,1745345308.545 [Default:CheckIn] Stopped 2025-04-22T18:08:28.545Z,1745345308.545 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T18:08:28.545Z,1745345308.545 [Default:CheckIn](INFO): Running loop #18 2025-04-22T18:08:28.545Z,1745345308.545 [Default:CheckIn] Running Loop=18 2025-04-22T18:08:28.546Z,1745345308.546 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T18:08:28.546Z,1745345308.546 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T18:08:29.740Z,1745345309.740 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T18:08:30.166Z,1745345310.166 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T18:08:30.166Z,1745345310.166 [NAL9602] Data Fault, FailCount= 4 2025-04-22T18:08:30.166Z,1745345310.166 [NAL9602](ERROR): Data Fault 2025-04-22T18:08:30.362Z,1745345310.362 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T18:08:30.551Z,1745345310.551 [NAL9602](INFO): Powering down 2025-04-22T18:08:31.517Z,1745345311.517 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T18:08:31.517Z,1745345311.517 [NAL9602] No Fault, FailCount= 4 2025-04-22T18:09:00.913Z,1745345340.913 [NAL9602](INFO): Powering up NAL9602 2025-04-22T18:09:11.761Z,1745345351.761 [NAL9602](INFO): NAL9602 initialized 2025-04-22T18:13:28.717Z,1745345608.717 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T18:08:28.5Z 2025-04-22T18:13:28.717Z,1745345608.717 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T18:13:28.718Z,1745345608.718 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T18:13:32.879Z,1745345612.879 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-04-22T18:13:36.994Z,1745345616.994 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0051.lzma 2025-04-22T18:13:37.332Z,1745345617.332 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-22T18:13:37.332Z,1745345617.332 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-04-22T18:13:37.366Z,1745345617.366 [BPC1](INFO): Received data from all battery sticks. 2025-04-22T18:13:37.995Z,1745345617.995 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0051.lzma.bak 2025-04-22T18:13:37.995Z,1745345617.995 [DataOverHttps](INFO): SBD MOMSN=24711098 2025-04-22T18:14:00.526Z,1745345640.526 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20250422T150719/Express0052.lzma 2025-04-22T18:14:01.527Z,1745345641.527 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0052.lzma.bak 2025-04-22T18:14:01.527Z,1745345641.527 [DataOverHttps](INFO): SBD MOMSN=24711100 2025-04-22T18:14:04.438Z,1745345644.438 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T18:14:04.438Z,1745345644.438 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T18:14:04.438Z,1745345644.438 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T18:14:15.307Z,1745345655.307 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T18:14:46.018Z,1745345686.018 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T18:19:04.992Z,1745345944.992 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T18:19:04.992Z,1745345944.992 [Default:CheckIn:C.Wait] Stopped 2025-04-22T18:19:04.992Z,1745345944.992 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T18:19:04.992Z,1745345944.992 [Default:CheckIn:D] Running Loop=1 2025-04-22T18:19:05.387Z,1745345945.387 [Default:CheckIn:D] Stopped 2025-04-22T18:19:05.387Z,1745345945.387 [Default:CheckIn:E] Running Loop=1 2025-04-22T18:19:05.792Z,1745345945.792 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 191.765104 min 2025-04-22T18:19:05.792Z,1745345945.792 [Default:CheckIn:E] Stopped 2025-04-22T18:19:05.793Z,1745345945.793 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T18:19:05.793Z,1745345945.793 [Default:CheckIn] Stopped 2025-04-22T18:19:05.793Z,1745345945.793 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T18:19:05.793Z,1745345945.793 [Default:CheckIn](INFO): Running loop #19 2025-04-22T18:19:05.793Z,1745345945.793 [Default:CheckIn] Running Loop=19 2025-04-22T18:19:05.793Z,1745345945.793 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T18:19:05.793Z,1745345945.793 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T18:19:38.515Z,1745345978.515 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T18:19:38.589Z,1745345978.589 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T18:21:35.672Z,1745346095.672 [NAL9602](INFO): SBD MO Status=2, MOMSN=13659, MT Status=2, MTMSN=0 2025-04-22T18:21:35.672Z,1745346095.672 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-22T18:24:06.005Z,1745346246.005 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T18:19:05.8Z 2025-04-22T18:24:06.005Z,1745346246.005 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T18:24:06.006Z,1745346246.006 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T18:24:10.426Z,1745346250.426 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T18:24:18.208Z,1745346258.208 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20250422T150719/Courier0054.lzma 2025-04-22T18:24:20.052Z,1745346260.052 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0054.lzma.bak 2025-04-22T18:24:20.052Z,1745346260.052 [DataOverHttps](INFO): SBD MOMSN=24711164 2025-04-22T18:24:37.389Z,1745346277.389 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20250422T150719/Express0055.lzma 2025-04-22T18:24:38.391Z,1745346278.391 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0055.lzma.bak 2025-04-22T18:24:38.391Z,1745346278.391 [DataOverHttps](INFO): SBD MOMSN=24711166 2025-04-22T18:24:41.146Z,1745346281.146 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T18:24:41.570Z,1745346281.570 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T18:24:41.570Z,1745346281.570 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T18:24:41.570Z,1745346281.570 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T18:29:42.117Z,1745346582.117 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T18:29:42.117Z,1745346582.117 [Default:CheckIn:C.Wait] Stopped 2025-04-22T18:29:42.117Z,1745346582.117 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T18:29:42.118Z,1745346582.118 [Default:CheckIn:D] Running Loop=1 2025-04-22T18:29:42.525Z,1745346582.525 [Default:CheckIn:D] Stopped 2025-04-22T18:29:42.525Z,1745346582.525 [Default:CheckIn:E] Running Loop=1 2025-04-22T18:29:42.924Z,1745346582.924 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 202.384049 min 2025-04-22T18:29:42.924Z,1745346582.924 [Default:CheckIn:E] Stopped 2025-04-22T18:29:42.924Z,1745346582.924 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T18:29:42.924Z,1745346582.924 [Default:CheckIn] Stopped 2025-04-22T18:29:42.924Z,1745346582.924 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T18:29:42.924Z,1745346582.924 [Default:CheckIn](INFO): Running loop #20 2025-04-22T18:29:42.924Z,1745346582.924 [Default:CheckIn] Running Loop=20 2025-04-22T18:29:42.924Z,1745346582.924 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T18:29:42.925Z,1745346582.925 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T18:29:44.529Z,1745346584.529 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T18:29:44.529Z,1745346584.529 [NAL9602] Data Fault, FailCount= 5 2025-04-22T18:29:44.529Z,1745346584.529 [NAL9602](ERROR): Data Fault 2025-04-22T18:29:44.717Z,1745346584.717 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T18:29:44.718Z,1745346584.718 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-04-22T18:29:44.938Z,1745346584.938 [NAL9602](INFO): Powering down 2025-04-22T18:29:45.123Z,1745346585.123 [CBIT](INFO): Critical error at 20250422T182944 2025-04-22T18:31:44.754Z,1745346704.754 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T18:31:44.754Z,1745346704.754 [NAL9602] No Fault, FailCount= 5 2025-04-22T18:31:44.970Z,1745346704.970 [NAL9602](INFO): Powering up NAL9602 2025-04-22T18:31:55.874Z,1745346715.874 [NAL9602](INFO): NAL9602 initialized 2025-04-22T18:33:09.002Z,1745346789.002 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-22T18:33:09.002Z,1745346789.002 [DropWeight] Hardware Fault, FailCount= 1 2025-04-22T18:33:09.002Z,1745346789.002 [DropWeight](ERROR): Hardware Fault 2025-04-22T18:33:09.167Z,1745346789.167 [CBIT](INFO): Critical error at 20250422T183309 2025-04-22T18:33:09.170Z,1745346789.170 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-22T18:33:09.170Z,1745346789.170 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-22T18:33:09.619Z,1745346789.619 [CBIT](INFO): Critical error at 20250422T183309 2025-04-22T18:34:43.158Z,1745346883.158 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T18:29:42.9Z 2025-04-22T18:34:43.158Z,1745346883.158 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T18:34:43.159Z,1745346883.159 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T18:34:50.649Z,1745346890.649 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20250422T150719/Courier0057.lzma 2025-04-22T18:34:51.651Z,1745346891.651 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0057.lzma.bak 2025-04-22T18:34:51.651Z,1745346891.651 [DataOverHttps](INFO): SBD MOMSN=24711196 2025-04-22T18:35:10.097Z,1745346910.097 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250422T150719/Express0058.lzma 2025-04-22T18:35:11.099Z,1745346911.099 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0058.lzma.bak 2025-04-22T18:35:11.100Z,1745346911.100 [DataOverHttps](INFO): SBD MOMSN=24711201 2025-04-22T18:35:14.288Z,1745346914.288 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T18:35:14.288Z,1745346914.288 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T18:35:14.293Z,1745346914.293 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T18:36:59.278Z,1745347019.278 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T18:37:29.982Z,1745347049.982 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T18:40:14.830Z,1745347214.830 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T18:40:14.830Z,1745347214.830 [Default:CheckIn:C.Wait] Stopped 2025-04-22T18:40:14.830Z,1745347214.830 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T18:40:14.831Z,1745347214.831 [Default:CheckIn:D] Running Loop=1 2025-04-22T18:40:15.241Z,1745347215.241 [Default:CheckIn:D] Stopped 2025-04-22T18:40:15.241Z,1745347215.241 [Default:CheckIn:E] Running Loop=1 2025-04-22T18:40:15.631Z,1745347215.631 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 212.929329 min 2025-04-22T18:40:15.631Z,1745347215.631 [Default:CheckIn:E] Stopped 2025-04-22T18:40:15.632Z,1745347215.632 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T18:40:15.632Z,1745347215.632 [Default:CheckIn] Stopped 2025-04-22T18:40:15.632Z,1745347215.632 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T18:40:15.632Z,1745347215.632 [Default:CheckIn](INFO): Running loop #21 2025-04-22T18:40:15.632Z,1745347215.632 [Default:CheckIn] Running Loop=21 2025-04-22T18:40:15.632Z,1745347215.632 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T18:40:15.632Z,1745347215.632 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T18:40:48.398Z,1745347248.398 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T18:40:48.474Z,1745347248.474 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T18:45:15.873Z,1745347515.873 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T18:40:15.6Z 2025-04-22T18:45:15.873Z,1745347515.873 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T18:45:15.873Z,1745347515.873 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T18:45:19.271Z,1745347519.271 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T18:45:24.157Z,1745347524.157 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0060.lzma 2025-04-22T18:45:25.159Z,1745347525.159 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0060.lzma.bak 2025-04-22T18:45:25.159Z,1745347525.159 [DataOverHttps](INFO): SBD MOMSN=24711291 2025-04-22T18:45:43.197Z,1745347543.197 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20250422T150719/Express0061.lzma 2025-04-22T18:45:44.199Z,1745347544.199 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0061.lzma.bak 2025-04-22T18:45:44.200Z,1745347544.200 [DataOverHttps](INFO): SBD MOMSN=24711298 2025-04-22T18:45:47.198Z,1745347547.198 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T18:45:47.199Z,1745347547.199 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T18:45:47.199Z,1745347547.199 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T18:45:49.986Z,1745347549.986 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T18:48:09.126Z,1745347689.126 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-22T18:48:09.126Z,1745347689.126 [DropWeight] No Fault, FailCount= 1 2025-04-22T18:50:47.831Z,1745347847.831 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T18:50:47.831Z,1745347847.831 [Default:CheckIn:C.Wait] Stopped 2025-04-22T18:50:47.831Z,1745347847.831 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T18:50:47.831Z,1745347847.831 [Default:CheckIn:D] Running Loop=1 2025-04-22T18:50:48.215Z,1745347848.215 [Default:CheckIn:D] Stopped 2025-04-22T18:50:48.215Z,1745347848.215 [Default:CheckIn:E] Running Loop=1 2025-04-22T18:50:48.611Z,1745347848.611 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 223.478890 min 2025-04-22T18:50:48.612Z,1745347848.612 [Default:CheckIn:E] Stopped 2025-04-22T18:50:48.612Z,1745347848.612 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T18:50:48.612Z,1745347848.612 [Default:CheckIn] Stopped 2025-04-22T18:50:48.612Z,1745347848.612 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T18:50:48.612Z,1745347848.612 [Default:CheckIn](INFO): Running loop #22 2025-04-22T18:50:48.612Z,1745347848.612 [Default:CheckIn] Running Loop=22 2025-04-22T18:50:48.612Z,1745347848.612 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T18:50:48.612Z,1745347848.612 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T18:50:50.217Z,1745347850.217 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T18:50:50.217Z,1745347850.217 [NAL9602] Data Fault, FailCount= 1 2025-04-22T18:50:50.217Z,1745347850.217 [NAL9602](ERROR): Data Fault 2025-04-22T18:50:50.502Z,1745347850.502 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T18:50:50.626Z,1745347850.626 [NAL9602](INFO): Powering down 2025-04-22T18:50:51.641Z,1745347851.641 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T18:50:51.642Z,1745347851.642 [NAL9602] No Fault, FailCount= 1 2025-04-22T18:51:20.922Z,1745347880.922 [NAL9602](INFO): Powering up NAL9602 2025-04-22T18:51:31.830Z,1745347891.830 [NAL9602](INFO): NAL9602 initialized 2025-04-22T18:55:48.786Z,1745348148.786 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T18:50:48.6Z 2025-04-22T18:55:48.786Z,1745348148.786 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T18:55:48.787Z,1745348148.787 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T18:55:56.832Z,1745348156.832 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0063.lzma 2025-04-22T18:55:57.835Z,1745348157.835 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0063.lzma.bak 2025-04-22T18:55:57.835Z,1745348157.835 [DataOverHttps](INFO): SBD MOMSN=24711437 2025-04-22T18:56:16.333Z,1745348176.333 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250422T150719/Express0064.lzma 2025-04-22T18:56:17.335Z,1745348177.335 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0064.lzma.bak 2025-04-22T18:56:17.335Z,1745348177.335 [DataOverHttps](INFO): SBD MOMSN=24711442 2025-04-22T18:56:20.329Z,1745348180.329 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T18:56:20.329Z,1745348180.329 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T18:56:20.329Z,1745348180.329 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T18:56:35.235Z,1745348195.235 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T18:57:05.938Z,1745348225.938 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T19:01:20.917Z,1745348480.917 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T19:01:20.917Z,1745348480.917 [Default:CheckIn:C.Wait] Stopped 2025-04-22T19:01:20.917Z,1745348480.917 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T19:01:22.646Z,1745348482.646 [Default:CheckIn:D] Running Loop=1 2025-04-22T19:01:22.833Z,1745348482.833 [Default:CheckIn:D] Stopped 2025-04-22T19:01:22.833Z,1745348482.833 [Default:CheckIn:E] Running Loop=1 2025-04-22T19:01:23.232Z,1745348483.232 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 234.055859 min 2025-04-22T19:01:23.232Z,1745348483.232 [Default:CheckIn:E] Stopped 2025-04-22T19:01:23.232Z,1745348483.232 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T19:01:23.232Z,1745348483.232 [Default:CheckIn] Stopped 2025-04-22T19:01:23.232Z,1745348483.232 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T19:01:23.233Z,1745348483.233 [Default:CheckIn](INFO): Running loop #23 2025-04-22T19:01:23.233Z,1745348483.233 [Default:CheckIn] Running Loop=23 2025-04-22T19:01:23.233Z,1745348483.233 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T19:01:23.233Z,1745348483.233 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T19:01:55.946Z,1745348515.946 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T19:01:56.021Z,1745348516.021 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T19:06:23.454Z,1745348783.454 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T19:01:23.2Z 2025-04-22T19:06:23.455Z,1745348783.455 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T19:06:23.455Z,1745348783.455 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T19:06:27.458Z,1745348787.458 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T19:06:31.161Z,1745348791.161 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0066.lzma 2025-04-22T19:06:32.163Z,1745348792.163 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0066.lzma.bak 2025-04-22T19:06:32.163Z,1745348792.163 [DataOverHttps](INFO): SBD MOMSN=24711465 2025-04-22T19:06:50.953Z,1745348810.953 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20250422T150719/Express0067.lzma 2025-04-22T19:06:51.955Z,1745348811.955 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0067.lzma.bak 2025-04-22T19:06:51.955Z,1745348811.955 [DataOverHttps](INFO): SBD MOMSN=24711473 2025-04-22T19:06:54.970Z,1745348814.970 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T19:06:54.970Z,1745348814.970 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T19:06:54.971Z,1745348814.971 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T19:06:58.162Z,1745348818.162 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T19:11:55.516Z,1745349115.516 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T19:11:55.516Z,1745349115.516 [Default:CheckIn:C.Wait] Stopped 2025-04-22T19:11:55.516Z,1745349115.516 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T19:11:55.516Z,1745349115.516 [Default:CheckIn:D] Running Loop=1 2025-04-22T19:11:55.933Z,1745349115.933 [Default:CheckIn:D] Stopped 2025-04-22T19:11:55.933Z,1745349115.933 [Default:CheckIn:E] Running Loop=1 2025-04-22T19:11:56.324Z,1745349116.324 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 244.607520 min 2025-04-22T19:11:56.324Z,1745349116.324 [Default:CheckIn:E] Stopped 2025-04-22T19:11:56.325Z,1745349116.325 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T19:11:56.337Z,1745349116.337 [Default:CheckIn] Stopped 2025-04-22T19:11:56.337Z,1745349116.337 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T19:11:56.337Z,1745349116.337 [Default:CheckIn](INFO): Running loop #24 2025-04-22T19:11:56.337Z,1745349116.337 [Default:CheckIn] Running Loop=24 2025-04-22T19:11:56.337Z,1745349116.337 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T19:11:56.337Z,1745349116.337 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T19:11:57.934Z,1745349117.934 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T19:11:57.934Z,1745349117.934 [NAL9602] Data Fault, FailCount= 2 2025-04-22T19:11:57.934Z,1745349117.934 [NAL9602](ERROR): Data Fault 2025-04-22T19:11:58.137Z,1745349118.137 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T19:11:58.342Z,1745349118.342 [NAL9602](INFO): Powering down 2025-04-22T19:11:59.298Z,1745349119.298 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T19:11:59.298Z,1745349119.298 [NAL9602] No Fault, FailCount= 2 2025-04-22T19:12:28.633Z,1745349148.633 [NAL9602](INFO): Powering up NAL9602 2025-04-22T19:12:39.541Z,1745349159.541 [NAL9602](INFO): NAL9602 initialized 2025-04-22T19:14:44.780Z,1745349284.780 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T19:14:58.113Z,1745349298.113 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T19:15:11.040Z,1745349311.040 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T19:15:23.161Z,1745349323.161 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T19:15:36.493Z,1745349336.493 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T19:15:49.825Z,1745349349.825 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T19:16:02.347Z,1745349362.347 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T19:16:56.519Z,1745349416.519 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T19:11:56.3Z 2025-04-22T19:16:56.519Z,1745349416.519 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T19:16:56.519Z,1745349416.519 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T19:17:04.868Z,1745349424.868 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0069.lzma 2025-04-22T19:17:05.871Z,1745349425.871 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0069.lzma.bak 2025-04-22T19:17:05.871Z,1745349425.871 [DataOverHttps](INFO): SBD MOMSN=24711498 2025-04-22T19:17:24.093Z,1745349444.093 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250422T150719/Express0070.lzma 2025-04-22T19:17:25.095Z,1745349445.095 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0070.lzma.bak 2025-04-22T19:17:25.095Z,1745349445.095 [DataOverHttps](INFO): SBD MOMSN=24711503 2025-04-22T19:17:28.360Z,1745349448.360 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T19:17:28.361Z,1745349448.361 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T19:17:28.365Z,1745349448.365 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T19:17:42.798Z,1745349462.798 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T19:18:10.258Z,1745349490.258 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-22T19:18:10.258Z,1745349490.258 [DropWeight] Hardware Fault, FailCount= 1 2025-04-22T19:18:10.258Z,1745349490.258 [DropWeight](ERROR): Hardware Fault 2025-04-22T19:18:10.411Z,1745349490.411 [CBIT](INFO): Critical error at 20250422T191810 2025-04-22T19:18:10.414Z,1745349490.414 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-22T19:18:10.414Z,1745349490.414 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-22T19:18:10.827Z,1745349490.827 [CBIT](INFO): Critical error at 20250422T191810 2025-04-22T19:18:13.486Z,1745349493.486 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T19:21:06.176Z,1745349666.176 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-04-22T19:21:10.223Z,1745349670.223 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-22T19:21:10.224Z,1745349670.224 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-04-22T19:21:10.231Z,1745349670.231 [BPC1](INFO): Received data from all battery sticks. 2025-04-22T19:22:29.012Z,1745349749.012 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T19:22:29.012Z,1745349749.012 [Default:CheckIn:C.Wait] Stopped 2025-04-22T19:22:29.012Z,1745349749.012 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T19:22:29.013Z,1745349749.013 [Default:CheckIn:D] Running Loop=1 2025-04-22T19:22:29.412Z,1745349749.412 [Default:CheckIn:D] Stopped 2025-04-22T19:22:29.412Z,1745349749.412 [Default:CheckIn:E] Running Loop=1 2025-04-22T19:22:29.820Z,1745349749.820 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 255.165511 min 2025-04-22T19:22:29.821Z,1745349749.821 [Default:CheckIn:E] Stopped 2025-04-22T19:22:29.821Z,1745349749.821 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T19:22:29.821Z,1745349749.821 [Default:CheckIn] Stopped 2025-04-22T19:22:29.822Z,1745349749.822 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T19:22:29.822Z,1745349749.822 [Default:CheckIn](INFO): Running loop #25 2025-04-22T19:22:29.822Z,1745349749.822 [Default:CheckIn] Running Loop=25 2025-04-22T19:22:29.822Z,1745349749.822 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T19:22:29.822Z,1745349749.822 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T19:23:02.534Z,1745349782.534 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T19:23:02.617Z,1745349782.617 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T19:27:29.994Z,1745350049.994 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T19:22:29.8Z 2025-04-22T19:27:29.994Z,1745350049.994 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T19:27:29.994Z,1745350049.994 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T19:27:34.510Z,1745350054.510 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T19:27:38.449Z,1745350058.449 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20250422T150719/Courier0072.lzma 2025-04-22T19:27:39.451Z,1745350059.451 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0072.lzma.bak 2025-04-22T19:27:39.451Z,1745350059.451 [DataOverHttps](INFO): SBD MOMSN=24711532 2025-04-22T19:27:57.521Z,1745350077.521 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20250422T150719/Express0073.lzma 2025-04-22T19:27:58.523Z,1745350078.523 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0073.lzma.bak 2025-04-22T19:27:58.523Z,1745350078.523 [DataOverHttps](INFO): SBD MOMSN=24711536 2025-04-22T19:28:01.602Z,1745350081.602 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T19:28:01.602Z,1745350081.602 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T19:28:01.602Z,1745350081.602 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T19:28:05.204Z,1745350085.204 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T19:33:02.160Z,1745350382.160 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T19:33:02.160Z,1745350382.160 [Default:CheckIn:C.Wait] Stopped 2025-04-22T19:33:02.160Z,1745350382.160 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T19:33:02.177Z,1745350382.177 [Default:CheckIn:D] Running Loop=1 2025-04-22T19:33:02.564Z,1745350382.564 [Default:CheckIn:D] Stopped 2025-04-22T19:33:02.564Z,1745350382.564 [Default:CheckIn:E] Running Loop=1 2025-04-22T19:33:02.965Z,1745350382.965 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 265.718050 min 2025-04-22T19:33:02.965Z,1745350382.965 [Default:CheckIn:E] Stopped 2025-04-22T19:33:02.965Z,1745350382.965 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T19:33:02.966Z,1745350382.966 [Default:CheckIn] Stopped 2025-04-22T19:33:02.966Z,1745350382.966 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T19:33:02.966Z,1745350382.966 [Default:CheckIn](INFO): Running loop #26 2025-04-22T19:33:02.966Z,1745350382.966 [Default:CheckIn] Running Loop=26 2025-04-22T19:33:02.966Z,1745350382.966 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T19:33:02.966Z,1745350382.966 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T19:33:04.570Z,1745350384.570 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T19:33:04.571Z,1745350384.571 [NAL9602] Data Fault, FailCount= 3 2025-04-22T19:33:04.571Z,1745350384.571 [NAL9602](ERROR): Data Fault 2025-04-22T19:33:04.734Z,1745350384.734 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T19:33:04.972Z,1745350384.972 [NAL9602](INFO): Powering down 2025-04-22T19:33:05.941Z,1745350385.941 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T19:33:05.941Z,1745350385.941 [NAL9602] No Fault, FailCount= 3 2025-04-22T19:33:10.406Z,1745350390.406 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-22T19:33:10.406Z,1745350390.406 [DropWeight] No Fault, FailCount= 1 2025-04-22T19:33:35.274Z,1745350415.274 [NAL9602](INFO): Powering up NAL9602 2025-04-22T19:33:46.182Z,1745350426.182 [NAL9602](INFO): NAL9602 initialized 2025-04-22T19:38:03.141Z,1745350683.141 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T19:33:02.0Z 2025-04-22T19:38:03.141Z,1745350683.141 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T19:38:03.142Z,1745350683.142 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T19:38:11.288Z,1745350691.288 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0075.lzma 2025-04-22T19:38:12.291Z,1745350692.291 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0075.lzma.bak 2025-04-22T19:38:12.292Z,1745350692.292 [DataOverHttps](INFO): SBD MOMSN=24711682 2025-04-22T19:38:31.246Z,1745350711.246 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250422T150719/Express0076.lzma 2025-04-22T19:38:32.247Z,1745350712.247 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0076.lzma.bak 2025-04-22T19:38:32.247Z,1745350712.247 [DataOverHttps](INFO): SBD MOMSN=24711684 2025-04-22T19:38:35.246Z,1745350715.246 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T19:38:35.247Z,1745350715.247 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T19:38:35.247Z,1745350715.247 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T19:38:49.754Z,1745350729.754 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T19:39:20.462Z,1745350760.462 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T19:43:35.825Z,1745351015.825 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T19:43:35.825Z,1745351015.825 [Default:CheckIn:C.Wait] Stopped 2025-04-22T19:43:35.825Z,1745351015.825 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T19:43:35.825Z,1745351015.825 [Default:CheckIn:D] Running Loop=1 2025-04-22T19:43:36.229Z,1745351016.229 [Default:CheckIn:D] Stopped 2025-04-22T19:43:36.229Z,1745351016.229 [Default:CheckIn:E] Running Loop=1 2025-04-22T19:43:36.635Z,1745351016.635 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 276.279134 min 2025-04-22T19:43:36.635Z,1745351016.635 [Default:CheckIn:E] Stopped 2025-04-22T19:43:36.636Z,1745351016.636 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T19:43:36.636Z,1745351016.636 [Default:CheckIn] Stopped 2025-04-22T19:43:36.636Z,1745351016.636 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T19:43:36.636Z,1745351016.636 [Default:CheckIn](INFO): Running loop #27 2025-04-22T19:43:36.636Z,1745351016.636 [Default:CheckIn] Running Loop=27 2025-04-22T19:43:36.636Z,1745351016.636 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T19:43:36.636Z,1745351016.636 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T19:47:01.452Z,1745351221.452 [NAL9602](INFO): SBD MO Status=2, MOMSN=13659, MT Status=2, MTMSN=0 2025-04-22T19:47:01.452Z,1745351221.452 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-22T19:48:36.822Z,1745351316.822 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T19:43:36.6Z 2025-04-22T19:48:36.822Z,1745351316.822 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T19:48:36.822Z,1745351316.822 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T19:48:40.430Z,1745351320.430 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T19:48:44.728Z,1745351324.728 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0078.lzma 2025-04-22T19:48:45.731Z,1745351325.731 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0078.lzma.bak 2025-04-22T19:48:45.731Z,1745351325.731 [DataOverHttps](INFO): SBD MOMSN=24711742 2025-04-22T19:49:03.877Z,1745351343.877 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20250422T150719/Express0079.lzma 2025-04-22T19:49:04.879Z,1745351344.879 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0079.lzma.bak 2025-04-22T19:49:04.879Z,1745351344.879 [DataOverHttps](INFO): SBD MOMSN=24711744 2025-04-22T19:49:07.927Z,1745351347.927 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T19:49:07.928Z,1745351347.928 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T19:49:07.928Z,1745351347.928 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T19:49:11.138Z,1745351351.138 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T19:54:08.511Z,1745351648.511 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T19:54:08.511Z,1745351648.511 [Default:CheckIn:C.Wait] Stopped 2025-04-22T19:54:08.511Z,1745351648.511 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T19:54:08.512Z,1745351648.512 [Default:CheckIn:D] Running Loop=1 2025-04-22T19:54:08.902Z,1745351648.902 [Default:CheckIn:D] Stopped 2025-04-22T19:54:08.902Z,1745351648.902 [Default:CheckIn:E] Running Loop=1 2025-04-22T19:54:09.296Z,1745351649.296 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 286.823665 min 2025-04-22T19:54:09.296Z,1745351649.296 [Default:CheckIn:E] Stopped 2025-04-22T19:54:09.296Z,1745351649.296 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T19:54:09.296Z,1745351649.296 [Default:CheckIn] Stopped 2025-04-22T19:54:09.296Z,1745351649.296 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T19:54:09.296Z,1745351649.296 [Default:CheckIn](INFO): Running loop #28 2025-04-22T19:54:09.296Z,1745351649.296 [Default:CheckIn] Running Loop=28 2025-04-22T19:54:09.296Z,1745351649.296 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T19:54:09.296Z,1745351649.296 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T19:54:10.901Z,1745351650.901 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T19:54:10.901Z,1745351650.901 [NAL9602] Data Fault, FailCount= 4 2025-04-22T19:54:10.901Z,1745351650.901 [NAL9602](ERROR): Data Fault 2025-04-22T19:54:11.097Z,1745351651.097 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T19:54:11.310Z,1745351651.310 [NAL9602](INFO): Powering down 2025-04-22T19:54:12.281Z,1745351652.281 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T19:54:12.281Z,1745351652.281 [NAL9602] No Fault, FailCount= 4 2025-04-22T19:54:41.610Z,1745351681.610 [NAL9602](INFO): Powering up NAL9602 2025-04-22T19:54:52.513Z,1745351692.513 [NAL9602](INFO): NAL9602 initialized 2025-04-22T19:59:09.495Z,1745351949.495 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T19:54:09.3Z 2025-04-22T19:59:09.495Z,1745351949.495 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T19:59:09.495Z,1745351949.495 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T19:59:17.893Z,1745351957.893 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0081.lzma 2025-04-22T19:59:18.891Z,1745351958.891 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0081.lzma.bak 2025-04-22T19:59:18.891Z,1745351958.891 [DataOverHttps](INFO): SBD MOMSN=24711859 2025-04-22T19:59:36.729Z,1745351976.729 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250422T150719/Express0082.lzma 2025-04-22T19:59:37.731Z,1745351977.731 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0082.lzma.bak 2025-04-22T19:59:37.732Z,1745351977.732 [DataOverHttps](INFO): SBD MOMSN=24711862 2025-04-22T19:59:40.859Z,1745351980.859 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T19:59:40.859Z,1745351980.859 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T19:59:40.859Z,1745351980.859 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T19:59:55.766Z,1745351995.766 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T20:00:26.478Z,1745352026.478 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T20:03:11.718Z,1745352191.718 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-22T20:03:11.718Z,1745352191.718 [DropWeight] Hardware Fault, FailCount= 1 2025-04-22T20:03:11.718Z,1745352191.718 [DropWeight](ERROR): Hardware Fault 2025-04-22T20:03:11.883Z,1745352191.883 [CBIT](INFO): Critical error at 20250422T200311 2025-04-22T20:03:11.886Z,1745352191.886 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-22T20:03:11.886Z,1745352191.886 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-22T20:03:12.279Z,1745352192.279 [CBIT](INFO): Critical error at 20250422T200311 2025-04-22T20:04:41.412Z,1745352281.412 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T20:04:41.412Z,1745352281.412 [Default:CheckIn:C.Wait] Stopped 2025-04-22T20:04:41.412Z,1745352281.412 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T20:04:41.413Z,1745352281.413 [Default:CheckIn:D] Running Loop=1 2025-04-22T20:04:41.829Z,1745352281.829 [Default:CheckIn:D] Stopped 2025-04-22T20:04:41.829Z,1745352281.829 [Default:CheckIn:E] Running Loop=1 2025-04-22T20:04:42.219Z,1745352282.219 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 297.372461 min 2025-04-22T20:04:42.220Z,1745352282.220 [Default:CheckIn:E] Stopped 2025-04-22T20:04:42.220Z,1745352282.220 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T20:04:42.220Z,1745352282.220 [Default:CheckIn] Stopped 2025-04-22T20:04:42.220Z,1745352282.220 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T20:04:42.220Z,1745352282.220 [Default:CheckIn](INFO): Running loop #29 2025-04-22T20:04:42.220Z,1745352282.220 [Default:CheckIn] Running Loop=29 2025-04-22T20:04:42.220Z,1745352282.220 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T20:04:42.220Z,1745352282.220 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T20:05:14.946Z,1745352314.946 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T20:05:15.029Z,1745352315.029 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T20:09:42.397Z,1745352582.397 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T20:04:42.2Z 2025-04-22T20:09:42.397Z,1745352582.397 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T20:09:42.397Z,1745352582.397 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T20:09:46.586Z,1745352586.586 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T20:09:50.748Z,1745352590.748 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20250422T150719/Courier0084.lzma 2025-04-22T20:09:51.751Z,1745352591.751 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0084.lzma.bak 2025-04-22T20:09:51.751Z,1745352591.751 [DataOverHttps](INFO): SBD MOMSN=24711976 2025-04-22T20:10:09.986Z,1745352609.986 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20250422T150719/Express0085.lzma 2025-04-22T20:10:10.987Z,1745352610.987 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0085.lzma.bak 2025-04-22T20:10:10.987Z,1745352610.987 [DataOverHttps](INFO): SBD MOMSN=24711980 2025-04-22T20:10:13.918Z,1745352613.918 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T20:10:13.919Z,1745352613.919 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T20:10:13.919Z,1745352613.919 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T20:10:17.130Z,1745352617.130 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T20:15:14.471Z,1745352914.471 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T20:15:14.471Z,1745352914.471 [Default:CheckIn:C.Wait] Stopped 2025-04-22T20:15:14.472Z,1745352914.472 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T20:15:14.472Z,1745352914.472 [Default:CheckIn:D] Running Loop=1 2025-04-22T20:15:14.876Z,1745352914.876 [Default:CheckIn:D] Stopped 2025-04-22T20:15:14.876Z,1745352914.876 [Default:CheckIn:E] Running Loop=1 2025-04-22T20:15:15.280Z,1745352915.280 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 307.923242 min 2025-04-22T20:15:15.280Z,1745352915.280 [Default:CheckIn:E] Stopped 2025-04-22T20:15:15.281Z,1745352915.281 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T20:15:15.281Z,1745352915.281 [Default:CheckIn] Stopped 2025-04-22T20:15:15.281Z,1745352915.281 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T20:15:15.281Z,1745352915.281 [Default:CheckIn](INFO): Running loop #30 2025-04-22T20:15:15.281Z,1745352915.281 [Default:CheckIn] Running Loop=30 2025-04-22T20:15:15.281Z,1745352915.281 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T20:15:15.281Z,1745352915.281 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T20:15:16.886Z,1745352916.886 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T20:15:16.886Z,1745352916.886 [NAL9602] Data Fault, FailCount= 5 2025-04-22T20:15:16.886Z,1745352916.886 [NAL9602](ERROR): Data Fault 2025-04-22T20:15:17.062Z,1745352917.062 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T20:15:17.062Z,1745352917.062 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-04-22T20:15:17.294Z,1745352917.294 [NAL9602](INFO): Powering down 2025-04-22T20:15:17.448Z,1745352917.448 [CBIT](INFO): Critical error at 20250422T201517 2025-04-22T20:17:17.061Z,1745353037.061 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T20:17:17.062Z,1745353037.062 [NAL9602] No Fault, FailCount= 5 2025-04-22T20:17:17.282Z,1745353037.282 [NAL9602](INFO): Powering up NAL9602 2025-04-22T20:17:28.186Z,1745353048.186 [NAL9602](INFO): NAL9602 initialized 2025-04-22T20:18:11.965Z,1745353091.965 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-22T20:18:11.965Z,1745353091.965 [DropWeight] No Fault, FailCount= 1 2025-04-22T20:20:15.457Z,1745353215.457 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T20:15:15.3Z 2025-04-22T20:20:15.457Z,1745353215.457 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T20:20:15.457Z,1745353215.457 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T20:20:23.608Z,1745353223.608 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20250422T150719/Courier0087.lzma 2025-04-22T20:20:24.612Z,1745353224.612 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0087.lzma.bak 2025-04-22T20:20:24.612Z,1745353224.612 [DataOverHttps](INFO): SBD MOMSN=24712055 2025-04-22T20:20:46.305Z,1745353246.305 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250422T150719/Express0088.lzma 2025-04-22T20:20:47.315Z,1745353247.315 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0088.lzma.bak 2025-04-22T20:20:47.315Z,1745353247.315 [DataOverHttps](INFO): SBD MOMSN=24712058 2025-04-22T20:20:53.274Z,1745353253.274 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T20:20:53.274Z,1745353253.274 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T20:20:53.274Z,1745353253.274 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T20:22:17.656Z,1745353337.656 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T20:22:30.585Z,1745353350.585 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T20:22:31.874Z,1745353351.874 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T20:22:43.917Z,1745353363.917 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T20:22:56.440Z,1745353376.440 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T20:23:02.506Z,1745353382.506 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T20:23:08.963Z,1745353388.963 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T20:23:21.892Z,1745353401.892 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T20:23:34.417Z,1745353414.417 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T20:25:53.808Z,1745353553.808 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T20:25:53.808Z,1745353553.808 [Default:CheckIn:C.Wait] Stopped 2025-04-22T20:25:53.808Z,1745353553.808 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T20:25:53.809Z,1745353553.809 [Default:CheckIn:D] Running Loop=1 2025-04-22T20:25:54.275Z,1745353554.275 [Default:CheckIn:D] Stopped 2025-04-22T20:25:54.275Z,1745353554.275 [Default:CheckIn:E] Running Loop=1 2025-04-22T20:25:54.625Z,1745353554.625 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 318.579883 min 2025-04-22T20:25:54.626Z,1745353554.626 [Default:CheckIn:E] Stopped 2025-04-22T20:25:54.626Z,1745353554.626 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T20:25:54.626Z,1745353554.626 [Default:CheckIn] Stopped 2025-04-22T20:25:54.626Z,1745353554.626 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T20:25:54.626Z,1745353554.626 [Default:CheckIn](INFO): Running loop #31 2025-04-22T20:25:54.626Z,1745353554.626 [Default:CheckIn] Running Loop=31 2025-04-22T20:25:54.626Z,1745353554.626 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T20:25:54.626Z,1745353554.626 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T20:26:27.334Z,1745353587.334 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T20:26:27.409Z,1745353587.409 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T20:28:38.628Z,1745353718.628 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-04-22T20:28:41.463Z,1745353721.463 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-22T20:28:41.464Z,1745353721.464 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-04-22T20:28:41.467Z,1745353721.467 [BPC1](INFO): Received data from all battery sticks. 2025-04-22T20:30:54.726Z,1745353854.726 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T20:25:54.6Z 2025-04-22T20:30:54.726Z,1745353854.726 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T20:30:54.726Z,1745353854.726 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T20:30:58.422Z,1745353858.422 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T20:31:01.374Z,1745353861.374 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20250422T150719/Courier0090.lzma 2025-04-22T20:31:02.379Z,1745353862.379 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0090.lzma.bak 2025-04-22T20:31:02.379Z,1745353862.379 [DataOverHttps](INFO): SBD MOMSN=24712152 2025-04-22T20:31:29.115Z,1745353889.115 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T20:31:39.043Z,1745353899.043 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20250422T150719/Express0091.lzma 2025-04-22T20:31:40.051Z,1745353900.051 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0091.lzma.bak 2025-04-22T20:31:40.051Z,1745353900.051 [DataOverHttps](INFO): SBD MOMSN=24712155 2025-04-22T20:31:43.695Z,1745353903.695 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T20:31:43.696Z,1745353903.696 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T20:31:43.696Z,1745353903.696 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T20:36:44.245Z,1745354204.245 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T20:36:44.245Z,1745354204.245 [Default:CheckIn:C.Wait] Stopped 2025-04-22T20:36:44.245Z,1745354204.245 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T20:36:44.245Z,1745354204.245 [Default:CheckIn:D] Running Loop=1 2025-04-22T20:36:44.653Z,1745354204.653 [Default:CheckIn:D] Stopped 2025-04-22T20:36:44.653Z,1745354204.653 [Default:CheckIn:E] Running Loop=1 2025-04-22T20:36:45.052Z,1745354205.052 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 329.419531 min 2025-04-22T20:36:45.052Z,1745354205.052 [Default:CheckIn:E] Stopped 2025-04-22T20:36:45.052Z,1745354205.052 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T20:36:45.052Z,1745354205.052 [Default:CheckIn] Stopped 2025-04-22T20:36:45.053Z,1745354205.053 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T20:36:45.069Z,1745354205.069 [Default:CheckIn](INFO): Running loop #32 2025-04-22T20:36:45.069Z,1745354205.069 [Default:CheckIn] Running Loop=32 2025-04-22T20:36:45.069Z,1745354205.069 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T20:36:45.069Z,1745354205.069 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T20:36:46.657Z,1745354206.657 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T20:36:46.657Z,1745354206.657 [NAL9602] Data Fault, FailCount= 1 2025-04-22T20:36:46.657Z,1745354206.657 [NAL9602](ERROR): Data Fault 2025-04-22T20:36:46.845Z,1745354206.845 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T20:36:47.061Z,1745354207.061 [NAL9602](INFO): Powering down 2025-04-22T20:36:48.037Z,1745354208.037 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T20:36:48.037Z,1745354208.037 [NAL9602] No Fault, FailCount= 1 2025-04-22T20:37:17.361Z,1745354237.361 [NAL9602](INFO): Powering up NAL9602 2025-04-22T20:37:28.270Z,1745354248.270 [NAL9602](INFO): NAL9602 initialized 2025-04-22T20:41:45.227Z,1745354505.227 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T20:36:45.1Z 2025-04-22T20:41:45.227Z,1745354505.227 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T20:41:45.227Z,1745354505.227 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T20:41:52.964Z,1745354512.964 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0093.lzma 2025-04-22T20:41:53.967Z,1745354513.967 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0093.lzma.bak 2025-04-22T20:41:53.967Z,1745354513.967 [DataOverHttps](INFO): SBD MOMSN=24712323 2025-04-22T20:42:14.142Z,1745354534.142 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250422T150719/Express0094.lzma 2025-04-22T20:42:15.147Z,1745354535.147 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0094.lzma.bak 2025-04-22T20:42:15.147Z,1745354535.147 [DataOverHttps](INFO): SBD MOMSN=24712325 2025-04-22T20:42:18.418Z,1745354538.418 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T20:42:18.418Z,1745354538.418 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T20:42:18.418Z,1745354538.418 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T20:42:31.686Z,1745354551.686 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T20:43:02.394Z,1745354582.394 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T20:47:18.948Z,1745354838.948 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T20:47:18.949Z,1745354838.949 [Default:CheckIn:C.Wait] Stopped 2025-04-22T20:47:18.949Z,1745354838.949 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T20:47:18.949Z,1745354838.949 [Default:CheckIn:D] Running Loop=1 2025-04-22T20:47:19.347Z,1745354839.347 [Default:CheckIn:D] Stopped 2025-04-22T20:47:19.347Z,1745354839.347 [Default:CheckIn:E] Running Loop=1 2025-04-22T20:47:19.752Z,1745354839.752 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 339.997754 min 2025-04-22T20:47:19.752Z,1745354839.752 [Default:CheckIn:E] Stopped 2025-04-22T20:47:19.752Z,1745354839.752 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T20:47:19.752Z,1745354839.752 [Default:CheckIn] Stopped 2025-04-22T20:47:19.752Z,1745354839.752 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T20:47:19.753Z,1745354839.753 [Default:CheckIn](INFO): Running loop #33 2025-04-22T20:47:19.753Z,1745354839.753 [Default:CheckIn] Running Loop=33 2025-04-22T20:47:19.753Z,1745354839.753 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T20:47:19.753Z,1745354839.753 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T20:47:52.471Z,1745354872.471 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T20:47:52.553Z,1745354872.553 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T20:48:13.078Z,1745354893.078 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-22T20:48:13.078Z,1745354893.078 [DropWeight] Hardware Fault, FailCount= 1 2025-04-22T20:48:13.078Z,1745354893.078 [DropWeight](ERROR): Hardware Fault 2025-04-22T20:48:13.256Z,1745354893.256 [CBIT](INFO): Critical error at 20250422T204813 2025-04-22T20:48:13.258Z,1745354893.258 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-22T20:48:13.259Z,1745354893.259 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-22T20:48:13.664Z,1745354893.664 [CBIT](INFO): Critical error at 20250422T204813 2025-04-22T20:52:19.962Z,1745355139.962 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T20:47:19.8Z 2025-04-22T20:52:19.962Z,1745355139.962 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T20:52:19.962Z,1745355139.962 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T20:52:24.374Z,1745355144.374 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T20:52:27.677Z,1745355147.677 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20250422T150719/Courier0096.lzma 2025-04-22T20:52:28.679Z,1745355148.679 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0096.lzma.bak 2025-04-22T20:52:28.679Z,1745355148.679 [DataOverHttps](INFO): SBD MOMSN=24712435 2025-04-22T20:52:46.733Z,1745355166.733 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20250422T150719/Express0097.lzma 2025-04-22T20:52:47.735Z,1745355167.735 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0097.lzma.bak 2025-04-22T20:52:47.735Z,1745355167.735 [DataOverHttps](INFO): SBD MOMSN=24712439 2025-04-22T20:52:50.666Z,1745355170.666 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T20:52:50.666Z,1745355170.666 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T20:52:50.667Z,1745355170.667 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T20:52:55.081Z,1745355175.081 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T20:57:51.224Z,1745355471.224 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T20:57:51.224Z,1745355471.224 [Default:CheckIn:C.Wait] Stopped 2025-04-22T20:57:51.224Z,1745355471.224 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T20:57:51.224Z,1745355471.224 [Default:CheckIn:D] Running Loop=1 2025-04-22T20:57:51.623Z,1745355471.623 [Default:CheckIn:D] Stopped 2025-04-22T20:57:51.623Z,1745355471.623 [Default:CheckIn:E] Running Loop=1 2025-04-22T20:57:52.033Z,1745355472.033 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 350.535710 min 2025-04-22T20:57:52.033Z,1745355472.033 [Default:CheckIn:E] Stopped 2025-04-22T20:57:52.033Z,1745355472.033 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T20:57:52.033Z,1745355472.033 [Default:CheckIn] Stopped 2025-04-22T20:57:52.033Z,1745355472.033 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T20:57:52.034Z,1745355472.034 [Default:CheckIn](INFO): Running loop #34 2025-04-22T20:57:52.034Z,1745355472.034 [Default:CheckIn] Running Loop=34 2025-04-22T20:57:52.034Z,1745355472.034 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T20:57:52.034Z,1745355472.034 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T20:57:53.634Z,1745355473.634 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T20:57:53.634Z,1745355473.634 [NAL9602] Data Fault, FailCount= 2 2025-04-22T20:57:53.634Z,1745355473.634 [NAL9602](ERROR): Data Fault 2025-04-22T20:57:53.833Z,1745355473.833 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T20:57:54.038Z,1745355474.038 [NAL9602](INFO): Powering down 2025-04-22T20:57:54.993Z,1745355474.993 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T20:57:54.993Z,1745355474.993 [NAL9602] No Fault, FailCount= 2 2025-04-22T20:58:24.337Z,1745355504.337 [NAL9602](INFO): Powering up NAL9602 2025-04-22T20:58:35.250Z,1745355515.250 [NAL9602](INFO): NAL9602 initialized 2025-04-22T21:02:52.200Z,1745355772.200 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T20:57:52.0Z 2025-04-22T21:02:52.200Z,1745355772.200 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T21:02:52.200Z,1745355772.200 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T21:03:00.188Z,1745355780.188 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0099.lzma 2025-04-22T21:03:01.191Z,1745355781.191 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0099.lzma.bak 2025-04-22T21:03:01.191Z,1745355781.191 [DataOverHttps](INFO): SBD MOMSN=24712507 2025-04-22T21:03:13.362Z,1745355793.362 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-22T21:03:13.362Z,1745355793.362 [DropWeight] No Fault, FailCount= 1 2025-04-22T21:03:19.521Z,1745355799.521 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250422T150719/Express0100.lzma 2025-04-22T21:03:20.523Z,1745355800.523 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0100.lzma.bak 2025-04-22T21:03:20.523Z,1745355800.523 [DataOverHttps](INFO): SBD MOMSN=24712518 2025-04-22T21:03:23.729Z,1745355803.729 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T21:03:23.729Z,1745355803.729 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T21:03:23.729Z,1745355803.729 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T21:03:38.658Z,1745355818.658 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T21:04:09.366Z,1745355849.366 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T21:08:24.301Z,1745356104.301 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T21:08:24.301Z,1745356104.301 [Default:CheckIn:C.Wait] Stopped 2025-04-22T21:08:24.301Z,1745356104.301 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T21:08:24.301Z,1745356104.301 [Default:CheckIn:D] Running Loop=1 2025-04-22T21:08:24.702Z,1745356104.702 [Default:CheckIn:D] Stopped 2025-04-22T21:08:24.703Z,1745356104.703 [Default:CheckIn:E] Running Loop=1 2025-04-22T21:08:25.100Z,1745356105.100 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 361.087012 min 2025-04-22T21:08:25.100Z,1745356105.100 [Default:CheckIn:E] Stopped 2025-04-22T21:08:25.100Z,1745356105.100 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T21:08:25.100Z,1745356105.100 [Default:CheckIn] Stopped 2025-04-22T21:08:25.100Z,1745356105.100 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T21:08:25.100Z,1745356105.100 [Default:CheckIn](INFO): Running loop #35 2025-04-22T21:08:25.100Z,1745356105.100 [Default:CheckIn] Running Loop=35 2025-04-22T21:08:25.101Z,1745356105.101 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T21:08:25.101Z,1745356105.101 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T21:08:57.815Z,1745356137.815 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T21:08:57.889Z,1745356137.889 [NAL9602](ERROR): received: +CSQ:1 OK659, 2, 0, 0, 0 OK 2025-04-22T21:13:25.274Z,1745356405.274 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T21:08:25.1Z 2025-04-22T21:13:25.274Z,1745356405.274 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T21:13:25.275Z,1745356405.275 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T21:13:29.306Z,1745356409.306 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T21:13:32.693Z,1745356412.693 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0102.lzma 2025-04-22T21:13:33.691Z,1745356413.691 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0102.lzma.bak 2025-04-22T21:13:33.691Z,1745356413.691 [DataOverHttps](INFO): SBD MOMSN=24712591 2025-04-22T21:13:52.213Z,1745356432.213 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20250422T150719/Express0103.lzma 2025-04-22T21:13:53.215Z,1745356433.215 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0103.lzma.bak 2025-04-22T21:13:53.215Z,1745356433.215 [DataOverHttps](INFO): SBD MOMSN=24712600 2025-04-22T21:13:56.421Z,1745356436.421 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T21:13:56.421Z,1745356436.421 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T21:13:56.421Z,1745356436.421 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T21:14:00.010Z,1745356440.010 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T21:18:56.962Z,1745356736.962 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T21:18:56.962Z,1745356736.962 [Default:CheckIn:C.Wait] Stopped 2025-04-22T21:18:56.962Z,1745356736.962 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T21:18:56.962Z,1745356736.962 [Default:CheckIn:D] Running Loop=1 2025-04-22T21:18:57.366Z,1745356737.366 [Default:CheckIn:D] Stopped 2025-04-22T21:18:57.366Z,1745356737.366 [Default:CheckIn:E] Running Loop=1 2025-04-22T21:18:57.788Z,1745356737.788 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 371.631413 min 2025-04-22T21:18:57.788Z,1745356737.788 [Default:CheckIn:E] Stopped 2025-04-22T21:18:57.788Z,1745356737.788 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T21:18:57.788Z,1745356737.788 [Default:CheckIn] Stopped 2025-04-22T21:18:57.788Z,1745356737.788 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T21:18:57.788Z,1745356737.788 [Default:CheckIn](INFO): Running loop #36 2025-04-22T21:18:57.788Z,1745356737.788 [Default:CheckIn] Running Loop=36 2025-04-22T21:18:57.788Z,1745356737.788 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T21:18:57.789Z,1745356737.789 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T21:18:59.369Z,1745356739.369 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T21:18:59.369Z,1745356739.369 [NAL9602] Data Fault, FailCount= 3 2025-04-22T21:18:59.369Z,1745356739.369 [NAL9602](ERROR): Data Fault 2025-04-22T21:18:59.561Z,1745356739.561 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T21:18:59.778Z,1745356739.778 [NAL9602](INFO): Powering down 2025-04-22T21:19:00.729Z,1745356740.729 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T21:19:00.729Z,1745356740.729 [NAL9602] No Fault, FailCount= 3 2025-04-22T21:19:30.073Z,1745356770.073 [NAL9602](INFO): Powering up NAL9602 2025-04-22T21:19:40.982Z,1745356780.982 [NAL9602](INFO): NAL9602 initialized 2025-04-22T21:23:57.958Z,1745357037.958 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T21:18:57.8Z 2025-04-22T21:23:57.958Z,1745357037.958 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T21:23:57.959Z,1745357037.959 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T21:24:06.940Z,1745357046.940 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0105.lzma 2025-04-22T21:24:07.943Z,1745357047.943 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0105.lzma.bak 2025-04-22T21:24:07.943Z,1745357047.943 [DataOverHttps](INFO): SBD MOMSN=24712717 2025-04-22T21:24:27.397Z,1745357067.397 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250422T150719/Express0106.lzma 2025-04-22T21:24:28.400Z,1745357068.400 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0106.lzma.bak 2025-04-22T21:24:28.400Z,1745357068.400 [DataOverHttps](INFO): SBD MOMSN=24712719 2025-04-22T21:24:31.502Z,1745357071.502 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T21:24:31.503Z,1745357071.503 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T21:24:31.503Z,1745357071.503 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T21:24:44.410Z,1745357084.410 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T21:25:15.110Z,1745357115.110 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T21:29:32.065Z,1745357372.065 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T21:29:32.065Z,1745357372.065 [Default:CheckIn:C.Wait] Stopped 2025-04-22T21:29:32.065Z,1745357372.065 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T21:29:32.065Z,1745357372.065 [Default:CheckIn:D] Running Loop=1 2025-04-22T21:29:32.483Z,1745357372.483 [Default:CheckIn:D] Stopped 2025-04-22T21:29:32.483Z,1745357372.483 [Default:CheckIn:E] Running Loop=1 2025-04-22T21:29:32.878Z,1745357372.878 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 382.216699 min 2025-04-22T21:29:32.878Z,1745357372.878 [Default:CheckIn:E] Stopped 2025-04-22T21:29:32.878Z,1745357372.878 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T21:29:32.878Z,1745357372.878 [Default:CheckIn] Stopped 2025-04-22T21:29:32.878Z,1745357372.878 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T21:29:32.878Z,1745357372.878 [Default:CheckIn](INFO): Running loop #37 2025-04-22T21:29:32.879Z,1745357372.879 [Default:CheckIn] Running Loop=37 2025-04-22T21:29:32.879Z,1745357372.879 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T21:29:32.879Z,1745357372.879 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T21:29:48.619Z,1745357388.619 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T21:30:01.948Z,1745357401.948 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T21:30:05.586Z,1745357405.586 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T21:30:05.661Z,1745357405.661 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T21:30:14.875Z,1745357414.875 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T21:30:27.444Z,1745357427.444 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T21:30:40.372Z,1745357440.372 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T21:30:53.705Z,1745357453.705 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T21:31:06.229Z,1745357466.229 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-04-22T21:33:14.710Z,1745357594.710 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-22T21:33:14.710Z,1745357594.710 [DropWeight] Hardware Fault, FailCount= 1 2025-04-22T21:33:14.710Z,1745357594.710 [DropWeight](ERROR): Hardware Fault 2025-04-22T21:33:14.883Z,1745357594.883 [CBIT](INFO): Critical error at 20250422T213314 2025-04-22T21:33:14.886Z,1745357594.886 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-22T21:33:14.886Z,1745357594.886 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-22T21:33:15.287Z,1745357595.287 [CBIT](INFO): Critical error at 20250422T213314 2025-04-22T21:33:27.804Z,1745357607.804 [CBIT](IMPORTANT): Beginning ground fault scan 2025-04-22T21:33:38.708Z,1745357618.708 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.011149 CHAN A1 (24V): -0.191239 CHAN A2 (12V): 0.000183 CHAN A3 (5V): -0.001198 CHAN B0 (3.3V): -0.000561 CHAN B1 (3.15aV): -0.001159 CHAN B2 (3.15bV): -0.000381 CHAN B3 (GND): -0.001858 OPEN: 0.005355 Full Scale: +/- 1 mA 2025-04-22T21:34:33.112Z,1745357673.112 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T21:29:32.9Z 2025-04-22T21:34:33.112Z,1745357673.112 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T21:34:33.112Z,1745357673.112 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T21:34:37.146Z,1745357677.146 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T21:34:40.922Z,1745357680.922 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20250422T150719/Courier0108.lzma 2025-04-22T21:34:41.923Z,1745357681.923 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0108.lzma.bak 2025-04-22T21:34:41.924Z,1745357681.924 [DataOverHttps](INFO): SBD MOMSN=24712804 2025-04-22T21:35:01.393Z,1745357701.393 [DataOverHttps](INFO): Sending 386 bytes from file Logs/20250422T150719/Express0109.lzma 2025-04-22T21:35:02.395Z,1745357702.395 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0109.lzma.bak 2025-04-22T21:35:02.396Z,1745357702.396 [DataOverHttps](INFO): SBD MOMSN=24712808 2025-04-22T21:35:05.454Z,1745357705.454 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T21:35:05.454Z,1745357705.454 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T21:35:05.455Z,1745357705.455 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T21:35:07.845Z,1745357707.845 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T21:36:10.059Z,1745357770.059 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2025-04-22T21:36:14.513Z,1745357774.513 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-22T21:36:14.514Z,1745357774.514 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 6. 2025-04-22T21:36:14.516Z,1745357774.516 [BPC1](INFO): Received data from all battery sticks. 2025-04-22T21:40:06.033Z,1745358006.033 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T21:40:06.033Z,1745358006.033 [Default:CheckIn:C.Wait] Stopped 2025-04-22T21:40:06.033Z,1745358006.033 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T21:40:06.034Z,1745358006.034 [Default:CheckIn:D] Running Loop=1 2025-04-22T21:40:06.436Z,1745358006.436 [Default:CheckIn:D] Stopped 2025-04-22T21:40:06.436Z,1745358006.436 [Default:CheckIn:E] Running Loop=1 2025-04-22T21:40:06.843Z,1745358006.843 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 392.782585 min 2025-04-22T21:40:06.843Z,1745358006.843 [Default:CheckIn:E] Stopped 2025-04-22T21:40:06.843Z,1745358006.843 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T21:40:06.843Z,1745358006.843 [Default:CheckIn] Stopped 2025-04-22T21:40:06.844Z,1745358006.844 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T21:40:06.844Z,1745358006.844 [Default:CheckIn](INFO): Running loop #38 2025-04-22T21:40:06.844Z,1745358006.844 [Default:CheckIn] Running Loop=38 2025-04-22T21:40:06.844Z,1745358006.844 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T21:40:06.844Z,1745358006.844 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T21:40:08.450Z,1745358008.450 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T21:40:08.450Z,1745358008.450 [NAL9602] Data Fault, FailCount= 4 2025-04-22T21:40:08.450Z,1745358008.450 [NAL9602](ERROR): Data Fault 2025-04-22T21:40:08.633Z,1745358008.633 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T21:40:08.850Z,1745358008.850 [NAL9602](INFO): Powering down 2025-04-22T21:40:09.813Z,1745358009.813 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T21:40:09.813Z,1745358009.813 [NAL9602] No Fault, FailCount= 4 2025-04-22T21:40:39.210Z,1745358039.210 [NAL9602](INFO): Powering up NAL9602 2025-04-22T21:40:50.122Z,1745358050.122 [NAL9602](INFO): NAL9602 initialized 2025-04-22T21:45:07.101Z,1745358307.101 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T21:40:06.8Z 2025-04-22T21:45:07.101Z,1745358307.101 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T21:45:07.102Z,1745358307.102 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T21:45:15.973Z,1745358315.973 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20250422T150719/Courier0111.lzma 2025-04-22T21:45:16.975Z,1745358316.975 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0111.lzma.bak 2025-04-22T21:45:16.975Z,1745358316.975 [DataOverHttps](INFO): SBD MOMSN=24712872 2025-04-22T21:45:35.089Z,1745358335.089 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250422T150719/Express0112.lzma 2025-04-22T21:45:36.091Z,1745358336.091 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0112.lzma.bak 2025-04-22T21:45:36.091Z,1745358336.091 [DataOverHttps](INFO): SBD MOMSN=24712887 2025-04-22T21:45:39.049Z,1745358339.049 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T21:45:39.049Z,1745358339.049 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T21:45:39.049Z,1745358339.049 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T21:45:53.570Z,1745358353.570 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T21:46:24.274Z,1745358384.274 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T21:48:14.713Z,1745358494.713 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-22T21:48:14.713Z,1745358494.713 [DropWeight] No Fault, FailCount= 1 2025-04-22T21:50:39.613Z,1745358639.613 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T21:50:39.613Z,1745358639.613 [Default:CheckIn:C.Wait] Stopped 2025-04-22T21:50:39.613Z,1745358639.613 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T21:50:39.613Z,1745358639.613 [Default:CheckIn:D] Running Loop=1 2025-04-22T21:50:40.023Z,1745358640.023 [Default:CheckIn:D] Stopped 2025-04-22T21:50:40.023Z,1745358640.023 [Default:CheckIn:E] Running Loop=1 2025-04-22T21:50:40.436Z,1745358640.436 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 403.342350 min 2025-04-22T21:50:40.436Z,1745358640.436 [Default:CheckIn:E] Stopped 2025-04-22T21:50:40.436Z,1745358640.436 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T21:50:40.436Z,1745358640.436 [Default:CheckIn] Stopped 2025-04-22T21:50:40.437Z,1745358640.437 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T21:50:40.441Z,1745358640.441 [Default:CheckIn](INFO): Running loop #39 2025-04-22T21:50:40.441Z,1745358640.441 [Default:CheckIn] Running Loop=39 2025-04-22T21:50:40.441Z,1745358640.441 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T21:50:40.441Z,1745358640.441 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T21:51:13.132Z,1745358673.132 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-22T21:51:13.213Z,1745358673.213 [NAL9602](ERROR): received: +CSQ:0 OK659, 2, 0, 0, 0 OK 2025-04-22T21:55:40.590Z,1745358940.590 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T21:50:40.4Z 2025-04-22T21:55:40.590Z,1745358940.590 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T21:55:40.590Z,1745358940.590 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T21:55:44.218Z,1745358944.218 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-22T21:55:47.473Z,1745358947.473 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250422T150719/Courier0114.lzma 2025-04-22T21:55:48.463Z,1745358948.463 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0114.lzma.bak 2025-04-22T21:55:48.463Z,1745358948.463 [DataOverHttps](INFO): SBD MOMSN=24712973 2025-04-22T21:56:06.569Z,1745358966.569 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20250422T150719/Express0115.lzma 2025-04-22T21:56:07.571Z,1745358967.571 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0115.lzma.bak 2025-04-22T21:56:07.571Z,1745358967.571 [DataOverHttps](INFO): SBD MOMSN=24712978 2025-04-22T21:56:10.506Z,1745358970.506 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T21:56:10.506Z,1745358970.506 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T21:56:10.506Z,1745358970.506 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T21:56:14.922Z,1745358974.922 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-22T22:01:11.060Z,1745359271.060 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-22T22:01:11.060Z,1745359271.060 [Default:CheckIn:C.Wait] Stopped 2025-04-22T22:01:11.060Z,1745359271.060 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T22:01:11.060Z,1745359271.060 [Default:CheckIn:D] Running Loop=1 2025-04-22T22:01:11.468Z,1745359271.468 [Default:CheckIn:D] Stopped 2025-04-22T22:01:11.469Z,1745359271.469 [Default:CheckIn:E] Running Loop=1 2025-04-22T22:01:11.880Z,1745359271.880 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 413.866471 min 2025-04-22T22:01:11.880Z,1745359271.880 [Default:CheckIn:E] Stopped 2025-04-22T22:01:11.880Z,1745359271.880 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-22T22:01:11.880Z,1745359271.880 [Default:CheckIn] Stopped 2025-04-22T22:01:11.880Z,1745359271.880 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T22:01:11.880Z,1745359271.880 [Default:CheckIn](INFO): Running loop #40 2025-04-22T22:01:11.881Z,1745359271.881 [Default:CheckIn] Running Loop=40 2025-04-22T22:01:11.881Z,1745359271.881 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-22T22:01:11.881Z,1745359271.881 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-22T22:01:13.481Z,1745359273.481 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-04-22T22:01:13.481Z,1745359273.481 [NAL9602] Data Fault, FailCount= 5 2025-04-22T22:01:13.481Z,1745359273.481 [NAL9602](ERROR): Data Fault 2025-04-22T22:01:13.645Z,1745359273.645 [CBIT](ERROR): Data Fault in component: NAL9602 2025-04-22T22:01:13.646Z,1745359273.646 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-04-22T22:01:13.881Z,1745359273.881 [NAL9602](INFO): Powering down 2025-04-22T22:01:14.051Z,1745359274.051 [CBIT](INFO): Critical error at 20250422T220113 2025-04-22T22:03:13.617Z,1745359393.617 [CBIT](INFO): Clearing failed state for component NAL9602 2025-04-22T22:03:13.617Z,1745359393.617 [NAL9602] No Fault, FailCount= 5 2025-04-22T22:03:13.870Z,1745359393.870 [NAL9602](INFO): Powering up NAL9602 2025-04-22T22:03:24.773Z,1745359404.773 [NAL9602](INFO): NAL9602 initialized 2025-04-22T22:06:12.046Z,1745359572.046 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-04-22T22:01:11.9Z 2025-04-22T22:06:12.046Z,1745359572.046 [Default:CheckIn:Read_GPS] Stopped 2025-04-22T22:06:12.046Z,1745359572.046 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-22T22:06:20.664Z,1745359580.664 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20250422T150719/Courier0117.lzma 2025-04-22T22:06:21.667Z,1745359581.667 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Courier0117.lzma.bak 2025-04-22T22:06:21.667Z,1745359581.667 [DataOverHttps](INFO): SBD MOMSN=24713077 2025-04-22T22:06:40.285Z,1745359600.285 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250422T150719/Express0118.lzma 2025-04-22T22:06:41.287Z,1745359601.287 [DataOverHttps](INFO): Moved sent file to Logs/20250422T150719/Express0118.lzma.bak 2025-04-22T22:06:41.287Z,1745359601.287 [DataOverHttps](INFO): SBD MOMSN=24713083 2025-04-22T22:06:44.380Z,1745359604.380 [Default:CheckIn:Read_Iridium] Stopped 2025-04-22T22:06:44.385Z,1745359604.385 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-22T22:06:44.385Z,1745359604.385 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-22T22:06:47.886Z,1745359607.886 [CommandExec](IMPORTANT): got command quit 2025-04-22T22:06:48.893Z,1745359608.893 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-22T22:06:48.893Z,1745359608.893 [CommandExec](INFO): Uninitializing the command executive. 2025-04-22T22:06:48.893Z,1745359608.893 [CommandExec](INFO): Uninitializing the command scheduler. 2025-04-22T22:06:48.894Z,1745359608.894 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:48.913Z,1745359608.913 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-04-22T22:06:48.913Z,1745359608.913 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-04-22T22:06:48.913Z,1745359608.913 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:48.914Z,1745359608.914 [NavChartDb](INFO): Join timeout helper Thread ID is 405 2025-04-22T22:06:49.003Z,1745359609.003 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-22T22:06:49.003Z,1745359609.003 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:49.014Z,1745359609.014 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-04-22T22:06:49.014Z,1745359609.014 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:49.014Z,1745359609.014 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 406 2025-04-22T22:06:49.089Z,1745359609.089 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-22T22:06:49.089Z,1745359609.089 [WetLabsBB2FL](INFO): Powering down 2025-04-22T22:06:49.090Z,1745359609.090 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:49.101Z,1745359609.101 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-04-22T22:06:49.101Z,1745359609.101 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:49.102Z,1745359609.102 [CTD_Seabird](INFO): Join timeout helper Thread ID is 407 2025-04-22T22:06:49.937Z,1745359609.937 [CTD_Seabird](INFO): Powering down 2025-04-22T22:06:49.954Z,1745359609.954 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-22T22:06:49.954Z,1745359609.954 [CTD_Seabird](INFO): Powering down 2025-04-22T22:06:49.965Z,1745359609.965 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:49.981Z,1745359609.981 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-04-22T22:06:49.981Z,1745359609.981 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:49.981Z,1745359609.981 [Radio_Surface](INFO): Join timeout helper Thread ID is 408 2025-04-22T22:06:50.245Z,1745359610.245 [Radio_Surface](INFO): Powering down 2025-04-22T22:06:50.246Z,1745359610.246 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-22T22:06:50.246Z,1745359610.246 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:50.262Z,1745359610.262 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-04-22T22:06:50.262Z,1745359610.262 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:50.263Z,1745359610.263 [Onboard](INFO): Join timeout helper Thread ID is 409 2025-04-22T22:06:50.288Z,1745359610.288 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-22T22:06:50.289Z,1745359610.289 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:50.303Z,1745359610.303 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-04-22T22:06:50.303Z,1745359610.303 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:50.304Z,1745359610.304 [DataOverHttps](INFO): Join timeout helper Thread ID is 410 2025-04-22T22:06:51.173Z,1745359611.173 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-22T22:06:51.176Z,1745359611.176 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:51.194Z,1745359611.194 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-04-22T22:06:51.194Z,1745359611.194 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:51.194Z,1745359611.194 [DAT](INFO): Join timeout helper Thread ID is 411 2025-04-22T22:06:51.395Z,1745359611.395 [DAT](INFO): Powering down 2025-04-22T22:06:51.462Z,1745359611.462 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-22T22:06:51.462Z,1745359611.462 [DAT](INFO): Powering down 2025-04-22T22:06:51.463Z,1745359611.463 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:51.478Z,1745359611.478 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-04-22T22:06:51.478Z,1745359611.478 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:51.478Z,1745359611.478 [BackseatComponent](INFO): Join timeout helper Thread ID is 412 2025-04-22T22:06:51.597Z,1745359611.597 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-22T22:06:51.598Z,1745359611.598 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:51.622Z,1745359611.622 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-04-22T22:06:51.622Z,1745359611.622 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:51.622Z,1745359611.622 [logger](INFO): Join timeout helper Thread ID is 413 2025-04-22T22:06:51.629Z,1745359611.629 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-22T22:06:51.629Z,1745359611.629 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:51.631Z,1745359611.631 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-04-22T22:06:51.631Z,1745359611.631 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:51.631Z,1745359611.631 [CommandLine](INFO): Join timeout helper Thread ID is 415 2025-04-22T22:06:51.661Z,1745359611.661 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-22T22:06:51.661Z,1745359611.661 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:51.674Z,1745359611.674 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-04-22T22:06:51.674Z,1745359611.674 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:51.674Z,1745359611.674 [CommandExec](INFO): Join timeout helper Thread ID is 416 2025-04-22T22:06:51.676Z,1745359611.676 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-04-22T22:06:51.676Z,1745359611.676 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:51.676Z,1745359611.676 [controlThread](INFO): Join timeout helper Thread ID is 417 2025-04-22T22:06:51.787Z,1745359611.787 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-22T22:06:51.787Z,1745359611.787 [controlThread](DEBUG): Uninitializing ControlThread 2025-04-22T22:06:51.787Z,1745359611.787 [AHRS_M2](INFO): Powering down 2025-04-22T22:06:51.858Z,1745359611.858 [NAL9602](INFO): Powering down 2025-04-22T22:06:51.859Z,1745359611.859 [Sonardyne_Nano](INFO): Powering down 2025-04-22T22:06:51.978Z,1745359611.978 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-04-22T22:06:51.979Z,1745359611.979 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-04-22T22:06:51.979Z,1745359611.979 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-04-22T22:06:51.980Z,1745359611.980 [MissionManager](INFO): Uninitializing Mission Default 2025-04-22T22:06:51.980Z,1745359611.980 [Default] Stopped 2025-04-22T22:06:51.980Z,1745359611.980 [Default](DEBUG): Aggregate::uninitialize Default 2025-04-22T22:06:51.980Z,1745359611.980 [Default:B.GoToSurface] Stopped 2025-04-22T22:06:51.980Z,1745359611.980 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-22T22:06:51.980Z,1745359611.980 [Default:CheckIn] Stopped 2025-04-22T22:06:51.980Z,1745359611.980 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-22T22:06:51.980Z,1745359611.980 [Default:CheckIn:C.Wait] Stopped 2025-04-22T22:06:51.980Z,1745359611.980 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-22T22:06:51.983Z,1745359611.983 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-04-22T22:06:51.983Z,1745359611.983 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-04-22T22:06:51.984Z,1745359611.984 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-04-22T22:06:51.984Z,1745359611.984 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-04-22T22:06:51.984Z,1745359611.984 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-04-22T22:06:51.984Z,1745359611.984 [BuoyancyServo](INFO): Powering down 2025-04-22T22:06:51.997Z,1745359611.997 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-04-22T22:06:51.997Z,1745359611.997 [MassServo](INFO): Powering down 2025-04-22T22:06:51.998Z,1745359611.998 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-04-22T22:06:51.998Z,1745359611.998 [RudderServo](INFO): Powering down 2025-04-22T22:06:51.999Z,1745359611.999 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-04-22T22:06:51.999Z,1745359611.999 [ThrusterHE](INFO): Powering down 2025-04-22T22:06:51.000Z,1745359612.000 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-04-22T22:06:52.000Z,1745359612.000 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-04-22T22:06:52.000Z,1745359612.000 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-04-22T22:06:52.000Z,1745359612.000 [CBIT](DEBUG): Powering off loads. 2025-04-22T22:06:52.013Z,1745359612.013 [CBIT](DEBUG): Disabling WDT. 2025-04-22T22:06:52.025Z,1745359612.025 [CBIT](DEBUG): Opening all GF detection circuits. 2025-04-22T22:06:52.026Z,1745359612.026 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:52.061Z,1745359612.061 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:52.064Z,1745359612.064 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:52.070Z,1745359612.070 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:52.077Z,1745359612.077 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:52.117Z,1745359612.117 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:52.324Z,1745359612.324 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:52.330Z,1745359612.330 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:52.348Z,1745359612.348 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-22T22:06:52.421Z,1745359612.421 [logger ThreadHandler](INFO): Thread cancelled.