search

NordicSemiconductor / NAT-TestFirmware

Used to test NAT timeouts, sends messages to NAT-TestServer
Other
0 stars 0 forks source link

TCP Test does not enter binary search if first interval fails #33

Closed coderbyheart closed 4 years ago

coderbyheart commented 4 years ago

Version: 2.4.0

[2020-06-22 14:30:40] Finished NAT timeout measurements
[2020-06-22 14:30:40] Max keep-alive time: 3 seconds
[2020-06-22 14:30:40] Connected to server
[2020-06-22 14[00:00:32.065,246] <dbg> modem_info.modem_info_string_get: Device contains 1 IP addresses
[2020-06-22 14:30:40] uart:~$ Packet sent: {
[2020-06-22 14:30:40]   "ip":   ["10.81.176.109"],
[2020-06-22 14:30:40]   "op":   "24202",
[2020-06-22 14:30:40]   "cell_id":      35493142,
[2020-06-22 14:30:40]   "ue_mode":      2,
[2020-06-22 14:30:40]   "lte_mode":     1,
[2020-06-22 14:30:40]   "nbiot_mode":   0,
[2020-06-22 14:30:40]   "iccid":        "89450421180216254864",
[2020-06-22 14:30:40]   "imei": "352656100378903",
[2020-06-22 14:30:40]   "interval":     300
[2020-06-22 14:30:40] }
[2020-06-22 14[00:00:35.766,662] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:30:44] uart:~$ Elapsed time: 60 of 310 seconds (10 seconds tolerance)
[2020-06-22 14[00:01:51.882,232] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:32:00] [00:01:57.307,922] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:32:05] uart:~$ Elapsed time: 120 of 310 seconds (10 seconds tolerance)
[2020-06-22 14:33:40] Elapsed time: 180 of 310 seconds (10 seconds tolerance)
[2020-06-22 14:34:40] Elapsed time: 240 of 310 seconds (10 seconds tolerance)
[2020-06-22 14[00:04:35.585,845] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:34:43] [00:04:40.972,320] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:34:49] uart:~$ Elapsed time: 300 of 310 seconds (10 seconds tolerance)
[2020-06-22 14:35:52] No response from server
[2020-06-22 14[00:05:44.345,855] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:35:52] [00:05:52.541,351] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:36:00] [00:05:52.957,000] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:36:01] [00:05:58.451,690] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:36:06] [00:05:58.716,094] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:36:07] uart:~$ connect failed, errno: 116
Test idle22 14:36:10] 
[2020-06-22 14[00:06:04.851,226] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:36:13] [00:06:17.146,118] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:36:25] [00:06:22.651,794] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:36:30] [00:06:31.616,333] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:36:39] [00:06:37.121,704] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:36:45] [00:06:59.916,809] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:37:08] [00:07:05.377,502] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:37:13] [00:07:55.177,673] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:38:03] [00:08:00.574,279] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 14:38:08] uart:~$
boundlesscalm commented 4 years ago

The test is aborted as the device failed to connect to the server. Should probably print that instead of only "connect failed". Has this been the case every tcp test?

coderbyheart commented 4 years ago

No, the server works.

What if the TCP NAT timeout is <300 but >0? We should at least try to detect that.

boundlesscalm commented 4 years ago

It would go into binary search. The issue is that after it detects a timeout it will close the connection and open a new one to the server. In your case it failed to connect anew and thus aborted the test. If this happens every single time then maybe there is an issue in how the connection is handled on closing that I need to look into. If this happened just once then it would mean the device somehow failed to connect.

coderbyheart commented 4 years ago

Second test: I hit reset after the time limit was way over, so somehow the timer never triggered the next run.

