Opened 13 years ago

Last modified 7 years ago

#312 closed defect (Works for me)

100% CPU Usage

Reported by: aaronsells Owned by:
Priority: High Milestone:
Component: Core Tor/Tor Version: 0.1.1.20
Severity: Keywords:
Cc: aaronsells Actual Points:
Parent ID: Points:
Reviewer: Sponsor:

Description

When used in conjunction with Azureus, tor continuously uses 100% of the CPU. Stopping and starting tor will halt the CPU usage for a few minutes, but the problem inevitably returns.

OS: Windows XP SP2
CPU: AMD Athlon 64 3500+ (NewCastle)

Azureus: 2.4.0.2
Vidalia: 0.0.5
Tor: 0.1.1.21
Qt: 4.1.0

[Automatically added by flyspray2trac: Operating System: Windows 2k/XP]

Child Tickets

Change History (11)

comment:1 Changed 13 years ago by nickm

  1. You *do* know that we specifically ask you *not* to use high-bandwidth P2P apps with the

Tor network, right?

  1. Can you run tor with 'debug' level logging enabled? Does it spend all its time building

circuits, or what?

comment:2 Changed 13 years ago by aaronsells

we specifically ask you *not* to use high-bandwidth P2P apps with the Tor network

I'm only using Tor for the tracker communications.

Can you run tor with 'debug' level logging enabled?

