[Fryer] master FAIL. 9 of 26 tests succeeded.
fryer at oneiros.varnish-software.com
fryer at oneiros.varnish-software.com
Fri Jun 1 00:01:35 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 lru-random siege-test sky-misc httperf-lru-nostream-default httperf-rapid-expire cold-default purge-fail
Tests OK: streaming memleak 4gpluss-stream 4gpluss basic-fryer 4gpluss-nostream streaming-grace 4gpluss-nogzip streaming-gzip
2012-05-31 19:32:15 [1,13]: Server pantoum checked out varnish-3.0.0-beta2-1008-g2342c8b of branch master
2012-05-31 19:32:42 [2,26]: httperf-lru-nostream-gzip(httperf): Starting test
2012-05-31 19:36:02 WARNING [0,199]: httperf-lru-nostream-gzip(httperf): Panic detected. I think!
2012-05-31 19:36:02 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf):
Last panic at: Thu, 31 May 2012 19:35:11 GMT
Assert error in VRT_r_req_restarts(), cache/cache_vrt_var.c line 365:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43bbe5: VRT_r_req_restarts+65
0x7f8260dfc388: _end+7f8260778bc0
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7f826f1f39ca: _end+7f826eb70202
sp = 0x7f8260441d20 {
fd = 24, id = 24, xid = 1492680866,
client = 10.20.100.8 16971,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f825ea02158 {
id = "req",
{s,f,r,e} = {0x7f825ea03730,+568,(nil),+59632},
},
http[req] = {
ws = 0x7f825ea02158[req]
"GET",
"/1/8/9/7/1/1.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f825eff6c60 {
ws = 0x7f825eff6e20 {
id = "wrk",
{s,f,r,e} = {0x7f825eff6450,0x7f825eff6450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 19:36:02 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 195 stat: 50 diff: 145). Did we crash?
2012-05-31 19:36:02 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-05-31 19:36:02 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: client_req(98780) less than lower boundary 1999720
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): Load: 21:36:02 up 7:58, 4 users, load average: 0.72, 0.60, 0.25
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): Test name: httperf-lru-nostream-gzip
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish options:
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): -t=3600
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): -s=malloc,30M
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish parameters:
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_max=5000
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): nuke_limit=250
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): http_gzip_support=on
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_min=200
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): Payload size (excludes headers): 10K
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): Branch: master
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of clients involved: 24
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): Type of test: httperf
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): Test iterations: 1
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): Runtime: 195 seconds
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of total connections: 200000
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): Requests per connection: 10
2012-05-31 19:36:02 [1, 0]: httperf-lru-nostream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-05-31 19:36:02 [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-05-31 19:36:09 [2, 6]: httperf-lru-nostream-gzip-deflateoff(httperf): Starting test
2012-05-31 19:39:25 WARNING [0,196]: httperf-lru-nostream-gzip-deflateoff(httperf): Panic detected. I think!
2012-05-31 19:39:25 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf):
Last panic at: Thu, 31 May 2012 19:38:32 GMT
Assert error in VRT_r_req_restarts(), cache/cache_vrt_var.c line 365:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43bbe5: VRT_r_req_restarts+65
0x7f65d88f53e8: _end+7f65d8271c20
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7f65e69199ca: _end+7f65e6296202
sp = 0x7f65d7509520 {
fd = 14, id = 14, xid = 902572752,
client = 10.20.100.9 14239,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f65d7da8158 {
id = "req",
{s,f,r,e} = {0x7f65d7da9730,+168,(nil),+59632},
},
http[req] = {
ws = 0x7f65d7da8158[req]
"GET",
"/1/4/8/6/3/8.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f65d6692c60 {
ws = 0x7f65d6692e20 {
id = "wrk",
{s,f,r,e} = {0x7f65d6692450,0x7f65d6692450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 19:39:25 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnishstat uptime and measured run-time is too large (measured: 192 stat: 52 diff: 140). Did we crash?
2012-05-31 19:39:25 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-05-31 19:39:25 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: client_req(144240) less than lower boundary 1999720
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Load: 21:39:25 up 8:02, 4 users, load average: 0.92, 0.83, 0.41
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test name: httperf-lru-nostream-gzip-deflateoff
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish options:
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -t=3600
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -s=malloc,30M
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish parameters:
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_max=5000
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): nuke_limit=250
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): http_gzip_support=on
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_min=200
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Payload size (excludes headers): 10K
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Branch: master
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of clients involved: 24
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Type of test: httperf
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test iterations: 1
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Runtime: 192 seconds
2012-05-31 19:39:25 [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-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of total connections: 200000
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Requests per connection: 10
2012-05-31 19:39:25 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Extra options to httperf: --wset=1000000,0.1
2012-05-31 19:39:25 [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-05-31 19:39:32 [2, 6]: streaming(httperf): Starting test
2012-05-31 19:42:16 [2,164]: httperf-lru-default(httperf): Starting test
2012-05-31 19:45:52 WARNING [0,216]: httperf-lru-default(httperf): Panic detected. I think!
2012-05-31 19:45:52 WARNING [0, 0]: httperf-lru-default(httperf):
Last panic at: Thu, 31 May 2012 19:45:22 GMT
Assert error in VRT_r_req_url(), cache/cache_vrt_var.c line 85:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43db15: VRT_r_req_url+b5
0x7fa7e2afc78b: _end+7fa7e2478fc3
0x4396cb: VCL_hash_method+4b
0x4164fe: cnt_recv+21e
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7fa7f0e749ca: _end+7fa7f07f1202
sp = 0x7fa7e065c620 {
fd = 26, id = 26, xid = 1428684156,
client = 10.20.100.8 18182,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fa7e093b158 {
id = "req",
{s,f,r,e} = {0x7fa7e093c730,+864,(nil),+59632},
},
http[req] = {
ws = 0x7fa7e093b158[req]
"GET",
"/1/9/4/9/6/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
worker = 0x7fa7e0e92c60 {
ws = 0x7fa7e0e92e20 {
id = "wrk",
{s,f,r,e} = {0x7fa7e0e92450,0x7fa7e0e92450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 19:45:52 WARNING [0, 0]: httperf-lru-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 212 stat: 29 diff: 183). Did we crash?
2012-05-31 19:45:53 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-05-31 19:45:53 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: client_req(59561) less than lower boundary 1999720
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): Load: 21:45:53 up 8:08, 4 users, load average: 0.87, 0.98, 0.60
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): Test name: httperf-lru-default
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): Varnish options:
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): -t=3600
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): -s=malloc,30M
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): Varnish parameters:
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): thread_pool_max=5000
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): nuke_limit=250
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): thread_pool_min=200
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): Payload size (excludes headers): 10K
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): Branch: master
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): Number of clients involved: 24
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): Type of test: httperf
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): Test iterations: 1
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): Runtime: 212 seconds
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): Number of total connections: 200000
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): Requests per connection: 10
2012-05-31 19:45:53 [1, 0]: httperf-lru-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-05-31 19:45:53 [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-05-31 19:45:59 [2, 6]: memleak(httperf): Starting test
2012-05-31 19:48:05 [2,126]: 4gpluss-stream(httperf): Starting test
2012-05-31 19:48:08 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-05-31 19:48:08 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-05-31 19:48:08 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-05-31 19:48:09 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-05-31 20:07:38 [2,1168]: httperf-lru-stream-default(httperf): Starting test
2012-05-31 20:11:09 WARNING [0,211]: httperf-lru-stream-default(httperf): Panic detected. I think!
2012-05-31 20:11:09 WARNING [0, 0]: httperf-lru-stream-default(httperf):
Last panic at: Thu, 31 May 2012 20:10:22 GMT
Assert error in VRT_r_req_restarts(), cache/cache_vrt_var.c line 365:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43bbe5: VRT_r_req_restarts+65
0x7fac18dfc388: _end+7fac18778bc0
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7fac270b29ca: _end+7fac26a2f202
sp = 0x7fac17202620 {
fd = 13, id = 13, xid = 386758414,
client = 10.20.100.8 16770,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fac17402158 {
id = "req",
{s,f,r,e} = {0x7fac17403730,+568,(nil),+59632},
},
http[req] = {
ws = 0x7fac17402158[req]
"GET",
"/1/9/0/0/7/0.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fac17132c60 {
ws = 0x7fac17132e20 {
id = "wrk",
{s,f,r,e} = {0x7fac17132450,0x7fac17132450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 20:11:09 WARNING [0, 0]: httperf-lru-stream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 206 stat: 46 diff: 160). Did we crash?
2012-05-31 20:11:09 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-05-31 20:11:09 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: client_req(92350) less than lower boundary 1999720
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): Load: 22:11:09 up 8:34, 4 users, load average: 0.93, 1.42, 1.11
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): Test name: httperf-lru-stream-default
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): Varnish options:
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): -t=3600
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): -s=malloc,30M
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): Varnish parameters:
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_max=5000
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): nuke_limit=250
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_min=200
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): Payload size (excludes headers): 10K
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): Branch: master
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): Number of clients involved: 24
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): Type of test: httperf
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): Test iterations: 1
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): Runtime: 206 seconds
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): Number of total connections: 200000
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): Requests per connection: 10
2012-05-31 20:11:09 [1, 0]: httperf-lru-stream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-05-31 20:11:09 [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-05-31 20:11:16 [2, 6]: httperf-hot(httperf): Starting test
2012-05-31 20:12:18 WARNING [0,61]: httperf-hot(httperf): Panic detected. I think!
2012-05-31 20:12:18 WARNING [0, 0]: httperf-hot(httperf):
Last panic at: Thu, 31 May 2012 20:11:46 GMT
Assert error in VRT_r_req_restarts(), cache/cache_vrt_var.c line 365:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43bbe5: VRT_r_req_restarts+65
0x7f3f264fc388: _end+7f3f25e78bc0
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7f3f348aa9ca: _end+7f3f34227202
sp = 0x7f3f25003220 {
fd = 17, id = 17, xid = 2008543419,
client = 10.20.100.8 6975,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f3f26918158 {
id = "req",
{s,f,r,e} = {0x7f3f26919730,+528,(nil),+59632},
},
http[req] = {
ws = 0x7f3f26918158[req]
"GET",
"/7/1/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f3f25a5bc60 {
ws = 0x7f3f25a5be20 {
id = "wrk",
{s,f,r,e} = {0x7f3f25a5b450,0x7f3f25a5b450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 20:12:18 WARNING [0, 0]: httperf-hot(httperf): Out of bounds: client_req(187690) less than lower boundary 999640
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): Load: 22:12:18 up 8:35, 4 users, load average: 1.35, 1.43, 1.13
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): Test name: httperf-hot
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): Varnish options:
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): Varnish parameters:
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): Payload size (excludes headers): 256
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): Branch: master
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): Number of clients involved: 24
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): Type of test: httperf
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): Test iterations: 1
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): Runtime: 58 seconds
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): Number of total connections: 100000
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): Requests per connection: 10
2012-05-31 20:12:18 [1, 0]: httperf-hot(httperf): Extra options to httperf: --wset=1000,0.25
2012-05-31 20:12:18 [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-05-31 20:12:25 [2, 6]: httperf-lru-nostream-nogzip(httperf): Starting test
2012-05-31 20:15:44 WARNING [0,199]: httperf-lru-nostream-nogzip(httperf): Panic detected. I think!
2012-05-31 20:15:44 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf):
Last panic at: Thu, 31 May 2012 20:14:25 GMT
Assert error in VRT_r_req_restarts(), cache/cache_vrt_var.c line 365:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43bbe5: VRT_r_req_restarts+65
0x7f129e5f9388: _end+7f129df75bc0
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7f12ac7c99ca: _end+7f12ac146202
sp = 0x7f129c337820 {
fd = 31, id = 31, xid = 878403845,
client = 10.20.100.9 14213,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f129d06a158 {
id = "req",
{s,f,r,e} = {0x7f129d06b730,+816,(nil),+59632},
},
http[req] = {
ws = 0x7f129d06a158[req]
"GET",
"/1/5/4/1/5/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f129c7eac60 {
ws = 0x7f129c7eae20 {
id = "wrk",
{s,f,r,e} = {0x7f129c7ea450,0x7f129c7ea450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 20:15:44 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 195 stat: 78 diff: 117). Did we crash?
2012-05-31 20:15:44 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: n_lru_nuked(30000) less than lower boundary 80000
2012-05-31 20:15:44 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: client_req(328610) less than lower boundary 1999720
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): Load: 22:15:44 up 8:38, 4 users, load average: 0.74, 1.18, 1.09
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test name: httperf-lru-nostream-nogzip
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish options:
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): -t=3600
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): -s=malloc,30M
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish parameters:
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_max=5000
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): nuke_limit=250
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): http_gzip_support=off
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_min=200
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): Payload size (excludes headers): 10K
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): Branch: master
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of clients involved: 24
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): Type of test: httperf
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test iterations: 1
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): Runtime: 195 seconds
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of total connections: 200000
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): Requests per connection: 10
2012-05-31 20:15:44 [1, 0]: httperf-lru-nostream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-05-31 20:15:44 [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-05-31 20:15:51 [2, 6]: cold-gzip(httperf): Starting test
2012-05-31 20:19:16 WARNING [0,205]: cold-gzip(httperf): Panic detected. I think!
2012-05-31 20:19:16 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Thu, 31 May 2012 20:18:31 GMT
Assert error in VRT_r_req_restarts(), cache/cache_vrt_var.c line 365:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43bbe5: VRT_r_req_restarts+65
0x7f1488ffc388: _end+7f1488978bc0
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7f1496e3d9ca: _end+7f14967ba202
sp = 0x7f1487b07f20 {
fd = 18, id = 18, xid = 16570952,
client = 10.20.100.8 7383,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f14849a5158 {
id = "req",
{s,f,r,e} = {0x7f14849a6730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f14849a5158[req]
"GET",
"/0/3/5/9/2/9/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f14881aac60 {
ws = 0x7f14881aae20 {
id = "wrk",
{s,f,r,e} = {0x7f14881aa450,0x7f14881aa450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 20:19:16 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 201 stat: 45 diff: 156). Did we crash?
2012-05-31 20:22:36 WARNING [0,199]: cold-gzip(httperf): Panic detected. I think!
2012-05-31 20:22:36 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Thu, 31 May 2012 20:20:51 GMT
Assert error in VRT_r_req_restarts(), cache/cache_vrt_var.c line 365:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43bbe5: VRT_r_req_restarts+65
0x7f1488dfc388: _end+7f1488778bc0
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7f1496e3d9ca: _end+7f14967ba202
sp = 0x7f1482b02c20 {
fd = 18, id = 18, xid = 371062541,
client = 10.20.100.7 5207,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f14817d3158 {
id = "req",
{s,f,r,e} = {0x7f14817d4730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f14817d3158[req]
"GET",
"/0/2/5/2/6/1/6.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f1486efec60 {
ws = 0x7f1486efee20 {
id = "wrk",
{s,f,r,e} = {0x7f1486efe450,0x7f1486efe450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 20:22:36 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 401 stat: 104 diff: 297). Did we crash?
2012-05-31 20:22:36 WARNING [0, 0]: cold-gzip(httperf): Out of bounds: client_req(199750) less than lower boundary 1599640
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): Load: 22:22:36 up 8:45, 4 users, load average: 0.43, 0.80, 0.96
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): Test name: cold-gzip
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): Varnish options:
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): -t=3600
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): -s=malloc,10G
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): Varnish parameters:
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): http_gzip_support=on
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): Payload size (excludes headers): 256
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): Branch: master
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): Number of clients involved: 24
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): Type of test: httperf
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): Test iterations: 2
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): Runtime: 401 seconds
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): Number of total connections: 80000
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): Requests per connection: 10
2012-05-31 20:22:36 [1, 0]: cold-gzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-05-31 20:22:36 [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-05-31 20:22:42 [2, 6]: 4gpluss(httperf): Starting test
2012-05-31 20:22:45 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-05-31 20:22:45 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-05-31 20:22:46 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-05-31 20:22:46 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-05-31 20:43:30 [2,1244]: httperf-lru-stream-gzip(httperf): Starting test
2012-05-31 20:47:42 WARNING [0,252]: httperf-lru-stream-gzip(httperf): Panic detected. I think!
2012-05-31 20:47:42 WARNING [0, 0]: httperf-lru-stream-gzip(httperf):
Last panic at: Thu, 31 May 2012 20:46:01 GMT
Assert error in VRT_r_req_restarts(), cache/cache_vrt_var.c line 365:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43bbe5: VRT_r_req_restarts+65
0x7ff4ecdfc388: _end+7ff4ec778bc0
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7ff4faf829ca: _end+7ff4fa8ff202
sp = 0x7ff4ebd09220 {
fd = 19, id = 19, xid = 1637401659,
client = 10.20.100.9 16531,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7ff4ea90b158 {
id = "req",
{s,f,r,e} = {0x7ff4ea90c730,+408,(nil),+59632},
},
http[req] = {
ws = 0x7ff4ea90b158[req]
"GET",
"/1/8/2/0/1/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7ff4eafcec60 {
ws = 0x7ff4eafcee20 {
id = "wrk",
{s,f,r,e} = {0x7ff4eafce450,0x7ff4eafce450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 20:47:42 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 247 stat: 100 diff: 147). Did we crash?
2012-05-31 20:47:43 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-05-31 20:47:43 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: client_req(130250) less than lower boundary 1999720
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): Load: 22:47:43 up 9:10, 4 users, load average: 0.24, 0.58, 0.90
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): Test name: httperf-lru-stream-gzip
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish options:
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): -t=3600
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): -s=malloc,30M
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish parameters:
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_max=5000
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): nuke_limit=250
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): http_gzip_support=on
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_min=200
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): Payload size (excludes headers): 10K
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): Branch: master
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): Number of clients involved: 24
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): Type of test: httperf
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): Test iterations: 1
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): Runtime: 247 seconds
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): Number of total connections: 200000
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): Requests per connection: 10
2012-05-31 20:47:43 [1, 0]: httperf-lru-stream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-05-31 20:47:43 [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-05-31 20:47:49 [2, 6]: httperf-lru-stream-nogzip(httperf): Starting test
2012-05-31 20:51:15 WARNING [0,205]: httperf-lru-stream-nogzip(httperf): Panic detected. I think!
2012-05-31 20:51:15 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf):
Last panic at: Thu, 31 May 2012 20:49:46 GMT
Assert error in VRT_r_req_url(), cache/cache_vrt_var.c line 85:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43db15: VRT_r_req_url+b5
0x7fe88cefc78b: _end+7fe88c878fc3
0x4396cb: VCL_hash_method+4b
0x4164fe: cnt_recv+21e
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7fe89af989ca: _end+7fe89a915202
sp = 0x7fe88b907220 {
fd = 23, id = 23, xid = 1575327928,
client = 10.20.100.8 10388,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fe888126158 {
id = "req",
{s,f,r,e} = {0x7fe888127730,+136,(nil),+59632},
},
http[req] = {
ws = 0x7fe888126158[req]
"GET",
"/1/1/2/9/5/7.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
worker = 0x7fe88aca2c60 {
ws = 0x7fe88aca2e20 {
id = "wrk",
{s,f,r,e} = {0x7fe88aca2450,0x7fe88aca2450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 20:51:15 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 202 stat: 88 diff: 114). Did we crash?
2012-05-31 20:51:15 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: n_lru_nuked(26739) less than lower boundary 80000
2012-05-31 20:51:15 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: client_req(295980) less than lower boundary 1999720
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): Load: 22:51:15 up 9:14, 4 users, load average: 2.48, 4.59, 2.77
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): Test name: httperf-lru-stream-nogzip
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish options:
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): -t=3600
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): -s=malloc,30M
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish parameters:
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_max=5000
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): nuke_limit=250
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): http_gzip_support=off
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_min=200
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): Payload size (excludes headers): 10K
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): Branch: master
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of clients involved: 24
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): Type of test: httperf
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): Test iterations: 1
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): Runtime: 202 seconds
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of total connections: 200000
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): Requests per connection: 10
2012-05-31 20:51:15 [1, 0]: httperf-lru-stream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-05-31 20:51:15 [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-05-31 20:51:22 [2, 6]: basic-fryer(httperf): Starting test
2012-05-31 20:51:34 [2,11]: cold-nogzip(httperf): Starting test
2012-05-31 20:54:58 WARNING [0,204]: cold-nogzip(httperf): Panic detected. I think!
2012-05-31 20:54:58 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Thu, 31 May 2012 20:54:10 GMT
Assert error in VRT_l_beresp_do_stream(), cache/cache_vrt_var.c line 200:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43c9fd: VRT_l_beresp_do_stream+8d
0x7ff675cfa919: _end+7ff675677151
0x43939e: VCL_fetch_method+4e
0x4186a9: cnt_fetch+479
0x41accd: CNT_Session+42d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7ff6835729ca: _end+7ff682eef202
sp = 0x7ff674613920 {
fd = 14, id = 14, xid = 922279733,
client = 10.20.100.8 7054,
step = STP_FETCH,
handling = deliver,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7ff6747e3020 {
ws = 0x7ff6747e3070 {
id = "bo",
{s,f,r,e} = {0x7ff6747e4aa0,+248,(nil),+58752},
},
do_stream
bodystatus = 4 (length),
},
http[bereq] = {
ws = 0x7ff6747e3070[bo]
"GET",
"/0/3/5/4/6/3/3.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
"X-Varnish: 922279733",
},
http[beresp] = {
ws = 0x7ff6747e3070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Thu, 31 May 2012 20:54:10 GMT",
"Content-Type: text/plain",
"Content-Length: 256",
"Last-Modified: Thu, 31 May 2012 20:51:37 GMT",
"Connection: keep-alive",
"Accept-Ranges: bytes",
},
ws = 0x7ff66ff3a158 {
id = "req",
{s,f,r,e} = {0x7ff66ff3b730,+216,(nil),+59632},
},
http[req] = {
ws = 0x7ff66ff3a158[req]
"GET",
"/0/3/5/4/6/3/3.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
worker = 0x7ff6749bdc60 {
ws = 0x7ff6749bde20 {
id = "wrk",
{s,f,r,e} = {0x7ff6749bd450,0x7ff6749bd450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 20:54:58 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 200 stat: 47 diff: 153). Did we crash?
2012-05-31 20:58:00 WARNING [0,182]: cold-nogzip(httperf): Panic detected. I think!
2012-05-31 20:58:00 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Thu, 31 May 2012 20:57:10 GMT
Assert error in VRT_r_req_restarts(), cache/cache_vrt_var.c line 365:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43bbe5: VRT_r_req_restarts+65
0x7ff6753f7388: _end+7ff674d73bc0
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7ff6835729ca: _end+7ff682eef202
sp = 0x7ff66eac4520 {
fd = 16, id = 16, xid = 501132353,
client = 10.20.100.7 7518,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7ff67426a158 {
id = "req",
{s,f,r,e} = {0x7ff67426b730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7ff67426a158[req]
"GET",
"/0/3/9/7/2/1/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7ff6749cdc60 {
ws = 0x7ff6749cde20 {
id = "wrk",
{s,f,r,e} = {0x7ff6749cd450,0x7ff6749cd450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 20:58:00 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 383 stat: 50 diff: 333). Did we crash?
2012-05-31 20:58:01 WARNING [0, 0]: cold-nogzip(httperf): Out of bounds: uptime(50) less than lower boundary 100
2012-05-31 20:58:01 WARNING [0, 0]: cold-nogzip(httperf): Out of bounds: client_req(53290) less than lower boundary 1599640
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): Load: 22:58:01 up 9:20, 4 users, load average: 0.83, 1.78, 2.05
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): Test name: cold-nogzip
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): Varnish options:
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): -t=3600
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): -s=malloc,10G
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): Varnish parameters:
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): http_gzip_support=off
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): Payload size (excludes headers): 256
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): Branch: master
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): Number of clients involved: 24
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): Type of test: httperf
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): Test iterations: 2
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): Runtime: 383 seconds
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): Number of total connections: 80000
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): Requests per connection: 10
2012-05-31 20:58:01 [1, 0]: cold-nogzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-05-31 20:58:01 [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-05-31 20:58:07 [2, 6]: 4gpluss-nostream(httperf): Starting test
2012-05-31 20:58:10 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-05-31 20:58:10 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-05-31 20:58:10 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-05-31 20:58:11 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-05-31 21:18:03 [2,1191]: lru-random(httperf): Starting test
2012-05-31 21:18:19 WARNING [0,16]: lru-random(httperf): Panic detected. I think!
2012-05-31 21:18:19 WARNING [0, 0]: lru-random(httperf):
Last panic at: Thu, 31 May 2012 21:18:11 GMT
Assert error in VRT_l_beresp_do_stream(), cache/cache_vrt_var.c line 200:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43c9fd: VRT_l_beresp_do_stream+8d
0x7f37818f7919: _end+7f3781274151
0x43939e: VCL_fetch_method+4e
0x4186a9: cnt_fetch+479
0x41accd: CNT_Session+42d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7f378faee9ca: _end+7f378f46b202
sp = 0x7f3780bb3220 {
fd = 22, id = 22, xid = 1690994929,
client = 10.20.100.8 1236,
step = STP_FETCH,
handling = deliver,
err_code = 502, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f3780735020 {
ws = 0x7f3780735070 {
id = "bo",
{s,f,r,e} = {0x7f3780736aa0,+360,(nil),+58752},
},
do_stream
bodystatus = 4 (length),
},
http[bereq] = {
ws = 0x7f3780735070[bo]
"GET",
"/0/1/4/5/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
"X-Varnish: 1690994929",
},
http[beresp] = {
ws = 0x7f3780735070[bo]
"HTTP/1.1",
"502",
"Bad Gateway",
"Server: nginx/0.7.65",
"Date: Thu, 31 May 2012 21:18:11 GMT",
"Content-Type: text/html",
"Content-Length: 173",
"Connection: keep-alive",
},
ws = 0x7f3780112158 {
id = "req",
{s,f,r,e} = {0x7f3780113730,+288,(nil),+59632},
},
http[req] = {
ws = 0x7f3780112158[req]
"GET",
"/0/1/4/5/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
worker = 0x7f3780e19c60 {
ws = 0x7f3780e19e20 {
id = "wrk",
{s,f,r,e} = {0x7f3780e19450,0x7f3780e19450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 21:18:19 WARNING [0, 0]: lru-random(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 3000
2012-05-31 21:18:19 WARNING [0, 0]: lru-random(httperf): Out of bounds: client_req(12333) less than lower boundary 29752
2012-05-31 21:18:19 WARNING [0, 0]: lru-random(httperf): Out of bounds: cache_hitpass(9233) more than upper boundary 0
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Load: 23:18:20 up 9:41, 4 users, load average: 0.58, 0.56, 0.95
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Test name: lru-random
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Varnish options:
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): -t=3600000
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): -s=malloc,1G
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Varnish parameters:
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Payload size (excludes headers): 256
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Branch: master
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Number of clients involved: 24
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Type of test: httperf
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Test iterations: 1
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Runtime: 12 seconds
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
// set beresp.do_gzip = true;
}
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Number of total connections: 10000
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Requests per connection: 3
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Extra options to httperf: --wset=100000,0.25
2012-05-31 21:18:20 [1, 0]: lru-random(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 3 --num-conns 416 --port 8080 --burst-length 3 --client 23/24 --server 10.20.100.12 --wset=100000,0.25
2012-05-31 21:18:26 [2, 6]: siege-test(siege): Starting test
2012-05-31 21:18:34 WARNING [0, 7]: siege-test(siege): Panic detected. I think!
2012-05-31 21:18:34 WARNING [0, 0]: siege-test(siege):
Last panic at: Thu, 31 May 2012 21:18:30 GMT
Assert error in VRT_r_req_restarts(), cache/cache_vrt_var.c line 365:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43bbe5: VRT_r_req_restarts+65
0x7fd05aefc388: _end+7fd05a878bc0
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7fd0692b79ca: _end+7fd068c34202
sp = 0x7fd05a003e20 {
fd = 23, id = 23, xid = 1830339138,
client = 10.20.100.8 43925,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fd059f46158 {
id = "req",
{s,f,r,e} = {0x7fd059f47730,+152,(nil),+59632},
},
http[req] = {
ws = 0x7fd059f46158[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 = 0x7fd05a5f0c60 {
ws = 0x7fd05a5f0e20 {
id = "wrk",
{s,f,r,e} = {0x7fd05a5f0450,0x7fd05a5f0450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 21:18:34 WARNING [0, 0]: siege-test(siege): Out of bounds: uptime(3) less than lower boundary 13
2012-05-31 21:18:34 [1, 0]: siege-test(siege): Load: 23:18:34 up 9:41, 4 users, load average: 0.59, 0.56, 0.94
2012-05-31 21:18:34 [1, 0]: siege-test(siege): Test name: siege-test
2012-05-31 21:18:34 [1, 0]: siege-test(siege): Varnish options:
2012-05-31 21:18:34 [1, 0]: siege-test(siege): Varnish parameters:
2012-05-31 21:18:34 [1, 0]: siege-test(siege): Payload size (excludes headers): 256
2012-05-31 21:18:34 [1, 0]: siege-test(siege): Branch: master
2012-05-31 21:18:34 [1, 0]: siege-test(siege): Number of clients involved: 0
2012-05-31 21:18:34 [1, 0]: siege-test(siege): Type of test: siege
2012-05-31 21:18:34 [1, 0]: siege-test(siege): Test iterations: 1
2012-05-31 21:18:34 [1, 0]: siege-test(siege): Runtime: 4 seconds
2012-05-31 21:18:34 [1, 0]: siege-test(siege): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-05-31 21:18:41 [2, 6]: sky-misc(httperf): Starting test
2012-05-31 21:20:03 WARNING [0,82]: sky-misc(httperf): Panic detected. I think!
2012-05-31 21:20:03 WARNING [0, 0]: sky-misc(httperf):
Last panic at: Thu, 31 May 2012 21:19:42 GMT
Assert error in VRT_r_req_restarts(), cache/cache_vrt_var.c line 365:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43bbe5: VRT_r_req_restarts+65
0x7f31d4efc388: _end+7f31d4878bc0
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7f31e2edd9ca: _end+7f31e285a202
sp = 0x7f31d3f06120 {
fd = 14, id = 14, xid = 912943346,
client = 10.20.100.8 8401,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f31d3e9b158 {
id = "req",
{s,f,r,e} = {0x7f31d3e9c730,+200,(nil),+59632},
},
http[req] = {
ws = 0x7f31d3e9b158[req]
"GET",
"/",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f31d44e8c60 {
ws = 0x7f31d44e8e20 {
id = "wrk",
{s,f,r,e} = {0x7f31d44e8450,0x7f31d44e8450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 21:20:03 WARNING [0, 0]: sky-misc(httperf): Varnishstat uptime and measured run-time is too large (measured: 79 stat: 21 diff: 58). Did we crash?
2012-05-31 21:20:04 WARNING [0, 0]: sky-misc(httperf): Out of bounds: client_req(271400) less than lower boundary 4999000
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Load: 23:20:04 up 9:42, 4 users, load average: 2.06, 1.26, 1.16
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Test name: sky-misc
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Varnish options:
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): -t=3600
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): -s=malloc,40M
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Varnish parameters:
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Payload size (excludes headers): 256
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Branch: master
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Number of clients involved: 24
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Type of test: httperf
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Test iterations: 1
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Runtime: 79 seconds
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Number of total connections: 100000
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Requests per connection: 50
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Extra options to httperf: --uri /
2012-05-31 21:20:04 [1, 0]: sky-misc(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 50 --num-conns 4166 --port 8080 --burst-length 50 --client 23/24 --server 10.20.100.12 --uri /
2012-05-31 21:20:11 [2, 6]: httperf-lru-nostream-default(httperf): Starting test
2012-05-31 21:23:25 WARNING [0,194]: httperf-lru-nostream-default(httperf): Panic detected. I think!
2012-05-31 21:23:25 WARNING [0, 0]: httperf-lru-nostream-default(httperf):
Last panic at: Thu, 31 May 2012 21:22:40 GMT
Assert error in VRT_r_req_restarts(), cache/cache_vrt_var.c line 365:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43bbe5: VRT_r_req_restarts+65
0x7ff014bfb388: _end+7ff014577bc0
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7ff0223f09ca: _end+7ff021d6d202
sp = 0x7ff013604720 {
fd = 26, id = 26, xid = 733419266,
client = 10.20.100.8 15178,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7ff012c9e158 {
id = "req",
{s,f,r,e} = {0x7ff012c9f730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7ff012c9e158[req]
"GET",
"/1/6/7/9/4/1.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7ff01210ec60 {
ws = 0x7ff01210ee20 {
id = "wrk",
{s,f,r,e} = {0x7ff01210e450,0x7ff01210e450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 21:23:25 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 190 stat: 44 diff: 146). Did we crash?
2012-05-31 21:23:25 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-05-31 21:23:25 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: client_req(75862) less than lower boundary 1999720
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): Load: 23:23:25 up 9:46, 4 users, load average: 9.19, 6.11, 3.07
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): Test name: httperf-lru-nostream-default
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): Varnish options:
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): -t=3600
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): -s=malloc,30M
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): Varnish parameters:
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_max=5000
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): nuke_limit=250
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_min=200
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): Payload size (excludes headers): 10K
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): Branch: master
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): Number of clients involved: 24
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): Type of test: httperf
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): Test iterations: 1
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): Runtime: 190 seconds
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): Number of total connections: 200000
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): Requests per connection: 10
2012-05-31 21:23:25 [1, 0]: httperf-lru-nostream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-05-31 21:23:25 [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-05-31 21:23:31 [2, 6]: httperf-rapid-expire(httperf): Starting test
2012-05-31 21:24:27 WARNING [0,55]: httperf-rapid-expire(httperf): Panic detected. I think!
2012-05-31 21:24:27 WARNING [0, 0]: httperf-rapid-expire(httperf):
Last panic at: Thu, 31 May 2012 21:24:13 GMT
Assert error in VRT_r_req_restarts(), cache/cache_vrt_var.c line 365:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43bbe5: VRT_r_req_restarts+65
0x7f2f859fa388: _end+7f2f85376bc0
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7f2f93bee9ca: _end+7f2f9356b202
sp = 0x7f2f84605920 {
fd = 14, id = 14, xid = 223935739,
client = 10.20.100.9 8396,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f2f84786158 {
id = "req",
{s,f,r,e} = {0x7f2f84787730,+512,(nil),+59632},
},
http[req] = {
ws = 0x7f2f84786158[req]
"GET",
"/5/7.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f2f8511ec60 {
ws = 0x7f2f8511ee20 {
id = "wrk",
{s,f,r,e} = {0x7f2f8511e450,0x7f2f8511e450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 21:24:27 WARNING [0, 0]: httperf-rapid-expire(httperf): Varnishstat uptime and measured run-time is too large (measured: 52 stat: 13 diff: 39). Did we crash?
2012-05-31 21:24:27 WARNING [0, 0]: httperf-rapid-expire(httperf): Out of bounds: client_req(18370) less than lower boundary 999640
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): Load: 23:24:27 up 9:47, 4 users, load average: 3.34, 4.99, 2.88
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): Test name: httperf-rapid-expire
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): Varnish options:
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): -t=2
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): Varnish parameters:
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): Payload size (excludes headers): 256
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): Branch: master
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): Number of clients involved: 24
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): Type of test: httperf
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): Test iterations: 1
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): Runtime: 52 seconds
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): Number of total connections: 100000
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): Requests per connection: 10
2012-05-31 21:24:27 [1, 0]: httperf-rapid-expire(httperf): Extra options to httperf: --wset=100,0.30
2012-05-31 21:24:27 [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-05-31 21:24:34 [2, 6]: streaming-grace(httperf): Starting test
2012-05-31 21:27:18 [2,163]: cold-default(httperf): Starting test
2012-05-31 21:30:16 WARNING [0,178]: cold-default(httperf): Panic detected. I think!
2012-05-31 21:30:16 WARNING [0, 0]: cold-default(httperf):
Last panic at: Thu, 31 May 2012 21:28:30 GMT
Assert error in VRT_handling(), cache/cache_vrt.c line 249:
Condition(hand == VCL_RET_OK) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x7f3801afc8e4: _end+7f380147911c
0x4394ab: VCL_hit_method+4b
0x418037: cnt_hit+1a7
0x41acfd: CNT_Session+45d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7f380fe759ca: _end+7f380f7f2202
0x7f380fbd2cdd: _end+7f380f54f515
sp = 0x7f380020d120 {
fd = 32, id = 32, xid = 844346859,
client = 10.20.100.7 5020,
step = STP_HIT,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f37ffe6b158 {
id = "req",
{s,f,r,e} = {0x7f37ffe6c730,+304,(nil),+59632},
},
http[req] = {
ws = 0x7f37ffe6b158[req]
"GET",
"/0/2/4/0/1/3/7.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.7",
},
worker = 0x7f3801e6ec60 {
ws = 0x7f3801e6ee20 {
id = "wrk",
{s,f,r,e} = {0x7f3801e6e450,0x7f3801e6e450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
obj = 0x7f37feee4a00 {
xid = 844346856,
ws = 0x7f37feee4a18 {
id = "obj",
{s,f,r,e} = {0x7f37feee4be8,+216,(nil),+248},
},
http[obj] = {
ws = 0x7f37feee4a18[obj]
"HTTP/1.1",
"OK",
"Server: nginx/0.7.65",
"Date: Thu, 31 May 2012 21:28:30 GMT",
"Content-Type: text/plain",
"Last-Modified: Thu, 31 May 2012 21:27:21 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-05-31 21:30:16 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 174 stat: 106 diff: 68). Did we crash?
2012-05-31 21:33:32 WARNING [0,195]: cold-default(httperf): Panic detected. I think!
2012-05-31 21:33:32 WARNING [0, 0]: cold-default(httperf):
Last panic at: Thu, 31 May 2012 21:31:10 GMT
Assert error in VRT_r_req_restarts(), cache/cache_vrt_var.c line 365:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43bbe5: VRT_r_req_restarts+65
0x7f3801dfa388: _end+7f3801776bc0
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7f380fe759ca: _end+7f380f7f2202
sp = 0x7f37f7f85420 {
fd = 21, id = 21, xid = 551072947,
client = 10.20.100.8 2756,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f37ef003158 {
id = "req",
{s,f,r,e} = {0x7f37ef004730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f37ef003158[req]
"GET",
"/0/1/0/1/2/9/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f38011dec60 {
ws = 0x7f38011dee20 {
id = "wrk",
{s,f,r,e} = {0x7f38011de450,0x7f38011de450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 21:33:32 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 370 stat: 141 diff: 229). Did we crash?
2012-05-31 21:33:32 WARNING [0, 0]: cold-default(httperf): Out of bounds: client_req(511410) less than lower boundary 1599640
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): Load: 23:33:32 up 9:56, 4 users, load average: 1.01, 2.17, 2.40
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): Test name: cold-default
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): Varnish options:
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): -t=3600
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): -s=malloc,10G
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): Varnish parameters:
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): Payload size (excludes headers): 256
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): Branch: master
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): Number of clients involved: 24
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): Type of test: httperf
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): Test iterations: 2
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): Runtime: 370 seconds
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): Number of total connections: 80000
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): Requests per connection: 10
2012-05-31 21:33:32 [1, 0]: cold-default(httperf): Extra options to httperf: --wset=4000000,0.50
2012-05-31 21:33:32 [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-05-31 21:33:39 [2, 6]: 4gpluss-nogzip(httperf): Starting test
2012-05-31 21:33:42 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-05-31 21:33:42 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-05-31 21:33:42 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-05-31 21:33:43 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-05-31 21:54:08 [2,1224]: purge-fail(httperf): Starting test
2012-05-31 21:59:04 WARNING [0,296]: purge-fail(httperf): Panic detected. I think!
2012-05-31 21:59:04 WARNING [0, 0]: purge-fail(httperf):
Last panic at: Thu, 31 May 2012 21:58:38 GMT
Assert error in VRT_r_req_url(), cache/cache_vrt_var.c line 85:
Condition((sp) != NULL) not true.
thread = (cache-worker)
ident = Linux,2.6.32-38-generic,x86_64,-smalloc,-smalloc,-hcritbit,epoll
Backtrace:
0x431d48: pan_ic+d8
0x43db15: VRT_r_req_url+b5
0x7fbbfbefb5cd: _end+7fbbfb877e05
0x4399fb: VCL_recv_method+4b
0x4164c5: cnt_recv+1e5
0x41ae1d: CNT_Session+57d
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7fbc09ed49ca: _end+7fbc09851202
sp = 0x7fbbfb014b20 {
fd = 16, id = 16, xid = 1832127861,
client = 10.20.100.8 25281,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fbbfae13158 {
id = "req",
{s,f,r,e} = {0x7fbbfae14730,+80,(nil),+59632},
},
http[req] = {
ws = 0x7fbbfae13158[req]
"GET",
"/1/6/8.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fbbfb3c7c60 {
ws = 0x7fbbfb3c7e20 {
id = "wrk",
{s,f,r,e} = {0x7fbbfb3c7450,0x7fbbfb3c7450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-05-31 21:59:04 WARNING [0, 0]: purge-fail(httperf): Varnishstat uptime and measured run-time is too large (measured: 292 stat: 25 diff: 267). Did we crash?
2012-05-31 21:59:05 WARNING [0, 0]: purge-fail(httperf): Out of bounds: client_req(7575) less than lower boundary 299800
2012-05-31 21:59:05 [1, 0]: purge-fail(httperf): Load: 23:59:05 up 10:21, 4 users, load average: 1.42, 1.99, 1.78
2012-05-31 21:59:05 [1, 0]: purge-fail(httperf): Test name: purge-fail
2012-05-31 21:59:05 [1, 0]: purge-fail(httperf): Varnish options:
2012-05-31 21:59:05 [1, 0]: purge-fail(httperf): Varnish parameters:
2012-05-31 21:59:05 [1, 0]: purge-fail(httperf): Payload size (excludes headers): 1K
2012-05-31 21:59:05 [1, 0]: purge-fail(httperf): Branch: master
2012-05-31 21:59:05 [1, 0]: purge-fail(httperf): Number of clients involved: 24
2012-05-31 21:59:05 [1, 0]: purge-fail(httperf): Type of test: httperf
2012-05-31 21:59:05 [1, 0]: purge-fail(httperf): Test iterations: 1
2012-05-31 21:59:05 [1, 0]: purge-fail(httperf): Runtime: 292 seconds
2012-05-31 21:59:05 [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-05-31 21:59:05 [1, 0]: purge-fail(httperf): Number of total connections: 300000
2012-05-31 21:59:05 [1, 0]: purge-fail(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-05-31 21:59:05 [1, 0]: purge-fail(httperf): Requests per connection: 1
2012-05-31 21:59:05 [1, 0]: purge-fail(httperf): Extra options to httperf: --wset=999,0.5 --timeout=5
2012-05-31 21:59:05 [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-05-31 21:59:11 [2, 6]: streaming-gzip(httperf): Starting test
2012-05-31 22:01:35 WARNING [0,144]: Tests finished with problems detected. Failed expectations: 17 Total run time: 8974 seconds
More information about the varnish-test
mailing list