php.net |  support |  documentation |  report a bug |  advanced search |  search howto |  statistics |  random bug |  login
Bug #62937 PHP-FPM starts but not respond as "STARTED"
Submitted: 2012-08-26 16:50 UTC Modified: 2012-09-29 06:06 UTC
Votes:6
Avg. Score:4.5 ± 0.8
Reproduced:6 of 6 (100.0%)
Same Version:4 (66.7%)
Same OS:3 (50.0%)
From: gencer at cmail dot cm Assigned:
Status: Open Package: APC (PECL)
PHP Version: 5.4.6 OS: CentOS 6.3
Private report: No CVE-ID:
Have you experienced this issue?
Rate the importance of this bug to you:

 [2012-08-26 16:50 UTC] gencer at cmail dot cm
Description:
------------
After i install and enable apc, php-fpm is not able to start "clearly".

>> service php-fpm start
>> Stopping php-fpm:           [   OK   ]
>> Starting php-fpm:           ...

At this point, php starts, i can see apc on phpinfo(); but it hangs.

Expected result:
----------------
It should say OK and then continue.

Actual result:
--------------
Hanging process.

Patches

Add a Patch

Pull Requests

Add a Pull Request

History

AllCommentsChangesGit/SVN commitsRelated reports
 [2012-08-26 17:14 UTC] gencer at cmail dot cm
I also did manually start attempt. No luck.

Without APC:
-------------------------
>> cd /usr/bin/
>> php-fpm
>> (done. it is working)

With APC:
-------------------------
>> cd /usr/bin/
>> php-fpm #...(hangs on) It starts but still hangs
 [2012-08-26 17:55 UTC] gencer at cmail dot cm
DEBUG:
------------------------------
[26-Aug-2012 13:54:35.537695] DEBUG: pid 30414, fpm_scoreboard_init_main(), line 40: got clock tick '100'
[26-Aug-2012 13:54:35.538226] DEBUG: pid 30414, fpm_event_init_main(), line 331: event module is epoll and 101 fds have been reserved
[26-Aug-2012 13:54:35.538297] NOTICE: pid 30414, fpm_init(), line 83: fpm is running, pid 30414
[26-Aug-2012 13:54:35.538311] DEBUG: pid 30414, main(), line 1810: Sending SIGUSR1 (OK) to parent 30413
[26-Aug-2012 13:54:35.539018] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30415 started
[26-Aug-2012 13:54:35.539461] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30416 started
[26-Aug-2012 13:54:35.539897] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30417 started
[26-Aug-2012 13:54:35.540352] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30418 started
[26-Aug-2012 13:54:35.540788] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30419 started
[26-Aug-2012 13:54:35.541223] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30420 started
[26-Aug-2012 13:54:35.541675] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30421 started
[26-Aug-2012 13:54:35.542147] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30422 started
[26-Aug-2012 13:54:35.542618] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30423 started
[26-Aug-2012 13:54:35.543070] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30424 started
[26-Aug-2012 13:54:35.543530] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30425 started
[26-Aug-2012 13:54:35.543988] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30426 started
[26-Aug-2012 13:54:35.544466] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30427 started
[26-Aug-2012 13:54:35.544927] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30428 started
[26-Aug-2012 13:54:35.545403] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30429 started
[26-Aug-2012 13:54:35.545880] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30430 started
[26-Aug-2012 13:54:35.546364] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30431 started
[26-Aug-2012 13:54:35.546822] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30432 started
[26-Aug-2012 13:54:35.547302] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30433 started
[26-Aug-2012 13:54:35.547762] DEBUG: pid 30414, fpm_children_make(), line 421: [pool www] child 30434 started
[26-Aug-2012 13:54:35.547782] DEBUG: pid 30414, fpm_event_loop(), line 360: 58904 bytes have been reserved in SHM
[26-Aug-2012 13:54:35.547787] NOTICE: pid 30414, fpm_event_loop(), line 361: ready to handle connections
[26-Aug-2012 13:54:36.548821] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:37.549843] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:38.550864] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:39.551884] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:40.552904] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:41.553764] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:42.554786] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:43.555814] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:44.556833] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:45.557854] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:46.558874] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:47.559891] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:48.560912] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:49.561930] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:50.562949] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:51.563778] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:52.564796] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:53.565812] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:54.566831] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:55.567851] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:56.568873] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:57.569893] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:58.570915] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:54:59.571943] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:55:00.572967] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:55:01.573989] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:55:02.575009] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:55:03.576034] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:55:04.577059] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
[26-Aug-2012 13:55:05.578090] DEBUG: pid 30414, fpm_pctl_perform_idle_server_maintenance(), line 379: [pool www] currently 0 active children, 20 spare children, 20 running children. Spawning rate 1
 [2012-08-26 19:02 UTC] gencer at cmail dot cm
Very weird thing is, if i install same config with same versions on Virtual Server (VPS or VDS) it works. But not in real dedicated (without virtualization).

I enabled apc debugging also but cant see where to get that.
 [2012-08-26 21:15 UTC] gencer at cmail dot cm
BTW, there is a reallyy weird thing in here:

I have 2 extensions. 

1-> APC
2-> ionCube Loader v4.2.2

In this case im getting same error. But, if i add another third extension like "Chilkat" it works!!!!! They are worked together. If i remove chilkat again not worked. If i add chilkat then worked.

And no, ioncube has nothing to do with this. I also try to remove ioncube and only apc remains, but not worked either. It seems after third extension it works :)

I really do not understand whats wrong with them!

Any idea/help is appreciated!
 [2012-08-26 21:21 UTC] rasmus@php.net
try compiling APC from svn trunk
 [2012-08-26 21:32 UTC] gencer at cmail dot cm
I tried already. It did not solved. Trunk is v3.1.13-dev.

Thanks,
Gencer.
 [2012-09-04 11:12 UTC] ab@php.net
-Status: Open +Status: Feedback
 [2012-09-04 11:12 UTC] ab@php.net
Are there any betterments with the 3.1.13 release?
 [2012-09-04 11:24 UTC] gencer at cmail dot cm
No, still exist. I can't start clearly.
 [2012-09-29 05:35 UTC] fat@php.net
Please try using this snapshot:

  http://snaps.php.net/php5.4-latest.tar.gz
 
For Windows:

  http://windows.php.net/snapshots/

see bug #62886
 [2012-09-29 06:06 UTC] gencer at cmail dot cm
-Status: Feedback +Status: Open
 [2012-09-29 06:06 UTC] gencer at cmail dot cm
Unfortunately, issue still exist.
 
PHP Copyright © 2001-2014 The PHP Group
All rights reserved.
Last updated: Fri Apr 18 00:01:21 2014 UTC