2025-06-11T17:49:10.004Z,1749664150.004 [DataOverHttps](INFO): Received command: restart logs
2025-06-11T17:49:10.009Z,1749664150.009 [CommandExec](IMPORTANT): got command restart logs
2025-06-11T17:50:34.377Z,1749664234.377 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T17:50:34.381Z,1749664234.381 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-06-11T17:50:48.514Z,1749664248.514 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T17:51:01.846Z,1749664261.846 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T17:51:15.579Z,1749664275.579 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T17:51:22.074Z,1749664282.074 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T17:51:22.074Z,1749664282.074 [Default:CheckIn:C.Wait] Stopped
2025-06-11T17:51:22.074Z,1749664282.074 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T17:51:22.075Z,1749664282.075 [Default:CheckIn:D] Running Loop=1
2025-06-11T17:51:22.462Z,1749664282.462 [Default:CheckIn:D] Stopped
2025-06-11T17:51:22.462Z,1749664282.462 [Default:CheckIn:E] Running Loop=1
2025-06-11T17:51:22.865Z,1749664282.865 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  13.387915 min 
2025-06-11T17:51:22.865Z,1749664282.865 [Default:CheckIn:E] Stopped
2025-06-11T17:51:22.865Z,1749664282.865 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T17:51:22.865Z,1749664282.865 [Default:CheckIn] Stopped
2025-06-11T17:51:22.866Z,1749664282.866 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T17:51:22.866Z,1749664282.866 [Default:CheckIn](INFO): Running loop #3
2025-06-11T17:51:22.866Z,1749664282.866 [Default:CheckIn] Running Loop=3
2025-06-11T17:51:22.866Z,1749664282.866 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T17:51:22.866Z,1749664282.866 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T17:51:24.875Z,1749664284.875 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175124.00,A,3648.17679,N,12149.26110,W,0.680,114.76,110625,,,D*7B
2025-06-11T17:51:24.885Z,1749664284.885 [NAL9602](INFO): GPS fix at 20250611T175124: (36.802946, -121.821018)
2025-06-11T17:51:24.901Z,1749664284.901 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T17:51:24.901Z,1749664284.901 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T17:51:29.323Z,1749664289.323 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T17:51:32.487Z,1749664292.487 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250610T203547/Courier0108.lzma
2025-06-11T17:51:33.490Z,1749664293.490 [DataOverHttps](INFO): Moved sent file to Logs/20250610T203547/Courier0108.lzma.bak
2025-06-11T17:51:33.490Z,1749664293.490 [DataOverHttps](INFO): SBD MOMSN=25192235
2025-06-11T17:51:42.663Z,1749664302.663 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T17:51:49.624Z,1749664309.624 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20250611T174910/Courier0000.lzma
2025-06-11T17:51:50.625Z,1749664310.625 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0000.lzma.bak
2025-06-11T17:51:50.625Z,1749664310.625 [DataOverHttps](INFO): SBD MOMSN=25192237
2025-06-11T17:51:51.968Z,1749664311.968 [NAL9602](INFO): SBD MO Status=2, MOMSN=63728, MT Status=2, MTMSN=0
2025-06-11T17:51:51.968Z,1749664311.968 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-11T17:51:55.996Z,1749664315.996 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T17:52:06.967Z,1749664326.967 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250611T174910/Courier0003.lzma
2025-06-11T17:52:07.969Z,1749664327.969 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0003.lzma.bak
2025-06-11T17:52:07.969Z,1749664327.969 [DataOverHttps](INFO): SBD MOMSN=25192246
2025-06-11T17:52:09.731Z,1749664329.731 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T17:52:23.899Z,1749664343.899 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T17:52:27.087Z,1749664347.087 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20250610T203547/Express0109.lzma
2025-06-11T17:52:28.092Z,1749664348.092 [DataOverHttps](INFO): Moved sent file to Logs/20250610T203547/Express0109.lzma.bak
2025-06-11T17:52:28.093Z,1749664348.093 [DataOverHttps](INFO): SBD MOMSN=25192248
2025-06-11T17:52:29.175Z,1749664349.175 [NAL9602](INFO): SBD MO Status=0, MOMSN=63728, MT Status=0, MTMSN=0
2025-06-11T17:52:29.175Z,1749664349.175 [NAL9602](INFO): No messages in MT queue
2025-06-11T17:52:37.283Z,1749664357.283 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T17:52:44.168Z,1749664364.168 [DataOverHttps](INFO): Sending 494 bytes from file Logs/20250611T174910/Express0001.lzma
2025-06-11T17:52:45.169Z,1749664365.169 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0001.lzma.bak
2025-06-11T17:52:45.169Z,1749664365.169 [DataOverHttps](INFO): SBD MOMSN=25192252
2025-06-11T17:52:51.031Z,1749664371.031 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T17:52:59.973Z,1749664379.973 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T17:53:04.715Z,1749664384.715 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250611T174910/Express0004.lzma
2025-06-11T17:53:05.717Z,1749664385.717 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0004.lzma.bak
2025-06-11T17:53:05.717Z,1749664385.717 [DataOverHttps](INFO): SBD MOMSN=25192256
2025-06-11T17:53:06.852Z,1749664386.852 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T17:53:06.852Z,1749664386.852 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T17:53:06.852Z,1749664386.852 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T17:57:39.959Z,1749664659.959 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-11T17:58:07.465Z,1749664687.465 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T17:58:07.465Z,1749664687.465 [Default:CheckIn:C.Wait] Stopped
2025-06-11T17:58:07.465Z,1749664687.465 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T17:58:07.466Z,1749664687.466 [Default:CheckIn:D] Running Loop=1
2025-06-11T17:58:07.862Z,1749664687.862 [Default:CheckIn:D] Stopped
2025-06-11T17:58:07.862Z,1749664687.862 [Default:CheckIn:E] Running Loop=1
2025-06-11T17:58:08.254Z,1749664688.254 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  20.144586 min 
2025-06-11T17:58:08.254Z,1749664688.254 [Default:CheckIn:E] Stopped
2025-06-11T17:58:08.254Z,1749664688.254 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T17:58:08.255Z,1749664688.255 [Default:CheckIn] Stopped
2025-06-11T17:58:08.255Z,1749664688.255 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T17:58:08.255Z,1749664688.255 [Default:CheckIn](INFO): Running loop #4
2025-06-11T17:58:08.255Z,1749664688.255 [Default:CheckIn] Running Loop=4
2025-06-11T17:58:08.255Z,1749664688.255 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T17:58:08.255Z,1749664688.255 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T17:58:10.271Z,1749664690.271 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175809.00,A,3648.17390,N,12147.79056,W,21.188,68.97,110625,,,D*71
2025-06-11T17:58:10.273Z,1749664690.273 [NAL9602](INFO): GPS fix at 20250611T175809: (36.802898, -121.796509)
2025-06-11T17:58:10.284Z,1749664690.284 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T17:58:10.284Z,1749664690.284 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T17:58:17.575Z,1749664697.575 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250611T174910/Courier0006.lzma
2025-06-11T17:58:18.577Z,1749664698.577 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0006.lzma.bak
2025-06-11T17:58:18.578Z,1749664698.578 [DataOverHttps](INFO): SBD MOMSN=25192299
2025-06-11T17:58:25.664Z,1749664705.664 [NAL9602](INFO): SBD MO Status=0, MOMSN=63729, MT Status=0, MTMSN=0
2025-06-11T17:58:25.664Z,1749664705.664 [NAL9602](INFO): No messages in MT queue
2025-06-11T17:58:37.788Z,1749664717.788 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-06-11T17:58:37.919Z,1749664717.919 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20250611T174910/Express0007.lzma
2025-06-11T17:58:38.921Z,1749664718.921 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0007.lzma.bak
2025-06-11T17:58:38.921Z,1749664718.921 [DataOverHttps](INFO): SBD MOMSN=25192302
2025-06-11T17:58:40.291Z,1749664720.291 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T17:58:40.292Z,1749664720.292 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T17:58:40.292Z,1749664720.292 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T17:58:56.387Z,1749664736.387 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T18:00:01.011Z,1749664801.011 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-11T18:00:07.073Z,1749664807.073 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:00:20.403Z,1749664820.403 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:00:33.736Z,1749664833.736 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:00:47.068Z,1749664847.068 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:01:00.411Z,1749664860.411 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:01:14.148Z,1749664874.148 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:01:27.885Z,1749664887.885 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:01:41.619Z,1749664901.619 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:01:55.358Z,1749664915.358 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:02:08.688Z,1749664928.688 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:02:22.047Z,1749664942.047 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:03:40.879Z,1749665020.879 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T18:03:40.879Z,1749665020.879 [Default:CheckIn:C.Wait] Stopped
2025-06-11T18:03:40.879Z,1749665020.879 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T18:03:40.880Z,1749665020.880 [Default:CheckIn:D] Running Loop=1
2025-06-11T18:03:41.279Z,1749665021.279 [Default:CheckIn:D] Stopped
2025-06-11T18:03:41.279Z,1749665021.279 [Default:CheckIn:E] Running Loop=1
2025-06-11T18:03:41.735Z,1749665021.735 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  25.701530 min 
2025-06-11T18:03:41.735Z,1749665021.735 [Default:CheckIn:E] Stopped
2025-06-11T18:03:41.735Z,1749665021.735 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T18:03:41.735Z,1749665021.735 [Default:CheckIn] Stopped
2025-06-11T18:03:41.736Z,1749665021.736 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T18:03:41.736Z,1749665021.736 [Default:CheckIn](INFO): Running loop #5
2025-06-11T18:03:41.736Z,1749665021.736 [Default:CheckIn] Running Loop=5
2025-06-11T18:03:41.736Z,1749665021.736 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T18:03:41.736Z,1749665021.736 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T18:03:43.692Z,1749665023.692 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180342.00,A,3648.48699,N,12147.12522,W,3.674,176.31,110625,,,D*74
2025-06-11T18:03:43.695Z,1749665023.695 [NAL9602](INFO): GPS fix at 20250611T180342: (36.808116, -121.785420)
2025-06-11T18:03:43.706Z,1749665023.706 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T18:03:43.706Z,1749665023.706 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T18:03:50.939Z,1749665030.939 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250611T174910/Courier0009.lzma
2025-06-11T18:03:51.941Z,1749665031.941 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0009.lzma.bak
2025-06-11T18:03:51.941Z,1749665031.941 [DataOverHttps](INFO): SBD MOMSN=25192392
2025-06-11T18:04:08.303Z,1749665048.303 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250611T174910/Express0010.lzma
2025-06-11T18:04:09.329Z,1749665049.329 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0010.lzma.bak
2025-06-11T18:04:09.329Z,1749665049.329 [DataOverHttps](INFO): SBD MOMSN=25192397
2025-06-11T18:04:10.776Z,1749665050.776 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T18:04:10.776Z,1749665050.776 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T18:04:10.776Z,1749665050.776 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T18:04:16.822Z,1749665056.822 [NAL9602](INFO): SBD MO Status=2, MOMSN=63730, MT Status=2, MTMSN=0
2025-06-11T18:04:16.822Z,1749665056.822 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-11T18:04:26.530Z,1749665066.530 [NAL9602](INFO): SBD MO Status=0, MOMSN=63730, MT Status=0, MTMSN=0
2025-06-11T18:04:26.530Z,1749665066.530 [NAL9602](INFO): No messages in MT queue
2025-06-11T18:04:57.226Z,1749665097.226 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T18:09:11.483Z,1749665351.483 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T18:09:11.483Z,1749665351.483 [Default:CheckIn:C.Wait] Stopped
2025-06-11T18:09:11.484Z,1749665351.484 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T18:09:11.484Z,1749665351.484 [Default:CheckIn:D] Running Loop=1
2025-06-11T18:09:11.903Z,1749665351.903 [Default:CheckIn:D] Stopped
2025-06-11T18:09:11.907Z,1749665351.907 [Default:CheckIn:E] Running Loop=1
2025-06-11T18:09:12.312Z,1749665352.312 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  31.211930 min 
2025-06-11T18:09:12.312Z,1749665352.312 [Default:CheckIn:E] Stopped
2025-06-11T18:09:12.312Z,1749665352.312 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T18:09:12.312Z,1749665352.312 [Default:CheckIn] Stopped
2025-06-11T18:09:12.312Z,1749665352.312 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T18:09:12.312Z,1749665352.312 [Default:CheckIn](INFO): Running loop #6
2025-06-11T18:09:12.312Z,1749665352.312 [Default:CheckIn] Running Loop=6
2025-06-11T18:09:12.312Z,1749665352.312 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T18:09:12.313Z,1749665352.313 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T18:09:14.303Z,1749665354.303 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180913.00,A,3648.16825,N,12147.20453,W,2.566,219.48,110625,,,D*7B
2025-06-11T18:09:14.305Z,1749665354.305 [NAL9602](INFO): GPS fix at 20250611T180913: (36.802804, -121.786742)
2025-06-11T18:09:14.316Z,1749665354.316 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T18:09:14.316Z,1749665354.316 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T18:09:22.119Z,1749665362.119 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250611T174910/Courier0012.lzma
2025-06-11T18:09:23.121Z,1749665363.121 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0012.lzma.bak
2025-06-11T18:09:23.122Z,1749665363.122 [DataOverHttps](INFO): SBD MOMSN=25192449
2025-06-11T18:09:34.091Z,1749665374.091 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-11T18:09:39.272Z,1749665379.272 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250611T174910/Express0013.lzma
2025-06-11T18:09:39.751Z,1749665379.751 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:09:40.273Z,1749665380.273 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0013.lzma.bak
2025-06-11T18:09:40.273Z,1749665380.273 [DataOverHttps](INFO): SBD MOMSN=25192461
2025-06-11T18:09:41.396Z,1749665381.396 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T18:09:41.396Z,1749665381.396 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T18:09:41.396Z,1749665381.396 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T18:09:46.626Z,1749665386.626 [NAL9602](INFO): SBD MO Status=0, MOMSN=63731, MT Status=0, MTMSN=0
2025-06-11T18:09:46.626Z,1749665386.626 [NAL9602](INFO): No messages in MT queue
2025-06-11T18:09:53.487Z,1749665393.487 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:10:06.819Z,1749665406.819 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:10:17.524Z,1749665417.524 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T18:10:20.162Z,1749665420.162 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:10:33.500Z,1749665433.500 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:10:47.236Z,1749665447.236 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:11:01.395Z,1749665461.395 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:11:15.131Z,1749665475.131 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:11:28.463Z,1749665488.463 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:11:42.603Z,1749665502.603 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:11:56.352Z,1749665516.352 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:14:42.164Z,1749665682.164 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T18:14:42.165Z,1749665682.165 [Default:CheckIn:C.Wait] Stopped
2025-06-11T18:14:42.165Z,1749665682.165 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T18:14:42.165Z,1749665682.165 [Default:CheckIn:D] Running Loop=1
2025-06-11T18:14:42.579Z,1749665682.579 [Default:CheckIn:D] Stopped
2025-06-11T18:14:42.579Z,1749665682.579 [Default:CheckIn:E] Running Loop=1
2025-06-11T18:14:42.980Z,1749665682.980 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  36.723193 min 
2025-06-11T18:14:42.980Z,1749665682.980 [Default:CheckIn:E] Stopped
2025-06-11T18:14:42.980Z,1749665682.980 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T18:14:42.980Z,1749665682.980 [Default:CheckIn] Stopped
2025-06-11T18:14:42.980Z,1749665682.980 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T18:14:42.980Z,1749665682.980 [Default:CheckIn](INFO): Running loop #7
2025-06-11T18:14:42.980Z,1749665682.980 [Default:CheckIn] Running Loop=7
2025-06-11T18:14:42.980Z,1749665682.980 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T18:14:42.980Z,1749665682.980 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T18:14:44.986Z,1749665684.986 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181444.00,A,3648.15169,N,12147.21818,W,0.039,208.86,110625,,,D*7A
2025-06-11T18:14:44.988Z,1749665684.988 [NAL9602](INFO): GPS fix at 20250611T181444: (36.802528, -121.786970)
2025-06-11T18:14:44.000Z,1749665685.000 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T18:14:44.000Z,1749665685.000 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T18:14:55.939Z,1749665695.939 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250611T174910/Courier0015.lzma
2025-06-11T18:14:56.941Z,1749665696.941 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0015.lzma.bak
2025-06-11T18:14:56.942Z,1749665696.942 [DataOverHttps](INFO): SBD MOMSN=25192524
2025-06-11T18:14:57.155Z,1749665697.155 [NAL9602](INFO): SBD MO Status=0, MOMSN=63732, MT Status=0, MTMSN=0
2025-06-11T18:14:57.155Z,1749665697.155 [NAL9602](INFO): No messages in MT queue
2025-06-11T18:15:13.400Z,1749665713.400 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250611T174910/Express0016.lzma
2025-06-11T18:15:14.401Z,1749665714.401 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0016.lzma.bak
2025-06-11T18:15:14.401Z,1749665714.401 [DataOverHttps](INFO): SBD MOMSN=25192527
2025-06-11T18:15:15.755Z,1749665715.755 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T18:15:15.776Z,1749665715.776 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T18:15:15.776Z,1749665715.776 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T18:15:27.876Z,1749665727.876 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T18:16:36.596Z,1749665796.596 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-06-11T18:16:36.599Z,1749665796.599 [BPC1](FAULT): Failed to receive data from 5 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 33, 36, 50, 51, 54.
2025-06-11T18:20:16.153Z,1749666016.153 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T18:20:16.153Z,1749666016.153 [Default:CheckIn:C.Wait] Stopped
2025-06-11T18:20:16.153Z,1749666016.153 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T18:20:16.154Z,1749666016.154 [Default:CheckIn:D] Running Loop=1
2025-06-11T18:20:16.569Z,1749666016.569 [Default:CheckIn:D] Stopped
2025-06-11T18:20:16.569Z,1749666016.569 [Default:CheckIn:E] Running Loop=1
2025-06-11T18:20:17.018Z,1749666017.018 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  42.289689 min 
2025-06-11T18:20:17.018Z,1749666017.018 [Default:CheckIn:E] Stopped
2025-06-11T18:20:17.018Z,1749666017.018 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T18:20:17.018Z,1749666017.018 [Default:CheckIn] Stopped
2025-06-11T18:20:17.019Z,1749666017.019 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T18:20:17.019Z,1749666017.019 [Default:CheckIn](INFO): Running loop #8
2025-06-11T18:20:17.019Z,1749666017.019 [Default:CheckIn] Running Loop=8
2025-06-11T18:20:17.019Z,1749666017.019 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T18:20:17.019Z,1749666017.019 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T18:20:18.974Z,1749666018.974 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182018.00,A,3648.15150,N,12147.21947,W,0.078,208.86,110625,,,A*75
2025-06-11T18:20:18.985Z,1749666018.985 [NAL9602](INFO): GPS fix at 20250611T182018: (36.802525, -121.786991)
2025-06-11T18:20:18.996Z,1749666018.996 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T18:20:18.996Z,1749666018.996 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T18:20:29.661Z,1749666029.661 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20250611T174910/Courier0018.lzma
2025-06-11T18:20:30.661Z,1749666030.661 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0018.lzma.bak
2025-06-11T18:20:30.661Z,1749666030.661 [DataOverHttps](INFO): SBD MOMSN=25192606
2025-06-11T18:20:46.796Z,1749666046.796 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20250611T174910/Express0019.lzma
2025-06-11T18:20:50.814Z,1749666050.814 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0019.lzma.bak
2025-06-11T18:20:50.814Z,1749666050.814 [DataOverHttps](INFO): SBD MOMSN=25192609
2025-06-11T18:20:52.159Z,1749666052.159 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T18:20:52.160Z,1749666052.160 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T18:20:52.160Z,1749666052.160 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T18:21:01.038Z,1749666061.038 [NAL9602](INFO): SBD MO Status=0, MOMSN=63733, MT Status=0, MTMSN=0
2025-06-11T18:21:01.038Z,1749666061.038 [NAL9602](INFO): No messages in MT queue
2025-06-11T18:21:31.747Z,1749666091.747 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T18:25:52.752Z,1749666352.752 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T18:25:52.753Z,1749666352.753 [Default:CheckIn:C.Wait] Stopped
2025-06-11T18:25:52.753Z,1749666352.753 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T18:25:52.753Z,1749666352.753 [Default:CheckIn:D] Running Loop=1
2025-06-11T18:25:53.151Z,1749666353.151 [Default:CheckIn:D] Stopped
2025-06-11T18:25:53.151Z,1749666353.151 [Default:CheckIn:E] Running Loop=1
2025-06-11T18:25:53.552Z,1749666353.552 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  47.899390 min 
2025-06-11T18:25:53.552Z,1749666353.552 [Default:CheckIn:E] Stopped
2025-06-11T18:25:53.552Z,1749666353.552 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T18:25:53.552Z,1749666353.552 [Default:CheckIn] Stopped
2025-06-11T18:25:53.552Z,1749666353.552 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T18:25:53.553Z,1749666353.553 [Default:CheckIn](INFO): Running loop #9
2025-06-11T18:25:53.553Z,1749666353.553 [Default:CheckIn] Running Loop=9
2025-06-11T18:25:53.553Z,1749666353.553 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T18:25:53.553Z,1749666353.553 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T18:25:55.568Z,1749666355.568 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182554.00,A,3648.15624,N,12147.24988,W,2.430,36.57,110625,,,A*43
2025-06-11T18:25:55.570Z,1749666355.570 [NAL9602](INFO): GPS fix at 20250611T182554: (36.802604, -121.787498)
2025-06-11T18:25:55.581Z,1749666355.581 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T18:25:55.581Z,1749666355.581 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T18:26:02.719Z,1749666362.719 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250611T174910/Courier0021.lzma
2025-06-11T18:26:03.721Z,1749666363.721 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0021.lzma.bak
2025-06-11T18:26:03.721Z,1749666363.721 [DataOverHttps](INFO): SBD MOMSN=25192631
2025-06-11T18:26:20.266Z,1749666380.266 [NAL9602](INFO): SBD MO Status=0, MOMSN=63734, MT Status=0, MTMSN=0
2025-06-11T18:26:20.266Z,1749666380.266 [NAL9602](INFO): No messages in MT queue
2025-06-11T18:26:21.467Z,1749666381.467 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20250611T174910/Express0022.lzma
2025-06-11T18:26:22.469Z,1749666382.469 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0022.lzma.bak
2025-06-11T18:26:22.470Z,1749666382.470 [DataOverHttps](INFO): SBD MOMSN=25192634
2025-06-11T18:26:23.915Z,1749666383.915 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T18:26:23.915Z,1749666383.915 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T18:26:23.915Z,1749666383.915 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T18:26:50.948Z,1749666410.948 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T18:27:24.164Z,1749666444.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T18:27:43.867Z,1749666463.867 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:27:55.288Z,1749666475.288 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T18:28:26.428Z,1749666506.428 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T18:28:57.564Z,1749666537.564 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T18:29:28.688Z,1749666568.688 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T18:29:33.708Z,1749666573.708 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.747421
2025-06-11T18:30:29.944Z,1749666629.944 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T18:31:01.068Z,1749666661.068 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T18:31:24.482Z,1749666684.482 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T18:31:24.483Z,1749666684.483 [Default:CheckIn:C.Wait] Stopped
2025-06-11T18:31:24.483Z,1749666684.483 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T18:31:24.483Z,1749666684.483 [Default:CheckIn:D] Running Loop=1
2025-06-11T18:31:24.871Z,1749666684.871 [Default:CheckIn:D] Stopped
2025-06-11T18:31:24.871Z,1749666684.871 [Default:CheckIn:E] Running Loop=1
2025-06-11T18:31:25.271Z,1749666685.271 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  53.428056 min 
2025-06-11T18:31:25.271Z,1749666685.271 [Default:CheckIn:E] Stopped
2025-06-11T18:31:25.288Z,1749666685.288 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T18:31:25.288Z,1749666685.288 [Default:CheckIn] Stopped
2025-06-11T18:31:25.288Z,1749666685.288 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T18:31:25.288Z,1749666685.288 [Default:CheckIn](INFO): Running loop #10
2025-06-11T18:31:25.288Z,1749666685.288 [Default:CheckIn] Running Loop=10
2025-06-11T18:31:25.289Z,1749666685.289 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T18:31:25.289Z,1749666685.289 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T18:31:27.286Z,1749666687.286 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183126.00,A,3648.18229,N,12147.28004,W,0.117,285.63,110625,,,A*79
2025-06-11T18:31:27.289Z,1749666687.289 [NAL9602](INFO): GPS fix at 20250611T183126: (36.803038, -121.788001)
2025-06-11T18:31:27.300Z,1749666687.300 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T18:31:27.300Z,1749666687.300 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T18:31:32.200Z,1749666692.200 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T18:31:39.492Z,1749666699.492 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:31:49.594Z,1749666709.594 [NAL9602](INFO): SBD MO Status=1, MOMSN=63735, MT Status=0, MTMSN=0
2025-06-11T18:31:49.644Z,1749666709.644 [NAL9602](INFO): Sent 73 bytes from file Logs/20250611T174910/Courier0024.lzma
2025-06-11T18:31:49.644Z,1749666709.644 [NAL9602](INFO): Packets left to send: 0
2025-06-11T18:31:53.279Z,1749666713.279 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:31:58.942Z,1749666718.942 [NAL9602](INFO): SBD MO Status=1, MOMSN=63736, MT Status=0, MTMSN=0
2025-06-11T18:31:58.992Z,1749666718.992 [NAL9602](INFO): Sent 169 bytes from file Logs/20250611T174910/Express0025.lzma
2025-06-11T18:31:58.992Z,1749666718.992 [NAL9602](INFO): Packets left to send: 0
2025-06-11T18:32:03.324Z,1749666723.324 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-11T18:32:05.810Z,1749666725.810 [NAL9602](INFO): SBD MO Status=0, MOMSN=63737, MT Status=0, MTMSN=0
2025-06-11T18:32:05.908Z,1749666725.908 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T18:32:05.908Z,1749666725.908 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T18:32:05.909Z,1749666725.909 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T18:32:06.614Z,1749666726.614 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:32:20.378Z,1749666740.378 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:32:23.156Z,1749666743.156 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003081
2025-06-11T18:32:33.699Z,1749666753.699 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:32:36.536Z,1749666756.536 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T18:32:47.048Z,1749666767.048 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:33:00.381Z,1749666780.381 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:33:13.713Z,1749666793.713 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:33:27.043Z,1749666807.043 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:33:40.375Z,1749666820.375 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:33:54.113Z,1749666834.113 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:37:06.453Z,1749667026.453 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T18:37:06.453Z,1749667026.453 [Default:CheckIn:C.Wait] Stopped
2025-06-11T18:37:06.453Z,1749667026.453 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T18:37:06.453Z,1749667026.453 [Default:CheckIn:D] Running Loop=1
2025-06-11T18:37:06.860Z,1749667026.860 [Default:CheckIn:D] Stopped
2025-06-11T18:37:06.860Z,1749667026.860 [Default:CheckIn:E] Running Loop=1
2025-06-11T18:37:07.276Z,1749667027.276 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  59.127877 min 
2025-06-11T18:37:07.276Z,1749667027.276 [Default:CheckIn:E] Stopped
2025-06-11T18:37:07.276Z,1749667027.276 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T18:37:07.276Z,1749667027.276 [Default:CheckIn] Stopped
2025-06-11T18:37:07.276Z,1749667027.276 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T18:37:07.276Z,1749667027.276 [Default:CheckIn](INFO): Running loop #11
2025-06-11T18:37:07.277Z,1749667027.277 [Default:CheckIn] Running Loop=11
2025-06-11T18:37:07.277Z,1749667027.277 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T18:37:07.277Z,1749667027.277 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T18:37:09.288Z,1749667029.288 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183708.00,A,3648.17410,N,12147.28088,W,0.156,285.63,110625,,,A*71
2025-06-11T18:37:09.290Z,1749667029.290 [NAL9602](INFO): GPS fix at 20250611T183708: (36.802902, -121.788015)
2025-06-11T18:37:09.301Z,1749667029.301 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T18:37:09.301Z,1749667029.301 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T18:37:17.159Z,1749667037.159 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250611T174910/Courier0027.lzma
2025-06-11T18:37:18.161Z,1749667038.161 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0027.lzma.bak
2025-06-11T18:37:18.161Z,1749667038.161 [DataOverHttps](INFO): SBD MOMSN=25192730
2025-06-11T18:37:34.388Z,1749667054.388 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250611T174910/Express0028.lzma
2025-06-11T18:37:35.389Z,1749667055.389 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0028.lzma.bak
2025-06-11T18:37:35.389Z,1749667055.389 [DataOverHttps](INFO): SBD MOMSN=25192733
2025-06-11T18:37:36.789Z,1749667056.789 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T18:37:36.790Z,1749667056.790 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T18:37:36.790Z,1749667056.790 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T18:37:57.368Z,1749667077.368 [NAL9602](INFO): SBD MO Status=2, MOMSN=63738, MT Status=2, MTMSN=0
2025-06-11T18:37:57.368Z,1749667077.368 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-11T18:38:41.190Z,1749667121.190 [NAL9602](INFO): SBD MO Status=2, MOMSN=63738, MT Status=2, MTMSN=0
2025-06-11T18:38:41.190Z,1749667121.190 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-11T18:38:56.428Z,1749667136.428 [NAL9602](INFO): SBD MO Status=0, MOMSN=63738, MT Status=0, MTMSN=0
2025-06-11T18:38:56.428Z,1749667136.428 [NAL9602](INFO): No messages in MT queue
2025-06-11T18:39:27.130Z,1749667167.130 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T18:39:44.897Z,1749667184.897 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-06-11T18:41:04.887Z,1749667264.887 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-11T18:41:10.560Z,1749667270.560 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:41:23.888Z,1749667283.888 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:41:37.220Z,1749667297.220 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:41:50.552Z,1749667310.552 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:42:03.884Z,1749667323.884 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:42:17.220Z,1749667337.220 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:42:30.547Z,1749667350.547 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:42:37.427Z,1749667357.427 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T18:42:37.427Z,1749667357.427 [Default:CheckIn:C.Wait] Stopped
2025-06-11T18:42:37.427Z,1749667357.427 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T18:42:37.428Z,1749667357.428 [Default:CheckIn:D] Running Loop=1
2025-06-11T18:42:37.833Z,1749667357.833 [Default:CheckIn:D] Stopped
2025-06-11T18:42:37.833Z,1749667357.833 [Default:CheckIn:E] Running Loop=1
2025-06-11T18:42:38.254Z,1749667358.254 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  64.644100 min 
2025-06-11T18:42:38.254Z,1749667358.254 [Default:CheckIn:E] Stopped
2025-06-11T18:42:38.254Z,1749667358.254 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T18:42:38.254Z,1749667358.254 [Default:CheckIn] Stopped
2025-06-11T18:42:38.254Z,1749667358.254 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T18:42:38.254Z,1749667358.254 [Default:CheckIn](INFO): Running loop #12
2025-06-11T18:42:38.255Z,1749667358.255 [Default:CheckIn] Running Loop=12
2025-06-11T18:42:38.255Z,1749667358.255 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T18:42:38.255Z,1749667358.255 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T18:42:40.243Z,1749667360.243 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184239.00,A,3648.17905,N,12147.27975,W,0.700,285.63,110625,,,A*79
2025-06-11T18:42:40.246Z,1749667360.246 [NAL9602](INFO): GPS fix at 20250611T184239: (36.802984, -121.787996)
2025-06-11T18:42:40.257Z,1749667360.257 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T18:42:40.257Z,1749667360.257 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T18:42:43.891Z,1749667363.891 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:42:48.059Z,1749667368.059 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250611T174910/Courier0030.lzma
2025-06-11T18:42:49.061Z,1749667369.061 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0030.lzma.bak
2025-06-11T18:42:49.062Z,1749667369.062 [DataOverHttps](INFO): SBD MOMSN=25192825
2025-06-11T18:42:53.602Z,1749667373.602 [NAL9602](INFO): SBD MO Status=0, MOMSN=63739, MT Status=0, MTMSN=0
2025-06-11T18:42:53.602Z,1749667373.602 [NAL9602](INFO): No messages in MT queue
2025-06-11T18:42:57.302Z,1749667377.302 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:43:08.540Z,1749667388.540 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250611T174910/Express0031.lzma
2025-06-11T18:43:09.647Z,1749667389.647 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0031.lzma.bak
2025-06-11T18:43:09.647Z,1749667389.647 [DataOverHttps](INFO): SBD MOMSN=25192828
2025-06-11T18:43:10.725Z,1749667390.725 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T18:43:10.725Z,1749667390.725 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T18:43:10.725Z,1749667390.725 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T18:43:11.014Z,1749667391.014 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:43:24.337Z,1749667404.337 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T18:43:25.139Z,1749667405.139 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:48:11.307Z,1749667691.307 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T18:48:11.307Z,1749667691.307 [Default:CheckIn:C.Wait] Stopped
2025-06-11T18:48:11.307Z,1749667691.307 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T18:48:11.308Z,1749667691.308 [Default:CheckIn:D] Running Loop=1
2025-06-11T18:48:11.761Z,1749667691.761 [Default:CheckIn:D] Stopped
2025-06-11T18:48:11.761Z,1749667691.761 [Default:CheckIn:E] Running Loop=1
2025-06-11T18:48:12.113Z,1749667692.113 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  70.209570 min 
2025-06-11T18:48:12.113Z,1749667692.113 [Default:CheckIn:E] Stopped
2025-06-11T18:48:12.113Z,1749667692.113 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T18:48:12.113Z,1749667692.113 [Default:CheckIn] Stopped
2025-06-11T18:48:12.113Z,1749667692.113 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T18:48:12.114Z,1749667692.114 [Default:CheckIn](INFO): Running loop #13
2025-06-11T18:48:12.114Z,1749667692.114 [Default:CheckIn] Running Loop=13
2025-06-11T18:48:12.114Z,1749667692.114 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T18:48:12.114Z,1749667692.114 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T18:48:14.118Z,1749667694.118 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184813.00,A,3648.17278,N,12147.28139,W,0.097,285.63,110625,,,A*7C
2025-06-11T18:48:14.120Z,1749667694.120 [NAL9602](INFO): GPS fix at 20250611T184813: (36.802880, -121.788023)
2025-06-11T18:48:14.131Z,1749667694.131 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T18:48:14.131Z,1749667694.131 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T18:48:24.763Z,1749667704.763 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250611T174910/Courier0033.lzma
2025-06-11T18:48:25.765Z,1749667705.765 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0033.lzma.bak
2025-06-11T18:48:25.766Z,1749667705.766 [DataOverHttps](INFO): SBD MOMSN=25192842
2025-06-11T18:48:26.686Z,1749667706.686 [NAL9602](INFO): SBD MO Status=0, MOMSN=63740, MT Status=0, MTMSN=0
2025-06-11T18:48:26.687Z,1749667706.687 [NAL9602](INFO): No messages in MT queue
2025-06-11T18:48:42.211Z,1749667722.211 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250611T174910/Express0034.lzma
2025-06-11T18:48:43.213Z,1749667723.213 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0034.lzma.bak
2025-06-11T18:48:43.214Z,1749667723.214 [DataOverHttps](INFO): SBD MOMSN=25192845
2025-06-11T18:48:44.480Z,1749667724.480 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T18:48:44.480Z,1749667724.480 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T18:48:44.481Z,1749667724.481 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T18:48:57.449Z,1749667737.449 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T18:50:49.415Z,1749667849.415 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:51:03.193Z,1749667863.193 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:51:16.524Z,1749667876.524 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:51:30.261Z,1749667890.261 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:51:43.591Z,1749667903.591 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:51:57.731Z,1749667917.731 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:52:11.064Z,1749667931.064 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:52:24.029Z,1749667944.029 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:52:37.383Z,1749667957.383 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:52:50.716Z,1749667970.716 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:53:04.120Z,1749667984.120 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T18:53:44.939Z,1749668024.939 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T18:53:44.939Z,1749668024.939 [Default:CheckIn:C.Wait] Stopped
2025-06-11T18:53:44.939Z,1749668024.939 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T18:53:44.939Z,1749668024.939 [Default:CheckIn:D] Running Loop=1
2025-06-11T18:53:45.347Z,1749668025.347 [Default:CheckIn:D] Stopped
2025-06-11T18:53:45.347Z,1749668025.347 [Default:CheckIn:E] Running Loop=1
2025-06-11T18:53:45.745Z,1749668025.745 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  75.769320 min 
2025-06-11T18:53:45.745Z,1749668025.745 [Default:CheckIn:E] Stopped
2025-06-11T18:53:45.745Z,1749668025.745 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T18:53:45.746Z,1749668025.746 [Default:CheckIn] Stopped
2025-06-11T18:53:45.746Z,1749668025.746 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T18:53:45.746Z,1749668025.746 [Default:CheckIn](INFO): Running loop #14
2025-06-11T18:53:45.746Z,1749668025.746 [Default:CheckIn] Running Loop=14
2025-06-11T18:53:45.746Z,1749668025.746 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T18:53:45.746Z,1749668025.746 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T18:53:47.761Z,1749668027.761 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185347.00,A,3648.17603,N,12147.27947,W,0.875,0.00,110625,,,A*7F
2025-06-11T18:53:47.784Z,1749668027.784 [NAL9602](INFO): GPS fix at 20250611T185347: (36.802934, -121.787991)
2025-06-11T18:53:47.817Z,1749668027.817 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T18:53:47.817Z,1749668027.817 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T18:53:55.103Z,1749668035.103 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250611T174910/Courier0036.lzma
2025-06-11T18:53:56.105Z,1749668036.105 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0036.lzma.bak
2025-06-11T18:53:56.105Z,1749668036.105 [DataOverHttps](INFO): SBD MOMSN=25192942
2025-06-11T18:54:12.307Z,1749668052.307 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250611T174910/Express0037.lzma
2025-06-11T18:54:13.309Z,1749668053.309 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0037.lzma.bak
2025-06-11T18:54:13.310Z,1749668053.310 [DataOverHttps](INFO): SBD MOMSN=25192948
2025-06-11T18:54:14.448Z,1749668054.448 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T18:54:14.448Z,1749668054.448 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T18:54:14.448Z,1749668054.448 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T18:56:13.320Z,1749668173.320 [NAL9602](INFO): SBD MO Status=0, MOMSN=63741, MT Status=0, MTMSN=0
2025-06-11T18:56:13.320Z,1749668173.320 [NAL9602](INFO): No messages in MT queue
2025-06-11T18:56:43.742Z,1749668203.742 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T18:57:38.291Z,1749668258.291 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-06-11T18:57:38.294Z,1749668258.294 [BPC1](FAULT): Failed to receive data from 5 sticks prior to timeout. Will not retry this scan cycle. Missing stick IDs are: 33, 36, 50, 51, 54.
2025-06-11T18:59:14.940Z,1749668354.940 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T18:59:14.940Z,1749668354.940 [Default:CheckIn:C.Wait] Stopped
2025-06-11T18:59:14.940Z,1749668354.940 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T18:59:14.941Z,1749668354.941 [Default:CheckIn:D] Running Loop=1
2025-06-11T18:59:15.356Z,1749668355.356 [Default:CheckIn:D] Stopped
2025-06-11T18:59:15.356Z,1749668355.356 [Default:CheckIn:E] Running Loop=1
2025-06-11T18:59:15.752Z,1749668355.752 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  81.269474 min 
2025-06-11T18:59:15.752Z,1749668355.752 [Default:CheckIn:E] Stopped
2025-06-11T18:59:15.752Z,1749668355.752 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T18:59:15.752Z,1749668355.752 [Default:CheckIn] Stopped
2025-06-11T18:59:15.752Z,1749668355.752 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T18:59:15.752Z,1749668355.752 [Default:CheckIn](INFO): Running loop #15
2025-06-11T18:59:15.752Z,1749668355.752 [Default:CheckIn] Running Loop=15
2025-06-11T18:59:15.752Z,1749668355.752 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T18:59:15.753Z,1749668355.753 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T18:59:17.758Z,1749668357.758 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185917.00,A,3648.17474,N,12147.27914,W,0.194,341.63,110625,,,A*71
2025-06-11T18:59:17.761Z,1749668357.761 [NAL9602](INFO): GPS fix at 20250611T185917: (36.802912, -121.787986)
2025-06-11T18:59:17.772Z,1749668357.772 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T18:59:17.772Z,1749668357.772 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T18:59:27.128Z,1749668367.128 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250611T174910/Courier0039.lzma
2025-06-11T18:59:28.129Z,1749668368.129 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0039.lzma.bak
2025-06-11T18:59:28.129Z,1749668368.129 [DataOverHttps](INFO): SBD MOMSN=25192965
2025-06-11T18:59:29.070Z,1749668369.070 [NAL9602](INFO): SBD MO Status=0, MOMSN=63742, MT Status=0, MTMSN=0
2025-06-11T18:59:29.071Z,1749668369.071 [NAL9602](INFO): No messages in MT queue
2025-06-11T18:59:44.004Z,1749668384.004 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20250611T174910/Express0040.lzma
2025-06-11T18:59:45.005Z,1749668385.005 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0040.lzma.bak
2025-06-11T18:59:45.006Z,1749668385.006 [DataOverHttps](INFO): SBD MOMSN=25192968
2025-06-11T18:59:46.470Z,1749668386.470 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T18:59:46.470Z,1749668386.470 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T18:59:46.470Z,1749668386.470 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T18:59:59.782Z,1749668399.782 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T19:04:46.910Z,1749668686.910 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T19:04:46.911Z,1749668686.911 [Default:CheckIn:C.Wait] Stopped
2025-06-11T19:04:46.911Z,1749668686.911 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T19:04:46.911Z,1749668686.911 [Default:CheckIn:D] Running Loop=1
2025-06-11T19:04:47.315Z,1749668687.315 [Default:CheckIn:D] Stopped
2025-06-11T19:04:47.315Z,1749668687.315 [Default:CheckIn:E] Running Loop=1
2025-06-11T19:04:47.723Z,1749668687.723 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  86.802124 min 
2025-06-11T19:04:47.723Z,1749668687.723 [Default:CheckIn:E] Stopped
2025-06-11T19:04:47.723Z,1749668687.723 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T19:04:47.723Z,1749668687.723 [Default:CheckIn] Stopped
2025-06-11T19:04:47.723Z,1749668687.723 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T19:04:47.723Z,1749668687.723 [Default:CheckIn](INFO): Running loop #16
2025-06-11T19:04:47.724Z,1749668687.724 [Default:CheckIn] Running Loop=16
2025-06-11T19:04:47.724Z,1749668687.724 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T19:04:47.724Z,1749668687.724 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T19:04:49.730Z,1749668689.730 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190449.00,A,3648.18670,N,12147.27591,W,0.039,215.74,110625,,,A*7B
2025-06-11T19:04:49.733Z,1749668689.733 [NAL9602](INFO): GPS fix at 20250611T190449: (36.803112, -121.787932)
2025-06-11T19:04:49.776Z,1749668689.776 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T19:04:49.776Z,1749668689.776 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T19:04:50.669Z,1749668690.669 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T19:04:50.670Z,1749668690.670 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T19:04:50.670Z,1749668690.670 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T19:05:20.982Z,1749668720.982 [NAL9602](INFO): SBD MO Status=0, MOMSN=63743, MT Status=0, MTMSN=0
2025-06-11T19:05:20.982Z,1749668720.982 [NAL9602](INFO): No messages in MT queue
2025-06-11T19:05:51.685Z,1749668751.685 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T19:08:45.025Z,1749668925.025 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:08:57.143Z,1749668937.143 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:09:09.264Z,1749668949.264 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:09:21.790Z,1749668961.790 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:09:33.907Z,1749668973.907 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:09:46.027Z,1749668986.027 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:09:51.298Z,1749668991.298 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T19:09:51.299Z,1749668991.299 [Default:CheckIn:C.Wait] Stopped
2025-06-11T19:09:51.299Z,1749668991.299 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T19:09:51.299Z,1749668991.299 [Default:CheckIn:D] Running Loop=1
2025-06-11T19:09:51.708Z,1749668991.708 [Default:CheckIn:D] Stopped
2025-06-11T19:09:51.708Z,1749668991.708 [Default:CheckIn:E] Running Loop=1
2025-06-11T19:09:52.100Z,1749668992.100 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  91.875342 min 
2025-06-11T19:09:52.100Z,1749668992.100 [Default:CheckIn:E] Stopped
2025-06-11T19:09:52.100Z,1749668992.100 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T19:09:52.100Z,1749668992.100 [Default:CheckIn] Stopped
2025-06-11T19:09:52.100Z,1749668992.100 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T19:09:52.100Z,1749668992.100 [Default:CheckIn](INFO): Running loop #17
2025-06-11T19:09:52.100Z,1749668992.100 [Default:CheckIn] Running Loop=17
2025-06-11T19:09:52.100Z,1749668992.100 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T19:09:52.101Z,1749668992.101 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T19:09:54.181Z,1749668994.181 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190953.00,A,3648.17712,N,12147.27607,W,0.194,164.78,110625,,,A*74
2025-06-11T19:09:54.183Z,1749668994.183 [NAL9602](INFO): GPS fix at 20250611T190953: (36.802952, -121.787935)
2025-06-11T19:09:54.209Z,1749668994.209 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T19:09:54.209Z,1749668994.209 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T19:09:57.344Z,1749668997.344 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:10:01.883Z,1749669001.883 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250611T174910/Courier0042.lzma
2025-06-11T19:10:02.885Z,1749669002.885 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0042.lzma.bak
2025-06-11T19:10:02.886Z,1749669002.886 [DataOverHttps](INFO): SBD MOMSN=25193092
2025-06-11T19:10:19.800Z,1749669019.800 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250611T174910/Courier0045.lzma
2025-06-11T19:10:20.801Z,1749669020.801 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0045.lzma.bak
2025-06-11T19:10:20.802Z,1749669020.802 [DataOverHttps](INFO): SBD MOMSN=25193095
2025-06-11T19:10:39.904Z,1749669039.904 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250611T174910/Express0043.lzma
2025-06-11T19:10:40.219Z,1749669040.219 [NAL9602](INFO): SBD MO Status=0, MOMSN=63744, MT Status=0, MTMSN=0
2025-06-11T19:10:40.219Z,1749669040.219 [NAL9602](INFO): No messages in MT queue
2025-06-11T19:10:40.906Z,1749669040.906 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0043.lzma.bak
2025-06-11T19:10:40.906Z,1749669040.906 [DataOverHttps](INFO): SBD MOMSN=25193098
2025-06-11T19:11:00.459Z,1749669060.459 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250611T174910/Express0046.lzma
2025-06-11T19:11:01.461Z,1749669061.461 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0046.lzma.bak
2025-06-11T19:11:01.462Z,1749669061.462 [DataOverHttps](INFO): SBD MOMSN=25193107
2025-06-11T19:11:02.896Z,1749669062.896 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T19:11:02.897Z,1749669062.897 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T19:11:02.897Z,1749669062.897 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T19:11:10.964Z,1749669070.964 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-11T19:12:40.255Z,1749669160.255 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:12:53.589Z,1749669173.589 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:13:06.919Z,1749669186.919 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:13:20.255Z,1749669200.255 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:13:33.990Z,1749669213.990 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:13:48.532Z,1749669228.532 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:14:02.268Z,1749669242.268 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:14:16.003Z,1749669256.003 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:14:29.739Z,1749669269.739 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:14:43.882Z,1749669283.882 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:14:57.228Z,1749669297.228 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:16:03.487Z,1749669363.487 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-11T19:16:03.488Z,1749669363.487 [Default:CheckIn:C.Wait] Stopped
2025-06-11T19:16:03.488Z,1749669363.488 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T19:16:03.488Z,1749669363.488 [Default:CheckIn:D] Running Loop=1
2025-06-11T19:16:03.885Z,1749669363.885 [Default:CheckIn:D] Stopped
2025-06-11T19:16:03.885Z,1749669363.885 [Default:CheckIn:E] Running Loop=1
2025-06-11T19:16:04.368Z,1749669364.368 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  98.078296 min 
2025-06-11T19:16:04.368Z,1749669364.368 [Default:CheckIn:E] Stopped
2025-06-11T19:16:04.368Z,1749669364.368 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-11T19:16:04.368Z,1749669364.368 [Default:CheckIn] Stopped
2025-06-11T19:16:04.368Z,1749669364.368 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T19:16:04.368Z,1749669364.368 [Default:CheckIn](INFO): Running loop #18
2025-06-11T19:16:04.369Z,1749669364.369 [Default:CheckIn] Running Loop=18
2025-06-11T19:16:04.369Z,1749669364.369 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-11T19:16:04.369Z,1749669364.369 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-11T19:16:06.307Z,1749669366.307 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191605.00,A,3648.16565,N,12147.28277,W,0.719,159.95,110625,,,A*78
2025-06-11T19:16:06.309Z,1749669366.309 [NAL9602](INFO): GPS fix at 20250611T191605: (36.802761, -121.788046)
2025-06-11T19:16:06.320Z,1749669366.320 [Default:CheckIn:Read_GPS] Stopped
2025-06-11T19:16:06.320Z,1749669366.320 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-11T19:16:14.051Z,1749669374.051 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250611T174910/Courier0048.lzma
2025-06-11T19:16:15.053Z,1749669375.053 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Courier0048.lzma.bak
2025-06-11T19:16:15.054Z,1749669375.054 [DataOverHttps](INFO): SBD MOMSN=25193192
2025-06-11T19:16:34.383Z,1749669394.383 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250611T174910/Express0049.lzma
2025-06-11T19:16:35.385Z,1749669395.385 [DataOverHttps](INFO): Moved sent file to Logs/20250611T174910/Express0049.lzma.bak
2025-06-11T19:16:35.386Z,1749669395.386 [DataOverHttps](INFO): SBD MOMSN=25193197
2025-06-11T19:16:36.680Z,1749669396.680 [Default:CheckIn:Read_Iridium] Stopped
2025-06-11T19:16:36.680Z,1749669396.680 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-11T19:16:36.680Z,1749669396.680 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-11T19:17:22.318Z,1749669442.318 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:17:34.431Z,1749669454.431 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:17:45.743Z,1749669465.743 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:17:57.460Z,1749669477.460 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:18:09.176Z,1749669489.176 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-11T19:18:17.968Z,1749669497.968 [CommandExec](IMPORTANT): got command quit
2025-06-11T19:18:18.972Z,1749669498.972 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-11T19:18:18.972Z,1749669498.972 [CommandExec](INFO): Uninitializing the command executive.
2025-06-11T19:18:18.972Z,1749669498.972 [CommandExec](INFO): Uninitializing the command scheduler.
2025-06-11T19:18:18.972Z,1749669498.972 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:19.015Z,1749669499.015 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-06-11T19:18:19.016Z,1749669499.016 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-06-11T19:18:19.016Z,1749669499.016 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:19.017Z,1749669499.017 [NavChartDb](INFO): Join timeout helper Thread ID is 7026
2025-06-11T19:18:19.040Z,1749669499.040 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-11T19:18:19.040Z,1749669499.040 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:19.048Z,1749669499.048 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-06-11T19:18:19.048Z,1749669499.048 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:19.048Z,1749669499.048 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7027
2025-06-11T19:18:19.133Z,1749669499.133 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-11T19:18:19.134Z,1749669499.134 [WetLabsBB2FL](INFO): Powering down
2025-06-11T19:18:19.136Z,1749669499.136 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:19.136Z,1749669499.136 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-06-11T19:18:19.136Z,1749669499.136 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:19.137Z,1749669499.137 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7028
2025-06-11T19:18:19.724Z,1749669499.724 [CTD_Seabird](INFO): Powering down
2025-06-11T19:18:19.736Z,1749669499.736 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-11T19:18:19.736Z,1749669499.736 [CTD_Seabird](INFO): Powering down
2025-06-11T19:18:19.748Z,1749669499.748 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:19.760Z,1749669499.760 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-06-11T19:18:19.760Z,1749669499.760 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:19.760Z,1749669499.760 [Radio_Surface](INFO): Join timeout helper Thread ID is 7029
2025-06-11T19:18:19.876Z,1749669499.876 [Radio_Surface](INFO): Powering down
2025-06-11T19:18:19.876Z,1749669499.876 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-11T19:18:19.877Z,1749669499.877 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:19.892Z,1749669499.892 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-06-11T19:18:19.892Z,1749669499.892 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:19.892Z,1749669499.892 [Onboard](INFO): Join timeout helper Thread ID is 7030
2025-06-11T19:18:20.096Z,1749669500.096 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-11T19:18:20.096Z,1749669500.096 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:20.109Z,1749669500.109 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-06-11T19:18:20.109Z,1749669500.109 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:20.109Z,1749669500.109 [DataOverHttps](INFO): Join timeout helper Thread ID is 7031
2025-06-11T19:18:20.284Z,1749669500.284 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-11T19:18:20.284Z,1749669500.284 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:20.288Z,1749669500.288 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-06-11T19:18:20.288Z,1749669500.288 [DAT ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:20.289Z,1749669500.289 [DAT](INFO): Join timeout helper Thread ID is 7032
2025-06-11T19:18:20.512Z,1749669500.512 [DAT](INFO): Powering down
2025-06-11T19:18:20.584Z,1749669500.584 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-11T19:18:20.584Z,1749669500.584 [DAT](INFO): Powering down
2025-06-11T19:18:20.585Z,1749669500.585 [DAT ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:20.596Z,1749669500.596 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-06-11T19:18:20.596Z,1749669500.596 [logger ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:20.597Z,1749669500.597 [logger](INFO): Join timeout helper Thread ID is 7033
2025-06-11T19:18:20.598Z,1749669500.598 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-11T19:18:20.598Z,1749669500.598 [logger ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:20.616Z,1749669500.616 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-06-11T19:18:20.616Z,1749669500.616 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:20.617Z,1749669500.617 [CommandLine](INFO): Join timeout helper Thread ID is 7034
2025-06-11T19:18:20.696Z,1749669500.696 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-11T19:18:20.696Z,1749669500.696 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:20.716Z,1749669500.716 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-06-11T19:18:20.716Z,1749669500.716 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:20.717Z,1749669500.717 [CommandExec](INFO): Join timeout helper Thread ID is 7035
2025-06-11T19:18:20.718Z,1749669500.718 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-06-11T19:18:20.718Z,1749669500.718 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:20.718Z,1749669500.718 [controlThread](INFO): Join timeout helper Thread ID is 7036
2025-06-11T19:18:20.876Z,1749669500.876 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-06-11T19:18:20.876Z,1749669500.876 [controlThread](DEBUG): Uninitializing ControlThread
2025-06-11T19:18:20.876Z,1749669500.876 [AHRS_M2](INFO): Powering down
2025-06-11T19:18:20.956Z,1749669500.956 [NAL9602](INFO): Powering down
2025-06-11T19:18:20.957Z,1749669500.957 [Sonardyne_Nano](INFO): Powering down
2025-06-11T19:18:21.160Z,1749669501.160 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-06-11T19:18:21.161Z,1749669501.161 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-06-11T19:18:21.161Z,1749669501.161 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-06-11T19:18:21.162Z,1749669501.162 [MissionManager](INFO): Uninitializing Mission Default
2025-06-11T19:18:21.162Z,1749669501.162 [Default] Stopped
2025-06-11T19:18:21.162Z,1749669501.162 [Default](DEBUG): Aggregate::uninitialize Default
2025-06-11T19:18:21.162Z,1749669501.162 [Default:B.GoToSurface] Stopped
2025-06-11T19:18:21.162Z,1749669501.162 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-11T19:18:21.162Z,1749669501.162 [Default:CheckIn] Stopped
2025-06-11T19:18:21.162Z,1749669501.162 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-11T19:18:21.162Z,1749669501.162 [Default:CheckIn:C.Wait] Stopped
2025-06-11T19:18:21.162Z,1749669501.162 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-11T19:18:21.165Z,1749669501.165 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-06-11T19:18:21.165Z,1749669501.165 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-06-11T19:18:21.166Z,1749669501.166 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-06-11T19:18:21.166Z,1749669501.166 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-06-11T19:18:21.166Z,1749669501.166 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-06-11T19:18:21.166Z,1749669501.166 [BuoyancyServo](INFO): Powering down
2025-06-11T19:18:21.180Z,1749669501.180 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-06-11T19:18:21.180Z,1749669501.180 [ElevatorServo](INFO): Powering down
2025-06-11T19:18:21.180Z,1749669501.180 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-06-11T19:18:21.181Z,1749669501.181 [MassServo](INFO): Powering down
2025-06-11T19:18:21.181Z,1749669501.181 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-06-11T19:18:21.181Z,1749669501.181 [RudderServo](INFO): Powering down
2025-06-11T19:18:21.182Z,1749669501.182 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-06-11T19:18:21.182Z,1749669501.182 [ThrusterHE](INFO): Powering down
2025-06-11T19:18:21.183Z,1749669501.183 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-06-11T19:18:21.184Z,1749669501.184 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-06-11T19:18:21.184Z,1749669501.184 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-06-11T19:18:21.184Z,1749669501.184 [CBIT](DEBUG): Powering off loads.
2025-06-11T19:18:21.195Z,1749669501.195 [CBIT](DEBUG): Disabling WDT.
2025-06-11T19:18:21.208Z,1749669501.208 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-06-11T19:18:21.208Z,1749669501.208 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-06-11T19:18:21.209Z,1749669501.209 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:21.277Z,1749669501.277 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:21.280Z,1749669501.280 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:21.286Z,1749669501.286 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:21.293Z,1749669501.293 [DAT ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:21.366Z,1749669501.366 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:21.371Z,1749669501.371 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:21.395Z,1749669501.395 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-06-11T19:18:21.395Z,1749669501.395 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-06-11T19:18:21.415Z,1749669501.415 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-06-11T19:18:21.529Z,1749669501.529 [logger ThreadHandler](INFO): Thread cancelled.