php-fpm7.0 慢查詢設定及說明

環境說明

[email protected]:/home/tb# cat /etc/issue
Ubuntu 16.04.2 LTS n l
[email protected]:/home/tb# php -v
PHP 7.0.15-0ubuntu0.16.04.4 (cli) ( NTS )
Copyright (c) 1997-2017 The PHP Group
Zend Engine v3.0.0, Copyright (c) 1998-2017 Zend Technologies
    with Zend OPcache v7.0.15-0ubuntu0.16.04.4, Copyright (c) 1999-2017, by Zend Technologies

檢視php-fpm設定路徑

[email protected]:/home/tb# ps -ef |grep fpm
root      1642     1  0 10:17 ?        00:00:01 php-fpm: master process (/etc/php/7.0/fpm/php-fpm.conf)
www-data  3685  1642  0 17:14 ?        00:00:04 php-fpm: pool www
www-data  3686  1642  0 17:14 ?        00:00:03 php-fpm: pool www
www-data  3808  1642  0 17:43 ?        00:00:03 php-fpm: pool www
root      3930  2208  0 18:10 pts/0    00:00:00 grep --color=auto fpm
[email protected]:/home/tb#

php-fpm.conf的最後一行為

include=/etc/php/7.0/fpm/pool.d/*.conf

那我們去那裡改www.conf，檢視關於慢查詢的介紹

314 ; The log file for slow requests
315 ; Default Value: not set
316 ; Note: slowlog is mandatory if request_slowlog_timeout is set
317 ;slowlog = log/$pool.log.slow
318
319 ; The timeout for serving a single request after which a PHP backtrace will be
320 ; dumped to the 'slowlog' file. A value of '0s' means 'off'.
321 ; Available units: s(econds)(default), m(inutes), h(ours), or d(ays)
322 ; Default Value: 0
323 ;request_slowlog_timeout = 0

新增兩行設定

slowlog=/var/log/php7.0/fpm/slow.log
request_slowlog_timeout=1s

重新啟動fpm，同時注意目錄許可權等一般問題

service php7.0-fpm reload

檢視log回顯結果

[03-Jan-2018 18:48:53]  [pool www] pid 4934
script_filename = /usr/share/nginx/ testapp/index.php
[0x00007fb626213520] session_start() /usr/share/nginx/ testapp/app/models/user_model.php:11
[0x00007fb6262134a0] __construct() /usr/share/nginx/ci_2.2.0/core/Loader.php:303
[0x00007fb626213390] model() /usr/share/nginx/ testapp/app/core/my_Controller.php:39
[0x00007fb6262132e0] __construct() /usr/share/nginx/ testapp/app/controllers/home.php:8
[0x00007fb626213270] __construct() /usr/share/nginx/ci_2.2.0/core/CodeIgniter.php:308
[0x00007fb6262130e0] [INCLUDE_OR_EVAL]() /usr/share/nginx/ testapp/index.php:325
[03-Jan-2018 18:48:53]  [pool www] pid 4931
script_filename = /usr/share/nginx/ testapp/index.php
[0x00007fb626213520] session_start() /usr/share/nginx/ testapp/app/models/user_model.php:11
[0x00007fb6262134a0] __construct() /usr/share/nginx/ci_2.2.0/core/Loader.php:303
[0x00007fb626213390] model() /usr/share/nginx/ testapp/app/core/my_Controller.php:39
[0x00007fb6262132e0] __construct() /usr/share/nginx/ testapp/app/controllers/home.php:8
[0x00007fb626213270] __construct() /usr/share/nginx/ci_2.2.0/core/CodeIgniter.php:308
[0x00007fb6262130e0] [INCLUDE_OR_EVAL]() /usr/share/nginx/ testapp/index.php:325

關於log的說明

[03-Jan-2018 18:48:53]  [pool www] pid 4931

這個沒啥解釋，時間進程id

script_filename = /usr/share/nginx/ testapp/index.php

執行指令碼名稱，php web應用都為單入口

[0x00007fb626213520] session_start() /usr/share/nginx/ testapp/app/models/user_model.php:11

這個第三行比較重要：是堆疊頂部資訊（is the top of the stack trace），他說明指出了超出了閾值的當前執行的方法的函數呼叫是哪個，以及具體的檔案及程式碼行數

剩下的其他部分就是呼叫的順序（從下往上，最終導致變慢超時的結果）

其他說明

如果發現第三行是以類似curl_exec()，比如：這一般是網路io占用了時間，如果是必須請求第三方，那麼。。忍吧。

[0x00007fb6262136f0] curl_exec() /usr/share/nginx/

同樣，如果有mysql_query之類的，也是由於sql 慢查詢導致的