as promised here the log content, when the connection can be established. This is on the command line.
Code: Select all
Thu Jun 9 21:37:03 2016 us=293984 Current Parameter Settings:
Thu Jun 9 21:37:03 2016 us=294077 config = '/etc/openvpn/client.conf'
Thu Jun 9 21:37:03 2016 us=294098 mode = 0
Thu Jun 9 21:37:03 2016 us=294116 persist_config = DISABLED
Thu Jun 9 21:37:03 2016 us=294132 persist_mode = 1
Thu Jun 9 21:37:03 2016 us=294148 show_ciphers = DISABLED
Thu Jun 9 21:37:03 2016 us=294164 show_digests = DISABLED
Thu Jun 9 21:37:03 2016 us=294180 show_engines = DISABLED
Thu Jun 9 21:37:03 2016 us=294196 genkey = DISABLED
Thu Jun 9 21:37:03 2016 us=294214 key_pass_file = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=294230 show_tls_ciphers = DISABLED
Thu Jun 9 21:37:03 2016 us=294245 Connection profiles [default]:
Thu Jun 9 21:37:03 2016 us=294261 proto = tcp-client
Thu Jun 9 21:37:03 2016 us=294276 local = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=294291 local_port = 0
Thu Jun 9 21:37:03 2016 us=294306 remote = 'vpn.nxxxxxxm.de'
Thu Jun 9 21:37:03 2016 us=294321 remote_port = 2443
Thu Jun 9 21:37:03 2016 us=294345 remote_float = DISABLED
Thu Jun 9 21:37:03 2016 us=294361 bind_defined = DISABLED
Thu Jun 9 21:37:03 2016 us=294375 bind_local = DISABLED
Thu Jun 9 21:37:03 2016 us=294390 connect_retry_seconds = 5
Thu Jun 9 21:37:03 2016 us=294404 connect_timeout = 10
Thu Jun 9 21:37:03 2016 us=294420 connect_retry_max = 0
Thu Jun 9 21:37:03 2016 us=294434 socks_proxy_server = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=294446 socks_proxy_port = 0
Thu Jun 9 21:37:03 2016 us=294458 socks_proxy_retry = DISABLED
Thu Jun 9 21:37:03 2016 us=294480 tun_mtu = 1500
Thu Jun 9 21:37:03 2016 us=294492 tun_mtu_defined = ENABLED
Thu Jun 9 21:37:03 2016 us=294505 link_mtu = 1500
Thu Jun 9 21:37:03 2016 us=294519 link_mtu_defined = DISABLED
Thu Jun 9 21:37:03 2016 us=294533 tun_mtu_extra = 0
Thu Jun 9 21:37:03 2016 us=294549 tun_mtu_extra_defined = DISABLED
Thu Jun 9 21:37:03 2016 us=294564 mtu_discover_type = -1
Thu Jun 9 21:37:03 2016 us=294579 fragment = 0
Thu Jun 9 21:37:03 2016 us=294593 mssfix = 1450
Thu Jun 9 21:37:03 2016 us=294608 explicit_exit_notification = 0
Thu Jun 9 21:37:03 2016 us=294625 Connection profiles END
Thu Jun 9 21:37:03 2016 us=294639 remote_random = DISABLED
Thu Jun 9 21:37:03 2016 us=294653 ipchange = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=294667 dev = 'tun'
Thu Jun 9 21:37:03 2016 us=294681 dev_type = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=294697 dev_node = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=294713 lladdr = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=294727 topology = 1
Thu Jun 9 21:37:03 2016 us=294742 tun_ipv6 = DISABLED
Thu Jun 9 21:37:03 2016 us=294756 ifconfig_local = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=294771 ifconfig_remote_netmask = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=294787 ifconfig_noexec = DISABLED
Thu Jun 9 21:37:03 2016 us=294801 ifconfig_nowarn = DISABLED
Thu Jun 9 21:37:03 2016 us=294815 ifconfig_ipv6_local = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=294830 ifconfig_ipv6_netbits = 0
Thu Jun 9 21:37:03 2016 us=294844 ifconfig_ipv6_remote = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=294859 shaper = 0
Thu Jun 9 21:37:03 2016 us=294873 mtu_test = 0
Thu Jun 9 21:37:03 2016 us=294887 mlock = DISABLED
Thu Jun 9 21:37:03 2016 us=294901 keepalive_ping = 0
Thu Jun 9 21:37:03 2016 us=294915 keepalive_timeout = 0
Thu Jun 9 21:37:03 2016 us=294930 inactivity_timeout = 0
Thu Jun 9 21:37:03 2016 us=294944 ping_send_timeout = 0
Thu Jun 9 21:37:03 2016 us=294959 ping_rec_timeout = 0
Thu Jun 9 21:37:03 2016 us=294973 ping_rec_timeout_action = 0
Thu Jun 9 21:37:03 2016 us=294988 ping_timer_remote = DISABLED
Thu Jun 9 21:37:03 2016 us=295024 remap_sigusr1 = 0
Thu Jun 9 21:37:03 2016 us=295038 persist_tun = DISABLED
Thu Jun 9 21:37:03 2016 us=295053 persist_local_ip = DISABLED
Thu Jun 9 21:37:03 2016 us=295068 persist_remote_ip = DISABLED
Thu Jun 9 21:37:03 2016 us=295082 persist_key = DISABLED
Thu Jun 9 21:37:03 2016 us=295096 passtos = DISABLED
Thu Jun 9 21:37:03 2016 us=295112 resolve_retry_seconds = 1000000000
Thu Jun 9 21:37:03 2016 us=295128 username = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=295142 groupname = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=295156 chroot_dir = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=295182 cd_dir = '/etc/openvpn/'
Thu Jun 9 21:37:03 2016 us=295199 writepid = '/var/run/openvpn/client.pid'
Thu Jun 9 21:37:03 2016 us=295216 up_script = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=295231 down_script = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=295245 down_pre = DISABLED
Thu Jun 9 21:37:03 2016 us=295259 up_restart = DISABLED
Thu Jun 9 21:37:03 2016 us=295273 up_delay = DISABLED
Thu Jun 9 21:37:03 2016 us=295288 daemon = ENABLED
Thu Jun 9 21:37:03 2016 us=295302 inetd = 0
Thu Jun 9 21:37:03 2016 us=295316 log = ENABLED
Thu Jun 9 21:37:03 2016 us=295330 suppress_timestamps = DISABLED
Thu Jun 9 21:37:03 2016 us=295344 nice = 0
Thu Jun 9 21:37:03 2016 us=295358 verbosity = 11
Thu Jun 9 21:37:03 2016 us=295374 mute = 0
Thu Jun 9 21:37:03 2016 us=295396 gremlin = 0
Thu Jun 9 21:37:03 2016 us=295413 status_file = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=295446 status_file_version = 1
Thu Jun 9 21:37:03 2016 us=295484 status_file_update_freq = 60
Thu Jun 9 21:37:03 2016 us=295516 occ = ENABLED
Thu Jun 9 21:37:03 2016 us=295549 rcvbuf = 0
Thu Jun 9 21:37:03 2016 us=295582 sndbuf = 0
Thu Jun 9 21:37:03 2016 us=295614 mark = 0
Thu Jun 9 21:37:03 2016 us=295628 sockflags = 0
Thu Jun 9 21:37:03 2016 us=295643 fast_io = DISABLED
Thu Jun 9 21:37:03 2016 us=295658 lzo = 7
Thu Jun 9 21:37:03 2016 us=295672 route_script = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=295686 route_default_gateway = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=295701 route_default_metric = 0
Thu Jun 9 21:37:03 2016 us=295715 route_noexec = DISABLED
Thu Jun 9 21:37:03 2016 us=295730 route_delay = 0
Thu Jun 9 21:37:03 2016 us=295745 route_delay_window = 30
Thu Jun 9 21:37:03 2016 us=295760 route_delay_defined = DISABLED
Thu Jun 9 21:37:03 2016 us=295775 route_nopull = DISABLED
Thu Jun 9 21:37:03 2016 us=295790 route_gateway_via_dhcp = DISABLED
Thu Jun 9 21:37:03 2016 us=295804 max_routes = 100
Thu Jun 9 21:37:03 2016 us=295819 allow_pull_fqdn = DISABLED
Thu Jun 9 21:37:03 2016 us=295833 management_addr = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=295847 management_port = 0
Thu Jun 9 21:37:03 2016 us=295861 management_user_pass = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=295876 management_log_history_cache = 250
Thu Jun 9 21:37:03 2016 us=295890 management_echo_buffer_size = 100
Thu Jun 9 21:37:03 2016 us=295905 management_write_peer_info_file = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=295920 management_client_user = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=295934 management_client_group = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=295950 management_flags = 0
Thu Jun 9 21:37:03 2016 us=295964 shared_secret_file = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=295978 key_direction = 0
Thu Jun 9 21:37:03 2016 us=295993 ciphername_defined = ENABLED
Thu Jun 9 21:37:03 2016 us=296007 ciphername = 'BF-CBC'
Thu Jun 9 21:37:03 2016 us=296022 authname_defined = ENABLED
Thu Jun 9 21:37:03 2016 us=296036 authname = 'SHA1'
Thu Jun 9 21:37:03 2016 us=296051 prng_hash = 'SHA1'
Thu Jun 9 21:37:03 2016 us=296080 prng_nonce_secret_len = 16
Thu Jun 9 21:37:03 2016 us=296093 keysize = 0
Thu Jun 9 21:37:03 2016 us=296108 engine = DISABLED
Thu Jun 9 21:37:03 2016 us=296123 replay = ENABLED
Thu Jun 9 21:37:03 2016 us=296140 mute_replay_warnings = DISABLED
Thu Jun 9 21:37:03 2016 us=296156 replay_window = 64
Thu Jun 9 21:37:03 2016 us=296170 replay_time = 15
Thu Jun 9 21:37:03 2016 us=296185 packet_id_file = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=296201 use_iv = ENABLED
Thu Jun 9 21:37:03 2016 us=296216 test_crypto = DISABLED
Thu Jun 9 21:37:03 2016 us=296230 tls_server = DISABLED
Thu Jun 9 21:37:03 2016 us=296244 tls_client = ENABLED
Thu Jun 9 21:37:03 2016 us=296259 key_method = 2
Thu Jun 9 21:37:03 2016 us=296273 ca_file = '/etc/openvpn/ca.crt'
Thu Jun 9 21:37:03 2016 us=296288 ca_path = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=296302 dh_file = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=296317 cert_file = '/etc/openvpn/client.crt'
Thu Jun 9 21:37:03 2016 us=296331 extra_certs_file = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=296346 priv_key_file = '/etc/openvpn/client.key'
Thu Jun 9 21:37:03 2016 us=296361 pkcs12_file = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=296375 cipher_list = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=296389 tls_verify = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=296404 tls_export_cert = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=296420 verify_x509_type = 0
Thu Jun 9 21:37:03 2016 us=296434 verify_x509_name = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=296449 crl_file = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=296485 ns_cert_type = 0
Thu Jun 9 21:37:03 2016 us=296505 remote_cert_ku[i] = 160
Thu Jun 9 21:37:03 2016 us=296519 remote_cert_ku[i] = 136
Thu Jun 9 21:37:03 2016 us=296540 remote_cert_ku[i] = 0
Thu Jun 9 21:37:03 2016 us=296555 remote_cert_ku[i] = 0
Thu Jun 9 21:37:03 2016 us=296569 remote_cert_ku[i] = 0
Thu Jun 9 21:37:03 2016 us=296585 remote_cert_ku[i] = 0
Thu Jun 9 21:37:03 2016 us=296599 remote_cert_ku[i] = 0
Thu Jun 9 21:37:03 2016 us=296614 remote_cert_ku[i] = 0
Thu Jun 9 21:37:03 2016 us=296628 remote_cert_ku[i] = 0
Thu Jun 9 21:37:03 2016 us=296643 remote_cert_ku[i] = 0
Thu Jun 9 21:37:03 2016 us=296657 remote_cert_ku[i] = 0
Thu Jun 9 21:37:03 2016 us=296671 remote_cert_ku[i] = 0
Thu Jun 9 21:37:03 2016 us=296685 remote_cert_ku[i] = 0
Thu Jun 9 21:37:03 2016 us=296699 remote_cert_ku[i] = 0
Thu Jun 9 21:37:03 2016 us=296713 remote_cert_ku[i] = 0
Thu Jun 9 21:37:03 2016 us=296727 remote_cert_ku[i] = 0
Thu Jun 9 21:37:03 2016 us=296742 remote_cert_eku = 'TLS Web Server Authentication'
Thu Jun 9 21:37:03 2016 us=296756 ssl_flags = 0
Thu Jun 9 21:37:03 2016 us=296770 tls_timeout = 2
Thu Jun 9 21:37:03 2016 us=296785 renegotiate_bytes = 0
Thu Jun 9 21:37:03 2016 us=296799 renegotiate_packets = 0
Thu Jun 9 21:37:03 2016 us=296814 renegotiate_seconds = 3600
Thu Jun 9 21:37:03 2016 us=296831 handshake_window = 60
Thu Jun 9 21:37:03 2016 us=296845 transition_window = 3600
Thu Jun 9 21:37:03 2016 us=296860 single_session = DISABLED
Thu Jun 9 21:37:03 2016 us=296874 push_peer_info = DISABLED
Thu Jun 9 21:37:03 2016 us=296888 tls_exit = DISABLED
Thu Jun 9 21:37:03 2016 us=296902 tls_auth_file = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=296917 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=296931 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=296945 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=296960 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=296975 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=296989 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=297004 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=297018 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=297032 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=297046 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=297061 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=297075 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=297089 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=297103 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=297117 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=297146 pkcs11_protected_authentication = DISABLED
Thu Jun 9 21:37:03 2016 us=297162 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297177 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297192 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297207 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297222 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297235 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297250 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297264 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297278 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297292 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297306 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297320 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297334 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297348 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297363 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297377 pkcs11_private_mode = 00000000
Thu Jun 9 21:37:03 2016 us=297391 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297406 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297420 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297441 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297455 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297476 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297490 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297504 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297518 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297533 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297547 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297561 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297576 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297592 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297606 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297620 pkcs11_cert_private = DISABLED
Thu Jun 9 21:37:03 2016 us=297635 pkcs11_pin_cache_period = -1
Thu Jun 9 21:37:03 2016 us=297649 pkcs11_id = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=297664 pkcs11_id_management = DISABLED
Thu Jun 9 21:37:03 2016 us=297687 server_network = 0.0.0.0
Thu Jun 9 21:37:03 2016 us=297701 server_netmask = 0.0.0.0
Thu Jun 9 21:37:03 2016 us=297721 server_network_ipv6 = ::
Thu Jun 9 21:37:03 2016 us=297733 server_netbits_ipv6 = 0
Thu Jun 9 21:37:03 2016 us=297749 server_bridge_ip = 0.0.0.0
Thu Jun 9 21:37:03 2016 us=297765 server_bridge_netmask = 0.0.0.0
Thu Jun 9 21:37:03 2016 us=297782 server_bridge_pool_start = 0.0.0.0
Thu Jun 9 21:37:03 2016 us=297797 server_bridge_pool_end = 0.0.0.0
Thu Jun 9 21:37:03 2016 us=297811 ifconfig_pool_defined = DISABLED
Thu Jun 9 21:37:03 2016 us=297827 ifconfig_pool_start = 0.0.0.0
Thu Jun 9 21:37:03 2016 us=297843 ifconfig_pool_end = 0.0.0.0
Thu Jun 9 21:37:03 2016 us=297858 ifconfig_pool_netmask = 0.0.0.0
Thu Jun 9 21:37:03 2016 us=297875 ifconfig_pool_persist_filename = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=297891 ifconfig_pool_persist_refresh_freq = 600
Thu Jun 9 21:37:03 2016 us=297905 ifconfig_ipv6_pool_defined = DISABLED
Thu Jun 9 21:37:03 2016 us=297921 ifconfig_ipv6_pool_base = ::
Thu Jun 9 21:37:03 2016 us=297935 ifconfig_ipv6_pool_netbits = 0
Thu Jun 9 21:37:03 2016 us=297949 n_bcast_buf = 256
Thu Jun 9 21:37:03 2016 us=297963 tcp_queue_limit = 64
Thu Jun 9 21:37:03 2016 us=297978 real_hash_size = 256
Thu Jun 9 21:37:03 2016 us=297993 virtual_hash_size = 256
Thu Jun 9 21:37:03 2016 us=298007 client_connect_script = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=298022 learn_address_script = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=298036 client_disconnect_script = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=298050 client_config_dir = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=298065 ccd_exclusive = DISABLED
Thu Jun 9 21:37:03 2016 us=298079 tmp_dir = '/tmp'
Thu Jun 9 21:37:03 2016 us=298094 push_ifconfig_defined = DISABLED
Thu Jun 9 21:37:03 2016 us=298109 push_ifconfig_local = 0.0.0.0
Thu Jun 9 21:37:03 2016 us=298124 push_ifconfig_remote_netmask = 0.0.0.0
Thu Jun 9 21:37:03 2016 us=298139 push_ifconfig_ipv6_defined = DISABLED
Thu Jun 9 21:37:03 2016 us=298154 push_ifconfig_ipv6_local = ::/0
Thu Jun 9 21:37:03 2016 us=298177 push_ifconfig_ipv6_remote = ::
Thu Jun 9 21:37:03 2016 us=298210 enable_c2c = DISABLED
Thu Jun 9 21:37:03 2016 us=298226 duplicate_cn = DISABLED
Thu Jun 9 21:37:03 2016 us=298240 cf_max = 0
Thu Jun 9 21:37:03 2016 us=298255 cf_per = 0
Thu Jun 9 21:37:03 2016 us=298269 max_clients = 1024
Thu Jun 9 21:37:03 2016 us=298283 max_routes_per_client = 256
Thu Jun 9 21:37:03 2016 us=298298 auth_user_pass_verify_script = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=298312 auth_user_pass_verify_script_via_file = DISABLED
Thu Jun 9 21:37:03 2016 us=298327 port_share_host = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=298341 port_share_port = 0
Thu Jun 9 21:37:03 2016 us=298355 client = ENABLED
Thu Jun 9 21:37:03 2016 us=298370 pull = ENABLED
Thu Jun 9 21:37:03 2016 us=298384 auth_user_pass_file = '[UNDEF]'
Thu Jun 9 21:37:03 2016 us=298408 OpenVPN 2.3.11 x86_64-redhat-linux-gnu [SSL (OpenSSL)] [LZO] [EPOLL] [PKCS11] [MH] [IPv6] built on May 10 2016
Thu Jun 9 21:37:03 2016 us=298436 library versions: OpenSSL 1.0.1e-fips 11 Feb 2013, LZO 2.03
Thu Jun 9 21:37:03 2016 us=299381 PKCS#11: pkcs11_initialize - entered
Thu Jun 9 21:37:03 2016 us=299576 PKCS#11: pkcs11_initialize - return 0-'CKR_OK'
Thu Jun 9 21:37:03 2016 us=299631 PO_INIT maxevents=4 flags=0x00000002
Thu Jun 9 21:37:03 2016 us=300701 WARNING: file '/etc/openvpn/client.key' is group or others accessible
Thu Jun 9 21:37:03 2016 us=301060 PRNG init md=SHA1 size=36
Thu Jun 9 21:37:03 2016 us=301099 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 40 bytes
Thu Jun 9 21:37:03 2016 us=301150 LZO compression initialized
Thu Jun 9 21:37:03 2016 us=301176 TLS: tls_session_init: entry
Thu Jun 9 21:37:03 2016 us=301198 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Thu Jun 9 21:37:03 2016 us=301266 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Thu Jun 9 21:37:03 2016 us=301298 TLS: tls_session_init: new session object, sid=73c67081 8a3cf597
Thu Jun 9 21:37:03 2016 us=301311 TLS: tls_session_init: entry
Thu Jun 9 21:37:03 2016 us=301329 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Thu Jun 9 21:37:03 2016 us=301367 PID packet_id_init tcp_mode=1 seq_backtrack=64 time_backtrack=15
Thu Jun 9 21:37:03 2016 us=301384 TLS: tls_session_init: new session object, sid=8014fb3e 759a2f5c
Thu Jun 9 21:37:03 2016 us=301420 Control Channel MTU parms [ L:1544 D:1210 EF:40 EB:0 ET:0 EL:3 ]
Thu Jun 9 21:37:03 2016 us=301446 MTU DYNAMIC mtu=1450, flags=2, 1544 -> 1450
Thu Jun 9 21:37:03 2016 us=309058 Socket Buffers: R=[87380->87380] S=[16384->16384]
Thu Jun 9 21:37:03 2016 us=309423 GETADDRINFO flags=0x0101 ai_family=2 ai_socktype=1
Thu Jun 9 21:37:03 2016 us=312297 RESOLVE_REMOTE flags=0x0101 phase=1 rrs=0 sig=-1 status=0
Thu Jun 9 21:37:03 2016 us=312362 Data Channel MTU parms [ L:1544 D:1450 EF:44 EB:143 ET:0 EL:3 AF:3/1 ]
Thu Jun 9 21:37:03 2016 us=312418 Local Options String: 'V4,dev-type tun,link-mtu 1544,tun-mtu 1500,proto TCPv4_CLIENT,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
Thu Jun 9 21:37:03 2016 us=312439 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1544,tun-mtu 1500,proto TCPv4_SERVER,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
Thu Jun 9 21:37:03 2016 us=312481 Local Options hash (VER=V4): '69109d17'
Thu Jun 9 21:37:03 2016 us=312508 Expected Remote Options hash (VER=V4): 'c0103fa8'
Thu Jun 9 21:37:03 2016 us=312532 STREAM: RESET
Thu Jun 9 21:37:03 2016 us=312548 STREAM: INIT maxlen=1547
Thu Jun 9 21:37:03 2016 us=312571 Attempting to establish TCP connection with [AF_INET]130.xxx.xxx.xxx:2443 [nonblock]
Thu Jun 9 21:37:04 2016 us=313442 TCP: connect to [AF_INET]130.xxx.xxx.xxx:2443 failed, will try again in 5 seconds: No route to host
Thu Jun 9 21:37:09 2016 us=313698 GETADDRINFO flags=0x0101 ai_family=2 ai_socktype=1
Thu Jun 9 21:37:10 2016 us=316064 TCP connection established with [AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=316111 TCPv4_CLIENT link local: [undef]
Thu Jun 9 21:37:10 2016 us=316123 TCPv4_CLIENT link remote: [AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=316148 TIMER: coarse timer wakeup 1 seconds
Thu Jun 9 21:37:10 2016 us=316176 TLS: tls_multi_process: i=0 state=S_INITIAL, mysid=73c67081 8a3cf597, stored-sid=00000000 00000000, stored-ip=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=316190 TLS: tls_process: chg=0 ks=S_INITIAL lame=S_UNDEF to_link->len=0 wakeup=604800
Thu Jun 9 21:37:10 2016 us=316207 ACK mark active outgoing ID 0
Thu Jun 9 21:37:10 2016 us=316219 TLS: Initial Handshake, sid=73c67081 8a3cf597
Thu Jun 9 21:37:10 2016 us=316230 ACK reliable_can_send active=1 current=1 : [1] 0
Thu Jun 9 21:37:10 2016 us=316239 ACK reliable_send ID 0 (size=4 to=2)
Thu Jun 9 21:37:10 2016 us=316249 Reliable -> TCP/UDP
Thu Jun 9 21:37:10 2016 us=316269 ACK reliable_send_timeout 2 [1] 0
Thu Jun 9 21:37:10 2016 us=316280 TLS: tls_process: timeout set to 2
Thu Jun 9 21:37:10 2016 us=316295 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=8014fb3e 759a2f5c, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=316309 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=316327 RANDOM USEC=132136
Thu Jun 9 21:37:10 2016 us=316340 STREAM: SET NEXT, buf=[192,0] next=[192,1547] len=-1 maxlen=1547
Thu Jun 9 21:37:10 2016 us=316350 PO_CTL rwflags=0x0003 ev=3 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=316363 I/O WAIT T?|T?|SR|SW [1/132136]
Thu Jun 9 21:37:10 2016 us=316391 PO_WAIT[0,0] fd=3 rev=0x00000004 rwflags=0x0002 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=316429 event_wait returned 1
Thu Jun 9 21:37:10 2016 us=316446 I/O WAIT status=0x0002
Thu Jun 9 21:37:10 2016 us=316478 TCPv4_CLIENT WRITE [14] to [AF_INET]130.xxx.xxx.xxx:2443: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 sid=73c67081 8a3cf597 [ ] pid=0 DATA
Thu Jun 9 21:37:10 2016 us=316491 STREAM: WRITE 14 offset=30
Thu Jun 9 21:37:10 2016 us=316539 TCPv4_CLIENT write returned 16
Thu Jun 9 21:37:10 2016 us=316570 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=73c67081 8a3cf597, stored-sid=00000000 00000000, stored-ip=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=316584 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Thu Jun 9 21:37:10 2016 us=316599 ACK reliable_can_send active=1 current=0 : [1] 0
Thu Jun 9 21:37:10 2016 us=316674 SSL state (connect): before/connect initialization
Thu Jun 9 21:37:10 2016 us=316727 SSL state (connect): SSLv2/v3 write client hello A
Thu Jun 9 21:37:10 2016 us=316760 ACK reliable_send_timeout 2 [1] 0
Thu Jun 9 21:37:10 2016 us=316772 TLS: tls_process: timeout set to 2
Thu Jun 9 21:37:10 2016 us=316796 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=8014fb3e 759a2f5c, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=316817 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=316833 STREAM: SET NEXT, buf=[192,0] next=[192,1547] len=-1 maxlen=1547
Thu Jun 9 21:37:10 2016 us=316847 PO_CTL rwflags=0x0001 ev=3 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=316867 I/O WAIT T?|T?|SR|Sw [1/132136]
Thu Jun 9 21:37:10 2016 us=346908 PO_WAIT[0,0] fd=3 rev=0x00000001 rwflags=0x0001 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=346933 event_wait returned 1
Thu Jun 9 21:37:10 2016 us=346947 I/O WAIT status=0x0001
Thu Jun 9 21:37:10 2016 us=346965 STREAM: GET NEXT len=1547
Thu Jun 9 21:37:10 2016 us=346983 STREAM: ADD length_added=28
Thu Jun 9 21:37:10 2016 us=346995 STREAM: ADD returned TRUE, buf_len=26, residual_len=0
Thu Jun 9 21:37:10 2016 us=347009 STREAM: GET FINAL len=26
Thu Jun 9 21:37:10 2016 us=347022 STREAM: RESET
Thu Jun 9 21:37:10 2016 us=347037 TCPv4_CLIENT read returned 26
Thu Jun 9 21:37:10 2016 us=347066 TCPv4_CLIENT READ [26] from [AF_INET]130.xxx.xxx.xxx:2443: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 sid=d75f6e90 4a091a97 [ 0 sid=73c67081 8a3cf597 ] pid=0 DATA
Thu Jun 9 21:37:10 2016 us=347115 TLS: control channel, op=P_CONTROL_HARD_RESET_SERVER_V2, IP=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=347152 TLS: initial packet test, i=0 state=S_PRE_START, mysid=73c67081 8a3cf597, rec-sid=d75f6e90 4a091a97, rec-ip=[AF_INET]130.xxx.xxx.xxx:2443, stored-sid=00000000 00000000, stored-ip=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=347177 TLS: initial packet test, i=1 state=S_INITIAL, mysid=8014fb3e 759a2f5c, rec-sid=d75f6e90 4a091a97, rec-ip=[AF_INET]130.xxx.xxx.xxx:2443, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=347201 TLS: initial packet test, i=2 state=S_UNDEF, mysid=00000000 00000000, rec-sid=d75f6e90 4a091a97, rec-ip=[AF_INET]130.xxx.xxx.xxx:2443, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=347226 TLS: Initial packet from [AF_INET]130.xxx.xxx.xxx:2443, sid=d75f6e90 4a091a97
Thu Jun 9 21:37:10 2016 us=347248 TLS: received control channel packet s#=0 sid=d75f6e90 4a091a97
Thu Jun 9 21:37:10 2016 us=347261 ACK reliable_schedule_now
Thu Jun 9 21:37:10 2016 us=347275 ACK received for pid 0, deleting from send buffer
Thu Jun 9 21:37:10 2016 us=347289 ACK read ID 0 (buf->len=0)
Thu Jun 9 21:37:10 2016 us=347304 ACK RWBS rel->size=8 rel->packet_id=00000000 id=00000000 ret=1
Thu Jun 9 21:37:10 2016 us=347318 ACK mark active incoming ID 0
Thu Jun 9 21:37:10 2016 us=347344 ACK acknowledge ID 0 (ack->len=1)
Thu Jun 9 21:37:10 2016 us=347375 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=73c67081 8a3cf597, stored-sid=d75f6e90 4a091a97, stored-ip=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=347388 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Thu Jun 9 21:37:10 2016 us=347411 ACK reliable_can_send active=0 current=0 : [1]
Thu Jun 9 21:37:10 2016 us=347430 Incoming Ciphertext -> TLS
Thu Jun 9 21:37:10 2016 us=347454 TLS: tls_process: chg=1 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Thu Jun 9 21:37:10 2016 us=347466 ACK reliable_can_send active=0 current=0 : [1]
Thu Jun 9 21:37:10 2016 us=347486 ACK write ID 0 (ack->len=1, n=1)
Thu Jun 9 21:37:10 2016 us=347500 Dedicated ACK -> TCP/UDP
Thu Jun 9 21:37:10 2016 us=347515 ACK reliable_send_timeout 604800 [1]
Thu Jun 9 21:37:10 2016 us=347529 TLS: tls_process: timeout set to 60
Thu Jun 9 21:37:10 2016 us=347552 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=8014fb3e 759a2f5c, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=347573 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=347587 STREAM: SET NEXT, buf=[192,0] next=[192,1547] len=-1 maxlen=1547
Thu Jun 9 21:37:10 2016 us=347601 PO_CTL rwflags=0x0003 ev=3 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=347620 I/O WAIT T?|T?|SR|SW [1/132136]
Thu Jun 9 21:37:10 2016 us=347636 PO_WAIT[0,0] fd=3 rev=0x00000004 rwflags=0x0002 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=347649 event_wait returned 1
Thu Jun 9 21:37:10 2016 us=347663 I/O WAIT status=0x0002
Thu Jun 9 21:37:10 2016 us=347689 TCPv4_CLIENT WRITE [22] to [AF_INET]130.xxx.xxx.xxx:2443: P_ACK_V1 kid=0 sid=73c67081 8a3cf597 [ 0 sid=d75f6e90 4a091a97 ]
Thu Jun 9 21:37:10 2016 us=347701 STREAM: WRITE 22 offset=35
Thu Jun 9 21:37:10 2016 us=347726 TCPv4_CLIENT write returned 24
Thu Jun 9 21:37:10 2016 us=347750 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=73c67081 8a3cf597, stored-sid=d75f6e90 4a091a97, stored-ip=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=347762 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Thu Jun 9 21:37:10 2016 us=347775 STATE S_START
Thu Jun 9 21:37:10 2016 us=347790 ACK reliable_can_send active=0 current=0 : [1]
Thu Jun 9 21:37:10 2016 us=347826 STATE S_SENT_KEY
Thu Jun 9 21:37:10 2016 us=347849 BIO read tls_read_ciphertext 205 bytes
Thu Jun 9 21:37:10 2016 us=347861 ACK mark active outgoing ID 1
Thu Jun 9 21:37:10 2016 us=347875 Outgoing Ciphertext -> Reliable
Thu Jun 9 21:37:10 2016 us=347889 TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Thu Jun 9 21:37:10 2016 us=347904 ACK reliable_can_send active=1 current=1 : [2] 1
Thu Jun 9 21:37:10 2016 us=347918 ACK reliable_send ID 1 (size=209 to=2)
Thu Jun 9 21:37:10 2016 us=347932 Reliable -> TCP/UDP
Thu Jun 9 21:37:10 2016 us=347947 ACK reliable_send_timeout 2 [2] 1
Thu Jun 9 21:37:10 2016 us=347960 TLS: tls_process: timeout set to 2
Thu Jun 9 21:37:10 2016 us=347989 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=8014fb3e 759a2f5c, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=348009 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=348024 STREAM: SET NEXT, buf=[192,0] next=[192,1547] len=-1 maxlen=1547
Thu Jun 9 21:37:10 2016 us=348044 PO_CTL rwflags=0x0003 ev=3 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=348062 I/O WAIT T?|T?|SR|SW [1/132136]
Thu Jun 9 21:37:10 2016 us=348078 PO_WAIT[0,0] fd=3 rev=0x00000004 rwflags=0x0002 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=348149 event_wait returned 1
Thu Jun 9 21:37:10 2016 us=348170 I/O WAIT status=0x0002
Thu Jun 9 21:37:10 2016 us=348299 TCPv4_CLIENT WRITE [219] to [AF_INET]130.xxx.xxx.xxx:2443: P_CONTROL_V1 kid=0 sid=73c67081 8a3cf597 [ ] pid=1 DATA 16030100 c8010000 c4030357 59c5662d 4e211e00 e6b1c6e2 05d130f5 11e6029[more...]
Thu Jun 9 21:37:10 2016 us=348317 STREAM: WRITE 219 offset=30
Thu Jun 9 21:37:10 2016 us=348335 TCPv4_CLIENT write returned 221
Thu Jun 9 21:37:10 2016 us=348362 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=73c67081 8a3cf597, stored-sid=d75f6e90 4a091a97, stored-ip=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=348392 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Thu Jun 9 21:37:10 2016 us=348414 ACK reliable_can_send active=1 current=0 : [2] 1
Thu Jun 9 21:37:10 2016 us=348445 ACK reliable_send_timeout 2 [2] 1
Thu Jun 9 21:37:10 2016 us=348462 TLS: tls_process: timeout set to 2
Thu Jun 9 21:37:10 2016 us=348493 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=8014fb3e 759a2f5c, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=348521 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=348535 STREAM: SET NEXT, buf=[192,0] next=[192,1547] len=-1 maxlen=1547
Thu Jun 9 21:37:10 2016 us=348551 PO_CTL rwflags=0x0001 ev=3 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=348570 I/O WAIT T?|T?|SR|Sw [1/132136]
Thu Jun 9 21:37:10 2016 us=461445 PO_WAIT[0,0] fd=3 rev=0x00000001 rwflags=0x0001 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=461487 event_wait returned 1
Thu Jun 9 21:37:10 2016 us=461505 I/O WAIT status=0x0001
Thu Jun 9 21:37:10 2016 us=461521 STREAM: GET NEXT len=1547
Thu Jun 9 21:37:10 2016 us=461544 STREAM: ADD length_added=1198
Thu Jun 9 21:37:10 2016 us=461557 STREAM: ADD returned TRUE, buf_len=1196, residual_len=0
Thu Jun 9 21:37:10 2016 us=461572 STREAM: GET FINAL len=1196
Thu Jun 9 21:37:10 2016 us=461586 STREAM: RESET
Thu Jun 9 21:37:10 2016 us=461602 TCPv4_CLIENT read returned 1196
Thu Jun 9 21:37:10 2016 us=462097 TCPv4_CLIENT READ [1196] from [AF_INET]130.xxx.xxx.xxx:2443: P_CONTROL_V1 kid=0 sid=d75f6e90 4a091a97 [ 1 sid=73c67081 8a3cf597 ] pid=1 DATA 16030300 36020000 320303ad fe4ba5c6 90c79936 e57424f0 97485c37 9974b34[more...]
Thu Jun 9 21:37:10 2016 us=462133 TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=462168 TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=73c67081 8a3cf597, rec-sid=d75f6e90 4a091a97, rec-ip=[AF_INET]130.xxx.xxx.xxx:2443, stored-sid=d75f6e90 4a091a97, stored-ip=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=462187 TLS: found match, session[0], sid=d75f6e90 4a091a97
Thu Jun 9 21:37:10 2016 us=462205 TLS: received control channel packet s#=0 sid=d75f6e90 4a091a97
Thu Jun 9 21:37:10 2016 us=462219 ACK received for pid 1, deleting from send buffer
Thu Jun 9 21:37:10 2016 us=462232 ACK read ID 1 (buf->len=1170)
Thu Jun 9 21:37:10 2016 us=462246 ACK RWBS rel->size=8 rel->packet_id=00000001 id=00000001 ret=1
Thu Jun 9 21:37:10 2016 us=462262 ACK mark active incoming ID 1
Thu Jun 9 21:37:10 2016 us=462277 ACK acknowledge ID 1 (ack->len=1)
Thu Jun 9 21:37:10 2016 us=462307 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=73c67081 8a3cf597, stored-sid=d75f6e90 4a091a97, stored-ip=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=462321 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Thu Jun 9 21:37:10 2016 us=462337 ACK reliable_can_send active=0 current=0 : [2]
Thu Jun 9 21:37:10 2016 us=462355 BIO write tls_write_ciphertext 1170 bytes
Thu Jun 9 21:37:10 2016 us=462368 Incoming Ciphertext -> TLS
Thu Jun 9 21:37:10 2016 us=462467 SSL state (connect): SSLv3 read server hello A
Thu Jun 9 21:37:10 2016 us=462600 TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Thu Jun 9 21:37:10 2016 us=462619 ACK reliable_can_send active=0 current=0 : [2]
Thu Jun 9 21:37:10 2016 us=462644 ACK write ID 1 (ack->len=1, n=1)
Thu Jun 9 21:37:10 2016 us=462656 Dedicated ACK -> TCP/UDP
Thu Jun 9 21:37:10 2016 us=462671 ACK reliable_send_timeout 604800 [2]
Thu Jun 9 21:37:10 2016 us=462685 TLS: tls_process: timeout set to 60
Thu Jun 9 21:37:10 2016 us=462709 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=8014fb3e 759a2f5c, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=462732 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=462749 STREAM: SET NEXT, buf=[192,0] next=[192,1547] len=-1 maxlen=1547
Thu Jun 9 21:37:10 2016 us=462762 PO_CTL rwflags=0x0003 ev=3 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=462789 I/O WAIT T?|T?|SR|SW [1/132136]
Thu Jun 9 21:37:10 2016 us=462807 PO_WAIT[0,0] fd=3 rev=0x00000004 rwflags=0x0002 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=462820 event_wait returned 1
Thu Jun 9 21:37:10 2016 us=462834 I/O WAIT status=0x0002
Thu Jun 9 21:37:10 2016 us=462863 TCPv4_CLIENT WRITE [22] to [AF_INET]130.xxx.xxx.xxx:2443: P_ACK_V1 kid=0 sid=73c67081 8a3cf597 [ 1 sid=d75f6e90 4a091a97 ]
Thu Jun 9 21:37:10 2016 us=462876 STREAM: WRITE 22 offset=35
Thu Jun 9 21:37:10 2016 us=462903 TCPv4_CLIENT write returned 24
Thu Jun 9 21:37:10 2016 us=462934 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=73c67081 8a3cf597, stored-sid=d75f6e90 4a091a97, stored-ip=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=462951 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Thu Jun 9 21:37:10 2016 us=462996 ACK reliable_can_send active=0 current=0 : [2]
Thu Jun 9 21:37:10 2016 us=463032 ACK reliable_send_timeout 604800 [2]
Thu Jun 9 21:37:10 2016 us=463049 TLS: tls_process: timeout set to 60
Thu Jun 9 21:37:10 2016 us=463074 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=8014fb3e 759a2f5c, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=463099 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=463119 STREAM: SET NEXT, buf=[192,0] next=[192,1547] len=-1 maxlen=1547
Thu Jun 9 21:37:10 2016 us=463142 PO_CTL rwflags=0x0001 ev=3 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=463163 I/O WAIT T?|T?|SR|Sw [1/132136]
Thu Jun 9 21:37:10 2016 us=467177 PO_WAIT[0,0] fd=3 rev=0x00000001 rwflags=0x0001 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=467224 event_wait returned 1
Thu Jun 9 21:37:10 2016 us=467245 I/O WAIT status=0x0001
Thu Jun 9 21:37:10 2016 us=467263 STREAM: GET NEXT len=1547
Thu Jun 9 21:37:10 2016 us=467288 STREAM: ADD length_added=1360
Thu Jun 9 21:37:10 2016 us=467305 STREAM: ADD returned TRUE, buf_len=1184, residual_len=174
Thu Jun 9 21:37:10 2016 us=467321 STREAM: GET FINAL len=1184
Thu Jun 9 21:37:10 2016 us=467334 STREAM: RESET
Thu Jun 9 21:37:10 2016 us=467350 TCPv4_CLIENT read returned 1184
Thu Jun 9 21:37:10 2016 us=468135 TCPv4_CLIENT READ [1184] from [AF_INET]130.xxx.xxx.xxx:2443: P_CONTROL_V1 kid=0 sid=d75f6e90 4a091a97 [ ] pid=2 DATA 6c69616e 6365311c 301a0603 55040313 136e7361 76706e2e 6e657473 7973746[more...]
Thu Jun 9 21:37:10 2016 us=468171 TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=468241 TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=73c67081 8a3cf597, rec-sid=d75f6e90 4a091a97, rec-ip=[AF_INET]130.xxx.xxx.xxx:2443, stored-sid=d75f6e90 4a091a97, stored-ip=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=468272 TLS: found match, session[0], sid=d75f6e90 4a091a97
Thu Jun 9 21:37:10 2016 us=468291 TLS: received control channel packet s#=0 sid=d75f6e90 4a091a97
Thu Jun 9 21:37:10 2016 us=468309 ACK read ID 2 (buf->len=1170)
Thu Jun 9 21:37:10 2016 us=468336 ACK RWBS rel->size=8 rel->packet_id=00000002 id=00000002 ret=1
Thu Jun 9 21:37:10 2016 us=468353 ACK mark active incoming ID 2
Thu Jun 9 21:37:10 2016 us=468368 ACK acknowledge ID 2 (ack->len=1)
Thu Jun 9 21:37:10 2016 us=468413 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=73c67081 8a3cf597, stored-sid=d75f6e90 4a091a97, stored-ip=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=468438 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Thu Jun 9 21:37:10 2016 us=468454 ACK reliable_can_send active=0 current=0 : [2]
Thu Jun 9 21:37:10 2016 us=468473 BIO write tls_write_ciphertext 1170 bytes
Thu Jun 9 21:37:10 2016 us=468487 Incoming Ciphertext -> TLS
Thu Jun 9 21:37:10 2016 us=468523 TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Thu Jun 9 21:37:10 2016 us=468544 ACK reliable_can_send active=0 current=0 : [2]
Thu Jun 9 21:37:10 2016 us=468571 ACK write ID 2 (ack->len=1, n=1)
Thu Jun 9 21:37:10 2016 us=468590 Dedicated ACK -> TCP/UDP
Thu Jun 9 21:37:10 2016 us=468606 ACK reliable_send_timeout 604800 [2]
Thu Jun 9 21:37:10 2016 us=468622 TLS: tls_process: timeout set to 60
Thu Jun 9 21:37:10 2016 us=468648 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=8014fb3e 759a2f5c, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=468674 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=468694 STREAM: ADD length_added=0
Thu Jun 9 21:37:10 2016 us=468710 STREAM: ADD returned FALSE (have=172 need=1184)
Thu Jun 9 21:37:10 2016 us=468726 STREAM: SET NEXT, buf=[194,172] next=[366,1012] len=1184 maxlen=1547
Thu Jun 9 21:37:10 2016 us=468741 STREAM: RESIDUAL FULLY FORMED [NO], len=0
Thu Jun 9 21:37:10 2016 us=468756 STREAM: SET NEXT, buf=[194,172] next=[366,1012] len=1184 maxlen=1547
Thu Jun 9 21:37:10 2016 us=468773 PO_CTL rwflags=0x0003 ev=3 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=468794 I/O WAIT T?|T?|SR|SW [1/132136]
Thu Jun 9 21:37:10 2016 us=468816 PO_WAIT[0,0] fd=3 rev=0x00000005 rwflags=0x0003 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=468833 event_wait returned 1
Thu Jun 9 21:37:10 2016 us=468846 I/O WAIT status=0x0003
Thu Jun 9 21:37:10 2016 us=468878 TCPv4_CLIENT WRITE [22] to [AF_INET]130.xxx.xxx.xxx:2443: P_ACK_V1 kid=0 sid=73c67081 8a3cf597 [ 2 sid=d75f6e90 4a091a97 ]
Thu Jun 9 21:37:10 2016 us=468896 STREAM: WRITE 22 offset=35
Thu Jun 9 21:37:10 2016 us=468915 TCPv4_CLIENT write returned 24
Thu Jun 9 21:37:10 2016 us=468946 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=73c67081 8a3cf597, stored-sid=d75f6e90 4a091a97, stored-ip=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=468965 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Thu Jun 9 21:37:10 2016 us=468980 ACK reliable_can_send active=0 current=0 : [2]
Thu Jun 9 21:37:10 2016 us=469007 ACK reliable_send_timeout 604800 [2]
Thu Jun 9 21:37:10 2016 us=469171 TLS: tls_process: timeout set to 60
Thu Jun 9 21:37:10 2016 us=469199 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=8014fb3e 759a2f5c, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=469226 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Thu Jun 9 21:37:10 2016 us=469248 STREAM: SET NEXT, buf=[194,172] next=[366,1012] len=1184 maxlen=1547
Thu Jun 9 21:37:10 2016 us=469264 PO_CTL rwflags=0x0001 ev=3 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=469315 I/O WAIT T?|T?|SR|Sw [1/132136]
Thu Jun 9 21:37:10 2016 us=469338 PO_WAIT[0,0] fd=3 rev=0x00000001 rwflags=0x0001 arg=0x0069efe8
Thu Jun 9 21:37:10 2016 us=469353 event_wait returned 1
Thu Jun 9 21:37:10 2016 us=469377 I/O WAIT status=0x0001
Thu Jun 9 21:37:10 2016 us=469394 STREAM: GET NEXT len=1012
Thu Jun 9 21:37:10 2016 us=469424 STREAM: ADD length_added=1012
Thu Jun 9 21:37:10 2016 us=469442 STREAM: ADD returned TRUE, buf_len=1184, residual_len=0
Thu Jun 9 21:37:10 2016 us=469466 STREAM: GET FINAL len=1184
Thu Jun 9 21:37:10 2016 us=469481 STREAM: RESET
Thu Jun 9 21:37:10 2016 us=469494 TCPv4_CLIENT read returned 1184
Thu Jun 9 21:37:10 2016 us=470255 TCPv4_CLIENT READ [1184] from [AF_INET]130.xxx.xxx.xxx:2443: P_CONTROL_V1 kid=0 sid=d75f6e90 4a091a97 [ ] pid=3 DATA bc4ee2bc 3dcf9bc8 4c63654d 35dd0af5 487e2c97 e0d43fec 9f683f34 1eda322[more...]
Thu Jun 9 21:37:10 2016 us=470285 TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=470319 TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=73c67081 8a3cf597, rec-sid=d75f6e90 4a091a97, rec-ip=[AF_INET]130.xxx.xxx.xxx:2443, stored-sid=d75f6e90 4a091a97, stored-ip=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=470381 TLS: found match, session[0], sid=d75f6e90 4a091a97
Thu Jun 9 21:37:10 2016 us=470413 TLS: received control channel packet s#=0 sid=d75f6e90 4a091a97
Thu Jun 9 21:37:10 2016 us=470432 ACK read ID 3 (buf->len=1170)
Thu Jun 9 21:37:10 2016 us=470446 ACK RWBS rel->size=8 rel->packet_id=00000003 id=00000003 ret=1
Thu Jun 9 21:37:10 2016 us=470460 ACK mark active incoming ID 3
Thu Jun 9 21:37:10 2016 us=470473 ACK acknowledge ID 3 (ack->len=1)
Thu Jun 9 21:37:10 2016 us=470504 TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=73c67081 8a3cf597, stored-sid=d75f6e90 4a091a97, stored-ip=[AF_INET]130.xxx.xxx.xxx:2443
Thu Jun 9 21:37:10 2016 us=470521 TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800
Thu Jun 9 21:37:10 2016 us=470536 ACK reliable_can_send active=0 current=0 : [2]
Thu Jun 9 21:37:10 2016 us=470552 BIO write tls_write_ciphertext 1170 bytes
Thanks again.