[2020-06-22 17:02:36] Finished NAT timeout measurements
[2020-06-22 17:02:36] Max keep-alive time: 3 seconds
[2020-06-22 17:02:36] Connected to server
[2020-06-22 17[00:00:27.634,765] <dbg> modem_info.modem_info_string_get: Device contains 1 IP addresses
[2020-06-22 17:02:36] uart:~$ Packet sent: {
[2020-06-22 17:02:36]   "ip":   ["10.81.170.176"],
[2020-06-22 17:02:36]   "op":   "24202",
[2020-06-22 17:02:36]   "cell_id":      35493142,
[2020-06-22 17:02:36]   "ue_mode":      2,
[2020-06-22 17:02:36]   "lte_mode":     1,
[2020-06-22 17:02:36]   "nbiot_mode":   0,
[2020-06-22 17:02:36]   "iccid":        "89450421180216254864",
[2020-06-22 17:02:36]   "imei": "352656100378903",
[2020-06-22 17:02:36]   "interval":     300
[2020-06-22 17:02:36] }
[2020-06-22 17[00:00:31.304,687] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:02:39] [00:00:31.437,866] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 5,"0901","020A930C",7,,,"11100000","11100000"
[2020-06-22 17:02:39] 
[2020-06-22 17:02:39] [00:00:31.453,643] <dbg> lte_lc.parse_psm_cfg: TAU: -1 sec, active time: -1 sec
                                                                                                     [2020-06-22 17:02:39] 
[2020-06-22 17:02:39] uart:~$ Elapsed time: 60 of 310 seconds (10 seconds tolerance)
[2020-06-22 17:04:36] Elapsed time: 120 of 310 seconds (10 seconds tolerance)
[2020-06-22 17[00:02:50.353,088] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:04:58] [00:02:55.755,493] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:05:04] [00:02:56.078,430] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 5,"0901","021D9516",7,,,"11100000","11100000"
[2020-06-22 17:05:04] 
[2020-06-22 17:05:04] [00:02:56.094,207] <dbg> lte_lc.parse_psm_cfg: TAU: -1 sec, active time: -1 sec
                                                                                                     [2020-06-22 17:05:04] 
[2020-06-22 17:05:04] uart:~$ Elapsed time: 180 of 310 seconds (10 seconds tolerance)
[2020-06-22 17:06:36] Elapsed time: 240 of 310 seconds (10 seconds tolerance)
[2020-06-22 17:07:36] Elapsed time: 300 of 310 seconds (10 seconds tolerance)
[2020-06-22 17[00:05:33.633,972] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:07:42] uart:~$
coderbyheart commented 4 years ago

Third run now does a binary search:

