2021-07-29T15:38:42.540Z,1627573122.540 [CommandLine](IMPORTANT): got command restart logs
2021-07-29T15:39:53.278Z,1627573193.278 [NAL9602](INFO): SBD MO Status=2, MOMSN=28605, MT Status=2, MTMSN=0
2021-07-29T15:39:53.279Z,1627573193.279 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-29T15:40:43.378Z,1627573243.378 [NAL9602](INFO): SBD MO Status=2, MOMSN=28605, MT Status=2, MTMSN=0
2021-07-29T15:40:43.379Z,1627573243.379 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-29T15:41:04.788Z,1627573264.788 [NAL9602](INFO): SBD MO Status=2, MOMSN=28605, MT Status=2, MTMSN=0
2021-07-29T15:41:04.788Z,1627573264.788 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-29T15:41:17.717Z,1627573277.717 [NAL9602](INFO): SBD MO Status=2, MOMSN=28605, MT Status=2, MTMSN=0
2021-07-29T15:41:17.717Z,1627573277.717 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-29T15:41:37.111Z,1627573297.111 [NAL9602](INFO): SBD MO Status=2, MOMSN=28605, MT Status=2, MTMSN=0
2021-07-29T15:41:37.111Z,1627573297.111 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-29T15:42:02.558Z,1627573322.558 [NAL9602](INFO): SBD MO Status=2, MOMSN=28605, MT Status=2, MTMSN=0
2021-07-29T15:42:02.558Z,1627573322.558 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-29T15:42:06.597Z,1627573326.597 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-29T15:42:37.336Z,1627573357.336 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-29T15:42:38.960Z,1627573358.960 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-29T15:42:38.960Z,1627573358.960 [Default:CheckIn:C.Wait] Stopped
2021-07-29T15:42:38.960Z,1627573358.960 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-29T15:42:38.960Z,1627573358.960 [Default:CheckIn:D] Running Loop=1
2021-07-29T15:42:39.370Z,1627573359.370 [Default:CheckIn:D] Stopped
2021-07-29T15:42:39.370Z,1627573359.370 [Default:CheckIn:E] Running Loop=1
2021-07-29T15:42:39.780Z,1627573359.780 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 989.502083 min 
2021-07-29T15:42:39.780Z,1627573359.780 [Default:CheckIn:E] Stopped
2021-07-29T15:42:39.780Z,1627573359.780 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-29T15:42:39.780Z,1627573359.780 [Default:CheckIn] Stopped
2021-07-29T15:42:39.780Z,1627573359.780 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-29T15:42:39.780Z,1627573359.780 [Default:CheckIn](INFO): Running loop #173
2021-07-29T15:42:39.780Z,1627573359.780 [Default:CheckIn] Running Loop=173
2021-07-29T15:42:39.781Z,1627573359.781 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-29T15:42:39.781Z,1627573359.781 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-29T15:42:41.783Z,1627573361.783 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154241.00,A,3648.16219,N,12147.28972,W,0.544,113.72,290721,,,D*71
2021-07-29T15:42:41.785Z,1627573361.785 [NAL9602](INFO): GPS fix at 20210729T154241: (36.802703, -121.788162)
2021-07-29T15:42:41.797Z,1627573361.797 [Default:CheckIn:Read_GPS] Stopped
2021-07-29T15:42:41.797Z,1627573361.797 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-29T15:42:47.296Z,1627573367.296 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210728T231657/Courier0519.lzma
2021-07-29T15:42:48.301Z,1627573368.301 [DataOverHttps](INFO): Moved sent file to Logs/20210728T231657/Courier0519.lzma.bak
2021-07-29T15:42:48.302Z,1627573368.302 [DataOverHttps](INFO): SBD MOMSN=15773785
2021-07-29T15:43:05.337Z,1627573385.337 [DataOverHttps](INFO): Sending 267 bytes from file Logs/20210729T153842/Courier0000.lzma
2021-07-29T15:43:06.337Z,1627573386.337 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Courier0000.lzma.bak
2021-07-29T15:43:06.337Z,1627573386.337 [DataOverHttps](INFO): SBD MOMSN=15773801
2021-07-29T15:43:12.605Z,1627573392.605 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-07-29T15:43:12.687Z,1627573392.687 [NAL9602](ERROR): received: 
+SBDI: 2, 28605, 2, 0, 0, 0

