Plesk stopped working. ERROR: Service_Agent_Exception Unable to execute php_handlers_control

[rescarcega]

I'm not been able to start plesk although web and mail services are running.

I'm getting the error unable to execute php_handlers_control.

I've patched and updated plesk with command line and bootstrap repair and I'm getting this errors:

==> Checking for: mail_responder_restore... fail
/usr/lib64/plesk-9.0/mail_restore: line 820: 17063 Illegal instruction /usr/local/psa//admin/sbin/mailmng-service --restart-service

Errors occured in mail restore procedure
Some utilities have exited with errors:
/usr/lib64/plesk-9.0/mail_auth_dump
/usr/lib64/plesk-9.0/mail_responder_restore

I get Illegal Instruction When I run :
# /usr/local/psa/admin/sbin/mailmng-service --restart-service
Illegal instruction

Also I switched from postfix to qmail and back using the command line with no luck.

/usr/local/psa/admin/bin/mailmng --features
runs without problems.....

/usr/local/psa/admin/logs/panel.log:

[06-Apr-2015 12:47:25 America/New_York] PleskUtilException: '/usr/local/psa/admin/bin/php_handlers_control' '--list-json' failed with code 4.

stdout:

stderr:

file: /usr/local/psa/admin/plib/Service/Agent/Transport/Local/Exec.php

line: 38

code: 0

trace: #0 /usr/local/psa/admin/plib/Service/Agent/Transport/Local.php(60): Service_Agent_Transport_Local_Exec->process(0, Object(Service_Agent_Command_Exec), Object(Service_Agent_Transport_LocalTransaction))

#1 /usr/local/psa/admin/plib/Service/Agent/Transport/Local.php(26): Service_Agent_Transport_Local->_command(0, Object(Service_Agent_Command_Exec), Object(Service_Agent_Transport_LocalTransaction))

#2 /usr/local/psa/admin/plib/Service/Agent.php(140): Service_Agent_Transport_Local->process('217baa1b041d6cb...', Array)

#3 /usr/local/psa/admin/plib/Service/Node/Environment/PhpHandlers/Unix.php(20): Service_Agent->commit()

#4 [internal function]: Service_Node_Environment_PhpHandlers_Unix->onSyncProperties()

#5 /usr/local/psa/admin/plib/Db/Table/Dictionary/ServiceNodeEnvironment.php(87): call_user_func_array(Array, Array)

#6 /usr/local/psa/admin/plib/Service/Driver/Packages/Unix.php(34): Db_Table_Dictionary_ServiceNodeEnvironment->sync()

#7 /usr/local/psa/admin/plib/Service/Driver/Core/Plesk.php(11): Service_Driver_Packages_Unix->sync(false)

#8 /usr/local/psa/admin/plib/Db/Table/Row/ServiceNode/ManagementNode.php(15): Service_Driver_Core_Plesk->syncAllData()

#9 /usr/local/psa/admin/plib/auth.php(583): Db_Table_Row_ServiceNode_ManagementNode->refreshProperties()

#10 {main}


2015-04-06T12:47:25-04:00 ERR (3) [panel]: Unable to execute php_handlers_control: :

0: Unix.php:22

Service_Node_Environment_PhpHandlers_Unix->onSyncProperties()

1: :

call_user_func_array(array, array)

2: ServiceNodeEnvironment.php:87

Db_Table_Dictionary_ServiceNodeEnvironment->sync()

3: Unix.php:34

Service_Driver_Packages_Unix->sync(boolean false)

4: Plesk.php:11

Service_Driver_Core_Plesk->syncAllData()

5: ManagementNode.php:15

Db_Table_Row_ServiceNode_ManagementNode->refreshProperties()

6: auth.php:583

[06-Apr-2015 12:47:25 America/New_York] Service_Agent_Exception: Unable to execute php_handlers_control:

file: /usr/local/psa/admin/plib/Service/Node/Environment/PhpHandlers/Unix.php

line: 22

code: 0

trace: #0 [internal function]: Service_Node_Environment_PhpHandlers_Unix->onSyncProperties()

