2021-08-05T20:19:21.707Z,1628194761.707 [DataOverHttps](INFO): Received command:restart logs 2021-08-05T20:19:21.712Z,1628194761.712 [CommandLine](IMPORTANT): got command restart logs 2021-08-05T20:22:33.349Z,1628194953.349 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2021-08-05T20:22:33.353Z,1628194953.353 [BPC1](INFO): Received data from all battery sticks. 2021-08-05T20:22:59.257Z,1628194979.257 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-05T20:22:59.257Z,1628194979.257 [Default:CheckIn:C.Wait] Stopped 2021-08-05T20:22:59.257Z,1628194979.257 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-05T20:22:59.258Z,1628194979.258 [Default:CheckIn:D] Running Loop=1 2021-08-05T20:22:59.685Z,1628194979.685 [Default:CheckIn:D] Stopped 2021-08-05T20:22:59.685Z,1628194979.685 [Default:CheckIn:E] Running Loop=1 2021-08-05T20:23:00.092Z,1628194980.092 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.169023 min 2021-08-05T20:23:00.092Z,1628194980.092 [Default:CheckIn:E] Stopped 2021-08-05T20:23:00.092Z,1628194980.092 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-05T20:23:00.092Z,1628194980.092 [Default:CheckIn] Stopped 2021-08-05T20:23:00.092Z,1628194980.092 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-05T20:23:00.093Z,1628194980.093 [Default:CheckIn](INFO): Running loop #4 2021-08-05T20:23:00.093Z,1628194980.093 [Default:CheckIn] Running Loop=4 2021-08-05T20:23:00.093Z,1628194980.093 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-05T20:23:00.093Z,1628194980.093 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-05T20:23:02.081Z,1628194982.081 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202301.00,A,3647.96102,N,12148.37435,W,29.546,69.16,050821,,,D*73 2021-08-05T20:23:02.091Z,1628194982.091 [NAL9602](INFO): GPS fix at 20210805T202301: (36.799350, -121.806239) 2021-08-05T20:23:02.111Z,1628194982.111 [Default:CheckIn:Read_GPS] Stopped 2021-08-05T20:23:02.111Z,1628194982.111 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-05T20:23:07.950Z,1628194987.950 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210805T165332/Courier0022.lzma 2021-08-05T20:23:08.953Z,1628194988.953 [DataOverHttps](INFO): Moved sent file to Logs/20210805T165332/Courier0022.lzma.bak 2021-08-05T20:23:08.953Z,1628194988.953 [DataOverHttps](INFO): SBD MOMSN=15839473 2021-08-05T20:23:16.262Z,1628194996.262 [NAL9602](INFO): SBD MO Status=0, MOMSN=29030, MT Status=0, MTMSN=0 2021-08-05T20:23:16.262Z,1628194996.262 [NAL9602](INFO): No messages in MT queue 2021-08-05T20:23:24.727Z,1628195004.727 [DataOverHttps](INFO): Sending 309 bytes from file Logs/20210805T201921/Courier0000.lzma 2021-08-05T20:23:25.729Z,1628195005.729 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Courier0000.lzma.bak 2021-08-05T20:23:25.729Z,1628195005.729 [DataOverHttps](INFO): SBD MOMSN=15839481 2021-08-05T20:23:41.598Z,1628195021.598 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20210805T165332/Express0023.lzma 2021-08-05T20:23:42.601Z,1628195022.601 [DataOverHttps](INFO): Moved sent file to Logs/20210805T165332/Express0023.lzma.bak 2021-08-05T20:23:42.601Z,1628195022.601 [DataOverHttps](INFO): SBD MOMSN=15839488 2021-08-05T20:23:46.956Z,1628195026.956 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-05T20:23:58.331Z,1628195038.331 [DataOverHttps](INFO): Sending 398 bytes from file Logs/20210805T201921/Express0001.lzma 2021-08-05T20:23:59.333Z,1628195039.333 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Express0001.lzma.bak 2021-08-05T20:23:59.333Z,1628195039.333 [DataOverHttps](INFO): SBD MOMSN=15839491 2021-08-05T20:24:15.054Z,1628195055.054 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20210805T201921/Express0004.lzma 2021-08-05T20:24:16.065Z,1628195056.065 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Express0004.lzma.bak 2021-08-05T20:24:16.065Z,1628195056.065 [DataOverHttps](INFO): SBD MOMSN=15839495 2021-08-05T20:24:17.280Z,1628195057.280 [Default:CheckIn:Read_Iridium] Stopped 2021-08-05T20:24:17.281Z,1628195057.281 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-05T20:24:17.281Z,1628195057.281 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-05T20:25:17.335Z,1628195117.335 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-05T20:25:48.459Z,1628195148.459 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-05T20:26:19.612Z,1628195179.612 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-08-05T20:29:17.947Z,1628195357.947 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-05T20:29:17.947Z,1628195357.947 [Default:CheckIn:C.Wait] Stopped 2021-08-05T20:29:17.947Z,1628195357.947 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-05T20:29:17.947Z,1628195357.947 [Default:CheckIn:D] Running Loop=1 2021-08-05T20:29:18.357Z,1628195358.357 [Default:CheckIn:D] Stopped 2021-08-05T20:29:18.357Z,1628195358.357 [Default:CheckIn:E] Running Loop=1 2021-08-05T20:29:18.774Z,1628195358.774 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.480219 min 2021-08-05T20:29:18.774Z,1628195358.774 [Default:CheckIn:E] Stopped 2021-08-05T20:29:18.774Z,1628195358.774 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-05T20:29:18.774Z,1628195358.774 [Default:CheckIn] Stopped 2021-08-05T20:29:18.774Z,1628195358.774 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-05T20:29:18.774Z,1628195358.774 [Default:CheckIn](INFO): Running loop #5 2021-08-05T20:29:18.774Z,1628195358.774 [Default:CheckIn] Running Loop=5 2021-08-05T20:29:18.774Z,1628195358.774 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-05T20:29:18.775Z,1628195358.775 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-05T20:29:20.767Z,1628195360.767 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202920.00,A,3648.31568,N,12147.13919,W,1.886,217.93,050821,,,D*77 2021-08-05T20:29:20.769Z,1628195360.769 [NAL9602](INFO): GPS fix at 20210805T202920: (36.805261, -121.785653) 2021-08-05T20:29:20.780Z,1628195360.780 [Default:CheckIn:Read_GPS] Stopped 2021-08-05T20:29:20.781Z,1628195360.781 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-05T20:29:28.586Z,1628195368.586 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210805T201921/Courier0006.lzma 2021-08-05T20:29:29.589Z,1628195369.589 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Courier0006.lzma.bak 2021-08-05T20:29:29.589Z,1628195369.589 [DataOverHttps](INFO): SBD MOMSN=15839550 2021-08-05T20:29:44.198Z,1628195384.198 [NAL9602](INFO): SBD MO Status=2, MOMSN=29031, MT Status=2, MTMSN=0 2021-08-05T20:29:44.198Z,1628195384.198 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-08-05T20:29:45.466Z,1628195385.466 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20210805T201921/Express0007.lzma 2021-08-05T20:29:46.469Z,1628195386.469 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Express0007.lzma.bak 2021-08-05T20:29:46.469Z,1628195386.469 [DataOverHttps](INFO): SBD MOMSN=15839558 2021-08-05T20:29:47.844Z,1628195387.844 [Default:CheckIn:Read_Iridium] Stopped 2021-08-05T20:29:47.845Z,1628195387.845 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-05T20:29:47.845Z,1628195387.845 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-05T20:31:10.750Z,1628195470.750 [NAL9602](INFO): SBD MO Status=2, MOMSN=29031, MT Status=2, MTMSN=0 2021-08-05T20:31:10.750Z,1628195470.750 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-08-05T20:31:25.700Z,1628195485.700 [NAL9602](INFO): SBD MO Status=2, MOMSN=29031, MT Status=2, MTMSN=0 2021-08-05T20:31:25.700Z,1628195485.700 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-08-05T20:31:53.166Z,1628195513.166 [NAL9602](INFO): SBD MO Status=0, MOMSN=29031, MT Status=0, MTMSN=0 2021-08-05T20:31:53.166Z,1628195513.166 [NAL9602](INFO): No messages in MT queue 2021-08-05T20:32:23.900Z,1628195543.900 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-05T20:34:48.625Z,1628195688.625 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-05T20:34:48.625Z,1628195688.625 [Default:CheckIn:C.Wait] Stopped 2021-08-05T20:34:48.625Z,1628195688.625 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-05T20:34:48.626Z,1628195688.626 [Default:CheckIn:D] Running Loop=1 2021-08-05T20:34:49.019Z,1628195689.019 [Default:CheckIn:D] Stopped 2021-08-05T20:34:49.019Z,1628195689.019 [Default:CheckIn:E] Running Loop=1 2021-08-05T20:34:49.423Z,1628195689.423 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.991252 min 2021-08-05T20:34:49.423Z,1628195689.423 [Default:CheckIn:E] Stopped 2021-08-05T20:34:49.423Z,1628195689.423 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-05T20:34:49.423Z,1628195689.423 [Default:CheckIn] Stopped 2021-08-05T20:34:49.423Z,1628195689.423 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-05T20:34:49.423Z,1628195689.423 [Default:CheckIn](INFO): Running loop #6 2021-08-05T20:34:49.423Z,1628195689.423 [Default:CheckIn] Running Loop=6 2021-08-05T20:34:49.424Z,1628195689.424 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-05T20:34:49.424Z,1628195689.424 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-05T20:34:51.441Z,1628195691.441 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203450.00,A,3648.31072,N,12147.14429,W,0.039,200.72,050821,,,D*7F 2021-08-05T20:34:51.452Z,1628195691.452 [NAL9602](INFO): GPS fix at 20210805T203450: (36.805179, -121.785738) 2021-08-05T20:34:51.463Z,1628195691.463 [Default:CheckIn:Read_GPS] Stopped 2021-08-05T20:34:51.463Z,1628195691.463 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-05T20:35:00.822Z,1628195700.822 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210805T201921/Courier0009.lzma 2021-08-05T20:35:01.825Z,1628195701.825 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Courier0009.lzma.bak 2021-08-05T20:35:01.825Z,1628195701.825 [DataOverHttps](INFO): SBD MOMSN=15839685 2021-08-05T20:35:07.710Z,1628195707.710 [NAL9602](INFO): SBD MO Status=2, MOMSN=29032, MT Status=2, MTMSN=0 2021-08-05T20:35:07.710Z,1628195707.710 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-08-05T20:35:17.706Z,1628195717.706 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20210805T201921/Express0010.lzma 2021-08-05T20:35:18.709Z,1628195718.709 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Express0010.lzma.bak 2021-08-05T20:35:18.709Z,1628195718.709 [DataOverHttps](INFO): SBD MOMSN=15839688 2021-08-05T20:35:19.843Z,1628195719.843 [Default:CheckIn:Read_Iridium] Stopped 2021-08-05T20:35:19.843Z,1628195719.843 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-05T20:35:19.843Z,1628195719.843 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-05T20:35:52.154Z,1628195752.154 [NAL9602](INFO): SBD MO Status=2, MOMSN=29032, MT Status=2, MTMSN=0 2021-08-05T20:35:52.154Z,1628195752.154 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-08-05T20:36:08.718Z,1628195768.718 [NAL9602](INFO): SBD MO Status=2, MOMSN=29032, MT Status=2, MTMSN=0 2021-08-05T20:36:08.718Z,1628195768.718 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-08-05T20:36:36.250Z,1628195796.250 [NAL9602](INFO): SBD MO Status=2, MOMSN=29032, MT Status=2, MTMSN=0 2021-08-05T20:36:36.250Z,1628195796.250 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-08-05T20:36:51.600Z,1628195811.600 [NAL9602](INFO): SBD MO Status=2, MOMSN=29032, MT Status=2, MTMSN=0 2021-08-05T20:36:51.600Z,1628195811.600 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-08-05T20:37:21.518Z,1628195841.518 [NAL9602](INFO): SBD MO Status=0, MOMSN=29032, MT Status=0, MTMSN=0 2021-08-05T20:37:21.518Z,1628195841.518 [NAL9602](INFO): No messages in MT queue 2021-08-05T20:37:52.251Z,1628195872.251 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-05T20:40:20.498Z,1628196020.498 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-05T20:40:20.498Z,1628196020.498 [Default:CheckIn:C.Wait] Stopped 2021-08-05T20:40:20.498Z,1628196020.498 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-05T20:40:20.499Z,1628196020.499 [Default:CheckIn:D] Running Loop=1 2021-08-05T20:40:20.903Z,1628196020.903 [Default:CheckIn:D] Stopped 2021-08-05T20:40:20.903Z,1628196020.903 [Default:CheckIn:E] Running Loop=1 2021-08-05T20:40:21.322Z,1628196021.322 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.522648 min 2021-08-05T20:40:21.322Z,1628196021.322 [Default:CheckIn:E] Stopped 2021-08-05T20:40:21.322Z,1628196021.322 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-05T20:40:21.322Z,1628196021.322 [Default:CheckIn] Stopped 2021-08-05T20:40:21.322Z,1628196021.322 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-05T20:40:21.322Z,1628196021.322 [Default:CheckIn](INFO): Running loop #7 2021-08-05T20:40:21.323Z,1628196021.323 [Default:CheckIn] Running Loop=7 2021-08-05T20:40:21.323Z,1628196021.323 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-05T20:40:21.323Z,1628196021.323 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-05T20:40:23.318Z,1628196023.318 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204022.00,A,3648.19699,N,12147.22329,W,2.644,182.46,050821,,,A*77 2021-08-05T20:40:23.321Z,1628196023.321 [NAL9602](INFO): GPS fix at 20210805T204022: (36.803283, -121.787055) 2021-08-05T20:40:23.340Z,1628196023.340 [Default:CheckIn:Read_GPS] Stopped 2021-08-05T20:40:23.340Z,1628196023.340 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-05T20:40:31.066Z,1628196031.066 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210805T201921/Courier0012.lzma 2021-08-05T20:40:32.069Z,1628196032.069 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Courier0012.lzma.bak 2021-08-05T20:40:32.069Z,1628196032.069 [DataOverHttps](INFO): SBD MOMSN=15839734 2021-08-05T20:40:47.968Z,1628196047.968 [NAL9602](INFO): SBD MO Status=0, MOMSN=29033, MT Status=0, MTMSN=0 2021-08-05T20:40:47.968Z,1628196047.968 [NAL9602](INFO): No messages in MT queue 2021-08-05T20:40:47.991Z,1628196047.991 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20210805T201921/Express0013.lzma 2021-08-05T20:40:48.993Z,1628196048.993 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Express0013.lzma.bak 2021-08-05T20:40:48.993Z,1628196048.993 [DataOverHttps](INFO): SBD MOMSN=15839740 2021-08-05T20:40:50.430Z,1628196050.430 [Default:CheckIn:Read_Iridium] Stopped 2021-08-05T20:40:50.430Z,1628196050.430 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-05T20:40:50.430Z,1628196050.430 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-05T20:41:18.708Z,1628196078.708 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-05T20:45:51.026Z,1628196351.026 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-05T20:45:51.027Z,1628196351.027 [Default:CheckIn:C.Wait] Stopped 2021-08-05T20:45:51.027Z,1628196351.027 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-05T20:45:51.027Z,1628196351.027 [Default:CheckIn:D] Running Loop=1 2021-08-05T20:45:51.436Z,1628196351.436 [Default:CheckIn:D] Stopped 2021-08-05T20:45:51.436Z,1628196351.436 [Default:CheckIn:E] Running Loop=1 2021-08-05T20:45:51.845Z,1628196351.845 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.031543 min 2021-08-05T20:45:51.845Z,1628196351.845 [Default:CheckIn:E] Stopped 2021-08-05T20:45:51.845Z,1628196351.845 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-05T20:45:51.845Z,1628196351.845 [Default:CheckIn] Stopped 2021-08-05T20:45:51.846Z,1628196351.846 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-05T20:45:51.846Z,1628196351.846 [Default:CheckIn](INFO): Running loop #8 2021-08-05T20:45:51.846Z,1628196351.846 [Default:CheckIn] Running Loop=8 2021-08-05T20:45:51.846Z,1628196351.846 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-05T20:45:51.846Z,1628196351.846 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-05T20:45:53.850Z,1628196353.850 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204553.00,A,3648.17069,N,12147.27974,W,0.058,160.00,050821,,,A*73 2021-08-05T20:45:53.852Z,1628196353.852 [NAL9602](INFO): GPS fix at 20210805T204553: (36.802845, -121.787996) 2021-08-05T20:45:53.895Z,1628196353.895 [Default:CheckIn:Read_GPS] Stopped 2021-08-05T20:45:53.895Z,1628196353.895 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-05T20:46:01.738Z,1628196361.738 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210805T201921/Courier0015.lzma 2021-08-05T20:46:02.741Z,1628196362.741 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Courier0015.lzma.bak 2021-08-05T20:46:02.741Z,1628196362.741 [DataOverHttps](INFO): SBD MOMSN=15839796 2021-08-05T20:46:19.191Z,1628196379.191 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20210805T201921/Express0016.lzma 2021-08-05T20:46:20.197Z,1628196380.197 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Express0016.lzma.bak 2021-08-05T20:46:20.197Z,1628196380.197 [DataOverHttps](INFO): SBD MOMSN=15839802 2021-08-05T20:46:21.372Z,1628196381.372 [Default:CheckIn:Read_Iridium] Stopped 2021-08-05T20:46:21.372Z,1628196381.372 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-05T20:46:21.372Z,1628196381.372 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-05T20:50:56.581Z,1628196656.581 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-08-05T20:51:22.042Z,1628196682.042 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-05T20:51:22.042Z,1628196682.042 [Default:CheckIn:C.Wait] Stopped 2021-08-05T20:51:22.043Z,1628196682.043 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-05T20:51:22.043Z,1628196682.043 [Default:CheckIn:D] Running Loop=1 2021-08-05T20:51:22.460Z,1628196682.460 [Default:CheckIn:D] Stopped 2021-08-05T20:51:22.460Z,1628196682.460 [Default:CheckIn:E] Running Loop=1 2021-08-05T20:51:22.871Z,1628196682.871 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.548612 min 2021-08-05T20:51:22.871Z,1628196682.871 [Default:CheckIn:E] Stopped 2021-08-05T20:51:22.871Z,1628196682.871 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-05T20:51:22.871Z,1628196682.871 [Default:CheckIn] Stopped 2021-08-05T20:51:22.871Z,1628196682.871 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-05T20:51:22.871Z,1628196682.871 [Default:CheckIn](INFO): Running loop #9 2021-08-05T20:51:22.872Z,1628196682.872 [Default:CheckIn] Running Loop=9 2021-08-05T20:51:22.872Z,1628196682.872 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-05T20:51:22.872Z,1628196682.872 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-05T20:51:24.862Z,1628196684.862 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205124.00,A,3648.16777,N,12147.28376,W,1.011,147.33,050821,,,A*71 2021-08-05T20:51:24.865Z,1628196684.865 [NAL9602](INFO): GPS fix at 20210805T205124: (36.802796, -121.788063) 2021-08-05T20:51:24.876Z,1628196684.876 [Default:CheckIn:Read_GPS] Stopped 2021-08-05T20:51:24.876Z,1628196684.876 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-05T20:51:32.594Z,1628196692.594 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210805T201921/Courier0018.lzma 2021-08-05T20:51:33.597Z,1628196693.597 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Courier0018.lzma.bak 2021-08-05T20:51:33.597Z,1628196693.597 [DataOverHttps](INFO): SBD MOMSN=15839846 2021-08-05T20:51:49.435Z,1628196709.435 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20210805T201921/Express0019.lzma 2021-08-05T20:51:50.437Z,1628196710.437 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Express0019.lzma.bak 2021-08-05T20:51:50.437Z,1628196710.437 [DataOverHttps](INFO): SBD MOMSN=15839852 2021-08-05T20:51:51.539Z,1628196711.539 [Default:CheckIn:Read_Iridium] Stopped 2021-08-05T20:51:51.539Z,1628196711.539 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-05T20:51:51.539Z,1628196711.539 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-05T20:51:57.588Z,1628196717.588 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-05T20:56:52.194Z,1628197012.194 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-05T20:56:52.194Z,1628197012.194 [Default:CheckIn:C.Wait] Stopped 2021-08-05T20:56:52.194Z,1628197012.194 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-05T20:56:52.195Z,1628197012.195 [Default:CheckIn:D] Running Loop=1 2021-08-05T20:56:52.601Z,1628197012.601 [Default:CheckIn:D] Stopped 2021-08-05T20:56:52.601Z,1628197012.601 [Default:CheckIn:E] Running Loop=1 2021-08-05T20:56:53.031Z,1628197013.031 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.050960 min 2021-08-05T20:56:53.031Z,1628197013.031 [Default:CheckIn:E] Stopped 2021-08-05T20:56:53.031Z,1628197013.031 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-05T20:56:53.031Z,1628197013.031 [Default:CheckIn] Stopped 2021-08-05T20:56:53.032Z,1628197013.032 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-05T20:56:53.032Z,1628197013.032 [Default:CheckIn](INFO): Running loop #10 2021-08-05T20:56:53.032Z,1628197013.032 [Default:CheckIn] Running Loop=10 2021-08-05T20:56:53.032Z,1628197013.032 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-05T20:56:53.032Z,1628197013.032 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-05T20:56:55.018Z,1628197015.018 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205654.00,A,3648.15979,N,12147.27869,W,0.233,147.33,050821,,,A*7B 2021-08-05T20:56:55.021Z,1628197015.021 [NAL9602](INFO): GPS fix at 20210805T205654: (36.802663, -121.787978) 2021-08-05T20:56:55.031Z,1628197015.031 [Default:CheckIn:Read_GPS] Stopped 2021-08-05T20:56:55.032Z,1628197015.032 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-05T20:57:02.910Z,1628197022.910 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210805T201921/Courier0021.lzma 2021-08-05T20:57:03.913Z,1628197023.913 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Courier0021.lzma.bak 2021-08-05T20:57:03.913Z,1628197023.913 [DataOverHttps](INFO): SBD MOMSN=15839908 2021-08-05T20:57:19.731Z,1628197039.731 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20210805T201921/Express0022.lzma 2021-08-05T20:57:20.733Z,1628197040.733 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Express0022.lzma.bak 2021-08-05T20:57:20.733Z,1628197040.733 [DataOverHttps](INFO): SBD MOMSN=15839911 2021-08-05T20:57:22.104Z,1628197042.104 [Default:CheckIn:Read_Iridium] Stopped 2021-08-05T20:57:22.104Z,1628197042.104 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-05T20:57:22.105Z,1628197042.105 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-05T20:57:25.717Z,1628197045.717 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-08-05T20:57:25.791Z,1628197045.791 [NAL9602](ERROR): received: +CSQ:0 OK033, 0, 0, 0, 0 OK 2021-08-05T21:01:57.220Z,1628197317.220 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-08-05T21:02:22.714Z,1628197342.714 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-05T21:02:22.714Z,1628197342.714 [Default:CheckIn:C.Wait] Stopped 2021-08-05T21:02:22.714Z,1628197342.714 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-05T21:02:22.714Z,1628197342.714 [Default:CheckIn:D] Running Loop=1 2021-08-05T21:02:23.089Z,1628197343.089 [Default:CheckIn:D] Stopped 2021-08-05T21:02:23.089Z,1628197343.089 [Default:CheckIn:E] Running Loop=1 2021-08-05T21:02:23.495Z,1628197343.495 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.559090 min 2021-08-05T21:02:23.495Z,1628197343.495 [Default:CheckIn:E] Stopped 2021-08-05T21:02:23.495Z,1628197343.495 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-05T21:02:23.495Z,1628197343.495 [Default:CheckIn] Stopped 2021-08-05T21:02:23.496Z,1628197343.496 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-05T21:02:23.496Z,1628197343.496 [Default:CheckIn](INFO): Running loop #11 2021-08-05T21:02:23.496Z,1628197343.496 [Default:CheckIn] Running Loop=11 2021-08-05T21:02:23.496Z,1628197343.496 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-05T21:02:23.496Z,1628197343.496 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-05T21:02:25.502Z,1628197345.502 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210224.00,A,3648.16490,N,12147.28339,W,0.778,152.40,050821,,,A*7E 2021-08-05T21:02:25.505Z,1628197345.505 [NAL9602](INFO): GPS fix at 20210805T210224: (36.802748, -121.788056) 2021-08-05T21:02:25.556Z,1628197345.556 [Default:CheckIn:Read_GPS] Stopped 2021-08-05T21:02:25.556Z,1628197345.556 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-05T21:02:32.942Z,1628197352.942 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210805T201921/Courier0024.lzma 2021-08-05T21:02:33.945Z,1628197353.945 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Courier0024.lzma.bak 2021-08-05T21:02:33.945Z,1628197353.945 [DataOverHttps](INFO): SBD MOMSN=15839957 2021-08-05T21:02:51.347Z,1628197371.347 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20210805T201921/Express0025.lzma 2021-08-05T21:02:52.349Z,1628197372.349 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Express0025.lzma.bak 2021-08-05T21:02:52.349Z,1628197372.349 [DataOverHttps](INFO): SBD MOMSN=15839960 2021-08-05T21:02:53.475Z,1628197373.475 [Default:CheckIn:Read_Iridium] Stopped 2021-08-05T21:02:53.475Z,1628197373.475 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-05T21:02:53.475Z,1628197373.475 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-05T21:02:57.444Z,1628197377.444 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-05T21:07:54.017Z,1628197674.017 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-05T21:07:54.017Z,1628197674.017 [Default:CheckIn:C.Wait] Stopped 2021-08-05T21:07:54.017Z,1628197674.017 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-05T21:07:54.018Z,1628197674.018 [Default:CheckIn:D] Running Loop=1 2021-08-05T21:07:54.434Z,1628197674.434 [Default:CheckIn:D] Stopped 2021-08-05T21:07:54.434Z,1628197674.434 [Default:CheckIn:E] Running Loop=1 2021-08-05T21:07:54.823Z,1628197674.823 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.081510 min 2021-08-05T21:07:54.823Z,1628197674.823 [Default:CheckIn:E] Stopped 2021-08-05T21:07:54.823Z,1628197674.823 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-05T21:07:54.823Z,1628197674.823 [Default:CheckIn] Stopped 2021-08-05T21:07:54.823Z,1628197674.823 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-05T21:07:54.824Z,1628197674.824 [Default:CheckIn](INFO): Running loop #12 2021-08-05T21:07:54.824Z,1628197674.824 [Default:CheckIn] Running Loop=12 2021-08-05T21:07:54.824Z,1628197674.824 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-05T21:07:54.824Z,1628197674.824 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-05T21:07:56.838Z,1628197676.838 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210756.00,A,3648.18492,N,12147.30965,W,4.724,318.54,050821,,,A*7C 2021-08-05T21:07:56.841Z,1628197676.841 [NAL9602](INFO): GPS fix at 20210805T210756: (36.803082, -121.788494) 2021-08-05T21:07:56.852Z,1628197676.852 [Default:CheckIn:Read_GPS] Stopped 2021-08-05T21:07:56.852Z,1628197676.852 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-05T21:08:03.862Z,1628197683.862 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210805T201921/Courier0027.lzma 2021-08-05T21:08:04.865Z,1628197684.865 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Courier0027.lzma.bak 2021-08-05T21:08:04.865Z,1628197684.865 [DataOverHttps](INFO): SBD MOMSN=15840025 2021-08-05T21:08:20.775Z,1628197700.775 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20210805T201921/Express0028.lzma 2021-08-05T21:08:21.777Z,1628197701.777 [DataOverHttps](INFO): Moved sent file to Logs/20210805T201921/Express0028.lzma.bak 2021-08-05T21:08:21.777Z,1628197701.777 [DataOverHttps](INFO): SBD MOMSN=15840028 2021-08-05T21:08:23.116Z,1628197703.116 [Default:CheckIn:Read_Iridium] Stopped 2021-08-05T21:08:23.116Z,1628197703.116 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-05T21:08:23.116Z,1628197703.116 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-05T21:08:27.537Z,1628197707.537 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-08-05T21:08:27.615Z,1628197707.615 [NAL9602](ERROR): received: +CSQ:0 OK033, 0, 0, 0, 0 OK 2021-08-05T21:09:55.676Z,1628197795.676 [CommandLine](IMPORTANT): got command quit 2021-08-05T21:09:56.679Z,1628197796.679 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2021-08-05T21:09:56.679Z,1628197796.679 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-05T21:09:56.680Z,1628197796.680 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:09:56.849Z,1628197796.849 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-08-05T21:09:56.849Z,1628197796.849 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:09:56.850Z,1628197796.850 [CommandLine](INFO): Join timeout helper Thread ID is 7419 2021-08-05T21:09:56.864Z,1628197796.864 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-08-05T21:09:56.864Z,1628197796.864 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:09:56.865Z,1628197796.865 [NavChartDb](INFO): Join timeout helper Thread ID is 7420 2021-08-05T21:09:56.948Z,1628197796.948 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-05T21:09:56.949Z,1628197796.949 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:09:56.959Z,1628197796.959 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2021-08-05T21:09:56.959Z,1628197796.959 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:09:56.959Z,1628197796.959 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7421 2021-08-05T21:09:57.163Z,1628197797.163 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-05T21:09:57.164Z,1628197797.164 [WetLabsBB2FL](INFO): Powering down 2021-08-05T21:09:57.164Z,1628197797.164 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:09:57.171Z,1628197797.171 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2021-08-05T21:09:57.171Z,1628197797.171 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:09:57.172Z,1628197797.172 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7422 2021-08-05T21:09:57.359Z,1628197797.359 [CTD_Seabird](INFO): Powering down 2021-08-05T21:09:57.371Z,1628197797.371 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-05T21:09:57.371Z,1628197797.371 [CTD_Seabird](INFO): Powering down 2021-08-05T21:09:57.383Z,1628197797.383 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:09:57.403Z,1628197797.403 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-08-05T21:09:57.403Z,1628197797.403 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:09:57.403Z,1628197797.403 [Radio_Surface](INFO): Join timeout helper Thread ID is 7423 2021-08-05T21:09:57.487Z,1628197797.487 [Radio_Surface](INFO): Powering down 2021-08-05T21:09:57.488Z,1628197797.488 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-05T21:09:57.488Z,1628197797.488 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:09:57.504Z,1628197797.504 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-08-05T21:09:57.504Z,1628197797.504 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:09:57.504Z,1628197797.504 [Onboard](INFO): Join timeout helper Thread ID is 7424 2021-08-05T21:10:02.143Z,1628197802.143 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-05T21:10:02.143Z,1628197802.143 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:10:02.145Z,1628197802.145 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2021-08-05T21:10:02.145Z,1628197802.145 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:10:02.145Z,1628197802.145 [DataOverHttps](INFO): Join timeout helper Thread ID is 7425 2021-08-05T21:10:02.867Z,1628197802.867 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-05T21:10:02.870Z,1628197802.870 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:10:02.888Z,1628197802.888 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2021-08-05T21:10:02.888Z,1628197802.888 [logger ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:10:02.888Z,1628197802.888 [logger](INFO): Join timeout helper Thread ID is 7426 2021-08-05T21:10:02.907Z,1628197802.907 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-05T21:10:02.907Z,1628197802.907 [logger ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:10:02.916Z,1628197802.916 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2021-08-05T21:10:02.916Z,1628197802.916 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:10:02.916Z,1628197802.916 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2021-08-05T21:10:02.916Z,1628197802.916 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:10:02.917Z,1628197802.917 [controlThread](INFO): Join timeout helper Thread ID is 7427 2021-08-05T21:10:02.918Z,1628197802.918 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-05T21:10:02.918Z,1628197802.918 [controlThread](DEBUG): Uninitializing ControlThread 2021-08-05T21:10:02.918Z,1628197802.918 [AHRS_M2](INFO): Powering down 2021-08-05T21:10:02.992Z,1628197802.992 [NAL9602](INFO): Powering down 2021-08-05T21:10:02.994Z,1628197802.994 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2021-08-05T21:10:02.996Z,1628197802.996 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2021-08-05T21:10:02.996Z,1628197802.996 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2021-08-05T21:10:02.997Z,1628197802.997 [MissionManager](INFO): Uninitializing Mission Default 2021-08-05T21:10:02.997Z,1628197802.997 [Default] Stopped 2021-08-05T21:10:02.997Z,1628197802.997 [Default](DEBUG): Aggregate::uninitialize Default 2021-08-05T21:10:02.997Z,1628197802.997 [Default:B.GoToSurface] Stopped 2021-08-05T21:10:02.997Z,1628197802.997 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-08-05T21:10:02.997Z,1628197802.997 [Default:CheckIn] Stopped 2021-08-05T21:10:02.997Z,1628197802.997 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-05T21:10:02.997Z,1628197802.997 [Default:CheckIn:C.Wait] Stopped 2021-08-05T21:10:02.998Z,1628197802.998 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-05T21:10:03.001Z,1628197803.001 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2021-08-05T21:10:03.001Z,1628197803.001 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2021-08-05T21:10:03.001Z,1628197803.001 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2021-08-05T21:10:03.002Z,1628197803.002 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2021-08-05T21:10:03.002Z,1628197803.002 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2021-08-05T21:10:03.002Z,1628197803.002 [BuoyancyServo](INFO): Powering down 2021-08-05T21:10:03.015Z,1628197803.015 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-08-05T21:10:03.015Z,1628197803.015 [ElevatorServo](INFO): Powering down 2021-08-05T21:10:03.016Z,1628197803.016 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-08-05T21:10:03.016Z,1628197803.016 [MassServo](INFO): Powering down 2021-08-05T21:10:03.017Z,1628197803.017 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-08-05T21:10:03.017Z,1628197803.017 [RudderServo](INFO): Powering down 2021-08-05T21:10:03.018Z,1628197803.018 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-08-05T21:10:03.018Z,1628197803.018 [ThrusterServo](INFO): Powering down 2021-08-05T21:10:03.019Z,1628197803.019 [SBIT](DEBUG): Uninitialize SBIT Component. 2021-08-05T21:10:03.020Z,1628197803.020 [IBIT](DEBUG): Uninitialize IBIT Component. 2021-08-05T21:10:03.020Z,1628197803.020 [CBIT](DEBUG): Uninitialize CBIT Component. 2021-08-05T21:10:03.020Z,1628197803.020 [CBIT](DEBUG): Powering off loads. 2021-08-05T21:10:03.031Z,1628197803.031 [CBIT](DEBUG): Disabling WDT. 2021-08-05T21:10:03.043Z,1628197803.043 [CBIT](DEBUG): Opening all GF detection circuits. 2021-08-05T21:10:03.044Z,1628197803.044 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:10:03.090Z,1628197803.090 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:10:03.093Z,1628197803.093 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:10:03.100Z,1628197803.100 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:10:03.172Z,1628197803.172 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:10:03.176Z,1628197803.176 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:10:03.215Z,1628197803.215 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-08-05T21:10:03.292Z,1628197803.292 [logger ThreadHandler](INFO): Thread cancelled.