ndt-users list created

["Galuschka Christoph" <>]

Richard,

sorry to also report in with an error:
>>
ANL/Internet2 NDT ver 3.5.6
        Variables file = /usr/local/ndt/web100_variables
        log file = /usr/local/ndt/web100srv.log
        Debug level set to 5

Send buffer initialized to 16384, Receive buffer initialized to 87380
server ready on port 3001
web100_init() read 69 variables from file
nothing in queue
Timer not running, waiting for new connection
New connection received, waiting for accept() to complete
New connection received from [10.110.109.104].
Child thinks pipe() returned fd0=8, fd1=9 for pid=0
Parent process spawned child = 1940
Parent thinks pipe() returned fd0=8, fd1=9
<<< recv_msg: type=2, len=1
initialize_tests returned old/new client = 1, test_suite = 1 8 2
Incrementing waiting variable now = 1
Telling client 1940 testing will begin now
>>> send_msg: type=1, len=1
extracting test_suite ' 1 8 2' and t_opts '1b' from buff 'go 27 1 8 2'
Child process 1940 started
>>> send_msg: type=2, len=6
run_test() routine, asking for test_suite =  1 8 2
>>> send_msg: type=2, len=6
Starting test suite:
 > Middlebox test
 > Simple firewall test
 > C2S throughput test
 <-- Middlebox test -->
KillHung(): returned non-0 response, nothing to kill or kill failed

Send buffer initialized to 16384, Receive buffer initialized to 87380
  -- port: 3003
>>> send_msg: type=3, len=4
Queue pointer = 1940, testing = 1, waiting = 1, zombie_check = 0
Waiting for new connection, timer running
Looking for Web100 data on socketid 8
Server: 10.11.215.13;3003 Client: 10.110.109.104;4752 1456;6;6;
Sending 1456 Byte packets over the network
Setting Cwnd Limit to 2912 octets
Signal 17 received by process 1940
now = 1251903572 Process started at 1251903569, run time = 3
Timer expired while waiting for a new connection
Queue pointer = 1940, testing = 1, waiting = 1, zombie_check = 0
Waiting for new connection, timer running
>>> send_msg: type=5, len=37
now = 1251903575 Process started at 1251903569, run time = 6
Timer expired while waiting for a new connection
Queue pointer = 1940, testing = 1, waiting = 1, zombie_check = 0
Waiting for new connection, timer running
now = 1251903578 Process started at 1251903569, run time = 9
Timer expired while waiting for a new connection
Queue pointer = 1940, testing = 1, waiting = 1, zombie_check = 0
Waiting for new connection, timer running
now = 1251903581 Process started at 1251903569, run time = 12
Timer expired while waiting for a new connection
Queue pointer = 1940, testing = 1, waiting = 1, zombie_check = 0
Waiting for new connection, timer running
<<< recv_msg: type=5, len=18
CWND limited throughput = 16005 kbps (16004.542797494782)
>>> send_msg: type=6, len=0
 <-------------------->
 <-- Simple firewall test -->

Send buffer initialized to 16384, Receive buffer initialized to 87380
  -- port: 36062
  -- time: 1
>>> send_msg: type=3, len=7
<<< recv_msg: type=5, len=4
  -- oport: 4757
>>> send_msg: type=4, len=0

Send buffer initialized to 16384, Receive buffer initialized to 87380
>>> 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 -->

Send buffer initialized to 16384, Receive buffer initialized to 87380
  -- port: 3002
listening for Inet connection on testOptions->c2ssockfd, fd=3
Sending 'GO' signal, to tell client to head for the next test
>>> send_msg: type=3, len=4
C2S test Child thinks pipe() returned fd0=10, fd1=11
Unknown address family=17 found
Unknown address family=17 found
IPv4 interface found address=10.11.215.13
IPv4 address match, setting device to 'eth2'
Opening network interface 'eth2' for packet-pair timing
pcap_open_live() returned pointer 0x8088830
installing pkt filter for 'host 10.110.109.104 and port 4759'
Initial pkt src data = 806b5c4
C2S test Parent thinks pipe() returned fd0=10, fd1=11
now = 1251903584 Process started at 1251903569, run time = 15
Timer expired while waiting for a new connection
Queue pointer = 1940, testing = 1, waiting = 1, zombie_check = 0
Waiting for new connection, timer running
>>> send_msg: type=4, len=0
New IPv4 packet trace started -- initializing counters
now = 1251903587 Process started at 1251903569, run time = 18
Timer expired while waiting for a new connection
Queue pointer = 1940, testing = 1, waiting = 1, zombie_check = 0
Waiting for new connection, timer running
now = 1251903590 Process started at 1251903569, run time = 21
Timer expired while waiting for a new connection
Queue pointer = 1940, testing = 1, waiting = 1, zombie_check = 0
Waiting for new connection, timer running
now = 1251903593 Process started at 1251903569, run time = 24
Timer expired while waiting for a new connection
Queue pointer = 1940, testing = 1, waiting = 1, zombie_check = 0
Waiting for new connection, timer running
728782 kbps outbound
>>> send_msg: type=5, len=6
Signal USR1(10) sent to child [1943]
Signal 10 received by process 1943
Received SIGUSRx signal terminating data collection loop for pid=1943
Sending pkt-pair data back to parent on pipe 10, 11
fwd.saddr = 686d6e0a:4759, rev.saddr = dd70b0a:3002
08:59:45.499000   10.110.109.104:4759 --> 10.11.215.13:3002 Collected 
pkt-pair data max = 352896
08:59:45.499000   10.11.215.13:3002 --> 10.110.109.104:4759 Collected 
pkt-pair data max = 98223
128 bytes read '  1 0 0 19 3609 5659 121335 1502 139849 352896 0 1 259.32 0 0 
0 1 0 7' from monitor pipe
128 bytes read '  0 0 1 0 57 897 27614 98223 6895 16613 1 0 877.79 948 58 
149295 0 1 7' from monitor pipe
>>> send_msg: type=6, len=0
Signal 11 received by process 1943
Signal 17 received by process 1940
 <------------------------->
Finished testing C2S = 728.78 Mbps, S2C = -0.00 Mbps
Client --> Server data detects link = OC-12
Client <-- Server Ack's detect link = Gigabit Ethernet
CWND limited test = 16004.54 while unlimited = -0.95
Better throughput when CWND is limited, may be duplex mismatch
>>> send_msg: type=8, len=42
>>> send_msg: type=8, len=73
>>> send_msg: type=8, len=69
>>> send_msg: type=8, len=68
>>> send_msg: type=8, len=72
>>> send_msg: type=8, len=46
>>> send_msg: type=9, len=0
Opened 
'/usr/local/ndt/serverdata/2009/09/02/20090902T14:59:29.94277000Z_10.110.109.104:4751.meta'
 metadata log file