#1 /usr/local/psa/admin/plib/Db/Table/Dictionary/ServiceNodeEnvironment.php(87): call_user_func_array(Array, Array)

#2 /usr/local/psa/admin/plib/Service/Driver/Packages/Unix.php(34): Db_Table_Dictionary_ServiceNodeEnvironment->sync()

#3 /usr/local/psa/admin/plib/Service/Driver/Core/Plesk.php(11): Service_Driver_Packages_Unix->sync(false)

#4 /usr/local/psa/admin/plib/Db/Table/Row/ServiceNode/ManagementNode.php(15): Service_Driver_Core_Plesk->syncAllData()

#5 /usr/local/psa/admin/plib/auth.php(583): Db_Table_Row_ServiceNode_ManagementNode->refreshProperties()

#6 {main}


Any ideas how to solve this problem?

Thanks

 

[rescarcega]

the problem seems to fall in the php_handlers_controls script. Every script that uses this script is failing with a proc_close() error and an Illegal instruction message.
Any ideas?

 

[IgorG]

There are may be absent psaadm user in the swkey-data group or broken permissions on the /etc/sw/keys directory.
As possible solution try to set the correct permissions by following the commands below:

# /usr/sbin/usermod -a -G swkey-data psaadm
# chown -R root:swkey-data /etc/sw/keys/
# chmod u+s /etc/sw/keys/restart/apskeyhandler /etc/sw/keys/restart/plesk-key-handler
# chmod 2770 /etc/sw/keys

 

[rescarcega]

Hello Igor

The commands didn't correct the problem. It still down.

When I try to start plesk, this is the output:

[root@chi2 sw]# /etc/init.d/psa start
Starting psa... Starting sw-engine-fpm: Warning: Current locale is unusable. Using 'C' instead.

[ OK ]
done
Starting xinetd service... done
Starting sw-cp-server service... done
Starting mysqld service... done
perl: warning: Setting locale failed.
perl: warning: Please check that your locale settings:
LANGUAGE = (unset),
LC_ALL = (unset),
LC_CTYPE = "UTF-8",
LANG = "C"
are supported and installed on your system.

perl: warning: Falling back to the standard locale ("C").

Starting named service... done
Starting postgresql service... not installed
Starting spamassassin service... done

Plesk: Starting Mail Server... /etc/init.d/psa: line 606: 30580 Illegal instruction $driver --start-service > /dev/null 2>&1

failed
Starting psa... Starting sw-engine-fpm:
done
Starting drwebd service... failed

Anything else?

Thanks a lot for your help

 

[IgorG]

Try to set up English local with

export LC_ALL="en_US.UTF-8"

and restart psa service.

 

[rescarcega]

It did correct the locale messages, but the problem still persists:
Plesk: Starting Mail Server... /etc/init.d/psa: line 606: 2165 Illegal instruction $driver --start-service > /dev/null 2>&1

failed

Starting psa... Starting sw-engine-fpm:

done

Starting drwebd service... failed


this is the output (debug on) of php_handler:


/usr/local/psa/bin/php_handler

2015-04-08T11:12:35-04:00 DEBUG (7) [dbquery]: [0] SQL: SET NAMES utf8

2015-04-08T11:12:35-04:00 DEBUG (7) [dbquery]: [0] END: 0.00033998489379883 sec

2015-04-08T11:12:35-04:00 DEBUG (7) [dbquery]: [1] SQL: select val from cl_param p, clients c where c.id = p.cl_id and c.login='admin' and p.param='poa-api-url'

2015-04-08T11:12:35-04:00 DEBUG (7) [dbquery]: [1] END: 0.00031590461730957 sec

2015-04-08T11:12:35-04:00 DEBUG (7) [dbquery]: [2] SQL: SET NAMES utf8

2015-04-08T11:12:35-04:00 DEBUG (7) [dbquery]: [2] END: 0.00016117095947266 sec

2015-04-08T11:12:35-04:00 DEBUG (7) [dbquery]: [3] SQL: SET NAMES utf8

