Sudden [Connection reset, restart] loop
Posted: Mon Jan 06, 2014 7:18 pm
I am at wits end, the configuration that used to work till few days
ago all of a sudden keeps resetting and restarting for no apparent
reasons. I could not find anything useful in the logs, would
appreciate if someone takes a look at it.
server.cfg
server.log
Thanks, Igor
ago all of a sudden keeps resetting and restarting for no apparent
reasons. I could not find anything useful in the logs, would
appreciate if someone takes a look at it.
server.cfg
Code: Select all
port 31423
proto tcp
dev tun
ca /home/safe/openvpn2/keys/master.crt
cert /home/safe/openvpn2/keys/server.crt
key /home/safe/openvpn2/keys/server.key
dh /home/safe/openvpn2/keys/diffie.pem
tls-auth /home/safe/openvpn2/keys/ip.key 0
persist-key
persist-tun
user nobody
group nobody
server 10.10.10.0 255.255.255.0
ifconfig-pool-persist /home/safe/openvpn2/ipp.txt
client-to-client
keepalive 10 120
comp-lzo
max-clients 100
status /home/safe/openvpn2/server.log
verb 11
Code: Select all
Mon Jan 6 14:06:24 2014 us=245726 Current Parameter Settings:
Mon Jan 6 14:06:24 2014 us=245908 config = 'server.cfg'
Mon Jan 6 14:06:24 2014 us=245942 mode = 1
Mon Jan 6 14:06:24 2014 us=245969 persist_config = DISABLED
Mon Jan 6 14:06:24 2014 us=245995 persist_mode = 1
Mon Jan 6 14:06:24 2014 us=246020 show_ciphers = DISABLED
Mon Jan 6 14:06:24 2014 us=246045 show_digests = DISABLED
Mon Jan 6 14:06:24 2014 us=246070 show_engines = DISABLED
Mon Jan 6 14:06:24 2014 us=246095 genkey = DISABLED
Mon Jan 6 14:06:24 2014 us=246139 key_pass_file = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=246166 show_tls_ciphers = DISABLED
Mon Jan 6 14:06:24 2014 us=246191 Connection profiles [default]:
Mon Jan 6 14:06:24 2014 us=246218 proto = tcp-server
Mon Jan 6 14:06:24 2014 us=246244 local = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=246270 local_port = 31423
Mon Jan 6 14:06:24 2014 us=246295 remote = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=246320 remote_port = 31423
Mon Jan 6 14:06:24 2014 us=246344 remote_float = DISABLED
Mon Jan 6 14:06:24 2014 us=246369 bind_defined = DISABLED
Mon Jan 6 14:06:24 2014 us=246403 bind_local = ENABLED
Mon Jan 6 14:06:24 2014 us=246430 connect_retry_seconds = 5
Mon Jan 6 14:06:24 2014 us=246455 connect_timeout = 10
Mon Jan 6 14:06:24 2014 us=246480 connect_retry_max = 0
Mon Jan 6 14:06:24 2014 us=246505 socks_proxy_server = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=246529 socks_proxy_port = 0
Mon Jan 6 14:06:24 2014 us=246554 socks_proxy_retry = DISABLED
Mon Jan 6 14:06:24 2014 us=246589 tun_mtu = 1500
Mon Jan 6 14:06:24 2014 us=246616 tun_mtu_defined = ENABLED
Mon Jan 6 14:06:24 2014 us=246665 link_mtu = 1500
Mon Jan 6 14:06:24 2014 us=246692 link_mtu_defined = DISABLED
Mon Jan 6 14:06:24 2014 us=246716 tun_mtu_extra = 0
Mon Jan 6 14:06:24 2014 us=246741 tun_mtu_extra_defined = DISABLED
Mon Jan 6 14:06:24 2014 us=246766 mtu_discover_type = -1
Mon Jan 6 14:06:24 2014 us=246800 fragment = 0
Mon Jan 6 14:06:24 2014 us=246827 mssfix = 1450
Mon Jan 6 14:06:24 2014 us=246852 explicit_exit_notification = 0
Mon Jan 6 14:06:24 2014 us=246882 Connection profiles END
Mon Jan 6 14:06:24 2014 us=246908 remote_random = DISABLED
Mon Jan 6 14:06:24 2014 us=246933 ipchange = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=246957 dev = 'tun'
Mon Jan 6 14:06:24 2014 us=246982 dev_type = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=247006 dev_node = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=247038 lladdr = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=247065 topology = 1
Mon Jan 6 14:06:24 2014 us=247090 tun_ipv6 = DISABLED
Mon Jan 6 14:06:24 2014 us=247114 ifconfig_local = '10.10.10.1'
Mon Jan 6 14:06:24 2014 us=247139 ifconfig_remote_netmask = '10.10.10.2'
Mon Jan 6 14:06:24 2014 us=247164 ifconfig_noexec = DISABLED
Mon Jan 6 14:06:24 2014 us=247188 ifconfig_nowarn = DISABLED
Mon Jan 6 14:06:24 2014 us=247213 ifconfig_ipv6_local = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=247238 ifconfig_ipv6_netbits = 0
Mon Jan 6 14:06:24 2014 us=247263 ifconfig_ipv6_remote = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=247287 shaper = 0
Mon Jan 6 14:06:24 2014 us=247312 mtu_test = 0
Mon Jan 6 14:06:24 2014 us=247336 mlock = DISABLED
Mon Jan 6 14:06:24 2014 us=247361 keepalive_ping = 10
Mon Jan 6 14:06:24 2014 us=247385 keepalive_timeout = 120
Mon Jan 6 14:06:24 2014 us=247409 inactivity_timeout = 0
Mon Jan 6 14:06:24 2014 us=247433 ping_send_timeout = 10
Mon Jan 6 14:06:24 2014 us=247457 ping_rec_timeout = 240
Mon Jan 6 14:06:24 2014 us=247481 ping_rec_timeout_action = 2
Mon Jan 6 14:06:24 2014 us=247505 ping_timer_remote = DISABLED
Mon Jan 6 14:06:24 2014 us=247530 remap_sigusr1 = 0
Mon Jan 6 14:06:24 2014 us=247554 persist_tun = ENABLED
Mon Jan 6 14:06:24 2014 us=247578 persist_local_ip = DISABLED
Mon Jan 6 14:06:24 2014 us=247603 persist_remote_ip = DISABLED
Mon Jan 6 14:06:24 2014 us=247653 persist_key = ENABLED
Mon Jan 6 14:06:24 2014 us=247682 passtos = DISABLED
Mon Jan 6 14:06:24 2014 us=247708 resolve_retry_seconds = 1000000000
Mon Jan 6 14:06:24 2014 us=247733 username = 'nobody'
Mon Jan 6 14:06:24 2014 us=247757 groupname = 'nobody'
Mon Jan 6 14:06:24 2014 us=247782 chroot_dir = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=247806 cd_dir = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=247830 writepid = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=247854 up_script = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=247878 down_script = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=247902 down_pre = DISABLED
Mon Jan 6 14:06:24 2014 us=247927 up_restart = DISABLED
Mon Jan 6 14:06:24 2014 us=247950 up_delay = DISABLED
Mon Jan 6 14:06:24 2014 us=247975 daemon = DISABLED
Mon Jan 6 14:06:24 2014 us=247999 inetd = 0
Mon Jan 6 14:06:24 2014 us=248023 log = DISABLED
Mon Jan 6 14:06:24 2014 us=248047 suppress_timestamps = DISABLED
Mon Jan 6 14:06:24 2014 us=248074 nice = 0
Mon Jan 6 14:06:24 2014 us=248099 verbosity = 11
Mon Jan 6 14:06:24 2014 us=248124 mute = 0
Mon Jan 6 14:06:24 2014 us=248148 gremlin = 0
Mon Jan 6 14:06:24 2014 us=248173 status_file = '/home/safe/openvpn2/server.log'
Mon Jan 6 14:06:24 2014 us=248198 status_file_version = 1
Mon Jan 6 14:06:24 2014 us=248222 status_file_update_freq = 60
Mon Jan 6 14:06:24 2014 us=248247 occ = ENABLED
Mon Jan 6 14:06:24 2014 us=248271 rcvbuf = 65536
Mon Jan 6 14:06:24 2014 us=248295 sndbuf = 65536
Mon Jan 6 14:06:24 2014 us=248319 mark = 0
Mon Jan 6 14:06:24 2014 us=248343 sockflags = 0
Mon Jan 6 14:06:24 2014 us=248367 fast_io = DISABLED
Mon Jan 6 14:06:24 2014 us=248391 lzo = 7
Mon Jan 6 14:06:24 2014 us=248415 route_script = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=248440 route_default_gateway = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=248465 route_default_metric = 0
Mon Jan 6 14:06:24 2014 us=248490 route_noexec = DISABLED
Mon Jan 6 14:06:24 2014 us=248514 route_delay = 0
Mon Jan 6 14:06:24 2014 us=248539 route_delay_window = 30
Mon Jan 6 14:06:24 2014 us=248563 route_delay_defined = DISABLED
Mon Jan 6 14:06:24 2014 us=248588 route_nopull = DISABLED
Mon Jan 6 14:06:24 2014 us=248613 route_gateway_via_dhcp = DISABLED
Mon Jan 6 14:06:24 2014 us=248669 max_routes = 100
Mon Jan 6 14:06:24 2014 us=248697 allow_pull_fqdn = DISABLED
Mon Jan 6 14:06:24 2014 us=248729 route 10.10.10.0/255.255.255.0/nil/nil
Mon Jan 6 14:06:24 2014 us=248756 management_addr = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=248780 management_port = 0
Mon Jan 6 14:06:24 2014 us=248805 management_user_pass = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=248830 management_log_history_cache = 250
Mon Jan 6 14:06:24 2014 us=248855 management_echo_buffer_size = 100
Mon Jan 6 14:06:24 2014 us=248880 management_write_peer_info_file = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=248905 management_client_user = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=248929 management_client_group = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=248954 management_flags = 0
Mon Jan 6 14:06:24 2014 us=248979 shared_secret_file = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=249004 key_direction = 1
Mon Jan 6 14:06:24 2014 us=249028 ciphername_defined = ENABLED
Mon Jan 6 14:06:24 2014 us=249053 ciphername = 'BF-CBC'
Mon Jan 6 14:06:24 2014 us=249078 authname_defined = ENABLED
Mon Jan 6 14:06:24 2014 us=249102 authname = 'SHA1'
Mon Jan 6 14:06:24 2014 us=249126 prng_hash = 'SHA1'
Mon Jan 6 14:06:24 2014 us=249156 prng_nonce_secret_len = 16
Mon Jan 6 14:06:24 2014 us=249182 keysize = 0
Mon Jan 6 14:06:24 2014 us=249206 engine = DISABLED
Mon Jan 6 14:06:24 2014 us=249231 replay = ENABLED
Mon Jan 6 14:06:24 2014 us=249256 mute_replay_warnings = DISABLED
Mon Jan 6 14:06:24 2014 us=249280 replay_window = 64
Mon Jan 6 14:06:24 2014 us=249305 replay_time = 15
Mon Jan 6 14:06:24 2014 us=249329 packet_id_file = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=249354 use_iv = ENABLED
Mon Jan 6 14:06:24 2014 us=249378 test_crypto = DISABLED
Mon Jan 6 14:06:24 2014 us=249403 tls_server = ENABLED
Mon Jan 6 14:06:24 2014 us=249427 tls_client = DISABLED
Mon Jan 6 14:06:24 2014 us=249451 key_method = 2
Mon Jan 6 14:06:24 2014 us=249476 ca_file = '/home/safe/openvpn2/keys/master.crt'
Mon Jan 6 14:06:24 2014 us=249501 ca_path = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=249526 dh_file = '/home/safe/openvpn2/keys/diffie.pem'
Mon Jan 6 14:06:24 2014 us=249551 cert_file = '/home/safe/openvpn2/keys/server.crt'
Mon Jan 6 14:06:24 2014 us=249575 priv_key_file = '/home/safe/openvpn2/keys/server.key'
Mon Jan 6 14:06:24 2014 us=249600 pkcs12_file = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=249642 cipher_list = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=249671 tls_verify = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=249696 tls_export_cert = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=249722 verify_x509_type = 0
Mon Jan 6 14:06:24 2014 us=249747 verify_x509_name = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=249772 crl_file = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=249799 ns_cert_type = 0
Mon Jan 6 14:06:24 2014 us=249824 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=249848 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=249872 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=249896 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=249920 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=249944 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=249968 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=249992 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=250016 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=250040 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=250064 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=250088 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=250112 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=250136 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=250160 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=250184 remote_cert_ku[i] = 0
Mon Jan 6 14:06:24 2014 us=250208 remote_cert_eku = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=250232 ssl_flags = 0
Mon Jan 6 14:06:24 2014 us=250256 tls_timeout = 2
Mon Jan 6 14:06:24 2014 us=250280 renegotiate_bytes = 0
Mon Jan 6 14:06:24 2014 us=250304 renegotiate_packets = 0
Mon Jan 6 14:06:24 2014 us=250328 renegotiate_seconds = 3600
Mon Jan 6 14:06:24 2014 us=250352 handshake_window = 60
Mon Jan 6 14:06:24 2014 us=250377 transition_window = 3600
Mon Jan 6 14:06:24 2014 us=250402 single_session = DISABLED
Mon Jan 6 14:06:24 2014 us=250427 push_peer_info = DISABLED
Mon Jan 6 14:06:24 2014 us=250451 tls_exit = DISABLED
Mon Jan 6 14:06:24 2014 us=250476 tls_auth_file = '/home/safe/openvpn2/keys/ip.key'
Mon Jan 6 14:06:24 2014 us=250501 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250526 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250550 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250574 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250598 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250623 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250676 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250701 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250725 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250750 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250774 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250798 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250823 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250847 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250871 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250895 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:24 2014 us=250926 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=250953 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=250978 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=251003 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=251027 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=251051 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=251076 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=251100 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=251124 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=251149 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=251172 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=251197 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=251222 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=251246 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=251271 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=251297 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:24 2014 us=251322 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251347 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251371 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251395 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251419 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251444 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251468 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251492 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251516 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251540 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251565 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251589 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251613 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251661 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251688 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251712 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:24 2014 us=251737 pkcs11_pin_cache_period = -1
Mon Jan 6 14:06:24 2014 us=251761 pkcs11_id = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=251786 pkcs11_id_management = DISABLED
Mon Jan 6 14:06:24 2014 us=251814 server_network = 10.10.10.0
Mon Jan 6 14:06:24 2014 us=251841 server_netmask = 255.255.255.0
Mon Jan 6 14:06:24 2014 us=251880 server_network_ipv6 = ::
Mon Jan 6 14:06:24 2014 us=251907 server_netbits_ipv6 = 0
Mon Jan 6 14:06:24 2014 us=251934 server_bridge_ip = 0.0.0.0
Mon Jan 6 14:06:24 2014 us=251968 server_bridge_netmask = 0.0.0.0
Mon Jan 6 14:06:24 2014 us=251999 server_bridge_pool_start = 0.0.0.0
Mon Jan 6 14:06:24 2014 us=252026 server_bridge_pool_end = 0.0.0.0
Mon Jan 6 14:06:24 2014 us=252052 push_entry = 'route 10.10.10.0 255.255.255.0'
Mon Jan 6 14:06:24 2014 us=252077 push_entry = 'topology net30'
Mon Jan 6 14:06:24 2014 us=252101 push_entry = 'ping 10'
Mon Jan 6 14:06:24 2014 us=252126 push_entry = 'ping-restart 120'
Mon Jan 6 14:06:24 2014 us=252151 ifconfig_pool_defined = ENABLED
Mon Jan 6 14:06:24 2014 us=252186 ifconfig_pool_start = 10.10.10.4
Mon Jan 6 14:06:24 2014 us=252223 ifconfig_pool_end = 10.10.10.251
Mon Jan 6 14:06:24 2014 us=252254 ifconfig_pool_netmask = 0.0.0.0
Mon Jan 6 14:06:24 2014 us=252287 ifconfig_pool_persist_filename = '/home/safe/openvpn2/ipp.txt'
Mon Jan 6 14:06:24 2014 us=252315 ifconfig_pool_persist_refresh_freq = 600
Mon Jan 6 14:06:24 2014 us=252341 ifconfig_ipv6_pool_defined = DISABLED
Mon Jan 6 14:06:24 2014 us=252369 ifconfig_ipv6_pool_base = ::
Mon Jan 6 14:06:24 2014 us=252394 ifconfig_ipv6_pool_netbits = 0
Mon Jan 6 14:06:24 2014 us=252420 n_bcast_buf = 256
Mon Jan 6 14:06:24 2014 us=252445 tcp_queue_limit = 64
Mon Jan 6 14:06:24 2014 us=252470 real_hash_size = 256
Mon Jan 6 14:06:24 2014 us=252494 virtual_hash_size = 256
Mon Jan 6 14:06:24 2014 us=252519 client_connect_script = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=252544 learn_address_script = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=252569 client_disconnect_script = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=252594 client_config_dir = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=252619 ccd_exclusive = DISABLED
Mon Jan 6 14:06:24 2014 us=252666 tmp_dir = '/tmp'
Mon Jan 6 14:06:24 2014 us=252692 push_ifconfig_defined = DISABLED
Mon Jan 6 14:06:24 2014 us=252720 push_ifconfig_local = 0.0.0.0
Mon Jan 6 14:06:24 2014 us=252748 push_ifconfig_remote_netmask = 0.0.0.0
Mon Jan 6 14:06:24 2014 us=252773 push_ifconfig_ipv6_defined = DISABLED
Mon Jan 6 14:06:24 2014 us=252800 push_ifconfig_ipv6_local = ::/0
Mon Jan 6 14:06:24 2014 us=252827 push_ifconfig_ipv6_remote = ::
Mon Jan 6 14:06:24 2014 us=252852 enable_c2c = ENABLED
Mon Jan 6 14:06:24 2014 us=252877 duplicate_cn = DISABLED
Mon Jan 6 14:06:24 2014 us=252902 cf_max = 0
Mon Jan 6 14:06:24 2014 us=252928 cf_per = 0
Mon Jan 6 14:06:24 2014 us=252953 max_clients = 100
Mon Jan 6 14:06:24 2014 us=252978 max_routes_per_client = 256
Mon Jan 6 14:06:24 2014 us=253003 auth_user_pass_verify_script = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=253029 auth_user_pass_verify_script_via_file = DISABLED
Mon Jan 6 14:06:24 2014 us=253055 port_share_host = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=253080 port_share_port = 0
Mon Jan 6 14:06:24 2014 us=253104 client = DISABLED
Mon Jan 6 14:06:24 2014 us=253128 pull = DISABLED
Mon Jan 6 14:06:24 2014 us=253153 auth_user_pass_file = '[UNDEF]'
Mon Jan 6 14:06:24 2014 us=253187 OpenVPN 2.3.2 x86_64-redhat-linux-gnu [SSL (OpenSSL)] [LZO] [EPOLL] [PKCS11] [eurephia] [MH] [IPv6] built on Sep 12 2013
Mon Jan 6 14:06:24 2014 us=253308 PKCS#11: pkcs11_initialize - entered
Mon Jan 6 14:06:24 2014 us=253430 PKCS#11: pkcs11_initialize - return 0-'CKR_OK'
Mon Jan 6 14:06:24 2014 us=300421 Diffie-Hellman initialized with 2048 bit key
Mon Jan 6 14:06:24 2014 us=302501 PRNG init md=SHA1 size=36
Mon Jan 6 14:06:24 2014 us=303095 Control Channel Authentication: using '/home/safe/openvpn2/keys/ip.key' as a OpenVPN static key file
Mon Jan 6 14:06:24 2014 us=303159 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Mon Jan 6 14:06:24 2014 us=303203 Outgoing Control Channel Authentication: HMAC KEY: 5df2e7a4 612ec135 6d8bcc6b 399d8732 d9cb9a1a
Mon Jan 6 14:06:24 2014 us=303228 Outgoing Control Channel Authentication: HMAC size=20 block_size=20
Mon Jan 6 14:06:24 2014 us=303260 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Mon Jan 6 14:06:24 2014 us=303299 Incoming Control Channel Authentication: HMAC KEY: 72820932 ce6e4a30 b68b7b2b 990707e2 45994b0b
Mon Jan 6 14:06:24 2014 us=303322 Incoming Control Channel Authentication: HMAC size=20 block_size=20
Mon Jan 6 14:06:24 2014 us=303365 MTU DYNAMIC mtu=0, flags=1, 0 -> 168
Mon Jan 6 14:06:24 2014 us=303397 TLS-Auth MTU parms [ L:1544 D:168 EF:68 EB:0 ET:0 EL:0 ]
Mon Jan 6 14:06:24 2014 us=303422 MTU DYNAMIC mtu=1450, flags=2, 1544 -> 1450
Mon Jan 6 14:06:24 2014 us=303475 Socket Buffers: R=[87380->131072] S=[16384->131072]
Mon Jan 6 14:06:24 2014 us=303764 ROUTE_GATEWAY 192.168.11.31/255.255.255.0 IFACE=enp0s25 HWADDR=00:1f:e2:16:23:d2
Mon Jan 6 14:06:24 2014 us=305692 TUN/TAP device tun0 opened
Mon Jan 6 14:06:24 2014 us=305745 TUN/TAP TX queue length set to 100
Mon Jan 6 14:06:24 2014 us=305796 do_ifconfig, tt->ipv6=0, tt->did_ifconfig_ipv6_setup=0
Mon Jan 6 14:06:24 2014 us=305870 /usr/sbin/ip link set dev tun0 up mtu 1500
Mon Jan 6 14:06:24 2014 us=306864 PKCS#11: __pkcs11h_forkFixup entry pid=11648, activate_slotevent=1
Mon Jan 6 14:06:24 2014 us=307039 PKCS#11: __pkcs11h_forkFixup return
Mon Jan 6 14:06:24 2014 us=382604 /usr/sbin/ip addr add dev tun0 local 10.10.10.1 peer 10.10.10.2
Mon Jan 6 14:06:24 2014 us=383460 PKCS#11: __pkcs11h_forkFixup entry pid=11650, activate_slotevent=1
Mon Jan 6 14:06:24 2014 us=383643 PKCS#11: __pkcs11h_forkFixup return
Mon Jan 6 14:06:24 2014 us=385713 /usr/sbin/ip route add 10.10.10.0/24 via 10.10.10.2
Mon Jan 6 14:06:24 2014 us=386324 PKCS#11: __pkcs11h_forkFixup entry pid=11651, activate_slotevent=1
Mon Jan 6 14:06:24 2014 us=386488 PKCS#11: __pkcs11h_forkFixup return
Mon Jan 6 14:06:24 2014 us=388491 Data Channel MTU parms [ L:1544 D:1450 EF:44 EB:135 ET:0 EL:0 AF:3/1 ]
Mon Jan 6 14:06:24 2014 us=391786 GID set to nobody
Mon Jan 6 14:06:24 2014 us=391840 UID set to nobody
Mon Jan 6 14:06:24 2014 us=391884 STREAM: RESET
Mon Jan 6 14:06:24 2014 us=391911 STREAM: INIT maxlen=1544
Mon Jan 6 14:06:24 2014 us=391943 Listening for incoming TCP connection on [undef]
Mon Jan 6 14:06:24 2014 us=391987 TCPv4_SERVER link local (bound): [undef]
Mon Jan 6 14:06:24 2014 us=392015 TCPv4_SERVER link remote: [undef]
Mon Jan 6 14:06:24 2014 us=392057 MULTI: multi_init called, r=256 v=256
Mon Jan 6 14:06:24 2014 us=392127 IFCONFIG POOL: base=10.10.10.4 size=62, ipv6=0
Mon Jan 6 14:06:24 2014 us=392164 ifconfig_pool_read(), in='necla,10.10.10.4', TODO: IPv6
Mon Jan 6 14:06:24 2014 us=392196 succeeded -> ifconfig_pool_set()
Mon Jan 6 14:06:24 2014 us=392222 ifconfig_pool_read(), in='marija,10.10.10.8', TODO: IPv6
Mon Jan 6 14:06:24 2014 us=392248 succeeded -> ifconfig_pool_set()
Mon Jan 6 14:06:24 2014 us=392277 IFCONFIG POOL LIST
Mon Jan 6 14:06:24 2014 us=392306 necla,10.10.10.4
Mon Jan 6 14:06:24 2014 us=392333 marija,10.10.10.8
Mon Jan 6 14:06:24 2014 us=392370 EP_INIT maxevents=104 flags=0x00000000
Mon Jan 6 14:06:24 2014 us=392419 MULTI: TCP INIT maxclients=100 maxevents=104
Mon Jan 6 14:06:24 2014 us=392483 Initialization Sequence Completed
Mon Jan 6 14:06:24 2014 us=392511 SCHEDULE: schedule_find_least NULL
Mon Jan 6 14:06:24 2014 us=392538 EP_CTL fd=5 rwflags=0x0001 ev=0x00000001 arg=0x00000001
Mon Jan 6 14:06:24 2014 us=392568 EP_CTL fd=6 rwflags=0x0001 ev=0x00000001 arg=0x00000002
Mon Jan 6 14:06:34 2014 us=402678 MULTI: REAP range 0 -> 16
Mon Jan 6 14:06:34 2014 us=403463 MULTI TCP: multi_tcp_action a=TA_TIMEOUT p=0
Mon Jan 6 14:06:34 2014 us=403510 MULTI TCP: multi_tcp_dispatch a=TA_TIMEOUT mi=0x00000000
Mon Jan 6 14:06:34 2014 us=403540 MULTI TCP: multi_tcp_post TA_TIMEOUT -> TA_UNDEF
Mon Jan 6 14:06:34 2014 us=403573 SCHEDULE: schedule_find_least NULL
Mon Jan 6 14:06:35 2014 us=30052 EP_WAIT[0] rwflags=0x0001 ev=0x00000001 arg=0x00000001
Mon Jan 6 14:06:35 2014 us=30110 MULTI: REAP range 16 -> 32
Mon Jan 6 14:06:35 2014 us=30141 MULTI: multi_create_instance called
Mon Jan 6 14:06:35 2014 us=30226 PO_INIT maxevents=4 flags=0x00000002
Mon Jan 6 14:06:35 2014 us=30272 Re-using SSL/TLS context
Mon Jan 6 14:06:35 2014 us=30353 LZO compression initialized
Mon Jan 6 14:06:35 2014 us=30384 MTU DYNAMIC mtu=0, flags=1, 0 -> 168
Mon Jan 6 14:06:35 2014 us=30413 TLS: tls_session_init: entry
Mon Jan 6 14:06:35 2014 us=30450 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:35 2014 us=30612 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:35 2014 us=30677 TLS: tls_session_init: new session object, sid=22de714b 96b9c0dd
Mon Jan 6 14:06:35 2014 us=30704 TLS: tls_session_init: entry
Mon Jan 6 14:06:35 2014 us=30734 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:35 2014 us=30848 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:35 2014 us=30883 TLS: tls_session_init: new session object, sid=7aae28b6 f46ebfa1
Mon Jan 6 14:06:35 2014 us=30913 Control Channel MTU parms [ L:1544 D:168 EF:68 EB:0 ET:0 EL:0 ]
Mon Jan 6 14:06:35 2014 us=30964 MTU DYNAMIC mtu=1450, flags=2, 1544 -> 1450
Mon Jan 6 14:06:35 2014 us=30999 Data Channel MTU parms [ L:1544 D:1450 EF:44 EB:135 ET:0 EL:0 AF:3/1 ]
Mon Jan 6 14:06:35 2014 us=31074 Local Options String: 'V4,dev-type tun,link-mtu 1544,tun-mtu 1500,proto TCPv4_SERVER,comp-lzo,keydir 0,cipher BF-CBC,auth SHA1,keysize 128,tls-auth,key-method 2,tls-server'
Mon Jan 6 14:06:35 2014 us=31102 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1544,tun-mtu 1500,proto TCPv4_CLIENT,comp-lzo,keydir 1,cipher BF-CBC,auth SHA1,keysize 128,tls-auth,key-method 2,tls-client'
Mon Jan 6 14:06:35 2014 us=31159 Local Options hash (VER=V4): 'bd577cd1'
Mon Jan 6 14:06:35 2014 us=31202 Expected Remote Options hash (VER=V4): 'ee93268d'
Mon Jan 6 14:06:35 2014 us=31249 STREAM: RESET
Mon Jan 6 14:06:35 2014 us=31275 STREAM: INIT maxlen=1544
Mon Jan 6 14:06:35 2014 us=31319 TCP connection established with [AF_INET]138.15.169.135:50723
Mon Jan 6 14:06:35 2014 us=31351 TCPv4_SERVER link local: [undef]
Mon Jan 6 14:06:35 2014 us=31381 TCPv4_SERVER link remote: [AF_INET]138.15.169.135:50723
Mon Jan 6 14:06:35 2014 us=31450 138.15.169.135:50723 SENT PING
Mon Jan 6 14:06:35 2014 us=31479 138.15.169.135:50723 TIMER: coarse timer wakeup 1 seconds
Mon Jan 6 14:06:35 2014 us=31542 138.15.169.135:50723 TLS: tls_multi_process: i=0 state=S_INITIAL, mysid=22de714b 96b9c0dd, stored-sid=00000000 00000000, stored-ip=[AF_INET]138.15.169.135:50723
Mon Jan 6 14:06:35 2014 us=31580 138.15.169.135:50723 TLS: tls_process: chg=0 ks=S_INITIAL lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 6 14:06:35 2014 us=31606 138.15.169.135:50723 ACK mark active outgoing ID 0
Mon Jan 6 14:06:35 2014 us=31654 138.15.169.135:50723 TLS: Initial Handshake, sid=22de714b 96b9c0dd
Mon Jan 6 14:06:35 2014 us=31684 138.15.169.135:50723 ACK reliable_can_send active=1 current=1 : [1] 0
Mon Jan 6 14:06:35 2014 us=31857 138.15.169.135:50723 SSL state (accept): before/accept initialization
Mon Jan 6 14:06:35 2014 us=31913 138.15.169.135:50723 TLS: tls_process: chg=1 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 6 14:06:35 2014 us=31944 138.15.169.135:50723 ACK reliable_can_send active=1 current=1 : [1] 0
Mon Jan 6 14:06:35 2014 us=31982 138.15.169.135:50723 ACK reliable_send_timeout 0 [1] 0
Mon Jan 6 14:06:35 2014 us=32009 138.15.169.135:50723 TLS: tls_process: timeout set to 1
Mon Jan 6 14:06:35 2014 us=32046 138.15.169.135:50723 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=7aae28b6 f46ebfa1, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:35 2014 us=32082 138.15.169.135:50723 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:35 2014 us=32121 138.15.169.135:50723 RANDOM USEC=255152
Mon Jan 6 14:06:35 2014 us=32165 138.15.169.135:50723 SCHEDULE: schedule_add_modify wakeup=[Mon Jan 6 14:06:36 2014 us=287297] pri=0
Mon Jan 6 14:06:35 2014 us=32194 138.15.169.135:50723 MULTI TCP: instance added: 138.15.169.135:50723
Mon Jan 6 14:06:35 2014 us=32221 138.15.169.135:50723 MULTI TCP: multi_tcp_action a=TA_INITIAL p=0
Mon Jan 6 14:06:35 2014 us=32246 138.15.169.135:50723 MULTI TCP: multi_tcp_dispatch a=TA_INITIAL mi=0x7f3dd2d227f0
Mon Jan 6 14:06:35 2014 us=32273 138.15.169.135:50723 STREAM: SET NEXT, buf=[180,0] next=[180,1544] len=-1 maxlen=1544
Mon Jan 6 14:06:35 2014 us=32298 138.15.169.135:50723 EP_CTL fd=8 rwflags=0x0001 ev=0x00000001 arg=0x7f3dd2d227f0
Mon Jan 6 14:06:35 2014 us=32351 138.15.169.135:50723 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=22de714b 96b9c0dd, stored-sid=00000000 00000000, stored-ip=[AF_INET]138.15.169.135:50723
Mon Jan 6 14:06:35 2014 us=32379 138.15.169.135:50723 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 6 14:06:35 2014 us=32405 138.15.169.135:50723 ACK reliable_can_send active=1 current=1 : [1] 0
Mon Jan 6 14:06:35 2014 us=32441 138.15.169.135:50723 ACK reliable_send_timeout 0 [1] 0
Mon Jan 6 14:06:35 2014 us=32467 138.15.169.135:50723 TLS: tls_process: timeout set to 1
Mon Jan 6 14:06:35 2014 us=32502 138.15.169.135:50723 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=7aae28b6 f46ebfa1, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:35 2014 us=32538 138.15.169.135:50723 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:35 2014 us=32569 138.15.169.135:50723 STREAM: SET NEXT, buf=[180,0] next=[180,1544] len=-1 maxlen=1544
Mon Jan 6 14:06:35 2014 us=32593 138.15.169.135:50723 MULTI TCP: multi_tcp_post TA_INITIAL -> TA_UNDEF
Mon Jan 6 14:06:35 2014 us=32651 138.15.169.135:50723 SCHEDULE: schedule_find_least wakeup=[Mon Jan 6 14:06:36 2014 us=287297] pri=1503059629
Mon Jan 6 14:06:36 2014 us=4588 138.15.169.135:50723 EP_WAIT[0] rwflags=0x0001 ev=0x00000001 arg=0x7f3dd2d227f0
Mon Jan 6 14:06:36 2014 us=4674 138.15.169.135:50723 MULTI: REAP range 32 -> 48
Mon Jan 6 14:06:36 2014 us=4708 138.15.169.135:50723 MULTI TCP: multi_tcp_action a=TA_SOCKET_READ p=0
Mon Jan 6 14:06:36 2014 us=4733 138.15.169.135:50723 MULTI TCP: multi_tcp_dispatch a=TA_SOCKET_READ mi=0x7f3dd2d227f0
Mon Jan 6 14:06:36 2014 us=4817 138.15.169.135:50723 STREAM: GET NEXT len=1544
Mon Jan 6 14:06:36 2014 us=4888 138.15.169.135:50723 STREAM: ADD length_added=44
Mon Jan 6 14:06:36 2014 us=4923 138.15.169.135:50723 STREAM: ADD returned TRUE, buf_len=42, residual_len=0
Mon Jan 6 14:06:36 2014 us=4948 138.15.169.135:50723 STREAM: GET FINAL len=42
Mon Jan 6 14:06:36 2014 us=4979 138.15.169.135:50723 STREAM: RESET
Mon Jan 6 14:06:36 2014 us=5006 138.15.169.135:50723 TCPv4_SERVER read returned 42
Mon Jan 6 14:06:36 2014 us=5095 138.15.169.135:50723 TCPv4_SERVER READ [42] from [AF_INET]138.15.169.135:50723: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 sid=b3c5c37a 60b416cc tls_hmac=5c7f8813 5e3d1a59 e1db4370 023790c4 66d1a130 pid=[ #1 / time = (1389035195) Mon Jan 6 14:06:35 2014 ] [ ] pid=0 DATA
Mon Jan 6 14:06:36 2014 us=5140 138.15.169.135:50723 TLS: control channel, op=P_CONTROL_HARD_RESET_CLIENT_V2, IP=[AF_INET]138.15.169.135:50723
Mon Jan 6 14:06:36 2014 us=5201 138.15.169.135:50723 TLS: initial packet test, i=0 state=S_PRE_START, mysid=22de714b 96b9c0dd, rec-sid=b3c5c37a 60b416cc, rec-ip=[AF_INET]138.15.169.135:50723, stored-sid=00000000 00000000, stored-ip=[AF_INET]138.15.169.135:50723
Mon Jan 6 14:06:36 2014 us=5276 138.15.169.135:50723 TLS: initial packet test, i=1 state=S_INITIAL, mysid=7aae28b6 f46ebfa1, rec-sid=b3c5c37a 60b416cc, rec-ip=[AF_INET]138.15.169.135:50723, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:36 2014 us=5333 138.15.169.135:50723 TLS: initial packet test, i=2 state=S_UNDEF, mysid=00000000 00000000, rec-sid=b3c5c37a 60b416cc, rec-ip=[AF_INET]138.15.169.135:50723, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:36 2014 us=5376 138.15.169.135:50723 TLS: Initial packet from [AF_INET]138.15.169.135:50723, sid=b3c5c37a 60b416cc
Mon Jan 6 14:06:36 2014 us=5431 138.15.169.135:50723 PID_TEST [0] [TLS_AUTH-0] [] 0:0 1389035195:1 t=1389035196[0] r=[0,0,0,0,1]
Mon Jan 6 14:06:36 2014 us=5481 138.15.169.135:50723 TLS: received control channel packet s#=0 sid=b3c5c37a 60b416cc
Mon Jan 6 14:06:36 2014 us=5512 138.15.169.135:50723 ACK reliable_schedule_now
Mon Jan 6 14:06:36 2014 us=5539 138.15.169.135:50723 ACK read ID 0 (buf->len=0)
Mon Jan 6 14:06:36 2014 us=5569 138.15.169.135:50723 ACK RWBS rel->size=8 rel->packet_id=00000000 id=00000000 ret=1
Mon Jan 6 14:06:36 2014 us=5595 138.15.169.135:50723 ACK mark active incoming ID 0
Mon Jan 6 14:06:36 2014 us=5623 138.15.169.135:50723 ACK acknowledge ID 0 (ack->len=1)
Mon Jan 6 14:06:36 2014 us=5683 138.15.169.135:50723 TIMER: coarse timer wakeup 1 seconds
Mon Jan 6 14:06:36 2014 us=5737 138.15.169.135:50723 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=22de714b 96b9c0dd, stored-sid=b3c5c37a 60b416cc, stored-ip=[AF_INET]138.15.169.135:50723
Mon Jan 6 14:06:36 2014 us=5771 138.15.169.135:50723 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 6 14:06:36 2014 us=5799 138.15.169.135:50723 ACK reliable_can_send active=1 current=1 : [1] 0
Mon Jan 6 14:06:36 2014 us=5828 138.15.169.135:50723 ACK reliable_send ID 0 (size=4 to=2)
Mon Jan 6 14:06:36 2014 us=5854 138.15.169.135:50723 ACK write ID 0 (ack->len=1, n=1)
Mon Jan 6 14:06:36 2014 us=5889 138.15.169.135:50723 Reliable -> TCP/UDP
Mon Jan 6 14:06:36 2014 us=5922 138.15.169.135:50723 ACK reliable_send_timeout 2 [1] 0
Mon Jan 6 14:06:36 2014 us=5947 138.15.169.135:50723 TLS: tls_process: timeout set to 2
Mon Jan 6 14:06:36 2014 us=5988 138.15.169.135:50723 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=7aae28b6 f46ebfa1, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:36 2014 us=6030 138.15.169.135:50723 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:36 2014 us=6077 138.15.169.135:50723 SCHEDULE: schedule_add_modify wakeup=[Mon Jan 6 14:06:37 2014 us=287826] pri=1503059629
Mon Jan 6 14:06:36 2014 us=6109 STREAM: SET NEXT, buf=[180,0] next=[180,1544] len=-1 maxlen=1544
Mon Jan 6 14:06:36 2014 us=6134 MULTI TCP: multi_tcp_post TA_SOCKET_READ -> TA_SOCKET_WRITE
Mon Jan 6 14:06:36 2014 us=6164 MULTI TCP: multi_tcp_action a=TA_SOCKET_WRITE p=1
Mon Jan 6 14:06:36 2014 us=6188 MULTI TCP: multi_tcp_wait_lite a=TA_SOCKET_WRITE mi=0x7f3dd2d227f0
Mon Jan 6 14:06:36 2014 us=6218 PO_CTL rwflags=0x0002 ev=8 arg=0x7f3dd2cea150
Mon Jan 6 14:06:36 2014 us=6243 PO_CTL rwflags=0x0001 ev=6 arg=0x7f3dd2cea068
Mon Jan 6 14:06:36 2014 us=6280 I/O WAIT TR|Tw|Sr|SW [0/0]
Mon Jan 6 14:06:36 2014 us=6320 PO_WAIT[0,0] fd=8 rev=0x00000004 rwflags=0x0002 arg=0x7f3dd2cea150
Mon Jan 6 14:06:36 2014 us=6352 event_wait returned 1
Mon Jan 6 14:06:36 2014 us=6376 I/O WAIT status=0x0002
Mon Jan 6 14:06:36 2014 us=6405 MULTI TCP: multi_tcp_dispatch a=TA_SOCKET_WRITE mi=0x7f3dd2d227f0
Mon Jan 6 14:06:36 2014 us=6487 138.15.169.135:50723 TCPv4_SERVER WRITE [54] to [AF_INET]138.15.169.135:50723: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 sid=22de714b 96b9c0dd tls_hmac=e97f838d 55a70abb 0d5e662a dc629fa5 81efa412 pid=[ #1 / time = (1389035196) Mon Jan 6 14:06:36 2014 ] [ 0 sid=b3c5c37a 60b416cc ] pid=0 DATA
Mon Jan 6 14:06:36 2014 us=6517 138.15.169.135:50723 STREAM: WRITE 54 offset=14
Mon Jan 6 14:06:36 2014 us=6573 138.15.169.135:50723 TCPv4_SERVER write returned 56
Mon Jan 6 14:06:36 2014 us=6660 138.15.169.135:50723 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=22de714b 96b9c0dd, stored-sid=b3c5c37a 60b416cc, stored-ip=[AF_INET]138.15.169.135:50723
Mon Jan 6 14:06:36 2014 us=6692 138.15.169.135:50723 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 6 14:06:36 2014 us=6719 138.15.169.135:50723 ACK reliable_can_send active=1 current=0 : [1] 0
Mon Jan 6 14:06:36 2014 us=6747 138.15.169.135:50723 Incoming Ciphertext -> TLS
Mon Jan 6 14:06:36 2014 us=6796 138.15.169.135:50723 TLS: tls_process: chg=1 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 6 14:06:36 2014 us=6830 138.15.169.135:50723 ACK reliable_can_send active=1 current=0 : [1] 0
Mon Jan 6 14:06:36 2014 us=6868 138.15.169.135:50723 ACK reliable_send_timeout 2 [1] 0
Mon Jan 6 14:06:36 2014 us=6900 138.15.169.135:50723 TLS: tls_process: timeout set to 2
Mon Jan 6 14:06:36 2014 us=6937 138.15.169.135:50723 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=7aae28b6 f46ebfa1, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:36 2014 us=6974 138.15.169.135:50723 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:36 2014 us=7005 STREAM: SET NEXT, buf=[180,0] next=[180,1544] len=-1 maxlen=1544
Mon Jan 6 14:06:36 2014 us=7029 MULTI TCP: multi_tcp_post TA_SOCKET_WRITE -> TA_UNDEF
Mon Jan 6 14:06:36 2014 us=7068 SCHEDULE: schedule_find_least wakeup=[Mon Jan 6 14:06:37 2014 us=287826] pri=1900212930
Mon Jan 6 14:06:36 2014 us=24058 EP_WAIT[0] rwflags=0x0001 ev=0x00000019 arg=0x7f3dd2d227f0
Mon Jan 6 14:06:36 2014 us=24107 MULTI TCP: multi_tcp_action a=TA_SOCKET_READ p=0
Mon Jan 6 14:06:36 2014 us=24136 MULTI TCP: multi_tcp_dispatch a=TA_SOCKET_READ mi=0x7f3dd2d227f0
Mon Jan 6 14:06:36 2014 us=24162 138.15.169.135:50723 STREAM: GET NEXT len=1544
Mon Jan 6 14:06:36 2014 us=24190 138.15.169.135:50723 Connection reset, restarting [-1]
Mon Jan 6 14:06:36 2014 us=24217 138.15.169.135:50723 SIGUSR1[soft,connection-reset] received, client-instance restarting
Mon Jan 6 14:06:36 2014 us=24240 MULTI: multi_close_instance called
Mon Jan 6 14:06:36 2014 us=24267 EP_DEL ev=8
Mon Jan 6 14:06:36 2014 us=24322 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=24441 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=24473 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=24497 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=24537 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=24563 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=24587 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=24622 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=24676 TCP/UDP: Closing socket
Mon Jan 6 14:06:36 2014 us=24720 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=24758 MULTI TCP: multi_tcp_post TA_SOCKET_READ -> TA_UNDEF
Mon Jan 6 14:06:36 2014 us=24783 SCHEDULE: schedule_find_least NULL
Mon Jan 6 14:06:41 2014 us=57455 EP_WAIT[0] rwflags=0x0001 ev=0x00000001 arg=0x00000001
Mon Jan 6 14:06:41 2014 us=57520 MULTI: REAP range 48 -> 64
Mon Jan 6 14:06:41 2014 us=57552 MULTI: multi_create_instance called
Mon Jan 6 14:06:41 2014 us=57624 PO_INIT maxevents=4 flags=0x00000002
Mon Jan 6 14:06:41 2014 us=57695 Re-using SSL/TLS context
Mon Jan 6 14:06:41 2014 us=57757 LZO compression initialized
Mon Jan 6 14:06:41 2014 us=57794 MTU DYNAMIC mtu=0, flags=1, 0 -> 168
Mon Jan 6 14:06:41 2014 us=57819 TLS: tls_session_init: entry
Mon Jan 6 14:06:41 2014 us=57864 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:41 2014 us=57975 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:41 2014 us=58017 TLS: tls_session_init: new session object, sid=06001a74 f280598b
Mon Jan 6 14:06:41 2014 us=58090 TLS: tls_session_init: entry
Mon Jan 6 14:06:41 2014 us=58133 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:41 2014 us=58215 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:41 2014 us=58256 TLS: tls_session_init: new session object, sid=540b5da0 cbbd6daf
Mon Jan 6 14:06:41 2014 us=58293 Control Channel MTU parms [ L:1544 D:168 EF:68 EB:0 ET:0 EL:0 ]
Mon Jan 6 14:06:41 2014 us=58336 MTU DYNAMIC mtu=1450, flags=2, 1544 -> 1450
Mon Jan 6 14:06:41 2014 us=58376 Data Channel MTU parms [ L:1544 D:1450 EF:44 EB:135 ET:0 EL:0 AF:3/1 ]
Mon Jan 6 14:06:41 2014 us=58458 Local Options String: 'V4,dev-type tun,link-mtu 1544,tun-mtu 1500,proto TCPv4_SERVER,comp-lzo,keydir 0,cipher BF-CBC,auth SHA1,keysize 128,tls-auth,key-method 2,tls-server'
Mon Jan 6 14:06:41 2014 us=58493 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1544,tun-mtu 1500,proto TCPv4_CLIENT,comp-lzo,keydir 1,cipher BF-CBC,auth SHA1,keysize 128,tls-auth,key-method 2,tls-client'
Mon Jan 6 14:06:41 2014 us=58542 Local Options hash (VER=V4): 'bd577cd1'
Mon Jan 6 14:06:41 2014 us=58590 Expected Remote Options hash (VER=V4): 'ee93268d'
Mon Jan 6 14:06:41 2014 us=58651 STREAM: RESET
Mon Jan 6 14:06:41 2014 us=58685 STREAM: INIT maxlen=1544
Mon Jan 6 14:06:41 2014 us=58737 TCP connection established with [AF_INET]138.15.169.135:50724
Mon Jan 6 14:06:41 2014 us=58774 TCPv4_SERVER link local: [undef]
Mon Jan 6 14:06:41 2014 us=58810 TCPv4_SERVER link remote: [AF_INET]138.15.169.135:50724
Mon Jan 6 14:06:41 2014 us=58858 138.15.169.135:50724 SENT PING
Mon Jan 6 14:06:41 2014 us=58890 138.15.169.135:50724 TIMER: coarse timer wakeup 1 seconds
Mon Jan 6 14:06:41 2014 us=58939 138.15.169.135:50724 TLS: tls_multi_process: i=0 state=S_INITIAL, mysid=06001a74 f280598b, stored-sid=00000000 00000000, stored-ip=[AF_INET]138.15.169.135:50724
Mon Jan 6 14:06:41 2014 us=58975 138.15.169.135:50724 TLS: tls_process: chg=0 ks=S_INITIAL lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 6 14:06:41 2014 us=59008 138.15.169.135:50724 ACK mark active outgoing ID 0
Mon Jan 6 14:06:41 2014 us=59044 138.15.169.135:50724 TLS: Initial Handshake, sid=06001a74 f280598b
Mon Jan 6 14:06:41 2014 us=59078 138.15.169.135:50724 ACK reliable_can_send active=1 current=1 : [1] 0
Mon Jan 6 14:06:41 2014 us=59173 138.15.169.135:50724 SSL state (accept): before/accept initialization
Mon Jan 6 14:06:41 2014 us=59217 138.15.169.135:50724 TLS: tls_process: chg=1 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 6 14:06:41 2014 us=59253 138.15.169.135:50724 ACK reliable_can_send active=1 current=1 : [1] 0
Mon Jan 6 14:06:41 2014 us=59300 138.15.169.135:50724 ACK reliable_send_timeout 0 [1] 0
Mon Jan 6 14:06:41 2014 us=59328 138.15.169.135:50724 TLS: tls_process: timeout set to 1
Mon Jan 6 14:06:41 2014 us=59372 138.15.169.135:50724 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=540b5da0 cbbd6daf, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:41 2014 us=59417 138.15.169.135:50724 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:41 2014 us=59456 138.15.169.135:50724 RANDOM USEC=138473
Mon Jan 6 14:06:41 2014 us=59509 138.15.169.135:50724 SCHEDULE: schedule_add_modify wakeup=[Mon Jan 6 14:06:42 2014 us=197959] pri=0
Mon Jan 6 14:06:41 2014 us=59545 138.15.169.135:50724 MULTI TCP: instance added: 138.15.169.135:50724
Mon Jan 6 14:06:41 2014 us=59578 138.15.169.135:50724 MULTI TCP: multi_tcp_action a=TA_INITIAL p=0
Mon Jan 6 14:06:41 2014 us=59604 138.15.169.135:50724 MULTI TCP: multi_tcp_dispatch a=TA_INITIAL mi=0x7f3dd2d3da70
Mon Jan 6 14:06:41 2014 us=59660 138.15.169.135:50724 STREAM: SET NEXT, buf=[180,0] next=[180,1544] len=-1 maxlen=1544
Mon Jan 6 14:06:41 2014 us=59695 138.15.169.135:50724 EP_CTL fd=8 rwflags=0x0001 ev=0x00000001 arg=0x7f3dd2d3da70
Mon Jan 6 14:06:41 2014 us=59750 138.15.169.135:50724 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=06001a74 f280598b, stored-sid=00000000 00000000, stored-ip=[AF_INET]138.15.169.135:50724
Mon Jan 6 14:06:41 2014 us=59787 138.15.169.135:50724 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 6 14:06:41 2014 us=59814 138.15.169.135:50724 ACK reliable_can_send active=1 current=1 : [1] 0
Mon Jan 6 14:06:41 2014 us=59858 138.15.169.135:50724 ACK reliable_send_timeout 0 [1] 0
Mon Jan 6 14:06:41 2014 us=59890 138.15.169.135:50724 TLS: tls_process: timeout set to 1
Mon Jan 6 14:06:41 2014 us=59929 138.15.169.135:50724 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=540b5da0 cbbd6daf, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:41 2014 us=59971 138.15.169.135:50724 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:41 2014 us=60010 138.15.169.135:50724 STREAM: SET NEXT, buf=[180,0] next=[180,1544] len=-1 maxlen=1544
Mon Jan 6 14:06:41 2014 us=60041 138.15.169.135:50724 MULTI TCP: multi_tcp_post TA_INITIAL -> TA_UNDEF
Mon Jan 6 14:06:41 2014 us=60082 138.15.169.135:50724 SCHEDULE: schedule_find_least wakeup=[Mon Jan 6 14:06:42 2014 us=197959] pri=707713394
Mon Jan 6 14:06:42 2014 us=29909 138.15.169.135:50724 EP_WAIT[0] rwflags=0x0001 ev=0x00000001 arg=0x7f3dd2d3da70
Mon Jan 6 14:06:42 2014 us=29967 138.15.169.135:50724 MULTI: REAP range 64 -> 80
Mon Jan 6 14:06:42 2014 us=29998 138.15.169.135:50724 MULTI TCP: multi_tcp_action a=TA_SOCKET_READ p=0
Mon Jan 6 14:06:42 2014 us=30024 138.15.169.135:50724 MULTI TCP: multi_tcp_dispatch a=TA_SOCKET_READ mi=0x7f3dd2d3da70
Mon Jan 6 14:06:42 2014 us=30050 138.15.169.135:50724 STREAM: GET NEXT len=1544
Mon Jan 6 14:06:42 2014 us=30090 138.15.169.135:50724 STREAM: ADD length_added=44
Mon Jan 6 14:06:42 2014 us=30117 138.15.169.135:50724 STREAM: ADD returned TRUE, buf_len=42, residual_len=0
Mon Jan 6 14:06:42 2014 us=30140 138.15.169.135:50724 STREAM: GET FINAL len=42
Mon Jan 6 14:06:42 2014 us=30163 138.15.169.135:50724 STREAM: RESET
Mon Jan 6 14:06:42 2014 us=30187 138.15.169.135:50724 TCPv4_SERVER read returned 42
Mon Jan 6 14:06:42 2014 us=30286 138.15.169.135:50724 TCPv4_SERVER READ [42] from [AF_INET]138.15.169.135:50724: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 sid=07b4287a fcd0f4af tls_hmac=1d1cb46c 2ad45185 96914e1a 21a7e7ca 3239fd0a pid=[ #1 / time = (1389035202) Mon Jan 6 14:06:42 2014 ] [ ] pid=0 DATA
Mon Jan 6 14:06:42 2014 us=30320 138.15.169.135:50724 TLS: control channel, op=P_CONTROL_HARD_RESET_CLIENT_V2, IP=[AF_INET]138.15.169.135:50724
Mon Jan 6 14:06:42 2014 us=30375 138.15.169.135:50724 TLS: initial packet test, i=0 state=S_PRE_START, mysid=06001a74 f280598b, rec-sid=07b4287a fcd0f4af, rec-ip=[AF_INET]138.15.169.135:50724, stored-sid=00000000 00000000, stored-ip=[AF_INET]138.15.169.135:50724
Mon Jan 6 14:06:42 2014 us=30425 138.15.169.135:50724 TLS: initial packet test, i=1 state=S_INITIAL, mysid=540b5da0 cbbd6daf, rec-sid=07b4287a fcd0f4af, rec-ip=[AF_INET]138.15.169.135:50724, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:42 2014 us=30474 138.15.169.135:50724 TLS: initial packet test, i=2 state=S_UNDEF, mysid=00000000 00000000, rec-sid=07b4287a fcd0f4af, rec-ip=[AF_INET]138.15.169.135:50724, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:42 2014 us=30511 138.15.169.135:50724 TLS: Initial packet from [AF_INET]138.15.169.135:50724, sid=07b4287a fcd0f4af
Mon Jan 6 14:06:42 2014 us=30559 138.15.169.135:50724 PID_TEST [0] [TLS_AUTH-0] [] 0:0 1389035202:1 t=1389035202[0] r=[0,0,0,0,1]
Mon Jan 6 14:06:42 2014 us=30593 138.15.169.135:50724 TLS: received control channel packet s#=0 sid=07b4287a fcd0f4af
Mon Jan 6 14:06:42 2014 us=30616 138.15.169.135:50724 ACK reliable_schedule_now
Mon Jan 6 14:06:42 2014 us=30676 138.15.169.135:50724 ACK read ID 0 (buf->len=0)
Mon Jan 6 14:06:42 2014 us=30704 138.15.169.135:50724 ACK RWBS rel->size=8 rel->packet_id=00000000 id=00000000 ret=1
Mon Jan 6 14:06:42 2014 us=30728 138.15.169.135:50724 ACK mark active incoming ID 0
Mon Jan 6 14:06:42 2014 us=30753 138.15.169.135:50724 ACK acknowledge ID 0 (ack->len=1)
Mon Jan 6 14:06:42 2014 us=30788 138.15.169.135:50724 TIMER: coarse timer wakeup 1 seconds
Mon Jan 6 14:06:42 2014 us=30837 138.15.169.135:50724 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=06001a74 f280598b, stored-sid=07b4287a fcd0f4af, stored-ip=[AF_INET]138.15.169.135:50724
Mon Jan 6 14:06:42 2014 us=30866 138.15.169.135:50724 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 6 14:06:42 2014 us=30893 138.15.169.135:50724 ACK reliable_can_send active=1 current=1 : [1] 0
Mon Jan 6 14:06:42 2014 us=30919 138.15.169.135:50724 ACK reliable_send ID 0 (size=4 to=2)
Mon Jan 6 14:06:42 2014 us=30955 138.15.169.135:50724 ACK write ID 0 (ack->len=1, n=1)
Mon Jan 6 14:06:42 2014 us=30989 138.15.169.135:50724 Reliable -> TCP/UDP
Mon Jan 6 14:06:42 2014 us=31017 138.15.169.135:50724 ACK reliable_send_timeout 2 [1] 0
Mon Jan 6 14:06:42 2014 us=31042 138.15.169.135:50724 TLS: tls_process: timeout set to 2
Mon Jan 6 14:06:42 2014 us=31079 138.15.169.135:50724 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=540b5da0 cbbd6daf, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:42 2014 us=31117 138.15.169.135:50724 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:42 2014 us=31165 138.15.169.135:50724 SCHEDULE: schedule_add_modify wakeup=[Mon Jan 6 14:06:43 2014 us=198581] pri=707713394
Mon Jan 6 14:06:42 2014 us=31192 STREAM: SET NEXT, buf=[180,0] next=[180,1544] len=-1 maxlen=1544
Mon Jan 6 14:06:42 2014 us=31218 MULTI TCP: multi_tcp_post TA_SOCKET_READ -> TA_SOCKET_WRITE
Mon Jan 6 14:06:42 2014 us=31241 MULTI TCP: multi_tcp_action a=TA_SOCKET_WRITE p=1
Mon Jan 6 14:06:42 2014 us=31265 MULTI TCP: multi_tcp_wait_lite a=TA_SOCKET_WRITE mi=0x7f3dd2d3da70
Mon Jan 6 14:06:42 2014 us=31291 PO_CTL rwflags=0x0002 ev=8 arg=0x7f3dd2cea150
Mon Jan 6 14:06:42 2014 us=31315 PO_CTL rwflags=0x0001 ev=6 arg=0x7f3dd2cea068
Mon Jan 6 14:06:42 2014 us=31347 I/O WAIT TR|Tw|Sr|SW [0/0]
Mon Jan 6 14:06:42 2014 us=31380 PO_WAIT[0,0] fd=8 rev=0x00000004 rwflags=0x0002 arg=0x7f3dd2cea150
Mon Jan 6 14:06:42 2014 us=31405 event_wait returned 1
Mon Jan 6 14:06:42 2014 us=31429 I/O WAIT status=0x0002
Mon Jan 6 14:06:42 2014 us=31452 MULTI TCP: multi_tcp_dispatch a=TA_SOCKET_WRITE mi=0x7f3dd2d3da70
Mon Jan 6 14:06:42 2014 us=31535 138.15.169.135:50724 TCPv4_SERVER WRITE [54] to [AF_INET]138.15.169.135:50724: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 sid=06001a74 f280598b tls_hmac=8b7a7f9d d6027cbc 7bfaa596 96913f46 77a79a75 pid=[ #1 / time = (1389035202) Mon Jan 6 14:06:42 2014 ] [ 0 sid=07b4287a fcd0f4af ] pid=0 DATA
Mon Jan 6 14:06:42 2014 us=31561 138.15.169.135:50724 STREAM: WRITE 54 offset=14
Mon Jan 6 14:06:42 2014 us=31612 138.15.169.135:50724 TCPv4_SERVER write returned 56
Mon Jan 6 14:06:42 2014 us=31694 138.15.169.135:50724 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=06001a74 f280598b, stored-sid=07b4287a fcd0f4af, stored-ip=[AF_INET]138.15.169.135:50724
Mon Jan 6 14:06:42 2014 us=31725 138.15.169.135:50724 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 6 14:06:42 2014 us=31752 138.15.169.135:50724 ACK reliable_can_send active=1 current=0 : [1] 0
Mon Jan 6 14:06:42 2014 us=31776 138.15.169.135:50724 Incoming Ciphertext -> TLS
Mon Jan 6 14:06:42 2014 us=31822 138.15.169.135:50724 TLS: tls_process: chg=1 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 6 14:06:42 2014 us=31852 138.15.169.135:50724 ACK reliable_can_send active=1 current=0 : [1] 0
Mon Jan 6 14:06:42 2014 us=31889 138.15.169.135:50724 ACK reliable_send_timeout 2 [1] 0
Mon Jan 6 14:06:42 2014 us=31915 138.15.169.135:50724 TLS: tls_process: timeout set to 2
Mon Jan 6 14:06:42 2014 us=31952 138.15.169.135:50724 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=540b5da0 cbbd6daf, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:42 2014 us=31989 138.15.169.135:50724 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:42 2014 us=32021 STREAM: SET NEXT, buf=[180,0] next=[180,1544] len=-1 maxlen=1544
Mon Jan 6 14:06:42 2014 us=32046 MULTI TCP: multi_tcp_post TA_SOCKET_WRITE -> TA_UNDEF
Mon Jan 6 14:06:42 2014 us=32086 SCHEDULE: schedule_find_least wakeup=[Mon Jan 6 14:06:43 2014 us=198581] pri=1107735140
Mon Jan 6 14:06:42 2014 us=53346 EP_WAIT[0] rwflags=0x0001 ev=0x00000019 arg=0x7f3dd2d3da70
Mon Jan 6 14:06:42 2014 us=53392 MULTI TCP: multi_tcp_action a=TA_SOCKET_READ p=0
Mon Jan 6 14:06:42 2014 us=53419 MULTI TCP: multi_tcp_dispatch a=TA_SOCKET_READ mi=0x7f3dd2d3da70
Mon Jan 6 14:06:42 2014 us=53444 138.15.169.135:50724 STREAM: GET NEXT len=1544
Mon Jan 6 14:06:42 2014 us=53571 138.15.169.135:50724 Connection reset, restarting [-1]
Mon Jan 6 14:06:42 2014 us=53675 138.15.169.135:50724 SIGUSR1[soft,connection-reset] received, client-instance restarting
Mon Jan 6 14:06:42 2014 us=53783 MULTI: multi_close_instance called
Mon Jan 6 14:06:42 2014 us=53874 EP_DEL ev=8
Mon Jan 6 14:06:42 2014 us=53961 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=54082 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=54171 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=54248 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=54379 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=54466 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=54539 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=54650 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=54739 TCP/UDP: Closing socket
Mon Jan 6 14:06:42 2014 us=54847 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=54937 MULTI TCP: multi_tcp_post TA_SOCKET_READ -> TA_UNDEF
Mon Jan 6 14:06:42 2014 us=54974 SCHEDULE: schedule_find_least NULL