2025-08-01T19:19:09.766Z,1754075949.766 [CommandExec](IMPORTANT): got command restart logs
2025-08-01T19:19:15.760Z,1754075955.760 [NAL9602](INFO): SBD MO Status=2, MOMSN=10642, MT Status=2, MTMSN=0
2025-08-01T19:19:15.760Z,1754075955.760 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T19:19:20.719Z,1754075960.719 [DataOverHttps](INFO): Moved sent file to Logs/20250731T004936/Courier0310.lzma.bak
2025-08-01T19:19:20.720Z,1754075960.720 [DataOverHttps](INFO): SBD MOMSN=25657774
2025-08-01T19:20:16.340Z,1754076016.340 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250731T004936/Courier0313.lzma
2025-08-01T19:20:32.404Z,1754076032.404 [DataOverHttps](INFO): Moved sent file to Logs/20250731T004936/Courier0313.lzma.bak
2025-08-01T19:20:32.404Z,1754076032.404 [DataOverHttps](INFO): SBD MOMSN=25657778
2025-08-01T19:20:58.241Z,1754076058.241 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250731T004936/Express0311.lzma
2025-08-01T19:21:18.319Z,1754076078.319 [DataOverHttps](INFO): Moved sent file to Logs/20250731T004936/Express0311.lzma.bak
2025-08-01T19:21:18.319Z,1754076078.319 [DataOverHttps](INFO): SBD MOMSN=25657780
2025-08-01T19:21:44.201Z,1754076104.201 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20250731T004936/Express0314.lzma
2025-08-01T19:21:58.255Z,1754076118.255 [DataOverHttps](INFO): Moved sent file to Logs/20250731T004936/Express0314.lzma.bak
2025-08-01T19:21:58.256Z,1754076118.256 [DataOverHttps](INFO): SBD MOMSN=25657789
2025-08-01T19:21:59.821Z,1754076119.821 [Default:CheckIn:Read_Iridium] Stopped
2025-08-01T19:21:59.821Z,1754076119.821 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-01T19:21:59.821Z,1754076119.821 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-01T19:22:07.059Z,1754076127.059 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-01T19:22:37.767Z,1754076157.767 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-01T19:27:00.379Z,1754076420.379 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-01T19:27:00.379Z,1754076420.379 [Default:CheckIn:C.Wait] Stopped
2025-08-01T19:27:00.379Z,1754076420.379 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-01T19:27:00.380Z,1754076420.380 [Default:CheckIn:D] Running Loop=1
2025-08-01T19:27:00.784Z,1754076420.784 [Default:CheckIn:D] Stopped
2025-08-01T19:27:00.784Z,1754076420.784 [Default:CheckIn:E] Running Loop=1
2025-08-01T19:27:01.191Z,1754076421.191 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  26.124443 min 
2025-08-01T19:27:01.191Z,1754076421.191 [Default:CheckIn:E] Stopped
2025-08-01T19:27:01.191Z,1754076421.191 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-01T19:27:01.191Z,1754076421.191 [Default:CheckIn] Stopped
2025-08-01T19:27:01.191Z,1754076421.191 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-01T19:27:01.191Z,1754076421.191 [Default:CheckIn](INFO): Running loop #4
2025-08-01T19:27:01.191Z,1754076421.191 [Default:CheckIn] Running Loop=4
2025-08-01T19:27:01.191Z,1754076421.191 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-01T19:27:01.192Z,1754076421.192 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-01T19:27:03.207Z,1754076423.207 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192703.00,A,3648.39953,N,12147.12325,W,3.927,188.35,010825,,,D*7B
2025-08-01T19:27:03.210Z,1754076423.210 [NAL9602](INFO): GPS fix at 20250801T192703: (36.806659, -121.785387)
2025-08-01T19:27:03.220Z,1754076423.220 [Default:CheckIn:Read_GPS] Stopped
2025-08-01T19:27:03.220Z,1754076423.220 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-01T19:27:10.917Z,1754076430.917 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250801T191909/Courier0003.lzma
2025-08-01T19:27:21.960Z,1754076441.960 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Courier0003.lzma.bak
2025-08-01T19:27:21.961Z,1754076441.961 [DataOverHttps](INFO): SBD MOMSN=25657807
2025-08-01T19:27:33.904Z,1754076453.904 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-08-01T19:27:33.981Z,1754076453.981 [NAL9602](ERROR): received: 
+CSQ:0

OK642, 2, 0, 0, 0

