善用php-fpm的慢执行日志slow log，分析php性能问题

旧城等待， 2023-10-17 07:30 9阅读 0赞

来源:http://www.bo56.com/%E5%96%84%E7%94%A8php-fpm%E7%9A%84%E6%85%A2%E6%89%A7%E8%A1%8C%E6%97%A5%E5%BF%97slow-log%EF%BC%8C%E5%88%86%E6%9E%90php%E6%80%A7%E8%83%BD%E9%97%AE%E9%A2%98/

众所周知，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/web/htdocs/sandbox\_canglong/test/tt.php  
> \[0x0000000003a00dc8\] curl\_exec() /home/web/htdocs/sandbox\_canglong/test/tt.php:2  
> \[0x0000000003a00cd0\] exfilter\_curl\_get() /home/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/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