Re: [tor-bugs] #30235 [Core Tor/Stem]: Stem hangs waiting for control port data

2019-12-20 Thread Tor Bug Tracker & Wiki
#30235: Stem hangs waiting for control port data
---+--
 Reporter:  teor   |  Owner:  (none)
 Type:  defect | Status:  closed
 Priority:  Medium |  Milestone:
Component:  Core Tor/Stem  |Version:
 Severity:  Normal | Resolution:  user disappeared
 Keywords:  tor-ci |  Actual Points:  0.5
Parent ID:  #29437 | Points:  1
 Reviewer: |Sponsor:  Sponsor31-can
---+--
Changes (by atagar):

 * status:  needs_information => closed
 * resolution:   => user disappeared


--
Ticket URL: 
Tor Bug Tracker & Wiki 
The Tor Project: anonymity online
___
tor-bugs mailing list
tor-bugs@lists.torproject.org
https://lists.torproject.org/cgi-bin/mailman/listinfo/tor-bugs

Re: [tor-bugs] #30235 [Core Tor/Stem]: Stem hangs waiting for control port data

2019-11-13 Thread Tor Bug Tracker & Wiki
#30235: Stem hangs waiting for control port data
---+---
 Reporter:  teor   |  Owner:  (none)
 Type:  defect | Status:  needs_information
 Priority:  Medium |  Milestone:
Component:  Core Tor/Stem  |Version:
 Severity:  Normal | Resolution:
 Keywords:  tor-ci |  Actual Points:  0.5
Parent ID:  #29437 | Points:  1
 Reviewer: |Sponsor:  Sponsor31-can
---+---
Changes (by teor):

 * keywords:  tor-ci-fail-sometimes => tor-ci


--
Ticket URL: 
Tor Bug Tracker & Wiki 
The Tor Project: anonymity online
___
tor-bugs mailing list
tor-bugs@lists.torproject.org
https://lists.torproject.org/cgi-bin/mailman/listinfo/tor-bugs

Re: [tor-bugs] #30235 [Core Tor/Stem]: Stem hangs waiting for control port data

2019-06-16 Thread Tor Bug Tracker & Wiki
#30235: Stem hangs waiting for control port data
---+---
 Reporter:  teor   |  Owner:  (none)
 Type:  defect | Status:  needs_information
 Priority:  Medium |  Milestone:
Component:  Core Tor/Stem  |Version:
 Severity:  Normal | Resolution:
 Keywords:  tor-ci-fail-sometimes  |  Actual Points:  0.5
Parent ID:  #29437 | Points:  1
 Reviewer: |Sponsor:  Sponsor31-can
---+---
Changes (by teor):

 * status:  new => needs_information


Comment:

 Replying to [comment:16 atagar]:
 > Hi teor, yup - agreed. That's my read of those logs as well.
 Unfortunately at this point I suspect the next step is to compile
 additional logging into tor to see what's happening to that request.

 I opened #30901 for this change to tor.

 > I'd also suggest adjusting the '--test' argument to '--test
 control.controller.test_set_conf' and see if these hangs continue to
 repro. If they do this will considerably cut down the haystack we need to
 look within.

 I'm not sure if we want to do this on master, because we need these tests.
 But we could do it on another branch, if the extra tor logging doesn't
 help.

 > Anything I can be of assistance with on my end?

 No, we need more info from tor. And then we'll know whether to fix stem or
 tor.

--
Ticket URL: 
Tor Bug Tracker & Wiki 
The Tor Project: anonymity online
___
tor-bugs mailing list
tor-bugs@lists.torproject.org
https://lists.torproject.org/cgi-bin/mailman/listinfo/tor-bugs

Re: [tor-bugs] #30235 [Core Tor/Stem]: Stem hangs waiting for control port data

2019-06-14 Thread Tor Bug Tracker & Wiki
#30235: Stem hangs waiting for control port data
---+---
 Reporter:  teor   |  Owner:  (none)
 Type:  defect | Status:  new
 Priority:  Medium |  Milestone:
Component:  Core Tor/Stem  |Version:
 Severity:  Normal | Resolution:
 Keywords:  tor-ci-fail-sometimes  |  Actual Points:  0.5
Parent ID:  #29437 | Points:  1
 Reviewer: |Sponsor:  Sponsor31-can
---+---

Comment (by atagar):

 Hi teor, yup - agreed. That's my read of those logs as well. Unfortunately
 at this point I suspect the next step is to compile additional logging
 into tor to see what's happening to that request.

 I'd also suggest adjusting the '--test' argument to '--test
 control.controller.test_set_conf' and see if these hangs continue to
 repro. If they do this will considerably cut down the haystack we need to
 look within.

 Anything I can be of assistance with on my end?

