2023-10-24T19:47:06.845Z,1698176826.845 [CommandExec](IMPORTANT): got command restart logs 2023-10-24T19:47:37.365Z,1698176857.365 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-24T19:51:26.865Z,1698177086.865 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-24T19:51:26.865Z,1698177086.865 [Default:CheckIn:C.Wait] Stopped 2023-10-24T19:51:26.865Z,1698177086.865 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-24T19:51:26.865Z,1698177086.865 [Default:CheckIn:D] Running Loop=1 2023-10-24T19:51:27.260Z,1698177087.260 [Default:CheckIn:D] Stopped 2023-10-24T19:51:27.260Z,1698177087.260 [Default:CheckIn:E] Running Loop=1 2023-10-24T19:51:27.668Z,1698177087.668 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.825781 min 2023-10-24T19:51:27.668Z,1698177087.668 [Default:CheckIn:E] Stopped 2023-10-24T19:51:27.669Z,1698177087.669 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-24T19:51:27.669Z,1698177087.669 [Default:CheckIn] Stopped 2023-10-24T19:51:27.669Z,1698177087.669 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-24T19:51:27.669Z,1698177087.669 [Default:CheckIn](INFO): Running loop #16 2023-10-24T19:51:27.669Z,1698177087.669 [Default:CheckIn] Running Loop=16 2023-10-24T19:51:27.669Z,1698177087.669 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-24T19:51:27.669Z,1698177087.669 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-24T19:51:29.676Z,1698177089.676 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195131.00,A,3648.17140,N,12147.27620,W,0.019,357.12,241023,,,A*74 2023-10-24T19:51:29.678Z,1698177089.678 [NAL9602](INFO): GPS fix at 20231024T195131: (36.802857, -121.787937) 2023-10-24T19:51:29.690Z,1698177089.690 [Default:CheckIn:Read_GPS] Stopped 2023-10-24T19:51:29.690Z,1698177089.690 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-24T19:51:37.263Z,1698177097.263 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20231024T154218/Courier0070.lzma 2023-10-24T19:51:38.266Z,1698177098.266 [DataOverHttps](INFO): Moved sent file to Logs/20231024T154218/Courier0070.lzma.bak 2023-10-24T19:51:38.266Z,1698177098.266 [DataOverHttps](INFO): SBD MOMSN=19026480 2023-10-24T19:51:58.368Z,1698177118.368 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20231024T194706/Courier0000.lzma 2023-10-24T19:51:59.369Z,1698177119.369 [DataOverHttps](INFO): Moved sent file to Logs/20231024T194706/Courier0000.lzma.bak 2023-10-24T19:51:59.370Z,1698177119.370 [DataOverHttps](INFO): SBD MOMSN=19026482 2023-10-24T19:52:15.879Z,1698177135.879 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20231024T154218/Express0071.lzma 2023-10-24T19:52:16.882Z,1698177136.882 [DataOverHttps](INFO): Moved sent file to Logs/20231024T154218/Express0071.lzma.bak 2023-10-24T19:52:16.882Z,1698177136.882 [DataOverHttps](INFO): SBD MOMSN=19026490 2023-10-24T19:52:33.264Z,1698177153.264 [DataOverHttps](INFO): Sending 445 bytes from file Logs/20231024T194706/Express0001.lzma 2023-10-24T19:52:34.266Z,1698177154.266 [DataOverHttps](INFO): Moved sent file to Logs/20231024T194706/Express0001.lzma.bak 2023-10-24T19:52:34.266Z,1698177154.266 [DataOverHttps](INFO): SBD MOMSN=19026494 2023-10-24T19:52:35.609Z,1698177155.609 [Default:CheckIn:Read_Iridium] Stopped 2023-10-24T19:52:35.609Z,1698177155.609 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-24T19:52:35.609Z,1698177155.609 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-24T19:54:34.847Z,1698177274.847 [NAL9602](INFO): SBD MO Status=2, MOMSN=32153, MT Status=2, MTMSN=0 2023-10-24T19:54:34.848Z,1698177274.848 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-24T19:55:00.306Z,1698177300.306 [NAL9602](INFO): SBD MO Status=0, MOMSN=32153, MT Status=0, MTMSN=0 2023-10-24T19:55:00.306Z,1698177300.306 [NAL9602](INFO): No messages in MT queue 2023-10-24T19:55:31.006Z,1698177331.006 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-24T19:57:36.251Z,1698177456.251 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-24T19:57:36.252Z,1698177456.252 [Default:CheckIn:C.Wait] Stopped 2023-10-24T19:57:36.253Z,1698177456.253 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-24T19:57:36.253Z,1698177456.253 [Default:CheckIn:D] Running Loop=1 2023-10-24T19:57:36.683Z,1698177456.683 [Default:CheckIn:D] Stopped 2023-10-24T19:57:36.683Z,1698177456.683 [Default:CheckIn:E] Running Loop=1 2023-10-24T19:57:37.069Z,1698177457.069 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.982845 min 2023-10-24T19:57:37.069Z,1698177457.069 [Default:CheckIn:E] Stopped 2023-10-24T19:57:37.069Z,1698177457.069 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-24T19:57:37.069Z,1698177457.069 [Default:CheckIn] Stopped 2023-10-24T19:57:37.069Z,1698177457.069 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-24T19:57:37.069Z,1698177457.069 [Default:CheckIn](INFO): Running loop #17 2023-10-24T19:57:37.069Z,1698177457.069 [Default:CheckIn] Running Loop=17 2023-10-24T19:57:37.070Z,1698177457.070 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-24T19:57:37.070Z,1698177457.070 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-24T19:57:39.071Z,1698177459.071 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195741.00,A,3648.17662,N,12147.27857,W,0.117,357.12,241023,,,A*73 2023-10-24T19:57:39.073Z,1698177459.073 [NAL9602](INFO): GPS fix at 20231024T195741: (36.802944, -121.787976) 2023-10-24T19:57:39.105Z,1698177459.105 [Default:CheckIn:Read_GPS] Stopped 2023-10-24T19:57:39.105Z,1698177459.105 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-24T19:57:44.327Z,1698177464.327 [NAL9602](INFO): SBD MO Status=0, MOMSN=32154, MT Status=0, MTMSN=0 2023-10-24T19:57:44.327Z,1698177464.327 [NAL9602](INFO): No messages in MT queue 2023-10-24T19:57:48.923Z,1698177468.923 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231024T194706/Courier0003.lzma 2023-10-24T19:57:49.926Z,1698177469.926 [DataOverHttps](INFO): Moved sent file to Logs/20231024T194706/Courier0003.lzma.bak 2023-10-24T19:57:49.926Z,1698177469.926 [DataOverHttps](INFO): SBD MOMSN=19026509 2023-10-24T19:58:08.823Z,1698177488.823 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20231024T194706/Express0004.lzma 2023-10-24T19:58:09.826Z,1698177489.826 [DataOverHttps](INFO): Moved sent file to Logs/20231024T194706/Express0004.lzma.bak 2023-10-24T19:58:09.826Z,1698177489.826 [DataOverHttps](INFO): SBD MOMSN=19026512 2023-10-24T19:58:11.050Z,1698177491.050 [Default:CheckIn:Read_Iridium] Stopped 2023-10-24T19:58:11.050Z,1698177491.050 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-24T19:58:11.050Z,1698177491.050 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-24T19:58:15.061Z,1698177495.061 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-24T20:03:11.691Z,1698177791.691 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-24T20:03:11.691Z,1698177791.691 [Default:CheckIn:C.Wait] Stopped 2023-10-24T20:03:11.691Z,1698177791.691 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-24T20:03:11.692Z,1698177791.692 [Default:CheckIn:D] Running Loop=1 2023-10-24T20:03:12.104Z,1698177792.104 [Default:CheckIn:D] Stopped 2023-10-24T20:03:12.104Z,1698177792.104 [Default:CheckIn:E] Running Loop=1 2023-10-24T20:03:12.533Z,1698177792.533 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.573185 min 2023-10-24T20:03:12.533Z,1698177792.533 [Default:CheckIn:E] Stopped 2023-10-24T20:03:12.533Z,1698177792.533 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-24T20:03:12.533Z,1698177792.533 [Default:CheckIn] Stopped 2023-10-24T20:03:12.533Z,1698177792.533 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-24T20:03:12.533Z,1698177792.533 [Default:CheckIn](INFO): Running loop #18 2023-10-24T20:03:12.533Z,1698177792.533 [Default:CheckIn] Running Loop=18 2023-10-24T20:03:12.533Z,1698177792.533 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-24T20:03:12.534Z,1698177792.534 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-24T20:03:14.515Z,1698177794.515 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200316.00,A,3648.17905,N,12147.27768,W,0.156,357.12,241023,,,A*72 2023-10-24T20:03:14.517Z,1698177794.517 [NAL9602](INFO): GPS fix at 20231024T200316: (36.802984, -121.787961) 2023-10-24T20:03:14.567Z,1698177794.567 [Default:CheckIn:Read_GPS] Stopped 2023-10-24T20:03:14.567Z,1698177794.567 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-24T20:03:21.987Z,1698177801.987 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20231024T194706/Courier0006.lzma 2023-10-24T20:03:22.990Z,1698177802.990 [DataOverHttps](INFO): Moved sent file to Logs/20231024T194706/Courier0006.lzma.bak 2023-10-24T20:03:22.990Z,1698177802.990 [DataOverHttps](INFO): SBD MOMSN=19026516 2023-10-24T20:03:35.922Z,1698177815.922 [NAL9602](INFO): SBD MO Status=2, MOMSN=32155, MT Status=2, MTMSN=0 2023-10-24T20:03:35.923Z,1698177815.923 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-24T20:03:39.576Z,1698177819.576 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20231024T194706/Express0007.lzma 2023-10-24T20:03:40.578Z,1698177820.578 [DataOverHttps](INFO): Moved sent file to Logs/20231024T194706/Express0007.lzma.bak 2023-10-24T20:03:40.578Z,1698177820.578 [DataOverHttps](INFO): SBD MOMSN=19026519 2023-10-24T20:03:41.000Z,1698177822.000 [Default:CheckIn:Read_Iridium] Stopped 2023-10-24T20:03:42.000Z,1698177822.000 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-24T20:03:42.000Z,1698177822.000 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-24T20:05:02.830Z,1698177902.830 [NAL9602](INFO): SBD MO Status=2, MOMSN=32155, MT Status=2, MTMSN=0 2023-10-24T20:05:02.830Z,1698177902.830 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-24T20:05:20.607Z,1698177920.607 [NAL9602](INFO): SBD MO Status=2, MOMSN=32155, MT Status=2, MTMSN=0 2023-10-24T20:05:20.607Z,1698177920.607 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-24T20:06:06.267Z,1698177966.267 [NAL9602](INFO): SBD MO Status=0, MOMSN=32155, MT Status=0, MTMSN=0 2023-10-24T20:06:06.267Z,1698177966.267 [NAL9602](INFO): No messages in MT queue 2023-10-24T20:06:36.965Z,1698177996.965 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-24T20:08:42.683Z,1698178122.683 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-24T20:08:42.683Z,1698178122.683 [Default:CheckIn:C.Wait] Stopped 2023-10-24T20:08:42.683Z,1698178122.683 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-24T20:08:42.688Z,1698178122.688 [Default:CheckIn:D] Running Loop=1 2023-10-24T20:08:43.035Z,1698178123.035 [Default:CheckIn:D] Stopped 2023-10-24T20:08:43.035Z,1698178123.035 [Default:CheckIn:E] Running Loop=1 2023-10-24T20:08:43.452Z,1698178123.452 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.088704 min 2023-10-24T20:08:43.452Z,1698178123.452 [Default:CheckIn:E] Stopped 2023-10-24T20:08:43.452Z,1698178123.452 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-24T20:08:43.452Z,1698178123.452 [Default:CheckIn] Stopped 2023-10-24T20:08:43.453Z,1698178123.453 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-24T20:08:43.453Z,1698178123.453 [Default:CheckIn](INFO): Running loop #19 2023-10-24T20:08:43.453Z,1698178123.453 [Default:CheckIn] Running Loop=19 2023-10-24T20:08:43.453Z,1698178123.453 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-24T20:08:43.453Z,1698178123.453 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-24T20:08:45.439Z,1698178125.439 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200847.00,A,3648.17758,N,12147.27919,W,0.156,357.12,241023,,,A*73 2023-10-24T20:08:45.441Z,1698178125.441 [NAL9602](INFO): GPS fix at 20231024T200847: (36.802960, -121.787987) 2023-10-24T20:08:45.453Z,1698178125.453 [Default:CheckIn:Read_GPS] Stopped 2023-10-24T20:08:45.453Z,1698178125.453 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-24T20:08:52.727Z,1698178132.727 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20231024T194706/Courier0009.lzma 2023-10-24T20:08:53.730Z,1698178133.730 [DataOverHttps](INFO): Moved sent file to Logs/20231024T194706/Courier0009.lzma.bak 2023-10-24T20:08:53.730Z,1698178133.730 [DataOverHttps](INFO): SBD MOMSN=19026530 2023-10-24T20:09:10.123Z,1698178150.123 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20231024T194706/Express0010.lzma 2023-10-24T20:09:11.126Z,1698178151.126 [DataOverHttps](INFO): Moved sent file to Logs/20231024T194706/Express0010.lzma.bak 2023-10-24T20:09:11.126Z,1698178151.126 [DataOverHttps](INFO): SBD MOMSN=19026533 2023-10-24T20:09:12.605Z,1698178152.605 [Default:CheckIn:Read_Iridium] Stopped 2023-10-24T20:09:12.605Z,1698178152.605 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-24T20:09:12.605Z,1698178152.605 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-24T20:12:42.699Z,1698178362.699 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-10-24T20:12:42.699Z,1698178362.699 [DropWeight] Hardware Fault, FailCount= 1 2023-10-24T20:12:42.699Z,1698178362.699 [DropWeight](ERROR): Hardware Fault 2023-10-24T20:12:42.795Z,1698178362.795 [CommandExec](FAULT): Scheduling is paused for 1 commands 2023-10-24T20:12:42.795Z,1698178362.795 [CBIT](INFO): Critical error at 20231024T201242 2023-10-24T20:12:42.802Z,1698178362.802 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-10-24T20:12:42.802Z,1698178362.802 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-10-24T20:12:43.114Z,1698178363.114 [CBIT](INFO): Critical error at 20231024T201242 2023-10-24T20:13:25.922Z,1698178405.922 [NAL9602](INFO): SBD MO Status=0, MOMSN=32156, MT Status=0, MTMSN=0 2023-10-24T20:13:25.922Z,1698178405.922 [NAL9602](INFO): No messages in MT queue 2023-10-24T20:13:56.632Z,1698178436.632 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-24T20:14:13.194Z,1698178453.194 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-24T20:14:13.194Z,1698178453.194 [Default:CheckIn:C.Wait] Stopped 2023-10-24T20:14:13.194Z,1698178453.194 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-24T20:14:13.195Z,1698178453.195 [Default:CheckIn:D] Running Loop=1 2023-10-24T20:14:13.611Z,1698178453.611 [Default:CheckIn:D] Stopped 2023-10-24T20:14:13.611Z,1698178453.611 [Default:CheckIn:E] Running Loop=1 2023-10-24T20:14:14.022Z,1698178454.022 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.598299 min 2023-10-24T20:14:14.022Z,1698178454.022 [Default:CheckIn:E] Stopped 2023-10-24T20:14:14.023Z,1698178454.023 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-24T20:14:14.023Z,1698178454.023 [Default:CheckIn] Stopped 2023-10-24T20:14:14.023Z,1698178454.023 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-24T20:14:14.023Z,1698178454.023 [Default:CheckIn](INFO): Running loop #20 2023-10-24T20:14:14.023Z,1698178454.023 [Default:CheckIn] Running Loop=20 2023-10-24T20:14:14.023Z,1698178454.023 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-24T20:14:14.023Z,1698178454.023 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-24T20:14:16.018Z,1698178456.018 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201418.00,A,3648.17494,N,12147.27615,W,2.002,0.00,241023,,,A*74 2023-10-24T20:14:16.021Z,1698178456.021 [NAL9602](INFO): GPS fix at 20231024T201418: (36.802916, -121.787936) 2023-10-24T20:14:16.054Z,1698178456.054 [Default:CheckIn:Read_GPS] Stopped 2023-10-24T20:14:16.054Z,1698178456.054 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-24T20:14:23.931Z,1698178463.931 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20231024T194706/Courier0012.lzma 2023-10-24T20:14:24.934Z,1698178464.934 [DataOverHttps](INFO): Moved sent file to Logs/20231024T194706/Courier0012.lzma.bak 2023-10-24T20:14:24.934Z,1698178464.934 [DataOverHttps](INFO): SBD MOMSN=19026536 2023-10-24T20:14:41.415Z,1698178481.415 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20231024T194706/Express0013.lzma 2023-10-24T20:14:42.427Z,1698178482.427 [DataOverHttps](INFO): Moved sent file to Logs/20231024T194706/Express0013.lzma.bak 2023-10-24T20:14:42.427Z,1698178482.427 [DataOverHttps](INFO): SBD MOMSN=19026541 2023-10-24T20:14:43.929Z,1698178483.929 [Default:CheckIn:Read_Iridium] Stopped 2023-10-24T20:14:43.929Z,1698178483.929 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-24T20:14:43.929Z,1698178483.929 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-24T20:14:48.754Z,1698178488.754 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,2310241215534717.6, 0.0,1514.6, 0 2023-10-24T20:15:38.475Z,1698178538.475 [NAL9602](INFO): SBD MO Status=2, MOMSN=32157, MT Status=2, MTMSN=0 2023-10-24T20:15:38.476Z,1698178538.476 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-24T20:17:05.297Z,1698178625.297 [NAL9602](INFO): SBD MO Status=2, MOMSN=32157, MT Status=2, MTMSN=0 2023-10-24T20:17:05.298Z,1698178625.298 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-10-24T20:19:18.669Z,1698178758.669 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-10-24T20:19:44.553Z,1698178784.553 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-10-24T20:19:44.554Z,1698178784.554 [Default:CheckIn:C.Wait] Stopped 2023-10-24T20:19:44.554Z,1698178784.554 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-24T20:19:44.554Z,1698178784.554 [Default:CheckIn:D] Running Loop=1 2023-10-24T20:19:44.947Z,1698178784.947 [Default:CheckIn:D] Stopped 2023-10-24T20:19:44.947Z,1698178784.947 [Default:CheckIn:E] Running Loop=1 2023-10-24T20:19:45.353Z,1698178785.353 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.120573 min 2023-10-24T20:19:45.353Z,1698178785.353 [Default:CheckIn:E] Stopped 2023-10-24T20:19:45.353Z,1698178785.353 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-10-24T20:19:45.353Z,1698178785.353 [Default:CheckIn] Stopped 2023-10-24T20:19:45.354Z,1698178785.354 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-24T20:19:45.354Z,1698178785.354 [Default:CheckIn](INFO): Running loop #21 2023-10-24T20:19:45.354Z,1698178785.354 [Default:CheckIn] Running Loop=21 2023-10-24T20:19:45.354Z,1698178785.354 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-10-24T20:19:45.354Z,1698178785.354 [Default:CheckIn:Read_GPS] Running Loop=1 2023-10-24T20:24:45.562Z,1698179085.562 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-10-24T20:19:45.4Z 2023-10-24T20:24:45.562Z,1698179085.562 [Default:CheckIn:Read_GPS] Stopped 2023-10-24T20:24:45.562Z,1698179085.562 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-10-24T20:24:58.255Z,1698179098.255 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20231024T194706/Courier0015.lzma 2023-10-24T20:24:59.258Z,1698179099.258 [DataOverHttps](INFO): Moved sent file to Logs/20231024T194706/Courier0015.lzma.bak 2023-10-24T20:24:59.258Z,1698179099.258 [DataOverHttps](INFO): SBD MOMSN=19026563 2023-10-24T20:25:16.903Z,1698179116.903 [NAL9602](INFO): Not Powering down - fast GPS 2023-10-24T20:25:25.460Z,1698179125.460 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20231024T194706/Express0016.lzma 2023-10-24T20:25:26.462Z,1698179126.462 [DataOverHttps](INFO): Moved sent file to Logs/20231024T194706/Express0016.lzma.bak 2023-10-24T20:25:26.463Z,1698179126.463 [DataOverHttps](INFO): SBD MOMSN=19026565 2023-10-24T20:25:28.253Z,1698179128.253 [Default:CheckIn:Read_Iridium] Stopped 2023-10-24T20:25:28.254Z,1698179128.254 [Default:CheckIn:C.Wait] Running Loop=1 2023-10-24T20:25:28.254Z,1698179128.254 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-10-24T20:27:42.800Z,1698179262.800 [CBIT](INFO): Clearing failed state for component DropWeight 2023-10-24T20:27:42.800Z,1698179262.800 [DropWeight] No Fault, FailCount= 1 2023-10-24T20:29:31.445Z,1698179371.445 [CommandExec](IMPORTANT): got command burn off 2023-10-24T20:29:31.445Z,1698179371.445 [CommandExec](IMPORTANT): Deactivating dropweight wire 2023-10-24T20:29:42.913Z,1698179382.913 [CommandExec](IMPORTANT): got command quit 2023-10-24T20:29:43.920Z,1698179383.920 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-24T20:29:43.920Z,1698179383.920 [CommandExec](INFO): Uninitializing the command executive. 2023-10-24T20:29:43.920Z,1698179383.920 [CommandExec](INFO): Uninitializing the command scheduler. 2023-10-24T20:29:43.921Z,1698179383.921 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:44.056Z,1698179384.056 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-10-24T20:29:44.056Z,1698179384.056 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-10-24T20:29:44.056Z,1698179384.056 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:44.057Z,1698179384.057 [NavChartDb](INFO): Join timeout helper Thread ID is 4665 2023-10-24T20:29:44.398Z,1698179384.398 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-24T20:29:44.399Z,1698179384.399 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:44.416Z,1698179384.416 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2023-10-24T20:29:44.416Z,1698179384.416 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:44.418Z,1698179384.418 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 4666 2023-10-24T20:29:44.652Z,1698179384.652 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-24T20:29:44.653Z,1698179384.653 [WetLabsUBAT](INFO): Powering down 2023-10-24T20:29:44.653Z,1698179384.653 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:44.660Z,1698179384.660 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-10-24T20:29:44.660Z,1698179384.660 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:44.661Z,1698179384.661 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4667 2023-10-24T20:29:44.852Z,1698179384.852 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-24T20:29:44.852Z,1698179384.852 [WetLabsBB2FL](INFO): Powering down 2023-10-24T20:29:44.853Z,1698179384.853 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:44.856Z,1698179384.856 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-10-24T20:29:44.856Z,1698179384.856 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:44.856Z,1698179384.856 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4668 2023-10-24T20:29:44.884Z,1698179384.884 [CTD_Seabird](INFO): Powering down 2023-10-24T20:29:44.896Z,1698179384.896 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-24T20:29:44.896Z,1698179384.896 [CTD_Seabird](INFO): Powering down 2023-10-24T20:29:44.908Z,1698179384.908 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:44.916Z,1698179384.916 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-10-24T20:29:44.916Z,1698179384.916 [DAT ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:44.917Z,1698179384.917 [DAT](INFO): Join timeout helper Thread ID is 4669 2023-10-24T20:29:44.984Z,1698179384.984 [DAT](INFO): Powering down 2023-10-24T20:29:45.057Z,1698179385.057 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-24T20:29:45.057Z,1698179385.057 [DAT](INFO): Powering down 2023-10-24T20:29:45.058Z,1698179385.058 [DAT ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:45.077Z,1698179385.077 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-10-24T20:29:45.077Z,1698179385.077 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:45.077Z,1698179385.077 [Radio_Surface](INFO): Join timeout helper Thread ID is 4670 2023-10-24T20:29:45.472Z,1698179385.472 [Radio_Surface](INFO): Powering down 2023-10-24T20:29:45.473Z,1698179385.473 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-24T20:29:45.473Z,1698179385.473 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:45.481Z,1698179385.481 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-10-24T20:29:45.481Z,1698179385.481 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:45.481Z,1698179385.481 [Onboard](INFO): Join timeout helper Thread ID is 4671 2023-10-24T20:29:49.124Z,1698179389.124 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-24T20:29:49.124Z,1698179389.124 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:49.133Z,1698179389.133 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-10-24T20:29:49.133Z,1698179389.133 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:49.133Z,1698179389.133 [DataOverHttps](INFO): Join timeout helper Thread ID is 4672 2023-10-24T20:29:49.760Z,1698179389.760 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-24T20:29:49.763Z,1698179389.763 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:49.781Z,1698179389.781 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-10-24T20:29:49.782Z,1698179389.782 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:49.782Z,1698179389.782 [BackseatComponent](INFO): Join timeout helper Thread ID is 4673 2023-10-24T20:29:49.872Z,1698179389.872 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-24T20:29:49.873Z,1698179389.873 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:49.880Z,1698179389.880 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-10-24T20:29:49.881Z,1698179389.881 [logger ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:49.881Z,1698179389.881 [logger](INFO): Join timeout helper Thread ID is 4674 2023-10-24T20:29:49.924Z,1698179389.924 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-24T20:29:49.924Z,1698179389.924 [logger ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:49.941Z,1698179389.941 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-10-24T20:29:49.941Z,1698179389.941 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:49.941Z,1698179389.941 [CommandLine](INFO): Join timeout helper Thread ID is 4675 2023-10-24T20:29:49.968Z,1698179389.968 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-24T20:29:49.968Z,1698179389.968 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:49.981Z,1698179389.981 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-10-24T20:29:49.981Z,1698179389.981 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:49.981Z,1698179389.981 [CommandExec](INFO): Join timeout helper Thread ID is 4676 2023-10-24T20:29:49.982Z,1698179389.982 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-10-24T20:29:49.982Z,1698179389.982 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:49.983Z,1698179389.983 [controlThread](INFO): Join timeout helper Thread ID is 4677 2023-10-24T20:29:49.992Z,1698179389.992 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-10-24T20:29:49.992Z,1698179389.992 [controlThread](DEBUG): Uninitializing ControlThread 2023-10-24T20:29:49.993Z,1698179389.993 [AHRS_M2](INFO): Powering down 2023-10-24T20:29:50.065Z,1698179390.065 [NAL9602](INFO): Powering down 2023-10-24T20:29:50.136Z,1698179390.136 [RDI_Pathfinder](INFO): Powering down 2023-10-24T20:29:50.137Z,1698179390.137 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-10-24T20:29:50.138Z,1698179390.138 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-10-24T20:29:50.139Z,1698179390.139 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-10-24T20:29:50.139Z,1698179390.139 [MissionManager](INFO): Uninitializing Mission Default 2023-10-24T20:29:50.139Z,1698179390.139 [Default] Stopped 2023-10-24T20:29:50.139Z,1698179390.139 [Default](DEBUG): Aggregate::uninitialize Default 2023-10-24T20:29:50.139Z,1698179390.139 [Default:B.GoToSurface] Stopped 2023-10-24T20:29:50.139Z,1698179390.139 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-10-24T20:29:50.140Z,1698179390.140 [Default:CheckIn] Stopped 2023-10-24T20:29:50.140Z,1698179390.140 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-10-24T20:29:50.140Z,1698179390.140 [Default:CheckIn:C.Wait] Stopped 2023-10-24T20:29:50.140Z,1698179390.140 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-10-24T20:29:50.143Z,1698179390.143 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-10-24T20:29:50.143Z,1698179390.143 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-10-24T20:29:50.143Z,1698179390.143 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-10-24T20:29:50.144Z,1698179390.144 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-10-24T20:29:50.144Z,1698179390.144 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-10-24T20:29:50.144Z,1698179390.144 [BuoyancyServo](INFO): Powering down 2023-10-24T20:29:50.156Z,1698179390.156 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-10-24T20:29:50.156Z,1698179390.156 [ElevatorServo](INFO): Powering down 2023-10-24T20:29:50.157Z,1698179390.157 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-10-24T20:29:50.157Z,1698179390.157 [MassServo](INFO): Powering down 2023-10-24T20:29:50.158Z,1698179390.158 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-10-24T20:29:50.158Z,1698179390.158 [RudderServo](INFO): Powering down 2023-10-24T20:29:50.159Z,1698179390.159 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-10-24T20:29:50.159Z,1698179390.159 [ThrusterServo](INFO): Powering down 2023-10-24T20:29:50.160Z,1698179390.160 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-10-24T20:29:50.160Z,1698179390.160 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-10-24T20:29:50.161Z,1698179390.161 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-10-24T20:29:50.161Z,1698179390.161 [CBIT](DEBUG): Powering off loads. 2023-10-24T20:29:50.172Z,1698179390.172 [CBIT](DEBUG): Disabling WDT. 2023-10-24T20:29:50.184Z,1698179390.184 [CBIT](DEBUG): Opening all GF detection circuits. 2023-10-24T20:29:50.185Z,1698179390.185 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:50.242Z,1698179390.242 [DAT ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:50.250Z,1698179390.250 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:50.252Z,1698179390.252 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:50.259Z,1698179390.259 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:50.302Z,1698179390.302 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:50.361Z,1698179390.361 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:50.363Z,1698179390.363 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:50.367Z,1698179390.367 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:50.375Z,1698179390.375 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-10-24T20:29:50.450Z,1698179390.450 [logger ThreadHandler](INFO): Thread cancelled.