2021-08-03T14:00:05.090Z,1627999205.090 [CommandLine](IMPORTANT): got command restart logs 2021-08-03T14:00:39.848Z,1627999239.848 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-08-03T13:55:39.6Z 2021-08-03T14:00:39.848Z,1627999239.848 [Default:CheckIn:Read_GPS] Stopped 2021-08-03T14:00:39.848Z,1627999239.848 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-03T14:00:43.446Z,1627999243.446 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-08-03T14:00:47.087Z,1627999247.087 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210803T133146/Courier0006.lzma 2021-08-03T14:00:48.090Z,1627999248.090 [DataOverHttps](INFO): Moved sent file to Logs/20210803T133146/Courier0006.lzma.bak 2021-08-03T14:00:48.090Z,1627999248.090 [DataOverHttps](INFO): SBD MOMSN=15800511 2021-08-03T14:01:03.783Z,1627999263.783 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20210803T140005/Courier0000.lzma 2021-08-03T14:01:04.786Z,1627999264.786 [DataOverHttps](INFO): Moved sent file to Logs/20210803T140005/Courier0000.lzma.bak 2021-08-03T14:01:04.786Z,1627999264.786 [DataOverHttps](INFO): SBD MOMSN=15800513 2021-08-03T14:01:14.148Z,1627999274.148 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-03T14:01:21.779Z,1627999281.779 [DataOverHttps](INFO): Sending 1402 bytes from file Logs/20210803T133146/Express0007.lzma 2021-08-03T14:01:22.778Z,1627999282.778 [DataOverHttps](INFO): Moved sent file to Logs/20210803T133146/Express0007.lzma.bak 2021-08-03T14:01:22.778Z,1627999282.778 [DataOverHttps](INFO): SBD MOMSN=15800516 2021-08-03T14:01:26.946Z,1627999286.946 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1627999232.000000 second 2021-08-03T14:01:40.532Z,1627999300.532 [DataOverHttps](INFO): Sending 257 bytes from file Logs/20210803T140005/Express0001.lzma 2021-08-03T14:01:41.534Z,1627999301.534 [DataOverHttps](INFO): Moved sent file to Logs/20210803T140005/Express0001.lzma.bak 2021-08-03T14:01:41.534Z,1627999301.534 [DataOverHttps](INFO): SBD MOMSN=15800557 2021-08-03T14:02:01.751Z,1627999321.751 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20210803T140005/Express0004.lzma 2021-08-03T14:02:02.771Z,1627999322.771 [DataOverHttps](INFO): Moved sent file to Logs/20210803T140005/Express0004.lzma.bak 2021-08-03T14:02:02.772Z,1627999322.772 [DataOverHttps](INFO): SBD MOMSN=15800560 2021-08-03T14:02:04.334Z,1627999324.334 [Default:CheckIn:Read_Iridium] Stopped 2021-08-03T14:02:04.335Z,1627999324.335 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-03T14:02:04.335Z,1627999324.335 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-03T14:07:05.080Z,1627999625.080 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-03T14:07:05.080Z,1627999625.080 [Default:CheckIn:C.Wait] Stopped 2021-08-03T14:07:05.080Z,1627999625.080 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-03T14:07:05.081Z,1627999625.081 [Default:CheckIn:D] Running Loop=1 2021-08-03T14:07:05.471Z,1627999625.471 [Default:CheckIn:D] Stopped 2021-08-03T14:07:05.472Z,1627999625.472 [Default:CheckIn:E] Running Loop=1 2021-08-03T14:07:05.887Z,1627999625.887 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.658651 min 2021-08-03T14:07:05.887Z,1627999625.887 [Default:CheckIn:E] Stopped 2021-08-03T14:07:05.887Z,1627999625.887 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-03T14:07:05.887Z,1627999625.887 [Default:CheckIn] Stopped 2021-08-03T14:07:05.887Z,1627999625.887 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-03T14:07:05.888Z,1627999625.888 [Default:CheckIn](INFO): Running loop #2 2021-08-03T14:07:05.888Z,1627999625.888 [Default:CheckIn] Running Loop=2 2021-08-03T14:07:05.888Z,1627999625.888 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-03T14:07:05.888Z,1627999625.888 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-03T14:07:07.505Z,1627999627.505 [NAL9602](FAULT): GPS failed to acquire within timeout. 2021-08-03T14:07:07.505Z,1627999627.505 [NAL9602] Data Fault, FailCount= 4 2021-08-03T14:07:07.505Z,1627999627.505 [NAL9602](ERROR): Data Fault 2021-08-03T14:07:07.524Z,1627999627.524 [CBIT](ERROR): Data Fault in component: NAL9602 2021-08-03T14:07:07.889Z,1627999627.889 [NAL9602](INFO): Powering down 2021-08-03T14:07:08.715Z,1627999628.715 [CBIT](INFO): Clearing failed state for component NAL9602 2021-08-03T14:07:08.715Z,1627999628.715 [NAL9602] No Fault, FailCount= 4 2021-08-03T14:07:38.185Z,1627999658.185 [NAL9602](INFO): Powering up NAL9602 2021-08-03T14:07:49.147Z,1627999669.147 [NAL9602](INFO): NAL9602 initialized 2021-08-03T14:08:58.644Z,1627999738.644 [DVL_micro](ERROR): Failed to parse: :WI,-00150,-00156+00000,A 2021-08-03T14:12:06.214Z,1627999926.214 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-08-03T14:07:05.9Z 2021-08-03T14:12:06.214Z,1627999926.214 [Default:CheckIn:Read_GPS] Stopped 2021-08-03T14:12:06.214Z,1627999926.214 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-03T14:12:13.452Z,1627999933.452 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20210803T140005/Courier0006.lzma 2021-08-03T14:12:14.454Z,1627999934.454 [DataOverHttps](INFO): Moved sent file to Logs/20210803T140005/Courier0006.lzma.bak 2021-08-03T14:12:14.454Z,1627999934.454 [DataOverHttps](INFO): SBD MOMSN=15800618 2021-08-03T14:12:30.388Z,1627999950.388 [DataOverHttps](INFO): Sending 410 bytes from file Logs/20210803T140005/Express0007.lzma 2021-08-03T14:12:31.390Z,1627999951.390 [DataOverHttps](INFO): Moved sent file to Logs/20210803T140005/Express0007.lzma.bak 2021-08-03T14:12:31.390Z,1627999951.390 [DataOverHttps](INFO): SBD MOMSN=15800620 2021-08-03T14:12:32.908Z,1627999952.908 [Default:CheckIn:Read_Iridium] Stopped 2021-08-03T14:12:32.908Z,1627999952.908 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-03T14:12:32.908Z,1627999952.908 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-03T14:12:52.668Z,1627999972.668 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-08-03T14:13:23.381Z,1628000003.381 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-03T14:13:48.940Z,1628000028.940 [DVL_micro](ERROR): DVL uart error: serial timeout 2021-08-03T14:13:48.940Z,1628000028.940 [DVL_micro] Communications Fault, FailCount= 1 2021-08-03T14:13:48.940Z,1628000028.940 [DVL_micro](ERROR): Communications Fault 2021-08-03T14:13:48.941Z,1628000028.941 [DVL_micro](ERROR): Failed to parse: 2021-08-03T14:13:49.026Z,1628000029.026 [CBIT](ERROR): Communications Fault in component: DVL_micro 2021-08-03T14:13:49.324Z,1628000029.324 [DVL_micro](INFO): Powering down 2021-08-03T14:13:50.116Z,1628000030.116 [CBIT](INFO): Clearing failed state for component DVL_micro 2021-08-03T14:13:50.116Z,1628000030.116 [DVL_micro] No Fault, FailCount= 1 2021-08-03T14:17:33.559Z,1628000253.559 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-03T14:17:33.559Z,1628000253.559 [Default:CheckIn:C.Wait] Stopped 2021-08-03T14:17:33.559Z,1628000253.559 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-03T14:17:33.559Z,1628000253.559 [Default:CheckIn:D] Running Loop=1 2021-08-03T14:17:33.976Z,1628000253.976 [Default:CheckIn:D] Stopped 2021-08-03T14:17:33.976Z,1628000253.976 [Default:CheckIn:E] Running Loop=1 2021-08-03T14:17:34.383Z,1628000254.383 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.133728 min 2021-08-03T14:17:34.383Z,1628000254.383 [Default:CheckIn:E] Stopped 2021-08-03T14:17:34.383Z,1628000254.383 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-03T14:17:34.383Z,1628000254.383 [Default:CheckIn] Stopped 2021-08-03T14:17:34.383Z,1628000254.383 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-03T14:17:34.383Z,1628000254.383 [Default:CheckIn](INFO): Running loop #3 2021-08-03T14:17:34.384Z,1628000254.384 [Default:CheckIn] Running Loop=3 2021-08-03T14:17:34.384Z,1628000254.384 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-03T14:17:34.384Z,1628000254.384 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-03T14:17:45.486Z,1628000265.486 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-08-03T14:18:07.142Z,1628000287.142 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-08-03T14:18:07.216Z,1628000287.216 [NAL9602](ERROR): received: +CSQ:0 OK 2021-08-03T14:21:48.425Z,1628000508.425 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-08-03T14:22:11.697Z,1628000531.697 [DVL_micro](ERROR): only read 3 of 4 data items 2021-08-03T14:22:11.697Z,1628000531.697 [DVL_micro](ERROR): Failed to parse: :BI,-00285,-00002,+0000,I 2021-08-03T14:22:34.743Z,1628000554.743 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-08-03T14:17:34.4Z 2021-08-03T14:22:34.743Z,1628000554.743 [Default:CheckIn:Read_GPS] Stopped 2021-08-03T14:22:34.743Z,1628000554.743 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-03T14:22:37.204Z,1628000557.204 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-08-03T14:22:38.762Z,1628000558.762 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-08-03T14:22:42.083Z,1628000562.083 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210803T140005/Courier0009.lzma 2021-08-03T14:22:43.086Z,1628000563.086 [DataOverHttps](INFO): Moved sent file to Logs/20210803T140005/Courier0009.lzma.bak 2021-08-03T14:22:43.086Z,1628000563.086 [DataOverHttps](INFO): SBD MOMSN=15800691 2021-08-03T14:22:59.060Z,1628000579.060 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20210803T140005/Express0010.lzma 2021-08-03T14:23:00.062Z,1628000580.062 [DataOverHttps](INFO): Moved sent file to Logs/20210803T140005/Express0010.lzma.bak 2021-08-03T14:23:00.062Z,1628000580.062 [DataOverHttps](INFO): SBD MOMSN=15800693 2021-08-03T14:23:01.394Z,1628000581.394 [Default:CheckIn:Read_Iridium] Stopped 2021-08-03T14:23:01.394Z,1628000581.394 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-03T14:23:01.395Z,1628000581.395 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-03T14:23:04.247Z,1628000584.247 [DVL_micro](ERROR): Failed to parse: 0000000000,35.0,+23.5,00000,000 2021-08-03T14:23:09.554Z,1628000589.554 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-03T14:24:26.246Z,1628000666.246 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2021-08-03T14:24:26.246Z,1628000666.246 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2021-08-03T14:24:26.247Z,1628000666.247 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2021-08-03T14:24:26.250Z,1628000666.250 [BPC1](INFO): Received data from all battery sticks. 2021-08-03T14:28:02.130Z,1628000882.130 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-03T14:28:02.130Z,1628000882.130 [Default:CheckIn:C.Wait] Stopped 2021-08-03T14:28:02.130Z,1628000882.130 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-03T14:28:02.130Z,1628000882.130 [Default:CheckIn:D] Running Loop=1 2021-08-03T14:28:02.536Z,1628000882.536 [Default:CheckIn:D] Stopped 2021-08-03T14:28:02.536Z,1628000882.536 [Default:CheckIn:E] Running Loop=1 2021-08-03T14:28:02.933Z,1628000882.933 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.609723 min 2021-08-03T14:28:02.934Z,1628000882.934 [Default:CheckIn:E] Stopped 2021-08-03T14:28:02.934Z,1628000882.934 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-03T14:28:02.934Z,1628000882.934 [Default:CheckIn] Stopped 2021-08-03T14:28:02.934Z,1628000882.934 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-03T14:28:02.934Z,1628000882.934 [Default:CheckIn](INFO): Running loop #4 2021-08-03T14:28:02.934Z,1628000882.934 [Default:CheckIn] Running Loop=4 2021-08-03T14:28:02.934Z,1628000882.934 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-03T14:28:02.934Z,1628000882.934 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-03T14:28:04.536Z,1628000884.536 [NAL9602](FAULT): GPS failed to acquire within timeout. 2021-08-03T14:28:04.536Z,1628000884.536 [NAL9602] Data Fault, FailCount= 5 2021-08-03T14:28:04.536Z,1628000884.536 [NAL9602](ERROR): Data Fault 2021-08-03T14:28:04.596Z,1628000884.596 [CBIT](ERROR): Data Fault in component: NAL9602 2021-08-03T14:28:04.599Z,1628000884.599 [CBIT](CRITICAL): Data Fault in component: NAL9602 2021-08-03T14:28:04.940Z,1628000884.940 [NAL9602](INFO): Powering down 2021-08-03T14:28:04.957Z,1628000884.957 [CommandLine](FAULT): Scheduling is paused 2021-08-03T14:28:04.958Z,1628000884.958 [CBIT](INFO): Critical error at 20210803T142804 2021-08-03T14:28:04.958Z,1628000884.958 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2021-08-03T14:30:04.611Z,1628001004.611 [CBIT](INFO): Clearing failed state for component NAL9602 2021-08-03T14:30:04.611Z,1628001004.611 [NAL9602] No Fault, FailCount= 5 2021-08-03T14:30:04.994Z,1628001004.994 [NAL9602](INFO): Powering up NAL9602 2021-08-03T14:30:15.905Z,1628001015.905 [NAL9602](INFO): NAL9602 initialized 2021-08-03T14:33:03.229Z,1628001183.229 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-08-03T14:28:02.9Z 2021-08-03T14:33:03.229Z,1628001183.229 [Default:CheckIn:Read_GPS] Stopped 2021-08-03T14:33:03.229Z,1628001183.229 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-03T14:33:10.972Z,1628001190.972 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20210803T140005/Courier0012.lzma 2021-08-03T14:33:11.974Z,1628001191.974 [DataOverHttps](INFO): Moved sent file to Logs/20210803T140005/Courier0012.lzma.bak 2021-08-03T14:33:11.974Z,1628001191.974 [DataOverHttps](INFO): SBD MOMSN=15800756 2021-08-03T14:33:27.792Z,1628001207.792 [DataOverHttps](INFO): Sending 248 bytes from file Logs/20210803T140005/Express0013.lzma 2021-08-03T14:33:28.794Z,1628001208.794 [DataOverHttps](INFO): Moved sent file to Logs/20210803T140005/Express0013.lzma.bak 2021-08-03T14:33:28.794Z,1628001208.794 [DataOverHttps](INFO): SBD MOMSN=15800759 2021-08-03T14:33:30.288Z,1628001210.288 [Default:CheckIn:Read_Iridium] Stopped 2021-08-03T14:33:30.288Z,1628001210.288 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-03T14:33:30.288Z,1628001210.288 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-03T14:35:19.372Z,1628001319.372 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-08-03T14:35:50.082Z,1628001350.082 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-03T14:38:30.933Z,1628001510.933 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-08-03T14:38:30.933Z,1628001510.933 [Default:CheckIn:C.Wait] Stopped 2021-08-03T14:38:30.933Z,1628001510.933 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-03T14:38:30.934Z,1628001510.934 [Default:CheckIn:D] Running Loop=1 2021-08-03T14:38:31.333Z,1628001511.333 [Default:CheckIn:D] Stopped 2021-08-03T14:38:31.333Z,1628001511.333 [Default:CheckIn:E] Running Loop=1 2021-08-03T14:38:31.782Z,1628001511.782 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.089669 min 2021-08-03T14:38:31.783Z,1628001511.783 [Default:CheckIn:E] Stopped 2021-08-03T14:38:31.783Z,1628001511.783 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-08-03T14:38:31.783Z,1628001511.783 [Default:CheckIn] Stopped 2021-08-03T14:38:31.783Z,1628001511.783 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-03T14:38:31.783Z,1628001511.783 [Default:CheckIn](INFO): Running loop #5 2021-08-03T14:38:31.783Z,1628001511.783 [Default:CheckIn] Running Loop=5 2021-08-03T14:38:31.783Z,1628001511.783 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-08-03T14:38:31.784Z,1628001511.784 [Default:CheckIn:Read_GPS] Running Loop=1 2021-08-03T14:42:54.980Z,1628001774.980 [CommandLine](IMPORTANT): got command get Depth_Keller.offset 2021-08-03T14:42:54.980Z,1628001774.980 [CommandLine](IMPORTANT): Depth_Keller.offset -0.560000 db 2021-08-03T14:43:32.045Z,1628001812.045 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-08-03T14:38:31.8Z 2021-08-03T14:43:32.045Z,1628001812.045 [Default:CheckIn:Read_GPS] Stopped 2021-08-03T14:43:32.046Z,1628001812.046 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-08-03T14:43:36.498Z,1628001816.498 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-08-03T14:43:42.623Z,1628001822.623 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210803T140005/Courier0015.lzma 2021-08-03T14:43:43.626Z,1628001823.626 [DataOverHttps](INFO): Moved sent file to Logs/20210803T140005/Courier0015.lzma.bak 2021-08-03T14:43:43.626Z,1628001823.626 [DataOverHttps](INFO): SBD MOMSN=15800821 2021-08-03T14:44:02.936Z,1628001842.936 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20210803T140005/Express0016.lzma 2021-08-03T14:44:03.938Z,1628001843.938 [DataOverHttps](INFO): Moved sent file to Logs/20210803T140005/Express0016.lzma.bak 2021-08-03T14:44:03.938Z,1628001843.938 [DataOverHttps](INFO): SBD MOMSN=15800823 2021-08-03T14:44:05.305Z,1628001845.305 [Default:CheckIn:Read_Iridium] Stopped 2021-08-03T14:44:05.305Z,1628001845.305 [Default:CheckIn:C.Wait] Running Loop=1 2021-08-03T14:44:05.305Z,1628001845.305 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-08-03T14:44:07.299Z,1628001847.299 [NAL9602](INFO): Not Powering down - fast GPS 2021-08-03T14:46:13.945Z,1628001973.945 [DataOverHttps](IMPORTANT): SBD MTMSN=20210803T144508 2021-08-03T14:46:21.343Z,1628001981.343 [DataOverHttps](INFO): Received command:configSet Depth_Keller.offset -0.2859 decibar persist 2021-08-03T14:46:21.571Z,1628001981.571 [CommandLine](IMPORTANT): got command configSet Depth_Keller.offset -0.285900 decibar persist 2021-08-03T14:46:52.765Z,1628002012.765 [DataOverHttps](IMPORTANT): SBD MTMSN=20210803T144547 2021-08-03T14:47:00.123Z,1628002020.123 [DataOverHttps](INFO): Received command:restart app 2021-08-03T14:47:00.214Z,1628002020.214 [CommandLine](IMPORTANT): got command restart application 2021-08-03T14:47:01.220Z,1628002021.220 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-03T14:47:01.220Z,1628002021.220 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:01.240Z,1628002021.240 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-08-03T14:47:01.240Z,1628002021.240 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:01.241Z,1628002021.241 [CommandLine](INFO): Join timeout helper Thread ID is 5858 2021-08-03T14:47:01.241Z,1628002021.241 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-08-03T14:47:01.242Z,1628002021.242 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:01.242Z,1628002021.242 [NavChartDb](INFO): Join timeout helper Thread ID is 5859 2021-08-03T14:47:01.284Z,1628002021.284 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-03T14:47:01.285Z,1628002021.285 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:01.304Z,1628002021.304 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-08-03T14:47:01.304Z,1628002021.304 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:01.304Z,1628002021.304 [Radio_Surface](INFO): Join timeout helper Thread ID is 5860 2021-08-03T14:47:01.308Z,1628002021.308 [Radio_Surface](INFO): Powering down 2021-08-03T14:47:01.309Z,1628002021.309 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-03T14:47:01.309Z,1628002021.309 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:01.324Z,1628002021.324 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-08-03T14:47:01.324Z,1628002021.324 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:01.324Z,1628002021.324 [Onboard](INFO): Join timeout helper Thread ID is 5861 2021-08-03T14:47:02.128Z,1628002022.128 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2021-08-03T14:47:05.404Z,1628002025.404 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-03T14:47:05.404Z,1628002025.404 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:05.420Z,1628002025.420 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2021-08-03T14:47:05.420Z,1628002025.420 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:05.420Z,1628002025.420 [DataOverHttps](INFO): Join timeout helper Thread ID is 5862 2021-08-03T14:47:06.144Z,1628002026.144 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-03T14:47:06.144Z,1628002026.144 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:06.157Z,1628002026.157 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2021-08-03T14:47:06.157Z,1628002026.157 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:06.157Z,1628002026.157 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5863 2021-08-03T14:47:06.564Z,1628002026.564 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-03T14:47:06.564Z,1628002026.564 [WetLabsBB2FL](INFO): Powering down 2021-08-03T14:47:06.565Z,1628002026.565 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:06.577Z,1628002026.577 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2021-08-03T14:47:06.577Z,1628002026.577 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:06.577Z,1628002026.577 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5864 2021-08-03T14:47:07.212Z,1628002027.212 [CTD_Seabird](INFO): Powering down 2021-08-03T14:47:07.225Z,1628002027.225 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-03T14:47:07.225Z,1628002027.225 [CTD_Seabird](INFO): Powering down 2021-08-03T14:47:07.236Z,1628002027.236 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:07.244Z,1628002027.244 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2021-08-03T14:47:07.245Z,1628002027.245 [logger ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:07.245Z,1628002027.245 [logger](INFO): Join timeout helper Thread ID is 5865 2021-08-03T14:47:07.260Z,1628002027.260 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-03T14:47:07.260Z,1628002027.260 [logger ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:07.264Z,1628002027.264 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2021-08-03T14:47:07.265Z,1628002027.265 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:07.265Z,1628002027.265 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2021-08-03T14:47:07.265Z,1628002027.265 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:07.265Z,1628002027.265 [controlThread](INFO): Join timeout helper Thread ID is 5866 2021-08-03T14:47:07.496Z,1628002027.496 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2021-08-03T14:47:07.496Z,1628002027.496 [controlThread](DEBUG): Uninitializing ControlThread 2021-08-03T14:47:07.498Z,1628002027.498 [AHRS_M2](INFO): Powering down 2021-08-03T14:47:07.640Z,1628002027.640 [DVL_micro](INFO): Powering down 2021-08-03T14:47:07.641Z,1628002027.641 [NAL9602](INFO): Powering down 2021-08-03T14:47:07.643Z,1628002027.643 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2021-08-03T14:47:07.644Z,1628002027.644 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2021-08-03T14:47:07.645Z,1628002027.645 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2021-08-03T14:47:07.645Z,1628002027.645 [MissionManager](INFO): Uninitializing Mission Default 2021-08-03T14:47:07.645Z,1628002027.645 [Default] Stopped 2021-08-03T14:47:07.645Z,1628002027.645 [Default](DEBUG): Aggregate::uninitialize Default 2021-08-03T14:47:07.645Z,1628002027.645 [Default:B.GoToSurface] Stopped 2021-08-03T14:47:07.646Z,1628002027.646 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-08-03T14:47:07.646Z,1628002027.646 [Default:CheckIn] Stopped 2021-08-03T14:47:07.646Z,1628002027.646 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-08-03T14:47:07.646Z,1628002027.646 [Default:CheckIn:C.Wait] Stopped 2021-08-03T14:47:07.646Z,1628002027.646 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-08-03T14:47:07.649Z,1628002027.649 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2021-08-03T14:47:07.650Z,1628002027.650 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2021-08-03T14:47:07.650Z,1628002027.650 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2021-08-03T14:47:07.650Z,1628002027.650 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2021-08-03T14:47:07.651Z,1628002027.651 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2021-08-03T14:47:07.651Z,1628002027.651 [BuoyancyServo](INFO): Powering down 2021-08-03T14:47:07.664Z,1628002027.664 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-08-03T14:47:07.664Z,1628002027.664 [ElevatorServo](INFO): Powering down 2021-08-03T14:47:07.665Z,1628002027.665 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-08-03T14:47:07.665Z,1628002027.665 [MassServo](INFO): Powering down 2021-08-03T14:47:07.666Z,1628002027.666 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-08-03T14:47:07.666Z,1628002027.666 [RudderServo](INFO): Powering down 2021-08-03T14:47:07.667Z,1628002027.667 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-08-03T14:47:07.667Z,1628002027.667 [ThrusterServo](INFO): Powering down 2021-08-03T14:47:07.668Z,1628002027.668 [SBIT](DEBUG): Uninitialize SBIT Component. 2021-08-03T14:47:07.669Z,1628002027.669 [IBIT](DEBUG): Uninitialize IBIT Component. 2021-08-03T14:47:07.669Z,1628002027.669 [CBIT](DEBUG): Uninitialize CBIT Component. 2021-08-03T14:47:07.669Z,1628002027.669 [CBIT](DEBUG): Powering off loads. 2021-08-03T14:47:07.680Z,1628002027.680 [CBIT](DEBUG): Disabling WDT. 2021-08-03T14:47:07.692Z,1628002027.692 [CBIT](DEBUG): Opening all GF detection circuits. 2021-08-03T14:47:07.693Z,1628002027.693 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:07.734Z,1628002027.734 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:07.813Z,1628002027.813 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:07.841Z,1628002027.841 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:07.843Z,1628002027.843 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:07.851Z,1628002027.851 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:07.943Z,1628002027.943 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-08-03T14:47:08.010Z,1628002028.010 [logger ThreadHandler](INFO): Thread cancelled.