Re: [Duplicity-talk] Restore fails at "cleanup temporary file"

[Kenneth Loafman]

address@hidden wrote:
> Newly using duplicity, I attempted an experimental restore operation.
> First I renamed a backed-up directory; then attempted to restore the
> original from the backup.  This failed with the error message: "Cleanup
> of temporary file ... failed."  It's particularly strange in that an
> earlier file (in the same directory) was "forgotten".
> 
> This is on a Debian testing/lenny system with duplicity 0.4.8.  The
> Changelog for the more current 0.4.9 didn't seem to have any item[s]
> that I could associate with this error.
> 
> Any help for this?  A fuller exposition of events follows:
> 
> ##############################################
> 
> #duplicity -v9 --file-to-restore home/fx/engin/arm/include 
> file:///mnt/bup/ibis /home/fx/engin/arm/include
> Main action: restore
> Ignoring file 'home'
> Ignoring file 
> 'duplicity-new-signatures.2007-12-31T18:17:29-08:00.to.2008-01-01T20:37:42-08:00.sigtar.gpg'
> Ignoring file 
> 'duplicity-new-signatures.2007-12-22T20:32:07-07:00.to.2007-12-23T07:19:17-07:00.sigtar.gpg'
> Ignoring file 'duplicity-full-signatures.2007-12-22T20:32:07-07:00.sigtar.gpg'
> Ignoring file 
> 'duplicity-new-signatures.2007-12-23T06:19:17-08:00.to.2007-12-31T18:17:29-08:00.sigtar.gpg'
> Ignoring file 
> 'duplicity-new-signatures.2008-01-01T20:37:42-08:00.to.2008-01-05T18:14:53-08:00.sigtar.gpg'
> Ignoring file 
> 'duplicity-new-signatures.2008-01-05T18:14:53-08:00.to.2008-01-19T18:33:57-08:00.sigtar.gpg'
> Ignoring file 
> 'duplicity-new-signatures.2008-01-19T18:33:57-08:00.to.2008-01-24T20:30:25-08:00.sigtar.gpg'
> Added incremental Backupset (start_time: Sat Dec 22 19:32:07 2007 / end_time: 
> Sun Dec 23 06:19:17 2007)
> Added incremental Backupset (start_time: Sun Dec 23 06:19:17 2007 / end_time: 
> Mon Dec 31 18:17:29 2007)
> Added incremental Backupset (start_time: Mon Dec 31 18:17:29 2007 / end_time: 
> Tue Jan  1 20:37:42 2008)
> Added incremental Backupset (start_time: Tue Jan  1 20:37:42 2008 / end_time: 
> Sat Jan  5 18:14:53 2008)
> Added incremental Backupset (start_time: Sat Jan  5 18:14:53 2008 / end_time: 
> Sat Jan 19 18:33:57 2008)
> Added incremental Backupset (start_time: Sat Jan 19 18:33:57 2008 / end_time: 
> Thu Jan 24 20:30:25 2008)
> Collection Status
> -----------------
> Connecting with backend: LocalBackend
> Archive dir: None
> 
> Found 0 backup chains without signatures.
> 
> Found a complete backup chain with matching signature chain:
> -------------------------
> Chain start time: Sat Dec 22 19:32:07 2007
> Chain end time: Thu Jan 24 20:30:25 2008
> Number of contained backup sets: 7
> Total number of contained volumes: 11090
>  Type of backup set:                            Time:      Num volumes:
>                 Full         Sat Dec 22 19:32:07 2007             10014
>          Incremental         Sun Dec 23 06:19:17 2007               127
>          Incremental         Mon Dec 31 18:17:29 2007               220
>          Incremental         Tue Jan  1 20:37:42 2008               169
>          Incremental         Sat Jan  5 18:14:53 2008               156
>          Incremental         Sat Jan 19 18:33:57 2008               243
>          Incremental         Thu Jan 24 20:30:25 2008               161
> -------------------------
> No orphaned or incomplete backup sets found.
> Last full backup date: Sat Dec 22 19:32:07 2007
> PASSPHRASE variable not set, asking user.
> GnuPG passphrase:
> Retype to confirm:
> Using temporary directory /var/tmp/zapall/duplicity-soBM-7-tempdir
> Registering (mktemp) temporary file 
> /var/tmp/zapall/duplicity-soBM-7-tempdir/mktemp-yqm0ME-1
> gpg: CAST5 encrypted data
> gpg: encrypted with 1 passphrase
> Deleting /var/tmp/zapall/duplicity-soBM-7-tempdir/mktemp-yqm0ME-1
> Forgetting temporary file 
> /var/tmp/zapall/duplicity-soBM-7-tempdir/mktemp-yqm0ME-1
> Registering (mktemp) temporary file 
> /var/tmp/zapall/duplicity-soBM-7-tempdir/mktemp-_-ZJRt-2
> Cleanup of temporary file 
> /var/tmp/zapall/duplicity-soBM-7-tempdir/mktemp-_-ZJRt-2 failed
> Traceback (most recent call last):
>   File "/usr/bin/duplicity", line 426, in ?
>     if __name__ == "__main__": with_tempdir(main)
>   File "/usr/bin/duplicity", line 422, in with_tempdir
>     fn()
>   File "/usr/bin/duplicity", line 402, in main
>     if action == "restore": restore(col_stats)
>   File "/usr/bin/duplicity", line 200, in restore
>     restore_get_patched_rop_iter(col_stats)):
>   File "/usr/lib/python2.4/site-packages/duplicity/patchdir.py", line 456, in 
> Write_ROPaths
>     for ropath in rop_iter:
>   File "/usr/lib/python2.4/site-packages/duplicity/patchdir.py", line 430, in 
> integrate_patch_iters
>     for patch_seq in collated:
>   File "/usr/lib/python2.4/site-packages/duplicity/patchdir.py", line 331, in 
> yield_tuples
>     setrorps(overflow, elems)
>   File "/usr/lib/python2.4/site-packages/duplicity/patchdir.py", line 320, in 
> setrorps
>     try: elems[i] = iter_list[i].next()
>   File "/usr/lib/python2.4/site-packages/duplicity/patchdir.py", line 78, in 
> filter_path_iter
>     for path in path_iter:
>   File "/usr/lib/python2.4/site-packages/duplicity/patchdir.py", line 91, in 
> difftar2path_iter
>     tarinfo_list = [tar_iter.next()]
>   File "/usr/lib/python2.4/site-packages/duplicity/patchdir.py", line 283, in 
> next
>     if not self.tarfile: self.set_tarfile()
>   File "/usr/lib/python2.4/site-packages/duplicity/patchdir.py", line 278, in 
> set_tarfile
>     self.current_fp = self.fileobj_iter.next()
>   File "/usr/bin/duplicity", line 222, in get_fileobj_iter
>     manifest.volume_info_dict[vol_num])
>   File "/usr/bin/duplicity", line 239, in restore_get_enc_fileobj
>     backend.get(filename, tdp)
>   File "/usr/lib/python2.4/site-packages/duplicity/backends.py", line 288, in 
> get
>     source_path = self.remote_pathdir.append(filename)
>   File "/usr/lib/python2.4/site-packages/duplicity/path.py", line 425, in 
> append
>     return self.__class__(self.base, self.index + (ext,))
>   File "/usr/lib/python2.4/site-packages/duplicity/path.py", line 408, in 
> __init__
>     self.setdata()
>   File "/usr/lib/python2.4/site-packages/duplicity/path.py", line 412, in 
> setdata
>     try: self.stat = os.lstat(self.name)
> OSError: [Errno 5] Input/output error: 
> '/mnt/bup/ibis/duplicity-full.2007-12-22T20:32:07-07:00.vol96.difftar.gpg'
> 
> ##############################################
> 
> Any hint/tips/pointers to documentation?  - would be appreciated!

The only documentation is the man page and on the website at
http://www.nongnu.org/duplicity/.

That said, I'm working on trying to improve the error messages and get
rid of the confusing tracebacks that confuse a lot of users.  Part of
usability is having clear error messages that the user understands and
can use to correct their own problems.  Duplicity is not doing very well
at that at all.

The error you got was a real I/O error and may indicate a problem with
the mounted drive, or the network.  The command attempted was an lstat
on the input file.  That would produce a "does not exist" error if the
file did not exist, but this is an I/O error when trying to stat the
file.  This does not normally occur.

Have you looked at the logs on your system (/var/log/syslog), or on the
remote system to see if there was an error indication?  If not, have you
retried the operation to see if the same error occurs?

...Thanks,
...Ken

signature.asc
Description: OpenPGP digital signature