Cycle with SSI ON in nginx config

Hi to all,

I’m trying to use memcache with nginx and if ssi set to on to add
additional info on cached pages :

So configuration is :

:::::::::

server {

    listen       *:8080;

    server_name  blabla;

    access_log off;



    location / {

add_header Content-Type “text/html; charset=utf-8”;

    ssi_types       application/octet-stream text/html;



    if ($request_method = POST) {

                proxy_pass http://127.0.0.1:8010;

                break;

        }



    set  $memcached_key  "$host:$request_uri";

    ssi on;



    memcached_pass   10.100.0.47:11211;



    proxy_intercept_errors  on;



    default_type     text/html;

    error_page       404 502 403 = /_fall_ ;

    }



    location = /dyn {



        internal;

        proxy_set_header   Referer          $http_referer;

        proxy_set_header   Host             $host;

        proxy_set_header   X-Real-IP        $remote_addr;

        proxy_set_header   X-Forwarded-For 

$proxy_add_x_forwarded_for;

        proxy_redirect off;



        proxy_pass       http://127.0.0.1:8010;



    }



    location = /_fall_ {

        internal;

        ssi on;

        proxy_pass       http://127.0.0.1:8010;

proxy_pass http://127.0.0.1:8010$request_uri;

        proxy_set_header   Referer          $http_referer;

        proxy_set_header   Host             $host;

        proxy_set_header   X-Real-IP        $remote_addr;

        proxy_set_header   X-Forwarded-For 

$proxy_add_x_forwarded_for;

        proxy_redirect off;



        client_max_body_size       10m;

        client_body_buffer_size    128k;



        proxy_connect_timeout      90;

        proxy_send_timeout         90;

        proxy_read_timeout         90;



        proxy_buffer_size          4k;

        proxy_buffers              4 32k;

        proxy_busy_buffers_size    64k;

        proxy_temp_file_write_size 64k;

    }

}

:

When I put on php file

I see ssi cycle :…

Nginx debug :

Server: nginx/0.6.32

Date: Thu, 29 Jan 2009 12:54:53 GMT

Content-Type: text/html; charset=windows-1251

Transfer-Encoding: chunked

Connection: close

Expires: Thu, 19 Nov 1981 08:52:00 GMT

Cache-Control: no-store, no-cache, must-revalidate, post-check=0,
pre-check=0

Pragma: no-cache

Content-Encoding: gzip

2009/01/29 14:54:53 [debug] 28499#0: *7 write new buf t:1 f:0
0000000002115D20, pos 0000000002115D20, size: 333 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 http write filter: l:0 f:0 s:333

2009/01/29 14:54:53 [debug] 28499#0: *7 http upstream process upstream

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe read upstream: 1

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe preread: 3760

2009/01/29 14:54:53 [debug] 28499#0: *7 input buf #0

2009/01/29 14:54:53 [debug] 28499#0: *7 malloc: 0000000002116B30:32768

2009/01/29 14:54:53 [debug] 28499#0: *7 readv: 1:32768

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe recv chain: 5157

2009/01/29 14:54:53 [debug] 28499#0: *7 readv: 1:27611

2009/01/29 14:54:53 [debug] 28499#0: *7 readv() not ready (11: Resource
temporarily unavailable)

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe recv chain: -2

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe buf in s:1 t:1 f:0
00000000020FCD10, pos 00000000020FCE60, size: 3760 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe buf free s:0 t:1 f:0
0000000002116B30, pos 0000000002116B30, size: 5157 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe write downstream: 1

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe write busy: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe write buf ls:1
00000000020FCE60
3760

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe write:
out:00000000020FCCE0,
f:0

2009/01/29 14:54:53 [debug] 28499#0: *7 http output filter “/fall?”

2009/01/29 14:54:53 [debug] 28499#0: *7 copy filter: “/fall?”

2009/01/29 14:54:53 [debug] 28499#0: *7 http ssi filter “/fall

