Could not pick one of the responsible hidden service directories, because we requested them all recently without success.

I am seeing connections to hidden services reliably fail, with this in the log:

Dec 21 13:50:16.000 [info] connection_ap_handshake_rewrite_and_attach(): Got a hidden service request for ID '[scrubbed]' Dec 21 13:50:16.000 [info] connection_ap_handshake_rewrite_and_attach(): Unknown descriptor [scrubbed]. Fetching. Dec 21 13:50:16.000 [debug] rend_client_refetch_v2_renddesc(): Fetching v2 rendezvous descriptor for service [scrubbed] Dec 21 13:50:16.000 [info] pick_hsdir(): Could not pick one of the responsible hidden service directories, because we requested them all recently without success. Dec 21 13:50:16.000 [info] pick_hsdir(): Could not pick one of the responsible hidden service directories, because we requested them all recently without success. Dec 21 13:50:16.000 [info] fetch_v2_desc_by_addr(): Could not pick one of the responsible hidden service directories to fetch descriptors, because we already tried them all unsuccessfully.

In my test case, I have 2 hidden services running on a machine. Both have been started up for the first time in the past 5-10 minutes. On the same machine, I open concurrently two socks connections, one to each hidden service. Reiably, one of the socks connections succeeds, and the other fails. After it starts failing, retries using that onion address continue to fail for several minutes.

Kind of looks like one socks request is breaking the other one. This seems similar to #16501 (closed) and #15937 (moved) but I am pretty sure I am only making 2 socks connections, to two different onion addresses.

Attached log shows this occurring, and then after a while the bad state cleared and a retry to connect to the hidden service that it had not been able to resolve succeeded.

Trac:
Username: joeyh