OK
2021-07-29T15:43:23.467Z,1627573403.467 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210729T153842/Courier0003.lzma
2021-07-29T15:43:24.469Z,1627573404.469 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Courier0003.lzma.bak
2021-07-29T15:43:24.469Z,1627573404.469 [DataOverHttps](INFO): SBD MOMSN=15773805
2021-07-29T15:43:44.084Z,1627573424.084 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20210728T231657/Express0520.lzma
2021-07-29T15:43:45.086Z,1627573425.086 [DataOverHttps](INFO): Moved sent file to Logs/20210728T231657/Express0520.lzma.bak
2021-07-29T15:43:45.086Z,1627573425.086 [DataOverHttps](INFO): SBD MOMSN=15773807
2021-07-29T15:44:03.456Z,1627573443.456 [DataOverHttps](INFO): Sending 465 bytes from file Logs/20210729T153842/Express0001.lzma
2021-07-29T15:44:04.457Z,1627573444.457 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Express0001.lzma.bak
2021-07-29T15:44:04.457Z,1627573444.457 [DataOverHttps](INFO): SBD MOMSN=15773810
2021-07-29T15:44:27.091Z,1627573467.091 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20210729T153842/Express0004.lzma
2021-07-29T15:44:28.094Z,1627573468.094 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Express0004.lzma.bak
2021-07-29T15:44:28.094Z,1627573468.094 [DataOverHttps](INFO): SBD MOMSN=15773815
2021-07-29T15:44:34.009Z,1627573474.009 [Default:CheckIn:Read_Iridium] Stopped
2021-07-29T15:44:34.009Z,1627573474.009 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-29T15:44:34.009Z,1627573474.009 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-29T15:47:44.347Z,1627573664.347 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-29T15:48:15.048Z,1627573695.048 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-29T15:49:34.715Z,1627573774.715 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-29T15:49:34.715Z,1627573774.715 [Default:CheckIn:C.Wait] Stopped
2021-07-29T15:49:34.715Z,1627573774.715 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-29T15:49:34.715Z,1627573774.715 [Default:CheckIn:D] Running Loop=1
2021-07-29T15:49:35.132Z,1627573775.132 [Default:CheckIn:D] Stopped
2021-07-29T15:49:35.132Z,1627573775.132 [Default:CheckIn:E] Running Loop=1
2021-07-29T15:49:35.522Z,1627573775.522 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 996.431445 min 
2021-07-29T15:49:35.522Z,1627573775.522 [Default:CheckIn:E] Stopped
2021-07-29T15:49:35.522Z,1627573775.522 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-29T15:49:35.522Z,1627573775.522 [Default:CheckIn] Stopped
2021-07-29T15:49:35.522Z,1627573775.522 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-29T15:49:35.523Z,1627573775.523 [Default:CheckIn](INFO): Running loop #174
2021-07-29T15:49:35.523Z,1627573775.523 [Default:CheckIn] Running Loop=174
2021-07-29T15:49:35.523Z,1627573775.523 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-29T15:49:35.523Z,1627573775.523 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-29T15:49:37.531Z,1627573777.531 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154936.00,A,3648.16174,N,12147.28669,W,0.505,233.20,290721,,,D*74
2021-07-29T15:49:37.533Z,1627573777.533 [NAL9602](INFO): GPS fix at 20210729T154936: (36.802696, -121.788111)
2021-07-29T15:49:37.546Z,1627573777.546 [Default:CheckIn:Read_GPS] Stopped
2021-07-29T15:49:37.546Z,1627573777.546 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-29T15:49:46.667Z,1627573786.667 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20210729T153842/Courier0006.lzma
2021-07-29T15:49:47.669Z,1627573787.669 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Courier0006.lzma.bak
2021-07-29T15:49:47.669Z,1627573787.669 [DataOverHttps](INFO): SBD MOMSN=15773817
2021-07-29T15:50:07.351Z,1627573807.351 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20210729T153842/Express0007.lzma
2021-07-29T15:50:08.229Z,1627573808.229 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-07-29T15:50:08.311Z,1627573808.311 [NAL9602](ERROR): received: 
+CSQ:0

