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

[Dirk-Willem van Gulik]

> On 03 May 2015, at 18:58, address@hidden wrote:
> 
> 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).
>> 
> 
> 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.
> 

Thanks - debugging with this (Setting timeout to a few hours) and looking at 
tcpdump suggests that after

        ssh: [chan 1] open('/duplicity-full.20150503T171238Z.vol1.difftar.gpg', 
'wb')
        ssh: [chan 1] open('/duplicity-full.20150503T171238Z.vol1.difftar.gpg', 
'wb') -> 39323765383430333130396635343637

no traffic is sent at all but for the occasional:

        ssh: Sending global request "address@hidden"
        ssh: Sending global request "address@hidden"
        ssh: Sending global request "address@hidden

requests. It is almost as if duplicity is not streaming at all. An identical 
command to ‘file://' shows the files getting created and sent/written just fine.

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
>> 
>