cannot PUT file with nginx-mogilefs-module

Valery Kholodkov valery+nginxen at grid.net.ru
Mon Sep 27 16:44:36 MSD 2010


What version of nginx are you using?

----- rrssuupp1231 at gmail.com wrote:
> 
> hi, I'm dai
> 
> I'm trying to PUT file to MogileFS with nginx-mogilefs-module 1.0.4,
>  But I cannot.
> 
> I compiled with '--with-debug' option, and the log seems
> "create_close" request is not send to MogileFS tracker.
> 
> How can I use ?
> 
> I sent HTTP-PUT request by this command
> ------------------------------------------------------------
>  curl --upload-file ~/install.log http://127.0.0.1:81/testput/dabada.txt
> ------------------------------------------------------------
> 
> This is Nginx Config file
> ------------------------------------------------------------
> server{
>         listen 81;
>         error_log logs/error.log debug;
>         location /testput/ {
>                 mogilefs_tracker 127.0.0.1:7001;
>                 mogilefs_domain  test;
>                 mogilefs_methods PUT GET;
>                 mogilefs_pass {
>                                 proxy_pass $mogilefs_path;
>                                 proxy_hide_header Content-Type;
>                                 proxy_buffering off;
>                         }
>         }
> }
> 
> and This is Nginx error.log file
> ------------------------------------------------------------
> 2010/09/27 15:04:31 [debug] 22519#0: epoll add event: fd:6 op:1 ev:00000001
> 2010/09/27 15:04:31 [debug] 22519#0: post event 000000000D0BAFA0
> 2010/09/27 15:04:31 [debug] 22519#0: delete posted event 000000000D0BAFA0
> 2010/09/27 15:04:31 [debug] 22519#0: accept on 0.0.0.0:81, ready: 0
> 2010/09/27 15:04:31 [debug] 22519#0: posix_memalign:
> 000000000CFE8990:256 @16
> 2010/09/27 15:04:31 [debug] 22519#0: *1 accept: 127.0.0.1 fd:10
> 2010/09/27 15:04:31 [debug] 22519#0: *1 event timer add: 10:
> 60000:1285567531241
> 2010/09/27 15:04:31 [debug] 22519#0: *1 epoll add event: fd:10 op:1
> ev:80000001
> 2010/09/27 15:04:31 [debug] 22519#0: *1 post event 000000000D0BB0D8
> 2010/09/27 15:04:31 [debug] 22519#0: *1 delete posted event 000000000D0BB0D8
> 2010/09/27 15:04:31 [debug] 22519#0: *1 malloc: 000000000CFDD6B0:1248
> 2010/09/27 15:04:31 [debug] 22519#0: *1 posix_memalign:
> 000000000D03D210:256 @16
> 2010/09/27 15:04:31 [debug] 22519#0: *1 malloc: 000000000CFD3340:1024
> 2010/09/27 15:04:31 [debug] 22519#0: *1 posix_memalign:
> 000000000CFDDBA0:4096 @16
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http process request line
> 2010/09/27 15:04:31 [debug] 22519#0: *1 recv: fd:10 219 of 1024
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http request line: "PUT
> /testput/dabada.txt HTTP/1.1"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http uri: "/testput/dabada.txt"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http args: ""
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http exten: "txt"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http process request header line
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http header: "User-Agent:
> curl/7.15.5 (x86_64-redhat-linux-gnu) libcurl/7.15.5 OpenSSL/0.9.8b
> zlib/1.2.3 libidn/0.6.5"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http header: "Host: 127.0.0.1:81"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http header: "Accept: */*"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http header: "Content-Length: 37197"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http header: "Expect: 100-continue"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http header done
> 2010/09/27 15:04:31 [debug] 22519#0: *1 event timer del: 10: 1285567531241
> 2010/09/27 15:04:31 [debug] 22519#0: *1 rewrite phase: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 test location:
> "/mogstored_spare_218042592/"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 test location:
> "/mogstored_spare_218051760/"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 test location: "/testput/"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 using configuration "/testput/"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http cl:37197 max:1048576
> 2010/09/27 15:04:31 [debug] 22519#0: *1 rewrite phase: 2
> 2010/09/27 15:04:31 [debug] 22519#0: *1 post rewrite phase: 3
> 2010/09/27 15:04:31 [debug] 22519#0: *1 generic phase: 4
> 2010/09/27 15:04:31 [debug] 22519#0: *1 generic phase: 5
> 2010/09/27 15:04:31 [debug] 22519#0: *1 access phase: 6
> 2010/09/27 15:04:31 [debug] 22519#0: *1 access phase: 7
> 2010/09/27 15:04:31 [debug] 22519#0: *1 post access phase: 8
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http finalize request: -5,
> "/testput/dabada.txt?" a:1, c:1
> 2010/09/27 15:04:31 [debug] 22519#0: *1 content phase: 9
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs put handler
> 2010/09/27 15:04:31 [debug] 22519#0: *1 send 100 Continue
> 2010/09/27 15:04:31 [debug] 22519#0: *1 send: fd:10 25 of 25
> 2010/09/27 15:04:31 [debug] 22519#0: *1 malloc: 000000000D016010:8192
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http read client request body
> 2010/09/27 15:04:31 [debug] 22519#0: *1 recv: fd:10 8192 of 8192
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http client request body recv 8192
> 2010/09/27 15:04:31 [debug] 22519#0: *1 add cleanup: 000000000CFDE830
> 2010/09/27 15:04:31 [debug] 22519#0: *1 hashed path:
> /usr/local/nginx/client_body_temp/0000000001
> 2010/09/27 15:04:31 [debug] 22519#0: *1 temp fd:11
> 2010/09/27 15:04:31 [warn] 22519#0: *1 a client request body is buffered
> to a temporary file /usr/local/nginx/client_body_temp/0000000001,
> client: 127.0.0.1, server: , request:
> "PUT /testput/dabada.txt HTTP/1.1", host: "127.0.0.1:81"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 write: 11, 000000000D016010, 8192, 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 recv: fd:10 8192 of 8192
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http client request body recv 8192
> 2010/09/27 15:04:31 [debug] 22519#0: *1 write: 11, 000000000D016010,
> 8192, 8192
> 2010/09/27 15:04:31 [debug] 22519#0: *1 recv: fd:10 8192 of 8192
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http client request body recv 8192
> 2010/09/27 15:04:31 [debug] 22519#0: *1 write: 11, 000000000D016010,
> 8192, 16384
> 2010/09/27 15:04:31 [debug] 22519#0: *1 recv: fd:10 8192 of 8192
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http client request body recv 8192
> 2010/09/27 15:04:31 [debug] 22519#0: *1 write: 11, 000000000D016010,
> 8192, 24576
> 2010/09/27 15:04:31 [debug] 22519#0: *1 recv: fd:10 4429 of 4429
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http client request body recv 4429
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http client request body rest 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 write: 11, 000000000D016010,
> 4429, 32768
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs body handler
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs put handler
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs put handler state: 0,
> status: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 posix_memalign:
> 000000000CFDEBB0:4096 @16
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http subrequest
> "/mogstored_spare_218023808/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http finalize request: -4,
> "/testput/dabada.txt?" a:0, c:3
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http request count:3 blk:0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http posted request:
> "/mogstored_spare_218023808/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 rewrite phase: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 test location:
> "/mogstored_spare_218042592/"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 test location:
> "/mogstored_spare_218028368/"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 test location:
> "/mogstored_spare_218023808/"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 using configuration
> "/mogstored_spare_218023808/"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http cl:37197 max:1048576
> 2010/09/27 15:04:31 [debug] 22519#0: *1 rewrite phase: 2
> 2010/09/27 15:04:31 [debug] 22519#0: *1 post rewrite phase: 3
> 2010/09/27 15:04:31 [debug] 22519#0: *1 generic phase: 4
> 2010/09/27 15:04:31 [debug] 22519#0: *1 generic phase: 5
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http init upstream, client timer: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 epoll add event: fd:10 op:3
> ev:80000005
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs request: "create_open
> key=dabada.txt&domain=test"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http cleanup add: 000000000CFDF7A0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 get rr peer, try: 1
> 2010/09/27 15:04:31 [debug] 22519#0: *1 socket 12
> 2010/09/27 15:04:31 [debug] 22519#0: *1 epoll add connection: fd:12
> ev:80000005
> 2010/09/27 15:04:31 [debug] 22519#0: *1 connect to 192.168.100.10:7001,
> fd:12 #2
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream connect: -2
> 2010/09/27 15:04:31 [debug] 22519#0: *1 event timer add: 12:
> 60000:1285567531241
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http finalize request: -4,
> "/mogstored_spare_218023808/dabada.txt?" a:1, c:3
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http request count:3 blk:0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 post event 000000000D0D5150
> 2010/09/27 15:04:31 [debug] 22519#0: *1 post event 000000000D0D50E8
> 2010/09/27 15:04:31 [debug] 22519#0: *1 delete posted event 000000000D0D50E8
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http run request:
> "/mogstored_spare_218023808/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream check client,
> write event:1, "/mogstored_spare_218023808/dabada.txt"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream recv(): -1 (11:
> Resource temporarily unavailable)
> 2010/09/27 15:04:31 [debug] 22519#0: *1 delete posted event 000000000D0D5150
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream request:
> "/mogstored_spare_218023808/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream send request handler
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream send request
> 2010/09/27 15:04:31 [debug] 22519#0: *1 chain writer buf fl:0 s:40
> 2010/09/27 15:04:31 [debug] 22519#0: *1 chain writer in: 000000000CFDF7D8
> 2010/09/27 15:04:31 [debug] 22519#0: *1 writev: 40
> 2010/09/27 15:04:31 [debug] 22519#0: *1 chain writer out: 0000000000000000
> 2010/09/27 15:04:31 [debug] 22519#0: *1 event timer del: 12: 1285567531241
> 2010/09/27 15:04:31 [debug] 22519#0: *1 event timer add: 12:
> 60000:1285567531241
> 2010/09/27 15:04:31 [debug] 22519#0: *1 post event 000000000D0BB140
> 2010/09/27 15:04:31 [debug] 22519#0: *1 post event 000000000D0D5150
> 2010/09/27 15:04:31 [debug] 22519#0: *1 delete posted event 000000000D0D5150
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream request:
> "/mogstored_spare_218023808/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream dummy handler
> 2010/09/27 15:04:31 [debug] 22519#0: *1 delete posted event 000000000D0BB140
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream request:
> "/mogstored_spare_218023808/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream process header
> 2010/09/27 15:04:31 [debug] 22519#0: *1 malloc: 000000000D018020:4096
> 2010/09/27 15:04:31 [debug] 22519#0: *1 recv: fd:12 81 of 4096
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs: "OK
> devid=5&fid=58&path=http://192.168.100.11:7500/dev5/0/000/000/0000000058.fid^M"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs param: "devid"="5"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs param: "fid"="58"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs param:
> "path"="http://192.168.100.11:7500/dev5/0/000/000/0000000058.fid"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 tcp_nodelay
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream process non
> buffered downstream
> 2010/09/27 15:04:31 [debug] 22519#0: *1 finalize http upstream request: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 finalize mogilefs request
> 2010/09/27 15:04:31 [debug] 22519#0: *1 free rr peer 1 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 close http upstream connection: 12
> 2010/09/27 15:04:31 [debug] 22519#0: *1 event timer del: 12: 1285567531241
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http output filter
> "/mogstored_spare_218023808/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http copy filter:
> "/mogstored_spare_218023808/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http postpone filter
> "/mogstored_spare_218023808/dabada.txt?" 00007FFFFD434D70
> 2010/09/27 15:04:31 [debug] 22519#0: *1 write new buf t:0 f:0
> 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http write filter: l:0 f:0 s:0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http copy filter: 0
> "/mogstored_spare_218023808/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http finalize request: 0,
> "/mogstored_spare_218023808/dabada.txt?" a:1, c:2
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs finish phase handler:
> state=1, status=0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http wake parent request:
> "/testput/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http posted request:
> "/testput/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 content phase: 9
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs put handler
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs put handler state: 1,
> status: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 posix_memalign:
> 000000000CFD7A80:4096 @16
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http subrequest
> "/mogstored_spare_218028368/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http finalize request: -4,
> "/testput/dabada.txt?" a:0, c:2
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http request count:2 blk:0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http posted request:
> "/mogstored_spare_218028368/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 rewrite phase: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 test location:
> "/mogstored_spare_218042592/"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 test location:
> "/mogstored_spare_218028368/"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 using configuration
> "/mogstored_spare_218028368/"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http cl:37197 max:1048576
> 2010/09/27 15:04:31 [debug] 22519#0: *1 rewrite phase: 2
> 2010/09/27 15:04:31 [debug] 22519#0: *1 post rewrite phase: 3
> 2010/09/27 15:04:31 [debug] 22519#0: *1 generic phase: 4
> 2010/09/27 15:04:31 [debug] 22519#0: *1 generic phase: 5
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http script var:
> "http://192.168.100.11:7500/dev5/0/000/000/0000000058.fid"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http init upstream, client timer: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http script copy: "Host: "
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http script var:
> "192.168.100.11:7500"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http script copy: "
> "
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http script copy: "Connection: close
> "
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http proxy header: "User-Agent:
> curl/7.15.5 (x86_64-redhat-linux-gnu) libcurl/7.15.5 OpenSSL/0.9.8b
> zlib/1.2.3 libidn/0.6.5"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http proxy header: "Accept: */*"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http proxy header:
> "Content-Length: 37197"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http proxy header:
> "PUT /dev5/0/000/000/0000000058.fid HTTP/1.0
> Host: 192.168.100.11:7500
> Connection: close
> User-Agent: curl/7.15.5 (x86_64-redhat-linux-gnu) libcurl/7.15.5
> OpenSSL/0.9.8b zlib/1.2.3 libidn/0.6.5
> Accept: */*
> Content-Length: 37197
> 
> "
> 2010/09/27 15:04:31 [debug] 22519#0: *1 posix_memalign:
> 000000000CFD8A90:4096 @16
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http cleanup add: 000000000CFD8B70
> 2010/09/27 15:04:31 [debug] 22519#0: *1 name was resolved to 192.168.100.11
> 2010/09/27 15:04:31 [debug] 22519#0: *1 get rr peer, try: 1
> 2010/09/27 15:04:31 [debug] 22519#0: *1 socket 12
> 2010/09/27 15:04:31 [debug] 22519#0: *1 epoll add connection: fd:12
> ev:80000005
> 2010/09/27 15:04:31 [debug] 22519#0: *1 connect to 192.168.100.11:7500,
> fd:12 #3
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream connect: -2
> 2010/09/27 15:04:31 [debug] 22519#0: *1 event timer add: 12:
> 60000:1285567531837
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http finalize request: -4,
> "/mogstored_spare_218028368/dabada.txt?" a:1, c:2
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http request count:2 blk:0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 post event 000000000D0D5150
> 2010/09/27 15:04:31 [debug] 22519#0: *1 delete posted event 000000000D0D5150
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream request:
> "/mogstored_spare_218028368/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream send request handler
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream send request
> 2010/09/27 15:04:31 [debug] 22519#0: *1 chain writer buf fl:0 s:234
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream request:
> "/mogstored_spare_218028368/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream send request handler
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream send request
> 2010/09/27 15:04:31 [debug] 22519#0: *1 chain writer buf fl:0 s:234
> 2010/09/27 15:04:31 [debug] 22519#0: *1 chain writer buf fl:1 s:37197
> 2010/09/27 15:04:31 [debug] 22519#0: *1 chain writer in: 000000000CFD8C78
> 2010/09/27 15:04:31 [debug] 22519#0: *1 tcp_nopush
> 2010/09/27 15:04:31 [debug] 22519#0: *1 writev: 234
> 2010/09/27 15:04:31 [debug] 22519#0: *1 sendfile: @0 37197
> 2010/09/27 15:04:31 [debug] 22519#0: *1 sendfile: 15694, @0 15694:37197
> 2010/09/27 15:04:31 [debug] 22519#0: *1 chain writer out: 000000000CFD8C88
> 2010/09/27 15:04:31 [debug] 22519#0: *1 event timer del: 12: 1285567531837
> 2010/09/27 15:04:31 [debug] 22519#0: *1 event timer add: 12:
> 60000:1285567531838
> 2010/09/27 15:04:31 [debug] 22519#0: *1 post event 000000000D0D5150
> 2010/09/27 15:04:31 [debug] 22519#0: *1 delete posted event 000000000D0D5150
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream request:
> "/mogstored_spare_218028368/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream send request handler
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream send request
> 2010/09/27 15:04:31 [debug] 22519#0: *1 chain writer in: 000000000CFD8C88
> 2010/09/27 15:04:31 [debug] 22519#0: *1 sendfile: @15694 21503
> 2010/09/27 15:04:31 [debug] 22519#0: *1 sendfile: 21503, @15694 21503:21503
> 2010/09/27 15:04:31 [debug] 22519#0: *1 chain writer out: 0000000000000000
> 2010/09/27 15:04:31 [debug] 22519#0: *1 event timer del: 12: 1285567531838
> 2010/09/27 15:04:31 [debug] 22519#0: *1 event timer add: 12:
> 60000:1285567531838
> 2010/09/27 15:04:31 [debug] 22519#0: *1 post event 000000000D0BB140
> 2010/09/27 15:04:31 [debug] 22519#0: *1 post event 000000000D0D5150
> 2010/09/27 15:04:31 [debug] 22519#0: *1 delete posted event 000000000D0D5150
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream request:
> "/mogstored_spare_218028368/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream dummy handler
> 2010/09/27 15:04:31 [debug] 22519#0: *1 delete posted event 000000000D0BB140
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream request:
> "/mogstored_spare_218028368/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http upstream process header
> 2010/09/27 15:04:31 [debug] 22519#0: *1 malloc: 000000000CFD9AA0:4096
> 2010/09/27 15:04:31 [debug] 22519#0: *1 recv: fd:12 118 of 4096
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http proxy status 200 "200 OK"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http proxy header:
> "Content-Type: text/html"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http proxy header:
> "Content-Length: 18"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http proxy header: "Server: Perlbal"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http proxy header: "Connection:
> close"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http proxy header done
> 2010/09/27 15:04:31 [debug] 22519#0: *1 finalize http upstream request: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 finalize http proxy request
> 2010/09/27 15:04:31 [debug] 22519#0: *1 free rr peer 1 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 close http upstream connection: 12
> 2010/09/27 15:04:31 [debug] 22519#0: *1 event timer del: 12: 1285567531838
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http output filter
> "/mogstored_spare_218028368/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http copy filter:
> "/mogstored_spare_218028368/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http postpone filter
> "/mogstored_spare_218028368/dabada.txt?" 00007FFFFD434EA0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 write old buf t:0 f:0
> 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 write new buf t:0 f:0
> 0000000000000000, pos 0000000000000000, size: 0 file: 0, size: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http write filter: l:0 f:0 s:0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http copy filter: 0
> "/mogstored_spare_218028368/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http finalize request: 0,
> "/mogstored_spare_218028368/dabada.txt?" a:1, c:1
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs finish phase handler:
> state=2, status=0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http wake parent request:
> "/testput/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http posted request:
> "/testput/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 content phase: 9
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs put handler
> 2010/09/27 15:04:31 [debug] 22519#0: *1 mogilefs put handler state: 2,
> status: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http subrequest
> "/mogstored_spare_218032984/dabada.txt?"
> 2010/09/27 15:04:31 [debug] 22519#0: *1 posix_memalign:
> 000000000D0EEFC0:4096 @16
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http finalize request: -4,
> "/testput/dabada.txt?" a:0, c:1
> 2010/09/27 15:04:31 [debug] 22519#0: *1 set http keepalive handler
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http close request
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http log handler
> 2010/09/27 15:04:31 [debug] 22519#0: *1 run cleanup: 000000000CFDE830
> 2010/09/27 15:04:31 [debug] 22519#0: *1 file cleanup: fd:11
> 2010/09/27 15:04:31 [debug] 22519#0: *1 free: 000000000CFD9AA0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 free: 000000000D018020
> 2010/09/27 15:04:31 [debug] 22519#0: *1 free: 000000000D016010
> 2010/09/27 15:04:31 [debug] 22519#0: *1 free: 000000000CFDDBA0, unused: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 free: 000000000CFDEBB0, unused: 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 free: 000000000CFD7A80, unused: 2
> 2010/09/27 15:04:31 [debug] 22519#0: *1 free: 000000000CFD8A90, unused: 124
> 2010/09/27 15:04:31 [debug] 22519#0: *1 free: 000000000D0EEFC0, unused: 3659
> 2010/09/27 15:04:31 [debug] 22519#0: *1 event timer add: 10:
> 1000:1285567472841
> 2010/09/27 15:04:31 [debug] 22519#0: *1 free: 000000000CFDD6B0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 free: 000000000CFD3340
> 2010/09/27 15:04:31 [debug] 22519#0: *1 hc free: 0000000000000000 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 hc busy: 0000000000000000 0
> 2010/09/27 15:04:31 [debug] 22519#0: *1 post event 000000000D0BB0D8
> 2010/09/27 15:04:31 [debug] 22519#0: *1 delete posted event 000000000D0BB0D8
> 2010/09/27 15:04:31 [debug] 22519#0: *1 http keepalive handler
> 2010/09/27 15:04:31 [debug] 22519#0: *1 malloc: 000000000CFD3340:1024
> 2010/09/27 15:04:31 [debug] 22519#0: *1 recv: fd:10 -1 of 1024
> 2010/09/27 15:04:31 [debug] 22519#0: *1 recv() not ready (11: Resource
> temporarily unavailable)
> 2010/09/27 15:04:32 [debug] 22519#0: *1 event timer del: 10: 1285567472841
> 2010/09/27 15:04:32 [debug] 22519#0: *1 http keepalive handler
> 2010/09/27 15:04:32 [debug] 22519#0: *1 close http connection: 10
> 2010/09/27 15:04:32 [debug] 22519#0: *1 free: 000000000CFD3340
> 2010/09/27 15:04:32 [debug] 22519#0: *1 free: 0000000000000000
> 2010/09/27 15:04:32 [debug] 22519#0: *1 free: 000000000CFE8990, unused: 8
> 2010/09/27 15:04:32 [debug] 22519#0: *1 free: 000000000D03D210, unused: 128
> 
> _______________________________________________
> nginx mailing list
> nginx at nginx.org
> http://nginx.org/mailman/listinfo/nginx

-- 
-- 
Regards,
Valery Kholodkov



More information about the nginx mailing list