[Fryer] master FAIL. 9 of 25 tests succeeded.
fryer at oneiros.varnish-software.com
fryer at oneiros.varnish-software.com
Sun Jun 3 23:57:02 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 cold-default purge-fail
Tests OK: streaming memleak 4gpluss-stream 4gpluss basic-fryer 4gpluss-nostream streaming-grace 4gpluss-nogzip streaming-gzip
2012-06-03 19:32:15 [1,14]: Server pantoum checked out varnish-3.0.0-beta2-1008-g2342c8b of branch master
2012-06-03 19:32:39 [2,23]: httperf-lru-nostream-gzip(httperf): Starting test
2012-06-03 19:36:06 WARNING [0,207]: httperf-lru-nostream-gzip(httperf): Panic detected. I think!
2012-06-03 19:36:06 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf):
Last panic at: Sun, 03 Jun 2012 19:34:54 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
0x7fca059f5919: _end+7fca05372151
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
0x7fca139a29ca: _end+7fca1331f202
sp = 0x7fca04702c20 {
fd = 22, id = 22, xid = 1132068845,
client = 10.20.100.9 14923,
step = STP_FETCH,
handling = deliver,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7fca04013020 {
ws = 0x7fca04013070 {
id = "bo",
{s,f,r,e} = {0x7fca04014aa0,+512,(nil),+58752},
},
do_stream
bodystatus = 3 (chunked),
},
http[bereq] = {
ws = 0x7fca04013070[bo]
"GET",
"/1/5/9/1/6/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.9",
"X-Varnish: 1132068845",
"Accept-Encoding: gzip",
},
http[beresp] = {
ws = 0x7fca04013070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Sun, 03 Jun 2012 19:34:54 GMT",
"Content-Type: text/plain",
"Last-Modified: Sun, 03 Jun 2012 19:32:42 GMT",
"Transfer-Encoding: chunked",
"Connection: keep-alive",
"Content-Encoding: gzip",
},
ws = 0x7fca0480b158 {
id = "req",
{s,f,r,e} = {0x7fca0480c730,+136,(nil),+59632},
},
http[req] = {
ws = 0x7fca0480b158[req]
"GET",
"/1/5/9/1/6/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.9",
},
worker = 0x7fca04926c60 {
ws = 0x7fca04926e20 {
id = "wrk",
{s,f,r,e} = {0x7fca04926450,0x7fca04926450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 19:36:06 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 203 stat: 71 diff: 132). Did we crash?
2012-06-03 19:36:06 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-03 19:36:06 WARNING [0, 0]: httperf-lru-nostream-gzip(httperf): Out of bounds: client_req(262234) less than lower boundary 1999720
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): Load: 21:36:06 up 3 days, 7:59, 3 users, load average: 0.61, 0.49, 0.20
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): Test name: httperf-lru-nostream-gzip
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish options:
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): -t=3600
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): -s=malloc,30M
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): Varnish parameters:
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_max=5000
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): nuke_limit=250
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): http_gzip_support=on
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): thread_pool_min=200
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): Payload size (excludes headers): 10K
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): Branch: master
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of clients involved: 24
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): Type of test: httperf
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): Test iterations: 1
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): Runtime: 203 seconds
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): Number of total connections: 200000
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): Requests per connection: 10
2012-06-03 19:36:06 [1, 0]: httperf-lru-nostream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-03 19:36:06 [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-03 19:36:13 [2, 6]: httperf-lru-nostream-gzip-deflateoff(httperf): Starting test
2012-06-03 19:40:39 WARNING [0,266]: httperf-lru-nostream-gzip-deflateoff(httperf): Panic detected. I think!
2012-06-03 19:40:39 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf):
Last panic at: Sun, 03 Jun 2012 19:38:27 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
0x7f567bafc3e8: _end+7f567b478c20
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
0x7f568a06c9ca: _end+7f56899e9202
sp = 0x7f5680623a20 {
fd = 21, id = 21, xid = 284089736,
client = 10.20.100.8 6598,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f567c939158 {
id = "req",
{s,f,r,e} = {0x7f567c93a730,+488,(nil),+59632},
},
http[req] = {
ws = 0x7f567c939158[req]
"GET",
"/0/6/6/8/7/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f5679ecec60 {
ws = 0x7f5679ecee20 {
id = "wrk",
{s,f,r,e} = {0x7f5679ece450,0x7f5679ece450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 19:40:39 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnishstat uptime and measured run-time is too large (measured: 263 stat: 132 diff: 131). Did we crash?
2012-06-03 19:40:40 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-03 19:40:40 WARNING [0, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Out of bounds: client_req(216621) less than lower boundary 1999720
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Load: 21:40:40 up 3 days, 8:03, 3 users, load average: 0.26, 0.65, 0.36
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test name: httperf-lru-nostream-gzip-deflateoff
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish options:
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -t=3600
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): -s=malloc,30M
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Varnish parameters:
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_max=5000
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): nuke_limit=250
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): http_gzip_support=on
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): thread_pool_min=200
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Payload size (excludes headers): 10K
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Branch: master
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of clients involved: 24
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Type of test: httperf
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Test iterations: 1
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Runtime: 263 seconds
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
set beresp.do_gzip = true;
}
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Number of total connections: 200000
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Requests per connection: 10
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-03 19:40:40 [1, 0]: httperf-lru-nostream-gzip-deflateoff(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 8333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=1000000,0.1
2012-06-03 19:40:46 [2, 6]: streaming(httperf): Starting test
2012-06-03 19:43:29 [2,163]: httperf-lru-default(httperf): Starting test
2012-06-03 19:46:36 WARNING [0,186]: httperf-lru-default(httperf): Panic detected. I think!
2012-06-03 19:46:36 WARNING [0, 0]: httperf-lru-default(httperf):
Last panic at: Sun, 03 Jun 2012 19:45:34 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
0x7f05954f8388: _end+7f0594e74bc0
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
0x7f05a37e79ca: _end+7f05a3164202
sp = 0x7f0593782720 {
fd = 23, id = 23, xid = 1256231011,
client = 10.20.100.8 16333,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f0595f87158 {
id = "req",
{s,f,r,e} = {0x7f0595f88730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7f0595f87158[req]
"GET",
"/1/8/1/6/4/5.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f059358ac60 {
ws = 0x7f059358ae20 {
id = "wrk",
{s,f,r,e} = {0x7f059358a450,0x7f059358a450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 19:46:36 WARNING [0, 0]: httperf-lru-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 182 stat: 61 diff: 121). Did we crash?
2012-06-03 19:46:36 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-03 19:46:36 WARNING [0, 0]: httperf-lru-default(httperf): Out of bounds: client_req(171100) less than lower boundary 1999720
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Load: 21:46:36 up 3 days, 8:09, 3 users, load average: 0.91, 0.95, 0.58
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Test name: httperf-lru-default
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Varnish options:
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): -t=3600
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): -s=malloc,30M
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Varnish parameters:
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): thread_pool_max=5000
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): nuke_limit=250
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): thread_pool_min=200
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Payload size (excludes headers): 10K
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Branch: master
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Number of clients involved: 24
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Type of test: httperf
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Test iterations: 1
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Runtime: 182 seconds
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Number of total connections: 200000
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Requests per connection: 10
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-03 19:46:36 [1, 0]: httperf-lru-default(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 8333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=1000000,0.1
2012-06-03 19:46:43 [2, 6]: memleak(httperf): Starting test
2012-06-03 19:48:49 [2,126]: 4gpluss-stream(httperf): Starting test
2012-06-03 19:48:52 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-03 19:48:52 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-03 19:48:52 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-03 19:48:52 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-03 20:08:13 [2,1160]: httperf-lru-stream-default(httperf): Starting test
2012-06-03 20:11:24 WARNING [0,191]: httperf-lru-stream-default(httperf): Panic detected. I think!
2012-06-03 20:11:24 WARNING [0, 0]: httperf-lru-stream-default(httperf):
Last panic at: Sun, 03 Jun 2012 20:10: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
0x7fcbab9fc388: _end+7fcbab378bc0
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
0x7fcbb9df39ca: _end+7fcbb9770202
sp = 0x7fcbaa52c120 {
fd = 15, id = 15, xid = 396575818,
client = 10.20.100.8 17247,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fcba9502158 {
id = "req",
{s,f,r,e} = {0x7fcba9503730,+168,(nil),+59632},
},
http[req] = {
ws = 0x7fcba9502158[req]
"GET",
"/1/9/8/4/9/1.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fcba995ec60 {
ws = 0x7fcba995ee20 {
id = "wrk",
{s,f,r,e} = {0x7fcba995e450,0x7fcba995e450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 20:11:24 WARNING [0, 0]: httperf-lru-stream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 187 stat: 47 diff: 140). Did we crash?
2012-06-03 20:11:24 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-03 20:11:24 WARNING [0, 0]: httperf-lru-stream-default(httperf): Out of bounds: client_req(114290) less than lower boundary 1999720
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): Load: 22:11:24 up 3 days, 8:34, 3 users, load average: 0.90, 0.89, 0.83
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): Test name: httperf-lru-stream-default
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): Varnish options:
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): -t=3600
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): -s=malloc,30M
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): Varnish parameters:
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_max=5000
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): nuke_limit=250
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): thread_pool_min=200
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): Payload size (excludes headers): 10K
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): Branch: master
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): Number of clients involved: 24
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): Type of test: httperf
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): Test iterations: 1
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): Runtime: 187 seconds
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): Number of total connections: 200000
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): Requests per connection: 10
2012-06-03 20:11:24 [1, 0]: httperf-lru-stream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-03 20:11:24 [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-03 20:11:31 [2, 6]: httperf-hot(httperf): Starting test
2012-06-03 20:12:28 WARNING [0,57]: httperf-hot(httperf): Panic detected. I think!
2012-06-03 20:12:28 WARNING [0, 0]: httperf-hot(httperf):
Last panic at: Sun, 03 Jun 2012 20:12: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
0x7f5953cf9388: _end+7f5953675bc0
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
0x7f5961f209ca: _end+7f596189d202
sp = 0x7f5952b05320 {
fd = 19, id = 19, xid = 1401853845,
client = 10.20.100.8 7640,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f5955ba9158 {
id = "req",
{s,f,r,e} = {0x7f5955baa730,+528,(nil),+59632},
},
http[req] = {
ws = 0x7f5955ba9158[req]
"GET",
"/4/3/5.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f5953339c60 {
ws = 0x7f5953339e20 {
id = "wrk",
{s,f,r,e} = {0x7f5953339450,0x7f5953339450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 20:12:28 WARNING [0, 0]: httperf-hot(httperf): Varnishstat uptime and measured run-time is too large (measured: 53 stat: 8 diff: 45). Did we crash?
2012-06-03 20:12:28 WARNING [0, 0]: httperf-hot(httperf): Out of bounds: client_req(13800) less than lower boundary 999640
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): Load: 22:12:28 up 3 days, 8:35, 3 users, load average: 1.20, 1.01, 0.88
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): Test name: httperf-hot
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): Varnish options:
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): Varnish parameters:
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): Payload size (excludes headers): 256
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): Branch: master
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): Number of clients involved: 24
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): Type of test: httperf
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): Test iterations: 1
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): Runtime: 53 seconds
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): Number of total connections: 100000
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): Requests per connection: 10
2012-06-03 20:12:28 [1, 0]: httperf-hot(httperf): Extra options to httperf: --wset=1000,0.25
2012-06-03 20:12:28 [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-03 20:12:35 [2, 6]: httperf-lru-nostream-nogzip(httperf): Starting test
2012-06-03 20:15:33 WARNING [0,178]: httperf-lru-nostream-nogzip(httperf): Panic detected. I think!
2012-06-03 20:15:33 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf):
Last panic at: Sun, 03 Jun 2012 20:14:41 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
0x7f4a72bfc8e4: _end+7f4a7257911c
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
0x7f4a80eca9ca: _end+7f4a80847202
0x7f4a80c27cdd: _end+7f4a805a4515
sp = 0x7f4a77517020 {
fd = 23, id = 23, xid = 905621485,
client = 10.20.100.9 16321,
step = STP_HIT,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f4a717a6158 {
id = "req",
{s,f,r,e} = {0x7f4a717a7730,+296,(nil),+59632},
},
http[req] = {
ws = 0x7f4a717a6158[req]
"GET",
"/1/8/5/6/1/7.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.9",
},
worker = 0x7f4a71092c60 {
ws = 0x7f4a71092e20 {
id = "wrk",
{s,f,r,e} = {0x7f4a71092450,0x7f4a71092450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
obj = 0x7f4a6f466800 {
xid = 905621390,
ws = 0x7f4a6f466818 {
id = "obj",
{s,f,r,e} = {0x7f4a6f4669d8,+192,(nil),+224},
},
http[obj] = {
ws = 0x7f4a6f466818[obj]
"HTTP/1.1",
"OK",
"Server: nginx/0.7.65",
"Date: Sun, 03 Jun 2012 20:14:41 GMT",
"Content-Type: text/plain",
"Last-Modified: Sun, 03 Jun 2012 20:12:38 GMT",
"Content-Length: 10240",
},
len = 10240,
store = {
10240 {
4c 6f 72 65 6d 20 69 70 73 75 6d 20 64 6f 6c 6f |Lorem ipsum dolo|
72 20 73 69 74 20 61 6d 65 74 2c 20 63 6f 6e 73 |r sit amet, cons|
65 63 74 65 74 75 72 20 61 64 69 70 69 73 69 63 |ectetur adipisic|
69 6e 67 20 65 6c 69 74 2c 20 73 65 64 20 64 6f |ing elit, sed do|
[10176 more]
},
},
},
},
2012-06-03 20:15:33 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 175 stat: 51 diff: 124). Did we crash?
2012-06-03 20:15:34 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: n_lru_nuked(9320) less than lower boundary 80000
2012-06-03 20:15:34 WARNING [0, 0]: httperf-lru-nostream-nogzip(httperf): Out of bounds: client_req(121840) less than lower boundary 1999720
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Load: 22:15:34 up 3 days, 8:38, 3 users, load average: 0.76, 0.99, 0.89
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test name: httperf-lru-nostream-nogzip
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish options:
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): -t=3600
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): -s=malloc,30M
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Varnish parameters:
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_max=5000
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): nuke_limit=250
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): http_gzip_support=off
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): thread_pool_min=200
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Payload size (excludes headers): 10K
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Branch: master
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of clients involved: 24
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Type of test: httperf
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Test iterations: 1
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Runtime: 175 seconds
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Number of total connections: 200000
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Requests per connection: 10
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-03 20:15:34 [1, 0]: httperf-lru-nostream-nogzip(httperf): Httperf command (last client): httperf --hog --timeout 60 --num-calls 10 --num-conns 8333 --port 8080 --burst-length 10 --client 23/24 --server 10.20.100.12 --wset=1000000,0.1
2012-06-03 20:15:40 [2, 6]: cold-gzip(httperf): Starting test
2012-06-03 20:19:02 WARNING [0,201]: cold-gzip(httperf): Panic detected. I think!
2012-06-03 20:19:02 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Sun, 03 Jun 2012 20:17:41 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
0x7fa7a3cfc919: _end+7fa7a3679151
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
0x7fa7b1de99ca: _end+7fa7b1766202
sp = 0x7fa799924f20 {
fd = 15, id = 15, xid = 1067429398,
client = 10.20.100.7 6750,
step = STP_FETCH,
handling = deliver,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7fa7a0c6b020 {
ws = 0x7fa7a0c6b070 {
id = "bo",
{s,f,r,e} = {0x7fa7a0c6caa0,+448,(nil),+58752},
},
do_stream
bodystatus = 3 (chunked),
},
http[bereq] = {
ws = 0x7fa7a0c6b070[bo]
"GET",
"/0/3/3/6/4/0/0.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.7",
"X-Varnish: 1067429398",
"Accept-Encoding: gzip",
},
http[beresp] = {
ws = 0x7fa7a0c6b070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Sun, 03 Jun 2012 20:17:41 GMT",
"Content-Type: text/plain",
"Last-Modified: Sun, 03 Jun 2012 20:15:43 GMT",
"Transfer-Encoding: chunked",
"Connection: keep-alive",
"Content-Encoding: gzip",
},
ws = 0x7fa797a24158 {
id = "req",
{s,f,r,e} = {0x7fa797a25730,+800,(nil),+59632},
},
http[req] = {
ws = 0x7fa797a24158[req]
"GET",
"/0/3/3/6/4/0/0.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.7",
},
worker = 0x7fa7a31dcc60 {
ws = 0x7fa7a31dce20 {
id = "wrk",
{s,f,r,e} = {0x7fa7a31dc450,0x7fa7a31dc450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 20:19:02 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 197 stat: 80 diff: 117). Did we crash?
2012-06-03 20:21:46 WARNING [0,164]: cold-gzip(httperf): Panic detected. I think!
2012-06-03 20:21:46 WARNING [0, 0]: cold-gzip(httperf):
Last panic at: Sun, 03 Jun 2012 20:20: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
0x7fa7a3bf9388: _end+7fa7a3575bc0
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
0x7fa7b1de99ca: _end+7fa7b1766202
sp = 0x7fa7a3092f20 {
fd = 29, id = 29, xid = 960729794,
client = 10.20.100.8 7641,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fa7a300b158 {
id = "req",
{s,f,r,e} = {0x7fa7a300c730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7fa7a300b158[req]
"GET",
"/0/3/9/5/7/7/4.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fa7a32f9c60 {
ws = 0x7fa7a32f9e20 {
id = "wrk",
{s,f,r,e} = {0x7fa7a32f9450,0x7fa7a32f9450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 20:21:46 WARNING [0, 0]: cold-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 362 stat: 60 diff: 302). Did we crash?
2012-06-03 20:21:46 WARNING [0, 0]: cold-gzip(httperf): Out of bounds: uptime(60) less than lower boundary 100
2012-06-03 20:21:46 WARNING [0, 0]: cold-gzip(httperf): Out of bounds: client_req(94670) less than lower boundary 1599640
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): Load: 22:21:47 up 3 days, 8:44, 3 users, load average: 0.97, 1.09, 0.95
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): Test name: cold-gzip
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): Varnish options:
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): -t=3600
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): -s=malloc,10G
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): Varnish parameters:
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): http_gzip_support=on
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): Payload size (excludes headers): 256
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): Branch: master
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): Number of clients involved: 24
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): Type of test: httperf
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): Test iterations: 2
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): Runtime: 362 seconds
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): Number of total connections: 80000
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): Requests per connection: 10
2012-06-03 20:21:47 [1, 0]: cold-gzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-03 20:21:47 [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-03 20:21:53 [2, 6]: 4gpluss(httperf): Starting test
2012-06-03 20:21:56 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-03 20:21:56 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-03 20:21:56 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-03 20:21:57 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-03 20:42:45 [2,1248]: httperf-lru-stream-gzip(httperf): Starting test
2012-06-03 20:46:19 WARNING [0,214]: httperf-lru-stream-gzip(httperf): Panic detected. I think!
2012-06-03 20:46:19 WARNING [0, 0]: httperf-lru-stream-gzip(httperf):
Last panic at: Sun, 03 Jun 2012 20:45:20 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
0x7f17f8cf7388: _end+7f17f8673bc0
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
0x7f1806cd79ca: _end+7f1806654202
sp = 0x7f17fd31b620 {
fd = 19, id = 19, xid = 1975460154,
client = 10.20.100.9 15699,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f17f69c9158 {
id = "req",
{s,f,r,e} = {0x7f17f69ca730,+736,(nil),+59632},
},
http[req] = {
ws = 0x7f17f69c9158[req]
"GET",
"/1/7/9/6/4/7.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f17f6bdac60 {
ws = 0x7f17f6bdae20 {
id = "wrk",
{s,f,r,e} = {0x7f17f6bda450,0x7f17f6bda450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 20:46:19 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 210 stat: 58 diff: 152). Did we crash?
2012-06-03 20:46:19 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-03 20:46:19 WARNING [0, 0]: httperf-lru-stream-gzip(httperf): Out of bounds: client_req(127400) less than lower boundary 1999720
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): Load: 22:46:20 up 3 days, 9:09, 3 users, load average: 0.80, 0.95, 1.14
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): Test name: httperf-lru-stream-gzip
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish options:
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): -t=3600
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): -s=malloc,30M
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): Varnish parameters:
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_max=5000
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): nuke_limit=250
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): http_gzip_support=on
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): thread_pool_min=200
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): Payload size (excludes headers): 10K
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): Branch: master
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): Number of clients involved: 24
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): Type of test: httperf
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): Test iterations: 1
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): Runtime: 210 seconds
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): Number of total connections: 200000
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): Requests per connection: 10
2012-06-03 20:46:20 [1, 0]: httperf-lru-stream-gzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-03 20:46:20 [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-03 20:46:26 [2, 6]: httperf-lru-stream-nogzip(httperf): Starting test
2012-06-03 20:49:29 WARNING [0,183]: httperf-lru-stream-nogzip(httperf): Panic detected. I think!
2012-06-03 20:49:29 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf):
Last panic at: Sun, 03 Jun 2012 20:48:41 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
0x7fb50a7f9388: _end+7fb50a175bc0
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
0x7fb5189dd9ca: _end+7fb51835a202
sp = 0x7fb509602c20 {
fd = 20, id = 20, xid = 273098475,
client = 10.20.100.9 17305,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fb509559158 {
id = "req",
{s,f,r,e} = {0x7fb50955a730,+248,(nil),+59632},
},
http[req] = {
ws = 0x7fb509559158[req]
"GET",
"/1/9/1/2/8/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fb5085bec60 {
ws = 0x7fb5085bee20 {
id = "wrk",
{s,f,r,e} = {0x7fb5085be450,0x7fb5085be450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 20:49:29 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 179 stat: 47 diff: 132). Did we crash?
2012-06-03 20:49:30 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: n_lru_nuked(12736) less than lower boundary 80000
2012-06-03 20:49:30 WARNING [0, 0]: httperf-lru-stream-nogzip(httperf): Out of bounds: client_req(156010) less than lower boundary 1999720
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): Load: 22:49:30 up 3 days, 9:12, 3 users, load average: 0.93, 0.97, 1.10
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): Test name: httperf-lru-stream-nogzip
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish options:
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): -t=3600
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): -s=malloc,30M
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): Varnish parameters:
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_max=5000
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): nuke_limit=250
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): http_gzip_support=off
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): thread_pool_min=200
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): Payload size (excludes headers): 10K
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): Branch: master
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of clients involved: 24
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): Type of test: httperf
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): Test iterations: 1
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): Runtime: 179 seconds
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): Number of total connections: 200000
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): Requests per connection: 10
2012-06-03 20:49:30 [1, 0]: httperf-lru-stream-nogzip(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-03 20:49:30 [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-03 20:49:36 [2, 6]: basic-fryer(httperf): Starting test
2012-06-03 20:49:51 [2,14]: cold-nogzip(httperf): Starting test
2012-06-03 20:53:06 WARNING [0,195]: cold-nogzip(httperf): Panic detected. I think!
2012-06-03 20:53:06 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Sun, 03 Jun 2012 20:51:45 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
0x7fac18bfca1a: _end+7fac18579252
0x43928e: VCL_deliver_method+4e
0x419729: cnt_prepresp+269
0x41ac6d: CNT_Session+3cd
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7fac26f799ca: _end+7fac268f6202
0x7fac26cd6cdd: _end+7fac26653515
sp = 0x7fac17203520 {
fd = 26, id = 26, xid = 593373606,
client = 10.20.100.9 6721,
step = STP_PREPRESP,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fac16b02158 {
id = "req",
{s,f,r,e} = {0x7fac16b03730,+408,(nil),+59632},
},
http[req] = {
ws = 0x7fac16b02158[req]
"GET",
"/0/2/9/5/3/2/6.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.9",
},
http[resp] = {
ws = 0x7fac16b02158[req]
"HTTP/1.1",
"OK",
"Server: nginx/0.7.65",
"Content-Type: text/plain",
"Last-Modified: Sun, 03 Jun 2012 20:49:54 GMT",
"Content-Length: 256",
"Accept-Ranges: bytes",
"Date: Sun, 03 Jun 2012 20:51:45 GMT",
"X-Varnish: 593373606 593373575",
"Age: 0",
"Via: 1.1 varnish",
"Connection: keep-alive",
},
worker = 0x7fac182aac60 {
ws = 0x7fac182aae20 {
id = "wrk",
{s,f,r,e} = {0x7fac182aa450,0x7fac182aa450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
obj = 0x7fac1505be00 {
xid = 593373575,
ws = 0x7fac1505be18 {
id = "obj",
{s,f,r,e} = {0x7fac1505bfd8,+192,(nil),+224},
},
http[obj] = {
ws = 0x7fac1505be18[obj]
"HTTP/1.1",
"OK",
"Server: nginx/0.7.65",
"Date: Sun, 03 Jun 2012 20:51:45 GMT",
"Content-Type: text/plain",
"Last-Modified: Sun, 03 Jun 2012 20:49:54 GMT",
"Content-Length: 256",
},
len = 256,
store = {
256 {
4c 6f 72 65 6d 20 69 70 73 75 6d 20 64 6f 6c 6f |Lorem ipsum dolo|
72 20 73 69 74 20 61 6d 65 74 2c 20 63 6f 6e 73 |r sit amet, cons|
65 63 74 65 74 75 72 20 61 64 69 70 69 73 69 63 |ectetur adipisic|
69 6e 67 20 65 6c 69 74 2c 20 73 65 64 20 64 6f |ing elit, sed do|
[192 more]
},
},
},
},
2012-06-03 20:53:06 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 191 stat: 80 diff: 111). Did we crash?
2012-06-03 20:56:23 WARNING [0,197]: cold-nogzip(httperf): Panic detected. I think!
2012-06-03 20:56:23 WARNING [0, 0]: cold-nogzip(httperf):
Last panic at: Sun, 03 Jun 2012 20:54:14 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
0x7fac18cf9388: _end+7fac18675bc0
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
0x7fac26f799ca: _end+7fac268f6202
sp = 0x7fac1970f320 {
fd = 16, id = 16, xid = 509160593,
client = 10.20.100.7 5982,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fac17b89158 {
id = "req",
{s,f,r,e} = {0x7fac17b8a730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7fac17b89158[req]
"GET",
"/0/3/0/0/7/3/9.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fac17d9ec60 {
ws = 0x7fac17d9ee20 {
id = "wrk",
{s,f,r,e} = {0x7fac17d9e450,0x7fac17d9e450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 20:56:23 WARNING [0, 0]: cold-nogzip(httperf): Varnishstat uptime and measured run-time is too large (measured: 389 stat: 128 diff: 261). Did we crash?
2012-06-03 20:56:23 WARNING [0, 0]: cold-nogzip(httperf): Out of bounds: client_req(269970) less than lower boundary 1599640
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): Load: 22:56:24 up 3 days, 9:19, 3 users, load average: 0.60, 0.73, 0.93
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): Test name: cold-nogzip
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): Varnish options:
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): -t=3600
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): -s=malloc,10G
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): Varnish parameters:
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): http_gzip_support=off
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): Payload size (excludes headers): 256
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): Branch: master
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): Number of clients involved: 24
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): Type of test: httperf
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): Test iterations: 2
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): Runtime: 389 seconds
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): Number of total connections: 80000
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): Requests per connection: 10
2012-06-03 20:56:24 [1, 0]: cold-nogzip(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-03 20:56:24 [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-03 20:56:30 [2, 6]: 4gpluss-nostream(httperf): Starting test
2012-06-03 20:56:33 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-03 20:56:33 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-03 20:56:33 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-03 20:56:34 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-03 21:16:12 [2,1178]: lru-random(httperf): Starting test
2012-06-03 21:16:29 WARNING [0,16]: lru-random(httperf): Panic detected. I think!
2012-06-03 21:16:29 WARNING [0, 0]: lru-random(httperf):
Last panic at: Sun, 03 Jun 2012 21:16: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
0x7f8d7c5fc388: _end+7f8d7bf78bc0
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
0x7f8d8a7a69ca: _end+7f8d8a123202
sp = 0x7f8d7b504120 {
fd = 14, id = 14, xid = 1983023934,
client = 10.20.100.9 1684,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f8d7b635158 {
id = "req",
{s,f,r,e} = {0x7f8d7b636730,+240,(nil),+59632},
},
http[req] = {
ws = 0x7f8d7b635158[req]
"GET",
"/0/6/1/4/8.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f8d7bb28c60 {
ws = 0x7f8d7bb28e20 {
id = "wrk",
{s,f,r,e} = {0x7f8d7bb28450,0x7f8d7bb28450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 21:16:29 WARNING [0, 0]: lru-random(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 3000
2012-06-03 21:16:29 WARNING [0, 0]: lru-random(httperf): Out of bounds: client_req(738) less than lower boundary 29752
2012-06-03 21:16:29 WARNING [0, 0]: lru-random(httperf): Out of bounds: cache_hitpass(549) more than upper boundary 0
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): Load: 23:16:29 up 3 days, 9:39, 3 users, load average: 0.17, 0.40, 0.58
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): Test name: lru-random
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): Varnish options:
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): -t=3600000
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): -s=malloc,1G
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): Varnish parameters:
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): Payload size (excludes headers): 256
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): Branch: master
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): Number of clients involved: 24
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): Type of test: httperf
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): Test iterations: 1
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): Runtime: 12 seconds
2012-06-03 21:16:29 [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-03 21:16:29 [1, 0]: lru-random(httperf): Number of total connections: 10000
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): Requests per connection: 3
2012-06-03 21:16:29 [1, 0]: lru-random(httperf): Extra options to httperf: --wset=100000,0.25
2012-06-03 21:16:29 [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-03 21:16:36 [2, 6]: siege-test(siege): Starting test
2012-06-03 21:16:44 WARNING [0, 8]: siege-test(siege): Panic detected. I think!
2012-06-03 21:16:44 WARNING [0, 0]: siege-test(siege):
Last panic at: Sun, 03 Jun 2012 21:16:41 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
0x7f484dafc388: _end+7f484d478bc0
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
0x7f485bf6c9ca: _end+7f485b8e9202
sp = 0x7f484ce03520 {
fd = 19, id = 19, xid = 1448025775,
client = 10.20.100.7 56916,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f484cf8a158 {
id = "req",
{s,f,r,e} = {0x7f484cf8b730,+152,(nil),+59632},
},
http[req] = {
ws = 0x7f484cf8a158[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 = 0x7f484d2ecc60 {
ws = 0x7f484d2ece20 {
id = "wrk",
{s,f,r,e} = {0x7f484d2ec450,0x7f484d2ec450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 21:16:44 WARNING [0, 0]: siege-test(siege): Out of bounds: uptime(3) less than lower boundary 13
2012-06-03 21:16:45 [1, 0]: siege-test(siege): Load: 23:16:45 up 3 days, 9:39, 3 users, load average: 0.13, 0.38, 0.57
2012-06-03 21:16:45 [1, 0]: siege-test(siege): Test name: siege-test
2012-06-03 21:16:45 [1, 0]: siege-test(siege): Varnish options:
2012-06-03 21:16:45 [1, 0]: siege-test(siege): Varnish parameters:
2012-06-03 21:16:45 [1, 0]: siege-test(siege): Payload size (excludes headers): 256
2012-06-03 21:16:45 [1, 0]: siege-test(siege): Branch: master
2012-06-03 21:16:45 [1, 0]: siege-test(siege): Number of clients involved: 0
2012-06-03 21:16:45 [1, 0]: siege-test(siege): Type of test: siege
2012-06-03 21:16:45 [1, 0]: siege-test(siege): Test iterations: 1
2012-06-03 21:16:45 [1, 0]: siege-test(siege): Runtime: 4 seconds
2012-06-03 21:16:45 [1, 0]: siege-test(siege): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-03 21:16:51 [2, 6]: httperf-lru-nostream-default(httperf): Starting test
2012-06-03 21:20:19 WARNING [0,208]: httperf-lru-nostream-default(httperf): Panic detected. I think!
2012-06-03 21:20:19 WARNING [0, 0]: httperf-lru-nostream-default(httperf):
Last panic at: Sun, 03 Jun 2012 21:19:21 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
0x7f73bf8f9388: _end+7f73bf275bc0
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
0x7f73cdb629ca: _end+7f73cd4df202
sp = 0x7f73c150a820 {
fd = 25, id = 25, xid = 1030568657,
client = 10.20.100.9 14084,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f73be2b0158 {
id = "req",
{s,f,r,e} = {0x7f73be2b1730,+168,(nil),+59632},
},
http[req] = {
ws = 0x7f73be2b0158[req]
"GET",
"/1/5/2/8/5/7.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f73bec45c60 {
ws = 0x7f73bec45e20 {
id = "wrk",
{s,f,r,e} = {0x7f73bec45450,0x7f73bec45450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 21:20:19 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 204 stat: 57 diff: 147). Did we crash?
2012-06-03 21:20:19 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: n_lru_nuked(0) less than lower boundary 80000
2012-06-03 21:20:19 WARNING [0, 0]: httperf-lru-nostream-default(httperf): Out of bounds: client_req(124160) less than lower boundary 1999720
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): Load: 23:20:19 up 3 days, 9:43, 3 users, load average: 0.86, 0.77, 0.69
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): Test name: httperf-lru-nostream-default
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): Varnish options:
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): -t=3600
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): -s=malloc,30M
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): Varnish parameters:
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_max=5000
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): nuke_limit=250
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): thread_pool_min=200
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): Payload size (excludes headers): 10K
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): Branch: master
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): Number of clients involved: 24
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): Type of test: httperf
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): Test iterations: 1
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): Runtime: 204 seconds
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = false;
}
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): Number of total connections: 200000
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): Requests per connection: 10
2012-06-03 21:20:19 [1, 0]: httperf-lru-nostream-default(httperf): Extra options to httperf: --wset=1000000,0.1
2012-06-03 21:20:19 [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-03 21:20:26 [2, 6]: httperf-rapid-expire(httperf): Starting test
2012-06-03 21:21:20 WARNING [0,54]: httperf-rapid-expire(httperf): Panic detected. I think!
2012-06-03 21:21:20 WARNING [0, 0]: httperf-rapid-expire(httperf):
Last panic at: Sun, 03 Jun 2012 21:20:59 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
0x7f3772afc388: _end+7f3772478bc0
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
0x7f3780dff9ca: _end+7f378077c202
sp = 0x7f3776912220 {
fd = 15, id = 15, xid = 1071099986,
client = 10.20.100.8 7571,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7f377190e158 {
id = "req",
{s,f,r,e} = {0x7f377190f730,+512,(nil),+59632},
},
http[req] = {
ws = 0x7f377190e158[req]
"GET",
"/0/0.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7f377228cc60 {
ws = 0x7f377228ce20 {
id = "wrk",
{s,f,r,e} = {0x7f377228c450,0x7f377228c450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 21:21:20 WARNING [0, 0]: httperf-rapid-expire(httperf): Varnishstat uptime and measured run-time is too large (measured: 51 stat: 20 diff: 31). Did we crash?
2012-06-03 21:21:21 WARNING [0, 0]: httperf-rapid-expire(httperf): Out of bounds: client_req(91260) less than lower boundary 999640
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): Load: 23:21:21 up 3 days, 9:44, 3 users, load average: 0.59, 0.72, 0.68
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): Test name: httperf-rapid-expire
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): Varnish options:
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): -t=2
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): Varnish parameters:
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): Payload size (excludes headers): 256
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): Branch: master
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): Number of clients involved: 24
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): Type of test: httperf
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): Test iterations: 1
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): Runtime: 51 seconds
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): Number of total connections: 100000
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): Requests per connection: 10
2012-06-03 21:21:21 [1, 0]: httperf-rapid-expire(httperf): Extra options to httperf: --wset=100,0.30
2012-06-03 21:21:21 [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-03 21:21:27 [2, 6]: streaming-grace(httperf): Starting test
2012-06-03 21:24:10 [2,163]: cold-default(httperf): Starting test
2012-06-03 21:27:24 WARNING [0,193]: cold-default(httperf): Panic detected. I think!
2012-06-03 21:27:24 WARNING [0, 0]: cold-default(httperf):
Last panic at: Sun, 03 Jun 2012 21:25:35 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
0x7fabc30fb919: _end+7fabc2a78151
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
0x7fabd13379ca: _end+7fabd0cb4202
sp = 0x7fabc2705c20 {
fd = 38, id = 38, xid = 554767479,
client = 10.20.100.8 6668,
step = STP_FETCH,
handling = deliver,
err_code = 200, err_reason = (null),
restarts = 0, esi_level = 0
busyobj = 0x7fabc2002020 {
ws = 0x7fabc2002070 {
id = "bo",
{s,f,r,e} = {0x7fabc2003aa0,+448,(nil),+58752},
},
do_stream
bodystatus = 3 (chunked),
},
http[bereq] = {
ws = 0x7fabc2002070[bo]
"GET",
"/0/3/2/5/4/7/5.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
"X-Varnish: 554767479",
"Accept-Encoding: gzip",
},
http[beresp] = {
ws = 0x7fabc2002070[bo]
"HTTP/1.1",
"200",
"OK",
"Server: nginx/0.7.65",
"Date: Sun, 03 Jun 2012 21:25:35 GMT",
"Content-Type: text/plain",
"Last-Modified: Sun, 03 Jun 2012 21:24:13 GMT",
"Transfer-Encoding: chunked",
"Connection: keep-alive",
"Content-Encoding: gzip",
},
ws = 0x7fabc1ca1158 {
id = "req",
{s,f,r,e} = {0x7fabc1ca2730,+216,(nil),+59632},
},
http[req] = {
ws = 0x7fabc1ca1158[req]
"GET",
"/0/3/2/5/4/7/5.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
"X-Forwarded-For: 10.20.100.8",
},
worker = 0x7fabc28d3c60 {
ws = 0x7fabc28d3e20 {
id = "wrk",
{s,f,r,e} = {0x7fabc28d3450,0x7fabc28d3450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 21:27:24 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 189 stat: 108 diff: 81). Did we crash?
2012-06-03 21:30:22 WARNING [0,178]: cold-default(httperf): Panic detected. I think!
2012-06-03 21:30:22 WARNING [0, 0]: cold-default(httperf):
Last panic at: Sun, 03 Jun 2012 21:29: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
0x7fabc32f8388: _end+7fabc2c74bc0
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
0x7fabd13379ca: _end+7fabd0cb4202
sp = 0x7fabc2008320 {
fd = 28, id = 28, xid = 705051732,
client = 10.20.100.9 6483,
step = STP_RECV,
handling = deliver,
restarts = 0, esi_level = 0
ws = 0x7fabc1a02158 {
id = "req",
{s,f,r,e} = {0x7fabc1a03730,+256,(nil),+59632},
},
http[req] = {
ws = 0x7fabc1a02158[req]
"GET",
"/0/3/2/5/0/8/2.html",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fabc1ccec60 {
ws = 0x7fabc1ccee20 {
id = "wrk",
{s,f,r,e} = {0x7fabc1cce450,0x7fabc1cce450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
},
2012-06-03 21:30:22 WARNING [0, 0]: cold-default(httperf): Varnishstat uptime and measured run-time is too large (measured: 368 stat: 51 diff: 317). Did we crash?
2012-06-03 21:30:22 WARNING [0, 0]: cold-default(httperf): Out of bounds: uptime(51) less than lower boundary 100
2012-06-03 21:30:22 WARNING [0, 0]: cold-default(httperf): Out of bounds: client_req(51044) less than lower boundary 1599640
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): Load: 23:30:22 up 3 days, 9:53, 3 users, load average: 1.41, 1.93, 1.24
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): Test name: cold-default
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): Varnish options:
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): -t=3600
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): -s=malloc,10G
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): Varnish parameters:
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): Payload size (excludes headers): 256
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): Branch: master
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): Number of clients involved: 24
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): Type of test: httperf
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): Test iterations: 2
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): Runtime: 368 seconds
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): VCL:
backend foo {
.host = "localhost";
.port = "80";
}
sub vcl_fetch {
set beresp.do_stream = true;
}
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): Number of total connections: 80000
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): Requests per connection: 10
2012-06-03 21:30:22 [1, 0]: cold-default(httperf): Extra options to httperf: --wset=4000000,0.50
2012-06-03 21:30:22 [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-03 21:30:29 [2, 6]: 4gpluss-nogzip(httperf): Starting test
2012-06-03 21:30:32 WARNING [0, 2]: Varnish failed to start. Fallback attempts starting
2012-06-03 21:30:32 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: thread_pool_max=300
2012-06-03 21:30:32 [1, 0]: Removed (hopefully incompatible) parameter to try to start varnish. Key: sess_timeout=60000s
2012-06-03 21:30:33 [1, 0]: Fallback worked. Parameter that seemed to cause problems: sess_timeout
2012-06-03 21:51:14 [2,1241]: purge-fail(httperf): Starting test
2012-06-03 21:54:23 WARNING [0,188]: purge-fail(httperf): Panic detected. I think!
2012-06-03 21:54:23 WARNING [0, 0]: purge-fail(httperf):
Last panic at: Sun, 03 Jun 2012 21:54:01 GMT
Assert error in VRT_SetHdr(), cache/cache_vrt.c line 225:
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
0x43aded: VRT_SetHdr+1ad
0x7fdeca6fcae6: _end+7fdeca07931e
0x43917e: VCL_error_method+4e
0x41a0b6: cnt_error+2e6
0x41ab05: CNT_Session+265
0x4366cd: ses_pool_task+fd
0x433552: Pool_Work_Thread+112
0x4407f8: wrk_thread_real+c8
0x7fded8b039ca: _end+7fded8480202
sp = 0x7fdec9b03820 {
fd = 19, id = 19, xid = 833202863,
client = 10.20.100.9 23555,
step = STP_ERROR,
handling = deliver,
err_code = 200, err_reason = Not in cache but not confusing httperf,
restarts = 0, esi_level = 0
busyobj = 0x7fdebefdf020 {
ws = 0x7fdebefdf070 {
id = "bo",
{s,f,r,e} = {0x7fdebefe0aa0,0x7fdebefe0aa0,(nil),+58752},
},
do_stream
bodystatus = 0 (none),
},
ws = 0x7fdecb383158 {
id = "req",
{s,f,r,e} = {0x7fdecb384730,+96,(nil),+59632},
},
http[req] = {
ws = 0x7fdecb383158[req]
"PURGE",
"/foo",
"HTTP/1.1",
"User-Agent: httperf/0.9.0",
"Host: 10.20.100.12",
},
worker = 0x7fdec9dd8c60 {
ws = 0x7fdec9dd8e20 {
id = "wrk",
{s,f,r,e} = {0x7fdec9dd8450,0x7fdec9dd8450,(nil),+2048},
},
},
vcl = {
srcname = {
"input",
"Default",
},
},
obj = 0x7fdebfef0000 {
xid = 833202863,
ws = 0x7fdebfef0018 {
id = "obj",
{s,f,r,e} = {0x7fdebfef0548,+96,(nil),+32768},
},
http[obj] = {
ws = 0x7fdebfef0018[obj]
"HTTP/1.1",
"Not in cache but not confusing httperf",
"Date: Sun, 03 Jun 2012 21:54:01 GMT",
"Server: Varnish",
},
len = 0,
store = {
},
},
},
2012-06-03 21:54:23 WARNING [0, 0]: purge-fail(httperf): Varnishstat uptime and measured run-time is too large (measured: 184 stat: 21 diff: 163). Did we crash?
2012-06-03 21:54:23 WARNING [0, 0]: purge-fail(httperf): Out of bounds: client_req(15608) less than lower boundary 299800
2012-06-03 21:54:23 [1, 0]: purge-fail(httperf): Load: 23:54:23 up 3 days, 10:17, 3 users, load average: 0.35, 0.58, 1.04
2012-06-03 21:54:23 [1, 0]: purge-fail(httperf): Test name: purge-fail
2012-06-03 21:54:23 [1, 0]: purge-fail(httperf): Varnish options:
2012-06-03 21:54:23 [1, 0]: purge-fail(httperf): Varnish parameters:
2012-06-03 21:54:23 [1, 0]: purge-fail(httperf): Payload size (excludes headers): 1K
2012-06-03 21:54:23 [1, 0]: purge-fail(httperf): Branch: master
2012-06-03 21:54:23 [1, 0]: purge-fail(httperf): Number of clients involved: 24
2012-06-03 21:54:23 [1, 0]: purge-fail(httperf): Type of test: httperf
2012-06-03 21:54:23 [1, 0]: purge-fail(httperf): Test iterations: 1
2012-06-03 21:54:23 [1, 0]: purge-fail(httperf): Runtime: 184 seconds
2012-06-03 21:54:23 [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-03 21:54:23 [1, 0]: purge-fail(httperf): Number of total connections: 300000
2012-06-03 21:54:23 [1, 0]: purge-fail(httperf): Note: connections are subject to rounding when divided among clients. Expect slight deviations.
2012-06-03 21:54:23 [1, 0]: purge-fail(httperf): Requests per connection: 1
2012-06-03 21:54:23 [1, 0]: purge-fail(httperf): Extra options to httperf: --wset=999,0.5 --timeout=5
2012-06-03 21:54:23 [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-03 21:54:30 [2, 6]: streaming-gzip(httperf): Starting test
2012-06-03 21:57:01 WARNING [0,151]: Tests finished with problems detected. Failed expectations: 16 Total run time: 8700 seconds
More information about the varnish-test
mailing list