2015-04-08T11:12:35-04:00 DEBUG (7) [dbquery]: [3] END: 0.00014090538024902 sec

2015-04-08T11:12:35-04:00 DEBUG (7) [dbquery]: [4] SQL: select param, val from misc

2015-04-08T11:12:35-04:00 DEBUG (7) [dbquery]: [4] END: 0.00024008750915527 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [5] SQL: connect

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [5] END: 0.00039815902709961 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [6] SQL: DESCRIBE `ServiceNodes`

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [6] END: 0.00049495697021484 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [7] SQL: SELECT `ServiceNodes`.* FROM `ServiceNodes` AS `ServiceNodes` WHERE (`ipAddress` = 'local')

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [7] END: 0.00027704238891602 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [8] SQL: DESCRIBE `ServiceNodeEnvironment`

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [8] END: 0.00027012825012207 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [9] SQL: SELECT `ServiceNodeEnvironment`.* FROM `ServiceNodeEnvironment` AS `ServiceNodeEnvironment` WHERE (`serviceNodeId` = '1' AND `section` = 'componentsPackages')

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [9] END: 0.0003511905670166 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [10] SQL: SELECT `ServiceNodeEnvironment`.* FROM `ServiceNodeEnvironment` AS `ServiceNodeEnvironment` WHERE (`serviceNodeId` = '1' AND `section` = 'componentsPackages')

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [10] END: 0.00040817260742188 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [util_exec]: Start plesk agent: transaction=da78152c1d96aa9b51c416914e5f9050, transport=local

2015-04-08T11:12:36-04:00 DEBUG (7) [util_exec]: 0, script = packagemng, version = 1.0, args=--list, stdin=

2015-04-08T11:12:36-04:00 DEBUG (7) [util_exec]: 0, time = 0.12701010704041, errcode = 0; stderr = ; stdout = PPSitebuilder:11.5.10-42107.13120413

SSHTerm:

awstats:7.1.1-13052215.parallels

bind:9.8.2-0.30.rc1.el6_6.2

coldfusion:

coldfusion-support:

commtouch:

courier-imap:4.12.0-cos6.build115130710.20

drweb:6.0.2.1-1211092005pleskel5

frontpage:

health-monitor:11.5.30-cos6.build115130618.12

httpd:2.2.15-30.el6.centos

mailman:2.1.12-18.el6

mod_bw:

mod_perl:2.0.4-11.el6_5

mod_python:3.3.1-16.el6

msmtp:

mysql:5.1.73-3.el6_5

nginx:1.5.0-1.13060711.centos6

perl-Apache-ASP:

php:5.3.3-27.el6_5

php-fpm:

plesk-billing:

postfix:

postgresql-server:

psa:11.5.30-cos6.build115130819.13

psa-api-rpc:11.5.30-cos6.build115130819.13

psa-atmail:

psa-autoinstaller:3.15.15-130814.09

psa-backup-manager:11.5.30-cos6.build115130819.13

psa-drweb-configurator:11.5.30-cos6.build115130819.13

psa-horde:5.1.5-cos6.build115131216.11

psa-imp:6.1.6-cos6.build115131216.11

psa-kav8:

psa-logrotate:3.8.2-cos6.build115130211.11

psa-migration-manager:11.5.30-cos6.build115130819.13

psa-miva:

psa-mod-fcgid-configurator:2.3.9-14012811

psa-proftpd:1.3.4c-cos6.build115130528.15

psa-qmail:1.03-cos6.build115130805.16

psa-qmail-rblsmtpd:0.88-cos6.build115130313.15

psa-spamassassin:11.5.30-cos6.build115130819.13

psa-tomcat-configurator:

psa-turba:4.1.3-cos6.build115131216.11

roundcube:0.9.5-cos6.build115131112.14

ruby:

samba:

sitebuilder:

spamassassin:3.3.1-3.el6

tomcat:

webalizer:2.21_02-3.3.el6