Successfully returned from run_test() routine
Signal 17 received by process 1939
now = 1251903595 Process started at 1251903569, run time = 26
Select exited with rc = -1
Queue pointer = 1940, testing = 1, waiting = 1, zombie_check = 0
Received SIGCHLD signal for active web100srv process [1939]
wait3() returned 0 for PID=1940
wexitstatus = '0'
Attempting to clean up child 1940, head pid = 1940
Child process 1940 causing head pointer modification
Removing Child from head, decrementing waiting now = 0
Timer not running, waiting for new connection
>>

Regards
Christoph

------------------------------------------------
Ing. Christoph Galuschka

TIWAG-Tiroler Wasserkraft AG
Bereich IT/Betrieb und Services
Eduard-Wallnöfer-Platz 2
6010 Innsbruck
T: +43 (0)50607 21832
F: +43 (0)50607 41832
www.tiroler-wasserkraft.at
------------------------------------------------
Firmenbuchgericht Innsbruck, FN 44133b
Sitz der Gesellschaft: Innsbruck
DVR: 0164089


-----Ursprüngliche Nachricht-----
Von: Richard Carlson 
[mailto:]
 
Gesendet: Mittwoch, 02. September 2009 15:38
An: Chris Welti
Cc: Galuschka Christoph; 

Betreff: Re: Error running web100 3.5.0

Hi Chris & Christoph;

I'm not sure why I can't duplicate this bug, but in looking at the  
code this is a possible solution.

