#9239: fail to make a connection because of 'upstream data before doing
handshake'
---------------------------+----------------------
Reporter: c95517 | Owner: asn
Type: defect | Status: reopened
Priority: normal | Milestone:
Component: Obfsproxy | Version:
Resolution: | Keywords:
Actual Points: | Parent ID:
Points: |
---------------------------+----------------------
Changes (by jonolumb):
* status: closed => reopened
* resolution: fixed =>
Comment:
I am running version 0.2.1 and am having this problem, below are the
server logs and client logs. Has a newer version been released since this
fix was committed? I am using the experimental PPA... Thanks!
======= SERVER ========
2013-09-09 08:06:12,706 [WARNING] Obfsproxy (version: 0.2.1) starting up.
2013-09-09 08:06:12,706 [DEBUG] argv: ['/usr/bin/obfsproxy', '--log-min-
severity=debug', 'obfs2', '--dest=127.0.0.1:22', 'server', '0.0.0.0:443']
2013-09-09 08:06:12,706 [DEBUG] args: Namespace(dest=('127.0.0.1', 22),
ext_cookie_file=None, listen_addr=('0.0.0.0', 443), log_file=None,
log_min_severity='debug', mode='server', name='obfs2', no_log=False,
no_safe_logging=False, shared_secret=None, ss_hash_iterations=None,
validation_function=<bound method type.validate_external_mode_cli of
<class 'obfsproxy.transports.obfs2.Obfs2Transport'>>)
2013-09-09 08:06:12,707 [INFO] StaticDestinationServerFactory starting on
443
2013-09-09 08:06:12,707 [INFO] Starting factory
<obfsproxy.network.network.StaticDestinationServerFactory instance at
0x1bd09e0>
2013-09-09 08:06:12,707 [DEBUG] fact_s_0x1bd09e0: Starting up static
destination server factory.
2013-09-09 08:06:12,707 [INFO] Launched 'server' listener at
'[scrubbed]:443' for transport 'obfs2'.
2013-09-09 08:06:17,414 [DEBUG] fact_s_0x1bd09e0: New connection from
[scrubbed]:38864.
2013-09-09 08:06:17,414 [INFO] Starting factory
<obfsproxy.network.network.StaticDestinationClientFactory instance at
0x1bd1290>
2013-09-09 08:06:17,414 [DEBUG] fact_c_0x1bd1290: Client factory started
connecting.
2013-09-09 08:06:17,415 [DEBUG] conn_0x1bcfbd0: connectionMade (server):
Setting it as downstream on our circuit.
2013-09-09 08:06:17,415 [DEBUG] circ_0x1bd1248: Setting downstream
connection (conn_0x1bcfbd0).
2013-09-09 08:06:17,423 [DEBUG] conn_0x1bcfd10: connectionMade (server):
Setting it as upstream on our circuit.
2013-09-09 08:06:17,424 [DEBUG] circ_0x1bd1248: Setting upstream
connection (conn_0x1bcfd10).
2013-09-09 08:06:17,424 [DEBUG] circ_0x1bd1248: Circuit completed.
2013-09-09 08:06:17,425 [DEBUG] obfs2 handshake: responder queued 7500
bytes (padding_length: 7476).
2013-09-09 08:06:17,425 [DEBUG] conn_0x1bcfbd0: Writing 7500 bytes.
2013-09-09 08:06:17,427 [DEBUG] circ_0x1bd1248: upstream: Received 41
bytes.
2013-09-09 08:06:17,427 [DEBUG] Got upstream data before doing handshake.
Caching.
2013-09-09 08:06:17,435 [DEBUG] conn_0x1bcfbd0: dataReceived called
without a reason.
======= CLIENT =======
sudo obfsproxy --log-min-severity=debug obfs2 --dest=175.41.179.239:443
server 0.0.0.0:8888
2013-09-09 16:06:06,045 [WARNING] Obfsproxy (version: 0.2.1) starting up.
2013-09-09 16:06:06,045 [DEBUG] argv: ['/usr/bin/obfsproxy', '--log-min-
severity=debug', 'obfs2', '--dest=175.41.179.239:443', 'server',
'0.0.0.0:8888']
2013-09-09 16:06:06,045 [DEBUG] args: Namespace(dest=('175.41.179.239',
443), ext_cookie_file=None, listen_addr=('0.0.0.0', 8888), log_file=None,
log_min_severity='debug', mode='server', name='obfs2', no_log=False,
no_safe_logging=False, shared_secret=None, ss_hash_iterations=None,
validation_function=<bound method type.validate_external_mode_cli of
<class 'obfsproxy.transports.obfs2.Obfs2Transport'>>)
2013-09-09 16:06:06,046 [INFO] StaticDestinationServerFactory starting on
8888
2013-09-09 16:06:06,046 [INFO] Starting factory
<obfsproxy.network.network.StaticDestinationServerFactory instance at
0x9edcf6c>
2013-09-09 16:06:06,046 [DEBUG] fact_s_0x9edcf6c: Starting up static
destination server factory.
2013-09-09 16:06:06,046 [INFO] Launched 'server' listener at
'[scrubbed]:8888' for transport 'obfs2'.
2013-09-09 16:06:23,093 [DEBUG] fact_s_0x9edcf6c: New connection from
[scrubbed]:36395.
2013-09-09 16:06:23,094 [INFO] Starting factory
<obfsproxy.network.network.StaticDestinationClientFactory instance at
0x9ed8d8c>
2013-09-09 16:06:23,094 [DEBUG] fact_c_0x9ed8d8c: Client factory started
connecting.
2013-09-09 16:06:23,095 [DEBUG] conn_0x9ed888c: connectionMade (server):
Setting it as downstream on our circuit.
2013-09-09 16:06:23,095 [DEBUG] circ_0x9ed8e6c: Setting downstream
connection (conn_0x9ed888c).
2013-09-09 16:06:23,226 [DEBUG] conn_0x9ee062c: connectionMade (server):
Setting it as upstream on our circuit.
2013-09-09 16:06:23,226 [DEBUG] circ_0x9ed8e6c: Setting upstream
connection (conn_0x9ee062c).
2013-09-09 16:06:23,226 [DEBUG] circ_0x9ed8e6c: Circuit completed.
2013-09-09 16:06:23,230 [DEBUG] obfs2 handshake: responder queued 7123
bytes (padding_length: 7099).
2013-09-09 16:06:23,230 [DEBUG] conn_0x9ed888c: Writing 7123 bytes.
2013-09-09 16:06:23,241 [DEBUG] conn_0x9ed888c: dataReceived called
without a reason.
2013-09-09 16:06:23,369 [DEBUG] circ_0x9ed8e6c: upstream: Received 4344
bytes.
2013-09-09 16:06:23,369 [DEBUG] Got upstream data before doing handshake.
Caching.
2013-09-09 16:06:23,370 [DEBUG] circ_0x9ed8e6c: upstream: Received 7240
bytes.
2013-09-09 16:06:23,371 [DEBUG] Got upstream data before doing handshake.
Caching.
2013-09-09 16:06:23,373 [DEBUG] circ_0x9ed8e6c: upstream: Received 7500
bytes.
2013-09-09 16:06:23,374 [DEBUG] Got upstream data before doing handshake.
Caching.
2013-09-09 16:06:33,155 [DEBUG] conn_0x9ee062c: Connection was lost
(Connection was closed cleanly.).
2013-09-09 16:06:33,155 [DEBUG] conn_0x9ee062c: Closing connection.
2013-09-09 16:06:33,156 [DEBUG] circ_0x9ed8e6c: Tearing down circuit.
2013-09-09 16:06:33,156 [DEBUG] conn_0x9ed888c: Closing connection.
2013-09-09 16:06:33,156 [INFO] Stopping factory
<obfsproxy.network.network.StaticDestinationClientFactory instance at
0x9ed8d8c>
2013-09-09 16:06:33,156 [DEBUG] conn_0x9ed888c: Connection was lost
(Connection was closed cleanly.).
--
Ticket URL: <https://trac.torproject.org/projects/tor/ticket/9239#comment:4>
Tor Bug Tracker & Wiki <https://trac.torproject.org/>
The Tor Project: anonymity online