OK605, 2, 0, 0, 0

OK
2021-07-29T15:50:08.431Z,1627573808.431 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Express0007.lzma.bak
2021-07-29T15:50:08.431Z,1627573808.431 [DataOverHttps](INFO): SBD MOMSN=15773820
2021-07-29T15:50:11.928Z,1627573811.928 [Default:CheckIn:Read_Iridium] Stopped
2021-07-29T15:50:11.929Z,1627573811.929 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-29T15:50:11.929Z,1627573811.929 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-29T15:51:02.474Z,1627573862.474 [NAL9602](INFO): SBD MO Status=2, MOMSN=28605, MT Status=2, MTMSN=0
2021-07-29T15:51:02.474Z,1627573862.474 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2021-07-29T15:54:39.513Z,1627574079.513 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-29T15:55:10.216Z,1627574110.216 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-29T15:55:12.656Z,1627574112.656 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-29T15:55:12.656Z,1627574112.656 [Default:CheckIn:C.Wait] Stopped
2021-07-29T15:55:12.656Z,1627574112.656 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-29T15:55:12.656Z,1627574112.656 [Default:CheckIn:D] Running Loop=1
2021-07-29T15:55:13.058Z,1627574113.058 [Default:CheckIn:D] Stopped
2021-07-29T15:55:13.058Z,1627574113.058 [Default:CheckIn:E] Running Loop=1
2021-07-29T15:55:13.481Z,1627574113.481 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1002.063542 min 
2021-07-29T15:55:13.481Z,1627574113.481 [Default:CheckIn:E] Stopped
2021-07-29T15:55:13.481Z,1627574113.481 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-29T15:55:13.481Z,1627574113.481 [Default:CheckIn] Stopped
2021-07-29T15:55:13.482Z,1627574113.482 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-29T15:55:13.482Z,1627574113.482 [Default:CheckIn](INFO): Running loop #175
2021-07-29T15:55:13.482Z,1627574113.482 [Default:CheckIn] Running Loop=175
2021-07-29T15:55:13.482Z,1627574113.482 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-29T15:55:13.482Z,1627574113.482 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-29T15:55:15.475Z,1627574115.475 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155514.00,A,3648.16142,N,12147.28596,W,0.194,233.20,290721,,,D*73
2021-07-29T15:55:15.477Z,1627574115.477 [NAL9602](INFO): GPS fix at 20210729T155514: (36.802690, -121.788099)
2021-07-29T15:55:15.517Z,1627574115.517 [Default:CheckIn:Read_GPS] Stopped
2021-07-29T15:55:15.517Z,1627574115.517 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-29T15:55:23.899Z,1627574123.899 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210729T153842/Courier0009.lzma
2021-07-29T15:55:24.910Z,1627574124.910 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Courier0009.lzma.bak
2021-07-29T15:55:24.910Z,1627574124.910 [DataOverHttps](INFO): SBD MOMSN=15773847
2021-07-29T15:55:43.295Z,1627574143.295 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20210729T153842/Express0010.lzma
2021-07-29T15:55:44.298Z,1627574144.298 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Express0010.lzma.bak
2021-07-29T15:55:44.298Z,1627574144.298 [DataOverHttps](INFO): SBD MOMSN=15773850
2021-07-29T15:55:47.858Z,1627574147.858 [Default:CheckIn:Read_Iridium] Stopped
2021-07-29T15:55:47.858Z,1627574147.858 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-29T15:55:47.858Z,1627574147.858 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-29T16:00:17.321Z,1627574417.321 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-29T16:00:48.024Z,1627574448.024 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-29T16:00:48.448Z,1627574448.448 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-29T16:00:48.448Z,1627574448.448 [Default:CheckIn:C.Wait] Stopped
2021-07-29T16:00:48.448Z,1627574448.448 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-29T16:00:48.448Z,1627574448.448 [Default:CheckIn:D] Running Loop=1
2021-07-29T16:00:48.861Z,1627574448.861 [Default:CheckIn:D] Stopped
2021-07-29T16:00:48.861Z,1627574448.861 [Default:CheckIn:E] Running Loop=1
2021-07-29T16:00:49.249Z,1627574449.249 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1007.660286 min 
2021-07-29T16:00:49.249Z,1627574449.249 [Default:CheckIn:E] Stopped
2021-07-29T16:00:49.249Z,1627574449.249 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-29T16:00:49.249Z,1627574449.249 [Default:CheckIn] Stopped
2021-07-29T16:00:49.249Z,1627574449.249 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-29T16:00:49.249Z,1627574449.249 [Default:CheckIn](INFO): Running loop #176
2021-07-29T16:00:49.250Z,1627574449.250 [Default:CheckIn] Running Loop=176
2021-07-29T16:00:49.250Z,1627574449.250 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-29T16:00:49.250Z,1627574449.250 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-29T16:00:51.262Z,1627574451.262 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160050.00,A,3648.16397,N,12147.28470,W,0.292,282.02,290721,,,D*7C
2021-07-29T16:00:51.265Z,1627574451.265 [NAL9602](INFO): GPS fix at 20210729T160050: (36.802733, -121.788078)
2021-07-29T16:00:51.277Z,1627574451.277 [Default:CheckIn:Read_GPS] Stopped
2021-07-29T16:00:51.277Z,1627574451.277 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-29T16:00:59.959Z,1627574459.959 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210729T153842/Courier0012.lzma
2021-07-29T16:01:00.961Z,1627574460.961 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Courier0012.lzma.bak
2021-07-29T16:01:00.961Z,1627574460.961 [DataOverHttps](INFO): SBD MOMSN=15773854
2021-07-29T16:01:19.431Z,1627574479.431 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20210729T153842/Express0013.lzma
2021-07-29T16:01:20.433Z,1627574480.433 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Express0013.lzma.bak
2021-07-29T16:01:20.433Z,1627574480.433 [DataOverHttps](INFO): SBD MOMSN=15773857
2021-07-29T16:01:21.961Z,1627574481.961 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-07-29T16:01:22.043Z,1627574482.043 [NAL9602](ERROR): received: 
+CSQ:0

