httpd-bugs mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From bugzi...@apache.org
Subject DO NOT REPLY [Bug 26781] New: - ab (apache benchmark) does not work
Date Sun, 08 Feb 2004 23:16:33 GMT
DO NOT REPLY TO THIS EMAIL, BUT PLEASE POST YOUR BUG 
RELATED COMMENTS THROUGH THE WEB INTERFACE AVAILABLE AT
<http://nagoya.apache.org/bugzilla/show_bug.cgi?id=26781>.
ANY REPLY MADE TO THIS MESSAGE WILL NOT BE COLLECTED AND 
INSERTED IN THE BUG DATABASE.

http://nagoya.apache.org/bugzilla/show_bug.cgi?id=26781

ab (apache benchmark) does not work

           Summary: ab (apache benchmark) does not work
           Product: Apache httpd-2.0
           Version: 2.1-HEAD
          Platform: PC
        OS/Version: Linux
            Status: NEW
          Severity: Major
          Priority: Other
         Component: support
        AssignedTo: bugs@httpd.apache.org
        ReportedBy: rduta@syskey.com


CPUs: 1
OS: Fedora 1
kernel: 2.4.22-1.2149.nptl

# ab -n10 http://localhost:8888/index.html
This is ApacheBench, Version 2.0.40-dev <$Revision: 1.121.2.4 $> apache-2.0
Copyright (c) 1996 Adam Twiss, Zeus Technology Ltd, http://www.zeustech.net/
Copyright (c) 1998-2002 The Apache Software Foundation, http://www.apache.org/

Benchmarking localhost (be patient)...Send request timed out!
..done


Server Software:        Apache/2.0.48
Server Hostname:        localhost
Server Port:            8888

Document Path:          /index.html
Document Length:        331 bytes

Concurrency Level:      1
Time taken for tests:   30.5476 seconds
Complete requests:      10
Failed requests:        1
   (Connect: 0, Length: 0, Exceptions: 1)
