Hi,
Thank you so much ... We are making progress. Tests do run and complete in each test case. However in each test case I see the same error "bad value outbound TCP option 2"
total 24
drwxrwxr-x 2 robert robert 4096 Aug 6 16:57 ./
drwxrwxr-x 30 robert robert 4096 Aug 6 16:57 ../
-rw-rw-r-- 1 robert robert 472 Aug 6 16:57 blocking-accept.pkt
-rw-rw-r-- 1 robert robert 332 Aug 6 16:57 blocking-connect.pkt
-rw-rw-r-- 1 robert robert 783 Aug 6 16:57 blocking-read.pkt
-rw-rw-r-- 1 robert robert 1024 Aug 6 16:57 blocking-write.pkt
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking# ../../packetdrill/packetdrill -v --wire_client --wire_client_dev=ztwfuo5474 --wire_server_ip=10.244.10.101 --local_ip=172.16.0.1 --gateway_ip=172.16.0.2 --netmask_ip=255.255.255.0 --remote_ip=
172.16.1.1/24 blocking-accept.pkt
socket syscall: 1628348361.751701
setsockopt syscall: 1628348361.751721
bind syscall: 1628348361.751747
listen syscall: 1628348361.751752
blocking-accept.pkt:13: error handling packet: bad value outbound TCP option 2
script packet: 0.100047 S. 0:0(0) ack 1 <mss 1460,nop,wscale 8>
actual packet: 0.144408 S. 0:0(0) ack 1 win 65535 <mss 2760,nop,wscale 8>
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking#
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking#
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking#
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking#
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking# ../../packetdrill/packetdrill -v --wire_client --wire_client_dev=ztwfuo5474 --wire_server_ip=10.244.10.101 --local_ip=172.16.0.1 --gateway_ip=172.16.0.2 --netmask_ip=255.255.255.0 --remote_ip=
172.16.1.1/24 blocking-connect.pkt
socket syscall: 1628348374.451687
blocking-connect.pkt:10: error handling packet: bad value outbound TCP option 2
script packet: 0.101497 S 0:0(0) <mss 1460,sackOK,TS val 100 ecr 0,nop,wscale 8>
actual packet: 0.135214 S 0:0(0) win 65535 <mss 2760,sackOK,TS val 100 ecr 0,nop,wscale 8>
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking#
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking#
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking#
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking# ../../packetdrill/packetdrill -v --wire_client --wire_client_dev=ztwfuo5474 --wire_server_ip=10.244.10.101 --local_ip=172.16.0.1 --gateway_ip=172.16.0.2 --netmask_ip=255.255.255.0 --remote_ip=
172.16.1.1/24 blocking-read.pkt
socket syscall: 1628348387.131685
setsockopt syscall: 1628348387.131704
bind syscall: 1628348387.131726
listen syscall: 1628348387.131731
blocking-read.pkt:13: error handling packet: bad value outbound TCP option 2
script packet: 0.105321 S. 0:0(0) ack 1 <mss 1460,nop,wscale 8>
actual packet: 0.125330 S. 0:0(0) ack 1 win 65535 <mss 2760,nop,wscale 8>
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking#
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking#
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking# ../../packetdrill/packetdrill -v --wire_client --wire_client_dev=ztwfuo5474 --wire_server_ip=10.244.10.101 --local_ip=172.16.0.1 --gateway_ip=172.16.0.2 --netmask_ip=255.255.255.0 --remote_ip=
172.16.1.1/24 blocking-write.pkt
socket syscall: 1628348395.551686
setsockopt syscall: 1628348395.551706
bind syscall: 1628348395.551714
listen syscall: 1628348395.551738
blocking-write.pkt:15: error handling packet: bad value outbound TCP option 2
script packet: 0.100047 S. 0:0(0) ack 1 <mss 1460,nop,wscale 8>
actual packet: 0.113893 S. 0:0(0) ack 1 win 65535 <mss 2760,nop,wscale 8>
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking#
Zoom on the client:
==============
1628348493.391353 times(NULL) = 1724352759
1628348493.391389 times(NULL) = 1724352759
1628348493.391424 times(NULL) = 1724352760
1628348493.391473 write(4, "\0\0\0\10\0\0\0\6", 8) = 8
1628348493.391729 socket(AF_INET, SOCK_STREAM, IPPROTO_TCP) = 5
1628348493.391784 fstat(1, {st_mode=S_IFCHR|0600, st_rdev=makedev(0x88, 0), ...}) = 0
1628348493.391845 write(1, "socket syscall: 1628348493.39177"..., 34socket syscall: 1628348493.391771
) = 34
1628348493.391905 setsockopt(5, SOL_SOCKET, SO_REUSEADDR, [1], 4) = 0
1628348493.391954 write(1, "setsockopt syscall: 1628348493.3"..., 38setsockopt syscall: 1628348493.391940
) = 38
1628348493.392003 bind(5, {sa_family=AF_INET, sin_port=htons(8080), sin_addr=inet_addr("172.16.0.1")}, 16) = 0
1628348493.392058 write(1, "bind syscall: 1628348493.392045\n", 32bind syscall: 1628348493.392045
) = 32
1628348493.392105 listen(5, 1) = 0
1628348493.392148 write(1, "listen syscall: 1628348493.39213"..., 34listen syscall: 1628348493.392135
) = 34
1628348493.392192 write(4, "\0\0\0\f\0\0\0\7", 8) = 8
1628348493.392256 write(4, "\0\0\0\4", 4) = 4
1628348493.392323 read(4, "\0\0\0\353\0\0\0\t", 8) = 8
1628348493.554949 read(4, "\377\377\377\377\0\0\0\6blocking-write.pkt:15: e"..., 227) = 227
1628348493.555073 write(2, "blocking-write.pkt:15: error han"..., 218blocking-write.pkt:15: error handling packet: bad value outbound TCP option 2
script packet: 0.114950 S. 0:0(0) ack 1 <mss 1460,nop,wscale 8>
actual packet: 0.140085 S. 0:0(0) ack 1 win 65535 <mss 2760,nop,wscale 8>
) = 218
1628348493.555208 rt_sigaction(SIGINT, {sa_handler=SIG_IGN, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x435380}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x435380}, 8) = 0
1628348493.555318 rt_sigaction(SIGQUIT, {sa_handler=SIG_IGN, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x435380}, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x435380}, 8) = 0
1628348493.555413 rt_sigprocmask(SIG_BLOCK, [CHLD], [], 8) = 0
1628348493.555529 mmap(NULL, 36864, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7f129d7b8000
1628348493.555671 rt_sigprocmask(SIG_BLOCK, ~[], [CHLD], 8) = 0
1628348493.555768 clone(child_stack=0x7f129d7c0ff0, flags=CLONE_VM|CLONE_VFORK|SIGCHLD) = 194079
1628348493.556268 munmap(0x7f129d7b8000, 36864) = 0
1628348493.556374 rt_sigprocmask(SIG_SETMASK, [CHLD], NULL, 8) = 0
1628348493.556475 wait4(194079, [{WIFEXITED(s) && WEXITSTATUS(s) == 0}], 0, NULL) = 194079
1628348493.560309 rt_sigaction(SIGINT, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x435380}, NULL, 8) = 0
1628348493.560490 rt_sigaction(SIGQUIT, {sa_handler=SIG_DFL, sa_mask=[], sa_flags=SA_RESTORER, sa_restorer=0x435380}, NULL, 8) = 0
1628348493.560620 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0
1628348493.560708 exit_group(1) = ?
1628348493.563162 +++ exited with 1 +++
root@nuc2-kom:/home/robert/TCP/git/packetdrill/gtests/net/tcp/blocking#
I the same time on the server:
inbound injected packet: 0.114921 S 0:0(0) win 50000 <mss 1000,nop,wscale 0>
outbound sniffed packet: 0.140085 S. 1665275920:1665275920(0) ack 1 win 65535 <mss 2760,nop,wscale 8>
blocking-write.pkt:15: error handling packet: bad value outbound TCP option 2
script packet: 0.114950 S. 0:0(0) ack 1 <mss 1460,nop,wscale 8>
actual packet: 0.140085 S. 0:0(0) ack 1 win 65535 <mss 2760,nop,wscale 8>
or with strace on the server:
1628348589.020924 listen(3, 100) = 0
1628348589.021001 accept(3, NULL, NULL
) = 4
1628348595.797921 setsockopt(4, SOL_TCP, TCP_NODELAY, [1], 4) = 0
1628348595.798177 setsockopt(4, SOL_SOCKET, SO_RCVBUF, [131072], 4) = 0
1628348595.798412 setsockopt(4, SOL_SOCKET, SO_SNDBUF, [131072], 4) = 0
1628348595.798665 socket(AF_INET, SOCK_DGRAM, IPPROTO_IP) = 5
1628348595.798768 ioctl(5, SIOCGIFINDEX, {ifr_name="ztwfuo5474", }) = 0
1628348595.798868 ioctl(5, SIOCGIFHWADDR, {ifr_name="ztwfuo5474", ifr_hwaddr={sa_family=ARPHRD_ETHER, sa_data=4e:0f:cc:7d:b5:46}}) = 0
1628348595.798968 close(5) = 0
1628348595.799104 mmap(NULL, 8392704, PROT_NONE, MAP_PRIVATE|MAP_ANONYMOUS|MAP_STACK, -1, 0) = 0x7f7f97658000
1628348595.799202 mprotect(0x7f7f97659000, 8388608, PROT_READ|PROT_WRITE) = 0
1628348595.799302 clone(child_stack=0x7f7f97e57e70, flags=CLONE_VM|CLONE_FS|CLONE_FILES|CLONE_SIGHAND|CLONE_THREAD|CLONE_SYSVSEM|CLONE_SETTLS|CLONE_PARENT_SETTID|CLONE_CHILD_CLEARTID, parent_tid=[158966], tls=0x7f7f97e58700, child_tidptr=0x7f7f97e589d0) = 158966
1628348595.799452 accept(3, NULL, NULL) = ? ERESTARTSYS (To be restarted if SA_RESTART is set)
1628348595.806585 accept(3, NULL, NULLinbound injected packet: 0.107355 S 0:0(0) win 50000 <mss 1000,nop,wscale 0>
outbound sniffed packet: 0.142045 S. 3117200949:3117200949(0) ack 1 win 65535 <mss 2760,nop,wscale 8>
blocking-write.pkt:15: error handling packet: bad value outbound TCP option 2
script packet: 0.107383 S. 0:0(0) ack 1 <mss 1460,nop,wscale 8>
actual packet: 0.142045 S. 0:0(0) ack 1 win 65535 <mss 2760,nop,wscale 8>
) = ? ERESTARTSYS (To be restarted if SA_RESTART is set)
1628348596.279929 accept(3, NULL, NULL
Many thx,
R.