[Fryer] master FAIL. 10 of 25 tests succeeded.
fryer at oneiros.varnish-software.com
fryer at oneiros.varnish-software.com
Tue Jun 5 00:11:51 CEST 2012
Tests Failed: httperf-lru-nostream-gzip httperf-lru-nostream-gzip-deflateoff httperf-lru-default httperf-lru-stream-default httperf-hot httperf-lru-nostream-nogzip cold-gzip httperf-lru-stream-gzip httperf-lru-stream-nogzip cold-nogzip siege-test httperf-lru-nostream-default httperf-rapid-expire cold-default purge-fail
Tests OK: streaming memleak 4gpluss-stream 4gpluss basic-fryer 4gpluss-nostream lru-random streaming-grace 4gpluss-nogzip streaming-gzip
2012-06-04 19:32:14 [1,13]: Server pantoum checked out varnish-3.0.0-beta2-1014-ge75cd2e of branch master
2012-06-04 19:32:39 [2,24]: httperf-lru-nostream-gzip(httperf): Starting test
2012-06-04 19:35:42 WARNING [0,182]: httperf-lru-nostream-gzip(httperf): Panic detected. I think!
2012-06-04 19:35:42 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf):
Last panic at: Mon, 04 Jun 2012 19:35: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
0x7f62d40f29ca: _end+7f62d3a6e202
0x7f62d3e4fcdd: _end+7f62d37cb515
sp = 0x7f62c5f22320 {
fd = 22, id = 22, xid = 191163343,
client = 10.20.100.9 17157,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f62c5013158 {
id = "req",
{s,f,r,e} = {0x7f62c5014730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7f62c5013158[req]
"GET",
"/1/8/5/8/6/0.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f62c48d6c60 {
ws = 0x7f62c48d6e20 {
id = "wrk",
{s,f,r,e} = {0x7f62c48d6450,0x7f62c48d6450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 19:35:42 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 179 stat: 24 diff: 155). Did we crash?
2012-06-04 19:35:43 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-04 19:35:43 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: client_req(10650) less than lower boundary 1999720
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): Load: 21:35:43 up 4 days, 7:58, 3 users, load average: 0.82, 0.48, 0.19
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): Test name: httperf-lru-nostream-gzip
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish options:
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): -t=3600
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): -s=malloc,30M
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish parameters:
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_stats_rate=1
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_max=5000
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): nuke_limit=250
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): http_gzip_support=on
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_min=200
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): Payload size (excludes headers): 10K
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): Branch: master
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of clients involved: 24
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): Type of test: httperf
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): Test iterations: 1
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): Runtime: 179 seconds
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of total connections: 200000
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): Requests per connection: 10
2012-06-04 19:35:43 [1, 0]: httperf-lru-nostream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-04 19:35:43 [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-04 19:35:50 [2, 6]: httperf-lru-nostream-gzip-deflateoff(httperf): Starting test
2012-06-04 19:39:01 WARNING [0,191]: httperf-lru-nostream-gzip-deflateoff(httperf): Panic detected. I think!
2012-06-04 19:39:01 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf):
Last panic at: Mon, 04 Jun 2012 19:38:16 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
0x7ffc93c159ca: _end+7ffc93591202
0x7ffc93972cdd: _end+7ffc932ee515
sp = 0x7ffc84a03b20 {
fd = 20, id = 20, xid = 1382455332,
client = 10.20.100.8 15175,
step = STP_FETCH,
handling = fetch,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7ffc84d79020 {
ws = 0x7ffc84d79070 {
id = "bo",
{s,f,r,e} = {0x7ffc84d7aaa0,+10488,(nil),+58752},
},
do_stream
bodystatus = 4 (length),
},
http[bereq] = {
ws = 0x7ffc84d79070[bo]
"GET",
"/1/7/0/1/4/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
"X-Varnish: 1382455332",
"Accept-Encoding: gzip",
},
http[beresp] = {
ws = 0x7ffc84d79070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Mon, 04 Jun 2012 19:38:16 GMT",
"Content-Type: text/plain",
"Content-Length: 10240",
"Last-Modified: Mon, 04 Jun 2012 19:35:53 GMT",
"Connection: keep-alive",
"Accept-Ranges: bytes",
},
ws = 0x7ffc8488a158 {
id = "req",
{s,f,r,e} = {0x7ffc8488b730,+136,(nil),+59632},
},
http[req] = {
ws = 0x7ffc8488a158[req]
"GET",
"/1/7/0/1/4/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
worker = 0x7ffc83fdec60 {
ws = 0x7ffc83fdee20 {
id = "wrk",
{s,f,r,e} = {0x7ffc83fde450,0x7ffc83fde450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 19:39:01 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnishstat uptime and measured run-time is too large (measured: 187 stat: 44 diff: 143). Did we crash?
2012-06-04 19:39:02 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-04 19:39:02 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: client_req(45250) less than lower boundary 1999720
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Load: 21:39:02 up 4 days, 8:01, 3 users, load average: 1.60, 1.37, 0.61
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test name: httperf-lru-nostream-gzip-deflateoff
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish options:
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -t=3600
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -s=malloc,30M
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish parameters:
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_stats_rate=1
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_max=5000
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): nuke_limit=250
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): http_gzip_support=on
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_min=200
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Payload size (excludes headers): 10K
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Branch: master
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of clients involved: 24
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Type of test: httperf
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test iterations: 1
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Runtime: 187 seconds
2012-06-04 19:39:02 [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-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of total connections: 200000
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Requests per connection: 10
2012-06-04 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-04 19:39:02 [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-04 19:39:09 [2, 6]: streaming(httperf): Starting test
2012-06-04 19:42:03 [2,173]: httperf-lru-default(httperf): Starting test
2012-06-04 19:45:36 WARNING [0,213]: httperf-lru-default(httperf): Panic detected. I think!
2012-06-04 19:45:36 WARNING [0, 0]: httperf-lru-default(httperf):
Last panic at: Mon, 04 Jun 2012 19:45:09 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
0x7fe5375689ca: _end+7fe536ee4202
0x7fe5372c5cdd: _end+7fe536c41515
sp = 0x7fe526263420 {
fd = 19, id = 19, xid = 604099404,
client = 10.20.100.8 17344,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fe527190158 {
id = "req",
{s,f,r,e} = {0x7fe527191730,+568,(nil),+59632},
},
http[req] = {
ws = 0x7fe527190158[req]
"GET",
"/1/9/4/8/7/1.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fe5272eac60 {
ws = 0x7fe5272eae20 {
id = "wrk",
{s,f,r,e} = {0x7fe5272ea450,0x7fe5272ea450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 19:45:36 WARNING [0, 0]: httperf-lru-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 209 stat: 26 diff: 183). Did we crash?
2012-06-04 19:45:36 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-04 19:45:36 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: client_req(20580) less than lower boundary 1999720
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): Load: 21:45:36 up 4 days, 8:08, 3 users, load average: 0.54, 0.93, 0.64
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): Test name: httperf-lru-default
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): Varnish options:
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): -t=3600
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): -s=malloc,30M
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): Varnish parameters:
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): thread_stats_rate=1
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): thread_pool_max=5000
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): nuke_limit=250
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): thread_pool_min=200
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): Payload size (excludes headers): 10K
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): Branch: master
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): Number of clients involved: 24
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): Type of test: httperf
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): Test iterations: 1
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): Runtime: 209 seconds
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): Number of total connections: 200000
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): Requests per connection: 10
2012-06-04 19:45:36 [1, 0]: httperf-lru-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-04 19:45:36 [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-04 19:45:43 [2, 6]: memleak(httperf): Starting test
2012-06-04 19:48:02 [2,138]: 4gpluss-stream(httperf): Starting test
2012-06-04 19:48:05 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-04 19:48:05 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-04 19:48:05 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-04 19:48:06 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-04 19:48:06 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-04 20:08:15 [2,1208]: httperf-lru-stream-default(httperf): Starting test
2012-06-04 20:11:37 WARNING [0,202]: httperf-lru-stream-default(httperf): Panic detected. I think!
2012-06-04 20:11:37 WARNING [0, 0]: httperf-lru-stream-default(httperf):
Last panic at: Mon, 04 Jun 2012 20:10:25 GMT
Assert error in VCL_recv_method(), ../../include/tbl/vcl_returns.h line 27:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x43a3b5: VCL_recv_method+1a5
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f56e24749ca: _end+7f56e1df0202
0x7f56e21d1cdd: _end+7f56e1b4d515
sp = 0x7f56d350dd20 {
fd = 32, id = 32, xid = 954521971,
client = 10.20.100.8 12759,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f56d2324158 {
id = "req",
{s,f,r,e} = {0x7f56d2325730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7f56d2324158[req]
"GET",
"/1/4/1/7/0/6.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f56d251ac60 {
ws = 0x7f56d251ae20 {
id = "wrk",
{s,f,r,e} = {0x7f56d251a450,0x7f56d251a450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 20:11:37 WARNING [0, 0]: httperf-lru-stream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 198 stat: 72 diff: 126). Did we crash?
2012-06-04 20:11:38 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-04 20:11:38 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: client_req(201760) less than lower boundary 1999720
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): Load: 22:11:38 up 4 days, 8:34, 3 users, load average: 0.62, 0.68, 0.77
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): Test name: httperf-lru-stream-default
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): Varnish options:
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): -t=3600
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): -s=malloc,30M
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): Varnish parameters:
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): thread_stats_rate=1
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_max=5000
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): nuke_limit=250
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_min=200
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): Payload size (excludes headers): 10K
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): Branch: master
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): Number of clients involved: 24
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): Type of test: httperf
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): Test iterations: 1
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): Runtime: 198 seconds
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): Number of total connections: 200000
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): Requests per connection: 10
2012-06-04 20:11:38 [1, 0]: httperf-lru-stream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-04 20:11:38 [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-04 20:11:45 [2, 6]: httperf-hot(httperf): Starting test
2012-06-04 20:12:50 WARNING [0,65]: httperf-hot(httperf): Panic detected. I think!
2012-06-04 20:12:50 WARNING [0, 0]: httperf-hot(httperf):
Last panic at: Mon, 04 Jun 2012 20:12:25 GMT
Assert error in VCL_recv_method(), ../../include/tbl/vcl_returns.h line 27:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x43a3b5: VCL_recv_method+1a5
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f37475a49ca: _end+7f3746f20202
0x7f3747301cdd: _end+7f3746c7d515
sp = 0x7f3746f22420 {
fd = 13, id = 13, xid = 202400003,
client = 10.20.100.9 5396,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f373ca98158 {
id = "req",
{s,f,r,e} = {0x7f373ca99730,+528,(nil),+59632},
},
http[req] = {
ws = 0x7f373ca98158[req]
"GET",
"/1/1/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f37389b4c60 {
ws = 0x7f37389b4e20 {
id = "wrk",
{s,f,r,e} = {0x7f37389b4450,0x7f37389b4450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 20:12:50 WARNING [0, 0]: httperf-hot(httperf): Varnishstat uptime and measured run-time is too large (measured: 62 stat: 24 diff: 38). Did we crash?
2012-06-04 20:12:51 WARNING [0, 0]: httperf-hot(httperf): Out of bounds: client_req(44340) less than lower boundary 999640
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): Load: 22:12:51 up 4 days, 8:35, 3 users, load average: 0.68, 0.69, 0.77
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): Test name: httperf-hot
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): Varnish options:
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): Varnish parameters:
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): thread_stats_rate=1
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): Payload size (excludes headers): 256
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): Branch: master
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): Number of clients involved: 24
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): Type of test: httperf
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): Test iterations: 1
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): Runtime: 62 seconds
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): Number of total connections: 100000
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): Requests per connection: 10
2012-06-04 20:12:51 [1, 0]: httperf-hot(httperf): Extra options to httperf: --wset=1000,0.25
2012-06-04 20:12:51 [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-04 20:12:58 [2, 6]: httperf-lru-nostream-nogzip(httperf): Starting test
2012-06-04 20:16:06 WARNING [0,188]: httperf-lru-nostream-nogzip(httperf): Panic detected. I think!
2012-06-04 20:16:06 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf):
Last panic at: Mon, 04 Jun 2012 20:15:07 GMT
Assert error in VCL_recv_method(), ../../include/tbl/vcl_returns.h line 27:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x43a3b5: VCL_recv_method+1a5
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7fe1e47ae9ca: _end+7fe1e412a202
0x7fe1e450bcdd: _end+7fe1e3e87515
sp = 0x7fe1d651e820 {
fd = 18, id = 18, xid = 774972712,
client = 10.20.100.8 14866,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fe1d5551158 {
id = "req",
{s,f,r,e} = {0x7fe1d5552730,+328,(nil),+59632},
},
http[req] = {
ws = 0x7fe1d5551158[req]
"GET",
"/1/6/7/7/0/0.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fe1d46fec60 {
ws = 0x7fe1d46fee20 {
id = "wrk",
{s,f,r,e} = {0x7fe1d46fe450,0x7fe1d46fe450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 20:16:06 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 184 stat: 58 diff: 126). Did we crash?
2012-06-04 20:16:06 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: n_lru_nuked(6004) less than lower boundary 80000
2012-06-04 20:16:06 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: client_req(88721) less than lower boundary 1999720
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): Load: 22:16:06 up 4 days, 8:39, 3 users, load average: 2.28, 2.07, 1.31
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test name: httperf-lru-nostream-nogzip
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish options:
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): -t=3600
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): -s=malloc,30M
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish parameters:
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_stats_rate=1
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_max=5000
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): nuke_limit=250
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): http_gzip_support=off
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_min=200
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): Payload size (excludes headers): 10K
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): Branch: master
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of clients involved: 24
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): Type of test: httperf
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test iterations: 1
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): Runtime: 184 seconds
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of total connections: 200000
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): Requests per connection: 10
2012-06-04 20:16:06 [1, 0]: httperf-lru-nostream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-04 20:16:06 [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-04 20:16:13 [2, 6]: cold-gzip(httperf): Starting test
2012-06-04 20:19:08 WARNING [0,175]: cold-gzip(httperf): Panic detected. I think!
2012-06-04 20:19:08 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Mon, 04 Jun 2012 20:18: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
0x7f6c6a6d59ca: _end+7f6c6a051202
0x7f6c6a432cdd: _end+7f6c69dae515
sp = 0x7f6c56287e20 {
fd = 17, id = 17, xid = 1801097927,
client = 10.20.100.8 7435,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f6c57bb0158 {
id = "req",
{s,f,r,e} = {0x7f6c57bb1730,+88,(nil),+59632},
},
http[req] = {
ws = 0x7f6c57bb0158[req]
"GET",
"/0/3/6/9/3/9/6.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f6c5bb0cc60 {
ws = 0x7f6c5bb0ce20 {
id = "wrk",
{s,f,r,e} = {0x7f6c5bb0c450,0x7f6c5bb0c450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 20:19:08 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 171 stat: 50 diff: 121). Did we crash?
2012-06-04 20:22:40 WARNING [0,211]: cold-gzip(httperf): Panic detected. I think!
2012-06-04 20:22:40 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Mon, 04 Jun 2012 20:20:35 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
0x7f6c6a6d59ca: _end+7f6c6a051202
0x7f6c6a432cdd: _end+7f6c69dae515
sp = 0x7f6c57993120 {
fd = 22, id = 22, xid = 174593073,
client = 10.20.100.9 4870,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f6c5b686158 {
id = "req",
{s,f,r,e} = {0x7f6c5b687730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f6c5b686158[req]
"GET",
"/0/2/1/8/4/0/6.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f6c5b19dc60 {
ws = 0x7f6c5b19de20 {
id = "wrk",
{s,f,r,e} = {0x7f6c5b19d450,0x7f6c5b19d450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 20:22:40 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 383 stat: 124 diff: 259). Did we crash?
2012-06-04 20:22:40 WARNING [0, 0]: cold-gzip(httperf): Out of bounds: client_req(267150) less than lower boundary 1599640
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): Load: 22:22:40 up 4 days, 8:45, 3 users, load average: 0.39, 1.03, 1.09
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): Test name: cold-gzip
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): Varnish options:
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): -t=3600
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): -s=malloc,10G
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): Varnish parameters:
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): thread_stats_rate=1
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): http_gzip_support=on
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): Payload size (excludes headers): 256
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): Branch: master
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): Number of clients involved: 24
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): Type of test: httperf
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): Test iterations: 2
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): Runtime: 383 seconds
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): Number of total connections: 80000
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): Requests per connection: 10
2012-06-04 20:22:40 [1, 0]: cold-gzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-04 20:22:40 [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-04 20:22:47 [2, 6]: 4gpluss(httperf): Starting test
2012-06-04 20:22:50 WARNING [0, 3]: Varnish failed to start. Fallback attempts starting
2012-06-04 20:22:50 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-04 20:22:51 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-04 20:22:51 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-04 20:22:52 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-04 20:44:01 [2,1269]: httperf-lru-stream-gzip(httperf): Starting test
2012-06-04 20:47:23 WARNING [0,201]: httperf-lru-stream-gzip(httperf): Panic detected. I think!
2012-06-04 20:47:23 WARNING [0, 0]: httperf-lru-stream-gzip(httperf):
Last panic at: Mon, 04 Jun 2012 20:46: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
0x7f5ae1b199ca: _end+7f5ae1495202
0x7f5ae1876cdd: _end+7f5ae11f2515
sp = 0x7f5ad2535c20 {
fd = 26, id = 26, xid = 110852842,
client = 10.20.100.8 17297,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f5ad2679158 {
id = "req",
{s,f,r,e} = {0x7f5ad267a730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7f5ad2679158[req]
"GET",
"/1/9/6/6/4/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f5ad1f2ec60 {
ws = 0x7f5ad1f2ee20 {
id = "wrk",
{s,f,r,e} = {0x7f5ad1f2e450,0x7f5ad1f2e450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 20:47:23 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 197 stat: 53 diff: 144). Did we crash?
2012-06-04 20:47:23 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-04 20:47:23 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: client_req(77270) less than lower boundary 1999720
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): Load: 22:47:23 up 4 days, 9:10, 3 users, load average: 0.99, 0.88, 1.11
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): Test name: httperf-lru-stream-gzip
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish options:
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): -t=3600
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): -s=malloc,30M
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish parameters:
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): thread_stats_rate=1
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_max=5000
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): nuke_limit=250
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): http_gzip_support=on
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_min=200
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): Payload size (excludes headers): 10K
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): Branch: master
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): Number of clients involved: 24
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): Type of test: httperf
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): Test iterations: 1
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): Runtime: 197 seconds
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): Number of total connections: 200000
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): Requests per connection: 10
2012-06-04 20:47:23 [1, 0]: httperf-lru-stream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-04 20:47:23 [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-04 20:47:31 [2, 7]: httperf-lru-stream-nogzip(httperf): Starting test
2012-06-04 20:50:45 WARNING [0,194]: httperf-lru-stream-nogzip(httperf): Panic detected. I think!
2012-06-04 20:50:45 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf):
Last panic at: Mon, 04 Jun 2012 20:50:05 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
0x7f34e0b709ca: _end+7f34e04ec202
0x7f34e08cdcdd: _end+7f34e0249515
sp = 0x7f34d5e04220 {
fd = 21, id = 21, xid = 755309101,
client = 10.20.100.9 15435,
step = STP_FETCH,
handling = fetch,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f34d16ce020 {
ws = 0x7f34d16ce070 {
id = "bo",
{s,f,r,e} = {0x7f34d16cfaa0,+248,(nil),+58752},
},
do_stream
bodystatus = 4 (length),
},
http[bereq] = {
ws = 0x7f34d16ce070[bo]
"GET",
"/1/7/3/7/1/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: 755309101",
},
http[beresp] = {
ws = 0x7f34d16ce070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Mon, 04 Jun 2012 20:50:05 GMT",
"Content-Type: text/plain",
"Content-Length: 10240",
"Last-Modified: Mon, 04 Jun 2012 20:47:34 GMT",
"Connection: keep-alive",
"Accept-Ranges: bytes",
},
ws = 0x7f34cf5d6158 {
id = "req",
{s,f,r,e} = {0x7f34cf5d7730,+136,(nil),+59632},
},
http[req] = {
ws = 0x7f34cf5d6158[req]
"GET",
"/1/7/3/7/1/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.9",
},
worker = 0x7f34d18a1c60 {
ws = 0x7f34d18a1e20 {
id = "wrk",
{s,f,r,e} = {0x7f34d18a1450,0x7f34d18a1450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 20:50:45 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 190 stat: 39 diff: 151). Did we crash?
2012-06-04 20:50:45 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: n_lru_nuked(3267) less than lower boundary 80000
2012-06-04 20:50:45 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: client_req(61360) less than lower boundary 1999720
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Load: 22:50:45 up 4 days, 9:13, 3 users, load average: 0.48, 0.80, 1.03
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Test name: httperf-lru-stream-nogzip
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish options:
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): -t=3600
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): -s=malloc,30M
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish parameters:
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_stats_rate=1
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_max=5000
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): nuke_limit=250
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): http_gzip_support=off
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_min=200
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Payload size (excludes headers): 10K
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Branch: master
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of clients involved: 24
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Type of test: httperf
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Test iterations: 1
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Runtime: 190 seconds
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of total connections: 200000
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Requests per connection: 10
2012-06-04 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-04 20:50:45 [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-04 20:50:52 [2, 6]: basic-fryer(httperf): Starting test
2012-06-04 20:51:14 [2,22]: cold-nogzip(httperf): Starting test
2012-06-04 20:54:31 WARNING [0,197]: cold-nogzip(httperf): Panic detected. I think!
2012-06-04 20:54:31 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Mon, 04 Jun 2012 20:53:12 GMT
Assert error in VCL_recv_method(), ../../include/tbl/vcl_returns.h line 27:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x43a3b5: VCL_recv_method+1a5
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f7521c629ca: _end+7f75215de202
0x7f75219bfcdd: _end+7f752133b515
sp = 0x7f750e2c1420 {
fd = 33, id = 33, xid = 1299429395,
client = 10.20.100.8 6236,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f7511f7b158 {
id = "req",
{s,f,r,e} = {0x7f7511f7c730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f7511f7b158[req]
"GET",
"/0/2/8/1/7/6/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f7512f8cc60 {
ws = 0x7f7512f8ce20 {
id = "wrk",
{s,f,r,e} = {0x7f7512f8c450,0x7f7512f8c450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 20:54:31 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 193 stat: 78 diff: 115). Did we crash?
2012-06-04 20:58:06 WARNING [0,214]: cold-nogzip(httperf): Panic detected. I think!
2012-06-04 20:58:06 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Mon, 04 Jun 2012 20:56:11 GMT
Assert error in cnt_first(), cache/cache_center.c line 943:
Condition(req->sp == sp) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x41b021: CNT_Session+781
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f7521c629ca: _end+7f75215de202
0x7f75219bfcdd: _end+7f752133b515
sp = 0x7f7512bc8220 {
fd = 23, id = 23, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f750b3e9158 {
id = "req",
{s,f,r,e} = {0x7f750b3ea730,0x7f750b3ea730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f7512f25c60 {
ws = 0x7f7512f25e20 {
id = "wrk",
{s,f,r,e} = {0x7f7512f25450,0x7f7512f25450,(nil),+2048},
},
},
},
2012-06-04 20:58:06 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 408 stat: 114 diff: 294). Did we crash?
2012-06-04 20:58:06 WARNING [0, 0]: cold-nogzip(httperf): Out of bounds: client_req(287602) less than lower boundary 1599640
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): Load: 22:58:07 up 4 days, 9:21, 3 users, load average: 0.64, 0.78, 0.93
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): Test name: cold-nogzip
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): Varnish options:
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): -t=3600
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): -s=malloc,10G
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): Varnish parameters:
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): thread_stats_rate=1
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): http_gzip_support=off
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): Payload size (excludes headers): 256
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): Branch: master
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): Number of clients involved: 24
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): Type of test: httperf
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): Test iterations: 2
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): Runtime: 408 seconds
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): Number of total connections: 80000
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): Requests per connection: 10
2012-06-04 20:58:07 [1, 0]: cold-nogzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-04 20:58:07 [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-04 20:58:13 [2, 6]: 4gpluss-nostream(httperf): Starting test
2012-06-04 20:58:16 WARNING [0, 3]: Varnish failed to start. Fallback attempts starting
2012-06-04 20:58:16 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-04 20:58:17 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-04 20:58:17 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-04 20:58:18 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-04 21:18:21 [2,1202]: lru-random(httperf): Starting test
2012-06-04 21:29:55 [2,694]: siege-test(siege): Starting test
2012-06-04 21:30:19 WARNING [0,24]: siege-test(siege): Panic detected. I think!
2012-06-04 21:30:19 WARNING [0, 0]: siege-test(siege):
Last panic at: Mon, 04 Jun 2012 21:30:01 GMT
Assert error in VCL_deliver_method(), ../../include/tbl/vcl_returns.h line 60:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x439695: VCL_deliver_method+1a5
0x419729: cnt_prepresp+269
0x41ac6d: CNT_Session+3cd
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7fdd864a39ca: _end+7fdd85e1f202
0x7fdd86200cdd: _end+7fdd85b7c515
sp = 0x7fdd76302c20 {
fd = 13, id = 13, xid = 1424895933,
client = 10.20.100.9 41397,
step = STP_PREPRESP,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fdd76c33158 {
id = "req",
{s,f,r,e} = {0x7fdd76c34730,+312,(nil),+59632},
},
http[req] = {
ws = 0x7fdd76c33158[req]
"GET",
"/",
"HTTP/1.1",
"Host: 10.20.100.12:8080",
"Accept: */*",
"User-Agent: JoeDog/1.00 [en] (X11; I; Siege 2.66)",
"Connection: close",
"X-Forwarded-For: 10.20.100.9",
"Accept-Encoding: gzip",
},
http[resp] = {
ws = 0x7fdd76c33158[req]
"HTTP/1.1",
"OK",
"Server: nginx/0.7.65",
"Content-Type: text/plain",
"Last-Modified: Mon, 04 Jun 2012 21:29:58 GMT",
"Content-Encoding: gzip",
"Content-Length: 181",
"Accept-Ranges: bytes",
"Date: Mon, 04 Jun 2012 21:30:01 GMT",
"X-Varnish: 1424895933 1424885169",
"Age: 1",
"Via: 1.1 varnish",
"Connection: close",
},
worker = 0x7fdd77b48c60 {
ws = 0x7fdd77b48e20 {
id = "wrk",
{s,f,r,e} = {0x7fdd77b48450,0x7fdd77b48450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
obj = 0x7fdd77508300 {
xid = 1424885169,
ws = 0x7fdd77508318 {
id = "obj",
{s,f,r,e} = {0x7fdd775084e8,+216,(nil),+248},
},
http[obj] = {
ws = 0x7fdd77508318[obj]
"HTTP/1.1",
"OK",
"Server: nginx/0.7.65",
"Date: Mon, 04 Jun 2012 21:29:59 GMT",
"Content-Type: text/plain",
"Last-Modified: Mon, 04 Jun 2012 21:29:58 GMT",
"Content-Encoding: gzip",
"Content-Length: 181",
},
len = 181,
store = {
181 {
1f 8b 08 00 00 00 00 00 00 03 25 8f 41 8e 43 31 |..........%.A.C1|
08 43 af e2 03 54 3d 45 97 b3 9d 03 d0 04 55 96 |.C...T=E......U.|
42 f8 4d 60 d4 e3 97 3f 7f 07 c2 7e 36 3f be d4 |B.M`...?...~6?..|
c0 63 a7 a1 fb f0 85 cd 80 98 c6 0d cd e7 d6 16 |.c..............|
[117 more]
},
},
},
},
2012-06-04 21:30:20 [1, 0]: siege-test(siege): Load: 23:30:20 up 4 days, 9:53, 3 users, load average: 0.27, 0.55, 0.64
2012-06-04 21:30:20 [1, 0]: siege-test(siege): Test name: siege-test
2012-06-04 21:30:20 [1, 0]: siege-test(siege): Varnish options:
2012-06-04 21:30:20 [1, 0]: siege-test(siege): Varnish parameters:
2012-06-04 21:30:20 [1, 0]: siege-test(siege): thread_stats_rate=1
2012-06-04 21:30:20 [1, 0]: siege-test(siege): Payload size (excludes headers): 256
2012-06-04 21:30:20 [1, 0]: siege-test(siege): Branch: master
2012-06-04 21:30:20 [1, 0]: siege-test(siege): Number of clients involved: 0
2012-06-04 21:30:20 [1, 0]: siege-test(siege): Type of test: siege
2012-06-04 21:30:20 [1, 0]: siege-test(siege): Test iterations: 1
2012-06-04 21:30:20 [1, 0]: siege-test(siege): Runtime: 20 seconds
2012-06-04 21:30:20 [1, 0]: siege-test(siege): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-04 21:30:27 [2, 6]: httperf-lru-nostream-default(httperf): Starting test
2012-06-04 21:34:07 WARNING [0,220]: httperf-lru-nostream-default(httperf): Panic detected. I think!
2012-06-04 21:34:07 WARNING [0, 0]: httperf-lru-nostream-default(httperf):
Last panic at: Mon, 04 Jun 2012 21:33:02 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
0x7f954d45b9ca: _end+7f954cdd7202
0x7f954d1b8cdd: _end+7f954cb34515
sp = 0x7f9543214020 {
fd = 24, id = 24, xid = 1310053582,
client = 10.20.100.9 14548,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f953dc6b158 {
id = "req",
{s,f,r,e} = {0x7f953dc6c730,+488,(nil),+59632},
},
http[req] = {
ws = 0x7f953dc6b158[req]
"GET",
"/1/5/7/8/0/6.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f953d1cec60 {
ws = 0x7f953d1cee20 {
id = "wrk",
{s,f,r,e} = {0x7f953d1ce450,0x7f953d1ce450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 21:34:07 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 216 stat: 63 diff: 153). Did we crash?
2012-06-04 21:34:07 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-04 21:34:07 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: client_req(130810) less than lower boundary 1999720
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): Load: 23:34:07 up 4 days, 9:57, 3 users, load average: 0.72, 0.80, 0.72
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): Test name: httperf-lru-nostream-default
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): Varnish options:
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): -t=3600
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): -s=malloc,30M
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): Varnish parameters:
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): thread_stats_rate=1
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_max=5000
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): nuke_limit=250
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_min=200
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): Payload size (excludes headers): 10K
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): Branch: master
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): Number of clients involved: 24
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): Type of test: httperf
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): Test iterations: 1
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): Runtime: 216 seconds
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): Number of total connections: 200000
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): Requests per connection: 10
2012-06-04 21:34:07 [1, 0]: httperf-lru-nostream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-04 21:34:07 [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-04 21:34:14 [2, 6]: httperf-rapid-expire(httperf): Starting test
2012-06-04 21:35:26 WARNING [0,71]: httperf-rapid-expire(httperf): Panic detected. I think!
2012-06-04 21:35:26 WARNING [0, 0]: httperf-rapid-expire(httperf):
Last panic at: Mon, 04 Jun 2012 21:34:42 GMT
Assert error in VCL_recv_method(), ../../include/tbl/vcl_returns.h line 27:
Condition((req->sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d88: pan_ic+d8
0x43a3b5: VCL_recv_method+1a5
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x43670d: ses_pool_task+fd
0x433592: Pool_Work_Thread+112
0x441128: wrk_thread_real+c8
0x7f3efef979ca: _end+7f3efe913202
0x7f3efecf4cdd: _end+7f3efe670515
sp = 0x7f3eeff06b20 {
fd = 16, id = 16, xid = 18258056,
client = 10.20.100.8 3205,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f3ef029d158 {
id = "req",
{s,f,r,e} = {0x7f3ef029e730,+80,(nil),+59632},
},
http[req] = {
ws = 0x7f3ef029d158[req]
"GET",
"/2/3.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f3eefcbac60 {
ws = 0x7f3eefcbae20 {
id = "wrk",
{s,f,r,e} = {0x7f3eefcba450,0x7f3eefcba450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 21:35:26 WARNING [0, 0]: httperf-rapid-expire(httperf): Out of bounds: client_req(169300) less than lower boundary 999640
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): Load: 23:35:26 up 4 days, 9:58, 3 users, load average: 0.79, 0.81, 0.73
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): Test name: httperf-rapid-expire
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): Varnish options:
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): -t=2
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): Varnish parameters:
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): thread_stats_rate=1
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): Payload size (excludes headers): 256
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): Branch: master
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): Number of clients involved: 24
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): Type of test: httperf
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): Test iterations: 1
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): Runtime: 67 seconds
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): Number of total connections: 100000
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): Requests per connection: 10
2012-06-04 21:35:26 [1, 0]: httperf-rapid-expire(httperf): Extra options to httperf: --wset=100,0.30
2012-06-04 21:35:26 [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-04 21:35:33 [2, 6]: streaming-grace(httperf): Starting test
2012-06-04 21:38:27 [2,173]: cold-default(httperf): Starting test
2012-06-04 21:41:50 WARNING [0,202]: cold-default(httperf): Panic detected. I think!
2012-06-04 21:41:50 WARNING [0, 0]: cold-default(httperf):
Last panic at: Mon, 04 Jun 2012 21:39:57 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
0x7f339cdd49ca: _end+7f339c750202
0x7f339cb31cdd: _end+7f339c4ad515
sp = 0x7f3388a38b20 {
fd = 23, id = 23, xid = 540517930,
client = 10.20.100.7 6344,
step = STP_RECV,
handling = lookup,
restarts = 0, esi_level = 0
ws = 0x7f338887a158 {
id = "req",
{s,f,r,e} = {0x7f338887b730,+304,(nil),+59632},
},
http[req] = {
ws = 0x7f338887a158[req]
"GET",
"/0/3/0/2/6/5/6.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.7",
},
worker = 0x7f338e19dc60 {
ws = 0x7f338e19de20 {
id = "wrk",
{s,f,r,e} = {0x7f338e19d450,0x7f338e19d450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 21:41:50 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 199 stat: 112 diff: 87). Did we crash?
2012-06-04 21:45:14 WARNING [0,204]: cold-default(httperf): Panic detected. I think!
2012-06-04 21:45:14 WARNING [0, 0]: cold-default(httperf):
Last panic at: Mon, 04 Jun 2012 21:44:12 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
0x7f339cdd49ca: _end+7f339c750202
0x7f339cb31cdd: _end+7f339c4ad515
sp = 0x7f338f0aca20 {
fd = 18, id = 18, xid = 0,
client = ,
step = STP_FIRST,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f338ce24158 {
id = "req",
{s,f,r,e} = {0x7f338ce25730,0x7f338ce25730,(nil),+59632},
},
http[req] = {
ws = (nil)[]
},
worker = 0x7f338e1b6c60 {
ws = 0x7f338e1b6e20 {
id = "wrk",
{s,f,r,e} = {0x7f338e1b6450,0x7f338e1b6450,(nil),+2048},
},
},
},
2012-06-04 21:45:14 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 403 stat: 61 diff: 342). Did we crash?
2012-06-04 21:45:14 WARNING [0, 0]: cold-default(httperf): Out of bounds: uptime(61) less than lower boundary 100
2012-06-04 21:45:14 WARNING [0, 0]: cold-default(httperf): Out of bounds: client_req(69573) less than lower boundary 1599640
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): Load: 23:45:14 up 4 days, 10:08, 3 users, load average: 0.59, 0.80, 0.83
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): Test name: cold-default
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): Varnish options:
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): -t=3600
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): -s=malloc,10G
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): Varnish parameters:
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): thread_stats_rate=1
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): Payload size (excludes headers): 256
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): Branch: master
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): Number of clients involved: 24
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): Type of test: httperf
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): Test iterations: 2
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): Runtime: 403 seconds
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): Number of total connections: 80000
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): Requests per connection: 10
2012-06-04 21:45:14 [1, 0]: cold-default(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-04 21:45:14 [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-04 21:45:21 [2, 6]: 4gpluss-nogzip(httperf): Starting test
2012-06-04 21:45:24 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-04 21:45:24 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_stats_rate=1
2012-06-04 21:45:25 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-04 21:45:25 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-04 21:45:26 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-04 22:06:27 [2,1261]: purge-fail(httperf): Starting test
2012-06-04 22:09:09 WARNING [0,161]: purge-fail(httperf): Panic detected. I think!
2012-06-04 22:09:09 WARNING [0, 0]: purge-fail(httperf):
Last panic at: Mon, 04 Jun 2012 22:08: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
0x7eff4c3869ca: _end+7eff4bd02202
0x7eff4c0e3cdd: _end+7eff4ba5f515
sp = 0x7eff3d502f20 {
fd = 21, id = 21, xid = 1834453301,
client = 10.20.100.7 24898,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7eff3edb6158 {
id = "req",
{s,f,r,e} = {0x7eff3edb7730,+80,(nil),+59632},
},
http[req] = {
ws = 0x7eff3edb6158[req]
"GET",
"/1/3/3.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7eff3d7f5c60 {
ws = 0x7eff3d7f5e20 {
id = "wrk",
{s,f,r,e} = {0x7eff3d7f5450,0x7eff3d7f5450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-04 22:09:09 WARNING [0, 0]: purge-fail(httperf): Varnishstat uptime and measured run-time is too large (measured: 156 stat: 48 diff: 108). Did we crash?
2012-06-04 22:09:09 WARNING [0, 0]: purge-fail(httperf): Out of bounds: client_req(48138) less than lower boundary 299800
2012-06-04 22:09:09 [1, 0]: purge-fail(httperf): Load: 00:09:09 up 4 days, 10:32, 3 users, load average: 0.48, 0.70, 1.04
2012-06-04 22:09:09 [1, 0]: purge-fail(httperf): Test name: purge-fail
2012-06-04 22:09:09 [1, 0]: purge-fail(httperf): Varnish options:
2012-06-04 22:09:09 [1, 0]: purge-fail(httperf): Varnish parameters:
2012-06-04 22:09:09 [1, 0]: purge-fail(httperf): thread_stats_rate=1
2012-06-04 22:09:09 [1, 0]: purge-fail(httperf): Payload size (excludes headers): 1K
2012-06-04 22:09:09 [1, 0]: purge-fail(httperf): Branch: master
2012-06-04 22:09:09 [1, 0]: purge-fail(httperf): Number of clients involved: 24
2012-06-04 22:09:09 [1, 0]: purge-fail(httperf): Type of test: httperf
2012-06-04 22:09:09 [1, 0]: purge-fail(httperf): Test iterations: 1
2012-06-04 22:09:09 [1, 0]: purge-fail(httperf): Runtime: 156 seconds
2012-06-04 22:09:09 [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-04 22:09:09 [1, 0]: purge-fail(httperf): Number of total connections: 300000
2012-06-04 22:09:09 [1, 0]: purge-fail(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-04 22:09:09 [1, 0]: purge-fail(httperf): Requests per connection: 1
2012-06-04 22:09:09 [1, 0]: purge-fail(httperf): Extra options to httperf: --wset=999,0.5 --timeout=5
2012-06-04 22:09:09 [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-04 22:09:16 [2, 6]: streaming-gzip(httperf): Starting test
2012-06-04 22:11:51 WARNING [0,154]: Tests finished with problems detected. Failed expectations: 15 Total run time: 9590 seconds
More information about the varnish-test
mailing list