Cycle with SSI ON in nginx config

Georgi Georgiev gogo at dirbg.com
Thu Jan 29 17:02:19 MSK 2009


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

 

<!--# include virtual="blabla/dyn" -->

 

 

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 )

 

 

 

 

 

 

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://nginx.org/pipermail/nginx/attachments/20090129/f3d8deaf/attachment.html>


More information about the nginx mailing list