[tor-bugs] #31056 [Core Tor/Tor]: Hidden service sometimes unable to create circuit

Tue Jul 2 14:03:10 UTC 2019

#31056: Hidden service sometimes unable to create circuit
--------------------------+------------------------------
 Reporter:  dylanholand   |          Owner:  (none)
     Type:  defect        |         Status:  new
 Priority:  High          |      Milestone:
Component:  Core Tor/Tor  |        Version:  Tor: 0.3.5.8
 Severity:  Normal        |     Resolution:
 Keywords:  tor-hs        |  Actual Points:
Parent ID:                |         Points:
 Reviewer:                |        Sponsor:
--------------------------+------------------------------

Comment (by dylanholand):

 Here's part of the info-level log-file while this is happening. Let me
 know if there's anything else I can provide. Thanks a bunch!

 {{{
 #!div style="font-size: 70%"
   {{{
 Jul 02 10:30:58.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3682712543.
 Jul 02 10:30:58.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:30:58.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:30:58.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3839988605.
 Jul 02 10:30:58.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:30:58.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:30:58.000 [info]
 channelpadding_send_padding_cell_for_callback(): Sending netflow keepalive
 on 56 to [scrubbed] ([scrubbed]) after 6924 ms. Delta 2ms
 Jul 02 10:30:58.000 [info]
 channelpadding_send_padding_cell_for_callback(): Sending netflow keepalive
 on 95 to [scrubbed] ([scrubbed]) after 8529 ms. Delta 2ms
 Jul 02 10:30:59.000 [info] connection_edge_process_relay_cell(): 68: end
 cell (misc error) for stream 42650. Removing stream.
 Jul 02 10:30:59.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3682712543.
 Jul 02 10:30:59.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:30:59.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:30:59.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:30:59.000 [info] circuit_predict_and_launch_new(): Have 2 clean
 circs (0 internal), need another internal circ for my hidden service.
 Jul 02 10:30:59.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3682712543.
 Jul 02 10:30:59.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:30:59.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:30:59.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3682712543.
 Jul 02 10:30:59.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:30:59.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:30:59.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3839988605.
 Jul 02 10:30:59.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:30:59.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:31:00.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3682712543.
 Jul 02 10:31:00.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:00.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:31:00.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3682712543.
 Jul 02 10:31:00.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:00.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:31:00.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:00.000 [info] circuit_predict_and_launch_new(): Have 2 clean
 circs (0 internal), need another internal circ for my hidden service.
 Jul 02 10:31:00.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3682712543.
 Jul 02 10:31:00.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:00.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:31:00.000 [info] connection_edge_finished_connecting(): Exit
 connection to [scrubbed]:80 ([scrubbed]) established.
 Jul 02 10:31:00.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3839988605.
 Jul 02 10:31:00.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:00.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:31:00.000 [info] connection_edge_finished_connecting(): Exit
 connection to [scrubbed]:80 ([scrubbed]) established.
 Jul 02 10:31:00.000 [info] connection_edge_finished_connecting(): Exit
 connection to [scrubbed]:80 ([scrubbed]) established.
 Jul 02 10:31:00.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3839988605.
 Jul 02 10:31:00.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:00.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:31:01.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:01.000 [info] circuit_predict_and_launch_new(): Have 2 clean
 circs (0 internal), need another internal circ for my hidden service.
 Jul 02 10:31:01.000 [info] connection_edge_process_relay_cell(): 38: end
 cell (misc error) for stream 51212. Removing stream.
 Jul 02 10:31:01.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3682712543.
 Jul 02 10:31:01.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:01.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:31:01.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 4112713265.
 Jul 02 10:31:01.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:01.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:31:01.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3839988605.
 Jul 02 10:31:01.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:01.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:31:02.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3682712543.
 Jul 02 10:31:02.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:02.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:31:02.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3682712543.
 Jul 02 10:31:02.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:02.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:31:02.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 4112713265.
 Jul 02 10:31:02.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:02.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:31:02.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 3682712543.
 Jul 02 10:31:02.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:02.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
 Jul 02 10:31:02.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:02.000 [info] circuit_predict_and_launch_new(): Have 2 clean
 circs (0 internal), need another internal circ for my hidden service.
 Jul 02 10:31:02.000 [info] rend_service_receive_introduction(): Received
 INTRODUCE2 cell for service "<onion_address_v2>" on circ 4112713265.
 Jul 02 10:31:02.000 [info] rep_hist_note_used_internal(): New port
 prediction added. Will continue predictive circ building for 2747 more
 seconds.
 Jul 02 10:31:02.000 [warn] Giving up launching first hop of circuit to
 rendezvous point [scrubbed] for service <onion_address_v2>.
   }}}
 }}}

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