2009/01/29 14:54:53 [debug] 28499#0: *7 saved: 0 state: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 parse: -2, looked: 0
00000000020FCE60-00000000020FDD10

2009/01/29 14:54:53 [debug] 28499#0: *7 saved: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 ssi out: 0000000002116080
00000000020FCE60

2009/01/29 14:54:53 [debug] 28499#0: *7 http postpone filter “/fall?”
00000000021160D0

2009/01/29 14:54:53 [debug] 28499#0: *7 http postpone filter out
“/fall?”

2009/01/29 14:54:53 [debug] 28499#0: *7 malloc: 00007F659E377010:270336

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip alloc: n:1 s:5928 a:8192
p:00007F659E377010

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip alloc: n:32768 s:2 a:65536
p:00007F659E379010

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip alloc: n:32768 s:2 a:65536
p:00007F659E389010

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip alloc: n:32768 s:2 a:65536
p:00007F659E399010

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip alloc: n:16384 s:4 a:65536
p:00007F659E3A9010

2009/01/29 14:54:53 [debug] 28499#0: *7 http chunk: 10

2009/01/29 14:54:53 [debug] 28499#0: *7 write old buf t:1 f:0
0000000002115D20, pos 0000000002115D20, size: 333 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 write new buf t:1 f:0
0000000000000000, pos 00000000021161A0, size: 3 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 write new buf t:0 f:0
0000000000000000, pos 0000000000676448, size: 10 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 write new buf t:0 f:0
0000000000000000, pos 000000000046323D, size: 2 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 http write filter: l:0 f:0 s:348

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in: 0000000002116238

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in_buf:0000000002116080
ni:00000000020FCE60 ai:3760

2009/01/29 14:54:53 [debug] 28499#0: *7 malloc: 000000000211EB40:4096

2009/01/29 14:54:53 [debug] 28499#0: *7 deflate in: ni:00000000020FCE60
no:000000000211EB40 ai:3760 ao:4096 fl:0 redo:0

2009/01/29 14:54:53 [debug] 28499#0: *7 deflate out: ni:00000000020FDD10
no:000000000211EB40 ai:0 ao:4096 rc:0

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in_buf:0000000002116080
pos:00000000020FCE60

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in: 0000000000000000

2009/01/29 14:54:53 [debug] 28499#0: *7 copy filter: 0 “/fall?”

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe write busy: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe write:
out:0000000000000000,
f:0

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe read upstream: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe buf free s:0 t:1 f:0
0000000002116B30, pos 0000000002116B30, size: 5157 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe buf free s:0 t:1 f:0
00000000020FCD10, pos 00000000020FCD10, size: 0 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 event timer: 15, old:
1233233783830,
new: 1233233783902

2009/01/29 14:54:53 [debug] 28499#0: posted event 0000000000000000

2009/01/29 14:54:53 [debug] 28499#0: worker cycle

2009/01/29 14:54:53 [debug] 28499#0: accept mutex locked

2009/01/29 14:54:53 [debug] 28499#0: epoll timer: 89928

2009/01/29 14:54:53 [debug] 28499#0: epoll: fd:15 ev:0005
d:00007F65A05301F1

2009/01/29 14:54:53 [debug] 28499#0: *7 post event 00007F659E4BD148

2009/01/29 14:54:53 [debug] 28499#0: *7 post event 00007F659E3BA148

2009/01/29 14:54:53 [debug] 28499#0: timer delta: 1

2009/01/29 14:54:53 [debug] 28499#0: posted events 00007F659E3BA148

2009/01/29 14:54:53 [debug] 28499#0: posted event 00007F659E3BA148

2009/01/29 14:54:53 [debug] 28499#0: *7 delete posted event
00007F659E3BA148

2009/01/29 14:54:53 [debug] 28499#0: *7 http upstream dummy handler

2009/01/29 14:54:53 [debug] 28499#0: posted event 00007F659E4BD148