[2020-06-22 17:10:58] Finished NAT timeout measurements
[2020-06-22 17:10:58] Max keep-alive time: 3 seconds
[2020-06-22 17:10:58] Connected to server
[2020-06-22 17[00:00:27.561,737] <dbg> modem_info.modem_info_string_get: Device contains 1 IP addresses
[2020-06-22 17:10:58] uart:~$ Packet sent: {
[2020-06-22 17:10:58]   "ip":   ["10.81.172.44"],
[2020-06-22 17:10:58]   "op":   "24202",
[2020-06-22 17:10:58]   "cell_id":      34247436,
[2020-06-22 17:10:58]   "ue_mode":      2,
[2020-06-22 17:10:58]   "lte_mode":     1,
[2020-06-22 17:10:58]   "nbiot_mode":   0,
[2020-06-22 17:10:58]   "iccid":        "89450421180216254864",
[2020-06-22 17:10:58]   "imei": "352656100378903",
[2020-06-22 17:10:58]   "interval":     300
[2020-06-22 17:10:58] }
[2020-06-22 17[00:00:31.271,942] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:11:02] [00:00:31.362,609] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 5,"0901","021D9516",7,,,"11100000","11100000"
[2020-06-22 17:11:02] 
[2020-06-22 17:11:02] [00:00:31.378,387] <dbg> lte_lc.parse_psm_cfg: TAU: -1 sec, active time: -1 sec
                                                                                                     [2020-06-22 17:11:02] 
[2020-06-22 17:11:02] uart:~$ Elapsed time: 60 of 310 seconds (10 seconds tolerance)
[2020-06-22 17:12:58] Elapsed time: 120 of 310 seconds (10 seconds tolerance)
[2020-06-22 17[00:02:38.907,623] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:13:09] [00:02:44.304,199] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:13:15] uart:~$ Elapsed time: 180 of 310 seconds (10 seconds tolerance)
[2020-06-22 17:14:58] Elapsed time: 240 of 310 seconds (10 seconds tolerance)
[2020-06-22 17[00:05:19.520,233] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:15:50] [00:05:25.115,600] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:15:56] uart:~$ Elapsed time: 300 of 310 seconds (10 seconds tolerance)
[2020-06-22 17:16:10] No response from server
[2020-06-22 17[00:05:39.830,444] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:16:10] uart:~$ Connected to server
[2020-06-22 17:16:10] Packet sent: {
[2020-06-22 17:16:10]   "ip":   ["10.81.172.44"],
[2020-06-22 17:16:10]   "op":   "24202",
[2020-06-22 17:16:10]   "cell_id":      34247436,
[2020-06-22 17:16:10]   "ue_mode":      2,
[2020-06-22 17:16:10]   "lte_mode":     1,
[2020-06-22 17:16:10]   "nbiot_mode":   0,
[2020-06-22 17:16:10]   "iccid":        "89450421180216254864",
[2020-06-22 17:16:10]   "imei": "352656100378903",
[2020-06-22 17:16:10]   "interval":     150
[2020-06-22 17:16:10] }
[2020-06-22 17[00:05:47.151,275] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:16:18] [00:05:51.861,785] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:16:22] [00:05:58.721,466] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:16:29] [00:05:59.441,864] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:16:30] [00:06:04.956,542] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:16:35] [00:06:14.241,088] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:16:45] [00:06:19.876,800] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:16:50] uart:~$ Elapsed time: 60 of 160 seconds (10 seconds tolerance)
[2020-06-22 17[00:06:42.541,595] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:17:13] [00:06:48.046,936] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:17:19] [00:07:37.962,432] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:18:08] uart:~$ Elapsed time: 120 of 160 seconds (10 seconds tolerance)
[2020-06-22 17[00:07:43.487,792] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:18:14] [00:08:03.503,906] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:18:34] [00:08:09.108,551] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:18:40] [00:08:10.362,976] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:18:41] uart:~$ Response: Interval: 150
[2020-06-22 17:18:41] Returned: 2020-06-22T15:18:40.99+0000
[2020-06-22 17:18:41] Version:  v2.7.7
[2020-06-22 17:18:41] TraceID:  b9a4b487-b31d-4cbf-b632-c4f3a9892660
[2020-06-22 17:18:41] 
[2020-06-22 17:18:41] Packet sent: {
[2020-06-22 17:18:41]   "ip":   ["10.81.172.44"],
[2020-06-22 17:18:41]   "op":   "24202",
[2020-06-22 17:18:41]   "cell_id":      34247436,
[2020-06-22 17:18:41]   "ue_mode":      2,
[2020-06-22 17:18:41]   "lte_mode":     1,
[2020-06-22 17:18:41]   "nbiot_mode":   0,
[2020-06-22 17:18:41]   "iccid":        "89450421180216254864",
[2020-06-22 17:18:41]   "imei": "352656100378903",
[2020-06-22 17:18:41]   "interval":     225
[2020-06-22 17:18:41] }
[2020-06-22 17[00:08:15.878,356] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:18:46] uart:~$ Elapsed time: 60 of 235 seconds (10 seconds tolerance)
[2020-06-22 17[00:09:29.545,227] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:20:00] [00:09:35.019,927] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:20:06] uart:~$ Elapsed time: 120 of 235 seconds (10 seconds tolerance)
[2020-06-22 17[00:10:47.235,504] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:21:18] [00:10:52.736,633] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:21:23] uart:~$ Elapsed time: 180 of 235 seconds (10 seconds tolerance)
[2020-06-22 17:22:38] No response from server
[2020-06-22 17[00:12:07.737,701] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:22:38] uart:~$ Connected to server
[2020-06-22 17:22:38] Packet sent: {
[2020-06-22 17:22:38]   "ip":   ["10.81.172.44"],
[2020-06-22 17:22:38]   "op":   "24202",
[2020-06-22 17:22:38]   "cell_id":      34247436,
[2020-06-22 17:22:38]   "ue_mode":      2,
[2020-06-22 17:22:38]   "lte_mode":     1,
[2020-06-22 17:22:38]   "nbiot_mode":   0,
[2020-06-22 17:22:38]   "iccid":        "89450421180216254864",
[2020-06-22 17:22:38]   "imei": "352656100378903",
[2020-06-22 17:22:38]   "interval":     187
[2020-06-22 17:22:38] }
[2020-06-22 17[00:12:13.347,473] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:22:44] [00:13:00.228,637] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:23:31] [00:13:05.703,338] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:23:36] uart:~$ Elapsed time: 60 of 197 seconds (10 seconds tolerance)
[2020-06-22 17[00:13:31.069,122] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:24:02] [00:13:36.477,539] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:24:07] uart:~$ Elapsed time: 120 of 197 seconds (10 seconds tolerance)
[2020-06-22 17:25:38] Elapsed time: 180 of 197 seconds (10 seconds tolerance)
[2020-06-22 17:25:56] No response from server
[2020-06-22 17[00:15:26.120,880] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:25:57] uart:~$ Connected to server
[2020-06-22 17:25:57] Packet sent: {
[2020-06-22 17:25:57]   "ip":   ["10.81.172.44"],
[2020-06-22 17:25:57]   "op":   "24202",
[2020-06-22 17:25:57]   "cell_id":      34247436,
[2020-06-22 17:25:57]   "ue_mode":      2,
[2020-06-22 17:25:57]   "lte_mode":     1,
[2020-06-22 17:25:57]   "nbiot_mode":   0,
[2020-06-22 17:25:57]   "iccid":        "89450421180216254864",
[2020-06-22 17:25:57]   "imei": "352656100378903",
[2020-06-22 17:25:57]   "interval":     168
[2020-06-22 17:25:57] }
[2020-06-22 17[00:15:31.610,687] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:26:02] [00:15:31.862,091] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 5,"0901","02026620",7,,,"11100000","11100000"
[2020-06-22 17:26:02] 
[2020-06-22 17:26:02] [00:15:31.877,929] <dbg> lte_lc.parse_psm_cfg: TAU: -1 sec, active time: -1 sec
                                                                                                     [2020-06-22 17:26:02] 
