2019-07-26T15:38:53.584Z,1564155533.584 [CommandLine](IMPORTANT): got command restart logs 2019-07-26T15:40:34.841Z,1564155634.841 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-26T15:40:34.841Z,1564155634.841 [Default:CheckIn:C.Wait] Stopped 2019-07-26T15:40:34.841Z,1564155634.841 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-26T15:40:34.841Z,1564155634.841 [Default:CheckIn:D] Running Loop=1 2019-07-26T15:40:35.256Z,1564155635.256 [Default:CheckIn:D] Stopped 2019-07-26T15:40:35.256Z,1564155635.256 [Default:CheckIn:E] Running Loop=1 2019-07-26T15:40:35.638Z,1564155635.638 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.387972 min 2019-07-26T15:40:35.638Z,1564155635.638 [Default:CheckIn:E] Stopped 2019-07-26T15:40:35.638Z,1564155635.638 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-26T15:40:35.638Z,1564155635.638 [Default:CheckIn] Stopped 2019-07-26T15:40:35.638Z,1564155635.638 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-26T15:40:35.639Z,1564155635.639 [Default:CheckIn](INFO): Running loop #4 2019-07-26T15:40:35.639Z,1564155635.639 [Default:CheckIn] Running Loop=4 2019-07-26T15:40:35.639Z,1564155635.639 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-26T15:40:35.639Z,1564155635.639 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-26T15:40:37.252Z,1564155637.252 [NAL9602](DEBUG): Fix Requested 2019-07-26T15:40:37.643Z,1564155637.643 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153915.00,A,3648.15557,N,12149.67042,W,29.410,87.16,260719,,,A*79 2019-07-26T15:40:37.645Z,1564155637.645 [NAL9602](INFO): GPS fix at 20190726T153915: (36.802593, -121.827840) 2019-07-26T15:40:37.669Z,1564155637.669 [Default:CheckIn:Read_GPS] Stopped 2019-07-26T15:40:37.669Z,1564155637.669 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-26T15:40:43.667Z,1564155643.667 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190726T081547/Courier0117.lzma 2019-07-26T15:40:44.474Z,1564155644.474 [DataOverHttps](INFO): Moved sent file to Logs/20190726T081547/Courier0117.lzma.bak 2019-07-26T15:40:44.474Z,1564155644.474 [DataOverHttps](INFO): SBD MOMSN=11478149 2019-07-26T15:40:47.754Z,1564155647.754 [NAL9602](INFO): SBD MO Status=0, MOMSN=30211, MT Status=0, MTMSN=0 2019-07-26T15:40:47.754Z,1564155647.754 [NAL9602](INFO): No messages in MT queue 2019-07-26T15:40:55.325Z,1564155655.325 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20190726T153853/Courier0000.lzma 2019-07-26T15:40:56.132Z,1564155656.132 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Courier0000.lzma.bak 2019-07-26T15:40:56.132Z,1564155656.132 [DataOverHttps](INFO): SBD MOMSN=11478151 2019-07-26T15:41:08.231Z,1564155668.231 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190726T153853/Courier0003.lzma 2019-07-26T15:41:09.037Z,1564155669.037 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Courier0003.lzma.bak 2019-07-26T15:41:09.037Z,1564155669.037 [DataOverHttps](INFO): SBD MOMSN=11478155 2019-07-26T15:41:18.445Z,1564155678.445 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-26T15:41:20.207Z,1564155680.207 [DataOverHttps](INFO): Sending 102 bytes from file Logs/20190726T081547/Express0118.lzma 2019-07-26T15:41:21.014Z,1564155681.014 [DataOverHttps](INFO): Moved sent file to Logs/20190726T081547/Express0118.lzma.bak 2019-07-26T15:41:21.014Z,1564155681.014 [DataOverHttps](INFO): SBD MOMSN=11478157 2019-07-26T15:41:31.952Z,1564155691.952 [DataOverHttps](INFO): Sending 341 bytes from file Logs/20190726T153853/Express0001.lzma 2019-07-26T15:41:32.757Z,1564155692.757 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Express0001.lzma.bak 2019-07-26T15:41:32.757Z,1564155692.757 [DataOverHttps](INFO): SBD MOMSN=11478160 2019-07-26T15:41:43.575Z,1564155703.575 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20190726T153853/Express0004.lzma 2019-07-26T15:41:44.381Z,1564155704.381 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Express0004.lzma.bak 2019-07-26T15:41:44.381Z,1564155704.381 [DataOverHttps](INFO): SBD MOMSN=11478164 2019-07-26T15:41:45.131Z,1564155705.131 [Default:CheckIn:Read_Iridium] Stopped 2019-07-26T15:41:45.132Z,1564155705.132 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-26T15:41:45.132Z,1564155705.132 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-26T15:46:45.709Z,1564156005.709 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-26T15:46:45.710Z,1564156005.710 [Default:CheckIn:C.Wait] Stopped 2019-07-26T15:46:45.710Z,1564156005.710 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-26T15:46:45.710Z,1564156005.710 [Default:CheckIn:D] Running Loop=1 2019-07-26T15:46:46.115Z,1564156006.115 [Default:CheckIn:D] Stopped 2019-07-26T15:46:46.115Z,1564156006.115 [Default:CheckIn:E] Running Loop=1 2019-07-26T15:46:46.513Z,1564156006.513 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.568955 min 2019-07-26T15:46:46.514Z,1564156006.514 [Default:CheckIn:E] Stopped 2019-07-26T15:46:46.514Z,1564156006.514 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-26T15:46:46.514Z,1564156006.514 [Default:CheckIn] Stopped 2019-07-26T15:46:46.514Z,1564156006.514 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-26T15:46:46.514Z,1564156006.514 [Default:CheckIn](INFO): Running loop #5 2019-07-26T15:46:46.514Z,1564156006.514 [Default:CheckIn] Running Loop=5 2019-07-26T15:46:46.514Z,1564156006.514 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-26T15:46:46.514Z,1564156006.514 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-26T15:46:48.120Z,1564156008.120 [NAL9602](DEBUG): Fix Requested 2019-07-26T15:46:48.515Z,1564156008.515 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154525.00,A,3648.38012,N,12147.12717,W,5.851,180.96,260719,,,D*7D 2019-07-26T15:46:48.517Z,1564156008.517 [NAL9602](INFO): GPS fix at 20190726T154525: (36.806335, -121.785453) 2019-07-26T15:46:48.567Z,1564156008.567 [Default:CheckIn:Read_GPS] Stopped 2019-07-26T15:46:48.572Z,1564156008.572 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-26T15:46:51.999Z,1564156011.999 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20190726T153853/Courier0006.lzma 2019-07-26T15:46:52.805Z,1564156012.805 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Courier0006.lzma.bak 2019-07-26T15:46:52.806Z,1564156012.806 [DataOverHttps](INFO): SBD MOMSN=11478217 2019-07-26T15:46:54.638Z,1564156014.638 [NAL9602](INFO): SBD MO Status=0, MOMSN=30212, MT Status=0, MTMSN=0 2019-07-26T15:46:54.638Z,1564156014.638 [NAL9602](INFO): No messages in MT queue 2019-07-26T15:47:04.059Z,1564156024.059 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20190726T153853/Express0007.lzma 2019-07-26T15:47:04.865Z,1564156024.865 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Express0007.lzma.bak 2019-07-26T15:47:04.865Z,1564156024.865 [DataOverHttps](INFO): SBD MOMSN=11478220 2019-07-26T15:47:05.575Z,1564156025.575 [Default:CheckIn:Read_Iridium] Stopped 2019-07-26T15:47:05.575Z,1564156025.575 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-26T15:47:05.575Z,1564156025.575 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-26T15:47:25.338Z,1564156045.338 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-26T15:52:06.156Z,1564156326.156 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-26T15:52:06.156Z,1564156326.156 [Default:CheckIn:C.Wait] Stopped 2019-07-26T15:52:06.156Z,1564156326.156 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-26T15:52:06.156Z,1564156326.156 [Default:CheckIn:D] Running Loop=1 2019-07-26T15:52:06.567Z,1564156326.567 [Default:CheckIn:D] Stopped 2019-07-26T15:52:06.567Z,1564156326.567 [Default:CheckIn:E] Running Loop=1 2019-07-26T15:52:06.980Z,1564156326.980 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.909827 min 2019-07-26T15:52:06.980Z,1564156326.980 [Default:CheckIn:E] Stopped 2019-07-26T15:52:06.980Z,1564156326.980 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-26T15:52:06.980Z,1564156326.980 [Default:CheckIn] Stopped 2019-07-26T15:52:06.981Z,1564156326.981 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-26T15:52:06.981Z,1564156326.981 [Default:CheckIn](INFO): Running loop #6 2019-07-26T15:52:06.981Z,1564156326.981 [Default:CheckIn] Running Loop=6 2019-07-26T15:52:06.981Z,1564156326.981 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-26T15:52:06.981Z,1564156326.981 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-26T15:52:08.568Z,1564156328.568 [NAL9602](DEBUG): Fix Requested 2019-07-26T15:52:08.962Z,1564156328.962 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155045.00,A,3648.14810,N,12147.22466,W,0.136,218.37,260719,,,D*79 2019-07-26T15:52:08.965Z,1564156328.965 [NAL9602](INFO): GPS fix at 20190726T155045: (36.802468, -121.787078) 2019-07-26T15:52:08.999Z,1564156328.999 [Default:CheckIn:Read_GPS] Stopped 2019-07-26T15:52:08.999Z,1564156328.999 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-26T15:52:11.918Z,1564156331.918 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20190726T153853/Courier0009.lzma 2019-07-26T15:52:12.533Z,1564156332.533 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Courier0009.lzma.bak 2019-07-26T15:52:12.533Z,1564156332.533 [DataOverHttps](INFO): SBD MOMSN=11478233 2019-07-26T15:52:24.699Z,1564156344.699 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20190726T153853/Express0010.lzma 2019-07-26T15:52:25.505Z,1564156345.505 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Express0010.lzma.bak 2019-07-26T15:52:25.506Z,1564156345.506 [DataOverHttps](INFO): SBD MOMSN=11478236 2019-07-26T15:52:25.609Z,1564156345.609 [NAL9602](INFO): SBD MO Status=0, MOMSN=30213, MT Status=0, MTMSN=0 2019-07-26T15:52:25.609Z,1564156345.609 [NAL9602](INFO): No messages in MT queue 2019-07-26T15:52:26.443Z,1564156346.443 [Default:CheckIn:Read_Iridium] Stopped 2019-07-26T15:52:26.443Z,1564156346.443 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-26T15:52:26.443Z,1564156346.443 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-26T15:52:56.290Z,1564156376.290 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-26T15:57:27.015Z,1564156647.015 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-26T15:57:27.015Z,1564156647.015 [Default:CheckIn:C.Wait] Stopped 2019-07-26T15:57:27.015Z,1564156647.015 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-26T15:57:27.016Z,1564156647.016 [Default:CheckIn:D] Running Loop=1 2019-07-26T15:57:27.409Z,1564156647.409 [Default:CheckIn:D] Stopped 2019-07-26T15:57:27.409Z,1564156647.409 [Default:CheckIn:E] Running Loop=1 2019-07-26T15:57:27.807Z,1564156647.807 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.257190 min 2019-07-26T15:57:27.808Z,1564156647.808 [Default:CheckIn:E] Stopped 2019-07-26T15:57:27.808Z,1564156647.808 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-26T15:57:27.808Z,1564156647.808 [Default:CheckIn] Stopped 2019-07-26T15:57:27.808Z,1564156647.808 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-26T15:57:27.808Z,1564156647.808 [Default:CheckIn](INFO): Running loop #7 2019-07-26T15:57:27.808Z,1564156647.808 [Default:CheckIn] Running Loop=7 2019-07-26T15:57:27.808Z,1564156647.808 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-26T15:57:27.809Z,1564156647.809 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-26T15:57:29.416Z,1564156649.416 [NAL9602](DEBUG): Fix Requested 2019-07-26T15:57:29.814Z,1564156649.814 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155605.00,A,3648.17541,N,12147.27707,W,0.855,285.81,260719,,,D*75 2019-07-26T15:57:29.817Z,1564156649.817 [NAL9602](INFO): GPS fix at 20190726T155605: (36.802923, -121.787951) 2019-07-26T15:57:29.839Z,1564156649.839 [Default:CheckIn:Read_GPS] Stopped 2019-07-26T15:57:29.839Z,1564156649.839 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-26T15:57:35.227Z,1564156655.227 [DataOverHttps](INFO): Sending 65 bytes from file Logs/20190726T153853/Courier0012.lzma 2019-07-26T15:57:36.033Z,1564156656.033 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Courier0012.lzma.bak 2019-07-26T15:57:36.033Z,1564156656.033 [DataOverHttps](INFO): SBD MOMSN=11478240 2019-07-26T15:57:41.137Z,1564156661.137 [NAL9602](INFO): SBD MO Status=0, MOMSN=30214, MT Status=0, MTMSN=0 2019-07-26T15:57:41.137Z,1564156661.137 [NAL9602](INFO): No messages in MT queue 2019-07-26T15:57:49.479Z,1564156669.479 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20190726T153853/Express0013.lzma 2019-07-26T15:57:50.285Z,1564156670.285 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Express0013.lzma.bak 2019-07-26T15:57:50.285Z,1564156670.285 [DataOverHttps](INFO): SBD MOMSN=11478243 2019-07-26T15:57:50.859Z,1564156670.859 [Default:CheckIn:Read_Iridium] Stopped 2019-07-26T15:57:50.859Z,1564156670.859 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-26T15:57:50.859Z,1564156670.859 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-26T15:58:11.823Z,1564156691.823 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-26T16:02:51.414Z,1564156971.414 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-26T16:02:51.414Z,1564156971.414 [Default:CheckIn:C.Wait] Stopped 2019-07-26T16:02:51.414Z,1564156971.414 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-26T16:02:51.415Z,1564156971.415 [Default:CheckIn:D] Running Loop=1 2019-07-26T16:02:51.831Z,1564156971.831 [Default:CheckIn:D] Stopped 2019-07-26T16:02:51.831Z,1564156971.831 [Default:CheckIn:E] Running Loop=1 2019-07-26T16:02:52.229Z,1564156972.229 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.664225 min 2019-07-26T16:02:52.229Z,1564156972.229 [Default:CheckIn:E] Stopped 2019-07-26T16:02:52.229Z,1564156972.229 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-26T16:02:52.229Z,1564156972.229 [Default:CheckIn] Stopped 2019-07-26T16:02:52.229Z,1564156972.229 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-26T16:02:52.229Z,1564156972.229 [Default:CheckIn](INFO): Running loop #8 2019-07-26T16:02:52.229Z,1564156972.229 [Default:CheckIn] Running Loop=8 2019-07-26T16:02:52.229Z,1564156972.229 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-26T16:02:52.230Z,1564156972.230 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-26T16:02:53.836Z,1564156973.836 [NAL9602](DEBUG): Fix Requested 2019-07-26T16:02:54.224Z,1564156974.224 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160129.00,A,3648.16277,N,12147.28277,W,0.544,169.15,260719,,,A*70 2019-07-26T16:02:54.227Z,1564156974.227 [NAL9602](INFO): GPS fix at 20190726T160129: (36.802713, -121.788046) 2019-07-26T16:02:54.259Z,1564156974.259 [Default:CheckIn:Read_GPS] Stopped 2019-07-26T16:02:54.259Z,1564156974.259 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-26T16:02:57.295Z,1564156977.295 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20190726T153853/Courier0015.lzma 2019-07-26T16:02:58.101Z,1564156978.101 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Courier0015.lzma.bak 2019-07-26T16:02:58.101Z,1564156978.101 [DataOverHttps](INFO): SBD MOMSN=11478258 2019-07-26T16:03:10.316Z,1564156990.316 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20190726T153853/Express0016.lzma 2019-07-26T16:03:11.121Z,1564156991.121 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Express0016.lzma.bak 2019-07-26T16:03:11.121Z,1564156991.121 [DataOverHttps](INFO): SBD MOMSN=11478261 2019-07-26T16:03:12.090Z,1564156992.090 [Default:CheckIn:Read_Iridium] Stopped 2019-07-26T16:03:12.090Z,1564156992.090 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-26T16:03:12.090Z,1564156992.090 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-26T16:07:56.067Z,1564157276.067 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-07-26T16:08:12.667Z,1564157292.667 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-26T16:08:12.667Z,1564157292.667 [Default:CheckIn:C.Wait] Stopped 2019-07-26T16:08:12.667Z,1564157292.667 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-26T16:08:12.668Z,1564157292.668 [Default:CheckIn:D] Running Loop=1 2019-07-26T16:08:13.058Z,1564157293.058 [Default:CheckIn:D] Stopped 2019-07-26T16:08:13.058Z,1564157293.058 [Default:CheckIn:E] Running Loop=1 2019-07-26T16:08:13.462Z,1564157293.462 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.018009 min 2019-07-26T16:08:13.462Z,1564157293.462 [Default:CheckIn:E] Stopped 2019-07-26T16:08:13.462Z,1564157293.462 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-26T16:08:13.462Z,1564157293.462 [Default:CheckIn] Stopped 2019-07-26T16:08:13.462Z,1564157293.462 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-26T16:08:13.463Z,1564157293.463 [Default:CheckIn](INFO): Running loop #9 2019-07-26T16:08:13.463Z,1564157293.463 [Default:CheckIn] Running Loop=9 2019-07-26T16:08:13.463Z,1564157293.463 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-26T16:08:13.463Z,1564157293.463 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-26T16:08:15.068Z,1564157295.068 [NAL9602](DEBUG): Fix Requested 2019-07-26T16:08:15.468Z,1564157295.468 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160650.00,A,3648.16562,N,12147.28836,W,1.788,195.07,260719,,,A*76 2019-07-26T16:08:15.470Z,1564157295.470 [NAL9602](INFO): GPS fix at 20190726T160650: (36.802760, -121.788139) 2019-07-26T16:08:15.552Z,1564157295.552 [Default:CheckIn:Read_GPS] Stopped 2019-07-26T16:08:15.552Z,1564157295.552 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-07-26T16:08:20.787Z,1564157300.787 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20190726T153853/Courier0018.lzma 2019-07-26T16:08:21.593Z,1564157301.593 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Courier0018.lzma.bak 2019-07-26T16:08:21.593Z,1564157301.593 [DataOverHttps](INFO): SBD MOMSN=11478264 2019-07-26T16:08:32.484Z,1564157312.484 [DataOverHttps](INFO): Sending 220 bytes from file Logs/20190726T153853/Express0019.lzma 2019-07-26T16:08:33.289Z,1564157313.289 [DataOverHttps](INFO): Moved sent file to Logs/20190726T153853/Express0019.lzma.bak 2019-07-26T16:08:33.290Z,1564157313.290 [DataOverHttps](INFO): SBD MOMSN=11478267 2019-07-26T16:08:34.070Z,1564157314.070 [Default:CheckIn:Read_Iridium] Stopped 2019-07-26T16:08:34.070Z,1564157314.070 [Default:CheckIn:C.Wait] Running Loop=1 2019-07-26T16:08:34.070Z,1564157314.070 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-07-26T16:08:47.778Z,1564157327.778 [NAL9602](INFO): Not Powering down - fast GPS 2019-07-26T16:10:55.492Z,1564157455.492 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2019-07-26T16:10:55.493Z,1564157455.493 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2019-07-26T16:10:55.494Z,1564157455.494 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2019-07-26T16:10:55.501Z,1564157455.501 [BPC1](INFO): Received data from all battery sticks. 2019-07-26T16:13:34.724Z,1564157614.724 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-07-26T16:13:34.724Z,1564157614.724 [Default:CheckIn:C.Wait] Stopped 2019-07-26T16:13:34.725Z,1564157614.725 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-07-26T16:13:34.725Z,1564157614.725 [Default:CheckIn:D] Running Loop=1 2019-07-26T16:13:35.131Z,1564157615.131 [Default:CheckIn:D] Stopped 2019-07-26T16:13:35.131Z,1564157615.131 [Default:CheckIn:E] Running Loop=1 2019-07-26T16:13:35.540Z,1564157615.540 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.385885 min 2019-07-26T16:13:35.540Z,1564157615.540 [Default:CheckIn:E] Stopped 2019-07-26T16:13:35.540Z,1564157615.540 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-07-26T16:13:35.540Z,1564157615.540 [Default:CheckIn] Stopped 2019-07-26T16:13:35.540Z,1564157615.540 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-26T16:13:35.540Z,1564157615.540 [Default:CheckIn](INFO): Running loop #10 2019-07-26T16:13:35.541Z,1564157615.541 [Default:CheckIn] Running Loop=10 2019-07-26T16:13:35.541Z,1564157615.541 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-07-26T16:13:35.541Z,1564157615.541 [Default:CheckIn:Read_GPS] Running Loop=1 2019-07-26T16:13:37.140Z,1564157617.140 [NAL9602](DEBUG): Fix Requested 2019-07-26T16:14:37.992Z,1564157677.992 [CommandLine](IMPORTANT): got command quit 2019-07-26T16:14:39.000Z,1564157679.000 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T16:14:39.000Z,1564157679.000 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:39.080Z,1564157679.080 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-07-26T16:14:39.080Z,1564157679.080 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:39.081Z,1564157679.081 [CommandLine](INFO): Join timeout helper Thread ID is 4669 2019-07-26T16:14:39.092Z,1564157679.092 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-07-26T16:14:39.092Z,1564157679.092 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:39.092Z,1564157679.092 [NavChartDb](INFO): Join timeout helper Thread ID is 4670 2019-07-26T16:14:39.108Z,1564157679.108 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T16:14:39.108Z,1564157679.108 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:39.132Z,1564157679.132 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-07-26T16:14:39.132Z,1564157679.132 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:39.133Z,1564157679.133 [Radio_Surface](INFO): Join timeout helper Thread ID is 4671 2019-07-26T16:14:39.140Z,1564157679.140 [Radio_Surface](INFO): Powering down 2019-07-26T16:14:39.141Z,1564157679.141 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T16:14:39.141Z,1564157679.141 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:39.144Z,1564157679.144 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-07-26T16:14:39.144Z,1564157679.144 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:39.144Z,1564157679.144 [DataOverHttps](INFO): Join timeout helper Thread ID is 4672 2019-07-26T16:14:41.177Z,1564157681.177 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T16:14:41.182Z,1564157681.182 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:41.200Z,1564157681.200 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-07-26T16:14:41.200Z,1564157681.200 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:41.200Z,1564157681.200 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4673 2019-07-26T16:14:41.312Z,1564157681.312 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T16:14:41.312Z,1564157681.312 [WetLabsBB2FL](INFO): Powering down 2019-07-26T16:14:41.313Z,1564157681.313 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:41.323Z,1564157681.323 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-07-26T16:14:41.323Z,1564157681.323 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:41.324Z,1564157681.324 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4674 2019-07-26T16:14:41.888Z,1564157681.888 [CTD_Seabird](INFO): Powering down 2019-07-26T16:14:41.900Z,1564157681.900 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T16:14:41.900Z,1564157681.900 [CTD_Seabird](INFO): Powering down 2019-07-26T16:14:41.912Z,1564157681.912 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:41.916Z,1564157681.916 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-07-26T16:14:41.917Z,1564157681.917 [logger ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:41.917Z,1564157681.917 [logger](INFO): Join timeout helper Thread ID is 4675 2019-07-26T16:14:41.940Z,1564157681.940 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T16:14:41.940Z,1564157681.940 [logger ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:41.956Z,1564157681.956 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-07-26T16:14:41.957Z,1564157681.957 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:41.957Z,1564157681.957 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-07-26T16:14:41.957Z,1564157681.957 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:41.957Z,1564157681.957 [controlThread](INFO): Join timeout helper Thread ID is 4676 2019-07-26T16:14:42.152Z,1564157682.152 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-07-26T16:14:42.152Z,1564157682.152 [controlThread](DEBUG): Uninitializing ControlThread 2019-07-26T16:14:42.153Z,1564157682.153 [Aanderaa_O2](INFO): Powering down 2019-07-26T16:14:42.155Z,1564157682.155 [AHRS_M2](INFO): Powering down 2019-07-26T16:14:42.224Z,1564157682.224 [NAL9602](INFO): Powering down 2019-07-26T16:14:42.225Z,1564157682.225 [DAT](INFO): Powering down 2019-07-26T16:14:42.345Z,1564157682.345 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-07-26T16:14:42.345Z,1564157682.345 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-07-26T16:14:42.346Z,1564157682.346 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-07-26T16:14:42.346Z,1564157682.346 [MissionManager](INFO): Uninitializing Mission Default 2019-07-26T16:14:42.347Z,1564157682.347 [Default] Stopped 2019-07-26T16:14:42.347Z,1564157682.347 [Default](DEBUG): Aggregate::uninitialize Default 2019-07-26T16:14:42.347Z,1564157682.347 [Default:B.GoToSurface] Stopped 2019-07-26T16:14:42.347Z,1564157682.347 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-07-26T16:14:42.347Z,1564157682.347 [Default:CheckIn] Stopped 2019-07-26T16:14:42.347Z,1564157682.347 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-07-26T16:14:42.347Z,1564157682.347 [Default:CheckIn:Read_GPS] Stopped 2019-07-26T16:14:42.349Z,1564157682.349 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-07-26T16:14:42.350Z,1564157682.350 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-07-26T16:14:42.350Z,1564157682.350 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-07-26T16:14:42.350Z,1564157682.350 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-07-26T16:14:42.350Z,1564157682.350 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-07-26T16:14:42.350Z,1564157682.350 [BuoyancyServo](INFO): Powering down 2019-07-26T16:14:42.364Z,1564157682.364 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-07-26T16:14:42.364Z,1564157682.364 [ElevatorServo](INFO): Powering down 2019-07-26T16:14:42.365Z,1564157682.365 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-07-26T16:14:42.365Z,1564157682.365 [MassServo](INFO): Powering down 2019-07-26T16:14:42.366Z,1564157682.366 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-07-26T16:14:42.366Z,1564157682.366 [RudderServo](INFO): Powering down 2019-07-26T16:14:42.366Z,1564157682.366 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-07-26T16:14:42.367Z,1564157682.367 [ThrusterServo](INFO): Powering down 2019-07-26T16:14:42.367Z,1564157682.367 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-07-26T16:14:42.368Z,1564157682.368 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-07-26T16:14:42.368Z,1564157682.368 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-07-26T16:14:42.368Z,1564157682.368 [CBIT](DEBUG): Powering off loads. 2019-07-26T16:14:42.380Z,1564157682.380 [CBIT](DEBUG): Disabling WDT. 2019-07-26T16:14:42.392Z,1564157682.392 [CBIT](DEBUG): Opening all GF detection circuits. 2019-07-26T16:14:42.392Z,1564157682.392 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:42.425Z,1564157682.425 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:42.501Z,1564157682.501 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:42.566Z,1564157682.566 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:42.571Z,1564157682.571 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:42.621Z,1564157682.621 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-07-26T16:14:42.695Z,1564157682.695 [logger ThreadHandler](INFO): Thread cancelled.