2009/01/29 14:54:53 [debug] 28499#0: *7 delete posted event
00007F659E4BD148

2009/01/29 14:54:53 [debug] 28499#0: *7 http upstream process upstream

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe read upstream: 1

2009/01/29 14:54:53 [debug] 28499#0: *7 readv: 2:4096

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe recv chain: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe buf free s:0 t:1 f:0
0000000002116B30, pos 0000000002116B30, size: 5157 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe buf free s:0 t:1 f:0
00000000020FCD10, pos 00000000020FCD10, size: 0 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 input buf #1

2009/01/29 14:54:53 [debug] 28499#0: *7 free: 00000000020FCD10

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe write downstream: 1

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe write downstream flush in

2009/01/29 14:54:53 [debug] 28499#0: *7 http output filter “/fall?”

2009/01/29 14:54:53 [debug] 28499#0: *7 copy filter: “/fall?”

2009/01/29 14:54:53 [debug] 28499#0: *7 http ssi filter “/fall

2009/01/29 14:54:53 [debug] 28499#0: *7 saved: 0 state: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 parse: 0, looked: 0
0000000002116B30-0000000002117AE6

2009/01/29 14:54:53 [debug] 28499#0: *7 saved: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 ssi out: 0000000002116080
0000000002116B30

2009/01/29 14:54:53 [debug] 28499#0: *7 http postpone filter “/fall?”
00000000021160D0

2009/01/29 14:54:53 [debug] 28499#0: *7 http postpone filter out
“/fall?”

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in: 0000000002116448

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in_buf:0000000002116080
ni:0000000002116B30 ai:4022

2009/01/29 14:54:53 [debug] 28499#0: *7 deflate in: ni:0000000002116B30
no:000000000211EB40 ai:4022 ao:4096 fl:0 redo:0

2009/01/29 14:54:53 [debug] 28499#0: *7 deflate out: ni:0000000002117AE6
no:000000000211EB40 ai:0 ao:4096 rc:0

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in_buf:0000000002116080
pos:0000000002116B30

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in: 0000000000000000

2009/01/29 14:54:53 [debug] 28499#0: *7 ssi include:
“/http://blablabla/dyn/calculate_minutes_ago.php?date=

2009/01/29 14:54:53 [debug] 28499#0: *7 malloc: 00000000020FCD10:4096

2009/01/29 14:54:53 [debug] 28499#0: *7 http subrequest
“/http://blablabla/dyn/calculate_minutes_ago.php?date=

2009/01/29 14:54:53 [debug] 28499#0: *7 generic phase: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 find location for
“/http://blablabla/dyn/calculate_minutes_ago.php

2009/01/29 14:54:53 [debug] 28499#0: *7 find location: “/”

2009/01/29 14:54:53 [debug] 28499#0: *7 find location: = “/fall

2009/01/29 14:54:53 [debug] 28499#0: *7 find location: = “/dyn”

2009/01/29 14:54:53 [debug] 28499#0: *7 find location for
“/http://blablabla/dyn/calculate_minutes_ago.php

2009/01/29 14:54:53 [debug] 28499#0: *7 using configuration “/”

2009/01/29 14:54:53 [debug] 28499#0: *7 http cl:-1 max:1048576

2009/01/29 14:54:53 [debug] 28499#0: *7 generic phase: 2

2009/01/29 14:54:53 [debug] 28499#0: *7 http script var

2009/01/29 14:54:53 [debug] 28499#0: *7 http script var: “GET”

2009/01/29 14:54:53 [debug] 28499#0: *7 http script value: “POST”

2009/01/29 14:54:53 [debug] 28499#0: *7 http script equal

2009/01/29 14:54:53 [debug] 28499#0: *7 http script equal: no

2009/01/29 14:54:53 [debug] 28499#0: *7 http script if

2009/01/29 14:54:53 [debug] 28499#0: *7 http script if: false

2009/01/29 14:54:53 [debug] 28499#0: *7 http script complex value

