Facebook
Twitter
OS: CentOS 6.9 (Final)
Product: Plesk Onyx
Version 17.5.3 Update #20, last updated on Sept 1, 2017 02:07 PM
Backup Manager hangs at 0% when attempting to perform a backup.
I have searched through the forum and have tried various fixes. The issue was discovered when a migration was hanging. After inspecting I found that the backup process hung on the source server.
Killed all pmm and plesk-migrator processes.
removed and reinstalled plesk migrator
Emptied /usr/local/psa/PMM sessions, tasks, rsessions, msessions.
used 'plesk db' to truncate longtasks and longtasksparams
plesk repair db
plesk repair installation
The pmmcli_daemon log mentions that the daemon is delayed for 30 mins. The process waits and after 30 mins it tries again and then keeps repeating. I think that might be the cause, but I do not see what is causing the delay, or why it is occurring.
Log snippets follow. Thank you in advance for any assistance.
Product: Plesk Onyx
Version 17.5.3 Update #20, last updated on Sept 1, 2017 02:07 PM
Backup Manager hangs at 0% when attempting to perform a backup.
I have searched through the forum and have tried various fixes. The issue was discovered when a migration was hanging. After inspecting I found that the backup process hung on the source server.
Killed all pmm and plesk-migrator processes.
removed and reinstalled plesk migrator
Emptied /usr/local/psa/PMM sessions, tasks, rsessions, msessions.
used 'plesk db' to truncate longtasks and longtasksparams
plesk repair db
plesk repair installation
The pmmcli_daemon log mentions that the daemon is delayed for 30 mins. The process waits and after 30 mins it tries again and then keeps repeating. I think that might be the cause, but I do not see what is causing the delay, or why it is occurring.
Log snippets follow. Thank you in advance for any assistance.
Code:
pmmcli_daemon.log
[2017-09-01 13:24:24] Acquire PmmcliDaemon lock
[2017-09-01 13:24:24] daemon job started
[2017-09-01 13:24:24] daemon job delayed for 30 minutes
Code:
pmmcli.log
[2017-09-01 13:37:19.526|11305] DEBUG: --> <pmmcli.GetTaskAction object at 0x1ad7d50>
[2017-09-01 13:37:19.526|11305] INFO: parameters: ['1']
[2017-09-01 13:37:19.531|11305] DEBUG: <pmmcli.ActionRunner object at 0x7fe06b90fad0>: doActivity
[2017-09-01 13:37:19.531|11305] DEBUG: Acquired session mutex: MainThread
[2017-09-01 13:37:19.532|11305] DEBUG: Load task id=1 type=Backup
[2017-09-01 13:37:19.587|11305] DEBUG: Released session mutex: MainThread
[2017-09-01 13:37:19.587|11305] DEBUG: <pmmcli.GetTaskAction object at 0x1ad7d50>: response
[2017-09-01 13:37:19.591|11305] INFO: Outgoing packet:
<?xml version="1.0" ?><response>
<errcode>0</errcode>
<data>
<task-list>
<task backup-profile-name="" creation-date="2017-Sep-01 13:24:24" mail-to="" owner-guid="47296f90-8817-4018-b1b8-2c1c62424825" owner-type="server" task-id="1" task-pid="" task-type="Backup">
<task-status task-id="1">
<working>
<starting/>
</working>
</task-status>
<dumps-storage-credentials storage-type="local" use-ftps="false" use-passive-ftp-mode="false">
<root-dir>/var/lib/psa/dumps</root-dir>
</dumps-storage-credentials>
</task>
</task-list>
</data>
</response>
[2017-09-01 13:37:25.527|11369] DEBUG: --> <pmmcli.GetTaskAction object at 0x1612d50>
[2017-09-01 13:37:25.527|11369] INFO: parameters: ['1']
[2017-09-01 13:37:25.532|11369] DEBUG: <pmmcli.ActionRunner object at 0x7fe9d3194ad0>: doActivity
[2017-09-01 13:37:25.532|11369] DEBUG: Acquired session mutex: MainThread
[2017-09-01 13:37:25.533|11369] DEBUG: Load task id=1 type=Backup
[2017-09-01 13:37:25.585|11369] DEBUG: Released session mutex: MainThread
[2017-09-01 13:37:25.585|11369] DEBUG: <pmmcli.GetTaskAction object at 0x1612d50>: response
[2017-09-01 13:37:25.588|11369] INFO: Outgoing packet:
<?xml version="1.0" ?><response>
<errcode>0</errcode>
<data>
<task-list>
<task backup-profile-name="" creation-date="2017-Sep-01 13:24:24" mail-to="" owner-guid="47296f90-8817-4018-b1b8-2c1c62424825" owner-type="server" task-id="1" task-pid="" task-type="Backup">
<task-status task-id="1">
<working>
<starting/>
</working>
</task-status>
<dumps-storage-credentials storage-type="local" use-ftps="false" use-passive-ftp-mode="false">
<root-dir>/var/lib/psa/dumps</root-dir>
</dumps-storage-credentials>
</task>
</task-list>
</data>
</response>
[2017-09-01 13:37:31.527|11456] DEBUG: --> <pmmcli.GetTaskAction object at 0x2260d50>
[2017-09-01 13:37:31.528|11456] INFO: parameters: ['1']
[2017-09-01 13:37:31.532|11456] DEBUG: <pmmcli.ActionRunner object at 0x7fe08a52cad0>: doActivity
[2017-09-01 13:37:31.533|11456] DEBUG: Acquired session mutex: MainThread
[2017-09-01 13:37:31.533|11456] DEBUG: Load task id=1 type=Backup
[2017-09-01 13:37:31.588|11456] DEBUG: Released session mutex: MainThread
[2017-09-01 13:37:31.588|11456] DEBUG: <pmmcli.GetTaskAction object at 0x2260d50>: response
[2017-09-01 13:37:31.592|11456] INFO: Outgoing packet:
<?xml version="1.0" ?><response>
<errcode>0</errcode>
<data>
<task-list>
<task backup-profile-name="" creation-date="2017-Sep-01 13:24:24" mail-to="" owner-guid="47296f90-8817-4018-b1b8-2c1c62424825" owner-type="server" task-id="1" task-pid="" task-type="Backup">
<task-status task-id="1">
<working>
<starting/>
</working>
</task-status>
<dumps-storage-credentials storage-type="local" use-ftps="false" use-passive-ftp-mode="false">
<root-dir>/var/lib/psa/dumps</root-dir>
</dumps-storage-credentials>
</task>
</task-list>
</data>
</response>
Code:
session log
[5637]: 2017-09-01 13:24:26.479 DEBUG Execute: /usr/local/psa/bin/sw-engine-pleskrun /usr/local/psa/admin/sbin/backup_restore_helper --get-extensions-hooks
[5637]: 2017-09-01 13:24:27.276 TRACE SQL: SELECT id, host, port, type, admin_login, admin_password, server_version FROM DatabaseServers: Params:
[5637]: 2017-09-01 13:24:27.277 DEBUG Set backup owner guid '47296f90-8817-4018-b1b8-2c1c62424825', type 'server'
[5637]: 2017-09-01 13:24:27.277 DEBUG Select to backup Administrator info
[5637]: 2017-09-01 13:24:27.277 DEBUG Select to backup server settings
[5637]: 2017-09-01 13:24:27.277 DEBUG Select to backup server settings
[5637]: 2017-09-01 13:24:27.277 TRACE SQL: SELECT val FROM misc WHERE param = 'power_user_panel': Params:
[5637]: 2017-09-01 13:24:27.278 DEBUG Execute: /usr/local/psa/bin/sw-engine-pleskrun /usr/local/psa/admin/sbin/backup_restore_helper --backup-server-info
[5637]: 2017-09-01 13:24:28.119 TRACE SQL: SELECT e.name, e.value FROM ServiceNodeEnvironment AS e INNER JOIN ServiceNodes AS n ON e.serviceNodeId = n.id WHERE n.ipAddress = 'local' AND e.section = 'componentsPackages' AND e.value IS NOT NULL AND e.value <> '': Params:
[5637]: 2017-09-01 13:24:28.121 DEBUG Execute: /usr/local/psa/bin/sw-engine-pleskrun /usr/local/psa/admin/sbin/backup_restore_helper --backup-server
