2018-09-04T20:13:02.665Z,1536091982.665 [CommandLine](IMPORTANT): got command restart logs 2018-09-04T20:13:10.243Z,1536091990.243 [NAL9602](INFO): SBD MO Status=0, MOMSN=8473, MT Status=0, MTMSN=0 2018-09-04T20:13:10.244Z,1536091990.244 [NAL9602](INFO): No messages in MT queue 2018-09-04T20:13:55.846Z,1536092035.846 [NAL9602](INFO): GPS fix at 20180904T201354: (36.804704, -121.840898) 2018-09-04T20:13:55.916Z,1536092035.916 [Default:CheckIn:Read_GPS] Stopped 2018-09-04T20:13:55.916Z,1536092035.916 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-04T20:14:00.723Z,1536092040.723 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180904T193300/Courier0025.lzma 2018-09-04T20:14:01.505Z,1536092041.505 [DataOverHttps](INFO): Moved sent file to Logs/20180904T193300/Courier0025.lzma.bak 2018-09-04T20:14:01.505Z,1536092041.505 [DataOverHttps](INFO): SBD MOMSN=8467120 2018-09-04T20:14:10.769Z,1536092050.769 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20180904T201302/Courier0000.lzma 2018-09-04T20:14:11.555Z,1536092051.555 [DataOverHttps](INFO): Moved sent file to Logs/20180904T201302/Courier0000.lzma.bak 2018-09-04T20:14:11.555Z,1536092051.555 [DataOverHttps](INFO): SBD MOMSN=8467122 2018-09-04T20:14:20.874Z,1536092060.874 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180904T201302/Courier0003.lzma 2018-09-04T20:14:21.662Z,1536092061.662 [DataOverHttps](INFO): Moved sent file to Logs/20180904T201302/Courier0003.lzma.bak 2018-09-04T20:14:21.662Z,1536092061.662 [DataOverHttps](INFO): SBD MOMSN=8467126 2018-09-04T20:14:31.981Z,1536092071.981 [NAL9602](INFO): Powering down 2018-09-04T20:14:32.086Z,1536092072.086 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20180904T193300/Express0026.lzma 2018-09-04T20:14:32.883Z,1536092072.883 [DataOverHttps](INFO): Moved sent file to Logs/20180904T193300/Express0026.lzma.bak 2018-09-04T20:14:32.883Z,1536092072.883 [DataOverHttps](INFO): SBD MOMSN=8467128 2018-09-04T20:14:42.614Z,1536092082.614 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20180904T201302/Express0001.lzma 2018-09-04T20:14:43.411Z,1536092083.411 [DataOverHttps](INFO): Moved sent file to Logs/20180904T201302/Express0001.lzma.bak 2018-09-04T20:14:43.411Z,1536092083.411 [DataOverHttps](INFO): SBD MOMSN=8467133 2018-09-04T20:14:52.889Z,1536092092.889 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20180904T201302/Express0004.lzma 2018-09-04T20:14:53.687Z,1536092093.687 [DataOverHttps](INFO): Moved sent file to Logs/20180904T201302/Express0004.lzma.bak 2018-09-04T20:14:53.687Z,1536092093.687 [DataOverHttps](INFO): SBD MOMSN=8467136 2018-09-04T20:14:54.181Z,1536092094.181 [Default:CheckIn:Read_Iridium] Stopped 2018-09-04T20:14:54.181Z,1536092094.181 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-04T20:14:54.181Z,1536092094.181 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-04T20:19:54.654Z,1536092394.654 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-09-04T20:19:54.655Z,1536092394.655 [Default:CheckIn:C.Wait] Stopped 2018-09-04T20:19:54.655Z,1536092394.655 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-04T20:19:54.655Z,1536092394.655 [Default:CheckIn:D] Running Loop=1 2018-09-04T20:19:55.074Z,1536092395.074 [Default:CheckIn:D] Stopped 2018-09-04T20:19:55.074Z,1536092395.074 [Default:CheckIn:E] Running Loop=1 2018-09-04T20:19:55.473Z,1536092395.473 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.381628 min 2018-09-04T20:19:55.473Z,1536092395.473 [Default:CheckIn:E] Stopped 2018-09-04T20:19:55.473Z,1536092395.473 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-09-04T20:19:55.474Z,1536092395.474 [Default:CheckIn] Stopped 2018-09-04T20:19:55.474Z,1536092395.474 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-04T20:19:55.474Z,1536092395.474 [Default:CheckIn](INFO): Running loop #8 2018-09-04T20:19:55.474Z,1536092395.474 [Default:CheckIn] Running Loop=8 2018-09-04T20:19:55.474Z,1536092395.474 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-04T20:19:55.475Z,1536092395.475 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-04T20:19:58.791Z,1536092398.791 [NAL9602](INFO): Powering up 2018-09-04T20:20:09.938Z,1536092409.938 [NAL9602](INFO): NAL9602 initialized 2018-09-04T20:20:27.940Z,1536092427.940 [NAL9602](INFO): SBD MO Status=0, MOMSN=8474, MT Status=0, MTMSN=0 2018-09-04T20:20:27.940Z,1536092427.940 [NAL9602](INFO): No messages in MT queue 2018-09-04T20:21:21.723Z,1536092481.723 [NAL9602](INFO): GPS fix at 20180904T202120: (36.804519, -121.839749) 2018-09-04T20:21:21.789Z,1536092481.789 [Default:CheckIn:Read_GPS] Stopped 2018-09-04T20:21:21.789Z,1536092481.789 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-04T20:21:26.661Z,1536092486.661 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180904T201302/Courier0006.lzma 2018-09-04T20:21:27.459Z,1536092487.459 [DataOverHttps](INFO): Moved sent file to Logs/20180904T201302/Courier0006.lzma.bak 2018-09-04T20:21:27.459Z,1536092487.459 [DataOverHttps](INFO): SBD MOMSN=8467159 2018-09-04T20:21:36.702Z,1536092496.702 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20180904T201302/Express0007.lzma 2018-09-04T20:21:37.497Z,1536092497.497 [DataOverHttps](INFO): Moved sent file to Logs/20180904T201302/Express0007.lzma.bak 2018-09-04T20:21:37.497Z,1536092497.497 [DataOverHttps](INFO): SBD MOMSN=8467162 2018-09-04T20:21:37.973Z,1536092497.973 [Default:CheckIn:Read_Iridium] Stopped 2018-09-04T20:21:37.973Z,1536092497.973 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-04T20:21:37.973Z,1536092497.973 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-04T20:21:56.458Z,1536092516.458 [NAL9602](INFO): Powering down 2018-09-04T20:26:38.442Z,1536092798.442 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-09-04T20:26:38.443Z,1536092798.443 [Default:CheckIn:C.Wait] Stopped 2018-09-04T20:26:38.443Z,1536092798.443 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-04T20:26:38.443Z,1536092798.443 [Default:CheckIn:D] Running Loop=1 2018-09-04T20:26:38.852Z,1536092798.852 [Default:CheckIn:D] Stopped 2018-09-04T20:26:38.853Z,1536092798.853 [Default:CheckIn:E] Running Loop=1 2018-09-04T20:26:41.985Z,1536092801.985 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.111267 min 2018-09-04T20:26:41.985Z,1536092801.985 [Default:CheckIn:E] Stopped 2018-09-04T20:26:41.985Z,1536092801.985 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-09-04T20:26:41.986Z,1536092801.986 [Default:CheckIn] Stopped 2018-09-04T20:26:41.986Z,1536092801.986 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-04T20:26:41.986Z,1536092801.986 [Default:CheckIn](INFO): Running loop #9 2018-09-04T20:26:41.986Z,1536092801.986 [Default:CheckIn] Running Loop=9 2018-09-04T20:26:41.986Z,1536092801.986 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-04T20:26:41.986Z,1536092801.986 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-04T20:26:42.422Z,1536092802.422 [NAL9602](INFO): Powering up 2018-09-04T20:26:53.622Z,1536092813.622 [NAL9602](INFO): NAL9602 initialized 2018-09-04T20:27:08.824Z,1536092828.824 [NAL9602](INFO): SBD MO Status=0, MOMSN=8475, MT Status=0, MTMSN=0 2018-09-04T20:27:08.824Z,1536092828.824 [NAL9602](INFO): No messages in MT queue 2018-09-04T20:27:50.846Z,1536092870.846 [NAL9602](INFO): GPS fix at 20180904T202749: (36.804232, -121.838673) 2018-09-04T20:27:50.923Z,1536092870.923 [Default:CheckIn:Read_GPS] Stopped 2018-09-04T20:27:50.923Z,1536092870.923 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-04T20:27:55.306Z,1536092875.306 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180904T201302/Courier0009.lzma 2018-09-04T20:27:56.107Z,1536092876.107 [DataOverHttps](INFO): Moved sent file to Logs/20180904T201302/Courier0009.lzma.bak 2018-09-04T20:27:56.107Z,1536092876.107 [DataOverHttps](INFO): SBD MOMSN=8467214 2018-09-04T20:28:06.150Z,1536092886.150 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20180904T201302/Express0010.lzma 2018-09-04T20:28:06.947Z,1536092886.947 [DataOverHttps](INFO): Moved sent file to Logs/20180904T201302/Express0010.lzma.bak 2018-09-04T20:28:06.948Z,1536092886.948 [DataOverHttps](INFO): SBD MOMSN=8467217 2018-09-04T20:28:07.500Z,1536092887.500 [Default:CheckIn:Read_Iridium] Stopped 2018-09-04T20:28:07.501Z,1536092887.501 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-04T20:28:07.501Z,1536092887.501 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-04T20:28:25.730Z,1536092905.730 [NAL9602](INFO): Powering down 2018-09-04T20:33:07.935Z,1536093187.935 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-09-04T20:33:07.935Z,1536093187.935 [Default:CheckIn:C.Wait] Stopped 2018-09-04T20:33:07.935Z,1536093187.935 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-04T20:33:07.935Z,1536093187.935 [Default:CheckIn:D] Running Loop=1 2018-09-04T20:33:08.297Z,1536093188.297 [Default:CheckIn:D] Stopped 2018-09-04T20:33:08.297Z,1536093188.297 [Default:CheckIn:E] Running Loop=1 2018-09-04T20:33:08.699Z,1536093188.699 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.602010 min 2018-09-04T20:33:08.699Z,1536093188.699 [Default:CheckIn:E] Stopped 2018-09-04T20:33:08.699Z,1536093188.699 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-09-04T20:33:08.700Z,1536093188.700 [Default:CheckIn] Stopped 2018-09-04T20:33:08.700Z,1536093188.700 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-04T20:33:08.700Z,1536093188.700 [Default:CheckIn](INFO): Running loop #10 2018-09-04T20:33:08.700Z,1536093188.700 [Default:CheckIn] Running Loop=10 2018-09-04T20:33:08.700Z,1536093188.700 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-04T20:33:08.700Z,1536093188.700 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-04T20:33:11.896Z,1536093191.896 [NAL9602](INFO): Powering up 2018-09-04T20:33:23.070Z,1536093203.070 [NAL9602](INFO): NAL9602 initialized 2018-09-04T20:33:45.872Z,1536093225.872 [NAL9602](INFO): SBD MO Status=2, MOMSN=8476, MT Status=2, MTMSN=0 2018-09-04T20:33:45.872Z,1536093225.872 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-09-04T20:34:11.962Z,1536093251.962 [NAL9602](INFO): SBD MO Status=2, MOMSN=8476, MT Status=2, MTMSN=0 2018-09-04T20:34:11.962Z,1536093251.962 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-09-04T20:34:29.644Z,1536093269.644 [NAL9602](INFO): SBD MO Status=0, MOMSN=8476, MT Status=0, MTMSN=0 2018-09-04T20:34:29.644Z,1536093269.644 [NAL9602](INFO): No messages in MT queue 2018-09-04T20:35:32.522Z,1536093332.522 [NAL9602](INFO): GPS fix at 20180904T203531: (36.814252, -121.884686) 2018-09-04T20:35:32.597Z,1536093332.597 [Default:CheckIn:Read_GPS] Stopped 2018-09-04T20:35:32.598Z,1536093332.598 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-04T20:35:37.038Z,1536093337.038 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20180904T201302/Courier0012.lzma 2018-09-04T20:35:37.839Z,1536093337.839 [DataOverHttps](INFO): Moved sent file to Logs/20180904T201302/Courier0012.lzma.bak 2018-09-04T20:35:37.839Z,1536093337.839 [DataOverHttps](INFO): SBD MOMSN=8467222 2018-09-04T20:35:47.045Z,1536093347.045 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20180904T201302/Express0013.lzma 2018-09-04T20:35:47.838Z,1536093347.838 [DataOverHttps](INFO): Moved sent file to Logs/20180904T201302/Express0013.lzma.bak 2018-09-04T20:35:47.839Z,1536093347.839 [DataOverHttps](INFO): SBD MOMSN=8467225 2018-09-04T20:35:48.334Z,1536093348.334 [Default:CheckIn:Read_Iridium] Stopped 2018-09-04T20:35:48.334Z,1536093348.334 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-04T20:35:48.334Z,1536093348.334 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-04T20:36:07.442Z,1536093367.442 [NAL9602](INFO): Powering down 2018-09-04T20:40:18.758Z,1536093618.758 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-09-04T20:40:48.808Z,1536093648.808 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-09-04T20:40:48.808Z,1536093648.808 [Default:CheckIn:C.Wait] Stopped 2018-09-04T20:40:48.808Z,1536093648.808 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-04T20:40:48.808Z,1536093648.808 [Default:CheckIn:D] Running Loop=1 2018-09-04T20:40:49.218Z,1536093649.218 [Default:CheckIn:D] Stopped 2018-09-04T20:40:49.218Z,1536093649.218 [Default:CheckIn:E] Running Loop=1 2018-09-04T20:40:49.613Z,1536093649.613 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.284029 min 2018-09-04T20:40:49.613Z,1536093649.613 [Default:CheckIn:E] Stopped 2018-09-04T20:40:49.613Z,1536093649.613 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-09-04T20:40:49.613Z,1536093649.613 [Default:CheckIn] Stopped 2018-09-04T20:40:49.613Z,1536093649.613 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-04T20:40:49.614Z,1536093649.614 [Default:CheckIn](INFO): Running loop #11 2018-09-04T20:40:49.614Z,1536093649.614 [Default:CheckIn] Running Loop=11 2018-09-04T20:40:49.614Z,1536093649.614 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-04T20:40:49.614Z,1536093649.614 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-04T20:40:50.362Z,1536093650.362 [NAL9602](INFO): Powering up 2018-09-04T20:41:01.162Z,1536093661.162 [NAL9602](INFO): NAL9602 initialized 2018-09-04T20:41:14.368Z,1536093674.368 [NAL9602](INFO): SBD MO Status=0, MOMSN=8477, MT Status=0, MTMSN=0 2018-09-04T20:41:14.368Z,1536093674.368 [NAL9602](INFO): No messages in MT queue 2018-09-04T20:42:19.162Z,1536093739.162 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-09-04T20:43:02.806Z,1536093782.806 [NAL9602](INFO): GPS fix at 20180904T204301: (36.831677, -121.951248) 2018-09-04T20:43:02.868Z,1536093782.868 [Default:CheckIn:Read_GPS] Stopped 2018-09-04T20:43:02.868Z,1536093782.868 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-04T20:43:07.313Z,1536093787.313 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20180904T201302/Courier0015.lzma 2018-09-04T20:43:08.071Z,1536093788.071 [DataOverHttps](INFO): Moved sent file to Logs/20180904T201302/Courier0015.lzma.bak 2018-09-04T20:43:08.071Z,1536093788.071 [DataOverHttps](INFO): SBD MOMSN=8467718 2018-09-04T20:43:37.674Z,1536093817.674 [NAL9602](INFO): Powering down 2018-09-04T20:44:09.670Z,1536093849.670 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-09-04T20:44:40.070Z,1536093880.070 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-09-04T20:45:10.870Z,1536093910.870 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-09-04T20:45:41.270Z,1536093941.270 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-09-04T20:46:08.470Z,1536093968.470 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.399689 2018-09-04T20:46:08.478Z,1536093968.478 [NAL9602](INFO): Powering up 2018-09-04T20:46:19.674Z,1536093979.674 [NAL9602](INFO): NAL9602 initialized 2018-09-04T20:46:38.962Z,1536093998.962 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-09-04T20:46:47.368Z,1536094007.368 [NAL9602](INFO): SBD MO Status=1, MOMSN=8478, MT Status=0, MTMSN=0 2018-09-04T20:46:47.421Z,1536094007.421 [NAL9602](INFO): Sent 137 bytes from file Logs/20180904T201302/Express0016.lzma 2018-09-04T20:46:47.421Z,1536094007.421 [NAL9602](INFO): Packets left to send: 0 2018-09-04T20:46:47.423Z,1536094007.423 [NAL9602](DEBUG): Stored copy of sent data in Logs/20180904T201302/Express0016.lzma.parts/0000.sbd 2018-09-04T20:46:47.423Z,1536094007.423 [NAL9602](DEBUG): Completed sending Logs/20180904T201302/Express0016.lzma 2018-09-04T20:47:39.364Z,1536094059.364 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-09-04T20:47:52.970Z,1536094072.970 [NAL9602](FAULT): Verify xmit timeout failure. 2018-09-04T20:47:52.970Z,1536094072.970 [NAL9602] Data Fault, FailCount= 1 2018-09-04T20:47:52.971Z,1536094072.971 [NAL9602](ERROR): Data Fault 2018-09-04T20:47:53.055Z,1536094073.055 [CBIT](ERROR): Data Fault in component: NAL9602 2018-09-04T20:47:53.366Z,1536094073.366 [NAL9602](INFO): Powering down 2018-09-04T20:47:54.221Z,1536094074.221 [CBIT](INFO): Clearing failed state for component NAL9602 2018-09-04T20:47:54.222Z,1536094074.222 [NAL9602] No Fault, FailCount= 1 2018-09-04T20:48:23.766Z,1536094103.766 [NAL9602](INFO): Powering up NAL9602 2018-09-04T20:48:34.566Z,1536094114.566 [NAL9602](INFO): NAL9602 initialized 2018-09-04T20:48:39.363Z,1536094119.363 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-09-04T20:48:54.572Z,1536094134.572 [NAL9602](INFO): SBD MO Status=2, MOMSN=8479, MT Status=2, MTMSN=0 2018-09-04T20:48:54.572Z,1536094134.572 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-09-04T20:49:04.567Z,1536094144.567 [NAL9602](INFO): SBD MO Status=0, MOMSN=8479, MT Status=0, MTMSN=0 2018-09-04T20:49:04.682Z,1536094144.682 [Default:CheckIn:Read_Iridium] Stopped 2018-09-04T20:49:04.682Z,1536094144.682 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-04T20:49:04.683Z,1536094144.683 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-04T20:49:21.385Z,1536094161.385 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002709 2018-09-04T20:49:34.986Z,1536094174.986 [NAL9602](INFO): Powering down 2018-09-04T20:54:05.089Z,1536094445.089 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-09-04T20:54:05.089Z,1536094445.089 [Default:CheckIn:C.Wait] Stopped 2018-09-04T20:54:05.089Z,1536094445.089 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-04T20:54:05.090Z,1536094445.090 [Default:CheckIn:D] Running Loop=1 2018-09-04T20:54:05.538Z,1536094445.538 [Default:CheckIn:D] Stopped 2018-09-04T20:54:05.538Z,1536094445.538 [Default:CheckIn:E] Running Loop=1 2018-09-04T20:54:05.922Z,1536094445.922 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.556022 min 2018-09-04T20:54:05.923Z,1536094445.923 [Default:CheckIn:E] Stopped 2018-09-04T20:54:05.923Z,1536094445.923 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-09-04T20:54:05.923Z,1536094445.923 [Default:CheckIn] Stopped 2018-09-04T20:54:05.923Z,1536094445.923 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-04T20:54:05.923Z,1536094445.923 [Default:CheckIn](INFO): Running loop #12 2018-09-04T20:54:05.923Z,1536094445.923 [Default:CheckIn] Running Loop=12 2018-09-04T20:54:05.924Z,1536094445.924 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-04T20:54:05.924Z,1536094445.924 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-04T20:54:06.630Z,1536094446.630 [NAL9602](INFO): Powering up 2018-09-04T20:54:17.546Z,1536094457.546 [NAL9602](INFO): NAL9602 initialized 2018-09-04T20:54:30.348Z,1536094470.348 [NAL9602](INFO): SBD MO Status=0, MOMSN=8480, MT Status=0, MTMSN=0 2018-09-04T20:54:30.348Z,1536094470.348 [NAL9602](INFO): No messages in MT queue 2018-09-04T20:55:32.790Z,1536094532.790 [NAL9602](INFO): GPS fix at 20180904T205531: (36.886418, -122.025323) 2018-09-04T20:55:32.872Z,1536094532.872 [Default:CheckIn:Read_GPS] Stopped 2018-09-04T20:55:32.872Z,1536094532.872 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-04T20:55:38.847Z,1536094538.847 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20180904T201302/Courier0018.lzma 2018-09-04T20:55:39.631Z,1536094539.631 [DataOverHttps](INFO): Moved sent file to Logs/20180904T201302/Courier0018.lzma.bak 2018-09-04T20:55:39.632Z,1536094539.632 [DataOverHttps](INFO): SBD MOMSN=8468007 2018-09-04T20:55:48.721Z,1536094548.721 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20180904T201302/Express0019.lzma 2018-09-04T20:55:49.519Z,1536094549.519 [DataOverHttps](INFO): Moved sent file to Logs/20180904T201302/Express0019.lzma.bak 2018-09-04T20:55:49.519Z,1536094549.519 [DataOverHttps](INFO): SBD MOMSN=8468012 2018-09-04T20:55:50.005Z,1536094550.005 [Default:CheckIn:Read_Iridium] Stopped 2018-09-04T20:55:50.005Z,1536094550.005 [Default:CheckIn:C.Wait] Running Loop=1 2018-09-04T20:55:50.005Z,1536094550.005 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-09-04T20:56:09.146Z,1536094569.146 [NAL9602](INFO): Powering down 2018-09-04T21:00:50.810Z,1536094850.810 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-09-04T21:00:50.811Z,1536094850.811 [Default:CheckIn:C.Wait] Stopped 2018-09-04T21:00:50.811Z,1536094850.811 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-09-04T21:00:50.811Z,1536094850.811 [Default:CheckIn:D] Running Loop=1 2018-09-04T21:00:51.210Z,1536094851.210 [Default:CheckIn:D] Stopped 2018-09-04T21:00:51.210Z,1536094851.210 [Default:CheckIn:E] Running Loop=1 2018-09-04T21:00:54.363Z,1536094854.363 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.317228 min 2018-09-04T21:00:54.364Z,1536094854.364 [Default:CheckIn:E] Stopped 2018-09-04T21:00:54.364Z,1536094854.364 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-09-04T21:00:54.364Z,1536094854.364 [Default:CheckIn] Stopped 2018-09-04T21:00:54.364Z,1536094854.364 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-09-04T21:00:54.364Z,1536094854.364 [Default:CheckIn](INFO): Running loop #13 2018-09-04T21:00:54.364Z,1536094854.364 [Default:CheckIn] Running Loop=13 2018-09-04T21:00:54.365Z,1536094854.365 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-09-04T21:00:54.365Z,1536094854.365 [Default:CheckIn:Read_GPS] Running Loop=1 2018-09-04T21:00:54.790Z,1536094854.790 [NAL9602](INFO): Powering up 2018-09-04T21:01:05.590Z,1536094865.590 [NAL9602](INFO): NAL9602 initialized 2018-09-04T21:01:23.996Z,1536094883.996 [NAL9602](INFO): SBD MO Status=0, MOMSN=8481, MT Status=0, MTMSN=0 2018-09-04T21:01:23.996Z,1536094883.996 [NAL9602](INFO): No messages in MT queue 2018-09-04T21:02:02.010Z,1536094922.010 [NAL9602](INFO): GPS fix at 20180904T210200: (36.900632, -122.050214) 2018-09-04T21:02:02.076Z,1536094922.076 [Default:CheckIn:Read_GPS] Stopped 2018-09-04T21:02:02.076Z,1536094922.076 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-09-04T21:02:06.452Z,1536094926.452 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20180904T201302/Courier0021.lzma 2018-09-04T21:02:07.243Z,1536094927.243 [DataOverHttps](INFO): Moved sent file to Logs/20180904T201302/Courier0021.lzma.bak 2018-09-04T21:02:07.243Z,1536094927.243 [DataOverHttps](IMPORTANT): SBD MOMSN=8468051, MTMSN=20180904T210206 2018-09-04T21:02:11.372Z,1536094931.372 [DataOverHttps](INFO): Received command:restart logs