Can't seem to get the right buffer size set

Server NGINX
1

To try to maximize the possibility I’ve set the following:

    fastcgi_buffer_size 16k;
    fastcgi_buffers 64 16k;
    fastcgi_busy_buffers_size 16k;

This is for requests to .php scripts

Even this, just in case:

    proxy_buffer_size 16k;
    proxy_buffers 64 16k;
    gzip_buffers 64 16k;

Now by reading past emails this looks like I should be able to buffer

fastcgi: (64 * 16) + 16 = 1040k per request
proxy: (64 * 16) + 16 = 1040k per request
even gzip 64 * 16 = 1024k

I am sending only 512k POST with a few headers - definately under a
megabyte.

I also have these, not sure if it matters.

    client_max_body_size 251m;
    client_header_buffer_size 8k;
    client_body_buffer_size 256k;
    large_client_header_buffers 16 8k;

Still getting this:

2008/11/25 00:20:58 [warn] 24362#0: *195 a client request body is
buffered to a temporary file /var/lib/nginx/body/0000000002, client:
1.2.3.4, server: foo.com, request: “POST /u/temp.php HTTP/1.1”, host:
“foo.com”

Is there a possibility the request is larger or something else is
going on? Is there anything I can log to check the full size of the
request?

2

Hello!

On Tue, Nov 25, 2008 at 12:22:09AM -0800, mike wrote:

    proxy_buffer_size 16k;
    proxy_buffers 64 16k;
    gzip_buffers 64 16k;

Now by reading past emails this looks like I should be able to buffer

fastcgi: (64 * 16) + 16 = 1040k per request
proxy: (64 * 16) + 16 = 1040k per request
even gzip 64 * 16 = 1024k

All this settings are irrelevant for client requests, they are
used only for response buffering.

I am sending only 512k POST with a few headers - definately under a megabyte.

I also have these, not sure if it matters.

    client_max_body_size 251m;
    client_header_buffer_size 8k;
    client_body_buffer_size 256k;

So you have you client request body buffer set to 256k.
Anything that don’t fit will go to disk.

Maxim D.

3

On Tue, Nov 25, 2008 at 2:20 AM, Maxim D. [email protected]
wrote:

Hello!

I am sending only 512k POST with a few headers - definately under a megabyte.

I also have these, not sure if it matters.

    client_max_body_size 251m;
    client_header_buffer_size 8k;
    client_body_buffer_size 256k;

So you have you client request body buffer set to 256k.
Anything that don’t fit will go to disk.

Ah! I was editing response the whole time!

So the client_body_buffer_size is the one I want it appears? I will
try that one (duh!)

4

This works:

    client_body_buffer_size 408k;

Oddly, the chunk size of the upload is 512,000 bytes. It appears 408k
is the lowest I can set with a multiple of 8k. At 400k it still
buffers. Why is there a difference of 104k? (512k - 408k)

That’s a curious difference…

On Tue, Nov 25, 2008 at 11:25 AM, mike [email protected] wrote:

Ah! I was editing response the whole time!

5

here’s a debug log, no clue what most of this means :slight_smile:

note the content length though…

