Chronic 504 Connect to hiddenservice.onion:80 TTL expired


2 years ago
For the past few weeks there have been lots of errors connecting to the duckduckgo hidden service via Polipo proxy => Tor relay.

These errors keep showing up in the web browser. "504 Connect to 3g2upl4pq6kufc4m.onion:80 failed: SOCKS error: TTL expired"

I have changed the tor relay log level to 'info' and am examining the log for clues as to what the problem might be. Here is a tor log file excerpt of a failed request

May 17 13:07:04.000 [info] connection_ap_handshake_rewrite_and_attach(): Got a hidden service request for ID '[scrubbed]' [28/1931]
May 17 13:07:04.000 [info] connection_ap_handshake_rewrite_and_attach(): Descriptor is here. Great.
May 17 13:07:04.000 [info] circuit_launch_by_extend_info(): Cannibalizing circ 'edwardsnowden1' for purpose 9 (Hidden service client: Establishing rendezvous point)
May 17 13:07:04.000 [info] rend_client_rendcirc_has_opened(): rendcirc is open
May 17 13:07:04.000 [info] rend_client_send_establish_rendezvous(): Sending an ESTABLISH_RENDEZVOUS cell
May 17 13:07:04.000 [info] circuit_get_open_circ_or_launch(): Chose $ED2338CAC2711B3E331392E1ED2831219B794024~$ED2338CAC2711B3E33 at 192.87.28.28 as intro point for '[scrubbed]'.
May 17 13:07:04.000 [info] circuit_launch_by_extend_info(): Cannibalizing circ 'catshavenosoul' for purpose 6 (Hidden service client: Connecting to intro point)
May 17 13:07:04.000 [info] circuit_send_next_onion_skin(): Sending extend relay cell.
May 17 13:07:04.000 [info] connection_ap_handshake_attach_circuit(): Intro (2147492653) and rend (2147507905) circs are not both ready. Stalling conn. (0 sec old)
May 17 13:07:04.000 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer.
May 17 13:07:04.000 [info] connection_ap_handshake_attach_circuit(): Intro (2147492653) and rend (2147507905) circs are not both ready. Stalling conn. (0 sec old)
May 17 13:07:04.000 [info] circuit_predict_and_launch_new(): Have 1 clean circs (0 uptime-internal, 0 internal), need another hidden service circ.
May 17 13:07:04.000 [info] circuit_send_next_onion_skin(): First hop: finished sending CREATE cell to '$704386F528CD7C31F8A46664A543A8A1513C949D=onion at 5.135.59.74'
May 17 13:07:04.000 [info] circuit_finish_handshake(): Finished building circuit hop:
May 17 13:07:04.000 [info] internal circ (length 3, last hop BaiMangalIsBack2): onion(open) torrex(closed) BaiMangalIsBack2(closed)
May 17 13:07:04.000 [info] circuit_send_next_onion_skin(): Sending extend relay cell.
May 17 13:07:04.000 [info] rend_client_rendezvous_acked(): Got rendezvous ack. This circuit is now ready for rendezvous.
May 17 13:07:04.000 [info] connection_ap_handshake_attach_circuit(): ready rend circ 2147507905 already here (no intro-ack yet on intro 2147492653). (stream 0 sec old)
May 17 13:07:04.000 [info] connection_ap_handshake_attach_circuit(): Intro (2147492653) and rend (2147507905) circs are not both ready. Stalling conn. (0 sec old)
May 17 13:07:04.000 [info] entry_guard_inc_circ_attempt_count(): Got success count 2.000000/3.000000 for guard onion ($704386F528CD7C31F8A46664A543A8A1513C949D)
May 17 13:07:04.000 [info] circuit_finish_handshake(): Finished building circuit hop:
May 17 13:07:04.000 [info] internal circ (length 3, last hop BaiMangalIsBack2): onion(open) torrex(open) BaiMangalIsBack2(closed)
May 17 13:07:04.000 [info] circuit_send_next_onion_skin(): Sending extend relay cell.
May 17 13:07:04.000 [info] circuit_finish_handshake(): Finished building circuit hop:
May 17 13:07:04.000 [info] internal circ (length 4, last hop $ED2338CAC2711B3E331392E1ED2831219B794024): GreenDragon(open) Yahta4ee(open) catshavenosoul(open) SEC6xFreeBSD64(open)
May 17 13:07:04.000 [info] circuit_send_next_onion_skin(): circuit built!
May 17 13:07:04.000 [info] rend_client_introcirc_has_opened(): introcirc is open
May 17 13:07:04.000 [info] connection_ap_handshake_attach_circuit(): ready rend circ 2147507905 already here (no intro-ack yet on intro 2147492653). (stream 0 sec old)
May 17 13:07:04.000 [info] connection_ap_handshake_attach_circuit(): found open intro circ 2147492653 (rend 2147507905); sending introduction. (stream 0 sec old)
May 17 13:07:04.000 [info] rend_client_send_introduction(): Sending an INTRODUCE1 cell
May 17 13:07:04.000 [info] pathbias_count_use_attempt(): Used circuit 67 is already in path state use succeeded. Circuit is a Hidden service client: Pending rendezvous point currently open.
May 17 13:07:05.000 [info] connection_ap_handshake_attach_circuit(): Intro circ 2147492653 present and awaiting ack (rend 2147507905). Stalling. (stream 1 sec old)
May 17 13:07:05.000 [info] circuit_predict_and_launch_new(): Have 2 clean circs (0 uptime-internal, 1 internal), need another hidden service circ.
May 17 13:07:05.000 [info] circuit_send_next_onion_skin(): First hop: finished sending CREATE cell to '$704386F528CD7C31F8A46664A543A8A1513C949D=onion at 5.135.59.74'
May 17 13:07:05.000 [info] circuit_finish_handshake(): Finished building circuit hop:
May 17 13:07:05.000 [info] internal circ (length 3, last hop BaiMangalIsBack2): onion(open) torrex(open) BaiMangalIsBack2(open)
May 17 13:07:05.000 [info] circuit_send_next_onion_skin(): circuit built!
May 17 13:07:05.000 [info] pathbias_count_build_success(): Got success count 3.000000/3.000000 for guard onion ($704386F528CD7C31F8A46664A543A8A1513C949D)
May 17 13:07:05.000 [info] connection_ap_handshake_attach_circuit(): Intro circ 2147492653 present and awaiting ack (rend 2147507905). Stalling. (stream 1 sec old)
May 17 13:07:05.000 [info] rend_client_introduction_acked(): Got nack for [scrubbed] from [scrubbed]...
May 17 13:07:05.000 [info] rend_client_report_intro_point_failure(): 2 options left for [scrubbed].
May 17 13:07:05.000 [info] rend_client_reextend_intro_circuit(): Re-extending circ 2147492653, this time to [scrubbed].
May 17 13:07:05.000 [info] circuit_send_next_onion_skin(): Sending extend relay cell.
May 17 13:07:05.000 [info] circuit_finish_handshake(): Finished building circuit hop:
May 17 13:07:05.000 [info] internal circ (length 3, last hop Unnamed): onion(open) $D2F4121057FFFD136D703931E31174BB3607EF0C(closed) $33044D87463191615C8906B0B7C8C30DA59A0522(closed)
May 17 13:07:05.000 [info] circuit_send_next_onion_skin(): Sending extend relay cell.
May 17 13:07:06.000 [info] entry_guard_inc_circ_attempt_count(): Got success count 3.000000/4.000000 for guard onion ($704386F528CD7C31F8A46664A543A8A1513C949D)
May 17 13:07:06.000 [info] circuit_finish_handshake(): Finished building circuit hop:
May 17 13:07:06.000 [info] internal circ (length 3, last hop Unnamed): onion(open) $D2F4121057FFFD136D703931E31174BB3607EF0C(open) $33044D87463191615C8906B0B7C8C30DA59A0522(closed)
May 17 13:07:06.000 [info] circuit_send_next_onion_skin(): Sending extend relay cell.
May 17 13:07:06.000 [info] connection_ap_handshake_attach_circuit(): ready rend circ 2147507905 already here (no intro-ack yet on intro 2147492653). (stream 2 sec old)
May 17 13:07:06.000 [info] connection_ap_handshake_attach_circuit(): Intro (2147492653) and rend (2147507905) circs are not both ready. Stalling conn. (2 sec old)
May 17 13:07:06.000 [info] circuit_finish_handshake(): Finished building circuit hop:
May 17 13:07:06.000 [info] internal circ (length 5, last hop $A4FD3E7FCD2504212FFEA08F1C4F7BDD0F6BA193): GreenDragon(open) Yahta4ee(open) catshavenosoul(open) SEC6xFreeBSD64(open) freedommail
May 17 13:07:06.000 [info] circuit_send_next_onion_skin(): circuit built!
May 17 13:07:06.000 [info] rend_client_introcirc_has_opened(): introcirc is open
May 17 13:07:06.000 [info] connection_ap_handshake_attach_circuit(): ready rend circ 2147507905 already here (no intro-ack yet on intro 2147492653). (stream 2 sec old)
May 17 13:07:06.000 [info] connection_ap_handshake_attach_circuit(): found open intro circ 2147492653 (rend 2147507905); sending introduction. (stream 2 sec old)
May 17 13:07:06.000 [info] rend_client_send_introduction(): Sending an INTRODUCE1 cell
May 17 13:07:06.000 [info] pathbias_count_use_attempt(): Used circuit 67 is already in path state use succeeded. Circuit is a Hidden service client: Pending rendezvous point currently open.
May 17 13:07:06.000 [info] circuit_finish_handshake(): Finished building circuit hop:
May 17 13:07:06.000 [info] internal circ (length 3, last hop Unnamed): onion(open) $D2F4121057FFFD136D703931E31174BB3607EF0C(open) $33044D87463191615C8906B0B7C8C30DA59A0522(open)
May 17 13:07:06.000 [info] circuit_send_next_onion_skin(): circuit built!
May 17 13:07:06.000 [info] pathbias_count_build_success(): Got success count 4.000000/4.000000 for guard onion ($704386F528CD7C31F8A46664A543A8A1513C949D)
May 17 13:07:06.000 [info] connection_ap_handshake_attach_circuit(): Intro circ 2147492653 present and awaiting ack (rend 2147507905). Stalling. (stream 2 sec old)
May 17 13:07:07.000 [info] connection_ap_handshake_attach_circuit(): Intro circ 2147492653 present and awaiting ack (rend 2147507905). Stalling. (stream 3 sec old)
May 17 13:07:07.000 [info] rend_client_introduction_acked(): Received ack. Telling rend circ...
May 17 13:07:07.000 [info] rend_client_receive_rendezvous(): Got RENDEZVOUS2 cell from hidden service.
May 17 13:07:07.000 [info] connection_ap_handshake_attach_circuit(): rend joined circ -2147459391 already here. attaching. (stream 3 sec old)
May 17 13:07:07.000 [info] pathbias_count_use_attempt(): Used circuit 67 is already in path state use succeeded. Circuit is a Hidden service client: Active rendezvous point currently open.
May 17 13:07:07.000 [info] rend_client_note_connection_attempt_ended(): Connection attempt for [scrubbed] has ended; cleaning up temporary state.
May 17 13:07:07.000 [info] connection_ap_handshake_send_begin(): Sending relay cell 0 to begin stream 13295.
May 17 13:07:07.000 [info] connection_ap_handshake_send_begin(): Address/port sent, ap socket 16, n_circ_id 2147507905
May 17 13:07:45.000 [info] router_pick_published_address(): Success: chose address 'redacted'.
May 17 13:07:45.000 [info] update_consensus_router_descriptor_downloads(): 0 router descriptors downloadable. 0 delayed; 4795 present (0 of those were in old_routers); 0 would_reject; 1 would
nt_use; 0 in progress.
May 17 13:07:45.000 [info] router_pick_published_address(): Success: chose address 'redacted'.
May 17 13:07:45.000 [info] routerlist_remove_old_routers(): We have 4888 live routers and 0 old router descriptors.
May 17 13:08:11.000 [info] connection_ap_process_end_not_open(): Edge got end (gave up (timeout)) before we're connected. Marking for close.
May 17 13:08:11.000 [info] internal circ (length 3): $C4F9E12624F81F338A3B73A64E881ABA3B5F1B8D(open) $18BE989663CF3351F73D33C672BB1C985E0EA5D0(open) edwardsnowden1(open)
May 17 13:08:11.000 [info] rend_client_note_connection_attempt_ended(): Connection attempt for [scrubbed] has ended; cleaning up temporary state.

Reply

You are not logged in. Login or register to reply on this thread.