search

NordicSemiconductor / NAT-TestFirmware

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

UDP measuring is not handling server messages correctly, detects to low timeout #16

Closed coderbyheart closed 4 years ago

coderbyheart commented 4 years ago

The device is not handling the server responses correctly and seems to ignore the 4 second message (it does arrive [2020-05-18 14:39:18] Response: Interval: 4), it then starts the binary search and sends an interval for 2 and 3 at the same time:

It then settles for 4 seconds result [2020-05-18 14:39:21] Keep-alive time set at: 4 seconds

Server logs

[e4441a67-11f4-4e20-874e-8c9d6303bc09] UDP Message received from 193.181.245.211:51210: interval 1
[e4441a67-11f4-4e20-874e-8c9d6303bc09] UDP Packet sent to 193.181.245.211:51210. Interval: 1.
[f3baf466-e68d-4960-9e72-1d420b9ca1b7] UDP Message received from 193.181.245.211:51210: interval 4
[f3baf466-e68d-4960-9e72-1d420b9ca1b7] UDP Packet sent to 193.181.245.211:51210. Interval: 4.
[d8618508-978b-4c2c-bdd0-2f04ec5c9383] UDP Message received from 193.181.245.211:51210: interval 2
[85d3c5f1-7656-463c-a1ae-8661927502af] UDP Message received from 193.181.245.211:51210: interval 3
[d8618508-978b-4c2c-bdd0-2f04ec5c9383] UDP Packet sent to 193.181.245.211:51210. Interval: 2.
[85d3c5f1-7656-463c-a1ae-8661927502af] UDP Packet sent to 193.181.245.211:51210. Interval: 3.
[85d3c5f1-7656-463c-a1ae-8661927502af] UDP connection to 193.181.245.211:51210 timed out. Connection terminated. Interval: 3.

Device logs

[2020-05-18 14:38:10] TCP client started
[2020-05-18 14:38:10] Version: 2.0.0
[2020-05-18 14:38:10] Setting up LTE connection
[2020-05-18 14:38:10] [00:00:00.204,284] <dbg> lte_lc.w_lte_lc_connect: Network mode: AT%XSYSTEMMODE=1,0,0,0
[2020-05-18 14:38:14] [00:00:04.422,546] <dbg> lte_lc.parse_nw_reg_status: Network registration status: 2
[2020-05-18 14:38:16] [00:00:06.134,490] <dbg> lte_lc.parse_nw_reg_status: Network registration status: 5
[2020-05-18 14:38:16] LTE connected
[2020-05-18 14:38:16] [00:00:06.146,759] <dbg> lte_lc.parse_nw_reg_status: Network registration status: 5
[2020-05-18 14:38:17] Connected to server with protocol UDP
[2020-05-18 14:38:17] Packet sent: {
[2020-05-18 14:38:17]   "ip":   ["10.81.170.83"],
[2020-05-18 14:38:17]   "op":   "24202",
[2020-05-18 14:38:17]   "cell_id":      34247436,
[2020-05-18 14:38:17]   "ue_mode":      2,
[2020-05-18 14:38:17]   "lte_mode":     1,
[2020-05-18 14:38:17]   "nbiot_mode":   0,
[2020-05-18 14:38:17]   "iccid":        "89450421180216254864",
[2020-05-18 14:38:17]   "imei": "352656100378903",
[2020-05-18 14:38:17]   "interval":     1
[2020-05-18 14:38:17] }
[2020-05-18 14:38:17] Waiting for response...
[2020-05-18 14:38:18] Response: Interval: 1
[2020-05-18 14:38:18] Returned: 2020-05-18T12:38:18.16+0000
[2020-05-18 14:38:18] Version:  v2.6.0
[2020-05-18 14:38:18] TraceID:  e4441a67-11f4-4e20-874e-8c9d6303bc09
[2020-05-18 14:38:18] 
[2020-05-18 14:38:18] Packet sent: {
[2020-05-18 14:38:18]   "ip":   ["10.81.170.83"],
[2020-05-18 14:38:18]   "op":   "24202",
[2020-05-18 14:38:18]   "cell_id":      34247436,
[2020-05-18 14:38:18]   "ue_mode":      2,
[2020-05-18 14:38:18]   "lte_mode":     1,
[2020-05-18 14:38:18]   "nbiot_mode":   0,
[2020-05-18 14:38:18]   "iccid":        "89450421180216254864",
[2020-05-18 14:38:18]   "imei": "352656100378903",
[2020-05-18 14:38:18]   "interval":     4
[2020-05-18 14:38:18] }
[2020-05-18 14:38:18] Waiting for response...
[2020-05-18 14:39:18] No response from server
[2020-05-18 14:39:18] Packet sent: {
[2020-05-18 14:39:18]   "ip":   ["10.81.170.83"],
[2020-05-18 14:39:18]   "op":   "24202",
[2020-05-18 14:39:18]   "cell_id":      34247436,
[2020-05-18 14:39:18]   "ue_mode":      2,
[2020-05-18 14:39:18]   "lte_mode":     1,
[2020-05-18 14:39:18]   "nbiot_mode":   0,
[2020-05-18 14:39:18]   "iccid":        "89450421180216254864",
[2020-05-18 14:39:18]   "imei": "352656100378903",
[2020-05-18 14:39:18]   "interval":     2
[2020-05-18 14:39:18] }
[2020-05-18 14:39:18] Waiting for response...
[2020-05-18 14:39:18] Response: Interval: 4
[2020-05-18 14:39:18] Returned: 2020-05-18T12:38:22.44+0000
[2020-05-18 14:39:18] Version:  v2.6.0
[2020-05-18 14:39:18] TraceID:  f3baf466-e68d-4960-9e72-1d420b9ca1b7
[2020-05-18 14:39:18] 
[2020-05-18 14:39:18] Packet sent: {
[2020-05-18 14:39:18]   "ip":   ["10.81.170.83"],
[2020-05-18 14:39:18]   "op":   "24202",
[2020-05-18 14:39:18]   "cell_id":      34247436,
[2020-05-18 14:39:18]   "ue_mode":      2,
[2020-05-18 14:39:18]   "lte_mode":     1,
[2020-05-18 14:39:18]   "nbiot_mode":   0,
[2020-05-18 14:39:18]   "iccid":        "89450421180216254864",
[2020-05-18 14:39:18]   "imei": "352656100378903",
[2020-05-18 14:39:18]   "interval":     3
[2020-05-18 14:39:18] }
[2020-05-18 14:39:18] Waiting for response...
[2020-05-18 14:39:21] Response: Interval: 2
[2020-05-18 14:39:21] Returned: 2020-05-18T12:39:20.84+0000
[2020-05-18 14:39:21] Version:  v2.6.0
[2020-05-18 14:39:21] TraceID:  d8618508-978b-4c2c-bdd0-2f04ec5c9383
[2020-05-18 14:39:21] 
[2020-05-18 14:39:21] Finished measuring for UDP.