Resolved backup job report error exit code 1?

[raytracy]

Both scheduled and manual backup job report the following error on panel whatever destinate to FTP or local:

pmm utility 'PMMCli' raised an exception. Error code is: 1 See pmmcli.log to find out detailed information on this

I got the following in /usr/local/psa/PMM/logs/backup-2017-08-04-02-50-58-370/pmmcli.log:

[2017-08-04 02:50:58.372|7888] DEBUG: LOG: custom log /var/log/plesk/PMM/backup-2017-08-04-02-50-58-370/backup.log
[2017-08-04 02:50:58.375|7888] INFO: Executing <subprocess[7889] '/usr/bin/perl /usr/local/psa/admin/bin/plesk_agent_manager domains-id --get-size --ftp --output-file=ftp://[email protected]// --incremental --session-path=/usr/local/psa/PMM/sessions/2017-08-04-025058.372 --from-file=/usr/local/psa/PMM/sessions/2017-08-04-025058.372/from-file'>
[2017-08-04 02:51:09.067|7888] INFO: Subprocess raised ExecuteException: Subprocess <subprocess[7889]
'/usr/bin/perl /usr/local/psa/admin/bin/plesk_agent_manager domains-id --get-size --ftp --output-file=ftp://[email protected]// --incremental --session-path=/usr/local/psa/PMM/sessions/2017-08-04-025058.372 --from-file=/usr/local/psa/PMM/sessions/2017-08-04-025058.372/from-file'> was finished with exit code 1
== STDOUT ====================

== STDERR ====================

[2017-08-04 02:51:09.068|7888] CRITICAL: PMMUtility exception:
Subprocess <subprocess[7889] '/usr/bin/perl /usr/local/psa/admin/bin/plesk_agent_manager domains-id --get-size --ftp --output-file=ftp://[email protected]// --incremental --session-path=/usr/local/psa/PMM/sessions/2017-08-04-025058.372 --from-file=/usr/local/psa/PMM/sessions/2017-08-04-025058.
372/from-file'> was finished with exit code 1
== STDOUT ====================

== STDERR ====================

Traceback (most recent call last):
File "/usr/local/psa/admin/share/pmmcli/pmmcli.py", line 2493, in main
data_action_response, errcode_response, error_message = actions.get(sys.argv[1][2:])(parameters)
File "/usr/local/psa/admin/share/pmmcli/pmmcli.py", line 2385, in make_dump
return ActionRunner(MakeDumpAction, backup_task_description, None).doActivity()
File "/usr/local/psa/admin/share/pmmcli/pmmcli.py", line 186, in doActivity
result = self.processor.doActivity()
File "/usr/local/psa/admin/share/pmmcli/pmmcli.py", line 1567, in doActivity
raise PMMUtilityException('PMMCli', x)
PMMUtilityException: PMMCli was finished with exit code 1

