2023-02-15T21:40:04.562Z,1676497204.562 [DataOverHttps](INFO): Received command: restart logs 2023-02-15T21:40:04.573Z,1676497204.573 [CommandExec](IMPORTANT): got command restart logs 2023-02-15T21:44:28.124Z,1676497468.124 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T21:44:28.124Z,1676497468.124 [Default:CheckIn:C.Wait] Stopped 2023-02-15T21:44:28.124Z,1676497468.124 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T21:44:28.125Z,1676497468.125 [Default:CheckIn:D] Running Loop=1 2023-02-15T21:44:28.506Z,1676497468.506 [Default:CheckIn:D] Stopped 2023-02-15T21:44:28.506Z,1676497468.506 [Default:CheckIn:E] Running Loop=1 2023-02-15T21:44:28.915Z,1676497468.915 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 14.329552 min 2023-02-15T21:44:28.915Z,1676497468.915 [Default:CheckIn:E] Stopped 2023-02-15T21:44:28.915Z,1676497468.915 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T21:44:28.915Z,1676497468.915 [Default:CheckIn] Stopped 2023-02-15T21:44:28.915Z,1676497468.915 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T21:44:28.916Z,1676497468.916 [Default:CheckIn](INFO): Running loop #3 2023-02-15T21:44:28.916Z,1676497468.916 [Default:CheckIn] Running Loop=3 2023-02-15T21:44:28.916Z,1676497468.916 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T21:44:28.916Z,1676497468.916 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T21:44:30.923Z,1676497470.923 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214430.00,A,3648.33229,N,12149.26800,W,1.866,84.65,150223,,,D*4B 2023-02-15T21:44:30.926Z,1676497470.926 [NAL9602](INFO): GPS fix at 20230215T214430: (36.805538, -121.821133) 2023-02-15T21:44:30.949Z,1676497470.949 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T21:44:30.949Z,1676497470.949 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T21:44:40.073Z,1676497480.073 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230215T183915/Courier0033.lzma 2023-02-15T21:44:41.074Z,1676497481.074 [DataOverHttps](INFO): Moved sent file to Logs/20230215T183915/Courier0033.lzma.bak 2023-02-15T21:44:41.074Z,1676497481.074 [DataOverHttps](INFO): SBD MOMSN=17475548 2023-02-15T21:45:00.134Z,1676497500.134 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20230215T214004/Courier0000.lzma 2023-02-15T21:45:01.014Z,1676497501.014 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0000.lzma.bak 2023-02-15T21:45:01.014Z,1676497501.014 [DataOverHttps](INFO): SBD MOMSN=17475550 2023-02-15T21:45:22.975Z,1676497522.975 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20230215T183915/Express0034.lzma 2023-02-15T21:45:23.978Z,1676497523.978 [DataOverHttps](INFO): Moved sent file to Logs/20230215T183915/Express0034.lzma.bak 2023-02-15T21:45:23.978Z,1676497523.978 [DataOverHttps](INFO): SBD MOMSN=17475591 2023-02-15T21:45:29.951Z,1676497529.951 [NAL9602](INFO): SBD MO Status=2, MOMSN=39099, MT Status=2, MTMSN=0 2023-02-15T21:45:29.951Z,1676497529.951 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T21:45:39.797Z,1676497539.797 [DataOverHttps](INFO): Sending 401 bytes from file Logs/20230215T214004/Express0001.lzma 2023-02-15T21:45:40.798Z,1676497540.798 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0001.lzma.bak 2023-02-15T21:45:40.798Z,1676497540.798 [DataOverHttps](INFO): SBD MOMSN=17475597 2023-02-15T21:45:56.716Z,1676497556.716 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20230215T214004/Express0004.lzma 2023-02-15T21:45:57.718Z,1676497557.718 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0004.lzma.bak 2023-02-15T21:45:57.718Z,1676497557.718 [DataOverHttps](INFO): SBD MOMSN=17475605 2023-02-15T21:45:59.069Z,1676497559.069 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T21:45:59.069Z,1676497559.069 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T21:45:59.069Z,1676497559.069 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T21:46:07.527Z,1676497567.527 [NAL9602](INFO): SBD MO Status=2, MOMSN=39099, MT Status=2, MTMSN=0 2023-02-15T21:46:07.527Z,1676497567.527 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T21:46:38.663Z,1676497598.663 [NAL9602](INFO): SBD MO Status=0, MOMSN=39099, MT Status=0, MTMSN=0 2023-02-15T21:46:38.663Z,1676497598.663 [NAL9602](INFO): No messages in MT queue 2023-02-15T21:47:09.334Z,1676497629.334 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T21:50:28.118Z,1676497828.118 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T21:50:43.068Z,1676497843.068 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T21:50:58.420Z,1676497858.420 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T21:50:59.643Z,1676497859.643 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T21:50:59.643Z,1676497859.643 [Default:CheckIn:C.Wait] Stopped 2023-02-15T21:50:59.643Z,1676497859.643 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T21:50:59.643Z,1676497859.643 [Default:CheckIn:D] Running Loop=1 2023-02-15T21:51:00.071Z,1676497860.071 [Default:CheckIn:D] Stopped 2023-02-15T21:51:00.072Z,1676497860.072 [Default:CheckIn:E] Running Loop=1 2023-02-15T21:51:00.464Z,1676497860.464 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.855648 min 2023-02-15T21:51:00.464Z,1676497860.464 [Default:CheckIn:E] Stopped 2023-02-15T21:51:00.465Z,1676497860.465 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T21:51:00.465Z,1676497860.465 [Default:CheckIn] Stopped 2023-02-15T21:51:00.465Z,1676497860.465 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T21:51:00.465Z,1676497860.465 [Default:CheckIn](INFO): Running loop #4 2023-02-15T21:51:00.465Z,1676497860.465 [Default:CheckIn] Running Loop=4 2023-02-15T21:51:00.465Z,1676497860.465 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T21:51:00.465Z,1676497860.465 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T21:51:02.467Z,1676497862.467 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215101.00,A,3648.36064,N,12149.19866,W,0.272,58.13,150223,,,D*41 2023-02-15T21:51:02.470Z,1676497862.470 [NAL9602](INFO): GPS fix at 20230215T215101: (36.806011, -121.819978) 2023-02-15T21:51:02.480Z,1676497862.480 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T21:51:02.480Z,1676497862.480 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T21:51:10.683Z,1676497870.683 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0006.lzma 2023-02-15T21:51:11.686Z,1676497871.686 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0006.lzma.bak 2023-02-15T21:51:11.686Z,1676497871.686 [DataOverHttps](INFO): SBD MOMSN=17475638 2023-02-15T21:51:12.963Z,1676497872.963 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T21:51:24.287Z,1676497884.287 [NAL9602](INFO): SBD MO Status=0, MOMSN=39100, MT Status=0, MTMSN=0 2023-02-15T21:51:24.287Z,1676497884.287 [NAL9602](INFO): No messages in MT queue 2023-02-15T21:51:27.576Z,1676497887.576 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20230215T214004/Express0007.lzma 2023-02-15T21:51:28.314Z,1676497888.314 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T21:51:28.578Z,1676497888.578 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0007.lzma.bak 2023-02-15T21:51:28.578Z,1676497888.578 [DataOverHttps](INFO): SBD MOMSN=17475649 2023-02-15T21:51:29.961Z,1676497889.961 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T21:51:29.962Z,1676497889.962 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T21:51:29.962Z,1676497889.962 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T21:51:43.262Z,1676497903.262 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T21:51:54.985Z,1676497914.985 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T21:51:58.210Z,1676497918.210 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T21:52:13.159Z,1676497933.159 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T21:52:28.107Z,1676497948.107 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T21:52:42.690Z,1676497962.690 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2023-02-15T21:52:58.010Z,1676497978.010 [BPC1](INFO): Calculating totals. Valid battery stick count: 47. Valid reserve battery stick count: 5. 2023-02-15T21:52:58.012Z,1676497978.012 [BPC1](INFO): Received data from all battery sticks. 2023-02-15T21:56:30.559Z,1676498190.559 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T21:56:30.559Z,1676498190.559 [Default:CheckIn:C.Wait] Stopped 2023-02-15T21:56:30.559Z,1676498190.559 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T21:56:30.560Z,1676498190.560 [Default:CheckIn:D] Running Loop=1 2023-02-15T21:56:30.938Z,1676498190.938 [Default:CheckIn:D] Stopped 2023-02-15T21:56:30.938Z,1676498190.938 [Default:CheckIn:E] Running Loop=1 2023-02-15T21:56:31.345Z,1676498191.345 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.370091 min 2023-02-15T21:56:31.345Z,1676498191.345 [Default:CheckIn:E] Stopped 2023-02-15T21:56:31.345Z,1676498191.345 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T21:56:31.345Z,1676498191.345 [Default:CheckIn] Stopped 2023-02-15T21:56:31.345Z,1676498191.345 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T21:56:31.346Z,1676498191.346 [Default:CheckIn](INFO): Running loop #5 2023-02-15T21:56:31.346Z,1676498191.346 [Default:CheckIn] Running Loop=5 2023-02-15T21:56:31.346Z,1676498191.346 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T21:56:31.346Z,1676498191.346 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T21:56:33.363Z,1676498193.363 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215632.00,A,3648.38179,N,12149.16254,W,2.605,278.89,150223,,,D*74 2023-02-15T21:56:33.366Z,1676498193.366 [NAL9602](INFO): GPS fix at 20230215T215632: (36.806363, -121.819376) 2023-02-15T21:56:33.377Z,1676498193.377 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T21:56:33.377Z,1676498193.377 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T21:57:04.184Z,1676498224.184 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T21:57:08.098Z,1676498228.098 [NAL9602](INFO): SBD MO Status=0, MOMSN=39101, MT Status=0, MTMSN=0 2023-02-15T21:57:08.098Z,1676498228.098 [NAL9602](INFO): No messages in MT queue 2023-02-15T21:57:35.338Z,1676498255.338 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T21:57:38.800Z,1676498258.800 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T21:58:06.460Z,1676498286.460 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T21:58:51.299Z,1676498331.299 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20230215T214004/Courier0009.lzma 2023-02-15T21:58:52.298Z,1676498332.298 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0009.lzma.bak 2023-02-15T21:58:52.298Z,1676498332.298 [DataOverHttps](INFO): SBD MOMSN=17475694 2023-02-15T21:59:09.056Z,1676498349.056 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230215T214004/Express0010.lzma 2023-02-15T21:59:10.058Z,1676498350.058 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0010.lzma.bak 2023-02-15T21:59:10.058Z,1676498350.058 [DataOverHttps](INFO): SBD MOMSN=17475697 2023-02-15T21:59:11.380Z,1676498351.380 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T21:59:11.380Z,1676498351.380 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T21:59:11.380Z,1676498351.380 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T22:02:15.612Z,1676498535.612 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:02:46.736Z,1676498566.736 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:04:12.092Z,1676498652.092 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T22:04:12.092Z,1676498652.092 [Default:CheckIn:C.Wait] Stopped 2023-02-15T22:04:12.092Z,1676498652.092 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T22:04:12.092Z,1676498652.092 [Default:CheckIn:D] Running Loop=1 2023-02-15T22:04:12.486Z,1676498652.486 [Default:CheckIn:D] Stopped 2023-02-15T22:04:12.486Z,1676498652.486 [Default:CheckIn:E] Running Loop=1 2023-02-15T22:04:12.896Z,1676498652.896 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.062551 min 2023-02-15T22:04:12.896Z,1676498652.896 [Default:CheckIn:E] Stopped 2023-02-15T22:04:12.896Z,1676498652.896 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T22:04:12.896Z,1676498652.896 [Default:CheckIn] Stopped 2023-02-15T22:04:12.896Z,1676498652.896 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T22:04:12.896Z,1676498652.897 [Default:CheckIn](INFO): Running loop #6 2023-02-15T22:04:12.897Z,1676498652.897 [Default:CheckIn] Running Loop=6 2023-02-15T22:04:12.897Z,1676498652.897 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T22:04:12.897Z,1676498652.897 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T22:04:14.911Z,1676498654.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220414.00,A,3648.57607,N,12149.77841,W,15.920,270.20,150223,,,D*4F 2023-02-15T22:04:14.914Z,1676498654.914 [NAL9602](INFO): GPS fix at 20230215T220414: (36.809601, -121.829640) 2023-02-15T22:04:14.942Z,1676498654.942 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T22:04:14.942Z,1676498654.942 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T22:04:38.731Z,1676498678.731 [NAL9602](INFO): SBD MO Status=0, MOMSN=39102, MT Status=0, MTMSN=0 2023-02-15T22:04:38.731Z,1676498678.731 [NAL9602](INFO): No messages in MT queue 2023-02-15T22:05:09.437Z,1676498709.437 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T22:05:17.300Z,1676498717.300 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:05:48.428Z,1676498748.428 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:06:19.553Z,1676498779.553 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:06:50.676Z,1676498810.676 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:07:09.753Z,1676498829.753 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.731522 2023-02-15T22:07:24.403Z,1676498844.403 [NAL9602](INFO): SBD MO Status=1, MOMSN=39103, MT Status=0, MTMSN=0 2023-02-15T22:07:24.464Z,1676498844.464 [NAL9602](INFO): Sent 74 bytes from file Logs/20230215T214004/Courier0012.lzma 2023-02-15T22:07:24.464Z,1676498844.464 [NAL9602](INFO): Packets left to send: 0 2023-02-15T22:07:40.880Z,1676498860.880 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:07:45.043Z,1676498865.043 [NAL9602](INFO): SBD MO Status=2, MOMSN=39104, MT Status=2, MTMSN=0 2023-02-15T22:07:45.043Z,1676498865.043 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T22:07:55.996Z,1676498875.996 [NAL9602](INFO): SBD MO Status=1, MOMSN=39104, MT Status=0, MTMSN=0 2023-02-15T22:07:56.044Z,1676498876.044 [NAL9602](INFO): Sent 145 bytes from file Logs/20230215T214004/Express0013.lzma 2023-02-15T22:07:56.044Z,1676498876.044 [NAL9602](INFO): Packets left to send: 0 2023-02-15T22:08:06.101Z,1676498886.101 [NAL9602](INFO): SBD MO Status=0, MOMSN=39105, MT Status=0, MTMSN=0 2023-02-15T22:08:06.181Z,1676498886.181 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T22:08:06.181Z,1676498886.181 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T22:08:06.181Z,1676498886.181 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T22:08:12.012Z,1676498892.012 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:08:36.797Z,1676498916.797 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T22:08:43.136Z,1676498923.136 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:09:14.288Z,1676498954.288 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:09:45.424Z,1676498985.424 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:10:16.548Z,1676499016.548 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:10:47.680Z,1676499047.680 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:11:18.804Z,1676499078.804 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:11:49.928Z,1676499109.928 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:12:21.060Z,1676499141.060 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:12:52.196Z,1676499172.196 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:13:03.668Z,1676499183.668 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003010 2023-02-15T22:13:06.692Z,1676499186.692 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T22:13:06.692Z,1676499186.692 [Default:CheckIn:C.Wait] Stopped 2023-02-15T22:13:06.692Z,1676499186.692 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T22:13:06.692Z,1676499186.692 [Default:CheckIn:D] Running Loop=1 2023-02-15T22:13:07.087Z,1676499187.087 [Default:CheckIn:D] Stopped 2023-02-15T22:13:07.087Z,1676499187.087 [Default:CheckIn:E] Running Loop=1 2023-02-15T22:13:07.487Z,1676499187.487 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.972571 min 2023-02-15T22:13:07.487Z,1676499187.487 [Default:CheckIn:E] Stopped 2023-02-15T22:13:07.487Z,1676499187.487 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T22:13:07.487Z,1676499187.487 [Default:CheckIn] Stopped 2023-02-15T22:13:07.487Z,1676499187.487 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T22:13:07.487Z,1676499187.487 [Default:CheckIn](INFO): Running loop #7 2023-02-15T22:13:07.487Z,1676499187.487 [Default:CheckIn] Running Loop=7 2023-02-15T22:13:07.487Z,1676499187.487 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T22:13:07.488Z,1676499187.488 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T22:13:09.507Z,1676499189.507 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221308.00,A,3648.44040,N,12150.33861,W,2.197,88.66,150223,,,D*4C 2023-02-15T22:13:09.510Z,1676499189.510 [NAL9602](INFO): GPS fix at 20230215T221308: (36.807340, -121.838977) 2023-02-15T22:13:09.520Z,1676499189.520 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T22:13:09.520Z,1676499189.520 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T22:13:17.547Z,1676499197.547 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230215T214004/Courier0015.lzma 2023-02-15T22:13:18.550Z,1676499198.550 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0015.lzma.bak 2023-02-15T22:13:18.551Z,1676499198.551 [DataOverHttps](INFO): SBD MOMSN=17475742 2023-02-15T22:13:38.340Z,1676499218.340 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20230215T214004/Express0016.lzma 2023-02-15T22:13:39.342Z,1676499219.342 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0016.lzma.bak 2023-02-15T22:13:39.342Z,1676499219.342 [DataOverHttps](INFO): SBD MOMSN=17475745 2023-02-15T22:13:40.710Z,1676499220.710 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T22:13:40.710Z,1676499220.710 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T22:13:40.710Z,1676499220.710 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T22:13:58.055Z,1676499238.055 [NAL9602](INFO): SBD MO Status=2, MOMSN=39106, MT Status=2, MTMSN=0 2023-02-15T22:13:58.055Z,1676499238.055 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T22:14:06.543Z,1676499246.543 [NAL9602](INFO): SBD MO Status=0, MOMSN=39106, MT Status=0, MTMSN=0 2023-02-15T22:14:06.543Z,1676499246.543 [NAL9602](INFO): No messages in MT queue 2023-02-15T22:14:37.249Z,1676499277.249 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T22:14:41.256Z,1676499281.256 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:15:12.380Z,1676499312.380 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:15:43.504Z,1676499343.504 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:16:14.636Z,1676499374.636 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:16:45.772Z,1676499405.772 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:16:48.813Z,1676499408.813 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.779388 2023-02-15T22:17:47.064Z,1676499467.064 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:18:18.192Z,1676499498.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:18:41.271Z,1676499521.271 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T22:18:41.271Z,1676499521.271 [Default:CheckIn:C.Wait] Stopped 2023-02-15T22:18:41.271Z,1676499521.271 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T22:18:41.271Z,1676499521.271 [Default:CheckIn:D] Running Loop=1 2023-02-15T22:18:41.684Z,1676499521.684 [Default:CheckIn:D] Stopped 2023-02-15T22:18:41.684Z,1676499521.684 [Default:CheckIn:E] Running Loop=1 2023-02-15T22:18:42.074Z,1676499522.074 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.549182 min 2023-02-15T22:18:42.074Z,1676499522.074 [Default:CheckIn:E] Stopped 2023-02-15T22:18:42.074Z,1676499522.074 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T22:18:42.074Z,1676499522.074 [Default:CheckIn] Stopped 2023-02-15T22:18:42.075Z,1676499522.075 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T22:18:42.075Z,1676499522.075 [Default:CheckIn](INFO): Running loop #8 2023-02-15T22:18:42.075Z,1676499522.075 [Default:CheckIn] Running Loop=8 2023-02-15T22:18:42.075Z,1676499522.075 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T22:18:42.075Z,1676499522.075 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T22:18:44.091Z,1676499524.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221843.00,A,3648.38804,N,12150.02054,W,14.987,93.02,150223,,,D*71 2023-02-15T22:18:44.093Z,1676499524.093 [NAL9602](INFO): GPS fix at 20230215T221843: (36.806467, -121.833676) 2023-02-15T22:18:44.103Z,1676499524.103 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T22:18:44.103Z,1676499524.103 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T22:18:49.316Z,1676499529.316 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:19:10.387Z,1676499550.387 [NAL9602](INFO): SBD MO Status=2, MOMSN=39107, MT Status=2, MTMSN=0 2023-02-15T22:19:10.387Z,1676499550.387 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T22:19:20.504Z,1676499560.504 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:19:38.307Z,1676499578.307 [NAL9602](INFO): SBD MO Status=2, MOMSN=39107, MT Status=2, MTMSN=0 2023-02-15T22:19:38.307Z,1676499578.307 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T22:19:49.968Z,1676499589.968 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003032 2023-02-15T22:19:57.415Z,1676499597.415 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0018.lzma 2023-02-15T22:19:58.418Z,1676499598.418 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0018.lzma.bak 2023-02-15T22:19:58.418Z,1676499598.418 [DataOverHttps](INFO): SBD MOMSN=17475749 2023-02-15T22:20:18.264Z,1676499618.264 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20230215T214004/Express0019.lzma 2023-02-15T22:20:19.266Z,1676499619.266 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0019.lzma.bak 2023-02-15T22:20:19.266Z,1676499619.266 [DataOverHttps](INFO): SBD MOMSN=17475752 2023-02-15T22:20:20.747Z,1676499620.747 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T22:20:20.747Z,1676499620.747 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T22:20:20.747Z,1676499620.747 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T22:20:25.585Z,1676499625.585 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T22:22:22.080Z,1676499742.080 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:23:57.204Z,1676499837.204 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:24:28.357Z,1676499868.357 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:24:29.361Z,1676499869.361 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.491684 2023-02-15T22:25:21.410Z,1676499921.410 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T22:25:21.410Z,1676499921.410 [Default:CheckIn:C.Wait] Stopped 2023-02-15T22:25:21.410Z,1676499921.410 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T22:25:21.410Z,1676499921.410 [Default:CheckIn:D] Running Loop=1 2023-02-15T22:25:21.798Z,1676499921.798 [Default:CheckIn:D] Stopped 2023-02-15T22:25:21.798Z,1676499921.798 [Default:CheckIn:E] Running Loop=1 2023-02-15T22:25:22.204Z,1676499922.204 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.217761 min 2023-02-15T22:25:22.204Z,1676499922.204 [Default:CheckIn:E] Stopped 2023-02-15T22:25:22.204Z,1676499922.204 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T22:25:22.204Z,1676499922.204 [Default:CheckIn] Stopped 2023-02-15T22:25:22.204Z,1676499922.204 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T22:25:22.204Z,1676499922.204 [Default:CheckIn](INFO): Running loop #9 2023-02-15T22:25:22.204Z,1676499922.204 [Default:CheckIn] Running Loop=9 2023-02-15T22:25:22.205Z,1676499922.205 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T22:25:22.205Z,1676499922.205 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T22:25:24.223Z,1676499924.223 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222523.00,A,3648.50767,N,12147.13808,W,8.553,139.55,150223,,,D*71 2023-02-15T22:25:24.226Z,1676499924.226 [NAL9602](INFO): GPS fix at 20230215T222523: (36.808461, -121.785635) 2023-02-15T22:25:24.236Z,1676499924.236 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T22:25:24.236Z,1676499924.236 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T22:25:29.634Z,1676499929.634 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:25:53.354Z,1676499953.354 [NAL9602](INFO): SBD MO Status=1, MOMSN=39107, MT Status=0, MTMSN=0 2023-02-15T22:25:53.408Z,1676499953.408 [NAL9602](INFO): Sent 73 bytes from file Logs/20230215T214004/Courier0021.lzma 2023-02-15T22:25:53.408Z,1676499953.408 [NAL9602](INFO): Packets left to send: 0 2023-02-15T22:26:00.812Z,1676499960.812 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:26:03.498Z,1676499963.498 [NAL9602](INFO): SBD MO Status=2, MOMSN=39108, MT Status=2, MTMSN=0 2023-02-15T22:26:03.498Z,1676499963.498 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T22:26:31.937Z,1676499991.937 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:26:32.255Z,1676499992.255 [NAL9602](INFO): SBD MO Status=1, MOMSN=39108, MT Status=0, MTMSN=0 2023-02-15T22:26:32.306Z,1676499992.306 [NAL9602](INFO): Sent 146 bytes from file Logs/20230215T214004/Express0022.lzma 2023-02-15T22:26:32.306Z,1676499992.306 [NAL9602](INFO): Packets left to send: 0 2023-02-15T22:26:39.527Z,1676499999.527 [NAL9602](INFO): SBD MO Status=0, MOMSN=39109, MT Status=0, MTMSN=0 2023-02-15T22:26:39.610Z,1676499999.610 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T22:26:39.610Z,1676499999.610 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T22:26:39.610Z,1676499999.610 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T22:27:03.061Z,1676500023.061 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-02-15T22:27:10.233Z,1676500030.233 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T22:27:21.008Z,1676500041.008 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003020 2023-02-15T22:31:40.121Z,1676500300.121 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T22:31:40.121Z,1676500300.121 [Default:CheckIn:C.Wait] Stopped 2023-02-15T22:31:40.121Z,1676500300.121 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T22:31:40.121Z,1676500300.121 [Default:CheckIn:D] Running Loop=1 2023-02-15T22:31:40.513Z,1676500300.513 [Default:CheckIn:D] Stopped 2023-02-15T22:31:40.513Z,1676500300.513 [Default:CheckIn:E] Running Loop=1 2023-02-15T22:31:40.918Z,1676500300.918 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.529679 min 2023-02-15T22:31:40.919Z,1676500300.918 [Default:CheckIn:E] Stopped 2023-02-15T22:31:40.919Z,1676500300.919 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T22:31:40.919Z,1676500300.919 [Default:CheckIn] Stopped 2023-02-15T22:31:40.919Z,1676500300.919 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T22:31:40.919Z,1676500300.919 [Default:CheckIn](INFO): Running loop #10 2023-02-15T22:31:40.919Z,1676500300.919 [Default:CheckIn] Running Loop=10 2023-02-15T22:31:40.919Z,1676500300.919 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T22:31:40.919Z,1676500300.919 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T22:31:42.931Z,1676500302.931 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223142.00,A,3648.15025,N,12147.22080,W,0.078,205.48,150223,,,D*7D 2023-02-15T22:31:42.933Z,1676500302.933 [NAL9602](INFO): GPS fix at 20230215T223142: (36.802504, -121.787013) 2023-02-15T22:31:42.994Z,1676500302.994 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T22:31:42.994Z,1676500302.994 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T22:31:45.308Z,1676500305.308 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0024.lzma 2023-02-15T22:31:46.206Z,1676500306.206 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0024.lzma.bak 2023-02-15T22:31:46.206Z,1676500306.206 [DataOverHttps](INFO): SBD MOMSN=17475782 2023-02-15T22:32:02.520Z,1676500322.520 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230215T214004/Express0025.lzma 2023-02-15T22:32:03.522Z,1676500323.522 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0025.lzma.bak 2023-02-15T22:32:03.522Z,1676500323.522 [DataOverHttps](INFO): SBD MOMSN=17475785 2023-02-15T22:32:04.630Z,1676500324.630 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T22:32:04.630Z,1676500324.630 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T22:32:04.630Z,1676500324.630 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T22:33:11.287Z,1676500391.287 [NAL9602](INFO): SBD MO Status=0, MOMSN=39110, MT Status=0, MTMSN=0 2023-02-15T22:33:11.287Z,1676500391.287 [NAL9602](INFO): No messages in MT queue 2023-02-15T22:33:41.984Z,1676500421.984 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T22:37:05.241Z,1676500625.241 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T22:37:05.241Z,1676500625.241 [Default:CheckIn:C.Wait] Stopped 2023-02-15T22:37:05.241Z,1676500625.241 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T22:37:05.242Z,1676500625.242 [Default:CheckIn:D] Running Loop=1 2023-02-15T22:37:05.656Z,1676500625.656 [Default:CheckIn:D] Stopped 2023-02-15T22:37:05.656Z,1676500625.656 [Default:CheckIn:E] Running Loop=1 2023-02-15T22:37:06.063Z,1676500626.063 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.948718 min 2023-02-15T22:37:06.063Z,1676500626.063 [Default:CheckIn:E] Stopped 2023-02-15T22:37:06.063Z,1676500626.063 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T22:37:06.063Z,1676500626.063 [Default:CheckIn] Stopped 2023-02-15T22:37:06.064Z,1676500626.064 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T22:37:06.064Z,1676500626.064 [Default:CheckIn](INFO): Running loop #11 2023-02-15T22:37:06.064Z,1676500626.064 [Default:CheckIn] Running Loop=11 2023-02-15T22:37:06.064Z,1676500626.064 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T22:37:06.064Z,1676500626.064 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T22:37:08.067Z,1676500628.067 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223707.00,A,3648.16844,N,12147.26013,W,2.819,291.05,150223,,,D*71 2023-02-15T22:37:08.070Z,1676500628.070 [NAL9602](INFO): GPS fix at 20230215T223707: (36.802807, -121.787669) 2023-02-15T22:37:08.080Z,1676500628.080 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T22:37:08.080Z,1676500628.080 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T22:37:15.323Z,1676500635.323 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0027.lzma 2023-02-15T22:37:16.326Z,1676500636.326 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0027.lzma.bak 2023-02-15T22:37:16.326Z,1676500636.326 [DataOverHttps](INFO): SBD MOMSN=17475830 2023-02-15T22:37:32.263Z,1676500652.263 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230215T214004/Express0028.lzma 2023-02-15T22:37:33.262Z,1676500653.262 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0028.lzma.bak 2023-02-15T22:37:33.262Z,1676500653.262 [DataOverHttps](INFO): SBD MOMSN=17475833 2023-02-15T22:37:34.348Z,1676500654.348 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T22:37:34.348Z,1676500654.348 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T22:37:34.348Z,1676500654.348 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T22:42:10.262Z,1676500930.262 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-02-15T22:42:34.915Z,1676500954.915 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T22:42:34.915Z,1676500954.915 [Default:CheckIn:C.Wait] Stopped 2023-02-15T22:42:34.915Z,1676500954.915 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T22:42:34.916Z,1676500954.916 [Default:CheckIn:D] Running Loop=1 2023-02-15T22:42:35.326Z,1676500955.326 [Default:CheckIn:D] Stopped 2023-02-15T22:42:35.326Z,1676500955.326 [Default:CheckIn:E] Running Loop=1 2023-02-15T22:42:35.727Z,1676500955.727 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.443221 min 2023-02-15T22:42:35.727Z,1676500955.727 [Default:CheckIn:E] Stopped 2023-02-15T22:42:35.727Z,1676500955.727 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T22:42:35.727Z,1676500955.727 [Default:CheckIn] Stopped 2023-02-15T22:42:35.727Z,1676500955.727 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T22:42:35.728Z,1676500955.728 [Default:CheckIn](INFO): Running loop #12 2023-02-15T22:42:35.728Z,1676500955.728 [Default:CheckIn] Running Loop=12 2023-02-15T22:42:35.728Z,1676500955.728 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T22:42:35.728Z,1676500955.728 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T22:42:37.735Z,1676500957.735 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224237.00,A,3648.16914,N,12147.28316,W,0.136,5.87,150223,,,D*7F 2023-02-15T22:42:37.738Z,1676500957.738 [NAL9602](INFO): GPS fix at 20230215T224237: (36.802819, -121.788053) 2023-02-15T22:42:37.748Z,1676500957.748 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T22:42:37.749Z,1676500957.749 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T22:42:45.072Z,1676500965.072 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230215T214004/Courier0030.lzma 2023-02-15T22:42:46.074Z,1676500966.074 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0030.lzma.bak 2023-02-15T22:42:46.074Z,1676500966.074 [DataOverHttps](INFO): SBD MOMSN=17475849 2023-02-15T22:43:04.396Z,1676500984.396 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20230215T214004/Express0031.lzma 2023-02-15T22:43:05.398Z,1676500985.398 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0031.lzma.bak 2023-02-15T22:43:05.398Z,1676500985.398 [DataOverHttps](INFO): SBD MOMSN=17475852 2023-02-15T22:43:06.870Z,1676500986.870 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T22:43:06.870Z,1676500986.870 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T22:43:06.870Z,1676500986.870 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T22:43:10.509Z,1676500990.509 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T22:48:07.448Z,1676501287.448 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T22:48:07.449Z,1676501287.449 [Default:CheckIn:C.Wait] Stopped 2023-02-15T22:48:07.449Z,1676501287.449 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T22:48:07.449Z,1676501287.449 [Default:CheckIn:D] Running Loop=1 2023-02-15T22:48:07.862Z,1676501287.862 [Default:CheckIn:D] Stopped 2023-02-15T22:48:07.862Z,1676501287.862 [Default:CheckIn:E] Running Loop=1 2023-02-15T22:48:08.267Z,1676501288.267 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.985482 min 2023-02-15T22:48:08.267Z,1676501288.267 [Default:CheckIn:E] Stopped 2023-02-15T22:48:08.267Z,1676501288.267 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T22:48:08.267Z,1676501288.267 [Default:CheckIn] Stopped 2023-02-15T22:48:08.267Z,1676501288.267 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T22:48:08.268Z,1676501288.268 [Default:CheckIn](INFO): Running loop #13 2023-02-15T22:48:08.268Z,1676501288.268 [Default:CheckIn] Running Loop=13 2023-02-15T22:48:08.268Z,1676501288.268 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T22:48:08.268Z,1676501288.268 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T22:48:10.269Z,1676501290.269 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224809.00,A,3648.16715,N,12147.27798,W,0.078,5.87,150223,,,D*71 2023-02-15T22:48:10.271Z,1676501290.271 [NAL9602](INFO): GPS fix at 20230215T224809: (36.802786, -121.787966) 2023-02-15T22:48:10.282Z,1676501290.282 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T22:48:10.282Z,1676501290.282 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T22:48:18.043Z,1676501298.043 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0033.lzma 2023-02-15T22:48:19.046Z,1676501299.046 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0033.lzma.bak 2023-02-15T22:48:19.046Z,1676501299.046 [DataOverHttps](INFO): SBD MOMSN=17475933 2023-02-15T22:48:37.696Z,1676501317.696 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230215T214004/Express0034.lzma 2023-02-15T22:48:38.698Z,1676501318.698 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0034.lzma.bak 2023-02-15T22:48:38.698Z,1676501318.698 [DataOverHttps](INFO): SBD MOMSN=17475938 2023-02-15T22:48:39.848Z,1676501319.848 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T22:48:39.848Z,1676501319.848 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T22:48:39.848Z,1676501319.848 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T22:48:41.002Z,1676501321.002 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-02-15T22:48:41.080Z,1676501321.080 [NAL9602](ERROR): received: +CSQ:0 OK110, 0, 0, 0, 0 OK 2023-02-15T22:53:12.582Z,1676501592.582 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-02-15T22:53:40.471Z,1676501620.471 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T22:53:40.472Z,1676501620.472 [Default:CheckIn:C.Wait] Stopped 2023-02-15T22:53:40.472Z,1676501620.472 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T22:53:40.472Z,1676501620.472 [Default:CheckIn:D] Running Loop=1 2023-02-15T22:53:40.871Z,1676501620.871 [Default:CheckIn:D] Stopped 2023-02-15T22:53:40.871Z,1676501620.871 [Default:CheckIn:E] Running Loop=1 2023-02-15T22:53:41.276Z,1676501621.276 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.535645 min 2023-02-15T22:53:41.276Z,1676501621.276 [Default:CheckIn:E] Stopped 2023-02-15T22:53:41.276Z,1676501621.276 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T22:53:41.276Z,1676501621.276 [Default:CheckIn] Stopped 2023-02-15T22:53:41.276Z,1676501621.276 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T22:53:41.277Z,1676501621.277 [Default:CheckIn](INFO): Running loop #14 2023-02-15T22:53:41.277Z,1676501621.277 [Default:CheckIn] Running Loop=14 2023-02-15T22:53:41.277Z,1676501621.277 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T22:53:41.277Z,1676501621.277 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T22:53:43.286Z,1676501623.286 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225342.00,A,3648.16881,N,12147.28119,W,0.330,5.87,150223,,,D*79 2023-02-15T22:53:43.289Z,1676501623.289 [NAL9602](INFO): GPS fix at 20230215T225342: (36.802813, -121.788020) 2023-02-15T22:53:43.299Z,1676501623.299 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T22:53:43.299Z,1676501623.299 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T22:53:51.131Z,1676501631.131 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0036.lzma 2023-02-15T22:53:52.134Z,1676501632.134 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0036.lzma.bak 2023-02-15T22:53:52.134Z,1676501632.134 [DataOverHttps](INFO): SBD MOMSN=17475948 2023-02-15T22:54:08.072Z,1676501648.072 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230215T214004/Express0037.lzma 2023-02-15T22:54:09.074Z,1676501649.074 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0037.lzma.bak 2023-02-15T22:54:09.074Z,1676501649.074 [DataOverHttps](INFO): SBD MOMSN=17475951 2023-02-15T22:54:10.365Z,1676501650.365 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T22:54:10.365Z,1676501650.365 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T22:54:10.366Z,1676501650.366 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T22:54:15.605Z,1676501655.605 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T22:57:56.623Z,1676501876.623 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-02-15T22:57:57.432Z,1676501877.432 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T22:58:02.278Z,1676501882.278 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-15T22:58:12.782Z,1676501892.782 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T22:58:12.787Z,1676501892.787 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2023-02-15T22:58:14.803Z,1676501894.803 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-15T22:58:27.070Z,1676501907.070 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-15T22:58:27.374Z,1676501907.374 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T22:58:38.686Z,1676501918.686 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-15T22:58:42.324Z,1676501922.324 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T22:58:50.847Z,1676501930.847 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-15T22:58:57.715Z,1676501937.715 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T22:59:02.970Z,1676501942.970 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-15T22:59:11.063Z,1676501951.063 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T22:59:11.063Z,1676501951.063 [Default:CheckIn:C.Wait] Stopped 2023-02-15T22:59:11.063Z,1676501951.063 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T22:59:11.064Z,1676501951.064 [Default:CheckIn:D] Running Loop=1 2023-02-15T22:59:11.469Z,1676501951.469 [Default:CheckIn:D] Stopped 2023-02-15T22:59:11.469Z,1676501951.469 [Default:CheckIn:E] Running Loop=1 2023-02-15T22:59:11.886Z,1676501951.886 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.045605 min 2023-02-15T22:59:11.886Z,1676501951.886 [Default:CheckIn:E] Stopped 2023-02-15T22:59:11.886Z,1676501951.886 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T22:59:11.886Z,1676501951.886 [Default:CheckIn] Stopped 2023-02-15T22:59:11.886Z,1676501951.886 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T22:59:11.886Z,1676501951.886 [Default:CheckIn](INFO): Running loop #15 2023-02-15T22:59:11.887Z,1676501951.887 [Default:CheckIn] Running Loop=15 2023-02-15T22:59:11.887Z,1676501951.887 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T22:59:11.887Z,1676501951.887 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T22:59:13.480Z,1676501953.480 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T22:59:13.903Z,1676501953.903 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225913.00,A,3648.16897,N,12147.28199,W,0.078,0.00,150223,,,D*7D 2023-02-15T22:59:13.905Z,1676501953.905 [NAL9602](INFO): GPS fix at 20230215T225913: (36.802816, -121.788033) 2023-02-15T22:59:13.919Z,1676501953.919 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T22:59:13.919Z,1676501953.919 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T22:59:14.704Z,1676501954.704 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-15T22:59:20.895Z,1676501960.895 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0039.lzma 2023-02-15T22:59:21.898Z,1676501961.898 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0039.lzma.bak 2023-02-15T22:59:21.898Z,1676501961.898 [DataOverHttps](INFO): SBD MOMSN=17475963 2023-02-15T22:59:26.824Z,1676501966.824 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-15T22:59:29.246Z,1676501969.246 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T22:59:37.788Z,1676501977.788 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230215T214004/Express0040.lzma 2023-02-15T22:59:38.790Z,1676501978.790 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0040.lzma.bak 2023-02-15T22:59:38.790Z,1676501978.790 [DataOverHttps](INFO): SBD MOMSN=17475966 2023-02-15T22:59:39.346Z,1676501979.346 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-15T22:59:40.171Z,1676501980.171 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T22:59:40.171Z,1676501980.171 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T22:59:40.171Z,1676501980.171 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T22:59:44.196Z,1676501984.196 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T22:59:44.618Z,1676501984.618 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-02-15T22:59:44.692Z,1676501984.692 [NAL9602](ERROR): received: +CSQ:0 OK110, 0, 0, 0, 0 OK 2023-02-15T22:59:51.870Z,1676501991.870 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-15T22:59:59.143Z,1676501999.143 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-15T23:00:03.993Z,1676502003.993 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-15T23:00:13.686Z,1676502013.686 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2023-02-15T23:00:15.706Z,1676502015.706 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-15T23:00:22.178Z,1676502022.178 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 5. 2023-02-15T23:00:22.189Z,1676502022.189 [BPC1](INFO): Received data from all battery sticks. 2023-02-15T23:04:16.529Z,1676502256.529 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-02-15T23:04:40.850Z,1676502280.850 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T23:04:40.850Z,1676502280.850 [Default:CheckIn:C.Wait] Stopped 2023-02-15T23:04:40.850Z,1676502280.850 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T23:04:40.850Z,1676502280.850 [Default:CheckIn:D] Running Loop=1 2023-02-15T23:04:41.243Z,1676502281.243 [Default:CheckIn:D] Stopped 2023-02-15T23:04:41.243Z,1676502281.243 [Default:CheckIn:E] Running Loop=1 2023-02-15T23:04:41.643Z,1676502281.643 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.541838 min 2023-02-15T23:04:41.643Z,1676502281.643 [Default:CheckIn:E] Stopped 2023-02-15T23:04:41.643Z,1676502281.643 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T23:04:41.643Z,1676502281.643 [Default:CheckIn] Stopped 2023-02-15T23:04:41.643Z,1676502281.643 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T23:04:41.643Z,1676502281.643 [Default:CheckIn](INFO): Running loop #16 2023-02-15T23:04:41.643Z,1676502281.643 [Default:CheckIn] Running Loop=16 2023-02-15T23:04:41.643Z,1676502281.643 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T23:04:41.644Z,1676502281.644 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T23:04:43.659Z,1676502283.659 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230442.00,A,3648.16808,N,12147.28164,W,0.330,0.00,150223,,,D*7B 2023-02-15T23:04:43.661Z,1676502283.661 [NAL9602](INFO): GPS fix at 20230215T230442: (36.802801, -121.788027) 2023-02-15T23:04:43.671Z,1676502283.671 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T23:04:43.671Z,1676502283.671 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T23:04:54.376Z,1676502294.376 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20230215T214004/Courier0042.lzma 2023-02-15T23:04:55.378Z,1676502295.378 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0042.lzma.bak 2023-02-15T23:04:55.378Z,1676502295.378 [DataOverHttps](INFO): SBD MOMSN=17475978 2023-02-15T23:05:11.216Z,1676502311.216 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230215T214004/Express0043.lzma 2023-02-15T23:05:12.218Z,1676502312.218 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0043.lzma.bak 2023-02-15T23:05:12.219Z,1676502312.219 [DataOverHttps](INFO): SBD MOMSN=17475981 2023-02-15T23:05:13.619Z,1676502313.619 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T23:05:13.619Z,1676502313.619 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T23:05:13.620Z,1676502313.620 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T23:05:16.429Z,1676502316.429 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T23:10:14.030Z,1676502614.030 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T23:10:14.030Z,1676502614.030 [Default:CheckIn:C.Wait] Stopped 2023-02-15T23:10:14.030Z,1676502614.030 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T23:10:14.030Z,1676502614.030 [Default:CheckIn:D] Running Loop=1 2023-02-15T23:10:14.427Z,1676502614.427 [Default:CheckIn:D] Stopped 2023-02-15T23:10:14.427Z,1676502614.427 [Default:CheckIn:E] Running Loop=1 2023-02-15T23:10:14.831Z,1676502614.831 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.094906 min 2023-02-15T23:10:14.831Z,1676502614.831 [Default:CheckIn:E] Stopped 2023-02-15T23:10:14.831Z,1676502614.831 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T23:10:14.832Z,1676502614.832 [Default:CheckIn] Stopped 2023-02-15T23:10:14.832Z,1676502614.832 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T23:10:14.832Z,1676502614.832 [Default:CheckIn](INFO): Running loop #17 2023-02-15T23:10:14.832Z,1676502614.832 [Default:CheckIn] Running Loop=17 2023-02-15T23:10:14.832Z,1676502614.832 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T23:10:14.832Z,1676502614.832 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T23:10:16.843Z,1676502616.843 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231016.00,A,3648.16760,N,12147.28220,W,0.136,0.00,150223,,,D*79 2023-02-15T23:10:16.845Z,1676502616.845 [NAL9602](INFO): GPS fix at 20230215T231016: (36.802793, -121.788037) 2023-02-15T23:10:16.862Z,1676502616.862 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T23:10:16.862Z,1676502616.862 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T23:10:27.220Z,1676502627.220 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230215T214004/Courier0045.lzma 2023-02-15T23:10:28.222Z,1676502628.222 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0045.lzma.bak 2023-02-15T23:10:28.222Z,1676502628.222 [DataOverHttps](INFO): SBD MOMSN=17475993 2023-02-15T23:10:43.980Z,1676502643.980 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230215T214004/Express0046.lzma 2023-02-15T23:10:45.239Z,1676502645.239 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0046.lzma.bak 2023-02-15T23:10:45.239Z,1676502645.239 [DataOverHttps](INFO): SBD MOMSN=17475996 2023-02-15T23:10:46.091Z,1676502646.091 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T23:10:46.092Z,1676502646.092 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T23:10:46.092Z,1676502646.092 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T23:10:47.282Z,1676502647.282 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-02-15T23:10:47.364Z,1676502647.364 [NAL9602](ERROR): received: +CSQ:0 OK110, 0, 0, 0, 0 OK 2023-02-15T23:15:18.990Z,1676502918.990 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-02-15T23:15:46.879Z,1676502946.879 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T23:15:46.879Z,1676502946.879 [Default:CheckIn:C.Wait] Stopped 2023-02-15T23:15:46.879Z,1676502946.879 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T23:15:46.879Z,1676502946.879 [Default:CheckIn:D] Running Loop=1 2023-02-15T23:15:47.283Z,1676502947.283 [Default:CheckIn:D] Stopped 2023-02-15T23:15:47.284Z,1676502947.284 [Default:CheckIn:E] Running Loop=1 2023-02-15T23:15:47.690Z,1676502947.690 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.642513 min 2023-02-15T23:15:47.691Z,1676502947.691 [Default:CheckIn:E] Stopped 2023-02-15T23:15:47.691Z,1676502947.691 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T23:15:47.691Z,1676502947.691 [Default:CheckIn] Stopped 2023-02-15T23:15:47.691Z,1676502947.691 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T23:15:47.691Z,1676502947.691 [Default:CheckIn](INFO): Running loop #18 2023-02-15T23:15:47.691Z,1676502947.691 [Default:CheckIn] Running Loop=18 2023-02-15T23:15:47.691Z,1676502947.691 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T23:15:47.691Z,1676502947.691 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T23:15:49.695Z,1676502949.695 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231548.00,A,3648.16568,N,12147.28512,W,0.078,0.00,150223,,,D*70 2023-02-15T23:15:49.697Z,1676502949.697 [NAL9602](INFO): GPS fix at 20230215T231548: (36.802761, -121.788085) 2023-02-15T23:15:49.744Z,1676502949.744 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T23:15:49.744Z,1676502949.744 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T23:15:57.347Z,1676502957.347 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230215T214004/Courier0048.lzma 2023-02-15T23:15:58.350Z,1676502958.350 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0048.lzma.bak 2023-02-15T23:15:58.350Z,1676502958.350 [DataOverHttps](INFO): SBD MOMSN=17476005 2023-02-15T23:16:14.220Z,1676502974.220 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20230215T214004/Express0049.lzma 2023-02-15T23:16:15.222Z,1676502975.222 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0049.lzma.bak 2023-02-15T23:16:15.222Z,1676502975.222 [DataOverHttps](INFO): SBD MOMSN=17476008 2023-02-15T23:16:16.368Z,1676502976.368 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T23:16:16.369Z,1676502976.369 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T23:16:16.369Z,1676502976.369 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T23:16:22.421Z,1676502982.421 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T23:21:17.123Z,1676503277.123 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T23:21:17.123Z,1676503277.123 [Default:CheckIn:C.Wait] Stopped 2023-02-15T23:21:17.123Z,1676503277.123 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T23:21:17.124Z,1676503277.124 [Default:CheckIn:D] Running Loop=1 2023-02-15T23:21:17.524Z,1676503277.524 [Default:CheckIn:D] Stopped 2023-02-15T23:21:17.524Z,1676503277.524 [Default:CheckIn:E] Running Loop=1 2023-02-15T23:21:17.950Z,1676503277.950 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.146517 min 2023-02-15T23:21:17.950Z,1676503277.950 [Default:CheckIn:E] Stopped 2023-02-15T23:21:17.950Z,1676503277.950 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T23:21:17.950Z,1676503277.950 [Default:CheckIn] Stopped 2023-02-15T23:21:17.950Z,1676503277.950 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T23:21:17.950Z,1676503277.950 [Default:CheckIn](INFO): Running loop #19 2023-02-15T23:21:17.950Z,1676503277.950 [Default:CheckIn] Running Loop=19 2023-02-15T23:21:17.950Z,1676503277.950 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T23:21:17.950Z,1676503277.950 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T23:21:19.938Z,1676503279.938 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232119.00,A,3648.16914,N,12147.28108,W,0.233,0.00,150223,,,D*76 2023-02-15T23:21:19.941Z,1676503279.941 [NAL9602](INFO): GPS fix at 20230215T232119: (36.802819, -121.788018) 2023-02-15T23:21:19.951Z,1676503279.951 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T23:21:19.951Z,1676503279.951 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T23:21:26.515Z,1676503286.515 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0051.lzma 2023-02-15T23:21:27.518Z,1676503287.518 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0051.lzma.bak 2023-02-15T23:21:27.518Z,1676503287.518 [DataOverHttps](INFO): SBD MOMSN=17476021 2023-02-15T23:21:43.296Z,1676503303.296 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230215T214004/Express0052.lzma 2023-02-15T23:21:44.298Z,1676503304.298 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0052.lzma.bak 2023-02-15T23:21:44.298Z,1676503304.298 [DataOverHttps](INFO): SBD MOMSN=17476024 2023-02-15T23:21:45.403Z,1676503305.403 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T23:21:45.403Z,1676503305.403 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T23:21:45.403Z,1676503305.403 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T23:21:50.646Z,1676503310.646 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-02-15T23:21:50.720Z,1676503310.720 [NAL9602](ERROR): received: +CSQ:0 OK110, 0, 0, 0, 0 OK 2023-02-15T23:23:55.135Z,1676503435.135 [NAL9602](INFO): SBD MO Status=2, MOMSN=39111, MT Status=2, MTMSN=0 2023-02-15T23:23:55.135Z,1676503435.135 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T23:24:59.025Z,1676503499.025 [NAL9602](INFO): SBD MO Status=2, MOMSN=39111, MT Status=2, MTMSN=0 2023-02-15T23:24:59.025Z,1676503499.025 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T23:26:22.646Z,1676503582.646 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-02-15T23:26:46.088Z,1676503606.088 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T23:26:46.088Z,1676503606.088 [Default:CheckIn:C.Wait] Stopped 2023-02-15T23:26:46.088Z,1676503606.088 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T23:26:46.088Z,1676503606.088 [Default:CheckIn:D] Running Loop=1 2023-02-15T23:26:46.504Z,1676503606.504 [Default:CheckIn:D] Stopped 2023-02-15T23:26:46.504Z,1676503606.504 [Default:CheckIn:E] Running Loop=1 2023-02-15T23:26:46.899Z,1676503606.899 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.629533 min 2023-02-15T23:26:46.899Z,1676503606.899 [Default:CheckIn:E] Stopped 2023-02-15T23:26:46.899Z,1676503606.899 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T23:26:46.932Z,1676503606.932 [Default:CheckIn] Stopped 2023-02-15T23:26:46.932Z,1676503606.932 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T23:26:46.932Z,1676503606.932 [Default:CheckIn](INFO): Running loop #20 2023-02-15T23:26:46.932Z,1676503606.932 [Default:CheckIn] Running Loop=20 2023-02-15T23:26:46.932Z,1676503606.932 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T23:26:46.932Z,1676503606.932 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T23:26:48.907Z,1676503608.907 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232648.00,A,3648.16547,N,12147.28602,W,0.097,0.00,150223,,,D*7E 2023-02-15T23:26:48.927Z,1676503608.927 [NAL9602](INFO): GPS fix at 20230215T232648: (36.802758, -121.788100) 2023-02-15T23:26:48.945Z,1676503608.945 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T23:26:48.945Z,1676503608.945 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T23:26:58.764Z,1676503618.764 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230215T214004/Courier0054.lzma 2023-02-15T23:26:59.766Z,1676503619.766 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0054.lzma.bak 2023-02-15T23:26:59.766Z,1676503619.766 [DataOverHttps](INFO): SBD MOMSN=17476042 2023-02-15T23:27:15.472Z,1676503635.472 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230215T214004/Express0055.lzma 2023-02-15T23:27:16.474Z,1676503636.474 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0055.lzma.bak 2023-02-15T23:27:16.475Z,1676503636.475 [DataOverHttps](INFO): SBD MOMSN=17476049 2023-02-15T23:27:18.064Z,1676503638.064 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T23:27:18.064Z,1676503638.064 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T23:27:18.065Z,1676503638.065 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T23:27:21.677Z,1676503641.677 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T23:32:18.687Z,1676503938.687 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T23:32:18.687Z,1676503938.687 [Default:CheckIn:C.Wait] Stopped 2023-02-15T23:32:18.687Z,1676503938.687 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T23:32:18.688Z,1676503938.688 [Default:CheckIn:D] Running Loop=1 2023-02-15T23:32:19.091Z,1676503939.091 [Default:CheckIn:D] Stopped 2023-02-15T23:32:19.091Z,1676503939.091 [Default:CheckIn:E] Running Loop=1 2023-02-15T23:32:19.506Z,1676503939.506 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.172640 min 2023-02-15T23:32:19.507Z,1676503939.507 [Default:CheckIn:E] Stopped 2023-02-15T23:32:19.507Z,1676503939.507 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T23:32:19.507Z,1676503939.507 [Default:CheckIn] Stopped 2023-02-15T23:32:19.507Z,1676503939.507 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T23:32:19.507Z,1676503939.507 [Default:CheckIn](INFO): Running loop #21 2023-02-15T23:32:19.507Z,1676503939.507 [Default:CheckIn] Running Loop=21 2023-02-15T23:32:19.507Z,1676503939.507 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T23:32:19.507Z,1676503939.507 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T23:32:21.507Z,1676503941.507 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233220.00,A,3648.16997,N,12147.28042,W,0.097,0.00,150223,,,D*76 2023-02-15T23:32:21.510Z,1676503941.510 [NAL9602](INFO): GPS fix at 20230215T233220: (36.802833, -121.788007) 2023-02-15T23:32:21.544Z,1676503941.544 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T23:32:21.544Z,1676503941.544 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T23:32:29.200Z,1676503949.200 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230215T214004/Courier0057.lzma 2023-02-15T23:32:30.202Z,1676503950.202 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0057.lzma.bak 2023-02-15T23:32:30.202Z,1676503950.202 [DataOverHttps](INFO): SBD MOMSN=17476060 2023-02-15T23:32:46.408Z,1676503966.408 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230215T214004/Express0058.lzma 2023-02-15T23:32:47.410Z,1676503967.410 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0058.lzma.bak 2023-02-15T23:32:47.410Z,1676503967.410 [DataOverHttps](INFO): SBD MOMSN=17476063 2023-02-15T23:32:48.592Z,1676503968.592 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T23:32:48.592Z,1676503968.592 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T23:32:48.592Z,1676503968.592 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T23:32:52.242Z,1676503972.242 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-02-15T23:32:52.320Z,1676503972.320 [NAL9602](ERROR): received: +CSQ:0 OK111, 2, 0, 0, 0 OK 2023-02-15T23:34:29.639Z,1676504069.639 [NAL9602](INFO): SBD MO Status=2, MOMSN=39111, MT Status=2, MTMSN=0 2023-02-15T23:34:29.639Z,1676504069.639 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T23:35:14.927Z,1676504114.927 [NAL9602](INFO): SBD MO Status=2, MOMSN=39111, MT Status=2, MTMSN=0 2023-02-15T23:35:14.927Z,1676504114.927 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T23:35:25.890Z,1676504125.890 [CBIT](IMPORTANT): Beginning ground fault scan 2023-02-15T23:35:28.691Z,1676504128.691 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2023-02-15T23:35:36.763Z,1676504136.763 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002703 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.002118 CHAN A3 (5V): -0.001686 CHAN B0 (3.3V): -0.000289 CHAN B1 (3.15aV): -0.000258 CHAN B2 (3.15bV): -0.000454 CHAN B3 (GND): -0.000700 OPEN: -0.000695 Full Scale: +/- 1 mA 2023-02-15T23:35:37.955Z,1676504137.955 [NAL9602](INFO): SBD MO Status=2, MOMSN=39111, MT Status=2, MTMSN=0 2023-02-15T23:35:37.955Z,1676504137.955 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T23:36:03.408Z,1676504163.408 [NAL9602](INFO): SBD MO Status=2, MOMSN=39111, MT Status=2, MTMSN=0 2023-02-15T23:36:03.408Z,1676504163.408 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T23:36:37.339Z,1676504197.339 [NAL9602](INFO): SBD MO Status=2, MOMSN=39111, MT Status=2, MTMSN=0 2023-02-15T23:36:37.339Z,1676504197.339 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T23:37:23.402Z,1676504243.402 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-02-15T23:37:49.267Z,1676504269.267 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T23:37:49.267Z,1676504269.267 [Default:CheckIn:C.Wait] Stopped 2023-02-15T23:37:49.267Z,1676504269.267 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T23:37:49.268Z,1676504269.268 [Default:CheckIn:D] Running Loop=1 2023-02-15T23:37:49.683Z,1676504269.683 [Default:CheckIn:D] Stopped 2023-02-15T23:37:49.684Z,1676504269.684 [Default:CheckIn:E] Running Loop=1 2023-02-15T23:37:50.079Z,1676504270.079 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.682520 min 2023-02-15T23:37:50.079Z,1676504270.079 [Default:CheckIn:E] Stopped 2023-02-15T23:37:50.080Z,1676504270.080 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T23:37:50.080Z,1676504270.080 [Default:CheckIn] Stopped 2023-02-15T23:37:50.080Z,1676504270.080 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T23:37:50.111Z,1676504270.111 [Default:CheckIn](INFO): Running loop #22 2023-02-15T23:37:50.111Z,1676504270.111 [Default:CheckIn] Running Loop=22 2023-02-15T23:37:50.111Z,1676504270.111 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T23:37:50.112Z,1676504270.112 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T23:37:52.087Z,1676504272.087 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233751.00,A,3648.16890,N,12147.28187,W,0.136,0.00,150223,,,D*71 2023-02-15T23:37:52.090Z,1676504272.090 [NAL9602](INFO): GPS fix at 20230215T233751: (36.802815, -121.788031) 2023-02-15T23:37:52.100Z,1676504272.100 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T23:37:52.100Z,1676504272.100 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T23:37:59.471Z,1676504279.471 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0060.lzma 2023-02-15T23:38:00.474Z,1676504280.474 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0060.lzma.bak 2023-02-15T23:38:00.474Z,1676504280.474 [DataOverHttps](INFO): SBD MOMSN=17476072 2023-02-15T23:38:16.424Z,1676504296.424 [DataOverHttps](INFO): Sending 409 bytes from file Logs/20230215T214004/Express0061.lzma 2023-02-15T23:38:17.426Z,1676504297.426 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0061.lzma.bak 2023-02-15T23:38:17.426Z,1676504297.426 [DataOverHttps](INFO): SBD MOMSN=17476075 2023-02-15T23:38:18.768Z,1676504298.768 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T23:38:18.768Z,1676504298.768 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T23:38:18.768Z,1676504298.768 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T23:38:24.400Z,1676504304.400 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T23:43:19.423Z,1676504599.423 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T23:43:19.423Z,1676504599.423 [Default:CheckIn:C.Wait] Stopped 2023-02-15T23:43:19.423Z,1676504599.423 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T23:43:19.424Z,1676504599.424 [Default:CheckIn:D] Running Loop=1 2023-02-15T23:43:19.831Z,1676504599.831 [Default:CheckIn:D] Stopped 2023-02-15T23:43:19.831Z,1676504599.831 [Default:CheckIn:E] Running Loop=1 2023-02-15T23:43:20.234Z,1676504600.234 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.184977 min 2023-02-15T23:43:20.234Z,1676504600.234 [Default:CheckIn:E] Stopped 2023-02-15T23:43:20.234Z,1676504600.234 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T23:43:20.234Z,1676504600.234 [Default:CheckIn] Stopped 2023-02-15T23:43:20.235Z,1676504600.235 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T23:43:20.235Z,1676504600.235 [Default:CheckIn](INFO): Running loop #23 2023-02-15T23:43:20.235Z,1676504600.235 [Default:CheckIn] Running Loop=23 2023-02-15T23:43:20.235Z,1676504600.235 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T23:43:20.235Z,1676504600.235 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T23:43:22.243Z,1676504602.243 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234321.00,A,3648.16822,N,12147.28500,W,0.136,0.00,150223,,,D*77 2023-02-15T23:43:22.246Z,1676504602.246 [NAL9602](INFO): GPS fix at 20230215T234321: (36.802804, -121.788083) 2023-02-15T23:43:22.280Z,1676504602.280 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T23:43:22.280Z,1676504602.280 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T23:43:29.655Z,1676504609.655 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0063.lzma 2023-02-15T23:43:30.658Z,1676504610.658 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0063.lzma.bak 2023-02-15T23:43:30.658Z,1676504610.658 [DataOverHttps](INFO): SBD MOMSN=17476090 2023-02-15T23:43:46.568Z,1676504626.568 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230215T214004/Express0064.lzma 2023-02-15T23:43:47.570Z,1676504627.570 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0064.lzma.bak 2023-02-15T23:43:47.570Z,1676504627.570 [DataOverHttps](INFO): SBD MOMSN=17476093 2023-02-15T23:43:48.921Z,1676504628.921 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T23:43:48.921Z,1676504628.921 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T23:43:48.921Z,1676504628.921 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T23:43:52.946Z,1676504632.946 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-02-15T23:43:53.020Z,1676504633.020 [NAL9602](ERROR): received: +CSQ:0 OK111, 2, 0, 0, 0 OK 2023-02-15T23:44:07.095Z,1676504647.095 [NAL9602](INFO): SBD MO Status=0, MOMSN=39111, MT Status=0, MTMSN=0 2023-02-15T23:44:07.095Z,1676504647.095 [NAL9602](INFO): No messages in MT queue 2023-02-15T23:44:37.793Z,1676504677.793 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T23:48:49.578Z,1676504929.578 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T23:48:49.578Z,1676504929.578 [Default:CheckIn:C.Wait] Stopped 2023-02-15T23:48:49.578Z,1676504929.578 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T23:48:49.578Z,1676504929.578 [Default:CheckIn:D] Running Loop=1 2023-02-15T23:48:49.992Z,1676504929.992 [Default:CheckIn:D] Stopped 2023-02-15T23:48:49.992Z,1676504929.992 [Default:CheckIn:E] Running Loop=1 2023-02-15T23:48:50.355Z,1676504930.355 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.687646 min 2023-02-15T23:48:50.355Z,1676504930.355 [Default:CheckIn:E] Stopped 2023-02-15T23:48:50.355Z,1676504930.355 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T23:48:50.355Z,1676504930.355 [Default:CheckIn] Stopped 2023-02-15T23:48:50.355Z,1676504930.355 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T23:48:50.356Z,1676504930.356 [Default:CheckIn](INFO): Running loop #24 2023-02-15T23:48:50.356Z,1676504930.356 [Default:CheckIn] Running Loop=24 2023-02-15T23:48:50.356Z,1676504930.356 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T23:48:50.356Z,1676504930.356 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T23:48:52.363Z,1676504932.363 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234851.00,A,3648.16716,N,12147.28194,W,0.039,0.00,150223,,,D*74 2023-02-15T23:48:52.366Z,1676504932.366 [NAL9602](INFO): GPS fix at 20230215T234851: (36.802786, -121.788032) 2023-02-15T23:48:52.396Z,1676504932.396 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T23:48:52.396Z,1676504932.396 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T23:49:00.103Z,1676504940.103 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0066.lzma 2023-02-15T23:49:01.107Z,1676504941.107 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0066.lzma.bak 2023-02-15T23:49:01.107Z,1676504941.107 [DataOverHttps](INFO): SBD MOMSN=17476105 2023-02-15T23:49:17.056Z,1676504957.056 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20230215T214004/Express0067.lzma 2023-02-15T23:49:18.058Z,1676504958.058 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0067.lzma.bak 2023-02-15T23:49:18.058Z,1676504958.058 [DataOverHttps](INFO): SBD MOMSN=17476108 2023-02-15T23:49:19.460Z,1676504959.460 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T23:49:19.460Z,1676504959.460 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T23:49:19.461Z,1676504959.461 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T23:50:39.831Z,1676505039.831 [NAL9602](INFO): SBD MO Status=2, MOMSN=39112, MT Status=2, MTMSN=0 2023-02-15T23:50:39.831Z,1676505039.831 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T23:51:40.463Z,1676505100.463 [NAL9602](INFO): SBD MO Status=2, MOMSN=39112, MT Status=2, MTMSN=0 2023-02-15T23:51:40.463Z,1676505100.463 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T23:52:24.499Z,1676505144.499 [NAL9602](INFO): SBD MO Status=2, MOMSN=39112, MT Status=2, MTMSN=0 2023-02-15T23:52:24.499Z,1676505144.499 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-15T23:52:55.611Z,1676505175.611 [NAL9602](INFO): SBD MO Status=0, MOMSN=39112, MT Status=0, MTMSN=0 2023-02-15T23:52:55.611Z,1676505175.611 [NAL9602](INFO): No messages in MT queue 2023-02-15T23:53:26.309Z,1676505206.309 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T23:54:20.065Z,1676505260.065 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T23:54:20.065Z,1676505260.065 [Default:CheckIn:C.Wait] Stopped 2023-02-15T23:54:20.065Z,1676505260.065 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T23:54:20.065Z,1676505260.065 [Default:CheckIn:D] Running Loop=1 2023-02-15T23:54:20.461Z,1676505260.461 [Default:CheckIn:D] Stopped 2023-02-15T23:54:20.461Z,1676505260.461 [Default:CheckIn:E] Running Loop=1 2023-02-15T23:54:20.860Z,1676505260.860 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 144.195475 min 2023-02-15T23:54:20.860Z,1676505260.860 [Default:CheckIn:E] Stopped 2023-02-15T23:54:20.860Z,1676505260.860 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T23:54:20.860Z,1676505260.860 [Default:CheckIn] Stopped 2023-02-15T23:54:20.860Z,1676505260.860 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T23:54:20.860Z,1676505260.860 [Default:CheckIn](INFO): Running loop #25 2023-02-15T23:54:20.860Z,1676505260.860 [Default:CheckIn] Running Loop=25 2023-02-15T23:54:20.861Z,1676505260.861 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T23:54:20.861Z,1676505260.861 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T23:54:22.872Z,1676505262.872 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235422.00,A,3648.16903,N,12147.28389,W,0.000,61.20,150223,,,D*46 2023-02-15T23:54:22.874Z,1676505262.874 [NAL9602](INFO): GPS fix at 20230215T235422: (36.802817, -121.788065) 2023-02-15T23:54:22.907Z,1676505262.907 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T23:54:22.907Z,1676505262.907 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-15T23:54:30.651Z,1676505270.651 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0069.lzma 2023-02-15T23:54:31.654Z,1676505271.654 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0069.lzma.bak 2023-02-15T23:54:31.654Z,1676505271.654 [DataOverHttps](INFO): SBD MOMSN=17476119 2023-02-15T23:54:39.439Z,1676505279.439 [NAL9602](INFO): SBD MO Status=0, MOMSN=39113, MT Status=0, MTMSN=0 2023-02-15T23:54:39.439Z,1676505279.439 [NAL9602](INFO): No messages in MT queue 2023-02-15T23:54:47.604Z,1676505287.604 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20230215T214004/Express0070.lzma 2023-02-15T23:54:48.608Z,1676505288.608 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0070.lzma.bak 2023-02-15T23:54:48.608Z,1676505288.608 [DataOverHttps](INFO): SBD MOMSN=17476122 2023-02-15T23:54:49.950Z,1676505289.950 [Default:CheckIn:Read_Iridium] Stopped 2023-02-15T23:54:49.950Z,1676505289.950 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-15T23:54:49.950Z,1676505289.950 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-15T23:55:10.185Z,1676505310.185 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-15T23:59:50.623Z,1676505590.623 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-15T23:59:50.623Z,1676505590.623 [Default:CheckIn:C.Wait] Stopped 2023-02-15T23:59:50.623Z,1676505590.623 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-15T23:59:50.623Z,1676505590.623 [Default:CheckIn:D] Running Loop=1 2023-02-15T23:59:51.013Z,1676505591.013 [Default:CheckIn:D] Stopped 2023-02-15T23:59:51.013Z,1676505591.013 [Default:CheckIn:E] Running Loop=1 2023-02-15T23:59:51.415Z,1676505591.415 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.704671 min 2023-02-15T23:59:51.415Z,1676505591.415 [Default:CheckIn:E] Stopped 2023-02-15T23:59:51.416Z,1676505591.416 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-15T23:59:51.416Z,1676505591.416 [Default:CheckIn] Stopped 2023-02-15T23:59:51.416Z,1676505591.416 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-15T23:59:51.416Z,1676505591.416 [Default:CheckIn](INFO): Running loop #26 2023-02-15T23:59:51.416Z,1676505591.416 [Default:CheckIn] Running Loop=26 2023-02-15T23:59:51.416Z,1676505591.416 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-15T23:59:51.416Z,1676505591.416 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-15T23:59:53.423Z,1676505593.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235952.00,A,3648.17011,N,12147.28368,W,0.311,61.20,150223,,,D*4B 2023-02-15T23:59:53.426Z,1676505593.426 [NAL9602](INFO): GPS fix at 20230215T235952: (36.802835, -121.788061) 2023-02-15T23:59:53.444Z,1676505593.444 [Default:CheckIn:Read_GPS] Stopped 2023-02-15T23:59:53.444Z,1676505593.444 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-16T00:00:00.463Z,1676505600.463 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230215T214004/Courier0072.lzma 2023-02-16T00:00:01.466Z,1676505601.466 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0072.lzma.bak 2023-02-16T00:00:01.466Z,1676505601.466 [DataOverHttps](INFO): SBD MOMSN=17476133 2023-02-16T00:00:17.740Z,1676505617.740 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230215T214004/Express0073.lzma 2023-02-16T00:00:18.742Z,1676505618.742 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0073.lzma.bak 2023-02-16T00:00:18.742Z,1676505618.742 [DataOverHttps](INFO): SBD MOMSN=17476136 2023-02-16T00:00:20.109Z,1676505620.109 [Default:CheckIn:Read_Iridium] Stopped 2023-02-16T00:00:20.109Z,1676505620.109 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-16T00:00:20.109Z,1676505620.109 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-16T00:02:50.112Z,1676505770.112 [NAL9602](INFO): SBD MO Status=2, MOMSN=39114, MT Status=2, MTMSN=0 2023-02-16T00:02:50.112Z,1676505770.112 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-16T00:03:23.959Z,1676505803.959 [NAL9602](INFO): SBD MO Status=2, MOMSN=39114, MT Status=2, MTMSN=0 2023-02-16T00:03:23.959Z,1676505803.959 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-16T00:03:48.205Z,1676505828.205 [NAL9602](INFO): SBD MO Status=2, MOMSN=39114, MT Status=2, MTMSN=0 2023-02-16T00:03:48.205Z,1676505828.205 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-16T00:04:51.657Z,1676505891.657 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 2023-02-16T00:04:55.291Z,1676505895.291 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-02-16T00:05:17.091Z,1676505917.091 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-16T00:05:19.111Z,1676505919.111 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-02-16T00:05:20.743Z,1676505920.743 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-16T00:05:20.743Z,1676505920.743 [Default:CheckIn:C.Wait] Stopped 2023-02-16T00:05:20.743Z,1676505920.743 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-16T00:05:20.743Z,1676505920.743 [Default:CheckIn:D] Running Loop=1 2023-02-16T00:05:21.147Z,1676505921.147 [Default:CheckIn:D] Stopped 2023-02-16T00:05:21.147Z,1676505921.147 [Default:CheckIn:E] Running Loop=1 2023-02-16T00:05:21.549Z,1676505921.549 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 155.206917 min 2023-02-16T00:05:21.549Z,1676505921.549 [Default:CheckIn:E] Stopped 2023-02-16T00:05:21.549Z,1676505921.549 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-16T00:05:21.549Z,1676505921.549 [Default:CheckIn] Stopped 2023-02-16T00:05:21.549Z,1676505921.549 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-16T00:05:21.550Z,1676505921.550 [Default:CheckIn](INFO): Running loop #27 2023-02-16T00:05:21.550Z,1676505921.550 [Default:CheckIn] Running Loop=27 2023-02-16T00:05:21.550Z,1676505921.550 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-16T00:05:21.550Z,1676505921.550 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-16T00:05:23.577Z,1676505923.577 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000522.00,A,3648.16689,N,12147.27971,W,1.011,61.20,160223,,,D*4E 2023-02-16T00:05:23.579Z,1676505923.579 [NAL9602](INFO): GPS fix at 20230216T000522: (36.802782, -121.787995) 2023-02-16T00:05:23.590Z,1676505923.590 [Default:CheckIn:Read_GPS] Stopped 2023-02-16T00:05:23.590Z,1676505923.590 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-16T00:05:23.958Z,1676505923.958 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-16T00:05:30.019Z,1676505930.019 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-16T00:05:31.383Z,1676505931.383 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0075.lzma 2023-02-16T00:05:32.386Z,1676505932.386 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0075.lzma.bak 2023-02-16T00:05:32.386Z,1676505932.386 [DataOverHttps](INFO): SBD MOMSN=17476147 2023-02-16T00:05:36.078Z,1676505936.078 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-16T00:05:42.542Z,1676505942.542 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-16T00:05:48.199Z,1676505948.199 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-16T00:05:48.416Z,1676505948.416 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20230215T214004/Express0076.lzma 2023-02-16T00:05:49.418Z,1676505949.418 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0076.lzma.bak 2023-02-16T00:05:49.418Z,1676505949.418 [DataOverHttps](INFO): SBD MOMSN=17476152 2023-02-16T00:05:50.635Z,1676505950.635 [Default:CheckIn:Read_Iridium] Stopped 2023-02-16T00:05:50.635Z,1676505950.635 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-16T00:05:50.635Z,1676505950.635 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-16T00:05:55.476Z,1676505955.476 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-16T00:05:55.488Z,1676505955.488 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-16T00:06:00.320Z,1676505960.320 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-16T00:06:08.399Z,1676505968.399 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-16T00:06:12.438Z,1676505972.438 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-16T00:06:20.923Z,1676505980.923 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-16T00:06:24.560Z,1676505984.560 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-16T00:06:33.851Z,1676505993.851 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-16T00:06:37.083Z,1676505997.083 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-16T00:06:46.778Z,1676506006.778 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-16T00:06:49.612Z,1676506009.612 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-16T00:06:59.739Z,1676506019.739 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-16T00:07:01.760Z,1676506021.760 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-16T00:07:13.071Z,1676506033.071 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-16T00:07:13.879Z,1676506033.879 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-16T00:07:25.191Z,1676506045.191 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-16T00:07:25.998Z,1676506045.998 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-02-16T00:07:37.311Z,1676506057.311 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2023-02-16T00:07:38.538Z,1676506058.538 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2023-02-16T00:07:49.842Z,1676506069.842 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 5. 2023-02-16T00:07:49.844Z,1676506069.844 [BPC1](INFO): Received data from all battery sticks. 2023-02-16T00:10:51.251Z,1676506251.251 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-16T00:10:51.251Z,1676506251.251 [Default:CheckIn:C.Wait] Stopped 2023-02-16T00:10:51.251Z,1676506251.251 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-16T00:10:51.252Z,1676506251.252 [Default:CheckIn:D] Running Loop=1 2023-02-16T00:10:51.651Z,1676506251.651 [Default:CheckIn:D] Stopped 2023-02-16T00:10:51.651Z,1676506251.651 [Default:CheckIn:E] Running Loop=1 2023-02-16T00:10:52.066Z,1676506252.066 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 160.715316 min 2023-02-16T00:10:52.067Z,1676506252.067 [Default:CheckIn:E] Stopped 2023-02-16T00:10:52.067Z,1676506252.067 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-16T00:10:52.067Z,1676506252.067 [Default:CheckIn] Stopped 2023-02-16T00:10:52.067Z,1676506252.067 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-16T00:10:52.067Z,1676506252.067 [Default:CheckIn](INFO): Running loop #28 2023-02-16T00:10:52.067Z,1676506252.067 [Default:CheckIn] Running Loop=28 2023-02-16T00:10:52.067Z,1676506252.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-16T00:10:52.067Z,1676506252.067 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-16T00:10:54.063Z,1676506254.063 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001053.00,A,3648.16773,N,12147.28506,W,0.253,61.20,160223,,,D*4E 2023-02-16T00:10:54.066Z,1676506254.066 [NAL9602](INFO): GPS fix at 20230216T001053: (36.802796, -121.788084) 2023-02-16T00:10:54.134Z,1676506254.134 [Default:CheckIn:Read_GPS] Stopped 2023-02-16T00:10:54.134Z,1676506254.134 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-16T00:11:01.132Z,1676506261.132 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20230215T214004/Courier0078.lzma 2023-02-16T00:11:02.134Z,1676506262.134 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0078.lzma.bak 2023-02-16T00:11:02.134Z,1676506262.134 [DataOverHttps](INFO): SBD MOMSN=17476161 2023-02-16T00:11:18.352Z,1676506278.352 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230215T214004/Express0079.lzma 2023-02-16T00:11:19.354Z,1676506279.354 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0079.lzma.bak 2023-02-16T00:11:19.354Z,1676506279.354 [DataOverHttps](INFO): SBD MOMSN=17476165 2023-02-16T00:11:20.479Z,1676506280.479 [Default:CheckIn:Read_Iridium] Stopped 2023-02-16T00:11:20.479Z,1676506280.479 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-16T00:11:20.480Z,1676506280.480 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-16T00:11:24.510Z,1676506284.510 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-02-16T00:11:24.592Z,1676506284.592 [NAL9602](ERROR): received: +CSQ:0 OK114, 2, 0, 0, 0 OK 2023-02-16T00:12:03.301Z,1676506323.301 [NAL9602](INFO): SBD MO Status=2, MOMSN=39114, MT Status=2, MTMSN=0 2023-02-16T00:12:03.301Z,1676506323.301 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-16T00:13:20.059Z,1676506400.059 [NAL9602](INFO): SBD MO Status=2, MOMSN=39114, MT Status=2, MTMSN=0 2023-02-16T00:13:20.059Z,1676506400.059 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-16T00:14:29.554Z,1676506469.554 [NAL9602](INFO): SBD MO Status=2, MOMSN=39114, MT Status=2, MTMSN=0 2023-02-16T00:14:29.554Z,1676506469.554 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-16T00:14:45.703Z,1676506485.703 [NAL9602](INFO): SBD MO Status=2, MOMSN=39114, MT Status=2, MTMSN=0 2023-02-16T00:14:45.703Z,1676506485.703 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-16T00:15:03.631Z,1676506503.631 [NAL9602](INFO): SBD MO Status=2, MOMSN=39114, MT Status=2, MTMSN=0 2023-02-16T00:15:03.631Z,1676506503.631 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-02-16T00:15:56.558Z,1676506556.558 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-02-16T00:16:21.210Z,1676506581.210 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-16T00:16:21.210Z,1676506581.210 [Default:CheckIn:C.Wait] Stopped 2023-02-16T00:16:21.210Z,1676506581.210 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-16T00:16:21.210Z,1676506581.210 [Default:CheckIn:D] Running Loop=1 2023-02-16T00:16:21.667Z,1676506581.667 [Default:CheckIn:D] Stopped 2023-02-16T00:16:21.667Z,1676506581.667 [Default:CheckIn:E] Running Loop=1 2023-02-16T00:16:22.032Z,1676506582.032 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 166.215576 min 2023-02-16T00:16:22.032Z,1676506582.032 [Default:CheckIn:E] Stopped 2023-02-16T00:16:22.032Z,1676506582.032 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-16T00:16:22.032Z,1676506582.032 [Default:CheckIn] Stopped 2023-02-16T00:16:22.032Z,1676506582.032 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-16T00:16:22.032Z,1676506582.032 [Default:CheckIn](INFO): Running loop #29 2023-02-16T00:16:22.033Z,1676506582.033 [Default:CheckIn] Running Loop=29 2023-02-16T00:16:22.033Z,1676506582.033 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-16T00:16:22.033Z,1676506582.033 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-16T00:16:24.031Z,1676506584.031 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001623.00,A,3648.16849,N,12147.28434,W,0.117,0.00,160223,,,A*7A 2023-02-16T00:16:24.033Z,1676506584.033 [NAL9602](INFO): GPS fix at 20230216T001623: (36.802808, -121.788072) 2023-02-16T00:16:24.063Z,1676506584.063 [Default:CheckIn:Read_GPS] Stopped 2023-02-16T00:16:24.063Z,1676506584.063 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-16T00:16:31.727Z,1676506591.727 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0081.lzma 2023-02-16T00:16:32.730Z,1676506592.730 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0081.lzma.bak 2023-02-16T00:16:32.730Z,1676506592.730 [DataOverHttps](INFO): SBD MOMSN=17476182 2023-02-16T00:16:49.098Z,1676506609.098 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20230215T214004/Express0082.lzma 2023-02-16T00:16:50.148Z,1676506610.148 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0082.lzma.bak 2023-02-16T00:16:50.148Z,1676506610.148 [DataOverHttps](INFO): SBD MOMSN=17476185 2023-02-16T00:16:51.230Z,1676506611.230 [Default:CheckIn:Read_Iridium] Stopped 2023-02-16T00:16:51.230Z,1676506611.230 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-16T00:16:51.230Z,1676506611.230 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-16T00:16:56.381Z,1676506616.381 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-16T00:21:26.253Z,1676506886.253 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-02-16T00:21:26.253Z,1676506886.253 [DropWeight] Hardware Fault, FailCount= 1 2023-02-16T00:21:26.253Z,1676506886.253 [DropWeight](ERROR): Hardware Fault 2023-02-16T00:21:26.271Z,1676506886.271 [CommandExec](FAULT): Scheduling is paused 2023-02-16T00:21:26.271Z,1676506886.271 [CBIT](INFO): Critical error at 20230216T002126 2023-02-16T00:21:26.274Z,1676506886.274 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-02-16T00:21:26.274Z,1676506886.274 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-02-16T00:21:26.686Z,1676506886.686 [CBIT](INFO): Critical error at 20230216T002126 2023-02-16T00:21:51.735Z,1676506911.735 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-16T00:21:51.735Z,1676506911.735 [Default:CheckIn:C.Wait] Stopped 2023-02-16T00:21:51.735Z,1676506911.735 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-16T00:21:51.735Z,1676506911.735 [Default:CheckIn:D] Running Loop=1 2023-02-16T00:21:52.138Z,1676506912.138 [Default:CheckIn:D] Stopped 2023-02-16T00:21:52.138Z,1676506912.138 [Default:CheckIn:E] Running Loop=1 2023-02-16T00:21:52.547Z,1676506912.547 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 171.723421 min 2023-02-16T00:21:52.547Z,1676506912.547 [Default:CheckIn:E] Stopped 2023-02-16T00:21:52.548Z,1676506912.548 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-16T00:21:52.548Z,1676506912.548 [Default:CheckIn] Stopped 2023-02-16T00:21:52.548Z,1676506912.548 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-16T00:21:52.548Z,1676506912.548 [Default:CheckIn](INFO): Running loop #30 2023-02-16T00:21:52.549Z,1676506912.549 [Default:CheckIn] Running Loop=30 2023-02-16T00:21:52.549Z,1676506912.549 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-16T00:21:52.549Z,1676506912.549 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-16T00:21:54.563Z,1676506914.563 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002153.00,A,3648.16897,N,12147.27995,W,0.156,0.00,160223,,,A*76 2023-02-16T00:21:54.565Z,1676506914.565 [NAL9602](INFO): GPS fix at 20230216T002153: (36.802816, -121.787999) 2023-02-16T00:21:54.576Z,1676506914.576 [Default:CheckIn:Read_GPS] Stopped 2023-02-16T00:21:54.576Z,1676506914.576 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-16T00:22:01.792Z,1676506921.792 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20230215T214004/Courier0084.lzma 2023-02-16T00:22:02.794Z,1676506922.794 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0084.lzma.bak 2023-02-16T00:22:02.794Z,1676506922.794 [DataOverHttps](INFO): SBD MOMSN=17476255 2023-02-16T00:22:18.832Z,1676506938.832 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20230215T214004/Express0085.lzma 2023-02-16T00:22:19.834Z,1676506939.834 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0085.lzma.bak 2023-02-16T00:22:19.834Z,1676506939.834 [DataOverHttps](INFO): SBD MOMSN=17476260 2023-02-16T00:22:21.287Z,1676506941.287 [Default:CheckIn:Read_Iridium] Stopped 2023-02-16T00:22:21.288Z,1676506941.288 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-16T00:22:21.288Z,1676506941.288 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-16T00:22:25.305Z,1676506945.305 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-02-16T00:22:25.388Z,1676506945.388 [NAL9602](ERROR): received: +CSQ:0 OK114, 2, 0, 0, 0 OK 2023-02-16T00:26:56.392Z,1676507216.392 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-02-16T00:27:21.870Z,1676507241.870 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-16T00:27:21.870Z,1676507241.870 [Default:CheckIn:C.Wait] Stopped 2023-02-16T00:27:21.871Z,1676507241.871 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-16T00:27:21.871Z,1676507241.871 [Default:CheckIn:D] Running Loop=1 2023-02-16T00:27:22.294Z,1676507242.294 [Default:CheckIn:D] Stopped 2023-02-16T00:27:22.294Z,1676507242.294 [Default:CheckIn:E] Running Loop=1 2023-02-16T00:27:22.663Z,1676507242.663 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 177.226025 min 2023-02-16T00:27:22.663Z,1676507242.663 [Default:CheckIn:E] Stopped 2023-02-16T00:27:22.663Z,1676507242.663 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-16T00:27:22.663Z,1676507242.663 [Default:CheckIn] Stopped 2023-02-16T00:27:22.663Z,1676507242.663 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-16T00:27:22.664Z,1676507242.664 [Default:CheckIn](INFO): Running loop #31 2023-02-16T00:27:22.664Z,1676507242.664 [Default:CheckIn] Running Loop=31 2023-02-16T00:27:22.664Z,1676507242.664 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-16T00:27:22.664Z,1676507242.664 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-16T00:27:24.675Z,1676507244.675 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002723.00,A,3648.18142,N,12147.26238,W,0.797,172.91,160223,,,A*72 2023-02-16T00:27:24.678Z,1676507244.678 [NAL9602](INFO): GPS fix at 20230216T002723: (36.803024, -121.787706) 2023-02-16T00:27:24.711Z,1676507244.711 [Default:CheckIn:Read_GPS] Stopped 2023-02-16T00:27:24.711Z,1676507244.711 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-16T00:27:32.087Z,1676507252.087 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230215T214004/Courier0087.lzma 2023-02-16T00:27:33.095Z,1676507253.095 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Courier0087.lzma.bak 2023-02-16T00:27:33.095Z,1676507253.095 [DataOverHttps](INFO): SBD MOMSN=17476285 2023-02-16T00:27:48.924Z,1676507268.924 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20230215T214004/Express0088.lzma 2023-02-16T00:27:49.926Z,1676507269.926 [DataOverHttps](INFO): Moved sent file to Logs/20230215T214004/Express0088.lzma.bak 2023-02-16T00:27:49.926Z,1676507269.926 [DataOverHttps](INFO): SBD MOMSN=17476288 2023-02-16T00:27:51.353Z,1676507271.353 [Default:CheckIn:Read_Iridium] Stopped 2023-02-16T00:27:51.353Z,1676507271.353 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-16T00:27:51.353Z,1676507271.353 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-16T00:27:57.423Z,1676507277.423 [NAL9602](INFO): Not Powering down - fast GPS 2023-02-16T00:32:52.003Z,1676507572.003 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-02-16T00:32:52.003Z,1676507572.003 [Default:CheckIn:C.Wait] Stopped 2023-02-16T00:32:52.003Z,1676507572.003 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-16T00:32:52.003Z,1676507572.003 [Default:CheckIn:D] Running Loop=1 2023-02-16T00:32:52.387Z,1676507572.387 [Default:CheckIn:D] Stopped 2023-02-16T00:32:52.387Z,1676507572.387 [Default:CheckIn:E] Running Loop=1 2023-02-16T00:32:52.764Z,1676507572.764 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 182.727572 min 2023-02-16T00:32:52.768Z,1676507572.768 [Default:CheckIn:E] Stopped 2023-02-16T00:32:52.768Z,1676507572.768 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-02-16T00:32:52.768Z,1676507572.768 [Default:CheckIn] Stopped 2023-02-16T00:32:52.769Z,1676507572.769 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-16T00:32:52.769Z,1676507572.769 [Default:CheckIn](INFO): Running loop #32 2023-02-16T00:32:52.769Z,1676507572.769 [Default:CheckIn] Running Loop=32 2023-02-16T00:32:52.769Z,1676507572.769 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-02-16T00:32:52.769Z,1676507572.769 [Default:CheckIn:Read_GPS] Running Loop=1 2023-02-16T00:32:54.771Z,1676507574.771 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003254.00,A,3648.16600,N,12147.28209,W,1.205,3.55,160223,,,A*75 2023-02-16T00:32:54.774Z,1676507574.774 [NAL9602](INFO): GPS fix at 20230216T003254: (36.802767, -121.788035) 2023-02-16T00:32:54.831Z,1676507574.831 [Default:CheckIn:Read_GPS] Stopped 2023-02-16T00:32:54.831Z,1676507574.831 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-02-16T00:32:55.886Z,1676507575.886 [Default:CheckIn:Read_Iridium] Stopped 2023-02-16T00:32:55.886Z,1676507575.886 [Default:CheckIn:C.Wait] Running Loop=1 2023-02-16T00:32:55.887Z,1676507575.887 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-02-16T00:33:25.313Z,1676507605.313 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-02-16T00:33:25.388Z,1676507605.388 [NAL9602](ERROR): received: +CSQ:0 OK114, 2, 0, 0, 0 OK 2023-02-16T00:36:26.385Z,1676507786.385 [CBIT](INFO): Clearing failed state for component DropWeight 2023-02-16T00:36:26.385Z,1676507786.385 [DropWeight] No Fault, FailCount= 1 2023-02-16T00:36:28.705Z,1676507788.705 [CommandExec](IMPORTANT): got command quit 2023-02-16T00:36:29.712Z,1676507789.712 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T00:36:29.713Z,1676507789.713 [CommandExec](INFO): Uninitializing the command executive. 2023-02-16T00:36:29.713Z,1676507789.713 [CommandExec](INFO): Uninitializing the command scheduler. 2023-02-16T00:36:29.713Z,1676507789.713 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:29.864Z,1676507789.864 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-02-16T00:36:29.864Z,1676507789.864 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-02-16T00:36:29.865Z,1676507789.865 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:29.865Z,1676507789.865 [NavChartDb](INFO): Join timeout helper Thread ID is 776 2023-02-16T00:36:29.877Z,1676507789.877 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T00:36:29.877Z,1676507789.877 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:29.877Z,1676507789.877 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-02-16T00:36:29.878Z,1676507789.878 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:29.878Z,1676507789.878 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 778 2023-02-16T00:36:29.904Z,1676507789.904 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T00:36:29.904Z,1676507789.904 [WetLabsBB2FL](INFO): Powering down 2023-02-16T00:36:29.905Z,1676507789.905 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:29.952Z,1676507789.952 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-02-16T00:36:29.952Z,1676507789.952 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:29.953Z,1676507789.953 [CTD_Seabird](INFO): Join timeout helper Thread ID is 779 2023-02-16T00:36:30.264Z,1676507790.264 [CTD_Seabird](INFO): Powering down 2023-02-16T00:36:30.279Z,1676507790.279 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T00:36:30.279Z,1676507790.279 [CTD_Seabird](INFO): Powering down 2023-02-16T00:36:30.292Z,1676507790.292 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:30.304Z,1676507790.304 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-02-16T00:36:30.304Z,1676507790.304 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:30.305Z,1676507790.305 [Radio_Surface](INFO): Join timeout helper Thread ID is 780 2023-02-16T00:36:30.308Z,1676507790.308 [Radio_Surface](INFO): Powering down 2023-02-16T00:36:30.309Z,1676507790.309 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T00:36:30.309Z,1676507790.309 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:30.316Z,1676507790.316 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-02-16T00:36:30.316Z,1676507790.316 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:30.317Z,1676507790.317 [Onboard](INFO): Join timeout helper Thread ID is 781 2023-02-16T00:36:33.488Z,1676507793.488 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T00:36:33.488Z,1676507793.488 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:33.493Z,1676507793.493 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-02-16T00:36:33.493Z,1676507793.493 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:33.494Z,1676507793.494 [DataOverHttps](INFO): Join timeout helper Thread ID is 782 2023-02-16T00:36:34.625Z,1676507794.625 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T00:36:34.628Z,1676507794.628 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:34.641Z,1676507794.641 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-02-16T00:36:34.641Z,1676507794.641 [logger ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:34.641Z,1676507794.641 [logger](INFO): Join timeout helper Thread ID is 783 2023-02-16T00:36:34.656Z,1676507794.656 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T00:36:34.656Z,1676507794.656 [logger ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:34.661Z,1676507794.661 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-02-16T00:36:34.661Z,1676507794.661 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:34.661Z,1676507794.661 [CommandLine](INFO): Join timeout helper Thread ID is 784 2023-02-16T00:36:34.744Z,1676507794.744 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T00:36:34.744Z,1676507794.744 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:34.761Z,1676507794.761 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-02-16T00:36:34.761Z,1676507794.761 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:34.761Z,1676507794.761 [CommandExec](INFO): Join timeout helper Thread ID is 785 2023-02-16T00:36:34.762Z,1676507794.762 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-02-16T00:36:34.762Z,1676507794.762 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:34.763Z,1676507794.763 [controlThread](INFO): Join timeout helper Thread ID is 786 2023-02-16T00:36:34.776Z,1676507794.776 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-02-16T00:36:34.776Z,1676507794.776 [controlThread](DEBUG): Uninitializing ControlThread 2023-02-16T00:36:34.777Z,1676507794.777 [AHRS_M2](INFO): Powering down 2023-02-16T00:36:34.849Z,1676507794.849 [MultiRay](INFO): Powering down. 2023-02-16T00:36:34.850Z,1676507794.850 [NAL9602](INFO): Powering down 2023-02-16T00:36:34.852Z,1676507794.852 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-02-16T00:36:34.853Z,1676507794.853 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-02-16T00:36:34.854Z,1676507794.854 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-02-16T00:36:34.854Z,1676507794.854 [MissionManager](INFO): Uninitializing Mission Default 2023-02-16T00:36:34.854Z,1676507794.854 [Default] Stopped 2023-02-16T00:36:34.854Z,1676507794.854 [Default](DEBUG): Aggregate::uninitialize Default 2023-02-16T00:36:34.854Z,1676507794.854 [Default:B.GoToSurface] Stopped 2023-02-16T00:36:34.855Z,1676507794.855 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-02-16T00:36:34.855Z,1676507794.855 [Default:CheckIn] Stopped 2023-02-16T00:36:34.855Z,1676507794.855 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-02-16T00:36:34.855Z,1676507794.855 [Default:CheckIn:C.Wait] Stopped 2023-02-16T00:36:34.855Z,1676507794.855 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-02-16T00:36:34.858Z,1676507794.858 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-02-16T00:36:34.858Z,1676507794.858 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-02-16T00:36:34.858Z,1676507794.858 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-02-16T00:36:34.858Z,1676507794.858 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-02-16T00:36:34.859Z,1676507794.859 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-02-16T00:36:34.859Z,1676507794.859 [BuoyancyServo](INFO): Powering down 2023-02-16T00:36:34.872Z,1676507794.872 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-02-16T00:36:34.872Z,1676507794.872 [ElevatorServo](INFO): Powering down 2023-02-16T00:36:34.873Z,1676507794.873 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-02-16T00:36:34.873Z,1676507794.873 [MassServo](INFO): Powering down 2023-02-16T00:36:34.874Z,1676507794.874 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-02-16T00:36:34.874Z,1676507794.874 [RudderServo](INFO): Powering down 2023-02-16T00:36:34.875Z,1676507794.875 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2023-02-16T00:36:34.875Z,1676507794.875 [ThrusterServo](INFO): Powering down 2023-02-16T00:36:34.876Z,1676507794.876 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-02-16T00:36:34.876Z,1676507794.876 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-02-16T00:36:34.877Z,1676507794.877 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-02-16T00:36:34.877Z,1676507794.877 [CBIT](DEBUG): Powering off loads. 2023-02-16T00:36:34.888Z,1676507794.888 [CBIT](DEBUG): Disabling WDT. 2023-02-16T00:36:34.900Z,1676507794.900 [CBIT](DEBUG): Opening all GF detection circuits. 2023-02-16T00:36:34.901Z,1676507794.901 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:34.966Z,1676507794.966 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:34.969Z,1676507794.969 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:34.976Z,1676507794.976 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:35.049Z,1676507795.049 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:35.052Z,1676507795.052 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:35.096Z,1676507795.096 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-02-16T00:36:35.206Z,1676507795.206 [logger ThreadHandler](INFO): Thread cancelled.