[Fryer] master FAIL. 9 of 25 tests succeeded.
fryer at oneiros.varnish-software.com
fryer at oneiros.varnish-software.com
Wed Jun 6 00:15:40 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 basic-fryer cold-nogzip siege-test httperf-lru-nostream-default httperf-rapid-expire cold-default purge-fail
Tests OK: streaming memleak 4gpluss-stream 4gpluss 4gpluss-nostream lru-random streaming-grace 4gpluss-nogzip streaming-gzip
2012-06-05 19:32:15 [1,13]: Server pantoum checked out varnish-3.0.0-beta2-1014-ge75cd2e of branch master
2012-06-05 19:32:38 [2,23]: httperf-lru-nostream-gzip(httperf): Starting test
2012-06-05 19:36:44 WARNING [0,245]: httperf-lru-nostream-gzip(httperf): Panic detected. I think!
2012-06-05 19:36:44 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf):
Last panic at: Tue, 05 Jun 2012 19:35: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
0x7f9865e0b9ca: _end+7f9865787202
0x7f9865b68cdd: _end+7f98654e4515
sp = 0x7f985bc11f20 {
fd = 16, id = 16, xid = 2028750955,
client = 10.20.100.8 16605,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f9856257158 {
id = "req",
{s,f,r,e} = {0x7f9856258730,+568,(nil),+59632},
},
http[req] = {
ws = 0x7f9856257158[req]
"GET",
"/1/8/4/8/0/8.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f9855e4ac60 {
ws = 0x7f9855e4ae20 {
id = "wrk",
{s,f,r,e} = {0x7f9855e4a450,0x7f9855e4a450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-05 19:36:44 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 241 stat: 97 diff: 144). Did we crash?
2012-06-05 19:36:44 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-05 19:36:44 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: client_req(53450) less than lower boundary 1989920
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Load: 21:36:44 up 5 days, 7:59, 3 users, load average: 2.58, 2.48, 1.05
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Test name: httperf-lru-nostream-gzip
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish options:
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): -t=3600
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): -s=malloc,30M
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish parameters:
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_stats_rate=1
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_max=5000
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): nuke_limit=250
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): http_gzip_support=on
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_min=200
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Payload size (excludes headers): 10K
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Branch: master
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of clients involved: 24
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Type of test: httperf
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Test iterations: 1
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Runtime: 241 seconds
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of total connections: 200000
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Requests per connection: 10
2012-06-05 19:36:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-05 19:36:44 [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-05 19:36:51 [2, 6]: httperf-lru-nostream-gzip-deflateoff(httperf): Starting test
2012-06-05 19:40:16 WARNING [0,204]: httperf-lru-nostream-gzip-deflateoff(httperf): Panic detected. I think!
2012-06-05 19:40:16 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf):
Last panic at: Tue, 05 Jun 2012 19:39:23 GMT
Assert error in VCL_fetch_method(), ../../include/tbl/vcl_returns.h line 56:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x439875: VCL_fetch_method+1a5
0x4186a9: cnt_fetch+479
0x41accd: CNT_Session+42d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f5f6f0879ca: _end+7f5f6ea03202
0x7f5f6ede4cdd: _end+7f5f6e760515
sp = 0x7f5f6000af20 {
fd = 17, id = 17, xid = 1023144019,
client = 10.20.100.9 15642,
step = STP_FETCH,
handling = fetch,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f5f5f913020 {
ws = 0x7f5f5f913070 {
id = "bo",
{s,f,r,e} = {0x7f5f5f914aa0,+10488,(nil),+58752},
},
do_stream
bodystatus = 4 (length),
},
http[bereq] = {
ws = 0x7f5f5f913070[bo]
"GET",
"/1/7/4/9/8/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.9",
"X-Varnish: 1023144019",
"Accept-Encoding: gzip",
},
http[beresp] = {
ws = 0x7f5f5f913070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Tue, 05 Jun 2012 19:39:23 GMT",
"Content-Type: text/plain",
"Content-Length: 10240",
"Last-Modified: Tue, 05 Jun 2012 19:36:55 GMT",
"Connection: keep-alive",
"Accept-Ranges: bytes",
},
ws = 0x7f5f5f40a158 {
id = "req",
{s,f,r,e} = {0x7f5f5f40b730,+136,(nil),+59632},
},
http[req] = {
ws = 0x7f5f5f40a158[req]
"GET",
"/1/7/4/9/8/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.9",
},
worker = 0x7f5f5f6cac60 {
ws = 0x7f5f5f6cae20 {
id = "wrk",
{s,f,r,e} = {0x7f5f5f6ca450,0x7f5f5f6ca450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-05 19:40:16 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnishstat uptime and measured run-time is too large (measured: 200 stat: 52 diff: 148). Did we crash?
2012-06-05 19:40:16 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-05 19:40:16 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: client_req(59510) less than lower boundary 1989920
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Load: 21:40:16 up 5 days, 8:03, 3 users, load average: 0.75, 1.77, 1.06
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test name: httperf-lru-nostream-gzip-deflateoff
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish options:
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -t=3600
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -s=malloc,30M
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish parameters:
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_stats_rate=1
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_max=5000
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): nuke_limit=250
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): http_gzip_support=on
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_min=200
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Payload size (excludes headers): 10K
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Branch: master
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of clients involved: 24
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Type of test: httperf
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test iterations: 1
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Runtime: 200 seconds
2012-06-05 19:40:16 [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-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of total connections: 200000
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Requests per connection: 10
2012-06-05 19:40:16 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-05 19:40:16 [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-05 19:40:23 [2, 6]: streaming(httperf): Starting test
2012-06-05 19:43:18 [2,174]: httperf-lru-default(httperf): Starting test
2012-06-05 19:47:01 WARNING [0,223]: httperf-lru-default(httperf): Panic detected. I think!
2012-06-05 19:47:01 WARNING [0, 0]: httperf-lru-default(httperf):
Last panic at: Tue, 05 Jun 2012 19:45:38 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
0x7f0efc1b29ca: _end+7f0efbb2e202
0x7f0efbf0fcdd: _end+7f0efb88b515
sp = 0x7f0eed502320 {
fd = 33, id = 33, xid = 1001242949,
client = 10.20.100.8 14484,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f0eebe94158 {
id = "req",
{s,f,r,e} = {0x7f0eebe95730,+168,(nil),+59632},
},
http[req] = {
ws = 0x7f0eebe94158[req]
"GET",
"/1/5/6/7/0/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f0eec096c60 {
ws = 0x7f0eec096e20 {
id = "wrk",
{s,f,r,e} = {0x7f0eec096450,0x7f0eec096450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-05 19:47:01 WARNING [0, 0]: httperf-lru-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 214 stat: 82 diff: 132). Did we crash?
2012-06-05 19:47:02 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-05 19:47:02 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: client_req(172353) less than lower boundary 1989920
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): Load: 21:47:02 up 5 days, 8:09, 3 users, load average: 0.60, 1.03, 0.96
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): Test name: httperf-lru-default
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): Varnish options:
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): -t=3600
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): -s=malloc,30M
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): Varnish parameters:
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): thread_stats_rate=1
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): thread_pool_max=5000
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): nuke_limit=250
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): thread_pool_min=200
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): Payload size (excludes headers): 10K
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): Branch: master
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): Number of clients involved: 24
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): Type of test: httperf
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): Test iterations: 1
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): Runtime: 214 seconds
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): Number of total connections: 200000
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): Requests per connection: 10
2012-06-05 19:47:02 [1, 0]: httperf-lru-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-05 19:47:02 [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-05 19:47:09 [2, 6]: memleak(httperf): Starting test
2012-06-05 19:49:33 [2,143]: 4gpluss-stream(httperf): Starting test
2012-06-05 19:49:35 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-05 19:49:35 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-05 19:49:36 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-05 19:49:36 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-05 19:49:37 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-05 20:09:22 [2,1185]: httperf-lru-stream-default(httperf): Starting test
2012-06-05 20:12:49 WARNING [0,207]: httperf-lru-stream-default(httperf): Panic detected. I think!
2012-06-05 20:12:49 WARNING [0, 0]: httperf-lru-stream-default(httperf):
Last panic at: Tue, 05 Jun 2012 20:11:54 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
0x7fd78135a9ca: _end+7fd780cd6202
0x7fd7810b7cdd: _end+7fd780a33515
sp = 0x7fd772602c20 {
fd = 18, id = 18, xid = 194284409,
client = 10.20.100.9 15511,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fd772386158 {
id = "req",
{s,f,r,e} = {0x7fd772387730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7fd772386158[req]
"GET",
"/1/7/5/0/3/5.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fd771bb6c60 {
ws = 0x7fd771bb6e20 {
id = "wrk",
{s,f,r,e} = {0x7fd771bb6450,0x7fd771bb6450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-05 20:12:49 WARNING [0, 0]: httperf-lru-stream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 203 stat: 54 diff: 149). Did we crash?
2012-06-05 20:12:50 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-05 20:12:50 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: client_req(84780) less than lower boundary 1989920
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): Load: 22:12:50 up 5 days, 8:35, 3 users, load average: 10.34, 4.96, 2.43
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): Test name: httperf-lru-stream-default
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): Varnish options:
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): -t=3600
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): -s=malloc,30M
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): Varnish parameters:
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): thread_stats_rate=1
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_max=5000
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): nuke_limit=250
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_min=200
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): Payload size (excludes headers): 10K
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): Branch: master
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): Number of clients involved: 24
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): Type of test: httperf
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): Test iterations: 1
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): Runtime: 203 seconds
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): Number of total connections: 200000
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): Requests per connection: 10
2012-06-05 20:12:50 [1, 0]: httperf-lru-stream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-05 20:12:50 [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-05 20:12:57 [2, 6]: httperf-hot(httperf): Starting test
2012-06-05 20:14:04 WARNING [0,66]: httperf-hot(httperf): Panic detected. I think!
2012-06-05 20:14:04 WARNING [0, 0]: httperf-hot(httperf):
Last panic at: Tue, 05 Jun 2012 20:13:43 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
0x7f73ea2c89ca: _end+7f73e9c44202
0x7f73ea025cdd: _end+7f73e99a1515
sp = 0x7f73db405820 {
fd = 14, id = 14, xid = 105368243,
client = 10.20.100.8 7900,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f73dbfc2158 {
id = "req",
{s,f,r,e} = {0x7f73dbfc3730,+232,(nil),+59632},
},
http[req] = {
ws = 0x7f73dbfc2158[req]
"GET",
"/3/0/1.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f73db5f5c60 {
ws = 0x7f73db5f5e20 {
id = "wrk",
{s,f,r,e} = {0x7f73db5f5450,0x7f73db5f5450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-05 20:14:04 WARNING [0, 0]: httperf-hot(httperf): Varnishstat uptime and measured run-time is too large (measured: 63 stat: 19 diff: 44). Did we crash?
2012-06-05 20:14:04 WARNING [0, 0]: httperf-hot(httperf): Out of bounds: client_req(27360) less than lower boundary 989840
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): Load: 22:14:04 up 5 days, 8:36, 3 users, load average: 3.83, 4.13, 2.33
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): Test name: httperf-hot
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): Varnish options:
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): Varnish parameters:
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): thread_stats_rate=1
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): Payload size (excludes headers): 256
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): Branch: master
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): Number of clients involved: 24
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): Type of test: httperf
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): Test iterations: 1
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): Runtime: 63 seconds
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): Number of total connections: 100000
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): Requests per connection: 10
2012-06-05 20:14:04 [1, 0]: httperf-hot(httperf): Extra options to httperf: --wset=1000,0.25
2012-06-05 20:14:04 [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-05 20:14:11 [2, 6]: httperf-lru-nostream-nogzip(httperf): Starting test
2012-06-05 20:17:33 WARNING [0,202]: httperf-lru-nostream-nogzip(httperf): Panic detected. I think!
2012-06-05 20:17:33 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf):
Last panic at: Tue, 05 Jun 2012 20:16:41 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
0x7f1c44ddf9ca: _end+7f1c4475b202
0x7f1c44b3ccdd: _end+7f1c444b8515
sp = 0x7f1c3ac84220 {
fd = 22, id = 22, xid = 1032213154,
client = 10.20.100.8 16927,
step = STP_FETCH,
handling = fetch,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f1c35e8a020 {
ws = 0x7f1c35e8a070 {
id = "bo",
{s,f,r,e} = {0x7f1c35e8baa0,+10488,(nil),+58752},
},
do_stream
bodystatus = 4 (length),
},
http[bereq] = {
ws = 0x7f1c35e8a070[bo]
"GET",
"/1/9/1/3/4/0.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
"X-Varnish: 1032213154",
},
http[beresp] = {
ws = 0x7f1c35e8a070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Tue, 05 Jun 2012 20:16:41 GMT",
"Content-Type: text/plain",
"Content-Length: 10240",
"Last-Modified: Tue, 05 Jun 2012 20:14:14 GMT",
"Connection: keep-alive",
"Accept-Ranges: bytes",
},
ws = 0x7f1c35edf158 {
id = "req",
{s,f,r,e} = {0x7f1c35ee0730,+136,(nil),+59632},
},
http[req] = {
ws = 0x7f1c35edf158[req]
"GET",
"/1/9/1/3/4/0.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
worker = 0x7f1c354aac60 {
ws = 0x7f1c354aae20 {
id = "wrk",
{s,f,r,e} = {0x7f1c354aa450,0x7f1c354aa450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-05 20:17:33 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 198 stat: 51 diff: 147). Did we crash?
2012-06-05 20:17:33 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: n_lru_nuked(2977) less than lower boundary 80000
2012-06-05 20:17:33 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: client_req(58440) less than lower boundary 1989920
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Load: 22:17:34 up 5 days, 8:40, 3 users, load average: 0.68, 2.53, 2.06
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test name: httperf-lru-nostream-nogzip
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish options:
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): -t=3600
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): -s=malloc,30M
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish parameters:
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_stats_rate=1
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_max=5000
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): nuke_limit=250
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): http_gzip_support=off
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_min=200
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Payload size (excludes headers): 10K
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Branch: master
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of clients involved: 24
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Type of test: httperf
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test iterations: 1
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Runtime: 198 seconds
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of total connections: 200000
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Requests per connection: 10
2012-06-05 20:17:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-05 20:17:34 [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-05 20:17:40 [2, 6]: cold-gzip(httperf): Starting test
2012-06-05 20:21:14 WARNING [0,213]: cold-gzip(httperf): Panic detected. I think!
2012-06-05 20:21:14 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Tue, 05 Jun 2012 20:20:04 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
0x7ffa9a6e89ca: _end+7ffa9a064202
0x7ffa9a445cdd: _end+7ffa99dc1515
sp = 0x7ffa8b42ad20 {
fd = 21, id = 21, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7ffa8b09a158 {
id = "req",
{s,f,r,e} = {0x7ffa8b09b730,0x7ffa8b09b730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7ffa8b69ec60 {
ws = 0x7ffa8b69ee20 {
id = "wrk",
{s,f,r,e} = {0x7ffa8b69e450,0x7ffa8b69e450,(nil),+2048},
},
},
},
2012-06-05 20:21:14 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 209 stat: 68 diff: 141). Did we crash?
2012-06-05 20:24:48 WARNING [0,214]: cold-gzip(httperf): Panic detected. I think!
2012-06-05 20:24:48 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Tue, 05 Jun 2012 20:21: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
0x7ffa9a6e89ca: _end+7ffa9a064202
0x7ffa9a445cdd: _end+7ffa99dc1515
sp = 0x7ffa868f5c20 {
fd = 41, id = 41, xid = 116031308,
client = 10.20.100.9 2579,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7ffa86e47158 {
id = "req",
{s,f,r,e} = {0x7ffa86e48730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7ffa86e47158[req]
"GET",
"/0/0/8/1/3/6/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7ffa8b8c2c60 {
ws = 0x7ffa8b8c2e20 {
id = "wrk",
{s,f,r,e} = {0x7ffa8b8c2450,0x7ffa8b8c2450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-05 20:24:48 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 423 stat: 170 diff: 253). Did we crash?
2012-06-05 20:24:48 WARNING [0, 0]: cold-gzip(httperf): Out of bounds: client_req(586061) less than lower boundary 1589840
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): Load: 22:24:49 up 5 days, 8:47, 3 users, load average: 0.86, 1.78, 1.97
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): Test name: cold-gzip
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): Varnish options:
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): -t=3600
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): -s=malloc,10G
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): Varnish parameters:
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): thread_stats_rate=1
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): http_gzip_support=on
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): Payload size (excludes headers): 256
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): Branch: master
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): Number of clients involved: 24
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): Type of test: httperf
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): Test iterations: 2
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): Runtime: 423 seconds
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): Number of total connections: 80000
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): Requests per connection: 10
2012-06-05 20:24:49 [1, 0]: cold-gzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-05 20:24:49 [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-05 20:24:55 [2, 6]: 4gpluss(httperf): Starting test
2012-06-05 20:24:58 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-05 20:24:58 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-05 20:24:59 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-05 20:24:59 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-05 20:25:00 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-05 20:46:15 [2,1275]: httperf-lru-stream-gzip(httperf): Starting test
2012-06-05 20:49:34 WARNING [0,198]: httperf-lru-stream-gzip(httperf): Panic detected. I think!
2012-06-05 20:49:34 WARNING [0, 0]: httperf-lru-stream-gzip(httperf):
Last panic at: Tue, 05 Jun 2012 20:48:36 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
0x7fe4a2b8a9ca: _end+7fe4a2506202
0x7fe4a28e7cdd: _end+7fe4a2263515
sp = 0x7fe493206f20 {
fd = 27, id = 27, xid = 1179679296,
client = 10.20.100.8 14080,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fe49360b158 {
id = "req",
{s,f,r,e} = {0x7fe49360c730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7fe49360b158[req]
"GET",
"/1/5/6/7/5/3.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fe492a86c60 {
ws = 0x7fe492a86e20 {
id = "wrk",
{s,f,r,e} = {0x7fe492a86450,0x7fe492a86450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-05 20:49:34 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 193 stat: 57 diff: 136). Did we crash?
2012-06-05 20:49:34 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-05 20:49:34 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: client_req(139963) less than lower boundary 1989920
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): Load: 22:49:34 up 5 days, 9:12, 3 users, load average: 0.62, 0.86, 1.32
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): Test name: httperf-lru-stream-gzip
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish options:
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): -t=3600
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): -s=malloc,30M
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish parameters:
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): thread_stats_rate=1
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_max=5000
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): nuke_limit=250
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): http_gzip_support=on
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_min=200
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): Payload size (excludes headers): 10K
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): Branch: master
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): Number of clients involved: 24
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): Type of test: httperf
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): Test iterations: 1
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): Runtime: 193 seconds
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): Number of total connections: 200000
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): Requests per connection: 10
2012-06-05 20:49:34 [1, 0]: httperf-lru-stream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-05 20:49:34 [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-05 20:49:41 [2, 6]: httperf-lru-stream-nogzip(httperf): Starting test
2012-06-05 20:53:03 WARNING [0,202]: httperf-lru-stream-nogzip(httperf): Panic detected. I think!
2012-06-05 20:53:03 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf):
Last panic at: Tue, 05 Jun 2012 20:52:10 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
0x7fa322f879ca: _end+7fa322903202
0x7fa322ce4cdd: _end+7fa322660515
sp = 0x7fa318cdef20 {
fd = 22, id = 22, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fa31119d158 {
id = "req",
{s,f,r,e} = {0x7fa31119e730,0x7fa31119e730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7fa312faec60 {
ws = 0x7fa312faee20 {
id = "wrk",
{s,f,r,e} = {0x7fa312fae450,0x7fa312fae450,(nil),+2048},
},
},
},
2012-06-05 20:53:03 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 198 stat: 52 diff: 146). Did we crash?
2012-06-05 20:53:04 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: n_lru_nuked(3094) less than lower boundary 80000
2012-06-05 20:53:04 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: client_req(59600) less than lower boundary 1989920
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): Load: 22:53:04 up 5 days, 9:15, 3 users, load average: 0.51, 0.86, 1.24
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): Test name: httperf-lru-stream-nogzip
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish options:
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): -t=3600
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): -s=malloc,30M
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish parameters:
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_stats_rate=1
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_max=5000
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): nuke_limit=250
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): http_gzip_support=off
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_min=200
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): Payload size (excludes headers): 10K
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): Branch: master
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of clients involved: 24
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): Type of test: httperf
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): Test iterations: 1
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): Runtime: 198 seconds
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of total connections: 200000
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): Requests per connection: 10
2012-06-05 20:53:04 [1, 0]: httperf-lru-stream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-05 20:53:04 [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-05 20:53:11 [2, 6]: basic-fryer(httperf): Starting test
2012-06-05 20:53:32 WARNING [0,21]: basic-fryer(httperf): Out of bounds: cache_hit(46) less than lower boundary 47
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): Load: 22:53:32 up 5 days, 9:16, 3 users, load average: 0.31, 0.77, 1.20
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): Test name: basic-fryer
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): Varnish options:
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): Varnish parameters:
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): thread_stats_rate=1
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): Payload size (excludes headers): 256
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): Branch: master
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): Number of clients involved: 24
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): Type of test: httperf
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): Test iterations: 1
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): Runtime: 12 seconds
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): Number of total connections: 50
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): Requests per connection: 1
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): No extra options to httperf
2012-06-05 20:53:32 [1, 0]: basic-fryer(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 1 --num-conns 2 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12
2012-06-05 20:53:39 [2, 6]: cold-nogzip(httperf): Starting test
2012-06-05 20:57:17 WARNING [0,217]: cold-nogzip(httperf): Panic detected. I think!
2012-06-05 20:57:17 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Tue, 05 Jun 2012 20:56:41 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
0x7fd7bc7a69ca: _end+7fd7bc122202
0x7fd7bc503cdd: _end+7fd7bbe7f515
sp = 0x7fd7a3147220 {
fd = 22, id = 22, xid = 773496949,
client = 10.20.100.9 7626,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fd7a3745158 {
id = "req",
{s,f,r,e} = {0x7fd7a3746730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7fd7a3745158[req]
"GET",
"/0/3/5/1/1/2/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fd7ad66ec60 {
ws = 0x7fd7ad66ee20 {
id = "wrk",
{s,f,r,e} = {0x7fd7ad66e450,0x7fd7ad66e450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-05 20:57:17 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 213 stat: 35 diff: 178). Did we crash?
2012-06-05 21:00:26 WARNING [0,188]: cold-nogzip(httperf): Panic detected. I think!
2012-06-05 21:00:26 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Tue, 05 Jun 2012 20:58:49 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
0x7fd7bc7a69ca: _end+7fd7bc122202
0x7fd7bc503cdd: _end+7fd7bbe7f515
sp = 0x7fd7b2519c20 {
fd = 26, id = 26, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fd7accbd158 {
id = "req",
{s,f,r,e} = {0x7fd7accbe730,0x7fd7accbe730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7fd7ae41dc60 {
ws = 0x7fd7ae41de20 {
id = "wrk",
{s,f,r,e} = {0x7fd7ae41d450,0x7fd7ae41d450,(nil),+2048},
},
},
},
2012-06-05 21:00:26 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 402 stat: 96 diff: 306). Did we crash?
2012-06-05 21:00:26 WARNING [0, 0]: cold-nogzip(httperf): Out of bounds: uptime(96) less than lower boundary 100
2012-06-05 21:00:26 WARNING [0, 0]: cold-nogzip(httperf): Out of bounds: client_req(179500) less than lower boundary 1589840
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): Load: 23:00:26 up 5 days, 9:23, 3 users, load average: 0.35, 0.68, 1.01
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): Test name: cold-nogzip
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): Varnish options:
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): -t=3600
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): -s=malloc,10G
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): Varnish parameters:
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): thread_stats_rate=1
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): http_gzip_support=off
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): Payload size (excludes headers): 256
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): Branch: master
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): Number of clients involved: 24
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): Type of test: httperf
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): Test iterations: 2
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): Runtime: 402 seconds
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): Number of total connections: 80000
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): Requests per connection: 10
2012-06-05 21:00:26 [1, 0]: cold-nogzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-05 21:00:26 [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-05 21:00:34 [2, 7]: 4gpluss-nostream(httperf): Starting test
2012-06-05 21:00:37 WARNING [0, 3]: Varnish failed to start. Fallback attempts starting
2012-06-05 21:00:37 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-05 21:00:38 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-05 21:00:38 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-05 21:00:39 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-05 21:20:51 [2,1212]: lru-random(httperf): Starting test
2012-06-05 21:32:24 [2,693]: siege-test(siege): Starting test
2012-06-05 21:32:42 WARNING [0,18]: siege-test(siege): Panic detected. I think!
2012-06-05 21:32:42 WARNING [0, 0]: siege-test(siege):
Last panic at: Tue, 05 Jun 2012 21:32:29 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
0x7f7dbd12e9ca: _end+7f7dbcaaa202
0x7f7dbce8bcdd: _end+7f7dbc807515
sp = 0x7f7db0b04e20 {
fd = 17, id = 17, xid = 869304432,
client = 10.20.100.9 59813,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f7dade35158 {
id = "req",
{s,f,r,e} = {0x7f7dade36730,+152,(nil),+59632},
},
http[req] = {
ws = 0x7f7dade35158[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 = 0x7f7dae3f0c60 {
ws = 0x7f7dae3f0e20 {
id = "wrk",
{s,f,r,e} = {0x7f7dae3f0450,0x7f7dae3f0450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-05 21:32:43 [1, 0]: siege-test(siege): Load: 23:32:43 up 5 days, 9:55, 3 users, load average: 0.67, 0.67, 0.65
2012-06-05 21:32:43 [1, 0]: siege-test(siege): Test name: siege-test
2012-06-05 21:32:43 [1, 0]: siege-test(siege): Varnish options:
2012-06-05 21:32:43 [1, 0]: siege-test(siege): Varnish parameters:
2012-06-05 21:32:43 [1, 0]: siege-test(siege): thread_stats_rate=1
2012-06-05 21:32:43 [1, 0]: siege-test(siege): Payload size (excludes headers): 256
2012-06-05 21:32:43 [1, 0]: siege-test(siege): Branch: master
2012-06-05 21:32:43 [1, 0]: siege-test(siege): Number of clients involved: 0
2012-06-05 21:32:43 [1, 0]: siege-test(siege): Type of test: siege
2012-06-05 21:32:43 [1, 0]: siege-test(siege): Test iterations: 1
2012-06-05 21:32:43 [1, 0]: siege-test(siege): Runtime: 14 seconds
2012-06-05 21:32:43 [1, 0]: siege-test(siege): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-05 21:32:50 [2, 6]: httperf-lru-nostream-default(httperf): Starting test
2012-06-05 21:36:06 WARNING [0,195]: httperf-lru-nostream-default(httperf): Panic detected. I think!
2012-06-05 21:36:06 WARNING [0, 0]: httperf-lru-nostream-default(httperf):
Last panic at: Tue, 05 Jun 2012 21:35:16 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
0x7f5185ab29ca: _end+7f518542e202
0x7f518580fcdd: _end+7f518518b515
sp = 0x7f51765f5520 {
fd = 14, id = 14, xid = 1887591876,
client = 10.20.100.9 14540,
step = STP_HIT,
handling = hash,
restarts = 0, esi_level = 0
ws = 0x7f517688d158 {
id = "req",
{s,f,r,e} = {0x7f517688e730,+864,(nil),+59632},
},
http[req] = {
ws = 0x7f517688d158[req]
"GET",
"/1/5/6/1/4/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.9",
},
worker = 0x7f51757c6c60 {
ws = 0x7f51757c6e20 {
id = "wrk",
{s,f,r,e} = {0x7f51757c6450,0x7f51757c6450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
obj = 0x7f51746df000 {
xid = 1887591789,
ws = 0x7f51746df018 {
id = "obj",
{s,f,r,e} = {0x7f51746df1e8,+216,(nil),+248},
},
http[obj] = {
ws = 0x7f51746df018[obj]
"HTTP/1.1",
"OK",
"Server: nginx/0.7.65",
"Date: Tue, 05 Jun 2012 21:35:16 GMT",
"Content-Type: text/plain",
"Last-Modified: Tue, 05 Jun 2012 21:32:53 GMT",
"Content-Encoding: gzip",
"Content-Length: 249",
},
len = 249,
store = {
249 {
1f 8b 08 00 00 00 00 00 00 03 ed d0 c1 6d 04 21 |.............m.!|
0c 05 d0 94 f2 0b 58 6d 15 7b cc 35 05 38 60 ad |......Xm.{.5.8`.|
2c 61 98 05 b3 4a f9 f1 64 aa 88 f4 39 81 c0 1f |,a...J..d...9...|
fb 7d 8e a9 0e 3b d6 76 d4 d1 c6 c4 b2 80 b8 c6 |.}...;.v........|
[185 more]
},
},
},
},
2012-06-05 21:36:06 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 192 stat: 49 diff: 143). Did we crash?
2012-06-05 21:36:06 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-05 21:36:06 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: client_req(97410) less than lower boundary 1989920
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): Load: 23:36:06 up 5 days, 9:59, 3 users, load average: 0.87, 0.90, 0.75
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): Test name: httperf-lru-nostream-default
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): Varnish options:
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): -t=3600
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): -s=malloc,30M
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): Varnish parameters:
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): thread_stats_rate=1
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_max=5000
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): nuke_limit=250
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_min=200
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): Payload size (excludes headers): 10K
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): Branch: master
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): Number of clients involved: 24
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): Type of test: httperf
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): Test iterations: 1
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): Runtime: 192 seconds
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): Number of total connections: 200000
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): Requests per connection: 10
2012-06-05 21:36:06 [1, 0]: httperf-lru-nostream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-05 21:36:06 [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-05 21:36:13 [2, 6]: httperf-rapid-expire(httperf): Starting test
2012-06-05 21:37:25 WARNING [0,71]: httperf-rapid-expire(httperf): Panic detected. I think!
2012-06-05 21:37:25 WARNING [0, 0]: httperf-rapid-expire(httperf):
Last panic at: Tue, 05 Jun 2012 21:36:49 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
0x7f3b8958f9ca: _end+7f3b88f0b202
0x7f3b892eccdd: _end+7f3b88c68515
sp = 0x7f3b79f04120 {
fd = 23, id = 23, xid = 1596354623,
client = 10.20.100.9 7323,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f3b7a1db158 {
id = "req",
{s,f,r,e} = {0x7f3b7a1dc730,+224,(nil),+59632},
},
http[req] = {
ws = 0x7f3b7a1db158[req]
"GET",
"/5/8.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f3b7a59ec60 {
ws = 0x7f3b7a59ee20 {
id = "wrk",
{s,f,r,e} = {0x7f3b7a59e450,0x7f3b7a59e450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-05 21:37:25 WARNING [0, 0]: httperf-rapid-expire(httperf): Varnishstat uptime and measured run-time is too large (measured: 68 stat: 35 diff: 33). Did we crash?
2012-06-05 21:37:25 WARNING [0, 0]: httperf-rapid-expire(httperf): Out of bounds: client_req(117580) less than lower boundary 989840
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): Load: 23:37:25 up 5 days, 10:00, 3 users, load average: 0.82, 0.90, 0.76
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): Test name: httperf-rapid-expire
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): Varnish options:
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): -t=2
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): Varnish parameters:
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): thread_stats_rate=1
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): Payload size (excludes headers): 256
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): Branch: master
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): Number of clients involved: 24
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): Type of test: httperf
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): Test iterations: 1
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): Runtime: 68 seconds
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): Number of total connections: 100000
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): Requests per connection: 10
2012-06-05 21:37:25 [1, 0]: httperf-rapid-expire(httperf): Extra options to httperf: --wset=100,0.30
2012-06-05 21:37:25 [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-05 21:37:32 [2, 6]: streaming-grace(httperf): Starting test
2012-06-05 21:40:26 [2,173]: cold-default(httperf): Starting test
2012-06-05 21:43:52 WARNING [0,205]: cold-default(httperf): Panic detected. I think!
2012-06-05 21:43:52 WARNING [0, 0]: cold-default(httperf):
Last panic at: Tue, 05 Jun 2012 21:42:10 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
0x7f9c5b7d59ca: _end+7f9c5b151202
0x7f9c5b532cdd: _end+7f9c5aeae515
sp = 0x7f9c39abd220 {
fd = 30, id = 30, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f9c4a402158 {
id = "req",
{s,f,r,e} = {0x7f9c4a403730,0x7f9c4a403730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f9c4d474c60 {
ws = 0x7f9c4d474e20 {
id = "wrk",
{s,f,r,e} = {0x7f9c4d474450,0x7f9c4d474450,(nil),+2048},
},
},
},
2012-06-05 21:43:52 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 201 stat: 100 diff: 101). Did we crash?
2012-06-05 21:47:20 WARNING [0,208]: cold-default(httperf): Panic detected. I think!
2012-06-05 21:47:20 WARNING [0, 0]: cold-default(httperf):
Last panic at: Tue, 05 Jun 2012 21:44:57 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
0x7f9c5b7d59ca: _end+7f9c5b151202
0x7f9c5b532cdd: _end+7f9c5aeae515
sp = 0x7f9c4e40c320 {
fd = 38, id = 38, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f9c4c110158 {
id = "req",
{s,f,r,e} = {0x7f9c4c111730,0x7f9c4c111730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f9c4d93dc60 {
ws = 0x7f9c4d93de20 {
id = "wrk",
{s,f,r,e} = {0x7f9c4d93d450,0x7f9c4d93d450,(nil),+2048},
},
},
},
2012-06-05 21:47:20 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 410 stat: 142 diff: 268). Did we crash?
2012-06-05 21:47:20 WARNING [0, 0]: cold-default(httperf): Out of bounds: client_req(364030) less than lower boundary 1589840
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): Load: 23:47:20 up 5 days, 10:10, 3 users, load average: 0.55, 0.73, 0.71
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): Test name: cold-default
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): Varnish options:
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): -t=3600
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): -s=malloc,10G
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): Varnish parameters:
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): thread_stats_rate=1
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): Payload size (excludes headers): 256
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): Branch: master
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): Number of clients involved: 24
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): Type of test: httperf
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): Test iterations: 2
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): Runtime: 410 seconds
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): Number of total connections: 80000
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): Requests per connection: 10
2012-06-05 21:47:20 [1, 0]: cold-default(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-05 21:47:20 [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-05 21:47:27 [2, 7]: 4gpluss-nogzip(httperf): Starting test
2012-06-05 21:47:30 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-05 21:47:30 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-05 21:47:31 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-05 21:47:31 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-05 21:47:32 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-05 22:08:44 [2,1271]: purge-fail(httperf): Starting test
2012-06-05 22:12:58 WARNING [0,254]: purge-fail(httperf): Panic detected. I think!
2012-06-05 22:12:58 WARNING [0, 0]: purge-fail(httperf):
Last panic at: Tue, 05 Jun 2012 22:12:20 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
0x7fa1564f99ca: _end+7fa155e75202
0x7fa156256cdd: _end+7fa155bd2515
sp = 0x7fa155e20620 {
fd = 12, id = 12, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fa146e02158 {
id = "req",
{s,f,r,e} = {0x7fa146e03730,0x7fa146e03730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7fa14773dc60 {
ws = 0x7fa14773de20 {
id = "wrk",
{s,f,r,e} = {0x7fa14773d450,0x7fa14773d450,(nil),+2048},
},
},
},
2012-06-05 22:12:58 WARNING [0, 0]: purge-fail(httperf): Varnishstat uptime and measured run-time is too large (measured: 250 stat: 37 diff: 213). Did we crash?
2012-06-05 22:12:59 WARNING [0, 0]: purge-fail(httperf): Out of bounds: client_req(12051) less than lower boundary 290000
2012-06-05 22:12:59 [1, 0]: purge-fail(httperf): Load: 00:12:59 up 5 days, 10:35, 3 users, load average: 0.47, 0.60, 0.93
2012-06-05 22:12:59 [1, 0]: purge-fail(httperf): Test name: purge-fail
2012-06-05 22:12:59 [1, 0]: purge-fail(httperf): Varnish options:
2012-06-05 22:12:59 [1, 0]: purge-fail(httperf): Varnish parameters:
2012-06-05 22:12:59 [1, 0]: purge-fail(httperf): thread_stats_rate=1
2012-06-05 22:12:59 [1, 0]: purge-fail(httperf): Payload size (excludes headers): 1K
2012-06-05 22:12:59 [1, 0]: purge-fail(httperf): Branch: master
2012-06-05 22:12:59 [1, 0]: purge-fail(httperf): Number of clients involved: 24
2012-06-05 22:12:59 [1, 0]: purge-fail(httperf): Type of test: httperf
2012-06-05 22:12:59 [1, 0]: purge-fail(httperf): Test iterations: 1
2012-06-05 22:12:59 [1, 0]: purge-fail(httperf): Runtime: 250 seconds
2012-06-05 22:12:59 [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-05 22:12:59 [1, 0]: purge-fail(httperf): Number of total connections: 300000
2012-06-05 22:12:59 [1, 0]: purge-fail(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-05 22:12:59 [1, 0]: purge-fail(httperf): Requests per connection: 1
2012-06-05 22:12:59 [1, 0]: purge-fail(httperf): Extra options to httperf: --wset=999,0.5 --timeout=5
2012-06-05 22:12:59 [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-05 22:13:06 [2, 6]: streaming-gzip(httperf): Starting test
2012-06-05 22:15:40 WARNING [0,153]: Tests finished with problems detected. Failed expectations: 16 Total run time: 9819 seconds
More information about the varnish-test
mailing list