2019-06-10T17:12:50.929Z,1560186770.929 [Default:E.Execute](INFO): Executing command restart logs 2019-06-10T17:12:50.931Z,1560186770.931 [Default:E.Execute] Stopped 2019-06-10T17:12:50.931Z,1560186770.931 [Default](INFO): Completed Default 2019-06-10T17:12:50.931Z,1560186770.931 [Default] Stopped 2019-06-10T17:12:50.931Z,1560186770.931 [Default](DEBUG): Aggregate::uninitialize Default 2019-06-10T17:12:50.932Z,1560186770.932 [Default:B.GoToSurface] Stopped 2019-06-10T17:12:50.932Z,1560186770.932 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-06-10T17:12:50.959Z,1560186770.959 [CommandLine](IMPORTANT): got command restart logs 2019-06-10T17:12:51.365Z,1560186771.365 [MissionManager](IMPORTANT): Started mission Default 2019-06-10T17:12:51.366Z,1560186771.366 [Default] Running Loop=1 2019-06-10T17:12:51.366Z,1560186771.366 [Default](DEBUG): Aggregate::initialize Default 2019-06-10T17:12:51.366Z,1560186771.366 [Default:B.GoToSurface] Running Loop=1 2019-06-10T17:12:51.366Z,1560186771.366 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-06-10T17:12:51.366Z,1560186771.366 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-06-10T17:12:51.374Z,1560186771.374 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-06-10T17:12:51.375Z,1560186771.375 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-06-10T17:12:51.375Z,1560186771.375 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-06-10T17:12:51.375Z,1560186771.375 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-06-10T17:12:51.376Z,1560186771.376 [Default:A.Wait] Running Loop=1 2019-06-10T17:12:51.376Z,1560186771.376 [Default:A.Wait](DEBUG): Initialize Wait Component. 2019-06-10T17:13:04.684Z,1560186784.684 [Default:A.Wait](INFO): Done Waiting. 2019-06-10T17:13:04.684Z,1560186784.684 [Default:A.Wait] Stopped 2019-06-10T17:13:04.684Z,1560186784.684 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2019-06-10T17:13:05.064Z,1560186785.064 [Default:CheckIn] Running Loop=1 2019-06-10T17:13:05.064Z,1560186785.064 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-06-10T17:13:05.064Z,1560186785.064 [Default:CheckIn:Read_GPS] Running Loop=1 2019-06-10T17:13:05.843Z,1560186785.843 [NAL9602](INFO): Powering up 2019-06-10T17:13:16.751Z,1560186796.751 [NAL9602](INFO): NAL9602 initialized 2019-06-10T17:13:17.560Z,1560186797.560 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-06-10T17:13:17.560Z,1560186797.560 [NAL9602] Data Fault, FailCount= 1 2019-06-10T17:13:17.560Z,1560186797.560 [NAL9602](ERROR): Data Fault 2019-06-10T17:13:17.613Z,1560186797.613 [CBIT](ERROR): Data Fault in component: NAL9602 2019-06-10T17:13:17.963Z,1560186797.963 [NAL9602](INFO): Powering down 2019-06-10T17:13:18.801Z,1560186798.801 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-10T17:13:18.801Z,1560186798.801 [NAL9602] No Fault, FailCount= 1 2019-06-10T17:13:48.279Z,1560186828.279 [NAL9602](INFO): Powering up NAL9602 2019-06-10T17:13:59.172Z,1560186839.172 [NAL9602](INFO): NAL9602 initialized 2019-06-10T17:13:59.998Z,1560186839.998 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:15:55.529Z,1560186955.529 [NAL9602](INFO): SBD MO Status=2, MOMSN=494, MT Status=2, MTMSN=0 2019-06-10T17:15:55.529Z,1560186955.529 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T17:18:05.267Z,1560187085.267 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-10T17:13:05.1Z 2019-06-10T17:18:05.267Z,1560187085.267 [Default:CheckIn:Read_GPS] Stopped 2019-06-10T17:18:05.267Z,1560187085.267 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-06-10T17:18:11.966Z,1560187091.966 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190608T191011/Courier0864.lzma 2019-06-10T17:18:12.772Z,1560187092.772 [DataOverHttps](INFO): Moved sent file to Logs/20190608T191011/Courier0864.lzma.bak 2019-06-10T17:18:12.772Z,1560187092.772 [DataOverHttps](INFO): SBD MOMSN=11361711 2019-06-10T17:18:25.266Z,1560187105.266 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190610T171250/Courier0000.lzma 2019-06-10T17:18:26.072Z,1560187106.072 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Courier0000.lzma.bak 2019-06-10T17:18:26.072Z,1560187106.072 [DataOverHttps](INFO): SBD MOMSN=11361713 2019-06-10T17:18:41.256Z,1560187121.256 [DataOverHttps](INFO): Sending 248 bytes from file Logs/20190608T191011/Express0865.lzma 2019-06-10T17:18:42.068Z,1560187122.068 [DataOverHttps](INFO): Moved sent file to Logs/20190608T191011/Express0865.lzma.bak 2019-06-10T17:18:42.068Z,1560187122.068 [DataOverHttps](INFO): SBD MOMSN=11361716 2019-06-10T17:18:55.967Z,1560187135.967 [DataOverHttps](INFO): Sending 349 bytes from file Logs/20190610T171250/Express0001.lzma 2019-06-10T17:18:56.822Z,1560187136.822 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Express0001.lzma.bak 2019-06-10T17:18:56.822Z,1560187136.822 [DataOverHttps](INFO): SBD MOMSN=11361721 2019-06-10T17:19:00.380Z,1560187140.380 [Default:CheckIn:Read_Iridium] Stopped 2019-06-10T17:19:00.380Z,1560187140.380 [Default:CheckIn:C.Wait] Running Loop=1 2019-06-10T17:19:00.380Z,1560187140.380 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-06-10T17:19:02.324Z,1560187142.324 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-06-10T17:19:33.032Z,1560187173.032 [NAL9602](INFO): Powering down 2019-06-10T17:24:00.912Z,1560187440.912 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-06-10T17:24:00.913Z,1560187440.913 [Default:CheckIn:C.Wait] Stopped 2019-06-10T17:24:00.913Z,1560187440.913 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-06-10T17:24:00.913Z,1560187440.913 [Default:CheckIn:D] Running Loop=1 2019-06-10T17:24:01.327Z,1560187441.327 [Default:CheckIn:D] Stopped 2019-06-10T17:24:01.327Z,1560187441.327 [Default:CheckIn:E] Running Loop=1 2019-06-10T17:24:01.717Z,1560187441.717 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.166018 min 2019-06-10T17:24:01.717Z,1560187441.717 [Default:CheckIn:E] Stopped 2019-06-10T17:24:01.717Z,1560187441.717 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-06-10T17:24:01.717Z,1560187441.717 [Default:CheckIn] Stopped 2019-06-10T17:24:01.717Z,1560187441.717 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-06-10T17:24:01.717Z,1560187441.717 [Default:CheckIn](INFO): Running loop #2 2019-06-10T17:24:01.718Z,1560187441.718 [Default:CheckIn] Running Loop=2 2019-06-10T17:24:01.718Z,1560187441.718 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-06-10T17:24:01.718Z,1560187441.718 [Default:CheckIn:Read_GPS] Running Loop=1 2019-06-10T17:24:02.493Z,1560187442.493 [NAL9602](INFO): Powering up 2019-06-10T17:24:13.411Z,1560187453.411 [NAL9602](INFO): NAL9602 initialized 2019-06-10T17:24:14.211Z,1560187454.211 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-06-10T17:24:14.212Z,1560187454.212 [NAL9602] Data Fault, FailCount= 2 2019-06-10T17:24:14.212Z,1560187454.212 [NAL9602](ERROR): Data Fault 2019-06-10T17:24:14.271Z,1560187454.271 [CBIT](ERROR): Data Fault in component: NAL9602 2019-06-10T17:24:14.619Z,1560187454.619 [NAL9602](INFO): Powering down 2019-06-10T17:24:15.460Z,1560187455.460 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-10T17:24:15.460Z,1560187455.460 [NAL9602] No Fault, FailCount= 2 2019-06-10T17:24:44.913Z,1560187484.913 [NAL9602](INFO): Powering up NAL9602 2019-06-10T17:24:55.821Z,1560187495.821 [NAL9602](INFO): NAL9602 initialized 2019-06-10T17:24:56.642Z,1560187496.642 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:29:01.919Z,1560187741.919 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-10T17:24:01.7Z 2019-06-10T17:29:01.919Z,1560187741.919 [Default:CheckIn:Read_GPS] Stopped 2019-06-10T17:29:01.919Z,1560187741.919 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-06-10T17:29:08.806Z,1560187748.806 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190610T171250/Courier0003.lzma 2019-06-10T17:29:09.612Z,1560187749.612 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Courier0003.lzma.bak 2019-06-10T17:29:09.612Z,1560187749.612 [DataOverHttps](INFO): SBD MOMSN=11361734 2019-06-10T17:29:24.726Z,1560187764.726 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20190610T171250/Express0004.lzma 2019-06-10T17:29:25.532Z,1560187765.532 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Express0004.lzma.bak 2019-06-10T17:29:25.532Z,1560187765.532 [DataOverHttps](INFO): SBD MOMSN=11361736 2019-06-10T17:29:28.976Z,1560187768.976 [Default:CheckIn:Read_Iridium] Stopped 2019-06-10T17:29:28.976Z,1560187768.976 [Default:CheckIn:C.Wait] Running Loop=1 2019-06-10T17:29:28.976Z,1560187768.976 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-06-10T17:29:59.233Z,1560187799.233 [NAL9602](INFO): SBD MO Status=0, MOMSN=494, MT Status=0, MTMSN=0 2019-06-10T17:29:59.233Z,1560187799.233 [NAL9602](INFO): No messages in MT queue 2019-06-10T17:30:29.935Z,1560187829.935 [NAL9602](INFO): Powering down 2019-06-10T17:34:29.539Z,1560188069.539 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-06-10T17:34:29.539Z,1560188069.539 [Default:CheckIn:C.Wait] Stopped 2019-06-10T17:34:29.539Z,1560188069.539 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-06-10T17:34:29.539Z,1560188069.539 [Default:CheckIn:D] Running Loop=1 2019-06-10T17:34:29.934Z,1560188069.934 [Default:CheckIn:D] Stopped 2019-06-10T17:34:29.934Z,1560188069.934 [Default:CheckIn:E] Running Loop=1 2019-06-10T17:34:30.333Z,1560188070.333 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.642810 min 2019-06-10T17:34:30.333Z,1560188070.333 [Default:CheckIn:E] Stopped 2019-06-10T17:34:30.333Z,1560188070.333 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-06-10T17:34:30.333Z,1560188070.333 [Default:CheckIn] Stopped 2019-06-10T17:34:30.333Z,1560188070.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-06-10T17:34:30.333Z,1560188070.333 [Default:CheckIn](INFO): Running loop #3 2019-06-10T17:34:30.333Z,1560188070.333 [Default:CheckIn] Running Loop=3 2019-06-10T17:34:30.333Z,1560188070.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-06-10T17:34:30.333Z,1560188070.333 [Default:CheckIn:Read_GPS] Running Loop=1 2019-06-10T17:34:31.123Z,1560188071.123 [NAL9602](INFO): Powering up 2019-06-10T17:34:42.036Z,1560188082.036 [NAL9602](INFO): NAL9602 initialized 2019-06-10T17:34:42.846Z,1560188082.846 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:36:48.885Z,1560188208.885 [NAL9602](INFO): SBD MO Status=2, MOMSN=495, MT Status=2, MTMSN=0 2019-06-10T17:36:48.885Z,1560188208.885 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T17:37:06.259Z,1560188226.259 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-06-10T17:37:06.259Z,1560188226.259 [DropWeight] Hardware Fault, FailCount= 1 2019-06-10T17:37:06.259Z,1560188226.259 [DropWeight](ERROR): Hardware Fault 2019-06-10T17:37:06.290Z,1560188226.290 [CBIT](INFO): Critical error at 20190610T173706 2019-06-10T17:37:06.293Z,1560188226.293 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-06-10T17:37:06.293Z,1560188226.293 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-06-10T17:37:06.697Z,1560188226.697 [CBIT](INFO): Critical error at 20190610T173706 2019-06-10T17:38:42.813Z,1560188322.813 [NAL9602](INFO): SBD MO Status=2, MOMSN=495, MT Status=2, MTMSN=0 2019-06-10T17:38:42.813Z,1560188322.813 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T17:39:00.185Z,1560188340.185 [NAL9602](INFO): SBD MO Status=2, MOMSN=495, MT Status=2, MTMSN=0 2019-06-10T17:39:00.185Z,1560188340.185 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T17:39:30.511Z,1560188370.511 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-10T17:34:30.3Z 2019-06-10T17:39:30.511Z,1560188370.511 [Default:CheckIn:Read_GPS] Stopped 2019-06-10T17:39:30.511Z,1560188370.511 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-06-10T17:39:37.298Z,1560188377.298 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20190610T171250/Courier0006.lzma 2019-06-10T17:39:38.104Z,1560188378.104 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Courier0006.lzma.bak 2019-06-10T17:39:38.104Z,1560188378.104 [DataOverHttps](INFO): SBD MOMSN=11361746 2019-06-10T17:39:38.565Z,1560188378.565 [NAL9602](INFO): SBD MO Status=2, MOMSN=495, MT Status=2, MTMSN=0 2019-06-10T17:39:38.565Z,1560188378.565 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T17:39:45.027Z,1560188385.027 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-06-10T17:39:52.510Z,1560188392.510 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20190610T171250/Express0007.lzma 2019-06-10T17:39:53.316Z,1560188393.316 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Express0007.lzma.bak 2019-06-10T17:39:53.316Z,1560188393.316 [DataOverHttps](INFO): SBD MOMSN=11361750 2019-06-10T17:39:56.815Z,1560188396.815 [Default:CheckIn:Read_Iridium] Stopped 2019-06-10T17:39:56.815Z,1560188396.815 [Default:CheckIn:C.Wait] Running Loop=1 2019-06-10T17:39:56.815Z,1560188396.815 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-06-10T17:40:15.735Z,1560188415.735 [NAL9602](INFO): Powering down 2019-06-10T17:44:57.363Z,1560188697.363 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-06-10T17:44:57.363Z,1560188697.363 [Default:CheckIn:C.Wait] Stopped 2019-06-10T17:44:57.363Z,1560188697.363 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-06-10T17:44:57.363Z,1560188697.363 [Default:CheckIn:D] Running Loop=1 2019-06-10T17:44:57.778Z,1560188697.778 [Default:CheckIn:D] Stopped 2019-06-10T17:44:57.778Z,1560188697.778 [Default:CheckIn:E] Running Loop=1 2019-06-10T17:44:58.184Z,1560188698.184 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.106875 min 2019-06-10T17:44:58.184Z,1560188698.184 [Default:CheckIn:E] Stopped 2019-06-10T17:44:58.184Z,1560188698.184 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-06-10T17:44:58.184Z,1560188698.184 [Default:CheckIn] Stopped 2019-06-10T17:44:58.184Z,1560188698.184 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-06-10T17:44:58.184Z,1560188698.184 [Default:CheckIn](INFO): Running loop #4 2019-06-10T17:44:58.185Z,1560188698.185 [Default:CheckIn] Running Loop=4 2019-06-10T17:44:58.185Z,1560188698.185 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-06-10T17:44:58.185Z,1560188698.185 [Default:CheckIn:Read_GPS] Running Loop=1 2019-06-10T17:44:58.955Z,1560188698.955 [NAL9602](INFO): Powering up 2019-06-10T17:45:09.867Z,1560188709.867 [NAL9602](INFO): NAL9602 initialized 2019-06-10T17:45:10.671Z,1560188710.671 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-06-10T17:45:10.671Z,1560188710.671 [NAL9602] Data Fault, FailCount= 1 2019-06-10T17:45:10.671Z,1560188710.671 [NAL9602](ERROR): Data Fault 2019-06-10T17:45:10.712Z,1560188710.712 [CBIT](ERROR): Data Fault in component: NAL9602 2019-06-10T17:45:11.079Z,1560188711.079 [NAL9602](INFO): Powering down 2019-06-10T17:45:11.928Z,1560188711.928 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-10T17:45:11.928Z,1560188711.928 [NAL9602] No Fault, FailCount= 1 2019-06-10T17:45:33.731Z,1560188733.731 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2019-06-10T17:45:33.733Z,1560188733.733 [BPC1](INFO): Received data from all battery sticks. 2019-06-10T17:45:41.379Z,1560188741.379 [NAL9602](INFO): Powering up NAL9602 2019-06-10T17:45:52.287Z,1560188752.287 [NAL9602](INFO): NAL9602 initialized 2019-06-10T17:45:53.106Z,1560188753.106 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:47:15.916Z,1560188835.916 [NAL9602](INFO): SBD MO Status=2, MOMSN=495, MT Status=2, MTMSN=0 2019-06-10T17:47:15.917Z,1560188835.917 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T17:47:41.766Z,1560188861.766 [NAL9602](INFO): SBD MO Status=2, MOMSN=495, MT Status=2, MTMSN=0 2019-06-10T17:47:41.766Z,1560188861.766 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T17:48:26.616Z,1560188906.616 [NAL9602](INFO): SBD MO Status=2, MOMSN=495, MT Status=2, MTMSN=0 2019-06-10T17:48:26.617Z,1560188906.617 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T17:48:48.429Z,1560188928.429 [NAL9602](INFO): SBD MO Status=2, MOMSN=495, MT Status=2, MTMSN=0 2019-06-10T17:48:48.429Z,1560188928.429 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T17:49:11.056Z,1560188951.056 [NAL9602](INFO): SBD MO Status=2, MOMSN=495, MT Status=2, MTMSN=0 2019-06-10T17:49:11.057Z,1560188951.057 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T17:49:50.238Z,1560188990.238 [NAL9602](INFO): SBD MO Status=2, MOMSN=495, MT Status=2, MTMSN=0 2019-06-10T17:49:50.238Z,1560188990.238 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T17:49:58.363Z,1560188998.363 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-10T17:44:58.2Z 2019-06-10T17:49:58.363Z,1560188998.363 [Default:CheckIn:Read_GPS] Stopped 2019-06-10T17:49:58.363Z,1560188998.363 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-06-10T17:50:05.306Z,1560189005.306 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20190610T171250/Courier0009.lzma 2019-06-10T17:50:06.112Z,1560189006.112 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Courier0009.lzma.bak 2019-06-10T17:50:06.112Z,1560189006.112 [DataOverHttps](INFO): SBD MOMSN=11361760 2019-06-10T17:50:20.178Z,1560189020.178 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20190610T171250/Express0010.lzma 2019-06-10T17:50:20.984Z,1560189020.984 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Express0010.lzma.bak 2019-06-10T17:50:20.984Z,1560189020.984 [DataOverHttps](INFO): SBD MOMSN=11361762 2019-06-10T17:50:24.637Z,1560189024.637 [Default:CheckIn:Read_Iridium] Stopped 2019-06-10T17:50:24.637Z,1560189024.637 [Default:CheckIn:C.Wait] Running Loop=1 2019-06-10T17:50:24.637Z,1560189024.637 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-06-10T17:50:55.284Z,1560189055.284 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-06-10T17:51:25.984Z,1560189085.984 [NAL9602](INFO): Powering down 2019-06-10T17:52:06.422Z,1560189126.422 [CBIT](INFO): Clearing failed state for component DropWeight 2019-06-10T17:52:06.422Z,1560189126.422 [DropWeight] No Fault, FailCount= 1 2019-06-10T17:55:25.182Z,1560189325.182 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-06-10T17:55:25.182Z,1560189325.182 [Default:CheckIn:C.Wait] Stopped 2019-06-10T17:55:25.182Z,1560189325.182 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-06-10T17:55:25.183Z,1560189325.183 [Default:CheckIn:D] Running Loop=1 2019-06-10T17:55:25.597Z,1560189325.597 [Default:CheckIn:D] Stopped 2019-06-10T17:55:25.598Z,1560189325.598 [Default:CheckIn:E] Running Loop=1 2019-06-10T17:55:25.995Z,1560189325.995 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.570528 min 2019-06-10T17:55:25.995Z,1560189325.995 [Default:CheckIn:E] Stopped 2019-06-10T17:55:25.995Z,1560189325.995 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-06-10T17:55:25.995Z,1560189325.995 [Default:CheckIn] Stopped 2019-06-10T17:55:25.995Z,1560189325.995 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-06-10T17:55:25.995Z,1560189325.995 [Default:CheckIn](INFO): Running loop #5 2019-06-10T17:55:25.996Z,1560189325.996 [Default:CheckIn] Running Loop=5 2019-06-10T17:55:25.996Z,1560189325.996 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-06-10T17:55:25.996Z,1560189325.996 [Default:CheckIn:Read_GPS] Running Loop=1 2019-06-10T17:55:26.779Z,1560189326.779 [NAL9602](INFO): Powering up 2019-06-10T17:55:37.683Z,1560189337.683 [NAL9602](INFO): NAL9602 initialized 2019-06-10T17:55:38.498Z,1560189338.498 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:56:09.596Z,1560189369.596 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2019-06-10T17:56:09.678Z,1560189369.678 [NAL9602](FAULT): received: +CSQ:0 OK5, 2, 0, 0, 0 OK 2019-06-10T17:56:09.678Z,1560189369.678 [NAL9602] Data Fault, FailCount= 2 2019-06-10T17:56:09.678Z,1560189369.678 [NAL9602](ERROR): Data Fault 2019-06-10T17:56:09.741Z,1560189369.741 [CBIT](ERROR): Data Fault in component: NAL9602 2019-06-10T17:56:10.015Z,1560189370.015 [NAL9602](INFO): Powering down 2019-06-10T17:56:10.837Z,1560189370.837 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-10T17:56:10.837Z,1560189370.837 [NAL9602] No Fault, FailCount= 2 2019-06-10T17:56:40.303Z,1560189400.303 [NAL9602](INFO): Powering up NAL9602 2019-06-10T17:56:51.208Z,1560189411.208 [NAL9602](INFO): NAL9602 initialized 2019-06-10T17:56:52.015Z,1560189412.015 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-06-10T17:56:52.015Z,1560189412.015 [NAL9602] Data Fault, FailCount= 3 2019-06-10T17:56:52.015Z,1560189412.015 [NAL9602](ERROR): Data Fault 2019-06-10T17:56:52.048Z,1560189412.048 [CBIT](ERROR): Data Fault in component: NAL9602 2019-06-10T17:56:52.420Z,1560189412.420 [NAL9602](INFO): Powering down 2019-06-10T17:56:53.265Z,1560189413.265 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-10T17:56:53.265Z,1560189413.265 [NAL9602] No Fault, FailCount= 3 2019-06-10T17:57:22.737Z,1560189442.737 [NAL9602](INFO): Powering up NAL9602 2019-06-10T17:57:33.632Z,1560189453.632 [NAL9602](INFO): NAL9602 initialized 2019-06-10T17:57:34.446Z,1560189454.446 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:12.413Z,1560189492.413 [NAL9602](INFO): SBD MO Status=0, MOMSN=495, MT Status=0, MTMSN=0 2019-06-10T17:58:12.413Z,1560189492.413 [NAL9602](INFO): No messages in MT queue 2019-06-10T17:58:13.230Z,1560189493.230 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:16.466Z,1560189496.466 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:19.310Z,1560189499.310 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:22.126Z,1560189502.126 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:24.138Z,1560189504.138 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:27.370Z,1560189507.370 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:30.198Z,1560189510.198 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:33.434Z,1560189513.434 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:36.258Z,1560189516.258 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:39.494Z,1560189519.494 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:42.318Z,1560189522.318 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:45.146Z,1560189525.146 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:47.570Z,1560189527.570 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:50.398Z,1560189530.398 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:53.634Z,1560189533.634 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:56.458Z,1560189536.458 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:58:59.286Z,1560189539.286 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:02.522Z,1560189542.522 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:05.346Z,1560189545.346 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:08.174Z,1560189548.174 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:11.410Z,1560189551.410 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:14.237Z,1560189554.237 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:17.466Z,1560189557.466 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:20.302Z,1560189560.302 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:23.526Z,1560189563.526 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:26.354Z,1560189566.354 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:29.586Z,1560189569.586 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:32.414Z,1560189572.414 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:35.646Z,1560189575.646 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:38.474Z,1560189578.474 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:41.306Z,1560189581.306 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:44.130Z,1560189584.130 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:47.362Z,1560189587.362 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:50.190Z,1560189590.190 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:53.426Z,1560189593.426 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:56.250Z,1560189596.250 [NAL9602](DEBUG): Fix Requested 2019-06-10T17:59:59.482Z,1560189599.482 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:00:02.310Z,1560189602.310 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:00:05.546Z,1560189605.546 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:00:08.370Z,1560189608.370 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:00:11.606Z,1560189611.606 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:00:14.430Z,1560189614.430 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:00:17.259Z,1560189617.259 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:00:20.494Z,1560189620.494 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:00:23.322Z,1560189623.322 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:00:26.150Z,1560189626.150 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:00:26.173Z,1560189626.173 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-10T17:55:25.0Z 2019-06-10T18:00:26.173Z,1560189626.173 [Default:CheckIn:Read_GPS] Stopped 2019-06-10T18:00:26.173Z,1560189626.173 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-06-10T18:00:33.282Z,1560189633.282 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190610T171250/Courier0012.lzma 2019-06-10T18:00:34.088Z,1560189634.088 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Courier0012.lzma.bak 2019-06-10T18:00:34.088Z,1560189634.088 [DataOverHttps](INFO): SBD MOMSN=11361777 2019-06-10T18:00:47.886Z,1560189647.886 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20190610T171250/Express0013.lzma 2019-06-10T18:00:48.692Z,1560189648.692 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Express0013.lzma.bak 2019-06-10T18:00:48.692Z,1560189648.692 [DataOverHttps](INFO): SBD MOMSN=11361779 2019-06-10T18:00:52.448Z,1560189652.448 [Default:CheckIn:Read_Iridium] Stopped 2019-06-10T18:00:52.448Z,1560189652.448 [Default:CheckIn:C.Wait] Running Loop=1 2019-06-10T18:00:52.448Z,1560189652.448 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-06-10T18:00:59.262Z,1560189659.262 [NAL9602](INFO): Powering down 2019-06-10T18:05:53.007Z,1560189953.007 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-06-10T18:05:53.007Z,1560189953.007 [Default:CheckIn:C.Wait] Stopped 2019-06-10T18:05:53.007Z,1560189953.007 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-06-10T18:05:53.008Z,1560189953.008 [Default:CheckIn:D] Running Loop=1 2019-06-10T18:05:53.402Z,1560189953.402 [Default:CheckIn:D] Stopped 2019-06-10T18:05:53.402Z,1560189953.402 [Default:CheckIn:E] Running Loop=1 2019-06-10T18:05:53.801Z,1560189953.801 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.033931 min 2019-06-10T18:05:53.801Z,1560189953.801 [Default:CheckIn:E] Stopped 2019-06-10T18:05:53.801Z,1560189953.801 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-06-10T18:05:53.801Z,1560189953.801 [Default:CheckIn] Stopped 2019-06-10T18:05:53.801Z,1560189953.801 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-06-10T18:05:53.801Z,1560189953.801 [Default:CheckIn](INFO): Running loop #6 2019-06-10T18:05:53.801Z,1560189953.801 [Default:CheckIn] Running Loop=6 2019-06-10T18:05:53.801Z,1560189953.801 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-06-10T18:05:53.802Z,1560189953.802 [Default:CheckIn:Read_GPS] Running Loop=1 2019-06-10T18:05:54.591Z,1560189954.591 [NAL9602](INFO): Powering up 2019-06-10T18:06:05.496Z,1560189965.496 [NAL9602](INFO): NAL9602 initialized 2019-06-10T18:06:06.318Z,1560189966.318 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:06:39.834Z,1560189999.834 [NAL9602](INFO): SBD MO Status=2, MOMSN=496, MT Status=2, MTMSN=0 2019-06-10T18:06:39.835Z,1560189999.835 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T18:07:13.777Z,1560190033.777 [NAL9602](INFO): SBD MO Status=2, MOMSN=496, MT Status=2, MTMSN=0 2019-06-10T18:07:13.777Z,1560190033.777 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T18:07:31.549Z,1560190051.549 [NAL9602](INFO): SBD MO Status=2, MOMSN=496, MT Status=2, MTMSN=0 2019-06-10T18:07:31.549Z,1560190051.549 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T18:07:52.561Z,1560190072.561 [NAL9602](INFO): SBD MO Status=0, MOMSN=496, MT Status=0, MTMSN=0 2019-06-10T18:07:52.561Z,1560190072.561 [NAL9602](INFO): No messages in MT queue 2019-06-10T18:07:53.367Z,1560190073.367 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-06-10T18:07:53.368Z,1560190073.368 [NAL9602] Data Fault, FailCount= 1 2019-06-10T18:07:53.368Z,1560190073.368 [NAL9602](ERROR): Data Fault 2019-06-10T18:07:53.424Z,1560190073.424 [CBIT](ERROR): Data Fault in component: NAL9602 2019-06-10T18:07:53.772Z,1560190073.772 [NAL9602](INFO): Powering down 2019-06-10T18:07:54.611Z,1560190074.611 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-10T18:07:54.611Z,1560190074.611 [NAL9602] No Fault, FailCount= 1 2019-06-10T18:08:24.071Z,1560190104.071 [NAL9602](INFO): Powering up NAL9602 2019-06-10T18:08:34.980Z,1560190114.980 [NAL9602](INFO): NAL9602 initialized 2019-06-10T18:08:35.794Z,1560190115.794 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:08:37.814Z,1560190117.814 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:08:39.834Z,1560190119.834 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:08:41.854Z,1560190121.854 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:08:45.086Z,1560190125.086 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:08:47.918Z,1560190127.918 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:08:51.146Z,1560190131.146 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:08:53.982Z,1560190133.982 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:08:56.802Z,1560190136.802 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:08:58.822Z,1560190138.822 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:00.842Z,1560190140.842 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:02.862Z,1560190142.862 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:06.099Z,1560190146.099 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:08.929Z,1560190148.929 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:12.166Z,1560190152.166 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:15.389Z,1560190155.389 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:18.218Z,1560190158.218 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:21.446Z,1560190161.446 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:24.274Z,1560190164.274 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:27.506Z,1560190167.506 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:30.338Z,1560190170.338 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:33.162Z,1560190173.162 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:35.586Z,1560190175.586 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:38.418Z,1560190178.418 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:41.646Z,1560190181.646 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:44.474Z,1560190184.474 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:47.302Z,1560190187.302 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:50.130Z,1560190190.130 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:53.362Z,1560190193.362 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:56.190Z,1560190196.190 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:09:59.426Z,1560190199.426 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:10:02.250Z,1560190202.250 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:10:05.486Z,1560190205.486 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:10:08.314Z,1560190208.314 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:10:11.547Z,1560190211.547 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:10:14.370Z,1560190214.370 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:10:17.198Z,1560190217.198 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:10:20.430Z,1560190220.430 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:10:23.262Z,1560190223.262 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:10:23.657Z,1560190223.657 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181006.00,A,3648.17772,N,12147.28647,W,0.467,0.00,100619,,,A*7B 2019-06-10T18:10:23.659Z,1560190223.659 [NAL9602](INFO): GPS fix at 20190610T181006: (36.802962, -121.788108) 2019-06-10T18:10:23.683Z,1560190223.683 [Default:CheckIn:Read_GPS] Stopped 2019-06-10T18:10:23.683Z,1560190223.683 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-06-10T18:10:30.802Z,1560190230.802 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20190610T171250/Courier0015.lzma 2019-06-10T18:10:31.608Z,1560190231.608 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Courier0015.lzma.bak 2019-06-10T18:10:31.608Z,1560190231.608 [DataOverHttps](INFO): SBD MOMSN=11361789 2019-06-10T18:10:45.242Z,1560190245.242 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20190610T171250/Express0016.lzma 2019-06-10T18:10:46.048Z,1560190246.048 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Express0016.lzma.bak 2019-06-10T18:10:46.048Z,1560190246.048 [DataOverHttps](INFO): SBD MOMSN=11361795 2019-06-10T18:10:49.567Z,1560190249.567 [Default:CheckIn:Read_Iridium] Stopped 2019-06-10T18:10:49.567Z,1560190249.567 [Default:CheckIn:C.Wait] Running Loop=1 2019-06-10T18:10:49.567Z,1560190249.567 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-06-10T18:10:56.379Z,1560190256.379 [NAL9602](INFO): Powering down 2019-06-10T18:15:50.104Z,1560190550.104 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-06-10T18:15:50.104Z,1560190550.104 [Default:CheckIn:C.Wait] Stopped 2019-06-10T18:15:50.104Z,1560190550.104 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-06-10T18:15:50.104Z,1560190550.104 [Default:CheckIn:D] Running Loop=1 2019-06-10T18:15:50.514Z,1560190550.514 [Default:CheckIn:D] Stopped 2019-06-10T18:15:50.514Z,1560190550.514 [Default:CheckIn:E] Running Loop=1 2019-06-10T18:15:50.919Z,1560190550.919 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.985807 min 2019-06-10T18:15:50.919Z,1560190550.919 [Default:CheckIn:E] Stopped 2019-06-10T18:15:50.919Z,1560190550.919 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-06-10T18:15:50.919Z,1560190550.919 [Default:CheckIn] Stopped 2019-06-10T18:15:50.919Z,1560190550.919 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-06-10T18:15:50.919Z,1560190550.919 [Default:CheckIn](INFO): Running loop #7 2019-06-10T18:15:50.919Z,1560190550.919 [Default:CheckIn] Running Loop=7 2019-06-10T18:15:50.919Z,1560190550.919 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-06-10T18:15:50.919Z,1560190550.919 [Default:CheckIn:Read_GPS] Running Loop=1 2019-06-10T18:15:51.700Z,1560190551.700 [NAL9602](INFO): Powering up 2019-06-10T18:16:02.608Z,1560190562.608 [NAL9602](INFO): NAL9602 initialized 2019-06-10T18:16:03.426Z,1560190563.426 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:17:01.197Z,1560190621.197 [NAL9602](INFO): SBD MO Status=2, MOMSN=497, MT Status=2, MTMSN=0 2019-06-10T18:17:01.197Z,1560190621.197 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-06-10T18:20:51.095Z,1560190851.095 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-10T18:15:50.9Z 2019-06-10T18:20:51.095Z,1560190851.095 [Default:CheckIn:Read_GPS] Stopped 2019-06-10T18:20:51.095Z,1560190851.095 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-06-10T18:20:57.913Z,1560190857.913 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190610T171250/Courier0018.lzma 2019-06-10T18:20:58.716Z,1560190858.716 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Courier0018.lzma.bak 2019-06-10T18:20:58.716Z,1560190858.716 [DataOverHttps](INFO): SBD MOMSN=11361806 2019-06-10T18:21:05.204Z,1560190865.204 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-06-10T18:21:12.490Z,1560190872.490 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20190610T171250/Express0019.lzma 2019-06-10T18:21:13.296Z,1560190873.296 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Express0019.lzma.bak 2019-06-10T18:21:13.296Z,1560190873.296 [DataOverHttps](INFO): SBD MOMSN=11361808 2019-06-10T18:21:16.942Z,1560190876.942 [Default:CheckIn:Read_Iridium] Stopped 2019-06-10T18:21:16.942Z,1560190876.942 [Default:CheckIn:C.Wait] Running Loop=1 2019-06-10T18:21:16.942Z,1560190876.942 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-06-10T18:21:35.907Z,1560190895.907 [NAL9602](INFO): Powering down 2019-06-10T18:22:07.823Z,1560190927.823 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-06-10T18:22:07.823Z,1560190927.823 [DropWeight] Hardware Fault, FailCount= 1 2019-06-10T18:22:07.823Z,1560190927.823 [DropWeight](ERROR): Hardware Fault 2019-06-10T18:22:07.885Z,1560190927.885 [CBIT](INFO): Critical error at 20190610T182207 2019-06-10T18:22:07.887Z,1560190927.887 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-06-10T18:22:07.887Z,1560190927.887 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-06-10T18:22:08.261Z,1560190928.261 [CBIT](INFO): Critical error at 20190610T182207 2019-06-10T18:26:17.521Z,1560191177.521 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-06-10T18:26:17.521Z,1560191177.521 [Default:CheckIn:C.Wait] Stopped 2019-06-10T18:26:17.521Z,1560191177.521 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-06-10T18:26:17.521Z,1560191177.521 [Default:CheckIn:D] Running Loop=1 2019-06-10T18:26:17.935Z,1560191177.935 [Default:CheckIn:D] Stopped 2019-06-10T18:26:17.935Z,1560191177.935 [Default:CheckIn:E] Running Loop=1 2019-06-10T18:26:18.338Z,1560191178.338 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.442814 min 2019-06-10T18:26:18.338Z,1560191178.338 [Default:CheckIn:E] Stopped 2019-06-10T18:26:18.338Z,1560191178.338 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-06-10T18:26:18.338Z,1560191178.338 [Default:CheckIn] Stopped 2019-06-10T18:26:18.338Z,1560191178.338 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-06-10T18:26:18.339Z,1560191178.339 [Default:CheckIn](INFO): Running loop #8 2019-06-10T18:26:18.339Z,1560191178.339 [Default:CheckIn] Running Loop=8 2019-06-10T18:26:18.339Z,1560191178.339 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-06-10T18:26:18.339Z,1560191178.339 [Default:CheckIn:Read_GPS] Running Loop=1 2019-06-10T18:26:19.108Z,1560191179.108 [NAL9602](INFO): Powering up 2019-06-10T18:26:30.018Z,1560191190.018 [NAL9602](INFO): NAL9602 initialized 2019-06-10T18:26:30.831Z,1560191190.831 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-06-10T18:26:30.831Z,1560191190.831 [NAL9602] Data Fault, FailCount= 2 2019-06-10T18:26:30.832Z,1560191190.832 [NAL9602](ERROR): Data Fault 2019-06-10T18:26:30.886Z,1560191190.886 [CBIT](ERROR): Data Fault in component: NAL9602 2019-06-10T18:26:31.229Z,1560191191.229 [NAL9602](INFO): Powering down 2019-06-10T18:26:32.074Z,1560191192.074 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-10T18:26:32.074Z,1560191192.074 [NAL9602] No Fault, FailCount= 2 2019-06-10T18:27:01.529Z,1560191221.529 [NAL9602](INFO): Powering up NAL9602 2019-06-10T18:27:12.443Z,1560191232.443 [NAL9602](INFO): NAL9602 initialized 2019-06-10T18:27:13.258Z,1560191233.258 [NAL9602](DEBUG): Fix Requested 2019-06-10T18:31:18.515Z,1560191478.515 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-10T18:26:18.3Z 2019-06-10T18:31:18.515Z,1560191478.515 [Default:CheckIn:Read_GPS] Stopped 2019-06-10T18:31:18.515Z,1560191478.515 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-06-10T18:31:20.708Z,1560191480.708 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:31:20.708Z,1560191480.708 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=61.700939 2019-06-10T18:31:51.007Z,1560191511.007 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:32:15.033Z,1560191535.033 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-06-10T18:32:26.171Z,1560191546.171 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:33:01.319Z,1560191581.319 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:33:36.467Z,1560191616.467 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:34:11.615Z,1560191651.615 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:34:46.763Z,1560191686.763 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:35:21.911Z,1560191721.911 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:35:57.067Z,1560191757.067 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:36:32.215Z,1560191792.215 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:37:07.363Z,1560191827.363 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:37:08.001Z,1560191828.001 [CBIT](INFO): Clearing failed state for component DropWeight 2019-06-10T18:37:08.001Z,1560191828.001 [DropWeight] No Fault, FailCount= 1 2019-06-10T18:37:42.511Z,1560191862.511 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:38:17.671Z,1560191897.671 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:38:52.819Z,1560191932.819 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:39:27.971Z,1560191967.971 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:40:03.151Z,1560192003.151 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:40:38.299Z,1560192038.299 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:41:13.447Z,1560192073.447 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:41:48.595Z,1560192108.595 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:42:23.743Z,1560192143.743 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:42:58.891Z,1560192178.891 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:43:34.039Z,1560192214.039 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:44:09.187Z,1560192249.187 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:44:44.335Z,1560192284.335 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:45:19.483Z,1560192319.483 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:45:54.651Z,1560192354.651 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:46:29.799Z,1560192389.799 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:47:04.947Z,1560192424.947 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:47:40.095Z,1560192460.095 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:48:15.243Z,1560192495.243 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:48:50.391Z,1560192530.391 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:49:25.551Z,1560192565.551 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:50:00.699Z,1560192600.699 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:50:35.847Z,1560192635.847 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:51:10.995Z,1560192670.995 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:51:46.143Z,1560192706.143 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:52:21.291Z,1560192741.291 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:52:56.439Z,1560192776.439 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:53:06.703Z,1560192786.703 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2019-06-10T18:53:06.705Z,1560192786.705 [BPC1](INFO): Received data from all battery sticks. 2019-06-10T18:53:31.587Z,1560192811.587 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:54:06.747Z,1560192846.747 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:54:41.895Z,1560192881.895 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:55:17.043Z,1560192917.043 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:55:52.191Z,1560192952.191 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:56:27.339Z,1560192987.339 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:57:02.487Z,1560193022.487 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:57:37.635Z,1560193057.635 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:58:12.811Z,1560193092.811 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:58:47.959Z,1560193127.959 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:59:23.107Z,1560193163.107 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T18:59:58.259Z,1560193198.259 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:00:33.407Z,1560193233.407 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:01:08.563Z,1560193268.563 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:01:43.743Z,1560193303.743 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:02:18.891Z,1560193338.891 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:02:54.059Z,1560193374.059 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:03:29.259Z,1560193409.259 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:04:04.407Z,1560193444.407 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:04:39.555Z,1560193479.555 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:05:14.715Z,1560193514.715 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:05:49.883Z,1560193549.883 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:06:25.047Z,1560193585.047 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:07:00.195Z,1560193620.195 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:07:09.547Z,1560193629.547 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-06-10T19:07:09.547Z,1560193629.547 [DropWeight] Hardware Fault, FailCount= 1 2019-06-10T19:07:09.547Z,1560193629.547 [DropWeight](ERROR): Hardware Fault 2019-06-10T19:07:10.896Z,1560193630.896 [CBIT](INFO): Critical error at 20190610T190709 2019-06-10T19:07:10.898Z,1560193630.898 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-06-10T19:07:10.899Z,1560193630.899 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-06-10T19:07:11.034Z,1560193631.034 [CBIT](INFO): Critical error at 20190610T190710 2019-06-10T19:07:35.359Z,1560193655.359 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:08:10.507Z,1560193690.507 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:08:45.656Z,1560193725.656 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:09:20.819Z,1560193760.819 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:09:55.967Z,1560193795.967 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:10:31.123Z,1560193831.123 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:11:06.271Z,1560193866.271 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:11:41.423Z,1560193901.423 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:12:16.571Z,1560193936.571 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:12:51.719Z,1560193971.719 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:13:26.875Z,1560194006.875 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:14:02.055Z,1560194042.055 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:14:37.203Z,1560194077.203 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:15:12.379Z,1560194112.379 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:15:47.555Z,1560194147.555 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:16:22.703Z,1560194182.703 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:16:57.883Z,1560194217.883 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:17:33.031Z,1560194253.031 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:18:08.179Z,1560194288.179 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:18:43.375Z,1560194323.375 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:19:18.535Z,1560194358.535 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:19:53.684Z,1560194393.684 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:20:28.855Z,1560194428.855 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:21:04.003Z,1560194464.003 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:21:39.151Z,1560194499.151 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:22:10.112Z,1560194530.112 [CBIT](INFO): Clearing failed state for component DropWeight 2019-06-10T19:22:10.112Z,1560194530.112 [DropWeight] No Fault, FailCount= 1 2019-06-10T19:22:14.299Z,1560194534.299 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:22:49.447Z,1560194569.447 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:23:24.595Z,1560194604.595 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:23:59.744Z,1560194639.744 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:24:34.891Z,1560194674.891 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:25:10.055Z,1560194710.055 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:25:45.203Z,1560194745.203 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:26:20.367Z,1560194780.367 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:26:55.515Z,1560194815.515 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:27:30.663Z,1560194850.663 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:28:05.811Z,1560194885.811 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:28:40.959Z,1560194920.959 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:29:16.123Z,1560194956.123 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:29:51.291Z,1560194991.291 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:30:26.439Z,1560195026.439 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:31:01.587Z,1560195061.587 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:31:36.735Z,1560195096.735 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:32:11.883Z,1560195131.883 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:32:47.031Z,1560195167.031 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:33:22.179Z,1560195202.179 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:33:57.371Z,1560195237.371 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:34:32.527Z,1560195272.527 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:35:07.675Z,1560195307.675 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:35:42.823Z,1560195342.823 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:36:17.977Z,1560195377.977 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:36:53.123Z,1560195413.123 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:37:28.295Z,1560195448.295 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:38:03.443Z,1560195483.443 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:38:38.595Z,1560195518.595 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:39:13.743Z,1560195553.743 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:39:48.891Z,1560195588.891 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:40:24.039Z,1560195624.039 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:40:59.187Z,1560195659.187 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:41:34.335Z,1560195694.335 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:42:09.483Z,1560195729.483 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:42:44.667Z,1560195764.667 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:43:19.815Z,1560195799.815 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:43:54.963Z,1560195834.963 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:44:30.111Z,1560195870.111 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:45:05.259Z,1560195905.259 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:45:40.407Z,1560195940.407 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:46:15.559Z,1560195975.559 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:46:50.707Z,1560196010.707 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:47:25.855Z,1560196045.855 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:48:01.011Z,1560196081.011 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:48:36.187Z,1560196116.187 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:49:11.343Z,1560196151.343 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:49:46.491Z,1560196186.491 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:50:21.639Z,1560196221.639 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:50:56.819Z,1560196256.819 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:51:31.991Z,1560196291.991 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:52:07.159Z,1560196327.159 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:52:11.151Z,1560196331.151 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-06-10T19:52:11.151Z,1560196331.151 [DropWeight] Hardware Fault, FailCount= 1 2019-06-10T19:52:11.151Z,1560196331.151 [DropWeight](ERROR): Hardware Fault 2019-06-10T19:52:12.547Z,1560196332.547 [CBIT](INFO): Critical error at 20190610T195211 2019-06-10T19:52:12.551Z,1560196332.551 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-06-10T19:52:12.551Z,1560196332.551 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-06-10T19:52:12.644Z,1560196332.644 [CBIT](INFO): Critical error at 20190610T195212 2019-06-10T19:52:42.307Z,1560196362.307 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:53:17.455Z,1560196397.455 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:53:52.603Z,1560196432.603 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:54:27.751Z,1560196467.751 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:55:02.919Z,1560196502.919 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:55:38.067Z,1560196538.067 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:56:13.215Z,1560196573.215 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:56:48.363Z,1560196608.363 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-06-10T19:57:00.506Z,1560196620.506 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002901 2019-06-10T19:57:13.222Z,1560196633.222 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20190610T171250/Courier0021.lzma 2019-06-10T19:57:14.028Z,1560196634.028 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Courier0021.lzma.bak 2019-06-10T19:57:14.028Z,1560196634.028 [DataOverHttps](INFO): SBD MOMSN=11361887 2019-06-10T19:57:31.967Z,1560196651.967 [NAL9602](INFO): Powering down 2019-06-10T19:57:35.490Z,1560196655.490 [DataOverHttps](INFO): Sending 214 bytes from file Logs/20190610T171250/Express0022.lzma 2019-06-10T19:57:36.296Z,1560196656.296 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Express0022.lzma.bak 2019-06-10T19:57:36.296Z,1560196656.296 [DataOverHttps](INFO): SBD MOMSN=11361891 2019-06-10T19:57:40.112Z,1560196660.112 [Default:CheckIn:Read_Iridium] Stopped 2019-06-10T19:57:40.112Z,1560196660.112 [Default:CheckIn:C.Wait] Running Loop=1 2019-06-10T19:57:40.112Z,1560196660.112 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-06-10T20:00:40.265Z,1560196840.265 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2019-06-10T20:00:40.267Z,1560196840.267 [BPC1](INFO): Received data from all battery sticks. 2019-06-10T20:02:40.660Z,1560196960.660 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-06-10T20:02:40.661Z,1560196960.661 [Default:CheckIn:C.Wait] Stopped 2019-06-10T20:02:40.661Z,1560196960.661 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-06-10T20:02:40.661Z,1560196960.661 [Default:CheckIn:D] Running Loop=1 2019-06-10T20:02:41.063Z,1560196961.063 [Default:CheckIn:D] Stopped 2019-06-10T20:02:41.063Z,1560196961.063 [Default:CheckIn:E] Running Loop=1 2019-06-10T20:02:41.475Z,1560196961.475 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 169.828288 min 2019-06-10T20:02:41.475Z,1560196961.475 [Default:CheckIn:E] Stopped 2019-06-10T20:02:41.475Z,1560196961.475 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-06-10T20:02:41.475Z,1560196961.475 [Default:CheckIn] Stopped 2019-06-10T20:02:41.476Z,1560196961.476 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-06-10T20:02:41.476Z,1560196961.476 [Default:CheckIn](INFO): Running loop #9 2019-06-10T20:02:41.476Z,1560196961.476 [Default:CheckIn] Running Loop=9 2019-06-10T20:02:41.476Z,1560196961.476 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-06-10T20:02:41.476Z,1560196961.476 [Default:CheckIn:Read_GPS] Running Loop=1 2019-06-10T20:02:42.255Z,1560196962.255 [NAL9602](INFO): Powering up 2019-06-10T20:02:53.167Z,1560196973.167 [NAL9602](INFO): NAL9602 initialized 2019-06-10T20:02:53.972Z,1560196973.972 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-06-10T20:02:53.972Z,1560196973.972 [NAL9602] Data Fault, FailCount= 3 2019-06-10T20:02:53.972Z,1560196973.972 [NAL9602](ERROR): Data Fault 2019-06-10T20:02:54.003Z,1560196974.003 [CBIT](ERROR): Data Fault in component: NAL9602 2019-06-10T20:02:54.375Z,1560196974.375 [NAL9602](INFO): Powering down 2019-06-10T20:02:55.227Z,1560196975.227 [CBIT](INFO): Clearing failed state for component NAL9602 2019-06-10T20:02:55.227Z,1560196975.227 [NAL9602] No Fault, FailCount= 3 2019-06-10T20:03:24.673Z,1560197004.673 [NAL9602](INFO): Powering up NAL9602 2019-06-10T20:03:35.587Z,1560197015.587 [NAL9602](INFO): NAL9602 initialized 2019-06-10T20:03:36.406Z,1560197016.406 [NAL9602](DEBUG): Fix Requested 2019-06-10T20:07:11.383Z,1560197231.383 [CBIT](INFO): Clearing failed state for component DropWeight 2019-06-10T20:07:11.383Z,1560197231.383 [DropWeight] No Fault, FailCount= 1 2019-06-10T20:07:41.646Z,1560197261.646 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-06-10T20:02:41.5Z 2019-06-10T20:07:41.646Z,1560197261.646 [Default:CheckIn:Read_GPS] Stopped 2019-06-10T20:07:41.646Z,1560197261.646 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-06-10T20:07:48.790Z,1560197268.790 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20190610T171250/Courier0024.lzma 2019-06-10T20:07:49.596Z,1560197269.596 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Courier0024.lzma.bak 2019-06-10T20:07:49.596Z,1560197269.596 [DataOverHttps](INFO): SBD MOMSN=11361905 2019-06-10T20:08:03.462Z,1560197283.462 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20190610T171250/Express0025.lzma 2019-06-10T20:08:04.268Z,1560197284.268 [DataOverHttps](INFO): Moved sent file to Logs/20190610T171250/Express0025.lzma.bak 2019-06-10T20:08:04.268Z,1560197284.268 [DataOverHttps](INFO): SBD MOMSN=11361911 2019-06-10T20:08:09.562Z,1560197289.562 [Default:CheckIn:Read_Iridium] Stopped 2019-06-10T20:08:09.562Z,1560197289.562 [Default:CheckIn:C.Wait] Running Loop=1 2019-06-10T20:08:09.562Z,1560197289.562 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-06-10T20:08:38.180Z,1560197318.180 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-06-10T20:09:08.881Z,1560197348.881 [NAL9602](INFO): Powering down 2019-06-10T20:12:52.454Z,1560197572.454 [CommandLine](IMPORTANT): got command failComponent 2019-06-10T20:12:52.459Z,1560197572.459 [CommandLine](IMPORTANT): Failed components: 2019-06-10T20:12:52.460Z,1560197572.460 [CommandLine](IMPORTANT): No failed Components. 2019-06-10T20:13:08.880Z,1560197588.880 [CommandLine](IMPORTANT): got command report mod Onboard.Pressure 2019-06-10T20:13:08.990Z,1560197588.990 [Reporter](INFO): Onboard.Pressure 74259.031250 Pa 2019-06-10T20:13:09.323Z,1560197589.323 [Reporter](INFO): Onboard.Pressure 74288.820312 Pa 2019-06-10T20:13:09.728Z,1560197589.728 [Reporter](INFO): Onboard.Pressure 73998.132812 Pa 2019-06-10T20:13:10.128Z,1560197590.128 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-06-10T20:13:10.128Z,1560197590.128 [Default:CheckIn:C.Wait] Stopped 2019-06-10T20:13:10.128Z,1560197590.128 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-06-10T20:13:10.128Z,1560197590.128 [Default:CheckIn:D] Running Loop=1 2019-06-10T20:13:10.149Z,1560197590.149 [Reporter](INFO): Onboard.Pressure 73938.789062 Pa 2019-06-10T20:13:10.528Z,1560197590.528 [Default:CheckIn:D] Stopped 2019-06-10T20:13:10.528Z,1560197590.528 [Default:CheckIn:E] Running Loop=1 2019-06-10T20:13:10.947Z,1560197590.947 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 180.319368 min 2019-06-10T20:13:10.947Z,1560197590.947 [Default:CheckIn:E] Stopped 2019-06-10T20:13:10.947Z,1560197590.947 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-06-10T20:13:10.947Z,1560197590.947 [Default:CheckIn] Stopped 2019-06-10T20:13:10.947Z,1560197590.947 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-06-10T20:13:10.947Z,1560197590.947 [Default:CheckIn](INFO): Running loop #10 2019-06-10T20:13:10.948Z,1560197590.948 [Default:CheckIn] Running Loop=10 2019-06-10T20:13:10.948Z,1560197590.948 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-06-10T20:13:10.948Z,1560197590.948 [Default:CheckIn:Read_GPS] Running Loop=1 2019-06-10T20:13:10.958Z,1560197590.958 [Reporter](INFO): Onboard.Pressure 74084.023438 Pa 2019-06-10T20:13:11.380Z,1560197591.380 [Reporter](INFO): Onboard.Pressure 74113.742188 Pa 2019-06-10T20:13:11.687Z,1560197591.687 [NAL9602](INFO): Powering up 2019-06-10T20:13:11.796Z,1560197591.796 [Reporter](INFO): Onboard.Pressure 73968.460938 Pa 2019-06-10T20:13:12.187Z,1560197592.187 [Reporter](INFO): Onboard.Pressure 73938.789062 Pa 2019-06-10T20:13:14.187Z,1560197594.187 [Reporter](INFO): Onboard.Pressure 74229.250000 Pa 2019-06-10T20:13:14.631Z,1560197594.631 [Reporter](INFO): Onboard.Pressure 73998.132812 Pa 2019-06-10T20:13:15.020Z,1560197595.020 [Reporter](INFO): Onboard.Pressure 74084.023438 Pa 2019-06-10T20:13:15.418Z,1560197595.418 [Reporter](INFO): Onboard.Pressure 74259.031250 Pa 2019-06-10T20:13:15.821Z,1560197595.821 [Reporter](INFO): Onboard.Pressure 73998.132812 Pa 2019-06-10T20:13:16.189Z,1560197596.189 [Reporter](INFO): Onboard.Pressure 73938.789062 Pa 2019-06-10T20:13:16.608Z,1560197596.608 [Reporter](INFO): Onboard.Pressure 74084.023438 Pa 2019-06-10T20:13:17.019Z,1560197597.019 [Reporter](INFO): Onboard.Pressure 74113.742188 Pa 2019-06-10T20:13:17.813Z,1560197597.813 [Reporter](INFO): Onboard.Pressure 73968.460938 Pa 2019-06-10T20:13:18.238Z,1560197598.238 [Reporter](INFO): Onboard.Pressure 73938.789062 Pa 2019-06-10T20:13:18.679Z,1560197598.679 [Reporter](INFO): Onboard.Pressure 74084.023438 Pa 2019-06-10T20:13:19.064Z,1560197599.064 [Reporter](INFO): Onboard.Pressure 74113.742188 Pa 2019-06-10T20:13:20.117Z,1560197600.117 [CommandLine](IMPORTANT): got command report mod Onboard.Humidity 2019-06-10T20:13:20.253Z,1560197600.253 [Reporter](INFO): Onboard.Humidity 19.387133 % 2019-06-10T20:13:20.647Z,1560197600.647 [Reporter](INFO): Onboard.Pressure 73968.460938 Pa 2019-06-10T20:13:21.057Z,1560197601.057 [Reporter](INFO): Onboard.Pressure 74084.023438 Pa 2019-06-10T20:13:21.457Z,1560197601.457 [Reporter](INFO): Onboard.Pressure 74259.031250 Pa 2019-06-10T20:13:21.887Z,1560197601.887 [Reporter](INFO): Onboard.Pressure 73998.132812 Pa 2019-06-10T20:13:22.285Z,1560197602.285 [Reporter](INFO): Onboard.Pressure 73938.789062 Pa 2019-06-10T20:13:22.596Z,1560197602.596 [NAL9602](INFO): NAL9602 initialized 2019-06-10T20:13:22.663Z,1560197602.663 [Reporter](INFO): Onboard.Pressure 74084.023438 Pa 2019-06-10T20:13:23.107Z,1560197603.107 [Reporter](INFO): Onboard.Pressure 74113.742188 Pa 2019-06-10T20:13:23.415Z,1560197603.415 [NAL9602](DEBUG): Fix Requested 2019-06-10T20:13:25.100Z,1560197605.100 [Reporter](INFO): Onboard.Pressure 74259.031250 Pa 2019-06-10T20:13:25.516Z,1560197605.516 [Reporter](INFO): Onboard.Pressure 74314.757812 Pa 2019-06-10T20:13:25.928Z,1560197605.928 [Reporter](INFO): Onboard.Pressure 74318.617188 Pa 2019-06-10T20:13:26.300Z,1560197606.300 [Reporter](INFO): Onboard.Pressure 74143.476562 Pa 2019-06-10T20:13:26.716Z,1560197606.716 [Reporter](INFO): Onboard.Pressure 74113.742188 Pa 2019-06-10T20:13:27.114Z,1560197607.114 [Reporter](INFO): Onboard.Pressure 73968.460938 Pa 2019-06-10T20:13:27.504Z,1560197607.504 [Reporter](INFO): Onboard.Pressure 74084.023438 Pa 2019-06-10T20:13:27.924Z,1560197607.924 [Reporter](INFO): Onboard.Pressure 73968.460938 Pa 2019-06-10T20:13:28.327Z,1560197608.327 [Reporter](INFO): Onboard.Pressure 74084.023438 Pa 2019-06-10T20:13:28.736Z,1560197608.736 [Reporter](INFO): Onboard.Pressure 73968.460938 Pa 2019-06-10T20:13:29.110Z,1560197609.110 [Reporter](INFO): Onboard.Pressure 74084.023438 Pa 2019-06-10T20:13:29.587Z,1560197609.587 [Reporter](INFO): Onboard.Pressure 74113.742188 Pa 2019-06-10T20:13:31.152Z,1560197611.152 [Reporter](INFO): Onboard.Pressure 74259.031250 Pa 2019-06-10T20:13:31.552Z,1560197611.552 [Reporter](INFO): Onboard.Pressure 74143.476562 Pa 2019-06-10T20:13:31.972Z,1560197611.972 [Reporter](INFO): Onboard.Pressure 74113.742188 Pa 2019-06-10T20:13:32.373Z,1560197612.373 [Reporter](INFO): Onboard.Pressure 73968.460938 Pa 2019-06-10T20:13:32.786Z,1560197612.786 [Reporter](INFO): Onboard.Pressure 73938.789062 Pa 2019-06-10T20:13:33.157Z,1560197613.157 [Reporter](INFO): Onboard.Pressure 74255.046875 Pa 2019-06-10T20:13:33.573Z,1560197613.573 [Reporter](INFO): Onboard.Pressure 74173.210938 Pa 2019-06-10T20:13:33.971Z,1560197613.971 [Reporter](INFO): Onboard.Pressure 74113.742188 Pa 2019-06-10T20:13:34.377Z,1560197614.377 [Reporter](INFO): Onboard.Pressure 73968.460938 Pa 2019-06-10T20:13:34.801Z,1560197614.801 [Reporter](INFO): Onboard.Pressure 74084.023438 Pa 2019-06-10T20:13:35.227Z,1560197615.227 [Reporter](INFO): Onboard.Pressure 74113.742188 Pa 2019-06-10T20:13:35.616Z,1560197615.616 [Reporter](INFO): Onboard.Pressure 73968.460938 Pa 2