2025-04-09T21:24:53.597Z,1744233893.597 [DataOverHttps](INFO): Received command: restart logs 2025-04-09T21:24:53.599Z,1744233893.599 [CommandExec](IMPORTANT): got command restart logs 2025-04-09T21:26:42.771Z,1744234002.771 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:26:42.771Z,1744234002.771 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:26:42.771Z,1744234002.771 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:26:42.772Z,1744234002.772 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:26:43.180Z,1744234003.180 [Default:CheckIn:D] Stopped 2025-04-09T21:26:43.180Z,1744234003.180 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:26:43.576Z,1744234003.576 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.319898 min 2025-04-09T21:26:43.576Z,1744234003.576 [Default:CheckIn:E] Stopped 2025-04-09T21:26:43.576Z,1744234003.576 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:26:43.576Z,1744234003.576 [Default:CheckIn] Stopped 2025-04-09T21:26:43.576Z,1744234003.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:26:43.576Z,1744234003.576 [Default:CheckIn](INFO): Running loop #3 2025-04-09T21:26:43.577Z,1744234003.577 [Default:CheckIn] Running Loop=3 2025-04-09T21:26:43.577Z,1744234003.577 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:26:43.593Z,1744234003.593 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:26:45.585Z,1744234005.585 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212645.00,A,3647.41962,N,12151.12795,W,1.011,113.59,090425,,,A*7E 2025-04-09T21:26:45.587Z,1744234005.587 [NAL9602](INFO): GPS fix at 20250409T212645: (36.790327, -121.852132) 2025-04-09T21:26:45.597Z,1744234005.597 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:26:45.597Z,1744234005.597 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:26:53.301Z,1744234013.301 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250409T013207/Courier0258.lzma 2025-04-09T21:26:54.303Z,1744234014.303 [DataOverHttps](INFO): Moved sent file to Logs/20250409T013207/Courier0258.lzma.bak 2025-04-09T21:26:54.303Z,1744234014.303 [DataOverHttps](INFO): SBD MOMSN=24600120 2025-04-09T21:27:02.160Z,1744234022.160 [NAL9602](INFO): SBD MO Status=0, MOMSN=18983, MT Status=0, MTMSN=0 2025-04-09T21:27:02.160Z,1744234022.160 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:27:32.853Z,1744234052.853 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:27:56.501Z,1744234076.501 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20250409T212453/Courier0000.lzma 2025-04-09T21:27:57.503Z,1744234077.503 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0000.lzma.bak 2025-04-09T21:27:57.503Z,1744234077.503 [DataOverHttps](INFO): SBD MOMSN=24600123 2025-04-09T21:28:30.301Z,1744234110.301 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20250409T013207/Express0259.lzma 2025-04-09T21:28:31.303Z,1744234111.303 [DataOverHttps](INFO): Moved sent file to Logs/20250409T013207/Express0259.lzma.bak 2025-04-09T21:28:31.304Z,1744234111.304 [DataOverHttps](INFO): SBD MOMSN=24600127 2025-04-09T21:29:20.863Z,1744234160.863 [DataOverHttps](INFO): Sending 479 bytes from file Logs/20250409T212453/Express0001.lzma 2025-04-09T21:29:21.859Z,1744234161.859 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0001.lzma.bak 2025-04-09T21:29:21.859Z,1744234161.859 [DataOverHttps](INFO): SBD MOMSN=24600138 2025-04-09T21:29:54.122Z,1744234194.122 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-04-09T21:30:22.525Z,1744234222.525 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.666595 2025-04-09T21:30:42.918Z,1744234242.918 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003738 2025-04-09T21:30:43.664Z,1744234243.664 [NAL9602](INFO): SBD MO Status=1, MOMSN=18984, MT Status=0, MTMSN=0 2025-04-09T21:30:43.725Z,1744234243.725 [NAL9602](INFO): Sent 61 bytes from file Logs/20250409T212453/Express0004.lzma 2025-04-09T21:30:43.725Z,1744234243.725 [NAL9602](INFO): Packets left to send: 0 2025-04-09T21:30:44.072Z,1744234244.072 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:30:44.073Z,1744234244.073 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:30:44.073Z,1744234244.073 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:31:14.362Z,1744234274.362 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:35:44.724Z,1744234544.724 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:35:44.724Z,1744234544.724 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:35:44.724Z,1744234544.724 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:35:44.724Z,1744234544.724 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:35:45.107Z,1744234545.107 [Default:CheckIn:D] Stopped 2025-04-09T21:35:45.108Z,1744234545.108 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:35:45.517Z,1744234545.517 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.352024 min 2025-04-09T21:35:45.518Z,1744234545.518 [Default:CheckIn:E] Stopped 2025-04-09T21:35:45.519Z,1744234545.519 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:35:45.519Z,1744234545.519 [Default:CheckIn] Stopped 2025-04-09T21:35:45.519Z,1744234545.519 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:35:45.519Z,1744234545.519 [Default:CheckIn](INFO): Running loop #4 2025-04-09T21:35:45.520Z,1744234545.520 [Default:CheckIn] Running Loop=4 2025-04-09T21:35:45.520Z,1744234545.520 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:35:45.520Z,1744234545.520 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:35:47.532Z,1744234547.532 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213548.00,A,3648.40810,N,12147.35754,W,5.443,46.37,090425,,,A*4A 2025-04-09T21:35:47.534Z,1744234547.534 [NAL9602](INFO): GPS fix at 20250409T213548: (36.806802, -121.789292) 2025-04-09T21:35:47.553Z,1744234547.553 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:35:47.553Z,1744234547.553 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:35:54.829Z,1744234554.829 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T212453/Courier0006.lzma 2025-04-09T21:35:55.831Z,1744234555.831 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0006.lzma.bak 2025-04-09T21:35:55.831Z,1744234555.831 [DataOverHttps](INFO): SBD MOMSN=24600234 2025-04-09T21:36:11.578Z,1744234571.578 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250409T212453/Express0007.lzma 2025-04-09T21:36:12.579Z,1744234572.579 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0007.lzma.bak 2025-04-09T21:36:12.579Z,1744234572.579 [DataOverHttps](INFO): SBD MOMSN=24600237 2025-04-09T21:36:13.829Z,1744234573.829 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:36:13.829Z,1744234573.829 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:36:13.829Z,1744234573.829 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:37:29.732Z,1744234649.732 [NAL9602](INFO): SBD MO Status=2, MOMSN=18985, MT Status=2, MTMSN=0 2025-04-09T21:37:29.732Z,1744234649.732 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T21:38:08.120Z,1744234688.120 [NAL9602](INFO): SBD MO Status=2, MOMSN=18985, MT Status=2, MTMSN=0 2025-04-09T21:38:08.120Z,1744234688.120 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T21:38:25.486Z,1744234705.486 [NAL9602](INFO): SBD MO Status=2, MOMSN=18985, MT Status=2, MTMSN=0 2025-04-09T21:38:25.486Z,1744234705.486 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T21:38:32.764Z,1744234712.764 [NAL9602](INFO): SBD MO Status=0, MOMSN=18985, MT Status=0, MTMSN=0 2025-04-09T21:38:32.764Z,1744234712.764 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:39:03.463Z,1744234743.463 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:41:14.392Z,1744234874.392 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:41:14.392Z,1744234874.392 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:41:14.392Z,1744234874.392 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:41:14.392Z,1744234874.392 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:41:14.784Z,1744234874.784 [Default:CheckIn:D] Stopped 2025-04-09T21:41:14.784Z,1744234874.784 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:41:15.189Z,1744234875.189 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.846629 min 2025-04-09T21:41:15.189Z,1744234875.189 [Default:CheckIn:E] Stopped 2025-04-09T21:41:15.189Z,1744234875.189 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:41:15.189Z,1744234875.189 [Default:CheckIn] Stopped 2025-04-09T21:41:15.189Z,1744234875.189 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:41:15.190Z,1744234875.190 [Default:CheckIn](INFO): Running loop #5 2025-04-09T21:41:15.190Z,1744234875.190 [Default:CheckIn] Running Loop=5 2025-04-09T21:41:15.190Z,1744234875.190 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:41:15.190Z,1744234875.190 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:41:17.200Z,1744234877.200 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214118.00,A,3648.26927,N,12147.13499,W,4.160,190.19,090425,,,D*79 2025-04-09T21:41:17.202Z,1744234877.202 [NAL9602](INFO): GPS fix at 20250409T214118: (36.804488, -121.785583) 2025-04-09T21:41:17.232Z,1744234877.232 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:41:17.232Z,1744234877.232 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:41:24.325Z,1744234884.325 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T212453/Courier0009.lzma 2025-04-09T21:41:25.327Z,1744234885.327 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0009.lzma.bak 2025-04-09T21:41:25.327Z,1744234885.327 [DataOverHttps](INFO): SBD MOMSN=24600273 2025-04-09T21:41:31.336Z,1744234891.336 [NAL9602](INFO): SBD MO Status=0, MOMSN=18986, MT Status=0, MTMSN=0 2025-04-09T21:41:31.336Z,1744234891.336 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:41:42.373Z,1744234902.373 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250409T212453/Express0010.lzma 2025-04-09T21:41:43.375Z,1744234903.375 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0010.lzma.bak 2025-04-09T21:41:43.375Z,1744234903.375 [DataOverHttps](INFO): SBD MOMSN=24600276 2025-04-09T21:41:44.692Z,1744234904.692 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:41:44.692Z,1744234904.692 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:41:44.692Z,1744234904.692 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:42:02.070Z,1744234922.070 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:46:45.293Z,1744235205.293 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:46:45.293Z,1744235205.293 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:46:45.293Z,1744235205.293 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:46:45.293Z,1744235205.293 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:46:45.687Z,1744235205.687 [Default:CheckIn:D] Stopped 2025-04-09T21:46:45.687Z,1744235205.687 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:46:46.096Z,1744235206.096 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.361688 min 2025-04-09T21:46:46.096Z,1744235206.096 [Default:CheckIn:E] Stopped 2025-04-09T21:46:46.096Z,1744235206.096 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:46:46.096Z,1744235206.096 [Default:CheckIn] Stopped 2025-04-09T21:46:46.096Z,1744235206.096 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:46:46.097Z,1744235206.097 [Default:CheckIn](INFO): Running loop #6 2025-04-09T21:46:46.097Z,1744235206.097 [Default:CheckIn] Running Loop=6 2025-04-09T21:46:46.097Z,1744235206.097 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:46:46.097Z,1744235206.097 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:46:48.108Z,1744235208.108 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214649.00,A,3648.15284,N,12147.22017,W,0.078,224.06,090425,,,D*76 2025-04-09T21:46:48.111Z,1744235208.111 [NAL9602](INFO): GPS fix at 20250409T214649: (36.802547, -121.787003) 2025-04-09T21:46:48.120Z,1744235208.120 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:46:48.120Z,1744235208.120 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:46:55.738Z,1744235215.738 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T212453/Courier0012.lzma 2025-04-09T21:46:56.739Z,1744235216.739 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0012.lzma.bak 2025-04-09T21:46:56.740Z,1744235216.740 [DataOverHttps](INFO): SBD MOMSN=24600334 2025-04-09T21:47:12.441Z,1744235232.441 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250409T212453/Express0013.lzma 2025-04-09T21:47:13.443Z,1744235233.443 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0013.lzma.bak 2025-04-09T21:47:13.443Z,1744235233.443 [DataOverHttps](INFO): SBD MOMSN=24600337 2025-04-09T21:47:14.786Z,1744235234.786 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:47:14.786Z,1744235234.786 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:47:14.786Z,1744235234.786 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:47:19.616Z,1744235239.616 [NAL9602](INFO): SBD MO Status=2, MOMSN=18987, MT Status=2, MTMSN=0 2025-04-09T21:47:19.616Z,1744235239.616 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T21:48:45.264Z,1744235325.264 [NAL9602](INFO): SBD MO Status=0, MOMSN=18987, MT Status=0, MTMSN=0 2025-04-09T21:48:45.264Z,1744235325.264 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:49:15.967Z,1744235355.967 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:52:15.362Z,1744235535.362 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:52:15.362Z,1744235535.362 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:52:15.362Z,1744235535.362 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:52:15.362Z,1744235535.362 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:52:15.774Z,1744235535.774 [Default:CheckIn:D] Stopped 2025-04-09T21:52:15.775Z,1744235535.775 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:52:16.167Z,1744235536.167 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.863139 min 2025-04-09T21:52:16.168Z,1744235536.168 [Default:CheckIn:E] Stopped 2025-04-09T21:52:16.168Z,1744235536.168 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:52:16.168Z,1744235536.168 [Default:CheckIn] Stopped 2025-04-09T21:52:16.168Z,1744235536.168 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:52:16.168Z,1744235536.168 [Default:CheckIn](INFO): Running loop #7 2025-04-09T21:52:16.168Z,1744235536.168 [Default:CheckIn] Running Loop=7 2025-04-09T21:52:16.168Z,1744235536.168 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:52:16.168Z,1744235536.168 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:52:18.180Z,1744235538.180 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215219.00,A,3648.15220,N,12147.22038,W,0.136,224.06,090425,,,A*7B 2025-04-09T21:52:18.183Z,1744235538.183 [NAL9602](INFO): GPS fix at 20250409T215219: (36.802537, -121.787006) 2025-04-09T21:52:18.193Z,1744235538.193 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:52:18.193Z,1744235538.193 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:52:25.102Z,1744235545.102 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T212453/Courier0015.lzma 2025-04-09T21:52:26.103Z,1744235546.103 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0015.lzma.bak 2025-04-09T21:52:26.103Z,1744235546.103 [DataOverHttps](INFO): SBD MOMSN=24600378 2025-04-09T21:52:35.148Z,1744235555.148 [NAL9602](INFO): SBD MO Status=0, MOMSN=18988, MT Status=0, MTMSN=0 2025-04-09T21:52:35.148Z,1744235555.148 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:52:41.849Z,1744235561.849 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250409T212453/Express0016.lzma 2025-04-09T21:52:42.851Z,1744235562.851 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0016.lzma.bak 2025-04-09T21:52:42.851Z,1744235562.851 [DataOverHttps](INFO): SBD MOMSN=24600383 2025-04-09T21:52:44.056Z,1744235564.056 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:52:44.056Z,1744235564.056 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:52:44.056Z,1744235564.056 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:53:05.858Z,1744235585.858 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T21:57:44.664Z,1744235864.664 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T21:57:44.664Z,1744235864.664 [Default:CheckIn:C.Wait] Stopped 2025-04-09T21:57:44.664Z,1744235864.664 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T21:57:44.664Z,1744235864.664 [Default:CheckIn:D] Running Loop=1 2025-04-09T21:57:45.062Z,1744235865.062 [Default:CheckIn:D] Stopped 2025-04-09T21:57:45.062Z,1744235865.062 [Default:CheckIn:E] Running Loop=1 2025-04-09T21:57:45.428Z,1744235865.428 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.351265 min 2025-04-09T21:57:45.428Z,1744235865.428 [Default:CheckIn:E] Stopped 2025-04-09T21:57:45.428Z,1744235865.428 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T21:57:45.429Z,1744235865.429 [Default:CheckIn] Stopped 2025-04-09T21:57:45.429Z,1744235865.429 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T21:57:45.429Z,1744235865.429 [Default:CheckIn](INFO): Running loop #8 2025-04-09T21:57:45.429Z,1744235865.429 [Default:CheckIn] Running Loop=8 2025-04-09T21:57:45.429Z,1744235865.429 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T21:57:45.429Z,1744235865.429 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T21:57:47.441Z,1744235867.441 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215748.00,A,3648.15386,N,12147.22478,W,0.000,224.06,090425,,,A*73 2025-04-09T21:57:47.443Z,1744235867.443 [NAL9602](INFO): GPS fix at 20250409T215748: (36.802564, -121.787080) 2025-04-09T21:57:47.486Z,1744235867.486 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T21:57:47.486Z,1744235867.486 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T21:57:57.009Z,1744235877.009 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T212453/Courier0018.lzma 2025-04-09T21:57:58.011Z,1744235878.011 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0018.lzma.bak 2025-04-09T21:57:58.011Z,1744235878.011 [DataOverHttps](INFO): SBD MOMSN=24600472 2025-04-09T21:58:13.757Z,1744235893.757 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250409T212453/Express0019.lzma 2025-04-09T21:58:14.759Z,1744235894.759 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0019.lzma.bak 2025-04-09T21:58:14.759Z,1744235894.759 [DataOverHttps](INFO): SBD MOMSN=24600475 2025-04-09T21:58:16.281Z,1744235896.281 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T21:58:16.282Z,1744235896.282 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T21:58:16.282Z,1744235896.282 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T21:58:23.944Z,1744235903.944 [NAL9602](INFO): SBD MO Status=0, MOMSN=18989, MT Status=0, MTMSN=0 2025-04-09T21:58:23.944Z,1744235903.944 [NAL9602](INFO): No messages in MT queue 2025-04-09T21:58:54.646Z,1744235934.646 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:03:16.907Z,1744236196.907 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:03:16.908Z,1744236196.908 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:03:16.908Z,1744236196.908 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:03:16.908Z,1744236196.908 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:03:17.319Z,1744236197.319 [Default:CheckIn:D] Stopped 2025-04-09T22:03:17.319Z,1744236197.319 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:03:17.719Z,1744236197.719 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.888888 min 2025-04-09T22:03:17.719Z,1744236197.719 [Default:CheckIn:E] Stopped 2025-04-09T22:03:17.719Z,1744236197.719 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:03:17.719Z,1744236197.719 [Default:CheckIn] Stopped 2025-04-09T22:03:17.719Z,1744236197.719 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:03:17.719Z,1744236197.719 [Default:CheckIn](INFO): Running loop #9 2025-04-09T22:03:17.719Z,1744236197.719 [Default:CheckIn] Running Loop=9 2025-04-09T22:03:17.719Z,1744236197.719 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:03:17.719Z,1744236197.719 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:03:19.742Z,1744236199.742 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220320.00,A,3648.15273,N,12147.22479,W,0.175,224.06,090425,,,A*76 2025-04-09T22:03:19.745Z,1744236199.745 [NAL9602](INFO): GPS fix at 20250409T220320: (36.802546, -121.787080) 2025-04-09T22:03:19.755Z,1744236199.755 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:03:19.755Z,1744236199.755 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:03:27.245Z,1744236207.245 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T212453/Courier0021.lzma 2025-04-09T22:03:28.248Z,1744236208.248 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0021.lzma.bak 2025-04-09T22:03:28.248Z,1744236208.248 [DataOverHttps](INFO): SBD MOMSN=24600564 2025-04-09T22:03:45.301Z,1744236225.301 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20250409T212453/Express0022.lzma 2025-04-09T22:03:46.304Z,1744236226.304 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0022.lzma.bak 2025-04-09T22:03:46.304Z,1744236226.304 [DataOverHttps](INFO): SBD MOMSN=24600567 2025-04-09T22:03:47.651Z,1744236227.651 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:03:47.651Z,1744236227.651 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:03:47.651Z,1744236227.651 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:04:37.360Z,1744236277.360 [NAL9602](INFO): SBD MO Status=0, MOMSN=18990, MT Status=0, MTMSN=0 2025-04-09T22:04:37.360Z,1744236277.360 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:05:08.055Z,1744236308.055 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:07:10.554Z,1744236430.554 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-04-09T22:07:23.828Z,1744236443.828 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2025-04-09T22:07:23.831Z,1744236443.831 [BPC1](INFO): Received data from all battery sticks. 2025-04-09T22:08:48.267Z,1744236528.267 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:08:48.267Z,1744236528.267 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:08:48.267Z,1744236528.267 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:08:48.268Z,1744236528.268 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:08:48.672Z,1744236528.672 [Default:CheckIn:D] Stopped 2025-04-09T22:08:48.672Z,1744236528.672 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:08:49.084Z,1744236529.084 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.411430 min 2025-04-09T22:08:49.085Z,1744236529.085 [Default:CheckIn:E] Stopped 2025-04-09T22:08:49.085Z,1744236529.085 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:08:49.085Z,1744236529.085 [Default:CheckIn] Stopped 2025-04-09T22:08:49.085Z,1744236529.085 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:08:49.085Z,1744236529.085 [Default:CheckIn](INFO): Running loop #10 2025-04-09T22:08:49.085Z,1744236529.085 [Default:CheckIn] Running Loop=10 2025-04-09T22:08:49.085Z,1744236529.085 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:08:49.085Z,1744236529.085 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:08:51.104Z,1744236531.104 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220852.00,A,3648.17807,N,12147.27785,W,0.447,288.90,090425,,,A*7B 2025-04-09T22:08:51.107Z,1744236531.107 [NAL9602](INFO): GPS fix at 20250409T220852: (36.802968, -121.787964) 2025-04-09T22:08:51.116Z,1744236531.116 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:08:51.117Z,1744236531.117 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:08:58.161Z,1744236538.161 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20250409T212453/Courier0024.lzma 2025-04-09T22:08:59.163Z,1744236539.163 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0024.lzma.bak 2025-04-09T22:08:59.163Z,1744236539.163 [DataOverHttps](INFO): SBD MOMSN=24600624 2025-04-09T22:09:15.821Z,1744236555.821 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250409T212453/Express0025.lzma 2025-04-09T22:09:17.827Z,1744236557.827 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0025.lzma.bak 2025-04-09T22:09:17.827Z,1744236557.827 [DataOverHttps](INFO): SBD MOMSN=24600627 2025-04-09T22:09:18.983Z,1744236558.983 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:09:18.983Z,1744236558.983 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:09:18.983Z,1744236558.983 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:10:50.268Z,1744236650.268 [NAL9602](INFO): SBD MO Status=0, MOMSN=18991, MT Status=0, MTMSN=0 2025-04-09T22:10:50.268Z,1744236650.268 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:11:20.970Z,1744236680.970 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:14:19.618Z,1744236859.618 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:14:19.618Z,1744236859.618 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:14:19.618Z,1744236859.618 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:14:19.619Z,1744236859.619 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:14:20.017Z,1744236860.017 [Default:CheckIn:D] Stopped 2025-04-09T22:14:20.017Z,1744236860.017 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:14:20.416Z,1744236860.416 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.933842 min 2025-04-09T22:14:20.416Z,1744236860.416 [Default:CheckIn:E] Stopped 2025-04-09T22:14:20.416Z,1744236860.416 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:14:20.416Z,1744236860.416 [Default:CheckIn] Stopped 2025-04-09T22:14:20.416Z,1744236860.416 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:14:20.416Z,1744236860.416 [Default:CheckIn](INFO): Running loop #11 2025-04-09T22:14:20.416Z,1744236860.416 [Default:CheckIn] Running Loop=11 2025-04-09T22:14:20.416Z,1744236860.416 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:14:20.416Z,1744236860.416 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:14:22.429Z,1744236862.429 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221423.00,A,3648.18233,N,12147.27738,W,0.019,288.90,090425,,,A*7B 2025-04-09T22:14:22.431Z,1744236862.431 [NAL9602](INFO): GPS fix at 20250409T221423: (36.803039, -121.787956) 2025-04-09T22:14:22.442Z,1744236862.442 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:14:22.442Z,1744236862.442 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:14:29.333Z,1744236869.333 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T212453/Courier0027.lzma 2025-04-09T22:14:30.335Z,1744236870.335 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0027.lzma.bak 2025-04-09T22:14:30.335Z,1744236870.335 [DataOverHttps](INFO): SBD MOMSN=24600662 2025-04-09T22:14:41.043Z,1744236881.043 [NAL9602](INFO): SBD MO Status=0, MOMSN=18992, MT Status=0, MTMSN=0 2025-04-09T22:14:41.043Z,1744236881.043 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:14:58.089Z,1744236898.089 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250409T212453/Express0028.lzma 2025-04-09T22:14:59.091Z,1744236899.091 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0028.lzma.bak 2025-04-09T22:14:59.091Z,1744236899.091 [DataOverHttps](INFO): SBD MOMSN=24600665 2025-04-09T22:15:00.433Z,1744236900.433 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:15:00.433Z,1744236900.433 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:15:00.433Z,1744236900.433 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:15:11.738Z,1744236911.738 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:20:01.056Z,1744237201.056 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:20:01.056Z,1744237201.056 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:20:01.056Z,1744237201.056 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:20:01.056Z,1744237201.056 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:20:01.452Z,1744237201.452 [Default:CheckIn:D] Stopped 2025-04-09T22:20:01.452Z,1744237201.452 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:20:01.852Z,1744237201.852 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.624430 min 2025-04-09T22:20:01.852Z,1744237201.852 [Default:CheckIn:E] Stopped 2025-04-09T22:20:01.852Z,1744237201.852 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:20:01.852Z,1744237201.852 [Default:CheckIn] Stopped 2025-04-09T22:20:01.852Z,1744237201.852 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:20:01.852Z,1744237201.852 [Default:CheckIn](INFO): Running loop #12 2025-04-09T22:20:01.852Z,1744237201.852 [Default:CheckIn] Running Loop=12 2025-04-09T22:20:01.852Z,1744237201.852 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:20:01.852Z,1744237201.852 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:20:03.866Z,1744237203.866 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222004.00,A,3648.17812,N,12147.27534,W,0.039,288.90,090425,,,A*73 2025-04-09T22:20:03.869Z,1744237203.869 [NAL9602](INFO): GPS fix at 20250409T222004: (36.802969, -121.787922) 2025-04-09T22:20:03.878Z,1744237203.878 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:20:03.878Z,1744237203.878 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:20:10.821Z,1744237210.821 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T212453/Courier0030.lzma 2025-04-09T22:20:11.823Z,1744237211.823 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0030.lzma.bak 2025-04-09T22:20:11.823Z,1744237211.823 [DataOverHttps](INFO): SBD MOMSN=24600725 2025-04-09T22:20:27.710Z,1744237227.710 [NAL9602](INFO): SBD MO Status=0, MOMSN=18993, MT Status=0, MTMSN=0 2025-04-09T22:20:27.711Z,1744237227.711 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:20:29.037Z,1744237229.037 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250409T212453/Express0031.lzma 2025-04-09T22:20:30.039Z,1744237230.039 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0031.lzma.bak 2025-04-09T22:20:30.039Z,1744237230.039 [DataOverHttps](INFO): SBD MOMSN=24600728 2025-04-09T22:20:31.344Z,1744237231.344 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:20:31.344Z,1744237231.344 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:20:31.344Z,1744237231.344 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:20:58.403Z,1744237258.403 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:22:17.038Z,1744237337.038 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.243717 2025-04-09T22:23:02.526Z,1744237382.526 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003702 2025-04-09T22:25:31.975Z,1744237531.975 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:25:31.975Z,1744237531.975 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:25:31.976Z,1744237531.976 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:25:31.976Z,1744237531.976 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:25:32.371Z,1744237532.371 [Default:CheckIn:D] Stopped 2025-04-09T22:25:32.371Z,1744237532.371 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:25:32.776Z,1744237532.776 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.139754 min 2025-04-09T22:25:32.776Z,1744237532.776 [Default:CheckIn:E] Stopped 2025-04-09T22:25:32.776Z,1744237532.776 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:25:32.776Z,1744237532.776 [Default:CheckIn] Stopped 2025-04-09T22:25:32.776Z,1744237532.776 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:25:32.776Z,1744237532.776 [Default:CheckIn](INFO): Running loop #13 2025-04-09T22:25:32.776Z,1744237532.776 [Default:CheckIn] Running Loop=13 2025-04-09T22:25:32.776Z,1744237532.776 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:25:32.776Z,1744237532.776 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:25:34.791Z,1744237534.791 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222535.00,A,3648.18585,N,12147.27065,W,0.253,288.90,090425,,,A*77 2025-04-09T22:25:34.793Z,1744237534.793 [NAL9602](INFO): GPS fix at 20250409T222535: (36.803097, -121.787844) 2025-04-09T22:25:34.803Z,1744237534.803 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:25:34.803Z,1744237534.803 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:25:42.661Z,1744237542.661 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250409T212453/Courier0033.lzma 2025-04-09T22:25:44.667Z,1744237544.667 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0033.lzma.bak 2025-04-09T22:25:44.667Z,1744237544.667 [DataOverHttps](INFO): SBD MOMSN=24600775 2025-04-09T22:25:46.545Z,1744237546.545 [NAL9602](INFO): SBD MO Status=0, MOMSN=18994, MT Status=0, MTMSN=0 2025-04-09T22:25:46.545Z,1744237546.545 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:26:08.173Z,1744237568.173 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250409T212453/Express0034.lzma 2025-04-09T22:26:09.175Z,1744237569.175 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0034.lzma.bak 2025-04-09T22:26:09.175Z,1744237569.175 [DataOverHttps](INFO): SBD MOMSN=24600779 2025-04-09T22:26:10.455Z,1744237570.455 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:26:10.456Z,1744237570.456 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:26:10.456Z,1744237570.456 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:26:17.310Z,1744237577.310 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:28:29.598Z,1744237709.598 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.243332 2025-04-09T22:28:38.998Z,1744237718.998 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003713 2025-04-09T22:31:11.067Z,1744237871.067 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:31:11.067Z,1744237871.067 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:31:11.067Z,1744237871.067 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:31:11.068Z,1744237871.068 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:31:11.469Z,1744237871.469 [Default:CheckIn:D] Stopped 2025-04-09T22:31:11.469Z,1744237871.469 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:31:11.874Z,1744237871.874 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.791390 min 2025-04-09T22:31:11.874Z,1744237871.874 [Default:CheckIn:E] Stopped 2025-04-09T22:31:11.874Z,1744237871.874 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:31:11.874Z,1744237871.874 [Default:CheckIn] Stopped 2025-04-09T22:31:11.874Z,1744237871.874 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:31:11.874Z,1744237871.874 [Default:CheckIn](INFO): Running loop #14 2025-04-09T22:31:11.874Z,1744237871.874 [Default:CheckIn] Running Loop=14 2025-04-09T22:31:11.875Z,1744237871.875 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:31:11.875Z,1744237871.875 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:31:13.889Z,1744237873.889 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223114.00,A,3648.17991,N,12147.27564,W,0.350,0.99,090425,,,A*7A 2025-04-09T22:31:13.899Z,1744237873.899 [NAL9602](INFO): GPS fix at 20250409T223114: (36.802999, -121.787927) 2025-04-09T22:31:13.917Z,1744237873.917 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:31:13.917Z,1744237873.917 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:31:20.813Z,1744237880.813 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T212453/Courier0036.lzma 2025-04-09T22:31:21.815Z,1744237881.815 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0036.lzma.bak 2025-04-09T22:31:21.815Z,1744237881.815 [DataOverHttps](INFO): SBD MOMSN=24600840 2025-04-09T22:31:30.856Z,1744237890.856 [NAL9602](INFO): SBD MO Status=0, MOMSN=18995, MT Status=0, MTMSN=0 2025-04-09T22:31:30.856Z,1744237890.856 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:31:46.401Z,1744237906.401 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250409T212453/Express0037.lzma 2025-04-09T22:31:52.423Z,1744237912.423 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0037.lzma.bak 2025-04-09T22:31:52.423Z,1744237912.423 [DataOverHttps](INFO): SBD MOMSN=24600843 2025-04-09T22:31:53.933Z,1744237913.933 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:31:53.933Z,1744237913.933 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:31:53.933Z,1744237913.933 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:32:01.596Z,1744237921.596 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:36:54.523Z,1744238214.523 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:36:54.523Z,1744238214.523 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:36:54.524Z,1744238214.524 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:36:54.524Z,1744238214.524 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:36:54.928Z,1744238214.928 [Default:CheckIn:D] Stopped 2025-04-09T22:36:54.928Z,1744238214.928 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:36:55.340Z,1744238215.340 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.515698 min 2025-04-09T22:36:55.340Z,1744238215.340 [Default:CheckIn:E] Stopped 2025-04-09T22:36:55.340Z,1744238215.340 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:36:55.340Z,1744238215.340 [Default:CheckIn] Stopped 2025-04-09T22:36:55.340Z,1744238215.340 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:36:55.340Z,1744238215.340 [Default:CheckIn](INFO): Running loop #15 2025-04-09T22:36:55.340Z,1744238215.340 [Default:CheckIn] Running Loop=15 2025-04-09T22:36:55.340Z,1744238215.340 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:36:55.340Z,1744238215.340 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:36:57.348Z,1744238217.348 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223658.00,A,3648.18210,N,12147.27738,W,0.330,2.11,090425,,,A*77 2025-04-09T22:36:57.351Z,1744238217.351 [NAL9602](INFO): GPS fix at 20250409T223658: (36.803035, -121.787956) 2025-04-09T22:36:57.380Z,1744238217.380 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:36:57.381Z,1744238217.381 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:37:11.233Z,1744238231.233 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T212453/Courier0039.lzma 2025-04-09T22:37:13.239Z,1744238233.239 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0039.lzma.bak 2025-04-09T22:37:13.239Z,1744238233.239 [DataOverHttps](INFO): SBD MOMSN=24600884 2025-04-09T22:37:33.881Z,1744238253.881 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250409T212453/Express0040.lzma 2025-04-09T22:37:34.883Z,1744238254.883 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0040.lzma.bak 2025-04-09T22:37:34.883Z,1744238254.883 [DataOverHttps](INFO): SBD MOMSN=24600893 2025-04-09T22:37:36.169Z,1744238256.169 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:37:36.170Z,1744238256.170 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:37:36.170Z,1744238256.170 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:38:09.756Z,1744238289.756 [NAL9602](INFO): SBD MO Status=2, MOMSN=18996, MT Status=2, MTMSN=0 2025-04-09T22:38:09.756Z,1744238289.756 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-09T22:38:44.902Z,1744238324.902 [NAL9602](INFO): SBD MO Status=0, MOMSN=18996, MT Status=0, MTMSN=0 2025-04-09T22:38:44.903Z,1744238324.903 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:39:15.602Z,1744238355.602 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:42:36.799Z,1744238556.799 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:42:36.799Z,1744238556.799 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:42:36.799Z,1744238556.799 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:42:36.800Z,1744238556.800 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:42:37.204Z,1744238557.204 [Default:CheckIn:D] Stopped 2025-04-09T22:42:37.204Z,1744238557.204 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:42:37.612Z,1744238557.612 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.220296 min 2025-04-09T22:42:37.612Z,1744238557.612 [Default:CheckIn:E] Stopped 2025-04-09T22:42:37.612Z,1744238557.612 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:42:37.612Z,1744238557.612 [Default:CheckIn] Stopped 2025-04-09T22:42:37.613Z,1744238557.613 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:42:37.613Z,1744238557.613 [Default:CheckIn](INFO): Running loop #16 2025-04-09T22:42:37.613Z,1744238557.613 [Default:CheckIn] Running Loop=16 2025-04-09T22:42:37.613Z,1744238557.613 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:42:37.613Z,1744238557.613 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:42:39.630Z,1744238559.630 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224240.00,A,3648.17848,N,12147.27762,W,0.156,359.95,090425,,,A*79 2025-04-09T22:42:39.632Z,1744238559.632 [NAL9602](INFO): GPS fix at 20250409T224240: (36.802975, -121.787960) 2025-04-09T22:42:39.642Z,1744238559.642 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:42:39.642Z,1744238559.642 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:42:46.833Z,1744238566.833 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T212453/Courier0042.lzma 2025-04-09T22:42:47.835Z,1744238567.835 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0042.lzma.bak 2025-04-09T22:42:47.835Z,1744238567.835 [DataOverHttps](INFO): SBD MOMSN=24600941 2025-04-09T22:42:53.360Z,1744238573.360 [NAL9602](INFO): SBD MO Status=0, MOMSN=18997, MT Status=0, MTMSN=0 2025-04-09T22:42:53.360Z,1744238573.360 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:43:03.613Z,1744238583.613 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250409T212453/Express0043.lzma 2025-04-09T22:43:04.615Z,1744238584.615 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0043.lzma.bak 2025-04-09T22:43:04.615Z,1744238584.615 [DataOverHttps](INFO): SBD MOMSN=24600944 2025-04-09T22:43:05.888Z,1744238585.888 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:43:05.888Z,1744238585.888 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:43:05.888Z,1744238585.888 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:43:24.062Z,1744238604.062 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:48:06.473Z,1744238886.473 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:48:06.473Z,1744238886.473 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:48:06.473Z,1744238886.473 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:48:06.473Z,1744238886.473 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:48:06.906Z,1744238886.906 [Default:CheckIn:D] Stopped 2025-04-09T22:48:06.906Z,1744238886.906 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:48:07.272Z,1744238887.272 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.715324 min 2025-04-09T22:48:07.272Z,1744238887.272 [Default:CheckIn:E] Stopped 2025-04-09T22:48:07.272Z,1744238887.272 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:48:07.273Z,1744238887.273 [Default:CheckIn] Stopped 2025-04-09T22:48:07.273Z,1744238887.273 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:48:07.273Z,1744238887.273 [Default:CheckIn](INFO): Running loop #17 2025-04-09T22:48:07.273Z,1744238887.273 [Default:CheckIn] Running Loop=17 2025-04-09T22:48:07.273Z,1744238887.273 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:48:07.273Z,1744238887.273 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:48:09.286Z,1744238889.286 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224810.00,A,3648.17601,N,12147.27807,W,0.447,11.16,090425,,,A*48 2025-04-09T22:48:09.288Z,1744238889.288 [NAL9602](INFO): GPS fix at 20250409T224810: (36.802934, -121.787968) 2025-04-09T22:48:09.298Z,1744238889.298 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:48:09.298Z,1744238889.298 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:48:16.564Z,1744238896.564 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T212453/Courier0045.lzma 2025-04-09T22:48:17.567Z,1744238897.567 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0045.lzma.bak 2025-04-09T22:48:17.567Z,1744238897.567 [DataOverHttps](INFO): SBD MOMSN=24600986 2025-04-09T22:48:31.128Z,1744238911.128 [NAL9602](INFO): SBD MO Status=0, MOMSN=18998, MT Status=0, MTMSN=0 2025-04-09T22:48:31.128Z,1744238911.128 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:48:33.321Z,1744238913.321 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250409T212453/Express0046.lzma 2025-04-09T22:48:35.327Z,1744238915.327 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0046.lzma.bak 2025-04-09T22:48:35.327Z,1744238915.327 [DataOverHttps](INFO): SBD MOMSN=24600989 2025-04-09T22:48:36.804Z,1744238916.804 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:48:36.804Z,1744238916.804 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:48:36.804Z,1744238916.804 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:49:01.832Z,1744238941.832 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:50:10.514Z,1744239010.514 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-09T22:50:10.514Z,1744239010.514 [DropWeight] Hardware Fault, FailCount= 1 2025-04-09T22:50:10.514Z,1744239010.514 [DropWeight](ERROR): Hardware Fault 2025-04-09T22:50:10.531Z,1744239010.531 [CommandExec](FAULT): Scheduling is paused 2025-04-09T22:50:10.531Z,1744239010.531 [CBIT](INFO): Critical error at 20250409T225010 2025-04-09T22:50:10.534Z,1744239010.534 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-09T22:50:10.534Z,1744239010.534 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-09T22:50:10.939Z,1744239010.939 [CBIT](INFO): Critical error at 20250409T225010 2025-04-09T22:53:37.379Z,1744239217.379 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:53:37.379Z,1744239217.379 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:53:37.379Z,1744239217.379 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:53:37.379Z,1744239217.379 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:53:37.785Z,1744239217.785 [Default:CheckIn:D] Stopped 2025-04-09T22:53:37.785Z,1744239217.785 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:53:38.179Z,1744239218.179 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.229972 min 2025-04-09T22:53:38.180Z,1744239218.180 [Default:CheckIn:E] Stopped 2025-04-09T22:53:38.180Z,1744239218.180 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:53:38.180Z,1744239218.180 [Default:CheckIn] Stopped 2025-04-09T22:53:38.180Z,1744239218.180 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:53:38.180Z,1744239218.180 [Default:CheckIn](INFO): Running loop #18 2025-04-09T22:53:38.180Z,1744239218.180 [Default:CheckIn] Running Loop=18 2025-04-09T22:53:38.180Z,1744239218.180 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:53:38.180Z,1744239218.180 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:53:40.196Z,1744239220.196 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225341.00,A,3648.17918,N,12147.27539,W,0.972,11.26,090425,,,A*49 2025-04-09T22:53:40.199Z,1744239220.199 [NAL9602](INFO): GPS fix at 20250409T225341: (36.802986, -121.787923) 2025-04-09T22:53:40.208Z,1744239220.208 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:53:40.208Z,1744239220.208 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:53:50.470Z,1744239230.470 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20250409T212453/Courier0048.lzma 2025-04-09T22:53:51.471Z,1744239231.471 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0048.lzma.bak 2025-04-09T22:53:51.471Z,1744239231.471 [DataOverHttps](INFO): SBD MOMSN=24601040 2025-04-09T22:54:08.263Z,1744239248.263 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20250409T212453/Express0049.lzma 2025-04-09T22:54:09.259Z,1744239249.259 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0049.lzma.bak 2025-04-09T22:54:09.259Z,1744239249.259 [DataOverHttps](INFO): SBD MOMSN=24601049 2025-04-09T22:54:10.548Z,1744239250.548 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:54:10.549Z,1744239250.549 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:54:10.549Z,1744239250.549 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:56:07.292Z,1744239367.292 [NAL9602](INFO): SBD MO Status=0, MOMSN=18999, MT Status=0, MTMSN=0 2025-04-09T22:56:07.292Z,1744239367.292 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:56:38.006Z,1744239398.006 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T22:59:11.171Z,1744239551.171 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T22:59:11.171Z,1744239551.171 [Default:CheckIn:C.Wait] Stopped 2025-04-09T22:59:11.171Z,1744239551.171 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T22:59:11.171Z,1744239551.171 [Default:CheckIn:D] Running Loop=1 2025-04-09T22:59:11.581Z,1744239551.581 [Default:CheckIn:D] Stopped 2025-04-09T22:59:11.581Z,1744239551.581 [Default:CheckIn:E] Running Loop=1 2025-04-09T22:59:11.972Z,1744239551.972 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.793254 min 2025-04-09T22:59:11.972Z,1744239551.972 [Default:CheckIn:E] Stopped 2025-04-09T22:59:11.972Z,1744239551.972 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T22:59:11.972Z,1744239551.972 [Default:CheckIn] Stopped 2025-04-09T22:59:11.972Z,1744239551.972 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T22:59:11.972Z,1744239551.972 [Default:CheckIn](INFO): Running loop #19 2025-04-09T22:59:11.972Z,1744239551.972 [Default:CheckIn] Running Loop=19 2025-04-09T22:59:11.972Z,1744239551.972 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T22:59:11.972Z,1744239551.972 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T22:59:13.993Z,1744239553.993 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225915.00,A,3648.17847,N,12147.27908,W,0.214,8.96,090425,,,A*7F 2025-04-09T22:59:13.995Z,1744239553.995 [NAL9602](INFO): GPS fix at 20250409T225915: (36.802974, -121.787985) 2025-04-09T22:59:14.005Z,1744239554.005 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T22:59:14.005Z,1744239554.005 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T22:59:21.389Z,1744239561.389 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T212453/Courier0051.lzma 2025-04-09T22:59:22.391Z,1744239562.391 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0051.lzma.bak 2025-04-09T22:59:22.391Z,1744239562.391 [DataOverHttps](INFO): SBD MOMSN=24601096 2025-04-09T22:59:28.528Z,1744239568.528 [NAL9602](INFO): SBD MO Status=0, MOMSN=19000, MT Status=0, MTMSN=0 2025-04-09T22:59:28.528Z,1744239568.528 [NAL9602](INFO): No messages in MT queue 2025-04-09T22:59:38.081Z,1744239578.081 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250409T212453/Express0052.lzma 2025-04-09T22:59:39.083Z,1744239579.083 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0052.lzma.bak 2025-04-09T22:59:39.083Z,1744239579.083 [DataOverHttps](INFO): SBD MOMSN=24601099 2025-04-09T22:59:40.306Z,1744239580.306 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T22:59:40.306Z,1744239580.306 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T22:59:40.306Z,1744239580.306 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T22:59:59.318Z,1744239599.318 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T23:04:40.921Z,1744239880.921 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:04:40.921Z,1744239880.921 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:04:40.921Z,1744239880.921 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:04:40.921Z,1744239880.921 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:04:41.312Z,1744239881.312 [Default:CheckIn:D] Stopped 2025-04-09T23:04:41.312Z,1744239881.312 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:04:41.719Z,1744239881.719 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.288761 min 2025-04-09T23:04:41.720Z,1744239881.720 [Default:CheckIn:E] Stopped 2025-04-09T23:04:41.720Z,1744239881.720 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:04:41.720Z,1744239881.720 [Default:CheckIn] Stopped 2025-04-09T23:04:41.720Z,1744239881.720 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:04:41.720Z,1744239881.720 [Default:CheckIn](INFO): Running loop #20 2025-04-09T23:04:41.720Z,1744239881.720 [Default:CheckIn] Running Loop=20 2025-04-09T23:04:41.720Z,1744239881.720 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:04:41.720Z,1744239881.720 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:04:43.740Z,1744239883.740 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230444.00,A,3648.17441,N,12147.28019,W,0.058,25.99,090425,,,A*44 2025-04-09T23:04:43.743Z,1744239883.743 [NAL9602](INFO): GPS fix at 20250409T230444: (36.802907, -121.788003) 2025-04-09T23:04:43.753Z,1744239883.753 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:04:43.754Z,1744239883.754 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:04:50.829Z,1744239890.829 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T212453/Courier0054.lzma 2025-04-09T23:04:52.002Z,1744239892.002 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0054.lzma.bak 2025-04-09T23:04:52.003Z,1744239892.003 [DataOverHttps](INFO): SBD MOMSN=24601205 2025-04-09T23:05:02.820Z,1744239902.820 [NAL9602](INFO): SBD MO Status=0, MOMSN=19001, MT Status=0, MTMSN=0 2025-04-09T23:05:02.820Z,1744239902.820 [NAL9602](INFO): No messages in MT queue 2025-04-09T23:05:07.597Z,1744239907.597 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250409T212453/Express0055.lzma 2025-04-09T23:05:08.599Z,1744239908.599 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0055.lzma.bak 2025-04-09T23:05:08.599Z,1744239908.599 [DataOverHttps](INFO): SBD MOMSN=24601208 2025-04-09T23:05:10.119Z,1744239910.119 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:05:10.119Z,1744239910.119 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:05:10.119Z,1744239910.119 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:05:10.924Z,1744239910.924 [CBIT](INFO): Clearing failed state for component DropWeight 2025-04-09T23:05:10.924Z,1744239910.924 [DropWeight] No Fault, FailCount= 1 2025-04-09T23:05:33.522Z,1744239933.522 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T23:10:10.742Z,1744240210.742 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:10:10.742Z,1744240210.742 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:10:10.742Z,1744240210.742 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:10:10.743Z,1744240210.743 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:10:11.145Z,1744240211.145 [Default:CheckIn:D] Stopped 2025-04-09T23:10:11.145Z,1744240211.145 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:10:11.555Z,1744240211.555 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.785978 min 2025-04-09T23:10:11.555Z,1744240211.555 [Default:CheckIn:E] Stopped 2025-04-09T23:10:11.555Z,1744240211.555 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:10:11.555Z,1744240211.555 [Default:CheckIn] Stopped 2025-04-09T23:10:11.556Z,1744240211.556 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:10:11.556Z,1744240211.556 [Default:CheckIn](INFO): Running loop #21 2025-04-09T23:10:11.556Z,1744240211.556 [Default:CheckIn] Running Loop=21 2025-04-09T23:10:11.556Z,1744240211.556 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:10:11.556Z,1744240211.556 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:10:13.556Z,1744240213.556 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231014.00,A,3648.17186,N,12147.27892,W,0.078,25.99,090425,,,A*4C 2025-04-09T23:10:13.559Z,1744240213.559 [NAL9602](INFO): GPS fix at 20250409T231014: (36.802864, -121.787982) 2025-04-09T23:10:13.569Z,1744240213.569 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:10:13.569Z,1744240213.569 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:10:21.220Z,1744240221.220 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T212453/Courier0057.lzma 2025-04-09T23:10:22.223Z,1744240222.223 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0057.lzma.bak 2025-04-09T23:10:22.223Z,1744240222.223 [DataOverHttps](INFO): SBD MOMSN=24601227 2025-04-09T23:10:24.468Z,1744240224.468 [NAL9602](INFO): SBD MO Status=0, MOMSN=19002, MT Status=0, MTMSN=0 2025-04-09T23:10:24.468Z,1744240224.468 [NAL9602](INFO): No messages in MT queue 2025-04-09T23:10:38.129Z,1744240238.129 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250409T212453/Express0058.lzma 2025-04-09T23:10:39.131Z,1744240239.131 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0058.lzma.bak 2025-04-09T23:10:39.132Z,1744240239.132 [DataOverHttps](INFO): SBD MOMSN=24601230 2025-04-09T23:10:40.636Z,1744240240.636 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:10:40.636Z,1744240240.636 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:10:40.636Z,1744240240.636 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:10:55.167Z,1744240255.167 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T23:14:48.332Z,1744240488.332 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2025-04-09T23:14:48.334Z,1744240488.334 [BPC1](INFO): Received data from all battery sticks. 2025-04-09T23:15:41.271Z,1744240541.271 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:15:41.271Z,1744240541.271 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:15:41.271Z,1744240541.271 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:15:41.272Z,1744240541.272 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:15:41.669Z,1744240541.669 [Default:CheckIn:D] Stopped 2025-04-09T23:15:41.669Z,1744240541.669 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:15:42.069Z,1744240542.069 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.294710 min 2025-04-09T23:15:42.069Z,1744240542.069 [Default:CheckIn:E] Stopped 2025-04-09T23:15:42.069Z,1744240542.069 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:15:42.069Z,1744240542.069 [Default:CheckIn] Stopped 2025-04-09T23:15:42.070Z,1744240542.070 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:15:42.070Z,1744240542.070 [Default:CheckIn](INFO): Running loop #22 2025-04-09T23:15:42.070Z,1744240542.070 [Default:CheckIn] Running Loop=22 2025-04-09T23:15:42.070Z,1744240542.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:15:42.070Z,1744240542.070 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:15:44.095Z,1744240544.095 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231545.00,A,3648.17428,N,12147.27901,W,0.078,25.99,090425,,,A*47 2025-04-09T23:15:44.097Z,1744240544.097 [NAL9602](INFO): GPS fix at 20250409T231545: (36.802905, -121.787983) 2025-04-09T23:15:44.106Z,1744240544.106 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:15:44.107Z,1744240544.107 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:15:54.017Z,1744240554.017 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20250409T212453/Courier0060.lzma 2025-04-09T23:15:55.019Z,1744240555.019 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0060.lzma.bak 2025-04-09T23:15:55.019Z,1744240555.019 [DataOverHttps](INFO): SBD MOMSN=24601310 2025-04-09T23:15:58.710Z,1744240558.710 [NAL9602](INFO): SBD MO Status=0, MOMSN=19003, MT Status=0, MTMSN=0 2025-04-09T23:15:58.710Z,1744240558.710 [NAL9602](INFO): No messages in MT queue 2025-04-09T23:16:11.045Z,1744240571.045 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250409T212453/Express0061.lzma 2025-04-09T23:16:12.047Z,1744240572.047 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0061.lzma.bak 2025-04-09T23:16:12.047Z,1744240572.047 [DataOverHttps](INFO): SBD MOMSN=24601313 2025-04-09T23:16:13.259Z,1744240573.259 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:16:13.259Z,1744240573.259 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:16:13.259Z,1744240573.259 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:16:29.402Z,1744240589.402 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T23:21:13.831Z,1744240873.831 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:21:13.831Z,1744240873.831 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:21:13.831Z,1744240873.831 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:21:13.832Z,1744240873.832 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:21:14.234Z,1744240874.234 [Default:CheckIn:D] Stopped 2025-04-09T23:21:14.234Z,1744240874.234 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:21:14.644Z,1744240874.644 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.837459 min 2025-04-09T23:21:14.644Z,1744240874.644 [Default:CheckIn:E] Stopped 2025-04-09T23:21:14.644Z,1744240874.644 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:21:14.644Z,1744240874.644 [Default:CheckIn] Stopped 2025-04-09T23:21:14.645Z,1744240874.645 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:21:14.649Z,1744240874.649 [Default:CheckIn](INFO): Running loop #23 2025-04-09T23:21:14.649Z,1744240874.649 [Default:CheckIn] Running Loop=23 2025-04-09T23:21:14.649Z,1744240874.649 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:21:14.649Z,1744240874.649 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:21:16.648Z,1744240876.648 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232117.00,A,3648.16469,N,12147.28394,W,0.330,0.00,090425,,,A*72 2025-04-09T23:21:16.651Z,1744240876.651 [NAL9602](INFO): GPS fix at 20250409T232117: (36.802745, -121.788066) 2025-04-09T23:21:16.661Z,1744240876.661 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:21:16.661Z,1744240876.661 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:21:23.985Z,1744240883.985 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T212453/Courier0063.lzma 2025-04-09T23:21:24.987Z,1744240884.987 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0063.lzma.bak 2025-04-09T23:21:24.987Z,1744240884.987 [DataOverHttps](INFO): SBD MOMSN=24601330 2025-04-09T23:21:40.777Z,1744240900.777 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250409T212453/Express0064.lzma 2025-04-09T23:21:41.779Z,1744240901.779 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0064.lzma.bak 2025-04-09T23:21:41.779Z,1744240901.779 [DataOverHttps](INFO): SBD MOMSN=24601333 2025-04-09T23:21:43.361Z,1744240903.361 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:21:43.361Z,1744240903.361 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:21:43.361Z,1744240903.361 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:26:18.503Z,1744241178.503 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-09T23:26:43.964Z,1744241203.964 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:26:43.964Z,1744241203.964 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:26:43.964Z,1744241203.964 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:26:43.965Z,1744241203.965 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:26:44.375Z,1744241204.375 [Default:CheckIn:D] Stopped 2025-04-09T23:26:44.376Z,1744241204.376 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:26:44.790Z,1744241204.790 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.339819 min 2025-04-09T23:26:44.791Z,1744241204.791 [Default:CheckIn:E] Stopped 2025-04-09T23:26:44.791Z,1744241204.791 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:26:44.791Z,1744241204.791 [Default:CheckIn] Stopped 2025-04-09T23:26:44.791Z,1744241204.791 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:26:44.791Z,1744241204.791 [Default:CheckIn](INFO): Running loop #24 2025-04-09T23:26:44.791Z,1744241204.791 [Default:CheckIn] Running Loop=24 2025-04-09T23:26:44.791Z,1744241204.791 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:26:44.791Z,1744241204.791 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:26:46.784Z,1744241206.784 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232647.00,A,3648.17342,N,12147.27514,W,2.819,276.48,090425,,,A*73 2025-04-09T23:26:46.787Z,1744241206.787 [NAL9602](INFO): GPS fix at 20250409T232647: (36.802890, -121.787919) 2025-04-09T23:26:46.797Z,1744241206.797 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:26:46.797Z,1744241206.797 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:26:54.101Z,1744241214.101 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T212453/Courier0066.lzma 2025-04-09T23:26:55.103Z,1744241215.103 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0066.lzma.bak 2025-04-09T23:26:55.103Z,1744241215.103 [DataOverHttps](INFO): SBD MOMSN=24601412 2025-04-09T23:27:10.793Z,1744241230.793 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20250409T212453/Express0067.lzma 2025-04-09T23:27:12.010Z,1744241232.010 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0067.lzma.bak 2025-04-09T23:27:12.010Z,1744241232.010 [DataOverHttps](INFO): SBD MOMSN=24601415 2025-04-09T23:27:13.064Z,1744241233.064 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:27:13.064Z,1744241233.064 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:27:13.064Z,1744241233.064 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:27:18.698Z,1744241238.698 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T23:32:13.663Z,1744241533.663 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:32:13.664Z,1744241533.664 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:32:13.664Z,1744241533.664 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:32:13.664Z,1744241533.664 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:32:14.067Z,1744241534.067 [Default:CheckIn:D] Stopped 2025-04-09T23:32:14.067Z,1744241534.067 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:32:14.477Z,1744241534.477 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.834684 min 2025-04-09T23:32:14.477Z,1744241534.477 [Default:CheckIn:E] Stopped 2025-04-09T23:32:14.477Z,1744241534.477 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:32:14.477Z,1744241534.477 [Default:CheckIn] Stopped 2025-04-09T23:32:14.477Z,1744241534.477 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:32:14.477Z,1744241534.477 [Default:CheckIn](INFO): Running loop #25 2025-04-09T23:32:14.478Z,1744241534.478 [Default:CheckIn] Running Loop=25 2025-04-09T23:32:14.478Z,1744241534.478 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:32:14.478Z,1744241534.478 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:32:16.480Z,1744241536.480 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233217.00,A,3648.18083,N,12147.28991,W,2.119,312.30,090425,,,A*79 2025-04-09T23:32:16.482Z,1744241536.483 [NAL9602](INFO): GPS fix at 20250409T233217: (36.803014, -121.788165) 2025-04-09T23:32:16.492Z,1744241536.492 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:32:16.492Z,1744241536.492 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:32:23.865Z,1744241543.865 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T212453/Courier0069.lzma 2025-04-09T23:32:24.867Z,1744241544.867 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0069.lzma.bak 2025-04-09T23:32:24.867Z,1744241544.867 [DataOverHttps](INFO): SBD MOMSN=24601431 2025-04-09T23:32:40.637Z,1744241560.637 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250409T212453/Express0070.lzma 2025-04-09T23:32:41.639Z,1744241561.639 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0070.lzma.bak 2025-04-09T23:32:41.639Z,1744241561.639 [DataOverHttps](INFO): SBD MOMSN=24601434 2025-04-09T23:32:43.153Z,1744241563.153 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:32:43.153Z,1744241563.153 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:32:43.153Z,1744241563.153 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:32:47.182Z,1744241567.182 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-09T23:32:47.265Z,1744241567.265 [NAL9602](ERROR): received: +CSQ:0 OK003, 0, 0, 0, 0 OK 2025-04-09T23:35:12.218Z,1744241712.218 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-04-09T23:35:12.218Z,1744241712.218 [DropWeight] Hardware Fault, FailCount= 1 2025-04-09T23:35:12.218Z,1744241712.218 [DropWeight](ERROR): Hardware Fault 2025-04-09T23:35:12.235Z,1744241712.235 [CBIT](INFO): Critical error at 20250409T233512 2025-04-09T23:35:12.238Z,1744241712.238 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-04-09T23:35:12.239Z,1744241712.239 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-04-09T23:35:12.651Z,1744241712.651 [CBIT](INFO): Critical error at 20250409T233512 2025-04-09T23:37:18.746Z,1744241838.746 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-09T23:37:43.796Z,1744241863.796 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:37:43.796Z,1744241863.796 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:37:43.796Z,1744241863.796 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:37:43.797Z,1744241863.797 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:37:44.212Z,1744241864.212 [Default:CheckIn:D] Stopped 2025-04-09T23:37:44.212Z,1744241864.212 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:37:44.628Z,1744241864.628 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 142.337093 min 2025-04-09T23:37:44.628Z,1744241864.628 [Default:CheckIn:E] Stopped 2025-04-09T23:37:44.628Z,1744241864.628 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:37:44.629Z,1744241864.629 [Default:CheckIn] Stopped 2025-04-09T23:37:44.629Z,1744241864.629 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:37:44.657Z,1744241864.657 [Default:CheckIn](INFO): Running loop #26 2025-04-09T23:37:44.657Z,1744241864.657 [Default:CheckIn] Running Loop=26 2025-04-09T23:37:44.657Z,1744241864.657 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:37:44.657Z,1744241864.657 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:37:46.616Z,1744241866.616 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233747.00,A,3648.17393,N,12147.27470,W,0.933,22.57,090425,,,A*4A 2025-04-09T23:37:46.650Z,1744241866.650 [NAL9602](INFO): GPS fix at 20250409T233747: (36.802899, -121.787912) 2025-04-09T23:37:46.659Z,1744241866.659 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:37:46.659Z,1744241866.659 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:37:56.573Z,1744241876.573 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250409T212453/Courier0072.lzma 2025-04-09T23:37:57.575Z,1744241877.575 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0072.lzma.bak 2025-04-09T23:37:57.575Z,1744241877.575 [DataOverHttps](INFO): SBD MOMSN=24601513 2025-04-09T23:38:14.297Z,1744241894.297 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20250409T212453/Express0073.lzma 2025-04-09T23:38:15.299Z,1744241895.299 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0073.lzma.bak 2025-04-09T23:38:15.299Z,1744241895.299 [DataOverHttps](INFO): SBD MOMSN=24601518 2025-04-09T23:38:16.575Z,1744241896.575 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:38:16.575Z,1744241896.575 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:38:16.576Z,1744241896.576 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:38:18.582Z,1744241898.582 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T23:43:17.207Z,1744242197.207 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:43:17.207Z,1744242197.207 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:43:17.207Z,1744242197.207 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:43:17.207Z,1744242197.207 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:43:17.603Z,1744242197.603 [Default:CheckIn:D] Stopped 2025-04-09T23:43:17.603Z,1744242197.603 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:43:18.004Z,1744242198.004 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.893604 min 2025-04-09T23:43:18.004Z,1744242198.004 [Default:CheckIn:E] Stopped 2025-04-09T23:43:18.004Z,1744242198.004 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:43:18.004Z,1744242198.004 [Default:CheckIn] Stopped 2025-04-09T23:43:18.005Z,1744242198.005 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:43:18.005Z,1744242198.005 [Default:CheckIn](INFO): Running loop #27 2025-04-09T23:43:18.005Z,1744242198.005 [Default:CheckIn] Running Loop=27 2025-04-09T23:43:18.005Z,1744242198.005 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:43:18.005Z,1744242198.005 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:43:20.024Z,1744242200.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234321.00,A,3648.16044,N,12147.26919,W,0.641,294.16,090425,,,A*72 2025-04-09T23:43:20.026Z,1744242200.026 [NAL9602](INFO): GPS fix at 20250409T234321: (36.802674, -121.787820) 2025-04-09T23:43:20.036Z,1744242200.036 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:43:20.036Z,1744242200.036 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:43:27.653Z,1744242207.653 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250409T212453/Courier0075.lzma 2025-04-09T23:43:28.655Z,1744242208.655 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0075.lzma.bak 2025-04-09T23:43:28.655Z,1744242208.655 [DataOverHttps](INFO): SBD MOMSN=24601547 2025-04-09T23:43:44.389Z,1744242224.389 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250409T212453/Express0076.lzma 2025-04-09T23:43:45.391Z,1744242225.391 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0076.lzma.bak 2025-04-09T23:43:45.391Z,1744242225.391 [DataOverHttps](INFO): SBD MOMSN=24601550 2025-04-09T23:43:46.717Z,1744242226.717 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:43:46.718Z,1744242226.718 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:43:46.718Z,1744242226.718 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:43:50.747Z,1744242230.747 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-04-09T23:43:50.821Z,1744242230.821 [NAL9602](ERROR): received: +CSQ:0 OK003, 0, 0, 0, 0 OK 2025-04-09T23:48:22.674Z,1744242502.674 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-09T23:48:47.337Z,1744242527.337 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-09T23:48:47.337Z,1744242527.337 [Default:CheckIn:C.Wait] Stopped 2025-04-09T23:48:47.337Z,1744242527.337 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-09T23:48:47.337Z,1744242527.337 [Default:CheckIn:D] Running Loop=1 2025-04-09T23:48:47.728Z,1744242527.728 [Default:CheckIn:D] Stopped 2025-04-09T23:48:47.728Z,1744242527.728 [Default:CheckIn:E] Running Loop=1 2025-04-09T23:48:48.132Z,1744242528.132 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 153.395703 min 2025-04-09T23:48:48.132Z,1744242528.132 [Default:CheckIn:E] Stopped 2025-04-09T23:48:48.132Z,1744242528.132 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-09T23:48:48.132Z,1744242528.132 [Default:CheckIn] Stopped 2025-04-09T23:48:48.132Z,1744242528.132 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-09T23:48:48.132Z,1744242528.132 [Default:CheckIn](INFO): Running loop #28 2025-04-09T23:48:48.132Z,1744242528.132 [Default:CheckIn] Running Loop=28 2025-04-09T23:48:48.132Z,1744242528.132 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-09T23:48:48.132Z,1744242528.132 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-09T23:48:50.146Z,1744242530.146 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234851.00,A,3648.18707,N,12147.28112,W,1.166,0.53,090425,,,A*70 2025-04-09T23:48:50.148Z,1744242530.148 [NAL9602](INFO): GPS fix at 20250409T234851: (36.803118, -121.788019) 2025-04-09T23:48:50.158Z,1744242530.158 [Default:CheckIn:Read_GPS] Stopped 2025-04-09T23:48:50.158Z,1744242530.158 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-09T23:48:57.680Z,1744242537.680 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250409T212453/Courier0078.lzma 2025-04-09T23:48:58.683Z,1744242538.683 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Courier0078.lzma.bak 2025-04-09T23:48:58.683Z,1744242538.683 [DataOverHttps](INFO): SBD MOMSN=24601628 2025-04-09T23:49:10.078Z,1744242550.078 [CommandExec](IMPORTANT): got command quit 2025-04-09T23:49:11.081Z,1744242551.081 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:49:11.081Z,1744242551.081 [CommandExec](INFO): Uninitializing the command executive. 2025-04-09T23:49:11.081Z,1744242551.081 [CommandExec](INFO): Uninitializing the command scheduler. 2025-04-09T23:49:11.081Z,1744242551.081 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:49:11.213Z,1744242551.213 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-04-09T23:49:11.213Z,1744242551.213 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-04-09T23:49:11.213Z,1744242551.213 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:49:11.214Z,1744242551.214 [NavChartDb](INFO): Join timeout helper Thread ID is 6069 2025-04-09T23:49:11.285Z,1744242551.285 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:49:11.286Z,1744242551.286 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:49:11.289Z,1744242551.289 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-04-09T23:49:11.289Z,1744242551.289 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:49:11.290Z,1744242551.290 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6070 2025-04-09T23:49:11.453Z,1744242551.453 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:49:11.454Z,1744242551.454 [WetLabsBB2FL](INFO): Powering down 2025-04-09T23:49:11.454Z,1744242551.454 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:49:11.457Z,1744242551.457 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-04-09T23:49:11.458Z,1744242551.458 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:49:11.458Z,1744242551.458 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6071 2025-04-09T23:49:11.705Z,1744242551.705 [CTD_Seabird](INFO): Powering down 2025-04-09T23:49:11.717Z,1744242551.717 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-09T23:49:11.717Z,1744242551.717 [CTD_Seabird](INFO): Powering down 2025-04-09T23:49:11.729Z,1744242551.729 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:49:11.752Z,1744242551.752 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-04-09T23:49:11.752Z,1744242551.752 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-09T23:49:11.752Z,1744242551.752 [Radio_Surface](INFO): Join timeout helper Thread ID is 6072 2025-04-09T23:49:14.517Z,1744242554.517 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20250409T212453/Express0079.lzma 2025-04-09T23:49:15.519Z,1744242555.519 [DataOverHttps](INFO): Moved sent file to Logs/20250409T212453/Express0079.lzma.bak 2025-04-09T23:49:15.519Z,1744242555.519 [DataOverHttps](INFO): SBD MOMSN=24601631 2025-04-09T23:49:16.828Z,1744242556.828 [Default:CheckIn:Read_Iridium] Stopped 2025-04-09T23:49:16.828Z,1744242556.828 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-09T23:49:16.828Z,1744242556.828 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-09T23:49:22.462Z,1744242562.462 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-09T23:49:42.230Z,1744242582.230 [BackseatComponent](CRITICAL): SIGSEGV: address not mapped to object 2025-04-09T23:49:42.230Z,1744242582.230 [Unknown] 2025-04-09T23:49:42.230Z,1744242582.230 [ 2025-04-09T23:49:42.259Z,1744242582.259 [ 2025-04-09T23:49:42.259Z,1744242582.259 [Unknown] 2025-04-09T23:49:42.259Z,1744242582.259 [ 2025-04-09T23:49:42.293Z,1744242582.293 [ 2025-04-09T23:49:42.305Z,1744242582.305 [ 2025-04-09T23:49:42.305Z,1744242582.305 [ 2025-04-09T23:49:42.329Z,1744242582.329 [ 2025-04-09T23:49:42.329Z,1744242582.329 [Unknown] 2025-04-09T23:49:42.330Z,1744242582.330 [ 2025-04-09T23:49:42.348Z,1744242582.348 [ 2025-04-09T23:49:42.365Z,1744242582.365 [ 2025-04-09T23:49:42.366Z,1744242582.366 [ 2025-04-09T23:49:42.394Z,1744242582.394 [ 2025-04-09T23:49:42.394Z,1744242582.394 [Unknown] 2025-04-09T23:49:42.394Z,1744242582.394 [ 2025-04-09T23:49:42.413Z,1744242582.413 [ 2025-04-09T23:49:42.422Z,1744242582.422 [ 2025-04-09T23:49:42.422Z,1744242582.422 [ 2025-04-09T23:49:42.443Z,1744242582.443 [ 2025-04-09T23:49:42.443Z,1744242582.443 [Unknown] 2025-04-09T23:49:42.443Z,1744242582.443 [ 2025-04-09T23:49:42.462Z,1744242582.462 [ 2025-04-09T23:49:42.477Z,1744242582.477 [ 2025-04-09T23:49:42.477Z,1744242582.477 [ 2025-04-09T23:49:42.495Z,1744242582.495 [ 2025-04-09T23:49:42.495Z,1744242582.495 [ 2025-04-09T23:49:42.495Z,1744242582.495 [ 2025-04-09T23:49:42.520Z,1744242582.520 [ 2025-04-09T23:49:42.553Z,1744242582.553 [ 2025-04-09T23:49:42.553Z,1744242582.553 [ 2025-04-09T23:49:42.571Z,1744242582.571 [ 2025-04-09T23:49:42.571Z,1744242582.571 [ 2025-04-09T23:49:42.571Z,1744242582.571 [ 2025-04-09T23:49:42.572Z,1744242582.572 [ 2025-04-09T23:49:42.573Z,1744242582.573 [ 2025-04-09T23:49:42.602Z,1744242582.602 [