Please manually edit the src/web100-pcap.c file and scroll down to  
line 922.  You should see this snipit of code

   log_println(5, "Pkt-Pair data collection ended, waiting for signal  
to terminate process");
   pcap_freealldevs(alldevs);

Please add the if statement so the snipitt look like this

   log_println(5, "Pkt-Pair data collection ended, waiting for signal  
to terminate process");
   if (alldevs != NULL)
     pcap_freealldevs(alldevs);

The log provides shows that this message is being printed just before  
the fault is detected.  If the alldevs value is NULL, then the attempt  
to free it will cause this fault.

As for the messages about the web100 variables.  The web100 variables  
are grouped into a couple of major categories (read only and tuning  
are 2 of these categories).  The NDT code collects variables in both  
of these categories.   When the s2c test completes the server walks  
through the list of variables (in the tcpbw100.variables file) and  
retrieves the values.  There are 2 passes made to retrieve the values  
one pass through the readonly category and a 2nd pass through the  
tuning category.  Since I pass the complete list to each category, you  
see lots of warnings when I ask for a variable from the wrong  
category.  To illustrate this

Variable 3 (CongAvoid): web100_snap_read(): invalid arguments
The CongAvoid variable is in the 'read' category.  This error is  
generated when the code looks for it in the 'tune' category.  I'll put  
this on the to-do list, but it will be a low priority so it's not a  
functional problem, it just looks wrong when you turn on debugging.

Rich

On Sep 2, 2009, at 2:05 AM, Chris Welti wrote:

> yes, I can confirm that:
>
>>>> send_msg: type=5, len=19
> pcap_loop exited SIOCGSTAMP: Bad file descriptor
> Pkt-Pair data collection ended, waiting for signal to terminate  
> process
> Signal 11 received by process 25555
> Signal 17 received by process 25442
> Protocol error!
>>>> send_msg: type=7, len=61
> S2C throughput test FAILED!
> Finished testing C2S = 91.38 Mbps, S2C = 0.00 Mbps
>
> Also there seemed to be alot of problems reading the variables, but  
> that might be a problem of the web100 kernel?
>
> libweb100: warning: accessing depricated variable AckPktsIn
> Variable 0 (AckPktsIn): web100_snap_read(): invalid arguments
> libweb100: warning: accessing depricated variable AckPktsOut
> Variable 1 (AckPktsOut): web100_snap_read(): invalid arguments
> Variable 2 (BytesRetrans): web100_snap_read(): invalid arguments
> Variable 3 (CongAvoid): web100_snap_read(): invalid arguments
> Variable 4 (CongestionOverCount): web100_snap_read(): invalid  
> arguments
> Variable 5 (CongestionSignals): web100_snap_read(): invalid arguments
> Variable 6 (CountRTT): web100_snap_read(): invalid arguments
> Variable 7 (CurCwnd): web100_snap_read(): invalid arguments
>>>> send_msg: type=5, len=13
> Variable 9 (CurRTO): web100_snap_read(): invalid arguments
> Variable 10 (CurRwinRcvd): web100_snap_read(): invalid arguments
> Variable 11 (CurRwinSent): web100_snap_read(): invalid arguments
> Variable 12 (CurSsthresh): web100_snap_read(): invalid arguments
> Variable 13 (CwndRestores) not found in KIS
> Variable 14 (DSACKDups): web100_snap_read(): invalid arguments
> Variable 15 (DataBytesIn): web100_snap_read(): invalid arguments
> Variable 16 (DataBytesOut): web100_snap_read(): invalid arguments
> Variable 17 (DataPktsIn): web100_snap_read(): invalid arguments
> Variable 18 (DataPktsOut): web100_snap_read(): invalid arguments
> Variable 19 (DupAcksIn): web100_snap_read(): invalid arguments
> Variable 20 (ECNEnabled): web100_snap_read(): invalid arguments
> Variable 21 (FastRetran): web100_snap_read(): invalid arguments
> Variable 22 (MaxCaCwnd) not found in KIS
> Variable 23 (MaxCwnd): web100_snap_read(): invalid arguments
> Variable 24 (MaxMSS): web100_snap_read(): invalid arguments
> Variable 25 (MaxRTO): web100_snap_read(): invalid arguments
> Variable 26 (MaxRTT): web100_snap_read(): invalid arguments
> Variable 27 (MaxRwinRcvd): web100_snap_read(): invalid arguments
> Variable 28 (MaxRwinSent): web100_snap_read(): invalid arguments
> Variable 29 (MaxSsthresh): web100_snap_read(): invalid arguments
> Variable 30 (MaxSaCwnd) not found in KIS
> Variable 31 (MinMSS): web100_snap_read(): invalid arguments
> Variable 32 (MinRTO): web100_snap_read(): invalid arguments
> Variable 33 (MinRTT): web100_snap_read(): invalid arguments
> Variable 34 (MinRwinRcvd): web100_snap_read(): invalid arguments
> Variable 35 (MinRwinSent): web100_snap_read(): invalid arguments
> Variable 36 (NagleEnabled): web100_snap_read(): invalid arguments
> Variable 37 (OtherReductions): web100_snap_read(): invalid arguments
> Variable 38 (PktsIn): web100_snap_read(): invalid arguments
> Variable 39 (PktsOut): web100_snap_read(): invalid arguments
> Variable 40 (PktsRetrans): web100_snap_read(): invalid arguments
>>>> send_msg: type=5, len=17
> Variable 42 (RcvWinScale): web100_snap_read(): invalid arguments
> Variable 43 (SACKEnabled): web100_snap_read(): invalid arguments
> Variable 44 (SACKsRcvd): web100_snap_read(): invalid arguments
> Variable 45 (SendStall): web100_snap_read(): invalid arguments
> Variable 46 (SlowStart): web100_snap_read(): invalid arguments
> Variable 47 (SampleRTT): web100_snap_read(): invalid arguments
> Variable 48 (SmoothedRTT): web100_snap_read(): invalid arguments
>>>> send_msg: type=5, len=18
> Variable 50 (SndWinScale): web100_snap_read(): invalid arguments
> Variable 51 (SndLimTimeRwin): web100_snap_read(): invalid arguments
> Variable 52 (SndLimTimeCwnd): web100_snap_read(): invalid arguments
> Variable 53 (SndLimTimeSender): web100_snap_read(): invalid arguments
> Variable 54 (SndLimTransRwin): web100_snap_read(): invalid arguments
> Variable 55 (SndLimTransCwnd): web100_snap_read(): invalid arguments
> Variable 56 (SndLimTransSender): web100_snap_read(): invalid arguments
> Variable 57 (SndLimBytesRwin): web100_snap_read(): invalid arguments
> Variable 58 (SndLimBytesCwnd): web100_snap_read(): invalid arguments
> Variable 59 (SndLimBytesSender): web100_snap_read(): invalid arguments
> Variable 60 (SubsequentTimeouts): web100_snap_read(): invalid  
> arguments
> Variable 61 (SumRTT): web100_snap_read(): invalid arguments
> Variable 62 (Timeouts): web100_snap_read(): invalid arguments
> Variable 63 (TimestampsEnabled): web100_snap_read(): invalid arguments
> Variable 64 (WinScaleRcvd): web100_snap_read(): invalid arguments
> Variable 65 (WinScaleSent): web100_snap_read(): invalid arguments
> Variable 66 (DupAcksOut): web100_snap_read(): invalid arguments
> Variable 67 (StartTimeUsec): web100_snap_read(): invalid arguments
> Variable 68 (Duration): web100_snap_read(): invalid arguments
>
> Maybe that's also the reason that the test fails, since it can't  
> read the databytes in correctly.
>
> I've included the whole server and client output as a text file, in  
> case you need more info.
>
> Chris
>
>
> Richard Carlson wrote:
>> Hi Chris;
>>
>> Great.  and can you confirm that you are getting test sig 11 faults  
>> by
>> running the web100srv in debug mode (web100srv -ddddd).
>>
>> Rich
>>
>> On Sep 1, 2009, at 10:36 AM, Chris Welti wrote:
>>
>>> Hi Rich,
>>>
>>> It seems to be using 0.9.8-5:
>>>
>>> : 
>>> root@lsmp2[sbin];
>>>  dpkg -l | grep libpcap
>>> ii  libpcap-dev
>>> 0.9.8-5                    development library for libpcap
>>> (transitional package)
>>> ii  libpcap0.8
>>> 0.9.8-5                    system interface for user-level packet  
>>> capture
>>> ii  libpcap0.8-dev
>>> 0.9.8-5                    development library and header files for
>>> libpcap0.8
>>>
>>> Regards,
>>> Chris
>>>
>>> Richard Carlson wrote:
>>>> Hi Chris;
>>>>
>>>> I pulled the 3.5.6 tarball down and tested it on 2 systems.  It ran
>>>> properly.
>>>>
>>>> Looking again at the trace Christoph sent I see the following:
>>>>
>>>> 690876 kbps outbound
>>>>>>> send_msg: type=5, len=6
>>>> Signal USR1(10) sent to child [3744]
>>>> Signal 10 received by process 3744
>>>> Received SIGUSRx signal terminating data collection loop for  
>>>> pid=3744
>>>> Sending pkt-pair data back to parent on pipe 8, 9
>>>> fwd.saddr = f006c0a:3451, rev.saddr = dd70b0a:3002
>>>> 01:02:46.369960   10.108.0.15:3451 --> 10.11.215.13:3002 Collected
>>>> pkt-pair data max = 334203
>>>> 01:02:46.369960   10.11.215.13:3002 --> 10.108.0.15:3451 Collected
>>>> pkt-pair data max = 81670
>>>> 128 bytes read '  1 0 0 0 19 12738 129915 1642 108553 334203 0 6705
>>>> 219.82 0 0 0 1 0 7' from monitor pipe
>>>> 128 bytes read '  0 0 1 0 3 31 49754 81670 19693 11227 1 0 581.71  
>>>> 21 1
>>>> 162358 0 1 7' from monitor pipe
>>>>>>> send_msg: type=6, len=0
>>>> Signal 11 received by process 3744
>>>> Signal 17 received by process 3741
>>>> <------------------------->
>>>> <-- S2C throughput test -->
>>>> [ test results and debugging info followed by]
>>>> Signal USR2(12) sent to child [3746]
>>>> Signal 12 received by process 3746
>>>> Received SIGUSRx signal terminating data collection loop for  
>>>> pid=3746
>>>> Sending pkt-pair data back to parent on pipe 8, 9
>>>> fwd.saddr = dd70b0a:3003, rev.saddr = f006c0a:3461
>>>> 01:02:56.724367   10.11.215.13:3003 --> 10.108.0.15:3461 Collected
>>>> pkt-pair data max = 18667
>>>> 01:02:56.724367   10.108.0.15:3461 --> 10.11.215.13:3003 Collected
>>>> pkt-pair data max = 65475
>>>> Read '  1 0 0 0 4 661 18667 6971 5501 5721 0 5377 976.37 0 0 0 1  
>>>> 0 7'
>>>> from monitor pipe
>>>> Read '  0 0 0 1 367 9334 40681 26321 35413 65475 39990 34285 663.83
>>>> 39864 40036 171967 0 39990 7' from monitor pipe
>>>> 550764 kbps inbound
>>>> [ followed by the test results being sent to the client and ]
>>>> Signal 11 received by process 3746
>>>> Signal 17 received by process 3741
>>>> Protocol error!
>>>>>>> send_msg: type=7, len=61
>>>> S2C throughput test FAILED!
>>>>
>>>> When I run tests on my servers I don't get the "Signal 11 receive  
>>>> by
>>>> process xxxx" message.  Instead I see the lines
>>>> pcap_loop exited recvfrom: Bad file descriptor
>>>> Pkt-Pair data collection ended, waiting for signal to terminate  
>>>> process
>>>>
>>>> Signal 11 is a SIGSEGV (invalid memory reference) and I did make  
>>>> changes
>>>> to the pcap routines to automatically detect which interface the  
>>>> server
>>>> is using.
>>>>
>>>> According to yum I have 0.9.8-3.fc10_x86_64 on my development  
>>>> system and
>>>> 0.9.4 on my production server.
>>>> Given the code runs on my servers, I need to ask what version of  
>>>> the
>>>> pcap lib development package are you using?
>>>>
>>>> Rich
>>>>
>>>> On Sep 1, 2009, at 7:39 AM, Chris Welti wrote:
>>>>
>>>>> Hi Rich,
>>>>>
>>>>> I've just upgraded one of our NDT servers from 3.4.4 to 3.5.6.
>>>>> I'm observing the same behaviour as listed in this thread:
>>>>>
>>>>> ** Starting test 1 of 1 **
>>>>> Connected to: lsmp2  --  Using IPv4 address
>>>>> Checking for Middleboxes . . . . . . . . . . . . . . . . . .  Done
>>>>> checking for firewalls . . . . . . . . . . . . . . . . . . .  Done
>>>>> running 10s outbound test (client-to-server [C2S]) . . . . .  
>>>>> 91.78Mb/s
>>>>> running 10s inbound test (server-to-client [S2C]) . . . . . .  
>>>>> 85.01Mb/s
>>>>> S2C throughput test: Received wrong type of the message
>>>>> ERROR MSG: Server (S2C throughput test): Invalid S2C throughput
>>>>> received
>>>>> S2C throughput test FAILED!
>>>>> Server unable to determine bottleneck link type.
>>>>> Information: Other network traffic is congesting the link
>>>>>
>>>>> There are test results for both C2S and S2C tests, but an error is
>>>>> reported anyway.
>>>>> On the server there are also error messages (Protocol error! S2C
>>>>> throughput test FAILED!)
>>>>>
>>>>> Anyway, I've tried to install all the 3.5. versions before and it
>>>>> seems to me that this bug was introduced with v3.5.3.
>>>>> Using v3.5.2 or v3.5.1 it works as a charm on the same system  
>>>>> (debian,
>>>>> lenny, 2.6.26-web100)
>>>>> v3.5.3, v3.5.4, v3.5.5 and v3.5.6 all produce the same error  
>>>>> output as
>>>>> above.
>>>>>
>>>>> Maybe that helps... Regards,
>>>>> Chris
>>>>>
>>>>> Galuschka Christoph wrote:
>>>>>> Hello Richard,
>>>>>>
>>>>>> I'm currently running the tests with 2.6.30.5 and IE6. JAVA - if
>>>>>> relevant - is 1.6.11.
>>>>>> The funny thing also is, I do get results in the webbrowser, the
>>>>>> client
>>>>>> just doesn't finish correctly.
>>>>>>
>>>>>> I will repair the WAIT-comment thing on monday (sorry, I'm not  
>>>>>> the
>>>>>> best
>>>>>> in C)
>>>>>>
>>>>>> Regards
>>>>>> Christoph
>>>>>>
>>>>>> -----------------------------------------
>>>>>> Ing. Christoph Galuschka
>>>>>>
>>>>>> TIWAG-Tiroler Wasserkraft AG
>>>>>> Bereich IT/Betrieb und Services
>>>>>> Eduard-Wallnöfer-Platz 2
>>>>>> 6010 Innsbruck
>>>>>> T: +43 (0)50607 21832
>>>>>> F: +43 (0)50607 41832
>>>>>> www.tiroler-wasserkraft.at <http://www.tiroler-wasserkraft.at>
>>>>>> -----------------------------------------
>>>>>> Firmenbuchgericht Innsbruck, FN 44133b
>>>>>> Sitz der Gesellschaft: Innsbruck
>>>>>> DVR: 0164089
>>>>>>
>>>>>>>
>>>>>>
>>>>>> Richard Carlson
>>>>>> 
>>>>>>  
>>>>>> <mailto:>
>>>>>> 1000 Oakbrook Dr
>>>>>> Ann Arbor, MI  48104
>>>>>>
>>>>>> P: 734-352-7043
>>>>>> C: 630-251-4572
>>>>>>
>>>>>
>>>>
>>>> Richard Carlson
>>>> 
>>>>  
>>>> <mailto:>
>>>> 1000 Oakbrook Dr
>>>> Ann Arbor, MI  48104
>>>>
>>>> P: 734-352-7043
>>>> C: 630-251-4572
>>>>
>>>
>>
>> Richard Carlson
>> 
>> 1000 Oakbrook Dr
>> Ann Arbor, MI  48104
>>
>> P: 734-352-7043
>> C: 630-251-4572
>>
>
> Web-Client Output:
> ==================
>
> TCP/Web100 Network Diagnostic Tool v5.5.4
> click START to begin
>
> ** Starting test 1 of 1 **
> Connected to: lsmp2  --  Using IPv4 address
> Checking for Middleboxes . . . . . . . . . . . . . . . . . .  Done
> checking for firewalls . . . . . . . . . . . . . . . . . . .  Done
> running 10s outbound test (client-to-server [C2S]) . . . . . 91.37Mb/s
> running 10s inbound test (server-to-client [S2C]) . . . . . .  
> 85.91Mb/s
> S2C throughput test: Received wrong type of the message
> ERROR MSG: Server (S2C throughput test): Invalid S2C throughput  
> received
> S2C throughput test FAILED!
> Server unable to determine bottleneck link type.
> Information: Other network traffic is congesting the link
>
> click START to re-test
>
> Client Statistics:
> ==================
>
> WEB100 Kernel Variables:
> Client: localhost/127.0.0.1
> CurMSS: 1448
> X_Rcvbuf: 174760
> X_Sndbuf: 4194304
> AckPktsIn: 41862
> AckPktsOut: 0
> BytesRetrans: 159280
> CongAvoid: 0
> CongestionOverCount: 0
> CongestionSignals: 24
> CountRTT: 40434
> CurCwnd: 49232
> CurRTO: 204
> CurRwinRcvd: 524280
> CurRwinSent: 17920
> CurSsthresh: 46336
> DSACKDups: 0
> DataBytesIn: 0
> DataBytesOut: 109500832
> DataPktsIn: 0
> DataPktsOut: 75468
> DupAcksIn: 1429
> ECNEnabled: 0
> FastRetran: 24
> MaxCwnd: 317112
> MaxMSS: 1448
> MaxRTO: 216
> MaxRTT: 20
> MaxRwinRcvd: 524280
> MaxRwinSent: 17920
> MaxSsthresh: 222992
> MinMSS: 1448
> MinRTO: 204
> MinRTT: 0
> MinRwinRcvd: 519832
> MinRwinSent: 17896
> NagleEnabled: 1
> OtherReductions: 24
> PktsIn: 41862
> PktsOut: 75468
> PktsRetrans: 110
> RcvWinScale: 8
> SACKEnabled: 3
> SACKsRcvd: 1526
> SendStall: 0
> SlowStart: 0
> SampleRTT: 4
> SmoothedRTT: 4
> SndWinScale: 3
> SndLimTimeRwin: 0
> SndLimTimeCwnd: 1161224
> SndLimTimeSender: 9038092
> SndLimTransRwin: 0
> SndLimTransCwnd: 3718
> SndLimTransSender: 3719
> SndLimBytesRwin: 0
> SndLimBytesCwnd: 89539984
> SndLimBytesSender: 19960848
> SubsequentTimeouts: 0
> SumRTT: 212452
> Timeouts: 0
> TimestampsEnabled: 1
> WinScaleRcvd: 3
> WinScaleSent: 8
> DupAcksOut: 0
> StartTimeUsec: 20077
> Duration: 10199462
> c2sData: -1
> c2sAck: -1
> s2cData: -1
> s2cAck: -1
> half_duplex: 0
> link: 100
> congestion: 1
> bad_cable: 0
> mismatch: 0
> spd: 85.89
> bw: 117.90
> loss: 0.000318016
> avgrtt: 5.25
> waitsec: 0.00
> timesec: 10.00
> order: 0.0341
> rwintime: 0.0000
> sendtime: 0.8861
> cwndtime: 0.1139
> rwin: 3.9999
> swin: 32.0000
> cwin: 2.4194
> rttsec: 0.005254
> Sndbuf: 4194304
> aspd: 0.00000
> CWND-Limited: 228.12
> minCWNDpeak: 40544
> maxCWNDpeak: 295392
> CWNDpeaks: 22
>
> The theoretical network limit is 117.9 Mbps
> The NDT server has a 2048.0 KByte buffer which limits the throughput  
> to 6090.59 Mbps
> Your PC/Workstation has a 511.0 KByte buffer which limits the  
> throughput to 761.30 Mbps
> The network based flow control limits the throughput to 460.48 Mbps
>
> Client Data reports link is 'System Fault', Client Acks report link  
> is 'System Fault'
> Server Data reports link is 'System Fault', Server Acks report link  
> is 'System Fault'
>
> More details:
> =============
>
> WEB100 Kernel Variables:
> Client: localhost/127.0.0.1
> CurMSS: 1448
> X_Rcvbuf: 174760
> X_Sndbuf: 4194304
> AckPktsIn: 41862
> AckPktsOut: 0
> BytesRetrans: 159280
> CongAvoid: 0
> CongestionOverCount: 0
> CongestionSignals: 24
> CountRTT: 40434
> CurCwnd: 49232
> CurRTO: 204
> CurRwinRcvd: 524280
> CurRwinSent: 17920
> CurSsthresh: 46336
> DSACKDups: 0
> DataBytesIn: 0
> DataBytesOut: 109500832
> DataPktsIn: 0
> DataPktsOut: 75468
> DupAcksIn: 1429
> ECNEnabled: 0
> FastRetran: 24
> MaxCwnd: 317112
> MaxMSS: 1448
> MaxRTO: 216
> MaxRTT: 20
> MaxRwinRcvd: 524280
> MaxRwinSent: 17920
> MaxSsthresh: 222992
> MinMSS: 1448
> MinRTO: 204
> MinRTT: 0
> MinRwinRcvd: 519832
> MinRwinSent: 17896
> NagleEnabled: 1
> OtherReductions: 24
> PktsIn: 41862
> PktsOut: 75468
> PktsRetrans: 110
> RcvWinScale: 8
> SACKEnabled: 3
> SACKsRcvd: 1526
> SendStall: 0
> SlowStart: 0
> SampleRTT: 4
> SmoothedRTT: 4
> SndWinScale: 3
> SndLimTimeRwin: 0
> SndLimTimeCwnd: 1161224
> SndLimTimeSender: 9038092
> SndLimTransRwin: 0
> SndLimTransCwnd: 3718
> SndLimTransSender: 3719
> SndLimBytesRwin: 0
> SndLimBytesCwnd: 89539984
> SndLimBytesSender: 19960848
> SubsequentTimeouts: 0
> SumRTT: 212452
> Timeouts: 0
> TimestampsEnabled: 1
> WinScaleRcvd: 3
> WinScaleSent: 8
> DupAcksOut: 0
> StartTimeUsec: 20077
> Duration: 10199462
> c2sData: -1
> c2sAck: -1
> s2cData: -1
> s2cAck: -1
> half_duplex: 0
> link: 100
> congestion: 1
> bad_cable: 0
> mismatch: 0
> spd: 85.89
> bw: 117.90
> loss: 0.000318016
> avgrtt: 5.25
> waitsec: 0.00
> timesec: 10.00
> order: 0.0341
> rwintime: 0.0000
> sendtime: 0.8861
> cwndtime: 0.1139
> rwin: 3.9999
> swin: 32.0000
> cwin: 2.4194
> rttsec: 0.005254
> Sndbuf: 4194304
> aspd: 0.00000
> CWND-Limited: 228.12
> minCWNDpeak: 40544
> maxCWNDpeak: 295392
> CWNDpeaks: 22
>
> The theoretical network limit is 117.9 Mbps
> The NDT server has a 2048.0 KByte buffer which limits the throughput  
> to 6090.59 Mbps
> Your PC/Workstation has a 511.0 KByte buffer which limits the  
> throughput to 761.30 Mbps
> The network based flow control limits the throughput to 460.48 Mbps
>
> Client Data reports link is 'System Fault', Client Acks report link  
> is 'System Fault'
> Server Data reports link is 'System Fault', Server Acks report link  
> is 'System Fault'
>
> : 
> root@lsmp2[ndt-3.5.6];
>  /usr/local/sbin/web100srv -a --ipv4 -i eth0  
> -ddddd
> ANL/Internet2 NDT ver 3.5.6
>       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 5
>
> Send buffer initialized to 4194304, Receive buffer initialized to  
> 174760
> server ready on port 3001
> web100_init() read 69 variables from file
> Web100 variables line received
>
> Initial counter Values Totalcnt = 0, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 1, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [1, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 1, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 2, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [2, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 2, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 3, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [3, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 3, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 4, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [3, 0, 0, 0, 0, 0, 1, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 4, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 5, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [3, 0, 0, 0, 0, 0, 2, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 5, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 6, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [3, 0, 0, 0, 0, 0, 3, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 6, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 7, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [4, 0, 0, 0, 0, 0, 3, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 7, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 8, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [4, 0, 0, 0, 0, 0, 4, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 8, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 9, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [4, 0, 0, 0, 0, 0, 5, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 9, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 10, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [5, 0, 0, 0, 0, 0, 5, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 10, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 11, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [6, 0, 0, 0, 0, 0, 5, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 11, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 12, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [7, 0, 0, 0, 0, 0, 5, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 12, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 13, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [7, 0, 0, 0, 0, 0, 6, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 13, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 14, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [7, 0, 0, 0, 0, 0, 7, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 14, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 15, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [8, 0, 0, 0, 0, 0, 7, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 15, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 16, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [9, 0, 0, 0, 0, 0, 7, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 16, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 17, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [10, 0, 0, 0, 0, 0, 7, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 17, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 18, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [11, 0, 0, 0, 0, 0, 7, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 18, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 19, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [12, 0, 0, 0, 0, 0, 7, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 19, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 20, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [12, 0, 0, 0, 0, 0, 8, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 20, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 21, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [12, 0, 1, 0, 0, 0, 8, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 21, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 22, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [12, 0, 1, 0, 0, 1, 8, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 22, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 23, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [12, 0, 1, 0, 0, 2, 8, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Web100 variables line received
>
> Initial counter Values Totalcnt = 23, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 24, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [13, 0, 1, 0, 0, 2, 8, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Signal 17 received by process 25244
> successfully locked '/tmp/view.string' for updating
> sending  
> '86147,0,972950,36,24,0,0,13,0,1,0,0,2,8,0,0,0,0,0,0,0,0,0,Sep  1  
> 12:19:38,Sep  1 15:32:35' to tmp file
> Generated iflist with device=eth0 and if_speed=7
> Generated iflist with device=eth1 and if_speed=7
> nothing in queue
> Timer not running, waiting for new connection
> New connection received, waiting for accept() to complete
> New connection received from [130.59.4.118].
> Parent process spawned child = 25442
> Parent thinks pipe() returned fd0=7, fd1=8
> <<< recv_msg: type=2, len=1
> initialize_tests returned old/new client = 0, test_suite = 1 8 2 4
> Incrementing waiting variable now = 1
> Telling client 25442 testing will begin now
>>>> send_msg: type=1, len=1
> Queue pointer = 25442, testing = 1, waiting = 1, zombie_check = 0
> Waiting for new connection, timer running
> Child thinks pipe() returned fd0=7, fd1=8 for pid=0
> extracting test_suite ' 1 8 2 4' and t_opts 'f' from buff 'go 15 1 8  
> 2 4'
> Child process 25442 started
>>>> send_msg: type=2, len=6
> run_test() routine, asking for test_suite =  1 8 2 4
>>>> send_msg: type=2, len=8
> 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
>
> Send buffer initialized to 4194304, Receive buffer initialized to  
> 174760
>  -- port: 3003
>>>> send_msg: type=3, len=4
> Looking for Web100 data on socketid 7
> Server: 130.59.35.42;3003 Client: 130.59.4.118;50492 1444;8;8;
> Sending 1444 Byte packets over the network
> Setting Cwnd Limit to 2888 octets
> Signal 17 received by process 25442
> now = 1251874507 Process started at 1251874504, run time = 3
> Timer expired while waiting for a new connection
> Queue pointer = 25442, testing = 1, waiting = 1, zombie_check = 0
> Waiting for new connection, timer running
>>>> send_msg: type=5, len=35
> <<< recv_msg: type=5, len=17
> CWND limited throughput = 228 kbps (228.1216865326454)
>>>> send_msg: type=6, len=0
> <-------------------->
> <-- Simple firewall test -->
>
> Send buffer initialized to 4194304, Receive buffer initialized to  
> 174760
>  -- port: 58595
>  -- time: 1
>>>> send_msg: type=3, len=7
> <<< recv_msg: type=5, len=5
>  -- oport: 50493
>>>> send_msg: type=4, len=0
>
> Send buffer initialized to 4194304, Receive buffer initialized to  
> 174760
> <<< recv_msg: type=5, len=20
>>>> send_msg: type=5, len=1
> now = 1251874510 Process started at 1251874504, run time = 6
> Timer expired while waiting for a new connection
> Queue pointer = 25442, testing = 1, waiting = 1, zombie_check = 0
> Waiting for new connection, timer running
> SIGALRM was caught
> SIGALRM was caught
> Unable to create connect socket.
>>>> send_msg: type=6, len=0
> <-------------------------->
> <-- C2S throughput test -->
>
> Send buffer initialized to 4194304, Receive buffer initialized to  
> 174760
>  -- port: 3002
> listening for Inet connection on testOptions->c2ssockfd, fd=3
> Sending 'GO' signal, to tell client to head for the next test
>>>> send_msg: type=3, len=4
> C2S test Child thinks pipe() returned fd0=9, fd1=10
> Opening network interface 'eth0' for packet-pair timing
> pcap_open_live() returned pointer 0x8bcf1a8
> installing pkt filter for 'host 130.59.4.118 and port 50495'
> Initial pkt src data = 806a6e4
> C2S test Parent thinks pipe() returned fd0=9, fd1=10
> now = 1251874513 Process started at 1251874504, run time = 9
> Timer expired while waiting for a new connection
> Queue pointer = 25442, testing = 1, waiting = 1, zombie_check = 0
> Waiting for new connection, timer running
>>>> send_msg: type=4, len=0
> New IPv4 packet trace started -- initializing counters
> now = 1251874516 Process started at 1251874504, run time = 12
> Timer expired while waiting for a new connection
> Queue pointer = 25442, testing = 1, waiting = 1, zombie_check = 0
> Waiting for new connection, timer running
> now = 1251874519 Process started at 1251874504, run time = 15
> Timer expired while waiting for a new connection
> Queue pointer = 25442, testing = 1, waiting = 1, zombie_check = 0
> Waiting for new connection, timer running
> now = 1251874522 Process started at 1251874504, run time = 18
> Timer expired while waiting for a new connection
> Queue pointer = 25442, testing = 1, waiting = 1, zombie_check = 0
> Waiting for new connection, timer running
> 91376 kbps outbound
>>>> send_msg: type=5, len=5
> Signal USR1(10) sent to child [25513]
> Signal 10 received by process 25513
> Received SIGUSRx signal terminating data collection loop for pid=25513
> Sending pkt-pair data back to parent on pipe 9, 10
> fwd.saddr = 0:0, rev.saddr = 0:0
> 00:55:13.953325   0.0.0.0:0 --> 0.0.0.0:0 No data Packets collected
> Collected pkt-pair data max = 0
> 128 bytes read '  -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 0.00 0 0 0 0 0  
> 0' from monitor pipe
> 00:55:13.953325   0.0.0.0:0 --> 0.0.0.0:0 No data Packets collected
> Collected pkt-pair data max = 0
> 128 bytes read '  -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 0.00 0 0 0 0 0  
> 0' from monitor pipe
>>>> send_msg: type=6, len=0
> pcap_loop exited SIOCGSTAMP: Bad file descriptor
> Pkt-Pair data collection ended, waiting for signal to terminate  
> process
> Signal 11 received by process 25513
> Signal 17 received by process 25442
> <------------------------->
> <-- S2C throughput test -->
>
> Send buffer initialized to 4194304, Receive buffer initialized to  
> 174760
>  -- port: 3003
>>>> send_msg: type=3, len=4
> waiting for data on testOptions->s2csockfd
> S2C test Child thinks pipe() returned fd0=9, fd1=10
> Opening network interface 'eth0' for packet-pair timing
> pcap_open_live() returned pointer 0x8bd80d0
> installing pkt filter for 'host 130.59.4.118 and port 50496'
> Initial pkt src data = 806a6e4
> Signal 17 received by process 25442
>>>> send_msg: type=4, len=0
> New IPv4 packet trace started -- initializing counters
> now = 1251874525 Process started at 1251874504, run time = 21
> Timer expired while waiting for a new connection
> Queue pointer = 25442, testing = 1, waiting = 1, zombie_check = 0
> Waiting for new connection, timer running
> now = 1251874528 Process started at 1251874504, run time = 24
> Timer expired while waiting for a new connection
> Queue pointer = 25442, testing = 1, waiting = 1, zombie_check = 0
> Waiting for new connection, timer running
> now = 1251874531 Process started at 1251874504, run time = 27
> Timer expired while waiting for a new connection
> Queue pointer = 25442, testing = 1, waiting = 1, zombie_check = 0
> Waiting for new connection, timer running
> now = 1251874534 Process started at 1251874504, run time = 30
> Timer expired while waiting for a new connection
> Queue pointer = 25442, testing = 1, waiting = 1, zombie_check = 0
> Waiting for new connection, timer running
>>>> send_msg: type=5, len=23
> sent 111820800 bytes to client in 10.13 seconds
> Buffer control counters Total = 13650, new data = 0, Draining Queue  
> = 0
> Signal USR2(12) sent to child [25555]
> Signal 12 received by process 25555
> Received SIGUSRx signal terminating data collection loop for pid=25555
> Sending pkt-pair data back to parent on pipe 9, 10
> fwd.saddr = 0:0, rev.saddr = 0:0
> 00:55:24.091838   0.0.0.0:0 --> 0.0.0.0:0 No data Packets collected
> Collected pkt-pair data max = 0
> Read '  -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 0.00 0 0 0 0 0 0' from  
> monitor pipe
> 00:55:24.091838   0.0.0.0:0 --> 0.0.0.0:0 No data Packets collected
> Collected pkt-pair data max = 0
> Read '  -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 -1 0.00 0 0 0 0 0 0' from  
> monitor pipe
> 88333 kbps inbound
> libweb100: warning: accessing depricated variable AckPktsIn
> Variable 0 (AckPktsIn): web100_snap_read(): invalid arguments
> libweb100: warning: accessing depricated variable AckPktsOut
> Variable 1 (AckPktsOut): web100_snap_read(): invalid arguments
> Variable 2 (BytesRetrans): web100_snap_read(): invalid arguments
> Variable 3 (CongAvoid): web100_snap_read(): invalid arguments
> Variable 4 (CongestionOverCount): web100_snap_read(): invalid  
> arguments
> Variable 5 (CongestionSignals): web100_snap_read(): invalid arguments
> Variable 6 (CountRTT): web100_snap_read(): invalid arguments
> Variable 7 (CurCwnd): web100_snap_read(): invalid arguments
>>>> send_msg: type=5, len=13
> Variable 9 (CurRTO): web100_snap_read(): invalid arguments
> Variable 10 (CurRwinRcvd): web100_snap_read(): invalid arguments
> Variable 11 (CurRwinSent): web100_snap_read(): invalid arguments
> Variable 12 (CurSsthresh): web100_snap_read(): invalid arguments
> Variable 13 (CwndRestores) not found in KIS
> Variable 14 (DSACKDups): web100_snap_read(): invalid arguments
> Variable 15 (DataBytesIn): web100_snap_read(): invalid arguments
> Variable 16 (DataBytesOut): web100_snap_read(): invalid arguments
> Variable 17 (DataPktsIn): web100_snap_read(): invalid arguments
> Variable 18 (DataPktsOut): web100_snap_read(): invalid arguments
> Variable 19 (DupAcksIn): web100_snap_read(): invalid arguments
> Variable 20 (ECNEnabled): web100_snap_read(): invalid arguments
> Variable 21 (FastRetran): web100_snap_read(): invalid arguments
> Variable 22 (MaxCaCwnd) not found in KIS
> Variable 23 (MaxCwnd): web100_snap_read(): invalid arguments
> Variable 24 (MaxMSS): web100_snap_read(): invalid arguments
> Variable 25 (MaxRTO): web100_snap_read(): invalid arguments
> Variable 26 (MaxRTT): web100_snap_read(): invalid arguments
> Variable 27 (MaxRwinRcvd): web100_snap_read(): invalid arguments
> Variable 28 (MaxRwinSent): web100_snap_read(): invalid arguments
> Variable 29 (MaxSsthresh): web100_snap_read(): invalid arguments
> Variable 30 (MaxSaCwnd) not found in KIS
> Variable 31 (MinMSS): web100_snap_read(): invalid arguments
> Variable 32 (MinRTO): web100_snap_read(): invalid arguments
> Variable 33 (MinRTT): web100_snap_read(): invalid arguments
> Variable 34 (MinRwinRcvd): web100_snap_read(): invalid arguments
> Variable 35 (MinRwinSent): web100_snap_read(): invalid arguments
> Variable 36 (NagleEnabled): web100_snap_read(): invalid arguments
> Variable 37 (OtherReductions): web100_snap_read(): invalid arguments
> Variable 38 (PktsIn): web100_snap_read(): invalid arguments
> Variable 39 (PktsOut): web100_snap_read(): invalid arguments
> Variable 40 (PktsRetrans): web100_snap_read(): invalid arguments
>>>> send_msg: type=5, len=17
> Variable 42 (RcvWinScale): web100_snap_read(): invalid arguments
> Variable 43 (SACKEnabled): web100_snap_read(): invalid arguments
> Variable 44 (SACKsRcvd): web100_snap_read(): invalid arguments
> Variable 45 (SendStall): web100_snap_read(): invalid arguments
> Variable 46 (SlowStart): web100_snap_read(): invalid arguments
> Variable 47 (SampleRTT): web100_snap_read(): invalid arguments
> Variable 48 (SmoothedRTT): web100_snap_read(): invalid arguments
>>>> send_msg: type=5, len=18
> Variable 50 (SndWinScale): web100_snap_read(): invalid arguments
> Variable 51 (SndLimTimeRwin): web100_snap_read(): invalid arguments
> Variable 52 (SndLimTimeCwnd): web100_snap_read(): invalid arguments
> Variable 53 (SndLimTimeSender): web100_snap_read(): invalid arguments
> Variable 54 (SndLimTransRwin): web100_snap_read(): invalid arguments
> Variable 55 (SndLimTransCwnd): web100_snap_read(): invalid arguments
> Variable 56 (SndLimTransSender): web100_snap_read(): invalid arguments
> Variable 57 (SndLimBytesRwin): web100_snap_read(): invalid arguments
> Variable 58 (SndLimBytesCwnd): web100_snap_read(): invalid arguments
> Variable 59 (SndLimBytesSender): web100_snap_read(): invalid arguments
> Variable 60 (SubsequentTimeouts): web100_snap_read(): invalid  
> arguments
> Variable 61 (SumRTT): web100_snap_read(): invalid arguments
> Variable 62 (Timeouts): web100_snap_read(): invalid arguments
> Variable 63 (TimestampsEnabled): web100_snap_read(): invalid arguments
> Variable 64 (WinScaleRcvd): web100_snap_read(): invalid arguments
> Variable 65 (WinScaleSent): web100_snap_read(): invalid arguments
> Variable 66 (DupAcksOut): web100_snap_read(): invalid arguments
> Variable 67 (StartTimeUsec): web100_snap_read(): invalid arguments
> Variable 68 (Duration): web100_snap_read(): invalid arguments
>>>> send_msg: type=5, len=17
>>>> send_msg: type=5, len=14
>>>> send_msg: type=5, len=21
>>>> send_msg: type=5, len=13
>>>> send_msg: type=5, len=23
>>>> send_msg: type=5, len=22
>>>> send_msg: type=5, len=16
>>>> send_msg: type=5, len=15
> Variable 8 (CurMSS): web100_snap_read(): invalid arguments
>>>> send_msg: type=5, len=12
>>>> send_msg: type=5, len=20
>>>> send_msg: type=5, len=19
>>>> send_msg: type=5, len=19
> Variable 13 (CwndRestores) not found in KIS
>>>> send_msg: type=5, len=13
>>>> send_msg: type=5, len=15
>>>> send_msg: type=5, len=24
>>>> send_msg: type=5, len=14
>>>> send_msg: type=5, len=19
>>>> send_msg: type=5, len=16
>>>> send_msg: type=5, len=14
>>>> send_msg: type=5, len=15
> Variable 22 (MaxCaCwnd) not found in KIS
>>>> send_msg: type=5, len=16
>>>> send_msg: type=5, len=13
>>>> send_msg: type=5, len=12
>>>> send_msg: type=5, len=11
>>>> send_msg: type=5, len=20
>>>> send_msg: type=5, len=19
>>>> send_msg: type=5, len=20
> Variable 30 (MaxSaCwnd) not found in KIS
>>>> send_msg: type=5, len=13
>>>> send_msg: type=5, len=12
>>>> send_msg: type=5, len=10
>>>> send_msg: type=5, len=20
>>>> send_msg: type=5, len=19
>>>> send_msg: type=5, len=16
>>>> send_msg: type=5, len=20
>>>> send_msg: type=5, len=14
>>>> send_msg: type=5, len=15
>>>> send_msg: type=5, len=17
> Variable 41 (X_Rcvbuf): web100_snap_read(): invalid arguments
>>>> send_msg: type=5, len=15
>>>> send_msg: type=5, len=15
>>>> send_msg: type=5, len=16
>>>> send_msg: type=5, len=13
>>>> send_msg: type=5, len=13
>>>> send_msg: type=5, len=13
>>>> send_msg: type=5, len=15
> Variable 49 (X_Sndbuf): web100_snap_read(): invalid arguments
>>>> send_msg: type=5, len=15
>>>> send_msg: type=5, len=18
>>>> send_msg: type=5, len=24
>>>> send_msg: type=5, len=26
>>>> send_msg: type=5, len=19
>>>> send_msg: type=5, len=22
>>>> send_msg: type=5, len=24
>>>> send_msg: type=5, len=19
>>>> send_msg: type=5, len=26
>>>> send_msg: type=5, len=28
>>>> send_msg: type=5, len=22
>>>> send_msg: type=5, len=15
>>>> send_msg: type=5, len=12
>>>> send_msg: type=5, len=21
>>>> send_msg: type=5, len=16
>>>> send_msg: type=5, len=16
>>>> send_msg: type=5, len=14
>>>> send_msg: type=5, len=21
>>>> send_msg: type=5, len=19
> pcap_loop exited SIOCGSTAMP: Bad file descriptor
> Pkt-Pair data collection ended, waiting for signal to terminate  
> process
> Signal 11 received by process 25555
> Signal 17 received by process 25442
> Protocol error!
>>>> send_msg: type=7, len=61
> S2C throughput test FAILED!
> Finished testing C2S = 91.38 Mbps, S2C = 0.00 Mbps
> Client --> Server data detects link = System Fault
> Client <-- Server Ack's detect link = System Fault
> Server --> Client data detects link = System Fault
> Server <-- Client Ack's detect link = System Fault
> CWND limited test = 228.12 while unlimited = 0.00
> Better throughput when CWND is limited, may be duplex mismatch
>>>> send_msg: type=8, len=46
>>>> send_msg: type=8, len=75
>>>> send_msg: type=8, len=85
>>>> send_msg: type=8, len=78
>>>> send_msg: type=8, len=81
>>>> send_msg: type=8, len=53
>>>> send_msg: type=9, len=0
> now = 1251874537 Process started at 1251874504, run time = 33
> Timer expired while waiting for a new connection
> Queue pointer = 25442, testing = 1, waiting = 1, zombie_check = 0
> Waiting for new connection, timer running
> Opened '/usr/local/ndt/serverdata/ 
> 2009/09/02/20090902T06:55:04.153056000Z_130.59.4.118:50491.meta'  
> metadata log file
> Updating admin_view variables: Total count = 24
> Initial counter Values Totalcnt = 24, Total Mismatch = 0, Total Bad  
> Cables = 0
> Updated counter values Totalcnt = 25, Total Mismatch = 0, Total Bad  
> Cables = 0
> Individual counts = [14, 0, 1, 0, 0, 2, 8, 0, 0, 0, 0, 0, 0, 0, 0, 0]
> Signal 17 received by process 25442
> successfully locked '/tmp/view.string' for updating
> sending  
> '91375,0,972950,0,25,0,0,14,0,1,0,0,2,8,0,0,0,0,0,0,0,0,0,Sep  2  
> 06:55:04al (6) received,Sep  2 06:55:04al (6) received' to tmp file
> Successfully returned from run_test() routine
> Signal 17 received by process 25244
> now = 1251874539 Process started at 1251874504, run time = 35
> Select exited with rc = -1
> Queue pointer = 25442, testing = 1, waiting = 1, zombie_check = 0
> Received SIGCHLD signal for active web100srv process [25244]
> wait3() returned 0 for PID=25442
> wexitstatus = '0'
> Attempting to clean up child 25442, head pid = 25442
> Child process 25442 causing head pointer modification
> Removing Child from head, decrementing waiting now = 0
> Timer not running, waiting for new connection
>

Richard Carlson

1000 Oakbrook Dr
Ann Arbor, MI  48104

P: 734-352-7043
C: 630-251-4572