tor router not working

I have Tor v0.2.5.12 (git-6350e21f2de7272f) install in rasping pi dives to useing it as tor router but but it's not working this is debug log please advice

Oct 10 25:04.0 [notice] Tor 0.2.5.12 (git-6350e21f2de7272f) opening new log file. Oct 11 00:02.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:02.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:02.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 34 Oct 11 00:09.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:09.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:09.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 33 Oct 11 00:10.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:10.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:10.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 32 Oct 11 00:11.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:11.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:11.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 31 Oct 11 00:13.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:13.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:13.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 30 Oct 11 00:17.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:17.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:17.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 29 Oct 11 00:24.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:24.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:24.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 28 Oct 11 00:25.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:25.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:25.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 27 Oct 11 00:26.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:26.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:26.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 26 Oct 11 00:28.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:28.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:28.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 25 Oct 11 00:32.0 [info] connection_ap_expire_beginning(): Tried for 120 seconds to get a connection to [scrubbed]:443. Giving up. (waiting for circuit) Oct 11 00:32.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:32.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 24 Oct 11 00:32.0 [info] connection_free_(): Freeing linked Socks connection [waiting for circuit] with 145 bytes on inbuf, 0 on outbuf. Oct 11 00:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:32.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 23 Oct 11 00:32.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 00:32.0 [info] connection_dir_client_reached_eof(): 'fetch' response not all here, but we're at eof. Closing. Oct 11 00:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:32.0 [info] update_consensus_networkstatus_downloads(): Launching microdesc networkstatus consensus download. Oct 11 00:32.0 [info] directory_pick_generic_dirserver(): No router found for consensus network-status fetch; falling back to dirserver list. Oct 11 00:32.0 [debug] directory_initiate_command_rend(): anonymized 0, use_begindir 1. Oct 11 00:32.0 [debug] directory_initiate_command_rend(): Initiating consensus network-status fetch Oct 11 00:32.0 [info] connection_ap_make_link(): Making internal direct tunnel to [scrubbed]:443 ... Oct 11 00:32.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address (Tor_internal), n_conns 23. Oct 11 00:32.0 [debug] circuit_get_open_circ_or_launch(): considering 1, $7EA6EAD6FD83083C538F44038BBFA077587DD755 Oct 11 00:32.0 [debug] onion_pick_cpath_exit(): Launching a one-hop circuit for dir tunnel. Oct 11 00:32.0 [info] onion_pick_cpath_exit(): Using requested exit node '$7EA6EAD6FD83083C538F44038BBFA077587DD7557EA6EAD6FD83083C538 at 194.109.206.212' Oct 11 00:32.0 [debug] onion_extend_cpath(): Path is 0 long; we want 1 Oct 11 00:32.0 [debug] onion_extend_cpath(): Chose router $7EA6EAD6FD83083C538F44038BBFA077587DD7557EA6EAD6FD83083C538 at 194.109.206.212 for hop 1 (exit is 7EA6EAD6FD83083C538F44038BBFA077587DD755) Oct 11 00:32.0 [debug] onion_extend_cpath(): Path is complete: 1 steps long Oct 11 00:32.0 [debug] circuit_handle_first_hop(): Looking for firsthop '194.109.206.212:443' Oct 11 00:32.0 [info] circuit_handle_first_hop(): Next router is [scrubbed]: Not connected. Connecting. Oct 11 00:32.0 [debug] channel_tls_connect(): In channel_tls_connect() for channel 0x569452f8 (global id 5) Oct 11 00:32.0 [debug] channel_tls_connect(): Marking new outgoing channel 5 at 0x569452f8 as remote Oct 11 00:32.0 [debug] channel_set_identity_digest(): Setting remote endpoint digest on channel 0x569452f8 with global ID 5 to digest 7EA6EAD6FD83083C538F44038BBFA077587DD755 Oct 11 00:32.0 [debug] connection_connect(): Connecting to [scrubbed]:443. Oct 11 00:32.0 [debug] connection_connect(): Connection to [scrubbed]:443 in progress (sock 14). Oct 11 00:32.0 [debug] connection_add_impl(): new conn type OR, socket 14, address 194.109.206.212, n_conns 24. Oct 11 00:32.0 [debug] channel_tls_connect(): Got orconn 0x5687cb60 for channel with global id 5 Oct 11 00:32.0 [debug] channel_register(): Registering channel 0x569452f8 (ID 5) in state opening (1) with digest 7EA6EAD6FD83083C538F44038BBFA077587DD755 Oct 11 00:32.0 [debug] channel_add_to_digest_map(): Added channel 0x569452f8 (global ID 5) to identity map in state opening (1) with digest 7EA6EAD6FD83083C538F44038BBFA077587DD755 Oct 11 00:32.0 [debug] channel_set_cell_handlers(): Setting cell_handler callback for channel 0x569452f8 to 0x54b791a8 Oct 11 00:32.0 [debug] channel_set_cell_handlers(): Setting var_cell_handler callback for channel 0x569452f8 to 0x54b7865c Oct 11 00:32.0 [debug] circuit_handle_first_hop(): connecting in progress (or finished). Good. Oct 11 00:32.0 [info] connection_ap_make_link(): ... application connection created and linked. Oct 11 00:32.0 [debug] connection_add_impl(): new conn type Directory, socket -1, address 194.109.206.212, n_conns 25. Oct 11 00:32.0 [info] directory_send_command(): Downloading consensus from 194.109.206.212:443 using /tor/status-vote/current/consensus-microdesc/0232AF+14C131+23D15D+49015F+D586D1+E8A9C4+ED03BB+EFCBE7.z Oct 11 00:32.0 [debug] connection_remove(): removing socket -1 (type Directory), n_conns now 25 Oct 11 00:32.0 [info] connection_free_(): Freeing linked Directory connection [client reading] with 0 bytes on inbuf, 0 on outbuf. Oct 11 00:32.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 00:32.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 00:32.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 00:32.0 [debug] connection_dir_finished_flushing(): client finished sending command. Oct 11 00:32.0 [debug] conn_write_callback(): socket 14 wants to write. Oct 11 00:32.0 [debug] connection_or_finished_connecting(): OR connect() to router at 194.109.206.212:443 finished. Oct 11 00:32.0 [debug] connection_tls_start_handshake(): starting TLS handshake on fd 14 Oct 11 00:32.0 [debug] tor_tls_handshake(): About to call SSL_connect on 0x5687f9b8 (before/accept initialization) Oct 11 00:32.0 [debug] tor_tls_debug_state_callback(): SSL 0x56945098 is now in state before/connect initialization [type=16,val=1]. Oct 11 00:32.0 [debug] tor_tls_debug_state_callback(): SSL 0x56945098 is now in state before/connect initialization [type=4097,val=1]. Oct 11 00:32.0 [debug] tor_tls_debug_state_callback(): SSL 0x56945098 is now in state SSLv2/v3 write client hello A [type=4097,val=1]. Oct 11 00:32.0 [debug] tor_tls_debug_state_callback(): SSL 0x56945098 is now in state SSLv2/v3 read server hello A [type=4098,val=-1]. Oct 11 00:32.0 [debug] tor_tls_handshake(): After call, 0x5687f9b8 was in state SSLv2/v3 read server hello A Oct 11 00:32.0 [debug] connection_tls_continue_handshake(): wanted read Oct 11 00:32.0 [debug] tor_tls_handshake(): About to call SSL_connect on 0x5687f9b8 (SSLv2/v3 read server hello A) Oct 11 00:32.0 [debug] tor_tls_debug_state_callback(): SSL 0x56945098 is now in state SSLv2/v3 read server hello A [type=4098,val=-1]. Oct 11 00:32.0 [debug] connection_tls_continue_handshake(): wanted read Oct 11 00:39.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:39.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:39.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 24 Oct 11 00:40.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:40.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:40.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 23 Oct 11 00:41.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:41.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:41.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 22 Oct 11 00:43.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:43.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:43.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 21 Oct 11 00:47.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:47.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:47.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 20 Oct 11 00:54.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:54.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:54.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 19 Oct 11 00:55.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:55.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:55.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 18 Oct 11 00:56.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:56.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:56.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 17 Oct 11 00:58.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 00:58.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 00:58.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 16 Oct 11 01:02.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 01:02.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 01:02.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 15 Oct 11 01:08.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 01:08.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 01:08.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 14 Oct 11 01:09.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 01:09.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 01:09.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 13 Oct 11 01:10.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 01:10.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 01:10.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 12 Oct 11 01:12.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 01:12.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 01:12.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 11 Oct 11 01:13.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:80. Giving up. (waiting for circuit) Oct 11 01:13.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 4). Oct 11 01:13.0 [debug] connection_remove(): removing socket 4 (type Socks), n_conns now 10 Oct 11 01:13.0 [debug] connection_free_(): closing fd 4. Oct 11 01:28.0 [info] run_connection_housekeeping(): Expiring non-open OR connection to fd 15 (128.31.0.39:9101). Oct 11 01:28.0 [debug] connection_mark_for_close_internal_(): Calling connection_mark_for_close_internal_() on an OR conn at ../src/or/connection_or.c:1311 Oct 11 01:28.0 [debug] channel_close_from_lower_layer(): Closing channel 0x5687cc58 (global ID 3) due to lower-layer event Oct 11 01:28.0 [debug] channel_change_state(): Changing state of channel 0x5687cc58 (global ID 3) from "opening" to "closing" Oct 11 01:28.0 [debug] channel_remove_from_digest_map(): Removed channel 0x5687cc58 (global ID 3) from identity map in state closing (4) with digest 9695DFC35FFEB861329B9F1AB04C46397020CE31 Oct 11 01:28.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 15). Oct 11 01:28.0 [debug] circuit_n_chan_done(): chan to NULL/128.31.0.39:9101, status=0 Oct 11 01:28.0 [info] circuit_n_chan_done(): Channel failed; closing circ. Oct 11 01:28.0 [info] circuit_build_failed(): Our circuit died before the first hop with no connection Oct 11 01:28.0 [debug] circuit_increment_failure_count(): n_circuit_failures now 1. Oct 11 01:28.0 [debug] channel_change_state(): Changing state of channel 0x5687cc58 (global ID 3) from "closing" to "closed" Oct 11 01:28.0 [info] connection_or_note_state_when_broken(): Connection died in state 'handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE' Oct 11 01:28.0 [info] control_event_bootstrap_problem(): Problem bootstrapping. Stuck at 10%: Finishing handshake with directory server. (DONE; DONE; count 4; recommendation ignore) Oct 11 01:28.0 [info] 4 connections have failed: Oct 11 01:28.0 [info] 4 connections died in state handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE Oct 11 01:28.0 [debug] connection_remove(): removing socket 15 (type OR), n_conns now 9 Oct 11 01:28.0 [debug] connection_free_(): closing fd 15. Oct 11 01:28.0 [debug] channel_free(): Freeing channel 3 at 0x5687cc58 Oct 11 01:28.0 [debug] channel_clear_remote_end(): Clearing remote endpoint identity on channel 0x5687cc58 with global ID 3 Oct 11 01:28.0 [debug] circuitmux_free(): Freeing cmux at 0x5687cd48 with no queued destroys, the cmux destroy balance was 0, global is 0 Oct 11 02:32.0 [info] connection_ap_expire_beginning(): Tried for 120 seconds to get a connection to [scrubbed]:443. Giving up. (waiting for circuit) Oct 11 02:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 02:32.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 8 Oct 11 02:32.0 [info] connection_free_(): Freeing linked Socks connection [waiting for circuit] with 146 bytes on inbuf, 0 on outbuf. Oct 11 02:32.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 02:32.0 [info] connection_dir_client_reached_eof(): 'fetch' response not all here, but we're at eof. Closing. Oct 11 02:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 02:32.0 [debug] connection_remove(): removing socket -1 (type Directory), n_conns now 7 Oct 11 02:32.0 [info] connection_free_(): Freeing linked Directory connection [client reading] with 0 bytes on inbuf, 0 on outbuf. Oct 11 03:32.0 [info] run_connection_housekeeping(): Expiring non-open OR connection to fd 18 (199.254.238.52:443). Oct 11 03:32.0 [debug] connection_mark_for_close_internal_(): Calling connection_mark_for_close_internal_() on an OR conn at ../src/or/connection_or.c:1311 Oct 11 03:32.0 [debug] channel_close_from_lower_layer(): Closing channel 0x569b8af8 (global ID 4) due to lower-layer event Oct 11 03:32.0 [debug] channel_change_state(): Changing state of channel 0x569b8af8 (global ID 4) from "opening" to "closing" Oct 11 03:32.0 [debug] channel_remove_from_digest_map(): Removed channel 0x569b8af8 (global ID 4) from identity map in state closing (4) with digest 74A910646BCEEFBCD2E874FC1DC997430F968145 Oct 11 03:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 18). Oct 11 03:32.0 [debug] circuit_n_chan_done(): chan to NULL/199.254.238.52:443, status=0 Oct 11 03:32.0 [info] circuit_n_chan_done(): Channel failed; closing circ. Oct 11 03:32.0 [info] circuit_build_failed(): Our circuit died before the first hop with no connection Oct 11 03:32.0 [debug] circuit_increment_failure_count(): n_circuit_failures now 1. Oct 11 03:32.0 [debug] channel_change_state(): Changing state of channel 0x569b8af8 (global ID 4) from "closing" to "closed" Oct 11 03:32.0 [info] connection_or_note_state_when_broken(): Connection died in state 'handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE' Oct 11 03:32.0 [info] control_event_bootstrap_problem(): Problem bootstrapping. Stuck at 10%: Finishing handshake with directory server. (DONE; DONE; count 5; recommendation ignore) Oct 11 03:32.0 [info] 5 connections have failed: Oct 11 03:32.0 [info] 5 connections died in state handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE Oct 11 03:32.0 [debug] connection_remove(): removing socket 18 (type OR), n_conns now 6 Oct 11 03:32.0 [debug] connection_free_(): closing fd 18. Oct 11 03:32.0 [debug] channel_free(): Freeing channel 4 at 0x569b8af8 Oct 11 03:32.0 [debug] channel_clear_remote_end(): Clearing remote endpoint identity on channel 0x569b8af8 with global ID 4 Oct 11 03:32.0 [debug] circuitmux_free(): Freeing cmux at 0x569b8be8 with no queued destroys, the cmux destroy balance was 0, global is 0 Oct 11 03:40.0 [info] update_consensus_networkstatus_downloads(): Launching microdesc networkstatus consensus download. Oct 11 03:40.0 [info] directory_pick_generic_dirserver(): No router found for consensus network-status fetch; falling back to dirserver list. Oct 11 03:40.0 [debug] directory_initiate_command_rend(): anonymized 0, use_begindir 1. Oct 11 03:40.0 [debug] directory_initiate_command_rend(): Initiating consensus network-status fetch Oct 11 03:40.0 [info] connection_ap_make_link(): Making internal direct tunnel to [scrubbed]:443 ... Oct 11 03:40.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address (Tor_internal), n_conns 6. Oct 11 03:40.0 [debug] circuit_get_open_circ_or_launch(): considering 1, $847B1F850344D7876491A54892F904934E4EB85D Oct 11 03:40.0 [debug] onion_pick_cpath_exit(): Launching a one-hop circuit for dir tunnel. Oct 11 03:40.0 [info] onion_pick_cpath_exit(): Using requested exit node '$847B1F850344D7876491A54892F904934E4EB85D847B1F850344D787649 at 86.59.21.38' Oct 11 03:40.0 [debug] onion_extend_cpath(): Path is 0 long; we want 1 Oct 11 03:40.0 [debug] onion_extend_cpath(): Chose router $847B1F850344D7876491A54892F904934E4EB85D847B1F850344D787649 at 86.59.21.38 for hop 1 (exit is 847B1F850344D7876491A54892F904934E4EB85D) Oct 11 03:40.0 [debug] onion_extend_cpath(): Path is complete: 1 steps long Oct 11 03:40.0 [debug] circuit_handle_first_hop(): Looking for firsthop '86.59.21.38:443' Oct 11 03:40.0 [info] circuit_handle_first_hop(): Next router is [scrubbed]: Not connected. Connecting. Oct 11 03:40.0 [debug] channel_tls_connect(): In channel_tls_connect() for channel 0x5687edb8 (global id 6) Oct 11 03:40.0 [debug] channel_tls_connect(): Marking new outgoing channel 6 at 0x5687edb8 as remote Oct 11 03:40.0 [debug] channel_set_identity_digest(): Setting remote endpoint digest on channel 0x5687edb8 with global ID 6 to digest 847B1F850344D7876491A54892F904934E4EB85D Oct 11 03:40.0 [debug] connection_connect(): Connecting to [scrubbed]:443. Oct 11 03:40.0 [debug] connection_connect(): Connection to [scrubbed]:443 in progress (sock 4). Oct 11 03:40.0 [debug] connection_add_impl(): new conn type OR, socket 4, address 86.59.21.38, n_conns 7. Oct 11 03:40.0 [debug] channel_tls_connect(): Got orconn 0x5687e960 for channel with global id 6 Oct 11 03:40.0 [debug] channel_register(): Registering channel 0x5687edb8 (ID 6) in state opening (1) with digest 847B1F850344D7876491A54892F904934E4EB85D Oct 11 03:40.0 [debug] channel_add_to_digest_map(): Added channel 0x5687edb8 (global ID 6) to identity map in state opening (1) with digest 847B1F850344D7876491A54892F904934E4EB85D Oct 11 03:40.0 [debug] channel_set_cell_handlers(): Setting cell_handler callback for channel 0x5687edb8 to 0x54b791a8 Oct 11 03:40.0 [debug] channel_set_cell_handlers(): Setting var_cell_handler callback for channel 0x5687edb8 to 0x54b7865c Oct 11 03:40.0 [debug] circuit_handle_first_hop(): connecting in progress (or finished). Good. Oct 11 03:40.0 [info] connection_ap_make_link(): ... application connection created and linked. Oct 11 03:40.0 [debug] connection_add_impl(): new conn type Directory, socket -1, address 86.59.21.38, n_conns 8. Oct 11 03:40.0 [info] directory_send_command(): Downloading consensus from 86.59.21.38:443 using /tor/status-vote/current/consensus-microdesc/0232AF+14C131+23D15D+49015F+D586D1+E8A9C4+ED03BB+EFCBE7.z Oct 11 03:40.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 03:40.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 03:40.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 03:40.0 [debug] connection_dir_finished_flushing(): client finished sending command. Oct 11 05:32.0 [info] run_connection_housekeeping(): Expiring non-open OR connection to fd 14 (194.109.206.212:443). Oct 11 05:32.0 [debug] connection_mark_for_close_internal_(): Calling connection_mark_for_close_internal_() on an OR conn at ../src/or/connection_or.c:1311 Oct 11 05:32.0 [debug] channel_close_from_lower_layer(): Closing channel 0x569452f8 (global ID 5) due to lower-layer event Oct 11 05:32.0 [debug] channel_change_state(): Changing state of channel 0x569452f8 (global ID 5) from "opening" to "closing" Oct 11 05:32.0 [debug] channel_remove_from_digest_map(): Removed channel 0x569452f8 (global ID 5) from identity map in state closing (4) with digest 7EA6EAD6FD83083C538F44038BBFA077587DD755 Oct 11 05:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 14). Oct 11 05:32.0 [debug] circuit_n_chan_done(): chan to NULL/194.109.206.212:443, status=0 Oct 11 05:32.0 [info] circuit_n_chan_done(): Channel failed; closing circ. Oct 11 05:32.0 [info] circuit_build_failed(): Our circuit died before the first hop with no connection Oct 11 05:32.0 [debug] circuit_increment_failure_count(): n_circuit_failures now 1. Oct 11 05:32.0 [debug] channel_change_state(): Changing state of channel 0x569452f8 (global ID 5) from "closing" to "closed" Oct 11 05:32.0 [info] connection_or_note_state_when_broken(): Connection died in state 'handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE' Oct 11 05:32.0 [info] control_event_bootstrap_problem(): Problem bootstrapping. Stuck at 10%: Finishing handshake with directory server. (DONE; DONE; count 6; recommendation ignore) Oct 11 05:32.0 [info] 6 connections have failed: Oct 11 05:32.0 [info] 6 connections died in state handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE Oct 11 05:32.0 [debug] connection_remove(): removing socket 14 (type OR), n_conns now 8 Oct 11 05:32.0 [debug] connection_free_(): closing fd 14. Oct 11 05:32.0 [debug] channel_free(): Freeing channel 5 at 0x569452f8 Oct 11 05:32.0 [debug] channel_clear_remote_end(): Clearing remote endpoint identity on channel 0x569452f8 with global ID 5 Oct 11 05:32.0 [debug] circuitmux_free(): Freeing cmux at 0x569a7928 with no queued destroys, the cmux destroy balance was 0, global is 0 Oct 11 05:40.0 [info] connection_ap_expire_beginning(): Tried for 120 seconds to get a connection to [scrubbed]:443. Giving up. (waiting for circuit) Oct 11 05:40.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 05:40.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 7 Oct 11 05:40.0 [info] connection_free_(): Freeing linked Socks connection [waiting for circuit] with 142 bytes on inbuf, 0 on outbuf. Oct 11 05:40.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 05:40.0 [info] connection_dir_client_reached_eof(): 'fetch' response not all here, but we're at eof. Closing. Oct 11 05:40.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 05:40.0 [debug] connection_remove(): removing socket -1 (type Directory), n_conns now 6 Oct 11 05:40.0 [info] connection_free_(): Freeing linked Directory connection [client reading] with 0 bytes on inbuf, 0 on outbuf. Oct 11 05:48.0 [debug] conn_read_callback(): socket 4 wants to read. Oct 11 05:48.0 [info] control_event_bootstrap_problem(): Problem bootstrapping. Stuck at 10%: Finishing handshake with directory server. (Connection timed out; TIMEOUT; count 7; recommendation ignore) Oct 11 05:48.0 [info] 6 connections have failed: Oct 11 05:48.0 [info] 6 connections died in state handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE Oct 11 05:48.0 [debug] channel_close_for_error(): Closing channel 0x5687edb8 due to lower-layer error Oct 11 05:48.0 [debug] channel_change_state(): Changing state of channel 0x5687edb8 (global ID 6) from "opening" to "closing" Oct 11 05:48.0 [debug] channel_remove_from_digest_map(): Removed channel 0x5687edb8 (global ID 6) from identity map in state closing (4) with digest 847B1F850344D7876491A54892F904934E4EB85D Oct 11 05:48.0 [debug] connection_mark_for_close_internal_(): Calling connection_mark_for_close_internal_() on an OR conn at ../src/or/connection.c:3184 Oct 11 05:48.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 05:48.0 [debug] circuit_n_chan_done(): chan to NULL/86.59.21.38:443, status=0 Oct 11 05:48.0 [info] circuit_n_chan_done(): Channel failed; closing circ. Oct 11 05:48.0 [info] circuit_build_failed(): Our circuit died before the first hop with no connection Oct 11 05:48.0 [debug] circuit_increment_failure_count(): n_circuit_failures now 2. Oct 11 05:48.0 [debug] channel_change_state(): Changing state of channel 0x5687edb8 (global ID 6) from "closing" to "channel error" Oct 11 05:48.0 [info] connection_or_note_state_when_broken(): Connection died in state 'connect()ing with SSL state (No SSL object)' Oct 11 05:48.0 [debug] connection_remove(): removing socket -1 (type OR), n_conns now 5 Oct 11 05:48.0 [debug] channel_free(): Freeing channel 6 at 0x5687edb8 Oct 11 05:48.0 [debug] channel_clear_remote_end(): Clearing remote endpoint identity on channel 0x5687edb8 with global ID 6 Oct 11 05:48.0 [debug] circuitmux_free(): Freeing cmux at 0x5687eea8 with no queued destroys, the cmux destroy balance was 0, global is 0 Oct 11 10:47.0 [info] update_consensus_networkstatus_downloads(): Launching microdesc networkstatus consensus download. Oct 11 10:47.0 [info] directory_pick_generic_dirserver(): No router found for consensus network-status fetch; falling back to dirserver list. Oct 11 10:47.0 [debug] directory_initiate_command_rend(): anonymized 0, use_begindir 1. Oct 11 10:47.0 [debug] directory_initiate_command_rend(): Initiating consensus network-status fetch Oct 11 10:47.0 [info] connection_ap_make_link(): Making internal direct tunnel to [scrubbed]:80 ... Oct 11 10:47.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address (Tor_internal), n_conns 5. Oct 11 10:47.0 [debug] circuit_get_open_circ_or_launch(): considering 1, $BD6A829255CB08E66FBE7D3748363586E46B3810 Oct 11 10:47.0 [debug] onion_pick_cpath_exit(): Launching a one-hop circuit for dir tunnel. Oct 11 10:47.0 [info] onion_pick_cpath_exit(): Using requested exit node '$BD6A829255CB08E66FBE7D3748363586E46B3810BD6A829255CB08E66FB at 171.25.193.9' Oct 11 10:47.0 [debug] onion_extend_cpath(): Path is 0 long; we want 1 Oct 11 10:47.0 [debug] onion_extend_cpath(): Chose router $BD6A829255CB08E66FBE7D3748363586E46B3810BD6A829255CB08E66FB at 171.25.193.9 for hop 1 (exit is BD6A829255CB08E66FBE7D3748363586E46B3810) Oct 11 10:47.0 [debug] onion_extend_cpath(): Path is complete: 1 steps long Oct 11 10:47.0 [debug] circuit_handle_first_hop(): Looking for firsthop '171.25.193.9:80' Oct 11 10:47.0 [info] circuit_handle_first_hop(): Next router is [scrubbed]: Not connected. Connecting. Oct 11 10:47.0 [debug] channel_tls_connect(): In channel_tls_connect() for channel 0x5699a5e8 (global id 7) Oct 11 10:47.0 [debug] channel_tls_connect(): Marking new outgoing channel 7 at 0x5699a5e8 as remote Oct 11 10:47.0 [debug] channel_set_identity_digest(): Setting remote endpoint digest on channel 0x5699a5e8 with global ID 7 to digest BD6A829255CB08E66FBE7D3748363586E46B3810 Oct 11 10:47.0 [debug] connection_connect(): Connecting to [scrubbed]:80. Oct 11 10:47.0 [debug] connection_connect(): Connection to [scrubbed]:80 in progress (sock 4). Oct 11 10:47.0 [debug] connection_add_impl(): new conn type OR, socket 4, address 171.25.193.9, n_conns 6. Oct 11 10:47.0 [debug] channel_tls_connect(): Got orconn 0x5687e960 for channel with global id 7 Oct 11 10:47.0 [debug] channel_register(): Registering channel 0x5699a5e8 (ID 7) in state opening (1) with digest BD6A829255CB08E66FBE7D3748363586E46B3810 Oct 11 10:47.0 [debug] channel_add_to_digest_map(): Added channel 0x5699a5e8 (global ID 7) to identity map in state opening (1) with digest BD6A829255CB08E66FBE7D3748363586E46B3810 Oct 11 10:47.0 [debug] channel_set_cell_handlers(): Setting cell_handler callback for channel 0x5699a5e8 to 0x54b791a8 Oct 11 10:47.0 [debug] channel_set_cell_handlers(): Setting var_cell_handler callback for channel 0x5699a5e8 to 0x54b7865c Oct 11 10:47.0 [debug] circuit_handle_first_hop(): connecting in progress (or finished). Good. Oct 11 10:47.0 [info] connection_ap_make_link(): ... application connection created and linked. Oct 11 10:47.0 [debug] connection_add_impl(): new conn type Directory, socket -1, address 171.25.193.9, n_conns 7. Oct 11 10:47.0 [info] directory_send_command(): Downloading consensus from 171.25.193.9 using /tor/status-vote/current/consensus-microdesc/0232AF+14C131+23D15D+49015F+D586D1+E8A9C4+ED03BB+EFCBE7.z Oct 11 10:47.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 10:47.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 10:47.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 10:47.0 [debug] connection_dir_finished_flushing(): client finished sending command. Oct 11 10:47.0 [debug] conn_write_callback(): socket 4 wants to write. Oct 11 10:47.0 [debug] connection_or_finished_connecting(): OR connect() to router at 171.25.193.9:80 finished. Oct 11 10:47.0 [debug] connection_tls_start_handshake(): starting TLS handshake on fd 4 Oct 11 10:47.0 [debug] tor_tls_handshake(): About to call SSL_connect on 0x569b1670 (before/accept initialization) Oct 11 10:47.0 [debug] tor_tls_debug_state_callback(): SSL 0x569c9328 is now in state before/connect initialization [type=16,val=1]. Oct 11 10:47.0 [debug] tor_tls_debug_state_callback(): SSL 0x569c9328 is now in state before/connect initialization [type=4097,val=1]. Oct 11 10:48.0 [debug] tor_tls_debug_state_callback(): SSL 0x569c9328 is now in state SSLv2/v3 write client hello A [type=4097,val=1]. Oct 11 10:48.0 [debug] tor_tls_debug_state_callback(): SSL 0x569c9328 is now in state SSLv2/v3 read server hello A [type=4098,val=-1]. Oct 11 10:48.0 [debug] tor_tls_handshake(): After call, 0x569b1670 was in state SSLv2/v3 read server hello A Oct 11 10:48.0 [debug] connection_tls_continue_handshake(): wanted read Oct 11 10:48.0 [debug] tor_tls_handshake(): About to call SSL_connect on 0x569b1670 (SSLv2/v3 read server hello A) Oct 11 10:48.0 [debug] tor_tls_debug_state_callback(): SSL 0x569c9328 is now in state SSLv2/v3 read server hello A [type=4098,val=-1]. Oct 11 10:48.0 [debug] connection_tls_continue_handshake(): wanted read Oct 11 12:47.0 [info] connection_ap_expire_beginning(): Tried for 120 seconds to get a connection to [scrubbed]:80. Giving up. (waiting for circuit) Oct 11 12:47.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 12:47.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 7 Oct 11 12:47.0 [info] connection_free_(): Freeing linked Socks connection [waiting for circuit] with 139 bytes on inbuf, 0 on outbuf. Oct 11 12:47.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 12:47.0 [info] connection_dir_client_reached_eof(): 'fetch' response not all here, but we're at eof. Closing. Oct 11 12:47.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 12:47.0 [debug] connection_remove(): removing socket -1 (type Directory), n_conns now 6 Oct 11 12:47.0 [info] connection_free_(): Freeing linked Directory connection [client reading] with 0 bytes on inbuf, 0 on outbuf. Oct 11 15:47.0 [info] run_connection_housekeeping(): Expiring non-open OR connection to fd 4 (171.25.193.9:80). Oct 11 15:47.0 [debug] connection_mark_for_close_internal_(): Calling connection_mark_for_close_internal_() on an OR conn at ../src/or/connection_or.c:1311 Oct 11 15:47.0 [debug] channel_close_from_lower_layer(): Closing channel 0x5699a5e8 (global ID 7) due to lower-layer event Oct 11 15:47.0 [debug] channel_change_state(): Changing state of channel 0x5699a5e8 (global ID 7) from "opening" to "closing" Oct 11 15:47.0 [debug] channel_remove_from_digest_map(): Removed channel 0x5699a5e8 (global ID 7) from identity map in state closing (4) with digest BD6A829255CB08E66FBE7D3748363586E46B3810 Oct 11 15:47.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 4). Oct 11 15:47.0 [debug] circuit_n_chan_done(): chan to NULL/171.25.193.9:80, status=0 Oct 11 15:47.0 [info] circuit_n_chan_done(): Channel failed; closing circ. Oct 11 15:47.0 [info] circuit_build_failed(): Our circuit died before the first hop with no connection Oct 11 15:47.0 [debug] circuit_increment_failure_count(): n_circuit_failures now 1. Oct 11 15:47.0 [debug] channel_change_state(): Changing state of channel 0x5699a5e8 (global ID 7) from "closing" to "closed" Oct 11 15:47.0 [info] connection_or_note_state_when_broken(): Connection died in state 'handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE' Oct 11 15:47.0 [info] control_event_bootstrap_problem(): Problem bootstrapping. Stuck at 10%: Finishing handshake with directory server. (DONE; DONE; count 8; recommendation ignore) Oct 11 15:47.0 [info] 8 connections have failed: Oct 11 15:47.0 [info] 7 connections died in state handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE Oct 11 15:47.0 [info] 1 connections died in state connect()ing with SSL state (No SSL object) Oct 11 15:47.0 [debug] connection_remove(): removing socket 4 (type OR), n_conns now 5 Oct 11 15:47.0 [debug] connection_free_(): closing fd 4. Oct 11 15:47.0 [debug] channel_free(): Freeing channel 7 at 0x5699a5e8 Oct 11 15:47.0 [debug] channel_clear_remote_end(): Clearing remote endpoint identity on channel 0x5699a5e8 with global ID 7 Oct 11 15:47.0 [debug] circuitmux_free(): Freeing cmux at 0x569a8730 with no queued destroys, the cmux destroy balance was 0, global is 0 Oct 11 20:32.0 [debug] conn_read_callback(): socket 7 wants to read. Oct 11 20:32.0 [debug] connection_handle_listener_read(): Connection accepted on socket 4 (child of fd 7). Oct 11 20:32.0 [debug] connection_add_impl(): new conn type Socks, socket 4, address 127.0.0.1, n_conns 5. Oct 11 20:32.0 [debug] conn_read_callback(): socket 4 wants to read. Oct 11 20:32.0 [debug] read_to_chunk(): Read 4 bytes. 4 on inbuf. Oct 11 20:32.0 [debug] connection_ap_handshake_process_socks(): entered. Oct 11 20:32.0 [debug] parse_socks(): socks5: accepted method 0 (no authentication) Oct 11 20:32.0 [debug] connection_ap_handshake_process_socks(): socks handshake not all here yet. Oct 11 20:32.0 [debug] connection_ap_handshake_process_socks(): entered. Oct 11 20:32.0 [debug] connection_ap_handshake_process_socks(): socks handshake not all here yet. Oct 11 20:32.0 [debug] conn_write_callback(): socket 4 wants to write. Oct 11 20:32.0 [debug] conn_read_callback(): socket 4 wants to read. Oct 11 20:32.0 [debug] read_to_chunk(): Read 27 bytes. 27 on inbuf. Oct 11 20:32.0 [debug] connection_ap_handshake_process_socks(): entered. Oct 11 20:32.0 [debug] parse_socks(): socks5: checking request Oct 11 20:32.0 [debug] parse_socks(): socks5: fqdn address type Oct 11 20:32.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:443 Oct 11 20:32.0 [notice] Application request when we haven't used client functionality lately. Optimistically trying directory fetches again. Oct 11 20:32.0 [info] update_consensus_networkstatus_downloads(): Launching microdesc networkstatus consensus download. Oct 11 20:32.0 [info] directory_pick_generic_dirserver(): No router found for consensus network-status fetch; falling back to dirserver list. Oct 11 20:32.0 [debug] directory_initiate_command_rend(): anonymized 0, use_begindir 1. Oct 11 20:32.0 [debug] directory_initiate_command_rend(): Initiating consensus network-status fetch Oct 11 20:32.0 [info] connection_ap_make_link(): Making internal direct tunnel to [scrubbed]:9101 ... Oct 11 20:32.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address (Tor_internal), n_conns 6. Oct 11 20:32.0 [debug] circuit_get_open_circ_or_launch(): considering 1, $9695DFC35FFEB861329B9F1AB04C46397020CE31 Oct 11 20:32.0 [debug] onion_pick_cpath_exit(): Launching a one-hop circuit for dir tunnel. Oct 11 20:32.0 [info] onion_pick_cpath_exit(): Using requested exit node '$9695DFC35FFEB861329B9F1AB04C46397020CE319695DFC35FFEB861329 at 128.31.0.39' Oct 11 20:32.0 [debug] onion_extend_cpath(): Path is 0 long; we want 1 Oct 11 20:32.0 [debug] onion_extend_cpath(): Chose router $9695DFC35FFEB861329B9F1AB04C46397020CE319695DFC35FFEB861329 at 128.31.0.39 for hop 1 (exit is 9695DFC35FFEB861329B9F1AB04C46397020CE31) Oct 11 20:32.0 [debug] onion_extend_cpath(): Path is complete: 1 steps long Oct 11 20:32.0 [debug] circuit_handle_first_hop(): Looking for firsthop '128.31.0.39:9101' Oct 11 20:32.0 [info] circuit_handle_first_hop(): Next router is [scrubbed]: Not connected. Connecting. Oct 11 20:32.0 [debug] channel_tls_connect(): In channel_tls_connect() for channel 0x569c9328 (global id 8) Oct 11 20:32.0 [debug] channel_tls_connect(): Marking new outgoing channel 8 at 0x569c9328 as remote Oct 11 20:32.0 [debug] channel_set_identity_digest(): Setting remote endpoint digest on channel 0x569c9328 with global ID 8 to digest 9695DFC35FFEB861329B9F1AB04C46397020CE31 Oct 11 20:32.0 [debug] connection_connect(): Connecting to [scrubbed]:9101. Oct 11 20:32.0 [debug] connection_connect(): Connection to [scrubbed]:9101 in progress (sock 14). Oct 11 20:32.0 [debug] connection_add_impl(): new conn type OR, socket 14, address 128.31.0.39, n_conns 7. Oct 11 20:32.0 [debug] channel_tls_connect(): Got orconn 0x569c9430 for channel with global id 8 Oct 11 20:32.0 [debug] channel_register(): Registering channel 0x569c9328 (ID 8) in state opening (1) with digest 9695DFC35FFEB861329B9F1AB04C46397020CE31 Oct 11 20:32.0 [debug] channel_add_to_digest_map(): Added channel 0x569c9328 (global ID 8) to identity map in state opening (1) with digest 9695DFC35FFEB861329B9F1AB04C46397020CE31 Oct 11 20:32.0 [debug] channel_set_cell_handlers(): Setting cell_handler callback for channel 0x569c9328 to 0x54b791a8 Oct 11 20:32.0 [debug] channel_set_cell_handlers(): Setting var_cell_handler callback for channel 0x569c9328 to 0x54b7865c Oct 11 20:32.0 [debug] circuit_handle_first_hop(): connecting in progress (or finished). Good. Oct 11 20:32.0 [info] connection_ap_make_link(): ... application connection created and linked. Oct 11 20:32.0 [debug] connection_add_impl(): new conn type Directory, socket -1, address 128.31.0.39, n_conns 8. Oct 11 20:32.0 [info] directory_send_command(): Downloading consensus from 128.31.0.39:9101 using /tor/status-vote/current/consensus-microdesc/0232AF+14C131+23D15D+49015F+D586D1+E8A9C4+ED03BB+EFCBE7.z Oct 11 20:32.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 20:32.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 20:32.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 20:32.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 20:32.0 [debug] connection_dir_finished_flushing(): client finished sending command. Oct 11 20:33.0 [debug] conn_write_callback(): socket 14 wants to write. Oct 11 20:33.0 [debug] connection_or_finished_connecting(): OR connect() to router at 128.31.0.39:9101 finished. Oct 11 20:33.0 [debug] connection_tls_start_handshake(): starting TLS handshake on fd 14 Oct 11 20:33.0 [debug] tor_tls_handshake(): About to call SSL_connect on 0x56935f30 (before/accept initialization) Oct 11 20:33.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687fa78 is now in state before/connect initialization [type=16,val=1]. Oct 11 20:33.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687fa78 is now in state before/connect initialization [type=4097,val=1]. Oct 11 20:33.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687fa78 is now in state SSLv2/v3 write client hello A [type=4097,val=1]. Oct 11 20:33.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687fa78 is now in state SSLv2/v3 read server hello A [type=4098,val=-1]. Oct 11 20:33.0 [debug] tor_tls_handshake(): After call, 0x56935f30 was in state SSLv2/v3 read server hello A Oct 11 20:33.0 [debug] connection_tls_continue_handshake(): wanted read Oct 11 20:33.0 [debug] tor_tls_handshake(): About to call SSL_connect on 0x56935f30 (SSLv2/v3 read server hello A) Oct 11 20:33.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687fa78 is now in state SSLv2/v3 read server hello A [type=4098,val=-1]. Oct 11 20:33.0 [debug] connection_tls_continue_handshake(): wanted read Oct 11 22:32.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:443. Giving up. (waiting for circuit) Oct 11 22:32.0 [info] connection_ap_expire_beginning(): Tried for 120 seconds to get a connection to [scrubbed]:9101. Giving up. (waiting for circuit) Oct 11 22:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 4). Oct 11 22:32.0 [debug] connection_remove(): removing socket 4 (type Socks), n_conns now 8 Oct 11 22:32.0 [debug] connection_free_(): closing fd 4. Oct 11 22:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 22:32.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 7 Oct 11 22:32.0 [info] connection_free_(): Freeing linked Socks connection [waiting for circuit] with 143 bytes on inbuf, 0 on outbuf. Oct 11 22:32.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 22:32.0 [info] connection_dir_client_reached_eof(): 'fetch' response not all here, but we're at eof. Closing. Oct 11 22:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 22:32.0 [info] update_consensus_networkstatus_downloads(): Launching microdesc networkstatus consensus download. Oct 11 22:32.0 [info] directory_pick_generic_dirserver(): No router found for consensus network-status fetch; falling back to dirserver list. Oct 11 22:32.0 [debug] directory_initiate_command_rend(): anonymized 0, use_begindir 1. Oct 11 22:32.0 [debug] directory_initiate_command_rend(): Initiating consensus network-status fetch Oct 11 22:32.0 [info] connection_ap_make_link(): Making internal direct tunnel to [scrubbed]:80 ... Oct 11 22:32.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address (Tor_internal), n_conns 7. Oct 11 22:32.0 [debug] circuit_get_open_circ_or_launch(): considering 1, $BD6A829255CB08E66FBE7D3748363586E46B3810 Oct 11 22:32.0 [debug] onion_pick_cpath_exit(): Launching a one-hop circuit for dir tunnel. Oct 11 22:32.0 [info] onion_pick_cpath_exit(): Using requested exit node '$BD6A829255CB08E66FBE7D3748363586E46B3810BD6A829255CB08E66FB at 171.25.193.9' Oct 11 22:32.0 [debug] onion_extend_cpath(): Path is 0 long; we want 1 Oct 11 22:32.0 [debug] onion_extend_cpath(): Chose router $BD6A829255CB08E66FBE7D3748363586E46B3810BD6A829255CB08E66FB at 171.25.193.9 for hop 1 (exit is BD6A829255CB08E66FBE7D3748363586E46B3810) Oct 11 22:32.0 [debug] onion_extend_cpath(): Path is complete: 1 steps long Oct 11 22:32.0 [debug] circuit_handle_first_hop(): Looking for firsthop '171.25.193.9:80' Oct 11 22:32.0 [info] circuit_handle_first_hop(): Next router is [scrubbed]: Not connected. Connecting. Oct 11 22:32.0 [debug] channel_tls_connect(): In channel_tls_connect() for channel 0x569b1390 (global id 9) Oct 11 22:32.0 [debug] channel_tls_connect(): Marking new outgoing channel 9 at 0x569b1390 as remote Oct 11 22:32.0 [debug] channel_set_identity_digest(): Setting remote endpoint digest on channel 0x569b1390 with global ID 9 to digest BD6A829255CB08E66FBE7D3748363586E46B3810 Oct 11 22:32.0 [debug] connection_connect(): Connecting to [scrubbed]:80. Oct 11 22:32.0 [debug] connection_connect(): Connection to [scrubbed]:80 in progress (sock 4). Oct 11 22:32.0 [debug] connection_add_impl(): new conn type OR, socket 4, address 171.25.193.9, n_conns 8. Oct 11 22:32.0 [debug] channel_tls_connect(): Got orconn 0x569b1498 for channel with global id 9 Oct 11 22:32.0 [debug] channel_register(): Registering channel 0x569b1390 (ID 9) in state opening (1) with digest BD6A829255CB08E66FBE7D3748363586E46B3810 Oct 11 22:32.0 [debug] channel_add_to_digest_map(): Added channel 0x569b1390 (global ID 9) to identity map in state opening (1) with digest BD6A829255CB08E66FBE7D3748363586E46B3810 Oct 11 22:32.0 [debug] channel_set_cell_handlers(): Setting cell_handler callback for channel 0x569b1390 to 0x54b791a8 Oct 11 22:32.0 [debug] channel_set_cell_handlers(): Setting var_cell_handler callback for channel 0x569b1390 to 0x54b7865c Oct 11 22:32.0 [debug] circuit_handle_first_hop(): connecting in progress (or finished). Good. Oct 11 22:32.0 [info] connection_ap_make_link(): ... application connection created and linked. Oct 11 22:32.0 [debug] connection_add_impl(): new conn type Directory, socket -1, address 171.25.193.9, n_conns 9. Oct 11 22:32.0 [info] directory_send_command(): Downloading consensus from 171.25.193.9 using /tor/status-vote/current/consensus-microdesc/0232AF+14C131+23D15D+49015F+D586D1+E8A9C4+ED03BB+EFCBE7.z Oct 11 22:32.0 [debug] connection_remove(): removing socket -1 (type Directory), n_conns now 9 Oct 11 22:32.0 [info] connection_free_(): Freeing linked Directory connection [client reading] with 0 bytes on inbuf, 0 on outbuf. Oct 11 22:32.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 22:32.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 22:32.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 22:32.0 [debug] connection_dir_finished_flushing(): client finished sending command. Oct 11 22:32.0 [debug] conn_write_callback(): socket 4 wants to write. Oct 11 22:32.0 [debug] connection_or_finished_connecting(): OR connect() to router at 171.25.193.9:80 finished. Oct 11 22:33.0 [debug] connection_tls_start_handshake(): starting TLS handshake on fd 4 Oct 11 22:33.0 [debug] tor_tls_handshake(): About to call SSL_connect on 0x5687eeb0 (before/accept initialization) Oct 11 22:33.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687d328 is now in state before/connect initialization [type=16,val=1]. Oct 11 22:33.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687d328 is now in state before/connect initialization [type=4097,val=1]. Oct 11 22:33.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687d328 is now in state SSLv2/v3 write client hello A [type=4097,val=1]. Oct 11 22:33.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687d328 is now in state SSLv2/v3 read server hello A [type=4098,val=-1]. Oct 11 22:33.0 [debug] tor_tls_handshake(): After call, 0x5687eeb0 was in state SSLv2/v3 read server hello A Oct 11 22:33.0 [debug] connection_tls_continue_handshake(): wanted read Oct 11 22:33.0 [debug] tor_tls_handshake(): About to call SSL_connect on 0x5687eeb0 (SSLv2/v3 read server hello A) Oct 11 22:33.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687d328 is now in state SSLv2/v3 read server hello A [type=4098,val=-1]. Oct 11 22:33.0 [debug] connection_tls_continue_handshake(): wanted read Oct 11 24:32.0 [info] connection_ap_expire_beginning(): Tried for 120 seconds to get a connection to [scrubbed]:80. Giving up. (waiting for circuit) Oct 11 24:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 24:32.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 8 Oct 11 24:32.0 [info] connection_free_(): Freeing linked Socks connection [waiting for circuit] with 139 bytes on inbuf, 0 on outbuf. Oct 11 24:32.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 24:32.0 [info] connection_dir_client_reached_eof(): 'fetch' response not all here, but we're at eof. Closing. Oct 11 24:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 24:32.0 [debug] connection_remove(): removing socket -1 (type Directory), n_conns now 7 Oct 11 24:32.0 [info] connection_free_(): Freeing linked Directory connection [client reading] with 0 bytes on inbuf, 0 on outbuf. Oct 11 25:31.0 [debug] conn_read_callback(): socket 7 wants to read. Oct 11 25:31.0 [debug] connection_handle_listener_read(): Connection accepted on socket 15 (child of fd 7). Oct 11 25:31.0 [debug] connection_add_impl(): new conn type Socks, socket 15, address 127.0.0.1, n_conns 7. Oct 11 25:31.0 [debug] conn_read_callback(): socket 15 wants to read. Oct 11 25:31.0 [debug] read_to_chunk(): Read 4 bytes. 4 on inbuf. Oct 11 25:31.0 [debug] connection_ap_handshake_process_socks(): entered. Oct 11 25:31.0 [debug] parse_socks(): socks5: accepted method 0 (no authentication) Oct 11 25:31.0 [debug] connection_ap_handshake_process_socks(): socks handshake not all here yet. Oct 11 25:31.0 [debug] connection_ap_handshake_process_socks(): entered. Oct 11 25:31.0 [debug] connection_ap_handshake_process_socks(): socks handshake not all here yet. Oct 11 25:31.0 [debug] conn_write_callback(): socket 15 wants to write. Oct 11 25:31.0 [debug] conn_read_callback(): socket 15 wants to read. Oct 11 25:31.0 [debug] read_to_chunk(): Read 27 bytes. 27 on inbuf. Oct 11 25:31.0 [debug] connection_ap_handshake_process_socks(): entered. Oct 11 25:31.0 [debug] parse_socks(): socks5: checking request Oct 11 25:31.0 [debug] parse_socks(): socks5: fqdn address type Oct 11 25:31.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:443 Oct 11 25:31.0 [notice] Application request when we haven't used client functionality lately. Optimistically trying directory fetches again. Oct 11 25:31.0 [info] update_consensus_networkstatus_downloads(): Launching microdesc networkstatus consensus download. Oct 11 25:31.0 [info] directory_pick_generic_dirserver(): No router found for consensus network-status fetch; falling back to dirserver list. Oct 11 25:31.0 [debug] directory_initiate_command_rend(): anonymized 0, use_begindir 1. Oct 11 25:31.0 [debug] directory_initiate_command_rend(): Initiating consensus network-status fetch Oct 11 25:31.0 [info] connection_ap_make_link(): Making internal direct tunnel to [scrubbed]:443 ... Oct 11 25:31.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address (Tor_internal), n_conns 8. Oct 11 25:31.0 [debug] circuit_get_open_circ_or_launch(): considering 1, $74A910646BCEEFBCD2E874FC1DC997430F968145 Oct 11 25:31.0 [debug] onion_pick_cpath_exit(): Launching a one-hop circuit for dir tunnel. Oct 11 25:31.0 [info] onion_pick_cpath_exit(): Using requested exit node '$74A910646BCEEFBCD2E874FC1DC997430F96814574A910646BCEEFBCD2E at 199.254.238.52' Oct 11 25:31.0 [debug] onion_extend_cpath(): Path is 0 long; we want 1 Oct 11 25:31.0 [debug] onion_extend_cpath(): Chose router $74A910646BCEEFBCD2E874FC1DC997430F96814574A910646BCEEFBCD2E at 199.254.238.52 for hop 1 (exit is 74A910646BCEEFBCD2E874FC1DC997430F968145) Oct 11 25:31.0 [debug] onion_extend_cpath(): Path is complete: 1 steps long Oct 11 25:31.0 [debug] circuit_handle_first_hop(): Looking for firsthop '199.254.238.52:443' Oct 11 25:31.0 [info] circuit_handle_first_hop(): Next router is [scrubbed]: Not connected. Connecting. Oct 11 25:31.0 [debug] channel_tls_connect(): In channel_tls_connect() for channel 0x569b1a70 (global id 10) Oct 11 25:31.0 [debug] channel_tls_connect(): Marking new outgoing channel 10 at 0x569b1a70 as remote Oct 11 25:31.0 [debug] channel_set_identity_digest(): Setting remote endpoint digest on channel 0x569b1a70 with global ID 10 to digest 74A910646BCEEFBCD2E874FC1DC997430F968145 Oct 11 25:31.0 [debug] connection_connect(): Connecting to [scrubbed]:443. Oct 11 25:31.0 [debug] connection_connect(): Connection to [scrubbed]:443 in progress (sock 16). Oct 11 25:31.0 [debug] connection_add_impl(): new conn type OR, socket 16, address 199.254.238.52, n_conns 9. Oct 11 25:31.0 [debug] channel_tls_connect(): Got orconn 0x569b1ba0 for channel with global id 10 Oct 11 25:31.0 [debug] channel_register(): Registering channel 0x569b1a70 (ID 10) in state opening (1) with digest 74A910646BCEEFBCD2E874FC1DC997430F968145 Oct 11 25:31.0 [debug] channel_add_to_digest_map(): Added channel 0x569b1a70 (global ID 10) to identity map in state opening (1) with digest 74A910646BCEEFBCD2E874FC1DC997430F968145 Oct 11 25:31.0 [debug] channel_set_cell_handlers(): Setting cell_handler callback for channel 0x569b1a70 to 0x54b791a8 Oct 11 25:31.0 [debug] channel_set_cell_handlers(): Setting var_cell_handler callback for channel 0x569b1a70 to 0x54b7865c Oct 11 25:31.0 [debug] circuit_handle_first_hop(): connecting in progress (or finished). Good. Oct 11 25:31.0 [info] connection_ap_make_link(): ... application connection created and linked. Oct 11 25:31.0 [debug] connection_add_impl(): new conn type Directory, socket -1, address 199.254.238.52, n_conns 10. Oct 11 25:31.0 [info] directory_send_command(): Downloading consensus from 199.254.238.52:443 using /tor/status-vote/current/consensus-microdesc/0232AF+14C131+23D15D+49015F+D586D1+E8A9C4+ED03BB+EFCBE7.z Oct 11 25:31.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 25:31.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 25:31.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 25:31.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 25:31.0 [debug] connection_dir_finished_flushing(): client finished sending command. Oct 11 25:31.0 [debug] conn_write_callback(): socket 16 wants to write. Oct 11 25:31.0 [debug] connection_or_finished_connecting(): OR connect() to router at 199.254.238.52:443 finished. Oct 11 25:31.0 [debug] connection_tls_start_handshake(): starting TLS handshake on fd 16 Oct 11 25:31.0 [debug] tor_tls_handshake(): About to call SSL_connect on 0x56999840 (before/accept initialization) Oct 11 25:31.0 [debug] tor_tls_debug_state_callback(): SSL 0x569a71d8 is now in state before/connect initialization [type=16,val=1]. Oct 11 25:31.0 [debug] tor_tls_debug_state_callback(): SSL 0x569a71d8 is now in state before/connect initialization [type=4097,val=1]. Oct 11 25:31.0 [debug] tor_tls_debug_state_callback(): SSL 0x569a71d8 is now in state SSLv2/v3 write client hello A [type=4097,val=1]. Oct 11 25:31.0 [debug] tor_tls_debug_state_callback(): SSL 0x569a71d8 is now in state SSLv2/v3 read server hello A [type=4098,val=-1]. Oct 11 25:31.0 [debug] tor_tls_handshake(): After call, 0x56999840 was in state SSLv2/v3 read server hello A Oct 11 25:31.0 [debug] connection_tls_continue_handshake(): wanted read Oct 11 25:31.0 [debug] tor_tls_handshake(): About to call SSL_connect on 0x56999840 (SSLv2/v3 read server hello A) Oct 11 25:31.0 [debug] tor_tls_debug_state_callback(): SSL 0x569a71d8 is now in state SSLv2/v3 read server hello A [type=4098,val=-1]. Oct 11 25:31.0 [debug] connection_tls_continue_handshake(): wanted read Oct 11 25:32.0 [info] run_connection_housekeeping(): Expiring non-open OR connection to fd 14 (128.31.0.39:9101). Oct 11 25:32.0 [debug] connection_mark_for_close_internal_(): Calling connection_mark_for_close_internal_() on an OR conn at ../src/or/connection_or.c:1311 Oct 11 25:32.0 [debug] channel_close_from_lower_layer(): Closing channel 0x569c9328 (global ID 8) due to lower-layer event Oct 11 25:32.0 [debug] channel_change_state(): Changing state of channel 0x569c9328 (global ID 8) from "opening" to "closing" Oct 11 25:32.0 [debug] channel_remove_from_digest_map(): Removed channel 0x569c9328 (global ID 8) from identity map in state closing (4) with digest 9695DFC35FFEB861329B9F1AB04C46397020CE31 Oct 11 25:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 14). Oct 11 25:32.0 [debug] circuit_n_chan_done(): chan to NULL/128.31.0.39:9101, status=0 Oct 11 25:32.0 [info] circuit_n_chan_done(): Channel failed; closing circ. Oct 11 25:32.0 [info] circuit_build_failed(): Our circuit died before the first hop with no connection Oct 11 25:32.0 [debug] circuit_increment_failure_count(): n_circuit_failures now 1. Oct 11 25:32.0 [debug] channel_change_state(): Changing state of channel 0x569c9328 (global ID 8) from "closing" to "closed" Oct 11 25:32.0 [info] connection_or_note_state_when_broken(): Connection died in state 'handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE' Oct 11 25:32.0 [info] control_event_bootstrap_problem(): Problem bootstrapping. Stuck at 10%: Finishing handshake with directory server. (DONE; DONE; count 9; recommendation ignore) Oct 11 25:32.0 [info] 9 connections have failed: Oct 11 25:32.0 [info] 8 connections died in state handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE Oct 11 25:32.0 [info] 1 connections died in state connect()ing with SSL state (No SSL object) Oct 11 25:32.0 [debug] connection_remove(): removing socket 14 (type OR), n_conns now 10 Oct 11 25:32.0 [debug] connection_free_(): closing fd 14. Oct 11 25:32.0 [debug] channel_free(): Freeing channel 8 at 0x569c9328 Oct 11 25:32.0 [debug] channel_clear_remote_end(): Clearing remote endpoint identity on channel 0x569c9328 with global ID 8 Oct 11 25:32.0 [debug] circuitmux_free(): Freeing cmux at 0x5687c9b0 with no queued destroys, the cmux destroy balance was 0, global is 0 Oct 11 26:41.0 [debug] conn_read_callback(): socket 15 wants to read. Oct 11 26:41.0 [debug] read_to_chunk(): Encountered eof on fd 15 Oct 11 26:41.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 26:41.0 [info] connection_edge_reached_eof(): conn (fd 15) reached eof. Closing. Oct 11 26:41.0 [debug] connection_edge_end(): No circ to send end on conn (fd 15). Oct 11 26:41.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 15). Oct 11 26:41.0 [debug] connection_remove(): removing socket 15 (type Socks), n_conns now 9 Oct 11 26:41.0 [debug] connection_free_(): closing fd 15. Oct 11 27:19.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:19.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 9. Oct 11 27:19.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:19.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:19.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:19.0 [debug] conn_read_callback(): socket 9 wants to read. Oct 11 27:19.0 [debug] connection_handle_listener_read(): Connection accepted on socket 14 (child of fd 9). Oct 11 27:19.0 [debug] connection_add_impl(): new conn type Socks, socket 14, address 192.168.42.10, n_conns 10. Oct 11 27:19.0 [debug] connection_ap_process_transparent(): entered. Oct 11 27:19.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:38980 Oct 11 27:19.0 [debug] conn_read_callback(): socket 14 wants to read. Oct 11 27:19.0 [debug] read_to_chunk(): Read 1460 bytes. 1460 on inbuf. Oct 11 27:19.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:19.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:19.0 [debug] conn_read_callback(): socket 14 wants to read. Oct 11 27:19.0 [debug] read_to_chunk(): Read 220 bytes. 1680 on inbuf. Oct 11 27:19.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:19.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:19.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:19.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 11. Oct 11 27:19.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:19.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:19.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:20.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:20.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 12. Oct 11 27:20.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:20.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:20.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:20.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:20.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 13. Oct 11 27:20.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:20.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:20.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:21.0 [debug] conn_read_callback(): socket 9 wants to read. Oct 11 27:21.0 [debug] connection_handle_listener_read(): Connection accepted on socket 15 (child of fd 9). Oct 11 27:21.0 [debug] connection_add_impl(): new conn type Socks, socket 15, address 192.168.42.10, n_conns 14. Oct 11 27:21.0 [debug] connection_ap_process_transparent(): entered. Oct 11 27:21.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:443 Oct 11 27:21.0 [debug] conn_read_callback(): socket 15 wants to read. Oct 11 27:21.0 [debug] read_to_chunk(): Read 231 bytes. 231 on inbuf. Oct 11 27:21.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:21.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:21.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:21.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 15. Oct 11 27:21.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:21.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:21.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:21.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:21.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 16. Oct 11 27:21.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:21.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:21.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:21.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:21.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 17. Oct 11 27:21.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:21.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:21.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:22.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:22.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 18. Oct 11 27:22.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:22.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:22.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:22.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:22.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 19. Oct 11 27:22.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:22.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:22.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:22.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:22.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 20. Oct 11 27:22.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:22.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:22.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:23.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:23.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 21. Oct 11 27:23.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:23.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:23.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:23.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:23.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:23.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 22. Oct 11 27:23.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:23.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:23.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:23.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:23.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 23. Oct 11 27:23.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:23.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:23.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:23.0 [debug] conn_read_callback(): socket 15 wants to read. Oct 11 27:23.0 [debug] read_to_chunk(): Encountered eof on fd 15 Oct 11 27:23.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:23.0 [info] connection_edge_reached_eof(): conn (fd 15) reached eof. Closing. Oct 11 27:23.0 [debug] connection_edge_end(): No circ to send end on conn (fd 15). Oct 11 27:23.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 15). Oct 11 27:23.0 [debug] connection_remove(): removing socket 15 (type Socks), n_conns now 23 Oct 11 27:23.0 [debug] connection_free_(): closing fd 15. Oct 11 27:24.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:24.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 23. Oct 11 27:24.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:24.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:24.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:24.0 [debug] conn_read_callback(): socket 9 wants to read. Oct 11 27:24.0 [debug] connection_handle_listener_read(): Connection accepted on socket 15 (child of fd 9). Oct 11 27:24.0 [debug] connection_add_impl(): new conn type Socks, socket 15, address 192.168.42.10, n_conns 24. Oct 11 27:24.0 [debug] connection_ap_process_transparent(): entered. Oct 11 27:24.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:443 Oct 11 27:24.0 [debug] conn_read_callback(): socket 15 wants to read. Oct 11 27:24.0 [debug] read_to_chunk(): Read 199 bytes. 199 on inbuf. Oct 11 27:24.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:24.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:24.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:24.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 25. Oct 11 27:24.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:24.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:24.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:24.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:24.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 26. Oct 11 27:24.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:24.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:24.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:25.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:25.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 27. Oct 11 27:25.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:25.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:25.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:25.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:25.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 28. Oct 11 27:25.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:25.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:25.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:25.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:25.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 29. Oct 11 27:25.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:25.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:25.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:25.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:25.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 30. Oct 11 27:25.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:25.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:25.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:25.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:25.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 31. Oct 11 27:25.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:25.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:25.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:25.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:25.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 32. Oct 11 27:25.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:25.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:25.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:25.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:25.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 33. Oct 11 27:25.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:25.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:25.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:25.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:25.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 34. Oct 11 27:25.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:25.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:25.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:25.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:25.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 35. Oct 11 27:25.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:25.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:25.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:25.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:25.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 36. Oct 11 27:25.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:25.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:25.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:26.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:26.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 37. Oct 11 27:26.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:26.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:26.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:26.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:26.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 38. Oct 11 27:26.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:26.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:26.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:26.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:26.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 39. Oct 11 27:26.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:26.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:26.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:26.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:26.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 40. Oct 11 27:26.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:26.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:26.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:26.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:26.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 41. Oct 11 27:26.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:26.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:26.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:26.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:26.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 42. Oct 11 27:26.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:26.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:26.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:26.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:26.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 43. Oct 11 27:26.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:26.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:26.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:26.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:26.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 44. Oct 11 27:26.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:26.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:26.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:26.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:26.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 45. Oct 11 27:26.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:26.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:26.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:27.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:27.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 46. Oct 11 27:27.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:27.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:27.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:27.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:27.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 47. Oct 11 27:27.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:27.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:27.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:27.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:27.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 48. Oct 11 27:27.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:27.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:27.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:27.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:27.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 49. Oct 11 27:27.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:27.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:27.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:27.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:27.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 50. Oct 11 27:27.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:27.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:27.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:28.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:28.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 51. Oct 11 27:28.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:28.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:28.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:28.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:28.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 52. Oct 11 27:28.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:28.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:28.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:28.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:28.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 53. Oct 11 27:28.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:28.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:28.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:28.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:28.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 54. Oct 11 27:28.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:28.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:28.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:28.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:28.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 55. Oct 11 27:28.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:28.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:28.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:29.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:29.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 56. Oct 11 27:29.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:29.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:29.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:29.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:29.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 57. Oct 11 27:29.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:29.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:29.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:29.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:29.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 58. Oct 11 27:29.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:29.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:29.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:29.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:29.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 59. Oct 11 27:29.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:29.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:29.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:29.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:29.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 60. Oct 11 27:29.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:29.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:29.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:29.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:29.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 61. Oct 11 27:29.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:29.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:29.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:30.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:30.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:30.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:30.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:30.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:30.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:30.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 62. Oct 11 27:30.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:30.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:30.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:30.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:30.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 63. Oct 11 27:30.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:30.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:30.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:30.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:30.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 64. Oct 11 27:30.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:30.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:30.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:31.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:31.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 65. Oct 11 27:31.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:31.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:31.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:31.0 [info] connection_ap_expire_beginning(): Tried for 120 seconds to get a connection to [scrubbed]:443. Giving up. (waiting for circuit) Oct 11 27:31.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 27:31.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 65 Oct 11 27:31.0 [info] connection_free_(): Freeing linked Socks connection [waiting for circuit] with 145 bytes on inbuf, 0 on outbuf. Oct 11 27:31.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 27:31.0 [info] connection_dir_client_reached_eof(): 'fetch' response not all here, but we're at eof. Closing. Oct 11 27:31.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 27:31.0 [info] update_consensus_networkstatus_downloads(): Launching microdesc networkstatus consensus download. Oct 11 27:31.0 [info] directory_pick_generic_dirserver(): No router found for consensus network-status fetch; falling back to dirserver list. Oct 11 27:31.0 [debug] directory_initiate_command_rend(): anonymized 0, use_begindir 1. Oct 11 27:31.0 [debug] directory_initiate_command_rend(): Initiating consensus network-status fetch Oct 11 27:31.0 [info] connection_ap_make_link(): Making internal direct tunnel to [scrubbed]:443 ... Oct 11 27:31.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address (Tor_internal), n_conns 65. Oct 11 27:31.0 [debug] circuit_get_open_circ_or_launch(): considering 1, $847B1F850344D7876491A54892F904934E4EB85D Oct 11 27:31.0 [debug] onion_pick_cpath_exit(): Launching a one-hop circuit for dir tunnel. Oct 11 27:31.0 [info] onion_pick_cpath_exit(): Using requested exit node '$847B1F850344D7876491A54892F904934E4EB85D847B1F850344D787649 at 86.59.21.38' Oct 11 27:31.0 [debug] onion_extend_cpath(): Path is 0 long; we want 1 Oct 11 27:31.0 [debug] onion_extend_cpath(): Chose router $847B1F850344D7876491A54892F904934E4EB85D847B1F850344D787649 at 86.59.21.38 for hop 1 (exit is 847B1F850344D7876491A54892F904934E4EB85D) Oct 11 27:31.0 [debug] onion_extend_cpath(): Path is complete: 1 steps long Oct 11 27:31.0 [debug] circuit_handle_first_hop(): Looking for firsthop '86.59.21.38:443' Oct 11 27:31.0 [info] circuit_handle_first_hop(): Next router is [scrubbed]: Not connected. Connecting. Oct 11 27:31.0 [debug] channel_tls_connect(): In channel_tls_connect() for channel 0x569a5c10 (global id 11) Oct 11 27:31.0 [debug] channel_tls_connect(): Marking new outgoing channel 11 at 0x569a5c10 as remote Oct 11 27:31.0 [debug] channel_set_identity_digest(): Setting remote endpoint digest on channel 0x569a5c10 with global ID 11 to digest 847B1F850344D7876491A54892F904934E4EB85D Oct 11 27:31.0 [debug] connection_connect(): Connecting to [scrubbed]:443. Oct 11 27:31.0 [debug] connection_connect(): Connection to [scrubbed]:443 in progress (sock 17). Oct 11 27:31.0 [debug] connection_add_impl(): new conn type OR, socket 17, address 86.59.21.38, n_conns 66. Oct 11 27:31.0 [debug] channel_tls_connect(): Got orconn 0x569a5d58 for channel with global id 11 Oct 11 27:31.0 [debug] channel_register(): Registering channel 0x569a5c10 (ID 11) in state opening (1) with digest 847B1F850344D7876491A54892F904934E4EB85D Oct 11 27:31.0 [debug] channel_add_to_digest_map(): Added channel 0x569a5c10 (global ID 11) to identity map in state opening (1) with digest 847B1F850344D7876491A54892F904934E4EB85D Oct 11 27:31.0 [debug] channel_set_cell_handlers(): Setting cell_handler callback for channel 0x569a5c10 to 0x54b791a8 Oct 11 27:31.0 [debug] channel_set_cell_handlers(): Setting var_cell_handler callback for channel 0x569a5c10 to 0x54b7865c Oct 11 27:31.0 [debug] circuit_handle_first_hop(): connecting in progress (or finished). Good. Oct 11 27:31.0 [info] connection_ap_make_link(): ... application connection created and linked. Oct 11 27:31.0 [debug] connection_add_impl(): new conn type Directory, socket -1, address 86.59.21.38, n_conns 67. Oct 11 27:31.0 [info] directory_send_command(): Downloading consensus from 86.59.21.38:443 using /tor/status-vote/current/consensus-microdesc/0232AF+14C131+23D15D+49015F+D586D1+E8A9C4+ED03BB+EFCBE7.z Oct 11 27:31.0 [debug] connection_remove(): removing socket -1 (type Directory), n_conns now 67 Oct 11 27:31.0 [info] connection_free_(): Freeing linked Directory connection [client reading] with 0 bytes on inbuf, 0 on outbuf. Oct 11 27:31.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 27:31.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:31.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:31.0 [debug] connection_dir_finished_flushing(): client finished sending command. Oct 11 27:31.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:31.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 67. Oct 11 27:31.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:31.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:31.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:32.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:32.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 68. Oct 11 27:32.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:32.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:32.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:32.0 [debug] conn_read_callback(): socket 15 wants to read. Oct 11 27:32.0 [debug] read_to_chunk(): Encountered eof on fd 15 Oct 11 27:32.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:32.0 [info] connection_edge_reached_eof(): conn (fd 15) reached eof. Closing. Oct 11 27:32.0 [debug] connection_edge_end(): No circ to send end on conn (fd 15). Oct 11 27:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 15). Oct 11 27:32.0 [debug] connection_remove(): removing socket 15 (type Socks), n_conns now 68 Oct 11 27:32.0 [debug] connection_free_(): closing fd 15. Oct 11 27:32.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:32.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 68. Oct 11 27:32.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:32.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:32.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:32.0 [info] run_connection_housekeeping(): Expiring non-open OR connection to fd 4 (171.25.193.9:80). Oct 11 27:32.0 [debug] connection_mark_for_close_internal_(): Calling connection_mark_for_close_internal_() on an OR conn at ../src/or/connection_or.c:1311 Oct 11 27:32.0 [debug] channel_close_from_lower_layer(): Closing channel 0x569b1390 (global ID 9) due to lower-layer event Oct 11 27:32.0 [debug] channel_change_state(): Changing state of channel 0x569b1390 (global ID 9) from "opening" to "closing" Oct 11 27:32.0 [debug] channel_remove_from_digest_map(): Removed channel 0x569b1390 (global ID 9) from identity map in state closing (4) with digest BD6A829255CB08E66FBE7D3748363586E46B3810 Oct 11 27:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 4). Oct 11 27:32.0 [debug] circuit_n_chan_done(): chan to NULL/171.25.193.9:80, status=0 Oct 11 27:32.0 [info] circuit_n_chan_done(): Channel failed; closing circ. Oct 11 27:32.0 [info] circuit_build_failed(): Our circuit died before the first hop with no connection Oct 11 27:32.0 [debug] circuit_increment_failure_count(): n_circuit_failures now 1. Oct 11 27:32.0 [debug] channel_change_state(): Changing state of channel 0x569b1390 (global ID 9) from "closing" to "closed" Oct 11 27:32.0 [info] connection_or_note_state_when_broken(): Connection died in state 'handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE' Oct 11 27:32.0 [warn] Problem bootstrapping. Stuck at 10%: Finishing handshake with directory server. (DONE; DONE; count 10; recommendation warn) Oct 11 27:32.0 [warn] 10 connections have failed: Oct 11 27:32.0 [warn] 9 connections died in state handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE Oct 11 27:32.0 [warn] 1 connections died in state connect()ing with SSL state (No SSL object) Oct 11 27:32.0 [debug] connection_remove(): removing socket 4 (type OR), n_conns now 68 Oct 11 27:32.0 [debug] connection_free_(): closing fd 4. Oct 11 27:32.0 [debug] channel_free(): Freeing channel 9 at 0x569b1390 Oct 11 27:32.0 [debug] channel_clear_remote_end(): Clearing remote endpoint identity on channel 0x569b1390 with global ID 9 Oct 11 27:32.0 [debug] circuitmux_free(): Freeing cmux at 0x569b0f38 with no queued destroys, the cmux destroy balance was 0, global is 0 Oct 11 27:32.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:32.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 68. Oct 11 27:32.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:32.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:32.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:33.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:33.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 69. Oct 11 27:33.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:33.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:33.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:33.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:33.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 70. Oct 11 27:33.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:33.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:33.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:33.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:33.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 71. Oct 11 27:33.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:33.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:33.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:33.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:33.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 72. Oct 11 27:33.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:33.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:33.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:33.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:33.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 73. Oct 11 27:33.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:33.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:33.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:33.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:33.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 74. Oct 11 27:33.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:33.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:33.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:33.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:33.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 75. Oct 11 27:33.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:33.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:33.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:33.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:33.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 76. Oct 11 27:33.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:33.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:33.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:34.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:34.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 77. Oct 11 27:34.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:34.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:34.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:34.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:34.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:34.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 78. Oct 11 27:34.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:34.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:34.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:34.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:34.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 79. Oct 11 27:34.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:34.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:34.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:34.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:34.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 80. Oct 11 27:34.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:34.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:34.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:34.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:34.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 81. Oct 11 27:34.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:34.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:34.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:35.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:35.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 82. Oct 11 27:35.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:35.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:35.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:35.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:35.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 83. Oct 11 27:35.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:35.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:35.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:35.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:35.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 84. Oct 11 27:35.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:35.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:35.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:36.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:36.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 85. Oct 11 27:36.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:36.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:36.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:36.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:36.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 86. Oct 11 27:36.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:36.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:36.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:36.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:36.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 87. Oct 11 27:36.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:36.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:36.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:36.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:36.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 88. Oct 11 27:36.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:36.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:36.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:37.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:37.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 89. Oct 11 27:37.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:37.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:37.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:37.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:37.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 90. Oct 11 27:37.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:37.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:37.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:37.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:37.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 91. Oct 11 27:37.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:37.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:37.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:37.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:37.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 92. Oct 11 27:37.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:37.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:37.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:37.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:37.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 93. Oct 11 27:37.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:37.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:37.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:37.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:37.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 94. Oct 11 27:37.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:37.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:37.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:37.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:37.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 95. Oct 11 27:37.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:37.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:37.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:37.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:37.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 96. Oct 11 27:37.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:37.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:37.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:38.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:38.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 97. Oct 11 27:38.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:38.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:38.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:38.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:38.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 98. Oct 11 27:38.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:38.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:38.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:38.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:38.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 99. Oct 11 27:38.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:38.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:38.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:38.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 27:38.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 99 Oct 11 27:39.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:39.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 99. Oct 11 27:39.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:39.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:39.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:40.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:40.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 100. Oct 11 27:40.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:40.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:40.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:40.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:40.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 101. Oct 11 27:40.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:40.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:40.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:41.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:41.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 102. Oct 11 27:41.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:41.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:41.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:41.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:41.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 103. Oct 11 27:41.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:41.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:41.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:41.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:41.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 104. Oct 11 27:41.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:41.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:41.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:43.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:43.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 105. Oct 11 27:43.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:43.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:43.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:43.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:43.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 106. Oct 11 27:43.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:43.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:43.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:43.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 27:43.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 106 Oct 11 27:45.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:45.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 106. Oct 11 27:45.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:45.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:45.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:46.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:46.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 107. Oct 11 27:46.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:46.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:46.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:47.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:47.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 108. Oct 11 27:47.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:47.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:47.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:48.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:48.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 109. Oct 11 27:48.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:48.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:48.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:48.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:48.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 110. Oct 11 27:48.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:48.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:48.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:49.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:49.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 111. Oct 11 27:49.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:49.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:49.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:49.0 [debug] conn_read_callback(): socket 14 wants to read. Oct 11 27:49.0 [debug] read_to_chunk(): Encountered eof on fd 14 Oct 11 27:49.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:49.0 [info] connection_edge_reached_eof(): conn (fd 14) reached eof. Closing. Oct 11 27:49.0 [debug] connection_edge_end(): No circ to send end on conn (fd 14). Oct 11 27:49.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 14). Oct 11 27:49.0 [debug] connection_remove(): removing socket 14 (type Socks), n_conns now 111 Oct 11 27:49.0 [debug] connection_free_(): closing fd 14. Oct 11 27:50.0 [debug] conn_read_callback(): socket 9 wants to read. Oct 11 27:50.0 [debug] connection_handle_listener_read(): Connection accepted on socket 4 (child of fd 9). Oct 11 27:50.0 [debug] connection_add_impl(): new conn type Socks, socket 4, address 192.168.42.10, n_conns 111. Oct 11 27:50.0 [debug] connection_ap_process_transparent(): entered. Oct 11 27:50.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:47779 Oct 11 27:50.0 [debug] conn_read_callback(): socket 4 wants to read. Oct 11 27:50.0 [debug] read_to_chunk(): Read 1460 bytes. 1460 on inbuf. Oct 11 27:50.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:50.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:50.0 [debug] conn_read_callback(): socket 4 wants to read. Oct 11 27:50.0 [debug] read_to_chunk(): Read 1312 bytes. 2772 on inbuf. Oct 11 27:50.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:50.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:51.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:51.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 112. Oct 11 27:51.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:51.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:51.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:52.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:52.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 113. Oct 11 27:52.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:52.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:52.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:52.0 [debug] conn_read_callback(): socket 9 wants to read. Oct 11 27:52.0 [debug] connection_handle_listener_read(): Connection accepted on socket 14 (child of fd 9). Oct 11 27:52.0 [debug] connection_add_impl(): new conn type Socks, socket 14, address 192.168.42.10, n_conns 114. Oct 11 27:52.0 [debug] connection_ap_process_transparent(): entered. Oct 11 27:52.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:443 Oct 11 27:52.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 27:52.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 114 Oct 11 27:52.0 [debug] conn_read_callback(): socket 14 wants to read. Oct 11 27:52.0 [debug] read_to_chunk(): Read 199 bytes. 199 on inbuf. Oct 11 27:52.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:52.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 27:52.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:52.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 114. Oct 11 27:52.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:52.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:52.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:53.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:53.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 115. Oct 11 27:53.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:53.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:53.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:54.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:54.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 116. Oct 11 27:54.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:54.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:54.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:55.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:55.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 117. Oct 11 27:55.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:55.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:55.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 27:56.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 27:56.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 118. Oct 11 27:56.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 27:56.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 27:56.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:00.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:00.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 119. Oct 11 28:00.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:00.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:00.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:01.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:01.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 120. Oct 11 28:01.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:01.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:01.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:02.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:02.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 121. Oct 11 28:02.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:02.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:02.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:03.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:03.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 122. Oct 11 28:03.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:03.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:03.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:05.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:05.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 123. Oct 11 28:05.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:05.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:05.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:05.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:05.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 124. Oct 11 28:05.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:05.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:05.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:05.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 28:05.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 124 Oct 11 28:09.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:09.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 124. Oct 11 28:09.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:09.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:09.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:13.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:13.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 125. Oct 11 28:13.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:13.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:13.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:14.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:14.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 126. Oct 11 28:14.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:14.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:14.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:15.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:15.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 127. Oct 11 28:15.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:15.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:15.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:17.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:17.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 128. Oct 11 28:17.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:17.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:17.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:20.0 [debug] conn_read_callback(): socket 4 wants to read. Oct 11 28:20.0 [debug] read_to_chunk(): Encountered eof on fd 4 Oct 11 28:20.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 28:20.0 [info] connection_edge_reached_eof(): conn (fd 4) reached eof. Closing. Oct 11 28:20.0 [debug] connection_edge_end(): No circ to send end on conn (fd 4). Oct 11 28:20.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 4). Oct 11 28:20.0 [debug] connection_remove(): removing socket 4 (type Socks), n_conns now 128 Oct 11 28:20.0 [debug] connection_free_(): closing fd 4. Oct 11 28:21.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:21.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 128. Oct 11 28:21.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:21.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:21.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:28.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:28.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 129. Oct 11 28:28.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:28.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:28.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:29.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:29.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 130. Oct 11 28:29.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:29.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:29.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:30.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:30.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 131. Oct 11 28:30.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:30.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:30.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 28:32.0 [info] evdns_server_callback(): Got a new DNS request! Oct 11 28:32.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address 192.168.42.10, n_conns 132. Oct 11 28:32.0 [info] evdns_server_callback(): Passing request for [scrubbed] to rewrite_and_attach. Oct 11 28:32.0 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:0 Oct 11 28:32.0 [info] evdns_server_callback(): Passed request for [scrubbed] to rewrite_and_attach_if_allowed. Oct 11 29:19.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:19.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:19.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:19.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 132 Oct 11 29:19.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:19.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 131 Oct 11 29:20.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:20.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:20.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:20.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 130 Oct 11 29:20.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:20.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 129 Oct 11 29:21.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:21.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:21.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:21.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:21.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 128 Oct 11 29:21.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:21.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 127 Oct 11 29:21.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:21.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 126 Oct 11 29:22.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:22.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:22.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:22.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:22.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 125 Oct 11 29:22.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:22.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 124 Oct 11 29:22.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:22.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 123 Oct 11 29:23.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:23.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:23.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:23.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:23.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 122 Oct 11 29:23.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:23.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 121 Oct 11 29:23.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:23.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 120 Oct 11 29:24.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:24.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:24.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:24.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:24.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 119 Oct 11 29:24.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:24.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 118 Oct 11 29:24.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:24.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 117 Oct 11 29:25.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:25.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:25.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:25.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:25.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:25.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:25.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:25.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:25.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:25.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:25.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:25.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 116 Oct 11 29:25.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:25.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 115 Oct 11 29:25.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:25.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 114 Oct 11 29:25.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:25.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 113 Oct 11 29:25.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:25.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 112 Oct 11 29:25.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:25.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 111 Oct 11 29:25.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:25.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 110 Oct 11 29:25.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:25.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 109 Oct 11 29:25.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:25.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 108 Oct 11 29:25.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:25.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 107 Oct 11 29:26.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:26.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:26.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:26.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:26.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:26.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:26.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:26.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:26.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:26.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:26.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 106 Oct 11 29:26.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:26.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 105 Oct 11 29:26.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:26.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 104 Oct 11 29:26.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:26.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 103 Oct 11 29:26.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:26.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 102 Oct 11 29:26.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:26.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 101 Oct 11 29:26.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:26.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 100 Oct 11 29:26.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:26.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 99 Oct 11 29:26.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:26.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 98 Oct 11 29:27.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:27.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:27.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:27.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:27.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:27.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:27.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 97 Oct 11 29:27.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:27.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 96 Oct 11 29:27.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:27.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 95 Oct 11 29:27.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:27.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 94 Oct 11 29:27.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:27.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 93 Oct 11 29:28.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:28.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:28.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:28.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:28.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:28.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:28.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 92 Oct 11 29:28.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:28.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 91 Oct 11 29:28.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:28.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 90 Oct 11 29:28.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:28.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 89 Oct 11 29:28.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:28.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 88 Oct 11 29:29.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:29.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:29.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:29.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:29.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:29.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:29.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:29.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 87 Oct 11 29:29.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:29.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 86 Oct 11 29:29.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:29.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 85 Oct 11 29:29.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:29.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 84 Oct 11 29:29.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:29.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 83 Oct 11 29:29.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:29.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 82 Oct 11 29:30.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:30.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:30.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:30.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:30.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 81 Oct 11 29:30.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:30.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 80 Oct 11 29:30.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:30.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 79 Oct 11 29:31.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:31.0 [info] connection_ap_expire_beginning(): Tried for 120 seconds to get a connection to [scrubbed]:443. Giving up. (waiting for circuit) Oct 11 29:31.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:31.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:31.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 78 Oct 11 29:31.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:31.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 77 Oct 11 29:31.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:31.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 76 Oct 11 29:31.0 [info] connection_free_(): Freeing linked Socks connection [waiting for circuit] with 142 bytes on inbuf, 0 on outbuf. Oct 11 29:31.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 29:31.0 [info] connection_dir_client_reached_eof(): 'fetch' response not all here, but we're at eof. Closing. Oct 11 29:31.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:31.0 [debug] connection_remove(): removing socket -1 (type Directory), n_conns now 75 Oct 11 29:31.0 [info] connection_free_(): Freeing linked Directory connection [client reading] with 0 bytes on inbuf, 0 on outbuf. Oct 11 29:32.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:32.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:32.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:32.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 74 Oct 11 29:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:32.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 73 Oct 11 29:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:32.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 72 Oct 11 29:33.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:33.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:33.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:33.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:33.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:33.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:33.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:33.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:33.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:33.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 71 Oct 11 29:33.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:33.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 70 Oct 11 29:33.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:33.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 69 Oct 11 29:33.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:33.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 68 Oct 11 29:33.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:33.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 67 Oct 11 29:33.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:33.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 66 Oct 11 29:33.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:33.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 65 Oct 11 29:33.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:33.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 64 Oct 11 29:34.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:34.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:34.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:34.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:34.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:34.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:34.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 63 Oct 11 29:34.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:34.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 62 Oct 11 29:34.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:34.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 61 Oct 11 29:34.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:34.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 60 Oct 11 29:34.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:34.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 59 Oct 11 29:35.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:35.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:35.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:35.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:35.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 58 Oct 11 29:35.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:35.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 57 Oct 11 29:35.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:35.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 56 Oct 11 29:36.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:36.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:36.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:36.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:36.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:36.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 55 Oct 11 29:36.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:36.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 54 Oct 11 29:36.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:36.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 53 Oct 11 29:36.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:36.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 52 Oct 11 29:37.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:37.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:37.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:37.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:37.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:37.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:37.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:37.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:37.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 51 Oct 11 29:37.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:37.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 50 Oct 11 29:37.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:37.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 49 Oct 11 29:37.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:37.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 48 Oct 11 29:37.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:37.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 47 Oct 11 29:37.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:37.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 46 Oct 11 29:37.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:37.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 45 Oct 11 29:38.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:38.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:38.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:38.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:38.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 44 Oct 11 29:38.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:38.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 43 Oct 11 29:38.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:38.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 42 Oct 11 29:39.0 [debug] conn_read_callback(): socket 17 wants to read. Oct 11 29:39.0 [warn] Problem bootstrapping. Stuck at 10%: Finishing handshake with directory server. (Connection timed out; TIMEOUT; count 11; recommendation warn) Oct 11 29:39.0 [warn] 10 connections have failed: Oct 11 29:39.0 [warn] 9 connections died in state handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE Oct 11 29:39.0 [warn] 1 connections died in state connect()ing with SSL state (No SSL object) Oct 11 29:39.0 [debug] channel_close_for_error(): Closing channel 0x569a5c10 due to lower-layer error Oct 11 29:39.0 [debug] channel_change_state(): Changing state of channel 0x569a5c10 (global ID 11) from "opening" to "closing" Oct 11 29:39.0 [debug] channel_remove_from_digest_map(): Removed channel 0x569a5c10 (global ID 11) from identity map in state closing (4) with digest 847B1F850344D7876491A54892F904934E4EB85D Oct 11 29:39.0 [debug] connection_mark_for_close_internal_(): Calling connection_mark_for_close_internal_() on an OR conn at ../src/or/connection.c:3184 Oct 11 29:39.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:39.0 [debug] circuit_n_chan_done(): chan to NULL/86.59.21.38:443, status=0 Oct 11 29:39.0 [info] circuit_n_chan_done(): Channel failed; closing circ. Oct 11 29:39.0 [info] circuit_build_failed(): Our circuit died before the first hop with no connection Oct 11 29:39.0 [debug] circuit_increment_failure_count(): n_circuit_failures now 1. Oct 11 29:39.0 [debug] channel_change_state(): Changing state of channel 0x569a5c10 (global ID 11) from "closing" to "channel error" Oct 11 29:39.0 [info] connection_or_note_state_when_broken(): Connection died in state 'connect()ing with SSL state (No SSL object)' Oct 11 29:39.0 [debug] connection_remove(): removing socket -1 (type OR), n_conns now 41 Oct 11 29:39.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:39.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:39.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 40 Oct 11 29:39.0 [debug] channel_free(): Freeing channel 11 at 0x569a5c10 Oct 11 29:39.0 [debug] channel_clear_remote_end(): Clearing remote endpoint identity on channel 0x569a5c10 with global ID 11 Oct 11 29:39.0 [debug] circuitmux_free(): Freeing cmux at 0x569a5d00 with no queued destroys, the cmux destroy balance was 0, global is 0 Oct 11 29:40.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:40.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:40.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:40.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 39 Oct 11 29:40.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:40.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 38 Oct 11 29:41.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:41.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:41.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:41.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:41.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 37 Oct 11 29:41.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:41.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 36 Oct 11 29:41.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:41.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 35 Oct 11 29:43.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:43.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:43.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 34 Oct 11 29:45.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:45.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:45.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 33 Oct 11 29:46.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:46.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:46.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 32 Oct 11 29:47.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:47.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:47.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 31 Oct 11 29:48.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:48.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:48.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:48.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 30 Oct 11 29:48.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:48.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 29 Oct 11 29:49.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:49.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:49.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 28 Oct 11 29:51.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:51.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:51.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 27 Oct 11 29:52.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:443. Giving up. (waiting for circuit) Oct 11 29:52.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:52.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 14). Oct 11 29:52.0 [debug] connection_remove(): removing socket 14 (type Socks), n_conns now 26 Oct 11 29:52.0 [debug] connection_free_(): closing fd 14. Oct 11 29:52.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:52.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 25 Oct 11 29:53.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:53.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:53.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 24 Oct 11 29:54.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:54.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:54.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 23 Oct 11 29:55.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:55.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:55.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 22 Oct 11 29:56.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 29:56.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 29:56.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 21 Oct 11 30:00.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:00.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:00.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 20 Oct 11 30:01.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:01.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:01.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 19 Oct 11 30:02.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:02.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:02.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 18 Oct 11 30:03.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:03.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:03.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 17 Oct 11 30:05.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:05.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:05.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 16 Oct 11 30:09.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:09.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:09.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 15 Oct 11 30:13.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:13.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:13.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 14 Oct 11 30:14.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:14.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:14.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 13 Oct 11 30:15.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:15.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:15.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 12 Oct 11 30:17.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:17.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:17.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 11 Oct 11 30:21.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:21.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:21.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 10 Oct 11 30:28.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:28.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:28.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 9 Oct 11 30:29.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:29.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:29.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 8 Oct 11 30:30.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:30.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:30.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 7 Oct 11 30:31.0 [info] run_connection_housekeeping(): Expiring non-open OR connection to fd 16 (199.254.238.52:443). Oct 11 30:31.0 [debug] connection_mark_for_close_internal_(): Calling connection_mark_for_close_internal_() on an OR conn at ../src/or/connection_or.c:1311 Oct 11 30:31.0 [debug] channel_close_from_lower_layer(): Closing channel 0x569b1a70 (global ID 10) due to lower-layer event Oct 11 30:31.0 [debug] channel_change_state(): Changing state of channel 0x569b1a70 (global ID 10) from "opening" to "closing" Oct 11 30:31.0 [debug] channel_remove_from_digest_map(): Removed channel 0x569b1a70 (global ID 10) from identity map in state closing (4) with digest 74A910646BCEEFBCD2E874FC1DC997430F968145 Oct 11 30:31.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 16). Oct 11 30:31.0 [debug] circuit_n_chan_done(): chan to NULL/199.254.238.52:443, status=0 Oct 11 30:31.0 [info] circuit_n_chan_done(): Channel failed; closing circ. Oct 11 30:31.0 [info] circuit_build_failed(): Our circuit died before the first hop with no connection Oct 11 30:31.0 [debug] circuit_increment_failure_count(): n_circuit_failures now 1. Oct 11 30:31.0 [debug] channel_change_state(): Changing state of channel 0x569b1a70 (global ID 10) from "closing" to "closed" Oct 11 30:31.0 [info] connection_or_note_state_when_broken(): Connection died in state 'handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE' Oct 11 30:31.0 [warn] Problem bootstrapping. Stuck at 10%: Finishing handshake with directory server. (DONE; DONE; count 12; recommendation warn) Oct 11 30:31.0 [warn] 12 connections have failed: Oct 11 30:31.0 [warn] 10 connections died in state handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE Oct 11 30:31.0 [warn] 2 connections died in state connect()ing with SSL state (No SSL object) Oct 11 30:31.0 [debug] connection_remove(): removing socket 16 (type OR), n_conns now 6 Oct 11 30:31.0 [debug] connection_free_(): closing fd 16. Oct 11 30:31.0 [debug] channel_free(): Freeing channel 10 at 0x569b1a70 Oct 11 30:31.0 [debug] channel_clear_remote_end(): Clearing remote endpoint identity on channel 0x569b1a70 with global ID 10 Oct 11 30:31.0 [debug] circuitmux_free(): Freeing cmux at 0x569b1b60 with no queued destroys, the cmux destroy balance was 0, global is 0 Oct 11 30:32.0 [notice] Tried for 120 seconds to get a connection to [scrubbed]:0. Giving up. (waiting for circuit) Oct 11 30:32.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 30:32.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 5 Oct 11 31:07.0 [info] update_consensus_networkstatus_downloads(): Launching microdesc networkstatus consensus download. Oct 11 31:07.0 [info] directory_pick_generic_dirserver(): No router found for consensus network-status fetch; falling back to dirserver list. Oct 11 31:07.0 [debug] directory_initiate_command_rend(): anonymized 0, use_begindir 1. Oct 11 31:07.0 [debug] directory_initiate_command_rend(): Initiating consensus network-status fetch Oct 11 31:07.0 [info] connection_ap_make_link(): Making internal direct tunnel to [scrubbed]:443 ... Oct 11 31:07.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address (Tor_internal), n_conns 5. Oct 11 31:07.0 [debug] circuit_get_open_circ_or_launch(): considering 1, $7EA6EAD6FD83083C538F44038BBFA077587DD755 Oct 11 31:07.0 [debug] onion_pick_cpath_exit(): Launching a one-hop circuit for dir tunnel. Oct 11 31:07.0 [info] onion_pick_cpath_exit(): Using requested exit node '$7EA6EAD6FD83083C538F44038BBFA077587DD7557EA6EAD6FD83083C538 at 194.109.206.212' Oct 11 31:07.0 [debug] onion_extend_cpath(): Path is 0 long; we want 1 Oct 11 31:07.0 [debug] onion_extend_cpath(): Chose router $7EA6EAD6FD83083C538F44038BBFA077587DD7557EA6EAD6FD83083C538 at 194.109.206.212 for hop 1 (exit is 7EA6EAD6FD83083C538F44038BBFA077587DD755) Oct 11 31:07.0 [debug] onion_extend_cpath(): Path is complete: 1 steps long Oct 11 31:07.0 [debug] circuit_handle_first_hop(): Looking for firsthop '194.109.206.212:443' Oct 11 31:07.0 [info] circuit_handle_first_hop(): Next router is [scrubbed]: Not connected. Connecting. Oct 11 31:07.0 [debug] channel_tls_connect(): In channel_tls_connect() for channel 0x5687edb8 (global id 12) Oct 11 31:07.0 [debug] channel_tls_connect(): Marking new outgoing channel 12 at 0x5687edb8 as remote Oct 11 31:07.0 [debug] channel_set_identity_digest(): Setting remote endpoint digest on channel 0x5687edb8 with global ID 12 to digest 7EA6EAD6FD83083C538F44038BBFA077587DD755 Oct 11 31:07.0 [debug] connection_connect(): Connecting to [scrubbed]:443. Oct 11 31:07.0 [debug] connection_connect(): Connection to [scrubbed]:443 in progress (sock 4). Oct 11 31:07.0 [debug] connection_add_impl(): new conn type OR, socket 4, address 194.109.206.212, n_conns 6. Oct 11 31:07.0 [debug] channel_tls_connect(): Got orconn 0x5699a5e8 for channel with global id 12 Oct 11 31:07.0 [debug] channel_register(): Registering channel 0x5687edb8 (ID 12) in state opening (1) with digest 7EA6EAD6FD83083C538F44038BBFA077587DD755 Oct 11 31:07.0 [debug] channel_add_to_digest_map(): Added channel 0x5687edb8 (global ID 12) to identity map in state opening (1) with digest 7EA6EAD6FD83083C538F44038BBFA077587DD755 Oct 11 31:07.0 [debug] channel_set_cell_handlers(): Setting cell_handler callback for channel 0x5687edb8 to 0x54b791a8 Oct 11 31:07.0 [debug] channel_set_cell_handlers(): Setting var_cell_handler callback for channel 0x5687edb8 to 0x54b7865c Oct 11 31:07.0 [debug] circuit_handle_first_hop(): connecting in progress (or finished). Good. Oct 11 31:07.0 [info] connection_ap_make_link(): ... application connection created and linked. Oct 11 31:07.0 [debug] connection_add_impl(): new conn type Directory, socket -1, address 194.109.206.212, n_conns 7. Oct 11 31:07.0 [info] directory_send_command(): Downloading consensus from 194.109.206.212:443 using /tor/status-vote/current/consensus-microdesc/0232AF+14C131+23D15D+49015F+D586D1+E8A9C4+ED03BB+EFCBE7.z Oct 11 31:07.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 31:07.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 31:07.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 31:07.0 [debug] connection_dir_finished_flushing(): client finished sending command. Oct 11 31:07.0 [debug] conn_write_callback(): socket 4 wants to write. Oct 11 31:07.0 [debug] connection_or_finished_connecting(): OR connect() to router at 194.109.206.212:443 finished. Oct 11 31:07.0 [debug] connection_tls_start_handshake(): starting TLS handshake on fd 4 Oct 11 31:07.0 [debug] tor_tls_handshake(): About to call SSL_connect on 0x5687d8e8 (before/accept initialization) Oct 11 31:07.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687e960 is now in state before/connect initialization [type=16,val=1]. Oct 11 31:07.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687e960 is now in state before/connect initialization [type=4097,val=1]. Oct 11 31:07.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687e960 is now in state SSLv2/v3 write client hello A [type=4097,val=1]. Oct 11 31:07.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687e960 is now in state SSLv2/v3 read server hello A [type=4098,val=-1]. Oct 11 31:07.0 [debug] tor_tls_handshake(): After call, 0x5687d8e8 was in state SSLv2/v3 read server hello A Oct 11 31:07.0 [debug] connection_tls_continue_handshake(): wanted read Oct 11 31:07.0 [debug] tor_tls_handshake(): About to call SSL_connect on 0x5687d8e8 (SSLv2/v3 read server hello A) Oct 11 31:07.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687e960 is now in state SSLv2/v3 read server hello A [type=4098,val=-1]. Oct 11 31:07.0 [debug] connection_tls_continue_handshake(): wanted read Oct 11 33:07.0 [info] connection_ap_expire_beginning(): Tried for 120 seconds to get a connection to [scrubbed]:443. Giving up. (waiting for circuit) Oct 11 33:07.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 33:07.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 7 Oct 11 33:07.0 [info] connection_free_(): Freeing linked Socks connection [waiting for circuit] with 146 bytes on inbuf, 0 on outbuf. Oct 11 33:07.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 33:07.0 [info] connection_dir_client_reached_eof(): 'fetch' response not all here, but we're at eof. Closing. Oct 11 33:07.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 33:07.0 [debug] connection_remove(): removing socket -1 (type Directory), n_conns now 6 Oct 11 33:07.0 [info] connection_free_(): Freeing linked Directory connection [client reading] with 0 bytes on inbuf, 0 on outbuf. Oct 11 36:07.0 [info] run_connection_housekeeping(): Expiring non-open OR connection to fd 4 (194.109.206.212:443). Oct 11 36:07.0 [debug] connection_mark_for_close_internal_(): Calling connection_mark_for_close_internal_() on an OR conn at ../src/or/connection_or.c:1311 Oct 11 36:07.0 [debug] channel_close_from_lower_layer(): Closing channel 0x5687edb8 (global ID 12) due to lower-layer event Oct 11 36:07.0 [debug] channel_change_state(): Changing state of channel 0x5687edb8 (global ID 12) from "opening" to "closing" Oct 11 36:07.0 [debug] channel_remove_from_digest_map(): Removed channel 0x5687edb8 (global ID 12) from identity map in state closing (4) with digest 7EA6EAD6FD83083C538F44038BBFA077587DD755 Oct 11 36:07.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 4). Oct 11 36:07.0 [debug] circuit_n_chan_done(): chan to NULL/194.109.206.212:443, status=0 Oct 11 36:07.0 [info] circuit_n_chan_done(): Channel failed; closing circ. Oct 11 36:07.0 [info] circuit_build_failed(): Our circuit died before the first hop with no connection Oct 11 36:07.0 [debug] circuit_increment_failure_count(): n_circuit_failures now 1. Oct 11 36:07.0 [debug] channel_change_state(): Changing state of channel 0x5687edb8 (global ID 12) from "closing" to "closed" Oct 11 36:07.0 [info] connection_or_note_state_when_broken(): Connection died in state 'handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE' Oct 11 36:07.0 [warn] Problem bootstrapping. Stuck at 10%: Finishing handshake with directory server. (DONE; DONE; count 13; recommendation warn) Oct 11 36:07.0 [warn] 13 connections have failed: Oct 11 36:07.0 [warn] 11 connections died in state handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE Oct 11 36:07.0 [warn] 2 connections died in state connect()ing with SSL state (No SSL object) Oct 11 36:07.0 [debug] connection_remove(): removing socket 4 (type OR), n_conns now 5 Oct 11 36:07.0 [debug] connection_free_(): closing fd 4. Oct 11 36:07.0 [debug] channel_free(): Freeing channel 12 at 0x5687edb8 Oct 11 36:07.0 [debug] channel_clear_remote_end(): Clearing remote endpoint identity on channel 0x5687edb8 with global ID 12 Oct 11 36:07.0 [debug] circuitmux_free(): Freeing cmux at 0x569a8730 with no queued destroys, the cmux destroy balance was 0, global is 0 Oct 11 38:14.0 [info] update_consensus_networkstatus_downloads(): Launching microdesc networkstatus consensus download. Oct 11 38:14.0 [info] directory_pick_generic_dirserver(): No router found for consensus network-status fetch; falling back to dirserver list. Oct 11 38:14.0 [debug] directory_initiate_command_rend(): anonymized 0, use_begindir 1. Oct 11 38:14.0 [debug] directory_initiate_command_rend(): Initiating consensus network-status fetch Oct 11 38:14.0 [info] connection_ap_make_link(): Making internal direct tunnel to [scrubbed]:443 ... Oct 11 38:14.0 [debug] connection_add_impl(): new conn type Socks, socket -1, address (Tor_internal), n_conns 5. Oct 11 38:14.0 [debug] circuit_get_open_circ_or_launch(): considering 1, $F2044413DAC2E02E3D6BCF4735A19BCA1DE97281 Oct 11 38:14.0 [debug] onion_pick_cpath_exit(): Launching a one-hop circuit for dir tunnel. Oct 11 38:14.0 [info] onion_pick_cpath_exit(): Using requested exit node '$F2044413DAC2E02E3D6BCF4735A19BCA1DE97281F2044413DAC2E02E3D6 at 131.188.40.189' Oct 11 38:14.0 [debug] onion_extend_cpath(): Path is 0 long; we want 1 Oct 11 38:14.0 [debug] onion_extend_cpath(): Chose router $F2044413DAC2E02E3D6BCF4735A19BCA1DE97281F2044413DAC2E02E3D6 at 131.188.40.189 for hop 1 (exit is F2044413DAC2E02E3D6BCF4735A19BCA1DE97281) Oct 11 38:14.0 [debug] onion_extend_cpath(): Path is complete: 1 steps long Oct 11 38:14.0 [debug] circuit_handle_first_hop(): Looking for firsthop '131.188.40.189:443' Oct 11 38:14.0 [info] circuit_handle_first_hop(): Next router is [scrubbed]: Not connected. Connecting. Oct 11 38:14.0 [debug] channel_tls_connect(): In channel_tls_connect() for channel 0x5687edb8 (global id 13) Oct 11 38:14.0 [debug] channel_tls_connect(): Marking new outgoing channel 13 at 0x5687edb8 as remote Oct 11 38:14.0 [debug] channel_set_identity_digest(): Setting remote endpoint digest on channel 0x5687edb8 with global ID 13 to digest F2044413DAC2E02E3D6BCF4735A19BCA1DE97281 Oct 11 38:14.0 [debug] connection_connect(): Connecting to [scrubbed]:443. Oct 11 38:14.0 [debug] connection_connect(): Connection to [scrubbed]:443 in progress (sock 4). Oct 11 38:14.0 [debug] connection_add_impl(): new conn type OR, socket 4, address 131.188.40.189, n_conns 6. Oct 11 38:14.0 [debug] channel_tls_connect(): Got orconn 0x569c9328 for channel with global id 13 Oct 11 38:14.0 [debug] channel_register(): Registering channel 0x5687edb8 (ID 13) in state opening (1) with digest F2044413DAC2E02E3D6BCF4735A19BCA1DE97281 Oct 11 38:14.0 [debug] channel_add_to_digest_map(): Added channel 0x5687edb8 (global ID 13) to identity map in state opening (1) with digest F2044413DAC2E02E3D6BCF4735A19BCA1DE97281 Oct 11 38:14.0 [debug] channel_set_cell_handlers(): Setting cell_handler callback for channel 0x5687edb8 to 0x54b791a8 Oct 11 38:14.0 [debug] channel_set_cell_handlers(): Setting var_cell_handler callback for channel 0x5687edb8 to 0x54b7865c Oct 11 38:14.0 [debug] circuit_handle_first_hop(): connecting in progress (or finished). Good. Oct 11 38:14.0 [info] connection_ap_make_link(): ... application connection created and linked. Oct 11 38:14.0 [debug] connection_add_impl(): new conn type Directory, socket -1, address 131.188.40.189, n_conns 7. Oct 11 38:14.0 [info] directory_send_command(): Downloading consensus from 131.188.40.189:443 using /tor/status-vote/current/consensus-microdesc/0232AF+14C131+23D15D+49015F+D586D1+E8A9C4+ED03BB+EFCBE7.z Oct 11 38:14.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 38:14.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 38:14.0 [info] connection_edge_process_inbuf(): data from edge while in 'waiting for circuit' state. Leaving it on buffer. Oct 11 38:14.0 [debug] connection_dir_finished_flushing(): client finished sending command. Oct 11 38:14.0 [debug] conn_write_callback(): socket 4 wants to write. Oct 11 38:14.0 [debug] connection_or_finished_connecting(): OR connect() to router at 131.188.40.189:443 finished. Oct 11 38:14.0 [debug] connection_tls_start_handshake(): starting TLS handshake on fd 4 Oct 11 38:14.0 [debug] tor_tls_handshake(): About to call SSL_connect on 0x5687d8e8 (before/accept initialization) Oct 11 38:14.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687e960 is now in state before/connect initialization [type=16,val=1]. Oct 11 38:14.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687e960 is now in state before/connect initialization [type=4097,val=1]. Oct 11 38:14.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687e960 is now in state SSLv2/v3 write client hello A [type=4097,val=1]. Oct 11 38:14.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687e960 is now in state SSLv2/v3 read server hello A [type=4098,val=-1]. Oct 11 38:14.0 [debug] tor_tls_handshake(): After call, 0x5687d8e8 was in state SSLv2/v3 read server hello A Oct 11 38:14.0 [debug] connection_tls_continue_handshake(): wanted read Oct 11 38:14.0 [debug] tor_tls_handshake(): About to call SSL_connect on 0x5687d8e8 (SSLv2/v3 read server hello A) Oct 11 38:14.0 [debug] tor_tls_debug_state_callback(): SSL 0x5687e960 is now in state SSLv2/v3 read server hello A [type=4098,val=-1]. Oct 11 38:14.0 [debug] connection_tls_continue_handshake(): wanted read Oct 11 40:14.0 [info] connection_ap_expire_beginning(): Tried for 120 seconds to get a connection to [scrubbed]:443. Giving up. (waiting for circuit) Oct 11 40:14.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 40:14.0 [debug] connection_remove(): removing socket -1 (type Socks), n_conns now 7 Oct 11 40:14.0 [info] connection_free_(): Freeing linked Socks connection [waiting for circuit] with 145 bytes on inbuf, 0 on outbuf. Oct 11 40:14.0 [debug] conn_read_callback(): socket -1 wants to read. Oct 11 40:14.0 [info] connection_dir_client_reached_eof(): 'fetch' response not all here, but we're at eof. Closing. Oct 11 40:14.0 [debug] conn_close_if_marked(): Cleaning up connection (fd -1). Oct 11 40:14.0 [debug] connection_remove(): removing socket -1 (type Directory), n_conns now 6 Oct 11 40:14.0 [info] connection_free_(): Freeing linked Directory connection [client reading] with 0 bytes on inbuf, 0 on outbuf. Oct 11 43:14.0 [info] run_connection_housekeeping(): Expiring non-open OR connection to fd 4 (131.188.40.189:443). Oct 11 43:14.0 [debug] connection_mark_for_close_internal_(): Calling connection_mark_for_close_internal_() on an OR conn at ../src/or/connection_or.c:1311 Oct 11 43:14.0 [debug] channel_close_from_lower_layer(): Closing channel 0x5687edb8 (global ID 13) due to lower-layer event Oct 11 43:14.0 [debug] channel_change_state(): Changing state of channel 0x5687edb8 (global ID 13) from "opening" to "closing" Oct 11 43:14.0 [debug] channel_remove_from_digest_map(): Removed channel 0x5687edb8 (global ID 13) from identity map in state closing (4) with digest F2044413DAC2E02E3D6BCF4735A19BCA1DE97281 Oct 11 43:14.0 [debug] conn_close_if_marked(): Cleaning up connection (fd 4). Oct 11 43:14.0 [debug] circuit_n_chan_done(): chan to NULL/131.188.40.189:443, status=0 Oct 11 43:14.0 [info] circuit_n_chan_done(): Channel failed; closing circ. Oct 11 43:14.0 [info] circuit_build_failed(): Our circuit died before the first hop with no connection Oct 11 43:14.0 [debug] circuit_increment_failure_count(): n_circuit_failures now 1. Oct 11 43:14.0 [debug] channel_change_state(): Changing state of channel 0x5687edb8 (global ID 13) from "closing" to "closed" Oct 11 43:14.0 [info] connection_or_note_state_when_broken(): Connection died in state 'handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE' Oct 11 43:14.0 [warn] Problem bootstrapping. Stuck at 10%: Finishing handshake with directory server. (DONE; DONE; count 14; recommendation warn) Oct 11 43:14.0 [warn] 14 connections have failed: Oct 11 43:14.0 [warn] 12 connections died in state handshaking (TLS) with SSL state SSLv2/v3 read server hello A in HANDSHAKE Oct 11 43:14.0 [warn] 2 connections died in state connect()ing with SSL state (No SSL object) Oct 11 43:14.0 [debug] connection_remove(): removing socket 4 (type OR), n_conns now 5 Oct 11 43:14.0 [debug] connection_free_(): closing fd 4. Oct 11 43:14.0 [debug] channel_free(): Freeing channel 13 at 0x5687edb8 Oct 11 43:14.0 [debug] channel_clear_remote_end(): Clearing remote endpoint identity on channel 0x5687edb8 with global ID 13 Oct 11 43:14.0 [debug] circuitmux_free(): Freeing cmux at 0x5687eea8 with no queued destroys, the cmux destroy balance was 0, global is 0

Trac:
Username: y.net