2009/01/29 14:54:53 [debug] 28499#0: *7 http script var:
“blablablall.css”
rel=“styleshe”

2009/01/29 14:54:53 [debug] 28499#0: *7 http script copy:
“blablabla:l.css”
rel=“styleshe”

2009/01/29 14:54:53 [debug] 28499#0: *7 http script var:
“blablabla:/news.php?id=3916242”

2009/01/29 14:54:53 [debug] 28499#0: *7 http script set var

2009/01/29 14:54:53 [debug] 28499#0: *7 post rewrite phase: 3

2009/01/29 14:54:53 [debug] 28499#0: *7 generic phase: 4

2009/01/29 14:54:53 [debug] 28499#0: *7 http init upstream, client
timer: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 http memcached request:
“blablabla:/news.php?id=3916242”

2009/01/29 14:54:53 [debug] 28499#0: *7 http cleanup add:
00000000020FD600

2009/01/29 14:54:53 [debug] 28499#0: *7 get rr peer, try: 1

2009/01/29 14:54:53 [debug] 28499#0: *7 socket 16

2009/01/29 14:54:53 [debug] 28499#0: *7 epoll add connection: fd:16
ev:80000005

2009/01/29 14:54:53 [debug] 28499#0: *7 connect to 10.100.0.47:11211,
fd:16
#10

2009/01/29 14:54:53 [debug] 28499#0: *7 http upstream connect: -2

2009/01/29 14:54:53 [debug] 28499#0: *7 event timer add: 16:
60000:1233233753903

2009/01/29 14:54:53 [debug] 28499#0: *7 http subrequest done
“/http://blablabla/dyn/calculate_minutes_ago.php?date=

2009/01/29 14:54:53 [debug] 28499#0: *7 saved: 0 state: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 parse: -2, looked: 0
0000000002117B3F-0000000002117F55

2009/01/29 14:54:53 [debug] 28499#0: *7 saved: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 ssi out: 0000000002116080
0000000002117B3F

2009/01/29 14:54:53 [debug] 28499#0: *7 http postpone filter “/fall?”
00000000021160D0

2009/01/29 14:54:53 [debug] 28499#0: *7 copy filter: -2 “/fall?”

2009/01/29 14:54:53 [debug] 28499#0: *7 pipe write downstream done

2009/01/29 14:54:53 [debug] 28499#0: *7 event timer: 15, old:
1233233783830,
new: 1233233783903

2009/01/29 14:54:53 [debug] 28499#0: *7 http upstream exit:
0000000000000000

2009/01/29 14:54:53 [debug] 28499#0: *7 finalize http upstream request:
0

2009/01/29 14:54:53 [debug] 28499#0: *7 finalize http proxy request

2009/01/29 14:54:53 [debug] 28499#0: *7 free rr peer 1 0

2009/01/29 14:54:53 [debug] 28499#0: *7 close http upstream connection:
15

2009/01/29 14:54:53 [debug] 28499#0: *7 event timer del: 15:
1233233783830

2009/01/29 14:54:53 [debug] 28499#0: *7 http upstream temp fd: -1

2009/01/29 14:54:53 [debug] 28499#0: *7 http output filter “/fall?”

2009/01/29 14:54:53 [debug] 28499#0: *7 copy filter: “/fall?”

2009/01/29 14:54:53 [debug] 28499#0: *7 http ssi filter “/fall

2009/01/29 14:54:53 [debug] 28499#0: *7 ssi out: 00000000020FD6C0
0000000000000000

2009/01/29 14:54:53 [debug] 28499#0: *7 http postpone filter “/fall?”
00000000020FD710

2009/01/29 14:54:53 [debug] 28499#0: *7 copy filter: -2 “/fall?”

2009/01/29 14:54:53 [debug] 28499#0: *7 http finalize request: -2,
“/fall?”

2009/01/29 14:54:53 [debug] 28499#0: *7 event timer add: 14:
60000:1233233753903