OK
2025-08-01T19:27:47.994Z,1754076467.994 [DataOverHttps](INFO): Sending 578 bytes from file Logs/20250801T191909/Express0004.lzma
2025-08-01T19:27:59.039Z,1754076479.039 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Express0004.lzma.bak
2025-08-01T19:27:59.039Z,1754076479.039 [DataOverHttps](INFO): SBD MOMSN=25657811
2025-08-01T19:28:00.577Z,1754076480.577 [Default:CheckIn:Read_Iridium] Stopped
2025-08-01T19:28:00.577Z,1754076480.577 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-01T19:28:00.577Z,1754076480.577 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-01T19:28:00.993Z,1754076480.993 [NAL9602](INFO): SBD MO Status=2, MOMSN=10642, MT Status=2, MTMSN=0
2025-08-01T19:28:00.993Z,1754076480.993 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T19:29:25.013Z,1754076565.013 [NAL9602](INFO): SBD MO Status=1, MOMSN=10642, MT Status=0, MTMSN=0
2025-08-01T19:29:25.013Z,1754076565.013 [NAL9602](INFO): No messages in MT queue
2025-08-01T19:29:55.745Z,1754076595.745 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-01T19:33:01.178Z,1754076781.178 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-01T19:33:01.178Z,1754076781.178 [Default:CheckIn:C.Wait] Stopped
2025-08-01T19:33:01.178Z,1754076781.178 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-01T19:33:01.178Z,1754076781.178 [Default:CheckIn:D] Running Loop=1
2025-08-01T19:33:01.592Z,1754076781.592 [Default:CheckIn:D] Stopped
2025-08-01T19:33:01.592Z,1754076781.592 [Default:CheckIn:E] Running Loop=1
2025-08-01T19:33:02.005Z,1754076782.005 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  32.137901 min 
2025-08-01T19:33:02.005Z,1754076782.005 [Default:CheckIn:E] Stopped
2025-08-01T19:33:02.005Z,1754076782.005 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-01T19:33:02.005Z,1754076782.005 [Default:CheckIn] Stopped
2025-08-01T19:33:02.005Z,1754076782.005 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-01T19:33:02.005Z,1754076782.005 [Default:CheckIn](INFO): Running loop #5
2025-08-01T19:33:02.005Z,1754076782.005 [Default:CheckIn] Running Loop=5
2025-08-01T19:33:02.006Z,1754076782.006 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-01T19:33:02.006Z,1754076782.006 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-01T19:33:04.004Z,1754076784.004 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193304.00,A,3648.15242,N,12147.21993,W,0.000,200.78,010825,,,D*7E
2025-08-01T19:33:04.007Z,1754076784.007 [NAL9602](INFO): GPS fix at 20250801T193304: (36.802540, -121.786999)
2025-08-01T19:33:04.017Z,1754076784.017 [Default:CheckIn:Read_GPS] Stopped
2025-08-01T19:33:04.037Z,1754076784.037 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-01T19:33:11.369Z,1754076791.369 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250801T191909/Courier0006.lzma
2025-08-01T19:33:22.411Z,1754076802.411 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Courier0006.lzma.bak
2025-08-01T19:33:22.411Z,1754076802.411 [DataOverHttps](INFO): SBD MOMSN=25657830
2025-08-01T19:33:32.273Z,1754076812.273 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-08-01T19:33:33.097Z,1754076813.097 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-01T19:33:47.221Z,1754076827.221 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-08-01T19:33:48.433Z,1754076828.433 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-01T19:33:48.509Z,1754076828.509 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250801T191909/Express0007.lzma
2025-08-01T19:33:59.551Z,1754076839.551 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Express0007.lzma.bak
2025-08-01T19:33:59.551Z,1754076839.551 [DataOverHttps](INFO): SBD MOMSN=25657839
2025-08-01T19:34:00.977Z,1754076840.977 [Default:CheckIn:Read_Iridium] Stopped
2025-08-01T19:34:00.977Z,1754076840.977 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-01T19:34:00.977Z,1754076840.977 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-01T19:34:02.608Z,1754076842.608 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-08-01T19:34:02.989Z,1754076842.989 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-01T19:34:17.524Z,1754076857.524 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-08-01T19:34:17.937Z,1754076857.937 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-01T19:34:32.873Z,1754076872.873 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-08-01T19:34:33.289Z,1754076873.289 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-01T19:34:47.824Z,1754076887.824 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-08-01T19:34:48.238Z,1754076888.238 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-01T19:35:02.769Z,1754076902.769 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-08-01T19:35:03.185Z,1754076903.185 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-01T19:35:17.718Z,1754076917.718 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-08-01T19:35:18.137Z,1754076918.137 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-01T19:35:33.069Z,1754076933.069 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-08-01T19:35:33.485Z,1754076933.485 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-01T19:35:48.423Z,1754076948.423 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-08-01T19:35:48.837Z,1754076948.837 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-08-01T19:35:48.844Z,1754076948.844 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 5.
2025-08-01T19:35:48.847Z,1754076948.847 [BPC1](INFO): Received data from all battery sticks.
2025-08-01T19:36:14.684Z,1754076974.684 [NAL9602](INFO): SBD MO Status=2, MOMSN=10643, MT Status=2, MTMSN=0
2025-08-01T19:36:14.684Z,1754076974.684 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T19:37:12.468Z,1754077032.468 [NAL9602](INFO): SBD MO Status=1, MOMSN=10643, MT Status=0, MTMSN=0
2025-08-01T19:37:12.468Z,1754077032.468 [NAL9602](INFO): No messages in MT queue
2025-08-01T19:37:43.162Z,1754077063.162 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-01T19:39:01.572Z,1754077141.572 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-01T19:39:01.572Z,1754077141.572 [Default:CheckIn:C.Wait] Stopped
2025-08-01T19:39:01.572Z,1754077141.572 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-01T19:39:01.572Z,1754077141.572 [Default:CheckIn:D] Running Loop=1
2025-08-01T19:39:01.959Z,1754077141.959 [Default:CheckIn:D] Stopped
2025-08-01T19:39:01.959Z,1754077141.959 [Default:CheckIn:E] Running Loop=1
2025-08-01T19:39:02.358Z,1754077142.358 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  38.144031 min 
2025-08-01T19:39:02.358Z,1754077142.358 [Default:CheckIn:E] Stopped
2025-08-01T19:39:02.358Z,1754077142.358 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-01T19:39:02.358Z,1754077142.358 [Default:CheckIn] Stopped
2025-08-01T19:39:02.358Z,1754077142.358 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-01T19:39:02.358Z,1754077142.358 [Default:CheckIn](INFO): Running loop #6
2025-08-01T19:39:02.359Z,1754077142.359 [Default:CheckIn] Running Loop=6
2025-08-01T19:39:02.359Z,1754077142.359 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-01T19:39:02.359Z,1754077142.359 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-01T19:39:04.368Z,1754077144.368 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193904.00,A,3648.15259,N,12147.21927,W,0.019,299.90,010825,,,A*7A
2025-08-01T19:39:04.378Z,1754077144.378 [NAL9602](INFO): GPS fix at 20250801T193904: (36.802543, -121.786988)
2025-08-01T19:39:04.388Z,1754077144.388 [Default:CheckIn:Read_GPS] Stopped
2025-08-01T19:39:04.388Z,1754077144.388 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-01T19:39:11.569Z,1754077151.569 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20250801T191909/Courier0009.lzma
2025-08-01T19:39:13.258Z,1754077153.258 [NAL9602](INFO): SBD MO Status=1, MOMSN=10644, MT Status=0, MTMSN=0
2025-08-01T19:39:13.258Z,1754077153.258 [NAL9602](INFO): No messages in MT queue
2025-08-01T19:39:22.611Z,1754077162.611 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Courier0009.lzma.bak
2025-08-01T19:39:22.611Z,1754077162.611 [DataOverHttps](INFO): SBD MOMSN=25657858
2025-08-01T19:39:43.959Z,1754077183.959 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-01T19:39:48.374Z,1754077188.374 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250801T191909/Express0010.lzma
2025-08-01T19:39:59.415Z,1754077199.415 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Express0010.lzma.bak
2025-08-01T19:39:59.415Z,1754077199.415 [DataOverHttps](INFO): SBD MOMSN=25657863
2025-08-01T19:40:00.943Z,1754077200.943 [Default:CheckIn:Read_Iridium] Stopped
2025-08-01T19:40:00.944Z,1754077200.944 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-01T19:40:00.944Z,1754077200.944 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-01T19:45:01.515Z,1754077501.515 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-01T19:45:01.515Z,1754077501.515 [Default:CheckIn:C.Wait] Stopped
2025-08-01T19:45:01.515Z,1754077501.515 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-01T19:45:01.516Z,1754077501.516 [Default:CheckIn:D] Running Loop=1
2025-08-01T19:45:01.921Z,1754077501.921 [Default:CheckIn:D] Stopped
2025-08-01T19:45:01.921Z,1754077501.921 [Default:CheckIn:E] Running Loop=1
2025-08-01T19:45:02.327Z,1754077502.327 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  44.143384 min 
2025-08-01T19:45:02.327Z,1754077502.327 [Default:CheckIn:E] Stopped
2025-08-01T19:45:02.327Z,1754077502.327 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-01T19:45:02.327Z,1754077502.327 [Default:CheckIn] Stopped
2025-08-01T19:45:02.327Z,1754077502.327 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-01T19:45:02.327Z,1754077502.327 [Default:CheckIn](INFO): Running loop #7
2025-08-01T19:45:02.328Z,1754077502.328 [Default:CheckIn] Running Loop=7
2025-08-01T19:45:02.328Z,1754077502.328 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-01T19:45:02.328Z,1754077502.328 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-01T19:45:04.346Z,1754077504.346 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194504.00,A,3648.17639,N,12147.27853,W,0.039,259.60,010825,,,A*74
2025-08-01T19:45:04.348Z,1754077504.348 [NAL9602](INFO): GPS fix at 20250801T194504: (36.802940, -121.787976)
2025-08-01T19:45:04.358Z,1754077504.358 [Default:CheckIn:Read_GPS] Stopped
2025-08-01T19:45:04.358Z,1754077504.358 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-01T19:45:11.208Z,1754077511.208 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250801T191909/Courier0012.lzma
2025-08-01T19:45:22.251Z,1754077522.251 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Courier0012.lzma.bak
2025-08-01T19:45:22.251Z,1754077522.251 [DataOverHttps](INFO): SBD MOMSN=25657878
2025-08-01T19:45:48.157Z,1754077548.157 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250801T191909/Express0013.lzma
2025-08-01T19:45:59.199Z,1754077559.199 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Express0013.lzma.bak
2025-08-01T19:45:59.199Z,1754077559.199 [DataOverHttps](INFO): SBD MOMSN=25657881
2025-08-01T19:46:00.556Z,1754077560.556 [Default:CheckIn:Read_Iridium] Stopped
2025-08-01T19:46:00.556Z,1754077560.556 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-01T19:46:00.556Z,1754077560.556 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-01T19:47:20.106Z,1754077640.106 [NAL9602](INFO): SBD MO Status=1, MOMSN=10645, MT Status=0, MTMSN=0
2025-08-01T19:47:20.106Z,1754077640.106 [NAL9602](INFO): No messages in MT queue
2025-08-01T19:47:50.810Z,1754077670.810 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-01T19:51:01.128Z,1754077861.128 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-01T19:51:01.128Z,1754077861.128 [Default:CheckIn:C.Wait] Stopped
2025-08-01T19:51:01.128Z,1754077861.128 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-01T19:51:01.128Z,1754077861.128 [Default:CheckIn:D] Running Loop=1
2025-08-01T19:51:01.539Z,1754077861.539 [Default:CheckIn:D] Stopped
2025-08-01T19:51:01.539Z,1754077861.539 [Default:CheckIn:E] Running Loop=1
2025-08-01T19:51:01.938Z,1754077861.938 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  50.137032 min 
2025-08-01T19:51:01.938Z,1754077861.938 [Default:CheckIn:E] Stopped
2025-08-01T19:51:01.938Z,1754077861.938 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-01T19:51:01.938Z,1754077861.938 [Default:CheckIn] Stopped
2025-08-01T19:51:01.938Z,1754077861.938 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-01T19:51:01.938Z,1754077861.938 [Default:CheckIn](INFO): Running loop #8
2025-08-01T19:51:01.939Z,1754077861.939 [Default:CheckIn] Running Loop=8
2025-08-01T19:51:01.939Z,1754077861.939 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-01T19:51:01.939Z,1754077861.939 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-01T19:51:03.948Z,1754077863.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195104.00,A,3648.17724,N,12147.27892,W,0.136,259.60,010825,,,A*7F
2025-08-01T19:51:03.958Z,1754077863.958 [NAL9602](INFO): GPS fix at 20250801T195104: (36.802954, -121.787982)
2025-08-01T19:51:03.968Z,1754077863.968 [Default:CheckIn:Read_GPS] Stopped
2025-08-01T19:51:03.968Z,1754077863.968 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-01T19:51:11.061Z,1754077871.061 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250801T191909/Courier0015.lzma
2025-08-01T19:51:22.103Z,1754077882.103 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Courier0015.lzma.bak
2025-08-01T19:51:22.104Z,1754077882.104 [DataOverHttps](INFO): SBD MOMSN=25657901
2025-08-01T19:51:28.600Z,1754077888.600 [NAL9602](INFO): SBD MO Status=2, MOMSN=10646, MT Status=2, MTMSN=0
2025-08-01T19:51:28.600Z,1754077888.600 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T19:51:47.930Z,1754077907.930 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250801T191909/Express0016.lzma
2025-08-01T19:51:50.812Z,1754077910.812 [NAL9602](INFO): SBD MO Status=2, MOMSN=10646, MT Status=2, MTMSN=0
2025-08-01T19:51:50.812Z,1754077910.812 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T19:51:58.971Z,1754077918.971 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Express0016.lzma.bak
2025-08-01T19:51:58.971Z,1754077918.971 [DataOverHttps](INFO): SBD MOMSN=25657904
2025-08-01T19:52:00.125Z,1754077920.125 [Default:CheckIn:Read_Iridium] Stopped
2025-08-01T19:52:00.125Z,1754077920.125 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-01T19:52:00.126Z,1754077920.126 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-01T19:52:04.152Z,1754077924.152 [NAL9602](INFO): SBD MO Status=2, MOMSN=10646, MT Status=2, MTMSN=0
2025-08-01T19:52:04.153Z,1754077924.153 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T19:56:06.159Z,1754078166.159 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-01T19:56:36.859Z,1754078196.859 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-01T19:57:00.711Z,1754078220.711 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-01T19:57:00.711Z,1754078220.711 [Default:CheckIn:C.Wait] Stopped
2025-08-01T19:57:00.711Z,1754078220.711 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-01T19:57:00.712Z,1754078220.712 [Default:CheckIn:D] Running Loop=1
2025-08-01T19:57:01.108Z,1754078221.108 [Default:CheckIn:D] Stopped
2025-08-01T19:57:01.108Z,1754078221.108 [Default:CheckIn:E] Running Loop=1
2025-08-01T19:57:01.519Z,1754078221.519 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  56.129846 min 
2025-08-01T19:57:01.519Z,1754078221.519 [Default:CheckIn:E] Stopped
2025-08-01T19:57:01.519Z,1754078221.519 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-01T19:57:01.519Z,1754078221.519 [Default:CheckIn] Stopped
2025-08-01T19:57:01.519Z,1754078221.519 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-01T19:57:01.520Z,1754078221.520 [Default:CheckIn](INFO): Running loop #9
2025-08-01T19:57:01.520Z,1754078221.520 [Default:CheckIn] Running Loop=9
2025-08-01T19:57:01.520Z,1754078221.520 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-01T19:57:01.520Z,1754078221.520 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-01T19:57:03.527Z,1754078223.527 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195704.00,A,3648.17756,N,12147.27735,W,0.505,259.60,010825,,,A*7A
2025-08-01T19:57:03.529Z,1754078223.529 [NAL9602](INFO): GPS fix at 20250801T195704: (36.802959, -121.787956)
2025-08-01T19:57:03.539Z,1754078223.539 [Default:CheckIn:Read_GPS] Stopped
2025-08-01T19:57:03.539Z,1754078223.539 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-01T19:57:11.153Z,1754078231.153 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250801T191909/Courier0018.lzma
2025-08-01T19:57:22.195Z,1754078242.195 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Courier0018.lzma.bak
2025-08-01T19:57:22.195Z,1754078242.195 [DataOverHttps](INFO): SBD MOMSN=25657919
2025-08-01T19:57:34.231Z,1754078254.231 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-08-01T19:57:34.313Z,1754078254.313 [NAL9602](ERROR): received: 
+CSQ:0

