Dear Nginx/Php-fpm users,
I installed a new VPS for testing pressflow with nginx/php-fpm ,but I seem to have somekind of unkown issue.
When I browse around on my website, installing a small modules or going to a simple page I sometimes get a nginx "504 Gateway Time-out"
After checking all the logs it seems the main cause is php-fpm that "execution timed", as i have given this option in my php-fpm config and set to 120s (request_terminate_timeout = 120s)
This all woudn't be strainge when i was running a heavy script for longer then 120 seconds, but I get this error also when i just browse around my site.. it looks like the unix socket stays open sometimes and thinks it's still executing? Then I browse around on my website and it uses that socket again and then notice it was executing to long (when it fact i'm just using it..) and i get a "504 Gateway Time-out" for simply browsing on my homepage ,flushing cache, or checking drupal log..
I hope somebody knows what the problem could be? Or a tip/advice?
VPS with 2GB RAM and 3.3 GHz
installed
nginx version: nginx/1.2.0
PHP 5.3.13 (fpm-fcgi) (built: May 8 2012 20:28:03)
Copyright (c) 1997-2009 The PHP Group
Zend Engine v2.3.0, Copyright (c) 1998-2012 Zend Technologies
I used this great php-fpm configuration:
https://github.com/perusio/php-fpm-example-config
and drupal6 boost nginx super settings from:
https://github.com/perusio/drupal-with-nginx
php-fmp log:
[10-May-2012 13:36:27] WARNING: [pool www0] child 21597, script '/var/www/sites/domain.com/index.php' (request: "GET /index.php") executing too slow (1490.017020 sec), logging
[10-May-2012 13:36:27] NOTICE: child 21597 stopped for tracing
[10-May-2012 13:36:27] NOTICE: about to trace 21597
[10-May-2012 13:36:27] NOTICE: finished trace of 21597
[10-May-2012 13:37:07] WARNING: [pool www0] child 21597, script '/var/www/sites/domain.com/index.php' (request: "GET /index.php") execution timed out (1530.017840 sec), terminating
[10-May-2012 13:37:07] WARNING: [pool www0] child 21597 exited on signal 15 (SIGTERM) after 1539.897944 seconds from start
[10-May-2012 13:37:07] NOTICE: [pool www0] child 22006 started
[10-May-2012 13:37:27] WARNING: [pool www1] child 21604, script '/var/www/sites/domain.com/index.php' (request: "GET /index.php") executing too slow (1553.880410 sec), logging
[10-May-2012 13:37:27] NOTICE: child 21604 stopped for tracing
[10-May-2012 13:37:27] NOTICE: about to trace 21604
[10-May-2012 13:37:27] NOTICE: finished trace of 21604
[10-May-2012 13:38:35] WARNING: [pool www0] child 21598, script '/var/www/sites/domain.com/index.php' (request: "POST /index.php") executing too slow (76.523173 sec), logging
[10-May-2012 13:38:35] NOTICE: child 21598 stopped for tracing
[10-May-2012 13:38:35] NOTICE: about to trace 21598
[10-May-2012 13:38:35] NOTICE: finished trace of 21598
[10-May-2012 13:40:08] WARNING: [pool www0] child 21598, script '/var/www/sites/domain.com/index.php' (request: "GET /index.php") execution timed out (170.236559 sec), terminating
[10-May-2012 13:40:08] WARNING: [pool www0] child 21598 exited on signal 15 (SIGTERM) after 1721.083711 seconds from start
[10-May-2012 13:40:08] NOTICE: [pool www0] child 22035 started
[10-May-2012 19:37:19] WARNING: [pool www0] child 31913, script '/var/www/sites/domain.com/index.php' (request: "GET /index.php") executing too slow (33.990649 sec), logging
[10-May-2012 19:37:19] NOTICE: child 31913 stopped for tracing
[10-May-2012 19:37:19] NOTICE: about to trace 31913
[10-May-2012 19:37:19] NOTICE: finished trace of 31913
[10-May-2012 19:38:32] WARNING: [pool www1] child 31912, script '/var/www/sites/domain.com/index.php' (request: "GET /index.php") executing too slow (116.895645 sec), logging
[10-May-2012 19:38:32] NOTICE: child 31912 stopped for tracing
[10-May-2012 19:38:32] NOTICE: about to trace 31912
[10-May-2012 19:38:32] NOTICE: finished trace of 31912
[10-May-2012 20:05:48] WARNING: [pool www1] child 31912, script '/var/www/sites/domain.com/index.php' (request: "GET /index.php") execution timed out (1752.894668 sec), terminating
[10-May-2012 20:05:48] WARNING: [pool www1] child 31912 exited on signal 15 (SIGTERM) after 2940.131743 seconds from start
[10-May-2012 20:05:48] NOTICE: [pool www1] child 32327 started
[10-May-2012 20:05:58] WARNING: [pool www0] child 31913, script '/var/www/sites/domain.com/index.php' (request: "GET /index.php") execution timed out (1752.661918 sec), terminating
[10-May-2012 20:05:58] WARNING: [pool www0] child 31913 exited on signal 15 (SIGTERM) after 2943.386227 seconds from start
[10-May-2012 20:05:58] NOTICE: [pool www0] child 32330 started
Comments
Almost same problem (502 bad
Almost same problem (502 bad gateway) with this config. With standart php-fpm it was fine.
502/504 'Bad Gateway'
This error [502/504] usually means a Nginx configuration problem.
Means that Nginx is having issues passing events [communicating] to PHP-FPM.
Here is the real 'clue': ""GET /index.php") execution timed out"
--
Linux: Web Developer
Peter Bowey Computer Solutions
Australia: GMT+9:30
(¯`·..·[ Peter ]·..·´¯)
Do you have tcp sockets or
UNIX sockets enabled. I suppose the problem is there. The default branch is
unixfor UNIX sockets. Checkout thetcpbranch for TCP sockets.But before I used a unix
But before I used a unix sockets too (fastcgi_pass unix:/tmp/php5-fpm.sock;)
I think there is something with timeouts. Maybe:
request_terminate_timeout = 120s
request_slowlog_timeout = 5s
When I comment those lines and rise pm.max_children = from 6 to 20 (I have 16gb memory) and pm.max_spare_servers = (from 5 to 15) the things going better.
In nginx logs I found:
582 recv() failed (104: Connection reset by peer) while reading response header from upstream,
*580 no live upstreams while connecting to upstream,
Enable the debug
log level on the php-fpm config:
log_level = debugrestart php-fpm and check the logs.
I need to check again but I
I need to check again but I did not have any timeouts anymore when i changed my php.ini max_execution_time max_input_time to a higher number.
But i did not have so much time to test this well.
sometime ago, I was in a
sometime ago, I was in a similar issue. It was my kernel max socket connections value, it was too slow to manage my connections. You can check it by a simple test with a benchmarking tool like ab:
ab -kc 100 -n 1000 http://www.server.com/test.php
If you arise the number of concurrent connections, probably you'll see on the nginx logs your connection errors.
Just a reference (in spanish) of what I'm talking about: http://www.japicoder.es/articulos/nginx-php-fpm-sockets-unix-y-el-error-...
i now started from scratch on
i now started from scratch on a new machine.. and i still have this issue, so i mean it didn't had to do anything with the machine, so there must be something wrong with my setup or/and the php-fpm/nginx config. I'll run php-fpm in debug log now log_level = debug to see what will happen and i'll report here.
@superfedya did you solve it?
Ok so here the debug log!
I hope somebody with experience can see what happends here?
I checked the debug log, but well i don't really understand it well..
What i see is that the "currently 1 active children" so... that means that the pool stays active after loading a php file.
I hope somebody see what is wrong with it:
[19-Jul-2012 20:59:49] NOTICE: Terminating ...[19-Jul-2012 20:59:49] NOTICE: exiting, bye-bye!
[19-Jul-2012 20:59:50.093674] DEBUG: pid 9336, fpm_scoreboard_init_main(), line 40: got clock tick '100'
[19-Jul-2012 20:59:50.094530] DEBUG: pid 9336, fpm_event_init_main(), line 331: event module is epoll and 37 fds have been reserved
[19-Jul-2012 20:59:50.094640] NOTICE: pid 9336, fpm_init(), line 80: fpm is running, pid 9336
[19-Jul-2012 20:59:50.095584] DEBUG: pid 9336, fpm_children_make(), line 421: [pool www2] child 9337 started
[19-Jul-2012 20:59:50.096443] DEBUG: pid 9336, fpm_children_make(), line 421: [pool www2] child 9338 started
[19-Jul-2012 20:59:50.097309] DEBUG: pid 9336, fpm_children_make(), line 421: [pool www2] child 9339 started
[19-Jul-2012 20:59:50.098181] DEBUG: pid 9336, fpm_children_make(), line 421: [pool www1] child 9340 started
[19-Jul-2012 20:59:50.099084] DEBUG: pid 9336, fpm_children_make(), line 421: [pool www1] child 9341 started
[19-Jul-2012 20:59:50.099972] DEBUG: pid 9336, fpm_children_make(), line 421: [pool www1] child 9342 started
[19-Jul-2012 20:59:50.100857] DEBUG: pid 9336, fpm_children_make(), line 421: [pool www0] child 9343 started
[19-Jul-2012 20:59:50.101738] DEBUG: pid 9336, fpm_children_make(), line 421: [pool www0] child 9344 started
[19-Jul-2012 20:59:50.102625] DEBUG: pid 9336, fpm_children_make(), line 421: [pool www0] child 9345 started
[19-Jul-2012 20:59:50.102667] DEBUG: pid 9336, fpm_pctl_heartbeat(), line 460: heartbeat have been set up with a timeout of 6666ms
[19-Jul-2012 20:59:50.102678] DEBUG: pid 9336, fpm_event_loop(), line 360: 21480 bytes have been reserved in SHM
[19-Jul-2012 20:59:50.102686] NOTICE: pid 9336, fpm_event_loop(), line 361: ready to handle connections
[19-Jul-2012 20:59:51.103481] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:51.103621] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:51.103655] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:52.103499] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:52.103602] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:52.103631] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:53.103519] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:53.103627] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:53.103656] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:54.103536] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:54.103637] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:54.103665] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:55.103553] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:55.103657] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:55.103686] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:56.103569] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:56.103714] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:56.103745] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:57.103587] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:57.103690] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:57.103718] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:58.103603] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:58.103709] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:58.103738] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:59.103621] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:59.103724] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 20:59:59.103753] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:00.103637] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:00.103740] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:00.103768] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:01.103653] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:01.103758] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:01.103786] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:02.103671] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:02.103772] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:02.103800] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:03.103688] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:03.103830] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:03.103860] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:04.103704] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:04.103806] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:04.103835] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:05.103722] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:05.103826] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:05.103853] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:06.103737] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:06.103839] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:06.103867] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:07.103755] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:07.103856] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:07.103885] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:08.103776] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:08.103890] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:08.103920] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:09.103792] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:09.103895] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:09.103924] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:10.103803] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:10.103941] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:10.103970] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:11.103825] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:11.103929] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:11.103958] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:12.103836] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:12.103938] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:12.103969] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:13.103859] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:13.103970] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:13.103998] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:14.103874] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:14.103977] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:14.104005] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:15.103893] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:15.103996] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:15.104024] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:16.103910] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:16.104013] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:16.104040] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:17.103924] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:17.104082] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:17.104117] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 2 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:17.105955] DEBUG: pid 9336, fpm_children_make(), line 421: [pool www0] child 9351 started
[19-Jul-2012 21:00:17.106049] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 428: [pool www0] 1 child(ren) have been created dynamically
[19-Jul-2012 21:00:18.103941] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:18.104049] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:18.104079] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 2
[19-Jul-2012 21:00:19.103956] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:19.104061] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:19.104089] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:20.103975] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:20.104076] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:20.104105] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:21.103990] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:21.104094] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:21.104123] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:22.104007] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:22.104116] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:22.104145] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:23.104025] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:23.104134] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:23.104236] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:24.104041] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:24.104144] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:24.104172] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:25.104057] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:25.104161] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:25.104189] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:26.104074] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:26.104177] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:26.104205] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:27.105091] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:27.105203] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:27.105245] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:28.106108] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:28.106215] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:28.106258] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:29.107127] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:29.107243] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:29.107273] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:30.107140] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:30.107255] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:30.107326] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:31.108159] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:31.108274] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:31.108303] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:32.109177] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:32.109298] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:32.109326] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:33.110194] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:33.110310] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:33.110339] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:34.111215] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:34.111346] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 2 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:34.113399] DEBUG: pid 9336, fpm_children_make(), line 421: [pool www1] child 9352 started
[19-Jul-2012 21:00:34.113492] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 428: [pool www1] 1 child(ren) have been created dynamically
[19-Jul-2012 21:00:34.113528] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:35.112242] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:35.112351] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 2
[19-Jul-2012 21:00:35.112381] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:36.113255] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:36.113358] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:36.113387] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:37.113272] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:37.113434] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:37.113466] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:38.113274] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:38.113350] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:38.113368] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:39.113295] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:39.113744] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:39.113778] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:40.113306] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:40.113420] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:40.113454] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:41.113322] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:41.113436] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:41.113470] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:42.113348] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:42.113469] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:42.113502] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:43.114330] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:43.114416] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:43.114448] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:44.114386] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:44.114878] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:44.114915] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:45.114398] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:45.114507] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:45.114538] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:46.114409] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:46.114513] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:46.114542] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:47.114433] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:47.114541] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:47.114570] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:48.114444] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:48.114552] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:48.114582] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:49.114463] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:49.114575] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:49.114604] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:50.114471] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:50.114576] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:50.114605] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:51.114494] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:51.114601] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:51.114630] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:52.114513] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:52.114618] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:52.114646] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:53.114528] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:53.114634] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:53.114664] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:54.114726] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:54.114844] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:54.114875] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:55.115560] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:55.115666] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:55.115696] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:56.115581] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:56.115685] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:56.115714] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:57.115598] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:57.115703] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:57.115733] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:58.115616] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:58.115725] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:58.115754] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:59.115629] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:00:59.115908] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:00:59.115950] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:00.115649] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:00.115755] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:00.115784] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:01.115665] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:01.115771] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:01.115800] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:02.115685] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:02.115799] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:02.115831] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:03.115701] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:03.115809] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:03.115839] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:04.115716] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:04.115822] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:04.115851] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:05.115733] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:05.115840] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:05.115868] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:06.115750] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:06.115855] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:06.115886] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:07.115767] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:07.115877] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:07.115906] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:08.126589] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:08.126709] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:08.126746] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:09.126801] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:09.126912] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:09.126944] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:10.126812] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:10.126917] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:10.126946] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:11.126835] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:11.126941] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:11.126970] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:12.126852] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:12.126967] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:12.126997] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:13.126868] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:13.126975] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:13.127003] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:14.126885] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:14.126990] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:14.127019] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:15.126901] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:15.127008] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:15.127037] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:16.126918] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:16.127023] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:16.127052] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:17.127324] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:17.127450] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:17.127481] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:18.127952] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:18.128058] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:18.128087] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:19.127969] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:19.128074] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:19.128103] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:20.127985] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:20.128089] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:20.128118] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:21.128004] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:21.128112] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:21.128142] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:22.128161] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:22.128291] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:22.128322] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:23.129036] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:23.129141] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:23.129171] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:24.129053] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:24.129162] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:24.129192] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:25.130070] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:25.130177] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:25.130205] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:26.131087] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:26.131193] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:26.131234] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:27.132107] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:27.132218] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:27.132260] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:28.133120] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:28.133237] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:28.133267] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:29.134137] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:29.134256] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:29.134286] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:30.134149] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:30.134267] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:30.134298] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:31.135171] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:31.135293] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:31.135323] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:32.136192] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:32.136318] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:32.136348] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:33.137203] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:33.137324] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:33.137352] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:34.138233] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:34.138338] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:34.138366] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:35.139250] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:35.139355] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:35.139383] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:36.140271] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:36.140376] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:36.140405] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:37.140287] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:37.140397] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:37.140427] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:39.141272] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:39.141395] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:39.141430] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:40.140316] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:40.140422] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:40.140452] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:41.140350] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:41.140456] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:41.140486] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:42.140357] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:42.140462] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:42.140490] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:43.140372] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:43.140480] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:43.140509] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:44.140407] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:44.140521] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:44.140551] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:45.140406] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:45.140512] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:45.140541] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:46.140418] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:46.140524] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:46.140553] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:47.140457] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:47.140568] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:47.140598] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:49.140707] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:49.140827] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:49.140859] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:50.141490] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:50.141597] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:50.141626] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:51.141510] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:51.141616] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:51.141645] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:52.141524] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:52.141630] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:52.141659] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:53.141543] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:53.141649] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:53.141678] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:54.141557] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:54.141668] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:54.141698] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:55.141579] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:55.141685] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:55.141714] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:56.141593] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:56.141698] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:56.141726] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:57.141611] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:57.141721] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:57.141749] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:58.141629] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:58.141734] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:58.141762] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:59.141745] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:01:59.141873] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:01:59.141904] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:00.142660] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:00.142766] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:00.142795] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:01.142680] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:01.142785] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:01.142814] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:02.142698] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:02.142805] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:02.142875] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:03.142713] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:03.142818] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:03.142847] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:04.142726] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:04.142846] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:04.142877] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:05.142748] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:05.142855] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:05.142884] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:06.142765] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:06.142873] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:06.142902] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:07.142782] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:07.142886] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:07.142916] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:08.142797] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:08.142904] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:08.142933] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:09.142965] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:09.143091] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:09.143166] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:10.143832] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:10.143939] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:10.143969] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:11.143846] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:11.143953] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:11.143982] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:12.143862] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:12.143967] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:12.143996] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:13.143883] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:13.143997] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:13.144025] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:14.143892] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:14.144004] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:14.144034] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:15.143914] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:15.144021] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:15.144050] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:16.143932] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:16.144037] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:16.144108] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:17.143946] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:17.144052] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:17.144082] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:18.143961] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:18.144076] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:18.144107] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:19.143985] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:19.144094] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:19.144124] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:20.143999] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:20.144105] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:20.144135] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:21.144016] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:21.144123] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:21.144153] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:22.144033] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:22.144137] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:22.144167] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:23.144050] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:23.144339] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:23.144411] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:23.429099] WARNING: pid 9336, fpm_request_check_timed_out(), line 271: [pool www0] child 9343, script '/var/www/drupal/index.php' (request: "POST /index.php") executing too slow (127.196943 sec), logging
[19-Jul-2012 21:02:23.430218] DEBUG: pid 9336, fpm_got_signal(), line 72: received SIGCHLD
[19-Jul-2012 21:02:23.430266] NOTICE: pid 9336, fpm_children_bury(), line 227: child 9343 stopped for tracing
[19-Jul-2012 21:02:23.430291] NOTICE: pid 9336, fpm_php_trace(), line 142: about to trace 9343
[19-Jul-2012 21:02:23.431378] NOTICE: pid 9336, fpm_php_trace(), line 170: finished trace of 9343
[19-Jul-2012 21:02:23.431419] DEBUG: pid 9336, fpm_event_loop(), line 409: event module triggered 1 events
[19-Jul-2012 21:02:24.145065] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:24.145174] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:24.145207] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:25.146080] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:25.146191] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:25.146234] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:26.147101] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:26.147207] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:26.147250] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:27.148109] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:27.148214] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:27.148256] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:28.149130] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:28.149251] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:28.149281] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:29.150152] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:29.150325] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:29.150359] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:30.096188] WARNING: pid 9336, fpm_request_check_timed_out(), line 281: [pool www0] child 9343, script '/var/www/drupal/index.php' (request: "POST /index.php") execution timed out (133.864056 sec), terminating
[19-Jul-2012 21:02:30.103586] DEBUG: pid 9336, fpm_event_loop(), line 409: event module triggered 2 events
[19-Jul-2012 21:02:30.103694] DEBUG: pid 9336, fpm_got_signal(), line 72: received SIGCHLD
[19-Jul-2012 21:02:30.103797] WARNING: pid 9336, fpm_children_bury(), line 252: [pool www0] child 9343 exited on signal 15 (SIGTERM) after 160.002952 seconds from start
[19-Jul-2012 21:02:30.106186] NOTICE: pid 9336, fpm_children_make(), line 421: [pool www0] child 9497 started
[19-Jul-2012 21:02:30.106309] DEBUG: pid 9336, fpm_event_loop(), line 409: event module triggered 1 events
[19-Jul-2012 21:02:30.151179] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:30.151299] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:30.151331] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 4 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:31.152183] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:31.152315] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:31.152350] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 4 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:32.153200] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:32.153320] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:32.153349] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 4 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:33.154232] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:33.154337] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:33.154367] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 4 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:34.155245] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:34.155352] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:34.155381] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 4 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:35.156263] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:35.156376] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:35.156406] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 4 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:36.156278] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:36.156384] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:36.156414] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 4 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:36.762319] WARNING: pid 9336, fpm_request_check_timed_out(), line 271: [pool www1] child 9340, script '/var/www/drupal/index.php' (request: "POST /index.php") executing too slow (123.336108 sec), logging
[19-Jul-2012 21:02:36.762463] DEBUG: pid 9336, fpm_got_signal(), line 72: received SIGCHLD
[19-Jul-2012 21:02:36.762501] NOTICE: pid 9336, fpm_children_bury(), line 227: child 9340 stopped for tracing
[19-Jul-2012 21:02:36.762525] NOTICE: pid 9336, fpm_php_trace(), line 142: about to trace 9340
[19-Jul-2012 21:02:36.763321] NOTICE: pid 9336, fpm_php_trace(), line 170: finished trace of 9340
[19-Jul-2012 21:02:36.763364] DEBUG: pid 9336, fpm_event_loop(), line 409: event module triggered 1 events
[19-Jul-2012 21:02:37.156293] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:37.156398] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:37.156427] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 4 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:38.156294] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:38.156398] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:38.156428] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 4 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:39.156314] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:39.156426] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:39.156460] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 4 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:40.156343] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:40.156461] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:40.156546] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 4 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:41.156351] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:41.156464] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:41.156493] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 4 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:42.156370] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:42.156476] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:42.156504] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 4 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:43.156385] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www2] currently 0 active children, 3 spare children, 3 running children. Spawning rate 1
[19-Jul-2012 21:02:43.156493] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www1] currently 1 active children, 3 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:43.156522] DEBUG: pid 9336, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www0] currently 0 active children, 4 spare children, 4 running children. Spawning rate 1
[19-Jul-2012 21:02:43.428415] WARNING: pid 9336, fpm_request_check_timed_out(), line 281: [pool www1] child 9340, script '/var/www/drupal/index.php' (request: "POST /index.php") execution timed out (130.002215 sec), terminating
[19-Jul-2012 21:02:43.434909] DEBUG: pid 9336, fpm_event_loop(), line 409: event module triggered 2 events
[19-Jul-2012 21:02:43.435045] DEBUG: pid 9336, fpm_got_signal(), line 72: received SIGCHLD
[19-Jul-2012 21:02:43.435137] WARNING: pid 9336, fpm_children_bury(), line 252: [pool www1] child 9340 exited on signal 15 (SIGTERM) after 173.336968 seconds from start
[19-Jul-2012 21:02:43.437163] NOTICE: pid 9336, fpm_children_make(), line 421: [pool www1] child 9505 started
[19-Jul-2012 21:02:43.437284] DEBUG: pid 9336, fpm_event_loop(), line 409: event module triggered 1 events
Seems a bit strange
at fist sight that you're POSTing to
/index.php. What's the request you're executing exactly? Does the site works with GET requests?Do you mean this?
Not really sure what you're saying.. but i also have this in my log:
[19-Jul-2012 12:31:25] WARNING: [pool www0] child 18721, script '/var/www/drupal/index.php' (request: "GET /index.php") executing too slow (11.227226 sec), logging
[19-Jul-2012 12:31:25] NOTICE: child 18721 stopped for tracing
[19-Jul-2012 12:31:25] NOTICE: about to trace 18721
[19-Jul-2012 12:31:25] NOTICE: finished trace of 18721
[19-Jul-2012 19:12:35] WARNING: [pool www0] child 26817, script '/var/www/drupal/index.php' (request: "GET /index.php") executing too slow (6.795049 sec), logging
[19-Jul-2012 19:12:35] NOTICE: child 26817 stopped for tracing
[19-Jul-2012 19:12:35] NOTICE: about to trace 26817
[19-Jul-2012 19:12:35] NOTICE: finished trace of 26817
[19-Jul-2012 19:15:55] WARNING: [pool www0] child 20786, script '/var/www/drupal/index.php' (request: "GET /index.php") executing too slow (230.767319 sec), logging
[19-Jul-2012 19:15:55] NOTICE: child 20786 stopped for tracing
[19-Jul-2012 19:15:55] NOTICE: about to trace 20786
[19-Jul-2012 19:15:55] NOTICE: finished trace of 20786
[19-Jul-2012 19:16:05] WARNING: [pool www1] child 20779, script '/var/www/drupal/index.php' (request: "GET /index.php") executing too slow (237.619388 sec), logging
[19-Jul-2012 19:16:05] NOTICE: child 20779 stopped for tracing
[19-Jul-2012 19:16:05] NOTICE: about to trace 20779
[19-Jul-2012 19:16:05] NOTICE: finished trace of 20779
You seem to have
some long lived requests. Try tuning
max_execution_timeonphp.iniandrequest_terminate_timeouton the pool config file.it has to do with nginx configuration
I now created a very simple nginx configuration file.. (before i used your configuration) and now it works good.. so I will add your settings one by one to find out where it goes wrong!
Hmm
the Nginx config sets a bunch of params for FCGI, Ok I'm curious to see what could be. I cannot reproduce this problem.
YES found the issue!
ok i found it.. i think.. well i'm sure it works good now :)
but the issue was or is:
fastcgi_keep_conn on;
I changed this to:
#fastcgi_keep_conn on;in the sites-available/example.com.conf
(I have nginx 1.2.2)
only why i don't know, maybe you understand because it's your code?
It is really PHP
I'm not sure it has something to do with nginx configuration. From your log, two are normal (6 and 11 second requests). Only 230 s and 230 s are really long. I doubt they are cron job (timeout limit for cron = 240 s). As it happened only once (at 9:15 and 9:16 - nearly the same time), it does not happen with the new conf' does not mean the problem is solved. Or it just means you did not have problem (your script is simply too long in some extremely rare condition).
I'd suggest you to install something like New Relic (14 first days is a free Premium ersion) so that you will have a detail backtrace of your slow requests.
it's not a php script
Yes sorry perhaps the issue wasn't that clear,
1.) i go to drupal homepage (just after a fresh install)
2.) i see in the log that (even after the page is loaded) the pool stays on "1 active childeren"
3.)I load the page again after 120 seconds (and if that pool is used for my php file) the system thinks it's already active/loading for 120 seconds so kills it. (because i have this in my request_terminate_timeout)
So basicly it looked like any script caused the child to stay active.. untill it was hit again after 120 seconds. I'm sure it has to do with fastcgi_keep_conn, so i'll try to find out what this setting needs to work OK. Because it seems to work on other systems.
keepalive
fastcgi_keep_connenables the keepalive to the FCGI upstream. This works by putting in place a connection cache. Are you using the default (5 cached connections)? This is a setting for a site with at least two pools.Try setting to 1 and see how it goes. I should add some documentation about it.
I have two pools. changing to keepalive 1 did help..
Well i have 2 pools, i used also your php-fpm config, but disabled www2 because you don't use that anymore as backup pool.
I now changed keepalive 5; to keepalive 1;
and i don't get any timeouts anymore! ...
upstream phpcgi {## If your version of Nginx doesn't have the fair load balancer:
## https://github.com/gnosek/nginx-upstream-fair comment out the
## following line.
fair;
server 127.0.0.1:9001;
server 127.0.0.1:9002;
## Create a backend connection cache. Note that this requires
## Nginx version greater or equal to 1.1.4.
## Cf. http://nginx.org/en/CHANGES. Comment out the following
## line if that's not the case.
keepalive 1;
}
I had the same problem and
I had the same problem and setting keepalive 1; resolve it. But i'm still don't understand this problem couse. Can anybody simply explain this?
My config:
Debian 6
Nginx 1.4.4 (dotdeb.org)
php5-fpm 5.3 (dotdeb.org)
I also have this problem with
I also have this problem with my new servers.
PHP stops answering requests after a few hours, and nginx simply returns 502. Impossible to put it on production.
I have max_execution_time as 90 and the same for request_terminate_timeout.
What helped me a little bit was setting php-fpm pools to static instead of dynamic.
But after 4 hours or more, the same problem happens.
listen.backlog is -1
Ideas:
Increase
max_requests.Set the request_terminate_timeout to 30 minutes.
Check to see if all children are taken.
Could be that the FCGI setup permits long lived requests, but the php-fpm don't.
No, on fpm-status the max
No, on fpm-status the max children is never reached.
I will try to increase 1. and 2.
Thanks
Any solution on this question???
I am having the same issue.
Thanks,
Solution
It seems that in my case the problem was not related with server configuration.
It was related with a module confguration. I hope this can help in future issues related with this subject.
Regards,
possible solution
I've been bumping my head for days into this problem, did a lot of googling and fiddling around with different settings and I came across this post a number of times. I somehow got rid of the time-outs and I would like to share my experience. I'm not exactly shure what did the trick. May be my post will help solve your time-out problems.
I recently installed a D6.26 in a Freebsd jail with nginx 1.2.2 with php-fpm 5.2.17 behind a nginx reverse proxy in another jail. My purpose of the site is to test Drupal on a nginx platform. I use memcached and xcache instead of apc. Here are excerpts of php.ini php-fpm.conf and nginx.conf that ihmo are relevant to this problem:
php.ini
output_buffering = 4096zlib.output_compression = On
zlib.output_compression_level = 2
zlib.output_handler = zlib.output_handler
safe_mode = Off
realpath_cache_size=16k
realpath_cache_ttl=120
max_execution_time = 90
max_input_time = 60
memory_limit = 64M
cgi.force_redirect = 1
cgi.fix_pathinfo=1
cgi.rfc2616_headers = 1
default_socket_timeout = 10
mysql.allow_persistent = Off
mysql.connect_timeout = 30
mysqli.reconnect = On
php-fpm.conf
<value name="listen_address">/tmp/php-fpm.sock</value><value name="request_terminate_timeout">10s</value>
nginx.conf
# Caches information about open FDs, freqently accessed files
open_file_cache max=20000 inactive=20s;
open_file_cache_valid 60s;
open_file_cache_min_uses 2;
open_file_cache_errors on;
fastcgi_buffer_size 128k;
fastcgi_buffers 4 256k;
fastcgi_busy_buffers_size 256k;
fastcgi_temp_file_write_size 256k;
fastcgi_intercept_errors on;
sendfile on;
tcp_nopush on;
tcp_nodelay on;
aio sendfile;
#timeouts
client_body_timeout 10;
client_header_timeout 10;
keepalive_timeout 5;
send_timeout 5;
fastcgi_connect_timeout 10;
fastcgi_read_timeout 5;
fastcgi_send_timeout 3;
As you can see my time-out settings are short, below average. I'm building a dev environment without load, so I undoubtfully will have to raise limits in a production environment. In any case this setup somehow solved my immediate problems, where mysite.com/admin and mysite,com/admin/reports/status returned time-outs. So far so good.
Like I said, I'm not sure what did the trick, but I doubt it has to do with timeout settings. I think that once I turned mysql.allow_persistent off and mysqli.reconnect on things started working for me. Or maybe it's a combination of more of the above. I'll leave the matter for now, as I already spent a couple of days on cracking this nut, and have to jump one more hurdle in replacing $_SERVER["REMOTE_ADDR"] with a function which understands X-Forwarded-For, due to my reverse proxy setup.
Hopefully this post is of any use to you all.
So far I'm very impressed with nginx's speed and simplicity.
Cheers!
P.
update on my previous post
I have to take some of my words back concerning time-outs. I had to increase the values, not for simple page loads but to facilitate imports. Here are my new time-out settings
client_body_timeout 30;client_header_timeout 30;
keepalive_timeout 30;
send_timeout 30;
fastcgi_connect_timeout 30;
fastcgi_read_timeout 90;
fastcgi_send_timeout 30;
off topic: i managed to fix the drupal_http_request() problem by using the 'Drupal 6 proxy server support' patch on this page http://drupal.org/node/735420
Still having problems
I am unfortunately still experiencing problems. I receive 502 Bad Gateway every few minutes.
It does seem I have some long lived requests. I have tried tuning max_execution_time on php.ini and request_terminate_timeout on the pool config file.
in php.ini
request_terminate_timeout = 30m
^ This did "fix" the errors, but this doesn't really resolve the root of the problem. Can anyone explain how to find what script is running so long? What are the cons of having request_terminate_timeout such a large value (scripts are never terminated when they probably should be).
I have examined the nginx error log and have the following errors (multiple):
recv() failed (104: Connection reset by peer) while reading response header from upstream
upstream timed out (110: Connection timed out) while reading response header from upstream
None of my php5-fpm logs contain any relevant errors.
There are a ton of reasons
There are a ton of reasons why a script could be taking time to execute. If it's a user initiated 'poor mans cron' or a slow loading page then a request could take longer than however many seconds nginx waits. On my local dev box I've got the read timeout in nginx bumped up nice and high to mitigate any slow page loads.
fastcgi_read_timeout 14400;yes i use elysia cron and regular crontabs
yes i use elysia cron and regular crontabs (every minute) to execute all crons; everything is spread evenly to avoid excessive load at a single time.
again, if i just increase:
fastcgi_read_timeout 14400;
won't that just be "hiding" the problem too? with these high timeouts, the problem isn't as obvious because 502 bad gateway errors don't occur. However, if i leave and return after 30 minutes in my case (when php.ini request_terminate_timeout = 30m), refreshing the page returns a 502 bad gateway error too!
It seems I have a script that is always running. Would google analytics possibly be the culprit? I'm just looking for any guidance or help for HOW to troubleshoot this. The logs just seem so inconclusive.
There is an easy fix for this
It took me a long, long time to find out. Basically, when you run php-fpm in 'dynamic' or 'ondemand' mode, php-fpm children run and after a time are removed and respawned as needed. Nginx, however, attempts to stay connected to these children even after they disappear. The result is a race condition where Nginx wants php-fpm to keep respawning more children, it does so, hits max children, then it all falls over.
The nginx directive you want set to prevent all this happening is:
fastcgi_keep_conn off;
Have that on is the issue. It took me a very long time to figure this out. I had this happening not only on Drupal sites but on Magento sites as well (it could affect ANY PHP site)
Perusio-drupal-nginx config has this set to on in the example site config. I recommend it's turned off.
In Nginx's config it is defaulted to off, probably for some good reasons.
References:
http://mailman.nginx.org/pipermail/nginx/2013-February/037496.html
https://bugs.php.net/bug.php?id=60961
https://bugs.php.net/bug.php?id=63395
Great explanation! I can
Great explanation! I can confirm that solved my communication issue between php-fpm and nginx, no more random hanging for me.
Philippe Blanc
President and founder of VisualFox LLc http://www.visualfox.me
Founder of OYOAHA which helped to organize the first Drupal Con in Portland
President and founder of the short lived Blacktonic A
Can someone open and issue in
Can someone open and issue in perusio's config to correct the things that have been discused here
re: Perusio drupal nginx
There's already an issue I added a comment to:
https://github.com/perusio/drupal-with-nginx/issues/55