SOLVED Error Pruning of backup files skipped due to errors

[ally1]

Hello cPanel community and support Team,

The core of the issue - incomplete backup execution. Server saves backups in the remote AWS S3 bucket but for the purity of the experiment I changed the destination to test backup server (via SFTP protocol) but each time the backup execution process ends up with the same result.

Below I posted the outcome from the /usr/local/cpanel/logs/cpbackup/1519545603.log file.
NOTICE-1: Each time server does backup from 10-20 accounts (while the total amount of accounts 65);
NOTICE-2: The process interrupts at random user;

##############################
[2018-02-25 04:13:06 -0600] Done
[2018-02-25 04:13:06 -0600] pkgacctfile is: /backup/weekly/2018-02-25/accounts/getreal.tar.gz
[2018-02-25 04:13:06 -0600]
[2018-02-25 04:13:06 -0600] size is: 602764363
[2018-02-25 04:13:06 -0600]
[2018-02-25 04:13:06 -0600] homesize is: 1030180864
[2018-02-25 04:13:06 -0600]
[2018-02-25 04:13:06 -0600] homefiles is: 31026
[2018-02-25 04:13:06 -0600] pkgacct completed
[2018-02-25 04:13:25 -0600] info [backup] Queuing weekly backup copy of “getreal” for transport of “/backup/weekly/2018-02-25/accounts/getreal.tar.gz” to “weekly/2018-02-25/accounts/getreal.tar.gz”
[2018-02-25 04:13:27 -0600] info [backup] Queuing transport of file: /backup/weekly/2018-02-25/accounts/getreal.tar.gz
[2018-02-25 04:13:27 -0600] info [backup] no_transport = 0 .. and queueid = TQ:TaskQueue:1245
[2018-02-25 04:13:27 -0600] info [backup] leaving queue_backup_transport_item
[2018-02-25 04:13:27 -0600] info [backup] Queuing transport of file: /backup/weekly/2018-02-25/accounts/getreal-=-meta
[2018-02-25 04:13:27 -0600] info [backup] no_transport = 0 .. and queueid = TQ:TaskQueue:1246
[2018-02-25 04:13:27 -0600] info [backup] leaving queue_backup_transport_item
[2018-02-25 04:13:27 -0600] info [backup] Queuing transport of file: /backup/weekly/2018-02-25/backup_incomplete
[2018-02-25 04:13:27 -0600] info [backup] no_transport = 0 .. and queueid = TQ:TaskQueue:1247
[2018-02-25 04:13:27 -0600] info [backup] leaving queue_backup_transport_item
[2018-02-25 04:13:27 -0600] warn [backup] Pruning of backup files skipped due to errors. at /usr/local/cpanel/bin/backup line 404.
        bin::backup::run("bin::backup") called at /usr/local/cpanel/bin/backup line 110
[2018-02-25 04:13:27 -0600] warn [backup] Pruning of backup files skipped due to errors.
[2018-02-25 04:13:27 -0600] info [backup] Queuing transport reporter
[2018-02-25 04:13:27 -0600] info [backup] no_transport = 0 .. and queueid = TQ:TaskQueue:1248
[2018-02-25 04:13:27 -0600] info [backup] leaving queue_backup_transport_item
[2018-02-25 04:13:27 -0600] info [backup] Completed at Sun Feb 25 04:13:27 2018
[2018-02-25 04:13:27 -0600] info [backup] Final state is Backup::PartialFailure (0)
[2018-02-25 04:13:27 -0600] info [backup] Sent Backup::PartialFailure notification.

##############################

Would be glad for any help.

 

[cPanelMichael]

[2018-02-25 04:13:27 -0600] warn [backup] Pruning of backup files skipped due to errors. at /usr/local/cpanel/bin/backup line 404.
bin::backup::run("bin::backup") called at /usr/local/cpanel/bin/backup line 110
[2018-02-25 04:13:27 -0600] warn [backup] Pruning of backup files skipped due to errors.

Hello,

Could you open a support ticket using the link in my signature so we can take a closer look to see why the backup process is failing?

Thank you.

 

[ally1]

Hi cPanel support team and folks,

For those who encountered similar issue.

1. Look to the error on the abckup log file:
grep -i err /usr/local/cpanel/logs/cpbackup/1519282801.log

My output was:

##################################################################################
[2018-03-01 05:07:36 -0600] Determining mysql dbs...warn [cpmysqladmin] The system failed to fetch the status of the database “volkert_dev” because of an error: (XID hq6atp) The system received an error from the “MySQL” database “mysql”: ER_BAD_DB_ERROR (Unknown database 'volkert_dev')
volkert_devexec(/usr/bin/mysqldump,--databases --skip-triggers --no-data --no-create-info -- volkert_dev) exited with error: The “/usr/bin/mysqldump” command (process 731816) reported error number 2 when it ended. at /usr/local/cpanel/Cpanel/Pkgacct.pm line 459.
[2018-03-01 05:07:43 -0600] volkert_dev: mysqldump: Got error: 1049: Unknown database 'volkert_dev' when selecting the database
warn [pkgacct] volkert_dev: mysqldump: Got error: 1049: Unknown database 'volkert_dev' when selecting the database
exec(/usr/bin/mysqldump,--complete-insert --quote-names --quick --single-transaction --events --routines --triggers --routines -- volkert_dev) exited with error: The “/usr/bin/mysqldump” command (process 731819) reported error number 2 when it ended. at /usr/local/cpanel/Cpanel/Pkgacct.pm line 459.
warn [pkgacct] Failed to dump database volkert_dev: The subprocess reported error number 2 when it ended.
[2018-03-01 05:07:43 -0600] Failed to dump database volkert_dev: The subprocess reported error number 2 when it ended.
[2018-03-01 05:44:52 -0600] warn [backup] Pruning of backup files skipped due to errors. at /usr/local/cpanel/bin/backup line 404.
##################################################################################

3. The cause of the problem was volkert_dev that was deleted outside of WHM/cPanel. This DB 'volkert_dev' was not exist in the /var/lib/mysql/ folder, but the name of 'volkert_dev' still was in /var/cpanel/databases/volkert.json file.
(cPanel uses a system that relies heavily on the content in /var/cpanel/databases to handle the mapping of the databases and database users to specific cPanel accounts.)

2. I just deleted 'volkert_dev' DB from the cpanel account, and the restarte backup. Backup execution finished successfully.

Thanks cPanel team for the super help!

Best Regards,
Ally L.

 

[cPanelMichael]

Hello,

I'm glad to see the issue is now solved. Thank you for sharing the outcome.