[Fryer] master FAIL. 10 of 25 tests succeeded.
fryer at oneiros.varnish-software.com
fryer at oneiros.varnish-software.com
Tue Jun 12 00:16:17 CEST 2012
Tests Failed: httperf-lru-nostream-gzip httperf-lru-nostream-gzip-deflateoff httperf-lru-default httperf-lru-stream-default httperf-hot httperf-lru-nostream-nogzip cold-gzip httperf-lru-stream-gzip httperf-lru-stream-nogzip cold-nogzip siege-test httperf-lru-nostream-default httperf-rapid-expire cold-default purge-fail
Tests OK: streaming memleak 4gpluss-stream 4gpluss basic-fryer 4gpluss-nostream lru-random streaming-grace 4gpluss-nogzip streaming-gzip
2012-06-11 19:32:17 [1,15]: Server pantoum checked out varnish-3.0.0-beta2-1014-ge75cd2e of branch master
2012-06-11 19:32:40 [2,23]: httperf-lru-nostream-gzip(httperf): Starting test
2012-06-11 19:36:20 WARNING [0,219]: httperf-lru-nostream-gzip(httperf): Panic detected. I think!
2012-06-11 19:36:20 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf):
Last panic at: Mon, 11 Jun 2012 19:34:14 GMT
Assert error in VCL_recv_method(), ../../include/tbl/vcl_returns.h line 27:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x43a3b5: VCL_recv_method+1a5
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7fb494cbc9ca: _end+7fb494638202
0x7fb494a19cdd: _end+7fb494395515
sp = 0x7fb488706c20 {
fd = 17, id = 17, xid = 1980198417,
client = 10.20.100.9 13141,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fb4850df158 {
id = "req",
{s,f,r,e} = {0x7fb4850e0730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7fb4850df158[req]
"GET",
"/1/4/6/5/7/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fb484bbac60 {
ws = 0x7fb484bbae20 {
id = "wrk",
{s,f,r,e} = {0x7fb484bba450,0x7fb484bba450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-11 19:36:20 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 215 stat: 124 diff: 91). Did we crash?
2012-06-11 19:36:20 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: n_lru_nuked(11504) less than lower boundary 80000
2012-06-11 19:36:20 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: client_req(432903) less than lower boundary 1989920
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Load: 21:36:20 up 11 days, 7:59, 0 users, load average: 0.47, 0.44, 0.19
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Test name: httperf-lru-nostream-gzip
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish options:
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): -t=3600
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): -s=malloc,30M
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish parameters:
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_stats_rate=1
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_max=5000
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): nuke_limit=250
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): http_gzip_support=on
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_min=200
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Payload size (excludes headers): 10K
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Branch: master
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of clients involved: 24
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Type of test: httperf
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Test iterations: 1
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Runtime: 215 seconds
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of total connections: 200000
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Requests per connection: 10
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-11 19:36:20 [1, 0]: httperf-lru-nostream-gzip(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 8333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=1000000,0.1
2012-06-11 19:36:27 [2, 6]: httperf-lru-nostream-gzip-deflateoff(httperf): Starting test
2012-06-11 19:39:30 WARNING [0,183]: httperf-lru-nostream-gzip-deflateoff(httperf): Panic detected. I think!
2012-06-11 19:39:30 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf):
Last panic at: Mon, 11 Jun 2012 19:39:00 GMT
Assert error in VCL_fetch_method(), ../../include/tbl/vcl_returns.h line 56:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x439875: VCL_fetch_method+1a5
0x4186a9: cnt_fetch+479
0x41accd: CNT_Session+42d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f76190429ca: _end+7f76189be202
0x7f7618d9fcdd: _end+7f761871b515
sp = 0x7f76084cd520 {
fd = 17, id = 17, xid = 1821666630,
client = 10.20.100.9 16959,
step = STP_FETCH,
handling = fetch,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f7609c9e020 {
ws = 0x7f7609c9e070 {
id = "bo",
{s,f,r,e} = {0x7f7609c9faa0,+10488,(nil),+58752},
},
do_stream
bodystatus = 4 (length),
},
http[bereq] = {
ws = 0x7f7609c9e070[bo]
"GET",
"/1/9/2/6/7/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.9",
"X-Varnish: 1821666630",
"Accept-Encoding: gzip",
},
http[beresp] = {
ws = 0x7f7609c9e070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Mon, 11 Jun 2012 19:39:00 GMT",
"Content-Type: text/plain",
"Content-Length: 10240",
"Last-Modified: Mon, 11 Jun 2012 19:36:30 GMT",
"Connection: keep-alive",
"Accept-Ranges: bytes",
},
ws = 0x7f7608caf158 {
id = "req",
{s,f,r,e} = {0x7f7608cb0730,+136,(nil),+59632},
},
http[req] = {
ws = 0x7f7608caf158[req]
"GET",
"/1/9/2/6/7/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.9",
},
worker = 0x7f7608f3ec60 {
ws = 0x7f7608f3ee20 {
id = "wrk",
{s,f,r,e} = {0x7f7608f3e450,0x7f7608f3e450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-11 19:39:30 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnishstat uptime and measured run-time is too large (measured: 179 stat: 29 diff: 150). Did we crash?
2012-06-11 19:39:30 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-11 19:39:30 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: client_req(37210) less than lower boundary 1989920
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Load: 21:39:31 up 11 days, 8:02, 0 users, load average: 1.16, 1.07, 0.49
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test name: httperf-lru-nostream-gzip-deflateoff
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish options:
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -t=3600
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -s=malloc,30M
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish parameters:
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_stats_rate=1
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_max=5000
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): nuke_limit=250
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): http_gzip_support=on
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_min=200
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Payload size (excludes headers): 10K
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Branch: master
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of clients involved: 24
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Type of test: httperf
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test iterations: 1
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Runtime: 179 seconds
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
set beresp.do_gzip = true;
}
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of total connections: 200000
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Requests per connection: 10
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-11 19:39:31 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 8333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=1000000,0.1
2012-06-11 19:39:37 [2, 6]: streaming(httperf): Starting test
2012-06-11 19:42:32 [2,174]: httperf-lru-default(httperf): Starting test
2012-06-11 19:46:14 WARNING [0,222]: httperf-lru-default(httperf): Panic detected. I think!
2012-06-11 19:46:14 WARNING [0, 0]: httperf-lru-default(httperf):
Last panic at: Mon, 11 Jun 2012 19:45:16 GMT
Assert error in VCL_recv_method(), ../../include/tbl/vcl_returns.h line 27:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x43a3b5: VCL_recv_method+1a5
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7fbe8307f9ca: _end+7fbe829fb202
0x7fbe82ddccdd: _end+7fbe82758515
sp = 0x7fbe74117c20 {
fd = 14, id = 14, xid = 874244648,
client = 10.20.100.8 15954,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fbe73faf158 {
id = "req",
{s,f,r,e} = {0x7fbe73fb0730,+88,(nil),+59632},
},
http[req] = {
ws = 0x7fbe73faf158[req]
"GET",
"/1/7/9/0/2/7.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fbe731d2c60 {
ws = 0x7fbe731d2e20 {
id = "wrk",
{s,f,r,e} = {0x7fbe731d2450,0x7fbe731d2450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-11 19:46:14 WARNING [0, 0]: httperf-lru-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 218 stat: 58 diff: 160). Did we crash?
2012-06-11 19:46:15 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-11 19:46:15 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: client_req(107920) less than lower boundary 1989920
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Load: 21:46:15 up 11 days, 8:09, 0 users, load average: 0.62, 0.93, 0.63
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Test name: httperf-lru-default
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Varnish options:
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): -t=3600
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): -s=malloc,30M
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Varnish parameters:
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): thread_stats_rate=1
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): thread_pool_max=5000
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): nuke_limit=250
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): thread_pool_min=200
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Payload size (excludes headers): 10K
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Branch: master
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Number of clients involved: 24
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Type of test: httperf
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Test iterations: 1
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Runtime: 218 seconds
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Number of total connections: 200000
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Requests per connection: 10
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-11 19:46:15 [1, 0]: httperf-lru-default(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 8333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=1000000,0.1
2012-06-11 19:46:22 [2, 6]: memleak(httperf): Starting test
2012-06-11 19:48:41 [2,139]: 4gpluss-stream(httperf): Starting test
2012-06-11 19:48:44 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-11 19:48:44 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-11 19:48:44 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-11 19:48:45 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-11 19:48:45 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-11 20:08:50 [2,1204]: httperf-lru-stream-default(httperf): Starting test
2012-06-11 20:12:47 WARNING [0,237]: httperf-lru-stream-default(httperf): Panic detected. I think!
2012-06-11 20:12:47 WARNING [0, 0]: httperf-lru-stream-default(httperf):
Last panic at: Mon, 11 Jun 2012 20:11:08 GMT
Assert error in cnt_first(), cache/cache_center.c line 943:
Condition(req->sp == sp) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x41b021: CNT_Session+781
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7fcc8ef609ca: _end+7fcc8e8dc202
0x7fcc8ecbdcdd: _end+7fcc8e639515
sp = 0x7fcc81713a20 {
fd = 24, id = 24, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fcc7f7a8158 {
id = "req",
{s,f,r,e} = {0x7fcc7f7a9730,0x7fcc7f7a9730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7fcc7f9a9c60 {
ws = 0x7fcc7f9a9e20 {
id = "wrk",
{s,f,r,e} = {0x7fcc7f9a9450,0x7fcc7f9a9450,(nil),+2048},
},
},
},
2012-06-11 20:12:47 WARNING [0, 0]: httperf-lru-stream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 232 stat: 98 diff: 134). Did we crash?
2012-06-11 20:12:47 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-11 20:12:47 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: client_req(216983) less than lower boundary 1989920
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Load: 22:12:47 up 11 days, 8:35, 0 users, load average: 1.25, 2.04, 1.39
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Test name: httperf-lru-stream-default
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Varnish options:
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): -t=3600
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): -s=malloc,30M
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Varnish parameters:
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): thread_stats_rate=1
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_max=5000
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): nuke_limit=250
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_min=200
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Payload size (excludes headers): 10K
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Branch: master
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Number of clients involved: 24
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Type of test: httperf
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Test iterations: 1
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Runtime: 232 seconds
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Number of total connections: 200000
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Requests per connection: 10
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-11 20:12:47 [1, 0]: httperf-lru-stream-default(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 8333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=1000000,0.1
2012-06-11 20:12:54 [2, 6]: httperf-hot(httperf): Starting test
2012-06-11 20:14:05 WARNING [0,70]: httperf-hot(httperf): Panic detected. I think!
2012-06-11 20:14:05 WARNING [0, 0]: httperf-hot(httperf):
Last panic at: Mon, 11 Jun 2012 20:13:22 GMT
Assert error in cnt_first(), cache/cache_center.c line 943:
Condition(req->sp == sp) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x41b021: CNT_Session+781
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f244e8c59ca: _end+7f244e241202
0x7f244e622cdd: _end+7f244df9e515
sp = 0x7f2444712220 {
fd = 22, id = 22, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f243f1d7158 {
id = "req",
{s,f,r,e} = {0x7f243f1d8730,0x7f243f1d8730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f243f51ac60 {
ws = 0x7f243f51ae20 {
id = "wrk",
{s,f,r,e} = {0x7f243f51a450,0x7f243f51a450,(nil),+2048},
},
},
},
2012-06-11 20:14:05 WARNING [0, 0]: httperf-hot(httperf): Out of bounds: client_req(191890) less than lower boundary 989840
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Load: 22:14:06 up 11 days, 8:37, 0 users, load average: 0.85, 1.74, 1.34
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Test name: httperf-hot
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Varnish options:
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Varnish parameters:
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): thread_stats_rate=1
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Payload size (excludes headers): 256
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Branch: master
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Number of clients involved: 24
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Type of test: httperf
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Test iterations: 1
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Runtime: 67 seconds
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Number of total connections: 100000
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Requests per connection: 10
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Extra options to httperf: --wset=1000,0.25
2012-06-11 20:14:06 [1, 0]: httperf-hot(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 4166 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=1000,0.25
2012-06-11 20:14:12 [2, 6]: httperf-lru-nostream-nogzip(httperf): Starting test
2012-06-11 20:17:30 WARNING [0,197]: httperf-lru-nostream-nogzip(httperf): Panic detected. I think!
2012-06-11 20:17:30 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf):
Last panic at: Mon, 11 Jun 2012 20:16:25 GMT
Assert error in VCL_recv_method(), ../../include/tbl/vcl_returns.h line 27:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x43a3b5: VCL_recv_method+1a5
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7fb61f23c9ca: _end+7fb61ebb8202
0x7fb61ef99cdd: _end+7fb61e915515
sp = 0x7fb610104420 {
fd = 17, id = 17, xid = 473785100,
client = 10.20.100.8 12930,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fb60f646158 {
id = "req",
{s,f,r,e} = {0x7fb60f647730,+568,(nil),+59632},
},
http[req] = {
ws = 0x7fb60f646158[req]
"GET",
"/1/4/3/1/6/8.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fb60f196c60 {
ws = 0x7fb60f196e20 {
id = "wrk",
{s,f,r,e} = {0x7fb60f196450,0x7fb60f196450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-11 20:17:30 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 193 stat: 64 diff: 129). Did we crash?
2012-06-11 20:17:30 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: n_lru_nuked(1443) less than lower boundary 80000
2012-06-11 20:17:30 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: client_req(43141) less than lower boundary 1989920
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Load: 22:17:31 up 11 days, 8:40, 0 users, load average: 0.30, 1.12, 1.17
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test name: httperf-lru-nostream-nogzip
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish options:
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): -t=3600
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): -s=malloc,30M
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish parameters:
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_stats_rate=1
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_max=5000
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): nuke_limit=250
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): http_gzip_support=off
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_min=200
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Payload size (excludes headers): 10K
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Branch: master
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of clients involved: 24
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Type of test: httperf
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test iterations: 1
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Runtime: 193 seconds
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of total connections: 200000
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Requests per connection: 10
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-11 20:17:31 [1, 0]: httperf-lru-nostream-nogzip(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 8333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=1000000,0.1
2012-06-11 20:17:37 [2, 6]: cold-gzip(httperf): Starting test
2012-06-11 20:20:50 WARNING [0,193]: cold-gzip(httperf): Panic detected. I think!
2012-06-11 20:20:50 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Mon, 11 Jun 2012 20:19:23 GMT
Assert error in VCL_fetch_method(), ../../include/tbl/vcl_returns.h line 56:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x439875: VCL_fetch_method+1a5
0x4186a9: cnt_fetch+479
0x41accd: CNT_Session+42d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f0b202489ca: _end+7f0b1fbc4202
0x7f0b1ffa5cdd: _end+7f0b1f921515
sp = 0x7f0b0e8a9420 {
fd = 36, id = 36, xid = 55349829,
client = 10.20.100.9 5893,
step = STP_FETCH,
handling = fetch,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f0b0f005020 {
ws = 0x7f0b0f005070 {
id = "bo",
{s,f,r,e} = {0x7f0b0f006aa0,+448,(nil),+58752},
},
do_stream
bodystatus = 3 (chunked),
},
http[bereq] = {
ws = 0x7f0b0f005070[bo]
"GET",
"/0/2/9/8/4/7/0.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.9",
"X-Varnish: 55349829",
"Accept-Encoding: gzip",
},
http[beresp] = {
ws = 0x7f0b0f005070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Mon, 11 Jun 2012 20:19:23 GMT",
"Content-Type: text/plain",
"Last-Modified: Mon, 11 Jun 2012 20:17:41 GMT",
"Transfer-Encoding: chunked",
"Connection: keep-alive",
"Content-Encoding: gzip",
},
ws = 0x7f0b0c7ab158 {
id = "req",
{s,f,r,e} = {0x7f0b0c7ac730,+216,(nil),+59632},
},
http[req] = {
ws = 0x7f0b0c7ab158[req]
"GET",
"/0/2/9/8/4/7/0.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.9",
},
worker = 0x7f0b1158cc60 {
ws = 0x7f0b1158ce20 {
id = "wrk",
{s,f,r,e} = {0x7f0b1158c450,0x7f0b1158c450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-11 20:20:50 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 189 stat: 86 diff: 103). Did we crash?
2012-06-11 20:23:58 WARNING [0,188]: cold-gzip(httperf): Panic detected. I think!
2012-06-11 20:23:58 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Mon, 11 Jun 2012 20:22:30 GMT
Assert error in cnt_first(), cache/cache_center.c line 943:
Condition(req->sp == sp) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x41b021: CNT_Session+781
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f0b202489ca: _end+7f0b1fbc4202
0x7f0b1ffa5cdd: _end+7f0b1f921515
sp = 0x7f0b15f3e120 {
fd = 26, id = 26, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f0b0d840158 {
id = "req",
{s,f,r,e} = {0x7f0b0d841730,0x7f0b0d841730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f0b11e14c60 {
ws = 0x7f0b11e14e20 {
id = "wrk",
{s,f,r,e} = {0x7f0b11e14450,0x7f0b11e14450,(nil),+2048},
},
},
},
2012-06-11 20:23:58 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 377 stat: 87 diff: 290). Did we crash?
2012-06-11 20:23:59 WARNING [0, 0]: cold-gzip(httperf): Out of bounds: uptime(87) less than lower boundary 100
2012-06-11 20:23:59 WARNING [0, 0]: cold-gzip(httperf): Out of bounds: client_req(128110) less than lower boundary 1589840
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Load: 22:23:59 up 11 days, 8:46, 0 users, load average: 0.50, 0.78, 0.98
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Test name: cold-gzip
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Varnish options:
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): -t=3600
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): -s=malloc,10G
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Varnish parameters:
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): thread_stats_rate=1
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): http_gzip_support=on
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Payload size (excludes headers): 256
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Branch: master
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Number of clients involved: 24
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Type of test: httperf
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Test iterations: 2
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Runtime: 377 seconds
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Number of total connections: 80000
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Requests per connection: 10
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-11 20:23:59 [1, 0]: cold-gzip(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 3333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=4000000,0.50
2012-06-11 20:24:06 [2, 6]: 4gpluss(httperf): Starting test
2012-06-11 20:24:09 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-11 20:24:09 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-11 20:24:09 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-11 20:24:10 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-11 20:24:10 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-11 20:45:44 [2,1293]: httperf-lru-stream-gzip(httperf): Starting test
2012-06-11 20:49:30 WARNING [0,226]: httperf-lru-stream-gzip(httperf): Panic detected. I think!
2012-06-11 20:49:30 WARNING [0, 0]: httperf-lru-stream-gzip(httperf):
Last panic at: Mon, 11 Jun 2012 20:48:07 GMT
Assert error in VCL_recv_method(), ../../include/tbl/vcl_returns.h line 27:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x43a3b5: VCL_recv_method+1a5
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f6d8b1209ca: _end+7f6d8aa9c202
0x7f6d8ae7dcdd: _end+7f6d8a7f9515
sp = 0x7f6d79936a20 {
fd = 16, id = 16, xid = 1268026707,
client = 10.20.100.9 12480,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f6d7c042158 {
id = "req",
{s,f,r,e} = {0x7f6d7c043730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7f6d7c042158[req]
"GET",
"/1/3/3/3/7/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f6d7aefec60 {
ws = 0x7f6d7aefee20 {
id = "wrk",
{s,f,r,e} = {0x7f6d7aefe450,0x7f6d7aefe450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-11 20:49:30 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 222 stat: 82 diff: 140). Did we crash?
2012-06-11 20:49:30 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-11 20:49:30 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: client_req(226651) less than lower boundary 1989920
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Load: 22:49:31 up 11 days, 9:12, 0 users, load average: 0.61, 0.75, 1.12
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Test name: httperf-lru-stream-gzip
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish options:
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): -t=3600
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): -s=malloc,30M
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish parameters:
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): thread_stats_rate=1
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_max=5000
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): nuke_limit=250
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): http_gzip_support=on
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_min=200
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Payload size (excludes headers): 10K
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Branch: master
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Number of clients involved: 24
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Type of test: httperf
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Test iterations: 1
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Runtime: 222 seconds
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Number of total connections: 200000
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Requests per connection: 10
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-11 20:49:31 [1, 0]: httperf-lru-stream-gzip(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 8333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=1000000,0.1
2012-06-11 20:49:38 [2, 6]: httperf-lru-stream-nogzip(httperf): Starting test
2012-06-11 20:53:00 WARNING [0,202]: httperf-lru-stream-nogzip(httperf): Panic detected. I think!
2012-06-11 20:53:00 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf):
Last panic at: Mon, 11 Jun 2012 20:52:35 GMT
Assert error in VCL_fetch_method(), ../../include/tbl/vcl_returns.h line 56:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x439875: VCL_fetch_method+1a5
0x4186a9: cnt_fetch+479
0x41accd: CNT_Session+42d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f0a89bfb9ca: _end+7f0a89577202
0x7f0a89958cdd: _end+7f0a892d4515
sp = 0x7f0a7560df20 {
fd = 15, id = 15, xid = 228003775,
client = 10.20.100.8 17542,
step = STP_FETCH,
handling = fetch,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f0a7a713020 {
ws = 0x7f0a7a713070 {
id = "bo",
{s,f,r,e} = {0x7f0a7a714aa0,+10488,(nil),+58752},
},
do_stream
bodystatus = 4 (length),
},
http[bereq] = {
ws = 0x7f0a7a713070[bo]
"GET",
"/1/9/7/7/2/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
"X-Varnish: 228003775",
},
http[beresp] = {
ws = 0x7f0a7a713070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Mon, 11 Jun 2012 20:52:35 GMT",
"Content-Type: text/plain",
"Content-Length: 10240",
"Last-Modified: Mon, 11 Jun 2012 20:49:41 GMT",
"Connection: keep-alive",
"Accept-Ranges: bytes",
},
ws = 0x7f0a79b13158 {
id = "req",
{s,f,r,e} = {0x7f0a79b14730,+136,(nil),+59632},
},
http[req] = {
ws = 0x7f0a79b13158[req]
"GET",
"/1/9/7/7/2/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
worker = 0x7f0a79aaac60 {
ws = 0x7f0a79aaae20 {
id = "wrk",
{s,f,r,e} = {0x7f0a79aaa450,0x7f0a79aaa450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-11 20:53:00 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 198 stat: 25 diff: 173). Did we crash?
2012-06-11 20:53:00 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-11 20:53:00 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: client_req(7610) less than lower boundary 1989920
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Load: 22:53:01 up 11 days, 9:15, 0 users, load average: 4.99, 5.12, 2.89
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Test name: httperf-lru-stream-nogzip
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish options:
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): -t=3600
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): -s=malloc,30M
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish parameters:
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_stats_rate=1
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_max=5000
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): nuke_limit=250
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): http_gzip_support=off
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_min=200
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Payload size (excludes headers): 10K
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Branch: master
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of clients involved: 24
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Type of test: httperf
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Test iterations: 1
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Runtime: 198 seconds
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of total connections: 200000
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Requests per connection: 10
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-11 20:53:01 [1, 0]: httperf-lru-stream-nogzip(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 8333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=1000000,0.1
2012-06-11 20:53:12 [2,11]: basic-fryer(httperf): Starting test
2012-06-11 20:53:35 [2,22]: cold-nogzip(httperf): Starting test
2012-06-11 20:56:57 WARNING [0,202]: cold-nogzip(httperf): Panic detected. I think!
2012-06-11 20:56:57 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Mon, 11 Jun 2012 20:55:39 GMT
Assert error in cnt_first(), cache/cache_center.c line 943:
Condition(req->sp == sp) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x41b021: CNT_Session+781
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f4d392579ca: _end+7f4d38bd3202
0x7f4d38fb4cdd: _end+7f4d38930515
sp = 0x7f4d24615a20 {
fd = 17, id = 17, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f4d25513158 {
id = "req",
{s,f,r,e} = {0x7f4d25514730,0x7f4d25514730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f4d298fec60 {
ws = 0x7f4d298fee20 {
id = "wrk",
{s,f,r,e} = {0x7f4d298fe450,0x7f4d298fe450,(nil),+2048},
},
},
},
2012-06-11 20:56:57 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 198 stat: 77 diff: 121). Did we crash?
2012-06-11 21:00:15 WARNING [0,197]: cold-nogzip(httperf): Panic detected. I think!
2012-06-11 21:00:15 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Mon, 11 Jun 2012 20:58:37 GMT
Assert error in VCL_fetch_method(), ../../include/tbl/vcl_returns.h line 56:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x439875: VCL_fetch_method+1a5
0x4186a9: cnt_fetch+479
0x41accd: CNT_Session+42d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f4d392579ca: _end+7f4d38bd3202
0x7f4d38fb4cdd: _end+7f4d38930515
sp = 0x7f4d29f08e20 {
fd = 17, id = 17, xid = 1587606503,
client = 10.20.100.7 6612,
step = STP_FETCH,
handling = fetch,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f4d29070020 {
ws = 0x7f4d29070070 {
id = "bo",
{s,f,r,e} = {0x7f4d29071aa0,+504,(nil),+58752},
},
do_stream
bodystatus = 4 (length),
},
http[bereq] = {
ws = 0x7f4d29070070[bo]
"GET",
"/0/3/2/8/1/2/5.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.7",
"X-Varnish: 1587606503",
},
http[beresp] = {
ws = 0x7f4d29070070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Mon, 11 Jun 2012 20:58:36 GMT",
"Content-Type: text/plain",
"Content-Length: 256",
"Last-Modified: Mon, 11 Jun 2012 20:53:38 GMT",
"Connection: keep-alive",
"Accept-Ranges: bytes",
},
ws = 0x7f4d2ae61158 {
id = "req",
{s,f,r,e} = {0x7f4d2ae62730,+216,(nil),+59632},
},
http[req] = {
ws = 0x7f4d2ae61158[req]
"GET",
"/0/3/2/8/1/2/5.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.7",
},
worker = 0x7f4d2a59dc60 {
ws = 0x7f4d2a59de20 {
id = "wrk",
{s,f,r,e} = {0x7f4d2a59d450,0x7f4d2a59d450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-11 21:00:15 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 396 stat: 97 diff: 299). Did we crash?
2012-06-11 21:00:15 WARNING [0, 0]: cold-nogzip(httperf): Out of bounds: uptime(97) less than lower boundary 100
2012-06-11 21:00:15 WARNING [0, 0]: cold-nogzip(httperf): Out of bounds: client_req(135580) less than lower boundary 1589840
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Load: 23:00:15 up 11 days, 9:23, 0 users, load average: 0.50, 1.69, 2.05
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Test name: cold-nogzip
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Varnish options:
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): -t=3600
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): -s=malloc,10G
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Varnish parameters:
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): thread_stats_rate=1
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): http_gzip_support=off
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Payload size (excludes headers): 256
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Branch: master
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Number of clients involved: 24
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Type of test: httperf
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Test iterations: 2
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Runtime: 396 seconds
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Number of total connections: 80000
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Requests per connection: 10
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-11 21:00:15 [1, 0]: cold-nogzip(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 3333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=4000000,0.50
2012-06-11 21:00:22 [2, 6]: 4gpluss-nostream(httperf): Starting test
2012-06-11 21:00:25 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-11 21:00:25 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-11 21:00:25 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-11 21:00:26 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-11 21:00:26 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-11 21:20:19 [2,1193]: lru-random(httperf): Starting test
2012-06-11 21:31:55 [2,695]: siege-test(siege): Starting test
2012-06-11 21:32:18 WARNING [0,23]: siege-test(siege): Panic detected. I think!
2012-06-11 21:32:18 WARNING [0, 0]: siege-test(siege):
Last panic at: Mon, 11 Jun 2012 21:32:01 GMT
Assert error in VCL_recv_method(), ../../include/tbl/vcl_returns.h line 27:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x43a3b5: VCL_recv_method+1a5
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f10e0c979ca: _end+7f10e0613202
0x7f10e09f4cdd: _end+7f10e0370515
sp = 0x7f10d290cf20 {
fd = 22, id = 22, xid = 1543705690,
client = 10.20.100.7 52400,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f10d1c02158 {
id = "req",
{s,f,r,e} = {0x7f10d1c03730,+152,(nil),+59632},
},
http[req] = {
ws = 0x7f10d1c02158[req]
"GET",
"/",
"HTTP/1.1",
"Host: 10.20.100.12:8080",
"Accept: */*",
"Accept-Encoding: gzip",
"User-Agent: JoeDog/1.00 [en] (X11; I; Siege 2.66)",
"Connection: close",
},
worker = 0x7f10d1fb9c60 {
ws = 0x7f10d1fb9e20 {
id = "wrk",
{s,f,r,e} = {0x7f10d1fb9450,0x7f10d1fb9450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-11 21:32:18 [1, 0]: siege-test(siege): Load: 23:32:18 up 11 days, 9:55, 0 users, load average: 0.46, 0.59, 0.76
2012-06-11 21:32:18 [1, 0]: siege-test(siege): Test name: siege-test
2012-06-11 21:32:18 [1, 0]: siege-test(siege): Varnish options:
2012-06-11 21:32:18 [1, 0]: siege-test(siege): Varnish parameters:
2012-06-11 21:32:18 [1, 0]: siege-test(siege): thread_stats_rate=1
2012-06-11 21:32:18 [1, 0]: siege-test(siege): Payload size (excludes headers): 256
2012-06-11 21:32:18 [1, 0]: siege-test(siege): Branch: master
2012-06-11 21:32:18 [1, 0]: siege-test(siege): Number of clients involved: 0
2012-06-11 21:32:18 [1, 0]: siege-test(siege): Type of test: siege
2012-06-11 21:32:18 [1, 0]: siege-test(siege): Test iterations: 1
2012-06-11 21:32:18 [1, 0]: siege-test(siege): Runtime: 19 seconds
2012-06-11 21:32:18 [1, 0]: siege-test(siege): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-11 21:32:25 [2, 6]: httperf-lru-nostream-default(httperf): Starting test
2012-06-11 21:35:59 WARNING [0,213]: httperf-lru-nostream-default(httperf): Panic detected. I think!
2012-06-11 21:35:59 WARNING [0, 0]: httperf-lru-nostream-default(httperf):
Last panic at: Mon, 11 Jun 2012 21:35:24 GMT
Assert error in VCL_recv_method(), ../../include/tbl/vcl_returns.h line 27:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x43a3b5: VCL_recv_method+1a5
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f38bd7e19ca: _end+7f38bd15d202
0x7f38bd53ecdd: _end+7f38bceba515
sp = 0x7f38ae304720 {
fd = 15, id = 15, xid = 2116402278,
client = 10.20.100.9 15899,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f38b3569158 {
id = "req",
{s,f,r,e} = {0x7f38b356a730,+568,(nil),+59632},
},
http[req] = {
ws = 0x7f38b3569158[req]
"GET",
"/1/7/3/4/0/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f38adc62c60 {
ws = 0x7f38adc62e20 {
id = "wrk",
{s,f,r,e} = {0x7f38adc62450,0x7f38adc62450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-11 21:35:59 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 204 stat: 33 diff: 171). Did we crash?
2012-06-11 21:35:59 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-11 21:35:59 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: client_req(13280) less than lower boundary 1989920
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Load: 23:35:59 up 11 days, 9:58, 0 users, load average: 0.78, 0.83, 0.82
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Test name: httperf-lru-nostream-default
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Varnish options:
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): -t=3600
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): -s=malloc,30M
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Varnish parameters:
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): thread_stats_rate=1
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_max=5000
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): nuke_limit=250
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_min=200
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Payload size (excludes headers): 10K
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Branch: master
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Number of clients involved: 24
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Type of test: httperf
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Test iterations: 1
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Runtime: 204 seconds
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Number of total connections: 200000
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Requests per connection: 10
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-11 21:35:59 [1, 0]: httperf-lru-nostream-default(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 8333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=1000000,0.1
2012-06-11 21:36:06 [2, 6]: httperf-rapid-expire(httperf): Starting test
2012-06-11 21:37:18 WARNING [0,71]: httperf-rapid-expire(httperf): Panic detected. I think!
2012-06-11 21:37:18 WARNING [0, 0]: httperf-rapid-expire(httperf):
Last panic at: Mon, 11 Jun 2012 21:36:42 GMT
Assert error in VCL_recv_method(), ../../include/tbl/vcl_returns.h line 27:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x43a3b5: VCL_recv_method+1a5
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f87262e19ca: _end+7f8725c5d202
0x7f872603ecdd: _end+7f87259ba515
sp = 0x7f8716e03b20 {
fd = 17, id = 17, xid = 697873190,
client = 10.20.100.8 7435,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f8718371158 {
id = "req",
{s,f,r,e} = {0x7f8718372730,+152,(nil),+59632},
},
http[req] = {
ws = 0x7f8718371158[req]
"GET",
"/5/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f87176a9c60 {
ws = 0x7f87176a9e20 {
id = "wrk",
{s,f,r,e} = {0x7f87176a9450,0x7f87176a9450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-11 21:37:18 WARNING [0, 0]: httperf-rapid-expire(httperf): Varnishstat uptime and measured run-time is too large (measured: 67 stat: 35 diff: 32). Did we crash?
2012-06-11 21:37:18 WARNING [0, 0]: httperf-rapid-expire(httperf): Out of bounds: client_req(90100) less than lower boundary 989840
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Load: 23:37:18 up 11 days, 10:00, 0 users, load average: 0.82, 0.85, 0.82
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Test name: httperf-rapid-expire
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Varnish options:
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): -t=2
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Varnish parameters:
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): thread_stats_rate=1
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Payload size (excludes headers): 256
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Branch: master
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Number of clients involved: 24
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Type of test: httperf
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Test iterations: 1
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Runtime: 67 seconds
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Number of total connections: 100000
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Requests per connection: 10
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Extra options to httperf: --wset=100,0.30
2012-06-11 21:37:18 [1, 0]: httperf-rapid-expire(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 4166 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=100,0.30
2012-06-11 21:37:25 [2, 6]: streaming-grace(httperf): Starting test
2012-06-11 21:40:19 [2,173]: cold-default(httperf): Starting test
2012-06-11 21:43:44 WARNING [0,205]: cold-default(httperf): Panic detected. I think!
2012-06-11 21:43:44 WARNING [0, 0]: cold-default(httperf):
Last panic at: Mon, 11 Jun 2012 21:42:25 GMT
Assert error in cnt_first(), cache/cache_center.c line 943:
Condition(req->sp == sp) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x41b021: CNT_Session+781
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f99e83759ca: _end+7f99e7cf1202
0x7f99e80d2cdd: _end+7f99e7a4e515
sp = 0x7f99d96ac220 {
fd = 35, id = 35, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f99d8d57158 {
id = "req",
{s,f,r,e} = {0x7f99d8d58730,0x7f99d8d58730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f99d92b6c60 {
ws = 0x7f99d92b6e20 {
id = "wrk",
{s,f,r,e} = {0x7f99d92b6450,0x7f99d92b6450,(nil),+2048},
},
},
},
2012-06-11 21:43:44 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 201 stat: 78 diff: 123). Did we crash?
2012-06-11 21:47:10 WARNING [0,205]: cold-default(httperf): Panic detected. I think!
2012-06-11 21:47:10 WARNING [0, 0]: cold-default(httperf):
Last panic at: Mon, 11 Jun 2012 21:45:54 GMT
Assert error in cnt_first(), cache/cache_center.c line 943:
Condition(req->sp == sp) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x41b021: CNT_Session+781
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f99e83759ca: _end+7f99e7cf1202
0x7f99e80d2cdd: _end+7f99e7a4e515
sp = 0x7f99d652dd20 {
fd = 22, id = 22, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f99d7c6e158 {
id = "req",
{s,f,r,e} = {0x7f99d7c6f730,0x7f99d7c6f730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f99d96e6c60 {
ws = 0x7f99d96e6e20 {
id = "wrk",
{s,f,r,e} = {0x7f99d96e6450,0x7f99d96e6450,(nil),+2048},
},
},
},
2012-06-11 21:47:10 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 407 stat: 75 diff: 332). Did we crash?
2012-06-11 21:47:10 WARNING [0, 0]: cold-default(httperf): Out of bounds: uptime(75) less than lower boundary 100
2012-06-11 21:47:10 WARNING [0, 0]: cold-default(httperf): Out of bounds: client_req(93223) less than lower boundary 1589840
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Load: 23:47:11 up 11 days, 10:10, 0 users, load average: 0.53, 0.69, 0.75
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Test name: cold-default
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Varnish options:
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): -t=3600
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): -s=malloc,10G
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Varnish parameters:
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): thread_stats_rate=1
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Payload size (excludes headers): 256
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Branch: master
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Number of clients involved: 24
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Type of test: httperf
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Test iterations: 2
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Runtime: 407 seconds
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Number of total connections: 80000
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Requests per connection: 10
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-11 21:47:11 [1, 0]: cold-default(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 3333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=4000000,0.50
2012-06-11 21:47:17 [2, 6]: 4gpluss-nogzip(httperf): Starting test
2012-06-11 21:47:20 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-11 21:47:20 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-11 21:47:21 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-11 21:47:21 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-11 21:47:22 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-11 22:09:02 [2,1299]: purge-fail(httperf): Starting test
2012-06-11 22:13:35 WARNING [0,273]: purge-fail(httperf): Panic detected. I think!
2012-06-11 22:13:35 WARNING [0, 0]: purge-fail(httperf):
Last panic at: Mon, 11 Jun 2012 22:13:02 GMT
Assert error in VCL_fetch_method(), ../../include/tbl/vcl_returns.h line 56:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x439875: VCL_fetch_method+1a5
0x4186a9: cnt_fetch+479
0x41accd: CNT_Session+42d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f20463f39ca: _end+7f2045d6f202
0x7f2046150cdd: _end+7f2045acc515
sp = 0x7f2037002f20 {
fd = 18, id = 18, xid = 1175626257,
client = 10.20.100.7 24003,
step = STP_FETCH,
handling = fetch,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f200db13020 {
ws = 0x7f200db13070 {
id = "bo",
{s,f,r,e} = {0x7f200db14aa0,+456,(nil),+58752},
},
do_stream
bodystatus = 3 (chunked),
},
http[bereq] = {
ws = 0x7f200db13070[bo]
"GET",
"/foo",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Varnish: 1175626257",
"Accept-Encoding: gzip",
},
http[beresp] = {
ws = 0x7f200db13070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Mon, 11 Jun 2012 22:13:02 GMT",
"Content-Type: text/plain",
"Last-Modified: Mon, 11 Jun 2012 22:09:05 GMT",
"Transfer-Encoding: chunked",
"Connection: keep-alive",
"Content-Encoding: gzip",
},
ws = 0x7f2037202158 {
id = "req",
{s,f,r,e} = {0x7f2037203730,+88,(nil),+59632},
},
http[req] = {
ws = 0x7f2037202158[req]
"GET",
"/foo",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f203775cc60 {
ws = 0x7f203775ce20 {
id = "wrk",
{s,f,r,e} = {0x7f203775c450,0x7f203775c450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-11 22:13:35 WARNING [0, 0]: purge-fail(httperf): Varnishstat uptime and measured run-time is too large (measured: 269 stat: 32 diff: 237). Did we crash?
2012-06-11 22:13:35 WARNING [0, 0]: purge-fail(httperf): Out of bounds: client_req(17774) less than lower boundary 290000
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Load: 00:13:36 up 11 days, 10:36, 0 users, load average: 0.37, 0.47, 0.90
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Test name: purge-fail
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Varnish options:
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Varnish parameters:
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): thread_stats_rate=1
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Payload size (excludes headers): 1K
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Branch: master
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Number of clients involved: 24
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Type of test: httperf
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Test iterations: 1
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Runtime: 269 seconds
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_recv {
if (!req.url ~ "/0/0.html") {
set req.request = "PURGE";
}
set req.url = "/foo";
return (lookup);
}
sub vcl_hit {
if (req.request == "PURGE") {
set obj.ttl = 0s;
error 200 "OK";
}
}
sub vcl_miss {
if (req.request == "PURGE") {
error 200 "Not in cache but not confusing httperf";
}
}
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Number of total connections: 300000
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Requests per connection: 1
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Extra options to httperf: --wset=999,0.5 --timeout=5
2012-06-11 22:13:36 [1, 0]: purge-fail(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 1 --num-conns 12500 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=999,0.5 --timeout=5
2012-06-11 22:13:43 [2, 6]: streaming-gzip(httperf): Starting test
2012-06-11 22:16:17 WARNING [0,154]: Tests finished with problems detected. Failed expectations: 15 Total run time: 9855 seconds
More information about the varnish-test
mailing list