OK605, 2, 0, 0, 0

OK
2021-07-29T16:01:24.413Z,1627574484.413 [Default:CheckIn:Read_Iridium] Stopped
2021-07-29T16:01:24.413Z,1627574484.413 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-29T16:01:24.413Z,1627574484.413 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-29T16:05:53.481Z,1627574753.481 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-29T16:06:24.180Z,1627574784.180 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-29T16:06:25.005Z,1627574785.005 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-29T16:06:25.005Z,1627574785.005 [Default:CheckIn:C.Wait] Stopped
2021-07-29T16:06:25.005Z,1627574785.005 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-29T16:06:25.006Z,1627574785.006 [Default:CheckIn:D] Running Loop=1
2021-07-29T16:06:25.416Z,1627574785.416 [Default:CheckIn:D] Stopped
2021-07-29T16:06:25.416Z,1627574785.416 [Default:CheckIn:E] Running Loop=1
2021-07-29T16:06:25.828Z,1627574785.828 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1013.269531 min 
2021-07-29T16:06:25.828Z,1627574785.828 [Default:CheckIn:E] Stopped
2021-07-29T16:06:25.829Z,1627574785.829 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-29T16:06:25.829Z,1627574785.829 [Default:CheckIn] Stopped
2021-07-29T16:06:25.829Z,1627574785.829 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-29T16:06:25.829Z,1627574785.829 [Default:CheckIn](INFO): Running loop #177
2021-07-29T16:06:25.829Z,1627574785.829 [Default:CheckIn] Running Loop=177
2021-07-29T16:06:25.829Z,1627574785.829 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-29T16:06:25.829Z,1627574785.829 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-29T16:06:27.819Z,1627574787.819 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160627.00,A,3648.16400,N,12147.28685,W,0.486,282.02,290721,,,D*78
2021-07-29T16:06:27.821Z,1627574787.821 [NAL9602](INFO): GPS fix at 20210729T160627: (36.802733, -121.788114)
2021-07-29T16:06:27.841Z,1627574787.841 [Default:CheckIn:Read_GPS] Stopped
2021-07-29T16:06:27.841Z,1627574787.841 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-29T16:06:38.335Z,1627574798.335 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210729T153842/Courier0015.lzma
2021-07-29T16:06:39.337Z,1627574799.337 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Courier0015.lzma.bak
2021-07-29T16:06:39.337Z,1627574799.337 [DataOverHttps](INFO): SBD MOMSN=15773884
2021-07-29T16:06:57.703Z,1627574817.703 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20210729T153842/Express0016.lzma
2021-07-29T16:06:58.534Z,1627574818.534 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-07-29T16:06:58.611Z,1627574818.611 [NAL9602](ERROR): received: 
+CSQ:0

