Picotcp tcp log in Bonfire eLua

tcp.log

setsockopt: Nagle algorithm enabled.
Sending SYN... (ports: 2696 - 4000) size: 78
Sending empty packet
pico_tcp_process_out: non-pure ACK with len=0, fl:0002
[sam] TCP> [tcp input] t_len: 40
[sam] TCP> flags = 0x12
[sam] TCP> s->state >> 8 = 3
[sam] TCP> [tcp input] socket: 0xa26b8 state: 3 <-- local port:2696 remote port: 4000 seq: 0x3352a6f1 ack: 0xabcfafc2 flags: 0x12 t_len: 40, hdr: 40 payload: 0
Mode: Look-ahead. In flight: 0/2 buf: 0
 -----=============== RTT CUR: 18 AVG: 18 RTTVAR: 9 RTO: 70 ======================----
TCP ACK> FRESH ACK abcfafc2 (acked 0) Queue size: 0/6144 frames: 0 cwnd: 2 in_flight: 0 snd_una: 0
Doing congestion control
TCP_CWND, 0, 142131, 0, 3
TCP_CWND, 0, 142131, 0, 3
Next timeout in 1 msec
TCP> Established. State: 50c
TCP> DEQUEUED (for output) frame abcfafc2, acks 00000000 len= 389, remaining frames 1
DBG> [tcp output] state: 05 --> local port:2696 remote port: 4000 seq: abcfafc2 ack: 3352a6f2 flags: 18 = t_len: 425, hdr: 36 payload: 389
Sending empty packet
Process out: sending 0x9ecf0 (389 bytes)
Next timeout in 64 msec
[sam] TCP> [tcp input] t_len: 32
[sam] TCP> flags = 0x10
[sam] TCP> s->state >> 8 = 5
[sam] TCP> [tcp input] socket: 0xa26b8 state: 5 <-- local port:2696 remote port: 4000 seq: 0x3352a6f2 ack: 0xabcfb147 flags: 0x10 t_len: 32, hdr: 32 payload: 0
Releasing 0xa7658
Mode: Look-ahead. In flight: 1/3 buf: 0
 -----=============== RTT CUR: 10 AVG: 17 RTTVAR: 8 RTO: 70 ======================----
TCP ACK> FRESH ACK abcfb147 (acked 1) Queue size: 0/6144 frames: 0 cwnd: 3 in_flight: 1 snd_una: 0
Doing congestion control
TCP_CWND, 0, 142153, 0, 4
TCP_CWND, 0, 142154, 0, 4
[sam] TCP> [tcp input] t_len: 387
[sam] TCP> flags = 0x18
[sam] TCP> s->state >> 8 = 5
[sam] TCP> [tcp input] socket: 0xa26b8 state: 5 <-- local port:2696 remote port: 4000 seq: 0x3352a6f2 ack: 0xabcfb147 flags: 0x18 t_len: 387, hdr: 32 payload: 355
Mode: Look-ahead. In flight: 0/4 buf: 0
 -----=============== RTT CUR: 15 AVG: 16 RTTVAR: 6 RTO: 70 ======================----
TCP ACK> FRESH ACK abcfb147 (acked 0) Queue size: 0/6144 frames: 0 cwnd: 4 in_flight: 0 snd_una: 0
Doing congestion control
TCP_CWND, 0, 142161, 0, 4
TCP_CWND, 0, 142161, 0, 4
TCP> Received segment. (exp: 3352a6f2 got: 3352a6f2)
[sam] TCP> [tcp input] t_len: 1480
[sam] TCP> flags = 0x10
[sam] TCP> s->state >> 8 = 5
[sam] TCP> [tcp input] socket: 0xa26b8 state: 5 <-- local port:2696 remote port: 4000 seq: 0x3352a855 ack: 0xabcfb147 flags: 0x10 t_len: 1480, hdr: 32 payload: 1448
Mode: Look-ahead. In flight: 0/4 buf: 0
 -----=============== RTT CUR: 21 AVG: 16 RTTVAR: 5 RTO: 70 ======================----
