crash in gzip.py

Previous Topic Next Topic
 
classic Classic list List threaded Threaded
7 messages Options
Reply | Threaded
Open this post in threaded view
|

crash in gzip.py

Lew Wolfgang
Hi Folks,

I occasionally encounter an error that terminates a backup
and requires a "--check-destination-dir" run to recover.

The error seems to be associated with gzip.py being asked to
do a negative seek in write mode.  The docs for gzip.py explicitly
say it can't do this.  I'm not sure about which file(s) cause this
error, bit it might be one of the firefox user  .cache files.  This
is on an openSUSE 13.2 Linux system with rdiff-backup 1.2.8.

Any ideas?

  File "/usr/lib64/python2.7/gzip.py", line 423, in seek
     raise IOError('Negative seek in write mode')
IOError: Negative seek in write mode

Thanks,
Lew


_______________________________________________
rdiff-backup-users mailing list at [hidden email]
https://lists.nongnu.org/mailman/listinfo/rdiff-backup-users
Wiki URL: http://rdiff-backup.solutionsfirst.com.au/index.php/RdiffBackupWiki
Reply | Threaded
Open this post in threaded view
|

Re: crash in gzip.py

Andrea Cozzolino
On Fri, Dec 11, 2015 at 9:56 PM, Lew Wolfgang <[hidden email]> wrote:
Hi Folks,

I occasionally encounter an error that terminates a backup
and requires a "--check-destination-dir" run to recover.

The error seems to be associated with gzip.py being asked to
do a negative seek in write mode.  The docs for gzip.py explicitly
say it can't do this.  I'm not sure about which file(s) cause this
error, bit it might be one of the firefox user  .cache files.  This
is on an openSUSE 13.2 Linux system with rdiff-backup 1.2.8.

Any ideas?

 File "/usr/lib64/python2.7/gzip.py", line 423, in seek
    raise IOError('Negative seek in write mode')
IOError: Negative seek in write mode

Hi Wolfgang,

I have quite the same problem and in the *same* environment
(openSUSE 13.2/rdiff-backup 1.2.8).

I have a broken and, so far, unrecoverable backup store.

Each time I try to backup my home dir, I got a "Negative seek"
error while "Writing file object to
/backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/content-prefs.sqlite.2015-11-23T02:12:37+01:00.snapshot.gz"

Note that I deleted ~/.mozilla/firefox/c3ggw723.suse directory
in between the last successful backup and the first failure.

Follows an extract of the log (--terminal-verbosity 9) of the
execution of

