2020-10-17T00:18:06.437Z,1602893886.437 [CommandLine](IMPORTANT): got command restart logs 2020-10-17T00:18:08.314Z,1602893888.314 [NAL9602](INFO): SBD MO Status=2, MOMSN=41117, MT Status=2, MTMSN=0 2020-10-17T00:18:08.314Z,1602893888.314 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:18:17.856Z,1602893897.856 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20201016T210715/Express0031.lzma 2020-10-17T00:18:38.937Z,1602893918.937 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2020-10-17T00:18:46.695Z,1602893926.695 [NAL9602](INFO): SBD MO Status=2, MOMSN=41117, MT Status=2, MTMSN=0 2020-10-17T00:18:46.695Z,1602893926.695 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:19:03.259Z,1602893943.259 [NAL9602](INFO): SBD MO Status=0, MOMSN=41117, MT Status=0, MTMSN=0 2020-10-17T00:19:03.260Z,1602893943.260 [NAL9602](INFO): No messages in MT queue 2020-10-17T00:19:10.073Z,1602893950.073 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:19:33.963Z,1602893973.963 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T00:19:41.205Z,1602893981.205 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:20:12.329Z,1602894012.329 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:20:43.453Z,1602894043.453 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:20:54.029Z,1602894054.029 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20201016T210715/Express0031.lzma 2020-10-17T00:20:55.031Z,1602894055.031 [DataOverHttps](INFO): Moved sent file to Logs/20201016T210715/Express0031.lzma.bak 2020-10-17T00:20:55.031Z,1602894055.031 [DataOverHttps](INFO): SBD MOMSN=12919009 2020-10-17T00:21:11.685Z,1602894071.685 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201016T210715/Courier0033.lzma 2020-10-17T00:21:12.687Z,1602894072.687 [DataOverHttps](INFO): Moved sent file to Logs/20201016T210715/Courier0033.lzma.bak 2020-10-17T00:21:12.687Z,1602894072.687 [DataOverHttps](INFO): SBD MOMSN=12919025 2020-10-17T00:21:28.405Z,1602894088.405 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20201016T210715/Express0034.lzma 2020-10-17T00:21:29.407Z,1602894089.407 [DataOverHttps](INFO): Moved sent file to Logs/20201016T210715/Express0034.lzma.bak 2020-10-17T00:21:29.407Z,1602894089.407 [DataOverHttps](INFO): SBD MOMSN=12919027 2020-10-17T00:21:31.974Z,1602894091.974 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T00:21:31.975Z,1602894091.975 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T00:21:31.975Z,1602894091.975 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T00:24:15.665Z,1602894255.665 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:24:46.801Z,1602894286.801 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:25:17.945Z,1602894317.945 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:25:49.069Z,1602894349.069 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:26:20.193Z,1602894380.193 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:26:31.246Z,1602894391.246 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.767426 2020-10-17T00:26:32.524Z,1602894392.524 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T00:26:32.524Z,1602894392.524 [Default:CheckIn:C.Wait] Stopped 2020-10-17T00:26:32.524Z,1602894392.524 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T00:26:32.525Z,1602894392.525 [Default:CheckIn:D] Running Loop=1 2020-10-17T00:26:32.927Z,1602894392.927 [Default:CheckIn:D] Stopped 2020-10-17T00:26:32.927Z,1602894392.927 [Default:CheckIn:E] Running Loop=1 2020-10-17T00:26:33.339Z,1602894393.339 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.788858 min 2020-10-17T00:26:33.339Z,1602894393.339 [Default:CheckIn:E] Stopped 2020-10-17T00:26:33.339Z,1602894393.339 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T00:26:33.339Z,1602894393.339 [Default:CheckIn] Stopped 2020-10-17T00:26:33.340Z,1602894393.340 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T00:26:33.340Z,1602894393.340 [Default:CheckIn](INFO): Running loop #3 2020-10-17T00:26:33.340Z,1602894393.340 [Default:CheckIn] Running Loop=3 2020-10-17T00:26:33.340Z,1602894393.340 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T00:26:33.340Z,1602894393.340 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T00:26:35.334Z,1602894395.334 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002547.00,A,3649.03082,N,12151.69012,W,21.168,289.46,171020,,,A*4B 2020-10-17T00:26:35.336Z,1602894395.336 [NAL9602](INFO): GPS fix at 20201017T002547: (36.817180, -121.861502) 2020-10-17T00:26:35.347Z,1602894395.347 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T00:26:35.347Z,1602894395.347 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T00:26:58.907Z,1602894418.907 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004444 2020-10-17T00:27:06.265Z,1602894426.265 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20201017T001806/Courier0003.lzma 2020-10-17T00:27:07.267Z,1602894427.267 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Courier0003.lzma.bak 2020-10-17T00:27:07.267Z,1602894427.267 [DataOverHttps](INFO): SBD MOMSN=12919083 2020-10-17T00:27:19.938Z,1602894439.938 [NAL9602](INFO): SBD MO Status=1, MOMSN=41118, MT Status=0, MTMSN=0 2020-10-17T00:27:19.985Z,1602894439.985 [NAL9602](INFO): Sent 267 bytes from file Logs/20201017T001806/Courier0003.lzma 2020-10-17T00:27:19.985Z,1602894439.985 [NAL9602](INFO): Packets left to send: 0 2020-10-17T00:27:23.841Z,1602894443.841 [DataOverHttps](INFO): Sending 398 bytes from file Logs/20201017T001806/Express0004.lzma 2020-10-17T00:27:24.843Z,1602894444.843 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Express0004.lzma.bak 2020-10-17T00:27:24.843Z,1602894444.843 [DataOverHttps](INFO): SBD MOMSN=12919087 2020-10-17T00:27:26.792Z,1602894446.792 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T00:27:26.792Z,1602894446.792 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T00:27:26.792Z,1602894446.792 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T00:27:50.646Z,1602894470.646 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T00:28:57.397Z,1602894537.397 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:29:28.541Z,1602894568.541 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:29:59.665Z,1602894599.665 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:30:30.793Z,1602894630.793 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:31:01.917Z,1602894661.917 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:31:06.938Z,1602894666.938 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.743668 2020-10-17T00:32:03.185Z,1602894723.185 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:32:27.396Z,1602894747.396 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T00:32:27.396Z,1602894747.396 [Default:CheckIn:C.Wait] Stopped 2020-10-17T00:32:27.396Z,1602894747.396 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T00:32:27.396Z,1602894747.396 [Default:CheckIn:D] Running Loop=1 2020-10-17T00:32:27.806Z,1602894747.806 [Default:CheckIn:D] Stopped 2020-10-17T00:32:27.807Z,1602894747.807 [Default:CheckIn:E] Running Loop=1 2020-10-17T00:32:28.217Z,1602894748.217 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.703516 min 2020-10-17T00:32:28.217Z,1602894748.217 [Default:CheckIn:E] Stopped 2020-10-17T00:32:28.217Z,1602894748.217 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T00:32:28.217Z,1602894748.217 [Default:CheckIn] Stopped 2020-10-17T00:32:28.217Z,1602894748.217 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T00:32:28.217Z,1602894748.217 [Default:CheckIn](INFO): Running loop #4 2020-10-17T00:32:28.217Z,1602894748.217 [Default:CheckIn] Running Loop=4 2020-10-17T00:32:28.218Z,1602894748.218 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T00:32:28.218Z,1602894748.218 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T00:32:30.214Z,1602894750.214 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003142.00,A,3649.54677,N,12152.48676,W,0.603,92.23,171020,,,A*43 2020-10-17T00:32:30.217Z,1602894750.217 [NAL9602](INFO): GPS fix at 20201017T003142: (36.825780, -121.874779) 2020-10-17T00:32:30.227Z,1602894750.227 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T00:32:30.227Z,1602894750.227 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T00:32:34.321Z,1602894754.321 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:32:52.204Z,1602894772.204 [NAL9602](INFO): SBD MO Status=1, MOMSN=41119, MT Status=0, MTMSN=0 2020-10-17T00:32:52.257Z,1602894772.257 [NAL9602](INFO): Sent 74 bytes from file Logs/20201017T001806/Courier0006.lzma 2020-10-17T00:32:52.257Z,1602894772.257 [NAL9602](INFO): Packets left to send: 0 2020-10-17T00:33:05.445Z,1602894785.445 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:33:10.614Z,1602894790.614 [NAL9602](INFO): SBD MO Status=2, MOMSN=41120, MT Status=2, MTMSN=0 2020-10-17T00:33:10.614Z,1602894790.614 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T00:33:30.814Z,1602894810.814 [NAL9602](INFO): SBD MO Status=1, MOMSN=41120, MT Status=0, MTMSN=0 2020-10-17T00:33:30.861Z,1602894810.861 [NAL9602](INFO): Sent 146 bytes from file Logs/20201017T001806/Express0007.lzma 2020-10-17T00:33:30.861Z,1602894810.861 [NAL9602](INFO): Packets left to send: 0 2020-10-17T00:33:36.569Z,1602894816.569 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:33:37.031Z,1602894817.031 [NAL9602](INFO): SBD MO Status=0, MOMSN=41121, MT Status=0, MTMSN=0 2020-10-17T00:33:37.102Z,1602894817.102 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T00:33:37.103Z,1602894817.103 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T00:33:37.103Z,1602894817.103 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T00:34:07.693Z,1602894847.693 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:34:07.772Z,1602894847.772 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T00:34:38.833Z,1602894878.833 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:35:09.973Z,1602894909.973 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:35:41.101Z,1602894941.101 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:36:12.233Z,1602894972.233 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:36:43.357Z,1602895003.357 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:37:12.063Z,1602895032.063 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.020173 2020-10-17T00:38:15.577Z,1602895095.577 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:38:37.614Z,1602895117.614 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T00:38:37.614Z,1602895117.614 [Default:CheckIn:C.Wait] Stopped 2020-10-17T00:38:37.614Z,1602895117.614 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T00:38:37.614Z,1602895117.614 [Default:CheckIn:D] Running Loop=1 2020-10-17T00:38:38.025Z,1602895118.025 [Default:CheckIn:D] Stopped 2020-10-17T00:38:38.025Z,1602895118.025 [Default:CheckIn:E] Running Loop=1 2020-10-17T00:38:38.418Z,1602895118.418 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.873818 min 2020-10-17T00:38:38.418Z,1602895118.418 [Default:CheckIn:E] Stopped 2020-10-17T00:38:38.418Z,1602895118.418 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T00:38:38.419Z,1602895118.419 [Default:CheckIn] Stopped 2020-10-17T00:38:38.419Z,1602895118.419 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T00:38:38.419Z,1602895118.419 [Default:CheckIn](INFO): Running loop #5 2020-10-17T00:38:38.419Z,1602895118.419 [Default:CheckIn] Running Loop=5 2020-10-17T00:38:38.419Z,1602895118.419 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T00:38:38.419Z,1602895118.419 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T00:38:40.431Z,1602895120.431 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003752.00,A,3648.84925,N,12149.75155,W,29.352,107.63,171020,,,D*44 2020-10-17T00:38:40.433Z,1602895120.433 [NAL9602](INFO): GPS fix at 20201017T003752: (36.814154, -121.829193) 2020-10-17T00:38:40.443Z,1602895120.443 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T00:38:40.443Z,1602895120.443 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T00:38:47.769Z,1602895127.769 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201017T001806/Courier0009.lzma 2020-10-17T00:38:48.771Z,1602895128.771 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Courier0009.lzma.bak 2020-10-17T00:38:48.771Z,1602895128.771 [DataOverHttps](INFO): SBD MOMSN=12919166 2020-10-17T00:38:53.787Z,1602895133.787 [NAL9602](INFO): SBD MO Status=0, MOMSN=41122, MT Status=0, MTMSN=0 2020-10-17T00:38:53.787Z,1602895133.787 [NAL9602](INFO): No messages in MT queue 2020-10-17T00:39:04.652Z,1602895144.652 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20201017T001806/Express0010.lzma 2020-10-17T00:39:05.655Z,1602895145.655 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Express0010.lzma.bak 2020-10-17T00:39:05.655Z,1602895145.655 [DataOverHttps](INFO): SBD MOMSN=12919169 2020-10-17T00:39:07.920Z,1602895147.920 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T00:39:07.920Z,1602895147.920 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T00:39:07.920Z,1602895147.920 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T00:39:24.477Z,1602895164.477 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T00:40:09.385Z,1602895209.385 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:40:40.517Z,1602895240.517 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:41:11.641Z,1602895271.641 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:41:42.769Z,1602895302.769 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:42:05.861Z,1602895325.861 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.207230 2020-10-17T00:42:44.013Z,1602895364.013 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T00:42:52.527Z,1602895372.527 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004045 2020-10-17T00:44:08.549Z,1602895448.549 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T00:44:08.549Z,1602895448.549 [Default:CheckIn:C.Wait] Stopped 2020-10-17T00:44:08.549Z,1602895448.549 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T00:44:08.550Z,1602895448.550 [Default:CheckIn:D] Running Loop=1 2020-10-17T00:44:08.906Z,1602895448.906 [Default:CheckIn:D] Stopped 2020-10-17T00:44:08.906Z,1602895448.906 [Default:CheckIn:E] Running Loop=1 2020-10-17T00:44:09.315Z,1602895449.315 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.388513 min 2020-10-17T00:44:09.315Z,1602895449.315 [Default:CheckIn:E] Stopped 2020-10-17T00:44:09.315Z,1602895449.315 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T00:44:09.315Z,1602895449.315 [Default:CheckIn] Stopped 2020-10-17T00:44:09.316Z,1602895449.316 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T00:44:09.316Z,1602895449.316 [Default:CheckIn](INFO): Running loop #6 2020-10-17T00:44:09.316Z,1602895449.316 [Default:CheckIn] Running Loop=6 2020-10-17T00:44:09.316Z,1602895449.316 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T00:44:09.316Z,1602895449.316 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T00:44:11.333Z,1602895451.333 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004323.00,A,3648.51044,N,12147.15258,W,9.019,110.79,171020,,,D*72 2020-10-17T00:44:11.335Z,1602895451.335 [NAL9602](INFO): GPS fix at 20201017T004323: (36.808507, -121.785876) 2020-10-17T00:44:11.345Z,1602895451.345 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T00:44:11.345Z,1602895451.345 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T00:44:18.228Z,1602895458.228 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201017T001806/Courier0012.lzma 2020-10-17T00:44:19.231Z,1602895459.231 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Courier0012.lzma.bak 2020-10-17T00:44:19.231Z,1602895459.231 [DataOverHttps](INFO): SBD MOMSN=12919176 2020-10-17T00:44:35.192Z,1602895475.192 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20201017T001806/Express0013.lzma 2020-10-17T00:44:36.195Z,1602895476.195 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Express0013.lzma.bak 2020-10-17T00:44:36.195Z,1602895476.195 [DataOverHttps](INFO): SBD MOMSN=12919183 2020-10-17T00:44:38.503Z,1602895478.503 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T00:44:38.503Z,1602895478.503 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T00:44:38.503Z,1602895478.503 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T00:44:40.880Z,1602895480.880 [NAL9602](INFO): SBD MO Status=0, MOMSN=41123, MT Status=0, MTMSN=0 2020-10-17T00:44:40.880Z,1602895480.880 [NAL9602](INFO): No messages in MT queue 2020-10-17T00:45:11.565Z,1602895511.565 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T00:49:39.027Z,1602895779.027 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T00:49:39.027Z,1602895779.027 [Default:CheckIn:C.Wait] Stopped 2020-10-17T00:49:39.027Z,1602895779.027 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T00:49:39.027Z,1602895779.027 [Default:CheckIn:D] Running Loop=1 2020-10-17T00:49:39.428Z,1602895779.428 [Default:CheckIn:D] Stopped 2020-10-17T00:49:39.428Z,1602895779.428 [Default:CheckIn:E] Running Loop=1 2020-10-17T00:49:39.837Z,1602895779.837 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.897209 min 2020-10-17T00:49:39.837Z,1602895779.837 [Default:CheckIn:E] Stopped 2020-10-17T00:49:39.837Z,1602895779.837 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T00:49:39.837Z,1602895779.837 [Default:CheckIn] Stopped 2020-10-17T00:49:39.838Z,1602895779.838 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T00:49:39.838Z,1602895779.838 [Default:CheckIn](INFO): Running loop #7 2020-10-17T00:49:39.838Z,1602895779.838 [Default:CheckIn] Running Loop=7 2020-10-17T00:49:39.838Z,1602895779.838 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T00:49:39.838Z,1602895779.838 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T00:49:41.844Z,1602895781.844 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,004853.00,A,3648.13926,N,12147.20559,W,0.097,246.04,171020,,,D*70 2020-10-17T00:49:41.846Z,1602895781.846 [NAL9602](INFO): GPS fix at 20201017T004853: (36.802321, -121.786760) 2020-10-17T00:49:41.875Z,1602895781.875 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T00:49:41.875Z,1602895781.875 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T00:49:49.556Z,1602895789.556 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201017T001806/Courier0015.lzma 2020-10-17T00:49:50.559Z,1602895790.559 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Courier0015.lzma.bak 2020-10-17T00:49:50.559Z,1602895790.559 [DataOverHttps](INFO): SBD MOMSN=12919244 2020-10-17T00:50:06.498Z,1602895806.498 [NAL9602](INFO): SBD MO Status=0, MOMSN=41124, MT Status=0, MTMSN=0 2020-10-17T00:50:06.499Z,1602895806.499 [NAL9602](INFO): No messages in MT queue 2020-10-17T00:50:06.956Z,1602895806.956 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20201017T001806/Express0016.lzma 2020-10-17T00:50:07.959Z,1602895807.959 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Express0016.lzma.bak 2020-10-17T00:50:07.959Z,1602895807.959 [DataOverHttps](INFO): SBD MOMSN=12919247 2020-10-17T00:50:09.740Z,1602895809.740 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T00:50:09.740Z,1602895809.740 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T00:50:09.740Z,1602895809.740 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T00:50:37.203Z,1602895837.203 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T00:55:10.308Z,1602896110.308 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T00:55:10.308Z,1602896110.308 [Default:CheckIn:C.Wait] Stopped 2020-10-17T00:55:10.308Z,1602896110.308 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T00:55:10.308Z,1602896110.308 [Default:CheckIn:D] Running Loop=1 2020-10-17T00:55:10.719Z,1602896110.719 [Default:CheckIn:D] Stopped 2020-10-17T00:55:10.719Z,1602896110.719 [Default:CheckIn:E] Running Loop=1 2020-10-17T00:55:11.128Z,1602896111.128 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.418730 min 2020-10-17T00:55:11.128Z,1602896111.128 [Default:CheckIn:E] Stopped 2020-10-17T00:55:11.128Z,1602896111.128 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T00:55:11.128Z,1602896111.128 [Default:CheckIn] Stopped 2020-10-17T00:55:11.128Z,1602896111.128 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T00:55:11.128Z,1602896111.128 [Default:CheckIn](INFO): Running loop #8 2020-10-17T00:55:11.128Z,1602896111.128 [Default:CheckIn] Running Loop=8 2020-10-17T00:55:11.128Z,1602896111.128 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T00:55:11.128Z,1602896111.128 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T00:55:13.135Z,1602896113.135 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005425.00,A,3648.14060,N,12147.20936,W,0.467,229.75,171020,,,D*71 2020-10-17T00:55:13.137Z,1602896113.137 [NAL9602](INFO): GPS fix at 20201017T005425: (36.802343, -121.786823) 2020-10-17T00:55:13.167Z,1602896113.167 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T00:55:13.167Z,1602896113.167 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T00:55:20.484Z,1602896120.484 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201017T001806/Courier0018.lzma 2020-10-17T00:55:21.487Z,1602896121.487 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Courier0018.lzma.bak 2020-10-17T00:55:21.487Z,1602896121.487 [DataOverHttps](INFO): SBD MOMSN=12919258 2020-10-17T00:55:39.860Z,1602896139.860 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20201017T001806/Express0019.lzma 2020-10-17T00:55:40.863Z,1602896140.863 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Express0019.lzma.bak 2020-10-17T00:55:40.863Z,1602896140.863 [DataOverHttps](INFO): SBD MOMSN=12919261 2020-10-17T00:55:42.640Z,1602896142.640 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T00:55:42.640Z,1602896142.640 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T00:55:42.640Z,1602896142.640 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T00:55:49.496Z,1602896149.496 [NAL9602](INFO): SBD MO Status=0, MOMSN=41125, MT Status=0, MTMSN=0 2020-10-17T00:55:49.496Z,1602896149.496 [NAL9602](INFO): No messages in MT queue 2020-10-17T00:56:20.197Z,1602896180.197 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T01:00:43.242Z,1602896443.242 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:00:43.242Z,1602896443.242 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:00:43.242Z,1602896443.242 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:00:43.242Z,1602896443.242 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:00:43.638Z,1602896443.638 [Default:CheckIn:D] Stopped 2020-10-17T01:00:43.638Z,1602896443.638 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:00:44.048Z,1602896444.048 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.967379 min 2020-10-17T01:00:44.048Z,1602896444.048 [Default:CheckIn:E] Stopped 2020-10-17T01:00:44.048Z,1602896444.048 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:00:44.048Z,1602896444.048 [Default:CheckIn] Stopped 2020-10-17T01:00:44.048Z,1602896444.048 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:00:44.048Z,1602896444.048 [Default:CheckIn](INFO): Running loop #9 2020-10-17T01:00:44.048Z,1602896444.048 [Default:CheckIn] Running Loop=9 2020-10-17T01:00:44.048Z,1602896444.048 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:00:44.049Z,1602896444.049 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:00:46.055Z,1602896446.055 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,005958.00,A,3648.14000,N,12147.22867,W,0.078,297.81,171020,,,D*73 2020-10-17T01:00:46.057Z,1602896446.057 [NAL9602](INFO): GPS fix at 20201017T005958: (36.802333, -121.787144) 2020-10-17T01:00:46.072Z,1602896446.072 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:00:46.072Z,1602896446.072 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:00:53.337Z,1602896453.337 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201017T001806/Courier0021.lzma 2020-10-17T01:00:54.339Z,1602896454.339 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Courier0021.lzma.bak 2020-10-17T01:00:54.339Z,1602896454.339 [DataOverHttps](INFO): SBD MOMSN=12919319 2020-10-17T01:00:59.383Z,1602896459.383 [NAL9602](INFO): SBD MO Status=0, MOMSN=41126, MT Status=0, MTMSN=0 2020-10-17T01:00:59.383Z,1602896459.383 [NAL9602](INFO): No messages in MT queue 2020-10-17T01:01:10.360Z,1602896470.360 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20201017T001806/Express0022.lzma 2020-10-17T01:01:11.363Z,1602896471.363 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Express0022.lzma.bak 2020-10-17T01:01:11.363Z,1602896471.363 [DataOverHttps](INFO): SBD MOMSN=12919322 2020-10-17T01:01:13.131Z,1602896473.131 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:01:13.132Z,1602896473.132 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T01:01:13.132Z,1602896473.132 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T01:01:30.087Z,1602896490.087 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T01:06:13.702Z,1602896773.702 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:06:13.702Z,1602896773.702 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:06:13.702Z,1602896773.702 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:06:13.703Z,1602896773.703 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:06:14.111Z,1602896774.111 [Default:CheckIn:D] Stopped 2020-10-17T01:06:14.111Z,1602896774.111 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:06:14.517Z,1602896774.517 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.475264 min 2020-10-17T01:06:14.517Z,1602896774.517 [Default:CheckIn:E] Stopped 2020-10-17T01:06:14.517Z,1602896774.517 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:06:14.518Z,1602896774.518 [Default:CheckIn] Stopped 2020-10-17T01:06:14.518Z,1602896774.518 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:06:14.518Z,1602896774.518 [Default:CheckIn](INFO): Running loop #10 2020-10-17T01:06:14.518Z,1602896774.518 [Default:CheckIn] Running Loop=10 2020-10-17T01:06:14.518Z,1602896774.518 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:06:14.518Z,1602896774.518 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:06:16.527Z,1602896776.527 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,010528.00,A,3648.17451,N,12147.27942,W,0.078,278.42,171020,,,D*72 2020-10-17T01:06:16.530Z,1602896776.530 [NAL9602](INFO): GPS fix at 20201017T010528: (36.802909, -121.787990) 2020-10-17T01:06:16.559Z,1602896776.559 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:06:16.559Z,1602896776.559 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:06:23.768Z,1602896783.768 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201017T001806/Courier0024.lzma 2020-10-17T01:06:24.771Z,1602896784.771 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Courier0024.lzma.bak 2020-10-17T01:06:24.771Z,1602896784.771 [DataOverHttps](INFO): SBD MOMSN=12919339 2020-10-17T01:06:34.318Z,1602896794.318 [NAL9602](INFO): SBD MO Status=0, MOMSN=41127, MT Status=0, MTMSN=0 2020-10-17T01:06:34.318Z,1602896794.318 [NAL9602](INFO): No messages in MT queue 2020-10-17T01:06:41.280Z,1602896801.280 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20201017T001806/Express0025.lzma 2020-10-17T01:06:42.283Z,1602896802.283 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Express0025.lzma.bak 2020-10-17T01:06:42.283Z,1602896802.283 [DataOverHttps](INFO): SBD MOMSN=12919381 2020-10-17T01:06:44.017Z,1602896804.017 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:06:44.017Z,1602896804.017 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T01:06:44.017Z,1602896804.017 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T01:07:05.006Z,1602896825.006 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T01:11:44.579Z,1602897104.579 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:11:44.579Z,1602897104.579 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:11:44.579Z,1602897104.579 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:11:44.580Z,1602897104.580 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:11:44.988Z,1602897104.988 [Default:CheckIn:D] Stopped 2020-10-17T01:11:44.988Z,1602897104.988 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:11:45.405Z,1602897105.405 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.989880 min 2020-10-17T01:11:45.405Z,1602897105.405 [Default:CheckIn:E] Stopped 2020-10-17T01:11:45.406Z,1602897105.406 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:11:45.406Z,1602897105.406 [Default:CheckIn] Stopped 2020-10-17T01:11:45.406Z,1602897105.406 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:11:45.406Z,1602897105.406 [Default:CheckIn](INFO): Running loop #11 2020-10-17T01:11:45.406Z,1602897105.406 [Default:CheckIn] Running Loop=11 2020-10-17T01:11:45.406Z,1602897105.406 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:11:45.406Z,1602897105.406 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:11:47.402Z,1602897107.402 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011059.00,A,3648.16954,N,12147.28138,W,0.486,171.32,171020,,,A*7E 2020-10-17T01:11:47.404Z,1602897107.404 [NAL9602](INFO): GPS fix at 20201017T011059: (36.802826, -121.788023) 2020-10-17T01:11:47.414Z,1602897107.414 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:11:47.415Z,1602897107.415 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:11:55.248Z,1602897115.248 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201017T001806/Courier0027.lzma 2020-10-17T01:11:56.251Z,1602897116.251 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Courier0027.lzma.bak 2020-10-17T01:11:56.251Z,1602897116.251 [DataOverHttps](INFO): SBD MOMSN=12919393 2020-10-17T01:12:12.128Z,1602897132.128 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20201017T001806/Express0028.lzma 2020-10-17T01:12:13.131Z,1602897133.131 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Express0028.lzma.bak 2020-10-17T01:12:13.132Z,1602897133.132 [DataOverHttps](INFO): SBD MOMSN=12919396 2020-10-17T01:12:14.896Z,1602897134.896 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:12:14.896Z,1602897134.896 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T01:12:14.896Z,1602897134.896 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T01:12:36.704Z,1602897156.704 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-10-17T01:12:48.455Z,1602897168.455 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-10-17T01:13:00.136Z,1602897180.136 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-10-17T01:13:11.054Z,1602897191.054 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-10-17T01:13:22.355Z,1602897202.355 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-10-17T01:13:33.328Z,1602897213.328 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-10-17T01:13:44.671Z,1602897224.671 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-10-17T01:13:55.907Z,1602897235.907 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2020-10-17T01:16:30.648Z,1602897390.648 [NAL9602](INFO): SBD MO Status=0, MOMSN=41128, MT Status=0, MTMSN=0 2020-10-17T01:16:30.649Z,1602897390.649 [NAL9602](INFO): No messages in MT queue 2020-10-17T01:17:01.340Z,1602897421.340 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T01:17:15.487Z,1602897435.487 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:17:15.487Z,1602897435.487 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:17:15.487Z,1602897435.487 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:17:15.488Z,1602897435.488 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:17:15.964Z,1602897435.964 [Default:CheckIn:D] Stopped 2020-10-17T01:17:15.964Z,1602897435.964 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:17:16.320Z,1602897436.320 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.506136 min 2020-10-17T01:17:16.320Z,1602897436.320 [Default:CheckIn:E] Stopped 2020-10-17T01:17:16.320Z,1602897436.320 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:17:16.320Z,1602897436.320 [Default:CheckIn] Stopped 2020-10-17T01:17:16.320Z,1602897436.320 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:17:16.320Z,1602897436.320 [Default:CheckIn](INFO): Running loop #12 2020-10-17T01:17:16.320Z,1602897436.320 [Default:CheckIn] Running Loop=12 2020-10-17T01:17:16.321Z,1602897436.321 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:17:16.321Z,1602897436.321 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:17:18.312Z,1602897438.312 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,011630.00,A,3648.17231,N,12147.28326,W,0.156,171.32,171020,,,A*7B 2020-10-17T01:17:18.315Z,1602897438.315 [NAL9602](INFO): GPS fix at 20201017T011630: (36.802872, -121.788054) 2020-10-17T01:17:18.350Z,1602897438.350 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:17:18.350Z,1602897438.350 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:17:25.632Z,1602897445.632 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20201017T001806/Courier0030.lzma 2020-10-17T01:17:26.635Z,1602897446.635 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Courier0030.lzma.bak 2020-10-17T01:17:26.635Z,1602897446.635 [DataOverHttps](INFO): SBD MOMSN=12919451 2020-10-17T01:17:41.763Z,1602897461.763 [NAL9602](INFO): SBD MO Status=0, MOMSN=41129, MT Status=0, MTMSN=0 2020-10-17T01:17:41.763Z,1602897461.763 [NAL9602](INFO): No messages in MT queue 2020-10-17T01:17:47.036Z,1602897467.036 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20201017T001806/Express0031.lzma 2020-10-17T01:17:48.039Z,1602897468.039 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Express0031.lzma.bak 2020-10-17T01:17:48.039Z,1602897468.039 [DataOverHttps](INFO): SBD MOMSN=12919454 2020-10-17T01:17:49.852Z,1602897469.852 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:17:49.852Z,1602897469.852 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T01:17:49.852Z,1602897469.852 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T01:18:12.460Z,1602897492.460 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T01:19:08.241Z,1602897548.241 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2020-10-17T01:19:08.242Z,1602897548.242 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2020-10-17T01:19:08.244Z,1602897548.244 [BPC1](INFO): Received data from all battery sticks. 2020-10-17T01:22:50.431Z,1602897770.431 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:22:50.431Z,1602897770.431 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:22:50.431Z,1602897770.431 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:22:50.431Z,1602897770.431 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:22:50.840Z,1602897770.840 [Default:CheckIn:D] Stopped 2020-10-17T01:22:50.840Z,1602897770.840 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:22:51.240Z,1602897771.240 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.087410 min 2020-10-17T01:22:51.240Z,1602897771.240 [Default:CheckIn:E] Stopped 2020-10-17T01:22:51.240Z,1602897771.240 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:22:51.240Z,1602897771.240 [Default:CheckIn] Stopped 2020-10-17T01:22:51.240Z,1602897771.240 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:22:51.240Z,1602897771.240 [Default:CheckIn](INFO): Running loop #13 2020-10-17T01:22:51.240Z,1602897771.240 [Default:CheckIn] Running Loop=13 2020-10-17T01:22:51.240Z,1602897771.240 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:22:51.240Z,1602897771.240 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:22:53.250Z,1602897773.250 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012205.00,A,3648.16753,N,12147.28425,W,0.330,171.32,171020,,,A*7C 2020-10-17T01:22:53.252Z,1602897773.252 [NAL9602](INFO): GPS fix at 20201017T012205: (36.802792, -121.788071) 2020-10-17T01:22:53.262Z,1602897773.262 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:22:53.263Z,1602897773.263 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:23:00.221Z,1602897780.221 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20201017T001806/Courier0033.lzma 2020-10-17T01:23:01.223Z,1602897781.223 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Courier0033.lzma.bak 2020-10-17T01:23:01.223Z,1602897781.223 [DataOverHttps](INFO): SBD MOMSN=12919467 2020-10-17T01:23:17.032Z,1602897797.032 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201017T001806/Express0034.lzma 2020-10-17T01:23:18.035Z,1602897798.035 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Express0034.lzma.bak 2020-10-17T01:23:18.035Z,1602897798.035 [DataOverHttps](INFO): SBD MOMSN=12919470 2020-10-17T01:23:19.936Z,1602897799.936 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:23:19.936Z,1602897799.936 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T01:23:19.936Z,1602897799.936 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T01:24:56.066Z,1602897896.066 [NAL9602](INFO): SBD MO Status=2, MOMSN=41130, MT Status=2, MTMSN=0 2020-10-17T01:24:56.067Z,1602897896.067 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:25:35.263Z,1602897935.263 [NAL9602](INFO): SBD MO Status=2, MOMSN=41130, MT Status=2, MTMSN=0 2020-10-17T01:25:35.264Z,1602897935.264 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:26:28.190Z,1602897988.190 [NAL9602](INFO): SBD MO Status=0, MOMSN=41130, MT Status=0, MTMSN=0 2020-10-17T01:26:28.190Z,1602897988.190 [NAL9602](INFO): No messages in MT queue 2020-10-17T01:26:58.876Z,1602898018.876 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T01:28:20.510Z,1602898100.510 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:28:20.510Z,1602898100.510 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:28:20.511Z,1602898100.511 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:28:20.511Z,1602898100.511 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:28:20.916Z,1602898100.916 [Default:CheckIn:D] Stopped 2020-10-17T01:28:20.916Z,1602898100.916 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:28:21.316Z,1602898101.316 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.588680 min 2020-10-17T01:28:21.316Z,1602898101.316 [Default:CheckIn:E] Stopped 2020-10-17T01:28:21.316Z,1602898101.316 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:28:21.316Z,1602898101.316 [Default:CheckIn] Stopped 2020-10-17T01:28:21.316Z,1602898101.316 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:28:21.345Z,1602898101.345 [Default:CheckIn](INFO): Running loop #14 2020-10-17T01:28:21.345Z,1602898101.345 [Default:CheckIn] Running Loop=14 2020-10-17T01:28:21.345Z,1602898101.345 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:28:21.345Z,1602898101.345 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:28:23.327Z,1602898103.327 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,012735.00,A,3648.17043,N,12147.27822,W,0.447,0.00,171020,,,A*78 2020-10-17T01:28:23.330Z,1602898103.330 [NAL9602](INFO): GPS fix at 20201017T012735: (36.802841, -121.787970) 2020-10-17T01:28:23.344Z,1602898103.344 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:28:23.344Z,1602898103.344 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:28:30.832Z,1602898110.832 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201017T001806/Courier0036.lzma 2020-10-17T01:28:31.835Z,1602898111.835 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Courier0036.lzma.bak 2020-10-17T01:28:31.835Z,1602898111.835 [DataOverHttps](INFO): SBD MOMSN=12919529 2020-10-17T01:28:47.648Z,1602898127.648 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20201017T001806/Express0037.lzma 2020-10-17T01:28:48.651Z,1602898128.651 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Express0037.lzma.bak 2020-10-17T01:28:48.651Z,1602898128.651 [DataOverHttps](INFO): SBD MOMSN=12919532 2020-10-17T01:28:50.400Z,1602898130.400 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:28:50.400Z,1602898130.400 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T01:28:50.400Z,1602898130.400 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T01:28:53.231Z,1602898133.231 [NAL9602](INFO): SBD MO Status=0, MOMSN=41131, MT Status=0, MTMSN=0 2020-10-17T01:28:53.231Z,1602898133.231 [NAL9602](INFO): No messages in MT queue 2020-10-17T01:29:23.927Z,1602898163.927 [NAL9602](INFO): Not Powering down - fast GPS 2020-10-17T01:33:51.002Z,1602898431.002 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:33:51.002Z,1602898431.002 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:33:51.002Z,1602898431.002 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:33:51.002Z,1602898431.002 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:33:51.416Z,1602898431.416 [Default:CheckIn:D] Stopped 2020-10-17T01:33:51.416Z,1602898431.416 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:33:51.803Z,1602898431.803 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.097005 min 2020-10-17T01:33:51.803Z,1602898431.803 [Default:CheckIn:E] Stopped 2020-10-17T01:33:51.803Z,1602898431.803 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:33:51.803Z,1602898431.803 [Default:CheckIn] Stopped 2020-10-17T01:33:51.803Z,1602898431.803 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:33:51.803Z,1602898431.803 [Default:CheckIn](INFO): Running loop #15 2020-10-17T01:33:51.804Z,1602898431.804 [Default:CheckIn] Running Loop=15 2020-10-17T01:33:51.804Z,1602898431.804 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:33:51.804Z,1602898431.804 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:33:53.820Z,1602898433.820 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,013305.00,A,3648.17093,N,12147.28242,W,0.175,0.00,171020,,,A*74 2020-10-17T01:33:53.822Z,1602898433.822 [NAL9602](INFO): GPS fix at 20201017T013305: (36.802849, -121.788040) 2020-10-17T01:33:53.832Z,1602898433.832 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:33:53.832Z,1602898433.832 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:34:01.499Z,1602898441.499 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201017T001806/Courier0039.lzma 2020-10-17T01:34:02.767Z,1602898442.767 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Courier0039.lzma.bak 2020-10-17T01:34:02.767Z,1602898442.767 [DataOverHttps](INFO): SBD MOMSN=12919544 2020-10-17T01:34:06.780Z,1602898446.780 [NAL9602](INFO): SBD MO Status=2, MOMSN=41132, MT Status=2, MTMSN=0 2020-10-17T01:34:06.780Z,1602898446.780 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:34:18.424Z,1602898458.424 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20201017T001806/Express0040.lzma 2020-10-17T01:34:19.427Z,1602898459.427 [DataOverHttps](INFO): Moved sent file to Logs/20201017T001806/Express0040.lzma.bak 2020-10-17T01:34:19.427Z,1602898459.427 [DataOverHttps](INFO): SBD MOMSN=12919547 2020-10-17T01:34:21.320Z,1602898461.320 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:34:21.320Z,1602898461.320 [Default:CheckIn:C.Wait] Running Loop=1 2020-10-17T01:34:21.320Z,1602898461.320 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-10-17T01:34:23.736Z,1602898463.736 [NAL9602](INFO): SBD MO Status=2, MOMSN=41132, MT Status=2, MTMSN=0 2020-10-17T01:34:23.736Z,1602898463.736 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:34:54.052Z,1602898494.052 [NAL9602](INFO): SBD MO Status=2, MOMSN=41132, MT Status=2, MTMSN=0 2020-10-17T01:34:54.053Z,1602898494.053 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:35:10.598Z,1602898510.598 [NAL9602](INFO): SBD MO Status=2, MOMSN=41132, MT Status=2, MTMSN=0 2020-10-17T01:35:10.598Z,1602898510.598 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:35:43.748Z,1602898543.748 [NAL9602](INFO): SBD MO Status=2, MOMSN=41132, MT Status=2, MTMSN=0 2020-10-17T01:35:43.748Z,1602898543.748 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:36:10.812Z,1602898570.812 [NAL9602](INFO): SBD MO Status=2, MOMSN=41132, MT Status=2, MTMSN=0 2020-10-17T01:36:10.812Z,1602898570.812 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2020-10-17T01:36:23.093Z,1602898583.093 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:36:54.217Z,1602898614.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:37:25.353Z,1602898645.353 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:37:56.477Z,1602898676.477 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:38:27.601Z,1602898707.601 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:38:30.614Z,1602898710.614 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.743301 2020-10-17T01:38:55.644Z,1602898735.644 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-10-17T01:39:21.910Z,1602898761.910 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-10-17T01:39:21.910Z,1602898761.910 [Default:CheckIn:C.Wait] Stopped 2020-10-17T01:39:21.910Z,1602898761.910 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-10-17T01:39:21.910Z,1602898761.910 [Default:CheckIn:D] Running Loop=1 2020-10-17T01:39:22.318Z,1602898762.318 [Default:CheckIn:D] Stopped 2020-10-17T01:39:22.318Z,1602898762.318 [Default:CheckIn:E] Running Loop=1 2020-10-17T01:39:22.719Z,1602898762.719 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.612044 min 2020-10-17T01:39:22.719Z,1602898762.719 [Default:CheckIn:E] Stopped 2020-10-17T01:39:22.719Z,1602898762.719 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-10-17T01:39:22.719Z,1602898762.719 [Default:CheckIn] Stopped 2020-10-17T01:39:22.719Z,1602898762.719 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:39:22.719Z,1602898762.719 [Default:CheckIn](INFO): Running loop #16 2020-10-17T01:39:22.719Z,1602898762.719 [Default:CheckIn] Running Loop=16 2020-10-17T01:39:22.720Z,1602898762.720 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-10-17T01:39:22.720Z,1602898762.720 [Default:CheckIn:Read_GPS] Running Loop=1 2020-10-17T01:39:28.845Z,1602898768.845 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:39:59.970Z,1602898799.970 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:40:31.094Z,1602898831.094 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:41:02.217Z,1602898862.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:41:33.349Z,1602898893.349 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:42:04.485Z,1602898924.485 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:42:35.610Z,1602898955.610 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:43:06.733Z,1602898986.733 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:43:37.869Z,1602899017.869 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:44:09.001Z,1602899049.001 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:44:22.902Z,1602899062.902 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-10-17T01:39:22.7Z 2020-10-17T01:44:22.902Z,1602899062.902 [Default:CheckIn:Read_GPS] Stopped 2020-10-17T01:44:22.903Z,1602899062.903 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-10-17T01:44:40.125Z,1602899080.125 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:44:53.587Z,1602899093.587 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2020-10-17T01:44:53.665Z,1602899093.665 [NAL9602](ERROR): received: +CSQ:0 OK132, 2, 0, 0, 0 OK 2020-10-17T01:44:53.665Z,1602899093.665 [NAL9602] Data Fault, FailCount= 1 2020-10-17T01:44:53.665Z,1602899093.665 [NAL9602](ERROR): Data Fault 2020-10-17T01:44:53.709Z,1602899093.709 [CBIT](ERROR): Data Fault in component: NAL9602 2020-10-17T01:44:53.994Z,1602899093.994 [NAL9602](INFO): Powering down 2020-10-17T01:44:54.397Z,1602899094.397 [NAL9602](FAULT): LCB fault: Software Overcurrent. 2020-10-17T01:44:54.397Z,1602899094.397 [NAL9602] Hardware Fault, FailCount= 1 2020-10-17T01:44:54.397Z,1602899094.397 [NAL9602](ERROR): Hardware Fault 2020-10-17T01:44:54.817Z,1602899094.817 [CBIT](INFO): Clearing failed state for component NAL9602 2020-10-17T01:44:54.818Z,1602899094.818 [NAL9602] No Fault, FailCount= 1 2020-10-17T01:45:11.249Z,1602899111.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:45:24.294Z,1602899124.294 [NAL9602](INFO): Powering up NAL9602 2020-10-17T01:45:35.201Z,1602899135.201 [NAL9602](INFO): NAL9602 initialized 2020-10-17T01:45:42.373Z,1602899142.373 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:46:13.501Z,1602899173.501 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:46:44.625Z,1602899204.625 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:47:15.763Z,1602899235.763 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:47:46.885Z,1602899266.885 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:48:18.009Z,1602899298.009 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:48:49.141Z,1602899329.141 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:49:20.265Z,1602899360.265 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:49:51.397Z,1602899391.397 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:50:22.521Z,1602899422.521 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:50:50.378Z,1602899450.378 [CommandLine](IMPORTANT): got command quit 2020-10-17T01:50:51.401Z,1602899451.401 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:50:51.401Z,1602899451.401 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:51.561Z,1602899451.561 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-10-17T01:50:51.561Z,1602899451.561 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:51.562Z,1602899451.562 [CommandLine](INFO): Join timeout helper Thread ID is 4766 2020-10-17T01:50:51.562Z,1602899451.562 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-10-17T01:50:51.562Z,1602899451.562 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:51.563Z,1602899451.563 [NavChartDb](INFO): Join timeout helper Thread ID is 4767 2020-10-17T01:50:51.925Z,1602899451.925 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:50:51.925Z,1602899451.925 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:51.929Z,1602899451.929 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-10-17T01:50:51.929Z,1602899451.929 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:51.929Z,1602899451.929 [Radio_Surface](INFO): Join timeout helper Thread ID is 4768 2020-10-17T01:50:52.237Z,1602899452.237 [Radio_Surface](INFO): Powering down 2020-10-17T01:50:52.238Z,1602899452.238 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:50:52.238Z,1602899452.238 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:52.253Z,1602899452.253 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2020-10-17T01:50:52.253Z,1602899452.253 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:52.253Z,1602899452.253 [Onboard](INFO): Join timeout helper Thread ID is 4769 2020-10-17T01:50:53.653Z,1602899453.653 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2020-10-17T01:50:54.657Z,1602899454.657 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2020-10-17T01:50:56.909Z,1602899456.909 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:50:56.909Z,1602899456.909 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:56.917Z,1602899456.917 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-10-17T01:50:56.917Z,1602899456.917 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:56.917Z,1602899456.917 [DataOverHttps](INFO): Join timeout helper Thread ID is 4770 2020-10-17T01:50:57.669Z,1602899457.669 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:50:57.670Z,1602899457.670 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:57.690Z,1602899457.690 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-10-17T01:50:57.690Z,1602899457.690 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:57.690Z,1602899457.690 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4771 2020-10-17T01:50:58.021Z,1602899458.021 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:50:58.022Z,1602899458.022 [WetLabsBB2FL](INFO): Powering down 2020-10-17T01:50:58.022Z,1602899458.022 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:58.026Z,1602899458.026 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-10-17T01:50:58.026Z,1602899458.026 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:58.026Z,1602899458.026 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4772 2020-10-17T01:50:58.473Z,1602899458.473 [CTD_Seabird](INFO): Powering down 2020-10-17T01:50:58.485Z,1602899458.485 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:50:58.485Z,1602899458.485 [CTD_Seabird](INFO): Powering down 2020-10-17T01:50:58.497Z,1602899458.497 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:58.514Z,1602899458.514 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-10-17T01:50:58.514Z,1602899458.514 [logger ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:58.514Z,1602899458.514 [logger](INFO): Join timeout helper Thread ID is 4773 2020-10-17T01:50:58.559Z,1602899458.559 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:50:58.559Z,1602899458.559 [logger ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:58.564Z,1602899458.564 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-10-17T01:50:58.564Z,1602899458.564 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:58.564Z,1602899458.564 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-10-17T01:50:58.564Z,1602899458.564 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:58.564Z,1602899458.564 [controlThread](INFO): Join timeout helper Thread ID is 4774 2020-10-17T01:50:58.865Z,1602899458.865 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-10-17T01:50:58.865Z,1602899458.865 [controlThread](DEBUG): Uninitializing ControlThread 2020-10-17T01:50:58.868Z,1602899458.868 [AHRS_M2](INFO): Powering down 2020-10-17T01:50:58.937Z,1602899458.937 [NAL9602](INFO): Powering down 2020-10-17T01:50:58.939Z,1602899458.939 [DAT](INFO): Powering down 2020-10-17T01:50:59.225Z,1602899459.225 [AMEcho](INFO): Powering down 2020-10-17T01:50:59.226Z,1602899459.226 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-10-17T01:50:59.227Z,1602899459.227 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-10-17T01:50:59.228Z,1602899459.228 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-10-17T01:50:59.228Z,1602899459.228 [MissionManager](INFO): Uninitializing Mission Default 2020-10-17T01:50:59.228Z,1602899459.228 [Default] Stopped 2020-10-17T01:50:59.228Z,1602899459.228 [Default](DEBUG): Aggregate::uninitialize Default 2020-10-17T01:50:59.228Z,1602899459.228 [Default:B.GoToSurface] Stopped 2020-10-17T01:50:59.228Z,1602899459.228 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-10-17T01:50:59.228Z,1602899459.228 [Default:CheckIn] Stopped 2020-10-17T01:50:59.228Z,1602899459.228 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-10-17T01:50:59.229Z,1602899459.229 [Default:CheckIn:Read_Iridium] Stopped 2020-10-17T01:50:59.231Z,1602899459.231 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-10-17T01:50:59.231Z,1602899459.231 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-10-17T01:50:59.232Z,1602899459.232 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-10-17T01:50:59.232Z,1602899459.232 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-10-17T01:50:59.232Z,1602899459.232 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-10-17T01:50:59.232Z,1602899459.232 [BuoyancyServo](INFO): Powering down 2020-10-17T01:50:59.245Z,1602899459.245 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-10-17T01:50:59.245Z,1602899459.245 [ElevatorServo](INFO): Powering down 2020-10-17T01:50:59.246Z,1602899459.246 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-10-17T01:50:59.246Z,1602899459.246 [MassServo](INFO): Powering down 2020-10-17T01:50:59.247Z,1602899459.247 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-10-17T01:50:59.247Z,1602899459.247 [RudderServo](INFO): Powering down 2020-10-17T01:50:59.247Z,1602899459.247 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-10-17T01:50:59.248Z,1602899459.248 [ThrusterServo](INFO): Powering down 2020-10-17T01:50:59.248Z,1602899459.248 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-10-17T01:50:59.249Z,1602899459.249 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-10-17T01:50:59.249Z,1602899459.249 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-10-17T01:50:59.249Z,1602899459.249 [CBIT](DEBUG): Powering off loads. 2020-10-17T01:50:59.261Z,1602899459.261 [CBIT](DEBUG): Disabling WDT. 2020-10-17T01:50:59.273Z,1602899459.273 [CBIT](DEBUG): Opening all GF detection circuits. 2020-10-17T01:50:59.274Z,1602899459.274 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:59.314Z,1602899459.314 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:59.398Z,1602899459.398 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:59.472Z,1602899459.472 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:59.473Z,1602899459.473 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:59.479Z,1602899459.479 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:59.531Z,1602899459.532 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-10-17T01:50:59.617Z,1602899459.617 [logger ThreadHandler](INFO): Thread cancelled.