2022-10-20T19:53:34.326Z,1666295614.326 [CommandExec](IMPORTANT): got command restart logs 2022-10-20T19:56:32.222Z,1666295792.222 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-10-20T19:56:32.223Z,1666295792.223 [Default:CheckIn:C.Wait] Stopped 2022-10-20T19:56:32.223Z,1666295792.223 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T19:56:32.223Z,1666295792.223 [Default:CheckIn:D] Running Loop=1 2022-10-20T19:56:32.584Z,1666295792.584 [Default:CheckIn:D] Stopped 2022-10-20T19:56:32.585Z,1666295792.585 [Default:CheckIn:E] Running Loop=1 2022-10-20T19:56:32.988Z,1666295792.988 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.590672 min 2022-10-20T19:56:32.988Z,1666295792.988 [Default:CheckIn:E] Stopped 2022-10-20T19:56:32.988Z,1666295792.988 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-10-20T19:56:32.989Z,1666295792.989 [Default:CheckIn] Stopped 2022-10-20T19:56:32.989Z,1666295792.989 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T19:56:32.989Z,1666295792.989 [Default:CheckIn](INFO): Running loop #4 2022-10-20T19:56:32.989Z,1666295792.989 [Default:CheckIn] Running Loop=4 2022-10-20T19:56:32.989Z,1666295792.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-10-20T19:56:32.989Z,1666295792.989 [Default:CheckIn:Read_GPS] Running Loop=1 2022-10-20T19:56:35.004Z,1666295795.004 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195634.00,A,3642.56473,N,12155.08359,W,19.808,45.92,201022,,,D*71 2022-10-20T19:56:35.015Z,1666295795.015 [NAL9602](INFO): GPS fix at 20221020T195634: (36.709412, -121.918060) 2022-10-20T19:56:35.031Z,1666295795.031 [Default:CheckIn:Read_GPS] Stopped 2022-10-20T19:56:35.031Z,1666295795.031 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-10-20T19:56:43.200Z,1666295803.200 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20221020T014116/Courier0069.lzma 2022-10-20T19:56:44.202Z,1666295804.202 [DataOverHttps](INFO): Moved sent file to Logs/20221020T014116/Courier0069.lzma.bak 2022-10-20T19:56:44.203Z,1666295804.203 [DataOverHttps](INFO): SBD MOMSN=17258734 2022-10-20T19:56:51.976Z,1666295811.976 [NAL9602](INFO): SBD MO Status=0, MOMSN=21933, MT Status=0, MTMSN=0 2022-10-20T19:56:51.976Z,1666295811.976 [NAL9602](INFO): No messages in MT queue 2022-10-20T19:57:06.504Z,1666295826.504 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20221020T195334/Courier0000.lzma 2022-10-20T19:57:07.506Z,1666295827.506 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Courier0000.lzma.bak 2022-10-20T19:57:07.506Z,1666295827.506 [DataOverHttps](INFO): SBD MOMSN=17258736 2022-10-20T19:57:22.673Z,1666295842.673 [NAL9602](INFO): Not Powering down - fast GPS 2022-10-20T19:57:23.912Z,1666295843.912 [DataOverHttps](INFO): Sending 103 bytes from file Logs/20221020T014116/Express0070.lzma 2022-10-20T19:57:24.916Z,1666295844.916 [DataOverHttps](INFO): Moved sent file to Logs/20221020T014116/Express0070.lzma.bak 2022-10-20T19:57:24.916Z,1666295844.916 [DataOverHttps](INFO): SBD MOMSN=17258740 2022-10-20T19:57:42.220Z,1666295862.220 [DataOverHttps](INFO): Sending 398 bytes from file Logs/20221020T195334/Express0001.lzma 2022-10-20T19:57:43.222Z,1666295863.222 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Express0001.lzma.bak 2022-10-20T19:57:43.222Z,1666295863.222 [DataOverHttps](INFO): SBD MOMSN=17258743 2022-10-20T19:58:00.456Z,1666295880.456 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20221020T195334/Express0004.lzma 2022-10-20T19:58:01.458Z,1666295881.458 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Express0004.lzma.bak 2022-10-20T19:58:01.458Z,1666295881.458 [DataOverHttps](INFO): SBD MOMSN=17258747 2022-10-20T19:58:02.682Z,1666295882.682 [Default:CheckIn:Read_Iridium] Stopped 2022-10-20T19:58:02.682Z,1666295882.682 [Default:CheckIn:C.Wait] Running Loop=1 2022-10-20T19:58:02.682Z,1666295882.682 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-10-20T20:03:03.250Z,1666296183.250 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-10-20T20:03:03.250Z,1666296183.250 [Default:CheckIn:C.Wait] Stopped 2022-10-20T20:03:03.250Z,1666296183.250 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T20:03:03.251Z,1666296183.251 [Default:CheckIn:D] Running Loop=1 2022-10-20T20:03:03.660Z,1666296183.660 [Default:CheckIn:D] Stopped 2022-10-20T20:03:03.660Z,1666296183.660 [Default:CheckIn:E] Running Loop=1 2022-10-20T20:03:04.087Z,1666296184.087 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.108594 min 2022-10-20T20:03:04.087Z,1666296184.087 [Default:CheckIn:E] Stopped 2022-10-20T20:03:04.087Z,1666296184.087 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-10-20T20:03:04.087Z,1666296184.087 [Default:CheckIn] Stopped 2022-10-20T20:03:04.087Z,1666296184.087 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T20:03:04.087Z,1666296184.087 [Default:CheckIn](INFO): Running loop #5 2022-10-20T20:03:04.087Z,1666296184.087 [Default:CheckIn] Running Loop=5 2022-10-20T20:03:04.087Z,1666296184.087 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-10-20T20:03:04.088Z,1666296184.088 [Default:CheckIn:Read_GPS] Running Loop=1 2022-10-20T20:03:06.072Z,1666296186.072 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200305.00,A,3644.28778,N,12152.62944,W,26.300,50.47,201022,,,D*70 2022-10-20T20:03:06.075Z,1666296186.075 [NAL9602](INFO): GPS fix at 20221020T200305: (36.738130, -121.877157) 2022-10-20T20:03:06.123Z,1666296186.123 [Default:CheckIn:Read_GPS] Stopped 2022-10-20T20:03:06.123Z,1666296186.123 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-10-20T20:03:13.880Z,1666296193.880 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20221020T195334/Courier0006.lzma 2022-10-20T20:03:14.882Z,1666296194.882 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Courier0006.lzma.bak 2022-10-20T20:03:14.882Z,1666296194.882 [DataOverHttps](INFO): SBD MOMSN=17258813 2022-10-20T20:03:31.096Z,1666296211.096 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20221020T195334/Express0007.lzma 2022-10-20T20:03:32.098Z,1666296212.098 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Express0007.lzma.bak 2022-10-20T20:03:32.098Z,1666296212.098 [DataOverHttps](INFO): SBD MOMSN=17258816 2022-10-20T20:03:33.554Z,1666296213.554 [Default:CheckIn:Read_Iridium] Stopped 2022-10-20T20:03:33.555Z,1666296213.555 [Default:CheckIn:C.Wait] Running Loop=1 2022-10-20T20:03:33.555Z,1666296213.555 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-10-20T20:03:48.513Z,1666296228.513 [NAL9602](INFO): SBD MO Status=0, MOMSN=21934, MT Status=0, MTMSN=0 2022-10-20T20:03:48.513Z,1666296228.513 [NAL9602](INFO): No messages in MT queue 2022-10-20T20:04:19.197Z,1666296259.197 [NAL9602](INFO): Not Powering down - fast GPS 2022-10-20T20:07:01.949Z,1666296421.949 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:07:03.957Z,1666296423.957 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239716 2022-10-20T20:07:50.384Z,1666296470.384 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002718 2022-10-20T20:08:34.158Z,1666296514.158 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-10-20T20:08:34.158Z,1666296514.158 [Default:CheckIn:C.Wait] Stopped 2022-10-20T20:08:34.158Z,1666296514.158 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T20:08:34.158Z,1666296514.158 [Default:CheckIn:D] Running Loop=1 2022-10-20T20:08:34.552Z,1666296514.552 [Default:CheckIn:D] Stopped 2022-10-20T20:08:34.552Z,1666296514.552 [Default:CheckIn:E] Running Loop=1 2022-10-20T20:08:34.937Z,1666296514.937 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.623197 min 2022-10-20T20:08:34.937Z,1666296514.937 [Default:CheckIn:E] Stopped 2022-10-20T20:08:34.937Z,1666296514.937 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-10-20T20:08:34.937Z,1666296514.937 [Default:CheckIn] Stopped 2022-10-20T20:08:34.937Z,1666296514.937 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T20:08:34.937Z,1666296514.937 [Default:CheckIn](INFO): Running loop #6 2022-10-20T20:08:34.937Z,1666296514.937 [Default:CheckIn] Running Loop=6 2022-10-20T20:08:34.938Z,1666296514.938 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-10-20T20:08:34.938Z,1666296514.938 [Default:CheckIn:Read_GPS] Running Loop=1 2022-10-20T20:08:36.949Z,1666296516.949 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200836.00,A,3645.84497,N,12150.55515,W,24.220,51.16,201022,,,D*74 2022-10-20T20:08:36.951Z,1666296516.951 [NAL9602](INFO): GPS fix at 20221020T200836: (36.764083, -121.842586) 2022-10-20T20:08:36.962Z,1666296516.962 [Default:CheckIn:Read_GPS] Stopped 2022-10-20T20:08:36.962Z,1666296516.962 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-10-20T20:08:48.613Z,1666296528.613 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:08:50.621Z,1666296530.621 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239494 2022-10-20T20:09:07.251Z,1666296547.251 [NAL9602](INFO): SBD MO Status=2, MOMSN=21935, MT Status=2, MTMSN=0 2022-10-20T20:09:07.251Z,1666296547.251 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T20:09:17.351Z,1666296557.351 [NAL9602](INFO): SBD MO Status=0, MOMSN=21935, MT Status=0, MTMSN=0 2022-10-20T20:09:17.351Z,1666296557.351 [NAL9602](INFO): No messages in MT queue 2022-10-20T20:09:21.745Z,1666296561.745 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:09:43.279Z,1666296583.279 [NAL9602](INFO): SBD MO Status=1, MOMSN=21936, MT Status=0, MTMSN=0 2022-10-20T20:09:43.340Z,1666296583.340 [NAL9602](INFO): Sent 73 bytes from file Logs/20221020T195334/Courier0009.lzma 2022-10-20T20:09:43.341Z,1666296583.341 [NAL9602](INFO): Packets left to send: 0 2022-10-20T20:09:53.506Z,1666296593.506 [NAL9602](INFO): SBD MO Status=1, MOMSN=21937, MT Status=0, MTMSN=0 2022-10-20T20:09:53.561Z,1666296593.561 [NAL9602](INFO): Sent 144 bytes from file Logs/20221020T195334/Express0010.lzma 2022-10-20T20:09:53.561Z,1666296593.561 [NAL9602](INFO): Packets left to send: 0 2022-10-20T20:09:56.886Z,1666296596.886 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:09:58.311Z,1666296598.311 [NAL9602](INFO): SBD MO Status=0, MOMSN=21938, MT Status=0, MTMSN=0 2022-10-20T20:09:58.405Z,1666296598.405 [Default:CheckIn:Read_Iridium] Stopped 2022-10-20T20:09:58.406Z,1666296598.406 [Default:CheckIn:C.Wait] Running Loop=1 2022-10-20T20:09:58.406Z,1666296598.406 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-10-20T20:10:29.014Z,1666296629.014 [NAL9602](INFO): Not Powering down - fast GPS 2022-10-20T20:10:32.024Z,1666296632.024 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:11:07.165Z,1666296667.165 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:11:42.317Z,1666296702.317 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:12:17.476Z,1666296737.476 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:12:52.629Z,1666296772.629 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:13:27.769Z,1666296807.769 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:14:02.913Z,1666296842.913 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:14:38.068Z,1666296878.068 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:14:58.902Z,1666296898.902 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-10-20T20:14:58.902Z,1666296898.902 [Default:CheckIn:C.Wait] Stopped 2022-10-20T20:14:58.903Z,1666296898.903 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T20:14:58.903Z,1666296898.903 [Default:CheckIn:D] Running Loop=1 2022-10-20T20:14:59.327Z,1666296899.327 [Default:CheckIn:D] Stopped 2022-10-20T20:14:59.327Z,1666296899.327 [Default:CheckIn:E] Running Loop=1 2022-10-20T20:14:59.713Z,1666296899.713 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.036389 min 2022-10-20T20:14:59.713Z,1666296899.713 [Default:CheckIn:E] Stopped 2022-10-20T20:14:59.713Z,1666296899.713 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-10-20T20:14:59.713Z,1666296899.713 [Default:CheckIn] Stopped 2022-10-20T20:14:59.713Z,1666296899.713 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T20:14:59.713Z,1666296899.713 [Default:CheckIn](INFO): Running loop #7 2022-10-20T20:14:59.713Z,1666296899.713 [Default:CheckIn] Running Loop=7 2022-10-20T20:14:59.713Z,1666296899.713 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-10-20T20:14:59.713Z,1666296899.713 [Default:CheckIn:Read_GPS] Running Loop=1 2022-10-20T20:15:01.727Z,1666296901.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201500.00,A,3647.61381,N,12148.28589,W,23.812,49.39,201022,,,D*7A 2022-10-20T20:15:01.730Z,1666296901.730 [NAL9602](INFO): GPS fix at 20221020T201500: (36.793563, -121.804765) 2022-10-20T20:15:01.741Z,1666296901.741 [Default:CheckIn:Read_GPS] Stopped 2022-10-20T20:15:01.741Z,1666296901.741 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-10-20T20:15:13.221Z,1666296913.221 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:15:31.727Z,1666296931.727 [NAL9602](INFO): SBD MO Status=1, MOMSN=21939, MT Status=0, MTMSN=0 2022-10-20T20:15:31.789Z,1666296931.789 [NAL9602](INFO): Sent 72 bytes from file Logs/20221020T195334/Courier0012.lzma 2022-10-20T20:15:31.790Z,1666296931.790 [NAL9602](INFO): Packets left to send: 0 2022-10-20T20:15:39.491Z,1666296939.491 [NAL9602](INFO): SBD MO Status=1, MOMSN=21940, MT Status=0, MTMSN=0 2022-10-20T20:15:39.548Z,1666296939.548 [NAL9602](INFO): Sent 144 bytes from file Logs/20221020T195334/Express0013.lzma 2022-10-20T20:15:39.549Z,1666296939.549 [NAL9602](INFO): Packets left to send: 0 2022-10-20T20:15:48.361Z,1666296948.361 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:15:49.187Z,1666296949.187 [NAL9602](INFO): SBD MO Status=0, MOMSN=21941, MT Status=0, MTMSN=0 2022-10-20T20:15:49.277Z,1666296949.277 [Default:CheckIn:Read_Iridium] Stopped 2022-10-20T20:15:49.277Z,1666296949.277 [Default:CheckIn:C.Wait] Running Loop=1 2022-10-20T20:15:49.278Z,1666296949.277 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-10-20T20:16:19.893Z,1666296979.893 [NAL9602](INFO): Not Powering down - fast GPS 2022-10-20T20:16:23.501Z,1666296983.501 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:16:58.661Z,1666297018.661 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:17:33.806Z,1666297053.806 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:18:08.952Z,1666297088.952 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:18:44.093Z,1666297124.093 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:19:19.240Z,1666297159.240 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:19:54.380Z,1666297194.380 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:20:29.525Z,1666297229.525 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:20:49.775Z,1666297249.775 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-10-20T20:20:49.776Z,1666297249.776 [Default:CheckIn:C.Wait] Stopped 2022-10-20T20:20:49.776Z,1666297249.776 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T20:20:49.776Z,1666297249.776 [Default:CheckIn:D] Running Loop=1 2022-10-20T20:20:50.209Z,1666297250.209 [Default:CheckIn:D] Stopped 2022-10-20T20:20:50.209Z,1666297250.209 [Default:CheckIn:E] Running Loop=1 2022-10-20T20:20:50.605Z,1666297250.605 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.884420 min 2022-10-20T20:20:50.605Z,1666297250.605 [Default:CheckIn:E] Stopped 2022-10-20T20:20:50.605Z,1666297250.605 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-10-20T20:20:50.605Z,1666297250.605 [Default:CheckIn] Stopped 2022-10-20T20:20:50.605Z,1666297250.605 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T20:20:50.605Z,1666297250.605 [Default:CheckIn](INFO): Running loop #8 2022-10-20T20:20:50.605Z,1666297250.605 [Default:CheckIn] Running Loop=8 2022-10-20T20:20:50.605Z,1666297250.605 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-10-20T20:20:50.606Z,1666297250.606 [Default:CheckIn:Read_GPS] Running Loop=1 2022-10-20T20:20:52.597Z,1666297252.597 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202051.00,A,3648.37779,N,12147.11804,W,4.102,182.15,201022,,,D*7F 2022-10-20T20:20:52.600Z,1666297252.600 [NAL9602](INFO): GPS fix at 20221020T202051: (36.806297, -121.785301) 2022-10-20T20:20:52.611Z,1666297252.611 [Default:CheckIn:Read_GPS] Stopped 2022-10-20T20:20:52.611Z,1666297252.611 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-10-20T20:21:04.664Z,1666297264.664 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:21:16.511Z,1666297276.511 [NAL9602](INFO): SBD MO Status=1, MOMSN=21942, MT Status=0, MTMSN=0 2022-10-20T20:21:16.568Z,1666297276.568 [NAL9602](INFO): Sent 74 bytes from file Logs/20221020T195334/Courier0015.lzma 2022-10-20T20:21:16.569Z,1666297276.569 [NAL9602](INFO): Packets left to send: 0 2022-10-20T20:21:26.299Z,1666297286.299 [NAL9602](INFO): SBD MO Status=1, MOMSN=21943, MT Status=0, MTMSN=0 2022-10-20T20:21:26.356Z,1666297286.356 [NAL9602](INFO): Sent 144 bytes from file Logs/20221020T195334/Express0016.lzma 2022-10-20T20:21:26.357Z,1666297286.357 [NAL9602](INFO): Packets left to send: 0 2022-10-20T20:21:36.803Z,1666297296.803 [NAL9602](INFO): SBD MO Status=0, MOMSN=21944, MT Status=0, MTMSN=0 2022-10-20T20:21:36.882Z,1666297296.882 [Default:CheckIn:Read_Iridium] Stopped 2022-10-20T20:21:36.882Z,1666297296.882 [Default:CheckIn:C.Wait] Running Loop=1 2022-10-20T20:21:36.882Z,1666297296.882 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-10-20T20:21:39.833Z,1666297299.833 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:22:07.502Z,1666297327.502 [NAL9602](INFO): Not Powering down - fast GPS 2022-10-20T20:22:14.973Z,1666297334.973 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:22:50.113Z,1666297370.113 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:23:25.253Z,1666297405.253 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:23:47.713Z,1666297427.713 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2022-10-20T20:23:47.716Z,1666297427.716 [BPC1](INFO): Received data from all battery sticks. 2022-10-20T20:24:00.401Z,1666297440.401 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:24:35.541Z,1666297475.541 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:25:10.689Z,1666297510.689 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:25:45.837Z,1666297545.837 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:26:20.985Z,1666297580.985 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:26:37.395Z,1666297597.395 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-10-20T20:26:37.396Z,1666297597.396 [Default:CheckIn:C.Wait] Stopped 2022-10-20T20:26:37.396Z,1666297597.396 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T20:26:37.396Z,1666297597.396 [Default:CheckIn:D] Running Loop=1 2022-10-20T20:26:37.793Z,1666297597.793 [Default:CheckIn:D] Stopped 2022-10-20T20:26:37.793Z,1666297597.793 [Default:CheckIn:E] Running Loop=1 2022-10-20T20:26:38.216Z,1666297598.216 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.677486 min 2022-10-20T20:26:38.236Z,1666297598.236 [Default:CheckIn:E] Stopped 2022-10-20T20:26:38.236Z,1666297598.236 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-10-20T20:26:38.236Z,1666297598.236 [Default:CheckIn] Stopped 2022-10-20T20:26:38.236Z,1666297598.236 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T20:26:38.237Z,1666297598.237 [Default:CheckIn](INFO): Running loop #9 2022-10-20T20:26:38.237Z,1666297598.237 [Default:CheckIn] Running Loop=9 2022-10-20T20:26:38.237Z,1666297598.237 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-10-20T20:26:38.237Z,1666297598.237 [Default:CheckIn:Read_GPS] Running Loop=1 2022-10-20T20:26:40.207Z,1666297600.207 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202639.00,A,3648.16466,N,12147.21575,W,0.058,230.33,201022,,,D*75 2022-10-20T20:26:40.210Z,1666297600.210 [NAL9602](INFO): GPS fix at 20221020T202639: (36.802744, -121.786929) 2022-10-20T20:26:40.242Z,1666297600.242 [Default:CheckIn:Read_GPS] Stopped 2022-10-20T20:26:40.242Z,1666297600.242 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-10-20T20:26:56.125Z,1666297616.125 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T20:27:02.915Z,1666297622.915 [NAL9602](INFO): SBD MO Status=2, MOMSN=21945, MT Status=2, MTMSN=0 2022-10-20T20:27:02.915Z,1666297622.915 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T20:27:08.748Z,1666297628.748 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002724 2022-10-20T20:27:16.644Z,1666297636.644 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20221020T195334/Courier0018.lzma 2022-10-20T20:27:17.646Z,1666297637.646 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Courier0018.lzma.bak 2022-10-20T20:27:17.646Z,1666297637.646 [DataOverHttps](INFO): SBD MOMSN=17258982 2022-10-20T20:27:34.096Z,1666297654.096 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20221020T195334/Express0019.lzma 2022-10-20T20:27:35.098Z,1666297655.098 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Express0019.lzma.bak 2022-10-20T20:27:35.098Z,1666297655.098 [DataOverHttps](INFO): SBD MOMSN=17258985 2022-10-20T20:27:36.464Z,1666297656.464 [Default:CheckIn:Read_Iridium] Stopped 2022-10-20T20:27:36.465Z,1666297656.465 [Default:CheckIn:C.Wait] Running Loop=1 2022-10-20T20:27:36.465Z,1666297656.465 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-10-20T20:27:47.753Z,1666297667.753 [NAL9602](INFO): Not Powering down - fast GPS 2022-10-20T20:32:37.048Z,1666297957.048 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-10-20T20:32:37.064Z,1666297957.064 [Default:CheckIn:C.Wait] Stopped 2022-10-20T20:32:37.064Z,1666297957.064 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T20:32:37.064Z,1666297957.064 [Default:CheckIn:D] Running Loop=1 2022-10-20T20:32:37.452Z,1666297957.452 [Default:CheckIn:D] Stopped 2022-10-20T20:32:37.452Z,1666297957.452 [Default:CheckIn:E] Running Loop=1 2022-10-20T20:32:37.907Z,1666297957.907 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.671794 min 2022-10-20T20:32:37.907Z,1666297957.907 [Default:CheckIn:E] Stopped 2022-10-20T20:32:37.907Z,1666297957.907 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-10-20T20:32:37.907Z,1666297957.907 [Default:CheckIn] Stopped 2022-10-20T20:32:37.907Z,1666297957.907 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T20:32:37.908Z,1666297957.908 [Default:CheckIn](INFO): Running loop #10 2022-10-20T20:32:37.908Z,1666297957.908 [Default:CheckIn] Running Loop=10 2022-10-20T20:32:37.908Z,1666297957.908 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-10-20T20:32:37.908Z,1666297957.908 [Default:CheckIn:Read_GPS] Running Loop=1 2022-10-20T20:32:39.879Z,1666297959.879 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203239.00,A,3648.14416,N,12147.23052,W,0.058,257.35,201022,,,A*75 2022-10-20T20:32:39.881Z,1666297959.881 [NAL9602](INFO): GPS fix at 20221020T203239: (36.802403, -121.787175) 2022-10-20T20:32:39.914Z,1666297959.914 [Default:CheckIn:Read_GPS] Stopped 2022-10-20T20:32:39.914Z,1666297959.914 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-10-20T20:32:46.976Z,1666297966.976 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20221020T195334/Courier0021.lzma 2022-10-20T20:32:47.978Z,1666297967.978 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Courier0021.lzma.bak 2022-10-20T20:32:47.978Z,1666297967.978 [DataOverHttps](INFO): SBD MOMSN=17258988 2022-10-20T20:33:04.904Z,1666297984.904 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20221020T195334/Express0022.lzma 2022-10-20T20:33:05.906Z,1666297985.906 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Express0022.lzma.bak 2022-10-20T20:33:05.906Z,1666297985.906 [DataOverHttps](INFO): SBD MOMSN=17258991 2022-10-20T20:33:07.353Z,1666297987.353 [Default:CheckIn:Read_Iridium] Stopped 2022-10-20T20:33:07.353Z,1666297987.353 [Default:CheckIn:C.Wait] Running Loop=1 2022-10-20T20:33:07.353Z,1666297987.353 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-10-20T20:34:19.655Z,1666298059.655 [NAL9602](INFO): SBD MO Status=2, MOMSN=21945, MT Status=2, MTMSN=0 2022-10-20T20:34:19.655Z,1666298059.655 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T20:35:10.151Z,1666298110.151 [NAL9602](INFO): SBD MO Status=2, MOMSN=21945, MT Status=2, MTMSN=0 2022-10-20T20:35:10.151Z,1666298110.151 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T20:35:43.688Z,1666298143.688 [NAL9602](INFO): SBD MO Status=2, MOMSN=21945, MT Status=2, MTMSN=0 2022-10-20T20:35:43.688Z,1666298143.688 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T20:36:00.652Z,1666298160.652 [NAL9602](INFO): SBD MO Status=2, MOMSN=21945, MT Status=2, MTMSN=0 2022-10-20T20:36:00.652Z,1666298160.652 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T20:36:49.538Z,1666298209.538 [NAL9602](INFO): SBD MO Status=1, MOMSN=21945, MT Status=0, MTMSN=0 2022-10-20T20:36:49.538Z,1666298209.538 [NAL9602](INFO): No messages in MT queue 2022-10-20T20:37:20.237Z,1666298240.237 [NAL9602](INFO): Not Powering down - fast GPS 2022-10-20T20:38:07.950Z,1666298287.950 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-10-20T20:38:07.950Z,1666298287.950 [Default:CheckIn:C.Wait] Stopped 2022-10-20T20:38:07.950Z,1666298287.950 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T20:38:07.951Z,1666298287.951 [Default:CheckIn:D] Running Loop=1 2022-10-20T20:38:08.332Z,1666298288.332 [Default:CheckIn:D] Stopped 2022-10-20T20:38:08.332Z,1666298288.332 [Default:CheckIn:E] Running Loop=1 2022-10-20T20:38:08.728Z,1666298288.728 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.186471 min 2022-10-20T20:38:08.729Z,1666298288.729 [Default:CheckIn:E] Stopped 2022-10-20T20:38:08.729Z,1666298288.729 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-10-20T20:38:08.729Z,1666298288.729 [Default:CheckIn] Stopped 2022-10-20T20:38:08.729Z,1666298288.729 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T20:38:08.729Z,1666298288.729 [Default:CheckIn](INFO): Running loop #11 2022-10-20T20:38:08.729Z,1666298288.729 [Default:CheckIn] Running Loop=11 2022-10-20T20:38:08.729Z,1666298288.729 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-10-20T20:38:08.729Z,1666298288.729 [Default:CheckIn:Read_GPS] Running Loop=1 2022-10-20T20:38:10.738Z,1666298290.738 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203809.00,A,3648.14377,N,12147.22950,W,0.097,257.35,201022,,,D*70 2022-10-20T20:38:10.750Z,1666298290.750 [NAL9602](INFO): GPS fix at 20221020T203809: (36.802396, -121.787158) 2022-10-20T20:38:10.762Z,1666298290.762 [Default:CheckIn:Read_GPS] Stopped 2022-10-20T20:38:10.762Z,1666298290.762 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-10-20T20:38:18.800Z,1666298298.800 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20221020T195334/Courier0024.lzma 2022-10-20T20:38:19.805Z,1666298299.805 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Courier0024.lzma.bak 2022-10-20T20:38:19.805Z,1666298299.805 [DataOverHttps](INFO): SBD MOMSN=17259055 2022-10-20T20:38:24.071Z,1666298304.071 [NAL9602](INFO): SBD MO Status=1, MOMSN=21946, MT Status=0, MTMSN=0 2022-10-20T20:38:24.071Z,1666298304.071 [NAL9602](INFO): No messages in MT queue 2022-10-20T20:38:36.188Z,1666298316.188 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20221020T195334/Express0025.lzma 2022-10-20T20:38:37.190Z,1666298317.190 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Express0025.lzma.bak 2022-10-20T20:38:37.190Z,1666298317.190 [DataOverHttps](INFO): SBD MOMSN=17259058 2022-10-20T20:38:38.666Z,1666298318.666 [Default:CheckIn:Read_Iridium] Stopped 2022-10-20T20:38:38.666Z,1666298318.666 [Default:CheckIn:C.Wait] Running Loop=1 2022-10-20T20:38:38.666Z,1666298318.666 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-10-20T20:38:54.796Z,1666298334.796 [NAL9602](INFO): Not Powering down - fast GPS 2022-10-20T20:43:39.245Z,1666298619.245 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-10-20T20:43:39.245Z,1666298619.245 [Default:CheckIn:C.Wait] Stopped 2022-10-20T20:43:39.245Z,1666298619.245 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T20:43:39.245Z,1666298619.245 [Default:CheckIn:D] Running Loop=1 2022-10-20T20:43:39.626Z,1666298619.626 [Default:CheckIn:D] Stopped 2022-10-20T20:43:39.626Z,1666298619.626 [Default:CheckIn:E] Running Loop=1 2022-10-20T20:43:40.052Z,1666298620.052 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.708032 min 2022-10-20T20:43:40.052Z,1666298620.052 [Default:CheckIn:E] Stopped 2022-10-20T20:43:40.052Z,1666298620.052 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-10-20T20:43:40.052Z,1666298620.052 [Default:CheckIn] Stopped 2022-10-20T20:43:40.052Z,1666298620.052 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T20:43:40.053Z,1666298620.053 [Default:CheckIn](INFO): Running loop #12 2022-10-20T20:43:40.053Z,1666298620.053 [Default:CheckIn] Running Loop=12 2022-10-20T20:43:40.053Z,1666298620.053 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-10-20T20:43:40.053Z,1666298620.053 [Default:CheckIn:Read_GPS] Running Loop=1 2022-10-20T20:43:42.042Z,1666298622.042 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204341.00,A,3648.14345,N,12147.22823,W,0.097,331.24,201022,,,D*75 2022-10-20T20:43:42.045Z,1666298622.045 [NAL9602](INFO): GPS fix at 20221020T204341: (36.802391, -121.787137) 2022-10-20T20:43:42.091Z,1666298622.091 [Default:CheckIn:Read_GPS] Stopped 2022-10-20T20:43:42.091Z,1666298622.091 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-10-20T20:43:50.085Z,1666298630.085 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20221020T195334/Courier0027.lzma 2022-10-20T20:43:51.086Z,1666298631.086 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Courier0027.lzma.bak 2022-10-20T20:43:51.086Z,1666298631.086 [DataOverHttps](INFO): SBD MOMSN=17259065 2022-10-20T20:44:07.505Z,1666298647.505 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20221020T195334/Express0028.lzma 2022-10-20T20:44:08.506Z,1666298648.506 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Express0028.lzma.bak 2022-10-20T20:44:08.507Z,1666298648.507 [DataOverHttps](INFO): SBD MOMSN=17259068 2022-10-20T20:44:09.972Z,1666298649.972 [Default:CheckIn:Read_Iridium] Stopped 2022-10-20T20:44:09.972Z,1666298649.972 [Default:CheckIn:C.Wait] Running Loop=1 2022-10-20T20:44:09.973Z,1666298649.973 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-10-20T20:44:17.623Z,1666298657.623 [NAL9602](INFO): SBD MO Status=1, MOMSN=21947, MT Status=0, MTMSN=0 2022-10-20T20:44:17.623Z,1666298657.623 [NAL9602](INFO): No messages in MT queue 2022-10-20T20:44:48.317Z,1666298688.317 [NAL9602](INFO): Not Powering down - fast GPS 2022-10-20T20:49:10.526Z,1666298950.526 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-10-20T20:49:10.526Z,1666298950.526 [Default:CheckIn:C.Wait] Stopped 2022-10-20T20:49:10.526Z,1666298950.526 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T20:49:10.526Z,1666298950.526 [Default:CheckIn:D] Running Loop=1 2022-10-20T20:49:10.939Z,1666298950.939 [Default:CheckIn:D] Stopped 2022-10-20T20:49:10.939Z,1666298950.939 [Default:CheckIn:E] Running Loop=1 2022-10-20T20:49:11.336Z,1666298951.336 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.229915 min 2022-10-20T20:49:11.336Z,1666298951.336 [Default:CheckIn:E] Stopped 2022-10-20T20:49:11.336Z,1666298951.336 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-10-20T20:49:11.336Z,1666298951.336 [Default:CheckIn] Stopped 2022-10-20T20:49:11.336Z,1666298951.336 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T20:49:11.336Z,1666298951.336 [Default:CheckIn](INFO): Running loop #13 2022-10-20T20:49:11.336Z,1666298951.336 [Default:CheckIn] Running Loop=13 2022-10-20T20:49:11.336Z,1666298951.336 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-10-20T20:49:11.337Z,1666298951.337 [Default:CheckIn:Read_GPS] Running Loop=1 2022-10-20T20:49:13.380Z,1666298953.380 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204912.00,A,3648.14112,N,12147.23283,W,0.058,236.36,201022,,,D*7E 2022-10-20T20:49:13.465Z,1666298953.465 [NAL9602](INFO): GPS fix at 20221020T204912: (36.802352, -121.787214) 2022-10-20T20:49:13.507Z,1666298953.507 [Default:CheckIn:Read_GPS] Stopped 2022-10-20T20:49:13.508Z,1666298953.508 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-10-20T20:49:21.348Z,1666298961.348 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221020T195334/Courier0030.lzma 2022-10-20T20:49:22.350Z,1666298962.350 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Courier0030.lzma.bak 2022-10-20T20:49:22.350Z,1666298962.350 [DataOverHttps](INFO): SBD MOMSN=17259128 2022-10-20T20:49:37.179Z,1666298977.179 [NAL9602](INFO): SBD MO Status=2, MOMSN=21948, MT Status=2, MTMSN=0 2022-10-20T20:49:37.179Z,1666298977.179 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T20:49:38.784Z,1666298978.784 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20221020T195334/Express0031.lzma 2022-10-20T20:49:39.791Z,1666298979.791 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Express0031.lzma.bak 2022-10-20T20:49:39.791Z,1666298979.791 [DataOverHttps](INFO): SBD MOMSN=17259131 2022-10-20T20:49:41.232Z,1666298981.232 [Default:CheckIn:Read_Iridium] Stopped 2022-10-20T20:49:41.232Z,1666298981.232 [Default:CheckIn:C.Wait] Running Loop=1 2022-10-20T20:49:41.233Z,1666298981.233 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-10-20T20:49:48.499Z,1666298988.499 [NAL9602](INFO): SBD MO Status=1, MOMSN=21948, MT Status=0, MTMSN=0 2022-10-20T20:49:48.499Z,1666298988.499 [NAL9602](INFO): No messages in MT queue 2022-10-20T20:50:19.193Z,1666299019.193 [NAL9602](INFO): Not Powering down - fast GPS 2022-10-20T20:54:41.842Z,1666299281.842 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-10-20T20:54:41.842Z,1666299281.842 [Default:CheckIn:C.Wait] Stopped 2022-10-20T20:54:41.842Z,1666299281.842 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T20:54:41.843Z,1666299281.843 [Default:CheckIn:D] Running Loop=1 2022-10-20T20:54:42.238Z,1666299282.238 [Default:CheckIn:D] Stopped 2022-10-20T20:54:42.238Z,1666299282.238 [Default:CheckIn:E] Running Loop=1 2022-10-20T20:54:42.630Z,1666299282.630 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.751563 min 2022-10-20T20:54:42.630Z,1666299282.630 [Default:CheckIn:E] Stopped 2022-10-20T20:54:42.630Z,1666299282.630 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-10-20T20:54:42.630Z,1666299282.630 [Default:CheckIn] Stopped 2022-10-20T20:54:42.630Z,1666299282.630 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T20:54:42.630Z,1666299282.630 [Default:CheckIn](INFO): Running loop #14 2022-10-20T20:54:42.630Z,1666299282.630 [Default:CheckIn] Running Loop=14 2022-10-20T20:54:42.630Z,1666299282.630 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-10-20T20:54:42.631Z,1666299282.631 [Default:CheckIn:Read_GPS] Running Loop=1 2022-10-20T20:54:44.632Z,1666299284.632 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205443.00,A,3648.13915,N,12147.23077,W,0.039,236.36,201022,,,D*70 2022-10-20T20:54:44.634Z,1666299284.634 [NAL9602](INFO): GPS fix at 20221020T205443: (36.802319, -121.787179) 2022-10-20T20:54:44.665Z,1666299284.665 [Default:CheckIn:Read_GPS] Stopped 2022-10-20T20:54:44.666Z,1666299284.666 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-10-20T20:54:52.744Z,1666299292.744 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20221020T195334/Courier0033.lzma 2022-10-20T20:54:53.746Z,1666299293.746 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Courier0033.lzma.bak 2022-10-20T20:54:53.746Z,1666299293.746 [DataOverHttps](INFO): SBD MOMSN=17259196 2022-10-20T20:55:08.867Z,1666299308.867 [NAL9602](INFO): SBD MO Status=1, MOMSN=21949, MT Status=0, MTMSN=0 2022-10-20T20:55:08.867Z,1666299308.867 [NAL9602](INFO): No messages in MT queue 2022-10-20T20:55:10.084Z,1666299310.084 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20221020T195334/Express0034.lzma 2022-10-20T20:55:11.086Z,1666299311.086 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Express0034.lzma.bak 2022-10-20T20:55:11.086Z,1666299311.086 [DataOverHttps](INFO): SBD MOMSN=17259264 2022-10-20T20:55:12.517Z,1666299312.517 [Default:CheckIn:Read_Iridium] Stopped 2022-10-20T20:55:12.517Z,1666299312.517 [Default:CheckIn:C.Wait] Running Loop=1 2022-10-20T20:55:12.517Z,1666299312.517 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-10-20T20:55:39.577Z,1666299339.577 [NAL9602](INFO): Not Powering down - fast GPS 2022-10-20T21:00:13.101Z,1666299613.101 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-10-20T21:00:13.101Z,1666299613.101 [Default:CheckIn:C.Wait] Stopped 2022-10-20T21:00:13.101Z,1666299613.101 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T21:00:13.101Z,1666299613.101 [Default:CheckIn:D] Running Loop=1 2022-10-20T21:00:13.499Z,1666299613.499 [Default:CheckIn:D] Stopped 2022-10-20T21:00:13.499Z,1666299613.499 [Default:CheckIn:E] Running Loop=1 2022-10-20T21:00:13.943Z,1666299613.943 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.272591 min 2022-10-20T21:00:13.943Z,1666299613.943 [Default:CheckIn:E] Stopped 2022-10-20T21:00:13.944Z,1666299613.944 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-10-20T21:00:13.944Z,1666299613.944 [Default:CheckIn] Stopped 2022-10-20T21:00:13.944Z,1666299613.944 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T21:00:13.944Z,1666299613.944 [Default:CheckIn](INFO): Running loop #15 2022-10-20T21:00:13.944Z,1666299613.944 [Default:CheckIn] Running Loop=15 2022-10-20T21:00:13.944Z,1666299613.944 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-10-20T21:00:13.944Z,1666299613.944 [Default:CheckIn:Read_GPS] Running Loop=1 2022-10-20T21:00:15.914Z,1666299615.914 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210015.00,A,3648.17003,N,12147.28346,W,0.389,200.12,201022,,,D*78 2022-10-20T21:00:15.916Z,1666299615.916 [NAL9602](INFO): GPS fix at 20221020T210015: (36.802834, -121.788058) 2022-10-20T21:00:15.946Z,1666299615.946 [Default:CheckIn:Read_GPS] Stopped 2022-10-20T21:00:15.946Z,1666299615.946 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-10-20T21:00:24.004Z,1666299624.004 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20221020T195334/Courier0036.lzma 2022-10-20T21:00:25.006Z,1666299625.006 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Courier0036.lzma.bak 2022-10-20T21:00:25.006Z,1666299625.006 [DataOverHttps](INFO): SBD MOMSN=17259269 2022-10-20T21:00:41.368Z,1666299641.368 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20221020T195334/Express0037.lzma 2022-10-20T21:00:42.370Z,1666299642.370 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Express0037.lzma.bak 2022-10-20T21:00:42.370Z,1666299642.370 [DataOverHttps](INFO): SBD MOMSN=17259272 2022-10-20T21:00:43.856Z,1666299643.856 [Default:CheckIn:Read_Iridium] Stopped 2022-10-20T21:00:43.857Z,1666299643.857 [Default:CheckIn:C.Wait] Running Loop=1 2022-10-20T21:00:43.857Z,1666299643.857 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-10-20T21:03:26.600Z,1666299806.600 [NAL9602](INFO): SBD MO Status=2, MOMSN=21950, MT Status=2, MTMSN=0 2022-10-20T21:03:26.600Z,1666299806.600 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T21:04:20.331Z,1666299860.331 [NAL9602](INFO): SBD MO Status=2, MOMSN=21950, MT Status=2, MTMSN=0 2022-10-20T21:04:20.332Z,1666299860.332 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T21:04:44.167Z,1666299884.167 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-10-20T21:04:47.403Z,1666299887.403 [NAL9602](INFO): SBD MO Status=2, MOMSN=21950, MT Status=2, MTMSN=0 2022-10-20T21:04:47.403Z,1666299887.403 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T21:05:18.509Z,1666299918.509 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2022-10-20T21:05:44.375Z,1666299944.375 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-10-20T21:05:44.375Z,1666299944.375 [Default:CheckIn:C.Wait] Stopped 2022-10-20T21:05:44.375Z,1666299944.375 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T21:05:44.376Z,1666299944.376 [Default:CheckIn:D] Running Loop=1 2022-10-20T21:05:44.785Z,1666299944.785 [Default:CheckIn:D] Stopped 2022-10-20T21:05:44.785Z,1666299944.785 [Default:CheckIn:E] Running Loop=1 2022-10-20T21:05:45.180Z,1666299945.180 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.794010 min 2022-10-20T21:05:45.180Z,1666299945.180 [Default:CheckIn:E] Stopped 2022-10-20T21:05:45.180Z,1666299945.180 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-10-20T21:05:45.180Z,1666299945.180 [Default:CheckIn] Stopped 2022-10-20T21:05:45.181Z,1666299945.181 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T21:05:45.181Z,1666299945.181 [Default:CheckIn](INFO): Running loop #16 2022-10-20T21:05:45.181Z,1666299945.181 [Default:CheckIn] Running Loop=16 2022-10-20T21:05:45.181Z,1666299945.181 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-10-20T21:05:45.181Z,1666299945.181 [Default:CheckIn:Read_GPS] Running Loop=1 2022-10-20T21:05:47.195Z,1666299947.195 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210546.00,A,3648.16695,N,12147.27893,W,0.156,200.12,201022,,,D*7F 2022-10-20T21:05:47.198Z,1666299947.198 [NAL9602](INFO): GPS fix at 20221020T210546: (36.802782, -121.787982) 2022-10-20T21:05:47.209Z,1666299947.209 [Default:CheckIn:Read_GPS] Stopped 2022-10-20T21:05:47.209Z,1666299947.209 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-10-20T21:05:55.212Z,1666299955.212 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20221020T195334/Courier0039.lzma 2022-10-20T21:05:56.214Z,1666299956.214 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Courier0039.lzma.bak 2022-10-20T21:05:56.215Z,1666299956.215 [DataOverHttps](INFO): SBD MOMSN=17259351 2022-10-20T21:06:12.588Z,1666299972.588 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20221020T195334/Express0040.lzma 2022-10-20T21:06:13.591Z,1666299973.591 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Express0040.lzma.bak 2022-10-20T21:06:13.591Z,1666299973.591 [DataOverHttps](INFO): SBD MOMSN=17259354 2022-10-20T21:06:15.074Z,1666299975.074 [Default:CheckIn:Read_Iridium] Stopped 2022-10-20T21:06:15.074Z,1666299975.074 [Default:CheckIn:C.Wait] Running Loop=1 2022-10-20T21:06:15.074Z,1666299975.074 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-10-20T21:06:19.509Z,1666299979.509 [NAL9602](INFO): Not Powering down - fast GPS 2022-10-20T21:07:56.329Z,1666300076.329 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:07:58.337Z,1666300078.337 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239197 2022-10-20T21:08:31.473Z,1666300111.473 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:09:06.613Z,1666300146.613 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:09:41.753Z,1666300181.753 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:10:16.893Z,1666300216.893 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:10:52.033Z,1666300252.033 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:11:15.660Z,1666300275.660 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2022-10-20T21:11:15.660Z,1666300275.660 [Default:CheckIn:C.Wait] Stopped 2022-10-20T21:11:15.660Z,1666300275.660 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T21:11:15.661Z,1666300275.661 [Default:CheckIn:D] Running Loop=1 2022-10-20T21:11:16.080Z,1666300276.080 [Default:CheckIn:D] Stopped 2022-10-20T21:11:16.080Z,1666300276.080 [Default:CheckIn:E] Running Loop=1 2022-10-20T21:11:16.464Z,1666300276.464 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.315592 min 2022-10-20T21:11:16.464Z,1666300276.464 [Default:CheckIn:E] Stopped 2022-10-20T21:11:16.465Z,1666300276.465 [Default:CheckIn](INFO): Completed Default:CheckIn 2022-10-20T21:11:16.465Z,1666300276.465 [Default:CheckIn] Stopped 2022-10-20T21:11:16.465Z,1666300276.465 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T21:11:16.465Z,1666300276.465 [Default:CheckIn](INFO): Running loop #17 2022-10-20T21:11:16.465Z,1666300276.465 [Default:CheckIn] Running Loop=17 2022-10-20T21:11:16.465Z,1666300276.465 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2022-10-20T21:11:16.465Z,1666300276.465 [Default:CheckIn:Read_GPS] Running Loop=1 2022-10-20T21:11:18.475Z,1666300278.475 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211117.00,A,3648.15538,N,12147.28980,W,1.633,255.78,201022,,,A*79 2022-10-20T21:11:18.478Z,1666300278.478 [NAL9602](INFO): GPS fix at 20221020T211117: (36.802590, -121.788163) 2022-10-20T21:11:18.490Z,1666300278.490 [Default:CheckIn:Read_GPS] Stopped 2022-10-20T21:11:18.490Z,1666300278.490 [Default:CheckIn:Read_Iridium] Running Loop=1 2022-10-20T21:11:27.185Z,1666300287.185 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:11:49.173Z,1666300309.173 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2022-10-20T21:11:49.256Z,1666300309.256 [NAL9602](ERROR): received: +CSQ:0 OK950, 2, 0, 0, 0 OK 2022-10-20T21:12:02.324Z,1666300322.324 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:12:37.469Z,1666300357.469 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:13:12.609Z,1666300392.609 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:13:47.749Z,1666300427.749 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:14:22.893Z,1666300462.893 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:14:58.033Z,1666300498.033 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:15:33.173Z,1666300533.173 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:16:08.321Z,1666300568.321 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:16:43.461Z,1666300603.461 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:17:18.609Z,1666300638.609 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:17:53.750Z,1666300673.750 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:18:28.889Z,1666300708.889 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:19:04.029Z,1666300744.029 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:19:39.197Z,1666300779.197 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:20:14.385Z,1666300814.385 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:20:49.541Z,1666300849.541 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:21:24.681Z,1666300884.681 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:21:59.823Z,1666300919.823 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:22:34.960Z,1666300954.960 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:23:10.101Z,1666300990.101 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:23:40.290Z,1666301020.290 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error 2022-10-20T21:23:45.249Z,1666301025.249 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:24:20.397Z,1666301060.397 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:24:55.545Z,1666301095.545 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:25:30.693Z,1666301130.693 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:26:05.833Z,1666301165.833 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:26:40.973Z,1666301200.973 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:27:16.112Z,1666301236.112 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:27:51.257Z,1666301271.257 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:28:26.397Z,1666301306.397 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:29:01.537Z,1666301341.537 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:29:36.677Z,1666301376.677 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:30:11.817Z,1666301411.817 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:30:46.964Z,1666301446.964 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:31:13.511Z,1666301473.511 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 6. 2022-10-20T21:31:13.531Z,1666301473.531 [BPC1](INFO): Received data from all battery sticks. 2022-10-20T21:31:22.105Z,1666301482.105 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:31:57.257Z,1666301517.257 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:32:32.397Z,1666301552.397 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:33:07.549Z,1666301587.549 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:33:42.709Z,1666301622.709 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:34:17.849Z,1666301657.849 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:34:52.993Z,1666301692.993 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:35:28.133Z,1666301728.133 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:36:03.273Z,1666301763.273 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:36:38.421Z,1666301798.421 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:37:13.565Z,1666301833.565 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:37:48.705Z,1666301868.705 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:38:23.845Z,1666301903.845 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:38:58.985Z,1666301938.985 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:39:34.125Z,1666301974.125 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:40:09.273Z,1666302009.273 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:40:44.430Z,1666302044.430 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:41:19.581Z,1666302079.581 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:41:54.720Z,1666302114.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:42:29.862Z,1666302149.862 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:43:05.001Z,1666302185.001 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:43:40.145Z,1666302220.145 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:44:15.284Z,1666302255.284 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:44:50.429Z,1666302290.429 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:45:25.569Z,1666302325.569 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:46:00.713Z,1666302360.713 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:46:35.866Z,1666302395.866 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:47:11.005Z,1666302431.005 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:47:46.153Z,1666302466.153 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:48:21.293Z,1666302501.293 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:48:56.433Z,1666302536.433 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:49:31.585Z,1666302571.585 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:50:06.729Z,1666302606.729 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:50:24.541Z,1666302624.541 [CBIT](IMPORTANT): Beginning ground fault scan 2022-10-20T21:50:35.437Z,1666302635.437 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.010011 CHAN A1 (24V): 0.000827 CHAN A2 (12V): -0.006856 CHAN A3 (5V): -0.002361 CHAN B0 (3.3V): -0.000113 CHAN B1 (3.15aV): -0.000532 CHAN B2 (3.15bV): 0.000598 CHAN B3 (GND): 0.002240 OPEN: 0.005308 Full Scale: +/- 1 mA 2022-10-20T21:50:41.873Z,1666302641.873 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:51:17.013Z,1666302677.013 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:51:52.164Z,1666302712.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:52:27.304Z,1666302747.304 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:53:02.461Z,1666302782.461 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:53:37.600Z,1666302817.600 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:54:12.749Z,1666302852.749 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:54:47.906Z,1666302887.906 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:55:23.061Z,1666302923.061 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:55:58.201Z,1666302958.201 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:56:33.349Z,1666302993.349 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:57:08.488Z,1666303028.489 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:57:43.629Z,1666303063.629 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:58:18.768Z,1666303098.768 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:58:48.035Z,1666303128.035 [NAL9602](INFO): SBD MO Status=2, MOMSN=21950, MT Status=2, MTMSN=0 2022-10-20T21:58:48.035Z,1666303128.035 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T21:58:53.909Z,1666303133.909 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:59:23.239Z,1666303163.239 [NAL9602](INFO): SBD MO Status=2, MOMSN=21950, MT Status=2, MTMSN=0 2022-10-20T21:59:23.239Z,1666303163.239 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T21:59:29.048Z,1666303169.048 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T21:59:36.631Z,1666303176.631 [NAL9602](INFO): SBD MO Status=2, MOMSN=21950, MT Status=2, MTMSN=0 2022-10-20T21:59:36.631Z,1666303176.631 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T21:59:59.744Z,1666303199.744 [NAL9602](INFO): SBD MO Status=2, MOMSN=21950, MT Status=2, MTMSN=0 2022-10-20T21:59:59.744Z,1666303199.744 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T22:00:04.189Z,1666303204.189 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T22:00:39.329Z,1666303239.329 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T22:00:51.107Z,1666303251.107 [NAL9602](INFO): SBD MO Status=2, MOMSN=21950, MT Status=2, MTMSN=0 2022-10-20T22:00:51.107Z,1666303251.107 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T22:01:07.331Z,1666303267.331 [NAL9602](INFO): SBD MO Status=2, MOMSN=21950, MT Status=2, MTMSN=0 2022-10-20T22:01:07.331Z,1666303267.331 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T22:01:14.485Z,1666303274.485 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T22:01:25.574Z,1666303285.574 [NAL9602](INFO): SBD MO Status=2, MOMSN=21950, MT Status=2, MTMSN=0 2022-10-20T22:01:25.574Z,1666303285.574 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T22:01:49.637Z,1666303309.637 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T22:01:54.339Z,1666303314.339 [NAL9602](INFO): SBD MO Status=2, MOMSN=21950, MT Status=2, MTMSN=0 2022-10-20T22:01:54.339Z,1666303314.339 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2022-10-20T22:02:24.789Z,1666303344.789 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T22:02:59.929Z,1666303379.930 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T22:03:26.925Z,1666303406.925 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2022-10-20T22:03:26.925Z,1666303406.925 [DropWeight] Hardware Fault, FailCount= 1 2022-10-20T22:03:26.926Z,1666303406.926 [DropWeight](ERROR): Hardware Fault 2022-10-20T22:03:26.982Z,1666303406.982 [CommandExec](FAULT): Scheduling is paused for 2 commands 2022-10-20T22:03:26.982Z,1666303406.982 [CBIT](INFO): Critical error at 20221020T220326 2022-10-20T22:03:26.985Z,1666303406.985 [CBIT](ERROR): Hardware Fault in component: DropWeight 2022-10-20T22:03:26.985Z,1666303406.985 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2022-10-20T22:03:27.375Z,1666303407.375 [CBIT](INFO): Critical error at 20221020T220326 2022-10-20T22:03:35.069Z,1666303415.069 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T22:04:10.217Z,1666303450.217 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T22:04:45.357Z,1666303485.357 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2022-10-20T22:04:58.193Z,1666303498.193 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003252 2022-10-20T22:05:05.804Z,1666303505.804 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20221020T195334/Courier0042.lzma 2022-10-20T22:05:06.806Z,1666303506.806 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Courier0042.lzma.bak 2022-10-20T22:05:06.806Z,1666303506.806 [DataOverHttps](INFO): SBD MOMSN=17259635 2022-10-20T22:05:23.036Z,1666303523.036 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20221020T195334/Express0043.lzma 2022-10-20T22:05:24.039Z,1666303524.039 [DataOverHttps](INFO): Moved sent file to Logs/20221020T195334/Express0043.lzma.bak 2022-10-20T22:05:24.039Z,1666303524.039 [DataOverHttps](INFO): SBD MOMSN=17259638 2022-10-20T22:05:25.317Z,1666303525.317 [Default:CheckIn:Read_Iridium] Stopped 2022-10-20T22:05:25.317Z,1666303525.317 [Default:CheckIn:C.Wait] Running Loop=1 2022-10-20T22:05:25.317Z,1666303525.317 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2022-10-20T22:05:31.357Z,1666303531.357 [NAL9602](INFO): Not Powering down - fast GPS 2022-10-20T22:05:51.109Z,1666303551.109 [CommandExec](IMPORTANT): got command quit 2022-10-20T22:05:52.112Z,1666303552.112 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2022-10-20T22:05:52.112Z,1666303552.112 [CommandExec](INFO): Uninitializing the command executive. 2022-10-20T22:05:52.112Z,1666303552.112 [CommandExec](INFO): Uninitializing the command scheduler. 2022-10-20T22:05:52.113Z,1666303552.113 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:52.176Z,1666303552.176 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2022-10-20T22:05:52.177Z,1666303552.177 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2022-10-20T22:05:52.177Z,1666303552.177 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:52.177Z,1666303552.177 [NavChartDb](INFO): Join timeout helper Thread ID is 8009 2022-10-20T22:05:52.484Z,1666303552.484 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2022-10-20T22:05:52.485Z,1666303552.485 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:52.488Z,1666303552.488 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2022-10-20T22:05:52.488Z,1666303552.488 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:52.489Z,1666303552.489 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 8010 2022-10-20T22:05:52.888Z,1666303552.888 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2022-10-20T22:05:52.889Z,1666303552.889 [WetLabsUBAT](INFO): Powering down 2022-10-20T22:05:52.889Z,1666303552.889 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:52.908Z,1666303552.908 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2022-10-20T22:05:52.908Z,1666303552.908 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:52.909Z,1666303552.909 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8011 2022-10-20T22:05:53.208Z,1666303553.208 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2022-10-20T22:05:53.209Z,1666303553.209 [WetLabsBB2FL](INFO): Powering down 2022-10-20T22:05:53.209Z,1666303553.209 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:53.216Z,1666303553.216 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2022-10-20T22:05:53.216Z,1666303553.216 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:53.217Z,1666303553.217 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8012 2022-10-20T22:05:53.308Z,1666303553.308 [CTD_Seabird](INFO): Powering down 2022-10-20T22:05:53.320Z,1666303553.320 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2022-10-20T22:05:53.320Z,1666303553.320 [CTD_Seabird](INFO): Powering down 2022-10-20T22:05:53.332Z,1666303553.332 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:53.336Z,1666303553.336 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2022-10-20T22:05:53.336Z,1666303553.336 [DAT ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:53.337Z,1666303553.337 [DAT](INFO): Join timeout helper Thread ID is 8013 2022-10-20T22:05:53.416Z,1666303553.416 [DAT](INFO): Powering down 2022-10-20T22:05:53.489Z,1666303553.489 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2022-10-20T22:05:53.489Z,1666303553.489 [DAT](INFO): Powering down 2022-10-20T22:05:53.490Z,1666303553.490 [DAT ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:53.505Z,1666303553.505 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2022-10-20T22:05:53.505Z,1666303553.505 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:53.506Z,1666303553.506 [Radio_Surface](INFO): Join timeout helper Thread ID is 8014 2022-10-20T22:05:53.721Z,1666303553.721 [Radio_Surface](INFO): Powering down 2022-10-20T22:05:53.722Z,1666303553.722 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2022-10-20T22:05:53.722Z,1666303553.722 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:53.737Z,1666303553.737 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2022-10-20T22:05:53.737Z,1666303553.737 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:53.737Z,1666303553.737 [Onboard](INFO): Join timeout helper Thread ID is 8015 2022-10-20T22:05:53.981Z,1666303553.981 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2022-10-20T22:05:56.804Z,1666303556.804 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2022-10-20T22:05:56.805Z,1666303556.805 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:56.813Z,1666303556.813 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2022-10-20T22:05:56.813Z,1666303556.813 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:56.813Z,1666303556.813 [DataOverHttps](INFO): Join timeout helper Thread ID is 8016 2022-10-20T22:05:56.992Z,1666303556.992 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2022-10-20T22:05:56.993Z,1666303556.993 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.005Z,1666303557.005 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2022-10-20T22:05:57.005Z,1666303557.005 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.006Z,1666303557.006 [BackseatComponent](INFO): Join timeout helper Thread ID is 8017 2022-10-20T22:05:57.144Z,1666303557.144 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2022-10-20T22:05:57.145Z,1666303557.145 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.165Z,1666303557.165 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2022-10-20T22:05:57.165Z,1666303557.165 [logger ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.165Z,1666303557.165 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2022-10-20T22:05:57.165Z,1666303557.165 [logger ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.166Z,1666303557.166 [logger](INFO): Join timeout helper Thread ID is 8018 2022-10-20T22:05:57.167Z,1666303557.167 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2022-10-20T22:05:57.167Z,1666303557.167 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.167Z,1666303557.167 [CommandLine](INFO): Join timeout helper Thread ID is 8019 2022-10-20T22:05:57.208Z,1666303557.208 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2022-10-20T22:05:57.209Z,1666303557.209 [CommandLine ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.213Z,1666303557.213 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2022-10-20T22:05:57.213Z,1666303557.213 [CommandExec ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.214Z,1666303557.214 [CommandExec](INFO): Join timeout helper Thread ID is 8020 2022-10-20T22:05:57.221Z,1666303557.221 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2022-10-20T22:05:57.221Z,1666303557.221 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.222Z,1666303557.222 [controlThread](INFO): Join timeout helper Thread ID is 8021 2022-10-20T22:05:57.243Z,1666303557.243 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2022-10-20T22:05:57.243Z,1666303557.243 [controlThread](DEBUG): Uninitializing ControlThread 2022-10-20T22:05:57.243Z,1666303557.243 [AHRS_M2](INFO): Powering down 2022-10-20T22:05:57.313Z,1666303557.313 [NAL9602](INFO): Powering down 2022-10-20T22:05:57.384Z,1666303557.384 [RDI_Pathfinder](INFO): Powering down 2022-10-20T22:05:57.386Z,1666303557.386 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2022-10-20T22:05:57.387Z,1666303557.387 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2022-10-20T22:05:57.387Z,1666303557.387 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2022-10-20T22:05:57.388Z,1666303557.388 [MissionManager](INFO): Uninitializing Mission Default 2022-10-20T22:05:57.388Z,1666303557.388 [Default] Stopped 2022-10-20T22:05:57.388Z,1666303557.388 [Default](DEBUG): Aggregate::uninitialize Default 2022-10-20T22:05:57.388Z,1666303557.388 [Default:B.GoToSurface] Stopped 2022-10-20T22:05:57.388Z,1666303557.388 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2022-10-20T22:05:57.388Z,1666303557.388 [Default:CheckIn] Stopped 2022-10-20T22:05:57.388Z,1666303557.388 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2022-10-20T22:05:57.388Z,1666303557.388 [Default:CheckIn:C.Wait] Stopped 2022-10-20T22:05:57.388Z,1666303557.388 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2022-10-20T22:05:57.391Z,1666303557.391 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2022-10-20T22:05:57.391Z,1666303557.391 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2022-10-20T22:05:57.392Z,1666303557.392 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2022-10-20T22:05:57.392Z,1666303557.392 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2022-10-20T22:05:57.392Z,1666303557.392 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2022-10-20T22:05:57.393Z,1666303557.393 [BuoyancyServo](INFO): Powering down 2022-10-20T22:05:57.404Z,1666303557.404 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2022-10-20T22:05:57.405Z,1666303557.405 [ElevatorServo](INFO): Powering down 2022-10-20T22:05:57.405Z,1666303557.405 [MassServo](DEBUG): Uninitialize Mass Servo. 2022-10-20T22:05:57.405Z,1666303557.405 [MassServo](INFO): Powering down 2022-10-20T22:05:57.406Z,1666303557.406 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2022-10-20T22:05:57.406Z,1666303557.406 [RudderServo](INFO): Powering down 2022-10-20T22:05:57.407Z,1666303557.407 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2022-10-20T22:05:57.407Z,1666303557.407 [ThrusterServo](INFO): Powering down 2022-10-20T22:05:57.408Z,1666303557.408 [SBIT](DEBUG): Uninitialize SBIT Component. 2022-10-20T22:05:57.409Z,1666303557.409 [IBIT](DEBUG): Uninitialize IBIT Component. 2022-10-20T22:05:57.409Z,1666303557.409 [CBIT](DEBUG): Uninitialize CBIT Component. 2022-10-20T22:05:57.409Z,1666303557.409 [CBIT](DEBUG): Powering off loads. 2022-10-20T22:05:57.420Z,1666303557.420 [CBIT](DEBUG): Disabling WDT. 2022-10-20T22:05:57.432Z,1666303557.432 [CBIT](DEBUG): Opening all GF detection circuits. 2022-10-20T22:05:57.433Z,1666303557.433 [controlThread ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.519Z,1666303557.519 [DAT ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.526Z,1666303557.526 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.528Z,1666303557.528 [Onboard ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.534Z,1666303557.534 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.577Z,1666303557.577 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.645Z,1666303557.645 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.647Z,1666303557.647 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.650Z,1666303557.650 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.660Z,1666303557.660 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2022-10-20T22:05:57.795Z,1666303557.795 [logger ThreadHandler](INFO): Thread cancelled.