rdiff-backup --exclude-fifos --exclude-device-files --exclude-sockets --terminal-verbosity 9 --exclude /home/andcoz/.local/share/Steam/SteamApps --exclude /home/andcoz/.mozilla/thunderbird/km324c33.gmail --exclude /home/andcoz/.Wip --exclude /home/andcoz/.mozilla/firefox/*/Cache --exclude /home/andcoz/.cache  /home/andcoz /backup/storage/BackUp/tseenfoo/home-andcoz_broken 2>&1 | tee backup_004.log
If I do a rdiff-backup --check and retry the backup,
I'll get the same error, on the same file.

Note that there is *no*
/backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/content-prefs.sqlite
file in backup directory.

Any Idea?

[[ -- LOG STARTS HERE -- ]]
Sun Dec 13 11:55:01 2015  Using rdiff-backup version 1.2.8
Sun Dec 13 11:55:01 2015  Unable to import module posix1e from pylibacl package.
POSIX ACLs not supported on filesystem at /home/andcoz
Sun Dec 13 11:55:01 2015  Unable to import win32security module. Windows ACLs
not supported by filesystem at /home/andcoz
Sun Dec 13 11:55:01 2015  escape_dos_devices not required by filesystem at /home/andcoz
Sun Dec 13 11:55:01 2015  -----------------------------------------------------------------
Detected abilities for source (read only) file system:
  Access control lists                         Off
  Extended attributes                          On
  Windows access control lists                 Off
  Case sensitivity                             On
  Escape DOS devices                           Off
  Escape trailing spaces                       Off
  Mac OS X style resource forks                Off
  Mac OS X Finder information                  Off
-----------------------------------------------------------------
Sun Dec 13 11:55:01 2015  Making directory /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/5-_ a.snapshot.gz
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/5-_ a.snapshot.gz
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/uniᄉ
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/uniᄉ
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/:\"
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/:\"
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/A
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/A
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/foo
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/foo
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Making directory /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/hl
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/hardlinked_file1
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Hard linking /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/hl/hardlinked_file2 to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/hardlinked_file1
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Unable to import module posix1e from pylibacl package.
POSIX ACLs not supported on filesystem at /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Unable to import win32security module. Windows ACLs
not supported by filesystem at /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/dir_inc_check
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/dir_inc_check
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/regfile
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/regfile
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/high_perms_file
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/high_perms_dir
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/high_perms_file
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/high_perms_dir
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/symlinked_file1
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/symlinked_file2
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0/symlinked_file1
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  escape_dos_devices not required by filesystem at /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Removing directory /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/rdiff-backup.tmp.0
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  -----------------------------------------------------------------
Detected abilities for destination (read/write) file system:
  Ownership changing                           On
  Hard linking                                 On
  fsync() directories                          On
  Directory inc permissions                    On
  High-bit permissions                         On
  Symlink permissions                          Off
  Extended filenames                           On
  Windows reserved filenames                   Off
  Access control lists                         Off
  Extended attributes                          On
  Windows access control lists                 Off
  Case sensitivity                             On
  Escape DOS devices                           Off
  Escape trailing spaces                       Off
  Mac OS X style resource forks                Off
  Mac OS X Finder information                  Off
-----------------------------------------------------------------
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Backup: must_escape_dos_devices = 0
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Writing mirror marker /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/current_mirror.2015-12-13T11:55:01+01:00.data
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Starting increment operation /home/andcoz to /backup/storage/BackUp/tseenfoo/home-andcoz_broken
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Processing changed file .
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments.2015-11-23T02:12:37+01:00.dir
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Copying inc attrs from () to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments.2015-11-23T02:12:37+01:00.dir
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments.2015-11-23T02:12:37+01:00.dir to 1448238791
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Processing changed file #state.json#
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Regular copying ('#state.json#',) to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup.tmp.1
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Writing file object to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup.tmp.1
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Copying attributes from ('#state.json#',) to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup.tmp.1
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup.tmp.1 to 1449657530
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken/#state.json#
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/#state.json#.2015-11-23T02:12:37+01:00.missing
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Renaming /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup.tmp.1 to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/#state.json#
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Getting signature of .ApacheDirectoryStudio/.metadata/.log with blocksize 224
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Getting delta (with hash) of /home/andcoz/.ApacheDirectoryStudio/.metadata/.log with signature .ApacheDirectoryStudio/.metadata/.log
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Processing changed file .ApacheDirectoryStudio
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.ApacheDirectoryStudio
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.ApacheDirectoryStudio.2015-11-23T02:12:37+01:00.dir
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Copying inc attrs from ('.ApacheDirectoryStudio',) to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.ApacheDirectoryStudio.2015-11-23T02:12:37+01:00.dir
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.ApacheDirectoryStudio.2015-11-23T02:12:37+01:00.dir to 1238971825
Sun Dec 13 11:55:<a href="tel:02%202015" value="+39022015" target="_blank">02 2015  Processing changed file .ApacheDirectoryStudio/.metadata

[ ... snip snip snip snip ... ]

Sun Dec 13 12:02:40 2015  Processing changed file .mozilla/firefox/c3ggw723.suse
Sun Dec 13 12:02:40 2015  Copying attributes from ('.mozilla', 'firefox', 'Crash Reports') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/Crash Reports
Sun Dec 13 12:02:40 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/Crash Reports to 1449932683
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/rdiff-backup.tmp.14993
Sun Dec 13 12:02:40 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse
Sun Dec 13 12:02:40 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse.2015-11-23T02:12:37+01:00.dir
Sun Dec 13 12:02:40 2015  Copying inc attrs from ('.mozilla', 'firefox', 'c3ggw723.suse') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse.2015-11-23T02:12:37+01:00.dir
Sun Dec 13 12:02:40 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse.2015-11-23T02:12:37+01:00.dir to 1434668877
Sun Dec 13 12:02:40 2015  Getting signature of .mozilla/firefox/c3ggw723.suse/.parentlock with blocksize 64
Sun Dec 13 12:02:40 2015  Processing changed file .mozilla/firefox/c3ggw723.suse/.parentlock
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', '.parentlock') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/rdiff-backup.tmp.14994
Sun Dec 13 12:02:40 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/.parentlock
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', '.parentlock') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/.parentlock.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Writing file object to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/.parentlock.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Copying attributes from ('.mozilla', 'firefox', 'c3ggw723.suse', '.parentlock') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/.parentlock.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/.parentlock.2015-11-23T02:12:37+01:00.snapshot.gz to 1434668515
Sun Dec 13 12:02:40 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/.parentlock
Sun Dec 13 12:02:40 2015  Getting signature of .mozilla/firefox/c3ggw723.suse/SiteSecurityServiceState.txt with blocksize 64
Sun Dec 13 12:02:40 2015  Processing changed file .mozilla/firefox/c3ggw723.suse/SiteSecurityServiceState.txt
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'SiteSecurityServiceState.txt') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/rdiff-backup.tmp.14995
Sun Dec 13 12:02:40 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/SiteSecurityServiceState.txt
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'SiteSecurityServiceState.txt') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/SiteSecurityServiceState.txt.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Writing file object to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/SiteSecurityServiceState.txt.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Copying attributes from ('.mozilla', 'firefox', 'c3ggw723.suse', 'SiteSecurityServiceState.txt') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/SiteSecurityServiceState.txt.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/SiteSecurityServiceState.txt.2015-11-23T02:12:37+01:00.snapshot.gz to 1434668876
Sun Dec 13 12:02:40 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/SiteSecurityServiceState.txt
Sun Dec 13 12:02:40 2015  Getting signature of .mozilla/firefox/c3ggw723.suse/addons.json with blocksize 160
Sun Dec 13 12:02:40 2015  Processing changed file .mozilla/firefox/c3ggw723.suse/addons.json
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'addons.json') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/rdiff-backup.tmp.14996
Sun Dec 13 12:02:40 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/addons.json
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'addons.json') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/addons.json.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Writing file object to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/addons.json.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Copying attributes from ('.mozilla', 'firefox', 'c3ggw723.suse', 'addons.json') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/addons.json.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/addons.json.2015-11-23T02:12:37+01:00.snapshot.gz to 1434555640
Sun Dec 13 12:02:40 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/addons.json
Sun Dec 13 12:02:40 2015  Getting signature of .mozilla/firefox/c3ggw723.suse/blocklist.xml with blocksize 400
Sun Dec 13 12:02:40 2015  Processing changed file .mozilla/firefox/c3ggw723.suse/blocklist.xml
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'blocklist.xml') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/rdiff-backup.tmp.14997
Sun Dec 13 12:02:40 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/blocklist.xml
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'blocklist.xml') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/blocklist.xml.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Writing file object to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/blocklist.xml.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Copying attributes from ('.mozilla', 'firefox', 'c3ggw723.suse', 'blocklist.xml') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/blocklist.xml.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/blocklist.xml.2015-11-23T02:12:37+01:00.snapshot.gz to 1434555760
Sun Dec 13 12:02:40 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/blocklist.xml
Sun Dec 13 12:02:40 2015  Processing changed file .mozilla/firefox/c3ggw723.suse/bookmarkbackups
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'bookmarkbackups') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/rdiff-backup.tmp.14998
Sun Dec 13 12:02:40 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/bookmarkbackups
Sun Dec 13 12:02:40 2015  Touching /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/bookmarkbackups.2015-11-23T02:12:37+01:00.dir
Sun Dec 13 12:02:40 2015  Copying inc attrs from ('.mozilla', 'firefox', 'c3ggw723.suse', 'bookmarkbackups') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/bookmarkbackups.2015-11-23T02:12:37+01:00.dir
Sun Dec 13 12:02:40 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/bookmarkbackups.2015-11-23T02:12:37+01:00.dir to 1434455557
Sun Dec 13 12:02:40 2015  Getting signature of .mozilla/firefox/c3ggw723.suse/bookmarkbackups/bookmarks-2015-06-16_26_ZGRRKtObSsW1nalYKKRsdw==.jsonlz4 with blocksize 64
Sun Dec 13 12:02:40 2015  Processing changed file .mozilla/firefox/c3ggw723.suse/bookmarkbackups/bookmarks-2015-06-16_26_ZGRRKtObSsW1nalYKKRsdw==.jsonlz4
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'bookmarkbackups', 'bookmarks-2015-06-16_26_ZGRRKtObSsW1nalYKKRsdw==.jsonlz4') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/bookmarkbackups/rdiff-backup.tmp.14999
Sun Dec 13 12:02:40 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/bookmarkbackups/bookmarks-2015-06-16_26_ZGRRKtObSsW1nalYKKRsdw==.jsonlz4
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'bookmarkbackups', 'bookmarks-2015-06-16_26_ZGRRKtObSsW1nalYKKRsdw==.jsonlz4') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/bookmarkbackups/bookmarks-2015-06-16_26_ZGRRKtObSsW1nalYKKRsdw==.jsonlz4.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Writing file object to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/bookmarkbackups/bookmarks-2015-06-16_26_ZGRRKtObSsW1nalYKKRsdw==.jsonlz4.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Copying attributes from ('.mozilla', 'firefox', 'c3ggw723.suse', 'bookmarkbackups', 'bookmarks-2015-06-16_26_ZGRRKtObSsW1nalYKKRsdw==.jsonlz4') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/bookmarkbackups/bookmarks-2015-06-16_26_ZGRRKtObSsW1nalYKKRsdw==.jsonlz4.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/bookmarkbackups/bookmarks-2015-06-16_26_ZGRRKtObSsW1nalYKKRsdw==.jsonlz4.2015-11-23T02:12:37+01:00.snapshot.gz to 1434455557
Sun Dec 13 12:02:40 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/bookmarkbackups/bookmarks-2015-06-16_26_ZGRRKtObSsW1nalYKKRsdw==.jsonlz4
Sun Dec 13 12:02:40 2015  Getting signature of .mozilla/firefox/c3ggw723.suse/bookmarks.html with blocksize 128
Sun Dec 13 12:02:40 2015  Processing changed file .mozilla/firefox/c3ggw723.suse/bookmarks.html
Sun Dec 13 12:02:40 2015  Removing directory /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/bookmarkbackups
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'bookmarks.html') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/rdiff-backup.tmp.15000
Sun Dec 13 12:02:40 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/bookmarks.html
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'bookmarks.html') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/bookmarks.html.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Writing file object to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/bookmarks.html.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Copying attributes from ('.mozilla', 'firefox', 'c3ggw723.suse', 'bookmarks.html') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/bookmarks.html.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/bookmarks.html.2015-11-23T02:12:37+01:00.snapshot.gz to 1434452863
Sun Dec 13 12:02:40 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/bookmarks.html
Sun Dec 13 12:02:40 2015  Getting signature of .mozilla/firefox/c3ggw723.suse/cert8.db with blocksize 336
Sun Dec 13 12:02:40 2015  Processing changed file .mozilla/firefox/c3ggw723.suse/cert8.db
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'cert8.db') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/rdiff-backup.tmp.15001
Sun Dec 13 12:02:40 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/cert8.db
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'cert8.db') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/cert8.db.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Writing file object to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/cert8.db.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Copying attributes from ('.mozilla', 'firefox', 'c3ggw723.suse', 'cert8.db') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/cert8.db.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/cert8.db.2015-11-23T02:12:37+01:00.snapshot.gz to 1434668876
Sun Dec 13 12:02:40 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/cert8.db
Sun Dec 13 12:02:40 2015  Getting signature of .mozilla/firefox/c3ggw723.suse/compatibility.ini with blocksize 64
Sun Dec 13 12:02:40 2015  Processing changed file .mozilla/firefox/c3ggw723.suse/compatibility.ini
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'compatibility.ini') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/rdiff-backup.tmp.15002
Sun Dec 13 12:02:40 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/compatibility.ini
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'compatibility.ini') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/compatibility.ini.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Writing file object to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/compatibility.ini.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Copying attributes from ('.mozilla', 'firefox', 'c3ggw723.suse', 'compatibility.ini') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/compatibility.ini.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/compatibility.ini.2015-11-23T02:12:37+01:00.snapshot.gz to 1434457789
Sun Dec 13 12:02:40 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/compatibility.ini
Sun Dec 13 12:02:40 2015  Getting signature of .mozilla/firefox/c3ggw723.suse/content-prefs.sqlite with blocksize 464
Sun Dec 13 12:02:40 2015  Processing changed file .mozilla/firefox/c3ggw723.suse/content-prefs.sqlite
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'content-prefs.sqlite') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/rdiff-backup.tmp.15003
Sun Dec 13 12:02:40 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/content-prefs.sqlite
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'content-prefs.sqlite') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/content-prefs.sqlite.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Writing file object to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/content-prefs.sqlite.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Copying attributes from ('.mozilla', 'firefox', 'c3ggw723.suse', 'cSun Dec 13 12:02:40 2015  Exception 'Negative seek in write mode' raised of class '<type 'exceptions.IOError'>':
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/robust.py", line 32, in check_common_error
    try: return function(*args)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/increment.py", line 44, in Increment
    else: incrp = makesnapshot(mirror, incpref)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/increment.py", line 73, in makesnapshot
    else: rpath.copy_with_attribs(mirror, snapshotrp, compress)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 277, in copy_with_attribs
    copy(rpin, rpout, compress)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 139, in copy
    if rpin.isreg(): return copy_reg_file(rpin, rpout, compress)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 167, in copy_reg_file
    return rpout.write_from_fileobj(rpin.open("rb"), compress = compress)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 1229, in write_from_fileobj
    copyfileobj(fp, outfp)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 97, in copyfileobj
    outputfp.seek(-1, os.SEEK_CUR)
  File "/usr/lib64/python2.7/gzip.py", line 423, in seek
    raise IOError('Negative seek in write mode')

Sun Dec 13 12:02:40 2015  Exception 'Negative seek in write mode' raised of class '<type 'exceptions.IOError'>':
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/Main.py", line 304, in error_check_Main
    try: Main(arglist)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/Main.py", line 324, in Main
    take_action(rps)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/Main.py", line 280, in take_action
    elif action == "backup": Backup(rps[0], rps[1])
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/Main.py", line 343, in Backup
    backup.Mirror_and_increment(rpin, rpout, incdir)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/backup.py", line 51, in Mirror_and_increment
    DestS.patch_and_increment(dest_rpath, source_diffiter, inc_rpath)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/backup.py", line 243, in patch_and_increment
    ITR(diff.index, diff)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rorpiter.py", line 281, in __call__
    last_branch.fast_process(*args)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/backup.py", line 694, in fast_process
    increment.Increment, (tf, mirror_rp, inc_prefix))
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/robust.py", line 32, in check_common_error
    try: return function(*args)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/increment.py", line 44, in Increment
    else: incrp = makesnapshot(mirror, incpref)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/increment.py", line 73, in makesnapshot
    else: rpath.copy_with_attribs(mirror, snapshotrp, compress)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 277, in copy_with_attribs
    copy(rpin, rpout, compress)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 139, in copy
    if rpin.isreg(): return copy_reg_file(rpin, rpout, compress)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 167, in copy_reg_file
    return rpout.write_from_fileobj(rpin.open("rb"), compress = compress)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 1229, in write_from_fileobj
    copyfileobj(fp, outfp)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 97, in copyfileobj
    outputfp.seek(-1, os.SEEK_CUR)
  File "/usr/lib64/python2.7/gzip.py", line 423, in seek
    raise IOError('Negative seek in write mode')

ontent-prefs.sqlite') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/content-prefs.sqlite.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Setting time of /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/content-prefs.sqlite.2015-11-23T02:12:37+01:00.snapshot.gz to 1434452872
Sun Dec 13 12:02:40 2015  Deleting /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/content-prefs.sqlite
Sun Dec 13 12:02:40 2015  Getting signature of .mozilla/firefox/c3ggw723.suse/cookies.sqlite with blocksize 720
Sun Dec 13 12:02:40 2015  Processing changed file .mozilla/firefox/c3ggw723.suse/cookies.sqlite
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'cookies.sqlite') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/rdiff-backup.tmp.15004
Sun Dec 13 12:02:40 2015  Incrementing mirror file /backup/storage/BackUp/tseenfoo/home-andcoz_broken/.mozilla/firefox/c3ggw723.suse/cookies.sqlite
Sun Dec 13 12:02:40 2015  Regular copying ('.mozilla', 'firefox', 'c3ggw723.suse', 'cookies.sqlite') to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/cookies.sqlite.2015-11-23T02:12:37+01:00.snapshot.gz
Sun Dec 13 12:02:40 2015  Writing file object to /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/cookies.sqlite.2015-11-23T02:12:37+01:00.snapshot.gz
Traceback (most recent call last):
  File "/usr/bin/rdiff-backup", line 30, in <module>
    rdiff_backup.Main.error_check_Main(sys.argv[1:])
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/Main.py", line 304, in error_check_Main
    try: Main(arglist)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/Main.py", line 324, in Main
    take_action(rps)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/Main.py", line 280, in take_action
    elif action == "backup": Backup(rps[0], rps[1])
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/Main.py", line 343, in Backup
    backup.Mirror_and_increment(rpin, rpout, incdir)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/backup.py", line 51, in Mirror_and_increment
    DestS.patch_and_increment(dest_rpath, source_diffiter, inc_rpath)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/backup.py", line 243, in patch_and_increment
    ITR(diff.index, diff)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rorpiter.py", line 281, in __call__
    last_branch.fast_process(*args)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/backup.py", line 694, in fast_process
    increment.Increment, (tf, mirror_rp, inc_prefix))
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/robust.py", line 32, in check_common_error
    try: return function(*args)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/increment.py", line 44, in Increment
    else: incrp = makesnapshot(mirror, incpref)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/increment.py", line 73, in makesnapshot
    else: rpath.copy_with_attribs(mirror, snapshotrp, compress)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 277, in copy_with_attribs
    copy(rpin, rpout, compress)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 139, in copy
    if rpin.isreg(): return copy_reg_file(rpin, rpout, compress)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 167, in copy_reg_file
    return rpout.write_from_fileobj(rpin.open("rb"), compress = compress)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 1229, in write_from_fileobj
    copyfileobj(fp, outfp)
  File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line 97, in copyfileobj
    outputfp.seek(-1, os.SEEK_CUR)
  File "/usr/lib64/python2.7/gzip.py", line 423, in seek
    raise IOError('Negative seek in write mode')
IOError: Negative seek in write mode

[[ -- LOG ENDS HERE -- ]]

LLaP, Andrea
--
Necessity is the mother of invention ... MacGyver is the father!
La necessità è madre dell'invenzione ... MacGyver è il padre!

_______________________________________________
rdiff-backup-users mailing list at [hidden email]
https://lists.nongnu.org/mailman/listinfo/rdiff-backup-users
Wiki URL: http://rdiff-backup.solutionsfirst.com.au/index.php/RdiffBackupWiki
Reply | Threaded
Open this post in threaded view
|

Re: crash in gzip.py

Joe Steele-2
On 12/13/2015 7:03 AM, Andrea Cozzolino wrote:
> I have quite the same problem and in the *same* environment
> (openSUSE 13.2/rdiff-backup 1.2.8).
>
> I have a broken and, so far, unrecoverable backup store.
>
> Each time I try to backup my home dir, I got a "Negative seek"
> error while "Writing file object to
> /backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/content-prefs.sqlite.2015-11-23T02:12:37+01:00.snapshot.gz"
>
...
> Traceback (most recent call last):
...
>    File "/usr/lib64/python2.7/site-packages/rdiff_backup/rpath.py", line
> 97, in copyfileobj
>      outputfp.seek(-1, os.SEEK_CUR)
>    File "/usr/lib64/python2.7/gzip.py", line 423, in seek
>      raise IOError('Negative seek in write mode')
> IOError: Negative seek in write mode
>

The vanilla rdiff-backup 1.2.8 does not have "outputfp.seek(-1,
os.SEEK_CUR)" on line 97 of rpath.py.

In looking at the packaging of rdiff-backup by openSUSE, I see that they
incorporate several patches not included in the upstream source.

Their revision history can be found here:

https://build.opensuse.org/package/revisions/openSUSE:Factory/rdiff-backup

One of their changes (which seems to be the source of your problem) was
related to the handling of sparse files and was incorporated here:

https://build.opensuse.org/request/show/222465

Apparently, that was causing somebody to have same problem that you are
having, and another patch was added:

https://build.opensuse.org/request/show/259636

Based on the line numbers in your log, it would appear that you do not
have that second patch which was intended to fix the problem.

In looking at the openSUSE packages
(http://software.opensuse.org/download.html?project=Archiving&package=rdiff-backup),
it appears that openSUSE 13.2 contains rdiff-backup-1.2.8-42.3.  It is
also evident when looking at the source that revision 42.3 contains the
second patch referenced above (and which you do not seem to have).  So
that would imply that you are not using their current revision(?).

--Joe

_______________________________________________
rdiff-backup-users mailing list at [hidden email]
https://lists.nongnu.org/mailman/listinfo/rdiff-backup-users
Wiki URL: http://rdiff-backup.solutionsfirst.com.au/index.php/RdiffBackupWiki
Reply | Threaded
Open this post in threaded view
|

Re: crash in gzip.py

Lew Wolfgang
In reply to this post by Andrea Cozzolino
On 12/13/2015 04:03 AM, Andrea Cozzolino wrote:
On Fri, Dec 11, 2015 at 9:56 PM, Lew Wolfgang <[hidden email]> wrote:
Hi Folks,

I occasionally encounter an error that terminates a backup
and requires a "--check-destination-dir" run to recover.

The error seems to be associated with gzip.py being asked to
do a negative seek in write mode.  The docs for gzip.py explicitly
say it can't do this.  I'm not sure about which file(s) cause this
error, bit it might be one of the firefox user  .cache files.  This
is on an openSUSE 13.2 Linux system with rdiff-backup 1.2.8.

Any ideas?

 File "/usr/lib64/python2.7/gzip.py", line 423, in seek
    raise IOError('Negative seek in write mode')
IOError: Negative seek in write mode

Hi Wolfgang,

I have quite the same problem and in the *same* environment
(openSUSE 13.2/rdiff-backup 1.2.8).

I have a broken and, so far, unrecoverable backup store.

Each time I try to backup my home dir, I got a "Negative seek"
error while "Writing file object to
/backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/content-prefs.sqlite.2015-11-23T02:12:37+01:00.snapshot.gz"

Note that I deleted ~/.mozilla/firefox/c3ggw723.suse directory
in between the last successful backup and the first failure.

Follows an extract of the log (--terminal-verbosity 9) of the
execution of

rdiff-backup --exclude-fifos --exclude-device-files --exclude-sockets --terminal-verbosity 9 --exclude /home/andcoz/.local/share/Steam/SteamApps --exclude /home/andcoz/.mozilla/thunderbird/km324c33.gmail --exclude /home/andcoz/.Wip --exclude /home/andcoz/.mozilla/firefox/*/Cache --exclude /home/andcoz/.cache  /home/andcoz /backup/storage/BackUp/tseenfoo/home-andcoz_broken 2>&1 | tee backup_004.log
If I do a rdiff-backup --check and retry the backup,
I'll get the same error, on the same file.

