[tor-bugs] #1314 [Tor]: Problem bootstrapping. Stuck at 45%: (Connection refused; CONNECTREFUSED)
Tor Bug Tracker & Wiki
blackhole at torproject.org
Tue Aug 27 14:01:04 UTC 2013
#1314: Problem bootstrapping. Stuck at 45%: (Connection refused; CONNECTREFUSED)
------------------------+------------------------------
Reporter: narr | Owner:
Type: defect | Status: reopened
Priority: minor | Milestone: Tor: unspecified
Component: Tor | Version: 0.2.2.10-alpha
Resolution: | Keywords:
Actual Points: | Parent ID:
Points: |
------------------------+------------------------------
Changes (by Grief):
* status: closed => reopened
* resolution: user disappeared =>
Comment:
Hello, I was running both tor '''0.2.2.39-1''' and '''0.2.3.22-rc-1''' on
my dir-825 with OpenWRT firmware (ATTITUDE ADJUSTMENT (12.09, r36088)) and
both versions were stuck at 45% with the following in the notices.log:
{{{
Aug 27 02:07:01.000 [warn] Problem bootstrapping. Stuck at 45%: Asking for
relay descriptors. (IOERROR; IOERROR; count 432; recommendation warn)
Aug 27 02:07:02.000 [warn] 435 connections have failed:
Aug 27 02:07:02.000 [warn] 285 connections died in state connect()ing
with SSL state (No SSL object)
Aug 27 02:07:02.000 [warn] 98 connections died in state handshaking (Tor,
v3 handshake) with SSL state SSL negotiation finished successfully in OPEN
Aug 27 02:07:02.000 [warn] 46 connections died in state handshaking (TLS)
with SSL state SSLv2/v3 read server hello A in HANDSHAKE
Aug 27 02:07:02.000 [warn] 6 connections died in state handshaking (TLS)
with SSL state SSLv3 read finished A in HANDSHAKE
Aug 27 02:07:07.000 [warn] Weighted bandwidth is 0.000000 in node
selection for rule weight as directory (unweighted was 352009000.000000)
[31 similar message(s) suppressed in last 15 seconds]
Aug 27 02:07:38.000 [warn] Weighted bandwidth is 0.000000 in node
selection for rule weight as directory (unweighted was 87767000.000000)
[35 similar message(s) suppressed in last 15 seconds]
Aug 27 02:08:09.000 [warn] Weighted bandwidth is 0.000000 in node
selection for rule weight as directory (unweighted was 62269000.000000)
[42 similar message(s) suppressed in last 15 seconds]
Aug 27 02:08:36.000 [warn] Weighted bandwidth is 0.000000 in node
selection for rule weight as directory (unweighted was 3127000.000000)
[44 similar message(s) suppressed in last 15 seconds]
Aug 27 02:10:42.000 [warn] Problem bootstrapping. Stuck at 45%: Asking for
relay descriptors. (DONE; DONE; count 433; recommendation warn)
}}}
and in the debug.log:
{{{
Aug 27 17:58:56.000 [debug] directory_initiate_command_rend(): anonymized
0, use_begindir 1.
Aug 27 17:58:56.000 [debug] directory_initiate_command_rend(): Initiating
microdescriptor fetch
Aug 27 17:58:56.000 [info] connection_ap_make_link(): Making internal
direct tunnel to [scrubbed]:9101 ...
Aug 27 17:58:56.000 [debug] connection_add_impl(): new conn type Socks,
socket -1, address (Tor_internal), n_conns 22.
Aug 27 17:58:56.000 [debug] circuit_get_open_circ_or_launch(): one on the
way!
Aug 27 17:58:56.000 [info] connection_ap_make_link(): ... application
connection created and linked.
Aug 27 17:58:56.000 [debug] connection_add_impl(): new conn type
Directory, socket -1, address 128.31.0.34, n_conns 23.
Aug 27 17:58:56.000 [debug] directory_initiate_command_rend(): anonymized
0, use_begindir 1.
Aug 27 17:58:56.000 [debug] directory_initiate_command_rend(): Initiating
microdescriptor fetch
Aug 27 17:58:56.000 [info] connection_ap_make_link(): Making internal
direct tunnel to [scrubbed]:80 ...
Aug 27 17:58:56.000 [debug] connection_add_impl(): new conn type Socks,
socket -1, address (Tor_internal), n_conns 24.
Aug 27 17:58:56.000 [debug] circuit_get_open_circ_or_launch(): one on the
way!
Aug 27 17:58:56.000 [info] connection_ap_make_link(): ... application
connection created and linked.
Aug 27 17:58:56.000 [debug] connection_add_impl(): new conn type
Directory, socket -1, address 208.83.223.34, n_conns 25.
Aug 27 17:58:56.000 [debug] directory_initiate_command_rend(): anonymized
0, use_begindir 1.
Aug 27 17:58:57.000 [debug] directory_initiate_command_rend(): Initiating
microdescriptor fetch
Aug 27 17:58:57.000 [info] connection_ap_make_link(): Making internal
direct tunnel to [scrubbed]:9101 ...
Aug 27 17:58:57.000 [debug] connection_add_impl(): new conn type Socks,
socket -1, address (Tor_internal), n_conns 26.
Aug 27 17:58:57.000 [debug] circuit_get_open_circ_or_launch(): one on the
way!
Aug 27 17:58:57.000 [info] connection_ap_make_link(): ... application
connection created and linked.
Aug 27 17:58:57.000 [debug] connection_add_impl(): new conn type
Directory, socket -1, address 128.31.0.34, n_conns 27.
Aug 27 17:58:57.000 [debug] directory_initiate_command_rend(): anonymized
0, use_begindir 1.
Aug 27 17:58:57.000 [debug] directory_initiate_command_rend(): Initiating
microdescriptor fetch
Aug 27 17:58:57.000 [info] connection_ap_make_link(): Making internal
direct tunnel to [scrubbed]:443 ...
Aug 27 17:58:57.000 [debug] connection_add_impl(): new conn type Socks,
socket -1, address (Tor_internal), n_conns 28.
Aug 27 17:58:57.000 [debug] circuit_get_open_circ_or_launch(): one on the
way!
Aug 27 17:58:57.000 [info] connection_ap_make_link(): ... application
connection created and linked.
Aug 27 17:58:57.000 [debug] connection_add_impl(): new conn type
Directory, socket -1, address 194.109.206.212, n_conns 29.
Aug 27 17:58:57.000 [debug] directory_initiate_command_rend(): anonymized
0, use_begindir 1.
Aug 27 17:58:57.000 [debug] directory_initiate_command_rend(): Initiating
microdescriptor fetch
Aug 27 17:58:57.000 [info] connection_ap_make_link(): Making internal
direct tunnel to [scrubbed]:443 ...
Aug 27 17:58:57.000 [debug] connection_add_impl(): new conn type Socks,
socket -1, address (Tor_internal), n_conns 30.
Aug 27 17:58:57.000 [debug] circuit_get_open_circ_or_launch(): considering
1, $7BE683E65D48141321C5ED92F075C55364AC7123
Aug 27 17:58:57.000 [debug] onion_pick_cpath_exit(): Launching a one-hop
circuit for dir tunnel.
Aug 27 17:58:57.000 [info] onion_pick_cpath_exit(): Using requested exit
node '$7BE683E65D48141321C5ED92F075C55364AC7123~7BE683E65D48141321C at
193.23.244.244'
Aug 27 17:58:57.000 [debug] onion_extend_cpath(): Path is 0 long; we want
1
Aug 27 17:58:57.000 [debug] onion_extend_cpath(): Chose router
$7BE683E65D48141321C5ED92F075C55364AC7123~7BE683E65D48141321C at
193.23.244.244 for hop 1 (exit is
7BE683E65D48141321C5ED92F075C55364AC7123)
Aug 27 17:58:57.000 [debug] onion_extend_cpath(): Path is complete: 1
steps long
Aug 27 17:58:57.000 [debug] circuit_handle_first_hop(): Looking for
firsthop '193.23.244.244:443'
Aug 27 17:58:57.000 [debug] circuit_handle_first_hop(): Conn open.
Delivering first onion skin.
Aug 27 17:58:57.000 [debug] circuit_send_next_onion_skin(): First skin;
sending create cell.
Aug 27 17:58:57.000 [debug] circuit_deliver_create_cell(): Chosen circID
38144.
Aug 27 17:58:57.000 [debug] append_cell_to_circuit_queue(): Made a circuit
active.
Aug 27 17:58:57.000 [info] circuit_send_next_onion_skin(): First hop:
finished sending CREATE_FAST cell to
'$7BE683E65D48141321C5ED92F075C55364AC7123=dannenberg at 193.23.244.244'
Aug 27 17:58:57.000 [info] connection_ap_make_link(): ... application
connection created and linked.
Aug 27 17:58:57.000 [debug] connection_add_impl(): new conn type
Directory, socket -1, address 193.23.244.244, n_conns 31.
}}}
The debug.log grows in about 300mb per day with simmilar, but not
completely the same messages.
--
Ticket URL: <https://trac.torproject.org/projects/tor/ticket/1314#comment:15>
Tor Bug Tracker & Wiki <https://trac.torproject.org/>
The Tor Project: anonymity online
More information about the tor-bugs
mailing list