Ticket #1042: log.txt

File log.txt, 9.1 KB (added by davidsarah, at 2010-05-15T03:33:39Z)

Log showing KeyError? exception

Line 
104:09:44.332 L10 []#2627 <GeneralSFTPFile #7>: .__init__(<function check_abort at 0x039C44F0>, 26, '\xbb\xdf\x9d\xac\x8b
2\x91\xceB\x9e(\x9d\x98\x00/Y\x933\xfc0\x82\x84b\x11\xcc\x05N\xc0\x93|\xf1v\xea', parent=<DirectoryNode RW-MUT tunj3>, ch
3ildname=u'mutable.txt', filenode=<MutableFileNode 3bac620 RW kmwbvzdb>, metadata={'ctime': 1273867330.5736511, 'tahoe':
4{'linkmotime': 1273867330.5736511, 'linkcrtime': 1273867330.5736511}, 'mtime': 1273867330.5736511})
504:09:44.581 L10 []#2628 <OverwriteableFileConsumer #7>: .when_reached(0)
604:09:44.591 L10 []#2629 <OverwriteableFileConsumer #7>: already reached 0
704:09:44.674 L20 []#2630 <GeneralSFTPFile #7>: .writeChunk(0, <data of length 13>)
804:09:44.740 L10 []#2631 <OverwriteableFileConsumer #7>: .overwrite(0, 'mutable test\n')
904:09:44.740 L10 []#2632 <EncryptedTemporaryFile #7>: .seek(0, 0)
1004:09:44.760 L10 []#2633 <EncryptedTemporaryFile #7>: .write('mutable test\n')
1104:09:44.785 L20 []#2634 <GeneralSFTPFile #7>: .close()
1204:09:44.855 L10 []#2635 <OverwriteableFileConsumer #7>: .read(0, 13), current_size = 13
1304:09:44.855 L10 []#2636 <OverwriteableFileConsumer #7>: .when_reached(0)
1404:09:44.867 L10 []#2637 <OverwriteableFileConsumer #7>: already reached 0
1504:09:44.867 L10 []#2638 <OverwriteableFileConsumer #7>: self.f = <EncryptedTemporaryFile #7>
1604:09:44.867 L10 []#2639 <EncryptedTemporaryFile #7>: .seek(0, 0)
1704:09:44.867 L10 []#2640 <EncryptedTemporaryFile #7>: .read(13)
1804:09:44.868 L20 []#2641 SharemapUpdater(ocbqg): starting (MODE_WRITE)
1904:09:44.884 L10 []#2642 sending query to [sp26qyqc], readsize=4000
2004:09:44.904 L10 []#2643 sending query to [etnv37de], readsize=4000
2104:09:46.359 L20 []#2644 <SFTPHandler #3>: .setAttrs('/mutable.txt', {'atime': 1273867330, 'mtime': 1273863951})
2204:09:46.372 L20 []#2645 storage: slot_readv ocbqg3y425mckxnzn4kfg4xmla []
2304:09:46.578 L10 []#2646 returning shares [1, 3, 9, 5, 7]
2404:09:46.671 L10 []#2647 got result from [etnv37de], 5 shares
2504:09:46.673 L10 []#2648 _got_results: got shnum #1 from peerid etnv37de
2604:09:46.733 L20 []#2649 got valid privkey from shnum 1 on peerid etnv37de
2704:09:46.782 L20 []#2650  found valid version 1-msaa from etnv37de-sh1: 3-10/12/10
2804:09:46.782 L10 []#2651 _got_results: got shnum #3 from peerid etnv37de
2904:09:46.783 L10 []#2652 _got_results: got shnum #9 from peerid etnv37de
3004:09:46.783 L10 []#2653 _got_results: got shnum #5 from peerid etnv37de
3104:09:46.783 L10 []#2654 _got_results: got shnum #7 from peerid etnv37de
3204:09:46.783 L10 []#2655 _got_results done
3304:09:46.783 L10 []#2656 _check_for_done, mode is 'MODE_WRITE', 1 queries outstanding, 0 extra peers available, 0 'must
34query' peers left, need_privkey=False
3504:09:46.785 L10 []#2657 no boundary yet, ?1
3604:09:46.785 L10 []#2658  there are 1 queries outstanding
3704:09:46.785 L10 []#2659 sending 0 more queries:
3804:09:46.984 L10 []#2660 got result from [sp26qyqc], 5 shares
3904:09:46.984 L10 []#2661 _got_results: got shnum #0 from peerid sp26qyqc
4004:09:46.984 L10 []#2662 _got_results: got shnum #8 from peerid sp26qyqc
4104:09:46.986 L10 []#2663 _got_results: got shnum #2 from peerid sp26qyqc
4204:09:46.986 L10 []#2664 _got_results: got shnum #4 from peerid sp26qyqc
4304:09:46.986 L10 []#2665 _got_results: got shnum #6 from peerid sp26qyqc
4404:09:46.986 L10 []#2666 _got_results done
4504:09:46.986 L10 []#2667 _check_for_done, mode is 'MODE_WRITE', 0 queries outstanding, 0 extra peers available, 0 'must
46query' peers left, need_privkey=False
4704:09:46.986 L20 []#2668 all queries are retired, no extra peers: done
4804:09:46.986 L20 []#2669 servermap: 10*seq1-msaa
4904:09:46.987 L20 []#2670 Publish(ocbqg): starting
5004:09:47.046 L20 []#2671 starting publish, datalen is 13
5104:09:47.066 L10 []#2672 new seqnum will be 2
5204:09:47.105 L20 []#2673 _encrypt_and_encode
5304:09:47.157 L20 []#2674 _generate_shares
5404:09:47.158 L20 []#2675 my new root_hash is eq6a3kzdhohjlvcpjus7naem7spfro2boe4oftr4h6gg2pi64gpq
5504:09:47.450 L10 []#2676 entering loop
5604:09:47.451 L10 []#2677 current goal: before update: , sh0 to [sp26qyqc], sh1 to [etnv37de], sh2 to [sp26qyqc], sh3 to
57[etnv37de], sh4 to [sp26qyqc], sh5 to [etnv37de], sh6 to [sp26qyqc], sh7 to [etnv37de], sh8 to [sp26qyqc], sh9 to [etnv3
587de]
5904:09:47.451 L10 []#2678 we are planning to push new seqnum=#2
6004:09:47.453 L10 []#2679 need to send 10 new shares
6104:09:47.453 L20 []#2680 _send_shares
6204:09:48.246 L23 []#2681 error during loop
63 FAILURE:
64 [CopiedFailure instance: Traceback from remote host -- Traceback (most recent call last):
65   File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\filenode.py", line 458, in _upload
66     d = p.publish(new_contents)
67   File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\publish.py", line 246, in publish
68     d.addCallback(self.loop) # trigger delivery
69   File "c:\Python26\lib\site-packages\twisted-8.2.0-py2.6-win32.egg\twisted\internet\defer.py", line 195, in addCallbac
70k
71     callbackKeywords=kw)
72   File "c:\Python26\lib\site-packages\twisted-8.2.0-py2.6-win32.egg\twisted\internet\defer.py", line 186, in addCallbac
73ks
74     self._runCallbacks()
75 --- <exception caught here> ---
76   File "c:\Python26\lib\site-packages\twisted-8.2.0-py2.6-win32.egg\twisted\internet\defer.py", line 328, in _runCallba
77cks
78     self.result = callback(self.result, *args, **kw)
79   File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\publish.py", line 300, in loop
80     self._send_shares(needed)
81   File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\publish.py", line 599, in _send_shares
82     writev = [(0, self.shares[shnum])]
83 exceptions.KeyError: 6
84 ]
8504:09:48.407 L30 []#2682 Publish done, with failure
86 FAILURE:
87 [CopiedFailure instance: Traceback from remote host -- Traceback (most recent call last):
88   File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\filenode.py", line 458, in _upload
89     d = p.publish(new_contents)
90   File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\publish.py", line 246, in publish
91     d.addCallback(self.loop) # trigger delivery
92   File "c:\Python26\lib\site-packages\twisted-8.2.0-py2.6-win32.egg\twisted\internet\defer.py", line 195, in addCallbac
93k
94     callbackKeywords=kw)
95   File "c:\Python26\lib\site-packages\twisted-8.2.0-py2.6-win32.egg\twisted\internet\defer.py", line 186, in addCallbac
96ks
97     self._runCallbacks()
98 --- <exception caught here> ---
99   File "c:\Python26\lib\site-packages\twisted-8.2.0-py2.6-win32.egg\twisted\internet\defer.py", line 328, in _runCallba
100cks
101     self.result = callback(self.result, *args, **kw)
102   File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\publish.py", line 300, in loop
103     self._send_shares(needed)
104   File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\publish.py", line 599, in _send_shares
105     writev = [(0, self.shares[shnum])]
106 exceptions.KeyError: 6
107 ]
10804:09:50.894 L10 []#2683 ERRBACK <Deferred at 0x3687eb8> <twisted.python.failure.Failure <type 'exceptions.KeyError'>>
10904:09:51.038 L20 []#2684 Unhandled Error
110Traceback (most recent call last):
111  File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\filenode.py", line 458, in _upload
112    d = p.publish(new_contents)
113  File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\publish.py", line 246, in publish
114    d.addCallback(self.loop) # trigger delivery
115  File "c:\Python26\lib\site-packages\twisted-8.2.0-py2.6-win32.egg\twisted\internet\defer.py", line 195, in addCallback
116
117    callbackKeywords=kw)
118  File "c:\Python26\lib\site-packages\twisted-8.2.0-py2.6-win32.egg\twisted\internet\defer.py", line 186, in addCallback
119s
120    self._runCallbacks()
121--- <exception caught here> ---
122  File "c:\Python26\lib\site-packages\twisted-8.2.0-py2.6-win32.egg\twisted\internet\defer.py", line 328, in _runCallbac
123ks
124    self.result = callback(self.result, *args, **kw)
125  File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\publish.py", line 300, in loop
126    self._send_shares(needed)
127  File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\publish.py", line 599, in _send_shares
128    writev = [(0, self.shares[shnum])]
129exceptions.KeyError: 6
130
131 FAILURE:
132 [CopiedFailure instance: Traceback from remote host -- Traceback (most recent call last):
133   File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\filenode.py", line 458, in _upload
134     d = p.publish(new_contents)
135   File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\publish.py", line 246, in publish
136     d.addCallback(self.loop) # trigger delivery
137   File "c:\Python26\lib\site-packages\twisted-8.2.0-py2.6-win32.egg\twisted\internet\defer.py", line 195, in addCallbac
138k
139     callbackKeywords=kw)
140   File "c:\Python26\lib\site-packages\twisted-8.2.0-py2.6-win32.egg\twisted\internet\defer.py", line 186, in addCallbac
141ks
142     self._runCallbacks()
143 --- <exception caught here> ---
144   File "c:\Python26\lib\site-packages\twisted-8.2.0-py2.6-win32.egg\twisted\internet\defer.py", line 328, in _runCallba
145cks
146     self.result = callback(self.result, *args, **kw)
147   File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\publish.py", line 300, in loop
148     self._send_shares(needed)
149   File "d:\tahoe\1.6.1\tahoe-lafs\src\allmydata\mutable\publish.py", line 599, in _send_shares
150     writev = [(0, self.shares[shnum])]
151 exceptions.KeyError: 6
152 ]