[2020-06-22 17:26:02] [00:16:21.099,365] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:26:52] [00:16:26.720,306] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:26:57] uart:~$ Elapsed time: 60 of 178 seconds (10 seconds tolerance)
[2020-06-22 17[00:16:26.899,749] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 5,"0901","020A930C",7,,,"11100000","11100000"
[2020-06-22 17:26:57] 
[2020-06-22 17:26:57] [00:16:26.915,557] <dbg> lte_lc.parse_psm_cfg: TAU: -1 sec, active time: -1 sec
                                                                                                     [2020-06-22 17:26:57] 
[2020-06-22 17:26:57] [00:16:42.899,108] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 5,"0901","021D950C",7,,,"11100000","11100000"
[2020-06-22 17:27:13] 
[2020-06-22 17:27:13] [00:16:42.914,947] <dbg> lte_lc.parse_psm_cfg: TAU: -1 sec, active time: -1 sec
                                                                                                     [2020-06-22 17:27:13] 
[2020-06-22 17:27:13] [00:16:43.388,763] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:27:14] [00:16:44.417,816] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 5,"0901","021D9516",7,,,"11100000","11100000"
[2020-06-22 17:27:15] 
[2020-06-22 17:27:15] [00:16:44.433,654] <dbg> lte_lc.parse_psm_cfg: TAU: -1 sec, active time: -1 sec
                                                                                                     [2020-06-22 17:27:15] 
