[tor-bugs] #29437 [Core Tor/Stem]: test-stem times out intermittently

Wed May 22 22:50:33 UTC 2019

#29437: test-stem times out intermittently
-----------------------------------+------------------------------------
 Reporter:  rl1987                 |          Owner:  (none)
     Type:  defect                 |         Status:  assigned
 Priority:  Medium                 |      Milestone:  Tor: 0.2.9.x-final
Component:  Core Tor/Stem          |        Version:  Tor: 0.2.4.8-alpha
 Severity:  Normal                 |     Resolution:
 Keywords:  tor-ci-fail-sometimes  |  Actual Points:  0.2
Parent ID:                         |         Points:  0.2
 Reviewer:                         |        Sponsor:
-----------------------------------+------------------------------------

Comment (by catalyst):

 This Travis failure seems to not be related to #30235?
 {{{
 [34;1m  control.controller...
 [0mtimelimit: sending warning signal 10

 ================================================================================
 Signal SIGUSR1 received by thread MainThread in process 19670
 --------------------------------------------------------------------------------
 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)

 --------------------------------------------------------------------------------
 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 <lambda>
     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)

 --------------------------------------------------------------------------------
 MainThread thread stacktrace

   File "/home/travis/build/torproject/tor/stem/run_tests.py", line 451, in
 <module>
     main()
   File "/home/travis/build/torproject/tor/stem/run_tests.py", line 292, in
 main
     run_result = _run_test(args, test_class, output_filters)
   File "/home/travis/build/torproject/tor/stem/run_tests.py", line 432, 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/require.py", line 57,
 in wrapped
     return func(self, *args, **kwargs)
   File
 "/home/travis/build/torproject/tor/stem/test/integ/control/controller.py",
 line 527, in test_without_ephemeral_hidden_services
     with test.runner.get_runner().get_tor_controller() as controller:
   File "/home/travis/build/torproject/tor/stem/test/runner.py", line 482,
 in get_tor_controller
     controller.authenticate(password = CONTROL_PASSWORD, chroot_path =
 self.get_chroot())
   File "/home/travis/build/torproject/tor/stem/stem/control.py", line
 1110, in authenticate
     stem.connection.authenticate(self, *args, **kwargs)
   File "/home/travis/build/torproject/tor/stem/stem/connection.py", line
 530, in authenticate
     protocolinfo_response = get_protocolinfo(controller)
   File "/home/travis/build/torproject/tor/stem/stem/connection.py", line
 1007, in get_protocolinfo
     protocolinfo_response = _msg(controller, 'PROTOCOLINFO 1')
   File "/home/travis/build/torproject/tor/stem/stem/connection.py", line
 1036, in _msg
     return controller.msg(message)
   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 98, 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))

 ================================================================================
 timelimit: sending kill signal 6

 ================================================================================
 Signal SIGIOT received by thread MainThread in process 19670
 --------------------------------------------------------------------------------
 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)

 --------------------------------------------------------------------------------
 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 <lambda>
     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)

 --------------------------------------------------------------------------------
 MainThread thread stacktrace

   File "/home/travis/build/torproject/tor/stem/run_tests.py", line 451, in
 <module>
     main()
   File "/home/travis/build/torproject/tor/stem/run_tests.py", line 292, in
 main
     run_result = _run_test(args, test_class, output_filters)
   File "/home/travis/build/torproject/tor/stem/run_tests.py", line 432, 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/require.py", line 57,
 in wrapped
     return func(self, *args, **kwargs)
   File
 "/home/travis/build/torproject/tor/stem/test/integ/control/controller.py",
 line 527, in test_without_ephemeral_hidden_services
     with test.runner.get_runner().get_tor_controller() as controller:
   File "/home/travis/build/torproject/tor/stem/test/runner.py", line 482,
 in get_tor_controller
     controller.authenticate(password = CONTROL_PASSWORD, chroot_path =
 self.get_chroot())
   File "/home/travis/build/torproject/tor/stem/stem/control.py", line
 1110, in authenticate
     stem.connection.authenticate(self, *args, **kwargs)
   File "/home/travis/build/torproject/tor/stem/stem/connection.py", line
 530, in authenticate
     protocolinfo_response = get_protocolinfo(controller)
   File "/home/travis/build/torproject/tor/stem/stem/connection.py", line
 1007, in get_protocolinfo
     protocolinfo_response = _msg(controller, 'PROTOCOLINFO 1')
   File "/home/travis/build/torproject/tor/stem/stem/connection.py", line
 1036, in _msg
     return controller.msg(message)
   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 98, 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))

 ================================================================================
 travis_time:end:009e4e68:start=1558563743787916536,finish=1558564362060945271,duration=618273028735
 [0K[31;1mThe command "if [[ "$TEST_STEM" != "" ]]; then make
 src/app/tor; timelimit -p -t 540 -s USR1 -T 30 -S ABRT python3
 "$STEM_SOURCE_DIR"/run_tests.py --tor src/app/tor --integ --log notice
 --target RUN_ALL; fi" exited with 255.[0m
 }}}

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