Application versions (embedded in logfile): Nevow: 0.9.33-r17222 Twisted: 8.1.0 allmydata-tahoe: 1.5.0-r4174 argparse: 0.9.1 foolscap: 0.4.2 platform: Linux-debian_5.0.3-i686-32bit pyOpenSSL: 0.7 pycryptopp: 0.5.15 pysqlite: 2.3.2 python: 2.5.2 pyutil: 1.3.34 setuptools: 0.6c12dev simplejson: 1.9.2 sqlite: 3.5.9 twisted: 8.1.0 zbase32: 1.1.1 zfec: 1.4.5 zope.interface: 3.3.1 PID: 31071 s3iqpt6a#1021377 20:08:19.130: web: 127.0.0.1 PUT /uri/[CENSORED].. 200 55 s3iqpt6a#1021378 20:08:19.325: CHKUploader starting s3iqpt6a#1021379 20:08:19.325: starting upload of s3iqpt6a#1021380 20:08:19.325: creating Encoder s3iqpt6a#1021381 20:08:19.325: file size: 703 s3iqpt6a#1021382 20:08:19.325: my encoding parameters: (2, 4, 5, 704) s3iqpt6a#1021383 20:08:19.325: got encoding parameters: 2/4/5 704 s3iqpt6a#1021384 20:08:19.325: now setting up codec s3iqpt6a#1021385 20:08:19.325: using storage index dk3zi s3iqpt6a#1021386 20:08:19.326: starting s3iqpt6a#1021387 20:08:19.768: response from peer rkkkx6sk: alreadygot=(0,), allocated=() s3iqpt6a#1021388 20:08:19.796: response from peer jfkoivg7: alreadygot=(1,), allocated=() s3iqpt6a#1021389 20:08:19.835: response from peer tkxzeoh2: alreadygot=(2,), allocated=() s3iqpt6a#1021390 20:08:20.059: response from peer wpxpieqx: alreadygot=(3,), allocated=() s3iqpt6a#1021391 20:08:20.077: response from peer b5lefkbu: alreadygot=(4,), allocated=() s3iqpt6a#1021392 20:08:20.077: peer selection successful for : placed all 5 shares, sent 5 queries to 5 peers, 0 queries placed some shares, 5 placed none, got 0 errors s3iqpt6a#1021393 20:08:20.077: _send_shares, used_peers is set([]) s3iqpt6a#1021394 20:08:20.077: starting s3iqpt6a#1021395 20:08:20.084: starting shareholders s3iqpt6a#1021396 20:08:20.097: read_encrypted handling 352B-sized chunk s3iqpt6a#1021397 20:08:20.104: read_encrypted handling 351B-sized chunk s3iqpt6a#1021398 20:08:20.104: send_segment(0) s3iqpt6a#1021399 20:08:20.105: uploaded 704 / 704 bytes (100%) of your file. s3iqpt6a#1021400 20:08:20.115: sending crypttext hash tree s3iqpt6a#1021401 20:08:20.115: sending block hash trees s3iqpt6a#1021402 20:08:20.116: sending all share hash trees s3iqpt6a#1021403 20:08:20.116: sending uri_extension s3iqpt6a#1021404 20:08:20.117: uri_extension_data is {'num_segments': 1, 'crypttext_hash': 'vja7oebqthhzu4uywfgcia2ekj4rqbatd62o6momocoeebskpsyq', 'tail_codec_params': '704-2-5', 'share_root_hash': 'nff64yd3t6jazwo7omu3nfemficjr2yp5a4zzgsnag57v6yekfsa', 'crypttext_root_hash': '42d3esyeeupugqizdcwqnl3fvscgqy7xvelyuh6d7zq3uxbhwvmq', 'codec_params': '704-2-5', 'codec_name': 'crs', 'total_shares': 5, 'needed_shares': 2, 'segment_size': 704, 'size': 703} s3iqpt6a#1021405 20:08:20.117: closing shareholders s3iqpt6a#1021406 20:08:20.117: upload done s3iqpt6a#1021407 20:08:20.119: web: 127.0.0.1 PUT /uri/[CENSORED].. 200 95 s3iqpt6a#1021408 20:08:20.350: CHKUploader starting s3iqpt6a#1021409 20:08:20.350: starting upload of s3iqpt6a#1021410 20:08:20.350: creating Encoder s3iqpt6a#1021411 20:08:20.351: file size: 1998 s3iqpt6a#1021412 20:08:20.351: my encoding parameters: (2, 4, 5, 1998) s3iqpt6a#1021413 20:08:20.351: got encoding parameters: 2/4/5 1998 s3iqpt6a#1021414 20:08:20.351: now setting up codec s3iqpt6a#1021415 20:08:20.351: using storage index dssph s3iqpt6a#1021416 20:08:20.351: starting s3iqpt6a#1021417 20:08:20.396: response from peer rkkkx6sk: alreadygot=(0,), allocated=() s3iqpt6a#1021418 20:08:20.411: response from peer b5lefkbu: alreadygot=(1,), allocated=() s3iqpt6a#1021419 20:08:20.424: response from peer jfkoivg7: alreadygot=(2,), allocated=() s3iqpt6a#1021420 20:08:21.086: response from peer tkxzeoh2: alreadygot=(3,), allocated=() s3iqpt6a#1021421 20:08:21.103: response from peer wpxpieqx: alreadygot=(4,), allocated=() s3iqpt6a#1021422 20:08:21.103: peer selection successful for : placed all 5 shares, sent 5 queries to 5 peers, 0 queries placed some shares, 5 placed none, got 0 errors s3iqpt6a#1021423 20:08:21.103: _send_shares, used_peers is set([]) s3iqpt6a#1021424 20:08:21.103: starting s3iqpt6a#1021425 20:08:21.111: starting shareholders s3iqpt6a#1021426 20:08:21.123: read_encrypted handling 999B-sized chunk s3iqpt6a#1021427 20:08:21.146: read_encrypted handling 999B-sized chunk s3iqpt6a#1021428 20:08:21.146: closed hash [0]: 1998B s3iqpt6a#1021429 20:08:21.146: plaintext leaf hash [0] is ozjpel2bo5ewdrx64fvwrzpc456rie76bxizrfnnscoezwd5ynga s3iqpt6a#1021430 20:08:21.146: send_segment(0) s3iqpt6a#1021431 20:08:21.147: uploaded 1998 / 1998 bytes (100%) of your file. s3iqpt6a#1021432 20:08:21.162: sending crypttext hash tree s3iqpt6a#1021433 20:08:21.162: sending block hash trees s3iqpt6a#1021434 20:08:21.162: sending all share hash trees s3iqpt6a#1021435 20:08:21.163: sending uri_extension s3iqpt6a#1021436 20:08:21.164: uri_extension_data is {'num_segments': 1, 'crypttext_hash': 'aowmzidmuqvj2x467ahjsg7n4fcfa4ska64gcu5nd7tsdonowlza', 'tail_codec_params': '1998-2-5', 'share_root_hash': 'c5cxxj2vui3mbwobxr7a2phryujq7xerjkzh2mgpu44nbwym3luq', 'crypttext_root_hash': '7zlidgduvrhgon4752znt7ynkb2cteo4naqk7rcccj5s2aj2i4bq', 'codec_params': '1998-2-5', 'codec_name': 'crs', 'total_shares': 5, 'needed_shares': 2, 'segment_size': 1998, 'size': 1998} s3iqpt6a#1021437 20:08:21.164: closing shareholders s3iqpt6a#1021438 20:08:21.164: upload done s3iqpt6a#1021439 20:08:21.166: web: 127.0.0.1 PUT /uri/[CENSORED].. 200 96 s3iqpt6a#1021440 20:08:21.168: connection to wpxp lost s3iqpt6a#1021441 20:08:21.168: Stopping factory s3iqpt6a#1021442 20:08:21.193: an outbound callRemote (that we [s3iq] sent to someone else [wpxp]) failed on the far end s3iqpt6a#1021443 20:08:21.193: reqID=18418, rref=, methname=RIStorageServer.tahoe.allmydata.com.add_lease s3iqpt6a#1021444 20:08:21.193: the REMOTE failure was: FAILURE: [CopiedFailure instance: Traceback from remote host -- Traceback (most recent call last): Failure: foolscap.ipb.DeadReferenceError: Connection was lost ] s3iqpt6a#1021445 20:08:21.193: {'level': 30, 'format': 'local error in add_lease to [%(peerid)s]: %(f_value)s', 'failure': >, 'f_value': 'Connection was lost', 'peerid': 'wpxpieqx', 'umid': 'hEGuQg'} s3iqpt6a#1021446 20:08:21.196: an outbound callRemote (that we [s3iq] sent to someone else [wpxp]) failed on the far end s3iqpt6a#1021447 20:08:21.196: reqID=18419, rref=, methname=RIStorageServer.tahoe.allmydata.com.get_buckets s3iqpt6a#1021448 20:08:21.196: the REMOTE failure was: FAILURE: [CopiedFailure instance: Traceback from remote host -- Traceback (most recent call last): Failure: foolscap.ipb.DeadReferenceError: Connection was lost ] s3iqpt6a#1021449 20:08:21.196: (kzxdquqg6uvk): failure from server on 'get_buckets' the REMOTE failure was: FAILURE: [CopiedFailure instance: Traceback from remote host -- Traceback (most recent call last): Failure: foolscap.ipb.DeadReferenceError: Connection was lost ] s3iqpt6a#1021450 20:08:21.196: lost connection to wpxpieqx s3iqpt6a#1021451 20:08:21.197: Unhandled error in Deferred: s3iqpt6a#1021452 20:08:21.198: Unhandled Error Traceback (most recent call last): File "/home/francois/dev/tahoe/support/lib/python2.5/site-packages/foolscap-0.4.2-py2.5.egg/foolscap/eventual.py", line 26, in _turn cb(*args, **kwargs) File "/home/francois/dev/tahoe/support/lib/python2.5/site-packages/foolscap-0.4.2-py2.5.egg/foolscap/call.py", line 90, in fail self.deferred.errback(why) File "/usr/lib/python2.5/site-packages/twisted/internet/defer.py", line 269, in errback self._startRunCallbacks(fail) File "/usr/lib/python2.5/site-packages/twisted/internet/defer.py", line 312, in _startRunCallbacks self._runCallbacks() --- --- File "/usr/lib/python2.5/site-packages/twisted/internet/defer.py", line 328, in _runCallbacks self.result = callback(self.result, *args, **kw) File "/home/francois/dev/tahoe/src/allmydata/immutable/checker.py", line 123, in _add_lease_failed level=log.WEIRD, umid="hEGuQg") File "/home/francois/dev/tahoe/src/allmydata/util/log.py", line 31, in err return log.err(*args, **kwargs) File "/home/francois/dev/tahoe/support/lib/python2.5/site-packages/foolscap-0.4.2-py2.5.egg/foolscap/logging/log.py", line 218, in err _stuff = failure.Failure() File "/usr/lib/python2.5/site-packages/twisted/python/failure.py", line 174, in __init__ raise NoCurrentExceptionError() twisted.python.failure.NoCurrentExceptionError: FAILURE: [CopiedFailure instance: Traceback from remote host -- Traceback (most recent call last): File "/home/francois/dev/tahoe/support/lib/python2.5/site-packages/foolscap-0.4.2-py2.5.egg/foolscap/eventual.py", line 26, in _turn cb(*args, **kwargs) File "/home/francois/dev/tahoe/support/lib/python2.5/site-packages/foolscap-0.4.2-py2.5.egg/foolscap/call.py", line 90, in fail self.deferred.errback(why) File "/usr/lib/python2.5/site-packages/twisted/internet/defer.py", line 269, in errback self._startRunCallbacks(fail) File "/usr/lib/python2.5/site-packages/twisted/internet/defer.py", line 312, in _startRunCallbacks self._runCallbacks() --- --- File "/usr/lib/python2.5/site-packages/twisted/internet/defer.py", line 328, in _runCallbacks self.result = callback(self.result, *args, **kw) File "/home/francois/dev/tahoe/src/allmydata/immutable/checker.py", line 123, in _add_lease_failed level=log.WEIRD, umid="hEGuQg") File "/home/francois/dev/tahoe/src/allmydata/util/log.py", line 31, in err return log.err(*args, **kwargs) File "/home/francois/dev/tahoe/support/lib/python2.5/site-packages/foolscap-0.4.2-py2.5.egg/foolscap/logging/log.py", line 218, in err _stuff = failure.Failure() File "/usr/lib/python2.5/site-packages/twisted/python/failure.py", line 174, in __init__ raise NoCurrentExceptionError() twisted.python.failure.NoCurrentExceptionError: ] s3iqpt6a#1021453 20:08:21.205: (kzxdq): starting repair s3iqpt6a#1021454 20:08:21.205: CHKUploader starting s3iqpt6a#1021455 20:08:21.205: starting upload of s3iqpt6a#1021456 20:08:21.205: creating Encoder s3iqpt6a#1021457 20:08:21.205: (kzxdquqg6uvk): starting download s3iqpt6a#1021458 20:08:21.205: sending DYHB to [b5lefkbu] s3iqpt6a#1021459 20:08:21.206: sending DYHB to [jfkoivg7] s3iqpt6a#1021460 20:08:21.207: sending DYHB to [rkkkx6sk] s3iqpt6a#1021461 20:08:21.208: sending DYHB to [tkxzeoh2] s3iqpt6a#1021462 20:08:21.233: got results from [b5lefkbu]: shnums [1] s3iqpt6a#1021463 20:08:21.237: got results from [jfkoivg7]: shnums [2] s3iqpt6a#1021464 20:08:21.260: got results from [rkkkx6sk]: shnums [3] s3iqpt6a#1021465 20:08:21.263: got results from [tkxzeoh2]: shnums [4] s3iqpt6a#1021466 20:08:21.381: (kzxdquqg6uvk): downloading seg#0 of 1 (0%) s3iqpt6a#1021467 20:08:21.381: (kzxdquqg6uvk): starting segment 0 s3iqpt6a#1021468 20:08:21.382: (-1): get_block(segnum=0) s3iqpt6a#1021469 20:08:21.383: (-2): get_block(segnum=0) s3iqpt6a#1021470 20:08:21.408: (-1): got block s3iqpt6a#1021471 20:08:21.418: (URI:CHK-Verifier:dssphylnsjav55lgpjm3i6g4ta:kgv3chjgnsvysx2e76nszaqnv7stav2ccwxgfx4nkx4uaclhnsyq:2:5:1998): starting s3iqpt6a#1021472 20:08:21.419: (URI:CHK-Verifier:dk3zitpvgm6woeay47l5r6h7gy:pht5cin6mdeobnp7g2lyoxxe53tuh66hftfin6tzu4ilojwwx6hq:2:5:703): starting s3iqpt6a#1021473 20:08:21.421: CHKUploader starting s3iqpt6a#1021474 20:08:21.421: starting upload of s3iqpt6a#1021475 20:08:21.421: creating Encoder s3iqpt6a#1021476 20:08:21.422: file size: 876 s3iqpt6a#1021477 20:08:21.422: my encoding parameters: (2, 4, 5, 876) s3iqpt6a#1021478 20:08:21.422: got encoding parameters: 2/4/5 876 s3iqpt6a#1021479 20:08:21.422: now setting up codec s3iqpt6a#1021480 20:08:21.422: using storage index ohhtd s3iqpt6a#1021481 20:08:21.422: starting s3iqpt6a#1021482 20:08:21.446: (-2): got block s3iqpt6a#1021483 20:08:21.446: (kzxdquqg6uvk): download done s3iqpt6a#1021484 20:08:21.469: file size: 213 s3iqpt6a#1021485 20:08:21.469: got encoding parameters: 2/5/5 214 s3iqpt6a#1021486 20:08:21.469: now setting up codec s3iqpt6a#1021487 20:08:21.469: using storage index kzxdq s3iqpt6a#1021488 20:08:21.470: starting s3iqpt6a#1021489 20:08:21.477: response from peer rkkkx6sk: alreadygot=(), allocated=(0,) s3iqpt6a#1021490 20:08:21.523: response from peer b5lefkbu: alreadygot=(1,), allocated=(0,) s3iqpt6a#1021491 20:08:21.527: response from peer b5lefkbu: alreadygot=(), allocated=(1,) s3iqpt6a#1021492 20:08:21.571: response from peer jfkoivg7: alreadygot=(2,), allocated=() s3iqpt6a#1021493 20:08:21.574: response from peer jfkoivg7: alreadygot=(), allocated=(2,) s3iqpt6a#1021494 20:08:21.587: response from peer rkkkx6sk: alreadygot=(3,), allocated=() s3iqpt6a#1021495 20:08:21.604: response from peer tkxzeoh2: alreadygot=(), allocated=(3,) s3iqpt6a#1021496 20:08:21.604: starting second pass s3iqpt6a#1021497 20:08:21.610: response from peer tkxzeoh2: alreadygot=(4,), allocated=() s3iqpt6a#1021498 20:08:21.610: peer selection successful for : placed all 5 shares, sent 4 queries to 4 peers, 1 queries placed some shares, 3 placed none, got 0 errors s3iqpt6a#1021499 20:08:21.611: _send_shares, used_peers is set([]) s3iqpt6a#1021500 20:08:21.611: starting s3iqpt6a#1021501 20:08:21.622: starting shareholders s3iqpt6a#1021502 20:08:21.624: send_segment(0) s3iqpt6a#1021503 20:08:21.624: put_block to s3iqpt6a#1021504 20:08:21.625: put_block done s3iqpt6a#1021505 20:08:21.625: uploaded 214 / 214 bytes (100%) of your file. s3iqpt6a#1021506 20:08:21.627: response from peer rkkkx6sk: alreadygot=(), allocated=(4,) s3iqpt6a#1021507 20:08:21.627: peer selection successful for : placed all 5 shares, sent 5 queries to 4 peers, 5 queries placed some shares, 0 placed none, got 0 errors s3iqpt6a#1021508 20:08:21.628: _send_shares, used_peers is set([, , , ]) s3iqpt6a#1021509 20:08:21.628: starting s3iqpt6a#1021510 20:08:21.648: sending crypttext hash tree s3iqpt6a#1021511 20:08:21.649: sending block hash trees s3iqpt6a#1021512 20:08:21.650: sending all share hash trees s3iqpt6a#1021513 20:08:21.652: sending uri_extension s3iqpt6a#1021514 20:08:21.652: uri_extension_data is {'num_segments': 1, 'crypttext_hash': 'qbi76a6smo4lwc4lzzguqvt3v7pwuzvg3velcetvac2k4zs24g3q', 'tail_codec_params': '214-2-5', 'share_root_hash': '2lcpwbnevfhrw56od2b2fhv3mdwsp5nblbvifzoajnlehvojxa6q', 'crypttext_root_hash': 'xhi3omqreclkz3rkesohd5hsyvix237jy2pactb5eymdfr2g7kea', 'codec_params': '214-2-5', 'codec_name': 'crs', 'total_shares': 5, 'needed_shares': 2, 'segment_size': 214, 'size': 213} s3iqpt6a#1021515 20:08:21.653: closing shareholders s3iqpt6a#1021516 20:08:21.654: starting shareholders s3iqpt6a#1021517 20:08:21.682: read_encrypted handling 438B-sized chunk s3iqpt6a#1021518 20:08:21.684: upload done s3iqpt6a#1021519 20:08:21.719: read_encrypted handling 438B-sized chunk s3iqpt6a#1021520 20:08:21.719: closed hash [0]: 876B s3iqpt6a#1021521 20:08:21.719: plaintext leaf hash [0] is penqgfgjnhpkrtrlc4yicnc7626bbyhviuiyvziwcysmfr5u7eda s3iqpt6a#1021522 20:08:21.719: send_segment(0) s3iqpt6a#1021523 20:08:21.720: put_block to s3iqpt6a#1021524 20:08:21.720: put_block done s3iqpt6a#1021525 20:08:21.721: put_block to s3iqpt6a#1021526 20:08:21.721: put_block done s3iqpt6a#1021527 20:08:21.722: put_block to s3iqpt6a#1021528 20:08:21.722: put_block done s3iqpt6a#1021529 20:08:21.723: put_block to s3iqpt6a#1021530 20:08:21.723: put_block done s3iqpt6a#1021531 20:08:21.723: put_block to s3iqpt6a#1021532 20:08:21.724: put_block done s3iqpt6a#1021533 20:08:21.725: uploaded 876 / 876 bytes (100%) of your file. s3iqpt6a#1021534 20:08:21.750: sending crypttext hash tree s3iqpt6a#1021535 20:08:21.754: sending block hash trees s3iqpt6a#1021536 20:08:21.758: sending all share hash trees s3iqpt6a#1021537 20:08:21.763: sending uri_extension s3iqpt6a#1021538 20:08:21.764: uri_extension_data is {'num_segments': 1, 'crypttext_hash': 'z6llrl5zoa5tmhnqz3klupc2ucy54k7sx7r7etyvrihydeyeslxq', 'tail_codec_params': '876-2-5', 'share_root_hash': 'thyjahoaomnmky4d2sbnwieothyqnbpr7itth7njiamm2nlfi56a', 'crypttext_root_hash': 'vx3276oviqpqrjz6o5rzhfpxyil3hbfj5e6hp5ee3qwb3zat7mdq', 'codec_params': '876-2-5', 'codec_name': 'crs', 'total_shares': 5, 'needed_shares': 2, 'segment_size': 876, 'size': 876} s3iqpt6a#1021539 20:08:21.768: closing shareholders s3iqpt6a#1021540 20:08:21.814: (k4adt): starting repair s3iqpt6a#1021541 20:08:21.814: CHKUploader starting s3iqpt6a#1021542 20:08:21.814: starting upload of s3iqpt6a#1021543 20:08:21.814: creating Encoder s3iqpt6a#1021544 20:08:21.814: (k4adtnc2r4rv): starting download s3iqpt6a#1021545 20:08:21.814: sending DYHB to [rkkkx6sk] s3iqpt6a#1021546 20:08:21.815: sending DYHB to [b5lefkbu] s3iqpt6a#1021547 20:08:21.816: sending DYHB to [tkxzeoh2] s3iqpt6a#1021548 20:08:21.817: sending DYHB to [jfkoivg7] s3iqpt6a#1021549 20:08:21.841: got results from [b5lefkbu]: shnums [1] s3iqpt6a#1021550 20:08:21.848: got results from [jfkoivg7]: shnums [4] s3iqpt6a#1021551 20:08:21.850: upload done s3iqpt6a#1021552 20:08:21.853: (URI:CHK-Verifier:ohhtdyu5gl2bbktjzc44orbi5y:kortxstcv6gdju55aq55ocuyttwrzcvr5nhtqllf4cciyn73cwdq:2:5:876): starting s3iqpt6a#1021553 20:08:21.854: web: 127.0.0.1 POST /uri/[CENSORED]..?t=mkdir-immutable 200 100