2018-10-03T23:17:48.986Z,1538608668.986 [Default](DEBUG): Aggregate::uninitialize Default 2018-10-03T23:17:48.986Z,1538608668.986 [Default:B.GoToSurface] Stopped 2018-10-03T23:17:48.986Z,1538608668.986 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-10-03T23:17:49.033Z,1538608669.033 [CommandLine](IMPORTANT): got command restart logs 2018-10-03T23:17:49.366Z,1538608669.366 [MissionManager](IMPORTANT): Started mission Default 2018-10-03T23:17:49.366Z,1538608669.366 [Default] Running Loop=1 2018-10-03T23:17:49.366Z,1538608669.366 [Default](DEBUG): Aggregate::initialize Default 2018-10-03T23:17:49.366Z,1538608669.366 [Default:B.GoToSurface] Running Loop=1 2018-10-03T23:17:49.366Z,1538608669.366 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2018-10-03T23:17:49.366Z,1538608669.366 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2018-10-03T23:17:49.367Z,1538608669.367 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2018-10-03T23:17:49.367Z,1538608669.367 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2018-10-03T23:17:49.367Z,1538608669.367 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2018-10-03T23:17:49.368Z,1538608669.368 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2018-10-03T23:17:49.368Z,1538608669.368 [Default:A.Wait] Running Loop=1 2018-10-03T23:17:49.368Z,1538608669.368 [Default:A.Wait](DEBUG): Initialize Wait Component. 2018-10-03T23:18:02.578Z,1538608682.578 [Default:A.Wait](INFO): Done Waiting. 2018-10-03T23:18:02.579Z,1538608682.579 [Default:A.Wait] Stopped 2018-10-03T23:18:02.579Z,1538608682.579 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2018-10-03T23:18:02.952Z,1538608682.952 [Default:CheckIn] Running Loop=1 2018-10-03T23:18:02.953Z,1538608682.953 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-03T23:18:02.953Z,1538608682.953 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-03T23:18:03.714Z,1538608683.714 [NAL9602](INFO): Powering up 2018-10-03T23:18:14.667Z,1538608694.667 [NAL9602](INFO): NAL9602 initialized 2018-10-03T23:18:46.395Z,1538608726.395 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-10-03T23:18:46.469Z,1538608726.469 [NAL9602](FAULT): received: +CSQ:0 OK86, 2, 0, 0, 0 OK 2018-10-03T23:18:46.469Z,1538608726.469 [NAL9602] Data Fault, FailCount= 3 2018-10-03T23:18:46.469Z,1538608726.469 [NAL9602](ERROR): Data Fault 2018-10-03T23:18:46.567Z,1538608726.567 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-03T23:18:46.786Z,1538608726.786 [NAL9602](INFO): Powering down 2018-10-03T23:18:47.686Z,1538608727.686 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-03T23:18:47.686Z,1538608727.686 [NAL9602] No Fault, FailCount= 3 2018-10-03T23:19:18.120Z,1538608758.120 [NAL9602](INFO): Powering up NAL9602 2018-10-03T23:19:29.086Z,1538608769.086 [NAL9602](INFO): NAL9602 initialized 2018-10-03T23:19:29.880Z,1538608769.880 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-10-03T23:19:29.880Z,1538608769.880 [NAL9602] Data Fault, FailCount= 4 2018-10-03T23:19:29.880Z,1538608769.880 [NAL9602](ERROR): Data Fault 2018-10-03T23:19:29.965Z,1538608769.965 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-03T23:19:30.286Z,1538608770.286 [NAL9602](INFO): Powering down 2018-10-03T23:19:31.160Z,1538608771.160 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-03T23:19:31.160Z,1538608771.160 [NAL9602] No Fault, FailCount= 4 2018-10-03T23:20:00.633Z,1538608800.633 [NAL9602](INFO): Powering up NAL9602 2018-10-03T23:20:11.840Z,1538608811.840 [NAL9602](INFO): NAL9602 initialized 2018-10-03T23:23:02.978Z,1538608982.978 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-03T23:18:02.0Z 2018-10-03T23:23:02.978Z,1538608982.978 [Default:CheckIn:Read_GPS] Stopped 2018-10-03T23:23:02.978Z,1538608982.978 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-03T23:23:07.708Z,1538608987.708 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181001T213837/Courier0864.lzma 2018-10-03T23:23:08.488Z,1538608988.488 [DataOverHttps](INFO): Moved sent file to Logs/20181001T213837/Courier0864.lzma.bak 2018-10-03T23:23:08.488Z,1538608988.488 [DataOverHttps](INFO): SBD MOMSN=8610357 2018-10-03T23:23:17.545Z,1538608997.545 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-03T23:23:17.692Z,1538608997.692 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181003T231749/Courier0000.lzma 2018-10-03T23:23:18.454Z,1538608998.454 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0000.lzma.bak 2018-10-03T23:23:18.454Z,1538608998.454 [DataOverHttps](INFO): SBD MOMSN=8610359 2018-10-03T23:23:28.054Z,1538609008.054 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20181001T213837/Express0865.lzma 2018-10-03T23:23:28.824Z,1538609008.824 [DataOverHttps](INFO): Moved sent file to Logs/20181001T213837/Express0865.lzma.bak 2018-10-03T23:23:28.825Z,1538609008.825 [DataOverHttps](INFO): SBD MOMSN=8610362 2018-10-03T23:23:38.067Z,1538609018.067 [DataOverHttps](INFO): Sending 386 bytes from file Logs/20181003T231749/Express0001.lzma 2018-10-03T23:23:45.795Z,1538609025.795 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0001.lzma.bak 2018-10-03T23:23:45.795Z,1538609025.795 [DataOverHttps](INFO): SBD MOMSN=8610367 2018-10-03T23:23:46.550Z,1538609026.550 [Default:CheckIn:Read_Iridium] Stopped 2018-10-03T23:23:46.550Z,1538609026.550 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-03T23:23:46.550Z,1538609026.550 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-03T23:23:49.804Z,1538609029.804 [NAL9602](INFO): Powering down 2018-10-03T23:27:33.487Z,1538609253.487 [RDI_Pathfinder](ERROR): Failed to parse: :B,-32768,-32768,-32768,V 2018-10-03T23:28:46.917Z,1538609326.917 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-03T23:28:46.918Z,1538609326.918 [Default:CheckIn:C.Wait] Stopped 2018-10-03T23:28:46.918Z,1538609326.918 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-03T23:28:46.918Z,1538609326.918 [Default:CheckIn:D] Running Loop=1 2018-10-03T23:28:47.316Z,1538609327.316 [Default:CheckIn:D] Stopped 2018-10-03T23:28:47.316Z,1538609327.316 [Default:CheckIn:E] Running Loop=1 2018-10-03T23:28:47.726Z,1538609327.726 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.965825 min 2018-10-03T23:28:47.727Z,1538609327.727 [Default:CheckIn:E] Stopped 2018-10-03T23:28:47.727Z,1538609327.727 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-03T23:28:47.727Z,1538609327.727 [Default:CheckIn] Stopped 2018-10-03T23:28:47.727Z,1538609327.727 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-03T23:28:47.727Z,1538609327.727 [Default:CheckIn](INFO): Running loop #2 2018-10-03T23:28:47.727Z,1538609327.727 [Default:CheckIn] Running Loop=2 2018-10-03T23:28:47.727Z,1538609327.727 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-03T23:28:47.728Z,1538609327.728 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-03T23:28:48.475Z,1538609328.475 [NAL9602](INFO): Powering up 2018-10-03T23:28:59.686Z,1538609339.686 [NAL9602](INFO): NAL9602 initialized 2018-10-03T23:29:31.579Z,1538609371.579 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-10-03T23:29:31.657Z,1538609371.657 [NAL9602](FAULT): received: +CSQ:0 OK86, 2, 0, 0, 0 OK 2018-10-03T23:29:31.657Z,1538609371.657 [NAL9602] Data Fault, FailCount= 5 2018-10-03T23:29:31.657Z,1538609371.657 [NAL9602](ERROR): Data Fault 2018-10-03T23:29:31.720Z,1538609371.720 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-03T23:29:31.725Z,1538609371.725 [CBIT](CRITICAL): Data Fault in component: NAL9602 2018-10-03T23:29:31.974Z,1538609371.974 [NAL9602](INFO): Powering down 2018-10-03T23:29:32.069Z,1538609372.069 [CBIT](INFO): Critical error at 20181003T232931 2018-10-03T23:31:32.045Z,1538609492.045 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-03T23:31:32.045Z,1538609492.045 [NAL9602] No Fault, FailCount= 5 2018-10-03T23:31:32.358Z,1538609492.358 [NAL9602](INFO): Powering up NAL9602 2018-10-03T23:31:43.567Z,1538609503.567 [NAL9602](INFO): NAL9602 initialized 2018-10-03T23:31:44.367Z,1538609504.367 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-10-03T23:31:44.367Z,1538609504.367 [NAL9602] Data Fault, FailCount= 1 2018-10-03T23:31:44.367Z,1538609504.367 [NAL9602](ERROR): Data Fault 2018-10-03T23:31:44.426Z,1538609504.426 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-03T23:31:44.766Z,1538609504.766 [NAL9602](INFO): Powering down 2018-10-03T23:31:45.637Z,1538609505.637 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-03T23:31:45.637Z,1538609505.637 [NAL9602] No Fault, FailCount= 1 2018-10-03T23:32:04.758Z,1538609524.758 [RDI_Pathfinder](ERROR): Failed to parse: :HM,G,D,0e8c,0ffd, 15.920, 0.004, 65.535 2018-10-03T23:32:15.122Z,1538609535.122 [NAL9602](INFO): Powering up NAL9602 2018-10-03T23:32:25.926Z,1538609545.926 [NAL9602](INFO): NAL9602 initialized 2018-10-03T23:33:48.799Z,1538609628.799 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-03T23:28:47.7Z 2018-10-03T23:33:48.799Z,1538609628.799 [Default:CheckIn:Read_GPS] Stopped 2018-10-03T23:33:48.800Z,1538609628.800 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-03T23:33:53.807Z,1538609633.807 [DataOverHttps](INFO): Sending 93 bytes from file Logs/20181003T231749/Courier0003.lzma 2018-10-03T23:33:54.565Z,1538609634.565 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0003.lzma.bak 2018-10-03T23:33:54.566Z,1538609634.566 [DataOverHttps](INFO): SBD MOMSN=8610373 2018-10-03T23:34:03.835Z,1538609643.835 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-03T23:34:04.008Z,1538609644.008 [DataOverHttps](INFO): Sending 282 bytes from file Logs/20181003T231749/Express0004.lzma 2018-10-03T23:34:04.762Z,1538609644.762 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0004.lzma.bak 2018-10-03T23:34:04.762Z,1538609644.762 [DataOverHttps](INFO): SBD MOMSN=8610376 2018-10-03T23:34:05.741Z,1538609645.741 [Default:CheckIn:Read_Iridium] Stopped 2018-10-03T23:34:05.741Z,1538609645.741 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-03T23:34:05.741Z,1538609645.741 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-03T23:34:34.283Z,1538609674.283 [NAL9602](INFO): Powering down 2018-10-03T23:38:17.788Z,1538609897.788 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-03T23:39:05.945Z,1538609945.945 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-03T23:39:05.945Z,1538609945.945 [Default:CheckIn:C.Wait] Stopped 2018-10-03T23:39:05.945Z,1538609945.945 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-03T23:39:05.946Z,1538609945.946 [Default:CheckIn:D] Running Loop=1 2018-10-03T23:39:06.349Z,1538609946.349 [Default:CheckIn:D] Stopped 2018-10-03T23:39:06.349Z,1538609946.349 [Default:CheckIn:E] Running Loop=1 2018-10-03T23:39:06.759Z,1538609946.759 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.283051 min 2018-10-03T23:39:06.759Z,1538609946.759 [Default:CheckIn:E] Stopped 2018-10-03T23:39:06.759Z,1538609946.759 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-03T23:39:06.759Z,1538609946.759 [Default:CheckIn] Stopped 2018-10-03T23:39:06.759Z,1538609946.759 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-03T23:39:06.760Z,1538609946.760 [Default:CheckIn](INFO): Running loop #3 2018-10-03T23:39:06.760Z,1538609946.760 [Default:CheckIn] Running Loop=3 2018-10-03T23:39:06.760Z,1538609946.760 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-03T23:39:06.760Z,1538609946.760 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-03T23:39:07.506Z,1538609947.506 [NAL9602](INFO): Powering up 2018-10-03T23:39:18.702Z,1538609958.702 [NAL9602](INFO): NAL9602 initialized 2018-10-03T23:39:50.546Z,1538609990.546 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-10-03T23:39:50.621Z,1538609990.621 [NAL9602](FAULT): received: +CSQ:0 OK86, 2, 0, 0, 0 OK 2018-10-03T23:39:50.621Z,1538609990.621 [NAL9602] Data Fault, FailCount= 2 2018-10-03T23:39:50.621Z,1538609990.621 [NAL9602](ERROR): Data Fault 2018-10-03T23:39:50.694Z,1538609990.694 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-03T23:39:50.946Z,1538609990.946 [NAL9602](INFO): Powering down 2018-10-03T23:39:51.836Z,1538609991.836 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-03T23:39:51.837Z,1538609991.837 [NAL9602] No Fault, FailCount= 2 2018-10-03T23:40:02.710Z,1538610002.710 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-03T23:40:21.070Z,1538610021.070 [NAL9602](INFO): Powering up NAL9602 2018-10-03T23:40:32.278Z,1538610032.278 [NAL9602](INFO): NAL9602 initialized 2018-10-03T23:40:49.649Z,1538610049.649 [RDI_Pathfinder](ERROR): Failed to parse: :HM,G,D,0e8c,0ffd,*15.847,*-0.010,*0.000 2018-10-03T23:44:08.651Z,1538610248.651 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-03T23:39:06.8Z 2018-10-03T23:44:08.651Z,1538610248.651 [Default:CheckIn:Read_GPS] Stopped 2018-10-03T23:44:08.652Z,1538610248.652 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-03T23:44:13.808Z,1538610253.808 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181003T231749/Courier0006.lzma 2018-10-03T23:44:14.554Z,1538610254.554 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0006.lzma.bak 2018-10-03T23:44:14.554Z,1538610254.554 [DataOverHttps](INFO): SBD MOMSN=8610382 2018-10-03T23:44:23.922Z,1538610263.922 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-03T23:44:24.083Z,1538610264.083 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20181003T231749/Express0007.lzma 2018-10-03T23:44:24.834Z,1538610264.834 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0007.lzma.bak 2018-10-03T23:44:24.834Z,1538610264.834 [DataOverHttps](INFO): SBD MOMSN=8610384 2018-10-03T23:44:25.865Z,1538610265.865 [Default:CheckIn:Read_Iridium] Stopped 2018-10-03T23:44:25.865Z,1538610265.865 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-03T23:44:25.865Z,1538610265.865 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-03T23:44:54.460Z,1538610294.460 [NAL9602](INFO): Powering down 2018-10-03T23:49:26.187Z,1538610566.187 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-03T23:49:26.187Z,1538610566.187 [Default:CheckIn:C.Wait] Stopped 2018-10-03T23:49:26.187Z,1538610566.187 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-03T23:49:26.188Z,1538610566.188 [Default:CheckIn:D] Running Loop=1 2018-10-03T23:49:26.603Z,1538610566.603 [Default:CheckIn:D] Stopped 2018-10-03T23:49:26.603Z,1538610566.603 [Default:CheckIn:E] Running Loop=1 2018-10-03T23:49:26.996Z,1538610566.996 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.620612 min 2018-10-03T23:49:26.997Z,1538610566.997 [Default:CheckIn:E] Stopped 2018-10-03T23:49:26.997Z,1538610566.997 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-03T23:49:26.997Z,1538610566.997 [Default:CheckIn] Stopped 2018-10-03T23:49:26.997Z,1538610566.997 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-03T23:49:26.997Z,1538610566.997 [Default:CheckIn](INFO): Running loop #4 2018-10-03T23:49:26.997Z,1538610566.997 [Default:CheckIn] Running Loop=4 2018-10-03T23:49:26.997Z,1538610566.997 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-03T23:49:26.998Z,1538610566.998 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-03T23:49:27.731Z,1538610567.731 [NAL9602](INFO): Powering up 2018-10-03T23:49:38.918Z,1538610578.918 [NAL9602](INFO): NAL9602 initialized 2018-10-03T23:49:39.719Z,1538610579.719 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-10-03T23:49:39.719Z,1538610579.719 [NAL9602] Data Fault, FailCount= 3 2018-10-03T23:49:39.719Z,1538610579.719 [NAL9602](ERROR): Data Fault 2018-10-03T23:49:39.819Z,1538610579.819 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-03T23:49:40.118Z,1538610580.118 [NAL9602](INFO): Powering down 2018-10-03T23:49:41.020Z,1538610581.020 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-03T23:49:41.020Z,1538610581.020 [NAL9602] No Fault, FailCount= 3 2018-10-03T23:50:10.319Z,1538610610.319 [NAL9602](INFO): Powering up NAL9602 2018-10-03T23:50:23.182Z,1538610623.182 [NAL9602](INFO): NAL9602 initialized 2018-10-03T23:50:57.738Z,1538610657.738 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-03T23:54:27.952Z,1538610867.952 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-03T23:49:26.0Z 2018-10-03T23:54:27.952Z,1538610867.952 [Default:CheckIn:Read_GPS] Stopped 2018-10-03T23:54:27.952Z,1538610867.952 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-03T23:54:33.015Z,1538610873.015 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181003T231749/Courier0009.lzma 2018-10-03T23:54:33.779Z,1538610873.779 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0009.lzma.bak 2018-10-03T23:54:33.779Z,1538610873.779 [DataOverHttps](INFO): SBD MOMSN=8610389 2018-10-03T23:54:43.108Z,1538610883.108 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20181003T231749/Express0010.lzma 2018-10-03T23:54:43.880Z,1538610883.880 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0010.lzma.bak 2018-10-03T23:54:43.881Z,1538610883.881 [DataOverHttps](INFO): SBD MOMSN=8610391 2018-10-03T23:54:44.827Z,1538610884.827 [Default:CheckIn:Read_Iridium] Stopped 2018-10-03T23:54:44.827Z,1538610884.827 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-03T23:54:44.827Z,1538610884.827 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-03T23:55:26.942Z,1538610926.942 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-03T23:55:58.917Z,1538610958.917 [NAL9602](INFO): Powering down 2018-10-03T23:57:10.906Z,1538611030.906 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,-32768,V 2018-10-03T23:59:44.976Z,1538611184.976 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-03T23:59:44.976Z,1538611184.976 [Default:CheckIn:C.Wait] Stopped 2018-10-03T23:59:44.976Z,1538611184.976 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-03T23:59:44.977Z,1538611184.977 [Default:CheckIn:D] Running Loop=1 2018-10-03T23:59:45.390Z,1538611185.390 [Default:CheckIn:D] Stopped 2018-10-03T23:59:45.390Z,1538611185.390 [Default:CheckIn:E] Running Loop=1 2018-10-03T23:59:45.794Z,1538611185.795 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.933647 min 2018-10-03T23:59:45.795Z,1538611185.795 [Default:CheckIn:E] Stopped 2018-10-03T23:59:45.795Z,1538611185.795 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-03T23:59:45.795Z,1538611185.795 [Default:CheckIn] Stopped 2018-10-03T23:59:45.795Z,1538611185.795 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-03T23:59:45.795Z,1538611185.795 [Default:CheckIn](INFO): Running loop #5 2018-10-03T23:59:45.796Z,1538611185.796 [Default:CheckIn] Running Loop=5 2018-10-03T23:59:45.796Z,1538611185.796 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-03T23:59:45.796Z,1538611185.796 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-03T23:59:46.534Z,1538611186.534 [NAL9602](INFO): Powering up 2018-10-03T23:59:57.338Z,1538611197.338 [NAL9602](INFO): NAL9602 initialized 2018-10-04T00:00:02.166Z,1538611202.166 [RDI_Pathfinder](ERROR): only read 3 of 5 data items 2018-10-04T00:00:02.166Z,1538611202.166 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-3268,-32768,V 2018-10-04T00:00:48.900Z,1538611248.900 [RDI_Pathfinder](ERROR): Failed to parse: 2018-10-04T00:04:48.448Z,1538611488.448 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-03T23:59:45.8Z 2018-10-04T00:04:48.448Z,1538611488.448 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T00:04:48.448Z,1538611488.448 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T00:04:53.665Z,1538611493.665 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181003T231749/Courier0012.lzma 2018-10-04T00:04:54.435Z,1538611494.435 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0012.lzma.bak 2018-10-04T00:04:54.435Z,1538611494.435 [DataOverHttps](INFO): SBD MOMSN=8610396 2018-10-04T00:05:00.438Z,1538611500.438 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T00:05:03.834Z,1538611503.834 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20181003T231749/Express0013.lzma 2018-10-04T00:05:04.545Z,1538611504.545 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0013.lzma.bak 2018-10-04T00:05:04.545Z,1538611504.545 [DataOverHttps](INFO): SBD MOMSN=8610398 2018-10-04T00:05:05.488Z,1538611505.488 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T00:05:05.488Z,1538611505.488 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T00:05:05.488Z,1538611505.488 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T00:05:33.794Z,1538611533.794 [NAL9602](INFO): Powering down 2018-10-04T00:06:03.650Z,1538611563.650 [RDI_Pathfinder](ERROR): Failed to parse: 0e8c,0ffd, 15.934, 0.003, 65.535 2018-10-04T00:10:05.715Z,1538611805.715 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T00:10:05.715Z,1538611805.715 [Default:CheckIn:C.Wait] Stopped 2018-10-04T00:10:05.715Z,1538611805.715 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T00:10:05.715Z,1538611805.715 [Default:CheckIn:D] Running Loop=1 2018-10-04T00:10:06.101Z,1538611806.101 [Default:CheckIn:D] Stopped 2018-10-04T00:10:06.101Z,1538611806.101 [Default:CheckIn:E] Running Loop=1 2018-10-04T00:10:06.539Z,1538611806.539 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.278711 min 2018-10-04T00:10:06.540Z,1538611806.540 [Default:CheckIn:E] Stopped 2018-10-04T00:10:06.540Z,1538611806.540 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T00:10:06.540Z,1538611806.540 [Default:CheckIn] Stopped 2018-10-04T00:10:06.540Z,1538611806.540 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T00:10:06.540Z,1538611806.540 [Default:CheckIn](INFO): Running loop #6 2018-10-04T00:10:06.540Z,1538611806.540 [Default:CheckIn] Running Loop=6 2018-10-04T00:10:06.540Z,1538611806.540 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T00:10:06.541Z,1538611806.541 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T00:10:07.234Z,1538611807.234 [NAL9602](INFO): Powering up 2018-10-04T00:10:18.438Z,1538611818.438 [NAL9602](INFO): NAL9602 initialized 2018-10-04T00:10:19.239Z,1538611819.239 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-10-04T00:10:19.239Z,1538611819.239 [NAL9602] Data Fault, FailCount= 4 2018-10-04T00:10:19.239Z,1538611819.239 [NAL9602](ERROR): Data Fault 2018-10-04T00:10:19.365Z,1538611819.365 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T00:10:19.642Z,1538611819.642 [NAL9602](INFO): Powering down 2018-10-04T00:10:20.512Z,1538611820.512 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T00:10:20.512Z,1538611820.512 [NAL9602] No Fault, FailCount= 4 2018-10-04T00:10:27.805Z,1538611827.805 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2018-10-04T00:10:27.805Z,1538611827.805 [RDI_Pathfinder](ERROR): Failed to parse: :TS,18100317162907,35, 0.0,1448.9, 0 2018-10-04T00:10:49.771Z,1538611849.771 [NAL9602](INFO): Powering up NAL9602 2018-10-04T00:11:02.319Z,1538611862.319 [NAL9602](INFO): NAL9602 initialized 2018-10-04T00:12:48.464Z,1538611968.464 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T00:12:49.635Z,1538611969.635 [RDI_Pathfinder](ERROR): Failed to parse: :HM,G,D,0e8c,0ffd, 15.873, -0.000, 0.000 2018-10-04T00:15:08.747Z,1538612108.747 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T00:10:06.5Z 2018-10-04T00:15:08.747Z,1538612108.747 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T00:15:08.747Z,1538612108.747 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T00:15:13.887Z,1538612113.887 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181003T231749/Courier0015.lzma 2018-10-04T00:15:14.654Z,1538612114.654 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0015.lzma.bak 2018-10-04T00:15:14.654Z,1538612114.654 [DataOverHttps](INFO): SBD MOMSN=8610402 2018-10-04T00:15:24.274Z,1538612124.274 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20181003T231749/Express0016.lzma 2018-10-04T00:15:25.046Z,1538612125.046 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0016.lzma.bak 2018-10-04T00:15:25.046Z,1538612125.046 [DataOverHttps](INFO): SBD MOMSN=8610404 2018-10-04T00:15:26.045Z,1538612126.045 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T00:15:26.045Z,1538612126.045 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T00:15:26.045Z,1538612126.045 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T00:16:05.655Z,1538612165.655 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T00:16:36.436Z,1538612196.436 [NAL9602](INFO): Powering down 2018-10-04T00:17:33.337Z,1538612253.337 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2018-10-04T00:17:52.059Z,1538612272.059 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T00:20:26.220Z,1538612426.220 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T00:20:26.220Z,1538612426.220 [Default:CheckIn:C.Wait] Stopped 2018-10-04T00:20:26.220Z,1538612426.220 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T00:20:26.393Z,1538612426.393 [Default:CheckIn:D] Running Loop=1 2018-10-04T00:20:26.662Z,1538612426.662 [Default:CheckIn:D] Stopped 2018-10-04T00:20:26.662Z,1538612426.662 [Default:CheckIn:E] Running Loop=1 2018-10-04T00:20:27.027Z,1538612427.027 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.621594 min 2018-10-04T00:20:27.027Z,1538612427.027 [Default:CheckIn:E] Stopped 2018-10-04T00:20:27.027Z,1538612427.027 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T00:20:27.028Z,1538612427.028 [Default:CheckIn] Stopped 2018-10-04T00:20:27.028Z,1538612427.028 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T00:20:27.028Z,1538612427.028 [Default:CheckIn](INFO): Running loop #7 2018-10-04T00:20:27.028Z,1538612427.028 [Default:CheckIn] Running Loop=7 2018-10-04T00:20:27.028Z,1538612427.028 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T00:20:27.028Z,1538612427.028 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T00:20:27.786Z,1538612427.786 [NAL9602](INFO): Powering up 2018-10-04T00:20:38.995Z,1538612438.995 [NAL9602](INFO): NAL9602 initialized 2018-10-04T00:21:10.687Z,1538612470.687 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-10-04T00:21:10.766Z,1538612470.766 [NAL9602](FAULT): received: +CSQ:0 OK86, 2, 0, 0, 0 OK 2018-10-04T00:21:10.766Z,1538612470.766 [NAL9602] Data Fault, FailCount= 5 2018-10-04T00:21:10.766Z,1538612470.766 [NAL9602](ERROR): Data Fault 2018-10-04T00:21:10.846Z,1538612470.846 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T00:21:10.847Z,1538612470.847 [CBIT](CRITICAL): Data Fault in component: NAL9602 2018-10-04T00:21:11.090Z,1538612471.090 [NAL9602](INFO): Powering down 2018-10-04T00:21:11.155Z,1538612471.155 [CBIT](INFO): Critical error at 20181004T002110 2018-10-04T00:23:09.950Z,1538612589.950 [RDI_Pathfinder](ERROR): only read 3 of 5 data items 2018-10-04T00:23:09.950Z,1538612589.950 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-3768,-32768,V 2018-10-04T00:23:10.799Z,1538612590.799 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T00:23:10.799Z,1538612590.799 [NAL9602] No Fault, FailCount= 5 2018-10-04T00:23:11.126Z,1538612591.126 [NAL9602](INFO): Powering up NAL9602 2018-10-04T00:23:22.327Z,1538612602.327 [NAL9602](INFO): NAL9602 initialized 2018-10-04T00:23:23.126Z,1538612603.126 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-10-04T00:23:23.126Z,1538612603.126 [NAL9602] Data Fault, FailCount= 1 2018-10-04T00:23:23.127Z,1538612603.127 [NAL9602](ERROR): Data Fault 2018-10-04T00:23:23.201Z,1538612603.201 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T00:23:23.526Z,1538612603.526 [NAL9602](INFO): Powering down 2018-10-04T00:23:24.414Z,1538612604.414 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T00:23:24.414Z,1538612604.414 [NAL9602] No Fault, FailCount= 1 2018-10-04T00:23:43.706Z,1538612623.706 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,-32768,V 2018-10-04T00:23:53.678Z,1538612633.678 [NAL9602](INFO): Powering up NAL9602 2018-10-04T00:24:04.486Z,1538612644.486 [NAL9602](INFO): NAL9602 initialized 2018-10-04T00:25:30.474Z,1538612730.474 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T00:20:27.0Z 2018-10-04T00:25:30.474Z,1538612730.474 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T00:25:30.475Z,1538612730.475 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T00:25:35.487Z,1538612735.487 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20181003T231749/Courier0018.lzma 2018-10-04T00:25:36.259Z,1538612736.259 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0018.lzma.bak 2018-10-04T00:25:36.259Z,1538612736.259 [DataOverHttps](INFO): SBD MOMSN=8610409 2018-10-04T00:25:37.173Z,1538612737.173 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,-32768,V 2018-10-04T00:25:45.499Z,1538612745.499 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T00:25:45.661Z,1538612745.661 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20181003T231749/Express0019.lzma 2018-10-04T00:25:46.429Z,1538612746.429 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0019.lzma.bak 2018-10-04T00:25:46.430Z,1538612746.430 [DataOverHttps](INFO): SBD MOMSN=8610412 2018-10-04T00:25:47.387Z,1538612747.387 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T00:25:47.387Z,1538612747.387 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T00:25:47.387Z,1538612747.387 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T00:26:15.958Z,1538612775.958 [NAL9602](INFO): Powering down 2018-10-04T00:30:47.563Z,1538613047.563 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T00:30:47.563Z,1538613047.563 [Default:CheckIn:C.Wait] Stopped 2018-10-04T00:30:47.563Z,1538613047.563 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T00:30:47.564Z,1538613047.564 [Default:CheckIn:D] Running Loop=1 2018-10-04T00:30:47.934Z,1538613047.934 [Default:CheckIn:D] Stopped 2018-10-04T00:30:47.934Z,1538613047.934 [Default:CheckIn:E] Running Loop=1 2018-10-04T00:30:48.336Z,1538613048.336 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.976131 min 2018-10-04T00:30:48.337Z,1538613048.337 [Default:CheckIn:E] Stopped 2018-10-04T00:30:48.337Z,1538613048.337 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T00:30:48.337Z,1538613048.337 [Default:CheckIn] Stopped 2018-10-04T00:30:48.337Z,1538613048.337 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T00:30:48.337Z,1538613048.337 [Default:CheckIn](INFO): Running loop #8 2018-10-04T00:30:48.337Z,1538613048.337 [Default:CheckIn] Running Loop=8 2018-10-04T00:30:48.337Z,1538613048.337 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T00:30:48.338Z,1538613048.338 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T00:30:49.107Z,1538613049.107 [NAL9602](INFO): Powering up 2018-10-04T00:31:00.299Z,1538613060.299 [NAL9602](INFO): NAL9602 initialized 2018-10-04T00:31:32.042Z,1538613092.042 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-10-04T00:31:32.121Z,1538613092.121 [NAL9602](FAULT): received: +CSQ:0 OK86, 2, 0, 0, 0 OK 2018-10-04T00:31:32.121Z,1538613092.121 [NAL9602] Data Fault, FailCount= 2 2018-10-04T00:31:32.121Z,1538613092.121 [NAL9602](ERROR): Data Fault 2018-10-04T00:31:32.202Z,1538613092.202 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T00:31:32.442Z,1538613092.442 [NAL9602](INFO): Powering down 2018-10-04T00:31:33.312Z,1538613093.312 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T00:31:33.317Z,1538613093.317 [NAL9602] No Fault, FailCount= 2 2018-10-04T00:32:02.618Z,1538613122.618 [NAL9602](INFO): Powering up NAL9602 2018-10-04T00:32:13.814Z,1538613133.814 [NAL9602](INFO): NAL9602 initialized 2018-10-04T00:34:05.706Z,1538613245.706 [RDI_Pathfinder](ERROR): Failed to parse: 2018-10-04T00:35:49.908Z,1538613349.908 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T00:30:48.3Z 2018-10-04T00:35:49.908Z,1538613349.908 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T00:35:49.910Z,1538613349.910 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T00:35:54.955Z,1538613354.955 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181003T231749/Courier0021.lzma 2018-10-04T00:35:55.778Z,1538613355.778 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0021.lzma.bak 2018-10-04T00:35:55.778Z,1538613355.778 [DataOverHttps](INFO): SBD MOMSN=8610418 2018-10-04T00:36:05.124Z,1538613365.124 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T00:36:05.385Z,1538613365.385 [DataOverHttps](INFO): Sending 236 bytes from file Logs/20181003T231749/Express0022.lzma 2018-10-04T00:36:06.127Z,1538613366.127 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0022.lzma.bak 2018-10-04T00:36:06.127Z,1538613366.127 [DataOverHttps](INFO): SBD MOMSN=8610420 2018-10-04T00:36:07.048Z,1538613367.048 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T00:36:07.048Z,1538613367.048 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T00:36:07.048Z,1538613367.048 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T00:36:35.598Z,1538613395.598 [NAL9602](INFO): Powering down 2018-10-04T00:41:07.417Z,1538613667.417 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T00:41:07.417Z,1538613667.417 [Default:CheckIn:C.Wait] Stopped 2018-10-04T00:41:07.417Z,1538613667.417 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T00:41:07.418Z,1538613667.418 [Default:CheckIn:D] Running Loop=1 2018-10-04T00:41:07.802Z,1538613667.802 [Default:CheckIn:D] Stopped 2018-10-04T00:41:07.802Z,1538613667.802 [Default:CheckIn:E] Running Loop=1 2018-10-04T00:41:08.183Z,1538613668.183 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.307267 min 2018-10-04T00:41:08.183Z,1538613668.183 [Default:CheckIn:E] Stopped 2018-10-04T00:41:08.183Z,1538613668.183 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T00:41:08.183Z,1538613668.183 [Default:CheckIn] Stopped 2018-10-04T00:41:08.183Z,1538613668.183 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T00:41:08.183Z,1538613668.183 [Default:CheckIn](INFO): Running loop #9 2018-10-04T00:41:08.184Z,1538613668.184 [Default:CheckIn] Running Loop=9 2018-10-04T00:41:08.184Z,1538613668.184 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T00:41:08.184Z,1538613668.184 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T00:41:08.938Z,1538613668.938 [NAL9602](INFO): Powering up 2018-10-04T00:41:19.742Z,1538613679.742 [NAL9602](INFO): NAL9602 initialized 2018-10-04T00:41:20.538Z,1538613680.538 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-10-04T00:41:20.539Z,1538613680.539 [NAL9602] Data Fault, FailCount= 3 2018-10-04T00:41:20.539Z,1538613680.539 [NAL9602](ERROR): Data Fault 2018-10-04T00:41:20.619Z,1538613680.619 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T00:41:20.938Z,1538613680.938 [NAL9602](INFO): Powering down 2018-10-04T00:41:21.833Z,1538613681.833 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T00:41:21.833Z,1538613681.833 [NAL9602] No Fault, FailCount= 3 2018-10-04T00:41:51.190Z,1538613711.190 [NAL9602](INFO): Powering up NAL9602 2018-10-04T00:42:04.680Z,1538613724.680 [NAL9602](INFO): NAL9602 initialized 2018-10-04T00:46:10.103Z,1538613970.103 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T00:41:08.2Z 2018-10-04T00:46:10.103Z,1538613970.103 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T00:46:10.103Z,1538613970.103 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T00:46:15.125Z,1538613975.125 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181003T231749/Courier0024.lzma 2018-10-04T00:46:15.881Z,1538613975.881 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0024.lzma.bak 2018-10-04T00:46:15.882Z,1538613975.882 [DataOverHttps](INFO): SBD MOMSN=8610425 2018-10-04T00:46:25.439Z,1538613985.439 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20181003T231749/Express0025.lzma 2018-10-04T00:46:26.214Z,1538613986.214 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0025.lzma.bak 2018-10-04T00:46:26.214Z,1538613986.214 [DataOverHttps](INFO): SBD MOMSN=8610427 2018-10-04T00:46:27.229Z,1538613987.229 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T00:46:27.244Z,1538613987.244 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T00:46:27.244Z,1538613987.244 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T00:46:27.245Z,1538613987.245 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T00:47:08.150Z,1538614028.150 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T00:47:41.354Z,1538614061.354 [NAL9602](INFO): Powering down 2018-10-04T00:50:13.731Z,1538614213.731 [RDI_Pathfinder](ERROR): only read 3 of 5 data items 2018-10-04T00:50:13.731Z,1538614213.731 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-3768,-32768,-32768,V 2018-10-04T00:51:27.644Z,1538614287.644 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T00:51:27.644Z,1538614287.644 [Default:CheckIn:C.Wait] Stopped 2018-10-04T00:51:27.649Z,1538614287.649 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T00:51:27.649Z,1538614287.649 [Default:CheckIn:D] Running Loop=1 2018-10-04T00:51:28.021Z,1538614288.021 [Default:CheckIn:D] Stopped 2018-10-04T00:51:28.022Z,1538614288.022 [Default:CheckIn:E] Running Loop=1 2018-10-04T00:51:28.439Z,1538614288.439 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.644255 min 2018-10-04T00:51:28.440Z,1538614288.440 [Default:CheckIn:E] Stopped 2018-10-04T00:51:28.440Z,1538614288.440 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T00:51:28.440Z,1538614288.440 [Default:CheckIn] Stopped 2018-10-04T00:51:28.440Z,1538614288.440 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T00:51:28.440Z,1538614288.440 [Default:CheckIn](INFO): Running loop #10 2018-10-04T00:51:28.440Z,1538614288.440 [Default:CheckIn] Running Loop=10 2018-10-04T00:51:28.440Z,1538614288.440 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T00:51:28.445Z,1538614288.445 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T00:51:29.166Z,1538614289.166 [NAL9602](INFO): Powering up 2018-10-04T00:51:39.974Z,1538614299.974 [NAL9602](INFO): NAL9602 initialized 2018-10-04T00:52:11.827Z,1538614331.827 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-10-04T00:52:11.905Z,1538614331.905 [NAL9602](FAULT): received: +CSQ:0 OK86, 2, 0, 0, 0 OK 2018-10-04T00:52:11.905Z,1538614331.905 [NAL9602] Data Fault, FailCount= 4 2018-10-04T00:52:11.905Z,1538614331.905 [NAL9602](ERROR): Data Fault 2018-10-04T00:52:12.002Z,1538614332.002 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T00:52:12.235Z,1538614332.235 [NAL9602](INFO): Powering down 2018-10-04T00:52:13.084Z,1538614333.084 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T00:52:13.084Z,1538614333.084 [NAL9602] No Fault, FailCount= 4 2018-10-04T00:52:42.386Z,1538614362.386 [NAL9602](INFO): Powering up NAL9602 2018-10-04T00:52:53.178Z,1538614373.178 [NAL9602](INFO): NAL9602 initialized 2018-10-04T00:52:53.996Z,1538614373.996 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-10-04T00:52:53.996Z,1538614373.996 [NAL9602] Data Fault, FailCount= 5 2018-10-04T00:52:53.996Z,1538614373.996 [NAL9602](ERROR): Data Fault 2018-10-04T00:52:54.099Z,1538614374.099 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T00:52:54.099Z,1538614374.099 [CBIT](CRITICAL): Data Fault in component: NAL9602 2018-10-04T00:52:54.382Z,1538614374.382 [NAL9602](INFO): Powering down 2018-10-04T00:52:54.488Z,1538614374.488 [CBIT](INFO): Critical error at 20181004T005254 2018-10-04T00:54:10.882Z,1538614450.882 [RDI_Pathfinder](ERROR): Failed to parse: :HM,G,D,0e8b,0ffd,*15.914,*-0.009,*0.000 2018-10-04T00:54:54.002Z,1538614494.002 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T00:54:54.002Z,1538614494.002 [NAL9602] No Fault, FailCount= 5 2018-10-04T00:54:54.356Z,1538614494.356 [NAL9602](INFO): Powering up NAL9602 2018-10-04T00:55:05.534Z,1538614505.534 [NAL9602](INFO): NAL9602 initialized 2018-10-04T00:56:29.747Z,1538614589.747 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T00:51:28.4Z 2018-10-04T00:56:29.747Z,1538614589.747 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T00:56:29.748Z,1538614589.748 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T00:56:34.813Z,1538614594.813 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20181003T231749/Courier0027.lzma 2018-10-04T00:56:35.583Z,1538614595.583 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0027.lzma.bak 2018-10-04T00:56:35.583Z,1538614595.583 [DataOverHttps](INFO): SBD MOMSN=8610432 2018-10-04T00:56:45.038Z,1538614605.038 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T00:56:45.203Z,1538614605.203 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20181003T231749/Express0028.lzma 2018-10-04T00:56:45.958Z,1538614605.958 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0028.lzma.bak 2018-10-04T00:56:45.958Z,1538614605.958 [DataOverHttps](INFO): SBD MOMSN=8610435 2018-10-04T00:56:46.890Z,1538614606.890 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T00:56:46.890Z,1538614606.890 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T00:56:46.891Z,1538614606.891 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T00:57:15.446Z,1538614635.446 [NAL9602](INFO): Powering down 2018-10-04T00:58:01.000Z,1538614682.000 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T01:01:47.440Z,1538614907.440 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T01:01:47.440Z,1538614907.440 [Default:CheckIn:C.Wait] Stopped 2018-10-04T01:01:47.440Z,1538614907.440 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T01:01:47.440Z,1538614907.440 [Default:CheckIn:D] Running Loop=1 2018-10-04T01:01:47.792Z,1538614907.792 [Default:CheckIn:D] Stopped 2018-10-04T01:01:47.792Z,1538614907.792 [Default:CheckIn:E] Running Loop=1 2018-10-04T01:01:48.201Z,1538614908.201 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.973763 min 2018-10-04T01:01:48.201Z,1538614908.201 [Default:CheckIn:E] Stopped 2018-10-04T01:01:48.201Z,1538614908.201 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T01:01:48.201Z,1538614908.201 [Default:CheckIn] Stopped 2018-10-04T01:01:48.202Z,1538614908.202 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T01:01:48.202Z,1538614908.202 [Default:CheckIn](INFO): Running loop #11 2018-10-04T01:01:48.202Z,1538614908.202 [Default:CheckIn] Running Loop=11 2018-10-04T01:01:48.202Z,1538614908.202 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T01:01:48.202Z,1538614908.202 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T01:01:48.946Z,1538614908.946 [NAL9602](INFO): Powering up 2018-10-04T01:02:00.146Z,1538614920.146 [NAL9602](INFO): NAL9602 initialized 2018-10-04T01:02:31.918Z,1538614951.918 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-10-04T01:02:32.001Z,1538614952.001 [NAL9602](FAULT): received: +CSQ:0 OK86, 2, 0, 0, 0 OK 2018-10-04T01:02:32.001Z,1538614952.001 [NAL9602] Data Fault, FailCount= 1 2018-10-04T01:02:32.001Z,1538614952.001 [NAL9602](ERROR): Data Fault 2018-10-04T01:02:32.105Z,1538614952.105 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T01:02:32.318Z,1538614952.318 [NAL9602](INFO): Powering down 2018-10-04T01:02:33.188Z,1538614953.188 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T01:02:33.188Z,1538614953.188 [NAL9602] No Fault, FailCount= 1 2018-10-04T01:03:02.518Z,1538614982.518 [NAL9602](INFO): Powering up NAL9602 2018-10-04T01:03:13.332Z,1538614993.332 [NAL9602](INFO): NAL9602 initialized 2018-10-04T01:06:50.009Z,1538615210.009 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T01:01:48.2Z 2018-10-04T01:06:50.009Z,1538615210.009 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T01:06:50.010Z,1538615210.010 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T01:06:55.131Z,1538615215.131 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181003T231749/Courier0030.lzma 2018-10-04T01:06:55.902Z,1538615215.902 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0030.lzma.bak 2018-10-04T01:06:55.902Z,1538615215.902 [DataOverHttps](INFO): SBD MOMSN=8610441 2018-10-04T01:07:05.148Z,1538615225.148 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T01:07:05.316Z,1538615225.316 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20181003T231749/Express0031.lzma 2018-10-04T01:07:06.075Z,1538615226.075 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0031.lzma.bak 2018-10-04T01:07:06.075Z,1538615226.075 [DataOverHttps](INFO): SBD MOMSN=8610443 2018-10-04T01:07:07.002Z,1538615227.002 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T01:07:07.002Z,1538615227.002 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T01:07:07.002Z,1538615227.002 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T01:07:35.499Z,1538615255.499 [NAL9602](INFO): Powering down 2018-10-04T01:11:55.706Z,1538615515.706 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T01:12:07.349Z,1538615527.349 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T01:12:07.350Z,1538615527.350 [Default:CheckIn:C.Wait] Stopped 2018-10-04T01:12:07.350Z,1538615527.350 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T01:12:07.351Z,1538615527.351 [Default:CheckIn:D] Running Loop=1 2018-10-04T01:12:07.739Z,1538615527.739 [Default:CheckIn:D] Stopped 2018-10-04T01:12:07.740Z,1538615527.740 [Default:CheckIn:E] Running Loop=1 2018-10-04T01:12:08.135Z,1538615528.135 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 114.306226 min 2018-10-04T01:12:08.135Z,1538615528.135 [Default:CheckIn:E] Stopped 2018-10-04T01:12:08.135Z,1538615528.135 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T01:12:08.135Z,1538615528.135 [Default:CheckIn] Stopped 2018-10-04T01:12:08.136Z,1538615528.136 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T01:12:08.136Z,1538615528.136 [Default:CheckIn](INFO): Running loop #12 2018-10-04T01:12:08.136Z,1538615528.136 [Default:CheckIn] Running Loop=12 2018-10-04T01:12:08.136Z,1538615528.136 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T01:12:08.136Z,1538615528.136 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T01:12:08.866Z,1538615528.866 [NAL9602](INFO): Powering up 2018-10-04T01:12:19.670Z,1538615539.670 [NAL9602](INFO): NAL9602 initialized 2018-10-04T01:12:20.467Z,1538615540.467 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-10-04T01:12:20.467Z,1538615540.467 [NAL9602] Data Fault, FailCount= 2 2018-10-04T01:12:20.467Z,1538615540.467 [NAL9602](ERROR): Data Fault 2018-10-04T01:12:20.524Z,1538615540.524 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T01:12:20.866Z,1538615540.866 [NAL9602](INFO): Powering down 2018-10-04T01:12:21.772Z,1538615541.772 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T01:12:21.772Z,1538615541.772 [NAL9602] No Fault, FailCount= 2 2018-10-04T01:12:51.038Z,1538615571.038 [NAL9602](INFO): Powering up NAL9602 2018-10-04T01:13:03.575Z,1538615583.575 [NAL9602](INFO): NAL9602 initialized 2018-10-04T01:16:00.821Z,1538615760.821 [RDI_Pathfinder](ERROR): Failed to parse: 2018-10-04T01:17:09.828Z,1538615829.828 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T01:12:08.1Z 2018-10-04T01:17:09.828Z,1538615829.828 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T01:17:09.829Z,1538615829.829 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T01:17:15.074Z,1538615835.074 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181003T231749/Courier0033.lzma 2018-10-04T01:17:15.845Z,1538615835.845 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0033.lzma.bak 2018-10-04T01:17:15.846Z,1538615835.846 [DataOverHttps](INFO): SBD MOMSN=8610448 2018-10-04T01:17:25.775Z,1538615845.775 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20181003T231749/Express0034.lzma 2018-10-04T01:17:27.158Z,1538615847.158 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0034.lzma.bak 2018-10-04T01:17:27.158Z,1538615847.158 [DataOverHttps](INFO): SBD MOMSN=8610450 2018-10-04T01:17:27.903Z,1538615847.903 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T01:17:27.903Z,1538615847.903 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T01:17:27.903Z,1538615847.903 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T01:18:06.838Z,1538615886.838 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T01:18:37.598Z,1538615917.598 [NAL9602](INFO): Powering down 2018-10-04T01:19:43.449Z,1538615983.449 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.399155 2018-10-04T01:20:18.681Z,1538616018.681 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:20:42.342Z,1538616042.342 [RDI_Pathfinder](ERROR): only read 4 of 5 data items 2018-10-04T01:20:42.342Z,1538616042.342 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768-32768,-32768,-32768,V 2018-10-04T01:20:53.884Z,1538616053.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:21:29.081Z,1538616089.081 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:22:04.287Z,1538616124.287 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:22:28.332Z,1538616148.332 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T01:22:28.332Z,1538616148.332 [Default:CheckIn:C.Wait] Stopped 2018-10-04T01:22:28.332Z,1538616148.332 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T01:22:28.332Z,1538616148.332 [Default:CheckIn:D] Running Loop=1 2018-10-04T01:22:28.743Z,1538616148.743 [Default:CheckIn:D] Stopped 2018-10-04T01:22:28.743Z,1538616148.743 [Default:CheckIn:E] Running Loop=1 2018-10-04T01:22:29.137Z,1538616149.137 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.656274 min 2018-10-04T01:22:29.137Z,1538616149.137 [Default:CheckIn:E] Stopped 2018-10-04T01:22:29.137Z,1538616149.137 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T01:22:29.137Z,1538616149.137 [Default:CheckIn] Stopped 2018-10-04T01:22:29.138Z,1538616149.138 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T01:22:29.138Z,1538616149.138 [Default:CheckIn](INFO): Running loop #13 2018-10-04T01:22:29.138Z,1538616149.138 [Default:CheckIn] Running Loop=13 2018-10-04T01:22:29.138Z,1538616149.138 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T01:22:29.138Z,1538616149.138 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T01:22:29.886Z,1538616149.886 [NAL9602](INFO): Powering up 2018-10-04T01:22:39.882Z,1538616159.882 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:22:41.096Z,1538616161.096 [NAL9602](INFO): NAL9602 initialized 2018-10-04T01:23:12.698Z,1538616192.698 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-10-04T01:23:12.781Z,1538616192.781 [NAL9602](FAULT): received: +CSQ:0 OK86, 2, 0, 0, 0 OK 2018-10-04T01:23:12.781Z,1538616192.781 [NAL9602] Data Fault, FailCount= 3 2018-10-04T01:23:12.781Z,1538616192.781 [NAL9602](ERROR): Data Fault 2018-10-04T01:23:12.864Z,1538616192.864 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T01:23:13.091Z,1538616193.091 [NAL9602](INFO): Powering down 2018-10-04T01:23:13.965Z,1538616193.965 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T01:23:13.965Z,1538616193.965 [NAL9602] No Fault, FailCount= 3 2018-10-04T01:23:14.693Z,1538616194.693 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:23:43.483Z,1538616223.483 [NAL9602](INFO): Powering up NAL9602 2018-10-04T01:23:50.280Z,1538616230.280 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:23:54.304Z,1538616234.304 [NAL9602](INFO): NAL9602 initialized 2018-10-04T01:23:55.095Z,1538616235.095 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-10-04T01:23:55.095Z,1538616235.095 [NAL9602] Data Fault, FailCount= 4 2018-10-04T01:23:55.095Z,1538616235.095 [NAL9602](ERROR): Data Fault 2018-10-04T01:23:55.160Z,1538616235.160 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T01:23:55.495Z,1538616235.495 [NAL9602](INFO): Powering down 2018-10-04T01:23:56.395Z,1538616236.395 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T01:23:56.395Z,1538616236.395 [NAL9602] No Fault, FailCount= 4 2018-10-04T01:24:25.485Z,1538616265.485 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:24:25.499Z,1538616265.499 [NAL9602](INFO): Powering up NAL9602 2018-10-04T01:24:36.686Z,1538616276.686 [NAL9602](INFO): NAL9602 initialized 2018-10-04T01:24:58.343Z,1538616298.343 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2018-10-04T01:25:00.280Z,1538616300.280 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:25:35.480Z,1538616335.480 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:26:11.080Z,1538616371.080 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:26:46.294Z,1538616406.294 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:27:12.347Z,1538616432.347 [RDI_Pathfinder](ERROR): Unknown system health message 2018-10-04T01:27:12.347Z,1538616432.347 [RDI_Pathfinder] Hardware Fault, FailCount= 1 2018-10-04T01:27:12.347Z,1538616432.347 [RDI_Pathfinder](ERROR): Hardware Fault 2018-10-04T01:27:12.398Z,1538616432.398 [CBIT](ERROR): Hardware Fault in component: RDI_Pathfinder 2018-10-04T01:27:12.773Z,1538616432.773 [RDI_Pathfinder](INFO): Powering down 2018-10-04T01:27:13.548Z,1538616433.548 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2018-10-04T01:27:13.548Z,1538616433.548 [RDI_Pathfinder] No Fault, FailCount= 0 2018-10-04T01:27:21.082Z,1538616441.082 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:27:27.131Z,1538616447.131 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder 2018-10-04T01:27:29.143Z,1538616449.143 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T01:22:29.1Z 2018-10-04T01:27:29.143Z,1538616449.143 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T01:27:29.143Z,1538616449.143 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T01:27:45.090Z,1538616465.090 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T01:27:56.293Z,1538616476.293 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:28:31.652Z,1538616511.652 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:29:06.856Z,1538616546.856 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:29:42.224Z,1538616582.224 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:30:17.441Z,1538616617.441 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:30:52.684Z,1538616652.684 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:31:27.909Z,1538616687.909 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:31:56.272Z,1538616716.272 [NAL9602](INFO): SBD MO Status=2, MOMSN=1986, MT Status=2, MTMSN=0 2018-10-04T01:31:56.272Z,1538616716.272 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-10-04T01:32:03.456Z,1538616723.456 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:32:38.320Z,1538616758.320 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:33:13.528Z,1538616793.528 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:33:29.453Z,1538616809.453 [RDI_Pathfinder](ERROR): Failed to parse: :HM,G,D,0e8c,0ffd, 15.833, -0.009, 0.000 2018-10-04T01:33:49.148Z,1538616829.148 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:34:24.348Z,1538616864.348 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:34:59.335Z,1538616899.335 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:35:34.684Z,1538616934.684 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:36:10.009Z,1538616970.009 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:36:46.284Z,1538617006.284 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:37:21.798Z,1538617041.798 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:37:57.005Z,1538617077.005 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:38:32.073Z,1538617112.073 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:39:06.936Z,1538617146.936 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:39:16.548Z,1538617156.548 [RDI_Pathfinder](ERROR): Failed to parse: :HM,G,D,0e8c,0ffd,*15.847,*-0.008,*0.000 2018-10-04T01:39:37.754Z,1538617177.754 [RDI_Pathfinder](ERROR): Failed to parse: 2018-10-04T01:39:38.813Z,1538617178.813 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T01:39:42.492Z,1538617182.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:40:03.984Z,1538617203.984 [NAL9602](INFO): SBD MO Status=2, MOMSN=1986, MT Status=2, MTMSN=0 2018-10-04T01:40:03.984Z,1538617203.984 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-10-04T01:40:17.553Z,1538617217.553 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:40:52.753Z,1538617252.753 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:41:04.942Z,1538617264.942 [NAL9602](INFO): SBD MO Status=2, MOMSN=1986, MT Status=2, MTMSN=0 2018-10-04T01:41:04.942Z,1538617264.942 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-10-04T01:41:28.536Z,1538617288.536 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:42:03.736Z,1538617323.736 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:42:27.292Z,1538617347.292 [NAL9602](INFO): SBD MO Status=2, MOMSN=1986, MT Status=2, MTMSN=0 2018-10-04T01:42:27.292Z,1538617347.292 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-10-04T01:42:36.542Z,1538617356.542 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2018-10-04T01:42:36.542Z,1538617356.542 [RDI_Pathfinder](ERROR): Failed to parse: :TS,18100318483758,35.0, -0.1, 0.0,1448.9,,-32768,-32768,-32768,-32768,V 2018-10-04T01:42:38.884Z,1538617358.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:43:14.084Z,1538617394.084 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:43:48.904Z,1538617428.904 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:44:24.508Z,1538617464.508 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:44:59.361Z,1538617499.361 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:45:34.591Z,1538617534.591 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:46:10.016Z,1538617570.016 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:46:44.832Z,1538617604.832 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:47:20.076Z,1538617640.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:47:55.304Z,1538617675.304 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:48:30.520Z,1538617710.520 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:49:05.720Z,1538617745.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:49:40.982Z,1538617780.982 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:50:16.540Z,1538617816.540 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:50:51.559Z,1538617851.559 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:51:07.148Z,1538617867.148 [NAL9602](INFO): SBD MO Status=2, MOMSN=1986, MT Status=2, MTMSN=0 2018-10-04T01:51:07.148Z,1538617867.148 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-10-04T01:51:26.938Z,1538617886.938 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:51:31.744Z,1538617891.744 [NAL9602](INFO): SBD MO Status=2, MOMSN=1986, MT Status=2, MTMSN=0 2018-10-04T01:51:31.745Z,1538617891.745 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-10-04T01:52:01.902Z,1538617921.902 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:52:35.252Z,1538617955.252 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2018-10-04T01:52:35.252Z,1538617955.252 [RDI_Pathfinder](ERROR): Failed to parse: :TS,18100318583658,35.0, -0.1, 0.0,1448:BI,-32768,-32768,-32768,-32768,V 2018-10-04T01:52:37.212Z,1538617957.212 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:53:08.100Z,1538617988.100 [RDI_Pathfinder](ERROR): Failed to parse: 2018-10-04T01:53:12.472Z,1538617992.472 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:53:26.462Z,1538618006.462 [RDI_Pathfinder](ERROR): Failed to parse: :HM,G,D,0e8c,0ffd,*15.853,*-0.003,*0.000 2018-10-04T01:53:47.743Z,1538618027.743 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:54:22.990Z,1538618062.990 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:54:58.094Z,1538618098.094 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T01:54:58.436Z,1538618098.436 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:55:33.696Z,1538618133.696 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:56:09.169Z,1538618169.169 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:56:44.361Z,1538618204.361 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:57:19.164Z,1538618239.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:57:54.412Z,1538618274.412 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:58:29.625Z,1538618309.625 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:59:04.908Z,1538618344.908 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:59:40.320Z,1538618380.320 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T01:59:53.536Z,1538618393.536 [NAL9602](INFO): SBD MO Status=1, MOMSN=1986, MT Status=0, MTMSN=0 2018-10-04T01:59:53.585Z,1538618393.585 [NAL9602](INFO): Sent 46 bytes from file Logs/20181003T231749/Courier0036.lzma 2018-10-04T01:59:53.585Z,1538618393.585 [NAL9602](INFO): Packets left to send: 0 2018-10-04T01:59:53.587Z,1538618393.587 [NAL9602](DEBUG): Stored copy of sent data in Logs/20181003T231749/Courier0036.lzma.parts/0000.sbd 2018-10-04T01:59:53.587Z,1538618393.587 [NAL9602](DEBUG): Completed sending Logs/20181003T231749/Courier0036.lzma 2018-10-04T02:00:08.364Z,1538618408.364 [NAL9602](INFO): SBD MO Status=2, MOMSN=1987, MT Status=2, MTMSN=0 2018-10-04T02:00:08.364Z,1538618408.364 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-10-04T02:00:15.440Z,1538618415.440 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:00:24.121Z,1538618424.121 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T02:00:34.493Z,1538618434.493 [NAL9602](INFO): SBD MO Status=2, MOMSN=1987, MT Status=2, MTMSN=0 2018-10-04T02:00:34.493Z,1538618434.493 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-10-04T02:00:50.604Z,1538618450.604 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:01:25.956Z,1538618485.956 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:02:00.913Z,1538618520.913 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:02:36.452Z,1538618556.452 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:03:11.520Z,1538618591.520 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:03:47.048Z,1538618627.048 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:04:22.260Z,1538618662.260 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:04:57.794Z,1538618697.794 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:05:32.727Z,1538618732.727 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:06:07.976Z,1538618767.976 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:06:43.216Z,1538618803.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:07:18.424Z,1538618838.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:07:53.516Z,1538618873.516 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:08:28.904Z,1538618908.904 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:09:04.176Z,1538618944.176 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:09:08.085Z,1538618948.085 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T02:09:39.341Z,1538618979.341 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:09:54.321Z,1538618994.321 [RDI_Pathfinder](ERROR): Failed to parse: :HM,G,D,0e8c,0ffd,*15.887,*-0.007,*0.000 2018-10-04T02:10:14.352Z,1538619014.352 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:10:49.741Z,1538619049.741 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:11:24.949Z,1538619084.949 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:11:27.008Z,1538619087.008 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T02:12:00.509Z,1538619120.509 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:12:35.916Z,1538619155.916 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:13:11.345Z,1538619191.345 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:13:46.529Z,1538619226.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:14:21.300Z,1538619261.300 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:14:57.264Z,1538619297.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:15:32.209Z,1538619332.209 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:16:07.281Z,1538619367.281 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:16:42.799Z,1538619402.799 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:17:03.202Z,1538619423.202 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T02:17:18.024Z,1538619438.024 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:17:53.296Z,1538619473.296 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:18:28.633Z,1538619508.633 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:19:03.730Z,1538619543.730 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:19:06.136Z,1538619546.136 [NAL9602](INFO): SBD MO Status=2, MOMSN=1987, MT Status=2, MTMSN=0 2018-10-04T02:19:06.136Z,1538619546.136 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-10-04T02:19:39.093Z,1538619579.093 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:19:39.125Z,1538619579.125 [RDI_Pathfinder](ERROR): DVL BIT error. See manual. Result code: -32768 2018-10-04T02:19:39.126Z,1538619579.126 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,-32768,V 2018-10-04T02:20:14.228Z,1538619614.228 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:20:49.520Z,1538619649.520 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:21:24.328Z,1538619684.328 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:21:59.924Z,1538619719.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:22:34.688Z,1538619754.688 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:23:10.100Z,1538619790.100 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:23:45.737Z,1538619825.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:24:20.848Z,1538619860.848 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:24:56.092Z,1538619896.092 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:25:31.257Z,1538619931.257 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:26:06.533Z,1538619966.533 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:26:41.564Z,1538620001.564 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:27:16.756Z,1538620036.756 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:27:51.832Z,1538620071.832 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:28:27.068Z,1538620107.068 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:29:02.237Z,1538620142.237 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:29:37.897Z,1538620177.897 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:30:12.657Z,1538620212.657 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:30:48.094Z,1538620248.094 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:31:23.307Z,1538620283.307 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:31:57.937Z,1538620317.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:32:28.329Z,1538620348.329 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2018-10-04T02:32:33.268Z,1538620353.268 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:33:08.297Z,1538620388.297 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:33:12.135Z,1538620392.135 [RDI_Pathfinder](ERROR): DVL BIT error. See manual. Result code: 2768 2018-10-04T02:33:12.136Z,1538620392.136 [RDI_Pathfinder](ERROR): Failed to parse: :BS,-32768,-32768,-32768,V 2018-10-04T02:33:43.496Z,1538620423.496 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:34:18.792Z,1538620458.792 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:34:54.084Z,1538620494.084 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:35:28.944Z,1538620528.944 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:36:04.048Z,1538620564.048 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:36:28.044Z,1538620588.044 [RDI_Pathfinder](ERROR): Failed to parse: :BE,-32768,-32768,-32768,V 2018-10-04T02:36:39.104Z,1538620599.104 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:37:14.532Z,1538620634.532 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:37:32.120Z,1538620652.120 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T02:37:49.588Z,1538620669.588 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:38:25.101Z,1538620705.101 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:38:32.022Z,1538620712.022 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T02:39:01.160Z,1538620741.160 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:39:36.536Z,1538620776.536 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:40:11.372Z,1538620811.372 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:40:46.359Z,1538620846.359 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:41:21.625Z,1538620881.625 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:41:56.648Z,1538620916.648 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:42:31.984Z,1538620951.984 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:43:07.301Z,1538620987.301 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:43:42.212Z,1538621022.212 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:44:17.540Z,1538621057.540 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:44:52.426Z,1538621092.426 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:45:27.800Z,1538621127.800 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:46:03.112Z,1538621163.112 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:46:38.180Z,1538621198.180 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:46:50.710Z,1538621210.710 [RDI_Pathfinder](ERROR): Failed to parse: :HM,G,D,0e8c,0ffd, 15.806, -0.013, 0.000 2018-10-04T02:47:13.116Z,1538621233.116 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:47:48.676Z,1538621268.676 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:48:24.032Z,1538621304.032 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:48:58.991Z,1538621338.991 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:49:34.349Z,1538621374.349 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:50:09.584Z,1538621409.584 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:50:44.881Z,1538621444.881 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:51:19.608Z,1538621479.608 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2018-10-04T02:51:55.069Z,1538621515.069 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2018-10-04T02:52:05.084Z,1538621525.084 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002592 2018-10-04T02:52:09.844Z,1538621529.844 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20181003T231749/Express0037.lzma 2018-10-04T02:52:10.594Z,1538621530.594 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0037.lzma.bak 2018-10-04T02:52:10.594Z,1538621530.594 [DataOverHttps](INFO): SBD MOMSN=8610455 2018-10-04T02:52:11.543Z,1538621531.543 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T02:52:11.544Z,1538621531.544 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T02:52:11.544Z,1538621531.544 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T02:52:36.102Z,1538621556.102 [NAL9602](INFO): Powering down 2018-10-04T02:56:57.993Z,1538621817.993 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T02:57:12.011Z,1538621832.011 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T02:57:12.012Z,1538621832.012 [Default:CheckIn:C.Wait] Stopped 2018-10-04T02:57:12.012Z,1538621832.012 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T02:57:12.012Z,1538621832.012 [Default:CheckIn:D] Running Loop=1 2018-10-04T02:57:12.413Z,1538621832.413 [Default:CheckIn:D] Stopped 2018-10-04T02:57:12.413Z,1538621832.413 [Default:CheckIn:E] Running Loop=1 2018-10-04T02:57:12.861Z,1538621832.861 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 219.384033 min 2018-10-04T02:57:12.862Z,1538621832.862 [Default:CheckIn:E] Stopped 2018-10-04T02:57:12.862Z,1538621832.862 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T02:57:12.862Z,1538621832.862 [Default:CheckIn] Stopped 2018-10-04T02:57:12.862Z,1538621832.862 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T02:57:12.862Z,1538621832.862 [Default:CheckIn](INFO): Running loop #14 2018-10-04T02:57:12.862Z,1538621832.862 [Default:CheckIn] Running Loop=14 2018-10-04T02:57:12.862Z,1538621832.862 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T02:57:12.862Z,1538621832.862 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T02:57:13.558Z,1538621833.558 [NAL9602](INFO): Powering up 2018-10-04T02:57:24.754Z,1538621844.754 [NAL9602](INFO): NAL9602 initialized 2018-10-04T02:57:25.555Z,1538621845.555 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-10-04T02:57:25.555Z,1538621845.555 [NAL9602] Data Fault, FailCount= 1 2018-10-04T02:57:25.555Z,1538621845.555 [NAL9602](ERROR): Data Fault 2018-10-04T02:57:25.621Z,1538621845.621 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T02:57:25.962Z,1538621845.962 [NAL9602](INFO): Powering down 2018-10-04T02:57:26.836Z,1538621846.836 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T02:57:26.836Z,1538621846.836 [NAL9602] No Fault, FailCount= 1 2018-10-04T02:57:56.086Z,1538621876.086 [NAL9602](INFO): Powering up NAL9602 2018-10-04T02:58:08.795Z,1538621888.795 [NAL9602](INFO): NAL9602 initialized 2018-10-04T03:02:14.218Z,1538622134.218 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T02:57:12.9Z 2018-10-04T03:02:14.218Z,1538622134.218 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T03:02:14.219Z,1538622134.219 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T03:02:19.308Z,1538622139.308 [DataOverHttps](INFO): Sending 46 bytes from file Logs/20181003T231749/Courier0039.lzma 2018-10-04T03:02:20.078Z,1538622140.078 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0039.lzma.bak 2018-10-04T03:02:20.078Z,1538622140.078 [DataOverHttps](INFO): SBD MOMSN=8610461 2018-10-04T03:02:29.553Z,1538622149.553 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20181003T231749/Express0040.lzma 2018-10-04T03:02:30.330Z,1538622150.330 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0040.lzma.bak 2018-10-04T03:02:30.330Z,1538622150.330 [DataOverHttps](INFO): SBD MOMSN=8610463 2018-10-04T03:02:31.288Z,1538622151.288 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T03:02:31.288Z,1538622151.288 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T03:02:31.288Z,1538622151.288 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T03:03:11.723Z,1538622191.723 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T03:03:42.098Z,1538622222.098 [NAL9602](INFO): Powering down 2018-10-04T03:04:21.956Z,1538622261.956 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T03:07:31.724Z,1538622451.724 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T03:07:31.729Z,1538622451.729 [Default:CheckIn:C.Wait] Stopped 2018-10-04T03:07:31.729Z,1538622451.729 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T03:07:31.729Z,1538622451.729 [Default:CheckIn:D] Running Loop=1 2018-10-04T03:07:32.129Z,1538622452.129 [Default:CheckIn:D] Stopped 2018-10-04T03:07:32.129Z,1538622452.129 [Default:CheckIn:E] Running Loop=1 2018-10-04T03:07:32.492Z,1538622452.492 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 229.712712 min 2018-10-04T03:07:32.492Z,1538622452.492 [Default:CheckIn:E] Stopped 2018-10-04T03:07:32.493Z,1538622452.493 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T03:07:32.493Z,1538622452.493 [Default:CheckIn] Stopped 2018-10-04T03:07:32.493Z,1538622452.493 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T03:07:32.493Z,1538622452.493 [Default:CheckIn](INFO): Running loop #15 2018-10-04T03:07:32.493Z,1538622452.493 [Default:CheckIn] Running Loop=15 2018-10-04T03:07:32.494Z,1538622452.494 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T03:07:32.494Z,1538622452.494 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T03:07:33.250Z,1538622453.250 [NAL9602](INFO): Powering up 2018-10-04T03:07:44.050Z,1538622464.050 [NAL9602](INFO): NAL9602 initialized 2018-10-04T03:08:15.751Z,1538622495.751 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-10-04T03:08:15.833Z,1538622495.833 [NAL9602](FAULT): received: +CSQ:0 OK87, 2, 0, 0, 0 OK 2018-10-04T03:08:15.833Z,1538622495.833 [NAL9602] Data Fault, FailCount= 2 2018-10-04T03:08:15.833Z,1538622495.833 [NAL9602](ERROR): Data Fault 2018-10-04T03:08:15.922Z,1538622495.922 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T03:08:16.159Z,1538622496.159 [NAL9602](INFO): Powering down 2018-10-04T03:08:17.023Z,1538622497.023 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T03:08:17.023Z,1538622497.023 [NAL9602] No Fault, FailCount= 2 2018-10-04T03:08:46.359Z,1538622526.359 [NAL9602](INFO): Powering up NAL9602 2018-10-04T03:08:57.175Z,1538622537.175 [NAL9602](INFO): NAL9602 initialized 2018-10-04T03:08:57.966Z,1538622537.966 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-10-04T03:08:57.966Z,1538622537.966 [NAL9602] Data Fault, FailCount= 3 2018-10-04T03:08:57.966Z,1538622537.966 [NAL9602](ERROR): Data Fault 2018-10-04T03:08:58.064Z,1538622538.064 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T03:08:58.366Z,1538622538.366 [NAL9602](INFO): Powering down 2018-10-04T03:08:59.232Z,1538622539.232 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T03:08:59.232Z,1538622539.232 [NAL9602] No Fault, FailCount= 3 2018-10-04T03:09:28.546Z,1538622568.546 [NAL9602](INFO): Powering up NAL9602 2018-10-04T03:09:39.603Z,1538622579.603 [NAL9602](INFO): NAL9602 initialized 2018-10-04T03:12:33.958Z,1538622753.958 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T03:07:32.5Z 2018-10-04T03:12:33.958Z,1538622753.958 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T03:12:33.958Z,1538622753.958 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T03:12:39.056Z,1538622759.056 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181003T231749/Courier0042.lzma 2018-10-04T03:12:39.832Z,1538622759.832 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0042.lzma.bak 2018-10-04T03:12:39.832Z,1538622759.832 [DataOverHttps](INFO): SBD MOMSN=8610471 2018-10-04T03:12:49.190Z,1538622769.190 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T03:12:49.344Z,1538622769.344 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20181003T231749/Express0043.lzma 2018-10-04T03:12:50.094Z,1538622770.094 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0043.lzma.bak 2018-10-04T03:12:50.094Z,1538622770.094 [DataOverHttps](INFO): SBD MOMSN=8610473 2018-10-04T03:12:51.107Z,1538622771.107 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T03:12:51.107Z,1538622771.107 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T03:12:51.107Z,1538622771.107 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T03:13:19.394Z,1538622799.394 [NAL9602](INFO): Powering down 2018-10-04T03:17:51.340Z,1538623071.340 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T03:17:51.340Z,1538623071.340 [Default:CheckIn:C.Wait] Stopped 2018-10-04T03:17:51.340Z,1538623071.340 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T03:17:51.340Z,1538623071.340 [Default:CheckIn:D] Running Loop=1 2018-10-04T03:17:51.767Z,1538623071.767 [Default:CheckIn:D] Stopped 2018-10-04T03:17:51.768Z,1538623071.768 [Default:CheckIn:E] Running Loop=1 2018-10-04T03:17:52.152Z,1538623072.152 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 240.040023 min 2018-10-04T03:17:52.153Z,1538623072.153 [Default:CheckIn:E] Stopped 2018-10-04T03:17:52.153Z,1538623072.153 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T03:17:52.153Z,1538623072.153 [Default:CheckIn] Stopped 2018-10-04T03:17:52.153Z,1538623072.153 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T03:17:52.154Z,1538623072.154 [Default:CheckIn](INFO): Running loop #16 2018-10-04T03:17:52.154Z,1538623072.154 [Default:CheckIn] Running Loop=16 2018-10-04T03:17:52.154Z,1538623072.154 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T03:17:52.154Z,1538623072.154 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T03:17:52.898Z,1538623072.898 [NAL9602](INFO): Powering up 2018-10-04T03:18:03.702Z,1538623083.702 [NAL9602](INFO): NAL9602 initialized 2018-10-04T03:18:15.063Z,1538623095.063 [RDI_Pathfinder](ERROR): Failed to parse: :H8c,0ffd,*15.847,*0.000 2018-10-04T03:18:35.398Z,1538623115.398 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-10-04T03:18:35.473Z,1538623115.473 [NAL9602](FAULT): received: +CSQ:0 OK87, 2, 0, 0, 0 OK 2018-10-04T03:18:35.473Z,1538623115.473 [NAL9602] Data Fault, FailCount= 4 2018-10-04T03:18:35.473Z,1538623115.473 [NAL9602](ERROR): Data Fault 2018-10-04T03:18:35.570Z,1538623115.570 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T03:18:35.802Z,1538623115.802 [NAL9602](INFO): Powering down 2018-10-04T03:18:36.684Z,1538623116.684 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T03:18:36.684Z,1538623116.684 [NAL9602] No Fault, FailCount= 4 2018-10-04T03:19:06.118Z,1538623146.118 [NAL9602](INFO): Powering up NAL9602 2018-10-04T03:19:16.922Z,1538623156.922 [NAL9602](INFO): NAL9602 initialized 2018-10-04T03:22:53.292Z,1538623373.292 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T03:17:52.2Z 2018-10-04T03:22:53.292Z,1538623373.292 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T03:22:53.317Z,1538623373.317 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T03:22:58.321Z,1538623378.321 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181003T231749/Courier0045.lzma 2018-10-04T03:22:59.077Z,1538623379.077 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0045.lzma.bak 2018-10-04T03:22:59.078Z,1538623379.078 [DataOverHttps](INFO): SBD MOMSN=8610479 2018-10-04T03:23:08.397Z,1538623388.397 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T03:23:08.551Z,1538623388.551 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20181003T231749/Express0046.lzma 2018-10-04T03:23:09.323Z,1538623389.323 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0046.lzma.bak 2018-10-04T03:23:09.323Z,1538623389.323 [DataOverHttps](INFO): SBD MOMSN=8610481 2018-10-04T03:23:10.306Z,1538623390.306 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T03:23:10.306Z,1538623390.306 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T03:23:10.306Z,1538623390.306 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T03:23:38.951Z,1538623418.951 [NAL9602](INFO): Powering down 2018-10-04T03:23:53.097Z,1538623433.097 [RDI_Pathfinder](ERROR): Failed to parse: :8,-32768,-32768,-32768,V 2018-10-04T03:28:10.649Z,1538623690.649 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T03:28:10.651Z,1538623690.651 [Default:CheckIn:C.Wait] Stopped 2018-10-04T03:28:10.651Z,1538623690.651 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T03:28:10.652Z,1538623690.652 [Default:CheckIn:D] Running Loop=1 2018-10-04T03:28:11.023Z,1538623691.023 [Default:CheckIn:D] Stopped 2018-10-04T03:28:11.023Z,1538623691.023 [Default:CheckIn:E] Running Loop=1 2018-10-04T03:28:11.411Z,1538623691.411 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 250.360938 min 2018-10-04T03:28:11.411Z,1538623691.411 [Default:CheckIn:E] Stopped 2018-10-04T03:28:11.412Z,1538623691.412 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T03:28:11.412Z,1538623691.412 [Default:CheckIn] Stopped 2018-10-04T03:28:11.412Z,1538623691.412 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T03:28:11.412Z,1538623691.412 [Default:CheckIn](INFO): Running loop #17 2018-10-04T03:28:11.412Z,1538623691.412 [Default:CheckIn] Running Loop=17 2018-10-04T03:28:11.412Z,1538623691.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T03:28:11.412Z,1538623691.412 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T03:28:12.170Z,1538623692.170 [NAL9602](INFO): Powering up 2018-10-04T03:28:23.370Z,1538623703.370 [NAL9602](INFO): NAL9602 initialized 2018-10-04T03:28:24.178Z,1538623704.178 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-10-04T03:28:24.179Z,1538623704.179 [NAL9602] Data Fault, FailCount= 5 2018-10-04T03:28:24.179Z,1538623704.179 [NAL9602](ERROR): Data Fault 2018-10-04T03:28:24.270Z,1538623704.270 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T03:28:24.270Z,1538623704.270 [CBIT](CRITICAL): Data Fault in component: NAL9602 2018-10-04T03:28:24.578Z,1538623704.578 [NAL9602](INFO): Powering down 2018-10-04T03:28:24.645Z,1538623704.645 [CBIT](INFO): Critical error at 20181004T032824 2018-10-04T03:30:24.210Z,1538623824.210 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T03:30:24.210Z,1538623824.210 [NAL9602] No Fault, FailCount= 5 2018-10-04T03:30:24.514Z,1538623824.514 [NAL9602](INFO): Powering up NAL9602 2018-10-04T03:30:35.314Z,1538623835.314 [NAL9602](INFO): NAL9602 initialized 2018-10-04T03:33:13.713Z,1538623993.713 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T03:28:11.4Z 2018-10-04T03:33:13.713Z,1538623993.713 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T03:33:13.713Z,1538623993.713 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T03:33:18.800Z,1538623998.800 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20181003T231749/Courier0048.lzma 2018-10-04T03:33:19.570Z,1538623999.570 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0048.lzma.bak 2018-10-04T03:33:19.570Z,1538623999.570 [DataOverHttps](INFO): SBD MOMSN=8610486 2018-10-04T03:33:25.110Z,1538624005.110 [RDI_Pathfinder](ERROR): Failed to parse: :BI,-32768,-32768,-32768,-32768,V 2018-10-04T03:33:29.074Z,1538624009.074 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20181003T231749/Express0049.lzma 2018-10-04T03:33:29.826Z,1538624009.826 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0049.lzma.bak 2018-10-04T03:33:29.826Z,1538624009.826 [DataOverHttps](INFO): SBD MOMSN=8610489 2018-10-04T03:33:30.800Z,1538624010.800 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T03:33:30.800Z,1538624010.800 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T03:33:30.800Z,1538624010.800 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T03:34:09.644Z,1538624049.644 [RDI_Pathfinder](ERROR): Failed to parse: :HM,G,D,0e8c,0ffd, 15.880, 0.005, 65.535 2018-10-04T03:35:33.391Z,1538624133.391 [CBIT](IMPORTANT): Beginning ground fault scan 2018-10-04T03:35:38.494Z,1538624138.494 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T03:35:44.167Z,1538624144.167 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002527 CHAN A1 (24V): 0.003551 CHAN A2 (12V): -0.007279 CHAN A3 (5V): -0.002284 CHAN B0 (3.3V): 0.000207 CHAN B1 (3.15aV): -0.000032 CHAN B2 (3.15bV): 0.000273 CHAN B3 (GND): 0.001969 OPEN: 0.005459 Full Scale Calc: 4.765 mA, -1.589 mA 2018-10-04T03:36:09.154Z,1538624169.154 [NAL9602](INFO): Powering down 2018-10-04T03:38:31.117Z,1538624311.117 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T03:38:31.118Z,1538624311.118 [Default:CheckIn:C.Wait] Stopped 2018-10-04T03:38:31.118Z,1538624311.118 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T03:38:31.118Z,1538624311.118 [Default:CheckIn:D] Running Loop=1 2018-10-04T03:38:31.517Z,1538624311.517 [Default:CheckIn:D] Stopped 2018-10-04T03:38:31.517Z,1538624311.517 [Default:CheckIn:E] Running Loop=1 2018-10-04T03:38:31.927Z,1538624311.927 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 260.702523 min 2018-10-04T03:38:31.927Z,1538624311.927 [Default:CheckIn:E] Stopped 2018-10-04T03:38:31.928Z,1538624311.928 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T03:38:31.928Z,1538624311.928 [Default:CheckIn] Stopped 2018-10-04T03:38:31.928Z,1538624311.928 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T03:38:31.928Z,1538624311.928 [Default:CheckIn](INFO): Running loop #18 2018-10-04T03:38:31.928Z,1538624311.928 [Default:CheckIn] Running Loop=18 2018-10-04T03:38:31.928Z,1538624311.928 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T03:38:31.928Z,1538624311.928 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T03:38:32.688Z,1538624312.688 [NAL9602](INFO): Powering up 2018-10-04T03:38:43.866Z,1538624323.866 [NAL9602](INFO): NAL9602 initialized 2018-10-04T03:39:48.363Z,1538624388.363 [BPC1](ERROR): BPC1B: No match for stick with serial number:1717 in the onboard configuration file. 2018-10-04T03:40:01.509Z,1538624401.509 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2018-10-04T03:40:33.064Z,1538624433.064 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2018-10-04T03:40:33.064Z,1538624433.064 [RDI_Pathfinder](ERROR): Failed to parse: :TS05.0, -0.1, 0.0,1448.9, 0 2018-10-04T03:41:11.992Z,1538624471.992 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2018-10-04T03:41:11.993Z,1538624471.993 [RDI_Pathfinder](ERROR): Failed to parse: :TS,18100320471358,35.0, 0.0,1448.9, 0 2018-10-04T03:43:32.861Z,1538624612.861 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T03:38:31.9Z 2018-10-04T03:43:32.862Z,1538624612.862 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T03:43:32.862Z,1538624612.862 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T03:43:37.953Z,1538624617.953 [DataOverHttps](INFO): Sending 46 bytes from file Logs/20181003T231749/Courier0051.lzma 2018-10-04T03:43:38.731Z,1538624618.731 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0051.lzma.bak 2018-10-04T03:43:38.731Z,1538624618.731 [DataOverHttps](INFO): SBD MOMSN=8610494 2018-10-04T03:43:48.540Z,1538624628.540 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T03:43:48.730Z,1538624628.730 [DataOverHttps](INFO): Sending 406 bytes from file Logs/20181003T231749/Express0052.lzma 2018-10-04T03:43:49.490Z,1538624629.490 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0052.lzma.bak 2018-10-04T03:43:49.490Z,1538624629.490 [DataOverHttps](INFO): SBD MOMSN=8610496 2018-10-04T03:43:50.523Z,1538624630.523 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T03:43:50.523Z,1538624630.523 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T03:43:50.523Z,1538624630.523 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T03:44:18.754Z,1538624658.754 [NAL9602](INFO): Powering down 2018-10-04T03:48:50.908Z,1538624930.908 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T03:48:50.908Z,1538624930.908 [Default:CheckIn:C.Wait] Stopped 2018-10-04T03:48:50.908Z,1538624930.908 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T03:48:50.908Z,1538624930.908 [Default:CheckIn:D] Running Loop=1 2018-10-04T03:48:51.283Z,1538624931.283 [Default:CheckIn:D] Stopped 2018-10-04T03:48:51.283Z,1538624931.283 [Default:CheckIn:E] Running Loop=1 2018-10-04T03:48:51.714Z,1538624931.714 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 271.031950 min 2018-10-04T03:48:51.714Z,1538624931.714 [Default:CheckIn:E] Stopped 2018-10-04T03:48:51.714Z,1538624931.714 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T03:48:51.714Z,1538624931.714 [Default:CheckIn] Stopped 2018-10-04T03:48:51.714Z,1538624931.714 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T03:48:51.715Z,1538624931.715 [Default:CheckIn](INFO): Running loop #19 2018-10-04T03:48:51.715Z,1538624931.715 [Default:CheckIn] Running Loop=19 2018-10-04T03:48:51.715Z,1538624931.715 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T03:48:51.715Z,1538624931.715 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T03:48:52.446Z,1538624932.446 [NAL9602](INFO): Powering up 2018-10-04T03:49:03.246Z,1538624943.246 [NAL9602](INFO): NAL9602 initialized 2018-10-04T03:49:04.048Z,1538624944.048 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-10-04T03:49:04.048Z,1538624944.048 [NAL9602] Data Fault, FailCount= 1 2018-10-04T03:49:04.048Z,1538624944.048 [NAL9602](ERROR): Data Fault 2018-10-04T03:49:04.156Z,1538624944.156 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T03:49:04.450Z,1538624944.450 [NAL9602](INFO): Powering down 2018-10-04T03:49:05.331Z,1538624945.331 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T03:49:05.331Z,1538624945.331 [NAL9602] No Fault, FailCount= 1 2018-10-04T03:49:34.631Z,1538624974.631 [NAL9602](INFO): Powering up NAL9602 2018-10-04T03:49:47.214Z,1538624987.214 [NAL9602](INFO): NAL9602 initialized 2018-10-04T03:53:53.415Z,1538625233.415 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T03:48:51.7Z 2018-10-04T03:53:53.415Z,1538625233.415 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T03:53:53.415Z,1538625233.415 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T03:53:58.412Z,1538625238.412 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181003T231749/Courier0054.lzma 2018-10-04T03:53:59.170Z,1538625239.170 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0054.lzma.bak 2018-10-04T03:53:59.170Z,1538625239.170 [DataOverHttps](INFO): SBD MOMSN=8610502 2018-10-04T03:54:08.535Z,1538625248.535 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20181003T231749/Express0055.lzma 2018-10-04T03:54:09.294Z,1538625249.294 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Express0055.lzma.bak 2018-10-04T03:54:09.294Z,1538625249.294 [DataOverHttps](INFO): SBD MOMSN=8610504 2018-10-04T03:54:10.245Z,1538625250.245 [Default:CheckIn:Read_Iridium] Stopped 2018-10-04T03:54:10.245Z,1538625250.245 [Default:CheckIn:C.Wait] Running Loop=1 2018-10-04T03:54:10.245Z,1538625250.245 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-10-04T03:54:50.535Z,1538625290.535 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T03:55:20.936Z,1538625320.936 [NAL9602](INFO): Powering down 2018-10-04T03:59:10.555Z,1538625550.555 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-10-04T03:59:10.556Z,1538625550.556 [Default:CheckIn:C.Wait] Stopped 2018-10-04T03:59:10.556Z,1538625550.556 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-10-04T03:59:10.556Z,1538625550.556 [Default:CheckIn:D] Running Loop=1 2018-10-04T03:59:10.963Z,1538625550.963 [Default:CheckIn:D] Stopped 2018-10-04T03:59:10.963Z,1538625550.963 [Default:CheckIn:E] Running Loop=1 2018-10-04T03:59:11.358Z,1538625551.358 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 281.359961 min 2018-10-04T03:59:11.358Z,1538625551.358 [Default:CheckIn:E] Stopped 2018-10-04T03:59:11.358Z,1538625551.358 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-10-04T03:59:11.358Z,1538625551.358 [Default:CheckIn] Stopped 2018-10-04T03:59:11.359Z,1538625551.359 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-10-04T03:59:11.359Z,1538625551.359 [Default:CheckIn](INFO): Running loop #20 2018-10-04T03:59:11.359Z,1538625551.359 [Default:CheckIn] Running Loop=20 2018-10-04T03:59:11.359Z,1538625551.359 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-10-04T03:59:11.359Z,1538625551.359 [Default:CheckIn:Read_GPS] Running Loop=1 2018-10-04T03:59:12.110Z,1538625552.110 [NAL9602](INFO): Powering up 2018-10-04T03:59:22.899Z,1538625562.899 [NAL9602](INFO): NAL9602 initialized 2018-10-04T03:59:54.789Z,1538625594.789 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2018-10-04T03:59:54.874Z,1538625594.874 [NAL9602](FAULT): received: +CSQ:0 OK87, 2, 0, 0, 0 OK 2018-10-04T03:59:54.874Z,1538625594.874 [NAL9602] Data Fault, FailCount= 2 2018-10-04T03:59:54.874Z,1538625594.874 [NAL9602](ERROR): Data Fault 2018-10-04T03:59:54.978Z,1538625594.978 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T03:59:55.195Z,1538625595.195 [NAL9602](INFO): Powering down 2018-10-04T03:59:56.086Z,1538625596.086 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T03:59:56.086Z,1538625596.086 [NAL9602] No Fault, FailCount= 2 2018-10-04T04:00:25.302Z,1538625625.302 [NAL9602](INFO): Powering up NAL9602 2018-10-04T04:00:36.506Z,1538625636.506 [NAL9602](INFO): NAL9602 initialized 2018-10-04T04:00:37.302Z,1538625637.302 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-10-04T04:00:37.303Z,1538625637.303 [NAL9602] Data Fault, FailCount= 3 2018-10-04T04:00:37.303Z,1538625637.303 [NAL9602](ERROR): Data Fault 2018-10-04T04:00:37.380Z,1538625637.380 [CBIT](ERROR): Data Fault in component: NAL9602 2018-10-04T04:00:37.702Z,1538625637.702 [NAL9602](INFO): Powering down 2018-10-04T04:00:38.564Z,1538625638.564 [CBIT](INFO): Clearing failed state for component NAL9602 2018-10-04T04:00:38.564Z,1538625638.564 [NAL9602] No Fault, FailCount= 3 2018-10-04T04:01:07.950Z,1538625667.950 [NAL9602](INFO): Powering up NAL9602 2018-10-04T04:01:19.006Z,1538625679.006 [NAL9602](INFO): NAL9602 initialized 2018-10-04T04:03:27.369Z,1538625807.369 [NAL9602](INFO): SBD MO Status=2, MOMSN=1987, MT Status=2, MTMSN=0 2018-10-04T04:03:27.369Z,1538625807.369 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-10-04T04:03:50.100Z,1538625830.100 [NAL9602](INFO): SBD MO Status=2, MOMSN=1987, MT Status=2, MTMSN=0 2018-10-04T04:03:50.100Z,1538625830.100 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-10-04T04:04:13.077Z,1538625853.077 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-04T03:59:11.4Z 2018-10-04T04:04:13.077Z,1538625853.077 [Default:CheckIn:Read_GPS] Stopped 2018-10-04T04:04:13.078Z,1538625853.078 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-10-04T04:04:17.908Z,1538625857.908 [NAL9602](INFO): SBD MO Status=2, MOMSN=1987, MT Status=2, MTMSN=0 2018-10-04T04:04:17.909Z,1538625857.909 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2018-10-04T04:04:18.090Z,1538625858.090 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181003T231749/Courier0057.lzma 2018-10-04T04:04:18.850Z,1538625858.850 [DataOverHttps](INFO): Moved sent file to Logs/20181003T231749/Courier0057.lzma.bak 2018-10-04T04:04:18.850Z,1538625858.850 [DataOverHttps](INFO): SBD MOMSN=8610509 2018-10-04T04:04:28.087Z,1538625868.087 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2018-10-04T04:04:28.259Z,1538625868.259 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20181