2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:01 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:63 1460 of 8860
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body recv
1460
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body rest
7400
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:63 -1 of 7400
2008/11/25 00:24:01 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body rest
7400
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer: 63, old:
1227601501608, new: 1227601501766
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:01 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:63 1460 of 7400
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body recv
1460
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body rest
5940
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:63 -1 of 5940
2008/11/25 00:24:01 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body rest
5940
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer: 63, old:
1227601501608, new: 1227601501766
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:01 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:63 1176 of 5940
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body recv
1176
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body rest
4764
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:63 -1 of 4764
2008/11/25 00:24:01 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body rest
4764
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer: 63, old:
1227601501608, new: 1227601501767
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:01 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:63 1460 of 4764
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body recv
1460
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body rest
3304
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:63 -1 of 3304
2008/11/25 00:24:01 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body rest
3304
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer: 63, old:
1227601501608, new: 1227601501841
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:01 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:63 1460 of 3304
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body recv
1460
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body rest
1844
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:63 -1 of 1844
2008/11/25 00:24:01 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body rest
1844
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer: 63, old:
1227601501608, new: 1227601501841
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:01 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:63 1176 of 1844
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body recv
1176
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body rest
668
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:63 -1 of 668
2008/11/25 00:24:01 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body rest
668
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer: 63, old:
1227601501608, new: 1227601501842
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:01 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:63 668 of 668
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body recv
668
2008/11/25 00:24:01 [debug] 14665#0: *185 http client request body rest
0
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer del: 63:
1227601501608
2008/11/25 00:24:01 [debug] 14665#0: *185 write: 23, 0000000000FAAFB0,
249856, 262144
2008/11/25 00:24:01 [debug] 14665#0: *185 http init upstream, client
timer: 0
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param: "QUERY_STRING:
"
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param:
“REQUEST_METHOD: POST”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param:
“CONTENT_TYPE: application/octet-stream”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param:
“CONTENT_LENGTH: 512000”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param:
“SCRIPT_FILENAME: /home/mike/web/u/temp.php”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param: “SCRIPT_NAME:
/u/temp.php”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param: “REQUEST_URI:
/u/temp.php”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param:
“DOCUMENT_URI: /u/temp.php”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param:
“DOCUMENT_ROOT: /home/mike/web”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param:
“SERVER_PROTOCOL: HTTP/1.1”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param:
“GATEWAY_INTERFACE: CGI/1.1”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param:
“SERVER_SOFTWARE: nginx/0.7.22”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param: “REMOTE_ADDR:
1.2.3.4”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param: “REMOTE_PORT:
3001”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param: “SERVER_ADDR:
10.13.220.85”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param: “SERVER_PORT:
80”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script var: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param: “SERVER_NAME:
mikehost.com”
2008/11/25 00:24:01 [debug] 14665#0: *185 http script copy: “”
2008/11/25 00:24:01 [debug] 14665#0: *185 fastcgi param:
“REDIRECT_STATUS: 200”
2008/11/25 00:24:01 [debug] 14665#0: *185 malloc: 0000000000D32A70:4096
2008/11/25 00:24:01 [debug] 14665#0: *185 http cleanup add:
0000000000D32FB0
2008/11/25 00:24:01 [debug] 14665#0: *185 get rr peer, try: 1
2008/11/25 00:24:01 [debug] 14665#0: *185 socket 59
2008/11/25 00:24:01 [debug] 14665#0: *185 epoll add connection: fd:59
ev:80000005
2008/11/25 00:24:01 [debug] 14665#0: *185 connect to 127.0.0.1:11000,
fd:59 #308
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream connect: -2
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer add: 59:
60000:1227601501918
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F1D300
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F1D300
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream send request
handler
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream send request
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:1200
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:20480
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer buf fl:0 s:8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer in:
0000000000D33208
2008/11/25 00:24:01 [debug] 14665#0: *185 tcp_nopush
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 1200
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @0 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 32768, @0
32768:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @32768 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 31560, @32768
31560:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer out:
0000000000D33238
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer del: 59:
1227601501918
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer add: 59:
60000:1227601501918
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F1D300
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F1D300
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream send request
handler
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream send request
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer in:
0000000000D33238
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @64328 1208
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 1208, @64328
1208:1208
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @65536 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 32768, @65536
32768:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @98304 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 32768, @98304
32768:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @131072 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 32768, @131072
32768:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @163840 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 32768, @163840
32768:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @196608 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 31520, @196608
31520:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer out:
0000000000D332D8
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer del: 59:
1227601501918
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer add: 59:
60000:1227601501918
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F1D300
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F1D300
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream send request
handler
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream send request
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer in:
0000000000D332D8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @228128 1248
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 1248, @228128
1248:1248
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @229376 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 32768, @229376
32768:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @262144 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 32768, @262144
32768:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @294912 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 32768, @294912
32768:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @327680 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 31488, @327680
31488:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer out:
0000000000D33358
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer del: 59:
1227601501918
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer add: 59:
60000:1227601501919
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F1D300
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F1D300
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream send request
handler
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream send request
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer in:
0000000000D33358
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @359168 1280
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 1280, @359168
1280:1280
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @360448 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 32768, @360448
32768:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @393216 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 31472, @393216
31472:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer out:
0000000000D33398
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer del: 59:
1227601501919
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer add: 59:
60000:1227601501919
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F1D300
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F1D300
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream send request
handler
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream send request
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer in:
0000000000D33398
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @424688 1296
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 1296, @424688
1296:1296
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @425984 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 32768, @425984
32768:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @458752 32768
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 32768, @458752
32768:32768
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: @491520 20480
2008/11/25 00:24:01 [debug] 14665#0: *185 sendfile: 20480, @491520
20480:20480
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 chain writer out:
0000000000000000
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer del: 59:
1227601501919
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer add: 59:
60000:1227601501919
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F032F0
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F1D300
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F1D300
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream dummy handler
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F032F0
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream process header
2008/11/25 00:24:01 [debug] 14665#0: *185 malloc: 0000000000F66E10:16384
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:59 56 of 16384
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 01
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 06
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 00
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 01
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 00
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 1B
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 05
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 00
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record length: 27
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi parser: 0
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi header:
“Content-type: text/html”
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi parser: 1
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi header done
2008/11/25 00:24:01 [debug] 14665#0: *185 xslt filter header
2008/11/25 00:24:01 [debug] 14665#0: *185 HTTP/1.1 200 OK
Server: nginx
Date: Tue, 25 Nov 2008 08:24:01 GMT
Content-Type: text/html; charset=utf-8
Transfer-Encoding: chunked
Connection: keep-alive
Vary: Accept-Encoding
Content-Encoding: gzip

