Untitled

Jul 22 07:04:23:859 [Notice] Tor v0.1.2.14. This is experimental software. Do not rely on it for strong anonymity.
Jul 22 07:04:23:968 [Notice] Initialized libevent version 1.3b using method win32. Good.
Jul 22 07:04:23:968 [Notice] Opening Socks listener on 127.0.0.1:9050
Jul 22 07:04:23:968 [Notice] Opening Control listener on 127.0.0.1:9051
Jul 22 07:04:26:250 [Info] circuit_predict_and_launch_new(): Have 1 clean circs (0 internal), need another exit circ.
Jul 22 07:04:26:250 [Info] choose_good_exit_server_general(): Found 387 servers that might support 0/0 pending connections.
Jul 22 07:04:26:250 [Info] choose_good_exit_server_general(): Chose exit server 'bach'
Jul 22 07:04:26:531 [Info] connection_dir_client_reached_eof(): Received http status code 503 ("Network status object unavailable") from server '85.214.91.152:9030'. I'll try again soon.
Jul 22 07:04:26:531 [Info] connection_dir_request_failed(): Giving up on directory server at '85.214.91.152'; retrying
Jul 22 07:04:27:156 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'iapetus'
Jul 22 07:04:27:265 [Info] circuit_predict_and_launch_new(): Have 2 clean circs (0 uptime-internal, 0 internal), need another hidserv circ.
Jul 22 07:04:27:812 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'videoraptor'
Jul 22 07:04:27:812 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'videoraptor'
Jul 22 07:04:28:265 [Info] circuit_predict_and_launch_new(): Have 3 clean circs (1 uptime-internal, 1 internal), need another hidserv circ.
Jul 22 07:04:28:265 [Info] circuit_send_next_onion_skin(): First hop: finished sending CREATE_FAST cell to 'iapetus'
Jul 22 07:04:28:296 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jul 22 07:04:28:296 [Info] exit circ (length 3, exit sarkozy1er): $3DE14CAA8E78BB422AED9F38F36187625BDEBB96(open) $B4402A2DB61B0FBFD8E168EF6F54111D36BDAA5E(closed) $9CAE1646602CB6CFAA97B6CC1533942133E3B22B(closed)
Jul 22 07:04:28:312 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jul 22 07:04:28:312 [Info] exit circ (length 3, exit bach): $6F01D9573E4838D0AD424A0FCCC4971445058104(open) commodore64(closed) bach(closed)
Jul 22 07:04:28:312 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jul 22 07:04:28:312 [Info] internal (high-uptime) circ (length 3, exit bettyboop): $6F01D9573E4838D0AD424A0FCCC4971445058104(open) inap1(closed) $D7F821F181CC1B811758E2FEE3EF1E9AD856D06D(closed)
Jul 22 07:04:29:296 [Info] circuit_finish_handshake(): Finished building fast circuit hop:
Jul 22 07:04:29:296 [Info] internal (high-uptime) circ (length 3, exit qed): $3DE14CAA8E78BB422AED9F38F36187625BDEBB96(open) $77B22A03BC35B737427E30B1F1183861C9BFB4B2(closed) $52BA5D64F672B8C31C31DCD0CDC46240AD2ACAD8(closed)
Jul 22 07:04:29:328 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jul 22 07:04:29:343 [Info] internal (high-uptime) circ (length 3, exit bettyboop): $6F01D9573E4838D0AD424A0FCCC4971445058104(open) inap1(open) $D7F821F181CC1B811758E2FEE3EF1E9AD856D06D(closed)
Jul 22 07:04:30:000 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jul 22 07:04:30:015 [Info] internal (high-uptime) circ (length 3, exit bettyboop): $6F01D9573E4838D0AD424A0FCCC4971445058104(open) inap1(open) $D7F821F181CC1B811758E2FEE3EF1E9AD856D06D(open)
Jul 22 07:04:30:015 [Info] circuit_send_next_onion_skin(): circuit built!
Jul 22 07:04:30:015 [Notice] Tor has successfully opened a circuit. Looks like client functionality is working.
Jul 22 07:04:31:328 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jul 22 07:04:31:328 [Info] exit circ (length 3, exit sarkozy1er): $3DE14CAA8E78BB422AED9F38F36187625BDEBB96(open) $B4402A2DB61B0FBFD8E168EF6F54111D36BDAA5E(open) $9CAE1646602CB6CFAA97B6CC1533942133E3B22B(closed)
Jul 22 07:04:31:343 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jul 22 07:04:31:343 [Info] internal (high-uptime) circ (length 3, exit qed): $3DE14CAA8E78BB422AED9F38F36187625BDEBB96(open) $77B22A03BC35B737427E30B1F1183861C9BFB4B2(open) $52BA5D64F672B8C31C31DCD0CDC46240AD2ACAD8(closed)
Jul 22 07:04:33:343 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jul 22 07:04:33:343 [Info] internal (high-uptime) circ (length 3, exit qed): $3DE14CAA8E78BB422AED9F38F36187625BDEBB96(open) $77B22A03BC35B737427E30B1F1183861C9BFB4B2(open) $52BA5D64F672B8C31C31DCD0CDC46240AD2ACAD8(open)
Jul 22 07:04:33:343 [Info] circuit_send_next_onion_skin(): circuit built!
Jul 22 07:04:38:484 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jul 22 07:04:38:500 [Info] exit circ (length 3, exit bach): $6F01D9573E4838D0AD424A0FCCC4971445058104(open) commodore64(open) bach(closed)
Jul 22 07:04:40:671 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jul 22 07:04:40:671 [Info] exit circ (length 3, exit sarkozy1er): $3DE14CAA8E78BB422AED9F38F36187625BDEBB96(open) $B4402A2DB61B0FBFD8E168EF6F54111D36BDAA5E(open) $9CAE1646602CB6CFAA97B6CC1533942133E3B22B(open)
Jul 22 07:04:40:671 [Info] circuit_send_next_onion_skin(): circuit built!
Jul 22 07:04:41:859 [Info] circuit_finish_handshake(): Finished building circuit hop:
Jul 22 07:04:41:906 [Info] exit circ (length 3, exit bach): $6F01D9573E4838D0AD424A0FCCC4971445058104(open) commodore64(open) bach(open)
Jul 22 07:04:41:937 [Info] circuit_send_next_onion_skin(): circuit built!
Jul 22 07:05:26:296 [Info] update_networkstatus_client_downloads(): For 5/5 running directory servers, we have 4 live network-status documents. Downloading 1.
Jul 22 07:32:39:203 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:39:234 [Debug] connection_edge_process_relay_cell(): Now seen 11 relay cells here.
Jul 22 07:32:39:281 [Info] connection_edge_process_relay_cell_not_open(): 'connected' received after 2 seconds.
Jul 22 07:32:39:312 [Info] addressmap_register(): Addressmap: (re)mapped '[scrubbed]' to '[scrubbed]'
Jul 22 07:32:39:343 [Info] exit circ (length 3): $445B04E58D03405934CD67937C404F97237BCD6F(open) $30195D6C3D0E1FDC71141816BED2A46D28362BB1(open) wuschelpuschel(open)
Jul 22 07:32:39:390 [Debug] write_to_buf(): added 10 bytes to buf (now 10 total).
Jul 22 07:32:39:421 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 999
Jul 22 07:32:39:453 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:32:39:500 [Debug] conn_write_callback(): socket 1524 wants to write.
Jul 22 07:32:39:531 [Debug] flush_buf(): 1524: flushed 10 bytes, 0 ready to flush, 0 remain.
Jul 22 07:32:39:562 [Debug] conn_read_callback(): socket 1524 wants to read.
Jul 22 07:32:39:609 [Debug] read_to_buf_impl(): Read 468 bytes. 468 on inbuf.
Jul 22 07:32:39:640 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 999
Jul 22 07:32:39:687 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 999
Jul 22 07:32:39:734 [Debug] connection_edge_package_raw_inbuf(): (1524) Packaging 468 bytes (0 waiting).
Jul 22 07:32:39:781 [Debug] relay_send_command_from_edge(): delivering 2 cell forward.
Jul 22 07:32:39:828 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:39:875 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:39:921 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:39:953 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jul 22 07:32:40:000 [Debug] connection_edge_package_raw_inbuf(): conn->package_window is now 499
Jul 22 07:32:40:046 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 998
Jul 22 07:32:40:093 [Debug] conn_write_callback(): socket 1608 wants to write.
Jul 22 07:32:40:140 [Debug] flush_buf_tls_impl(): flushed 512 bytes, 0 ready to flush, 0 remain.
Jul 22 07:32:40:171 [Debug] connection_handle_write(): After TLS write of 512: 0 read, 586 written
Jul 22 07:32:40:218 [Debug] conn_read_callback(): socket 1784 wants to read.
Jul 22 07:32:40:265 [Debug] connection_handle_listener_read(): Connection accepted on socket 1512 (child of fd 1784).
Jul 22 07:32:40:296 [Debug] connection_add(): new conn type Socks, socket 1512, n_conns 9.
Jul 22 07:32:40:328 [Debug] conn_read_callback(): socket 1512 wants to read.
Jul 22 07:32:40:375 [Debug] read_to_buf_impl(): Read 3 bytes. 3 on inbuf.
Jul 22 07:32:40:406 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:32:40:453 [Debug] fetch_from_buf_socks(): socks5: accepted method 0
Jul 22 07:32:40:484 [Debug] write_to_buf(): added 2 bytes to buf (now 2 total).
Jul 22 07:32:40:515 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:32:40:562 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:32:40:593 [Debug] conn_write_callback(): socket 1512 wants to write.
Jul 22 07:32:40:640 [Debug] flush_buf(): 1512: flushed 2 bytes, 0 ready to flush, 0 remain.
Jul 22 07:32:40:671 [Debug] conn_read_callback(): socket 1512 wants to read.
Jul 22 07:32:40:718 [Debug] read_to_buf_impl(): Read 5 bytes. 5 on inbuf.
Jul 22 07:32:40:750 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:32:40:796 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:32:40:828 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:32:40:875 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:32:40:906 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:32:40:953 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:32:40:984 [Debug] conn_read_callback(): socket 1512 wants to read.
Jul 22 07:32:41:015 [Debug] read_to_buf_impl(): Read 13 bytes. 18 on inbuf.
Jul 22 07:32:41:062 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:32:41:093 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:32:41:140 [Debug] fetch_from_buf_socks(): socks5: fqdn address type
Jul 22 07:32:41:171 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:32:41:218 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:32:41:265 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:32:41:312 [Debug] fetch_from_buf_socks(): socks5: fqdn address type
Jul 22 07:32:41:343 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:32:41:390 [Debug] conn_read_callback(): socket 1512 wants to read.
Jul 22 07:32:41:437 [Debug] read_to_buf_impl(): Read 2 bytes. 20 on inbuf.
Jul 22 07:32:41:468 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:32:41:515 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:32:41:546 [Debug] fetch_from_buf_socks(): socks5: fqdn address type
Jul 22 07:32:41:593 [Debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:80
Jul 22 07:32:41:625 [Debug] connection_ap_handshake_attach_circuit(): Attaching apconn to circ 17122 (stream 0 sec old).
Jul 22 07:32:41:671 [Info] exit circ (length 3): $445B04E58D03405934CD67937C404F97237BCD6F(open) $30195D6C3D0E1FDC71141816BED2A46D28362BB1(open) wuschelpuschel(open)
Jul 22 07:32:41:718 [Debug] link_apconn_to_circ(): attaching new conn to circ. n_circ_id 17122.
Jul 22 07:32:41:750 [Debug] connection_ap_handshake_send_begin(): Sending relay cell to begin stream 83.
Jul 22 07:32:41:796 [Debug] relay_send_command_from_edge(): delivering 1 cell forward.
Jul 22 07:32:41:828 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:41:875 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:41:906 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:41:953 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jul 22 07:32:41:984 [Info] connection_ap_handshake_send_begin(): Address/port sent, ap socket 1512, n_circ_id 17122
Jul 22 07:32:42:031 [Info] connection_edge_process_inbuf(): data from edge while in 'waiting for connect response' state. Leaving it on buffer.
Jul 22 07:32:42:062 [Debug] conn_write_callback(): socket 1608 wants to write.
Jul 22 07:32:42:109 [Debug] flush_buf_tls_impl(): flushed 512 bytes, 0 ready to flush, 0 remain.
Jul 22 07:32:42:140 [Debug] connection_handle_write(): After TLS write of 512: 0 read, 586 written
Jul 22 07:32:42:187 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:32:42:234 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jul 22 07:32:42:265 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:42:312 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:42:359 [Debug] read_to_buf_tls_impl(): Read 1024 bytes. 1024 on inbuf; 0 pending
Jul 22 07:32:42:390 [Debug] connection_read_to_buf(): After TLS read of 1024: 1098 read, 0 written
Jul 22 07:32:42:437 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1024 (0 pending in tls object).
Jul 22 07:32:42:515 [Debug] relay_lookup_conn(): found conn for stream 82.
Jul 22 07:32:42:562 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:42:609 [Debug] connection_edge_process_relay_cell(): Now seen 12 relay cells here.
Jul 22 07:32:42:656 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 998.
Jul 22 07:32:42:687 [Debug] write_to_buf(): added 498 bytes to buf (now 498 total).
Jul 22 07:32:42:734 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 512 (0 pending in tls object).
Jul 22 07:32:42:781 [Debug] relay_lookup_conn(): found conn for stream 82.
Jul 22 07:32:42:812 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:42:859 [Debug] connection_edge_process_relay_cell(): Now seen 13 relay cells here.
Jul 22 07:32:42:890 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 997.
Jul 22 07:32:42:937 [Debug] write_to_buf(): added 143 bytes to buf (now 641 total).
Jul 22 07:32:42:984 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:32:43:015 [Debug] conn_write_callback(): socket 1524 wants to write.
Jul 22 07:32:43:062 [Debug] flush_buf(): 1524: flushed 641 bytes, 0 ready to flush, 0 remain.
Jul 22 07:32:43:109 [Debug] global_read_bucket now 6291456.
Jul 22 07:32:43:140 [Debug] global_write_bucket now 6291456.
Jul 22 07:32:43:203 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:32:43:250 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jul 22 07:32:43:281 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:43:328 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:43:375 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jul 22 07:32:43:406 [Debug] connection_read_to_buf(): After TLS read of 512: 586 read, 0 written
Jul 22 07:32:43:453 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 512 (0 pending in tls object).
Jul 22 07:32:43:500 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:32:43:531 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:43:578 [Debug] connection_edge_process_relay_cell(): Now seen 14 relay cells here.
Jul 22 07:32:43:625 [Info] connection_edge_process_relay_cell_not_open(): 'connected' received after 1 seconds.
Jul 22 07:32:43:671 [Info] addressmap_register(): Addressmap: (re)mapped '[scrubbed]' to '[scrubbed]'
Jul 22 07:32:43:718 [Info] exit circ (length 3): $445B04E58D03405934CD67937C404F97237BCD6F(open) $30195D6C3D0E1FDC71141816BED2A46D28362BB1(open) wuschelpuschel(open)
Jul 22 07:32:43:765 [Debug] write_to_buf(): added 10 bytes to buf (now 10 total).
Jul 22 07:32:43:812 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 998
Jul 22 07:32:43:859 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:32:43:906 [Debug] conn_write_callback(): socket 1512 wants to write.
Jul 22 07:32:43:937 [Debug] flush_buf(): 1512: flushed 10 bytes, 0 ready to flush, 0 remain.
Jul 22 07:32:43:984 [Debug] conn_read_callback(): socket 1512 wants to read.
Jul 22 07:32:44:031 [Debug] read_to_buf_impl(): Read 486 bytes. 486 on inbuf.
Jul 22 07:32:44:125 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 998
Jul 22 07:32:44:156 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 998
Jul 22 07:32:44:203 [Debug] connection_edge_package_raw_inbuf(): (1512) Packaging 486 bytes (0 waiting).
Jul 22 07:32:44:250 [Debug] relay_send_command_from_edge(): delivering 2 cell forward.
Jul 22 07:32:44:312 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:44:343 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:44:390 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:44:437 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jul 22 07:32:44:500 [Debug] connection_edge_package_raw_inbuf(): conn->package_window is now 499
Jul 22 07:32:44:531 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 997
Jul 22 07:32:44:593 [Debug] conn_write_callback(): socket 1608 wants to write.
Jul 22 07:32:44:640 [Debug] flush_buf_tls_impl(): flushed 512 bytes, 0 ready to flush, 0 remain.
Jul 22 07:32:44:687 [Debug] connection_handle_write(): After TLS write of 512: 0 read, 586 written
Jul 22 07:32:44:750 [Debug] conn_read_callback(): socket 1784 wants to read.
Jul 22 07:32:44:812 [Debug] connection_handle_listener_read(): Connection accepted on socket 1500 (child of fd 1784).
Jul 22 07:32:44:859 [Debug] connection_add(): new conn type Socks, socket 1500, n_conns 10.
Jul 22 07:32:44:890 [Debug] conn_read_callback(): socket 1500 wants to read.
Jul 22 07:32:44:937 [Debug] read_to_buf_impl(): Read 3 bytes. 3 on inbuf.
Jul 22 07:32:44:984 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:32:45:031 [Debug] fetch_from_buf_socks(): socks5: accepted method 0
Jul 22 07:32:45:078 [Debug] write_to_buf(): added 2 bytes to buf (now 2 total).
Jul 22 07:32:45:109 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:32:45:156 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:32:45:203 [Debug] conn_write_callback(): socket 1500 wants to write.
Jul 22 07:32:45:265 [Debug] flush_buf(): 1500: flushed 2 bytes, 0 ready to flush, 0 remain.
Jul 22 07:32:45:296 [Debug] conn_read_callback(): socket 1500 wants to read.
Jul 22 07:32:45:343 [Debug] read_to_buf_impl(): Read 5 bytes. 5 on inbuf.
Jul 22 07:32:45:390 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:32:45:437 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:32:45:484 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:32:45:515 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:32:45:562 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:32:45:609 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:32:45:656 [Debug] conn_read_callback(): socket 1500 wants to read.
Jul 22 07:32:45:703 [Debug] read_to_buf_impl(): Read 17 bytes. 22 on inbuf.
Jul 22 07:32:45:750 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:32:45:796 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:32:45:843 [Debug] fetch_from_buf_socks(): socks5: fqdn address type
Jul 22 07:32:45:890 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:32:45:937 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:32:45:968 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:32:46:015 [Debug] fetch_from_buf_socks(): socks5: fqdn address type
Jul 22 07:32:46:062 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:32:46:109 [Debug] conn_read_callback(): socket 1500 wants to read.
Jul 22 07:32:46:156 [Debug] read_to_buf_impl(): Read 2 bytes. 24 on inbuf.
Jul 22 07:32:46:203 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:32:46:250 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:32:46:296 [Debug] fetch_from_buf_socks(): socks5: fqdn address type
Jul 22 07:32:46:328 [Debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:80
Jul 22 07:32:46:375 [Debug] connection_ap_handshake_attach_circuit(): Attaching apconn to circ 17122 (stream 0 sec old).
Jul 22 07:32:46:421 [Info] exit circ (length 3): $445B04E58D03405934CD67937C404F97237BCD6F(open) $30195D6C3D0E1FDC71141816BED2A46D28362BB1(open) wuschelpuschel(open)
Jul 22 07:32:46:468 [Debug] link_apconn_to_circ(): attaching new conn to circ. n_circ_id 17122.
Jul 22 07:32:46:515 [Debug] connection_ap_handshake_send_begin(): Sending relay cell to begin stream 84.
Jul 22 07:32:46:546 [Debug] relay_send_command_from_edge(): delivering 1 cell forward.
Jul 22 07:32:46:593 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:46:640 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:46:687 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:46:734 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jul 22 07:32:46:781 [Info] connection_ap_handshake_send_begin(): Address/port sent, ap socket 1500, n_circ_id 17122
Jul 22 07:32:46:828 [Info] connection_edge_process_inbuf(): data from edge while in 'waiting for connect response' state. Leaving it on buffer.
Jul 22 07:32:46:875 [Debug] conn_write_callback(): socket 1608 wants to write.
Jul 22 07:32:46:921 [Debug] flush_buf_tls_impl(): flushed 512 bytes, 0 ready to flush, 0 remain.
Jul 22 07:32:46:968 [Debug] connection_handle_write(): After TLS write of 512: 0 read, 586 written
Jul 22 07:32:47:015 [Debug] global_read_bucket now 6291456.
Jul 22 07:32:47:062 [Debug] global_write_bucket now 6291456.
Jul 22 07:32:47:109 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:32:47:156 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jul 22 07:32:47:203 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:47:250 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:47:296 [Debug] read_to_buf_tls_impl(): Read 2048 bytes. 2048 on inbuf; 0 pending
Jul 22 07:32:47:343 [Debug] connection_read_to_buf(): After TLS read of 2048: 2122 read, 0 written
Jul 22 07:32:47:390 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 2048 (0 pending in tls object).
Jul 22 07:32:47:437 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:32:47:484 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:47:531 [Debug] connection_edge_process_relay_cell(): Now seen 15 relay cells here.
Jul 22 07:32:47:578 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 996.
Jul 22 07:32:47:625 [Debug] write_to_buf(): added 498 bytes to buf (now 498 total).
Jul 22 07:32:47:687 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1536 (0 pending in tls object).
Jul 22 07:32:47:734 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:32:47:781 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:47:828 [Debug] connection_edge_process_relay_cell(): Now seen 16 relay cells here.
Jul 22 07:32:47:875 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 995.
Jul 22 07:32:47:921 [Debug] write_to_buf(): added 498 bytes to buf (now 996 total).
Jul 22 07:32:47:968 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1024 (0 pending in tls object).
Jul 22 07:32:48:015 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:32:48:062 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:48:109 [Debug] connection_edge_process_relay_cell(): Now seen 17 relay cells here.
Jul 22 07:32:48:156 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 994.
Jul 22 07:32:48:203 [Debug] write_to_buf(): added 498 bytes to buf (now 1494 total).
Jul 22 07:32:48:250 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 512 (0 pending in tls object).
Jul 22 07:32:48:312 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:32:48:359 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:48:406 [Debug] connection_edge_process_relay_cell(): Now seen 18 relay cells here.
Jul 22 07:32:48:453 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 993.
Jul 22 07:32:48:500 [Debug] write_to_buf(): added 219 bytes to buf (now 1713 total).
Jul 22 07:32:48:546 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:32:48:593 [Debug] conn_write_callback(): socket 1512 wants to write.
Jul 22 07:32:48:640 [Debug] flush_buf(): 1512: flushed 1713 bytes, 0 ready to flush, 0 remain.
Jul 22 07:32:48:703 [Debug] global_read_bucket now 6291456.
Jul 22 07:32:48:750 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:32:48:812 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jul 22 07:32:48:859 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:48:906 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:48:953 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jul 22 07:32:49:000 [Debug] connection_read_to_buf(): After TLS read of 512: 586 read, 0 written
Jul 22 07:32:49:046 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 512 (0 pending in tls object).
Jul 22 07:32:49:093 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:49:156 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:49:203 [Debug] connection_edge_process_relay_cell(): Now seen 19 relay cells here.
Jul 22 07:32:49:250 [Info] connection_edge_process_relay_cell_not_open(): 'connected' received after 2 seconds.
Jul 22 07:32:49:359 [Info] addressmap_register(): Addressmap: (re)mapped '[scrubbed]' to '[scrubbed]'
Jul 22 07:32:49:625 [Info] exit circ (length 3): $445B04E58D03405934CD67937C404F97237BCD6F(open) $30195D6C3D0E1FDC71141816BED2A46D28362BB1(open) wuschelpuschel(open)
Jul 22 07:32:49:671 [Debug] write_to_buf(): added 10 bytes to buf (now 10 total).
Jul 22 07:32:49:718 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 997
Jul 22 07:32:49:765 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:32:49:812 [Debug] conn_write_callback(): socket 1500 wants to write.
Jul 22 07:32:49:875 [Debug] flush_buf(): 1500: flushed 10 bytes, 0 ready to flush, 0 remain.
Jul 22 07:32:49:921 [Debug] conn_read_callback(): socket 1500 wants to read.
Jul 22 07:32:49:968 [Debug] read_to_buf_impl(): Read 793 bytes. 793 on inbuf.
Jul 22 07:32:50:015 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 997
Jul 22 07:32:50:062 [Debug] connection_edge_package_raw_inbuf(): (1500) Packaging 498 bytes (295 waiting).
Jul 22 07:32:50:125 [Debug] relay_send_command_from_edge(): delivering 2 cell forward.
Jul 22 07:32:50:171 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:50:218 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:50:265 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:50:312 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jul 22 07:32:50:375 [Debug] connection_edge_package_raw_inbuf(): conn->package_window is now 499
Jul 22 07:32:50:421 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 996
Jul 22 07:32:50:468 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 996
Jul 22 07:32:50:515 [Debug] connection_edge_package_raw_inbuf(): (1500) Packaging 295 bytes (0 waiting).
Jul 22 07:32:50:562 [Debug] relay_send_command_from_edge(): delivering 2 cell forward.
Jul 22 07:32:50:609 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:50:687 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:50:734 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:50:781 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jul 22 07:32:50:843 [Debug] connection_edge_package_raw_inbuf(): conn->package_window is now 498
Jul 22 07:32:50:890 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 995
Jul 22 07:32:50:937 [Debug] conn_write_callback(): socket 1608 wants to write.
Jul 22 07:32:50:984 [Debug] flush_buf_tls_impl(): flushed 1024 bytes, 0 ready to flush, 0 remain.
Jul 22 07:32:51:031 [Debug] connection_handle_write(): After TLS write of 1024: 0 read, 1098 written
Jul 22 07:32:51:093 [Debug] conn_read_callback(): socket 1512 wants to read.
Jul 22 07:32:51:140 [Debug] read_to_buf_impl(): Read 464 bytes. 464 on inbuf.
Jul 22 07:32:51:187 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 995
Jul 22 07:32:51:250 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 995
Jul 22 07:32:51:296 [Debug] connection_edge_package_raw_inbuf(): (1512) Packaging 464 bytes (0 waiting).
Jul 22 07:32:51:343 [Debug] relay_send_command_from_edge(): delivering 2 cell forward.
Jul 22 07:32:51:406 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:51:453 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:51:500 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:32:51:546 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jul 22 07:32:51:609 [Debug] connection_edge_package_raw_inbuf(): conn->package_window is now 498
Jul 22 07:32:51:656 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 994
Jul 22 07:32:51:703 [Debug] conn_write_callback(): socket 1608 wants to write.
Jul 22 07:32:51:765 [Debug] flush_buf_tls_impl(): flushed 512 bytes, 0 ready to flush, 0 remain.
Jul 22 07:32:51:812 [Debug] connection_handle_write(): After TLS write of 512: 0 read, 586 written
Jul 22 07:32:51:875 [Debug] global_read_bucket now 6291456.
Jul 22 07:32:51:921 [Debug] global_write_bucket now 6291456.
Jul 22 07:32:51:984 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:32:52:031 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jul 22 07:32:52:078 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:52:140 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:52:187 [Debug] tor_tls_read(): read returned r=-1, err=-2
Jul 22 07:32:52:250 [Debug] connection_read_to_buf(): After TLS read of 0: 2800 read, 0 written
Jul 22 07:32:52:296 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:32:52:343 [Debug] global_read_bucket now 6291456.
Jul 22 07:32:52:406 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:32:52:453 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jul 22 07:32:52:500 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:52:562 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:52:609 [Debug] tor_tls_read(): read returned r=-1, err=-2
Jul 22 07:32:52:671 [Debug] connection_read_to_buf(): After TLS read of 0: 2800 read, 0 written
Jul 22 07:32:52:718 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:32:52:765 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:32:52:828 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jul 22 07:32:52:875 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:52:937 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:52:984 [Debug] tor_tls_read(): read returned r=-1, err=-2
Jul 22 07:32:53:031 [Debug] connection_read_to_buf(): After TLS read of 0: 2800 read, 0 written
Jul 22 07:32:53:093 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:32:53:140 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:32:53:203 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jul 22 07:32:53:250 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:53:312 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:53:359 [Debug] tor_tls_read(): read returned r=-1, err=-2
Jul 22 07:32:53:421 [Debug] connection_read_to_buf(): After TLS read of 0: 2800 read, 0 written
Jul 22 07:32:53:468 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:32:53:531 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:32:53:578 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jul 22 07:32:53:640 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:53:687 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:53:750 [Debug] tor_tls_read(): read returned r=-1, err=-2
Jul 22 07:32:53:796 [Debug] connection_read_to_buf(): After TLS read of 0: 2800 read, 0 written
Jul 22 07:32:53:843 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:32:53:906 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:32:53:953 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jul 22 07:32:54:015 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:54:062 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:32:54:125 [Debug] read_to_buf_tls_impl(): Read 4096 bytes. 4096 on inbuf; 11776 pending
Jul 22 07:32:54:171 [Debug] read_to_buf_tls(): start: 4096 on buf, 11776 pending, at_most 11776.
Jul 22 07:32:54:234 [Debug] buf_ensure_capacity(): Growing buffer from 4096 to 16384 bytes.
Jul 22 07:32:54:281 [Debug] read_to_buf_tls_impl(): before: 4096 on buf, 11776 pending, at_most 11776.
Jul 22 07:32:54:359 [Debug] read_to_buf_tls_impl(): Read 11776 bytes. 15872 on inbuf; 0 pending
Jul 22 07:32:54:421 [Debug] connection_read_to_buf(): After TLS read of 15872: 1946 read, 0 written
Jul 22 07:32:54:484 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 15872 (0 pending in tls object).
Jul 22 07:32:54:546 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:54:609 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:54:671 [Debug] connection_edge_process_relay_cell(): Now seen 20 relay cells here.
Jul 22 07:32:54:734 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 992.
Jul 22 07:32:54:781 [Debug] write_to_buf(): added 498 bytes to buf (now 498 total).
Jul 22 07:32:54:843 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 15360 (0 pending in tls object).
Jul 22 07:32:54:906 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:54:953 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:55:015 [Debug] connection_edge_process_relay_cell(): Now seen 21 relay cells here.
Jul 22 07:32:55:062 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 991.
Jul 22 07:32:55:125 [Debug] write_to_buf(): added 498 bytes to buf (now 996 total).
Jul 22 07:32:55:187 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 14848 (0 pending in tls object).
Jul 22 07:32:55:234 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:55:296 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:55:359 [Debug] connection_edge_process_relay_cell(): Now seen 22 relay cells here.
Jul 22 07:32:55:406 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 990.
Jul 22 07:32:55:468 [Debug] write_to_buf(): added 498 bytes to buf (now 1494 total).
Jul 22 07:32:55:531 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 14336 (0 pending in tls object).
Jul 22 07:32:55:578 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:55:640 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:55:718 [Debug] connection_edge_process_relay_cell(): Now seen 23 relay cells here.
Jul 22 07:32:56:000 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 989.
Jul 22 07:32:56:093 [Debug] write_to_buf(): added 498 bytes to buf (now 1992 total).
Jul 22 07:32:56:156 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 13824 (0 pending in tls object).
Jul 22 07:32:56:203 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:56:265 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:56:328 [Debug] connection_edge_process_relay_cell(): Now seen 24 relay cells here.
Jul 22 07:32:56:406 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 988.
Jul 22 07:32:56:484 [Debug] write_to_buf(): added 498 bytes to buf (now 2490 total).
Jul 22 07:32:56:531 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 13312 (0 pending in tls object).
Jul 22 07:32:56:593 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:56:656 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:56:703 [Debug] connection_edge_process_relay_cell(): Now seen 25 relay cells here.
Jul 22 07:32:56:765 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 987.
Jul 22 07:32:56:828 [Debug] write_to_buf(): added 498 bytes to buf (now 2988 total).
Jul 22 07:32:56:890 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 12800 (0 pending in tls object).
Jul 22 07:32:56:953 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:57:015 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:57:062 [Debug] connection_edge_process_relay_cell(): Now seen 26 relay cells here.
Jul 22 07:32:57:125 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 986.
Jul 22 07:32:57:187 [Debug] write_to_buf(): added 498 bytes to buf (now 3486 total).
Jul 22 07:32:57:250 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 12288 (0 pending in tls object).
Jul 22 07:32:57:312 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:57:375 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:57:421 [Debug] connection_edge_process_relay_cell(): Now seen 27 relay cells here.
Jul 22 07:32:57:484 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 985.
Jul 22 07:32:57:546 [Debug] write_to_buf(): added 498 bytes to buf (now 3984 total).
Jul 22 07:32:57:609 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 11776 (0 pending in tls object).
Jul 22 07:32:57:671 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:57:734 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:57:781 [Debug] connection_edge_process_relay_cell(): Now seen 28 relay cells here.
Jul 22 07:32:57:843 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 984.
Jul 22 07:32:57:906 [Debug] buf_ensure_capacity(): Growing buffer from 4096 to 8192 bytes.
Jul 22 07:32:57:953 [Debug] write_to_buf(): added 498 bytes to buf (now 4482 total).
Jul 22 07:32:58:015 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 11264 (0 pending in tls object).
Jul 22 07:32:58:078 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:58:125 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:58:187 [Debug] connection_edge_process_relay_cell(): Now seen 29 relay cells here.
Jul 22 07:32:58:250 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 983.
Jul 22 07:32:58:296 [Debug] write_to_buf(): added 498 bytes to buf (now 4980 total).
Jul 22 07:32:58:359 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 10752 (0 pending in tls object).
Jul 22 07:32:58:421 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:58:484 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:58:531 [Debug] connection_edge_process_relay_cell(): Now seen 30 relay cells here.
Jul 22 07:32:58:593 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 982.
Jul 22 07:32:58:656 [Debug] write_to_buf(): added 498 bytes to buf (now 5478 total).
Jul 22 07:32:58:703 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 10240 (0 pending in tls object).
Jul 22 07:32:58:796 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:58:859 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:58:921 [Debug] connection_edge_process_relay_cell(): Now seen 31 relay cells here.
Jul 22 07:32:58:984 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 981.
Jul 22 07:32:59:031 [Debug] write_to_buf(): added 498 bytes to buf (now 5976 total).
Jul 22 07:32:59:093 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 9728 (0 pending in tls object).
Jul 22 07:32:59:156 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:59:218 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:59:281 [Debug] connection_edge_process_relay_cell(): Now seen 32 relay cells here.
Jul 22 07:32:59:343 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 980.
Jul 22 07:32:59:406 [Debug] write_to_buf(): added 498 bytes to buf (now 6474 total).
Jul 22 07:32:59:468 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 9216 (0 pending in tls object).
Jul 22 07:32:59:531 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:32:59:593 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:32:59:656 [Debug] connection_edge_process_relay_cell(): Now seen 33 relay cells here.
Jul 22 07:32:59:718 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 979.
Jul 22 07:32:59:765 [Debug] write_to_buf(): added 498 bytes to buf (now 6972 total).
Jul 22 07:32:59:828 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 8704 (0 pending in tls object).
Jul 22 07:33:00:078 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:00:234 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:00:296 [Debug] connection_edge_process_relay_cell(): Now seen 34 relay cells here.
Jul 22 07:33:00:343 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 978.
Jul 22 07:33:00:406 [Debug] write_to_buf(): added 498 bytes to buf (now 7470 total).
Jul 22 07:33:00:468 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 8192 (0 pending in tls object).
Jul 22 07:33:00:515 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:00:578 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:00:640 [Debug] connection_edge_process_relay_cell(): Now seen 35 relay cells here.
Jul 22 07:33:00:703 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 977.
Jul 22 07:33:00:750 [Debug] write_to_buf(): added 498 bytes to buf (now 7968 total).
Jul 22 07:33:00:812 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 7680 (0 pending in tls object).
Jul 22 07:33:00:875 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:00:937 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:01:000 [Debug] connection_edge_process_relay_cell(): Now seen 36 relay cells here.
Jul 22 07:33:01:062 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 976.
Jul 22 07:33:01:109 [Debug] buf_ensure_capacity(): Growing buffer from 8192 to 16384 bytes.
Jul 22 07:33:01:171 [Debug] write_to_buf(): added 498 bytes to buf (now 8466 total).
Jul 22 07:33:01:234 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 7168 (0 pending in tls object).
Jul 22 07:33:01:296 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:01:359 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:01:406 [Debug] connection_edge_process_relay_cell(): Now seen 37 relay cells here.
Jul 22 07:33:01:468 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 975.
Jul 22 07:33:01:531 [Debug] write_to_buf(): added 498 bytes to buf (now 8964 total).
Jul 22 07:33:01:593 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 6656 (0 pending in tls object).
Jul 22 07:33:01:671 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:01:750 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:01:812 [Debug] connection_edge_process_relay_cell(): Now seen 38 relay cells here.
Jul 22 07:33:01:875 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 974.
Jul 22 07:33:01:921 [Debug] write_to_buf(): added 498 bytes to buf (now 9462 total).
Jul 22 07:33:01:984 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 6144 (0 pending in tls object).
Jul 22 07:33:02:046 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:02:109 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:02:156 [Debug] connection_edge_process_relay_cell(): Now seen 39 relay cells here.
Jul 22 07:33:02:218 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 973.
Jul 22 07:33:02:281 [Debug] write_to_buf(): added 498 bytes to buf (now 9960 total).
Jul 22 07:33:02:343 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 5632 (0 pending in tls object).
Jul 22 07:33:02:390 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:02:453 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:02:515 [Debug] connection_edge_process_relay_cell(): Now seen 40 relay cells here.
Jul 22 07:33:02:578 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 972.
Jul 22 07:33:02:640 [Debug] write_to_buf(): added 498 bytes to buf (now 10458 total).
Jul 22 07:33:02:687 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 5120 (0 pending in tls object).
Jul 22 07:33:02:750 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:02:812 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:02:859 [Debug] connection_edge_process_relay_cell(): Now seen 41 relay cells here.
Jul 22 07:33:02:921 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 971.
Jul 22 07:33:02:984 [Debug] write_to_buf(): added 498 bytes to buf (now 10956 total).
Jul 22 07:33:03:046 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 4608 (0 pending in tls object).
Jul 22 07:33:03:093 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:03:156 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:03:218 [Debug] connection_edge_process_relay_cell(): Now seen 42 relay cells here.
Jul 22 07:33:03:281 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 970.
Jul 22 07:33:03:328 [Debug] write_to_buf(): added 498 bytes to buf (now 11454 total).
Jul 22 07:33:03:390 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 4096 (0 pending in tls object).
Jul 22 07:33:03:437 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:03:500 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:03:562 [Debug] connection_edge_process_relay_cell(): Now seen 43 relay cells here.
Jul 22 07:33:03:609 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 969.
Jul 22 07:33:03:671 [Debug] write_to_buf(): added 498 bytes to buf (now 11952 total).
Jul 22 07:33:03:734 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 3584 (0 pending in tls object).
Jul 22 07:33:03:781 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:03:843 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:03:906 [Debug] connection_edge_process_relay_cell(): Now seen 44 relay cells here.
Jul 22 07:33:03:968 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 968.
Jul 22 07:33:04:015 [Debug] write_to_buf(): added 498 bytes to buf (now 12450 total).
Jul 22 07:33:04:078 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 3072 (0 pending in tls object).
Jul 22 07:33:04:140 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:04:203 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:04:265 [Debug] connection_edge_process_relay_cell(): Now seen 45 relay cells here.
Jul 22 07:33:04:328 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 967.
Jul 22 07:33:04:375 [Debug] write_to_buf(): added 498 bytes to buf (now 12948 total).
Jul 22 07:33:04:437 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 2560 (0 pending in tls object).
Jul 22 07:33:04:500 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:04:546 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:04:609 [Debug] connection_edge_process_relay_cell(): Now seen 46 relay cells here.
Jul 22 07:33:04:671 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 966.
Jul 22 07:33:04:734 [Debug] write_to_buf(): added 498 bytes to buf (now 13446 total).
Jul 22 07:33:04:781 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 2048 (0 pending in tls object).
Jul 22 07:33:04:875 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:04:921 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:04:984 [Debug] connection_edge_process_relay_cell(): Now seen 47 relay cells here.
Jul 22 07:33:05:046 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 965.
Jul 22 07:33:05:203 [Debug] write_to_buf(): added 498 bytes to buf (now 13944 total).
Jul 22 07:33:05:421 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1536 (0 pending in tls object).
Jul 22 07:33:05:484 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:05:531 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:05:609 [Debug] connection_edge_process_relay_cell(): Now seen 48 relay cells here.
Jul 22 07:33:05:671 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 964.
Jul 22 07:33:05:734 [Debug] write_to_buf(): added 266 bytes to buf (now 14210 total).
Jul 22 07:33:05:796 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1024 (0 pending in tls object).
Jul 22 07:33:05:859 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:05:921 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:05:984 [Debug] connection_edge_process_relay_cell(): Now seen 49 relay cells here.
Jul 22 07:33:06:031 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 963.
Jul 22 07:33:06:093 [Debug] write_to_buf(): added 498 bytes to buf (now 498 total).
Jul 22 07:33:06:156 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 512 (0 pending in tls object).
Jul 22 07:33:06:203 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:06:265 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:06:328 [Debug] connection_edge_process_relay_cell(): Now seen 50 relay cells here.
Jul 22 07:33:06:390 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 962.
Jul 22 07:33:06:437 [Debug] write_to_buf(): added 498 bytes to buf (now 996 total).
Jul 22 07:33:06:500 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:33:06:562 [Debug] conn_write_callback(): socket 1500 wants to write.
Jul 22 07:33:06:625 [Debug] flush_buf(): 1500: flushed 14210 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:06:671 [Debug] conn_write_callback(): socket 1512 wants to write.
Jul 22 07:33:06:796 [Debug] flush_buf(): 1512: flushed 996 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:06:875 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:33:06:937 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 16384.
Jul 22 07:33:07:000 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:07:062 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:07:171 [Debug] tor_tls_read(): read returned r=-1, err=-2
Jul 22 07:33:07:250 [Debug] connection_read_to_buf(): After TLS read of 0: 4200 read, 0 written
Jul 22 07:33:07:312 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:33:07:375 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:33:07:437 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 16384.
Jul 22 07:33:07:500 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:07:562 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:07:640 [Debug] read_to_buf_tls_impl(): Read 4608 bytes. 4608 on inbuf; 0 pending
Jul 22 07:33:07:687 [Debug] connection_read_to_buf(): After TLS read of 4608: 482 read, 0 written
Jul 22 07:33:07:750 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 4608 (0 pending in tls object).
Jul 22 07:33:07:812 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:07:875 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:07:937 [Debug] connection_edge_process_relay_cell(): Now seen 51 relay cells here.
Jul 22 07:33:07:984 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 961.
Jul 22 07:33:08:078 [Debug] write_to_buf(): added 498 bytes to buf (now 498 total).
Jul 22 07:33:08:140 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 4096 (0 pending in tls object).
Jul 22 07:33:08:234 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:08:343 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:08:453 [Debug] connection_edge_process_relay_cell(): Now seen 52 relay cells here.
Jul 22 07:33:08:546 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 960.
Jul 22 07:33:08:671 [Debug] write_to_buf(): added 498 bytes to buf (now 996 total).
Jul 22 07:33:08:781 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 3584 (0 pending in tls object).
Jul 22 07:33:08:843 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:08:906 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:08:968 [Debug] connection_edge_process_relay_cell(): Now seen 53 relay cells here.
Jul 22 07:33:09:015 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 959.
Jul 22 07:33:09:078 [Debug] write_to_buf(): added 498 bytes to buf (now 1494 total).
Jul 22 07:33:09:140 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 3072 (0 pending in tls object).
Jul 22 07:33:09:203 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:09:265 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:09:328 [Debug] connection_edge_process_relay_cell(): Now seen 54 relay cells here.
Jul 22 07:33:09:390 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 958.
Jul 22 07:33:09:437 [Debug] write_to_buf(): added 498 bytes to buf (now 1992 total).
Jul 22 07:33:09:500 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 2560 (0 pending in tls object).
Jul 22 07:33:09:562 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:09:609 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:09:671 [Debug] connection_edge_process_relay_cell(): Now seen 55 relay cells here.
Jul 22 07:33:09:734 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 957.
Jul 22 07:33:09:781 [Debug] write_to_buf(): added 498 bytes to buf (now 2490 total).
Jul 22 07:33:09:843 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 2048 (0 pending in tls object).
Jul 22 07:33:09:906 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:09:953 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:10:015 [Debug] connection_edge_process_relay_cell(): Now seen 56 relay cells here.
Jul 22 07:33:10:078 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 956.
Jul 22 07:33:10:125 [Debug] write_to_buf(): added 498 bytes to buf (now 2988 total).
Jul 22 07:33:10:187 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1536 (0 pending in tls object).
Jul 22 07:33:10:250 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:10:296 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:10:359 [Debug] connection_edge_process_relay_cell(): Now seen 57 relay cells here.
Jul 22 07:33:10:421 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 955.
Jul 22 07:33:10:468 [Debug] write_to_buf(): added 498 bytes to buf (now 3486 total).
Jul 22 07:33:10:531 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1024 (0 pending in tls object).
Jul 22 07:33:10:593 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:10:656 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:10:703 [Debug] connection_edge_process_relay_cell(): Now seen 58 relay cells here.
Jul 22 07:33:10:765 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 954.
Jul 22 07:33:10:828 [Debug] write_to_buf(): added 498 bytes to buf (now 3984 total).
Jul 22 07:33:10:875 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 512 (0 pending in tls object).
Jul 22 07:33:10:937 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:11:000 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:11:046 [Debug] connection_edge_process_relay_cell(): Now seen 59 relay cells here.
Jul 22 07:33:11:109 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 953.
Jul 22 07:33:11:171 [Debug] buf_ensure_capacity(): Growing buffer from 4096 to 8192 bytes.
Jul 22 07:33:11:218 [Debug] write_to_buf(): added 321 bytes to buf (now 4305 total).
Jul 22 07:33:11:281 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:33:11:343 [Debug] conn_write_callback(): socket 1512 wants to write.
Jul 22 07:33:11:406 [Debug] flush_buf(): 1512: flushed 4305 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:11:453 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:33:11:515 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 16384.
Jul 22 07:33:11:578 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:11:640 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:11:703 [Debug] tor_tls_read(): read returned r=-1, err=-2
Jul 22 07:33:11:750 [Debug] connection_read_to_buf(): After TLS read of 0: 2800 read, 0 written
Jul 22 07:33:11:812 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:33:11:875 [Debug] global_read_bucket now 6291456.
Jul 22 07:33:11:953 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:33:12:015 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 16384.
Jul 22 07:33:12:062 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:12:125 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:12:187 [Debug] read_to_buf_tls_impl(): Read 4608 bytes. 4608 on inbuf; 0 pending
Jul 22 07:33:12:250 [Debug] connection_read_to_buf(): After TLS read of 4608: 1882 read, 0 written
Jul 22 07:33:12:296 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 4608 (0 pending in tls object).
Jul 22 07:33:12:359 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:12:421 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:12:468 [Debug] connection_edge_process_relay_cell(): Now seen 60 relay cells here.
Jul 22 07:33:12:531 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 952.
Jul 22 07:33:12:593 [Debug] write_to_buf(): added 498 bytes to buf (now 498 total).
Jul 22 07:33:12:656 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 4096 (0 pending in tls object).
Jul 22 07:33:12:718 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:12:781 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:12:828 [Debug] connection_edge_process_relay_cell(): Now seen 61 relay cells here.
Jul 22 07:33:12:890 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 951.
Jul 22 07:33:12:953 [Debug] write_to_buf(): added 498 bytes to buf (now 996 total).
Jul 22 07:33:13:015 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 3584 (0 pending in tls object).
Jul 22 07:33:13:062 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:13:125 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:13:187 [Debug] connection_edge_process_relay_cell(): Now seen 62 relay cells here.
Jul 22 07:33:13:250 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 950.
Jul 22 07:33:13:296 [Debug] write_to_buf(): added 498 bytes to buf (now 1494 total).
Jul 22 07:33:13:359 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 3072 (0 pending in tls object).
Jul 22 07:33:13:421 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:13:484 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:13:531 [Debug] connection_edge_process_relay_cell(): Now seen 63 relay cells here.
Jul 22 07:33:13:593 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 949.
Jul 22 07:33:13:656 [Debug] write_to_buf(): added 498 bytes to buf (now 1992 total).
Jul 22 07:33:13:718 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 2560 (0 pending in tls object).
Jul 22 07:33:13:765 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:13:828 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:13:875 [Debug] connection_edge_process_relay_cell(): Now seen 64 relay cells here.
Jul 22 07:33:13:937 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 948.
Jul 22 07:33:14:000 [Debug] write_to_buf(): added 498 bytes to buf (now 2490 total).
Jul 22 07:33:14:046 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 2048 (0 pending in tls object).
Jul 22 07:33:14:125 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:14:437 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:14:515 [Debug] connection_edge_process_relay_cell(): Now seen 65 relay cells here.
Jul 22 07:33:14:562 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 947.
Jul 22 07:33:14:625 [Debug] write_to_buf(): added 498 bytes to buf (now 2988 total).
Jul 22 07:33:14:687 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1536 (0 pending in tls object).
Jul 22 07:33:14:734 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:14:796 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:14:859 [Debug] connection_edge_process_relay_cell(): Now seen 66 relay cells here.
Jul 22 07:33:15:078 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 946.
Jul 22 07:33:15:250 [Debug] write_to_buf(): added 498 bytes to buf (now 3486 total).
Jul 22 07:33:15:312 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1024 (0 pending in tls object).
Jul 22 07:33:15:375 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:15:437 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:15:484 [Debug] connection_edge_process_relay_cell(): Now seen 67 relay cells here.
Jul 22 07:33:15:546 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 945.
Jul 22 07:33:15:609 [Debug] write_to_buf(): added 498 bytes to buf (now 3984 total).
Jul 22 07:33:15:656 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 512 (0 pending in tls object).
Jul 22 07:33:15:718 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:15:781 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:15:828 [Debug] connection_edge_process_relay_cell(): Now seen 68 relay cells here.
Jul 22 07:33:15:968 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 944.
Jul 22 07:33:16:203 [Debug] write_to_buf(): added 42 bytes to buf (now 4026 total).
Jul 22 07:33:16:250 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:33:16:312 [Debug] conn_write_callback(): socket 1512 wants to write.
Jul 22 07:33:16:375 [Debug] flush_buf(): 1512: flushed 4026 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:16:421 [Debug] conn_read_callback(): socket 1512 wants to read.
Jul 22 07:33:16:484 [Debug] read_to_buf_impl(): Read 458 bytes. 458 on inbuf.
Jul 22 07:33:16:546 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 994
Jul 22 07:33:16:593 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 994
Jul 22 07:33:16:656 [Debug] connection_edge_package_raw_inbuf(): (1512) Packaging 458 bytes (0 waiting).
Jul 22 07:33:16:718 [Debug] relay_send_command_from_edge(): delivering 2 cell forward.
Jul 22 07:33:16:781 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:16:828 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:16:890 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:16:953 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jul 22 07:33:17:000 [Debug] connection_edge_package_raw_inbuf(): conn->package_window is now 497
Jul 22 07:33:17:062 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 993
Jul 22 07:33:17:125 [Debug] conn_write_callback(): socket 1608 wants to write.
Jul 22 07:33:17:171 [Debug] flush_buf_tls_impl(): flushed 512 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:17:234 [Debug] connection_handle_write(): After TLS write of 512: 0 read, 586 written
Jul 22 07:33:17:296 [Debug] conn_read_callback(): socket 1784 wants to read.
Jul 22 07:33:17:343 [Debug] connection_handle_listener_read(): Connection accepted on socket 1488 (child of fd 1784).
Jul 22 07:33:17:406 [Debug] connection_add(): new conn type Socks, socket 1488, n_conns 11.
Jul 22 07:33:17:468 [Debug] conn_read_callback(): socket 1488 wants to read.
Jul 22 07:33:17:531 [Debug] read_to_buf_impl(): Read 3 bytes. 3 on inbuf.
Jul 22 07:33:17:578 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:33:17:640 [Debug] fetch_from_buf_socks(): socks5: accepted method 0
Jul 22 07:33:17:703 [Debug] write_to_buf(): added 2 bytes to buf (now 2 total).
Jul 22 07:33:17:750 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:33:17:812 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:33:17:859 [Debug] conn_write_callback(): socket 1488 wants to write.
Jul 22 07:33:17:921 [Debug] flush_buf(): 1488: flushed 2 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:17:984 [Debug] conn_read_callback(): socket 1488 wants to read.
Jul 22 07:33:18:031 [Debug] read_to_buf_impl(): Read 5 bytes. 5 on inbuf.
Jul 22 07:33:18:093 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:33:18:156 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:33:18:203 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:33:18:265 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:33:18:328 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:33:18:390 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:33:18:437 [Debug] conn_read_callback(): socket 1488 wants to read.
Jul 22 07:33:18:500 [Debug] read_to_buf_impl(): Read 13 bytes. 18 on inbuf.
Jul 22 07:33:18:562 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:33:18:625 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:33:18:687 [Debug] fetch_from_buf_socks(): socks5: fqdn address type
Jul 22 07:33:18:734 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:33:18:796 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:33:18:859 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:33:18:906 [Debug] fetch_from_buf_socks(): socks5: fqdn address type
Jul 22 07:33:18:968 [Debug] connection_ap_handshake_process_socks(): socks handshake not all here yet.
Jul 22 07:33:19:015 [Debug] conn_read_callback(): socket 1488 wants to read.
Jul 22 07:33:19:078 [Debug] read_to_buf_impl(): Read 2 bytes. 20 on inbuf.
Jul 22 07:33:19:140 [Debug] connection_ap_handshake_process_socks(): entered.
Jul 22 07:33:19:187 [Debug] fetch_from_buf_socks(): socks5: checking request
Jul 22 07:33:19:250 [Debug] fetch_from_buf_socks(): socks5: fqdn address type
Jul 22 07:33:19:312 [Debug] connection_ap_handshake_rewrite_and_attach(): Client asked for [scrubbed]:80
Jul 22 07:33:19:359 [Info] addressmap_rewrite(): Addressmap: rewriting [scrubbed] to [scrubbed]
Jul 22 07:33:19:421 [Debug] connection_ap_handshake_attach_circuit(): Attaching apconn to circ 17122 (stream 0 sec old).
Jul 22 07:33:19:468 [Info] exit circ (length 3): $445B04E58D03405934CD67937C404F97237BCD6F(open) $30195D6C3D0E1FDC71141816BED2A46D28362BB1(open) wuschelpuschel(open)
Jul 22 07:33:19:531 [Debug] link_apconn_to_circ(): attaching new conn to circ. n_circ_id 17122.
Jul 22 07:33:19:593 [Debug] connection_ap_handshake_send_begin(): Sending relay cell to begin stream 85.
Jul 22 07:33:19:640 [Debug] relay_send_command_from_edge(): delivering 1 cell forward.
Jul 22 07:33:19:703 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:19:765 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:19:812 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:19:875 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jul 22 07:33:19:921 [Info] connection_ap_handshake_send_begin(): Address/port sent, ap socket 1488, n_circ_id 17122
Jul 22 07:33:19:984 [Info] connection_edge_process_inbuf(): data from edge while in 'waiting for connect response' state. Leaving it on buffer.
Jul 22 07:33:20:046 [Debug] conn_write_callback(): socket 1608 wants to write.
Jul 22 07:33:20:093 [Debug] flush_buf_tls_impl(): flushed 512 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:20:156 [Debug] connection_handle_write(): After TLS write of 512: 0 read, 586 written
Jul 22 07:33:20:218 [Debug] global_read_bucket now 6291456.
Jul 22 07:33:20:281 [Debug] global_write_bucket now 6291456.
Jul 22 07:33:20:453 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:33:20:500 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 16384.
Jul 22 07:33:20:562 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:20:625 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:20:671 [Debug] tor_tls_read(): read returned r=-1, err=-2
Jul 22 07:33:20:734 [Debug] connection_read_to_buf(): After TLS read of 0: 2800 read, 0 written
Jul 22 07:33:20:796 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:33:20:859 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:33:20:921 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 16384.
Jul 22 07:33:20:984 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:21:046 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:21:093 [Debug] tor_tls_read(): read returned r=-1, err=-2
Jul 22 07:33:21:156 [Debug] connection_read_to_buf(): After TLS read of 0: 2800 read, 0 written
Jul 22 07:33:21:218 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:33:21:281 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:33:21:328 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 16384.
Jul 22 07:33:21:390 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:21:453 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:21:500 [Debug] read_to_buf_tls_impl(): Read 6144 bytes. 6144 on inbuf; 0 pending
Jul 22 07:33:21:562 [Debug] connection_read_to_buf(): After TLS read of 6144: 618 read, 0 written
Jul 22 07:33:21:625 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 6144 (0 pending in tls object).
Jul 22 07:33:21:687 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:21:734 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:21:796 [Debug] connection_edge_process_relay_cell(): Now seen 69 relay cells here.
Jul 22 07:33:21:859 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 943.
Jul 22 07:33:21:906 [Debug] write_to_buf(): added 498 bytes to buf (now 498 total).
Jul 22 07:33:21:968 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 5632 (0 pending in tls object).
Jul 22 07:33:22:031 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:22:078 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:22:140 [Debug] connection_edge_process_relay_cell(): Now seen 70 relay cells here.
Jul 22 07:33:22:203 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 942.
Jul 22 07:33:22:250 [Debug] write_to_buf(): added 498 bytes to buf (now 996 total).
Jul 22 07:33:22:312 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 5120 (0 pending in tls object).
Jul 22 07:33:22:375 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:22:437 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:22:484 [Debug] connection_edge_process_relay_cell(): Now seen 71 relay cells here.
Jul 22 07:33:22:546 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 941.
Jul 22 07:33:22:609 [Debug] write_to_buf(): added 498 bytes to buf (now 1494 total).
Jul 22 07:33:22:671 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 4608 (0 pending in tls object).
Jul 22 07:33:22:734 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:22:781 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:22:843 [Debug] connection_edge_process_relay_cell(): Now seen 72 relay cells here.
Jul 22 07:33:22:906 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 940.
Jul 22 07:33:22:953 [Debug] write_to_buf(): added 498 bytes to buf (now 1992 total).
Jul 22 07:33:23:015 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 4096 (0 pending in tls object).
Jul 22 07:33:23:078 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:23:140 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:23:187 [Debug] connection_edge_process_relay_cell(): Now seen 73 relay cells here.
Jul 22 07:33:23:250 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 939.
Jul 22 07:33:23:312 [Debug] write_to_buf(): added 498 bytes to buf (now 2490 total).
Jul 22 07:33:23:375 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 3584 (0 pending in tls object).
Jul 22 07:33:23:437 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:23:500 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:23:562 [Debug] connection_edge_process_relay_cell(): Now seen 74 relay cells here.
Jul 22 07:33:23:609 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 938.
Jul 22 07:33:23:671 [Debug] write_to_buf(): added 498 bytes to buf (now 2988 total).
Jul 22 07:33:23:734 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 3072 (0 pending in tls object).
Jul 22 07:33:23:796 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:23:859 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:23:921 [Debug] connection_edge_process_relay_cell(): Now seen 75 relay cells here.
Jul 22 07:33:23:984 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 937.
Jul 22 07:33:24:031 [Debug] write_to_buf(): added 498 bytes to buf (now 3486 total).
Jul 22 07:33:24:093 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 2560 (0 pending in tls object).
Jul 22 07:33:24:156 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:24:218 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:24:281 [Debug] connection_edge_process_relay_cell(): Now seen 76 relay cells here.
Jul 22 07:33:24:328 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 936.
Jul 22 07:33:24:390 [Debug] write_to_buf(): added 498 bytes to buf (now 3984 total).
Jul 22 07:33:24:453 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 2048 (0 pending in tls object).
Jul 22 07:33:24:500 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:24:562 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:24:625 [Debug] connection_edge_process_relay_cell(): Now seen 77 relay cells here.
Jul 22 07:33:24:687 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 935.
Jul 22 07:33:24:750 [Debug] write_to_buf(): added 498 bytes to buf (now 4482 total).
Jul 22 07:33:24:812 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1536 (0 pending in tls object).
Jul 22 07:33:24:875 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:24:921 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:24:984 [Debug] connection_edge_process_relay_cell(): Now seen 78 relay cells here.
Jul 22 07:33:25:046 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 934.
Jul 22 07:33:25:109 [Debug] write_to_buf(): added 498 bytes to buf (now 4980 total).
Jul 22 07:33:25:156 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1024 (0 pending in tls object).
Jul 22 07:33:25:234 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:25:296 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:25:343 [Debug] connection_edge_process_relay_cell(): Now seen 79 relay cells here.
Jul 22 07:33:25:406 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 933.
Jul 22 07:33:25:468 [Debug] write_to_buf(): added 498 bytes to buf (now 5478 total).
Jul 22 07:33:25:531 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 512 (0 pending in tls object).
Jul 22 07:33:25:578 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:25:640 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:25:703 [Debug] connection_edge_process_relay_cell(): Now seen 80 relay cells here.
Jul 22 07:33:25:765 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 932.
Jul 22 07:33:25:828 [Debug] write_to_buf(): added 498 bytes to buf (now 5976 total).
Jul 22 07:33:25:875 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:33:25:937 [Debug] global_read_bucket now 6291456.
Jul 22 07:33:26:000 [Debug] conn_write_callback(): socket 1512 wants to write.
Jul 22 07:33:26:062 [Debug] flush_buf(): 1512: flushed 5976 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:26:140 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:33:26:187 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 16384.
Jul 22 07:33:26:265 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:26:312 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:26:375 [Debug] read_to_buf_tls_impl(): Read 2048 bytes. 2048 on inbuf; 0 pending
Jul 22 07:33:26:437 [Debug] connection_read_to_buf(): After TLS read of 2048: 2122 read, 0 written
Jul 22 07:33:26:500 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 2048 (0 pending in tls object).
Jul 22 07:33:26:546 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:26:609 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:26:671 [Debug] connection_edge_process_relay_cell(): Now seen 81 relay cells here.
Jul 22 07:33:26:734 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 931.
Jul 22 07:33:26:796 [Debug] write_to_buf(): added 319 bytes to buf (now 319 total).
Jul 22 07:33:26:859 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1536 (0 pending in tls object).
Jul 22 07:33:26:906 [Debug] relay_lookup_conn(): found conn for stream 84.
Jul 22 07:33:26:968 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:27:031 [Debug] connection_edge_process_relay_cell(): Now seen 82 relay cells here.
Jul 22 07:33:27:093 [Info] connection_edge_process_relay_cell(): 1500: end cell (closed normally) for stream 84. Removing stream.
Jul 22 07:33:27:156 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1024 (0 pending in tls object).
Jul 22 07:33:27:203 [Debug] relay_lookup_conn(): found conn for stream 83.
Jul 22 07:33:27:265 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:27:328 [Debug] connection_edge_process_relay_cell(): Now seen 83 relay cells here.
Jul 22 07:33:27:390 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 930.
Jul 22 07:33:27:453 [Debug] write_to_buf(): added 247 bytes to buf (now 566 total).
Jul 22 07:33:27:500 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 512 (0 pending in tls object).
Jul 22 07:33:27:562 [Debug] relay_lookup_conn(): found conn for stream 85.
Jul 22 07:33:27:625 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:27:687 [Debug] connection_edge_process_relay_cell(): Now seen 84 relay cells here.
Jul 22 07:33:27:734 [Info] connection_edge_process_relay_cell_not_open(): 'connected' received after 6 seconds.
Jul 22 07:33:27:796 [Info] exit circ (length 3): $445B04E58D03405934CD67937C404F97237BCD6F(open) $30195D6C3D0E1FDC71141816BED2A46D28362BB1(open) wuschelpuschel(open)
Jul 22 07:33:27:859 [Debug] write_to_buf(): added 10 bytes to buf (now 10 total).
Jul 22 07:33:27:921 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 993
Jul 22 07:33:27:968 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:33:28:031 [Debug] conn_close_if_marked(): Cleaning up connection (fd 1500).
Jul 22 07:33:28:093 [Debug] connection_remove(): removing socket 1500 (type Socks), n_conns now 10
Jul 22 07:33:28:156 [Debug] _connection_free(): closing fd 1500.
Jul 22 07:33:28:218 [Debug] conn_write_callback(): socket 1488 wants to write.
Jul 22 07:33:28:281 [Debug] flush_buf(): 1488: flushed 10 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:28:343 [Debug] conn_write_callback(): socket 1512 wants to write.
Jul 22 07:33:28:390 [Debug] flush_buf(): 1512: flushed 566 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:28:453 [Debug] conn_read_callback(): socket 1488 wants to read.
Jul 22 07:33:28:515 [Debug] read_to_buf_impl(): Read 417 bytes. 417 on inbuf.
Jul 22 07:33:28:578 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 993
Jul 22 07:33:28:640 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 993
Jul 22 07:33:28:703 [Debug] connection_edge_package_raw_inbuf(): (1488) Packaging 417 bytes (0 waiting).
Jul 22 07:33:28:750 [Debug] relay_send_command_from_edge(): delivering 2 cell forward.
Jul 22 07:33:28:812 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:28:875 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:28:921 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:28:984 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jul 22 07:33:29:046 [Debug] connection_edge_package_raw_inbuf(): conn->package_window is now 499
Jul 22 07:33:29:109 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 992
Jul 22 07:33:29:156 [Debug] conn_write_callback(): socket 1608 wants to write.
Jul 22 07:33:29:234 [Debug] flush_buf_tls_impl(): flushed 512 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:29:281 [Debug] connection_handle_write(): After TLS write of 512: 0 read, 586 written
Jul 22 07:33:29:343 [Debug] global_read_bucket now 6291456.
Jul 22 07:33:29:406 [Debug] global_write_bucket now 6291456.
Jul 22 07:33:29:484 [Debug] conn_read_callback(): socket 1608 wants to read.
Jul 22 07:33:29:546 [Debug] connection_read_to_buf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object). at_most 16384.
Jul 22 07:33:29:609 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:29:671 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 16384.
Jul 22 07:33:29:734 [Debug] read_to_buf_tls_impl(): Read 2048 bytes. 2048 on inbuf; 0 pending
Jul 22 07:33:29:781 [Debug] connection_read_to_buf(): After TLS read of 2048: 2122 read, 0 written
Jul 22 07:33:29:843 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 2048 (0 pending in tls object).
Jul 22 07:33:29:906 [Debug] relay_lookup_conn(): found conn for stream 85.
Jul 22 07:33:29:968 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:30:015 [Debug] connection_edge_process_relay_cell(): Now seen 85 relay cells here.
Jul 22 07:33:30:078 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 929.
Jul 22 07:33:30:140 [Debug] write_to_buf(): added 498 bytes to buf (now 498 total).
Jul 22 07:33:30:203 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1536 (0 pending in tls object).
Jul 22 07:33:30:265 [Debug] relay_lookup_conn(): found conn for stream 85.
Jul 22 07:33:30:328 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:30:375 [Debug] connection_edge_process_relay_cell(): Now seen 86 relay cells here.
Jul 22 07:33:30:437 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 928.
Jul 22 07:33:30:500 [Debug] write_to_buf(): added 498 bytes to buf (now 996 total).
Jul 22 07:33:30:562 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 1024 (0 pending in tls object).
Jul 22 07:33:30:609 [Debug] relay_lookup_conn(): found conn for stream 85.
Jul 22 07:33:30:671 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:30:734 [Debug] connection_edge_process_relay_cell(): Now seen 87 relay cells here.
Jul 22 07:33:30:796 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 927.
Jul 22 07:33:30:843 [Debug] write_to_buf(): added 498 bytes to buf (now 1494 total).
Jul 22 07:33:30:906 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 512 (0 pending in tls object).
Jul 22 07:33:30:968 [Debug] relay_lookup_conn(): found conn for stream 85.
Jul 22 07:33:31:031 [Debug] circuit_receive_relay_cell(): Sending to origin.
Jul 22 07:33:31:093 [Debug] connection_edge_process_relay_cell(): Now seen 88 relay cells here.
Jul 22 07:33:31:140 [Debug] connection_edge_process_relay_cell(): circ deliver_window now 926.
Jul 22 07:33:31:203 [Debug] write_to_buf(): added 121 bytes to buf (now 1615 total).
Jul 22 07:33:31:265 [Debug] connection_or_process_cells_from_inbuf(): 1608: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:33:31:328 [Debug] global_read_bucket now 6291456.
Jul 22 07:33:31:375 [Debug] conn_write_callback(): socket 1488 wants to write.
Jul 22 07:33:31:437 [Debug] flush_buf(): 1488: flushed 1615 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:31:546 [Debug] conn_read_callback(): socket 1524 wants to read.
Jul 22 07:33:31:609 [Debug] read_to_buf_impl(): Encountered eof
Jul 22 07:33:31:656 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 992
Jul 22 07:33:31:718 [Info] connection_edge_reached_eof(): conn (fd 1524) reached eof. Closing.
Jul 22 07:33:31:781 [Debug] connection_edge_end(): Sending end on conn (fd 1524).
Jul 22 07:33:31:843 [Debug] relay_send_command_from_edge(): delivering 3 cell forward.
Jul 22 07:33:31:906 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:31:968 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:32:015 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:32:078 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jul 22 07:33:32:140 [Debug] conn_close_if_marked(): Cleaning up connection (fd 1524).
Jul 22 07:33:32:187 [Debug] connection_remove(): removing socket 1524 (type Socks), n_conns now 9
Jul 22 07:33:32:265 [Debug] _connection_free(): closing fd 1524.
Jul 22 07:33:32:312 [Debug] conn_write_callback(): socket 1608 wants to write.
Jul 22 07:33:32:375 [Debug] flush_buf_tls_impl(): flushed 512 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:32:437 [Debug] connection_handle_write(): After TLS write of 512: 0 read, 586 written
Jul 22 07:33:32:500 [Debug] global_write_bucket now 6291456.
Jul 22 07:33:32:593 [Debug] conn_read_callback(): socket 1512 wants to read.
Jul 22 07:33:32:656 [Debug] read_to_buf_impl(): Encountered eof
Jul 22 07:33:32:718 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 992
Jul 22 07:33:32:765 [Info] connection_edge_reached_eof(): conn (fd 1512) reached eof. Closing.
Jul 22 07:33:32:828 [Debug] connection_edge_end(): Sending end on conn (fd 1512).
Jul 22 07:33:32:890 [Debug] relay_send_command_from_edge(): delivering 3 cell forward.
Jul 22 07:33:32:953 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:33:015 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:33:078 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:33:125 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jul 22 07:33:33:187 [Debug] conn_close_if_marked(): Cleaning up connection (fd 1512).
Jul 22 07:33:33:250 [Debug] connection_remove(): removing socket 1512 (type Socks), n_conns now 8
Jul 22 07:33:33:312 [Debug] _connection_free(): closing fd 1512.
Jul 22 07:33:33:375 [Debug] conn_read_callback(): socket 1536 wants to read.
Jul 22 07:33:33:437 [Debug] read_to_buf_impl(): Encountered eof
Jul 22 07:33:33:484 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 992
Jul 22 07:33:33:546 [Info] connection_edge_reached_eof(): conn (fd 1536) reached eof. Closing.
Jul 22 07:33:33:625 [Debug] connection_edge_end(): Sending end on conn (fd 1536).
Jul 22 07:33:33:671 [Debug] relay_send_command_from_edge(): delivering 3 cell forward.
Jul 22 07:33:33:734 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:33:796 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:33:843 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:33:906 [Debug] write_to_buf(): added 512 bytes to buf (now 1024 total).
Jul 22 07:33:33:968 [Debug] conn_close_if_marked(): Cleaning up connection (fd 1536).
Jul 22 07:33:34:031 [Debug] connection_remove(): removing socket 1536 (type Socks), n_conns now 7
Jul 22 07:33:34:078 [Debug] _connection_free(): closing fd 1536.
Jul 22 07:33:34:140 [Debug] conn_read_callback(): socket 1488 wants to read.
Jul 22 07:33:34:203 [Debug] read_to_buf_impl(): Encountered eof
Jul 22 07:33:34:265 [Debug] circuit_consider_stop_edge_reading(): considering layer_hint->package_window 992
Jul 22 07:33:34:328 [Info] connection_edge_reached_eof(): conn (fd 1488) reached eof. Closing.
Jul 22 07:33:34:375 [Debug] connection_edge_end(): Sending end on conn (fd 1488).
Jul 22 07:33:34:437 [Debug] relay_send_command_from_edge(): delivering 3 cell forward.
Jul 22 07:33:34:500 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:34:562 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:34:609 [Debug] circuit_package_relay_cell(): crypting a layer of the relay cell.
Jul 22 07:33:34:671 [Debug] write_to_buf(): added 512 bytes to buf (now 1536 total).
Jul 22 07:33:34:734 [Debug] conn_close_if_marked(): Cleaning up connection (fd 1488).
Jul 22 07:33:34:796 [Debug] connection_remove(): removing socket 1488 (type Socks), n_conns now 6
Jul 22 07:33:34:859 [Debug] _connection_free(): closing fd 1488.
Jul 22 07:33:34:906 [Debug] conn_write_callback(): socket 1608 wants to write.
Jul 22 07:33:34:968 [Debug] flush_buf_tls_impl(): flushed 1536 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:35:031 [Debug] connection_handle_write(): After TLS write of 1536: 0 read, 1610 written
Jul 22 07:33:35:093 [Debug] global_write_bucket now 6291456.
Jul 22 07:33:35:312 [Debug] run_connection_housekeeping(): Sending keepalive to (134.130.57.18:9001)
Jul 22 07:33:35:359 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jul 22 07:33:35:421 [Debug] conn_write_callback(): socket 1584 wants to write.
Jul 22 07:33:35:484 [Debug] flush_buf_tls_impl(): flushed 512 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:35:531 [Debug] connection_handle_write(): After TLS write of 512: 0 read, 586 written
Jul 22 07:33:35:593 [Debug] global_write_bucket now 6291456.
Jul 22 07:33:35:671 [Debug] run_connection_housekeeping(): Sending keepalive to (213.239.207.67:9002)
Jul 22 07:33:35:718 [Debug] write_to_buf(): added 512 bytes to buf (now 512 total).
Jul 22 07:33:35:781 [Debug] conn_write_callback(): socket 1548 wants to write.
Jul 22 07:33:35:828 [Debug] flush_buf_tls_impl(): flushed 512 bytes, 0 ready to flush, 0 remain.
Jul 22 07:33:35:890 [Debug] connection_handle_write(): After TLS write of 512: 0 read, 586 written
Jul 22 07:33:35:953 [Debug] global_write_bucket now 6291456.
Jul 22 07:33:36:062 [Debug] conn_read_callback(): socket 1548 wants to read.
Jul 22 07:33:36:109 [Debug] connection_read_to_buf(): 1548: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jul 22 07:33:36:171 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:33:36:234 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:33:36:296 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jul 22 07:33:36:343 [Debug] connection_read_to_buf(): After TLS read of 512: 586 read, 0 written
Jul 22 07:33:36:406 [Debug] connection_or_process_cells_from_inbuf(): 1548: starting, inbuf_datalen 512 (0 pending in tls object).
Jul 22 07:33:36:453 [Debug] connection_or_process_cells_from_inbuf(): 1548: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:33:36:515 [Debug] global_read_bucket now 6291456.
Jul 22 07:33:36:625 [Debug] conn_read_callback(): socket 1584 wants to read.
Jul 22 07:33:36:687 [Debug] connection_read_to_buf(): 1584: starting, inbuf_datalen 0 (0 pending in tls object). at_most 4096.
Jul 22 07:33:36:734 [Debug] read_to_buf_tls(): start: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:33:36:796 [Debug] read_to_buf_tls_impl(): before: 0 on buf, 0 pending, at_most 4096.
Jul 22 07:33:36:859 [Debug] read_to_buf_tls_impl(): Read 512 bytes. 512 on inbuf; 0 pending
Jul 22 07:33:36:906 [Debug] connection_read_to_buf(): After TLS read of 512: 586 read, 0 written
Jul 22 07:33:36:968 [Debug] connection_or_process_cells_from_inbuf(): 1584: starting, inbuf_datalen 512 (0 pending in tls object).
Jul 22 07:33:37:015 [Debug] connection_or_process_cells_from_inbuf(): 1584: starting, inbuf_datalen 0 (0 pending in tls object).
Jul 22 07:33:37:078 [Debug] global_read_bucket now 6291456.