TCP ACK> FRESH ACK abcfb147 (acked 0) Queue size: 0/6144 frames: 0 cwnd: 4 in_flight: 0 snd_una: 0
Doing congestion control
TCP_CWND, 0, 142164, 0, 4
TCP_CWND, 0, 142164, 0, 4
TCP> Received segment. (exp: 3352a855 got: 3352a855)
[sam] TCP> [tcp input] t_len: 1480
[sam] TCP> flags = 0x10
[sam] TCP> s->state >> 8 = 5
[sam] TCP> [tcp input] socket: 0xa26b8 state: 5 <-- local port:2696 remote port: 4000 seq: 0x3352adfd ack: 0xabcfb147 flags: 0x10 t_len: 1480, hdr: 32 payload: 1448
Mode: Look-ahead. In flight: 0/4 buf: 0
 -----=============== RTT CUR: 28 AVG: 17 RTTVAR: 6 RTO: 70 ======================----
TCP ACK> FRESH ACK abcfb147 (acked 0) Queue size: 0/6144 frames: 0 cwnd: 4 in_flight: 0 snd_una: 0
Doing congestion control
TCP_CWND, 0, 142172, 0, 4
TCP_CWND, 0, 142172, 0, 4
TCP> Received segment. (exp: 3352adfd got: 3352adfd)
[sam] TCP> [tcp input] t_len: 1480
[sam] TCP> flags = 0x10
[sam] TCP> s->state >> 8 = 5
[sam] TCP> [tcp input] socket: 0xa26b8 state: 5 <-- local port:2696 remote port: 4000 seq: 0x3352b3a5 ack: 0xabcfb147 flags: 0x10 t_len: 1480, hdr: 32 payload: 1448
Mode: Look-ahead. In flight: 0/4 buf: 0
 -----=============== RTT CUR: 36 AVG: 19 RTTVAR: 9 RTO: 70 ======================----
TCP ACK> FRESH ACK abcfb147 (acked 0) Queue size: 0/6144 frames: 0 cwnd: 4 in_flight: 0 snd_una: 0
Doing congestion control
TCP_CWND, 0, 142179, 0, 5
TCP_CWND, 0, 142179, 0, 5
TCP> Received segment. (exp: 3352b3a5 got: 3352b3a5)
[sam] TCP> [tcp input] t_len: 1480
[sam] TCP> flags = 0x10
[sam] TCP> s->state >> 8 = 5
[sam] TCP> [tcp input] socket: 0xa26b8 state: 5 <-- local port:2696 remote port: 4000 seq: 0x3352bef5 ack: 0xabcfb147 flags: 0x10 t_len: 1480, hdr: 32 payload: 1448
Mode: Look-ahead. In flight: 0/5 buf: 0
 -----=============== RTT CUR: 43 AVG: 22 RTTVAR: 12 RTO: 70 ======================----
TCP ACK> FRESH ACK abcfb147 (acked 0) Queue size: 0/6144 frames: 0 cwnd: 5 in_flight: 0 snd_una: 0
Doing congestion control
TCP_CWND, 0, 142186, 0, 5
TCP_CWND, 0, 142186, 0, 5
TCP> Received segment. (exp: 3352b94d got: 3352bef5)
TCP> hi segment. Possible packet loss. I'll dupack this. (exp: 3352b94d got: 3352bef5)
[sam] TCP> [tcp input] t_len: 1480
[sam] TCP> flags = 0x10
[sam] TCP> s->state >> 8 = 5
[sam] TCP> [tcp input] socket: 0xa26b8 state: 5 <-- local port:2696 remote port: 4000 seq: 0x3352c49d ack: 0xabcfb147 flags: 0x10 t_len: 1480, hdr: 32 payload: 1448
Mode: Look-ahead. In flight: 0/5 buf: 0
 -----=============== RTT CUR: 45 AVG: 24 RTTVAR: 14 RTO: 80 ======================----