2008/11/25 00:24:01 [debug] 14665#0: *185 write new buf t:1 f:0
0000000000D33760, pos 0000000000D33760, size: 210 file: 0, size: 0
2008/11/25 00:24:01 [debug] 14665#0: *185 http write filter: l:0 f:0
s:210
2008/11/25 00:24:01 [debug] 14665#0: *185 file cleanup: fd:23
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream process upstream
2008/11/25 00:24:01 [debug] 14665#0: *185 pipe read upstream: 1
2008/11/25 00:24:01 [debug] 14665#0: *185 pipe preread: 21
2008/11/25 00:24:01 [debug] 14665#0: *185 readv: 1:16328
2008/11/25 00:24:01 [debug] 14665#0: *185 pipe recv chain: 0
2008/11/25 00:24:01 [debug] 14665#0: *185 pipe buf free s:0 t:1 f:0
0000000000F66E10, pos 0000000000F66E33, size: 21 file: 0, size: 0
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 01
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 03
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 00
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 01
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 00
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 08
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 00
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record byte: 00
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi record length: 8
2008/11/25 00:24:01 [debug] 14665#0: *185 http fastcgi sent end request
2008/11/25 00:24:01 [debug] 14665#0: *185 free: 0000000000F66E10
2008/11/25 00:24:01 [debug] 14665#0: *185 pipe write downstream: 1
2008/11/25 00:24:01 [debug] 14665#0: *185 pipe write downstream done
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer: 59, old:
1227601501919, new: 1227601501975
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream exit:
0000000000000000
2008/11/25 00:24:01 [debug] 14665#0: *185 finalize http upstream
request: 0
2008/11/25 00:24:01 [debug] 14665#0: *185 finalize http fastcgi request
2008/11/25 00:24:01 [debug] 14665#0: *185 free rr peer 1 0
2008/11/25 00:24:01 [debug] 14665#0: *185 close http upstream
connection: 59
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer del: 59:
1227601501919
2008/11/25 00:24:01 [debug] 14665#0: *185 http upstream temp fd: -1
2008/11/25 00:24:01 [debug] 14665#0: *185 http output filter
“/tmp/u/temp.php?”
2008/11/25 00:24:01 [debug] 14665#0: *185 copy filter:
“/tmp/u/temp.php?”
2008/11/25 00:24:01 [debug] 14665#0: *185 xslt filter body
2008/11/25 00:24:01 [debug] 14665#0: *185 http postpone filter
“/tmp/u/temp.php?” 00007FFF82362440
2008/11/25 00:24:01 [debug] 14665#0: *185 http postpone filter out
“/tmp/u/temp.php?”
2008/11/25 00:24:01 [debug] 14665#0: *185 malloc:
0000000000FEAFC0:270336
2008/11/25 00:24:01 [debug] 14665#0: *185 gzip alloc: n:1 s:5928
a:8192 p:0000000000FEAFC0
2008/11/25 00:24:01 [debug] 14665#0: *185 gzip alloc: n:32768 s:2
a:65536 p:0000000000FECFC0
2008/11/25 00:24:01 [debug] 14665#0: *185 gzip alloc: n:32768 s:2
a:65536 p:0000000000FFCFC0
2008/11/25 00:24:01 [debug] 14665#0: *185 gzip alloc: n:32768 s:2
a:65536 p:000000000100CFC0
2008/11/25 00:24:01 [debug] 14665#0: *185 gzip alloc: n:16384 s:4
a:65536 p:000000000101CFC0
2008/11/25 00:24:01 [debug] 14665#0: *185 gzip in: 0000000000D33A30
2008/11/25 00:24:01 [debug] 14665#0: *185 gzip in_buf:0000000000D33960
ni:0000000000000000 ai:0
2008/11/25 00:24:01 [debug] 14665#0: *185 malloc: 00000000007C4A40:4096
2008/11/25 00:24:01 [debug] 14665#0: *185 malloc: 0000000000F66E10:16384
2008/11/25 00:24:01 [debug] 14665#0: *185 deflate in:
ni:0000000000000000 no:0000000000F66E10 ai:0 ao:16384 fl:4 redo:0
2008/11/25 00:24:01 [debug] 14665#0: *185 deflate out:
ni:0000000000000000 no:0000000000F66E12 ai:0 ao:16382 rc:1
2008/11/25 00:24:01 [debug] 14665#0: *185 gzip in_buf:0000000000D33960
pos:0000000000000000
2008/11/25 00:24:01 [debug] 14665#0: *185 free: 0000000000FEAFC0
2008/11/25 00:24:01 [debug] 14665#0: *185 http chunk: 10
2008/11/25 00:24:01 [debug] 14665#0: *185 http chunk: 10
2008/11/25 00:24:01 [debug] 14665#0: *185 write old buf t:1 f:0
0000000000D33760, pos 0000000000D33760, size: 210 file: 0, size: 0
2008/11/25 00:24:01 [debug] 14665#0: *185 write new buf t:1 f:0
0000000000000000, pos 00000000007C4B68, size: 4 file: 0, size: 0
2008/11/25 00:24:01 [debug] 14665#0: *185 write new buf t:0 f:0
0000000000000000, pos 0000000000773FC8, size: 10 file: 0, size: 0
2008/11/25 00:24:01 [debug] 14665#0: *185 write new buf t:1 f:0
0000000000F66E10, pos 0000000000F66E10, size: 10 file: 0, size: 0
2008/11/25 00:24:01 [debug] 14665#0: *185 write new buf t:0 f:0
0000000000000000, pos 0000000000512118, size: 7 file: 0, size: 0
2008/11/25 00:24:01 [debug] 14665#0: *185 http write filter: l:1 f:1
s:241
2008/11/25 00:24:01 [debug] 14665#0: *185 http write filter limit 0
2008/11/25 00:24:01 [debug] 14665#0: *185 writev: 241
2008/11/25 00:24:01 [debug] 14665#0: *185 http write filter
0000000000000000
2008/11/25 00:24:01 [debug] 14665#0: *185 copy filter: 0
“/tmp/u/temp.php?”
2008/11/25 00:24:01 [debug] 14665#0: *185 http finalize request: 0,
“/tmp/u/temp.php?”
2008/11/25 00:24:01 [debug] 14665#0: *185 set http keepalive handler
2008/11/25 00:24:01 [debug] 14665#0: *185 http close request
2008/11/25 00:24:01 [debug] 14665#0: *185 http log handler
2008/11/25 00:24:01 [debug] 14665#0: *185 free: 0000000000F66E10
2008/11/25 00:24:01 [debug] 14665#0: *185 free: 0000000000000000
2008/11/25 00:24:01 [debug] 14665#0: *185 free: 0000000000000000
2008/11/25 00:24:01 [debug] 14665#0: *185 free: 0000000000FAAFB0
2008/11/25 00:24:01 [debug] 14665#0: *185 free: 00000000007CA2F0,
unused: 3
2008/11/25 00:24:01 [debug] 14665#0: *185 free: 0000000000E9B010,
unused: 0
2008/11/25 00:24:01 [debug] 14665#0: *185 free: 0000000000D32A70,
unused: 0
2008/11/25 00:24:01 [debug] 14665#0: *185 free: 00000000007C4A40,
unused: 3537
2008/11/25 00:24:01 [debug] 14665#0: *185 event timer add: 63:
30000:1227601471975
2008/11/25 00:24:01 [debug] 14665#0: *185 free: 0000000000A91FF0
2008/11/25 00:24:01 [debug] 14665#0: *185 free: 0000000000F76E90
2008/11/25 00:24:01 [debug] 14665#0: *185 hc free: 0000000000000000 0
2008/11/25 00:24:01 [debug] 14665#0: *185 hc busy: 0000000000000000 0
2008/11/25 00:24:01 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:01 [debug] 14665#0: *185 http keepalive handler
2008/11/25 00:24:01 [debug] 14665#0: *185 malloc: 0000000000F76E90:8192
2008/11/25 00:24:01 [debug] 14665#0: *185 recv: fd:63 -1 of 8192
2008/11/25 00:24:01 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http keepalive handler
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 674 of 8192
2008/11/25 00:24:02 [debug] 14665#0: *185 malloc: 00000000009025E0:1280
2008/11/25 00:24:02 [debug] 14665#0: *185 malloc: 00000000007CA2F0:4096
2008/11/25 00:24:02 [debug] 14665#0: *185 http process request line
2008/11/25 00:24:02 [debug] 14665#0: *185 http request line: “POST
/u/temp.php HTTP/1.1”
2008/11/25 00:24:02 [debug] 14665#0: *185 http uri: “/u/temp.php”
2008/11/25 00:24:02 [debug] 14665#0: *185 http args: “”
2008/11/25 00:24:02 [debug] 14665#0: *185 http exten: “php”
2008/11/25 00:24:02 [debug] 14665#0: *185 http process request header
line
2008/11/25 00:24:02 [debug] 14665#0: *185 http header: “Host: foo.com”
2008/11/25 00:24:02 [debug] 14665#0: 185 http header: “User-Agent:
Mozilla/5.0 (Windows; U; Windows NT 5.1; en-US; rv:1.8.1.18)
Gecko/20081029 Firefox/2.0.0.18”
2008/11/25 00:24:02 [debug] 14665#0: 185 http header: "Accept:
text/xml,application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,/;q=0.5"
2008/11/25 00:24:02 [debug] 14665#0: *185 http header:
“Accept-Language: en-us,en;q=0.5”
2008/11/25 00:24:02 [debug] 14665#0: *185 http header:
“Accept-Encoding: gzip,deflate”
2008/11/25 00:24:02 [debug] 14665#0: 185 http header:
"Accept-Charset: ISO-8859-1,utf-8;q=0.7,;q=0.7"
2008/11/25 00:24:02 [debug] 14665#0: *185 http header: “Keep-Alive: 300”
2008/11/25 00:24:02 [debug] 14665#0: *185 http header: “Connection:
keep-alive”
2008/11/25 00:24:02 [debug] 14665#0: *185 http header:
“content-disposition: attachment; name=“22af76a1-ace0”;
filename=“shadles-san-ramon-dvd.wmv””
2008/11/25 00:24:02 [debug] 14665#0: *185 http header: “Content-Type:
application/octet-stream”
2008/11/25 00:24:02 [debug] 14665#0: *185 http header: “Content-Range:
bytes 512000-1024000/335754419”
2008/11/25 00:24:02 [debug] 14665#0: *185 http header: “Content-Length:
512000”
2008/11/25 00:24:02 [debug] 14665#0: *185 http header: “Pragma:
no-cache”
2008/11/25 00:24:02 [debug] 14665#0: *185 http header: “Cache-Control:
no-cache”
2008/11/25 00:24:02 [debug] 14665#0: *185 http header done
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer del: 63:
1227601471975
2008/11/25 00:24:02 [debug] 14665#0: *185 generic phase: 0
2008/11/25 00:24:02 [debug] 14665#0: *185 add cleanup: 00000000007CAE00
2008/11/25 00:24:02 [debug] 14665#0: *185 generic phase: 1
2008/11/25 00:24:02 [debug] 14665#0: 185 http script regex:
"^/~mike/(.)"
2008/11/25 00:24:02 [debug] 14665#0: *185 http script copy:
“/tmp/u/temp.php”
2008/11/25 00:24:02 [debug] 14665#0: *185 http script capture:
“/tmp/u/temp.php”
2008/11/25 00:24:02 [debug] 14665#0: *185 http script regex end
2008/11/25 00:24:02 [debug] 14665#0: *185 test location: “/robots.txt”
2008/11/25 00:24:02 [debug] 14665#0: *185 test location: ~ “/.ht”
2008/11/25 00:24:02 [debug] 14665#0: *185 test location: ~
“.(jpg|jpeg|gif|css|png|js|ico|html)$”
2008/11/25 00:24:02 [debug] 14665#0: *185 test location: ~ “.php”
2008/11/25 00:24:02 [debug] 14665#0: *185 using configuration “.php”
2008/11/25 00:24:02 [debug] 14665#0: *185 http cl:512000 max:263192576
2008/11/25 00:24:02 [debug] 14665#0: *185 generic phase: 3
2008/11/25 00:24:02 [debug] 14665#0: *185 post rewrite phase: 4
2008/11/25 00:24:02 [debug] 14665#0: *185 generic phase: 5
2008/11/25 00:24:02 [debug] 14665#0: *185 add cleanup: 00000000007CAF68
2008/11/25 00:24:02 [debug] 14665#0: *185 generic phase: 6
2008/11/25 00:24:02 [debug] 14665#0: *185 generic phase: 7
2008/11/25 00:24:02 [debug] 14665#0: *185 access phase: 8
2008/11/25 00:24:02 [debug] 14665#0: *185 access phase: 9
2008/11/25 00:24:02 [debug] 14665#0: *185 post access phase: 10
2008/11/25 00:24:02 [debug] 14665#0: *185 malloc: 0000000000D32A70:4096
2008/11/25 00:24:02 [debug] 14665#0: *185 malloc:
0000000000FAAFB0:262144
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 262144
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
512000
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer add: 63:
60000:1227601502093
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 1460 of 262144
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
1460
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
510540
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 260684
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
510540
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer: 63, old:
1227601502093, new: 1227601502094
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 1962 of 260684
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
1962
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
508578
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 258722
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
508578
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer: 63, old:
1227601502093, new: 1227601502094
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 1460 of 258722
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
1460
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
507118
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 257262
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
507118
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer: 63, old:
1227601502093, new: 1227601502095
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 1460 of 257262
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
1460
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
505658
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 255802
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
505658
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer: 63, old:
1227601502093, new: 1227601502096
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 1176 of 255802
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
1176
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
504482
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 254626
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
504482
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer: 63, old:
1227601502093, new: 1227601502096
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 1460 of 254626
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
1460
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
503022
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 253166
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
503022
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer: 63, old:
1227601502093, new: 1227601502170
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 1460 of 253166
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
1460
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
501562
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 251706
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
501562
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer: 63, old:
1227601502093, new: 1227601502171
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 1176 of 251706
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
1176
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
500386
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 250530
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
500386
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer: 63, old:
1227601502093, new: 1227601502171
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 1460 of 250530
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
1460
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
498926
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 249070
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
498926
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer: 63, old:
1227601502093, new: 1227601502173
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 1460 of 249070
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
1460
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
497466
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 247610
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
497466
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer: 63, old:
1227601502093, new: 1227601502174
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 1176 of 247610
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
1176
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
496290
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 246434
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
496290
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer: 63, old:
1227601502093, new: 1227601502174
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 1460 of 246434
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
1460
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
494830
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 244974
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
494830
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer: 63, old:
1227601502093, new: 1227601502248
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 1460 of 244974
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
1460
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
493370
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 243514
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
493370
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer: 63, old:
1227601502093, new: 1227601502249
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 http read client request body
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 1176 of 243514
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
1176
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
492194
2008/11/25 00:24:02 [debug] 14665#0: *185 recv: fd:63 -1 of 242338
2008/11/25 00:24:02 [debug] 14665#0: *185 recv() not ready (11:
Resource temporarily unavailable)
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body recv
-2
2008/11/25 00:24:02 [debug] 14665#0: *185 http client request body rest
492194
2008/11/25 00:24:02 [debug] 14665#0: *185 event timer: 63, old:
1227601502093, new: 1227601502249
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F041F8
2008/11/25 00:24:02 [debug] 14665#0: *185 post event 0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F1E208
2008/11/25 00:24:02 [debug] 14665#0: *185 http request empty handler
2008/11/25 00:24:02 [debug] 14665#0: *185 delete posted event
0000000000F041F8