Nov 19 10:32:14.160 [debug] connection_edge_package_raw_inbuf(): (12) Packaging 438 bytes (0 waiting).
Nov 19 10:32:14.161 [debug] relay_send_command_from_edge(): delivering 2 cell forward.
Nov 19 10:32:14.162 [debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Nov 19 10:32:14.163 [debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Nov 19 10:32:14.168 [debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Nov 19 10:32:14.169 [debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Nov 19 10:32:14.169 [debug] append_cell_to_circuit_queue(): Made a circuit active.
Nov 19 10:32:14.170 [debug] append_cell_to_circuit_queue(): Primed a buffer.
Nov 19 10:32:14.171 [debug] buf_ensure_capacity(): Growing buffer from 0 to 512 bytes.
Nov 19 10:32:14.171 [debug] buf_get_initial_mem(): Got buf mem from 4096-byte freelist. Freelist has 94 entries.
Nov 19 10:32:14.172 [debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Nov 19 10:32:14.173 [debug] connection_or_flush_from_first_active_circuit(): Made a circuit inactive.
Nov 19 10:32:14.173 [debug] connection_edge_package_raw_inbuf(): conn->package_window is now 499
Nov 19 10:32:14.174 [debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 986
Nov 19 10:32:14.175 [debug] conn_read_callback(): socket 6 wants to read.
Nov 19 10:32:14.176 [debug] connection_handle_listener_read(): Connection accepted on socket 15 (child of fd 6).
Nov 19 10:32:14.176 [debug] buf_get_initial_mem(): Got buf mem from 4096-byte freelist. Freelist has 93 entries.
Nov 19 10:32:14.177 [debug] buf_get_initial_mem(): Got buf mem from 4096-byte freelist. Freelist has 92 entries.
Nov 19 10:32:14.178 [debug] connection_add(): new conn type Socks, socket 15, n_conns 11.
Nov 19 10:32:14.178 [debug] conn_write_callback(): socket 14 wants to write.
Nov 19 10:32:14.180 [debug] add_buf_mem_to_freelist(): Add buf mem to 4096-byte freelist. Freelist has 93 entries.
Nov 19 10:32:14.180 [debug] flush_buf_tls_impl(): flushed 512 bytes, 0 ready to flush, 0 remain.
Nov 19 10:32:14.181 [debug] connection_handle_write(): After TLS write of 512: 0 read, 586 written
Nov 19 10:32:14.182 [debug] conn_read_callback(): socket 15 wants to read.
Nov 19 10:32:14.183 [debug] read_to_buf_impl(): Read 41 bytes. 41 on inbuf.
Nov 19 10:32:14.183 [debug] connection_ap_handshake_process_socks(): entered.
Nov 19 10:32:14.184 [debug] fetch_from_buf_socks(): socks4: Everything is here. Success.
Nov 19 10:32:14.184 [debug] add_buf_mem_to_freelist(): Add buf mem to 4096-byte freelist. Freelist has 94 entries.
Nov 19 10:32:14.185 [debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:80
Nov 19 10:32:14.186 [info] connection_ap_handshake_rewrite_and_attach(): Got a hidden service request for ID '[scrubbed]'
Nov 19 10:32:14.187 [info] connection_ap_handshake_rewrite_and_attach(): Descriptor is here and fresh enough. Great.
Nov 19 10:32:14.187 [debug] circuit_get_open_circ_or_launch(): one on the way!
Nov 19 10:32:14.188 [debug] circuit_get_open_circ_or_launch(): one on the way!
Nov 19 10:32:14.189 [info] connection_ap_handshake_attach_circuit(): ready rend circ 32152 already here (no intro-ack yet on intro 32153). (stream 0 sec old)
Nov 19 10:32:14.189 [info] connection_ap_handshake_attach_circuit(): found open intro circ 32153 (rend 32152); sending introduction. (stream 0 sec old)