2024-12-20T20:42:26.545Z,1734727346.545 [CommandExec](IMPORTANT): got command restart logs 2024-12-20T20:42:41.079Z,1734727361.079 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:42:56.080Z,1734727376.080 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2024-12-20T20:45:17.139Z,1734727517.139 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-12-20T20:45:23.555Z,1734727523.555 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:45:37.290Z,1734727537.290 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:45:40.944Z,1734727540.944 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T20:45:40.944Z,1734727540.944 [Default:CheckIn:C.Wait] Stopped 2024-12-20T20:45:40.944Z,1734727540.944 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T20:45:40.944Z,1734727540.944 [Default:CheckIn:D] Running Loop=1 2024-12-20T20:45:41.347Z,1734727541.347 [Default:CheckIn:D] Stopped 2024-12-20T20:45:41.348Z,1734727541.348 [Default:CheckIn:E] Running Loop=1 2024-12-20T20:45:41.757Z,1734727541.757 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.000549 min 2024-12-20T20:45:41.758Z,1734727541.758 [Default:CheckIn:E] Stopped 2024-12-20T20:45:41.758Z,1734727541.758 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T20:45:41.758Z,1734727541.758 [Default:CheckIn] Stopped 2024-12-20T20:45:41.758Z,1734727541.758 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T20:45:41.758Z,1734727541.758 [Default:CheckIn](INFO): Running loop #5 2024-12-20T20:45:41.758Z,1734727541.758 [Default:CheckIn] Running Loop=5 2024-12-20T20:45:41.758Z,1734727541.758 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T20:45:41.758Z,1734727541.758 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T20:45:43.767Z,1734727543.767 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204548.00,A,3644.48008,N,12152.33493,W,26.261,50.69,201224,,,D*70 2024-12-20T20:45:43.779Z,1734727543.779 [NAL9602](INFO): GPS fix at 20241220T204548: (36.741335, -121.872249) 2024-12-20T20:45:43.790Z,1734727543.790 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T20:45:43.790Z,1734727543.790 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T20:45:51.027Z,1734727551.027 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:45:51.275Z,1734727551.275 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241220T174333/Courier0036.lzma 2024-12-20T20:45:52.278Z,1734727552.278 [DataOverHttps](INFO): Moved sent file to Logs/20241220T174333/Courier0036.lzma.bak 2024-12-20T20:45:52.278Z,1734727552.278 [DataOverHttps](INFO): SBD MOMSN=23850733 2024-12-20T20:46:03.159Z,1734727563.159 [NAL9602](INFO): SBD MO Status=1, MOMSN=20602, MT Status=0, MTMSN=0 2024-12-20T20:46:03.159Z,1734727563.159 [NAL9602](INFO): No messages in MT queue 2024-12-20T20:46:05.166Z,1734727565.166 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:46:08.100Z,1734727568.100 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20241220T204226/Courier0000.lzma 2024-12-20T20:46:11.110Z,1734727571.110 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0000.lzma.bak 2024-12-20T20:46:11.110Z,1734727571.110 [DataOverHttps](INFO): SBD MOMSN=23850735 2024-12-20T20:46:18.902Z,1734727578.902 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:46:28.127Z,1734727588.127 [DataOverHttps](INFO): Sending 107 bytes from file Logs/20241220T174333/Express0037.lzma 2024-12-20T20:46:29.130Z,1734727589.130 [DataOverHttps](INFO): Moved sent file to Logs/20241220T174333/Express0037.lzma.bak 2024-12-20T20:46:29.130Z,1734727589.130 [DataOverHttps](INFO): SBD MOMSN=23850741 2024-12-20T20:46:32.638Z,1734727592.638 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:46:33.869Z,1734727593.869 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-20T20:46:46.813Z,1734727606.813 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:46:47.648Z,1734727607.648 [DataOverHttps](INFO): Sending 405 bytes from file Logs/20241220T204226/Express0001.lzma 2024-12-20T20:46:48.650Z,1734727608.650 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0001.lzma.bak 2024-12-20T20:46:48.650Z,1734727608.650 [DataOverHttps](INFO): SBD MOMSN=23850744 2024-12-20T20:47:00.546Z,1734727620.546 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:47:05.755Z,1734727625.755 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20241220T204226/Express0004.lzma 2024-12-20T20:47:06.758Z,1734727626.758 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0004.lzma.bak 2024-12-20T20:47:06.758Z,1734727626.758 [DataOverHttps](INFO): SBD MOMSN=23850748 2024-12-20T20:47:08.243Z,1734727628.243 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T20:47:08.244Z,1734727628.244 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T20:47:08.244Z,1734727628.244 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T20:47:13.879Z,1734727633.879 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:47:27.210Z,1734727647.210 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:49:11.511Z,1734727751.511 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-12-20T20:49:40.080Z,1734727780.080 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-20T20:50:04.019Z,1734727804.019 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:50:11.204Z,1734727811.204 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-20T20:50:19.403Z,1734727819.403 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:50:35.139Z,1734727835.139 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:50:42.344Z,1734727842.344 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-12-20T20:50:50.078Z,1734727850.078 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:51:05.026Z,1734727865.026 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:51:19.974Z,1734727879.974 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:51:35.329Z,1734727895.329 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:51:50.274Z,1734727910.274 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:52:06.030Z,1734727926.030 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T20:52:08.900Z,1734727928.900 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T20:52:08.900Z,1734727928.900 [Default:CheckIn:C.Wait] Stopped 2024-12-20T20:52:08.900Z,1734727928.900 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T20:52:08.901Z,1734727928.901 [Default:CheckIn:D] Running Loop=1 2024-12-20T20:52:09.300Z,1734727929.300 [Default:CheckIn:D] Stopped 2024-12-20T20:52:09.301Z,1734727929.301 [Default:CheckIn:E] Running Loop=1 2024-12-20T20:52:09.717Z,1734727929.717 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.466435 min 2024-12-20T20:52:09.717Z,1734727929.717 [Default:CheckIn:E] Stopped 2024-12-20T20:52:09.717Z,1734727929.717 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T20:52:09.717Z,1734727929.717 [Default:CheckIn] Stopped 2024-12-20T20:52:09.717Z,1734727929.717 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T20:52:09.717Z,1734727929.717 [Default:CheckIn](INFO): Running loop #6 2024-12-20T20:52:09.717Z,1734727929.717 [Default:CheckIn] Running Loop=6 2024-12-20T20:52:09.717Z,1734727929.717 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T20:52:09.717Z,1734727929.717 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T20:52:11.691Z,1734727931.691 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205216.00,A,3646.49689,N,12149.43118,W,28.963,42.12,201224,,,D*72 2024-12-20T20:52:11.693Z,1734727931.693 [NAL9602](INFO): GPS fix at 20241220T205216: (36.774948, -121.823853) 2024-12-20T20:52:11.706Z,1734727931.706 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T20:52:11.706Z,1734727931.706 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T20:52:19.267Z,1734727939.267 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20241220T204226/Courier0006.lzma 2024-12-20T20:52:20.270Z,1734727940.270 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0006.lzma.bak 2024-12-20T20:52:20.270Z,1734727940.270 [DataOverHttps](INFO): SBD MOMSN=23850769 2024-12-20T20:52:24.622Z,1734727944.622 [NAL9602](INFO): SBD MO Status=1, MOMSN=20603, MT Status=0, MTMSN=0 2024-12-20T20:52:24.623Z,1734727944.623 [NAL9602](INFO): No messages in MT queue 2024-12-20T20:52:36.175Z,1734727956.175 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20241220T204226/Express0007.lzma 2024-12-20T20:52:37.178Z,1734727957.178 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0007.lzma.bak 2024-12-20T20:52:37.178Z,1734727957.178 [DataOverHttps](INFO): SBD MOMSN=23850772 2024-12-20T20:52:38.767Z,1734727958.767 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T20:52:38.767Z,1734727958.767 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T20:52:38.767Z,1734727958.767 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T20:52:55.353Z,1734727975.353 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-20T20:54:46.516Z,1734728086.516 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2024-12-20T20:54:46.517Z,1734728086.517 [BPC1](INFO): Calculating totals. Valid battery stick count: 45. Valid reserve battery stick count: 5. 2024-12-20T20:54:46.519Z,1734728086.519 [BPC1](INFO): Received data from all battery sticks. 2024-12-20T20:57:39.495Z,1734728259.495 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T20:57:39.495Z,1734728259.495 [Default:CheckIn:C.Wait] Stopped 2024-12-20T20:57:39.495Z,1734728259.495 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T20:57:39.495Z,1734728259.495 [Default:CheckIn:D] Running Loop=1 2024-12-20T20:57:39.904Z,1734728259.904 [Default:CheckIn:D] Stopped 2024-12-20T20:57:39.904Z,1734728259.904 [Default:CheckIn:E] Running Loop=1 2024-12-20T20:57:40.314Z,1734728260.314 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.976485 min 2024-12-20T20:57:40.314Z,1734728260.314 [Default:CheckIn:E] Stopped 2024-12-20T20:57:40.314Z,1734728260.314 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T20:57:40.314Z,1734728260.314 [Default:CheckIn] Stopped 2024-12-20T20:57:40.314Z,1734728260.314 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T20:57:40.314Z,1734728260.314 [Default:CheckIn](INFO): Running loop #7 2024-12-20T20:57:40.314Z,1734728260.314 [Default:CheckIn] Running Loop=7 2024-12-20T20:57:40.314Z,1734728260.314 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T20:57:40.315Z,1734728260.315 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T20:57:42.315Z,1734728262.315 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205747.00,A,3648.39211,N,12147.38858,W,9.758,49.11,201224,,,D*4D 2024-12-20T20:57:42.317Z,1734728262.317 [NAL9602](INFO): GPS fix at 20241220T205747: (36.806535, -121.789810) 2024-12-20T20:57:42.353Z,1734728262.353 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T20:57:42.353Z,1734728262.353 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T20:57:49.692Z,1734728269.692 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20241220T204226/Courier0009.lzma 2024-12-20T20:57:50.694Z,1734728270.694 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0009.lzma.bak 2024-12-20T20:57:50.694Z,1734728270.694 [DataOverHttps](INFO): SBD MOMSN=23850798 2024-12-20T20:57:57.670Z,1734728277.670 [NAL9602](INFO): SBD MO Status=1, MOMSN=20604, MT Status=0, MTMSN=0 2024-12-20T20:57:57.671Z,1734728277.671 [NAL9602](INFO): No messages in MT queue 2024-12-20T20:58:06.491Z,1734728286.491 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20241220T204226/Express0010.lzma 2024-12-20T20:58:07.494Z,1734728287.494 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0010.lzma.bak 2024-12-20T20:58:07.494Z,1734728287.494 [DataOverHttps](INFO): SBD MOMSN=23850801 2024-12-20T20:58:08.994Z,1734728288.994 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T20:58:08.994Z,1734728288.994 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T20:58:08.994Z,1734728288.994 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T20:58:28.369Z,1734728308.369 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-20T20:59:11.687Z,1734728351.687 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-12-20T21:03:09.774Z,1734728589.774 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T21:03:09.774Z,1734728589.774 [Default:CheckIn:C.Wait] Stopped 2024-12-20T21:03:09.774Z,1734728589.774 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T21:03:09.775Z,1734728589.775 [Default:CheckIn:D] Running Loop=1 2024-12-20T21:03:10.172Z,1734728590.172 [Default:CheckIn:D] Stopped 2024-12-20T21:03:10.172Z,1734728590.172 [Default:CheckIn:E] Running Loop=1 2024-12-20T21:03:10.571Z,1734728590.571 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.480957 min 2024-12-20T21:03:10.572Z,1734728590.572 [Default:CheckIn:E] Stopped 2024-12-20T21:03:10.572Z,1734728590.572 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T21:03:10.572Z,1734728590.572 [Default:CheckIn] Stopped 2024-12-20T21:03:10.572Z,1734728590.572 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T21:03:10.572Z,1734728590.572 [Default:CheckIn](INFO): Running loop #8 2024-12-20T21:03:10.572Z,1734728590.572 [Default:CheckIn] Running Loop=8 2024-12-20T21:03:10.572Z,1734728590.572 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T21:03:10.573Z,1734728590.573 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T21:03:12.591Z,1734728592.591 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210317.00,A,3648.16965,N,12147.18971,W,4.335,229.88,201224,,,D*73 2024-12-20T21:03:12.593Z,1734728592.593 [NAL9602](INFO): GPS fix at 20241220T210317: (36.802827, -121.786495) 2024-12-20T21:03:12.609Z,1734728592.609 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T21:03:12.609Z,1734728592.609 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T21:03:19.103Z,1734728599.103 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241220T204226/Courier0012.lzma 2024-12-20T21:03:20.106Z,1734728600.106 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0012.lzma.bak 2024-12-20T21:03:20.106Z,1734728600.106 [DataOverHttps](INFO): SBD MOMSN=23850826 2024-12-20T21:03:30.762Z,1734728610.762 [NAL9602](INFO): SBD MO Status=2, MOMSN=20605, MT Status=2, MTMSN=0 2024-12-20T21:03:30.762Z,1734728610.762 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-20T21:03:35.779Z,1734728615.779 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20241220T204226/Express0013.lzma 2024-12-20T21:03:36.782Z,1734728616.782 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0013.lzma.bak 2024-12-20T21:03:36.782Z,1734728616.782 [DataOverHttps](INFO): SBD MOMSN=23850829 2024-12-20T21:03:38.053Z,1734728618.053 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T21:03:38.053Z,1734728618.053 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T21:03:38.053Z,1734728618.053 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T21:03:43.703Z,1734728623.703 [NAL9602](INFO): SBD MO Status=1, MOMSN=20605, MT Status=0, MTMSN=0 2024-12-20T21:03:43.703Z,1734728623.703 [NAL9602](INFO): No messages in MT queue 2024-12-20T21:04:14.391Z,1734728654.391 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-20T21:08:38.704Z,1734728918.704 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T21:08:38.704Z,1734728918.704 [Default:CheckIn:C.Wait] Stopped 2024-12-20T21:08:38.705Z,1734728918.705 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T21:08:38.705Z,1734728918.705 [Default:CheckIn:D] Running Loop=1 2024-12-20T21:08:39.116Z,1734728919.116 [Default:CheckIn:D] Stopped 2024-12-20T21:08:39.116Z,1734728919.116 [Default:CheckIn:E] Running Loop=1 2024-12-20T21:08:39.513Z,1734728919.513 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.963359 min 2024-12-20T21:08:39.513Z,1734728919.513 [Default:CheckIn:E] Stopped 2024-12-20T21:08:39.513Z,1734728919.513 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T21:08:39.513Z,1734728919.513 [Default:CheckIn] Stopped 2024-12-20T21:08:39.513Z,1734728919.513 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T21:08:39.513Z,1734728919.513 [Default:CheckIn](INFO): Running loop #9 2024-12-20T21:08:39.513Z,1734728919.513 [Default:CheckIn] Running Loop=9 2024-12-20T21:08:39.513Z,1734728919.513 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T21:08:39.513Z,1734728919.513 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T21:08:41.523Z,1734728921.523 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210846.00,A,3648.15011,N,12147.22138,W,0.039,208.36,201224,,,D*74 2024-12-20T21:08:41.526Z,1734728921.526 [NAL9602](INFO): GPS fix at 20241220T210846: (36.802502, -121.787023) 2024-12-20T21:08:41.581Z,1734728921.581 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T21:08:41.582Z,1734728921.582 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T21:08:51.271Z,1734728931.271 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241220T204226/Courier0015.lzma 2024-12-20T21:08:52.290Z,1734728932.290 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0015.lzma.bak 2024-12-20T21:08:52.290Z,1734728932.290 [DataOverHttps](INFO): SBD MOMSN=23850854 2024-12-20T21:08:56.263Z,1734728936.263 [NAL9602](INFO): SBD MO Status=1, MOMSN=20606, MT Status=0, MTMSN=0 2024-12-20T21:08:56.263Z,1734728936.263 [NAL9602](INFO): No messages in MT queue 2024-12-20T21:09:08.051Z,1734728948.051 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20241220T204226/Express0016.lzma 2024-12-20T21:09:09.054Z,1734728949.054 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0016.lzma.bak 2024-12-20T21:09:09.054Z,1734728949.054 [DataOverHttps](INFO): SBD MOMSN=23850857 2024-12-20T21:09:10.442Z,1734728950.442 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T21:09:10.442Z,1734728950.442 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T21:09:10.442Z,1734728950.442 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T21:09:12.047Z,1734728952.047 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-12-20T21:09:26.983Z,1734728966.983 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-20T21:14:11.116Z,1734729251.116 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T21:14:11.116Z,1734729251.116 [Default:CheckIn:C.Wait] Stopped 2024-12-20T21:14:11.116Z,1734729251.116 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T21:14:11.116Z,1734729251.116 [Default:CheckIn:D] Running Loop=1 2024-12-20T21:14:11.520Z,1734729251.520 [Default:CheckIn:D] Stopped 2024-12-20T21:14:11.520Z,1734729251.520 [Default:CheckIn:E] Running Loop=1 2024-12-20T21:14:11.932Z,1734729251.932 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.503418 min 2024-12-20T21:14:11.932Z,1734729251.932 [Default:CheckIn:E] Stopped 2024-12-20T21:14:11.932Z,1734729251.932 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T21:14:11.932Z,1734729251.932 [Default:CheckIn] Stopped 2024-12-20T21:14:11.932Z,1734729251.932 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T21:14:11.933Z,1734729251.933 [Default:CheckIn](INFO): Running loop #10 2024-12-20T21:14:11.933Z,1734729251.933 [Default:CheckIn] Running Loop=10 2024-12-20T21:14:11.933Z,1734729251.933 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T21:14:11.933Z,1734729251.933 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T21:14:13.934Z,1734729253.934 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211418.00,A,3648.14963,N,12147.22341,W,0.078,235.17,201224,,,D*7B 2024-12-20T21:14:13.936Z,1734729253.936 [NAL9602](INFO): GPS fix at 20241220T211418: (36.802494, -121.787057) 2024-12-20T21:14:13.947Z,1734729253.947 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T21:14:13.948Z,1734729253.948 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T21:14:21.047Z,1734729261.047 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241220T204226/Courier0018.lzma 2024-12-20T21:14:22.050Z,1734729262.050 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0018.lzma.bak 2024-12-20T21:14:22.050Z,1734729262.050 [DataOverHttps](INFO): SBD MOMSN=23850884 2024-12-20T21:14:37.800Z,1734729277.800 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20241220T204226/Express0019.lzma 2024-12-20T21:14:38.802Z,1734729278.802 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0019.lzma.bak 2024-12-20T21:14:38.802Z,1734729278.802 [DataOverHttps](INFO): SBD MOMSN=23850887 2024-12-20T21:14:40.209Z,1734729280.209 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T21:14:40.209Z,1734729280.209 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T21:14:40.209Z,1734729280.209 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T21:14:43.846Z,1734729283.846 [NAL9602](INFO): SBD MO Status=2, MOMSN=20607, MT Status=2, MTMSN=0 2024-12-20T21:14:43.847Z,1734729283.847 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-20T21:15:21.446Z,1734729321.446 [NAL9602](INFO): SBD MO Status=2, MOMSN=20607, MT Status=2, MTMSN=0 2024-12-20T21:15:21.446Z,1734729321.446 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-20T21:15:41.651Z,1734729341.651 [NAL9602](INFO): SBD MO Status=1, MOMSN=20607, MT Status=0, MTMSN=0 2024-12-20T21:15:41.651Z,1734729341.651 [NAL9602](INFO): No messages in MT queue 2024-12-20T21:16:12.347Z,1734729372.347 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-20T21:19:12.226Z,1734729552.226 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-12-20T21:19:40.929Z,1734729580.929 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T21:19:40.929Z,1734729580.929 [Default:CheckIn:C.Wait] Stopped 2024-12-20T21:19:40.930Z,1734729580.930 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T21:19:40.930Z,1734729580.930 [Default:CheckIn:D] Running Loop=1 2024-12-20T21:19:41.344Z,1734729581.344 [Default:CheckIn:D] Stopped 2024-12-20T21:19:41.344Z,1734729581.344 [Default:CheckIn:E] Running Loop=1 2024-12-20T21:19:41.727Z,1734729581.727 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.000216 min 2024-12-20T21:19:41.727Z,1734729581.727 [Default:CheckIn:E] Stopped 2024-12-20T21:19:41.727Z,1734729581.727 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T21:19:41.727Z,1734729581.727 [Default:CheckIn] Stopped 2024-12-20T21:19:41.727Z,1734729581.727 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T21:19:41.727Z,1734729581.727 [Default:CheckIn](INFO): Running loop #11 2024-12-20T21:19:41.727Z,1734729581.727 [Default:CheckIn] Running Loop=11 2024-12-20T21:19:41.727Z,1734729581.727 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T21:19:41.728Z,1734729581.727 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T21:19:43.739Z,1734729583.739 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211948.00,A,3648.17283,N,12147.28094,W,0.058,200.89,201224,,,D*77 2024-12-20T21:19:43.742Z,1734729583.742 [NAL9602](INFO): GPS fix at 20241220T211948: (36.802881, -121.788016) 2024-12-20T21:19:43.753Z,1734729583.753 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T21:19:43.753Z,1734729583.753 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T21:19:50.439Z,1734729590.439 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20241220T204226/Courier0021.lzma 2024-12-20T21:19:51.442Z,1734729591.442 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0021.lzma.bak 2024-12-20T21:19:51.442Z,1734729591.442 [DataOverHttps](INFO): SBD MOMSN=23850914 2024-12-20T21:19:55.455Z,1734729595.455 [NAL9602](INFO): SBD MO Status=1, MOMSN=20608, MT Status=0, MTMSN=0 2024-12-20T21:19:55.455Z,1734729595.455 [NAL9602](INFO): No messages in MT queue 2024-12-20T21:20:07.179Z,1734729607.179 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20241220T204226/Express0022.lzma 2024-12-20T21:20:08.182Z,1734729608.182 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0022.lzma.bak 2024-12-20T21:20:08.182Z,1734729608.182 [DataOverHttps](INFO): SBD MOMSN=23850917 2024-12-20T21:20:09.625Z,1734729609.625 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T21:20:09.625Z,1734729609.625 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T21:20:09.625Z,1734729609.625 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T21:20:26.181Z,1734729626.181 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-20T21:25:10.387Z,1734729910.387 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T21:25:10.387Z,1734729910.387 [Default:CheckIn:C.Wait] Stopped 2024-12-20T21:25:10.387Z,1734729910.387 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T21:25:10.388Z,1734729910.388 [Default:CheckIn:D] Running Loop=1 2024-12-20T21:25:10.796Z,1734729910.796 [Default:CheckIn:D] Stopped 2024-12-20T21:25:10.796Z,1734729910.796 [Default:CheckIn:E] Running Loop=1 2024-12-20T21:25:11.211Z,1734729911.211 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.491353 min 2024-12-20T21:25:11.211Z,1734729911.211 [Default:CheckIn:E] Stopped 2024-12-20T21:25:11.211Z,1734729911.211 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T21:25:11.211Z,1734729911.211 [Default:CheckIn] Stopped 2024-12-20T21:25:11.211Z,1734729911.211 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T21:25:11.211Z,1734729911.211 [Default:CheckIn](INFO): Running loop #12 2024-12-20T21:25:11.211Z,1734729911.211 [Default:CheckIn] Running Loop=12 2024-12-20T21:25:11.211Z,1734729911.211 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T21:25:11.211Z,1734729911.211 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T21:25:13.211Z,1734729913.211 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212518.00,A,3648.16989,N,12147.28135,W,0.369,200.89,201224,,,A*73 2024-12-20T21:25:13.213Z,1734729913.213 [NAL9602](INFO): GPS fix at 20241220T212518: (36.802832, -121.788022) 2024-12-20T21:25:13.249Z,1734729913.249 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T21:25:13.249Z,1734729913.249 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T21:25:19.695Z,1734729919.695 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241220T204226/Courier0024.lzma 2024-12-20T21:25:20.698Z,1734729920.698 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0024.lzma.bak 2024-12-20T21:25:20.698Z,1734729920.698 [DataOverHttps](INFO): SBD MOMSN=23850943 2024-12-20T21:25:36.507Z,1734729936.507 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20241220T204226/Express0025.lzma 2024-12-20T21:25:37.510Z,1734729937.510 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0025.lzma.bak 2024-12-20T21:25:37.510Z,1734729937.510 [DataOverHttps](INFO): SBD MOMSN=23850947 2024-12-20T21:25:39.080Z,1734729939.080 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T21:25:39.080Z,1734729939.080 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T21:25:39.081Z,1734729939.081 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T21:25:51.586Z,1734729951.586 [NAL9602](INFO): SBD MO Status=2, MOMSN=20609, MT Status=2, MTMSN=0 2024-12-20T21:25:51.586Z,1734729951.586 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-20T21:26:08.563Z,1734729968.563 [NAL9602](INFO): SBD MO Status=1, MOMSN=20609, MT Status=0, MTMSN=0 2024-12-20T21:26:08.563Z,1734729968.563 [NAL9602](INFO): No messages in MT queue 2024-12-20T21:26:39.261Z,1734729999.261 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-20T21:29:12.475Z,1734730152.475 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-12-20T21:30:39.744Z,1734730239.744 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T21:30:39.744Z,1734730239.744 [Default:CheckIn:C.Wait] Stopped 2024-12-20T21:30:39.744Z,1734730239.744 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T21:30:39.745Z,1734730239.745 [Default:CheckIn:D] Running Loop=1 2024-12-20T21:30:40.151Z,1734730240.151 [Default:CheckIn:D] Stopped 2024-12-20T21:30:40.151Z,1734730240.151 [Default:CheckIn:E] Running Loop=1 2024-12-20T21:30:40.556Z,1734730240.556 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.980615 min 2024-12-20T21:30:40.556Z,1734730240.556 [Default:CheckIn:E] Stopped 2024-12-20T21:30:40.556Z,1734730240.556 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T21:30:40.556Z,1734730240.556 [Default:CheckIn] Stopped 2024-12-20T21:30:40.556Z,1734730240.556 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T21:30:40.556Z,1734730240.556 [Default:CheckIn](INFO): Running loop #13 2024-12-20T21:30:40.556Z,1734730240.556 [Default:CheckIn] Running Loop=13 2024-12-20T21:30:40.557Z,1734730240.557 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T21:30:40.557Z,1734730240.557 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T21:30:42.562Z,1734730242.562 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213047.00,A,3648.16834,N,12147.27648,W,0.991,200.89,201224,,,A*75 2024-12-20T21:30:42.564Z,1734730242.564 [NAL9602](INFO): GPS fix at 20241220T213047: (36.802806, -121.787941) 2024-12-20T21:30:42.608Z,1734730242.608 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T21:30:42.608Z,1734730242.608 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T21:30:52.319Z,1734730252.319 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241220T204226/Courier0027.lzma 2024-12-20T21:30:53.322Z,1734730253.322 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0027.lzma.bak 2024-12-20T21:30:53.322Z,1734730253.322 [DataOverHttps](INFO): SBD MOMSN=23850972 2024-12-20T21:31:09.255Z,1734730269.255 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20241220T204226/Express0028.lzma 2024-12-20T21:31:10.258Z,1734730270.258 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0028.lzma.bak 2024-12-20T21:31:10.258Z,1734730270.258 [DataOverHttps](INFO): SBD MOMSN=23850975 2024-12-20T21:31:11.747Z,1734730271.747 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T21:31:11.747Z,1734730271.747 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T21:31:11.748Z,1734730271.748 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T21:31:17.783Z,1734730277.783 [NAL9602](INFO): SBD MO Status=2, MOMSN=20610, MT Status=2, MTMSN=0 2024-12-20T21:31:17.783Z,1734730277.783 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-20T21:34:53.214Z,1734730493.214 [NAL9602](INFO): SBD MO Status=1, MOMSN=20610, MT Status=0, MTMSN=0 2024-12-20T21:34:53.214Z,1734730493.214 [NAL9602](INFO): No messages in MT queue 2024-12-20T21:35:23.922Z,1734730523.922 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-20T21:36:12.411Z,1734730572.411 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T21:36:12.411Z,1734730572.411 [Default:CheckIn:C.Wait] Stopped 2024-12-20T21:36:12.411Z,1734730572.411 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T21:36:12.412Z,1734730572.412 [Default:CheckIn:D] Running Loop=1 2024-12-20T21:36:12.813Z,1734730572.813 [Default:CheckIn:D] Stopped 2024-12-20T21:36:12.813Z,1734730572.813 [Default:CheckIn:E] Running Loop=1 2024-12-20T21:36:13.221Z,1734730573.221 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.524967 min 2024-12-20T21:36:13.221Z,1734730573.221 [Default:CheckIn:E] Stopped 2024-12-20T21:36:13.221Z,1734730573.221 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T21:36:13.221Z,1734730573.221 [Default:CheckIn] Stopped 2024-12-20T21:36:13.221Z,1734730573.221 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T21:36:13.221Z,1734730573.221 [Default:CheckIn](INFO): Running loop #14 2024-12-20T21:36:13.221Z,1734730573.221 [Default:CheckIn] Running Loop=14 2024-12-20T21:36:13.221Z,1734730573.221 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T21:36:13.222Z,1734730573.222 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T21:36:15.227Z,1734730575.227 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213620.00,A,3648.16996,N,12147.28013,W,0.272,355.75,201224,,,A*78 2024-12-20T21:36:15.229Z,1734730575.229 [NAL9602](INFO): GPS fix at 20241220T213620: (36.802833, -121.788002) 2024-12-20T21:36:15.260Z,1734730575.260 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T21:36:15.260Z,1734730575.260 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T21:36:22.407Z,1734730582.407 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20241220T204226/Courier0030.lzma 2024-12-20T21:36:23.410Z,1734730583.410 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0030.lzma.bak 2024-12-20T21:36:23.410Z,1734730583.410 [DataOverHttps](INFO): SBD MOMSN=23851002 2024-12-20T21:36:39.160Z,1734730599.160 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20241220T204226/Express0031.lzma 2024-12-20T21:36:40.162Z,1734730600.162 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0031.lzma.bak 2024-12-20T21:36:40.162Z,1734730600.162 [DataOverHttps](INFO): SBD MOMSN=23851006 2024-12-20T21:36:41.919Z,1734730601.919 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T21:36:41.919Z,1734730601.919 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T21:36:41.919Z,1734730601.919 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T21:37:17.491Z,1734730637.491 [NAL9602](INFO): SBD MO Status=2, MOMSN=20611, MT Status=2, MTMSN=0 2024-12-20T21:37:17.491Z,1734730637.491 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-20T21:37:56.271Z,1734730676.271 [NAL9602](INFO): SBD MO Status=2, MOMSN=20611, MT Status=2, MTMSN=0 2024-12-20T21:37:56.271Z,1734730676.271 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-20T21:39:12.686Z,1734730752.686 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2024-12-20T21:41:17.945Z,1734730877.945 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-12-20T21:41:42.600Z,1734730902.600 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T21:41:42.600Z,1734730902.600 [Default:CheckIn:C.Wait] Stopped 2024-12-20T21:41:42.600Z,1734730902.600 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T21:41:42.600Z,1734730902.600 [Default:CheckIn:D] Running Loop=1 2024-12-20T21:41:43.008Z,1734730903.008 [Default:CheckIn:D] Stopped 2024-12-20T21:41:43.008Z,1734730903.008 [Default:CheckIn:E] Running Loop=1 2024-12-20T21:41:43.424Z,1734730903.424 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.028223 min 2024-12-20T21:41:43.424Z,1734730903.424 [Default:CheckIn:E] Stopped 2024-12-20T21:41:43.424Z,1734730903.424 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T21:41:43.424Z,1734730903.424 [Default:CheckIn] Stopped 2024-12-20T21:41:43.424Z,1734730903.424 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T21:41:43.424Z,1734730903.424 [Default:CheckIn](INFO): Running loop #15 2024-12-20T21:41:43.424Z,1734730903.424 [Default:CheckIn] Running Loop=15 2024-12-20T21:41:43.425Z,1734730903.425 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T21:41:43.425Z,1734730903.425 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T21:41:45.415Z,1734730905.415 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214150.00,A,3648.16931,N,12147.27938,W,0.758,15.47,201224,,,A*46 2024-12-20T21:41:45.417Z,1734730905.417 [NAL9602](INFO): GPS fix at 20241220T214150: (36.802822, -121.787990) 2024-12-20T21:41:45.429Z,1734730905.429 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T21:41:45.429Z,1734730905.429 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T21:41:53.132Z,1734730913.132 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241220T204226/Courier0033.lzma 2024-12-20T21:41:54.130Z,1734730914.130 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0033.lzma.bak 2024-12-20T21:41:54.130Z,1734730914.130 [DataOverHttps](INFO): SBD MOMSN=23851025 2024-12-20T21:42:09.956Z,1734730929.956 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20241220T204226/Express0034.lzma 2024-12-20T21:42:10.958Z,1734730930.958 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0034.lzma.bak 2024-12-20T21:42:10.958Z,1734730930.958 [DataOverHttps](INFO): SBD MOMSN=23851028 2024-12-20T21:42:12.497Z,1734730932.497 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T21:42:12.497Z,1734730932.497 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T21:42:12.497Z,1734730932.497 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T21:42:17.737Z,1734730937.737 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-20T21:47:13.155Z,1734731233.155 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T21:47:13.155Z,1734731233.155 [Default:CheckIn:C.Wait] Stopped 2024-12-20T21:47:13.155Z,1734731233.155 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T21:47:13.155Z,1734731233.155 [Default:CheckIn:D] Running Loop=1 2024-12-20T21:47:13.564Z,1734731233.564 [Default:CheckIn:D] Stopped 2024-12-20T21:47:13.564Z,1734731233.564 [Default:CheckIn:E] Running Loop=1 2024-12-20T21:47:13.971Z,1734731233.971 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.537484 min 2024-12-20T21:47:13.971Z,1734731233.971 [Default:CheckIn:E] Stopped 2024-12-20T21:47:13.971Z,1734731233.971 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T21:47:13.971Z,1734731233.971 [Default:CheckIn] Stopped 2024-12-20T21:47:13.971Z,1734731233.971 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T21:47:13.971Z,1734731233.971 [Default:CheckIn](INFO): Running loop #16 2024-12-20T21:47:13.971Z,1734731233.971 [Default:CheckIn] Running Loop=16 2024-12-20T21:47:13.971Z,1734731233.971 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T21:47:13.972Z,1734731233.972 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T21:47:15.975Z,1734731235.975 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214720.00,A,3648.16820,N,12147.27972,W,0.952,15.47,201224,,,A*4C 2024-12-20T21:47:15.977Z,1734731235.977 [NAL9602](INFO): GPS fix at 20241220T214720: (36.802803, -121.787995) 2024-12-20T21:47:16.007Z,1734731236.007 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T21:47:16.007Z,1734731236.007 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T21:47:22.987Z,1734731242.987 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20241220T204226/Courier0036.lzma 2024-12-20T21:47:23.990Z,1734731243.990 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0036.lzma.bak 2024-12-20T21:47:23.990Z,1734731243.990 [DataOverHttps](INFO): SBD MOMSN=23851054 2024-12-20T21:47:39.751Z,1734731259.751 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20241220T204226/Express0037.lzma 2024-12-20T21:47:40.754Z,1734731260.754 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0037.lzma.bak 2024-12-20T21:47:40.754Z,1734731260.754 [DataOverHttps](INFO): SBD MOMSN=23851061 2024-12-20T21:47:42.245Z,1734731262.245 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T21:47:42.245Z,1734731262.245 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T21:47:42.245Z,1734731262.245 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T21:47:46.673Z,1734731266.673 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-12-20T21:47:46.748Z,1734731266.748 [NAL9602](ERROR): received: +CSQ:0 OK611, 2, 0, 0, 0 OK 2024-12-20T21:48:43.247Z,1734731323.247 [NAL9602](INFO): SBD MO Status=2, MOMSN=20611, MT Status=2, MTMSN=0 2024-12-20T21:48:43.247Z,1734731323.247 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-20T21:52:10.630Z,1734731530.630 [NAL9602](INFO): SBD MO Status=2, MOMSN=20611, MT Status=2, MTMSN=0 2024-12-20T21:52:10.630Z,1734731530.630 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-20T21:52:18.709Z,1734731538.709 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-12-20T21:52:42.972Z,1734731562.972 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T21:52:42.972Z,1734731562.972 [Default:CheckIn:C.Wait] Stopped 2024-12-20T21:52:42.972Z,1734731562.972 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T21:52:42.972Z,1734731562.972 [Default:CheckIn:D] Running Loop=1 2024-12-20T21:52:43.383Z,1734731563.383 [Default:CheckIn:D] Stopped 2024-12-20T21:52:43.383Z,1734731563.383 [Default:CheckIn:E] Running Loop=1 2024-12-20T21:52:43.777Z,1734731563.777 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.034473 min 2024-12-20T21:52:43.777Z,1734731563.777 [Default:CheckIn:E] Stopped 2024-12-20T21:52:43.777Z,1734731563.777 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T21:52:43.777Z,1734731563.777 [Default:CheckIn] Stopped 2024-12-20T21:52:43.777Z,1734731563.777 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T21:52:43.777Z,1734731563.777 [Default:CheckIn](INFO): Running loop #17 2024-12-20T21:52:43.777Z,1734731563.777 [Default:CheckIn] Running Loop=17 2024-12-20T21:52:43.777Z,1734731563.777 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T21:52:43.777Z,1734731563.777 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T21:52:45.780Z,1734731565.780 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215250.00,A,3648.16669,N,12147.28256,W,0.292,15.47,201224,,,A*49 2024-12-20T21:52:45.782Z,1734731565.782 [NAL9602](INFO): GPS fix at 20241220T215250: (36.802778, -121.788043) 2024-12-20T21:52:45.802Z,1734731565.802 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T21:52:45.802Z,1734731565.802 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T21:52:54.283Z,1734731574.283 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241220T204226/Courier0039.lzma 2024-12-20T21:52:55.286Z,1734731575.286 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0039.lzma.bak 2024-12-20T21:52:55.286Z,1734731575.286 [DataOverHttps](INFO): SBD MOMSN=23851071 2024-12-20T21:53:11.128Z,1734731591.128 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20241220T204226/Express0040.lzma 2024-12-20T21:53:12.130Z,1734731592.130 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0040.lzma.bak 2024-12-20T21:53:12.130Z,1734731592.130 [DataOverHttps](INFO): SBD MOMSN=23851074 2024-12-20T21:53:13.669Z,1734731593.669 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T21:53:13.669Z,1734731593.669 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T21:53:13.669Z,1734731593.669 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T21:53:17.725Z,1734731597.725 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-20T21:55:54.488Z,1734731754.488 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T21:56:09.826Z,1734731769.826 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T21:56:25.180Z,1734731785.180 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T21:56:40.530Z,1734731800.530 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T21:56:55.076Z,1734731815.076 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T21:57:10.427Z,1734731830.427 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T21:57:24.972Z,1734731844.972 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T21:57:39.920Z,1734731859.920 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T21:57:55.675Z,1734731875.675 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T21:58:14.280Z,1734731894.280 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T21:58:14.280Z,1734731894.280 [Default:CheckIn:C.Wait] Stopped 2024-12-20T21:58:14.280Z,1734731894.280 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T21:58:14.281Z,1734731894.281 [Default:CheckIn:D] Running Loop=1 2024-12-20T21:58:14.676Z,1734731894.676 [Default:CheckIn:D] Stopped 2024-12-20T21:58:14.676Z,1734731894.676 [Default:CheckIn:E] Running Loop=1 2024-12-20T21:58:15.079Z,1734731895.079 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.556030 min 2024-12-20T21:58:15.080Z,1734731895.080 [Default:CheckIn:E] Stopped 2024-12-20T21:58:15.080Z,1734731895.080 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T21:58:15.080Z,1734731895.080 [Default:CheckIn] Stopped 2024-12-20T21:58:15.080Z,1734731895.080 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T21:58:15.080Z,1734731895.080 [Default:CheckIn](INFO): Running loop #18 2024-12-20T21:58:15.080Z,1734731895.080 [Default:CheckIn] Running Loop=18 2024-12-20T21:58:15.080Z,1734731895.080 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T21:58:15.081Z,1734731895.081 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T21:58:17.099Z,1734731897.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215822.00,A,3648.17834,N,12147.28029,W,0.894,0.00,201224,,,A*70 2024-12-20T21:58:17.101Z,1734731897.101 [NAL9602](INFO): GPS fix at 20241220T215822: (36.802972, -121.788005) 2024-12-20T21:58:17.113Z,1734731897.113 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T21:58:17.113Z,1734731897.113 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T21:58:24.511Z,1734731904.511 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241220T204226/Courier0042.lzma 2024-12-20T21:58:25.514Z,1734731905.514 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0042.lzma.bak 2024-12-20T21:58:25.514Z,1734731905.514 [DataOverHttps](INFO): SBD MOMSN=23851100 2024-12-20T21:58:41.380Z,1734731921.380 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20241220T204226/Express0043.lzma 2024-12-20T21:58:42.382Z,1734731922.382 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0043.lzma.bak 2024-12-20T21:58:42.382Z,1734731922.382 [DataOverHttps](INFO): SBD MOMSN=23851106 2024-12-20T21:58:43.851Z,1734731923.851 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T21:58:43.851Z,1734731923.851 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T21:58:43.851Z,1734731923.851 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T21:58:47.818Z,1734731927.818 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-12-20T21:58:47.892Z,1734731927.892 [NAL9602](ERROR): received: +CSQ:0 OK611, 2, 0, 0, 0 OK 2024-12-20T22:00:23.583Z,1734732023.583 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:00:30.450Z,1734732030.450 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:00:44.591Z,1734732044.591 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:00:58.374Z,1734732058.374 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:01:11.706Z,1734732071.706 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:01:25.038Z,1734732085.038 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:01:38.774Z,1734732098.774 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:01:52.511Z,1734732112.511 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:02:06.247Z,1734732126.247 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:02:19.983Z,1734732139.983 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:02:33.314Z,1734732153.314 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:03:28.295Z,1734732208.295 [NAL9602](INFO): SBD MO Status=2, MOMSN=20611, MT Status=2, MTMSN=0 2024-12-20T22:03:28.296Z,1734732208.296 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-20T22:03:28.673Z,1734732208.673 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-12-20T22:03:44.504Z,1734732224.504 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T22:03:44.504Z,1734732224.504 [Default:CheckIn:C.Wait] Stopped 2024-12-20T22:03:44.504Z,1734732224.504 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T22:03:44.505Z,1734732224.505 [Default:CheckIn:D] Running Loop=1 2024-12-20T22:03:44.856Z,1734732224.856 [Default:CheckIn:D] Stopped 2024-12-20T22:03:44.856Z,1734732224.856 [Default:CheckIn:E] Running Loop=1 2024-12-20T22:03:45.263Z,1734732225.263 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.059017 min 2024-12-20T22:03:45.264Z,1734732225.264 [Default:CheckIn:E] Stopped 2024-12-20T22:03:45.264Z,1734732225.264 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T22:03:45.264Z,1734732225.264 [Default:CheckIn] Stopped 2024-12-20T22:03:45.264Z,1734732225.264 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T22:03:45.264Z,1734732225.264 [Default:CheckIn](INFO): Running loop #19 2024-12-20T22:03:45.264Z,1734732225.264 [Default:CheckIn] Running Loop=19 2024-12-20T22:03:45.264Z,1734732225.264 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T22:03:45.265Z,1734732225.265 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T22:03:47.258Z,1734732227.258 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220352.00,A,3648.16955,N,12147.28110,W,0.039,0.00,201224,,,A*79 2024-12-20T22:03:47.261Z,1734732227.261 [NAL9602](INFO): GPS fix at 20241220T220352: (36.802826, -121.788018) 2024-12-20T22:03:47.289Z,1734732227.289 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T22:03:47.289Z,1734732227.289 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T22:03:54.931Z,1734732234.931 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20241220T204226/Courier0045.lzma 2024-12-20T22:03:55.934Z,1734732235.934 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0045.lzma.bak 2024-12-20T22:03:55.934Z,1734732235.934 [DataOverHttps](INFO): SBD MOMSN=23851127 2024-12-20T22:04:11.872Z,1734732251.872 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20241220T204226/Express0046.lzma 2024-12-20T22:04:12.874Z,1734732252.874 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0046.lzma.bak 2024-12-20T22:04:12.874Z,1734732252.874 [DataOverHttps](INFO): SBD MOMSN=23851130 2024-12-20T22:04:14.366Z,1734732254.366 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T22:04:14.366Z,1734732254.366 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T22:04:14.366Z,1734732254.366 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T22:04:20.009Z,1734732260.009 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-20T22:05:08.927Z,1734732308.927 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:05:24.683Z,1734732324.683 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:05:40.039Z,1734732340.039 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:05:55.386Z,1734732355.386 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:06:10.335Z,1734732370.335 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:06:25.687Z,1734732385.687 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:06:41.443Z,1734732401.443 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:06:57.198Z,1734732417.198 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:07:12.206Z,1734732432.206 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:09:14.979Z,1734732554.979 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T22:09:14.979Z,1734732554.979 [Default:CheckIn:C.Wait] Stopped 2024-12-20T22:09:14.979Z,1734732554.979 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T22:09:14.980Z,1734732554.980 [Default:CheckIn:D] Running Loop=1 2024-12-20T22:09:15.380Z,1734732555.380 [Default:CheckIn:D] Stopped 2024-12-20T22:09:15.380Z,1734732555.380 [Default:CheckIn:E] Running Loop=1 2024-12-20T22:09:15.792Z,1734732555.792 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.567749 min 2024-12-20T22:09:15.792Z,1734732555.792 [Default:CheckIn:E] Stopped 2024-12-20T22:09:15.792Z,1734732555.792 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T22:09:15.792Z,1734732555.792 [Default:CheckIn] Stopped 2024-12-20T22:09:15.793Z,1734732555.793 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T22:09:15.793Z,1734732555.793 [Default:CheckIn](INFO): Running loop #20 2024-12-20T22:09:15.793Z,1734732555.793 [Default:CheckIn] Running Loop=20 2024-12-20T22:09:15.793Z,1734732555.793 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T22:09:15.793Z,1734732555.793 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T22:09:17.794Z,1734732557.794 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220922.00,A,3648.16897,N,12147.28133,W,0.097,186.01,201224,,,A*70 2024-12-20T22:09:17.797Z,1734732557.797 [NAL9602](INFO): GPS fix at 20241220T220922: (36.802816, -121.788022) 2024-12-20T22:09:17.828Z,1734732557.828 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T22:09:17.828Z,1734732557.828 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T22:09:24.899Z,1734732564.899 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241220T204226/Courier0048.lzma 2024-12-20T22:09:25.902Z,1734732565.902 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0048.lzma.bak 2024-12-20T22:09:25.902Z,1734732565.902 [DataOverHttps](INFO): SBD MOMSN=23851154 2024-12-20T22:09:41.727Z,1734732581.727 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20241220T204226/Express0049.lzma 2024-12-20T22:09:42.730Z,1734732582.730 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0049.lzma.bak 2024-12-20T22:09:42.731Z,1734732582.731 [DataOverHttps](INFO): SBD MOMSN=23851159 2024-12-20T22:09:44.161Z,1734732584.161 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T22:09:44.161Z,1734732584.161 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T22:09:44.161Z,1734732584.161 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T22:09:48.183Z,1734732588.183 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2024-12-20T22:09:55.050Z,1734732595.050 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:10:08.385Z,1734732608.385 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-12-20T22:10:08.385Z,1734732608.385 [DropWeight] Hardware Fault, FailCount= 1 2024-12-20T22:10:08.385Z,1734732608.385 [DropWeight](ERROR): Hardware Fault 2024-12-20T22:10:08.421Z,1734732608.421 [CommandExec](FAULT): Scheduling is paused for 1 commands 2024-12-20T22:10:08.422Z,1734732608.422 [CBIT](INFO): Critical error at 20241220T221008 2024-12-20T22:10:08.428Z,1734732608.428 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-12-20T22:10:08.429Z,1734732608.429 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-12-20T22:10:08.855Z,1734732608.855 [CBIT](INFO): Critical error at 20241220T221008 2024-12-20T22:10:09.190Z,1734732609.190 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:10:23.330Z,1734732623.330 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:10:37.470Z,1734732637.470 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:10:51.262Z,1734732651.262 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:11:04.985Z,1734732664.985 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:11:18.720Z,1734732678.720 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:11:32.454Z,1734732692.454 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:11:45.786Z,1734732705.786 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:11:59.119Z,1734732719.119 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:13:30.426Z,1734732810.426 [NAL9602](INFO): SBD MO Status=2, MOMSN=20611, MT Status=2, MTMSN=0 2024-12-20T22:13:30.427Z,1734732810.427 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-20T22:13:57.954Z,1734732837.954 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-12-20T22:13:58.326Z,1734732838.326 [NAL9602](INFO): SBD MO Status=2, MOMSN=20611, MT Status=2, MTMSN=0 2024-12-20T22:13:58.327Z,1734732838.327 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-12-20T22:14:19.712Z,1734732859.712 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-12-20T22:14:33.042Z,1734732873.042 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:14:44.779Z,1734732884.779 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T22:14:44.779Z,1734732884.779 [Default:CheckIn:C.Wait] Stopped 2024-12-20T22:14:44.779Z,1734732884.779 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T22:14:44.780Z,1734732884.780 [Default:CheckIn:D] Running Loop=1 2024-12-20T22:14:45.177Z,1734732885.177 [Default:CheckIn:D] Stopped 2024-12-20T22:14:45.177Z,1734732885.177 [Default:CheckIn:E] Running Loop=1 2024-12-20T22:14:45.609Z,1734732885.609 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.064372 min 2024-12-20T22:14:45.609Z,1734732885.609 [Default:CheckIn:E] Stopped 2024-12-20T22:14:45.609Z,1734732885.609 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T22:14:45.609Z,1734732885.609 [Default:CheckIn] Stopped 2024-12-20T22:14:45.609Z,1734732885.609 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T22:14:45.609Z,1734732885.609 [Default:CheckIn](INFO): Running loop #21 2024-12-20T22:14:45.609Z,1734732885.609 [Default:CheckIn] Running Loop=21 2024-12-20T22:14:45.609Z,1734732885.609 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T22:14:45.609Z,1734732885.609 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T22:14:47.662Z,1734732887.662 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221452.00,A,3648.16763,N,12147.28305,W,0.097,186.01,201224,,,A*78 2024-12-20T22:14:47.665Z,1734732887.665 [NAL9602](INFO): GPS fix at 20241220T221452: (36.802794, -121.788051) 2024-12-20T22:14:47.725Z,1734732887.725 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T22:14:47.725Z,1734732887.725 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T22:14:47.990Z,1734732887.990 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:14:55.563Z,1734732895.563 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20241220T204226/Courier0051.lzma 2024-12-20T22:14:56.566Z,1734732896.566 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0051.lzma.bak 2024-12-20T22:14:56.566Z,1734732896.566 [DataOverHttps](INFO): SBD MOMSN=23851173 2024-12-20T22:15:03.351Z,1734732903.351 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:15:12.372Z,1734732912.372 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20241220T204226/Express0052.lzma 2024-12-20T22:15:13.374Z,1734732913.374 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0052.lzma.bak 2024-12-20T22:15:13.374Z,1734732913.374 [DataOverHttps](INFO): SBD MOMSN=23851181 2024-12-20T22:15:14.686Z,1734732914.686 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T22:15:14.686Z,1734732914.686 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T22:15:14.686Z,1734732914.686 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T22:15:17.921Z,1734732917.921 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:15:19.917Z,1734732919.917 [NAL9602](INFO): Not Powering down - fast GPS 2024-12-20T22:15:33.247Z,1734732933.247 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:15:48.194Z,1734732948.194 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:16:03.548Z,1734732963.548 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:16:19.316Z,1734732979.316 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:16:34.654Z,1734732994.654 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:16:49.208Z,1734733009.208 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2024-12-20T22:19:12.222Z,1734733152.222 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:19:26.363Z,1734733166.363 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:19:40.098Z,1734733180.098 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:19:54.245Z,1734733194.245 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:20:08.379Z,1734733208.379 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:20:15.259Z,1734733215.259 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-12-20T22:20:15.259Z,1734733215.259 [Default:CheckIn:C.Wait] Stopped 2024-12-20T22:20:15.259Z,1734733215.259 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T22:20:15.260Z,1734733215.260 [Default:CheckIn:D] Running Loop=1 2024-12-20T22:20:15.675Z,1734733215.675 [Default:CheckIn:D] Stopped 2024-12-20T22:20:15.675Z,1734733215.675 [Default:CheckIn:E] Running Loop=1 2024-12-20T22:20:16.068Z,1734733216.068 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.572664 min 2024-12-20T22:20:16.069Z,1734733216.069 [Default:CheckIn:E] Stopped 2024-12-20T22:20:16.069Z,1734733216.069 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-12-20T22:20:16.069Z,1734733216.069 [Default:CheckIn] Stopped 2024-12-20T22:20:16.069Z,1734733216.069 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T22:20:16.069Z,1734733216.069 [Default:CheckIn](INFO): Running loop #22 2024-12-20T22:20:16.069Z,1734733216.069 [Default:CheckIn] Running Loop=22 2024-12-20T22:20:16.069Z,1734733216.069 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-20T22:20:16.069Z,1734733216.069 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-20T22:20:18.086Z,1734733218.086 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222023.00,A,3648.16769,N,12147.28062,W,0.097,186.01,201224,,,A*71 2024-12-20T22:20:18.089Z,1734733218.089 [NAL9602](INFO): GPS fix at 20241220T222023: (36.802795, -121.788010) 2024-12-20T22:20:18.100Z,1734733218.100 [Default:CheckIn:Read_GPS] Stopped 2024-12-20T22:20:18.101Z,1734733218.101 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-20T22:20:22.518Z,1734733222.518 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:20:25.683Z,1734733225.683 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241220T204226/Courier0054.lzma 2024-12-20T22:20:26.686Z,1734733226.686 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Courier0054.lzma.bak 2024-12-20T22:20:26.686Z,1734733226.686 [DataOverHttps](INFO): SBD MOMSN=23851196 2024-12-20T22:20:36.659Z,1734733236.659 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:20:43.088Z,1734733243.088 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20241220T204226/Express0055.lzma 2024-12-20T22:20:44.090Z,1734733244.090 [DataOverHttps](INFO): Moved sent file to Logs/20241220T204226/Express0055.lzma.bak 2024-12-20T22:20:44.090Z,1734733244.090 [DataOverHttps](INFO): SBD MOMSN=23851202 2024-12-20T22:20:45.602Z,1734733245.602 [Default:CheckIn:Read_Iridium] Stopped 2024-12-20T22:20:45.602Z,1734733245.602 [Default:CheckIn:C.Wait] Running Loop=1 2024-12-20T22:20:45.602Z,1734733245.602 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-12-20T22:20:48.821Z,1734733248.821 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-12-20T22:20:48.896Z,1734733248.896 [NAL9602](ERROR): received: +CSQ:0 OK611, 2, 0, 0, 0 OK 2024-12-20T22:20:50.026Z,1734733250.026 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:21:03.774Z,1734733263.774 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:21:17.510Z,1734733277.510 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:21:31.650Z,1734733291.650 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-12-20T22:21:36.115Z,1734733296.115 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2024-12-20T22:21:36.120Z,1734733296.120 [BPC1](INFO): Calculating totals. Valid battery stick count: 45. Valid reserve battery stick count: 5. 2024-12-20T22:21:36.123Z,1734733296.123 [BPC1](INFO): Received data from all battery sticks. 2024-12-20T22:24:32.473Z,1734733472.473 [CommandExec](IMPORTANT): got command quit 2024-12-20T22:24:33.476Z,1734733473.476 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-20T22:24:33.476Z,1734733473.476 [CommandExec](INFO): Uninitializing the command executive. 2024-12-20T22:24:33.476Z,1734733473.476 [CommandExec](INFO): Uninitializing the command scheduler. 2024-12-20T22:24:33.477Z,1734733473.477 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:33.533Z,1734733473.533 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-12-20T22:24:33.534Z,1734733473.534 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-12-20T22:24:33.534Z,1734733473.534 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:33.534Z,1734733473.534 [NavChartDb](INFO): Join timeout helper Thread ID is 307 2024-12-20T22:24:33.612Z,1734733473.612 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-20T22:24:33.612Z,1734733473.612 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:33.620Z,1734733473.620 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-12-20T22:24:33.620Z,1734733473.620 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:33.620Z,1734733473.620 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 308 2024-12-20T22:24:33.664Z,1734733473.664 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-20T22:24:33.665Z,1734733473.665 [WetLabsBB2FL](INFO): Powering down 2024-12-20T22:24:33.665Z,1734733473.665 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:33.680Z,1734733473.680 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-12-20T22:24:33.680Z,1734733473.680 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:33.680Z,1734733473.680 [CTD_Seabird](INFO): Join timeout helper Thread ID is 309 2024-12-20T22:24:33.824Z,1734733473.824 [CTD_Seabird](INFO): Powering down 2024-12-20T22:24:33.836Z,1734733473.836 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-20T22:24:33.836Z,1734733473.836 [CTD_Seabird](INFO): Powering down 2024-12-20T22:24:33.848Z,1734733473.848 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:33.868Z,1734733473.868 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-12-20T22:24:33.869Z,1734733473.869 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:33.869Z,1734733473.869 [Radio_Surface](INFO): Join timeout helper Thread ID is 310 2024-12-20T22:24:34.040Z,1734733474.040 [Radio_Surface](INFO): Powering down 2024-12-20T22:24:34.041Z,1734733474.041 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-20T22:24:34.041Z,1734733474.041 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:34.052Z,1734733474.052 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-12-20T22:24:34.052Z,1734733474.052 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:34.052Z,1734733474.052 [Onboard](INFO): Join timeout helper Thread ID is 311 2024-12-20T22:24:34.280Z,1734733474.280 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-20T22:24:34.280Z,1734733474.280 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:34.298Z,1734733474.298 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-12-20T22:24:34.298Z,1734733474.298 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:34.299Z,1734733474.299 [DataOverHttps](INFO): Join timeout helper Thread ID is 312 2024-12-20T22:24:34.701Z,1734733474.701 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-20T22:24:34.701Z,1734733474.701 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:34.705Z,1734733474.705 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-12-20T22:24:34.705Z,1734733474.705 [DAT ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:34.705Z,1734733474.705 [DAT](INFO): Join timeout helper Thread ID is 313 2024-12-20T22:24:34.836Z,1734733474.836 [DAT](INFO): Powering down 2024-12-20T22:24:34.909Z,1734733474.909 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-20T22:24:34.909Z,1734733474.909 [DAT](INFO): Powering down 2024-12-20T22:24:34.910Z,1734733474.910 [DAT ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:34.929Z,1734733474.929 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-12-20T22:24:34.929Z,1734733474.929 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:34.929Z,1734733474.929 [BackseatComponent](INFO): Join timeout helper Thread ID is 314 2024-12-20T22:24:35.073Z,1734733475.073 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-20T22:24:35.073Z,1734733475.073 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.093Z,1734733475.093 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-12-20T22:24:35.093Z,1734733475.093 [logger ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.093Z,1734733475.093 [logger](INFO): Join timeout helper Thread ID is 315 2024-12-20T22:24:35.124Z,1734733475.124 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-20T22:24:35.124Z,1734733475.124 [logger ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.144Z,1734733475.144 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-12-20T22:24:35.145Z,1734733475.145 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.145Z,1734733475.145 [CommandLine](INFO): Join timeout helper Thread ID is 316 2024-12-20T22:24:35.196Z,1734733475.196 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-20T22:24:35.196Z,1734733475.196 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.205Z,1734733475.205 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-12-20T22:24:35.205Z,1734733475.205 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.205Z,1734733475.205 [CommandExec](INFO): Join timeout helper Thread ID is 317 2024-12-20T22:24:35.206Z,1734733475.206 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-12-20T22:24:35.206Z,1734733475.206 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.207Z,1734733475.207 [controlThread](INFO): Join timeout helper Thread ID is 318 2024-12-20T22:24:35.468Z,1734733475.468 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-12-20T22:24:35.468Z,1734733475.468 [controlThread](DEBUG): Uninitializing ControlThread 2024-12-20T22:24:35.469Z,1734733475.469 [AHRS_M2](INFO): Powering down 2024-12-20T22:24:35.541Z,1734733475.541 [NAL9602](INFO): Powering down 2024-12-20T22:24:35.542Z,1734733475.542 [Sonardyne_Nano](INFO): Powering down 2024-12-20T22:24:35.660Z,1734733475.660 [Waterlinked](INFO): Powering down 2024-12-20T22:24:35.662Z,1734733475.662 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-12-20T22:24:35.662Z,1734733475.662 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-12-20T22:24:35.663Z,1734733475.663 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-12-20T22:24:35.663Z,1734733475.663 [MissionManager](INFO): Uninitializing Mission Default 2024-12-20T22:24:35.664Z,1734733475.664 [Default] Stopped 2024-12-20T22:24:35.664Z,1734733475.664 [Default](DEBUG): Aggregate::uninitialize Default 2024-12-20T22:24:35.664Z,1734733475.664 [Default:B.GoToSurface] Stopped 2024-12-20T22:24:35.664Z,1734733475.664 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-12-20T22:24:35.664Z,1734733475.664 [Default:CheckIn] Stopped 2024-12-20T22:24:35.664Z,1734733475.664 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-12-20T22:24:35.664Z,1734733475.664 [Default:CheckIn:C.Wait] Stopped 2024-12-20T22:24:35.664Z,1734733475.664 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-12-20T22:24:35.667Z,1734733475.667 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-12-20T22:24:35.667Z,1734733475.667 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-12-20T22:24:35.668Z,1734733475.668 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-12-20T22:24:35.668Z,1734733475.668 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-12-20T22:24:35.668Z,1734733475.668 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-12-20T22:24:35.668Z,1734733475.668 [BuoyancyServo](INFO): Powering down 2024-12-20T22:24:35.680Z,1734733475.680 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-12-20T22:24:35.680Z,1734733475.680 [ElevatorServo](INFO): Powering down 2024-12-20T22:24:35.681Z,1734733475.681 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-12-20T22:24:35.681Z,1734733475.681 [MassServo](INFO): Powering down 2024-12-20T22:24:35.682Z,1734733475.682 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-12-20T22:24:35.682Z,1734733475.682 [RudderServo](INFO): Powering down 2024-12-20T22:24:35.683Z,1734733475.683 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-12-20T22:24:35.683Z,1734733475.683 [ThrusterHE](INFO): Powering down 2024-12-20T22:24:35.683Z,1734733475.683 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-12-20T22:24:35.684Z,1734733475.684 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-12-20T22:24:35.684Z,1734733475.684 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-12-20T22:24:35.684Z,1734733475.684 [CBIT](DEBUG): Powering off loads. 2024-12-20T22:24:35.696Z,1734733475.696 [CBIT](DEBUG): Disabling WDT. 2024-12-20T22:24:35.708Z,1734733475.708 [CBIT](DEBUG): Opening all GF detection circuits. 2024-12-20T22:24:35.709Z,1734733475.709 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.759Z,1734733475.759 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.762Z,1734733475.762 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.768Z,1734733475.768 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.774Z,1734733475.774 [DAT ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.814Z,1734733475.814 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.901Z,1734733475.901 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.907Z,1734733475.907 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:35.915Z,1734733475.915 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-12-20T22:24:36.003Z,1734733476.003 [logger ThreadHandler](INFO): Thread cancelled.