2009/01/29 14:54:53 [debug] 28499#0: *7 http finalize non-active
request:
“/fall?”

2009/01/29 14:54:53 [debug] 28499#0: posted event 0000000000000000

2009/01/29 14:54:53 [debug] 28499#0: worker cycle

2009/01/29 14:54:53 [debug] 28499#0: accept mutex locked

2009/01/29 14:54:53 [debug] 28499#0: epoll timer: 60000

2009/01/29 14:54:53 [debug] 28499#0: epoll: fd:16 ev:0004
d:00007F65A0530290

2009/01/29 14:54:53 [debug] 28499#0: *7 post event 00007F659E3BA1B0

2009/01/29 14:54:53 [debug] 28499#0: timer delta: 1

2009/01/29 14:54:53 [debug] 28499#0: posted events 00007F659E3BA1B0

2009/01/29 14:54:53 [debug] 28499#0: posted event 00007F659E3BA1B0

2009/01/29 14:54:53 [debug] 28499#0: *7 delete posted event
00007F659E3BA1B0

2009/01/29 14:54:53 [debug] 28499#0: *7 http upstream send request
handler

2009/01/29 14:54:53 [debug] 28499#0: *7 http upstream send request

2009/01/29 14:54:53 [debug] 28499#0: *7 chain writer buf fl:0 s:45

2009/01/29 14:54:53 [debug] 28499#0: *7 chain writer in:
00000000020FD730

2009/01/29 14:54:53 [debug] 28499#0: *7 writev: 45

2009/01/29 14:54:53 [debug] 28499#0: *7 chain writer out:
0000000000000000

2009/01/29 14:54:53 [debug] 28499#0: *7 event timer del: 16:
1233233753903

2009/01/29 14:54:53 [debug] 28499#0: *7 event timer add: 16:
60000:1233233753904

2009/01/29 14:54:53 [debug] 28499#0: posted event 0000000000000000

2009/01/29 14:54:53 [debug] 28499#0: worker cycle

2009/01/29 14:54:53 [debug] 28499#0: accept mutex locked

2009/01/29 14:54:53 [debug] 28499#0: epoll timer: 59999

2009/01/29 14:54:53 [debug] 28499#0: epoll: fd:16 ev:0005
d:00007F65A0530290

2009/01/29 14:54:53 [debug] 28499#0: *7 post event 00007F659E4BD1B0

2009/01/29 14:54:53 [debug] 28499#0: *7 post event 00007F659E3BA1B0

2009/01/29 14:54:53 [debug] 28499#0: timer delta: 0

2009/01/29 14:54:53 [debug] 28499#0: posted events 00007F659E3BA1B0

2009/01/29 14:54:53 [debug] 28499#0: posted event 00007F659E3BA1B0

2009/01/29 14:54:53 [debug] 28499#0: *7 delete posted event
00007F659E3BA1B0

2009/01/29 14:54:53 [debug] 28499#0: *7 http upstream dummy handler

2009/01/29 14:54:53 [debug] 28499#0: posted event 00007F659E4BD1B0

2009/01/29 14:54:53 [debug] 28499#0: *7 delete posted event
00007F659E4BD1B0

2009/01/29 14:54:53 [debug] 28499#0: *7 http upstream process header

2009/01/29 14:54:53 [debug] 28499#0: *7 malloc: 000000000211FB50:4096

2009/01/29 14:54:53 [debug] 28499#0: *7 recv: fd:16 4096 of 4096

2009/01/29 14:54:53 [debug] 28499#0: *7 memcached: “VALUE
blablabla:/news.php?id=3916242 0 8584”

2009/01/29 14:54:53 [debug] 28499#0: *7 tcp_nodelay

2009/01/29 14:54:53 [debug] 28499#0: *7 memcached filter bytes:4042
size:4042 length:8591 rest:7

2009/01/29 14:54:53 [debug] 28499#0: *7 http upstream process non
buffered
downstream

