ndt-users list created

[Richard Carlson <>]

Hi Clint;

Arrg, that's not suppose to happen .-)

OK, thanks for the report.  We'll look into this and see what went wrong.

Rich

At 12:49 PM 5/30/2007, Clint Simmons wrote:

> I will start off by saying 3.3.19 works fine...
>
> After installing the new 3.3.20, I now get the following error. This is
> the same ports etc I use on 3.3.19. No differences in the setup or
> usage.
>
> Linux Kernel 2.6.20-web100
> web100-2.5.14-200702061450
> web100_userland-1.6
>
> running 10s outbound test (client-to-server [C2S]) . . . . . 44.14Mb/s
> running 10s inbound test (server-to-client [S2C]) . . . . . . Protocol
> error!
>
> " SIGALRM was caught" see below.
>
> I use ports 1494 and 443 so that we can test our customers, due to
> restricted customer firewalls only allowing those ports.
>
> [root@speeddaemon
>  ndt-3.3.20]# /usr/local/sbin/web100srv -4 -ddddddd -a
> ANL/Internet2 NDT ver 3.3.20
>         Variables file = /usr/local/ndt/web100_variables
>         log file = /usr/local/ndt/web100srv.log
>         Admin file = /usr/local/ndt/admin.html
>         Debug level set to 7
> server ready on port 1494
> web100_init() read 69 variables from file
> Signal 17 received by process 26170
> successfully locked '/tmp/view.string' for updating
> sending '0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,0,,' to tmp file
> nothing in queue
> Timer not running, waiting for new connection
> New connection received, waiting for accept() to complete
> New connection received from [10.16.10.11].
> Child thinks pipe() returned fd0=5, fd1=6 for pid=0
> Parent process spawned child = 26177
> Parent thinks pipe() returned fd0=5, fd1=6
> Incrementing waiting variable now = 1
> Telling client 26177 testing will begin now
> >>> send_msg: type=1, len=1
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> Got 'go' signal from parent, ready to start testing
> Child process 26177 started
> >>> send_msg: type=2, len=7
> <<< recv_msg: type=2, len=1
> >>> send_msg: type=2, len=7
> Starting test suite:
>  > Middlebox test
>  > Simple firewall test
>  > C2S throughput test
>  > S2C throughput test
>  <-- Middlebox test -->
> KillHung(): returned non-0 response, nothing to kill or kill failed
>   -- port: 443
> >>> send_msg: type=3, len=3
> Middlebox test, Port 443 waiting for incoming connection (fd=3)
> Set MSS to 536, Receiving Window size set to 217088KB
> Sending Window size set to 217088KB
> Looking for Web100 data on socketid 5
> Server: 10.15.2.2;443 Client: 10.16.10.11;4745 1260;-1;-1;
> Sending 1260 Byte packets over the network
> Setting Cwnd Limit to 2520 octets
> Signal 17 received by process 26177
> now = 1180543293 Process started at 1180543290, run time = 3
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543296 Process started at 1180543290, run time = 6
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> >>> send_msg: type=5, len=33
> <<< recv_msg: type=5, len=17
> CWND limited throughput = 22064 kbps (22063.95931710861)
> >>> send_msg: type=6, len=0
>  <-------------------->
>  <-- Simple firewall test -->
>   -- port: 40379
>   -- time: 1
> >>> send_msg: type=3, len=7
> <<< recv_msg: type=5, len=4
>   -- oport: 4748
> >>> send_msg: type=4, len=0
> >>> send_msg: type=5, len=20
> <<< recv_msg: type=5, len=20
> >>> send_msg: type=5, len=1
> >>> send_msg: type=6, len=0
>  <-------------------------->
>  <-- C2S throughput test -->
>   -- port: 443
> listening for Inet connection on options->c2ssockfd, fd=3
> Sending 'GO' signal, to tell client to head for the next test
> >>> send_msg: type=3, len=3
> C2S test Child thinks pipe() returned fd0=7, fd1=8
> C2S test calling init_pkttrace() with pd=0xbfeb0d54
> Opening network interface 'eth0' for packet-pair timing
> pcap_open_live() returned pointer 0x809ae20
> installing pkt filter for 'host 10.16.10.11 and port 4750'
> Initial pkt src data = 80806e4
> C2S test Parent thinks pipe() returned fd0=7, fd1=8
> >>> send_msg: type=4, len=0
> New packet trace started -- initializing counters
> now = 1180543299 Process started at 1180543290, run time = 9
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543302 Process started at 1180543290, run time = 12
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543305 Process started at 1180543290, run time = 15
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543308 Process started at 1180543290, run time = 18
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
>  48426 kbps outbound
> >>> send_msg: type=5, len=5
> Signal USR1(10) sent to child [26192]
> Signal 10 received by process 26192
> DEBUG, caught SIGUSR1, setting sig1 flag to force exit
> Received SIGUSRx signal terminating data collection loop for pid=26192
> Sending pkt-pair data back to parent on pipe 7, 8
> fwd.saddr = b0a100a:4750, rev.saddr = 2020f0a:443
> 10:41:38.914029   10.16.10.11:4750 --> 10.15.2.2:443 Collected pkt-pair
> data max = 37386
> wrote 128 bytes: link counters are '  0 2 150 1193 5728 37386 7290 0 0 0
> 0 2 62.47 0 0 0 3 0'
> #$#$#$#$ pcap routine says window increases = 0, decreases = 0, no
> change = 0
> 10:41:38.914029   10.15.2.2:443 --> 10.16.10.11:4750 Collected pkt-pair
> data max = 26871
> wrote 128 bytes: link counters are '  0 0 39 564 1138 26871 970 0 0 0 0
> 0 54.39 24 0 29558 0 0'
> #$#$#$#$ pcap routine says window increases = 24, decreases = 0, no
> change = 29558
> 128 bytes read '  0 2 150 1193 5728 37386 7290 0 0 0 0 2 62.47 0 0 0 3
> 0' from monitor pipe
> 128 bytes read '  0 0 39 564 1138 26871 970 0 0 0 0 0 54.39 24 0 29558 0
> 0' from monitor pipe
> >>> send_msg: type=6, len=0
> Finished reading pkt-pair data from network, process 26192 should
> terminate now
> pcap_loop failed ioctl: Bad file descriptor
> Pkt-Pair data collection ended, waiting for signal to terminate process
> Signal 17 received by process 26177
>  <------------------------->
>  <-- S2C throughput test -->
>   -- port: 443
> >>> send_msg: type=3, len=3
> waiting for data on options->s2csockfd
> S2C test Child thinks pipe() returned fd0=7, fd1=8
> S2C test calling init_pkttrace() with pd=0xbfeb0d3c
> Opening network interface 'eth0' for packet-pair timing
> pcap_open_live() returned pointer 0x809cb08
> installing pkt filter for 'host 10.16.10.11 and port 4751'
> Initial pkt src data = 80806e4
> Signal 17 received by process 26177
> >>> send_msg: type=4, len=0
> now = 1180543311 Process started at 1180543290, run time = 21
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543314 Process started at 1180543290, run time = 24
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543317 Process started at 1180543290, run time = 27
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> SIGALRM was caught
> now = 1180543320 Process started at 1180543290, run time = 30
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543323 Process started at 1180543290, run time = 33
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543326 Process started at 1180543290, run time = 36
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543329 Process started at 1180543290, run time = 39
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543332 Process started at 1180543290, run time = 42
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543335 Process started at 1180543290, run time = 45
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543338 Process started at 1180543290, run time = 48
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543341 Process started at 1180543290, run time = 51
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543344 Process started at 1180543290, run time = 54
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543347 Process started at 1180543290, run time = 57
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543350 Process started at 1180543290, run time = 60
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543353 Process started at 1180543290, run time = 63
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> Signal 14 received by process 26197
> Received SIGALRM signal [S2C throughput test]
> Signal 17 received by process 26177
> now = 1180543356 Process started at 1180543290, run time = 66
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543359 Process started at 1180543290, run time = 69
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543362 Process started at 1180543290, run time = 72
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543365 Process started at 1180543290, run time = 75
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543368 Process started at 1180543290, run time = 78
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543371 Process started at 1180543290, run time = 81
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543374 Process started at 1180543290, run time = 84
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543377 Process started at 1180543290, run time = 87
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543380 Process started at 1180543290, run time = 90
> Timer expired while waiting for a new connection
> Queue pointer = 26177, testing = 1, waiting = 1
> Waiting for new connection, timer running
> now = 1180543383 Process started at 1180543290, run time = 93
> Timer expired while waiting for a new connection
> nothing in queue
> Timer not running, waiting for new connection
> Signal 17 received by process 26170
> Select exited with rc = -1
> nothing in queue
> Received SIGCHLD signal for active web100srv process [26170]
> wait3() returned 9 for PID=26177
> wtermsig = 9
> Timer not running, waiting for new connection
>
>
> Thanks,
> Clint Simmons

------------------------------------



Richard A. Carlson                              e-mail: 

Network Engineer                                phone:  (734) 352-7043
Internet2                                       fax:    (734) 913-4255
1000 Oakbrook Dr; Suite 300
Ann Arbor,  MI  48104