Error log ngx.exec()

gistfile1.txt

2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi record length: 283
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi parser: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi header: "Status: 302 Moved Temporarily"
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi parser: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi header: "X-Powered-By: PHP/5.3.6"
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi parser: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi header: "Expires: Thu, 19 Nov 1981 08:52:00 GMT"
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi parser: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi header: "Cache-Control: no-store, no-cache, must-revalidate, post-check=0, pre-check=0"
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi parser: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi header: "Pragma: no-cache"
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi parser: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi header: "Location: /user/register-business-info?inc=1"
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi parser: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi header: "Content-type: text/html; Charset=utf-8"
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi parser: 1
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi header done
2011/08/12 17:49:17 [debug] 26292#0: *368 http cacheable: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 http upstream process upstream
2011/08/12 17:49:17 [debug] 26292#0: *368 pipe read upstream: 1
2011/08/12 17:49:17 [debug] 26292#0: *368 pipe preread: 23
2011/08/12 17:49:17 [debug] 26292#0: *368 readv: 1:3784
2011/08/12 17:49:17 [debug] 26292#0: *368 pipe recv chain: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 pipe buf free s:0 t:1 f:0 0000000004759CD0, pos 0000000004759DF1, size: 23 file: 0, size: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 input buf #0 0000000004759DF1
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi record byte: 01
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi record byte: 03
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi record byte: 00
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi record byte: 01
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi record byte: 00
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi record byte: 08
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi record byte: 00
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi record byte: 00
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi record length: 8
2011/08/12 17:49:17 [debug] 26292#0: *368 http fastcgi sent end request
2011/08/12 17:49:17 [debug] 26292#0: *368 input buf 0000000004759DF1 2
2011/08/12 17:49:17 [debug] 26292#0: *368 pipe write downstream: 1
2011/08/12 17:49:17 [debug] 26292#0: *368 pipe write downstream flush in
2011/08/12 17:49:17 [debug] 26292#0: *368 http output filter "/content/user/login?"
2011/08/12 17:49:17 [debug] 26292#0: *368 http copy filter: "/content/user/login?"
2011/08/12 17:49:17 [debug] 26292#0: *368 http copy filter: 0 "/content/user/login?"
2011/08/12 17:49:17 [debug] 26292#0: *368 pipe write downstream done
2011/08/12 17:49:17 [debug] 26292#0: *368 event timer: 5, old: 1313142617682, new: 1313142617737
2011/08/12 17:49:17 [debug] 26292#0: *368 http upstream exit: 0000000000000000
2011/08/12 17:49:17 [debug] 26292#0: *368 finalize http upstream request: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 finalize http fastcgi request
2011/08/12 17:49:17 [debug] 26292#0: *368 free rr peer 1 0
2011/08/12 17:49:17 [debug] 26292#0: *368 close http upstream connection: 5
2011/08/12 17:49:17 [debug] 26292#0: *368 event timer del: 5: 1313142617682
2011/08/12 17:49:17 [debug] 26292#0: *368 reusable connection: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 http upstream temp fd: -1
2011/08/12 17:49:17 [debug] 26292#0: *368 http output filter "/content/user/login?"
2011/08/12 17:49:17 [debug] 26292#0: *368 http copy filter: "/content/user/login?"
2011/08/12 17:49:17 [debug] 26292#0: *368 http copy filter: 0 "/content/user/login?"
2011/08/12 17:49:17 [debug] 26292#0: *368 http finalize request: 0, "/content/user/login?" a:1, c:2
2011/08/12 17:49:17 [debug] 26292#0: *368 http wake parent request: "/user/login?"
2011/08/12 17:49:17 [debug] 26292#0: *368 http posted request: "/user/login?"
2011/08/12 17:49:17 [notice] 26292#0: *368 Content Header: /user/register-business-info?inc=1|||||||| while sending to client, client: 203.185.31.178, server: cache.beta2.asiaclassified.com, request: "POST /user/login HTTP/1.1", host: "cache.beta2.asiaclassified.com:9090", referrer: "http://cache.beta2.asiaclassified.com:9090/user/login"
2011/08/12 17:49:17 [notice] 26292#0: *368 Request URI: /user/login|||||||| while sending to client, client: 203.185.31.178, server: cache.beta2.asiaclassified.com, request: "POST /user/login HTTP/1.1", host: "cache.beta2.asiaclassified.com:9090", referrer: "http://cache.beta2.asiaclassified.com:9090/user/login"
2011/08/12 17:49:17 [notice] 26292#0: *368 Redirect URL: /user/register-business-info?inc=1|||||||| while sending to client, client: 203.185.31.178, server: cache.beta2.asiaclassified.com, request: "POST /user/login HTTP/1.1", host: "cache.beta2.asiaclassified.com:9090", referrer: "http://cache.beta2.asiaclassified.com:9090/user/login"
2011/08/12 17:49:17 [debug] 26292#0: *368 http finalize request: 302, "/user/login?" a:1, c:1
2011/08/12 17:49:17 [debug] 26292#0: *368 http special response: 302, "/user/login?"
2011/08/12 17:49:17 [debug] 26292#0: *368 http set discard body
2011/08/12 17:49:17 [debug] 26292#0: *368 http read discarded body
2011/08/12 17:49:17 [debug] 26292#0: *368 recv: fd:4 -1 of 132
2011/08/12 17:49:17 [debug] 26292#0: *368 recv() not ready (11: Resource temporarily unavailable)
2011/08/12 17:49:17 [debug] 26292#0: *368 HTTP/1.1 302 Moved Temporarily
Server: nginx/1.0.5
Date: Fri, 12 Aug 2011 09:49:17 GMT
Content-Type: text/html
Content-Length: 160
Location: http://cache.beta2.asiaclassified.com:9090/user/register-business-info?inc=1
Connection: keep-alive

