[Fryer] master FAIL. 8 of 25 tests succeeded.
fryer at oneiros.varnish-software.com
fryer at oneiros.varnish-software.com
Sat Jun 2 23:59:18 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 httperf-lru-nostream-default httperf-rapid-expire streaming-grace cold-default purge-fail
Tests OK: streaming memleak 4gpluss-stream 4gpluss basic-fryer 4gpluss-nostream 4gpluss-nogzip streaming-gzip
2012-06-02 19:32:15 [1,14]: Server pantoum checked out varnish-3.0.0-beta2-1008-g2342c8b of branch master
2012-06-02 19:32:39 [2,23]: httperf-lru-nostream-gzip(httperf): Starting test
2012-06-02 19:35:44 WARNING [0,184]: httperf-lru-nostream-gzip(httperf): Panic detected. I think!
2012-06-02 19:35:44 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf):
Last panic at: Sat, 02 Jun 2012 19:35: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
0x7f8ab8cf9388: _end+7f8ab8675bc0
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
0x7f8ac6d759ca: _end+7f8ac66f2202
sp = 0x7f8aba998a20 {
fd = 17, id = 17, xid = 763327042,
client = 10.20.100.9 16457,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f8ab729b158 {
id = "req",
{s,f,r,e} = {0x7f8ab729c730,+568,(nil),+59632},
},
http[req] = {
ws = 0x7f8ab729b158[req]
"GET",
"/1/8/4/7/5/8.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f8ab711ac60 {
ws = 0x7f8ab711ae20 {
id = "wrk",
{s,f,r,e} = {0x7f8ab711a450,0x7f8ab711a450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 19:35:44 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 181 stat: 42 diff: 139). Did we crash?
2012-06-02 19:35:44 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-02 19:35:44 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: client_req(77760) less than lower boundary 1999720
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Load: 21:35:44 up 2 days, 7:58, 3 users, load average: 0.73, 0.54, 0.21
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Test name: httperf-lru-nostream-gzip
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish options:
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): -t=3600
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): -s=malloc,30M
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish parameters:
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_max=5000
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): nuke_limit=250
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): http_gzip_support=on
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_min=200
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Payload size (excludes headers): 10K
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Branch: master
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of clients involved: 24
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Type of test: httperf
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Test iterations: 1
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Runtime: 181 seconds
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of total connections: 200000
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Requests per connection: 10
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-02 19:35:44 [1, 0]: httperf-lru-nostream-gzip(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 8333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=1000000,0.1
2012-06-02 19:35:51 [2, 6]: httperf-lru-nostream-gzip-deflateoff(httperf): Starting test
2012-06-02 19:39:02 WARNING [0,190]: httperf-lru-nostream-gzip-deflateoff(httperf): Panic detected. I think!
2012-06-02 19:39:02 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf):
Last panic at: Sat, 02 Jun 2012 19:38:08 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
0x7f4fad2fa3e8: _end+7f4facc76c20
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
0x7f4fbaafb9ca: _end+7f4fba478202
sp = 0x7f4fab514520 {
fd = 19, id = 19, xid = 472808358,
client = 10.20.100.9 16900,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f4fae514158 {
id = "req",
{s,f,r,e} = {0x7f4fae515730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7f4fae514158[req]
"GET",
"/1/8/7/3/9/7.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f4faab36c60 {
ws = 0x7f4faab36e20 {
id = "wrk",
{s,f,r,e} = {0x7f4faab36450,0x7f4faab36450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 19:39:02 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnishstat uptime and measured run-time is too large (measured: 187 stat: 53 diff: 134). Did we crash?
2012-06-02 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-02 19:39:02 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: client_req(134890) less than lower boundary 1999720
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Load: 21:39:02 up 2 days, 8:01, 3 users, load average: 10.10, 4.54, 1.73
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test name: httperf-lru-nostream-gzip-deflateoff
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish options:
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -t=3600
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -s=malloc,30M
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish parameters:
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_max=5000
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): nuke_limit=250
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): http_gzip_support=on
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_min=200
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Payload size (excludes headers): 10K
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Branch: master
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of clients involved: 24
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Type of test: httperf
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test iterations: 1
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Runtime: 187 seconds
2012-06-02 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-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of total connections: 200000
2012-06-02 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-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Requests per connection: 10
2012-06-02 19:39:02 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-02 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-02 19:39:09 [2, 6]: streaming(httperf): Starting test
2012-06-02 19:41:53 [2,164]: httperf-lru-default(httperf): Starting test
2012-06-02 19:45:10 WARNING [0,196]: httperf-lru-default(httperf): Panic detected. I think!
2012-06-02 19:45:10 WARNING [0, 0]: httperf-lru-default(httperf):
Last panic at: Sat, 02 Jun 2012 19:44:36 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
0x7f51869f9388: _end+7f5186375bc0
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
0x7f5194c339ca: _end+7f51945b0202
sp = 0x7f518b213b20 {
fd = 17, id = 17, xid = 1656110760,
client = 10.20.100.8 16269,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f51858d1158 {
id = "req",
{s,f,r,e} = {0x7f51858d2730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7f51858d1158[req]
"GET",
"/1/8/1/4/0/5.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f5184faac60 {
ws = 0x7f5184faae20 {
id = "wrk",
{s,f,r,e} = {0x7f5184faa450,0x7f5184faa450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 19:45:10 WARNING [0, 0]: httperf-lru-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 192 stat: 33 diff: 159). Did we crash?
2012-06-02 19:45:10 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-02 19:45:10 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: client_req(47580) less than lower boundary 1999720
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): Load: 21:45:10 up 2 days, 8:08, 3 users, load average: 0.93, 2.41, 1.72
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): Test name: httperf-lru-default
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): Varnish options:
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): -t=3600
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): -s=malloc,30M
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): Varnish parameters:
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): thread_pool_max=5000
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): nuke_limit=250
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): thread_pool_min=200
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): Payload size (excludes headers): 10K
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): Branch: master
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): Number of clients involved: 24
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): Type of test: httperf
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): Test iterations: 1
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): Runtime: 192 seconds
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): Number of total connections: 200000
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): Requests per connection: 10
2012-06-02 19:45:10 [1, 0]: httperf-lru-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-02 19:45:10 [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-02 19:45:16 [2, 6]: memleak(httperf): Starting test
2012-06-02 19:47:26 [2,129]: 4gpluss-stream(httperf): Starting test
2012-06-02 19:47:29 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-02 19:47:29 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-02 19:47:30 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-02 19:47:30 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-02 20:07:35 [2,1205]: httperf-lru-stream-default(httperf): Starting test
2012-06-02 20:11:18 WARNING [0,222]: httperf-lru-stream-default(httperf): Panic detected. I think!
2012-06-02 20:11:18 WARNING [0, 0]: httperf-lru-stream-default(httperf):
Last panic at: Sat, 02 Jun 2012 20:10:29 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
0x7f824c1f6388: _end+7f824bb72bc0
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
0x7f8259a6a9ca: _end+7f82593e7202
sp = 0x7f8249143a20 {
fd = 21, id = 21, xid = 56777339,
client = 10.20.100.8 17999,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f824a245158 {
id = "req",
{s,f,r,e} = {0x7f824a246730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7f824a245158[req]
"GET",
"/1/9/3/4/0/3.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f8249a06c60 {
ws = 0x7f8249a06e20 {
id = "wrk",
{s,f,r,e} = {0x7f8249a06450,0x7f8249a06450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 20:11:18 WARNING [0, 0]: httperf-lru-stream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 218 stat: 47 diff: 171). Did we crash?
2012-06-02 20:11:18 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-02 20:11:18 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: client_req(51120) less than lower boundary 1999720
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): Load: 22:11:18 up 2 days, 8:34, 3 users, load average: 1.01, 0.94, 1.13
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): Test name: httperf-lru-stream-default
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): Varnish options:
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): -t=3600
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): -s=malloc,30M
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): Varnish parameters:
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_max=5000
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): nuke_limit=250
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_min=200
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): Payload size (excludes headers): 10K
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): Branch: master
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): Number of clients involved: 24
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): Type of test: httperf
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): Test iterations: 1
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): Runtime: 218 seconds
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): Number of total connections: 200000
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): Requests per connection: 10
2012-06-02 20:11:18 [1, 0]: httperf-lru-stream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-02 20:11:18 [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-02 20:11:24 [2, 6]: httperf-hot(httperf): Starting test
2012-06-02 20:12:28 WARNING [0,63]: httperf-hot(httperf): Panic detected. I think!
2012-06-02 20:12:28 WARNING [0, 0]: httperf-hot(httperf):
Last panic at: Sat, 02 Jun 2012 20:12:05 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
0x7f32499fc388: _end+7f3249378bc0
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
0x7f3257e599ca: _end+7f32577d6202
sp = 0x7f3248705f20 {
fd = 14, id = 14, xid = 1802871054,
client = 10.20.100.8 8980,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f3248535158 {
id = "req",
{s,f,r,e} = {0x7f3248536730,+232,(nil),+59632},
},
http[req] = {
ws = 0x7f3248535158[req]
"GET",
"/1/0/3.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f3248bdac60 {
ws = 0x7f3248bdae20 {
id = "wrk",
{s,f,r,e} = {0x7f3248bda450,0x7f3248bda450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 20:12:28 WARNING [0, 0]: httperf-hot(httperf): Varnishstat uptime and measured run-time is too large (measured: 60 stat: 22 diff: 38). Did we crash?
2012-06-02 20:12:28 WARNING [0, 0]: httperf-hot(httperf): Out of bounds: client_req(61230) less than lower boundary 999640
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): Load: 22:12:29 up 2 days, 8:35, 3 users, load average: 0.67, 0.86, 1.08
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): Test name: httperf-hot
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): Varnish options:
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): Varnish parameters:
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): Payload size (excludes headers): 256
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): Branch: master
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): Number of clients involved: 24
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): Type of test: httperf
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): Test iterations: 1
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): Runtime: 60 seconds
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): Number of total connections: 100000
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): Requests per connection: 10
2012-06-02 20:12:29 [1, 0]: httperf-hot(httperf): Extra options to httperf: --wset=1000,0.25
2012-06-02 20:12:29 [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-02 20:12:35 [2, 6]: httperf-lru-nostream-nogzip(httperf): Starting test
2012-06-02 20:15:50 WARNING [0,194]: httperf-lru-nostream-nogzip(httperf): Panic detected. I think!
2012-06-02 20:15:50 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf):
Last panic at: Sat, 02 Jun 2012 20:14:28 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
0x7f08a67fb919: _end+7f08a6178151
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
0x7f08b4a0e9ca: _end+7f08b438b202
sp = 0x7f08a660d520 {
fd = 31, id = 31, xid = 323603143,
client = 10.20.100.8 14485,
step = STP_FETCH,
handling = deliver,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f08a5568020 {
ws = 0x7f08a5568070 {
id = "bo",
{s,f,r,e} = {0x7f08a5569aa0,+10488,(nil),+58752},
},
do_stream
bodystatus = 4 (length),
},
http[bereq] = {
ws = 0x7f08a5568070[bo]
"GET",
"/1/5/4/8/5/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: 323603143",
},
http[beresp] = {
ws = 0x7f08a5568070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Sat, 02 Jun 2012 20:14:28 GMT",
"Content-Type: text/plain",
"Content-Length: 10240",
"Last-Modified: Sat, 02 Jun 2012 20:12:38 GMT",
"Connection: keep-alive",
"Accept-Ranges: bytes",
},
ws = 0x7f08a59d6158 {
id = "req",
{s,f,r,e} = {0x7f08a59d7730,+136,(nil),+59632},
},
http[req] = {
ws = 0x7f08a59d6158[req]
"GET",
"/1/5/4/8/5/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
worker = 0x7f08a4f1ec60 {
ws = 0x7f08a4f1ee20 {
id = "wrk",
{s,f,r,e} = {0x7f08a4f1e450,0x7f08a4f1e450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 20:15:50 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 191 stat: 81 diff: 110). Did we crash?
2012-06-02 20:15:50 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: n_lru_nuked(21821) less than lower boundary 80000
2012-06-02 20:15:50 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: client_req(246840) less than lower boundary 1999720
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): Load: 22:15:50 up 2 days, 8:38, 3 users, load average: 0.71, 0.89, 1.05
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test name: httperf-lru-nostream-nogzip
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish options:
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): -t=3600
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): -s=malloc,30M
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish parameters:
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_max=5000
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): nuke_limit=250
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): http_gzip_support=off
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_min=200
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): Payload size (excludes headers): 10K
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): Branch: master
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of clients involved: 24
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): Type of test: httperf
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test iterations: 1
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): Runtime: 191 seconds
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of total connections: 200000
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): Requests per connection: 10
2012-06-02 20:15:50 [1, 0]: httperf-lru-nostream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-02 20:15:50 [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-02 20:15:56 [2, 6]: cold-gzip(httperf): Starting test
2012-06-02 20:19:09 WARNING [0,192]: cold-gzip(httperf): Panic detected. I think!
2012-06-02 20:19:09 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Sat, 02 Jun 2012 20:17:58 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
0x7f31964fc919: _end+7f3195e79151
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
0x7f31a49119ca: _end+7f31a428e202
sp = 0x7f319b9c6c20 {
fd = 15, id = 15, xid = 76705365,
client = 10.20.100.7 6405,
step = STP_FETCH,
handling = deliver,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f3195b22020 {
ws = 0x7f3195b22070 {
id = "bo",
{s,f,r,e} = {0x7f3195b23aa0,+448,(nil),+58752},
},
do_stream
bodystatus = 3 (chunked),
},
http[bereq] = {
ws = 0x7f3195b22070[bo]
"GET",
"/0/3/3/0/6/4/3.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.7",
"X-Varnish: 76705365",
"Accept-Encoding: gzip",
},
http[beresp] = {
ws = 0x7f3195b22070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Sat, 02 Jun 2012 20:17:58 GMT",
"Content-Type: text/plain",
"Last-Modified: Sat, 02 Jun 2012 20:15:59 GMT",
"Transfer-Encoding: chunked",
"Connection: keep-alive",
"Content-Encoding: gzip",
},
ws = 0x7f31956ef158 {
id = "req",
{s,f,r,e} = {0x7f31956f0730,+216,(nil),+59632},
},
http[req] = {
ws = 0x7f31956ef158[req]
"GET",
"/0/3/3/0/6/4/3.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.7",
},
worker = 0x7f3195cbcc60 {
ws = 0x7f3195cbce20 {
id = "wrk",
{s,f,r,e} = {0x7f3195cbc450,0x7f3195cbc450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 20:19:09 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 189 stat: 70 diff: 119). Did we crash?
2012-06-02 20:22:25 WARNING [0,195]: cold-gzip(httperf): Panic detected. I think!
2012-06-02 20:22:25 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Sat, 02 Jun 2012 20:21:19 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
0x7f31964fc388: _end+7f3195e78bc0
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
0x7f31a49119ca: _end+7f31a428e202
sp = 0x7f3195205b20 {
fd = 17, id = 17, xid = 1202058425,
client = 10.20.100.9 7959,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f3194fce158 {
id = "req",
{s,f,r,e} = {0x7f3194fcf730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f3194fce158[req]
"GET",
"/0/3/7/1/8/8/1.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f3195392c60 {
ws = 0x7f3195392e20 {
id = "wrk",
{s,f,r,e} = {0x7f3195392450,0x7f3195392450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 20:22:25 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 385 stat: 65 diff: 320). Did we crash?
2012-06-02 20:22:25 WARNING [0, 0]: cold-gzip(httperf): Out of bounds: uptime(65) less than lower boundary 100
2012-06-02 20:22:25 WARNING [0, 0]: cold-gzip(httperf): Out of bounds: client_req(103840) less than lower boundary 1599640
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): Load: 22:22:25 up 2 days, 8:45, 3 users, load average: 0.68, 0.90, 1.00
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): Test name: cold-gzip
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): Varnish options:
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): -t=3600
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): -s=malloc,10G
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): Varnish parameters:
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): http_gzip_support=on
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): Payload size (excludes headers): 256
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): Branch: master
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): Number of clients involved: 24
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): Type of test: httperf
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): Test iterations: 2
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): Runtime: 385 seconds
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): Number of total connections: 80000
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): Requests per connection: 10
2012-06-02 20:22:25 [1, 0]: cold-gzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-02 20:22:25 [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-02 20:22:32 [2, 6]: 4gpluss(httperf): Starting test
2012-06-02 20:22:35 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-02 20:22:35 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-02 20:22:35 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-02 20:22:36 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-02 20:43:20 [2,1244]: httperf-lru-stream-gzip(httperf): Starting test
2012-06-02 20:47:21 WARNING [0,241]: httperf-lru-stream-gzip(httperf): Panic detected. I think!
2012-06-02 20:47:21 WARNING [0, 0]: httperf-lru-stream-gzip(httperf):
Last panic at: Sat, 02 Jun 2012 20:45: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
0x7fa6975f8388: _end+7fa696f74bc0
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
0x7fa6a58e49ca: _end+7fa6a5261202
sp = 0x7fa69b42be20 {
fd = 13, id = 13, xid = 1881229505,
client = 10.20.100.9 12291,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fa695968158 {
id = "req",
{s,f,r,e} = {0x7fa695969730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7fa695968158[req]
"GET",
"/1/3/0/8/2/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fa6958f2c60 {
ws = 0x7fa6958f2e20 {
id = "wrk",
{s,f,r,e} = {0x7fa6958f2450,0x7fa6958f2450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 20:47:21 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 237 stat: 89 diff: 148). Did we crash?
2012-06-02 20:47:22 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-02 20:47:22 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: client_req(161230) less than lower boundary 1999720
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): Load: 22:47:22 up 2 days, 9:10, 3 users, load average: 0.66, 0.90, 1.09
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): Test name: httperf-lru-stream-gzip
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish options:
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): -t=3600
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): -s=malloc,30M
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish parameters:
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_max=5000
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): nuke_limit=250
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): http_gzip_support=on
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_min=200
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): Payload size (excludes headers): 10K
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): Branch: master
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): Number of clients involved: 24
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): Type of test: httperf
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): Test iterations: 1
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): Runtime: 237 seconds
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): Number of total connections: 200000
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): Requests per connection: 10
2012-06-02 20:47:22 [1, 0]: httperf-lru-stream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-02 20:47:22 [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-02 20:47:28 [2, 6]: httperf-lru-stream-nogzip(httperf): Starting test
2012-06-02 20:50:45 WARNING [0,196]: httperf-lru-stream-nogzip(httperf): Panic detected. I think!
2012-06-02 20:50:45 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf):
Last panic at: Sat, 02 Jun 2012 20:50:09 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
0x7f6c860fb388: _end+7f6c85a77bc0
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
0x7f6c941869ca: _end+7f6c93b03202
sp = 0x7f6c8350cf20 {
fd = 19, id = 19, xid = 1763167210,
client = 10.20.100.8 17157,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f6c84046158 {
id = "req",
{s,f,r,e} = {0x7f6c84047730,+568,(nil),+59632},
},
http[req] = {
ws = 0x7f6c84046158[req]
"GET",
"/1/9/5/9/9/5.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f6c8487ac60 {
ws = 0x7f6c8487ae20 {
id = "wrk",
{s,f,r,e} = {0x7f6c8487a450,0x7f6c8487a450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 20:50:45 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 187 stat: 35 diff: 152). Did we crash?
2012-06-02 20:50:45 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: n_lru_nuked(1318) less than lower boundary 80000
2012-06-02 20:50:45 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: client_req(41890) less than lower boundary 1999720
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Load: 22:50:45 up 2 days, 9:13, 3 users, load average: 1.01, 0.99, 1.08
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Test name: httperf-lru-stream-nogzip
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish options:
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): -t=3600
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): -s=malloc,30M
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish parameters:
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_max=5000
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): nuke_limit=250
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): http_gzip_support=off
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_min=200
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Payload size (excludes headers): 10K
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Branch: master
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of clients involved: 24
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Type of test: httperf
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Test iterations: 1
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Runtime: 187 seconds
2012-06-02 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-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of total connections: 200000
2012-06-02 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-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Requests per connection: 10
2012-06-02 20:50:45 [1, 0]: httperf-lru-stream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-02 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-02 20:50:51 [2, 6]: basic-fryer(httperf): Starting test
2012-06-02 20:51:06 [2,14]: cold-nogzip(httperf): Starting test
2012-06-02 20:54:09 WARNING [0,183]: cold-nogzip(httperf): Panic detected. I think!
2012-06-02 20:54:09 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Sat, 02 Jun 2012 20:52:49 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
0x7f857befc388: _end+7f857b878bc0
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
0x7f8589ef69ca: _end+7f8589873202
sp = 0x7f85763e2a20 {
fd = 21, id = 21, xid = 1773157,
client = 10.20.100.7 5910,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f857a524158 {
id = "req",
{s,f,r,e} = {0x7f857a525730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f857a524158[req]
"GET",
"/0/3/0/1/6/9/6.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f857b488c60 {
ws = 0x7f857b488e20 {
id = "wrk",
{s,f,r,e} = {0x7f857b488450,0x7f857b488450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 20:54:09 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 180 stat: 79 diff: 101). Did we crash?
2012-06-02 20:57:22 WARNING [0,192]: cold-nogzip(httperf): Panic detected. I think!
2012-06-02 20:57:22 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Sat, 02 Jun 2012 20:55: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
0x7f857bffb388: _end+7f857b977bc0
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
0x7f8589ef69ca: _end+7f8589873202
sp = 0x7f857a60e220 {
fd = 29, id = 29, xid = 1627310799,
client = 10.20.100.8 6674,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f857ac45158 {
id = "req",
{s,f,r,e} = {0x7f857ac46730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f857ac45158[req]
"GET",
"/0/3/4/3/3/3/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f857b062c60 {
ws = 0x7f857b062e20 {
id = "wrk",
{s,f,r,e} = {0x7f857b062450,0x7f857b062450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 20:57:22 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 373 stat: 95 diff: 278). Did we crash?
2012-06-02 20:57:23 WARNING [0, 0]: cold-nogzip(httperf): Out of bounds: uptime(95) less than lower boundary 100
2012-06-02 20:57:23 WARNING [0, 0]: cold-nogzip(httperf): Out of bounds: client_req(185630) less than lower boundary 1599640
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): Load: 22:57:23 up 2 days, 9:20, 3 users, load average: 0.51, 0.77, 0.95
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): Test name: cold-nogzip
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): Varnish options:
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): -t=3600
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): -s=malloc,10G
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): Varnish parameters:
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): http_gzip_support=off
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): Payload size (excludes headers): 256
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): Branch: master
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): Number of clients involved: 24
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): Type of test: httperf
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): Test iterations: 2
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): Runtime: 373 seconds
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): Number of total connections: 80000
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): Requests per connection: 10
2012-06-02 20:57:23 [1, 0]: cold-nogzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-02 20:57:23 [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-02 20:57:29 [2, 6]: 4gpluss-nostream(httperf): Starting test
2012-06-02 20:57:32 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-02 20:57:32 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-02 20:57:32 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-02 20:57:33 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-02 21:17:11 [2,1178]: lru-random(httperf): Starting test
2012-06-02 21:17:34 WARNING [0,23]: lru-random(httperf): Panic detected. I think!
2012-06-02 21:17:34 WARNING [0, 0]: lru-random(httperf):
Last panic at: Sat, 02 Jun 2012 21:17:20 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
0x7f0f2b0fb919: _end+7f0f2aa78151
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
0x7f0f394629ca: _end+7f0f38ddf202
sp = 0x7f0f29b0c520 {
fd = 32, id = 32, xid = 901140101,
client = 10.20.100.7 1299,
step = STP_FETCH,
handling = deliver,
err_code = 502, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7f0f2a3de020 {
ws = 0x7f0f2a3de070 {
id = "bo",
{s,f,r,e} = {0x7f0f2a3dfaa0,+360,(nil),+58752},
},
do_stream
bodystatus = 4 (length),
},
http[bereq] = {
ws = 0x7f0f2a3de070[bo]
"GET",
"/0/2/7/0/6.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.7",
"X-Varnish: 901140101",
},
http[beresp] = {
ws = 0x7f0f2a3de070[bo]
"HTTP/1.1",
"502",
"Bad Gateway",
"Server: nginx/0.7.65",
"Date: Sat, 02 Jun 2012 21:17:20 GMT",
"Content-Type: text/html",
"Content-Length: 173",
"Connection: keep-alive",
},
ws = 0x7f0f2a297158 {
id = "req",
{s,f,r,e} = {0x7f0f2a298730,+288,(nil),+59632},
},
http[req] = {
ws = 0x7f0f2a297158[req]
"GET",
"/0/2/7/0/6.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.7",
},
worker = 0x7f0f2bc90c60 {
ws = 0x7f0f2bc90e20 {
id = "wrk",
{s,f,r,e} = {0x7f0f2bc90450,0x7f0f2bc90450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 21:17:35 WARNING [0, 0]: lru-random(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 3000
2012-06-02 21:17:35 WARNING [0, 0]: lru-random(httperf): Out of bounds: client_req(11049) less than lower boundary 29752
2012-06-02 21:17:35 WARNING [0, 0]: lru-random(httperf): Out of bounds: cache_hitpass(8267) more than upper boundary 0
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): Load: 23:17:35 up 2 days, 9:40, 3 users, load average: 0.60, 0.58, 0.69
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): Test name: lru-random
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): Varnish options:
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): -t=3600000
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): -s=malloc,1G
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): Varnish parameters:
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): Payload size (excludes headers): 256
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): Branch: master
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): Number of clients involved: 24
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): Type of test: httperf
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): Test iterations: 1
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): Runtime: 19 seconds
2012-06-02 21:17:35 [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-06-02 21:17:35 [1, 0]: lru-random(httperf): Number of total connections: 10000
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): Requests per connection: 3
2012-06-02 21:17:35 [1, 0]: lru-random(httperf): Extra options to httperf: --wset=100000,0.25
2012-06-02 21:17:35 [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-06-02 21:17:41 [2, 6]: siege-test(siege): Starting test
2012-06-02 21:17:49 WARNING [0, 7]: siege-test(siege): Panic detected. I think!
2012-06-02 21:17:49 WARNING [0, 0]: siege-test(siege):
Last panic at: Sat, 02 Jun 2012 21:17:45 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
0x7fc829dfc388: _end+7fc829778bc0
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
0x7fc837aae9ca: _end+7fc83742b202
sp = 0x7fc828bb3b20 {
fd = 20, id = 20, xid = 900016790,
client = 10.20.100.8 57253,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fc828b9b158 {
id = "req",
{s,f,r,e} = {0x7fc828b9c730,+152,(nil),+59632},
},
http[req] = {
ws = 0x7fc828b9b158[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 = 0x7fc8291a5c60 {
ws = 0x7fc8291a5e20 {
id = "wrk",
{s,f,r,e} = {0x7fc8291a5450,0x7fc8291a5450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 21:17:49 WARNING [0, 0]: siege-test(siege): Out of bounds: uptime(3) less than lower boundary 13
2012-06-02 21:17:49 [1, 0]: siege-test(siege): Load: 23:17:49 up 2 days, 9:40, 3 users, load average: 0.50, 0.56, 0.69
2012-06-02 21:17:49 [1, 0]: siege-test(siege): Test name: siege-test
2012-06-02 21:17:49 [1, 0]: siege-test(siege): Varnish options:
2012-06-02 21:17:49 [1, 0]: siege-test(siege): Varnish parameters:
2012-06-02 21:17:49 [1, 0]: siege-test(siege): Payload size (excludes headers): 256
2012-06-02 21:17:49 [1, 0]: siege-test(siege): Branch: master
2012-06-02 21:17:49 [1, 0]: siege-test(siege): Number of clients involved: 0
2012-06-02 21:17:49 [1, 0]: siege-test(siege): Type of test: siege
2012-06-02 21:17:49 [1, 0]: siege-test(siege): Test iterations: 1
2012-06-02 21:17:49 [1, 0]: siege-test(siege): Runtime: 4 seconds
2012-06-02 21:17:49 [1, 0]: siege-test(siege): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-02 21:17:56 [2, 6]: httperf-lru-nostream-default(httperf): Starting test
2012-06-02 21:21:31 WARNING [0,215]: httperf-lru-nostream-default(httperf): Panic detected. I think!
2012-06-02 21:21:31 WARNING [0, 0]: httperf-lru-nostream-default(httperf):
Last panic at: Sat, 02 Jun 2012 21:20:17 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
0x7fdcbccf5388: _end+7fdcbc671bc0
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
0x7fdccabef9ca: _end+7fdcca56c202
sp = 0x7fdcbb403220 {
fd = 17, id = 17, xid = 746797094,
client = 10.20.100.8 17172,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fdcc09e3158 {
id = "req",
{s,f,r,e} = {0x7fdcc09e4730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7fdcc09e3158[req]
"GET",
"/1/9/3/0/1/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fdcbb0b6c60 {
ws = 0x7fdcbb0b6e20 {
id = "wrk",
{s,f,r,e} = {0x7fdcbb0b6450,0x7fdcbb0b6450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 21:21:31 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 211 stat: 73 diff: 138). Did we crash?
2012-06-02 21:21:31 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-02 21:21:31 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: client_req(100715) less than lower boundary 1999720
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): Load: 23:21:31 up 2 days, 9:44, 3 users, load average: 2.02, 3.75, 2.18
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): Test name: httperf-lru-nostream-default
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): Varnish options:
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): -t=3600
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): -s=malloc,30M
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): Varnish parameters:
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_max=5000
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): nuke_limit=250
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_min=200
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): Payload size (excludes headers): 10K
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): Branch: master
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): Number of clients involved: 24
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): Type of test: httperf
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): Test iterations: 1
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): Runtime: 211 seconds
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): Number of total connections: 200000
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): Requests per connection: 10
2012-06-02 21:21:31 [1, 0]: httperf-lru-nostream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-02 21:21:31 [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-02 21:21:38 [2, 6]: httperf-rapid-expire(httperf): Starting test
2012-06-02 21:22:32 WARNING [0,54]: httperf-rapid-expire(httperf): Panic detected. I think!
2012-06-02 21:22:32 WARNING [0, 0]: httperf-rapid-expire(httperf):
Last panic at: Sat, 02 Jun 2012 21:22:09 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
0x7f1c5e0fb388: _end+7f1c5da77bc0
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
0x7f1c6c2de9ca: _end+7f1c6bc5b202
sp = 0x7f1c5f504b20 {
fd = 16, id = 16, xid = 1034324638,
client = 10.20.100.8 8922,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f1c5d102158 {
id = "req",
{s,f,r,e} = {0x7f1c5d103730,+224,(nil),+59632},
},
http[req] = {
ws = 0x7f1c5d102158[req]
"GET",
"/1/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f1c5d753c60 {
ws = 0x7f1c5d753e20 {
id = "wrk",
{s,f,r,e} = {0x7f1c5d753450,0x7f1c5d753450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 21:22:33 WARNING [0, 0]: httperf-rapid-expire(httperf): Out of bounds: client_req(170860) less than lower boundary 999640
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): Load: 23:22:33 up 2 days, 9:45, 3 users, load average: 1.86, 3.42, 2.17
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): Test name: httperf-rapid-expire
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): Varnish options:
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): -t=2
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): Varnish parameters:
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): Payload size (excludes headers): 256
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): Branch: master
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): Number of clients involved: 24
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): Type of test: httperf
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): Test iterations: 1
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): Runtime: 50 seconds
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): Number of total connections: 100000
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): Requests per connection: 10
2012-06-02 21:22:33 [1, 0]: httperf-rapid-expire(httperf): Extra options to httperf: --wset=100,0.30
2012-06-02 21:22:33 [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-02 21:22:39 [2, 6]: streaming-grace(httperf): Starting test
2012-06-02 21:25:16 WARNING [0,156]: streaming-grace(httperf): Panic detected. I think!
2012-06-02 21:25:16 WARNING [0, 0]: streaming-grace(httperf):
Last panic at: Sat, 02 Jun 2012 21:24:01 GMT
Assert error in VRT_l_req_grace(), cache/cache_vrt_var.c line 404:
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
0x43ead2: VRT_l_req_grace+92
0x7f92e90fc4c0: _end+7f92e8a78cf8
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
0x7f92f74849ca: _end+7f92f6e01202
sp = 0x7f92e02f4520 {
fd = 992, id = 992, xid = 488111000,
client = 10.20.100.7 4622,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f92dd068158 {
id = "req",
{s,f,r,e} = {0x7f92dd069730,+80,(nil),+59632},
},
http[req] = {
ws = 0x7f92dd068158[req]
"GET",
"/5/7/5.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f92ea843c60 {
ws = 0x7f92ea843e20 {
id = "wrk",
{s,f,r,e} = {0x7f92ea843450,0x7f92ea843450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 21:25:16 WARNING [0, 0]: streaming-grace(httperf): Varnishstat uptime and measured run-time is too large (measured: 153 stat: 74 diff: 79). Did we crash?
2012-06-02 21:25:16 WARNING [0, 0]: streaming-grace(httperf): Out of bounds: client_req(4373) less than lower boundary 9784
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Load: 23:25:16 up 2 days, 9:48, 3 users, load average: 1.06, 2.44, 2.00
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Test name: streaming-grace
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Varnish options:
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): -t=1
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Varnish parameters:
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): thread_pool_add_delay=1
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): http_gzip_support=off
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): default_grace=10
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Payload size (excludes headers): 1M
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Branch: master
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Number of clients involved: 24
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Type of test: httperf
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Test iterations: 1
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Runtime: 153 seconds
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
.connect_timeout = 10s;
}
sub vcl_recv {
set req.grace = 15s;
}
sub vcl_fetch {
set beresp.do_stream = true;
set beresp.grace = 10s;
set beresp.ttl = 15s;
}
sub vcl_deliver {
set resp.http.x-fryer = "some test";
}
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Number of total connections: 10000
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Requests per connection: 1
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Extra options to httperf: --wset=1000,0.1 --rate 3
2012-06-02 21:25:16 [1, 0]: streaming-grace(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 1 --num-conns 416 --port 8080 --burst-length 1 --client 23/24 --server 10.20.100.12 --wset=1000,0.1 --rate 3
2012-06-02 21:25:23 [2, 6]: cold-default(httperf): Starting test
2012-06-02 21:28:29 WARNING [0,185]: cold-default(httperf): Panic detected. I think!
2012-06-02 21:28:29 WARNING [0, 0]: cold-default(httperf):
Last panic at: Sat, 02 Jun 2012 21:27: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
0x7f27267fc388: _end+7f2726178bc0
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
0x7f2734c359ca: _end+7f27345b2202
sp = 0x7f2725807a20 {
fd = 25, id = 25, xid = 1940278828,
client = 10.20.100.8 7573,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f2724b71158 {
id = "req",
{s,f,r,e} = {0x7f2724b72730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f2724b71158[req]
"GET",
"/0/3/8/9/5/3/1.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f2726b3ac60 {
ws = 0x7f2726b3ae20 {
id = "wrk",
{s,f,r,e} = {0x7f2726b3a450,0x7f2726b3a450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 21:28:29 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 182 stat: 77 diff: 105). Did we crash?
2012-06-02 21:31:34 WARNING [0,185]: cold-default(httperf): Panic detected. I think!
2012-06-02 21:31:34 WARNING [0, 0]: cold-default(httperf):
Last panic at: Sat, 02 Jun 2012 21:31:16 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
0x7f27267fb388: _end+7f2726177bc0
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
0x7f2734c359ca: _end+7f27345b2202
sp = 0x7f271bfbe720 {
fd = 26, id = 26, xid = 57855284,
client = 10.20.100.7 7487,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f2725324158 {
id = "req",
{s,f,r,e} = {0x7f2725325730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7f2725324158[req]
"GET",
"/0/3/9/6/0/2/3.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f2725686c60 {
ws = 0x7f2725686e20 {
id = "wrk",
{s,f,r,e} = {0x7f2725686450,0x7f2725686450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 21:31:34 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 367 stat: 17 diff: 350). Did we crash?
2012-06-02 21:31:34 WARNING [0, 0]: cold-default(httperf): Out of bounds: uptime(17) less than lower boundary 100
2012-06-02 21:31:34 WARNING [0, 0]: cold-default(httperf): Out of bounds: client_req(17880) less than lower boundary 1599640
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): Load: 23:31:34 up 2 days, 9:54, 3 users, load average: 0.73, 1.26, 1.58
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): Test name: cold-default
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): Varnish options:
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): -t=3600
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): -s=malloc,10G
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): Varnish parameters:
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): Payload size (excludes headers): 256
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): Branch: master
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): Number of clients involved: 24
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): Type of test: httperf
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): Test iterations: 2
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): Runtime: 367 seconds
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): Number of total connections: 80000
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): Requests per connection: 10
2012-06-02 21:31:34 [1, 0]: cold-default(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-02 21:31:34 [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-02 21:31:41 [2, 6]: 4gpluss-nogzip(httperf): Starting test
2012-06-02 21:31:44 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-02 21:31:44 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-02 21:31:44 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-02 21:31:44 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-02 21:52:24 [2,1240]: purge-fail(httperf): Starting test
2012-06-02 21:56:46 WARNING [0,261]: purge-fail(httperf): Panic detected. I think!
2012-06-02 21:56:46 WARNING [0, 0]: purge-fail(httperf):
Last panic at: Sat, 02 Jun 2012 21:56:20 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
0x7f9aeb3fc5cd: _end+7f9aead78e05
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
0x7f9af96319ca: _end+7f9af8fae202
sp = 0x7f9aea302c20 {
fd = 19, id = 19, xid = 1790603353,
client = 10.20.100.9 24325,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f9aea535158 {
id = "req",
{s,f,r,e} = {0x7f9aea536730,+80,(nil),+59632},
},
http[req] = {
ws = 0x7f9aea535158[req]
"GET",
"/9/9/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f9aeaa0cc60 {
ws = 0x7f9aeaa0ce20 {
id = "wrk",
{s,f,r,e} = {0x7f9aeaa0c450,0x7f9aeaa0c450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-02 21:56:46 WARNING [0, 0]: purge-fail(httperf): Varnishstat uptime and measured run-time is too large (measured: 257 stat: 25 diff: 232). Did we crash?
2012-06-02 21:56:46 WARNING [0, 0]: purge-fail(httperf): Out of bounds: client_req(9987) less than lower boundary 299800
2012-06-02 21:56:46 [1, 0]: purge-fail(httperf): Load: 23:56:46 up 2 days, 10:19, 3 users, load average: 0.48, 1.13, 1.36
2012-06-02 21:56:46 [1, 0]: purge-fail(httperf): Test name: purge-fail
2012-06-02 21:56:46 [1, 0]: purge-fail(httperf): Varnish options:
2012-06-02 21:56:46 [1, 0]: purge-fail(httperf): Varnish parameters:
2012-06-02 21:56:46 [1, 0]: purge-fail(httperf): Payload size (excludes headers): 1K
2012-06-02 21:56:46 [1, 0]: purge-fail(httperf): Branch: master
2012-06-02 21:56:46 [1, 0]: purge-fail(httperf): Number of clients involved: 24
2012-06-02 21:56:46 [1, 0]: purge-fail(httperf): Type of test: httperf
2012-06-02 21:56:46 [1, 0]: purge-fail(httperf): Test iterations: 1
2012-06-02 21:56:46 [1, 0]: purge-fail(httperf): Runtime: 257 seconds
2012-06-02 21:56:46 [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-02 21:56:46 [1, 0]: purge-fail(httperf): Number of total connections: 300000
2012-06-02 21:56:46 [1, 0]: purge-fail(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-02 21:56:46 [1, 0]: purge-fail(httperf): Requests per connection: 1
2012-06-02 21:56:46 [1, 0]: purge-fail(httperf): Extra options to httperf: --wset=999,0.5 --timeout=5
2012-06-02 21:56:46 [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-02 21:56:52 [2, 6]: streaming-gzip(httperf): Starting test
2012-06-02 21:59:17 WARNING [0,145]: Tests finished with problems detected. Failed expectations: 17 Total run time: 8836 seconds
More information about the varnish-test
mailing list