Re: [Duplicity-talk] duplicity 0.7.02, freebsd and sftp backend -- how to further debug.

[edgar . soldin]

On 03.05.2015 18:37, Dirk-Willem van Gulik wrote:
> ------------ ------------ ------------ ------------ ------------ ——————
> 
> On a (new) 0.7.02 upgrade/install; which works fine with a file:/// backend 
> to /tmp as a test — we’re finding that it hangs ‘forever’ on ‘sftp’.
> 
> sftp itself works fine; and when we place a few files there - we can see in 
> the output that they are nicely seen; and ignored (as is the .ssh directory 
> with the right authorized_keys magic).
> 
> Below is the command used and the output. Any suggestion as to where/how to 
> start debugging this ?
> 
> The main change with prior version/use is a sharply increased volume and 
> going to version 0.7.02 (as python needed to be upgraded).
> 
> Thanks,
> 
> Dw.
> 
> # duplicity --version
> duplicity 0.7.02
> 
> # cat backup.sh
> 
> #!/bin/sh
> unset SSH_AUTH_SOCK
> export GNUPGHOME=/XXXXXXX
> export PASSPHRASE=""
> export SIGN_PASSPHRASE=“”
> 
> # Removed: --gpg-options "--yes --lock-never --no-random-seed-file  
> --no-greeting --no-secmem-warning --no-auto-check-trustd -q " 
> 
> /usr/local/bin/duplicity \
>       --ssh-options "-oIdentityFile=XXXXX  \
>       --archive-dir /var/db/duplicity \
>       --hidden-encrypt-key XXX \
>       --hidden-encrypt-key XXX \
>       --hidden-encrypt-key XXX \
>       --sign-key XXX \
>       --include /XXX \
>       ..snipped directories XXXXX ...
>       --exclude '**' \
>       / sftp://address@hidden
> 
> 
> 
> 
> # sh backup.sh 
> Using archive dir: /var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe
> Using backup name: be3234cf4cbafbdf41099030093b6bbe
> Import of duplicity.backends.azurebackend Succeeded
> Import of duplicity.backends.botobackend Succeeded
> Import of duplicity.backends.cfbackend Succeeded
> Import of duplicity.backends.copycombackend Succeeded
> Import of duplicity.backends.dpbxbackend Succeeded
> Import of duplicity.backends.ftpbackend Succeeded
> Import of duplicity.backends.ftpsbackend Succeeded
> Import of duplicity.backends.gdocsbackend Succeeded
> Import of duplicity.backends.giobackend Succeeded
> Import of duplicity.backends.hsibackend Succeeded
> Import of duplicity.backends.hubicbackend Succeeded
> Import of duplicity.backends.imapbackend Succeeded
> Import of duplicity.backends.lftpbackend Failed: the scheme ftp already has a 
> backend associated with it
> Import of duplicity.backends.localbackend Succeeded
> Import of duplicity.backends.megabackend Succeeded
> Import of duplicity.backends.ncftpbackend Succeeded
> Import of duplicity.backends.onedrivebackend Failed: No module named requests
> Import of duplicity.backends.par2backend Succeeded
> Import of duplicity.backends.pydrivebackend Succeeded
> Import of duplicity.backends.rsyncbackend Succeeded
> Import of duplicity.backends.ssh_paramiko_backend Succeeded
> Import of duplicity.backends.ssh_pexpect_backend Succeeded
> Import of duplicity.backends.sshbackend Failed: 'module' object has no 
> attribute 'ssh_backend'
> Import of duplicity.backends.swiftbackend Succeeded
> Import of duplicity.backends.sxbackend Succeeded
> Import of duplicity.backends.tahoebackend Succeeded
> Import of duplicity.backends.webdavbackend Succeeded
> Import of duplicity.backends.~par2wrapperbackend Succeeded
> ssh: starting thread (client mode): 0x816c750L
> ssh: Connected (version 2.0, client mod_sftp/0.9.9)
> ssh: kex algos:[u'ecdh-sha2-nistp256', u'ecdh-sha2-nistp384', 
> u'ecdh-sha2-nistp521', u'diffie-hellman-group-exchange-sha256', 
> u'diffie-hellman-group-exchange-sha1', u'diffie-hellman-group14-sha1', 
> u'diffie-hellman-group1-sha1', u'rsa1024-sha1'] server key:[u'ssh-rsa', 
> u'ssh-dss'] client encrypt:[u'aes256-ctr', u'aes192-ctr', u'aes128-ctr', 
> u'aes256-cbc', u'aes192-cbc', u'aes128-cbc', u'blowfish-ctr', 
> u'blowfish-cbc', u'cast128-cbc', u'arcfour256', u'arcfour128', u'3des-ctr', 
> u'3des-cbc'] server encrypt:[u'aes256-ctr', u'aes192-ctr', u'aes128-ctr', 
> u'aes256-cbc', u'aes192-cbc', u'aes128-cbc', u'blowfish-ctr', 
> u'blowfish-cbc', u'cast128-cbc', u'arcfour256', u'arcfour128', u'3des-ctr', 
> u'3des-cbc'] client mac:[u'hmac-sha2-256', u'hmac-sha2-512', u'hmac-sha1', 
> u'hmac-sha1-96', u'hmac-md5', u'hmac-md5-96', u'hmac-ripemd160', 
> u'address@hidden'] server mac:[u'hmac-sha2-256', u'hmac-sha2-512', 
> u'hmac-sha1', u'hmac-sha1-96', u'hmac-md5', u'hmac-md5-96', 
> u'hmac-ripemd160', u'address@hidden
enssh.com'] client compress:[u'none'] server compress:[u'none'] client 
lang:[u''] server lang:[u''] kex follows?False
> ssh: Ciphers agreed: local=aes128-ctr, remote=aes128-ctr
> ssh: using kex diffie-hellman-group1-sha1; server key type ssh-rsa; cipher: 
> local aes128-ctr, remote aes128-ctr; mac: local hmac-sha1, remote hmac-sha1; 
> compression: local none, remote none
> ssh: Switch to new keys ...
> ssh: Trying key ef4214c5fdba6d3c519c737ed1bf6358 from XXXXX
> ssh: userauth is OK
> ssh: Authentication (publickey) successful!
> ssh: [chan 1] Max packet in: 34816 bytes
> ssh: [chan 1] Max packet out: 0 bytes
> ssh: Secsh channel 1 opened.
> ssh: [chan 1] Sesch channel 1 request ok
> ssh: [chan 1] Opened sftp connection (server version 3)
> ssh: [chan 1] stat('.')
> ssh: [chan 1] stat('.')
> ssh: [chan 1] normalize('.')
> Main action: inc
> ================================================================================
> duplicity 0.7.02 (March 11, 2015)
> Args: /usr/local/bin/duplicity --verbosity debug --ssh-options 
> -oIdentityFile=XXXXX --archive-dir /var/db/duplicity --hidden-encrypt-key XXX 
> --hidden-encrypt-key XXX --hidden-encrypt-key XXXX --sign-key XXX --include 
> /XXX XXXXXX  --exclude ** / sftp://address@hidden
> FreeBSD XXXXXXX 10.1-RELEASE-p9 FreeBSD 10.1-RELEASE-p9 #0: Tue Apr  7 
> 01:09:46 UTC 2015     address@hidden:/usr/obj/usr/src/sys/GENERIC amd64 amd64
> /usr/local/bin/python 2.7.9 (default, Apr  8 2015, 15:12:41) 
> [GCC 4.2.1 Compatible FreeBSD Clang 3.4.1 (tags/RELEASE_34/dot1-final 208032)]
> ================================================================================
> Using temporary directory /tmp/duplicity-MSIr4U-tempdir
> Registering (mkstemp) temporary file 
> /tmp/duplicity-MSIr4U-tempdir/mkstemp-xUwUpW-1
> Temp has 13190230286848 available, backup will use approx 34078720.
> ssh: [chan 1] listdir('/.')
> Local and Remote metadata are synchronized, no sync needed.
> ssh: [chan 1] listdir('/.')
> 1 file exists on backend
> 4 files exist in cache
> Extracting backup chains from list of files: 
> [u'duplicity-full-signatures.20150502T140646Z.sigtar.part', 
> u'duplicity-full.20150502T140646Z.manifest.part', u'.ssh']
> File duplicity-full-signatures.20150502T140646Z.sigtar.part is not part of a 
> known set; creating new set
> Ignoring file (rejected by backup set) 
> 'duplicity-full-signatures.20150502T140646Z.sigtar.part'
> File duplicity-full.20150502T140646Z.manifest.part is not part of a known 
> set; creating new set
> File .ssh is not part of a known set; creating new set
> Ignoring file (rejected by backup set) '.ssh'
> Found backup chain [Sat May  2 16:06:46 2015]-[Sat May  2 16:06:46 2015]
> Last full backup left a partial set, restarting.
> Last full backup date: Sat May  2 16:06:46 2015
> Collection Status
> -----------------
> Connecting with backend: BackendWrapper
> Archive dir: /var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe
> 
> Found 0 secondary backup chains.
> 
> Found primary backup chain with matching signature chain:
> -------------------------
> Chain start time: Sat May  2 16:06:46 2015
> Chain end time: Sat May  2 16:06:46 2015
> Number of contained backup sets: 1
> Total number of contained volumes: 0
>  Type of backup set:                            Time:      Num volumes:
> -------------------------
> No orphaned or incomplete backup sets found.
> RESTART: The first volume failed to upload before termination.
>          Restart is impossible...starting backup from beginning.
> Deleting 
> /var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe/duplicity-full-signatures.20150502T140646Z.sigtar.part
> Deleting 
> /var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe/duplicity-full.20150502T140646Z.manifest.part
> Releasing lockfile <LinkLockFile: 
> '/var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe/XXXXXX.650626177224228893873130'
>  -- '/var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe/lockfile'>
> Using archive dir: /var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe
> Using backup name: be3234cf4cbafbdf41099030093b6bbe
> Import of duplicity.backends.azurebackend Succeeded
> Import of duplicity.backends.botobackend Succeeded
> Import of duplicity.backends.cfbackend Succeeded
> Import of duplicity.backends.copycombackend Succeeded
> Import of duplicity.backends.dpbxbackend Succeeded
> Import of duplicity.backends.ftpbackend Succeeded
> Import of duplicity.backends.ftpsbackend Succeeded
> Import of duplicity.backends.gdocsbackend Succeeded
> Import of duplicity.backends.giobackend Succeeded
> Import of duplicity.backends.hsibackend Succeeded
> Import of duplicity.backends.hubicbackend Succeeded
> Import of duplicity.backends.imapbackend Succeeded
> Import of duplicity.backends.lftpbackend Failed: the scheme ftp already has a 
> backend associated with it
> Import of duplicity.backends.localbackend Succeeded
> Import of duplicity.backends.megabackend Succeeded
> Import of duplicity.backends.ncftpbackend Succeeded
> Import of duplicity.backends.onedrivebackend Failed: No module named requests
> Import of duplicity.backends.par2backend Succeeded
> Import of duplicity.backends.pydrivebackend Succeeded
> Import of duplicity.backends.rsyncbackend Succeeded
> Import of duplicity.backends.ssh_paramiko_backend Succeeded
> Import of duplicity.backends.ssh_pexpect_backend Succeeded
> Import of duplicity.backends.sshbackend Failed: 'module' object has no 
> attribute 'ssh_backend'
> Import of duplicity.backends.swiftbackend Succeeded
> Import of duplicity.backends.sxbackend Succeeded
> Import of duplicity.backends.tahoebackend Succeeded
> Import of duplicity.backends.webdavbackend Succeeded
> Import of duplicity.backends.~par2wrapperbackend Succeeded
> ssh: starting thread (client mode): 0x816c750L
> ssh: Connected (version 2.0, client mod_sftp/0.9.9)
> ssh: kex algos:[u'ecdh-sha2-nistp256', u'ecdh-sha2-nistp384', 
> u'ecdh-sha2-nistp521', u'diffie-hellman-group-exchange-sha256', 
> u'diffie-hellman-group-exchange-sha1', u'diffie-hellman-group14-sha1', 
> u'diffie-hellman-group1-sha1', u'rsa1024-sha1'] server key:[u'ssh-rsa', 
> u'ssh-dss'] client encrypt:[u'aes256-ctr', u'aes192-ctr', u'aes128-ctr', 
> u'aes256-cbc', u'aes192-cbc', u'aes128-cbc', u'blowfish-ctr', 
> u'blowfish-cbc', u'cast128-cbc', u'arcfour256', u'arcfour128', u'3des-ctr', 
> u'3des-cbc'] server encrypt:[u'aes256-ctr', u'aes192-ctr', u'aes128-ctr', 
> u'aes256-cbc', u'aes192-cbc', u'aes128-cbc', u'blowfish-ctr', 
> u'blowfish-cbc', u'cast128-cbc', u'arcfour256', u'arcfour128', u'3des-ctr', 
> u'3des-cbc'] client mac:[u'hmac-sha2-256', u'hmac-sha2-512', u'hmac-sha1', 
> u'hmac-sha1-96', u'hmac-md5', u'hmac-md5-96', u'hmac-ripemd160', 
> u'address@hidden'] server mac:[u'hmac-sha2-256', u'hmac-sha2-512', 
> u'hmac-sha1', u'hmac-sha1-96', u'hmac-md5', u'hmac-md5-96', 
> u'hmac-ripemd160', u'address@hidden
enssh.com'] client compress:[u'none'] server compress:[u'none'] client 
lang:[u''] server lang:[u''] kex follows?False
> ssh: Ciphers agreed: local=aes128-ctr, remote=aes128-ctr
> ssh: using kex diffie-hellman-group1-sha1; server key type ssh-rsa; cipher: 
> local aes128-ctr, remote aes128-ctr; mac: local hmac-sha1, remote hmac-sha1; 
> compression: local none, remote none
> ssh: Switch to new keys ...
> ssh: Trying key ef4214c5fdba6d3c519c737ed1bf6358 from /XXXXX
> ssh: userauth is OK
> ssh: Authentication (publickey) successful!
> ssh: [chan 1] Max packet in: 34816 bytes
> ssh: [chan 1] Max packet out: 0 bytes
> ssh: Secsh channel 1 opened.
> ssh: [chan 1] Sesch channel 1 request ok
> ssh: [chan 1] Opened sftp connection (server version 3)
> ssh: [chan 1] stat('.')
> ssh: [chan 1] stat('.')
> ssh: [chan 1] normalize('.')
> Main action: inc
> ================================================================================
> duplicity 0.7.02 (March 11, 2015)
> Args: /usr/local/bin/duplicity --verbosity debug --ssh-options 
> -oIdentityFile=//XXXX --archive-dir /var/db/duplicity --hidden-encrypt-key 
> XXXX --hidden-encrypt-key XXX --hidden-encrypt-key XXX --sign-key XXXX 
> --include /XXX XXXXXX-exclude ** / sftp://address@hidden
> FreeBSD XXXXXX 10.1-RELEASE-p9 FreeBSD 10.1-RELEASE-p9 #0: Tue Apr  7 
> 01:09:46 UTC 2015     address@hidden:/usr/obj/usr/src/sys/GENERIC amd64 amd64
> /usr/local/bin/python 2.7.9 (default, Apr  8 2015, 15:12:41) 
> [GCC 4.2.1 Compatible FreeBSD Clang 3.4.1 (tags/RELEASE_34/dot1-final 208032)]
> ================================================================================
> Using temporary directory /tmp/duplicity-ny_w0E-tempdir
> Registering (mkstemp) temporary file 
> /tmp/duplicity-ny_w0E-tempdir/mkstemp-hiD03y-1
> Temp has 13190230286848 available, backup will use approx 34078720.
> ssh: [chan 1] listdir('/.')
> Local and Remote metadata are synchronized, no sync needed.
> ssh: [chan 1] listdir('/.')
> 1 file exists on backend
> 2 files exist in cache
> Extracting backup chains from list of files: [u'.ssh']
> File .ssh is not part of a known set; creating new set
> Ignoring file (rejected by backup set) '.ssh'
> Last full backup date: none
> Collection Status
> -----------------
> Connecting with backend: BackendWrapper
> Archive dir: /var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe
> 
> Found 0 secondary backup chains.
> No backup chains with active signatures found
> No orphaned or incomplete backup sets found.
> No signatures found, switching to full backup.
> Using temporary directory 
> /var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe/duplicity-uGRFgC-tempdir
> Registering (mktemp) temporary file 
> /var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe/duplicity-uGRFgC-tempdir/mktemp-v8RrI4-1
> Using temporary directory 
> /var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe/duplicity-Ek5eEY-tempdir
> Registering (mktemp) temporary file 
> /var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe/duplicity-Ek5eEY-tempdir/mktemp-gWTVYe-1
> AsyncScheduler: instantiating at concurrency 0
> Registering (mktemp) temporary file 
> /tmp/duplicity-ny_w0E-tempdir/mktemp-cOWPt7-2
> Selecting /
> Comparing . and None
> Getting delta of (. dir) and None
> A .
> Selecting /XXXX
> Comparing XXXX and None
> Getting delta of (XXX dir) and None
> A XXX
> 
> …. some 350k lines snipped ….
> 
> Removing still remembered temporary file 
> /var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe/duplicity-uGRFgC-tempdir/mktemp-v8RrI4-1
> Removing still remembered temporary file 
> /var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe/duplicity-Ek5eEY-tempdir/mktemp-gWTVYe-1
> AsyncScheduler: running task synchronously (asynchronicity disabled)
> Writing duplicity-full.20150503T112752Z.vol1.difftar.gpg
> ssh: [chan 1] open('/duplicity-full.20150503T112752Z.vol1.difftar.gpg', 'wb')
> ssh: [chan 1] open('/duplicity-full.20150503T112752Z.vol1.difftar.gpg', 'wb') 
> -> 61623031376465316137393162356235
> ssh: Sending global request "address@hidden"
> ssh: Sending global request "address@hidden"
> ssh: Sending global request "address@hidden"
> ssh: Sending global request "address@hidden
> … repeated few 100 times ...
> ssh: Sending global request "address@hidden"
> ssh: Sending global request "address@hidden"
> ssh: Sending global request "address@hidden"
> ssh: Disconnect (code 11): data stalled timeout reached
> ssh: [chan 1] close(61623031376465316137393162356235)
> Backtrace of previous error: Traceback (innermost last):
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 365, in inner_retry
>     return fn(self, *args)
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 515, in put
>     self.__do_put(source_path, remote_filename)
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 501, in __do_put
>     self.backend._put(source_path, remote_filename)
>   File 
> "/usr/local/lib/python2.7/site-packages/duplicity/backends/ssh_paramiko_backend.py",
>  line 288, in _put
>     self.sftp.put(source_path.name, remote_filename)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 587, in put
>     return self.putfo(fl, remotepath, os.stat(localpath).st_size, callback, 
> confirm)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 546, in putfo
>     fr.write(data)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/file.py", line 330, 
> in write
>     self._write_all(data)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/file.py", line 447, 
> in _write_all
>     count = self._write(data)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_file.py", line 
> 176, in _write
>     self._reqs.append(self.sftp._async_request(type(None), CMD_WRITE, 
> self.handle, long(self._realpos), data[:chunk]))
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 670, in _async_request
>     self._send_packet(t, msg)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp.py", line 170, 
> in _send_packet
>     self._write_all(out)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp.py", line 135, 
> in _write_all
>     raise EOFError()
>  EOFError
> 
> Attempt 1 failed. EOFError: 
> Writing duplicity-full.20150503T112752Z.vol1.difftar.gpg
> ssh: [chan 1] open('/duplicity-full.20150503T112752Z.vol1.difftar.gpg', 'wb')
> Backtrace of previous error: Traceback (innermost last):
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 365, in inner_retry
>     return fn(self, *args)
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 515, in put
>     self.__do_put(source_path, remote_filename)
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 501, in __do_put
>     self.backend._put(source_path, remote_filename)
>   File 
> "/usr/local/lib/python2.7/site-packages/duplicity/backends/ssh_paramiko_backend.py",
>  line 288, in _put
>     self.sftp.put(source_path.name, remote_filename)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 587, in put
>     return self.putfo(fl, remotepath, os.stat(localpath).st_size, callback, 
> confirm)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 541, in putfo
>     with self.file(remotepath, 'wb') as fr:
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 245, in open
>     t, msg = self._request(CMD_OPEN, filename, imode, attrblock)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 648, in _request
>     num = self._async_request(type(None), t, *arg)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 670, in _async_request
>     self._send_packet(t, msg)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp.py", line 170, 
> in _send_packet
>     self._write_all(out)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp.py", line 135, 
> in _write_all
>     raise EOFError()
>  EOFError
> 
> Attempt 2 failed. EOFError: 
> Writing duplicity-full.20150503T112752Z.vol1.difftar.gpg
> ssh: [chan 1] open('/duplicity-full.20150503T112752Z.vol1.difftar.gpg', 'wb')
> Backtrace of previous error: Traceback (innermost last):
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 365, in inner_retry
>     return fn(self, *args)
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 515, in put
>     self.__do_put(source_path, remote_filename)
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 501, in __do_put
>     self.backend._put(source_path, remote_filename)
>   File 
> "/usr/local/lib/python2.7/site-packages/duplicity/backends/ssh_paramiko_backend.py",
>  line 288, in _put
>     self.sftp.put(source_path.name, remote_filename)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 587, in put
>     return self.putfo(fl, remotepath, os.stat(localpath).st_size, callback, 
> confirm)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 541, in putfo
>     with self.file(remotepath, 'wb') as fr:
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 245, in open
>     t, msg = self._request(CMD_OPEN, filename, imode, attrblock)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 648, in _request
>     num = self._async_request(type(None), t, *arg)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 670, in _async_request
>     self._send_packet(t, msg)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp.py", line 170, 
> in _send_packet
>     self._write_all(out)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp.py", line 135, 
> in _write_all
>     raise EOFError()
>  EOFError
> 
> Attempt 3 failed. EOFError: 
> Writing duplicity-full.20150503T112752Z.vol1.difftar.gpg
> ssh: [chan 1] open('/duplicity-full.20150503T112752Z.vol1.difftar.gpg', 'wb')
> Backtrace of previous error: Traceback (innermost last):
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 365, in inner_retry
>     return fn(self, *args)
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 515, in put
>     self.__do_put(source_path, remote_filename)
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 501, in __do_put
>     self.backend._put(source_path, remote_filename)
>   File 
> "/usr/local/lib/python2.7/site-packages/duplicity/backends/ssh_paramiko_backend.py",
>  line 288, in _put
>     self.sftp.put(source_path.name, remote_filename)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 587, in put
>     return self.putfo(fl, remotepath, os.stat(localpath).st_size, callback, 
> confirm)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 541, in putfo
>     with self.file(remotepath, 'wb') as fr:
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 245, in open
>     t, msg = self._request(CMD_OPEN, filename, imode, attrblock)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 648, in _request
>     num = self._async_request(type(None), t, *arg)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 670, in _async_request
>     self._send_packet(t, msg)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp.py", line 170, 
> in _send_packet
>     self._write_all(out)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp.py", line 135, 
> in _write_all
>     raise EOFError()
>  EOFError
> 
> Attempt 4 failed. EOFError: 
> Writing duplicity-full.20150503T112752Z.vol1.difftar.gpg
> ssh: [chan 1] open('/duplicity-full.20150503T112752Z.vol1.difftar.gpg', 'wb')
> Backtrace of previous error: Traceback (innermost last):
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 365, in inner_retry
>     return fn(self, *args)
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 515, in put
>     self.__do_put(source_path, remote_filename)
>   File "/usr/local/lib/python2.7/site-packages/duplicity/backend.py", line 
> 501, in __do_put
>     self.backend._put(source_path, remote_filename)
>   File 
> "/usr/local/lib/python2.7/site-packages/duplicity/backends/ssh_paramiko_backend.py",
>  line 288, in _put
>     self.sftp.put(source_path.name, remote_filename)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 587, in put
>     return self.putfo(fl, remotepath, os.stat(localpath).st_size, callback, 
> confirm)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 541, in putfo
>     with self.file(remotepath, 'wb') as fr:
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 245, in open
>     t, msg = self._request(CMD_OPEN, filename, imode, attrblock)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 648, in _request
>     num = self._async_request(type(None), t, *arg)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp_client.py", line 
> 670, in _async_request
>     self._send_packet(t, msg)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp.py", line 170, 
> in _send_packet
>     self._write_all(out)
>   File "/usr/local/lib/python2.7/site-packages/paramiko/sftp.py", line 135, 
> in _write_all
>     raise EOFError()
>  EOFError
> 
> Giving up after 5 attempts. EOFError: 
> Releasing lockfile <LinkLockFile: 
> '/var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe/XXXXXXg.650626177224228893873130'
>  -- '/var/db/duplicity/be3234cf4cbafbdf41099030093b6bbe/lockfile'>
> Removing still remembered temporary file 
> /tmp/duplicity-ny_w0E-tempdir/mktemp-cOWPt7-2
> Removing still remembered temporary file 
> /tmp/duplicity-ny_w0E-tempdir/mkstemp-hiD03y-1
> 

the keepalive & EOFError parts suggest that your ssh connection is dropped 
after a while. check if that is disabled for some reason in your ssh server 
settings.

alternatively use the old pexpect ssh backend.

..ede/duply.net