[Fryer] master FAIL. 9 of 25 tests succeeded.
fryer at oneiros.varnish-software.com
fryer at oneiros.varnish-software.com
Thu Jun 7 00:14:53 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-06 19:32:16 [1,13]: Server pantoum checked out varnish-3.0.0-beta2-1014-ge75cd2e of branch master
2012-06-06 19:32:39 [2,23]: httperf-lru-nostream-gzip(httperf): Starting test
2012-06-06 19:35:59 WARNING [0,199]: httperf-lru-nostream-gzip(httperf): Panic detected. I think!
2012-06-06 19:35:59 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf):
Last panic at: Wed, 06 Jun 2012 19:35: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
0x7f5eb0a249ca: _end+7f5eb03a0202
0x7f5eb0781cdd: _end+7f5eb00fd515
sp = 0x7f5ea1ac5220 {
fd = 19, id = 19, xid = 1756351561,
client = 10.20.100.8 15238,
step = STP_FETCH,
handling = fetch,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f5ea1557020 {
ws = 0x7f5ea1557070 {
id = "bo",
{s,f,r,e} = {0x7f5ea1558aa0,+512,(nil),+58752},
},
do_stream
bodystatus = 3 (chunked),
},
http[bereq] = {
ws = 0x7f5ea1557070[bo]
"GET",
"/1/7/1/2/7/6.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
"X-Varnish: 1756351561",
"Accept-Encoding: gzip",
},
http[beresp] = {
ws = 0x7f5ea1557070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Wed, 06 Jun 2012 19:35:02 GMT",
"Content-Type: text/plain",
"Last-Modified: Wed, 06 Jun 2012 19:32:42 GMT",
"Transfer-Encoding: chunked",
"Connection: keep-alive",
"Content-Encoding: gzip",
},
ws = 0x7f5ea0f46158 {
id = "req",
{s,f,r,e} = {0x7f5ea0f47730,+136,(nil),+59632},
},
http[req] = {
ws = 0x7f5ea0f46158[req]
"GET",
"/1/7/1/2/7/6.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
worker = 0x7f5ea1126c60 {
ws = 0x7f5ea1126e20 {
id = "wrk",
{s,f,r,e} = {0x7f5ea1126450,0x7f5ea1126450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-06 19:35:59 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 195 stat: 55 diff: 140). Did we crash?
2012-06-06 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-06 19:35:59 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: client_req(64790) less than lower boundary 1989920
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Load: 21:35:59 up 6 days, 7:58, 3 users, load average: 8.96, 4.10, 1.53
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Test name: httperf-lru-nostream-gzip
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish options:
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): -t=3600
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): -s=malloc,30M
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish parameters:
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_stats_rate=1
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_max=5000
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): nuke_limit=250
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): http_gzip_support=on
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_min=200
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Payload size (excludes headers): 10K
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Branch: master
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of clients involved: 24
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Type of test: httperf
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Test iterations: 1
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Runtime: 195 seconds
2012-06-06 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-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of total connections: 200000
2012-06-06 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-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Requests per connection: 10
2012-06-06 19:35:59 [1, 0]: httperf-lru-nostream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-06 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-06 19:36:11 [2,11]: httperf-lru-nostream-gzip-deflateoff(httperf): Starting test
2012-06-06 19:39:40 WARNING [0,208]: httperf-lru-nostream-gzip-deflateoff(httperf): Panic detected. I think!
2012-06-06 19:39:40 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf):
Last panic at: Wed, 06 Jun 2012 19:38:20 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
0x7fbbebaa49ca: _end+7fbbeb420202
0x7fbbeb801cdd: _end+7fbbeb17d515
sp = 0x7fbbdacc5d20 {
fd = 25, id = 25, xid = 353646619,
client = 10.20.100.8 14986,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fbbda202158 {
id = "req",
{s,f,r,e} = {0x7fbbda203730,+568,(nil),+59632},
},
http[req] = {
ws = 0x7fbbda202158[req]
"GET",
"/1/6/2/4/1/6.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fbbdb9c2c60 {
ws = 0x7fbbdb9c2e20 {
id = "wrk",
{s,f,r,e} = {0x7fbbdb9c2450,0x7fbbdb9c2450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-06 19:39:40 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnishstat uptime and measured run-time is too large (measured: 204 stat: 78 diff: 126). Did we crash?
2012-06-06 19:39:40 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-06 19:39:40 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: client_req(204923) less than lower boundary 1989920
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Load: 21:39:40 up 6 days, 8:02, 3 users, load average: 0.64, 2.35, 1.37
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test name: httperf-lru-nostream-gzip-deflateoff
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish options:
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -t=3600
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -s=malloc,30M
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish parameters:
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_stats_rate=1
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_max=5000
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): nuke_limit=250
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): http_gzip_support=on
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_min=200
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Payload size (excludes headers): 10K
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Branch: master
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of clients involved: 24
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Type of test: httperf
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test iterations: 1
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Runtime: 204 seconds
2012-06-06 19:39:40 [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-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of total connections: 200000
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Requests per connection: 10
2012-06-06 19:39:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-06 19:39:40 [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-06 19:39:47 [2, 6]: streaming(httperf): Starting test
2012-06-06 19:42:41 [2,173]: httperf-lru-default(httperf): Starting test
2012-06-06 19:46:12 WARNING [0,210]: httperf-lru-default(httperf): Panic detected. I think!
2012-06-06 19:46:12 WARNING [0, 0]: httperf-lru-default(httperf):
Last panic at: Wed, 06 Jun 2012 19:45:17 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
0x7f482ee849ca: _end+7f482e800202
0x7f482ebe1cdd: _end+7f482e55d515
sp = 0x7f481f50a120 {
fd = 17, id = 17, xid = 1852132148,
client = 10.20.100.9 14477,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f481ea02158 {
id = "req",
{s,f,r,e} = {0x7f481ea03730,+568,(nil),+59632},
},
http[req] = {
ws = 0x7f481ea02158[req]
"GET",
"/1/5/9/7/9/5.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f481ecdec60 {
ws = 0x7f481ecdee20 {
id = "wrk",
{s,f,r,e} = {0x7f481ecde450,0x7f481ecde450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-06 19:46:12 WARNING [0, 0]: httperf-lru-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 206 stat: 54 diff: 152). Did we crash?
2012-06-06 19:46:12 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-06 19:46:12 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: client_req(86410) less than lower boundary 1989920
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): Load: 21:46:12 up 6 days, 8:09, 3 users, load average: 0.72, 1.33, 1.22
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): Test name: httperf-lru-default
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): Varnish options:
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): -t=3600
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): -s=malloc,30M
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): Varnish parameters:
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): thread_stats_rate=1
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): thread_pool_max=5000
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): nuke_limit=250
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): thread_pool_min=200
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): Payload size (excludes headers): 10K
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): Branch: master
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): Number of clients involved: 24
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): Type of test: httperf
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): Test iterations: 1
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): Runtime: 206 seconds
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): Number of total connections: 200000
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): Requests per connection: 10
2012-06-06 19:46:12 [1, 0]: httperf-lru-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-06 19:46:12 [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-06 19:46:19 [2, 6]: memleak(httperf): Starting test
2012-06-06 19:48:42 [2,143]: 4gpluss-stream(httperf): Starting test
2012-06-06 19:48:45 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-06 19:48:45 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-06 19:48:46 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-06 19:48:46 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-06 19:48:47 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-06 20:08:39 [2,1192]: httperf-lru-stream-default(httperf): Starting test
2012-06-06 20:12:05 WARNING [0,206]: httperf-lru-stream-default(httperf): Panic detected. I think!
2012-06-06 20:12:05 WARNING [0, 0]: httperf-lru-stream-default(httperf):
Last panic at: Wed, 06 Jun 2012 20:11:06 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
0x7ff5525f19ca: _end+7ff551f6d202
0x7ff55234ecdd: _end+7ff551cca515
sp = 0x7ff544e4e220 {
fd = 30, id = 30, xid = 712907223,
client = 10.20.100.8 14847,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7ff5439af158 {
id = "req",
{s,f,r,e} = {0x7ff5439b0730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7ff5439af158[req]
"GET",
"/1/6/4/9/6/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7ff54241ec60 {
ws = 0x7ff54241ee20 {
id = "wrk",
{s,f,r,e} = {0x7ff54241e450,0x7ff54241e450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-06 20:12:05 WARNING [0, 0]: httperf-lru-stream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 202 stat: 58 diff: 144). Did we crash?
2012-06-06 20:12:06 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-06 20:12:06 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: client_req(163950) less than lower boundary 1989920
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): Load: 22:12:06 up 6 days, 8:35, 3 users, load average: 0.45, 0.62, 0.84
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): Test name: httperf-lru-stream-default
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): Varnish options:
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): -t=3600
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): -s=malloc,30M
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): Varnish parameters:
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): thread_stats_rate=1
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_max=5000
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): nuke_limit=250
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_min=200
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): Payload size (excludes headers): 10K
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): Branch: master
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): Number of clients involved: 24
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): Type of test: httperf
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): Test iterations: 1
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): Runtime: 202 seconds
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): Number of total connections: 200000
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): Requests per connection: 10
2012-06-06 20:12:06 [1, 0]: httperf-lru-stream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-06 20:12:06 [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-06 20:12:13 [2, 6]: httperf-hot(httperf): Starting test
2012-06-06 20:13:25 WARNING [0,71]: httperf-hot(httperf): Panic detected. I think!
2012-06-06 20:13:25 WARNING [0, 0]: httperf-hot(httperf):
Last panic at: Wed, 06 Jun 2012 20:12:46 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
0x7f716ea689ca: _end+7f716e3e4202
0x7f716e7c5cdd: _end+7f716e141515
sp = 0x7f715f6f6420 {
fd = 22, id = 22, xid = 1161529482,
client = 10.20.100.9 8208,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f7168aea158 {
id = "req",
{s,f,r,e} = {0x7f7168aeb730,+152,(nil),+59632},
},
http[req] = {
ws = 0x7f7168aea158[req]
"GET",
"/6/3/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f71607a4c60 {
ws = 0x7f71607a4e20 {
id = "wrk",
{s,f,r,e} = {0x7f71607a4450,0x7f71607a4450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-06 20:13:25 WARNING [0, 0]: httperf-hot(httperf): Out of bounds: client_req(142653) less than lower boundary 989840
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): Load: 22:13:25 up 6 days, 8:36, 3 users, load average: 0.99, 0.74, 0.86
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): Test name: httperf-hot
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): Varnish options:
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): Varnish parameters:
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): thread_stats_rate=1
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): Payload size (excludes headers): 256
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): Branch: master
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): Number of clients involved: 24
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): Type of test: httperf
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): Test iterations: 1
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): Runtime: 68 seconds
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): Number of total connections: 100000
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): Requests per connection: 10
2012-06-06 20:13:25 [1, 0]: httperf-hot(httperf): Extra options to httperf: --wset=1000,0.25
2012-06-06 20:13:25 [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-06 20:13:32 [2, 6]: httperf-lru-nostream-nogzip(httperf): Starting test
2012-06-06 20:17:24 WARNING [0,232]: httperf-lru-nostream-nogzip(httperf): Panic detected. I think!
2012-06-06 20:17:24 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf):
Last panic at: Wed, 06 Jun 2012 20:15:09 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
0x7f61e59a59ca: _end+7f61e5321202
0x7f61e5702cdd: _end+7f61e507e515
sp = 0x7f61d6406d20 {
fd = 19, id = 19, xid = 1012202424,
client = 10.20.100.9 4637,
step = STP_FETCH,
handling = fetch,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f61dae23020 {
ws = 0x7f61dae23070 {
id = "bo",
{s,f,r,e} = {0x7f61dae24aa0,+248,(nil),+58752},
},
do_stream
bodystatus = 4 (length),
},
http[bereq] = {
ws = 0x7f61dae23070[bo]
"GET",
"/0/4/4/3/3/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: 1012202424",
},
http[beresp] = {
ws = 0x7f61dae23070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Wed, 06 Jun 2012 20:15:09 GMT",
"Content-Type: text/plain",
"Content-Length: 10240",
"Last-Modified: Wed, 06 Jun 2012 20:13:35 GMT",
"Connection: keep-alive",
"Accept-Ranges: bytes",
},
ws = 0x7f61d617e158 {
id = "req",
{s,f,r,e} = {0x7f61d617f730,+136,(nil),+59632},
},
http[req] = {
ws = 0x7f61d617e158[req]
"GET",
"/0/4/4/3/3/0.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.9",
},
worker = 0x7f61d5506c60 {
ws = 0x7f61d5506e20 {
id = "wrk",
{s,f,r,e} = {0x7f61d5506450,0x7f61d5506450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-06 20:17:24 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 228 stat: 134 diff: 94). Did we crash?
2012-06-06 20:17:24 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: n_lru_nuked(46036) less than lower boundary 80000
2012-06-06 20:17:24 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: client_req(488903) less than lower boundary 1989920
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): Load: 22:17:24 up 6 days, 8:40, 3 users, load average: 0.59, 0.80, 0.86
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test name: httperf-lru-nostream-nogzip
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish options:
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): -t=3600
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): -s=malloc,30M
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish parameters:
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_stats_rate=1
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_max=5000
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): nuke_limit=250
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): http_gzip_support=off
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_min=200
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): Payload size (excludes headers): 10K
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): Branch: master
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of clients involved: 24
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): Type of test: httperf
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test iterations: 1
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): Runtime: 228 seconds
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of total connections: 200000
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): Requests per connection: 10
2012-06-06 20:17:24 [1, 0]: httperf-lru-nostream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-06 20:17:24 [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-06 20:17:31 [2, 6]: cold-gzip(httperf): Starting test
2012-06-06 20:20:33 WARNING [0,181]: cold-gzip(httperf): Panic detected. I think!
2012-06-06 20:20:33 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Wed, 06 Jun 2012 20:19:34 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
0x7f2d6b8a89ca: _end+7f2d6b224202
0x7f2d6b605cdd: _end+7f2d6af81515
sp = 0x7f2d5b406120 {
fd = 22, id = 22, xid = 1481099924,
client = 10.20.100.8 6340,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f2d5b657158 {
id = "req",
{s,f,r,e} = {0x7f2d5b658730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f2d5b657158[req]
"GET",
"/0/3/0/9/3/7/1.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f2d5c9bac60 {
ws = 0x7f2d5c9bae20 {
id = "wrk",
{s,f,r,e} = {0x7f2d5c9ba450,0x7f2d5c9ba450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-06 20:20:33 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 177 stat: 58 diff: 119). Did we crash?
2012-06-06 20:23:42 WARNING [0,188]: cold-gzip(httperf): Panic detected. I think!
2012-06-06 20:23:42 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Wed, 06 Jun 2012 20:22:52 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
0x7f2d6b8a89ca: _end+7f2d6b224202
0x7f2d6b605cdd: _end+7f2d6af81515
sp = 0x7f2d51299620 {
fd = 16, id = 16, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f2d5bf9b158 {
id = "req",
{s,f,r,e} = {0x7f2d5bf9c730,0x7f2d5bf9c730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f2d5cd18c60 {
ws = 0x7f2d5cd18e20 {
id = "wrk",
{s,f,r,e} = {0x7f2d5cd18450,0x7f2d5cd18450,(nil),+2048},
},
},
},
2012-06-06 20:23:42 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 366 stat: 49 diff: 317). Did we crash?
2012-06-06 20:23:42 WARNING [0, 0]: cold-gzip(httperf): Out of bounds: uptime(49) less than lower boundary 100
2012-06-06 20:23:42 WARNING [0, 0]: cold-gzip(httperf): Out of bounds: client_req(45900) less than lower boundary 1589840
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): Load: 22:23:42 up 6 days, 8:46, 3 users, load average: 0.35, 0.69, 0.80
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): Test name: cold-gzip
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): Varnish options:
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): -t=3600
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): -s=malloc,10G
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): Varnish parameters:
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): thread_stats_rate=1
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): http_gzip_support=on
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): Payload size (excludes headers): 256
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): Branch: master
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): Number of clients involved: 24
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): Type of test: httperf
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): Test iterations: 2
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): Runtime: 366 seconds
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): Number of total connections: 80000
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): Requests per connection: 10
2012-06-06 20:23:42 [1, 0]: cold-gzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-06 20:23:42 [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-06 20:23:49 [2, 6]: 4gpluss(httperf): Starting test
2012-06-06 20:23:52 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-06 20:23:52 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-06 20:23:53 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-06 20:23:53 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-06 20:23:54 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-06 20:45:17 [2,1283]: httperf-lru-stream-gzip(httperf): Starting test
2012-06-06 20:48:50 WARNING [0,213]: httperf-lru-stream-gzip(httperf): Panic detected. I think!
2012-06-06 20:48:50 WARNING [0, 0]: httperf-lru-stream-gzip(httperf):
Last panic at: Wed, 06 Jun 2012 20:47:52 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
0x7f4fb8af69ca: _end+7f4fb8472202
0x7f4fb8853cdd: _end+7f4fb81cf515
sp = 0x7f4fa7f17020 {
fd = 19, id = 19, xid = 1848697481,
client = 10.20.100.8 16016,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f4fa8702158 {
id = "req",
{s,f,r,e} = {0x7f4fa8703730,+168,(nil),+59632},
},
http[req] = {
ws = 0x7f4fa8702158[req]
"GET",
"/1/8/0/7/8/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f4fa8956c60 {
ws = 0x7f4fa8956e20 {
id = "wrk",
{s,f,r,e} = {0x7f4fa8956450,0x7f4fa8956450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-06 20:48:50 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 208 stat: 57 diff: 151). Did we crash?
2012-06-06 20:48:50 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-06 20:48:50 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: client_req(122890) less than lower boundary 1989920
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): Load: 22:48:51 up 6 days, 9:11, 3 users, load average: 0.71, 0.84, 1.08
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): Test name: httperf-lru-stream-gzip
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish options:
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): -t=3600
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): -s=malloc,30M
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish parameters:
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): thread_stats_rate=1
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_max=5000
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): nuke_limit=250
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): http_gzip_support=on
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_min=200
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): Payload size (excludes headers): 10K
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): Branch: master
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): Number of clients involved: 24
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): Type of test: httperf
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): Test iterations: 1
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): Runtime: 208 seconds
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): Number of total connections: 200000
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): Requests per connection: 10
2012-06-06 20:48:51 [1, 0]: httperf-lru-stream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-06 20:48:51 [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-06 20:48:57 [2, 6]: httperf-lru-stream-nogzip(httperf): Starting test
2012-06-06 20:52:32 WARNING [0,214]: httperf-lru-stream-nogzip(httperf): Panic detected. I think!
2012-06-06 20:52:32 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf):
Last panic at: Wed, 06 Jun 2012 20:51:31 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
0x7f7854ace9ca: _end+7f785444a202
0x7f785482bcdd: _end+7f78541a7515
sp = 0x7f784a911920 {
fd = 18, id = 18, xid = 1771485698,
client = 10.20.100.8 17611,
step = STP_FETCH,
handling = fetch,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f7842e9b020 {
ws = 0x7f7842e9b070 {
id = "bo",
{s,f,r,e} = {0x7f7842e9caa0,+10488,(nil),+58752},
},
do_stream
bodystatus = 4 (length),
},
http[bereq] = {
ws = 0x7f7842e9b070[bo]
"GET",
"/1/9/4/8/2/1.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
"X-Varnish: 1771485698",
},
http[beresp] = {
ws = 0x7f7842e9b070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Wed, 06 Jun 2012 20:51:31 GMT",
"Content-Type: text/plain",
"Content-Length: 10240",
"Last-Modified: Wed, 06 Jun 2012 20:49:01 GMT",
"Connection: keep-alive",
"Accept-Ranges: bytes",
},
ws = 0x7f7845ad3158 {
id = "req",
{s,f,r,e} = {0x7f7845ad4730,+136,(nil),+59632},
},
http[req] = {
ws = 0x7f7845ad3158[req]
"GET",
"/1/9/4/8/2/1.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
worker = 0x7f7844aaec60 {
ws = 0x7f7844aaee20 {
id = "wrk",
{s,f,r,e} = {0x7f7844aae450,0x7f7844aae450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-06 20:52:32 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 210 stat: 60 diff: 150). Did we crash?
2012-06-06 20:52:32 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: n_lru_nuked(3688) less than lower boundary 80000
2012-06-06 20:52:32 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: client_req(65542) less than lower boundary 1989920
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): Load: 22:52:32 up 6 days, 9:15, 3 users, load average: 0.84, 0.98, 1.09
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): Test name: httperf-lru-stream-nogzip
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish options:
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): -t=3600
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): -s=malloc,30M
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish parameters:
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_stats_rate=1
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_max=5000
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): nuke_limit=250
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): http_gzip_support=off
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_min=200
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): Payload size (excludes headers): 10K
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): Branch: master
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of clients involved: 24
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): Type of test: httperf
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): Test iterations: 1
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): Runtime: 210 seconds
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of total connections: 200000
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): Requests per connection: 10
2012-06-06 20:52:32 [1, 0]: httperf-lru-stream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-06 20:52:32 [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-06 20:52:39 [2, 6]: basic-fryer(httperf): Starting test
2012-06-06 20:53:05 [2,25]: cold-nogzip(httperf): Starting test
2012-06-06 20:56:25 WARNING [0,200]: cold-nogzip(httperf): Panic detected. I think!
2012-06-06 20:56:25 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Wed, 06 Jun 2012 20:55:51 GMT
Assert error in VCL_hit_method(), ../../include/tbl/vcl_returns.h line 51:
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
0x439a55: VCL_hit_method+1a5
0x418037: cnt_hit+1a7
0x41acfd: CNT_Session+45d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f2d993b79ca: _end+7f2d98d33202
0x7f2d99114cdd: _end+7f2d98a90515
sp = 0x7f2d87474220 {
fd = 24, id = 24, xid = 1351955658,
client = 10.20.100.8 6870,
step = STP_HIT,
handling = hash,
restarts = 0, esi_level = 0
ws = 0x7f2d85f4a158 {
id = "req",
{s,f,r,e} = {0x7f2d85f4b730,+304,(nil),+59632},
},
http[req] = {
ws = 0x7f2d85f4a158[req]
"GET",
"/0/3/4/6/3/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 = 0x7f2d8a775c60 {
ws = 0x7f2d8a775e20 {
id = "wrk",
{s,f,r,e} = {0x7f2d8a775450,0x7f2d8a775450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
obj = 0x7f2d865dac00 {
xid = 1351955639,
ws = 0x7f2d865dac18 {
id = "obj",
{s,f,r,e} = {0x7f2d865dadd8,+192,(nil),+224},
},
http[obj] = {
ws = 0x7f2d865dac18[obj]
"HTTP/1.1",
"OK",
"Server: nginx/0.7.65",
"Date: Wed, 06 Jun 2012 20:55:51 GMT",
"Content-Type: text/plain",
"Last-Modified: Wed, 06 Jun 2012 20:53:08 GMT",
"Content-Length: 256",
},
len = 256,
store = {
256 {
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|
[192 more]
},
},
},
},
2012-06-06 20:56:25 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 195 stat: 33 diff: 162). Did we crash?
2012-06-06 20:59:42 WARNING [0,197]: cold-nogzip(httperf): Panic detected. I think!
2012-06-06 20:59:42 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Wed, 06 Jun 2012 20:58:22 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
0x7f2d993b79ca: _end+7f2d98d33202
0x7f2d99114cdd: _end+7f2d98a90515
sp = 0x7f2d82f93220 {
fd = 24, id = 24, xid = 832153885,
client = 10.20.100.7 7495,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f2d853d9158 {
id = "req",
{s,f,r,e} = {0x7f2d853da730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f2d853d9158[req]
"GET",
"/0/3/9/4/5/7/7.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f2d8b23ec60 {
ws = 0x7f2d8b23ee20 {
id = "wrk",
{s,f,r,e} = {0x7f2d8b23e450,0x7f2d8b23e450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-06 20:59:42 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 393 stat: 79 diff: 314). Did we crash?
2012-06-06 20:59:43 WARNING [0, 0]: cold-nogzip(httperf): Out of bounds: uptime(79) less than lower boundary 100
2012-06-06 20:59:43 WARNING [0, 0]: cold-nogzip(httperf): Out of bounds: client_req(123050) less than lower boundary 1589840
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): Load: 22:59:43 up 6 days, 9:22, 3 users, load average: 0.64, 0.72, 0.90
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): Test name: cold-nogzip
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): Varnish options:
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): -t=3600
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): -s=malloc,10G
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): Varnish parameters:
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): thread_stats_rate=1
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): http_gzip_support=off
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): Payload size (excludes headers): 256
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): Branch: master
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): Number of clients involved: 24
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): Type of test: httperf
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): Test iterations: 2
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): Runtime: 393 seconds
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): Number of total connections: 80000
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): Requests per connection: 10
2012-06-06 20:59:43 [1, 0]: cold-nogzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-06 20:59:43 [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-06 20:59:50 [2, 6]: 4gpluss-nostream(httperf): Starting test
2012-06-06 20:59:53 WARNING [0, 3]: Varnish failed to start. Fallback attempts starting
2012-06-06 20:59:53 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-06 20:59:53 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-06 20:59:54 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-06 20:59:54 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-06 21:19:58 [2,1203]: lru-random(httperf): Starting test
2012-06-06 21:31:33 [2,694]: siege-test(siege): Starting test
2012-06-06 21:31:51 WARNING [0,17]: siege-test(siege): Panic detected. I think!
2012-06-06 21:31:51 WARNING [0, 0]: siege-test(siege):
Last panic at: Wed, 06 Jun 2012 21:31:37 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
0x7f190bcd69ca: _end+7f190b652202
0x7f190ba33cdd: _end+7f190b3af515
sp = 0x7f18fc502620 {
fd = 22, id = 22, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f18fc302158 {
id = "req",
{s,f,r,e} = {0x7f18fc303730,0x7f18fc303730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f1900aa6c60 {
ws = 0x7f1900aa6e20 {
id = "wrk",
{s,f,r,e} = {0x7f1900aa6450,0x7f1900aa6450,(nil),+2048},
},
},
},
2012-06-06 21:31:51 [1, 0]: siege-test(siege): Load: 23:31:51 up 6 days, 9:54, 3 users, load average: 0.15, 0.40, 0.51
2012-06-06 21:31:51 [1, 0]: siege-test(siege): Test name: siege-test
2012-06-06 21:31:51 [1, 0]: siege-test(siege): Varnish options:
2012-06-06 21:31:51 [1, 0]: siege-test(siege): Varnish parameters:
2012-06-06 21:31:51 [1, 0]: siege-test(siege): thread_stats_rate=1
2012-06-06 21:31:51 [1, 0]: siege-test(siege): Payload size (excludes headers): 256
2012-06-06 21:31:51 [1, 0]: siege-test(siege): Branch: master
2012-06-06 21:31:51 [1, 0]: siege-test(siege): Number of clients involved: 0
2012-06-06 21:31:51 [1, 0]: siege-test(siege): Type of test: siege
2012-06-06 21:31:51 [1, 0]: siege-test(siege): Test iterations: 1
2012-06-06 21:31:51 [1, 0]: siege-test(siege): Runtime: 14 seconds
2012-06-06 21:31:51 [1, 0]: siege-test(siege): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-06 21:31:58 [2, 6]: httperf-lru-nostream-default(httperf): Starting test
2012-06-06 21:35:32 WARNING [0,213]: httperf-lru-nostream-default(httperf): Panic detected. I think!
2012-06-06 21:35:32 WARNING [0, 0]: httperf-lru-nostream-default(httperf):
Last panic at: Wed, 06 Jun 2012 21:33:57 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
0x7f62fdbdc9ca: _end+7f62fd558202
0x7f62fd939cdd: _end+7f62fd2b5515
sp = 0x7f62eedf1320 {
fd = 14, id = 14, xid = 918142968,
client = 10.20.100.9 14161,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f62ec3b8158 {
id = "req",
{s,f,r,e} = {0x7f62ec3b9730,+88,(nil),+59632},
},
http[req] = {
ws = 0x7f62ec3b8158[req]
"GET",
"/1/5/8/5/6/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f62edaaac60 {
ws = 0x7f62edaaae20 {
id = "wrk",
{s,f,r,e} = {0x7f62edaaa450,0x7f62edaaa450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-06 21:35:32 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 209 stat: 94 diff: 115). Did we crash?
2012-06-06 21:35:32 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: n_lru_nuked(2692) less than lower boundary 80000
2012-06-06 21:35:32 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: client_req(344770) less than lower boundary 1989920
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): Load: 23:35:32 up 6 days, 9:58, 3 users, load average: 0.87, 0.80, 0.65
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): Test name: httperf-lru-nostream-default
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): Varnish options:
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): -t=3600
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): -s=malloc,30M
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): Varnish parameters:
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): thread_stats_rate=1
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_max=5000
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): nuke_limit=250
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_min=200
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): Payload size (excludes headers): 10K
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): Branch: master
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): Number of clients involved: 24
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): Type of test: httperf
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): Test iterations: 1
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): Runtime: 209 seconds
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): Number of total connections: 200000
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): Requests per connection: 10
2012-06-06 21:35:32 [1, 0]: httperf-lru-nostream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-06 21:35:32 [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-06 21:35:39 [2, 6]: httperf-rapid-expire(httperf): Starting test
2012-06-06 21:36:48 WARNING [0,69]: httperf-rapid-expire(httperf): Panic detected. I think!
2012-06-06 21:36:48 WARNING [0, 0]: httperf-rapid-expire(httperf):
Last panic at: Wed, 06 Jun 2012 21:36:14 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
0x7f0c9516d9ca: _end+7f0c94ae9202
0x7f0c94ecacdd: _end+7f0c94846515
sp = 0x7f0c85e03820 {
fd = 23, id = 23, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f0c85b02158 {
id = "req",
{s,f,r,e} = {0x7f0c85b03730,0x7f0c85b03730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f0c862c2c60 {
ws = 0x7f0c862c2e20 {
id = "wrk",
{s,f,r,e} = {0x7f0c862c2450,0x7f0c862c2450,(nil),+2048},
},
},
},
2012-06-06 21:36:48 WARNING [0, 0]: httperf-rapid-expire(httperf): Varnishstat uptime and measured run-time is too large (measured: 65 stat: 33 diff: 32). Did we crash?
2012-06-06 21:36:48 WARNING [0, 0]: httperf-rapid-expire(httperf): Out of bounds: client_req(148140) less than lower boundary 989840
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Load: 23:36:49 up 6 days, 9:59, 3 users, load average: 0.92, 0.84, 0.67
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Test name: httperf-rapid-expire
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Varnish options:
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): -t=2
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Varnish parameters:
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): thread_stats_rate=1
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Payload size (excludes headers): 256
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Branch: master
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Number of clients involved: 24
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Type of test: httperf
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Test iterations: 1
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Runtime: 65 seconds
2012-06-06 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-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Number of total connections: 100000
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Requests per connection: 10
2012-06-06 21:36:49 [1, 0]: httperf-rapid-expire(httperf): Extra options to httperf: --wset=100,0.30
2012-06-06 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-06 21:36:55 [2, 6]: streaming-grace(httperf): Starting test
2012-06-06 21:39:42 WARNING [0,166]: streaming-grace(httperf): Panic detected. I think!
2012-06-06 21:39:42 WARNING [0, 0]: streaming-grace(httperf):
Last panic at: Wed, 06 Jun 2012 21:38:00 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
0x7f865175b9ca: _end+7f86510d7202
0x7f86514b8cdd: _end+7f8650e34515
sp = 0x7f8648687420 {
fd = 1005, id = 1005, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f863c313158 {
id = "req",
{s,f,r,e} = {0x7f863c314730,0x7f863c314730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f86435fdc60 {
ws = 0x7f86435fde20 {
id = "wrk",
{s,f,r,e} = {0x7f86435fd450,0x7f86435fd450,(nil),+2048},
},
},
},
2012-06-06 21:39:42 WARNING [0, 0]: streaming-grace(httperf): Varnishstat uptime and measured run-time is too large (measured: 163 stat: 101 diff: 62). Did we crash?
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): Load: 23:39:43 up 6 days, 10:02, 3 users, load average: 0.83, 0.85, 0.70
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): Test name: streaming-grace
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): Varnish options:
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): -t=1
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): Varnish parameters:
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): thread_stats_rate=1
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): thread_pool_add_delay=1
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): http_gzip_support=off
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): default_grace=10
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): Payload size (excludes headers): 1M
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): Branch: master
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): Number of clients involved: 24
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): Type of test: httperf
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): Test iterations: 1
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): Runtime: 163 seconds
2012-06-06 21:39:43 [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-06 21:39:43 [1, 0]: streaming-grace(httperf): Number of total connections: 10000
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): Requests per connection: 1
2012-06-06 21:39:43 [1, 0]: streaming-grace(httperf): Extra options to httperf: --wset=1000,0.1 --rate 3
2012-06-06 21:39:43 [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-06 21:39:49 [2, 6]: cold-default(httperf): Starting test
2012-06-06 21:43:06 WARNING [0,196]: cold-default(httperf): Panic detected. I think!
2012-06-06 21:43:06 WARNING [0, 0]: cold-default(httperf):
Last panic at: Wed, 06 Jun 2012 21:41:59 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
0x7fbd6281f9ca: _end+7fbd6219b202
0x7fbd6257ccdd: _end+7fbd61ef8515
sp = 0x7fbd51207720 {
fd = 24, id = 24, xid = 1793102816,
client = 10.20.100.9 7236,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fbd53010158 {
id = "req",
{s,f,r,e} = {0x7fbd53011730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7fbd53010158[req]
"GET",
"/0/3/6/0/7/2/3.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fbd536b6c60 {
ws = 0x7fbd536b6e20 {
id = "wrk",
{s,f,r,e} = {0x7fbd536b6450,0x7fbd536b6450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-06 21:43:06 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 192 stat: 66 diff: 126). Did we crash?
2012-06-06 21:46:36 WARNING [0,209]: cold-default(httperf): Panic detected. I think!
2012-06-06 21:46:36 WARNING [0, 0]: cold-default(httperf):
Last panic at: Wed, 06 Jun 2012 21:44:58 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
0x7fbd6281f9ca: _end+7fbd6219b202
0x7fbd6257ccdd: _end+7fbd61ef8515
sp = 0x7fbd52d1bb20 {
fd = 27, id = 27, xid = 475250785,
client = 10.20.100.8 5837,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fbd53068158 {
id = "req",
{s,f,r,e} = {0x7fbd53069730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7fbd53068158[req]
"GET",
"/0/2/8/7/6/4/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fbd53b84c60 {
ws = 0x7fbd53b84e20 {
id = "wrk",
{s,f,r,e} = {0x7fbd53b84450,0x7fbd53b84450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-06 21:46:36 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 402 stat: 97 diff: 305). Did we crash?
2012-06-06 21:46:36 WARNING [0, 0]: cold-default(httperf): Out of bounds: uptime(97) less than lower boundary 100
2012-06-06 21:46:36 WARNING [0, 0]: cold-default(httperf): Out of bounds: client_req(200500) less than lower boundary 1589840
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): Load: 23:46:36 up 6 days, 10:09, 3 users, load average: 0.61, 0.82, 0.74
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): Test name: cold-default
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): Varnish options:
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): -t=3600
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): -s=malloc,10G
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): Varnish parameters:
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): thread_stats_rate=1
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): Payload size (excludes headers): 256
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): Branch: master
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): Number of clients involved: 24
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): Type of test: httperf
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): Test iterations: 2
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): Runtime: 402 seconds
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): Number of total connections: 80000
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): Requests per connection: 10
2012-06-06 21:46:36 [1, 0]: cold-default(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-06 21:46:36 [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-06 21:46:43 [2, 6]: 4gpluss-nogzip(httperf): Starting test
2012-06-06 21:46:46 WARNING [0, 3]: Varnish failed to start. Fallback attempts starting
2012-06-06 21:46:46 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-06 21:46:47 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-06 21:46:47 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-06 21:46:48 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-06 22:08:15 [2,1287]: purge-fail(httperf): Starting test
2012-06-06 22:12:11 WARNING [0,236]: purge-fail(httperf): Panic detected. I think!
2012-06-06 22:12:11 WARNING [0, 0]: purge-fail(httperf):
Last panic at: Wed, 06 Jun 2012 22:11:23 GMT
Assert error in VCL_hash_method(), ../../include/tbl/vcl_returns.h line 39:
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
0x439e15: VCL_hash_method+1a5
0x4164fe: cnt_recv+21e
0x41ae1d: CNT_Session+57d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7fcf549419ca: _end+7fcf542bd202
0x7fcf5469ecdd: _end+7fcf5401a515
sp = 0x7fcf45904920 {
fd = 12, id = 12, xid = 615864373,
client = 10.20.100.7 25422,
step = STP_RECV,
handling = lookup,
restarts = 0, esi_level = 0
ws = 0x7fcf45aa4158 {
id = "req",
{s,f,r,e} = {0x7fcf45aa5730,+96,(nil),+59632},
},
http[req] = {
ws = 0x7fcf45aa4158[req]
"PURGE",
"/foo",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fcf45df5c60 {
ws = 0x7fcf45df5e20 {
id = "wrk",
{s,f,r,e} = {0x7fcf45df5450,0x7fcf45df5450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-06 22:12:11 WARNING [0, 0]: purge-fail(httperf): Varnishstat uptime and measured run-time is too large (measured: 231 stat: 48 diff: 183). Did we crash?
2012-06-06 22:12:12 WARNING [0, 0]: purge-fail(httperf): Out of bounds: client_req(19062) less than lower boundary 290000
2012-06-06 22:12:12 [1, 0]: purge-fail(httperf): Load: 00:12:12 up 6 days, 10:35, 3 users, load average: 0.44, 0.69, 1.03
2012-06-06 22:12:12 [1, 0]: purge-fail(httperf): Test name: purge-fail
2012-06-06 22:12:12 [1, 0]: purge-fail(httperf): Varnish options:
2012-06-06 22:12:12 [1, 0]: purge-fail(httperf): Varnish parameters:
2012-06-06 22:12:12 [1, 0]: purge-fail(httperf): thread_stats_rate=1
2012-06-06 22:12:12 [1, 0]: purge-fail(httperf): Payload size (excludes headers): 1K
2012-06-06 22:12:12 [1, 0]: purge-fail(httperf): Branch: master
2012-06-06 22:12:12 [1, 0]: purge-fail(httperf): Number of clients involved: 24
2012-06-06 22:12:12 [1, 0]: purge-fail(httperf): Type of test: httperf
2012-06-06 22:12:12 [1, 0]: purge-fail(httperf): Test iterations: 1
2012-06-06 22:12:12 [1, 0]: purge-fail(httperf): Runtime: 231 seconds
2012-06-06 22:12:12 [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-06 22:12:12 [1, 0]: purge-fail(httperf): Number of total connections: 300000
2012-06-06 22:12:12 [1, 0]: purge-fail(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-06 22:12:12 [1, 0]: purge-fail(httperf): Requests per connection: 1
2012-06-06 22:12:12 [1, 0]: purge-fail(httperf): Extra options to httperf: --wset=999,0.5 --timeout=5
2012-06-06 22:12:12 [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-06 22:12:18 [2, 6]: streaming-gzip(httperf): Starting test
2012-06-06 22:14:53 WARNING [0,154]: Tests finished with problems detected. Failed expectations: 16 Total run time: 9771 seconds
More information about the varnish-test
mailing list