Write errors:           0
Non-2xx responses:      10
Total transferred:      5550 bytes
HTML transferred:       3310 bytes
Requests per second:    0.33 [#/sec] (mean)
Time per request:       3000.548 [ms] (mean)
Time per request:       3000.548 [ms] (mean, across all concurrent requests)
Transfer rate:          0.17 [Kbytes/sec] received

Connection Times (ms)
              min  mean[+/-sd] median   max
Connect:        0    0   0.0      0       0
Processing:     0 3000 9486.8      0   30000
Waiting:        0    0   0.0      0       0
Total:          0 3000 9486.8      0   30000

Percentage of the requests served within a certain time (ms)
  50%      0
  66%      0
  75%      0
  80%      0
  90%  30000
  95%  30000
  98%  30000
  99%  30000
 100%  30000 (longest request)

# ldd /usr/bin/ab
        libssl.so.4 => /lib/libssl.so.4 (0x00f9a000)
        libcrypto.so.4 => /lib/libcrypto.so.4 (0x00be1000)
        libgssapi_krb5.so.2 => /usr/lib/libgssapi_krb5.so.2 (0x00954000)
        libkrb5.so.3 => /usr/lib/libkrb5.so.3 (0x001e0000)
        libcom_err.so.2 => /lib/libcom_err.so.2 (0x00111000)
        libk5crypto.so.3 => /usr/lib/libk5crypto.so.3 (0x00d03000)
        libresolv.so.2 => /lib/libresolv.so.2 (0x0086f000)
        libz.so.1 => /usr/lib/libz.so.1 (0x00898000)
        libpcre.so.0 => /lib/libpcre.so.0 (0x0059b000)
        libpcreposix.so.0 => /usr/lib/libpcreposix.so.0 (0x005c0000)
        libaprutil-0.so.0 => /usr/lib/libaprutil-0.so.0 (0x004c3000)
        libldap.so.2 => /usr/lib/libldap.so.2 (0x00a04000)
        liblber.so.2 => /usr/lib/liblber.so.2 (0x00680000)
        libgdbm.so.2 => /usr/lib/libgdbm.so.2 (0x00775000)
        libdb-4.1.so => /lib/tls/libdb-4.1.so (0x00114000)
        libpthread.so.0 => /lib/tls/libpthread.so.0 (0x00368000)
        libexpat.so.0 => /usr/lib/libexpat.so.0 (0x00653000)
        libapr-0.so.0 => /usr/lib/libapr-0.so.0 (0x00291000)
        librt.so.1 => /lib/tls/librt.so.1 (0x007e8000)
        libm.so.6 => /lib/tls/libm.so.6 (0x00e45000)
        libcrypt.so.1 => /lib/libcrypt.so.1 (0x003a7000)
        libnsl.so.1 => /lib/libnsl.so.1 (0x0025d000)
        libdl.so.2 => /lib/libdl.so.2 (0x00582000)
        libc.so.6 => /lib/tls/libc.so.6 (0x00a36000)
        libsasl2.so.2 => /usr/lib/libsasl2.so.2 (0x002b1000)
        /lib/ld-linux.so.2 => /lib/ld-linux.so.2 (0x0027b000)



----------------------------------------------------------------------------------
here is the output from an older verion of ab which works just fine:

# ./ab -n10 http://localhost:8888/index.html
This is ApacheBench, Version 1.3d <$Revision: 1.67 $> apache-1.3
Copyright (c) 1996 Adam Twiss, Zeus Technology Ltd, http://www.zeustech.net/
Copyright (c) 1998-2002 The Apache Software Foundation, http://www.apache.org/

Benchmarking localhost (be patient).....done
Server Software:        Apache/2.0.48                                      
Server Hostname:        localhost
Server Port:            8888

Document Path:          /index.html
Document Length:        331 bytes

Concurrency Level:      1
Time taken for tests:   0.002 seconds
Complete requests:      10
Failed requests:        0
Broken pipe errors:     0
Non-2xx responses:      10
Total transferred:      5550 bytes
HTML transferred:       3310 bytes
Requests per second:    5000.00 [#/sec] (mean)
Time per request:       0.20 [ms] (mean)
Time per request:       0.20 [ms] (mean, across all concurrent requests)
Transfer rate:          2775.00 [Kbytes/sec] received

Connnection Times (ms)
              min  mean[+/-sd] median   max
Connect:        0     0    0.0      0     0
Processing:     0     0    0.0      0     0
Waiting:        0     0    0.0      0     0
Total:          0     0    0.0      0     0

Percentage of the requests served within a certain time (ms)
  50%      0
  66%      0
  75%      0
  80%      0
  90%      0
  95%      0
  98%      0
  99%      0
 100%      0 (last request)
[root@bb rduta]# ldd ./ab
        /home/rduta/libmm.so.11.0.23 => /home/rduta/libmm.so.11.0.23 (0x40001000)
        libm.so.6 => /lib/tls/libm.so.6 (0x003ce000)
        libpthread.so.0 => /lib/tls/libpthread.so.0 (0x004fa000)
        libcrypt.so.1 => /lib/libcrypt.so.1 (0x001f5000)
        libdb-3.3.so => /lib/libdb-3.3.so (0x003f7000)
        libexpat.so.0 => /usr/lib/libexpat.so.0 (0x00615000)
        libdl.so.2 => /lib/libdl.so.2 (0x003f2000)
        libc.so.6 => /lib/tls/libc.so.6 (0x00293000)
        /lib/ld-linux.so.2 => /lib/ld-linux.so.2 (0x0027b000)
[root@bb rduta]# 

---------------------------------------------------------------------
and here are some straces of the broken ab.
strace  -t -r ab -n10 http://localhost:8888/index.html


     0.000000 execve("/usr/bin/ab", ["ab", "-n1000",
"http://localhost:8888/test.php"], [/* 23 vars */]) = 0
     0.000095 uname({sys="Linux", node="bb", ...}) = 0
     0.000106 brk(0)                    = 0x9eca000
     0.000051 open("/etc/ld.so.preload", O_RDONLY) = -1 ENOENT (No such file or
directory)
     0.000065 open("/etc/ld.so.cache", O_RDONLY) = 3
     0.000038 fstat64(3, {st_mode=S_IFREG|0644, st_size=74449, ...}) = 0
     0.000062 old_mmap(NULL, 74449, PROT_READ, MAP_PRIVATE, 3, 0) = 0xbf5c8000
     0.000046 close(3)                  = 0
     0.000048 open("/lib/libssl.so.4", O_RDONLY) = 3
     0.000042 read(3, "\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0
\270\241"..., 512) = 512
     0.000057 fstat64(3, {st_mode=S_IFREG|0755, st_size=217512, ...}) = 0
     0.000052 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xbf5c7000
     0.000053 old_mmap(NULL, 216736, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x70b000
     0.000055 old_mmap(0x73d000, 12288, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x32000) = 0x73d000
     0.000053 close(3)                  = 0
     0.000044 open("/lib/libcrypto.so.4", O_RDONLY) = 3
     0.000041 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\260\310"..., 512) = 512
     0.000049 fstat64(3, {st_mode=S_IFREG|0755, st_size=972464, ...}) = 0
     0.000054 old_mmap(NULL, 984888, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x4a5000
     0.000054 old_mmap(0x581000, 69632, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0xdc000) = 0x581000
     0.000054 old_mmap(0x592000, 14136, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x592000
     0.000045 close(3)                  = 0
     0.000039 open("/usr/lib/libgssapi_krb5.so.2", O_RDONLY) = 3
     0.000045 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\0.\247"..., 512) = 512
     0.000050 fstat64(3, {st_mode=S_IFREG|0755, st_size=78628, ...}) = 0
     0.000053 old_mmap(NULL, 76132, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x111000
     0.000055 old_mmap(0x123000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x12000) = 0x123000
     0.000049 close(3)                  = 0
     0.000039 open("/usr/lib/libkrb5.so.3", O_RDONLY) = 3
     0.000042 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\20F\217"..., 512) = 512
     0.000049 fstat64(3, {st_mode=S_IFREG|0755, st_size=423136, ...}) = 0
     0.000053 old_mmap(NULL, 421128, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0xcc5000
     0.000055 old_mmap(0xd2a000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x65000) = 0xd2a000
     0.000050 close(3)                  = 0
     0.000045 open("/lib/libcom_err.so.2", O_RDONLY) = 3
     0.000043 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\220Z\207"..., 512) = 512
     0.000049 fstat64(3, {st_mode=S_IFREG|0755, st_size=7464, ...}) = 0
     0.000053 old_mmap(NULL, 9184, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x3cc000
     0.000055 old_mmap(0x3ce000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x1000) = 0x3ce000
     0.000050 close(3)                  = 0
     0.000036 open("/usr/lib/libk5crypto.so.3", O_RDONLY) = 3
     0.000043 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\340\24"..., 512) = 512
     0.000049 fstat64(3, {st_mode=S_IFREG|0755, st_size=139896, ...}) = 0
     0.000053 old_mmap(NULL, 138592, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0xc72000
     0.000055 old_mmap(0xc93000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x21000) = 0xc93000
     0.000066 close(3)                  = 0
     0.000038 open("/lib/libresolv.so.2", O_RDONLY) = 3
     0.000040 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\360\30"..., 512) = 512
     0.000049 fstat64(3, {st_mode=S_IFREG|0755, st_size=78048, ...}) = 0
     0.000053 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xbf5c6000
     0.000050 old_mmap(NULL, 73608, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0xb29000
     0.000054 old_mmap(0xb38000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0xf000) = 0xb38000
     0.000056 old_mmap(0xb39000, 8072, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xb39000
     0.000056 close(3)                  = 0
     0.000037 open("/usr/lib/libz.so.1", O_RDONLY) = 3
     0.000042 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0000\207"..., 512) = 512
     0.000050 fstat64(3, {st_mode=S_IFREG|0755, st_size=65928, ...}) = 0
     0.000053 old_mmap(NULL, 67608, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x7ec000
     0.000055 old_mmap(0x7fc000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0xf000) = 0x7fc000
     0.000050 close(3)                  = 0
     0.000036 open("/lib/libpcre.so.0", O_RDONLY) = 3
     0.000042 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\260<\250"..., 512) = 512
     0.000048 fstat64(3, {st_mode=S_IFREG|0755, st_size=62832, ...}) = 0
     0.000053 old_mmap(NULL, 64516, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x124000
     0.000055 old_mmap(0x133000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0xe000) = 0x133000
     0.000049 close(3)                  = 0
     0.000036 open("/usr/lib/libpcreposix.so.0", O_RDONLY) = 3
     0.000044 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\220\352"..., 512) = 512
     0.000049 fstat64(3, {st_mode=S_IFREG|0755, st_size=9452, ...}) = 0
     0.000053 old_mmap(NULL, 11096, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x945000
     0.000055 old_mmap(0x947000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x1000) = 0x947000
     0.000049 close(3)                  = 0
     0.000037 open("/usr/lib/libaprutil-0.so.0", O_RDONLY) = 3
     0.000044 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0PR\0\000"..., 512) = 512
     0.000048 fstat64(3, {st_mode=S_IFREG|0755, st_size=85816, ...}) = 0
     0.000053 old_mmap(NULL, 88856, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x833000
     0.000055 old_mmap(0x848000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x14000) = 0x848000
     0.000049 close(3)                  = 0
     0.000038 open("/usr/lib/libldap.so.2", O_RDONLY) = 3
     0.000042 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\220\210"..., 512) = 512
     0.000050 fstat64(3, {st_mode=S_IFREG|0755, st_size=203032, ...}) = 0
     0.000053 old_mmap(NULL, 204356, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x470000
     0.000054 old_mmap(0x4a1000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x30000) = 0x4a1000
     0.000050 close(3)                  = 0
     0.000038 open("/usr/lib/liblber.so.2", O_RDONLY) = 3
     0.000042 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\340el\000"..., 512) = 512
     0.000050 fstat64(3, {st_mode=S_IFREG|0755, st_size=48440, ...}) = 0
     0.000052 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xbf5c5000
     0.000051 old_mmap(NULL, 46160, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x2d4000
     0.000054 old_mmap(0x2df000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0xb000) = 0x2df000
     0.000050 close(3)                  = 0
     0.000039 open("/usr/lib/libgdbm.so.2", O_RDONLY) = 3
     0.000043 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0000\204"..., 512) = 512
     0.000048 fstat64(3, {st_mode=S_IFREG|0755, st_size=23812, ...}) = 0
     0.000053 old_mmap(NULL, 25512, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0xd9c000
     0.000055 old_mmap(0xda2000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x5000) = 0xda2000
     0.000049 close(3)                  = 0
     0.000037 open("/lib/tls/libdb-4.1.so", O_RDONLY) = 3
     0.000040 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0pn\251\000"..., 512) = 512
     0.000049 lseek(3, 782036, SEEK_SET) = 782036
     0.000031 read(3,
"\4\0\0\0\20\0\0\0\1\0\0\0GNU\0\0\0\0\0\2\0\0\0\4\0\0\0"..., 32) = 32
     0.000046 fstat64(3, {st_mode=S_IFREG|0755, st_size=793264, ...}) = 0
     0.000054 old_mmap(NULL, 791036, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x134000
     0.000055 old_mmap(0x1f3000, 12288, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0xbf000) = 0x1f3000
     0.000049 close(3)                  = 0
     0.000056 open("/lib/tls/libpthread.so.0", O_RDONLY) = 3
     0.000043 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\20\347"..., 512) = 512
     0.000049 fstat64(3, {st_mode=S_IFREG|0755, st_size=101264, ...}) = 0
     0.000054 old_mmap(NULL, 62436, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0xdbb000
     0.000055 old_mmap(0xdc8000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0xd000) = 0xdc8000
     0.000052 old_mmap(0xdc9000, 5092, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xdc9000
     0.000045 close(3)                  = 0
     0.000038 open("/usr/lib/libexpat.so.0", O_RDONLY) = 3
     0.000043 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\20qa\000"..., 512) = 512
     0.000049 fstat64(3, {st_mode=S_IFREG|0755, st_size=128272, ...}) = 0
     0.000053 old_mmap(NULL, 129828, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x1f6000
     0.000055 old_mmap(0x214000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x1d000) = 0x214000
     0.000049 close(3)                  = 0
     0.000038 open("/usr/lib/libapr-0.so.0", O_RDONLY) = 3
     0.000042 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\0\206\0"..., 512) = 512
     0.000049 fstat64(3, {st_mode=S_IFREG|0755, st_size=125784, ...}) = 0
     0.000053 old_mmap(NULL, 128840, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x216000
     0.000055 old_mmap(0x234000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x1e000) = 0x234000
     0.000053 old_mmap(0x235000, 1864, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x235000
     0.000045 close(3)                  = 0
     0.000039 open("/lib/tls/librt.so.1", O_RDONLY) = 3
     0.000041 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0p\376\224"..., 512) = 512
     0.000049 fstat64(3, {st_mode=S_IFREG|0755, st_size=49196, ...}) = 0
     0.000052 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xbf5c4000
     0.000051 old_mmap(NULL, 81848, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x236000
     0.000056 old_mmap(0x23e000, 8192, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x7000) = 0x23e000
     0.000053 old_mmap(0x240000, 40888, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x240000
     0.000044 close(3)                  = 0
     0.000047 open("/lib/tls/libm.so.6", O_RDONLY) = 3
     0.000041 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\0\25=\000"..., 512) = 512
     0.000049 fstat64(3, {st_mode=S_IFREG|0755, st_size=213244, ...}) = 0
     0.000054 old_mmap(NULL, 138000, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x24a000
     0.000055 old_mmap(0x26b000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x20000) = 0x26b000
     0.000049 close(3)                  = 0
     0.000040 open("/lib/libcrypt.so.1", O_RDONLY) = 3
     0.000041 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\240Y\37"..., 512) = 512
     0.000049 fstat64(3, {st_mode=S_IFREG|0755, st_size=24848, ...}) = 0
     0.000053 old_mmap(NULL, 181212, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x291000
     0.000055 old_mmap(0x296000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x4000) = 0x296000
     0.000053 old_mmap(0x297000, 156636, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x297000
     0.000044 close(3)                  = 0
     0.000040 open("/lib/libnsl.so.1", O_RDONLY) = 3
     0.000041 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0000\314"..., 512) = 512
     0.000048 fstat64(3, {st_mode=S_IFREG|0755, st_size=93028, ...}) = 0
     0.000054 old_mmap(NULL, 85248, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0xbad000
     0.000055 old_mmap(0xbbf000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x11000) = 0xbbf000
     0.000053 old_mmap(0xbc0000, 7424, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0xbc0000
     0.000044 close(3)                  = 0
     0.000039 open("/lib/libdl.so.2", O_RDONLY) = 3
     0.000040 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0P:?\000"..., 512) = 512
     0.000058 fstat64(3, {st_mode=S_IFREG|0755, st_size=16312, ...}) = 0
     0.000054 old_mmap(NULL, 12148, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x26c000
     0.000055 old_mmap(0x26e000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x1000) = 0x26e000
     0.000050 close(3)                  = 0
     0.000038 open("/lib/tls/libc.so.6", O_RDONLY) = 3
     0.000040 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0`\210*\000"..., 512) = 512
     0.000048 fstat64(3, {st_mode=S_IFREG|0755, st_size=1578228, ...}) = 0
     0.000055 old_mmap(NULL, 1281996, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x596000
     0.000056 old_mmap(0x6c9000, 16384, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x132000) = 0x6c9000
     0.000054 old_mmap(0x6cd000, 8140, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED|MAP_ANONYMOUS, -1, 0) = 0x6cd000
     0.000044 close(3)                  = 0
     0.000084 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xbf5c3000
     0.000057 open("/usr/lib/libsasl2.so.2", O_RDONLY) = 3
     0.000042 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\220\277"..., 512) = 512
     0.000050 fstat64(3, {st_mode=S_IFREG|0755, st_size=77936, ...}) = 0
     0.000053 old_mmap(NULL, 75504, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0xc4f000
     0.000055 old_mmap(0xc61000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x12000) = 0xc61000
     0.000050 close(3)                  = 0
     0.000155 old_mmap(NULL, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xbf5c2000
     0.002989 set_thread_area({entry_number:-1 -> 6, base_addr:0xbf5c2680,
limit:1048575, seg_32bit:1, contents:0, read_exec_only:0, limit_in_pages:1,
seg_not_present:0, usea
ble:1}) = 0
     0.000060 munmap(0xbf5c8000, 74449) = 0
     0.000041 set_tid_address(0xbf5c26c8) = 20251
     0.000047 rt_sigaction(SIGRTMIN, {0xdbf680, [], SA_RESTORER|SA_SIGINFO,
0xdc60b0}, NULL, 8) = 0
     0.000049 rt_sigprocmask(SIG_UNBLOCK, [RTMIN], NULL, 8) = 0
     0.000048 getrlimit(RLIMIT_STACK, {rlim_cur=10240*1024,
rlim_max=RLIM_INFINITY}) = 0
     0.000260 brk(0)                    = 0x9eca000
     0.000035 brk(0x9eeb000)            = 0x9eeb000
     0.000033 brk(0)                    = 0x9eeb000
     0.000284 rt_sigaction(SIGPIPE, {SIG_IGN}, {SIG_DFL}, 8) = 0
     0.000071 fstat64(1, {st_mode=S_IFCHR|0620, st_rdev=makedev(136, 1), ...}) = 0
     0.000059 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xbf5da000
     0.000050 write(1, "This is ApacheBench, Version 2.0"..., 76) = 76
     0.000219 write(1, "Copyright (c) 1996 Adam Twiss, Z"..., 77) = 77
     0.000160 write(1, "Copyright (c) 1998-2002 The Apac"..., 79) = 79
     0.000156 write(1, "\n", 1)         = 1
     0.000130 write(1, "Benchmarking localhost (be patie"..., 36) = 36
     0.000182 gettimeofday({1076281667, 852451}, NULL) = 0
     0.000150 socket(PF_NETLINK, SOCK_RAW, 0) = 3
     0.000044 bind(3, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0
     0.000043 getsockname(3, {sa_family=AF_NETLINK, pid=20251, groups=00000000},
[12]) = 0
     0.000057 time(NULL)                = 1076281667
     0.000035 sendto(3, "\24\0\0\0\26\0\1\3C\301&@\0\0\0\0\0qZ\0", 20, 0,
{sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20
     0.000059 recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
groups=00000000},
msg_iov(1)=[{"D\0\0\0\24\0\2\0C\301&@\33O\0\0\2\10\200\376\1\0\0\0\10"...,
4096}], msg_
controllen=0, msg_flags=0}, 0) = 136
     0.000071 recvmsg(3, {msg_name(12)={sa_family=AF_NETLINK, pid=0,
groups=00000000},
msg_iov(1)=[{"\24\0\0\0\3\0\2\0C\301&@\33O\0\0\0\0\0\0\1\0\0\0\10\0\1"...,
4096}], msg_
controllen=0, msg_flags=0}, 0) = 20
     0.000060 close(3)                  = 0
     0.000053 gettimeofday({1076281667, 853022}, NULL) = 0
     0.000035 getpid()                  = 20251
     0.000049 open("/etc/resolv.conf", O_RDONLY) = 3
     0.000051 fstat64(3, {st_mode=S_IFREG|0644, st_size=24, ...}) = 0
     0.000055 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xbf5d9000
     0.000052 read(3, "nameserver 209.10.66.23\n", 4096) = 24
     0.000052 read(3, "", 4096)         = 0
     0.000033 close(3)                  = 0
     0.000033 munmap(0xbf5d9000, 4096)  = 0
     0.000048 uname({sys="Linux", node="bb", ...}) = 0
     0.000106 socket(PF_UNIX, SOCK_STREAM, 0) = 3
     0.000038 connect(3, {sa_family=AF_UNIX, path="/var/run/.nscd_socket"}, 110)
= -1 ENOENT (No such file or directory)
     0.000065 close(3)                  = 0
     0.000037 open("/etc/nsswitch.conf", O_RDONLY) = 3
     0.000040 fstat64(3, {st_mode=S_IFREG|0644, st_size=1686, ...}) = 0
     0.000054 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xbf5d9000
     0.000036 read(3, "#\n# /etc/nsswitch.conf\n#\n# An ex"..., 4096) = 1686
     0.000073 read(3, "", 4096)         = 0
     0.000032 close(3)                  = 0
     0.000031 munmap(0xbf5d9000, 4096)  = 0
     0.000070 open("/etc/ld.so.cache", O_RDONLY) = 3
     0.000040 fstat64(3, {st_mode=S_IFREG|0644, st_size=74449, ...}) = 0
     0.000054 old_mmap(NULL, 74449, PROT_READ, MAP_PRIVATE, 3, 0) = 0xbf5af000
     0.000043 close(3)                  = 0
     0.000047 open("/lib/libnss_files.so.2", O_RDONLY) = 3
     0.000045 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0000\35\0"..., 512) = 512
     0.000051 fstat64(3, {st_mode=S_IFREG|0755, st_size=51152, ...}) = 0
     0.000056 old_mmap(NULL, 41904, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x26f000
     0.000060 old_mmap(0x279000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0xa000) = 0x279000
     0.000051 close(3)                  = 0
     0.000133 munmap(0xbf5af000, 74449) = 0
     0.000063 open("/etc/host.conf", O_RDONLY) = 3
     0.000042 fstat64(3, {st_mode=S_IFREG|0644, st_size=17, ...}) = 0
     0.000054 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xbf5c1000
     0.000037 read(3, "order hosts,bind\n", 4096) = 17
     0.000047 read(3, "", 4096)         = 0
     0.000032 close(3)                  = 0
     0.000031 munmap(0xbf5c1000, 4096)  = 0
     0.000037 futex(0x6ce260, FUTEX_WAKE, 2147483647) = 0
     0.000048 open("/etc/hosts", O_RDONLY) = 3
     0.000053 fcntl64(3, F_GETFD)       = 0
     0.000033 fcntl64(3, F_SETFD, FD_CLOEXEC) = 0
     0.000045 fstat64(3, {st_mode=S_IFREG|0644, st_size=149, ...}) = 0
     0.000054 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS,
-1, 0) = 0xbf5c1000
     0.000036 read(3, "# Do not remove the following li"..., 4096) = 149
     0.000076 read(3, "", 4096)         = 0
     0.000041 close(3)                  = 0
     0.000031 munmap(0xbf5c1000, 4096)  = 0
     0.000043 open("/etc/ld.so.cache", O_RDONLY) = 3
     0.000040 fstat64(3, {st_mode=S_IFREG|0644, st_size=74449, ...}) = 0
     0.000053 old_mmap(NULL, 74449, PROT_READ, MAP_PRIVATE, 3, 0) = 0xbf5af000
     0.000043 close(3)                  = 0
     0.000046 open("/lib/libnss_dns.so.2", O_RDONLY) = 3
     0.000041 read(3,
"\177ELF\1\1\1\0\0\0\0\0\0\0\0\0\3\0\3\0\1\0\0\0\260\16"..., 512) = 512
     0.000051 fstat64(3, {st_mode=S_IFREG|0755, st_size=18316, ...}) = 0
     0.000058 old_mmap(NULL, 16852, PROT_READ|PROT_EXEC, MAP_PRIVATE, 3, 0) =
0x2be000
     0.000058 old_mmap(0x2c2000, 4096, PROT_READ|PROT_WRITE,
MAP_PRIVATE|MAP_FIXED, 3, 0x3000) = 0x2c2000
     0.000051 close(3)                  = 0
     0.000079 munmap(0xbf5af000, 74449) = 0
     0.000151 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 3
     0.000051 connect(3, {sa_family=AF_INET, sin_port=htons(53),
sin_addr=inet_addr("209.10.66.23")}, 28) = 0
     0.000058 send(3, "\212G\1\0\0\1\0\0\0\0\0\0\tlocalhost\0\0\1\0\1", 27, 0) = 27
     0.000066 gettimeofday({1076281667, 856240}, NULL) = 0
     0.000043 poll([{fd=3, events=POLLIN, revents=POLLIN}], 1, 5000) = 1
     0.010022 ioctl(3, FIONREAD, [57])  = 0
     0.000052 recvfrom(3,
"\212G\205\200\0\1\0\1\0\1\0\0\tlocalhost\0\0\1\0\1\300"..., 1024, 0,
{sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("209.10.66.23")}, [
16]) = 57
     0.000128 close(3)                  = 0
     0.000084 gettimeofday({1076281667, 866569}, NULL) = 0
     0.000078 socket(PF_INET, SOCK_STREAM, IPPROTO_IP) = 3
     0.000049 fcntl64(3, F_GETFL)       = 0x2 (flags O_RDWR)
     0.000066 fcntl64(3, F_SETFL, O_RDWR|O_NONBLOCK) = 0
     0.000032 gettimeofday({1076281667, 866794}, NULL) = 0
     0.000050 connect(3, {sa_family=AF_INET, sin_port=htons(8888),
sin_addr=inet_addr("127.0.0.1")}, 16) = -1 EINPROGRESS (Operation now in progress)
     0.000157 gettimeofday({1076281667, 867001}, NULL) = 0
     0.000050 poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, 30000) = 1
     0.000040 gettimeofday({1076281667, 867091}, NULL) = 0
     0.000056 write(3, "", 0)           = 0
     0.000033 gettimeofday({1076281667, 867179}, NULL) = 0
     0.000033 write(3, "GET /test.php HTTP/1.0\r\nUser-Age"..., 97) = 97
     0.012290 gettimeofday({1076281667, 879505}, NULL) = 0
     0.000039 gettimeofday({1076281667, 879542}, NULL) = 0
     0.000035 poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, 30000) = 1
     0.000041 gettimeofday({1076281667, 879617}, NULL) = 0
     0.000041 write(3, "GET /test.php HTTP/1.0\r\nUser-Age"..., 97) = 97
     0.000065 gettimeofday({1076281667, 879724}, NULL) = 0
     0.000033 gettimeofday({1076281667, 879757}, NULL) = 0
     0.000033 poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, 30000) = 1
     0.000037 gettimeofday({1076281667, 879826}, NULL) = 0
     0.000033 write(3, "GET /test.php HTTP/1.0\r\nUser-Age"..., 97) = 97
     0.000049 gettimeofday({1076281667, 879909}, NULL) = 0
     0.000033 gettimeofday({1076281667, 879942}, NULL) = 0
     0.000033 poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, 30000) = 1
     0.000037 gettimeofday({1076281667, 880011}, NULL) = 0
     0.000033 write(3, "GET /test.php HTTP/1.0\r\nUser-Age"..., 97) = 97
     0.000047 gettimeofday({1076281667, 880091}, NULL) = 0
     0.000033 gettimeofday({1076281667, 880124}, NULL) = 0
     0.000032 poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, 30000) = 1
     0.000037 gettimeofday({1076281667, 880193}, NULL) = 0
     0.000033 write(3, "GET /test.php HTTP/1.0\r\nUser-Age"..., 97) = 97
     0.000048 gettimeofday({1076281667, 880274}, NULL) = 0
     0.000032 gettimeofday({1076281667, 880307}, NULL) = 0
     0.000033 poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, 30000) = 1
     0.000036 gettimeofday({1076281667, 880376}, NULL) = 0
     0.000033 write(3, "GET /test.php HTTP/1.0\r\nUser-Age"..., 97) = 97
     0.000047 gettimeofday({1076281667, 880456}, NULL) = 0
     0.000033 gettimeofday({1076281667, 880488}, NULL) = 0
     0.000033 poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, 30000) = 1
     0.000036 gettimeofday({1076281667, 880558}, NULL) = 0
     0.000033 write(3, "GET /test.php HTTP/1.0\r\nUser-Age"..., 97) = 97
     0.000048 gettimeofday({1076281667, 880638}, NULL) = 0
     0.000033 gettimeofday({1076281667, 880671}, NULL) = 0
     0.000033 poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, 30000) = 1
     0.000036 gettimeofday({1076281667, 880740}, NULL) = 0
     0.000033 write(3, "GET /test.php HTTP/1.0\r\nUser-Age"..., 97) = 97
     0.000047 gettimeofday({1076281667, 880821}, NULL) = 0
     0.000033 gettimeofday({1076281667, 880853}, NULL) = 0
     0.000033 poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, 30000) = 1
     0.000036 gettimeofday({1076281667, 880923}, NULL) = 0
     0.000033 write(3, "GET /test.php HTTP/1.0\r\nUser-Age"..., 97) = 97
     0.000048 gettimeofday({1076281667, 881003}, NULL) = 0
     0.000033 gettimeofday({1076281667, 881036}, NULL) = 0
     0.000032 poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, 30000) = 1
     0.000037 gettimeofday({1076281667, 881105}, NULL) = 0
     0.000033 write(3, "GET /test.php HTTP/1.0\r\nUser-Age"..., 97) = 97
     0.000047 gettimeofday({1076281667, 881185}, NULL) = 0
     0.000032 gettimeofday({1076281667, 881231}, NULL) = 0
     0.000047 poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, 30000) = 1
     0.000036 gettimeofday({1076281667, 881301}, NULL) = 0
     0.000034 write(3, "GET /test.php HTTP/1.0\r\nUser-Age"..., 97) = 97
     0.000061 gettimeofday({1076281667, 881396}, NULL) = 0
     0.000033 gettimeofday({1076281667, 881429}, NULL) = 0
     0.000033 poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, 30000) = 1
     0.000037 gettimeofday({1076281667, 881498}, NULL) = 0
     0.000033 write(3, "GET /test.php HTTP/1.0\r\nUser-Age"..., 97) = 97
     0.000048 gettimeofday({1076281667, 881579}, NULL) = 0
     0.000032 gettimeofday({1076281667, 881612}, NULL) = 0
     0.000033 poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, 30000) = 1
     0.000037 gettimeofday({1076281667, 881681}, NULL) = 0
     0.000033 write(3, "GET /test.php HTTP/1.0\r\nUser-Age"..., 97) = 97
     0.000048 gettimeofday({1076281667, 881762}, NULL) = 0
     0.000032 gettimeofday({1076281667, 881795}, NULL) = 0
     0.000033 poll([{fd=3, events=POLLIN|POLLOUT, revents=POLLOUT}], 1, 30000) = 1
     0.000037 gettimeofday({1076281667, 881865}, NULL) = 0
     0.000033 write(3, "GET /test.php HTTP/1.0\r\nUser-Age"..., 97) = 97
     0.000048 gettimeofday({1076281667, 881945}, NULL) = 0
     0.000032 gettimeofday({1076281667, 881978}, NULL) = 0

the last couple of lines repeat for a few hundred meg worth of log, so I'll
spare you.

Just for the record, ApacheBench, Version 1.3d works just fine!.  I have never
seen this kind of problem before.

---------------------------------------------------------------------
To unsubscribe, e-mail: bugs-unsubscribe@httpd.apache.org
For additional commands, e-mail: bugs-help@httpd.apache.org


Mime
View raw message