TCP ACK> FRESH ACK abcfb147 (acked 0) Queue size: 0/6144 frames: 0 cwnd: 5 in_flight: 0 snd_una: 0
Doing congestion control
TCP_CWND, 0, 142191, 0, 5
TCP_CWND, 0, 142191, 0, 5
TCP> Received segment. (exp: 3352b94d got: 3352c49d)
TCP> hi segment. Possible packet loss. I'll dupack this. (exp: 3352b94d got: 3352c49d)
Sending empty packet
Sending empty packet
Sending empty packet
Sending empty packet
Sending empty packet
Sending empty packet
[sam] TCP> [tcp input] t_len: 722
[sam] TCP> flags = 0x18
[sam] TCP> s->state >> 8 = 5
[sam] TCP> [tcp input] socket: 0xa26b8 state: 5 <-- local port:2696 remote port: 4000 seq: 0x3352db3d ack: 0xabcfb147 flags: 0x18 t_len: 722, hdr: 32 payload: 690
Mode: Look-ahead. In flight: 0/5 buf: 0
 -----=============== RTT CUR: 50 AVG: 27 RTTVAR: 17 RTO: 95 ======================----
TCP ACK> FRESH ACK abcfb147 (acked 0) Queue size: 0/6144 frames: 0 cwnd: 5 in_flight: 0 snd_una: 0
Doing congestion control
TCP_CWND, 0, 142211, 0, 5
TCP_CWND, 0, 142211, 0, 5
TCP> Received segment. (exp: 3352b94d got: 3352db3d)
TCP> hi segment. Possible packet loss. I'll dupack this. (exp: 3352b94d got: 3352db3d)
Sending empty packet
TIMEOUT! backoff = 0, rto: 95
[sam] TCP> [tcp input] t_len: 1480
[sam] TCP> flags = 0x10
[sam] TCP> s->state >> 8 = 5
[sam] TCP> [tcp input] socket: 0xa26b8 state: 5 <-- local port:2696 remote port: 4000 seq: 0x3352b94d ack: 0xabcfb147 flags: 0x10 t_len: 1480, hdr: 32 payload: 1448
Mode: Look-ahead. In flight: 0/5 buf: 0
 -----=============== RTT CUR: 38 AVG: 28 RTTVAR: 15 RTO: 88 ======================----
TCP ACK> FRESH ACK abcfb147 (acked 0) Queue size: 0/6144 frames: 0 cwnd: 5 in_flight: 0 snd_una: 0
Doing congestion control
TCP_CWND, 0, 142230, 0, 5
TCP_CWND, 0, 142230, 0, 5
Next timeout in 1 msec
TCP> Received segment. (exp: 3352b94d got: 3352b94d)
scrolling rcv_nxt...3352bef5
scrolling rcv_nxt...3352c49d
TCP> read hole beginning of data, 3352ca45 - 3352db3d. rcv_nxt is 3352ca45
TCP> read hole beginning of data, 3352ca45 - 3352db3d. rcv_nxt is 3352ca45
TCP> read hole beginning of data, 3352ca45 - 3352db3d. rcv_nxt is 3352ca45
Sending empty packet
TIMEOUT! backoff = 0, rto: 88
[sam] TCP> [tcp input] t_len: 1480
[sam] TCP> flags = 0x10
[sam] TCP> s->state >> 8 = 5
[sam] TCP> [tcp input] socket: 0xa26b8 state: 5 <-- local port:2696 remote port: 4000 seq: 0x3352ca45 ack: 0xabcfb147 flags: 0x10 t_len: 1480, hdr: 32 payload: 1448
Mode: Look-ahead. In flight: 0/5 buf: 0
 -----=============== RTT CUR: 33 AVG: 28 RTTVAR: 12 RTO: 76 ======================----