2011/08/12 17:49:17 [debug] 26292#0: *368 write new buf t:1 f:0 0000000004790E20, pos 0000000004790E20, size: 250 file: 0, size: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 http write filter: l:0 f:0 s:250
2011/08/12 17:49:17 [debug] 26292#0: *368 http output filter "/user/login?"
2011/08/12 17:49:17 [debug] 26292#0: *368 http copy filter: "/user/login?"
2011/08/12 17:49:17 [debug] 26292#0: *368 http postpone filter "/user/login?" 0000000004791048
2011/08/12 17:49:17 [debug] 26292#0: *368 write old buf t:1 f:0 0000000004790E20, pos 0000000004790E20, size: 250 file: 0, size: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 write new buf t:0 f:0 0000000000000000, pos 000000000069D640, size: 108 file: 0, size: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 write new buf t:0 f:0 0000000000000000, pos 000000000069D380, size: 52 file: 0, size: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 http write filter: l:1 f:0 s:410
2011/08/12 17:49:17 [debug] 26292#0: *368 http write filter limit 0
2011/08/12 17:49:17 [debug] 26292#0: *368 writev: 410
2011/08/12 17:49:17 [debug] 26292#0: *368 http write filter 0000000000000000
2011/08/12 17:49:17 [debug] 26292#0: *368 http copy filter: 0 "/user/login?"
2011/08/12 17:49:17 [debug] 26292#0: *368 http finalize request: 0, "/user/login?" a:1, c:2
2011/08/12 17:49:17 [debug] 26292#0: *368 event timer add: 4: 5000:1313142562737
2011/08/12 17:49:17 [debug] 26292#0: *368 http request count:2 blk:0
2011/08/12 17:49:17 [debug] 26292#0: *368 http run request: "/user/login?"
2011/08/12 17:49:17 [debug] 26292#0: *368 http read discarded body
2011/08/12 17:49:17 [debug] 26292#0: *368 recv: fd:4 132 of 132
2011/08/12 17:49:17 [debug] 26292#0: *368 http finalize request: -4, "/user/login?" a:1, c:1
2011/08/12 17:49:17 [debug] 26292#0: *368 set http keepalive handler
2011/08/12 17:49:17 [debug] 26292#0: *368 http close request
2011/08/12 17:49:17 [debug] 26292#0: *368 http log handler
2011/08/12 17:49:17 [debug] 26292#0: *368 free: 0000000004759CD0
2011/08/12 17:49:17 [debug] 26292#0: *368 free: 000000000478AFC0
2011/08/12 17:49:17 [debug] 26292#0: *368 free: 00000000047926C0, unused: 3
2011/08/12 17:49:17 [debug] 26292#0: *368 free: 0000000004760280, unused: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 free: 0000000004789FB0, unused: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 free: 000000000478F660, unused: 4
2011/08/12 17:49:17 [debug] 26292#0: *368 free: 0000000004790670, unused: 1244
2011/08/12 17:49:17 [debug] 26292#0: *368 event timer del: 4: 1313142562737
2011/08/12 17:49:17 [debug] 26292#0: *368 event timer add: 4: 65000:1313142622910
2011/08/12 17:49:17 [debug] 26292#0: *368 free: 00000000047D48D0
2011/08/12 17:49:17 [debug] 26292#0: *368 free: 0000000004793D40
2011/08/12 17:49:17 [debug] 26292#0: *368 hc free: 0000000000000000 0
2011/08/12 17:49:17 [debug] 26292#0: *368 hc busy: 0000000000000000 0
2011/08/12 17:49:17 [debug] 26292#0: *368 reusable connection: 1
2011/08/12 17:49:17 [debug] 26292#0: *368 post event 00000000048113D0
2011/08/12 17:49:17 [debug] 26292#0: *368 http empty handler
2011/08/12 17:49:17 [debug] 26292#0: *368 delete posted event 00000000048113D0
2011/08/12 17:49:17 [debug] 26292#0: *368 http keepalive handler
2011/08/12 17:49:17 [debug] 26292#0: *368 malloc: 0000000004793D40:1024
2011/08/12 17:49:17 [debug] 26292#0: *368 recv: fd:4 642 of 1024
2011/08/12 17:49:17 [debug] 26292#0: *368 reusable connection: 0
2011/08/12 17:49:17 [debug] 26292#0: *368 malloc: 00000000047D48D0:1256