Page 1 of 1

Windows7 not using Interface

Posted: Fri Jun 03, 2011 3:47 pm
by drk
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:

Code: Select all

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:

Code: Select all

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:

Code: Select all

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:

Code: Select all

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