[tor-bugs] #5617 [Obfsproxy]: obfsproxy: test suite failure (connection refused)

Tor Bug Tracker & Wiki torproject-admin at torproject.org
Fri Apr 13 14:48:49 UTC 2012


#5617: obfsproxy: test suite failure (connection refused)
-----------------------+----------------------------------------------------
 Reporter:  asn        |          Owner:  asn
     Type:  defect     |         Status:  new
 Priority:  normal     |      Milestone:     
Component:  Obfsproxy  |        Version:     
 Keywords:             |         Parent:     
   Points:             |   Actualpoints:     
-----------------------+----------------------------------------------------
 I ran `tester.py` in a `while true` loop, and every once in a while (like,
 every 6 runs or so), a test fails like this:

 {{{
 F................
 ======================================================================
 FAIL: test_direct_transfer (__main__.DirectDummy)
 ----------------------------------------------------------------------
 Traceback (most recent call last):
   File "./tester.py", line 195, in test_direct_transfer
     self.fail("\n" + report)
 AssertionError:
 errors in transfer:
 --- expected
 +++ received
 @@ -1,39 +1,1 @@
 -THIS IS A TEST FILE. IT\'S USED BY THE INTEGRATION TESTS.
 -THIS IS A TEST FILE. IT\'S USED BY THE INTEGRATION TESTS.
 -THIS IS A TEST FILE. IT\'S USED BY THE INTEGRATION TESTS.
 -THIS IS A TEST FILE. IT\'S USED BY THE INTEGRATION TESTS.

 -"Can entropy ever be reversed?"
 -"THERE IS AS YET INSUFFICIENT DATA FOR A MEANINGFUL ANSWER."
 -"Can entropy ever be reversed?"
 -"THERE IS AS YET INSUFFICIENT DATA FOR A MEANINGFUL ANSWER."
 -"Can entropy ever be reversed?"
 -"THERE IS AS YET INSUFFICIENT DATA FOR A MEANINGFUL ANSWER."
 -"Can entropy ever be reversed?"
 -"THERE IS AS YET INSUFFICIENT DATA FOR A MEANINGFUL ANSWER."
 -"Can entropy ever be reversed?"
 -"THERE IS AS YET INSUFFICIENT DATA FOR A MEANINGFUL ANSWER."
 -"Can entropy ever be reversed?"
 -"THERE IS AS YET INSUFFICIENT DATA FOR A MEANINGFUL ANSWER."
 -"Can entropy ever be reversed?"
 -"THERE IS AS YET INSUFFICIENT DATA FOR A MEANINGFUL ANSWER."
 -"Can entropy ever be reversed?"
 -"THERE IS AS YET INSUFFICIENT DATA FOR A MEANINGFUL ANSWER."
 -
 -    In obfuscatory age geeky warfare did I wage
 -      For hiding bits from nasty censors\' sight
 -    I was hacker to my set in that dim dark age of net
 -      And I hacked from noon till three or four at night
 -
 -    Then a rival from Helsinki said my protocol was dinky
 -      So I flamed him with a condescending laugh,
 -    Saying his designs for stego might as well be made of lego
 -      And that my bikeshed was prettier by half.
 -
 -    But Claude Shannon saw my shame. From his noiseless channel came
 -       A message sent with not a wasted byte
 -    "There are nine and sixty ways to disguise communiques
 -       And RATHER MORE THAN ONE OF THEM IS RIGHT"
 -
 -\t\t    (apologies to Rudyard Kipling.)
 -
 obfsproxy stderr:
 | 2012-04-13 17:43:42 [notice] Starting (git-d34089686511346d).
 | 2012-04-13 17:43:42 [debug] Configuration 1: dummy server 127.0.0.1:5000
 127.0.0.1:5001
 | 2012-04-13 17:43:42 [debug] Configuration 2: dummy client 127.0.0.1:4999
 127.0.0.1:5000
 | 2012-04-13 17:43:42 [debug] Now listening on 127.0.0.1:5000 for protocol
 dummy.
 | 2012-04-13 17:43:42 [debug] Now listening on 127.0.0.1:4999 for protocol
 dummy.
 | 2012-04-13 17:43:42 [debug] [scrubbed]: new connection from [scrubbed]
 (1 total)
 | 2012-04-13 17:43:42 [debug] [scrubbed]: simple client connection
 | 2012-04-13 17:43:42 [info] [scrubbed] (dummy): Successful outbound
 connection to '[scrubbed]'.
 | 2012-04-13 17:43:42 [debug] [scrubbed]: setup complete
 | 2012-04-13 17:43:42 [debug] [scrubbed]: new connection from [scrubbed]
 (3 total)
 | 2012-04-13 17:43:42 [debug] [scrubbed]: server connection
 | 2012-04-13 17:43:42 [info] [scrubbed] (dummy): Successful outbound
 connection to '[scrubbed]'.
 | 2012-04-13 17:43:42 [debug] [scrubbed]: setup complete
 | 2012-04-13 17:43:42 [debug] [scrubbed]: pending_conn_cb
 | 2012-04-13 17:43:42 [debug] [scrubbed]: Successful connection
 | 2012-04-13 17:43:42 [debug] [scrubbed]: pending_conn_cb
 | 2012-04-13 17:43:42 [debug] error_cb for [scrubbed]: what=0x0020
 errno=111
 | 2012-04-13 17:43:42 [info] Error talking to [scrubbed]: Connection
 refused
 | 2012-04-13 17:43:42 [debug] error_or_eof for [scrubbed]
 | 2012-04-13 17:43:42 [debug] Closing connection with [scrubbed]; 3
 remaining
 | 2012-04-13 17:43:42 [debug] Closing connection with [scrubbed]; 2
 remaining
 | 2012-04-13 17:43:42 [debug] [scrubbed]: upstream_read_cb, 1666 bytes
 available
 | 2012-04-13 17:43:42 [debug] [scrubbed]: transmitted 1666 bytes
 | 2012-04-13 17:43:42 [debug] error_cb for [scrubbed]: what=0x0011
 | 2012-04-13 17:43:42 [info] EOF from [scrubbed]
 | 2012-04-13 17:43:42 [debug] error_or_eof for [scrubbed]
 | 2012-04-13 17:43:42 [debug] Closing connection with [scrubbed]; 1
 remaining
 | 2012-04-13 17:43:42 [debug] Closing connection with [scrubbed]; 0
 remaining
 | 2012-04-13 17:43:43 [info] Closing all listeners.
 | 2012-04-13 17:43:43 [notice] Got SIGINT. Preparing shutdown.
 | 2012-04-13 17:43:43 [debug] Beginning normal shutdown.
 | 2012-04-13 17:43:43 [debug] Finishing shutdown.
 | 2012-04-13 17:43:43 [notice] Exiting.
 }}}

 Initially, I thought it was introduced by the #5156 fix (like #5586), but
 it seems like I can reproduce the bug in earlier revisions too (the
 attached log is of `d34089686511346d`).

 I'd guess it's a problem with `tester.py` itself, but I'm not sure yet.

 PS: I can only reproduce this in certain systems. This one is a Debian
 32bit. I can't reproduce this in Debian or Ubuntu 64bit.

-- 
Ticket URL: <https://trac.torproject.org/projects/tor/ticket/5617>
Tor Bug Tracker & Wiki <https://trac.torproject.org/>
The Tor Project: anonymity online


More information about the tor-bugs mailing list