2023-09-18T15:26:33.869Z,1695050793.869 [CommandExec](IMPORTANT): got command restart logs 2023-09-18T15:26:42.160Z,1695050802.160 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:27:04.422Z,1695050824.422 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T15:27:13.296Z,1695050833.296 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:27:32.819Z,1695050852.819 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230918T123651/Courier0070.lzma 2023-09-18T15:27:33.822Z,1695050853.822 [DataOverHttps](INFO): Moved sent file to Logs/20230918T123651/Courier0070.lzma.bak 2023-09-18T15:27:33.822Z,1695050853.822 [DataOverHttps](INFO): SBD MOMSN=18781934 2023-09-18T15:27:58.991Z,1695050878.991 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230918T123651/Courier0073.lzma 2023-09-18T15:27:59.994Z,1695050879.994 [DataOverHttps](INFO): Moved sent file to Logs/20230918T123651/Courier0073.lzma.bak 2023-09-18T15:27:59.994Z,1695050879.994 [DataOverHttps](INFO): SBD MOMSN=18781937 2023-09-18T15:28:18.861Z,1695050898.861 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230918T123651/Express0071.lzma 2023-09-18T15:28:19.862Z,1695050899.862 [DataOverHttps](INFO): Moved sent file to Logs/20230918T123651/Express0071.lzma.bak 2023-09-18T15:28:19.862Z,1695050899.862 [DataOverHttps](INFO): SBD MOMSN=18781939 2023-09-18T15:28:39.140Z,1695050919.140 [DataOverHttps](INFO): Sending 109 bytes from file Logs/20230918T123651/Express0074.lzma 2023-09-18T15:28:40.142Z,1695050920.142 [DataOverHttps](INFO): Moved sent file to Logs/20230918T123651/Express0074.lzma.bak 2023-09-18T15:28:40.143Z,1695050920.143 [DataOverHttps](INFO): SBD MOMSN=18781942 2023-09-18T15:28:45.596Z,1695050925.596 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T15:28:45.596Z,1695050925.596 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T15:28:45.597Z,1695050925.597 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T15:33:46.198Z,1695051226.198 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T15:33:46.198Z,1695051226.198 [Default:CheckIn:C.Wait] Stopped 2023-09-18T15:33:46.198Z,1695051226.198 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T15:33:46.198Z,1695051226.198 [Default:CheckIn:D] Running Loop=1 2023-09-18T15:33:46.578Z,1695051226.578 [Default:CheckIn:D] Stopped 2023-09-18T15:33:46.578Z,1695051226.578 [Default:CheckIn:E] Running Loop=1 2023-09-18T15:33:46.999Z,1695051226.999 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.262138 min 2023-09-18T15:33:46.999Z,1695051226.999 [Default:CheckIn:E] Stopped 2023-09-18T15:33:46.999Z,1695051226.999 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T15:33:46.000Z,1695051227.000 [Default:CheckIn] Stopped 2023-09-18T15:33:47.000Z,1695051227.000 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T15:33:47.000Z,1695051227.000 [Default:CheckIn](INFO): Running loop #10 2023-09-18T15:33:47.001Z,1695051227.001 [Default:CheckIn] Running Loop=10 2023-09-18T15:33:47.001Z,1695051227.001 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T15:33:47.001Z,1695051227.001 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T15:33:48.991Z,1695051228.991 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153344.00,A,4149.09198,N,08106.30597,W,0.078,139.90,180923,,,D*77 2023-09-18T15:33:48.993Z,1695051228.993 [NAL9602](INFO): GPS fix at 20230918T153344: (41.818200, -81.105099) 2023-09-18T15:33:49.020Z,1695051229.020 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T15:33:49.020Z,1695051229.020 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T15:33:57.304Z,1695051237.304 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20230918T152633/Courier0000.lzma 2023-09-18T15:33:58.306Z,1695051238.306 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0000.lzma.bak 2023-09-18T15:33:58.306Z,1695051238.306 [DataOverHttps](INFO): SBD MOMSN=18781946 2023-09-18T15:34:16.568Z,1695051256.568 [DataOverHttps](INFO): Sending 394 bytes from file Logs/20230918T152633/Express0001.lzma 2023-09-18T15:34:18.330Z,1695051258.330 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0001.lzma.bak 2023-09-18T15:34:18.330Z,1695051258.330 [DataOverHttps](INFO): SBD MOMSN=18781950 2023-09-18T15:34:19.287Z,1695051259.287 [NAL9602](INFO): SBD MO Status=2, MOMSN=11580, MT Status=2, MTMSN=0 2023-09-18T15:34:19.287Z,1695051259.287 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-18T15:34:20.949Z,1695051260.949 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T15:34:20.949Z,1695051260.949 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T15:34:20.949Z,1695051260.949 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T15:35:02.517Z,1695051302.517 [NAL9602](INFO): SBD MO Status=0, MOMSN=11580, MT Status=0, MTMSN=0 2023-09-18T15:35:02.517Z,1695051302.517 [NAL9602](INFO): No messages in MT queue 2023-09-18T15:35:33.257Z,1695051333.257 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T15:38:34.656Z,1695051514.656 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:39:05.792Z,1695051545.792 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:39:21.533Z,1695051561.533 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T15:39:21.533Z,1695051561.533 [Default:CheckIn:C.Wait] Stopped 2023-09-18T15:39:21.533Z,1695051561.533 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T15:39:21.533Z,1695051561.533 [Default:CheckIn:D] Running Loop=1 2023-09-18T15:39:21.929Z,1695051561.929 [Default:CheckIn:D] Stopped 2023-09-18T15:39:21.930Z,1695051561.930 [Default:CheckIn:E] Running Loop=1 2023-09-18T15:39:22.338Z,1695051562.338 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.851326 min 2023-09-18T15:39:22.339Z,1695051562.339 [Default:CheckIn:E] Stopped 2023-09-18T15:39:22.339Z,1695051562.339 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T15:39:22.339Z,1695051562.339 [Default:CheckIn] Stopped 2023-09-18T15:39:22.339Z,1695051562.339 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T15:39:22.339Z,1695051562.339 [Default:CheckIn](INFO): Running loop #11 2023-09-18T15:39:22.339Z,1695051562.339 [Default:CheckIn] Running Loop=11 2023-09-18T15:39:22.339Z,1695051562.339 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T15:39:22.339Z,1695051562.339 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T15:39:24.347Z,1695051564.347 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153919.00,A,4149.08724,N,08106.30601,W,0.156,118.95,180923,,,D*72 2023-09-18T15:39:24.350Z,1695051564.350 [NAL9602](INFO): GPS fix at 20230918T153919: (41.818121, -81.105100) 2023-09-18T15:39:24.360Z,1695051564.360 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T15:39:24.361Z,1695051564.361 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T15:39:37.248Z,1695051577.248 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:40:08.376Z,1695051608.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:40:39.529Z,1695051639.529 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:40:42.541Z,1695051642.541 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.095261 2023-09-18T15:41:13.668Z,1695051673.668 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:41:44.793Z,1695051704.793 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:42:15.916Z,1695051735.916 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:42:47.040Z,1695051767.040 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:43:18.164Z,1695051798.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:43:49.288Z,1695051829.288 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:44:20.412Z,1695051860.412 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:44:26.197Z,1695051866.197 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T15:44:51.536Z,1695051891.536 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:45:22.660Z,1695051922.660 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:45:53.801Z,1695051953.801 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:46:24.932Z,1695051984.932 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:46:56.068Z,1695052016.068 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:47:27.192Z,1695052047.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:47:58.316Z,1695052078.316 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:48:29.440Z,1695052109.440 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:49:00.564Z,1695052140.564 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:49:31.688Z,1695052171.688 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:50:02.816Z,1695052202.816 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:50:33.956Z,1695052233.956 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:51:05.080Z,1695052265.080 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:51:36.224Z,1695052296.224 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:52:07.348Z,1695052327.348 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:52:38.472Z,1695052358.472 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:53:09.596Z,1695052389.596 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:53:40.720Z,1695052420.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:54:11.844Z,1695052451.844 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:54:42.968Z,1695052482.968 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:55:14.100Z,1695052514.100 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:55:45.224Z,1695052545.224 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:56:16.349Z,1695052576.349 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:56:47.500Z,1695052607.500 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:57:18.624Z,1695052638.624 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:57:49.749Z,1695052669.749 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:58:20.872Z,1695052700.872 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:58:51.996Z,1695052731.996 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:59:23.120Z,1695052763.120 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T15:59:54.244Z,1695052794.244 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:00:25.368Z,1695052825.368 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:00:56.509Z,1695052856.509 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:01:27.632Z,1695052887.632 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:01:58.756Z,1695052918.756 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:02:29.880Z,1695052949.880 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:03:01.004Z,1695052981.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:03:32.144Z,1695053012.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:04:03.272Z,1695053043.272 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:04:34.400Z,1695053074.400 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:05:05.524Z,1695053105.524 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:05:36.656Z,1695053136.656 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:06:07.780Z,1695053167.780 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:06:38.904Z,1695053198.904 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:07:10.044Z,1695053230.044 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:07:41.168Z,1695053261.168 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:07:44.128Z,1695053264.128 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-09-18T16:07:44.131Z,1695053264.131 [BPC1](INFO): Received data from all battery sticks. 2023-09-18T16:08:12.324Z,1695053292.324 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:08:43.448Z,1695053323.448 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:09:14.576Z,1695053354.576 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:09:45.720Z,1695053385.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:10:16.852Z,1695053416.852 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:10:47.980Z,1695053447.980 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:11:19.108Z,1695053479.108 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:11:50.244Z,1695053510.244 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:12:21.368Z,1695053541.368 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:12:52.524Z,1695053572.524 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:13:23.680Z,1695053603.680 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:13:54.804Z,1695053634.804 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:14:25.940Z,1695053665.940 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:14:57.064Z,1695053697.064 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:15:28.188Z,1695053728.188 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:15:59.316Z,1695053759.316 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:16:30.440Z,1695053790.440 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:17:01.568Z,1695053821.568 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:17:32.698Z,1695053852.698 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:18:03.820Z,1695053883.820 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:18:34.944Z,1695053914.944 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:19:06.068Z,1695053946.068 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:19:37.192Z,1695053977.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:20:08.332Z,1695054008.332 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:20:39.464Z,1695054039.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:21:10.588Z,1695054070.588 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:21:41.712Z,1695054101.712 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:22:12.848Z,1695054132.848 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:22:43.972Z,1695054163.972 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:23:15.096Z,1695054195.096 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:23:46.248Z,1695054226.248 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:24:17.373Z,1695054257.373 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:24:48.496Z,1695054288.496 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:25:19.620Z,1695054319.620 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:25:50.760Z,1695054350.760 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:26:21.884Z,1695054381.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:26:53.021Z,1695054413.021 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:27:24.144Z,1695054444.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:27:55.272Z,1695054475.272 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:28:26.396Z,1695054506.396 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:28:57.520Z,1695054537.520 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:29:28.644Z,1695054568.644 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:29:59.768Z,1695054599.768 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:30:30.900Z,1695054630.900 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:31:02.024Z,1695054662.024 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:31:33.204Z,1695054693.204 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:32:04.328Z,1695054724.328 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:32:35.454Z,1695054755.454 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:33:06.592Z,1695054786.592 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:33:37.716Z,1695054817.716 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:34:08.840Z,1695054848.840 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:34:39.964Z,1695054879.964 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:35:11.088Z,1695054911.088 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:35:42.228Z,1695054942.228 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:36:13.368Z,1695054973.368 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:36:44.492Z,1695055004.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:37:15.616Z,1695055035.616 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:37:46.740Z,1695055066.740 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:38:17.864Z,1695055097.864 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:38:48.988Z,1695055128.988 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:39:20.112Z,1695055160.112 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:39:51.236Z,1695055191.236 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:40:22.360Z,1695055222.360 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:40:53.484Z,1695055253.484 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:41:24.608Z,1695055284.608 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:41:55.732Z,1695055315.732 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:42:26.856Z,1695055346.856 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:42:57.980Z,1695055377.980 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:43:29.104Z,1695055409.104 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:44:00.244Z,1695055440.244 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:44:31.371Z,1695055471.371 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:45:02.492Z,1695055502.492 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:45:33.616Z,1695055533.616 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:46:04.749Z,1695055564.749 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:46:35.880Z,1695055595.880 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:47:07.004Z,1695055627.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:47:38.132Z,1695055658.132 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:48:09.256Z,1695055689.256 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:48:40.392Z,1695055720.392 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:49:11.524Z,1695055751.524 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:49:42.648Z,1695055782.648 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:50:13.772Z,1695055813.772 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:50:44.900Z,1695055844.900 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:51:16.028Z,1695055876.028 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:51:47.164Z,1695055907.164 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:52:18.291Z,1695055938.291 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:52:49.440Z,1695055969.440 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:53:20.580Z,1695056000.580 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:53:51.717Z,1695056031.717 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:54:22.852Z,1695056062.852 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:54:53.976Z,1695056093.976 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:55:25.124Z,1695056125.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:55:56.264Z,1695056156.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:56:27.388Z,1695056187.388 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:56:58.512Z,1695056218.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:57:29.636Z,1695056249.636 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:58:00.760Z,1695056280.760 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:58:31.884Z,1695056311.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:59:03.008Z,1695056343.008 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T16:59:10.906Z,1695056350.906 [DVL_micro](ERROR): Failed to parse: :+00000,I 2023-09-18T16:59:34.132Z,1695056374.132 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:00:05.264Z,1695056405.264 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:00:36.412Z,1695056436.412 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:01:07.536Z,1695056467.536 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:01:38.660Z,1695056498.660 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:02:09.800Z,1695056529.800 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:02:40.924Z,1695056560.924 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:03:12.052Z,1695056592.052 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:03:43.176Z,1695056623.176 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:04:14.316Z,1695056654.316 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:04:45.447Z,1695056685.447 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:05:16.569Z,1695056716.569 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:05:47.712Z,1695056747.712 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:06:18.836Z,1695056778.836 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:06:49.960Z,1695056809.960 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:07:21.084Z,1695056841.084 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:07:52.236Z,1695056872.236 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:08:23.376Z,1695056903.376 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:08:54.512Z,1695056934.512 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:09:20.887Z,1695056960.887 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file). 2023-09-18T17:09:25.636Z,1695056965.636 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:09:56.772Z,1695056996.772 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:10:27.896Z,1695057027.896 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:10:59.020Z,1695057059.020 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:11:30.144Z,1695057090.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:12:01.268Z,1695057121.268 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:12:32.392Z,1695057152.392 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:13:03.516Z,1695057183.516 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:13:34.652Z,1695057214.652 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:14:05.780Z,1695057245.780 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:14:36.912Z,1695057276.912 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:15:08.044Z,1695057308.044 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:15:27.471Z,1695057327.471 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-09-18T17:15:27.474Z,1695057327.474 [BPC1](INFO): Received data from all battery sticks. 2023-09-18T17:15:39.168Z,1695057339.168 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:16:10.292Z,1695057370.292 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:16:41.432Z,1695057401.432 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:17:12.556Z,1695057432.556 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:17:43.696Z,1695057463.696 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:18:14.840Z,1695057494.840 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:18:45.968Z,1695057525.968 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:19:17.093Z,1695057557.093 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:19:48.216Z,1695057588.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:20:19.340Z,1695057619.340 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:20:50.464Z,1695057650.464 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:21:21.592Z,1695057681.592 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:21:52.721Z,1695057712.721 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:22:23.845Z,1695057743.845 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:22:54.968Z,1695057774.968 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:23:26.100Z,1695057806.100 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:23:57.225Z,1695057837.225 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:24:28.348Z,1695057868.348 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:24:59.472Z,1695057899.472 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:25:30.596Z,1695057930.596 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:26:01.720Z,1695057961.720 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:26:32.844Z,1695057992.844 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:27:03.968Z,1695058023.968 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:27:35.092Z,1695058055.092 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:28:06.216Z,1695058086.216 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:28:37.340Z,1695058117.340 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:29:08.465Z,1695058148.465 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T17:29:12.044Z,1695058152.044 [NAL9602](INFO): SBD MO Status=2, MOMSN=11581, MT Status=2, MTMSN=0 2023-09-18T17:29:12.044Z,1695058152.044 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-18T17:29:38.772Z,1695058178.772 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003040 2023-09-18T17:29:51.847Z,1695058191.847 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230918T152633/Courier0003.lzma 2023-09-18T17:29:52.850Z,1695058192.850 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0003.lzma.bak 2023-09-18T17:29:52.850Z,1695058192.850 [DataOverHttps](INFO): SBD MOMSN=18782083 2023-09-18T17:30:03.894Z,1695058203.894 [NAL9602](INFO): SBD MO Status=2, MOMSN=11581, MT Status=2, MTMSN=0 2023-09-18T17:30:03.894Z,1695058203.894 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-18T17:30:11.035Z,1695058211.035 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230918T152633/Express0004.lzma 2023-09-18T17:30:12.038Z,1695058212.038 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0004.lzma.bak 2023-09-18T17:30:12.038Z,1695058212.038 [DataOverHttps](INFO): SBD MOMSN=18782087 2023-09-18T17:30:15.619Z,1695058215.619 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T17:30:15.619Z,1695058215.619 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T17:30:15.619Z,1695058215.619 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T17:30:34.611Z,1695058234.611 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T17:35:16.203Z,1695058516.203 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T17:35:16.204Z,1695058516.204 [Default:CheckIn:C.Wait] Stopped 2023-09-18T17:35:16.204Z,1695058516.204 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T17:35:16.204Z,1695058516.204 [Default:CheckIn:D] Running Loop=1 2023-09-18T17:35:16.613Z,1695058516.613 [Default:CheckIn:D] Stopped 2023-09-18T17:35:16.613Z,1695058516.613 [Default:CheckIn:E] Running Loop=1 2023-09-18T17:35:17.029Z,1695058517.029 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 176.762712 min 2023-09-18T17:35:17.030Z,1695058517.030 [Default:CheckIn:E] Stopped 2023-09-18T17:35:17.030Z,1695058517.030 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T17:35:17.030Z,1695058517.030 [Default:CheckIn] Stopped 2023-09-18T17:35:17.030Z,1695058517.030 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T17:35:17.030Z,1695058517.030 [Default:CheckIn](INFO): Running loop #12 2023-09-18T17:35:17.030Z,1695058517.030 [Default:CheckIn] Running Loop=12 2023-09-18T17:35:17.030Z,1695058517.030 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T17:35:17.030Z,1695058517.030 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T17:35:19.027Z,1695058519.027 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173514.00,A,4149.09015,N,08106.30752,W,0.214,0.00,180923,,,A*76 2023-09-18T17:35:19.029Z,1695058519.029 [NAL9602](INFO): GPS fix at 20230918T173514: (41.818169, -81.105125) 2023-09-18T17:35:19.040Z,1695058519.040 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T17:35:19.040Z,1695058519.040 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T17:35:27.899Z,1695058527.899 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230918T152633/Courier0006.lzma 2023-09-18T17:35:28.902Z,1695058528.902 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0006.lzma.bak 2023-09-18T17:35:28.902Z,1695058528.902 [DataOverHttps](INFO): SBD MOMSN=18782099 2023-09-18T17:35:41.669Z,1695058541.669 [NAL9602](INFO): SBD MO Status=1, MOMSN=11581, MT Status=0, MTMSN=0 2023-09-18T17:35:41.669Z,1695058541.669 [NAL9602](INFO): No messages in MT queue 2023-09-18T17:35:53.408Z,1695058553.408 [DataOverHttps](INFO): Sending 232 bytes from file Logs/20230918T152633/Express0007.lzma 2023-09-18T17:35:54.410Z,1695058554.410 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0007.lzma.bak 2023-09-18T17:35:54.410Z,1695058554.410 [DataOverHttps](INFO): SBD MOMSN=18782102 2023-09-18T17:35:57.855Z,1695058557.855 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T17:35:57.875Z,1695058557.875 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T17:35:57.875Z,1695058557.875 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T17:36:12.392Z,1695058572.392 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T17:40:58.562Z,1695058858.562 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T17:40:58.562Z,1695058858.562 [Default:CheckIn:C.Wait] Stopped 2023-09-18T17:40:58.562Z,1695058858.562 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T17:40:58.563Z,1695058858.563 [Default:CheckIn:D] Running Loop=1 2023-09-18T17:40:58.955Z,1695058858.955 [Default:CheckIn:D] Stopped 2023-09-18T17:40:58.955Z,1695058858.955 [Default:CheckIn:E] Running Loop=1 2023-09-18T17:40:59.369Z,1695058859.369 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 182.468408 min 2023-09-18T17:40:59.370Z,1695058859.370 [Default:CheckIn:E] Stopped 2023-09-18T17:40:59.370Z,1695058859.370 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T17:40:59.370Z,1695058859.370 [Default:CheckIn] Stopped 2023-09-18T17:40:59.370Z,1695058859.370 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T17:40:59.370Z,1695058859.370 [Default:CheckIn](INFO): Running loop #13 2023-09-18T17:40:59.370Z,1695058859.370 [Default:CheckIn] Running Loop=13 2023-09-18T17:40:59.370Z,1695058859.370 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T17:40:59.370Z,1695058859.370 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T17:41:01.377Z,1695058861.377 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174056.00,A,4149.09011,N,08106.30638,W,0.039,0.00,180923,,,A*76 2023-09-18T17:41:01.379Z,1695058861.379 [NAL9602](INFO): GPS fix at 20230918T174056: (41.818168, -81.105106) 2023-09-18T17:41:01.390Z,1695058861.390 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T17:41:01.390Z,1695058861.390 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T17:41:10.375Z,1695058870.375 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230918T152633/Courier0009.lzma 2023-09-18T17:41:11.378Z,1695058871.378 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0009.lzma.bak 2023-09-18T17:41:11.378Z,1695058871.378 [DataOverHttps](INFO): SBD MOMSN=18782115 2023-09-18T17:41:31.677Z,1695058891.677 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20230918T152633/Express0010.lzma 2023-09-18T17:41:32.678Z,1695058892.678 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0010.lzma.bak 2023-09-18T17:41:32.678Z,1695058892.678 [DataOverHttps](INFO): SBD MOMSN=18782118 2023-09-18T17:41:36.150Z,1695058896.150 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T17:41:36.150Z,1695058896.150 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T17:41:36.150Z,1695058896.150 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T17:42:36.795Z,1695058956.795 [NAL9602](INFO): SBD MO Status=2, MOMSN=11582, MT Status=2, MTMSN=0 2023-09-18T17:42:36.795Z,1695058956.795 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-18T17:42:53.771Z,1695058973.771 [NAL9602](INFO): SBD MO Status=2, MOMSN=11582, MT Status=2, MTMSN=0 2023-09-18T17:42:53.771Z,1695058973.771 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-18T17:43:15.592Z,1695058995.592 [NAL9602](INFO): SBD MO Status=1, MOMSN=11582, MT Status=0, MTMSN=0 2023-09-18T17:43:15.592Z,1695058995.592 [NAL9602](INFO): No messages in MT queue 2023-09-18T17:43:46.297Z,1695059026.297 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T17:46:36.803Z,1695059196.803 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T17:46:36.803Z,1695059196.803 [Default:CheckIn:C.Wait] Stopped 2023-09-18T17:46:36.803Z,1695059196.803 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T17:46:36.804Z,1695059196.804 [Default:CheckIn:D] Running Loop=1 2023-09-18T17:46:37.202Z,1695059197.202 [Default:CheckIn:D] Stopped 2023-09-18T17:46:37.202Z,1695059197.202 [Default:CheckIn:E] Running Loop=1 2023-09-18T17:46:37.615Z,1695059197.615 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 188.105859 min 2023-09-18T17:46:37.615Z,1695059197.615 [Default:CheckIn:E] Stopped 2023-09-18T17:46:37.624Z,1695059197.624 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T17:46:37.624Z,1695059197.624 [Default:CheckIn] Stopped 2023-09-18T17:46:37.624Z,1695059197.624 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T17:46:37.624Z,1695059197.624 [Default:CheckIn](INFO): Running loop #14 2023-09-18T17:46:37.624Z,1695059197.624 [Default:CheckIn] Running Loop=14 2023-09-18T17:46:37.624Z,1695059197.624 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T17:46:37.624Z,1695059197.624 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T17:46:39.621Z,1695059199.621 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174635.00,A,4149.08840,N,08106.30348,W,0.078,0.00,180923,,,A*7F 2023-09-18T17:46:39.623Z,1695059199.623 [NAL9602](INFO): GPS fix at 20230918T174635: (41.818140, -81.105058) 2023-09-18T17:46:39.641Z,1695059199.641 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T17:46:39.641Z,1695059199.641 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T17:46:51.708Z,1695059211.708 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230918T152633/Courier0012.lzma 2023-09-18T17:46:52.710Z,1695059212.710 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0012.lzma.bak 2023-09-18T17:46:52.710Z,1695059212.710 [DataOverHttps](INFO): SBD MOMSN=18782124 2023-09-18T17:47:00.303Z,1695059220.303 [NAL9602](INFO): SBD MO Status=1, MOMSN=11583, MT Status=0, MTMSN=0 2023-09-18T17:47:00.303Z,1695059220.303 [NAL9602](INFO): No messages in MT queue 2023-09-18T17:47:11.027Z,1695059231.027 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230918T152633/Express0013.lzma 2023-09-18T17:47:12.031Z,1695059232.031 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0013.lzma.bak 2023-09-18T17:47:12.031Z,1695059232.031 [DataOverHttps](INFO): SBD MOMSN=18782127 2023-09-18T17:47:15.712Z,1695059235.712 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T17:47:15.858Z,1695059235.858 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T17:47:15.858Z,1695059235.858 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T17:47:31.003Z,1695059251.003 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T17:52:16.255Z,1695059536.255 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T17:52:16.255Z,1695059536.255 [Default:CheckIn:C.Wait] Stopped 2023-09-18T17:52:16.255Z,1695059536.255 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T17:52:16.255Z,1695059536.255 [Default:CheckIn:D] Running Loop=1 2023-09-18T17:52:16.646Z,1695059536.646 [Default:CheckIn:D] Stopped 2023-09-18T17:52:16.646Z,1695059536.646 [Default:CheckIn:E] Running Loop=1 2023-09-18T17:52:17.062Z,1695059537.062 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 193.763281 min 2023-09-18T17:52:17.062Z,1695059537.062 [Default:CheckIn:E] Stopped 2023-09-18T17:52:17.063Z,1695059537.063 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T17:52:17.063Z,1695059537.063 [Default:CheckIn] Stopped 2023-09-18T17:52:17.063Z,1695059537.063 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T17:52:17.063Z,1695059537.063 [Default:CheckIn](INFO): Running loop #15 2023-09-18T17:52:17.063Z,1695059537.063 [Default:CheckIn] Running Loop=15 2023-09-18T17:52:17.063Z,1695059537.063 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T17:52:17.063Z,1695059537.063 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T17:52:19.069Z,1695059539.069 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175214.00,A,4149.08970,N,08106.30400,W,0.058,0.00,180923,,,A*72 2023-09-18T17:52:19.072Z,1695059539.072 [NAL9602](INFO): GPS fix at 20230918T175214: (41.818162, -81.105067) 2023-09-18T17:52:19.082Z,1695059539.082 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T17:52:19.082Z,1695059539.082 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T17:52:28.031Z,1695059548.031 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230918T152633/Courier0015.lzma 2023-09-18T17:52:29.034Z,1695059549.034 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0015.lzma.bak 2023-09-18T17:52:29.034Z,1695059549.034 [DataOverHttps](INFO): SBD MOMSN=18782138 2023-09-18T17:52:34.819Z,1695059554.819 [NAL9602](INFO): SBD MO Status=2, MOMSN=11584, MT Status=2, MTMSN=0 2023-09-18T17:52:34.819Z,1695059554.819 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-18T17:52:54.619Z,1695059574.619 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230918T152633/Express0016.lzma 2023-09-18T17:52:55.465Z,1695059575.465 [NAL9602](INFO): SBD MO Status=2, MOMSN=11584, MT Status=2, MTMSN=0 2023-09-18T17:52:55.465Z,1695059575.465 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-18T17:52:56.626Z,1695059576.626 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0016.lzma.bak 2023-09-18T17:52:56.626Z,1695059576.626 [DataOverHttps](INFO): SBD MOMSN=18782141 2023-09-18T17:53:00.349Z,1695059580.349 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T17:53:00.349Z,1695059580.349 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T17:53:00.349Z,1695059580.349 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T17:53:21.317Z,1695059601.317 [NAL9602](INFO): SBD MO Status=2, MOMSN=11584, MT Status=2, MTMSN=0 2023-09-18T17:53:21.317Z,1695059601.317 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-18T17:53:38.690Z,1695059618.690 [NAL9602](INFO): SBD MO Status=2, MOMSN=11584, MT Status=2, MTMSN=0 2023-09-18T17:53:38.690Z,1695059618.690 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-18T17:53:57.715Z,1695059637.715 [NAL9602](INFO): SBD MO Status=1, MOMSN=11584, MT Status=0, MTMSN=0 2023-09-18T17:53:57.716Z,1695059637.716 [NAL9602](INFO): No messages in MT queue 2023-09-18T17:54:28.417Z,1695059668.417 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T17:58:00.958Z,1695059880.958 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T17:58:00.958Z,1695059880.958 [Default:CheckIn:C.Wait] Stopped 2023-09-18T17:58:00.958Z,1695059880.958 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T17:58:00.958Z,1695059880.958 [Default:CheckIn:D] Running Loop=1 2023-09-18T17:58:01.350Z,1695059881.350 [Default:CheckIn:D] Stopped 2023-09-18T17:58:01.350Z,1695059881.350 [Default:CheckIn:E] Running Loop=1 2023-09-18T17:58:01.755Z,1695059881.755 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 199.508333 min 2023-09-18T17:58:01.755Z,1695059881.755 [Default:CheckIn:E] Stopped 2023-09-18T17:58:01.755Z,1695059881.755 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T17:58:01.756Z,1695059881.756 [Default:CheckIn] Stopped 2023-09-18T17:58:01.756Z,1695059881.756 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T17:58:01.756Z,1695059881.756 [Default:CheckIn](INFO): Running loop #16 2023-09-18T17:58:01.756Z,1695059881.756 [Default:CheckIn] Running Loop=16 2023-09-18T17:58:01.756Z,1695059881.756 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T17:58:01.756Z,1695059881.756 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T17:58:03.763Z,1695059883.763 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175759.00,A,4149.08749,N,08106.30737,W,0.136,0.00,180923,,,A*74 2023-09-18T17:58:03.766Z,1695059883.766 [NAL9602](INFO): GPS fix at 20230918T175759: (41.818125, -81.105123) 2023-09-18T17:58:03.776Z,1695059883.776 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T17:58:03.776Z,1695059883.776 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T17:58:12.011Z,1695059892.011 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230918T152633/Courier0018.lzma 2023-09-18T17:58:13.014Z,1695059893.014 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0018.lzma.bak 2023-09-18T17:58:13.014Z,1695059893.014 [DataOverHttps](INFO): SBD MOMSN=18782153 2023-09-18T17:58:31.331Z,1695059911.331 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230918T152633/Express0019.lzma 2023-09-18T17:58:32.334Z,1695059912.334 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0019.lzma.bak 2023-09-18T17:58:32.334Z,1695059912.334 [DataOverHttps](INFO): SBD MOMSN=18782156 2023-09-18T17:58:35.712Z,1695059915.712 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T17:58:35.713Z,1695059915.713 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T17:58:35.713Z,1695059915.713 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T17:58:40.557Z,1695059920.557 [NAL9602](INFO): SBD MO Status=2, MOMSN=11585, MT Status=2, MTMSN=0 2023-09-18T17:58:40.558Z,1695059920.558 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-18T18:00:01.811Z,1695060001.811 [NAL9602](INFO): SBD MO Status=1, MOMSN=11585, MT Status=0, MTMSN=0 2023-09-18T18:00:01.812Z,1695060001.812 [NAL9602](INFO): No messages in MT queue 2023-09-18T18:00:32.515Z,1695060032.515 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T18:03:36.356Z,1695060216.356 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T18:03:36.356Z,1695060216.356 [Default:CheckIn:C.Wait] Stopped 2023-09-18T18:03:36.356Z,1695060216.356 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T18:03:36.356Z,1695060216.356 [Default:CheckIn:D] Running Loop=1 2023-09-18T18:03:36.774Z,1695060216.774 [Default:CheckIn:D] Stopped 2023-09-18T18:03:36.774Z,1695060216.774 [Default:CheckIn:E] Running Loop=1 2023-09-18T18:03:37.158Z,1695060217.158 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 205.098730 min 2023-09-18T18:03:37.158Z,1695060217.158 [Default:CheckIn:E] Stopped 2023-09-18T18:03:37.158Z,1695060217.158 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T18:03:37.159Z,1695060217.159 [Default:CheckIn] Stopped 2023-09-18T18:03:37.159Z,1695060217.159 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T18:03:37.159Z,1695060217.159 [Default:CheckIn](INFO): Running loop #17 2023-09-18T18:03:37.159Z,1695060217.159 [Default:CheckIn] Running Loop=17 2023-09-18T18:03:37.159Z,1695060217.159 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T18:03:37.159Z,1695060217.159 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T18:03:39.162Z,1695060219.162 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180334.00,A,4149.09004,N,08106.29965,W,0.097,0.00,180923,,,A*75 2023-09-18T18:03:39.172Z,1695060219.172 [NAL9602](INFO): GPS fix at 20230918T180334: (41.818167, -81.104994) 2023-09-18T18:03:39.183Z,1695060219.183 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T18:03:39.183Z,1695060219.183 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T18:03:50.223Z,1695060230.223 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20230918T152633/Courier0021.lzma 2023-09-18T18:03:51.226Z,1695060231.226 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0021.lzma.bak 2023-09-18T18:03:51.226Z,1695060231.226 [DataOverHttps](INFO): SBD MOMSN=18782169 2023-09-18T18:04:11.515Z,1695060251.515 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20230918T152633/Express0022.lzma 2023-09-18T18:04:12.518Z,1695060252.518 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0022.lzma.bak 2023-09-18T18:04:12.518Z,1695060252.518 [DataOverHttps](INFO): SBD MOMSN=18782172 2023-09-18T18:04:16.025Z,1695060256.025 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T18:04:16.025Z,1695060256.025 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T18:04:16.025Z,1695060256.025 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T18:04:44.708Z,1695060284.708 [NAL9602](INFO): SBD MO Status=2, MOMSN=11586, MT Status=2, MTMSN=0 2023-09-18T18:04:44.708Z,1695060284.708 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-18T18:05:43.675Z,1695060343.675 [NAL9602](INFO): SBD MO Status=2, MOMSN=11586, MT Status=2, MTMSN=0 2023-09-18T18:05:43.675Z,1695060343.675 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-18T18:06:47.538Z,1695060407.538 [NAL9602](INFO): SBD MO Status=1, MOMSN=11586, MT Status=0, MTMSN=0 2023-09-18T18:06:47.538Z,1695060407.538 [NAL9602](INFO): No messages in MT queue 2023-09-18T18:07:18.216Z,1695060438.216 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T18:09:16.641Z,1695060556.641 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T18:09:16.641Z,1695060556.641 [Default:CheckIn:C.Wait] Stopped 2023-09-18T18:09:16.641Z,1695060556.641 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T18:09:16.642Z,1695060556.642 [Default:CheckIn:D] Running Loop=1 2023-09-18T18:09:17.076Z,1695060557.076 [Default:CheckIn:D] Stopped 2023-09-18T18:09:17.076Z,1695060557.076 [Default:CheckIn:E] Running Loop=1 2023-09-18T18:09:17.485Z,1695060557.485 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 210.770231 min 2023-09-18T18:09:17.485Z,1695060557.485 [Default:CheckIn:E] Stopped 2023-09-18T18:09:17.485Z,1695060557.485 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T18:09:17.485Z,1695060557.485 [Default:CheckIn] Stopped 2023-09-18T18:09:17.485Z,1695060557.485 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T18:09:17.485Z,1695060557.485 [Default:CheckIn](INFO): Running loop #18 2023-09-18T18:09:17.485Z,1695060557.485 [Default:CheckIn] Running Loop=18 2023-09-18T18:09:17.485Z,1695060557.485 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T18:09:17.486Z,1695060557.486 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T18:09:19.410Z,1695060559.410 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180914.00,A,4149.09178,N,08106.30005,W,0.156,0.00,180923,,,A*7C 2023-09-18T18:09:19.412Z,1695060559.412 [NAL9602](INFO): GPS fix at 20230918T180914: (41.818196, -81.105001) 2023-09-18T18:09:19.438Z,1695060559.438 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T18:09:19.439Z,1695060559.439 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T18:09:27.907Z,1695060567.907 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230918T152633/Courier0024.lzma 2023-09-18T18:09:28.910Z,1695060568.910 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0024.lzma.bak 2023-09-18T18:09:28.910Z,1695060568.910 [DataOverHttps](INFO): SBD MOMSN=18782183 2023-09-18T18:09:50.943Z,1695060590.943 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230918T152633/Express0025.lzma 2023-09-18T18:09:51.946Z,1695060591.946 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0025.lzma.bak 2023-09-18T18:09:51.946Z,1695060591.946 [DataOverHttps](INFO): SBD MOMSN=18782186 2023-09-18T18:09:55.483Z,1695060595.483 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T18:09:55.488Z,1695060595.488 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T18:09:55.488Z,1695060595.488 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T18:10:13.641Z,1695060613.641 [NAL9602](INFO): SBD MO Status=1, MOMSN=11587, MT Status=0, MTMSN=0 2023-09-18T18:10:13.641Z,1695060613.641 [NAL9602](INFO): No messages in MT queue 2023-09-18T18:10:44.410Z,1695060644.410 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T18:14:56.198Z,1695060896.198 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T18:14:56.199Z,1695060896.199 [Default:CheckIn:C.Wait] Stopped 2023-09-18T18:14:56.199Z,1695060896.199 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T18:14:56.199Z,1695060896.199 [Default:CheckIn:D] Running Loop=1 2023-09-18T18:14:56.608Z,1695060896.608 [Default:CheckIn:D] Stopped 2023-09-18T18:14:56.609Z,1695060896.609 [Default:CheckIn:E] Running Loop=1 2023-09-18T18:14:57.026Z,1695060897.026 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 216.429313 min 2023-09-18T18:14:57.026Z,1695060897.026 [Default:CheckIn:E] Stopped 2023-09-18T18:14:57.026Z,1695060897.026 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T18:14:57.026Z,1695060897.026 [Default:CheckIn] Stopped 2023-09-18T18:14:57.026Z,1695060897.026 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T18:14:57.026Z,1695060897.026 [Default:CheckIn](INFO): Running loop #19 2023-09-18T18:14:57.026Z,1695060897.026 [Default:CheckIn] Running Loop=19 2023-09-18T18:14:57.027Z,1695060897.027 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T18:14:57.027Z,1695060897.027 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T18:14:59.018Z,1695060899.018 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181454.00,A,4149.08277,N,08106.30224,W,0.272,227.87,180923,,,A*75 2023-09-18T18:14:59.020Z,1695060899.020 [NAL9602](INFO): GPS fix at 20230918T181454: (41.818046, -81.105037) 2023-09-18T18:14:59.031Z,1695060899.031 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T18:14:59.031Z,1695060899.031 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T18:15:13.220Z,1695060913.220 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T18:15:44.368Z,1695060944.368 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T18:16:15.493Z,1695060975.493 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T18:16:46.628Z,1695061006.628 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T18:17:17.753Z,1695061037.753 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T18:17:19.761Z,1695061039.761 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.151520 2023-09-18T18:17:50.884Z,1695061070.884 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T18:18:22.024Z,1695061102.024 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T18:18:53.148Z,1695061133.148 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T18:19:24.740Z,1695061164.740 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003032 2023-09-18T18:19:34.063Z,1695061174.063 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230918T152633/Courier0027.lzma 2023-09-18T18:19:35.066Z,1695061175.066 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0027.lzma.bak 2023-09-18T18:19:35.066Z,1695061175.066 [DataOverHttps](INFO): SBD MOMSN=18782191 2023-09-18T18:20:01.344Z,1695061201.344 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T18:20:08.808Z,1695061208.808 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20230918T152633/Express0028.lzma 2023-09-18T18:20:09.818Z,1695061209.818 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0028.lzma.bak 2023-09-18T18:20:09.818Z,1695061209.818 [DataOverHttps](INFO): SBD MOMSN=18782194 2023-09-18T18:20:13.909Z,1695061213.909 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T18:20:13.909Z,1695061213.909 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T18:20:13.909Z,1695061213.909 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T18:20:32.037Z,1695061232.037 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T18:23:05.958Z,1695061385.958 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-09-18T18:23:05.960Z,1695061385.960 [BPC1](INFO): Received data from all battery sticks. 2023-09-18T18:25:14.434Z,1695061514.434 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T18:25:14.434Z,1695061514.434 [Default:CheckIn:C.Wait] Stopped 2023-09-18T18:25:14.434Z,1695061514.434 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T18:25:14.434Z,1695061514.434 [Default:CheckIn:D] Running Loop=1 2023-09-18T18:25:14.846Z,1695061514.846 [Default:CheckIn:D] Stopped 2023-09-18T18:25:14.847Z,1695061514.847 [Default:CheckIn:E] Running Loop=1 2023-09-18T18:25:15.243Z,1695061515.243 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 226.733268 min 2023-09-18T18:25:15.243Z,1695061515.243 [Default:CheckIn:E] Stopped 2023-09-18T18:25:15.243Z,1695061515.243 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T18:25:15.243Z,1695061515.243 [Default:CheckIn] Stopped 2023-09-18T18:25:15.244Z,1695061515.244 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T18:25:15.244Z,1695061515.244 [Default:CheckIn](INFO): Running loop #20 2023-09-18T18:25:15.244Z,1695061515.244 [Default:CheckIn] Running Loop=20 2023-09-18T18:25:15.244Z,1695061515.244 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T18:25:15.244Z,1695061515.244 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T18:25:17.266Z,1695061517.266 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182512.00,A,4147.16867,N,08107.80195,W,18.253,253.22,180923,,,A*4A 2023-09-18T18:25:17.269Z,1695061517.269 [NAL9602](INFO): GPS fix at 20230918T182512: (41.786144, -81.130032) 2023-09-18T18:25:17.284Z,1695061517.284 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T18:25:17.284Z,1695061517.284 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T18:25:25.511Z,1695061525.511 [DataOverHttps](INFO): Sending 90 bytes from file Logs/20230918T152633/Courier0030.lzma 2023-09-18T18:25:28.577Z,1695061528.577 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0030.lzma.bak 2023-09-18T18:25:28.577Z,1695061528.577 [DataOverHttps](INFO): SBD MOMSN=18782203 2023-09-18T18:25:48.075Z,1695061548.075 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-18T18:25:48.152Z,1695061548.152 [NAL9602](ERROR): received: +CSQ:0 OK587, 0, 0, 0, 0 OK 2023-09-18T18:25:49.444Z,1695061549.444 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20230918T152633/Express0031.lzma 2023-09-18T18:25:50.446Z,1695061550.446 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0031.lzma.bak 2023-09-18T18:25:50.446Z,1695061550.446 [DataOverHttps](INFO): SBD MOMSN=18782206 2023-09-18T18:25:53.785Z,1695061553.785 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T18:25:53.785Z,1695061553.785 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T18:25:53.785Z,1695061553.785 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T18:30:19.022Z,1695061819.022 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T18:30:49.778Z,1695061849.778 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T18:30:54.230Z,1695061854.230 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T18:30:54.230Z,1695061854.230 [Default:CheckIn:C.Wait] Stopped 2023-09-18T18:30:54.230Z,1695061854.230 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T18:30:54.231Z,1695061854.231 [Default:CheckIn:D] Running Loop=1 2023-09-18T18:30:54.643Z,1695061854.643 [Default:CheckIn:D] Stopped 2023-09-18T18:30:54.643Z,1695061854.643 [Default:CheckIn:E] Running Loop=1 2023-09-18T18:30:55.039Z,1695061855.039 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 232.396549 min 2023-09-18T18:30:55.039Z,1695061855.039 [Default:CheckIn:E] Stopped 2023-09-18T18:30:55.040Z,1695061855.040 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T18:30:55.040Z,1695061855.040 [Default:CheckIn] Stopped 2023-09-18T18:30:55.040Z,1695061855.040 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T18:30:55.040Z,1695061855.040 [Default:CheckIn](INFO): Running loop #21 2023-09-18T18:30:55.040Z,1695061855.040 [Default:CheckIn] Running Loop=21 2023-09-18T18:30:55.040Z,1695061855.040 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T18:30:55.040Z,1695061855.040 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T18:30:57.046Z,1695061857.046 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183052.00,A,4145.36860,N,08111.79216,W,42.765,238.14,180923,,,A*43 2023-09-18T18:30:57.049Z,1695061857.049 [NAL9602](INFO): GPS fix at 20230918T183052: (41.756143, -81.196536) 2023-09-18T18:30:57.059Z,1695061857.059 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T18:30:57.059Z,1695061857.059 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T18:31:05.999Z,1695061865.999 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20230918T152633/Courier0033.lzma 2023-09-18T18:31:07.002Z,1695061867.002 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0033.lzma.bak 2023-09-18T18:31:07.002Z,1695061867.002 [DataOverHttps](INFO): SBD MOMSN=18782216 2023-09-18T18:31:25.220Z,1695061885.220 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20230918T152633/Express0034.lzma 2023-09-18T18:31:26.222Z,1695061886.222 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0034.lzma.bak 2023-09-18T18:31:26.222Z,1695061886.222 [DataOverHttps](INFO): SBD MOMSN=18782219 2023-09-18T18:31:27.745Z,1695061887.745 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-18T18:31:27.820Z,1695061887.820 [NAL9602](ERROR): received: +CSQ:0 OK587, 0, 0, 0, 0 OK 2023-09-18T18:31:29.810Z,1695061889.810 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T18:31:29.811Z,1695061889.811 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T18:31:29.811Z,1695061889.811 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T18:35:44.193Z,1695062144.193 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-09-18T18:35:59.367Z,1695062159.367 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T18:36:15.780Z,1695062175.780 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-09-18T18:36:30.064Z,1695062190.064 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T18:36:30.479Z,1695062190.479 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T18:36:30.479Z,1695062190.479 [Default:CheckIn:C.Wait] Stopped 2023-09-18T18:36:30.479Z,1695062190.479 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T18:36:30.479Z,1695062190.479 [Default:CheckIn:D] Running Loop=1 2023-09-18T18:36:30.890Z,1695062190.890 [Default:CheckIn:D] Stopped 2023-09-18T18:36:30.890Z,1695062190.890 [Default:CheckIn:E] Running Loop=1 2023-09-18T18:36:31.312Z,1695062191.312 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 238.000667 min 2023-09-18T18:36:31.312Z,1695062191.312 [Default:CheckIn:E] Stopped 2023-09-18T18:36:31.313Z,1695062191.313 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T18:36:31.313Z,1695062191.313 [Default:CheckIn] Stopped 2023-09-18T18:36:31.313Z,1695062191.313 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T18:36:31.313Z,1695062191.313 [Default:CheckIn](INFO): Running loop #22 2023-09-18T18:36:31.313Z,1695062191.313 [Default:CheckIn] Running Loop=22 2023-09-18T18:36:31.313Z,1695062191.313 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T18:36:31.313Z,1695062191.313 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T18:36:33.298Z,1695062193.298 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183628.00,A,4143.21170,N,08117.38246,W,51.745,249.98,180923,,,A*44 2023-09-18T18:36:33.300Z,1695062193.300 [NAL9602](INFO): GPS fix at 20230918T183628: (41.720195, -81.289708) 2023-09-18T18:36:33.311Z,1695062193.311 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T18:36:33.311Z,1695062193.311 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T18:37:04.004Z,1695062224.004 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-18T18:37:04.084Z,1695062224.084 [NAL9602](ERROR): received: +CSQ:0 OK587, 0, 0, 0, 0 OK 2023-09-18T18:37:49.483Z,1695062269.483 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230918T152633/Courier0036.lzma 2023-09-18T18:37:50.484Z,1695062270.484 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-09-18T18:37:58.003Z,1695062278.003 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=184.481079 2023-09-18T18:38:13.537Z,1695062293.537 [CBIT](IMPORTANT): Beginning ground fault scan 2023-09-18T18:38:24.435Z,1695062304.435 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.000791 CHAN A1 (24V): 0.014152 CHAN A2 (12V): -0.000855 CHAN A3 (5V): -0.002375 CHAN B0 (3.3V): -0.001534 CHAN B1 (3.15aV): -0.001999 CHAN B2 (3.15bV): -0.001714 CHAN B3 (GND): -0.001995 OPEN: 0.002196 Full Scale: +/- 1 mA 2023-09-18T18:38:28.732Z,1695062308.732 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-09-18T18:39:30.992Z,1695062370.992 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T18:40:02.564Z,1695062402.564 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-09-18T18:40:34.168Z,1695062434.168 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-09-18T18:41:35.099Z,1695062495.099 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T18:41:36.460Z,1695062496.460 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T18:42:07.588Z,1695062527.588 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T18:42:39.050Z,1695062559.050 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-09-18T18:43:28.266Z,1695062608.266 [DVL_micro](ERROR): Failed to parse: :BI,-00117,-00152,-00066,+00000,I 2023-09-18T18:43:41.340Z,1695062621.340 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T18:44:12.476Z,1695062652.476 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T18:44:18.351Z,1695062658.351 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,0000000000000079,+00000,I 2023-09-18T18:44:18.351Z,1695062658.351 [DVL_micro](ERROR): Failed to parse: :TS,0000000000000079,+00000,I 2023-09-18T18:44:43.760Z,1695062683.760 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-09-18T18:46:17.136Z,1695062777.136 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T18:46:42.668Z,1695062802.668 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003042 2023-09-18T18:46:53.779Z,1695062813.779 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230918T152633/Courier0036.lzma 2023-09-18T18:47:02.231Z,1695062822.231 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230918T152633/Courier0036.lzma 2023-09-18T18:47:10.779Z,1695062830.779 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230918T152633/Courier0036.lzma 2023-09-18T18:47:13.672Z,1695062833.672 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T18:47:21.255Z,1695062841.255 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230918T152633/Courier0036.lzma 2023-09-18T18:47:29.731Z,1695062849.731 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230918T152633/Courier0036.lzma 2023-09-18T18:47:39.803Z,1695062859.803 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230918T152633/Courier0036.lzma 2023-09-18T18:47:50.355Z,1695062870.355 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230918T152633/Courier0036.lzma 2023-09-18T18:47:53.367Z,1695062873.367 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0036.lzma.bak 2023-09-18T18:47:53.367Z,1695062873.367 [DataOverHttps](INFO): SBD MOMSN=18782224 2023-09-18T18:48:38.837Z,1695062918.837 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20230918T152633/Express0037.lzma 2023-09-18T18:48:39.838Z,1695062919.838 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0037.lzma.bak 2023-09-18T18:48:39.838Z,1695062919.838 [DataOverHttps](INFO): SBD MOMSN=18782231 2023-09-18T18:48:45.610Z,1695062925.610 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T18:48:45.610Z,1695062925.610 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T18:48:45.610Z,1695062925.610 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T18:53:46.221Z,1695063226.221 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T18:53:46.221Z,1695063226.221 [Default:CheckIn:C.Wait] Stopped 2023-09-18T18:53:46.221Z,1695063226.221 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T18:53:46.221Z,1695063226.221 [Default:CheckIn:D] Running Loop=1 2023-09-18T18:53:46.607Z,1695063226.607 [Default:CheckIn:D] Stopped 2023-09-18T18:53:46.607Z,1695063226.607 [Default:CheckIn:E] Running Loop=1 2023-09-18T18:53:47.039Z,1695063227.039 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 255.262614 min 2023-09-18T18:53:47.039Z,1695063227.039 [Default:CheckIn:E] Stopped 2023-09-18T18:53:47.040Z,1695063227.040 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T18:53:47.040Z,1695063227.040 [Default:CheckIn] Stopped 2023-09-18T18:53:47.040Z,1695063227.040 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T18:53:47.040Z,1695063227.040 [Default:CheckIn](INFO): Running loop #23 2023-09-18T18:53:47.040Z,1695063227.040 [Default:CheckIn] Running Loop=23 2023-09-18T18:53:47.040Z,1695063227.040 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T18:53:47.040Z,1695063227.040 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T18:53:49.015Z,1695063229.015 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185344.00,A,4134.01810,N,08134.71890,W,61.076,249.06,180923,,,A*4E 2023-09-18T18:53:49.017Z,1695063229.017 [NAL9602](INFO): GPS fix at 20230918T185344: (41.566968, -81.578648) 2023-09-18T18:53:49.028Z,1695063229.028 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T18:53:49.028Z,1695063229.028 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T18:53:57.875Z,1695063237.875 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20230918T152633/Courier0039.lzma 2023-09-18T18:53:58.878Z,1695063238.878 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0039.lzma.bak 2023-09-18T18:53:58.878Z,1695063238.878 [DataOverHttps](INFO): SBD MOMSN=18782355 2023-09-18T18:54:16.956Z,1695063256.956 [DataOverHttps](INFO): Sending 418 bytes from file Logs/20230918T152633/Express0040.lzma 2023-09-18T18:54:17.958Z,1695063257.958 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0040.lzma.bak 2023-09-18T18:54:17.958Z,1695063257.958 [DataOverHttps](INFO): SBD MOMSN=18782361 2023-09-18T18:54:21.389Z,1695063261.389 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T18:54:21.389Z,1695063261.389 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T18:54:21.389Z,1695063261.389 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T18:58:51.373Z,1695063531.373 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T18:59:22.069Z,1695063562.069 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T18:59:22.108Z,1695063562.108 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T18:59:22.108Z,1695063562.108 [Default:CheckIn:C.Wait] Stopped 2023-09-18T18:59:22.108Z,1695063562.108 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T18:59:22.108Z,1695063562.108 [Default:CheckIn:D] Running Loop=1 2023-09-18T18:59:22.520Z,1695063562.520 [Default:CheckIn:D] Stopped 2023-09-18T18:59:22.521Z,1695063562.521 [Default:CheckIn:E] Running Loop=1 2023-09-18T18:59:22.897Z,1695063562.897 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 260.861182 min 2023-09-18T18:59:22.897Z,1695063562.897 [Default:CheckIn:E] Stopped 2023-09-18T18:59:22.898Z,1695063562.898 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T18:59:22.898Z,1695063562.898 [Default:CheckIn] Stopped 2023-09-18T18:59:22.898Z,1695063562.898 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T18:59:22.898Z,1695063562.898 [Default:CheckIn](INFO): Running loop #24 2023-09-18T18:59:22.898Z,1695063562.898 [Default:CheckIn] Running Loop=24 2023-09-18T18:59:22.898Z,1695063562.898 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T18:59:22.898Z,1695063562.898 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T18:59:55.665Z,1695063595.665 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-18T18:59:55.740Z,1695063595.740 [NAL9602](ERROR): received: +CSQ:0 OK587, 0, 0, 0, 0 OK 2023-09-18T19:04:22.919Z,1695063862.919 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T18:59:22.9Z 2023-09-18T19:04:22.919Z,1695063862.919 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T19:04:22.920Z,1695063862.920 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T19:04:27.328Z,1695063867.328 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T19:04:31.359Z,1695063871.359 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230918T152633/Courier0042.lzma 2023-09-18T19:04:32.362Z,1695063872.362 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0042.lzma.bak 2023-09-18T19:04:32.362Z,1695063872.362 [DataOverHttps](INFO): SBD MOMSN=18782379 2023-09-18T19:04:58.107Z,1695063898.107 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T19:05:05.340Z,1695063905.340 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230918T152633/Express0043.lzma 2023-09-18T19:05:06.342Z,1695063906.342 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0043.lzma.bak 2023-09-18T19:05:06.342Z,1695063906.342 [DataOverHttps](INFO): SBD MOMSN=18782381 2023-09-18T19:05:09.848Z,1695063909.848 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T19:05:09.849Z,1695063909.849 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T19:05:09.849Z,1695063909.849 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T19:07:52.056Z,1695064072.056 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-09-18T19:10:10.407Z,1695064210.407 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T19:10:10.407Z,1695064210.407 [Default:CheckIn:C.Wait] Stopped 2023-09-18T19:10:10.408Z,1695064210.408 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T19:10:10.408Z,1695064210.408 [Default:CheckIn:D] Running Loop=1 2023-09-18T19:10:10.823Z,1695064210.823 [Default:CheckIn:D] Stopped 2023-09-18T19:10:10.823Z,1695064210.823 [Default:CheckIn:E] Running Loop=1 2023-09-18T19:10:11.218Z,1695064211.218 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 271.666227 min 2023-09-18T19:10:11.218Z,1695064211.218 [Default:CheckIn:E] Stopped 2023-09-18T19:10:11.218Z,1695064211.218 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T19:10:11.218Z,1695064211.218 [Default:CheckIn] Stopped 2023-09-18T19:10:11.219Z,1695064211.219 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T19:10:11.219Z,1695064211.219 [Default:CheckIn](INFO): Running loop #25 2023-09-18T19:10:11.219Z,1695064211.219 [Default:CheckIn] Running Loop=25 2023-09-18T19:10:11.219Z,1695064211.219 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T19:10:11.219Z,1695064211.219 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T19:10:12.820Z,1695064212.820 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-18T19:10:12.820Z,1695064212.820 [NAL9602] Data Fault, FailCount= 1 2023-09-18T19:10:12.820Z,1695064212.820 [NAL9602](ERROR): Data Fault 2023-09-18T19:10:12.864Z,1695064212.864 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-18T19:10:13.227Z,1695064213.227 [NAL9602](INFO): Powering down 2023-09-18T19:10:14.071Z,1695064214.071 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-18T19:10:14.071Z,1695064214.071 [NAL9602] No Fault, FailCount= 1 2023-09-18T19:10:43.523Z,1695064243.523 [NAL9602](INFO): Powering up NAL9602 2023-09-18T19:10:54.448Z,1695064254.448 [NAL9602](INFO): NAL9602 initialized 2023-09-18T19:15:11.577Z,1695064511.577 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T19:10:11.2Z 2023-09-18T19:15:11.577Z,1695064511.577 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T19:15:11.577Z,1695064511.577 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T19:15:20.447Z,1695064520.447 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230918T152633/Courier0045.lzma 2023-09-18T19:15:21.450Z,1695064521.450 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0045.lzma.bak 2023-09-18T19:15:21.450Z,1695064521.450 [DataOverHttps](INFO): SBD MOMSN=18782484 2023-09-18T19:15:42.100Z,1695064542.100 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230918T152633/Express0046.lzma 2023-09-18T19:15:43.102Z,1695064543.102 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0046.lzma.bak 2023-09-18T19:15:43.102Z,1695064543.102 [DataOverHttps](INFO): SBD MOMSN=18782486 2023-09-18T19:15:48.485Z,1695064548.485 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T19:15:48.485Z,1695064548.485 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T19:15:48.485Z,1695064548.485 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T19:15:58.495Z,1695064558.495 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T19:16:29.193Z,1695064589.193 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T19:20:49.093Z,1695064849.093 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T19:20:49.093Z,1695064849.093 [Default:CheckIn:C.Wait] Stopped 2023-09-18T19:20:49.093Z,1695064849.093 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T19:20:49.094Z,1695064849.094 [Default:CheckIn:D] Running Loop=1 2023-09-18T19:20:49.490Z,1695064849.490 [Default:CheckIn:D] Stopped 2023-09-18T19:20:49.490Z,1695064849.490 [Default:CheckIn:E] Running Loop=1 2023-09-18T19:20:49.904Z,1695064849.904 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 282.310677 min 2023-09-18T19:20:49.904Z,1695064849.904 [Default:CheckIn:E] Stopped 2023-09-18T19:20:49.905Z,1695064849.905 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T19:20:49.905Z,1695064849.905 [Default:CheckIn] Stopped 2023-09-18T19:20:49.905Z,1695064849.905 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T19:20:49.905Z,1695064849.905 [Default:CheckIn](INFO): Running loop #26 2023-09-18T19:20:49.905Z,1695064849.905 [Default:CheckIn] Running Loop=26 2023-09-18T19:20:49.905Z,1695064849.905 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T19:20:49.905Z,1695064849.905 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T19:21:22.595Z,1695064882.595 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-18T19:21:22.680Z,1695064882.680 [NAL9602](ERROR): received: +CSQ:0 OK587, 0, 0, 0, 0 OK 2023-09-18T19:25:50.321Z,1695065150.321 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T19:20:49.9Z 2023-09-18T19:25:50.321Z,1695065150.321 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T19:25:50.321Z,1695065150.321 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T19:25:53.917Z,1695065153.917 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T19:25:58.575Z,1695065158.575 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230918T152633/Courier0048.lzma 2023-09-18T19:25:59.578Z,1695065159.578 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0048.lzma.bak 2023-09-18T19:25:59.578Z,1695065159.578 [DataOverHttps](INFO): SBD MOMSN=18782505 2023-09-18T19:26:17.953Z,1695065177.953 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20230918T152633/Express0049.lzma 2023-09-18T19:26:18.954Z,1695065178.954 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0049.lzma.bak 2023-09-18T19:26:18.954Z,1695065178.954 [DataOverHttps](INFO): SBD MOMSN=18782507 2023-09-18T19:26:22.241Z,1695065182.241 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T19:26:22.241Z,1695065182.241 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T19:26:22.241Z,1695065182.241 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T19:26:24.643Z,1695065184.643 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T19:30:44.720Z,1695065444.720 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-09-18T19:30:44.723Z,1695065444.723 [BPC1](INFO): Received data from all battery sticks. 2023-09-18T19:31:22.715Z,1695065482.715 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T19:31:22.715Z,1695065482.715 [Default:CheckIn:C.Wait] Stopped 2023-09-18T19:31:22.715Z,1695065482.715 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T19:31:22.715Z,1695065482.715 [Default:CheckIn:D] Running Loop=1 2023-09-18T19:31:23.108Z,1695065483.108 [Default:CheckIn:D] Stopped 2023-09-18T19:31:23.108Z,1695065483.108 [Default:CheckIn:E] Running Loop=1 2023-09-18T19:31:23.510Z,1695065483.510 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 292.870964 min 2023-09-18T19:31:23.511Z,1695065483.511 [Default:CheckIn:E] Stopped 2023-09-18T19:31:23.511Z,1695065483.511 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T19:31:23.511Z,1695065483.511 [Default:CheckIn] Stopped 2023-09-18T19:31:23.511Z,1695065483.511 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T19:31:23.511Z,1695065483.511 [Default:CheckIn](INFO): Running loop #27 2023-09-18T19:31:23.511Z,1695065483.511 [Default:CheckIn] Running Loop=27 2023-09-18T19:31:23.511Z,1695065483.511 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T19:31:23.511Z,1695065483.511 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T19:31:25.121Z,1695065485.121 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-18T19:31:25.121Z,1695065485.121 [NAL9602] Data Fault, FailCount= 2 2023-09-18T19:31:25.121Z,1695065485.121 [NAL9602](ERROR): Data Fault 2023-09-18T19:31:25.141Z,1695065485.141 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-18T19:31:25.521Z,1695065485.521 [NAL9602](INFO): Powering down 2023-09-18T19:31:26.367Z,1695065486.367 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-18T19:31:26.367Z,1695065486.367 [NAL9602] No Fault, FailCount= 2 2023-09-18T19:31:55.877Z,1695065515.877 [NAL9602](INFO): Powering up NAL9602 2023-09-18T19:32:06.792Z,1695065526.792 [NAL9602](INFO): NAL9602 initialized 2023-09-18T19:36:23.895Z,1695065783.895 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T19:31:23.5Z 2023-09-18T19:36:23.895Z,1695065783.895 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T19:36:23.895Z,1695065783.895 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T19:36:32.287Z,1695065792.287 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20230918T152633/Courier0051.lzma 2023-09-18T19:36:33.290Z,1695065793.290 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0051.lzma.bak 2023-09-18T19:36:33.290Z,1695065793.290 [DataOverHttps](INFO): SBD MOMSN=18782527 2023-09-18T19:36:55.952Z,1695065815.952 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20230918T152633/Express0052.lzma 2023-09-18T19:36:56.954Z,1695065816.954 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0052.lzma.bak 2023-09-18T19:36:56.954Z,1695065816.954 [DataOverHttps](INFO): SBD MOMSN=18782529 2023-09-18T19:37:00.333Z,1695065820.333 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T19:37:00.333Z,1695065820.333 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T19:37:00.333Z,1695065820.333 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T19:37:09.623Z,1695065829.623 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T19:37:40.321Z,1695065860.321 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T19:42:00.907Z,1695066120.907 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T19:42:00.907Z,1695066120.907 [Default:CheckIn:C.Wait] Stopped 2023-09-18T19:42:00.907Z,1695066120.907 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T19:42:00.907Z,1695066120.907 [Default:CheckIn:D] Running Loop=1 2023-09-18T19:42:01.317Z,1695066121.317 [Default:CheckIn:D] Stopped 2023-09-18T19:42:01.317Z,1695066121.317 [Default:CheckIn:E] Running Loop=1 2023-09-18T19:42:01.714Z,1695066121.714 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 303.507780 min 2023-09-18T19:42:01.714Z,1695066121.714 [Default:CheckIn:E] Stopped 2023-09-18T19:42:01.714Z,1695066121.714 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T19:42:01.714Z,1695066121.714 [Default:CheckIn] Stopped 2023-09-18T19:42:01.714Z,1695066121.714 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T19:42:01.714Z,1695066121.714 [Default:CheckIn](INFO): Running loop #28 2023-09-18T19:42:01.714Z,1695066121.714 [Default:CheckIn] Running Loop=28 2023-09-18T19:42:01.715Z,1695066121.715 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T19:42:01.715Z,1695066121.715 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T19:42:34.428Z,1695066154.428 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-18T19:42:34.508Z,1695066154.508 [NAL9602](ERROR): received: +CSQ:0 OK587, 0, 0, 0, 0 OK 2023-09-18T19:47:01.916Z,1695066421.916 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T19:42:01.7Z 2023-09-18T19:47:01.916Z,1695066421.916 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T19:47:01.916Z,1695066421.916 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T19:47:05.528Z,1695066425.528 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T19:47:10.447Z,1695066430.447 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230918T152633/Courier0054.lzma 2023-09-18T19:47:11.450Z,1695066431.450 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0054.lzma.bak 2023-09-18T19:47:11.450Z,1695066431.450 [DataOverHttps](INFO): SBD MOMSN=18782550 2023-09-18T19:47:29.767Z,1695066449.767 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20230918T152633/Express0055.lzma 2023-09-18T19:47:30.770Z,1695066450.770 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0055.lzma.bak 2023-09-18T19:47:30.770Z,1695066450.770 [DataOverHttps](INFO): SBD MOMSN=18782552 2023-09-18T19:47:34.275Z,1695066454.275 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T19:47:34.275Z,1695066454.275 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T19:47:34.275Z,1695066454.275 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T19:47:36.280Z,1695066456.280 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T19:52:34.865Z,1695066754.865 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T19:52:34.865Z,1695066754.865 [Default:CheckIn:C.Wait] Stopped 2023-09-18T19:52:34.865Z,1695066754.865 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T19:52:34.866Z,1695066754.866 [Default:CheckIn:D] Running Loop=1 2023-09-18T19:52:35.275Z,1695066755.275 [Default:CheckIn:D] Stopped 2023-09-18T19:52:35.275Z,1695066755.275 [Default:CheckIn:E] Running Loop=1 2023-09-18T19:52:35.677Z,1695066755.677 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 314.073763 min 2023-09-18T19:52:35.677Z,1695066755.677 [Default:CheckIn:E] Stopped 2023-09-18T19:52:35.677Z,1695066755.677 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T19:52:35.677Z,1695066755.677 [Default:CheckIn] Stopped 2023-09-18T19:52:35.677Z,1695066755.677 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T19:52:35.678Z,1695066755.678 [Default:CheckIn](INFO): Running loop #29 2023-09-18T19:52:35.678Z,1695066755.678 [Default:CheckIn] Running Loop=29 2023-09-18T19:52:35.678Z,1695066755.678 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T19:52:35.678Z,1695066755.678 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T19:52:37.275Z,1695066757.275 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-18T19:52:37.275Z,1695066757.275 [NAL9602] Data Fault, FailCount= 3 2023-09-18T19:52:37.275Z,1695066757.275 [NAL9602](ERROR): Data Fault 2023-09-18T19:52:37.348Z,1695066757.348 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-18T19:52:37.680Z,1695066757.680 [NAL9602](INFO): Powering down 2023-09-18T19:52:38.526Z,1695066758.526 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-18T19:52:38.526Z,1695066758.526 [NAL9602] No Fault, FailCount= 3 2023-09-18T19:53:07.993Z,1695066787.993 [NAL9602](INFO): Powering up NAL9602 2023-09-18T19:53:18.895Z,1695066798.895 [NAL9602](INFO): NAL9602 initialized 2023-09-18T19:57:35.871Z,1695067055.871 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T19:52:35.7Z 2023-09-18T19:57:35.871Z,1695067055.871 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T19:57:35.871Z,1695067055.871 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T19:57:48.611Z,1695067068.611 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230918T152633/Courier0057.lzma 2023-09-18T19:57:49.615Z,1695067069.615 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0057.lzma.bak 2023-09-18T19:57:49.615Z,1695067069.615 [DataOverHttps](INFO): SBD MOMSN=18782569 2023-09-18T19:58:09.548Z,1695067089.548 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20230918T152633/Express0058.lzma 2023-09-18T19:58:10.550Z,1695067090.550 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0058.lzma.bak 2023-09-18T19:58:10.550Z,1695067090.550 [DataOverHttps](INFO): SBD MOMSN=18782571 2023-09-18T19:58:14.010Z,1695067094.010 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T19:58:14.011Z,1695067094.011 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T19:58:14.011Z,1695067094.011 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T19:58:21.640Z,1695067101.640 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T19:58:52.360Z,1695067132.360 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T20:02:27.144Z,1695067347.144 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T20:02:58.288Z,1695067378.288 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T20:03:14.560Z,1695067394.560 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T20:03:14.560Z,1695067394.560 [Default:CheckIn:C.Wait] Stopped 2023-09-18T20:03:14.560Z,1695067394.560 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T20:03:14.560Z,1695067394.560 [Default:CheckIn:D] Running Loop=1 2023-09-18T20:03:14.959Z,1695067394.959 [Default:CheckIn:D] Stopped 2023-09-18T20:03:14.972Z,1695067394.972 [Default:CheckIn:E] Running Loop=1 2023-09-18T20:03:15.362Z,1695067395.362 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 324.735156 min 2023-09-18T20:03:15.363Z,1695067395.363 [Default:CheckIn:E] Stopped 2023-09-18T20:03:15.363Z,1695067395.363 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T20:03:15.363Z,1695067395.363 [Default:CheckIn] Stopped 2023-09-18T20:03:15.363Z,1695067395.363 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T20:03:15.363Z,1695067395.363 [Default:CheckIn](INFO): Running loop #30 2023-09-18T20:03:15.363Z,1695067395.363 [Default:CheckIn] Running Loop=30 2023-09-18T20:03:15.363Z,1695067395.363 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T20:03:15.363Z,1695067395.363 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T20:03:29.412Z,1695067409.412 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T20:03:48.076Z,1695067428.076 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-18T20:03:48.152Z,1695067428.152 [NAL9602](ERROR): received: +CSQ:0 OK587, 0, 0, 0, 0 OK 2023-09-18T20:03:52.531Z,1695067432.531 [DVL_micro](ERROR): Failed to parse: :BI,-00325,+00098,+00124,+00000,I 2023-09-18T20:04:00.536Z,1695067440.536 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T20:04:31.660Z,1695067471.660 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T20:04:35.677Z,1695067475.677 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.739578 2023-09-18T20:05:32.944Z,1695067532.944 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T20:06:04.068Z,1695067564.068 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T20:06:35.192Z,1695067595.192 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T20:07:06.316Z,1695067626.316 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T20:07:37.452Z,1695067657.452 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T20:08:08.588Z,1695067688.588 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T20:08:15.545Z,1695067695.545 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T20:03:15.4Z 2023-09-18T20:08:15.545Z,1695067695.545 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T20:08:15.546Z,1695067695.546 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T20:08:19.569Z,1695067699.569 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T20:08:38.910Z,1695067718.910 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003175 2023-09-18T20:08:53.906Z,1695067733.906 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230918T152633/Courier0060.lzma 2023-09-18T20:08:54.914Z,1695067734.914 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0060.lzma.bak 2023-09-18T20:08:54.914Z,1695067734.914 [DataOverHttps](INFO): SBD MOMSN=18782586 2023-09-18T20:09:13.017Z,1695067753.017 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T20:09:13.084Z,1695067753.084 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20230918T152633/Express0061.lzma 2023-09-18T20:09:14.086Z,1695067754.086 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0061.lzma.bak 2023-09-18T20:09:14.086Z,1695067754.086 [DataOverHttps](INFO): SBD MOMSN=18782588 2023-09-18T20:09:17.504Z,1695067757.504 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T20:09:17.504Z,1695067757.504 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T20:09:17.504Z,1695067757.504 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T20:14:18.050Z,1695068058.050 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T20:14:18.050Z,1695068058.050 [Default:CheckIn:C.Wait] Stopped 2023-09-18T20:14:18.050Z,1695068058.050 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T20:14:18.051Z,1695068058.051 [Default:CheckIn:D] Running Loop=1 2023-09-18T20:14:18.461Z,1695068058.461 [Default:CheckIn:D] Stopped 2023-09-18T20:14:18.461Z,1695068058.461 [Default:CheckIn:E] Running Loop=1 2023-09-18T20:14:18.858Z,1695068058.858 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 335.793522 min 2023-09-18T20:14:18.858Z,1695068058.858 [Default:CheckIn:E] Stopped 2023-09-18T20:14:18.858Z,1695068058.858 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T20:14:18.858Z,1695068058.858 [Default:CheckIn] Stopped 2023-09-18T20:14:18.858Z,1695068058.858 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T20:14:18.858Z,1695068058.858 [Default:CheckIn](INFO): Running loop #31 2023-09-18T20:14:18.858Z,1695068058.858 [Default:CheckIn] Running Loop=31 2023-09-18T20:14:18.858Z,1695068058.858 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T20:14:18.858Z,1695068058.858 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T20:14:20.469Z,1695068060.469 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-18T20:14:20.469Z,1695068060.469 [NAL9602] Data Fault, FailCount= 4 2023-09-18T20:14:20.469Z,1695068060.469 [NAL9602](ERROR): Data Fault 2023-09-18T20:14:20.521Z,1695068060.521 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-18T20:14:20.867Z,1695068060.867 [NAL9602](INFO): Powering down 2023-09-18T20:14:21.700Z,1695068061.700 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-18T20:14:21.700Z,1695068061.700 [NAL9602] No Fault, FailCount= 4 2023-09-18T20:14:51.209Z,1695068091.209 [NAL9602](INFO): Powering up NAL9602 2023-09-18T20:15:02.114Z,1695068102.114 [NAL9602](INFO): NAL9602 initialized 2023-09-18T20:19:18.874Z,1695068358.874 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T20:14:18.9Z 2023-09-18T20:19:18.875Z,1695068358.875 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T20:19:18.875Z,1695068358.875 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T20:19:27.823Z,1695068367.823 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230918T152633/Courier0063.lzma 2023-09-18T20:19:28.826Z,1695068368.826 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0063.lzma.bak 2023-09-18T20:19:28.826Z,1695068368.826 [DataOverHttps](INFO): SBD MOMSN=18782604 2023-09-18T20:19:51.512Z,1695068391.512 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20230918T152633/Express0064.lzma 2023-09-18T20:19:52.514Z,1695068392.514 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0064.lzma.bak 2023-09-18T20:19:52.514Z,1695068392.514 [DataOverHttps](INFO): SBD MOMSN=18782606 2023-09-18T20:19:56.119Z,1695068396.119 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T20:19:56.119Z,1695068396.119 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T20:19:56.140Z,1695068396.140 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T20:20:04.571Z,1695068404.571 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T20:20:35.267Z,1695068435.267 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T20:24:56.975Z,1695068696.975 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T20:24:56.976Z,1695068696.976 [Default:CheckIn:C.Wait] Stopped 2023-09-18T20:24:56.976Z,1695068696.976 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T20:24:56.976Z,1695068696.976 [Default:CheckIn:D] Running Loop=1 2023-09-18T20:24:57.385Z,1695068697.385 [Default:CheckIn:D] Stopped 2023-09-18T20:24:57.385Z,1695068697.385 [Default:CheckIn:E] Running Loop=1 2023-09-18T20:24:57.759Z,1695068697.759 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 346.442253 min 2023-09-18T20:24:57.759Z,1695068697.759 [Default:CheckIn:E] Stopped 2023-09-18T20:24:57.760Z,1695068697.760 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T20:24:57.760Z,1695068697.760 [Default:CheckIn] Stopped 2023-09-18T20:24:57.760Z,1695068697.760 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T20:24:57.760Z,1695068697.760 [Default:CheckIn](INFO): Running loop #32 2023-09-18T20:24:57.760Z,1695068697.760 [Default:CheckIn] Running Loop=32 2023-09-18T20:24:57.760Z,1695068697.760 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T20:24:57.760Z,1695068697.760 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T20:25:30.475Z,1695068730.475 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-18T20:25:30.552Z,1695068730.552 [NAL9602](ERROR): received: +CSQ:0 OK587, 0, 0, 0, 0 OK 2023-09-18T20:29:57.954Z,1695068997.954 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T20:24:57.8Z 2023-09-18T20:29:57.954Z,1695068997.954 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T20:29:57.955Z,1695068997.955 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T20:30:02.402Z,1695069002.402 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T20:30:06.839Z,1695069006.839 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230918T152633/Courier0066.lzma 2023-09-18T20:30:07.842Z,1695069007.842 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0066.lzma.bak 2023-09-18T20:30:07.842Z,1695069007.842 [DataOverHttps](INFO): SBD MOMSN=18782625 2023-09-18T20:30:25.897Z,1695069025.897 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20230918T152633/Express0067.lzma 2023-09-18T20:30:26.898Z,1695069026.898 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0067.lzma.bak 2023-09-18T20:30:26.898Z,1695069026.898 [DataOverHttps](INFO): SBD MOMSN=18782627 2023-09-18T20:30:30.302Z,1695069030.302 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T20:30:30.302Z,1695069030.302 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T20:30:30.302Z,1695069030.302 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T20:30:33.102Z,1695069033.102 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T20:35:30.954Z,1695069330.954 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T20:35:30.954Z,1695069330.954 [Default:CheckIn:C.Wait] Stopped 2023-09-18T20:35:30.954Z,1695069330.954 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T20:35:30.955Z,1695069330.955 [Default:CheckIn:D] Running Loop=1 2023-09-18T20:35:31.363Z,1695069331.363 [Default:CheckIn:D] Stopped 2023-09-18T20:35:31.363Z,1695069331.363 [Default:CheckIn:E] Running Loop=1 2023-09-18T20:35:31.779Z,1695069331.779 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 357.008561 min 2023-09-18T20:35:31.779Z,1695069331.779 [Default:CheckIn:E] Stopped 2023-09-18T20:35:31.779Z,1695069331.779 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T20:35:31.779Z,1695069331.779 [Default:CheckIn] Stopped 2023-09-18T20:35:31.779Z,1695069331.779 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T20:35:31.779Z,1695069331.779 [Default:CheckIn](INFO): Running loop #33 2023-09-18T20:35:31.779Z,1695069331.779 [Default:CheckIn] Running Loop=33 2023-09-18T20:35:31.779Z,1695069331.779 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T20:35:31.780Z,1695069331.780 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T20:35:33.374Z,1695069333.374 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-18T20:35:33.374Z,1695069333.374 [NAL9602] Data Fault, FailCount= 5 2023-09-18T20:35:33.375Z,1695069333.375 [NAL9602](ERROR): Data Fault 2023-09-18T20:35:33.395Z,1695069333.395 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-18T20:35:33.395Z,1695069333.395 [CBIT](CRITICAL): Data Fault in component: NAL9602 2023-09-18T20:35:33.773Z,1695069333.773 [NAL9602](INFO): Powering down 2023-09-18T20:35:33.812Z,1695069333.812 [CommandExec](FAULT): Scheduling is paused 2023-09-18T20:35:33.812Z,1695069333.812 [CBIT](INFO): Critical error at 20230918T203533 2023-09-18T20:37:33.376Z,1695069453.376 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-18T20:37:33.377Z,1695069453.377 [NAL9602] No Fault, FailCount= 5 2023-09-18T20:37:33.767Z,1695069453.767 [NAL9602](INFO): Powering up NAL9602 2023-09-18T20:37:44.717Z,1695069464.717 [NAL9602](INFO): NAL9602 initialized 2023-09-18T20:38:26.699Z,1695069506.699 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-09-18T20:38:26.702Z,1695069506.702 [BPC1](INFO): Received data from all battery sticks. 2023-09-18T20:39:51.208Z,1695069591.208 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T20:40:31.983Z,1695069631.983 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T20:35:31.8Z 2023-09-18T20:40:31.983Z,1695069631.983 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T20:40:31.984Z,1695069631.984 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T20:40:41.731Z,1695069641.731 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20230918T152633/Courier0069.lzma 2023-09-18T20:40:42.734Z,1695069642.734 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0069.lzma.bak 2023-09-18T20:40:42.734Z,1695069642.734 [DataOverHttps](INFO): SBD MOMSN=18782643 2023-09-18T20:41:03.873Z,1695069663.873 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20230918T152633/Express0070.lzma 2023-09-18T20:41:04.310Z,1695069664.310 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0070.lzma.bak 2023-09-18T20:41:04.310Z,1695069664.310 [DataOverHttps](INFO): SBD MOMSN=18782646 2023-09-18T20:41:07.601Z,1695069667.601 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T20:41:07.601Z,1695069667.601 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T20:41:07.601Z,1695069667.601 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T20:42:47.351Z,1695069767.351 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T20:43:18.041Z,1695069798.041 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T20:46:08.134Z,1695069968.134 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T20:46:08.134Z,1695069968.134 [Default:CheckIn:C.Wait] Stopped 2023-09-18T20:46:08.134Z,1695069968.134 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T20:46:08.135Z,1695069968.135 [Default:CheckIn:D] Running Loop=1 2023-09-18T20:46:08.545Z,1695069968.545 [Default:CheckIn:D] Stopped 2023-09-18T20:46:08.545Z,1695069968.545 [Default:CheckIn:E] Running Loop=1 2023-09-18T20:46:08.949Z,1695069968.949 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 367.628255 min 2023-09-18T20:46:08.949Z,1695069968.949 [Default:CheckIn:E] Stopped 2023-09-18T20:46:08.949Z,1695069968.949 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T20:46:08.950Z,1695069968.950 [Default:CheckIn] Stopped 2023-09-18T20:46:08.950Z,1695069968.950 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T20:46:08.950Z,1695069968.950 [Default:CheckIn](INFO): Running loop #34 2023-09-18T20:46:08.950Z,1695069968.950 [Default:CheckIn] Running Loop=34 2023-09-18T20:46:08.950Z,1695069968.950 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T20:46:08.950Z,1695069968.950 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T20:46:41.652Z,1695070001.652 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-18T20:46:41.732Z,1695070001.732 [NAL9602](ERROR): received: +CSQ:0 OK587, 0, 0, 0, 0 OK 2023-09-18T20:51:09.227Z,1695070269.227 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T20:46:08.0Z 2023-09-18T20:51:09.227Z,1695070269.227 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T20:51:09.227Z,1695070269.227 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T20:51:13.216Z,1695070273.216 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T20:51:18.171Z,1695070278.171 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230918T152633/Courier0072.lzma 2023-09-18T20:51:19.175Z,1695070279.175 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0072.lzma.bak 2023-09-18T20:51:19.175Z,1695070279.175 [DataOverHttps](INFO): SBD MOMSN=18782668 2023-09-18T20:51:37.550Z,1695070297.550 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20230918T152633/Express0073.lzma 2023-09-18T20:51:38.550Z,1695070298.550 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0073.lzma.bak 2023-09-18T20:51:38.550Z,1695070298.550 [DataOverHttps](INFO): SBD MOMSN=18782670 2023-09-18T20:51:43.967Z,1695070303.967 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T20:51:44.035Z,1695070304.035 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T20:51:44.052Z,1695070304.052 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T20:51:44.052Z,1695070304.052 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T20:56:44.614Z,1695070604.614 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T20:56:44.614Z,1695070604.614 [Default:CheckIn:C.Wait] Stopped 2023-09-18T20:56:44.615Z,1695070604.615 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T20:56:44.615Z,1695070604.615 [Default:CheckIn:D] Running Loop=1 2023-09-18T20:56:45.033Z,1695070605.033 [Default:CheckIn:D] Stopped 2023-09-18T20:56:45.033Z,1695070605.033 [Default:CheckIn:E] Running Loop=1 2023-09-18T20:56:45.419Z,1695070605.419 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 378.236393 min 2023-09-18T20:56:45.419Z,1695070605.419 [Default:CheckIn:E] Stopped 2023-09-18T20:56:45.419Z,1695070605.419 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T20:56:45.419Z,1695070605.419 [Default:CheckIn] Stopped 2023-09-18T20:56:45.419Z,1695070605.419 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T20:56:45.420Z,1695070605.420 [Default:CheckIn](INFO): Running loop #35 2023-09-18T20:56:45.420Z,1695070605.420 [Default:CheckIn] Running Loop=35 2023-09-18T20:56:45.420Z,1695070605.420 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T20:56:45.420Z,1695070605.420 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T20:56:47.032Z,1695070607.032 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-18T20:56:47.033Z,1695070607.033 [NAL9602] Data Fault, FailCount= 1 2023-09-18T20:56:47.033Z,1695070607.033 [NAL9602](ERROR): Data Fault 2023-09-18T20:56:47.101Z,1695070607.101 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-18T20:56:47.425Z,1695070607.425 [NAL9602](INFO): Powering down 2023-09-18T20:56:48.347Z,1695070608.347 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-18T20:56:48.347Z,1695070608.347 [NAL9602] No Fault, FailCount= 1 2023-09-18T20:57:17.721Z,1695070637.721 [NAL9602](INFO): Powering up NAL9602 2023-09-18T20:57:28.628Z,1695070648.628 [NAL9602](INFO): NAL9602 initialized 2023-09-18T21:01:45.563Z,1695070905.563 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T20:56:45.4Z 2023-09-18T21:01:45.563Z,1695070905.563 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T21:01:45.564Z,1695070905.564 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T21:01:48.280Z,1695070908.280 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230918T152633/Courier0075.lzma 2023-09-18T21:01:49.286Z,1695070909.286 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0075.lzma.bak 2023-09-18T21:01:49.286Z,1695070909.286 [DataOverHttps](INFO): SBD MOMSN=18782691 2023-09-18T21:02:07.512Z,1695070927.512 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20230918T152633/Express0076.lzma 2023-09-18T21:02:08.514Z,1695070928.514 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0076.lzma.bak 2023-09-18T21:02:08.514Z,1695070928.514 [DataOverHttps](INFO): SBD MOMSN=18782693 2023-09-18T21:02:12.112Z,1695070932.112 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T21:02:12.112Z,1695070932.112 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T21:02:12.112Z,1695070932.112 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T21:02:31.452Z,1695070951.452 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T21:03:02.156Z,1695070982.156 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T21:07:12.734Z,1695071232.734 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T21:07:12.734Z,1695071232.734 [Default:CheckIn:C.Wait] Stopped 2023-09-18T21:07:12.734Z,1695071232.734 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T21:07:12.736Z,1695071232.736 [Default:CheckIn:D] Running Loop=1 2023-09-18T21:07:13.119Z,1695071233.119 [Default:CheckIn:D] Stopped 2023-09-18T21:07:13.119Z,1695071233.119 [Default:CheckIn:E] Running Loop=1 2023-09-18T21:07:13.526Z,1695071233.526 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 388.704492 min 2023-09-18T21:07:13.526Z,1695071233.526 [Default:CheckIn:E] Stopped 2023-09-18T21:07:13.526Z,1695071233.526 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T21:07:13.526Z,1695071233.526 [Default:CheckIn] Stopped 2023-09-18T21:07:13.526Z,1695071233.526 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T21:07:13.527Z,1695071233.527 [Default:CheckIn](INFO): Running loop #36 2023-09-18T21:07:13.527Z,1695071233.527 [Default:CheckIn] Running Loop=36 2023-09-18T21:07:13.527Z,1695071233.527 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T21:07:13.527Z,1695071233.527 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T21:07:46.265Z,1695071266.265 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-18T21:07:46.340Z,1695071266.340 [NAL9602](ERROR): received: +CSQ:0 OK587, 0, 0, 0, 0 OK 2023-09-18T21:12:13.750Z,1695071533.750 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T21:07:13.5Z 2023-09-18T21:12:13.750Z,1695071533.750 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T21:12:13.751Z,1695071533.751 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T21:12:17.374Z,1695071537.374 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T21:12:22.691Z,1695071542.691 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230918T152633/Courier0078.lzma 2023-09-18T21:12:23.694Z,1695071543.694 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0078.lzma.bak 2023-09-18T21:12:23.694Z,1695071543.694 [DataOverHttps](INFO): SBD MOMSN=18782712 2023-09-18T21:12:44.832Z,1695071564.832 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20230918T152633/Express0079.lzma 2023-09-18T21:12:45.834Z,1695071565.834 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0079.lzma.bak 2023-09-18T21:12:45.834Z,1695071565.834 [DataOverHttps](INFO): SBD MOMSN=18782714 2023-09-18T21:12:48.184Z,1695071568.184 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T21:12:51.827Z,1695071571.827 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T21:12:51.832Z,1695071571.832 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T21:12:51.832Z,1695071571.832 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T21:17:52.443Z,1695071872.443 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T21:17:52.443Z,1695071872.443 [Default:CheckIn:C.Wait] Stopped 2023-09-18T21:17:52.443Z,1695071872.443 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T21:17:52.443Z,1695071872.443 [Default:CheckIn:D] Running Loop=1 2023-09-18T21:17:52.840Z,1695071872.840 [Default:CheckIn:D] Stopped 2023-09-18T21:17:52.840Z,1695071872.840 [Default:CheckIn:E] Running Loop=1 2023-09-18T21:17:53.248Z,1695071873.248 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 399.366504 min 2023-09-18T21:17:53.248Z,1695071873.248 [Default:CheckIn:E] Stopped 2023-09-18T21:17:53.248Z,1695071873.248 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T21:17:53.248Z,1695071873.248 [Default:CheckIn] Stopped 2023-09-18T21:17:53.249Z,1695071873.249 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T21:17:53.249Z,1695071873.249 [Default:CheckIn](INFO): Running loop #37 2023-09-18T21:17:53.249Z,1695071873.249 [Default:CheckIn] Running Loop=37 2023-09-18T21:17:53.249Z,1695071873.249 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T21:17:53.249Z,1695071873.249 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T21:17:54.860Z,1695071874.860 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-18T21:17:54.860Z,1695071874.860 [NAL9602] Data Fault, FailCount= 2 2023-09-18T21:17:54.860Z,1695071874.860 [NAL9602](ERROR): Data Fault 2023-09-18T21:17:54.881Z,1695071874.881 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-18T21:17:55.252Z,1695071875.252 [NAL9602](INFO): Powering down 2023-09-18T21:17:56.077Z,1695071876.077 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-18T21:17:56.077Z,1695071876.077 [NAL9602] No Fault, FailCount= 2 2023-09-18T21:18:25.549Z,1695071905.549 [NAL9602](INFO): Powering up NAL9602 2023-09-18T21:18:36.474Z,1695071916.474 [NAL9602](INFO): NAL9602 initialized 2023-09-18T21:22:53.425Z,1695072173.425 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T21:17:53.2Z 2023-09-18T21:22:53.425Z,1695072173.425 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T21:22:53.425Z,1695072173.425 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T21:22:56.882Z,1695072176.882 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230918T152633/Courier0081.lzma 2023-09-18T21:22:57.890Z,1695072177.890 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0081.lzma.bak 2023-09-18T21:22:57.890Z,1695072177.890 [DataOverHttps](INFO): SBD MOMSN=18782731 2023-09-18T21:23:17.888Z,1695072197.888 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20230918T152633/Express0082.lzma 2023-09-18T21:23:18.890Z,1695072198.890 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0082.lzma.bak 2023-09-18T21:23:18.890Z,1695072198.890 [DataOverHttps](INFO): SBD MOMSN=18782733 2023-09-18T21:23:22.193Z,1695072202.193 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T21:23:22.193Z,1695072202.193 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T21:23:22.193Z,1695072202.193 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T21:23:39.164Z,1695072219.164 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T21:24:09.854Z,1695072249.854 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T21:28:22.985Z,1695072502.985 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T21:28:22.985Z,1695072502.985 [Default:CheckIn:C.Wait] Stopped 2023-09-18T21:28:22.985Z,1695072502.985 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T21:28:22.985Z,1695072502.985 [Default:CheckIn:D] Running Loop=1 2023-09-18T21:28:23.391Z,1695072503.391 [Default:CheckIn:D] Stopped 2023-09-18T21:28:23.391Z,1695072503.391 [Default:CheckIn:E] Running Loop=1 2023-09-18T21:28:23.799Z,1695072503.799 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 409.875684 min 2023-09-18T21:28:23.800Z,1695072503.800 [Default:CheckIn:E] Stopped 2023-09-18T21:28:23.800Z,1695072503.800 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T21:28:23.800Z,1695072503.800 [Default:CheckIn] Stopped 2023-09-18T21:28:23.800Z,1695072503.800 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T21:28:23.801Z,1695072503.801 [Default:CheckIn](INFO): Running loop #38 2023-09-18T21:28:23.801Z,1695072503.801 [Default:CheckIn] Running Loop=38 2023-09-18T21:28:23.801Z,1695072503.801 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T21:28:23.801Z,1695072503.801 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T21:28:56.574Z,1695072536.574 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-18T21:28:56.648Z,1695072536.648 [NAL9602](ERROR): received: +CSQ:0 OK587, 0, 0, 0, 0 OK 2023-09-18T21:33:24.156Z,1695072804.156 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-09-18T21:28:23.8Z 2023-09-18T21:33:24.156Z,1695072804.156 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T21:33:24.157Z,1695072804.157 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T21:33:28.210Z,1695072808.210 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T21:33:32.647Z,1695072812.647 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230918T152633/Courier0084.lzma 2023-09-18T21:33:33.650Z,1695072813.650 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0084.lzma.bak 2023-09-18T21:33:33.650Z,1695072813.650 [DataOverHttps](INFO): SBD MOMSN=18782782 2023-09-18T21:33:58.744Z,1695072838.744 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20230918T152633/Express0085.lzma 2023-09-18T21:33:58.981Z,1695072838.981 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T21:33:59.746Z,1695072839.746 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0085.lzma.bak 2023-09-18T21:33:59.746Z,1695072839.746 [DataOverHttps](INFO): SBD MOMSN=18782784 2023-09-18T21:34:03.049Z,1695072843.049 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T21:34:03.049Z,1695072843.049 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T21:34:03.049Z,1695072843.049 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T21:35:44.124Z,1695072944.124 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T21:36:15.248Z,1695072975.248 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T21:36:46.388Z,1695073006.388 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T21:37:17.513Z,1695073037.513 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T21:37:48.636Z,1695073068.636 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T21:37:54.661Z,1695073074.661 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.751770 2023-09-18T21:38:49.888Z,1695073129.888 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T21:39:03.666Z,1695073143.666 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T21:39:03.666Z,1695073143.666 [Default:CheckIn:C.Wait] Stopped 2023-09-18T21:39:03.666Z,1695073143.666 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T21:39:03.667Z,1695073143.667 [Default:CheckIn:D] Running Loop=1 2023-09-18T21:39:04.076Z,1695073144.076 [Default:CheckIn:D] Stopped 2023-09-18T21:39:04.076Z,1695073144.076 [Default:CheckIn:E] Running Loop=1 2023-09-18T21:39:04.476Z,1695073144.476 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 420.553776 min 2023-09-18T21:39:04.476Z,1695073144.476 [Default:CheckIn:E] Stopped 2023-09-18T21:39:04.476Z,1695073144.476 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T21:39:04.477Z,1695073144.477 [Default:CheckIn] Stopped 2023-09-18T21:39:04.477Z,1695073144.477 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T21:39:04.477Z,1695073144.477 [Default:CheckIn](INFO): Running loop #39 2023-09-18T21:39:04.477Z,1695073144.477 [Default:CheckIn] Running Loop=39 2023-09-18T21:39:04.477Z,1695073144.477 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T21:39:04.477Z,1695073144.477 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T21:39:06.081Z,1695073146.081 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-09-18T21:39:06.081Z,1695073146.081 [NAL9602] Data Fault, FailCount= 3 2023-09-18T21:39:06.081Z,1695073146.081 [NAL9602](ERROR): Data Fault 2023-09-18T21:39:06.106Z,1695073146.106 [CBIT](ERROR): Data Fault in component: NAL9602 2023-09-18T21:39:06.482Z,1695073146.482 [NAL9602](INFO): Powering down 2023-09-18T21:39:07.312Z,1695073147.312 [CBIT](INFO): Clearing failed state for component NAL9602 2023-09-18T21:39:07.312Z,1695073147.312 [NAL9602] No Fault, FailCount= 3 2023-09-18T21:39:21.012Z,1695073161.012 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T21:39:36.782Z,1695073176.782 [NAL9602](INFO): Powering up NAL9602 2023-09-18T21:39:47.701Z,1695073187.701 [NAL9602](INFO): NAL9602 initialized 2023-09-18T21:39:52.136Z,1695073192.136 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-09-18T21:40:21.697Z,1695073221.697 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003568 2023-09-18T21:40:30.110Z,1695073230.110 [NAL9602](INFO): SBD MO Status=2, MOMSN=11588, MT Status=2, MTMSN=0 2023-09-18T21:40:30.110Z,1695073230.110 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-18T21:40:48.713Z,1695073248.713 [DVL_micro](ERROR): Failed to parse: :SA,-03.82,+03.49,182.6 2023-09-18T21:41:52.126Z,1695073312.126 [NAL9602](INFO): SBD MO Status=2, MOMSN=11588, MT Status=2, MTMSN=0 2023-09-18T21:41:52.126Z,1695073312.126 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-09-18T21:42:16.393Z,1695073336.393 [NAL9602](INFO): SBD MO Status=0, MOMSN=11588, MT Status=0, MTMSN=0 2023-09-18T21:42:16.393Z,1695073336.393 [NAL9602](INFO): No messages in MT queue 2023-09-18T21:42:17.600Z,1695073337.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214209.00,A,4213.00271,N,08344.18821,W,0.117,69.14,180923,,,A*45 2023-09-18T21:42:17.602Z,1695073337.602 [NAL9602](INFO): GPS fix at 20230918T214209: (42.216712, -83.736470) 2023-09-18T21:42:17.625Z,1695073337.625 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T21:42:17.626Z,1695073337.626 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T21:42:25.751Z,1695073345.751 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20230918T152633/Courier0087.lzma 2023-09-18T21:42:26.754Z,1695073346.754 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0087.lzma.bak 2023-09-18T21:42:26.754Z,1695073346.754 [DataOverHttps](INFO): SBD MOMSN=18782796 2023-09-18T21:42:48.173Z,1695073368.173 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20230918T152633/Express0088.lzma 2023-09-18T21:42:49.178Z,1695073369.178 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0088.lzma.bak 2023-09-18T21:42:49.179Z,1695073369.179 [DataOverHttps](INFO): SBD MOMSN=18782799 2023-09-18T21:42:50.097Z,1695073370.097 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T21:42:52.900Z,1695073372.900 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T21:42:52.900Z,1695073372.900 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T21:42:52.901Z,1695073372.901 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T21:46:09.046Z,1695073569.046 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2023-09-18T21:46:09.049Z,1695073569.049 [BPC1](INFO): Received data from all battery sticks. 2023-09-18T21:47:53.713Z,1695073673.713 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T21:47:53.713Z,1695073673.713 [Default:CheckIn:C.Wait] Stopped 2023-09-18T21:47:53.713Z,1695073673.713 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T21:47:53.713Z,1695073673.713 [Default:CheckIn:D] Running Loop=1 2023-09-18T21:47:54.138Z,1695073674.138 [Default:CheckIn:D] Stopped 2023-09-18T21:47:54.139Z,1695073674.139 [Default:CheckIn:E] Running Loop=1 2023-09-18T21:47:54.514Z,1695073674.514 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 429.388151 min 2023-09-18T21:47:54.514Z,1695073674.514 [Default:CheckIn:E] Stopped 2023-09-18T21:47:54.514Z,1695073674.514 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T21:47:54.514Z,1695073674.514 [Default:CheckIn] Stopped 2023-09-18T21:47:54.514Z,1695073674.514 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T21:47:54.514Z,1695073674.514 [Default:CheckIn](INFO): Running loop #40 2023-09-18T21:47:54.514Z,1695073674.514 [Default:CheckIn] Running Loop=40 2023-09-18T21:47:54.514Z,1695073674.514 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T21:47:54.514Z,1695073674.514 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T21:47:56.545Z,1695073676.545 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214751.00,A,4212.99398,N,08344.16463,W,0.467,78.43,180923,,,A*4E 2023-09-18T21:47:56.547Z,1695073676.547 [NAL9602](INFO): GPS fix at 20230918T214751: (42.216566, -83.736077) 2023-09-18T21:47:56.558Z,1695073676.558 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T21:47:56.558Z,1695073676.558 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T21:48:05.347Z,1695073685.347 [DataOverHttps](INFO): Sending 89 bytes from file Logs/20230918T152633/Courier0090.lzma 2023-09-18T21:48:06.350Z,1695073686.350 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0090.lzma.bak 2023-09-18T21:48:06.350Z,1695073686.350 [DataOverHttps](INFO): SBD MOMSN=18782816 2023-09-18T21:48:24.840Z,1695073704.840 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20230918T152633/Express0091.lzma 2023-09-18T21:48:25.842Z,1695073705.842 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0091.lzma.bak 2023-09-18T21:48:25.842Z,1695073705.842 [DataOverHttps](INFO): SBD MOMSN=18782824 2023-09-18T21:48:31.302Z,1695073711.302 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T21:48:31.303Z,1695073711.303 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T21:48:31.303Z,1695073711.303 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T21:52:59.168Z,1695073979.168 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T21:53:29.873Z,1695074009.873 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T21:53:31.935Z,1695074011.935 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T21:53:31.935Z,1695074011.935 [Default:CheckIn:C.Wait] Stopped 2023-09-18T21:53:31.935Z,1695074011.935 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-09-18T21:53:31.980Z,1695074011.980 [Default:CheckIn:D] Running Loop=1 2023-09-18T21:53:32.387Z,1695074012.387 [Default:CheckIn:D] Stopped 2023-09-18T21:53:32.387Z,1695074012.387 [Default:CheckIn:E] Running Loop=1 2023-09-18T21:53:32.711Z,1695074012.711 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 435.025618 min 2023-09-18T21:53:32.711Z,1695074012.711 [Default:CheckIn:E] Stopped 2023-09-18T21:53:32.711Z,1695074012.711 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-09-18T21:53:32.711Z,1695074012.711 [Default:CheckIn] Stopped 2023-09-18T21:53:32.711Z,1695074012.711 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-09-18T21:53:32.711Z,1695074012.711 [Default:CheckIn](INFO): Running loop #41 2023-09-18T21:53:32.711Z,1695074012.711 [Default:CheckIn] Running Loop=41 2023-09-18T21:53:32.711Z,1695074012.711 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-09-18T21:53:32.724Z,1695074012.724 [Default:CheckIn:Read_GPS] Running Loop=1 2023-09-18T21:53:34.724Z,1695074014.724 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215330.00,A,4212.99431,N,08344.16629,W,0.311,78.43,180923,,,A*42 2023-09-18T21:53:34.726Z,1695074014.726 [NAL9602](INFO): GPS fix at 20230918T215330: (42.216572, -83.736105) 2023-09-18T21:53:34.736Z,1695074014.736 [Default:CheckIn:Read_GPS] Stopped 2023-09-18T21:53:34.736Z,1695074014.736 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-09-18T21:53:47.279Z,1695074027.279 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20230918T152633/Courier0093.lzma 2023-09-18T21:53:48.282Z,1695074028.282 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Courier0093.lzma.bak 2023-09-18T21:53:48.283Z,1695074028.283 [DataOverHttps](INFO): SBD MOMSN=18782846 2023-09-18T21:54:05.493Z,1695074045.493 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2023-09-18T21:54:05.576Z,1695074045.576 [NAL9602](ERROR): received: +CSQ:0 OK588, 0, 0, 0, 0 OK 2023-09-18T21:54:06.840Z,1695074046.840 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20230918T152633/Express0094.lzma 2023-09-18T21:54:07.842Z,1695074047.842 [DataOverHttps](INFO): Moved sent file to Logs/20230918T152633/Express0094.lzma.bak 2023-09-18T21:54:07.842Z,1695074047.842 [DataOverHttps](INFO): SBD MOMSN=18782849 2023-09-18T21:54:11.187Z,1695074051.187 [Default:CheckIn:Read_Iridium] Stopped 2023-09-18T21:54:11.187Z,1695074051.187 [Default:CheckIn:C.Wait] Running Loop=1 2023-09-18T21:54:11.187Z,1695074051.187 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-09-18T21:58:37.380Z,1695074317.380 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-09-18T21:59:08.089Z,1695074348.089 [NAL9602](INFO): Not Powering down - fast GPS 2023-09-18T21:59:11.829Z,1695074351.829 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-09-18T21:59:11.829Z,1695074351.829 [Default:CheckIn:C.Wait] Stopped 2023-09-18T21:59:11.829Z,169507435