2025-07-10T18:19:45.300Z,1752171585.300 [DataOverHttps](INFO): Received command: restart logs 2025-07-10T18:19:45.306Z,1752171585.306 [CommandExec](IMPORTANT): got command restart logs 2025-07-10T18:19:47.194Z,1752171587.194 [Default:A.Wait](INFO): Done Waiting. 2025-07-10T18:19:47.194Z,1752171587.194 [Default:A.Wait] Stopped 2025-07-10T18:19:47.194Z,1752171587.194 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T18:19:47.610Z,1752171587.610 [Default:CheckIn] Running Loop=1 2025-07-10T18:19:47.610Z,1752171587.610 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T18:19:47.610Z,1752171587.610 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T18:19:49.621Z,1752171589.621 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181948.00,A,3648.10397,N,12150.60852,W,28.361,74.48,100725,,,D*7F 2025-07-10T18:19:49.623Z,1752171589.623 [NAL9602](INFO): GPS fix at 20250710T181948: (36.801733, -121.843475) 2025-07-10T18:19:49.650Z,1752171589.650 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T18:19:49.650Z,1752171589.650 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T18:19:50.723Z,1752171590.723 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T18:19:52.685Z,1752171592.685 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec). 2025-07-10T18:19:52.686Z,1752171592.686 [BackseatComponent](INFO): Unsubscribing from LCM channels. 2025-07-10T18:19:52.686Z,1752171592.686 [LcmUniversalReporter](INFO): Deactivating messaging. 2025-07-10T18:19:52.709Z,1752171592.709 [Depth_Keller](INFO): Deactivating messaging. 2025-07-10T18:19:53.922Z,1752171593.922 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T18:20:03.754Z,1752171603.754 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250710T080740/Courier0094.lzma 2025-07-10T18:20:05.656Z,1752171605.656 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T18:20:09.273Z,1752171609.273 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T18:20:14.795Z,1752171614.795 [DataOverHttps](INFO): Moved sent file to Logs/20250710T080740/Courier0094.lzma.bak 2025-07-10T18:20:14.795Z,1752171614.795 [DataOverHttps](INFO): SBD MOMSN=25473666 2025-07-10T18:20:20.585Z,1752171620.585 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T18:20:24.625Z,1752171624.625 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T18:20:35.533Z,1752171635.533 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T18:20:40.785Z,1752171640.785 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T18:20:41.180Z,1752171641.180 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250710T080740/Courier0097.lzma 2025-07-10T18:20:51.291Z,1752171651.291 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T18:20:56.946Z,1752171656.946 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T18:21:00.255Z,1752171660.255 [DataOverHttps](INFO): Moved sent file to Logs/20250710T080740/Courier0097.lzma.bak 2025-07-10T18:21:00.255Z,1752171660.255 [DataOverHttps](INFO): SBD MOMSN=25473679 2025-07-10T18:21:06.237Z,1752171666.237 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T18:21:10.767Z,1752171670.767 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=9731, MT Status=1, MTMSN=1523 2025-07-10T18:21:10.767Z,1752171670.767 [NAL9602](INFO): Data available in MT queue 2025-07-10T18:21:11.094Z,1752171671.094 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 5. 2025-07-10T18:21:11.098Z,1752171671.098 [BPC1](INFO): Received data from all battery sticks. 2025-07-10T18:21:11.189Z,1752171671.189 [NAL9602](INFO): Received command: stop 2025-07-10T18:21:11.246Z,1752171671.246 [CommandExec](IMPORTANT): got command stop 2025-07-10T18:21:38.937Z,1752171698.937 [DataOverHttps](INFO): Sending 337 bytes from file Logs/20250710T080740/Express0095.lzma 2025-07-10T18:21:41.792Z,1752171701.792 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T18:21:52.991Z,1752171712.991 [DataOverHttps](INFO): Moved sent file to Logs/20250710T080740/Express0095.lzma.bak 2025-07-10T18:21:52.991Z,1752171712.991 [DataOverHttps](INFO): SBD MOMSN=25473687 2025-07-10T18:22:29.817Z,1752171749.817 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20250710T080740/Express0098.lzma 2025-07-10T18:22:43.871Z,1752171763.871 [DataOverHttps](INFO): Moved sent file to Logs/20250710T080740/Express0098.lzma.bak 2025-07-10T18:22:43.871Z,1752171763.871 [DataOverHttps](INFO): SBD MOMSN=25473691 2025-07-10T18:22:45.247Z,1752171765.247 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T18:22:45.248Z,1752171765.248 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T18:22:45.248Z,1752171765.248 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T18:26:12.689Z,1752171972.689 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-10T18:26:24.737Z,1752171984.737 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.247417 2025-07-10T18:27:22.969Z,1752172042.969 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-10T18:27:45.827Z,1752172065.827 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T18:27:45.827Z,1752172065.827 [Default:CheckIn:C.Wait] Stopped 2025-07-10T18:27:45.827Z,1752172065.827 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T18:27:45.828Z,1752172065.828 [Default:CheckIn:D] Running Loop=1 2025-07-10T18:27:46.241Z,1752172066.241 [Default:CheckIn:D] Stopped 2025-07-10T18:27:46.241Z,1752172066.241 [Default:CheckIn:E] Running Loop=1 2025-07-10T18:27:46.634Z,1752172066.634 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.205650 min 2025-07-10T18:27:46.634Z,1752172066.634 [Default:CheckIn:E] Stopped 2025-07-10T18:27:46.634Z,1752172066.634 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T18:27:46.634Z,1752172066.634 [Default:CheckIn] Stopped 2025-07-10T18:27:46.634Z,1752172066.634 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T18:27:46.635Z,1752172066.635 [Default:CheckIn](INFO): Running loop #2 2025-07-10T18:27:46.635Z,1752172066.635 [Default:CheckIn] Running Loop=2 2025-07-10T18:27:46.635Z,1752172066.635 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T18:27:46.635Z,1752172066.635 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T18:27:48.646Z,1752172068.646 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182747.00,A,3648.44795,N,12149.33027,W,5.987,266.39,100725,,,D*74 2025-07-10T18:27:48.649Z,1752172068.649 [NAL9602](INFO): GPS fix at 20250710T182747: (36.807466, -121.822171) 2025-07-10T18:27:48.659Z,1752172068.659 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T18:27:48.659Z,1752172068.659 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T18:27:54.393Z,1752172074.393 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002989 2025-07-10T18:28:02.801Z,1752172082.801 [DataOverHttps](INFO): Sending 312 bytes from file Logs/20250710T181945/Courier0005.lzma 2025-07-10T18:28:13.843Z,1752172093.843 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0005.lzma.bak 2025-07-10T18:28:13.843Z,1752172093.843 [DataOverHttps](INFO): SBD MOMSN=25473858 2025-07-10T18:28:17.787Z,1752172097.787 [NAL9602](INFO): SBD MO Status=2, MOMSN=9732, MT Status=2, MTMSN=0 2025-07-10T18:28:17.787Z,1752172097.787 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T18:28:47.846Z,1752172127.846 [DataOverHttps](INFO): Sending 613 bytes from file Logs/20250710T181945/Express0004.lzma 2025-07-10T18:28:48.490Z,1752172128.490 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T18:29:11.933Z,1752172151.933 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-07-10T18:29:22.390Z,1752172162.390 [DataOverHttps](INFO): Sending 613 bytes from file Logs/20250710T181945/Express0004.lzma 2025-07-10T18:29:33.431Z,1752172173.431 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0004.lzma.bak 2025-07-10T18:29:33.431Z,1752172173.431 [DataOverHttps](INFO): SBD MOMSN=25473862 2025-07-10T18:29:34.569Z,1752172174.569 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T18:29:34.569Z,1752172174.569 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T18:29:34.569Z,1752172174.569 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T18:30:04.901Z,1752172204.901 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-07-10T18:30:34.017Z,1752172234.017 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.587147 2025-07-10T18:31:15.193Z,1752172275.193 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-10T18:31:45.753Z,1752172305.753 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003015 2025-07-10T18:34:35.145Z,1752172475.145 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T18:34:35.146Z,1752172475.146 [Default:CheckIn:C.Wait] Stopped 2025-07-10T18:34:35.146Z,1752172475.146 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T18:34:35.146Z,1752172475.146 [Default:CheckIn:D] Running Loop=1 2025-07-10T18:34:35.549Z,1752172475.549 [Default:CheckIn:D] Stopped 2025-07-10T18:34:35.549Z,1752172475.549 [Default:CheckIn:E] Running Loop=1 2025-07-10T18:34:35.961Z,1752172475.961 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.027452 min 2025-07-10T18:34:35.961Z,1752172475.961 [Default:CheckIn:E] Stopped 2025-07-10T18:34:35.961Z,1752172475.961 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T18:34:35.961Z,1752172475.961 [Default:CheckIn] Stopped 2025-07-10T18:34:35.962Z,1752172475.962 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T18:34:35.962Z,1752172475.962 [Default:CheckIn](INFO): Running loop #3 2025-07-10T18:34:35.962Z,1752172475.962 [Default:CheckIn] Running Loop=3 2025-07-10T18:34:35.962Z,1752172475.962 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T18:34:35.962Z,1752172475.962 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T18:34:37.965Z,1752172477.965 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183437.00,A,3648.42944,N,12149.13563,W,3.790,96.39,100725,,,D*41 2025-07-10T18:34:37.967Z,1752172477.967 [NAL9602](INFO): GPS fix at 20250710T183437: (36.807157, -121.818927) 2025-07-10T18:34:37.988Z,1752172477.988 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T18:34:37.988Z,1752172477.988 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T18:34:51.948Z,1752172491.948 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250710T181945/Courier0009.lzma 2025-07-10T18:35:01.988Z,1752172501.988 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.970348 2025-07-10T18:35:33.105Z,1752172533.105 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-07-10T18:36:04.642Z,1752172564.642 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003024 2025-07-10T18:36:14.084Z,1752172574.084 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250710T181945/Courier0009.lzma 2025-07-10T18:36:28.139Z,1752172588.139 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0009.lzma.bak 2025-07-10T18:36:28.139Z,1752172588.139 [DataOverHttps](INFO): SBD MOMSN=25473898 2025-07-10T18:36:40.882Z,1752172600.882 [NAL9602](IMPORTANT): SBD MO Status=1, MOMSN=9732, MT Status=1, MTMSN=1524 2025-07-10T18:36:40.882Z,1752172600.882 [NAL9602](INFO): Data available in MT queue 2025-07-10T18:36:41.373Z,1752172601.373 [NAL9602](INFO): Received command: stop 2025-07-10T18:36:41.414Z,1752172601.414 [CommandExec](IMPORTANT): got command stop 2025-07-10T18:37:00.625Z,1752172620.625 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-07-10T18:37:11.990Z,1752172631.990 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T18:37:28.143Z,1752172648.143 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.004845 2025-07-10T18:37:56.472Z,1752172676.472 [NAL9602](INFO): SBD MO Status=2, MOMSN=9733, MT Status=2, MTMSN=0 2025-07-10T18:37:56.472Z,1752172676.472 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T18:37:59.261Z,1752172679.261 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-10T18:38:34.401Z,1752172714.401 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-10T18:39:09.542Z,1752172749.542 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-10T18:39:44.697Z,1752172784.697 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-07-10T18:40:20.397Z,1752172820.397 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2025-07-10T18:40:42.881Z,1752172842.881 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003043 2025-07-10T18:40:52.297Z,1752172852.297 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250710T181945/Express0010.lzma 2025-07-10T18:41:02.792Z,1752172862.792 [NAL9602](INFO): SBD MO Status=2, MOMSN=9733, MT Status=2, MTMSN=0 2025-07-10T18:41:02.792Z,1752172862.792 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T18:41:03.339Z,1752172863.339 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0010.lzma.bak 2025-07-10T18:41:03.339Z,1752172863.339 [DataOverHttps](INFO): SBD MOMSN=25474153 2025-07-10T18:41:04.825Z,1752172864.825 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T18:41:04.826Z,1752172864.826 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T18:41:04.826Z,1752172864.826 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T18:41:33.486Z,1752172893.486 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T18:46:05.392Z,1752173165.392 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T18:46:05.392Z,1752173165.392 [Default:CheckIn:C.Wait] Stopped 2025-07-10T18:46:05.392Z,1752173165.392 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T18:46:05.393Z,1752173165.393 [Default:CheckIn:D] Running Loop=1 2025-07-10T18:46:05.786Z,1752173165.786 [Default:CheckIn:D] Stopped 2025-07-10T18:46:05.786Z,1752173165.786 [Default:CheckIn:E] Running Loop=1 2025-07-10T18:46:06.196Z,1752173166.196 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.531415 min 2025-07-10T18:46:06.196Z,1752173166.196 [Default:CheckIn:E] Stopped 2025-07-10T18:46:06.196Z,1752173166.196 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T18:46:06.197Z,1752173166.197 [Default:CheckIn] Stopped 2025-07-10T18:46:06.197Z,1752173166.197 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T18:46:06.197Z,1752173166.197 [Default:CheckIn](INFO): Running loop #4 2025-07-10T18:46:06.197Z,1752173166.197 [Default:CheckIn] Running Loop=4 2025-07-10T18:46:06.197Z,1752173166.197 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T18:46:06.197Z,1752173166.197 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T18:46:08.202Z,1752173168.202 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184607.00,A,3648.26872,N,12148.30236,W,4.043,106.83,100725,,,D*71 2025-07-10T18:46:08.204Z,1752173168.204 [NAL9602](INFO): GPS fix at 20250710T184607: (36.804479, -121.805039) 2025-07-10T18:46:08.215Z,1752173168.215 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T18:46:08.215Z,1752173168.215 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T18:46:15.344Z,1752173175.344 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250710T181945/Courier0012.lzma 2025-07-10T18:46:26.391Z,1752173186.391 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0012.lzma.bak 2025-07-10T18:46:26.391Z,1752173186.391 [DataOverHttps](INFO): SBD MOMSN=25474238 2025-07-10T18:46:53.369Z,1752173213.369 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20250710T181945/Express0013.lzma 2025-07-10T18:47:04.411Z,1752173224.411 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0013.lzma.bak 2025-07-10T18:47:04.411Z,1752173224.411 [DataOverHttps](INFO): SBD MOMSN=25474244 2025-07-10T18:47:05.584Z,1752173225.584 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T18:47:05.584Z,1752173225.584 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T18:47:05.584Z,1752173225.584 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T18:48:26.369Z,1752173306.369 [NAL9602](INFO): SBD MO Status=2, MOMSN=9733, MT Status=2, MTMSN=0 2025-07-10T18:48:26.369Z,1752173306.369 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T18:51:10.394Z,1752173470.394 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-10T18:51:41.094Z,1752173501.094 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T18:52:06.155Z,1752173526.155 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T18:52:06.155Z,1752173526.155 [Default:CheckIn:C.Wait] Stopped 2025-07-10T18:52:06.155Z,1752173526.155 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T18:52:06.156Z,1752173526.156 [Default:CheckIn:D] Running Loop=1 2025-07-10T18:52:06.556Z,1752173526.556 [Default:CheckIn:D] Stopped 2025-07-10T18:52:06.556Z,1752173526.556 [Default:CheckIn:E] Running Loop=1 2025-07-10T18:52:06.963Z,1752173526.963 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.544236 min 2025-07-10T18:52:06.963Z,1752173526.963 [Default:CheckIn:E] Stopped 2025-07-10T18:52:06.963Z,1752173526.963 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T18:52:06.963Z,1752173526.963 [Default:CheckIn] Stopped 2025-07-10T18:52:06.963Z,1752173526.963 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T18:52:06.963Z,1752173526.963 [Default:CheckIn](INFO): Running loop #5 2025-07-10T18:52:06.963Z,1752173526.963 [Default:CheckIn] Running Loop=5 2025-07-10T18:52:06.963Z,1752173526.963 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T18:52:06.963Z,1752173526.963 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T18:52:08.981Z,1752173528.981 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185208.00,A,3648.25522,N,12147.83616,W,3.985,101.78,100725,,,D*76 2025-07-10T18:52:08.983Z,1752173528.983 [NAL9602](INFO): GPS fix at 20250710T185208: (36.804254, -121.797269) 2025-07-10T18:52:08.994Z,1752173528.994 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T18:52:08.994Z,1752173528.994 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T18:52:16.552Z,1752173536.552 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250710T181945/Courier0015.lzma 2025-07-10T18:52:28.641Z,1752173548.641 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0015.lzma.bak 2025-07-10T18:52:28.641Z,1752173548.641 [DataOverHttps](INFO): SBD MOMSN=25474265 2025-07-10T18:52:39.676Z,1752173559.676 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-10T18:52:39.757Z,1752173559.757 [NAL9602](ERROR): received: +CSQ:0 OK33, 2, 0, 0, 0 OK 2025-07-10T18:52:54.565Z,1752173574.565 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20250710T181945/Express0016.lzma 2025-07-10T18:53:05.607Z,1752173585.607 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0016.lzma.bak 2025-07-10T18:53:05.607Z,1752173585.607 [DataOverHttps](INFO): SBD MOMSN=25474270 2025-07-10T18:53:06.762Z,1752173586.762 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T18:53:06.762Z,1752173586.762 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T18:53:06.762Z,1752173586.762 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T18:57:08.746Z,1752173828.746 [NAL9602](INFO): SBD MO Status=2, MOMSN=9733, MT Status=2, MTMSN=0 2025-07-10T18:57:08.746Z,1752173828.746 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T18:57:11.574Z,1752173831.574 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-10T18:57:42.284Z,1752173862.284 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T18:58:07.331Z,1752173887.331 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T18:58:07.332Z,1752173887.332 [Default:CheckIn:C.Wait] Stopped 2025-07-10T18:58:07.332Z,1752173887.332 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T18:58:07.332Z,1752173887.332 [Default:CheckIn:D] Running Loop=1 2025-07-10T18:58:07.737Z,1752173887.737 [Default:CheckIn:D] Stopped 2025-07-10T18:58:07.737Z,1752173887.737 [Default:CheckIn:E] Running Loop=1 2025-07-10T18:58:08.143Z,1752173888.143 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.563924 min 2025-07-10T18:58:08.143Z,1752173888.143 [Default:CheckIn:E] Stopped 2025-07-10T18:58:08.143Z,1752173888.143 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T18:58:08.143Z,1752173888.143 [Default:CheckIn] Stopped 2025-07-10T18:58:08.144Z,1752173888.144 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T18:58:08.144Z,1752173888.144 [Default:CheckIn](INFO): Running loop #6 2025-07-10T18:58:08.144Z,1752173888.144 [Default:CheckIn] Running Loop=6 2025-07-10T18:58:08.144Z,1752173888.144 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T18:58:08.144Z,1752173888.144 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T18:58:10.164Z,1752173890.164 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185809.00,A,3648.37737,N,12147.41026,W,4.140,55.21,100725,,,D*49 2025-07-10T18:58:10.167Z,1752173890.167 [NAL9602](INFO): GPS fix at 20250710T185809: (36.806289, -121.790171) 2025-07-10T18:58:10.177Z,1752173890.177 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T18:58:10.177Z,1752173890.177 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T18:58:17.956Z,1752173897.956 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250710T181945/Courier0018.lzma 2025-07-10T18:58:28.999Z,1752173908.999 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0018.lzma.bak 2025-07-10T18:58:28.999Z,1752173908.999 [DataOverHttps](INFO): SBD MOMSN=25474339 2025-07-10T18:58:40.855Z,1752173920.855 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-10T18:58:40.933Z,1752173920.933 [NAL9602](ERROR): received: +SBDI: 2, 9733, 2, 0, 0, 0 OK 2025-07-10T18:58:55.025Z,1752173935.025 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20250710T181945/Express0019.lzma 2025-07-10T18:59:06.067Z,1752173946.067 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0019.lzma.bak 2025-07-10T18:59:06.067Z,1752173946.067 [DataOverHttps](INFO): SBD MOMSN=25474342 2025-07-10T18:59:07.551Z,1752173947.551 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T18:59:07.552Z,1752173947.552 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T18:59:07.552Z,1752173947.552 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T18:59:22.488Z,1752173962.488 [NAL9602](INFO): SBD MO Status=2, MOMSN=9733, MT Status=2, MTMSN=0 2025-07-10T18:59:22.488Z,1752173962.488 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T18:59:58.043Z,1752173998.043 [NAL9602](INFO): SBD MO Status=1, MOMSN=9733, MT Status=0, MTMSN=0 2025-07-10T18:59:58.043Z,1752173998.043 [NAL9602](INFO): No messages in MT queue 2025-07-10T19:00:28.738Z,1752174028.738 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T19:04:08.135Z,1752174248.135 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T19:04:08.135Z,1752174248.135 [Default:CheckIn:C.Wait] Stopped 2025-07-10T19:04:08.135Z,1752174248.135 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T19:04:08.136Z,1752174248.136 [Default:CheckIn:D] Running Loop=1 2025-07-10T19:04:08.531Z,1752174248.531 [Default:CheckIn:D] Stopped 2025-07-10T19:04:08.531Z,1752174248.531 [Default:CheckIn:E] Running Loop=1 2025-07-10T19:04:08.931Z,1752174248.931 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.577165 min 2025-07-10T19:04:08.931Z,1752174248.931 [Default:CheckIn:E] Stopped 2025-07-10T19:04:08.931Z,1752174248.931 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T19:04:08.931Z,1752174248.931 [Default:CheckIn] Stopped 2025-07-10T19:04:08.931Z,1752174248.931 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T19:04:08.931Z,1752174248.931 [Default:CheckIn](INFO): Running loop #7 2025-07-10T19:04:08.932Z,1752174248.932 [Default:CheckIn] Running Loop=7 2025-07-10T19:04:08.932Z,1752174248.932 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T19:04:08.932Z,1752174248.932 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T19:04:10.954Z,1752174250.954 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190410.00,A,3648.44065,N,12147.11969,W,3.732,192.83,100725,,,D*7C 2025-07-10T19:04:10.956Z,1752174250.956 [NAL9602](INFO): GPS fix at 20250710T190410: (36.807344, -121.785328) 2025-07-10T19:04:10.989Z,1752174250.989 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T19:04:10.989Z,1752174250.989 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T19:04:18.384Z,1752174258.384 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250710T181945/Courier0021.lzma 2025-07-10T19:04:29.427Z,1752174269.427 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0021.lzma.bak 2025-07-10T19:04:29.427Z,1752174269.427 [DataOverHttps](INFO): SBD MOMSN=25474364 2025-07-10T19:04:55.393Z,1752174295.393 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250710T181945/Express0022.lzma 2025-07-10T19:05:06.435Z,1752174306.435 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0022.lzma.bak 2025-07-10T19:05:06.435Z,1752174306.435 [DataOverHttps](INFO): SBD MOMSN=25474367 2025-07-10T19:05:07.912Z,1752174307.912 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T19:05:07.912Z,1752174307.912 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T19:05:07.915Z,1752174307.915 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T19:05:18.820Z,1752174318.820 [NAL9602](INFO): SBD MO Status=1, MOMSN=9734, MT Status=0, MTMSN=0 2025-07-10T19:05:18.820Z,1752174318.820 [NAL9602](INFO): No messages in MT queue 2025-07-10T19:05:49.516Z,1752174349.516 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T19:10:08.488Z,1752174608.488 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T19:10:08.489Z,1752174608.489 [Default:CheckIn:C.Wait] Stopped 2025-07-10T19:10:08.489Z,1752174608.489 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T19:10:08.489Z,1752174608.489 [Default:CheckIn:D] Running Loop=1 2025-07-10T19:10:08.892Z,1752174608.892 [Default:CheckIn:D] Stopped 2025-07-10T19:10:08.892Z,1752174608.892 [Default:CheckIn:E] Running Loop=1 2025-07-10T19:10:09.298Z,1752174609.298 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.583179 min 2025-07-10T19:10:09.299Z,1752174609.299 [Default:CheckIn:E] Stopped 2025-07-10T19:10:09.299Z,1752174609.299 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T19:10:09.299Z,1752174609.299 [Default:CheckIn] Stopped 2025-07-10T19:10:09.299Z,1752174609.299 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T19:10:09.299Z,1752174609.299 [Default:CheckIn](INFO): Running loop #8 2025-07-10T19:10:09.299Z,1752174609.299 [Default:CheckIn] Running Loop=8 2025-07-10T19:10:09.299Z,1752174609.299 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T19:10:09.299Z,1752174609.299 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T19:10:11.315Z,1752174611.315 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191010.00,A,3648.15637,N,12147.21638,W,1.166,229.45,100725,,,D*78 2025-07-10T19:10:11.318Z,1752174611.318 [NAL9602](INFO): GPS fix at 20250710T191010: (36.802606, -121.786940) 2025-07-10T19:10:11.328Z,1752174611.328 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T19:10:11.328Z,1752174611.328 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T19:10:18.752Z,1752174618.752 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250710T181945/Courier0024.lzma 2025-07-10T19:10:29.795Z,1752174629.795 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0024.lzma.bak 2025-07-10T19:10:29.795Z,1752174629.795 [DataOverHttps](INFO): SBD MOMSN=25474432 2025-07-10T19:10:55.697Z,1752174655.697 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250710T181945/Express0025.lzma 2025-07-10T19:11:06.739Z,1752174666.739 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0025.lzma.bak 2025-07-10T19:11:06.739Z,1752174666.739 [DataOverHttps](INFO): SBD MOMSN=25474435 2025-07-10T19:11:07.889Z,1752174667.889 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T19:11:07.889Z,1752174667.889 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T19:11:07.889Z,1752174667.889 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T19:11:15.144Z,1752174675.144 [NAL9602](INFO): SBD MO Status=2, MOMSN=9735, MT Status=2, MTMSN=0 2025-07-10T19:11:15.144Z,1752174675.144 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:11:41.056Z,1752174701.056 [NAL9602](INFO): SBD MO Status=2, MOMSN=9735, MT Status=2, MTMSN=0 2025-07-10T19:11:41.065Z,1752174701.065 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:13:13.125Z,1752174793.125 [NAL9602](INFO): SBD MO Status=2, MOMSN=9735, MT Status=2, MTMSN=0 2025-07-10T19:13:13.125Z,1752174793.125 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:13:40.995Z,1752174820.995 [NAL9602](INFO): SBD MO Status=2, MOMSN=9735, MT Status=2, MTMSN=0 2025-07-10T19:13:40.996Z,1752174820.996 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:15:19.971Z,1752174919.971 [NAL9602](INFO): SBD MO Status=1, MOMSN=9735, MT Status=0, MTMSN=0 2025-07-10T19:15:19.971Z,1752174919.971 [NAL9602](INFO): No messages in MT queue 2025-07-10T19:15:50.670Z,1752174950.670 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T19:16:08.496Z,1752174968.496 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T19:16:08.496Z,1752174968.496 [Default:CheckIn:C.Wait] Stopped 2025-07-10T19:16:08.496Z,1752174968.496 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T19:16:08.496Z,1752174968.496 [Default:CheckIn:D] Running Loop=1 2025-07-10T19:16:08.877Z,1752174968.877 [Default:CheckIn:D] Stopped 2025-07-10T19:16:08.877Z,1752174968.877 [Default:CheckIn:E] Running Loop=1 2025-07-10T19:16:09.281Z,1752174969.281 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.582918 min 2025-07-10T19:16:09.281Z,1752174969.281 [Default:CheckIn:E] Stopped 2025-07-10T19:16:09.281Z,1752174969.281 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T19:16:09.281Z,1752174969.281 [Default:CheckIn] Stopped 2025-07-10T19:16:09.281Z,1752174969.281 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T19:16:09.281Z,1752174969.281 [Default:CheckIn](INFO): Running loop #9 2025-07-10T19:16:09.281Z,1752174969.281 [Default:CheckIn] Running Loop=9 2025-07-10T19:16:09.282Z,1752174969.282 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T19:16:09.282Z,1752174969.282 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T19:16:11.295Z,1752174971.295 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191610.00,A,3648.14728,N,12147.22239,W,0.039,205.79,100725,,,D*7D 2025-07-10T19:16:11.297Z,1752174971.297 [NAL9602](INFO): GPS fix at 20250710T191610: (36.802455, -121.787040) 2025-07-10T19:16:11.307Z,1752174971.307 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T19:16:11.307Z,1752174971.307 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T19:16:18.208Z,1752174978.208 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250710T181945/Courier0027.lzma 2025-07-10T19:16:29.255Z,1752174989.255 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0027.lzma.bak 2025-07-10T19:16:29.255Z,1752174989.255 [DataOverHttps](INFO): SBD MOMSN=25474453 2025-07-10T19:16:55.261Z,1752175015.261 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250710T181945/Express0028.lzma 2025-07-10T19:17:06.303Z,1752175026.303 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0028.lzma.bak 2025-07-10T19:17:06.303Z,1752175026.303 [DataOverHttps](INFO): SBD MOMSN=25474463 2025-07-10T19:17:07.505Z,1752175027.505 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T19:17:07.505Z,1752175027.505 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T19:17:07.505Z,1752175027.505 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T19:18:59.785Z,1752175139.785 [NAL9602](INFO): SBD MO Status=2, MOMSN=9736, MT Status=2, MTMSN=0 2025-07-10T19:18:59.785Z,1752175139.785 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:19:58.760Z,1752175198.760 [NAL9602](INFO): SBD MO Status=2, MOMSN=9736, MT Status=2, MTMSN=0 2025-07-10T19:19:58.760Z,1752175198.760 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:20:20.174Z,1752175220.174 [NAL9602](INFO): SBD MO Status=2, MOMSN=9736, MT Status=2, MTMSN=0 2025-07-10T19:20:20.174Z,1752175220.174 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:20:31.484Z,1752175231.484 [NAL9602](INFO): SBD MO Status=1, MOMSN=9736, MT Status=0, MTMSN=0 2025-07-10T19:20:31.484Z,1752175231.484 [NAL9602](INFO): No messages in MT queue 2025-07-10T19:21:02.210Z,1752175262.210 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T19:22:08.051Z,1752175328.051 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T19:22:08.051Z,1752175328.051 [Default:CheckIn:C.Wait] Stopped 2025-07-10T19:22:08.051Z,1752175328.051 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T19:22:08.051Z,1752175328.051 [Default:CheckIn:D] Running Loop=1 2025-07-10T19:22:08.463Z,1752175328.463 [Default:CheckIn:D] Stopped 2025-07-10T19:22:08.463Z,1752175328.463 [Default:CheckIn:E] Running Loop=1 2025-07-10T19:22:08.863Z,1752175328.863 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.576029 min 2025-07-10T19:22:08.864Z,1752175328.864 [Default:CheckIn:E] Stopped 2025-07-10T19:22:08.864Z,1752175328.864 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T19:22:08.864Z,1752175328.864 [Default:CheckIn] Stopped 2025-07-10T19:22:08.864Z,1752175328.864 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T19:22:08.864Z,1752175328.864 [Default:CheckIn](INFO): Running loop #10 2025-07-10T19:22:08.864Z,1752175328.864 [Default:CheckIn] Running Loop=10 2025-07-10T19:22:08.864Z,1752175328.864 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T19:22:08.864Z,1752175328.864 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T19:22:10.872Z,1752175330.872 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192210.00,A,3648.15261,N,12147.21994,W,0.078,205.79,100725,,,D*79 2025-07-10T19:22:10.874Z,1752175330.874 [NAL9602](INFO): GPS fix at 20250710T192210: (36.802543, -121.786999) 2025-07-10T19:22:10.884Z,1752175330.884 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T19:22:10.885Z,1752175330.885 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T19:22:17.761Z,1752175337.761 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250710T181945/Courier0030.lzma 2025-07-10T19:22:28.803Z,1752175348.803 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0030.lzma.bak 2025-07-10T19:22:28.803Z,1752175348.803 [DataOverHttps](INFO): SBD MOMSN=25474530 2025-07-10T19:22:36.729Z,1752175356.729 [NAL9602](INFO): SBD MO Status=2, MOMSN=9737, MT Status=2, MTMSN=0 2025-07-10T19:22:36.730Z,1752175356.730 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:22:54.881Z,1752175374.881 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250710T181945/Express0031.lzma 2025-07-10T19:23:05.944Z,1752175385.944 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0031.lzma.bak 2025-07-10T19:23:05.944Z,1752175385.944 [DataOverHttps](INFO): SBD MOMSN=25474533 2025-07-10T19:23:07.047Z,1752175387.047 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T19:23:07.047Z,1752175387.047 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T19:23:07.048Z,1752175387.048 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T19:23:08.646Z,1752175388.646 [NAL9602](INFO): SBD MO Status=2, MOMSN=9737, MT Status=2, MTMSN=0 2025-07-10T19:23:08.646Z,1752175388.646 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:23:48.642Z,1752175428.642 [NAL9602](INFO): SBD MO Status=2, MOMSN=9737, MT Status=2, MTMSN=0 2025-07-10T19:23:48.642Z,1752175428.642 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:24:19.027Z,1752175459.027 [NAL9602](INFO): SBD MO Status=2, MOMSN=9737, MT Status=2, MTMSN=0 2025-07-10T19:24:19.028Z,1752175459.028 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:24:51.667Z,1752175491.667 [NAL9602](INFO): SBD MO Status=2, MOMSN=9737, MT Status=2, MTMSN=0 2025-07-10T19:24:51.667Z,1752175491.667 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:25:13.504Z,1752175513.504 [NAL9602](INFO): SBD MO Status=2, MOMSN=9737, MT Status=2, MTMSN=0 2025-07-10T19:25:13.504Z,1752175513.504 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:26:03.179Z,1752175563.179 [NAL9602](INFO): SBD MO Status=2, MOMSN=9737, MT Status=2, MTMSN=0 2025-07-10T19:26:03.180Z,1752175563.180 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:26:08.825Z,1752175568.825 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T19:26:08.831Z,1752175568.831 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T19:26:15.695Z,1752175575.695 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T19:26:16.501Z,1752175576.501 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T19:26:30.641Z,1752175590.641 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T19:26:31.448Z,1752175591.448 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T19:26:45.999Z,1752175605.999 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T19:26:46.802Z,1752175606.802 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T19:27:01.394Z,1752175621.394 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T19:27:01.749Z,1752175621.749 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T19:27:13.468Z,1752175633.468 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-10T19:27:16.699Z,1752175636.699 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T19:27:16.704Z,1752175636.704 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T19:27:32.049Z,1752175652.049 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T19:27:32.054Z,1752175652.054 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T19:27:44.178Z,1752175664.178 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T19:27:46.999Z,1752175666.999 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T19:27:47.414Z,1752175667.414 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T19:28:02.349Z,1752175682.349 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T19:28:02.765Z,1752175682.765 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T19:28:07.615Z,1752175687.615 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T19:28:07.615Z,1752175687.615 [Default:CheckIn:C.Wait] Stopped 2025-07-10T19:28:07.616Z,1752175687.616 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T19:28:07.616Z,1752175687.616 [Default:CheckIn:D] Running Loop=1 2025-07-10T19:28:08.018Z,1752175688.018 [Default:CheckIn:D] Stopped 2025-07-10T19:28:08.018Z,1752175688.018 [Default:CheckIn:E] Running Loop=1 2025-07-10T19:28:08.431Z,1752175688.431 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.568604 min 2025-07-10T19:28:08.431Z,1752175688.431 [Default:CheckIn:E] Stopped 2025-07-10T19:28:08.431Z,1752175688.431 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T19:28:08.431Z,1752175688.431 [Default:CheckIn] Stopped 2025-07-10T19:28:08.431Z,1752175688.431 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T19:28:08.431Z,1752175688.431 [Default:CheckIn](INFO): Running loop #11 2025-07-10T19:28:08.431Z,1752175688.431 [Default:CheckIn] Running Loop=11 2025-07-10T19:28:08.431Z,1752175688.431 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T19:28:08.432Z,1752175688.432 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T19:28:10.432Z,1752175690.432 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192809.00,A,3648.15503,N,12147.22437,W,0.058,317.72,100725,,,D*74 2025-07-10T19:28:10.435Z,1752175690.435 [NAL9602](INFO): GPS fix at 20250710T192809: (36.802584, -121.787073) 2025-07-10T19:28:10.448Z,1752175690.448 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T19:28:10.448Z,1752175690.448 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T19:28:17.702Z,1752175697.702 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-10T19:28:17.808Z,1752175697.808 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250710T181945/Courier0033.lzma 2025-07-10T19:28:18.117Z,1752175698.117 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-10T19:28:28.851Z,1752175708.851 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0033.lzma.bak 2025-07-10T19:28:28.852Z,1752175708.852 [DataOverHttps](INFO): SBD MOMSN=25474604 2025-07-10T19:28:31.848Z,1752175711.848 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 5. 2025-07-10T19:28:31.851Z,1752175711.851 [BPC1](INFO): Received data from all battery sticks. 2025-07-10T19:28:41.141Z,1752175721.141 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-10T19:28:41.221Z,1752175721.221 [NAL9602](ERROR): received: +CSQ:0 OK37, 2, 0, 0, 0 OK 2025-07-10T19:28:54.821Z,1752175734.821 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250710T181945/Express0034.lzma 2025-07-10T19:29:02.954Z,1752175742.954 [NAL9602](INFO): SBD MO Status=2, MOMSN=9737, MT Status=2, MTMSN=0 2025-07-10T19:29:02.954Z,1752175742.954 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:29:05.863Z,1752175745.863 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0034.lzma.bak 2025-07-10T19:29:05.863Z,1752175745.863 [DataOverHttps](INFO): SBD MOMSN=25474607 2025-07-10T19:29:07.017Z,1752175747.017 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T19:29:07.018Z,1752175747.018 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T19:29:07.018Z,1752175747.018 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T19:29:44.971Z,1752175784.971 [NAL9602](INFO): SBD MO Status=2, MOMSN=9737, MT Status=2, MTMSN=0 2025-07-10T19:29:44.972Z,1752175784.972 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:30:28.603Z,1752175828.603 [NAL9602](INFO): SBD MO Status=1, MOMSN=9737, MT Status=0, MTMSN=0 2025-07-10T19:30:28.604Z,1752175828.604 [NAL9602](INFO): No messages in MT queue 2025-07-10T19:30:59.302Z,1752175859.302 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T19:34:07.628Z,1752176047.628 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T19:34:07.628Z,1752176047.628 [Default:CheckIn:C.Wait] Stopped 2025-07-10T19:34:07.628Z,1752176047.628 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T19:34:07.628Z,1752176047.628 [Default:CheckIn:D] Running Loop=1 2025-07-10T19:34:08.032Z,1752176048.032 [Default:CheckIn:D] Stopped 2025-07-10T19:34:08.032Z,1752176048.032 [Default:CheckIn:E] Running Loop=1 2025-07-10T19:34:08.432Z,1752176048.432 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.568840 min 2025-07-10T19:34:08.432Z,1752176048.432 [Default:CheckIn:E] Stopped 2025-07-10T19:34:08.432Z,1752176048.432 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T19:34:08.432Z,1752176048.432 [Default:CheckIn] Stopped 2025-07-10T19:34:08.432Z,1752176048.432 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T19:34:08.432Z,1752176048.432 [Default:CheckIn](INFO): Running loop #12 2025-07-10T19:34:08.432Z,1752176048.432 [Default:CheckIn] Running Loop=12 2025-07-10T19:34:08.432Z,1752176048.432 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T19:34:08.432Z,1752176048.432 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T19:34:10.448Z,1752176050.448 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193409.00,A,3648.15645,N,12147.22576,W,0.019,268.64,100725,,,D*77 2025-07-10T19:34:10.450Z,1752176050.450 [NAL9602](INFO): GPS fix at 20250710T193409: (36.802608, -121.787096) 2025-07-10T19:34:10.460Z,1752176050.460 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T19:34:10.460Z,1752176050.460 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T19:34:17.456Z,1752176057.456 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250710T181945/Courier0036.lzma 2025-07-10T19:34:28.499Z,1752176068.499 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0036.lzma.bak 2025-07-10T19:34:28.499Z,1752176068.499 [DataOverHttps](INFO): SBD MOMSN=25474628 2025-07-10T19:34:54.515Z,1752176094.515 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250710T181945/Express0037.lzma 2025-07-10T19:35:05.555Z,1752176105.555 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0037.lzma.bak 2025-07-10T19:35:05.555Z,1752176105.555 [DataOverHttps](INFO): SBD MOMSN=25474631 2025-07-10T19:35:06.684Z,1752176106.684 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T19:35:06.684Z,1752176106.684 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T19:35:06.684Z,1752176106.684 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T19:35:15.960Z,1752176115.960 [NAL9602](INFO): SBD MO Status=2, MOMSN=9738, MT Status=2, MTMSN=0 2025-07-10T19:35:15.960Z,1752176115.960 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:35:43.844Z,1752176143.844 [NAL9602](INFO): SBD MO Status=1, MOMSN=9738, MT Status=0, MTMSN=0 2025-07-10T19:35:43.844Z,1752176143.844 [NAL9602](INFO): No messages in MT queue 2025-07-10T19:36:14.547Z,1752176174.547 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T19:40:07.245Z,1752176407.245 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T19:40:07.245Z,1752176407.245 [Default:CheckIn:C.Wait] Stopped 2025-07-10T19:40:07.245Z,1752176407.245 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T19:40:07.246Z,1752176407.246 [Default:CheckIn:D] Running Loop=1 2025-07-10T19:40:07.660Z,1752176407.660 [Default:CheckIn:D] Stopped 2025-07-10T19:40:07.660Z,1752176407.660 [Default:CheckIn:E] Running Loop=1 2025-07-10T19:40:08.052Z,1752176408.052 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.562646 min 2025-07-10T19:40:08.052Z,1752176408.052 [Default:CheckIn:E] Stopped 2025-07-10T19:40:08.052Z,1752176408.052 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T19:40:08.052Z,1752176408.052 [Default:CheckIn] Stopped 2025-07-10T19:40:08.053Z,1752176408.053 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T19:40:08.053Z,1752176408.053 [Default:CheckIn](INFO): Running loop #13 2025-07-10T19:40:08.053Z,1752176408.053 [Default:CheckIn] Running Loop=13 2025-07-10T19:40:08.053Z,1752176408.053 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T19:40:08.053Z,1752176408.053 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T19:40:10.064Z,1752176410.064 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194009.00,A,3648.15661,N,12147.22253,W,0.000,268.64,100725,,,D*7A 2025-07-10T19:40:10.067Z,1752176410.067 [NAL9602](INFO): GPS fix at 20250710T194009: (36.802610, -121.787042) 2025-07-10T19:40:10.077Z,1752176410.077 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T19:40:10.077Z,1752176410.077 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T19:40:16.968Z,1752176416.968 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250710T181945/Courier0039.lzma 2025-07-10T19:40:28.011Z,1752176428.011 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0039.lzma.bak 2025-07-10T19:40:28.011Z,1752176428.011 [DataOverHttps](INFO): SBD MOMSN=25474660 2025-07-10T19:40:29.926Z,1752176429.926 [NAL9602](INFO): SBD MO Status=1, MOMSN=9739, MT Status=0, MTMSN=0 2025-07-10T19:40:29.926Z,1752176429.926 [NAL9602](INFO): No messages in MT queue 2025-07-10T19:40:56.949Z,1752176456.949 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250710T181945/Express0040.lzma 2025-07-10T19:41:00.610Z,1752176460.610 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T19:41:07.991Z,1752176467.991 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0040.lzma.bak 2025-07-10T19:41:07.991Z,1752176467.991 [DataOverHttps](INFO): SBD MOMSN=25474671 2025-07-10T19:41:09.503Z,1752176469.503 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T19:41:09.504Z,1752176469.504 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T19:41:09.504Z,1752176469.504 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T19:46:10.082Z,1752176770.082 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T19:46:10.082Z,1752176770.082 [Default:CheckIn:C.Wait] Stopped 2025-07-10T19:46:10.082Z,1752176770.082 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T19:46:10.082Z,1752176770.082 [Default:CheckIn:D] Running Loop=1 2025-07-10T19:46:10.489Z,1752176770.489 [Default:CheckIn:D] Stopped 2025-07-10T19:46:10.489Z,1752176770.489 [Default:CheckIn:E] Running Loop=1 2025-07-10T19:46:10.905Z,1752176770.905 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.609790 min 2025-07-10T19:46:10.906Z,1752176770.906 [Default:CheckIn:E] Stopped 2025-07-10T19:46:10.906Z,1752176770.906 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T19:46:10.906Z,1752176770.906 [Default:CheckIn] Stopped 2025-07-10T19:46:10.906Z,1752176770.906 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T19:46:10.906Z,1752176770.906 [Default:CheckIn](INFO): Running loop #14 2025-07-10T19:46:10.906Z,1752176770.906 [Default:CheckIn] Running Loop=14 2025-07-10T19:46:10.906Z,1752176770.906 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T19:46:10.906Z,1752176770.906 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T19:46:12.904Z,1752176772.904 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194612.00,A,3648.15582,N,12147.22239,W,0.058,268.64,100725,,,D*79 2025-07-10T19:46:12.906Z,1752176772.906 [NAL9602](INFO): GPS fix at 20250710T194612: (36.802597, -121.787040) 2025-07-10T19:46:12.916Z,1752176772.916 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T19:46:12.916Z,1752176772.916 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T19:46:19.925Z,1752176779.925 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250710T181945/Courier0042.lzma 2025-07-10T19:46:30.967Z,1752176790.967 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0042.lzma.bak 2025-07-10T19:46:30.967Z,1752176790.967 [DataOverHttps](INFO): SBD MOMSN=25474795 2025-07-10T19:46:32.292Z,1752176792.292 [NAL9602](INFO): SBD MO Status=1, MOMSN=9740, MT Status=0, MTMSN=0 2025-07-10T19:46:32.293Z,1752176792.293 [NAL9602](INFO): No messages in MT queue 2025-07-10T19:46:56.989Z,1752176816.989 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250710T181945/Express0043.lzma 2025-07-10T19:47:02.992Z,1752176822.992 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T19:47:08.031Z,1752176828.031 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0043.lzma.bak 2025-07-10T19:47:08.031Z,1752176828.031 [DataOverHttps](INFO): SBD MOMSN=25474800 2025-07-10T19:47:09.487Z,1752176829.487 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T19:47:09.487Z,1752176829.487 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T19:47:09.487Z,1752176829.487 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T19:52:10.068Z,1752177130.068 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T19:52:10.068Z,1752177130.068 [Default:CheckIn:C.Wait] Stopped 2025-07-10T19:52:10.068Z,1752177130.068 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T19:52:10.068Z,1752177130.068 [Default:CheckIn:D] Running Loop=1 2025-07-10T19:52:10.480Z,1752177130.480 [Default:CheckIn:D] Stopped 2025-07-10T19:52:10.480Z,1752177130.480 [Default:CheckIn:E] Running Loop=1 2025-07-10T19:52:10.872Z,1752177130.872 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.609644 min 2025-07-10T19:52:10.872Z,1752177130.872 [Default:CheckIn:E] Stopped 2025-07-10T19:52:10.872Z,1752177130.872 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T19:52:10.872Z,1752177130.872 [Default:CheckIn] Stopped 2025-07-10T19:52:10.872Z,1752177130.872 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T19:52:10.873Z,1752177130.873 [Default:CheckIn](INFO): Running loop #15 2025-07-10T19:52:10.873Z,1752177130.873 [Default:CheckIn] Running Loop=15 2025-07-10T19:52:10.873Z,1752177130.873 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T19:52:10.873Z,1752177130.873 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T19:52:12.884Z,1752177132.884 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195212.00,A,3648.15575,N,12147.22312,W,0.019,268.64,100725,,,D*79 2025-07-10T19:52:12.887Z,1752177132.887 [NAL9602](INFO): GPS fix at 20250710T195212: (36.802596, -121.787052) 2025-07-10T19:52:12.897Z,1752177132.897 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T19:52:12.897Z,1752177132.897 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T19:52:20.468Z,1752177140.468 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250710T181945/Courier0045.lzma 2025-07-10T19:52:31.071Z,1752177151.071 [NAL9602](INFO): SBD MO Status=2, MOMSN=9741, MT Status=2, MTMSN=0 2025-07-10T19:52:31.072Z,1752177151.072 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:52:31.511Z,1752177151.511 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0045.lzma.bak 2025-07-10T19:52:31.511Z,1752177151.511 [DataOverHttps](INFO): SBD MOMSN=25474816 2025-07-10T19:52:57.457Z,1752177177.457 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250710T181945/Express0046.lzma 2025-07-10T19:53:08.500Z,1752177188.500 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0046.lzma.bak 2025-07-10T19:53:08.500Z,1752177188.500 [DataOverHttps](INFO): SBD MOMSN=25474819 2025-07-10T19:53:09.870Z,1752177189.870 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T19:53:09.870Z,1752177189.870 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T19:53:09.870Z,1752177189.870 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T19:53:15.112Z,1752177195.112 [NAL9602](INFO): SBD MO Status=2, MOMSN=9741, MT Status=2, MTMSN=0 2025-07-10T19:53:15.112Z,1752177195.112 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T19:53:25.614Z,1752177205.614 [NAL9602](INFO): SBD MO Status=1, MOMSN=9741, MT Status=0, MTMSN=0 2025-07-10T19:53:25.614Z,1752177205.614 [NAL9602](INFO): No messages in MT queue 2025-07-10T19:53:56.312Z,1752177236.312 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T19:58:10.446Z,1752177490.446 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T19:58:10.446Z,1752177490.446 [Default:CheckIn:C.Wait] Stopped 2025-07-10T19:58:10.447Z,1752177490.447 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T19:58:10.447Z,1752177490.447 [Default:CheckIn:D] Running Loop=1 2025-07-10T19:58:10.858Z,1752177490.858 [Default:CheckIn:D] Stopped 2025-07-10T19:58:10.858Z,1752177490.858 [Default:CheckIn:E] Running Loop=1 2025-07-10T19:58:11.253Z,1752177491.253 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.615951 min 2025-07-10T19:58:11.253Z,1752177491.253 [Default:CheckIn:E] Stopped 2025-07-10T19:58:11.253Z,1752177491.253 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T19:58:11.253Z,1752177491.253 [Default:CheckIn] Stopped 2025-07-10T19:58:11.253Z,1752177491.253 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T19:58:11.254Z,1752177491.254 [Default:CheckIn](INFO): Running loop #16 2025-07-10T19:58:11.254Z,1752177491.254 [Default:CheckIn] Running Loop=16 2025-07-10T19:58:11.254Z,1752177491.254 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T19:58:11.254Z,1752177491.254 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T19:58:13.273Z,1752177493.273 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195812.00,A,3648.17524,N,12147.28289,W,0.019,289.61,100725,,,D*76 2025-07-10T19:58:13.275Z,1752177493.275 [NAL9602](INFO): GPS fix at 20250710T195812: (36.802921, -121.788048) 2025-07-10T19:58:13.285Z,1752177493.285 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T19:58:13.286Z,1752177493.286 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T19:58:20.700Z,1752177500.700 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250710T181945/Courier0048.lzma 2025-07-10T19:58:28.619Z,1752177508.619 [NAL9602](INFO): SBD MO Status=1, MOMSN=9742, MT Status=0, MTMSN=0 2025-07-10T19:58:28.619Z,1752177508.619 [NAL9602](INFO): No messages in MT queue 2025-07-10T19:58:31.743Z,1752177511.743 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0048.lzma.bak 2025-07-10T19:58:31.743Z,1752177511.743 [DataOverHttps](INFO): SBD MOMSN=25474888 2025-07-10T19:58:57.641Z,1752177537.641 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250710T181945/Express0049.lzma 2025-07-10T19:58:59.322Z,1752177539.322 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T19:59:08.683Z,1752177548.683 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0049.lzma.bak 2025-07-10T19:59:08.683Z,1752177548.683 [DataOverHttps](INFO): SBD MOMSN=25474891 2025-07-10T19:59:09.841Z,1752177549.841 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T19:59:09.841Z,1752177549.841 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T19:59:09.841Z,1752177549.841 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T20:04:10.412Z,1752177850.412 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T20:04:10.412Z,1752177850.412 [Default:CheckIn:C.Wait] Stopped 2025-07-10T20:04:10.412Z,1752177850.412 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T20:04:10.413Z,1752177850.413 [Default:CheckIn:D] Running Loop=1 2025-07-10T20:04:10.823Z,1752177850.823 [Default:CheckIn:D] Stopped 2025-07-10T20:04:10.823Z,1752177850.823 [Default:CheckIn:E] Running Loop=1 2025-07-10T20:04:11.233Z,1752177851.233 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.615356 min 2025-07-10T20:04:11.233Z,1752177851.233 [Default:CheckIn:E] Stopped 2025-07-10T20:04:11.233Z,1752177851.233 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T20:04:11.233Z,1752177851.233 [Default:CheckIn] Stopped 2025-07-10T20:04:11.233Z,1752177851.233 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T20:04:11.234Z,1752177851.234 [Default:CheckIn](INFO): Running loop #17 2025-07-10T20:04:11.234Z,1752177851.234 [Default:CheckIn] Running Loop=17 2025-07-10T20:04:11.234Z,1752177851.234 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T20:04:11.234Z,1752177851.234 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T20:04:13.232Z,1752177853.232 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200412.00,A,3648.17772,N,12147.28206,W,0.194,290.05,100725,,,D*7D 2025-07-10T20:04:13.234Z,1752177853.234 [NAL9602](INFO): GPS fix at 20250710T200412: (36.802962, -121.788034) 2025-07-10T20:04:13.244Z,1752177853.244 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T20:04:13.244Z,1752177853.244 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T20:04:20.680Z,1752177860.680 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250710T181945/Courier0051.lzma 2025-07-10T20:04:31.723Z,1752177871.723 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0051.lzma.bak 2025-07-10T20:04:31.723Z,1752177871.723 [DataOverHttps](INFO): SBD MOMSN=25474913 2025-07-10T20:04:57.661Z,1752177897.661 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250710T181945/Express0052.lzma 2025-07-10T20:05:08.703Z,1752177908.703 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0052.lzma.bak 2025-07-10T20:05:08.703Z,1752177908.703 [DataOverHttps](INFO): SBD MOMSN=25474916 2025-07-10T20:05:09.840Z,1752177909.840 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T20:05:09.841Z,1752177909.841 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T20:05:09.841Z,1752177909.841 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T20:06:23.755Z,1752177983.755 [NAL9602](INFO): SBD MO Status=2, MOMSN=9743, MT Status=2, MTMSN=0 2025-07-10T20:06:23.755Z,1752177983.755 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T20:06:55.664Z,1752178015.664 [NAL9602](INFO): SBD MO Status=1, MOMSN=9743, MT Status=0, MTMSN=0 2025-07-10T20:06:55.664Z,1752178015.664 [NAL9602](INFO): No messages in MT queue 2025-07-10T20:07:26.367Z,1752178046.367 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T20:07:55.071Z,1752178075.071 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-10T20:07:59.546Z,1752178079.546 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.036265 CHAN A1 (24V): 0.069987 CHAN A2 (12V): 0.030749 CHAN A3 (5V): 0.008120 CHAN B0 (3.3V): 0.005531 CHAN B1 (3.15aV): 0.005380 CHAN B2 (3.15bV): 0.005234 CHAN B3 (GND): -0.004612 OPEN: 0.007071 Full Scale: +/- 1 mA 2025-07-10T20:08:31.414Z,1752178111.414 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-07-10T20:08:31.414Z,1752178111.414 [DropWeight] Hardware Fault, FailCount= 1 2025-07-10T20:08:31.414Z,1752178111.414 [DropWeight](ERROR): Hardware Fault 2025-07-10T20:08:31.464Z,1752178111.464 [CommandExec](FAULT): Scheduling is paused 2025-07-10T20:08:31.464Z,1752178111.464 [CBIT](INFO): Critical error at 20250710T200831 2025-07-10T20:08:31.471Z,1752178111.471 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-07-10T20:08:31.472Z,1752178111.472 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-07-10T20:08:31.861Z,1752178111.861 [CBIT](INFO): Critical error at 20250710T200831 2025-07-10T20:10:10.407Z,1752178210.407 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T20:10:10.407Z,1752178210.407 [Default:CheckIn:C.Wait] Stopped 2025-07-10T20:10:10.407Z,1752178210.407 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T20:10:10.407Z,1752178210.407 [Default:CheckIn:D] Running Loop=1 2025-07-10T20:10:10.814Z,1752178210.814 [Default:CheckIn:D] Stopped 2025-07-10T20:10:10.814Z,1752178210.814 [Default:CheckIn:E] Running Loop=1 2025-07-10T20:10:11.219Z,1752178211.219 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.615218 min 2025-07-10T20:10:11.219Z,1752178211.219 [Default:CheckIn:E] Stopped 2025-07-10T20:10:11.219Z,1752178211.219 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T20:10:11.220Z,1752178211.220 [Default:CheckIn] Stopped 2025-07-10T20:10:11.220Z,1752178211.220 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T20:10:11.220Z,1752178211.220 [Default:CheckIn](INFO): Running loop #18 2025-07-10T20:10:11.220Z,1752178211.220 [Default:CheckIn] Running Loop=18 2025-07-10T20:10:11.220Z,1752178211.220 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T20:10:11.220Z,1752178211.220 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T20:10:13.230Z,1752178213.230 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201012.00,A,3648.17482,N,12147.28322,W,0.292,247.38,100725,,,D*72 2025-07-10T20:10:13.232Z,1752178213.232 [NAL9602](INFO): GPS fix at 20250710T201012: (36.802914, -121.788054) 2025-07-10T20:10:13.262Z,1752178213.262 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T20:10:13.262Z,1752178213.262 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T20:10:20.145Z,1752178220.145 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250710T181945/Courier0054.lzma 2025-07-10T20:10:31.207Z,1752178231.207 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0054.lzma.bak 2025-07-10T20:10:31.207Z,1752178231.207 [DataOverHttps](INFO): SBD MOMSN=25474943 2025-07-10T20:10:43.556Z,1752178243.556 [NAL9602](INFO): SBD MO Status=2, MOMSN=9744, MT Status=2, MTMSN=0 2025-07-10T20:10:43.556Z,1752178243.556 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T20:10:57.250Z,1752178257.250 [DataOverHttps](INFO): Sending 378 bytes from file Logs/20250710T181945/Express0055.lzma 2025-07-10T20:11:08.291Z,1752178268.291 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0055.lzma.bak 2025-07-10T20:11:08.291Z,1752178268.291 [DataOverHttps](INFO): SBD MOMSN=25474948 2025-07-10T20:11:09.428Z,1752178269.428 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T20:11:09.428Z,1752178269.428 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T20:11:09.428Z,1752178269.428 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T20:15:15.447Z,1752178515.447 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-10T20:15:46.162Z,1752178546.162 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T20:16:09.996Z,1752178569.996 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T20:16:09.997Z,1752178569.997 [Default:CheckIn:C.Wait] Stopped 2025-07-10T20:16:09.997Z,1752178569.997 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T20:16:09.998Z,1752178569.998 [Default:CheckIn:D] Running Loop=1 2025-07-10T20:16:10.402Z,1752178570.402 [Default:CheckIn:D] Stopped 2025-07-10T20:16:10.402Z,1752178570.402 [Default:CheckIn:E] Running Loop=1 2025-07-10T20:16:10.817Z,1752178570.817 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.608350 min 2025-07-10T20:16:10.817Z,1752178570.817 [Default:CheckIn:E] Stopped 2025-07-10T20:16:10.817Z,1752178570.817 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T20:16:10.817Z,1752178570.817 [Default:CheckIn] Stopped 2025-07-10T20:16:10.818Z,1752178570.818 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T20:16:10.818Z,1752178570.818 [Default:CheckIn](INFO): Running loop #19 2025-07-10T20:16:10.818Z,1752178570.818 [Default:CheckIn] Running Loop=19 2025-07-10T20:16:10.818Z,1752178570.818 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T20:16:10.818Z,1752178570.818 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T20:16:12.816Z,1752178572.816 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201612.00,A,3648.16504,N,12147.27976,W,0.272,213.96,100725,,,A*70 2025-07-10T20:16:12.818Z,1752178572.818 [NAL9602](INFO): GPS fix at 20250710T201612: (36.802751, -121.787996) 2025-07-10T20:16:12.828Z,1752178572.828 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T20:16:12.828Z,1752178572.828 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T20:16:19.728Z,1752178579.728 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250710T181945/Courier0057.lzma 2025-07-10T20:16:30.771Z,1752178590.771 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0057.lzma.bak 2025-07-10T20:16:30.771Z,1752178590.771 [DataOverHttps](INFO): SBD MOMSN=25474974 2025-07-10T20:16:57.145Z,1752178617.145 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250710T181945/Express0058.lzma 2025-07-10T20:17:08.187Z,1752178628.187 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0058.lzma.bak 2025-07-10T20:17:08.187Z,1752178628.187 [DataOverHttps](INFO): SBD MOMSN=25474977 2025-07-10T20:17:09.406Z,1752178629.406 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T20:17:09.407Z,1752178629.407 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T20:17:09.407Z,1752178629.407 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T20:21:15.420Z,1752178875.420 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-10T20:21:46.122Z,1752178906.122 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T20:22:09.980Z,1752178929.980 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T20:22:09.980Z,1752178929.980 [Default:CheckIn:C.Wait] Stopped 2025-07-10T20:22:09.980Z,1752178929.980 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T20:22:09.980Z,1752178929.980 [Default:CheckIn:D] Running Loop=1 2025-07-10T20:22:10.376Z,1752178930.376 [Default:CheckIn:D] Stopped 2025-07-10T20:22:10.376Z,1752178930.376 [Default:CheckIn:E] Running Loop=1 2025-07-10T20:22:10.782Z,1752178930.782 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.607910 min 2025-07-10T20:22:10.782Z,1752178930.782 [Default:CheckIn:E] Stopped 2025-07-10T20:22:10.782Z,1752178930.782 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T20:22:10.782Z,1752178930.782 [Default:CheckIn] Stopped 2025-07-10T20:22:10.782Z,1752178930.782 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T20:22:10.782Z,1752178930.782 [Default:CheckIn](INFO): Running loop #20 2025-07-10T20:22:10.782Z,1752178930.782 [Default:CheckIn] Running Loop=20 2025-07-10T20:22:10.782Z,1752178930.782 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T20:22:10.782Z,1752178930.782 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T20:22:12.789Z,1752178932.789 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202212.00,A,3648.16149,N,12147.28424,W,0.058,232.71,100725,,,A*7F 2025-07-10T20:22:12.792Z,1752178932.792 [NAL9602](INFO): GPS fix at 20250710T202212: (36.802692, -121.788071) 2025-07-10T20:22:12.802Z,1752178932.802 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T20:22:12.802Z,1752178932.802 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T20:22:19.668Z,1752178939.668 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250710T181945/Courier0060.lzma 2025-07-10T20:22:30.711Z,1752178950.711 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0060.lzma.bak 2025-07-10T20:22:30.711Z,1752178950.711 [DataOverHttps](INFO): SBD MOMSN=25474994 2025-07-10T20:22:43.510Z,1752178963.510 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-10T20:22:43.593Z,1752178963.593 [NAL9602](ERROR): received: +CSQ:0 OK44, 2, 0, 0, 0 OK 2025-07-10T20:22:56.725Z,1752178976.725 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250710T181945/Express0061.lzma 2025-07-10T20:23:07.767Z,1752178987.767 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0061.lzma.bak 2025-07-10T20:23:07.767Z,1752178987.767 [DataOverHttps](INFO): SBD MOMSN=25475000 2025-07-10T20:23:08.981Z,1752178988.981 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T20:23:08.981Z,1752178988.981 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T20:23:08.981Z,1752178988.981 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T20:23:31.631Z,1752179011.631 [CBIT](INFO): Clearing failed state for component DropWeight 2025-07-10T20:23:31.631Z,1752179011.631 [DropWeight] No Fault, FailCount= 1 2025-07-10T20:25:32.388Z,1752179132.388 [NAL9602](INFO): SBD MO Status=2, MOMSN=9744, MT Status=2, MTMSN=0 2025-07-10T20:25:32.388Z,1752179132.388 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T20:25:57.843Z,1752179157.843 [NAL9602](INFO): SBD MO Status=2, MOMSN=9744, MT Status=2, MTMSN=0 2025-07-10T20:25:57.844Z,1752179157.844 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T20:26:20.460Z,1752179180.460 [NAL9602](INFO): SBD MO Status=2, MOMSN=9744, MT Status=2, MTMSN=0 2025-07-10T20:26:20.460Z,1752179180.460 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-10T20:27:15.411Z,1752179235.411 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-07-10T20:27:46.116Z,1752179266.116 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-10T20:28:09.569Z,1752179289.569 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-10T20:28:09.569Z,1752179289.569 [Default:CheckIn:C.Wait] Stopped 2025-07-10T20:28:09.569Z,1752179289.569 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T20:28:09.570Z,1752179289.570 [Default:CheckIn:D] Running Loop=1 2025-07-10T20:28:09.960Z,1752179289.960 [Default:CheckIn:D] Stopped 2025-07-10T20:28:09.960Z,1752179289.960 [Default:CheckIn:E] Running Loop=1 2025-07-10T20:28:10.364Z,1752179290.364 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.600968 min 2025-07-10T20:28:10.364Z,1752179290.364 [Default:CheckIn:E] Stopped 2025-07-10T20:28:10.364Z,1752179290.364 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-10T20:28:10.365Z,1752179290.365 [Default:CheckIn] Stopped 2025-07-10T20:28:10.365Z,1752179290.365 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T20:28:10.365Z,1752179290.365 [Default:CheckIn](INFO): Running loop #21 2025-07-10T20:28:10.365Z,1752179290.365 [Default:CheckIn] Running Loop=21 2025-07-10T20:28:10.365Z,1752179290.365 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-10T20:28:10.365Z,1752179290.365 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-10T20:28:12.379Z,1752179292.379 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202811.00,A,3648.15830,N,12147.28242,W,2.391,319.38,100725,,,A*75 2025-07-10T20:28:12.382Z,1752179292.382 [NAL9602](INFO): GPS fix at 20250710T202811: (36.802638, -121.788040) 2025-07-10T20:28:12.392Z,1752179292.392 [Default:CheckIn:Read_GPS] Stopped 2025-07-10T20:28:12.392Z,1752179292.392 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-10T20:28:19.960Z,1752179299.960 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250710T181945/Courier0063.lzma 2025-07-10T20:28:31.019Z,1752179311.019 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Courier0063.lzma.bak 2025-07-10T20:28:31.019Z,1752179311.019 [DataOverHttps](INFO): SBD MOMSN=25475025 2025-07-10T20:28:43.080Z,1752179323.080 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-07-10T20:28:43.153Z,1752179323.153 [NAL9602](ERROR): received: +CSQ:0 OK44, 2, 0, 0, 0 OK 2025-07-10T20:28:57.197Z,1752179337.197 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20250710T181945/Express0064.lzma 2025-07-10T20:29:08.243Z,1752179348.243 [DataOverHttps](INFO): Moved sent file to Logs/20250710T181945/Express0064.lzma.bak 2025-07-10T20:29:08.243Z,1752179348.243 [DataOverHttps](INFO): SBD MOMSN=25475031 2025-07-10T20:29:09.386Z,1752179349.386 [Default:CheckIn:Read_Iridium] Stopped 2025-07-10T20:29:09.386Z,1752179349.386 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-10T20:29:09.387Z,1752179349.387 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-10T20:31:42.071Z,1752179502.071 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2025-07-10T20:33:06.142Z,1752179586.142 [CommandExec](IMPORTANT): got command quit 2025-07-10T20:33:07.145Z,1752179587.145 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-10T20:33:07.145Z,1752179587.145 [CommandExec](INFO): Uninitializing the command executive. 2025-07-10T20:33:07.146Z,1752179587.146 [CommandExec](INFO): Uninitializing the command scheduler. 2025-07-10T20:33:07.146Z,1752179587.146 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:07.245Z,1752179587.245 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-07-10T20:33:07.245Z,1752179587.245 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-07-10T20:33:07.245Z,1752179587.245 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:07.245Z,1752179587.245 [NavChartDb](INFO): Join timeout helper Thread ID is 6939 2025-07-10T20:33:07.461Z,1752179587.461 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-10T20:33:07.461Z,1752179587.461 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:07.481Z,1752179587.481 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-07-10T20:33:07.481Z,1752179587.481 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:07.481Z,1752179587.481 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6940 2025-07-10T20:33:07.549Z,1752179587.549 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-10T20:33:07.549Z,1752179587.549 [WetLabsBB2FL](INFO): Powering down 2025-07-10T20:33:07.550Z,1752179587.550 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:07.561Z,1752179587.561 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-07-10T20:33:07.561Z,1752179587.561 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:07.561Z,1752179587.561 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6941 2025-07-10T20:33:08.065Z,1752179588.065 [CTD_Seabird](INFO): Powering down 2025-07-10T20:33:08.077Z,1752179588.077 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-10T20:33:08.077Z,1752179588.077 [CTD_Seabird](INFO): Powering down 2025-07-10T20:33:08.090Z,1752179588.090 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:08.099Z,1752179588.099 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-07-10T20:33:08.099Z,1752179588.099 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:08.100Z,1752179588.100 [Radio_Surface](INFO): Join timeout helper Thread ID is 6942 2025-07-10T20:33:08.242Z,1752179588.242 [Radio_Surface](INFO): Powering down 2025-07-10T20:33:08.242Z,1752179588.242 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-10T20:33:08.243Z,1752179588.243 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:08.249Z,1752179588.249 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-07-10T20:33:08.249Z,1752179588.249 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:08.249Z,1752179588.249 [Onboard](INFO): Join timeout helper Thread ID is 6943 2025-07-10T20:33:12.409Z,1752179592.409 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-10T20:33:12.409Z,1752179592.409 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:12.419Z,1752179592.419 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-07-10T20:33:12.419Z,1752179592.419 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:12.420Z,1752179592.420 [DataOverHttps](INFO): Join timeout helper Thread ID is 6944 2025-07-10T20:33:13.285Z,1752179593.285 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-10T20:33:13.288Z,1752179593.288 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:13.303Z,1752179593.303 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-07-10T20:33:13.303Z,1752179593.303 [DAT ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:13.303Z,1752179593.303 [DAT](INFO): Join timeout helper Thread ID is 6945 2025-07-10T20:33:13.477Z,1752179593.477 [DAT](INFO): Powering down 2025-07-10T20:33:13.550Z,1752179593.550 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-10T20:33:13.550Z,1752179593.550 [DAT](INFO): Powering down 2025-07-10T20:33:13.551Z,1752179593.551 [DAT ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:13.554Z,1752179593.554 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-07-10T20:33:13.554Z,1752179593.554 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:13.554Z,1752179593.554 [BackseatComponent](INFO): Join timeout helper Thread ID is 6946 2025-07-10T20:33:13.690Z,1752179593.690 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-10T20:33:13.693Z,1752179593.693 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:13.714Z,1752179593.714 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-07-10T20:33:13.714Z,1752179593.714 [logger ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:13.714Z,1752179593.714 [logger](INFO): Join timeout helper Thread ID is 6947 2025-07-10T20:33:13.715Z,1752179593.715 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-10T20:33:13.715Z,1752179593.715 [logger ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:13.716Z,1752179593.716 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-07-10T20:33:13.717Z,1752179593.717 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:13.717Z,1752179593.717 [CommandLine](INFO): Join timeout helper Thread ID is 6948 2025-07-10T20:33:13.749Z,1752179593.749 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-10T20:33:13.749Z,1752179593.749 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:13.762Z,1752179593.762 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-07-10T20:33:13.762Z,1752179593.762 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:13.762Z,1752179593.762 [CommandExec](INFO): Join timeout helper Thread ID is 6949 2025-07-10T20:33:13.770Z,1752179593.770 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-07-10T20:33:13.770Z,1752179593.770 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:13.770Z,1752179593.770 [controlThread](INFO): Join timeout helper Thread ID is 6950 2025-07-10T20:33:13.791Z,1752179593.791 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-10T20:33:13.791Z,1752179593.791 [controlThread](DEBUG): Uninitializing ControlThread 2025-07-10T20:33:13.791Z,1752179593.791 [AHRS_M2](INFO): Powering down 2025-07-10T20:33:13.869Z,1752179593.869 [NAL9602](INFO): Powering down 2025-07-10T20:33:13.870Z,1752179593.870 [Sonardyne_Nano](INFO): Powering down 2025-07-10T20:33:13.989Z,1752179593.989 [Waterlinked](INFO): Powering down 2025-07-10T20:33:14.077Z,1752179594.077 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-07-10T20:33:14.078Z,1752179594.078 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-07-10T20:33:14.078Z,1752179594.078 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-07-10T20:33:14.079Z,1752179594.079 [MissionManager](INFO): Uninitializing Mission Default 2025-07-10T20:33:14.079Z,1752179594.079 [Default] Stopped 2025-07-10T20:33:14.079Z,1752179594.079 [Default](DEBUG): Aggregate::uninitialize Default 2025-07-10T20:33:14.079Z,1752179594.079 [Default:B.GoToSurface] Stopped 2025-07-10T20:33:14.079Z,1752179594.079 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-07-10T20:33:14.079Z,1752179594.079 [Default:CheckIn] Stopped 2025-07-10T20:33:14.079Z,1752179594.079 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-10T20:33:14.080Z,1752179594.080 [Default:CheckIn:C.Wait] Stopped 2025-07-10T20:33:14.080Z,1752179594.080 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-10T20:33:14.083Z,1752179594.083 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-07-10T20:33:14.083Z,1752179594.083 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-07-10T20:33:14.083Z,1752179594.083 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-07-10T20:33:14.084Z,1752179594.084 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-07-10T20:33:14.084Z,1752179594.084 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-07-10T20:33:14.084Z,1752179594.084 [BuoyancyServo](INFO): Powering down 2025-07-10T20:33:14.097Z,1752179594.097 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-07-10T20:33:14.097Z,1752179594.097 [ElevatorServo](INFO): Powering down 2025-07-10T20:33:14.098Z,1752179594.098 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-07-10T20:33:14.098Z,1752179594.098 [MassServo](INFO): Powering down 2025-07-10T20:33:14.099Z,1752179594.099 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-07-10T20:33:14.099Z,1752179594.099 [RudderServo](INFO): Powering down 2025-07-10T20:33:14.100Z,1752179594.100 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-07-10T20:33:14.100Z,1752179594.100 [ThrusterHE](INFO): Powering down 2025-07-10T20:33:14.101Z,1752179594.101 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-07-10T20:33:14.101Z,1752179594.101 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-07-10T20:33:14.101Z,1752179594.101 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-07-10T20:33:14.102Z,1752179594.102 [CBIT](DEBUG): Powering off loads. 2025-07-10T20:33:14.113Z,1752179594.113 [CBIT](DEBUG): Disabling WDT. 2025-07-10T20:33:14.125Z,1752179594.125 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-07-10T20:33:14.125Z,1752179594.125 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-07-10T20:33:14.126Z,1752179594.126 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:14.191Z,1752179594.191 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:14.194Z,1752179594.194 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:14.200Z,1752179594.200 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:14.206Z,1752179594.206 [DAT ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:14.248Z,1752179594.248 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:14.290Z,1752179594.290 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:14.295Z,1752179594.295 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:14.314Z,1752179594.314 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-07-10T20:33:14.314Z,1752179594.314 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-07-10T20:33:14.329Z,1752179594.329 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-07-10T20:33:14.381Z,1752179594.381 [logger ThreadHandler](INFO): Thread cancelled.