Hi All,
I have been using OpenVPN for about 9yrs and never had to turn to the forums before which I have been quite happy about.
We run multiple OpenVPN site-to-site tunnels and roadhog networks using several servers in several countries and have 80+ individuals connecting using Linux, Windows XP, Windows7 and Apples - all without issues.
However, I had to buy a new personal laptop recently and it came with Windows7 Home Premium x64. Typically I boot to Linux and that works fine on this laptop with OpenVPN but I simply cannot get Windows7 to work and it is really getting to me.
I have stopped or removed any 3rd party software/services which do anything to the Windows network services.
I have uninstalled/reinstalled OpenVPN about 6 times with both 2.1 and 2.2.
I have tried the laptop on at least four different networks/routers in two different countries.
I have stripped my configs right back and tried many things but I always get the same error which is this:
Fri Jun 03 16:04:22 2011 us=286000 UDPv4 link local: [undef]
Fri Jun 03 16:04:22 2011 us=286000 UDPv4 link remote: xxx.xxx.xxx.xxx:8000
Fri Jun 03 16:04:22 2011 us=286000 UDPv4 WRITE [42] to xxx.xxx.xxx.xxx:8000: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #1 ] [ ] pid=0 DATA len=0
Fri Jun 03 16:04:22 2011 us=286000 UDPv4 READ [0] from [undef]: DATA UNDEF len=-1
Fri Jun 03 16:04:24 2011 us=751000 UDPv4 WRITE [42] to xxx.xxx.xxx.xxx:8000: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #2 ] [ ] pid=0 DATA len=0
Fri Jun 03 16:04:28 2011 us=433000 UDPv4 WRITE [42] to xxx.xxx.xxx.xxx:8000: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #3 ] [ ] pid=0 DATA len=0
Fri Jun 03 16:04:36 2011 us=966000 UDPv4 WRITE [42] to xxx.xxx.xxx.xxx:8000: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #4 ] [ ] pid=0 DATA len=0
Fri Jun 03 16:04:52 2011 us=972000 UDPv4 WRITE [42] to xxx.xxx.xxx.xxx:8000: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #5 ] [ ] pid=0 DATA len=0
Fri Jun 03 16:05:23 2011 us=33000 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
This happens at the very start of the initiation of the connection and nothing is actually written to the interface let alone the server.
Searching for "P_CONTROL_HARD_RESET_CLIENT_V2" usually points to firewalls but I have variously disabled the Windows firewall entirely or excluded the TAP interface entirely from it or added rules to allow all OpenVPN traffic etc. I only have one firewall.
I have uninstalled the interface entirely as well as with the batch files in the 'bin' directory.
The interface is seen by Openvpn:
C:\Program Files (x86)\OpenVPN\bin>openvpn --show-adapters
Available TAP-WIN32 adapters [name, GUID]:
'OpenVPN' {4ABBEDB9-9E79-4B7C-B8D5-B27DE2DBB41E}
Just why OpenVPN won't talk it I can't fathom - any ideas at all gratefully accepted!
I set up another Windows7x64 laptop this morning and it works perfectly along with all the other clients so I won't include the server configs (and no traffic reaches the server anyway!)...
Thanks
DrK
Client config:
dev-node OpenVPN
dev tun
client
proto udp
remote xxx.xxx.xxx.xxx 8000
resolv-retry infinite
ns-cert-type server
cert C:\\Program\ Files\ (x86)\\OpenVPN\\keys\\xyz.crt
key C:\\Program\ Files\ (x86)\\OpenVPN\\keys\\xyz.key
ca C:\\Program\ Files\ (x86)\\OpenVPN\\keys\\ca.crt
tls-auth C:\\Program\ Files\ (x86)\\OpenVPN\\keys\\ta.key 1
auth-user-pass
nobind
persist-key
persist-tun
comp-lzo
verb 7
Full Log:
Fri Jun 03 16:04:13 2011 us=753000 Current Parameter Settings:
Fri Jun 03 16:04:13 2011 us=769000 config = 'xyz.ovpn'
Fri Jun 03 16:04:13 2011 us=769000 mode = 0
Fri Jun 03 16:04:13 2011 us=769000 show_ciphers = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 show_digests = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 show_engines = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 genkey = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 key_pass_file = '[UNDEF]'
Fri Jun 03 16:04:13 2011 us=769000 show_tls_ciphers = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 Connection profiles [default]:
Fri Jun 03 16:04:13 2011 us=769000 proto = udp
Fri Jun 03 16:04:13 2011 us=769000 local = '[UNDEF]'
Fri Jun 03 16:04:13 2011 us=769000 local_port = 0
Fri Jun 03 16:04:13 2011 us=769000 remote = 'xxx.xxx.xxx.xxx'
Fri Jun 03 16:04:13 2011 us=769000 remote_port = 8000
Fri Jun 03 16:04:13 2011 us=769000 remote_float = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 bind_defined = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 bind_local = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 connect_retry_seconds = 5
Fri Jun 03 16:04:13 2011 us=769000 connect_timeout = 10
Fri Jun 03 16:04:13 2011 us=769000 connect_retry_max = 0
Fri Jun 03 16:04:13 2011 us=769000 socks_proxy_server = '[UNDEF]'
Fri Jun 03 16:04:13 2011 us=769000 socks_proxy_port = 0
Fri Jun 03 16:04:13 2011 us=769000 socks_proxy_retry = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 Connection profiles END
Fri Jun 03 16:04:13 2011 us=769000 remote_random = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 ipchange = '[UNDEF]'
Fri Jun 03 16:04:13 2011 us=769000 dev = 'OpenVPN'
Fri Jun 03 16:04:13 2011 us=769000 dev_type = '[UNDEF]'
Fri Jun 03 16:04:13 2011 us=769000 dev_node = 'OpenVPN'
Fri Jun 03 16:04:13 2011 us=769000 lladdr = '[UNDEF]'
Fri Jun 03 16:04:13 2011 us=769000 topology = 1
Fri Jun 03 16:04:13 2011 us=769000 tun_ipv6 = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 ifconfig_local = '[UNDEF]'
Fri Jun 03 16:04:13 2011 us=769000 ifconfig_remote_netmask = '[UNDEF]'
Fri Jun 03 16:04:13 2011 us=769000 ifconfig_noexec = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 ifconfig_nowarn = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 shaper = 0
Fri Jun 03 16:04:13 2011 us=769000 tun_mtu = 1500
Fri Jun 03 16:04:13 2011 us=769000 tun_mtu_defined = ENABLED
Fri Jun 03 16:04:13 2011 us=769000 link_mtu = 1500
Fri Jun 03 16:04:13 2011 us=769000 link_mtu_defined = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 tun_mtu_extra = 0
Fri Jun 03 16:04:13 2011 us=769000 tun_mtu_extra_defined = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 fragment = 0
Fri Jun 03 16:04:13 2011 us=769000 mtu_discover_type = -1
Fri Jun 03 16:04:13 2011 us=769000 mtu_test = 0
Fri Jun 03 16:04:13 2011 us=769000 mlock = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 keepalive_ping = 0
Fri Jun 03 16:04:13 2011 us=769000 keepalive_timeout = 0
Fri Jun 03 16:04:13 2011 us=769000 inactivity_timeout = 0
Fri Jun 03 16:04:13 2011 us=769000 ping_send_timeout = 0
Fri Jun 03 16:04:13 2011 us=769000 ping_rec_timeout = 0
Fri Jun 03 16:04:13 2011 us=769000 ping_rec_timeout_action = 0
Fri Jun 03 16:04:13 2011 us=769000 ping_timer_remote = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 remap_sigusr1 = 0
Fri Jun 03 16:04:13 2011 us=769000 explicit_exit_notification = 0
Fri Jun 03 16:04:13 2011 us=769000 persist_tun = ENABLED
Fri Jun 03 16:04:13 2011 us=769000 persist_local_ip = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 persist_remote_ip = DISABLED
Fri Jun 03 16:04:13 2011 us=769000 persist_key = ENABLED
Fri Jun 03 16:04:13 2011 us=769000 mssfix = 1450
Fri Jun 03 16:04:13 2011 us=769000 resolve_retry_seconds = 1000000000
Fri Jun 03 16:04:13 2011 us=769000 username = '[UNDEF]'
Fri Jun 03 16:04:13 2011 us=769000 groupname = '[UNDEF]'
Fri Jun 03 16:04:13 2011 us=769000 chroot_dir = '[UNDEF]'
Fri Jun 03 16:04:13 2011 us=769000 cd_dir = '[UNDEF]'
Fri Jun 03 16:04:13 2011 us=769000 writepid = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=3000 up_script = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=3000 down_script = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=3000 down_pre = DISABLED
Fri Jun 03 16:04:14 2011 us=3000 up_restart = DISABLED
Fri Jun 03 16:04:14 2011 us=3000 up_delay = DISABLED
Fri Jun 03 16:04:14 2011 us=3000 daemon = DISABLED
Fri Jun 03 16:04:14 2011 us=3000 inetd = 0
Fri Jun 03 16:04:14 2011 us=3000 log = DISABLED
Fri Jun 03 16:04:14 2011 us=3000 suppress_timestamps = DISABLED
Fri Jun 03 16:04:14 2011 us=3000 nice = 0
Fri Jun 03 16:04:14 2011 us=3000 verbosity = 7
Fri Jun 03 16:04:14 2011 us=3000 mute = 0
Fri Jun 03 16:04:14 2011 us=3000 gremlin = 0
Fri Jun 03 16:04:14 2011 us=3000 status_file = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=3000 status_file_version = 1
Fri Jun 03 16:04:14 2011 us=3000 status_file_update_freq = 60
Fri Jun 03 16:04:14 2011 us=3000 occ = ENABLED
Fri Jun 03 16:04:14 2011 us=3000 rcvbuf = 0
Fri Jun 03 16:04:14 2011 us=3000 sndbuf = 0
Fri Jun 03 16:04:14 2011 us=18000 sockflags = 0
Fri Jun 03 16:04:14 2011 us=18000 fast_io = DISABLED
Fri Jun 03 16:04:14 2011 us=18000 lzo = 7
Fri Jun 03 16:04:14 2011 us=18000 route_script = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=18000 route_default_gateway = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=18000 route_default_metric = 0
Fri Jun 03 16:04:14 2011 us=18000 route_noexec = DISABLED
Fri Jun 03 16:04:14 2011 us=18000 route_delay = 0
Fri Jun 03 16:04:14 2011 us=18000 route_delay_window = 30
Fri Jun 03 16:04:14 2011 us=18000 route_delay_defined = DISABLED
Fri Jun 03 16:04:14 2011 us=18000 route_nopull = DISABLED
Fri Jun 03 16:04:14 2011 us=18000 route_gateway_via_dhcp = DISABLED
Fri Jun 03 16:04:14 2011 us=18000 max_routes = 100
Fri Jun 03 16:04:14 2011 us=18000 allow_pull_fqdn = DISABLED
Fri Jun 03 16:04:14 2011 us=18000 management_addr = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=18000 management_port = 0
Fri Jun 03 16:04:14 2011 us=18000 management_user_pass = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=65000 management_log_history_cache = 250
Fri Jun 03 16:04:14 2011 us=65000 management_echo_buffer_size = 100
Fri Jun 03 16:04:14 2011 us=65000 management_write_peer_info_file = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=65000 management_client_user = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=65000 management_client_group = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=65000 management_flags = 0
Fri Jun 03 16:04:14 2011 us=65000 shared_secret_file = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=65000 key_direction = 2
Fri Jun 03 16:04:14 2011 us=65000 ciphername_defined = ENABLED
Fri Jun 03 16:04:14 2011 us=65000 ciphername = 'BF-CBC'
Fri Jun 03 16:04:14 2011 us=65000 authname_defined = ENABLED
Fri Jun 03 16:04:14 2011 us=65000 authname = 'SHA1'
Fri Jun 03 16:04:14 2011 us=65000 prng_hash = 'SHA1'
Fri Jun 03 16:04:14 2011 us=65000 prng_nonce_secret_len = 16
Fri Jun 03 16:04:14 2011 us=65000 keysize = 0
Fri Jun 03 16:04:14 2011 us=65000 engine = DISABLED
Fri Jun 03 16:04:14 2011 us=96000 replay = ENABLED
Fri Jun 03 16:04:14 2011 us=96000 mute_replay_warnings = DISABLED
Fri Jun 03 16:04:14 2011 us=96000 replay_window = 64
Fri Jun 03 16:04:14 2011 us=96000 replay_time = 15
Fri Jun 03 16:04:14 2011 us=96000 packet_id_file = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=96000 use_iv = ENABLED
Fri Jun 03 16:04:14 2011 us=96000 test_crypto = DISABLED
Fri Jun 03 16:04:14 2011 us=96000 tls_server = DISABLED
Fri Jun 03 16:04:14 2011 us=96000 tls_client = ENABLED
Fri Jun 03 16:04:14 2011 us=96000 key_method = 2
Fri Jun 03 16:04:14 2011 us=96000 ca_file = 'C:\Program Files (x86)\OpenVPN\keys\ca.crt'
Fri Jun 03 16:04:14 2011 us=96000 ca_path = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=96000 dh_file = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=96000 cert_file = 'C:\Program Files (x86)\OpenVPN\keys\xyz.crt'
Fri Jun 03 16:04:14 2011 us=96000 priv_key_file = 'C:\Program Files (x86)\OpenVPN\keys\xyz.key'
Fri Jun 03 16:04:14 2011 us=96000 pkcs12_file = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=127000 cryptoapi_cert = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=127000 cipher_list = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=127000 tls_verify = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=127000 tls_export_cert = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=127000 tls_remote = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=127000 crl_file = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=127000 ns_cert_type = 64
Fri Jun 03 16:04:14 2011 us=127000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=127000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=127000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=127000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=127000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=127000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=127000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=127000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=127000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=174000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=174000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=174000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=174000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=174000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=174000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=174000 remote_cert_ku[i] = 0
Fri Jun 03 16:04:14 2011 us=174000 remote_cert_eku = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=174000 tls_timeout = 2
Fri Jun 03 16:04:14 2011 us=174000 renegotiate_bytes = 0
Fri Jun 03 16:04:14 2011 us=174000 renegotiate_packets = 0
Fri Jun 03 16:04:14 2011 us=174000 renegotiate_seconds = 3600
Fri Jun 03 16:04:14 2011 us=174000 handshake_window = 60
Fri Jun 03 16:04:14 2011 us=174000 transition_window = 3600
Fri Jun 03 16:04:14 2011 us=174000 single_session = DISABLED
Fri Jun 03 16:04:14 2011 us=174000 push_peer_info = DISABLED
Fri Jun 03 16:04:14 2011 us=205000 tls_exit = DISABLED
Fri Jun 03 16:04:14 2011 us=205000 tls_auth_file = 'C:\Program Files (x86)\OpenVPN\keys\ta.key'
Fri Jun 03 16:04:14 2011 us=205000 server_network = 0.0.0.0
Fri Jun 03 16:04:14 2011 us=205000 server_netmask = 0.0.0.0
Fri Jun 03 16:04:14 2011 us=205000 server_bridge_ip = 0.0.0.0
Fri Jun 03 16:04:14 2011 us=205000 server_bridge_netmask = 0.0.0.0
Fri Jun 03 16:04:14 2011 us=205000 server_bridge_pool_start = 0.0.0.0
Fri Jun 03 16:04:14 2011 us=205000 server_bridge_pool_end = 0.0.0.0
Fri Jun 03 16:04:14 2011 us=205000 ifconfig_pool_defined = DISABLED
Fri Jun 03 16:04:14 2011 us=205000 ifconfig_pool_start = 0.0.0.0
Fri Jun 03 16:04:14 2011 us=205000 ifconfig_pool_end = 0.0.0.0
Fri Jun 03 16:04:14 2011 us=205000 ifconfig_pool_netmask = 0.0.0.0
Fri Jun 03 16:04:14 2011 us=205000 ifconfig_pool_persist_filename = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=205000 ifconfig_pool_persist_refresh_freq = 600
Fri Jun 03 16:04:14 2011 us=252000 n_bcast_buf = 256
Fri Jun 03 16:04:14 2011 us=252000 tcp_queue_limit = 64
Fri Jun 03 16:04:14 2011 us=252000 real_hash_size = 256
Fri Jun 03 16:04:14 2011 us=252000 virtual_hash_size = 256
Fri Jun 03 16:04:14 2011 us=252000 client_connect_script = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=252000 learn_address_script = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=252000 client_disconnect_script = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=252000 client_config_dir = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=252000 ccd_exclusive = DISABLED
Fri Jun 03 16:04:14 2011 us=252000 tmp_dir = 'C:\Users\xyz\AppData\Local\Temp\'
Fri Jun 03 16:04:14 2011 us=252000 push_ifconfig_defined = DISABLED
Fri Jun 03 16:04:14 2011 us=252000 push_ifconfig_local = 0.0.0.0
Fri Jun 03 16:04:14 2011 us=252000 push_ifconfig_remote_netmask = 0.0.0.0
Fri Jun 03 16:04:14 2011 us=252000 enable_c2c = DISABLED
Fri Jun 03 16:04:14 2011 us=252000 duplicate_cn = DISABLED
Fri Jun 03 16:04:14 2011 us=283000 cf_max = 0
Fri Jun 03 16:04:14 2011 us=283000 cf_per = 0
Fri Jun 03 16:04:14 2011 us=283000 max_clients = 1024
Fri Jun 03 16:04:14 2011 us=283000 max_routes_per_client = 256
Fri Jun 03 16:04:14 2011 us=283000 auth_user_pass_verify_script = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=283000 auth_user_pass_verify_script_via_file = DISABLED
Fri Jun 03 16:04:14 2011 us=283000 ssl_flags = 0
Fri Jun 03 16:04:14 2011 us=283000 client = ENABLED
Fri Jun 03 16:04:14 2011 us=283000 pull = ENABLED
Fri Jun 03 16:04:14 2011 us=283000 auth_user_pass_file = 'stdin'
Fri Jun 03 16:04:14 2011 us=283000 show_net_up = DISABLED
Fri Jun 03 16:04:14 2011 us=283000 route_method = 0
Fri Jun 03 16:04:14 2011 us=283000 ip_win32_defined = DISABLED
Fri Jun 03 16:04:14 2011 us=283000 ip_win32_type = 3
Fri Jun 03 16:04:14 2011 us=283000 dhcp_masq_offset = 0
Fri Jun 03 16:04:14 2011 us=315000 dhcp_lease_time = 31536000
Fri Jun 03 16:04:14 2011 us=315000 tap_sleep = 0
Fri Jun 03 16:04:14 2011 us=315000 dhcp_options = DISABLED
Fri Jun 03 16:04:14 2011 us=315000 dhcp_renew = DISABLED
Fri Jun 03 16:04:14 2011 us=315000 dhcp_pre_release = DISABLED
Fri Jun 03 16:04:14 2011 us=315000 dhcp_release = DISABLED
Fri Jun 03 16:04:14 2011 us=315000 domain = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=315000 netbios_scope = '[UNDEF]'
Fri Jun 03 16:04:14 2011 us=315000 netbios_node_type = 0
Fri Jun 03 16:04:14 2011 us=315000 disable_nbt = DISABLED
Fri Jun 03 16:04:14 2011 us=315000 OpenVPN 2.2.0 Win32-MSVC++ [SSL] [LZO2] built on Apr 26 2011
Fri Jun 03 16:04:21 2011 us=974000 NOTE: OpenVPN 2.1 requires '--script-security 2' or higher to call user-defined scripts or executables
Fri Jun 03 16:04:22 2011 us=286000 PRNG init md=SHA1 size=36
Fri Jun 03 16:04:22 2011 us=286000 Control Channel Authentication: using 'C:\Program Files (x86)\OpenVPN\keys\ta.key' as a OpenVPN static key file
Fri Jun 03 16:04:22 2011 us=286000 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jun 03 16:04:22 2011 us=286000 Outgoing Control Channel Authentication: HMAC KEY: 8792773b 0cf3fa63 37da5011 c451e769 a7f405d8
Fri Jun 03 16:04:22 2011 us=286000 Outgoing Control Channel Authentication: HMAC size=20 block_size=64
Fri Jun 03 16:04:22 2011 us=286000 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jun 03 16:04:22 2011 us=286000 Incoming Control Channel Authentication: HMAC KEY: 3f23ae99 3460e1a6 2e99a865 41d88ff4 b5f2ac42
Fri Jun 03 16:04:22 2011 us=286000 Incoming Control Channel Authentication: HMAC size=20 block_size=64
Fri Jun 03 16:04:22 2011 us=286000 LZO compression initialized
Fri Jun 03 16:04:22 2011 us=286000 MTU DYNAMIC mtu=0, flags=1, 0 -> 166
Fri Jun 03 16:04:22 2011 us=286000 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jun 03 16:04:22 2011 us=286000 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jun 03 16:04:22 2011 us=286000 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jun 03 16:04:22 2011 us=286000 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jun 03 16:04:22 2011 us=286000 Control Channel MTU parms [ L:1542 D:166 EF:66 EB:0 ET:0 EL:0 ]
Fri Jun 03 16:04:22 2011 us=286000 MTU DYNAMIC mtu=1450, flags=2, 1542 -> 1450
Fri Jun 03 16:04:22 2011 us=286000 Socket Buffers: R=[8192->8192] S=[8192->8192]
Fri Jun 03 16:04:22 2011 us=286000 RESOLVE_REMOTE flags=0x0101 phase=1 rrs=0 sig=-1 status=1
Fri Jun 03 16:04:22 2011 us=286000 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
Fri Jun 03 16:04:22 2011 us=286000 Local Options String: 'V4,dev-type [unknown-dev-type],link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 1,cipher BF-CBC,auth SHA1,keysize 128,tls-auth,key-method 2,tls-client'
Fri Jun 03 16:04:22 2011 us=286000 Expected Remote Options String: 'V4,dev-type [unknown-dev-type],link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 0,cipher BF-CBC,auth SHA1,keysize 128,tls-auth,key-method 2,tls-server'
Fri Jun 03 16:04:22 2011 us=286000 Local Options hash (VER=V4): '370ab41f'
Fri Jun 03 16:04:22 2011 us=286000 Expected Remote Options hash (VER=V4): 'f73eddde'
Fri Jun 03 16:04:22 2011 us=286000 UDPv4 link local: [undef]
Fri Jun 03 16:04:22 2011 us=286000 UDPv4 link remote: xxx.xxx.xxx.xxx:8000
Fri Jun 03 16:04:22 2011 us=286000 UDPv4 WRITE [42] to xxx.xxx.xxx.xxx:8000: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #1 ] [ ] pid=0 DATA len=0
Fri Jun 03 16:04:22 2011 us=286000 UDPv4 READ [0] from [undef]: DATA UNDEF len=-1
Fri Jun 03 16:04:24 2011 us=751000 UDPv4 WRITE [42] to xxx.xxx.xxx.xxx:8000: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #2 ] [ ] pid=0 DATA len=0
Fri Jun 03 16:04:28 2011 us=433000 UDPv4 WRITE [42] to xxx.xxx.xxx.xxx:8000: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #3 ] [ ] pid=0 DATA len=0
Fri Jun 03 16:04:36 2011 us=966000 UDPv4 WRITE [42] to xxx.xxx.xxx.xxx:8000: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #4 ] [ ] pid=0 DATA len=0
Fri Jun 03 16:04:52 2011 us=972000 UDPv4 WRITE [42] to xxx.xxx.xxx.xxx:8000: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #5 ] [ ] pid=0 DATA len=0
Fri Jun 03 16:05:23 2011 us=33000 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Fri Jun 03 16:05:23 2011 us=33000 TLS Error: TLS handshake failed
Fri Jun 03 16:05:23 2011 us=33000 PID packet_id_free
Fri Jun 03 16:05:23 2011 us=33000 PID packet_id_free
Fri Jun 03 16:05:23 2011 us=33000 PID packet_id_free
Fri Jun 03 16:05:23 2011 us=33000 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jun 03 16:05:23 2011 us=33000 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jun 03 16:05:23 2011 us=33000 PID packet_id_free
Fri Jun 03 16:05:23 2011 us=33000 PID packet_id_free
Fri Jun 03 16:05:23 2011 us=33000 PID packet_id_free
Fri Jun 03 16:05:23 2011 us=33000 PID packet_id_free
Fri Jun 03 16:05:23 2011 us=33000 PID packet_id_free
Fri Jun 03 16:05:23 2011 us=33000 PID packet_id_free
Fri Jun 03 16:05:23 2011 us=33000 PID packet_id_free
Fri Jun 03 16:05:23 2011 us=33000 PID packet_id_free
Fri Jun 03 16:05:23 2011 us=33000 TCP/UDP: Closing socket
Fri Jun 03 16:05:23 2011 us=33000 PID packet_id_free
Fri Jun 03 16:05:23 2011 us=33000 SIGUSR1[soft,tls-error] received, process restarting
Fri Jun 03 16:05:23 2011 us=33000 Restart pause, 2 second(s)
Fri Jun 03 16:05:25 2011 us=45000 NOTE: OpenVPN 2.1 requires '--script-security 2' or higher to call user-defined scripts or executables
Fri Jun 03 16:05:25 2011 us=45000 Re-using SSL/TLS context
Fri Jun 03 16:05:25 2011 us=45000 LZO compression initialized
Fri Jun 03 16:05:25 2011 us=45000 MTU DYNAMIC mtu=0, flags=1, 0 -> 166
Fri Jun 03 16:05:25 2011 us=45000 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jun 03 16:05:25 2011 us=45000 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jun 03 16:05:25 2011 us=45000 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jun 03 16:05:25 2011 us=45000 PID packet_id_init seq_backtrack=64 time_backtrack=15
Fri Jun 03 16:05:25 2011 us=45000 Control Channel MTU parms [ L:1542 D:166 EF:66 EB:0 ET:0 EL:0 ]
Fri Jun 03 16:05:25 2011 us=45000 MTU DYNAMIC mtu=1450, flags=2, 1542 -> 1450
Fri Jun 03 16:05:25 2011 us=45000 Socket Buffers: R=[8192->8192] S=[8192->8192]
Fri Jun 03 16:05:25 2011 us=45000 RESOLVE_REMOTE flags=0x0101 phase=1 rrs=0 sig=-1 status=1
Fri Jun 03 16:05:25 2011 us=45000 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
Fri Jun 03 16:05:25 2011 us=45000 Local Options String: 'V4,dev-type [unknown-dev-type],link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 1,cipher BF-CBC,auth SHA1,keysize 128,tls-auth,key-method 2,tls-client'
Fri Jun 03 16:05:25 2011 us=45000 Expected Remote Options String: 'V4,dev-type [unknown-dev-type],link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 0,cipher BF-CBC,auth SHA1,keysize 128,tls-auth,key-method 2,tls-server'
Fri Jun 03 16:05:25 2011 us=45000 Local Options hash (VER=V4): '370ab41f'
Fri Jun 03 16:05:25 2011 us=45000 Expected Remote Options hash (VER=V4): 'f73eddde'
Fri Jun 03 16:05:25 2011 us=45000 UDPv4 link local: [undef]
Fri Jun 03 16:05:25 2011 us=45000 UDPv4 link remote: xxx.xxx.xxx.xxx:8000
Fri Jun 03 16:05:25 2011 us=45000 UDPv4 WRITE [42] to xxx.xxx.xxx.xxx:8000: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #1 ] [ ] pid=0 DATA len=0
Fri Jun 03 16:05:25 2011 us=45000 UDPv4 READ [0] from [undef]: DATA UNDEF len=-1
Fri Jun 03 16:05:27 2011 us=58000 UDPv4 WRITE [42] to xxx.xxx.xxx.xxx:8000: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 pid=[ #2 ] [ ] pid=0 DATA len=0
