foolscap negotiation fails with "unknown TubID"

[Lukas Pirl]

Hello again,

does anyone has any ideas on this? I am willing to provide more
information if needed.

Best,

Lukas

On 12/28/2014 05:29 PM, Lukas Pirl wrote:
> Hi Tahoe people,
> 
> I have an introducer and a client, both running on a publicly accessible
> server (S). All storage servers are supposed to connect to S.
> 
> The problem is, that one of my storage servers cannot connect to the
> grid. It is the only one running on FreeBSD, the others are running on
> Debian. All instances are installed via PIP.
> 
> It fails to negotiate a connection, telling me something about an
> unknown TubID (see error pasted below).
> 
> How can I debug/solve this?
> 
> Thanks in advance,
> 
> Lukas
> 
> ––––––––––––––––––– BEGIN log ––––––––––––––––––––––––––
> 16:57:59.156 L20 []#188 Negotiation started
> 16:57:59.156 L10 []#189 initServer
> 16:57:59.199 L10 []#190 dataReceived(isClient=False,phase=0,options={}):
> 'GET /id/eruo3ibyroMODIFIEDvkheeidhbm63vn HTTP/1.1\r\nHost:
> localhost\r\nUpgrade: TLS/1.0\r\nConnection: Upgrade\r\n\r\n'
> 16:57:59.200 L10 []#191 handlePLAINTEXTServer:
> targetTubID='eruo3ibyroMODIFIEDvkheeidhbm63vn'
> 16:57:59.200 L10 []#192 handlePLAINTEXTServer: wantEncrypted=True
> 16:57:59.201 L20 []#193 negotiationFailed
>  FAILURE:
>  [CopiedFailure instance: Traceback from remote host -- Traceback (most
> recent call last):
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/twisted/python/context.py",
> line 81, in callWithContext
>      return func(*args,**kw)
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/twisted/internet/posixbase.py",
> line 614, in _doReadOrWrite
>      why = selectable.doRead()
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/twisted/internet/tcp.py", line
> 214, in doRead
>      return self._dataReceived(data)
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/twisted/internet/tcp.py", line
> 220, in _dataReceived
>      rval = self.protocol.dataReceived(data)
>  --- <exception caught here> ---
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/foolscap/negotiate.py",
> line 404, in dataReceived
>      self.handlePLAINTEXTServer(header)
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/foolscap/negotiate.py",
> line 532, in handlePLAINTEXTServer
>      raise NegotiationError("unknown TubID %s" % targetTubID)
>  foolscap.tokens.NegotiationError: unknown TubID
> eruo3ibyroMODIFIEDvkheeidhbm63vn
>  ]
> 16:57:59.201 L10 []#194 dataReceived(isClient=True,phase=0,options={}):
> 'HTTP/1.1 500 Internal Server Error: unknown TubID
> eruo3ibyroMODIFIEDvkheeidhbm63vn\r\n\r\n'
> 16:57:59.201 L10 []#195 handlePLAINTEXTClient: header='HTTP/1.1 500
> Internal Server Error: unknown TubID eruo3ibyroMODIFIEDvkheeidhbm63vn'
> 16:57:59.202 L23 []#196 negotiation had internal error:
>  FAILURE:
>  [CopiedFailure instance: Traceback from remote host -- Traceback (most
> recent call last):
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/twisted/python/context.py",
> line 81, in callWithContext
>      return func(*args,**kw)
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/twisted/internet/posixbase.py",
> line 614, in _doReadOrWrite
>      why = selectable.doRead()
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/twisted/internet/tcp.py", line
> 214, in doRead
>      return self._dataReceived(data)
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/twisted/internet/tcp.py", line
> 220, in _dataReceived
>      rval = self.protocol.dataReceived(data)
>  --- <exception caught here> ---
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/foolscap/negotiate.py",
> line 402, in dataReceived
>      self.handlePLAINTEXTClient(header)
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/foolscap/negotiate.py",
> line 571, in handlePLAINTEXTClient
>      % lines[0])
>  foolscap.tokens.BananaError: BananaError: ("not right, got 'HTTP/1.1
> 500 Internal Server Error: unknown TubID
> eruo3ibyroMODIFIEDvkheeidhbm63vn', expected 101 Switching Protocols",)
>  ]
> 16:57:59.203 L20 []#197 negotiationFailed
>  FAILURE:
>  [CopiedFailure instance: Traceback from remote host -- Traceback (most
> recent call last):
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/twisted/python/context.py",
> line 81, in callWithContext
>      return func(*args,**kw)
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/twisted/internet/posixbase.py",
> line 614, in _doReadOrWrite
>      why = selectable.doRead()
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/twisted/internet/tcp.py", line
> 214, in doRead
>      return self._dataReceived(data)
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/twisted/internet/tcp.py", line
> 220, in _dataReceived
>      rval = self.protocol.dataReceived(data)
>  --- <exception caught here> ---
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/foolscap/negotiate.py",
> line 402, in dataReceived
>      self.handlePLAINTEXTClient(header)
>    File
> "/home/tahoe/.local/lib/python2.7/site-packages/foolscap/negotiate.py",
> line 571, in handlePLAINTEXTClient
>      % lines[0])
>  foolscap.tokens.BananaError: BananaError: ("not right, got 'HTTP/1.1
> 500 Internal Server Error: unknown TubID
> eruo3ibyroMODIFIEDvkheeidhbm63vn', expected 101 Switching Protocols",)
>  ]
> ––––––––––––––––––– END log ––––––––––––––––––––––––––––
>