2025-05-23T17:16:09.258Z,1748020569.258 [CommandExec](IMPORTANT): got command restart logs 2025-05-23T17:17:22.192Z,1748020642.192 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-05-23T17:17:49.679Z,1748020669.679 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-23T17:17:49.679Z,1748020669.679 [Default:CheckIn:C.Wait] Stopped 2025-05-23T17:17:49.679Z,1748020669.679 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-23T17:17:49.679Z,1748020669.679 [Default:CheckIn:D] Running Loop=1 2025-05-23T17:17:50.090Z,1748020670.090 [Default:CheckIn:D] Stopped 2025-05-23T17:17:50.090Z,1748020670.090 [Default:CheckIn:E] Running Loop=1 2025-05-23T17:17:50.483Z,1748020670.483 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.798837 min 2025-05-23T17:17:50.483Z,1748020670.483 [Default:CheckIn:E] Stopped 2025-05-23T17:17:50.483Z,1748020670.483 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-23T17:17:50.483Z,1748020670.483 [Default:CheckIn] Stopped 2025-05-23T17:17:50.483Z,1748020670.483 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-23T17:17:50.484Z,1748020670.484 [Default:CheckIn](INFO): Running loop #3 2025-05-23T17:17:50.484Z,1748020670.484 [Default:CheckIn] Running Loop=3 2025-05-23T17:17:50.484Z,1748020670.484 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-23T17:17:50.484Z,1748020670.484 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-23T17:17:52.494Z,1748020672.494 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171755.00,A,3646.89430,N,12149.96313,W,8.028,68.73,230525,,,D*42 2025-05-23T17:17:52.496Z,1748020672.496 [NAL9602](INFO): GPS fix at 20250523T171755: (36.781572, -121.832719) 2025-05-23T17:17:52.508Z,1748020672.508 [Default:CheckIn:Read_GPS] Stopped 2025-05-23T17:17:52.508Z,1748020672.508 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-23T17:18:25.224Z,1748020705.224 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-23T17:18:31.620Z,1748020711.620 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250522T214719/Courier0139.lzma 2025-05-23T17:18:32.623Z,1748020712.623 [DataOverHttps](INFO): Moved sent file to Logs/20250522T214719/Courier0139.lzma.bak 2025-05-23T17:18:32.623Z,1748020712.623 [DataOverHttps](INFO): SBD MOMSN=24966742 2025-05-23T17:18:51.229Z,1748020731.229 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20250523T171609/Courier0000.lzma 2025-05-23T17:18:52.231Z,1748020732.231 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Courier0000.lzma.bak 2025-05-23T17:18:52.231Z,1748020732.231 [DataOverHttps](INFO): SBD MOMSN=24966744 2025-05-23T17:19:08.425Z,1748020748.425 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20250522T214719/Express0140.lzma 2025-05-23T17:19:09.428Z,1748020749.428 [DataOverHttps](INFO): Moved sent file to Logs/20250522T214719/Express0140.lzma.bak 2025-05-23T17:19:09.428Z,1748020749.428 [DataOverHttps](INFO): SBD MOMSN=24966748 2025-05-23T17:19:25.398Z,1748020765.398 [DataOverHttps](INFO): Sending 589 bytes from file Logs/20250523T171609/Express0001.lzma 2025-05-23T17:19:26.399Z,1748020766.399 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Express0001.lzma.bak 2025-05-23T17:19:26.399Z,1748020766.399 [DataOverHttps](INFO): SBD MOMSN=24966751 2025-05-23T17:19:27.896Z,1748020767.896 [Default:CheckIn:Read_Iridium] Stopped 2025-05-23T17:19:27.896Z,1748020767.896 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-23T17:19:27.896Z,1748020767.896 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-23T17:24:28.497Z,1748021068.497 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-23T17:24:28.497Z,1748021068.497 [Default:CheckIn:C.Wait] Stopped 2025-05-23T17:24:28.497Z,1748021068.497 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-23T17:24:28.497Z,1748021068.497 [Default:CheckIn:D] Running Loop=1 2025-05-23T17:24:28.902Z,1748021068.902 [Default:CheckIn:D] Stopped 2025-05-23T17:24:28.902Z,1748021068.902 [Default:CheckIn:E] Running Loop=1 2025-05-23T17:24:29.310Z,1748021069.310 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 18.445699 min 2025-05-23T17:24:29.310Z,1748021069.310 [Default:CheckIn:E] Stopped 2025-05-23T17:24:29.310Z,1748021069.310 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-23T17:24:29.310Z,1748021069.310 [Default:CheckIn] Stopped 2025-05-23T17:24:29.310Z,1748021069.310 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-23T17:24:29.310Z,1748021069.310 [Default:CheckIn](INFO): Running loop #4 2025-05-23T17:24:29.310Z,1748021069.310 [Default:CheckIn] Running Loop=4 2025-05-23T17:24:29.310Z,1748021069.310 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-23T17:24:29.311Z,1748021069.311 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-23T17:24:31.312Z,1748021071.312 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172434.00,A,3648.31561,N,12147.47804,W,23.501,45.38,230525,,,D*75 2025-05-23T17:24:31.314Z,1748021071.314 [NAL9602](INFO): GPS fix at 20250523T172434: (36.805260, -121.791301) 2025-05-23T17:24:31.326Z,1748021071.326 [Default:CheckIn:Read_GPS] Stopped 2025-05-23T17:24:31.326Z,1748021071.326 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-23T17:24:38.549Z,1748021078.549 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250523T171609/Courier0003.lzma 2025-05-23T17:24:39.551Z,1748021079.551 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Courier0003.lzma.bak 2025-05-23T17:24:39.551Z,1748021079.551 [DataOverHttps](INFO): SBD MOMSN=24966770 2025-05-23T17:24:58.905Z,1748021098.905 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250523T171609/Express0005.lzma 2025-05-23T17:24:59.907Z,1748021099.907 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Express0005.lzma.bak 2025-05-23T17:24:59.907Z,1748021099.907 [DataOverHttps](INFO): SBD MOMSN=24966773 2025-05-23T17:25:01.274Z,1748021101.274 [Default:CheckIn:Read_Iridium] Stopped 2025-05-23T17:25:01.274Z,1748021101.274 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-23T17:25:01.274Z,1748021101.274 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-23T17:25:02.071Z,1748021102.071 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-05-23T17:25:02.145Z,1748021102.145 [NAL9602](ERROR): received: +CSQ:0 OK658, 2, 0, 0, 0 OK 2025-05-23T17:25:44.082Z,1748021144.082 [NAL9602](INFO): SBD MO Status=2, MOMSN=24658, MT Status=2, MTMSN=0 2025-05-23T17:25:44.082Z,1748021144.082 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-23T17:28:05.489Z,1748021285.489 [NAL9602](INFO): SBD MO Status=2, MOMSN=24658, MT Status=2, MTMSN=0 2025-05-23T17:28:05.489Z,1748021285.489 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-23T17:28:54.796Z,1748021334.796 [NAL9602](INFO): SBD MO Status=2, MOMSN=24658, MT Status=2, MTMSN=0 2025-05-23T17:28:54.796Z,1748021334.796 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-23T17:29:26.692Z,1748021366.692 [NAL9602](INFO): SBD MO Status=2, MOMSN=24658, MT Status=2, MTMSN=0 2025-05-23T17:29:26.692Z,1748021366.692 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-23T17:29:33.158Z,1748021373.158 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-05-23T17:30:01.851Z,1748021401.851 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-23T17:30:01.851Z,1748021401.851 [Default:CheckIn:C.Wait] Stopped 2025-05-23T17:30:01.851Z,1748021401.851 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-23T17:30:01.851Z,1748021401.851 [Default:CheckIn:D] Running Loop=1 2025-05-23T17:30:02.256Z,1748021402.256 [Default:CheckIn:D] Stopped 2025-05-23T17:30:02.256Z,1748021402.256 [Default:CheckIn:E] Running Loop=1 2025-05-23T17:30:02.668Z,1748021402.668 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 24.001595 min 2025-05-23T17:30:02.668Z,1748021402.668 [Default:CheckIn:E] Stopped 2025-05-23T17:30:02.668Z,1748021402.668 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-23T17:30:02.668Z,1748021402.668 [Default:CheckIn] Stopped 2025-05-23T17:30:02.669Z,1748021402.669 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-23T17:30:02.669Z,1748021402.669 [Default:CheckIn](INFO): Running loop #5 2025-05-23T17:30:02.669Z,1748021402.669 [Default:CheckIn] Running Loop=5 2025-05-23T17:30:02.669Z,1748021402.669 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-23T17:30:02.669Z,1748021402.669 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-23T17:30:04.668Z,1748021404.668 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173008.00,A,3648.34987,N,12147.13049,W,3.402,183.90,230525,,,D*77 2025-05-23T17:30:04.670Z,1748021404.670 [NAL9602](INFO): GPS fix at 20250523T173008: (36.805831, -121.785508) 2025-05-23T17:30:04.682Z,1748021404.682 [Default:CheckIn:Read_GPS] Stopped 2025-05-23T17:30:04.682Z,1748021404.682 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-23T17:30:12.188Z,1748021412.188 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250523T171609/Courier0009.lzma 2025-05-23T17:30:13.191Z,1748021413.191 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Courier0009.lzma.bak 2025-05-23T17:30:13.191Z,1748021413.191 [DataOverHttps](INFO): SBD MOMSN=24966837 2025-05-23T17:30:28.969Z,1748021428.969 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20250523T171609/Express0010.lzma 2025-05-23T17:30:29.971Z,1748021429.971 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Express0010.lzma.bak 2025-05-23T17:30:29.971Z,1748021429.971 [DataOverHttps](INFO): SBD MOMSN=24966844 2025-05-23T17:30:31.351Z,1748021431.351 [Default:CheckIn:Read_Iridium] Stopped 2025-05-23T17:30:31.351Z,1748021431.351 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-23T17:30:31.351Z,1748021431.351 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-23T17:30:37.391Z,1748021437.391 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-23T17:35:31.925Z,1748021731.925 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-23T17:35:31.925Z,1748021731.925 [Default:CheckIn:C.Wait] Stopped 2025-05-23T17:35:31.925Z,1748021731.925 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-23T17:35:31.925Z,1748021731.925 [Default:CheckIn:D] Running Loop=1 2025-05-23T17:35:32.331Z,1748021732.331 [Default:CheckIn:D] Stopped 2025-05-23T17:35:32.331Z,1748021732.331 [Default:CheckIn:E] Running Loop=1 2025-05-23T17:35:32.745Z,1748021732.745 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.502854 min 2025-05-23T17:35:32.745Z,1748021732.745 [Default:CheckIn:E] Stopped 2025-05-23T17:35:32.746Z,1748021732.746 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-23T17:35:32.746Z,1748021732.746 [Default:CheckIn] Stopped 2025-05-23T17:35:32.746Z,1748021732.746 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-23T17:35:32.746Z,1748021732.746 [Default:CheckIn](INFO): Running loop #6 2025-05-23T17:35:32.746Z,1748021732.746 [Default:CheckIn] Running Loop=6 2025-05-23T17:35:32.746Z,1748021732.746 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-23T17:35:32.746Z,1748021732.746 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-23T17:35:34.736Z,1748021734.736 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173538.00,A,3648.16585,N,12147.20793,W,0.175,33.99,230525,,,D*4A 2025-05-23T17:35:34.739Z,1748021734.739 [NAL9602](INFO): GPS fix at 20250523T173538: (36.802764, -121.786799) 2025-05-23T17:35:34.771Z,1748021734.771 [Default:CheckIn:Read_GPS] Stopped 2025-05-23T17:35:34.771Z,1748021734.771 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-23T17:35:42.937Z,1748021742.937 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250523T171609/Courier0012.lzma 2025-05-23T17:35:43.939Z,1748021743.939 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Courier0012.lzma.bak 2025-05-23T17:35:43.939Z,1748021743.939 [DataOverHttps](INFO): SBD MOMSN=24966858 2025-05-23T17:35:59.789Z,1748021759.789 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250523T171609/Express0013.lzma 2025-05-23T17:36:00.791Z,1748021760.791 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Express0013.lzma.bak 2025-05-23T17:36:00.791Z,1748021760.791 [DataOverHttps](INFO): SBD MOMSN=24966863 2025-05-23T17:36:02.266Z,1748021762.266 [Default:CheckIn:Read_Iridium] Stopped 2025-05-23T17:36:02.266Z,1748021762.266 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-23T17:36:02.266Z,1748021762.266 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-23T17:36:05.467Z,1748021765.467 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-05-23T17:36:05.541Z,1748021765.541 [NAL9602](ERROR): received: +CSQ:1 OK658, 2, 0, 0, 0 OK 2025-05-23T17:36:55.560Z,1748021815.560 [NAL9602](INFO): SBD MO Status=2, MOMSN=24658, MT Status=2, MTMSN=0 2025-05-23T17:36:55.561Z,1748021815.561 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-23T17:37:27.880Z,1748021847.880 [NAL9602](INFO): SBD MO Status=1, MOMSN=24658, MT Status=0, MTMSN=0 2025-05-23T17:37:27.880Z,1748021847.880 [NAL9602](INFO): No messages in MT queue 2025-05-23T17:37:58.583Z,1748021878.583 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-23T17:41:02.817Z,1748022062.817 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-23T17:41:02.817Z,1748022062.817 [Default:CheckIn:C.Wait] Stopped 2025-05-23T17:41:02.817Z,1748022062.817 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-23T17:41:02.818Z,1748022062.818 [Default:CheckIn:D] Running Loop=1 2025-05-23T17:41:03.223Z,1748022063.223 [Default:CheckIn:D] Stopped 2025-05-23T17:41:03.223Z,1748022063.223 [Default:CheckIn:E] Running Loop=1 2025-05-23T17:41:03.627Z,1748022063.627 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.017721 min 2025-05-23T17:41:03.627Z,1748022063.627 [Default:CheckIn:E] Stopped 2025-05-23T17:41:03.627Z,1748022063.627 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-23T17:41:03.627Z,1748022063.627 [Default:CheckIn] Stopped 2025-05-23T17:41:03.627Z,1748022063.627 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-23T17:41:03.628Z,1748022063.628 [Default:CheckIn](INFO): Running loop #7 2025-05-23T17:41:03.628Z,1748022063.628 [Default:CheckIn] Running Loop=7 2025-05-23T17:41:03.628Z,1748022063.628 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-23T17:41:03.628Z,1748022063.628 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-23T17:41:05.650Z,1748022065.650 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174109.00,A,3648.15283,N,12147.21964,W,0.039,198.54,230525,,,A*73 2025-05-23T17:41:05.652Z,1748022065.652 [NAL9602](INFO): GPS fix at 20250523T174109: (36.802547, -121.786994) 2025-05-23T17:41:05.664Z,1748022065.664 [Default:CheckIn:Read_GPS] Stopped 2025-05-23T17:41:05.664Z,1748022065.664 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-23T17:41:12.821Z,1748022072.821 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250523T171609/Courier0015.lzma 2025-05-23T17:41:13.823Z,1748022073.823 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Courier0015.lzma.bak 2025-05-23T17:41:13.823Z,1748022073.823 [DataOverHttps](INFO): SBD MOMSN=24966957 2025-05-23T17:41:29.789Z,1748022089.789 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250523T171609/Express0016.lzma 2025-05-23T17:41:30.791Z,1748022090.791 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Express0016.lzma.bak 2025-05-23T17:41:30.791Z,1748022090.791 [DataOverHttps](INFO): SBD MOMSN=24966960 2025-05-23T17:41:32.331Z,1748022092.331 [Default:CheckIn:Read_Iridium] Stopped 2025-05-23T17:41:32.331Z,1748022092.331 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-23T17:41:32.331Z,1748022092.331 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-23T17:41:38.380Z,1748022098.380 [NAL9602](INFO): SBD MO Status=2, MOMSN=24659, MT Status=2, MTMSN=0 2025-05-23T17:41:38.380Z,1748022098.380 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-23T17:42:44.660Z,1748022164.660 [NAL9602](INFO): SBD MO Status=2, MOMSN=24659, MT Status=2, MTMSN=0 2025-05-23T17:42:44.660Z,1748022164.660 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-23T17:44:55.580Z,1748022295.580 [NAL9602](INFO): SBD MO Status=1, MOMSN=24659, MT Status=0, MTMSN=0 2025-05-23T17:44:55.580Z,1748022295.580 [NAL9602](INFO): No messages in MT queue 2025-05-23T17:45:26.259Z,1748022326.259 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-23T17:46:32.929Z,1748022392.929 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-23T17:46:32.929Z,1748022392.929 [Default:CheckIn:C.Wait] Stopped 2025-05-23T17:46:32.929Z,1748022392.929 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-23T17:46:32.930Z,1748022392.930 [Default:CheckIn:D] Running Loop=1 2025-05-23T17:46:33.335Z,1748022393.335 [Default:CheckIn:D] Stopped 2025-05-23T17:46:33.335Z,1748022393.335 [Default:CheckIn:E] Running Loop=1 2025-05-23T17:46:33.748Z,1748022393.748 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.519592 min 2025-05-23T17:46:33.749Z,1748022393.749 [Default:CheckIn:E] Stopped 2025-05-23T17:46:33.749Z,1748022393.749 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-23T17:46:33.749Z,1748022393.749 [Default:CheckIn] Stopped 2025-05-23T17:46:33.749Z,1748022393.749 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-23T17:46:33.749Z,1748022393.749 [Default:CheckIn](INFO): Running loop #8 2025-05-23T17:46:33.749Z,1748022393.749 [Default:CheckIn] Running Loop=8 2025-05-23T17:46:33.750Z,1748022393.750 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-23T17:46:33.750Z,1748022393.750 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-23T17:46:35.752Z,1748022395.752 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174639.00,A,3648.15303,N,12147.22681,W,0.019,51.49,230525,,,D*46 2025-05-23T17:46:35.754Z,1748022395.754 [NAL9602](INFO): GPS fix at 20250523T174639: (36.802551, -121.787114) 2025-05-23T17:46:35.785Z,1748022395.785 [Default:CheckIn:Read_GPS] Stopped 2025-05-23T17:46:35.786Z,1748022395.786 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-23T17:46:42.774Z,1748022402.774 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250523T171609/Courier0018.lzma 2025-05-23T17:46:43.775Z,1748022403.775 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Courier0018.lzma.bak 2025-05-23T17:46:43.775Z,1748022403.775 [DataOverHttps](INFO): SBD MOMSN=24967009 2025-05-23T17:47:02.449Z,1748022422.449 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250523T171609/Express0019.lzma 2025-05-23T17:47:03.451Z,1748022423.451 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Express0019.lzma.bak 2025-05-23T17:47:03.451Z,1748022423.451 [DataOverHttps](INFO): SBD MOMSN=24967012 2025-05-23T17:47:04.951Z,1748022424.951 [Default:CheckIn:Read_Iridium] Stopped 2025-05-23T17:47:04.951Z,1748022424.951 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-23T17:47:04.951Z,1748022424.951 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-23T17:47:11.801Z,1748022431.801 [NAL9602](INFO): SBD MO Status=2, MOMSN=24660, MT Status=2, MTMSN=0 2025-05-23T17:47:11.802Z,1748022431.802 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-23T17:47:31.598Z,1748022451.598 [NAL9602](INFO): SBD MO Status=2, MOMSN=24660, MT Status=2, MTMSN=0 2025-05-23T17:47:31.598Z,1748022451.598 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-23T17:47:49.380Z,1748022469.380 [NAL9602](INFO): SBD MO Status=2, MOMSN=24660, MT Status=2, MTMSN=0 2025-05-23T17:47:49.380Z,1748022469.380 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-23T17:48:13.613Z,1748022493.613 [NAL9602](INFO): SBD MO Status=1, MOMSN=24660, MT Status=0, MTMSN=0 2025-05-23T17:48:13.613Z,1748022493.613 [NAL9602](INFO): No messages in MT queue 2025-05-23T17:48:44.315Z,1748022524.315 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-23T17:51:03.696Z,1748022663.696 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-23T17:51:17.433Z,1748022677.433 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-23T17:51:31.170Z,1748022691.170 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-23T17:51:44.907Z,1748022704.907 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-23T17:51:58.238Z,1748022718.238 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-23T17:52:05.521Z,1748022725.521 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-23T17:52:05.522Z,1748022725.522 [Default:CheckIn:C.Wait] Stopped 2025-05-23T17:52:05.522Z,1748022725.522 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-23T17:52:05.522Z,1748022725.522 [Default:CheckIn:D] Running Loop=1 2025-05-23T17:52:05.964Z,1748022725.964 [Default:CheckIn:D] Stopped 2025-05-23T17:52:05.964Z,1748022725.964 [Default:CheckIn:E] Running Loop=1 2025-05-23T17:52:06.332Z,1748022726.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.063391 min 2025-05-23T17:52:06.332Z,1748022726.332 [Default:CheckIn:E] Stopped 2025-05-23T17:52:06.332Z,1748022726.332 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-23T17:52:06.332Z,1748022726.332 [Default:CheckIn] Stopped 2025-05-23T17:52:06.332Z,1748022726.332 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-23T17:52:06.332Z,1748022726.332 [Default:CheckIn](INFO): Running loop #9 2025-05-23T17:52:06.332Z,1748022726.332 [Default:CheckIn] Running Loop=9 2025-05-23T17:52:06.332Z,1748022726.332 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-23T17:52:06.333Z,1748022726.333 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-23T17:52:08.347Z,1748022728.347 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175211.00,A,3648.19019,N,12147.26758,W,0.233,220.95,230525,,,D*73 2025-05-23T17:52:08.349Z,1748022728.349 [NAL9602](INFO): GPS fix at 20250523T175211: (36.803170, -121.787793) 2025-05-23T17:52:08.362Z,1748022728.362 [Default:CheckIn:Read_GPS] Stopped 2025-05-23T17:52:08.362Z,1748022728.362 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-23T17:52:11.654Z,1748022731.654 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-23T17:52:15.461Z,1748022735.461 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250523T171609/Courier0021.lzma 2025-05-23T17:52:16.463Z,1748022736.463 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Courier0021.lzma.bak 2025-05-23T17:52:16.463Z,1748022736.463 [DataOverHttps](INFO): SBD MOMSN=24967084 2025-05-23T17:52:25.389Z,1748022745.389 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-23T17:52:32.365Z,1748022752.365 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250523T171609/Express0022.lzma 2025-05-23T17:52:33.367Z,1748022753.367 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Express0022.lzma.bak 2025-05-23T17:52:33.367Z,1748022753.367 [DataOverHttps](INFO): SBD MOMSN=24967087 2025-05-23T17:52:34.726Z,1748022754.726 [Default:CheckIn:Read_Iridium] Stopped 2025-05-23T17:52:34.726Z,1748022754.726 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-23T17:52:34.726Z,1748022754.726 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-23T17:52:39.127Z,1748022759.127 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-23T17:52:52.055Z,1748022772.055 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-23T17:53:00.968Z,1748022780.968 [NAL9602](INFO): SBD MO Status=2, MOMSN=24661, MT Status=2, MTMSN=0 2025-05-23T17:53:00.968Z,1748022780.968 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-23T17:53:05.386Z,1748022785.386 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-23T17:53:19.122Z,1748022799.122 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-23T17:53:28.825Z,1748022808.825 [NAL9602](INFO): SBD MO Status=1, MOMSN=24661, MT Status=0, MTMSN=0 2025-05-23T17:53:28.825Z,1748022808.825 [NAL9602](INFO): No messages in MT queue 2025-05-23T17:53:59.527Z,1748022839.527 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-23T17:55:47.389Z,1748022947.389 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-23T17:55:47.396Z,1748022947.396 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2025-05-23T17:55:47.397Z,1748022947.397 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-23T17:55:47.398Z,1748022947.398 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-23T17:55:47.398Z,1748022947.398 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-05-23T17:55:47.399Z,1748022947.399 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 5. 2025-05-23T17:55:47.401Z,1748022947.401 [BPC1](INFO): Received data from all battery sticks. 2025-05-23T17:57:35.306Z,1748023055.306 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-23T17:57:35.306Z,1748023055.306 [Default:CheckIn:C.Wait] Stopped 2025-05-23T17:57:35.306Z,1748023055.306 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-23T17:57:35.306Z,1748023055.306 [Default:CheckIn:D] Running Loop=1 2025-05-23T17:57:35.717Z,1748023055.717 [Default:CheckIn:D] Stopped 2025-05-23T17:57:35.717Z,1748023055.717 [Default:CheckIn:E] Running Loop=1 2025-05-23T17:57:36.114Z,1748023056.114 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.559290 min 2025-05-23T17:57:36.114Z,1748023056.114 [Default:CheckIn:E] Stopped 2025-05-23T17:57:36.114Z,1748023056.114 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-23T17:57:36.114Z,1748023056.114 [Default:CheckIn] Stopped 2025-05-23T17:57:36.114Z,1748023056.114 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-23T17:57:36.115Z,1748023056.115 [Default:CheckIn](INFO): Running loop #10 2025-05-23T17:57:36.115Z,1748023056.115 [Default:CheckIn] Running Loop=10 2025-05-23T17:57:36.115Z,1748023056.115 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-23T17:57:36.115Z,1748023056.115 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-23T17:57:38.126Z,1748023058.126 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175741.00,A,3648.18121,N,12147.27542,W,0.117,331.66,230525,,,A*7D 2025-05-23T17:57:38.128Z,1748023058.128 [NAL9602](INFO): GPS fix at 20250523T175741: (36.803020, -121.787924) 2025-05-23T17:57:38.140Z,1748023058.140 [Default:CheckIn:Read_GPS] Stopped 2025-05-23T17:57:38.140Z,1748023058.140 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-23T17:57:45.553Z,1748023065.553 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20250523T171609/Courier0024.lzma 2025-05-23T17:57:46.555Z,1748023066.555 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Courier0024.lzma.bak 2025-05-23T17:57:46.555Z,1748023066.555 [DataOverHttps](INFO): SBD MOMSN=24967112 2025-05-23T17:57:54.284Z,1748023074.284 [NAL9602](INFO): SBD MO Status=1, MOMSN=24662, MT Status=0, MTMSN=0 2025-05-23T17:57:54.284Z,1748023074.284 [NAL9602](INFO): No messages in MT queue 2025-05-23T17:58:02.405Z,1748023082.405 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20250523T171609/Express0025.lzma 2025-05-23T17:58:03.408Z,1748023083.408 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Express0025.lzma.bak 2025-05-23T17:58:03.408Z,1748023083.408 [DataOverHttps](INFO): SBD MOMSN=24967115 2025-05-23T17:58:04.802Z,1748023084.802 [Default:CheckIn:Read_Iridium] Stopped 2025-05-23T17:58:04.802Z,1748023084.802 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-23T17:58:04.802Z,1748023084.802 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-23T17:58:24.986Z,1748023104.986 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-23T18:03:05.423Z,1748023385.423 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-23T18:03:05.423Z,1748023385.423 [Default:CheckIn:C.Wait] Stopped 2025-05-23T18:03:05.423Z,1748023385.423 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-23T18:03:05.423Z,1748023385.423 [Default:CheckIn:D] Running Loop=1 2025-05-23T18:03:05.834Z,1748023385.834 [Default:CheckIn:D] Stopped 2025-05-23T18:03:05.834Z,1748023385.834 [Default:CheckIn:E] Running Loop=1 2025-05-23T18:03:06.248Z,1748023386.248 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 57.061239 min 2025-05-23T18:03:06.248Z,1748023386.248 [Default:CheckIn:E] Stopped 2025-05-23T18:03:06.248Z,1748023386.248 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-23T18:03:06.248Z,1748023386.248 [Default:CheckIn] Stopped 2025-05-23T18:03:06.248Z,1748023386.248 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-23T18:03:06.248Z,1748023386.248 [Default:CheckIn](INFO): Running loop #11 2025-05-23T18:03:06.248Z,1748023386.248 [Default:CheckIn] Running Loop=11 2025-05-23T18:03:06.249Z,1748023386.249 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-23T18:03:06.249Z,1748023386.249 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-23T18:03:08.244Z,1748023388.244 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180311.00,A,3648.18417,N,12147.28142,W,3.499,331.02,230525,,,A*7F 2025-05-23T18:03:08.246Z,1748023388.246 [NAL9602](INFO): GPS fix at 20250523T180311: (36.803069, -121.788024) 2025-05-23T18:03:08.258Z,1748023388.258 [Default:CheckIn:Read_GPS] Stopped 2025-05-23T18:03:08.258Z,1748023388.258 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-23T18:03:15.409Z,1748023395.409 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250523T171609/Courier0027.lzma 2025-05-23T18:03:16.412Z,1748023396.412 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Courier0027.lzma.bak 2025-05-23T18:03:16.412Z,1748023396.412 [DataOverHttps](INFO): SBD MOMSN=24967203 2025-05-23T18:03:29.656Z,1748023409.656 [NAL9602](INFO): SBD MO Status=1, MOMSN=24663, MT Status=0, MTMSN=0 2025-05-23T18:03:29.656Z,1748023409.656 [NAL9602](INFO): No messages in MT queue 2025-05-23T18:03:32.307Z,1748023412.307 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250523T171609/Express0028.lzma 2025-05-23T18:03:33.307Z,1748023413.307 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Express0028.lzma.bak 2025-05-23T18:03:33.307Z,1748023413.307 [DataOverHttps](INFO): SBD MOMSN=24967206 2025-05-23T18:03:34.515Z,1748023414.515 [Default:CheckIn:Read_Iridium] Stopped 2025-05-23T18:03:34.515Z,1748023414.515 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-23T18:03:34.515Z,1748023414.515 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-23T18:04:00.354Z,1748023440.354 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-23T18:08:35.103Z,1748023715.103 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-23T18:08:35.103Z,1748023715.103 [Default:CheckIn:C.Wait] Stopped 2025-05-23T18:08:35.103Z,1748023715.103 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-23T18:08:35.103Z,1748023715.103 [Default:CheckIn:D] Running Loop=1 2025-05-23T18:08:35.514Z,1748023715.514 [Default:CheckIn:D] Stopped 2025-05-23T18:08:35.514Z,1748023715.514 [Default:CheckIn:E] Running Loop=1 2025-05-23T18:08:35.928Z,1748023715.928 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.555896 min 2025-05-23T18:08:35.928Z,1748023715.928 [Default:CheckIn:E] Stopped 2025-05-23T18:08:35.928Z,1748023715.928 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-23T18:08:35.928Z,1748023715.928 [Default:CheckIn] Stopped 2025-05-23T18:08:35.928Z,1748023715.928 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-23T18:08:35.928Z,1748023715.928 [Default:CheckIn](INFO): Running loop #12 2025-05-23T18:08:35.929Z,1748023715.929 [Default:CheckIn] Running Loop=12 2025-05-23T18:08:35.929Z,1748023715.929 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-23T18:08:35.929Z,1748023715.929 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-23T18:08:37.920Z,1748023717.920 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180841.00,A,3648.16034,N,12147.31022,W,2.702,234.19,230525,,,A*7B 2025-05-23T18:08:37.923Z,1748023717.923 [NAL9602](INFO): GPS fix at 20250523T180841: (36.802672, -121.788504) 2025-05-23T18:08:37.934Z,1748023717.934 [Default:CheckIn:Read_GPS] Stopped 2025-05-23T18:08:37.934Z,1748023717.934 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-23T18:08:46.371Z,1748023726.371 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250523T171609/Courier0030.lzma 2025-05-23T18:08:47.371Z,1748023727.371 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Courier0030.lzma.bak 2025-05-23T18:08:47.371Z,1748023727.371 [DataOverHttps](INFO): SBD MOMSN=24967261 2025-05-23T18:09:05.478Z,1748023745.478 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250523T171609/Express0031.lzma 2025-05-23T18:09:06.479Z,1748023746.479 [DataOverHttps](INFO): Moved sent file to Logs/20250523T171609/Express0031.lzma.bak 2025-05-23T18:09:06.479Z,1748023746.479 [DataOverHttps](INFO): SBD MOMSN=24967265 2025-05-23T18:09:07.887Z,1748023747.887 [Default:CheckIn:Read_Iridium] Stopped 2025-05-23T18:09:07.888Z,1748023747.888 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-23T18:09:07.888Z,1748023747.888 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-23T18:13:16.610Z,1748023996.610 [CommandExec](IMPORTANT): got command quit 2025-05-23T18:13:17.613Z,1748023997.613 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-23T18:13:17.613Z,1748023997.613 [CommandExec](INFO): Uninitializing the command executive. 2025-05-23T18:13:17.613Z,1748023997.613 [CommandExec](INFO): Uninitializing the command scheduler. 2025-05-23T18:13:17.614Z,1748023997.614 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:17.638Z,1748023997.638 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-05-23T18:13:17.639Z,1748023997.639 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-05-23T18:13:17.639Z,1748023997.639 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:17.640Z,1748023997.640 [NavChartDb](INFO): Join timeout helper Thread ID is 9425 2025-05-23T18:13:17.673Z,1748023997.673 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-23T18:13:17.673Z,1748023997.673 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:17.681Z,1748023997.681 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-05-23T18:13:17.681Z,1748023997.681 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:17.682Z,1748023997.682 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9426 2025-05-23T18:13:18.097Z,1748023998.097 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-23T18:13:18.098Z,1748023998.098 [WetLabsBB2FL](INFO): Powering down 2025-05-23T18:13:18.098Z,1748023998.098 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:18.117Z,1748023998.117 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-05-23T18:13:18.117Z,1748023998.117 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:18.117Z,1748023998.117 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9427 2025-05-23T18:13:18.501Z,1748023998.501 [CTD_Seabird](INFO): Powering down 2025-05-23T18:13:18.513Z,1748023998.513 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-23T18:13:18.513Z,1748023998.513 [CTD_Seabird](INFO): Powering down 2025-05-23T18:13:18.525Z,1748023998.525 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:18.537Z,1748023998.537 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-05-23T18:13:18.537Z,1748023998.537 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:18.538Z,1748023998.538 [Radio_Surface](INFO): Join timeout helper Thread ID is 9428 2025-05-23T18:13:18.913Z,1748023998.913 [Radio_Surface](INFO): Powering down 2025-05-23T18:13:18.914Z,1748023998.914 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-23T18:13:18.914Z,1748023998.914 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:18.937Z,1748023998.937 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-05-23T18:13:18.939Z,1748023998.939 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:18.939Z,1748023998.939 [Onboard](INFO): Join timeout helper Thread ID is 9429 2025-05-23T18:13:20.845Z,1748024000.845 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-05-23T18:13:22.037Z,1748024002.037 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-23T18:13:22.038Z,1748024002.038 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:22.058Z,1748024002.058 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-05-23T18:13:22.058Z,1748024002.058 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:22.058Z,1748024002.058 [DataOverHttps](INFO): Join timeout helper Thread ID is 9430 2025-05-23T18:13:22.853Z,1748024002.853 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-23T18:13:22.854Z,1748024002.854 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:22.874Z,1748024002.874 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-05-23T18:13:22.874Z,1748024002.874 [DAT ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:22.874Z,1748024002.874 [DAT](INFO): Join timeout helper Thread ID is 9431 2025-05-23T18:13:22.969Z,1748024002.969 [DAT](INFO): Powering down 2025-05-23T18:13:23.046Z,1748024003.046 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-23T18:13:23.046Z,1748024003.046 [DAT](INFO): Powering down 2025-05-23T18:13:23.047Z,1748024003.047 [DAT ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:23.054Z,1748024003.054 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-05-23T18:13:23.054Z,1748024003.054 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:23.054Z,1748024003.054 [BackseatComponent](INFO): Join timeout helper Thread ID is 9432 2025-05-23T18:13:23.205Z,1748024003.205 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-23T18:13:23.205Z,1748024003.205 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:23.207Z,1748024003.207 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-05-23T18:13:23.207Z,1748024003.207 [logger ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:23.207Z,1748024003.207 [logger](INFO): Join timeout helper Thread ID is 9433 2025-05-23T18:13:23.239Z,1748024003.239 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-23T18:13:23.240Z,1748024003.240 [logger ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:23.246Z,1748024003.246 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-05-23T18:13:23.246Z,1748024003.246 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:23.246Z,1748024003.246 [CommandLine](INFO): Join timeout helper Thread ID is 9434 2025-05-23T18:13:23.309Z,1748024003.309 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-23T18:13:23.309Z,1748024003.309 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:23.318Z,1748024003.318 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-05-23T18:13:23.318Z,1748024003.318 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:23.318Z,1748024003.318 [CommandExec](INFO): Join timeout helper Thread ID is 9435 2025-05-23T18:13:23.319Z,1748024003.319 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-05-23T18:13:23.319Z,1748024003.319 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:23.320Z,1748024003.320 [controlThread](INFO): Join timeout helper Thread ID is 9436 2025-05-23T18:13:23.589Z,1748024003.589 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-23T18:13:23.589Z,1748024003.589 [controlThread](DEBUG): Uninitializing ControlThread 2025-05-23T18:13:23.590Z,1748024003.590 [AHRS_M2](INFO): Powering down 2025-05-23T18:13:23.669Z,1748024003.669 [NAL9602](INFO): Powering down 2025-05-23T18:13:23.671Z,1748024003.671 [Sonardyne_Nano](INFO): Powering down 2025-05-23T18:13:23.789Z,1748024003.789 [Waterlinked](INFO): Powering down 2025-05-23T18:13:23.877Z,1748024003.877 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-05-23T18:13:23.878Z,1748024003.878 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-05-23T18:13:23.879Z,1748024003.879 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-05-23T18:13:23.879Z,1748024003.879 [MissionManager](INFO): Uninitializing Mission Default 2025-05-23T18:13:23.879Z,1748024003.879 [Default] Stopped 2025-05-23T18:13:23.879Z,1748024003.879 [Default](DEBUG): Aggregate::uninitialize Default 2025-05-23T18:13:23.879Z,1748024003.879 [Default:B.GoToSurface] Stopped 2025-05-23T18:13:23.879Z,1748024003.879 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-05-23T18:13:23.880Z,1748024003.880 [Default:CheckIn] Stopped 2025-05-23T18:13:23.880Z,1748024003.880 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-23T18:13:23.880Z,1748024003.880 [Default:CheckIn:C.Wait] Stopped 2025-05-23T18:13:23.880Z,1748024003.880 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-23T18:13:23.883Z,1748024003.883 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-05-23T18:13:23.883Z,1748024003.883 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-05-23T18:13:23.883Z,1748024003.883 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-05-23T18:13:23.884Z,1748024003.884 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-05-23T18:13:23.884Z,1748024003.884 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-05-23T18:13:23.884Z,1748024003.884 [BuoyancyServo](INFO): Powering down 2025-05-23T18:13:23.897Z,1748024003.897 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-05-23T18:13:23.897Z,1748024003.897 [ElevatorServo](INFO): Powering down 2025-05-23T18:13:23.898Z,1748024003.898 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-05-23T18:13:23.898Z,1748024003.898 [MassServo](INFO): Powering down 2025-05-23T18:13:23.899Z,1748024003.899 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-05-23T18:13:23.899Z,1748024003.899 [RudderServo](INFO): Powering down 2025-05-23T18:13:23.900Z,1748024003.900 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-23T18:13:23.900Z,1748024003.900 [ThrusterHE](INFO): Powering down 2025-05-23T18:13:23.901Z,1748024003.901 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-05-23T18:13:23.901Z,1748024003.901 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-05-23T18:13:23.902Z,1748024003.902 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-05-23T18:13:23.902Z,1748024003.902 [CBIT](DEBUG): Powering off loads. 2025-05-23T18:13:23.913Z,1748024003.913 [CBIT](DEBUG): Disabling WDT. 2025-05-23T18:13:23.925Z,1748024003.925 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-05-23T18:13:23.925Z,1748024003.925 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-05-23T18:13:23.926Z,1748024003.926 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:23.969Z,1748024003.969 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:23.972Z,1748024003.972 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:23.978Z,1748024003.978 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:23.984Z,1748024003.984 [DAT ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:24.025Z,1748024004.025 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:24.059Z,1748024004.059 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-05-23T18:13:24.059Z,1748024004.059 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-05-23T18:13:24.115Z,1748024004.115 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:24.121Z,1748024004.121 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:24.129Z,1748024004.129 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-05-23T18:13:24.219Z,1748024004.219 [logger ThreadHandler](INFO): Thread cancelled.