OK646, 2, 0, 0, 0

OK
2025-08-01T19:57:46.352Z,1754078266.352 [NAL9602](INFO): SBD MO Status=1, MOMSN=10646, MT Status=0, MTMSN=0
2025-08-01T19:57:46.352Z,1754078266.352 [NAL9602](INFO): No messages in MT queue
2025-08-01T19:57:48.109Z,1754078268.109 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250801T191909/Express0019.lzma
2025-08-01T19:57:59.546Z,1754078279.546 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Express0019.lzma.bak
2025-08-01T19:57:59.546Z,1754078279.546 [DataOverHttps](INFO): SBD MOMSN=25657923
2025-08-01T19:58:00.505Z,1754078280.505 [Default:CheckIn:Read_Iridium] Stopped
2025-08-01T19:58:00.505Z,1754078280.505 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-01T19:58:00.505Z,1754078280.505 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-01T19:58:17.054Z,1754078297.054 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-01T20:03:01.091Z,1754078581.091 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-01T20:03:01.091Z,1754078581.091 [Default:CheckIn:C.Wait] Stopped
2025-08-01T20:03:01.091Z,1754078581.091 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-01T20:03:01.092Z,1754078581.092 [Default:CheckIn:D] Running Loop=1
2025-08-01T20:03:01.495Z,1754078581.495 [Default:CheckIn:D] Stopped
2025-08-01T20:03:01.495Z,1754078581.495 [Default:CheckIn:E] Running Loop=1
2025-08-01T20:03:01.898Z,1754078581.898 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  62.136292 min 
2025-08-01T20:03:01.898Z,1754078581.898 [Default:CheckIn:E] Stopped
2025-08-01T20:03:01.898Z,1754078581.898 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-01T20:03:01.898Z,1754078581.898 [Default:CheckIn] Stopped
2025-08-01T20:03:01.898Z,1754078581.898 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-01T20:03:01.898Z,1754078581.898 [Default:CheckIn](INFO): Running loop #10
2025-08-01T20:03:01.898Z,1754078581.898 [Default:CheckIn] Running Loop=10
2025-08-01T20:03:01.898Z,1754078581.898 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-01T20:03:01.899Z,1754078581.899 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-01T20:03:03.908Z,1754078583.908 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200304.00,A,3648.17984,N,12147.27502,W,0.078,331.22,010825,,,A*70
2025-08-01T20:03:03.920Z,1754078583.920 [NAL9602](INFO): GPS fix at 20250801T200304: (36.802997, -121.787917)
2025-08-01T20:03:03.931Z,1754078583.931 [Default:CheckIn:Read_GPS] Stopped
2025-08-01T20:03:03.931Z,1754078583.931 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-01T20:03:11.325Z,1754078591.325 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250801T191909/Courier0021.lzma
2025-08-01T20:03:22.367Z,1754078602.367 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Courier0021.lzma.bak
2025-08-01T20:03:22.367Z,1754078602.367 [DataOverHttps](INFO): SBD MOMSN=25657941
2025-08-01T20:03:48.321Z,1754078628.321 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250801T191909/Express0022.lzma
2025-08-01T20:03:59.363Z,1754078639.363 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Express0022.lzma.bak
2025-08-01T20:03:59.363Z,1754078639.363 [DataOverHttps](INFO): SBD MOMSN=25657951
2025-08-01T20:04:00.572Z,1754078640.572 [Default:CheckIn:Read_Iridium] Stopped
2025-08-01T20:04:00.572Z,1754078640.572 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-01T20:04:00.572Z,1754078640.572 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-01T20:05:53.650Z,1754078753.650 [NAL9602](INFO): SBD MO Status=2, MOMSN=10647, MT Status=2, MTMSN=0
2025-08-01T20:05:53.650Z,1754078753.650 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T20:06:19.917Z,1754078779.917 [NAL9602](INFO): SBD MO Status=1, MOMSN=10647, MT Status=0, MTMSN=0
2025-08-01T20:06:19.917Z,1754078779.917 [NAL9602](INFO): No messages in MT queue
2025-08-01T20:06:50.626Z,1754078810.626 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-01T20:09:01.136Z,1754078941.136 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-01T20:09:01.136Z,1754078941.136 [Default:CheckIn:C.Wait] Stopped
2025-08-01T20:09:01.136Z,1754078941.136 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-01T20:09:01.136Z,1754078941.136 [Default:CheckIn:D] Running Loop=1
2025-08-01T20:09:01.535Z,1754078941.535 [Default:CheckIn:D] Stopped
2025-08-01T20:09:01.535Z,1754078941.535 [Default:CheckIn:E] Running Loop=1
2025-08-01T20:09:01.936Z,1754078941.936 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  68.136959 min 
2025-08-01T20:09:01.936Z,1754078941.936 [Default:CheckIn:E] Stopped
2025-08-01T20:09:01.936Z,1754078941.936 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-01T20:09:01.936Z,1754078941.936 [Default:CheckIn] Stopped
2025-08-01T20:09:01.936Z,1754078941.936 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-01T20:09:01.936Z,1754078941.936 [Default:CheckIn](INFO): Running loop #11
2025-08-01T20:09:01.937Z,1754078941.937 [Default:CheckIn] Running Loop=11
2025-08-01T20:09:01.937Z,1754078941.937 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-01T20:09:01.937Z,1754078941.937 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-01T20:09:03.950Z,1754078943.950 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200904.00,A,3648.17630,N,12147.27907,W,0.641,331.22,010825,,,A*7F
2025-08-01T20:09:03.953Z,1754078943.953 [NAL9602](INFO): GPS fix at 20250801T200904: (36.802938, -121.787984)
2025-08-01T20:09:03.962Z,1754078943.962 [Default:CheckIn:Read_GPS] Stopped
2025-08-01T20:09:03.962Z,1754078943.962 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-01T20:09:11.613Z,1754078951.613 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250801T191909/Courier0024.lzma
2025-08-01T20:09:22.655Z,1754078962.655 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Courier0024.lzma.bak
2025-08-01T20:09:22.655Z,1754078962.655 [DataOverHttps](INFO): SBD MOMSN=25657963
2025-08-01T20:09:24.556Z,1754078964.556 [NAL9602](INFO): SBD MO Status=2, MOMSN=10648, MT Status=2, MTMSN=0
2025-08-01T20:09:24.556Z,1754078964.556 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T20:09:43.141Z,1754078983.141 [NAL9602](INFO): SBD MO Status=2, MOMSN=10648, MT Status=2, MTMSN=0
2025-08-01T20:09:43.141Z,1754078983.141 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T20:09:48.481Z,1754078988.481 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250801T191909/Express0025.lzma
2025-08-01T20:09:58.893Z,1754078998.893 [NAL9602](INFO): SBD MO Status=2, MOMSN=10648, MT Status=2, MTMSN=0
2025-08-01T20:09:58.893Z,1754078998.893 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T20:09:59.535Z,1754078999.535 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Express0025.lzma.bak
2025-08-01T20:09:59.535Z,1754078999.535 [DataOverHttps](INFO): SBD MOMSN=25657966
2025-08-01T20:10:00.946Z,1754079000.946 [Default:CheckIn:Read_Iridium] Stopped
2025-08-01T20:10:00.946Z,1754079000.946 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-01T20:10:00.946Z,1754079000.946 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-01T20:10:20.708Z,1754079020.708 [NAL9602](INFO): SBD MO Status=2, MOMSN=10648, MT Status=2, MTMSN=0
2025-08-01T20:10:20.708Z,1754079020.708 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T20:10:37.684Z,1754079037.684 [NAL9602](INFO): SBD MO Status=2, MOMSN=10648, MT Status=2, MTMSN=0
2025-08-01T20:10:37.684Z,1754079037.684 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T20:11:15.656Z,1754079075.656 [NAL9602](INFO): SBD MO Status=2, MOMSN=10648, MT Status=2, MTMSN=0
2025-08-01T20:11:15.656Z,1754079075.656 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T20:14:06.160Z,1754079246.160 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-01T20:14:36.862Z,1754079276.862 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-01T20:15:01.523Z,1754079301.523 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-01T20:15:01.523Z,1754079301.523 [Default:CheckIn:C.Wait] Stopped
2025-08-01T20:15:01.523Z,1754079301.523 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-01T20:15:01.523Z,1754079301.523 [Default:CheckIn:D] Running Loop=1
2025-08-01T20:15:01.922Z,1754079301.922 [Default:CheckIn:D] Stopped
2025-08-01T20:15:01.922Z,1754079301.922 [Default:CheckIn:E] Running Loop=1
2025-08-01T20:15:02.337Z,1754079302.337 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  74.143408 min 
2025-08-01T20:15:02.337Z,1754079302.337 [Default:CheckIn:E] Stopped
2025-08-01T20:15:02.338Z,1754079302.338 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-01T20:15:02.338Z,1754079302.338 [Default:CheckIn] Stopped
2025-08-01T20:15:02.338Z,1754079302.338 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-01T20:15:02.338Z,1754079302.338 [Default:CheckIn](INFO): Running loop #12
2025-08-01T20:15:02.338Z,1754079302.338 [Default:CheckIn] Running Loop=12
2025-08-01T20:15:02.338Z,1754079302.338 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-01T20:15:02.338Z,1754079302.338 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-01T20:15:04.344Z,1754079304.344 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201504.00,A,3648.18881,N,12147.27123,W,0.700,246.15,010825,,,A*76
2025-08-01T20:15:04.346Z,1754079304.346 [NAL9602](INFO): GPS fix at 20250801T201504: (36.803147, -121.787854)
2025-08-01T20:15:04.376Z,1754079304.376 [Default:CheckIn:Read_GPS] Stopped
2025-08-01T20:15:04.376Z,1754079304.376 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-01T20:15:11.224Z,1754079311.224 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250801T191909/Courier0027.lzma
2025-08-01T20:15:22.267Z,1754079322.267 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Courier0027.lzma.bak
2025-08-01T20:15:22.267Z,1754079322.267 [DataOverHttps](INFO): SBD MOMSN=25657989
2025-08-01T20:15:35.072Z,1754079335.072 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-08-01T20:15:35.145Z,1754079335.145 [NAL9602](ERROR): received: 
+CSQ:0

