Sudden [Connection reset, restart] loop

igord

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

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

server.log

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

Thanks, Igor

igord

This is the client side config/log

client.cfg

client
dev tun
proto tcp
remote shrimp.homeip.net 31423

resolv-retry infinite
nobind

persist-key
persist-tun

user  nobody
group nobody

ca   /safe/safe/txt2/keys/master.crt
cert /safe/safe/txt2/keys/necla.crt
key  /safe/safe/txt2/keys/necla.key
tls-auth /safe/safe/txt2/keys/ip.key 1

comp-lzo
verb 11

client.log

Mon Jan 6 14:06:34 2014 us=990969 Current Parameter Settings:
Mon Jan 6 14:06:34 2014 us=991037 config = '/safe/safe/txt2/necla.cfg'
Mon Jan 6 14:06:34 2014 us=991051 mode = 0
Mon Jan 6 14:06:34 2014 us=991072 persist_config = DISABLED
Mon Jan 6 14:06:34 2014 us=991082 persist_mode = 1
Mon Jan 6 14:06:34 2014 us=991091 show_ciphers = DISABLED
Mon Jan 6 14:06:34 2014 us=991100 show_digests = DISABLED
Mon Jan 6 14:06:34 2014 us=991109 show_engines = DISABLED
Mon Jan 6 14:06:34 2014 us=991117 genkey = DISABLED
Mon Jan 6 14:06:34 2014 us=991125 key_pass_file = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991134 show_tls_ciphers = DISABLED
Mon Jan 6 14:06:34 2014 us=991142 Connection profiles [default]:
Mon Jan 6 14:06:34 2014 us=991151 proto = tcp-client
Mon Jan 6 14:06:34 2014 us=991159 local = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991167 local_port = 0
Mon Jan 6 14:06:34 2014 us=991175 remote = 'shrimp.homeip.net'
Mon Jan 6 14:06:34 2014 us=991184 remote_port = 31423
Mon Jan 6 14:06:34 2014 us=991192 remote_float = DISABLED
Mon Jan 6 14:06:34 2014 us=991200 bind_defined = DISABLED
Mon Jan 6 14:06:34 2014 us=991208 bind_local = DISABLED
Mon Jan 6 14:06:34 2014 us=991217 connect_retry_seconds = 5
Mon Jan 6 14:06:34 2014 us=991225 connect_timeout = 10
Mon Jan 6 14:06:34 2014 us=991233 connect_retry_max = 0
Mon Jan 6 14:06:34 2014 us=991242 socks_proxy_server = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991250 socks_proxy_port = 0
Mon Jan 6 14:06:34 2014 us=991259 socks_proxy_retry = DISABLED
Mon Jan 6 14:06:34 2014 us=991267 tun_mtu = 1500
Mon Jan 6 14:06:34 2014 us=991275 tun_mtu_defined = ENABLED
Mon Jan 6 14:06:34 2014 us=991283 link_mtu = 1500
Mon Jan 6 14:06:34 2014 us=991292 link_mtu_defined = DISABLED
Mon Jan 6 14:06:34 2014 us=991300 tun_mtu_extra = 0
Mon Jan 6 14:06:34 2014 us=991308 tun_mtu_extra_defined = DISABLED
Mon Jan 6 14:06:34 2014 us=991316 mtu_discover_type = -1
Mon Jan 6 14:06:34 2014 us=991324 fragment = 0
Mon Jan 6 14:06:34 2014 us=991333 mssfix = 1450
Mon Jan 6 14:06:34 2014 us=991341 explicit_exit_notification = 0
Mon Jan 6 14:06:34 2014 us=991351 Connection profiles END
Mon Jan 6 14:06:34 2014 us=991360 remote_random = DISABLED
Mon Jan 6 14:06:34 2014 us=991368 ipchange = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991379 dev = 'tun'
Mon Jan 6 14:06:34 2014 us=991388 dev_type = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991396 dev_node = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991404 lladdr = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991412 topology = 1
Mon Jan 6 14:06:34 2014 us=991421 tun_ipv6 = DISABLED
Mon Jan 6 14:06:34 2014 us=991429 ifconfig_local = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991437 ifconfig_remote_netmask = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991445 ifconfig_noexec = DISABLED
Mon Jan 6 14:06:34 2014 us=991453 ifconfig_nowarn = DISABLED
Mon Jan 6 14:06:34 2014 us=991462 ifconfig_ipv6_local = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991472 ifconfig_ipv6_netbits = 0
Mon Jan 6 14:06:34 2014 us=991480 ifconfig_ipv6_remote = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991489 shaper = 0
Mon Jan 6 14:06:34 2014 us=991497 mtu_test = 0
Mon Jan 6 14:06:34 2014 us=991505 mlock = DISABLED
Mon Jan 6 14:06:34 2014 us=991513 keepalive_ping = 0
Mon Jan 6 14:06:34 2014 us=991521 keepalive_timeout = 0
Mon Jan 6 14:06:34 2014 us=991529 inactivity_timeout = 0
Mon Jan 6 14:06:34 2014 us=991537 ping_send_timeout = 0
Mon Jan 6 14:06:34 2014 us=991545 ping_rec_timeout = 0
Mon Jan 6 14:06:34 2014 us=991554 ping_rec_timeout_action = 0
Mon Jan 6 14:06:34 2014 us=991562 ping_timer_remote = DISABLED
Mon Jan 6 14:06:34 2014 us=991570 remap_sigusr1 = 0
Mon Jan 6 14:06:34 2014 us=991578 persist_tun = ENABLED
Mon Jan 6 14:06:34 2014 us=991586 persist_local_ip = DISABLED
Mon Jan 6 14:06:34 2014 us=991594 persist_remote_ip = DISABLED
Mon Jan 6 14:06:34 2014 us=991602 persist_key = ENABLED
Mon Jan 6 14:06:34 2014 us=991610 passtos = DISABLED
Mon Jan 6 14:06:34 2014 us=991618 resolve_retry_seconds = 1000000000
Mon Jan 6 14:06:34 2014 us=991627 username = 'nobody'
Mon Jan 6 14:06:34 2014 us=991635 groupname = 'nobody'
Mon Jan 6 14:06:34 2014 us=991644 chroot_dir = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991652 cd_dir = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991660 writepid = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991668 up_script = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991676 down_script = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991684 down_pre = DISABLED
Mon Jan 6 14:06:34 2014 us=991692 up_restart = DISABLED
Mon Jan 6 14:06:34 2014 us=991700 up_delay = DISABLED
Mon Jan 6 14:06:34 2014 us=991708 daemon = DISABLED
Mon Jan 6 14:06:34 2014 us=991715 inetd = 0
Mon Jan 6 14:06:34 2014 us=991723 log = DISABLED
Mon Jan 6 14:06:34 2014 us=991732 suppress_timestamps = DISABLED
Mon Jan 6 14:06:34 2014 us=991740 nice = 0
Mon Jan 6 14:06:34 2014 us=991748 verbosity = 11
Mon Jan 6 14:06:34 2014 us=991756 mute = 0
Mon Jan 6 14:06:34 2014 us=991764 gremlin = 0
Mon Jan 6 14:06:34 2014 us=991772 status_file = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991780 status_file_version = 1
Mon Jan 6 14:06:34 2014 us=991788 status_file_update_freq = 60
Mon Jan 6 14:06:34 2014 us=991796 occ = ENABLED
Mon Jan 6 14:06:34 2014 us=991804 rcvbuf = 65536
Mon Jan 6 14:06:34 2014 us=991813 sndbuf = 65536
Mon Jan 6 14:06:34 2014 us=991821 mark = 0
Mon Jan 6 14:06:34 2014 us=991829 sockflags = 0
Mon Jan 6 14:06:34 2014 us=991837 fast_io = DISABLED
Mon Jan 6 14:06:34 2014 us=991845 lzo = 7
Mon Jan 6 14:06:34 2014 us=991853 route_script = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991861 route_default_gateway = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991869 route_default_metric = 0
Mon Jan 6 14:06:34 2014 us=991878 route_noexec = DISABLED
Mon Jan 6 14:06:34 2014 us=991886 route_delay = 0
Mon Jan 6 14:06:34 2014 us=991895 route_delay_window = 30
Mon Jan 6 14:06:34 2014 us=991903 route_delay_defined = DISABLED
Mon Jan 6 14:06:34 2014 us=991911 route_nopull = DISABLED
Mon Jan 6 14:06:34 2014 us=991919 route_gateway_via_dhcp = DISABLED
Mon Jan 6 14:06:34 2014 us=991928 max_routes = 100
Mon Jan 6 14:06:34 2014 us=991936 allow_pull_fqdn = DISABLED
Mon Jan 6 14:06:34 2014 us=991944 management_addr = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991952 management_port = 0
Mon Jan 6 14:06:34 2014 us=991961 management_user_pass = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991969 management_log_history_cache = 250
Mon Jan 6 14:06:34 2014 us=991977 management_echo_buffer_size = 100
Mon Jan 6 14:06:34 2014 us=991986 management_write_peer_info_file = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=991994 management_client_user = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=992002 management_client_group = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=992010 management_flags = 0
Mon Jan 6 14:06:34 2014 us=992019 shared_secret_file = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=992027 key_direction = 2
Mon Jan 6 14:06:34 2014 us=992035 ciphername_defined = ENABLED
Mon Jan 6 14:06:34 2014 us=992043 ciphername = 'BF-CBC'
Mon Jan 6 14:06:34 2014 us=992052 authname_defined = ENABLED
Mon Jan 6 14:06:34 2014 us=992066 authname = 'SHA1'
Mon Jan 6 14:06:34 2014 us=992075 prng_hash = 'SHA1'
Mon Jan 6 14:06:34 2014 us=992083 prng_nonce_secret_len = 16
Mon Jan 6 14:06:34 2014 us=992092 keysize = 0
Mon Jan 6 14:06:34 2014 us=992100 engine = DISABLED
Mon Jan 6 14:06:34 2014 us=992108 replay = ENABLED
Mon Jan 6 14:06:34 2014 us=992116 mute_replay_warnings = DISABLED
Mon Jan 6 14:06:34 2014 us=992125 replay_window = 64
Mon Jan 6 14:06:34 2014 us=992133 replay_time = 15
Mon Jan 6 14:06:34 2014 us=992141 packet_id_file = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=992150 use_iv = ENABLED
Mon Jan 6 14:06:34 2014 us=992158 test_crypto = DISABLED
Mon Jan 6 14:06:34 2014 us=992167 tls_server = DISABLED
Mon Jan 6 14:06:34 2014 us=992175 tls_client = ENABLED
Mon Jan 6 14:06:34 2014 us=992183 key_method = 2
Mon Jan 6 14:06:34 2014 us=992192 ca_file = '/safe/safe/txt2/keys/master.crt'
Mon Jan 6 14:06:34 2014 us=992201 ca_path = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=992209 dh_file = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=992218 cert_file = '/safe/safe/txt2/keys/necla.crt'
Mon Jan 6 14:06:34 2014 us=992226 priv_key_file = '/safe/safe/txt2/keys/necla.key'
Mon Jan 6 14:06:34 2014 us=992235 pkcs12_file = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=992243 cipher_list = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=992251 tls_verify = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=992259 tls_export_cert = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=992268 verify_x509_type = 0
Mon Jan 6 14:06:34 2014 us=992276 verify_x509_name = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=992284 crl_file = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=992293 ns_cert_type = 0
Mon Jan 6 14:06:34 2014 us=992301 remote_cert_ku = 0
Mon Jan 6 14:06:34 2014 us=992309 remote_cert_ku = 0
Mon Jan 6 14:06:34 2014 us=992316 remote_cert_ku = 0
Mon Jan 6 14:06:34 2014 us=992324 remote_cert_ku = 0
Mon Jan 6 14:06:34 2014 us=992332 remote_cert_ku = 0
Mon Jan 6 14:06:34 2014 us=992340 remote_cert_ku = 0
Mon Jan 6 14:06:34 2014 us=992348 remote_cert_ku = 0
Mon Jan 6 14:06:34 2014 us=992356 remote_cert_ku = 0
Mon Jan 6 14:06:34 2014 us=992363 remote_cert_ku = 0
Mon Jan 6 14:06:34 2014 us=992371 remote_cert_ku = 0
Mon Jan 6 14:06:34 2014 us=992379 remote_cert_ku[i] = 0
Mon Jan 6 14:06:34 2014 us=992387 remote_cert_ku[i] = 0
Mon Jan 6 14:06:34 2014 us=992394 remote_cert_ku[i] = 0
Mon Jan 6 14:06:34 2014 us=992402 remote_cert_ku[i] = 0
Mon Jan 6 14:06:34 2014 us=992410 remote_cert_ku[i] = 0
Mon Jan 6 14:06:34 2014 us=992418 remote_cert_ku[i] = 0
Mon Jan 6 14:06:34 2014 us=992426 remote_cert_eku = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=992434 ssl_flags = 0
Mon Jan 6 14:06:34 2014 us=992442 tls_timeout = 2
Mon Jan 6 14:06:34 2014 us=992450 renegotiate_bytes = 0
Mon Jan 6 14:06:34 2014 us=992458 renegotiate_packets = 0
Mon Jan 6 14:06:34 2014 us=992466 renegotiate_seconds = 3600
Mon Jan 6 14:06:34 2014 us=992474 handshake_window = 60
Mon Jan 6 14:06:34 2014 us=992482 transition_window = 3600
Mon Jan 6 14:06:34 2014 us=992491 single_session = DISABLED
Mon Jan 6 14:06:34 2014 us=992499 push_peer_info = DISABLED
Mon Jan 6 14:06:34 2014 us=992507 tls_exit = DISABLED
Mon Jan 6 14:06:34 2014 us=992516 tls_auth_file = '/safe/safe/txt2/keys/ip.key'
Mon Jan 6 14:06:34 2014 us=992524 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992533 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992541 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992549 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992557 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992566 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992574 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992582 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992590 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992598 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992606 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992615 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992623 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992631 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992639 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992647 pkcs11_protected_authentication = DISABLED
Mon Jan 6 14:06:34 2014 us=992656 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992664 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992673 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992681 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992689 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992698 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992706 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992715 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992723 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992731 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992739 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992747 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992755 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992763 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992771 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992780 pkcs11_private_mode = 00000000
Mon Jan 6 14:06:34 2014 us=992788 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992796 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992804 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992812 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992820 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992828 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992836 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992844 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992852 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992860 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992868 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992876 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992884 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992892 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992900 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992908 pkcs11_cert_private = DISABLED
Mon Jan 6 14:06:34 2014 us=992917 pkcs11_pin_cache_period = -1
Mon Jan 6 14:06:34 2014 us=992925 pkcs11_id = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=992933 pkcs11_id_management = DISABLED
Mon Jan 6 14:06:34 2014 us=992947 server_network = 0.0.0.0
Mon Jan 6 14:06:34 2014 us=992957 server_netmask = 0.0.0.0
Mon Jan 6 14:06:34 2014 us=992972 server_network_ipv6 = ::
Mon Jan 6 14:06:34 2014 us=992981 server_netbits_ipv6 = 0
Mon Jan 6 14:06:34 2014 us=992990 server_bridge_ip = 0.0.0.0
Mon Jan 6 14:06:34 2014 us=993002 server_bridge_netmask = 0.0.0.0
Mon Jan 6 14:06:34 2014 us=993012 server_bridge_pool_start = 0.0.0.0
Mon Jan 6 14:06:34 2014 us=993022 server_bridge_pool_end = 0.0.0.0
Mon Jan 6 14:06:34 2014 us=993030 ifconfig_pool_defined = DISABLED
Mon Jan 6 14:06:34 2014 us=993040 ifconfig_pool_start = 0.0.0.0
Mon Jan 6 14:06:34 2014 us=993049 ifconfig_pool_end = 0.0.0.0
Mon Jan 6 14:06:34 2014 us=993063 ifconfig_pool_netmask = 0.0.0.0
Mon Jan 6 14:06:34 2014 us=993073 ifconfig_pool_persist_filename = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=993081 ifconfig_pool_persist_refresh_freq = 600
Mon Jan 6 14:06:34 2014 us=993090 ifconfig_ipv6_pool_defined = DISABLED
Mon Jan 6 14:06:34 2014 us=993099 ifconfig_ipv6_pool_base = ::
Mon Jan 6 14:06:34 2014 us=993108 ifconfig_ipv6_pool_netbits = 0
Mon Jan 6 14:06:34 2014 us=993116 n_bcast_buf = 256
Mon Jan 6 14:06:34 2014 us=993124 tcp_queue_limit = 64
Mon Jan 6 14:06:34 2014 us=993132 real_hash_size = 256
Mon Jan 6 14:06:34 2014 us=993141 virtual_hash_size = 256
Mon Jan 6 14:06:34 2014 us=993149 client_connect_script = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=993157 learn_address_script = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=993166 client_disconnect_script = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=993174 client_config_dir = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=993182 ccd_exclusive = DISABLED
Mon Jan 6 14:06:34 2014 us=993191 tmp_dir = '/tmp'
Mon Jan 6 14:06:34 2014 us=993199 push_ifconfig_defined = DISABLED
Mon Jan 6 14:06:34 2014 us=993208 push_ifconfig_local = 0.0.0.0
Mon Jan 6 14:06:34 2014 us=993217 push_ifconfig_remote_netmask = 0.0.0.0
Mon Jan 6 14:06:34 2014 us=993226 push_ifconfig_ipv6_defined = DISABLED
Mon Jan 6 14:06:34 2014 us=993236 push_ifconfig_ipv6_local = ::/0
Mon Jan 6 14:06:34 2014 us=993245 push_ifconfig_ipv6_remote = ::
Mon Jan 6 14:06:34 2014 us=993253 enable_c2c = DISABLED
Mon Jan 6 14:06:34 2014 us=993262 duplicate_cn = DISABLED
Mon Jan 6 14:06:34 2014 us=993270 cf_max = 0
Mon Jan 6 14:06:34 2014 us=993278 cf_per = 0
Mon Jan 6 14:06:34 2014 us=993286 max_clients = 1024
Mon Jan 6 14:06:34 2014 us=993295 max_routes_per_client = 256
Mon Jan 6 14:06:34 2014 us=993303 auth_user_pass_verify_script = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=993312 auth_user_pass_verify_script_via_file = DISABLED
Mon Jan 6 14:06:34 2014 us=993320 port_share_host = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=993328 port_share_port = 0
Mon Jan 6 14:06:34 2014 us=993336 client = ENABLED
Mon Jan 6 14:06:34 2014 us=993344 pull = ENABLED
Mon Jan 6 14:06:34 2014 us=993352 auth_user_pass_file = '[UNDEF]'
Mon Jan 6 14:06:34 2014 us=993364 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:34 2014 us=993417 PKCS#11: pkcs11_initialize - entered
Mon Jan 6 14:06:34 2014 us=993470 PKCS#11: pkcs11_initialize - return 0-'CKR_OK'
Mon Jan 6 14:06:34 2014 us=993488 WARNING: No server certificate verification method has been enabled. See http://openvpn.net/howto.html#mitm for more info.
Mon Jan 6 14:06:34 2014 us=993504 PO_INIT maxevents=4 flags=0x00000002
Mon Jan 6 14:06:34 2014 us=994198 PRNG init md=SHA1 size=36
Mon Jan 6 14:06:34 2014 us=994308 Control Channel Authentication: using '/safe/safe/txt2/keys/ip.key' as a OpenVPN static key file
Mon Jan 6 14:06:34 2014 us=994491 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Mon Jan 6 14:06:34 2014 us=994508 Outgoing Control Channel Authentication: HMAC KEY: 72820932 ce6e4a30 b68b7b2b 990707e2 45994b0b
Mon Jan 6 14:06:34 2014 us=994516 Outgoing Control Channel Authentication: HMAC size=20 block_size=20
Mon Jan 6 14:06:34 2014 us=994527 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Mon Jan 6 14:06:34 2014 us=994541 Incoming Control Channel Authentication: HMAC KEY: 5df2e7a4 612ec135 6d8bcc6b 399d8732 d9cb9a1a
Mon Jan 6 14:06:34 2014 us=994549 Incoming Control Channel Authentication: HMAC size=20 block_size=20
Mon Jan 6 14:06:34 2014 us=994575 LZO compression initialized
Mon Jan 6 14:06:34 2014 us=995826 MTU DYNAMIC mtu=0, flags=1, 0 -> 168
Mon Jan 6 14:06:34 2014 us=995841 TLS: tls_session_init: entry
Mon Jan 6 14:06:34 2014 us=995858 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:34 2014 us=995902 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:34 2014 us=995917 TLS: tls_session_init: new session object, sid=b3c5c37a 60b416cc
Mon Jan 6 14:06:34 2014 us=995926 TLS: tls_session_init: entry
Mon Jan 6 14:06:34 2014 us=995936 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:34 2014 us=995966 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:34 2014 us=995978 TLS: tls_session_init: new session object, sid=d52b7365 e4ae5c1c
Mon Jan 6 14:06:34 2014 us=996092 Control Channel MTU parms [ L:1544 D:168 EF:68 EB:0 ET:0 EL:0 ]
Mon Jan 6 14:06:34 2014 us=996114 MTU DYNAMIC mtu=1450, flags=2, 1544 -> 1450
Mon Jan 6 14:06:34 2014 us=996144 Socket Buffers: R=[87380->131072] S=[16384->131072]
Mon Jan 6 14:06:34 2014 us=996164 GETADDRINFO flags=0x0101 ai_family=2 ai_socktype=1
Mon Jan 6 14:06:34 2014 us=996632 RESOLVE_REMOTE flags=0x0101 phase=1 rrs=0 sig=-1 status=0
Mon Jan 6 14:06:34 2014 us=996661 Data Channel MTU parms [ L:1544 D:1450 EF:44 EB:135 ET:0 EL:0 AF:3/1 ]
Mon Jan 6 14:06:34 2014 us=996695 Local 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:34 2014 us=996708 Expected Remote 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:34 2014 us=996738 Local Options hash (VER=V4): 'ee93268d'
Mon Jan 6 14:06:34 2014 us=996774 Expected Remote Options hash (VER=V4): 'bd577cd1'
Mon Jan 6 14:06:34 2014 us=996947 NOTE: UID/GID downgrade will be delayed because of --client, --pull, or --up-delay
Mon Jan 6 14:06:34 2014 us=996972 STREAM: RESET
Mon Jan 6 14:06:34 2014 us=996981 STREAM: INIT maxlen=1544
Mon Jan 6 14:06:34 2014 us=996995 Attempting to establish TCP connection with [AF_INET]50.166.56.161:31423 [nonblock]
Mon Jan 6 14:06:35 2014 us=997126 TCP connection established with [AF_INET]50.166.56.161:31423
Mon Jan 6 14:06:35 2014 us=997170 TCPv4_CLIENT link local: [undef]
Mon Jan 6 14:06:35 2014 us=997185 TCPv4_CLIENT link remote: [AF_INET]50.166.56.161:31423
Mon Jan 6 14:06:35 2014 us=997206 TIMER: coarse timer wakeup 1 seconds
Mon Jan 6 14:06:35 2014 us=997232 TLS: tls_multi_process: i=0 state=S_INITIAL, mysid=b3c5c37a 60b416cc, stored-sid=00000000 00000000, stored-ip=[AF_INET]50.166.56.161:31423
Mon Jan 6 14:06:35 2014 us=997244 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=997255 ACK mark active outgoing ID 0
Mon Jan 6 14:06:35 2014 us=997265 TLS: Initial Handshake, sid=b3c5c37a 60b416cc
Mon Jan 6 14:06:35 2014 us=997276 ACK reliable_can_send active=1 current=1 : [1] 0
Mon Jan 6 14:06:35 2014 us=997285 ACK reliable_send ID 0 (size=4 to=2)
Mon Jan 6 14:06:35 2014 us=997303 Reliable -> TCP/UDP
Mon Jan 6 14:06:35 2014 us=997313 ACK reliable_send_timeout 2 [1] 0
Mon Jan 6 14:06:35 2014 us=997320 TLS: tls_process: timeout set to 2
Mon Jan 6 14:06:35 2014 us=997334 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d52b7365 e4ae5c1c, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:35 2014 us=997348 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=997365 RANDOM USEC=128132
Mon Jan 6 14:06:35 2014 us=997376 STREAM: SET NEXT, buf=[180,0] next=[180,1544] len=-1 maxlen=1544
Mon Jan 6 14:06:35 2014 us=997386 PO_CTL rwflags=0x0003 ev=3 arg=0x7f3b3c9c8150
Mon Jan 6 14:06:35 2014 us=997404 I/O WAIT T?|T?|SR|SW [1/128132]
Mon Jan 6 14:06:35 2014 us=997418 PO_WAIT[0,0] fd=3 rev=0x00000004 rwflags=0x0002 arg=0x7f3b3c9c8150
Mon Jan 6 14:06:35 2014 us=997427 event_wait returned 1
Mon Jan 6 14:06:35 2014 us=997435 I/O WAIT status=0x0002
Mon Jan 6 14:06:35 2014 us=997465 TCPv4_CLIENT WRITE [42] to [AF_INET]50.166.56.161:31423: 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:35 2014 us=997477 STREAM: WRITE 42 offset=26
Mon Jan 6 14:06:35 2014 us=997508 TCPv4_CLIENT write returned 44
Mon Jan 6 14:06:35 2014 us=997528 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=b3c5c37a 60b416cc, stored-sid=00000000 00000000, stored-ip=[AF_INET]50.166.56.161:31423
Mon Jan 6 14:06:35 2014 us=997567 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=997577 ACK reliable_can_send active=1 current=0 : [1] 0
Mon Jan 6 14:06:35 2014 us=997662 SSL state (connect): before/connect initialization
Mon Jan 6 14:06:35 2014 us=997716 SSL state (connect): SSLv3 write client hello A
Mon Jan 6 14:06:35 2014 us=997736 ACK reliable_send_timeout 2 [1] 0
Mon Jan 6 14:06:35 2014 us=997745 TLS: tls_process: timeout set to 2
Mon Jan 6 14:06:35 2014 us=997759 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d52b7365 e4ae5c1c, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:35 2014 us=997773 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=997784 STREAM: SET NEXT, buf=[180,0] next=[180,1544] len=-1 maxlen=1544
Mon Jan 6 14:06:35 2014 us=997792 PO_CTL rwflags=0x0001 ev=3 arg=0x7f3b3c9c8150
Mon Jan 6 14:06:35 2014 us=997803 I/O WAIT T?|T?|SR|Sw [1/128132]
Mon Jan 6 14:06:36 2014 us=20258 PO_WAIT[0,0] fd=3 rev=0x00000019 rwflags=0x0001 arg=0x7f3b3c9c8150
Mon Jan 6 14:06:36 2014 us=20288 event_wait returned 1
Mon Jan 6 14:06:36 2014 us=20300 I/O WAIT status=0x0001
Mon Jan 6 14:06:36 2014 us=20310 STREAM: GET NEXT len=1544
Mon Jan 6 14:06:36 2014 us=20326 Connection reset, restarting [-1]
Mon Jan 6 14:06:36 2014 us=20364 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=20411 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=20422 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=20430 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=20444 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=20452 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=20460 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=20467 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=20475 TCP/UDP: Closing socket
Mon Jan 6 14:06:36 2014 us=20497 PID packet_id_free
Mon Jan 6 14:06:36 2014 us=20510 SIGUSR1[soft,connection-reset] received, process restarting
Mon Jan 6 14:06:36 2014 us=20523 Restart pause, 5 second(s)
Mon Jan 6 14:06:41 2014 us=20613 WARNING: No server certificate verification method has been enabled. See http://openvpn.net/howto.html#mitm for more info.
Mon Jan 6 14:06:41 2014 us=20659 PO_INIT maxevents=4 flags=0x00000002
Mon Jan 6 14:06:41 2014 us=20674 Re-using SSL/TLS context
Mon Jan 6 14:06:41 2014 us=20702 LZO compression initialized
Mon Jan 6 14:06:41 2014 us=20713 MTU DYNAMIC mtu=0, flags=1, 0 -> 168
Mon Jan 6 14:06:41 2014 us=20722 TLS: tls_session_init: entry
Mon Jan 6 14:06:41 2014 us=20740 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:41 2014 us=20777 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:41 2014 us=20790 TLS: tls_session_init: new session object, sid=07b4287a fcd0f4af
Mon Jan 6 14:06:41 2014 us=20798 TLS: tls_session_init: entry
Mon Jan 6 14:06:41 2014 us=20809 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:41 2014 us=20829 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Mon Jan 6 14:06:41 2014 us=20843 TLS: tls_session_init: new session object, sid=b821aaaf 2a903094
Mon Jan 6 14:06:41 2014 us=20854 Control Channel MTU parms [ L:1544 D:168 EF:68 EB:0 ET:0 EL:0 ]
Mon Jan 6 14:06:41 2014 us=20867 MTU DYNAMIC mtu=1450, flags=2, 1544 -> 1450
Mon Jan 6 14:06:41 2014 us=20890 Socket Buffers: R=[87380->131072] S=[16384->131072]
Mon Jan 6 14:06:41 2014 us=20904 GETADDRINFO flags=0x0101 ai_family=2 ai_socktype=1
Mon Jan 6 14:06:41 2014 us=20926 RESOLVE_REMOTE flags=0x0101 phase=1 rrs=0 sig=-1 status=0
Mon Jan 6 14:06:41 2014 us=20938 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=20961 Local 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=20971 Expected Remote 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=20991 Local Options hash (VER=V4): 'ee93268d'
Mon Jan 6 14:06:41 2014 us=21010 Expected Remote Options hash (VER=V4): 'bd577cd1'
Mon Jan 6 14:06:41 2014 us=21022 STREAM: RESET
Mon Jan 6 14:06:41 2014 us=21030 STREAM: INIT maxlen=1544
Mon Jan 6 14:06:41 2014 us=21042 Attempting to establish TCP connection with [AF_INET]50.166.56.161:31423 [nonblock]
Mon Jan 6 14:06:42 2014 us=21189 TCP connection established with [AF_INET]50.166.56.161:31423
Mon Jan 6 14:06:42 2014 us=21233 TCPv4_CLIENT link local: [undef]
Mon Jan 6 14:06:42 2014 us=21247 TCPv4_CLIENT link remote: [AF_INET]50.166.56.161:31423
Mon Jan 6 14:06:42 2014 us=21263 TIMER: coarse timer wakeup 1 seconds
Mon Jan 6 14:06:42 2014 us=21287 TLS: tls_multi_process: i=0 state=S_INITIAL, mysid=07b4287a fcd0f4af, stored-sid=00000000 00000000, stored-ip=[AF_INET]50.166.56.161:31423
Mon Jan 6 14:06:42 2014 us=21297 TLS: tls_process: chg=0 ks=S_INITIAL lame=S_UNDEF to_link->len=0 wakeup=604800
Mon Jan 6 14:06:42 2014 us=21306 ACK mark active outgoing ID 0
Mon Jan 6 14:06:42 2014 us=21316 TLS: Initial Handshake, sid=07b4287a fcd0f4af
Mon Jan 6 14:06:42 2014 us=21326 ACK reliable_can_send active=1 current=1 : [1] 0
Mon Jan 6 14:06:42 2014 us=21335 ACK reliable_send ID 0 (size=4 to=2)
Mon Jan 6 14:06:42 2014 us=21359 Reliable -> TCP/UDP
Mon Jan 6 14:06:42 2014 us=21368 ACK reliable_send_timeout 2 [1] 0
Mon Jan 6 14:06:42 2014 us=21376 TLS: tls_process: timeout set to 2
Mon Jan 6 14:06:42 2014 us=21391 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b821aaaf 2a903094, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:42 2014 us=21405 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=21419 RANDOM USEC=151444
Mon Jan 6 14:06:42 2014 us=21433 STREAM: SET NEXT, buf=[180,0] next=[180,1544] len=-1 maxlen=1544
Mon Jan 6 14:06:42 2014 us=21442 PO_CTL rwflags=0x0003 ev=3 arg=0x7f3b3c9c8150
Mon Jan 6 14:06:42 2014 us=21455 I/O WAIT T?|T?|SR|SW [1/151444]
Mon Jan 6 14:06:42 2014 us=21468 PO_WAIT[0,0] fd=3 rev=0x00000004 rwflags=0x0002 arg=0x7f3b3c9c8150
Mon Jan 6 14:06:42 2014 us=21476 event_wait returned 1
Mon Jan 6 14:06:42 2014 us=21484 I/O WAIT status=0x0002
Mon Jan 6 14:06:42 2014 us=21516 TCPv4_CLIENT WRITE [42] to [AF_INET]50.166.56.161:31423: 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=21525 STREAM: WRITE 42 offset=26
Mon Jan 6 14:06:42 2014 us=21557 TCPv4_CLIENT write returned 44
Mon Jan 6 14:06:42 2014 us=21577 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=07b4287a fcd0f4af, stored-sid=00000000 00000000, stored-ip=[AF_INET]50.166.56.161:31423
Mon Jan 6 14:06:42 2014 us=21586 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=21596 ACK reliable_can_send active=1 current=0 : [1] 0
Mon Jan 6 14:06:42 2014 us=21633 SSL state (connect): before/connect initialization
Mon Jan 6 14:06:42 2014 us=21672 SSL state (connect): SSLv3 write client hello A
Mon Jan 6 14:06:42 2014 us=21688 ACK reliable_send_timeout 2 [1] 0
Mon Jan 6 14:06:42 2014 us=21701 TLS: tls_process: timeout set to 2
Mon Jan 6 14:06:42 2014 us=21715 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=b821aaaf 2a903094, stored-sid=00000000 00000000, stored-ip=[undef]
Mon Jan 6 14:06:42 2014 us=21729 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=21740 STREAM: SET NEXT, buf=[180,0] next=[180,1544] len=-1 maxlen=1544
Mon Jan 6 14:06:42 2014 us=21749 PO_CTL rwflags=0x0001 ev=3 arg=0x7f3b3c9c8150
Mon Jan 6 14:06:42 2014 us=21760 I/O WAIT T?|T?|SR|Sw [1/151444]
Mon Jan 6 14:06:42 2014 us=48637 PO_WAIT[0,0] fd=3 rev=0x00000019 rwflags=0x0001 arg=0x7f3b3c9c8150
Mon Jan 6 14:06:42 2014 us=48673 event_wait returned 1
Mon Jan 6 14:06:42 2014 us=49109 I/O WAIT status=0x0001
Mon Jan 6 14:06:42 2014 us=49120 STREAM: GET NEXT len=1544
Mon Jan 6 14:06:42 2014 us=49131 Connection reset, restarting [-1]
Mon Jan 6 14:06:42 2014 us=49143 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=49172 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=49569 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=49579 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=49596 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=49605 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=49613 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=49620 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=49629 TCP/UDP: Closing socket
Mon Jan 6 14:06:42 2014 us=49657 PID packet_id_free
Mon Jan 6 14:06:42 2014 us=49672 SIGUSR1[soft,connection-reset] received, process restarting
Mon Jan 6 14:06:42 2014 us=49686 Restart pause, 5 second(s)

Thanks, Igor