2025-07-23T17:48:44.544Z,1753292924.544 [DataOverHttps](INFO): Received command: restart logs 2025-07-23T17:48:44.566Z,1753292924.566 [CommandExec](IMPORTANT): got command restart logs 2025-07-23T17:48:49.700Z,1753292929.700 [NAL9602](INFO): SBD MO Status=2, MOMSN=64050, MT Status=2, MTMSN=0 2025-07-23T17:48:49.700Z,1753292929.700 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T17:49:23.479Z,1753292963.479 [NAL9602](INFO): SBD MO Status=0, MOMSN=64050, MT Status=0, MTMSN=0 2025-07-23T17:49:23.480Z,1753292963.480 [NAL9602](INFO): No messages in MT queue 2025-07-23T17:49:54.179Z,1753292994.179 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T17:50:15.590Z,1753293015.590 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T17:50:28.970Z,1753293028.970 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T17:50:42.301Z,1753293042.301 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T17:50:55.632Z,1753293055.632 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T17:51:08.964Z,1753293068.964 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T17:51:22.297Z,1753293082.297 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T17:51:36.033Z,1753293096.033 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T17:51:49.365Z,1753293109.365 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T17:52:02.696Z,1753293122.696 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T17:52:16.028Z,1753293136.028 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T17:52:29.468Z,1753293149.468 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T17:53:14.341Z,1753293194.341 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T17:53:14.342Z,1753293194.342 [Default:CheckIn:C.Wait] Stopped 2025-07-23T17:53:14.342Z,1753293194.342 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T17:53:14.342Z,1753293194.342 [Default:CheckIn:D] Running Loop=1 2025-07-23T17:53:14.757Z,1753293194.757 [Default:CheckIn:D] Stopped 2025-07-23T17:53:14.757Z,1753293194.757 [Default:CheckIn:E] Running Loop=1 2025-07-23T17:53:15.148Z,1753293195.148 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.486992 min 2025-07-23T17:53:15.149Z,1753293195.149 [Default:CheckIn:E] Stopped 2025-07-23T17:53:15.149Z,1753293195.149 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T17:53:15.149Z,1753293195.149 [Default:CheckIn] Stopped 2025-07-23T17:53:15.149Z,1753293195.149 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T17:53:15.149Z,1753293195.149 [Default:CheckIn](INFO): Running loop #3 2025-07-23T17:53:15.149Z,1753293195.149 [Default:CheckIn] Running Loop=3 2025-07-23T17:53:15.149Z,1753293195.149 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T17:53:15.149Z,1753293195.149 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T17:53:17.154Z,1753293197.154 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175318.00,A,3648.15926,N,12149.81661,W,17.689,77.85,230725,,,D*7A 2025-07-23T17:53:17.156Z,1753293197.156 [NAL9602](INFO): GPS fix at 20250723T175318: (36.802654, -121.830277) 2025-07-23T17:53:17.167Z,1753293197.167 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T17:53:17.168Z,1753293197.168 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T17:53:25.200Z,1753293205.200 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250722T214724/Courier0135.lzma 2025-07-23T17:53:26.203Z,1753293206.203 [DataOverHttps](INFO): Moved sent file to Logs/20250722T214724/Courier0135.lzma.bak 2025-07-23T17:53:26.203Z,1753293206.203 [DataOverHttps](INFO): SBD MOMSN=25577634 2025-07-23T17:54:39.949Z,1753293279.949 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T17:54:40.384Z,1753293280.384 [NAL9602](INFO): SBD MO Status=2, MOMSN=64051, MT Status=2, MTMSN=0 2025-07-23T17:54:40.384Z,1753293280.384 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T17:54:59.367Z,1753293299.367 [NAL9602](INFO): SBD MO Status=2, MOMSN=64051, MT Status=2, MTMSN=0 2025-07-23T17:54:59.367Z,1753293299.367 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T17:55:05.742Z,1753293305.742 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20250723T174844/Courier0000.lzma 2025-07-23T17:55:06.742Z,1753293306.742 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0000.lzma.bak 2025-07-23T17:55:06.743Z,1753293306.743 [DataOverHttps](INFO): SBD MOMSN=25577645 2025-07-23T17:55:19.580Z,1753293319.580 [NAL9602](INFO): SBD MO Status=0, MOMSN=64051, MT Status=0, MTMSN=0 2025-07-23T17:55:19.580Z,1753293319.580 [NAL9602](INFO): No messages in MT queue 2025-07-23T17:55:23.052Z,1753293323.052 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20250722T214724/Express0136.lzma 2025-07-23T17:55:24.055Z,1753293324.055 [DataOverHttps](INFO): Moved sent file to Logs/20250722T214724/Express0136.lzma.bak 2025-07-23T17:55:24.055Z,1753293324.055 [DataOverHttps](INFO): SBD MOMSN=25577649 2025-07-23T17:55:40.145Z,1753293340.145 [DataOverHttps](INFO): Sending 496 bytes from file Logs/20250723T174844/Express0001.lzma 2025-07-23T17:55:41.146Z,1753293341.146 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0001.lzma.bak 2025-07-23T17:55:41.147Z,1753293341.147 [DataOverHttps](INFO): SBD MOMSN=25577652 2025-07-23T17:55:50.267Z,1753293350.267 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T17:55:57.486Z,1753293357.486 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250723T174844/Express0004.lzma 2025-07-23T17:55:58.486Z,1753293358.486 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0004.lzma.bak 2025-07-23T17:55:58.487Z,1753293358.487 [DataOverHttps](INFO): SBD MOMSN=25577657 2025-07-23T17:55:59.604Z,1753293359.604 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T17:55:59.604Z,1753293359.604 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T17:55:59.604Z,1753293359.604 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T17:58:00.813Z,1753293480.813 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T17:58:31.949Z,1753293511.949 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T17:59:03.081Z,1753293543.081 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T17:59:46.630Z,1753293586.630 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-23T17:59:52.288Z,1753293592.288 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:00:05.618Z,1753293605.618 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:00:20.162Z,1753293620.162 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:00:34.706Z,1753293634.706 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:00:49.250Z,1753293649.250 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:01:00.176Z,1753293660.176 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:01:00.176Z,1753293660.176 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:01:00.176Z,1753293660.176 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:01:00.177Z,1753293660.177 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:01:00.575Z,1753293660.575 [Default:CheckIn:D] Stopped 2025-07-23T18:01:00.575Z,1753293660.575 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:01:00.991Z,1753293660.991 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.250627 min 2025-07-23T18:01:00.991Z,1753293660.991 [Default:CheckIn:E] Stopped 2025-07-23T18:01:00.992Z,1753293660.992 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:01:00.992Z,1753293660.992 [Default:CheckIn] Stopped 2025-07-23T18:01:00.992Z,1753293660.992 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:01:00.992Z,1753293660.992 [Default:CheckIn](INFO): Running loop #4 2025-07-23T18:01:00.992Z,1753293660.992 [Default:CheckIn] Running Loop=4 2025-07-23T18:01:00.992Z,1753293660.992 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:01:00.992Z,1753293660.992 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:01:02.985Z,1753293662.985 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:01:02.998Z,1753293662.998 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180104.00,A,3648.02420,N,12149.11145,W,3.849,84.91,230725,,,D*44 2025-07-23T18:01:03.000Z,1753293663.000 [NAL9602](INFO): GPS fix at 20250723T180104: (36.800403, -121.818524) 2025-07-23T18:01:03.035Z,1753293663.035 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:01:03.036Z,1753293663.036 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:01:11.684Z,1753293671.684 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250723T174844/Courier0006.lzma 2025-07-23T18:01:13.690Z,1753293673.690 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0006.lzma.bak 2025-07-23T18:01:13.691Z,1753293673.691 [DataOverHttps](INFO): SBD MOMSN=25577675 2025-07-23T18:01:17.529Z,1753293677.529 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:01:23.658Z,1753293683.658 [NAL9602](INFO): SBD MO Status=0, MOMSN=64052, MT Status=0, MTMSN=0 2025-07-23T18:01:23.658Z,1753293683.658 [NAL9602](INFO): No messages in MT queue 2025-07-23T18:01:29.717Z,1753293689.717 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20250723T174844/Express0007.lzma 2025-07-23T18:01:30.719Z,1753293690.719 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0007.lzma.bak 2025-07-23T18:01:30.719Z,1753293690.719 [DataOverHttps](INFO): SBD MOMSN=25577685 2025-07-23T18:01:30.861Z,1753293690.861 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:01:32.094Z,1753293692.094 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:01:32.094Z,1753293692.094 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:01:32.094Z,1753293692.094 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:01:45.405Z,1753293705.405 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:01:54.303Z,1753293714.303 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T18:01:59.147Z,1753293719.147 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:03:34.077Z,1753293814.077 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T18:04:05.213Z,1753293845.213 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T18:04:36.337Z,1753293876.337 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-23T18:06:32.702Z,1753293992.702 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:06:32.702Z,1753293992.702 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:06:32.702Z,1753293992.702 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:06:32.702Z,1753293992.702 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:06:33.110Z,1753293993.110 [Default:CheckIn:D] Stopped 2025-07-23T18:06:33.110Z,1753293993.110 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:06:33.489Z,1753293993.489 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.792865 min 2025-07-23T18:06:33.489Z,1753293993.489 [Default:CheckIn:E] Stopped 2025-07-23T18:06:33.489Z,1753293993.489 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:06:33.489Z,1753293993.489 [Default:CheckIn] Stopped 2025-07-23T18:06:33.489Z,1753293993.489 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:06:33.489Z,1753293993.489 [Default:CheckIn](INFO): Running loop #5 2025-07-23T18:06:33.489Z,1753293993.489 [Default:CheckIn] Running Loop=5 2025-07-23T18:06:33.490Z,1753293993.490 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:06:33.490Z,1753293993.490 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:06:35.501Z,1753293995.501 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180636.00,A,3648.00160,N,12148.63749,W,4.121,92.00,230725,,,D*40 2025-07-23T18:06:35.503Z,1753293995.503 [NAL9602](INFO): GPS fix at 20250723T180636: (36.800027, -121.810625) 2025-07-23T18:06:35.515Z,1753293995.515 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:06:35.515Z,1753293995.515 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:06:43.459Z,1753294003.459 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250723T174844/Courier0009.lzma 2025-07-23T18:06:44.454Z,1753294004.454 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0009.lzma.bak 2025-07-23T18:06:44.455Z,1753294004.455 [DataOverHttps](INFO): SBD MOMSN=25577713 2025-07-23T18:06:48.428Z,1753294008.428 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-07-23T18:06:48.432Z,1753294008.432 [BPC1](FAULT): Failed to receive data from 7 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 36, 37, 50, 51, 54. 2025-07-23T18:07:00.589Z,1753294020.589 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250723T174844/Express0010.lzma 2025-07-23T18:07:01.591Z,1753294021.591 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0010.lzma.bak 2025-07-23T18:07:01.591Z,1753294021.591 [DataOverHttps](INFO): SBD MOMSN=25577716 2025-07-23T18:07:02.998Z,1753294022.998 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:07:02.998Z,1753294022.998 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:07:02.998Z,1753294022.998 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:07:20.757Z,1753294040.757 [NAL9602](INFO): SBD MO Status=0, MOMSN=64053, MT Status=0, MTMSN=0 2025-07-23T18:07:20.758Z,1753294040.758 [NAL9602](INFO): No messages in MT queue 2025-07-23T18:07:51.458Z,1753294071.458 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T18:12:03.656Z,1753294323.656 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:12:03.656Z,1753294323.656 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:12:03.673Z,1753294323.673 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:12:03.673Z,1753294323.673 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:12:04.064Z,1753294324.064 [Default:CheckIn:D] Stopped 2025-07-23T18:12:04.064Z,1753294324.064 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:12:04.465Z,1753294324.465 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.308767 min 2025-07-23T18:12:04.465Z,1753294324.465 [Default:CheckIn:E] Stopped 2025-07-23T18:12:04.465Z,1753294324.465 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:12:04.465Z,1753294324.465 [Default:CheckIn] Stopped 2025-07-23T18:12:04.465Z,1753294324.465 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:12:04.465Z,1753294324.465 [Default:CheckIn](INFO): Running loop #6 2025-07-23T18:12:04.465Z,1753294324.465 [Default:CheckIn] Running Loop=6 2025-07-23T18:12:04.466Z,1753294324.466 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:12:04.466Z,1753294324.466 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:12:06.501Z,1753294326.501 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181207.00,A,3648.03730,N,12148.15743,W,4.335,78.41,230725,,,D*4A 2025-07-23T18:12:06.503Z,1753294326.503 [NAL9602](INFO): GPS fix at 20250723T181207: (36.800622, -121.802624) 2025-07-23T18:12:06.514Z,1753294326.514 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:12:06.514Z,1753294326.514 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:12:14.713Z,1753294334.713 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20250723T174844/Courier0012.lzma 2025-07-23T18:12:15.715Z,1753294335.715 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0012.lzma.bak 2025-07-23T18:12:15.715Z,1753294335.715 [DataOverHttps](INFO): SBD MOMSN=25577739 2025-07-23T18:12:19.012Z,1753294339.012 [NAL9602](INFO): SBD MO Status=0, MOMSN=64054, MT Status=0, MTMSN=0 2025-07-23T18:12:19.012Z,1753294339.012 [NAL9602](INFO): No messages in MT queue 2025-07-23T18:12:34.817Z,1753294354.817 [DataOverHttps](INFO): Sending 308 bytes from file Logs/20250723T174844/Express0013.lzma 2025-07-23T18:12:35.818Z,1753294355.818 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0013.lzma.bak 2025-07-23T18:12:35.819Z,1753294355.819 [DataOverHttps](INFO): SBD MOMSN=25577742 2025-07-23T18:12:37.266Z,1753294357.266 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:12:37.266Z,1753294357.266 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:12:37.266Z,1753294357.266 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:12:49.754Z,1753294369.754 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T18:17:37.837Z,1753294657.837 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:17:37.837Z,1753294657.837 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:17:37.837Z,1753294657.837 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:17:37.837Z,1753294657.837 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:17:38.228Z,1753294658.228 [Default:CheckIn:D] Stopped 2025-07-23T18:17:38.228Z,1753294658.228 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:17:38.633Z,1753294658.633 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.878170 min 2025-07-23T18:17:38.633Z,1753294658.633 [Default:CheckIn:E] Stopped 2025-07-23T18:17:38.633Z,1753294658.633 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:17:38.633Z,1753294658.633 [Default:CheckIn] Stopped 2025-07-23T18:17:38.633Z,1753294658.633 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:17:38.634Z,1753294658.634 [Default:CheckIn](INFO): Running loop #7 2025-07-23T18:17:38.634Z,1753294658.634 [Default:CheckIn] Running Loop=7 2025-07-23T18:17:38.634Z,1753294658.634 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:17:38.634Z,1753294658.634 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:17:40.646Z,1753294660.646 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181741.00,A,3648.17380,N,12147.71235,W,4.335,50.27,230725,,,D*44 2025-07-23T18:17:40.649Z,1753294660.649 [NAL9602](INFO): GPS fix at 20250723T181741: (36.802897, -121.795206) 2025-07-23T18:17:40.660Z,1753294660.660 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:17:40.660Z,1753294660.660 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:17:48.488Z,1753294668.488 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250723T174844/Courier0015.lzma 2025-07-23T18:17:49.490Z,1753294669.490 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0015.lzma.bak 2025-07-23T18:17:49.491Z,1753294669.491 [DataOverHttps](INFO): SBD MOMSN=25577800 2025-07-23T18:17:55.188Z,1753294675.188 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:17:58.827Z,1753294678.827 [NAL9602](INFO): SBD MO Status=0, MOMSN=64055, MT Status=0, MTMSN=0 2025-07-23T18:17:58.827Z,1753294678.827 [NAL9602](INFO): No messages in MT queue 2025-07-23T18:18:05.553Z,1753294685.553 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250723T174844/Express0016.lzma 2025-07-23T18:18:06.554Z,1753294686.554 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0016.lzma.bak 2025-07-23T18:18:06.555Z,1753294686.555 [DataOverHttps](INFO): SBD MOMSN=25577803 2025-07-23T18:18:06.900Z,1753294686.900 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:18:07.733Z,1753294687.733 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:18:07.733Z,1753294687.733 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:18:07.734Z,1753294687.734 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:18:19.021Z,1753294699.021 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:18:29.534Z,1753294709.534 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T18:18:30.737Z,1753294710.737 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:18:42.477Z,1753294722.477 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:18:53.790Z,1753294733.790 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:19:05.908Z,1753294745.908 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:19:17.641Z,1753294757.641 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:21:45.493Z,1753294905.493 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:22:01.249Z,1753294921.249 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:22:16.609Z,1753294936.609 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:22:32.465Z,1753294952.465 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:22:47.761Z,1753294967.761 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:23:02.304Z,1753294982.304 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:23:08.382Z,1753294988.382 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:23:08.382Z,1753294988.382 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:23:08.382Z,1753294988.382 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:23:08.382Z,1753294988.382 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:23:08.795Z,1753294988.795 [Default:CheckIn:D] Stopped 2025-07-23T18:23:08.795Z,1753294988.795 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:23:09.199Z,1753294989.199 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.387622 min 2025-07-23T18:23:09.199Z,1753294989.199 [Default:CheckIn:E] Stopped 2025-07-23T18:23:09.200Z,1753294989.200 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:23:09.200Z,1753294989.200 [Default:CheckIn] Stopped 2025-07-23T18:23:09.200Z,1753294989.200 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:23:09.200Z,1753294989.200 [Default:CheckIn](INFO): Running loop #8 2025-07-23T18:23:09.200Z,1753294989.200 [Default:CheckIn] Running Loop=8 2025-07-23T18:23:09.200Z,1753294989.200 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:23:09.200Z,1753294989.200 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:23:11.201Z,1753294991.201 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182312.00,A,3648.42700,N,12147.33550,W,3.382,46.51,230725,,,D*46 2025-07-23T18:23:11.203Z,1753294991.203 [NAL9602](INFO): GPS fix at 20250723T182312: (36.807117, -121.788925) 2025-07-23T18:23:11.230Z,1753294991.230 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:23:11.230Z,1753294991.230 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:23:18.130Z,1753294998.130 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:23:18.584Z,1753294998.584 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250723T174844/Courier0018.lzma 2025-07-23T18:23:19.586Z,1753294999.586 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0018.lzma.bak 2025-07-23T18:23:19.587Z,1753294999.587 [DataOverHttps](INFO): SBD MOMSN=25577831 2025-07-23T18:23:27.825Z,1753295007.825 [NAL9602](INFO): SBD MO Status=0, MOMSN=64056, MT Status=0, MTMSN=0 2025-07-23T18:23:27.826Z,1753295007.826 [NAL9602](INFO): No messages in MT queue 2025-07-23T18:23:33.876Z,1753295013.876 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:23:35.669Z,1753295015.669 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20250723T174844/Express0019.lzma 2025-07-23T18:23:36.671Z,1753295016.671 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0019.lzma.bak 2025-07-23T18:23:36.671Z,1753295016.671 [DataOverHttps](INFO): SBD MOMSN=25577834 2025-07-23T18:23:37.942Z,1753295017.942 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:23:37.942Z,1753295017.942 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:23:37.942Z,1753295017.942 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:23:49.229Z,1753295029.229 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:23:58.542Z,1753295038.542 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T18:24:05.010Z,1753295045.010 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:24:57.116Z,1753295097.116 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-07-23T18:28:38.588Z,1753295318.588 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:28:38.588Z,1753295318.588 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:28:38.588Z,1753295318.588 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:28:38.589Z,1753295318.589 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:28:39.008Z,1753295319.008 [Default:CheckIn:D] Stopped 2025-07-23T18:28:39.008Z,1753295319.008 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:28:39.409Z,1753295319.409 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.891166 min 2025-07-23T18:28:39.409Z,1753295319.409 [Default:CheckIn:E] Stopped 2025-07-23T18:28:39.409Z,1753295319.409 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:28:39.409Z,1753295319.409 [Default:CheckIn] Stopped 2025-07-23T18:28:39.409Z,1753295319.409 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:28:39.409Z,1753295319.409 [Default:CheckIn](INFO): Running loop #9 2025-07-23T18:28:39.409Z,1753295319.409 [Default:CheckIn] Running Loop=9 2025-07-23T18:28:39.410Z,1753295319.410 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:28:39.410Z,1753295319.410 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:28:41.524Z,1753295321.524 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182842.00,A,3648.42815,N,12147.11891,W,3.188,183.19,230725,,,D*7F 2025-07-23T18:28:41.571Z,1753295321.571 [NAL9602](INFO): GPS fix at 20250723T182842: (36.807136, -121.785315) 2025-07-23T18:28:41.608Z,1753295321.608 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:28:41.608Z,1753295321.608 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:28:48.820Z,1753295328.820 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250723T174844/Courier0021.lzma 2025-07-23T18:28:49.822Z,1753295329.822 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0021.lzma.bak 2025-07-23T18:28:49.823Z,1753295329.823 [DataOverHttps](INFO): SBD MOMSN=25577896 2025-07-23T18:28:54.343Z,1753295334.343 [NAL9602](INFO): SBD MO Status=0, MOMSN=64057, MT Status=0, MTMSN=0 2025-07-23T18:28:54.344Z,1753295334.344 [NAL9602](INFO): No messages in MT queue 2025-07-23T18:29:05.921Z,1753295345.921 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20250723T174844/Express0022.lzma 2025-07-23T18:29:06.923Z,1753295346.923 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0022.lzma.bak 2025-07-23T18:29:06.924Z,1753295346.924 [DataOverHttps](INFO): SBD MOMSN=25577899 2025-07-23T18:29:08.089Z,1753295348.089 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:29:08.089Z,1753295348.089 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:29:08.089Z,1753295348.089 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:29:25.038Z,1753295365.038 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T18:31:16.939Z,1753295476.939 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-23T18:31:22.188Z,1753295482.188 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:31:35.520Z,1753295495.520 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:31:48.852Z,1753295508.852 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:32:02.589Z,1753295522.589 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:32:16.744Z,1753295536.744 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:32:30.507Z,1753295550.507 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:32:44.646Z,1753295564.646 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:32:54.346Z,1753295574.346 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-07-23T18:32:58.383Z,1753295578.383 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:33:12.174Z,1753295592.174 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:33:25.504Z,1753295605.504 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:33:39.244Z,1753295619.244 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:34:08.754Z,1753295648.754 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:34:08.754Z,1753295648.754 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:34:08.754Z,1753295648.754 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:34:08.755Z,1753295648.755 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:34:09.170Z,1753295649.170 [Default:CheckIn:D] Stopped 2025-07-23T18:34:09.170Z,1753295649.170 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:34:09.609Z,1753295649.609 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.393872 min 2025-07-23T18:34:09.609Z,1753295649.609 [Default:CheckIn:E] Stopped 2025-07-23T18:34:09.609Z,1753295649.609 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:34:09.609Z,1753295649.609 [Default:CheckIn] Stopped 2025-07-23T18:34:09.609Z,1753295649.609 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:34:09.610Z,1753295649.610 [Default:CheckIn](INFO): Running loop #10 2025-07-23T18:34:09.610Z,1753295649.610 [Default:CheckIn] Running Loop=10 2025-07-23T18:34:09.610Z,1753295649.610 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:34:09.610Z,1753295649.610 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:34:11.566Z,1753295651.566 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183412.00,A,3648.16849,N,12147.19835,W,2.255,221.13,230725,,,D*7A 2025-07-23T18:34:11.568Z,1753295651.568 [NAL9602](INFO): GPS fix at 20250723T183412: (36.802808, -121.786639) 2025-07-23T18:34:11.579Z,1753295651.579 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:34:11.579Z,1753295651.579 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:34:21.789Z,1753295661.789 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250723T174844/Courier0024.lzma 2025-07-23T18:34:22.790Z,1753295662.790 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0024.lzma.bak 2025-07-23T18:34:22.791Z,1753295662.791 [DataOverHttps](INFO): SBD MOMSN=25577925 2025-07-23T18:34:39.853Z,1753295679.853 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20250723T174844/Express0025.lzma 2025-07-23T18:34:40.854Z,1753295680.854 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0025.lzma.bak 2025-07-23T18:34:40.855Z,1753295680.855 [DataOverHttps](INFO): SBD MOMSN=25577928 2025-07-23T18:34:41.412Z,1753295681.412 [NAL9602](INFO): SBD MO Status=2, MOMSN=64058, MT Status=2, MTMSN=0 2025-07-23T18:34:41.412Z,1753295681.412 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T18:34:42.270Z,1753295682.270 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:34:42.270Z,1753295682.270 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:34:42.270Z,1753295682.270 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:34:57.580Z,1753295697.580 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-07-23T18:35:48.576Z,1753295748.576 [NAL9602](INFO): SBD MO Status=2, MOMSN=64058, MT Status=2, MTMSN=0 2025-07-23T18:35:48.576Z,1753295748.576 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T18:36:09.083Z,1753295769.083 [NAL9602](INFO): SBD MO Status=2, MOMSN=64058, MT Status=2, MTMSN=0 2025-07-23T18:36:09.083Z,1753295769.083 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T18:36:22.080Z,1753295782.080 [NAL9602](INFO): SBD MO Status=0, MOMSN=64058, MT Status=0, MTMSN=0 2025-07-23T18:36:22.080Z,1753295782.080 [NAL9602](INFO): No messages in MT queue 2025-07-23T18:36:52.766Z,1753295812.766 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T18:39:42.948Z,1753295982.948 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:39:42.948Z,1753295982.948 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:39:42.948Z,1753295982.948 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:39:42.948Z,1753295982.948 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:39:43.333Z,1753295983.333 [Default:CheckIn:D] Stopped 2025-07-23T18:39:43.333Z,1753295983.333 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:39:43.732Z,1753295983.732 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.963245 min 2025-07-23T18:39:43.732Z,1753295983.732 [Default:CheckIn:E] Stopped 2025-07-23T18:39:43.749Z,1753295983.749 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:39:43.749Z,1753295983.749 [Default:CheckIn] Stopped 2025-07-23T18:39:43.749Z,1753295983.749 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:39:43.749Z,1753295983.749 [Default:CheckIn](INFO): Running loop #11 2025-07-23T18:39:43.749Z,1753295983.749 [Default:CheckIn] Running Loop=11 2025-07-23T18:39:43.749Z,1753295983.749 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:39:43.749Z,1753295983.749 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:39:45.756Z,1753295985.756 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183947.00,A,3648.14740,N,12147.22272,W,0.019,206.14,230725,,,D*78 2025-07-23T18:39:45.758Z,1753295985.758 [NAL9602](INFO): GPS fix at 20250723T183947: (36.802457, -121.787045) 2025-07-23T18:39:45.798Z,1753295985.798 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:39:45.798Z,1753295985.798 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:39:52.832Z,1753295992.832 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250723T174844/Courier0027.lzma 2025-07-23T18:39:53.835Z,1753295993.835 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0027.lzma.bak 2025-07-23T18:39:53.835Z,1753295993.835 [DataOverHttps](INFO): SBD MOMSN=25577947 2025-07-23T18:39:58.697Z,1753295998.697 [NAL9602](INFO): SBD MO Status=0, MOMSN=64059, MT Status=0, MTMSN=0 2025-07-23T18:39:58.697Z,1753295998.697 [NAL9602](INFO): No messages in MT queue 2025-07-23T18:40:10.245Z,1753296010.245 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20250723T174844/Express0028.lzma 2025-07-23T18:40:11.247Z,1753296011.247 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0028.lzma.bak 2025-07-23T18:40:11.247Z,1753296011.247 [DataOverHttps](INFO): SBD MOMSN=25577951 2025-07-23T18:40:12.440Z,1753296012.440 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:40:12.440Z,1753296012.440 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:40:12.441Z,1753296012.441 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:40:29.410Z,1753296029.410 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T18:40:57.688Z,1753296057.688 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:41:11.021Z,1753296071.021 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:41:24.354Z,1753296084.354 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:41:37.692Z,1753296097.692 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:41:51.017Z,1753296111.017 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:42:04.752Z,1753296124.752 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:42:18.138Z,1753296138.138 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:42:32.676Z,1753296152.676 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:42:46.817Z,1753296166.817 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:43:00.568Z,1753296180.568 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:43:13.900Z,1753296193.900 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:44:57.796Z,1753296297.796 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-07-23T18:45:13.206Z,1753296313.206 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:45:13.206Z,1753296313.206 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:45:13.206Z,1753296313.206 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:45:13.207Z,1753296313.207 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:45:13.575Z,1753296313.575 [Default:CheckIn:D] Stopped 2025-07-23T18:45:13.575Z,1753296313.575 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:45:13.982Z,1753296313.982 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.467285 min 2025-07-23T18:45:13.982Z,1753296313.982 [Default:CheckIn:E] Stopped 2025-07-23T18:45:13.982Z,1753296313.982 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:45:13.982Z,1753296313.982 [Default:CheckIn] Stopped 2025-07-23T18:45:13.982Z,1753296313.982 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:45:13.982Z,1753296313.982 [Default:CheckIn](INFO): Running loop #12 2025-07-23T18:45:13.983Z,1753296313.983 [Default:CheckIn] Running Loop=12 2025-07-23T18:45:13.983Z,1753296313.983 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:45:13.983Z,1753296313.983 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:45:15.969Z,1753296315.969 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184517.00,A,3648.15244,N,12147.21992,W,0.272,206.14,230725,,,A*7A 2025-07-23T18:45:15.971Z,1753296315.971 [NAL9602](INFO): GPS fix at 20250723T184517: (36.802541, -121.786999) 2025-07-23T18:45:15.982Z,1753296315.982 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:45:15.982Z,1753296315.982 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:45:24.056Z,1753296324.056 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250723T174844/Courier0030.lzma 2025-07-23T18:45:25.058Z,1753296325.058 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0030.lzma.bak 2025-07-23T18:45:25.059Z,1753296325.059 [DataOverHttps](INFO): SBD MOMSN=25577988 2025-07-23T18:45:41.333Z,1753296341.333 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250723T174844/Express0031.lzma 2025-07-23T18:45:42.339Z,1753296342.339 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0031.lzma.bak 2025-07-23T18:45:42.339Z,1753296342.339 [DataOverHttps](INFO): SBD MOMSN=25577992 2025-07-23T18:45:43.061Z,1753296343.061 [NAL9602](INFO): SBD MO Status=0, MOMSN=64060, MT Status=0, MTMSN=0 2025-07-23T18:45:43.061Z,1753296343.061 [NAL9602](INFO): No messages in MT queue 2025-07-23T18:45:43.885Z,1753296343.885 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:45:43.885Z,1753296343.885 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:45:43.885Z,1753296343.885 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:46:13.774Z,1753296373.774 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T18:47:49.975Z,1753296469.975 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 6. 2025-07-23T18:47:49.979Z,1753296469.979 [BPC1](FAULT): Failed to receive data from 7 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 36, 37, 50, 51, 54. 2025-07-23T18:50:44.634Z,1753296644.634 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:50:44.634Z,1753296644.634 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:50:44.634Z,1753296644.634 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:50:44.635Z,1753296644.635 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:50:45.032Z,1753296645.032 [Default:CheckIn:D] Stopped 2025-07-23T18:50:45.048Z,1753296645.048 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:50:45.434Z,1753296645.434 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.991577 min 2025-07-23T18:50:45.434Z,1753296645.434 [Default:CheckIn:E] Stopped 2025-07-23T18:50:45.434Z,1753296645.434 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:50:45.434Z,1753296645.434 [Default:CheckIn] Stopped 2025-07-23T18:50:45.434Z,1753296645.434 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:50:45.434Z,1753296645.434 [Default:CheckIn](INFO): Running loop #13 2025-07-23T18:50:45.434Z,1753296645.434 [Default:CheckIn] Running Loop=13 2025-07-23T18:50:45.434Z,1753296645.434 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:50:45.434Z,1753296645.434 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:50:47.445Z,1753296647.445 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185048.00,A,3648.17627,N,12147.27803,W,0.369,296.97,230725,,,A*71 2025-07-23T18:50:47.447Z,1753296647.447 [NAL9602](INFO): GPS fix at 20250723T185048: (36.802938, -121.787967) 2025-07-23T18:50:47.459Z,1753296647.459 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:50:47.459Z,1753296647.459 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:50:55.652Z,1753296655.652 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250723T174844/Courier0033.lzma 2025-07-23T18:50:56.654Z,1753296656.654 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0033.lzma.bak 2025-07-23T18:50:56.655Z,1753296656.655 [DataOverHttps](INFO): SBD MOMSN=25578013 2025-07-23T18:51:12.665Z,1753296672.665 [DataOverHttps](INFO): Sending 282 bytes from file Logs/20250723T174844/Express0034.lzma 2025-07-23T18:51:12.909Z,1753296672.909 [NAL9602](INFO): SBD MO Status=0, MOMSN=64061, MT Status=0, MTMSN=0 2025-07-23T18:51:12.909Z,1753296672.909 [NAL9602](INFO): No messages in MT queue 2025-07-23T18:51:13.667Z,1753296673.667 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0034.lzma.bak 2025-07-23T18:51:13.667Z,1753296673.667 [DataOverHttps](INFO): SBD MOMSN=25578016 2025-07-23T18:51:14.948Z,1753296674.948 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:51:14.948Z,1753296674.948 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:51:14.948Z,1753296674.948 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:51:43.620Z,1753296703.620 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T18:54:58.035Z,1753296898.035 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-07-23T18:56:15.608Z,1753296975.608 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T18:56:15.608Z,1753296975.608 [Default:CheckIn:C.Wait] Stopped 2025-07-23T18:56:15.608Z,1753296975.608 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T18:56:15.609Z,1753296975.609 [Default:CheckIn:D] Running Loop=1 2025-07-23T18:56:16.018Z,1753296976.018 [Default:CheckIn:D] Stopped 2025-07-23T18:56:16.018Z,1753296976.018 [Default:CheckIn:E] Running Loop=1 2025-07-23T18:56:16.446Z,1753296976.446 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.508000 min 2025-07-23T18:56:16.446Z,1753296976.446 [Default:CheckIn:E] Stopped 2025-07-23T18:56:16.447Z,1753296976.447 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T18:56:16.447Z,1753296976.447 [Default:CheckIn] Stopped 2025-07-23T18:56:16.447Z,1753296976.447 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T18:56:16.447Z,1753296976.447 [Default:CheckIn](INFO): Running loop #14 2025-07-23T18:56:16.447Z,1753296976.447 [Default:CheckIn] Running Loop=14 2025-07-23T18:56:16.447Z,1753296976.447 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T18:56:16.447Z,1753296976.447 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T18:56:18.431Z,1753296978.431 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185619.00,A,3648.17575,N,12147.27922,W,0.078,296.97,230725,,,D*73 2025-07-23T18:56:18.434Z,1753296978.434 [NAL9602](INFO): GPS fix at 20250723T185619: (36.802929, -121.787987) 2025-07-23T18:56:18.490Z,1753296978.490 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T18:56:18.490Z,1753296978.490 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T18:56:27.845Z,1753296987.845 [NAL9602](INFO): SBD MO Status=0, MOMSN=64062, MT Status=0, MTMSN=0 2025-07-23T18:56:27.845Z,1753296987.845 [NAL9602](INFO): No messages in MT queue 2025-07-23T18:56:29.305Z,1753296989.305 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250723T174844/Courier0036.lzma 2025-07-23T18:56:30.307Z,1753296990.307 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0036.lzma.bak 2025-07-23T18:56:30.307Z,1753296990.307 [DataOverHttps](INFO): SBD MOMSN=25578054 2025-07-23T18:56:46.533Z,1753297006.533 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250723T174844/Express0037.lzma 2025-07-23T18:56:47.534Z,1753297007.534 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0037.lzma.bak 2025-07-23T18:56:47.535Z,1753297007.535 [DataOverHttps](INFO): SBD MOMSN=25578067 2025-07-23T18:56:48.876Z,1753297008.876 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T18:56:48.876Z,1753297008.876 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T18:56:48.897Z,1753297008.897 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T18:56:58.531Z,1753297018.531 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T18:58:56.905Z,1753297136.905 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:59:08.216Z,1753297148.216 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:59:19.529Z,1753297159.529 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:59:30.841Z,1753297170.841 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:59:42.154Z,1753297182.154 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T18:59:53.869Z,1753297193.869 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:00:05.200Z,1753297205.200 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:00:17.301Z,1753297217.301 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:01:49.424Z,1753297309.424 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T19:01:49.425Z,1753297309.425 [Default:CheckIn:C.Wait] Stopped 2025-07-23T19:01:49.425Z,1753297309.425 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T19:01:49.425Z,1753297309.425 [Default:CheckIn:D] Running Loop=1 2025-07-23T19:01:49.836Z,1753297309.836 [Default:CheckIn:D] Stopped 2025-07-23T19:01:49.836Z,1753297309.836 [Default:CheckIn:E] Running Loop=1 2025-07-23T19:01:50.249Z,1753297310.249 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.071639 min 2025-07-23T19:01:50.249Z,1753297310.249 [Default:CheckIn:E] Stopped 2025-07-23T19:01:50.249Z,1753297310.249 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T19:01:50.249Z,1753297310.249 [Default:CheckIn] Stopped 2025-07-23T19:01:50.249Z,1753297310.249 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T19:01:50.250Z,1753297310.250 [Default:CheckIn](INFO): Running loop #15 2025-07-23T19:01:50.250Z,1753297310.250 [Default:CheckIn] Running Loop=15 2025-07-23T19:01:50.250Z,1753297310.250 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T19:01:50.250Z,1753297310.250 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T19:01:52.251Z,1753297312.251 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190153.00,A,3648.17573,N,12147.27737,W,0.117,296.97,230725,,,D*7A 2025-07-23T19:01:52.254Z,1753297312.254 [NAL9602](INFO): GPS fix at 20250723T190153: (36.802929, -121.787956) 2025-07-23T19:01:52.289Z,1753297312.289 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T19:01:52.289Z,1753297312.289 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T19:01:59.672Z,1753297319.672 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250723T174844/Courier0039.lzma 2025-07-23T19:02:00.675Z,1753297320.675 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0039.lzma.bak 2025-07-23T19:02:00.675Z,1753297320.675 [DataOverHttps](INFO): SBD MOMSN=25578138 2025-07-23T19:02:17.729Z,1753297337.729 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250723T174844/Express0040.lzma 2025-07-23T19:02:18.731Z,1753297338.731 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0040.lzma.bak 2025-07-23T19:02:18.731Z,1753297338.731 [DataOverHttps](INFO): SBD MOMSN=25578146 2025-07-23T19:02:20.163Z,1753297340.163 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T19:02:20.163Z,1753297340.163 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T19:02:20.164Z,1753297340.164 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T19:02:52.873Z,1753297372.873 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:03:06.205Z,1753297386.205 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:03:19.538Z,1753297399.538 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:03:33.273Z,1753297413.273 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:03:47.817Z,1753297427.817 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:04:01.555Z,1753297441.555 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:04:15.289Z,1753297455.289 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:04:29.439Z,1753297469.439 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:04:42.817Z,1753297482.817 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:04:56.959Z,1753297496.959 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:04:58.176Z,1753297498.176 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-07-23T19:05:20.416Z,1753297520.416 [NAL9602](INFO): SBD MO Status=0, MOMSN=64063, MT Status=0, MTMSN=0 2025-07-23T19:05:20.416Z,1753297520.416 [NAL9602](INFO): No messages in MT queue 2025-07-23T19:05:51.107Z,1753297551.107 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T19:07:20.801Z,1753297640.801 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T19:07:20.801Z,1753297640.801 [Default:CheckIn:C.Wait] Stopped 2025-07-23T19:07:20.801Z,1753297640.801 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T19:07:20.802Z,1753297640.802 [Default:CheckIn:D] Running Loop=1 2025-07-23T19:07:21.204Z,1753297641.204 [Default:CheckIn:D] Stopped 2025-07-23T19:07:21.204Z,1753297641.204 [Default:CheckIn:E] Running Loop=1 2025-07-23T19:07:21.660Z,1753297641.660 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.594442 min 2025-07-23T19:07:21.660Z,1753297641.660 [Default:CheckIn:E] Stopped 2025-07-23T19:07:21.660Z,1753297641.660 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T19:07:21.660Z,1753297641.660 [Default:CheckIn] Stopped 2025-07-23T19:07:21.661Z,1753297641.661 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T19:07:21.661Z,1753297641.661 [Default:CheckIn](INFO): Running loop #16 2025-07-23T19:07:21.661Z,1753297641.661 [Default:CheckIn] Running Loop=16 2025-07-23T19:07:21.661Z,1753297641.661 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T19:07:21.661Z,1753297641.661 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T19:07:23.620Z,1753297643.620 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190724.00,A,3648.17320,N,12147.28136,W,0.000,296.97,230725,,,D*73 2025-07-23T19:07:23.622Z,1753297643.622 [NAL9602](INFO): GPS fix at 20250723T190724: (36.802887, -121.788023) 2025-07-23T19:07:23.654Z,1753297643.654 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T19:07:23.654Z,1753297643.654 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T19:07:31.100Z,1753297651.100 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250723T174844/Courier0042.lzma 2025-07-23T19:07:32.103Z,1753297652.103 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0042.lzma.bak 2025-07-23T19:07:32.103Z,1753297652.103 [DataOverHttps](INFO): SBD MOMSN=25578171 2025-07-23T19:07:45.031Z,1753297665.031 [NAL9602](INFO): SBD MO Status=2, MOMSN=64064, MT Status=2, MTMSN=0 2025-07-23T19:07:45.031Z,1753297665.031 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T19:07:48.173Z,1753297668.173 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250723T174844/Express0043.lzma 2025-07-23T19:07:49.174Z,1753297669.174 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0043.lzma.bak 2025-07-23T19:07:49.175Z,1753297669.175 [DataOverHttps](INFO): SBD MOMSN=25578174 2025-07-23T19:07:50.695Z,1753297670.695 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T19:07:50.695Z,1753297670.695 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T19:07:50.695Z,1753297670.695 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T19:08:10.480Z,1753297690.480 [NAL9602](INFO): SBD MO Status=2, MOMSN=64064, MT Status=2, MTMSN=0 2025-07-23T19:08:10.480Z,1753297690.480 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T19:08:54.128Z,1753297734.128 [NAL9602](INFO): SBD MO Status=2, MOMSN=64064, MT Status=2, MTMSN=0 2025-07-23T19:08:54.128Z,1753297734.128 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T19:09:16.346Z,1753297756.346 [NAL9602](INFO): SBD MO Status=0, MOMSN=64064, MT Status=0, MTMSN=0 2025-07-23T19:09:16.346Z,1753297756.346 [NAL9602](INFO): No messages in MT queue 2025-07-23T19:09:47.051Z,1753297787.051 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T19:12:31.068Z,1753297951.068 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:12:45.211Z,1753297965.211 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:12:51.285Z,1753297971.285 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T19:12:51.285Z,1753297971.285 [Default:CheckIn:C.Wait] Stopped 2025-07-23T19:12:51.286Z,1753297971.286 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T19:12:51.286Z,1753297971.286 [Default:CheckIn:D] Running Loop=1 2025-07-23T19:12:51.686Z,1753297971.686 [Default:CheckIn:D] Stopped 2025-07-23T19:12:51.686Z,1753297971.686 [Default:CheckIn:E] Running Loop=1 2025-07-23T19:12:52.098Z,1753297972.098 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.102474 min 2025-07-23T19:12:52.098Z,1753297972.098 [Default:CheckIn:E] Stopped 2025-07-23T19:12:52.099Z,1753297972.099 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T19:12:52.099Z,1753297972.099 [Default:CheckIn] Stopped 2025-07-23T19:12:52.099Z,1753297972.099 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T19:12:52.099Z,1753297972.099 [Default:CheckIn](INFO): Running loop #17 2025-07-23T19:12:52.099Z,1753297972.099 [Default:CheckIn] Running Loop=17 2025-07-23T19:12:52.099Z,1753297972.099 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T19:12:52.099Z,1753297972.099 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T19:12:54.103Z,1753297974.103 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191255.00,A,3648.17462,N,12147.27861,W,0.058,296.87,230725,,,D*78 2025-07-23T19:12:54.106Z,1753297974.106 [NAL9602](INFO): GPS fix at 20250723T191255: (36.802910, -121.787977) 2025-07-23T19:12:54.133Z,1753297974.133 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T19:12:54.133Z,1753297974.133 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T19:12:58.946Z,1753297978.946 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:13:02.170Z,1753297982.170 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250723T174844/Courier0045.lzma 2025-07-23T19:13:03.170Z,1753297983.170 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0045.lzma.bak 2025-07-23T19:13:03.171Z,1753297983.171 [DataOverHttps](INFO): SBD MOMSN=25578191 2025-07-23T19:13:12.278Z,1753297992.278 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:13:19.421Z,1753297999.421 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250723T174844/Express0046.lzma 2025-07-23T19:13:20.423Z,1753298000.423 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0046.lzma.bak 2025-07-23T19:13:20.423Z,1753298000.423 [DataOverHttps](INFO): SBD MOMSN=25578196 2025-07-23T19:13:21.616Z,1753298001.616 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T19:13:21.616Z,1753298001.616 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T19:13:21.616Z,1753298001.616 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T19:13:25.608Z,1753298005.608 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:13:38.940Z,1753298018.940 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:13:52.272Z,1753298032.272 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:14:06.010Z,1753298046.010 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:14:19.341Z,1753298059.341 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:14:32.673Z,1753298072.673 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:14:46.005Z,1753298086.005 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:14:58.536Z,1753298098.536 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-07-23T19:15:24.851Z,1753298124.851 [NAL9602](INFO): SBD MO Status=2, MOMSN=64065, MT Status=2, MTMSN=0 2025-07-23T19:15:24.851Z,1753298124.851 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T19:15:42.571Z,1753298142.571 [NAL9602](INFO): SBD MO Status=2, MOMSN=64065, MT Status=2, MTMSN=0 2025-07-23T19:15:42.571Z,1753298142.571 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T19:15:57.920Z,1753298157.920 [NAL9602](INFO): SBD MO Status=2, MOMSN=64065, MT Status=2, MTMSN=0 2025-07-23T19:15:57.920Z,1753298157.920 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-23T19:16:12.463Z,1753298172.463 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-07-23T19:16:12.463Z,1753298172.463 [DropWeight] Hardware Fault, FailCount= 1 2025-07-23T19:16:12.463Z,1753298172.463 [DropWeight](ERROR): Hardware Fault 2025-07-23T19:16:12.483Z,1753298172.483 [CommandExec](FAULT): Scheduling is paused for 3 commands 2025-07-23T19:16:12.484Z,1753298172.484 [CBIT](INFO): Critical error at 20250723T191612 2025-07-23T19:16:12.486Z,1753298172.486 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-07-23T19:16:12.487Z,1753298172.487 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-07-23T19:16:12.948Z,1753298172.948 [CBIT](INFO): Critical error at 20250723T191612 2025-07-23T19:17:56.300Z,1753298276.300 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T19:18:22.176Z,1753298302.176 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T19:18:22.176Z,1753298302.176 [Default:CheckIn:C.Wait] Stopped 2025-07-23T19:18:22.176Z,1753298302.176 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T19:18:22.185Z,1753298302.185 [Default:CheckIn:D] Running Loop=1 2025-07-23T19:18:22.601Z,1753298302.601 [Default:CheckIn:D] Stopped 2025-07-23T19:18:22.601Z,1753298302.601 [Default:CheckIn:E] Running Loop=1 2025-07-23T19:18:23.096Z,1753298303.096 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.617725 min 2025-07-23T19:18:23.096Z,1753298303.096 [Default:CheckIn:E] Stopped 2025-07-23T19:18:23.096Z,1753298303.096 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T19:18:23.096Z,1753298303.096 [Default:CheckIn] Stopped 2025-07-23T19:18:23.096Z,1753298303.096 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T19:18:23.133Z,1753298303.133 [Default:CheckIn](INFO): Running loop #18 2025-07-23T19:18:23.133Z,1753298303.133 [Default:CheckIn] Running Loop=18 2025-07-23T19:18:23.133Z,1753298303.133 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T19:18:23.133Z,1753298303.133 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T19:18:24.984Z,1753298304.984 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191826.00,A,3648.17432,N,12147.27804,W,0.097,296.87,230725,,,D*73 2025-07-23T19:18:25.002Z,1753298305.002 [NAL9602](INFO): GPS fix at 20250723T191826: (36.802905, -121.787967) 2025-07-23T19:18:25.013Z,1753298305.013 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T19:18:25.013Z,1753298305.013 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T19:18:33.149Z,1753298313.149 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20250723T174844/Courier0048.lzma 2025-07-23T19:18:34.150Z,1753298314.150 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0048.lzma.bak 2025-07-23T19:18:34.151Z,1753298314.151 [DataOverHttps](INFO): SBD MOMSN=25578215 2025-07-23T19:18:50.149Z,1753298330.149 [DataOverHttps](INFO): Sending 289 bytes from file Logs/20250723T174844/Express0049.lzma 2025-07-23T19:18:51.151Z,1753298331.151 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0049.lzma.bak 2025-07-23T19:18:51.151Z,1753298331.151 [DataOverHttps](INFO): SBD MOMSN=25578222 2025-07-23T19:18:52.469Z,1753298332.469 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T19:18:52.469Z,1753298332.469 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T19:18:52.470Z,1753298332.470 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T19:18:57.697Z,1753298337.697 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T19:22:02.733Z,1753298522.733 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:22:16.066Z,1753298536.066 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:22:29.800Z,1753298549.800 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:22:43.537Z,1753298563.537 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:22:56.869Z,1753298576.869 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:23:10.215Z,1753298590.215 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:23:23.534Z,1753298603.534 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:23:36.865Z,1753298616.865 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:23:50.600Z,1753298630.600 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:23:53.046Z,1753298633.046 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T19:23:53.046Z,1753298633.046 [Default:CheckIn:C.Wait] Stopped 2025-07-23T19:23:53.046Z,1753298633.046 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T19:23:53.046Z,1753298633.046 [Default:CheckIn:D] Running Loop=1 2025-07-23T19:23:53.456Z,1753298633.456 [Default:CheckIn:D] Stopped 2025-07-23T19:23:53.456Z,1753298633.456 [Default:CheckIn:E] Running Loop=1 2025-07-23T19:23:53.847Z,1753298633.847 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.131966 min 2025-07-23T19:23:53.847Z,1753298633.847 [Default:CheckIn:E] Stopped 2025-07-23T19:23:53.847Z,1753298633.847 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T19:23:53.847Z,1753298633.847 [Default:CheckIn] Stopped 2025-07-23T19:23:53.847Z,1753298633.847 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T19:23:53.848Z,1753298633.848 [Default:CheckIn](INFO): Running loop #19 2025-07-23T19:23:53.848Z,1753298633.848 [Default:CheckIn] Running Loop=19 2025-07-23T19:23:53.848Z,1753298633.848 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T19:23:53.848Z,1753298633.848 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T19:23:55.858Z,1753298635.858 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192357.00,A,3648.16495,N,12147.27971,W,0.253,203.02,230725,,,A*7C 2025-07-23T19:23:55.860Z,1753298635.860 [NAL9602](INFO): GPS fix at 20250723T192357: (36.802749, -121.787995) 2025-07-23T19:23:55.871Z,1753298635.871 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T19:23:55.871Z,1753298635.871 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T19:24:03.444Z,1753298643.444 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250723T174844/Courier0051.lzma 2025-07-23T19:24:03.949Z,1753298643.949 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:24:04.446Z,1753298644.446 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0051.lzma.bak 2025-07-23T19:24:04.447Z,1753298644.447 [DataOverHttps](INFO): SBD MOMSN=25578248 2025-07-23T19:24:17.305Z,1753298657.305 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-07-23T19:24:23.686Z,1753298663.686 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250723T174844/Express0052.lzma 2025-07-23T19:24:24.691Z,1753298664.691 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0052.lzma.bak 2025-07-23T19:24:24.691Z,1753298664.691 [DataOverHttps](INFO): SBD MOMSN=25578255 2025-07-23T19:24:25.883Z,1753298665.883 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T19:24:25.883Z,1753298665.883 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T19:24:25.883Z,1753298665.883 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T19:24:26.650Z,1753298666.650 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-23T19:24:26.725Z,1753298666.725 [NAL9602](ERROR): received: +CSQ:0 OK065, 2, 0, 0, 0 OK 2025-07-23T19:24:58.548Z,1753298698.548 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-07-23T19:28:51.685Z,1753298931.685 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2025-07-23T19:28:51.689Z,1753298931.689 [BPC1](FAULT): Failed to receive data from 7 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 32, 33, 36, 37, 50, 51, 54. 2025-07-23T19:28:58.563Z,1753298938.563 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-23T19:29:26.440Z,1753298966.440 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-23T19:29:26.440Z,1753298966.440 [Default:CheckIn:C.Wait] Stopped 2025-07-23T19:29:26.441Z,1753298966.441 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T19:29:26.441Z,1753298966.441 [Default:CheckIn:D] Running Loop=1 2025-07-23T19:29:26.852Z,1753298966.852 [Default:CheckIn:D] Stopped 2025-07-23T19:29:26.852Z,1753298966.852 [Default:CheckIn:E] Running Loop=1 2025-07-23T19:29:27.258Z,1753298967.258 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 108.688566 min 2025-07-23T19:29:27.258Z,1753298967.258 [Default:CheckIn:E] Stopped 2025-07-23T19:29:27.259Z,1753298967.259 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-23T19:29:27.259Z,1753298967.259 [Default:CheckIn] Stopped 2025-07-23T19:29:27.259Z,1753298967.259 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T19:29:27.259Z,1753298967.259 [Default:CheckIn](INFO): Running loop #20 2025-07-23T19:29:27.259Z,1753298967.259 [Default:CheckIn] Running Loop=20 2025-07-23T19:29:27.259Z,1753298967.259 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-23T19:29:27.259Z,1753298967.259 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-23T19:29:32.091Z,1753298972.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192933.00,A,3648.14360,N,12147.30108,W,14.657,217.62,230725,,,A*4D 2025-07-23T19:29:32.094Z,1753298972.094 [NAL9602](INFO): GPS fix at 20250723T192933: (36.802393, -121.788351) 2025-07-23T19:29:32.105Z,1753298972.105 [Default:CheckIn:Read_GPS] Stopped 2025-07-23T19:29:32.105Z,1753298972.105 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-23T19:29:38.596Z,1753298978.596 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20250723T174844/Courier0054.lzma 2025-07-23T19:29:39.598Z,1753298979.598 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Courier0054.lzma.bak 2025-07-23T19:29:39.599Z,1753298979.599 [DataOverHttps](INFO): SBD MOMSN=25578272 2025-07-23T19:29:55.493Z,1753298995.493 [DataOverHttps](INFO): Sending 331 bytes from file Logs/20250723T174844/Express0055.lzma 2025-07-23T19:29:56.495Z,1753298996.495 [DataOverHttps](INFO): Moved sent file to Logs/20250723T174844/Express0055.lzma.bak 2025-07-23T19:29:56.495Z,1753298996.495 [DataOverHttps](INFO): SBD MOMSN=25578280 2025-07-23T19:29:57.960Z,1753298997.960 [Default:CheckIn:Read_Iridium] Stopped 2025-07-23T19:29:57.960Z,1753298997.960 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-23T19:29:57.961Z,1753298997.961 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-23T19:30:04.411Z,1753299004.411 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-23T19:31:12.813Z,1753299072.813 [CBIT](INFO): Clearing failed state for component DropWeight 2025-07-23T19:31:12.813Z,1753299072.813 [DropWeight] No Fault, FailCount= 1 2025-07-23T19:32:50.675Z,1753299170.675 [CommandExec](IMPORTANT): got command quit 2025-07-23T19:32:51.685Z,1753299171.685 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T19:32:51.685Z,1753299171.685 [CommandExec](INFO): Uninitializing the command executive. 2025-07-23T19:32:51.685Z,1753299171.685 [CommandExec](INFO): Uninitializing the command scheduler. 2025-07-23T19:32:51.686Z,1753299171.686 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:51.686Z,1753299171.686 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-07-23T19:32:51.686Z,1753299171.686 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-07-23T19:32:51.686Z,1753299171.686 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:51.687Z,1753299171.687 [NavChartDb](INFO): Join timeout helper Thread ID is 4869 2025-07-23T19:32:51.937Z,1753299171.937 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T19:32:51.937Z,1753299171.937 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:51.941Z,1753299171.941 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-07-23T19:32:51.941Z,1753299171.941 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:51.942Z,1753299171.942 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4870 2025-07-23T19:32:52.059Z,1753299172.059 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T19:32:52.059Z,1753299172.059 [WetLabsBB2FL](INFO): Powering down 2025-07-23T19:32:52.060Z,1753299172.060 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:52.077Z,1753299172.077 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-07-23T19:32:52.077Z,1753299172.077 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:52.077Z,1753299172.077 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4871 2025-07-23T19:32:52.417Z,1753299172.417 [CTD_Seabird](INFO): Powering down 2025-07-23T19:32:52.429Z,1753299172.429 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T19:32:52.430Z,1753299172.430 [CTD_Seabird](INFO): Powering down 2025-07-23T19:32:52.445Z,1753299172.445 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:52.449Z,1753299172.449 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-07-23T19:32:52.449Z,1753299172.449 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:52.449Z,1753299172.449 [Radio_Surface](INFO): Join timeout helper Thread ID is 4872 2025-07-23T19:32:52.717Z,1753299172.717 [Radio_Surface](INFO): Powering down 2025-07-23T19:32:52.718Z,1753299172.718 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T19:32:52.718Z,1753299172.718 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:52.721Z,1753299172.721 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-07-23T19:32:52.721Z,1753299172.721 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:52.721Z,1753299172.721 [Onboard](INFO): Join timeout helper Thread ID is 4873 2025-07-23T19:32:53.557Z,1753299173.557 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-07-23T19:32:55.657Z,1753299175.657 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T19:32:55.657Z,1753299175.657 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:55.674Z,1753299175.674 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-07-23T19:32:55.674Z,1753299175.674 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:55.675Z,1753299175.675 [DataOverHttps](INFO): Join timeout helper Thread ID is 4874 2025-07-23T19:32:56.569Z,1753299176.569 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T19:32:56.569Z,1753299176.569 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:56.577Z,1753299176.577 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-07-23T19:32:56.578Z,1753299176.578 [DAT ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:56.578Z,1753299176.578 [DAT](INFO): Join timeout helper Thread ID is 4875 2025-07-23T19:32:56.785Z,1753299176.785 [DAT](INFO): Powering down 2025-07-23T19:32:56.857Z,1753299176.857 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T19:32:56.857Z,1753299176.857 [DAT](INFO): Powering down 2025-07-23T19:32:56.858Z,1753299176.858 [DAT ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:56.861Z,1753299176.861 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-07-23T19:32:56.861Z,1753299176.861 [logger ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:56.862Z,1753299176.862 [logger](INFO): Join timeout helper Thread ID is 4876 2025-07-23T19:32:56.865Z,1753299176.865 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T19:32:56.865Z,1753299176.865 [logger ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:56.881Z,1753299176.881 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-07-23T19:32:56.881Z,1753299176.881 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:56.882Z,1753299176.882 [CommandLine](INFO): Join timeout helper Thread ID is 4877 2025-07-23T19:32:56.960Z,1753299176.960 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T19:32:56.960Z,1753299176.960 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:56.961Z,1753299176.961 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-07-23T19:32:56.962Z,1753299176.962 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:56.962Z,1753299176.962 [CommandExec](INFO): Join timeout helper Thread ID is 4878 2025-07-23T19:32:56.975Z,1753299176.975 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-07-23T19:32:56.975Z,1753299176.975 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:56.975Z,1753299176.975 [controlThread](INFO): Join timeout helper Thread ID is 4879 2025-07-23T19:32:56.988Z,1753299176.988 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T19:32:56.988Z,1753299176.988 [controlThread](DEBUG): Uninitializing ControlThread 2025-07-23T19:32:56.988Z,1753299176.988 [AHRS_M2](INFO): Powering down 2025-07-23T19:32:57.069Z,1753299177.069 [NAL9602](INFO): Powering down 2025-07-23T19:32:57.070Z,1753299177.070 [Sonardyne_Nano](INFO): Powering down 2025-07-23T19:32:57.273Z,1753299177.273 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-07-23T19:32:57.274Z,1753299177.274 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-07-23T19:32:57.274Z,1753299177.274 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-07-23T19:32:57.275Z,1753299177.275 [MissionManager](INFO): Uninitializing Mission Default 2025-07-23T19:32:57.275Z,1753299177.275 [Default] Stopped 2025-07-23T19:32:57.275Z,1753299177.275 [Default](DEBUG): Aggregate::uninitialize Default 2025-07-23T19:32:57.275Z,1753299177.275 [Default:B.GoToSurface] Stopped 2025-07-23T19:32:57.275Z,1753299177.275 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-07-23T19:32:57.275Z,1753299177.275 [Default:CheckIn] Stopped 2025-07-23T19:32:57.275Z,1753299177.275 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-23T19:32:57.275Z,1753299177.275 [Default:CheckIn:C.Wait] Stopped 2025-07-23T19:32:57.275Z,1753299177.275 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-23T19:32:57.278Z,1753299177.278 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-07-23T19:32:57.279Z,1753299177.279 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-07-23T19:32:57.279Z,1753299177.279 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-07-23T19:32:57.279Z,1753299177.279 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-07-23T19:32:57.280Z,1753299177.280 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-07-23T19:32:57.280Z,1753299177.280 [BuoyancyServo](INFO): Powering down 2025-07-23T19:32:57.293Z,1753299177.293 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-07-23T19:32:57.293Z,1753299177.293 [ElevatorServo](INFO): Powering down 2025-07-23T19:32:57.294Z,1753299177.294 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-07-23T19:32:57.294Z,1753299177.294 [MassServo](INFO): Powering down 2025-07-23T19:32:57.295Z,1753299177.295 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-07-23T19:32:57.295Z,1753299177.295 [RudderServo](INFO): Powering down 2025-07-23T19:32:57.295Z,1753299177.295 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-07-23T19:32:57.296Z,1753299177.296 [ThrusterHE](INFO): Powering down 2025-07-23T19:32:57.297Z,1753299177.297 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-07-23T19:32:57.297Z,1753299177.297 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-07-23T19:32:57.297Z,1753299177.297 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-07-23T19:32:57.297Z,1753299177.297 [CBIT](DEBUG): Powering off loads. 2025-07-23T19:32:57.308Z,1753299177.308 [CBIT](DEBUG): Disabling WDT. 2025-07-23T19:32:57.321Z,1753299177.321 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-07-23T19:32:57.321Z,1753299177.321 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-07-23T19:32:57.322Z,1753299177.322 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:57.377Z,1753299177.377 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:57.380Z,1753299177.380 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:57.386Z,1753299177.386 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:57.394Z,1753299177.394 [DAT ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:57.467Z,1753299177.467 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:57.471Z,1753299177.471 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:57.495Z,1753299177.495 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-07-23T19:32:57.495Z,1753299177.495 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-07-23T19:32:57.515Z,1753299177.515 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-07-23T19:32:57.626Z,1753299177.626 [logger ThreadHandler](INFO): Thread cancelled.