[Author Prev][Author Next][Thread Prev][Thread Next][Author Index][Thread Index]

Re: Tor 0.2.1.7-alpha is out



Roger Dingledine <arma@xxxxxxx> wrote:

> Changes in version 0.2.1.7-alpha - 2008-11-08

>   o Minor bugfixes:
>     - Minor fix in the warning messages when you're having problems
>       bootstrapping; also, be more forgiving of bootstrap problems when
>       we're still making incremental progress on a given bootstrap phase.

I'm not sure if it's related to this change,
but bootstrapping no longer works for me on
FreeBSD 8.0-CURRENT:

Nov 23 18:59:08.931 [notice] Tor 0.2.1.7-alpha-dev (r17366) opening log file.
Nov 23 18:59:08.945 [notice] Parsing GEOIP file.
Nov 23 18:59:10.160 [notice] This version of Tor (0.2.1.7-alpha-dev) is newer than any recommended version, according to the directory authorities. Recommended versions are: 0.1.2.17,0.1.2.18,0.1.2.19,0.2.0.26-rc,0.2.0.27-rc,0.2.0.28-rc,0.2.0.29-rc,0.2.0.30,0.2.0.31,0.2.0.32,0.2.1.1-alpha,0.2.1.2-alpha,0.2.1.4-alpha,0.2.1.5-alpha,0.2.1.6-alpha,0.2.1.7-alpha
Nov 23 18:59:11.347 [notice] Bootstrapped 45%: Asking for relay descriptors.
Nov 23 18:59:11.347 [notice] I learned some more directory information, but not enough to build a circuit: We have only 0/1259 usable descriptors.
Nov 23 18:59:12.163 [notice] Bootstrapped 50%: Loading relay descriptors.
Nov 23 18:59:12.741 [notice] I learned some more directory information, but not enough to build a circuit: We have only 0/1259 usable descriptors.
[repeated 31 times]
Nov 23 18:59:28.774 [warn] Problem bootstrapping. Stuck at 50%: Loading relay descriptors. (Tor gave up on the connection; TIMEOUT; count 10; recommendation warn)
Nov 23 18:59:28.805 [warn] Problem bootstrapping. Stuck at 50%: Loading relay descriptors. (Tor gave up on the connection; TIMEOUT; count 11; recommendation warn)
Nov 23 18:59:28.810 [warn] Problem bootstrapping. Stuck at 50%: Loading relay descriptors. (DONE; DONE; count 12; recommendation warn)
Nov 23 18:59:28.810 [warn] Problem bootstrapping. Stuck at 50%: Loading relay descriptors. (DONE; DONE; count 13; recommendation warn)
Nov 23 18:59:28.872 [notice] I learned some more directory information, but not enough to build a circuit: We have only 0/1259 usable descriptors.
[Seems to loop endlessly here]

I have a complete debug log if any Tor developer is interested.
Does the following indicate that Tor is trying to use invalid sockets?