--
Ticket URL: 
Tor Bug Tracker & Wiki 
The Tor Project: anonymity online
___
tor-bugs mailing list
tor-bugs@lists.torproject.org
https://lists.torproject.org/cgi-bin/mailman/listinfo/tor-bugs

Re: [tor-bugs] #30235 [Core Tor/Stem]: Stem hangs waiting for control port data

2019-06-14 Thread Tor Bug Tracker & Wiki
#30235: Stem hangs waiting for control port data
---+---
 Reporter:  teor   |  Owner:  (none)
 Type:  defect | Status:  new
 Priority:  Medium |  Milestone:
Component:  Core Tor/Stem  |Version:
 Severity:  Normal | Resolution:
 Keywords:  tor-ci-fail-sometimes  |  Actual Points:  0.5
Parent ID:  #29437 | Points:  1
 Reviewer: |Sponsor:  Sponsor31-can
---+---
Changes (by teor):

 * status:  needs_information => new


Comment:

 Hmm, it seems that something goes wrong a few lines in, where stem says:
 {{{
 06/14/2019 03:10:42 [TRACE] Sent to tor: GETINFO version
 }}}

 But it never gets a response back from tor.

 Here's the latest output:
 {{{
 06/14/2019 03:10:42 [TRACE] Sent to tor: GETINFO version
 06/14/2019 03:10:42 [TRACE] Received from tor: 250-version=0.4.2.0-alpha-
 dev (git-aadad2c643a34ea5)\n250 OK
 06/14/2019 03:10:42 [TRACE] Sent to tor: GETINFO blarg
 06/14/2019 03:10:42 [TRACE] Received from tor: 552 Unrecognized key
 "blarg"
 06/14/2019 03:10:42 [TRACE] Sent to tor: blarg
 06/14/2019 03:10:42 [TRACE] Received from tor: 510 Unrecognized command
 "blarg"
 06/14/2019 03:10:42 [TRACE] Sent to tor: GETINFO version
 06/14/2019 03:10:42 [TRACE] Received from tor: 650 STATUS_SERVER NOTICE
 EXTERNAL_ADDRESS ADDRESS=35.188.1.99 METHOD=DIRSERV
 06/14/2019 03:10:42 [TRACE] Received from tor: 650 STATUS_SERVER NOTICE
 EXTERNAL_ADDRESS ADDRESS=35.224.112.202 METHOD=DIRSERV
 06/14/2019 03:10:42 [TRACE] Received from tor: 650 STATUS_SERVER NOTICE
 EXTERNAL_ADDRESS ADDRESS=35.192.85.2 METHOD=DIRSERV
 06/14/2019 03:10:42 [TRACE] Sent to tor: Blarg!
 06/14/2019 03:10:42 [TRACE] Received from tor: 650 STATUS_SERVER NOTICE
 EXTERNAL_ADDRESS ADDRESS=35.184.226.236 METHOD=DIRSERV
 06/14/2019 03:10:42 [TRACE] Received from tor: 650 STATUS_SERVER NOTICE
 EXTERNAL_ADDRESS ADDRESS=104.154.120.187 METHOD=DIRSERV
 ...
 6/14/2019 03:10:46 [TRACE] Received from tor: 650 STATUS_SERVER NOTICE
 EXTERNAL_ADDRESS ADDRESS=35.193.7.13 METHOD=DIRSERV
 06/14/2019 03:10:46 [TRACE] Received from tor: 650 STATUS_SERVER NOTICE
 EXTERNAL_ADDRESS ADDRESS=35.184.96.71 METHOD=DIRSERV
 06/14/2019 03:10:46 [TRACE] Received from tor: 514 Authentication
 required.
 06/14/2019 03:10:46 [TRACE] Sent to tor: blarg
 timelimit: sending warning signal 10
 

 Signal SIGUSR1 received by thread MainThread in process 20776
 

 Tor listener thread stacktrace
   File "/usr/lib/python3.4/threading.py", line 888, in _bootstrap
 self._bootstrap_inner()
   File "/usr/lib/python3.4/threading.py", line 920, in _bootstrap_inner
 self.run()
   File "/usr/lib/python3.4/threading.py", line 868, in run
 self._target(*self._args, **self._kwargs)
   File "/home/travis/build/torproject/tor/stem/stem/control.py", line 946,
 in _reader_loop
 control_message = self._socket.recv()
   File "/home/travis/build/torproject/tor/stem/stem/socket.py", line 474,
 in recv
 return self._recv(lambda s, sf: recv_message(sf))
   File "/home/travis/build/torproject/tor/stem/stem/socket.py", line 274,
 in _recv
 return handler(my_socket, my_socket_file)
   File "/home/travis/build/torproject/tor/stem/stem/socket.py", line 474,
 in 
 return self._recv(lambda s, sf: recv_message(sf))
   File "/home/travis/build/torproject/tor/stem/stem/socket.py", line 676,
 in recv_message
 line = control_file.readline()
   File "/usr/lib/python3.4/socket.py", line 374, in readinto
 return self._sock.recv_into(b)
 

 Event notifier thread stacktrace
   File "/usr/lib/python3.4/threading.py", line 888, in _bootstrap
 self._bootstrap_inner()
   File "/usr/lib/python3.4/threading.py", line 920, in _bootstrap_inner
 self.run()
   File "/usr/lib/python3.4/threading.py", line 868, in run
 self._target(*self._args, **self._kwargs)
   File "/home/travis/build/torproject/tor/stem/stem/control.py", line 991,
 in _event_loop
 self._event_notice.wait(0.05)
   File "/usr/lib/python3.4/threading.py", line 553, in wait
 signaled = self._cond.wait(timeout)
   File "/usr/lib/python3.4/threading.py", line 294, in wait
 gotit = waiter.acquire(True, timeout)
 

 MainThread thread stacktrace
   File "/home/travis/build/torproject/tor/stem/run_tests.py", line 479, in
 
 main()
   File "/home/travis/build/torproject/tor/stem/run_tests.py", line 311, in
 main
 run_result = _run_test(args, test_class, output_filters)
   File 

Re: [tor-bugs] #30235 [Core Tor/Stem]: Stem hangs waiting for control port data

2019-06-13 Thread Tor Bug Tracker & Wiki
#30235: Stem hangs waiting for control port data
---+---
 Reporter:  teor   |  Owner:  (none)
 Type:  defect | Status:  needs_information
 Priority:  Medium |  Milestone:
Component:  Core Tor/Stem  |Version:
 Severity:  Normal | Resolution:
 Keywords:  tor-ci-fail-sometimes  |  Actual Points:  0.5
Parent ID:  #29437 | Points:  1
 Reviewer: |Sponsor:  Sponsor31-can
---+---

Comment (by teor):

 Hopefully one of the pull requests in #30591 will fail, if they do, I'll
 paste the logs here:
 https://trac.torproject.org/projects/tor/ticket/30591#comment:20

 (We don't want the logs from the deliberate failure pull request.)

--
Ticket URL: 
Tor Bug Tracker & Wiki 
The Tor Project: anonymity online
___
tor-bugs mailing list
tor-bugs@lists.torproject.org
https://lists.torproject.org/cgi-bin/mailman/listinfo/tor-bugs

Re: [tor-bugs] #30235 [Core Tor/Stem]: Stem hangs waiting for control port data

2019-06-07 Thread Tor Bug Tracker & Wiki
#30235: Stem hangs waiting for control port data
---+---
 Reporter:  teor   |  Owner:  (none)
 Type:  defect | Status:  needs_information
 Priority:  Medium |  Milestone:
Component:  Core Tor/Stem  |Version:
 Severity:  Normal | Resolution:
 Keywords:  tor-ci-fail-sometimes  |  Actual Points:  0.5
Parent ID:  #29437 | Points:  1
 Reviewer: |Sponsor:  Sponsor31-can
---+---
Changes (by atagar):

 * status:  assigned => needs_information


Comment:

 Hi teor, is this still occurring? If so, could I get a fresh instance with
 the '--log-file' output we recently added with trace logs?

--
Ticket URL: 
Tor Bug Tracker & Wiki 
The Tor Project: anonymity online
___
tor-bugs mailing list
tor-bugs@lists.torproject.org
https://lists.torproject.org/cgi-bin/mailman/listinfo/tor-bugs

Re: [tor-bugs] #30235 [Core Tor/Stem]: Stem hangs waiting for control port data (was: Tor hangs when asked to change DisableAllSwap over the control port)

2019-05-30 Thread Tor Bug Tracker & Wiki
#30235: Stem hangs waiting for control port data
---+---
 Reporter:  teor   |  Owner:  (none)
 Type:  defect | Status:  assigned
 Priority:  Medium |  Milestone:
Component:  Core Tor/Stem  |Version:
 Severity:  Normal | Resolution:
 Keywords:  tor-ci-fail-sometimes  |  Actual Points:  0.5
Parent ID:  #29437 | Points:  1
 Reviewer: |Sponsor:  Sponsor31-can
---+---
Changes (by teor):

 * milestone:  Tor: 0.2.9.x-final =>
 * component:  Core Tor/Tor => Core Tor/Stem
 * version:  Tor: 0.2.4.8-alpha =>
 * actualpoints:  0.3 => 0.5


Comment:

 It looks like stem hangs waiting for a controller reply, but stem never
 actually sends a command to trigger the reply:
 {{{
 05/30/2019 10:20:56 [TRACE] Sent to tor: Blarg!
 05/30/2019 10:20:56 [TRACE] Received from tor: 650 STATUS_SERVER NOTICE
 EXTERNAL_ADDRESS ADDRESS=35.202.145.110 METHOD=DIRSERV
 05/30/2019 10:20:56 [TRACE] Received from tor: 514 Authentication
 required.
 05/30/2019 10:20:56 [TRACE] Sent to tor: blarg
 05/30/2019 10:20:56 [INFO] Error while receiving a control message
 (SocketClosed): empty socket content
 05/30/2019 10:20:56 [TRACE] Received from tor: 650 STATUS_SERVER NOTICE
 EXTERNAL_ADDRESS ADDRESS=35.184.96.71 METHOD=DIRSERV
   control.controller...

 

 Signal SIGUSR1 received by thread MainThread in process 18617
 

 MainThread thread stacktrace
   File "/home/travis/build/torproject/tor/stem/run_tests.py", line 456, in
 
 main()
   File "/home/travis/build/torproject/tor/stem/run_tests.py", line 297, in
 main
 run_result = _run_test(args, test_class, output_filters)
   File "/home/travis/build/torproject/tor/stem/run_tests.py", line 437, in
 _run_test
 run_result = stem.util.test_tools.TimedTestRunner(test_results,
 verbosity = 2).run(suite)
   File "/home/travis/build/torproject/tor/stem/stem/util/test_tools.py",
 line 308, in run
 return super(TimedTestRunner, self).run(test)
   File "/usr/lib/python3.4/unittest/runner.py", line 168, in run
 test(result)
   File "/usr/lib/python3.4/unittest/suite.py", line 84, in __call__
 return self.run(*args, **kwds)
   File "/usr/lib/python3.4/unittest/suite.py", line 122, in run
 test(result)
   File "/usr/lib/python3.4/unittest/case.py", line 625, in __call__
 return self.run(*args, **kwds)
   File "/home/travis/build/torproject/tor/stem/stem/util/test_tools.py",
 line 258, in run
 result = super(type(self), self).run(result)
   File "/usr/lib/python3.4/unittest/case.py", line 577, in run
 testMethod()
   File "/home/travis/build/torproject/tor/stem/test/require.py", line 57,
 in wrapped
 return func(self, *args, **kwargs)
   File
 "/home/travis/build/torproject/tor/stem/test/integ/control/controller.py",
 line 782, in test_set_conf
 ), reset = True)
   File "/home/travis/build/torproject/tor/stem/stem/control.py", line
 2541, in set_options
 response = self.msg(query)
   File "/home/travis/build/torproject/tor/stem/stem/control.py", line 661,
 in msg
 response = self._reply_queue.get()
   File "/usr/lib/python3.4/queue.py", line 167, in get
 self.not_empty.wait()
   File "/usr/lib/python3.4/threading.py", line 290, in wait
 waiter.acquire()
   File "/home/travis/build/torproject/tor/stem/run_tests.py", line 91, in
 log_traceback
 for thread_name, stacktrace in
 test.output.thread_stacktraces().items():
   File "/home/travis/build/torproject/tor/stem/test/output.py", line 110,
 in thread_stacktraces
 stacktraces[thread.name] = ''.join(traceback.format_stack(frame))
 

 Tor listener thread stacktrace
   File "/usr/lib/python3.4/threading.py", line 888, in _bootstrap
 self._bootstrap_inner()
   File "/usr/lib/python3.4/threading.py", line 920, in _bootstrap_inner
 self.run()
   File "/usr/lib/python3.4/threading.py", line 868, in run
 self._target(*self._args, **self._kwargs)
   File "/home/travis/build/torproject/tor/stem/stem/control.py", line 946,
 in _reader_loop
 control_message = self._socket.recv()
   File "/home/travis/build/torproject/tor/stem/stem/socket.py", line 474,
 in recv
 return self._recv(lambda s, sf: recv_message(sf))
   File "/home/travis/build/torproject/tor/stem/stem/socket.py", line 274,
 in _recv
 return handler(my_socket, my_socket_file)
   File "/home/travis/build/torproject/tor/stem/stem/socket.py", line 474,
 in 
 return self._recv(lambda s, sf: recv_message(sf))
   File "/home/travis/build/torproject/tor/stem/stem/socket.py", line 676,
 in recv_message