[Fryer] master FAIL. 9 of 25 tests succeeded.
fryer at oneiros.varnish-software.com
fryer at oneiros.varnish-software.com
Sun Jun 10 00:16:31 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 streaming-grace cold-default purge-fail
Tests OK: streaming memleak 4gpluss-stream 4gpluss basic-fryer 4gpluss-nostream lru-random 4gpluss-nogzip streaming-gzip
2012-06-09 19:32:15 [1,13]: Server pantoum checked out varnish-3.0.0-beta2-1014-ge75cd2e of branch master
2012-06-09 19:32:43 [2,28]: httperf-lru-nostream-gzip(httperf): Starting test
2012-06-09 19:35:59 WARNING [0,195]: httperf-lru-nostream-gzip(httperf): Panic detected. I think!
2012-06-09 19:35:59 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf):
Last panic at: Sat, 09 Jun 2012 19:35:18 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
0x7f978dcb99ca: _end+7f978d635202
0x7f978da16cdd: _end+7f978d392515
sp = 0x7f977e704420 {
fd = 26, id = 26, xid = 1446092493,
client = 10.20.100.9 17361,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f977e913158 {
id = "req",
{s,f,r,e} = {0x7f977e914730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7f977e913158[req]
"GET",
"/1/9/4/8/1/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f977dd06c60 {
ws = 0x7f977dd06e20 {
id = "wrk",
{s,f,r,e} = {0x7f977dd06450,0x7f977dd06450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-09 19:35:59 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 191 stat: 40 diff: 151). Did we crash?
2012-06-09 19:35:59 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-09 19:35:59 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: client_req(34550) less than lower boundary 1989920
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Load: 21:35:59 up 9 days, 7:58, 0 users, load average: 2.57, 3.57, 1.63
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Test name: httperf-lru-nostream-gzip
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish options:
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): -t=3600
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): -s=malloc,30M
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish parameters:
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_stats_rate=1
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_max=5000
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): nuke_limit=250
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): http_gzip_support=on
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_min=200
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Payload size (excludes headers): 10K
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Branch: master
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of clients involved: 24
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Type of test: httperf
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Test iterations: 1
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Runtime: 191 seconds
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of total connections: 200000
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Requests per connection: 10
2012-06-09 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-09 19:35:59 [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-09 19:36:06 [2, 6]: httperf-lru-nostream-gzip-deflateoff(httperf): Starting test
2012-06-09 19:39:19 WARNING [0,193]: httperf-lru-nostream-gzip-deflateoff(httperf): Panic detected. I think!
2012-06-09 19:39:19 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf):
Last panic at: Sat, 09 Jun 2012 19:38:38 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
0x7f11f7aad9ca: _end+7f11f7429202
0x7f11f780acdd: _end+7f11f7186515
sp = 0x7f11e5dbc020 {
fd = 14, id = 14, xid = 1277241645,
client = 10.20.100.8 17612,
step = STP_FETCH,
handling = fetch,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f11e8a22020 {
ws = 0x7f11e8a22070 {
id = "bo",
{s,f,r,e} = {0x7f11e8a23aa0,+10488,(nil),+58752},
},
do_stream
bodystatus = 4 (length),
},
http[bereq] = {
ws = 0x7f11e8a22070[bo]
"GET",
"/1/9/1/9/4/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
"X-Varnish: 1277241645",
"Accept-Encoding: gzip",
},
http[beresp] = {
ws = 0x7f11e8a22070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Sat, 09 Jun 2012 19:38:38 GMT",
"Content-Type: text/plain",
"Content-Length: 10240",
"Last-Modified: Sat, 09 Jun 2012 19:36:09 GMT",
"Connection: keep-alive",
"Accept-Ranges: bytes",
},
ws = 0x7f11e8068158 {
id = "req",
{s,f,r,e} = {0x7f11e8069730,+136,(nil),+59632},
},
http[req] = {
ws = 0x7f11e8068158[req]
"GET",
"/1/9/1/9/4/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
worker = 0x7f11e823ec60 {
ws = 0x7f11e823ee20 {
id = "wrk",
{s,f,r,e} = {0x7f11e823e450,0x7f11e823e450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-09 19:39:19 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnishstat uptime and measured run-time is too large (measured: 189 stat: 40 diff: 149). Did we crash?
2012-06-09 19:39:20 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-09 19:39:20 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: client_req(67280) less than lower boundary 1989920
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Load: 21:39:20 up 9 days, 8:02, 0 users, load average: 0.73, 2.31, 1.51
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test name: httperf-lru-nostream-gzip-deflateoff
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish options:
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -t=3600
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -s=malloc,30M
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish parameters:
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_stats_rate=1
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_max=5000
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): nuke_limit=250
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): http_gzip_support=on
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_min=200
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Payload size (excludes headers): 10K
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Branch: master
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of clients involved: 24
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Type of test: httperf
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test iterations: 1
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Runtime: 189 seconds
2012-06-09 19:39:20 [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-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of total connections: 200000
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Requests per connection: 10
2012-06-09 19:39:20 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-09 19:39:20 [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-09 19:39:27 [2, 6]: streaming(httperf): Starting test
2012-06-09 19:42:24 [2,177]: httperf-lru-default(httperf): Starting test
2012-06-09 19:46:25 WARNING [0,241]: httperf-lru-default(httperf): Panic detected. I think!
2012-06-09 19:46:25 WARNING [0, 0]: httperf-lru-default(httperf):
Last panic at: Sat, 09 Jun 2012 19:45:04 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
0x7f0161aba9ca: _end+7f0161436202
0x7f0161817cdd: _end+7f0161193515
sp = 0x7f0153c0c320 {
fd = 25, id = 25, xid = 1014497126,
client = 10.20.100.9 15815,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f01526a8158 {
id = "req",
{s,f,r,e} = {0x7f01526a9730,+168,(nil),+59632},
},
http[req] = {
ws = 0x7f01526a8158[req]
"GET",
"/1/7/9/8/8/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f0151acec60 {
ws = 0x7f0151acee20 {
id = "wrk",
{s,f,r,e} = {0x7f0151ace450,0x7f0151ace450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-09 19:46:25 WARNING [0, 0]: httperf-lru-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 237 stat: 80 diff: 157). Did we crash?
2012-06-09 19:46:26 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-09 19:46:26 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: client_req(121070) less than lower boundary 1989920
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): Load: 21:46:26 up 9 days, 8:09, 0 users, load average: 0.58, 1.08, 1.18
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): Test name: httperf-lru-default
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): Varnish options:
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): -t=3600
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): -s=malloc,30M
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): Varnish parameters:
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): thread_stats_rate=1
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): thread_pool_max=5000
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): nuke_limit=250
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): thread_pool_min=200
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): Payload size (excludes headers): 10K
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): Branch: master
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): Number of clients involved: 24
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): Type of test: httperf
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): Test iterations: 1
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): Runtime: 237 seconds
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): Number of total connections: 200000
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): Requests per connection: 10
2012-06-09 19:46:26 [1, 0]: httperf-lru-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-09 19:46:26 [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-09 19:46:33 [2, 6]: memleak(httperf): Starting test
2012-06-09 19:48:55 [2,142]: 4gpluss-stream(httperf): Starting test
2012-06-09 19:48:58 WARNING [0, 3]: Varnish failed to start. Fallback attempts starting
2012-06-09 19:48:58 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-09 19:48:58 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-09 19:48:59 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-09 19:48:59 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-09 20:09:03 [2,1203]: httperf-lru-stream-default(httperf): Starting test
2012-06-09 20:12:31 WARNING [0,208]: httperf-lru-stream-default(httperf): Panic detected. I think!
2012-06-09 20:12:31 WARNING [0, 0]: httperf-lru-stream-default(httperf):
Last panic at: Sat, 09 Jun 2012 20:12:21 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
0x7f833f77c9ca: _end+7f833f0f8202
0x7f833f4d9cdd: _end+7f833ee55515
sp = 0x7f8330308920 {
fd = 14, id = 14, xid = 279719120,
client = 10.20.100.8 17672,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f832d902158 {
id = "req",
{s,f,r,e} = {0x7f832d903730,+568,(nil),+59632},
},
http[req] = {
ws = 0x7f832d902158[req]
"GET",
"/1/9/3/9/8/1.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f832f836c60 {
ws = 0x7f832f836e20 {
id = "wrk",
{s,f,r,e} = {0x7f832f836450,0x7f832f836450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-09 20:12:31 WARNING [0, 0]: httperf-lru-stream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 204 stat: 10 diff: 194). Did we crash?
2012-06-09 20:12:32 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-09 20:12:32 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: client_req(0) less than lower boundary 1989920
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): Load: 22:12:32 up 9 days, 8:35, 0 users, load average: 0.70, 0.82, 0.94
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): Test name: httperf-lru-stream-default
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): Varnish options:
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): -t=3600
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): -s=malloc,30M
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): Varnish parameters:
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): thread_stats_rate=1
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_max=5000
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): nuke_limit=250
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_min=200
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): Payload size (excludes headers): 10K
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): Branch: master
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): Number of clients involved: 24
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): Type of test: httperf
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): Test iterations: 1
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): Runtime: 204 seconds
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): Number of total connections: 200000
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): Requests per connection: 10
2012-06-09 20:12:32 [1, 0]: httperf-lru-stream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-09 20:12:32 [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-09 20:12:39 [2, 6]: httperf-hot(httperf): Starting test
2012-06-09 20:13:50 WARNING [0,71]: httperf-hot(httperf): Panic detected. I think!
2012-06-09 20:13:50 WARNING [0, 0]: httperf-hot(httperf):
Last panic at: Sat, 09 Jun 2012 20:13:11 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
0x7f228c92d9ca: _end+7f228c2a9202
0x7f228c68acdd: _end+7f228c006515
sp = 0x7f227db05f20 {
fd = 16, id = 16, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f227d357158 {
id = "req",
{s,f,r,e} = {0x7f227d358730,0x7f227d358730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f227dc31c60 {
ws = 0x7f227dc31e20 {
id = "wrk",
{s,f,r,e} = {0x7f227dc31450,0x7f227dc31450,(nil),+2048},
},
},
},
2012-06-09 20:13:50 WARNING [0, 0]: httperf-hot(httperf): Out of bounds: client_req(146690) less than lower boundary 989840
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): Load: 22:13:50 up 9 days, 8:36, 0 users, load average: 0.58, 0.75, 0.91
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): Test name: httperf-hot
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): Varnish options:
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): Varnish parameters:
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): thread_stats_rate=1
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): Payload size (excludes headers): 256
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): Branch: master
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): Number of clients involved: 24
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): Type of test: httperf
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): Test iterations: 1
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): Runtime: 67 seconds
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): Number of total connections: 100000
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): Requests per connection: 10
2012-06-09 20:13:50 [1, 0]: httperf-hot(httperf): Extra options to httperf: --wset=1000,0.25
2012-06-09 20:13:50 [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-09 20:13:57 [2, 6]: httperf-lru-nostream-nogzip(httperf): Starting test
2012-06-09 20:17:29 WARNING [0,212]: httperf-lru-nostream-nogzip(httperf): Panic detected. I think!
2012-06-09 20:17:29 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf):
Last panic at: Sat, 09 Jun 2012 20:16:38 GMT
Assert error in VCL_deliver_method(), ../../include/tbl/vcl_returns.h line 60:
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
0x439695: VCL_deliver_method+1a5
0x419729: cnt_prepresp+269
0x41ac6d: CNT_Session+3cd
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f9d2b90b9ca: _end+7f9d2b287202
0x7f9d2b668cdd: _end+7f9d2afe4515
sp = 0x7f9d1c207720 {
fd = 13, id = 13, xid = 1231322369,
client = 10.20.100.8 16667,
step = STP_PREPRESP,
handling = deliver,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
ws = 0x7f9d20d90158 {
id = "req",
{s,f,r,e} = {0x7f9d20d91730,+232,(nil),+59632},
},
http[req] = {
ws = 0x7f9d20d90158[req]
"GET",
"/1/8/4/3/0/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
http[resp] = {
ws = 0x7f9d20d90158[req]
"HTTP/1.1",
"OK",
"Server: nginx/0.7.65",
"Content-Type: text/plain",
"Last-Modified: Sat, 09 Jun 2012 20:14:00 GMT",
"Content-Length: 10240",
"Accept-Ranges: bytes",
"Date: Sat, 09 Jun 2012 20:16:38 GMT",
"X-Varnish: 1231322369",
"Age: 0",
"Via: 1.1 varnish",
"Connection: keep-alive",
},
worker = 0x7f9d1bdaec60 {
ws = 0x7f9d1bdaee20 {
id = "wrk",
{s,f,r,e} = {0x7f9d1bdae450,0x7f9d1bdae450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
obj = 0x7f9d1b509e00 {
xid = 1231322369,
ws = 0x7f9d1b509e18 {
id = "obj",
{s,f,r,e} = {0x7f9d1b509fd8,+192,(nil),+224},
},
http[obj] = {
ws = 0x7f9d1b509e18[obj]
"HTTP/1.1",
"OK",
"Server: nginx/0.7.65",
"Date: Sat, 09 Jun 2012 20:16:38 GMT",
"Content-Type: text/plain",
"Last-Modified: Sat, 09 Jun 2012 20:14:00 GMT",
"Content-Length: 10240",
},
len = 10240,
store = {
10240 {
4c 6f 72 65 6d 20 69 70 73 75 6d 20 64 6f 6c 6f |Lorem ipsum dolo|
72 20 73 69 74 20 61 6d 65 74 2c 20 63 6f 6e 73 |r sit amet, cons|
65 63 74 65 74 75 72 20 61 64 69 70 69 73 69 63 |ectetur adipisic|
69 6e 67 20 65 6c 69 74 2c 20 73 65 64 20 64 6f |ing elit, sed do|
[10176 more]
},
},
},
},
2012-06-09 20:17:29 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 208 stat: 50 diff: 158). Did we crash?
2012-06-09 20:17:29 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: n_lru_nuked(1293) less than lower boundary 80000
2012-06-09 20:17:29 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: client_req(41630) less than lower boundary 1989920
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): Load: 22:17:30 up 9 days, 8:40, 0 users, load average: 0.45, 0.77, 0.88
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test name: httperf-lru-nostream-nogzip
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish options:
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): -t=3600
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): -s=malloc,30M
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish parameters:
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_stats_rate=1
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_max=5000
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): nuke_limit=250
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): http_gzip_support=off
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_min=200
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): Payload size (excludes headers): 10K
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): Branch: master
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of clients involved: 24
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): Type of test: httperf
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test iterations: 1
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): Runtime: 208 seconds
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of total connections: 200000
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): Requests per connection: 10
2012-06-09 20:17:30 [1, 0]: httperf-lru-nostream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-09 20:17:30 [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-09 20:17:36 [2, 6]: cold-gzip(httperf): Starting test
2012-06-09 20:20:59 WARNING [0,202]: cold-gzip(httperf): Panic detected. I think!
2012-06-09 20:20:59 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Sat, 09 Jun 2012 20:19:56 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
0x7f6a884169ca: _end+7f6a87d92202
0x7f6a88173cdd: _end+7f6a87aef515
sp = 0x7f6a7914a120 {
fd = 16, id = 16, xid = 1537362622,
client = 10.20.100.8 5898,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f6a73d48158 {
id = "req",
{s,f,r,e} = {0x7f6a73d49730,+88,(nil),+59632},
},
http[req] = {
ws = 0x7f6a73d48158[req]
"GET",
"/0/2/6/1/7/5/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f6a7a2fdc60 {
ws = 0x7f6a7a2fde20 {
id = "wrk",
{s,f,r,e} = {0x7f6a7a2fd450,0x7f6a7a2fd450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-09 20:20:59 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 198 stat: 62 diff: 136). Did we crash?
2012-06-09 20:24:01 WARNING [0,182]: cold-gzip(httperf): Panic detected. I think!
2012-06-09 20:24:01 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Sat, 09 Jun 2012 20:22:27 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
0x7f6a884169ca: _end+7f6a87d92202
0x7f6a88173cdd: _end+7f6a87aef515
sp = 0x7f6a7a320320 {
fd = 26, id = 26, xid = 941009035,
client = 10.20.100.8 5132,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f6a78bd7158 {
id = "req",
{s,f,r,e} = {0x7f6a78bd8730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f6a78bd7158[req]
"GET",
"/0/2/3/2/3/3/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f6a7a40bc60 {
ws = 0x7f6a7a40be20 {
id = "wrk",
{s,f,r,e} = {0x7f6a7a40b450,0x7f6a7a40b450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-09 20:24:01 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 380 stat: 93 diff: 287). Did we crash?
2012-06-09 20:24:01 WARNING [0, 0]: cold-gzip(httperf): Out of bounds: uptime(93) less than lower boundary 100
2012-06-09 20:24:01 WARNING [0, 0]: cold-gzip(httperf): Out of bounds: client_req(162330) less than lower boundary 1589840
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): Load: 22:24:01 up 9 days, 8:46, 0 users, load average: 0.42, 0.62, 0.77
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): Test name: cold-gzip
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): Varnish options:
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): -t=3600
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): -s=malloc,10G
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): Varnish parameters:
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): thread_stats_rate=1
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): http_gzip_support=on
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): Payload size (excludes headers): 256
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): Branch: master
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): Number of clients involved: 24
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): Type of test: httperf
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): Test iterations: 2
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): Runtime: 380 seconds
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): Number of total connections: 80000
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): Requests per connection: 10
2012-06-09 20:24:01 [1, 0]: cold-gzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-09 20:24:01 [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-09 20:24:08 [2, 6]: 4gpluss(httperf): Starting test
2012-06-09 20:24:11 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-09 20:24:11 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-09 20:24:12 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-09 20:24:12 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-09 20:24:13 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-09 20:45:45 [2,1292]: httperf-lru-stream-gzip(httperf): Starting test
2012-06-09 20:49:07 WARNING [0,201]: httperf-lru-stream-gzip(httperf): Panic detected. I think!
2012-06-09 20:49:07 WARNING [0, 0]: httperf-lru-stream-gzip(httperf):
Last panic at: Sat, 09 Jun 2012 20:48:24 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
0x7f9575f179ca: _end+7f9575893202
0x7f9575c74cdd: _end+7f95755f0515
sp = 0x7f956990e220 {
fd = 21, id = 21, xid = 1659166466,
client = 10.20.100.8 17920,
step = STP_FETCH,
handling = fetch,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f9566eb9020 {
ws = 0x7f9566eb9070 {
id = "bo",
{s,f,r,e} = {0x7f9566ebaaa0,+512,(nil),+58752},
},
do_stream
bodystatus = 3 (chunked),
},
http[bereq] = {
ws = 0x7f9566eb9070[bo]
"GET",
"/1/9/4/0/2/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
"X-Varnish: 1659166466",
"Accept-Encoding: gzip",
},
http[beresp] = {
ws = 0x7f9566eb9070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Sat, 09 Jun 2012 20:48:24 GMT",
"Content-Type: text/plain",
"Last-Modified: Sat, 09 Jun 2012 20:45:48 GMT",
"Transfer-Encoding: chunked",
"Connection: keep-alive",
"Content-Encoding: gzip",
},
ws = 0x7f9565f13158 {
id = "req",
{s,f,r,e} = {0x7f9565f14730,+136,(nil),+59632},
},
http[req] = {
ws = 0x7f9565f13158[req]
"GET",
"/1/9/4/0/2/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
worker = 0x7f9565dc6c60 {
ws = 0x7f9565dc6e20 {
id = "wrk",
{s,f,r,e} = {0x7f9565dc6450,0x7f9565dc6450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-09 20:49:07 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 197 stat: 42 diff: 155). Did we crash?
2012-06-09 20:49:07 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-09 20:49:07 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: client_req(31140) less than lower boundary 1989920
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): Load: 22:49:07 up 9 days, 9:12, 0 users, load average: 0.51, 0.75, 1.10
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): Test name: httperf-lru-stream-gzip
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish options:
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): -t=3600
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): -s=malloc,30M
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish parameters:
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): thread_stats_rate=1
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_max=5000
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): nuke_limit=250
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): http_gzip_support=on
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_min=200
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): Payload size (excludes headers): 10K
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): Branch: master
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): Number of clients involved: 24
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): Type of test: httperf
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): Test iterations: 1
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): Runtime: 197 seconds
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): Number of total connections: 200000
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): Requests per connection: 10
2012-06-09 20:49:07 [1, 0]: httperf-lru-stream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-09 20:49:07 [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-09 20:49:14 [2, 6]: httperf-lru-stream-nogzip(httperf): Starting test
2012-06-09 20:52:31 WARNING [0,196]: httperf-lru-stream-nogzip(httperf): Panic detected. I think!
2012-06-09 20:52:31 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf):
Last panic at: Sat, 09 Jun 2012 20:51:35 GMT
Assert error in VCL_deliver_method(), ../../include/tbl/vcl_returns.h line 60:
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
0x439695: VCL_deliver_method+1a5
0x419729: cnt_prepresp+269
0x41ac6d: CNT_Session+3cd
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f870058d9ca: _end+7f86fff09202
0x7f87002eacdd: _end+7f86ffc66515
sp = 0x7f86ffe23f20 {
fd = 25, id = 25, xid = 793230597,
client = 10.20.100.8 14723,
step = STP_PREPRESP,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f86efc0e158 {
id = "req",
{s,f,r,e} = {0x7f86efc0f730,+400,(nil),+59632},
},
http[req] = {
ws = 0x7f86efc0e158[req]
"GET",
"/1/6/8/4/6/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
http[resp] = {
ws = 0x7f86efc0e158[req]
"HTTP/1.1",
"OK",
"Server: nginx/0.7.65",
"Content-Type: text/plain",
"Last-Modified: Sat, 09 Jun 2012 20:49:18 GMT",
"Content-Length: 10240",
"Accept-Ranges: bytes",
"Date: Sat, 09 Jun 2012 20:51:35 GMT",
"X-Varnish: 793230597 793230526",
"Age: 0",
"Via: 1.1 varnish",
"Connection: keep-alive",
},
worker = 0x7f86f0b2ac60 {
ws = 0x7f86f0b2ae20 {
id = "wrk",
{s,f,r,e} = {0x7f86f0b2a450,0x7f86f0b2a450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
obj = 0x7f86f0111400 {
xid = 793230526,
ws = 0x7f86f0111418 {
id = "obj",
{s,f,r,e} = {0x7f86f01115d8,+192,(nil),+224},
},
http[obj] = {
ws = 0x7f86f0111418[obj]
"HTTP/1.1",
"OK",
"Server: nginx/0.7.65",
"Date: Sat, 09 Jun 2012 20:51:35 GMT",
"Content-Type: text/plain",
"Last-Modified: Sat, 09 Jun 2012 20:49:18 GMT",
"Content-Length: 10240",
},
len = 10240,
store = {
10240 {
4c 6f 72 65 6d 20 69 70 73 75 6d 20 64 6f 6c 6f |Lorem ipsum dolo|
72 20 73 69 74 20 61 6d 65 74 2c 20 63 6f 6e 73 |r sit amet, cons|
65 63 74 65 74 75 72 20 61 64 69 70 69 73 69 63 |ectetur adipisic|
69 6e 67 20 65 6c 69 74 2c 20 73 65 64 20 64 6f |ing elit, sed do|
[10176 more]
},
},
},
},
2012-06-09 20:52:31 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 192 stat: 55 diff: 137). Did we crash?
2012-06-09 20:52:31 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: n_lru_nuked(8455) less than lower boundary 80000
2012-06-09 20:52:31 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: client_req(113210) less than lower boundary 1989920
2012-06-09 20:52:37 [1, 5]: httperf-lru-stream-nogzip(httperf): Load: 22:52:37 up 9 days, 9:15, 0 users, load average: 0.58, 0.71, 1.01
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): Test name: httperf-lru-stream-nogzip
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish options:
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): -t=3600
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): -s=malloc,30M
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish parameters:
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_stats_rate=1
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_max=5000
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): nuke_limit=250
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): http_gzip_support=off
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_min=200
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): Payload size (excludes headers): 10K
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): Branch: master
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of clients involved: 24
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): Type of test: httperf
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): Test iterations: 1
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): Runtime: 192 seconds
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of total connections: 200000
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): Requests per connection: 10
2012-06-09 20:52:37 [1, 0]: httperf-lru-stream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-09 20:52:37 [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-09 20:52:44 [2, 6]: basic-fryer(httperf): Starting test
2012-06-09 20:53:06 [2,22]: cold-nogzip(httperf): Starting test
2012-06-09 20:56:32 WARNING [0,205]: cold-nogzip(httperf): Panic detected. I think!
2012-06-09 20:56:32 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Sat, 09 Jun 2012 20:54:12 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
0x7f2f5b32a9ca: _end+7f2f5aca6202
0x7f2f5b087cdd: _end+7f2f5aa03515
sp = 0x7f2f51919720 {
fd = 28, id = 28, xid = 60022631,
client = 10.20.100.8 3419,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f2f49435158 {
id = "req",
{s,f,r,e} = {0x7f2f49436730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f2f49435158[req]
"GET",
"/0/1/3/3/9/3/5.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f2f4c655c60 {
ws = 0x7f2f4c655e20 {
id = "wrk",
{s,f,r,e} = {0x7f2f4c655450,0x7f2f4c655450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-09 20:56:32 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 201 stat: 139 diff: 62). Did we crash?
2012-06-09 21:00:00 WARNING [0,207]: cold-nogzip(httperf): Panic detected. I think!
2012-06-09 21:00:00 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Sat, 09 Jun 2012 20:57: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
0x7f2f5b32a9ca: _end+7f2f5aca6202
0x7f2f5b087cdd: _end+7f2f5aa03515
sp = 0x7f2f5539d520 {
fd = 15, id = 15, xid = 2054931731,
client = 10.20.100.9 3395,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f2f4c3ab158 {
id = "req",
{s,f,r,e} = {0x7f2f4c3ac730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f2f4c3ab158[req]
"GET",
"/0/1/2/8/2/8/0.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f2f4d099c60 {
ws = 0x7f2f4d099e20 {
id = "wrk",
{s,f,r,e} = {0x7f2f4d099450,0x7f2f4d099450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-09 21:00:00 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 409 stat: 153 diff: 256). Did we crash?
2012-06-09 21:00:00 WARNING [0, 0]: cold-nogzip(httperf): Out of bounds: client_req(430083) less than lower boundary 1589840
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): Load: 23:00:00 up 9 days, 9:22, 0 users, load average: 0.61, 0.65, 0.85
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): Test name: cold-nogzip
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): Varnish options:
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): -t=3600
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): -s=malloc,10G
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): Varnish parameters:
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): thread_stats_rate=1
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): http_gzip_support=off
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): Payload size (excludes headers): 256
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): Branch: master
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): Number of clients involved: 24
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): Type of test: httperf
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): Test iterations: 2
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): Runtime: 409 seconds
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): Number of total connections: 80000
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): Requests per connection: 10
2012-06-09 21:00:00 [1, 0]: cold-nogzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-09 21:00:00 [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-09 21:00:07 [2, 6]: 4gpluss-nostream(httperf): Starting test
2012-06-09 21:00:10 WARNING [0, 3]: Varnish failed to start. Fallback attempts starting
2012-06-09 21:00:10 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-09 21:00:16 [1, 5]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-09 21:00:16 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-09 21:00:17 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-09 21:19:48 [2,1171]: lru-random(httperf): Starting test
2012-06-09 21:31:17 [2,689]: siege-test(siege): Starting test
2012-06-09 21:31:37 WARNING [0,19]: siege-test(siege): Panic detected. I think!
2012-06-09 21:31:37 WARNING [0, 0]: siege-test(siege):
Last panic at: Sat, 09 Jun 2012 21:31:23 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
0x7f54967759ca: _end+7f54960f1202
0x7f54964d2cdd: _end+7f5495e4e515
sp = 0x7f5487008320 {
fd = 15, id = 15, xid = 893745829,
client = 10.20.100.7 46776,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f54878a4158 {
id = "req",
{s,f,r,e} = {0x7f54878a5730,+152,(nil),+59632},
},
http[req] = {
ws = 0x7f54878a4158[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 = 0x7f5488776c60 {
ws = 0x7f5488776e20 {
id = "wrk",
{s,f,r,e} = {0x7f5488776450,0x7f5488776450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-09 21:31:37 [1, 0]: siege-test(siege): Load: 23:31:37 up 9 days, 9:54, 0 users, load average: 0.39, 0.57, 0.64
2012-06-09 21:31:37 [1, 0]: siege-test(siege): Test name: siege-test
2012-06-09 21:31:37 [1, 0]: siege-test(siege): Varnish options:
2012-06-09 21:31:37 [1, 0]: siege-test(siege): Varnish parameters:
2012-06-09 21:31:37 [1, 0]: siege-test(siege): thread_stats_rate=1
2012-06-09 21:31:37 [1, 0]: siege-test(siege): Payload size (excludes headers): 256
2012-06-09 21:31:37 [1, 0]: siege-test(siege): Branch: master
2012-06-09 21:31:37 [1, 0]: siege-test(siege): Number of clients involved: 0
2012-06-09 21:31:37 [1, 0]: siege-test(siege): Type of test: siege
2012-06-09 21:31:37 [1, 0]: siege-test(siege): Test iterations: 1
2012-06-09 21:31:37 [1, 0]: siege-test(siege): Runtime: 15 seconds
2012-06-09 21:31:37 [1, 0]: siege-test(siege): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-09 21:31:44 [2, 6]: httperf-lru-nostream-default(httperf): Starting test
2012-06-09 21:35:27 WARNING [0,222]: httperf-lru-nostream-default(httperf): Panic detected. I think!
2012-06-09 21:35:27 WARNING [0, 0]: httperf-lru-nostream-default(httperf):
Last panic at: Sat, 09 Jun 2012 21:34:31 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
0x7f74c19f19ca: _end+7f74c136d202
0x7f74c174ecdd: _end+7f74c10ca515
sp = 0x7f74b2c03b20 {
fd = 17, id = 17, xid = 341390538,
client = 10.20.100.8 13249,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f74bbad7158 {
id = "req",
{s,f,r,e} = {0x7f74bbad8730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7f74bbad7158[req]
"GET",
"/1/5/0/2/5/1.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f74b1d4dc60 {
ws = 0x7f74b1d4de20 {
id = "wrk",
{s,f,r,e} = {0x7f74b1d4d450,0x7f74b1d4d450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-09 21:35:27 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 219 stat: 55 diff: 164). Did we crash?
2012-06-09 21:35:27 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-09 21:35:27 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: client_req(23050) less than lower boundary 1989920
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): Load: 23:35:27 up 9 days, 9:58, 0 users, load average: 0.43, 0.66, 0.67
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): Test name: httperf-lru-nostream-default
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): Varnish options:
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): -t=3600
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): -s=malloc,30M
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): Varnish parameters:
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): thread_stats_rate=1
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_max=5000
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): nuke_limit=250
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_min=200
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): Payload size (excludes headers): 10K
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): Branch: master
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): Number of clients involved: 24
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): Type of test: httperf
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): Test iterations: 1
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): Runtime: 219 seconds
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): Number of total connections: 200000
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): Requests per connection: 10
2012-06-09 21:35:27 [1, 0]: httperf-lru-nostream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-09 21:35:27 [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-09 21:35:34 [2, 6]: httperf-rapid-expire(httperf): Starting test
2012-06-09 21:36:48 WARNING [0,73]: httperf-rapid-expire(httperf): Panic detected. I think!
2012-06-09 21:36:48 WARNING [0, 0]: httperf-rapid-expire(httperf):
Last panic at: Sat, 09 Jun 2012 21:36:11 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
0x7ff9e15fd9ca: _end+7ff9e0f79202
0x7ff9e135acdd: _end+7ff9e0cd6515
sp = 0x7ff9d2705220 {
fd = 27, id = 27, xid = 1146184213,
client = 10.20.100.9 8401,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7ff9d239b158 {
id = "req",
{s,f,r,e} = {0x7ff9d239c730,+224,(nil),+59632},
},
http[req] = {
ws = 0x7ff9d239b158[req]
"GET",
"/7/6.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7ff9d28e0c60 {
ws = 0x7ff9d28e0e20 {
id = "wrk",
{s,f,r,e} = {0x7ff9d28e0450,0x7ff9d28e0450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-09 21:36:48 WARNING [0, 0]: httperf-rapid-expire(httperf): Varnishstat uptime and measured run-time is too large (measured: 69 stat: 36 diff: 33). Did we crash?
2012-06-09 21:36:48 WARNING [0, 0]: httperf-rapid-expire(httperf): Out of bounds: client_req(56770) less than lower boundary 989840
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Load: 23:36:48 up 9 days, 9:59, 0 users, load average: 0.60, 0.68, 0.68
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Test name: httperf-rapid-expire
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Varnish options:
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): -t=2
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Varnish parameters:
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): thread_stats_rate=1
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Payload size (excludes headers): 256
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Branch: master
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Number of clients involved: 24
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Type of test: httperf
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Test iterations: 1
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Runtime: 69 seconds
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Number of total connections: 100000
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Requests per connection: 10
2012-06-09 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Extra options to httperf: --wset=100,0.30
2012-06-09 21:36:49 [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-09 21:36:55 [2, 6]: streaming-grace(httperf): Starting test
2012-06-09 21:39:49 WARNING [0,174]: streaming-grace(httperf): Panic detected. I think!
2012-06-09 21:39:49 WARNING [0, 0]: streaming-grace(httperf):
Last panic at: Sat, 09 Jun 2012 21:38:18 GMT
Assert error in cnt_wait(), cache/cache_center.c line 119:
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
0x41ba66: CNT_Session+11c6
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f94f684c9ca: _end+7f94f61c8202
0x7f94f65a9cdd: _end+7f94f5f25515
sp = 0x7f94e7405620 {
fd = 230, id = 230, xid = 0,
client = 10.20.100.9 3984,
step = STP_WAIT,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f94e2435158 {
id = "req",
{s,f,r,e} = {0x7f94e2436730,0x7f94e2436730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f94eab17c60 {
ws = 0x7f94eab17e20 {
id = "wrk",
{s,f,r,e} = {0x7f94eab17450,0x7f94eab17450,(nil),+2048},
},
},
},
2012-06-09 21:39:49 WARNING [0, 0]: streaming-grace(httperf): Varnishstat uptime and measured run-time is too large (measured: 170 stat: 90 diff: 80). Did we crash?
2012-06-09 21:39:55 [1, 5]: streaming-grace(httperf): Load: 23:39:55 up 9 days, 10:02, 0 users, load average: 0.49, 0.64, 0.66
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): Test name: streaming-grace
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): Varnish options:
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): -t=1
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): Varnish parameters:
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): thread_stats_rate=1
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): thread_pool_add_delay=1
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): http_gzip_support=off
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): default_grace=10
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): Payload size (excludes headers): 1M
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): Branch: master
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): Number of clients involved: 24
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): Type of test: httperf
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): Test iterations: 1
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): Runtime: 170 seconds
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
.connect_timeout = 10s;
}
sub vcl_recv {
set req.grace = 15s;
}
sub vcl_fetch {
set beresp.do_stream = true;
set beresp.grace = 10s;
set beresp.ttl = 15s;
}
sub vcl_deliver {
set resp.http.x-fryer = "some test";
}
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): Number of total connections: 10000
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): Requests per connection: 1
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): Extra options to httperf: --wset=1000,0.1 --rate 3
2012-06-09 21:39:55 [1, 0]: streaming-grace(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 1 --num-conns 416 --port 8080 --burst-length 1 --client 23/24 --server 10.20.100.12 --wset=1000,0.1 --rate 3
2012-06-09 21:40:02 [2, 6]: cold-default(httperf): Starting test
2012-06-09 21:43:30 WARNING [0,208]: cold-default(httperf): Panic detected. I think!
2012-06-09 21:43:30 WARNING [0, 0]: cold-default(httperf):
Last panic at: Sat, 09 Jun 2012 21:41:56 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
0x7f2d782529ca: _end+7f2d77bce202
0x7f2d77fafcdd: _end+7f2d7792b515
sp = 0x7f2d68d55d20 {
fd = 31, id = 31, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f2d61a40158 {
id = "req",
{s,f,r,e} = {0x7f2d61a41730,0x7f2d61a41730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f2d6959dc60 {
ws = 0x7f2d6959de20 {
id = "wrk",
{s,f,r,e} = {0x7f2d6959d450,0x7f2d6959d450,(nil),+2048},
},
},
},
2012-06-09 21:43:30 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 204 stat: 94 diff: 110). Did we crash?
2012-06-09 21:47:00 WARNING [0,209]: cold-default(httperf): Panic detected. I think!
2012-06-09 21:47:00 WARNING [0, 0]: cold-default(httperf):
Last panic at: Sat, 09 Jun 2012 21:45:01 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
0x7f2d782529ca: _end+7f2d77bce202
0x7f2d77fafcdd: _end+7f2d7792b515
sp = 0x7f2d693c2420 {
fd = 19, id = 19, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f2d66305158 {
id = "req",
{s,f,r,e} = {0x7f2d66306730,0x7f2d66306730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f2d69796c60 {
ws = 0x7f2d69796e20 {
id = "wrk",
{s,f,r,e} = {0x7f2d69796450,0x7f2d69796450,(nil),+2048},
},
},
},
2012-06-09 21:47:00 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 414 stat: 118 diff: 296). Did we crash?
2012-06-09 21:47:00 WARNING [0, 0]: cold-default(httperf): Out of bounds: client_req(234522) less than lower boundary 1589840
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): Load: 23:47:00 up 9 days, 10:09, 0 users, load average: 0.62, 0.68, 0.66
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): Test name: cold-default
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): Varnish options:
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): -t=3600
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): -s=malloc,10G
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): Varnish parameters:
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): thread_stats_rate=1
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): Payload size (excludes headers): 256
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): Branch: master
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): Number of clients involved: 24
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): Type of test: httperf
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): Test iterations: 2
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): Runtime: 414 seconds
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): Number of total connections: 80000
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): Requests per connection: 10
2012-06-09 21:47:00 [1, 0]: cold-default(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-09 21:47:00 [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-09 21:47:07 [2, 6]: 4gpluss-nogzip(httperf): Starting test
2012-06-09 21:47:10 WARNING [0, 3]: Varnish failed to start. Fallback attempts starting
2012-06-09 21:47:10 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-09 21:47:11 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-09 21:47:11 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-09 21:47:12 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-09 22:08:48 [2,1296]: purge-fail(httperf): Starting test
2012-06-09 22:13:50 WARNING [0,301]: purge-fail(httperf): Panic detected. I think!
2012-06-09 22:13:50 WARNING [0, 0]: purge-fail(httperf):
Last panic at: Sat, 09 Jun 2012 22:13:06 GMT
Assert error in VCL_error_method(), ../../include/tbl/vcl_returns.h line 64:
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
0x4394b5: VCL_error_method+1a5
0x41a0b6: cnt_error+2e6
0x41ab05: CNT_Session+265
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f1c189139ca: _end+7f1c1828f202
0x7f1c18670cdd: _end+7f1c17fec515
sp = 0x7f1c0f514f20 {
fd = 18, id = 18, xid = 1673473600,
client = 10.20.100.7 25671,
step = STP_ERROR,
handling = error,
err_code = 200, err_reason = Not in cache but not confusing httperf,
restarts = 0, esi_level = 0
busyobj = 0x7f1c00ebd020 {
ws = 0x7f1c00ebd070 {
id = "bo",
{s,f,r,e} = {0x7f1c00ebeaa0,0x7f1c00ebeaa0,(nil),+58752},
},
do_stream
bodystatus = 0 (none),
},
ws = 0x7f1c09913158 {
id = "req",
{s,f,r,e} = {0x7f1c09914730,+96,(nil),+59632},
},
http[req] = {
ws = 0x7f1c09913158[req]
"PURGE",
"/foo",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f1c09be4c60 {
ws = 0x7f1c09be4e20 {
id = "wrk",
{s,f,r,e} = {0x7f1c09be4450,0x7f1c09be4450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
obj = 0x7f1c01df0000 {
xid = 1673473600,
ws = 0x7f1c01df0018 {
id = "obj",
{s,f,r,e} = {0x7f1c01df0548,+96,(nil),+32768},
},
http[obj] = {
ws = 0x7f1c01df0018[obj]
"HTTP/1.1",
"Not in cache but not confusing httperf",
"Date: Sat, 09 Jun 2012 22:13:06 GMT",
"Server: Varnish",
},
len = 0,
store = {
},
},
},
2012-06-09 22:13:50 WARNING [0, 0]: purge-fail(httperf): Varnishstat uptime and measured run-time is too large (measured: 296 stat: 42 diff: 254). Did we crash?
2012-06-09 22:13:50 WARNING [0, 0]: purge-fail(httperf): Out of bounds: client_req(6449) less than lower boundary 290000
2012-06-09 22:13:50 [1, 0]: purge-fail(httperf): Load: 00:13:50 up 9 days, 10:36, 0 users, load average: 0.25, 0.57, 0.99
2012-06-09 22:13:50 [1, 0]: purge-fail(httperf): Test name: purge-fail
2012-06-09 22:13:50 [1, 0]: purge-fail(httperf): Varnish options:
2012-06-09 22:13:50 [1, 0]: purge-fail(httperf): Varnish parameters:
2012-06-09 22:13:50 [1, 0]: purge-fail(httperf): thread_stats_rate=1
2012-06-09 22:13:50 [1, 0]: purge-fail(httperf): Payload size (excludes headers): 1K
2012-06-09 22:13:50 [1, 0]: purge-fail(httperf): Branch: master
2012-06-09 22:13:50 [1, 0]: purge-fail(httperf): Number of clients involved: 24
2012-06-09 22:13:50 [1, 0]: purge-fail(httperf): Type of test: httperf
2012-06-09 22:13:50 [1, 0]: purge-fail(httperf): Test iterations: 1
2012-06-09 22:13:50 [1, 0]: purge-fail(httperf): Runtime: 296 seconds
2012-06-09 22:13:50 [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-09 22:13:50 [1, 0]: purge-fail(httperf): Number of total connections: 300000
2012-06-09 22:13:50 [1, 0]: purge-fail(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-09 22:13:50 [1, 0]: purge-fail(httperf): Requests per connection: 1
2012-06-09 22:13:50 [1, 0]: purge-fail(httperf): Extra options to httperf: --wset=999,0.5 --timeout=5
2012-06-09 22:13:50 [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-09 22:13:57 [2, 6]: streaming-gzip(httperf): Starting test
2012-06-09 22:16:31 WARNING [0,154]: Tests finished with problems detected. Failed expectations: 16 Total run time: 9870 seconds
More information about the varnish-test
mailing list