2024-02-16T17:48:25.233Z,1708105705.233 [CommandExec](IMPORTANT): got command restart logs 2024-02-16T17:48:28.300Z,1708105708.300 [DataOverHttps](INFO): Moved sent file to Logs/20240215T225645/Courier0084.lzma.bak 2024-02-16T17:48:28.300Z,1708105708.300 [DataOverHttps](INFO): SBD MOMSN=19285250 2024-02-16T17:48:47.965Z,1708105727.965 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20240216T174432/Courier0000.lzma 2024-02-16T17:48:48.966Z,1708105728.966 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174432/Courier0000.lzma.bak 2024-02-16T17:48:48.967Z,1708105728.967 [DataOverHttps](INFO): SBD MOMSN=19285255 2024-02-16T17:48:53.102Z,1708105733.102 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T17:49:07.876Z,1708105747.876 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240216T174432/Courier0001.lzma 2024-02-16T17:49:08.878Z,1708105748.878 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174432/Courier0001.lzma.bak 2024-02-16T17:49:08.879Z,1708105748.879 [DataOverHttps](INFO): SBD MOMSN=19285259 2024-02-16T17:49:24.664Z,1708105764.664 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240216T174432/Courier0004.lzma 2024-02-16T17:49:25.666Z,1708105765.666 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174432/Courier0004.lzma.bak 2024-02-16T17:49:25.666Z,1708105765.666 [DataOverHttps](INFO): SBD MOMSN=19285261 2024-02-16T17:49:44.600Z,1708105784.600 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240215T225645/Express0082.lzma 2024-02-16T17:49:45.603Z,1708105785.603 [DataOverHttps](INFO): Moved sent file to Logs/20240215T225645/Express0082.lzma.bak 2024-02-16T17:49:45.603Z,1708105785.603 [DataOverHttps](INFO): SBD MOMSN=19285265 2024-02-16T17:50:02.276Z,1708105802.276 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20240215T225645/Express0085.lzma 2024-02-16T17:50:03.275Z,1708105803.275 [DataOverHttps](INFO): Moved sent file to Logs/20240215T225645/Express0085.lzma.bak 2024-02-16T17:50:03.275Z,1708105803.275 [DataOverHttps](INFO): SBD MOMSN=19285267 2024-02-16T17:50:24.377Z,1708105824.377 [DataOverHttps](INFO): Sending 397 bytes from file Logs/20240216T174432/Express0001.lzma 2024-02-16T17:50:25.378Z,1708105825.378 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174432/Express0001.lzma.bak 2024-02-16T17:50:25.379Z,1708105825.379 [DataOverHttps](INFO): SBD MOMSN=19285270 2024-02-16T17:51:32.525Z,1708105892.525 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20240216T174432/Express0005.lzma 2024-02-16T17:51:33.526Z,1708105893.526 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174432/Express0005.lzma.bak 2024-02-16T17:51:33.527Z,1708105893.527 [DataOverHttps](INFO): SBD MOMSN=19285272 2024-02-16T17:51:34.821Z,1708105894.821 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T17:51:34.821Z,1708105894.821 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T17:51:34.821Z,1708105894.821 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T17:53:05.617Z,1708105985.617 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T17:53:36.745Z,1708106016.745 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T17:54:07.912Z,1708106047.912 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T17:54:39.057Z,1708106079.057 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T17:55:10.189Z,1708106110.189 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T17:55:13.201Z,1708106113.201 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.795410 2024-02-16T17:56:03.890Z,1708106163.890 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003497 2024-02-16T17:56:35.372Z,1708106195.372 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T17:56:35.372Z,1708106195.372 [Default:CheckIn:C.Wait] Stopped 2024-02-16T17:56:35.372Z,1708106195.372 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T17:56:35.373Z,1708106195.373 [Default:CheckIn:D] Running Loop=1 2024-02-16T17:56:35.772Z,1708106195.772 [Default:CheckIn:D] Stopped 2024-02-16T17:56:35.772Z,1708106195.772 [Default:CheckIn:E] Running Loop=1 2024-02-16T17:56:36.188Z,1708106196.188 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.391222 min 2024-02-16T17:56:36.188Z,1708106196.188 [Default:CheckIn:E] Stopped 2024-02-16T17:56:36.188Z,1708106196.188 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T17:56:36.188Z,1708106196.188 [Default:CheckIn] Stopped 2024-02-16T17:56:36.188Z,1708106196.188 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T17:56:36.188Z,1708106196.188 [Default:CheckIn](INFO): Running loop #3 2024-02-16T17:56:36.189Z,1708106196.189 [Default:CheckIn] Running Loop=3 2024-02-16T17:56:36.189Z,1708106196.189 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T17:56:36.189Z,1708106196.189 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T17:56:38.191Z,1708106198.191 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175633.00,A,3648.59807,N,12149.63173,W,4.063,114.07,160224,,,D*78 2024-02-16T17:56:38.194Z,1708106198.194 [NAL9602](INFO): GPS fix at 20240216T175633: (36.809968, -121.827196) 2024-02-16T17:56:38.205Z,1708106198.205 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T17:56:38.205Z,1708106198.205 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T17:56:51.929Z,1708106211.929 [NAL9602](INFO): SBD MO Status=1, MOMSN=47312, MT Status=0, MTMSN=0 2024-02-16T17:56:51.929Z,1708106211.929 [NAL9602](INFO): No messages in MT queue 2024-02-16T17:56:54.352Z,1708106214.352 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20240216T174825/Courier0003.lzma 2024-02-16T17:56:55.354Z,1708106215.354 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0003.lzma.bak 2024-02-16T17:56:55.355Z,1708106215.355 [DataOverHttps](INFO): SBD MOMSN=19285277 2024-02-16T17:57:13.453Z,1708106233.453 [DataOverHttps](INFO): Sending 397 bytes from file Logs/20240216T174825/Express0004.lzma 2024-02-16T17:57:14.454Z,1708106234.454 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0004.lzma.bak 2024-02-16T17:57:14.454Z,1708106234.454 [DataOverHttps](INFO): SBD MOMSN=19285281 2024-02-16T17:57:15.836Z,1708106235.836 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T17:57:15.853Z,1708106235.853 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T17:57:15.853Z,1708106235.853 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T17:57:22.685Z,1708106242.685 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T17:58:16.041Z,1708106296.041 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T17:58:47.165Z,1708106327.165 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T17:59:18.305Z,1708106358.305 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T17:59:49.429Z,1708106389.429 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:00:20.555Z,1708106420.555 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:00:25.573Z,1708106425.573 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.755280 2024-02-16T18:01:21.797Z,1708106481.797 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:01:52.933Z,1708106512.933 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:02:16.408Z,1708106536.408 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T18:02:16.408Z,1708106536.408 [Default:CheckIn:C.Wait] Stopped 2024-02-16T18:02:16.408Z,1708106536.408 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T18:02:16.408Z,1708106536.408 [Default:CheckIn:D] Running Loop=1 2024-02-16T18:02:16.812Z,1708106536.812 [Default:CheckIn:D] Stopped 2024-02-16T18:02:16.812Z,1708106536.812 [Default:CheckIn:E] Running Loop=1 2024-02-16T18:02:17.226Z,1708106537.226 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.075218 min 2024-02-16T18:02:17.226Z,1708106537.226 [Default:CheckIn:E] Stopped 2024-02-16T18:02:17.226Z,1708106537.226 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T18:02:17.226Z,1708106537.226 [Default:CheckIn] Stopped 2024-02-16T18:02:17.226Z,1708106537.226 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T18:02:17.226Z,1708106537.226 [Default:CheckIn](INFO): Running loop #4 2024-02-16T18:02:17.226Z,1708106537.226 [Default:CheckIn] Running Loop=4 2024-02-16T18:02:17.226Z,1708106537.226 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T18:02:17.226Z,1708106537.226 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T18:02:19.228Z,1708106539.228 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180214.00,A,3648.48902,N,12149.70098,W,1.749,280.20,160224,,,D*70 2024-02-16T18:02:19.230Z,1708106539.230 [NAL9602](INFO): GPS fix at 20240216T180214: (36.808150, -121.828350) 2024-02-16T18:02:19.261Z,1708106539.261 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T18:02:19.261Z,1708106539.261 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T18:02:24.057Z,1708106544.057 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:02:41.912Z,1708106561.912 [NAL9602](INFO): SBD MO Status=1, MOMSN=47313, MT Status=0, MTMSN=0 2024-02-16T18:02:41.961Z,1708106561.961 [NAL9602](INFO): Sent 72 bytes from file Logs/20240216T174825/Courier0006.lzma 2024-02-16T18:02:41.961Z,1708106561.961 [NAL9602](INFO): Packets left to send: 0 2024-02-16T18:02:48.024Z,1708106568.024 [NAL9602](INFO): SBD MO Status=1, MOMSN=47314, MT Status=0, MTMSN=0 2024-02-16T18:02:48.077Z,1708106568.077 [NAL9602](INFO): Sent 143 bytes from file Logs/20240216T174825/Express0007.lzma 2024-02-16T18:02:48.077Z,1708106568.077 [NAL9602](INFO): Packets left to send: 0 2024-02-16T18:02:55.201Z,1708106575.201 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:02:56.511Z,1708106576.511 [NAL9602](INFO): SBD MO Status=0, MOMSN=47315, MT Status=0, MTMSN=0 2024-02-16T18:02:56.600Z,1708106576.600 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T18:02:56.601Z,1708106576.601 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T18:02:56.601Z,1708106576.601 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T18:03:26.325Z,1708106606.325 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:03:27.232Z,1708106607.232 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T18:03:57.449Z,1708106637.449 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:04:28.573Z,1708106668.573 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:04:59.705Z,1708106699.705 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:05:30.849Z,1708106730.849 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:06:01.973Z,1708106761.973 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:06:33.097Z,1708106793.097 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:07:04.229Z,1708106824.229 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:07:35.353Z,1708106855.353 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:07:57.096Z,1708106877.096 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T18:07:57.096Z,1708106877.096 [Default:CheckIn:C.Wait] Stopped 2024-02-16T18:07:57.096Z,1708106877.096 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T18:07:57.096Z,1708106877.096 [Default:CheckIn:D] Running Loop=1 2024-02-16T18:07:57.495Z,1708106877.495 [Default:CheckIn:D] Stopped 2024-02-16T18:07:57.495Z,1708106877.495 [Default:CheckIn:E] Running Loop=1 2024-02-16T18:07:57.904Z,1708106877.904 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.753269 min 2024-02-16T18:07:57.904Z,1708106877.904 [Default:CheckIn:E] Stopped 2024-02-16T18:07:57.904Z,1708106877.904 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T18:07:57.904Z,1708106877.904 [Default:CheckIn] Stopped 2024-02-16T18:07:57.905Z,1708106877.905 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T18:07:57.905Z,1708106877.905 [Default:CheckIn](INFO): Running loop #5 2024-02-16T18:07:57.905Z,1708106877.905 [Default:CheckIn] Running Loop=5 2024-02-16T18:07:57.905Z,1708106877.905 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T18:07:57.905Z,1708106877.905 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T18:07:59.915Z,1708106879.915 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180755.00,A,3648.52664,N,12149.76744,W,1.361,319.86,160224,,,D*77 2024-02-16T18:07:59.918Z,1708106879.918 [NAL9602](INFO): GPS fix at 20240216T180755: (36.808777, -121.829457) 2024-02-16T18:07:59.949Z,1708106879.949 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T18:07:59.949Z,1708106879.949 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T18:08:06.477Z,1708106886.477 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:08:22.175Z,1708106902.175 [NAL9602](INFO): SBD MO Status=1, MOMSN=47316, MT Status=0, MTMSN=0 2024-02-16T18:08:22.240Z,1708106902.240 [NAL9602](INFO): Sent 72 bytes from file Logs/20240216T174825/Courier0009.lzma 2024-02-16T18:08:22.240Z,1708106902.240 [NAL9602](INFO): Packets left to send: 0 2024-02-16T18:08:37.601Z,1708106917.601 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:08:39.615Z,1708106919.615 [NAL9602](INFO): SBD MO Status=1, MOMSN=47317, MT Status=0, MTMSN=0 2024-02-16T18:08:39.663Z,1708106919.663 [NAL9602](INFO): Sent 142 bytes from file Logs/20240216T174825/Express0010.lzma 2024-02-16T18:08:39.663Z,1708106919.663 [NAL9602](INFO): Packets left to send: 0 2024-02-16T18:08:58.199Z,1708106938.199 [NAL9602](INFO): SBD MO Status=0, MOMSN=47318, MT Status=0, MTMSN=0 2024-02-16T18:08:58.285Z,1708106938.285 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T18:08:58.286Z,1708106938.286 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T18:08:58.286Z,1708106938.286 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T18:09:08.729Z,1708106948.729 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:09:28.902Z,1708106968.902 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T18:09:39.853Z,1708106979.853 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:10:10.977Z,1708107010.977 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:10:43.605Z,1708107043.605 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003489 2024-02-16T18:13:48.661Z,1708107228.661 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-02-16T18:13:58.844Z,1708107238.844 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T18:13:58.844Z,1708107238.844 [Default:CheckIn:C.Wait] Stopped 2024-02-16T18:13:58.844Z,1708107238.844 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T18:13:58.844Z,1708107238.844 [Default:CheckIn:D] Running Loop=1 2024-02-16T18:13:59.248Z,1708107239.248 [Default:CheckIn:D] Stopped 2024-02-16T18:13:59.248Z,1708107239.248 [Default:CheckIn:E] Running Loop=1 2024-02-16T18:13:59.651Z,1708107239.651 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.782495 min 2024-02-16T18:13:59.651Z,1708107239.651 [Default:CheckIn:E] Stopped 2024-02-16T18:13:59.651Z,1708107239.651 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T18:13:59.651Z,1708107239.651 [Default:CheckIn] Stopped 2024-02-16T18:13:59.652Z,1708107239.652 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T18:13:59.652Z,1708107239.652 [Default:CheckIn](INFO): Running loop #6 2024-02-16T18:13:59.652Z,1708107239.652 [Default:CheckIn] Running Loop=6 2024-02-16T18:13:59.652Z,1708107239.652 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T18:13:59.652Z,1708107239.652 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T18:14:01.667Z,1708107241.667 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181356.00,A,3648.54877,N,12149.73512,W,5.015,88.96,160224,,,D*41 2024-02-16T18:14:01.679Z,1708107241.679 [NAL9602](INFO): GPS fix at 20240216T181356: (36.809146, -121.828919) 2024-02-16T18:14:01.690Z,1708107241.690 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T18:14:01.690Z,1708107241.690 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T18:14:19.785Z,1708107259.785 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:14:35.199Z,1708107275.199 [NAL9602](INFO): SBD MO Status=2, MOMSN=47319, MT Status=2, MTMSN=0 2024-02-16T18:14:35.199Z,1708107275.200 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-16T18:14:50.913Z,1708107290.913 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:14:51.355Z,1708107291.355 [NAL9602](INFO): SBD MO Status=0, MOMSN=47319, MT Status=0, MTMSN=0 2024-02-16T18:14:51.355Z,1708107291.355 [NAL9602](INFO): No messages in MT queue 2024-02-16T18:15:22.037Z,1708107322.037 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:15:22.062Z,1708107322.062 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T18:15:53.161Z,1708107353.161 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:16:04.205Z,1708107364.205 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.215073 2024-02-16T18:16:35.389Z,1708107395.389 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:16:43.331Z,1708107403.331 [NAL9602](INFO): SBD MO Status=1, MOMSN=47320, MT Status=0, MTMSN=0 2024-02-16T18:16:43.393Z,1708107403.393 [NAL9602](INFO): Sent 72 bytes from file Logs/20240216T174825/Courier0012.lzma 2024-02-16T18:16:43.393Z,1708107403.393 [NAL9602](INFO): Packets left to send: 0 2024-02-16T18:16:59.146Z,1708107419.146 [NAL9602](INFO): SBD MO Status=2, MOMSN=47321, MT Status=2, MTMSN=0 2024-02-16T18:16:59.146Z,1708107419.146 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-16T18:17:06.513Z,1708107426.513 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:17:36.758Z,1708107456.758 [NAL9602](INFO): SBD MO Status=2, MOMSN=47321, MT Status=2, MTMSN=0 2024-02-16T18:17:36.758Z,1708107456.758 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-16T18:17:37.637Z,1708107457.637 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:18:07.511Z,1708107487.511 [NAL9602](INFO): SBD MO Status=1, MOMSN=47321, MT Status=0, MTMSN=0 2024-02-16T18:18:07.565Z,1708107487.565 [NAL9602](INFO): Sent 143 bytes from file Logs/20240216T174825/Express0013.lzma 2024-02-16T18:18:07.565Z,1708107487.565 [NAL9602](INFO): Packets left to send: 0 2024-02-16T18:18:08.761Z,1708107488.761 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:18:23.671Z,1708107503.671 [NAL9602](INFO): SBD MO Status=2, MOMSN=47322, MT Status=2, MTMSN=0 2024-02-16T18:18:23.671Z,1708107503.671 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-16T18:18:38.215Z,1708107518.215 [NAL9602](INFO): SBD MO Status=0, MOMSN=47322, MT Status=0, MTMSN=0 2024-02-16T18:18:38.297Z,1708107518.297 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T18:18:38.298Z,1708107518.298 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T18:18:38.298Z,1708107518.298 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T18:18:39.893Z,1708107519.893 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:19:08.921Z,1708107548.921 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T18:19:11.017Z,1708107551.017 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:19:42.149Z,1708107582.149 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:20:13.285Z,1708107613.285 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:20:44.421Z,1708107644.421 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:21:15.545Z,1708107675.545 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:21:46.669Z,1708107706.669 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:22:18.157Z,1708107738.157 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2024-02-16T18:22:49.281Z,1708107769.281 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:23:20.405Z,1708107800.405 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:23:38.817Z,1708107818.817 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T18:23:38.817Z,1708107818.817 [Default:CheckIn:C.Wait] Stopped 2024-02-16T18:23:38.817Z,1708107818.817 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T18:23:38.817Z,1708107818.817 [Default:CheckIn:D] Running Loop=1 2024-02-16T18:23:39.208Z,1708107819.208 [Default:CheckIn:D] Stopped 2024-02-16T18:23:39.208Z,1708107819.208 [Default:CheckIn:E] Running Loop=1 2024-02-16T18:23:39.611Z,1708107819.611 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.448490 min 2024-02-16T18:23:39.612Z,1708107819.612 [Default:CheckIn:E] Stopped 2024-02-16T18:23:39.612Z,1708107819.612 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T18:23:39.612Z,1708107819.612 [Default:CheckIn] Stopped 2024-02-16T18:23:39.612Z,1708107819.612 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T18:23:39.612Z,1708107819.612 [Default:CheckIn](INFO): Running loop #7 2024-02-16T18:23:39.612Z,1708107819.612 [Default:CheckIn] Running Loop=7 2024-02-16T18:23:39.612Z,1708107819.612 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T18:23:39.613Z,1708107819.613 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T18:23:41.631Z,1708107821.631 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182336.00,A,3648.49012,N,12148.84366,W,4.179,109.16,160224,,,D*75 2024-02-16T18:23:41.634Z,1708107821.634 [NAL9602](INFO): GPS fix at 20240216T182336: (36.808169, -121.814061) 2024-02-16T18:23:41.645Z,1708107821.645 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T18:23:41.645Z,1708107821.645 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T18:23:51.529Z,1708107831.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:24:11.571Z,1708107851.571 [NAL9602](INFO): SBD MO Status=2, MOMSN=47323, MT Status=2, MTMSN=0 2024-02-16T18:24:11.571Z,1708107851.571 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-16T18:24:22.677Z,1708107862.677 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:24:34.245Z,1708107874.245 [NAL9602](INFO): SBD MO Status=1, MOMSN=47323, MT Status=0, MTMSN=0 2024-02-16T18:24:34.295Z,1708107874.295 [NAL9602](INFO): Sent 72 bytes from file Logs/20240216T174825/Courier0015.lzma 2024-02-16T18:24:34.295Z,1708107874.295 [NAL9602](INFO): Packets left to send: 0 2024-02-16T18:24:42.772Z,1708107882.772 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-16T18:24:47.623Z,1708107887.623 [NAL9602](INFO): SBD MO Status=1, MOMSN=47324, MT Status=0, MTMSN=0 2024-02-16T18:24:47.673Z,1708107887.673 [NAL9602](INFO): Sent 142 bytes from file Logs/20240216T174825/Express0016.lzma 2024-02-16T18:24:47.673Z,1708107887.673 [NAL9602](INFO): Packets left to send: 0 2024-02-16T18:24:53.801Z,1708107893.801 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:24:55.348Z,1708107895.348 [NAL9602](INFO): SBD MO Status=0, MOMSN=47325, MT Status=0, MTMSN=0 2024-02-16T18:24:55.427Z,1708107895.427 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T18:24:55.427Z,1708107895.427 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T18:24:55.427Z,1708107895.427 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T18:24:55.700Z,1708107895.700 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-16T18:25:08.627Z,1708107908.627 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-16T18:25:21.962Z,1708107921.962 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-16T18:25:24.937Z,1708107924.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:25:26.022Z,1708107926.022 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T18:25:34.887Z,1708107934.887 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-16T18:25:48.219Z,1708107948.219 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-16T18:25:56.097Z,1708107956.097 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:26:00.743Z,1708107960.743 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-16T18:26:58.385Z,1708108018.385 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:27:29.509Z,1708108049.509 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:28:00.633Z,1708108080.633 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:28:31.757Z,1708108111.757 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:28:42.353Z,1708108122.353 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6. 2024-02-16T18:28:42.355Z,1708108122.355 [BPC1](INFO): Received data from all battery sticks. 2024-02-16T18:29:02.881Z,1708108142.881 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:29:34.013Z,1708108174.013 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:29:55.885Z,1708108195.885 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T18:29:55.885Z,1708108195.885 [Default:CheckIn:C.Wait] Stopped 2024-02-16T18:29:55.885Z,1708108195.885 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T18:29:55.885Z,1708108195.885 [Default:CheckIn:D] Running Loop=1 2024-02-16T18:29:56.306Z,1708108196.306 [Default:CheckIn:D] Stopped 2024-02-16T18:29:56.306Z,1708108196.306 [Default:CheckIn:E] Running Loop=1 2024-02-16T18:29:56.697Z,1708108196.697 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.733451 min 2024-02-16T18:29:56.697Z,1708108196.697 [Default:CheckIn:E] Stopped 2024-02-16T18:29:56.698Z,1708108196.698 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T18:29:56.698Z,1708108196.698 [Default:CheckIn] Stopped 2024-02-16T18:29:56.698Z,1708108196.698 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T18:29:56.698Z,1708108196.698 [Default:CheckIn](INFO): Running loop #8 2024-02-16T18:29:56.698Z,1708108196.698 [Default:CheckIn] Running Loop=8 2024-02-16T18:29:56.698Z,1708108196.698 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T18:29:56.698Z,1708108196.698 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T18:29:58.716Z,1708108198.716 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182953.00,A,3648.35164,N,12148.32439,W,3.635,135.02,160224,,,D*75 2024-02-16T18:29:58.718Z,1708108198.718 [NAL9602](INFO): GPS fix at 20240216T182953: (36.805861, -121.805407) 2024-02-16T18:29:58.729Z,1708108198.729 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T18:29:58.729Z,1708108198.729 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T18:30:05.137Z,1708108205.137 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:30:25.415Z,1708108225.415 [NAL9602](INFO): SBD MO Status=1, MOMSN=47326, MT Status=0, MTMSN=0 2024-02-16T18:30:25.469Z,1708108225.469 [NAL9602](INFO): Sent 202 bytes from file Logs/20240216T174825/Courier0018.lzma 2024-02-16T18:30:25.469Z,1708108225.469 [NAL9602](INFO): Packets left to send: 0 2024-02-16T18:30:36.277Z,1708108236.277 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:30:38.002Z,1708108238.002 [NAL9602](INFO): SBD MO Status=1, MOMSN=47327, MT Status=0, MTMSN=0 2024-02-16T18:30:38.065Z,1708108238.065 [NAL9602](INFO): Sent 144 bytes from file Logs/20240216T174825/Express0019.lzma 2024-02-16T18:30:38.065Z,1708108238.065 [NAL9602](INFO): Packets left to send: 0 2024-02-16T18:30:58.599Z,1708108258.599 [NAL9602](INFO): SBD MO Status=0, MOMSN=47328, MT Status=0, MTMSN=0 2024-02-16T18:30:58.687Z,1708108258.687 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T18:30:58.687Z,1708108258.687 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T18:30:58.687Z,1708108258.687 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T18:31:07.401Z,1708108267.401 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-02-16T18:31:15.770Z,1708108275.770 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003497 2024-02-16T18:31:29.301Z,1708108289.301 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T18:32:36.389Z,1708108356.389 [CBIT](IMPORTANT): Beginning ground fault scan 2024-02-16T18:32:39.212Z,1708108359.212 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2024-02-16T18:32:47.301Z,1708108367.301 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002654 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.002326 CHAN A3 (5V): -0.001310 CHAN B0 (3.3V): -0.000274 CHAN B1 (3.15aV): 0.000127 CHAN B2 (3.15bV): -0.000429 CHAN B3 (GND): -0.000244 OPEN: -0.000364 Full Scale: +/- 1 mA 2024-02-16T18:35:59.182Z,1708108559.182 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T18:35:59.183Z,1708108559.183 [Default:CheckIn:C.Wait] Stopped 2024-02-16T18:35:59.183Z,1708108559.183 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T18:35:59.183Z,1708108559.183 [Default:CheckIn:D] Running Loop=1 2024-02-16T18:35:59.592Z,1708108559.592 [Default:CheckIn:D] Stopped 2024-02-16T18:35:59.592Z,1708108559.592 [Default:CheckIn:E] Running Loop=1 2024-02-16T18:36:00.001Z,1708108560.001 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.788220 min 2024-02-16T18:36:00.001Z,1708108560.001 [Default:CheckIn:E] Stopped 2024-02-16T18:36:00.001Z,1708108560.001 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T18:36:00.002Z,1708108560.002 [Default:CheckIn] Stopped 2024-02-16T18:36:00.002Z,1708108560.002 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T18:36:00.002Z,1708108560.002 [Default:CheckIn](INFO): Running loop #9 2024-02-16T18:36:00.002Z,1708108560.002 [Default:CheckIn] Running Loop=9 2024-02-16T18:36:00.002Z,1708108560.002 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T18:36:00.002Z,1708108560.002 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T18:36:02.002Z,1708108562.002 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183557.00,A,3648.24444,N,12147.80270,W,5.132,83.48,160224,,,D*42 2024-02-16T18:36:02.005Z,1708108562.005 [NAL9602](INFO): GPS fix at 20240216T183557: (36.804074, -121.796712) 2024-02-16T18:36:02.031Z,1708108562.031 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T18:36:02.031Z,1708108562.031 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T18:36:08.900Z,1708108568.900 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0021.lzma 2024-02-16T18:36:09.902Z,1708108569.902 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0021.lzma.bak 2024-02-16T18:36:09.902Z,1708108569.902 [DataOverHttps](INFO): SBD MOMSN=19285352 2024-02-16T18:36:28.625Z,1708108588.625 [DataOverHttps](INFO): Sending 352 bytes from file Logs/20240216T174825/Express0022.lzma 2024-02-16T18:36:28.715Z,1708108588.715 [NAL9602](INFO): SBD MO Status=2, MOMSN=47329, MT Status=2, MTMSN=0 2024-02-16T18:36:28.715Z,1708108588.715 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-16T18:36:29.627Z,1708108589.627 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0022.lzma.bak 2024-02-16T18:36:29.627Z,1708108589.627 [DataOverHttps](INFO): SBD MOMSN=19285355 2024-02-16T18:36:30.785Z,1708108590.785 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T18:36:30.785Z,1708108590.785 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T18:36:30.785Z,1708108590.785 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T18:36:55.790Z,1708108615.790 [NAL9602](INFO): SBD MO Status=0, MOMSN=47329, MT Status=0, MTMSN=0 2024-02-16T18:36:55.790Z,1708108615.790 [NAL9602](INFO): No messages in MT queue 2024-02-16T18:37:26.494Z,1708108646.494 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T18:41:31.330Z,1708108891.330 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T18:41:31.331Z,1708108891.331 [Default:CheckIn:C.Wait] Stopped 2024-02-16T18:41:31.331Z,1708108891.331 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T18:41:31.331Z,1708108891.331 [Default:CheckIn:D] Running Loop=1 2024-02-16T18:41:31.740Z,1708108891.740 [Default:CheckIn:D] Stopped 2024-02-16T18:41:31.740Z,1708108891.740 [Default:CheckIn:E] Running Loop=1 2024-02-16T18:41:32.144Z,1708108892.144 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.324028 min 2024-02-16T18:41:32.144Z,1708108892.144 [Default:CheckIn:E] Stopped 2024-02-16T18:41:32.144Z,1708108892.144 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T18:41:32.144Z,1708108892.144 [Default:CheckIn] Stopped 2024-02-16T18:41:32.144Z,1708108892.144 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T18:41:32.145Z,1708108892.145 [Default:CheckIn](INFO): Running loop #10 2024-02-16T18:41:32.145Z,1708108892.145 [Default:CheckIn] Running Loop=10 2024-02-16T18:41:32.145Z,1708108892.145 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T18:41:32.145Z,1708108892.145 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T18:41:34.188Z,1708108894.188 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184129.00,A,3648.36694,N,12147.44271,W,4.179,55.55,160224,,,D*44 2024-02-16T18:41:34.193Z,1708108894.193 [NAL9602](INFO): GPS fix at 20240216T184129: (36.806116, -121.790712) 2024-02-16T18:41:34.204Z,1708108894.204 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T18:41:34.204Z,1708108894.204 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T18:41:41.996Z,1708108901.996 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240216T174825/Courier0024.lzma 2024-02-16T18:41:42.998Z,1708108902.998 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0024.lzma.bak 2024-02-16T18:41:42.999Z,1708108902.999 [DataOverHttps](INFO): SBD MOMSN=19285369 2024-02-16T18:41:53.951Z,1708108913.951 [NAL9602](INFO): SBD MO Status=2, MOMSN=47330, MT Status=2, MTMSN=0 2024-02-16T18:41:53.951Z,1708108913.951 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-16T18:41:58.904Z,1708108918.904 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20240216T174825/Express0025.lzma 2024-02-16T18:41:59.906Z,1708108919.906 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0025.lzma.bak 2024-02-16T18:41:59.907Z,1708108919.907 [DataOverHttps](INFO): SBD MOMSN=19285372 2024-02-16T18:42:01.244Z,1708108921.244 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T18:42:01.244Z,1708108921.244 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T18:42:01.244Z,1708108921.244 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T18:42:40.007Z,1708108960.007 [NAL9602](INFO): SBD MO Status=0, MOMSN=47330, MT Status=0, MTMSN=0 2024-02-16T18:42:40.007Z,1708108960.007 [NAL9602](INFO): No messages in MT queue 2024-02-16T18:43:10.719Z,1708108990.719 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T18:47:01.853Z,1708109221.853 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T18:47:01.854Z,1708109221.854 [Default:CheckIn:C.Wait] Stopped 2024-02-16T18:47:01.854Z,1708109221.854 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T18:47:01.854Z,1708109221.854 [Default:CheckIn:D] Running Loop=1 2024-02-16T18:47:02.254Z,1708109222.254 [Default:CheckIn:D] Stopped 2024-02-16T18:47:02.254Z,1708109222.254 [Default:CheckIn:E] Running Loop=1 2024-02-16T18:47:02.670Z,1708109222.670 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.832593 min 2024-02-16T18:47:02.670Z,1708109222.670 [Default:CheckIn:E] Stopped 2024-02-16T18:47:02.670Z,1708109222.670 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T18:47:02.671Z,1708109222.671 [Default:CheckIn] Stopped 2024-02-16T18:47:02.671Z,1708109222.671 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T18:47:02.671Z,1708109222.671 [Default:CheckIn](INFO): Running loop #11 2024-02-16T18:47:02.671Z,1708109222.671 [Default:CheckIn] Running Loop=11 2024-02-16T18:47:02.671Z,1708109222.671 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T18:47:02.671Z,1708109222.671 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T18:47:04.669Z,1708109224.669 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184703.00,A,3648.43507,N,12147.12125,W,3.052,185.13,160224,,,D*71 2024-02-16T18:47:04.672Z,1708109224.672 [NAL9602](INFO): GPS fix at 20240216T184703: (36.807251, -121.785354) 2024-02-16T18:47:04.691Z,1708109224.691 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T18:47:04.691Z,1708109224.691 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T18:47:12.214Z,1708109232.214 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0027.lzma 2024-02-16T18:47:13.216Z,1708109233.216 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0027.lzma.bak 2024-02-16T18:47:13.216Z,1708109233.216 [DataOverHttps](INFO): SBD MOMSN=19285377 2024-02-16T18:47:23.262Z,1708109243.262 [NAL9602](INFO): SBD MO Status=0, MOMSN=47331, MT Status=0, MTMSN=0 2024-02-16T18:47:23.262Z,1708109243.262 [NAL9602](INFO): No messages in MT queue 2024-02-16T18:47:28.946Z,1708109248.946 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240216T174825/Express0028.lzma 2024-02-16T18:47:29.948Z,1708109249.948 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0028.lzma.bak 2024-02-16T18:47:29.948Z,1708109249.948 [DataOverHttps](INFO): SBD MOMSN=19285380 2024-02-16T18:47:31.347Z,1708109251.347 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T18:47:31.347Z,1708109251.347 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T18:47:31.347Z,1708109251.347 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T18:47:53.959Z,1708109273.959 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T18:52:31.944Z,1708109551.944 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T18:52:31.944Z,1708109551.944 [Default:CheckIn:C.Wait] Stopped 2024-02-16T18:52:31.944Z,1708109551.944 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T18:52:31.945Z,1708109551.945 [Default:CheckIn:D] Running Loop=1 2024-02-16T18:52:32.337Z,1708109552.337 [Default:CheckIn:D] Stopped 2024-02-16T18:52:32.337Z,1708109552.337 [Default:CheckIn:E] Running Loop=1 2024-02-16T18:52:32.746Z,1708109552.746 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.333968 min 2024-02-16T18:52:32.746Z,1708109552.746 [Default:CheckIn:E] Stopped 2024-02-16T18:52:32.747Z,1708109552.747 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T18:52:32.747Z,1708109552.747 [Default:CheckIn] Stopped 2024-02-16T18:52:32.747Z,1708109552.747 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T18:52:32.747Z,1708109552.747 [Default:CheckIn](INFO): Running loop #12 2024-02-16T18:52:32.747Z,1708109552.747 [Default:CheckIn] Running Loop=12 2024-02-16T18:52:32.747Z,1708109552.747 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T18:52:32.747Z,1708109552.747 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T18:52:34.759Z,1708109554.759 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185234.00,A,3648.16689,N,12147.17655,W,2.158,198.00,160224,,,D*75 2024-02-16T18:52:34.761Z,1708109554.761 [NAL9602](INFO): GPS fix at 20240216T185234: (36.802782, -121.786276) 2024-02-16T18:52:34.772Z,1708109554.772 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T18:52:34.772Z,1708109554.772 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T18:52:41.994Z,1708109561.994 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0030.lzma 2024-02-16T18:52:42.996Z,1708109562.996 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0030.lzma.bak 2024-02-16T18:52:42.996Z,1708109562.996 [DataOverHttps](INFO): SBD MOMSN=19285392 2024-02-16T18:52:50.165Z,1708109570.165 [NAL9602](INFO): SBD MO Status=0, MOMSN=47332, MT Status=0, MTMSN=0 2024-02-16T18:52:50.166Z,1708109570.166 [NAL9602](INFO): No messages in MT queue 2024-02-16T18:53:02.062Z,1708109582.062 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240216T174825/Express0031.lzma 2024-02-16T18:53:03.065Z,1708109583.065 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0031.lzma.bak 2024-02-16T18:53:03.065Z,1708109583.065 [DataOverHttps](INFO): SBD MOMSN=19285395 2024-02-16T18:53:04.336Z,1708109584.336 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T18:53:04.336Z,1708109584.336 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T18:53:04.336Z,1708109584.336 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T18:53:20.875Z,1708109600.875 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T18:58:04.897Z,1708109884.897 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T18:58:04.898Z,1708109884.898 [Default:CheckIn:C.Wait] Stopped 2024-02-16T18:58:04.898Z,1708109884.898 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T18:58:04.898Z,1708109884.898 [Default:CheckIn:D] Running Loop=1 2024-02-16T18:58:05.299Z,1708109885.299 [Default:CheckIn:D] Stopped 2024-02-16T18:58:05.299Z,1708109885.299 [Default:CheckIn:E] Running Loop=1 2024-02-16T18:58:05.710Z,1708109885.710 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.883333 min 2024-02-16T18:58:05.711Z,1708109885.711 [Default:CheckIn:E] Stopped 2024-02-16T18:58:05.711Z,1708109885.711 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T18:58:05.711Z,1708109885.711 [Default:CheckIn] Stopped 2024-02-16T18:58:05.711Z,1708109885.711 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T18:58:05.711Z,1708109885.711 [Default:CheckIn](INFO): Running loop #13 2024-02-16T18:58:05.711Z,1708109885.711 [Default:CheckIn] Running Loop=13 2024-02-16T18:58:05.712Z,1708109885.712 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T18:58:05.712Z,1708109885.712 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T18:58:07.717Z,1708109887.717 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185806.00,A,3648.13210,N,12147.21000,W,0.175,234.53,160224,,,D*72 2024-02-16T18:58:07.720Z,1708109887.720 [NAL9602](INFO): GPS fix at 20240216T185806: (36.802202, -121.786833) 2024-02-16T18:58:07.751Z,1708109887.751 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T18:58:07.751Z,1708109887.751 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T18:58:15.510Z,1708109895.510 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240216T174825/Courier0033.lzma 2024-02-16T18:58:16.512Z,1708109896.512 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0033.lzma.bak 2024-02-16T18:58:16.512Z,1708109896.512 [DataOverHttps](INFO): SBD MOMSN=19285433 2024-02-16T18:58:32.442Z,1708109912.442 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20240216T174825/Express0034.lzma 2024-02-16T18:58:33.444Z,1708109913.444 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0034.lzma.bak 2024-02-16T18:58:33.445Z,1708109913.445 [DataOverHttps](INFO): SBD MOMSN=19285436 2024-02-16T18:58:34.796Z,1708109914.796 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T18:58:34.796Z,1708109914.796 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T18:58:34.796Z,1708109914.796 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T19:01:28.533Z,1708110088.533 [NAL9602](INFO): SBD MO Status=0, MOMSN=47333, MT Status=0, MTMSN=0 2024-02-16T19:01:28.533Z,1708110088.533 [NAL9602](INFO): No messages in MT queue 2024-02-16T19:01:59.224Z,1708110119.224 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T19:03:35.389Z,1708110215.389 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T19:03:35.390Z,1708110215.390 [Default:CheckIn:C.Wait] Stopped 2024-02-16T19:03:35.390Z,1708110215.390 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T19:03:35.391Z,1708110215.391 [Default:CheckIn:D] Running Loop=1 2024-02-16T19:03:35.803Z,1708110215.803 [Default:CheckIn:D] Stopped 2024-02-16T19:03:35.803Z,1708110215.803 [Default:CheckIn:E] Running Loop=1 2024-02-16T19:03:36.193Z,1708110216.193 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.391740 min 2024-02-16T19:03:36.193Z,1708110216.193 [Default:CheckIn:E] Stopped 2024-02-16T19:03:36.193Z,1708110216.193 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T19:03:36.193Z,1708110216.193 [Default:CheckIn] Stopped 2024-02-16T19:03:36.193Z,1708110216.193 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T19:03:36.193Z,1708110216.193 [Default:CheckIn](INFO): Running loop #14 2024-02-16T19:03:36.194Z,1708110216.194 [Default:CheckIn] Running Loop=14 2024-02-16T19:03:36.194Z,1708110216.194 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T19:03:36.194Z,1708110216.194 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T19:03:38.209Z,1708110218.209 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190337.00,A,3648.13139,N,12147.21143,W,0.039,234.53,160224,,,D*78 2024-02-16T19:03:38.212Z,1708110218.212 [NAL9602](INFO): GPS fix at 20240216T190337: (36.802190, -121.786857) 2024-02-16T19:03:38.222Z,1708110218.222 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T19:03:38.222Z,1708110218.222 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T19:03:45.818Z,1708110225.818 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0036.lzma 2024-02-16T19:03:46.820Z,1708110226.820 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0036.lzma.bak 2024-02-16T19:03:46.820Z,1708110226.820 [DataOverHttps](INFO): SBD MOMSN=19285440 2024-02-16T19:03:56.793Z,1708110236.793 [NAL9602](INFO): SBD MO Status=2, MOMSN=47334, MT Status=2, MTMSN=0 2024-02-16T19:03:56.793Z,1708110236.793 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-16T19:04:02.670Z,1708110242.670 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20240216T174825/Express0037.lzma 2024-02-16T19:04:03.672Z,1708110243.672 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0037.lzma.bak 2024-02-16T19:04:03.673Z,1708110243.673 [DataOverHttps](INFO): SBD MOMSN=19285443 2024-02-16T19:04:04.886Z,1708110244.886 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T19:04:04.886Z,1708110244.886 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T19:04:04.886Z,1708110244.886 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T19:04:20.630Z,1708110260.630 [NAL9602](INFO): SBD MO Status=2, MOMSN=47334, MT Status=2, MTMSN=0 2024-02-16T19:04:20.631Z,1708110260.631 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-16T19:04:46.482Z,1708110286.482 [NAL9602](INFO): SBD MO Status=2, MOMSN=47334, MT Status=2, MTMSN=0 2024-02-16T19:04:46.482Z,1708110286.482 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-02-16T19:06:05.260Z,1708110365.260 [NAL9602](INFO): SBD MO Status=0, MOMSN=47334, MT Status=0, MTMSN=0 2024-02-16T19:06:05.260Z,1708110365.260 [NAL9602](INFO): No messages in MT queue 2024-02-16T19:06:35.962Z,1708110395.962 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T19:09:05.457Z,1708110545.457 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T19:09:05.458Z,1708110545.458 [Default:CheckIn:C.Wait] Stopped 2024-02-16T19:09:05.458Z,1708110545.458 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T19:09:05.458Z,1708110545.458 [Default:CheckIn:D] Running Loop=1 2024-02-16T19:09:05.865Z,1708110545.865 [Default:CheckIn:D] Stopped 2024-02-16T19:09:05.865Z,1708110545.865 [Default:CheckIn:E] Running Loop=1 2024-02-16T19:09:06.266Z,1708110546.266 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.892773 min 2024-02-16T19:09:06.267Z,1708110546.267 [Default:CheckIn:E] Stopped 2024-02-16T19:09:06.267Z,1708110546.267 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T19:09:06.267Z,1708110546.267 [Default:CheckIn] Stopped 2024-02-16T19:09:06.267Z,1708110546.267 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T19:09:06.267Z,1708110546.267 [Default:CheckIn](INFO): Running loop #15 2024-02-16T19:09:06.267Z,1708110546.267 [Default:CheckIn] Running Loop=15 2024-02-16T19:09:06.267Z,1708110546.267 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T19:09:06.267Z,1708110546.267 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T19:09:08.284Z,1708110548.284 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190907.00,A,3648.13034,N,12147.20907,W,0.039,234.53,160224,,,D*74 2024-02-16T19:09:08.287Z,1708110548.287 [NAL9602](INFO): GPS fix at 20240216T190907: (36.802172, -121.786818) 2024-02-16T19:09:08.297Z,1708110548.297 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T19:09:08.297Z,1708110548.297 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T19:09:15.222Z,1708110555.222 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0039.lzma 2024-02-16T19:09:16.224Z,1708110556.224 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0039.lzma.bak 2024-02-16T19:09:16.224Z,1708110556.224 [DataOverHttps](INFO): SBD MOMSN=19285447 2024-02-16T19:09:32.066Z,1708110572.066 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240216T174825/Express0040.lzma 2024-02-16T19:09:33.069Z,1708110573.069 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0040.lzma.bak 2024-02-16T19:09:33.069Z,1708110573.069 [DataOverHttps](INFO): SBD MOMSN=19285450 2024-02-16T19:09:34.143Z,1708110574.143 [NAL9602](INFO): SBD MO Status=0, MOMSN=47335, MT Status=0, MTMSN=0 2024-02-16T19:09:34.143Z,1708110574.143 [NAL9602](INFO): No messages in MT queue 2024-02-16T19:09:34.555Z,1708110574.555 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T19:09:34.555Z,1708110574.555 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T19:09:34.555Z,1708110574.555 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T19:10:04.840Z,1708110604.840 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T19:14:35.137Z,1708110875.137 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T19:14:35.137Z,1708110875.137 [Default:CheckIn:C.Wait] Stopped 2024-02-16T19:14:35.137Z,1708110875.137 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T19:14:35.137Z,1708110875.137 [Default:CheckIn:D] Running Loop=1 2024-02-16T19:14:35.526Z,1708110875.526 [Default:CheckIn:D] Stopped 2024-02-16T19:14:35.526Z,1708110875.526 [Default:CheckIn:E] Running Loop=1 2024-02-16T19:14:35.931Z,1708110875.931 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.387118 min 2024-02-16T19:14:35.932Z,1708110875.932 [Default:CheckIn:E] Stopped 2024-02-16T19:14:35.932Z,1708110875.932 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T19:14:35.932Z,1708110875.932 [Default:CheckIn] Stopped 2024-02-16T19:14:35.932Z,1708110875.932 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T19:14:35.932Z,1708110875.932 [Default:CheckIn](INFO): Running loop #16 2024-02-16T19:14:35.932Z,1708110875.932 [Default:CheckIn] Running Loop=16 2024-02-16T19:14:35.932Z,1708110875.932 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T19:14:35.932Z,1708110875.932 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T19:14:37.938Z,1708110877.938 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191437.00,A,3648.15272,N,12147.21734,W,1.050,238.61,160224,,,D*71 2024-02-16T19:14:37.940Z,1708110877.940 [NAL9602](INFO): GPS fix at 20240216T191437: (36.802545, -121.786956) 2024-02-16T19:14:37.967Z,1708110877.967 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T19:14:37.967Z,1708110877.967 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T19:14:39.556Z,1708110879.556 [NAL9602](FAULT): LCB fault: Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated. 2024-02-16T19:14:39.556Z,1708110879.556 [NAL9602] Hardware Fault, FailCount= 1 2024-02-16T19:14:39.556Z,1708110879.556 [NAL9602](ERROR): Hardware Fault 2024-02-16T19:14:39.601Z,1708110879.601 [CBIT](ERROR): Hardware Fault in component: NAL9602 2024-02-16T19:14:39.959Z,1708110879.959 [NAL9602](INFO): Powering down 2024-02-16T19:14:40.800Z,1708110880.800 [CBIT](INFO): Clearing failed state for component NAL9602 2024-02-16T19:14:40.800Z,1708110880.800 [NAL9602] No Fault, FailCount= 1 2024-02-16T19:14:45.142Z,1708110885.142 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0042.lzma 2024-02-16T19:14:46.144Z,1708110886.144 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0042.lzma.bak 2024-02-16T19:14:46.144Z,1708110886.144 [DataOverHttps](INFO): SBD MOMSN=19285454 2024-02-16T19:15:04.546Z,1708110904.546 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20240216T174825/Express0043.lzma 2024-02-16T19:15:05.548Z,1708110905.548 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0043.lzma.bak 2024-02-16T19:15:05.549Z,1708110905.549 [DataOverHttps](INFO): SBD MOMSN=19285457 2024-02-16T19:15:06.702Z,1708110906.702 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T19:15:06.702Z,1708110906.702 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T19:15:06.702Z,1708110906.702 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T19:15:10.320Z,1708110910.320 [NAL9602](INFO): Powering up NAL9602 2024-02-16T19:15:21.236Z,1708110921.236 [NAL9602](INFO): NAL9602 initialized 2024-02-16T19:15:52.340Z,1708110952.340 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T19:20:07.290Z,1708111207.290 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T19:20:07.290Z,1708111207.290 [Default:CheckIn:C.Wait] Stopped 2024-02-16T19:20:07.290Z,1708111207.290 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T19:20:07.290Z,1708111207.290 [Default:CheckIn:D] Running Loop=1 2024-02-16T19:20:07.693Z,1708111207.693 [Default:CheckIn:D] Stopped 2024-02-16T19:20:07.693Z,1708111207.693 [Default:CheckIn:E] Running Loop=1 2024-02-16T19:20:08.117Z,1708111208.117 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.923234 min 2024-02-16T19:20:08.117Z,1708111208.117 [Default:CheckIn:E] Stopped 2024-02-16T19:20:08.117Z,1708111208.117 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T19:20:08.117Z,1708111208.117 [Default:CheckIn] Stopped 2024-02-16T19:20:08.117Z,1708111208.117 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T19:20:08.117Z,1708111208.117 [Default:CheckIn](INFO): Running loop #17 2024-02-16T19:20:08.117Z,1708111208.117 [Default:CheckIn] Running Loop=17 2024-02-16T19:20:08.117Z,1708111208.117 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T19:20:08.117Z,1708111208.117 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T19:20:10.097Z,1708111210.097 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192009.00,A,3648.14209,N,12147.22752,W,0.039,192.54,160224,,,A*7B 2024-02-16T19:20:10.099Z,1708111210.099 [NAL9602](INFO): GPS fix at 20240216T192009: (36.802368, -121.787125) 2024-02-16T19:20:10.110Z,1708111210.110 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T19:20:10.110Z,1708111210.110 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T19:20:10.575Z,1708111210.575 [NAL9602](ERROR): parseGSV uart error: serial timeout 2024-02-16T19:20:17.238Z,1708111217.238 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0045.lzma 2024-02-16T19:20:18.240Z,1708111218.240 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0045.lzma.bak 2024-02-16T19:20:18.240Z,1708111218.240 [DataOverHttps](INFO): SBD MOMSN=19285460 2024-02-16T19:20:20.601Z,1708111220.601 [NAL9602](INFO): SBD MO Status=0, MOMSN=47336, MT Status=0, MTMSN=0 2024-02-16T19:20:20.602Z,1708111220.602 [NAL9602](INFO): No messages in MT queue 2024-02-16T19:20:34.028Z,1708111234.028 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20240216T174825/Express0046.lzma 2024-02-16T19:20:35.024Z,1708111235.024 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0046.lzma.bak 2024-02-16T19:20:35.025Z,1708111235.025 [DataOverHttps](INFO): SBD MOMSN=19285463 2024-02-16T19:20:36.375Z,1708111236.375 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T19:20:36.375Z,1708111236.375 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T19:20:36.375Z,1708111236.375 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T19:20:51.302Z,1708111251.302 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T19:25:36.963Z,1708111536.963 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T19:25:36.964Z,1708111536.964 [Default:CheckIn:C.Wait] Stopped 2024-02-16T19:25:36.964Z,1708111536.964 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T19:25:36.964Z,1708111536.964 [Default:CheckIn:D] Running Loop=1 2024-02-16T19:25:37.349Z,1708111537.349 [Default:CheckIn:D] Stopped 2024-02-16T19:25:37.349Z,1708111537.349 [Default:CheckIn:E] Running Loop=1 2024-02-16T19:25:37.754Z,1708111537.754 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.417497 min 2024-02-16T19:25:37.754Z,1708111537.754 [Default:CheckIn:E] Stopped 2024-02-16T19:25:37.755Z,1708111537.755 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T19:25:37.755Z,1708111537.755 [Default:CheckIn] Stopped 2024-02-16T19:25:37.755Z,1708111537.755 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T19:25:37.755Z,1708111537.755 [Default:CheckIn](INFO): Running loop #18 2024-02-16T19:25:37.755Z,1708111537.755 [Default:CheckIn] Running Loop=18 2024-02-16T19:25:37.755Z,1708111537.755 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T19:25:37.755Z,1708111537.755 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T19:25:39.765Z,1708111539.765 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192539.00,A,3648.14176,N,12147.22777,W,0.019,192.54,160224,,,D*76 2024-02-16T19:25:39.768Z,1708111539.768 [NAL9602](INFO): GPS fix at 20240216T192539: (36.802363, -121.787130) 2024-02-16T19:25:39.803Z,1708111539.803 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T19:25:39.803Z,1708111539.803 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T19:25:47.438Z,1708111547.438 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0048.lzma 2024-02-16T19:25:48.440Z,1708111548.440 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0048.lzma.bak 2024-02-16T19:25:48.441Z,1708111548.441 [DataOverHttps](INFO): SBD MOMSN=19285468 2024-02-16T19:25:49.897Z,1708111549.897 [NAL9602](INFO): SBD MO Status=0, MOMSN=47337, MT Status=0, MTMSN=0 2024-02-16T19:25:49.897Z,1708111549.897 [NAL9602](INFO): No messages in MT queue 2024-02-16T19:26:04.370Z,1708111564.370 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240216T174825/Express0049.lzma 2024-02-16T19:26:05.372Z,1708111565.372 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0049.lzma.bak 2024-02-16T19:26:05.373Z,1708111565.373 [DataOverHttps](INFO): SBD MOMSN=19285472 2024-02-16T19:26:06.486Z,1708111566.486 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T19:26:06.487Z,1708111566.487 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T19:26:06.487Z,1708111566.487 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T19:26:20.599Z,1708111580.599 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T19:29:49.477Z,1708111789.477 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-16T19:30:01.985Z,1708111801.985 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-16T19:30:14.913Z,1708111814.913 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-16T19:30:28.245Z,1708111828.245 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-16T19:30:41.178Z,1708111841.178 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-16T19:30:54.507Z,1708111854.507 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-16T19:31:07.029Z,1708111867.029 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-02-16T19:31:07.069Z,1708111867.069 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T19:31:07.069Z,1708111867.069 [Default:CheckIn:C.Wait] Stopped 2024-02-16T19:31:07.069Z,1708111867.069 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T19:31:07.069Z,1708111867.069 [Default:CheckIn:D] Running Loop=1 2024-02-16T19:31:07.473Z,1708111867.473 [Default:CheckIn:D] Stopped 2024-02-16T19:31:07.473Z,1708111867.473 [Default:CheckIn:E] Running Loop=1 2024-02-16T19:31:07.870Z,1708111867.870 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.919580 min 2024-02-16T19:31:07.870Z,1708111867.870 [Default:CheckIn:E] Stopped 2024-02-16T19:31:07.870Z,1708111867.870 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T19:31:07.870Z,1708111867.870 [Default:CheckIn] Stopped 2024-02-16T19:31:07.870Z,1708111867.870 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T19:31:07.870Z,1708111867.870 [Default:CheckIn](INFO): Running loop #19 2024-02-16T19:31:07.870Z,1708111867.870 [Default:CheckIn] Running Loop=19 2024-02-16T19:31:07.871Z,1708111867.871 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T19:31:07.871Z,1708111867.871 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T19:31:09.869Z,1708111869.869 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193109.00,A,3648.14175,N,12147.22793,W,0.019,192.54,160224,,,D*79 2024-02-16T19:31:09.872Z,1708111869.872 [NAL9602](INFO): GPS fix at 20240216T193109: (36.802363, -121.787132) 2024-02-16T19:31:09.883Z,1708111869.883 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T19:31:09.883Z,1708111869.883 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T19:31:16.982Z,1708111876.982 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0051.lzma 2024-02-16T19:31:17.984Z,1708111877.984 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0051.lzma.bak 2024-02-16T19:31:17.984Z,1708111877.984 [DataOverHttps](INFO): SBD MOMSN=19285483 2024-02-16T19:31:34.141Z,1708111894.141 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240216T174825/Express0052.lzma 2024-02-16T19:31:34.915Z,1708111894.915 [NAL9602](INFO): SBD MO Status=0, MOMSN=47338, MT Status=0, MTMSN=0 2024-02-16T19:31:34.915Z,1708111894.915 [NAL9602](INFO): No messages in MT queue 2024-02-16T19:31:35.036Z,1708111895.036 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0052.lzma.bak 2024-02-16T19:31:35.037Z,1708111895.037 [DataOverHttps](INFO): SBD MOMSN=19285486 2024-02-16T19:31:36.149Z,1708111896.149 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T19:31:36.149Z,1708111896.149 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T19:31:36.149Z,1708111896.149 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T19:32:05.612Z,1708111925.612 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T19:33:47.437Z,1708112027.437 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6. 2024-02-16T19:33:47.440Z,1708112027.440 [BPC1](INFO): Received data from all battery sticks. 2024-02-16T19:36:36.742Z,1708112196.742 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T19:36:36.742Z,1708112196.742 [Default:CheckIn:C.Wait] Stopped 2024-02-16T19:36:36.743Z,1708112196.743 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T19:36:36.743Z,1708112196.743 [Default:CheckIn:D] Running Loop=1 2024-02-16T19:36:37.126Z,1708112197.126 [Default:CheckIn:D] Stopped 2024-02-16T19:36:37.126Z,1708112197.126 [Default:CheckIn:E] Running Loop=1 2024-02-16T19:36:37.530Z,1708112197.530 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.413786 min 2024-02-16T19:36:37.530Z,1708112197.530 [Default:CheckIn:E] Stopped 2024-02-16T19:36:37.530Z,1708112197.530 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T19:36:37.530Z,1708112197.530 [Default:CheckIn] Stopped 2024-02-16T19:36:37.531Z,1708112197.531 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T19:36:37.531Z,1708112197.531 [Default:CheckIn](INFO): Running loop #20 2024-02-16T19:36:37.531Z,1708112197.531 [Default:CheckIn] Running Loop=20 2024-02-16T19:36:37.531Z,1708112197.531 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T19:36:37.531Z,1708112197.531 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T19:36:39.542Z,1708112199.542 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193638.00,A,3648.17132,N,12147.27764,W,0.039,296.55,160224,,,D*75 2024-02-16T19:36:39.545Z,1708112199.545 [NAL9602](INFO): GPS fix at 20240216T193638: (36.802855, -121.787961) 2024-02-16T19:36:39.556Z,1708112199.556 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T19:36:39.556Z,1708112199.556 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T19:36:44.789Z,1708112204.789 [NAL9602](INFO): SBD MO Status=0, MOMSN=47339, MT Status=0, MTMSN=0 2024-02-16T19:36:44.789Z,1708112204.789 [NAL9602](INFO): No messages in MT queue 2024-02-16T19:36:46.798Z,1708112206.798 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20240216T174825/Courier0054.lzma 2024-02-16T19:36:47.800Z,1708112207.800 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0054.lzma.bak 2024-02-16T19:36:47.801Z,1708112207.801 [DataOverHttps](INFO): SBD MOMSN=19285494 2024-02-16T19:37:06.222Z,1708112226.222 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240216T174825/Express0055.lzma 2024-02-16T19:37:07.224Z,1708112227.224 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0055.lzma.bak 2024-02-16T19:37:07.225Z,1708112227.225 [DataOverHttps](INFO): SBD MOMSN=19285498 2024-02-16T19:37:08.687Z,1708112228.687 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T19:37:08.687Z,1708112228.687 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T19:37:08.687Z,1708112228.687 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T19:37:15.547Z,1708112235.547 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T19:42:09.275Z,1708112529.275 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T19:42:09.275Z,1708112529.275 [Default:CheckIn:C.Wait] Stopped 2024-02-16T19:42:09.275Z,1708112529.275 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T19:42:09.275Z,1708112529.275 [Default:CheckIn:D] Running Loop=1 2024-02-16T19:42:09.672Z,1708112529.672 [Default:CheckIn:D] Stopped 2024-02-16T19:42:09.672Z,1708112529.672 [Default:CheckIn:E] Running Loop=1 2024-02-16T19:42:10.074Z,1708112530.074 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.956217 min 2024-02-16T19:42:10.074Z,1708112530.074 [Default:CheckIn:E] Stopped 2024-02-16T19:42:10.074Z,1708112530.074 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T19:42:10.074Z,1708112530.074 [Default:CheckIn] Stopped 2024-02-16T19:42:10.074Z,1708112530.074 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T19:42:10.075Z,1708112530.075 [Default:CheckIn](INFO): Running loop #21 2024-02-16T19:42:10.075Z,1708112530.075 [Default:CheckIn] Running Loop=21 2024-02-16T19:42:10.075Z,1708112530.075 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T19:42:10.075Z,1708112530.075 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T19:42:12.092Z,1708112532.092 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194211.00,A,3648.17401,N,12147.27908,W,0.019,296.55,160224,,,A*7B 2024-02-16T19:42:12.094Z,1708112532.094 [NAL9602](INFO): GPS fix at 20240216T194211: (36.802900, -121.787985) 2024-02-16T19:42:12.105Z,1708112532.105 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T19:42:12.105Z,1708112532.105 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T19:42:19.786Z,1708112539.786 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0057.lzma 2024-02-16T19:42:20.788Z,1708112540.788 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0057.lzma.bak 2024-02-16T19:42:20.788Z,1708112540.788 [DataOverHttps](INFO): SBD MOMSN=19285510 2024-02-16T19:42:36.774Z,1708112556.774 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240216T174825/Express0058.lzma 2024-02-16T19:42:37.776Z,1708112557.776 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0058.lzma.bak 2024-02-16T19:42:37.777Z,1708112557.777 [DataOverHttps](INFO): SBD MOMSN=19285514 2024-02-16T19:42:39.187Z,1708112559.187 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T19:42:39.187Z,1708112559.187 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T19:42:39.187Z,1708112559.187 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T19:45:41.765Z,1708112741.765 [NAL9602](INFO): SBD MO Status=0, MOMSN=47340, MT Status=0, MTMSN=0 2024-02-16T19:45:41.765Z,1708112741.765 [NAL9602](INFO): No messages in MT queue 2024-02-16T19:46:12.463Z,1708112772.463 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T19:47:39.758Z,1708112859.758 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T19:47:39.758Z,1708112859.758 [Default:CheckIn:C.Wait] Stopped 2024-02-16T19:47:39.758Z,1708112859.758 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T19:47:39.758Z,1708112859.758 [Default:CheckIn:D] Running Loop=1 2024-02-16T19:47:40.167Z,1708112860.167 [Default:CheckIn:D] Stopped 2024-02-16T19:47:40.167Z,1708112860.167 [Default:CheckIn:E] Running Loop=1 2024-02-16T19:47:40.563Z,1708112860.563 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.464461 min 2024-02-16T19:47:40.564Z,1708112860.564 [Default:CheckIn:E] Stopped 2024-02-16T19:47:40.564Z,1708112860.564 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T19:47:40.564Z,1708112860.564 [Default:CheckIn] Stopped 2024-02-16T19:47:40.564Z,1708112860.564 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T19:47:40.564Z,1708112860.564 [Default:CheckIn](INFO): Running loop #22 2024-02-16T19:47:40.564Z,1708112860.564 [Default:CheckIn] Running Loop=22 2024-02-16T19:47:40.564Z,1708112860.564 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T19:47:40.564Z,1708112860.564 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T19:47:42.573Z,1708112862.573 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194741.00,A,3648.17197,N,12147.27948,W,0.039,296.55,160224,,,A*77 2024-02-16T19:47:42.576Z,1708112862.576 [NAL9602](INFO): GPS fix at 20240216T194741: (36.802866, -121.787991) 2024-02-16T19:47:42.592Z,1708112862.592 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T19:47:42.592Z,1708112862.592 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T19:47:50.534Z,1708112870.534 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240216T174825/Courier0060.lzma 2024-02-16T19:47:51.536Z,1708112871.536 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0060.lzma.bak 2024-02-16T19:47:51.537Z,1708112871.537 [DataOverHttps](INFO): SBD MOMSN=19285558 2024-02-16T19:48:07.386Z,1708112887.386 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240216T174825/Express0061.lzma 2024-02-16T19:48:07.621Z,1708112887.621 [NAL9602](INFO): SBD MO Status=0, MOMSN=47341, MT Status=0, MTMSN=0 2024-02-16T19:48:07.621Z,1708112887.621 [NAL9602](INFO): No messages in MT queue 2024-02-16T19:48:08.388Z,1708112888.388 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0061.lzma.bak 2024-02-16T19:48:08.389Z,1708112888.389 [DataOverHttps](INFO): SBD MOMSN=19285561 2024-02-16T19:48:09.648Z,1708112889.648 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T19:48:09.648Z,1708112889.648 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T19:48:09.648Z,1708112889.648 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T19:48:38.320Z,1708112918.320 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T19:53:10.320Z,1708113190.320 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T19:53:10.320Z,1708113190.320 [Default:CheckIn:C.Wait] Stopped 2024-02-16T19:53:10.320Z,1708113190.320 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T19:53:10.321Z,1708113190.321 [Default:CheckIn:D] Running Loop=1 2024-02-16T19:53:10.726Z,1708113190.726 [Default:CheckIn:D] Stopped 2024-02-16T19:53:10.726Z,1708113190.726 [Default:CheckIn:E] Running Loop=1 2024-02-16T19:53:11.134Z,1708113191.134 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.973787 min 2024-02-16T19:53:11.134Z,1708113191.134 [Default:CheckIn:E] Stopped 2024-02-16T19:53:11.134Z,1708113191.134 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T19:53:11.134Z,1708113191.134 [Default:CheckIn] Stopped 2024-02-16T19:53:11.134Z,1708113191.134 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T19:53:11.134Z,1708113191.134 [Default:CheckIn](INFO): Running loop #23 2024-02-16T19:53:11.134Z,1708113191.134 [Default:CheckIn] Running Loop=23 2024-02-16T19:53:11.135Z,1708113191.135 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T19:53:11.135Z,1708113191.135 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T19:53:13.142Z,1708113193.142 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195312.00,A,3648.17171,N,12147.27636,W,0.097,296.55,160224,,,A*7E 2024-02-16T19:53:13.144Z,1708113193.144 [NAL9602](INFO): GPS fix at 20240216T195312: (36.802862, -121.787939) 2024-02-16T19:53:13.172Z,1708113193.172 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T19:53:13.172Z,1708113193.172 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T19:53:21.061Z,1708113201.061 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0063.lzma 2024-02-16T19:53:22.089Z,1708113202.089 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0063.lzma.bak 2024-02-16T19:53:22.089Z,1708113202.089 [DataOverHttps](INFO): SBD MOMSN=19285610 2024-02-16T19:53:38.114Z,1708113218.114 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240216T174825/Express0064.lzma 2024-02-16T19:53:39.116Z,1708113219.116 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0064.lzma.bak 2024-02-16T19:53:39.117Z,1708113219.117 [DataOverHttps](INFO): SBD MOMSN=19285613 2024-02-16T19:53:40.225Z,1708113220.225 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T19:53:40.225Z,1708113220.225 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T19:53:40.225Z,1708113220.225 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T19:55:10.712Z,1708113310.712 [NAL9602](INFO): SBD MO Status=0, MOMSN=47342, MT Status=0, MTMSN=0 2024-02-16T19:55:10.712Z,1708113310.712 [NAL9602](INFO): No messages in MT queue 2024-02-16T19:55:41.421Z,1708113341.421 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T19:58:40.822Z,1708113520.822 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T19:58:40.822Z,1708113520.822 [Default:CheckIn:C.Wait] Stopped 2024-02-16T19:58:40.822Z,1708113520.822 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T19:58:40.823Z,1708113520.823 [Default:CheckIn:D] Running Loop=1 2024-02-16T19:58:41.235Z,1708113521.235 [Default:CheckIn:D] Stopped 2024-02-16T19:58:41.235Z,1708113521.235 [Default:CheckIn:E] Running Loop=1 2024-02-16T19:58:41.630Z,1708113521.630 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.482275 min 2024-02-16T19:58:41.630Z,1708113521.630 [Default:CheckIn:E] Stopped 2024-02-16T19:58:41.630Z,1708113521.630 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T19:58:41.630Z,1708113521.630 [Default:CheckIn] Stopped 2024-02-16T19:58:41.630Z,1708113521.630 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T19:58:41.631Z,1708113521.631 [Default:CheckIn](INFO): Running loop #24 2024-02-16T19:58:41.631Z,1708113521.631 [Default:CheckIn] Running Loop=24 2024-02-16T19:58:41.631Z,1708113521.631 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T19:58:41.631Z,1708113521.631 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T19:58:43.637Z,1708113523.637 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195842.00,A,3648.17087,N,12147.29091,W,0.389,225.62,160224,,,A*7D 2024-02-16T19:58:43.640Z,1708113523.640 [NAL9602](INFO): GPS fix at 20240216T195842: (36.802848, -121.788182) 2024-02-16T19:58:43.651Z,1708113523.651 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T19:58:43.651Z,1708113523.651 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T19:58:52.962Z,1708113532.962 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0066.lzma 2024-02-16T19:58:53.965Z,1708113533.965 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0066.lzma.bak 2024-02-16T19:58:53.965Z,1708113533.965 [DataOverHttps](INFO): SBD MOMSN=19285618 2024-02-16T19:59:11.190Z,1708113551.190 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20240216T174825/Express0067.lzma 2024-02-16T19:59:12.192Z,1708113552.192 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0067.lzma.bak 2024-02-16T19:59:12.193Z,1708113552.193 [DataOverHttps](INFO): SBD MOMSN=19285621 2024-02-16T19:59:13.604Z,1708113553.604 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T19:59:13.604Z,1708113553.604 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T19:59:13.604Z,1708113553.604 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T20:03:46.416Z,1708113826.416 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-02-16T20:04:14.319Z,1708113854.319 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T20:04:14.319Z,1708113854.319 [Default:CheckIn:C.Wait] Stopped 2024-02-16T20:04:14.319Z,1708113854.319 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T20:04:14.320Z,1708113854.320 [Default:CheckIn:D] Running Loop=1 2024-02-16T20:04:14.705Z,1708113854.705 [Default:CheckIn:D] Stopped 2024-02-16T20:04:14.705Z,1708113854.705 [Default:CheckIn:E] Running Loop=1 2024-02-16T20:04:15.116Z,1708113855.116 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 144.040104 min 2024-02-16T20:04:15.116Z,1708113855.116 [Default:CheckIn:E] Stopped 2024-02-16T20:04:15.116Z,1708113855.116 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T20:04:15.116Z,1708113855.116 [Default:CheckIn] Stopped 2024-02-16T20:04:15.116Z,1708113855.116 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T20:04:15.117Z,1708113855.117 [Default:CheckIn](INFO): Running loop #25 2024-02-16T20:04:15.117Z,1708113855.117 [Default:CheckIn] Running Loop=25 2024-02-16T20:04:15.117Z,1708113855.117 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T20:04:15.117Z,1708113855.117 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T20:04:17.129Z,1708113857.129 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200416.00,A,3648.17078,N,12147.28295,W,0.428,294.98,160224,,,A*7B 2024-02-16T20:04:17.132Z,1708113857.132 [NAL9602](INFO): GPS fix at 20240216T200416: (36.802846, -121.788049) 2024-02-16T20:04:17.147Z,1708113857.147 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T20:04:17.147Z,1708113857.147 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T20:04:24.750Z,1708113864.750 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0069.lzma 2024-02-16T20:04:25.753Z,1708113865.753 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0069.lzma.bak 2024-02-16T20:04:25.753Z,1708113865.753 [DataOverHttps](INFO): SBD MOMSN=19285659 2024-02-16T20:04:41.678Z,1708113881.678 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20240216T174825/Express0070.lzma 2024-02-16T20:04:42.680Z,1708113882.680 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0070.lzma.bak 2024-02-16T20:04:42.681Z,1708113882.681 [DataOverHttps](INFO): SBD MOMSN=19285662 2024-02-16T20:04:43.798Z,1708113883.798 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T20:04:43.798Z,1708113883.798 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T20:04:43.798Z,1708113883.798 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T20:04:49.495Z,1708113889.495 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T20:09:44.524Z,1708114184.524 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T20:09:44.524Z,1708114184.524 [Default:CheckIn:C.Wait] Stopped 2024-02-16T20:09:44.524Z,1708114184.524 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T20:09:44.524Z,1708114184.524 [Default:CheckIn:D] Running Loop=1 2024-02-16T20:09:44.927Z,1708114184.927 [Default:CheckIn:D] Stopped 2024-02-16T20:09:44.927Z,1708114184.927 [Default:CheckIn:E] Running Loop=1 2024-02-16T20:09:45.322Z,1708114185.322 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.543815 min 2024-02-16T20:09:45.322Z,1708114185.322 [Default:CheckIn:E] Stopped 2024-02-16T20:09:45.322Z,1708114185.322 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T20:09:45.322Z,1708114185.322 [Default:CheckIn] Stopped 2024-02-16T20:09:45.322Z,1708114185.322 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T20:09:45.323Z,1708114185.323 [Default:CheckIn](INFO): Running loop #26 2024-02-16T20:09:45.323Z,1708114185.323 [Default:CheckIn] Running Loop=26 2024-02-16T20:09:45.323Z,1708114185.323 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T20:09:45.323Z,1708114185.323 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T20:09:47.329Z,1708114187.329 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200946.00,A,3648.16457,N,12147.27288,W,1.050,103.04,160224,,,A*7A 2024-02-16T20:09:47.332Z,1708114187.332 [NAL9602](INFO): GPS fix at 20240216T200946: (36.802743, -121.787881) 2024-02-16T20:09:47.343Z,1708114187.343 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T20:09:47.343Z,1708114187.343 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T20:09:55.154Z,1708114195.154 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0072.lzma 2024-02-16T20:09:56.156Z,1708114196.156 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0072.lzma.bak 2024-02-16T20:09:56.157Z,1708114196.157 [DataOverHttps](INFO): SBD MOMSN=19285667 2024-02-16T20:10:12.052Z,1708114212.052 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240216T174825/Express0073.lzma 2024-02-16T20:10:13.053Z,1708114213.053 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0073.lzma.bak 2024-02-16T20:10:13.053Z,1708114213.053 [DataOverHttps](INFO): SBD MOMSN=19285670 2024-02-16T20:10:14.411Z,1708114214.411 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T20:10:14.411Z,1708114214.411 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T20:10:14.411Z,1708114214.411 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T20:12:40.299Z,1708114360.299 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-02-16T20:12:40.299Z,1708114360.299 [DropWeight] Hardware Fault, FailCount= 1 2024-02-16T20:12:40.299Z,1708114360.299 [DropWeight](ERROR): Hardware Fault 2024-02-16T20:12:40.370Z,1708114360.370 [CommandExec](FAULT): Scheduling is paused 2024-02-16T20:12:40.375Z,1708114360.375 [CBIT](INFO): Critical error at 20240216T201240 2024-02-16T20:12:40.377Z,1708114360.377 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-02-16T20:12:40.377Z,1708114360.377 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-02-16T20:12:40.743Z,1708114360.743 [CBIT](INFO): Critical error at 20240216T201240 2024-02-16T20:14:49.644Z,1708114489.644 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-02-16T20:15:15.159Z,1708114515.159 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T20:15:15.159Z,1708114515.159 [Default:CheckIn:C.Wait] Stopped 2024-02-16T20:15:15.159Z,1708114515.159 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T20:15:15.160Z,1708114515.160 [Default:CheckIn:D] Running Loop=1 2024-02-16T20:15:15.576Z,1708114515.576 [Default:CheckIn:D] Stopped 2024-02-16T20:15:15.576Z,1708114515.576 [Default:CheckIn:E] Running Loop=1 2024-02-16T20:15:15.967Z,1708114515.967 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 155.054622 min 2024-02-16T20:15:15.968Z,1708114515.968 [Default:CheckIn:E] Stopped 2024-02-16T20:15:15.968Z,1708114515.968 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T20:15:15.968Z,1708114515.968 [Default:CheckIn] Stopped 2024-02-16T20:15:15.968Z,1708114515.968 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T20:15:15.968Z,1708114515.968 [Default:CheckIn](INFO): Running loop #27 2024-02-16T20:15:15.968Z,1708114515.968 [Default:CheckIn] Running Loop=27 2024-02-16T20:15:15.968Z,1708114515.968 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T20:15:15.968Z,1708114515.968 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T20:15:17.977Z,1708114517.977 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201517.00,A,3648.16752,N,12147.28409,W,0.214,103.04,160224,,,A*76 2024-02-16T20:15:17.980Z,1708114517.980 [NAL9602](INFO): GPS fix at 20240216T201517: (36.802792, -121.788068) 2024-02-16T20:15:17.990Z,1708114517.990 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T20:15:17.991Z,1708114517.991 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T20:15:25.794Z,1708114525.794 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20240216T174825/Courier0075.lzma 2024-02-16T20:15:26.796Z,1708114526.796 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0075.lzma.bak 2024-02-16T20:15:26.796Z,1708114526.796 [DataOverHttps](INFO): SBD MOMSN=19285699 2024-02-16T20:15:42.631Z,1708114542.631 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20240216T174825/Express0076.lzma 2024-02-16T20:15:43.632Z,1708114543.632 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0076.lzma.bak 2024-02-16T20:15:43.633Z,1708114543.633 [DataOverHttps](INFO): SBD MOMSN=19285704 2024-02-16T20:15:45.064Z,1708114545.064 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T20:15:45.064Z,1708114545.064 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T20:15:45.064Z,1708114545.064 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T20:15:50.697Z,1708114550.697 [NAL9602](INFO): Not Powering down - fast GPS 2024-02-16T20:20:45.777Z,1708114845.777 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-02-16T20:20:45.777Z,1708114845.777 [Default:CheckIn:C.Wait] Stopped 2024-02-16T20:20:45.777Z,1708114845.777 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T20:20:45.778Z,1708114845.778 [Default:CheckIn:D] Running Loop=1 2024-02-16T20:20:46.186Z,1708114846.186 [Default:CheckIn:D] Stopped 2024-02-16T20:20:46.186Z,1708114846.186 [Default:CheckIn:E] Running Loop=1 2024-02-16T20:20:46.576Z,1708114846.576 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 160.564779 min 2024-02-16T20:20:46.577Z,1708114846.577 [Default:CheckIn:E] Stopped 2024-02-16T20:20:46.577Z,1708114846.577 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-02-16T20:20:46.577Z,1708114846.577 [Default:CheckIn] Stopped 2024-02-16T20:20:46.577Z,1708114846.577 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T20:20:46.577Z,1708114846.577 [Default:CheckIn](INFO): Running loop #28 2024-02-16T20:20:46.577Z,1708114846.577 [Default:CheckIn] Running Loop=28 2024-02-16T20:20:46.577Z,1708114846.577 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-02-16T20:20:46.577Z,1708114846.577 [Default:CheckIn:Read_GPS] Running Loop=1 2024-02-16T20:20:48.589Z,1708114848.589 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202047.00,A,3648.16467,N,12147.27721,W,0.408,103.04,160224,,,A*7D 2024-02-16T20:20:48.592Z,1708114848.592 [NAL9602](INFO): GPS fix at 20240216T202047: (36.802745, -121.787954) 2024-02-16T20:20:48.627Z,1708114848.627 [Default:CheckIn:Read_GPS] Stopped 2024-02-16T20:20:48.627Z,1708114848.627 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-02-16T20:20:58.386Z,1708114858.386 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240216T174825/Courier0078.lzma 2024-02-16T20:20:59.389Z,1708114859.389 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Courier0078.lzma.bak 2024-02-16T20:20:59.389Z,1708114859.389 [DataOverHttps](INFO): SBD MOMSN=19285709 2024-02-16T20:21:15.284Z,1708114875.284 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20240216T174825/Express0079.lzma 2024-02-16T20:21:16.284Z,1708114876.284 [DataOverHttps](INFO): Moved sent file to Logs/20240216T174825/Express0079.lzma.bak 2024-02-16T20:21:16.285Z,1708114876.285 [DataOverHttps](INFO): SBD MOMSN=19285712 2024-02-16T20:21:17.756Z,1708114877.756 [Default:CheckIn:Read_Iridium] Stopped 2024-02-16T20:21:17.756Z,1708114877.756 [Default:CheckIn:C.Wait] Running Loop=1 2024-02-16T20:21:17.756Z,1708114877.756 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-02-16T20:21:19.358Z,1708114879.358 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-02-16T20:21:19.434Z,1708114879.434 [NAL9602](ERROR): received: +CSQ:0 OK342, 0, 0, 0, 0 OK 2024-02-16T20:25:50.444Z,1708115150.444 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-02-16T20:26:09.227Z,1708115169.227 [CommandExec](IMPORTANT): got command quit 2024-02-16T20:26:10.403Z,1708115170.403 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-16T20:26:10.403Z,1708115170.403 [CommandExec](INFO): Uninitializing the command executive. 2024-02-16T20:26:10.403Z,1708115170.403 [CommandExec](INFO): Uninitializing the command scheduler. 2024-02-16T20:26:10.403Z,1708115170.403 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:10.586Z,1708115170.586 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-02-16T20:26:10.587Z,1708115170.587 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-02-16T20:26:10.587Z,1708115170.587 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:10.587Z,1708115170.587 [NavChartDb](INFO): Join timeout helper Thread ID is 8076 2024-02-16T20:26:10.743Z,1708115170.743 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-16T20:26:10.743Z,1708115170.743 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:10.762Z,1708115170.762 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-02-16T20:26:10.763Z,1708115170.763 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:10.763Z,1708115170.763 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8077 2024-02-16T20:26:11.051Z,1708115171.051 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-16T20:26:11.051Z,1708115171.051 [WetLabsBB2FL](INFO): Powering down 2024-02-16T20:26:11.052Z,1708115171.052 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:11.063Z,1708115171.063 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-02-16T20:26:11.063Z,1708115171.063 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:11.063Z,1708115171.063 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8078 2024-02-16T20:26:11.239Z,1708115171.239 [CTD_Seabird](INFO): Powering down 2024-02-16T20:26:11.255Z,1708115171.255 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-16T20:26:11.259Z,1708115171.259 [CTD_Seabird](INFO): Powering down 2024-02-16T20:26:11.273Z,1708115171.273 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:11.286Z,1708115171.286 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-02-16T20:26:11.287Z,1708115171.287 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:11.287Z,1708115171.287 [Radio_Surface](INFO): Join timeout helper Thread ID is 8079 2024-02-16T20:26:11.595Z,1708115171.595 [Radio_Surface](INFO): Powering down 2024-02-16T20:26:11.595Z,1708115171.595 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-16T20:26:11.596Z,1708115171.596 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:11.606Z,1708115171.606 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-02-16T20:26:11.607Z,1708115171.607 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:11.607Z,1708115171.607 [Onboard](INFO): Join timeout helper Thread ID is 8080 2024-02-16T20:26:12.419Z,1708115172.419 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-02-16T20:26:15.515Z,1708115175.515 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-16T20:26:15.515Z,1708115175.515 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:15.523Z,1708115175.523 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-02-16T20:26:15.524Z,1708115175.524 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:15.524Z,1708115175.524 [DataOverHttps](INFO): Join timeout helper Thread ID is 8081 2024-02-16T20:26:16.435Z,1708115176.435 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-16T20:26:16.435Z,1708115176.435 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:16.439Z,1708115176.439 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-02-16T20:26:16.439Z,1708115176.439 [logger ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:16.440Z,1708115176.440 [logger](INFO): Join timeout helper Thread ID is 8082 2024-02-16T20:26:16.467Z,1708115176.467 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-16T20:26:16.467Z,1708115176.467 [logger ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:16.479Z,1708115176.479 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-02-16T20:26:16.479Z,1708115176.479 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:16.480Z,1708115176.480 [CommandLine](INFO): Join timeout helper Thread ID is 8083 2024-02-16T20:26:16.495Z,1708115176.495 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-16T20:26:16.495Z,1708115176.495 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:16.499Z,1708115176.499 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-02-16T20:26:16.499Z,1708115176.499 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:16.500Z,1708115176.500 [CommandExec](INFO): Join timeout helper Thread ID is 8084 2024-02-16T20:26:16.501Z,1708115176.501 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-02-16T20:26:16.501Z,1708115176.501 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:16.501Z,1708115176.501 [controlThread](INFO): Join timeout helper Thread ID is 8085 2024-02-16T20:26:16.683Z,1708115176.683 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-02-16T20:26:16.683Z,1708115176.683 [controlThread](DEBUG): Uninitializing ControlThread 2024-02-16T20:26:16.683Z,1708115176.683 [AHRS_M2](INFO): Powering down 2024-02-16T20:26:16.755Z,1708115176.755 [NAL9602](INFO): Powering down 2024-02-16T20:26:16.757Z,1708115176.757 [Sonardyne_Nano](INFO): Powering down 2024-02-16T20:26:16.876Z,1708115176.876 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-02-16T20:26:16.877Z,1708115176.877 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-02-16T20:26:16.877Z,1708115176.877 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-02-16T20:26:16.878Z,1708115176.878 [MissionManager](INFO): Uninitializing Mission Default 2024-02-16T20:26:16.878Z,1708115176.878 [Default] Stopped 2024-02-16T20:26:16.878Z,1708115176.878 [Default](DEBUG): Aggregate::uninitialize Default 2024-02-16T20:26:16.878Z,1708115176.878 [Default:B.GoToSurface] Stopped 2024-02-16T20:26:16.878Z,1708115176.878 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-02-16T20:26:16.878Z,1708115176.878 [Default:CheckIn] Stopped 2024-02-16T20:26:16.878Z,1708115176.878 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-02-16T20:26:16.879Z,1708115176.879 [Default:CheckIn:C.Wait] Stopped 2024-02-16T20:26:16.879Z,1708115176.879 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-02-16T20:26:16.881Z,1708115176.881 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-02-16T20:26:16.882Z,1708115176.882 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-02-16T20:26:16.882Z,1708115176.882 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-02-16T20:26:16.882Z,1708115176.882 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-02-16T20:26:16.883Z,1708115176.883 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-02-16T20:26:16.883Z,1708115176.883 [BuoyancyServo](INFO): Powering down 2024-02-16T20:26:16.895Z,1708115176.895 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-02-16T20:26:16.895Z,1708115176.895 [ElevatorServo](INFO): Powering down 2024-02-16T20:26:16.895Z,1708115176.895 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-02-16T20:26:16.896Z,1708115176.896 [MassServo](INFO): Powering down 2024-02-16T20:26:16.896Z,1708115176.896 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-02-16T20:26:16.896Z,1708115176.896 [RudderServo](INFO): Powering down 2024-02-16T20:26:16.897Z,1708115176.897 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-02-16T20:26:16.897Z,1708115176.897 [ThrusterHE](INFO): Powering down 2024-02-16T20:26:16.898Z,1708115176.898 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-02-16T20:26:16.899Z,1708115176.899 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-02-16T20:26:16.899Z,1708115176.899 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-02-16T20:26:16.899Z,1708115176.899 [CBIT](DEBUG): Powering off loads. 2024-02-16T20:26:16.910Z,1708115176.910 [CBIT](DEBUG): Disabling WDT. 2024-02-16T20:26:16.922Z,1708115176.922 [CBIT](DEBUG): Opening all GF detection circuits. 2024-02-16T20:26:16.923Z,1708115176.923 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:16.982Z,1708115176.982 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:16.985Z,1708115176.985 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:16.991Z,1708115176.991 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:17.063Z,1708115177.063 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:17.067Z,1708115177.067 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:17.108Z,1708115177.108 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-02-16T20:26:17.218Z,1708115177.218 [logger ThreadHandler](INFO): Thread cancelled.