Lexa Software: Nginx-ru@sysoev.ru archive

> > В отладочном логе видно, что клиенту уходит Set-Cookie ?<http://nginx.org/mailman/listinfo/nginx-ru>

> >
>
> Отладочный лог не делал, но кука появляется, смотрел в firefox через -
> информация о странице -> защита -> посмотреть куки. Сделать отладочный лог?

http://nginx.org/en/docs/debugging_log.html

Поставил nginx из портов с опцией отладки, эффект повторился (первый раз не пускает, второй раз пускает). Насколько я понял по логу - клиенту уходит Set-Cookie.

www# nginx -V
nginx version: nginx/0.8.53
TLS SNI support enabled
configure arguments: --prefix=/usr/local/etc/nginx --with-cc-opt='-I /usr/local/include' --with-ld-opt='-L /usr/local/lib' --conf-path=/usr/local/etc/nginx/nginx.conf --sbin-path=/usr/local/sbin/nginx --pid-path=/var/run/nginx.pid --error-log-path=/var/log/nginx-error.log --user=www --group=www --with-debug --with-file-aio --with-google_perftools_module --http-client-body-temp-path=/var/tmp/nginx/client_body_temp --http-fastcgi-temp-path=/var/tmp/nginx/fastcgi_temp --http-proxy-temp-path=/var/tmp/nginx/proxy_temp --http-scgi-temp-path=/var/tmp/nginx/scgi_temp --http-uwsgi-temp-path=/var/tmp/nginx/uwsgi_temp --http-log-path=/var/log/nginx-access.log --with-http_addition_module --with-http_gzip_static_module --with-http_random_index_module --with-http_ssl_module --with-http_stub_status_module --with-pcre