2009/01/29 14:54:53 [debug] 28499#0: *7 http output filter
“/http://blablabla/dyn/calculate_minutes_ago.php?date=

2009/01/29 14:54:53 [debug] 28499#0: *7 copy filter:
“/http://blablabla/dyn/calculate_minutes_ago.php?date=

2009/01/29 14:54:53 [debug] 28499#0: *7 http ssi filter
“/http://blablabla/dyn/calculate_minutes_ago.php

2009/01/29 14:54:53 [debug] 28499#0: *7 saved: 0 state: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 parse: -2, looked: 0
000000000211FB86-0000000002120B50

2009/01/29 14:54:53 [debug] 28499#0: *7 saved: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 ssi out: 00000000020FDBA0
000000000211FB86

2009/01/29 14:54:53 [debug] 28499#0: *7 http postpone filter
“/http://blablabla/dyn/calculate_minutes_ago.php?date=” 00000000020FDBF0

2009/01/29 14:54:53 [debug] 28499#0: *7 http postpone filter out
“/http://blablabla/dyn/calculate_minutes_ago.php?date=

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in: 00000000020FDC00

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in_buf:00000000020FDBA0
ni:000000000211FB86 ai:4042

2009/01/29 14:54:53 [debug] 28499#0: *7 deflate in: ni:000000000211FB86
no:000000000211EB40 ai:4042 ao:4096 fl:2 redo:0

2009/01/29 14:54:53 [debug] 28499#0: *7 deflate out: ni:0000000002120B50
no:000000000211F854 ai:0 ao:748 rc:0

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in_buf:00000000020FDBA0
pos:000000000211FB86

2009/01/29 14:54:53 [debug] 28499#0: *7 http chunk: 3348

2009/01/29 14:54:53 [debug] 28499#0: *7 write old buf t:1 f:0
0000000002115D20, pos 0000000002115D20, size: 333 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 write old buf t:1 f:0
0000000000000000, pos 00000000021161A0, size: 3 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 write old buf t:0 f:0
0000000000000000, pos 0000000000676448, size: 10 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 write old buf t:0 f:0
0000000000000000, pos 000000000046323D, size: 2 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 write new buf t:1 f:0
0000000000000000, pos 00000000020FDC80, size: 5 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 write new buf t:1 f:0
000000000211EB40, pos 000000000211EB40, size: 3348 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 malloc: 0000000002120B60:4096

2009/01/29 14:54:53 [debug] 28499#0: *7 write new buf t:0 f:0
0000000000000000, pos 000000000046323D, size: 2 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 http write filter: l:0 f:1
s:3703

2009/01/29 14:54:53 [debug] 28499#0: *7 http write filter limit 0

2009/01/29 14:54:53 [debug] 28499#0: *7 writev: 3703

2009/01/29 14:54:53 [debug] 28499#0: *7 http write filter
0000000000000000

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in: 0000000000000000

2009/01/29 14:54:53 [debug] 28499#0: *7 copy filter: 0
“/http://blablabla/dyn/calculate_minutes_ago.php?date=

2009/01/29 14:54:53 [debug] 28499#0: *7 recv: fd:16 200 of 4096

2009/01/29 14:54:53 [debug] 28499#0: *7 memcached filter bytes:200
size:200
length:4549 rest:7

2009/01/29 14:54:53 [debug] 28499#0: *7 http output filter
“/http://blablabla/dyn/calculate_minutes_ago.php?date=

2009/01/29 14:54:53 [debug] 28499#0: *7 copy filter:
“/http://blablabla/dyn/calculate_minutes_ago.php?date=

2009/01/29 14:54:53 [debug] 28499#0: *7 http ssi filter
“/http://blablabla/dyn/calculate_minutes_ago.php

2009/01/29 14:54:53 [debug] 28499#0: *7 saved: 0 state: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 parse: -2, looked: 0
000000000211FB50-000000000211FC18

