Bug 683800

Summary: Previously working backup config fails since 1.0.6
Product: [Fedora] Fedora Reporter: Tim Waugh <twaugh>
Component: backintimeAssignee: Tim Jackson <rpm>
Status: CLOSED WORKSFORME QA Contact: Fedora Extras Quality Assurance <extras-qa>
Severity: unspecified Docs Contact:
Priority: unspecified    
Version: 14CC: rpm
Target Milestone: ---   
Target Release: ---   
Hardware: Unspecified   
OS: Unspecified   
Whiteboard:
Fixed In Version: Doc Type: Bug Fix
Doc Text:
Story Points: ---
Clone Of: Environment:
Last Closed: 2012-07-17 19:46:59 UTC Type: ---
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:

Description Tim Waugh 2011-03-10 12:00:05 UTC
Description of problem:
Ever since backintime was updated to 1.0.6, back-ups have been failing for me.  I have run "Back In Time (root)" and converted the file layout to the new format.

I am running backintime backups via a cron script in /etc/cron.daily/:

#!/bin/sh
HOME=/root nice -n 19 /usr/bin/backintime --backup-job >/dev/null 2>&1

Version-Release number of selected component (if applicable):
backintime-gnome-1.0.6-2.fc14.noarch
backintime-common-1.0.6-2.fc14.noarch

How reproducible:
Every time.

Steps to Reproduce:
1.Let backup script run.
  
Actual results:
These messages in /var/log/messages:

Mar 10 10:48:27 worm backintime (root): INFO: Lock
Mar 10 10:48:27 worm backintime (root): INFO: on process begins
Mar 10 10:48:27 worm backintime (root): INFO: Profile_id: 1
Mar 10 10:48:42 worm backintime (root): INFO: Compare with old snapshot: 20110212-111501
Mar 10 10:57:37 worm backintime (root): WARNING: Command "rsync -rtDH --links --no-p --no-g --no-o  --delete --delete-excluded  -i --dry-run --out-format="BACKINTIME: %i %n%L" --chmod=Du+wx  --exclude="/mnt/backup" --exclude="/root/.local/share/backintime" --include="/etc/" --include="/var/" --include="/home/" --exclude="*.backup*" --exclude="*~" --exclude="/home/twaugh/VM" --exclude="*.src.rpm" --exclude="*-*_*-*_*" --exclude="noarch" --exclude="x86_64" --exclude="*.iso" --exclude="/var/cache" --exclude=".ccache" --exclude=".cache" --exclude="Cache" --exclude="/var/lib" --exclude="/home/twaugh/Video Editing" --include="/etc/**" --include="/var/**" --include="/home/**" --exclude="*" / "/mnt/backup/backintime/worm.elk/root/1/20110212-111501/backup/"" returns 5888
Mar 10 10:57:37 worm backintime (root): INFO: Create hard-links
Mar 10 10:59:09 worm backintime (root): INFO: Command "find "/mnt/backup/backintime/worm.elk/root/1/20110212-111501/backup/" -type d -exec chmod u+wx {} \;" returns 0
Mar 10 11:10:48 worm backintime (root): INFO: Command "cp -aRl "/mnt/backup/backintime/worm.elk/root/1/20110212-111501/backup/"* "/mnt/backup/backintime/worm.elk/root/1/new_snapshot/backup/"" returns 0
Mar 10 11:12:11 worm backintime (root): INFO: Command "find "/mnt/backup/backintime/worm.elk/root/1/20110212-111501/backup/" -type d -exec chmod a-w {} \;" returns 0
Mar 10 11:19:00 worm backintime (root): INFO: Command "chmod -R a+w "/mnt/backup/backintime/worm.elk/root/1/new_snapshot"" returns 0
Mar 10 11:19:00 worm backintime (root): INFO: Call rsync to take the snapshot
Mar 10 11:29:50 worm backintime (root): WARNING: Command "rsync -rtDH --links --no-p --no-g --no-o  --delete --delete-excluded  -v  --chmod=Du+wx  --exclude="/mnt/backup" --exclude="/root/.local/share/backintime" --include="/etc/" --include="/var/" --include="/home/" --exclude="*.backup*" --exclude="*~" --exclude="/home/twaugh/VM" --exclude="*.src.rpm" --exclude="*-*_*-*_*" --exclude="noarch" --exclude="x86_64" --exclude="*.iso" --exclude="/var/cache" --exclude=".ccache" --exclude=".cache" --exclude="Cache" --exclude="/var/lib" --exclude="/home/twaugh/Video Editing" --include="/etc/**" --include="/var/**" --include="/home/**" --exclude="*" / "/mnt/backup/backintime/worm.elk/root/1/new_snapshot/backup/" 2>&1" returns 5888
Mar 10 11:31:51 worm backintime (root): INFO: Command "find "/mnt/backup/backintime/worm.elk/root/1/new_snapshot" -type d -exec chmod u+wx {} \;" returns 0
Mar 10 11:41:42 worm backintime (root): INFO: Command "rm -rf "/mnt/backup/backintime/worm.elk/root/1/new_snapshot"" returns 0
Mar 10 11:48:47 worm backintime (root): INFO: Command "chmod -R a-w "/mnt/backup/backintime/worm.elk/root/1/20110212-111501/backup/"" returns 0
Mar 10 11:48:47 worm backintime (root): INFO: Command "rm -rf "/mnt/backup/backintime/worm.elk/root/1/20110310-104827-768"" returns 0
Mar 10 11:48:47 worm backintime (root): ERROR: Failed to take snapshot !!!
Mar 10 11:48:51 worm backintime (root): INFO: Unlock

Comment 1 Tim Jackson 2012-07-17 19:46:59 UTC
This problem hasn't been reported by any other users in the significant time since that upgrade. Looking at the output, rsync seems to return a crazy return code (5888) which doesn't match any one documented in the manual. I think you need to debug that rsync command and see if you can reproduce it outside backintime, and track back from there. Maybe some of those globs are questionable; not sure whether some of them are generated by backintime?

I'm going to close this bug as I don't think there is anything I can do in the Fedora packaging of this, and support issues would be better handled upstream, but feel free to re-open if there is a packaging issue causing this.