2011/06/14 22:56:15 [debug] 13313#0: epoll: fd:3 ev:0005 d:098908A9 2011/06/14 22:56:15 [debug] 13313#0: *11 http keepalive handler 2011/06/14 22:56:15 [debug] 13313#0: *11 recv: fd:3 376 of 1024 2011/06/14 22:56:15 [debug] 13313#0: *11 reusable connection: 0 2011/06/14 22:56:15 [debug] 13313#0: *11 malloc: 09880230:660 2011/06/14 22:56:15 [debug] 13313#0: *11 posix_memalign: 098804E0:4096 @16 2011/06/14 22:56:15 [debug] 13313#0: *11 http process request line 2011/06/14 22:56:15 [debug] 13313#0: *11 http request line: "GET /read/?d=19 HTTP/1.1" 2011/06/14 22:56:15 [debug] 13313#0: *11 http uri: "/read/" 2011/06/14 22:56:15 [debug] 13313#0: *11 http args: "d=19" 2011/06/14 22:56:15 [debug] 13313#0: *11 http exten: "" 2011/06/14 22:56:15 [debug] 13313#0: *11 http process request header line 2011/06/14 22:56:15 [debug] 13313#0: *11 http header: "Host: slim" 2011/06/14 22:56:15 [debug] 13313#0: *11 http header: "User-Agent: Mozilla/5.0 (Windows; U; Windows NT 5.1; ru; rv:1.9.0.16) Gecko/2009120208 Firefox/3.0.16 (.NET CLR 3.5.30729)" 2011/06/14 22:56:15 [debug] 13313#0: *11 http header: "Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8" 2011/06/14 22:56:15 [debug] 13313#0: *11 http header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2011/06/14 22:56:15 [debug] 13313#0: *11 http header: "Accept-Encoding: gzip,deflate" 2011/06/14 22:56:15 [debug] 13313#0: *11 http header: "Accept-Charset: UTF-8,*" 2011/06/14 22:56:15 [debug] 13313#0: *11 http header: "Keep-Alive: 300" 2011/06/14 22:56:15 [debug] 13313#0: *11 http header: "Connection: keep-alive" 2011/06/14 22:56:15 [debug] 13313#0: *11 http header done 2011/06/14 22:56:15 [debug] 13313#0: *11 event timer del: 3: 2407767362 2011/06/14 22:56:15 [debug] 13313#0: *11 rewrite phase: 0 2011/06/14 22:56:15 [debug] 13313#0: *11 test location: "/read" 2011/06/14 22:56:15 [debug] 13313#0: *11 using configuration "/read" 2011/06/14 22:56:15 [debug] 13313#0: *11 http cl:-1 max:1048576 2011/06/14 22:56:15 [debug] 13313#0: *11 rewrite phase: 2 2011/06/14 22:56:15 [debug] 13313#0: *11 post rewrite phase: 3 2011/06/14 22:56:15 [debug] 13313#0: *11 generic phase: 4 2011/06/14 22:56:15 [debug] 13313#0: *11 generic phase: 5 2011/06/14 22:56:15 [debug] 13313#0: *11 access phase: 6 2011/06/14 22:56:15 [debug] 13313#0: *11 access phase: 7 2011/06/14 22:56:15 [debug] 13313#0: *11 post access phase: 8 2011/06/14 22:56:15 [debug] 13313#0: *11 http set discard body 2011/06/14 22:56:15 [debug] 13313#0: *11 http init upstream, client timer: 0 2011/06/14 22:56:15 [debug] 13313#0: *11 http cleanup add: 09880BF8 2011/06/14 22:56:15 [debug] 13313#0: *11 event timer add: 9: 30000:2407747399 2011/06/14 22:56:15 [debug] 13313#0: *11 http upstream connect: -2 2011/06/14 22:56:15 [debug] 13313#0: *11 event timer add: 9: 10000:2407727399 2011/06/14 22:56:15 [debug] 13313#0: *11 http finalize request: -4, "/read/?d=19" a:1, c:2 2011/06/14 22:56:15 [debug] 13313#0: *11 http request count:2 blk:0 2011/06/14 22:56:15 [debug] 13313#0: *11 http run request: "/read/?d=19" 2011/06/14 22:56:15 [debug] 13313#0: *11 http request empty handler 2011/06/14 22:56:15 [debug] 13313#0: timer delta: 15037 2011/06/14 22:56:15 [debug] 13313#0: posted events 00000000 2011/06/14 22:56:15 [debug] 13313#0: worker cycle 2011/06/14 22:56:15 [debug] 13313#0: epoll timer: 10000 2011/06/14 22:56:15 [debug] 13313#0: epoll: fd:9 ev:0005 d:09890908 2011/06/14 22:56:15 [debug] 13313#0: *11 http upstream request: "/read/?d=19" 2011/06/14 22:56:15 [debug] 13313#0: *11 event timer del: 9: 2407727399 2011/06/14 22:56:15 [debug] 13313#0: *11 Postgres: output row begin 2011/06/14 22:56:15 [debug] 13313#0: *11 Postgres: output row end 2011/06/14 22:56:15 [debug] 13313#0: *11 event timer del: 9: 2407747399 2011/06/14 22:56:15 [debug] 13313#0: *11 finalize http upstream request: 0 2011/06/14 22:56:15 [debug] 13313#0: *11 charset: "" > "utf-8" 2011/06/14 22:56:15 [debug] 13313#0: *11 HTTP/1.1 200 OK Server: nginx/1.0.4 Date: Tue, 14 Jun 2011 18:56:15 GMT Content-Type: text/plain; charset=utf-8 Transfer-Encoding: chunked Connection: keep-alive 2011/06/14 22:56:15 [debug] 13313#0: *11 write new buf t:1 f:0 09880CC4, pos 09880CC4, size: 170 file: 0, size: 0 2011/06/14 22:56:15 [debug] 13313#0: *11 http write filter: l:0 f:0 s:170 2011/06/14 22:56:15 [debug] 13313#0: *11 http output filter "/read/?d=19" 2011/06/14 22:56:15 [debug] 13313#0: *11 http copy filter: "/read/?d=19" 2011/06/14 22:56:15 [debug] 13313#0: *11 http postpone filter "/read/?d=19" 09880C7C 2011/06/14 22:56:15 [debug] 13313#0: *11 http chunk: 8 2011/06/14 22:56:15 [debug] 13313#0: *11 write old buf t:1 f:0 09880CC4, pos 09880CC4, size: 170 file: 0, size: 0 2011/06/14 22:56:15 [debug] 13313#0: *11 write new buf t:1 f:0 00000000, pos 09880DEC, size: 3 file: 0, size: 0 2011/06/14 22:56:15 [debug] 13313#0: *11 write new buf t:1 f:0 09880C74, pos 09880C74, size: 8 file: 0, size: 0 2011/06/14 22:56:15 [debug] 13313#0: *11 write new buf t:0 f:0 00000000, pos 080B85BC, size: 7 file: 0, size: 0 2011/06/14 22:56:15 [debug] 13313#0: *11 http write filter: l:1 f:0 s:188 2011/06/14 22:56:15 [debug] 13313#0: *11 http write filter limit 0 2011/06/14 22:56:15 [debug] 13313#0: *11 writev: 188 2011/06/14 22:56:15 [debug] 13313#0: *11 http write filter 00000000 2011/06/14 22:56:15 [debug] 13313#0: *11 http copy filter: 0 "/read/?d=19" 2011/06/14 22:56:15 [debug] 13313#0: *11 postgres: free keepalive peer 2011/06/14 22:56:15 [debug] 13313#0: *11 postgres: free keepalive peer: saving connection 09890908 2011/06/14 22:56:15 [debug] 13313#0: *11 http output filter "/read/?d=19" 2011/06/14 22:56:15 [debug] 13313#0: *11 http copy filter: "/read/?d=19" 2011/06/14 22:56:15 [debug] 13313#0: *11 http postpone filter "/read/?d=19" BFBAC158 2011/06/14 22:56:15 [debug] 13313#0: *11 http chunk: 0 2011/06/14 22:56:15 [debug] 13313#0: *11 write new buf t:0 f:0 00000000, pos 080B85BE, size: 5 file: 0, size: 0 2011/06/14 22:56:15 [debug] 13313#0: *11 http write filter: l:1 f:0 s:5 2011/06/14 22:56:15 [debug] 13313#0: *11 http write filter limit 0 2011/06/14 22:56:15 [debug] 13313#0: *11 writev: 5 2011/06/14 22:56:15 [debug] 13313#0: *11 http write filter 00000000 2011/06/14 22:56:15 [debug] 13313#0: *11 http copy filter: 0 "/read/?d=19" 2011/06/14 22:56:15 [debug] 13313#0: *11 http finalize request: 0, "/read/?d=19" a:1, c:1 2011/06/14 22:56:15 [debug] 13313#0: *11 set http keepalive handler 2011/06/14 22:56:15 [debug] 13313#0: *11 http close request 2011/06/14 22:56:15 [debug] 13313#0: *11 http log handler 2011/06/14 22:56:15 [debug] 13313#0: *11 free: 098804E0, unused: 1362 2011/06/14 22:56:15 [debug] 13313#0: *11 event timer add: 3: 65000:2407782400 2011/06/14 22:56:15 [debug] 13313#0: *11 free: 09880230 2011/06/14 22:56:15 [debug] 13313#0: *11 free: 0987FE28 2011/06/14 22:56:15 [debug] 13313#0: *11 hc free: 00000000 0 2011/06/14 22:56:15 [debug] 13313#0: *11 hc busy: 00000000 0 2011/06/14 22:56:15 [debug] 13313#0: *11 reusable connection: 1 2011/06/14 22:56:15 [debug] 13313#0: *11 post event 098A8858 2011/06/14 22:56:15 [debug] 13313#0: timer delta: 1 2011/06/14 22:56:15 [debug] 13313#0: posted events 098A8858 2011/06/14 22:56:15 [debug] 13313#0: posted event 098A8858 2011/06/14 22:56:15 [debug] 13313#0: *11 delete posted event 098A8858 2011/06/14 22:56:15 [debug] 13313#0: *11 http keepalive handler 2011/06/14 22:56:15 [debug] 13313#0: *11 malloc: 0987FE28:1024 2011/06/14 22:56:15 [debug] 13313#0: *11 recv: fd:3 -1 of 1024 2011/06/14 22:56:15 [debug] 13313#0: *11 recv() not ready (11: Resource temporarily unavailable) 2011/06/14 22:56:15 [debug] 13313#0: posted event 00000000 2011/06/14 22:56:15 [debug] 13313#0: worker cycle 2011/06/14 22:56:15 [debug] 13313#0: epoll timer: 65000 2011/06/14 22:56:18 [debug] 13313#0: epoll: fd:3 ev:0005 d:098908A9 2011/06/14 22:56:18 [debug] 13313#0: *11 http keepalive handler 2011/06/14 22:56:18 [debug] 13313#0: *11 recv: fd:3 0 of 1024 2011/06/14 22:56:18 [info] 13313#0: *11 client 192.168.0.1 closed keepalive connection 2011/06/14 22:56:18 [debug] 13313#0: *11 close http connection: 3 2011/06/14 22:56:18 [debug] 13313#0: *11 event timer del: 3: 2407782400 2011/06/14 22:56:18 [debug] 13313#0: *11 reusable connection: 0 2011/06/14 22:56:18 [debug] 13313#0: *11 free: 0987FE28 2011/06/14 22:56:18 [debug] 13313#0: *11 free: 00000000 2011/06/14 22:56:18 [debug] 13313#0: *11 free: 0987A290, unused: 56 2011/06/14 22:56:18 [debug] 13313#0: timer delta: 3352 2011/06/14 22:56:18 [debug] 13313#0: posted events 00000000 2011/06/14 22:56:18 [debug] 13313#0: worker cycle 2011/06/14 22:56:18 [debug] 13313#0: epoll timer: -1