[2020-06-22 17:27:15] [00:16:47.906,890] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:27:18] [00:16:48.020,080] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 5,"0901","020A930C",7,,,"11100000","11100000"
[2020-06-22 17:27:19] 
[2020-06-22 17:27:19] [00:16:48.035,919] <dbg> lte_lc.parse_psm_cfg: TAU: -1 sec, active time: -1 sec
                                                                                                     [2020-06-22 17:27:19] 
[2020-06-22 17:27:19] uart:~$ Elapsed time: 120 of 178 seconds (10 seconds tolerance)
[2020-06-22 17:28:57] No response from server
[2020-06-22 17[00:18:26.973,571] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:28:57] uart:~$ Connected to server
[2020-06-22 17:28:58] Packet sent: {
[2020-06-22 17:28:58]   "ip":   ["10.81.172.44"],
[2020-06-22 17:28:58]   "op":   "24202",
[2020-06-22 17:28:58]   "cell_id":      34247436,
[2020-06-22 17:28:58]   "ue_mode":      2,
[2020-06-22 17:28:58]   "lte_mode":     1,
[2020-06-22 17:28:58]   "nbiot_mode":   0,
[2020-06-22 17:28:58]   "iccid":        "89450421180216254864",
[2020-06-22 17:28:58]   "imei": "352656100378903",
[2020-06-22 17:28:58]   "interval":     159
[2020-06-22 17:28:58] }
[2020-06-22 17[00:18:32.464,355] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:29:03] [00:18:59.114,166] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:29:30] [00:19:04.699,890] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:29:35] uart:~$ Elapsed time: 60 of 169 seconds (10 seconds tolerance)
[2020-06-22 17[00:20:14.205,383] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:30:45] [00:20:19.830,780] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:30:50] [00:20:20.502,807] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 5,"0901","021D9516",7,,,"11100000","11100000"
[2020-06-22 17:30:51] 
[2020-06-22 17:30:51] [00:20:20.518,585] <dbg> lte_lc.parse_psm_cfg: TAU: -1 sec, active time: -1 sec
                                                                                                     [2020-06-22 17:30:51] 
[2020-06-22 17:30:51] uart:~$ Elapsed time: 120 of 169 seconds (10 seconds tolerance)
[2020-06-22 17:31:49] No response from server
[2020-06-22 17[00:21:18.385,559] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:31:49] uart:~$ Connected to server
[2020-06-22 17:31:49] Packet sent: {
[2020-06-22 17:31:49]   "ip":   ["10.81.172.44"],
[2020-06-22 17:31:49]   "op":   "24202",
[2020-06-22 17:31:49]   "cell_id":      34247436,
[2020-06-22 17:31:49]   "ue_mode":      2,
[2020-06-22 17:31:49]   "lte_mode":     1,
[2020-06-22 17:31:49]   "nbiot_mode":   0,
[2020-06-22 17:31:49]   "iccid":        "89450421180216254864",
[2020-06-22 17:31:49]   "imei": "352656100378903",
[2020-06-22 17:31:49]   "interval":     154
[2020-06-22 17:31:49] }
[2020-06-22 17[00:21:23.766,357] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:31:54] [00:21:42.676,055] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:32:13] [00:21:48.082,611] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:32:19] [00:21:49.576,141] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:32:20] [00:21:55.181,610] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:32:26] uart:~$ Elapsed time: 60 of 164 seconds (10 seconds tolerance)
[2020-06-22 17:33:49] Elapsed time: 120 of 164 seconds (10 seconds tolerance)
[2020-06-22 17:34:34] No response from server
[2020-06-22 17[00:24:03.749,206] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:34:34] uart:~$ Connected to server
[2020-06-22 17:34:34] Packet sent: {
[2020-06-22 17:34:34]   "ip":   ["10.81.172.44"],
[2020-06-22 17:34:34]   "op":   "24202",
[2020-06-22 17:34:34]   "cell_id":      34247436,
[2020-06-22 17:34:34]   "ue_mode":      2,
[2020-06-22 17:34:34]   "lte_mode":     1,
[2020-06-22 17:34:34]   "nbiot_mode":   0,
[2020-06-22 17:34:34]   "iccid":        "89450421180216254864",
[2020-06-22 17:34:34]   "imei": "352656100378903",
[2020-06-22 17:34:34]   "interval":     152
[2020-06-22 17:34:34] }
[2020-06-22 17[00:24:09.179,016] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:34:40] [00:24:09.626,251] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 5,"0901","020A930C",7,,,"11100000","11100000"
[2020-06-22 17:34:40] 
[2020-06-22 17:34:40] [00:24:09.642,059] <dbg> lte_lc.parse_psm_cfg: TAU: -1 sec, active time: -1 sec
                                                                                                     [2020-06-22 17:34:40] 
