Skip to content

Instantly share code, notes, and snippets.

@josip

josip/Transmit.log

Created May 15, 2010 13:51
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save josip/402203 to your computer and use it in GitHub Desktop.
Save josip/402203 to your computer and use it in GitHub Desktop.
Download ZIP
Raw
Transmit.log
15:34:18.130 L20 []#407 disabling diffie-hellman-group-exchange because we cannot find moduli file
15:34:18.262 L20 []#408 kex alg, key alg: diffie-hellman-group1-sha1 ssh-rsa
15:34:18.262 L20 []#409 outgoing: aes128-ctr hmac-md5 none
15:34:18.262 L20 []#410 incoming: aes128-ctr hmac-md5 none
15:34:19.869 L20 []#411 NEW KEYS
15:34:19.869 L20 []#412 starting service ssh-userauth
15:34:20.767 L20 []#413 josip trying auth none
15:34:20.815 L20 []#414 josip trying auth password
15:34:20.815 L20 []#415 josip authenticated with password
15:34:20.816 L20 []#416 starting service ssh-connection
15:34:20.868 L20 []#417 got channel session request
15:34:20.868 L20 []#418 channel open
15:34:20.917 L20 []#419 asking for subsystem "sftp"
15:34:20.917 L20 []#420 {'sftp': <class twisted.conch.ssh.filetransfer.FileTransferServer at 0x75a7b0>}
15:34:20.917 L10 []#421 <SFTPHandler #2>: .__init__(<SFTPUser #2>)
15:34:20.918 L20 []#422 <SFTPHandler #2>: .gotVersion(3, {})
15:34:21.068 L20 []#423 <SFTPHandler #2>: .realPath('.')
15:34:21.068 L10 []#424 <SFTPHandler #2>: CONVERT '.'
15:34:21.068 L10 []#425 <SFTPHandler #2>: PATH []
15:34:21.319 L20 []#426 <SFTPHandler #2>: .openDirectory('/')
15:34:21.319 L10 []#427 <SFTPHandler #2>: CONVERT '/'
15:34:21.319 L10 []#428 <SFTPHandler #2>: PATH []
15:34:21.319 L10 []#429 <SFTPHandler #2>: _got_root( (<DirectoryNode RW-MUT ieyrl>, []) )
15:34:21.320 L20 []#430 SharemapUpdater(26kct): starting (MODE_READ)
15:34:21.320 L10 []#431 sending query to [g4z7jkne], readsize=4000
15:34:21.458 L20 []#432 storage: slot_readv 26kctlae6vk6r56p2waaui3jky []
15:34:21.469 L10 []#433 returning shares [0, 1, 2, 3, 4, 5, 6, 7, 8, 9]
15:34:21.524 L10 []#434 got result from [g4z7jkne], 10 shares
15:34:21.524 L10 []#435 _got_results: got shnum #0 from peerid g4z7jkne
15:34:21.525 L20 []#436 found valid version 51-qilg from g4z7jkne-sh0: 3-10/3819/3819
15:34:21.525 L10 []#437 _got_results: got shnum #1 from peerid g4z7jkne
15:34:21.526 L10 []#438 _got_results: got shnum #2 from peerid g4z7jkne
15:34:21.526 L10 []#439 _got_results: got shnum #3 from peerid g4z7jkne
15:34:21.526 L10 []#440 _got_results: got shnum #4 from peerid g4z7jkne
15:34:21.526 L10 []#441 _got_results: got shnum #5 from peerid g4z7jkne
15:34:21.531 L10 []#442 _got_results: got shnum #6 from peerid g4z7jkne
15:34:21.532 L10 []#443 _got_results: got shnum #7 from peerid g4z7jkne
15:34:21.532 L10 []#444 _got_results: got shnum #8 from peerid g4z7jkne
15:34:21.532 L10 []#445 _got_results: got shnum #9 from peerid g4z7jkne
15:34:21.532 L10 []#446 _got_results done
15:34:21.533 L10 []#447 _check_for_done, mode is 'MODE_READ', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False
15:34:21.533 L20 []#448 all queries are retired, no extra peers: done
15:34:21.533 L20 []#449 servermap: 10*seq51-qilg
15:34:21.608 L20 []#450 Retrieve(26kct): starting
15:34:21.609 L10 []#451 sending sh#0 request to [g4z7jkne]
15:34:21.609 L20 []#452 got data from cache
15:34:21.609 L10 []#453 sending sh#1 request to [g4z7jkne]
15:34:21.609 L20 []#454 got data from cache
15:34:21.609 L10 []#455 sending sh#2 request to [g4z7jkne]
15:34:21.609 L20 []#456 got data from cache
15:34:21.692 L10 []#457 got results (1 shares) from [g4z7jkne]
15:34:21.693 L20 []#458 _got_results: got shnum #0 from peerid g4z7jkne
15:34:21.693 L20 []#459 data valid! len=1273
15:34:21.693 L10 []#460 _check_for_done: running=True, decoding=False
15:34:21.693 L10 []#461 _maybe_send_more_queries, have=1, k=3, outstanding=2
15:34:21.693 L10 []#462 enough queries are in flight, no more are needed
15:34:21.694 L10 []#463 got results (1 shares) from [g4z7jkne]
15:34:21.694 L20 []#464 _got_results: got shnum #1 from peerid g4z7jkne
15:34:21.694 L20 []#465 data valid! len=1273
15:34:21.694 L10 []#466 _check_for_done: running=True, decoding=False
15:34:21.694 L10 []#467 _maybe_send_more_queries, have=2, k=3, outstanding=1
15:34:21.694 L10 []#468 enough queries are in flight, no more are needed
15:34:21.695 L10 []#469 got results (1 shares) from [g4z7jkne]
15:34:21.695 L20 []#470 _got_results: got shnum #2 from peerid g4z7jkne
15:34:21.700 L20 []#471 data valid! len=1273
15:34:21.700 L10 []#472 _check_for_done: running=True, decoding=False
15:34:21.700 L20 []#473 params (3819, 3, 10), we have 3 shares
15:34:21.700 L20 []#474 about to decode, shareids=[0, 1, 2]
15:34:21.700 L20 []#475 decode done, 3 buffers
15:34:21.700 L20 []#476 joined length 3819, datalength 3819
15:34:21.700 L20 []#477 segment len=3819
15:34:21.701 L20 []#478 Retrieve done, success!
15:34:23.382 L20 []#479 disabling diffie-hellman-group-exchange because we cannot find moduli file
15:34:23.411 L20 []#480 kex alg, key alg: diffie-hellman-group1-sha1 ssh-rsa
15:34:23.411 L20 []#481 outgoing: aes128-ctr hmac-md5 none
15:34:23.411 L20 []#482 incoming: aes128-ctr hmac-md5 none
15:34:26.653 L20 []#483 NEW KEYS
15:34:26.653 L20 []#484 starting service ssh-userauth
15:34:26.685 L20 []#485 josip trying auth none
15:34:26.746 L20 []#486 josip trying auth password
15:34:26.746 L20 []#487 josip authenticated with password
15:34:26.747 L20 []#488 starting service ssh-connection
15:34:26.788 L20 []#489 got channel session request
15:34:26.788 L20 []#490 channel open
15:34:26.849 L20 []#491 executing command "df -P -k /"
15:34:26.921 L20 []#492 <SFTPUser #3>: .execCommand(<twisted.conch.ssh.session.SSHSessionProcessProtocol instance at 0x1ff0c38>, 'df -P -k /')
15:34:26.922 L20 []#493 Unhandled Error
Traceback (most recent call last):
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/log.py", line 69, in callWithContext
return context.call({ILogContext: newCtx}, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 59, in callWithContext
return self.currentContext().callWithContext(ctx, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 37, in callWithContext
return func(*args,**kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/channel.py", line 137, in requestReceived
return f(data)
--- <exception caught here> ---
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/session.py", line 67, in request_exec
self.session.execCommand(pp, f)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/allmydata_tahoe-1.6.1_r4288-py2.6.egg/allmydata/frontends/sftpd.py", line 1240, in execCommand
raise NotImplementedError
exceptions.NotImplementedError:
FAILURE:
[CopiedFailure instance: Traceback from remote host -- Traceback (most recent call last):
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/log.py", line 69, in callWithContext
return context.call({ILogContext: newCtx}, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 59, in callWithContext
return self.currentContext().callWithContext(ctx, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 37, in callWithContext
return func(*args,**kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/channel.py", line 137, in requestReceived
return f(data)
--- <exception caught here> ---
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/session.py", line 67, in request_exec
self.session.execCommand(pp, f)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/allmydata_tahoe-1.6.1_r4288-py2.6.egg/allmydata/frontends/sftpd.py", line 1240, in execCommand
raise NotImplementedError
exceptions.NotImplementedError:
]
15:34:27.023 L20 []#494 <SFTPUser #3>: .eofReceived()
15:34:27.093 L20 []#495 <SFTPUser #3>: .closed()
15:34:27.093 L20 []#496 connection lost
15:34:30.873 L20 []#497 <SFTPHandler #2>: .makeDirectory('/.Trashes', {})
15:34:30.873 L10 []#498 <SFTPHandler #2>: CONVERT '/.Trashes'
15:34:30.873 L10 []#499 <SFTPHandler #2>: PATH [u'.Trashes']
15:34:30.873 L20 []#500 SharemapUpdater(26kct): starting (MODE_READ)
15:34:30.873 L10 []#501 sending query to [g4z7jkne], readsize=4000
15:34:30.888 L20 []#502 storage: slot_readv 26kctlae6vk6r56p2waaui3jky []
15:34:30.889 L10 []#503 returning shares [0, 1, 2, 3, 4, 5, 6, 7, 8, 9]
15:34:30.913 L10 []#504 got result from [g4z7jkne], 10 shares
15:34:30.913 L10 []#505 _got_results: got shnum #0 from peerid g4z7jkne
15:34:30.914 L20 []#506 found valid version 51-qilg from g4z7jkne-sh0: 3-10/3819/3819
15:34:30.914 L10 []#507 _got_results: got shnum #1 from peerid g4z7jkne
15:34:30.914 L10 []#508 _got_results: got shnum #2 from peerid g4z7jkne
15:34:30.914 L10 []#509 _got_results: got shnum #3 from peerid g4z7jkne
15:34:30.914 L10 []#510 _got_results: got shnum #4 from peerid g4z7jkne
15:34:30.914 L10 []#511 _got_results: got shnum #5 from peerid g4z7jkne
15:34:30.915 L10 []#512 _got_results: got shnum #6 from peerid g4z7jkne
15:34:30.915 L10 []#513 _got_results: got shnum #7 from peerid g4z7jkne
15:34:30.915 L10 []#514 _got_results: got shnum #8 from peerid g4z7jkne
15:34:30.915 L10 []#515 _got_results: got shnum #9 from peerid g4z7jkne
15:34:30.915 L10 []#516 _got_results done
15:34:30.915 L10 []#517 _check_for_done, mode is 'MODE_READ', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False
15:34:30.915 L20 []#518 all queries are retired, no extra peers: done
15:34:30.916 L20 []#519 servermap: 10*seq51-qilg
15:34:30.959 L20 []#520 Retrieve(26kct): starting
15:34:30.959 L10 []#521 sending sh#0 request to [g4z7jkne]
15:34:30.959 L20 []#522 got data from cache
15:34:30.959 L10 []#523 sending sh#1 request to [g4z7jkne]
15:34:30.960 L20 []#524 got data from cache
15:34:30.960 L10 []#525 sending sh#2 request to [g4z7jkne]
15:34:30.960 L20 []#526 got data from cache
15:34:30.967 L10 []#527 got results (1 shares) from [g4z7jkne]
15:34:30.967 L20 []#528 _got_results: got shnum #0 from peerid g4z7jkne
15:34:30.969 L20 []#529 data valid! len=1273
15:34:30.969 L10 []#530 _check_for_done: running=True, decoding=False
15:34:30.969 L10 []#531 _maybe_send_more_queries, have=1, k=3, outstanding=2
15:34:30.969 L10 []#532 enough queries are in flight, no more are needed
15:34:30.969 L10 []#533 got results (1 shares) from [g4z7jkne]
15:34:30.969 L20 []#534 _got_results: got shnum #1 from peerid g4z7jkne
15:34:30.969 L20 []#535 data valid! len=1273
15:34:30.970 L10 []#536 _check_for_done: running=True, decoding=False
15:34:30.970 L10 []#537 _maybe_send_more_queries, have=2, k=3, outstanding=1
15:34:30.970 L10 []#538 enough queries are in flight, no more are needed
15:34:30.970 L10 []#539 got results (1 shares) from [g4z7jkne]
15:34:30.970 L20 []#540 _got_results: got shnum #2 from peerid g4z7jkne
15:34:30.970 L20 []#541 data valid! len=1273
15:34:30.970 L10 []#542 _check_for_done: running=True, decoding=False
15:34:30.970 L20 []#543 params (3819, 3, 10), we have 3 shares
15:34:30.970 L20 []#544 about to decode, shareids=[0, 1, 2]
15:34:30.971 L20 []#545 decode done, 3 buffers
15:34:30.971 L20 []#546 joined length 3819, datalength 3819
15:34:30.971 L20 []#547 segment len=3819
15:34:30.971 L20 []#548 Retrieve done, success!
15:34:31.005 L20 []#549 <SFTPHandler #2>: .openDirectory('/')
15:34:31.005 L10 []#550 <SFTPHandler #2>: CONVERT '/'
15:34:31.005 L10 []#551 <SFTPHandler #2>: PATH []
15:34:31.006 L10 []#552 <SFTPHandler #2>: _got_root( (<DirectoryNode RW-MUT ieyrl>, []) )
15:34:31.006 L20 []#553 SharemapUpdater(26kct): starting (MODE_READ)
15:34:31.006 L10 []#554 sending query to [g4z7jkne], readsize=4000
15:34:31.011 L20 []#555 storage: slot_readv 26kctlae6vk6r56p2waaui3jky []
15:34:31.013 L10 []#556 returning shares [0, 1, 2, 3, 4, 5, 6, 7, 8, 9]
15:34:31.161 L10 []#557 got result from [g4z7jkne], 10 shares
15:34:31.161 L10 []#558 _got_results: got shnum #0 from peerid g4z7jkne
15:34:31.162 L20 []#559 found valid version 51-qilg from g4z7jkne-sh0: 3-10/3819/3819
15:34:31.163 L10 []#560 _got_results: got shnum #1 from peerid g4z7jkne
15:34:31.163 L10 []#561 _got_results: got shnum #2 from peerid g4z7jkne
15:34:31.163 L10 []#562 _got_results: got shnum #3 from peerid g4z7jkne
15:34:31.163 L10 []#563 _got_results: got shnum #4 from peerid g4z7jkne
15:34:31.164 L10 []#564 _got_results: got shnum #5 from peerid g4z7jkne
15:34:31.164 L10 []#565 _got_results: got shnum #6 from peerid g4z7jkne
15:34:31.164 L10 []#566 _got_results: got shnum #7 from peerid g4z7jkne
15:34:31.164 L10 []#567 _got_results: got shnum #8 from peerid g4z7jkne
15:34:31.165 L10 []#568 _got_results: got shnum #9 from peerid g4z7jkne
15:34:31.165 L10 []#569 _got_results done
15:34:31.165 L10 []#570 _check_for_done, mode is 'MODE_READ', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False
15:34:31.165 L20 []#571 all queries are retired, no extra peers: done
15:34:31.165 L20 []#572 servermap: 10*seq51-qilg
15:34:31.199 L20 []#573 Retrieve(26kct): starting
15:34:31.200 L10 []#574 sending sh#0 request to [g4z7jkne]
15:34:31.200 L20 []#575 got data from cache
15:34:31.201 L10 []#576 sending sh#1 request to [g4z7jkne]
15:34:31.201 L20 []#577 got data from cache
15:34:31.201 L10 []#578 sending sh#2 request to [g4z7jkne]
15:34:31.201 L20 []#579 got data from cache
15:34:31.202 L10 []#580 got results (1 shares) from [g4z7jkne]
15:34:31.202 L20 []#581 _got_results: got shnum #0 from peerid g4z7jkne
15:34:31.203 L20 []#582 data valid! len=1273
15:34:31.203 L10 []#583 _check_for_done: running=True, decoding=False
15:34:31.203 L10 []#584 _maybe_send_more_queries, have=1, k=3, outstanding=2
15:34:31.203 L10 []#585 enough queries are in flight, no more are needed
15:34:31.203 L10 []#586 got results (1 shares) from [g4z7jkne]
15:34:31.203 L20 []#587 _got_results: got shnum #1 from peerid g4z7jkne
15:34:31.203 L20 []#588 data valid! len=1273
15:34:31.204 L10 []#589 _check_for_done: running=True, decoding=False
15:34:31.204 L10 []#590 _maybe_send_more_queries, have=2, k=3, outstanding=1
15:34:31.204 L10 []#591 enough queries are in flight, no more are needed
15:34:31.204 L10 []#592 got results (1 shares) from [g4z7jkne]
15:34:31.204 L20 []#593 _got_results: got shnum #2 from peerid g4z7jkne
15:34:31.204 L20 []#594 data valid! len=1273
15:34:31.226 L10 []#595 _check_for_done: running=True, decoding=False
15:34:31.226 L20 []#596 params (3819, 3, 10), we have 3 shares
15:34:31.226 L20 []#597 about to decode, shareids=[0, 1, 2]
15:34:31.226 L20 []#598 decode done, 3 buffers
15:34:31.226 L20 []#599 joined length 3819, datalength 3819
15:34:31.226 L20 []#600 segment len=3819
15:34:31.226 L20 []#601 Retrieve done, success!
15:34:31.770 L20 []#602 <SFTPHandler #2>: .makeDirectory('/.Trashes', {})
15:34:31.770 L10 []#603 <SFTPHandler #2>: CONVERT '/.Trashes'
15:34:32.131 L10 []#604 <SFTPHandler #2>: PATH [u'.Trashes']
15:34:32.152 L20 []#605 SharemapUpdater(26kct): starting (MODE_READ)
15:34:32.152 L10 []#606 sending query to [g4z7jkne], readsize=4000
15:34:32.212 L20 []#607 disabling diffie-hellman-group-exchange because we cannot find moduli file
15:34:32.294 L20 []#608 storage: slot_readv 26kctlae6vk6r56p2waaui3jky []
15:34:32.296 L10 []#609 returning shares [0, 1, 2, 3, 4, 5, 6, 7, 8, 9]
15:34:32.378 L10 []#610 got result from [g4z7jkne], 10 shares
15:34:32.378 L10 []#611 _got_results: got shnum #0 from peerid g4z7jkne
15:34:32.380 L20 []#612 found valid version 51-qilg from g4z7jkne-sh0: 3-10/3819/3819
15:34:32.380 L10 []#613 _got_results: got shnum #1 from peerid g4z7jkne
15:34:32.380 L10 []#614 _got_results: got shnum #2 from peerid g4z7jkne
15:34:32.381 L10 []#615 _got_results: got shnum #3 from peerid g4z7jkne
15:34:32.381 L10 []#616 _got_results: got shnum #4 from peerid g4z7jkne
15:34:32.381 L10 []#617 _got_results: got shnum #5 from peerid g4z7jkne
15:34:32.381 L10 []#618 _got_results: got shnum #6 from peerid g4z7jkne
15:34:32.381 L10 []#619 _got_results: got shnum #7 from peerid g4z7jkne
15:34:32.381 L10 []#620 _got_results: got shnum #8 from peerid g4z7jkne
15:34:32.382 L10 []#621 _got_results: got shnum #9 from peerid g4z7jkne
15:34:32.382 L10 []#622 _got_results done
15:34:32.382 L10 []#623 _check_for_done, mode is 'MODE_READ', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False
15:34:32.382 L20 []#624 all queries are retired, no extra peers: done
15:34:32.382 L20 []#625 servermap: 10*seq51-qilg
15:34:32.410 L20 []#626 kex alg, key alg: diffie-hellman-group1-sha1 ssh-rsa
15:34:32.411 L20 []#627 outgoing: aes128-ctr hmac-md5 none
15:34:32.411 L20 []#628 incoming: aes128-ctr hmac-md5 none
15:34:33.663 L20 []#629 Retrieve(26kct): starting
15:34:33.663 L10 []#630 sending sh#0 request to [g4z7jkne]
15:34:33.663 L20 []#631 got data from cache
15:34:33.664 L10 []#632 sending sh#1 request to [g4z7jkne]
15:34:33.664 L20 []#633 got data from cache
15:34:33.664 L10 []#634 sending sh#2 request to [g4z7jkne]
15:34:33.664 L20 []#635 got data from cache
15:34:33.726 L10 []#636 got results (1 shares) from [g4z7jkne]
15:34:33.726 L20 []#637 _got_results: got shnum #0 from peerid g4z7jkne
15:34:33.742 L20 []#638 data valid! len=1273
15:34:33.742 L10 []#639 _check_for_done: running=True, decoding=False
15:34:33.742 L10 []#640 _maybe_send_more_queries, have=1, k=3, outstanding=2
15:34:33.742 L10 []#641 enough queries are in flight, no more are needed
15:34:33.742 L10 []#642 got results (1 shares) from [g4z7jkne]
15:34:33.742 L20 []#643 _got_results: got shnum #1 from peerid g4z7jkne
15:34:33.743 L20 []#644 data valid! len=1273
15:34:33.743 L10 []#645 _check_for_done: running=True, decoding=False
15:34:33.743 L10 []#646 _maybe_send_more_queries, have=2, k=3, outstanding=1
15:34:33.743 L10 []#647 enough queries are in flight, no more are needed
15:34:33.743 L10 []#648 got results (1 shares) from [g4z7jkne]
15:34:33.743 L20 []#649 _got_results: got shnum #2 from peerid g4z7jkne
15:34:33.744 L20 []#650 data valid! len=1273
15:34:33.744 L10 []#651 _check_for_done: running=True, decoding=False
15:34:33.744 L20 []#652 params (3819, 3, 10), we have 3 shares
15:34:33.744 L20 []#653 about to decode, shareids=[0, 1, 2]
15:34:33.744 L20 []#654 decode done, 3 buffers
15:34:33.744 L20 []#655 joined length 3819, datalength 3819
15:34:33.744 L20 []#656 segment len=3819
15:34:33.744 L20 []#657 Retrieve done, success!
15:34:33.767 L20 []#658 NEW KEYS
15:34:33.768 L20 []#659 starting service ssh-userauth
15:34:33.877 L20 []#660 josip trying auth none
15:34:33.932 L20 []#661 <SFTPHandler #2>: .openDirectory('/')
15:34:33.932 L10 []#662 <SFTPHandler #2>: CONVERT '/'
15:34:33.933 L10 []#663 <SFTPHandler #2>: PATH []
15:34:33.933 L10 []#664 <SFTPHandler #2>: _got_root( (<DirectoryNode RW-MUT ieyrl>, []) )
15:34:33.933 L20 []#665 SharemapUpdater(26kct): starting (MODE_READ)
15:34:33.953 L10 []#666 sending query to [g4z7jkne], readsize=4000
15:34:34.001 L20 []#667 josip trying auth password
15:34:34.011 L20 []#668 josip authenticated with password
15:34:34.012 L20 []#669 starting service ssh-connection
15:34:34.039 L20 []#670 storage: slot_readv 26kctlae6vk6r56p2waaui3jky []
15:34:34.049 L10 []#671 returning shares [0, 1, 2, 3, 4, 5, 6, 7, 8, 9]
15:34:34.125 L10 []#672 got result from [g4z7jkne], 10 shares
15:34:34.125 L10 []#673 _got_results: got shnum #0 from peerid g4z7jkne
15:34:34.126 L20 []#674 found valid version 51-qilg from g4z7jkne-sh0: 3-10/3819/3819
15:34:34.126 L10 []#675 _got_results: got shnum #1 from peerid g4z7jkne
15:34:34.126 L10 []#676 _got_results: got shnum #2 from peerid g4z7jkne
15:34:34.126 L10 []#677 _got_results: got shnum #3 from peerid g4z7jkne
15:34:34.127 L10 []#678 _got_results: got shnum #4 from peerid g4z7jkne
15:34:34.127 L10 []#679 _got_results: got shnum #5 from peerid g4z7jkne
15:34:34.127 L10 []#680 _got_results: got shnum #6 from peerid g4z7jkne
15:34:34.127 L10 []#681 _got_results: got shnum #7 from peerid g4z7jkne
15:34:34.127 L10 []#682 _got_results: got shnum #8 from peerid g4z7jkne
15:34:34.128 L10 []#683 _got_results: got shnum #9 from peerid g4z7jkne
15:34:34.128 L10 []#684 _got_results done
15:34:34.128 L10 []#685 _check_for_done, mode is 'MODE_READ', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False
15:34:34.128 L20 []#686 all queries are retired, no extra peers: done
15:34:34.128 L20 []#687 servermap: 10*seq51-qilg
15:34:34.160 L20 []#688 got channel session request
15:34:34.160 L20 []#689 channel open
15:34:34.196 L20 []#690 Retrieve(26kct): starting
15:34:34.197 L10 []#691 sending sh#0 request to [g4z7jkne]
15:34:34.203 L20 []#692 got data from cache
15:34:34.203 L10 []#693 sending sh#1 request to [g4z7jkne]
15:34:34.203 L20 []#694 got data from cache
15:34:34.203 L10 []#695 sending sh#2 request to [g4z7jkne]
15:34:34.203 L20 []#696 got data from cache
15:34:34.224 L10 []#697 got results (1 shares) from [g4z7jkne]
15:34:34.224 L20 []#698 _got_results: got shnum #0 from peerid g4z7jkne
15:34:34.225 L20 []#699 data valid! len=1273
15:34:34.225 L10 []#700 _check_for_done: running=True, decoding=False
15:34:34.225 L10 []#701 _maybe_send_more_queries, have=1, k=3, outstanding=2
15:34:34.225 L10 []#702 enough queries are in flight, no more are needed
15:34:34.225 L10 []#703 got results (1 shares) from [g4z7jkne]
15:34:34.225 L20 []#704 _got_results: got shnum #1 from peerid g4z7jkne
15:34:34.226 L20 []#705 data valid! len=1273
15:34:34.226 L10 []#706 _check_for_done: running=True, decoding=False
15:34:34.226 L10 []#707 _maybe_send_more_queries, have=2, k=3, outstanding=1
15:34:34.226 L10 []#708 enough queries are in flight, no more are needed
15:34:34.226 L10 []#709 got results (1 shares) from [g4z7jkne]
15:34:34.226 L20 []#710 _got_results: got shnum #2 from peerid g4z7jkne
15:34:34.226 L20 []#711 data valid! len=1273
15:34:34.226 L10 []#712 _check_for_done: running=True, decoding=False
15:34:34.227 L20 []#713 params (3819, 3, 10), we have 3 shares
15:34:34.227 L20 []#714 about to decode, shareids=[0, 1, 2]
15:34:34.227 L20 []#715 decode done, 3 buffers
15:34:34.227 L20 []#716 joined length 3819, datalength 3819
15:34:34.227 L20 []#717 segment len=3819
15:34:34.227 L20 []#718 Retrieve done, success!
15:34:34.259 L20 []#719 asking for subsystem "sftp"
15:34:34.259 L20 []#720 {'sftp': <class twisted.conch.ssh.filetransfer.FileTransferServer at 0x75a7b0>}
15:34:34.259 L10 []#721 <SFTPHandler #3>: .__init__(<SFTPUser #4>)
15:34:34.260 L20 []#722 <SFTPHandler #3>: .gotVersion(3, {})
15:34:34.441 L20 []#723 <SFTPHandler #3>: .realPath('.')
15:34:34.442 L10 []#724 <SFTPHandler #3>: CONVERT '.'
15:34:34.442 L10 []#725 <SFTPHandler #3>: PATH []
15:34:34.561 L20 []#726 disabling diffie-hellman-group-exchange because we cannot find moduli file
15:34:34.901 L20 []#727 kex alg, key alg: diffie-hellman-group1-sha1 ssh-rsa
15:34:34.901 L20 []#728 outgoing: aes128-ctr hmac-md5 none
15:34:34.901 L20 []#729 incoming: aes128-ctr hmac-md5 none
15:34:36.182 L20 []#730 NEW KEYS
15:34:36.182 L20 []#731 starting service ssh-userauth
15:34:37.323 L20 []#732 disabling diffie-hellman-group-exchange because we cannot find moduli file
15:34:37.375 L20 []#733 josip trying auth none
15:34:37.406 L20 []#734 kex alg, key alg: diffie-hellman-group1-sha1 ssh-rsa
15:34:37.406 L20 []#735 outgoing: aes128-ctr hmac-md5 none
15:34:37.406 L20 []#736 incoming: aes128-ctr hmac-md5 none
15:34:38.274 L20 []#737 josip trying auth password
15:34:38.275 L20 []#738 josip authenticated with password
15:34:38.275 L20 []#739 starting service ssh-connection
15:34:38.276 L20 []#740 NEW KEYS
15:34:38.276 L20 []#741 starting service ssh-userauth
15:34:38.332 L20 []#742 got channel session request
15:34:38.333 L20 []#743 channel open
15:34:38.334 L20 []#744 josip trying auth none
15:34:38.397 L20 []#745 executing command "df -P -k /"
15:34:38.397 L20 []#746 <SFTPUser #5>: .execCommand(<twisted.conch.ssh.session.SSHSessionProcessProtocol instance at 0x2352a08>, 'df -P -k /')
15:34:38.398 L20 []#747 Unhandled Error
Traceback (most recent call last):
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/log.py", line 69, in callWithContext
return context.call({ILogContext: newCtx}, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 59, in callWithContext
return self.currentContext().callWithContext(ctx, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 37, in callWithContext
return func(*args,**kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/channel.py", line 137, in requestReceived
return f(data)
--- <exception caught here> ---
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/session.py", line 67, in request_exec
self.session.execCommand(pp, f)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/allmydata_tahoe-1.6.1_r4288-py2.6.egg/allmydata/frontends/sftpd.py", line 1240, in execCommand
raise NotImplementedError
exceptions.NotImplementedError:
FAILURE:
[CopiedFailure instance: Traceback from remote host -- Traceback (most recent call last):
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/log.py", line 69, in callWithContext
return context.call({ILogContext: newCtx}, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 59, in callWithContext
return self.currentContext().callWithContext(ctx, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 37, in callWithContext
return func(*args,**kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/channel.py", line 137, in requestReceived
return f(data)
--- <exception caught here> ---
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/session.py", line 67, in request_exec
self.session.execCommand(pp, f)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/allmydata_tahoe-1.6.1_r4288-py2.6.egg/allmydata/frontends/sftpd.py", line 1240, in execCommand
raise NotImplementedError
exceptions.NotImplementedError:
]
15:34:38.408 L20 []#748 <SFTPUser #5>: .eofReceived()
15:34:38.408 L20 []#749 josip trying auth password
15:34:38.410 L20 []#750 josip authenticated with password
15:34:38.410 L20 []#751 starting service ssh-connection
15:34:38.512 L20 []#752 <SFTPUser #5>: .closed()
15:34:38.512 L20 []#753 connection lost
15:34:38.512 L20 []#754 got channel session request
15:34:38.513 L20 []#755 channel open
15:34:38.576 L20 []#756 executing command "df -P -k /"
15:34:38.576 L20 []#757 <SFTPUser #6>: .execCommand(<twisted.conch.ssh.session.SSHSessionProcessProtocol instance at 0x23395f8>, 'df -P -k /')
15:34:38.577 L20 []#758 Unhandled Error
Traceback (most recent call last):
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/log.py", line 69, in callWithContext
return context.call({ILogContext: newCtx}, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 59, in callWithContext
return self.currentContext().callWithContext(ctx, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 37, in callWithContext
return func(*args,**kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/channel.py", line 137, in requestReceived
return f(data)
--- <exception caught here> ---
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/session.py", line 67, in request_exec
self.session.execCommand(pp, f)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/allmydata_tahoe-1.6.1_r4288-py2.6.egg/allmydata/frontends/sftpd.py", line 1240, in execCommand
raise NotImplementedError
exceptions.NotImplementedError:
FAILURE:
[CopiedFailure instance: Traceback from remote host -- Traceback (most recent call last):
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/log.py", line 69, in callWithContext
return context.call({ILogContext: newCtx}, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 59, in callWithContext
return self.currentContext().callWithContext(ctx, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 37, in callWithContext
return func(*args,**kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/channel.py", line 137, in requestReceived
return f(data)
--- <exception caught here> ---
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/session.py", line 67, in request_exec
self.session.execCommand(pp, f)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/allmydata_tahoe-1.6.1_r4288-py2.6.egg/allmydata/frontends/sftpd.py", line 1240, in execCommand
raise NotImplementedError
exceptions.NotImplementedError:
]
15:34:38.587 L20 []#759 <SFTPUser #6>: .eofReceived()
15:34:38.658 L20 []#760 <SFTPUser #6>: .closed()
15:34:38.658 L20 []#761 connection lost
15:34:39.327 L20 []#762 <SFTPHandler #2>: .makeDirectory('/.Trashes', {})
15:34:39.327 L10 []#763 <SFTPHandler #2>: CONVERT '/.Trashes'
15:34:39.328 L10 []#764 <SFTPHandler #2>: PATH [u'.Trashes']
15:34:39.328 L20 []#765 SharemapUpdater(26kct): starting (MODE_READ)
15:34:39.328 L10 []#766 sending query to [g4z7jkne], readsize=4000
15:34:39.356 L20 []#767 storage: slot_readv 26kctlae6vk6r56p2waaui3jky []
15:34:39.358 L10 []#768 returning shares [0, 1, 2, 3, 4, 5, 6, 7, 8, 9]
15:34:39.377 L10 []#769 got result from [g4z7jkne], 10 shares
15:34:39.377 L10 []#770 _got_results: got shnum #0 from peerid g4z7jkne
15:34:39.378 L20 []#771 found valid version 51-qilg from g4z7jkne-sh0: 3-10/3819/3819
15:34:39.378 L10 []#772 _got_results: got shnum #1 from peerid g4z7jkne
15:34:39.378 L10 []#773 _got_results: got shnum #2 from peerid g4z7jkne
15:34:39.378 L10 []#774 _got_results: got shnum #3 from peerid g4z7jkne
15:34:39.378 L10 []#775 _got_results: got shnum #4 from peerid g4z7jkne
15:34:39.379 L10 []#776 _got_results: got shnum #5 from peerid g4z7jkne
15:34:39.379 L10 []#777 _got_results: got shnum #6 from peerid g4z7jkne
15:34:39.379 L10 []#778 _got_results: got shnum #7 from peerid g4z7jkne
15:34:39.379 L10 []#779 _got_results: got shnum #8 from peerid g4z7jkne
15:34:39.379 L10 []#780 _got_results: got shnum #9 from peerid g4z7jkne
15:34:39.379 L10 []#781 _got_results done
15:34:39.379 L10 []#782 _check_for_done, mode is 'MODE_READ', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False
15:34:39.379 L20 []#783 all queries are retired, no extra peers: done
15:34:39.380 L20 []#784 servermap: 10*seq51-qilg
15:34:39.406 L20 []#785 Retrieve(26kct): starting
15:34:39.407 L10 []#786 sending sh#0 request to [g4z7jkne]
15:34:39.407 L20 []#787 got data from cache
15:34:39.407 L10 []#788 sending sh#1 request to [g4z7jkne]
15:34:39.414 L20 []#789 got data from cache
15:34:39.414 L10 []#790 sending sh#2 request to [g4z7jkne]
15:34:39.414 L20 []#791 got data from cache
15:34:39.453 L10 []#792 got results (1 shares) from [g4z7jkne]
15:34:39.453 L20 []#793 _got_results: got shnum #0 from peerid g4z7jkne
15:34:39.453 L20 []#794 data valid! len=1273
15:34:39.453 L10 []#795 _check_for_done: running=True, decoding=False
15:34:39.453 L10 []#796 _maybe_send_more_queries, have=1, k=3, outstanding=2
15:34:39.453 L10 []#797 enough queries are in flight, no more are needed
15:34:39.454 L10 []#798 got results (1 shares) from [g4z7jkne]
15:34:39.454 L20 []#799 _got_results: got shnum #1 from peerid g4z7jkne
15:34:39.455 L20 []#800 data valid! len=1273
15:34:39.455 L10 []#801 _check_for_done: running=True, decoding=False
15:34:39.455 L10 []#802 _maybe_send_more_queries, have=2, k=3, outstanding=1
15:34:39.455 L10 []#803 enough queries are in flight, no more are needed
15:34:39.455 L10 []#804 got results (1 shares) from [g4z7jkne]
15:34:39.455 L20 []#805 _got_results: got shnum #2 from peerid g4z7jkne
15:34:39.456 L20 []#806 data valid! len=1273
15:34:39.456 L10 []#807 _check_for_done: running=True, decoding=False
15:34:39.456 L20 []#808 params (3819, 3, 10), we have 3 shares
15:34:39.456 L20 []#809 about to decode, shareids=[0, 1, 2]
15:34:39.456 L20 []#810 decode done, 3 buffers
15:34:39.456 L20 []#811 joined length 3819, datalength 3819
15:34:39.456 L20 []#812 segment len=3819
15:34:39.456 L20 []#813 Retrieve done, success!
15:34:39.480 L20 []#814 <SFTPHandler #2>: .openDirectory('/')
15:34:39.480 L10 []#815 <SFTPHandler #2>: CONVERT '/'
15:34:39.480 L10 []#816 <SFTPHandler #2>: PATH []
15:34:39.481 L10 []#817 <SFTPHandler #2>: _got_root( (<DirectoryNode RW-MUT ieyrl>, []) )
15:34:39.481 L20 []#818 SharemapUpdater(26kct): starting (MODE_READ)
15:34:39.481 L10 []#819 sending query to [g4z7jkne], readsize=4000
15:34:39.505 L20 []#820 storage: slot_readv 26kctlae6vk6r56p2waaui3jky []
15:34:39.507 L10 []#821 returning shares [0, 1, 2, 3, 4, 5, 6, 7, 8, 9]
15:34:39.551 L10 []#822 got result from [g4z7jkne], 10 shares
15:34:39.551 L10 []#823 _got_results: got shnum #0 from peerid g4z7jkne
15:34:39.553 L20 []#824 found valid version 51-qilg from g4z7jkne-sh0: 3-10/3819/3819
15:34:39.553 L10 []#825 _got_results: got shnum #1 from peerid g4z7jkne
15:34:39.553 L10 []#826 _got_results: got shnum #2 from peerid g4z7jkne
15:34:39.553 L10 []#827 _got_results: got shnum #3 from peerid g4z7jkne
15:34:39.553 L10 []#828 _got_results: got shnum #4 from peerid g4z7jkne
15:34:39.553 L10 []#829 _got_results: got shnum #5 from peerid g4z7jkne
15:34:39.554 L10 []#830 _got_results: got shnum #6 from peerid g4z7jkne
15:34:39.554 L10 []#831 _got_results: got shnum #7 from peerid g4z7jkne
15:34:39.554 L10 []#832 _got_results: got shnum #8 from peerid g4z7jkne
15:34:39.554 L10 []#833 _got_results: got shnum #9 from peerid g4z7jkne
15:34:39.554 L10 []#834 _got_results done
15:34:39.554 L10 []#835 _check_for_done, mode is 'MODE_READ', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False
15:34:39.554 L20 []#836 all queries are retired, no extra peers: done
15:34:39.554 L20 []#837 servermap: 10*seq51-qilg
15:34:39.570 L20 []#838 Retrieve(26kct): starting
15:34:39.571 L10 []#839 sending sh#0 request to [g4z7jkne]
15:34:39.571 L20 []#840 got data from cache
15:34:39.571 L10 []#841 sending sh#1 request to [g4z7jkne]
15:34:39.571 L20 []#842 got data from cache
15:34:39.574 L10 []#843 sending sh#2 request to [g4z7jkne]
15:34:39.574 L20 []#844 got data from cache
15:34:39.613 L10 []#845 got results (1 shares) from [g4z7jkne]
15:34:39.613 L20 []#846 _got_results: got shnum #0 from peerid g4z7jkne
15:34:39.614 L20 []#847 data valid! len=1273
15:34:39.614 L10 []#848 _check_for_done: running=True, decoding=False
15:34:39.614 L10 []#849 _maybe_send_more_queries, have=1, k=3, outstanding=2
15:34:39.614 L10 []#850 enough queries are in flight, no more are needed
15:34:39.614 L10 []#851 got results (1 shares) from [g4z7jkne]
15:34:39.614 L20 []#852 _got_results: got shnum #1 from peerid g4z7jkne
15:34:39.615 L20 []#853 data valid! len=1273
15:34:39.615 L10 []#854 _check_for_done: running=True, decoding=False
15:34:39.615 L10 []#855 _maybe_send_more_queries, have=2, k=3, outstanding=1
15:34:39.615 L10 []#856 enough queries are in flight, no more are needed
15:34:39.615 L10 []#857 got results (1 shares) from [g4z7jkne]
15:34:39.615 L20 []#858 _got_results: got shnum #2 from peerid g4z7jkne
15:34:39.616 L20 []#859 data valid! len=1273
15:34:39.616 L10 []#860 _check_for_done: running=True, decoding=False
15:34:39.616 L20 []#861 params (3819, 3, 10), we have 3 shares
15:34:39.616 L20 []#862 about to decode, shareids=[0, 1, 2]
15:34:39.616 L20 []#863 decode done, 3 buffers
15:34:39.616 L20 []#864 joined length 3819, datalength 3819
15:34:39.616 L20 []#865 segment len=3819
15:34:39.617 L20 []#866 Retrieve done, success!
15:34:39.961 L20 []#867 <SFTPHandler #2>: .makeDirectory('/.Trashes', {})
15:34:39.961 L10 []#868 <SFTPHandler #2>: CONVERT '/.Trashes'
15:34:39.961 L10 []#869 <SFTPHandler #2>: PATH [u'.Trashes']
15:34:39.961 L20 []#870 SharemapUpdater(26kct): starting (MODE_READ)
15:34:39.962 L10 []#871 sending query to [g4z7jkne], readsize=4000
15:34:40.001 L20 []#872 storage: slot_readv 26kctlae6vk6r56p2waaui3jky []
15:34:40.004 L10 []#873 returning shares [0, 1, 2, 3, 4, 5, 6, 7, 8, 9]
15:34:40.050 L10 []#874 got result from [g4z7jkne], 10 shares
15:34:40.050 L10 []#875 _got_results: got shnum #0 from peerid g4z7jkne
15:34:40.051 L20 []#876 found valid version 51-qilg from g4z7jkne-sh0: 3-10/3819/3819
15:34:40.052 L10 []#877 _got_results: got shnum #1 from peerid g4z7jkne
15:34:40.052 L10 []#878 _got_results: got shnum #2 from peerid g4z7jkne
15:34:40.052 L10 []#879 _got_results: got shnum #3 from peerid g4z7jkne
15:34:40.052 L10 []#880 _got_results: got shnum #4 from peerid g4z7jkne
15:34:40.052 L10 []#881 _got_results: got shnum #5 from peerid g4z7jkne
15:34:40.052 L10 []#882 _got_results: got shnum #6 from peerid g4z7jkne
15:34:40.053 L10 []#883 _got_results: got shnum #7 from peerid g4z7jkne
15:34:40.053 L10 []#884 _got_results: got shnum #8 from peerid g4z7jkne
15:34:40.053 L10 []#885 _got_results: got shnum #9 from peerid g4z7jkne
15:34:40.053 L10 []#886 _got_results done
15:34:40.053 L10 []#887 _check_for_done, mode is 'MODE_READ', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False
15:34:40.053 L20 []#888 all queries are retired, no extra peers: done
15:34:40.054 L20 []#889 servermap: 10*seq51-qilg
15:34:40.068 L20 []#890 Retrieve(26kct): starting
15:34:40.070 L10 []#891 sending sh#0 request to [g4z7jkne]
15:34:40.070 L20 []#892 got data from cache
15:34:40.070 L10 []#893 sending sh#1 request to [g4z7jkne]
15:34:40.073 L20 []#894 got data from cache
15:34:40.073 L10 []#895 sending sh#2 request to [g4z7jkne]
15:34:40.073 L20 []#896 got data from cache
15:34:40.110 L10 []#897 got results (1 shares) from [g4z7jkne]
15:34:40.110 L20 []#898 _got_results: got shnum #0 from peerid g4z7jkne
15:34:40.111 L20 []#899 data valid! len=1273
15:34:40.111 L10 []#900 _check_for_done: running=True, decoding=False
15:34:40.111 L10 []#901 _maybe_send_more_queries, have=1, k=3, outstanding=2
15:34:40.111 L10 []#902 enough queries are in flight, no more are needed
15:34:40.111 L10 []#903 got results (1 shares) from [g4z7jkne]
15:34:40.111 L20 []#904 _got_results: got shnum #1 from peerid g4z7jkne
15:34:40.112 L20 []#905 data valid! len=1273
15:34:40.112 L10 []#906 _check_for_done: running=True, decoding=False
15:34:40.112 L10 []#907 _maybe_send_more_queries, have=2, k=3, outstanding=1
15:34:40.112 L10 []#908 enough queries are in flight, no more are needed
15:34:40.112 L10 []#909 got results (1 shares) from [g4z7jkne]
15:34:40.113 L20 []#910 _got_results: got shnum #2 from peerid g4z7jkne
15:34:40.113 L20 []#911 data valid! len=1273
15:34:40.113 L10 []#912 _check_for_done: running=True, decoding=False
15:34:40.113 L20 []#913 params (3819, 3, 10), we have 3 shares
15:34:40.113 L20 []#914 about to decode, shareids=[0, 1, 2]
15:34:40.113 L20 []#915 decode done, 3 buffers
15:34:40.113 L20 []#916 joined length 3819, datalength 3819
15:34:40.113 L20 []#917 segment len=3819
15:34:40.114 L20 []#918 Retrieve done, success!
15:34:40.251 L20 []#919 <SFTPHandler #2>: .openDirectory('/')
15:34:40.252 L10 []#920 <SFTPHandler #2>: CONVERT '/'
15:34:40.252 L10 []#921 <SFTPHandler #2>: PATH []
15:34:40.252 L10 []#922 <SFTPHandler #2>: _got_root( (<DirectoryNode RW-MUT ieyrl>, []) )
15:34:40.252 L20 []#923 SharemapUpdater(26kct): starting (MODE_READ)
15:34:40.252 L10 []#924 sending query to [g4z7jkne], readsize=4000
15:34:40.292 L20 []#925 storage: slot_readv 26kctlae6vk6r56p2waaui3jky []
15:34:40.294 L10 []#926 returning shares [0, 1, 2, 3, 4, 5, 6, 7, 8, 9]
15:34:40.340 L10 []#927 got result from [g4z7jkne], 10 shares
15:34:40.341 L10 []#928 _got_results: got shnum #0 from peerid g4z7jkne
15:34:40.342 L20 []#929 found valid version 51-qilg from g4z7jkne-sh0: 3-10/3819/3819
15:34:40.342 L10 []#930 _got_results: got shnum #1 from peerid g4z7jkne
15:34:40.342 L10 []#931 _got_results: got shnum #2 from peerid g4z7jkne
15:34:40.343 L10 []#932 _got_results: got shnum #3 from peerid g4z7jkne
15:34:40.343 L10 []#933 _got_results: got shnum #4 from peerid g4z7jkne
15:34:40.343 L10 []#934 _got_results: got shnum #5 from peerid g4z7jkne
15:34:40.343 L10 []#935 _got_results: got shnum #6 from peerid g4z7jkne
15:34:40.343 L10 []#936 _got_results: got shnum #7 from peerid g4z7jkne
15:34:40.343 L10 []#937 _got_results: got shnum #8 from peerid g4z7jkne
15:34:40.344 L10 []#938 _got_results: got shnum #9 from peerid g4z7jkne
15:34:40.344 L10 []#939 _got_results done
15:34:40.344 L10 []#940 _check_for_done, mode is 'MODE_READ', 0 queries outstanding, 0 extra peers available, 0 'must query' peers left, need_privkey=False
15:34:40.344 L20 []#941 all queries are retired, no extra peers: done
15:34:40.344 L20 []#942 servermap: 10*seq51-qilg
15:34:40.394 L20 []#943 Retrieve(26kct): starting
15:34:40.394 L10 []#944 sending sh#0 request to [g4z7jkne]
15:34:40.394 L20 []#945 got data from cache
15:34:40.394 L10 []#946 sending sh#1 request to [g4z7jkne]
15:34:40.394 L20 []#947 got data from cache
15:34:40.394 L10 []#948 sending sh#2 request to [g4z7jkne]
15:34:40.394 L20 []#949 got data from cache
15:34:40.437 L10 []#950 got results (1 shares) from [g4z7jkne]
15:34:40.437 L20 []#951 _got_results: got shnum #0 from peerid g4z7jkne
15:34:40.437 L20 []#952 data valid! len=1273
15:34:40.437 L10 []#953 _check_for_done: running=True, decoding=False
15:34:40.437 L10 []#954 _maybe_send_more_queries, have=1, k=3, outstanding=2
15:34:40.437 L10 []#955 enough queries are in flight, no more are needed
15:34:40.437 L10 []#956 got results (1 shares) from [g4z7jkne]
15:34:40.438 L20 []#957 _got_results: got shnum #1 from peerid g4z7jkne
15:34:40.438 L20 []#958 data valid! len=1273
15:34:40.438 L10 []#959 _check_for_done: running=True, decoding=False
15:34:40.438 L10 []#960 _maybe_send_more_queries, have=2, k=3, outstanding=1
15:34:40.438 L10 []#961 enough queries are in flight, no more are needed
15:34:40.438 L10 []#962 got results (1 shares) from [g4z7jkne]
15:34:40.438 L20 []#963 _got_results: got shnum #2 from peerid g4z7jkne
15:34:40.439 L20 []#964 data valid! len=1273
15:34:40.439 L10 []#965 _check_for_done: running=True, decoding=False
15:34:40.439 L20 []#966 params (3819, 3, 10), we have 3 shares
15:34:40.439 L20 []#967 about to decode, shareids=[0, 1, 2]
15:34:40.439 L20 []#968 decode done, 3 buffers
15:34:40.439 L20 []#969 joined length 3819, datalength 3819
15:34:40.439 L20 []#970 segment len=3819
15:34:40.440 L20 []#971 Retrieve done, success!
15:34:50.269 L20 []#972 disabling diffie-hellman-group-exchange because we cannot find moduli file
15:34:50.282 L20 []#973 kex alg, key alg: diffie-hellman-group1-sha1 ssh-rsa
15:34:50.282 L20 []#974 outgoing: aes128-ctr hmac-md5 none
15:34:50.282 L20 []#975 incoming: aes128-ctr hmac-md5 none
15:34:51.157 L20 []#976 NEW KEYS
15:34:51.157 L20 []#977 starting service ssh-userauth
15:34:51.170 L20 []#978 josip trying auth none
15:34:51.183 L20 []#979 josip trying auth password
15:34:51.184 L20 []#980 josip authenticated with password
15:34:51.184 L20 []#981 starting service ssh-connection
15:34:51.205 L20 []#982 got channel session request
15:34:51.205 L20 []#983 channel open
15:34:51.221 L20 []#984 executing command "df -P -k /"
15:34:51.221 L20 []#985 <SFTPUser #7>: .execCommand(<twisted.conch.ssh.session.SSHSessionProcessProtocol instance at 0x2395cd8>, 'df -P -k /')
15:34:51.222 L20 []#986 Unhandled Error
Traceback (most recent call last):
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/log.py", line 69, in callWithContext
return context.call({ILogContext: newCtx}, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 59, in callWithContext
return self.currentContext().callWithContext(ctx, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 37, in callWithContext
return func(*args,**kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/channel.py", line 137, in requestReceived
return f(data)
--- <exception caught here> ---
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/session.py", line 67, in request_exec
self.session.execCommand(pp, f)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/allmydata_tahoe-1.6.1_r4288-py2.6.egg/allmydata/frontends/sftpd.py", line 1240, in execCommand
raise NotImplementedError
exceptions.NotImplementedError:
FAILURE:
[CopiedFailure instance: Traceback from remote host -- Traceback (most recent call last):
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/log.py", line 69, in callWithContext
return context.call({ILogContext: newCtx}, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 59, in callWithContext
return self.currentContext().callWithContext(ctx, func, *args, **kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/python/context.py", line 37, in callWithContext
return func(*args,**kw)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/channel.py", line 137, in requestReceived
return f(data)
--- <exception caught here> ---
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/Twisted-10.0.0_r28913-py2.6-macosx-10.3-fat.egg/twisted/conch/ssh/session.py", line 67, in request_exec
self.session.execCommand(pp, f)
File "/Library/Frameworks/Python.framework/Versions/2.6/lib/python2.6/site-packages/allmydata_tahoe-1.6.1_r4288-py2.6.egg/allmydata/frontends/sftpd.py", line 1240, in execCommand
raise NotImplementedError
exceptions.NotImplementedError:
]
15:34:51.236 L20 []#987 <SFTPUser #7>: .eofReceived()
15:34:51.299 L20 []#988 <SFTPUser #7>: .closed()
15:34:51.299 L20 []#989 connection lost
15:38:39.412 L20 []#990 sending close 0
15:38:39.523 L20 []#991 remote close
15:38:39.526 L20 []#992 connection lost
15:38:51.820 L20 []#993 connection lost
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment