Problem with GZIP

Server NGINX
1

I have ubuntu server 10.04, nginx 1.1.4(compiled) and my gzip support is
not working. This is my configuration:

nginx: nginx version: nginx/1.1.4
nginx: built by gcc 4.4.3 (Ubuntu 4.4.3-4ubuntu5)
nginx: TLS SNI support enabled
nginx: configure arguments: --user=www-data --group=www-data
–with-http_ssl_module --with-http_realip_module
–with-http_addition_module --with-http_sub_module
–with-http_dav_module --with-http_flv_module
–with-http_gzip_static_module --with-http_random_index_module
–with-http_secure_link_module --with-http_stub_status_module
–with-debug

my nginx configuration is:

http {
include mime.types;
default_type application/octet-stream;

#log_format  main  '$remote_addr - $remote_user [$time_local]

“$request” ’
# '$status $body_bytes_sent “$http_referer” ’
# ‘“$http_user_agent” “$http_x_forwarded_for”’;

#access_log  logs/access.log  main;

sendfile        on;
tcp_nodelay     on;
tcp_nopush      off;

#keepalive_timeout  0;
keepalive_timeout  65;

gzip on;
gzip_min_length 1000;
gzip_comp_level 6;
gzip_proxied expired no-cache no-store private auth;
gzip_types text/plain application/xml;
#gzip_disable "MSIE [1-6]\.";


server {

listen 80;
server_name localhost;

#access_log /var/log/nginx/alfaomega.access_log main;
#error_log /var/log/nginx/alfaomega.error_log info;

root /var/www/alfaomega/web;
charset utf-8;

   location = / {
 index index.php;
   }

}

#Other locations

}

Posted at Nginx Forum:

2

Hello!

On Thu, Sep 29, 2011 at 10:16:21AM -0400, firestorm wrote:

I have ubuntu server 10.04, nginx 1.1.4(compiled) and my gzip support is
not working. This is my configuration:

Please show how do you test it’s “not working”. If in doubt,
please provide debug log, see Debugging | NGINX.

Maxim D.

3

Using firefox´s plg-in firebug I can check gzip support it´s not
enabled:

There are 8 plain text components that should be sent compressed
http://10.128.50.101/css/principal.css
http://10.128.50.101/css/blueprint/screen.css
http://10.128.50.101/css/learning/learningp.css
http://10.128.50.101/css/learning/learningm.css
http://10.128.50.101/css/blueprint/plugins/dashboard/buttons.css
http://10.128.50.101/css/learning/lear_dashboard.css
http://10.128.50.101/sfJqueryReloadedPlugin/css/plugins/jquery.fancybox.css
http://10.128.50.101/js/js_c7b7c377449a5aad6e7bad82bfa9d21a.js

Posted at Nginx Forum:

4

On Mon, Oct 03, 2011 at 10:01:53AM -0400, firestorm wrote:

Hi there,

Using firefoxs plg-in firebug I can check gzip support its not
enabled:

There are 8 plain text components that should be sent compressed
http://10.128.50.101/css/principal.css

Your config says

gzip_types text/plain application/xml;

Your output log says

Content-Type: text/css

Copy one big-enough file to a .txt-ending name – for example copy
dashboard.css to dashboard.txt – and then try to access that file and
see if it is gzipped.

If it is, then include the right content types in your gzip_types config
and it should Just Work.

If it isn’t, then more investigation is needed.

Good luck,

f

Francis D. [email protected]

5

Using firefox´s plg-in firebug I can check gzip support it´s not enabled:
http://10.128.50.101/css/blueprint/screen.css

Your configuration (
Re: Problem with GZIP ) is missing
text/css from gzip_types. Which is
returned by nginx so the gzip module doesn’t do anything:

2011/09/29 07:44:01 [debug] 14365#0: *4 http filename:
“/var/www/appname/web/css/administration/modules/dashboard.css”
2011/09/29 07:44:01 [debug] 14365#0: *4 HTTP/1.1 200 OK
Server: nginx/1.1.4
Date: Thu, 29 Sep 2011 11:44:01 GMT
Content-Type: text/css
^^^^^^^

The line probably should be (add or remove additional document types if
needed):

gzip_types text/plain text/css text/xml application/x-javascript;

p.s. Also the IE regex can be simplified:
gzip_disable msie6;

rr

6

My configuration now is:

gzip on;
gzip_min_length 1000;
gzip_comp_level 6;
gzip_proxied expired no-cache no-store private auth;
gzip_types text/plain application/xml application/x-javascript
text/css;
#gzip_disable msie6;

and the problem remains.

Posted at Nginx Forum:

7

On Mon, Oct 03, 2011 at 11:09:47AM -0400, firestorm wrote:

Hi there,

and the problem remains.

It works for me.

So there is some difference in our configurations.

I’ve copied your original gzip* configuration, and added “expires max”
to the relevant location, since the logs suggest you are using that too.

While testing, I suggest you simplify the configuration until you find
what changes makes it go from not-working to working, or the other
way around.

With the following nginx:

$ sbin/nginx -V
nginx: nginx version: nginx/1.1.4
nginx: built by gcc 4.4.5 (Debian 4.4.5-8)
nginx: configure arguments: --with-debug

and the following nginx.conf:

$ cat conf/nginx.conf

events {
worker_connections 1024;
debug_connection 127.0.0.1;
}

http {
include mime.types;

gzip on;
gzip_min_length 1000;
gzip_comp_level 6;
gzip_proxied expired no-cache no-store private auth;
gzip_types text/plain application/xml;

server {
    listen       8000;

    location / {
        expires max;
    }
}

}

I can see the result of the following commands:

$ curl -v --compressed http://localhost:8000/a.txt >/dev/null

  • About to connect() to localhost port 8000 (#0)
  • Trying 127.0.0.1… % Total % Received % Xferd Average Speed
    Time Time
    Time Current
    Dload Upload Total Spent Left
    Speed
    0 0 0 0 0 0 0 0 --:–:-- --:–:-- --:–:–
    0connected
  • Connected to localhost (127.0.0.1) port 8000 (#0)

GET /a.txt HTTP/1.1
User-Agent: curl/7.21.0 (i486-pc-linux-gnu) libcurl/7.21.0 OpenSSL/0.9.8o
zlib/1.2.3.4 libidn/1.15 libssh2/1.2.6
Host: localhost:8000
Accept: /
Accept-Encoding: deflate, gzip

< HTTP/1.1 200 OK
< Server: nginx/1.1.4
< Date: Mon, 03 Oct 2011 19:14:08 GMT
< Content-Type: text/plain
< Last-Modified: Mon, 03 Oct 2011 19:03:51 GMT
< Transfer-Encoding: chunked
< Connection: keep-alive
< Expires: Thu, 31 Dec 2037 23:55:55 GMT
< Cache-Control: max-age=315360000
< Content-Encoding: gzip
<
{ [data not shown]
100 1028 0 1028 0 0 51451 0 --:–:-- --:–:-- --:–:–
57111* Connection #0 to host localhost left intact

  • Closing connection #0

which shows that my text/plain was compressed (“Content-Encoding: gzip”
in the response headers); and

$ curl -v --compressed http://localhost:8000/a.css >/dev/null

  • About to connect() to localhost port 8000 (#0)
  • Trying 127.0.0.1… % Total % Received % Xferd Average Speed
    Time Time
    Time Current
    Dload Upload Total Spent Left
    Speed
    0 0 0 0 0 0 0 0 --:–:-- --:–:-- --:–:–
    0connected
  • Connected to localhost (127.0.0.1) port 8000 (#0)

GET /a.css HTTP/1.1
User-Agent: curl/7.21.0 (i486-pc-linux-gnu) libcurl/7.21.0 OpenSSL/0.9.8o
zlib/1.2.3.4 libidn/1.15 libssh2/1.2.6
Host: localhost:8000
Accept: /
Accept-Encoding: deflate, gzip

< HTTP/1.1 200 OK
< Server: nginx/1.1.4
< Date: Mon, 03 Oct 2011 19:15:36 GMT
< Content-Type: text/css
< Content-Length: 2726
< Last-Modified: Mon, 03 Oct 2011 19:03:51 GMT
< Connection: keep-alive
< Expires: Thu, 31 Dec 2037 23:55:55 GMT
< Cache-Control: max-age=315360000
< Accept-Ranges: bytes
<
{ [data not shown]
100 2726 100 2726 0 0 487k 0 --:–:-- --:–:-- --:–:–
887k* Connection #0 to host localhost left intact

  • Closing connection #0

which shows that my text/css output was not compressed.

When I add text/css to the gzip_types line and reload, then my text/css
output shows as compressed.

Get that much working on your system, to prove that your test system is
right, and then add back the desired final configuration in stages.

Good luck with it,

f

Francis D. [email protected]

8

2011/09/29 07:44:01 [debug] 14365#0: *1 write new buf t:1 f:0 0872B620,
pos 0872B620, size: 366 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 http write filter: l:0 f:0
s:366
2011/09/29 07:44:01 [debug] 14365#0: *1 http cacheable: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 http upstream process upstream
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe read upstream: 1
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe preread: 3873
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 01
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 06
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 00
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 01
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 99
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 00
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 00
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 00
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record length:
39168
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf #0 086E16B0
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf 086E16B0 3864
2011/09/29 07:44:01 [debug] 14365#0: *1 malloc: 0872C548:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 readv: 1:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe recv chain: 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf #1 0872C548
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf 0872C548 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 malloc: 0872D550:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 readv: 1:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe recv chain: 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf #2 0872D550
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf 0872D550 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 malloc: 0872E558:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 readv: 1:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe recv chain: 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf #3 0872E558
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf 0872E558 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 malloc: 0872F560:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 readv: 1:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe recv chain: 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf #4 0872F560
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf 0872F560 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 malloc: 08730568:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 readv: 1:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe recv chain: 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf #5 08730568
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf 08730568 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 malloc: 08731570:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 readv: 1:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe recv chain: 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf #6 08731570
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf 08731570 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 malloc: 08732578:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 readv: 1:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe recv chain: 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf #7 08732578
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf 08732578 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 malloc: 08733580:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 readv: 1:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe recv chain: 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf #8 08733580
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf 08733580 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe downstream ready
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf in s:1 t:1 f:0
086E15C8, pos 086E16B0, size: 3864 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf in s:1 t:1 f:0
0872C548, pos 0872C548, size: 4096 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf in s:1 t:1 f:0
0872D550, pos 0872D550, size: 4096 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf in s:1 t:1 f:0
0872E558, pos 0872E558, size: 4096 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf in s:1 t:1 f:0
0872F560, pos 0872F560, size: 4096 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf in s:1 t:1 f:0
08730568, pos 08730568, size: 4096 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf in s:1 t:1 f:0
08731570, pos 08731570, size: 4096 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf in s:1 t:1 f:0
08732578, pos 08732578, size: 4096 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf in s:1 t:1 f:0
08733580, pos 08733580, size: 4096 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe length: -1
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write downstream: 1
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write busy: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write buf ls:1 086E16B0
3864
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write buf ls:1 0872C548
4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write buf ls:1 0872D550
4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write: out:0872B874, f:1
2011/09/29 07:44:01 [debug] 14365#0: *1 http output filter
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter:
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http postpone filter
“/administration.php/sf_guard_group?” 0872B838
2011/09/29 07:44:01 [debug] 14365#0: *1 http gzip filter
2011/09/29 07:44:01 [debug] 14365#0: *1 malloc: B76F1008:270336
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip alloc: n:1 s:5824 a:8192
p:B76F1008
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip alloc: n:32768 s:2 a:65536
p:B76F3008
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip alloc: n:32768 s:2 a:65536
p:B7703008
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip alloc: n:32768 s:2 a:65536
p:B7713008
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip alloc: n:16384 s:4 a:65536
p:B7723008
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 0872BC84
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872B840 ni:086E16B0
ai:3864
2011/09/29 07:44:01 [debug] 14365#0: *1 malloc: 08734588:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate in: ni:086E16B0
no:08734588 ai:3864 ao:4096 fl:0 redo:0
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate out: ni:086E25C8
no:08734588 ai:0 ao:4096 rc:0
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872B840
pos:086E16B0
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 0872BC8C
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872B8B8 ni:0872C548
ai:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate in: ni:0872C548
no:08734588 ai:4096 ao:4096 fl:0 redo:0
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate out: ni:0872D548
no:08734588 ai:0 ao:4096 rc:0
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872B8B8
pos:0872C548
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter: 0
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write busy: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write buf ls:1 0872D550
4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write buf ls:1 0872E558
4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write buf ls:1 0872F560
4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write: out:0872B964, f:1
2011/09/29 07:44:01 [debug] 14365#0: *1 http output filter
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter:
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http postpone filter
“/administration.php/sf_guard_group?” 0872BCD0
2011/09/29 07:44:01 [debug] 14365#0: *1 http gzip filter
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 0872BCE0
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872B930 ni:0872D550
ai:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate in: ni:0872D550
no:08734588 ai:4096 ao:4096 fl:0 redo:0
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate out: ni:0872E550
no:08734588 ai:0 ao:4096 rc:0
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872B930
pos:0872D550
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 0872BCE8
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872B9A8 ni:0872E558
ai:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate in: ni:0872E558
no:08734588 ai:4096 ao:4096 fl:0 redo:0
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate out: ni:0872F558
no:08734588 ai:0 ao:4096 rc:0
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872B9A8
pos:0872E558
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter: 0
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write busy: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write buf ls:1 0872F560
4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write buf ls:1 08730568
4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write buf ls:1 08731570
4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write: out:0872BA54, f:1
2011/09/29 07:44:01 [debug] 14365#0: *1 http output filter
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter:
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http postpone filter
“/administration.php/sf_guard_group?” 0872BCF0
2011/09/29 07:44:01 [debug] 14365#0: *1 http gzip filter
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 0872BD00
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BA20 ni:0872F560
ai:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate in: ni:0872F560
no:08734588 ai:4096 ao:4096 fl:0 redo:0
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate out: ni:08730560
no:08734588 ai:0 ao:4096 rc:0
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BA20
pos:0872F560
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 0872BD08
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BA98 ni:08730568
ai:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate in: ni:08730568
no:08734588 ai:4096 ao:4096 fl:0 redo:0
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate out: ni:08731568
no:08734588 ai:0 ao:4096 rc:0
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BA98
pos:08730568
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter: 0
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write busy: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write buf ls:1 08731570
4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write buf ls:1 08732578
4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write buf ls:1 08733580
4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write: out:0872BB44, f:1
2011/09/29 07:44:01 [debug] 14365#0: *1 http output filter
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter:
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http postpone filter
“/administration.php/sf_guard_group?” 0872BD10
2011/09/29 07:44:01 [debug] 14365#0: *1 http gzip filter
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 0872BD20
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BB10 ni:08731570
ai:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate in: ni:08731570
no:08734588 ai:4096 ao:4096 fl:0 redo:0
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate out: ni:08732570
no:08734588 ai:0 ao:4096 rc:0
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BB10
pos:08731570
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 0872BD28
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BB88 ni:08732578
ai:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate in: ni:08732578
no:08734588 ai:4096 ao:4096 fl:0 redo:0
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate out: ni:08733578
no:08734588 ai:0 ao:4096 rc:0
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BB88
pos:08732578
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter: 0
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write busy: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write buf ls:1 08733580
4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write: out:0872BC34, f:0
2011/09/29 07:44:01 [debug] 14365#0: *1 http output filter
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter:
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http postpone filter
“/administration.php/sf_guard_group?” 0872BC34
2011/09/29 07:44:01 [debug] 14365#0: *1 http gzip filter
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 0872BD30
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BC00 ni:08733580
ai:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate in: ni:08733580
no:08734588 ai:4096 ao:4096 fl:0 redo:0
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate out: ni:08734580
no:08734588 ai:0 ao:4096 rc:0
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BC00
pos:08733580
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter: 0
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write busy: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write: out:00000000, f:0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe read upstream: 1
2011/09/29 07:44:01 [debug] 14365#0: *1 readv: 9:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe recv chain: 2536
2011/09/29 07:44:01 [debug] 14365#0: *1 readv: 9:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 readv() not ready (11: Resource
temporarily unavailable)
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe recv chain: -2
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
08733580, pos 08733580, size: 2536 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
08731570, pos 08731570, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
08732578, pos 08732578, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
0872F560, pos 0872F560, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
08730568, pos 08730568, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
0872D550, pos 0872D550, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
0872E558, pos 0872E558, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
086E15C8, pos 086E15C8, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
0872C548, pos 0872C548, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe length: -1
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write downstream: 1
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write busy: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write: out:00000000, f:0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe read upstream: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
08733580, pos 08733580, size: 2536 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
08731570, pos 08731570, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
08732578, pos 08732578, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
0872F560, pos 0872F560, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
08730568, pos 08730568, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
0872D550, pos 0872D550, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
0872E558, pos 0872E558, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
086E15C8, pos 086E15C8, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
0872C548, pos 0872C548, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe length: -1
2011/09/29 07:44:01 [debug] 14365#0: *1 event timer del: 12: 3036708358
2011/09/29 07:44:01 [debug] 14365#0: *1 event timer add: 12:
60000:3036709099
2011/09/29 07:44:01 [debug] 14365#0: posted event 00000000
2011/09/29 07:44:01 [debug] 14365#0: worker cycle
2011/09/29 07:44:01 [debug] 14365#0: accept mutex locked
2011/09/29 07:44:01 [debug] 14365#0: epoll timer: 60000
2011/09/29 07:44:01 [debug] 14365#0: epoll: fd:12 ev:0004 d:086F8655
2011/09/29 07:44:01 [debug] 14365#0: *1 post event 0871E5D4
2011/09/29 07:44:01 [debug] 14365#0: timer delta: 11
2011/09/29 07:44:01 [debug] 14365#0: posted events 0871E5D4
2011/09/29 07:44:01 [debug] 14365#0: posted event 0871E5D4
2011/09/29 07:44:01 [debug] 14365#0: *1 delete posted event 0871E5D4
2011/09/29 07:44:01 [debug] 14365#0: *1 http upstream request:
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http upstream dummy handler
2011/09/29 07:44:01 [debug] 14365#0: posted event 00000000
2011/09/29 07:44:01 [debug] 14365#0: worker cycle
2011/09/29 07:44:01 [debug] 14365#0: accept mutex locked
2011/09/29 07:44:01 [debug] 14365#0: epoll timer: 59989
2011/09/29 07:44:01 [debug] 14365#0: epoll: fd:12 ev:0005 d:086F8655
2011/09/29 07:44:01 [debug] 14365#0: *1 post event 087115CC
2011/09/29 07:44:01 [debug] 14365#0: *1 post event 0871E5D4
2011/09/29 07:44:01 [debug] 14365#0: timer delta: 40
2011/09/29 07:44:01 [debug] 14365#0: posted events 0871E5D4
2011/09/29 07:44:01 [debug] 14365#0: posted event 0871E5D4
2011/09/29 07:44:01 [debug] 14365#0: *1 delete posted event 0871E5D4
2011/09/29 07:44:01 [debug] 14365#0: *1 http upstream request:
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http upstream dummy handler
2011/09/29 07:44:01 [debug] 14365#0: posted event 087115CC
2011/09/29 07:44:01 [debug] 14365#0: *1 delete posted event 087115CC
2011/09/29 07:44:01 [debug] 14365#0: *1 http upstream request:
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http upstream process upstream
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe read upstream: 1
2011/09/29 07:44:01 [debug] 14365#0: *1 readv: 9:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe recv chain: 16
2011/09/29 07:44:01 [debug] 14365#0: *1 readv: 9:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe recv chain: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
08733580, pos 08733580, size: 2552 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
08731570, pos 08731570, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
08732578, pos 08732578, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
0872F560, pos 0872F560, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
08730568, pos 08730568, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
0872D550, pos 0872D550, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
0872E558, pos 0872E558, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
086E15C8, pos 086E15C8, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe buf free s:0 t:1 f:0
0872C548, pos 0872C548, size: 0 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe length: -1
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf #9 08733580
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 01
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 03
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 00
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 01
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 00
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 08
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 00
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record byte: 00
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi record length: 8
2011/09/29 07:44:01 [debug] 14365#0: *1 http fastcgi sent end request
2011/09/29 07:44:01 [debug] 14365#0: *1 input buf 08733580 2536
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 08731570
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 08732578
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 0872F560
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 08730568
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 0872D550
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 0872E558
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 086E15C8
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 0872C548
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write downstream: 1
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write downstream flush in
2011/09/29 07:44:01 [debug] 14365#0: *1 http output filter
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter:
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http postpone filter
“/administration.php/sf_guard_group?” 0872BC34
2011/09/29 07:44:01 [debug] 14365#0: *1 http gzip filter
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 0872BD40
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BC00 ni:08733580
ai:2536
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate in: ni:08733580
no:08734588 ai:2536 ao:4096 fl:0 redo:0
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate out: ni:08733F68
no:08734588 ai:0 ao:4096 rc:0
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BC00
pos:08733580
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter: 0
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 pipe write downstream done
2011/09/29 07:44:01 [debug] 14365#0: *1 event timer: 12, old:
3036709099, new: 3036709150
2011/09/29 07:44:01 [debug] 14365#0: *1 http upstream exit: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 finalize http upstream request:
0
2011/09/29 07:44:01 [debug] 14365#0: *1 finalize http fastcgi request
2011/09/29 07:44:01 [debug] 14365#0: *1 free rr peer 1 0
2011/09/29 07:44:01 [debug] 14365#0: *1 close http upstream connection:
12
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 086DA5B0, unused: 88
2011/09/29 07:44:01 [debug] 14365#0: *1 event timer del: 12: 3036709099
2011/09/29 07:44:01 [debug] 14365#0: *1 reusable connection: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 http upstream temp fd: -1
2011/09/29 07:44:01 [debug] 14365#0: *1 http output filter
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter:
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http postpone filter
“/administration.php/sf_guard_group?” BFEEF108
2011/09/29 07:44:01 [debug] 14365#0: *1 http gzip filter
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in: 0872BD7C
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BD48 ni:00000000
ai:0
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate in: ni:00000000
no:08734588 ai:0 ao:4096 fl:4 redo:0
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate out: ni:00000000
no:08735588 ai:0 ao:0 rc:0
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BD48
pos:00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 malloc: 086E15C8:4096
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate in: ni:00000000
no:086E15C8 ai:0 ao:4096 fl:4 redo:1
2011/09/29 07:44:01 [debug] 14365#0: *1 deflate out: ni:00000000
no:086E1DAD ai:0 ao:2075 rc:1
2011/09/29 07:44:01 [debug] 14365#0: *1 gzip in_buf:0872BD48
pos:00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 free: B76F1008
2011/09/29 07:44:01 [debug] 14365#0: *1 http chunk: 10
2011/09/29 07:44:01 [debug] 14365#0: *1 http chunk: 4096
2011/09/29 07:44:01 [debug] 14365#0: *1 http chunk: 2029
2011/09/29 07:44:01 [debug] 14365#0: *1 write old buf t:1 f:0 0872B620,
pos 0872B620, size: 366 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 write new buf t:1 f:0 0872BE58,
pos 0872BE58, size: 6 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 write new buf t:0 f:0 00000000,
pos 080CAFE4, size: 10 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 write new buf t:1 f:0 08734588,
pos 08734588, size: 4096 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 write new buf t:1 f:0 086E15C8,
pos 086E15C8, size: 2029 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 write new buf t:0 f:0 00000000,
pos 080BF4DC, size: 7 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 http write filter: l:1 f:1
s:6514
2011/09/29 07:44:01 [debug] 14365#0: *1 http write filter limit 0
2011/09/29 07:44:01 [debug] 14365#0: *1 writev: 6514
2011/09/29 07:44:01 [debug] 14365#0: *1 http write filter 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter: 0
“/administration.php/sf_guard_group?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http finalize request: 0,
“/administration.php/sf_guard_group?” a:1, c:1
2011/09/29 07:44:01 [debug] 14365#0: *1 set http keepalive handler
2011/09/29 07:44:01 [debug] 14365#0: *1 http close request
2011/09/29 07:44:01 [debug] 14365#0: *1 http log handler
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 08734588
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 08733580
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 086E15C8
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 086E05C0, unused: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 0872B540, unused: 1426
2011/09/29 07:44:01 [debug] 14365#0: *1 event timer add: 11:
5000:3036654150
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 086E0310
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 086DFF08
2011/09/29 07:44:01 [debug] 14365#0: *1 hc free: 00000000 0
2011/09/29 07:44:01 [debug] 14365#0: *1 hc busy: 00000000 0
2011/09/29 07:44:01 [debug] 14365#0: *1 reusable connection: 1
2011/09/29 07:44:01 [debug] 14365#0: *1 post event 08711598
2011/09/29 07:44:01 [debug] 14365#0: posted event 08711598
2011/09/29 07:44:01 [debug] 14365#0: *1 delete posted event 08711598
2011/09/29 07:44:01 [debug] 14365#0: *1 http keepalive handler
2011/09/29 07:44:01 [debug] 14365#0: *1 malloc: 086DFF08:1024
2011/09/29 07:44:01 [debug] 14365#0: *1 recv: fd:11 -1 of 1024
2011/09/29 07:44:01 [debug] 14365#0: *1 recv() not ready (11: Resource
temporarily unavailable)
2011/09/29 07:44:01 [debug] 14365#0: posted event 00000000
2011/09/29 07:44:01 [debug] 14365#0: worker cycle
2011/09/29 07:44:01 [debug] 14365#0: accept mutex locked
2011/09/29 07:44:01 [debug] 14365#0: epoll timer: 5000
2011/09/29 07:44:01 [debug] 14366#0: timer delta: 499
2011/09/29 07:44:01 [debug] 14366#0: posted events 00000000
2011/09/29 07:44:01 [debug] 14366#0: worker cycle
2011/09/29 07:44:01 [debug] 14366#0: accept mutex lock failed: 0
2011/09/29 07:44:01 [debug] 14366#0: epoll timer: 500
2011/09/29 07:44:01 [debug] 14367#0: timer delta: 500
2011/09/29 07:44:01 [debug] 14367#0: posted events 00000000
2011/09/29 07:44:01 [debug] 14367#0: worker cycle
2011/09/29 07:44:01 [debug] 14367#0: accept mutex lock failed: 0
2011/09/29 07:44:01 [debug] 14367#0: epoll timer: 500
2011/09/29 07:44:01 [debug] 14368#0: timer delta: 500
2011/09/29 07:44:01 [debug] 14368#0: posted events 00000000
2011/09/29 07:44:01 [debug] 14368#0: worker cycle
2011/09/29 07:44:01 [debug] 14368#0: accept mutex lock failed: 0
2011/09/29 07:44:01 [debug] 14368#0: epoll timer: 500
2011/09/29 07:44:01 [debug] 14365#0: epoll: fd:11 ev:0005 d:086F85F0
2011/09/29 07:44:01 [debug] 14365#0: *1 post event 08711598
2011/09/29 07:44:01 [debug] 14365#0: *1 post event 0871E5A0
2011/09/29 07:44:01 [debug] 14365#0: timer delta: 197
2011/09/29 07:44:01 [debug] 14365#0: posted events 0871E5A0
2011/09/29 07:44:01 [debug] 14365#0: posted event 0871E5A0
2011/09/29 07:44:01 [debug] 14365#0: *1 delete posted event 0871E5A0
2011/09/29 07:44:01 [debug] 14365#0: *1 http empty handler
2011/09/29 07:44:01 [debug] 14365#0: posted event 08711598
2011/09/29 07:44:01 [debug] 14365#0: *1 delete posted event 08711598
2011/09/29 07:44:01 [debug] 14365#0: *1 http keepalive handler
2011/09/29 07:44:01 [debug] 14365#0: *1 recv: fd:11 441 of 1024
2011/09/29 07:44:01 [debug] 14365#0: *1 reusable connection: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 malloc: 086E0310:680
2011/09/29 07:44:01 [debug] 14365#0: *1 posix_memalign: 086E05C0:4096
@16
2011/09/29 07:44:01 [debug] 14365#0: *1 http process request line
2011/09/29 07:44:01 [debug] 14365#0: *1 http request line: “GET
/css/blueprint/print.css HTTP/1.1”
2011/09/29 07:44:01 [debug] 14365#0: *1 http uri:
“/css/blueprint/print.css”
2011/09/29 07:44:01 [debug] 14365#0: *1 http args: “”
2011/09/29 07:44:01 [debug] 14365#0: *1 http exten: “css”
2011/09/29 07:44:01 [debug] 14365#0: *1 http process request header
line
2011/09/29 07:44:01 [debug] 14365#0: *1 http header: “Host:
10.128.50.101”
2011/09/29 07:44:01 [debug] 14365#0: 1 http header: “User-Agent:
Mozilla/5.0 (Windows NT 5.1; rv:7.0) Gecko/20100101 Firefox/7.0”
2011/09/29 07:44:01 [debug] 14365#0: 1 http header: "Accept:
text/css,/;q=0.1"
2011/09/29 07:44:01 [debug] 14365#0: *1 http header: “Accept-Language:
es-es,es;q=0.8,en-us;q=0.5,en;q=0.3”
2011/09/29 07:44:01 [debug] 14365#0: *1 http header: “Accept-Encoding:
gzip, deflate”
2011/09/29 07:44:01 [debug] 14365#0: 1 http header: "Accept-Charset:
ISO-8859-1,utf-8;q=0.7,;q=0.7"
2011/09/29 07:44:01 [debug] 14365#0: *1 http header: “Connection:
keep-alive”
2011/09/29 07:44:01 [debug] 14365#0: *1 http header: “Referer:
http://10.128.50.101/administration.php/sf_guard_group”
2011/09/29 07:44:01 [debug] 14365#0: *1 http header: “Cookie:
zera=vmlib90drktmrpmo3gdra6hi12; has_js=1”
2011/09/29 07:44:01 [debug] 14365#0: *1 http header done
2011/09/29 07:44:01 [debug] 14365#0: *1 event timer del: 11: 3036654150
2011/09/29 07:44:01 [debug] 14365#0: *1 generic phase: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 rewrite phase: 1
2011/09/29 07:44:01 [debug] 14365#0: *1 test location: “/404.html”
2011/09/29 07:44:01 [debug] 14365#0: *1 test location: “/sf/”
2011/09/29 07:44:01 [debug] 14365#0: *1 test location: ~
“.+.(js|htc|ico|gif|jpg|png|css)$”
2011/09/29 07:44:01 [debug] 14365#0: *1 using configuration
“.+.(js|htc|ico|gif|jpg|png|css)$”
2011/09/29 07:44:01 [debug] 14365#0: *1 http cl:-1 max:1048576
2011/09/29 07:44:01 [debug] 14365#0: *1 rewrite phase: 3
2011/09/29 07:44:01 [debug] 14365#0: *1 post rewrite phase: 4
2011/09/29 07:44:01 [debug] 14365#0: *1 generic phase: 5
2011/09/29 07:44:01 [debug] 14365#0: *1 generic phase: 6
2011/09/29 07:44:01 [debug] 14365#0: *1 generic phase: 7
2011/09/29 07:44:01 [debug] 14365#0: *1 access phase: 8
2011/09/29 07:44:01 [debug] 14365#0: *1 access phase: 9
2011/09/29 07:44:01 [debug] 14365#0: *1 post access phase: 10
2011/09/29 07:44:01 [debug] 14365#0: *1 content phase: 11
2011/09/29 07:44:01 [debug] 14365#0: *1 content phase: 12
2011/09/29 07:44:01 [debug] 14365#0: *1 content phase: 13
2011/09/29 07:44:01 [debug] 14365#0: *1 content phase: 14
2011/09/29 07:44:01 [debug] 14365#0: *1 content phase: 15
2011/09/29 07:44:01 [debug] 14365#0: *1 content phase: 16
2011/09/29 07:44:01 [debug] 14365#0: *1 http filename:
“/var/www/appname/web/css/blueprint/print.css”
2011/09/29 07:44:01 [debug] 14365#0: *1 add cleanup: 086E0BEC
2011/09/29 07:44:01 [debug] 14365#0: *1 http static fd: 12
2011/09/29 07:44:01 [debug] 14365#0: *1 http set discard body
2011/09/29 07:44:01 [debug] 14365#0: *1 HTTP/1.1 200 OK
Server: nginx/1.1.4
Date: Thu, 29 Sep 2011 11:44:01 GMT
Content-Type: text/css
Content-Length: 1285
Last-Modified: Wed, 28 Sep 2011 15:47:21 GMT
Connection: keep-alive
Expires: Thu, 31 Dec 2037 23:55:55 GMT
Cache-Control: max-age=315360000
Accept-Ranges: bytes

2011/09/29 07:44:01 [debug] 14365#0: *1 write new buf t:1 f:0 086E0CFC,
pos 086E0CFC, size: 289 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 http write filter: l:0 f:0
s:289
2011/09/29 07:44:01 [debug] 14365#0: *1 http output filter
“/css/blueprint/print.css?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter:
“/css/blueprint/print.css?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http postpone filter
“/css/blueprint/print.css?” BFEEF03C
2011/09/29 07:44:01 [debug] 14365#0: *1 write old buf t:1 f:0 086E0CFC,
pos 086E0CFC, size: 289 file: 0, size: 0
2011/09/29 07:44:01 [debug] 14365#0: *1 write new buf t:0 f:1 00000000,
pos 00000000, size: 0 file: 0, size: 1285
2011/09/29 07:44:01 [debug] 14365#0: *1 http write filter: l:1 f:0
s:1574
2011/09/29 07:44:01 [debug] 14365#0: *1 http write filter limit 0
2011/09/29 07:44:01 [debug] 14365#0: *1 writev: 289
2011/09/29 07:44:01 [debug] 14365#0: *1 sendfile: @0 1285
2011/09/29 07:44:01 [debug] 14365#0: *1 sendfile: 1285, @0 1285:1285
2011/09/29 07:44:01 [debug] 14365#0: *1 http write filter 00000000
2011/09/29 07:44:01 [debug] 14365#0: *1 http copy filter: 0
“/css/blueprint/print.css?”
2011/09/29 07:44:01 [debug] 14365#0: *1 http finalize request: 0,
“/css/blueprint/print.css?” a:1, c:1
2011/09/29 07:44:01 [debug] 14365#0: *1 set http keepalive handler
2011/09/29 07:44:01 [debug] 14365#0: *1 http close request
2011/09/29 07:44:01 [debug] 14365#0: *1 http log handler
2011/09/29 07:44:01 [debug] 14365#0: *1 run cleanup: 086E0BEC
2011/09/29 07:44:01 [debug] 14365#0: *1 file cleanup: fd:12
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 086E05C0, unused: 1631
2011/09/29 07:44:01 [debug] 14365#0: *1 event timer add: 11:
5000:3036654347
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 086E0310
2011/09/29 07:44:01 [debug] 14365#0: *1 free: 086DFF08
2011/09/29 07:44:01 [debug] 14365#0: *1 hc free: 00000000 0
2011/09/29 07:44:01 [debug] 14365#0: *1 hc busy: 00000000 0
2011/09/29 07:44:01 [debug] 14365#0: *1 reusable connection: 1
2011/09/29 07:44:01 [debug] 14365#0: *1 post event 08711598
2011/09/29 07:44:01 [debug] 14365#0: posted event 08711598
2011/09/29 07:44:01 [debug] 14365#0: *1 delete posted event 08711598
2011/09/29 07:44:01 [debug] 14365#0: *1 http keepalive handler
2011/09/29 07:44:01 [debug] 14365#0: *1 malloc: 086DFF08:1024
2011/09/29 07:44:01 [debug] 14365#0: *1 recv: fd:11 -1 of 1024
2011/09/29 07:44:01 [debug] 14365#0: *1 recv() not ready (11: Resource
temporarily unavailable)
2011/09/29 07:44:01 [debug] 14365#0: posted event 00000000
2011/09/29 07:44:01 [debug] 14365#0: worker cycle
2011/09/29 07:44:01 [debug] 14365#0: accept mutex locked
2011/09/29 07:44:01 [debug] 14365#0: epoll timer: 5000
2011/09/29 07:44:01 [debug] 14365#0: epoll: fd:6 ev:0001 d:086F8528
2011/09/29 07:44:01 [debug] 14365#0: post event 08711530
2011/09/29 07:44:01 [debug] 14365#0: timer delta: 10
2011/09/29 07:44:01 [debug] 14365#0: posted event 08711530
2011/09/29 07:44:01 [debug] 14365#0: delete posted event 08711530
2011/09/29 07:44:01 [debug] 14365#0: accept on 0.0.0.0:80, ready: 0
2011/09/29 07:44:01 [debug] 14365#0: posix_memalign: 086E0310:256 @16
2011/09/29 07:44:01 [debug] 14365#0: *4 accept: 10.35.9.129 fd:12
2011/09/29 07:44:01 [debug] 14365#0: *4 event timer add: 12:
60000:3036709357
2011/09/29 07:44:01 [debug] 14365#0: *4 epoll add event: fd:12 op:1
ev:80000001
2011/09/29 07:44:01 [debug] 14365#0: posted event 00000000
2011/09/29 07:44:01 [debug] 14365#0: posted events 00000000
2011/09/29 07:44:01 [debug] 14365#0: worker cycle
2011/09/29 07:44:01 [debug] 14365#0: accept mutex locked
2011/09/29 07:44:01 [debug] 14365#0: epoll timer: 4990
2011/09/29 07:44:01 [debug] 14365#0: epoll: fd:6 ev:0001 d:086F8528
2011/09/29 07:44:01 [debug] 14365#0: post event 08711530
2011/09/29 07:44:01 [debug] 14365#0: timer delta: 8
2011/09/29 07:44:01 [debug] 14365#0: posted event 08711530
2011/09/29 07:44:01 [debug] 14365#0: delete posted event 08711530
2011/09/29 07:44:01 [debug] 14365#0: accept on 0.0.0.0:80, ready: 0
2011/09/29 07:44:01 [debug] 14365#0: posix_memalign: 086E0440:256 @16
2011/09/29 07:44:01 [debug] 14365#0: *5 accept: 10.35.9.129 fd:13
2011/09/29 07:44:01 [debug] 14365#0: *5 event timer add: 13:
60000:3036709365
2011/09/29 07:44:01 [debug] 14365#0: *5 epoll add event: fd:13 op:1
ev:80000001
2011/09/29 07:44:01 [debug] 14365#0: posted event 00000000
2011/09/29 07:44:01 [debug] 14365#0: posted events 00000000
2011/09/29 07:44:01 [debug] 14365#0: worker cycle
2011/09/29 07:44:01 [debug] 14365#0: accept mutex locked
2011/09/29 07:44:01 [debug] 14365#0: epoll timer: 4982
2011/09/29 07:44:01 [debug] 14365#0: epoll: fd:12 ev:0001 d:086F8654
2011/09/29 07:44:01 [debug] 14365#0: *4 post event 087115CC
2011/09/29 07:44:01 [debug] 14365#0: timer delta: 5
2011/09/29 07:44:01 [debug] 14365#0: posted events 087115CC
2011/09/29 07:44:01 [debug] 14365#0: posted event 087115CC
2011/09/29 07:44:01 [debug] 14365#0: *4 delete posted event 087115CC
2011/09/29 07:44:01 [debug] 14365#0: *4 malloc: 086E0548:680
2011/09/29 07:44:01 [debug] 14365#0: *4 malloc: 086E07F8:1024
2011/09/29 07:44:01 [debug] 14365#0: *4 posix_memalign: 086E0C00:4096
@16
2011/09/29 07:44:01 [debug] 14365#0: *4 http process request line
2011/09/29 07:44:01 [debug] 14365#0: *4 recv: fd:12 458 of 1024
2011/09/29 07:44:01 [debug] 14365#0: *4 http request line: “GET
/css/administration/modules/dashboard.css HTTP/1.1”
2011/09/29 07:44:01 [debug] 14365#0: *4 http uri:
“/css/administration/modules/dashboard.css”
2011/09/29 07:44:01 [debug] 14365#0: *4 http args: “”
2011/09/29 07:44:01 [debug] 14365#0: *4 http exten: “css”
2011/09/29 07:44:01 [debug] 14365#0: *4 http process request header
line
2011/09/29 07:44:01 [debug] 14365#0: *4 http header: “Host:
10.128.50.101”
2011/09/29 07:44:01 [debug] 14365#0: 4 http header: “User-Agent:
Mozilla/5.0 (Windows NT 5.1; rv:7.0) Gecko/20100101 Firefox/7.0”
2011/09/29 07:44:01 [debug] 14365#0: 4 http header: "Accept:
text/css,/;q=0.1"
2011/09/29 07:44:01 [debug] 14365#0: *4 http header: “Accept-Language:
es-es,es;q=0.8,en-us;q=0.5,en;q=0.3”
2011/09/29 07:44:01 [debug] 14365#0: *4 http header: “Accept-Encoding:
gzip, deflate”
2011/09/29 07:44:01 [debug] 14365#0: 4 http header: "Accept-Charset:
ISO-8859-1,utf-8;q=0.7,;q=0.7"
2011/09/29 07:44:01 [debug] 14365#0: *4 http header: “Connection:
keep-alive”
2011/09/29 07:44:01 [debug] 14365#0: *4 http header: “Referer:
http://10.128.50.101/administration.php/sf_guard_group”
2011/09/29 07:44:01 [debug] 14365#0: *4 http header: “Cookie:
zera=vmlib90drktmrpmo3gdra6hi12; has_js=1”
2011/09/29 07:44:01 [debug] 14365#0: *4 http header done
2011/09/29 07:44:01 [debug] 14365#0: *4 event timer del: 12: 3036709357
2011/09/29 07:44:01 [debug] 14365#0: *4 generic phase: 0
2011/09/29 07:44:01 [debug] 14365#0: *4 rewrite phase: 1
2011/09/29 07:44:01 [debug] 14365#0: *4 test location: “/404.html”
2011/09/29 07:44:01 [debug] 14365#0: *4 test location: “/sf/”
2011/09/29 07:44:01 [debug] 14365#0: *4 test location: ~
“.+.(js|htc|ico|gif|jpg|png|css)$”
2011/09/29 07:44:01 [debug] 14365#0: *4 using configuration
“.+.(js|htc|ico|gif|jpg|png|css)$”
2011/09/29 07:44:01 [debug] 14365#0: *4 http cl:-1 max:1048576
2011/09/29 07:44:01 [debug] 14365#0: *4 rewrite phase: 3
2011/09/29 07:44:01 [debug] 14365#0: *4 post rewrite phase: 4
2011/09/29 07:44:01 [debug] 14365#0: *4 generic phase: 5
2011/09/29 07:44:01 [debug] 14365#0: *4 generic phase: 6
2011/09/29 07:44:01 [debug] 14365#0: *4 generic phase: 7
2011/09/29 07:44:01 [debug] 14365#0: *4 access phase: 8
2011/09/29 07:44:01 [debug] 14365#0: *4 access phase: 9
2011/09/29 07:44:01 [debug] 14365#0: *4 post access phase: 10
2011/09/29 07:44:01 [debug] 14365#0: *4 content phase: 11
2011/09/29 07:44:01 [debug] 14365#0: *4 content phase: 12
2011/09/29 07:44:01 [debug] 14365#0: *4 content phase: 13
2011/09/29 07:44:01 [debug] 14365#0: *4 content phase: 14
2011/09/29 07:44:01 [debug] 14365#0: *4 content phase: 15
2011/09/29 07:44:01 [debug] 14365#0: *4 content phase: 16
2011/09/29 07:44:01 [debug] 14365#0: *4 http filename:
“/var/www/appname/web/css/administration/modules/dashboard.css”
2011/09/29 07:44:01 [debug] 14365#0: *4 add cleanup: 086E123C
2011/09/29 07:44:01 [debug] 14365#0: *4 http static fd: 14
2011/09/29 07:44:01 [debug] 14365#0: *4 http set discard body
2011/09/29 07:44:01 [debug] 14365#0: *4 HTTP/1.1 200 OK
Server: nginx/1.1.4
Date: Thu, 29 Sep 2011 11:44:01 GMT
Content-Type: text/css
Content-Length: 3785
Last-Modified: Wed, 28 Sep 2011 15:47:21 GMT
Connection: keep-alive
Expires: Thu, 31 Dec 2037 23:55:55 GMT
Cache-Control: max-age=315360000
Accept-Ranges: bytes

Posted at Nginx Forum: