OK, so what is my problem? I don't know where else to look. I tried again on my main backup drive. I launched the BiT GUI and clicked the snapshot icon to take a snapshot (Log-1). It seemed to work apart for a couple of warning with 5888 and 3072 errors. But BiT went through the complete process. Then when the next scheduled snapshot was due BIT got stuck again on compare with previous snapshot for an hour (Log-2). I then killed the python process and launched the BiT GUI and clicked the snapshot icon to take another snapshot and it worked again (Log-3). So it seems killing the python BiT process and taking another snapshot via the BiT GUi works but the scheduled snapshot doesn't. Also, I don't think the problem is that it's taking too long to compare snapshots because when I start a snapshot from the GUi it completes in about 30mins.
Log-1: Jan 15 14:36:21 suntp001 backintime (Gxxxx): INFO: Lock Jan 15 14:36:21 suntp001 backintime (Gxxxx): INFO: Include folders: ['/boot', '/srv', '/VAULT/Documents', '/home/Gxxxx', '/etc', '/usr/local', '/usr/lib', '/usr/share'] Jan 15 14:36:21 suntp001 backintime (Gxxxx): INFO: Ignore folders: [] Jan 15 14:36:21 suntp001 backintime (Gxxxx): INFO: Last snapshots: {} Jan 15 14:36:21 suntp001 backintime (Gxxxx): INFO: [KDE4Plugin.Systray.run] Jan 15 14:36:21 suntp001 backintime (Gxxxx): INFO: Compare with old snapshot: 20100113-130225 Jan 15 14:36:22 suntp001 backintime (Gxxxx): INFO: [KDE4Plugin.Systray.run] begin loop Jan 15 14:37:08 suntp001 backintime (Gxxxx): WARNING: Command "rsync -aEAX -i --dry-run --chmod=Fa-w,D+w --whole-file --delete --exclude="/media/Backup/TPX60s" --exclude="/home/Gxxxx/.local/share/backintime" --include="/boot/" --include="/srv/" --include="/VAULT/Documents/" --include="/VAULT/" --include="/home/Gxxxx/" --include="/home/" --include="/etc/" --include="/usr/local/" --include="/usr/" --include="/usr/lib/" --include="/usr/share/" --exclude="*.backup*" --exclude="*~" --exclude="/home/Gxxxx/.local/share/Trash/" --exclude="/home/Gxxxx/.gvfs" --exclude="/VAULT/Documents/WebSite" --exclude="/VAULT/Downloads" --include="/boot/**" --include="/srv/**" --include="/VAULT/Documents/**" --include="/home/Gxxxx/**" --include="/etc/**" --include="/usr/local/**" --include="/usr/lib/**" --include="/usr/share/**" --exclude="*" / "/media/Backup/TPX60s/backintime/20100113-130225/backup/"" returns 5888 Jan 15 14:37:08 suntp001 backintime (Gxxxx): INFO: Create hard-links Jan 15 14:37:36 suntp001 backintime (Gxxxx): INFO: Command "cp -al "/media/Backup/TPX60s/backintime/20100113-130225/backup/"* "/media/Backup/TPX60s/backintime/new_snapshot/backup/"" returns 0 Jan 15 14:37:36 suntp001 backintime (Gxxxx): INFO: Call rsync to take the snapshot Jan 15 14:50:45 suntp001 rsyslogd: -- MARK -- Jan 15 14:51:30 suntp001 smartd[4593]: Device: /dev/sda [SAT], SMART Usage Attribute: 194 Temperature_Celsius changed from 137 to 127 Jan 15 14:55:38 suntp001 backintime (Gxxxx): WARNING: Command "rsync -aEAX -v --delete-excluded --chmod=Fa-w,D+w --whole-file --delete --exclude="/media/Backup/TPX60s" --exclude="/home/Gxxxx/.local/share/backintime" --include="/boot/" --include="/srv/" --include="/VAULT/Documents/" --include="/VAULT/" --include="/home/Gxxxx/" --include="/home/" --include="/etc/" --include="/usr/local/" --include="/usr/" --include="/usr/lib/" --include="/usr/share/" --exclude="*.backup*" --exclude="*~" --exclude="/home/Gxxxx/.local/share/Trash/" --exclude="/home/Gxxxx/.gvfs" --exclude="/VAULT/Documents/WebSite" --exclude="/VAULT/Downloads" --include="/boot/**" --include="/srv/**" --include="/VAULT/Documents/**" --include="/home/Gxxxx/**" --include="/etc/**" --include="/usr/local/**" --include="/usr/lib/**" --include="/usr/share/**" --exclude="*" / "/media/Backup/TPX60s/backintime/new_snapshot/backup/"" returns 3072 Jan 15 14:55:38 suntp001 backintime (Gxxxx): INFO: Save permissions Jan 15 14:57:15 suntp001 backintime (Gxxxx): INFO: [smart remove] keep all >= 20100114-000000 Jan 15 14:57:15 suntp001 backintime (Gxxxx): INFO: [smart remove] keep first >= 20100104-000000 and < 20100111-000000 Jan 15 14:57:15 suntp001 backintime (Gxxxx): INFO: [smart remove] keep first >= 20091228-000000 and < 20100104-000000 Jan 15 14:57:15 suntp001 backintime (Gxxxx): INFO: [smart remove] keep snapshots: ['20100115-143621'] Jan 15 14:57:15 suntp001 backintime (Gxxxx): INFO: [smart remove] remove snapshot: 20100113-130225 Jan 15 14:57:34 suntp001 backintime (Gxxxx): INFO: Command "chmod -R a+rwx "/media/Backup/TPX60s/backintime/20100113-130225"" returns 0 Jan 15 14:57:46 suntp001 backintime (Gxxxx): INFO: Command "rm -rfv "/media/Backup/TPX60s/backintime/20100113-130225"" returns 0 Jan 15 14:57:46 suntp001 backintime (Gxxxx): INFO: [smart remove] remove snapshot: 20100112-142528 Jan 15 14:58:30 suntp001 backintime (Gxxxx): INFO: Command "chmod -R a+rwx "/media/Backup/TPX60s/backintime/20100112-142528"" returns 0 Jan 15 14:59:12 suntp001 backintime (Gxxxx): INFO: Command "rm -rfv "/media/Backup/TPX60s/backintime/20100112-142528"" returns 0 Jan 15 14:59:12 suntp001 backintime (Gxxxx): INFO: [smart remove] remove snapshot: 20100112-121613 Jan 15 14:59:45 suntp001 backintime (Gxxxx): INFO: Command "chmod -R a+rwx "/media/Backup/TPX60s/backintime/20100112-121613"" returns 0 Jan 15 15:00:01 suntp001 /usr/sbin/cron[23862]: (Gxxxx) CMD (nice -n 19 /usr/bin/backintime --backup-job >/dev/null 2>&1) Jan 15 15:00:05 suntp001 backintime (Gxxxx): WARNING: A backup is already running Jan 15 15:00:24 suntp001 backintime (Gxxxx): INFO: Command "rm -rfv "/media/Backup/TPX60s/backintime/20100112-121613"" returns 0 Jan 15 15:00:25 suntp001 backintime (Gxxxx): INFO: [smart remove] remove snapshot: 20100112-090001 Jan 15 15:00:57 suntp001 backintime (Gxxxx): INFO: Command "chmod -R a+rwx "/media/Backup/TPX60s/backintime/20100112-090001"" returns 0 Jan 15 15:01:31 suntp001 backintime (Gxxxx): INFO: Command "rm -rfv "/media/Backup/TPX60s/backintime/20100112-090001"" returns 0 Jan 15 15:01:31 suntp001 backintime (Gxxxx): INFO: Keep min free disk space: 200 Mb Jan 15 15:01:33 suntp001 backintime (Gxxxx): INFO: [KDE4Plugin.Systray.run] end loop Jan 15 15:01:33 suntp001 backintime (Gxxxx): INFO: Unlock Log-2: Jan 15 16:00:03 suntp001 backintime (Gxxxx): INFO: Lock Jan 15 16:00:03 suntp001 backintime (Gxxxx): INFO: Include folders: ['/boot', '/srv', '/VAULT/Documents', '/home/Gxxxx', '/etc', '/usr/local', '/usr/lib', '/usr/share'] Jan 15 16:00:03 suntp001 backintime (Gxxxx): INFO: Ignore folders: [] Jan 15 16:00:03 suntp001 backintime (Gxxxx): INFO: Last snapshots: {} Jan 15 16:00:03 suntp001 backintime (Gxxxx): INFO: [KDE4Plugin.Systray.run] Jan 15 16:00:04 suntp001 backintime (Gxxxx): INFO: Compare with old snapshot: 20100115-143621 Jan 15 16:00:04 suntp001 backintime (Gxxxx): INFO: [KDE4Plugin.Systray.run] begin loop Log-3 Jan 15 16:26:19 suntp001 backintime (Gxxxx): INFO: Lock Jan 15 16:26:19 suntp001 backintime (Gxxxx): INFO: Include folders: ['/boot', '/srv', '/VAULT/Documents', '/home/Gxxxx', '/etc', '/usr/local', '/usr/lib', '/usr/share'] Jan 15 16:26:19 suntp001 backintime (Gxxxx): INFO: Ignore folders: [] Jan 15 16:26:19 suntp001 backintime (Gxxxx): INFO: Last snapshots: {} Jan 15 16:26:19 suntp001 backintime (Gxxxx): INFO: [KDE4Plugin.Systray.run] Jan 15 16:26:19 suntp001 backintime (Gxxxx): INFO: Compare with old snapshot: 20100115-143621 Jan 15 16:26:19 suntp001 backintime (Gxxxx): INFO: [KDE4Plugin.Systray.run] begin loop Jan 15 16:28:33 suntp001 backintime (Gxxxx): WARNING: Command "rsync -aEAX -i --dry-run --chmod=Fa-w,D+w --whole-file --delete --exclude="/media/Backup/TPX60s" --exclude="/home/Gxxxx/.local/share/backintime" --include="/boot/" --include="/srv/" --include="/VAULT/Documents/" --include="/VAULT/" --include="/home/Gxxxx/" --include="/home/" --include="/etc/" --include="/usr/local/" --include="/usr/" --include="/usr/lib/" --include="/usr/share/" --exclude="*.backup*" --exclude="*~" --exclude="/home/Gxxxx/.local/share/Trash/" --exclude="/home/Gxxxx/.gvfs" --exclude="/VAULT/Documents/WebSite" --exclude="/VAULT/Downloads" --include="/boot/**" --include="/srv/**" --include="/VAULT/Documents/**" --include="/home/Gxxxx/**" --include="/etc/**" --include="/usr/local/**" --include="/usr/lib/**" --include="/usr/share/**" --exclude="*" / "/media/Backup/TPX60s/backintime/20100115-143621/backup/"" returns 5888 Jan 15 16:28:33 suntp001 backintime (Gxxxx): INFO: Create hard-links Jan 15 16:28:55 suntp001 backintime (Gxxxx): INFO: Command "cp -al "/media/Backup/TPX60s/backintime/20100115-143621/backup/"* "/media/Backup/TPX60s/backintime/new_snapshot/backup/"" returns 0 Jan 15 16:28:55 suntp001 backintime (Gxxxx): INFO: Call rsync to take the snapshot Jan 15 16:38:26 suntp001 backintime (Gxxxx): WARNING: Command "rsync -aEAX -v --delete-excluded --chmod=Fa-w,D+w --whole-file --delete --exclude="/media/Backup/TPX60s" --exclude="/home/Gxxxx/.local/share/backintime" --include="/boot/" --include="/srv/" --include="/VAULT/Documents/" --include="/VAULT/" --include="/home/Gxxxx/" --include="/home/" --include="/etc/" --include="/usr/local/" --include="/usr/" --include="/usr/lib/" --include="/usr/share/" --exclude="*.backup*" --exclude="*~" --exclude="/home/Gxxxx/.local/share/Trash/" --exclude="/home/Gxxxx/.gvfs" --exclude="/VAULT/Documents/WebSite" --exclude="/VAULT/Downloads" --include="/boot/**" --include="/srv/**" --include="/VAULT/Documents/**" --include="/home/Gxxxx/**" --include="/etc/**" --include="/usr/local/**" --include="/usr/lib/**" --include="/usr/share/**" --exclude="*" / "/media/Backup/TPX60s/backintime/new_snapshot/backup/"" returns 5888 Jan 15 16:38:26 suntp001 backintime (Gxxxx): INFO: Save permissions Jan 15 16:39:46 suntp001 backintime (Gxxxx): INFO: [smart remove] keep all >= 20100114-000000 Jan 15 16:39:46 suntp001 backintime (Gxxxx): INFO: [smart remove] keep first >= 20100104-000000 and < 20100111-000000 Jan 15 16:39:46 suntp001 backintime (Gxxxx): INFO: [smart remove] keep first >= 20091228-000000 and < 20100104-000000 Jan 15 16:39:46 suntp001 backintime (Gxxxx): INFO: [smart remove] keep snapshots: ['20100115-162619', '20100115-143621'] Jan 15 16:39:46 suntp001 backintime (Gxxxx): INFO: Keep min free disk space: 200 Mb Jan 15 16:39:48 suntp001 backintime (Gxxxx): INFO: [KDE4Plugin.Systray.run] end loop Jan 15 16:39:48 suntp001 backintime (Gxxxx): INFO: Unlock -- BiT continually stuck on Comparing snapshot...and permission problems https://bugs.launchpad.net/bugs/506876 You received this bug notification because you are a member of Back In Time Team, which is subscribed to Back In Time. Status in Back In Time: New Bug description: I'm posting this as requested by Dan on the Answers section https://answers.launchpad.net/backintime/+question/97137 BiT seems to have stopped working for me on a fresh installation of openSUSE 11.2. It seems to create the first snapshot fine but thereafter just gets stuck on Comparing snapshot. I have it configured to take a snapshot once every hour. I have the default excludes and tried adding .gvfs as an exclude (as mentioned in another thread) but couldn't. When I run BiT from the terminal as "backintime -b" it seems to run fine except I get a lot of permission denied errors. See output below. Back In Time Version: 0.9.26 Back In Time comes with ABSOLUTELY NO WARRANTY. This is free software, and you are welcome to redistribute it under certain conditions; type `backintime --license' for details. INFO: Lock INFO: Include folders: ['/boot', '/srv', '/VAULT/Documents', '/home', '/etc', '/usr/local', '/usr/lib', '/usr/share'] INFO: Ignore folders: [] INFO: Last snapshots: {} INFO: [KDE4Plugin.Systray.run] INFO: [KDE4Plugin.Systray.run] begin loop INFO: Compare with old snapshot: 20100112-090001 rsync: opendir "/boot/lost+found" failed: Permission denied (13) rsync: opendir "/etc/audisp" failed: Permission denied (13) rsync: opendir "/etc/audit" failed: Permission denied (13) rsync: opendir "/etc/cups/ssl" failed: Permission denied (13) rsync: opendir "/etc/news" failed: Permission denied (13) rsync: opendir "/etc/ppp" failed: Permission denied (13) ...etc I also get... rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1039) [sender=3.0.6] WARNING: Command "rsync -aEAX -i --dry-run --chmod=Fa-w,D+w --whole-file --delete --exclude="/media/Backup/TPX60s" --exclude="/home/Gxxxx/.local/share/backintime" --include="/boot/" --include="/srv/" --include="/VAULT/Documents/" --include="/VAULT/" --include="/home/" --include="/etc/" --include="/usr/local/" --include="/usr/" --include="/usr/lib/" --include="/usr/share/" --exclude="*.backup*" --exclude="*~" --include="/boot/**" --include="/srv/**" --include="/VAULT/Documents/**" --include="/home/**" --include="/etc/**" --include="/usr/local/**" --include="/usr/lib/**" --include="/usr/share/**" --exclude="*" / "/media/Backup/TPX60s/backintime/20100112-090001/backup/"" returns 5888 Then lots more permission denied errors INFO: Create hard-links INFO: Command "cp -al "/media/Backup/TPX60s/backintime/20100112-090001/backup/"* "/media/Backup/TPX60s/backintime/new_snapshot/backup/"" returns 0 INFO: Call rsync to take the snapshot rsync: opendir "/boot/lost+found" failed: Permission denied (13) rsync: opendir "/etc/audisp" failed: Permission denied (13) rsync: opendir "/etc/audit" failed: Permission denied (13) rsync: opendir "/etc/cups/ssl" failed: Permission denied (13) rsync: send_files failed to open "/boot/backup_mbr": Permission denied (13) rsync: send_files failed to open "/boot/grub/device.map": Permission denied (13) rsync: send_files failed to open "/boot/grub/device.map.old": Permission denied (13) rsync: send_files failed to open "/boot/grub/menu.lst": Permission denied (13) rsync: send_files failed to open "/boot/grub/menu.lst.old": Permission denied (13) rsync: send_files failed to open "/etc/.pwd.lock": Permission denied (13) rsync: send_files failed to open "/etc/at.deny": Permission denied (13) rsync: send_files failed to open "/etc/autofs_ldap_auth.conf": Permission denied (13) rsync: send_files failed to open "/etc/cron.deny": Permission denied (13) ...etc.. And then completes wit rsync: send_files failed to open "/usr/share/omc/svcinfo.d/syslog.xml": Permission denied (13) rsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1039) [sender=3.0.6] WARNING: Command "rsync -aEAX -v --delete-excluded --chmod=Fa-w,D+w --whole-file --delete --exclude="/media/Backup/TPX60s" --exclude="/home/Gxxxx/.local/share/backintime" --include="/boot/" --include="/srv/" --include="/VAULT/Documents/" --include="/VAULT/" --include="/home/" --include="/etc/" --include="/usr/local/" --include="/usr/" --include="/usr/lib/" --include="/usr/share/" --exclude="*.backup*" --exclude="*~" --include="/boot/**" --include="/srv/**" --include="/VAULT/Documents/**" --include="/home/**" --include="/etc/**" --include="/usr/local/**" --include="/usr/lib/**" --include="/usr/share/**" --exclude="*" / "/media/Backup/TPX60s/backintime/new_snapshot/backup/"" returns 5888 INFO: Save permissions INFO: [smart remove] keep all >= 20100111-000000 INFO: [smart remove] keep first >= 20100104-000000 and < 20100111-000000 INFO: [smart remove] keep first >= 20091228-000000 and < 20100104-000000 INFO: [smart remove] keep snapshots: ['20100112-121613', '20100112-090001', '20100111-141531'] INFO: Keep min free disk space: 200 Mb INFO: [KDE4Plugin.Systray.run] end loop INFO: Unlock Why am I getting these permission errors and what are those WARNINGS? This is the what's logged in the syslog from BiT started by the Cron job. Jan 12 11:00:01 suntp001 /usr/sbin/cron[11440]: (Gxxxx) CMD (nice -n 19 /usr/bin/backintime --backup-job >/dev/null 2>&1) Jan 12 11:00:01 suntp001 backintime (Gxxxx): INFO: Lock Jan 12 11:00:01 suntp001 backintime (Gxxxx): INFO: Include folders: ['/boot', '/srv', '/VAULT/Documents', '/home', '/etc', '/usr/local', '/usr/lib', '/usr/share'] Jan 12 11:00:01 suntp001 backintime (Gxxxx): INFO: Ignore folders: [] Jan 12 11:00:01 suntp001 backintime (Gxxxx): INFO: Last snapshots: {} Jan 12 11:00:01 suntp001 backintime (Gxxxx): INFO: [KDE4Plugin.Systray.run] Jan 12 11:00:02 suntp001 backintime (Gxxxx): INFO: Compare with old snapshot: 20100112-090001 Jan 12 11:00:02 suntp001 backintime (Gxxxx): INFO: [KDE4Plugin.Systray.run] begin loop Jan 12 11:15:59 suntp001 su: (to root) Gxxxx on /dev/pts/4 Jan 12 11:16:00 suntp001 su: (to root) Gxxxx on /dev/pts/4 Jan 12 11:26:04 suntp001 smartd[2752]: Device: /dev/sda [SAT], SMART Usage Attribute: 194 Temperature_Celsius changed from 148 to 144 Jan 12 11:45:54 suntp001 rsyslogd: -- MARK -- Jan 12 12:00:01 suntp001 /usr/sbin/cron[13050]: (Gxxxx) CMD (nice -n 19 /usr/bin/backintime --backup-job >/dev/null 2>&1) Jan 12 12:00:02 suntp001 backintime (Gxxxx): WARNING: A backup is already running Notice that a hour passes and a warning is thrown that BiT is already running. Nothing really happened during that hour and the whole time the tray icon just showed Comparing with snapshot 20100112-090001. If you require any other info or log output let me know. _______________________________________________ Mailing list: https://launchpad.net/~bit-team Post to : bit-team@lists.launchpad.net Unsubscribe : https://launchpad.net/~bit-team More help : https://help.launchpad.net/ListHelp