2014/05/06 12:55:43 [debug] 8060#0: epoll timer: 59991 2014/05/06 12:56:02 [debug] 8060#0: epoll: fd:3 ev:0005 d:00007F426406ED90 2014/05/06 12:56:02 [debug] 8060#0: *64 http keepalive handler 2014/05/06 12:56:02 [debug] 8060#0: *64 malloc: 0000000001233770:1024 2014/05/06 12:56:02 [debug] 8060#0: *64 recv: fd:3 472 of 1024 2014/05/06 12:56:02 [debug] 8060#0: *64 reusable connection: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 posix_memalign: 00000000012293B0:4096 @16 2014/05/06 12:56:02 [debug] 8060#0: *64 event timer del: 3: 1399381008048 2014/05/06 12:56:02 [debug] 8060#0: *64 http process request line 2014/05/06 12:56:02 [debug] 8060#0: *64 http request line: "POST /Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E HTTP/1.1" 2014/05/06 12:56:02 [debug] 8060#0: *64 http uri: "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E" 2014/05/06 12:56:02 [debug] 8060#0: *64 http args: "" 2014/05/06 12:56:02 [debug] 8060#0: *64 http exten: "do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E" 2014/05/06 12:56:02 [debug] 8060#0: *64 http process request header line 2014/05/06 12:56:02 [debug] 8060#0: *64 http header: "Accept: text/html, application/xhtml+xml, */*" 2014/05/06 12:56:02 [debug] 8060#0: *64 http header: "Referer: http://168.189.9.09:443/" 2014/05/06 12:56:02 [debug] 8060#0: *64 http header: "Accept-Language: en-US" 2014/05/06 12:56:02 [debug] 8060#0: *64 http header: "User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; Trident/7.0; rv:11.0) like Gecko" 2014/05/06 12:56:02 [debug] 8060#0: *64 http header: "Content-Type: application/x-www-form-urlencoded" 2014/05/06 12:56:02 [debug] 8060#0: *64 http header: "Accept-Encoding: gzip, deflate" 2014/05/06 12:56:02 [debug] 8060#0: *64 http header: "Host: 168.189.9.09:443" 2014/05/06 12:56:02 [debug] 8060#0: *64 http header: "Content-Length: 33" 2014/05/06 12:56:02 [debug] 8060#0: *64 http header: "DNT: 1" 2014/05/06 12:56:02 [debug] 8060#0: *64 http header: "Connection: Keep-Alive" 2014/05/06 12:56:02 [debug] 8060#0: *64 http header: "Cache-Control: no-cache" 2014/05/06 12:56:02 [debug] 8060#0: *64 http header done 2014/05/06 12:56:02 [debug] 8060#0: *64 generic phase: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 rewrite phase: 1 2014/05/06 12:56:02 [debug] 8060#0: *64 test location: "/" 2014/05/06 12:56:02 [debug] 8060#0: *64 test location: "50x.html" 2014/05/06 12:56:02 [debug] 8060#0: *64 using configuration "/" 2014/05/06 12:56:02 [debug] 8060#0: *64 http cl:33 max:1048576 2014/05/06 12:56:02 [debug] 8060#0: *64 rewrite phase: 3 2014/05/06 12:56:02 [debug] 8060#0: *64 post rewrite phase: 4 2014/05/06 12:56:02 [debug] 8060#0: *64 generic phase: 5 2014/05/06 12:56:02 [debug] 8060#0: *64 generic phase: 6 2014/05/06 12:56:02 [debug] 8060#0: *64 generic phase: 7 2014/05/06 12:56:02 [debug] 8060#0: *64 access phase: 8 2014/05/06 12:56:02 [debug] 8060#0: *64 access phase: 9 2014/05/06 12:56:02 [debug] 8060#0: *64 access phase: 10 2014/05/06 12:56:02 [debug] 8060#0: *64 post access phase: 11 2014/05/06 12:56:02 [debug] 8060#0: *64 posix_memalign: 0000000001221590:4096 @16 2014/05/06 12:56:02 [debug] 8060#0: *64 http client request body preread 33 2014/05/06 12:56:02 [debug] 8060#0: *64 http request body content length filter 2014/05/06 12:56:02 [debug] 8060#0: *64 http body new buf t:1 f:0 0000000001233927, pos 0000000001233927, size: 33 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 http init upstream, client timer: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 http script copy: "Host: " 2014/05/06 12:56:02 [debug] 8060#0: *64 http script var: "168.189.9.09" 2014/05/06 12:56:02 [debug] 8060#0: *64 http script copy: " " 2014/05/06 12:56:02 [debug] 8060#0: *64 http script copy: "Connection: close " 2014/05/06 12:56:02 [debug] 8060#0: *64 http script copy: "Content-Length: " 2014/05/06 12:56:02 [debug] 8060#0: *64 http script var: "33" 2014/05/06 12:56:02 [debug] 8060#0: *64 http script copy: " " 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header: "Accept: text/html, application/xhtml+xml, */*" 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header: "Referer: http://168.189.9.09:443/" 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header: "Accept-Language: en-US" 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header: "User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; Trident/7.0; rv:11.0) like Gecko" 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header: "Content-Type: application/x-www-form-urlencoded" 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header: "Accept-Encoding: gzip, deflate" 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header: "DNT: 1" 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header: "Cache-Control: no-cache" 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header: "POST /Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E HTTP/1.0 Host: 168.189.9.09 Connection: close Content-Length: 33 Accept: text/html, application/xhtml+xml, */* Referer: http://168.189.9.09:443/ Accept-Language: en-US User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; Trident/7.0; rv:11.0) like Gecko Content-Type: application/x-www-form-urlencoded Accept-Encoding: gzip, deflate DNT: 1 Cache-Control: no-cache " 2014/05/06 12:56:02 [debug] 8060#0: *64 http cleanup add: 0000000001221E88 2014/05/06 12:56:02 [debug] 8060#0: *64 get rr peer, try: 1 2014/05/06 12:56:02 [debug] 8060#0: *64 socket 13 2014/05/06 12:56:02 [debug] 8060#0: *64 epoll add connection: fd:13 ev:80002005 2014/05/06 12:56:02 [debug] 8060#0: *64 connect to 168.189.9.09:80, fd:13 #68 2014/05/06 12:56:02 [debug] 8060#0: *64 http upstream connect: -2 2014/05/06 12:56:02 [debug] 8060#0: *64 posix_memalign: 000000000121CC40:128 @16 2014/05/06 12:56:02 [debug] 8060#0: *64 event timer add: 13: 60000:1399381022908 2014/05/06 12:56:02 [debug] 8060#0: *64 http finalize request: -4, "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" a:1, c:2 2014/05/06 12:56:02 [debug] 8060#0: *64 http request count:2 blk:0 2014/05/06 12:56:02 [debug] 8060#0: *64 http run request: "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http upstream check client, write event:1, "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E" 2014/05/06 12:56:02 [debug] 8060#0: *64 http upstream recv(): -1 (11: Resource temporarily unavailable) 2014/05/06 12:56:02 [debug] 8060#0: timer delta: 19852 2014/05/06 12:56:02 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: worker cycle 2014/05/06 12:56:02 [debug] 8060#0: epoll timer: 40139 2014/05/06 12:56:02 [debug] 8060#0: epoll: fd:13 ev:0004 d:00007F426406E7A9 2014/05/06 12:56:02 [debug] 8060#0: *64 http upstream request: "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http upstream send request handler 2014/05/06 12:56:02 [debug] 8060#0: *64 http upstream send request 2014/05/06 12:56:02 [debug] 8060#0: *64 chain writer buf fl:0 s:430 2014/05/06 12:56:02 [debug] 8060#0: *64 chain writer buf fl:1 s:33 2014/05/06 12:56:02 [debug] 8060#0: *64 chain writer in: 0000000001221EE0 2014/05/06 12:56:02 [debug] 8060#0: *64 writev: 463 2014/05/06 12:56:02 [debug] 8060#0: *64 chain writer out: 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 event timer del: 13: 1399381022908 2014/05/06 12:56:02 [debug] 8060#0: *64 event timer add: 13: 60000:1399381022908 2014/05/06 12:56:02 [debug] 8060#0: timer delta: 0 2014/05/06 12:56:02 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: worker cycle 2014/05/06 12:56:02 [debug] 8060#0: epoll timer: 40139 2014/05/06 12:56:02 [debug] 8060#0: epoll: fd:13 ev:0005 d:00007F426406E7A9 2014/05/06 12:56:02 [debug] 8060#0: *64 http upstream request: "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http upstream process header 2014/05/06 12:56:02 [debug] 8060#0: *64 malloc: 00000000012225A0:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 recv: fd:13 4096 of 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy status 200 "200 OK" 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header: "Server: Apache-Coyote/1.1" 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header: "Set-Cookie: JSESSIONID=BD8F93C7799656BBC6949CFEBF33EB86; Path=/; HttpOnly" 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header: "Content-Type: text/html;charset=ISO-8859-1" 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header: "Date: Tue, 06 May 2014 12:56:02 GMT" 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header: "Connection: close" 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy header done 2014/05/06 12:56:02 [debug] 8060#0: *64 HTTP/1.1 200 OK Server: nginx/1.6.0 Date: Tue, 06 May 2014 12:56:02 GMT Content-Type: text/html;charset=ISO-8859-1 Transfer-Encoding: chunked Connection: keep-alive Set-Cookie: JSESSIONID=BD8F93C7799656BBC6949CFEBF33EB86; Path=/; HttpOnly 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 00000000012221E0, pos 00000000012221E0, size: 248 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter: l:0 f:0 s:248 2014/05/06 12:56:02 [debug] 8060#0: *64 http cacheable: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 http proxy filter init s:200 h:0 c:0 l:-1 2014/05/06 12:56:02 [debug] 8060#0: *64 http upstream process upstream 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe read upstream: 1 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe preread: 3875 2014/05/06 12:56:02 [debug] 8060#0: *64 input buf #0 2014/05/06 12:56:02 [debug] 8060#0: *64 malloc: 00000000012235B0:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 readv: 1:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe recv chain: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 input buf #1 2014/05/06 12:56:02 [debug] 8060#0: *64 malloc: 00000000012245C0:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 readv: 1:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe recv chain: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 posix_memalign: 000000000127B6A0:4096 @16 2014/05/06 12:56:02 [debug] 8060#0: *64 input buf #2 2014/05/06 12:56:02 [debug] 8060#0: *64 malloc: 000000000127C6B0:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 readv: 1:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe recv chain: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 input buf #3 2014/05/06 12:56:02 [debug] 8060#0: *64 malloc: 000000000127D6C0:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 readv: 1:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe recv chain: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 input buf #4 2014/05/06 12:56:02 [debug] 8060#0: *64 malloc: 000000000127E6D0:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 readv: 1:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe recv chain: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 input buf #5 2014/05/06 12:56:02 [debug] 8060#0: *64 malloc: 000000000127F6E0:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 readv: 1:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe recv chain: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 input buf #6 2014/05/06 12:56:02 [debug] 8060#0: *64 malloc: 00000000012806F0:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 readv: 1:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe recv chain: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 input buf #7 2014/05/06 12:56:02 [debug] 8060#0: *64 malloc: 0000000001281700:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 readv: 1:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe recv chain: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 input buf #8 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe downstream ready 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf in s:1 t:1 f:0 00000000012225A0, pos 000000000122267D, size: 3875 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf in s:1 t:1 f:0 00000000012235B0, pos 00000000012235B0, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf in s:1 t:1 f:0 00000000012245C0, pos 00000000012245C0, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf in s:1 t:1 f:0 000000000127C6B0, pos 000000000127C6B0, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf in s:1 t:1 f:0 000000000127D6C0, pos 000000000127D6C0, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf in s:1 t:1 f:0 000000000127E6D0, pos 000000000127E6D0, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf in s:1 t:1 f:0 000000000127F6E0, pos 000000000127F6E0, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf in s:1 t:1 f:0 00000000012806F0, pos 00000000012806F0, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf in s:1 t:1 f:0 0000000001281700, pos 0000000001281700, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe length: -1 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write downstream: 1 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write busy: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write buf ls:1 000000000122267D 3875 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write buf ls:1 00000000012235B0 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write buf ls:1 00000000012245C0 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write: out:00000000012223D8, f:1 2014/05/06 12:56:02 [debug] 8060#0: *64 http output filter "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http copy filter: "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http postpone filter "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 0000000001221EC0 2014/05/06 12:56:02 [debug] 8060#0: *64 http chunk: 3875 2014/05/06 12:56:02 [debug] 8060#0: *64 http chunk: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 write old buf t:1 f:0 00000000012221E0, pos 00000000012221E0, size: 248 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 000000000127BC70, pos 000000000127BC70, size: 6 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 00000000012225A0, pos 000000000122267D, size: 3875 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 00000000012235B0, pos 00000000012235B0, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:0 f:0 0000000000000000, pos 00000000004AC03D, size: 2 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter: l:0 f:1 s:8227 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter limit 0 2014/05/06 12:56:02 [debug] 8060#0: *64 writev: 8227 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 http copy filter: 0 "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write busy: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write buf ls:1 00000000012245C0 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write buf ls:1 000000000127C6B0 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write buf ls:1 000000000127D6C0 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write: out:0000000001222558, f:1 2014/05/06 12:56:02 [debug] 8060#0: *64 http output filter "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http copy filter: "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http postpone filter "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 000000000127BC00 2014/05/06 12:56:02 [debug] 8060#0: *64 http chunk: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 http chunk: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 000000000127BD28, pos 000000000127BD28, size: 6 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 00000000012245C0, pos 00000000012245C0, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 000000000127C6B0, pos 000000000127C6B0, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:0 f:0 000000000127BC70, pos 00000000004AC03D, size: 2 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter: l:0 f:1 s:8200 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter limit 0 2014/05/06 12:56:02 [debug] 8060#0: *64 writev: 8200 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 http copy filter: 0 "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write busy: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write buf ls:1 000000000127D6C0 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write buf ls:1 000000000127E6D0 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write buf ls:1 000000000127F6E0 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write: out:000000000127B810, f:1 2014/05/06 12:56:02 [debug] 8060#0: *64 http output filter "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http copy filter: "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http postpone filter "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 000000000127BD08 2014/05/06 12:56:02 [debug] 8060#0: *64 http chunk: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 http chunk: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 000000000127BC70, pos 000000000127BC70, size: 6 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 000000000127D6C0, pos 000000000127D6C0, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 000000000127E6D0, pos 000000000127E6D0, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:0 f:0 000000000127BD28, pos 00000000004AC03D, size: 2 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter: l:0 f:1 s:8200 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter limit 0 2014/05/06 12:56:02 [debug] 8060#0: *64 writev: 8200 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 http copy filter: 0 "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write busy: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write buf ls:1 000000000127F6E0 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write buf ls:1 00000000012806F0 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write buf ls:1 0000000001281700 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write: out:000000000127B990, f:1 2014/05/06 12:56:02 [debug] 8060#0: *64 http output filter "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http copy filter: "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http postpone filter "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 0000000001221ED0 2014/05/06 12:56:02 [debug] 8060#0: *64 http chunk: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 http chunk: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 000000000127BD28, pos 000000000127BD28, size: 6 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 000000000127F6E0, pos 000000000127F6E0, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 00000000012806F0, pos 00000000012806F0, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:0 f:0 000000000127BC70, pos 00000000004AC03D, size: 2 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter: l:0 f:1 s:8200 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter limit 0 2014/05/06 12:56:02 [debug] 8060#0: *64 writev: 8200 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 http copy filter: 0 "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write busy: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write buf ls:1 0000000001281700 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write: out:000000000127BB10, f:0 2014/05/06 12:56:02 [debug] 8060#0: *64 http output filter "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http copy filter: "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http postpone filter "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 000000000127BB10 2014/05/06 12:56:02 [debug] 8060#0: *64 http chunk: 4096 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 000000000127BC70, pos 000000000127BC70, size: 6 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 0000000001281700, pos 0000000001281700, size: 4096 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:0 f:0 000000000127BD28, pos 00000000004AC03D, size: 2 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter: l:0 f:1 s:4104 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter limit 0 2014/05/06 12:56:02 [debug] 8060#0: *64 writev: 4104 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 http copy filter: 0 "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write busy: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write: out:0000000000000000, f:0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe read upstream: 1 2014/05/06 12:56:02 [debug] 8060#0: *64 readv: 9:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe recv chain: 167 2014/05/06 12:56:02 [debug] 8060#0: *64 readv: 9:4096 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe recv chain: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf free s:0 t:1 f:0 0000000001281700, pos 0000000001281700, size: 167 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf free s:0 t:1 f:0 000000000127F6E0, pos 000000000127F6E0, size: 0 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf free s:0 t:1 f:0 00000000012806F0, pos 00000000012806F0, size: 0 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf free s:0 t:1 f:0 000000000127D6C0, pos 000000000127D6C0, size: 0 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf free s:0 t:1 f:0 000000000127E6D0, pos 000000000127E6D0, size: 0 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf free s:0 t:1 f:0 00000000012245C0, pos 00000000012245C0, size: 0 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf free s:0 t:1 f:0 000000000127C6B0, pos 000000000127C6B0, size: 0 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf free s:0 t:1 f:0 00000000012225A0, pos 00000000012225A0, size: 0 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe buf free s:0 t:1 f:0 00000000012235B0, pos 00000000012235B0, size: 0 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe length: -1 2014/05/06 12:56:02 [debug] 8060#0: *64 input buf #9 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 000000000127F6E0 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 00000000012806F0 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 000000000127D6C0 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 000000000127E6D0 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 00000000012245C0 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 000000000127C6B0 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 00000000012225A0 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 00000000012235B0 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write downstream: 1 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write downstream flush in 2014/05/06 12:56:02 [debug] 8060#0: *64 http output filter "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http copy filter: "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http postpone filter "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 000000000127BB10 2014/05/06 12:56:02 [debug] 8060#0: *64 http chunk: 167 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 000000000127BD28, pos 000000000127BD28, size: 4 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:1 f:0 0000000001281700, pos 0000000001281700, size: 167 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:0 f:0 000000000127BC70, pos 00000000004AC03D, size: 2 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter: l:0 f:0 s:173 2014/05/06 12:56:02 [debug] 8060#0: *64 http copy filter: 0 "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 pipe write downstream done 2014/05/06 12:56:02 [debug] 8060#0: *64 event timer: 13, old: 1399381022908, new: 1399381022920 2014/05/06 12:56:02 [debug] 8060#0: *64 http upstream exit: 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 finalize http upstream request: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 finalize http proxy request 2014/05/06 12:56:02 [debug] 8060#0: *64 free rr peer 1 0 2014/05/06 12:56:02 [debug] 8060#0: *64 close http upstream connection: 13 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 000000000121CC40, unused: 48 2014/05/06 12:56:02 [debug] 8060#0: *64 event timer del: 13: 1399381022908 2014/05/06 12:56:02 [debug] 8060#0: *64 reusable connection: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 http upstream temp fd: -1 2014/05/06 12:56:02 [debug] 8060#0: *64 http output filter "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http copy filter: "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http postpone filter "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 00007FFF214AA5B0 2014/05/06 12:56:02 [debug] 8060#0: *64 http chunk: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write old buf t:1 f:0 000000000127BD28, pos 000000000127BD28, size: 4 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write old buf t:1 f:0 0000000001281700, pos 0000000001281700, size: 167 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write old buf t:0 f:0 000000000127BC70, pos 00000000004AC03D, size: 2 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 write new buf t:0 f:0 0000000000000000, pos 00000000004AC03A, size: 5 file: 0, size: 0 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter: l:1 f:0 s:178 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter limit 0 2014/05/06 12:56:02 [debug] 8060#0: *64 writev: 178 2014/05/06 12:56:02 [debug] 8060#0: *64 http write filter 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 http copy filter: 0 "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" 2014/05/06 12:56:02 [debug] 8060#0: *64 http finalize request: 0, "/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E?" a:1, c:1 2014/05/06 12:56:02 [debug] 8060#0: *64 set http keepalive handler 2014/05/06 12:56:02 [debug] 8060#0: *64 http close request 2014/05/06 12:56:02 [debug] 8060#0: *64 http log handler 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 0000000001281700 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 00000000012293B0, unused: 2 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 0000000001221590, unused: 8 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 000000000127B6A0, unused: 1858 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 0000000001233770 2014/05/06 12:56:02 [debug] 8060#0: *64 hc free: 0000000000000000 0 2014/05/06 12:56:02 [debug] 8060#0: *64 hc busy: 0000000000000000 0 2014/05/06 12:56:02 [debug] 8060#0: *64 reusable connection: 1 2014/05/06 12:56:02 [debug] 8060#0: *64 event timer add: 3: 65000:1399381027920 2014/05/06 12:56:02 [debug] 8060#0: *64 post event 0000000001247D00 2014/05/06 12:56:02 [debug] 8060#0: timer delta: 12 2014/05/06 12:56:02 [debug] 8060#0: posted events 0000000001247D00 2014/05/06 12:56:02 [debug] 8060#0: posted event 0000000001247D00 2014/05/06 12:56:02 [debug] 8060#0: *64 delete posted event 0000000001247D00 2014/05/06 12:56:02 [debug] 8060#0: *64 http keepalive handler 2014/05/06 12:56:02 [debug] 8060#0: *64 malloc: 0000000001233770:1024 2014/05/06 12:56:02 [debug] 8060#0: *64 recv: fd:3 -1 of 1024 2014/05/06 12:56:02 [debug] 8060#0: *64 recv() not ready (11: Resource temporarily unavailable) 2014/05/06 12:56:02 [debug] 8060#0: *64 free: 0000000001233770 2014/05/06 12:56:02 [debug] 8060#0: posted event 0000000000000000 2014/05/06 12:56:02 [debug] 8060#0: worker cycle 2014/05/06 12:56:02 [debug] 8060#0: epoll timer: 40127 2014/05/06 12:56:05 [debug] 8060#0: epoll: fd:6 ev:0001 d:00007F426406E010 2014/05/06 12:56:05 [debug] 8060#0: accept on 0.0.0.0:6401, ready: 0 2014/05/06 12:56:05 [debug] 8060#0: posix_memalign: 0000000001233470:256 @16 2014/05/06 12:56:05 [debug] 8060#0: *69 accept: 14.97.50.74 fd:13 2014/05/06 12:56:05 [debug] 8060#0: posix_memalign: 0000000001233770:256 @16 2014/05/06 12:56:05 [debug] 8060#0: *69 event timer add: 13: 60000:1399381025326 2014/05/06 12:56:05 [debug] 8060#0: *69 reusable connection: 1 2014/05/06 12:56:05 [debug] 8060#0: *69 epoll add event: fd:13 op:1 ev:80002001 2014/05/06 12:56:05 [debug] 8060#0: timer delta: 2406 2014/05/06 12:56:05 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:56:05 [debug] 8060#0: worker cycle 2014/05/06 12:56:05 [debug] 8060#0: epoll timer: 37721 2014/05/06 12:56:05 [debug] 8060#0: epoll: fd:13 ev:0001 d:00007F426406E7A8 2014/05/06 12:56:05 [debug] 8060#0: *69 http wait request handler 2014/05/06 12:56:05 [debug] 8060#0: *69 malloc: 0000000001233880:1024 2014/05/06 12:56:05 [debug] 8060#0: *69 recv: fd:13 402 of 1024 2014/05/06 12:56:05 [debug] 8060#0: *69 reusable connection: 0 2014/05/06 12:56:05 [debug] 8060#0: *69 posix_memalign: 00000000012293B0:4096 @16 2014/05/06 12:56:05 [debug] 8060#0: *69 http process request line 2014/05/06 12:56:05 [debug] 8060#0: *69 http request line: "GET /Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202 HTTP/1.1" 2014/05/06 12:56:05 [debug] 8060#0: *69 http uri: "/Stream.html" 2014/05/06 12:56:05 [debug] 8060#0: *69 http args: "s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 2014/05/06 12:56:05 [debug] 8060#0: *69 http exten: "html" 2014/05/06 12:56:05 [debug] 8060#0: *69 http process request header line 2014/05/06 12:56:05 [debug] 8060#0: *69 http header: "Accept: text/html, application/xhtml+xml, */*" 2014/05/06 12:56:05 [debug] 8060#0: *69 http header: "Referer: http://168.189.9.09:443/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E" 2014/05/06 12:56:05 [debug] 8060#0: *69 http header: "Accept-Language: en-US" 2014/05/06 12:56:05 [debug] 8060#0: *69 http header: "User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; Trident/7.0; rv:11.0) like Gecko" 2014/05/06 12:56:05 [debug] 8060#0: *69 http header: "Accept-Encoding: gzip, deflate" 2014/05/06 12:56:05 [debug] 8060#0: *69 http header: "Host: 168.189.9.09:6401" 2014/05/06 12:56:05 [debug] 8060#0: *69 http header: "DNT: 1" 2014/05/06 12:56:05 [debug] 8060#0: *69 http header: "Connection: Keep-Alive" 2014/05/06 12:56:05 [debug] 8060#0: *69 http header done 2014/05/06 12:56:05 [debug] 8060#0: *69 event timer del: 13: 1399381025326 2014/05/06 12:56:05 [debug] 8060#0: *69 generic phase: 0 2014/05/06 12:56:05 [debug] 8060#0: *69 rewrite phase: 1 2014/05/06 12:56:05 [debug] 8060#0: *69 test location: "/" 2014/05/06 12:56:05 [debug] 8060#0: *69 test location: "50x.html" 2014/05/06 12:56:05 [debug] 8060#0: *69 using configuration "/" 2014/05/06 12:56:05 [debug] 8060#0: *69 http cl:-1 max:1048576 2014/05/06 12:56:05 [debug] 8060#0: *69 rewrite phase: 3 2014/05/06 12:56:05 [debug] 8060#0: *69 post rewrite phase: 4 2014/05/06 12:56:05 [debug] 8060#0: *69 generic phase: 5 2014/05/06 12:56:05 [debug] 8060#0: *69 generic phase: 6 2014/05/06 12:56:05 [debug] 8060#0: *69 generic phase: 7 2014/05/06 12:56:05 [debug] 8060#0: *69 access phase: 8 2014/05/06 12:56:05 [debug] 8060#0: *69 access phase: 9 2014/05/06 12:56:05 [debug] 8060#0: *69 access phase: 10 2014/05/06 12:56:05 [debug] 8060#0: *69 post access phase: 11 2014/05/06 12:56:05 [debug] 8060#0: *69 posix_memalign: 0000000001221590:4096 @16 2014/05/06 12:56:05 [debug] 8060#0: *69 http init upstream, client timer: 0 2014/05/06 12:56:05 [debug] 8060#0: *69 epoll add event: fd:13 op:3 ev:80002005 2014/05/06 12:56:05 [debug] 8060#0: *69 http script copy: "Host: " 2014/05/06 12:56:05 [debug] 8060#0: *69 http script var: "168.189.9.09:6400" 2014/05/06 12:56:05 [debug] 8060#0: *69 http script copy: " " 2014/05/06 12:56:05 [debug] 8060#0: *69 http script copy: "Connection: close " 2014/05/06 12:56:05 [debug] 8060#0: *69 http script copy: "" 2014/05/06 12:56:05 [debug] 8060#0: *69 http script copy: "" 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy header: "Accept: text/html, application/xhtml+xml, */*" 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy header: "Referer: http://168.189.9.09:443/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E" 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy header: "Accept-Language: en-US" 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy header: "User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; Trident/7.0; rv:11.0) like Gecko" 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy header: "Accept-Encoding: gzip, deflate" 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy header: "DNT: 1" 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy header: "GET /Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202 HTTP/1.0 Host: 168.189.9.09:6400 Connection: close Accept: text/html, application/xhtml+xml, */* Referer: http://168.189.9.09:443/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E Accept-Language: en-US User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; Trident/7.0; rv:11.0) like Gecko Accept-Encoding: gzip, deflate DNT: 1 " 2014/05/06 12:56:05 [debug] 8060#0: *69 http cleanup add: 0000000001221D60 2014/05/06 12:56:05 [debug] 8060#0: *69 get rr peer, try: 1 2014/05/06 12:56:05 [debug] 8060#0: *69 socket 14 2014/05/06 12:56:05 [debug] 8060#0: *69 epoll add connection: fd:14 ev:80002005 2014/05/06 12:56:05 [debug] 8060#0: *69 connect to 168.189.9.09:6400, fd:14 #70 2014/05/06 12:56:05 [debug] 8060#0: *69 http upstream connect: -2 2014/05/06 12:56:05 [debug] 8060#0: *69 posix_memalign: 000000000121CC40:128 @16 2014/05/06 12:56:05 [debug] 8060#0: *69 event timer add: 14: 60000:1399381025380 2014/05/06 12:56:05 [debug] 8060#0: *69 http finalize request: -4, "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" a:1, c:2 2014/05/06 12:56:05 [debug] 8060#0: *69 http request count:2 blk:0 2014/05/06 12:56:05 [debug] 8060#0: timer delta: 54 2014/05/06 12:56:05 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:56:05 [debug] 8060#0: worker cycle 2014/05/06 12:56:05 [debug] 8060#0: epoll timer: 37667 2014/05/06 12:56:05 [debug] 8060#0: epoll: fd:13 ev:0004 d:00007F426406E7A8 2014/05/06 12:56:05 [debug] 8060#0: *69 http run request: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 2014/05/06 12:56:05 [debug] 8060#0: *69 http upstream check client, write event:1, "/Stream.html" 2014/05/06 12:56:05 [debug] 8060#0: *69 http upstream recv(): -1 (11: Resource temporarily unavailable) 2014/05/06 12:56:05 [debug] 8060#0: epoll: fd:14 ev:0004 d:00007F426406E5F9 2014/05/06 12:56:05 [debug] 8060#0: *69 http upstream request: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 2014/05/06 12:56:05 [debug] 8060#0: *69 http upstream send request handler 2014/05/06 12:56:05 [debug] 8060#0: *69 http upstream send request 2014/05/06 12:56:05 [debug] 8060#0: *69 chain writer buf fl:1 s:397 2014/05/06 12:56:05 [debug] 8060#0: *69 chain writer in: 0000000001221D98 2014/05/06 12:56:05 [debug] 8060#0: *69 writev: 397 2014/05/06 12:56:05 [debug] 8060#0: *69 chain writer out: 0000000000000000 2014/05/06 12:56:05 [debug] 8060#0: *69 event timer del: 14: 1399381025380 2014/05/06 12:56:05 [debug] 8060#0: *69 event timer add: 14: 60000:1399381025381 2014/05/06 12:56:05 [debug] 8060#0: timer delta: 1 2014/05/06 12:56:05 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:56:05 [debug] 8060#0: worker cycle 2014/05/06 12:56:05 [debug] 8060#0: epoll timer: 37666 2014/05/06 12:56:05 [debug] 8060#0: epoll: fd:14 ev:0005 d:00007F426406E5F9 2014/05/06 12:56:05 [debug] 8060#0: *69 http upstream request: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 2014/05/06 12:56:05 [debug] 8060#0: *69 http upstream process header 2014/05/06 12:56:05 [debug] 8060#0: *69 malloc: 00000000012225A0:4096 2014/05/06 12:56:05 [debug] 8060#0: *69 recv: fd:14 1821 of 4096 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy status 200 "200 OK" 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy header: "Content-Type: text/html; charset=UTF-8" 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy header: "Cache-Control: no-store, no-cache, private" 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy header: "Transfer-Encoding: chunked" 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy header: "Connection: close" 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy header done 2014/05/06 12:56:05 [debug] 8060#0: *69 HTTP/1.1 200 OK Server: nginx/1.6.0 Date: Tue, 06 May 2014 12:56:05 GMT Content-Type: text/html; charset=UTF-8 Transfer-Encoding: chunked Connection: keep-alive Cache-Control: no-store, no-cache, private 2014/05/06 12:56:05 [debug] 8060#0: *69 write new buf t:1 f:0 0000000001222070, pos 0000000001222070, size: 213 file: 0, size: 0 2014/05/06 12:56:05 [debug] 8060#0: *69 http write filter: l:0 f:0 s:213 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy filter init s:200 h:0 c:1 l:-1 2014/05/06 12:56:05 [debug] 8060#0: *69 tcp_nodelay 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 30 s:0 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 31 s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 64 s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 63 s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0D s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0A s:3 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 3C s:4 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy out buf 000000000122263C 476 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0D s:5 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0A s:6 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 30 s:0 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 30 s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 32 s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 66 s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0D s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0A s:3 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 3C s:4 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy out buf 0000000001222820 47 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0D s:5 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0A s:6 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 30 s:0 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 34 s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 30 s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 32 s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0D s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0A s:3 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 3C s:4 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy out buf 0000000001222857 1026 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0D s:5 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0A s:6 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 30 s:0 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 30 s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 35 s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 61 s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0D s:1 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0A s:3 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 3C s:4 2014/05/06 12:56:05 [debug] 8060#0: *69 http proxy out buf 0000000001222C61 90 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0D s:5 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunked byte: 0A s:6 2014/05/06 12:56:05 [debug] 8060#0: *69 http upstream process non buffered downstream 2014/05/06 12:56:05 [debug] 8060#0: *69 http output filter "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 2014/05/06 12:56:05 [debug] 8060#0: *69 http copy filter: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 2014/05/06 12:56:05 [debug] 8060#0: *69 http postpone filter "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 0000000001222348 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunk: 476 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunk: 47 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunk: 1026 2014/05/06 12:56:05 [debug] 8060#0: *69 http chunk: 90 2014/05/06 12:56:05 [debug] 8060#0: *69 write old buf t:1 f:0 0000000001222070, pos 0000000001222070, size: 213 file: 0, size: 0 2014/05/06 12:56:05 [debug] 8060#0: *69 write new buf t:1 f:0 0000000001222428, pos 0000000001222428, size: 5 file: 0, size: 0 2014/05/06 12:56:05 [debug] 8060#0: *69 write new buf t:0 f:0 0000000000000000, pos 000000000122263C, size: 476 file: 0, size: 0 2014/05/06 12:56:05 [debug] 8060#0: *69 write new buf t:0 f:0 0000000000000000, pos 0000000001222820, size: 47 file: 0, size: 0 2014/05/06 12:56:05 [debug] 8060#0: *69 write new buf t:0 f:0 0000000000000000, pos 0000000001222857, size: 1026 file: 0, size: 0 2014/05/06 12:56:05 [debug] 8060#0: *69 write new buf t:0 f:0 0000000000000000, pos 0000000001222C61, size: 90 file: 0, size: 0 2014/05/06 12:56:05 [debug] 8060#0: *69 write new buf t:0 f:0 0000000000000000, pos 00000000004AC03D, size: 2 file: 0, size: 0 2014/05/06 12:56:05 [debug] 8060#0: *69 http write filter: l:0 f:1 s:1859 2014/05/06 12:56:05 [debug] 8060#0: *69 http write filter limit 0 2014/05/06 12:56:05 [debug] 8060#0: *69 writev: 1859 2014/05/06 12:56:05 [debug] 8060#0: *69 http write filter 0000000000000000 2014/05/06 12:56:05 [debug] 8060#0: *69 http copy filter: 0 "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 2014/05/06 12:56:05 [debug] 8060#0: *69 recv: fd:14 -1 of 4096 2014/05/06 12:56:05 [debug] 8060#0: *69 recv() not ready (11: Resource temporarily unavailable) 2014/05/06 12:56:05 [debug] 8060#0: *69 event timer: 14, old: 1399381025381, new: 1399381025381 2014/05/06 12:56:05 [debug] 8060#0: *69 http upstream request: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 2014/05/06 12:56:05 [debug] 8060#0: *69 http upstream dummy handler 2014/05/06 12:56:05 [debug] 8060#0: timer delta: 0 2014/05/06 12:56:05 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:56:05 [debug] 8060#0: worker cycle 2014/05/06 12:56:05 [debug] 8060#0: epoll timer: 37666 2014/05/06 12:56:05 [debug] 8060#0: epoll: fd:14 ev:0004 d:00007F426406E5F9 2014/05/06 12:56:05 [debug] 8060#0: *69 http upstream request: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 2014/05/06 12:56:05 [debug] 8060#0: *69 http upstream dummy handler 2014/05/06 12:56:05 [debug] 8060#0: timer delta: 0 2014/05/06 12:56:05 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:56:05 [debug] 8060#0: worker cycle 2014/05/06 12:56:05 [debug] 8060#0: epoll timer: 37666 2014/05/06 12:56:43 [debug] 8060#0: timer delta: 37674 2014/05/06 12:56:43 [debug] 8060#0: *66 event timer del: 12: 1399381003047 2014/05/06 12:56:43 [debug] 8060#0: *66 http wait request handler 2014/05/06 12:56:43 [info] 8060#0: *66 client timed out (110: Connection timed out) while waiting for request, client: 14.97.50.74, server: 0.0.0.0:443 2014/05/06 12:56:43 [debug] 8060#0: *66 close http connection: 12 2014/05/06 12:56:43 [debug] 8060#0: *66 reusable connection: 0 2014/05/06 12:56:43 [debug] 8060#0: *66 free: 000000000121CB30, unused: 16 2014/05/06 12:56:43 [debug] 8060#0: *66 free: 0000000001233660, unused: 200 2014/05/06 12:56:43 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:56:43 [debug] 8060#0: worker cycle 2014/05/06 12:56:43 [debug] 8060#0: epoll timer: 1 2014/05/06 12:56:43 [debug] 8060#0: timer delta: 1 2014/05/06 12:56:43 [debug] 8060#0: *67 event timer del: 11: 1399381003056 2014/05/06 12:56:43 [debug] 8060#0: *67 http wait request handler 2014/05/06 12:56:43 [info] 8060#0: *67 client timed out (110: Connection timed out) while waiting for request, client: 14.97.50.74, server: 0.0.0.0:443 2014/05/06 12:56:43 [debug] 8060#0: *67 close http connection: 11 2014/05/06 12:56:43 [debug] 8060#0: *67 reusable connection: 0 2014/05/06 12:56:43 [debug] 8060#0: *67 free: 0000000001233250, unused: 16 2014/05/06 12:56:43 [debug] 8060#0: *67 free: 0000000001233360, unused: 200 2014/05/06 12:56:43 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:56:43 [debug] 8060#0: worker cycle 2014/05/06 12:56:43 [debug] 8060#0: epoll timer: 22325 2014/05/06 12:57:05 [debug] 8060#0: epoll: fd:3 ev:2005 d:00007F426406ED90 2014/05/06 12:57:05 [debug] 8060#0: *64 http keepalive handler 2014/05/06 12:57:05 [debug] 8060#0: *64 malloc: 000000000122A3C0:1024 2014/05/06 12:57:05 [debug] 8060#0: *64 recv: fd:3 0 of 1024 2014/05/06 12:57:05 [info] 8060#0: *64 client 14.97.50.74 closed keepalive connection 2014/05/06 12:57:05 [debug] 8060#0: *64 close http connection: 3 2014/05/06 12:57:05 [debug] 8060#0: *64 event timer del: 3: 1399381027920 2014/05/06 12:57:05 [debug] 8060#0: *64 reusable connection: 0 2014/05/06 12:57:05 [debug] 8060#0: *64 free: 000000000122A3C0 2014/05/06 12:57:05 [debug] 8060#0: *64 free: 000000000121C8C0, unused: 0 2014/05/06 12:57:05 [debug] 8060#0: *64 free: 000000000121CA20, unused: 120 2014/05/06 12:57:05 [debug] 8060#0: timer delta: 22190 2014/05/06 12:57:05 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:57:05 [debug] 8060#0: worker cycle 2014/05/06 12:57:05 [debug] 8060#0: epoll timer: 135 2014/05/06 12:57:05 [debug] 8060#0: timer delta: 135 2014/05/06 12:57:05 [debug] 8060#0: *69 event timer del: 14: 1399381025381 2014/05/06 12:57:05 [debug] 8060#0: *69 http upstream request: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 2014/05/06 12:57:05 [debug] 8060#0: *69 http upstream process non buffered upstream 2014/05/06 12:57:05 [error] 8060#0: *69 upstream timed out (110: Connection timed out) while reading upstream, client: 14.97.50.74, server: 168.189.9.09, request: "GET /Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202 HTTP/1.1", upstream: "http://168.189.9.09:6400/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202", host: "168.189.9.09:6401", referrer: "http://168.189.9.09:443/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E" 2014/05/06 12:57:05 [debug] 8060#0: *69 finalize http upstream request: 504 2014/05/06 12:57:05 [debug] 8060#0: *69 finalize http proxy request 2014/05/06 12:57:05 [debug] 8060#0: *69 free rr peer 1 0 2014/05/06 12:57:05 [debug] 8060#0: *69 close http upstream connection: 14 2014/05/06 12:57:05 [debug] 8060#0: *69 free: 000000000121CC40, unused: 48 2014/05/06 12:57:05 [debug] 8060#0: *69 reusable connection: 0 2014/05/06 12:57:05 [debug] 8060#0: *69 http output filter "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 2014/05/06 12:57:05 [debug] 8060#0: *69 http copy filter: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 2014/05/06 12:57:05 [debug] 8060#0: *69 http postpone filter "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 00007FFF214AA6C0 2014/05/06 12:57:05 [debug] 8060#0: *69 http chunk: 0 2014/05/06 12:57:05 [debug] 8060#0: *69 write new buf t:0 f:0 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0 2014/05/06 12:57:05 [debug] 8060#0: *69 http write filter: l:0 f:1 s:0 2014/05/06 12:57:05 [debug] 8060#0: *69 http copy filter: 0 "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" 2014/05/06 12:57:05 [debug] 8060#0: *69 http finalize request: 0, "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381418202" a:1, c:1 2014/05/06 12:57:05 [debug] 8060#0: *69 event timer add: 13: 5000:1399381030381 2014/05/06 12:57:05 [debug] 8060#0: *69 http lingering close handler 2014/05/06 12:57:05 [debug] 8060#0: *69 recv: fd:13 -1 of 4096 2014/05/06 12:57:05 [debug] 8060#0: *69 recv() not ready (11: Resource temporarily unavailable) 2014/05/06 12:57:05 [debug] 8060#0: *69 lingering read: -2 2014/05/06 12:57:05 [debug] 8060#0: *69 event timer: 13, old: 1399381030381, new: 1399381030381 2014/05/06 12:57:05 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:57:05 [debug] 8060#0: worker cycle 2014/05/06 12:57:05 [debug] 8060#0: epoll timer: 5000 2014/05/06 12:57:05 [debug] 8060#0: epoll: fd:6 ev:0001 d:00007F426406E010 2014/05/06 12:57:05 [debug] 8060#0: accept on 0.0.0.0:6401, ready: 0 2014/05/06 12:57:05 [debug] 8060#0: posix_memalign: 000000000121C8C0:256 @16 2014/05/06 12:57:05 [debug] 8060#0: *71 accept: 14.97.50.74 fd:3 2014/05/06 12:57:05 [debug] 8060#0: posix_memalign: 0000000001233580:256 @16 2014/05/06 12:57:05 [debug] 8060#0: *71 event timer add: 3: 60000:1399381085578 2014/05/06 12:57:05 [debug] 8060#0: *71 reusable connection: 1 2014/05/06 12:57:05 [debug] 8060#0: *71 epoll add event: fd:3 op:1 ev:80002001 2014/05/06 12:57:05 [debug] 8060#0: timer delta: 197 2014/05/06 12:57:05 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:57:05 [debug] 8060#0: worker cycle 2014/05/06 12:57:05 [debug] 8060#0: epoll timer: 4803 2014/05/06 12:57:05 [debug] 8060#0: epoll: fd:3 ev:0001 d:00007F426406E5F8 2014/05/06 12:57:05 [debug] 8060#0: *71 http wait request handler 2014/05/06 12:57:05 [debug] 8060#0: *71 malloc: 000000000122A3C0:1024 2014/05/06 12:57:05 [debug] 8060#0: *71 recv: fd:3 402 of 1024 2014/05/06 12:57:05 [debug] 8060#0: *71 reusable connection: 0 2014/05/06 12:57:05 [debug] 8060#0: *71 posix_memalign: 00000000012235B0:4096 @16 2014/05/06 12:57:05 [debug] 8060#0: *71 http process request line 2014/05/06 12:57:05 [debug] 8060#0: *71 http request line: "GET /Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365 HTTP/1.1" 2014/05/06 12:57:05 [debug] 8060#0: *71 http uri: "/Stream.html" 2014/05/06 12:57:05 [debug] 8060#0: *71 http args: "s=0&d=%22168.189.9.09%22&p=450&t=1399381478365" 2014/05/06 12:57:05 [debug] 8060#0: *71 http exten: "html" 2014/05/06 12:57:05 [debug] 8060#0: *71 http process request header line 2014/05/06 12:57:05 [debug] 8060#0: *71 http header: "Accept: text/html, application/xhtml+xml, */*" 2014/05/06 12:57:05 [debug] 8060#0: *71 http header: "Referer: http://168.189.9.09:443/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E" 2014/05/06 12:57:05 [debug] 8060#0: *71 http header: "Accept-Language: en-US" 2014/05/06 12:57:05 [debug] 8060#0: *71 http header: "User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; Trident/7.0; rv:11.0) like Gecko" 2014/05/06 12:57:05 [debug] 8060#0: *71 http header: "Accept-Encoding: gzip, deflate" 2014/05/06 12:57:05 [debug] 8060#0: *71 http header: "Host: 168.189.9.09:6401" 2014/05/06 12:57:05 [debug] 8060#0: *71 http header: "DNT: 1" 2014/05/06 12:57:05 [debug] 8060#0: *71 http header: "Connection: Keep-Alive" 2014/05/06 12:57:05 [debug] 8060#0: *71 http header done 2014/05/06 12:57:05 [debug] 8060#0: *71 event timer del: 3: 1399381085578 2014/05/06 12:57:05 [debug] 8060#0: *71 generic phase: 0 2014/05/06 12:57:05 [debug] 8060#0: *71 rewrite phase: 1 2014/05/06 12:57:05 [debug] 8060#0: *71 test location: "/" 2014/05/06 12:57:05 [debug] 8060#0: *71 test location: "50x.html" 2014/05/06 12:57:05 [debug] 8060#0: *71 using configuration "/" 2014/05/06 12:57:05 [debug] 8060#0: *71 http cl:-1 max:1048576 2014/05/06 12:57:05 [debug] 8060#0: *71 rewrite phase: 3 2014/05/06 12:57:05 [debug] 8060#0: *71 post rewrite phase: 4 2014/05/06 12:57:05 [debug] 8060#0: *71 generic phase: 5 2014/05/06 12:57:05 [debug] 8060#0: *71 generic phase: 6 2014/05/06 12:57:05 [debug] 8060#0: *71 generic phase: 7 2014/05/06 12:57:05 [debug] 8060#0: *71 access phase: 8 2014/05/06 12:57:05 [debug] 8060#0: *71 access phase: 9 2014/05/06 12:57:05 [debug] 8060#0: *71 access phase: 10 2014/05/06 12:57:05 [debug] 8060#0: *71 post access phase: 11 2014/05/06 12:57:05 [debug] 8060#0: *71 posix_memalign: 00000000012245C0:4096 @16 2014/05/06 12:57:05 [debug] 8060#0: *71 http init upstream, client timer: 0 2014/05/06 12:57:05 [debug] 8060#0: *71 epoll add event: fd:3 op:3 ev:80002005 2014/05/06 12:57:05 [debug] 8060#0: *71 http script copy: "Host: " 2014/05/06 12:57:05 [debug] 8060#0: *71 http script var: "168.189.9.09:6400" 2014/05/06 12:57:05 [debug] 8060#0: *71 http script copy: " " 2014/05/06 12:57:05 [debug] 8060#0: *71 http script copy: "Connection: close " 2014/05/06 12:57:05 [debug] 8060#0: *71 http script copy: "" 2014/05/06 12:57:05 [debug] 8060#0: *71 http script copy: "" 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy header: "Accept: text/html, application/xhtml+xml, */*" 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy header: "Referer: http://168.189.9.09:443/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E" 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy header: "Accept-Language: en-US" 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy header: "User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; Trident/7.0; rv:11.0) like Gecko" 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy header: "Accept-Encoding: gzip, deflate" 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy header: "DNT: 1" 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy header: "GET /Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365 HTTP/1.0 Host: 168.189.9.09:6400 Connection: close Accept: text/html, application/xhtml+xml, */* Referer: http://168.189.9.09:443/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E Accept-Language: en-US User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; Trident/7.0; rv:11.0) like Gecko Accept-Encoding: gzip, deflate DNT: 1 " 2014/05/06 12:57:05 [debug] 8060#0: *71 http cleanup add: 0000000001224D90 2014/05/06 12:57:05 [debug] 8060#0: *71 get rr peer, try: 1 2014/05/06 12:57:05 [debug] 8060#0: *71 socket 11 2014/05/06 12:57:05 [debug] 8060#0: *71 epoll add connection: fd:11 ev:80002005 2014/05/06 12:57:05 [debug] 8060#0: *71 connect to 168.189.9.09:6400, fd:11 #72 2014/05/06 12:57:05 [debug] 8060#0: *71 http upstream connect: -2 2014/05/06 12:57:05 [debug] 8060#0: *71 posix_memalign: 0000000001226B50:128 @16 2014/05/06 12:57:05 [debug] 8060#0: *71 event timer add: 11: 60000:1399381085846 2014/05/06 12:57:05 [debug] 8060#0: *71 http finalize request: -4, "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365" a:1, c:2 2014/05/06 12:57:05 [debug] 8060#0: *71 http request count:2 blk:0 2014/05/06 12:57:05 [debug] 8060#0: timer delta: 268 2014/05/06 12:57:05 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:57:05 [debug] 8060#0: worker cycle 2014/05/06 12:57:05 [debug] 8060#0: epoll timer: 4535 2014/05/06 12:57:05 [debug] 8060#0: epoll: fd:3 ev:0004 d:00007F426406E5F8 2014/05/06 12:57:05 [debug] 8060#0: *71 http run request: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365" 2014/05/06 12:57:05 [debug] 8060#0: *71 http upstream check client, write event:1, "/Stream.html" 2014/05/06 12:57:05 [debug] 8060#0: *71 http upstream recv(): -1 (11: Resource temporarily unavailable) 2014/05/06 12:57:05 [debug] 8060#0: epoll: fd:11 ev:0004 d:00007F426406ED91 2014/05/06 12:57:05 [debug] 8060#0: *71 http upstream request: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365" 2014/05/06 12:57:05 [debug] 8060#0: *71 http upstream send request handler 2014/05/06 12:57:05 [debug] 8060#0: *71 http upstream send request 2014/05/06 12:57:05 [debug] 8060#0: *71 chain writer buf fl:1 s:397 2014/05/06 12:57:05 [debug] 8060#0: *71 chain writer in: 0000000001224DC8 2014/05/06 12:57:05 [debug] 8060#0: *71 writev: 397 2014/05/06 12:57:05 [debug] 8060#0: *71 chain writer out: 0000000000000000 2014/05/06 12:57:05 [debug] 8060#0: *71 event timer del: 11: 1399381085846 2014/05/06 12:57:05 [debug] 8060#0: *71 event timer add: 11: 60000:1399381085847 2014/05/06 12:57:05 [debug] 8060#0: timer delta: 1 2014/05/06 12:57:05 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:57:05 [debug] 8060#0: worker cycle 2014/05/06 12:57:05 [debug] 8060#0: epoll timer: 4534 2014/05/06 12:57:05 [debug] 8060#0: epoll: fd:11 ev:0005 d:00007F426406ED91 2014/05/06 12:57:05 [debug] 8060#0: *71 http upstream request: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365" 2014/05/06 12:57:05 [debug] 8060#0: *71 http upstream process header 2014/05/06 12:57:05 [debug] 8060#0: *71 malloc: 000000000127B6A0:4096 2014/05/06 12:57:05 [debug] 8060#0: *71 recv: fd:11 1821 of 4096 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy status 200 "200 OK" 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy header: "Content-Type: text/html; charset=UTF-8" 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy header: "Cache-Control: no-store, no-cache, private" 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy header: "Transfer-Encoding: chunked" 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy header: "Connection: close" 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy header done 2014/05/06 12:57:05 [debug] 8060#0: *71 HTTP/1.1 200 OK Server: nginx/1.6.0 Date: Tue, 06 May 2014 12:57:05 GMT Content-Type: text/html; charset=UTF-8 Transfer-Encoding: chunked Connection: keep-alive Cache-Control: no-store, no-cache, private 2014/05/06 12:57:05 [debug] 8060#0: *71 write new buf t:1 f:0 00000000012250A0, pos 00000000012250A0, size: 213 file: 0, size: 0 2014/05/06 12:57:05 [debug] 8060#0: *71 http write filter: l:0 f:0 s:213 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy filter init s:200 h:0 c:1 l:-1 2014/05/06 12:57:05 [debug] 8060#0: *71 tcp_nodelay 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 30 s:0 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 31 s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 64 s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 63 s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0D s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0A s:3 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 3C s:4 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy out buf 000000000127B73C 476 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0D s:5 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0A s:6 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 30 s:0 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 30 s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 32 s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 66 s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0D s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0A s:3 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 3C s:4 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy out buf 000000000127B920 47 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0D s:5 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0A s:6 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 30 s:0 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 34 s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 30 s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 32 s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0D s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0A s:3 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 3C s:4 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy out buf 000000000127B957 1026 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0D s:5 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0A s:6 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 30 s:0 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 30 s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 35 s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 61 s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0D s:1 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0A s:3 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 3C s:4 2014/05/06 12:57:05 [debug] 8060#0: *71 http proxy out buf 000000000127BD61 90 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0D s:5 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunked byte: 0A s:6 2014/05/06 12:57:05 [debug] 8060#0: *71 http upstream process non buffered downstream 2014/05/06 12:57:05 [debug] 8060#0: *71 http output filter "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365" 2014/05/06 12:57:05 [debug] 8060#0: *71 http copy filter: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365" 2014/05/06 12:57:05 [debug] 8060#0: *71 http postpone filter "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365" 0000000001225378 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunk: 476 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunk: 47 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunk: 1026 2014/05/06 12:57:05 [debug] 8060#0: *71 http chunk: 90 2014/05/06 12:57:05 [debug] 8060#0: *71 write old buf t:1 f:0 00000000012250A0, pos 00000000012250A0, size: 213 file: 0, size: 0 2014/05/06 12:57:05 [debug] 8060#0: *71 write new buf t:1 f:0 0000000001225458, pos 0000000001225458, size: 5 file: 0, size: 0 2014/05/06 12:57:05 [debug] 8060#0: *71 write new buf t:0 f:0 0000000000000000, pos 000000000127B73C, size: 476 file: 0, size: 0 2014/05/06 12:57:05 [debug] 8060#0: *71 write new buf t:0 f:0 0000000000000000, pos 000000000127B920, size: 47 file: 0, size: 0 2014/05/06 12:57:05 [debug] 8060#0: *71 write new buf t:0 f:0 0000000000000000, pos 000000000127B957, size: 1026 file: 0, size: 0 2014/05/06 12:57:05 [debug] 8060#0: *71 write new buf t:0 f:0 0000000000000000, pos 000000000127BD61, size: 90 file: 0, size: 0 2014/05/06 12:57:05 [debug] 8060#0: *71 write new buf t:0 f:0 0000000000000000, pos 00000000004AC03D, size: 2 file: 0, size: 0 2014/05/06 12:57:05 [debug] 8060#0: *71 http write filter: l:0 f:1 s:1859 2014/05/06 12:57:05 [debug] 8060#0: *71 http write filter limit 0 2014/05/06 12:57:05 [debug] 8060#0: *71 writev: 1859 2014/05/06 12:57:05 [debug] 8060#0: *71 http write filter 0000000000000000 2014/05/06 12:57:05 [debug] 8060#0: *71 http copy filter: 0 "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365" 2014/05/06 12:57:05 [debug] 8060#0: *71 recv: fd:11 -1 of 4096 2014/05/06 12:57:05 [debug] 8060#0: *71 recv() not ready (11: Resource temporarily unavailable) 2014/05/06 12:57:05 [debug] 8060#0: *71 event timer: 11, old: 1399381085847, new: 1399381085847 2014/05/06 12:57:05 [debug] 8060#0: *71 http upstream request: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365" 2014/05/06 12:57:05 [debug] 8060#0: *71 http upstream dummy handler 2014/05/06 12:57:05 [debug] 8060#0: timer delta: 0 2014/05/06 12:57:05 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:57:05 [debug] 8060#0: worker cycle 2014/05/06 12:57:05 [debug] 8060#0: epoll timer: 4534 2014/05/06 12:57:05 [debug] 8060#0: epoll: fd:11 ev:0004 d:00007F426406ED91 2014/05/06 12:57:05 [debug] 8060#0: *71 http upstream request: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365" 2014/05/06 12:57:05 [debug] 8060#0: *71 http upstream dummy handler 2014/05/06 12:57:05 [debug] 8060#0: timer delta: 0 2014/05/06 12:57:05 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:57:05 [debug] 8060#0: worker cycle 2014/05/06 12:57:05 [debug] 8060#0: epoll timer: 4534 2014/05/06 12:57:05 [debug] 8060#0: epoll: fd:13 ev:2011 d:00007F426406E7A8 2014/05/06 12:57:05 [debug] 8060#0: epoll_wait() error on fd:13 ev:2011 2014/05/06 12:57:05 [debug] 8060#0: *69 http lingering close handler 2014/05/06 12:57:05 [debug] 8060#0: *69 recv: fd:13 0 of 4096 2014/05/06 12:57:05 [debug] 8060#0: *69 lingering read: 0 2014/05/06 12:57:05 [debug] 8060#0: *69 http request count:1 blk:0 2014/05/06 12:57:05 [debug] 8060#0: *69 http close request 2014/05/06 12:57:05 [debug] 8060#0: *69 http log handler 2014/05/06 12:57:05 [debug] 8060#0: *69 posix_memalign: 000000000127C6B0:4096 @16 2014/05/06 12:57:05 [debug] 8060#0: *69 free: 00000000012225A0 2014/05/06 12:57:05 [debug] 8060#0: *69 free: 00000000012293B0, unused: 0 2014/05/06 12:57:05 [debug] 8060#0: *69 free: 0000000001221590, unused: 64 2014/05/06 12:57:05 [debug] 8060#0: *69 free: 000000000127C6B0, unused: 3722 2014/05/06 12:57:05 [debug] 8060#0: *69 close http connection: 13 2014/05/06 12:57:05 [debug] 8060#0: *69 event timer del: 13: 1399381030381 2014/05/06 12:57:05 [debug] 8060#0: *69 reusable connection: 0 2014/05/06 12:57:05 [debug] 8060#0: *69 free: 0000000001233880 2014/05/06 12:57:05 [debug] 8060#0: *69 free: 0000000001233470, unused: 0 2014/05/06 12:57:05 [debug] 8060#0: *69 free: 0000000001233770, unused: 120 2014/05/06 12:57:05 [debug] 8060#0: timer delta: 134 2014/05/06 12:57:05 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:57:05 [debug] 8060#0: worker cycle 2014/05/06 12:57:05 [debug] 8060#0: epoll timer: 59866 2014/05/06 12:57:12 [debug] 8060#0: epoll: fd:3 ev:2005 d:00007F426406E5F8 2014/05/06 12:57:12 [debug] 8060#0: *71 http run request: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365" 2014/05/06 12:57:12 [debug] 8060#0: *71 http upstream check client, write event:0, "/Stream.html" 2014/05/06 12:57:12 [info] 8060#0: *71 epoll_wait() reported that client prematurely closed connection, so upstream connection is closed too while sending to client, client: 14.97.50.74, server: 168.189.9.09, request: "GET /Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365 HTTP/1.1", upstream: "http://168.189.9.09:6400/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365", host: "168.189.9.09:6401", referrer: "http://168.189.9.09:443/Login.do;jsessionid=23A0D509FE7135EC4AB85B757E8BC62E" 2014/05/06 12:57:12 [debug] 8060#0: *71 finalize http upstream request: 499 2014/05/06 12:57:12 [debug] 8060#0: *71 finalize http proxy request 2014/05/06 12:57:12 [debug] 8060#0: *71 free rr peer 1 0 2014/05/06 12:57:12 [debug] 8060#0: *71 close http upstream connection: 11 2014/05/06 12:57:12 [debug] 8060#0: *71 free: 0000000001226B50, unused: 48 2014/05/06 12:57:12 [debug] 8060#0: *71 event timer del: 11: 1399381085847 2014/05/06 12:57:12 [debug] 8060#0: *71 reusable connection: 0 2014/05/06 12:57:12 [debug] 8060#0: *71 http finalize request: 499, "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365" a:1, c:1 2014/05/06 12:57:12 [debug] 8060#0: *71 http terminate request count:1 2014/05/06 12:57:12 [debug] 8060#0: *71 http terminate cleanup count:1 blk:0 2014/05/06 12:57:12 [debug] 8060#0: *71 http posted request: "/Stream.html?s=0&d=%22168.189.9.09%22&p=450&t=1399381478365" 2014/05/06 12:57:12 [debug] 8060#0: *71 http terminate handler count:1 2014/05/06 12:57:12 [debug] 8060#0: *71 http request count:1 blk:0 2014/05/06 12:57:12 [debug] 8060#0: *71 http close request 2014/05/06 12:57:12 [debug] 8060#0: *71 http log handler 2014/05/06 12:57:12 [debug] 8060#0: *71 posix_memalign: 00000000012293B0:4096 @16 2014/05/06 12:57:12 [debug] 8060#0: *71 free: 000000000127B6A0 2014/05/06 12:57:12 [debug] 8060#0: *71 free: 00000000012235B0, unused: 0 2014/05/06 12:57:12 [debug] 8060#0: *71 free: 00000000012245C0, unused: 144 2014/05/06 12:57:12 [debug] 8060#0: *71 free: 00000000012293B0, unused: 3722 2014/05/06 12:57:12 [debug] 8060#0: *71 close http connection: 3 2014/05/06 12:57:12 [debug] 8060#0: *71 reusable connection: 0 2014/05/06 12:57:12 [debug] 8060#0: *71 free: 000000000122A3C0 2014/05/06 12:57:12 [debug] 8060#0: *71 free: 000000000121C8C0, unused: 0 2014/05/06 12:57:12 [debug] 8060#0: *71 free: 0000000001233580, unused: 120 2014/05/06 12:57:12 [debug] 8060#0: timer delta: 6319 2014/05/06 12:57:12 [debug] 8060#0: posted events 0000000000000000 2014/05/06 12:57:12 [debug] 8060#0: worker cycle 2014/05/06 12:57:12 [debug] 8060#0: epoll timer: -1