2020-10-17T00:13:15.314Z,1602893595.314 [CommandLine](IMPORTANT): got command restart logs 2020-10-17T00:13:47.867Z,1602893627.867 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T00:13:47.867Z,1602893627.867 [Default:CheckIn:C.Wait] Stopped 2020-10-17T00:13:47.867Z,1602893627.867 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T00:13:47.868Z,1602893627.868 [Default:CheckIn:D] Running Loop=1 2020-10-17T00:13:48.281Z,1602893628.281 [Default:CheckIn:D] Stopped 2020-10-17T00:13:48.282Z,1602893628.282 [Default:CheckIn:E] Running Loop=1 2020-10-17T00:13:48.678Z,1602893628.678 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.291486 min 2020-10-17T00:13:48.678Z,1602893628.678 [Default:CheckIn:E] Stopped 2020-10-17T00:13:48.678Z,1602893628.678 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T00:13:48.678Z,1602893628.678 [Default:CheckIn] Stopped 2020-10-17T00:13:48.678Z,1602893628.678 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T00:13:48.678Z,1602893628.678 [Default:CheckIn](INFO): Running loop #4 2020-10-17T00:13:48.678Z,1602893628.678 [Default:CheckIn] Running Loop=4 2020-10-17T00:13:48.678Z,1602893628.678 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T00:13:48.679Z,1602893628.679 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T00:13:50.688Z,1602893630.688 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001356.00,A,3648.50563,N,12149.42316,W,13.276,219.84,171020,,,A*4E 2020-10-17T00:13:50.690Z,1602893630.690 [NAL9602](INFO): GPS fix at 20201017T001356: (36.808427, -121.823719) 2020-10-17T00:13:50.707Z,1602893630.707 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T00:13:50.708Z,1602893630.708 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T00:14:05.736Z,1602893645.736 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201016T222156/Courier0027.lzma 2020-10-17T00:14:14.770Z,1602893654.770 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2020-10-17T00:14:16.780Z,1602893656.780 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.529945 2020-10-17T00:14:28.662Z,1602893668.662 [NAL9602](INFO): SBD MO Status=2, MOMSN=11103, MT Status=2, MTMSN=0 2020-10-17T00:14:28.662Z,1602893668.662 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:14:47.901Z,1602893687.901 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:15:03.282Z,1602893703.282 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003465 2020-10-17T00:15:08.660Z,1602893708.660 [NAL9602](INFO): SBD MO Status=0, MOMSN=11103, MT Status=0, MTMSN=0 2020-10-17T00:15:08.660Z,1602893708.660 [NAL9602](INFO): No messages in MT queue 2020-10-17T00:15:10.632Z,1602893710.632 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201016T222156/Courier0027.lzma 2020-10-17T00:15:18.663Z,1602893718.663 [DataOverHttps](INFO): Moved sent file to Logs/20201016T222156/Courier0027.lzma.bak 2020-10-17T00:15:18.663Z,1602893718.663 [DataOverHttps](INFO): SBD MOMSN=12918999 2020-10-17T00:15:39.362Z,1602893739.362 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T00:15:49.786Z,1602893749.786 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:16:18.926Z,1602893778.926 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.263733 2020-10-17T00:16:39.195Z,1602893799.195 [NAL9602](INFO): SBD MO Status=1, MOMSN=11104, MT Status=0, MTMSN=0 2020-10-17T00:16:39.255Z,1602893799.255 [NAL9602](INFO): Sent 267 bytes from file Logs/20201017T001315/Courier0000.lzma 2020-10-17T00:16:39.256Z,1602893799.256 [NAL9602](INFO): Packets left to send: 0 2020-10-17T00:16:50.049Z,1602893810.049 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:16:55.399Z,1602893815.399 [NAL9602](INFO): SBD MO Status=2, MOMSN=11105, MT Status=2, MTMSN=0 2020-10-17T00:16:55.400Z,1602893815.400 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:17:25.189Z,1602893845.189 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:17:27.348Z,1602893847.348 [NAL9602](INFO): SBD MO Status=2, MOMSN=11105, MT Status=2, MTMSN=0 2020-10-17T00:17:27.348Z,1602893847.348 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:17:57.688Z,1602893877.688 [NAL9602](INFO): SBD MO Status=2, MOMSN=11105, MT Status=2, MTMSN=0 2020-10-17T00:17:57.688Z,1602893877.688 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:18:00.329Z,1602893880.329 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:18:23.577Z,1602893903.577 [NAL9602](INFO): SBD MO Status=2, MOMSN=11105, MT Status=2, MTMSN=0 2020-10-17T00:18:23.577Z,1602893903.577 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:18:35.470Z,1602893915.470 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:18:53.124Z,1602893933.124 [NAL9602](INFO): SBD MO Status=1, MOMSN=11105, MT Status=0, MTMSN=0 2020-10-17T00:18:53.185Z,1602893933.185 [NAL9602](INFO): Sent 186 bytes from file Logs/20201016T222156/Express0028.lzma 2020-10-17T00:18:53.185Z,1602893933.185 [NAL9602](INFO): Packets left to send: 0 2020-10-17T00:19:10.610Z,1602893950.610 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:19:17.416Z,1602893957.416 [NAL9602](INFO): SBD MO Status=1, MOMSN=11106, MT Status=0, MTMSN=0 2020-10-17T00:19:17.477Z,1602893957.477 [NAL9602](INFO): Sent 332 bytes from file Logs/20201017T001315/Express0001.lzma 2020-10-17T00:19:17.478Z,1602893957.478 [NAL9602](INFO): Packets left to send: 1 2020-10-17T00:19:25.535Z,1602893965.536 [NAL9602](INFO): SBD MO Status=1, MOMSN=11107, MT Status=0, MTMSN=0 2020-10-17T00:19:25.585Z,1602893965.585 [NAL9602](INFO): Sent 5 bytes from file Logs/20201017T001315/Express0001.lzma 2020-10-17T00:19:25.585Z,1602893965.585 [NAL9602](INFO): Packets left to send: 0 2020-10-17T00:19:33.252Z,1602893973.252 [NAL9602](INFO): SBD MO Status=1, MOMSN=11108, MT Status=0, MTMSN=0 2020-10-17T00:19:33.301Z,1602893973.301 [NAL9602](INFO): Sent 52 bytes from file Logs/20201017T001315/Express0004.lzma 2020-10-17T00:19:33.301Z,1602893973.301 [NAL9602](INFO): Packets left to send: 0 2020-10-17T00:19:45.749Z,1602893985.749 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:19:52.236Z,1602893992.236 [NAL9602](INFO): SBD MO Status=2, MOMSN=11109, MT Status=2, MTMSN=0 2020-10-17T00:19:52.236Z,1602893992.236 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:20:13.645Z,1602894013.645 [NAL9602](INFO): SBD MO Status=0, MOMSN=11109, MT Status=0, MTMSN=0 2020-10-17T00:20:13.721Z,1602894013.721 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T00:20:13.722Z,1602894013.722 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T00:20:13.722Z,1602894013.722 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T00:20:20.897Z,1602894020.897 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:20:44.352Z,1602894044.352 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T00:20:56.045Z,1602894056.045 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:21:31.221Z,1602894091.221 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:22:06.369Z,1602894126.369 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:22:41.509Z,1602894161.509 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:23:16.649Z,1602894196.649 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:23:51.798Z,1602894231.798 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:24:26.937Z,1602894266.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:25:02.077Z,1602894302.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:25:14.236Z,1602894314.236 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T00:25:14.236Z,1602894314.236 [Default:CheckIn:C.Wait] Stopped 2020-10-17T00:25:14.236Z,1602894314.236 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T00:25:14.237Z,1602894314.237 [Default:CheckIn:D] Running Loop=1 2020-10-17T00:25:14.645Z,1602894314.645 [Default:CheckIn:D] Stopped 2020-10-17T00:25:14.645Z,1602894314.645 [Default:CheckIn:E] Running Loop=1 2020-10-17T00:25:15.044Z,1602894315.044 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.730880 min 2020-10-17T00:25:15.044Z,1602894315.044 [Default:CheckIn:E] Stopped 2020-10-17T00:25:15.044Z,1602894315.044 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T00:25:15.044Z,1602894315.044 [Default:CheckIn] Stopped 2020-10-17T00:25:15.045Z,1602894315.045 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T00:25:15.065Z,1602894315.065 [Default:CheckIn](INFO): Running loop #5 2020-10-17T00:25:15.065Z,1602894315.065 [Default:CheckIn] Running Loop=5 2020-10-17T00:25:15.065Z,1602894315.065 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T00:25:15.065Z,1602894315.065 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T00:25:17.056Z,1602894317.056 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002523.00,A,3648.99616,N,12151.52931,W,19.652,285.34,171020,,,D*49 2020-10-17T00:25:17.059Z,1602894317.059 [NAL9602](INFO): GPS fix at 20201017T002523: (36.816603, -121.858822) 2020-10-17T00:25:17.069Z,1602894317.069 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T00:25:17.069Z,1602894317.069 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T00:25:37.218Z,1602894337.218 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:25:38.099Z,1602894338.099 [NAL9602](INFO): SBD MO Status=1, MOMSN=11110, MT Status=0, MTMSN=0 2020-10-17T00:25:38.149Z,1602894338.149 [NAL9602](INFO): Sent 72 bytes from file Logs/20201017T001315/Courier0006.lzma 2020-10-17T00:25:38.149Z,1602894338.149 [NAL9602](INFO): Packets left to send: 0 2020-10-17T00:25:52.691Z,1602894352.691 [NAL9602](INFO): SBD MO Status=2, MOMSN=11111, MT Status=2, MTMSN=0 2020-10-17T00:25:52.692Z,1602894352.692 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:26:12.369Z,1602894372.369 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:26:21.423Z,1602894381.423 [NAL9602](INFO): SBD MO Status=1, MOMSN=11111, MT Status=0, MTMSN=0 2020-10-17T00:26:21.477Z,1602894381.477 [NAL9602](INFO): Sent 254 bytes from file Logs/20201017T001315/Express0007.lzma 2020-10-17T00:26:21.477Z,1602894381.477 [NAL9602](INFO): Packets left to send: 0 2020-10-17T00:26:27.880Z,1602894387.880 [NAL9602](INFO): SBD MO Status=0, MOMSN=11112, MT Status=0, MTMSN=0 2020-10-17T00:26:27.966Z,1602894387.966 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T00:26:27.966Z,1602894387.966 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T00:26:27.966Z,1602894387.966 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T00:26:47.525Z,1602894407.525 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:26:58.598Z,1602894418.598 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T00:27:22.665Z,1602894442.665 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:27:57.821Z,1602894477.821 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:28:32.994Z,1602894512.994 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:29:08.133Z,1602894548.133 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:29:43.281Z,1602894583.281 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:30:18.421Z,1602894618.421 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:30:53.561Z,1602894653.561 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:31:28.488Z,1602894688.488 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T00:31:28.488Z,1602894688.488 [Default:CheckIn:C.Wait] Stopped 2020-10-17T00:31:28.489Z,1602894688.489 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T00:31:28.490Z,1602894688.490 [Default:CheckIn:D] Running Loop=1 2020-10-17T00:31:28.737Z,1602894688.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:31:28.872Z,1602894688.872 [Default:CheckIn:D] Stopped 2020-10-17T00:31:28.872Z,1602894688.872 [Default:CheckIn:E] Running Loop=1 2020-10-17T00:31:29.280Z,1602894689.280 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.967993 min 2020-10-17T00:31:29.280Z,1602894689.280 [Default:CheckIn:E] Stopped 2020-10-17T00:31:29.280Z,1602894689.280 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T00:31:29.281Z,1602894689.281 [Default:CheckIn] Stopped 2020-10-17T00:31:29.281Z,1602894689.281 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T00:31:29.281Z,1602894689.281 [Default:CheckIn](INFO): Running loop #6 2020-10-17T00:31:29.281Z,1602894689.281 [Default:CheckIn] Running Loop=6 2020-10-17T00:31:29.281Z,1602894689.281 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T00:31:29.281Z,1602894689.281 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T00:31:31.288Z,1602894691.288 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003137.00,A,3649.54660,N,12152.48812,W,0.194,89.65,171020,,,D*4F 2020-10-17T00:31:31.300Z,1602894691.300 [NAL9602](INFO): GPS fix at 20201017T003137: (36.825777, -121.874802) 2020-10-17T00:31:31.310Z,1602894691.310 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T00:31:31.310Z,1602894691.310 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T00:31:56.369Z,1602894716.369 [NAL9602](INFO): SBD MO Status=2, MOMSN=11113, MT Status=2, MTMSN=0 2020-10-17T00:31:56.369Z,1602894716.369 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:32:03.877Z,1602894723.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:32:21.868Z,1602894741.868 [NAL9602](INFO): SBD MO Status=2, MOMSN=11113, MT Status=2, MTMSN=0 2020-10-17T00:32:21.868Z,1602894741.868 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:32:39.021Z,1602894759.021 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:32:42.928Z,1602894762.928 [NAL9602](INFO): SBD MO Status=1, MOMSN=11113, MT Status=0, MTMSN=0 2020-10-17T00:32:42.981Z,1602894762.981 [NAL9602](INFO): Sent 72 bytes from file Logs/20201017T001315/Courier0009.lzma 2020-10-17T00:32:42.981Z,1602894762.981 [NAL9602](INFO): Packets left to send: 0 2020-10-17T00:33:02.769Z,1602894782.769 [NAL9602](INFO): SBD MO Status=1, MOMSN=11114, MT Status=0, MTMSN=0 2020-10-17T00:33:02.813Z,1602894782.813 [NAL9602](INFO): Sent 146 bytes from file Logs/20201017T001315/Express0010.lzma 2020-10-17T00:33:02.813Z,1602894782.813 [NAL9602](INFO): Packets left to send: 0 2020-10-17T00:33:14.162Z,1602894794.162 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:33:20.960Z,1602894800.960 [NAL9602](INFO): SBD MO Status=2, MOMSN=11115, MT Status=2, MTMSN=0 2020-10-17T00:33:20.960Z,1602894800.960 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:33:25.599Z,1602894805.599 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2020-10-17T00:33:28.834Z,1602894808.834 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2020-10-17T00:33:37.719Z,1602894817.719 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2020-10-17T00:33:46.203Z,1602894826.203 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2020-10-17T00:33:49.313Z,1602894829.313 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:33:50.642Z,1602894830.642 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2020-10-17T00:33:54.685Z,1602894834.685 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2020-10-17T00:33:56.708Z,1602894836.708 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2020-10-17T00:34:00.752Z,1602894840.752 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2020-10-17T00:34:02.764Z,1602894842.764 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2020-10-17T00:34:11.652Z,1602894851.652 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2020-10-17T00:34:15.775Z,1602894855.775 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2020-10-17T00:34:20.137Z,1602894860.137 [NAL9602](ERROR): Inadequate Iridium signal strength reported: 0 2020-10-17T00:34:24.453Z,1602894864.453 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:34:42.160Z,1602894882.160 [NAL9602](INFO): SBD MO Status=2, MOMSN=11115, MT Status=2, MTMSN=0 2020-10-17T00:34:42.160Z,1602894882.160 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:34:52.253Z,1602894892.253 [NAL9602](INFO): SBD MO Status=0, MOMSN=11115, MT Status=0, MTMSN=0 2020-10-17T00:34:52.344Z,1602894892.344 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T00:34:52.345Z,1602894892.345 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T00:34:52.345Z,1602894892.345 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T00:34:59.597Z,1602894899.597 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:35:22.956Z,1602894922.956 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T00:35:34.737Z,1602894934.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:36:09.877Z,1602894969.877 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:36:45.021Z,1602895005.021 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:37:20.161Z,1602895040.161 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:37:55.309Z,1602895075.309 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:38:30.473Z,1602895110.473 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:39:05.645Z,1602895145.645 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:39:40.785Z,1602895180.785 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:39:52.855Z,1602895192.855 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T00:39:52.855Z,1602895192.855 [Default:CheckIn:C.Wait] Stopped 2020-10-17T00:39:52.855Z,1602895192.855 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T00:39:52.855Z,1602895192.855 [Default:CheckIn:D] Running Loop=1 2020-10-17T00:39:53.242Z,1602895193.242 [Default:CheckIn:D] Stopped 2020-10-17T00:39:53.242Z,1602895193.242 [Default:CheckIn:E] Running Loop=1 2020-10-17T00:39:53.683Z,1602895193.683 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.374162 min 2020-10-17T00:39:53.683Z,1602895193.683 [Default:CheckIn:E] Stopped 2020-10-17T00:39:53.683Z,1602895193.683 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T00:39:53.683Z,1602895193.683 [Default:CheckIn] Stopped 2020-10-17T00:39:53.683Z,1602895193.683 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T00:39:53.684Z,1602895193.684 [Default:CheckIn](INFO): Running loop #7 2020-10-17T00:39:53.684Z,1602895193.684 [Default:CheckIn] Running Loop=7 2020-10-17T00:39:53.684Z,1602895193.684 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T00:39:53.684Z,1602895193.684 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T00:39:55.664Z,1602895195.664 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004001.00,A,3648.54028,N,12148.54151,W,29.216,109.23,171020,,,D*46 2020-10-17T00:39:55.666Z,1602895195.666 [NAL9602](INFO): GPS fix at 20201017T004001: (36.809005, -121.809025) 2020-10-17T00:39:55.697Z,1602895195.697 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T00:39:55.697Z,1602895195.697 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T00:40:23.170Z,1602895223.170 [NAL9602](INFO): SBD MO Status=1, MOMSN=11116, MT Status=0, MTMSN=0 2020-10-17T00:40:23.218Z,1602895223.218 [NAL9602](INFO): Sent 72 bytes from file Logs/20201017T001315/Courier0012.lzma 2020-10-17T00:40:23.218Z,1602895223.218 [NAL9602](INFO): Packets left to send: 0 2020-10-17T00:40:45.045Z,1602895245.045 [NAL9602](INFO): SBD MO Status=2, MOMSN=11117, MT Status=2, MTMSN=0 2020-10-17T00:40:45.046Z,1602895245.046 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:40:51.601Z,1602895251.601 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:41:02.048Z,1602895262.048 [NAL9602](INFO): SBD MO Status=1, MOMSN=11117, MT Status=0, MTMSN=0 2020-10-17T00:41:02.099Z,1602895262.099 [NAL9602](INFO): Sent 146 bytes from file Logs/20201017T001315/Express0013.lzma 2020-10-17T00:41:02.099Z,1602895262.099 [NAL9602](INFO): Packets left to send: 0 2020-10-17T00:41:12.552Z,1602895272.552 [NAL9602](INFO): SBD MO Status=0, MOMSN=11118, MT Status=0, MTMSN=0 2020-10-17T00:41:12.648Z,1602895272.648 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T00:41:12.648Z,1602895272.648 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T00:41:12.648Z,1602895272.648 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T00:41:26.749Z,1602895286.749 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:41:43.251Z,1602895303.251 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T00:42:01.889Z,1602895321.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:42:14.394Z,1602895334.394 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003426 2020-10-17T00:46:13.139Z,1602895573.139 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T00:46:13.139Z,1602895573.139 [Default:CheckIn:C.Wait] Stopped 2020-10-17T00:46:13.140Z,1602895573.140 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T00:46:13.140Z,1602895573.140 [Default:CheckIn:D] Running Loop=1 2020-10-17T00:46:13.538Z,1602895573.538 [Default:CheckIn:D] Stopped 2020-10-17T00:46:13.538Z,1602895573.538 [Default:CheckIn:E] Running Loop=1 2020-10-17T00:46:13.948Z,1602895573.948 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.712419 min 2020-10-17T00:46:13.948Z,1602895573.948 [Default:CheckIn:E] Stopped 2020-10-17T00:46:13.948Z,1602895573.948 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T00:46:13.948Z,1602895573.948 [Default:CheckIn] Stopped 2020-10-17T00:46:13.949Z,1602895573.949 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T00:46:13.949Z,1602895573.949 [Default:CheckIn](INFO): Running loop #8 2020-10-17T00:46:13.949Z,1602895573.949 [Default:CheckIn] Running Loop=8 2020-10-17T00:46:13.949Z,1602895573.949 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T00:46:13.949Z,1602895573.949 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T00:46:15.960Z,1602895575.960 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004622.00,A,3648.23917,N,12147.15138,W,4.840,193.30,171020,,,D*76 2020-10-17T00:46:15.972Z,1602895575.972 [NAL9602](INFO): GPS fix at 20201017T004622: (36.803986, -121.785856) 2020-10-17T00:46:15.982Z,1602895575.982 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T00:46:15.982Z,1602895575.982 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T00:46:23.548Z,1602895583.548 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201017T001315/Courier0015.lzma 2020-10-17T00:46:24.551Z,1602895584.551 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Courier0015.lzma.bak 2020-10-17T00:46:24.551Z,1602895584.551 [DataOverHttps](INFO): SBD MOMSN=12919237 2020-10-17T00:46:36.972Z,1602895596.972 [NAL9602](INFO): SBD MO Status=0, MOMSN=11119, MT Status=0, MTMSN=0 2020-10-17T00:46:36.972Z,1602895596.972 [NAL9602](INFO): No messages in MT queue 2020-10-17T00:46:40.209Z,1602895600.209 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20201017T001315/Express0016.lzma 2020-10-17T00:46:41.211Z,1602895601.211 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Express0016.lzma.bak 2020-10-17T00:46:41.211Z,1602895601.211 [DataOverHttps](INFO): SBD MOMSN=12919240 2020-10-17T00:46:42.646Z,1602895602.646 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T00:46:42.646Z,1602895602.646 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T00:46:42.646Z,1602895602.646 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T00:47:07.682Z,1602895627.682 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T00:51:43.247Z,1602895903.247 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T00:51:43.247Z,1602895903.247 [Default:CheckIn:C.Wait] Stopped 2020-10-17T00:51:43.247Z,1602895903.247 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T00:51:43.247Z,1602895903.247 [Default:CheckIn:D] Running Loop=1 2020-10-17T00:51:43.662Z,1602895903.662 [Default:CheckIn:D] Stopped 2020-10-17T00:51:43.662Z,1602895903.662 [Default:CheckIn:E] Running Loop=1 2020-10-17T00:51:44.080Z,1602895904.080 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.214490 min 2020-10-17T00:51:44.080Z,1602895904.080 [Default:CheckIn:E] Stopped 2020-10-17T00:51:44.080Z,1602895904.080 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T00:51:44.080Z,1602895904.080 [Default:CheckIn] Stopped 2020-10-17T00:51:44.080Z,1602895904.080 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T00:51:44.080Z,1602895904.080 [Default:CheckIn](INFO): Running loop #9 2020-10-17T00:51:44.080Z,1602895904.080 [Default:CheckIn] Running Loop=9 2020-10-17T00:51:44.080Z,1602895904.080 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T00:51:44.080Z,1602895904.080 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T00:51:46.066Z,1602895906.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005152.00,A,3648.13937,N,12147.20607,W,0.117,189.14,171020,,,D*79 2020-10-17T00:51:46.068Z,1602895906.068 [NAL9602](INFO): GPS fix at 20201017T005152: (36.802323, -121.786768) 2020-10-17T00:51:46.083Z,1602895906.083 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T00:51:46.083Z,1602895906.083 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T00:51:53.262Z,1602895913.262 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20201017T001315/Courier0018.lzma 2020-10-17T00:51:54.215Z,1602895914.215 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Courier0018.lzma.bak 2020-10-17T00:51:54.215Z,1602895914.215 [DataOverHttps](INFO): SBD MOMSN=12919251 2020-10-17T00:52:00.616Z,1602895920.616 [NAL9602](INFO): SBD MO Status=0, MOMSN=11120, MT Status=0, MTMSN=0 2020-10-17T00:52:00.616Z,1602895920.616 [NAL9602](INFO): No messages in MT queue 2020-10-17T00:52:09.841Z,1602895929.841 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201017T001315/Express0019.lzma 2020-10-17T00:52:10.843Z,1602895930.843 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Express0019.lzma.bak 2020-10-17T00:52:10.843Z,1602895930.843 [DataOverHttps](INFO): SBD MOMSN=12919254 2020-10-17T00:52:12.348Z,1602895932.348 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T00:52:12.348Z,1602895932.348 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T00:52:12.348Z,1602895932.348 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T00:52:31.314Z,1602895951.314 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T00:57:12.918Z,1602896232.918 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T00:57:12.918Z,1602896232.918 [Default:CheckIn:C.Wait] Stopped 2020-10-17T00:57:12.918Z,1602896232.918 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T00:57:12.918Z,1602896232.918 [Default:CheckIn:D] Running Loop=1 2020-10-17T00:57:13.315Z,1602896233.315 [Default:CheckIn:D] Stopped 2020-10-17T00:57:13.315Z,1602896233.315 [Default:CheckIn:E] Running Loop=1 2020-10-17T00:57:13.729Z,1602896233.729 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.708712 min 2020-10-17T00:57:13.729Z,1602896233.729 [Default:CheckIn:E] Stopped 2020-10-17T00:57:13.729Z,1602896233.729 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T00:57:13.729Z,1602896233.729 [Default:CheckIn] Stopped 2020-10-17T00:57:13.729Z,1602896233.729 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T00:57:13.730Z,1602896233.730 [Default:CheckIn](INFO): Running loop #10 2020-10-17T00:57:13.730Z,1602896233.730 [Default:CheckIn] Running Loop=10 2020-10-17T00:57:13.730Z,1602896233.730 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T00:57:13.730Z,1602896233.730 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T00:57:15.736Z,1602896235.736 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005721.00,A,3648.13754,N,12147.21055,W,0.058,198.85,171020,,,D*72 2020-10-17T00:57:15.738Z,1602896235.738 [NAL9602](INFO): GPS fix at 20201017T005721: (36.802292, -121.786843) 2020-10-17T00:57:15.749Z,1602896235.749 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T00:57:15.749Z,1602896235.749 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T00:57:22.916Z,1602896242.916 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20201017T001315/Courier0021.lzma 2020-10-17T00:57:23.919Z,1602896243.919 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Courier0021.lzma.bak 2020-10-17T00:57:23.919Z,1602896243.919 [DataOverHttps](INFO): SBD MOMSN=12919312 2020-10-17T00:57:26.637Z,1602896246.637 [NAL9602](INFO): SBD MO Status=0, MOMSN=11121, MT Status=0, MTMSN=0 2020-10-17T00:57:26.638Z,1602896246.638 [NAL9602](INFO): No messages in MT queue 2020-10-17T00:57:39.717Z,1602896259.717 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20201017T001315/Express0022.lzma 2020-10-17T00:57:40.719Z,1602896260.719 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Express0022.lzma.bak 2020-10-17T00:57:40.719Z,1602896260.719 [DataOverHttps](INFO): SBD MOMSN=12919315 2020-10-17T00:57:42.008Z,1602896262.008 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T00:57:42.008Z,1602896262.008 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T00:57:42.008Z,1602896262.008 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T00:57:57.346Z,1602896277.346 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T00:59:28.261Z,1602896368.261 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-10-17T00:59:28.263Z,1602896368.263 [BPC1](INFO): Received data from all battery sticks. 2020-10-17T01:02:42.597Z,1602896562.597 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:02:42.597Z,1602896562.597 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:02:42.597Z,1602896562.597 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:02:42.597Z,1602896562.597 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:02:43.014Z,1602896563.014 [Default:CheckIn:D] Stopped 2020-10-17T01:02:43.014Z,1602896563.014 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:02:43.404Z,1602896563.404 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.203695 min 2020-10-17T01:02:43.404Z,1602896563.404 [Default:CheckIn:E] Stopped 2020-10-17T01:02:43.404Z,1602896563.404 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:02:43.404Z,1602896563.404 [Default:CheckIn] Stopped 2020-10-17T01:02:43.404Z,1602896563.404 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:02:43.404Z,1602896563.404 [Default:CheckIn](INFO): Running loop #11 2020-10-17T01:02:43.404Z,1602896563.404 [Default:CheckIn] Running Loop=11 2020-10-17T01:02:43.405Z,1602896563.405 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:02:43.405Z,1602896563.405 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:02:45.410Z,1602896565.410 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010251.00,A,3648.17075,N,12147.28092,W,0.233,209.04,171020,,,D*7B 2020-10-17T01:02:45.412Z,1602896565.412 [NAL9602](INFO): GPS fix at 20201017T010251: (36.802846, -121.788015) 2020-10-17T01:02:45.423Z,1602896565.423 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:02:45.423Z,1602896565.423 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:02:52.549Z,1602896572.549 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20201017T001315/Courier0024.lzma 2020-10-17T01:02:53.551Z,1602896573.551 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Courier0024.lzma.bak 2020-10-17T01:02:53.551Z,1602896573.551 [DataOverHttps](INFO): SBD MOMSN=12919326 2020-10-17T01:03:09.225Z,1602896589.225 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201017T001315/Express0025.lzma 2020-10-17T01:03:10.227Z,1602896590.227 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Express0025.lzma.bak 2020-10-17T01:03:10.227Z,1602896590.227 [DataOverHttps](INFO): SBD MOMSN=12919329 2020-10-17T01:03:11.692Z,1602896591.692 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:03:11.692Z,1602896591.692 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T01:03:11.692Z,1602896591.692 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T01:03:20.563Z,1602896600.563 [NAL9602](INFO): SBD MO Status=2, MOMSN=11122, MT Status=2, MTMSN=0 2020-10-17T01:03:20.564Z,1602896600.564 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:03:37.531Z,1602896617.531 [NAL9602](INFO): SBD MO Status=2, MOMSN=11122, MT Status=2, MTMSN=0 2020-10-17T01:03:37.532Z,1602896617.532 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:03:57.725Z,1602896637.725 [NAL9602](INFO): SBD MO Status=0, MOMSN=11122, MT Status=0, MTMSN=0 2020-10-17T01:03:57.725Z,1602896637.725 [NAL9602](INFO): No messages in MT queue 2020-10-17T01:04:28.436Z,1602896668.436 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T01:08:12.253Z,1602896892.253 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:08:12.254Z,1602896892.254 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:08:12.254Z,1602896892.254 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:08:12.254Z,1602896892.254 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:08:12.664Z,1602896892.664 [Default:CheckIn:D] Stopped 2020-10-17T01:08:12.664Z,1602896892.664 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:08:13.068Z,1602896893.068 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.697860 min 2020-10-17T01:08:13.068Z,1602896893.068 [Default:CheckIn:E] Stopped 2020-10-17T01:08:13.068Z,1602896893.068 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:08:13.068Z,1602896893.068 [Default:CheckIn] Stopped 2020-10-17T01:08:13.068Z,1602896893.068 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:08:13.068Z,1602896893.068 [Default:CheckIn](INFO): Running loop #12 2020-10-17T01:08:13.068Z,1602896893.068 [Default:CheckIn] Running Loop=12 2020-10-17T01:08:13.069Z,1602896893.069 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:08:13.069Z,1602896893.069 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:08:15.076Z,1602896895.076 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010821.00,A,3648.16780,N,12147.27801,W,0.097,216.73,171020,,,A*70 2020-10-17T01:08:15.079Z,1602896895.079 [NAL9602](INFO): GPS fix at 20201017T010821: (36.802797, -121.787967) 2020-10-17T01:08:15.094Z,1602896895.094 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:08:15.094Z,1602896895.094 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:08:23.364Z,1602896903.364 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201017T001315/Courier0027.lzma 2020-10-17T01:08:24.367Z,1602896904.367 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Courier0027.lzma.bak 2020-10-17T01:08:24.367Z,1602896904.367 [DataOverHttps](INFO): SBD MOMSN=12919386 2020-10-17T01:08:33.715Z,1602896913.715 [NAL9602](INFO): SBD MO Status=0, MOMSN=11123, MT Status=0, MTMSN=0 2020-10-17T01:08:33.715Z,1602896913.715 [NAL9602](INFO): No messages in MT queue 2020-10-17T01:08:40.109Z,1602896920.109 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20201017T001315/Express0028.lzma 2020-10-17T01:08:41.111Z,1602896921.111 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Express0028.lzma.bak 2020-10-17T01:08:41.111Z,1602896921.111 [DataOverHttps](INFO): SBD MOMSN=12919389 2020-10-17T01:08:42.576Z,1602896922.576 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:08:42.576Z,1602896922.576 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T01:08:42.576Z,1602896922.576 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T01:09:04.370Z,1602896944.370 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T01:13:43.224Z,1602897223.224 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:13:43.224Z,1602897223.224 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:13:43.224Z,1602897223.224 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:13:43.225Z,1602897223.225 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:13:43.572Z,1602897223.572 [Default:CheckIn:D] Stopped 2020-10-17T01:13:43.572Z,1602897223.572 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:13:43.984Z,1602897223.984 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.212996 min 2020-10-17T01:13:43.984Z,1602897223.984 [Default:CheckIn:E] Stopped 2020-10-17T01:13:43.984Z,1602897223.984 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:13:43.984Z,1602897223.984 [Default:CheckIn] Stopped 2020-10-17T01:13:43.984Z,1602897223.984 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:13:43.985Z,1602897223.985 [Default:CheckIn](INFO): Running loop #13 2020-10-17T01:13:43.985Z,1602897223.985 [Default:CheckIn] Running Loop=13 2020-10-17T01:13:43.985Z,1602897223.985 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:13:43.985Z,1602897223.985 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:13:45.984Z,1602897225.984 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011352.00,A,3648.16736,N,12147.27424,W,0.175,118.10,171020,,,A*7D 2020-10-17T01:13:45.986Z,1602897225.986 [NAL9602](INFO): GPS fix at 20201017T011352: (36.802789, -121.787904) 2020-10-17T01:13:45.996Z,1602897225.996 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:13:45.997Z,1602897225.997 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:13:53.128Z,1602897233.128 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20201017T001315/Courier0030.lzma 2020-10-17T01:13:54.131Z,1602897234.131 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Courier0030.lzma.bak 2020-10-17T01:13:54.131Z,1602897234.131 [DataOverHttps](INFO): SBD MOMSN=12919399 2020-10-17T01:14:09.933Z,1602897249.933 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20201017T001315/Express0031.lzma 2020-10-17T01:14:10.935Z,1602897250.935 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Express0031.lzma.bak 2020-10-17T01:14:10.935Z,1602897250.935 [DataOverHttps](INFO): SBD MOMSN=12919402 2020-10-17T01:14:12.261Z,1602897252.261 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:14:12.261Z,1602897252.261 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T01:14:12.262Z,1602897252.262 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T01:15:00.323Z,1602897300.323 [NAL9602](INFO): SBD MO Status=2, MOMSN=11124, MT Status=2, MTMSN=0 2020-10-17T01:15:00.324Z,1602897300.324 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:16:36.875Z,1602897396.875 [NAL9602](INFO): SBD MO Status=0, MOMSN=11124, MT Status=0, MTMSN=0 2020-10-17T01:16:36.875Z,1602897396.875 [NAL9602](INFO): No messages in MT queue 2020-10-17T01:17:07.582Z,1602897427.582 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T01:19:12.839Z,1602897552.839 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:19:12.839Z,1602897552.839 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:19:12.839Z,1602897552.839 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:19:12.839Z,1602897552.839 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:19:13.253Z,1602897553.253 [Default:CheckIn:D] Stopped 2020-10-17T01:19:13.253Z,1602897553.253 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:19:13.648Z,1602897553.648 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.707682 min 2020-10-17T01:19:13.648Z,1602897553.648 [Default:CheckIn:E] Stopped 2020-10-17T01:19:13.648Z,1602897553.648 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:19:13.648Z,1602897553.648 [Default:CheckIn] Stopped 2020-10-17T01:19:13.648Z,1602897553.648 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:19:13.648Z,1602897553.648 [Default:CheckIn](INFO): Running loop #14 2020-10-17T01:19:13.649Z,1602897553.649 [Default:CheckIn] Running Loop=14 2020-10-17T01:19:13.649Z,1602897553.649 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:19:13.649Z,1602897553.649 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:19:15.664Z,1602897555.664 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011921.00,A,3648.16745,N,12147.28151,W,0.311,118.10,171020,,,A*7F 2020-10-17T01:19:15.666Z,1602897555.666 [NAL9602](INFO): GPS fix at 20201017T011921: (36.802791, -121.788025) 2020-10-17T01:19:15.676Z,1602897555.676 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:19:15.677Z,1602897555.677 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:19:23.484Z,1602897563.484 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201017T001315/Courier0033.lzma 2020-10-17T01:19:24.487Z,1602897564.487 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Courier0033.lzma.bak 2020-10-17T01:19:24.487Z,1602897564.487 [DataOverHttps](INFO): SBD MOMSN=12919460 2020-10-17T01:19:40.265Z,1602897580.265 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20201017T001315/Express0034.lzma 2020-10-17T01:19:41.267Z,1602897581.267 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Express0034.lzma.bak 2020-10-17T01:19:41.267Z,1602897581.267 [DataOverHttps](INFO): SBD MOMSN=12919463 2020-10-17T01:19:41.917Z,1602897581.917 [NAL9602](INFO): SBD MO Status=2, MOMSN=11125, MT Status=2, MTMSN=0 2020-10-17T01:19:41.918Z,1602897581.918 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:19:42.748Z,1602897582.748 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:19:42.749Z,1602897582.749 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T01:19:42.749Z,1602897582.749 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T01:19:59.731Z,1602897599.731 [NAL9602](INFO): SBD MO Status=0, MOMSN=11125, MT Status=0, MTMSN=0 2020-10-17T01:19:59.732Z,1602897599.732 [NAL9602](INFO): No messages in MT queue 2020-10-17T01:20:30.412Z,1602897630.412 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T01:24:43.343Z,1602897883.343 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:24:43.344Z,1602897883.344 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:24:43.344Z,1602897883.344 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:24:43.344Z,1602897883.344 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:24:43.750Z,1602897883.750 [Default:CheckIn:D] Stopped 2020-10-17T01:24:43.750Z,1602897883.750 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:24:44.148Z,1602897884.148 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.215951 min 2020-10-17T01:24:44.148Z,1602897884.148 [Default:CheckIn:E] Stopped 2020-10-17T01:24:44.148Z,1602897884.148 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:24:44.148Z,1602897884.148 [Default:CheckIn] Stopped 2020-10-17T01:24:44.149Z,1602897884.149 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:24:44.149Z,1602897884.149 [Default:CheckIn](INFO): Running loop #15 2020-10-17T01:24:44.149Z,1602897884.149 [Default:CheckIn] Running Loop=15 2020-10-17T01:24:44.149Z,1602897884.149 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:24:44.149Z,1602897884.149 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:24:46.159Z,1602897886.159 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012452.00,A,3648.16642,N,12147.28184,W,0.019,118.10,171020,,,A*70 2020-10-17T01:24:46.161Z,1602897886.161 [NAL9602](INFO): GPS fix at 20201017T012452: (36.802774, -121.788031) 2020-10-17T01:24:46.171Z,1602897886.171 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:24:46.171Z,1602897886.171 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:24:53.325Z,1602897893.325 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20201017T001315/Courier0036.lzma 2020-10-17T01:24:54.327Z,1602897894.327 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Courier0036.lzma.bak 2020-10-17T01:24:54.327Z,1602897894.327 [DataOverHttps](INFO): SBD MOMSN=12919473 2020-10-17T01:25:10.037Z,1602897910.037 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201017T001315/Express0037.lzma 2020-10-17T01:25:11.039Z,1602897911.039 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Express0037.lzma.bak 2020-10-17T01:25:11.039Z,1602897911.039 [DataOverHttps](INFO): SBD MOMSN=12919476 2020-10-17T01:25:12.430Z,1602897912.430 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:25:12.430Z,1602897912.430 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T01:25:12.430Z,1602897912.430 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T01:26:13.828Z,1602897973.828 [NAL9602](INFO): SBD MO Status=2, MOMSN=11126, MT Status=2, MTMSN=0 2020-10-17T01:26:13.828Z,1602897973.828 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:26:48.172Z,1602898008.172 [NAL9602](INFO): SBD MO Status=2, MOMSN=11126, MT Status=2, MTMSN=0 2020-10-17T01:26:48.172Z,1602898008.172 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:27:57.279Z,1602898077.279 [NAL9602](INFO): SBD MO Status=2, MOMSN=11126, MT Status=2, MTMSN=0 2020-10-17T01:27:57.280Z,1602898077.280 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:29:48.376Z,1602898188.376 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-10-17T01:30:13.031Z,1602898213.031 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:30:13.032Z,1602898213.032 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:30:13.032Z,1602898213.032 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:30:13.032Z,1602898213.032 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:30:13.436Z,1602898213.436 [Default:CheckIn:D] Stopped 2020-10-17T01:30:13.436Z,1602898213.436 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:30:13.840Z,1602898213.840 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.710726 min 2020-10-17T01:30:13.840Z,1602898213.840 [Default:CheckIn:E] Stopped 2020-10-17T01:30:13.840Z,1602898213.840 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:30:13.840Z,1602898213.840 [Default:CheckIn] Stopped 2020-10-17T01:30:13.841Z,1602898213.841 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:30:13.841Z,1602898213.841 [Default:CheckIn](INFO): Running loop #16 2020-10-17T01:30:13.841Z,1602898213.841 [Default:CheckIn] Running Loop=16 2020-10-17T01:30:13.841Z,1602898213.841 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:30:13.841Z,1602898213.841 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:30:15.848Z,1602898215.848 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013022.00,A,3648.16001,N,12147.29223,W,1.516,146.12,171020,,,D*7B 2020-10-17T01:30:15.850Z,1602898215.850 [NAL9602](INFO): GPS fix at 20201017T013022: (36.802667, -121.788204) 2020-10-17T01:30:15.882Z,1602898215.882 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:30:15.882Z,1602898215.882 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:30:25.136Z,1602898225.136 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201017T001315/Courier0039.lzma 2020-10-17T01:30:26.139Z,1602898226.139 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Courier0039.lzma.bak 2020-10-17T01:30:26.139Z,1602898226.139 [DataOverHttps](INFO): SBD MOMSN=12919537 2020-10-17T01:30:41.997Z,1602898241.997 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20201017T001315/Express0040.lzma 2020-10-17T01:30:42.999Z,1602898242.999 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Express0040.lzma.bak 2020-10-17T01:30:42.999Z,1602898242.999 [DataOverHttps](INFO): SBD MOMSN=12919540 2020-10-17T01:30:44.549Z,1602898244.549 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:30:44.550Z,1602898244.550 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T01:30:44.550Z,1602898244.550 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T01:30:48.570Z,1602898248.570 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T01:35:45.123Z,1602898545.123 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:35:45.124Z,1602898545.124 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:35:45.124Z,1602898545.124 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:35:45.124Z,1602898545.124 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:35:45.531Z,1602898545.531 [Default:CheckIn:D] Stopped 2020-10-17T01:35:45.532Z,1602898545.532 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:35:45.928Z,1602898545.928 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.245654 min 2020-10-17T01:35:45.928Z,1602898545.928 [Default:CheckIn:E] Stopped 2020-10-17T01:35:45.928Z,1602898545.928 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:35:45.928Z,1602898545.928 [Default:CheckIn] Stopped 2020-10-17T01:35:45.929Z,1602898545.929 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:35:45.930Z,1602898545.930 [Default:CheckIn](INFO): Running loop #17 2020-10-17T01:35:45.930Z,1602898545.930 [Default:CheckIn] Running Loop=17 2020-10-17T01:35:45.930Z,1602898545.930 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:35:45.930Z,1602898545.930 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:35:47.941Z,1602898547.941 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013554.00,A,3648.16582,N,12147.29375,W,0.505,352.55,171020,,,D*74 2020-10-17T01:35:47.943Z,1602898547.943 [NAL9602](INFO): GPS fix at 20201017T013554: (36.802764, -121.788229) 2020-10-17T01:35:47.954Z,1602898547.954 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:35:47.954Z,1602898547.954 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:35:55.377Z,1602898555.377 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201017T001315/Courier0042.lzma 2020-10-17T01:35:56.379Z,1602898556.379 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Courier0042.lzma.bak 2020-10-17T01:35:56.379Z,1602898556.379 [DataOverHttps](INFO): SBD MOMSN=12919551 2020-10-17T01:36:12.137Z,1602898572.137 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201017T001315/Express0043.lzma 2020-10-17T01:36:13.571Z,1602898573.571 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001315/Express0043.lzma.bak 2020-10-17T01:36:13.571Z,1602898573.571 [DataOverHttps](INFO): SBD MOMSN=12919554 2020-10-17T01:36:14.620Z,1602898574.620 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:36:14.620Z,1602898574.620 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T01:36:14.620Z,1602898574.620 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T01:36:18.638Z,1602898578.638 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2020-10-17T01:36:18.713Z,1602898578.713 [NAL9602](FAULT): received: +CSQ:0 OK126, 2, 0, 0, 0 OK 2020-10-17T01:36:18.713Z,1602898578.713 [NAL9602] Data Fault, FailCount= 1 2020-10-17T01:36:18.713Z,1602898578.713 [NAL9602](ERROR): Data Fault 2020-10-17T01:36:18.810Z,1602898578.810 [CBIT](ERROR): Data Fault in component: NAL9602 2020-10-17T01:36:19.046Z,1602898579.046 [NAL9602](INFO): Powering down 2020-10-17T01:36:19.885Z,1602898579.885 [CBIT](INFO): Clearing failed state for component NAL9602 2020-10-17T01:36:19.885Z,1602898579.885 [NAL9602] No Fault, FailCount= 1 2020-10-17T01:36:49.346Z,1602898609.346 [NAL9602](INFO): Powering up NAL9602 2020-10-17T01:37:00.255Z,1602898620.255 [NAL9602](INFO): NAL9602 initialized 2020-10-17T01:37:31.357Z,1602898651.357 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T01:38:40.154Z,1602898720.154 [CommandLine](IMPORTANT): got command quit 2020-10-17T01:38:41.161Z,1602898721.161 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:38:41.162Z,1602898721.162 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:41.189Z,1602898721.189 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-10-17T01:38:41.189Z,1602898721.189 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:41.190Z,1602898721.190 [CommandLine](INFO): Join timeout helper Thread ID is 5526 2020-10-17T01:38:41.197Z,1602898721.197 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-10-17T01:38:41.197Z,1602898721.197 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:41.198Z,1602898721.198 [NavChartDb](INFO): Join timeout helper Thread ID is 5527 2020-10-17T01:38:41.541Z,1602898721.541 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:38:41.541Z,1602898721.541 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:41.557Z,1602898721.557 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2020-10-17T01:38:41.557Z,1602898721.557 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:41.557Z,1602898721.557 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 5528 2020-10-17T01:38:41.861Z,1602898721.861 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:38:41.862Z,1602898721.862 [WetLabsUBAT](INFO): Powering down 2020-10-17T01:38:41.862Z,1602898721.862 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:41.877Z,1602898721.877 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-10-17T01:38:41.877Z,1602898721.877 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:41.877Z,1602898721.877 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5529 2020-10-17T01:38:42.109Z,1602898722.109 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:38:42.110Z,1602898722.110 [WetLabsBB2FL](INFO): Powering down 2020-10-17T01:38:42.110Z,1602898722.110 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:42.129Z,1602898722.129 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-10-17T01:38:42.129Z,1602898722.129 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:42.129Z,1602898722.129 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5530 2020-10-17T01:38:42.693Z,1602898722.693 [CTD_Seabird](INFO): Powering down 2020-10-17T01:38:42.705Z,1602898722.705 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:38:42.705Z,1602898722.705 [CTD_Seabird](INFO): Powering down 2020-10-17T01:38:42.725Z,1602898722.725 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:42.726Z,1602898722.726 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-10-17T01:38:42.726Z,1602898722.726 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:42.727Z,1602898722.727 [Radio_Surface](INFO): Join timeout helper Thread ID is 5531 2020-10-17T01:38:43.069Z,1602898723.069 [Radio_Surface](INFO): Powering down 2020-10-17T01:38:43.070Z,1602898723.070 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:38:43.070Z,1602898723.070 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:43.074Z,1602898723.074 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2020-10-17T01:38:43.074Z,1602898723.074 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:43.074Z,1602898723.074 [Onboard](INFO): Join timeout helper Thread ID is 5532 2020-10-17T01:38:46.813Z,1602898726.813 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:38:46.813Z,1602898726.813 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:46.834Z,1602898726.834 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-10-17T01:38:46.834Z,1602898726.834 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:46.834Z,1602898726.834 [DataOverHttps](INFO): Join timeout helper Thread ID is 5533 2020-10-17T01:38:47.197Z,1602898727.197 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:38:47.201Z,1602898727.201 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:47.218Z,1602898727.218 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2020-10-17T01:38:47.218Z,1602898727.218 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:47.218Z,1602898727.218 [BackseatComponent](INFO): Join timeout helper Thread ID is 5534 2020-10-17T01:38:47.345Z,1602898727.345 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:38:47.346Z,1602898727.346 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:47.370Z,1602898727.370 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-10-17T01:38:47.370Z,1602898727.370 [logger ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:47.370Z,1602898727.370 [logger](INFO): Join timeout helper Thread ID is 5535 2020-10-17T01:38:47.398Z,1602898727.398 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:38:47.398Z,1602898727.398 [logger ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:47.402Z,1602898727.402 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-10-17T01:38:47.402Z,1602898727.402 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:47.402Z,1602898727.402 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-10-17T01:38:47.402Z,1602898727.402 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:47.403Z,1602898727.403 [controlThread](INFO): Join timeout helper Thread ID is 5536 2020-10-17T01:38:47.729Z,1602898727.729 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:38:47.729Z,1602898727.729 [controlThread](DEBUG): Uninitializing ControlThread 2020-10-17T01:38:47.730Z,1602898727.730 [AHRS_M2](INFO): Powering down 2020-10-17T01:38:47.802Z,1602898727.802 [NAL9602](INFO): Powering down 2020-10-17T01:38:47.803Z,1602898727.803 [DAT](INFO): Powering down 2020-10-17T01:38:47.922Z,1602898727.922 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-10-17T01:38:47.923Z,1602898727.923 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-10-17T01:38:47.923Z,1602898727.923 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-10-17T01:38:47.924Z,1602898727.924 [MissionManager](INFO): Uninitializing Mission Default 2020-10-17T01:38:47.924Z,1602898727.924 [Default] Stopped 2020-10-17T01:38:47.924Z,1602898727.924 [Default](DEBUG): Aggregate::uninitialize Default 2020-10-17T01:38:47.924Z,1602898727.924 [Default:B.GoToSurface] Stopped 2020-10-17T01:38:47.924Z,1602898727.924 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-10-17T01:38:47.924Z,1602898727.924 [Default:CheckIn] Stopped 2020-10-17T01:38:47.924Z,1602898727.924 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:38:47.924Z,1602898727.924 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:38:47.924Z,1602898727.924 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:38:47.927Z,1602898727.927 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-10-17T01:38:47.927Z,1602898727.927 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-10-17T01:38:47.928Z,1602898727.928 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-10-17T01:38:47.928Z,1602898727.928 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-10-17T01:38:47.928Z,1602898727.928 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-10-17T01:38:47.928Z,1602898727.928 [BuoyancyServo](INFO): Powering down 2020-10-17T01:38:47.941Z,1602898727.941 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-10-17T01:38:47.941Z,1602898727.941 [ElevatorServo](INFO): Powering down 2020-10-17T01:38:47.942Z,1602898727.942 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-10-17T01:38:47.942Z,1602898727.942 [MassServo](INFO): Powering down 2020-10-17T01:38:47.943Z,1602898727.943 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-10-17T01:38:47.943Z,1602898727.943 [RudderServo](INFO): Powering down 2020-10-17T01:38:47.944Z,1602898727.944 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-10-17T01:38:47.944Z,1602898727.944 [ThrusterServo](INFO): Powering down 2020-10-17T01:38:47.945Z,1602898727.945 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-10-17T01:38:47.946Z,1602898727.946 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-10-17T01:38:47.946Z,1602898727.946 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-10-17T01:38:47.946Z,1602898727.946 [CBIT](DEBUG): Powering off loads. 2020-10-17T01:38:47.957Z,1602898727.957 [CBIT](DEBUG): Disabling WDT. 2020-10-17T01:38:47.969Z,1602898727.969 [CBIT](DEBUG): Opening all GF detection circuits. 2020-10-17T01:38:47.970Z,1602898727.970 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:48.033Z,1602898728.033 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:48.035Z,1602898728.035 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:48.041Z,1602898728.041 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:48.083Z,1602898728.083 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:48.145Z,1602898728.145 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:48.147Z,1602898728.147 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:48.151Z,1602898728.151 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:48.159Z,1602898728.159 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:38:48.265Z,1602898728.265 [logger ThreadHandler](INFO): Thread cancelled.