2015-04-08T11:12:36-04:00 DEBUG (7) [util_exec]: End: da78152c1d96aa9b51c416914e5f9050

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [11] SQL: DESCRIBE `ServiceNodeConfiguration`

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [11] END: 0.00043582916259766 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [12] SQL: SELECT `ServiceNodeConfiguration`.* FROM `ServiceNodeConfiguration` AS `ServiceNodeConfiguration` WHERE (`serviceNodeId` = '1' AND `section` = 'coldfusion')

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [12] END: 0.00025796890258789 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [13] SQL: UPDATE `ServiceNodeEnvironment` SET `value` = ? WHERE (`ServiceNodeEnvironment`.`serviceNodeId` = 1) AND (`ServiceNodeEnvironment`.`section` = 'componentsPackages') AND (`ServiceNodeEnvironment`.`name` = 'synced')

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [13] BOUND PARAMETERS:

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [13] PARAM 1: 1428505956

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [13] END: 0.091661930084229 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [14] SQL: SELECT `ServiceNodeEnvironment`.* FROM `ServiceNodeEnvironment` AS `ServiceNodeEnvironment` WHERE (`ServiceNodeEnvironment`.`serviceNodeId` = 1) AND (`ServiceNodeEnvironment`.`section` = 'componentsPackages') AND (`ServiceNodeEnvironment`.`name` = 'synced') LIMIT 1

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [14] END: 0.00020909309387207 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [15] SQL: SELECT `ServiceNodeEnvironment`.* FROM `ServiceNodeEnvironment` AS `ServiceNodeEnvironment` WHERE (`serviceNodeId` = '1' AND `section` = 'componentsPackages')

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [15] END: 0.00050902366638184 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [16] SQL: DESCRIBE `ServiceNodeEnvironment`

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [16] END: 0.00042295455932617 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [17] SQL: SELECT `ServiceNodeEnvironment`.* FROM `ServiceNodeEnvironment` AS `ServiceNodeEnvironment` WHERE (`serviceNodeId` = '1' AND `section` = 'phphandlers')

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [17] END: 0.00026702880859375 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [18] SQL: SELECT `ServiceNodeEnvironment`.* FROM `ServiceNodeEnvironment` AS `ServiceNodeEnvironment` WHERE (`serviceNodeId` = '1' AND `section` = 'phphandlers')

2015-04-08T11:12:36-04:00 DEBUG (7) [dbquery]: [18] END: 0.00026917457580566 sec

2015-04-08T11:12:36-04:00 DEBUG (7) [util_exec]: Start plesk agent: transaction=f01b60cc4da90dced18bb1eb08d6200b, transport=local

2015-04-08T11:12:36-04:00 DEBUG (7) [util_exec]: 0, script = php_handlers_control, version = 1.0, args=--reread, stdin=

2015-04-08T11:12:36-04:00 ERR (3) [util_exec]: proc_close() failed

2015-04-08T11:12:36-04:00 DEBUG (7) [util_exec]: 0, time = 0.020049095153809, errcode = 4; stderr = ; stdout =

2015-04-08T11:12:36-04:00 DEBUG (7) [util_exec]: send-error-report/usr/local/psa/admin/bin/send-error-report 'warning'TRUE0.00072


Error occured while sending feedback. HTTP code returned: 502

2015-04-08T11:12:36-04:00 DEBUG (7) [util_exec]: End: f01b60cc4da90dced18bb1eb08d6200b

2015-04-08T11:12:36-04:00 ERR (3) [panel]:

2015-04-08T11:12:36-04:00 DEBUG (7) [util_exec]: Start plesk agent: transaction=8bf32815dd241cfcdd7ec51a01081dd4, transport=local

2015-04-08T11:12:36-04:00 DEBUG (7) [util_exec]: 0, script = php_handlers_control, version = 1.0, args=--list-json, stdin=

2015-04-08T11:12:36-04:00 ERR (3) [util_exec]: proc_close() failed

2015-04-08T11:12:36-04:00 DEBUG (7) [util_exec]: 0, time = 0.020056962966919, errcode = 4; stderr = ; stdout =