Note that there is *no*
/backup/storage/BackUp/tseenfoo/home-andcoz_broken/rdiff-backup-data/increments/.mozilla/firefox/c3ggw723.suse/content-prefs.sqlite
file in backup directory.


( snipped logs )

Hi Andrea,

I'm re-running with your --terminal-verbosity 9 level.  Before then, I notice
that a directory was removed in my case too.  A "Cache" directory in the .mozilla
hierarchy was removed and re-created as "cache2".  The "Cache" directory existed
in May 12 2014, but apparently was changed due to a Firefox update
sometime after then.

So the common point of failure "seems" to be a removed directory. 

BTW, since rdiff-backup hasn't been updated since 2009, I started to
look around for an alternative.  Alas, rdiff-backup's capabilities seem
to be unique.  I wonder what will happen to it when python 2.x finally
goes away?  I remember complaining to Hans Reiser when I observed
that ReiserFS doesn't have native dump/restore as do the ext filesystems.
He grumbled, "Just use tar".  Well, okay...

Regards,
Lew


_______________________________________________
rdiff-backup-users mailing list at [hidden email]
https://lists.nongnu.org/mailman/listinfo/rdiff-backup-users
Wiki URL: http://rdiff-backup.solutionsfirst.com.au/index.php/RdiffBackupWiki
Reply | Threaded
Open this post in threaded view
|

