php效能分析之php-fpm的慢執行日誌slow log

眾所周知，mysql有slow query log，根據慢查詢日誌，我們可以知道那些sql語句有效能問題。作為mysql的好搭檔，php也有這樣的功能。如果你使用php-fpm來管理php的話，你可以通過如下選項開啟。

PHP 5.3.3 之前設定如下：

<value name=”request_slowlog_timeout”>5s</value>
<value name=”slowlog”>logs/php-fpm-slowlog.log</value>
PHP 5.3.3 之後設定以下如下：
request_slowlog_timeout = 5s
slowlog = /usr/local/php/log/php-fpm-slowlog.log

說明：

request_slowlog_timeout是指令碼超過多長時間 就可以記錄到記錄檔
slowlog 是記錄檔的路徑
開啟後，如果有指令碼執行超過指定的時間，就會在指定的記錄檔中寫入類似如下的資訊：

[19-Dec-2013 16:54:49] [pool www] pid 18575
script_filename = /home/admin/web/htdocs/sandbox_canglong/test/tt.php
[0x0000000003a00dc8] curl_exec() /home/admin/web/htdocs/sandbox_canglong/test/tt.php:2
[0x0000000003a00cd0] exfilter_curl_get() /home/admin/web/htdocs/sandbox_canglong/test/tt.php:6

日誌說明：

script_filename 是入口檔案

curl_exec() ： 說明是執行這個方法的時候超過執行時間的。
exfilter_curl_get() ：說明調用curl_exec()的方法是exfilter_curl_get() 。
每行冒號後面的數字是行號。

開啟後，在錯誤記錄檔檔案中也有相關記錄。如下：

[19-Dec-2013 15:55:37] WARNING: [pool www] child 18575, script ‘/home/admin/web/htdocs/sandbox_canglong/test/tt.php’ (request: “GET /test/tt.php”) executing too slow (1.006222 sec), logging
[19-Dec-2013 15:55:37] NOTICE: child 18575 stopped for tracing
[19-Dec-2013 15:55:37] NOTICE: about to trace 18575
[19-Dec-2013 15:55:37] NOTICE: finished trace of 18575