2021-05-11T21:42:39.827Z,1620769359.827 [DataOverHttps](INFO): Received command:restart logs 2021-05-11T21:42:39.865Z,1620769359.865 [CommandLine](IMPORTANT): got command restart logs 2021-05-11T21:42:44.838Z,1620769364.838 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=4439, MT Status=1, MTMSN=304 2021-05-11T21:42:44.838Z,1620769364.838 [NAL9602](INFO): Data available in MT queue 2021-05-11T21:42:45.364Z,1620769365.364 [NAL9602](INFO): Received command:stop 2021-05-11T21:42:51.354Z,1620769371.354 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T21:43:06.746Z,1620769386.746 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T21:43:13.321Z,1620769393.321 [DataOverHttps](IMPORTANT): SBD MTMSN=20210511T214312 2021-05-11T21:43:16.034Z,1620769396.034 [NAL9602](INFO): Not Powering down - fast GPS 2021-05-11T21:43:20.583Z,1620769400.583 [DataOverHttps](INFO): Received command:strobe off 2021-05-11T21:43:20.612Z,1620769400.612 [CommandLine](IMPORTANT): got command strobe off 2021-05-11T21:43:20.612Z,1620769400.612 [CommandLine](IMPORTANT): Deactivating strobe 2021-05-11T21:43:21.689Z,1620769401.689 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T21:43:37.044Z,1620769417.044 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T21:44:55.992Z,1620769495.992 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2021-05-11T21:44:57.000Z,1620769498.000 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.635288 2021-05-11T21:45:31.132Z,1620769531.132 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-05-11T21:46:06.272Z,1620769566.272 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-05-11T21:46:41.412Z,1620769601.412 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-05-11T21:47:16.552Z,1620769636.552 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-05-11T21:47:22.923Z,1620769642.923 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-11T21:47:22.923Z,1620769642.923 [Default:CheckIn:C.Wait] Stopped 2021-05-11T21:47:22.923Z,1620769642.923 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T21:47:22.923Z,1620769642.923 [Default:CheckIn:D] Running Loop=1 2021-05-11T21:47:23.306Z,1620769643.306 [Default:CheckIn:D] Stopped 2021-05-11T21:47:23.306Z,1620769643.306 [Default:CheckIn:E] Running Loop=1 2021-05-11T21:47:23.702Z,1620769643.702 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.389635 min 2021-05-11T21:47:23.702Z,1620769643.702 [Default:CheckIn:E] Stopped 2021-05-11T21:47:23.702Z,1620769643.702 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-11T21:47:23.702Z,1620769643.702 [Default:CheckIn] Stopped 2021-05-11T21:47:23.702Z,1620769643.702 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T21:47:23.702Z,1620769643.702 [Default:CheckIn](INFO): Running loop #3 2021-05-11T21:47:23.702Z,1620769643.702 [Default:CheckIn] Running Loop=3 2021-05-11T21:47:23.703Z,1620769643.703 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-11T21:47:23.703Z,1620769643.703 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-11T21:47:25.708Z,1620769645.708 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214725.00,A,3648.33004,N,12147.67385,W,21.013,89.68,110521,,,D*75 2021-05-11T21:47:25.710Z,1620769645.710 [NAL9602](INFO): GPS fix at 20210511T214725: (36.805501, -121.794564) 2021-05-11T21:47:25.721Z,1620769645.721 [Default:CheckIn:Read_GPS] Stopped 2021-05-11T21:47:25.721Z,1620769645.721 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-11T21:47:50.008Z,1620769670.008 [NAL9602](INFO): SBD MO Status=1, MOMSN=4440, MT Status=0, MTMSN=0 2021-05-11T21:47:50.064Z,1620769670.064 [NAL9602](INFO): Sent 39 bytes from file Logs/20210511T143133/Courier0139.lzma 2021-05-11T21:47:50.064Z,1620769670.064 [NAL9602](INFO): Packets left to send: 0 2021-05-11T21:47:51.696Z,1620769671.696 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2021-05-11T21:48:02.978Z,1620769682.978 [NAL9602](INFO): SBD MO Status=2, MOMSN=4441, MT Status=2, MTMSN=0 2021-05-11T21:48:02.978Z,1620769682.978 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-05-11T21:48:24.223Z,1620769704.223 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002880 2021-05-11T21:48:27.674Z,1620769707.674 [NAL9602](INFO): SBD MO Status=2, MOMSN=4441, MT Status=2, MTMSN=0 2021-05-11T21:48:27.674Z,1620769707.674 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-05-11T21:48:31.447Z,1620769711.447 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20210511T214239/Courier0000.lzma 2021-05-11T21:48:32.449Z,1620769712.449 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0000.lzma.bak 2021-05-11T21:48:32.449Z,1620769712.449 [DataOverHttps](INFO): SBD MOMSN=15640064 2021-05-11T21:48:54.355Z,1620769734.355 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210511T214239/Courier0003.lzma 2021-05-11T21:48:55.357Z,1620769735.357 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0003.lzma.bak 2021-05-11T21:48:55.357Z,1620769735.357 [DataOverHttps](INFO): SBD MOMSN=15640068 2021-05-11T21:48:58.094Z,1620769738.094 [NAL9602](INFO): Not Powering down - fast GPS 2021-05-11T21:49:11.027Z,1620769751.027 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20210511T143133/Express0140.lzma 2021-05-11T21:49:12.030Z,1620769752.030 [DataOverHttps](INFO): Moved sent file to Logs/20210511T143133/Express0140.lzma.bak 2021-05-11T21:49:12.030Z,1620769752.030 [DataOverHttps](INFO): SBD MOMSN=15640070 2021-05-11T21:49:28.304Z,1620769768.304 [DataOverHttps](INFO): Sending 518 bytes from file Logs/20210511T214239/Express0001.lzma 2021-05-11T21:49:29.305Z,1620769769.305 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0001.lzma.bak 2021-05-11T21:49:29.305Z,1620769769.305 [DataOverHttps](INFO): SBD MOMSN=15640073 2021-05-11T21:49:45.047Z,1620769785.047 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210511T214239/Express0004.lzma 2021-05-11T21:49:46.049Z,1620769786.049 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0004.lzma.bak 2021-05-11T21:49:46.049Z,1620769786.049 [DataOverHttps](INFO): SBD MOMSN=15640081 2021-05-11T21:49:47.410Z,1620769787.410 [Default:CheckIn:Read_Iridium] Stopped 2021-05-11T21:49:47.410Z,1620769787.410 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-11T21:49:47.410Z,1620769787.410 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-11T21:51:02.525Z,1620769862.525 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T21:51:17.092Z,1620769877.092 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T21:51:30.816Z,1620769890.816 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T21:51:44.945Z,1620769904.945 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T21:51:59.489Z,1620769919.489 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T21:52:14.033Z,1620769934.033 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T21:52:28.172Z,1620769948.172 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T21:52:42.317Z,1620769962.317 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T21:52:56.457Z,1620769976.457 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T21:53:11.007Z,1620769991.007 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T21:53:25.949Z,1620770005.949 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-05-11T21:54:48.052Z,1620770088.052 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-11T21:54:48.052Z,1620770088.052 [Default:CheckIn:C.Wait] Stopped 2021-05-11T21:54:48.052Z,1620770088.052 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T21:54:48.053Z,1620770088.053 [Default:CheckIn:D] Running Loop=1 2021-05-11T21:54:48.433Z,1620770088.433 [Default:CheckIn:D] Stopped 2021-05-11T21:54:48.433Z,1620770088.433 [Default:CheckIn:E] Running Loop=1 2021-05-11T21:54:48.834Z,1620770088.834 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.808407 min 2021-05-11T21:54:48.834Z,1620770088.834 [Default:CheckIn:E] Stopped 2021-05-11T21:54:48.834Z,1620770088.834 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-11T21:54:48.834Z,1620770088.834 [Default:CheckIn] Stopped 2021-05-11T21:54:48.834Z,1620770088.834 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T21:54:48.834Z,1620770088.834 [Default:CheckIn](INFO): Running loop #4 2021-05-11T21:54:48.835Z,1620770088.835 [Default:CheckIn] Running Loop=4 2021-05-11T21:54:48.835Z,1620770088.835 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-11T21:54:48.835Z,1620770088.835 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-11T21:54:50.908Z,1620770090.908 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215450.00,A,3648.15827,N,12147.21044,W,3.674,223.26,110521,,,D*7B 2021-05-11T21:54:50.910Z,1620770090.910 [NAL9602](INFO): GPS fix at 20210511T215450: (36.802638, -121.786841) 2021-05-11T21:54:50.921Z,1620770090.921 [Default:CheckIn:Read_GPS] Stopped 2021-05-11T21:54:50.921Z,1620770090.921 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-11T21:54:57.671Z,1620770097.671 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210511T214239/Courier0006.lzma 2021-05-11T21:54:58.673Z,1620770098.673 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0006.lzma.bak 2021-05-11T21:54:58.673Z,1620770098.673 [DataOverHttps](INFO): SBD MOMSN=15640100 2021-05-11T21:55:14.363Z,1620770114.363 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20210511T214239/Express0007.lzma 2021-05-11T21:55:15.365Z,1620770115.365 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0007.lzma.bak 2021-05-11T21:55:15.365Z,1620770115.365 [DataOverHttps](INFO): SBD MOMSN=15640103 2021-05-11T21:55:16.826Z,1620770116.826 [Default:CheckIn:Read_Iridium] Stopped 2021-05-11T21:55:16.826Z,1620770116.826 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-11T21:55:16.827Z,1620770116.827 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-11T21:56:30.738Z,1620770190.738 [NAL9602](INFO): SBD MO Status=1, MOMSN=4441, MT Status=0, MTMSN=0 2021-05-11T21:56:30.738Z,1620770190.738 [NAL9602](INFO): No messages in MT queue 2021-05-11T21:57:01.444Z,1620770221.444 [NAL9602](INFO): Not Powering down - fast GPS 2021-05-11T22:00:17.219Z,1620770417.219 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-11T22:00:17.219Z,1620770417.219 [Default:CheckIn:C.Wait] Stopped 2021-05-11T22:00:17.219Z,1620770417.219 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T22:00:17.219Z,1620770417.219 [Default:CheckIn:D] Running Loop=1 2021-05-11T22:00:17.499Z,1620770417.499 [Default:CheckIn:D] Stopped 2021-05-11T22:00:17.499Z,1620770417.499 [Default:CheckIn:E] Running Loop=1 2021-05-11T22:00:17.896Z,1620770417.896 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.292841 min 2021-05-11T22:00:17.896Z,1620770417.896 [Default:CheckIn:E] Stopped 2021-05-11T22:00:17.896Z,1620770417.896 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-11T22:00:17.896Z,1620770417.896 [Default:CheckIn] Stopped 2021-05-11T22:00:17.896Z,1620770417.896 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T22:00:17.896Z,1620770417.896 [Default:CheckIn](INFO): Running loop #5 2021-05-11T22:00:17.896Z,1620770417.896 [Default:CheckIn] Running Loop=5 2021-05-11T22:00:17.896Z,1620770417.896 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-11T22:00:17.897Z,1620770417.897 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-11T22:00:19.890Z,1620770419.890 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220019.00,A,3648.14869,N,12147.22302,W,0.039,234.93,110521,,,D*79 2021-05-11T22:00:19.892Z,1620770419.892 [NAL9602](INFO): GPS fix at 20210511T220019: (36.802478, -121.787050) 2021-05-11T22:00:19.902Z,1620770419.902 [Default:CheckIn:Read_GPS] Stopped 2021-05-11T22:00:19.902Z,1620770419.902 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-11T22:00:26.859Z,1620770426.859 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210511T214239/Courier0009.lzma 2021-05-11T22:00:27.861Z,1620770427.861 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0009.lzma.bak 2021-05-11T22:00:27.861Z,1620770427.861 [DataOverHttps](INFO): SBD MOMSN=15640115 2021-05-11T22:00:40.114Z,1620770440.114 [NAL9602](INFO): SBD MO Status=1, MOMSN=4442, MT Status=0, MTMSN=0 2021-05-11T22:00:40.114Z,1620770440.114 [NAL9602](INFO): No messages in MT queue 2021-05-11T22:00:48.323Z,1620770448.323 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20210511T214239/Express0010.lzma 2021-05-11T22:00:49.325Z,1620770449.325 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0010.lzma.bak 2021-05-11T22:00:49.325Z,1620770449.325 [DataOverHttps](INFO): SBD MOMSN=15640118 2021-05-11T22:00:50.734Z,1620770450.734 [Default:CheckIn:Read_Iridium] Stopped 2021-05-11T22:00:50.734Z,1620770450.734 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-11T22:00:50.734Z,1620770450.734 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-11T22:00:51.077Z,1620770451.077 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:01:06.835Z,1620770466.835 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:01:10.876Z,1620770470.876 [NAL9602](INFO): Not Powering down - fast GPS 2021-05-11T22:01:22.596Z,1620770482.596 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:01:37.943Z,1620770497.943 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:01:53.697Z,1620770513.697 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:02:09.453Z,1620770529.453 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:02:24.808Z,1620770544.808 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:02:39.773Z,1620770559.773 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:02:55.572Z,1620770575.572 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:05:51.316Z,1620770751.316 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-11T22:05:51.316Z,1620770751.316 [Default:CheckIn:C.Wait] Stopped 2021-05-11T22:05:51.316Z,1620770751.316 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T22:05:51.316Z,1620770751.316 [Default:CheckIn:D] Running Loop=1 2021-05-11T22:05:51.722Z,1620770751.722 [Default:CheckIn:D] Stopped 2021-05-11T22:05:51.722Z,1620770751.722 [Default:CheckIn:E] Running Loop=1 2021-05-11T22:05:52.138Z,1620770752.138 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.863234 min 2021-05-11T22:05:52.138Z,1620770752.138 [Default:CheckIn:E] Stopped 2021-05-11T22:05:52.138Z,1620770752.138 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-11T22:05:52.138Z,1620770752.138 [Default:CheckIn] Stopped 2021-05-11T22:05:52.138Z,1620770752.138 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T22:05:52.138Z,1620770752.138 [Default:CheckIn](INFO): Running loop #6 2021-05-11T22:05:52.138Z,1620770752.138 [Default:CheckIn] Running Loop=6 2021-05-11T22:05:52.138Z,1620770752.138 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-11T22:05:52.139Z,1620770752.139 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-11T22:05:54.155Z,1620770754.155 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220553.00,A,3648.17536,N,12147.28097,W,0.214,309.20,110521,,,D*79 2021-05-11T22:05:54.157Z,1620770754.157 [NAL9602](INFO): GPS fix at 20210511T220553: (36.802923, -121.788016) 2021-05-11T22:05:54.188Z,1620770754.188 [Default:CheckIn:Read_GPS] Stopped 2021-05-11T22:05:54.188Z,1620770754.188 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-11T22:06:01.691Z,1620770761.691 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210511T214239/Courier0012.lzma 2021-05-11T22:06:02.693Z,1620770762.693 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0012.lzma.bak 2021-05-11T22:06:02.693Z,1620770762.693 [DataOverHttps](INFO): SBD MOMSN=15640137 2021-05-11T22:06:18.507Z,1620770778.507 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20210511T214239/Express0013.lzma 2021-05-11T22:06:19.509Z,1620770779.509 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0013.lzma.bak 2021-05-11T22:06:19.509Z,1620770779.509 [DataOverHttps](INFO): SBD MOMSN=15640140 2021-05-11T22:06:20.822Z,1620770780.822 [Default:CheckIn:Read_Iridium] Stopped 2021-05-11T22:06:20.822Z,1620770780.822 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-11T22:06:20.822Z,1620770780.822 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-11T22:09:18.174Z,1620770958.174 [NAL9602](INFO): SBD MO Status=2, MOMSN=4443, MT Status=2, MTMSN=0 2021-05-11T22:09:18.174Z,1620770958.174 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-05-11T22:09:58.161Z,1620770998.161 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5. 2021-05-11T22:09:58.164Z,1620770998.164 [BPC1](FAULT): Failed to receive data from 3 sticks prior to timeout. Missing stick IDs are: 40, 41, 47. 2021-05-11T22:09:58.164Z,1620770998.164 [BPC1] Data Fault, FailCount= 3 2021-05-11T22:09:58.164Z,1620770998.164 [BPC1](ERROR): Data Fault 2021-05-11T22:09:58.249Z,1620770998.249 [CBIT](ERROR): Data Fault in component: BPC1 2021-05-11T22:10:25.638Z,1620771025.638 [NAL9602](INFO): SBD MO Status=1, MOMSN=4443, MT Status=0, MTMSN=0 2021-05-11T22:10:25.639Z,1620771025.639 [NAL9602](INFO): No messages in MT queue 2021-05-11T22:10:56.352Z,1620771056.352 [NAL9602](INFO): Not Powering down - fast GPS 2021-05-11T22:11:21.423Z,1620771081.423 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-11T22:11:21.423Z,1620771081.423 [Default:CheckIn:C.Wait] Stopped 2021-05-11T22:11:21.427Z,1620771081.427 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T22:11:21.427Z,1620771081.427 [Default:CheckIn:D] Running Loop=1 2021-05-11T22:11:21.814Z,1620771081.814 [Default:CheckIn:D] Stopped 2021-05-11T22:11:21.814Z,1620771081.814 [Default:CheckIn:E] Running Loop=1 2021-05-11T22:11:22.211Z,1620771082.211 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.364766 min 2021-05-11T22:11:22.211Z,1620771082.211 [Default:CheckIn:E] Stopped 2021-05-11T22:11:22.211Z,1620771082.211 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-11T22:11:22.212Z,1620771082.212 [Default:CheckIn] Stopped 2021-05-11T22:11:22.212Z,1620771082.212 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T22:11:22.212Z,1620771082.212 [Default:CheckIn](INFO): Running loop #7 2021-05-11T22:11:22.212Z,1620771082.212 [Default:CheckIn] Running Loop=7 2021-05-11T22:11:22.212Z,1620771082.212 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-11T22:11:22.212Z,1620771082.212 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-11T22:11:24.230Z,1620771084.230 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221123.00,A,3648.16670,N,12147.28352,W,0.214,249.78,110521,,,A*7C 2021-05-11T22:11:24.232Z,1620771084.232 [NAL9602](INFO): GPS fix at 20210511T221123: (36.802778, -121.788059) 2021-05-11T22:11:24.243Z,1620771084.243 [Default:CheckIn:Read_GPS] Stopped 2021-05-11T22:11:24.243Z,1620771084.243 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-11T22:11:31.623Z,1620771091.623 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20210511T214239/Courier0015.lzma 2021-05-11T22:11:32.625Z,1620771092.625 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0015.lzma.bak 2021-05-11T22:11:32.626Z,1620771092.626 [DataOverHttps](INFO): SBD MOMSN=15640149 2021-05-11T22:11:48.639Z,1620771108.639 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20210511T214239/Express0016.lzma 2021-05-11T22:11:49.641Z,1620771109.641 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0016.lzma.bak 2021-05-11T22:11:49.641Z,1620771109.641 [DataOverHttps](INFO): SBD MOMSN=15640152 2021-05-11T22:11:50.906Z,1620771110.906 [Default:CheckIn:Read_Iridium] Stopped 2021-05-11T22:11:50.906Z,1620771110.906 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-11T22:11:50.906Z,1620771110.906 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-11T22:16:26.424Z,1620771386.424 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-05-11T22:16:51.537Z,1620771411.537 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-11T22:16:51.537Z,1620771411.537 [Default:CheckIn:C.Wait] Stopped 2021-05-11T22:16:51.537Z,1620771411.537 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T22:16:51.538Z,1620771411.538 [Default:CheckIn:D] Running Loop=1 2021-05-11T22:16:51.938Z,1620771411.938 [Default:CheckIn:D] Stopped 2021-05-11T22:16:51.938Z,1620771411.938 [Default:CheckIn:E] Running Loop=1 2021-05-11T22:16:52.353Z,1620771412.353 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.866829 min 2021-05-11T22:16:52.353Z,1620771412.353 [Default:CheckIn:E] Stopped 2021-05-11T22:16:52.353Z,1620771412.353 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-11T22:16:52.353Z,1620771412.353 [Default:CheckIn] Stopped 2021-05-11T22:16:52.353Z,1620771412.353 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T22:16:52.353Z,1620771412.353 [Default:CheckIn](INFO): Running loop #8 2021-05-11T22:16:52.353Z,1620771412.353 [Default:CheckIn] Running Loop=8 2021-05-11T22:16:52.354Z,1620771412.354 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-11T22:16:52.354Z,1620771412.354 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-11T22:16:54.354Z,1620771414.354 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221653.00,A,3648.16703,N,12147.28062,W,0.486,249.78,110521,,,A*74 2021-05-11T22:16:54.356Z,1620771414.356 [NAL9602](INFO): GPS fix at 20210511T221653: (36.802784, -121.788010) 2021-05-11T22:16:54.366Z,1620771414.366 [Default:CheckIn:Read_GPS] Stopped 2021-05-11T22:16:54.366Z,1620771414.366 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-11T22:17:01.915Z,1620771421.915 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210511T214239/Courier0018.lzma 2021-05-11T22:17:02.917Z,1620771422.917 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0018.lzma.bak 2021-05-11T22:17:02.917Z,1620771422.917 [DataOverHttps](INFO): SBD MOMSN=15640174 2021-05-11T22:17:18.543Z,1620771438.543 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20210511T214239/Express0019.lzma 2021-05-11T22:17:19.545Z,1620771439.545 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0019.lzma.bak 2021-05-11T22:17:19.545Z,1620771439.545 [DataOverHttps](INFO): SBD MOMSN=15640177 2021-05-11T22:17:20.665Z,1620771440.665 [Default:CheckIn:Read_Iridium] Stopped 2021-05-11T22:17:20.665Z,1620771440.665 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-11T22:17:20.666Z,1620771440.666 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-11T22:17:26.272Z,1620771446.272 [NAL9602](INFO): Not Powering down - fast GPS 2021-05-11T22:19:59.060Z,1620771599.060 [CBIT](INFO): Clearing failed state for component BPC1 2021-05-11T22:19:59.060Z,1620771599.060 [BPC1] No Fault, FailCount= 3 2021-05-11T22:22:21.275Z,1620771741.275 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-11T22:22:21.275Z,1620771741.275 [Default:CheckIn:C.Wait] Stopped 2021-05-11T22:22:21.275Z,1620771741.275 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T22:22:21.276Z,1620771741.276 [Default:CheckIn:D] Running Loop=1 2021-05-11T22:22:21.677Z,1620771741.677 [Default:CheckIn:D] Stopped 2021-05-11T22:22:21.678Z,1620771741.678 [Default:CheckIn:E] Running Loop=1 2021-05-11T22:22:22.084Z,1620771742.084 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.362488 min 2021-05-11T22:22:22.084Z,1620771742.084 [Default:CheckIn:E] Stopped 2021-05-11T22:22:22.084Z,1620771742.084 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-11T22:22:22.084Z,1620771742.084 [Default:CheckIn] Stopped 2021-05-11T22:22:22.085Z,1620771742.085 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T22:22:22.085Z,1620771742.085 [Default:CheckIn](INFO): Running loop #9 2021-05-11T22:22:22.085Z,1620771742.085 [Default:CheckIn] Running Loop=9 2021-05-11T22:22:22.085Z,1620771742.085 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-11T22:22:22.085Z,1620771742.085 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-11T22:22:24.095Z,1620771744.095 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222223.00,A,3648.14792,N,12147.29174,W,0.855,224.42,110521,,,A*79 2021-05-11T22:22:24.097Z,1620771744.097 [NAL9602](INFO): GPS fix at 20210511T222223: (36.802465, -121.788196) 2021-05-11T22:22:24.123Z,1620771744.123 [Default:CheckIn:Read_GPS] Stopped 2021-05-11T22:22:24.123Z,1620771744.123 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-11T22:22:29.745Z,1620771749.745 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-05-11T22:22:31.511Z,1620771751.511 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210511T214239/Courier0021.lzma 2021-05-11T22:22:32.521Z,1620771752.521 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0021.lzma.bak 2021-05-11T22:22:32.521Z,1620771752.521 [DataOverHttps](INFO): SBD MOMSN=15640189 2021-05-11T22:22:35.825Z,1620771755.825 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:22:50.856Z,1620771770.856 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:22:53.827Z,1620771773.827 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20210511T214239/Express0022.lzma 2021-05-11T22:22:54.829Z,1620771774.829 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0022.lzma.bak 2021-05-11T22:22:54.829Z,1620771774.829 [DataOverHttps](INFO): SBD MOMSN=15640192 2021-05-11T22:22:54.935Z,1620771774.935 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-05-11T22:22:55.011Z,1620771775.011 [NAL9602](ERROR): received: +CSQ:0 OK43, 0, 0, 0, 0 OK 2021-05-11T22:22:56.185Z,1620771776.185 [Default:CheckIn:Read_Iridium] Stopped 2021-05-11T22:22:56.185Z,1620771776.185 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-11T22:22:56.185Z,1620771776.185 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-11T22:23:05.417Z,1620771785.417 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:23:20.797Z,1620771800.797 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:23:35.721Z,1620771815.721 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:23:50.676Z,1620771830.676 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:24:03.992Z,1620771843.992 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.723339 2021-05-11T22:24:06.417Z,1620771846.417 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:24:14.304Z,1620771854.304 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003355 2021-05-11T22:24:22.175Z,1620771862.175 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:24:37.942Z,1620771877.942 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:27:26.416Z,1620772046.416 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-05-11T22:27:56.764Z,1620772076.764 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-11T22:27:56.764Z,1620772076.764 [Default:CheckIn:C.Wait] Stopped 2021-05-11T22:27:56.764Z,1620772076.764 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T22:27:56.765Z,1620772076.765 [Default:CheckIn:D] Running Loop=1 2021-05-11T22:27:57.126Z,1620772077.126 [NAL9602](INFO): Not Powering down - fast GPS 2021-05-11T22:27:57.170Z,1620772077.170 [Default:CheckIn:D] Stopped 2021-05-11T22:27:57.170Z,1620772077.170 [Default:CheckIn:E] Running Loop=1 2021-05-11T22:27:57.538Z,1620772077.538 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.954036 min 2021-05-11T22:27:57.538Z,1620772077.538 [Default:CheckIn:E] Stopped 2021-05-11T22:27:57.538Z,1620772077.538 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-11T22:27:57.538Z,1620772077.538 [Default:CheckIn] Stopped 2021-05-11T22:27:57.539Z,1620772077.539 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T22:27:57.539Z,1620772077.539 [Default:CheckIn](INFO): Running loop #10 2021-05-11T22:27:57.539Z,1620772077.539 [Default:CheckIn] Running Loop=10 2021-05-11T22:27:57.539Z,1620772077.539 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-11T22:27:57.539Z,1620772077.539 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-11T22:27:59.563Z,1620772079.563 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222758.00,A,3648.16790,N,12147.27555,W,0.292,224.42,110521,,,A*78 2021-05-11T22:27:59.569Z,1620772079.569 [NAL9602](INFO): GPS fix at 20210511T222758: (36.802798, -121.787926) 2021-05-11T22:27:59.584Z,1620772079.584 [Default:CheckIn:Read_GPS] Stopped 2021-05-11T22:27:59.584Z,1620772079.584 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-11T22:28:06.895Z,1620772086.895 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210511T214239/Courier0024.lzma 2021-05-11T22:28:07.897Z,1620772087.897 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0024.lzma.bak 2021-05-11T22:28:07.897Z,1620772087.897 [DataOverHttps](INFO): SBD MOMSN=15640216 2021-05-11T22:28:24.267Z,1620772104.267 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20210511T214239/Express0025.lzma 2021-05-11T22:28:25.269Z,1620772105.269 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0025.lzma.bak 2021-05-11T22:28:25.269Z,1620772105.269 [DataOverHttps](INFO): SBD MOMSN=15640220 2021-05-11T22:28:26.619Z,1620772106.619 [Default:CheckIn:Read_Iridium] Stopped 2021-05-11T22:28:26.619Z,1620772106.619 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-11T22:28:26.619Z,1620772106.619 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-11T22:32:01.963Z,1620772321.963 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-05-11T22:32:07.176Z,1620772327.176 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2021-05-11T22:32:07.176Z,1620772327.176 [DropWeight] Hardware Fault, FailCount= 1 2021-05-11T22:32:07.176Z,1620772327.176 [DropWeight](ERROR): Hardware Fault 2021-05-11T22:32:07.286Z,1620772327.286 [CommandLine](FAULT): Scheduling is paused 2021-05-11T22:32:07.286Z,1620772327.286 [CBIT](INFO): Critical error at 20210511T223207 2021-05-11T22:32:07.288Z,1620772327.288 [CBIT](ERROR): Hardware Fault in component: DropWeight 2021-05-11T22:32:07.289Z,1620772327.289 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2021-05-11T22:32:07.695Z,1620772327.695 [CBIT](INFO): Critical error at 20210511T223207 2021-05-11T22:32:07.981Z,1620772327.981 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:32:23.737Z,1620772343.737 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:32:39.089Z,1620772359.089 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:32:54.442Z,1620772374.442 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:33:01.316Z,1620772381.316 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-05-11T22:33:09.793Z,1620772389.793 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:33:25.560Z,1620772405.560 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:33:27.226Z,1620772407.226 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-11T22:33:27.226Z,1620772407.226 [Default:CheckIn:C.Wait] Stopped 2021-05-11T22:33:27.226Z,1620772407.226 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T22:33:27.226Z,1620772407.226 [Default:CheckIn:D] Running Loop=1 2021-05-11T22:33:27.613Z,1620772407.613 [Default:CheckIn:D] Stopped 2021-05-11T22:33:27.613Z,1620772407.613 [Default:CheckIn:E] Running Loop=1 2021-05-11T22:33:28.001Z,1620772408.001 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.461410 min 2021-05-11T22:33:28.001Z,1620772408.001 [Default:CheckIn:E] Stopped 2021-05-11T22:33:28.001Z,1620772408.001 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-11T22:33:28.001Z,1620772408.001 [Default:CheckIn] Stopped 2021-05-11T22:33:28.002Z,1620772408.002 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T22:33:28.002Z,1620772408.002 [Default:CheckIn](INFO): Running loop #11 2021-05-11T22:33:28.002Z,1620772408.002 [Default:CheckIn] Running Loop=11 2021-05-11T22:33:28.002Z,1620772408.002 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-11T22:33:28.002Z,1620772408.002 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-11T22:33:30.002Z,1620772410.002 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223329.00,A,3648.15677,N,12147.28045,W,2.119,190.19,110521,,,A*7B 2021-05-11T22:33:30.004Z,1620772410.004 [NAL9602](INFO): GPS fix at 20210511T223329: (36.802613, -121.788008) 2021-05-11T22:33:30.054Z,1620772410.054 [Default:CheckIn:Read_GPS] Stopped 2021-05-11T22:33:30.054Z,1620772410.054 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-11T22:33:41.045Z,1620772421.045 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:33:41.967Z,1620772421.967 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20210511T214239/Courier0027.lzma 2021-05-11T22:33:42.970Z,1620772422.970 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0027.lzma.bak 2021-05-11T22:33:42.970Z,1620772422.970 [DataOverHttps](INFO): SBD MOMSN=15640235 2021-05-11T22:33:56.803Z,1620772436.803 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:34:02.473Z,1620772442.473 [NAL9602](INFO): Not Powering down - fast GPS 2021-05-11T22:34:05.947Z,1620772445.947 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20210511T214239/Express0028.lzma 2021-05-11T22:34:06.949Z,1620772446.949 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0028.lzma.bak 2021-05-11T22:34:06.949Z,1620772446.949 [DataOverHttps](INFO): SBD MOMSN=15640240 2021-05-11T22:34:08.145Z,1620772448.145 [Default:CheckIn:Read_Iridium] Stopped 2021-05-11T22:34:08.145Z,1620772448.145 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-11T22:34:08.145Z,1620772448.145 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-11T22:34:12.557Z,1620772452.557 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:39:08.714Z,1620772748.714 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-11T22:39:08.714Z,1620772748.714 [Default:CheckIn:C.Wait] Stopped 2021-05-11T22:39:08.714Z,1620772748.714 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T22:39:08.714Z,1620772748.714 [Default:CheckIn:D] Running Loop=1 2021-05-11T22:39:09.109Z,1620772749.109 [Default:CheckIn:D] Stopped 2021-05-11T22:39:09.109Z,1620772749.109 [Default:CheckIn:E] Running Loop=1 2021-05-11T22:39:09.508Z,1620772749.508 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.153019 min 2021-05-11T22:39:09.508Z,1620772749.508 [Default:CheckIn:E] Stopped 2021-05-11T22:39:09.508Z,1620772749.508 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-11T22:39:09.508Z,1620772749.508 [Default:CheckIn] Stopped 2021-05-11T22:39:09.509Z,1620772749.509 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T22:39:09.509Z,1620772749.509 [Default:CheckIn](INFO): Running loop #12 2021-05-11T22:39:09.509Z,1620772749.509 [Default:CheckIn] Running Loop=12 2021-05-11T22:39:09.509Z,1620772749.509 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-11T22:39:09.509Z,1620772749.509 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-11T22:39:11.526Z,1620772751.526 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223910.00,A,3648.18161,N,12147.27294,W,0.719,30.75,110521,,,A*42 2021-05-11T22:39:11.529Z,1620772751.529 [NAL9602](INFO): GPS fix at 20210511T223910: (36.803027, -121.787882) 2021-05-11T22:39:11.538Z,1620772751.538 [Default:CheckIn:Read_GPS] Stopped 2021-05-11T22:39:11.538Z,1620772751.538 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-11T22:39:19.316Z,1620772759.316 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210511T214239/Courier0030.lzma 2021-05-11T22:39:20.317Z,1620772760.317 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0030.lzma.bak 2021-05-11T22:39:20.317Z,1620772760.317 [DataOverHttps](INFO): SBD MOMSN=15640261 2021-05-11T22:39:36.135Z,1620772776.135 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20210511T214239/Express0031.lzma 2021-05-11T22:39:37.137Z,1620772777.137 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0031.lzma.bak 2021-05-11T22:39:37.137Z,1620772777.137 [DataOverHttps](INFO): SBD MOMSN=15640264 2021-05-11T22:39:38.659Z,1620772778.659 [Default:CheckIn:Read_Iridium] Stopped 2021-05-11T22:39:38.659Z,1620772778.659 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-11T22:39:38.659Z,1620772778.659 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-11T22:39:42.224Z,1620772782.224 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-05-11T22:39:42.307Z,1620772782.307 [NAL9602](ERROR): received: +CSQ:0 OK43, 0, 0, 0, 0 OK 2021-05-11T22:41:48.272Z,1620772908.272 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:42:04.026Z,1620772924.026 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:42:20.247Z,1620772940.247 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:42:35.944Z,1620772955.944 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:42:50.889Z,1620772970.889 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:43:06.647Z,1620772986.647 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:43:21.996Z,1620773001.996 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:43:37.351Z,1620773017.351 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:43:53.105Z,1620773033.105 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T22:44:13.308Z,1620773053.308 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-05-11T22:44:39.174Z,1620773079.174 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-11T22:44:39.174Z,1620773079.174 [Default:CheckIn:C.Wait] Stopped 2021-05-11T22:44:39.174Z,1620773079.174 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T22:44:39.176Z,1620773079.176 [Default:CheckIn:D] Running Loop=1 2021-05-11T22:44:39.591Z,1620773079.591 [Default:CheckIn:D] Stopped 2021-05-11T22:44:39.591Z,1620773079.591 [Default:CheckIn:E] Running Loop=1 2021-05-11T22:44:39.983Z,1620773079.983 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.661051 min 2021-05-11T22:44:39.983Z,1620773079.983 [Default:CheckIn:E] Stopped 2021-05-11T22:44:39.984Z,1620773079.984 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-11T22:44:39.984Z,1620773079.984 [Default:CheckIn] Stopped 2021-05-11T22:44:39.984Z,1620773079.984 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T22:44:39.985Z,1620773079.985 [Default:CheckIn](INFO): Running loop #13 2021-05-11T22:44:39.986Z,1620773079.986 [Default:CheckIn] Running Loop=13 2021-05-11T22:44:39.987Z,1620773079.987 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-11T22:44:39.988Z,1620773079.988 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-11T22:44:41.999Z,1620773081.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224441.00,A,3648.12033,N,12147.33193,W,0.875,215.07,110521,,,A*74 2021-05-11T22:44:42.001Z,1620773082.001 [NAL9602](INFO): GPS fix at 20210511T224441: (36.802005, -121.788865) 2021-05-11T22:44:42.012Z,1620773082.012 [Default:CheckIn:Read_GPS] Stopped 2021-05-11T22:44:42.012Z,1620773082.012 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-11T22:44:49.835Z,1620773089.835 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210511T214239/Courier0033.lzma 2021-05-11T22:44:50.838Z,1620773090.838 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0033.lzma.bak 2021-05-11T22:44:50.838Z,1620773090.838 [DataOverHttps](INFO): SBD MOMSN=15640275 2021-05-11T22:45:06.539Z,1620773106.539 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20210511T214239/Express0034.lzma 2021-05-11T22:45:07.541Z,1620773107.541 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0034.lzma.bak 2021-05-11T22:45:07.541Z,1620773107.541 [DataOverHttps](INFO): SBD MOMSN=15640278 2021-05-11T22:45:08.680Z,1620773108.680 [Default:CheckIn:Read_Iridium] Stopped 2021-05-11T22:45:08.680Z,1620773108.680 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-11T22:45:08.680Z,1620773108.680 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-11T22:45:14.433Z,1620773114.433 [NAL9602](INFO): Not Powering down - fast GPS 2021-05-11T22:47:07.454Z,1620773227.454 [CBIT](INFO): Clearing failed state for component DropWeight 2021-05-11T22:47:07.454Z,1620773227.454 [DropWeight] No Fault, FailCount= 1 2021-05-11T22:50:09.263Z,1620773409.263 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-11T22:50:09.263Z,1620773409.263 [Default:CheckIn:C.Wait] Stopped 2021-05-11T22:50:09.263Z,1620773409.263 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T22:50:09.263Z,1620773409.263 [Default:CheckIn:D] Running Loop=1 2021-05-11T22:50:09.654Z,1620773409.654 [Default:CheckIn:D] Stopped 2021-05-11T22:50:09.654Z,1620773409.654 [Default:CheckIn:E] Running Loop=1 2021-05-11T22:50:10.070Z,1620773410.070 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.162101 min 2021-05-11T22:50:10.070Z,1620773410.070 [Default:CheckIn:E] Stopped 2021-05-11T22:50:10.070Z,1620773410.070 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-11T22:50:10.070Z,1620773410.070 [Default:CheckIn] Stopped 2021-05-11T22:50:10.070Z,1620773410.070 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T22:50:10.070Z,1620773410.070 [Default:CheckIn](INFO): Running loop #14 2021-05-11T22:50:10.070Z,1620773410.070 [Default:CheckIn] Running Loop=14 2021-05-11T22:50:10.071Z,1620773410.071 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-11T22:50:10.071Z,1620773410.071 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-11T22:50:42.834Z,1620773442.834 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-05-11T22:50:42.915Z,1620773442.915 [NAL9602](ERROR): received: +CSQ:0 OK43, 0, 0, 0, 0 OK 2021-05-11T22:50:59.740Z,1620773459.740 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5. 2021-05-11T22:50:59.742Z,1620773459.742 [BPC1](FAULT): Failed to receive data from 3 sticks prior to timeout. Missing stick IDs are: 40, 41, 47. 2021-05-11T22:50:59.742Z,1620773459.742 [BPC1] Data Fault, FailCount= 4 2021-05-11T22:50:59.743Z,1620773459.743 [BPC1](ERROR): Data Fault 2021-05-11T22:50:59.785Z,1620773459.785 [CBIT](ERROR): Data Fault in component: BPC1 2021-05-11T22:55:10.228Z,1620773710.228 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-05-11T22:50:10.1Z 2021-05-11T22:55:10.228Z,1620773710.228 [Default:CheckIn:Read_GPS] Stopped 2021-05-11T22:55:10.229Z,1620773710.229 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-11T22:55:14.264Z,1620773714.264 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-05-11T22:55:17.619Z,1620773717.619 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20210511T214239/Courier0036.lzma 2021-05-11T22:55:18.621Z,1620773718.621 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0036.lzma.bak 2021-05-11T22:55:18.621Z,1620773718.621 [DataOverHttps](INFO): SBD MOMSN=15640299 2021-05-11T22:55:34.167Z,1620773734.167 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20210511T214239/Express0037.lzma 2021-05-11T22:55:35.169Z,1620773735.169 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0037.lzma.bak 2021-05-11T22:55:35.169Z,1620773735.169 [DataOverHttps](INFO): SBD MOMSN=15640301 2021-05-11T22:55:36.498Z,1620773736.498 [Default:CheckIn:Read_Iridium] Stopped 2021-05-11T22:55:36.499Z,1620773736.499 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-11T22:55:36.499Z,1620773736.499 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-11T22:55:44.968Z,1620773744.968 [NAL9602](INFO): Not Powering down - fast GPS 2021-05-11T23:00:37.069Z,1620774037.069 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-11T23:00:37.069Z,1620774037.069 [Default:CheckIn:C.Wait] Stopped 2021-05-11T23:00:37.069Z,1620774037.069 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T23:00:37.070Z,1620774037.070 [Default:CheckIn:D] Running Loop=1 2021-05-11T23:00:37.474Z,1620774037.474 [Default:CheckIn:D] Stopped 2021-05-11T23:00:37.474Z,1620774037.474 [Default:CheckIn:E] Running Loop=1 2021-05-11T23:00:37.874Z,1620774037.874 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.625757 min 2021-05-11T23:00:37.874Z,1620774037.874 [Default:CheckIn:E] Stopped 2021-05-11T23:00:37.875Z,1620774037.875 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-11T23:00:37.875Z,1620774037.875 [Default:CheckIn] Stopped 2021-05-11T23:00:37.875Z,1620774037.875 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T23:00:37.875Z,1620774037.875 [Default:CheckIn](INFO): Running loop #15 2021-05-11T23:00:37.875Z,1620774037.875 [Default:CheckIn] Running Loop=15 2021-05-11T23:00:37.875Z,1620774037.875 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-11T23:00:37.875Z,1620774037.875 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-11T23:00:39.489Z,1620774039.489 [NAL9602](FAULT): GPS failed to acquire within timeout. 2021-05-11T23:00:39.489Z,1620774039.489 [NAL9602] Data Fault, FailCount= 1 2021-05-11T23:00:39.489Z,1620774039.489 [NAL9602](ERROR): Data Fault 2021-05-11T23:00:39.508Z,1620774039.508 [CBIT](ERROR): Data Fault in component: NAL9602 2021-05-11T23:00:39.884Z,1620774039.884 [NAL9602](INFO): Powering down 2021-05-11T23:00:40.714Z,1620774040.714 [CBIT](INFO): Clearing failed state for component NAL9602 2021-05-11T23:00:40.714Z,1620774040.714 [NAL9602] No Fault, FailCount= 1 2021-05-11T23:01:00.918Z,1620774060.918 [CBIT](INFO): Clearing failed state for component BPC1 2021-05-11T23:01:00.919Z,1620774060.918 [BPC1] No Fault, FailCount= 4 2021-05-11T23:01:10.182Z,1620774070.182 [NAL9602](INFO): Powering up NAL9602 2021-05-11T23:01:21.097Z,1620774081.097 [NAL9602](INFO): NAL9602 initialized 2021-05-11T23:03:31.580Z,1620774211.580 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2021-05-11T23:03:37.641Z,1620774217.641 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:03:52.185Z,1620774232.185 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:04:08.004Z,1620774248.004 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:04:23.293Z,1620774263.293 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:04:39.049Z,1620774279.049 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:04:53.996Z,1620774293.996 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:05:09.756Z,1620774309.756 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:05:25.509Z,1620774325.509 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:05:38.075Z,1620774338.076 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-05-11T23:00:37.9Z 2021-05-11T23:05:38.076Z,1620774338.076 [Default:CheckIn:Read_GPS] Stopped 2021-05-11T23:05:38.076Z,1620774338.076 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-11T23:05:41.061Z,1620774341.061 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:05:48.359Z,1620774348.359 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210511T214239/Courier0039.lzma 2021-05-11T23:05:49.361Z,1620774349.361 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0039.lzma.bak 2021-05-11T23:05:49.361Z,1620774349.361 [DataOverHttps](INFO): SBD MOMSN=15640312 2021-05-11T23:06:05.175Z,1620774365.175 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20210511T214239/Express0040.lzma 2021-05-11T23:06:06.177Z,1620774366.177 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0040.lzma.bak 2021-05-11T23:06:06.178Z,1620774366.178 [DataOverHttps](INFO): SBD MOMSN=15640314 2021-05-11T23:06:07.348Z,1620774367.348 [Default:CheckIn:Read_Iridium] Stopped 2021-05-11T23:06:07.348Z,1620774367.348 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-11T23:06:07.348Z,1620774367.348 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-11T23:06:24.355Z,1620774384.355 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-05-11T23:06:55.028Z,1620774415.028 [NAL9602](INFO): Not Powering down - fast GPS 2021-05-11T23:11:07.908Z,1620774667.908 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-11T23:11:07.908Z,1620774667.908 [Default:CheckIn:C.Wait] Stopped 2021-05-11T23:11:07.908Z,1620774667.908 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T23:11:07.908Z,1620774667.908 [Default:CheckIn:D] Running Loop=1 2021-05-11T23:11:08.322Z,1620774668.322 [Default:CheckIn:D] Stopped 2021-05-11T23:11:08.322Z,1620774668.322 [Default:CheckIn:E] Running Loop=1 2021-05-11T23:11:08.745Z,1620774668.745 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.139893 min 2021-05-11T23:11:08.745Z,1620774668.745 [Default:CheckIn:E] Stopped 2021-05-11T23:11:08.745Z,1620774668.745 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-11T23:11:08.745Z,1620774668.745 [Default:CheckIn] Stopped 2021-05-11T23:11:08.746Z,1620774668.746 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T23:11:08.746Z,1620774668.746 [Default:CheckIn](INFO): Running loop #16 2021-05-11T23:11:08.746Z,1620774668.746 [Default:CheckIn] Running Loop=16 2021-05-11T23:11:08.746Z,1620774668.746 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-11T23:11:08.746Z,1620774668.746 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-11T23:11:41.437Z,1620774701.437 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2021-05-11T23:11:41.511Z,1620774701.511 [NAL9602](ERROR): received: +CSQ:0 OK43, 0, 0, 0, 0 OK 2021-05-11T23:13:13.541Z,1620774793.541 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:13:29.297Z,1620774809.297 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:13:44.664Z,1620774824.664 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:13:59.193Z,1620774839.193 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:14:15.369Z,1620774855.369 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:14:31.109Z,1620774871.109 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:14:46.865Z,1620774886.865 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:15:02.621Z,1620774902.621 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:15:18.388Z,1620774918.388 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-05-11T23:16:08.898Z,1620774968.898 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-05-11T23:11:08.7Z 2021-05-11T23:16:08.898Z,1620774968.898 [Default:CheckIn:Read_GPS] Stopped 2021-05-11T23:16:08.899Z,1620774968.899 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-11T23:16:13.325Z,1620774973.325 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-05-11T23:16:16.703Z,1620774976.703 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210511T214239/Courier0042.lzma 2021-05-11T23:16:17.705Z,1620774977.705 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Courier0042.lzma.bak 2021-05-11T23:16:17.705Z,1620774977.705 [DataOverHttps](INFO): SBD MOMSN=15640338 2021-05-11T23:16:33.279Z,1620774993.279 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20210511T214239/Express0043.lzma 2021-05-11T23:16:34.281Z,1620774994.281 [DataOverHttps](INFO): Moved sent file to Logs/20210511T214239/Express0043.lzma.bak 2021-05-11T23:16:34.281Z,1620774994.281 [DataOverHttps](INFO): SBD MOMSN=15640340 2021-05-11T23:16:35.578Z,1620774995.578 [Default:CheckIn:Read_Iridium] Stopped 2021-05-11T23:16:35.578Z,1620774995.578 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-11T23:16:35.578Z,1620774995.578 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-11T23:16:44.036Z,1620775004.036 [NAL9602](INFO): Not Powering down - fast GPS 2021-05-11T23:17:08.692Z,1620775028.692 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2021-05-11T23:17:08.692Z,1620775028.692 [DropWeight] Hardware Fault, FailCount= 1 2021-05-11T23:17:08.692Z,1620775028.692 [DropWeight](ERROR): Hardware Fault 2021-05-11T23:17:08.709Z,1620775028.709 [CBIT](INFO): Critical error at 20210511T231708 2021-05-11T23:17:08.711Z,1620775028.711 [CBIT](ERROR): Hardware Fault in component: DropWeight 2021-05-11T23:17:08.712Z,1620775028.712 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2021-05-11T23:17:09.116Z,1620775029.116 [CBIT](INFO): Critical error at 20210511T231708 2021-05-11T23:19:03.537Z,1620775143.537 [CommandLine](IMPORTANT): got command quit 2021-05-11T23:19:04.548Z,1620775144.548 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-11T23:19:04.548Z,1620775144.548 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:04.683Z,1620775144.683 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-05-11T23:19:04.683Z,1620775144.683 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:04.684Z,1620775144.684 [CommandLine](INFO): Join timeout helper Thread ID is 2704 2021-05-11T23:19:04.687Z,1620775144.687 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-05-11T23:19:04.687Z,1620775144.687 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:04.688Z,1620775144.688 [NavChartDb](INFO): Join timeout helper Thread ID is 2705 2021-05-11T23:19:04.995Z,1620775144.995 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-11T23:19:04.996Z,1620775144.996 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:05.024Z,1620775145.024 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2021-05-11T23:19:05.024Z,1620775145.024 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:05.024Z,1620775145.024 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2706 2021-05-11T23:19:05.343Z,1620775145.343 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-11T23:19:05.344Z,1620775145.344 [WetLabsBB2FL](INFO): Powering down 2021-05-11T23:19:05.345Z,1620775145.345 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:05.347Z,1620775145.347 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2021-05-11T23:19:05.347Z,1620775145.347 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:05.348Z,1620775145.348 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2707 2021-05-11T23:19:05.419Z,1620775145.419 [CTD_Seabird](INFO): Powering down 2021-05-11T23:19:05.431Z,1620775145.431 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-11T23:19:05.431Z,1620775145.431 [CTD_Seabird](INFO): Powering down 2021-05-11T23:19:05.443Z,1620775145.443 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:05.451Z,1620775145.451 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-05-11T23:19:05.451Z,1620775145.451 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:05.452Z,1620775145.452 [Radio_Surface](INFO): Join timeout helper Thread ID is 2708 2021-05-11T23:19:05.811Z,1620775145.811 [Radio_Surface](INFO): Powering down 2021-05-11T23:19:05.812Z,1620775145.812 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-11T23:19:05.813Z,1620775145.813 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:05.844Z,1620775145.844 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-05-11T23:19:05.844Z,1620775145.844 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:05.845Z,1620775145.845 [Onboard](INFO): Join timeout helper Thread ID is 2709 2021-05-11T23:19:05.867Z,1620775145.867 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2021-05-11T23:19:06.743Z,1620775146.743 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-11T23:19:06.744Z,1620775146.744 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:06.748Z,1620775146.748 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2021-05-11T23:19:06.748Z,1620775146.748 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:06.749Z,1620775146.749 [DataOverHttps](INFO): Join timeout helper Thread ID is 2710 2021-05-11T23:19:06.871Z,1620775146.871 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-11T23:19:06.872Z,1620775146.872 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:06.888Z,1620775146.888 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2021-05-11T23:19:06.888Z,1620775146.888 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:06.888Z,1620775146.888 [BackseatComponent](INFO): Join timeout helper Thread ID is 2711 2021-05-11T23:19:06.947Z,1620775146.947 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-11T23:19:06.948Z,1620775146.948 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:06.968Z,1620775146.968 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2021-05-11T23:19:06.968Z,1620775146.968 [logger ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:06.968Z,1620775146.968 [logger](INFO): Join timeout helper Thread ID is 2712 2021-05-11T23:19:06.979Z,1620775146.979 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-11T23:19:06.980Z,1620775146.980 [logger ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:06.988Z,1620775146.988 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2021-05-11T23:19:06.988Z,1620775146.988 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:06.988Z,1620775146.988 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2021-05-11T23:19:06.988Z,1620775146.988 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:06.989Z,1620775146.989 [controlThread](INFO): Join timeout helper Thread ID is 2713 2021-05-11T23:19:07.027Z,1620775147.027 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-11T23:19:07.028Z,1620775147.028 [controlThread](DEBUG): Uninitializing ControlThread 2021-05-11T23:19:07.028Z,1620775147.028 [AHRS_M2](INFO): Powering down 2021-05-11T23:19:07.100Z,1620775147.100 [NAL9602](INFO): Powering down 2021-05-11T23:19:07.102Z,1620775147.102 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2021-05-11T23:19:07.103Z,1620775147.103 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2021-05-11T23:19:07.104Z,1620775147.104 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2021-05-11T23:19:07.104Z,1620775147.104 [MissionManager](INFO): Uninitializing Mission Default 2021-05-11T23:19:07.104Z,1620775147.104 [Default] Stopped 2021-05-11T23:19:07.104Z,1620775147.104 [Default](DEBUG): Aggregate::uninitialize Default 2021-05-11T23:19:07.104Z,1620775147.104 [Default:B.GoToSurface] Stopped 2021-05-11T23:19:07.105Z,1620775147.105 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-05-11T23:19:07.105Z,1620775147.105 [Default:CheckIn] Stopped 2021-05-11T23:19:07.105Z,1620775147.105 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-11T23:19:07.105Z,1620775147.105 [Default:CheckIn:C.Wait] Stopped 2021-05-11T23:19:07.105Z,1620775147.105 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-11T23:19:07.108Z,1620775147.108 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2021-05-11T23:19:07.108Z,1620775147.108 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2021-05-11T23:19:07.109Z,1620775147.109 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2021-05-11T23:19:07.109Z,1620775147.109 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2021-05-11T23:19:07.109Z,1620775147.109 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2021-05-11T23:19:07.109Z,1620775147.109 [BuoyancyServo](INFO): Powering down 2021-05-11T23:19:07.123Z,1620775147.123 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-05-11T23:19:07.124Z,1620775147.124 [ElevatorServo](INFO): Powering down 2021-05-11T23:19:07.124Z,1620775147.124 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-05-11T23:19:07.124Z,1620775147.124 [MassServo](INFO): Powering down 2021-05-11T23:19:07.125Z,1620775147.125 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-05-11T23:19:07.125Z,1620775147.125 [RudderServo](INFO): Powering down 2021-05-11T23:19:07.126Z,1620775147.126 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-05-11T23:19:07.126Z,1620775147.126 [ThrusterServo](INFO): Powering down 2021-05-11T23:19:07.127Z,1620775147.127 [SBIT](DEBUG): Uninitialize SBIT Component. 2021-05-11T23:19:07.128Z,1620775147.128 [IBIT](DEBUG): Uninitialize IBIT Component. 2021-05-11T23:19:07.128Z,1620775147.128 [CBIT](DEBUG): Uninitialize CBIT Component. 2021-05-11T23:19:07.128Z,1620775147.128 [CBIT](DEBUG): Powering off loads. 2021-05-11T23:19:07.139Z,1620775147.139 [CBIT](DEBUG): Disabling WDT. 2021-05-11T23:19:07.151Z,1620775147.151 [CBIT](DEBUG): Opening all GF detection circuits. 2021-05-11T23:19:07.152Z,1620775147.152 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:07.214Z,1620775147.214 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:07.217Z,1620775147.217 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:07.223Z,1620775147.223 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:07.267Z,1620775147.267 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:07.301Z,1620775147.301 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:07.305Z,1620775147.305 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:07.338Z,1620775147.338 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-05-11T23:19:07.417Z,1620775147.417 [logger ThreadHandler](INFO): Thread cancelled.