OK648, 2, 0, 0, 0

OK
2025-08-01T20:15:45.584Z,1754079345.584 [NAL9602](INFO): SBD MO Status=1, MOMSN=10648, MT Status=0, MTMSN=0
2025-08-01T20:15:45.584Z,1754079345.584 [NAL9602](INFO): No messages in MT queue
2025-08-01T20:15:48.433Z,1754079348.433 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20250801T191909/Express0028.lzma
2025-08-01T20:15:59.475Z,1754079359.475 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Express0028.lzma.bak
2025-08-01T20:15:59.475Z,1754079359.475 [DataOverHttps](INFO): SBD MOMSN=25657992
2025-08-01T20:16:00.937Z,1754079360.937 [Default:CheckIn:Read_Iridium] Stopped
2025-08-01T20:16:00.938Z,1754079360.938 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-01T20:16:00.938Z,1754079360.938 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-01T20:16:16.279Z,1754079376.279 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-01T20:21:01.529Z,1754079661.529 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-01T20:21:01.529Z,1754079661.529 [Default:CheckIn:C.Wait] Stopped
2025-08-01T20:21:01.529Z,1754079661.529 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-01T20:21:01.529Z,1754079661.529 [Default:CheckIn:D] Running Loop=1
2025-08-01T20:21:01.928Z,1754079661.928 [Default:CheckIn:D] Stopped
2025-08-01T20:21:01.928Z,1754079661.928 [Default:CheckIn:E] Running Loop=1
2025-08-01T20:21:02.333Z,1754079662.333 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  80.143506 min 
2025-08-01T20:21:02.333Z,1754079662.333 [Default:CheckIn:E] Stopped
2025-08-01T20:21:02.334Z,1754079662.334 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-01T20:21:02.334Z,1754079662.334 [Default:CheckIn] Stopped
2025-08-01T20:21:02.334Z,1754079662.334 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-01T20:21:02.334Z,1754079662.334 [Default:CheckIn](INFO): Running loop #13
2025-08-01T20:21:02.334Z,1754079662.334 [Default:CheckIn] Running Loop=13
2025-08-01T20:21:02.334Z,1754079662.334 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-01T20:21:02.334Z,1754079662.334 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-01T20:21:04.346Z,1754079664.346 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202104.00,A,3648.16998,N,12147.26480,W,1.341,180.46,010825,,,A*74
2025-08-01T20:21:04.373Z,1754079664.373 [NAL9602](INFO): GPS fix at 20250801T202104: (36.802833, -121.787747)
2025-08-01T20:21:04.391Z,1754079664.391 [Default:CheckIn:Read_GPS] Stopped
2025-08-01T20:21:04.391Z,1754079664.391 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-01T20:21:11.521Z,1754079671.521 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250801T191909/Courier0030.lzma
2025-08-01T20:21:22.564Z,1754079682.564 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Courier0030.lzma.bak
2025-08-01T20:21:22.564Z,1754079682.564 [DataOverHttps](INFO): SBD MOMSN=25658007
2025-08-01T20:21:48.389Z,1754079708.389 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20250801T191909/Express0031.lzma
2025-08-01T20:21:54.442Z,1754079714.442 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-08-01T20:21:54.442Z,1754079714.442 [DropWeight] Hardware Fault, FailCount= 1
2025-08-01T20:21:54.442Z,1754079714.442 [DropWeight](ERROR): Hardware Fault
2025-08-01T20:21:54.495Z,1754079714.495 [CommandExec](FAULT): Scheduling is paused
2025-08-01T20:21:54.496Z,1754079714.496 [CBIT](INFO): Critical error at 20250801T202154
2025-08-01T20:21:54.498Z,1754079714.498 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-08-01T20:21:54.499Z,1754079714.499 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-08-01T20:21:54.859Z,1754079714.859 [CBIT](INFO): Critical error at 20250801T202154
2025-08-01T20:21:59.439Z,1754079719.439 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Express0031.lzma.bak
2025-08-01T20:21:59.439Z,1754079719.439 [DataOverHttps](INFO): SBD MOMSN=25658019
2025-08-01T20:22:00.943Z,1754079720.943 [Default:CheckIn:Read_Iridium] Stopped
2025-08-01T20:22:00.943Z,1754079720.943 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-01T20:22:00.943Z,1754079720.943 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-01T20:22:36.463Z,1754079756.463 [NAL9602](INFO): SBD MO Status=2, MOMSN=10649, MT Status=2, MTMSN=0
2025-08-01T20:22:36.464Z,1754079756.464 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T20:25:03.519Z,1754079903.519 [NAL9602](INFO): SBD MO Status=2, MOMSN=10649, MT Status=2, MTMSN=0
2025-08-01T20:25:03.520Z,1754079903.520 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-08-01T20:26:06.947Z,1754079966.947 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-01T20:26:37.646Z,1754079997.646 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-01T20:27:01.502Z,1754080021.502 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-01T20:27:01.502Z,1754080021.502 [Default:CheckIn:C.Wait] Stopped
2025-08-01T20:27:01.502Z,1754080021.502 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-01T20:27:01.502Z,1754080021.502 [Default:CheckIn:D] Running Loop=1
2025-08-01T20:27:01.896Z,1754080021.896 [Default:CheckIn:D] Stopped
2025-08-01T20:27:01.896Z,1754080021.896 [Default:CheckIn:E] Running Loop=1
2025-08-01T20:27:02.300Z,1754080022.300 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  86.142969 min 
2025-08-01T20:27:02.300Z,1754080022.300 [Default:CheckIn:E] Stopped
2025-08-01T20:27:02.300Z,1754080022.300 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-01T20:27:02.300Z,1754080022.300 [Default:CheckIn] Stopped
2025-08-01T20:27:02.300Z,1754080022.300 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-01T20:27:02.301Z,1754080022.301 [Default:CheckIn](INFO): Running loop #14
2025-08-01T20:27:02.301Z,1754080022.301 [Default:CheckIn] Running Loop=14
2025-08-01T20:27:02.301Z,1754080022.301 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-01T20:27:02.301Z,1754080022.301 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-01T20:27:04.314Z,1754080024.314 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202704.00,A,3648.16204,N,12147.27761,W,1.438,159.21,010825,,,A*7D
2025-08-01T20:27:04.316Z,1754080024.316 [NAL9602](INFO): GPS fix at 20250801T202704: (36.802701, -121.787960)
2025-08-01T20:27:04.326Z,1754080024.326 [Default:CheckIn:Read_GPS] Stopped
2025-08-01T20:27:04.326Z,1754080024.326 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-01T20:27:11.245Z,1754080031.245 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20250801T191909/Courier0033.lzma
2025-08-01T20:27:22.287Z,1754080042.287 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Courier0033.lzma.bak
2025-08-01T20:27:22.287Z,1754080042.287 [DataOverHttps](INFO): SBD MOMSN=25658040
2025-08-01T20:27:35.039Z,1754080055.039 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-08-01T20:27:35.117Z,1754080055.117 [NAL9602](ERROR): received: 
+CSQ:0

