Comment 1 for bug 585358

NSSBackup 'Default Profile' Logger
==============
2010-05-25 17:48:04,773 - INFO in ConfigManager.__create_logger(713): Log output for [Default Profile] is directed to file '/var/log/nssbackup.log'.
2010-05-25 17:48:04,774 - DEBUG in ConfigManager.validateConfigFileOpts(760): Validating config file.
2010-05-25 17:48:04,774 - INFO in ConfigManager.__init__(324): Profile settings are being read from file '/etc/nssbackup.conf'.
2010-05-25 17:48:04,774 - DEBUG in nssbackupd.__init__(84): Not So Simple Backup Suite 0.2-0
2010-05-25 17:48:04,824 - DEBUG in BackupManager.__init__(249): Instance of BackupManager created.
2010-05-25 17:48:04,835 - INFO in BackupManager.makeBackup(268): Backup process is being started.
2010-05-25 17:48:04,836 - DEBUG in BackupManager.__setlockfile(441): Created lockfile at '/var/lock/nssbackup.lock' with info '2925'
2010-05-25 17:48:04,836 - INFO in FuseFAM.initialize(176): Initializing FUSE File Access Manager.
2010-05-25 17:48:04,837 - DEBUG in FuseFAM.initialize(242): [log]
file = '/var/log/nssbackup.log'
level = '10'
[places]
prefix = '/usr'
[schedule]
anacron = 'daily'
[dirconfig]
/media/ = '0'
/home/.....
/home/.....
/var/cache/ = '0'
/home/.......
/var/spool/ = '0'
/var/tmp/ = '0'
/home/.......
/etc/ = '1'
[general]
splitsize = '0'
target = '/media/backup/dailybackup'
format = 'bzip2'
mountdir = '/mnt/nssbackup'
maxincrement = '7'
purge = '10'
followlinks = '0'
lockfile = '/var/lock/nssbackup.lock'
[report]
from = 'NSsbackup Daemon <email address hidden>'
to = '..........'
smtppassword = '.......'
smtptls = '1'
smtpuser = '.............................'
smtpport = '587'
smtpserver = '.........................'
[exclude]
regex = '\\.mkv,/home/[^/]+?/\\.thumbnails/,/home/[^/]+?/\\.Trash,/home/[^/]+?/\\..+/[cC]ache,\\..vdi'
maxsize = '157286400'
2010-05-25 17:48:04,837 - INFO in UpgradeManager.need_upgrade(140): Checking need for upgrading in '/media/backup/dailybackup'
2010-05-25 17:48:04,840 - DEBUG in SnapshotManager.get_snapshots(204): [Snapshots Listing - current format]
2010-05-25 17:48:04,840 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-23_10.03.24.877220.lucid.inc
2010-05-25 17:48:04,840 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-21_06.28.06.547394.lucid.inc
2010-05-25 17:48:04,840 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-20_06.27.39.167673.lucid.ful
2010-05-25 17:48:04,840 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-19_20.54.48.455397.lucid.inc
2010-05-25 17:48:04,840 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-18_06.31.59.387443.lucid.inc
2010-05-25 17:48:04,840 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-17_06.18.56.506006.lucid.inc
2010-05-25 17:48:04,841 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-16_13.21.05.248497.lucid.inc
2010-05-25 17:48:04,841 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-15_07.54.49.296694.lucid.inc
2010-05-25 17:48:04,841 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-14_06.44.49.111514.lucid.inc
2010-05-25 17:48:04,841 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-13_18.31.50.714097.lucid.ful
2010-05-25 17:48:04,841 - INFO in SnapshotManager._do_cutoff_purge(993): Simple purge - remove all backups older then 10 days.
2010-05-25 17:48:04,841 - DEBUG in SnapshotManager.get_snapshots(204): [Snapshots Listing - current format]
2010-05-25 17:48:04,841 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-23_10.03.24.877220.lucid.inc
2010-05-25 17:48:04,842 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-21_06.28.06.547394.lucid.inc
2010-05-25 17:48:04,842 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-20_06.27.39.167673.lucid.ful
2010-05-25 17:48:04,842 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-19_20.54.48.455397.lucid.inc
2010-05-25 17:48:04,842 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-18_06.31.59.387443.lucid.inc
2010-05-25 17:48:04,842 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-17_06.18.56.506006.lucid.inc
2010-05-25 17:48:04,842 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-16_13.21.05.248497.lucid.inc
2010-05-25 17:48:04,842 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-15_07.54.49.296694.lucid.inc
2010-05-25 17:48:04,842 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-14_06.44.49.111514.lucid.inc
2010-05-25 17:48:04,843 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-13_18.31.50.714097.lucid.ful
2010-05-25 17:48:04,843 - DEBUG in SnapshotManager._do_cutoff_purge(996): Checking snapshot '2010-05-23_10.03.24.877220.lucid.inc' for simple purge!
2010-05-25 17:48:04,843 - DEBUG in SnapshotManager._do_cutoff_purge(996): Checking snapshot '2010-05-21_06.28.06.547394.lucid.inc' for simple purge!
2010-05-25 17:48:04,843 - DEBUG in SnapshotManager._do_cutoff_purge(996): Checking snapshot '2010-05-20_06.27.39.167673.lucid.ful' for simple purge!
2010-05-25 17:48:04,843 - DEBUG in SnapshotManager._do_cutoff_purge(996): Checking snapshot '2010-05-19_20.54.48.455397.lucid.inc' for simple purge!
2010-05-25 17:48:04,843 - DEBUG in SnapshotManager._do_cutoff_purge(996): Checking snapshot '2010-05-18_06.31.59.387443.lucid.inc' for simple purge!
2010-05-25 17:48:04,844 - DEBUG in SnapshotManager._do_cutoff_purge(996): Checking snapshot '2010-05-17_06.18.56.506006.lucid.inc' for simple purge!
2010-05-25 17:48:04,844 - DEBUG in SnapshotManager._do_cutoff_purge(996): Checking snapshot '2010-05-16_13.21.05.248497.lucid.inc' for simple purge!
2010-05-25 17:48:04,844 - DEBUG in SnapshotManager._do_cutoff_purge(996): Checking snapshot '2010-05-15_07.54.49.296694.lucid.inc' for simple purge!
2010-05-25 17:48:04,844 - DEBUG in SnapshotManager._do_cutoff_purge(996): Checking snapshot '2010-05-14_06.44.49.111514.lucid.inc' for simple purge!
2010-05-25 17:48:04,844 - DEBUG in SnapshotManager._do_cutoff_purge(1003): Snapshot '2010-05-14_06.44.49.111514.lucid.inc' is older than 10 days -> will be removed.
2010-05-25 17:48:04,844 - INFO in SnapshotManager.removeSnapshot(808): Deleting snapshot: '2010-05-14_06.44.49.111514.lucid.inc'.
2010-05-25 17:48:04,846 - DEBUG in SnapshotManager.get_snapshots(204): [Snapshots Listing - current format]
2010-05-25 17:48:04,846 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-23_10.03.24.877220.lucid.inc
2010-05-25 17:48:04,846 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-21_06.28.06.547394.lucid.inc
2010-05-25 17:48:04,846 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-20_06.27.39.167673.lucid.ful
2010-05-25 17:48:04,847 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-19_20.54.48.455397.lucid.inc
2010-05-25 17:48:04,847 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-18_06.31.59.387443.lucid.inc
2010-05-25 17:48:04,847 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-17_06.18.56.506006.lucid.inc
2010-05-25 17:48:04,847 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-16_13.21.05.248497.lucid.inc
2010-05-25 17:48:04,847 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-15_07.54.49.296694.lucid.inc
2010-05-25 17:48:04,847 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-14_06.44.49.111514.lucid.inc
2010-05-25 17:48:04,847 - DEBUG in SnapshotManager.get_snapshots(206): 2010-05-13_18.31.50.714097.lucid.ful
2010-05-25 17:48:04,848 - INFO in SnapshotManager.rebaseSnapshot(251): Re-base of snapshot '2010-05-15_07.54.49.296694.lucid.inc' to '2010-05-13_18.31.50.714097.lucid.ful'
2010-05-25 17:48:04,848 - INFO in SnapshotManager.rebaseSnapshot(275): Rebasing '2010-05-15_07.54.49.296694.lucid.inc' on '2010-05-14_06.44.49.111514.lucid.inc'
2010-05-25 17:48:04,849 - DEBUG in SnapshotManager._copy_empty_snar(522): Create temporary SNARFILE to prepare merging
2010-05-25 17:48:04,849 - DEBUG in SnapshotManager._copy_empty_snar(549): Current SNAR Header (NULL replaced by newline):
GNU tar-1.22-2
1273890348
222416336

2010-05-25 17:48:04,849 - INFO in SnapshotManager._merge_snarfiles(588): Merging SNARFILEs to make the transfer