Re: nginx keep-alive ipv6 6in4

["actionmanager" <nginx-forum@xxxxxxxx>]

nginx debug log обработки запроса от клиента

2013/10/18 20:31:23 [debug] 2127#0: epoll add event: fd:42 op:1 ev:00000001
2013/10/18 20:31:23 [debug] 2128#0: epoll add event: fd:42 op:1 ev:00000001
2013/10/18 20:31:23 [debug] 2128#0: epoll del event: fd:42 op:2 ev:00000000
2013/10/18 20:32:15 [debug] 2127#0: post event 00007F3746A7E078
2013/10/18 20:32:15 [debug] 2127#0: delete posted event 00007F3746A7E078
2013/10/18 20:32:15 [debug] 2127#0: accept on [2a01:4f8:101:4382::2]:80,
ready: 0
2013/10/18 20:32:15 [debug] 2127#0: posix_memalign: 0000000001BE3E40:256
@16
2013/10/18 20:32:15 [debug] 2127#0: *3 accept: ipv6клиента fd:47
2013/10/18 20:32:15 [debug] 2127#0: posix_memalign: 0000000001A99650:256
@16
2013/10/18 20:32:15 [debug] 2127#0: *3 event timer add: 47:
60000:1382113995050
2013/10/18 20:32:15 [debug] 2127#0: *3 reusable connection: 1
2013/10/18 20:32:15 [debug] 2127#0: *3 epoll add event: fd:47 op:1
ev:80000001
2013/10/18 20:32:15 [debug] 2127#0: *3 post event 00007F3746A7E148
2013/10/18 20:32:15 [debug] 2127#0: *3 delete posted event 00007F3746A7E148
2013/10/18 20:32:15 [debug] 2127#0: *3 http wait request handler
2013/10/18 20:32:15 [debug] 2127#0: *3 malloc: 0000000001BE5120:1024
2013/10/18 20:32:15 [debug] 2127#0: *3 recv: fd:47 567 of 1024
2013/10/18 20:32:15 [debug] 2127#0: *3 reusable connection: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 posix_memalign: 0000000001AA7490:4096
@16
2013/10/18 20:32:15 [debug] 2127#0: *3 http process request line
2013/10/18 20:32:15 [debug] 2127#0: *3 http request line: "GET / HTTP/1.1"
2013/10/18 20:32:15 [debug] 2127#0: *3 http uri: "/"
2013/10/18 20:32:15 [debug] 2127#0: *3 http args: ""
2013/10/18 20:32:15 [debug] 2127#0: *3 http exten: ""
2013/10/18 20:32:15 [debug] 2127#0: *3 http process request header line
2013/10/18 20:32:15 [debug] 2127#0: *3 http header: "Host: site.ru"
2013/10/18 20:32:15 [debug] 2127#0: *3 http header: "Connection:
keep-alive"
2013/10/18 20:32:15 [debug] 2127#0: *3 http header: "Cache-Control:
max-age=0"
2013/10/18 20:32:15 [debug] 2127#0: *3 http header: "Accept:
text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,*/*;q=0.8"
2013/10/18 20:32:15 [debug] 2127#0: *3 http header: "User-Agent: Mozilla/5.0
(Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko)
Chrome/30.0.1599.101 Safari/537.36"
2013/10/18 20:32:15 [debug] 2127#0: *3 http header: "Accept-Encoding:
gzip,deflate,sdch"
2013/10/18 20:32:15 [debug] 2127#0: *3 http header: "Accept-Language:
ru-RU,ru;q=0.8,en-US;q=0.6,en;q=0.4"
2013/10/18 20:32:15 [debug] 2127#0: *3 http header: "Cookie:
__utma=267804109.666579157.1329935757.1382103946.1382106493.432;
__utmc=267804109;
__utmz=267804109.1353650605.358.5.utmcsr=google|utmccn=(organic)|utmcmd=organic|utmctr=site.ru"
2013/10/18 20:32:15 [debug] 2127#0: *3 http header done
2013/10/18 20:32:15 [debug] 2127#0: *3 event timer del: 47: 1382113995050
2013/10/18 20:32:15 [debug] 2127#0: *3 rewrite phase: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 test location: "/"
2013/10/18 20:32:15 [debug] 2127#0: *3 test location: ~
"\.(?:ico|css|js|gif|jpe?g|png|zip|tgz|gz|rar|bz2|doc|xls|pdf|ppt|txt|tar|wav|mp3|bmp|rtf|js|swf|flv|xml|avi)$"
2013/10/18 20:32:15 [debug] 2127#0: *3 test location: ~ "/\.ht"
2013/10/18 20:32:15 [debug] 2127#0: *3 test location: ~ "\.php$"
2013/10/18 20:32:15 [debug] 2127#0: *3 using configuration "/"
2013/10/18 20:32:15 [debug] 2127#0: *3 http cl:-1 max:1048576
2013/10/18 20:32:15 [debug] 2127#0: *3 rewrite phase: 2
2013/10/18 20:32:15 [debug] 2127#0: *3 post rewrite phase: 3
2013/10/18 20:32:15 [debug] 2127#0: *3 generic phase: 4
2013/10/18 20:32:15 [debug] 2127#0: *3 generic phase: 5
2013/10/18 20:32:15 [debug] 2127#0: *3 access phase: 6
2013/10/18 20:32:15 [debug] 2127#0: *3 access phase: 7
2013/10/18 20:32:15 [debug] 2127#0: *3 post access phase: 8
2013/10/18 20:32:15 [debug] 2127#0: *3 content phase: 9
2013/10/18 20:32:15 [debug] 2127#0: *3 open index
"/home/site/www/site.ru/index.php"
2013/10/18 20:32:15 [debug] 2127#0: *3 internal redirect: "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 rewrite phase: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 test location: "/"
2013/10/18 20:32:15 [debug] 2127#0: *3 test location: "50x.html"
2013/10/18 20:32:15 [debug] 2127#0: *3 test location: ~
"\.(?:ico|css|js|gif|jpe?g|png|zip|tgz|gz|rar|bz2|doc|xls|pdf|ppt|txt|tar|wav|mp3|bmp|rtf|js|swf|flv|xml|avi)$"
2013/10/18 20:32:15 [debug] 2127#0: *3 test location: ~ "/\.ht"
2013/10/18 20:32:15 [debug] 2127#0: *3 test location: ~ "\.php$"
2013/10/18 20:32:15 [debug] 2127#0: *3 using configuration "\.php$"
2013/10/18 20:32:15 [debug] 2127#0: *3 http cl:-1 max:1048576
2013/10/18 20:32:15 [debug] 2127#0: *3 rewrite phase: 2
2013/10/18 20:32:15 [debug] 2127#0: *3 posix_memalign: 0000000001BA3500:4096
@16
2013/10/18 20:32:15 [debug] 2127#0: *3 http script complex value
2013/10/18 20:32:15 [debug] 2127#0: *3 http script var:
"/home/site/www/site.ru/index.php"
2013/10/18 20:32:15 [debug] 2127#0: *3 http script copy: "?"
2013/10/18 20:32:15 [debug] 2127#0: *3 http script file op 0000000000000001
"/home/site/www/site.ru/index.php"
2013/10/18 20:32:15 [debug] 2127#0: *3 http script file op false
2013/10/18 20:32:15 [debug] 2127#0: *3 http script if
2013/10/18 20:32:15 [debug] 2127#0: *3 http script if: false
2013/10/18 20:32:15 [debug] 2127#0: *3 post rewrite phase: 3
2013/10/18 20:32:15 [debug] 2127#0: *3 generic phase: 4
2013/10/18 20:32:15 [debug] 2127#0: *3 limit_req[0]: 0 0.000
2013/10/18 20:32:15 [debug] 2127#0: *3 generic phase: 5
2013/10/18 20:32:15 [debug] 2127#0: *3 access phase: 6
2013/10/18 20:32:15 [debug] 2127#0: *3 access phase: 7
2013/10/18 20:32:15 [debug] 2127#0: *3 post access phase: 8
2013/10/18 20:32:15 [debug] 2127#0: *3 http init upstream, client timer: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 epoll add event: fd:47 op:3
ev:80000005
2013/10/18 20:32:15 [debug] 2127#0: *3 http script copy: "Host: "
2013/10/18 20:32:15 [debug] 2127#0: *3 http script var: "site.ru"
2013/10/18 20:32:15 [debug] 2127#0: *3 http script copy: "
"
2013/10/18 20:32:15 [debug] 2127#0: *3 http script copy: "X-Real-IP: "
2013/10/18 20:32:15 [debug] 2127#0: *3 http script var: "ipv6клиента"
2013/10/18 20:32:15 [debug] 2127#0: *3 http script copy: "
"
2013/10/18 20:32:15 [debug] 2127#0: *3 http script copy: "X-Forwarded-For:
"
2013/10/18 20:32:15 [debug] 2127#0: *3 http script var: "ipv6клиента"
2013/10/18 20:32:15 [debug] 2127#0: *3 http script copy: "
"
2013/10/18 20:32:15 [debug] 2127#0: *3 http script copy: "Connection: close
"
2013/10/18 20:32:15 [debug] 2127#0: *3 http script copy: ""
2013/10/18 20:32:15 [debug] 2127#0: *3 http script copy: ""
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header: "Cache-Control:
max-age=0"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header: "Accept:
text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,*/*;q=0.8"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header: "User-Agent:
Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML, like Gecko)
Chrome/30.0.1599.101 Safari/537.36"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header: "Accept-Encoding:
gzip,deflate,sdch"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header: "Accept-Language:
ru-RU,ru;q=0.8,en-US;q=0.6,en;q=0.4"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header: "Cookie:
__utma=267804109.666579157.1329935757.1382103946.1382106493.432;
__utmc=267804109;
__utmz=267804109.1353650605.358.5.utmcsr=google|utmccn=(organic)|utmcmd=organic|utmctr=site.ru"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header:
"GET /index.php HTTP/1.0
Host: site.ru
X-Real-IP: ipv6клиента
X-Forwarded-For: ipv6клиента
Connection: close
Cache-Control: max-age=0
Accept:
text/html,application/xhtml+xml,application/xml;q=0.9,image/webp,*/*;q=0.8
User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64) AppleWebKit/537.36 (KHTML,
like Gecko) Chrome/30.0.1599.101 Safari/537.36
Accept-Encoding: gzip,deflate,sdch
Accept-Language: ru-RU,ru;q=0.8,en-US;q=0.6,en;q=0.4
Cookie: __utma=267804109.666579157.1329935757.1382103946.1382106493.432;
__utmc=267804109;
__utmz=267804109.1353650605.358.5.utmcsr=google|utmccn=(organic)|utmcmd=organic|utmctr=site.ru

"
2013/10/18 20:32:15 [debug] 2127#0: *3 http cleanup add: 0000000001BA3F28
2013/10/18 20:32:15 [debug] 2127#0: *3 get rr peer, try: 1
2013/10/18 20:32:15 [debug] 2127#0: *3 socket 48
2013/10/18 20:32:15 [debug] 2127#0: *3 epoll add connection: fd:48
ev:80000005
2013/10/18 20:32:15 [debug] 2127#0: *3 connect to 127.0.0.1:80, fd:48 #4
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream connect: -2
2013/10/18 20:32:15 [debug] 2127#0: *3 posix_memalign: 0000000001A99760:128
@16
2013/10/18 20:32:15 [debug] 2127#0: *3 event timer add: 48:
60000:1382113995051
2013/10/18 20:32:15 [debug] 2127#0: *3 http finalize request: -4,
"/index.php?" a:1, c:3
2013/10/18 20:32:15 [debug] 2127#0: *3 http request count:3 blk:0
2013/10/18 20:32:15 [debug] 2127#0: *3 http finalize request: -4,
"/index.php?" a:1, c:2
2013/10/18 20:32:15 [debug] 2127#0: *3 http request count:2 blk:0
2013/10/18 20:32:15 [debug] 2127#0: *3 post event 00007F3746569148
2013/10/18 20:32:15 [debug] 2127#0: *3 post event 00007F37465691B0
2013/10/18 20:32:15 [debug] 2127#0: *3 delete posted event 00007F37465691B0
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream request: "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream send request handler
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream send request
2013/10/18 20:32:15 [debug] 2127#0: *3 chain writer buf fl:1 s:673
2013/10/18 20:32:15 [debug] 2127#0: *3 chain writer in: 0000000001BA3F60
2013/10/18 20:32:15 [debug] 2127#0: *3 writev: 673
2013/10/18 20:32:15 [debug] 2127#0: *3 chain writer out: 0000000000000000
2013/10/18 20:32:15 [debug] 2127#0: *3 event timer del: 48: 1382113995051
2013/10/18 20:32:15 [debug] 2127#0: *3 event timer add: 48:
60000:1382113995051
2013/10/18 20:32:15 [debug] 2127#0: *3 delete posted event 00007F3746569148
2013/10/18 20:32:15 [debug] 2127#0: *3 http run request: "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream check client, write
event:1, "/index.php"
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream recv(): -1 (11:
Resource temporarily unavailable)
2013/10/18 20:32:15 [debug] 2127#0: *3 post event 00007F3746A7E1B0
2013/10/18 20:32:15 [debug] 2127#0: *3 post event 00007F37465691B0
2013/10/18 20:32:15 [debug] 2127#0: *3 delete posted event 00007F37465691B0
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream request: "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream dummy handler
2013/10/18 20:32:15 [debug] 2127#0: *3 delete posted event 00007F3746A7E1B0
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream request: "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream process header
2013/10/18 20:32:15 [debug] 2127#0: *3 malloc: 0000000001BA4510:4096
2013/10/18 20:32:15 [debug] 2127#0: *3 recv: fd:48 4096 of 4096
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy status 200 "200 OK"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header: "Date: Fri, 18 Oct
2013 16:32:15 GMT"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header: "Server: Apache"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header: "Set-Cookie:
PHPSESSID=ik9k1d0tqnddgghjt282kigfn7; path=/"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header: "Expires: Thu, 19
Nov 1981 08:52:00 GMT"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header: "Cache-Control:
no-store, no-cache, must-revalidate, post-check=0, pre-check=0"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header: "Pragma:
no-cache"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header: "Connection:
close"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header: "Content-Type:
text/html"
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy header done
2013/10/18 20:32:15 [debug] 2127#0: *3 xslt filter header
2013/10/18 20:32:15 [debug] 2127#0: *3 charset: "" > "utf-8"
2013/10/18 20:32:15 [debug] 2127#0: *3 posix_memalign: 0000000001BA5520:4096
@16
2013/10/18 20:32:15 [debug] 2127#0: *3 HTTP/1.1 200 OK
Server: nginx
Date: Fri, 18 Oct 2013 16:32:15 GMT
Content-Type: text/html; charset=utf-8
Transfer-Encoding: chunked
Connection: keep-alive
Set-Cookie: PHPSESSID=ik9k1d0tqnddgghjt282kigfn7; path=/
Expires: Thu, 19 Nov 1981 08:52:00 GMT
Cache-Control: no-store, no-cache, must-revalidate, post-check=0,
pre-check=0
Pragma: no-cache
Content-Encoding: gzip

2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:1 f:0
0000000001BA5540, pos 0000000001BA5540, size: 382 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter: l:0 f:0 s:382
2013/10/18 20:32:15 [debug] 2127#0: *3 http proxy filter init s:200 h:0 c:0
l:-1
2013/10/18 20:32:15 [debug] 2127#0: *3 tcp_nodelay
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream process non buffered
downstream
2013/10/18 20:32:15 [debug] 2127#0: *3 http output filter "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 http copy filter: "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 image filter
2013/10/18 20:32:15 [debug] 2127#0: *3 xslt filter body
2013/10/18 20:32:15 [debug] 2127#0: *3 http postpone filter "/index.php?"
0000000001BA4410
2013/10/18 20:32:15 [debug] 2127#0: *3 http gzip filter
2013/10/18 20:32:15 [debug] 2127#0: *3 malloc: 0000000001C58600:270336
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip alloc: n:1 s:5936 a:8192
p:0000000001C58600
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip alloc: n:32768 s:2 a:65536
p:0000000001C5A600
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip alloc: n:32768 s:2 a:65536
p:0000000001C6A600
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip alloc: n:32768 s:2 a:65536
p:0000000001C7A600
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip alloc: n:16384 s:4 a:65536
p:0000000001C8A600
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in: 0000000001BA44E8
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in_buf:0000000001BA4420
ni:0000000001BA4647 ai:3785
2013/10/18 20:32:15 [debug] 2127#0: *3 malloc: 0000000001BAF530:8192
2013/10/18 20:32:15 [debug] 2127#0: *3 deflate in: ni:0000000001BA4647
no:0000000001BAF530 ai:3785 ao:8192 fl:2 redo:0
2013/10/18 20:32:15 [debug] 2127#0: *3 deflate out: ni:0000000001BA5510
no:0000000001BAFC42 ai:0 ao:6382 rc:0
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in_buf:0000000001BA4420
pos:0000000001BA4647
2013/10/18 20:32:15 [debug] 2127#0: *3 http chunk: 10
2013/10/18 20:32:15 [debug] 2127#0: *3 http chunk: 1810
2013/10/18 20:32:15 [debug] 2127#0: *3 write old buf t:1 f:0
0000000001BA5540, pos 0000000001BA5540, size: 382 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:1 f:0
0000000001BA5810, pos 0000000001BA5810, size: 5 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:0 f:0
0000000000000000, pos 00000000006AEA88, size: 10 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:1 f:0
0000000001BAF530, pos 0000000001BAF530, size: 1810 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:0 f:0
0000000000000000, pos 000000000049333D, size: 2 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter: l:0 f:1 s:2209
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter limit 0
2013/10/18 20:32:15 [debug] 2127#0: *3 writev: 2209
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter 0000000000000000
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in: 0000000000000000
2013/10/18 20:32:15 [debug] 2127#0: *3 http copy filter: 0 "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 recv: fd:48 4096 of 4096
2013/10/18 20:32:15 [debug] 2127#0: *3 http output filter "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 http copy filter: "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 image filter
2013/10/18 20:32:15 [debug] 2127#0: *3 xslt filter body
2013/10/18 20:32:15 [debug] 2127#0: *3 http postpone filter "/index.php?"
0000000001BA4410
2013/10/18 20:32:15 [debug] 2127#0: *3 http gzip filter
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in: 0000000001BA5780
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in_buf:0000000001BA4420
ni:0000000001BA4510 ai:4096
2013/10/18 20:32:15 [debug] 2127#0: *3 deflate in: ni:0000000001BA4510
no:0000000001BAF530 ai:4096 ao:8192 fl:2 redo:0
2013/10/18 20:32:15 [debug] 2127#0: *3 deflate out: ni:0000000001BA5510
no:0000000001BAFA35 ai:0 ao:6907 rc:0
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in_buf:0000000001BA4420
pos:0000000001BA4510
2013/10/18 20:32:15 [debug] 2127#0: *3 http chunk: 1285
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:1 f:0
0000000001BA58C8, pos 0000000001BA58C8, size: 5 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:1 f:0
0000000001BAF530, pos 0000000001BAF530, size: 1285 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:0 f:0
0000000001BA5810, pos 000000000049333D, size: 2 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter: l:0 f:1 s:1292
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter limit 0
2013/10/18 20:32:15 [debug] 2127#0: *3 writev: 1292
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter 0000000000000000
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in: 0000000000000000
2013/10/18 20:32:15 [debug] 2127#0: *3 http copy filter: 0 "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 recv: fd:48 3693 of 4096
2013/10/18 20:32:15 [debug] 2127#0: *3 http output filter "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 http copy filter: "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 image filter
2013/10/18 20:32:15 [debug] 2127#0: *3 xslt filter body
2013/10/18 20:32:15 [debug] 2127#0: *3 http postpone filter "/index.php?"
0000000001BA4410
2013/10/18 20:32:15 [debug] 2127#0: *3 http gzip filter
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in: 0000000001BA5790
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in_buf:0000000001BA4420
ni:0000000001BA4510 ai:3693
2013/10/18 20:32:15 [debug] 2127#0: *3 deflate in: ni:0000000001BA4510
no:0000000001BAF530 ai:3693 ao:8192 fl:2 redo:0
2013/10/18 20:32:15 [debug] 2127#0: *3 deflate out: ni:0000000001BA537D
no:0000000001BAFA29 ai:0 ao:6919 rc:0
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in_buf:0000000001BA4420
pos:0000000001BA4510
2013/10/18 20:32:15 [debug] 2127#0: *3 http chunk: 1273
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:1 f:0
0000000001BA5810, pos 0000000001BA5810, size: 5 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:1 f:0
0000000001BAF530, pos 0000000001BAF530, size: 1273 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:0 f:0
0000000001BA58C8, pos 000000000049333D, size: 2 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter: l:0 f:1 s:1280
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter limit 0
2013/10/18 20:32:15 [debug] 2127#0: *3 writev: 1280
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter 0000000000000000
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in: 0000000000000000
2013/10/18 20:32:15 [debug] 2127#0: *3 http copy filter: 0 "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 recv: fd:48 -1 of 4096
2013/10/18 20:32:15 [debug] 2127#0: *3 recv() not ready (11: Resource
temporarily unavailable)
2013/10/18 20:32:15 [debug] 2127#0: *3 event timer: 48, old: 1382113995051,
new: 1382113995055
2013/10/18 20:32:15 [debug] 2127#0: *3 post event 00007F3746A7E1B0
2013/10/18 20:32:15 [debug] 2127#0: *3 post event 00007F37465691B0
2013/10/18 20:32:15 [debug] 2127#0: *3 delete posted event 00007F37465691B0
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream request: "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream dummy handler
2013/10/18 20:32:15 [debug] 2127#0: *3 delete posted event 00007F3746A7E1B0
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream request: "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 http upstream process non buffered
upstream
2013/10/18 20:32:15 [debug] 2127#0: *3 recv: fd:48 717 of 4096
2013/10/18 20:32:15 [debug] 2127#0: *3 http output filter "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 http copy filter: "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 image filter
2013/10/18 20:32:15 [debug] 2127#0: *3 xslt filter body
2013/10/18 20:32:15 [debug] 2127#0: *3 http postpone filter "/index.php?"
0000000001BA4410
2013/10/18 20:32:15 [debug] 2127#0: *3 http gzip filter
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in: 0000000001BA58A8
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in_buf:0000000001BA4420
ni:0000000001BA4510 ai:717
2013/10/18 20:32:15 [debug] 2127#0: *3 deflate in: ni:0000000001BA4510
no:0000000001BAF530 ai:717 ao:8192 fl:2 redo:0
2013/10/18 20:32:15 [debug] 2127#0: *3 deflate out: ni:0000000001BA47DD
no:0000000001BAF672 ai:0 ao:7870 rc:0
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in_buf:0000000001BA4420
pos:0000000001BA4510
2013/10/18 20:32:15 [debug] 2127#0: *3 http chunk: 322
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:1 f:0
0000000001BA58C8, pos 0000000001BA58C8, size: 5 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:1 f:0
0000000001BAF530, pos 0000000001BAF530, size: 322 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:0 f:0
0000000001BA5810, pos 000000000049333D, size: 2 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter: l:0 f:1 s:329
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter limit 0
2013/10/18 20:32:15 [debug] 2127#0: *3 writev: 329
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter 0000000000000000
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in: 0000000000000000
2013/10/18 20:32:15 [debug] 2127#0: *3 http copy filter: 0 "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 recv: fd:48 0 of 4096
2013/10/18 20:32:15 [debug] 2127#0: *3 finalize http upstream request: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 finalize http proxy request
2013/10/18 20:32:15 [debug] 2127#0: *3 free rr peer 1 0
2013/10/18 20:32:15 [debug] 2127#0: *3 close http upstream connection: 48
2013/10/18 20:32:15 [debug] 2127#0: *3 free: 0000000001A99760, unused: 48
2013/10/18 20:32:15 [debug] 2127#0: *3 event timer del: 48: 1382113995051
2013/10/18 20:32:15 [debug] 2127#0: *3 reusable connection: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 http output filter "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 http copy filter: "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 image filter
2013/10/18 20:32:15 [debug] 2127#0: *3 xslt filter body
2013/10/18 20:32:15 [debug] 2127#0: *3 http postpone filter "/index.php?"
00007FFF64CF1DA0
2013/10/18 20:32:15 [debug] 2127#0: *3 http gzip filter
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in: 0000000001BA5888
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in_buf:0000000001BA5900
ni:0000000000000000 ai:0
2013/10/18 20:32:15 [debug] 2127#0: *3 deflate in: ni:0000000000000000
no:0000000001BAF530 ai:0 ao:8192 fl:4 redo:0
2013/10/18 20:32:15 [debug] 2127#0: *3 deflate out: ni:0000000000000000
no:0000000001BAF532 ai:0 ao:8190 rc:1
2013/10/18 20:32:15 [debug] 2127#0: *3 gzip in_buf:0000000001BA5900
pos:0000000000000000
2013/10/18 20:32:15 [debug] 2127#0: *3 free: 0000000001C58600
2013/10/18 20:32:15 [debug] 2127#0: *3 http chunk: 10
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:1 f:0
0000000001BA5810, pos 0000000001BA5810, size: 3 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:1 f:0
0000000001BAF530, pos 0000000001BAF530, size: 10 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 write new buf t:0 f:0
0000000001BA58C8, pos 0000000000493338, size: 7 file: 0, size: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter: l:1 f:1 s:20
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter limit 0
2013/10/18 20:32:15 [debug] 2127#0: *3 writev: 20
2013/10/18 20:32:15 [debug] 2127#0: *3 http write filter 0000000000000000
2013/10/18 20:32:15 [debug] 2127#0: *3 http copy filter: 0 "/index.php?"
2013/10/18 20:32:15 [debug] 2127#0: *3 http finalize request: 0,
"/index.php?" a:1, c:1
2013/10/18 20:32:15 [debug] 2127#0: *3 set http keepalive handler
2013/10/18 20:32:15 [debug] 2127#0: *3 http close request
2013/10/18 20:32:15 [debug] 2127#0: *3 http log handler
2013/10/18 20:32:15 [debug] 2127#0: *3 free: 0000000001BAF530
2013/10/18 20:32:15 [debug] 2127#0: *3 free: 0000000000000000
2013/10/18 20:32:15 [debug] 2127#0: *3 free: 0000000001BA4510
2013/10/18 20:32:15 [debug] 2127#0: *3 free: 0000000001AA7490, unused: 0
2013/10/18 20:32:15 [debug] 2127#0: *3 free: 0000000001BA3500, unused: 8
2013/10/18 20:32:15 [debug] 2127#0: *3 free: 0000000001BA5520, unused: 2748
2013/10/18 20:32:15 [debug] 2127#0: *3 free: 0000000001BE5120
2013/10/18 20:32:15 [debug] 2127#0: *3 hc free: 0000000000000000 0
2013/10/18 20:32:15 [debug] 2127#0: *3 hc busy: 0000000000000000 0
2013/10/18 20:32:15 [debug] 2127#0: *3 reusable connection: 1
2013/10/18 20:32:15 [debug] 2127#0: *3 event timer add: 47:
65000:1382114000058
2013/10/18 20:32:15 [debug] 2127#0: *3 post event 00007F3746A7E148
2013/10/18 20:32:15 [debug] 2127#0: *3 delete posted event 00007F3746A7E148
2013/10/18 20:32:15 [debug] 2127#0: *3 http keepalive handler
2013/10/18 20:32:15 [debug] 2127#0: *3 malloc: 0000000001BE5120:1024
2013/10/18 20:32:15 [debug] 2127#0: *3 recv: fd:47 -1 of 1024
2013/10/18 20:32:15 [debug] 2127#0: *3 recv() not ready (11: Resource
temporarily unavailable)
2013/10/18 20:32:15 [debug] 2127#0: *3 free: 0000000001BE5120
2013/10/18 20:33:20 [debug] 2127#0: *3 event timer del: 47: 1382114000058
2013/10/18 20:33:20 [debug] 2127#0: *3 http keepalive handler
2013/10/18 20:33:20 [debug] 2127#0: *3 close http connection: 47
2013/10/18 20:33:20 [debug] 2127#0: *3 reusable connection: 0
2013/10/18 20:33:20 [debug] 2127#0: *3 free: 0000000000000000
2013/10/18 20:33:20 [debug] 2127#0: *3 free: 0000000001BE3E40, unused: 8
2013/10/18 20:33:20 [debug] 2127#0: *3 free: 0000000001A99650, unused: 72

Posted at Nginx Forum: 
http://forum.nginx.org/read.php?21,243833,243835#msg-243835

_______________________________________________
nginx-ru mailing list
nginx-ru@xxxxxxxxx
http://mailman.nginx.org/mailman/listinfo/nginx-ru