OK649, 2, 0, 0, 0

OK
2025-08-01T20:27:48.138Z,1754080068.138 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20250801T191909/Express0034.lzma
2025-08-01T20:27:59.179Z,1754080079.179 [DataOverHttps](INFO): Moved sent file to Logs/20250801T191909/Express0034.lzma.bak
2025-08-01T20:27:59.179Z,1754080079.179 [DataOverHttps](INFO): SBD MOMSN=25658045
2025-08-01T20:28:00.509Z,1754080080.509 [Default:CheckIn:Read_Iridium] Stopped
2025-08-01T20:28:00.509Z,1754080080.509 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-01T20:28:00.509Z,1754080080.509 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-01T20:32:06.178Z,1754080326.178 [CommandExec](IMPORTANT): got command quit
2025-08-01T20:32:06.927Z,1754080326.927 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-01T20:32:07.185Z,1754080327.185 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-01T20:32:07.185Z,1754080327.185 [CommandExec](INFO): Uninitializing the command executive.
2025-08-01T20:32:07.185Z,1754080327.185 [CommandExec](INFO): Uninitializing the command scheduler.
2025-08-01T20:32:07.186Z,1754080327.186 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:07.277Z,1754080327.277 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-08-01T20:32:07.278Z,1754080327.278 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-08-01T20:32:07.279Z,1754080327.279 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:07.280Z,1754080327.280 [NavChartDb](INFO): Join timeout helper Thread ID is 5155
2025-08-01T20:32:07.434Z,1754080327.434 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-01T20:32:07.434Z,1754080327.434 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:07.437Z,1754080327.437 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-08-01T20:32:07.437Z,1754080327.437 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:07.437Z,1754080327.437 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5156
2025-08-01T20:32:07.678Z,1754080327.678 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-01T20:32:07.680Z,1754080327.680 [WetLabsBB2FL](INFO): Powering down
2025-08-01T20:32:07.681Z,1754080327.681 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:07.682Z,1754080327.682 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-08-01T20:32:07.682Z,1754080327.682 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:07.682Z,1754080327.682 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5157
2025-08-01T20:32:07.837Z,1754080327.837 [CTD_Seabird](INFO): Powering down
2025-08-01T20:32:07.852Z,1754080327.852 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-01T20:32:07.852Z,1754080327.852 [CTD_Seabird](INFO): Powering down
2025-08-01T20:32:07.865Z,1754080327.865 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:07.881Z,1754080327.881 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-08-01T20:32:07.881Z,1754080327.881 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:07.882Z,1754080327.882 [Radio_Surface](INFO): Join timeout helper Thread ID is 5158
2025-08-01T20:32:07.981Z,1754080327.981 [Radio_Surface](INFO): Powering down
2025-08-01T20:32:07.982Z,1754080327.982 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-01T20:32:07.982Z,1754080327.982 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:08.005Z,1754080328.005 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-08-01T20:32:08.005Z,1754080328.005 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:08.006Z,1754080328.006 [Onboard](INFO): Join timeout helper Thread ID is 5159
2025-08-01T20:32:08.921Z,1754080328.921 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-08-01T20:32:09.117Z,1754080329.117 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-01T20:32:09.117Z,1754080329.117 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:09.122Z,1754080329.122 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-08-01T20:32:09.122Z,1754080329.122 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:09.123Z,1754080329.123 [DataOverHttps](INFO): Join timeout helper Thread ID is 5160
2025-08-01T20:32:09.926Z,1754080329.926 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-01T20:32:09.926Z,1754080329.926 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:09.942Z,1754080329.942 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-08-01T20:32:09.942Z,1754080329.942 [DAT ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:09.942Z,1754080329.942 [DAT](INFO): Join timeout helper Thread ID is 5161
2025-08-01T20:32:09.997Z,1754080329.997 [DAT](INFO): Powering down
2025-08-01T20:32:10.071Z,1754080330.071 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-01T20:32:10.071Z,1754080330.071 [DAT](INFO): Powering down
2025-08-01T20:32:10.072Z,1754080330.072 [DAT ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.074Z,1754080330.074 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-08-01T20:32:10.074Z,1754080330.074 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.074Z,1754080330.074 [BackseatComponent](INFO): Join timeout helper Thread ID is 5162
2025-08-01T20:32:10.121Z,1754080330.121 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-01T20:32:10.122Z,1754080330.122 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.134Z,1754080330.134 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-08-01T20:32:10.134Z,1754080330.134 [logger ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.134Z,1754080330.134 [logger](INFO): Join timeout helper Thread ID is 5163
2025-08-01T20:32:10.135Z,1754080330.135 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-01T20:32:10.135Z,1754080330.135 [logger ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.136Z,1754080330.136 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-08-01T20:32:10.136Z,1754080330.136 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.137Z,1754080330.137 [CommandLine](INFO): Join timeout helper Thread ID is 5164
2025-08-01T20:32:10.149Z,1754080330.149 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-01T20:32:10.150Z,1754080330.150 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.158Z,1754080330.158 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-08-01T20:32:10.158Z,1754080330.158 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.158Z,1754080330.158 [CommandExec](INFO): Join timeout helper Thread ID is 5165
2025-08-01T20:32:10.162Z,1754080330.162 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-08-01T20:32:10.162Z,1754080330.162 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.162Z,1754080330.162 [controlThread](INFO): Join timeout helper Thread ID is 5166
2025-08-01T20:32:10.184Z,1754080330.184 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-01T20:32:10.184Z,1754080330.184 [controlThread](DEBUG): Uninitializing ControlThread
2025-08-01T20:32:10.184Z,1754080330.184 [AHRS_M2](INFO): Powering down
2025-08-01T20:32:10.262Z,1754080330.262 [NAL9602](INFO): Powering down
2025-08-01T20:32:10.263Z,1754080330.263 [Sonardyne_Nano](INFO): Powering down
2025-08-01T20:32:10.469Z,1754080330.469 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-08-01T20:32:10.470Z,1754080330.470 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-08-01T20:32:10.471Z,1754080330.471 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-08-01T20:32:10.471Z,1754080330.471 [MissionManager](INFO): Uninitializing Mission Default
2025-08-01T20:32:10.471Z,1754080330.471 [Default] Stopped
2025-08-01T20:32:10.471Z,1754080330.471 [Default](DEBUG): Aggregate::uninitialize Default
2025-08-01T20:32:10.471Z,1754080330.471 [Default:B.GoToSurface] Stopped
2025-08-01T20:32:10.471Z,1754080330.471 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-01T20:32:10.472Z,1754080330.472 [Default:CheckIn] Stopped
2025-08-01T20:32:10.472Z,1754080330.472 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-01T20:32:10.472Z,1754080330.472 [Default:CheckIn:C.Wait] Stopped
2025-08-01T20:32:10.472Z,1754080330.472 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-01T20:32:10.475Z,1754080330.475 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-08-01T20:32:10.475Z,1754080330.475 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-08-01T20:32:10.475Z,1754080330.475 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-08-01T20:32:10.476Z,1754080330.476 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-08-01T20:32:10.476Z,1754080330.476 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-08-01T20:32:10.476Z,1754080330.476 [BuoyancyServo](INFO): Powering down
2025-08-01T20:32:10.489Z,1754080330.489 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-08-01T20:32:10.489Z,1754080330.489 [ElevatorServo](INFO): Powering down
2025-08-01T20:32:10.490Z,1754080330.490 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-08-01T20:32:10.490Z,1754080330.490 [MassServo](INFO): Powering down
2025-08-01T20:32:10.491Z,1754080330.491 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-08-01T20:32:10.491Z,1754080330.491 [RudderServo](INFO): Powering down
2025-08-01T20:32:10.492Z,1754080330.492 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-08-01T20:32:10.492Z,1754080330.492 [ThrusterHE](INFO): Powering down
2025-08-01T20:32:10.493Z,1754080330.493 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-08-01T20:32:10.493Z,1754080330.493 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-08-01T20:32:10.494Z,1754080330.494 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-08-01T20:32:10.494Z,1754080330.494 [CBIT](DEBUG): Powering off loads.
2025-08-01T20:32:10.505Z,1754080330.505 [CBIT](DEBUG): Disabling WDT.
2025-08-01T20:32:10.517Z,1754080330.517 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-08-01T20:32:10.517Z,1754080330.517 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-08-01T20:32:10.518Z,1754080330.518 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.585Z,1754080330.585 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.588Z,1754080330.588 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.595Z,1754080330.595 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.602Z,1754080330.602 [DAT ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.645Z,1754080330.645 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.687Z,1754080330.687 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.692Z,1754080330.692 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.711Z,1754080330.711 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-08-01T20:32:10.711Z,1754080330.711 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-08-01T20:32:10.726Z,1754080330.726 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-08-01T20:32:10.780Z,1754080330.780 [logger ThreadHandler](INFO): Thread cancelled.