[2017-08-04 02:51:09.068|7888] DEBUG: send-error-report /usr/local/psa/admin/bin/send-error-report <?xml version="1.0" ?><error><module>pmmcli</module><operation>&lt;class 'pmmcli_exceptions.PMMUtilityException'&gt;</operation><message>PMMCli was finished with exit code 1 at File &quot;/usr/local/psa/admin/sbin/pmmcli&quot;, line 6, in &lt;module&gt;
pmmcli.main()
File &quot;/usr/local/psa/admin/share/pmmcli/pmmcli.py&quot;, line 2520, in main
logUnhandledError(response, e, 1)
File &quot;/usr/local/psa/admin/share/pmmcli/pmmcli.py&quot;, line 2427, in logUnhandledError
ErrorReporter.sendException(exception)
File &quot;/usr/local/psa/admin/lib/python/error_reporter.py&quot;, line 19, in sendException
stack_trace = traceback.format_stack()
</message></error>
[2017-08-04 02:51:09.072|7888] INFO: Executing asynchronously <subprocess[7931] '/usr/local/psa/admin/bin/send-error-report send-error-report pmm'>
[2017-08-04 02:51:09.073|7888] INFO: Outgoing packet:
<?xml version="1.0" ?><response>
<errcode>1000</errcode>
<errmsg>pmm utility 'PMMCli' raised an exception. Error code is: 1
See pmmcli.log to find out detailed information on this</errmsg>
</response>
[2017-08-04 02:51:09.075|7888] DEBUG: send-error-report /usr/local/psa/admin/bin/send-error-report <?xml version="1.0" ?><error><module>pmmcli</module><operation>NonzeroExitError</operation><message>Command: /usr/bin/perl /usr/local/psa/admin/bin/plesk_agent_manager domains-id --get-size --ftp --output-file=ftp://[email protected]// --incremental --session-path=/usr/local/psa/PMM/sessions/2017-08-04-025058.372 --from-file=/usr/local/psa/PMM/sessions/2017-08-04-025058.372/from-file
Code: 1
STDOUT:
STDERR:
Stacktrace: File &quot;/usr/local/psa/admin/sbin/pmmcli&quot;, line 6, in &lt;module&gt;
pmmcli.main()
File &quot;/usr/local/psa/admin/share/pmmcli/pmmcli.py&quot;, line 2493, in main
data_action_response, errcode_response, error_message = actions.get(sys.argv[1][2:])(parameters)
File &quot;/usr/local/psa/admin/share/pmmcli/pmmcli.py&quot;, line 2385, in make_dump
return ActionRunner(MakeDumpAction, backup_task_description, None).doActivity()
File &quot;/usr/local/psa/admin/share/pmmcli/pmmcli.py&quot;, line 186, in doActivity
result = self.processor.doActivity()
File &quot;/usr/local/psa/admin/share/pmmcli/pmmcli.py&quot;, line 1549, in doActivity
proc = cmdGetSize.spawn()
File &quot;/usr/local/psa/admin/lib/python/subproc.py&quot;, line 262, in spawn
self.proc.run()
File &quot;/usr/local/psa/admin/lib/python/subproc.py&quot;, line 207, in run
BaseSubprocess.run(self)
File &quot;/usr/local/psa/admin/lib/python/subproc.py&quot;, line 181, in run
self.wait()
File &quot;/usr/local/psa/admin/lib/python/subproc.py&quot;, line 215, in wait
BaseSubprocess.wait(self)
File &quot;/usr/local/psa/admin/lib/python/subproc.py&quot;, line 195, in wait
raise NonzeroExitException(self, self.__retcode)
File &quot;/usr/local/psa/admin/lib/python/subproc.py&quot;, line 54, in __init__
self.stackTrace = stackTrace if stackTrace else traceback.format_stack()
</message></error>
[2017-08-04 02:51:09.077|7888] INFO: Executing asynchronously <subprocess[7932] '/usr/local/psa/admin/bin/send-error-report send-error-report pmm'>

And backup.log in below:

[7889]: 2017-08-04 02:51:09.056 ERROR 8356ea46-1d0b-4fa0-8624-8d9f47f1df17 Runtime error: XML/SAX/Base.pm did not return a true value at /usr/share/perl5/vendor_perl/XML/SAX/PurePerl.pm line 18.
BEGIN failed--compilation aborted at /usr/share/perl5/vendor_perl/XML/SAX/PurePerl.pm line 18.
Compilation failed in require at (eval 30) line 1.
:
at /usr/local/psa/PMM/agents/shared/Logging.pm line 128.
Logging::error('Runtime error: XML/SAX/Base.pm did not return a true value at...', 'fatal') called at /usr/local/psa/admin/bin/plesk_agent_manager line 1387
main::__ANON__('Error::Simple=HASH(0x2aa8438)', 'SCALAR(0x19fc9c8)') called at /usr/local/psa/PMM/agents/shared/Error.pm line 330
eval {...} called at /usr/local/psa/PMM/agents/shared/Error.pm line 321
Error::subs::run_clauses('HASH(0x2a1bec0)', 'XML/SAX/Base.pm did not return a true value at /usr/share/per...', undef, 'ARRAY(0x1a05738)') called at /usr/local/psa/PMM/agents/shared/Error.pm line 417
Error::subs::try('CODE(0x1318888)', 'HASH(0x2a1bec0)') called at /usr/local/psa/admin/bin/plesk_agent_manager line 1389
main::main() called at /usr/local/psa/admin/bin/plesk_agent_manager line 1393