OK605, 2, 0, 0, 0

OK
2021-07-29T16:06:58.705Z,1627574818.705 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Express0016.lzma.bak
2021-07-29T16:06:58.705Z,1627574818.705 [DataOverHttps](INFO): SBD MOMSN=15773887
2021-07-29T16:07:03.002Z,1627574823.002 [Default:CheckIn:Read_Iridium] Stopped
2021-07-29T16:07:03.002Z,1627574823.002 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-29T16:07:03.002Z,1627574823.002 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-29T16:11:30.513Z,1627575090.513 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-29T16:12:01.212Z,1627575121.212 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-29T16:12:03.663Z,1627575123.663 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-29T16:12:03.663Z,1627575123.663 [Default:CheckIn:C.Wait] Stopped
2021-07-29T16:12:03.663Z,1627575123.663 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-29T16:12:03.664Z,1627575123.664 [Default:CheckIn:D] Running Loop=1
2021-07-29T16:12:04.051Z,1627575124.051 [Default:CheckIn:D] Stopped
2021-07-29T16:12:04.052Z,1627575124.052 [Default:CheckIn:E] Running Loop=1
2021-07-29T16:12:04.461Z,1627575124.461 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1018.913411 min 
2021-07-29T16:12:04.461Z,1627575124.461 [Default:CheckIn:E] Stopped
2021-07-29T16:12:04.461Z,1627575124.461 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-29T16:12:04.461Z,1627575124.461 [Default:CheckIn] Stopped
2021-07-29T16:12:04.461Z,1627575124.461 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-29T16:12:04.461Z,1627575124.461 [Default:CheckIn](INFO): Running loop #178
2021-07-29T16:12:04.461Z,1627575124.461 [Default:CheckIn] Running Loop=178
2021-07-29T16:12:04.461Z,1627575124.461 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-29T16:12:04.462Z,1627575124.462 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-29T16:12:06.474Z,1627575126.474 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161205.00,A,3648.16114,N,12147.27379,W,0.914,113.06,290721,,,D*7D
2021-07-29T16:12:06.477Z,1627575126.477 [NAL9602](INFO): GPS fix at 20210729T161205: (36.802686, -121.787897)
2021-07-29T16:12:06.489Z,1627575126.489 [Default:CheckIn:Read_GPS] Stopped
2021-07-29T16:12:06.489Z,1627575126.489 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-29T16:12:14.975Z,1627575134.975 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210729T153842/Courier0018.lzma
2021-07-29T16:12:15.977Z,1627575135.977 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Courier0018.lzma.bak
2021-07-29T16:12:15.977Z,1627575135.977 [DataOverHttps](INFO): SBD MOMSN=15773891
2021-07-29T16:12:36.921Z,1627575156.921 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-07-29T16:12:36.995Z,1627575156.995 [NAL9602](ERROR): received: 
+CSQ:0

OK605, 2, 0, 0, 0

