2025-05-21T15:51:43.872Z,1747842703.872 [CommandExec](IMPORTANT): got command restart logs 2025-05-21T15:51:47.099Z,1747842707.099 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T15:51:47.099Z,1747842707.099 [Default:CheckIn:C.Wait] Stopped 2025-05-21T15:51:47.100Z,1747842707.100 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T15:51:47.100Z,1747842707.100 [Default:CheckIn:D] Running Loop=1 2025-05-21T15:51:47.504Z,1747842707.504 [Default:CheckIn:D] Stopped 2025-05-21T15:51:47.504Z,1747842707.504 [Default:CheckIn:E] Running Loop=1 2025-05-21T15:51:47.937Z,1747842707.937 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.062720 min 2025-05-21T15:51:47.937Z,1747842707.937 [Default:CheckIn:E] Stopped 2025-05-21T15:51:47.937Z,1747842707.937 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T15:51:47.937Z,1747842707.937 [Default:CheckIn] Stopped 2025-05-21T15:51:47.937Z,1747842707.937 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T15:51:47.938Z,1747842707.938 [Default:CheckIn](INFO): Running loop #9 2025-05-21T15:51:47.938Z,1747842707.938 [Default:CheckIn] Running Loop=9 2025-05-21T15:51:47.938Z,1747842707.938 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T15:51:47.938Z,1747842707.938 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T15:51:49.910Z,1747842709.910 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155149.00,A,3648.22886,N,12151.02445,W,3.946,71.87,210525,,,D*4A 2025-05-21T15:51:49.912Z,1747842709.912 [NAL9602](INFO): GPS fix at 20250521T155149: (36.803814, -121.850408) 2025-05-21T15:51:49.936Z,1747842709.936 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T15:51:49.936Z,1747842709.936 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T15:52:21.033Z,1747842741.033 [NAL9602](INFO): SBD MO Status=2, MOMSN=63433, MT Status=2, MTMSN=0 2025-05-21T15:52:21.033Z,1747842741.033 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-21T15:52:52.679Z,1747842772.679 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-21T15:53:03.919Z,1747842783.919 [NAL9602](INFO): SBD MO Status=2, MOMSN=63433, MT Status=2, MTMSN=0 2025-05-21T15:53:03.920Z,1747842783.920 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-21T15:53:23.803Z,1747842803.803 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-21T15:53:33.755Z,1747842813.755 [NAL9602](INFO): SBD MO Status=2, MOMSN=63433, MT Status=2, MTMSN=0 2025-05-21T15:53:33.755Z,1747842813.755 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-21T15:53:54.931Z,1747842834.931 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-21T15:54:07.693Z,1747842847.693 [NAL9602](INFO): SBD MO Status=2, MOMSN=63433, MT Status=2, MTMSN=0 2025-05-21T15:54:07.693Z,1747842847.693 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-21T15:54:26.056Z,1747842866.056 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2025-05-21T15:54:40.011Z,1747842880.011 [NAL9602](INFO): SBD MO Status=1, MOMSN=63433, MT Status=0, MTMSN=0 2025-05-21T15:54:40.012Z,1747842880.012 [NAL9602](INFO): No messages in MT queue 2025-05-21T15:54:45.131Z,1747842885.131 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.355331 2025-05-21T15:55:07.541Z,1747842907.541 [NAL9602](INFO): SBD MO Status=2, MOMSN=63434, MT Status=2, MTMSN=0 2025-05-21T15:55:07.541Z,1747842907.541 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-21T15:55:30.015Z,1747842930.015 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003532 2025-05-21T15:55:32.708Z,1747842932.708 [NAL9602](INFO): SBD MO Status=2, MOMSN=63434, MT Status=2, MTMSN=0 2025-05-21T15:55:32.709Z,1747842932.709 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-21T15:55:37.462Z,1747842937.462 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250520T205737/Courier0132.lzma 2025-05-21T15:55:38.465Z,1747842938.465 [DataOverHttps](INFO): Moved sent file to Logs/20250520T205737/Courier0132.lzma.bak 2025-05-21T15:55:38.465Z,1747842938.465 [DataOverHttps](INFO): SBD MOMSN=24943556 2025-05-21T15:55:54.890Z,1747842954.890 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20250520T205737/Express0133.lzma 2025-05-21T15:55:55.894Z,1747842955.894 [DataOverHttps](INFO): Moved sent file to Logs/20250520T205737/Express0133.lzma.bak 2025-05-21T15:55:55.895Z,1747842955.895 [DataOverHttps](INFO): SBD MOMSN=24943558 2025-05-21T15:55:57.447Z,1747842957.447 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T15:55:57.448Z,1747842957.448 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T15:55:57.448Z,1747842957.448 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T15:56:03.417Z,1747842963.417 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-21T15:57:58.139Z,1747843078.139 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-21T15:58:02.986Z,1747843082.986 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T15:58:16.318Z,1747843096.318 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T15:58:30.058Z,1747843110.058 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T15:58:43.794Z,1747843123.794 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T15:58:57.935Z,1747843137.935 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T15:59:11.670Z,1747843151.670 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T15:59:25.002Z,1747843165.002 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T15:59:38.738Z,1747843178.738 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T15:59:52.878Z,1747843192.878 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:00:06.615Z,1747843206.615 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:00:19.975Z,1747843219.975 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:00:57.937Z,1747843257.937 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T16:00:57.937Z,1747843257.937 [Default:CheckIn:C.Wait] Stopped 2025-05-21T16:00:57.937Z,1747843257.937 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T16:00:57.938Z,1747843257.938 [Default:CheckIn:D] Running Loop=1 2025-05-21T16:00:58.344Z,1747843258.344 [Default:CheckIn:D] Stopped 2025-05-21T16:00:58.344Z,1747843258.344 [Default:CheckIn:E] Running Loop=1 2025-05-21T16:00:58.762Z,1747843258.762 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.243396 min 2025-05-21T16:00:58.763Z,1747843258.763 [Default:CheckIn:E] Stopped 2025-05-21T16:00:58.763Z,1747843258.763 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T16:00:58.763Z,1747843258.763 [Default:CheckIn] Stopped 2025-05-21T16:00:58.763Z,1747843258.763 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T16:00:58.763Z,1747843258.763 [Default:CheckIn](INFO): Running loop #10 2025-05-21T16:00:58.763Z,1747843258.763 [Default:CheckIn] Running Loop=10 2025-05-21T16:00:58.763Z,1747843258.763 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T16:00:58.763Z,1747843258.763 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T16:01:00.754Z,1747843260.754 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160100.00,A,3648.41767,N,12147.36875,W,7.970,51.03,210525,,,D*44 2025-05-21T16:01:00.756Z,1747843260.756 [NAL9602](INFO): GPS fix at 20250521T160100: (36.806961, -121.789479) 2025-05-21T16:01:00.767Z,1747843260.767 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T16:01:00.767Z,1747843260.767 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T16:01:02.884Z,1747843262.884 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2025-05-21T16:01:07.811Z,1747843267.811 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20250521T155143/Courier0006.lzma 2025-05-21T16:01:08.812Z,1747843268.812 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0006.lzma.bak 2025-05-21T16:01:08.812Z,1747843268.812 [DataOverHttps](INFO): SBD MOMSN=24943587 2025-05-21T16:01:24.996Z,1747843284.996 [DataOverHttps](INFO): Sending 553 bytes from file Logs/20250521T155143/Express0007.lzma 2025-05-21T16:01:26.027Z,1747843286.027 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0007.lzma.bak 2025-05-21T16:01:26.028Z,1747843286.028 [DataOverHttps](INFO): SBD MOMSN=24943591 2025-05-21T16:01:27.173Z,1747843287.173 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T16:01:27.173Z,1747843287.173 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T16:01:27.173Z,1747843287.173 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T16:01:51.805Z,1747843311.805 [NAL9602](INFO): SBD MO Status=1, MOMSN=63434, MT Status=0, MTMSN=0 2025-05-21T16:01:51.805Z,1747843311.805 [NAL9602](INFO): No messages in MT queue 2025-05-21T16:02:22.456Z,1747843342.455 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-21T16:05:00.019Z,1747843500.019 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 5. 2025-05-21T16:05:00.036Z,1747843500.036 [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-05-21T16:06:27.712Z,1747843587.712 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T16:06:27.712Z,1747843587.712 [Default:CheckIn:C.Wait] Stopped 2025-05-21T16:06:27.712Z,1747843587.712 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T16:06:27.712Z,1747843587.712 [Default:CheckIn:D] Running Loop=1 2025-05-21T16:06:28.108Z,1747843588.108 [Default:CheckIn:D] Stopped 2025-05-21T16:06:28.108Z,1747843588.108 [Default:CheckIn:E] Running Loop=1 2025-05-21T16:06:28.514Z,1747843588.514 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.739457 min 2025-05-21T16:06:28.514Z,1747843588.514 [Default:CheckIn:E] Stopped 2025-05-21T16:06:28.515Z,1747843588.515 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T16:06:28.515Z,1747843588.515 [Default:CheckIn] Stopped 2025-05-21T16:06:28.515Z,1747843588.515 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T16:06:28.515Z,1747843588.515 [Default:CheckIn](INFO): Running loop #11 2025-05-21T16:06:28.515Z,1747843588.515 [Default:CheckIn] Running Loop=11 2025-05-21T16:06:28.515Z,1747843588.515 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T16:06:28.515Z,1747843588.515 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T16:06:30.520Z,1747843590.520 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160629.00,A,3648.21982,N,12147.16925,W,3.771,209.98,210525,,,D*7B 2025-05-21T16:06:30.523Z,1747843590.523 [NAL9602](INFO): GPS fix at 20250521T160629: (36.803664, -121.786154) 2025-05-21T16:06:30.538Z,1747843590.538 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T16:06:30.538Z,1747843590.538 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T16:06:37.891Z,1747843597.891 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20250521T155143/Courier0009.lzma 2025-05-21T16:06:38.893Z,1747843598.893 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0009.lzma.bak 2025-05-21T16:06:38.893Z,1747843598.893 [DataOverHttps](INFO): SBD MOMSN=24943664 2025-05-21T16:06:40.213Z,1747843600.213 [NAL9602](INFO): SBD MO Status=1, MOMSN=63435, MT Status=0, MTMSN=0 2025-05-21T16:06:40.213Z,1747843600.213 [NAL9602](INFO): No messages in MT queue 2025-05-21T16:06:57.767Z,1747843617.767 [DataOverHttps](INFO): Sending 306 bytes from file Logs/20250521T155143/Express0010.lzma 2025-05-21T16:06:58.768Z,1747843618.768 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0010.lzma.bak 2025-05-21T16:06:58.769Z,1747843618.769 [DataOverHttps](INFO): SBD MOMSN=24943667 2025-05-21T16:07:00.091Z,1747843620.091 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T16:07:00.091Z,1747843620.091 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T16:07:00.091Z,1747843620.091 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T16:07:10.992Z,1747843630.992 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-21T16:12:00.690Z,1747843920.690 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T16:12:00.690Z,1747843920.690 [Default:CheckIn:C.Wait] Stopped 2025-05-21T16:12:00.690Z,1747843920.690 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T16:12:00.691Z,1747843920.691 [Default:CheckIn:D] Running Loop=1 2025-05-21T16:12:01.102Z,1747843921.102 [Default:CheckIn:D] Stopped 2025-05-21T16:12:01.103Z,1747843921.103 [Default:CheckIn:E] Running Loop=1 2025-05-21T16:12:01.496Z,1747843921.496 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.289364 min 2025-05-21T16:12:01.497Z,1747843921.497 [Default:CheckIn:E] Stopped 2025-05-21T16:12:01.497Z,1747843921.497 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T16:12:01.497Z,1747843921.497 [Default:CheckIn] Stopped 2025-05-21T16:12:01.497Z,1747843921.497 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T16:12:01.497Z,1747843921.497 [Default:CheckIn](INFO): Running loop #12 2025-05-21T16:12:01.497Z,1747843921.497 [Default:CheckIn] Running Loop=12 2025-05-21T16:12:01.497Z,1747843921.497 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T16:12:01.497Z,1747843921.497 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T16:12:03.507Z,1747843923.507 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161202.00,A,3648.15208,N,12147.22107,W,0.097,209.91,210525,,,D*73 2025-05-21T16:12:03.510Z,1747843923.510 [NAL9602](INFO): GPS fix at 20250521T161202: (36.802535, -121.787018) 2025-05-21T16:12:03.520Z,1747843923.520 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T16:12:03.521Z,1747843923.521 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T16:12:04.331Z,1747843924.331 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T16:12:04.331Z,1747843924.331 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T16:12:04.331Z,1747843924.331 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T16:13:02.088Z,1747843982.088 [NAL9602](INFO): SBD MO Status=1, MOMSN=63436, MT Status=0, MTMSN=0 2025-05-21T16:13:02.088Z,1747843982.088 [NAL9602](INFO): No messages in MT queue 2025-05-21T16:13:32.794Z,1747844012.794 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-21T16:17:04.914Z,1747844224.914 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T16:17:04.914Z,1747844224.914 [Default:CheckIn:C.Wait] Stopped 2025-05-21T16:17:04.914Z,1747844224.914 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T16:17:04.930Z,1747844224.930 [Default:CheckIn:D] Running Loop=1 2025-05-21T16:17:05.323Z,1747844225.323 [Default:CheckIn:D] Stopped 2025-05-21T16:17:05.323Z,1747844225.323 [Default:CheckIn:E] Running Loop=1 2025-05-21T16:17:05.721Z,1747844225.721 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.359709 min 2025-05-21T16:17:05.721Z,1747844225.721 [Default:CheckIn:E] Stopped 2025-05-21T16:17:05.721Z,1747844225.721 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T16:17:05.721Z,1747844225.721 [Default:CheckIn] Stopped 2025-05-21T16:17:05.722Z,1747844225.722 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T16:17:05.722Z,1747844225.722 [Default:CheckIn](INFO): Running loop #13 2025-05-21T16:17:05.722Z,1747844225.722 [Default:CheckIn] Running Loop=13 2025-05-21T16:17:05.722Z,1747844225.722 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T16:17:05.722Z,1747844225.722 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T16:17:07.736Z,1747844227.736 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161707.00,A,3648.15125,N,12147.22548,W,0.214,194.98,210525,,,A*72 2025-05-21T16:17:07.739Z,1747844227.739 [NAL9602](INFO): GPS fix at 20250521T161707: (36.802521, -121.787091) 2025-05-21T16:17:07.749Z,1747844227.749 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T16:17:07.749Z,1747844227.749 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T16:17:15.758Z,1747844235.758 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250521T155143/Courier0012.lzma 2025-05-21T16:17:16.760Z,1747844236.760 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0012.lzma.bak 2025-05-21T16:17:16.761Z,1747844236.761 [DataOverHttps](INFO): SBD MOMSN=24943747 2025-05-21T16:17:33.146Z,1747844253.146 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250521T155143/Courier0015.lzma 2025-05-21T16:17:34.149Z,1747844254.149 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0015.lzma.bak 2025-05-21T16:17:34.149Z,1747844254.149 [DataOverHttps](INFO): SBD MOMSN=24943750 2025-05-21T16:17:38.029Z,1747844258.029 [NAL9602](INFO): SBD MO Status=2, MOMSN=63437, MT Status=2, MTMSN=0 2025-05-21T16:17:38.029Z,1747844258.029 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-21T16:17:50.319Z,1747844270.319 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250521T155143/Express0013.lzma 2025-05-21T16:17:51.320Z,1747844271.320 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0013.lzma.bak 2025-05-21T16:17:51.321Z,1747844271.321 [DataOverHttps](INFO): SBD MOMSN=24943753 2025-05-21T16:17:59.458Z,1747844279.458 [NAL9602](INFO): SBD MO Status=1, MOMSN=63437, MT Status=0, MTMSN=0 2025-05-21T16:17:59.458Z,1747844279.458 [NAL9602](INFO): No messages in MT queue 2025-05-21T16:18:07.623Z,1747844287.623 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250521T155143/Express0016.lzma 2025-05-21T16:18:08.624Z,1747844288.624 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0016.lzma.bak 2025-05-21T16:18:08.625Z,1747844288.625 [DataOverHttps](INFO): SBD MOMSN=24943756 2025-05-21T16:18:09.964Z,1747844289.964 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T16:18:09.964Z,1747844289.964 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T16:18:09.964Z,1747844289.964 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T16:18:30.145Z,1747844310.145 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-21T16:20:02.658Z,1747844402.658 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:20:18.822Z,1747844418.822 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:20:34.580Z,1747844434.580 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:20:49.940Z,1747844449.940 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:21:04.887Z,1747844464.887 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:21:20.643Z,1747844480.643 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:21:36.404Z,1747844496.404 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:21:52.156Z,1747844512.156 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:22:07.912Z,1747844527.912 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:23:10.652Z,1747844590.652 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T16:23:10.652Z,1747844590.652 [Default:CheckIn:C.Wait] Stopped 2025-05-21T16:23:10.652Z,1747844590.652 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T16:23:10.652Z,1747844590.652 [Default:CheckIn:D] Running Loop=1 2025-05-21T16:23:11.066Z,1747844591.066 [Default:CheckIn:D] Stopped 2025-05-21T16:23:11.066Z,1747844591.066 [Default:CheckIn:E] Running Loop=1 2025-05-21T16:23:11.480Z,1747844591.480 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.455420 min 2025-05-21T16:23:11.480Z,1747844591.480 [Default:CheckIn:E] Stopped 2025-05-21T16:23:11.480Z,1747844591.480 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T16:23:11.480Z,1747844591.480 [Default:CheckIn] Stopped 2025-05-21T16:23:11.480Z,1747844591.480 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T16:23:11.480Z,1747844591.480 [Default:CheckIn](INFO): Running loop #14 2025-05-21T16:23:11.480Z,1747844591.480 [Default:CheckIn] Running Loop=14 2025-05-21T16:23:11.480Z,1747844591.480 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T16:23:11.480Z,1747844591.480 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T16:23:13.462Z,1747844593.462 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162312.00,A,3648.15040,N,12147.22806,W,0.019,194.98,210525,,,D*7E 2025-05-21T16:23:13.464Z,1747844593.464 [NAL9602](INFO): GPS fix at 20250521T162312: (36.802507, -121.787134) 2025-05-21T16:23:13.479Z,1747844593.479 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T16:23:13.479Z,1747844593.479 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T16:23:21.074Z,1747844601.074 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250521T155143/Courier0018.lzma 2025-05-21T16:23:22.076Z,1747844602.076 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0018.lzma.bak 2025-05-21T16:23:22.077Z,1747844602.077 [DataOverHttps](INFO): SBD MOMSN=24943767 2025-05-21T16:23:31.237Z,1747844611.237 [NAL9602](INFO): SBD MO Status=1, MOMSN=63438, MT Status=0, MTMSN=0 2025-05-21T16:23:31.237Z,1747844611.237 [NAL9602](INFO): No messages in MT queue 2025-05-21T16:23:38.327Z,1747844618.327 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250521T155143/Express0019.lzma 2025-05-21T16:23:39.328Z,1747844619.328 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0019.lzma.bak 2025-05-21T16:23:39.328Z,1747844619.328 [DataOverHttps](INFO): SBD MOMSN=24943778 2025-05-21T16:23:40.554Z,1747844620.554 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T16:23:40.555Z,1747844620.555 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T16:23:40.555Z,1747844620.555 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T16:24:01.999Z,1747844641.999 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-21T16:28:41.268Z,1747844921.268 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T16:28:41.268Z,1747844921.268 [Default:CheckIn:C.Wait] Stopped 2025-05-21T16:28:41.268Z,1747844921.268 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T16:28:41.269Z,1747844921.269 [Default:CheckIn:D] Running Loop=1 2025-05-21T16:28:41.666Z,1747844921.666 [Default:CheckIn:D] Stopped 2025-05-21T16:28:41.666Z,1747844921.666 [Default:CheckIn:E] Running Loop=1 2025-05-21T16:28:42.052Z,1747844922.052 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.965430 min 2025-05-21T16:28:42.052Z,1747844922.052 [Default:CheckIn:E] Stopped 2025-05-21T16:28:42.053Z,1747844922.053 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T16:28:42.053Z,1747844922.053 [Default:CheckIn] Stopped 2025-05-21T16:28:42.053Z,1747844922.053 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T16:28:42.053Z,1747844922.053 [Default:CheckIn](INFO): Running loop #15 2025-05-21T16:28:42.053Z,1747844922.053 [Default:CheckIn] Running Loop=15 2025-05-21T16:28:42.053Z,1747844922.053 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T16:28:42.053Z,1747844922.053 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T16:28:44.092Z,1747844924.092 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162843.00,A,3648.17602,N,12147.27939,W,0.019,289.12,210525,,,D*76 2025-05-21T16:28:44.094Z,1747844924.094 [NAL9602](INFO): GPS fix at 20250521T162843: (36.802934, -121.787990) 2025-05-21T16:28:44.105Z,1747844924.105 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T16:28:44.105Z,1747844924.105 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T16:28:54.716Z,1747844934.716 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250521T155143/Courier0021.lzma 2025-05-21T16:28:55.705Z,1747844935.705 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0021.lzma.bak 2025-05-21T16:28:55.705Z,1747844935.705 [DataOverHttps](INFO): SBD MOMSN=24943832 2025-05-21T16:29:12.523Z,1747844952.523 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250521T155143/Express0022.lzma 2025-05-21T16:29:13.524Z,1747844953.524 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0022.lzma.bak 2025-05-21T16:29:13.525Z,1747844953.525 [DataOverHttps](INFO): SBD MOMSN=24943842 2025-05-21T16:29:14.855Z,1747844954.855 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T16:29:14.855Z,1747844954.855 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T16:29:14.855Z,1747844954.855 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T16:29:30.987Z,1747844970.987 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:29:44.319Z,1747844984.319 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:29:58.458Z,1747844998.458 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:30:06.546Z,1747845006.546 [NAL9602](INFO): SBD MO Status=1, MOMSN=63439, MT Status=0, MTMSN=0 2025-05-21T16:30:06.546Z,1747845006.546 [NAL9602](INFO): No messages in MT queue 2025-05-21T16:30:12.196Z,1747845012.196 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:30:25.992Z,1747845025.992 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:30:37.304Z,1747845037.304 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-21T16:30:39.322Z,1747845039.322 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:30:52.694Z,1747845052.694 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:31:06.051Z,1747845066.051 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:31:19.384Z,1747845079.384 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:31:32.723Z,1747845092.723 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:31:46.450Z,1747845106.450 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:34:15.646Z,1747845255.646 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T16:34:15.646Z,1747845255.646 [Default:CheckIn:C.Wait] Stopped 2025-05-21T16:34:15.646Z,1747845255.646 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T16:34:15.647Z,1747845255.647 [Default:CheckIn:D] Running Loop=1 2025-05-21T16:34:16.114Z,1747845256.114 [Default:CheckIn:D] Stopped 2025-05-21T16:34:16.114Z,1747845256.114 [Default:CheckIn:E] Running Loop=1 2025-05-21T16:34:16.464Z,1747845256.464 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.539559 min 2025-05-21T16:34:16.464Z,1747845256.464 [Default:CheckIn:E] Stopped 2025-05-21T16:34:16.464Z,1747845256.464 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T16:34:16.464Z,1747845256.464 [Default:CheckIn] Stopped 2025-05-21T16:34:16.464Z,1747845256.464 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T16:34:16.464Z,1747845256.464 [Default:CheckIn](INFO): Running loop #16 2025-05-21T16:34:16.464Z,1747845256.464 [Default:CheckIn] Running Loop=16 2025-05-21T16:34:16.465Z,1747845256.465 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T16:34:16.465Z,1747845256.465 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T16:34:18.470Z,1747845258.470 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163417.00,A,3648.17427,N,12147.28151,W,0.078,289.12,210525,,,D*71 2025-05-21T16:34:18.485Z,1747845258.485 [NAL9602](INFO): GPS fix at 20250521T163417: (36.802904, -121.788025) 2025-05-21T16:34:18.508Z,1747845258.508 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T16:34:18.508Z,1747845258.508 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T16:34:29.315Z,1747845269.315 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250521T155143/Courier0024.lzma 2025-05-21T16:34:30.316Z,1747845270.316 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0024.lzma.bak 2025-05-21T16:34:30.317Z,1747845270.317 [DataOverHttps](INFO): SBD MOMSN=24943940 2025-05-21T16:34:30.674Z,1747845270.674 [NAL9602](INFO): SBD MO Status=1, MOMSN=63440, MT Status=0, MTMSN=0 2025-05-21T16:34:30.674Z,1747845270.674 [NAL9602](INFO): No messages in MT queue 2025-05-21T16:34:46.531Z,1747845286.531 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250521T155143/Express0025.lzma 2025-05-21T16:34:47.544Z,1747845287.544 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0025.lzma.bak 2025-05-21T16:34:47.545Z,1747845287.545 [DataOverHttps](INFO): SBD MOMSN=24943953 2025-05-21T16:34:48.892Z,1747845288.892 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T16:34:48.892Z,1747845288.892 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T16:34:48.892Z,1747845288.892 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T16:35:01.422Z,1747845301.422 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-21T16:36:41.640Z,1747845401.640 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-05-21T16:39:07.931Z,1747845547.931 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:39:21.263Z,1747845561.263 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:39:34.602Z,1747845574.602 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:39:47.926Z,1747845587.926 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:39:49.555Z,1747845589.555 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T16:39:49.556Z,1747845589.556 [Default:CheckIn:C.Wait] Stopped 2025-05-21T16:39:49.556Z,1747845589.556 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T16:39:49.556Z,1747845589.556 [Default:CheckIn:D] Running Loop=1 2025-05-21T16:39:49.965Z,1747845589.965 [Default:CheckIn:D] Stopped 2025-05-21T16:39:49.965Z,1747845589.965 [Default:CheckIn:E] Running Loop=1 2025-05-21T16:39:50.378Z,1747845590.378 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.103735 min 2025-05-21T16:39:50.379Z,1747845590.379 [Default:CheckIn:E] Stopped 2025-05-21T16:39:50.379Z,1747845590.379 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T16:39:50.379Z,1747845590.379 [Default:CheckIn] Stopped 2025-05-21T16:39:50.379Z,1747845590.379 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T16:39:50.379Z,1747845590.379 [Default:CheckIn](INFO): Running loop #17 2025-05-21T16:39:50.379Z,1747845590.379 [Default:CheckIn] Running Loop=17 2025-05-21T16:39:50.379Z,1747845590.379 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T16:39:50.379Z,1747845590.379 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T16:39:52.394Z,1747845592.394 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163951.00,A,3648.17678,N,12147.27543,W,0.194,289.12,210525,,,D*7D 2025-05-21T16:39:52.396Z,1747845592.396 [NAL9602](INFO): GPS fix at 20250521T163951: (36.802946, -121.787924) 2025-05-21T16:39:52.424Z,1747845592.424 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T16:39:52.424Z,1747845592.424 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T16:40:01.260Z,1747845601.260 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:40:03.394Z,1747845603.394 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250521T155143/Courier0027.lzma 2025-05-21T16:40:04.396Z,1747845604.396 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0027.lzma.bak 2025-05-21T16:40:04.397Z,1747845604.397 [DataOverHttps](INFO): SBD MOMSN=24943983 2025-05-21T16:40:12.174Z,1747845612.174 [NAL9602](INFO): SBD MO Status=2, MOMSN=63441, MT Status=2, MTMSN=0 2025-05-21T16:40:12.174Z,1747845612.174 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-21T16:40:14.592Z,1747845614.592 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:40:21.910Z,1747845621.910 [NAL9602](INFO): SBD MO Status=1, MOMSN=63441, MT Status=0, MTMSN=0 2025-05-21T16:40:21.910Z,1747845621.910 [NAL9602](INFO): No messages in MT queue 2025-05-21T16:40:23.595Z,1747845623.595 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250521T155143/Express0028.lzma 2025-05-21T16:40:24.597Z,1747845624.597 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0028.lzma.bak 2025-05-21T16:40:24.597Z,1747845624.597 [DataOverHttps](INFO): SBD MOMSN=24943989 2025-05-21T16:40:25.988Z,1747845625.988 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T16:40:25.988Z,1747845625.988 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T16:40:25.988Z,1747845625.988 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T16:40:27.959Z,1747845627.959 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:40:41.294Z,1747845641.294 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:40:52.615Z,1747845652.615 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-21T16:40:55.035Z,1747845655.035 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:41:08.770Z,1747845668.770 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:41:22.104Z,1747845682.104 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T16:45:26.549Z,1747845926.549 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T16:45:26.550Z,1747845926.550 [Default:CheckIn:C.Wait] Stopped 2025-05-21T16:45:26.550Z,1747845926.550 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T16:45:26.550Z,1747845926.550 [Default:CheckIn:D] Running Loop=1 2025-05-21T16:45:26.942Z,1747845926.942 [Default:CheckIn:D] Stopped 2025-05-21T16:45:26.942Z,1747845926.942 [Default:CheckIn:E] Running Loop=1 2025-05-21T16:45:27.343Z,1747845927.343 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.720028 min 2025-05-21T16:45:27.343Z,1747845927.343 [Default:CheckIn:E] Stopped 2025-05-21T16:45:27.343Z,1747845927.343 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T16:45:27.343Z,1747845927.343 [Default:CheckIn] Stopped 2025-05-21T16:45:27.344Z,1747845927.344 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T16:45:27.344Z,1747845927.344 [Default:CheckIn](INFO): Running loop #18 2025-05-21T16:45:27.344Z,1747845927.344 [Default:CheckIn] Running Loop=18 2025-05-21T16:45:27.344Z,1747845927.344 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T16:45:27.344Z,1747845927.344 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T16:45:29.355Z,1747845929.355 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164528.00,A,3648.17955,N,12147.27917,W,0.097,289.12,210525,,,D*77 2025-05-21T16:45:29.357Z,1747845929.357 [NAL9602](INFO): GPS fix at 20250521T164528: (36.802993, -121.787986) 2025-05-21T16:45:29.369Z,1747845929.369 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T16:45:29.369Z,1747845929.369 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T16:45:37.421Z,1747845937.421 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250521T155143/Courier0030.lzma 2025-05-21T16:45:38.416Z,1747845938.416 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0030.lzma.bak 2025-05-21T16:45:38.417Z,1747845938.417 [DataOverHttps](INFO): SBD MOMSN=24944068 2025-05-21T16:45:54.619Z,1747845954.619 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250521T155143/Express0031.lzma 2025-05-21T16:45:55.621Z,1747845955.621 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0031.lzma.bak 2025-05-21T16:45:55.621Z,1747845955.621 [DataOverHttps](INFO): SBD MOMSN=24944076 2025-05-21T16:45:56.863Z,1747845956.863 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T16:45:56.863Z,1747845956.863 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T16:45:56.863Z,1747845956.863 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T16:46:01.688Z,1747845961.688 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4. 2025-05-21T16:46:01.691Z,1747845961.691 [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-05-21T16:48:13.385Z,1747846093.385 [NAL9602](INFO): SBD MO Status=2, MOMSN=63442, MT Status=2, MTMSN=0 2025-05-21T16:48:13.385Z,1747846093.385 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-21T16:48:31.561Z,1747846111.561 [NAL9602](INFO): SBD MO Status=1, MOMSN=63442, MT Status=0, MTMSN=0 2025-05-21T16:48:31.561Z,1747846111.561 [NAL9602](INFO): No messages in MT queue 2025-05-21T16:49:02.272Z,1747846142.272 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-21T16:50:57.422Z,1747846257.422 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T16:50:57.423Z,1747846257.423 [Default:CheckIn:C.Wait] Stopped 2025-05-21T16:50:57.423Z,1747846257.423 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T16:50:57.423Z,1747846257.423 [Default:CheckIn:D] Running Loop=1 2025-05-21T16:50:57.833Z,1747846257.833 [Default:CheckIn:D] Stopped 2025-05-21T16:50:57.833Z,1747846257.833 [Default:CheckIn:E] Running Loop=1 2025-05-21T16:50:58.230Z,1747846258.230 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.234871 min 2025-05-21T16:50:58.230Z,1747846258.230 [Default:CheckIn:E] Stopped 2025-05-21T16:50:58.230Z,1747846258.230 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T16:50:58.230Z,1747846258.230 [Default:CheckIn] Stopped 2025-05-21T16:50:58.230Z,1747846258.230 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T16:50:58.231Z,1747846258.231 [Default:CheckIn](INFO): Running loop #19 2025-05-21T16:50:58.231Z,1747846258.231 [Default:CheckIn] Running Loop=19 2025-05-21T16:50:58.231Z,1747846258.231 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T16:50:58.231Z,1747846258.231 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T16:51:00.238Z,1747846260.238 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165059.00,A,3648.17717,N,12147.28145,W,0.078,289.12,210525,,,D*7C 2025-05-21T16:51:00.240Z,1747846260.240 [NAL9602](INFO): GPS fix at 20250521T165059: (36.802953, -121.788024) 2025-05-21T16:51:00.296Z,1747846260.296 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T16:51:00.296Z,1747846260.296 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T16:51:08.342Z,1747846268.342 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20250521T155143/Courier0033.lzma 2025-05-21T16:51:09.344Z,1747846269.344 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0033.lzma.bak 2025-05-21T16:51:09.345Z,1747846269.345 [DataOverHttps](INFO): SBD MOMSN=24944087 2025-05-21T16:51:25.387Z,1747846285.387 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250521T155143/Express0034.lzma 2025-05-21T16:51:26.388Z,1747846286.388 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0034.lzma.bak 2025-05-21T16:51:26.389Z,1747846286.389 [DataOverHttps](INFO): SBD MOMSN=24944090 2025-05-21T16:51:27.734Z,1747846287.734 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T16:51:27.735Z,1747846287.735 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T16:51:27.735Z,1747846287.735 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T16:52:36.391Z,1747846356.391 [NAL9602](INFO): SBD MO Status=1, MOMSN=63443, MT Status=0, MTMSN=0 2025-05-21T16:52:36.391Z,1747846356.391 [NAL9602](INFO): No messages in MT queue 2025-05-21T16:53:07.096Z,1747846387.096 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-21T16:56:28.302Z,1747846588.302 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T16:56:28.302Z,1747846588.302 [Default:CheckIn:C.Wait] Stopped 2025-05-21T16:56:28.302Z,1747846588.302 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T16:56:28.302Z,1747846588.302 [Default:CheckIn:D] Running Loop=1 2025-05-21T16:56:28.706Z,1747846588.706 [Default:CheckIn:D] Stopped 2025-05-21T16:56:28.706Z,1747846588.706 [Default:CheckIn:E] Running Loop=1 2025-05-21T16:56:29.108Z,1747846589.108 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.749422 min 2025-05-21T16:56:29.108Z,1747846589.108 [Default:CheckIn:E] Stopped 2025-05-21T16:56:29.108Z,1747846589.108 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T16:56:29.108Z,1747846589.108 [Default:CheckIn] Stopped 2025-05-21T16:56:29.108Z,1747846589.108 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T16:56:29.108Z,1747846589.108 [Default:CheckIn](INFO): Running loop #20 2025-05-21T16:56:29.108Z,1747846589.108 [Default:CheckIn] Running Loop=20 2025-05-21T16:56:29.108Z,1747846589.108 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T16:56:29.109Z,1747846589.109 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T16:56:31.118Z,1747846591.118 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165630.00,A,3648.17591,N,12147.27975,W,0.058,0.00,210525,,,D*7F 2025-05-21T16:56:31.120Z,1747846591.120 [NAL9602](INFO): GPS fix at 20250521T165630: (36.802932, -121.787996) 2025-05-21T16:56:31.146Z,1747846591.146 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T16:56:31.147Z,1747846591.147 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T16:56:39.210Z,1747846599.210 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250521T155143/Courier0036.lzma 2025-05-21T16:56:40.212Z,1747846600.212 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0036.lzma.bak 2025-05-21T16:56:40.213Z,1747846600.213 [DataOverHttps](INFO): SBD MOMSN=24944192 2025-05-21T16:56:59.443Z,1747846619.443 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250521T155143/Express0037.lzma 2025-05-21T16:57:00.445Z,1747846620.445 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0037.lzma.bak 2025-05-21T16:57:00.445Z,1747846620.445 [DataOverHttps](INFO): SBD MOMSN=24944199 2025-05-21T16:57:01.897Z,1747846621.897 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T16:57:01.898Z,1747846621.898 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T16:57:01.898Z,1747846621.898 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T16:57:39.453Z,1747846659.453 [NAL9602](INFO): SBD MO Status=1, MOMSN=63444, MT Status=0, MTMSN=0 2025-05-21T16:57:39.453Z,1747846659.453 [NAL9602](INFO): No messages in MT queue 2025-05-21T16:58:10.152Z,1747846690.152 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-21T17:01:01.458Z,1747846861.458 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:01:14.803Z,1747846874.803 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:01:28.526Z,1747846888.526 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:01:42.262Z,1747846902.262 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:01:55.594Z,1747846915.594 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:02:02.480Z,1747846922.480 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T17:02:02.480Z,1747846922.480 [Default:CheckIn:C.Wait] Stopped 2025-05-21T17:02:02.480Z,1747846922.480 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T17:02:02.481Z,1747846922.481 [Default:CheckIn:D] Running Loop=1 2025-05-21T17:02:02.887Z,1747846922.887 [Default:CheckIn:D] Stopped 2025-05-21T17:02:02.887Z,1747846922.887 [Default:CheckIn:E] Running Loop=1 2025-05-21T17:02:03.283Z,1747846923.283 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.319116 min 2025-05-21T17:02:03.283Z,1747846923.283 [Default:CheckIn:E] Stopped 2025-05-21T17:02:03.283Z,1747846923.283 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T17:02:03.283Z,1747846923.283 [Default:CheckIn] Stopped 2025-05-21T17:02:03.283Z,1747846923.283 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T17:02:03.283Z,1747846923.283 [Default:CheckIn](INFO): Running loop #21 2025-05-21T17:02:03.283Z,1747846923.283 [Default:CheckIn] Running Loop=21 2025-05-21T17:02:03.283Z,1747846923.283 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T17:02:03.283Z,1747846923.283 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T17:02:05.296Z,1747846925.296 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170204.00,A,3648.17741,N,12147.28091,W,0.136,0.00,210525,,,D*72 2025-05-21T17:02:05.299Z,1747846925.299 [NAL9602](INFO): GPS fix at 20250521T170204: (36.802957, -121.788015) 2025-05-21T17:02:05.309Z,1747846925.309 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T17:02:05.309Z,1747846925.309 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T17:02:08.926Z,1747846928.926 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:02:12.958Z,1747846932.958 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250521T155143/Courier0039.lzma 2025-05-21T17:02:13.960Z,1747846933.960 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0039.lzma.bak 2025-05-21T17:02:13.961Z,1747846933.961 [DataOverHttps](INFO): SBD MOMSN=24944254 2025-05-21T17:02:22.294Z,1747846942.294 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:02:33.925Z,1747846953.925 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250521T155143/Express0040.lzma 2025-05-21T17:02:34.896Z,1747846954.896 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0040.lzma.bak 2025-05-21T17:02:34.897Z,1747846954.897 [DataOverHttps](INFO): SBD MOMSN=24944257 2025-05-21T17:02:35.639Z,1747846955.639 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:02:36.091Z,1747846956.091 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T17:02:36.091Z,1747846956.091 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T17:02:36.091Z,1747846956.091 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T17:02:48.981Z,1747846968.981 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:03:02.366Z,1747846982.366 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:03:15.696Z,1747846995.696 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:05:49.973Z,1747847149.973 [NAL9602](INFO): SBD MO Status=2, MOMSN=63445, MT Status=2, MTMSN=0 2025-05-21T17:05:49.973Z,1747847149.973 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-21T17:06:45.318Z,1747847205.318 [NAL9602](INFO): SBD MO Status=2, MOMSN=63445, MT Status=2, MTMSN=0 2025-05-21T17:06:45.318Z,1747847205.318 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-05-21T17:07:07.537Z,1747847227.537 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-05-21T17:07:36.631Z,1747847256.631 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T17:07:36.631Z,1747847256.631 [Default:CheckIn:C.Wait] Stopped 2025-05-21T17:07:36.631Z,1747847256.631 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T17:07:36.632Z,1747847256.632 [Default:CheckIn:D] Running Loop=1 2025-05-21T17:07:37.037Z,1747847257.037 [Default:CheckIn:D] Stopped 2025-05-21T17:07:37.037Z,1747847257.037 [Default:CheckIn:E] Running Loop=1 2025-05-21T17:07:37.452Z,1747847257.452 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.888273 min 2025-05-21T17:07:37.452Z,1747847257.452 [Default:CheckIn:E] Stopped 2025-05-21T17:07:37.452Z,1747847257.452 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T17:07:37.452Z,1747847257.452 [Default:CheckIn] Stopped 2025-05-21T17:07:37.452Z,1747847257.452 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T17:07:37.452Z,1747847257.452 [Default:CheckIn](INFO): Running loop #22 2025-05-21T17:07:37.452Z,1747847257.452 [Default:CheckIn] Running Loop=22 2025-05-21T17:07:37.452Z,1747847257.452 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T17:07:37.452Z,1747847257.452 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T17:07:39.457Z,1747847259.457 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170738.00,A,3648.17643,N,12147.28245,W,0.156,320.83,210525,,,D*7C 2025-05-21T17:07:39.460Z,1747847259.460 [NAL9602](INFO): GPS fix at 20250521T170738: (36.802940, -121.788041) 2025-05-21T17:07:39.487Z,1747847259.487 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T17:07:39.487Z,1747847259.487 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T17:07:47.274Z,1747847267.274 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250521T155143/Courier0042.lzma 2025-05-21T17:07:48.276Z,1747847268.276 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0042.lzma.bak 2025-05-21T17:07:48.277Z,1747847268.277 [DataOverHttps](INFO): SBD MOMSN=24944350 2025-05-21T17:08:04.539Z,1747847284.539 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20250521T155143/Express0043.lzma 2025-05-21T17:08:05.540Z,1747847285.540 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0043.lzma.bak 2025-05-21T17:08:05.541Z,1747847285.541 [DataOverHttps](INFO): SBD MOMSN=24944353 2025-05-21T17:08:06.191Z,1747847286.191 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-05-21T17:08:06.939Z,1747847286.939 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T17:08:06.939Z,1747847286.939 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T17:08:06.940Z,1747847286.940 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T17:08:11.495Z,1747847291.495 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-21T17:10:40.898Z,1747847440.898 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:10:55.038Z,1747847455.038 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:11:08.371Z,1747847468.371 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:11:21.706Z,1747847481.705 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:11:35.035Z,1747847495.035 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:11:48.775Z,1747847508.775 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:12:02.103Z,1747847522.103 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:12:15.435Z,1747847535.435 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:12:28.378Z,1747847548.378 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:12:39.756Z,1747847559.756 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-05-21T17:12:42.123Z,1747847562.123 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:12:55.450Z,1747847575.450 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:13:07.591Z,1747847587.591 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T17:13:07.591Z,1747847587.591 [Default:CheckIn:C.Wait] Stopped 2025-05-21T17:13:07.591Z,1747847587.591 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T17:13:07.592Z,1747847587.592 [Default:CheckIn:D] Running Loop=1 2025-05-21T17:13:07.995Z,1747847587.995 [Default:CheckIn:D] Stopped 2025-05-21T17:13:07.995Z,1747847587.995 [Default:CheckIn:E] Running Loop=1 2025-05-21T17:13:08.412Z,1747847588.412 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.404240 min 2025-05-21T17:13:08.412Z,1747847588.412 [Default:CheckIn:E] Stopped 2025-05-21T17:13:08.412Z,1747847588.412 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T17:13:08.412Z,1747847588.412 [Default:CheckIn] Stopped 2025-05-21T17:13:08.412Z,1747847588.412 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T17:13:08.412Z,1747847588.412 [Default:CheckIn](INFO): Running loop #23 2025-05-21T17:13:08.412Z,1747847588.412 [Default:CheckIn] Running Loop=23 2025-05-21T17:13:08.412Z,1747847588.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T17:13:08.412Z,1747847588.412 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T17:13:10.408Z,1747847590.408 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171309.00,A,3648.17052,N,12147.27563,W,1.089,337.57,210525,,,A*79 2025-05-21T17:13:10.411Z,1747847590.411 [NAL9602](INFO): GPS fix at 20250521T171309: (36.802842, -121.787927) 2025-05-21T17:13:10.422Z,1747847590.422 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T17:13:10.423Z,1747847590.423 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T17:13:18.518Z,1747847598.518 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250521T155143/Courier0045.lzma 2025-05-21T17:13:19.520Z,1747847599.520 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0045.lzma.bak 2025-05-21T17:13:19.521Z,1747847599.521 [DataOverHttps](INFO): SBD MOMSN=24944363 2025-05-21T17:13:35.743Z,1747847615.743 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250521T155143/Express0046.lzma 2025-05-21T17:13:36.744Z,1747847616.744 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0046.lzma.bak 2025-05-21T17:13:36.745Z,1747847616.745 [DataOverHttps](INFO): SBD MOMSN=24944366 2025-05-21T17:13:37.946Z,1747847617.946 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T17:13:37.946Z,1747847617.946 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T17:13:37.946Z,1747847617.946 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T17:13:41.108Z,1747847621.108 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-05-21T17:13:41.191Z,1747847621.191 [NAL9602](ERROR): received: +CSQ:1 OK445, 2, 0, 0, 0 OK 2025-05-21T17:18:12.220Z,1747847892.220 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-05-21T17:18:38.602Z,1747847918.602 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T17:18:38.602Z,1747847918.602 [Default:CheckIn:C.Wait] Stopped 2025-05-21T17:18:38.602Z,1747847918.602 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T17:18:38.602Z,1747847918.602 [Default:CheckIn:D] Running Loop=1 2025-05-21T17:18:39.011Z,1747847919.011 [Default:CheckIn:D] Stopped 2025-05-21T17:18:39.011Z,1747847919.011 [Default:CheckIn:E] Running Loop=1 2025-05-21T17:18:39.406Z,1747847919.406 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 131.921183 min 2025-05-21T17:18:39.406Z,1747847919.406 [Default:CheckIn:E] Stopped 2025-05-21T17:18:39.406Z,1747847919.406 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T17:18:39.406Z,1747847919.406 [Default:CheckIn] Stopped 2025-05-21T17:18:39.406Z,1747847919.406 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T17:18:39.406Z,1747847919.406 [Default:CheckIn](INFO): Running loop #24 2025-05-21T17:18:39.406Z,1747847919.406 [Default:CheckIn] Running Loop=24 2025-05-21T17:18:39.407Z,1747847919.407 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T17:18:39.407Z,1747847919.407 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T17:18:41.422Z,1747847921.422 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171840.00,A,3648.16472,N,12147.27335,W,0.758,124.83,210525,,,A*7E 2025-05-21T17:18:41.424Z,1747847921.424 [NAL9602](INFO): GPS fix at 20250521T171840: (36.802745, -121.787889) 2025-05-21T17:18:41.435Z,1747847921.435 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T17:18:41.435Z,1747847921.435 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T17:18:51.150Z,1747847931.150 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250521T155143/Courier0048.lzma 2025-05-21T17:18:52.153Z,1747847932.153 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0048.lzma.bak 2025-05-21T17:18:52.153Z,1747847932.153 [DataOverHttps](INFO): SBD MOMSN=24944434 2025-05-21T17:19:10.591Z,1747847950.591 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250521T155143/Express0049.lzma 2025-05-21T17:19:11.593Z,1747847951.593 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0049.lzma.bak 2025-05-21T17:19:11.593Z,1747847951.593 [DataOverHttps](INFO): SBD MOMSN=24944438 2025-05-21T17:19:13.044Z,1747847953.044 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T17:19:13.044Z,1747847953.044 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T17:19:13.044Z,1747847953.044 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T17:19:13.395Z,1747847953.395 [NAL9602](INFO): Not Powering down - fast GPS 2025-05-21T17:20:12.750Z,1747848012.750 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:20:26.564Z,1747848026.564 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:20:39.894Z,1747848039.894 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:20:53.282Z,1747848053.282 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:21:07.014Z,1747848067.014 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:21:21.156Z,1747848081.156 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:21:21.162Z,1747848081.162 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-05-21T17:21:21.162Z,1747848081.162 [DropWeight] Hardware Fault, FailCount= 1 2025-05-21T17:21:21.162Z,1747848081.162 [DropWeight](ERROR): Hardware Fault 2025-05-21T17:21:21.180Z,1747848081.180 [CommandExec](FAULT): Scheduling is paused 2025-05-21T17:21:21.181Z,1747848081.181 [CBIT](INFO): Critical error at 20250521T172121 2025-05-21T17:21:21.181Z,1747848081.181 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-05-21T17:21:21.183Z,1747848081.183 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-05-21T17:21:21.184Z,1747848081.184 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-05-21T17:21:21.593Z,1747848081.593 [CBIT](INFO): Critical error at 20250521T172121 2025-05-21T17:21:33.680Z,1747848093.680 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:21:47.023Z,1747848107.023 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:22:00.746Z,1747848120.746 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:22:15.303Z,1747848135.303 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:22:28.690Z,1747848148.690 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2025-05-21T17:22:34.750Z,1747848154.750 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-05-21T17:24:13.815Z,1747848253.815 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-05-21T17:24:13.815Z,1747848253.815 [Default:CheckIn:C.Wait] Stopped 2025-05-21T17:24:13.816Z,1747848253.816 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T17:24:13.816Z,1747848253.816 [Default:CheckIn:D] Running Loop=1 2025-05-21T17:24:14.195Z,1747848254.195 [Default:CheckIn:D] Stopped 2025-05-21T17:24:14.195Z,1747848254.195 [Default:CheckIn:E] Running Loop=1 2025-05-21T17:24:14.600Z,1747848254.600 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.507585 min 2025-05-21T17:24:14.600Z,1747848254.600 [Default:CheckIn:E] Stopped 2025-05-21T17:24:14.601Z,1747848254.601 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-05-21T17:24:14.601Z,1747848254.601 [Default:CheckIn] Stopped 2025-05-21T17:24:14.601Z,1747848254.601 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T17:24:14.601Z,1747848254.601 [Default:CheckIn](INFO): Running loop #25 2025-05-21T17:24:14.601Z,1747848254.601 [Default:CheckIn] Running Loop=25 2025-05-21T17:24:14.601Z,1747848254.601 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-05-21T17:24:14.601Z,1747848254.601 [Default:CheckIn:Read_GPS] Running Loop=1 2025-05-21T17:24:16.675Z,1747848256.675 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172415.00,A,3648.16951,N,12147.27375,W,0.914,124.83,210525,,,A*7F 2025-05-21T17:24:16.677Z,1747848256.677 [NAL9602](INFO): GPS fix at 20250521T172415: (36.802825, -121.787896) 2025-05-21T17:24:16.688Z,1747848256.688 [Default:CheckIn:Read_GPS] Stopped 2025-05-21T17:24:16.688Z,1747848256.688 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-05-21T17:24:24.271Z,1747848264.271 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20250521T155143/Courier0051.lzma 2025-05-21T17:24:25.272Z,1747848265.272 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Courier0051.lzma.bak 2025-05-21T17:24:25.273Z,1747848265.273 [DataOverHttps](INFO): SBD MOMSN=24944448 2025-05-21T17:24:41.475Z,1747848281.475 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20250521T155143/Express0052.lzma 2025-05-21T17:24:42.477Z,1747848282.477 [DataOverHttps](INFO): Moved sent file to Logs/20250521T155143/Express0052.lzma.bak 2025-05-21T17:24:42.477Z,1747848282.477 [DataOverHttps](INFO): SBD MOMSN=24944453 2025-05-21T17:24:43.697Z,1747848283.697 [Default:CheckIn:Read_Iridium] Stopped 2025-05-21T17:24:43.697Z,1747848283.697 [Default:CheckIn:C.Wait] Running Loop=1 2025-05-21T17:24:43.697Z,1747848283.697 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-05-21T17:24:47.320Z,1747848287.320 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2025-05-21T17:24:47.403Z,1747848287.403 [NAL9602](ERROR): received: +CSQ:0 OK445, 2, 0, 0, 0 OK 2025-05-21T17:25:18.147Z,1747848318.147 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-05-21T17:25:28.598Z,1747848328.598 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-05-21T17:25:39.505Z,1747848339.505 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-05-21T17:25:50.413Z,1747848350.413 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-05-21T17:26:01.317Z,1747848361.317 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-05-21T17:26:12.224Z,1747848372.224 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-05-21T17:26:23.138Z,1747848383.138 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-05-21T17:26:34.450Z,1747848394.450 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-05-21T17:26:45.354Z,1747848405.354 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-05-21T17:26:56.261Z,1747848416.261 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2025-05-21T17:27:03.868Z,1747848423.868 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4. 2025-05-21T17:27:03.881Z,1747848423.881 [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-05-21T17:29:19.223Z,1747848559.223 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-05-21T17:29:34.464Z,1747848574.464 [CommandExec](IMPORTANT): got command quit 2025-05-21T17:29:35.471Z,1747848575.471 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-21T17:29:35.471Z,1747848575.471 [CommandExec](INFO): Uninitializing the command executive. 2025-05-21T17:29:35.471Z,1747848575.471 [CommandExec](INFO): Uninitializing the command scheduler. 2025-05-21T17:29:35.471Z,1747848575.471 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:35.558Z,1747848575.558 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-05-21T17:29:35.559Z,1747848575.559 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-05-21T17:29:35.559Z,1747848575.559 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:35.559Z,1747848575.559 [NavChartDb](INFO): Join timeout helper Thread ID is 7527 2025-05-21T17:29:35.911Z,1747848575.911 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-21T17:29:35.911Z,1747848575.911 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:35.930Z,1747848575.930 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-05-21T17:29:35.931Z,1747848575.931 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:35.931Z,1747848575.931 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7528 2025-05-21T17:29:36.119Z,1747848576.119 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-21T17:29:36.119Z,1747848576.119 [WetLabsBB2FL](INFO): Powering down 2025-05-21T17:29:36.120Z,1747848576.120 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:36.130Z,1747848576.130 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-05-21T17:29:36.131Z,1747848576.131 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:36.131Z,1747848576.131 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7529 2025-05-21T17:29:36.243Z,1747848576.243 [CTD_Seabird](INFO): Powering down 2025-05-21T17:29:36.255Z,1747848576.255 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-21T17:29:36.255Z,1747848576.255 [CTD_Seabird](INFO): Powering down 2025-05-21T17:29:36.267Z,1747848576.267 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:36.283Z,1747848576.283 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-05-21T17:29:36.283Z,1747848576.283 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:36.283Z,1747848576.283 [Radio_Surface](INFO): Join timeout helper Thread ID is 7530 2025-05-21T17:29:36.403Z,1747848576.403 [Radio_Surface](INFO): Powering down 2025-05-21T17:29:36.404Z,1747848576.404 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-21T17:29:36.404Z,1747848576.404 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:36.425Z,1747848576.425 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-05-21T17:29:36.425Z,1747848576.425 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:36.425Z,1747848576.425 [Onboard](INFO): Join timeout helper Thread ID is 7531 2025-05-21T17:29:37.179Z,1747848577.179 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-21T17:29:37.179Z,1747848577.179 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:37.205Z,1747848577.205 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-05-21T17:29:37.205Z,1747848577.205 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:37.206Z,1747848577.206 [DataOverHttps](INFO): Join timeout helper Thread ID is 7532 2025-05-21T17:29:37.395Z,1747848577.395 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-21T17:29:37.395Z,1747848577.395 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:37.399Z,1747848577.399 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-05-21T17:29:37.399Z,1747848577.399 [DAT ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:37.403Z,1747848577.403 [DAT](INFO): Join timeout helper Thread ID is 7533 2025-05-21T17:29:37.495Z,1747848577.495 [DAT](INFO): Powering down 2025-05-21T17:29:37.567Z,1747848577.567 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-21T17:29:37.567Z,1747848577.567 [DAT](INFO): Powering down 2025-05-21T17:29:37.568Z,1747848577.568 [DAT ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:37.583Z,1747848577.583 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-05-21T17:29:37.583Z,1747848577.583 [logger ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:37.584Z,1747848577.584 [logger](INFO): Join timeout helper Thread ID is 7534 2025-05-21T17:29:37.615Z,1747848577.615 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-21T17:29:37.615Z,1747848577.615 [logger ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:37.624Z,1747848577.624 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-05-21T17:29:37.624Z,1747848577.624 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:37.624Z,1747848577.624 [CommandLine](INFO): Join timeout helper Thread ID is 7535 2025-05-21T17:29:37.703Z,1747848577.703 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-21T17:29:37.703Z,1747848577.703 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:37.723Z,1747848577.723 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-05-21T17:29:37.723Z,1747848577.723 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:37.724Z,1747848577.724 [CommandExec](INFO): Join timeout helper Thread ID is 7536 2025-05-21T17:29:37.725Z,1747848577.725 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-05-21T17:29:37.725Z,1747848577.725 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:37.725Z,1747848577.725 [controlThread](INFO): Join timeout helper Thread ID is 7537 2025-05-21T17:29:37.787Z,1747848577.787 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-05-21T17:29:37.787Z,1747848577.787 [controlThread](DEBUG): Uninitializing ControlThread 2025-05-21T17:29:37.787Z,1747848577.787 [AHRS_M2](INFO): Powering down 2025-05-21T17:29:37.867Z,1747848577.867 [NAL9602](INFO): Powering down 2025-05-21T17:29:37.868Z,1747848577.868 [Sonardyne_Nano](INFO): Powering down 2025-05-21T17:29:38.071Z,1747848578.071 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-05-21T17:29:38.072Z,1747848578.072 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-05-21T17:29:38.072Z,1747848578.072 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-05-21T17:29:38.073Z,1747848578.073 [MissionManager](INFO): Uninitializing Mission Default 2025-05-21T17:29:38.073Z,1747848578.073 [Default] Stopped 2025-05-21T17:29:38.073Z,1747848578.073 [Default](DEBUG): Aggregate::uninitialize Default 2025-05-21T17:29:38.073Z,1747848578.073 [Default:B.GoToSurface] Stopped 2025-05-21T17:29:38.073Z,1747848578.073 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-05-21T17:29:38.073Z,1747848578.073 [Default:CheckIn] Stopped 2025-05-21T17:29:38.073Z,1747848578.073 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-05-21T17:29:38.073Z,1747848578.073 [Default:CheckIn:C.Wait] Stopped 2025-05-21T17:29:38.073Z,1747848578.073 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-05-21T17:29:38.076Z,1747848578.076 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-05-21T17:29:38.077Z,1747848578.077 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-05-21T17:29:38.077Z,1747848578.077 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-05-21T17:29:38.077Z,1747848578.077 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-05-21T17:29:38.077Z,1747848578.077 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-05-21T17:29:38.078Z,1747848578.078 [BuoyancyServo](INFO): Powering down 2025-05-21T17:29:38.091Z,1747848578.091 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-05-21T17:29:38.091Z,1747848578.091 [ElevatorServo](INFO): Powering down 2025-05-21T17:29:38.092Z,1747848578.092 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-05-21T17:29:38.092Z,1747848578.092 [MassServo](INFO): Powering down 2025-05-21T17:29:38.092Z,1747848578.092 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-05-21T17:29:38.093Z,1747848578.093 [RudderServo](INFO): Powering down 2025-05-21T17:29:38.093Z,1747848578.093 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-05-21T17:29:38.093Z,1747848578.093 [ThrusterHE](INFO): Powering down 2025-05-21T17:29:38.094Z,1747848578.094 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-05-21T17:29:38.095Z,1747848578.095 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-05-21T17:29:38.095Z,1747848578.095 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-05-21T17:29:38.095Z,1747848578.095 [CBIT](DEBUG): Powering off loads. 2025-05-21T17:29:38.106Z,1747848578.106 [CBIT](DEBUG): Disabling WDT. 2025-05-21T17:29:38.119Z,1747848578.119 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-05-21T17:29:38.119Z,1747848578.119 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-05-21T17:29:38.120Z,1747848578.120 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:38.175Z,1747848578.175 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:38.179Z,1747848578.179 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:38.185Z,1747848578.185 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:38.194Z,1747848578.194 [DAT ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:38.267Z,1747848578.267 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:38.272Z,1747848578.272 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:38.296Z,1747848578.296 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-05-21T17:29:38.296Z,1747848578.296 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-05-21T17:29:38.317Z,1747848578.317 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-05-21T17:29:38.431Z,1747848578.431 [logger ThreadHandler](INFO): Thread cancelled.