2024-04-17T17:24:56.948Z,1713374696.948 [CommandExec](IMPORTANT): got command restart logs 2024-04-17T17:28:06.901Z,1713374886.901 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T17:28:06.902Z,1713374886.902 [Default:CheckIn:C.Wait] Stopped 2024-04-17T17:28:06.902Z,1713374886.902 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T17:28:06.902Z,1713374886.902 [Default:CheckIn:D] Running Loop=1 2024-04-17T17:28:07.303Z,1713374887.303 [Default:CheckIn:D] Stopped 2024-04-17T17:28:07.303Z,1713374887.303 [Default:CheckIn:E] Running Loop=1 2024-04-17T17:28:07.690Z,1713374887.690 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.323918 min 2024-04-17T17:28:07.690Z,1713374887.690 [Default:CheckIn:E] Stopped 2024-04-17T17:28:07.691Z,1713374887.691 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T17:28:07.691Z,1713374887.691 [Default:CheckIn] Stopped 2024-04-17T17:28:07.691Z,1713374887.691 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T17:28:07.691Z,1713374887.691 [Default:CheckIn](INFO): Running loop #2 2024-04-17T17:28:07.691Z,1713374887.691 [Default:CheckIn] Running Loop=2 2024-04-17T17:28:07.691Z,1713374887.691 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T17:28:07.691Z,1713374887.691 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T17:28:09.699Z,1713374889.699 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172808.00,A,3647.92450,N,12150.30935,W,17.747,74.78,170424,,,D*70 2024-04-17T17:28:09.701Z,1713374889.701 [NAL9602](INFO): GPS fix at 20240417T172808: (36.798742, -121.838489) 2024-04-17T17:28:09.712Z,1713374889.712 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T17:28:09.712Z,1713374889.712 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T17:28:17.094Z,1713374897.094 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240415T184452/Courier0216.lzma 2024-04-17T17:28:18.097Z,1713374898.097 [DataOverHttps](INFO): Moved sent file to Logs/20240415T184452/Courier0216.lzma.bak 2024-04-17T17:28:18.097Z,1713374898.097 [DataOverHttps](INFO): SBD MOMSN=19413753 2024-04-17T17:28:36.361Z,1713374916.361 [NAL9602](INFO): SBD MO Status=0, MOMSN=49761, MT Status=0, MTMSN=0 2024-04-17T17:28:36.361Z,1713374916.361 [NAL9602](INFO): No messages in MT queue 2024-04-17T17:28:53.799Z,1713374933.799 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20240417T172456/Courier0000.lzma 2024-04-17T17:28:54.800Z,1713374934.800 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0000.lzma.bak 2024-04-17T17:28:54.800Z,1713374934.800 [DataOverHttps](INFO): SBD MOMSN=19413756 2024-04-17T17:29:07.099Z,1713374947.099 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T17:29:11.242Z,1713374951.242 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240417T172456/Courier0003.lzma 2024-04-17T17:29:12.245Z,1713374952.245 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0003.lzma.bak 2024-04-17T17:29:12.245Z,1713374952.245 [DataOverHttps](INFO): SBD MOMSN=19413760 2024-04-17T17:29:28.270Z,1713374968.270 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20240415T184452/Express0217.lzma 2024-04-17T17:29:29.273Z,1713374969.273 [DataOverHttps](INFO): Moved sent file to Logs/20240415T184452/Express0217.lzma.bak 2024-04-17T17:29:29.273Z,1713374969.273 [DataOverHttps](INFO): SBD MOMSN=19413762 2024-04-17T17:30:41.047Z,1713375041.047 [DataOverHttps](INFO): Sending 389 bytes from file Logs/20240417T172456/Express0001.lzma 2024-04-17T17:30:42.048Z,1713375042.048 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0001.lzma.bak 2024-04-17T17:30:42.048Z,1713375042.048 [DataOverHttps](INFO): SBD MOMSN=19413766 2024-04-17T17:31:51.942Z,1713375111.942 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240417T172456/Express0004.lzma 2024-04-17T17:31:52.944Z,1713375112.944 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0004.lzma.bak 2024-04-17T17:31:52.944Z,1713375112.944 [DataOverHttps](INFO): SBD MOMSN=19413770 2024-04-17T17:31:54.398Z,1713375114.398 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T17:31:54.399Z,1713375114.399 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T17:31:54.399Z,1713375114.399 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T17:34:26.283Z,1713375266.283 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-17T17:34:57.407Z,1713375297.407 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-17T17:36:55.026Z,1713375415.026 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T17:36:55.026Z,1713375415.026 [Default:CheckIn:C.Wait] Stopped 2024-04-17T17:36:55.026Z,1713375415.026 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T17:36:55.027Z,1713375415.027 [Default:CheckIn:D] Running Loop=1 2024-04-17T17:36:55.431Z,1713375415.431 [Default:CheckIn:D] Stopped 2024-04-17T17:36:55.431Z,1713375415.431 [Default:CheckIn:E] Running Loop=1 2024-04-17T17:36:55.849Z,1713375415.849 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.126056 min 2024-04-17T17:36:55.849Z,1713375415.849 [Default:CheckIn:E] Stopped 2024-04-17T17:36:55.849Z,1713375415.849 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T17:36:55.849Z,1713375415.849 [Default:CheckIn] Stopped 2024-04-17T17:36:55.849Z,1713375415.849 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T17:36:55.849Z,1713375415.849 [Default:CheckIn](INFO): Running loop #3 2024-04-17T17:36:55.849Z,1713375415.849 [Default:CheckIn] Running Loop=3 2024-04-17T17:36:55.850Z,1713375415.850 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T17:36:55.850Z,1713375415.850 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T17:36:57.849Z,1713375417.849 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173657.00,A,3648.41504,N,12149.32533,W,1.516,256.93,170424,,,D*72 2024-04-17T17:36:57.852Z,1713375417.852 [NAL9602](INFO): GPS fix at 20240417T173657: (36.806917, -121.822089) 2024-04-17T17:36:57.863Z,1713375417.863 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T17:36:57.863Z,1713375417.863 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T17:37:13.609Z,1713375433.609 [NAL9602](INFO): SBD MO Status=0, MOMSN=49762, MT Status=0, MTMSN=0 2024-04-17T17:37:13.609Z,1713375433.609 [NAL9602](INFO): No messages in MT queue 2024-04-17T17:37:15.262Z,1713375435.262 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240417T172456/Courier0006.lzma 2024-04-17T17:37:16.264Z,1713375436.264 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0006.lzma.bak 2024-04-17T17:37:16.264Z,1713375436.264 [DataOverHttps](INFO): SBD MOMSN=19413772 2024-04-17T17:37:44.304Z,1713375464.304 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T17:38:04.818Z,1713375484.818 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240417T172456/Express0007.lzma 2024-04-17T17:38:05.820Z,1713375485.820 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0007.lzma.bak 2024-04-17T17:38:05.820Z,1713375485.820 [DataOverHttps](INFO): SBD MOMSN=19413776 2024-04-17T17:38:06.949Z,1713375486.949 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T17:38:06.950Z,1713375486.950 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T17:38:06.950Z,1713375486.950 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T17:42:13.781Z,1713375733.781 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-04-17T17:43:07.551Z,1713375787.551 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T17:43:07.551Z,1713375787.551 [Default:CheckIn:C.Wait] Stopped 2024-04-17T17:43:07.551Z,1713375787.551 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T17:43:07.551Z,1713375787.551 [Default:CheckIn:D] Running Loop=1 2024-04-17T17:43:07.956Z,1713375787.956 [Default:CheckIn:D] Stopped 2024-04-17T17:43:07.956Z,1713375787.956 [Default:CheckIn:E] Running Loop=1 2024-04-17T17:43:08.371Z,1713375788.371 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.334808 min 2024-04-17T17:43:08.371Z,1713375788.371 [Default:CheckIn:E] Stopped 2024-04-17T17:43:08.371Z,1713375788.371 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T17:43:08.371Z,1713375788.371 [Default:CheckIn] Stopped 2024-04-17T17:43:08.371Z,1713375788.371 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T17:43:08.371Z,1713375788.371 [Default:CheckIn](INFO): Running loop #4 2024-04-17T17:43:08.371Z,1713375788.371 [Default:CheckIn] Running Loop=4 2024-04-17T17:43:08.371Z,1713375788.371 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T17:43:08.372Z,1713375788.372 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T17:43:10.373Z,1713375790.373 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174309.00,A,3648.35857,N,12149.16953,W,4.335,123.65,170424,,,D*75 2024-04-17T17:43:10.376Z,1713375790.376 [NAL9602](INFO): GPS fix at 20240417T174309: (36.805976, -121.819492) 2024-04-17T17:43:10.387Z,1713375790.387 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T17:43:10.387Z,1713375790.387 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T17:43:17.691Z,1713375797.691 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240417T172456/Courier0009.lzma 2024-04-17T17:43:18.721Z,1713375798.721 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0009.lzma.bak 2024-04-17T17:43:18.721Z,1713375798.721 [DataOverHttps](INFO): SBD MOMSN=19413781 2024-04-17T17:43:34.675Z,1713375814.675 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240417T172456/Express0010.lzma 2024-04-17T17:43:35.017Z,1713375815.017 [NAL9602](INFO): SBD MO Status=0, MOMSN=49763, MT Status=0, MTMSN=0 2024-04-17T17:43:35.017Z,1713375815.017 [NAL9602](INFO): No messages in MT queue 2024-04-17T17:43:35.668Z,1713375815.668 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0010.lzma.bak 2024-04-17T17:43:35.668Z,1713375815.668 [DataOverHttps](INFO): SBD MOMSN=19413784 2024-04-17T17:43:37.047Z,1713375817.047 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T17:43:37.047Z,1713375817.047 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T17:43:37.047Z,1713375817.047 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T17:44:05.715Z,1713375845.715 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T17:48:37.677Z,1713376117.677 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T17:48:37.677Z,1713376117.677 [Default:CheckIn:C.Wait] Stopped 2024-04-17T17:48:37.677Z,1713376117.677 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T17:48:37.677Z,1713376117.677 [Default:CheckIn:D] Running Loop=1 2024-04-17T17:48:38.089Z,1713376118.089 [Default:CheckIn:D] Stopped 2024-04-17T17:48:38.089Z,1713376118.089 [Default:CheckIn:E] Running Loop=1 2024-04-17T17:48:38.498Z,1713376118.498 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.837022 min 2024-04-17T17:48:38.498Z,1713376118.498 [Default:CheckIn:E] Stopped 2024-04-17T17:48:38.498Z,1713376118.498 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T17:48:38.499Z,1713376118.499 [Default:CheckIn] Stopped 2024-04-17T17:48:38.499Z,1713376118.499 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T17:48:38.499Z,1713376118.499 [Default:CheckIn](INFO): Running loop #5 2024-04-17T17:48:38.499Z,1713376118.499 [Default:CheckIn] Running Loop=5 2024-04-17T17:48:38.499Z,1713376118.499 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T17:48:38.499Z,1713376118.499 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T17:48:40.495Z,1713376120.495 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174839.00,A,3648.23534,N,12148.72135,W,3.907,111.31,170424,,,D*75 2024-04-17T17:48:40.497Z,1713376120.497 [NAL9602](INFO): GPS fix at 20240417T174839: (36.803922, -121.812022) 2024-04-17T17:48:40.508Z,1713376120.508 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T17:48:40.508Z,1713376120.508 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T17:48:47.610Z,1713376127.610 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240417T172456/Courier0012.lzma 2024-04-17T17:48:48.612Z,1713376128.612 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0012.lzma.bak 2024-04-17T17:48:48.612Z,1713376128.612 [DataOverHttps](INFO): SBD MOMSN=19413789 2024-04-17T17:48:53.849Z,1713376133.849 [NAL9602](INFO): SBD MO Status=0, MOMSN=49764, MT Status=0, MTMSN=0 2024-04-17T17:48:53.849Z,1713376133.849 [NAL9602](INFO): No messages in MT queue 2024-04-17T17:49:07.206Z,1713376147.206 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240417T172456/Express0013.lzma 2024-04-17T17:49:08.208Z,1713376148.208 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0013.lzma.bak 2024-04-17T17:49:08.208Z,1713376148.208 [DataOverHttps](INFO): SBD MOMSN=19413792 2024-04-17T17:49:09.649Z,1713376149.649 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T17:49:09.649Z,1713376149.649 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T17:49:09.649Z,1713376149.649 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T17:49:24.580Z,1713376164.580 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T17:52:12.259Z,1713376332.259 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-17T17:54:10.313Z,1713376450.313 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T17:54:10.314Z,1713376450.314 [Default:CheckIn:C.Wait] Stopped 2024-04-17T17:54:10.314Z,1713376450.314 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T17:54:10.314Z,1713376450.314 [Default:CheckIn:D] Running Loop=1 2024-04-17T17:54:10.721Z,1713376450.721 [Default:CheckIn:D] Stopped 2024-04-17T17:54:10.722Z,1713376450.722 [Default:CheckIn:E] Running Loop=1 2024-04-17T17:54:11.132Z,1713376451.132 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.380896 min 2024-04-17T17:54:11.132Z,1713376451.132 [Default:CheckIn:E] Stopped 2024-04-17T17:54:11.132Z,1713376451.132 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T17:54:11.132Z,1713376451.132 [Default:CheckIn] Stopped 2024-04-17T17:54:11.133Z,1713376451.133 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T17:54:11.133Z,1713376451.133 [Default:CheckIn](INFO): Running loop #6 2024-04-17T17:54:11.133Z,1713376451.133 [Default:CheckIn] Running Loop=6 2024-04-17T17:54:11.133Z,1713376451.133 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T17:54:11.133Z,1713376451.133 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T17:54:13.133Z,1713376453.133 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175412.00,A,3648.14537,N,12148.22763,W,4.451,96.14,170424,,,D*46 2024-04-17T17:54:13.136Z,1713376453.136 [NAL9602](INFO): GPS fix at 20240417T175412: (36.802423, -121.803794) 2024-04-17T17:54:13.167Z,1713376453.167 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T17:54:13.168Z,1713376453.168 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T17:54:25.186Z,1713376465.186 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240417T172456/Courier0015.lzma 2024-04-17T17:54:26.189Z,1713376466.189 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0015.lzma.bak 2024-04-17T17:54:26.189Z,1713376466.189 [DataOverHttps](INFO): SBD MOMSN=19413797 2024-04-17T17:54:29.697Z,1713376469.697 [NAL9602](INFO): SBD MO Status=0, MOMSN=49765, MT Status=0, MTMSN=0 2024-04-17T17:54:29.697Z,1713376469.697 [NAL9602](INFO): No messages in MT queue 2024-04-17T17:54:43.366Z,1713376483.366 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240417T172456/Express0016.lzma 2024-04-17T17:54:44.368Z,1713376484.368 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0016.lzma.bak 2024-04-17T17:54:44.368Z,1713376484.368 [DataOverHttps](INFO): SBD MOMSN=19413800 2024-04-17T17:54:45.872Z,1713376485.872 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T17:54:45.873Z,1713376485.873 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T17:54:45.873Z,1713376485.873 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T17:55:00.447Z,1713376500.447 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T17:59:46.605Z,1713376786.605 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T17:59:46.605Z,1713376786.605 [Default:CheckIn:C.Wait] Stopped 2024-04-17T17:59:46.605Z,1713376786.605 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T17:59:46.606Z,1713376786.606 [Default:CheckIn:D] Running Loop=1 2024-04-17T17:59:47.012Z,1713376787.012 [Default:CheckIn:D] Stopped 2024-04-17T17:59:47.012Z,1713376787.012 [Default:CheckIn:E] Running Loop=1 2024-04-17T17:59:47.431Z,1713376787.431 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.985730 min 2024-04-17T17:59:47.431Z,1713376787.431 [Default:CheckIn:E] Stopped 2024-04-17T17:59:47.431Z,1713376787.431 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T17:59:47.432Z,1713376787.432 [Default:CheckIn] Stopped 2024-04-17T17:59:47.432Z,1713376787.432 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T17:59:47.432Z,1713376787.432 [Default:CheckIn](INFO): Running loop #7 2024-04-17T17:59:47.432Z,1713376787.432 [Default:CheckIn] Running Loop=7 2024-04-17T17:59:47.432Z,1713376787.432 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T17:59:47.432Z,1713376787.432 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T17:59:49.421Z,1713376789.421 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175948.00,A,3648.17570,N,12147.78738,W,3.363,70.49,170424,,,D*4B 2024-04-17T17:59:49.424Z,1713376789.424 [NAL9602](INFO): GPS fix at 20240417T175948: (36.802928, -121.796456) 2024-04-17T17:59:49.435Z,1713376789.435 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T17:59:49.435Z,1713376789.435 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T17:59:57.450Z,1713376797.450 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240417T172456/Courier0018.lzma 2024-04-17T17:59:58.452Z,1713376798.452 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0018.lzma.bak 2024-04-17T17:59:58.452Z,1713376798.452 [DataOverHttps](INFO): SBD MOMSN=19413806 2024-04-17T18:00:13.657Z,1713376813.657 [NAL9602](INFO): SBD MO Status=2, MOMSN=49766, MT Status=2, MTMSN=0 2024-04-17T18:00:13.657Z,1713376813.657 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-04-17T18:00:14.498Z,1713376814.498 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240417T172456/Express0019.lzma 2024-04-17T18:00:15.500Z,1713376815.500 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0019.lzma.bak 2024-04-17T18:00:15.500Z,1713376815.500 [DataOverHttps](INFO): SBD MOMSN=19413814 2024-04-17T18:00:16.903Z,1713376816.903 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T18:00:16.903Z,1713376816.903 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T18:00:16.903Z,1713376816.903 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T18:00:34.266Z,1713376834.266 [NAL9602](INFO): SBD MO Status=0, MOMSN=49766, MT Status=0, MTMSN=0 2024-04-17T18:00:34.266Z,1713376834.266 [NAL9602](INFO): No messages in MT queue 2024-04-17T18:01:05.019Z,1713376865.019 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T18:03:41.064Z,1713377021.064 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-17T18:03:53.975Z,1713377033.975 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-17T18:04:06.498Z,1713377046.498 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-17T18:04:19.024Z,1713377059.024 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-17T18:04:31.570Z,1713377071.570 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-17T18:04:44.498Z,1713377084.498 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-17T18:04:57.429Z,1713377097.429 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-17T18:05:17.650Z,1713377117.650 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T18:05:17.650Z,1713377117.650 [Default:CheckIn:C.Wait] Stopped 2024-04-17T18:05:17.650Z,1713377117.650 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T18:05:17.651Z,1713377117.651 [Default:CheckIn:D] Running Loop=1 2024-04-17T18:05:18.057Z,1713377118.057 [Default:CheckIn:D] Stopped 2024-04-17T18:05:18.057Z,1713377118.057 [Default:CheckIn:E] Running Loop=1 2024-04-17T18:05:18.450Z,1713377118.450 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.503149 min 2024-04-17T18:05:18.450Z,1713377118.450 [Default:CheckIn:E] Stopped 2024-04-17T18:05:18.450Z,1713377118.450 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T18:05:18.450Z,1713377118.450 [Default:CheckIn] Stopped 2024-04-17T18:05:18.451Z,1713377118.451 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T18:05:18.451Z,1713377118.451 [Default:CheckIn](INFO): Running loop #8 2024-04-17T18:05:18.451Z,1713377118.451 [Default:CheckIn] Running Loop=8 2024-04-17T18:05:18.451Z,1713377118.451 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T18:05:18.451Z,1713377118.451 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T18:05:20.466Z,1713377120.466 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180519.00,A,3648.27787,N,12147.55130,W,4.529,48.76,170424,,,D*49 2024-04-17T18:05:20.468Z,1713377120.468 [NAL9602](INFO): GPS fix at 20240417T180519: (36.804631, -121.792522) 2024-04-17T18:05:20.480Z,1713377120.480 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T18:05:20.480Z,1713377120.480 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T18:05:27.646Z,1713377127.646 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240417T172456/Courier0021.lzma 2024-04-17T18:05:28.648Z,1713377128.648 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0021.lzma.bak 2024-04-17T18:05:28.648Z,1713377128.648 [DataOverHttps](INFO): SBD MOMSN=19413844 2024-04-17T18:05:44.830Z,1713377144.830 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240417T172456/Express0022.lzma 2024-04-17T18:05:45.832Z,1713377145.832 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0022.lzma.bak 2024-04-17T18:05:45.832Z,1713377145.832 [DataOverHttps](INFO): SBD MOMSN=19413847 2024-04-17T18:05:47.147Z,1713377147.147 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T18:05:47.147Z,1713377147.147 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T18:05:47.147Z,1713377147.147 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T18:05:51.989Z,1713377151.989 [NAL9602](INFO): SBD MO Status=0, MOMSN=49767, MT Status=0, MTMSN=0 2024-04-17T18:05:51.989Z,1713377151.989 [NAL9602](INFO): No messages in MT queue 2024-04-17T18:06:22.799Z,1713377182.799 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T18:07:38.707Z,1713377258.707 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6. 2024-04-17T18:07:38.710Z,1713377258.710 [BPC1](INFO): Received data from all battery sticks. 2024-04-17T18:10:47.859Z,1713377447.859 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T18:10:47.859Z,1713377447.859 [Default:CheckIn:C.Wait] Stopped 2024-04-17T18:10:47.859Z,1713377447.859 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T18:10:47.859Z,1713377447.859 [Default:CheckIn:D] Running Loop=1 2024-04-17T18:10:48.248Z,1713377448.248 [Default:CheckIn:D] Stopped 2024-04-17T18:10:48.248Z,1713377448.248 [Default:CheckIn:E] Running Loop=1 2024-04-17T18:10:48.686Z,1713377448.686 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.006331 min 2024-04-17T18:10:48.686Z,1713377448.686 [Default:CheckIn:E] Stopped 2024-04-17T18:10:48.700Z,1713377448.700 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T18:10:48.700Z,1713377448.700 [Default:CheckIn] Stopped 2024-04-17T18:10:48.701Z,1713377448.701 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T18:10:48.701Z,1713377448.701 [Default:CheckIn](INFO): Running loop #9 2024-04-17T18:10:48.701Z,1713377448.701 [Default:CheckIn] Running Loop=9 2024-04-17T18:10:48.701Z,1713377448.701 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T18:10:48.701Z,1713377448.701 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T18:10:50.658Z,1713377450.658 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181049.00,A,3648.51558,N,12147.14163,W,4.374,117.73,170424,,,D*7A 2024-04-17T18:10:50.672Z,1713377450.672 [NAL9602](INFO): GPS fix at 20240417T181049: (36.808593, -121.785694) 2024-04-17T18:10:50.684Z,1713377450.684 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T18:10:50.684Z,1713377450.684 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T18:11:00.630Z,1713377460.630 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20240417T172456/Courier0024.lzma 2024-04-17T18:11:01.633Z,1713377461.633 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0024.lzma.bak 2024-04-17T18:11:01.633Z,1713377461.633 [DataOverHttps](INFO): SBD MOMSN=19413851 2024-04-17T18:11:03.431Z,1713377463.431 [NAL9602](INFO): SBD MO Status=0, MOMSN=49768, MT Status=0, MTMSN=0 2024-04-17T18:11:03.431Z,1713377463.431 [NAL9602](INFO): No messages in MT queue 2024-04-17T18:11:17.906Z,1713377477.906 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240417T172456/Express0025.lzma 2024-04-17T18:11:18.908Z,1713377478.908 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0025.lzma.bak 2024-04-17T18:11:18.908Z,1713377478.908 [DataOverHttps](INFO): SBD MOMSN=19413854 2024-04-17T18:11:20.002Z,1713377480.002 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T18:11:20.002Z,1713377480.002 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T18:11:20.002Z,1713377480.002 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T18:11:34.131Z,1713377494.131 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T18:16:20.635Z,1713377780.635 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T18:16:20.635Z,1713377780.635 [Default:CheckIn:C.Wait] Stopped 2024-04-17T18:16:20.635Z,1713377780.635 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T18:16:20.635Z,1713377780.635 [Default:CheckIn:D] Running Loop=1 2024-04-17T18:16:21.018Z,1713377781.018 [Default:CheckIn:D] Stopped 2024-04-17T18:16:21.018Z,1713377781.018 [Default:CheckIn:E] Running Loop=1 2024-04-17T18:16:21.434Z,1713377781.434 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.552502 min 2024-04-17T18:16:21.434Z,1713377781.434 [Default:CheckIn:E] Stopped 2024-04-17T18:16:21.434Z,1713377781.434 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T18:16:21.434Z,1713377781.434 [Default:CheckIn] Stopped 2024-04-17T18:16:21.434Z,1713377781.434 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T18:16:21.435Z,1713377781.435 [Default:CheckIn](INFO): Running loop #10 2024-04-17T18:16:21.435Z,1713377781.435 [Default:CheckIn] Running Loop=10 2024-04-17T18:16:21.435Z,1713377781.435 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T18:16:21.435Z,1713377781.435 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T18:16:23.430Z,1713377783.430 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181622.00,A,3648.17690,N,12147.16527,W,2.391,207.02,170424,,,D*7B 2024-04-17T18:16:23.440Z,1713377783.440 [NAL9602](INFO): GPS fix at 20240417T181622: (36.802948, -121.786088) 2024-04-17T18:16:23.451Z,1713377783.451 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T18:16:23.451Z,1713377783.451 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T18:16:30.618Z,1713377790.618 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240417T172456/Courier0027.lzma 2024-04-17T18:16:31.621Z,1713377791.621 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0027.lzma.bak 2024-04-17T18:16:31.621Z,1713377791.621 [DataOverHttps](INFO): SBD MOMSN=19413858 2024-04-17T18:16:48.006Z,1713377808.006 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240417T172456/Express0028.lzma 2024-04-17T18:16:49.008Z,1713377809.008 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0028.lzma.bak 2024-04-17T18:16:49.009Z,1713377809.009 [DataOverHttps](INFO): SBD MOMSN=19413861 2024-04-17T18:16:50.526Z,1713377810.526 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T18:16:50.526Z,1713377810.526 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T18:16:50.526Z,1713377810.526 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T18:16:51.307Z,1713377811.307 [NAL9602](INFO): SBD MO Status=0, MOMSN=49769, MT Status=0, MTMSN=0 2024-04-17T18:16:51.307Z,1713377811.307 [NAL9602](INFO): No messages in MT queue 2024-04-17T18:17:22.084Z,1713377842.084 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T18:21:51.154Z,1713378111.154 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T18:21:51.154Z,1713378111.154 [Default:CheckIn:C.Wait] Stopped 2024-04-17T18:21:51.154Z,1713378111.154 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T18:21:51.155Z,1713378111.155 [Default:CheckIn:D] Running Loop=1 2024-04-17T18:21:51.554Z,1713378111.554 [Default:CheckIn:D] Stopped 2024-04-17T18:21:51.554Z,1713378111.554 [Default:CheckIn:E] Running Loop=1 2024-04-17T18:21:51.959Z,1713378111.959 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.061438 min 2024-04-17T18:21:51.959Z,1713378111.959 [Default:CheckIn:E] Stopped 2024-04-17T18:21:51.959Z,1713378111.959 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T18:21:51.959Z,1713378111.959 [Default:CheckIn] Stopped 2024-04-17T18:21:51.960Z,1713378111.960 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T18:21:51.960Z,1713378111.960 [Default:CheckIn](INFO): Running loop #11 2024-04-17T18:21:51.960Z,1713378111.960 [Default:CheckIn] Running Loop=11 2024-04-17T18:21:51.960Z,1713378111.960 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T18:21:51.960Z,1713378111.960 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T18:21:53.969Z,1713378113.969 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182153.00,A,3648.13322,N,12147.20941,W,0.039,207.15,170424,,,D*7D 2024-04-17T18:21:53.972Z,1713378113.972 [NAL9602](INFO): GPS fix at 20240417T182153: (36.802220, -121.786823) 2024-04-17T18:21:54.002Z,1713378114.002 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T18:21:54.002Z,1713378114.002 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T18:22:01.786Z,1713378121.786 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240417T172456/Courier0030.lzma 2024-04-17T18:22:02.788Z,1713378122.788 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0030.lzma.bak 2024-04-17T18:22:02.788Z,1713378122.788 [DataOverHttps](INFO): SBD MOMSN=19413865 2024-04-17T18:22:11.749Z,1713378131.749 [NAL9602](INFO): SBD MO Status=0, MOMSN=49770, MT Status=0, MTMSN=0 2024-04-17T18:22:11.749Z,1713378131.749 [NAL9602](INFO): No messages in MT queue 2024-04-17T18:22:18.726Z,1713378138.726 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240417T172456/Express0031.lzma 2024-04-17T18:22:19.728Z,1713378139.728 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0031.lzma.bak 2024-04-17T18:22:19.728Z,1713378139.728 [DataOverHttps](INFO): SBD MOMSN=19413868 2024-04-17T18:22:21.051Z,1713378141.051 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T18:22:21.051Z,1713378141.051 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T18:22:21.051Z,1713378141.051 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T18:22:42.457Z,1713378162.457 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T18:27:21.641Z,1713378441.641 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T18:27:21.641Z,1713378441.641 [Default:CheckIn:C.Wait] Stopped 2024-04-17T18:27:21.641Z,1713378441.641 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T18:27:21.642Z,1713378441.642 [Default:CheckIn:D] Running Loop=1 2024-04-17T18:27:22.035Z,1713378442.035 [Default:CheckIn:D] Stopped 2024-04-17T18:27:22.035Z,1713378442.035 [Default:CheckIn:E] Running Loop=1 2024-04-17T18:27:22.439Z,1713378442.439 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.569450 min 2024-04-17T18:27:22.439Z,1713378442.439 [Default:CheckIn:E] Stopped 2024-04-17T18:27:22.439Z,1713378442.439 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T18:27:22.439Z,1713378442.439 [Default:CheckIn] Stopped 2024-04-17T18:27:22.439Z,1713378442.439 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T18:27:22.439Z,1713378442.439 [Default:CheckIn](INFO): Running loop #12 2024-04-17T18:27:22.439Z,1713378442.439 [Default:CheckIn] Running Loop=12 2024-04-17T18:27:22.439Z,1713378442.439 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T18:27:22.440Z,1713378442.440 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T18:27:24.452Z,1713378444.452 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182723.00,A,3648.13220,N,12147.20922,W,0.039,207.15,170424,,,D*7A 2024-04-17T18:27:24.455Z,1713378444.455 [NAL9602](INFO): GPS fix at 20240417T182723: (36.802203, -121.786820) 2024-04-17T18:27:24.466Z,1713378444.466 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T18:27:24.466Z,1713378444.466 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T18:27:31.790Z,1713378451.790 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240417T172456/Courier0033.lzma 2024-04-17T18:27:32.792Z,1713378452.792 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0033.lzma.bak 2024-04-17T18:27:32.792Z,1713378452.792 [DataOverHttps](INFO): SBD MOMSN=19413872 2024-04-17T18:27:46.269Z,1713378466.269 [NAL9602](INFO): SBD MO Status=0, MOMSN=49771, MT Status=0, MTMSN=0 2024-04-17T18:27:46.269Z,1713378466.269 [NAL9602](INFO): No messages in MT queue 2024-04-17T18:27:48.842Z,1713378468.842 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240417T172456/Express0034.lzma 2024-04-17T18:27:49.844Z,1713378469.844 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0034.lzma.bak 2024-04-17T18:27:49.844Z,1713378469.844 [DataOverHttps](INFO): SBD MOMSN=19413875 2024-04-17T18:27:51.131Z,1713378471.131 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T18:27:51.131Z,1713378471.131 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T18:27:51.131Z,1713378471.131 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T18:28:16.976Z,1713378496.976 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T18:32:51.789Z,1713378771.789 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T18:32:51.789Z,1713378771.789 [Default:CheckIn:C.Wait] Stopped 2024-04-17T18:32:51.789Z,1713378771.789 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T18:32:51.789Z,1713378771.789 [Default:CheckIn:D] Running Loop=1 2024-04-17T18:32:52.170Z,1713378772.170 [Default:CheckIn:D] Stopped 2024-04-17T18:32:52.170Z,1713378772.170 [Default:CheckIn:E] Running Loop=1 2024-04-17T18:32:52.576Z,1713378772.576 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.071704 min 2024-04-17T18:32:52.576Z,1713378772.576 [Default:CheckIn:E] Stopped 2024-04-17T18:32:52.576Z,1713378772.576 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T18:32:52.576Z,1713378772.576 [Default:CheckIn] Stopped 2024-04-17T18:32:52.576Z,1713378772.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T18:32:52.576Z,1713378772.576 [Default:CheckIn](INFO): Running loop #13 2024-04-17T18:32:52.576Z,1713378772.576 [Default:CheckIn] Running Loop=13 2024-04-17T18:32:52.576Z,1713378772.576 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T18:32:52.576Z,1713378772.576 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T18:32:54.581Z,1713378774.581 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183253.00,A,3648.16480,N,12147.20523,W,1.924,242.87,170424,,,D*73 2024-04-17T18:32:54.600Z,1713378774.600 [NAL9602](INFO): GPS fix at 20240417T183253: (36.802747, -121.786754) 2024-04-17T18:32:54.620Z,1713378774.620 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T18:32:54.620Z,1713378774.620 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T18:33:02.490Z,1713378782.490 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240417T172456/Courier0036.lzma 2024-04-17T18:33:03.492Z,1713378783.492 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0036.lzma.bak 2024-04-17T18:33:03.492Z,1713378783.492 [DataOverHttps](INFO): SBD MOMSN=19413880 2024-04-17T18:33:13.592Z,1713378793.592 [NAL9602](INFO): SBD MO Status=0, MOMSN=49772, MT Status=0, MTMSN=0 2024-04-17T18:33:13.593Z,1713378793.593 [NAL9602](INFO): No messages in MT queue 2024-04-17T18:33:19.350Z,1713378799.350 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20240417T172456/Express0037.lzma 2024-04-17T18:33:20.353Z,1713378800.353 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0037.lzma.bak 2024-04-17T18:33:20.353Z,1713378800.353 [DataOverHttps](INFO): SBD MOMSN=19413883 2024-04-17T18:33:21.670Z,1713378801.670 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T18:33:21.670Z,1713378801.670 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T18:33:21.670Z,1713378801.670 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T18:33:44.268Z,1713378824.268 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T18:38:22.244Z,1713379102.244 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T18:38:22.244Z,1713379102.244 [Default:CheckIn:C.Wait] Stopped 2024-04-17T18:38:22.244Z,1713379102.244 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T18:38:22.245Z,1713379102.245 [Default:CheckIn:D] Running Loop=1 2024-04-17T18:38:22.660Z,1713379102.660 [Default:CheckIn:D] Stopped 2024-04-17T18:38:22.660Z,1713379102.660 [Default:CheckIn:E] Running Loop=1 2024-04-17T18:38:23.070Z,1713379103.070 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.579875 min 2024-04-17T18:38:23.070Z,1713379103.070 [Default:CheckIn:E] Stopped 2024-04-17T18:38:23.070Z,1713379103.070 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T18:38:23.071Z,1713379103.071 [Default:CheckIn] Stopped 2024-04-17T18:38:23.071Z,1713379103.071 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T18:38:23.071Z,1713379103.071 [Default:CheckIn](INFO): Running loop #14 2024-04-17T18:38:23.071Z,1713379103.071 [Default:CheckIn] Running Loop=14 2024-04-17T18:38:23.071Z,1713379103.071 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T18:38:23.071Z,1713379103.071 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T18:38:25.065Z,1713379105.065 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183824.00,A,3648.15038,N,12147.22034,W,0.058,196.79,170424,,,D*74 2024-04-17T18:38:25.068Z,1713379105.068 [NAL9602](INFO): GPS fix at 20240417T183824: (36.802506, -121.787006) 2024-04-17T18:38:25.079Z,1713379105.079 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T18:38:25.079Z,1713379105.079 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T18:38:32.958Z,1713379112.958 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240417T172456/Courier0039.lzma 2024-04-17T18:38:33.960Z,1713379113.960 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0039.lzma.bak 2024-04-17T18:38:33.960Z,1713379113.960 [DataOverHttps](INFO): SBD MOMSN=19413890 2024-04-17T18:38:51.431Z,1713379131.431 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240417T172456/Express0040.lzma 2024-04-17T18:38:52.432Z,1713379132.432 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0040.lzma.bak 2024-04-17T18:38:52.433Z,1713379132.433 [DataOverHttps](INFO): SBD MOMSN=19413893 2024-04-17T18:38:53.781Z,1713379133.781 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T18:38:53.781Z,1713379133.781 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T18:38:53.781Z,1713379133.781 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T18:38:59.841Z,1713379139.841 [NAL9602](INFO): SBD MO Status=0, MOMSN=49773, MT Status=0, MTMSN=0 2024-04-17T18:38:59.841Z,1713379139.841 [NAL9602](INFO): No messages in MT queue 2024-04-17T18:39:30.543Z,1713379170.543 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T18:43:54.364Z,1713379434.364 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T18:43:54.364Z,1713379434.364 [Default:CheckIn:C.Wait] Stopped 2024-04-17T18:43:54.364Z,1713379434.364 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T18:43:54.364Z,1713379434.364 [Default:CheckIn:D] Running Loop=1 2024-04-17T18:43:54.776Z,1713379434.776 [Default:CheckIn:D] Stopped 2024-04-17T18:43:54.776Z,1713379434.776 [Default:CheckIn:E] Running Loop=1 2024-04-17T18:43:55.170Z,1713379435.170 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.115137 min 2024-04-17T18:43:55.170Z,1713379435.170 [Default:CheckIn:E] Stopped 2024-04-17T18:43:55.170Z,1713379435.170 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T18:43:55.170Z,1713379435.170 [Default:CheckIn] Stopped 2024-04-17T18:43:55.170Z,1713379435.170 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T18:43:55.171Z,1713379435.171 [Default:CheckIn](INFO): Running loop #15 2024-04-17T18:43:55.171Z,1713379435.171 [Default:CheckIn] Running Loop=15 2024-04-17T18:43:55.171Z,1713379435.171 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T18:43:55.171Z,1713379435.171 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T18:43:57.189Z,1713379437.189 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184356.00,A,3648.14177,N,12147.24572,W,2.508,279.51,170424,,,A*78 2024-04-17T18:43:57.192Z,1713379437.192 [NAL9602](INFO): GPS fix at 20240417T184356: (36.802363, -121.787429) 2024-04-17T18:43:57.203Z,1713379437.203 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T18:43:57.203Z,1713379437.203 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T18:44:04.134Z,1713379444.134 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240417T172456/Courier0042.lzma 2024-04-17T18:44:05.136Z,1713379445.136 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0042.lzma.bak 2024-04-17T18:44:05.136Z,1713379445.136 [DataOverHttps](INFO): SBD MOMSN=19413897 2024-04-17T18:44:18.593Z,1713379458.593 [NAL9602](INFO): SBD MO Status=0, MOMSN=49774, MT Status=0, MTMSN=0 2024-04-17T18:44:18.593Z,1713379458.593 [NAL9602](INFO): No messages in MT queue 2024-04-17T18:44:21.034Z,1713379461.034 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240417T172456/Express0043.lzma 2024-04-17T18:44:22.036Z,1713379462.036 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0043.lzma.bak 2024-04-17T18:44:22.036Z,1713379462.036 [DataOverHttps](INFO): SBD MOMSN=19413900 2024-04-17T18:44:23.461Z,1713379463.461 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T18:44:23.461Z,1713379463.461 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T18:44:23.462Z,1713379463.462 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T18:44:49.311Z,1713379489.311 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T18:49:24.036Z,1713379764.036 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T18:49:24.036Z,1713379764.036 [Default:CheckIn:C.Wait] Stopped 2024-04-17T18:49:24.036Z,1713379764.036 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T18:49:24.036Z,1713379764.036 [Default:CheckIn:D] Running Loop=1 2024-04-17T18:49:24.445Z,1713379764.445 [Default:CheckIn:D] Stopped 2024-04-17T18:49:24.445Z,1713379764.445 [Default:CheckIn:E] Running Loop=1 2024-04-17T18:49:24.871Z,1713379764.871 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.609619 min 2024-04-17T18:49:24.871Z,1713379764.871 [Default:CheckIn:E] Stopped 2024-04-17T18:49:24.872Z,1713379764.872 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T18:49:24.872Z,1713379764.872 [Default:CheckIn] Stopped 2024-04-17T18:49:24.872Z,1713379764.872 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T18:49:24.872Z,1713379764.872 [Default:CheckIn](INFO): Running loop #16 2024-04-17T18:49:24.872Z,1713379764.872 [Default:CheckIn] Running Loop=16 2024-04-17T18:49:24.872Z,1713379764.872 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T18:49:24.872Z,1713379764.872 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T18:49:26.857Z,1713379766.857 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184926.00,A,3648.17489,N,12147.28061,W,0.039,271.34,170424,,,A*77 2024-04-17T18:49:26.860Z,1713379766.860 [NAL9602](INFO): GPS fix at 20240417T184926: (36.802915, -121.788010) 2024-04-17T18:49:26.872Z,1713379766.872 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T18:49:26.872Z,1713379766.872 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T18:49:34.466Z,1713379774.466 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240417T172456/Courier0045.lzma 2024-04-17T18:49:35.468Z,1713379775.468 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0045.lzma.bak 2024-04-17T18:49:35.468Z,1713379775.468 [DataOverHttps](INFO): SBD MOMSN=19413905 2024-04-17T18:49:51.446Z,1713379791.446 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240417T172456/Express0046.lzma 2024-04-17T18:49:52.448Z,1713379792.448 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0046.lzma.bak 2024-04-17T18:49:52.448Z,1713379792.448 [DataOverHttps](INFO): SBD MOMSN=19413908 2024-04-17T18:49:53.934Z,1713379793.934 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T18:49:53.934Z,1713379793.934 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T18:49:53.934Z,1713379793.934 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T18:50:39.176Z,1713379839.176 [NAL9602](INFO): SBD MO Status=0, MOMSN=49775, MT Status=0, MTMSN=0 2024-04-17T18:50:39.176Z,1713379839.176 [NAL9602](INFO): No messages in MT queue 2024-04-17T18:51:09.887Z,1713379869.887 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T18:54:54.529Z,1713380094.529 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T18:54:54.529Z,1713380094.529 [Default:CheckIn:C.Wait] Stopped 2024-04-17T18:54:54.530Z,1713380094.530 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T18:54:54.530Z,1713380094.530 [Default:CheckIn:D] Running Loop=1 2024-04-17T18:54:54.919Z,1713380094.919 [Default:CheckIn:D] Stopped 2024-04-17T18:54:54.919Z,1713380094.919 [Default:CheckIn:E] Running Loop=1 2024-04-17T18:54:55.332Z,1713380095.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.117521 min 2024-04-17T18:54:55.332Z,1713380095.332 [Default:CheckIn:E] Stopped 2024-04-17T18:54:55.332Z,1713380095.332 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T18:54:55.332Z,1713380095.332 [Default:CheckIn] Stopped 2024-04-17T18:54:55.333Z,1713380095.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T18:54:55.333Z,1713380095.333 [Default:CheckIn](INFO): Running loop #17 2024-04-17T18:54:55.333Z,1713380095.333 [Default:CheckIn] Running Loop=17 2024-04-17T18:54:55.333Z,1713380095.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T18:54:55.333Z,1713380095.333 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T18:54:57.334Z,1713380097.334 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185456.00,A,3648.17519,N,12147.28157,W,0.117,271.34,170424,,,A*7D 2024-04-17T18:54:57.344Z,1713380097.344 [NAL9602](INFO): GPS fix at 20240417T185456: (36.802920, -121.788026) 2024-04-17T18:54:57.355Z,1713380097.355 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T18:54:57.355Z,1713380097.355 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T18:55:04.751Z,1713380104.751 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240417T172456/Courier0048.lzma 2024-04-17T18:55:05.753Z,1713380105.753 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0048.lzma.bak 2024-04-17T18:55:05.753Z,1713380105.753 [DataOverHttps](INFO): SBD MOMSN=19413913 2024-04-17T18:55:21.662Z,1713380121.662 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240417T172456/Express0049.lzma 2024-04-17T18:55:22.664Z,1713380122.664 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0049.lzma.bak 2024-04-17T18:55:22.664Z,1713380122.664 [DataOverHttps](INFO): SBD MOMSN=19413916 2024-04-17T18:55:24.008Z,1713380124.008 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T18:55:24.008Z,1713380124.008 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T18:55:24.008Z,1713380124.008 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T18:57:27.646Z,1713380247.646 [NAL9602](INFO): SBD MO Status=0, MOMSN=49776, MT Status=0, MTMSN=0 2024-04-17T18:57:27.646Z,1713380247.646 [NAL9602](INFO): No messages in MT queue 2024-04-17T18:57:58.322Z,1713380278.322 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T19:00:24.588Z,1713380424.588 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T19:00:24.588Z,1713380424.588 [Default:CheckIn:C.Wait] Stopped 2024-04-17T19:00:24.589Z,1713380424.589 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T19:00:24.589Z,1713380424.589 [Default:CheckIn:D] Running Loop=1 2024-04-17T19:00:24.982Z,1713380424.982 [Default:CheckIn:D] Stopped 2024-04-17T19:00:24.982Z,1713380424.982 [Default:CheckIn:E] Running Loop=1 2024-04-17T19:00:25.392Z,1713380425.392 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.618571 min 2024-04-17T19:00:25.392Z,1713380425.392 [Default:CheckIn:E] Stopped 2024-04-17T19:00:25.392Z,1713380425.392 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T19:00:25.393Z,1713380425.393 [Default:CheckIn] Stopped 2024-04-17T19:00:25.393Z,1713380425.393 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T19:00:25.393Z,1713380425.393 [Default:CheckIn](INFO): Running loop #18 2024-04-17T19:00:25.393Z,1713380425.393 [Default:CheckIn] Running Loop=18 2024-04-17T19:00:25.393Z,1713380425.393 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T19:00:25.393Z,1713380425.393 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T19:00:27.406Z,1713380427.406 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190026.00,A,3648.17671,N,12147.28142,W,0.117,271.34,170424,,,A*73 2024-04-17T19:00:27.408Z,1713380427.408 [NAL9602](INFO): GPS fix at 20240417T190026: (36.802945, -121.788024) 2024-04-17T19:00:27.420Z,1713380427.420 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T19:00:27.420Z,1713380427.420 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T19:00:35.302Z,1713380435.302 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240417T172456/Courier0051.lzma 2024-04-17T19:00:36.304Z,1713380436.304 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0051.lzma.bak 2024-04-17T19:00:36.304Z,1713380436.304 [DataOverHttps](INFO): SBD MOMSN=19413921 2024-04-17T19:00:54.998Z,1713380454.998 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240417T172456/Express0052.lzma 2024-04-17T19:00:55.321Z,1713380455.321 [NAL9602](INFO): SBD MO Status=0, MOMSN=49777, MT Status=0, MTMSN=0 2024-04-17T19:00:55.321Z,1713380455.321 [NAL9602](INFO): No messages in MT queue 2024-04-17T19:00:56.000Z,1713380456.000 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0052.lzma.bak 2024-04-17T19:00:56.001Z,1713380456.001 [DataOverHttps](INFO): SBD MOMSN=19413924 2024-04-17T19:00:57.359Z,1713380457.359 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T19:00:57.360Z,1713380457.360 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T19:00:57.360Z,1713380457.360 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T19:01:26.023Z,1713380486.023 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T19:05:57.936Z,1713380757.936 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T19:05:57.936Z,1713380757.936 [Default:CheckIn:C.Wait] Stopped 2024-04-17T19:05:57.936Z,1713380757.936 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T19:05:57.936Z,1713380757.936 [Default:CheckIn:D] Running Loop=1 2024-04-17T19:05:58.356Z,1713380758.356 [Default:CheckIn:D] Stopped 2024-04-17T19:05:58.356Z,1713380758.356 [Default:CheckIn:E] Running Loop=1 2024-04-17T19:05:58.747Z,1713380758.747 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.174805 min 2024-04-17T19:05:58.747Z,1713380758.747 [Default:CheckIn:E] Stopped 2024-04-17T19:05:58.748Z,1713380758.748 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T19:05:58.748Z,1713380758.748 [Default:CheckIn] Stopped 2024-04-17T19:05:58.748Z,1713380758.748 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T19:05:58.748Z,1713380758.748 [Default:CheckIn](INFO): Running loop #19 2024-04-17T19:05:58.748Z,1713380758.748 [Default:CheckIn] Running Loop=19 2024-04-17T19:05:58.748Z,1713380758.748 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T19:05:58.748Z,1713380758.748 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T19:06:00.762Z,1713380760.762 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190600.00,A,3648.17594,N,12147.27988,W,0.039,271.34,170424,,,A*75 2024-04-17T19:06:00.764Z,1713380760.764 [NAL9602](INFO): GPS fix at 20240417T190600: (36.802932, -121.787998) 2024-04-17T19:06:00.775Z,1713380760.775 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T19:06:00.775Z,1713380760.775 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T19:06:08.778Z,1713380768.778 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240417T172456/Courier0054.lzma 2024-04-17T19:06:09.780Z,1713380769.780 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0054.lzma.bak 2024-04-17T19:06:09.780Z,1713380769.780 [DataOverHttps](INFO): SBD MOMSN=19413928 2024-04-17T19:06:25.822Z,1713380785.822 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240417T172456/Express0055.lzma 2024-04-17T19:06:26.824Z,1713380786.824 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0055.lzma.bak 2024-04-17T19:06:26.824Z,1713380786.824 [DataOverHttps](INFO): SBD MOMSN=19413931 2024-04-17T19:06:28.235Z,1713380788.235 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T19:06:28.235Z,1713380788.235 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T19:06:28.235Z,1713380788.235 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T19:06:36.722Z,1713380796.722 [NAL9602](INFO): SBD MO Status=2, MOMSN=49778, MT Status=2, MTMSN=0 2024-04-17T19:06:36.722Z,1713380796.722 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-04-17T19:07:32.879Z,1713380852.879 [NAL9602](INFO): SBD MO Status=2, MOMSN=49778, MT Status=2, MTMSN=0 2024-04-17T19:07:32.879Z,1713380852.879 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-04-17T19:08:45.988Z,1713380925.988 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-17T19:08:58.510Z,1713380938.510 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-17T19:09:11.035Z,1713380951.035 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-17T19:09:23.156Z,1713380963.156 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-17T19:09:36.082Z,1713380976.082 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-17T19:09:48.204Z,1713380988.204 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-17T19:09:51.841Z,1713380991.841 [NAL9602](INFO): SBD MO Status=2, MOMSN=49778, MT Status=2, MTMSN=0 2024-04-17T19:09:51.841Z,1713380991.841 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-04-17T19:10:01.131Z,1713381001.131 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-04-17T19:10:12.042Z,1713381012.042 [NAL9602](INFO): SBD MO Status=2, MOMSN=49778, MT Status=2, MTMSN=0 2024-04-17T19:10:12.042Z,1713381012.042 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-04-17T19:10:51.637Z,1713381051.637 [NAL9602](INFO): SBD MO Status=0, MOMSN=49778, MT Status=0, MTMSN=0 2024-04-17T19:10:51.637Z,1713381051.637 [NAL9602](INFO): No messages in MT queue 2024-04-17T19:11:22.355Z,1713381082.355 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T19:11:28.835Z,1713381088.835 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T19:11:28.835Z,1713381088.835 [Default:CheckIn:C.Wait] Stopped 2024-04-17T19:11:28.835Z,1713381088.835 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T19:11:28.835Z,1713381088.835 [Default:CheckIn:D] Running Loop=1 2024-04-17T19:11:29.236Z,1713381089.236 [Default:CheckIn:D] Stopped 2024-04-17T19:11:29.236Z,1713381089.236 [Default:CheckIn:E] Running Loop=1 2024-04-17T19:11:29.623Z,1713381089.623 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.689469 min 2024-04-17T19:11:29.623Z,1713381089.623 [Default:CheckIn:E] Stopped 2024-04-17T19:11:29.623Z,1713381089.623 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T19:11:29.623Z,1713381089.623 [Default:CheckIn] Stopped 2024-04-17T19:11:29.623Z,1713381089.623 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T19:11:29.623Z,1713381089.623 [Default:CheckIn](INFO): Running loop #20 2024-04-17T19:11:29.623Z,1713381089.623 [Default:CheckIn] Running Loop=20 2024-04-17T19:11:29.623Z,1713381089.623 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T19:11:29.623Z,1713381089.623 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T19:11:31.632Z,1713381091.632 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191130.00,A,3648.17648,N,12147.27974,W,0.214,271.34,170424,,,A*7C 2024-04-17T19:11:31.635Z,1713381091.635 [NAL9602](INFO): GPS fix at 20240417T191130: (36.802941, -121.787996) 2024-04-17T19:11:31.646Z,1713381091.646 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T19:11:31.647Z,1713381091.647 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T19:11:39.598Z,1713381099.598 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240417T172456/Courier0057.lzma 2024-04-17T19:11:40.600Z,1713381100.600 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0057.lzma.bak 2024-04-17T19:11:40.600Z,1713381100.600 [DataOverHttps](INFO): SBD MOMSN=19413935 2024-04-17T19:11:50.644Z,1713381110.644 [NAL9602](INFO): SBD MO Status=0, MOMSN=49779, MT Status=0, MTMSN=0 2024-04-17T19:11:50.644Z,1713381110.644 [NAL9602](INFO): No messages in MT queue 2024-04-17T19:11:56.758Z,1713381116.758 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240417T172456/Express0058.lzma 2024-04-17T19:11:57.760Z,1713381117.760 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0058.lzma.bak 2024-04-17T19:11:57.761Z,1713381117.761 [DataOverHttps](INFO): SBD MOMSN=19413938 2024-04-17T19:11:59.119Z,1713381119.119 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T19:11:59.119Z,1713381119.119 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T19:11:59.119Z,1713381119.119 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T19:12:21.323Z,1713381141.323 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T19:12:42.334Z,1713381162.334 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6. 2024-04-17T19:12:42.337Z,1713381162.337 [BPC1](INFO): Received data from all battery sticks. 2024-04-17T19:16:59.723Z,1713381419.723 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T19:16:59.723Z,1713381419.723 [Default:CheckIn:C.Wait] Stopped 2024-04-17T19:16:59.723Z,1713381419.723 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T19:16:59.723Z,1713381419.723 [Default:CheckIn:D] Running Loop=1 2024-04-17T19:17:00.134Z,1713381420.134 [Default:CheckIn:D] Stopped 2024-04-17T19:17:00.134Z,1713381420.134 [Default:CheckIn:E] Running Loop=1 2024-04-17T19:17:00.534Z,1713381420.534 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.204443 min 2024-04-17T19:17:00.534Z,1713381420.534 [Default:CheckIn:E] Stopped 2024-04-17T19:17:00.534Z,1713381420.534 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T19:17:00.534Z,1713381420.534 [Default:CheckIn] Stopped 2024-04-17T19:17:00.534Z,1713381420.534 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T19:17:00.535Z,1713381420.535 [Default:CheckIn](INFO): Running loop #21 2024-04-17T19:17:00.535Z,1713381420.535 [Default:CheckIn] Running Loop=21 2024-04-17T19:17:00.535Z,1713381420.535 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T19:17:00.535Z,1713381420.535 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T19:17:02.549Z,1713381422.549 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191701.00,A,3648.17744,N,12147.27435,W,0.292,296.62,170424,,,A*79 2024-04-17T19:17:02.552Z,1713381422.552 [NAL9602](INFO): GPS fix at 20240417T191701: (36.802957, -121.787906) 2024-04-17T19:17:02.563Z,1713381422.563 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T19:17:02.563Z,1713381422.563 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T19:17:10.530Z,1713381430.530 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20240417T172456/Courier0060.lzma 2024-04-17T19:17:11.532Z,1713381431.532 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0060.lzma.bak 2024-04-17T19:17:11.532Z,1713381431.532 [DataOverHttps](INFO): SBD MOMSN=19413942 2024-04-17T19:17:27.703Z,1713381447.703 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20240417T172456/Express0061.lzma 2024-04-17T19:17:28.704Z,1713381448.704 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0061.lzma.bak 2024-04-17T19:17:28.705Z,1713381448.705 [DataOverHttps](INFO): SBD MOMSN=19413945 2024-04-17T19:17:30.027Z,1713381450.027 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T19:17:30.027Z,1713381450.027 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T19:17:30.027Z,1713381450.027 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T19:18:37.886Z,1713381517.886 [NAL9602](INFO): SBD MO Status=0, MOMSN=49780, MT Status=0, MTMSN=0 2024-04-17T19:18:37.886Z,1713381517.886 [NAL9602](INFO): No messages in MT queue 2024-04-17T19:19:08.601Z,1713381548.601 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T19:20:58.881Z,1713381658.881 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-04-17T19:20:58.881Z,1713381658.881 [DropWeight] Hardware Fault, FailCount= 1 2024-04-17T19:20:58.881Z,1713381658.881 [DropWeight](ERROR): Hardware Fault 2024-04-17T19:20:58.899Z,1713381658.899 [CommandExec](FAULT): Scheduling is paused 2024-04-17T19:20:58.900Z,1713381658.900 [CBIT](INFO): Critical error at 20240417T192058 2024-04-17T19:20:58.903Z,1713381658.903 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-04-17T19:20:58.903Z,1713381658.903 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-04-17T19:20:59.319Z,1713381659.319 [CBIT](INFO): Critical error at 20240417T192058 2024-04-17T19:22:30.598Z,1713381750.598 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T19:22:30.598Z,1713381750.598 [Default:CheckIn:C.Wait] Stopped 2024-04-17T19:22:30.598Z,1713381750.598 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T19:22:30.599Z,1713381750.599 [Default:CheckIn:D] Running Loop=1 2024-04-17T19:22:31.011Z,1713381751.011 [Default:CheckIn:D] Stopped 2024-04-17T19:22:31.011Z,1713381751.011 [Default:CheckIn:E] Running Loop=1 2024-04-17T19:22:31.410Z,1713381751.410 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.719059 min 2024-04-17T19:22:31.410Z,1713381751.410 [Default:CheckIn:E] Stopped 2024-04-17T19:22:31.410Z,1713381751.410 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T19:22:31.411Z,1713381751.411 [Default:CheckIn] Stopped 2024-04-17T19:22:31.411Z,1713381751.411 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T19:22:31.411Z,1713381751.411 [Default:CheckIn](INFO): Running loop #22 2024-04-17T19:22:31.411Z,1713381751.411 [Default:CheckIn] Running Loop=22 2024-04-17T19:22:31.411Z,1713381751.411 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T19:22:31.411Z,1713381751.411 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T19:22:33.425Z,1713381753.425 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192232.00,A,3648.16909,N,12147.28270,W,0.350,292.43,170424,,,A*79 2024-04-17T19:22:33.427Z,1713381753.427 [NAL9602](INFO): GPS fix at 20240417T192232: (36.802818, -121.788045) 2024-04-17T19:22:33.439Z,1713381753.439 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T19:22:33.439Z,1713381753.439 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T19:22:40.967Z,1713381760.967 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20240417T172456/Courier0063.lzma 2024-04-17T19:22:41.964Z,1713381761.964 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0063.lzma.bak 2024-04-17T19:22:41.965Z,1713381761.965 [DataOverHttps](INFO): SBD MOMSN=19413950 2024-04-17T19:22:51.609Z,1713381771.609 [NAL9602](INFO): SBD MO Status=0, MOMSN=49781, MT Status=0, MTMSN=0 2024-04-17T19:22:51.609Z,1713381771.609 [NAL9602](INFO): No messages in MT queue 2024-04-17T19:23:03.474Z,1713381783.474 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20240417T172456/Express0064.lzma 2024-04-17T19:23:04.476Z,1713381784.476 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0064.lzma.bak 2024-04-17T19:23:04.476Z,1713381784.476 [DataOverHttps](INFO): SBD MOMSN=19413955 2024-04-17T19:23:05.781Z,1713381785.781 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T19:23:05.781Z,1713381785.781 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T19:23:05.781Z,1713381785.781 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T19:23:22.323Z,1713381802.323 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T19:28:06.353Z,1713382086.353 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T19:28:06.353Z,1713382086.353 [Default:CheckIn:C.Wait] Stopped 2024-04-17T19:28:06.354Z,1713382086.354 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T19:28:06.354Z,1713382086.354 [Default:CheckIn:D] Running Loop=1 2024-04-17T19:28:06.762Z,1713382086.762 [Default:CheckIn:D] Stopped 2024-04-17T19:28:06.762Z,1713382086.762 [Default:CheckIn:E] Running Loop=1 2024-04-17T19:28:07.158Z,1713382087.158 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.314909 min 2024-04-17T19:28:07.158Z,1713382087.158 [Default:CheckIn:E] Stopped 2024-04-17T19:28:07.159Z,1713382087.159 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T19:28:07.159Z,1713382087.159 [Default:CheckIn] Stopped 2024-04-17T19:28:07.159Z,1713382087.159 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T19:28:07.159Z,1713382087.159 [Default:CheckIn](INFO): Running loop #23 2024-04-17T19:28:07.159Z,1713382087.159 [Default:CheckIn] Running Loop=23 2024-04-17T19:28:07.159Z,1713382087.159 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T19:28:07.159Z,1713382087.159 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T19:28:09.169Z,1713382089.169 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192808.00,A,3648.17038,N,12147.27834,W,0.641,74.55,170424,,,A*4D 2024-04-17T19:28:09.172Z,1713382089.172 [NAL9602](INFO): GPS fix at 20240417T192808: (36.802840, -121.787972) 2024-04-17T19:28:09.182Z,1713382089.182 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T19:28:09.183Z,1713382089.183 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T19:28:16.890Z,1713382096.890 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240417T172456/Courier0066.lzma 2024-04-17T19:28:17.892Z,1713382097.892 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0066.lzma.bak 2024-04-17T19:28:17.893Z,1713382097.893 [DataOverHttps](INFO): SBD MOMSN=19413960 2024-04-17T19:28:34.134Z,1713382114.134 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20240417T172456/Express0067.lzma 2024-04-17T19:28:35.136Z,1713382115.136 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0067.lzma.bak 2024-04-17T19:28:35.137Z,1713382115.137 [DataOverHttps](INFO): SBD MOMSN=19413963 2024-04-17T19:28:36.252Z,1713382116.252 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T19:28:36.252Z,1713382116.252 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T19:28:36.252Z,1713382116.252 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T19:33:11.363Z,1713382391.363 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-17T19:33:36.851Z,1713382416.851 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T19:33:36.851Z,1713382416.851 [Default:CheckIn:C.Wait] Stopped 2024-04-17T19:33:36.851Z,1713382416.851 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T19:33:36.851Z,1713382416.851 [Default:CheckIn:D] Running Loop=1 2024-04-17T19:33:37.230Z,1713382417.230 [Default:CheckIn:D] Stopped 2024-04-17T19:33:37.230Z,1713382417.230 [Default:CheckIn:E] Running Loop=1 2024-04-17T19:33:37.637Z,1713382417.637 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.822705 min 2024-04-17T19:33:37.637Z,1713382417.637 [Default:CheckIn:E] Stopped 2024-04-17T19:33:37.638Z,1713382417.638 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T19:33:37.638Z,1713382417.638 [Default:CheckIn] Stopped 2024-04-17T19:33:37.638Z,1713382417.638 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T19:33:37.638Z,1713382417.638 [Default:CheckIn](INFO): Running loop #24 2024-04-17T19:33:37.638Z,1713382417.638 [Default:CheckIn] Running Loop=24 2024-04-17T19:33:37.638Z,1713382417.638 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T19:33:37.638Z,1713382417.638 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T19:33:39.653Z,1713382419.653 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193338.00,A,3648.16640,N,12147.27554,W,0.428,74.55,170424,,,A*4A 2024-04-17T19:33:39.656Z,1713382419.656 [NAL9602](INFO): GPS fix at 20240417T193338: (36.802773, -121.787926) 2024-04-17T19:33:39.667Z,1713382419.667 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T19:33:39.667Z,1713382419.667 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T19:33:47.598Z,1713382427.598 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240417T172456/Courier0069.lzma 2024-04-17T19:33:48.600Z,1713382428.600 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0069.lzma.bak 2024-04-17T19:33:48.600Z,1713382428.600 [DataOverHttps](INFO): SBD MOMSN=19413966 2024-04-17T19:34:03.538Z,1713382443.538 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20240417T172456/Express0070.lzma 2024-04-17T19:34:04.540Z,1713382444.540 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0070.lzma.bak 2024-04-17T19:34:04.541Z,1713382444.541 [DataOverHttps](INFO): SBD MOMSN=19413969 2024-04-17T19:34:05.923Z,1713382445.923 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T19:34:05.923Z,1713382445.923 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T19:34:05.924Z,1713382445.924 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T19:34:12.367Z,1713382452.367 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-17T19:35:59.039Z,1713382559.039 [CBIT](INFO): Clearing failed state for component DropWeight 2024-04-17T19:35:59.039Z,1713382559.039 [DropWeight] No Fault, FailCount= 1 2024-04-17T19:39:06.504Z,1713382746.504 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-17T19:39:06.505Z,1713382746.505 [Default:CheckIn:C.Wait] Stopped 2024-04-17T19:39:06.505Z,1713382746.505 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T19:39:06.505Z,1713382746.505 [Default:CheckIn:D] Running Loop=1 2024-04-17T19:39:06.894Z,1713382746.894 [Default:CheckIn:D] Stopped 2024-04-17T19:39:06.894Z,1713382746.894 [Default:CheckIn:E] Running Loop=1 2024-04-17T19:39:07.343Z,1713382747.343 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 139.317106 min 2024-04-17T19:39:07.343Z,1713382747.343 [Default:CheckIn:E] Stopped 2024-04-17T19:39:07.343Z,1713382747.343 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-17T19:39:07.343Z,1713382747.343 [Default:CheckIn] Stopped 2024-04-17T19:39:07.343Z,1713382747.343 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T19:39:07.343Z,1713382747.343 [Default:CheckIn](INFO): Running loop #25 2024-04-17T19:39:07.343Z,1713382747.343 [Default:CheckIn] Running Loop=25 2024-04-17T19:39:07.343Z,1713382747.343 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-17T19:39:07.343Z,1713382747.343 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-17T19:39:09.314Z,1713382749.314 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193908.00,A,3648.16681,N,12147.27531,W,0.233,2.58,170424,,,A*7D 2024-04-17T19:39:09.316Z,1713382749.316 [NAL9602](INFO): GPS fix at 20240417T193908: (36.802780, -121.787922) 2024-04-17T19:39:09.327Z,1713382749.327 [Default:CheckIn:Read_GPS] Stopped 2024-04-17T19:39:09.327Z,1713382749.327 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-17T19:39:17.502Z,1713382757.502 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240417T172456/Courier0072.lzma 2024-04-17T19:39:18.504Z,1713382758.504 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Courier0072.lzma.bak 2024-04-17T19:39:18.504Z,1713382758.504 [DataOverHttps](INFO): SBD MOMSN=19413973 2024-04-17T19:39:18.808Z,1713382758.808 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2024-04-17T19:39:18.808Z,1713382758.808 [CommandExec](IMPORTANT): Onboard.Pressure 9.784589 psi 2024-04-17T19:39:29.334Z,1713382769.334 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2024-04-17T19:39:29.334Z,1713382769.334 [CommandExec](IMPORTANT): Onboard.Humidity 10.596996 % 2024-04-17T19:39:35.314Z,1713382775.314 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20240417T172456/Express0073.lzma 2024-04-17T19:39:36.316Z,1713382776.316 [DataOverHttps](INFO): Moved sent file to Logs/20240417T172456/Express0073.lzma.bak 2024-04-17T19:39:36.317Z,1713382776.317 [DataOverHttps](INFO): SBD MOMSN=19413976 2024-04-17T19:39:37.604Z,1713382777.604 [Default:CheckIn:Read_Iridium] Stopped 2024-04-17T19:39:37.604Z,1713382777.604 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-17T19:39:37.604Z,1713382777.604 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-17T19:39:40.012Z,1713382780.012 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-04-17T19:39:40.094Z,1713382780.094 [NAL9602](ERROR): received: +CSQ:0 OK781, 0, 0, 0, 0 OK 2024-04-17T19:40:30.375Z,1713382830.375 [CommandExec](IMPORTANT): got command quit 2024-04-17T19:40:31.379Z,1713382831.379 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-17T19:40:31.379Z,1713382831.379 [CommandExec](INFO): Uninitializing the command executive. 2024-04-17T19:40:31.379Z,1713382831.379 [CommandExec](INFO): Uninitializing the command scheduler. 2024-04-17T19:40:31.379Z,1713382831.379 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:31.414Z,1713382831.414 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-04-17T19:40:31.415Z,1713382831.415 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-04-17T19:40:31.415Z,1713382831.415 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:31.415Z,1713382831.415 [NavChartDb](INFO): Join timeout helper Thread ID is 7742 2024-04-17T19:40:31.439Z,1713382831.439 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-17T19:40:31.439Z,1713382831.439 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:31.454Z,1713382831.454 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-04-17T19:40:31.454Z,1713382831.454 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:31.455Z,1713382831.455 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7743 2024-04-17T19:40:31.799Z,1713382831.799 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-17T19:40:31.799Z,1713382831.799 [WetLabsBB2FL](INFO): Powering down 2024-04-17T19:40:31.800Z,1713382831.800 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:31.800Z,1713382831.800 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-04-17T19:40:31.800Z,1713382831.800 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:31.801Z,1713382831.801 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7744 2024-04-17T19:40:31.995Z,1713382831.995 [CTD_Seabird](INFO): Powering down 2024-04-17T19:40:32.006Z,1713382832.006 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-17T19:40:32.007Z,1713382832.007 [CTD_Seabird](INFO): Powering down 2024-04-17T19:40:32.018Z,1713382832.018 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:32.022Z,1713382832.022 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-04-17T19:40:32.022Z,1713382832.022 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:32.023Z,1713382832.023 [DAT](INFO): Join timeout helper Thread ID is 7745 2024-04-17T19:40:32.234Z,1713382832.234 [DAT](INFO): Powering down 2024-04-17T19:40:32.307Z,1713382832.307 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-17T19:40:32.307Z,1713382832.307 [DAT](INFO): Powering down 2024-04-17T19:40:32.308Z,1713382832.308 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:32.314Z,1713382832.314 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-04-17T19:40:32.314Z,1713382832.314 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:32.315Z,1713382832.315 [Radio_Surface](INFO): Join timeout helper Thread ID is 7746 2024-04-17T19:40:32.702Z,1713382832.702 [Radio_Surface](INFO): Powering down 2024-04-17T19:40:32.703Z,1713382832.703 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-17T19:40:32.704Z,1713382832.704 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:32.719Z,1713382832.719 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-04-17T19:40:32.719Z,1713382832.719 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:32.720Z,1713382832.720 [Onboard](INFO): Join timeout helper Thread ID is 7747 2024-04-17T19:40:32.827Z,1713382832.827 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-04-17T19:40:37.087Z,1713382837.087 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-17T19:40:37.087Z,1713382837.087 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:37.108Z,1713382837.108 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-04-17T19:40:37.108Z,1713382837.108 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:37.108Z,1713382837.108 [DataOverHttps](INFO): Join timeout helper Thread ID is 7748 2024-04-17T19:40:37.847Z,1713382837.847 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-17T19:40:37.847Z,1713382837.847 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:37.863Z,1713382837.863 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-04-17T19:40:37.863Z,1713382837.863 [logger ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:37.864Z,1713382837.864 [logger](INFO): Join timeout helper Thread ID is 7749 2024-04-17T19:40:37.883Z,1713382837.883 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-17T19:40:37.884Z,1713382837.884 [logger ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:37.887Z,1713382837.887 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-04-17T19:40:37.887Z,1713382837.887 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:37.888Z,1713382837.888 [CommandLine](INFO): Join timeout helper Thread ID is 7750 2024-04-17T19:40:37.979Z,1713382837.979 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-17T19:40:37.979Z,1713382837.979 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:37.990Z,1713382837.990 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-04-17T19:40:37.990Z,1713382837.990 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:37.991Z,1713382837.991 [CommandExec](INFO): Join timeout helper Thread ID is 7751 2024-04-17T19:40:37.992Z,1713382837.992 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-04-17T19:40:37.992Z,1713382837.992 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:37.992Z,1713382837.992 [controlThread](INFO): Join timeout helper Thread ID is 7752 2024-04-17T19:40:38.167Z,1713382838.167 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-17T19:40:38.167Z,1713382838.167 [controlThread](DEBUG): Uninitializing ControlThread 2024-04-17T19:40:38.167Z,1713382838.167 [AHRS_M2](INFO): Powering down 2024-04-17T19:40:38.239Z,1713382838.239 [NAL9602](INFO): Powering down 2024-04-17T19:40:38.241Z,1713382838.241 [Sonardyne_Nano](INFO): Powering down 2024-04-17T19:40:38.360Z,1713382838.360 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-04-17T19:40:38.361Z,1713382838.361 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-04-17T19:40:38.361Z,1713382838.361 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-04-17T19:40:38.362Z,1713382838.362 [MissionManager](INFO): Uninitializing Mission Default 2024-04-17T19:40:38.362Z,1713382838.362 [Default] Stopped 2024-04-17T19:40:38.362Z,1713382838.362 [Default](DEBUG): Aggregate::uninitialize Default 2024-04-17T19:40:38.362Z,1713382838.362 [Default:B.GoToSurface] Stopped 2024-04-17T19:40:38.362Z,1713382838.362 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-04-17T19:40:38.362Z,1713382838.362 [Default:CheckIn] Stopped 2024-04-17T19:40:38.362Z,1713382838.362 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-17T19:40:38.362Z,1713382838.362 [Default:CheckIn:C.Wait] Stopped 2024-04-17T19:40:38.362Z,1713382838.362 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-17T19:40:38.365Z,1713382838.365 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-04-17T19:40:38.365Z,1713382838.365 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-04-17T19:40:38.366Z,1713382838.366 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-04-17T19:40:38.366Z,1713382838.366 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-04-17T19:40:38.367Z,1713382838.367 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-04-17T19:40:38.367Z,1713382838.367 [BuoyancyServo](INFO): Powering down 2024-04-17T19:40:38.378Z,1713382838.378 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-04-17T19:40:38.379Z,1713382838.379 [ElevatorServo](INFO): Powering down 2024-04-17T19:40:38.379Z,1713382838.379 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-04-17T19:40:38.380Z,1713382838.380 [MassServo](INFO): Powering down 2024-04-17T19:40:38.380Z,1713382838.380 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-04-17T19:40:38.380Z,1713382838.380 [RudderServo](INFO): Powering down 2024-04-17T19:40:38.381Z,1713382838.381 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-04-17T19:40:38.381Z,1713382838.381 [ThrusterHE](INFO): Powering down 2024-04-17T19:40:38.382Z,1713382838.382 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-04-17T19:40:38.383Z,1713382838.383 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-04-17T19:40:38.383Z,1713382838.383 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-04-17T19:40:38.383Z,1713382838.383 [CBIT](DEBUG): Powering off loads. 2024-04-17T19:40:38.394Z,1713382838.394 [CBIT](DEBUG): Disabling WDT. 2024-04-17T19:40:38.406Z,1713382838.406 [CBIT](DEBUG): Opening all GF detection circuits. 2024-04-17T19:40:38.407Z,1713382838.407 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:38.455Z,1713382838.455 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:38.462Z,1713382838.462 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:38.464Z,1713382838.464 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:38.469Z,1713382838.469 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:38.541Z,1713382838.541 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:38.545Z,1713382838.545 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:38.587Z,1713382838.587 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-17T19:40:38.698Z,1713382838.698 [logger ThreadHandler](INFO): Thread cancelled.