2025-12-17T19:28:21.901Z,1765999701.901 [CommandExec](IMPORTANT): got command restart logs 2025-12-17T19:30:33.799Z,1765999833.799 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T19:30:33.800Z,1765999833.800 [Default:CheckIn:C.Wait] Stopped 2025-12-17T19:30:33.800Z,1765999833.800 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T19:30:33.800Z,1765999833.800 [Default:CheckIn:D] Running Loop=1 2025-12-17T19:30:34.212Z,1765999834.212 [Default:CheckIn:D] Stopped 2025-12-17T19:30:34.212Z,1765999834.212 [Default:CheckIn:E] Running Loop=1 2025-12-17T19:30:34.609Z,1765999834.609 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.597327 min 2025-12-17T19:30:34.609Z,1765999834.609 [Default:CheckIn:E] Stopped 2025-12-17T19:30:34.609Z,1765999834.609 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T19:30:34.609Z,1765999834.609 [Default:CheckIn] Stopped 2025-12-17T19:30:34.610Z,1765999834.610 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T19:30:34.610Z,1765999834.610 [Default:CheckIn](INFO): Running loop #7 2025-12-17T19:30:34.610Z,1765999834.610 [Default:CheckIn] Running Loop=7 2025-12-17T19:30:34.610Z,1765999834.610 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T19:30:34.610Z,1765999834.610 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T19:30:36.620Z,1765999836.620 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193035.00,A,3648.25423,N,12149.48922,W,0.097,117.02,171225,,,D*7C 2025-12-17T19:30:36.622Z,1765999836.622 [NAL9602](INFO): GPS fix at 20251217T193035: (36.804237, -121.824820) 2025-12-17T19:30:36.633Z,1765999836.633 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T19:30:36.633Z,1765999836.633 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T19:30:43.959Z,1765999843.959 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20251217T183105/Courier0037.lzma 2025-12-17T19:30:44.962Z,1765999844.962 [DataOverHttps](INFO): Moved sent file to Logs/20251217T183105/Courier0037.lzma.bak 2025-12-17T19:30:44.962Z,1765999844.962 [DataOverHttps](INFO): SBD MOMSN=26515766 2025-12-17T19:31:00.824Z,1765999860.824 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20251217T192821/Courier0000.lzma 2025-12-17T19:31:01.826Z,1765999861.826 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0000.lzma.bak 2025-12-17T19:31:01.826Z,1765999861.826 [DataOverHttps](INFO): SBD MOMSN=26515768 2025-12-17T19:31:08.130Z,1765999868.130 [NAL9602](INFO): SBD MO Status=2, MOMSN=454, MT Status=2, MTMSN=0 2025-12-17T19:31:08.131Z,1765999868.131 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-17T19:31:17.868Z,1765999877.868 [DataOverHttps](INFO): Sending 446 bytes from file Logs/20251217T183105/Express0038.lzma 2025-12-17T19:31:18.870Z,1765999878.870 [DataOverHttps](INFO): Moved sent file to Logs/20251217T183105/Express0038.lzma.bak 2025-12-17T19:31:18.870Z,1765999878.870 [DataOverHttps](INFO): SBD MOMSN=26515773 2025-12-17T19:31:23.078Z,1765999883.078 [NAL9602](INFO): SBD MO Status=2, MOMSN=454, MT Status=2, MTMSN=0 2025-12-17T19:31:23.078Z,1765999883.078 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-17T19:31:34.849Z,1765999894.849 [DataOverHttps](INFO): Sending 629 bytes from file Logs/20251217T192821/Express0001.lzma 2025-12-17T19:31:35.850Z,1765999895.850 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0001.lzma.bak 2025-12-17T19:31:35.850Z,1765999895.850 [DataOverHttps](INFO): SBD MOMSN=26515776 2025-12-17T19:31:51.835Z,1765999911.835 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20251217T192821/Express0004.lzma 2025-12-17T19:31:52.838Z,1765999912.838 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0004.lzma.bak 2025-12-17T19:31:52.838Z,1765999912.838 [DataOverHttps](INFO): SBD MOMSN=26515780 2025-12-17T19:31:54.217Z,1765999914.217 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T19:31:54.217Z,1765999914.217 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T19:31:54.217Z,1765999914.217 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T19:31:57.418Z,1765999917.418 [NAL9602](INFO): SBD MO Status=2, MOMSN=454, MT Status=2, MTMSN=0 2025-12-17T19:31:57.418Z,1765999917.418 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-17T19:32:26.101Z,1765999946.101 [NAL9602](INFO): SBD MO Status=2, MOMSN=454, MT Status=2, MTMSN=0 2025-12-17T19:32:26.101Z,1765999946.101 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-17T19:32:43.070Z,1765999963.070 [NAL9602](INFO): SBD MO Status=0, MOMSN=454, MT Status=0, MTMSN=0 2025-12-17T19:32:43.071Z,1765999963.071 [NAL9602](INFO): No messages in MT queue 2025-12-17T19:33:13.771Z,1765999993.771 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T19:36:54.787Z,1766000214.787 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T19:36:54.788Z,1766000214.788 [Default:CheckIn:C.Wait] Stopped 2025-12-17T19:36:54.788Z,1766000214.788 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T19:36:54.788Z,1766000214.788 [Default:CheckIn:D] Running Loop=1 2025-12-17T19:36:55.175Z,1766000215.175 [Default:CheckIn:D] Stopped 2025-12-17T19:36:55.175Z,1766000215.175 [Default:CheckIn:E] Running Loop=1 2025-12-17T19:36:55.580Z,1766000215.580 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.946704 min 2025-12-17T19:36:55.580Z,1766000215.580 [Default:CheckIn:E] Stopped 2025-12-17T19:36:55.581Z,1766000215.581 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T19:36:55.581Z,1766000215.581 [Default:CheckIn] Stopped 2025-12-17T19:36:55.581Z,1766000215.581 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T19:36:55.581Z,1766000215.581 [Default:CheckIn](INFO): Running loop #8 2025-12-17T19:36:55.581Z,1766000215.581 [Default:CheckIn] Running Loop=8 2025-12-17T19:36:55.581Z,1766000215.581 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T19:36:55.581Z,1766000215.581 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T19:36:57.599Z,1766000217.599 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193656.00,A,3648.18505,N,12148.69649,W,18.175,114.79,171225,,,D*4F 2025-12-17T19:36:57.601Z,1766000217.601 [NAL9602](INFO): GPS fix at 20251217T193656: (36.803084, -121.811608) 2025-12-17T19:36:57.612Z,1766000217.612 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T19:36:57.612Z,1766000217.612 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T19:37:05.599Z,1766000225.599 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251217T192821/Courier0006.lzma 2025-12-17T19:37:06.602Z,1766000226.602 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0006.lzma.bak 2025-12-17T19:37:06.602Z,1766000226.602 [DataOverHttps](INFO): SBD MOMSN=26515924 2025-12-17T19:37:22.588Z,1766000242.588 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20251217T192821/Express0007.lzma 2025-12-17T19:37:23.590Z,1766000243.590 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0007.lzma.bak 2025-12-17T19:37:23.590Z,1766000243.590 [DataOverHttps](INFO): SBD MOMSN=26515934 2025-12-17T19:37:25.081Z,1766000245.081 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T19:37:25.081Z,1766000245.081 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T19:37:25.081Z,1766000245.081 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T19:37:36.374Z,1766000256.374 [NAL9602](INFO): SBD MO Status=0, MOMSN=455, MT Status=0, MTMSN=0 2025-12-17T19:37:36.375Z,1766000256.375 [NAL9602](INFO): No messages in MT queue 2025-12-17T19:38:07.077Z,1766000287.077 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T19:42:25.654Z,1766000545.654 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T19:42:25.654Z,1766000545.654 [Default:CheckIn:C.Wait] Stopped 2025-12-17T19:42:25.654Z,1766000545.654 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T19:42:25.654Z,1766000545.654 [Default:CheckIn:D] Running Loop=1 2025-12-17T19:42:26.074Z,1766000546.074 [Default:CheckIn:D] Stopped 2025-12-17T19:42:26.074Z,1766000546.074 [Default:CheckIn:E] Running Loop=1 2025-12-17T19:42:26.484Z,1766000546.484 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.461694 min 2025-12-17T19:42:26.484Z,1766000546.484 [Default:CheckIn:E] Stopped 2025-12-17T19:42:26.484Z,1766000546.484 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T19:42:26.484Z,1766000546.484 [Default:CheckIn] Stopped 2025-12-17T19:42:26.484Z,1766000546.484 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T19:42:26.485Z,1766000546.485 [Default:CheckIn](INFO): Running loop #9 2025-12-17T19:42:26.485Z,1766000546.485 [Default:CheckIn] Running Loop=9 2025-12-17T19:42:26.485Z,1766000546.485 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T19:42:26.485Z,1766000546.485 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T19:42:28.480Z,1766000548.480 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194227.00,A,3648.50618,N,12147.20021,W,5.812,85.02,171225,,,D*42 2025-12-17T19:42:28.482Z,1766000548.482 [NAL9602](INFO): GPS fix at 20251217T194227: (36.808436, -121.786670) 2025-12-17T19:42:28.517Z,1766000548.517 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T19:42:28.518Z,1766000548.518 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T19:42:35.851Z,1766000555.851 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251217T192821/Courier0009.lzma 2025-12-17T19:42:36.854Z,1766000556.854 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0009.lzma.bak 2025-12-17T19:42:36.854Z,1766000556.854 [DataOverHttps](INFO): SBD MOMSN=26515966 2025-12-17T19:42:41.006Z,1766000561.006 [NAL9602](INFO): SBD MO Status=0, MOMSN=456, MT Status=0, MTMSN=0 2025-12-17T19:42:41.006Z,1766000561.006 [NAL9602](INFO): No messages in MT queue 2025-12-17T19:42:52.796Z,1766000572.796 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20251217T192821/Express0010.lzma 2025-12-17T19:42:53.798Z,1766000573.798 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0010.lzma.bak 2025-12-17T19:42:53.798Z,1766000573.798 [DataOverHttps](INFO): SBD MOMSN=26515977 2025-12-17T19:42:55.160Z,1766000575.160 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T19:42:55.160Z,1766000575.160 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T19:42:55.160Z,1766000575.160 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T19:43:11.699Z,1766000591.699 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T19:47:55.744Z,1766000875.744 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T19:47:55.744Z,1766000875.744 [Default:CheckIn:C.Wait] Stopped 2025-12-17T19:47:55.744Z,1766000875.744 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T19:47:55.744Z,1766000875.744 [Default:CheckIn:D] Running Loop=1 2025-12-17T19:47:56.147Z,1766000876.147 [Default:CheckIn:D] Stopped 2025-12-17T19:47:56.147Z,1766000876.147 [Default:CheckIn:E] Running Loop=1 2025-12-17T19:47:56.560Z,1766000876.560 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.962899 min 2025-12-17T19:47:56.560Z,1766000876.560 [Default:CheckIn:E] Stopped 2025-12-17T19:47:56.560Z,1766000876.560 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T19:47:56.560Z,1766000876.560 [Default:CheckIn] Stopped 2025-12-17T19:47:56.560Z,1766000876.560 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T19:47:56.560Z,1766000876.560 [Default:CheckIn](INFO): Running loop #10 2025-12-17T19:47:56.560Z,1766000876.560 [Default:CheckIn] Running Loop=10 2025-12-17T19:47:56.561Z,1766000876.561 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T19:47:56.561Z,1766000876.561 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T19:47:58.562Z,1766000878.562 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194757.00,A,3648.18781,N,12147.18197,W,2.857,216.02,171225,,,D*74 2025-12-17T19:47:58.564Z,1766000878.564 [NAL9602](INFO): GPS fix at 20251217T194757: (36.803130, -121.786366) 2025-12-17T19:47:58.587Z,1766000878.587 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T19:47:58.587Z,1766000878.587 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T19:48:05.823Z,1766000885.823 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251217T192821/Courier0012.lzma 2025-12-17T19:48:06.826Z,1766000886.826 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0012.lzma.bak 2025-12-17T19:48:06.826Z,1766000886.826 [DataOverHttps](INFO): SBD MOMSN=26516089 2025-12-17T19:48:14.726Z,1766000894.726 [NAL9602](INFO): SBD MO Status=0, MOMSN=457, MT Status=0, MTMSN=0 2025-12-17T19:48:14.726Z,1766000894.726 [NAL9602](INFO): No messages in MT queue 2025-12-17T19:48:25.548Z,1766000905.548 [DataOverHttps](INFO): Sending 343 bytes from file Logs/20251217T192821/Express0013.lzma 2025-12-17T19:48:26.550Z,1766000906.550 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0013.lzma.bak 2025-12-17T19:48:26.550Z,1766000906.550 [DataOverHttps](INFO): SBD MOMSN=26516092 2025-12-17T19:48:27.733Z,1766000907.733 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T19:48:27.733Z,1766000907.733 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T19:48:27.733Z,1766000907.733 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T19:48:45.486Z,1766000925.486 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T19:53:28.306Z,1766001208.306 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T19:53:28.306Z,1766001208.306 [Default:CheckIn:C.Wait] Stopped 2025-12-17T19:53:28.306Z,1766001208.306 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T19:53:28.307Z,1766001208.307 [Default:CheckIn:D] Running Loop=1 2025-12-17T19:53:28.719Z,1766001208.719 [Default:CheckIn:D] Stopped 2025-12-17T19:53:28.719Z,1766001208.719 [Default:CheckIn:E] Running Loop=1 2025-12-17T19:53:29.127Z,1766001209.127 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.505770 min 2025-12-17T19:53:29.128Z,1766001209.128 [Default:CheckIn:E] Stopped 2025-12-17T19:53:29.128Z,1766001209.128 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T19:53:29.128Z,1766001209.128 [Default:CheckIn] Stopped 2025-12-17T19:53:29.128Z,1766001209.128 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T19:53:29.128Z,1766001209.128 [Default:CheckIn](INFO): Running loop #11 2025-12-17T19:53:29.128Z,1766001209.128 [Default:CheckIn] Running Loop=11 2025-12-17T19:53:29.128Z,1766001209.128 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T19:53:29.129Z,1766001209.129 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T19:53:31.131Z,1766001211.131 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195329.00,A,3648.15143,N,12147.21827,W,0.019,204.47,171225,,,D*77 2025-12-17T19:53:31.133Z,1766001211.133 [NAL9602](INFO): GPS fix at 20251217T195329: (36.802524, -121.786971) 2025-12-17T19:53:31.143Z,1766001211.143 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T19:53:31.143Z,1766001211.143 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T19:53:31.944Z,1766001211.944 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T19:53:31.944Z,1766001211.944 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T19:53:31.944Z,1766001211.944 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T19:56:11.541Z,1766001371.541 [NAL9602](INFO): SBD MO Status=2, MOMSN=458, MT Status=2, MTMSN=0 2025-12-17T19:56:11.541Z,1766001371.541 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-17T19:57:01.205Z,1766001421.205 [NAL9602](INFO): SBD MO Status=0, MOMSN=458, MT Status=0, MTMSN=0 2025-12-17T19:57:01.205Z,1766001421.205 [NAL9602](INFO): No messages in MT queue 2025-12-17T19:57:31.909Z,1766001451.909 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T19:58:32.525Z,1766001512.525 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T19:58:32.525Z,1766001512.525 [Default:CheckIn:C.Wait] Stopped 2025-12-17T19:58:32.525Z,1766001512.525 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T19:58:32.525Z,1766001512.525 [Default:CheckIn:D] Running Loop=1 2025-12-17T19:58:32.938Z,1766001512.938 [Default:CheckIn:D] Stopped 2025-12-17T19:58:32.938Z,1766001512.938 [Default:CheckIn:E] Running Loop=1 2025-12-17T19:58:33.334Z,1766001513.334 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.576086 min 2025-12-17T19:58:33.334Z,1766001513.334 [Default:CheckIn:E] Stopped 2025-12-17T19:58:33.334Z,1766001513.334 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T19:58:33.334Z,1766001513.334 [Default:CheckIn] Stopped 2025-12-17T19:58:33.334Z,1766001513.334 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T19:58:33.335Z,1766001513.335 [Default:CheckIn](INFO): Running loop #12 2025-12-17T19:58:33.335Z,1766001513.335 [Default:CheckIn] Running Loop=12 2025-12-17T19:58:33.335Z,1766001513.335 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T19:58:33.335Z,1766001513.335 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T19:58:35.343Z,1766001515.343 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195834.00,A,3648.15128,N,12147.22476,W,0.019,268.02,171225,,,D*7D 2025-12-17T19:58:35.345Z,1766001515.345 [NAL9602](INFO): GPS fix at 20251217T195834: (36.802521, -121.787079) 2025-12-17T19:58:35.356Z,1766001515.356 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T19:58:35.356Z,1766001515.356 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T19:58:43.131Z,1766001523.131 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251217T192821/Courier0015.lzma 2025-12-17T19:58:44.134Z,1766001524.134 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0015.lzma.bak 2025-12-17T19:58:44.134Z,1766001524.134 [DataOverHttps](INFO): SBD MOMSN=26516287 2025-12-17T19:58:53.921Z,1766001533.921 [NAL9602](INFO): SBD MO Status=0, MOMSN=459, MT Status=0, MTMSN=0 2025-12-17T19:58:53.922Z,1766001533.922 [NAL9602](INFO): No messages in MT queue 2025-12-17T19:59:00.003Z,1766001540.003 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251217T192821/Courier0018.lzma 2025-12-17T19:59:01.006Z,1766001541.006 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0018.lzma.bak 2025-12-17T19:59:01.006Z,1766001541.006 [DataOverHttps](INFO): SBD MOMSN=26516290 2025-12-17T19:59:16.920Z,1766001556.920 [DataOverHttps](INFO): Sending 303 bytes from file Logs/20251217T192821/Express0016.lzma 2025-12-17T19:59:17.923Z,1766001557.923 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0016.lzma.bak 2025-12-17T19:59:17.923Z,1766001557.923 [DataOverHttps](INFO): SBD MOMSN=26516293 2025-12-17T19:59:24.625Z,1766001564.625 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T19:59:33.904Z,1766001573.904 [DataOverHttps](INFO): Sending 425 bytes from file Logs/20251217T192821/Express0019.lzma 2025-12-17T19:59:34.906Z,1766001574.906 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0019.lzma.bak 2025-12-17T19:59:34.906Z,1766001574.906 [DataOverHttps](INFO): SBD MOMSN=26516296 2025-12-17T19:59:36.367Z,1766001576.367 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T19:59:36.367Z,1766001576.367 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T19:59:36.367Z,1766001576.367 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T20:04:36.955Z,1766001876.955 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T20:04:36.956Z,1766001876.956 [Default:CheckIn:C.Wait] Stopped 2025-12-17T20:04:36.956Z,1766001876.956 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T20:04:36.956Z,1766001876.956 [Default:CheckIn:D] Running Loop=1 2025-12-17T20:04:37.353Z,1766001877.353 [Default:CheckIn:D] Stopped 2025-12-17T20:04:37.353Z,1766001877.353 [Default:CheckIn:E] Running Loop=1 2025-12-17T20:04:37.752Z,1766001877.752 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.649662 min 2025-12-17T20:04:37.752Z,1766001877.752 [Default:CheckIn:E] Stopped 2025-12-17T20:04:37.752Z,1766001877.752 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T20:04:37.752Z,1766001877.752 [Default:CheckIn] Stopped 2025-12-17T20:04:37.753Z,1766001877.753 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T20:04:37.753Z,1766001877.753 [Default:CheckIn](INFO): Running loop #13 2025-12-17T20:04:37.753Z,1766001877.753 [Default:CheckIn] Running Loop=13 2025-12-17T20:04:37.753Z,1766001877.753 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T20:04:37.753Z,1766001877.753 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T20:04:39.764Z,1766001879.764 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200438.00,A,3648.14121,N,12147.21335,W,0.136,190.11,171225,,,D*73 2025-12-17T20:04:39.767Z,1766001879.767 [NAL9602](INFO): GPS fix at 20251217T200438: (36.802354, -121.786889) 2025-12-17T20:04:39.778Z,1766001879.778 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T20:04:39.778Z,1766001879.778 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T20:04:46.947Z,1766001886.947 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251217T192821/Courier0021.lzma 2025-12-17T20:04:47.950Z,1766001887.950 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0021.lzma.bak 2025-12-17T20:04:47.950Z,1766001887.950 [DataOverHttps](INFO): SBD MOMSN=26516307 2025-12-17T20:04:53.495Z,1766001893.495 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:05:04.011Z,1766001904.011 [NAL9602](INFO): SBD MO Status=2, MOMSN=460, MT Status=2, MTMSN=0 2025-12-17T20:05:04.011Z,1766001904.011 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-17T20:05:04.036Z,1766001904.036 [DataOverHttps](INFO): Sending 192 bytes from file Logs/20251217T192821/Express0022.lzma 2025-12-17T20:05:05.039Z,1766001905.039 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0022.lzma.bak 2025-12-17T20:05:05.039Z,1766001905.039 [DataOverHttps](INFO): SBD MOMSN=26516310 2025-12-17T20:05:06.436Z,1766001906.436 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T20:05:06.436Z,1766001906.436 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T20:05:06.436Z,1766001906.436 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T20:05:07.635Z,1766001907.635 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:05:21.778Z,1766001921.778 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:05:25.819Z,1766001925.819 [NAL9602](INFO): SBD MO Status=0, MOMSN=460, MT Status=0, MTMSN=0 2025-12-17T20:05:25.819Z,1766001925.819 [NAL9602](INFO): No messages in MT queue 2025-12-17T20:05:35.510Z,1766001935.510 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:05:50.058Z,1766001950.058 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:05:56.525Z,1766001956.525 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T20:06:03.790Z,1766001963.790 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:06:17.123Z,1766001977.123 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:06:30.456Z,1766001990.456 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:06:44.196Z,1766002004.196 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:06:58.331Z,1766002018.331 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:07:17.319Z,1766002037.319 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:07:30.650Z,1766002050.650 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:07:43.983Z,1766002063.983 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:07:56.912Z,1766002076.912 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:08:09.838Z,1766002089.838 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:08:22.767Z,1766002102.767 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:08:35.694Z,1766002115.694 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:08:48.219Z,1766002128.219 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:09:01.552Z,1766002141.552 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:09:14.084Z,1766002154.084 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:09:27.016Z,1766002167.016 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:10:07.021Z,1766002207.021 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T20:10:07.021Z,1766002207.021 [Default:CheckIn:C.Wait] Stopped 2025-12-17T20:10:07.021Z,1766002207.021 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T20:10:07.021Z,1766002207.021 [Default:CheckIn:D] Running Loop=1 2025-12-17T20:10:07.425Z,1766002207.425 [Default:CheckIn:D] Stopped 2025-12-17T20:10:07.425Z,1766002207.425 [Default:CheckIn:E] Running Loop=1 2025-12-17T20:10:07.835Z,1766002207.835 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.150871 min 2025-12-17T20:10:07.835Z,1766002207.835 [Default:CheckIn:E] Stopped 2025-12-17T20:10:07.836Z,1766002207.836 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T20:10:07.836Z,1766002207.836 [Default:CheckIn] Stopped 2025-12-17T20:10:07.836Z,1766002207.836 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T20:10:07.836Z,1766002207.836 [Default:CheckIn](INFO): Running loop #14 2025-12-17T20:10:07.836Z,1766002207.836 [Default:CheckIn] Running Loop=14 2025-12-17T20:10:07.836Z,1766002207.836 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T20:10:07.837Z,1766002207.837 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T20:10:09.835Z,1766002209.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201008.00,A,3648.17366,N,12147.28067,W,0.350,161.00,171225,,,D*76 2025-12-17T20:10:09.837Z,1766002209.837 [NAL9602](INFO): GPS fix at 20251217T201008: (36.802894, -121.788011) 2025-12-17T20:10:09.868Z,1766002209.868 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T20:10:09.868Z,1766002209.868 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T20:10:17.251Z,1766002217.251 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251217T192821/Courier0024.lzma 2025-12-17T20:10:18.254Z,1766002218.254 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0024.lzma.bak 2025-12-17T20:10:18.254Z,1766002218.254 [DataOverHttps](INFO): SBD MOMSN=26516330 2025-12-17T20:10:36.908Z,1766002236.908 [DataOverHttps](INFO): Sending 367 bytes from file Logs/20251217T192821/Express0025.lzma 2025-12-17T20:10:37.910Z,1766002237.910 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0025.lzma.bak 2025-12-17T20:10:37.910Z,1766002237.910 [DataOverHttps](INFO): SBD MOMSN=26516333 2025-12-17T20:10:39.460Z,1766002239.460 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T20:10:39.461Z,1766002239.461 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T20:10:39.461Z,1766002239.461 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T20:11:54.539Z,1766002314.539 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-12-17T20:13:08.076Z,1766002388.076 [NAL9602](INFO): SBD MO Status=2, MOMSN=461, MT Status=2, MTMSN=0 2025-12-17T20:13:08.076Z,1766002388.076 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-17T20:13:44.434Z,1766002424.434 [NAL9602](INFO): SBD MO Status=0, MOMSN=461, MT Status=0, MTMSN=0 2025-12-17T20:13:44.434Z,1766002424.434 [NAL9602](INFO): No messages in MT queue 2025-12-17T20:14:15.137Z,1766002455.137 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T20:14:19.992Z,1766002459.992 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:14:34.122Z,1766002474.122 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:14:48.263Z,1766002488.263 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:15:01.998Z,1766002501.998 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:15:15.331Z,1766002515.331 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:15:29.070Z,1766002529.070 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:15:39.993Z,1766002539.993 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T20:15:39.993Z,1766002539.993 [Default:CheckIn:C.Wait] Stopped 2025-12-17T20:15:39.993Z,1766002539.993 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T20:15:39.994Z,1766002539.994 [Default:CheckIn:D] Running Loop=1 2025-12-17T20:15:40.400Z,1766002540.400 [Default:CheckIn:D] Stopped 2025-12-17T20:15:40.400Z,1766002540.400 [Default:CheckIn:E] Running Loop=1 2025-12-17T20:15:40.807Z,1766002540.807 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.700448 min 2025-12-17T20:15:40.807Z,1766002540.807 [Default:CheckIn:E] Stopped 2025-12-17T20:15:40.812Z,1766002540.812 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T20:15:40.812Z,1766002540.812 [Default:CheckIn] Stopped 2025-12-17T20:15:40.812Z,1766002540.812 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T20:15:40.812Z,1766002540.812 [Default:CheckIn](INFO): Running loop #15 2025-12-17T20:15:40.812Z,1766002540.812 [Default:CheckIn] Running Loop=15 2025-12-17T20:15:40.812Z,1766002540.812 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T20:15:40.812Z,1766002540.812 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T20:15:42.857Z,1766002542.857 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201541.00,A,3648.17800,N,12147.27883,W,0.039,161.00,171225,,,A*71 2025-12-17T20:15:42.859Z,1766002542.859 [NAL9602](INFO): GPS fix at 20251217T201541: (36.802967, -121.787981) 2025-12-17T20:15:42.870Z,1766002542.870 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T20:15:42.870Z,1766002542.870 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T20:15:43.206Z,1766002543.206 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:15:50.399Z,1766002550.399 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251217T192821/Courier0027.lzma 2025-12-17T20:15:51.402Z,1766002551.402 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0027.lzma.bak 2025-12-17T20:15:51.402Z,1766002551.402 [DataOverHttps](INFO): SBD MOMSN=26516344 2025-12-17T20:15:55.738Z,1766002555.738 [NAL9602](INFO): SBD MO Status=0, MOMSN=462, MT Status=0, MTMSN=0 2025-12-17T20:15:55.738Z,1766002555.738 [NAL9602](INFO): No messages in MT queue 2025-12-17T20:15:56.945Z,1766002556.945 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:16:07.523Z,1766002567.523 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20251217T192821/Express0028.lzma 2025-12-17T20:16:08.526Z,1766002568.526 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0028.lzma.bak 2025-12-17T20:16:08.526Z,1766002568.526 [DataOverHttps](INFO): SBD MOMSN=26516347 2025-12-17T20:16:09.889Z,1766002569.889 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T20:16:09.889Z,1766002569.889 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T20:16:09.889Z,1766002569.889 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T20:16:11.083Z,1766002571.083 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:16:25.223Z,1766002585.223 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:16:26.437Z,1766002586.437 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T20:16:38.556Z,1766002598.556 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:16:57.542Z,1766002617.542 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:17:10.524Z,1766002630.524 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:17:23.398Z,1766002643.398 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:17:25.827Z,1766002645.827 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-12-17T20:17:35.922Z,1766002655.922 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:17:48.449Z,1766002668.449 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:18:01.376Z,1766002681.376 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:18:13.901Z,1766002693.901 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:18:26.426Z,1766002706.426 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:18:38.951Z,1766002718.951 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:18:51.475Z,1766002731.475 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:18:58.406Z,1766002738.406 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-17T20:19:04.812Z,1766002744.812 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:21:10.543Z,1766002870.543 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T20:21:10.543Z,1766002870.543 [Default:CheckIn:C.Wait] Stopped 2025-12-17T20:21:10.543Z,1766002870.543 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T20:21:10.543Z,1766002870.543 [Default:CheckIn:D] Running Loop=1 2025-12-17T20:21:10.940Z,1766002870.940 [Default:CheckIn:D] Stopped 2025-12-17T20:21:10.941Z,1766002870.941 [Default:CheckIn:E] Running Loop=1 2025-12-17T20:21:11.332Z,1766002871.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.209465 min 2025-12-17T20:21:11.332Z,1766002871.332 [Default:CheckIn:E] Stopped 2025-12-17T20:21:11.332Z,1766002871.332 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T20:21:11.333Z,1766002871.333 [Default:CheckIn] Stopped 2025-12-17T20:21:11.333Z,1766002871.333 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T20:21:11.333Z,1766002871.333 [Default:CheckIn](INFO): Running loop #16 2025-12-17T20:21:11.333Z,1766002871.333 [Default:CheckIn] Running Loop=16 2025-12-17T20:21:11.333Z,1766002871.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T20:21:11.333Z,1766002871.333 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T20:21:13.347Z,1766002873.347 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202112.00,A,3648.18671,N,12147.27650,W,0.097,161.00,171225,,,A*73 2025-12-17T20:21:13.349Z,1766002873.349 [NAL9602](INFO): GPS fix at 20251217T202112: (36.803112, -121.787942) 2025-12-17T20:21:13.376Z,1766002873.376 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T20:21:13.376Z,1766002873.376 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T20:21:21.115Z,1766002881.115 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20251217T192821/Courier0030.lzma 2025-12-17T20:21:22.118Z,1766002882.118 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0030.lzma.bak 2025-12-17T20:21:22.118Z,1766002882.118 [DataOverHttps](INFO): SBD MOMSN=26516366 2025-12-17T20:21:38.315Z,1766002898.315 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20251217T192821/Express0031.lzma 2025-12-17T20:21:39.318Z,1766002899.318 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0031.lzma.bak 2025-12-17T20:21:39.318Z,1766002899.318 [DataOverHttps](INFO): SBD MOMSN=26516369 2025-12-17T20:21:40.484Z,1766002900.484 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T20:21:40.484Z,1766002900.484 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T20:21:40.484Z,1766002900.484 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T20:22:21.238Z,1766002941.238 [NAL9602](INFO): SBD MO Status=2, MOMSN=463, MT Status=2, MTMSN=0 2025-12-17T20:22:21.238Z,1766002941.238 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-17T20:22:40.210Z,1766002960.210 [NAL9602](INFO): SBD MO Status=0, MOMSN=463, MT Status=0, MTMSN=0 2025-12-17T20:22:40.210Z,1766002960.210 [NAL9602](INFO): No messages in MT queue 2025-12-17T20:23:10.916Z,1766002990.916 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T20:23:59.390Z,1766003039.390 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:24:13.126Z,1766003053.126 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:24:26.490Z,1766003066.490 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:24:39.815Z,1766003079.815 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:24:53.144Z,1766003093.144 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:25:06.879Z,1766003106.879 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:25:21.018Z,1766003121.018 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:25:35.172Z,1766003135.172 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:25:48.490Z,1766003148.490 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:26:02.226Z,1766003162.226 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T20:26:38.635Z,1766003198.635 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:26:41.075Z,1766003201.075 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T20:26:41.075Z,1766003201.075 [Default:CheckIn:C.Wait] Stopped 2025-12-17T20:26:41.075Z,1766003201.075 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T20:26:41.075Z,1766003201.075 [Default:CheckIn:D] Running Loop=1 2025-12-17T20:26:41.475Z,1766003201.475 [Default:CheckIn:D] Stopped 2025-12-17T20:26:41.475Z,1766003201.475 [Default:CheckIn:E] Running Loop=1 2025-12-17T20:26:41.882Z,1766003201.882 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.718376 min 2025-12-17T20:26:41.882Z,1766003201.882 [Default:CheckIn:E] Stopped 2025-12-17T20:26:41.882Z,1766003201.882 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T20:26:41.882Z,1766003201.882 [Default:CheckIn] Stopped 2025-12-17T20:26:41.882Z,1766003201.882 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T20:26:41.882Z,1766003201.882 [Default:CheckIn](INFO): Running loop #17 2025-12-17T20:26:41.882Z,1766003201.882 [Default:CheckIn] Running Loop=17 2025-12-17T20:26:41.882Z,1766003201.882 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T20:26:41.882Z,1766003201.882 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T20:26:43.899Z,1766003203.899 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202642.00,A,3648.18629,N,12147.27791,W,0.214,161.00,171225,,,A*79 2025-12-17T20:26:43.901Z,1766003203.901 [NAL9602](INFO): GPS fix at 20251217T202642: (36.803105, -121.787965) 2025-12-17T20:26:43.913Z,1766003203.913 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T20:26:43.913Z,1766003203.913 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T20:26:51.243Z,1766003211.243 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251217T192821/Courier0033.lzma 2025-12-17T20:26:51.968Z,1766003211.968 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:26:52.246Z,1766003212.246 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0033.lzma.bak 2025-12-17T20:26:52.246Z,1766003212.246 [DataOverHttps](INFO): SBD MOMSN=26516382 2025-12-17T20:27:00.052Z,1766003220.052 [NAL9602](INFO): SBD MO Status=0, MOMSN=464, MT Status=0, MTMSN=0 2025-12-17T20:27:00.052Z,1766003220.052 [NAL9602](INFO): No messages in MT queue 2025-12-17T20:27:04.897Z,1766003224.897 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:27:08.371Z,1766003228.371 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20251217T192821/Express0034.lzma 2025-12-17T20:27:09.374Z,1766003229.374 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0034.lzma.bak 2025-12-17T20:27:09.374Z,1766003229.374 [DataOverHttps](INFO): SBD MOMSN=26516387 2025-12-17T20:27:10.587Z,1766003230.587 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T20:27:10.587Z,1766003230.587 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T20:27:10.588Z,1766003230.588 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T20:27:17.423Z,1766003237.423 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:27:29.942Z,1766003249.942 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:27:30.773Z,1766003250.773 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T20:27:42.063Z,1766003262.063 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:27:54.587Z,1766003274.587 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:28:07.515Z,1766003287.515 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:28:20.442Z,1766003300.442 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:28:32.966Z,1766003312.966 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:28:45.493Z,1766003325.493 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T20:31:23.889Z,1766003483.889 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-12-17T20:31:23.889Z,1766003483.889 [DropWeight] Hardware Fault, FailCount= 1 2025-12-17T20:31:23.889Z,1766003483.889 [DropWeight](ERROR): Hardware Fault 2025-12-17T20:31:23.908Z,1766003483.908 [CommandExec](FAULT): Scheduling is paused 2025-12-17T20:31:23.908Z,1766003483.908 [CBIT](INFO): Critical error at 20251217T203123 2025-12-17T20:31:23.911Z,1766003483.911 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-12-17T20:31:23.911Z,1766003483.911 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-12-17T20:31:24.336Z,1766003484.336 [CBIT](INFO): Critical error at 20251217T203123 2025-12-17T20:32:11.163Z,1766003531.163 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T20:32:11.163Z,1766003531.163 [Default:CheckIn:C.Wait] Stopped 2025-12-17T20:32:11.163Z,1766003531.163 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T20:32:11.164Z,1766003531.164 [Default:CheckIn:D] Running Loop=1 2025-12-17T20:32:11.571Z,1766003531.571 [Default:CheckIn:D] Stopped 2025-12-17T20:32:11.571Z,1766003531.571 [Default:CheckIn:E] Running Loop=1 2025-12-17T20:32:11.976Z,1766003531.976 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.219971 min 2025-12-17T20:32:11.976Z,1766003531.976 [Default:CheckIn:E] Stopped 2025-12-17T20:32:11.976Z,1766003531.976 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T20:32:11.976Z,1766003531.976 [Default:CheckIn] Stopped 2025-12-17T20:32:11.977Z,1766003531.977 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T20:32:11.977Z,1766003531.977 [Default:CheckIn](INFO): Running loop #18 2025-12-17T20:32:11.977Z,1766003531.977 [Default:CheckIn] Running Loop=18 2025-12-17T20:32:11.977Z,1766003531.977 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T20:32:11.977Z,1766003531.977 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T20:32:13.982Z,1766003533.982 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203212.00,A,3648.19724,N,12147.27441,W,0.175,161.00,171225,,,A*7E 2025-12-17T20:32:13.989Z,1766003533.989 [NAL9602](INFO): GPS fix at 20251217T203212: (36.803287, -121.787907) 2025-12-17T20:32:14.031Z,1766003534.031 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T20:32:14.031Z,1766003534.031 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T20:32:24.092Z,1766003544.092 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20251217T192821/Courier0036.lzma 2025-12-17T20:32:25.094Z,1766003545.094 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0036.lzma.bak 2025-12-17T20:32:25.094Z,1766003545.094 [DataOverHttps](INFO): SBD MOMSN=26516405 2025-12-17T20:32:26.550Z,1766003546.550 [NAL9602](INFO): SBD MO Status=0, MOMSN=465, MT Status=0, MTMSN=0 2025-12-17T20:32:26.550Z,1766003546.550 [NAL9602](INFO): No messages in MT queue 2025-12-17T20:32:42.047Z,1766003562.047 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20251217T192821/Express0037.lzma 2025-12-17T20:32:43.050Z,1766003563.050 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0037.lzma.bak 2025-12-17T20:32:43.050Z,1766003563.050 [DataOverHttps](INFO): SBD MOMSN=26516410 2025-12-17T20:32:44.360Z,1766003564.360 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T20:32:44.361Z,1766003564.361 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T20:32:44.361Z,1766003564.361 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T20:32:57.257Z,1766003577.257 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T20:33:19.067Z,1766003599.067 [BPC1](ERROR): Battery stick #46 (s/n: 0213) reported OVER_TEMP_ALARM. Status code: 0x1717. 2025-12-17T20:33:19.068Z,1766003599.068 [BPC1](INFO): Calculating totals. Valid battery stick count: 45. Valid reserve battery stick count: 4. 2025-12-17T20:33:19.070Z,1766003599.070 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2025-12-17T20:37:45.031Z,1766003865.031 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T20:37:45.031Z,1766003865.031 [Default:CheckIn:C.Wait] Stopped 2025-12-17T20:37:45.031Z,1766003865.031 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T20:37:45.046Z,1766003865.046 [Default:CheckIn:D] Running Loop=1 2025-12-17T20:37:45.430Z,1766003865.430 [Default:CheckIn:D] Stopped 2025-12-17T20:37:45.430Z,1766003865.430 [Default:CheckIn:E] Running Loop=1 2025-12-17T20:37:45.845Z,1766003865.845 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.784277 min 2025-12-17T20:37:45.845Z,1766003865.845 [Default:CheckIn:E] Stopped 2025-12-17T20:37:45.845Z,1766003865.845 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T20:37:45.845Z,1766003865.845 [Default:CheckIn] Stopped 2025-12-17T20:37:45.845Z,1766003865.845 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T20:37:45.845Z,1766003865.845 [Default:CheckIn](INFO): Running loop #19 2025-12-17T20:37:45.845Z,1766003865.845 [Default:CheckIn] Running Loop=19 2025-12-17T20:37:45.845Z,1766003865.845 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T20:37:45.845Z,1766003865.845 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T20:37:47.861Z,1766003867.861 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203746.00,A,3648.19419,N,12147.27515,W,0.078,0.00,171225,,,A*7D 2025-12-17T20:37:47.863Z,1766003867.863 [NAL9602](INFO): GPS fix at 20251217T203746: (36.803236, -121.787919) 2025-12-17T20:37:47.874Z,1766003867.874 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T20:37:47.874Z,1766003867.874 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T20:37:55.619Z,1766003875.619 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20251217T192821/Courier0039.lzma 2025-12-17T20:37:56.622Z,1766003876.622 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0039.lzma.bak 2025-12-17T20:37:56.622Z,1766003876.622 [DataOverHttps](INFO): SBD MOMSN=26516430 2025-12-17T20:38:12.551Z,1766003892.551 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20251217T192821/Express0040.lzma 2025-12-17T20:38:13.554Z,1766003893.554 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0040.lzma.bak 2025-12-17T20:38:13.554Z,1766003893.554 [DataOverHttps](INFO): SBD MOMSN=26516433 2025-12-17T20:38:14.951Z,1766003894.951 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T20:38:14.951Z,1766003894.951 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T20:38:14.951Z,1766003894.951 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T20:41:43.186Z,1766004103.186 [NAL9602](INFO): SBD MO Status=2, MOMSN=466, MT Status=2, MTMSN=0 2025-12-17T20:41:43.186Z,1766004103.186 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-17T20:42:24.851Z,1766004144.851 [NAL9602](INFO): SBD MO Status=2, MOMSN=466, MT Status=2, MTMSN=0 2025-12-17T20:42:24.851Z,1766004144.851 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-17T20:42:49.497Z,1766004169.497 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-17T20:43:15.368Z,1766004195.368 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T20:43:15.368Z,1766004195.368 [Default:CheckIn:C.Wait] Stopped 2025-12-17T20:43:15.368Z,1766004195.368 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T20:43:15.368Z,1766004195.368 [Default:CheckIn:D] Running Loop=1 2025-12-17T20:43:15.785Z,1766004195.785 [Default:CheckIn:D] Stopped 2025-12-17T20:43:15.785Z,1766004195.785 [Default:CheckIn:E] Running Loop=1 2025-12-17T20:43:16.176Z,1766004196.176 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.290194 min 2025-12-17T20:43:16.176Z,1766004196.176 [Default:CheckIn:E] Stopped 2025-12-17T20:43:16.176Z,1766004196.176 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T20:43:16.176Z,1766004196.176 [Default:CheckIn] Stopped 2025-12-17T20:43:16.176Z,1766004196.176 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T20:43:16.176Z,1766004196.176 [Default:CheckIn](INFO): Running loop #20 2025-12-17T20:43:16.176Z,1766004196.176 [Default:CheckIn] Running Loop=20 2025-12-17T20:43:16.177Z,1766004196.177 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T20:43:16.177Z,1766004196.177 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T20:43:18.186Z,1766004198.186 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204317.00,A,3648.16748,N,12147.28191,W,0.816,280.45,171225,,,A*7E 2025-12-17T20:43:18.189Z,1766004198.189 [NAL9602](INFO): GPS fix at 20251217T204317: (36.802791, -121.788032) 2025-12-17T20:43:18.199Z,1766004198.199 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T20:43:18.200Z,1766004198.200 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T20:43:25.751Z,1766004205.751 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251217T192821/Courier0042.lzma 2025-12-17T20:43:26.754Z,1766004206.754 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0042.lzma.bak 2025-12-17T20:43:26.754Z,1766004206.754 [DataOverHttps](INFO): SBD MOMSN=26516447 2025-12-17T20:43:42.776Z,1766004222.776 [DataOverHttps](INFO): Sending 276 bytes from file Logs/20251217T192821/Express0043.lzma 2025-12-17T20:43:43.774Z,1766004223.774 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0043.lzma.bak 2025-12-17T20:43:43.774Z,1766004223.774 [DataOverHttps](INFO): SBD MOMSN=26516450 2025-12-17T20:43:45.263Z,1766004225.263 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T20:43:45.263Z,1766004225.263 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T20:43:45.263Z,1766004225.263 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T20:43:50.909Z,1766004230.909 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T20:46:24.166Z,1766004384.166 [CBIT](INFO): Clearing failed state for component DropWeight 2025-12-17T20:46:24.166Z,1766004384.166 [DropWeight] No Fault, FailCount= 1 2025-12-17T20:48:45.979Z,1766004525.979 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T20:48:45.979Z,1766004525.979 [Default:CheckIn:C.Wait] Stopped 2025-12-17T20:48:45.979Z,1766004525.979 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T20:48:45.979Z,1766004525.979 [Default:CheckIn:D] Running Loop=1 2025-12-17T20:48:46.391Z,1766004526.391 [Default:CheckIn:D] Stopped 2025-12-17T20:48:46.391Z,1766004526.391 [Default:CheckIn:E] Running Loop=1 2025-12-17T20:48:46.799Z,1766004526.799 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.800301 min 2025-12-17T20:48:46.799Z,1766004526.799 [Default:CheckIn:E] Stopped 2025-12-17T20:48:46.799Z,1766004526.799 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T20:48:46.799Z,1766004526.799 [Default:CheckIn] Stopped 2025-12-17T20:48:46.799Z,1766004526.799 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T20:48:46.799Z,1766004526.799 [Default:CheckIn](INFO): Running loop #21 2025-12-17T20:48:46.799Z,1766004526.799 [Default:CheckIn] Running Loop=21 2025-12-17T20:48:46.799Z,1766004526.799 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T20:48:46.800Z,1766004526.800 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T20:48:48.799Z,1766004528.799 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204847.00,A,3648.15490,N,12147.28956,W,0.583,322.49,171225,,,A*72 2025-12-17T20:48:48.801Z,1766004528.801 [NAL9602](INFO): GPS fix at 20251217T204847: (36.802582, -121.788159) 2025-12-17T20:48:48.832Z,1766004528.832 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T20:48:48.832Z,1766004528.832 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T20:48:56.115Z,1766004536.115 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251217T192821/Courier0045.lzma 2025-12-17T20:48:57.118Z,1766004537.118 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0045.lzma.bak 2025-12-17T20:48:57.118Z,1766004537.118 [DataOverHttps](INFO): SBD MOMSN=26516467 2025-12-17T20:49:13.175Z,1766004553.175 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20251217T192821/Express0046.lzma 2025-12-17T20:49:14.178Z,1766004554.178 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0046.lzma.bak 2025-12-17T20:49:14.178Z,1766004554.178 [DataOverHttps](INFO): SBD MOMSN=26516470 2025-12-17T20:49:15.489Z,1766004555.489 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T20:49:15.489Z,1766004555.489 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T20:49:15.489Z,1766004555.489 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T20:49:19.505Z,1766004559.505 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-12-17T20:49:19.588Z,1766004559.588 [NAL9602](ERROR): received: +CSQ:0 OK6, 2, 0, 0, 0 OK 2025-12-17T20:53:07.866Z,1766004787.866 [NAL9602](INFO): SBD MO Status=2, MOMSN=466, MT Status=2, MTMSN=0 2025-12-17T20:53:07.866Z,1766004787.866 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-17T20:53:15.951Z,1766004795.951 [NAL9602](INFO): SBD MO Status=0, MOMSN=466, MT Status=0, MTMSN=0 2025-12-17T20:53:15.951Z,1766004795.951 [NAL9602](INFO): No messages in MT queue 2025-12-17T20:53:46.650Z,1766004826.650 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T20:54:16.158Z,1766004856.158 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T20:54:16.158Z,1766004856.158 [Default:CheckIn:C.Wait] Stopped 2025-12-17T20:54:16.158Z,1766004856.158 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T20:54:16.159Z,1766004856.159 [Default:CheckIn:D] Running Loop=1 2025-12-17T20:54:16.559Z,1766004856.559 [Default:CheckIn:D] Stopped 2025-12-17T20:54:16.559Z,1766004856.559 [Default:CheckIn:E] Running Loop=1 2025-12-17T20:54:16.964Z,1766004856.964 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.303109 min 2025-12-17T20:54:16.964Z,1766004856.964 [Default:CheckIn:E] Stopped 2025-12-17T20:54:16.964Z,1766004856.964 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T20:54:16.964Z,1766004856.964 [Default:CheckIn] Stopped 2025-12-17T20:54:16.964Z,1766004856.964 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T20:54:16.965Z,1766004856.965 [Default:CheckIn](INFO): Running loop #22 2025-12-17T20:54:16.965Z,1766004856.965 [Default:CheckIn] Running Loop=22 2025-12-17T20:54:16.965Z,1766004856.965 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T20:54:16.965Z,1766004856.965 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T20:54:18.970Z,1766004858.970 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205418.00,A,3648.16634,N,12147.28037,W,0.622,7.34,171225,,,A*72 2025-12-17T20:54:18.973Z,1766004858.973 [NAL9602](INFO): GPS fix at 20251217T205418: (36.802772, -121.788006) 2025-12-17T20:54:19.017Z,1766004859.017 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T20:54:19.018Z,1766004859.018 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T20:54:29.583Z,1766004869.583 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251217T192821/Courier0048.lzma 2025-12-17T20:54:30.586Z,1766004870.586 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0048.lzma.bak 2025-12-17T20:54:30.586Z,1766004870.586 [DataOverHttps](INFO): SBD MOMSN=26516501 2025-12-17T20:54:46.507Z,1766004886.507 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20251217T192821/Express0049.lzma 2025-12-17T20:54:47.510Z,1766004887.510 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0049.lzma.bak 2025-12-17T20:54:47.510Z,1766004887.510 [DataOverHttps](INFO): SBD MOMSN=26516504 2025-12-17T20:54:49.000Z,1766004889.000 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T20:54:49.001Z,1766004889.001 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T20:54:49.001Z,1766004889.001 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T20:55:23.295Z,1766004923.295 [NAL9602](INFO): SBD MO Status=2, MOMSN=467, MT Status=2, MTMSN=0 2025-12-17T20:55:23.295Z,1766004923.295 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-17T20:59:37.486Z,1766005177.486 [NAL9602](INFO): SBD MO Status=2, MOMSN=467, MT Status=2, MTMSN=0 2025-12-17T20:59:37.486Z,1766005177.486 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-17T20:59:37.897Z,1766005177.897 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-17T20:59:49.627Z,1766005189.627 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T20:59:49.627Z,1766005189.627 [Default:CheckIn:C.Wait] Stopped 2025-12-17T20:59:49.627Z,1766005189.627 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T20:59:49.627Z,1766005189.627 [Default:CheckIn:D] Running Loop=1 2025-12-17T20:59:50.024Z,1766005190.024 [Default:CheckIn:D] Stopped 2025-12-17T20:59:50.025Z,1766005190.025 [Default:CheckIn:E] Running Loop=1 2025-12-17T20:59:50.423Z,1766005190.423 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.860864 min 2025-12-17T20:59:50.423Z,1766005190.423 [Default:CheckIn:E] Stopped 2025-12-17T20:59:50.423Z,1766005190.423 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T20:59:50.423Z,1766005190.423 [Default:CheckIn] Stopped 2025-12-17T20:59:50.423Z,1766005190.423 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T20:59:50.423Z,1766005190.423 [Default:CheckIn](INFO): Running loop #23 2025-12-17T20:59:50.423Z,1766005190.423 [Default:CheckIn] Running Loop=23 2025-12-17T20:59:50.424Z,1766005190.424 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T20:59:50.424Z,1766005190.424 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T20:59:52.439Z,1766005192.439 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205951.00,A,3648.16706,N,12147.28044,W,0.952,7.34,171225,,,A*7E 2025-12-17T20:59:52.441Z,1766005192.441 [NAL9602](INFO): GPS fix at 20251217T205951: (36.802784, -121.788007) 2025-12-17T20:59:52.453Z,1766005192.453 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T20:59:52.453Z,1766005192.453 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T20:59:53.646Z,1766005193.646 [NAL9602](FAULT): LCB fault: Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated. 2025-12-17T20:59:53.646Z,1766005193.646 [NAL9602] Hardware Fault, FailCount= 1 2025-12-17T20:59:53.646Z,1766005193.646 [NAL9602](ERROR): Hardware Fault 2025-12-17T20:59:53.720Z,1766005193.720 [CBIT](ERROR): Hardware Fault in component: NAL9602 2025-12-17T20:59:54.050Z,1766005194.050 [NAL9602](INFO): Powering down 2025-12-17T20:59:54.895Z,1766005194.895 [CBIT](INFO): Clearing failed state for component NAL9602 2025-12-17T20:59:54.895Z,1766005194.895 [NAL9602] No Fault, FailCount= 1 2025-12-17T20:59:59.847Z,1766005199.847 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251217T192821/Courier0051.lzma 2025-12-17T21:00:00.850Z,1766005200.850 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0051.lzma.bak 2025-12-17T21:00:00.850Z,1766005200.850 [DataOverHttps](INFO): SBD MOMSN=26516519 2025-12-17T21:00:16.735Z,1766005216.735 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20251217T192821/Express0052.lzma 2025-12-17T21:00:17.738Z,1766005217.738 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0052.lzma.bak 2025-12-17T21:00:17.738Z,1766005217.738 [DataOverHttps](INFO): SBD MOMSN=26516522 2025-12-17T21:00:19.115Z,1766005219.115 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T21:00:19.115Z,1766005219.115 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T21:00:19.116Z,1766005219.116 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T21:00:24.382Z,1766005224.382 [NAL9602](INFO): Powering up NAL9602 2025-12-17T21:00:35.305Z,1766005235.305 [NAL9602](INFO): NAL9602 initialized 2025-12-17T21:01:06.413Z,1766005266.413 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T21:05:19.735Z,1766005519.735 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T21:05:19.735Z,1766005519.735 [Default:CheckIn:C.Wait] Stopped 2025-12-17T21:05:19.735Z,1766005519.735 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T21:05:19.735Z,1766005519.735 [Default:CheckIn:D] Running Loop=1 2025-12-17T21:05:20.139Z,1766005520.139 [Default:CheckIn:D] Stopped 2025-12-17T21:05:20.139Z,1766005520.139 [Default:CheckIn:E] Running Loop=1 2025-12-17T21:05:20.550Z,1766005520.550 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.362777 min 2025-12-17T21:05:20.551Z,1766005520.551 [Default:CheckIn:E] Stopped 2025-12-17T21:05:20.551Z,1766005520.551 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T21:05:20.551Z,1766005520.551 [Default:CheckIn] Stopped 2025-12-17T21:05:20.551Z,1766005520.551 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T21:05:20.551Z,1766005520.551 [Default:CheckIn](INFO): Running loop #24 2025-12-17T21:05:20.551Z,1766005520.551 [Default:CheckIn] Running Loop=24 2025-12-17T21:05:20.551Z,1766005520.551 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T21:05:20.551Z,1766005520.551 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T21:05:22.550Z,1766005522.550 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210521.00,A,3648.17020,N,12147.28046,W,0.097,0.00,171225,,,A*71 2025-12-17T21:05:22.552Z,1766005522.552 [NAL9602](INFO): GPS fix at 20251217T210521: (36.802837, -121.788008) 2025-12-17T21:05:22.563Z,1766005522.563 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T21:05:22.563Z,1766005522.563 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T21:05:23.020Z,1766005523.020 [NAL9602](ERROR): parseGSV uart error: serial timeout 2025-12-17T21:05:30.515Z,1766005530.515 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251217T192821/Courier0054.lzma 2025-12-17T21:05:31.519Z,1766005531.519 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0054.lzma.bak 2025-12-17T21:05:31.519Z,1766005531.519 [DataOverHttps](INFO): SBD MOMSN=26516534 2025-12-17T21:05:47.640Z,1766005547.640 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20251217T192821/Express0055.lzma 2025-12-17T21:05:48.642Z,1766005548.642 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0055.lzma.bak 2025-12-17T21:05:48.642Z,1766005548.642 [DataOverHttps](INFO): SBD MOMSN=26516537 2025-12-17T21:05:50.066Z,1766005550.066 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T21:05:50.066Z,1766005550.066 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T21:05:50.066Z,1766005550.066 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T21:08:43.338Z,1766005723.338 [NAL9602](INFO): SBD MO Status=2, MOMSN=467, MT Status=2, MTMSN=0 2025-12-17T21:08:43.339Z,1766005723.339 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-17T21:10:23.534Z,1766005823.534 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-17T21:10:50.611Z,1766005850.611 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T21:10:50.611Z,1766005850.611 [Default:CheckIn:C.Wait] Stopped 2025-12-17T21:10:50.612Z,1766005850.612 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T21:10:50.613Z,1766005850.613 [Default:CheckIn:D] Running Loop=1 2025-12-17T21:10:51.020Z,1766005851.020 [Default:CheckIn:D] Stopped 2025-12-17T21:10:51.021Z,1766005851.021 [Default:CheckIn:E] Running Loop=1 2025-12-17T21:10:51.420Z,1766005851.420 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.877466 min 2025-12-17T21:10:51.420Z,1766005851.420 [Default:CheckIn:E] Stopped 2025-12-17T21:10:51.420Z,1766005851.420 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T21:10:51.420Z,1766005851.420 [Default:CheckIn] Stopped 2025-12-17T21:10:51.420Z,1766005851.420 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T21:10:51.420Z,1766005851.420 [Default:CheckIn](INFO): Running loop #25 2025-12-17T21:10:51.420Z,1766005851.420 [Default:CheckIn] Running Loop=25 2025-12-17T21:10:51.421Z,1766005851.421 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T21:10:51.421Z,1766005851.421 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T21:10:53.435Z,1766005853.435 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211052.00,A,3648.17194,N,12147.27453,W,0.292,0.00,171225,,,A*77 2025-12-17T21:10:53.446Z,1766005853.446 [NAL9602](INFO): GPS fix at 20251217T211052: (36.802866, -121.787909) 2025-12-17T21:10:53.457Z,1766005853.457 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T21:10:53.458Z,1766005853.458 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T21:11:01.115Z,1766005861.115 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20251217T192821/Courier0057.lzma 2025-12-17T21:11:02.118Z,1766005862.118 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0057.lzma.bak 2025-12-17T21:11:02.118Z,1766005862.118 [DataOverHttps](INFO): SBD MOMSN=26516669 2025-12-17T21:11:18.075Z,1766005878.075 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20251217T192821/Express0058.lzma 2025-12-17T21:11:19.078Z,1766005879.078 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0058.lzma.bak 2025-12-17T21:11:19.078Z,1766005879.078 [DataOverHttps](INFO): SBD MOMSN=26516675 2025-12-17T21:11:20.536Z,1766005880.536 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T21:11:20.536Z,1766005880.536 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T21:11:20.536Z,1766005880.536 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T21:11:25.773Z,1766005885.773 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T21:16:21.107Z,1766006181.107 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T21:16:21.107Z,1766006181.107 [Default:CheckIn:C.Wait] Stopped 2025-12-17T21:16:21.107Z,1766006181.107 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T21:16:21.108Z,1766006181.108 [Default:CheckIn:D] Running Loop=1 2025-12-17T21:16:21.519Z,1766006181.519 [Default:CheckIn:D] Stopped 2025-12-17T21:16:21.519Z,1766006181.519 [Default:CheckIn:E] Running Loop=1 2025-12-17T21:16:21.933Z,1766006181.933 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 139.385758 min 2025-12-17T21:16:21.933Z,1766006181.933 [Default:CheckIn:E] Stopped 2025-12-17T21:16:21.933Z,1766006181.933 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T21:16:21.933Z,1766006181.933 [Default:CheckIn] Stopped 2025-12-17T21:16:21.933Z,1766006181.933 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T21:16:21.933Z,1766006181.933 [Default:CheckIn](INFO): Running loop #26 2025-12-17T21:16:21.933Z,1766006181.933 [Default:CheckIn] Running Loop=26 2025-12-17T21:16:21.933Z,1766006181.933 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T21:16:21.934Z,1766006181.934 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T21:16:25.545Z,1766006185.545 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-12-17T21:16:25.545Z,1766006185.545 [DropWeight] Hardware Fault, FailCount= 1 2025-12-17T21:16:25.545Z,1766006185.545 [DropWeight](ERROR): Hardware Fault 2025-12-17T21:16:25.618Z,1766006185.618 [CBIT](INFO): Critical error at 20251217T211625 2025-12-17T21:16:25.624Z,1766006185.624 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-12-17T21:16:25.625Z,1766006185.625 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-12-17T21:16:25.962Z,1766006185.962 [CBIT](INFO): Critical error at 20251217T211625 2025-12-17T21:16:54.633Z,1766006214.633 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-12-17T21:16:54.708Z,1766006214.708 [NAL9602](ERROR): received: +CSQ:1 OK7, 2, 0, 0, 0 OK 2025-12-17T21:21:22.095Z,1766006482.095 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-12-17T21:16:21.9Z 2025-12-17T21:21:22.095Z,1766006482.095 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T21:21:22.095Z,1766006482.095 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T21:21:25.721Z,1766006485.721 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-17T21:21:29.243Z,1766006489.243 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20251217T192821/Courier0060.lzma 2025-12-17T21:21:30.246Z,1766006490.246 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0060.lzma.bak 2025-12-17T21:21:30.246Z,1766006490.246 [DataOverHttps](INFO): SBD MOMSN=26516971 2025-12-17T21:21:46.731Z,1766006506.731 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20251217T192821/Express0061.lzma 2025-12-17T21:21:47.734Z,1766006507.734 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0061.lzma.bak 2025-12-17T21:21:47.734Z,1766006507.734 [DataOverHttps](INFO): SBD MOMSN=26516975 2025-12-17T21:21:49.162Z,1766006509.162 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T21:21:49.162Z,1766006509.162 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T21:21:49.162Z,1766006509.162 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T21:21:56.421Z,1766006516.421 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T21:26:49.748Z,1766006809.748 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T21:26:49.748Z,1766006809.748 [Default:CheckIn:C.Wait] Stopped 2025-12-17T21:26:49.748Z,1766006809.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T21:26:49.748Z,1766006809.748 [Default:CheckIn:D] Running Loop=1 2025-12-17T21:26:50.143Z,1766006810.143 [Default:CheckIn:D] Stopped 2025-12-17T21:26:50.143Z,1766006810.143 [Default:CheckIn:E] Running Loop=1 2025-12-17T21:26:50.547Z,1766006810.547 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.862842 min 2025-12-17T21:26:50.547Z,1766006810.547 [Default:CheckIn:E] Stopped 2025-12-17T21:26:50.547Z,1766006810.547 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T21:26:50.547Z,1766006810.547 [Default:CheckIn] Stopped 2025-12-17T21:26:50.547Z,1766006810.547 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T21:26:50.547Z,1766006810.547 [Default:CheckIn](INFO): Running loop #27 2025-12-17T21:26:50.548Z,1766006810.548 [Default:CheckIn] Running Loop=27 2025-12-17T21:26:50.548Z,1766006810.548 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T21:26:50.548Z,1766006810.548 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T21:26:52.148Z,1766006812.148 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-12-17T21:26:52.148Z,1766006812.148 [NAL9602] Data Fault, FailCount= 1 2025-12-17T21:26:52.148Z,1766006812.148 [NAL9602](ERROR): Data Fault 2025-12-17T21:26:52.169Z,1766006812.169 [CBIT](ERROR): Data Fault in component: NAL9602 2025-12-17T21:26:52.561Z,1766006812.561 [NAL9602](INFO): Powering down 2025-12-17T21:26:52.957Z,1766006812.957 [NAL9602](FAULT): LCB fault: LCB Watchdog Reset. Invalid Command. Hardware Overcurrent Shutdown. Current Limiter Activated. 2025-12-17T21:26:52.957Z,1766006812.957 [NAL9602] Hardware Fault, FailCount= 1 2025-12-17T21:26:52.957Z,1766006812.957 [NAL9602](ERROR): Hardware Fault 2025-12-17T21:26:53.397Z,1766006813.397 [CBIT](INFO): Clearing failed state for component NAL9602 2025-12-17T21:26:53.398Z,1766006813.398 [NAL9602] No Fault, FailCount= 1 2025-12-17T21:27:22.857Z,1766006842.857 [NAL9602](INFO): Powering up NAL9602 2025-12-17T21:27:33.762Z,1766006853.762 [NAL9602](INFO): NAL9602 initialized 2025-12-17T21:31:25.710Z,1766007085.710 [CBIT](INFO): Clearing failed state for component DropWeight 2025-12-17T21:31:25.710Z,1766007085.710 [DropWeight] No Fault, FailCount= 1 2025-12-17T21:31:50.737Z,1766007110.737 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-12-17T21:26:50.5Z 2025-12-17T21:31:50.737Z,1766007110.737 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T21:31:50.737Z,1766007110.737 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-17T21:31:58.387Z,1766007118.387 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20251217T192821/Courier0063.lzma 2025-12-17T21:31:59.390Z,1766007119.390 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Courier0063.lzma.bak 2025-12-17T21:31:59.390Z,1766007119.390 [DataOverHttps](INFO): SBD MOMSN=26517167 2025-12-17T21:32:18.624Z,1766007138.624 [DataOverHttps](INFO): Sending 318 bytes from file Logs/20251217T192821/Express0064.lzma 2025-12-17T21:32:19.626Z,1766007139.626 [DataOverHttps](INFO): Moved sent file to Logs/20251217T192821/Express0064.lzma.bak 2025-12-17T21:32:19.626Z,1766007139.626 [DataOverHttps](INFO): SBD MOMSN=26517169 2025-12-17T21:32:21.116Z,1766007141.116 [Default:CheckIn:Read_Iridium] Stopped 2025-12-17T21:32:21.138Z,1766007141.138 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-17T21:32:21.138Z,1766007141.138 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-17T21:32:37.657Z,1766007157.657 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-17T21:33:08.357Z,1766007188.357 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-17T21:34:25.945Z,1766007265.945 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-17T21:34:36.899Z,1766007276.899 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-17T21:34:47.809Z,1766007287.809 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-17T21:34:58.789Z,1766007298.789 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-17T21:35:09.623Z,1766007309.623 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-17T21:35:20.532Z,1766007320.532 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-17T21:35:31.034Z,1766007331.034 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-17T21:35:41.952Z,1766007341.952 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-17T21:35:47.946Z,1766007347.946 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2025-12-17T21:35:47.946Z,1766007347.946 [AHRS_M2] Data Fault, FailCount= 1 2025-12-17T21:35:47.946Z,1766007347.946 [AHRS_M2](ERROR): Data Fault 2025-12-17T21:35:47.977Z,1766007347.977 [CBIT](ERROR): Data Fault in component: AHRS_M2 2025-12-17T21:35:48.329Z,1766007348.329 [AHRS_M2](INFO): Powering down 2025-12-17T21:35:49.174Z,1766007349.174 [CBIT](INFO): Clearing failed state for component AHRS_M2 2025-12-17T21:35:49.174Z,1766007349.174 [AHRS_M2] No Fault, FailCount= 1 2025-12-17T21:35:49.541Z,1766007349.541 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-12-17T21:35:50.364Z,1766007350.364 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-17T21:35:53.100Z,1766007353.100 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:53.199Z,1766007353.199 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-12-17T21:35:53.560Z,1766007353.560 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:53.964Z,1766007353.964 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:54.372Z,1766007354.372 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:54.772Z,1766007354.772 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:55.176Z,1766007355.176 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:55.584Z,1766007355.584 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:55.988Z,1766007355.988 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:56.388Z,1766007356.388 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:56.792Z,1766007356.792 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:57.200Z,1766007357.200 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:57.624Z,1766007357.624 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:58.028Z,1766007358.028 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:58.416Z,1766007358.416 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:58.828Z,1766007358.828 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:59.236Z,1766007359.236 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:35:59.652Z,1766007359.652 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:36:00.056Z,1766007360.056 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:36:00.456Z,1766007360.456 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:36:00.852Z,1766007360.852 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:36:01.260Z,1766007361.260 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:36:01.656Z,1766007361.656 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:36:02.064Z,1766007362.064 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:36:02.468Z,1766007362.468 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:36:02.468Z,1766007362.468 [AHRS_M2](FAULT): Failed to initialize within timeout. 2025-12-17T21:36:02.468Z,1766007362.468 [AHRS_M2] Communications Fault, FailCount= 2 2025-12-17T21:36:02.468Z,1766007362.468 [AHRS_M2](ERROR): Communications Fault 2025-12-17T21:36:02.514Z,1766007362.514 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2025-12-17T21:36:02.549Z,1766007362.549 [AHRS_M2](INFO): Powering down 2025-12-17T21:36:03.438Z,1766007363.438 [CBIT](INFO): Clearing failed state for component AHRS_M2 2025-12-17T21:36:03.438Z,1766007363.438 [AHRS_M2] No Fault, FailCount= 2 2025-12-17T21:36:03.761Z,1766007363.761 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-12-17T21:36:07.084Z,1766007367.084 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2025-12-17T21:36:17.516Z,1766007377.516 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2025-12-17T21:36:17.516Z,1766007377.516 [AHRS_M2] Data Fault, FailCount= 3 2025-12-17T21:36:17.516Z,1766007377.516 [AHRS_M2](ERROR): Data Fault 2025-12-17T21:36:17.555Z,1766007377.555 [CBIT](ERROR): Data Fault in component: AHRS_M2 2025-12-17T21:36:17.901Z,1766007377.901 [AHRS_M2](INFO): Powering down 2025-12-17T21:36:18.736Z,1766007378.736 [CBIT](INFO): Clearing failed state for component AHRS_M2 2025-12-17T21:36:18.736Z,1766007378.736 [AHRS_M2] No Fault, FailCount= 3 2025-12-17T21:36:19.113Z,1766007379.113 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-12-17T21:36:19.518Z,1766007379.518 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T21:36:32.464Z,1766007392.464 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2025-12-17T21:36:32.464Z,1766007392.464 [AHRS_M2] Data Fault, FailCount= 4 2025-12-17T21:36:32.464Z,1766007392.464 [AHRS_M2](ERROR): Data Fault 2025-12-17T21:36:32.491Z,1766007392.491 [CBIT](ERROR): Data Fault in component: AHRS_M2 2025-12-17T21:36:32.849Z,1766007392.849 [AHRS_M2](INFO): Powering down 2025-12-17T21:36:33.254Z,1766007393.254 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T21:36:33.695Z,1766007393.695 [CBIT](INFO): Clearing failed state for component AHRS_M2 2025-12-17T21:36:33.695Z,1766007393.695 [AHRS_M2] No Fault, FailCount= 4 2025-12-17T21:36:34.061Z,1766007394.061 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-12-17T21:36:46.632Z,1766007406.632 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T21:36:47.412Z,1766007407.412 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2025-12-17T21:36:47.412Z,1766007407.412 [AHRS_M2] Data Fault, FailCount= 5 2025-12-17T21:36:47.412Z,1766007407.412 [AHRS_M2](ERROR): Data Fault 2025-12-17T21:36:47.463Z,1766007407.463 [CBIT](ERROR): Data Fault in component: AHRS_M2 2025-12-17T21:36:47.464Z,1766007407.464 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 2025-12-17T21:36:47.797Z,1766007407.797 [AHRS_M2](INFO): Powering down 2025-12-17T21:36:47.909Z,1766007407.909 [CBIT](INFO): Critical error at 20251217T213647 2025-12-17T21:37:00.321Z,1766007420.321 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T21:37:13.653Z,1766007433.653 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T21:37:21.752Z,1766007441.752 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-17T21:37:21.752Z,1766007441.752 [Default:CheckIn:C.Wait] Stopped 2025-12-17T21:37:21.752Z,1766007441.752 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-17T21:37:21.752Z,1766007441.752 [Default:CheckIn:D] Running Loop=1 2025-12-17T21:37:22.160Z,1766007442.160 [Default:CheckIn:D] Stopped 2025-12-17T21:37:22.160Z,1766007442.160 [Default:CheckIn:E] Running Loop=1 2025-12-17T21:37:22.563Z,1766007442.563 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 160.396452 min 2025-12-17T21:37:22.563Z,1766007442.563 [Default:CheckIn:E] Stopped 2025-12-17T21:37:22.563Z,1766007442.563 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-17T21:37:22.564Z,1766007442.564 [Default:CheckIn] Stopped 2025-12-17T21:37:22.564Z,1766007442.564 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T21:37:22.564Z,1766007442.564 [Default:CheckIn](INFO): Running loop #28 2025-12-17T21:37:22.564Z,1766007442.564 [Default:CheckIn] Running Loop=28 2025-12-17T21:37:22.564Z,1766007442.564 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-17T21:37:22.564Z,1766007442.564 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-17T21:37:26.985Z,1766007446.985 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T21:37:40.721Z,1766007460.721 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T21:37:54.053Z,1766007474.053 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T21:38:07.386Z,1766007487.386 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-12-17T21:38:17.081Z,1766007497.081 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T21:38:30.817Z,1766007510.817 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T21:38:43.341Z,1766007523.341 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T21:38:47.402Z,1766007527.402 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2025-12-17T21:38:47.402Z,1766007527.402 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-12-17T21:38:47.402Z,1766007527.402 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-12-17T21:38:47.417Z,1766007527.417 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-12-17T21:38:47.809Z,1766007527.809 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-12-17T21:38:47.809Z,1766007527.809 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 0 2025-12-17T21:38:55.865Z,1766007535.865 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T21:39:08.806Z,1766007548.806 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T21:39:15.950Z,1766007555.950 [CommandExec](IMPORTANT): got command quit 2025-12-17T21:39:16.952Z,1766007556.952 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-17T21:39:16.952Z,1766007556.952 [CommandExec](INFO): Uninitializing the command executive. 2025-12-17T21:39:16.952Z,1766007556.952 [CommandExec](INFO): Uninitializing the command scheduler. 2025-12-17T21:39:16.953Z,1766007556.953 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:17.100Z,1766007557.100 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-12-17T21:39:17.100Z,1766007557.100 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-12-17T21:39:17.100Z,1766007557.100 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:17.101Z,1766007557.101 [NavChartDb](INFO): Join timeout helper Thread ID is 1570 2025-12-17T21:39:17.108Z,1766007557.108 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-17T21:39:17.108Z,1766007557.108 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:17.120Z,1766007557.120 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-12-17T21:39:17.120Z,1766007557.120 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:17.121Z,1766007557.121 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1571 2025-12-17T21:39:17.316Z,1766007557.316 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-17T21:39:17.316Z,1766007557.316 [WetLabsBB2FL](INFO): Powering down 2025-12-17T21:39:17.317Z,1766007557.317 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:17.328Z,1766007557.328 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-12-17T21:39:17.328Z,1766007557.328 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:17.328Z,1766007557.328 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1572 2025-12-17T21:39:17.624Z,1766007557.624 [CTD_Seabird](INFO): Powering down 2025-12-17T21:39:17.636Z,1766007557.636 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-17T21:39:17.636Z,1766007557.636 [CTD_Seabird](INFO): Powering down 2025-12-17T21:39:17.652Z,1766007557.652 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:17.661Z,1766007557.661 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-12-17T21:39:17.661Z,1766007557.661 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:17.662Z,1766007557.662 [Radio_Surface](INFO): Join timeout helper Thread ID is 1573 2025-12-17T21:39:17.828Z,1766007557.828 [Radio_Surface](INFO): Powering down 2025-12-17T21:39:17.829Z,1766007557.829 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-17T21:39:17.829Z,1766007557.829 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:17.848Z,1766007557.848 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-12-17T21:39:17.848Z,1766007557.848 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:17.848Z,1766007557.848 [Onboard](INFO): Join timeout helper Thread ID is 1574 2025-12-17T21:39:18.684Z,1766007558.684 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-12-17T21:39:21.325Z,1766007561.325 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-12-17T21:39:21.708Z,1766007561.708 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-17T21:39:21.709Z,1766007561.709 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:21.722Z,1766007561.722 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-12-17T21:39:21.722Z,1766007561.722 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:21.723Z,1766007561.723 [DataOverHttps](INFO): Join timeout helper Thread ID is 1575 2025-12-17T21:39:22.701Z,1766007562.701 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-17T21:39:22.701Z,1766007562.701 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:22.720Z,1766007562.720 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-12-17T21:39:22.721Z,1766007562.721 [DAT ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:22.721Z,1766007562.721 [DAT](INFO): Join timeout helper Thread ID is 1576 2025-12-17T21:39:22.952Z,1766007562.952 [DAT](INFO): Powering down 2025-12-17T21:39:23.025Z,1766007563.025 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-17T21:39:23.025Z,1766007563.025 [DAT](INFO): Powering down 2025-12-17T21:39:23.026Z,1766007563.026 [DAT ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.045Z,1766007563.045 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-12-17T21:39:23.045Z,1766007563.045 [logger ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.045Z,1766007563.045 [logger](INFO): Join timeout helper Thread ID is 1577 2025-12-17T21:39:23.060Z,1766007563.060 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-17T21:39:23.060Z,1766007563.060 [logger ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.065Z,1766007563.065 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-12-17T21:39:23.065Z,1766007563.065 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.065Z,1766007563.065 [CommandLine](INFO): Join timeout helper Thread ID is 1578 2025-12-17T21:39:23.164Z,1766007563.164 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-17T21:39:23.164Z,1766007563.164 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.184Z,1766007563.184 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-12-17T21:39:23.185Z,1766007563.185 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.185Z,1766007563.185 [CommandExec](INFO): Join timeout helper Thread ID is 1579 2025-12-17T21:39:23.186Z,1766007563.186 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-12-17T21:39:23.186Z,1766007563.186 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.187Z,1766007563.187 [controlThread](INFO): Join timeout helper Thread ID is 1580 2025-12-17T21:39:23.332Z,1766007563.332 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-17T21:39:23.332Z,1766007563.332 [controlThread](DEBUG): Uninitializing ControlThread 2025-12-17T21:39:23.333Z,1766007563.333 [AHRS_M2](INFO): Powering down 2025-12-17T21:39:23.336Z,1766007563.336 [NAL9602](INFO): Powering down 2025-12-17T21:39:23.337Z,1766007563.337 [Sonardyne_Nano](INFO): Powering down 2025-12-17T21:39:23.544Z,1766007563.544 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-12-17T21:39:23.545Z,1766007563.545 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-12-17T21:39:23.545Z,1766007563.545 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-12-17T21:39:23.546Z,1766007563.546 [MissionManager](INFO): Uninitializing Mission Default 2025-12-17T21:39:23.546Z,1766007563.546 [Default] Stopped 2025-12-17T21:39:23.546Z,1766007563.546 [Default](DEBUG): Aggregate::uninitialize Default 2025-12-17T21:39:23.546Z,1766007563.546 [Default:B.GoToSurface] Stopped 2025-12-17T21:39:23.546Z,1766007563.546 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-17T21:39:23.546Z,1766007563.546 [Default:CheckIn] Stopped 2025-12-17T21:39:23.546Z,1766007563.546 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-17T21:39:23.547Z,1766007563.547 [Default:CheckIn:Read_GPS] Stopped 2025-12-17T21:39:23.550Z,1766007563.550 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-12-17T21:39:23.550Z,1766007563.550 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-12-17T21:39:23.550Z,1766007563.550 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-12-17T21:39:23.550Z,1766007563.550 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-12-17T21:39:23.551Z,1766007563.551 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-12-17T21:39:23.551Z,1766007563.551 [BuoyancyServo](INFO): Powering down 2025-12-17T21:39:23.564Z,1766007563.564 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-12-17T21:39:23.564Z,1766007563.564 [ElevatorServo](INFO): Powering down 2025-12-17T21:39:23.565Z,1766007563.565 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-12-17T21:39:23.565Z,1766007563.565 [MassServo](INFO): Powering down 2025-12-17T21:39:23.566Z,1766007563.566 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-12-17T21:39:23.566Z,1766007563.566 [RudderServo](INFO): Powering down 2025-12-17T21:39:23.567Z,1766007563.567 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-12-17T21:39:23.567Z,1766007563.567 [ThrusterHE](INFO): Powering down 2025-12-17T21:39:23.568Z,1766007563.568 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-12-17T21:39:23.568Z,1766007563.568 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-12-17T21:39:23.568Z,1766007563.568 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-12-17T21:39:23.569Z,1766007563.569 [CBIT](DEBUG): Powering off loads. 2025-12-17T21:39:23.580Z,1766007563.580 [CBIT](DEBUG): Disabling WDT. 2025-12-17T21:39:23.592Z,1766007563.592 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-12-17T21:39:23.592Z,1766007563.592 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-12-17T21:39:23.593Z,1766007563.593 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.645Z,1766007563.645 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.648Z,1766007563.648 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.654Z,1766007563.654 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.661Z,1766007563.661 [DAT ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.736Z,1766007563.736 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.740Z,1766007563.740 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.763Z,1766007563.763 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-12-17T21:39:23.764Z,1766007563.764 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-12-17T21:39:23.784Z,1766007563.784 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-12-17T21:39:23.864Z,1766007563.864 [logger ThreadHandler](INFO): Thread cancelled.