OK
2021-07-29T16:12:43.475Z,1627575163.475 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20210729T153842/Express0019.lzma
2021-07-29T16:12:44.477Z,1627575164.477 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Express0019.lzma.bak
2021-07-29T16:12:44.477Z,1627575164.477 [DataOverHttps](INFO): SBD MOMSN=15773894
2021-07-29T16:12:48.273Z,1627575168.273 [Default:CheckIn:Read_Iridium] Stopped
2021-07-29T16:12:48.273Z,1627575168.273 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-29T16:12:48.273Z,1627575168.273 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2021-07-29T16:17:08.429Z,1627575428.429 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-07-29T16:17:39.133Z,1627575459.133 [NAL9602](INFO): Not Powering down - fast GPS
2021-07-29T16:17:48.841Z,1627575468.841 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2021-07-29T16:17:48.842Z,1627575468.842 [Default:CheckIn:C.Wait] Stopped
2021-07-29T16:17:48.842Z,1627575468.842 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2021-07-29T16:17:48.843Z,1627575468.843 [Default:CheckIn:D] Running Loop=1
2021-07-29T16:17:49.257Z,1627575469.257 [Default:CheckIn:D] Stopped
2021-07-29T16:17:49.257Z,1627575469.257 [Default:CheckIn:E] Running Loop=1
2021-07-29T16:17:49.652Z,1627575469.652 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 1024.666862 min 
2021-07-29T16:17:49.652Z,1627575469.652 [Default:CheckIn:E] Stopped
2021-07-29T16:17:49.652Z,1627575469.652 [Default:CheckIn](INFO): Completed Default:CheckIn
2021-07-29T16:17:49.652Z,1627575469.652 [Default:CheckIn] Stopped
2021-07-29T16:17:49.653Z,1627575469.653 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-07-29T16:17:49.653Z,1627575469.653 [Default:CheckIn](INFO): Running loop #179
2021-07-29T16:17:49.653Z,1627575469.653 [Default:CheckIn] Running Loop=179
2021-07-29T16:17:49.653Z,1627575469.653 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-07-29T16:17:49.653Z,1627575469.653 [Default:CheckIn:Read_GPS] Running Loop=1
2021-07-29T16:17:51.663Z,1627575471.663 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161750.00,A,3648.16457,N,12147.28200,W,0.039,187.38,290721,,,D*7C
2021-07-29T16:17:51.665Z,1627575471.665 [NAL9602](INFO): GPS fix at 20210729T161750: (36.802743, -121.788033)
2021-07-29T16:17:51.681Z,1627575471.681 [Default:CheckIn:Read_GPS] Stopped
2021-07-29T16:17:51.681Z,1627575471.681 [Default:CheckIn:Read_Iridium] Running Loop=1
2021-07-29T16:17:59.899Z,1627575479.899 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20210729T153842/Courier0021.lzma
2021-07-29T16:18:00.904Z,1627575480.904 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Courier0021.lzma.bak
2021-07-29T16:18:00.904Z,1627575480.904 [DataOverHttps](INFO): SBD MOMSN=15773916
2021-07-29T16:18:21.132Z,1627575501.132 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20210729T153842/Express0022.lzma
2021-07-29T16:18:22.134Z,1627575502.134 [DataOverHttps](INFO): Moved sent file to Logs/20210729T153842/Express0022.lzma.bak
2021-07-29T16:18:22.134Z,1627575502.134 [DataOverHttps](IMPORTANT): SBD MOMSN=15773920, MTMSN=20210729T161821
2021-07-29T16:18:22.385Z,1627575502.385 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2021-07-29T16:18:22.463Z,1627575502.463 [NAL9602](ERROR): received: 
+CSQ:1

OK605, 2, 0, 0, 0

OK
2021-07-29T16:18:28.138Z,1627575508.138 [Default:CheckIn:Read_Iridium] Stopped
2021-07-29T16:18:28.138Z,1627575508.138 [Default:CheckIn:C.Wait] Running Loop=1
2021-07-29T16:18:28.139Z,1627575508.139 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.