Welcome! Log In Create A New Profile

Advanced

How to read the php-fpm slow-log?

Posted by Rainer Duffner 
Rainer Duffner
How to read the php-fpm slow-log?
March 22, 2012 05:32PM
Hi,

I run a typo3 on php5.3.10, freebsd.82-amd64 and nginx 1.1.x (no apache).

Sometimes, pages are very slow to load.
I've setup the slow-log (10s threshold) and I get this sometimes:

cript_filename = /home/USER/FTPROOT/htdocs/index.php
[0x0000000806f8c248] get() /home/USER/FTPROOT/typo3_src-4.6.3/t3lib/cache/backend/class.t3lib_cache_backend_memcachedbackend.php:335
[0x0000000806f8bcf0] findIdentifiersByTag() /home/USER/FTPROOT/typo3_src-4.6.3/t3lib/cache/backend/class.t3lib_cache_backend_memcachedbackend.php:380
[0x0000000806f8ad50] addIdentifierToTags() /home/USER/FTPROOT/typo3_src-4.6.3/t3lib/cache/backend/class.t3lib_cache_backend_memcachedbackend.php:266
[0x0000000806f8a7b8] set() /home/USER/FTPROOT/typo3_src-4.6.3/t3lib/cache/frontend/class.t3lib_cache_frontend_variablefrontend.php:88
[0x0000000806f8a4d8] set() /home/USER/FTPROOT/typo3_src-4.6.3/t3lib/class.t3lib_page.php:961
[0x0000000806f7ef88] storeHash() /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_menu..php:845
[0x0000000806f7db58] makeMenu() /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_menu..php:1317
[0x0000000806f7d470] subMenu() /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_menu..php:1938
[0x0000000806f77da0] extProc_afterLinking() /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_menu..php:1820
[0x0000000806f76d38] writeMenu() /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/content/class.tslib_content_hierarchicalmenu.php:72
[0x0000000806f75970] render() /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_content.php:601
[0x0000000806f75528] cObjGetSingle() /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_content.php:548
[0x0000000806f74eb0] cObjGet() /home/USER/FTPROOT/typo3_src-USER4.6.3/typo3/sysext/cms/tslib/content/class.tslib_content_contentobjectarray.php:48
[0x0000000806f73ae8] render() /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_content.php:601
[0x0000000806f736a0] cObjGetSingle() /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_content.php:548
[0x0000000806f73028] cObjGet() /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/content/class.tslib_content_contentobjectarray.php:48
[0x0000000806f71c60] render() /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_content.php:601
[0x0000000806f71160] cObjGetSingle() /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/fluid/Classes/ViewHelpers/CObjectViewHelper.php:119
[0x00007fffffffacb0] render() unknown:0
[0x0000000806f70d20] call_user_func_array() /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/fluid/Classes/Core/ViewHelper/AbstractViewHelper.php:251


Can someone explain what this means and how I find out what is actually slowing the request down?

I tried ktracing the requests, but this was not really successful - and the server is busy now, so ktracing is just generating lots of noise which I can hardly wade through...



Thanks in advance.


Rainer
Anatoly Pashin
Re: How to read the php-fpm slow-log?
March 22, 2012 07:12PM
[id] functionName() /path/to/script.php:line
You got here functions that ran more than 10s as you set it.

May be it's not php's slow. Check I/O wait, php accelerator, mysql/whatever
database.
And what is config of your hardware?

2012/3/23 Rainer Duffner <rainer@ultra-secure.de>

> Hi,
>
> I run a typo3 on php5.3.10, freebsd.82-amd64 and nginx 1.1.x (no apache).
>
> Sometimes, pages are very slow to load.
> I've setup the slow-log (10s threshold) and I get this sometimes:
>
> cript_filename = /home/USER/FTPROOT/htdocs/index.php
> [0x0000000806f8c248] get()
> /home/USER/FTPROOT/typo3_src-4.6.3/t3lib/cache/backend/class.t3lib_cache_backend_memcachedbackend.php:335
> [0x0000000806f8bcf0] findIdentifiersByTag()
> /home/USER/FTPROOT/typo3_src-4.6.3/t3lib/cache/backend/class.t3lib_cache_backend_memcachedbackend.php:380
> [0x0000000806f8ad50] addIdentifierToTags()
> /home/USER/FTPROOT/typo3_src-4.6.3/t3lib/cache/backend/class.t3lib_cache_backend_memcachedbackend.php:266
> [0x0000000806f8a7b8] set()
> /home/USER/FTPROOT/typo3_src-4.6.3/t3lib/cache/frontend/class.t3lib_cache_frontend_variablefrontend.php:88
> [0x0000000806f8a4d8] set()
> /home/USER/FTPROOT/typo3_src-4.6.3/t3lib/class.t3lib_page.php:961
> [0x0000000806f7ef88] storeHash()
> /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_menu.php:845
> [0x0000000806f7db58] makeMenu()
> /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_menu.php:1317
> [0x0000000806f7d470] subMenu()
> /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_menu.php:1938
> [0x0000000806f77da0] extProc_afterLinking()
> /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_menu.php:1820
> [0x0000000806f76d38] writeMenu()
> /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/content/class.tslib_content_hierarchicalmenu.php:72
> [0x0000000806f75970] render()
> /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_content.php:601
> [0x0000000806f75528] cObjGetSingle()
> /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_content.php:548
> [0x0000000806f74eb0] cObjGet()
> /home/USER/FTPROOT/typo3_src-USER4.6.3/typo3/sysext/cms/tslib/content/class.tslib_content_contentobjectarray.php:48
> [0x0000000806f73ae8] render()
> /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_content.php:601
> [0x0000000806f736a0] cObjGetSingle()
> /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_content.php:548
> [0x0000000806f73028] cObjGet()
> /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/content/class.tslib_content_contentobjectarray.php:48
> [0x0000000806f71c60] render()
> /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/cms/tslib/class.tslib_content.php:601
> [0x0000000806f71160] cObjGetSingle()
> /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/fluid/Classes/ViewHelpers/CObjectViewHelper.php:119
> [0x00007fffffffacb0] render() unknown:0
> [0x0000000806f70d20] call_user_func_array()
> /home/USER/FTPROOT/typo3_src-4.6.3/typo3/sysext/fluid/Classes/Core/ViewHelper/AbstractViewHelper.php:251
>
>
> Can someone explain what this means and how I find out what is actually
> slowing the request down?
>
> I tried ktracing the requests, but this was not really successful - and
> the server is busy now, so ktracing is just generating lots of noise which
> I can hardly wade through...
>
>
>
> Thanks in advance.
>
>
> Rainer
Sorry, only registered users may post in this forum.

Click here to login

Online Users

Guests: 264
Record Number of Users: 8 on April 13, 2023
Record Number of Guests: 421 on December 02, 2018
Powered by nginx      Powered by FreeBSD      PHP Powered      Powered by MariaDB      ipv6 ready