Re: crash in gzip.py

Andrea Cozzolino
In reply to this post by Joe Steele-2
On Sun, Dec 13, 2015 at 7:22 PM, Joe Steele <[hidden email]> wrote:

On 12/13/2015 7:03 AM, Andrea Cozzolino wrote:
I have quite the same problem and in the *same* environment
(openSUSE 13.2/rdiff-backup 1.2.8).

I have a broken and, so far, unrecoverable backup store.

Each time I try to backup my home dir, I got a "Negative seek"
error while "Writing file object to

The vanilla rdiff-backup 1.2.8 does not have 
"outputfp.seek(-1, os.SEEK_CUR)" on line 97 of rpath.py

Mumble ... that line looks guilty to me.
 
[ snip snip ]
Apparently, that was causing somebody to have same problem
that you are having, and another patch was added:
https://build.opensuse.org/request/show/259636
[ snip snip ]
Based on the line numbers in your log, it would appear that you do 
not have that second patch which was intended to fix the problem.

Latest openSuSE rdiff-backup release does not have the second patch.
 
In looking at the openSUSE packages 
(http://software.opensuse.org/download.html?project=Archiving&package=rdiff-backup), 
it appears that openSUSE 13.2 contains rdiff-backup-1.2.8-42.3.  It is also evident when 
looking at the source that revision 42.3 contains the second patch referenced above 
(and which you do not seem to have).  So that would imply that you are not using their 
current revision(?).

No, I had rdiff-backup-1.2.8-22.1.4. Release 22.1.4 is the latest
in mainstream openSuSE update repository.

The version 42.3 is only in "Archiving" repository, an optional repository.

I subscribed the optional repository and upgraded to release 42.3.
I did a couple of test and the problem seems solved.

Thank you Joe for your help.

LLaP, Andrea
--
Necessity is the mother of invention ... MacGyver is the father!
La necessità è madre dell'invenzione ... MacGyver è il padre!

_______________________________________________
rdiff-backup-users mailing list at [hidden email]
https://lists.nongnu.org/mailman/listinfo/rdiff-backup-users
Wiki URL: http://rdiff-backup.solutionsfirst.com.au/index.php/RdiffBackupWiki
Reply | Threaded
Open this post in threaded view
|

Re: crash in gzip.py

Andrea Cozzolino
In reply to this post by Lew Wolfgang
On Sun, Dec 13, 2015 at 9:35 PM, Lew Wolfgang <[hidden email]> wrote:
On 12/13/2015 04:03 AM, Andrea Cozzolino wrote:

BTW, since rdiff-backup hasn't been updated since 2009, I started to
look around for an alternative.  Alas, rdiff-backup's capabilities seem
to be unique.  I wonder what will happen to it when python 2.x finally
goes away?

Hi Lew, I understand your worries but I have used it for more that 10
years and this was my first problem, so I'll stick to it.

LLaP, Andrea
--
Necessity is the mother of invention ... MacGyver is the father!
La necessità è madre dell'invenzione ... MacGyver è il padre!

_______________________________________________
rdiff-backup-users mailing list at [hidden email]
https://lists.nongnu.org/mailman/listinfo/rdiff-backup-users
Wiki URL: http://rdiff-backup.solutionsfirst.com.au/index.php/RdiffBackupWiki
Reply | Threaded
Open this post in threaded view
|

Re: crash in gzip.py

Lew Wolfgang
On 12/13/2015 01:13 PM, Andrea Cozzolino wrote:
On Sun, Dec 13, 2015 at 9:35 PM, Lew Wolfgang <[hidden email]> wrote:
On 12/13/2015 04:03 AM, Andrea Cozzolino wrote:

BTW, since rdiff-backup hasn't been updated since 2009, I started to
look around for an alternative.  Alas, rdiff-backup's capabilities seem
to be unique.  I wonder what will happen to it when python 2.x finally
goes away?

Hi Lew, I understand your worries but I have used it for more that 10
years and this was my first problem, so I'll stick to it.

Hi Andrea,

As will I (keep using it)!  I've also been using rdiff-backup for more
than 10-years and it's been a good experience.  I use it for everything
from backing up my local disks to other local disks, to backing
up remote co-lo servers, to backing up dozens of desktops and servers
onto centrally located servers with hundreds of terabytes of RAID6
storage.

I tried the repository that Joe Steele and you recommended and
my gzip.py problem is also gone!

Thanks for the help, this is open-source at it's best!

Regards,
Lew


_______________________________________________
rdiff-backup-users mailing list at [hidden email]
https://lists.nongnu.org/mailman/listinfo/rdiff-backup-users
Wiki URL: http://rdiff-backup.solutionsfirst.com.au/index.php/RdiffBackupWiki