Ticket #2861: introducer-log.txt

File introducer-log.txt, 15.1 KB (added by str4d, at 2017-01-10T17:56:12Z)

Log of 1.12 introducer receiving connection from 1.12 storage node

Line 
1$ flogtool tail fix-2861-introducer/private/logport.furl
2starting..
3Connecting..
4Connected (to pid 1712)
5Remote Versions:
6 Nevow: 0.14.2
7 OpenSSL: 1.0.2g
8 PyYAML: 3.12
9 Twisted: 16.6.0
10 attrs: 16.3.0
11 cffi: 1.9.1
12 characteristic: 14.3.0
13 constantly: 15.1.0
14 cryptography: 1.7.1
15 enum34: 1.1.6
16 foolscap: 0.12.5
17 idna: 2.1
18 incremental: 16.10.1
19 ipaddress: 1.0.17
20 platform: Linux-Ubuntu_16.04-x86_64-32bit_ELF
21 pyOpenSSL: 16.2.0
22 pyasn1: 0.1.9
23 pyasn1-modules: 0.0.8
24 pycparser: 2.17
25 pycrypto: 2.6.1
26 pycryptopp: 0.7.1.869544967005693312591928092448767568728501330214
27 python: 2.7.12
28 service-identity: 16.0.0
29 setuptools: 32.1.0
30 shutilwhich: 1.1.0
31 simplejson: 3.10.0
32 six: 1.10.0
33 tahoe-lafs: 1.12.0
34 twisted: 16.6.0
35 zfec: 1.4.24
36 zope.interface: unknown
3717:53:37.416 L20 []#704 <Listener at 0xf52f17ac on <txi2p.sam.endpoints.SAMI2PStreamServerEndpoint object at 0xf50875ac> with tub fjfx3ue7xchrhtbvjwe7ojor2qlcltrb> accepting connection from I2PAddress(2szp44znod3pp7yzmmpnomsd4jrv7qfyblttcaaaaaajdtituzdq.b32.i2p)
3817:53:37.416 L20 []#705 Negotiation started
3917:53:37.416 L10 []#706 initServer
4017:53:37.833 L10 []#707 dataReceived(isClient=False,phase=0,options={}): 'G'
4117:53:37.833 L10 []#708 dataReceived(isClient=False,phase=0,options={}): 'E'
4217:53:37.833 L10 []#709 dataReceived(isClient=False,phase=0,options={}): 'T'
4317:53:37.834 L10 []#710 dataReceived(isClient=False,phase=0,options={}): ' '
4417:53:37.834 L10 []#711 dataReceived(isClient=False,phase=0,options={}): '/'
4517:53:37.834 L10 []#712 dataReceived(isClient=False,phase=0,options={}): 'i'
4617:53:37.834 L10 []#713 dataReceived(isClient=False,phase=0,options={}): 'd'
4717:53:37.835 L10 []#714 dataReceived(isClient=False,phase=0,options={}): '/'
4817:53:37.835 L10 []#715 dataReceived(isClient=False,phase=0,options={}): 'f'
4917:53:37.835 L10 []#716 dataReceived(isClient=False,phase=0,options={}): 'j'
5017:53:37.835 L10 []#717 dataReceived(isClient=False,phase=0,options={}): 'f'
5117:53:37.835 L10 []#718 dataReceived(isClient=False,phase=0,options={}): 'x'
5217:53:37.835 L10 []#719 dataReceived(isClient=False,phase=0,options={}): '3'
5317:53:37.841 L10 []#720 dataReceived(isClient=False,phase=0,options={}): 'u'
5417:53:37.841 L10 []#721 dataReceived(isClient=False,phase=0,options={}): 'e'
5517:53:37.841 L10 []#722 dataReceived(isClient=False,phase=0,options={}): '7'
5617:53:37.841 L10 []#723 dataReceived(isClient=False,phase=0,options={}): 'x'
5717:53:37.841 L10 []#724 dataReceived(isClient=False,phase=0,options={}): 'c'
5817:53:37.843 L10 []#725 dataReceived(isClient=False,phase=0,options={}): 'h'
5917:53:37.844 L10 []#726 dataReceived(isClient=False,phase=0,options={}): 'r'
6017:53:37.844 L10 []#727 dataReceived(isClient=False,phase=0,options={}): 'h'
6117:53:37.844 L10 []#728 dataReceived(isClient=False,phase=0,options={}): 't'
6217:53:37.844 L10 []#729 dataReceived(isClient=False,phase=0,options={}): 'b'
6317:53:37.845 L10 []#730 dataReceived(isClient=False,phase=0,options={}): 'v'
6417:53:37.845 L10 []#731 dataReceived(isClient=False,phase=0,options={}): 'j'
6517:53:37.845 L10 []#732 dataReceived(isClient=False,phase=0,options={}): 'w'
6617:53:37.845 L10 []#733 dataReceived(isClient=False,phase=0,options={}): 'e'
6717:53:37.845 L10 []#734 dataReceived(isClient=False,phase=0,options={}): '7'
6817:53:37.845 L10 []#735 dataReceived(isClient=False,phase=0,options={}): 'o'
6917:53:37.845 L10 []#736 dataReceived(isClient=False,phase=0,options={}): 'j'
7017:53:37.845 L10 []#737 dataReceived(isClient=False,phase=0,options={}): 'o'
7117:53:37.846 L10 []#738 dataReceived(isClient=False,phase=0,options={}): 'r'
7217:53:37.846 L10 []#739 dataReceived(isClient=False,phase=0,options={}): '2'
7317:53:37.846 L10 []#740 dataReceived(isClient=False,phase=0,options={}): 'q'
7417:53:37.846 L10 []#741 dataReceived(isClient=False,phase=0,options={}): 'l'
7517:53:37.846 L10 []#742 dataReceived(isClient=False,phase=0,options={}): 'c'
7617:53:37.846 L10 []#743 dataReceived(isClient=False,phase=0,options={}): 'l'
7717:53:37.846 L10 []#744 dataReceived(isClient=False,phase=0,options={}): 't'
7817:53:37.846 L10 []#745 dataReceived(isClient=False,phase=0,options={}): 'r'
7917:53:37.846 L10 []#746 dataReceived(isClient=False,phase=0,options={}): 'b'
8017:53:37.847 L10 []#747 dataReceived(isClient=False,phase=0,options={}): ' '
8117:53:37.847 L10 []#748 dataReceived(isClient=False,phase=0,options={}): 'H'
8217:53:37.847 L10 []#749 dataReceived(isClient=False,phase=0,options={}): 'T'
8317:53:37.847 L10 []#750 dataReceived(isClient=False,phase=0,options={}): 'T'
8417:53:37.847 L10 []#751 dataReceived(isClient=False,phase=0,options={}): 'P'
8517:53:37.847 L10 []#752 dataReceived(isClient=False,phase=0,options={}): '/'
8617:53:37.847 L10 []#753 dataReceived(isClient=False,phase=0,options={}): '1'
8717:53:37.847 L10 []#754 dataReceived(isClient=False,phase=0,options={}): '.'
8817:53:37.847 L10 []#755 dataReceived(isClient=False,phase=0,options={}): '1'
8917:53:37.848 L10 []#756 dataReceived(isClient=False,phase=0,options={}): '\r'
9017:53:37.848 L10 []#757 dataReceived(isClient=False,phase=0,options={}): '\n'
9117:53:37.848 L10 []#758 dataReceived(isClient=False,phase=0,options={}): 'H'
9217:53:37.848 L10 []#759 dataReceived(isClient=False,phase=0,options={}): 'o'
9317:53:37.848 L10 []#760 dataReceived(isClient=False,phase=0,options={}): 's'
9417:53:37.848 L10 []#761 dataReceived(isClient=False,phase=0,options={}): 't'
9517:53:37.848 L10 []#762 dataReceived(isClient=False,phase=0,options={}): ':'
9617:53:37.848 L10 []#763 dataReceived(isClient=False,phase=0,options={}): ' '
9717:53:37.848 L10 []#764 dataReceived(isClient=False,phase=0,options={}): 'x'
9817:53:37.849 L10 []#765 dataReceived(isClient=False,phase=0,options={}): 'f'
9917:53:37.849 L10 []#766 dataReceived(isClient=False,phase=0,options={}): '6'
10017:53:37.849 L10 []#767 dataReceived(isClient=False,phase=0,options={}): 'g'
10117:53:37.849 L10 []#768 dataReceived(isClient=False,phase=0,options={}): 's'
10217:53:37.849 L10 []#769 dataReceived(isClient=False,phase=0,options={}): 'h'
10317:53:37.849 L10 []#770 dataReceived(isClient=False,phase=0,options={}): 'i'
10417:53:37.849 L10 []#771 dataReceived(isClient=False,phase=0,options={}): 'e'
10517:53:37.849 L10 []#772 dataReceived(isClient=False,phase=0,options={}): '2'
10617:53:37.849 L10 []#773 dataReceived(isClient=False,phase=0,options={}): '4'
10717:53:37.850 L10 []#774 dataReceived(isClient=False,phase=0,options={}): 'c'
10817:53:37.850 L10 []#775 dataReceived(isClient=False,phase=0,options={}): 'd'
10917:53:37.850 L10 []#776 dataReceived(isClient=False,phase=0,options={}): 'n'
11017:53:37.850 L10 []#777 dataReceived(isClient=False,phase=0,options={}): 'c'
11117:53:37.850 L10 []#778 dataReceived(isClient=False,phase=0,options={}): '5'
11217:53:37.850 L10 []#779 dataReceived(isClient=False,phase=0,options={}): 'f'
11317:53:37.850 L10 []#780 dataReceived(isClient=False,phase=0,options={}): 'k'
11417:53:37.850 L10 []#781 dataReceived(isClient=False,phase=0,options={}): 'k'
11517:53:37.851 L10 []#782 dataReceived(isClient=False,phase=0,options={}): 'k'
11617:53:37.851 L10 []#783 dataReceived(isClient=False,phase=0,options={}): 'a'
11717:53:37.851 L10 []#784 dataReceived(isClient=False,phase=0,options={}): '5'
11817:53:37.851 L10 []#785 dataReceived(isClient=False,phase=0,options={}): 'p'
11917:53:37.851 L10 []#786 dataReceived(isClient=False,phase=0,options={}): 'e'
12017:53:37.851 L10 []#787 dataReceived(isClient=False,phase=0,options={}): 's'
12117:53:37.851 L10 []#788 dataReceived(isClient=False,phase=0,options={}): 't'
12217:53:37.852 L10 []#789 dataReceived(isClient=False,phase=0,options={}): 'u'
12317:53:37.852 L10 []#790 dataReceived(isClient=False,phase=0,options={}): 'g'
12417:53:37.852 L10 []#791 dataReceived(isClient=False,phase=0,options={}): 'e'
12517:53:37.852 L10 []#792 dataReceived(isClient=False,phase=0,options={}): 'x'
12617:53:37.852 L10 []#793 dataReceived(isClient=False,phase=0,options={}): 'v'
12717:53:37.852 L10 []#794 dataReceived(isClient=False,phase=0,options={}): 'h'
12817:53:37.853 L10 []#795 dataReceived(isClient=False,phase=0,options={}): 'k'
12917:53:37.853 L10 []#796 dataReceived(isClient=False,phase=0,options={}): 't'
13017:53:37.853 L10 []#797 dataReceived(isClient=False,phase=0,options={}): '2'
13117:53:37.853 L10 []#798 dataReceived(isClient=False,phase=0,options={}): 'i'
13217:53:37.853 L10 []#799 dataReceived(isClient=False,phase=0,options={}): 'y'
13317:53:37.853 L10 []#800 dataReceived(isClient=False,phase=0,options={}): 'y'
13417:53:37.853 L10 []#801 dataReceived(isClient=False,phase=0,options={}): 'n'
13517:53:37.853 L10 []#802 dataReceived(isClient=False,phase=0,options={}): '5'
13617:53:37.854 L10 []#803 dataReceived(isClient=False,phase=0,options={}): 'f'
13717:53:37.854 L10 []#804 dataReceived(isClient=False,phase=0,options={}): 's'
13817:53:37.854 L10 []#805 dataReceived(isClient=False,phase=0,options={}): 'g'
13917:53:37.854 L10 []#806 dataReceived(isClient=False,phase=0,options={}): 'r'
14017:53:37.854 L10 []#807 dataReceived(isClient=False,phase=0,options={}): '4'
14117:53:37.854 L10 []#808 dataReceived(isClient=False,phase=0,options={}): 'b'
14217:53:37.859 L10 []#809 dataReceived(isClient=False,phase=0,options={}): '7'
14317:53:37.860 L10 []#810 dataReceived(isClient=False,phase=0,options={}): 'v'
14417:53:37.860 L10 []#811 dataReceived(isClient=False,phase=0,options={}): 'p'
14517:53:37.868 L10 []#812 dataReceived(isClient=False,phase=0,options={}): 'v'
14617:53:37.868 L10 []#813 dataReceived(isClient=False,phase=0,options={}): '6'
14717:53:37.869 L10 []#814 dataReceived(isClient=False,phase=0,options={}): 'q'
14817:53:37.869 L10 []#815 dataReceived(isClient=False,phase=0,options={}): 'a'
14917:53:37.869 L10 []#816 dataReceived(isClient=False,phase=0,options={}): '.'
15017:53:37.869 L10 []#817 dataReceived(isClient=False,phase=0,options={}): 'b'
15117:53:37.869 L10 []#818 dataReceived(isClient=False,phase=0,options={}): '3'
15217:53:37.869 L10 []#819 dataReceived(isClient=False,phase=0,options={}): '2'
15317:53:37.869 L10 []#820 dataReceived(isClient=False,phase=0,options={}): '.'
15417:53:37.869 L10 []#821 dataReceived(isClient=False,phase=0,options={}): 'i'
15517:53:37.870 L10 []#822 dataReceived(isClient=False,phase=0,options={}): '2'
15617:53:37.870 L10 []#823 dataReceived(isClient=False,phase=0,options={}): 'p'
15717:53:37.870 L10 []#824 dataReceived(isClient=False,phase=0,options={}): '\r'
15817:53:37.870 L10 []#825 dataReceived(isClient=False,phase=0,options={}): '\n'
15917:53:37.870 L10 []#826 dataReceived(isClient=False,phase=0,options={}): 'U'
16017:53:37.870 L10 []#827 dataReceived(isClient=False,phase=0,options={}): 'p'
16117:53:37.870 L10 []#828 dataReceived(isClient=False,phase=0,options={}): 'g'
16217:53:37.870 L10 []#829 dataReceived(isClient=False,phase=0,options={}): 'r'
16317:53:37.871 L10 []#830 dataReceived(isClient=False,phase=0,options={}): 'a'
16417:53:37.871 L10 []#831 dataReceived(isClient=False,phase=0,options={}): 'd'
16517:53:37.871 L10 []#832 dataReceived(isClient=False,phase=0,options={}): 'e'
16617:53:37.871 L10 []#833 dataReceived(isClient=False,phase=0,options={}): ':'
16717:53:37.871 L10 []#834 dataReceived(isClient=False,phase=0,options={}): ' '
16817:53:37.871 L10 []#835 dataReceived(isClient=False,phase=0,options={}): 'T'
16917:53:37.871 L10 []#836 dataReceived(isClient=False,phase=0,options={}): 'L'
17017:53:37.871 L10 []#837 dataReceived(isClient=False,phase=0,options={}): 'S'
17117:53:37.872 L10 []#838 dataReceived(isClient=False,phase=0,options={}): '/'
17217:53:37.872 L10 []#839 dataReceived(isClient=False,phase=0,options={}): '1'
17317:53:37.872 L10 []#840 dataReceived(isClient=False,phase=0,options={}): '.'
17417:53:37.872 L10 []#841 dataReceived(isClient=False,phase=0,options={}): '0'
17517:53:37.872 L10 []#842 dataReceived(isClient=False,phase=0,options={}): '\r'
17617:53:37.872 L10 []#843 dataReceived(isClient=False,phase=0,options={}): '\n'
17717:53:37.872 L10 []#844 dataReceived(isClient=False,phase=0,options={}): 'C'
17817:53:37.872 L10 []#845 dataReceived(isClient=False,phase=0,options={}): 'o'
17917:53:37.873 L10 []#846 dataReceived(isClient=False,phase=0,options={}): 'n'
18017:53:37.873 L10 []#847 dataReceived(isClient=False,phase=0,options={}): 'n'
18117:53:37.873 L10 []#848 dataReceived(isClient=False,phase=0,options={}): 'e'
18217:53:37.873 L10 []#849 dataReceived(isClient=False,phase=0,options={}): 'c'
18317:53:37.873 L10 []#850 dataReceived(isClient=False,phase=0,options={}): 't'
18417:53:37.873 L10 []#851 dataReceived(isClient=False,phase=0,options={}): 'i'
18517:53:37.873 L10 []#852 dataReceived(isClient=False,phase=0,options={}): 'o'
18617:53:37.874 L10 []#853 dataReceived(isClient=False,phase=0,options={}): 'n'
18717:53:37.874 L10 []#854 dataReceived(isClient=False,phase=0,options={}): ':'
18817:53:37.874 L10 []#855 dataReceived(isClient=False,phase=0,options={}): ' '
18917:53:37.874 L10 []#856 dataReceived(isClient=False,phase=0,options={}): 'U'
19017:53:37.874 L10 []#857 dataReceived(isClient=False,phase=0,options={}): 'p'
19117:53:37.874 L10 []#858 dataReceived(isClient=False,phase=0,options={}): 'g'
19217:53:37.874 L10 []#859 dataReceived(isClient=False,phase=0,options={}): 'r'
19317:53:37.874 L10 []#860 dataReceived(isClient=False,phase=0,options={}): 'a'
19417:53:37.875 L10 []#861 dataReceived(isClient=False,phase=0,options={}): 'd'
19517:53:37.875 L10 []#862 dataReceived(isClient=False,phase=0,options={}): 'e'
19617:53:37.875 L10 []#863 dataReceived(isClient=False,phase=0,options={}): '\r'
19717:53:37.875 L10 []#864 dataReceived(isClient=False,phase=0,options={}): '\n'
19817:53:37.875 L10 []#865 dataReceived(isClient=False,phase=0,options={}): '\r'
19917:53:37.875 L10 []#866 dataReceived(isClient=False,phase=0,options={}): '\n'
20017:53:37.875 L10 []#867 handlePLAINTEXTServer: targetTubID='fjfx3ue7xchrhtbvjwe7ojor2qlcltrb'
20117:53:37.875 L10 []#868 handlePLAINTEXTServer: wantEncrypted=True
20217:53:37.876 L10 []#869 startENCRYPTED(isClient=False)
20317:53:37.876 L10 []#870 startTLS, client=False
20417:53:37.883 L10 []#871 Negotiate.sendHello (isClient=False): {'my-incarnation': '8c0706d079d8e567', 'my-tub-id': 'fjfx3ue7xchrhtbvjwe7ojor2qlcltrb', 'initial-vocab-table-range': '0 1', 'banana-negotiation-range': '3 3'}
20517:53:38.382 L20 []#872 negotiationFailed
206 FAILURE:
207 [CopiedFailure instance: Traceback from remote host -- Traceback (most recent call last):
208   File "/home/i2p/bin/tahoe-env/local/lib/python2.7/site-packages/twisted/internet/posixbase.py", line 597, in _doReadOrWrite
209     why = selectable.doRead()
210   File "/home/i2p/bin/tahoe-env/local/lib/python2.7/site-packages/twisted/internet/tcp.py", line 208, in doRead
211     return self._dataReceived(data)
212   File "/home/i2p/bin/tahoe-env/local/lib/python2.7/site-packages/twisted/internet/tcp.py", line 214, in _dataReceived
213     rval = self.protocol.dataReceived(data)
214   File "/home/i2p/bin/tahoe-env/local/lib/python2.7/site-packages/twisted/protocols/tls.py", line 415, in dataReceived
215     self._checkHandshakeStatus()
216 --- <exception caught here> ---
217   File "/home/i2p/bin/tahoe-env/local/lib/python2.7/site-packages/twisted/protocols/tls.py", line 335, in _checkHandshakeStatus
218     self._tlsConnection.do_handshake()
219   File "/home/i2p/bin/tahoe-env/local/lib/python2.7/site-packages/OpenSSL/SSL.py", line 1426, in do_handshake
220     self._raise_ssl_error(self._ssl, result)
221   File "/home/i2p/bin/tahoe-env/local/lib/python2.7/site-packages/OpenSSL/SSL.py", line 1174, in _raise_ssl_error
222     _raise_current_error()
223   File "/home/i2p/bin/tahoe-env/local/lib/python2.7/site-packages/OpenSSL/_util.py", line 48, in exception_from_error_queue
224     raise exception_type(errors)
225 OpenSSL.SSL.Error: [('SSL routines', 'SSL23_GET_SERVER_HELLO', 'unknown protocol')]
226 ]