[tor-bugs] #1930 [Tor Client]: Hidden service race when getting v0 and v2 descriptors

[Tor Bug Tracker & Wiki]

#1930: Hidden service race when getting v0 and v2 descriptors
------------------------+---------------------------------------------------
 Reporter:  arma        |       Owner:                     
     Type:  defect      |      Status:  new                
 Priority:  minor       |   Milestone:  Tor: post 0.2.2.x  
Component:  Tor Client  |     Version:  Tor: 0.2.2.15-alpha
 Keywords:              |      Parent:                     
------------------------+---------------------------------------------------
 Tried to access a hidden service when my Tor didn't have any circuits
 ready (I hadn't used it for an hour), and my Tor refused:

 {{{
 Sep 10 15:12:48.558 [info] connection_ap_handshake_rewrite_and_attach():
 Got a hidden service request for ID 'sw4...'
 Sep 10 15:12:48.559 [info] connection_ap_handshake_rewrite_and_attach():
 Descriptor is here. Great.
 Sep 10 15:12:48.559 [debug] circuit_find_to_cannibalize(): Hunting for a
 circ to cannibalize: purpose 9, uptime 0, capacity 1, internal 1
 Sep 10 15:12:48.559 [debug] new_route_len(): Chosen route length 3
 (1934/2502 routers suitable).
 ^^^ that's the rendezvous circuit
 ...
 Sep 10 15:12:48.571 [info] circuit_get_open_circ_or_launch(): Chose
 '$99C353AAA6C3BCEBDEA60C26F41A712F7EC444FF...' as intro point for
 'sw4...'.
 Sep 10 15:12:48.571 [debug] circuit_find_to_cannibalize(): Hunting for a
 circ to cannibalize: purpose 6, uptime 0, capacity 1, internal 1
 Sep 10 15:12:48.571 [debug] new_route_len(): Chosen route length 4
 (1934/2502 routers suitable).
 ^^^ that's the introduction circuit
 ...
 Sep 10 15:12:51.054 [info] connection_ap_handshake_attach_circuit(): found
 open intro circ 39366 (rend 39365); sending introduction. (stream 3 sec
 old)
 Sep 10 15:12:51.065 [info] rend_client_send_introduction(): Sending an
 INTRODUCE1 cell
 ...
 Sep 10 15:12:52.280 [info] rend_client_remove_intro_point(): 2 options
 left for "sw4...".
 Sep 10 15:12:52.280 [info] rend_client_introduction_acked(): Got nack for
 "sw4..." from $99C353AAA6C3BCEBDEA60C26F41A712F7EC444FF. Re-extending circ
 39366, this time to $CF91FBA32FDAC4C500F3A3565591F144D5074820.
 ...
 Sep 10 15:12:53.268 [info] rend_client_remove_intro_point(): 1 options
 left for "sw4...".
 Sep 10 15:12:53.268 [info] rend_client_introduction_acked(): Got nack for
 "sw4..." from $CF91FBA32FDAC4C500F3A3565591F144D5074820. Re-extending circ
 39366, this time to $550CC9724FA77C7F9260B93989D22A70654D3B92.
 ...
 Sep 10 15:12:55.559 [info] rend_client_remove_intro_point(): No more intro
 points remain for "sw4...". Re-fetching descriptor.
 Sep 10 15:12:55.559 [debug] rend_client_refetch_v2_renddesc(): Fetching v2
 rendezvous descriptor for service sw4...
 Sep 10 15:12:55.559 [debug] directory_initiate_command_rend(): anonymized
 1, use_begindir 1.
 Sep 10 15:12:55.559 [debug] directory_initiate_command_rend(): Initiating
 hidden-service v2 descriptor fetch
 ...
 Sep 10 15:12:55.572 [info] directory_get_from_hs_dir(): Sending fetch
 request for v2 descriptor for service 'sw4...' with descriptor ID
 '22dnx...', auth type 0, and descriptor cookie '[none]' to hidden service
 directory 'hamakor' on port 80.
 ...
 Sep 10 15:13:00.082 [debug] connection_dir_client_reached_eof(): Received
 response from directory server '82.80.248.177:443': 200 "OK"
 Sep 10 15:13:00.082 [debug] connection_dir_client_reached_eof(): Time on
 received directory is within tolerance; we are -23 seconds skewed.
 (That's okay.)
 Sep 10 15:13:00.082 [info] connection_dir_client_reached_eof(): Received
 rendezvous descriptor (size 3250, status 200 ("OK"))
 Sep 10 15:13:00.084 [debug] rend_cache_store_v2_desc_as_client():
 Successfully stored rend desc 'sw4...', len 3250.
 Sep 10 15:13:00.084 [info] connection_dir_client_reached_eof():
 Successfully fetched v2 rendezvous descriptor.
 Sep 10 15:13:00.084 [info] rend_client_desc_trynow(): Rend desc is usable.
 Launching circuits.
 Sep 10 15:13:00.084 [debug] circuit_get_open_circ_or_launch(): one on the
 way!
 Sep 10 15:13:00.084 [debug] circuit_get_open_circ_or_launch(): one on the
 way!
 Sep 10 15:13:00.084 [info] connection_ap_handshake_attach_circuit(): ready
 rend circ 39365 already here (no intro-ack yet on intro 39366). (stream 0
 sec old)
 Sep 10 15:13:00.084 [info] connection_ap_handshake_attach_circuit(): found
 open intro circ 39366 (rend 39365); sending introduction. (stream 0 sec
 old)
 Sep 10 15:13:00.084 [info] rend_client_send_introduction(): Our
 introduction point knowledge changed in mid-connect! Could not find intro
 key; we only have a v2 rend desc with 3 intro points. Giving up.
 Sep 10 15:13:00.084 [debug] connection_or_send_destroy(): Sending destroy
 (circID 39366).
 Sep 10 15:13:00.084 [debug] connection_or_send_destroy(): Sending destroy
 (circID 39365).
 Sep 10 15:13:00.084 [warn] Rendezvous attempt failed. Closing.

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