archlinux, nginx и php-fastcgi...

[Ustalov "eveel" Dmitry]

Alexey Karagodov пишет:
> в пхп детализацию логов увеличить надо 
> и их тоже поглядеть на предмет подозрительного 
Не помню, чтобы PHP умел вести логи...
А вот debug-лог сервера, который выдал nginx при попытке зайти на 
localhost:8080 (прошу прощения, что так много):


2007/10/11 19:46:27 [debug] 10296#0: timer delta: 242090
2007/10/11 19:46:27 [debug] 10296#0: posted event 080F333C
2007/10/11 19:46:27 [debug] 10296#0: delete posted event 080F333C
2007/10/11 19:46:27 [debug] 10296#0: accept on 0.0.0.0:8080, ready: 0
2007/10/11 19:46:27 [debug] 10296#0: malloc: 080C6C28:256
2007/10/11 19:46:27 [debug] 10296#0: *18 accept: 127.0.0.1 fd:8
2007/10/11 19:46:27 [debug] 10296#0: *18 event timer add: 8: 
60000:2404506224
2007/10/11 19:46:27 [debug] 10296#0: *18 epoll add event: fd:8 op:1 
ev:80000001
2007/10/11 19:46:27 [debug] 10296#0: posted event 00000000
2007/10/11 19:46:27 [debug] 10296#0: posted events 00000000
2007/10/11 19:46:27 [debug] 10296#0: worker cycle
2007/10/11 19:46:27 [debug] 10296#0: accept mutex locked
2007/10/11 19:46:27 [debug] 10296#0: epoll timer: 60000
2007/10/11 19:46:27 [debug] 10296#0: epoll: fd:8 ev:0001 d:080DE54D
2007/10/11 19:46:27 [debug] 10296#0: *18 post event 080F3474
2007/10/11 19:46:27 [debug] 10296#0: timer delta: 0
2007/10/11 19:46:27 [debug] 10296#0: posted events 080F3474
2007/10/11 19:46:27 [debug] 10296#0: posted event 080F3474
2007/10/11 19:46:27 [debug] 10296#0: *18 delete posted event 080F3474
2007/10/11 19:46:27 [debug] 10296#0: *18 malloc: 080D12E0:632
2007/10/11 19:46:27 [debug] 10296#0: *18 malloc: 080D1560:1024
2007/10/11 19:46:27 [debug] 10296#0: *18 malloc: 080CCA60:4096
2007/10/11 19:46:27 [debug] 10296#0: *18 http process request line
2007/10/11 19:46:27 [debug] 10296#0: *18 recv: fd:8 187 of 1024
2007/10/11 19:46:27 [debug] 10296#0: *18 http request line: "GET / HTTP/1.0"
2007/10/11 19:46:27 [debug] 10296#0: *18 http uri: "/"
2007/10/11 19:46:27 [debug] 10296#0: *18 http args: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http exten: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http process request header line
2007/10/11 19:46:27 [debug] 10296#0: *18 http header: "User-Agent: 
w3m/0.5.2"
2007/10/11 19:46:27 [debug] 10296#0: *18 http header: "Accept: 
text/html, text/*;q=0.5, image/*"
2007/10/11 19:46:27 [debug] 10296#0: *18 http header: "Accept-Encoding: 
gzip, compress, bzip, bzip2, deflate"
2007/10/11 19:46:27 [debug] 10296#0: *18 http header: "Accept-Language: 
en;q=1.0"
2007/10/11 19:46:27 [debug] 10296#0: *18 http header: "Host: localhost:8080"
2007/10/11 19:46:27 [debug] 10296#0: *18 http header done
2007/10/11 19:46:27 [debug] 10296#0: *18 event timer del: 8: 2404506224
2007/10/11 19:46:27 [debug] 10296#0: *18 generic phase: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 find location for "/"
2007/10/11 19:46:27 [debug] 10296#0: *18 find location: "/"
2007/10/11 19:46:27 [debug] 10296#0: *18 find location for "/"
2007/10/11 19:46:27 [debug] 10296#0: *18 find location: ~ "\.php$"
2007/10/11 19:46:27 [debug] 10296#0: *18 find location: ~ "/public/*"
2007/10/11 19:46:27 [debug] 10296#0: *18 using configuration "/"
2007/10/11 19:46:27 [debug] 10296#0: *18 http cl:-1 max:1048576
2007/10/11 19:46:27 [debug] 10296#0: *18 generic phase: 2
2007/10/11 19:46:27 [debug] 10296#0: *18 post rewrite phase: 3
2007/10/11 19:46:27 [debug] 10296#0: *18 generic phase: 4
2007/10/11 19:46:27 [debug] 10296#0: *18 access phase: 5
2007/10/11 19:46:27 [debug] 10296#0: *18 access phase: 6
2007/10/11 19:46:27 [debug] 10296#0: *18 post access phase: 7
2007/10/11 19:46:27 [debug] 10296#0: *18 content phase: 8
2007/10/11 19:46:27 [debug] 10296#0: *18 open index 
"/srv/www/nginx/spectrum/index.php"
2007/10/11 19:46:27 [debug] 10296#0: *18 add cleanup: 080CCFE0
2007/10/11 19:46:27 [debug] 10296#0: *18 internal redirect: "/index.php?"
2007/10/11 19:46:27 [debug] 10296#0: *18 generic phase: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 find location for "/index.php"
2007/10/11 19:46:27 [debug] 10296#0: *18 find location: "/"
2007/10/11 19:46:27 [debug] 10296#0: *18 find location for "/index.php"
2007/10/11 19:46:27 [debug] 10296#0: *18 find location: ~ "\.php$"
2007/10/11 19:46:27 [debug] 10296#0: *18 using configuration "\.php$"
2007/10/11 19:46:27 [debug] 10296#0: *18 http cl:-1 max:1048576
2007/10/11 19:46:27 [debug] 10296#0: *18 generic phase: 2
2007/10/11 19:46:27 [debug] 10296#0: *18 post rewrite phase: 3
2007/10/11 19:46:27 [debug] 10296#0: *18 generic phase: 4
2007/10/11 19:46:27 [debug] 10296#0: *18 access phase: 5
2007/10/11 19:46:27 [debug] 10296#0: *18 access phase: 6
2007/10/11 19:46:27 [debug] 10296#0: *18 post access phase: 7
2007/10/11 19:46:27 [debug] 10296#0: *18 http init upstream, client timer: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 epoll add event: fd:8 op:3 
ev:80000005
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script var: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script var: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script var: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script var: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script var: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script var: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script var: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script var: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script copy: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http script var: ""
2007/10/11 19:46:27 [debug] 10296#0: *18 http cleanup add: 080CD4C8
2007/10/11 19:46:27 [debug] 10296#0: *18 get rr peer, try: 1
2007/10/11 19:46:27 [debug] 10296#0: *18 socket 12
2007/10/11 19:46:27 [debug] 10296#0: *18 epoll add connection: fd:12 
ev:80000005
2007/10/11 19:46:27 [debug] 10296#0: *18 connect to 
unix:/tmp/php-fcgi.sock, fd:12 #19
2007/10/11 19:46:27 [debug] 10296#0: *18 connected
2007/10/11 19:46:27 [debug] 10296#0: *18 http upstream connect: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 http upstream send request
2007/10/11 19:46:27 [debug] 10296#0: *18 chain writer buf fl:0 s:520
2007/10/11 19:46:27 [debug] 10296#0: *18 chain writer in: 080CD4D4
2007/10/11 19:46:27 [debug] 10296#0: *18 writev: 520
2007/10/11 19:46:27 [debug] 10296#0: *18 chain writer out: 00000000
2007/10/11 19:46:27 [debug] 10296#0: *18 event timer add: 12: 
180000:2404626224
2007/10/11 19:46:27 [debug] 10296#0: posted event 00000000
2007/10/11 19:46:27 [debug] 10296#0: worker cycle
2007/10/11 19:46:27 [debug] 10296#0: accept mutex locked
2007/10/11 19:46:27 [debug] 10296#0: epoll timer: 180000
2007/10/11 19:46:27 [debug] 10296#0: epoll: fd:8 ev:0004 d:080DE54D
2007/10/11 19:46:27 [debug] 10296#0: *18 post event 0810047C
2007/10/11 19:46:27 [debug] 10296#0: epoll: fd:12 ev:0005 d:080DE451
2007/10/11 19:46:27 [debug] 10296#0: *18 post event 080F33D8
2007/10/11 19:46:27 [debug] 10296#0: *18 post event 081003E0
2007/10/11 19:46:27 [debug] 10296#0: timer delta: 17
2007/10/11 19:46:27 [debug] 10296#0: posted events 081003E0
2007/10/11 19:46:27 [debug] 10296#0: posted event 081003E0
2007/10/11 19:46:27 [debug] 10296#0: *18 delete posted event 081003E0
2007/10/11 19:46:27 [debug] 10296#0: *18 http upstream dummy handler
2007/10/11 19:46:27 [debug] 10296#0: posted event 080F33D8
2007/10/11 19:46:27 [debug] 10296#0: *18 delete posted event 080F33D8
2007/10/11 19:46:27 [debug] 10296#0: *18 http upstream process header
2007/10/11 19:46:27 [debug] 10296#0: *18 malloc: 0810D318:32768
2007/10/11 19:46:27 [debug] 10296#0: *18 recv: fd:12 96 of 32768
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 01
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 06
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 00
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 01
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 00
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 41
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 07
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 00
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record length: 65
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi parser: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi header: "Status: 404"
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi parser: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi header: 
"Content-type: text/html"
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi parser: 1
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi header done
2007/10/11 19:46:27 [debug] 10296#0: *18 HTTP/1.1 404
Server: nginx/0.5.32
Date: Thu, 11 Oct 2007 13:46:27 GMT
Content-Type: text/html
Connection: close


2007/10/11 19:46:27 [debug] 10296#0: *18 write new buf t:1 f:0 080CD62C, 
pos 080CD62C, size: 119 file: 0, size: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 http write filter: l:0 f:0 s:119
2007/10/11 19:46:27 [debug] 10296#0: *18 http upstream process upstream
2007/10/11 19:46:27 [debug] 10296#0: *18 pipe read upstream: 1
2007/10/11 19:46:27 [debug] 10296#0: *18 pipe preread: 48
2007/10/11 19:46:27 [debug] 10296#0: *18 readv: 1:32672
2007/10/11 19:46:27 [debug] 10296#0: *18 pipe recv chain: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 pipe buf free s:0 t:1 f:0 
0810D318, pos 0810D348, size: 48 file: 0, size: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 input buf #0 0810D348
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 01
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 03
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 00
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 01
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 00
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 08
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 00
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record byte: 00
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi record length: 8
2007/10/11 19:46:27 [debug] 10296#0: *18 http fastcgi sent end request
2007/10/11 19:46:27 [debug] 10296#0: *18 input buf 0810D348 25
2007/10/11 19:46:27 [debug] 10296#0: *18 pipe write downstream: 1
2007/10/11 19:46:27 [debug] 10296#0: *18 pipe write downstream flush in
2007/10/11 19:46:27 [debug] 10296#0: *18 http output filter "/index.php?"
2007/10/11 19:46:27 [debug] 10296#0: *18 copy filter: "/index.php?"
2007/10/11 19:46:27 [debug] 10296#0: *18 http postpone filter 
"/index.php?" 080CD788
2007/10/11 19:46:27 [debug] 10296#0: *18 http postpone filter out 
"/index.php?"
2007/10/11 19:46:27 [debug] 10296#0: *18 write old buf t:1 f:0 080CD62C, 
pos 080CD62C, size: 119 file: 0, size: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 write new buf t:1 f:0 0810D318, 
pos 0810D348, size: 25 file: 0, size: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 http write filter: l:0 f:0 s:144
2007/10/11 19:46:27 [debug] 10296#0: *18 copy filter: 0 "/index.php?"
2007/10/11 19:46:27 [debug] 10296#0: *18 pipe write downstream done
2007/10/11 19:46:27 [debug] 10296#0: *18 event timer: 12, old: 
2404626224, new: 2404626241
2007/10/11 19:46:27 [debug] 10296#0: *18 http upstream exit: 00000000
2007/10/11 19:46:27 [debug] 10296#0: *18 finalize http upstream request: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 finalize http fastcgi request
2007/10/11 19:46:27 [debug] 10296#0: *18 free rr peer 1 0
2007/10/11 19:46:27 [debug] 10296#0: *18 close http upstream connection: 12
2007/10/11 19:46:27 [debug] 10296#0: *18 event timer del: 12: 2404626224
2007/10/11 19:46:27 [debug] 10296#0: *18 http upstream temp fd: -1
2007/10/11 19:46:27 [debug] 10296#0: *18 http output filter "/index.php?"
2007/10/11 19:46:27 [debug] 10296#0: *18 copy filter: "/index.php?"
2007/10/11 19:46:27 [debug] 10296#0: *18 http postpone filter 
"/index.php?" BFC423A8
2007/10/11 19:46:27 [debug] 10296#0: *18 http postpone filter out 
"/index.php?"
2007/10/11 19:46:27 [debug] 10296#0: *18 write old buf t:1 f:0 080CD62C, 
pos 080CD62C, size: 119 file: 0, size: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 write old buf t:1 f:0 0810D318, 
pos 0810D348, size: 25 file: 0, size: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 write new buf t:0 f:0 00000000, 
pos 00000000, size: 0 file: 0, size: 0
2007/10/11 19:46:27 [debug] 10296#0: *18 http write filter: l:1 f:0 s:144
2007/10/11 19:46:27 [debug] 10296#0: *18 http write filter limit 0
2007/10/11 19:46:27 [debug] 10296#0: *18 writev: 144
2007/10/11 19:46:27 [debug] 10296#0: *18 http write filter 00000000
2007/10/11 19:46:27 [debug] 10296#0: *18 copy filter: 0 "/index.php?"
2007/10/11 19:46:27 [debug] 10296#0: *18 http finalize request: 0, 
"/index.php?"
2007/10/11 19:46:27 [debug] 10296#0: *18 http close request
2007/10/11 19:46:27 [debug] 10296#0: *18 http log handler
2007/10/11 19:46:27 [debug] 10296#0: *18 run cleanup: 080CCFEC, fd:9
2007/10/11 19:46:27 [debug] 10296#0: *18 free: 0810D318
2007/10/11 19:46:27 [debug] 10296#0: *18 free: 080CCA60, unused: 495
2007/10/11 19:46:27 [debug] 10296#0: *18 close http connection: 8
2007/10/11 19:46:27 [debug] 10296#0: *18 delete posted event 0810047C
2007/10/11 19:46:27 [debug] 10296#0: *18 free: 080D1560
2007/10/11 19:46:27 [debug] 10296#0: *18 free: 080D12E0
2007/10/11 19:46:27 [debug] 10296#0: *18 free: 080C6C28, unused: 64