[2020-06-22 17:34:40] [00:24:29.579,498] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:35:00] [00:24:34.970,184] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:35:05] [00:24:35.225,860] <dbg> lte_lc.at_handler: +CEREG notification: +CEREG: 5,"0901","021D9516",7,,,"11100000","11100000"
[2020-06-22 17:35:06] 
[2020-06-22 17:35:06] [00:24:35.241,668] <dbg> lte_lc.parse_psm_cfg: TAU: -1 sec, active time: -1 sec
                                                                                                     [2020-06-22 17:35:06] 
[2020-06-22 17:35:06] uart:~$ Elapsed time: 60 of 162 seconds (10 seconds tolerance)
[2020-06-22 17:36:34] Elapsed time: 120 of 162 seconds (10 seconds tolerance)
[2020-06-22 17:37:16] No response from server
[2020-06-22 17[00:26:46.121,826] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:37:17] uart:~$ Connected to server
[2020-06-22 17:37:17] Packet sent: {
[2020-06-22 17:37:17]   "ip":   ["10.81.172.44"],
[2020-06-22 17:37:17]   "op":   "24202",
[2020-06-22 17:37:17]   "cell_id":      34247436,
[2020-06-22 17:37:17]   "ue_mode":      2,
[2020-06-22 17:37:17]   "lte_mode":     1,
[2020-06-22 17:37:17]   "nbiot_mode":   0,
[2020-06-22 17:37:17]   "iccid":        "89450421180216254864",
[2020-06-22 17:37:17]   "imei": "352656100378903",
[2020-06-22 17:37:17]   "interval":     151
[2020-06-22 17:37:17] }
[2020-06-22 17[00:26:53.256,378] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:37:24] [00:26:56.481,109] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:37:27] [00:27:02.046,783] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:37:33] [00:27:03.761,260] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:37:34] [00:27:09.127,380] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:37:40] [00:27:18.471,466] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:37:49] [00:27:23.936,828] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:37:54] uart:~$ Elapsed time: 60 of 161 seconds (10 seconds tolerance)
[2020-06-22 17[00:27:47.951,934] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:38:18] [00:27:53.417,297] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:38:24] [00:28:44.773,834] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:39:15] uart:~$ Elapsed time: 120 of 161 seconds (10 seconds tolerance)
[2020-06-22 17[00:28:50.253,540] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:39:21] uart:~$ No response from server
[2020-06-22 17[00:29:28.563,446] <dbg> lte_lc.at_handler: +CSCON notification
[2020-06-22 17:39:59] uart:~$ Connected to server
[2020-06-22 17:39:59] Finished NAT timeout measurements
[2020-06-22 17:39:59] Max keep-alive time: 150 seconds
[2020-06-22 17:39:59] Test idle
coderbyheart commented 4 years ago

Seems to have been a temporary glitch.