2015-04-08T11:12:36-04:00 DEBUG (7) [util_exec]: send-error-report/usr/local/psa/admin/bin/send-error-report 'warning'TRUE0.00071


Error occured while sending feedback. HTTP code returned: 502

2015-04-08T11:12:37-04:00 DEBUG (7) [util_exec]: End: 8bf32815dd241cfcdd7ec51a01081dd4

2015-04-08T11:12:37-04:00 DEBUG (7) [util_exec]: send-error-report/usr/local/psa/admin/bin/send-error-reportTRUE0.00091


Error occured while sending feedback. HTTP code returned: 502

Unable to execute php_handlers_control:


Releasing semaphore 27 in request cleanup

 

[IgorG]

Have you tried to run bootstrapper repair procedure?

 

[rescarcega]

Bootstrap didn't repaired plesk. Its still with this error: Unable to execute php_handlers_control:

Bootstrap execution:

[root@chi2 ~]# /usr/local/psa/bootstrapper/pp11.5.30-bootstrapper/bootstrapper.sh repair
Started bootstrapper repair procedure. This may take a while.
Certain actions may be skipped if not applicable.

-- Warning: Skipping the data of table mysql.event. Specify the --events option explicitly.
 Finishing up upgrade procedures and rerunning previously failed upgrade actions...
===> Cumulative Plesk database upgrade and repair (revertable stage) has been started.
===> Preparing Plesk database upgrade (revertable stage).
===> Cumulative upgrade and repair of Plesk database (revertable stage) has been completed.
===> Cumulative Plesk upgrade and repair (final stage) has been started.
===> Preparing Plesk upgrade (final stage).
===> Cumulative upgrade and repair of Plesk (final stage) has been completed.
===> Cumulative APS controller database (apsc) upgrade and repair has been started.
===> Upgrade and repair of APS controller database has been completed.
2015-04-09T12:18:51-04:00 DEBUG (7)  [dbquery]: [0] SQL: SET NAMES utf8
2015-04-09T12:18:51-04:00 DEBUG (7)  [dbquery]: [0] END: 0.00028085708618164 sec
2015-04-09T12:18:51-04:00 DEBUG (7)  [dbquery]: [1] SQL: select val from cl_param p, clients c where c.id = p.cl_id and c.login='admin' and p.param='poa-api-url'
2015-04-09T12:18:51-04:00 DEBUG (7)  [dbquery]: [1] END: 0.00049781799316406 sec
2015-04-09T12:18:51-04:00 DEBUG (7)  [dbquery]: [2] SQL: SET NAMES utf8
2015-04-09T12:18:51-04:00 DEBUG (7)  [dbquery]: [2] END: 6.0081481933594E-5 sec
2015-04-09T12:18:51-04:00 DEBUG (7)  [dbquery]: [3] SQL: select param, val from misc
2015-04-09T12:18:51-04:00 DEBUG (7)  [dbquery]: [3] END: 0.00035190582275391 sec
 Reconfiguring mail subsystem...
 Reconfiguring Apache web server...
 Reconfiguring ProFTPD FTP server...
 Reconfiguring AWStats web statistics...
 Reconfiguring WatchDog...
 Restoring SELinux contexts...
 Regenerating web servers' configuration files...
 Cleaning active Panel sessions...

Bootstrapper repair finished.
If problems persist, please check installer logs ('/tmp/plesk_11.5.30_repair.log' and '/tmp/plesk_11.5.30_repair_problems.log') for errors.
If you can't resolve the issue on your own, please address Parallels support.

bootstrap repair log:

/usr/lib64/plesk-9.0/mail_restore: line 820: 9111 Illegal instruction /usr/local/psa//admin/sbin/mailmng-service --restart-service
Errors occured in mail restore procedure
Some utilities have exited with errors:
/usr/lib64/plesk-9.0/mail_auth_dump
/usr/lib64/plesk-9.0/mail_responder_restore

 

[rescarcega]

Can you think of anything else? The problem persists.