2010/11/16 16:28:59 [debug] 45125#0: bind() 0.0.0.0:80 #6 2010/11/16 16:28:59 [debug] 45125#0: counter: 28576080, 1 2010/11/16 16:28:59 [debug] 45135#0: bind() 0.0.0.0:80 #6 2010/11/16 16:28:59 [debug] 45135#0: counter: 28576080, 1 2010/11/16 16:28:59 [debug] 45136#0: bind() 0.0.0.0:80 #6 2010/11/16 16:28:59 [notice] 45136#0: using the "kqueue" event method 2010/11/16 16:28:59 [debug] 45136#0: counter: 28576080, 1 2010/11/16 16:28:59 [notice] 45136#0: nginx/0.8.53 2010/11/16 16:28:59 [notice] 45136#0: OS: FreeBSD 8.0-STABLE 2010/11/16 16:28:59 [notice] 45136#0: kern.osreldate: 800108, built on 800108 2010/11/16 16:28:59 [notice] 45136#0: hw.ncpu: 1 2010/11/16 16:28:59 [notice] 45136#0: net.inet.tcp.sendspace: 32768 2010/11/16 16:28:59 [notice] 45136#0: kern.ipc.somaxconn: 18641 2010/11/16 16:28:59 [notice] 45136#0: getrlimit(RLIMIT_NOFILE): 2000:2000 2010/11/16 16:28:59 [debug] 45137#0: write: 7, BFBFEA3E, 6, 0 2010/11/16 16:28:59 [notice] 45137#0: start worker processes 2010/11/16 16:28:59 [debug] 45137#0: channel 3:7 2010/11/16 16:28:59 [notice] 45137#0: start worker process 45138 2010/11/16 16:28:59 [debug] 45137#0: channel 8:9 2010/11/16 16:28:59 [notice] 45137#0: start worker process 45139 2010/11/16 16:28:59 [debug] 45137#0: pass channel s:1 pid:45139 fd:8 to s:0 pid:45138 fd:3 2010/11/16 16:28:59 [debug] 45137#0: channel 10:11 2010/11/16 16:28:59 [notice] 45137#0: start cache manager process 45140 2010/11/16 16:28:59 [debug] 45137#0: pass channel s:2 pid:45140 fd:10 to s:0 pid:45138 fd:3 2010/11/16 16:28:59 [debug] 45137#0: pass channel s:2 pid:45140 fd:10 to s:1 pid:45139 fd:8 2010/11/16 16:28:59 [debug] 45137#0: channel 12:13 2010/11/16 16:28:59 [notice] 45137#0: start cache loader process 45141 2010/11/16 16:28:59 [debug] 45137#0: pass channel s:3 pid:45141 fd:12 to s:0 pid:45138 fd:3 2010/11/16 16:28:59 [debug] 45137#0: pass channel s:3 pid:45141 fd:12 to s:1 pid:45139 fd:8 2010/11/16 16:28:59 [debug] 45137#0: pass channel s:3 pid:45141 fd:12 to s:2 pid:45140 fd:10 2010/11/16 16:28:59 [debug] 45137#0: sigsuspend 2010/11/16 16:28:59 [debug] 45138#0: malloc: 28648000:10240 2010/11/16 16:28:59 [debug] 45138#0: malloc: 2867A000:10240 2010/11/16 16:28:59 [debug] 45138#0: malloc: 2867D000:10240 2010/11/16 16:28:59 [debug] 45138#0: malloc: 28680000:98304 2010/11/16 16:28:59 [debug] 45138#0: malloc: 28698000:61440 2010/11/16 16:28:59 [debug] 45138#0: malloc: 286A7000:61440 2010/11/16 16:28:59 [debug] 45138#0: kevent set event: 7: ft:-1 fl:0005 2010/11/16 16:28:59 [debug] 45138#0: worker cycle 2010/11/16 16:28:59 [debug] 45138#0: accept mutex locked 2010/11/16 16:28:59 [debug] 45138#0: kevent set event: 6: ft:-1 fl:0005 2010/11/16 16:28:59 [debug] 45138#0: kevent timer: -1, changes: 2 2010/11/16 16:28:59 [debug] 45138#0: kevent events: 1 2010/11/16 16:28:59 [debug] 45138#0: kevent: 7: ft:-1 fl:0000 ff:00000000 d:48 ud:2869803C 2010/11/16 16:28:59 [debug] 45138#0: post event 2869803C 2010/11/16 16:28:59 [debug] 45138#0: timer delta: 40 2010/11/16 16:28:59 [debug] 45138#0: posted events 2869803C 2010/11/16 16:28:59 [debug] 45138#0: posted event 2869803C 2010/11/16 16:28:59 [debug] 45138#0: delete posted event 2869803C 2010/11/16 16:28:59 [debug] 45138#0: channel handler 2010/11/16 16:28:59 [notice] 45137#0: signal 23 (SIGIO) received 2010/11/16 16:28:59 [debug] 45137#0: wake up, sigio 0 2010/11/16 16:28:59 [debug] 45137#0: sigsuspend 2010/11/16 16:28:59 [debug] 45139#0: malloc: 28648000:10240 2010/11/16 16:28:59 [debug] 45139#0: malloc: 2867A000:10240 2010/11/16 16:28:59 [debug] 45139#0: malloc: 2867D000:10240 2010/11/16 16:28:59 [debug] 45139#0: malloc: 28680000:98304 2010/11/16 16:28:59 [debug] 45139#0: malloc: 28698000:61440 2010/11/16 16:28:59 [debug] 45139#0: malloc: 286A7000:61440 2010/11/16 16:28:59 [debug] 45139#0: kevent set event: 9: ft:-1 fl:0005 2010/11/16 16:28:59 [debug] 45139#0: worker cycle 2010/11/16 16:28:59 [debug] 45139#0: accept mutex locked 2010/11/16 16:28:59 [debug] 45139#0: kevent set event: 6: ft:-1 fl:0005 2010/11/16 16:28:59 [debug] 45139#0: kevent timer: -1, changes: 2 2010/11/16 16:28:59 [debug] 45139#0: kevent events: 1 2010/11/16 16:28:59 [debug] 45139#0: kevent: 9: ft:-1 fl:0000 ff:00000000 d:32 ud:2869803C 2010/11/16 16:28:59 [debug] 45139#0: post event 2869803C 2010/11/16 16:28:59 [debug] 45139#0: timer delta: 42 2010/11/16 16:28:59 [debug] 45139#0: posted events 2869803C 2010/11/16 16:28:59 [debug] 45139#0: posted event 2869803C 2010/11/16 16:28:59 [debug] 45139#0: delete posted event 2869803C 2010/11/16 16:28:59 [debug] 45139#0: channel handler 2010/11/16 16:28:59 [notice] 45137#0: signal 23 (SIGIO) received 2010/11/16 16:28:59 [debug] 45137#0: wake up, sigio 0 2010/11/16 16:28:59 [debug] 45137#0: sigsuspend 2010/11/16 16:28:59 [debug] 45140#0: malloc: 28648000:10240 2010/11/16 16:28:59 [debug] 45140#0: malloc: 2867A000:10240 2010/11/16 16:28:59 [debug] 45140#0: malloc: 2867D000:10240 2010/11/16 16:28:59 [debug] 45140#0: malloc: 28680000:49152 2010/11/16 16:28:59 [debug] 45140#0: malloc: 2868C000:30720 2010/11/16 16:28:59 [debug] 45140#0: malloc: 28694000:30720 2010/11/16 16:28:59 [debug] 45140#0: kevent set event: 11: ft:-1 fl:0005 2010/11/16 16:28:59 [debug] 45140#0: close listening 0.0.0.0:80 #6 2010/11/16 16:28:59 [debug] 45140#0: event timer add: -1: 0:1405951059 2010/11/16 16:28:59 [debug] 45140#0: kevent timer: 0, changes: 1 2010/11/16 16:28:59 [debug] 45140#0: kevent events: 1 2010/11/16 16:28:59 [debug] 45140#0: kevent: 11: ft:-1 fl:0000 ff:00000000 d:16 ud:2868C03C 2010/11/16 16:28:59 [debug] 45140#0: channel handler 2010/11/16 16:28:59 [notice] 45137#0: signal 23 (SIGIO) received 2010/11/16 16:28:59 [debug] 45137#0: wake up, sigio 0 2010/11/16 16:28:59 [debug] 45137#0: sigsuspend 2010/11/16 16:28:59 [debug] 45141#0: malloc: 28648000:10240 2010/11/16 16:28:59 [debug] 45141#0: malloc: 2867A000:10240 2010/11/16 16:28:59 [debug] 45141#0: malloc: 2867D000:10240 2010/11/16 16:28:59 [debug] 45141#0: malloc: 28680000:49152 2010/11/16 16:28:59 [debug] 45141#0: malloc: 2868C000:30720 2010/11/16 16:28:59 [debug] 45141#0: malloc: 28694000:30720 2010/11/16 16:28:59 [debug] 45141#0: kevent set event: 13: ft:-1 fl:0005 2010/11/16 16:28:59 [debug] 45141#0: close listening 0.0.0.0:80 #6 2010/11/16 16:28:59 [debug] 45141#0: event timer add: -1: 60000:1406011059 2010/11/16 16:28:59 [debug] 45141#0: kevent timer: 60000, changes: 1 2010/11/16 16:28:59 [debug] 45138#0: channel: 16 2010/11/16 16:28:59 [debug] 45138#0: channel command: 1 2010/11/16 16:28:59 [debug] 45138#0: get channel s:1 pid:45139 fd:3 2010/11/16 16:28:59 [notice] 45137#0: signal 23 (SIGIO) received 2010/11/16 16:28:59 [debug] 45137#0: wake up, sigio 0 2010/11/16 16:28:59 [debug] 45137#0: sigsuspend 2010/11/16 16:28:59 [debug] 45139#0: channel: 16 2010/11/16 16:28:59 [debug] 45139#0: channel command: 1 2010/11/16 16:28:59 [debug] 45139#0: get channel s:2 pid:45140 fd:7 2010/11/16 16:28:59 [notice] 45137#0: signal 23 (SIGIO) received 2010/11/16 16:28:59 [debug] 45137#0: wake up, sigio 0 2010/11/16 16:28:59 [debug] 45137#0: sigsuspend 2010/11/16 16:28:59 [debug] 45140#0: channel: 16 2010/11/16 16:28:59 [debug] 45140#0: channel command: 1 2010/11/16 16:28:59 [debug] 45140#0: get channel s:3 pid:45141 fd:6 2010/11/16 16:28:59 [debug] 45140#0: channel: -2 2010/11/16 16:28:59 [debug] 45140#0: timer delta: 43 2010/11/16 16:28:59 [debug] 45140#0: event timer del: -1: 1405951059 2010/11/16 16:28:59 [debug] 45140#0: http file cache expire 2010/11/16 16:28:59 [debug] 45140#0: malloc: 28608400:55 2010/11/16 16:28:59 [debug] 45140#0: http file cache size: 0 2010/11/16 16:28:59 [debug] 45140#0: event timer add: -1: 10000:1405961104 2010/11/16 16:28:59 [debug] 45140#0: posted events 00000000 2010/11/16 16:28:59 [debug] 45140#0: kevent timer: 10000, changes: 0 2010/11/16 16:28:59 [debug] 45138#0: channel: 16 2010/11/16 16:28:59 [debug] 45138#0: channel command: 1 2010/11/16 16:28:59 [debug] 45138#0: get channel s:2 pid:45140 fd:9 2010/11/16 16:28:59 [notice] 45137#0: signal 23 (SIGIO) received 2010/11/16 16:28:59 [debug] 45137#0: wake up, sigio 0 2010/11/16 16:28:59 [debug] 45137#0: sigsuspend 2010/11/16 16:28:59 [debug] 45139#0: channel: 16 2010/11/16 16:28:59 [debug] 45139#0: channel command: 1 2010/11/16 16:28:59 [debug] 45139#0: get channel s:3 pid:45141 fd:8 2010/11/16 16:28:59 [debug] 45139#0: channel: -2 2010/11/16 16:28:59 [debug] 45139#0: posted event 00000000 2010/11/16 16:28:59 [debug] 45139#0: worker cycle 2010/11/16 16:28:59 [debug] 45139#0: accept mutex locked 2010/11/16 16:28:59 [debug] 45139#0: kevent timer: -1, changes: 0 2010/11/16 16:28:59 [debug] 45138#0: channel: 16 2010/11/16 16:28:59 [debug] 45138#0: channel command: 1 2010/11/16 16:28:59 [debug] 45138#0: get channel s:3 pid:45141 fd:10 2010/11/16 16:28:59 [debug] 45138#0: channel: -2 2010/11/16 16:28:59 [debug] 45138#0: posted event 00000000 2010/11/16 16:28:59 [debug] 45138#0: worker cycle 2010/11/16 16:28:59 [debug] 45138#0: accept mutex lock failed: 1 2010/11/16 16:28:59 [debug] 45138#0: kevent set event: 6: ft:-1 fl:0008 2010/11/16 16:28:59 [debug] 45138#0: kevent timer: 500, changes: 1 2010/11/16 16:29:00 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:00 [debug] 45138#0: timer delta: 507 2010/11/16 16:29:00 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:00 [debug] 45138#0: worker cycle 2010/11/16 16:29:00 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:00 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:00 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:00 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:00 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:00 [debug] 45138#0: worker cycle 2010/11/16 16:29:00 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:00 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:01 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:01 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:01 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:01 [debug] 45138#0: worker cycle 2010/11/16 16:29:01 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:01 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:01 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:01 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:01 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:01 [debug] 45138#0: worker cycle 2010/11/16 16:29:01 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:01 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:02 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:02 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:02 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:02 [debug] 45138#0: worker cycle 2010/11/16 16:29:02 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:02 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:02 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:02 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:02 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:02 [debug] 45138#0: worker cycle 2010/11/16 16:29:02 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:02 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:03 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:03 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:03 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:03 [debug] 45138#0: worker cycle 2010/11/16 16:29:03 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:03 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:03 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:03 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:03 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:03 [debug] 45138#0: worker cycle 2010/11/16 16:29:03 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:03 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:04 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:04 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:04 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:04 [debug] 45138#0: worker cycle 2010/11/16 16:29:04 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:04 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:04 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:04 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:04 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:04 [debug] 45138#0: worker cycle 2010/11/16 16:29:04 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:04 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:05 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:05 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:05 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:05 [debug] 45138#0: worker cycle 2010/11/16 16:29:05 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:05 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:05 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:05 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:05 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:05 [debug] 45138#0: worker cycle 2010/11/16 16:29:05 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:05 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:06 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:06 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:06 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:06 [debug] 45138#0: worker cycle 2010/11/16 16:29:06 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:06 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:06 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:06 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:06 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:06 [debug] 45138#0: worker cycle 2010/11/16 16:29:06 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:06 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:07 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:07 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:07 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:07 [debug] 45138#0: worker cycle 2010/11/16 16:29:07 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:07 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:07 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:07 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:07 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:07 [debug] 45138#0: worker cycle 2010/11/16 16:29:07 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:07 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:08 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:08 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:08 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:08 [debug] 45138#0: worker cycle 2010/11/16 16:29:08 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:08 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:08 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:08 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:08 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:08 [debug] 45138#0: worker cycle 2010/11/16 16:29:08 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:08 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:09 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:09 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:09 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:09 [debug] 45138#0: worker cycle 2010/11/16 16:29:09 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:09 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:09 [debug] 45140#0: kevent events: 0 2010/11/16 16:29:09 [debug] 45140#0: timer delta: 10001 2010/11/16 16:29:09 [debug] 45140#0: event timer del: -1: 1405961104 2010/11/16 16:29:09 [debug] 45140#0: http file cache expire 2010/11/16 16:29:09 [debug] 45140#0: malloc: 28608400:55 2010/11/16 16:29:09 [debug] 45140#0: http file cache size: 0 2010/11/16 16:29:09 [debug] 45140#0: event timer add: -1: 10000:1405971105 2010/11/16 16:29:09 [debug] 45140#0: posted events 00000000 2010/11/16 16:29:09 [debug] 45140#0: kevent timer: 10000, changes: 0 2010/11/16 16:29:09 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:09 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:09 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:09 [debug] 45138#0: worker cycle 2010/11/16 16:29:09 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:09 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:10 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:10 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:10 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:10 [debug] 45138#0: worker cycle 2010/11/16 16:29:10 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:10 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:10 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:10 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:10 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:10 [debug] 45138#0: worker cycle 2010/11/16 16:29:10 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:10 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:11 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:11 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:11 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:11 [debug] 45138#0: worker cycle 2010/11/16 16:29:11 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:11 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:11 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:11 [debug] 45138#0: timer delta: 505 2010/11/16 16:29:11 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:11 [debug] 45138#0: worker cycle 2010/11/16 16:29:11 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:11 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:12 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:12 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:12 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:12 [debug] 45138#0: worker cycle 2010/11/16 16:29:12 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:12 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:12 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:12 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:12 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:12 [debug] 45138#0: worker cycle 2010/11/16 16:29:12 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:12 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:13 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:13 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:13 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:13 [debug] 45138#0: worker cycle 2010/11/16 16:29:13 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:13 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:13 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:13 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:13 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:13 [debug] 45138#0: worker cycle 2010/11/16 16:29:13 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:13 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:14 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:14 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:14 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:14 [debug] 45138#0: worker cycle 2010/11/16 16:29:14 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:14 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:14 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:14 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:14 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:14 [debug] 45138#0: worker cycle 2010/11/16 16:29:14 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:14 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:15 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:15 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:15 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:15 [debug] 45138#0: worker cycle 2010/11/16 16:29:15 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:15 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:15 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:15 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:15 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:15 [debug] 45138#0: worker cycle 2010/11/16 16:29:15 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:15 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:16 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:16 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:16 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:16 [debug] 45138#0: worker cycle 2010/11/16 16:29:16 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:16 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:16 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:16 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:16 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:16 [debug] 45138#0: worker cycle 2010/11/16 16:29:16 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:16 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:17 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:17 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:17 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:17 [debug] 45138#0: worker cycle 2010/11/16 16:29:17 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:17 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:17 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:17 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:17 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:17 [debug] 45138#0: worker cycle 2010/11/16 16:29:17 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:17 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:18 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:18 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:18 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:18 [debug] 45138#0: worker cycle 2010/11/16 16:29:18 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:18 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:18 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:18 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:18 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:18 [debug] 45138#0: worker cycle 2010/11/16 16:29:18 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:18 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:19 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:19 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:19 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:19 [debug] 45138#0: worker cycle 2010/11/16 16:29:19 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:19 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:19 [debug] 45140#0: kevent events: 0 2010/11/16 16:29:19 [debug] 45140#0: timer delta: 10002 2010/11/16 16:29:19 [debug] 45140#0: event timer del: -1: 1405971105 2010/11/16 16:29:19 [debug] 45140#0: http file cache expire 2010/11/16 16:29:19 [debug] 45140#0: malloc: 28608400:55 2010/11/16 16:29:19 [debug] 45140#0: http file cache size: 0 2010/11/16 16:29:19 [debug] 45140#0: event timer add: -1: 10000:1405981107 2010/11/16 16:29:19 [debug] 45140#0: posted events 00000000 2010/11/16 16:29:19 [debug] 45140#0: kevent timer: 10000, changes: 0 2010/11/16 16:29:19 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:19 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:19 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:19 [debug] 45138#0: worker cycle 2010/11/16 16:29:19 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:19 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:20 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:20 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:20 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:20 [debug] 45138#0: worker cycle 2010/11/16 16:29:20 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:20 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:20 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:20 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:20 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:20 [debug] 45138#0: worker cycle 2010/11/16 16:29:20 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:20 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:21 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:21 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:21 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:21 [debug] 45138#0: worker cycle 2010/11/16 16:29:21 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:21 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:21 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:21 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:21 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:21 [debug] 45138#0: worker cycle 2010/11/16 16:29:21 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:21 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:22 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:22 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:22 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:22 [debug] 45138#0: worker cycle 2010/11/16 16:29:22 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:22 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:22 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:22 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:22 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:22 [debug] 45138#0: worker cycle 2010/11/16 16:29:22 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:22 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:23 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:23 [debug] 45139#0: kevent: 6: ft:-1 fl:0000 ff:00000000 d:1 ud:28698000 2010/11/16 16:29:23 [debug] 45139#0: post event 28698000 2010/11/16 16:29:23 [debug] 45139#0: timer delta: 23540 2010/11/16 16:29:23 [debug] 45139#0: posted event 28698000 2010/11/16 16:29:23 [debug] 45139#0: delete posted event 28698000 2010/11/16 16:29:23 [debug] 45139#0: accept on 0.0.0.0:80, ready: 1 2010/11/16 16:29:23 [debug] 45139#0: posix_memalign: 28640200:256 @16 2010/11/16 16:29:23 [debug] 45139#0: *1 accept: 84.237.22.11 fd:11 2010/11/16 16:29:23 [debug] 45139#0: *1 event timer add: 11: 60000:1406034641 2010/11/16 16:29:23 [debug] 45139#0: *1 kevent set event: 11: ft:-1 fl:0025 2010/11/16 16:29:23 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:23 [debug] 45139#0: posted events 00000000 2010/11/16 16:29:23 [debug] 45139#0: worker cycle 2010/11/16 16:29:23 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:23 [debug] 45139#0: kevent timer: 60000, changes: 1 2010/11/16 16:29:23 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:23 [debug] 45139#0: kevent: 11: ft:-1 fl:0020 ff:00000000 d:369 ud:28698078 2010/11/16 16:29:23 [debug] 45139#0: *1 post event 28698078 2010/11/16 16:29:23 [debug] 45139#0: timer delta: 0 2010/11/16 16:29:23 [debug] 45139#0: posted events 28698078 2010/11/16 16:29:23 [debug] 45139#0: posted event 28698078 2010/11/16 16:29:23 [debug] 45139#0: *1 delete posted event 28698078 2010/11/16 16:29:23 [debug] 45139#0: *1 malloc: 286B6300:656 2010/11/16 16:29:23 [debug] 45139#0: *1 malloc: 28628400:1024 2010/11/16 16:29:23 [debug] 45139#0: *1 posix_memalign: 28612000:4096 @16 2010/11/16 16:29:23 [debug] 45139#0: *1 http process request line 2010/11/16 16:29:23 [debug] 45139#0: *1 recv: eof:0, avail:369, err:0 2010/11/16 16:29:23 [debug] 45139#0: *1 recv: fd:11 369 of 1024 2010/11/16 16:29:23 [debug] 45139#0: *1 http request line: "GET /login HTTP/1.1" 2010/11/16 16:29:23 [debug] 45139#0: *1 http uri: "/login" 2010/11/16 16:29:23 [debug] 45139#0: *1 http args: "" 2010/11/16 16:29:23 [debug] 45139#0: *1 http exten: "" 2010/11/16 16:29:23 [debug] 45139#0: *1 http process request header line 2010/11/16 16:29:23 [debug] 45139#0: *1 http header: "Host: sei.irk.ru" 2010/11/16 16:29:23 [debug] 45139#0: *1 http header: "User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3" 2010/11/16 16:29:23 [debug] 45139#0: *1 http header: "Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8" 2010/11/16 16:29:23 [debug] 45139#0: *1 http header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2010/11/16 16:29:23 [debug] 45139#0: *1 http header: "Accept-Encoding: gzip,deflate" 2010/11/16 16:29:23 [debug] 45139#0: *1 http header: "Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7" 2010/11/16 16:29:23 [debug] 45139#0: *1 http header: "Keep-Alive: 115" 2010/11/16 16:29:23 [debug] 45139#0: *1 http header: "Connection: keep-alive" 2010/11/16 16:29:23 [debug] 45139#0: *1 http header done 2010/11/16 16:29:23 [debug] 45139#0: *1 event timer del: 11: 1406034641 2010/11/16 16:29:23 [debug] 45139#0: *1 rewrite phase: 0 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: "/" 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: "index.jsp" 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: "publications.jsp" 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: ~ "/tel" 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: ~ "/mail" 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: ~ "/manager" 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: ~ "/admin" 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: ~ "/chronicles" 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: ~ "/symp2010" 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: ~ "^/(aec|articles|diser|history|ieee_pes_russiancapter|ieee_pes_wg_asia_australia|ircei|les|library|powerdiscussion|ppt|rfbg|rfn|sei34|semtps)" 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: ~ "^/manager.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: ~ "^/admin.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: ~ "^.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: ~ "^.+\.(pdf|doc|txt)$" 2010/11/16 16:29:23 [debug] 45139#0: *1 test location: ~ "^.+\.(js|css)$" 2010/11/16 16:29:23 [debug] 45139#0: *1 using configuration "/" 2010/11/16 16:29:23 [debug] 45139#0: *1 http cl:-1 max:157286400 2010/11/16 16:29:23 [debug] 45139#0: *1 rewrite phase: 2 2010/11/16 16:29:23 [debug] 45139#0: *1 post rewrite phase: 3 2010/11/16 16:29:23 [debug] 45139#0: *1 generic phase: 4 2010/11/16 16:29:23 [debug] 45139#0: *1 generic phase: 5 2010/11/16 16:29:23 [debug] 45139#0: *1 access phase: 6 2010/11/16 16:29:23 [debug] 45139#0: *1 access phase: 7 2010/11/16 16:29:23 [debug] 45139#0: *1 post access phase: 8 2010/11/16 16:29:23 [debug] 45139#0: *1 try files phase: 9 2010/11/16 16:29:23 [debug] 45139#0: *1 http init upstream, client timer: 0 2010/11/16 16:29:23 [debug] 45139#0: *1 kevent set event: 11: ft:-2 fl:0025 2010/11/16 16:29:23 [debug] 45139#0: *1 http script copy: "Host: " 2010/11/16 16:29:23 [debug] 45139#0: *1 http script var: "sei.irk.ru" 2010/11/16 16:29:23 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:23 [debug] 45139#0: *1 http script copy: "X-Real-IP: " 2010/11/16 16:29:23 [debug] 45139#0: *1 http script var: "84.237.22.11" 2010/11/16 16:29:23 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:23 [debug] 45139#0: *1 http script copy: "X-Forwarded-For: " 2010/11/16 16:29:23 [debug] 45139#0: *1 http script var: "84.237.22.11" 2010/11/16 16:29:23 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:23 [debug] 45139#0: *1 http script copy: "Connection: close " 2010/11/16 16:29:23 [debug] 45139#0: *1 http proxy header: "User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3" 2010/11/16 16:29:23 [debug] 45139#0: *1 http proxy header: "Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8" 2010/11/16 16:29:23 [debug] 45139#0: *1 http proxy header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2010/11/16 16:29:23 [debug] 45139#0: *1 http proxy header: "Accept-Encoding: gzip,deflate" 2010/11/16 16:29:23 [debug] 45139#0: *1 http proxy header: "Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7" 2010/11/16 16:29:23 [debug] 45139#0: *1 http proxy header: "GET /login HTTP/1.0 Host: sei.irk.ru X-Real-IP: 84.237.22.11 X-Forwarded-For: 84.237.22.11 Connection: close User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3 Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8 Accept-Language: ru,en-us;q=0.7,en;q=0.3 Accept-Encoding: gzip,deflate Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7 " 2010/11/16 16:29:23 [debug] 45139#0: *1 http cleanup add: 28612A0C 2010/11/16 16:29:23 [debug] 45139#0: *1 get rr peer, try: 1 2010/11/16 16:29:23 [debug] 45139#0: *1 socket 12 2010/11/16 16:29:23 [debug] 45139#0: *1 connect to 127.0.0.1:8180, fd:12 #2 2010/11/16 16:29:23 [debug] 45139#0: *1 kevent set event: 12: ft:-1 fl:0025 2010/11/16 16:29:23 [debug] 45139#0: *1 connected 2010/11/16 16:29:23 [debug] 45139#0: *1 http upstream connect: 0 2010/11/16 16:29:23 [debug] 45139#0: *1 http upstream send request 2010/11/16 16:29:23 [debug] 45139#0: *1 chain writer buf fl:1 s:403 2010/11/16 16:29:23 [debug] 45139#0: *1 chain writer in: 28612A28 2010/11/16 16:29:23 [debug] 45139#0: *1 writev: 403 of 403 2010/11/16 16:29:23 [debug] 45139#0: *1 chain writer out: 00000000 2010/11/16 16:29:23 [debug] 45139#0: *1 event timer add: 12: 60000:1406034641 2010/11/16 16:29:23 [debug] 45139#0: *1 http finalize request: -4, "/login?" a:1, c:2 2010/11/16 16:29:23 [debug] 45139#0: *1 http request count:2 blk:0 2010/11/16 16:29:23 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:23 [debug] 45139#0: worker cycle 2010/11/16 16:29:23 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:23 [debug] 45139#0: kevent timer: 60000, changes: 2 2010/11/16 16:29:23 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:23 [debug] 45139#0: kevent: 11: ft:-2 fl:0020 ff:00000000 d:33696 ud:286A7078 2010/11/16 16:29:23 [debug] 45139#0: *1 post event 286A7078 2010/11/16 16:29:23 [debug] 45139#0: timer delta: 1 2010/11/16 16:29:23 [debug] 45139#0: posted events 286A7078 2010/11/16 16:29:23 [debug] 45139#0: posted event 286A7078 2010/11/16 16:29:23 [debug] 45139#0: *1 delete posted event 286A7078 2010/11/16 16:29:23 [debug] 45139#0: *1 http run request: "/login?" 2010/11/16 16:29:23 [debug] 45139#0: *1 http upstream check client, write event:1, "/login" 2010/11/16 16:29:23 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:23 [debug] 45139#0: worker cycle 2010/11/16 16:29:23 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:23 [debug] 45139#0: kevent timer: 59999, changes: 0 2010/11/16 16:29:23 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:23 [debug] 45139#0: kevent: 12: ft:-1 fl:8020 ff:00000000 d:1345 ud:286980B4 2010/11/16 16:29:23 [debug] 45139#0: *1 post event 286980B4 2010/11/16 16:29:23 [debug] 45139#0: timer delta: 3 2010/11/16 16:29:23 [debug] 45139#0: posted events 286980B4 2010/11/16 16:29:23 [debug] 45139#0: posted event 286980B4 2010/11/16 16:29:23 [debug] 45139#0: *1 delete posted event 286980B4 2010/11/16 16:29:23 [debug] 45139#0: *1 http upstream request: "/login?" 2010/11/16 16:29:23 [debug] 45139#0: *1 http upstream process header 2010/11/16 16:29:23 [debug] 45139#0: *1 malloc: 2861F000:4096 2010/11/16 16:29:23 [debug] 45139#0: *1 recv: eof:1, avail:1345, err:0 2010/11/16 16:29:23 [debug] 45139#0: *1 recv: fd:12 1345 of 4096 2010/11/16 16:29:23 [debug] 45139#0: *1 http proxy status 200 "200 OK" 2010/11/16 16:29:23 [debug] 45139#0: *1 http proxy header: "Set-Cookie: JSESSIONID=AB29799B13480078EF36C03FEEB0CCC1; Path=/" 2010/11/16 16:29:23 [debug] 45139#0: *1 http proxy header: "Content-Type: text/html;charset=UTF-8" 2010/11/16 16:29:23 [debug] 45139#0: *1 http proxy header: "Content-Length: 1128" 2010/11/16 16:29:23 [debug] 45139#0: *1 http proxy header: "Date: Tue, 16 Nov 2010 08:29:23 GMT" 2010/11/16 16:29:23 [debug] 45139#0: *1 http proxy header: "Connection: close" 2010/11/16 16:29:23 [debug] 45139#0: *1 http proxy header: "Server: Apache" 2010/11/16 16:29:23 [debug] 45139#0: *1 http proxy header done 2010/11/16 16:29:23 [debug] 45139#0: *1 HTTP/1.1 200 OK Server: nginx Date: Tue, 16 Nov 2010 08:29:23 GMT Content-Type: text/html;charset=UTF-8 Transfer-Encoding: chunked Connection: keep-alive Vary: Accept-Encoding Content-Encoding: gzip 2010/11/16 16:29:23 [debug] 45139#0: *1 write new buf t:1 f:0 28612CB8, pos 28612CB8, size: 209 file: 0, size: 0 2010/11/16 16:29:23 [debug] 45139#0: *1 http write filter: l:0 f:0 s:209 2010/11/16 16:29:23 [debug] 45139#0: *1 http cacheable: 0 2010/11/16 16:29:23 [debug] 45139#0: *1 http upstream process upstream 2010/11/16 16:29:23 [debug] 45139#0: *1 pipe read upstream: 1 2010/11/16 16:29:23 [debug] 45139#0: *1 pipe preread: 1128 2010/11/16 16:29:23 [debug] 45139#0: *1 pipe buf free s:0 t:1 f:0 2861F000, pos 2861F0D9, size: 1128 file: 0, size: 0 2010/11/16 16:29:23 [debug] 45139#0: *1 input buf #0 2010/11/16 16:29:23 [debug] 45139#0: *1 pipe write downstream: 1 2010/11/16 16:29:23 [debug] 45139#0: *1 pipe write downstream flush in 2010/11/16 16:29:23 [debug] 45139#0: *1 http output filter "/login?" 2010/11/16 16:29:23 [debug] 45139#0: *1 http copy filter: "/login?" 2010/11/16 16:29:23 [debug] 45139#0: *1 http postpone filter "/login?" 28612E74 2010/11/16 16:29:23 [debug] 45139#0: *1 http gzip filter 2010/11/16 16:29:23 [debug] 45139#0: *1 malloc: 286BF000:24576 2010/11/16 16:29:23 [debug] 45139#0: *1 gzip alloc: n:1 s:5824 a:8192 p:286BF000 2010/11/16 16:29:23 [debug] 45139#0: *1 gzip alloc: n:2048 s:2 a:4096 p:286C1000 2010/11/16 16:29:23 [debug] 45139#0: *1 gzip alloc: n:2048 s:2 a:4096 p:286C2000 2010/11/16 16:29:23 [debug] 45139#0: *1 gzip alloc: n:2048 s:2 a:4096 p:286C3000 2010/11/16 16:29:23 [debug] 45139#0: *1 gzip alloc: n:1024 s:4 a:4096 p:286C4000 2010/11/16 16:29:23 [debug] 45139#0: *1 gzip in: 28612EC0 2010/11/16 16:29:23 [debug] 45139#0: *1 gzip in_buf:28612E40 ni:2861F0D9 ai:1128 2010/11/16 16:29:23 [debug] 45139#0: *1 malloc: 2864B000:4096 2010/11/16 16:29:23 [debug] 45139#0: *1 deflate in: ni:2861F0D9 no:2864B000 ai:1128 ao:4096 fl:0 redo:0 2010/11/16 16:29:23 [debug] 45139#0: *1 deflate out: ni:2861F541 no:2864B000 ai:0 ao:4096 rc:0 2010/11/16 16:29:23 [debug] 45139#0: *1 gzip in_buf:28612E40 pos:2861F0D9 2010/11/16 16:29:23 [debug] 45139#0: *1 gzip in: 00000000 2010/11/16 16:29:23 [debug] 45139#0: *1 http copy filter: 0 "/login?" 2010/11/16 16:29:23 [debug] 45139#0: *1 pipe write downstream done 2010/11/16 16:29:23 [debug] 45139#0: *1 event timer: 12, old: 1406034641, new: 1406034645 2010/11/16 16:29:23 [debug] 45139#0: *1 http upstream exit: 00000000 2010/11/16 16:29:23 [debug] 45139#0: *1 finalize http upstream request: 0 2010/11/16 16:29:23 [debug] 45139#0: *1 finalize http proxy request 2010/11/16 16:29:23 [debug] 45139#0: *1 free rr peer 1 0 2010/11/16 16:29:23 [debug] 45139#0: *1 close http upstream connection: 12 2010/11/16 16:29:23 [debug] 45139#0: *1 event timer del: 12: 1406034641 2010/11/16 16:29:23 [debug] 45139#0: *1 http upstream temp fd: -1 2010/11/16 16:29:23 [debug] 45139#0: *1 http output filter "/login?" 2010/11/16 16:29:23 [debug] 45139#0: *1 http copy filter: "/login?" 2010/11/16 16:29:23 [debug] 45139#0: *1 http postpone filter "/login?" BFBFE7FC 2010/11/16 16:29:23 [debug] 45139#0: *1 http gzip filter 2010/11/16 16:29:23 [debug] 45139#0: *1 gzip in: 28612F38 2010/11/16 16:29:23 [debug] 45139#0: *1 gzip in_buf:28612F04 ni:00000000 ai:0 2010/11/16 16:29:23 [debug] 45139#0: *1 deflate in: ni:00000000 no:2864B000 ai:0 ao:4096 fl:4 redo:0 2010/11/16 16:29:23 [debug] 45139#0: *1 deflate out: ni:00000000 no:2864B22A ai:0 ao:3542 rc:1 2010/11/16 16:29:23 [debug] 45139#0: *1 gzip in_buf:28612F04 pos:00000000 2010/11/16 16:29:23 [debug] 45139#0: *1 free: 286BF000 2010/11/16 16:29:23 [debug] 45139#0: *1 http chunk: 10 2010/11/16 16:29:23 [debug] 45139#0: *1 http chunk: 562 2010/11/16 16:29:23 [debug] 45139#0: *1 posix_memalign: 286BF000:4096 @16 2010/11/16 16:29:23 [debug] 45139#0: *1 write old buf t:1 f:0 28612CB8, pos 28612CB8, size: 209 file: 0, size: 0 2010/11/16 16:29:23 [debug] 45139#0: *1 write new buf t:1 f:0 00000000, pos 28612FC8, size: 5 file: 0, size: 0 2010/11/16 16:29:23 [debug] 45139#0: *1 write new buf t:0 f:0 00000000, pos 080EFCCC, size: 10 file: 0, size: 0 2010/11/16 16:29:23 [debug] 45139#0: *1 write new buf t:1 f:0 2864B000, pos 2864B000, size: 562 file: 0, size: 0 2010/11/16 16:29:23 [debug] 45139#0: *1 write new buf t:0 f:0 00000000, pos 080E3CFC, size: 7 file: 0, size: 0 2010/11/16 16:29:23 [debug] 45139#0: *1 http write filter: l:1 f:1 s:793 2010/11/16 16:29:23 [debug] 45139#0: *1 http write filter limit 0 2010/11/16 16:29:23 [debug] 45139#0: *1 writev: 793 of 793 2010/11/16 16:29:23 [debug] 45139#0: *1 http write filter 00000000 2010/11/16 16:29:23 [debug] 45139#0: *1 http copy filter: 0 "/login?" 2010/11/16 16:29:23 [debug] 45139#0: *1 http finalize request: 0, "/login?" a:1, c:1 2010/11/16 16:29:23 [debug] 45139#0: *1 set http keepalive handler 2010/11/16 16:29:23 [debug] 45139#0: *1 http close request 2010/11/16 16:29:23 [debug] 45139#0: *1 http log handler 2010/11/16 16:29:23 [debug] 45139#0: *1 free: 2864B000 2010/11/16 16:29:23 [debug] 45139#0: *1 free: 00000000 2010/11/16 16:29:23 [debug] 45139#0: *1 free: 2861F000 2010/11/16 16:29:23 [debug] 45139#0: *1 free: 28612000, unused: 4 2010/11/16 16:29:23 [debug] 45139#0: *1 free: 286BF000, unused: 3851 2010/11/16 16:29:23 [debug] 45139#0: *1 event timer add: 11: 65000:1406039645 2010/11/16 16:29:23 [debug] 45139#0: *1 free: 286B6300 2010/11/16 16:29:23 [debug] 45139#0: *1 free: 28628400 2010/11/16 16:29:23 [debug] 45139#0: *1 hc free: 00000000 0 2010/11/16 16:29:23 [debug] 45139#0: *1 hc busy: 00000000 0 2010/11/16 16:29:23 [debug] 45139#0: *1 tcp_nodelay 2010/11/16 16:29:23 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:23 [debug] 45139#0: worker cycle 2010/11/16 16:29:23 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:23 [debug] 45139#0: kevent timer: 65000, changes: 0 2010/11/16 16:29:23 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:23 [debug] 45139#0: kevent: 11: ft:-2 fl:0020 ff:00000000 d:33696 ud:286A7078 2010/11/16 16:29:23 [debug] 45139#0: *1 post event 286A7078 2010/11/16 16:29:23 [debug] 45139#0: timer delta: 8 2010/11/16 16:29:23 [debug] 45139#0: posted events 286A7078 2010/11/16 16:29:23 [debug] 45139#0: posted event 286A7078 2010/11/16 16:29:23 [debug] 45139#0: *1 delete posted event 286A7078 2010/11/16 16:29:23 [debug] 45139#0: *1 http empty handler 2010/11/16 16:29:23 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:23 [debug] 45139#0: worker cycle 2010/11/16 16:29:23 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:23 [debug] 45139#0: kevent timer: 64992, changes: 0 2010/11/16 16:29:23 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:23 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:23 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:23 [debug] 45138#0: worker cycle 2010/11/16 16:29:23 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:23 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:24 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:24 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:24 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:24 [debug] 45138#0: worker cycle 2010/11/16 16:29:24 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:24 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:24 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:24 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:24 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:24 [debug] 45138#0: worker cycle 2010/11/16 16:29:24 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:24 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:25 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:25 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:25 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:25 [debug] 45138#0: worker cycle 2010/11/16 16:29:25 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:25 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:25 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:25 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:25 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:25 [debug] 45138#0: worker cycle 2010/11/16 16:29:25 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:25 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:26 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:26 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:26 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:26 [debug] 45138#0: worker cycle 2010/11/16 16:29:26 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:26 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:26 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:26 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:26 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:26 [debug] 45138#0: worker cycle 2010/11/16 16:29:26 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:26 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:27 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:27 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:27 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:27 [debug] 45138#0: worker cycle 2010/11/16 16:29:27 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:27 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:27 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:27 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:27 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:27 [debug] 45138#0: worker cycle 2010/11/16 16:29:27 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:27 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:28 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:28 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:28 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:28 [debug] 45138#0: worker cycle 2010/11/16 16:29:28 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:28 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:28 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:28 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:28 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:28 [debug] 45138#0: worker cycle 2010/11/16 16:29:28 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:28 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:28 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:28 [debug] 45139#0: kevent: 11: ft:-1 fl:0020 ff:00000000 d:557 ud:28698078 2010/11/16 16:29:28 [debug] 45139#0: *1 post event 28698078 2010/11/16 16:29:28 [debug] 45139#0: timer delta: 5268 2010/11/16 16:29:28 [debug] 45139#0: posted events 28698078 2010/11/16 16:29:28 [debug] 45139#0: posted event 28698078 2010/11/16 16:29:28 [debug] 45139#0: *1 delete posted event 28698078 2010/11/16 16:29:28 [debug] 45139#0: *1 http keepalive handler 2010/11/16 16:29:28 [debug] 45139#0: *1 malloc: 28628400:1024 2010/11/16 16:29:28 [debug] 45139#0: *1 recv: eof:0, avail:557, err:0 2010/11/16 16:29:28 [debug] 45139#0: *1 recv: fd:11 557 of 1024 2010/11/16 16:29:28 [debug] 45139#0: *1 malloc: 286B6300:656 2010/11/16 16:29:28 [debug] 45139#0: *1 posix_memalign: 28612000:4096 @16 2010/11/16 16:29:28 [debug] 45139#0: *1 http process request line 2010/11/16 16:29:28 [debug] 45139#0: *1 http request line: "POST /controller HTTP/1.1" 2010/11/16 16:29:28 [debug] 45139#0: *1 http uri: "/controller" 2010/11/16 16:29:28 [debug] 45139#0: *1 http args: "" 2010/11/16 16:29:28 [debug] 45139#0: *1 http exten: "" 2010/11/16 16:29:28 [debug] 45139#0: *1 http process request header line 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Host: sei.irk.ru" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Accept: */*" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Accept-Encoding: gzip,deflate" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Keep-Alive: 115" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Connection: keep-alive" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Content-Type: application/x-www-form-urlencoded; charset=UTF-8" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "X-Requested-With: XMLHttpRequest" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Referer: http://sei.irk.ru/login"; 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Content-Length: 47" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Pragma: no-cache" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Cache-Control: no-cache" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header done 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer del: 11: 1406039645 2010/11/16 16:29:28 [debug] 45139#0: *1 rewrite phase: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: "/" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: "index.jsp" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: "5xx.html" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/tel" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/mail" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/manager" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/admin" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/chronicles" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/symp2010" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "^/(aec|articles|diser|history|ieee_pes_russiancapter|ieee_pes_wg_asia_australia|ircei|les|library|powerdiscussion|ppt|rfbg|rfn|sei34|semtps)" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "^/manager.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "^/admin.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "^.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "^.+\.(pdf|doc|txt)$" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "^.+\.(js|css)$" 2010/11/16 16:29:28 [debug] 45139#0: *1 using configuration "/" 2010/11/16 16:29:28 [debug] 45139#0: *1 http cl:47 max:157286400 2010/11/16 16:29:28 [debug] 45139#0: *1 rewrite phase: 2 2010/11/16 16:29:28 [debug] 45139#0: *1 post rewrite phase: 3 2010/11/16 16:29:28 [debug] 45139#0: *1 generic phase: 4 2010/11/16 16:29:28 [debug] 45139#0: *1 generic phase: 5 2010/11/16 16:29:28 [debug] 45139#0: *1 access phase: 6 2010/11/16 16:29:28 [debug] 45139#0: *1 access phase: 7 2010/11/16 16:29:28 [debug] 45139#0: *1 post access phase: 8 2010/11/16 16:29:28 [debug] 45139#0: *1 try files phase: 9 2010/11/16 16:29:28 [debug] 45139#0: *1 http client request body preread 47 2010/11/16 16:29:28 [debug] 45139#0: *1 http init upstream, client timer: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: "Host: " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script var: "sei.irk.ru" 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: "X-Real-IP: " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script var: "84.237.22.11" 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: "X-Forwarded-For: " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script var: "84.237.22.11" 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: "Connection: close " 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Accept: */*" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Accept-Encoding: gzip,deflate" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Content-Type: application/x-www-form-urlencoded; charset=UTF-8" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "X-Requested-With: XMLHttpRequest" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Referer: http://sei.irk.ru/login"; 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Content-Length: 47" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Pragma: no-cache" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Cache-Control: no-cache" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "POST /controller HTTP/1.0 Host: sei.irk.ru X-Real-IP: 84.237.22.11 X-Forwarded-For: 84.237.22.11 Connection: close User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3 Accept: */* Accept-Language: ru,en-us;q=0.7,en;q=0.3 Accept-Encoding: gzip,deflate Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7 Content-Type: application/x-www-form-urlencoded; charset=UTF-8 X-Requested-With: XMLHttpRequest Referer: http://sei.irk.ru/login Content-Length: 47 Pragma: no-cache Cache-Control: no-cache " 2010/11/16 16:29:28 [debug] 45139#0: *1 http cleanup add: 28612B58 2010/11/16 16:29:28 [debug] 45139#0: *1 get rr peer, try: 1 2010/11/16 16:29:28 [debug] 45139#0: *1 socket 12 2010/11/16 16:29:28 [debug] 45139#0: *1 connect to 127.0.0.1:8180, fd:12 #3 2010/11/16 16:29:28 [debug] 45139#0: *1 kevent set event: 12: ft:-1 fl:0025 2010/11/16 16:29:28 [debug] 45139#0: *1 connected 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream connect: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream send request 2010/11/16 16:29:28 [debug] 45139#0: *1 chain writer buf fl:0 s:544 2010/11/16 16:29:28 [debug] 45139#0: *1 chain writer buf fl:1 s:47 2010/11/16 16:29:28 [debug] 45139#0: *1 chain writer in: 28612B84 2010/11/16 16:29:28 [debug] 45139#0: *1 writev: 591 of 591 2010/11/16 16:29:28 [debug] 45139#0: *1 chain writer out: 00000000 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer add: 12: 60000:1406039921 2010/11/16 16:29:28 [debug] 45139#0: *1 http finalize request: -4, "/controller?" a:1, c:2 2010/11/16 16:29:28 [debug] 45139#0: *1 http request count:2 blk:0 2010/11/16 16:29:28 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:28 [debug] 45139#0: worker cycle 2010/11/16 16:29:28 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:28 [debug] 45139#0: kevent timer: 60000, changes: 1 2010/11/16 16:29:28 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:28 [debug] 45139#0: kevent: 12: ft:-1 fl:8020 ff:00000000 d:235 ud:286980B5 2010/11/16 16:29:28 [debug] 45139#0: *1 post event 286980B4 2010/11/16 16:29:28 [debug] 45139#0: timer delta: 24 2010/11/16 16:29:28 [debug] 45139#0: posted events 286980B4 2010/11/16 16:29:28 [debug] 45139#0: posted event 286980B4 2010/11/16 16:29:28 [debug] 45139#0: *1 delete posted event 286980B4 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream request: "/controller?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream process header 2010/11/16 16:29:28 [debug] 45139#0: *1 malloc: 2861F000:4096 2010/11/16 16:29:28 [debug] 45139#0: *1 recv: eof:1, avail:235, err:0 2010/11/16 16:29:28 [debug] 45139#0: *1 recv: fd:12 235 of 4096 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy status 200 "200 OK" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Set-Cookie: JSESSIONID=BD957F1F40CB0A1C1D48B07FEB567658; Path=/" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Content-Length: 59" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Date: Tue, 16 Nov 2010 08:29:28 GMT" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Connection: close" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Server: Apache" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header done 2010/11/16 16:29:28 [debug] 45139#0: *1 HTTP/1.1 200 OK Server: nginx Date: Tue, 16 Nov 2010 08:29:28 GMT Connection: keep-alive Content-Length: 59 2010/11/16 16:29:28 [debug] 45139#0: *1 write new buf t:1 f:0 28612D58, pos 28612D58, size: 115 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http write filter: l:0 f:0 s:115 2010/11/16 16:29:28 [debug] 45139#0: *1 http cacheable: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream process upstream 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe read upstream: 1 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe preread: 59 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe buf free s:0 t:1 f:0 2861F000, pos 2861F0B0, size: 59 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 input buf #0 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe write downstream: 1 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe write downstream flush in 2010/11/16 16:29:28 [debug] 45139#0: *1 http output filter "/controller?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http copy filter: "/controller?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http postpone filter "/controller?" 28612EB4 2010/11/16 16:29:28 [debug] 45139#0: *1 write old buf t:1 f:0 28612D58, pos 28612D58, size: 115 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 write new buf t:1 f:0 2861F000, pos 2861F0B0, size: 59 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http write filter: l:0 f:0 s:174 2010/11/16 16:29:28 [debug] 45139#0: *1 http copy filter: 0 "/controller?" 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe write downstream done 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer: 12, old: 1406039921, new: 1406039945 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream exit: 00000000 2010/11/16 16:29:28 [debug] 45139#0: *1 finalize http upstream request: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 finalize http proxy request 2010/11/16 16:29:28 [debug] 45139#0: *1 free rr peer 1 0 2010/11/16 16:29:28 [debug] 45139#0: *1 close http upstream connection: 12 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer del: 12: 1406039921 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream temp fd: -1 2010/11/16 16:29:28 [debug] 45139#0: *1 http output filter "/controller?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http copy filter: "/controller?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http postpone filter "/controller?" BFBFE7FC 2010/11/16 16:29:28 [debug] 45139#0: *1 write old buf t:1 f:0 28612D58, pos 28612D58, size: 115 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 write old buf t:1 f:0 2861F000, pos 2861F0B0, size: 59 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 write new buf t:0 f:0 00000000, pos 00000000, size: 0 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http write filter: l:1 f:0 s:174 2010/11/16 16:29:28 [debug] 45139#0: *1 http write filter limit 0 2010/11/16 16:29:28 [debug] 45139#0: *1 writev: 174 of 174 2010/11/16 16:29:28 [debug] 45139#0: *1 http write filter 00000000 2010/11/16 16:29:28 [debug] 45139#0: *1 http copy filter: 0 "/controller?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http finalize request: 0, "/controller?" a:1, c:1 2010/11/16 16:29:28 [debug] 45139#0: *1 set http keepalive handler 2010/11/16 16:29:28 [debug] 45139#0: *1 http close request 2010/11/16 16:29:28 [debug] 45139#0: *1 http log handler 2010/11/16 16:29:28 [debug] 45139#0: *1 posix_memalign: 2864B000:4096 @16 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 2861F000 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 28612000, unused: 196 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 2864B000, unused: 3875 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer add: 11: 65000:1406044945 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 286B6300 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 28628400 2010/11/16 16:29:28 [debug] 45139#0: *1 hc free: 00000000 0 2010/11/16 16:29:28 [debug] 45139#0: *1 hc busy: 00000000 0 2010/11/16 16:29:28 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:28 [debug] 45139#0: worker cycle 2010/11/16 16:29:28 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:28 [debug] 45139#0: kevent timer: 65000, changes: 0 2010/11/16 16:29:28 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:28 [debug] 45139#0: kevent: 11: ft:-2 fl:0020 ff:00000000 d:33696 ud:286A7078 2010/11/16 16:29:28 [debug] 45139#0: *1 post event 286A7078 2010/11/16 16:29:28 [debug] 45139#0: timer delta: 8 2010/11/16 16:29:28 [debug] 45139#0: posted events 286A7078 2010/11/16 16:29:28 [debug] 45139#0: posted event 286A7078 2010/11/16 16:29:28 [debug] 45139#0: *1 delete posted event 286A7078 2010/11/16 16:29:28 [debug] 45139#0: *1 http empty handler 2010/11/16 16:29:28 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:28 [debug] 45139#0: worker cycle 2010/11/16 16:29:28 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:28 [debug] 45139#0: kevent timer: 64992, changes: 0 2010/11/16 16:29:28 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:28 [debug] 45139#0: kevent: 11: ft:-1 fl:0020 ff:00000000 d:409 ud:28698078 2010/11/16 16:29:28 [debug] 45139#0: *1 post event 28698078 2010/11/16 16:29:28 [debug] 45139#0: timer delta: 16 2010/11/16 16:29:28 [debug] 45139#0: posted events 28698078 2010/11/16 16:29:28 [debug] 45139#0: posted event 28698078 2010/11/16 16:29:28 [debug] 45139#0: *1 delete posted event 28698078 2010/11/16 16:29:28 [debug] 45139#0: *1 http keepalive handler 2010/11/16 16:29:28 [debug] 45139#0: *1 malloc: 28628400:1024 2010/11/16 16:29:28 [debug] 45139#0: *1 recv: eof:0, avail:409, err:0 2010/11/16 16:29:28 [debug] 45139#0: *1 recv: fd:11 409 of 1024 2010/11/16 16:29:28 [debug] 45139#0: *1 malloc: 286B6300:656 2010/11/16 16:29:28 [debug] 45139#0: *1 posix_memalign: 28612000:4096 @16 2010/11/16 16:29:28 [debug] 45139#0: *1 http process request line 2010/11/16 16:29:28 [debug] 45139#0: *1 http request line: "GET /admin_panel HTTP/1.1" 2010/11/16 16:29:28 [debug] 45139#0: *1 http uri: "/admin_panel" 2010/11/16 16:29:28 [debug] 45139#0: *1 http args: "" 2010/11/16 16:29:28 [debug] 45139#0: *1 http exten: "" 2010/11/16 16:29:28 [debug] 45139#0: *1 http process request header line 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Host: sei.irk.ru" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Accept-Encoding: gzip,deflate" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Keep-Alive: 115" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Connection: keep-alive" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Referer: http://sei.irk.ru/login"; 2010/11/16 16:29:28 [debug] 45139#0: *1 http header done 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer del: 11: 1406044945 2010/11/16 16:29:28 [debug] 45139#0: *1 rewrite phase: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: "/" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: "index.jsp" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: "5xx.html" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/tel" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/mail" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/manager" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/admin" 2010/11/16 16:29:28 [debug] 45139#0: *1 using configuration "/admin" 2010/11/16 16:29:28 [debug] 45139#0: *1 http cl:-1 max:1048576 2010/11/16 16:29:28 [debug] 45139#0: *1 rewrite phase: 2 2010/11/16 16:29:28 [debug] 45139#0: *1 post rewrite phase: 3 2010/11/16 16:29:28 [debug] 45139#0: *1 generic phase: 4 2010/11/16 16:29:28 [debug] 45139#0: *1 generic phase: 5 2010/11/16 16:29:28 [debug] 45139#0: *1 access phase: 6 2010/11/16 16:29:28 [debug] 45139#0: *1 access phase: 7 2010/11/16 16:29:28 [debug] 45139#0: *1 post access phase: 8 2010/11/16 16:29:28 [debug] 45139#0: *1 try files phase: 9 2010/11/16 16:29:28 [debug] 45139#0: *1 http init upstream, client timer: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: "Host: " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script var: "sei.irk.ru" 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: "X-Real-IP: " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script var: "84.237.22.11" 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: "X-Forwarded-For: " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script var: "84.237.22.11" 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: "Connection: close " 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Accept-Encoding: gzip,deflate" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Referer: http://sei.irk.ru/login"; 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "GET /admin_panel HTTP/1.0 Host: sei.irk.ru X-Real-IP: 84.237.22.11 X-Forwarded-For: 84.237.22.11 Connection: close User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3 Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8 Accept-Language: ru,en-us;q=0.7,en;q=0.3 Accept-Encoding: gzip,deflate Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7 Referer: http://sei.irk.ru/login " 2010/11/16 16:29:28 [debug] 45139#0: *1 http cleanup add: 28612A24 2010/11/16 16:29:28 [debug] 45139#0: *1 get rr peer, try: 1 2010/11/16 16:29:28 [debug] 45139#0: *1 socket 12 2010/11/16 16:29:28 [debug] 45139#0: *1 connect to 127.0.0.1:8180, fd:12 #4 2010/11/16 16:29:28 [debug] 45139#0: *1 kevent set event: 12: ft:-1 fl:0025 2010/11/16 16:29:28 [debug] 45139#0: *1 connected 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream connect: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream send request 2010/11/16 16:29:28 [debug] 45139#0: *1 chain writer buf fl:1 s:443 2010/11/16 16:29:28 [debug] 45139#0: *1 chain writer in: 28612A40 2010/11/16 16:29:28 [debug] 45139#0: *1 writev: 443 of 443 2010/11/16 16:29:28 [debug] 45139#0: *1 chain writer out: 00000000 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer add: 12: 60000:1406039969 2010/11/16 16:29:28 [debug] 45139#0: *1 http finalize request: -4, "/admin_panel?" a:1, c:2 2010/11/16 16:29:28 [debug] 45139#0: *1 http request count:2 blk:0 2010/11/16 16:29:28 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:28 [debug] 45139#0: worker cycle 2010/11/16 16:29:28 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:28 [debug] 45139#0: kevent timer: 60000, changes: 1 2010/11/16 16:29:28 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:28 [debug] 45139#0: kevent: 12: ft:-1 fl:8020 ff:00000000 d:264 ud:286980B4 2010/11/16 16:29:28 [debug] 45139#0: *1 post event 286980B4 2010/11/16 16:29:28 [debug] 45139#0: timer delta: 3 2010/11/16 16:29:28 [debug] 45139#0: posted events 286980B4 2010/11/16 16:29:28 [debug] 45139#0: posted event 286980B4 2010/11/16 16:29:28 [debug] 45139#0: *1 delete posted event 286980B4 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream request: "/admin_panel?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream process header 2010/11/16 16:29:28 [debug] 45139#0: *1 malloc: 2861F000:4096 2010/11/16 16:29:28 [debug] 45139#0: *1 recv: eof:1, avail:264, err:0 2010/11/16 16:29:28 [debug] 45139#0: *1 recv: fd:12 264 of 4096 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy status 302 "302 Moved Temporarily" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Set-Cookie: JSESSIONID=60E12512E766D3C8820E4A6272B69F6E; Path=/" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Location: http://sei.irk.ru/login"; 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Content-Type: text/html;charset=UTF-8" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Content-Length: 0" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Date: Tue, 16 Nov 2010 08:29:28 GMT" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Connection: close" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Server: Apache" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header done 2010/11/16 16:29:28 [debug] 45139#0: *1 HTTP/1.1 302 Moved Temporarily Server: nginx Date: Tue, 16 Nov 2010 08:29:28 GMT Content-Type: text/html;charset=UTF-8 Connection: keep-alive Set-Cookie: JSESSIONID=60E12512E766D3C8820E4A6272B69F6E; Path=/ Location: http://sei.irk.ru/login Content-Length: 0 Expires: Tue, 16 Nov 2010 08:30:28 GMT Cache-Control: max-age=60 2010/11/16 16:29:28 [debug] 45139#0: *1 write new buf t:1 f:0 28612CAC, pos 28612CAC, size: 335 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http write filter: l:0 f:0 s:335 2010/11/16 16:29:28 [debug] 45139#0: *1 http cacheable: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream process upstream 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe read upstream: 1 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe preread: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe buf free s:0 t:1 f:0 2861F000, pos 2861F108, size: 0 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe write downstream: 1 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe write downstream done 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer: 12, old: 1406039969, new: 1406039972 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream exit: 00000000 2010/11/16 16:29:28 [debug] 45139#0: *1 finalize http upstream request: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 finalize http proxy request 2010/11/16 16:29:28 [debug] 45139#0: *1 free rr peer 1 0 2010/11/16 16:29:28 [debug] 45139#0: *1 close http upstream connection: 12 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer del: 12: 1406039969 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream temp fd: -1 2010/11/16 16:29:28 [debug] 45139#0: *1 http output filter "/admin_panel?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http copy filter: "/admin_panel?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http postpone filter "/admin_panel?" BFBFE7FC 2010/11/16 16:29:28 [debug] 45139#0: *1 write old buf t:1 f:0 28612CAC, pos 28612CAC, size: 335 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 write new buf t:0 f:0 00000000, pos 00000000, size: 0 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http write filter: l:1 f:0 s:335 2010/11/16 16:29:28 [debug] 45139#0: *1 http write filter limit 0 2010/11/16 16:29:28 [debug] 45139#0: *1 writev: 335 of 335 2010/11/16 16:29:28 [debug] 45139#0: *1 http write filter 00000000 2010/11/16 16:29:28 [debug] 45139#0: *1 http copy filter: 0 "/admin_panel?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http finalize request: 0, "/admin_panel?" a:1, c:1 2010/11/16 16:29:28 [debug] 45139#0: *1 set http keepalive handler 2010/11/16 16:29:28 [debug] 45139#0: *1 http close request 2010/11/16 16:29:28 [debug] 45139#0: *1 http log handler 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 2861F000 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 28612000, unused: 11 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer add: 11: 65000:1406044972 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 286B6300 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 28628400 2010/11/16 16:29:28 [debug] 45139#0: *1 hc free: 00000000 0 2010/11/16 16:29:28 [debug] 45139#0: *1 hc busy: 00000000 0 2010/11/16 16:29:28 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:28 [debug] 45139#0: worker cycle 2010/11/16 16:29:28 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:28 [debug] 45139#0: kevent timer: 65000, changes: 0 2010/11/16 16:29:28 [debug] 45139#0: kevent events: 2 2010/11/16 16:29:28 [debug] 45139#0: kevent: 11: ft:-2 fl:0020 ff:00000000 d:33696 ud:286A7078 2010/11/16 16:29:28 [debug] 45139#0: *1 post event 286A7078 2010/11/16 16:29:28 [debug] 45139#0: kevent: 11: ft:-1 fl:0020 ff:00000000 d:456 ud:28698078 2010/11/16 16:29:28 [debug] 45139#0: *1 post event 28698078 2010/11/16 16:29:28 [debug] 45139#0: timer delta: 14 2010/11/16 16:29:28 [debug] 45139#0: posted events 28698078 2010/11/16 16:29:28 [debug] 45139#0: posted event 28698078 2010/11/16 16:29:28 [debug] 45139#0: *1 delete posted event 28698078 2010/11/16 16:29:28 [debug] 45139#0: *1 http keepalive handler 2010/11/16 16:29:28 [debug] 45139#0: *1 malloc: 28628400:1024 2010/11/16 16:29:28 [debug] 45139#0: *1 recv: eof:0, avail:456, err:0 2010/11/16 16:29:28 [debug] 45139#0: *1 recv: fd:11 456 of 1024 2010/11/16 16:29:28 [debug] 45139#0: *1 malloc: 286B6300:656 2010/11/16 16:29:28 [debug] 45139#0: *1 posix_memalign: 28612000:4096 @16 2010/11/16 16:29:28 [debug] 45139#0: *1 http process request line 2010/11/16 16:29:28 [debug] 45139#0: *1 http request line: "GET /login HTTP/1.1" 2010/11/16 16:29:28 [debug] 45139#0: *1 http uri: "/login" 2010/11/16 16:29:28 [debug] 45139#0: *1 http args: "" 2010/11/16 16:29:28 [debug] 45139#0: *1 http exten: "" 2010/11/16 16:29:28 [debug] 45139#0: *1 http process request header line 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Host: sei.irk.ru" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Accept-Encoding: gzip,deflate" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Keep-Alive: 115" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Connection: keep-alive" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Referer: http://sei.irk.ru/login"; 2010/11/16 16:29:28 [debug] 45139#0: *1 http header: "Cookie: JSESSIONID=60E12512E766D3C8820E4A6272B69F6E" 2010/11/16 16:29:28 [debug] 45139#0: *1 http header done 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer del: 11: 1406044972 2010/11/16 16:29:28 [debug] 45139#0: *1 rewrite phase: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: "/" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: "index.jsp" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: "publications.jsp" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/tel" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/mail" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/manager" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/admin" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/chronicles" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "/symp2010" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "^/(aec|articles|diser|history|ieee_pes_russiancapter|ieee_pes_wg_asia_australia|ircei|les|library|powerdiscussion|ppt|rfbg|rfn|sei34|semtps)" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "^/manager.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "^/admin.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "^.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "^.+\.(pdf|doc|txt)$" 2010/11/16 16:29:28 [debug] 45139#0: *1 test location: ~ "^.+\.(js|css)$" 2010/11/16 16:29:28 [debug] 45139#0: *1 using configuration "/" 2010/11/16 16:29:28 [debug] 45139#0: *1 http cl:-1 max:157286400 2010/11/16 16:29:28 [debug] 45139#0: *1 rewrite phase: 2 2010/11/16 16:29:28 [debug] 45139#0: *1 post rewrite phase: 3 2010/11/16 16:29:28 [debug] 45139#0: *1 generic phase: 4 2010/11/16 16:29:28 [debug] 45139#0: *1 generic phase: 5 2010/11/16 16:29:28 [debug] 45139#0: *1 access phase: 6 2010/11/16 16:29:28 [debug] 45139#0: *1 access phase: 7 2010/11/16 16:29:28 [debug] 45139#0: *1 post access phase: 8 2010/11/16 16:29:28 [debug] 45139#0: *1 try files phase: 9 2010/11/16 16:29:28 [debug] 45139#0: *1 http init upstream, client timer: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: "Host: " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script var: "sei.irk.ru" 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: "X-Real-IP: " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script var: "84.237.22.11" 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: "X-Forwarded-For: " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script var: "84.237.22.11" 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:28 [debug] 45139#0: *1 http script copy: "Connection: close " 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Accept-Encoding: gzip,deflate" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Referer: http://sei.irk.ru/login"; 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Cookie: JSESSIONID=60E12512E766D3C8820E4A6272B69F6E" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "GET /login HTTP/1.0 Host: sei.irk.ru X-Real-IP: 84.237.22.11 X-Forwarded-For: 84.237.22.11 Connection: close User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3 Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8 Accept-Language: ru,en-us;q=0.7,en;q=0.3 Accept-Encoding: gzip,deflate Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7 Referer: http://sei.irk.ru/login Cookie: JSESSIONID=60E12512E766D3C8820E4A6272B69F6E " 2010/11/16 16:29:28 [debug] 45139#0: *1 http cleanup add: 28612A70 2010/11/16 16:29:28 [debug] 45139#0: *1 get rr peer, try: 1 2010/11/16 16:29:28 [debug] 45139#0: *1 socket 12 2010/11/16 16:29:28 [debug] 45139#0: *1 connect to 127.0.0.1:8180, fd:12 #5 2010/11/16 16:29:28 [debug] 45139#0: *1 kevent set event: 12: ft:-1 fl:0025 2010/11/16 16:29:28 [debug] 45139#0: *1 connected 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream connect: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream send request 2010/11/16 16:29:28 [debug] 45139#0: *1 chain writer buf fl:1 s:490 2010/11/16 16:29:28 [debug] 45139#0: *1 chain writer in: 28612A8C 2010/11/16 16:29:28 [debug] 45139#0: *1 writev: 490 of 490 2010/11/16 16:29:28 [debug] 45139#0: *1 chain writer out: 00000000 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer add: 12: 60000:1406039986 2010/11/16 16:29:28 [debug] 45139#0: *1 http finalize request: -4, "/login?" a:1, c:2 2010/11/16 16:29:28 [debug] 45139#0: *1 http request count:2 blk:0 2010/11/16 16:29:28 [debug] 45139#0: posted event 286A7078 2010/11/16 16:29:28 [debug] 45139#0: *1 delete posted event 286A7078 2010/11/16 16:29:28 [debug] 45139#0: *1 http run request: "/login?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream check client, write event:1, "/login" 2010/11/16 16:29:28 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:28 [debug] 45139#0: worker cycle 2010/11/16 16:29:28 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:28 [debug] 45139#0: kevent timer: 60000, changes: 1 2010/11/16 16:29:28 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:28 [debug] 45139#0: kevent: 12: ft:-1 fl:8020 ff:00000000 d:1280 ud:286980B5 2010/11/16 16:29:28 [debug] 45139#0: *1 post event 286980B4 2010/11/16 16:29:28 [debug] 45139#0: timer delta: 2 2010/11/16 16:29:28 [debug] 45139#0: posted events 286980B4 2010/11/16 16:29:28 [debug] 45139#0: posted event 286980B4 2010/11/16 16:29:28 [debug] 45139#0: *1 delete posted event 286980B4 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream request: "/login?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream process header 2010/11/16 16:29:28 [debug] 45139#0: *1 malloc: 2861F000:4096 2010/11/16 16:29:28 [debug] 45139#0: *1 recv: eof:1, avail:1280, err:0 2010/11/16 16:29:28 [debug] 45139#0: *1 recv: fd:12 1280 of 4096 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy status 200 "200 OK" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Content-Type: text/html;charset=UTF-8" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Content-Length: 1128" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Date: Tue, 16 Nov 2010 08:29:28 GMT" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Connection: close" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header: "Server: Apache" 2010/11/16 16:29:28 [debug] 45139#0: *1 http proxy header done 2010/11/16 16:29:28 [debug] 45139#0: *1 HTTP/1.1 200 OK Server: nginx Date: Tue, 16 Nov 2010 08:29:28 GMT Content-Type: text/html;charset=UTF-8 Transfer-Encoding: chunked Connection: keep-alive Vary: Accept-Encoding Content-Encoding: gzip 2010/11/16 16:29:28 [debug] 45139#0: *1 write new buf t:1 f:0 28612CD0, pos 28612CD0, size: 209 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http write filter: l:0 f:0 s:209 2010/11/16 16:29:28 [debug] 45139#0: *1 http cacheable: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream process upstream 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe read upstream: 1 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe preread: 1128 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe buf free s:0 t:1 f:0 2861F000, pos 2861F098, size: 1128 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 input buf #0 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe write downstream: 1 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe write downstream flush in 2010/11/16 16:29:28 [debug] 45139#0: *1 http output filter "/login?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http copy filter: "/login?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http postpone filter "/login?" 28612E8C 2010/11/16 16:29:28 [debug] 45139#0: *1 http gzip filter 2010/11/16 16:29:28 [debug] 45139#0: *1 malloc: 286BF000:24576 2010/11/16 16:29:28 [debug] 45139#0: *1 gzip alloc: n:1 s:5824 a:8192 p:286BF000 2010/11/16 16:29:28 [debug] 45139#0: *1 gzip alloc: n:2048 s:2 a:4096 p:286C1000 2010/11/16 16:29:28 [debug] 45139#0: *1 gzip alloc: n:2048 s:2 a:4096 p:286C2000 2010/11/16 16:29:28 [debug] 45139#0: *1 gzip alloc: n:2048 s:2 a:4096 p:286C3000 2010/11/16 16:29:28 [debug] 45139#0: *1 gzip alloc: n:1024 s:4 a:4096 p:286C4000 2010/11/16 16:29:28 [debug] 45139#0: *1 gzip in: 28612ED8 2010/11/16 16:29:28 [debug] 45139#0: *1 gzip in_buf:28612E58 ni:2861F098 ai:1128 2010/11/16 16:29:28 [debug] 45139#0: *1 malloc: 2864B000:4096 2010/11/16 16:29:28 [debug] 45139#0: *1 deflate in: ni:2861F098 no:2864B000 ai:1128 ao:4096 fl:0 redo:0 2010/11/16 16:29:28 [debug] 45139#0: *1 deflate out: ni:2861F500 no:2864B000 ai:0 ao:4096 rc:0 2010/11/16 16:29:28 [debug] 45139#0: *1 gzip in_buf:28612E58 pos:2861F098 2010/11/16 16:29:28 [debug] 45139#0: *1 gzip in: 00000000 2010/11/16 16:29:28 [debug] 45139#0: *1 http copy filter: 0 "/login?" 2010/11/16 16:29:28 [debug] 45139#0: *1 pipe write downstream done 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer: 12, old: 1406039986, new: 1406039988 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream exit: 00000000 2010/11/16 16:29:28 [debug] 45139#0: *1 finalize http upstream request: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 finalize http proxy request 2010/11/16 16:29:28 [debug] 45139#0: *1 free rr peer 1 0 2010/11/16 16:29:28 [debug] 45139#0: *1 close http upstream connection: 12 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer del: 12: 1406039986 2010/11/16 16:29:28 [debug] 45139#0: *1 http upstream temp fd: -1 2010/11/16 16:29:28 [debug] 45139#0: *1 http output filter "/login?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http copy filter: "/login?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http postpone filter "/login?" BFBFE7FC 2010/11/16 16:29:28 [debug] 45139#0: *1 http gzip filter 2010/11/16 16:29:28 [debug] 45139#0: *1 gzip in: 28612F50 2010/11/16 16:29:28 [debug] 45139#0: *1 gzip in_buf:28612F1C ni:00000000 ai:0 2010/11/16 16:29:28 [debug] 45139#0: *1 deflate in: ni:00000000 no:2864B000 ai:0 ao:4096 fl:4 redo:0 2010/11/16 16:29:28 [debug] 45139#0: *1 deflate out: ni:00000000 no:2864B22A ai:0 ao:3542 rc:1 2010/11/16 16:29:28 [debug] 45139#0: *1 gzip in_buf:28612F1C pos:00000000 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 286BF000 2010/11/16 16:29:28 [debug] 45139#0: *1 http chunk: 10 2010/11/16 16:29:28 [debug] 45139#0: *1 http chunk: 562 2010/11/16 16:29:28 [debug] 45139#0: *1 posix_memalign: 286BF000:4096 @16 2010/11/16 16:29:28 [debug] 45139#0: *1 write old buf t:1 f:0 28612CD0, pos 28612CD0, size: 209 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 write new buf t:1 f:0 00000000, pos 28612FE0, size: 5 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 write new buf t:0 f:0 00000000, pos 080EFCCC, size: 10 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 write new buf t:1 f:0 2864B000, pos 2864B000, size: 562 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 write new buf t:0 f:0 00000000, pos 080E3CFC, size: 7 file: 0, size: 0 2010/11/16 16:29:28 [debug] 45139#0: *1 http write filter: l:1 f:1 s:793 2010/11/16 16:29:28 [debug] 45139#0: *1 http write filter limit 0 2010/11/16 16:29:28 [debug] 45139#0: *1 writev: 793 of 793 2010/11/16 16:29:28 [debug] 45139#0: *1 http write filter 00000000 2010/11/16 16:29:28 [debug] 45139#0: *1 http copy filter: 0 "/login?" 2010/11/16 16:29:28 [debug] 45139#0: *1 http finalize request: 0, "/login?" a:1, c:1 2010/11/16 16:29:28 [debug] 45139#0: *1 set http keepalive handler 2010/11/16 16:29:28 [debug] 45139#0: *1 http close request 2010/11/16 16:29:28 [debug] 45139#0: *1 http log handler 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 2864B000 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 00000000 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 2861F000 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 28612000, unused: 4 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 286BF000, unused: 3805 2010/11/16 16:29:28 [debug] 45139#0: *1 event timer add: 11: 65000:1406044988 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 286B6300 2010/11/16 16:29:28 [debug] 45139#0: *1 free: 28628400 2010/11/16 16:29:28 [debug] 45139#0: *1 hc free: 00000000 0 2010/11/16 16:29:28 [debug] 45139#0: *1 hc busy: 00000000 0 2010/11/16 16:29:28 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:28 [debug] 45139#0: worker cycle 2010/11/16 16:29:28 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:28 [debug] 45139#0: kevent timer: 65000, changes: 0 2010/11/16 16:29:28 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:28 [debug] 45139#0: kevent: 11: ft:-2 fl:0020 ff:00000000 d:33696 ud:286A7078 2010/11/16 16:29:28 [debug] 45139#0: *1 post event 286A7078 2010/11/16 16:29:28 [debug] 45139#0: timer delta: 50 2010/11/16 16:29:28 [debug] 45139#0: posted events 286A7078 2010/11/16 16:29:28 [debug] 45139#0: posted event 286A7078 2010/11/16 16:29:28 [debug] 45139#0: *1 delete posted event 286A7078 2010/11/16 16:29:28 [debug] 45139#0: *1 http empty handler 2010/11/16 16:29:28 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:28 [debug] 45139#0: worker cycle 2010/11/16 16:29:28 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:28 [debug] 45139#0: kevent timer: 64950, changes: 0 2010/11/16 16:29:29 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:29 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:29 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:29 [debug] 45138#0: worker cycle 2010/11/16 16:29:29 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:29 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:29 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:29 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:29 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:29 [debug] 45138#0: worker cycle 2010/11/16 16:29:29 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:29 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:29 [debug] 45140#0: kevent events: 0 2010/11/16 16:29:29 [debug] 45140#0: timer delta: 10002 2010/11/16 16:29:29 [debug] 45140#0: event timer del: -1: 1405981107 2010/11/16 16:29:29 [debug] 45140#0: http file cache expire 2010/11/16 16:29:29 [debug] 45140#0: malloc: 28608400:55 2010/11/16 16:29:29 [debug] 45140#0: http file cache size: 0 2010/11/16 16:29:29 [debug] 45140#0: event timer add: -1: 10000:1405991109 2010/11/16 16:29:29 [debug] 45140#0: posted events 00000000 2010/11/16 16:29:29 [debug] 45140#0: kevent timer: 10000, changes: 0 2010/11/16 16:29:30 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:30 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:30 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:30 [debug] 45138#0: worker cycle 2010/11/16 16:29:30 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:30 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:30 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:30 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:30 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:30 [debug] 45138#0: worker cycle 2010/11/16 16:29:30 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:30 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:31 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:31 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:31 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:31 [debug] 45138#0: worker cycle 2010/11/16 16:29:31 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:31 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:31 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:31 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:31 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:31 [debug] 45138#0: worker cycle 2010/11/16 16:29:31 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:31 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:32 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:32 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:32 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:32 [debug] 45138#0: worker cycle 2010/11/16 16:29:32 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:32 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:32 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:32 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:32 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:32 [debug] 45138#0: worker cycle 2010/11/16 16:29:32 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:32 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:33 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:33 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:33 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:33 [debug] 45138#0: worker cycle 2010/11/16 16:29:33 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:33 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:33 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:33 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:33 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:33 [debug] 45138#0: worker cycle 2010/11/16 16:29:33 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:33 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:34 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:34 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:34 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:34 [debug] 45138#0: worker cycle 2010/11/16 16:29:34 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:34 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:34 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:34 [debug] 45139#0: kevent: 11: ft:-1 fl:0020 ff:00000000 d:610 ud:28698078 2010/11/16 16:29:34 [debug] 45139#0: *1 post event 28698078 2010/11/16 16:29:34 [debug] 45139#0: timer delta: 5260 2010/11/16 16:29:34 [debug] 45139#0: posted events 28698078 2010/11/16 16:29:34 [debug] 45139#0: posted event 28698078 2010/11/16 16:29:34 [debug] 45139#0: *1 delete posted event 28698078 2010/11/16 16:29:34 [debug] 45139#0: *1 http keepalive handler 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 28628400:1024 2010/11/16 16:29:34 [debug] 45139#0: *1 recv: eof:0, avail:610, err:0 2010/11/16 16:29:34 [debug] 45139#0: *1 recv: fd:11 610 of 1024 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 286B6300:656 2010/11/16 16:29:34 [debug] 45139#0: *1 posix_memalign: 28612000:4096 @16 2010/11/16 16:29:34 [debug] 45139#0: *1 http process request line 2010/11/16 16:29:34 [debug] 45139#0: *1 http request line: "POST /controller HTTP/1.1" 2010/11/16 16:29:34 [debug] 45139#0: *1 http uri: "/controller" 2010/11/16 16:29:34 [debug] 45139#0: *1 http args: "" 2010/11/16 16:29:34 [debug] 45139#0: *1 http exten: "" 2010/11/16 16:29:34 [debug] 45139#0: *1 http process request header line 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Host: sei.irk.ru" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Accept: */*" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Accept-Encoding: gzip,deflate" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Keep-Alive: 115" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Connection: keep-alive" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Content-Type: application/x-www-form-urlencoded; charset=UTF-8" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "X-Requested-With: XMLHttpRequest" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Referer: http://sei.irk.ru/login"; 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Content-Length: 47" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Cookie: JSESSIONID=60E12512E766D3C8820E4A6272B69F6E" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Pragma: no-cache" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Cache-Control: no-cache" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header done 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer del: 11: 1406044988 2010/11/16 16:29:34 [debug] 45139#0: *1 rewrite phase: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: "/" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: "index.jsp" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: "5xx.html" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/tel" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/mail" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/manager" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/admin" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/chronicles" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/symp2010" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "^/(aec|articles|diser|history|ieee_pes_russiancapter|ieee_pes_wg_asia_australia|ircei|les|library|powerdiscussion|ppt|rfbg|rfn|sei34|semtps)" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "^/manager.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "^/admin.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "^.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "^.+\.(pdf|doc|txt)$" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "^.+\.(js|css)$" 2010/11/16 16:29:34 [debug] 45139#0: *1 using configuration "/" 2010/11/16 16:29:34 [debug] 45139#0: *1 http cl:47 max:157286400 2010/11/16 16:29:34 [debug] 45139#0: *1 rewrite phase: 2 2010/11/16 16:29:34 [debug] 45139#0: *1 post rewrite phase: 3 2010/11/16 16:29:34 [debug] 45139#0: *1 generic phase: 4 2010/11/16 16:29:34 [debug] 45139#0: *1 generic phase: 5 2010/11/16 16:29:34 [debug] 45139#0: *1 access phase: 6 2010/11/16 16:29:34 [debug] 45139#0: *1 access phase: 7 2010/11/16 16:29:34 [debug] 45139#0: *1 post access phase: 8 2010/11/16 16:29:34 [debug] 45139#0: *1 try files phase: 9 2010/11/16 16:29:34 [debug] 45139#0: *1 http client request body preread 47 2010/11/16 16:29:34 [debug] 45139#0: *1 http init upstream, client timer: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: "Host: " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script var: "sei.irk.ru" 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: "X-Real-IP: " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script var: "84.237.22.11" 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: "X-Forwarded-For: " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script var: "84.237.22.11" 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: "Connection: close " 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Accept: */*" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Accept-Encoding: gzip,deflate" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Content-Type: application/x-www-form-urlencoded; charset=UTF-8" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "X-Requested-With: XMLHttpRequest" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Referer: http://sei.irk.ru/login"; 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Content-Length: 47" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Cookie: JSESSIONID=60E12512E766D3C8820E4A6272B69F6E" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Pragma: no-cache" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Cache-Control: no-cache" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "POST /controller HTTP/1.0 Host: sei.irk.ru X-Real-IP: 84.237.22.11 X-Forwarded-For: 84.237.22.11 Connection: close User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3 Accept: */* Accept-Language: ru,en-us;q=0.7,en;q=0.3 Accept-Encoding: gzip,deflate Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7 Content-Type: application/x-www-form-urlencoded; charset=UTF-8 X-Requested-With: XMLHttpRequest Referer: http://sei.irk.ru/login Content-Length: 47 Cookie: JSESSIONID=60E12512E766D3C8820E4A6272B69F6E Pragma: no-cache Cache-Control: no-cache " 2010/11/16 16:29:34 [debug] 45139#0: *1 http cleanup add: 28612B90 2010/11/16 16:29:34 [debug] 45139#0: *1 get rr peer, try: 1 2010/11/16 16:29:34 [debug] 45139#0: *1 socket 12 2010/11/16 16:29:34 [debug] 45139#0: *1 connect to 127.0.0.1:8180, fd:12 #6 2010/11/16 16:29:34 [debug] 45139#0: *1 kevent set event: 12: ft:-1 fl:0025 2010/11/16 16:29:34 [debug] 45139#0: *1 connected 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream connect: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream send request 2010/11/16 16:29:34 [debug] 45139#0: *1 chain writer buf fl:0 s:597 2010/11/16 16:29:34 [debug] 45139#0: *1 chain writer buf fl:1 s:47 2010/11/16 16:29:34 [debug] 45139#0: *1 chain writer in: 28612BBC 2010/11/16 16:29:34 [debug] 45139#0: *1 writev: 644 of 644 2010/11/16 16:29:34 [debug] 45139#0: *1 chain writer out: 00000000 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer add: 12: 60000:1406045298 2010/11/16 16:29:34 [debug] 45139#0: *1 http finalize request: -4, "/controller?" a:1, c:2 2010/11/16 16:29:34 [debug] 45139#0: *1 http request count:2 blk:0 2010/11/16 16:29:34 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:34 [debug] 45139#0: worker cycle 2010/11/16 16:29:34 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:34 [debug] 45139#0: kevent timer: 60000, changes: 1 2010/11/16 16:29:34 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:34 [debug] 45139#0: kevent: 12: ft:-1 fl:8020 ff:00000000 d:170 ud:286980B4 2010/11/16 16:29:34 [debug] 45139#0: *1 post event 286980B4 2010/11/16 16:29:34 [debug] 45139#0: timer delta: 25 2010/11/16 16:29:34 [debug] 45139#0: posted events 286980B4 2010/11/16 16:29:34 [debug] 45139#0: posted event 286980B4 2010/11/16 16:29:34 [debug] 45139#0: *1 delete posted event 286980B4 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream request: "/controller?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream process header 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 2861F000:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 recv: eof:1, avail:170, err:0 2010/11/16 16:29:34 [debug] 45139#0: *1 recv: fd:12 170 of 4096 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy status 200 "200 OK" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Content-Length: 59" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Date: Tue, 16 Nov 2010 08:29:34 GMT" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Connection: close" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Server: Apache" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header done 2010/11/16 16:29:34 [debug] 45139#0: *1 HTTP/1.1 200 OK Server: nginx Date: Tue, 16 Nov 2010 08:29:34 GMT Connection: keep-alive Content-Length: 59 2010/11/16 16:29:34 [debug] 45139#0: *1 write new buf t:1 f:0 28612D44, pos 28612D44, size: 115 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http write filter: l:0 f:0 s:115 2010/11/16 16:29:34 [debug] 45139#0: *1 http cacheable: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream process upstream 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe read upstream: 1 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe preread: 59 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe buf free s:0 t:1 f:0 2861F000, pos 2861F06F, size: 59 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 input buf #0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write downstream: 1 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write downstream flush in 2010/11/16 16:29:34 [debug] 45139#0: *1 http output filter "/controller?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http copy filter: "/controller?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http postpone filter "/controller?" 28612EA0 2010/11/16 16:29:34 [debug] 45139#0: *1 write old buf t:1 f:0 28612D44, pos 28612D44, size: 115 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 write new buf t:1 f:0 2861F000, pos 2861F06F, size: 59 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http write filter: l:0 f:0 s:174 2010/11/16 16:29:34 [debug] 45139#0: *1 http copy filter: 0 "/controller?" 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write downstream done 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer: 12, old: 1406045298, new: 1406045323 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream exit: 00000000 2010/11/16 16:29:34 [debug] 45139#0: *1 finalize http upstream request: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 finalize http proxy request 2010/11/16 16:29:34 [debug] 45139#0: *1 free rr peer 1 0 2010/11/16 16:29:34 [debug] 45139#0: *1 close http upstream connection: 12 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer del: 12: 1406045298 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream temp fd: -1 2010/11/16 16:29:34 [debug] 45139#0: *1 http output filter "/controller?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http copy filter: "/controller?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http postpone filter "/controller?" BFBFE7FC 2010/11/16 16:29:34 [debug] 45139#0: *1 write old buf t:1 f:0 28612D44, pos 28612D44, size: 115 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 write old buf t:1 f:0 2861F000, pos 2861F06F, size: 59 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 write new buf t:0 f:0 00000000, pos 00000000, size: 0 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http write filter: l:1 f:0 s:174 2010/11/16 16:29:34 [debug] 45139#0: *1 http write filter limit 0 2010/11/16 16:29:34 [debug] 45139#0: *1 writev: 174 of 174 2010/11/16 16:29:34 [debug] 45139#0: *1 http write filter 00000000 2010/11/16 16:29:34 [debug] 45139#0: *1 http copy filter: 0 "/controller?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http finalize request: 0, "/controller?" a:1, c:1 2010/11/16 16:29:34 [debug] 45139#0: *1 set http keepalive handler 2010/11/16 16:29:34 [debug] 45139#0: *1 http close request 2010/11/16 16:29:34 [debug] 45139#0: *1 http log handler 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 2861F000 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 28612000, unused: 11 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer add: 11: 65000:1406050323 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 286B6300 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 28628400 2010/11/16 16:29:34 [debug] 45139#0: *1 hc free: 00000000 0 2010/11/16 16:29:34 [debug] 45139#0: *1 hc busy: 00000000 0 2010/11/16 16:29:34 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:34 [debug] 45139#0: worker cycle 2010/11/16 16:29:34 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:34 [debug] 45139#0: kevent timer: 65000, changes: 0 2010/11/16 16:29:34 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:34 [debug] 45139#0: kevent: 11: ft:-2 fl:0020 ff:00000000 d:33696 ud:286A7078 2010/11/16 16:29:34 [debug] 45139#0: *1 post event 286A7078 2010/11/16 16:29:34 [debug] 45139#0: timer delta: 7 2010/11/16 16:29:34 [debug] 45139#0: posted events 286A7078 2010/11/16 16:29:34 [debug] 45139#0: posted event 286A7078 2010/11/16 16:29:34 [debug] 45139#0: *1 delete posted event 286A7078 2010/11/16 16:29:34 [debug] 45139#0: *1 http empty handler 2010/11/16 16:29:34 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:34 [debug] 45139#0: worker cycle 2010/11/16 16:29:34 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:34 [debug] 45139#0: kevent timer: 64993, changes: 0 2010/11/16 16:29:34 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:34 [debug] 45139#0: kevent: 11: ft:-1 fl:0020 ff:00000000 d:462 ud:28698078 2010/11/16 16:29:34 [debug] 45139#0: *1 post event 28698078 2010/11/16 16:29:34 [debug] 45139#0: timer delta: 24 2010/11/16 16:29:34 [debug] 45139#0: posted events 28698078 2010/11/16 16:29:34 [debug] 45139#0: posted event 28698078 2010/11/16 16:29:34 [debug] 45139#0: *1 delete posted event 28698078 2010/11/16 16:29:34 [debug] 45139#0: *1 http keepalive handler 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 28628400:1024 2010/11/16 16:29:34 [debug] 45139#0: *1 recv: eof:0, avail:462, err:0 2010/11/16 16:29:34 [debug] 45139#0: *1 recv: fd:11 462 of 1024 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 286B6300:656 2010/11/16 16:29:34 [debug] 45139#0: *1 posix_memalign: 28612000:4096 @16 2010/11/16 16:29:34 [debug] 45139#0: *1 http process request line 2010/11/16 16:29:34 [debug] 45139#0: *1 http request line: "GET /admin_panel HTTP/1.1" 2010/11/16 16:29:34 [debug] 45139#0: *1 http uri: "/admin_panel" 2010/11/16 16:29:34 [debug] 45139#0: *1 http args: "" 2010/11/16 16:29:34 [debug] 45139#0: *1 http exten: "" 2010/11/16 16:29:34 [debug] 45139#0: *1 http process request header line 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Host: sei.irk.ru" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Accept-Encoding: gzip,deflate" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Keep-Alive: 115" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Connection: keep-alive" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Referer: http://sei.irk.ru/login"; 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Cookie: JSESSIONID=60E12512E766D3C8820E4A6272B69F6E" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header done 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer del: 11: 1406050323 2010/11/16 16:29:34 [debug] 45139#0: *1 rewrite phase: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: "/" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: "index.jsp" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: "5xx.html" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/tel" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/mail" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/manager" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/admin" 2010/11/16 16:29:34 [debug] 45139#0: *1 using configuration "/admin" 2010/11/16 16:29:34 [debug] 45139#0: *1 http cl:-1 max:1048576 2010/11/16 16:29:34 [debug] 45139#0: *1 rewrite phase: 2 2010/11/16 16:29:34 [debug] 45139#0: *1 post rewrite phase: 3 2010/11/16 16:29:34 [debug] 45139#0: *1 generic phase: 4 2010/11/16 16:29:34 [debug] 45139#0: *1 generic phase: 5 2010/11/16 16:29:34 [debug] 45139#0: *1 access phase: 6 2010/11/16 16:29:34 [debug] 45139#0: *1 access phase: 7 2010/11/16 16:29:34 [debug] 45139#0: *1 post access phase: 8 2010/11/16 16:29:34 [debug] 45139#0: *1 try files phase: 9 2010/11/16 16:29:34 [debug] 45139#0: *1 http init upstream, client timer: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: "Host: " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script var: "sei.irk.ru" 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: "X-Real-IP: " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script var: "84.237.22.11" 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: "X-Forwarded-For: " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script var: "84.237.22.11" 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: "Connection: close " 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Accept-Encoding: gzip,deflate" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Referer: http://sei.irk.ru/login"; 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Cookie: JSESSIONID=60E12512E766D3C8820E4A6272B69F6E" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "GET /admin_panel HTTP/1.0 Host: sei.irk.ru X-Real-IP: 84.237.22.11 X-Forwarded-For: 84.237.22.11 Connection: close User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3 Accept: text/html,application/xhtml+xml,application/xml;q=0.9,*/*;q=0.8 Accept-Language: ru,en-us;q=0.7,en;q=0.3 Accept-Encoding: gzip,deflate Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7 Referer: http://sei.irk.ru/login Cookie: JSESSIONID=60E12512E766D3C8820E4A6272B69F6E " 2010/11/16 16:29:34 [debug] 45139#0: *1 http cleanup add: 28612A5C 2010/11/16 16:29:34 [debug] 45139#0: *1 get rr peer, try: 1 2010/11/16 16:29:34 [debug] 45139#0: *1 socket 12 2010/11/16 16:29:34 [debug] 45139#0: *1 connect to 127.0.0.1:8180, fd:12 #7 2010/11/16 16:29:34 [debug] 45139#0: *1 kevent set event: 12: ft:-1 fl:0025 2010/11/16 16:29:34 [debug] 45139#0: *1 connected 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream connect: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream send request 2010/11/16 16:29:34 [debug] 45139#0: *1 chain writer buf fl:1 s:496 2010/11/16 16:29:34 [debug] 45139#0: *1 chain writer in: 28612A78 2010/11/16 16:29:34 [debug] 45139#0: *1 writev: 496 of 496 2010/11/16 16:29:34 [debug] 45139#0: *1 chain writer out: 00000000 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer add: 12: 60000:1406045354 2010/11/16 16:29:34 [debug] 45139#0: *1 http finalize request: -4, "/admin_panel?" a:1, c:2 2010/11/16 16:29:34 [debug] 45139#0: *1 http request count:2 blk:0 2010/11/16 16:29:34 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:34 [debug] 45139#0: worker cycle 2010/11/16 16:29:34 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:34 [debug] 45139#0: kevent timer: 60000, changes: 1 2010/11/16 16:29:34 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:34 [debug] 45139#0: kevent: 12: ft:-1 fl:0020 ff:00000000 d:9000 ud:286980B5 2010/11/16 16:29:34 [debug] 45139#0: *1 post event 286980B4 2010/11/16 16:29:34 [debug] 45139#0: timer delta: 4 2010/11/16 16:29:34 [debug] 45139#0: posted events 286980B4 2010/11/16 16:29:34 [debug] 45139#0: posted event 286980B4 2010/11/16 16:29:34 [debug] 45139#0: *1 delete posted event 286980B4 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream request: "/admin_panel?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream process header 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 2861F000:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 recv: eof:0, avail:9000, err:0 2010/11/16 16:29:34 [debug] 45139#0: *1 recv: fd:12 4096 of 4096 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy status 200 "200 OK" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Content-Type: text/html;charset=UTF-8" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Date: Tue, 16 Nov 2010 08:29:34 GMT" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Connection: close" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Server: Apache" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header done 2010/11/16 16:29:34 [debug] 45139#0: *1 HTTP/1.1 200 OK Server: nginx Date: Tue, 16 Nov 2010 08:29:34 GMT Content-Type: text/html;charset=UTF-8 Transfer-Encoding: chunked Connection: keep-alive Vary: Accept-Encoding Expires: Tue, 16 Nov 2010 08:30:34 GMT Cache-Control: max-age=60 Content-Encoding: gzip 2010/11/16 16:29:34 [debug] 45139#0: *1 write new buf t:1 f:0 28612CD4, pos 28612CD4, size: 276 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http write filter: l:0 f:0 s:276 2010/11/16 16:29:34 [debug] 45139#0: *1 http cacheable: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream process upstream 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe read upstream: 1 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe preread: 3966 2010/11/16 16:29:34 [debug] 45139#0: *1 input buf #0 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 2864B000:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 readv: eof:0, avail:4904, err:0 2010/11/16 16:29:34 [debug] 45139#0: *1 readv: 1, last:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe recv chain: 4096 2010/11/16 16:29:34 [debug] 45139#0: *1 input buf #1 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 286BF000:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 readv: eof:0, avail:808, err:0 2010/11/16 16:29:34 [debug] 45139#0: *1 readv: 1, last:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe recv chain: 808 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe buf in s:1 t:1 f:0 2861F000, pos 2861F082, size: 3966 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe buf in s:1 t:1 f:0 2864B000, pos 2864B000, size: 4096 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe buf free s:0 t:1 f:0 286BF000, pos 286BF000, size: 808 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write downstream: 1 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write busy: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write buf ls:1 2861F082 3966 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write buf ls:1 2864B000 4096 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write: out:28612ED0, f:0 2010/11/16 16:29:34 [debug] 45139#0: *1 http output filter "/admin_panel?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http copy filter: "/admin_panel?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http postpone filter "/admin_panel?" 28612FC8 2010/11/16 16:29:34 [debug] 45139#0: *1 http gzip filter 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 29101000:270336 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip alloc: n:1 s:5824 a:8192 p:29101000 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip alloc: n:32768 s:2 a:65536 p:29103000 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip alloc: n:32768 s:2 a:65536 p:29113000 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip alloc: n:32768 s:2 a:65536 p:29123000 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip alloc: n:16384 s:4 a:65536 p:29133000 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in: 28612FE0 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:28612E9C ni:2861F082 ai:3966 2010/11/16 16:29:34 [debug] 45139#0: *1 posix_memalign: 286C0000:4096 @16 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 286C1000:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate in: ni:2861F082 no:286C1000 ai:3966 ao:4096 fl:0 redo:0 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate out: ni:28620000 no:286C1000 ai:0 ao:4096 rc:0 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:28612E9C pos:2861F082 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in: 28612FE8 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:28612F14 ni:2864B000 ai:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate in: ni:2864B000 no:286C1000 ai:4096 ao:4096 fl:0 redo:0 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate out: ni:2864C000 no:286C1000 ai:0 ao:4096 rc:0 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:28612F14 pos:2864B000 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in: 00000000 2010/11/16 16:29:34 [debug] 45139#0: *1 http copy filter: 0 "/admin_panel?" 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write busy: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write: out:00000000, f:0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe read upstream: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe buf free s:0 t:1 f:0 286BF000, pos 286BF000, size: 808 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe buf free s:0 t:1 f:0 2861F000, pos 2861F000, size: 0 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe buf free s:0 t:1 f:0 2864B000, pos 2864B000, size: 0 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer: 12, old: 1406045354, new: 1406045358 2010/11/16 16:29:34 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:34 [debug] 45139#0: worker cycle 2010/11/16 16:29:34 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:34 [debug] 45139#0: kevent timer: 59996, changes: 0 2010/11/16 16:29:34 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:34 [debug] 45139#0: kevent: 12: ft:-1 fl:8020 ff:00000000 d:17349 ud:286980B5 2010/11/16 16:29:34 [debug] 45139#0: *1 post event 286980B4 2010/11/16 16:29:34 [debug] 45139#0: timer delta: 100 2010/11/16 16:29:34 [debug] 45139#0: posted events 286980B4 2010/11/16 16:29:34 [debug] 45139#0: posted event 286980B4 2010/11/16 16:29:34 [debug] 45139#0: *1 delete posted event 286980B4 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream request: "/admin_panel?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream process upstream 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe read upstream: 1 2010/11/16 16:29:34 [debug] 45139#0: *1 readv: eof:1, avail:17349, err:0 2010/11/16 16:29:34 [debug] 45139#0: *1 readv: 3, last:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe recv chain: 11480 2010/11/16 16:29:34 [debug] 45139#0: *1 input buf #2 2010/11/16 16:29:34 [debug] 45139#0: *1 input buf #3 2010/11/16 16:29:34 [debug] 45139#0: *1 input buf #4 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 286C2000:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 readv: eof:1, avail:5869, err:0 2010/11/16 16:29:34 [debug] 45139#0: *1 readv: 1, last:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe recv chain: 4096 2010/11/16 16:29:34 [debug] 45139#0: *1 input buf #5 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 286C3000:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 readv: eof:1, avail:1773, err:0 2010/11/16 16:29:34 [debug] 45139#0: *1 readv: 1, last:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe recv chain: 1773 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe buf in s:1 t:1 f:0 286BF000, pos 286BF000, size: 4096 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe buf in s:1 t:1 f:0 2861F000, pos 2861F000, size: 4096 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe buf in s:1 t:1 f:0 2864B000, pos 2864B000, size: 4096 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe buf in s:1 t:1 f:0 286C2000, pos 286C2000, size: 4096 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe buf free s:0 t:1 f:0 286C3000, pos 286C3000, size: 1773 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 input buf #6 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write downstream: 1 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write downstream flush in 2010/11/16 16:29:34 [debug] 45139#0: *1 http output filter "/admin_panel?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http copy filter: "/admin_panel?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http postpone filter "/admin_panel?" 286C0168 2010/11/16 16:29:34 [debug] 45139#0: *1 http gzip filter 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in: 286C0190 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:28612F14 ni:286BF000 ai:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate in: ni:286BF000 no:286C1000 ai:4096 ao:4096 fl:0 redo:0 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate out: ni:286C0000 no:286C1000 ai:0 ao:4096 rc:0 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:28612F14 pos:286BF000 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in: 286C0198 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:28612E9C ni:2861F000 ai:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate in: ni:2861F000 no:286C1000 ai:4096 ao:4096 fl:0 redo:0 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate out: ni:28620000 no:286C1000 ai:0 ao:4096 rc:0 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:28612E9C pos:2861F000 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in: 286C01A0 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:286C004C ni:2864B000 ai:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate in: ni:2864B000 no:286C1000 ai:4096 ao:4096 fl:0 redo:0 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate out: ni:2864C000 no:286C1000 ai:0 ao:4096 rc:0 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:286C004C pos:2864B000 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in: 286C01A8 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:286C00BC ni:286C2000 ai:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate in: ni:286C2000 no:286C1000 ai:4096 ao:4096 fl:0 redo:0 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate out: ni:286C3000 no:286C1000 ai:0 ao:4096 rc:0 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:286C00BC pos:286C2000 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in: 286C01B0 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:286C012C ni:286C3000 ai:1773 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate in: ni:286C3000 no:286C1000 ai:1773 ao:4096 fl:0 redo:0 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate out: ni:286C36ED no:286C1000 ai:0 ao:4096 rc:0 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:286C012C pos:286C3000 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in: 00000000 2010/11/16 16:29:34 [debug] 45139#0: *1 http copy filter: 0 "/admin_panel?" 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write downstream done 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer: 12, old: 1406045354, new: 1406045458 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream exit: 00000000 2010/11/16 16:29:34 [debug] 45139#0: *1 finalize http upstream request: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 finalize http proxy request 2010/11/16 16:29:34 [debug] 45139#0: *1 free rr peer 1 0 2010/11/16 16:29:34 [debug] 45139#0: *1 close http upstream connection: 12 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer del: 12: 1406045354 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream temp fd: -1 2010/11/16 16:29:34 [debug] 45139#0: *1 http output filter "/admin_panel?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http copy filter: "/admin_panel?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http postpone filter "/admin_panel?" BFBFE87C 2010/11/16 16:29:34 [debug] 45139#0: *1 http gzip filter 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in: 286C01EC 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:286C01B8 ni:00000000 ai:0 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate in: ni:00000000 no:286C1000 ai:0 ao:4096 fl:4 redo:0 2010/11/16 16:29:34 [debug] 45139#0: *1 deflate out: ni:00000000 no:286C1C75 ai:0 ao:907 rc:1 2010/11/16 16:29:34 [debug] 45139#0: *1 gzip in_buf:286C01B8 pos:00000000 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 29101000 2010/11/16 16:29:34 [debug] 45139#0: *1 http chunk: 10 2010/11/16 16:29:34 [debug] 45139#0: *1 http chunk: 3197 2010/11/16 16:29:34 [debug] 45139#0: *1 write old buf t:1 f:0 28612CD4, pos 28612CD4, size: 276 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 write new buf t:1 f:0 00000000, pos 286C027C, size: 5 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 write new buf t:0 f:0 00000000, pos 080EFCCC, size: 10 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 write new buf t:1 f:0 286C1000, pos 286C1000, size: 3197 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 write new buf t:0 f:0 00000000, pos 080E3CFC, size: 7 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http write filter: l:1 f:1 s:3495 2010/11/16 16:29:34 [debug] 45139#0: *1 http write filter limit 0 2010/11/16 16:29:34 [debug] 45139#0: *1 writev: 3495 of 3495 2010/11/16 16:29:34 [debug] 45139#0: *1 http write filter 00000000 2010/11/16 16:29:34 [debug] 45139#0: *1 http copy filter: 0 "/admin_panel?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http finalize request: 0, "/admin_panel?" a:1, c:1 2010/11/16 16:29:34 [debug] 45139#0: *1 set http keepalive handler 2010/11/16 16:29:34 [debug] 45139#0: *1 http close request 2010/11/16 16:29:34 [debug] 45139#0: *1 http log handler 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 286C3000 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 286C2000 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 286C1000 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 00000000 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 286BF000 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 2864B000 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 2861F000 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 28612000, unused: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 286C0000, unused: 3151 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer add: 11: 65000:1406050458 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 286B6300 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 28628400 2010/11/16 16:29:34 [debug] 45139#0: *1 hc free: 00000000 0 2010/11/16 16:29:34 [debug] 45139#0: *1 hc busy: 00000000 0 2010/11/16 16:29:34 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:34 [debug] 45139#0: worker cycle 2010/11/16 16:29:34 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:34 [debug] 45139#0: kevent timer: 65000, changes: 0 2010/11/16 16:29:34 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:34 [debug] 45139#0: kevent: 11: ft:-2 fl:0020 ff:00000000 d:32697 ud:286A7078 2010/11/16 16:29:34 [debug] 45139#0: *1 post event 286A7078 2010/11/16 16:29:34 [debug] 45139#0: timer delta: 8 2010/11/16 16:29:34 [debug] 45139#0: posted events 286A7078 2010/11/16 16:29:34 [debug] 45139#0: posted event 286A7078 2010/11/16 16:29:34 [debug] 45139#0: *1 delete posted event 286A7078 2010/11/16 16:29:34 [debug] 45139#0: *1 http empty handler 2010/11/16 16:29:34 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:34 [debug] 45139#0: worker cycle 2010/11/16 16:29:34 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:34 [debug] 45139#0: kevent timer: 64992, changes: 0 2010/11/16 16:29:34 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:34 [debug] 45139#0: kevent: 11: ft:-2 fl:0020 ff:00000000 d:33696 ud:286A7078 2010/11/16 16:29:34 [debug] 45139#0: *1 post event 286A7078 2010/11/16 16:29:34 [debug] 45139#0: timer delta: 40 2010/11/16 16:29:34 [debug] 45139#0: posted events 286A7078 2010/11/16 16:29:34 [debug] 45139#0: posted event 286A7078 2010/11/16 16:29:34 [debug] 45139#0: *1 delete posted event 286A7078 2010/11/16 16:29:34 [debug] 45139#0: *1 http empty handler 2010/11/16 16:29:34 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:34 [debug] 45139#0: worker cycle 2010/11/16 16:29:34 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:34 [debug] 45139#0: kevent timer: 64952, changes: 0 2010/11/16 16:29:34 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:34 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:34 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:34 [debug] 45138#0: worker cycle 2010/11/16 16:29:34 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:34 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:34 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:34 [debug] 45139#0: kevent: 11: ft:-1 fl:0020 ff:00000000 d:450 ud:28698078 2010/11/16 16:29:34 [debug] 45139#0: *1 post event 28698078 2010/11/16 16:29:34 [debug] 45139#0: timer delta: 400 2010/11/16 16:29:34 [debug] 45139#0: posted events 28698078 2010/11/16 16:29:34 [debug] 45139#0: posted event 28698078 2010/11/16 16:29:34 [debug] 45139#0: *1 delete posted event 28698078 2010/11/16 16:29:34 [debug] 45139#0: *1 http keepalive handler 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 28628400:1024 2010/11/16 16:29:34 [debug] 45139#0: *1 recv: eof:0, avail:450, err:0 2010/11/16 16:29:34 [debug] 45139#0: *1 recv: fd:11 450 of 1024 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 286B6300:656 2010/11/16 16:29:34 [debug] 45139#0: *1 posix_memalign: 28612000:4096 @16 2010/11/16 16:29:34 [debug] 45139#0: *1 http process request line 2010/11/16 16:29:34 [debug] 45139#0: *1 http request line: "GET /tabs/statistics.jsp HTTP/1.1" 2010/11/16 16:29:34 [debug] 45139#0: *1 http uri: "/tabs/statistics.jsp" 2010/11/16 16:29:34 [debug] 45139#0: *1 http args: "" 2010/11/16 16:29:34 [debug] 45139#0: *1 http exten: "jsp" 2010/11/16 16:29:34 [debug] 45139#0: *1 http process request header line 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Host: sei.irk.ru" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Accept: */*" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Accept-Encoding: gzip,deflate" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Keep-Alive: 115" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Connection: keep-alive" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "X-Requested-With: XMLHttpRequest" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Referer: http://sei.irk.ru/admin_panel"; 2010/11/16 16:29:34 [debug] 45139#0: *1 http header: "Cookie: JSESSIONID=60E12512E766D3C8820E4A6272B69F6E" 2010/11/16 16:29:34 [debug] 45139#0: *1 http header done 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer del: 11: 1406050458 2010/11/16 16:29:34 [debug] 45139#0: *1 rewrite phase: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: "/" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: "index.jsp" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: "publications.jsp" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/tel" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/mail" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/manager" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/admin" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/chronicles" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "/symp2010" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "^/(aec|articles|diser|history|ieee_pes_russiancapter|ieee_pes_wg_asia_australia|ircei|les|library|powerdiscussion|ppt|rfbg|rfn|sei34|semtps)" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "^/manager.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "^/admin.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "^.+\.(jpg|jpeg|gif|png|svg)$" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "^.+\.(pdf|doc|txt)$" 2010/11/16 16:29:34 [debug] 45139#0: *1 test location: ~ "^.+\.(js|css)$" 2010/11/16 16:29:34 [debug] 45139#0: *1 using configuration "/" 2010/11/16 16:29:34 [debug] 45139#0: *1 http cl:-1 max:157286400 2010/11/16 16:29:34 [debug] 45139#0: *1 rewrite phase: 2 2010/11/16 16:29:34 [debug] 45139#0: *1 post rewrite phase: 3 2010/11/16 16:29:34 [debug] 45139#0: *1 generic phase: 4 2010/11/16 16:29:34 [debug] 45139#0: *1 generic phase: 5 2010/11/16 16:29:34 [debug] 45139#0: *1 access phase: 6 2010/11/16 16:29:34 [debug] 45139#0: *1 access phase: 7 2010/11/16 16:29:34 [debug] 45139#0: *1 post access phase: 8 2010/11/16 16:29:34 [debug] 45139#0: *1 try files phase: 9 2010/11/16 16:29:34 [debug] 45139#0: *1 http init upstream, client timer: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: "Host: " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script var: "sei.irk.ru" 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: "X-Real-IP: " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script var: "84.237.22.11" 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: "X-Forwarded-For: " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script var: "84.237.22.11" 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: " " 2010/11/16 16:29:34 [debug] 45139#0: *1 http script copy: "Connection: close " 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Accept: */*" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Accept-Language: ru,en-us;q=0.7,en;q=0.3" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Accept-Encoding: gzip,deflate" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "X-Requested-With: XMLHttpRequest" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Referer: http://sei.irk.ru/admin_panel"; 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Cookie: JSESSIONID=60E12512E766D3C8820E4A6272B69F6E" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "GET /tabs/statistics.jsp HTTP/1.0 Host: sei.irk.ru X-Real-IP: 84.237.22.11 X-Forwarded-For: 84.237.22.11 Connection: close User-Agent: Mozilla/5.0 (X11; U; Linux i686; ru; rv:1.9.2.3) Gecko/20100401 Firefox/3.6.3 Accept: */* Accept-Language: ru,en-us;q=0.7,en;q=0.3 Accept-Encoding: gzip,deflate Accept-Charset: windows-1251,utf-8;q=0.7,*;q=0.7 X-Requested-With: XMLHttpRequest Referer: http://sei.irk.ru/admin_panel Cookie: JSESSIONID=60E12512E766D3C8820E4A6272B69F6E " 2010/11/16 16:29:34 [debug] 45139#0: *1 http cleanup add: 28612A7C 2010/11/16 16:29:34 [debug] 45139#0: *1 get rr peer, try: 1 2010/11/16 16:29:34 [debug] 45139#0: *1 socket 12 2010/11/16 16:29:34 [debug] 45139#0: *1 connect to 127.0.0.1:8180, fd:12 #8 2010/11/16 16:29:34 [debug] 45139#0: *1 kevent set event: 12: ft:-1 fl:0025 2010/11/16 16:29:34 [debug] 45139#0: *1 connected 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream connect: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream send request 2010/11/16 16:29:34 [debug] 45139#0: *1 chain writer buf fl:1 s:484 2010/11/16 16:29:34 [debug] 45139#0: *1 chain writer in: 28612A98 2010/11/16 16:29:34 [debug] 45139#0: *1 writev: 484 of 484 2010/11/16 16:29:34 [debug] 45139#0: *1 chain writer out: 00000000 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer add: 12: 60000:1406045906 2010/11/16 16:29:34 [debug] 45139#0: *1 http finalize request: -4, "/tabs/statistics.jsp?" a:1, c:2 2010/11/16 16:29:34 [debug] 45139#0: *1 http request count:2 blk:0 2010/11/16 16:29:34 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:34 [debug] 45139#0: worker cycle 2010/11/16 16:29:34 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:34 [debug] 45139#0: kevent timer: 60000, changes: 1 2010/11/16 16:29:34 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:34 [debug] 45139#0: kevent: 12: ft:-1 fl:8020 ff:00000000 d:149 ud:286980B4 2010/11/16 16:29:34 [debug] 45139#0: *1 post event 286980B4 2010/11/16 16:29:34 [debug] 45139#0: timer delta: 3 2010/11/16 16:29:34 [debug] 45139#0: posted events 286980B4 2010/11/16 16:29:34 [debug] 45139#0: posted event 286980B4 2010/11/16 16:29:34 [debug] 45139#0: *1 delete posted event 286980B4 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream request: "/tabs/statistics.jsp?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream process header 2010/11/16 16:29:34 [debug] 45139#0: *1 malloc: 2861F000:4096 2010/11/16 16:29:34 [debug] 45139#0: *1 recv: eof:1, avail:149, err:0 2010/11/16 16:29:34 [debug] 45139#0: *1 recv: fd:12 149 of 4096 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy status 200 "200 OK" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Content-Type: text/html;charset=UTF-8" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Content-Length: 0" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Date: Tue, 16 Nov 2010 08:29:34 GMT" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Connection: close" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header: "Server: Apache" 2010/11/16 16:29:34 [debug] 45139#0: *1 http proxy header done 2010/11/16 16:29:34 [debug] 45139#0: *1 HTTP/1.1 200 OK Server: nginx Date: Tue, 16 Nov 2010 08:29:34 GMT Content-Type: text/html;charset=UTF-8 Connection: keep-alive Content-Length: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 write new buf t:1 f:0 28612C48, pos 28612C48, size: 153 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http write filter: l:0 f:0 s:153 2010/11/16 16:29:34 [debug] 45139#0: *1 http cacheable: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream process upstream 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe read upstream: 1 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe preread: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe buf free s:0 t:1 f:0 2861F000, pos 2861F095, size: 0 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write downstream: 1 2010/11/16 16:29:34 [debug] 45139#0: *1 pipe write downstream done 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer: 12, old: 1406045906, new: 1406045909 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream exit: 00000000 2010/11/16 16:29:34 [debug] 45139#0: *1 finalize http upstream request: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 finalize http proxy request 2010/11/16 16:29:34 [debug] 45139#0: *1 free rr peer 1 0 2010/11/16 16:29:34 [debug] 45139#0: *1 close http upstream connection: 12 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer del: 12: 1406045906 2010/11/16 16:29:34 [debug] 45139#0: *1 http upstream temp fd: -1 2010/11/16 16:29:34 [debug] 45139#0: *1 http output filter "/tabs/statistics.jsp?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http copy filter: "/tabs/statistics.jsp?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http postpone filter "/tabs/statistics.jsp?" BFBFE7FC 2010/11/16 16:29:34 [debug] 45139#0: *1 write old buf t:1 f:0 28612C48, pos 28612C48, size: 153 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 write new buf t:0 f:0 00000000, pos 00000000, size: 0 file: 0, size: 0 2010/11/16 16:29:34 [debug] 45139#0: *1 http write filter: l:1 f:0 s:153 2010/11/16 16:29:34 [debug] 45139#0: *1 http write filter limit 0 2010/11/16 16:29:34 [debug] 45139#0: *1 writev: 153 of 153 2010/11/16 16:29:34 [debug] 45139#0: *1 http write filter 00000000 2010/11/16 16:29:34 [debug] 45139#0: *1 http copy filter: 0 "/tabs/statistics.jsp?" 2010/11/16 16:29:34 [debug] 45139#0: *1 http finalize request: 0, "/tabs/statistics.jsp?" a:1, c:1 2010/11/16 16:29:34 [debug] 45139#0: *1 set http keepalive handler 2010/11/16 16:29:34 [debug] 45139#0: *1 http close request 2010/11/16 16:29:34 [debug] 45139#0: *1 http log handler 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 2861F000 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 28612000, unused: 277 2010/11/16 16:29:34 [debug] 45139#0: *1 event timer add: 11: 65000:1406050909 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 286B6300 2010/11/16 16:29:34 [debug] 45139#0: *1 free: 28628400 2010/11/16 16:29:34 [debug] 45139#0: *1 hc free: 00000000 0 2010/11/16 16:29:34 [debug] 45139#0: *1 hc busy: 00000000 0 2010/11/16 16:29:34 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:34 [debug] 45139#0: worker cycle 2010/11/16 16:29:34 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:34 [debug] 45139#0: kevent timer: 65000, changes: 0 2010/11/16 16:29:34 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:34 [debug] 45139#0: kevent: 11: ft:-2 fl:0020 ff:00000000 d:33696 ud:286A7078 2010/11/16 16:29:34 [debug] 45139#0: *1 post event 286A7078 2010/11/16 16:29:34 [debug] 45139#0: timer delta: 9 2010/11/16 16:29:34 [debug] 45139#0: posted events 286A7078 2010/11/16 16:29:34 [debug] 45139#0: posted event 286A7078 2010/11/16 16:29:34 [debug] 45139#0: *1 delete posted event 286A7078 2010/11/16 16:29:34 [debug] 45139#0: *1 http empty handler 2010/11/16 16:29:34 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:34 [debug] 45139#0: worker cycle 2010/11/16 16:29:34 [debug] 45139#0: accept mutex locked 2010/11/16 16:29:34 [debug] 45139#0: kevent timer: 64991, changes: 0 2010/11/16 16:29:35 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:35 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:35 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:35 [debug] 45138#0: worker cycle 2010/11/16 16:29:35 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:35 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:35 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:35 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:35 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:35 [debug] 45138#0: worker cycle 2010/11/16 16:29:35 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:35 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:36 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:36 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:36 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:36 [debug] 45138#0: worker cycle 2010/11/16 16:29:36 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:36 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:36 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:36 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:36 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:36 [debug] 45138#0: worker cycle 2010/11/16 16:29:36 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:36 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:37 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:37 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:37 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:37 [debug] 45138#0: worker cycle 2010/11/16 16:29:37 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:37 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:37 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:37 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:37 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:37 [debug] 45138#0: worker cycle 2010/11/16 16:29:37 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:37 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:38 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:38 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:38 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:38 [debug] 45138#0: worker cycle 2010/11/16 16:29:38 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:38 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:38 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:38 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:38 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:38 [debug] 45138#0: worker cycle 2010/11/16 16:29:38 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:38 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:39 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:39 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:39 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:39 [debug] 45138#0: worker cycle 2010/11/16 16:29:39 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:39 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:39 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:39 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:39 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:39 [debug] 45138#0: worker cycle 2010/11/16 16:29:39 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:39 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:39 [debug] 45140#0: kevent events: 0 2010/11/16 16:29:39 [debug] 45140#0: timer delta: 10002 2010/11/16 16:29:39 [debug] 45140#0: event timer del: -1: 1405991109 2010/11/16 16:29:39 [debug] 45140#0: http file cache expire 2010/11/16 16:29:39 [debug] 45140#0: malloc: 28608400:55 2010/11/16 16:29:39 [debug] 45140#0: http file cache size: 0 2010/11/16 16:29:39 [debug] 45140#0: event timer add: -1: 10000:1406001111 2010/11/16 16:29:39 [debug] 45140#0: posted events 00000000 2010/11/16 16:29:39 [debug] 45140#0: kevent timer: 10000, changes: 0 2010/11/16 16:29:40 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:40 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:40 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:40 [debug] 45138#0: worker cycle 2010/11/16 16:29:40 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:40 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:40 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:40 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:40 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:40 [debug] 45138#0: worker cycle 2010/11/16 16:29:40 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:40 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:41 [debug] 45138#0: kevent events: 0 2010/11/16 16:29:41 [debug] 45138#0: timer delta: 502 2010/11/16 16:29:41 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:41 [debug] 45138#0: worker cycle 2010/11/16 16:29:41 [debug] 45138#0: accept mutex lock failed: 0 2010/11/16 16:29:41 [debug] 45138#0: kevent timer: 500, changes: 0 2010/11/16 16:29:41 [notice] 45137#0: signal 15 (SIGTERM) received, exiting 2010/11/16 16:29:41 [debug] 45137#0: wake up, sigio 0 2010/11/16 16:29:41 [debug] 45137#0: child: 0 45138 e:0 t:0 d:0 r:1 j:0 2010/11/16 16:29:41 [debug] 45137#0: child: 1 45139 e:0 t:0 d:0 r:1 j:0 2010/11/16 16:29:41 [debug] 45137#0: child: 2 45140 e:0 t:0 d:0 r:1 j:0 2010/11/16 16:29:41 [debug] 45137#0: child: 3 45141 e:0 t:0 d:0 r:0 j:0 2010/11/16 16:29:41 [debug] 45137#0: termination cycle: 50 2010/11/16 16:29:41 [debug] 45137#0: sigsuspend 2010/11/16 16:29:41 [debug] 45138#0: kevent events: 1 2010/11/16 16:29:41 [debug] 45138#0: kevent: 7: ft:-1 fl:0000 ff:00000000 d:16 ud:2869803C 2010/11/16 16:29:41 [debug] 45138#0: channel handler 2010/11/16 16:29:41 [debug] 45138#0: channel: 16 2010/11/16 16:29:41 [debug] 45138#0: channel command: 4 2010/11/16 16:29:41 [debug] 45138#0: channel: -2 2010/11/16 16:29:41 [debug] 45138#0: timer delta: 477 2010/11/16 16:29:41 [debug] 45138#0: posted events 00000000 2010/11/16 16:29:41 [notice] 45138#0: exiting 2010/11/16 16:29:41 [debug] 45138#0: flush files 2010/11/16 16:29:41 [debug] 45138#0: run cleanup: 2865B6D8 2010/11/16 16:29:41 [debug] 45138#0: cleanup resolver 2010/11/16 16:29:41 [debug] 45138#0: free: 28644000, unused: 0 2010/11/16 16:29:41 [debug] 45138#0: free: 2864C000, unused: 0 2010/11/16 16:29:41 [debug] 45138#0: free: 28650000, unused: 0 2010/11/16 16:29:41 [debug] 45138#0: free: 28654000, unused: 0 2010/11/16 16:29:41 [debug] 45138#0: free: 28658000, unused: 0 2010/11/16 16:29:41 [debug] 45138#0: free: 2865E000, unused: 0 2010/11/16 16:29:41 [debug] 45138#0: free: 28662000, unused: 0 2010/11/16 16:29:41 [debug] 45138#0: free: 28666000, unused: 0 2010/11/16 16:29:41 [debug] 45138#0: free: 2866A000, unused: 4860 2010/11/16 16:29:41 [notice] 45138#0: exit 2010/11/16 16:29:41 [debug] 45139#0: kevent events: 1 2010/11/16 16:29:41 [debug] 45139#0: kevent: 9: ft:-1 fl:0000 ff:00000000 d:16 ud:2869803C 2010/11/16 16:29:41 [debug] 45139#0: post event 2869803C 2010/11/16 16:29:41 [debug] 45139#0: timer delta: 6831 2010/11/16 16:29:41 [debug] 45139#0: posted events 2869803C 2010/11/16 16:29:41 [debug] 45139#0: posted event 2869803C 2010/11/16 16:29:41 [debug] 45139#0: delete posted event 2869803C 2010/11/16 16:29:41 [debug] 45139#0: channel handler 2010/11/16 16:29:41 [debug] 45139#0: channel: 16 2010/11/16 16:29:41 [debug] 45139#0: channel command: 4 2010/11/16 16:29:41 [debug] 45139#0: channel: -2 2010/11/16 16:29:41 [debug] 45139#0: posted event 00000000 2010/11/16 16:29:41 [notice] 45139#0: exiting 2010/11/16 16:29:41 [debug] 45139#0: flush files 2010/11/16 16:29:41 [debug] 45139#0: run cleanup: 2865B6D8 2010/11/16 16:29:41 [debug] 45139#0: cleanup resolver 2010/11/16 16:29:41 [debug] 45139#0: free: 28644000, unused: 0 2010/11/16 16:29:41 [debug] 45139#0: free: 2864C000, unused: 0 2010/11/16 16:29:41 [debug] 45139#0: free: 28650000, unused: 0 2010/11/16 16:29:41 [debug] 45139#0: free: 28654000, unused: 0 2010/11/16 16:29:41 [debug] 45139#0: free: 28658000, unused: 0 2010/11/16 16:29:41 [debug] 45139#0: free: 2865E000, unused: 0 2010/11/16 16:29:41 [debug] 45139#0: free: 28662000, unused: 0 2010/11/16 16:29:41 [debug] 45139#0: free: 28666000, unused: 0 2010/11/16 16:29:41 [debug] 45139#0: free: 2866A000, unused: 4860 2010/11/16 16:29:41 [notice] 45139#0: exit 2010/11/16 16:29:41 [debug] 45140#0: kevent events: 1 2010/11/16 16:29:41 [debug] 45140#0: kevent: 11: ft:-1 fl:0000 ff:00000000 d:16 ud:2868C03C 2010/11/16 16:29:41 [debug] 45140#0: channel handler 2010/11/16 16:29:41 [debug] 45140#0: channel: 16 2010/11/16 16:29:41 [debug] 45140#0: channel command: 4 2010/11/16 16:29:41 [debug] 45140#0: channel: -2 2010/11/16 16:29:41 [debug] 45140#0: timer delta: 1640 2010/11/16 16:29:41 [debug] 45140#0: posted events 00000000 2010/11/16 16:29:41 [notice] 45140#0: exiting 2010/11/16 16:29:41 [notice] 45137#0: signal 23 (SIGIO) received 2010/11/16 16:29:41 [notice] 45137#0: signal 20 (SIGCHLD) received 2010/11/16 16:29:41 [notice] 45137#0: cache manager process 45140 exited with code 0 2010/11/16 16:29:41 [notice] 45137#0: worker process 45139 exited with code 0 2010/11/16 16:29:41 [notice] 45137#0: worker process 45138 exited with code 0 2010/11/16 16:29:41 [debug] 45137#0: wake up, sigio 4 2010/11/16 16:29:41 [debug] 45137#0: reap children 2010/11/16 16:29:41 [debug] 45137#0: child: 0 45138 e:1 t:1 d:0 r:1 j:0 2010/11/16 16:29:41 [debug] 45137#0: pass close channel s:0 pid:45138 to:45141 2010/11/16 16:29:41 [debug] 45137#0: child: 1 45139 e:1 t:1 d:0 r:1 j:0 2010/11/16 16:29:41 [debug] 45137#0: pass close channel s:1 pid:45139 to:45141 2010/11/16 16:29:41 [debug] 45137#0: child: 2 45140 e:1 t:1 d:0 r:1 j:0 2010/11/16 16:29:41 [debug] 45137#0: pass close channel s:2 pid:45140 to:45141 2010/11/16 16:29:41 [debug] 45137#0: child: 3 45141 e:1 t:0 d:0 r:0 j:0 2010/11/16 16:29:41 [debug] 45137#0: termination cycle: 50 2010/11/16 16:29:41 [debug] 45137#0: sigsuspend 2010/11/16 16:29:41 [notice] 45137#0: signal 23 (SIGIO) received 2010/11/16 16:29:41 [debug] 45137#0: wake up, sigio 3 2010/11/16 16:29:41 [debug] 45137#0: termination cycle: 50 2010/11/16 16:29:41 [debug] 45137#0: sigsuspend 2010/11/16 16:29:41 [debug] 45141#0: kevent events: 1 2010/11/16 16:29:41 [debug] 45141#0: kevent: 13: ft:-1 fl:0000 ff:00000000 d:64 ud:2868C03C 2010/11/16 16:29:41 [debug] 45141#0: channel handler 2010/11/16 16:29:41 [debug] 45141#0: channel: 16 2010/11/16 16:29:41 [debug] 45141#0: channel command: 4 2010/11/16 16:29:41 [debug] 45141#0: channel: 16 2010/11/16 16:29:41 [debug] 45141#0: channel command: 2 2010/11/16 16:29:41 [debug] 45141#0: close channel s:0 pid:45138 our:45138 fd:3 2010/11/16 16:29:41 [debug] 45141#0: channel: 16 2010/11/16 16:29:41 [debug] 45141#0: channel command: 2 2010/11/16 16:29:41 [debug] 45141#0: close channel s:1 pid:45139 our:45139 fd:8 2010/11/16 16:29:41 [debug] 45141#0: channel: 16 2010/11/16 16:29:41 [debug] 45141#0: channel command: 2 2010/11/16 16:29:41 [debug] 45141#0: close channel s:2 pid:45140 our:45140 fd:10 2010/11/16 16:29:41 [debug] 45141#0: channel: -2 2010/11/16 16:29:41 [debug] 45141#0: timer delta: 41694 2010/11/16 16:29:41 [debug] 45141#0: posted events 00000000 2010/11/16 16:29:41 [notice] 45141#0: exiting 2010/11/16 16:29:41 [notice] 45137#0: signal 23 (SIGIO) received 2010/11/16 16:29:41 [notice] 45137#0: signal 20 (SIGCHLD) received 2010/11/16 16:29:41 [notice] 45137#0: cache loader process 45141 exited with code 0 2010/11/16 16:29:41 [debug] 45137#0: wake up, sigio 2 2010/11/16 16:29:41 [debug] 45137#0: reap children 2010/11/16 16:29:41 [debug] 45137#0: child: 0 -1 e:1 t:1 d:0 r:1 j:0 2010/11/16 16:29:41 [debug] 45137#0: child: 1 -1 e:1 t:1 d:0 r:1 j:0 2010/11/16 16:29:41 [debug] 45137#0: child: 2 -1 e:1 t:1 d:0 r:1 j:0 2010/11/16 16:29:41 [debug] 45137#0: child: 3 45141 e:1 t:1 d:0 r:0 j:0 2010/11/16 16:29:41 [notice] 45137#0: exit 2010/11/16 16:29:41 [debug] 45137#0: close listening 0.0.0.0:80 #6 2010/11/16 16:29:41 [debug] 45137#0: run cleanup: 2865B6D8 2010/11/16 16:29:41 [debug] 45137#0: cleanup resolver 2010/11/16 16:29:41 [debug] 45137#0: free: 28644000, unused: 0 2010/11/16 16:29:41 [debug] 45137#0: free: 2864C000, unused: 0 2010/11/16 16:29:41 [debug] 45137#0: free: 28650000, unused: 0 2010/11/16 16:29:41 [debug] 45137#0: free: 28654000, unused: 0 2010/11/16 16:29:41 [debug] 45137#0: free: 28658000, unused: 0 2010/11/16 16:29:41 [debug] 45137#0: free: 2865E000, unused: 0 2010/11/16 16:29:41 [debug] 45137#0: free: 28662000, unused: 0 2010/11/16 16:29:41 [debug] 45137#0: free: 28666000, unused: 0 2010/11/16 16:29:41 [debug] 45137#0: free: 2866A000, unused: 4864

Re: Баг или фича - proxy_hide_header