Nov 23 15:05:14.034 [debug] relay_lookup_conn(): found conn for stream 17393.
Nov 23 15:05:14.035 [debug] circuit_receive_relay_cell(): Sending to origin.
Nov 23 15:05:14.035 [debug] connection_edge_process_relay_cell(): Now seen 343 relay cells here.
Nov 23 15:05:14.035 [debug] connection_edge_process_relay_cell(): circ deliver_window now 992.
Nov 23 15:05:14.035 [debug] connection_or_process_cells_from_inbuf(): 28: starting, inbuf_datalen 496 (0 pending in tls object).
Nov 23 15:05:14.035 [debug] conn_write_callback(): socket 19 wants to write.
Nov 23 15:05:14.035 [debug] flush_chunk_tls(): flushed 512 bytes, 0 ready to flush, 0 remain.
Nov 23 15:05:14.035 [debug] connection_handle_write(): After TLS write of 512: 0 read, 586 written
Nov 23 15:05:14.035 [debug] connection_or_flush_from_first_active_circuit(): Made a circuit inactive.
Nov 23 15:05:14.035 [debug] conn_read_callback(): socket 28 wants to read.
Nov 23 15:05:14.036 [debug] connection_read_to_buf(): 28: starting, inbuf_datalen 496 (0 pending in tls object). at_most 16384.
Nov 23 15:05:14.036 [debug] tor_tls_read(): read returned r=-1, err=-2
Nov 23 15:05:14.036 [debug] connection_read_to_buf(): After TLS read of 0: 1440 read, 0 written
Nov 23 15:05:14.036 [debug] connection_or_process_cells_from_inbuf(): 28: starting, inbuf_datalen 496 (0 pending in tls object).
Nov 23 15:05:14.036 [debug] conn_write_callback(): socket 19 wants to write.
Nov 23 15:05:14.036 [debug] flush_chunk_tls(): flushed 512 bytes, 0 ready to flush, 0 remain.
Nov 23 15:05:14.036 [debug] connection_handle_write(): After TLS write of 512: 0 read, 586 written
Nov 23 15:05:14.036 [debug] conn_read_callback(): socket -1 wants to read.
Nov 23 15:05:14.036 [debug] connection_dir_process_inbuf(): Got data, not eof. Leaving on inbuf.
Nov 23 15:05:14.045 [debug] conn_read_callback(): socket 21 wants to read.
Nov 23 15:05:14.045 [debug] connection_read_to_buf(): 21: starting, inbuf_datalen 0 (0 pending in tls object). at_most 16384.
Nov 23 15:05:14.046 [debug] connection_read_to_buf(): After TLS read of 4080: 1258 read, 0 written
Nov 23 15:05:14.046 [debug] connection_or_process_cells_from_inbuf(): 21: starting, inbuf_datalen 4080 (0 pending in tls object).

According to the debug log, tor_tls_read() returns -1 all the time:
fk@TP51 ~ $grep -c "tor_tls_read(): read returned r=-1, err=-2" test/tor/debug.log 
757
fk@TP51 ~ $grep "tor_tls_read(): read" test/tor/debug.log | grep -v -c "r=-1"
0

Tor 0.2.1.6-alpha bootstraps without problems:

Nov 23 19:38:00.572 [notice] Tor 0.2.1.6-alpha (r17011) opening log file.
Nov 23 19:38:00.584 [notice] Parsing GEOIP file.
Nov 23 19:38:01.119 [notice] Bootstrapped 45%: Asking for relay descriptors.
Nov 23 19:38:01.119 [notice] I learned some more directory information, but not enough to build a circuit: We have only 0/1260 usable descriptors.
Nov 23 19:38:02.022 [notice] Bootstrapped 50%: Loading relay descriptors.
Nov 23 19:38:02.926 [notice] Bootstrapped 58%: Loading relay descriptors.
Nov 23 19:38:02.927 [notice] I learned some more directory information, but not enough to build a circuit: We have only 96/1259 usable descriptors.
Nov 23 19:38:03.370 [notice] Bootstrapped 67%: Loading relay descriptors.
Nov 23 19:38:03.370 [notice] I learned some more directory information, but not enough to build a circuit: We have only 188/1259 usable descriptors.
Nov 23 19:38:05.275 [notice] Bootstrapped 70%: Loading relay descriptors.
Nov 23 19:38:05.297 [notice] I learned some more directory information, but not enough to build a circuit: We have only 220/1259 usable descriptors.
Nov 23 19:38:11.469 [notice] Bootstrapped 73%: Loading relay descriptors.
Nov 23 19:38:11.470 [notice] I learned some more directory information, but not enough to build a circuit: We have only 250/1254 usable descriptors.
Nov 23 19:38:12.002 [notice] We now have enough directory information to build circuits.
Nov 23 19:38:12.002 [notice] Bootstrapped 80%: Connecting to the Tor network.
Nov 23 19:38:12.006 [notice] Bootstrapped 85%: Finishing handshake with first hop.
Nov 23 19:38:13.673 [notice] Bootstrapped 90%: Establishing a Tor circuit.
Nov 23 19:38:21.262 [notice] Tor has successfully opened a circuit. Looks like client functionality is working.
Nov 23 19:38:21.263 [notice] Bootstrapped 100%: Done.

Fabian

Attachment: signature.asc
Description: PGP signature