TCP ACK> FRESH ACK abcfb147 (acked 0) Queue size: 0/6144 frames: 0 cwnd: 5 in_flight: 0 snd_una: 0
Doing congestion control
TCP_CWND, 0, 142265, 0, 6
TCP_CWND, 0, 142265, 0, 6
Next timeout in 1 msec
TCP> Received segment. (exp: 3352ca45 got: 3352ca45)
TCP> read hole beginning of data, 3352cfed - 3352db3d. rcv_nxt is 3352cfed
TCP> read hole beginning of data, 3352cfed - 3352db3d. rcv_nxt is 3352cfed
TCP> read hole beginning of data, 3352cfed - 3352db3d. rcv_nxt is 3352cfed
[sam] TCP> [tcp input] t_len: 1480
[sam] TCP> flags = 0x10
[sam] TCP> s->state >> 8 = 5
[sam] TCP> [tcp input] socket: 0xa26b8 state: 5 <-- local port:2696 remote port: 4000 seq: 0x3352cfed ack: 0xabcfb147 flags: 0x10 t_len: 1480, hdr: 32 payload: 1448
Mode: Look-ahead. In flight: 0/6 buf: 0
 -----=============== RTT CUR: 42 AVG: 29 RTTVAR: 12 RTO: 77 ======================----
TCP ACK> FRESH ACK abcfb147 (acked 0) Queue size: 0/6144 frames: 0 cwnd: 6 in_flight: 0 snd_una: 0
Doing congestion control
TCP_CWND, 0, 142273, 0, 6
TCP_CWND, 0, 142274, 0, 6
TCP> Received segment. (exp: 3352cfed got: 3352cfed)
TCP> read hole beginning of data, 3352d595 - 3352db3d. rcv_nxt is 3352d595
TCP> read hole beginning of data, 3352d595 - 3352db3d. rcv_nxt is 3352d595
TCP> read hole beginning of data, 3352d595 - 3352db3d. rcv_nxt is 3352d595
Sending empty packet
Sending empty packet
TIMEOUT! backoff = 0, rto: 77
[sam] TCP> [tcp input] t_len: 1480
[sam] TCP> flags = 0x10
[sam] TCP> s->state >> 8 = 5
[sam] TCP> [tcp input] socket: 0xa26b8 state: 5 <-- local port:2696 remote port: 4000 seq: 0x3352d595 ack: 0xabcfb147 flags: 0x10 t_len: 1480, hdr: 32 payload: 1448
Mode: Look-ahead. In flight: 0/6 buf: 0
 -----=============== RTT CUR: 37 AVG: 30 RTTVAR: 11 RTO: 74 ======================----
TCP ACK> FRESH ACK abcfb147 (acked 0) Queue size: 0/6144 frames: 0 cwnd: 6 in_flight: 0 snd_una: 0
Doing congestion control
TCP_CWND, 0, 142303, 0, 6
TCP_CWND, 0, 142303, 0, 6
Next timeout in 1 msec
TCP> Received segment. (exp: 3352d595 got: 3352d595)
scrolling rcv_nxt...3352db3d
[sam] TCP> [tcp input] t_len: 722
[sam] TCP> flags = 0x18
[sam] TCP> s->state >> 8 = 5
[sam] TCP> [tcp input] socket: 0xa26b8 state: 5 <-- local port:2696 remote port: 4000 seq: 0x3352db3d ack: 0xabcfb147 flags: 0x18 t_len: 722, hdr: 32 payload: 690
Mode: Look-ahead. In flight: 0/6 buf: 0
 -----=============== RTT CUR: 42 AVG: 31 RTTVAR: 11 RTO: 75 ======================----
TCP ACK> FRESH ACK abcfb147 (acked 0) Queue size: 0/6144 frames: 0 cwnd: 6 in_flight: 0 snd_una: 0
Doing congestion control
TCP_CWND, 0, 142309, 0, 6
TCP_CWND, 0, 142311, 0, 6
TCP> Received segment. (exp: 3352ddef got: 3352db3d)
TCP> lo segment. Uninteresting retransmission. (exp: 3352ddef got: 3352db3d)
Sending empty packet
Sending empty packet
TIMEOUT! backoff = 0, rto: 75