2009/01/29 14:54:53 [debug] 28499#0: *7 saved: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 ssi out: 00000000020FDBA0
000000000211FB50

2009/01/29 14:54:53 [debug] 28499#0: *7 http postpone filter
“/http://blablabla/dyn/calculate_minutes_ago.php?date=” 00000000020FDBF0

2009/01/29 14:54:53 [debug] 28499#0: *7 http postpone filter out
“/http://blablabla/dyn/calculate_minutes_ago.php?date=

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in: 00000000020FDCF8

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in_buf:00000000020FDBA0
ni:000000000211FB50 ai:200

2009/01/29 14:54:53 [debug] 28499#0: *7 deflate in: ni:000000000211FB50
no:000000000211EB40 ai:200 ao:4096 fl:2 redo:0

2009/01/29 14:54:53 [debug] 28499#0: *7 deflate out: ni:000000000211FC18
no:000000000211EB5B ai:0 ao:4069 rc:0

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in_buf:00000000020FDBA0
pos:000000000211FB50

2009/01/29 14:54:53 [debug] 28499#0: *7 http chunk: 27

2009/01/29 14:54:53 [debug] 28499#0: *7 write new buf t:1 f:0
0000000000000000, pos 0000000002120C00, size: 4 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 write new buf t:1 f:0
000000000211EB40, pos 000000000211EB40, size: 27 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 write new buf t:0 f:0
0000000000000000, pos 000000000046323D, size: 2 file: 0, size: 0

2009/01/29 14:54:53 [debug] 28499#0: *7 http write filter: l:0 f:1 s:33

2009/01/29 14:54:53 [debug] 28499#0: *7 http write filter limit 0

2009/01/29 14:54:53 [debug] 28499#0: *7 writev: 33

2009/01/29 14:54:53 [debug] 28499#0: *7 http write filter
0000000000000000

2009/01/29 14:54:53 [debug] 28499#0: *7 gzip in: 0000000000000000

2009/01/29 14:54:53 [debug] 28499#0: *7 copy filter: 0
“/http://blablabla/dyn/calculate_minutes_ago.php?date=

2009/01/29 14:54:53 [debug] 28499#0: *7 recv: fd:16 -1 of 4096

2009/01/29 14:54:53 [debug] 28499#0: *7 recv() not ready (11: Resource
temporarily unavailable)

2009/01/29 14:54:53 [debug] 28499#0: *7 event timer del: 14:
1233233753903

2009/01/29 14:54:53 [debug] 28499#0: *7 event timer: 16, old:
1233233753904,
new: 1233233753904

2009/01/29 14:54:53 [debug] 28499#0: posted event 0000000000000000

2009/01/29 14:54:53 [debug] 28499#0: worker cycle

2009/01/29 14:54:53 [debug] 28499#0: accept mutex locked

2009/01/29 14:54:53 [debug] 28499#0: epoll timer: 60000

2009/01/29 14:54:53 [debug] 28499#0: epoll: fd:16 ev:0004
d:00007F65A0530290

2009/01/29 14:54:53 [debug] 28499#0: *7 post event 00007F659E3BA1B0

2009/01/29 14:54:53 [debug] 28499#0: timer delta: 1

2009/01/29 14:54:53 [debug] 28499#0: posted events 00007F659E3BA1B0

2009/01/29 14:54:53 [debug] 28499#0: posted event 00007F659E3BA1B0

2009/01/29 14:54:53 [debug] 28499#0: *7 delete posted event
00007F659E3BA1B0

So I can see some kind of cycle - like take 80-90% of page and returns

2009/01/29 14:54:53 [debug] 28499#0: *7 recv() not ready (11: Resource
temporarily unavailable)

And then loads page again and again

What could be the problem ?

Best Regards,

Georgi Georgiev

PS:

OS : Linux Debian Lenny

nxinx version 0.6.32

memcache : 1.2.4

php5-memcache 3.0.1 ( for client )