BackupPC-users

[BackupPC-users] Empty backups marked as successful (rsyncd)

2013-03-26 21:07:45
Subject: [BackupPC-users] Empty backups marked as successful (rsyncd)
From: Adam Goryachev <mailinglists AT websitemanagers.com DOT au>
To: backuppc-users AT lists.sourceforge DOT net
Date: Wed, 27 Mar 2013 12:05:41 +1100
I've got backuppc running fairly well against a MS Windows 2008 server
using rsync with the shadowmount scripting. Most of the time, this host
is backed up properly, and works very well, but approximately once every
2 or 3 weeks, it will "fail" the backup the machine, yet backuppc marks
it as a successful backup. The big problem is that the next backup
(which is successful) will transfer the full content of the HD (about
100G) over the internet connection, because it is compared to the
previous successful backup which was empty.

Here is the complete backup log for the most recent backup (I've
modified the hostname in this output)

Contents of file /var/lib/backuppc/pc/hostname/XferLOG.1147.z, modified
2013-03-25 18:00:57

Executing DumpPreUserCmd: /usr/bin/ssh -q -x -l Administrator hostname 
/usr/bin/bash -l -c /home/Administrator/pre-backuppc.sh
Mon Mar 25 18:00:11 AUSEDT 2013
Mon Mar 25 18:00:11 AUSEDT 2013
Shadowing all drives
SUCCESS: Attempted to run the scheduled task "WMBackupPC".
incr backup started back to 2013-03-24 19:41:36 (backup #1146) for directory 
/cygdrive/c/shadow/c
Running: /usr/bin/ssh -C -q -x -l Administrator hostname /usr/bin/rsync 
--server --sender --numeric-ids --perms --owner --group --devices --links 
--times --block-size=2048 --recursive --checksum-seed=32761 -D 
--one-file-system . /cygdrive/c/shadow/c/
Xfer PIDs are now 3495
Got remote protocol 30
Negotiated protocol version 28
Checksum caching enabled (checksumSeed = 32761)
Sent exclude: /hiberfil.sys
Sent exclude: /pagefile.sys
Sent exclude: Application Data/Mozilla/Firefox/Profiles
Sent exclude: ProgramData/AVG2012
Xfer PIDs are now 3495,3496
  create d 755   544/513           0 .
Done: 0 files, 0 bytes
Executing DumpPostUserCmd: /usr/bin/ssh -q -x -l Administrator hostname 
/usr/bin/bash -l -c /home/Administrator/post-backuppc.sh
Mon Mar 25 18:00:37 AUSEDT 2013
Mon Mar 25 18:00:37 AUSEDT 2013
UnShadowing all drives
SUCCESS: Attempted to run the scheduled task "WMBackupPCPost".


Here is a snipped version of the same host for the earlier backup which
did backup all files:

Contents of file /var/lib/backuppc/pc/hostname/XferLOG.1146.z, modified
2013-03-24 20:32:09

Executing DumpPreUserCmd: /usr/bin/ssh -q -x -l Administrator hostname 
/usr/bin/bash -l -c /home/Administrator/pre-backuppc.sh
Sun Mar 24 19:41:39 AUSEDT 2013
Sun Mar 24 19:41:39 AUSEDT 2013
Shadowing all drives
SUCCESS: Attempted to run the scheduled task "WMBackupPC".
incr backup started back to 2013-03-24 02:30:51 (backup #1145) for directory 
/cygdrive/c/shadow/c
Running: /usr/bin/ssh -C -q -x -l Administrator hostname /usr/bin/rsync 
--server --sender --numeric-ids --perms --owner --group --devices --links 
--times --block-size=2048 --recursive --checksum-seed=32761 -D 
--one-file-system . /cygdrive/c/shadow/c/
Xfer PIDs are now 27960
Got remote protocol 30
Negotiated protocol version 28
Checksum caching enabled (checksumSeed = 32761)
Sent exclude: /hiberfil.sys
Sent exclude: /pagefile.sys
Sent exclude: Application Data/Mozilla/Firefox/Profiles
Sent exclude: ProgramData/AVG2012
Xfer PIDs are now 27960,29357
  create d 700   544/513           0 $AVG
  create d 700     18/18           0 $AVG/$CHJW
  create d 700   544/513           0 $AVG/$VAULT
  create   777   544/513         216 $AVG/$VAULT/vvfolder.idx
  create d 700 544/4294967295           0 $Recycle.Bin
  create d 700 4294967295/513           0 
$Recycle.Bin/S-1-5-21-2778639677-432647548-2969923321-1004
[SNIP]
create d 700 4294967295/513           0 temp/Canon iR1024 Scanner CD/res/common
  create d 700 4294967295/513           0 temp/Canon iR1024 Scanner CD/res/image
  create d 700 4294967295/513           0 temp/Canon iR1024 Scanner 
CD/res/uk_eng
  create d 700   544/513           0 temp/logs
Done: 121 files, 1487287247 bytes
Executing DumpPostUserCmd: /usr/bin/ssh -q -x -l Administrator hostname 
/usr/bin/bash -l -c /home/Administrator/post-backuppc.sh
Sun Mar 24 20:31:48 AUSEDT 2013
Sun Mar 24 20:31:48 AUSEDT 2013
UnShadowing all drives
SUCCESS: Attempted to run the scheduled task "WMBackupPCPost".


Note, in both the global config and this host config I have:
$Conf{BackupZeroFilesIsFatal} = '1';

The problem seems to be that backing up the . directory is enough to
cause the backup to be marked as successful. In reality, there were no
*files* backed up, only a single directory. It might be helpful to have
an option to specify how many files much be backed up to be considered
successful (in my case, 2 would probably be sufficient).

Any suggestions or assistance on how to prevent this from happening and
chewing up all my bandwidth would be appreciated.

PS, once I notice this has happened, and if I notice before the next
backup has succeeded, then I will just kill the in-progress backup,
delete the most recent partial backup, delete the "empty" backup, and
then re-run a backup which succeeds.

Regards,
Adam

-- 
Adam Goryachev
Website Managers
www.websitemanagers.com.au


------------------------------------------------------------------------------
Own the Future-Intel&reg; Level Up Game Demo Contest 2013
Rise to greatness in Intel's independent game demo contest.
Compete for recognition, cash, and the chance to get your game 
on Steam. $5K grand prize plus 10 genre and skill prizes. 
Submit your demo by 6/6/13. http://p.sf.net/sfu/intel_levelupd2d
_______________________________________________
BackupPC-users mailing list
BackupPC-users AT lists.sourceforge DOT net
List:    https://lists.sourceforge.net/lists/listinfo/backuppc-users
Wiki:    http://backuppc.wiki.sourceforge.net
Project: http://backuppc.sourceforge.net/

<Prev in Thread] Current Thread [Next in Thread>
  • [BackupPC-users] Empty backups marked as successful (rsyncd), Adam Goryachev <=