2025-04-11T23:25:22.054Z,1744413922.054 [CommandExec](IMPORTANT): got command restart logs 2025-04-11T23:25:26.702Z,1744413926.702 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-11T23:25:26.702Z,1744413926.702 [Default:CheckIn:C.Wait] Stopped 2025-04-11T23:25:26.702Z,1744413926.702 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-11T23:25:26.702Z,1744413926.702 [Default:CheckIn:D] Running Loop=1 2025-04-11T23:25:27.092Z,1744413927.092 [Default:CheckIn:D] Stopped 2025-04-11T23:25:27.092Z,1744413927.092 [Default:CheckIn:E] Running Loop=1 2025-04-11T23:25:27.516Z,1744413927.516 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.333643 min 2025-04-11T23:25:27.516Z,1744413927.516 [Default:CheckIn:E] Stopped 2025-04-11T23:25:27.517Z,1744413927.517 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-11T23:25:27.517Z,1744413927.517 [Default:CheckIn] Stopped 2025-04-11T23:25:27.517Z,1744413927.517 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-11T23:25:27.517Z,1744413927.517 [Default:CheckIn](INFO): Running loop #3 2025-04-11T23:25:27.517Z,1744413927.517 [Default:CheckIn] Running Loop=3 2025-04-11T23:25:27.517Z,1744413927.517 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-11T23:25:27.517Z,1744413927.517 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-11T23:25:29.503Z,1744413929.503 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232527.00,A,3648.32948,N,12148.88424,W,4.957,90.57,110425,,,D*4C 2025-04-11T23:25:29.505Z,1744413929.505 [NAL9602](INFO): GPS fix at 20250411T232527: (36.805491, -121.814737) 2025-04-11T23:25:29.515Z,1744413929.515 [Default:CheckIn:Read_GPS] Stopped 2025-04-11T23:25:29.515Z,1744413929.515 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-11T23:25:38.691Z,1744413938.691 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250411T212148/Courier0043.lzma 2025-04-11T23:25:39.694Z,1744413939.694 [DataOverHttps](INFO): Moved sent file to Logs/20250411T212148/Courier0043.lzma.bak 2025-04-11T23:25:39.694Z,1744413939.694 [DataOverHttps](INFO): SBD MOMSN=24619949 2025-04-11T23:25:50.204Z,1744413950.204 [NAL9602](INFO): SBD MO Status=0, MOMSN=19477, MT Status=0, MTMSN=0 2025-04-11T23:25:50.205Z,1744413950.205 [NAL9602](INFO): No messages in MT queue 2025-04-11T23:25:58.944Z,1744413958.944 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20250411T232522/Courier0000.lzma 2025-04-11T23:25:59.945Z,1744413959.945 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Courier0000.lzma.bak 2025-04-11T23:25:59.946Z,1744413959.946 [DataOverHttps](INFO): SBD MOMSN=24619951 2025-04-11T23:26:19.040Z,1744413979.040 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250411T212148/Express0044.lzma 2025-04-11T23:26:20.042Z,1744413980.042 [DataOverHttps](INFO): Moved sent file to Logs/20250411T212148/Express0044.lzma.bak 2025-04-11T23:26:20.042Z,1744413980.042 [DataOverHttps](INFO): SBD MOMSN=24619956 2025-04-11T23:26:20.899Z,1744413980.899 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-11T23:26:37.845Z,1744413997.845 [DataOverHttps](INFO): Sending 443 bytes from file Logs/20250411T232522/Express0001.lzma 2025-04-11T23:26:38.846Z,1744413998.846 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Express0001.lzma.bak 2025-04-11T23:26:38.846Z,1744413998.846 [DataOverHttps](INFO): SBD MOMSN=24619959 2025-04-11T23:26:55.183Z,1744414015.183 [DataOverHttps](INFO): Sending 19 bytes from file Logs/20250411T232522/Express0005.lzma 2025-04-11T23:26:57.191Z,1744414017.191 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Express0005.lzma.bak 2025-04-11T23:26:57.191Z,1744414017.191 [DataOverHttps](INFO): SBD MOMSN=24619963 2025-04-11T23:26:58.511Z,1744414018.511 [Default:CheckIn:Read_Iridium] Stopped 2025-04-11T23:26:58.511Z,1744414018.511 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-11T23:26:58.511Z,1744414018.511 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-11T23:31:59.145Z,1744414319.145 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-11T23:31:59.145Z,1744414319.145 [Default:CheckIn:C.Wait] Stopped 2025-04-11T23:31:59.145Z,1744414319.145 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-11T23:31:59.146Z,1744414319.146 [Default:CheckIn:D] Running Loop=1 2025-04-11T23:31:59.538Z,1744414319.538 [Default:CheckIn:D] Stopped 2025-04-11T23:31:59.538Z,1744414319.538 [Default:CheckIn:E] Running Loop=1 2025-04-11T23:31:59.942Z,1744414319.942 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.874402 min 2025-04-11T23:31:59.942Z,1744414319.942 [Default:CheckIn:E] Stopped 2025-04-11T23:31:59.942Z,1744414319.942 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-11T23:31:59.942Z,1744414319.942 [Default:CheckIn] Stopped 2025-04-11T23:31:59.942Z,1744414319.942 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-11T23:31:59.942Z,1744414319.942 [Default:CheckIn](INFO): Running loop #4 2025-04-11T23:31:59.942Z,1744414319.942 [Default:CheckIn] Running Loop=4 2025-04-11T23:31:59.943Z,1744414319.943 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-11T23:31:59.943Z,1744414319.943 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-11T23:32:01.960Z,1744414321.960 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233200.00,A,3648.48412,N,12147.11967,W,5.501,163.56,110425,,,D*77 2025-04-11T23:32:01.962Z,1744414321.962 [NAL9602](INFO): GPS fix at 20250411T233200: (36.808069, -121.785328) 2025-04-11T23:32:01.973Z,1744414321.973 [Default:CheckIn:Read_GPS] Stopped 2025-04-11T23:32:01.973Z,1744414321.973 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-11T23:32:09.644Z,1744414329.644 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250411T232522/Courier0003.lzma 2025-04-11T23:32:10.764Z,1744414330.764 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Courier0003.lzma.bak 2025-04-11T23:32:10.765Z,1744414330.765 [DataOverHttps](INFO): SBD MOMSN=24619990 2025-04-11T23:32:21.350Z,1744414341.350 [NAL9602](INFO): SBD MO Status=0, MOMSN=19478, MT Status=0, MTMSN=0 2025-04-11T23:32:21.351Z,1744414341.351 [NAL9602](INFO): No messages in MT queue 2025-04-11T23:32:27.741Z,1744414347.741 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20250411T232522/Express0006.lzma 2025-04-11T23:32:28.742Z,1744414348.742 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Express0006.lzma.bak 2025-04-11T23:32:28.742Z,1744414348.742 [DataOverHttps](INFO): SBD MOMSN=24619993 2025-04-11T23:32:30.257Z,1744414350.257 [Default:CheckIn:Read_Iridium] Stopped 2025-04-11T23:32:30.257Z,1744414350.257 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-11T23:32:30.257Z,1744414350.257 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-11T23:32:52.052Z,1744414372.052 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-11T23:37:30.856Z,1744414650.856 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-11T23:37:30.856Z,1744414650.856 [Default:CheckIn:C.Wait] Stopped 2025-04-11T23:37:30.856Z,1744414650.856 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-11T23:37:30.857Z,1744414650.857 [Default:CheckIn:D] Running Loop=1 2025-04-11T23:37:31.262Z,1744414651.262 [Default:CheckIn:D] Stopped 2025-04-11T23:37:31.262Z,1744414651.262 [Default:CheckIn:E] Running Loop=1 2025-04-11T23:37:31.670Z,1744414651.670 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.403147 min 2025-04-11T23:37:31.671Z,1744414651.671 [Default:CheckIn:E] Stopped 2025-04-11T23:37:31.671Z,1744414651.671 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-11T23:37:31.671Z,1744414651.671 [Default:CheckIn] Stopped 2025-04-11T23:37:31.671Z,1744414651.671 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-11T23:37:31.671Z,1744414651.671 [Default:CheckIn](INFO): Running loop #5 2025-04-11T23:37:31.671Z,1744414651.671 [Default:CheckIn] Running Loop=5 2025-04-11T23:37:31.671Z,1744414651.671 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-11T23:37:31.671Z,1744414651.671 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-11T23:37:33.673Z,1744414653.673 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233731.00,A,3648.14837,N,12147.22081,W,0.194,188.12,110425,,,D*7B 2025-04-11T23:37:33.675Z,1744414653.675 [NAL9602](INFO): GPS fix at 20250411T233731: (36.802473, -121.787014) 2025-04-11T23:37:33.718Z,1744414653.718 [Default:CheckIn:Read_GPS] Stopped 2025-04-11T23:37:33.718Z,1744414653.718 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-11T23:37:41.471Z,1744414661.471 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250411T232522/Courier0009.lzma 2025-04-11T23:37:42.474Z,1744414662.474 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Courier0009.lzma.bak 2025-04-11T23:37:42.474Z,1744414662.474 [DataOverHttps](INFO): SBD MOMSN=24620045 2025-04-11T23:37:58.464Z,1744414678.464 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20250411T232522/Express0010.lzma 2025-04-11T23:37:59.466Z,1744414679.466 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Express0010.lzma.bak 2025-04-11T23:37:59.466Z,1744414679.466 [DataOverHttps](INFO): SBD MOMSN=24620071 2025-04-11T23:38:00.745Z,1744414680.745 [Default:CheckIn:Read_Iridium] Stopped 2025-04-11T23:38:00.745Z,1744414680.745 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-11T23:38:00.745Z,1744414680.745 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-11T23:38:03.161Z,1744414683.161 [NAL9602](INFO): SBD MO Status=2, MOMSN=19479, MT Status=2, MTMSN=0 2025-04-11T23:38:03.161Z,1744414683.161 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-11T23:38:39.950Z,1744414719.950 [NAL9602](INFO): SBD MO Status=0, MOMSN=19479, MT Status=0, MTMSN=0 2025-04-11T23:38:39.950Z,1744414719.950 [NAL9602](INFO): No messages in MT queue 2025-04-11T23:39:10.648Z,1744414750.648 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-11T23:43:01.349Z,1744414981.349 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-11T23:43:01.349Z,1744414981.349 [Default:CheckIn:C.Wait] Stopped 2025-04-11T23:43:01.349Z,1744414981.349 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-11T23:43:01.349Z,1744414981.349 [Default:CheckIn:D] Running Loop=1 2025-04-11T23:43:01.763Z,1744414981.763 [Default:CheckIn:D] Stopped 2025-04-11T23:43:01.763Z,1744414981.763 [Default:CheckIn:E] Running Loop=1 2025-04-11T23:43:02.151Z,1744414982.151 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.911491 min 2025-04-11T23:43:02.151Z,1744414982.151 [Default:CheckIn:E] Stopped 2025-04-11T23:43:02.151Z,1744414982.151 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-11T23:43:02.151Z,1744414982.151 [Default:CheckIn] Stopped 2025-04-11T23:43:02.151Z,1744414982.151 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-11T23:43:02.151Z,1744414982.151 [Default:CheckIn](INFO): Running loop #6 2025-04-11T23:43:02.151Z,1744414982.151 [Default:CheckIn] Running Loop=6 2025-04-11T23:43:02.152Z,1744414982.152 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-11T23:43:02.152Z,1744414982.152 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-11T23:43:04.167Z,1744414984.167 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234302.00,A,3648.15077,N,12147.22351,W,0.952,296.44,110425,,,A*73 2025-04-11T23:43:04.170Z,1744414984.170 [NAL9602](INFO): GPS fix at 20250411T234302: (36.802513, -121.787059) 2025-04-11T23:43:04.179Z,1744414984.179 [Default:CheckIn:Read_GPS] Stopped 2025-04-11T23:43:04.180Z,1744414984.180 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-11T23:43:11.436Z,1744414991.436 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250411T232522/Courier0012.lzma 2025-04-11T23:43:12.438Z,1744414992.438 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Courier0012.lzma.bak 2025-04-11T23:43:12.438Z,1744414992.438 [DataOverHttps](INFO): SBD MOMSN=24620075 2025-04-11T23:43:16.718Z,1744414996.718 [NAL9602](INFO): SBD MO Status=0, MOMSN=19480, MT Status=0, MTMSN=0 2025-04-11T23:43:16.719Z,1744414996.719 [NAL9602](INFO): No messages in MT queue 2025-04-11T23:43:28.864Z,1744415008.864 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250411T232522/Express0013.lzma 2025-04-11T23:43:29.866Z,1744415009.866 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Express0013.lzma.bak 2025-04-11T23:43:29.866Z,1744415009.866 [DataOverHttps](INFO): SBD MOMSN=24620078 2025-04-11T23:43:31.243Z,1744415011.243 [Default:CheckIn:Read_Iridium] Stopped 2025-04-11T23:43:31.243Z,1744415011.243 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-11T23:43:31.243Z,1744415011.243 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-11T23:43:47.405Z,1744415027.405 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-11T23:44:52.049Z,1744415092.049 [BPC1](FAULT): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-04-11T23:44:52.050Z,1744415092.050 [BPC1](FAULT): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-04-11T23:44:52.051Z,1744415092.051 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6. 2025-04-11T23:44:52.069Z,1744415092.069 [BPC1](INFO): Received data from all battery sticks. 2025-04-11T23:48:31.843Z,1744415311.843 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-11T23:48:31.843Z,1744415311.843 [Default:CheckIn:C.Wait] Stopped 2025-04-11T23:48:31.843Z,1744415311.843 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-11T23:48:31.844Z,1744415311.844 [Default:CheckIn:D] Running Loop=1 2025-04-11T23:48:32.253Z,1744415312.253 [Default:CheckIn:D] Stopped 2025-04-11T23:48:32.253Z,1744415312.253 [Default:CheckIn:E] Running Loop=1 2025-04-11T23:48:32.649Z,1744415312.649 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.419657 min 2025-04-11T23:48:32.649Z,1744415312.649 [Default:CheckIn:E] Stopped 2025-04-11T23:48:32.650Z,1744415312.650 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-11T23:48:32.650Z,1744415312.650 [Default:CheckIn] Stopped 2025-04-11T23:48:32.650Z,1744415312.650 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-11T23:48:32.650Z,1744415312.650 [Default:CheckIn](INFO): Running loop #7 2025-04-11T23:48:32.650Z,1744415312.650 [Default:CheckIn] Running Loop=7 2025-04-11T23:48:32.650Z,1744415312.650 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-11T23:48:32.650Z,1744415312.650 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-11T23:48:34.662Z,1744415314.662 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234832.00,A,3648.17510,N,12147.27949,W,0.058,277.54,110425,,,D*73 2025-04-11T23:48:34.673Z,1744415314.673 [NAL9602](INFO): GPS fix at 20250411T234832: (36.802918, -121.787992) 2025-04-11T23:48:34.683Z,1744415314.683 [Default:CheckIn:Read_GPS] Stopped 2025-04-11T23:48:34.683Z,1744415314.683 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-11T23:48:42.461Z,1744415322.461 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20250411T232522/Courier0015.lzma 2025-04-11T23:48:43.470Z,1744415323.470 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Courier0015.lzma.bak 2025-04-11T23:48:43.470Z,1744415323.470 [DataOverHttps](INFO): SBD MOMSN=24620157 2025-04-11T23:48:54.426Z,1744415334.426 [NAL9602](INFO): SBD MO Status=0, MOMSN=19481, MT Status=0, MTMSN=0 2025-04-11T23:48:54.427Z,1744415334.427 [NAL9602](INFO): No messages in MT queue 2025-04-11T23:49:00.832Z,1744415340.832 [DataOverHttps](INFO): Sending 297 bytes from file Logs/20250411T232522/Express0016.lzma 2025-04-11T23:49:01.834Z,1744415341.834 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Express0016.lzma.bak 2025-04-11T23:49:01.834Z,1744415341.834 [DataOverHttps](INFO): SBD MOMSN=24620160 2025-04-11T23:49:03.224Z,1744415343.224 [Default:CheckIn:Read_Iridium] Stopped 2025-04-11T23:49:03.224Z,1744415343.224 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-11T23:49:03.224Z,1744415343.224 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-11T23:49:25.017Z,1744415365.017 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-11T23:54:03.832Z,1744415643.832 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-11T23:54:03.832Z,1744415643.832 [Default:CheckIn:C.Wait] Stopped 2025-04-11T23:54:03.832Z,1744415643.832 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-11T23:54:03.833Z,1744415643.833 [Default:CheckIn:D] Running Loop=1 2025-04-11T23:54:04.241Z,1744415644.241 [Default:CheckIn:D] Stopped 2025-04-11T23:54:04.241Z,1744415644.241 [Default:CheckIn:E] Running Loop=1 2025-04-11T23:54:04.638Z,1744415644.638 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.952791 min 2025-04-11T23:54:04.638Z,1744415644.638 [Default:CheckIn:E] Stopped 2025-04-11T23:54:04.638Z,1744415644.638 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-11T23:54:04.638Z,1744415644.638 [Default:CheckIn] Stopped 2025-04-11T23:54:04.639Z,1744415644.639 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-11T23:54:04.639Z,1744415644.639 [Default:CheckIn](INFO): Running loop #8 2025-04-11T23:54:04.639Z,1744415644.639 [Default:CheckIn] Running Loop=8 2025-04-11T23:54:04.639Z,1744415644.639 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-11T23:54:04.639Z,1744415644.639 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-11T23:54:06.650Z,1744415646.650 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235404.00,A,3648.17599,N,12147.27719,W,0.039,193.21,110425,,,D*7D 2025-04-11T23:54:06.652Z,1744415646.652 [NAL9602](INFO): GPS fix at 20250411T235404: (36.802933, -121.787953) 2025-04-11T23:54:06.662Z,1744415646.662 [Default:CheckIn:Read_GPS] Stopped 2025-04-11T23:54:06.662Z,1744415646.662 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-11T23:54:15.715Z,1744415655.715 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250411T232522/Courier0018.lzma 2025-04-11T23:54:16.718Z,1744415656.718 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Courier0018.lzma.bak 2025-04-11T23:54:16.718Z,1744415656.718 [DataOverHttps](INFO): SBD MOMSN=24620168 2025-04-11T23:54:28.931Z,1744415668.931 [NAL9602](INFO): SBD MO Status=2, MOMSN=19482, MT Status=2, MTMSN=0 2025-04-11T23:54:28.931Z,1744415668.931 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-04-11T23:54:32.924Z,1744415672.924 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250411T232522/Express0019.lzma 2025-04-11T23:54:33.926Z,1744415673.926 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Express0019.lzma.bak 2025-04-11T23:54:33.926Z,1744415673.926 [DataOverHttps](INFO): SBD MOMSN=24620175 2025-04-11T23:54:35.501Z,1744415675.501 [Default:CheckIn:Read_Iridium] Stopped 2025-04-11T23:54:35.501Z,1744415675.501 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-11T23:54:35.501Z,1744415675.501 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-11T23:55:05.709Z,1744415705.709 [NAL9602](INFO): SBD MO Status=0, MOMSN=19482, MT Status=0, MTMSN=0 2025-04-11T23:55:05.709Z,1744415705.709 [NAL9602](INFO): No messages in MT queue 2025-04-11T23:55:36.409Z,1744415736.409 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-11T23:57:23.579Z,1744415843.579 [DVL_micro](ERROR): Failed to parse: :WI,+01031,-00997,-01747,+00000,A 2025-04-11T23:59:35.995Z,1744415975.995 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-11T23:59:35.995Z,1744415975.995 [Default:CheckIn:C.Wait] Stopped 2025-04-11T23:59:35.995Z,1744415975.995 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-11T23:59:35.995Z,1744415975.995 [Default:CheckIn:D] Running Loop=1 2025-04-11T23:59:36.401Z,1744415976.401 [Default:CheckIn:D] Stopped 2025-04-11T23:59:36.401Z,1744415976.401 [Default:CheckIn:E] Running Loop=1 2025-04-11T23:59:36.810Z,1744415976.810 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.488770 min 2025-04-11T23:59:36.810Z,1744415976.810 [Default:CheckIn:E] Stopped 2025-04-11T23:59:36.810Z,1744415976.810 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-11T23:59:36.810Z,1744415976.810 [Default:CheckIn] Stopped 2025-04-11T23:59:36.811Z,1744415976.811 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-11T23:59:36.811Z,1744415976.811 [Default:CheckIn](INFO): Running loop #9 2025-04-11T23:59:36.811Z,1744415976.811 [Default:CheckIn] Running Loop=9 2025-04-11T23:59:36.811Z,1744415976.811 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-11T23:59:36.811Z,1744415976.811 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-11T23:59:38.814Z,1744415978.814 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235936.00,A,3648.16283,N,12147.28032,W,1.536,283.30,110425,,,D*74 2025-04-11T23:59:38.816Z,1744415978.816 [NAL9602](INFO): GPS fix at 20250411T235936: (36.802714, -121.788005) 2025-04-11T23:59:38.846Z,1744415978.846 [Default:CheckIn:Read_GPS] Stopped 2025-04-11T23:59:38.846Z,1744415978.846 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-11T23:59:47.043Z,1744415987.043 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250411T232522/Courier0021.lzma 2025-04-11T23:59:48.046Z,1744415988.046 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Courier0021.lzma.bak 2025-04-11T23:59:48.046Z,1744415988.046 [DataOverHttps](INFO): SBD MOMSN=24620240 2025-04-12T00:00:06.584Z,1744416006.584 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250411T232522/Express0022.lzma 2025-04-12T00:00:07.586Z,1744416007.586 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Express0022.lzma.bak 2025-04-12T00:00:07.586Z,1744416007.586 [DataOverHttps](INFO): SBD MOMSN=24620243 2025-04-12T00:00:09.123Z,1744416009.123 [Default:CheckIn:Read_Iridium] Stopped 2025-04-12T00:00:09.123Z,1744416009.123 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-12T00:00:09.123Z,1744416009.123 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-12T00:04:40.608Z,1744416280.608 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-12T00:05:09.744Z,1744416309.744 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-04-12T00:05:09.744Z,1744416309.744 [Default:CheckIn:C.Wait] Stopped 2025-04-12T00:05:09.744Z,1744416309.744 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-12T00:05:09.745Z,1744416309.745 [Default:CheckIn:D] Running Loop=1 2025-04-12T00:05:10.123Z,1744416310.123 [Default:CheckIn:D] Stopped 2025-04-12T00:05:10.123Z,1744416310.123 [Default:CheckIn:E] Running Loop=1 2025-04-12T00:05:10.533Z,1744416310.533 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.050834 min 2025-04-12T00:05:10.533Z,1744416310.533 [Default:CheckIn:E] Stopped 2025-04-12T00:05:10.533Z,1744416310.533 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-04-12T00:05:10.533Z,1744416310.533 [Default:CheckIn] Stopped 2025-04-12T00:05:10.534Z,1744416310.534 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-12T00:05:10.534Z,1744416310.534 [Default:CheckIn](INFO): Running loop #10 2025-04-12T00:05:10.534Z,1744416310.534 [Default:CheckIn] Running Loop=10 2025-04-12T00:05:10.534Z,1744416310.534 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-12T00:05:10.534Z,1744416310.534 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-12T00:05:12.532Z,1744416312.532 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000510.00,A,3648.15854,N,12147.27419,W,1.108,358.66,120425,,,A*72 2025-04-12T00:05:12.534Z,1744416312.534 [NAL9602](INFO): GPS fix at 20250412T000510: (36.802642, -121.787903) 2025-04-12T00:05:12.545Z,1744416312.545 [Default:CheckIn:Read_GPS] Stopped 2025-04-12T00:05:12.545Z,1744416312.545 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-04-12T00:05:20.467Z,1744416320.467 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250411T232522/Courier0024.lzma 2025-04-12T00:05:21.470Z,1744416321.470 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Courier0024.lzma.bak 2025-04-12T00:05:21.470Z,1744416321.470 [DataOverHttps](INFO): SBD MOMSN=24620260 2025-04-12T00:05:40.380Z,1744416340.380 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20250411T232522/Express0025.lzma 2025-04-12T00:05:41.382Z,1744416341.382 [DataOverHttps](INFO): Moved sent file to Logs/20250411T232522/Express0025.lzma.bak 2025-04-12T00:05:41.382Z,1744416341.382 [DataOverHttps](INFO): SBD MOMSN=24620294 2025-04-12T00:05:42.831Z,1744416342.831 [Default:CheckIn:Read_Iridium] Stopped 2025-04-12T00:05:42.831Z,1744416342.831 [Default:CheckIn:C.Wait] Running Loop=1 2025-04-12T00:05:42.852Z,1744416342.852 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-04-12T00:05:44.854Z,1744416344.854 [NAL9602](INFO): Not Powering down - fast GPS 2025-04-12T00:06:24.442Z,1744416384.442 [DVL_micro](ERROR): Failed to parse: :RD,+9999.99,+9999.99,+9999.99,+9999.99 2025-04-12T00:09:45.456Z,1744416585.456 [CommandExec](IMPORTANT): got command quit 2025-04-12T00:09:46.460Z,1744416586.460 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2025-04-12T00:09:46.460Z,1744416586.460 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-12T00:09:46.460Z,1744416586.460 [CommandExec](INFO): Uninitializing the command executive. 2025-04-12T00:09:46.460Z,1744416586.460 [CommandExec](INFO): Uninitializing the command scheduler. 2025-04-12T00:09:46.461Z,1744416586.461 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:46.648Z,1744416586.648 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-04-12T00:09:46.648Z,1744416586.648 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-04-12T00:09:46.648Z,1744416586.648 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:46.649Z,1744416586.649 [NavChartDb](INFO): Join timeout helper Thread ID is 3067 2025-04-12T00:09:46.704Z,1744416586.704 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-12T00:09:46.704Z,1744416586.704 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:46.709Z,1744416586.709 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-04-12T00:09:46.710Z,1744416586.710 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:46.710Z,1744416586.710 [Radio_Surface](INFO): Join timeout helper Thread ID is 3068 2025-04-12T00:09:46.984Z,1744416586.984 [Radio_Surface](INFO): Powering down 2025-04-12T00:09:46.985Z,1744416586.985 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-12T00:09:46.985Z,1744416586.985 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:46.992Z,1744416586.992 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-04-12T00:09:46.992Z,1744416586.992 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:46.993Z,1744416586.993 [Onboard](INFO): Join timeout helper Thread ID is 3069 2025-04-12T00:09:47.976Z,1744416587.976 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-04-12T00:09:49.940Z,1744416589.940 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-12T00:09:49.940Z,1744416589.940 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:49.953Z,1744416589.953 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-04-12T00:09:49.953Z,1744416589.953 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:49.954Z,1744416589.954 [DataOverHttps](INFO): Join timeout helper Thread ID is 3070 2025-04-12T00:09:49.984Z,1744416589.984 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-12T00:09:49.985Z,1744416589.985 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:49.988Z,1744416589.988 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-04-12T00:09:49.988Z,1744416589.988 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:49.989Z,1744416589.989 [DAT](INFO): Join timeout helper Thread ID is 3071 2025-04-12T00:09:50.016Z,1744416590.016 [DAT](INFO): Powering down 2025-04-12T00:09:50.017Z,1744416590.017 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-12T00:09:50.017Z,1744416590.017 [DAT](INFO): Powering down 2025-04-12T00:09:50.018Z,1744416590.018 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:50.033Z,1744416590.033 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-04-12T00:09:50.033Z,1744416590.033 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:50.033Z,1744416590.033 [BackseatComponent](INFO): Join timeout helper Thread ID is 3072 2025-04-12T00:09:50.220Z,1744416590.220 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-12T00:09:50.220Z,1744416590.220 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:50.225Z,1744416590.225 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-04-12T00:09:50.225Z,1744416590.225 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:50.225Z,1744416590.225 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3073 2025-04-12T00:09:50.568Z,1744416590.568 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-12T00:09:50.568Z,1744416590.568 [WetLabsBB2FL](INFO): Powering down 2025-04-12T00:09:50.569Z,1744416590.569 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:50.590Z,1744416590.590 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-04-12T00:09:50.590Z,1744416590.590 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:50.590Z,1744416590.590 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3074 2025-04-12T00:09:50.688Z,1744416590.688 [CTD_Seabird](INFO): Powering down 2025-04-12T00:09:50.700Z,1744416590.700 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-12T00:09:50.700Z,1744416590.700 [CTD_Seabird](INFO): Powering down 2025-04-12T00:09:50.712Z,1744416590.712 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:50.713Z,1744416590.713 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-04-12T00:09:50.713Z,1744416590.713 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:50.714Z,1744416590.714 [logger](INFO): Join timeout helper Thread ID is 3075 2025-04-12T00:09:50.740Z,1744416590.740 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-12T00:09:50.740Z,1744416590.740 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:50.752Z,1744416590.752 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-04-12T00:09:50.753Z,1744416590.753 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:50.753Z,1744416590.753 [CommandLine](INFO): Join timeout helper Thread ID is 3076 2025-04-12T00:09:50.772Z,1744416590.772 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-12T00:09:50.772Z,1744416590.772 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:50.792Z,1744416590.792 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-04-12T00:09:50.793Z,1744416590.793 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:50.793Z,1744416590.793 [CommandExec](INFO): Join timeout helper Thread ID is 3077 2025-04-12T00:09:50.794Z,1744416590.794 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-04-12T00:09:50.794Z,1744416590.794 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:50.795Z,1744416590.795 [controlThread](INFO): Join timeout helper Thread ID is 3078 2025-04-12T00:09:50.856Z,1744416590.856 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-12T00:09:50.856Z,1744416590.856 [controlThread](DEBUG): Uninitializing ControlThread 2025-04-12T00:09:50.858Z,1744416590.858 [AHRS_M2](INFO): Powering down 2025-04-12T00:09:51.032Z,1744416591.032 [DVL_micro](INFO): Powering down 2025-04-12T00:09:51.033Z,1744416591.033 [NAL9602](INFO): Powering down 2025-04-12T00:09:51.034Z,1744416591.034 [Sonardyne_Nano](INFO): Powering down 2025-04-12T00:09:51.240Z,1744416591.240 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-04-12T00:09:51.241Z,1744416591.241 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-04-12T00:09:51.241Z,1744416591.241 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-04-12T00:09:51.242Z,1744416591.242 [MissionManager](INFO): Uninitializing Mission Default 2025-04-12T00:09:51.242Z,1744416591.242 [Default] Stopped 2025-04-12T00:09:51.242Z,1744416591.242 [Default](DEBUG): Aggregate::uninitialize Default 2025-04-12T00:09:51.242Z,1744416591.242 [Default:B.GoToSurface] Stopped 2025-04-12T00:09:51.242Z,1744416591.242 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-12T00:09:51.242Z,1744416591.242 [Default:CheckIn] Stopped 2025-04-12T00:09:51.242Z,1744416591.242 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-12T00:09:51.242Z,1744416591.242 [Default:CheckIn:C.Wait] Stopped 2025-04-12T00:09:51.242Z,1744416591.242 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-04-12T00:09:51.245Z,1744416591.245 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-04-12T00:09:51.246Z,1744416591.246 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-04-12T00:09:51.246Z,1744416591.246 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-04-12T00:09:51.246Z,1744416591.246 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-04-12T00:09:51.247Z,1744416591.247 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-04-12T00:09:51.247Z,1744416591.247 [BuoyancyServo](INFO): Powering down 2025-04-12T00:09:51.260Z,1744416591.260 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-04-12T00:09:51.260Z,1744416591.260 [ElevatorServo](INFO): Powering down 2025-04-12T00:09:51.261Z,1744416591.261 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-04-12T00:09:51.261Z,1744416591.261 [MassServo](INFO): Powering down 2025-04-12T00:09:51.262Z,1744416591.262 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-04-12T00:09:51.262Z,1744416591.262 [RudderServo](INFO): Powering down 2025-04-12T00:09:51.263Z,1744416591.263 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-04-12T00:09:51.263Z,1744416591.263 [ThrusterHE](INFO): Powering down 2025-04-12T00:09:51.264Z,1744416591.264 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-04-12T00:09:51.264Z,1744416591.264 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-04-12T00:09:51.264Z,1744416591.264 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-04-12T00:09:51.264Z,1744416591.264 [CBIT](DEBUG): Powering off loads. 2025-04-12T00:09:51.276Z,1744416591.276 [CBIT](DEBUG): Disabling WDT. 2025-04-12T00:09:51.288Z,1744416591.288 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-04-12T00:09:51.288Z,1744416591.288 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-04-12T00:09:51.289Z,1744416591.289 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:51.318Z,1744416591.318 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-04-12T00:09:51.318Z,1744416591.318 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-04-12T00:09:51.333Z,1744416591.333 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:51.339Z,1744416591.339 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:51.371Z,1744416591.371 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:51.374Z,1744416591.374 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:51.382Z,1744416591.382 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:51.388Z,1744416591.388 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:51.431Z,1744416591.431 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:51.484Z,1744416591.484 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-12T00:09:51.563Z,1744416591.563 [logger ThreadHandler](INFO): Thread cancelled.