It just finished a complete scheduled backup yesterday, but report error today.
Any idea to fix it?

(CentOS 7.3+Onyx 17.5.3#MU16)

 

[pleskpanel]

Interesting that it happens within seconds of execution. This looks like a KB with some similar characteristics Plesk backup fails: pmm utility 'PMMCli' raised an exception. Error code is: 1 but the article could be a false positive.

 

[raytracy]

My version file looks good (same content compare to my other host):

#cat /usr/local/psa/version
17.5.3 CentOS 7 1705170317.16
#ll /usr/local/psa/version
-rw-r--r--. 1 root root 30 Jul 22 23:50 /usr/local/psa/version

By the way, I cannot execute the lsb command which KB has specified:

#lsb_release
bash: lsb_release: command not found

Does it can be ignored? or something wrong?

 

[IgorG]

#lsb_release
bash: lsb_release: command not found

# yum provides */lsb_release
Loaded plugins: fastestmirror
Repository base is listed more than once in the configuration
Repository updates is listed more than once in the configuration
Loading mirror speeds from cached hostfile
redhat-lsb-core-4.1-27.el7.centos.1.i686 : LSB Core module support
Repo : base
Matched from:
Filename : /usr/bin/lsb_release



redhat-lsb-core-4.1-27.el7.centos.1.x86_64 : LSB Core module support
Repo : base
Matched from:
Filename : /usr/bin/lsb_release



redhat-lsb-core-4.1-27.el7.centos.1.x86_64 : LSB Core module support
Repo : @base
Matched from:
Filename : /usr/bin/lsb_release

So, package redhat-lsb-core should be installed for getting lsb_release command.
And please try to update and correct all possible problems with

# plesk installer --select-release-current --reinstall-patch --upgrade-installed-components
# plesk repair installation

I hope it will help.

 

[raytracy]

I have installed redhat-lsb-core and execute two plesk command as above, but no luck, problem still persist....

Since this host has experienced some disk corruption incident in before, I suspect there are some execution binary broken.

By the way, if I do plesk repair fs -verbose, it show me:

Checking Linux system files[2017-08-05 01:59:42] ERR [util_exec] proc_close() failed ['/usr/local/psa/admin/bin/fsmng' '--check-ac' '/var/www/dumps' '--perms' '0750' '--owner' 'psaadm' '--group' 'psaadm'] with exit code [1]
Error occured while sending feedback. HTTP code returned: 502

fsmng failed: ERROR:Command '['/bin/rpm', '-qf', '/var/www/dumps', '--dump']' returned non-zero exit status 1

Checking virtual hosts' file system

There are files or directories with suspicious permissions in the
root directory of the domain 'wangsbakery.com.tw' ................. [WARNING]

(....Listing of some user uploaded image files in below, omitted....)

It won't be repaired, and show the warning every time.

I try to list the permission, it seems good:

ll -d /var/www/dumps
drwxr-x---. 4 psaadm psaadm 4096 Aug 5 01:54 /var/www/dumps
But all files inside the dumps folder were marked 600 and owned by root user/group.


Any advice to my next action?

 

[IgorG]

What is this directory /var/www/dumps ?
Some kind of customization? Default location for backup dumps is /var/lib/psa/dumps
Do you have it defined in /etc/psa/psa.conf ?

 

[raytracy]

What is this directory /var/www/dumps ?
Some kind of customization? Default location for backup dumps is /var/lib/psa/dumps
Do you have it defined in /etc/psa/psa.conf ?

Yes, I have redefined these var in psa.conf:

DUMP_D /var/www/dumps
DUMP_TMP_D /var/www/tmp

Since I have only a small root partition, I would like to relocate them to a larger disk which mounted on /var/www to avoid root capacity contention.

But I just change the psa.conf, and don't know is there something should be changed or updated too?

 

[IgorG]

When you run plesk repair fs directory /var/www/dumps is considered as some kind of part of vhosts structure and it lead to problems with permissions checking. I would not recommend to place it in /var/www directory.

 

[raytracy]

I fixed it with the following commands:

yum reinstall \*
plesk repair installation -y -v
plesk repair all -y -v

I have try several times to repair with plesk only, but not success until I reinstall all packages from yum.