Jun 30 17:40:21:562 [Info] exit circ (length 3, exit Snark): serifos(open) logireach(open) Snark(open)
Jun 30 17:40:21:562 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:21:562 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:21:562 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:21:562 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:21:562 [Info] choose_good_exit_server_general(): Chose exit server 'arigatto'
Jun 30 17:40:21:562 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:21:562 [Debug] onion_extend_cpath(): Chose router redgene for hop 1 (exit is arigatto)
Jun 30 17:40:21:562 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:21:562 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:21:562 [Debug] onion_extend_cpath(): Chose router Emzy for hop 2 (exit is arigatto)
Jun 30 17:40:21:562 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:21:562 [Debug] onion_extend_cpath(): Chose router arigatto for hop 3 (exit is arigatto)
Jun 30 17:40:21:562 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:21:562 [Debug] circuit_handle_first_hop(): Looking for firsthop '85.31.186.61:9001'
Jun 30 17:40:21:562 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:21:562 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:21:562 [Debug] circuit_deliver_create_cell(): Chosen circID 18346.
Jun 30 17:40:21:562 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:21:562 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'redgene'
Jun 30 17:40:21:562 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:21:562 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:21:562 [Info] choose_good_exit_server_general(): Chose exit server 'polkadot'
Jun 30 17:40:21:562 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:21:562 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is polkadot)
Jun 30 17:40:21:562 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:21:562 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:21:562 [Debug] onion_extend_cpath(): Chose router FreedomVoice for hop 2 (exit is polkadot)
Jun 30 17:40:21:562 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:21:562 [Debug] onion_extend_cpath(): Chose router polkadot for hop 3 (exit is polkadot)
Jun 30 17:40:21:562 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:21:562 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:21:562 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:21:562 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:21:562 [Debug] circuit_deliver_create_cell(): Chosen circID 26171.
Jun 30 17:40:21:578 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:21:578 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:21:578 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:21:578 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:21:578 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:21:578 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:21:578 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:21:578 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:21:578 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:21:578 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:21:578 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:21:578 [Info] exit circ (length 3, exit reversed): redgene(open) b0red(closed) reversed(closed)
Jun 30 17:40:21:578 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:21:578 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:21:578 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:21:578 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:21:578 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:21:578 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:21:578 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:21:578 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:21:578 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:21:578 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:21:578 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:21:578 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:21:578 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:21:578 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:21:578 [Debug] connection_edge_process_relay_cell(): Now seen 616 relay cells here.
Jun 30 17:40:21:578 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:21:578 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:21:578 [Info] exit circ (length 3, exit reversed): ThunderGodOdin2(open) aaron(open) reversed(open)
Jun 30 17:40:21:578 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:21:578 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:21:578 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:21:578 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:21:578 [Info] choose_good_exit_server_general(): Chose exit server 'cust81tor'
Jun 30 17:40:21:578 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:21:578 [Debug] onion_extend_cpath(): Chose router redgene for hop 1 (exit is cust81tor)
Jun 30 17:40:21:578 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:21:578 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:21:578 [Debug] onion_extend_cpath(): Chose router tetra4 for hop 2 (exit is cust81tor)
Jun 30 17:40:21:578 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:21:578 [Debug] onion_extend_cpath(): Chose router cust81tor for hop 3 (exit is cust81tor)
Jun 30 17:40:21:578 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:21:578 [Debug] circuit_handle_first_hop(): Looking for firsthop '85.31.186.61:9001'
Jun 30 17:40:21:578 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:21:578 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:21:578 [Debug] circuit_deliver_create_cell(): Chosen circID 18347.
Jun 30 17:40:21:578 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:21:578 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'redgene'
Jun 30 17:40:21:578 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:21:578 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:21:578 [Info] choose_good_exit_server_general(): Chose exit server 'tor26'
Jun 30 17:40:21:578 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:21:578 [Debug] onion_extend_cpath(): Chose router ThunderGodOdin2 for hop 1 (exit is tor26)
Jun 30 17:40:21:578 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:21:578 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:21:578 [Debug] onion_extend_cpath(): Chose router flatlineDE for hop 2 (exit is tor26)
Jun 30 17:40:21:578 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:21:578 [Debug] onion_extend_cpath(): Chose router tor26 for hop 3 (exit is tor26)
Jun 30 17:40:21:578 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:21:578 [Debug] circuit_handle_first_hop(): Looking for firsthop '63.211.169.142:9001'
Jun 30 17:40:21:578 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:21:578 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:21:578 [Debug] circuit_deliver_create_cell(): Chosen circID 47022.
Jun 30 17:40:21:578 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:21:578 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'ThunderGodOdin2'
Jun 30 17:40:21:578 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:21:578 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:21:578 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:21:578 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:21:578 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:21:578 [Debug] read_to_buf_tls_impl(): Read 1024 bytes. 1024 on inbuf; 0 pending
Jun 30 17:40:21:578 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 1024 (0 pending in tls object).
Jun 30 17:40:21:578 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:21:578 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:21:578 [Info] exit circ (length 3, exit caronte): serifos(open) tetra4(closed) caronte(closed)
Jun 30 17:40:21:578 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:21:578 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:21:578 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:21:578 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:21:578 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:21:578 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:21:578 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:21:578 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:21:578 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:21:578 [Info] exit circ (length 3, exit rfc1149): serifos(open) Karlchen(closed) rfc1149(closed)
Jun 30 17:40:21:578 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:21:578 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:21:578 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:21:578 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:21:578 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:21:578 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:21:578 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:21:578 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:21:578 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:21:578 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:21:578 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:21:578 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:21:578 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:21:578 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:21:578 [Debug] connection_edge_process_relay_cell(): Now seen 617 relay cells here.
Jun 30 17:40:21:578 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:21:578 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:21:578 [Info] exit circ (length 3, exit HTTPDNet): redgene(open) gvg1(open) HTTPDNet(closed)
Jun 30 17:40:21:578 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:21:578 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:21:578 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:21:578 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:21:578 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:21:578 [Debug] write_to_buf(): added 512 bytes to buf (now 2048 total).
Jun 30 17:40:21:578 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:22:015 [Debug] conn_write_callback(): socket 1676 wants to write.
Jun 30 17:40:22:015 [Debug] flush_buf_tls_impl(): flushed 1024 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:22:015 [Debug] conn_write_callback(): socket 1640 wants to write.
Jun 30 17:40:22:015 [Debug] flush_buf_tls_impl(): flushed 2048 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:22:015 [Debug] conn_write_callback(): socket 1696 wants to write.
Jun 30 17:40:22:015 [Debug] flush_buf_tls_impl(): flushed 1536 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:22:015 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:22:015 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:22:015 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:22:015 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:22:015 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:22:015 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:22:015 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:22:031 [Debug] connection_edge_process_relay_cell(): Now seen 618 relay cells here.
Jun 30 17:40:22:031 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:22:031 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:22:031 [Info] exit circ (length 3, exit FoeBuD): ThunderGodOdin2(open) bestleftunsaid(open) FoeBuD(closed)
Jun 30 17:40:22:031 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:22:031 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:22:031 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:22:031 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:22:031 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:22:031 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:22:031 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:22:031 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:22:031 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:22:031 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:22:031 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:22:031 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:22:031 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:22:031 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:22:031 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:22:031 [Info] exit circ (length 3, exit ccc2): serifos(open) 1898(closed) ccc2(closed)
Jun 30 17:40:22:031 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:22:031 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:22:031 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:22:031 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:22:031 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:22:031 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:22:031 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:22:031 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:22:031 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:22:031 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:22:031 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:22:031 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:22:031 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:22:031 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:22:031 [Debug] connection_edge_process_relay_cell(): Now seen 619 relay cells here.
Jun 30 17:40:22:031 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:22:031 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:22:031 [Info] exit circ (length 3, exit wormhole): redgene(open) tetra4(open) wormhole(open)
Jun 30 17:40:22:031 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:22:031 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:22:031 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:22:031 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:22:031 [Info] choose_good_exit_server_general(): Chose exit server 'jap'
Jun 30 17:40:22:031 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:22:031 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is jap)
Jun 30 17:40:22:031 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:22:031 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:22:031 [Debug] onion_extend_cpath(): Chose router spoon for hop 2 (exit is jap)
Jun 30 17:40:22:031 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:22:031 [Debug] onion_extend_cpath(): Chose router jap for hop 3 (exit is jap)
Jun 30 17:40:22:031 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:22:031 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:22:031 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:22:031 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:22:031 [Debug] circuit_deliver_create_cell(): Chosen circID 26172.
Jun 30 17:40:22:031 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:22:031 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:22:031 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:22:031 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:22:031 [Info] choose_good_exit_server_general(): Chose exit server 'Scooby'
Jun 30 17:40:22:031 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:22:031 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is Scooby)
Jun 30 17:40:22:031 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:22:031 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:22:031 [Debug] onion_extend_cpath(): Chose router irithimus for hop 2 (exit is Scooby)
Jun 30 17:40:22:031 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:22:031 [Debug] onion_extend_cpath(): Chose router Scooby for hop 3 (exit is Scooby)
Jun 30 17:40:22:031 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:22:031 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:22:031 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:22:031 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:22:031 [Debug] circuit_deliver_create_cell(): Chosen circID 26173.
Jun 30 17:40:22:031 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:22:031 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:22:031 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:22:921 [Info] circuit_expire_building(): Abandoning circ 140.247.62.119:9001:26081 (state 0:doing handshakes, purpose 5)
Jun 30 17:40:22:921 [Info] exit circ (length 3, exit mauger): serifos(open) jipjipjip2(waiting for keys) mauger(closed)
Jun 30 17:40:22:921 [Debug] circuit_increment_failure_count(): n_circuit_failures now 1.
Jun 30 17:40:22:921 [Debug] connection_or_send_destroy(): Sending destroy (circID 26081).
Jun 30 17:40:22:921 [Debug] write_to_buf(): added 512 bytes to buf (now 2048 total).
Jun 30 17:40:22:921 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:22:921 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:22:921 [Info] choose_good_exit_server_general(): Chose exit server 'tor26'
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Chose router redgene for hop 1 (exit is tor26)
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:22:921 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Chose router nobodyeditedaconfig for hop 2 (exit is tor26)
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Chose router tor26 for hop 3 (exit is tor26)
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:22:921 [Debug] circuit_handle_first_hop(): Looking for firsthop '85.31.186.61:9001'
Jun 30 17:40:22:921 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:22:921 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:22:921 [Debug] circuit_deliver_create_cell(): Chosen circID 18348.
Jun 30 17:40:22:921 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:22:921 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'redgene'
Jun 30 17:40:22:921 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:22:921 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:22:921 [Info] choose_good_exit_server_general(): Chose exit server 'HTTPDNet'
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Chose router ThunderGodOdin2 for hop 1 (exit is HTTPDNet)
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:22:921 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Chose router BuddhaVsEntropy for hop 2 (exit is HTTPDNet)
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Chose router HTTPDNet for hop 3 (exit is HTTPDNet)
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:22:921 [Debug] circuit_handle_first_hop(): Looking for firsthop '63.211.169.142:9001'
Jun 30 17:40:22:921 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:22:921 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:22:921 [Debug] circuit_deliver_create_cell(): Chosen circID 47023.
Jun 30 17:40:22:921 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:22:921 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'ThunderGodOdin2'
Jun 30 17:40:22:921 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:22:921 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:22:921 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:22:921 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:22:921 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:22:921 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:22:921 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:22:921 [Debug] connection_edge_process_relay_cell(): Now seen 620 relay cells here.
Jun 30 17:40:22:921 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:22:921 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:22:921 [Info] exit circ (length 3, exit Zwiebelkuchen): ThunderGodOdin2(open) sabotage(open) Zwiebelkuchen(closed)
Jun 30 17:40:22:921 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:22:921 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:22:921 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:22:921 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:22:921 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:22:921 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:22:921 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:22:921 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:22:921 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:22:921 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:22:921 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:22:921 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:22:921 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:22:921 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:22:921 [Debug] connection_edge_process_relay_cell(): Now seen 621 relay cells here.
Jun 30 17:40:22:921 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:22:921 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:22:921 [Info] exit circ (length 3, exit einfallstor): serifos(open) morgentau(open) einfallstor(open)
Jun 30 17:40:22:921 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:22:921 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:22:921 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:22:921 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:22:921 [Info] choose_good_exit_server_general(): Chose exit server 'einfallstor'
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Chose router ThunderGodOdin2 for hop 1 (exit is einfallstor)
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:22:921 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:22:921 [Debug] onion_extend_cpath(): Chose router ccc2 for hop 2 (exit is einfallstor)
Jun 30 17:40:22:937 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:22:937 [Debug] onion_extend_cpath(): Chose router einfallstor for hop 3 (exit is einfallstor)
Jun 30 17:40:22:937 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:22:937 [Debug] circuit_handle_first_hop(): Looking for firsthop '63.211.169.142:9001'
Jun 30 17:40:22:937 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:22:937 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:22:937 [Debug] circuit_deliver_create_cell(): Chosen circID 47024.
Jun 30 17:40:22:937 [Debug] write_to_buf(): added 512 bytes to buf (now 2048 total).
Jun 30 17:40:22:937 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'ThunderGodOdin2'
Jun 30 17:40:22:937 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:22:937 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:22:937 [Info] choose_good_exit_server_general(): Chose exit server 'rZn'
Jun 30 17:40:22:937 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:22:937 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is rZn)
Jun 30 17:40:22:937 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:22:937 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:22:937 [Debug] onion_extend_cpath(): Chose router Zwerg2k for hop 2 (exit is rZn)
Jun 30 17:40:22:937 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:22:937 [Debug] onion_extend_cpath(): Chose router rZn for hop 3 (exit is rZn)
Jun 30 17:40:22:937 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:22:937 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:22:937 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:22:937 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:22:937 [Debug] circuit_deliver_create_cell(): Chosen circID 26174.
Jun 30 17:40:22:937 [Debug] write_to_buf(): added 512 bytes to buf (now 2560 total).
Jun 30 17:40:22:937 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:22:937 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:22:937 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:22:937 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:22:937 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:22:937 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:22:937 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:22:937 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:22:937 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:22:937 [Debug] connection_edge_process_relay_cell(): Now seen 622 relay cells here.
Jun 30 17:40:22:937 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:22:937 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:22:937 [Info] exit circ (length 3, exit phobos): redgene(open) serifos(open) phobos(closed)
Jun 30 17:40:22:937 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:22:937 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:22:937 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:22:937 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:22:937 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:22:937 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:22:937 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:22:937 [Debug] conn_write_callback(): socket 1696 wants to write.
Jun 30 17:40:22:937 [Debug] flush_buf_tls_impl(): flushed 2560 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:22:937 [Debug] conn_write_callback(): socket 1676 wants to write.
Jun 30 17:40:22:937 [Debug] flush_buf_tls_impl(): flushed 2048 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:23:843 [Debug] conn_write_callback(): socket 1640 wants to write.
Jun 30 17:40:23:843 [Debug] flush_buf_tls_impl(): flushed 1024 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:23:843 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:23:843 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:23:843 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:23:843 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:23:843 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:23:843 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:23:843 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:23:843 [Debug] connection_edge_process_relay_cell(): Now seen 623 relay cells here.
Jun 30 17:40:23:843 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:23:843 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:23:843 [Info] exit circ (length 3, exit mrspliffdoingtor): ThunderGodOdin2(open) Zwiebelkuchen(open) mrspliffdoingtor(open)
Jun 30 17:40:23:843 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:23:843 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:23:843 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:23:843 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:23:843 [Info] choose_good_exit_server_general(): Chose exit server 'arigatto'
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Chose router redgene for hop 1 (exit is arigatto)
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:23:843 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Chose router sasquatch for hop 2 (exit is arigatto)
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Chose router arigatto for hop 3 (exit is arigatto)
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:23:843 [Debug] circuit_handle_first_hop(): Looking for firsthop '85.31.186.61:9001'
Jun 30 17:40:23:843 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:23:843 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:23:843 [Debug] circuit_deliver_create_cell(): Chosen circID 18349.
Jun 30 17:40:23:843 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:23:843 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'redgene'
Jun 30 17:40:23:843 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:23:843 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:23:843 [Info] choose_good_exit_server_general(): Chose exit server 'behindblueeyes'
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Chose router redgene for hop 1 (exit is behindblueeyes)
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:23:843 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Chose router mauger for hop 2 (exit is behindblueeyes)
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Chose router behindblueeyes for hop 3 (exit is behindblueeyes)
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:23:843 [Debug] circuit_handle_first_hop(): Looking for firsthop '85.31.186.61:9001'
Jun 30 17:40:23:843 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:23:843 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:23:843 [Debug] circuit_deliver_create_cell(): Chosen circID 18350.
Jun 30 17:40:23:843 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:23:843 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'redgene'
Jun 30 17:40:23:843 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:23:843 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:23:843 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:23:843 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:23:843 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:23:843 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:23:843 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:23:843 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:23:843 [Debug] connection_edge_process_relay_cell(): Now seen 624 relay cells here.
Jun 30 17:40:23:843 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:23:843 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:23:843 [Info] exit circ (length 3, exit distro): serifos(open) aala(open) distro(open)
Jun 30 17:40:23:843 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:23:843 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:23:843 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:23:843 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:23:843 [Info] choose_good_exit_server_general(): Chose exit server 'commodore64'
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is commodore64)
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:23:843 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Chose router lysander for hop 2 (exit is commodore64)
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Chose router commodore64 for hop 3 (exit is commodore64)
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:23:843 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:23:843 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:23:843 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:23:843 [Debug] circuit_deliver_create_cell(): Chosen circID 26175.
Jun 30 17:40:23:843 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:23:843 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:23:843 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:23:843 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:23:843 [Info] choose_good_exit_server_general(): Chose exit server 'rfc1149'
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is rfc1149)
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:23:843 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Chose router extrapolator for hop 2 (exit is rfc1149)
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Chose router rfc1149 for hop 3 (exit is rfc1149)
Jun 30 17:40:23:843 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:23:843 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:23:843 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:23:843 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:23:843 [Debug] circuit_deliver_create_cell(): Chosen circID 26176.
Jun 30 17:40:23:843 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:23:843 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:23:843 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:23:843 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:23:843 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:23:843 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:23:843 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:23:843 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:23:843 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:23:843 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:23:843 [Debug] connection_edge_process_relay_cell(): Now seen 625 relay cells here.
Jun 30 17:40:23:843 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:23:843 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:23:843 [Info] exit circ (length 3, exit flatlineDE): redgene(open) ni(open) flatlineDE(closed)
Jun 30 17:40:23:843 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:23:843 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:23:843 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:23:843 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:23:843 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:23:843 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:23:843 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:24:328 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:24:328 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:24:328 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:24:328 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:24:328 [Debug] read_to_buf_tls_impl(): Read 1536 bytes. 1536 on inbuf; 0 pending
Jun 30 17:40:24:328 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 1536 (0 pending in tls object).
Jun 30 17:40:24:328 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:24:328 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:24:328 [Info] exit circ (length 3, exit donk3ypunch): ThunderGodOdin2(open) polkadot(closed) donk3ypunch(closed)
Jun 30 17:40:24:328 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:24:328 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:24:328 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:24:328 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:24:328 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:24:328 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:24:328 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 1024 (0 pending in tls object).
Jun 30 17:40:24:328 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:24:328 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:24:328 [Info] exit circ (length 3, exit GreenForest2): ThunderGodOdin2(open) flyingboy(closed) GreenForest2(closed)
Jun 30 17:40:24:328 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:24:328 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:24:328 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:24:328 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:24:328 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:24:328 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:24:328 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:24:328 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:24:328 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:24:328 [Info] exit circ (length 3, exit arigatto): ThunderGodOdin2(open) yt1n1rt(closed) arigatto(closed)
Jun 30 17:40:24:328 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:24:328 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:24:328 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:24:328 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:24:328 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:24:328 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:24:328 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:24:328 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:24:328 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:24:328 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:24:328 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:24:328 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:24:328 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:24:328 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:24:328 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:24:328 [Info] exit circ (length 3, exit hardbody): serifos(open) JRBobDobbs(closed) hardbody(closed)
Jun 30 17:40:24:328 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:24:328 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:24:328 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:24:328 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:24:328 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:24:328 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:24:343 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:24:343 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:24:343 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:24:343 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:24:343 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:24:343 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:24:343 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:24:343 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:24:343 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:24:343 [Info] exit circ (length 3, exit ni): redgene(open) nobodyeditedaconfig(closed) ni(closed)
Jun 30 17:40:24:343 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:24:343 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:24:343 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:24:343 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:24:343 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:24:343 [Debug] write_to_buf(): added 512 bytes to buf (now 2048 total).
Jun 30 17:40:24:343 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:24:343 [Debug] conn_write_callback(): socket 1696 wants to write.
Jun 30 17:40:24:343 [Debug] flush_buf_tls_impl(): flushed 1536 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:24:343 [Debug] conn_write_callback(): socket 1640 wants to write.
Jun 30 17:40:24:343 [Debug] flush_buf_tls_impl(): flushed 2048 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:24:343 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:24:343 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:24:343 [Info] choose_good_exit_server_general(): Chose exit server 'tor26'
Jun 30 17:40:24:343 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:24:343 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is tor26)
Jun 30 17:40:24:343 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:24:343 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:24:343 [Debug] onion_extend_cpath(): Chose router blueice for hop 2 (exit is tor26)
Jun 30 17:40:24:343 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:24:343 [Debug] onion_extend_cpath(): Chose router tor26 for hop 3 (exit is tor26)
Jun 30 17:40:24:343 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:24:343 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:24:343 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:24:343 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:24:343 [Debug] circuit_deliver_create_cell(): Chosen circID 26177.
Jun 30 17:40:24:343 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:24:343 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:24:343 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:24:343 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:24:343 [Info] choose_good_exit_server_general(): Chose exit server 'lebut'
Jun 30 17:40:24:343 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:24:343 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is lebut)
Jun 30 17:40:24:343 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:24:343 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:24:343 [Debug] onion_extend_cpath(): Chose router i3jd883d for hop 2 (exit is lebut)
Jun 30 17:40:24:343 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:24:343 [Debug] onion_extend_cpath(): Chose router lebut for hop 3 (exit is lebut)
Jun 30 17:40:24:343 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:24:343 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:24:343 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:24:343 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:24:343 [Debug] circuit_deliver_create_cell(): Chosen circID 26178.
Jun 30 17:40:24:343 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:24:343 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:24:343 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:24:343 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:24:343 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:24:343 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:24:343 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:24:343 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:24:343 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:24:343 [Debug] connection_edge_process_relay_cell(): Now seen 626 relay cells here.
Jun 30 17:40:24:343 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:24:343 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:24:343 [Info] exit circ (length 3, exit leapingtomcruise): ThunderGodOdin2(open) ccc2(open) leapingtomcruise(closed)
Jun 30 17:40:24:343 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:24:343 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:24:343 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:24:343 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:24:343 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:24:343 [Debug] write_to_buf(): added 512 bytes to buf (now 2048 total).
Jun 30 17:40:24:343 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:24:343 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:24:343 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:24:343 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:24:343 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:24:343 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:24:343 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:24:343 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:24:343 [Debug] connection_edge_process_relay_cell(): Now seen 627 relay cells here.
Jun 30 17:40:24:343 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:24:343 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:24:343 [Info] exit circ (length 3, exit wormhole): serifos(open) FoeBuD(open) wormhole(closed)
Jun 30 17:40:24:343 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:24:343 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:24:343 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:24:343 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:24:359 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:24:359 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:24:359 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:24:359 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:24:359 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:24:359 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:24:359 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:24:359 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:24:359 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:24:359 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:24:359 [Debug] connection_edge_process_relay_cell(): Now seen 628 relay cells here.
Jun 30 17:40:24:359 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:24:359 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:24:359 [Info] exit circ (length 3, exit poptex): redgene(open) tortuga(open) poptex(closed)
Jun 30 17:40:24:359 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:24:359 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:24:359 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:24:359 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:24:359 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:24:359 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:24:359 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:24:359 [Debug] conn_write_callback(): socket 1676 wants to write.
Jun 30 17:40:24:359 [Debug] flush_buf_tls_impl(): flushed 2048 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:24:843 [Debug] conn_write_callback(): socket 1696 wants to write.
Jun 30 17:40:24:843 [Debug] flush_buf_tls_impl(): flushed 1536 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:24:843 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:24:843 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:24:843 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:24:843 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:24:843 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:24:843 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:24:843 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:24:843 [Debug] connection_edge_process_relay_cell(): Now seen 629 relay cells here.
Jun 30 17:40:24:843 [Debug] circuit_increment_failure_count(): n_circuit_failures now 1.
Jun 30 17:40:24:843 [Debug] connection_or_send_destroy(): Sending destroy (circID 47016).
Jun 30 17:40:24:843 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:24:843 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:24:843 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:24:843 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:24:843 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:24:843 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:24:843 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:24:843 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:24:843 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:24:843 [Debug] connection_edge_process_relay_cell(): Now seen 630 relay cells here.
Jun 30 17:40:24:843 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:24:843 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:24:843 [Info] exit circ (length 3, exit err): serifos(open) SEC(open) err(closed)
Jun 30 17:40:24:843 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:24:843 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:24:843 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:24:843 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:24:843 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:24:843 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:24:843 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:24:843 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:24:843 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:24:843 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:24:843 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:24:843 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:24:843 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:24:843 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:24:843 [Debug] connection_edge_process_relay_cell(): Now seen 631 relay cells here.
Jun 30 17:40:24:843 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:24:843 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:24:843 [Info] exit circ (length 3, exit cyberprofilers): redgene(open) Bellum(open) cyberprofilers(open)
Jun 30 17:40:24:843 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:24:843 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:24:843 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:24:843 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:24:843 [Info] choose_good_exit_server_general(): Chose exit server 'tortuga'
Jun 30 17:40:24:843 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:24:843 [Debug] onion_extend_cpath(): Chose router redgene for hop 1 (exit is tortuga)
Jun 30 17:40:24:843 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:24:843 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:24:859 [Debug] onion_extend_cpath(): Chose router Titmerde for hop 2 (exit is tortuga)
Jun 30 17:40:24:859 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:24:859 [Debug] onion_extend_cpath(): Chose router tortuga for hop 3 (exit is tortuga)
Jun 30 17:40:24:859 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:24:859 [Debug] circuit_handle_first_hop(): Looking for firsthop '85.31.186.61:9001'
Jun 30 17:40:24:859 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:24:859 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:24:859 [Debug] circuit_deliver_create_cell(): Chosen circID 18351.
Jun 30 17:40:24:859 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:24:859 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'redgene'
Jun 30 17:40:24:859 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:24:859 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:24:859 [Info] choose_good_exit_server_general(): Chose exit server 'flyingboy'
Jun 30 17:40:24:859 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:24:859 [Debug] onion_extend_cpath(): Chose router ThunderGodOdin2 for hop 1 (exit is flyingboy)
Jun 30 17:40:24:859 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:24:859 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:24:859 [Debug] onion_extend_cpath(): Chose router einfallstor for hop 2 (exit is flyingboy)
Jun 30 17:40:24:859 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:24:859 [Debug] onion_extend_cpath(): Chose router flyingboy for hop 3 (exit is flyingboy)
Jun 30 17:40:24:859 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:24:859 [Debug] circuit_handle_first_hop(): Looking for firsthop '63.211.169.142:9001'
Jun 30 17:40:24:859 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:24:859 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:24:859 [Debug] circuit_deliver_create_cell(): Chosen circID 47025.
Jun 30 17:40:24:859 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:24:859 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'ThunderGodOdin2'
Jun 30 17:40:24:859 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:24:859 [Debug] conn_write_callback(): socket 1640 wants to write.
Jun 30 17:40:24:859 [Debug] flush_buf_tls_impl(): flushed 1024 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:26:234 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:26:234 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:26:234 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:26:234 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:26:234 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:26:234 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:26:234 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:26:234 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:26:234 [Info] exit circ (length 3, exit tor26): ThunderGodOdin2(open) flatlineDE(closed) tor26(closed)
Jun 30 17:40:26:234 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:26:234 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:234 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:26:234 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:26:234 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:234 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:26:234 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:26:234 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:26:234 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:26:234 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:234 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:234 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:26:234 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:26:234 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:26:234 [Debug] connection_edge_process_relay_cell(): Now seen 632 relay cells here.
Jun 30 17:40:26:234 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:26:234 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:26:234 [Info] exit circ (length 3, exit stimpysbutt): serifos(open) caronte(open) stimpysbutt(open)
Jun 30 17:40:26:234 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:234 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:26:234 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:26:234 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:26:234 [Info] choose_good_exit_server_general(): Chose exit server 'nutsak'
Jun 30 17:40:26:234 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:26:234 [Debug] onion_extend_cpath(): Chose router ThunderGodOdin2 for hop 1 (exit is nutsak)
Jun 30 17:40:26:234 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:26:234 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:26:234 [Debug] onion_extend_cpath(): Chose router moxtor01 for hop 2 (exit is nutsak)
Jun 30 17:40:26:234 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:26:234 [Debug] onion_extend_cpath(): Chose router nutsak for hop 3 (exit is nutsak)
Jun 30 17:40:26:234 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:26:234 [Debug] circuit_handle_first_hop(): Looking for firsthop '63.211.169.142:9001'
Jun 30 17:40:26:234 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:26:234 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:26:234 [Debug] circuit_deliver_create_cell(): Chosen circID 47026.
Jun 30 17:40:26:234 [Debug] write_to_buf(): added 512 bytes to buf (now 2048 total).
Jun 30 17:40:26:234 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'ThunderGodOdin2'
Jun 30 17:40:26:234 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:26:234 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:26:234 [Info] choose_good_exit_server_general(): Chose exit server 'moxtor01'
Jun 30 17:40:26:234 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:26:234 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is moxtor01)
Jun 30 17:40:26:234 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:26:250 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Chose router molly for hop 2 (exit is moxtor01)
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Chose router moxtor01 for hop 3 (exit is moxtor01)
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:26:250 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:26:250 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:26:250 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:26:250 [Debug] circuit_deliver_create_cell(): Chosen circID 26179.
Jun 30 17:40:26:250 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:26:250 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:26:250 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:26:250 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:26:250 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:26:250 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:250 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:250 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:26:250 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:26:250 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:26:250 [Debug] connection_edge_process_relay_cell(): Now seen 633 relay cells here.
Jun 30 17:40:26:250 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:26:250 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:26:250 [Info] exit circ (length 3, exit AnemoneNemorosa): redgene(open) moilen(open) AnemoneNemorosa(closed)
Jun 30 17:40:26:250 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:250 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:26:250 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:26:250 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:250 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:250 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:26:250 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:26:250 [Debug] conn_write_callback(): socket 1696 wants to write.
Jun 30 17:40:26:250 [Debug] flush_buf_tls_impl(): flushed 1024 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:26:250 [Debug] conn_write_callback(): socket 1676 wants to write.
Jun 30 17:40:26:250 [Debug] flush_buf_tls_impl(): flushed 2048 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:26:250 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:26:250 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:26:250 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:26:250 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:26:250 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:26:250 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:26:250 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:26:250 [Debug] connection_edge_process_relay_cell(): Now seen 634 relay cells here.
Jun 30 17:40:26:250 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:26:250 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:26:250 [Info] exit circ (length 3, exit xilolix): ThunderGodOdin2(open) cyberprofilers(open) xilolix(open)
Jun 30 17:40:26:250 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:250 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:26:250 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:26:250 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:26:250 [Info] choose_good_exit_server_general(): Chose exit server 'reversed'
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Chose router redgene for hop 1 (exit is reversed)
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:26:250 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Chose router wintools for hop 2 (exit is reversed)
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Chose router reversed for hop 3 (exit is reversed)
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:26:250 [Debug] circuit_handle_first_hop(): Looking for firsthop '85.31.186.61:9001'
Jun 30 17:40:26:250 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:26:250 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:26:250 [Debug] circuit_deliver_create_cell(): Chosen circID 18352.
Jun 30 17:40:26:250 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:26:250 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'redgene'
Jun 30 17:40:26:250 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:26:250 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:26:250 [Info] choose_good_exit_server_general(): Chose exit server 'moria2'
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Chose router ThunderGodOdin2 for hop 1 (exit is moria2)
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:26:250 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Chose router sasquatch for hop 2 (exit is moria2)
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Chose router moria2 for hop 3 (exit is moria2)
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:26:250 [Debug] circuit_handle_first_hop(): Looking for firsthop '63.211.169.142:9001'
Jun 30 17:40:26:250 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:26:250 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:26:250 [Debug] circuit_deliver_create_cell(): Chosen circID 47027.
Jun 30 17:40:26:250 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:26:250 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'ThunderGodOdin2'
Jun 30 17:40:26:250 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:26:250 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:26:250 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:26:250 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:250 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:250 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:26:250 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:26:250 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:26:250 [Debug] connection_edge_process_relay_cell(): Now seen 635 relay cells here.
Jun 30 17:40:26:250 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:26:250 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:26:250 [Info] exit circ (length 3, exit clearbluetormonster): serifos(open) so19charsismax(open) clearbluetormonster(open)
Jun 30 17:40:26:250 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:250 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:26:250 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:26:250 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:26:250 [Info] choose_good_exit_server_general(): Chose exit server 'Zwiebelsuppe'
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is Zwiebelsuppe)
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:26:250 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Chose router donk3ypunch for hop 2 (exit is Zwiebelsuppe)
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Chose router Zwiebelsuppe for hop 3 (exit is Zwiebelsuppe)
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:26:250 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:26:250 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:26:250 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:26:250 [Debug] circuit_deliver_create_cell(): Chosen circID 26180.
Jun 30 17:40:26:250 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:26:250 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:26:250 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:26:250 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:26:250 [Info] choose_good_exit_server_general(): Chose exit server 'arigatto'
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is arigatto)
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:26:250 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Chose router kvelling for hop 2 (exit is arigatto)
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Chose router arigatto for hop 3 (exit is arigatto)
Jun 30 17:40:26:250 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:26:250 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:26:250 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:26:250 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:26:250 [Debug] circuit_deliver_create_cell(): Chosen circID 26181.
Jun 30 17:40:26:250 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:26:250 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:26:250 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:26:250 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:26:250 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:26:250 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:250 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:250 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:26:250 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:26:265 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:26:265 [Debug] connection_edge_process_relay_cell(): Now seen 636 relay cells here.
Jun 30 17:40:26:265 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:26:265 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:26:734 [Info] exit circ (length 3, exit wormhole): redgene(open) serifos(open) wormhole(closed)
Jun 30 17:40:26:734 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:734 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:26:734 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:26:734 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:734 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:734 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:26:734 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:26:734 [Debug] conn_write_callback(): socket 1640 wants to write.
Jun 30 17:40:26:734 [Debug] flush_buf_tls_impl(): flushed 1536 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:26:734 [Info] circuit_expire_building(): Abandoning circ 63.211.169.142:9001:46939 (state 0:doing handshakes, purpose 5)
Jun 30 17:40:26:734 [Info] exit circ (length 3, exit devilproxytor): ThunderGodOdin2(open) kvelling(open) devilproxytor(waiting for keys)
Jun 30 17:40:26:734 [Debug] circuit_increment_failure_count(): n_circuit_failures now 1.
Jun 30 17:40:26:734 [Debug] connection_or_send_destroy(): Sending destroy (circID 46939).
Jun 30 17:40:26:734 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:26:734 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:26:734 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:26:734 [Info] choose_good_exit_server_general(): Chose exit server 'rodos'
Jun 30 17:40:26:734 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:26:750 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is rodos)
Jun 30 17:40:26:750 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:26:750 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:26:750 [Debug] onion_extend_cpath(): Chose router Zwiebelsuppe for hop 2 (exit is rodos)
Jun 30 17:40:26:750 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:26:750 [Debug] onion_extend_cpath(): Chose router rodos for hop 3 (exit is rodos)
Jun 30 17:40:26:750 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:26:750 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:26:750 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:26:750 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:26:750 [Debug] circuit_deliver_create_cell(): Chosen circID 26182.
Jun 30 17:40:26:750 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:26:750 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:26:750 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:26:750 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:26:750 [Info] choose_good_exit_server_general(): Chose exit server 'rodos'
Jun 30 17:40:26:750 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:26:750 [Debug] onion_extend_cpath(): Chose router ThunderGodOdin2 for hop 1 (exit is rodos)
Jun 30 17:40:26:750 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:26:750 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:26:750 [Debug] onion_extend_cpath(): Chose router multicstor for hop 2 (exit is rodos)
Jun 30 17:40:26:750 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:26:750 [Debug] onion_extend_cpath(): Chose router rodos for hop 3 (exit is rodos)
Jun 30 17:40:26:750 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:26:750 [Debug] circuit_handle_first_hop(): Looking for firsthop '63.211.169.142:9001'
Jun 30 17:40:26:750 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:26:750 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:26:750 [Debug] circuit_deliver_create_cell(): Chosen circID 47028.
Jun 30 17:40:26:750 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:26:750 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'ThunderGodOdin2'
Jun 30 17:40:26:750 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:26:750 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:26:750 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:26:750 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:26:750 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:26:750 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:26:750 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:26:750 [Debug] connection_edge_process_relay_cell(): Now seen 637 relay cells here.
Jun 30 17:40:26:750 [Debug] circuit_increment_failure_count(): n_circuit_failures now 2.
Jun 30 17:40:26:750 [Debug] connection_or_send_destroy(): Sending destroy (circID 46993).
Jun 30 17:40:26:750 [Debug] write_to_buf(): added 512 bytes to buf (now 2048 total).
Jun 30 17:40:26:750 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:26:750 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:26:750 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:26:750 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:750 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:750 [Debug] read_to_buf_tls_impl(): Read 1536 bytes. 1536 on inbuf; 0 pending
Jun 30 17:40:26:750 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 1536 (0 pending in tls object).
Jun 30 17:40:26:750 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:26:750 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:26:750 [Info] exit circ (length 3, exit b0red): serifos(open) lostinthenoise(closed) b0red(closed)
Jun 30 17:40:26:750 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:26:750 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:750 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:26:750 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:26:750 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:750 [Debug] write_to_buf(): added 512 bytes to buf (now 2048 total).
Jun 30 17:40:26:750 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 1024 (0 pending in tls object).
Jun 30 17:40:26:750 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:26:750 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:26:750 [Info] exit circ (length 3, exit wintools): serifos(open) Terminal2(closed) wintools(closed)
Jun 30 17:40:26:750 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:26:750 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:750 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:26:750 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:26:750 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:750 [Debug] write_to_buf(): added 512 bytes to buf (now 2560 total).
Jun 30 17:40:26:750 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:26:750 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:26:750 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:26:750 [Info] exit circ (length 3, exit ccc2): serifos(open) Patra(closed) ccc2(closed)
Jun 30 17:40:26:750 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:26:750 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:750 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:26:750 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:26:750 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:750 [Debug] write_to_buf(): added 512 bytes to buf (now 3072 total).
Jun 30 17:40:26:750 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:26:750 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:26:750 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:26:750 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:750 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:750 [Debug] read_to_buf_tls_impl(): Read 1024 bytes. 1024 on inbuf; 0 pending
Jun 30 17:40:26:750 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 1024 (0 pending in tls object).
Jun 30 17:40:26:750 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:26:750 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:26:750 [Info] exit circ (length 3, exit arigatto): redgene(open) Emzy(closed) arigatto(closed)
Jun 30 17:40:26:750 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:26:750 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:750 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:26:750 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:26:750 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:750 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:26:750 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:26:750 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:26:750 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:26:750 [Info] exit circ (length 3, exit cust81tor): redgene(open) tetra4(closed) cust81tor(closed)
Jun 30 17:40:26:750 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:26:750 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:750 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:26:750 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:26:750 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:750 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:26:750 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:26:750 [Debug] conn_write_callback(): socket 1696 wants to write.
Jun 30 17:40:26:750 [Debug] flush_buf_tls_impl(): flushed 3072 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:26:781 [Debug] conn_write_callback(): socket 1676 wants to write.
Jun 30 17:40:26:781 [Debug] flush_buf_tls_impl(): flushed 2048 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:26:781 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:26:781 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:26:781 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:26:781 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:26:781 [Debug] read_to_buf_tls_impl(): Read 1024 bytes. 1024 on inbuf; 0 pending
Jun 30 17:40:26:781 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 1024 (0 pending in tls object).
Jun 30 17:40:26:781 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:26:781 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:26:781 [Info] exit circ (length 3, exit HTTPDNet): ThunderGodOdin2(open) BuddhaVsEntropy(closed) HTTPDNet(closed)
Jun 30 17:40:26:781 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:26:781 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:781 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:26:781 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:26:781 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:781 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:26:781 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:26:781 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:26:781 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:26:781 [Info] exit circ (length 3, exit einfallstor): ThunderGodOdin2(open) ccc2(closed) einfallstor(closed)
Jun 30 17:40:26:781 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:26:781 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:781 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:26:781 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:26:781 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:781 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:26:781 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:26:781 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:26:781 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:26:781 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:781 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:781 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:26:781 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:26:781 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:26:781 [Debug] connection_edge_process_relay_cell(): Now seen 638 relay cells here.
Jun 30 17:40:26:781 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:26:781 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:26:781 [Info] exit circ (length 3, exit caronte): serifos(open) nobodyeditedaconfig(open) caronte(closed)
Jun 30 17:40:26:781 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:781 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:26:781 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:26:781 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:781 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:781 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:26:781 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:26:781 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:26:781 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:26:781 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:781 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:26:781 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:26:781 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:26:781 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:26:781 [Debug] connection_edge_process_relay_cell(): Now seen 639 relay cells here.
Jun 30 17:40:26:781 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:26:781 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:26:781 [Info] exit circ (length 3, exit reversed): redgene(open) b0red(open) reversed(closed)
Jun 30 17:40:26:781 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:26:781 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:26:781 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:26:781 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:781 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:26:781 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:26:781 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:26:781 [Debug] conn_write_callback(): socket 1640 wants to write.
Jun 30 17:40:26:781 [Debug] flush_buf_tls_impl(): flushed 1536 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:28:671 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:28:671 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:28:671 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:28:671 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:28:671 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:28:671 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:28:671 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:28:671 [Debug] connection_edge_process_relay_cell(): Now seen 640 relay cells here.
Jun 30 17:40:28:671 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:28:671 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:28:671 [Info] exit circ (length 3, exit Zwiebelkuchen): ThunderGodOdin2(open) sabotage(open) Zwiebelkuchen(open)
Jun 30 17:40:28:671 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:28:671 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:28:671 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:28:671 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:28:671 [Info] choose_good_exit_server_general(): Chose exit server 'irithimus'
Jun 30 17:40:28:671 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:28:671 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is irithimus)
Jun 30 17:40:28:671 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:28:671 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:28:671 [Debug] onion_extend_cpath(): Chose router entity for hop 2 (exit is irithimus)
Jun 30 17:40:28:671 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:28:671 [Debug] onion_extend_cpath(): Chose router irithimus for hop 3 (exit is irithimus)
Jun 30 17:40:28:671 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:28:671 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:28:671 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:28:671 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:28:671 [Debug] circuit_deliver_create_cell(): Chosen circID 26183.
Jun 30 17:40:28:671 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:28:671 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:28:671 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:28:671 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:28:671 [Info] choose_good_exit_server_general(): Chose exit server 'FoeBuD'
Jun 30 17:40:28:671 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:28:671 [Debug] onion_extend_cpath(): Chose router redgene for hop 1 (exit is FoeBuD)
Jun 30 17:40:28:671 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:28:671 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:28:671 [Debug] onion_extend_cpath(): Chose router fitor1 for hop 2 (exit is FoeBuD)
Jun 30 17:40:28:671 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:28:671 [Debug] onion_extend_cpath(): Chose router FoeBuD for hop 3 (exit is FoeBuD)
Jun 30 17:40:28:671 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:28:671 [Debug] circuit_handle_first_hop(): Looking for firsthop '85.31.186.61:9001'
Jun 30 17:40:28:671 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:28:671 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:28:671 [Debug] circuit_deliver_create_cell(): Chosen circID 18353.
Jun 30 17:40:28:671 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:28:671 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'redgene'
Jun 30 17:40:28:671 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:28:671 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:28:671 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:28:671 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:28:671 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:28:671 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:28:687 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:28:687 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:28:687 [Debug] connection_edge_process_relay_cell(): Now seen 641 relay cells here.
Jun 30 17:40:28:687 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:28:687 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:28:687 [Info] exit circ (length 3, exit moria1): serifos(open) keenmind(open) moria1(open)
Jun 30 17:40:28:687 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:28:687 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:28:687 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:28:687 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:28:687 [Info] choose_good_exit_server_general(): Chose exit server 'distro'
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Chose router ThunderGodOdin2 for hop 1 (exit is distro)
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:28:687 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Chose router holyfreakingcrap for hop 2 (exit is distro)
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Chose router distro for hop 3 (exit is distro)
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:28:687 [Debug] circuit_handle_first_hop(): Looking for firsthop '63.211.169.142:9001'
Jun 30 17:40:28:687 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:28:687 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:28:687 [Debug] circuit_deliver_create_cell(): Chosen circID 47029.
Jun 30 17:40:28:687 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:28:687 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'ThunderGodOdin2'
Jun 30 17:40:28:687 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:28:687 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:28:687 [Info] choose_good_exit_server_general(): Chose exit server 'nycbug'
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is nycbug)
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:28:687 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Chose router frell2 for hop 2 (exit is nycbug)
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Chose router nycbug for hop 3 (exit is nycbug)
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:28:687 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:28:687 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:28:687 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:28:687 [Debug] circuit_deliver_create_cell(): Chosen circID 26184.
Jun 30 17:40:28:687 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:28:687 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:28:687 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:28:687 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:28:687 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:28:687 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:28:687 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:28:687 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:28:687 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:28:687 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:28:687 [Debug] connection_edge_process_relay_cell(): Now seen 642 relay cells here.
Jun 30 17:40:28:687 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:28:687 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:28:687 [Info] exit circ (length 3, exit kvelling): redgene(open) AnemoneNemorosa(open) kvelling(closed)
Jun 30 17:40:28:687 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:28:687 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:28:687 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:28:687 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:28:687 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:28:687 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:28:687 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:28:687 [Debug] conn_write_callback(): socket 1696 wants to write.
Jun 30 17:40:28:687 [Debug] flush_buf_tls_impl(): flushed 1536 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:28:687 [Debug] conn_write_callback(): socket 1676 wants to write.
Jun 30 17:40:28:687 [Debug] flush_buf_tls_impl(): flushed 1536 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:28:687 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:28:687 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:28:687 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:28:687 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:28:687 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:28:687 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:28:687 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:28:687 [Debug] connection_edge_process_relay_cell(): Now seen 643 relay cells here.
Jun 30 17:40:28:687 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:28:687 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:28:687 [Info] exit circ (length 3, exit FoeBuD): ThunderGodOdin2(open) bestleftunsaid(open) FoeBuD(open)
Jun 30 17:40:28:687 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:28:687 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:28:687 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:28:687 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:28:687 [Info] choose_good_exit_server_general(): Chose exit server 'omahabeach'
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Chose router ThunderGodOdin2 for hop 1 (exit is omahabeach)
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:28:687 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Chose router kvelling for hop 2 (exit is omahabeach)
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Chose router omahabeach for hop 3 (exit is omahabeach)
Jun 30 17:40:28:687 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:28:687 [Debug] circuit_handle_first_hop(): Looking for firsthop '63.211.169.142:9001'
Jun 30 17:40:28:687 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:28:687 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:28:687 [Debug] circuit_deliver_create_cell(): Chosen circID 47030.
Jun 30 17:40:28:703 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:28:703 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'ThunderGodOdin2'
Jun 30 17:40:28:703 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:28:703 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:28:703 [Info] choose_good_exit_server_general(): Chose exit server 'flubber'
Jun 30 17:40:28:703 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:28:703 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is flubber)
Jun 30 17:40:28:703 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:28:703 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:28:703 [Debug] onion_extend_cpath(): Chose router jap for hop 2 (exit is flubber)
Jun 30 17:40:28:703 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:28:703 [Debug] onion_extend_cpath(): Chose router flubber for hop 3 (exit is flubber)
Jun 30 17:40:28:703 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:28:703 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:28:703 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:28:703 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:28:703 [Debug] circuit_deliver_create_cell(): Chosen circID 26185.
Jun 30 17:40:28:703 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:28:703 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:28:703 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:28:703 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:28:703 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:28:718 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:28:718 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:28:718 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:28:718 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:28:718 [Debug] command_process_created_cell(): at OP. Finishing handshake.
Jun 30 17:40:28:718 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jun 30 17:40:28:718 [Info] exit circ (length 3, exit polkadot): serifos(open) FreedomVoice(closed) polkadot(closed)
Jun 30 17:40:28:718 [Debug] command_process_created_cell(): Moving to next skin.
Jun 30 17:40:28:718 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:28:718 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:28:718 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:28:718 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:28:718 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:28:718 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:28:718 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:28:718 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:28:718 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:28:718 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:28:718 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:28:718 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:28:718 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:28:718 [Debug] connection_edge_process_relay_cell(): Now seen 644 relay cells here.
Jun 30 17:40:28:718 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:28:718 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:28:718 [Info] exit circ (length 3, exit HTTPDNet): redgene(open) gvg1(open) HTTPDNet(open)
Jun 30 17:40:30:140 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:30:140 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:30:140 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:30:140 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:30:140 [Info] choose_good_exit_server_general(): Chose exit server 'biafra'
Jun 30 17:40:30:140 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:30:140 [Debug] onion_extend_cpath(): Chose router ThunderGodOdin2 for hop 1 (exit is biafra)
Jun 30 17:40:30:140 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:30:140 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:30:140 [Debug] onion_extend_cpath(): Chose router manetherendrelle for hop 2 (exit is biafra)
Jun 30 17:40:30:140 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:30:140 [Debug] onion_extend_cpath(): Chose router biafra for hop 3 (exit is biafra)
Jun 30 17:40:30:140 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:30:140 [Debug] circuit_handle_first_hop(): Looking for firsthop '63.211.169.142:9001'
Jun 30 17:40:30:140 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:30:140 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:30:140 [Debug] circuit_deliver_create_cell(): Chosen circID 47031.
Jun 30 17:40:30:140 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:30:140 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'ThunderGodOdin2'
Jun 30 17:40:30:140 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:30:140 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:30:140 [Info] choose_good_exit_server_general(): Chose exit server 'arigatto'
Jun 30 17:40:30:140 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:30:140 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is arigatto)
Jun 30 17:40:30:140 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:30:140 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:30:140 [Debug] onion_extend_cpath(): Chose router TheGreatSantini for hop 2 (exit is arigatto)
Jun 30 17:40:30:140 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:30:140 [Debug] onion_extend_cpath(): Chose router arigatto for hop 3 (exit is arigatto)
Jun 30 17:40:30:140 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:30:140 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:30:140 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:30:140 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:30:140 [Debug] circuit_deliver_create_cell(): Chosen circID 26186.
Jun 30 17:40:30:140 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:30:140 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:30:140 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:30:140 [Debug] conn_write_callback(): socket 1640 wants to write.
Jun 30 17:40:30:140 [Debug] flush_buf_tls_impl(): flushed 1024 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:30:140 [Info] circuit_expire_building(): Abandoning circ 85.31.186.61:9001:18249 (state 0:doing handshakes, purpose 5)
Jun 30 17:40:30:140 [Info] exit circ (length 3, exit anselmi): redgene(open) SEC(open) anselmi(waiting for keys)
Jun 30 17:40:30:140 [Debug] circuit_increment_failure_count(): n_circuit_failures now 1.
Jun 30 17:40:30:140 [Debug] connection_or_send_destroy(): Sending destroy (circID 18249).
Jun 30 17:40:30:156 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:30:156 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:30:156 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:30:156 [Info] choose_good_exit_server_general(): Chose exit server 'stimpysbutt'
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Chose router redgene for hop 1 (exit is stimpysbutt)
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:30:156 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Chose router waerternet for hop 2 (exit is stimpysbutt)
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Chose router stimpysbutt for hop 3 (exit is stimpysbutt)
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:30:156 [Debug] circuit_handle_first_hop(): Looking for firsthop '85.31.186.61:9001'
Jun 30 17:40:30:156 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:30:156 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:30:156 [Debug] circuit_deliver_create_cell(): Chosen circID 18354.
Jun 30 17:40:30:156 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jun 30 17:40:30:156 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'redgene'
Jun 30 17:40:30:156 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:30:156 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:30:156 [Info] choose_good_exit_server_general(): Chose exit server 'bolanda'
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Chose router ThunderGodOdin2 for hop 1 (exit is bolanda)
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:30:156 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Chose router FoeBuD for hop 2 (exit is bolanda)
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Chose router bolanda for hop 3 (exit is bolanda)
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:30:156 [Debug] circuit_handle_first_hop(): Looking for firsthop '63.211.169.142:9001'
Jun 30 17:40:30:156 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:30:156 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:30:156 [Debug] circuit_deliver_create_cell(): Chosen circID 47032.
Jun 30 17:40:30:156 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:30:156 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'ThunderGodOdin2'
Jun 30 17:40:30:156 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:30:156 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:30:156 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:30:156 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:30:156 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:30:156 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:30:156 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:30:156 [Debug] connection_edge_process_relay_cell(): Now seen 645 relay cells here.
Jun 30 17:40:30:156 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:30:156 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:30:156 [Info] exit circ (length 3, exit mauger): ThunderGodOdin2(open) DrazziBTorNode(open) mauger(open)
Jun 30 17:40:30:156 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:30:156 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:30:156 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:30:156 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:30:156 [Info] choose_good_exit_server_general(): Chose exit server 'paxprivoso'
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is paxprivoso)
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:30:156 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Chose router lehighnet for hop 2 (exit is paxprivoso)
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Chose router paxprivoso for hop 3 (exit is paxprivoso)
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:30:156 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:30:156 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:30:156 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:30:156 [Debug] circuit_deliver_create_cell(): Chosen circID 26187.
Jun 30 17:40:30:156 [Debug] write_to_buf(): added 512 bytes to buf (now 2048 total).
Jun 30 17:40:30:156 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:30:156 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:30:156 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:30:156 [Info] choose_good_exit_server_general(): Chose exit server 'inap1'
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Chose router redgene for hop 1 (exit is inap1)
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:30:156 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Chose router FoeBuD for hop 2 (exit is inap1)
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Chose router inap1 for hop 3 (exit is inap1)
Jun 30 17:40:30:156 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:30:156 [Debug] circuit_handle_first_hop(): Looking for firsthop '85.31.186.61:9001'
Jun 30 17:40:30:156 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:30:156 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:30:156 [Debug] circuit_deliver_create_cell(): Chosen circID 18355.
Jun 30 17:40:30:156 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jun 30 17:40:30:156 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'redgene'
Jun 30 17:40:30:156 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:30:156 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:30:156 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:30:156 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:30:156 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:30:156 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:30:156 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:30:156 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:30:156 [Debug] connection_edge_process_relay_cell(): Now seen 646 relay cells here.
Jun 30 17:40:30:156 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:30:156 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:30:156 [Info] exit circ (length 3, exit caronte): serifos(open) tetra4(open) caronte(closed)
Jun 30 17:40:30:156 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:30:156 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:30:156 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:30:156 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:30:156 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:30:156 [Debug] write_to_buf(): added 512 bytes to buf (now 2560 total).
Jun 30 17:40:30:156 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:30:156 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:30:156 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:30:156 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:30:156 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:30:156 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:30:156 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:30:156 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:30:171 [Debug] connection_edge_process_relay_cell(): Now seen 647 relay cells here.
Jun 30 17:40:30:171 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:30:171 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:30:171 [Info] exit circ (length 3, exit donk3ypunch): redgene(open) jap(open) donk3ypunch(open)
Jun 30 17:40:30:171 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:30:171 [Info] circuit_send_next_onion_skin(): circuit built!
Jun 30 17:40:30:171 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:30:171 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:30:171 [Info] choose_good_exit_server_general(): Chose exit server 'nutsak'
Jun 30 17:40:30:171 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:30:171 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is nutsak)
Jun 30 17:40:30:171 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:30:171 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:30:171 [Debug] onion_extend_cpath(): Chose router hardbody for hop 2 (exit is nutsak)
Jun 30 17:40:30:171 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:30:171 [Debug] onion_extend_cpath(): Chose router nutsak for hop 3 (exit is nutsak)
Jun 30 17:40:30:171 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:30:171 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:30:171 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:30:171 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:30:171 [Debug] circuit_deliver_create_cell(): Chosen circID 26188.
Jun 30 17:40:30:171 [Debug] write_to_buf(): added 512 bytes to buf (now 3072 total).
Jun 30 17:40:30:171 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:30:171 [Debug] new_route_len(): Chosen route length 3 (970 routers available).
Jun 30 17:40:30:171 [Info] choose_good_exit_server_general(): Found 292 servers that might support 0/2 pending connections.
Jun 30 17:40:30:171 [Info] choose_good_exit_server_general(): Chose exit server 'muchacha'
Jun 30 17:40:30:171 [Debug] onion_extend_cpath(): Path is 0 long; we want 3
Jun 30 17:40:30:171 [Debug] onion_extend_cpath(): Chose router serifos for hop 1 (exit is muchacha)
Jun 30 17:40:30:171 [Debug] onion_extend_cpath(): Path is 1 long; we want 3
Jun 30 17:40:30:171 [Debug] choose_good_middle_server(): Contemplating intermediate hop: random choice.
Jun 30 17:40:30:171 [Debug] onion_extend_cpath(): Chose router cqas02 for hop 2 (exit is muchacha)
Jun 30 17:40:30:171 [Debug] onion_extend_cpath(): Path is 2 long; we want 3
Jun 30 17:40:30:171 [Debug] onion_extend_cpath(): Chose router muchacha for hop 3 (exit is muchacha)
Jun 30 17:40:30:171 [Debug] onion_extend_cpath(): Path is complete: 3 steps long
Jun 30 17:40:30:171 [Debug] circuit_handle_first_hop(): Looking for firsthop '140.247.62.119:9001'
Jun 30 17:40:30:171 [Debug] circuit_handle_first_hop(): Conn open. Delivering first onion skin.
Jun 30 17:40:30:171 [Debug] circuit_send_next_onion_skin(): First skin; sending create cell.
Jun 30 17:40:30:171 [Debug] circuit_deliver_create_cell(): Chosen circID 26189.
Jun 30 17:40:30:171 [Debug] write_to_buf(): added 512 bytes to buf (now 3584 total).
Jun 30 17:40:30:171 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'serifos'
Jun 30 17:40:30:171 [Debug] connection_or_process_cells_from_inbuf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:30:171 [Debug] conn_write_callback(): socket 1696 wants to write.
Jun 30 17:40:30:171 [Debug] flush_buf_tls_impl(): flushed 3584 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:30:671 [Debug] conn_write_callback(): socket 1676 wants to write.
Jun 30 17:40:30:671 [Debug] flush_buf_tls_impl(): flushed 1536 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:30:671 [Debug] conn_write_callback(): socket 1640 wants to write.
Jun 30 17:40:30:671 [Debug] flush_buf_tls_impl(): flushed 1536 bytes, 0 ready to flush, 0 remain.
Jun 30 17:40:30:671 [Debug] conn_read_callback(): socket 1676 wants to read.
Jun 30 17:40:30:671 [Debug] connection_read_to_buf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object). at_most 8192.
Jun 30 17:40:30:671 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:30:671 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 8192.
Jun 30 17:40:30:671 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:30:671 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:30:671 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:30:671 [Debug] connection_edge_process_relay_cell(): Now seen 648 relay cells here.
Jun 30 17:40:30:671 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:30:671 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:30:671 [Info] exit circ (length 3, exit logireach): ThunderGodOdin2(open) cust81tor(open) logireach(closed)
Jun 30 17:40:30:671 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:30:671 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:30:671 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:30:671 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:30:671 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:30:671 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:30:671 [Debug] connection_or_process_cells_from_inbuf(): 1676: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:30:671 [Debug] conn_read_callback(): socket 1696 wants to read.
Jun 30 17:40:30:671 [Debug] connection_read_to_buf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jun 30 17:40:30:671 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:30:671 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jun 30 17:40:30:671 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jun 30 17:40:30:671 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 512 (0 pending in tls object).
Jun 30 17:40:30:671 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jun 30 17:40:30:671 [Debug] connection_edge_process_relay_cell(): Now seen 649 relay cells here.
Jun 30 17:40:30:671 [Debug] connection_edge_process_relay_cell(): Got an extended cell! Yay.
Jun 30 17:40:30:671 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jun 30 17:40:30:671 [Info] exit circ (length 3, exit rfc1149): serifos(open) Karlchen(open) rfc1149(closed)
Jun 30 17:40:30:671 [Debug] circuit_send_next_onion_skin(): starting to send subsequent skin.
Jun 30 17:40:30:671 [Debug] circuit_send_next_onion_skin(): Sending extend relay cell.
Jun 30 17:40:30:671 [Debug] connection_edge_send_command(): delivering 6 cell forward.
Jun 30 17:40:30:671 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:30:671 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jun 30 17:40:30:671 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jun 30 17:40:30:671 [Debug] connection_or_process_cells_from_inbuf(): 1696: starting, inbuf_datalen 0 (0 pending in tls object).
Jun 30 17:40:30:671 [Debug] conn_read_callback(): socket 1640 wants to read.
Jun 30 17:40:30:671 [Debug] connection_read_to_buf(): 1640: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.

comment:3 Changed 13 years ago by aaronsells

It appears that the 100% CPU usage that I was seeing was the result of memory corruption due to a bad SDRAM module. Upon removal of the SDRAM module, the 100% CPU issue went away.

comment:4 Changed 13 years ago by aaronsells

Nevermind. I am still seeing the high CPU usage. It looks like it just may be a function of the high number of concurrent TCP/IP connections that comes with using Azureus.

comment:5 Changed 13 years ago by nickm

So, the high CPU usage doesn't show up when you use other applications, like web browsing or chat?

comment:6 Changed 13 years ago by aaronsells

Correct, the only high cpu usage I see is associated with tor.exe.

comment:7 Changed 13 years ago by phobos

Is this still occurring with current versions of Tor?

comment:8 Changed 13 years ago by aaronsells

No, I have not seen this behavior in the current version of Tor. I would be OK with removing this task.

comment:9 Changed 13 years ago by arma

Ok. I'm going to close it then. Perhaps somebody will arrive with more details
and we'll open a new one.

comment:10 Changed 13 years ago by arma

flyspray2trac: bug closed.

comment:11 Changed 7 years ago by nickm

Component: Tor ClientTor
Note: See TracTickets for help on using tickets.