2021-12-02T19:50:23.674Z,1638474623.674 [CommandExec](IMPORTANT): got command restart logs 2021-12-02T19:51:44.313Z,1638474704.313 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-12-02T19:52:11.415Z,1638474731.415 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-12-02T19:52:11.416Z,1638474731.416 [Default:CheckIn:C.Wait] Stopped 2021-12-02T19:52:11.416Z,1638474731.416 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-12-02T19:52:11.436Z,1638474731.436 [Default:CheckIn:D] Running Loop=1 2021-12-02T19:52:11.638Z,1638474731.638 [Default:CheckIn:D] Stopped 2021-12-02T19:52:11.638Z,1638474731.638 [Default:CheckIn:E] Running Loop=1 2021-12-02T19:52:12.138Z,1638474732.138 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.888841 min 2021-12-02T19:52:12.138Z,1638474732.138 [Default:CheckIn:E] Stopped 2021-12-02T19:52:12.138Z,1638474732.138 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-12-02T19:52:12.138Z,1638474732.138 [Default:CheckIn] Stopped 2021-12-02T19:52:12.139Z,1638474732.139 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-12-02T19:52:12.139Z,1638474732.139 [Default:CheckIn](INFO): Running loop #4 2021-12-02T19:52:12.139Z,1638474732.139 [Default:CheckIn] Running Loop=4 2021-12-02T19:52:12.139Z,1638474732.139 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-12-02T19:52:12.139Z,1638474732.139 [Default:CheckIn:Read_GPS] Running Loop=1 2021-12-02T19:52:13.990Z,1638474733.990 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195213.00,A,3648.16046,N,12147.28386,W,1.769,305.33,021221,,,A*76 2021-12-02T19:52:13.992Z,1638474733.992 [NAL9602](INFO): GPS fix at 20211202T195213: (36.802674, -121.788064) 2021-12-02T19:52:14.078Z,1638474734.078 [Default:CheckIn:Read_GPS] Stopped 2021-12-02T19:52:14.079Z,1638474734.079 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-12-02T19:52:15.713Z,1638474735.713 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20211202T190717/Courier0012.lzma 2021-12-02T19:52:16.078Z,1638474736.078 [DataOverHttps](INFO): Moved sent file to Logs/20211202T190717/Courier0012.lzma.bak 2021-12-02T19:52:16.079Z,1638474736.079 [DataOverHttps](INFO): SBD MOMSN=16323503 2021-12-02T19:52:33.025Z,1638474753.025 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20211202T195023/Courier0000.lzma 2021-12-02T19:52:34.026Z,1638474754.026 [DataOverHttps](INFO): Moved sent file to Logs/20211202T195023/Courier0000.lzma.bak 2021-12-02T19:52:34.026Z,1638474754.026 [DataOverHttps](INFO): SBD MOMSN=16323505 2021-12-02T19:52:46.381Z,1638474766.381 [NAL9602](INFO): Not Powering down - fast GPS 2021-12-02T19:52:50.948Z,1638474770.948 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20211202T190717/Express0013.lzma 2021-12-02T19:52:51.950Z,1638474771.950 [DataOverHttps](INFO): Moved sent file to Logs/20211202T190717/Express0013.lzma.bak 2021-12-02T19:52:51.951Z,1638474771.951 [DataOverHttps](INFO): SBD MOMSN=16323509 2021-12-02T19:53:09.069Z,1638474789.069 [DataOverHttps](INFO): Sending 464 bytes from file Logs/20211202T195023/Express0001.lzma 2021-12-02T19:53:10.070Z,1638474790.070 [DataOverHttps](INFO): Moved sent file to Logs/20211202T195023/Express0001.lzma.bak 2021-12-02T19:53:10.070Z,1638474790.070 [DataOverHttps](INFO): SBD MOMSN=16323512 2021-12-02T19:53:26.848Z,1638474806.848 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20211202T195023/Express0004.lzma 2021-12-02T19:53:27.850Z,1638474807.850 [DataOverHttps](INFO): Moved sent file to Logs/20211202T195023/Express0004.lzma.bak 2021-12-02T19:53:27.850Z,1638474807.850 [DataOverHttps](INFO): SBD MOMSN=16323517 2021-12-02T19:53:29.252Z,1638474809.252 [Default:CheckIn:Read_Iridium] Stopped 2021-12-02T19:53:29.252Z,1638474809.252 [Default:CheckIn:C.Wait] Running Loop=1 2021-12-02T19:53:29.252Z,1638474809.252 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-12-02T19:55:06.601Z,1638474906.601 [RDI_Pathfinder](ERROR): only read 2 of 4 data items 2021-12-02T19:58:29.873Z,1638475109.873 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-12-02T19:58:29.874Z,1638475109.874 [Default:CheckIn:C.Wait] Stopped 2021-12-02T19:58:29.874Z,1638475109.874 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-12-02T19:58:29.874Z,1638475109.874 [Default:CheckIn:D] Running Loop=1 2021-12-02T19:58:30.242Z,1638475110.242 [Default:CheckIn:D] Stopped 2021-12-02T19:58:30.242Z,1638475110.242 [Default:CheckIn:E] Running Loop=1 2021-12-02T19:58:30.675Z,1638475110.675 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.198922 min 2021-12-02T19:58:30.675Z,1638475110.675 [Default:CheckIn:E] Stopped 2021-12-02T19:58:30.675Z,1638475110.675 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-12-02T19:58:30.675Z,1638475110.675 [Default:CheckIn] Stopped 2021-12-02T19:58:30.676Z,1638475110.676 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-12-02T19:58:30.676Z,1638475110.676 [Default:CheckIn](INFO): Running loop #5 2021-12-02T19:58:30.676Z,1638475110.676 [Default:CheckIn] Running Loop=5 2021-12-02T19:58:30.676Z,1638475110.676 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-12-02T19:58:30.677Z,1638475110.677 [Default:CheckIn:Read_GPS] Running Loop=1 2021-12-02T19:58:32.664Z,1638475112.664 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195831.00,A,3648.16601,N,12147.28291,W,0.272,198.88,021221,,,A*76 2021-12-02T19:58:32.666Z,1638475112.666 [NAL9602](INFO): GPS fix at 20211202T195831: (36.802767, -121.788049) 2021-12-02T19:58:32.717Z,1638475112.717 [Default:CheckIn:Read_GPS] Stopped 2021-12-02T19:58:32.717Z,1638475112.717 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-12-02T19:58:40.309Z,1638475120.309 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211202T195023/Courier0006.lzma 2021-12-02T19:58:41.018Z,1638475121.018 [DataOverHttps](INFO): Moved sent file to Logs/20211202T195023/Courier0006.lzma.bak 2021-12-02T19:58:41.018Z,1638475121.018 [DataOverHttps](INFO): SBD MOMSN=16323538 2021-12-02T19:58:57.772Z,1638475137.772 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20211202T195023/Express0007.lzma 2021-12-02T19:58:58.774Z,1638475138.774 [DataOverHttps](INFO): Moved sent file to Logs/20211202T195023/Express0007.lzma.bak 2021-12-02T19:58:58.774Z,1638475138.774 [DataOverHttps](INFO): SBD MOMSN=16323541 2021-12-02T19:59:00.165Z,1638475140.165 [Default:CheckIn:Read_Iridium] Stopped 2021-12-02T19:59:00.165Z,1638475140.165 [Default:CheckIn:C.Wait] Running Loop=1 2021-12-02T19:59:00.166Z,1638475140.166 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-12-02T19:59:03.370Z,1638475143.370 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-12-02T19:59:03.452Z,1638475143.452 [NAL9602](ERROR): received: +CSQ:1 OK816, 2, 0, 0, 0 OK 2021-12-02T20:03:35.255Z,1638475415.255 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-12-02T20:04:00.744Z,1638475440.744 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-12-02T20:04:00.744Z,1638475440.744 [Default:CheckIn:C.Wait] Stopped 2021-12-02T20:04:00.752Z,1638475440.752 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-12-02T20:04:00.753Z,1638475440.753 [Default:CheckIn:D] Running Loop=1 2021-12-02T20:04:01.158Z,1638475441.158 [Default:CheckIn:D] Stopped 2021-12-02T20:04:01.158Z,1638475441.158 [Default:CheckIn:E] Running Loop=1 2021-12-02T20:04:01.554Z,1638475441.554 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.714172 min 2021-12-02T20:04:01.554Z,1638475441.554 [Default:CheckIn:E] Stopped 2021-12-02T20:04:01.554Z,1638475441.554 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-12-02T20:04:01.554Z,1638475441.554 [Default:CheckIn] Stopped 2021-12-02T20:04:01.554Z,1638475441.554 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-12-02T20:04:01.555Z,1638475441.555 [Default:CheckIn](INFO): Running loop #6 2021-12-02T20:04:01.555Z,1638475441.555 [Default:CheckIn] Running Loop=6 2021-12-02T20:04:01.555Z,1638475441.555 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-12-02T20:04:01.555Z,1638475441.555 [Default:CheckIn:Read_GPS] Running Loop=1 2021-12-02T20:04:03.556Z,1638475443.556 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200402.00,A,3648.16330,N,12147.28633,W,0.097,198.88,021221,,,A*77 2021-12-02T20:04:03.558Z,1638475443.558 [NAL9602](INFO): GPS fix at 20211202T200402: (36.802722, -121.788106) 2021-12-02T20:04:03.603Z,1638475443.603 [Default:CheckIn:Read_GPS] Stopped 2021-12-02T20:04:03.603Z,1638475443.603 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-12-02T20:04:12.772Z,1638475452.772 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211202T195023/Courier0009.lzma 2021-12-02T20:04:13.774Z,1638475453.774 [DataOverHttps](INFO): Moved sent file to Logs/20211202T195023/Courier0009.lzma.bak 2021-12-02T20:04:13.774Z,1638475453.774 [DataOverHttps](INFO): SBD MOMSN=16323554 2021-12-02T20:04:30.208Z,1638475470.208 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20211202T195023/Express0010.lzma 2021-12-02T20:04:31.195Z,1638475471.195 [DataOverHttps](INFO): Moved sent file to Logs/20211202T195023/Express0010.lzma.bak 2021-12-02T20:04:31.195Z,1638475471.195 [DataOverHttps](INFO): SBD MOMSN=16323557 2021-12-02T20:04:32.729Z,1638475472.729 [Default:CheckIn:Read_Iridium] Stopped 2021-12-02T20:04:32.729Z,1638475472.729 [Default:CheckIn:C.Wait] Running Loop=1 2021-12-02T20:04:32.729Z,1638475472.729 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-12-02T20:04:35.528Z,1638475475.528 [NAL9602](INFO): Not Powering down - fast GPS 2021-12-02T20:09:33.337Z,1638475773.337 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-12-02T20:09:33.337Z,1638475773.337 [Default:CheckIn:C.Wait] Stopped 2021-12-02T20:09:33.337Z,1638475773.337 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-12-02T20:09:33.337Z,1638475773.337 [Default:CheckIn:D] Running Loop=1 2021-12-02T20:09:33.742Z,1638475773.742 [Default:CheckIn:D] Stopped 2021-12-02T20:09:33.742Z,1638475773.742 [Default:CheckIn:E] Running Loop=1 2021-12-02T20:09:34.147Z,1638475774.147 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.257243 min 2021-12-02T20:09:34.147Z,1638475774.147 [Default:CheckIn:E] Stopped 2021-12-02T20:09:34.147Z,1638475774.147 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-12-02T20:09:34.147Z,1638475774.147 [Default:CheckIn] Stopped 2021-12-02T20:09:34.148Z,1638475774.148 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-12-02T20:09:34.148Z,1638475774.148 [Default:CheckIn](INFO): Running loop #7 2021-12-02T20:09:34.148Z,1638475774.148 [Default:CheckIn] Running Loop=7 2021-12-02T20:09:34.149Z,1638475774.149 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-12-02T20:09:34.149Z,1638475774.149 [Default:CheckIn:Read_GPS] Running Loop=1 2021-12-02T20:09:36.152Z,1638475776.152 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200935.00,A,3648.15834,N,12147.28665,W,1.263,212.84,021221,,,A*74 2021-12-02T20:09:36.154Z,1638475776.154 [NAL9602](INFO): GPS fix at 20211202T200935: (36.802639, -121.788111) 2021-12-02T20:09:36.166Z,1638475776.166 [Default:CheckIn:Read_GPS] Stopped 2021-12-02T20:09:36.166Z,1638475776.166 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-12-02T20:09:43.588Z,1638475783.588 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20211202T195023/Courier0012.lzma 2021-12-02T20:09:44.590Z,1638475784.590 [DataOverHttps](INFO): Moved sent file to Logs/20211202T195023/Courier0012.lzma.bak 2021-12-02T20:09:44.590Z,1638475784.590 [DataOverHttps](INFO): SBD MOMSN=16323580 2021-12-02T20:10:01.029Z,1638475801.029 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20211202T195023/Express0013.lzma 2021-12-02T20:10:02.030Z,1638475802.030 [DataOverHttps](INFO): Moved sent file to Logs/20211202T195023/Express0013.lzma.bak 2021-12-02T20:10:02.030Z,1638475802.030 [DataOverHttps](INFO): SBD MOMSN=16323583 2021-12-02T20:10:03.647Z,1638475803.647 [Default:CheckIn:Read_Iridium] Stopped 2021-12-02T20:10:03.648Z,1638475803.648 [Default:CheckIn:C.Wait] Running Loop=1 2021-12-02T20:10:03.648Z,1638475803.648 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-12-02T20:10:06.854Z,1638475806.854 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-12-02T20:10:06.936Z,1638475806.936 [NAL9602](ERROR): received: +CSQ:0 OK816, 2, 0, 0, 0 OK 2021-12-02T20:11:23.633Z,1638475883.633 [NAL9602](INFO): SBD MO Status=2, MOMSN=17816, MT Status=2, MTMSN=0 2021-12-02T20:11:23.633Z,1638475883.633 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-12-02T20:11:24.413Z,1638475884.413 [CommandExec](IMPORTANT): got command quit 2021-12-02T20:11:25.416Z,1638475885.416 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2021-12-02T20:11:25.417Z,1638475885.417 [CommandExec](INFO): Uninitializing the command executive. 2021-12-02T20:11:25.417Z,1638475885.417 [CommandExec](INFO): Uninitializing the command scheduler. 2021-12-02T20:11:25.417Z,1638475885.417 [CommandExec ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:25.560Z,1638475885.560 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-12-02T20:11:25.560Z,1638475885.560 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-12-02T20:11:25.560Z,1638475885.560 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:25.561Z,1638475885.561 [NavChartDb](INFO): Join timeout helper Thread ID is 4543 2021-12-02T20:11:25.844Z,1638475885.844 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-12-02T20:11:25.845Z,1638475885.845 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:25.852Z,1638475885.852 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2021-12-02T20:11:25.852Z,1638475885.852 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:25.853Z,1638475885.853 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 4544 2021-12-02T20:11:26.197Z,1638475886.197 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2021-12-02T20:11:26.198Z,1638475886.198 [WetLabsUBAT](INFO): Powering down 2021-12-02T20:11:26.199Z,1638475886.199 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:26.213Z,1638475886.213 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2021-12-02T20:11:26.213Z,1638475886.213 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:26.214Z,1638475886.214 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4548 2021-12-02T20:11:26.388Z,1638475886.388 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2021-12-02T20:11:26.389Z,1638475886.389 [WetLabsBB2FL](INFO): Powering down 2021-12-02T20:11:26.390Z,1638475886.390 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:26.392Z,1638475886.392 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2021-12-02T20:11:26.392Z,1638475886.392 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:26.393Z,1638475886.393 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4549 2021-12-02T20:11:26.729Z,1638475886.729 [CTD_Seabird](INFO): Powering down 2021-12-02T20:11:26.740Z,1638475886.740 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2021-12-02T20:11:26.741Z,1638475886.741 [CTD_Seabird](INFO): Powering down 2021-12-02T20:11:26.754Z,1638475886.754 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:26.765Z,1638475886.765 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-12-02T20:11:26.765Z,1638475886.765 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:26.765Z,1638475886.765 [Radio_Surface](INFO): Join timeout helper Thread ID is 4550 2021-12-02T20:11:27.001Z,1638475887.001 [Radio_Surface](INFO): Powering down 2021-12-02T20:11:27.002Z,1638475887.002 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-12-02T20:11:27.002Z,1638475887.002 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:27.025Z,1638475887.025 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-12-02T20:11:27.025Z,1638475887.025 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:27.026Z,1638475887.026 [Onboard](INFO): Join timeout helper Thread ID is 4551 2021-12-02T20:11:27.305Z,1638475887.305 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2021-12-02T20:11:27.305Z,1638475887.305 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:27.321Z,1638475887.321 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2021-12-02T20:11:27.321Z,1638475887.321 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:27.322Z,1638475887.322 [DataOverHttps](INFO): Join timeout helper Thread ID is 4552 2021-12-02T20:11:27.613Z,1638475887.613 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2021-12-02T20:11:27.613Z,1638475887.613 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:27.621Z,1638475887.621 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2021-12-02T20:11:27.621Z,1638475887.621 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:27.622Z,1638475887.622 [BackseatComponent](INFO): Join timeout helper Thread ID is 4553 2021-12-02T20:11:27.622Z,1638475887.622 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2021-12-02T20:11:27.623Z,1638475887.623 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:27.624Z,1638475887.624 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2021-12-02T20:11:27.624Z,1638475887.624 [logger ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:27.625Z,1638475887.625 [logger](INFO): Join timeout helper Thread ID is 4554 2021-12-02T20:11:27.645Z,1638475887.645 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2021-12-02T20:11:27.645Z,1638475887.645 [logger ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:27.646Z,1638475887.646 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2021-12-02T20:11:27.646Z,1638475887.646 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:27.647Z,1638475887.647 [CommandLine](INFO): Join timeout helper Thread ID is 4555 2021-12-02T20:11:27.705Z,1638475887.705 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2021-12-02T20:11:27.705Z,1638475887.705 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:27.709Z,1638475887.709 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2021-12-02T20:11:27.709Z,1638475887.709 [CommandExec ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:27.710Z,1638475887.710 [CommandExec](INFO): Join timeout helper Thread ID is 4556 2021-12-02T20:11:27.713Z,1638475887.713 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2021-12-02T20:11:27.713Z,1638475887.713 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:27.713Z,1638475887.713 [controlThread](INFO): Join timeout helper Thread ID is 4557 2021-12-02T20:11:28.053Z,1638475888.053 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2021-12-02T20:11:28.053Z,1638475888.053 [controlThread](DEBUG): Uninitializing ControlThread 2021-12-02T20:11:28.053Z,1638475888.053 [AHRS_M2](INFO): Powering down 2021-12-02T20:11:28.125Z,1638475888.125 [NAL9602](INFO): Powering down 2021-12-02T20:11:28.197Z,1638475888.197 [RDI_Pathfinder](INFO): Powering down 2021-12-02T20:11:28.198Z,1638475888.198 [DAT](INFO): Powering down 2021-12-02T20:11:28.269Z,1638475888.269 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2021-12-02T20:11:28.270Z,1638475888.270 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2021-12-02T20:11:28.271Z,1638475888.271 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2021-12-02T20:11:28.271Z,1638475888.271 [MissionManager](INFO): Uninitializing Mission Default 2021-12-02T20:11:28.271Z,1638475888.271 [Default] Stopped 2021-12-02T20:11:28.272Z,1638475888.272 [Default](DEBUG): Aggregate::uninitialize Default 2021-12-02T20:11:28.272Z,1638475888.272 [Default:B.GoToSurface] Stopped 2021-12-02T20:11:28.272Z,1638475888.272 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-12-02T20:11:28.272Z,1638475888.272 [Default:CheckIn] Stopped 2021-12-02T20:11:28.272Z,1638475888.272 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-12-02T20:11:28.272Z,1638475888.272 [Default:CheckIn:C.Wait] Stopped 2021-12-02T20:11:28.272Z,1638475888.272 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-12-02T20:11:28.275Z,1638475888.275 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2021-12-02T20:11:28.276Z,1638475888.276 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2021-12-02T20:11:28.276Z,1638475888.276 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2021-12-02T20:11:28.276Z,1638475888.276 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2021-12-02T20:11:28.277Z,1638475888.277 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2021-12-02T20:11:28.277Z,1638475888.277 [BuoyancyServo](INFO): Powering down 2021-12-02T20:11:28.288Z,1638475888.288 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-12-02T20:11:28.289Z,1638475888.289 [ElevatorServo](INFO): Powering down 2021-12-02T20:11:28.290Z,1638475888.290 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-12-02T20:11:28.290Z,1638475888.290 [MassServo](INFO): Powering down 2021-12-02T20:11:28.291Z,1638475888.291 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-12-02T20:11:28.291Z,1638475888.291 [RudderServo](INFO): Powering down 2021-12-02T20:11:28.292Z,1638475888.292 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-12-02T20:11:28.292Z,1638475888.292 [ThrusterServo](INFO): Powering down 2021-12-02T20:11:28.293Z,1638475888.293 [SBIT](DEBUG): Uninitialize SBIT Component. 2021-12-02T20:11:28.294Z,1638475888.294 [IBIT](DEBUG): Uninitialize IBIT Component. 2021-12-02T20:11:28.294Z,1638475888.294 [CBIT](DEBUG): Uninitialize CBIT Component. 2021-12-02T20:11:28.294Z,1638475888.294 [CBIT](DEBUG): Powering off loads. 2021-12-02T20:11:28.308Z,1638475888.308 [CBIT](DEBUG): Disabling WDT. 2021-12-02T20:11:28.320Z,1638475888.320 [CBIT](DEBUG): Opening all GF detection circuits. 2021-12-02T20:11:28.321Z,1638475888.321 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:28.391Z,1638475888.391 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:28.394Z,1638475888.394 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:28.406Z,1638475888.406 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:28.466Z,1638475888.466 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:28.551Z,1638475888.551 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:28.553Z,1638475888.553 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:28.557Z,1638475888.557 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:28.564Z,1638475888.564 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-12-02T20:11:28.679Z,1638475888.679 [logger ThreadHandler](INFO): Thread cancelled.