TLS handshake failed

Need help configuring your VPN? Just post here and you'll get that help.

Moderators: TinCanTech, TinCanTech, TinCanTech, TinCanTech, TinCanTech, TinCanTech

Forum rules
Please use the [oconf] BB tag for openvpn Configurations. See viewtopic.php?f=30&t=21589 for an example.
Post Reply
Bransonb3
OpenVpn Newbie
Posts: 4
Joined: Sat Jan 06, 2018 3:38 pm

TLS handshake failed

Post by Bransonb3 » Sat Jan 06, 2018 4:57 pm

When I try to connect to my openvpn server I get TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity) and TLS Error: TLS handshake failed. It looks like the server sees the client try to connect (TLS: Initial packet from...) but doesn't respond.

I'm running openvpn 2.4.4 on Windows Server 2016 1607. I am trying to connect my Mac running OSX 10.13.2, using Tunnelblick 3.7.4b but have also tried connecting using the openvpn connect android app. I have port forwarded 1194 udp to my server, made an inbound and outbound windows firewall rule allowing openvpn.exe, allowed tunnelblick incoming connections on the mac firewall. I have also tried to run the server as admin.

Server
1
port 1194
2
proto udp
3
dev tap
4
dev-node Ethernet_7
5
ca "C:\\Program Files\\OpenVPN\\config\\ca.crt"
6
cert "C:\\Program Files\\OpenVPN\\config\\server.crt"
7
key "C:\\Program Files\\OpenVPN\\config\\server.key"
8
dh "C:\\Program Files\\OpenVPN\\config\\dh4096.pem"
9
topology subnet
10
ifconfig-pool-persist ipp.txt
11
server-bridge 192.168.1.227 255.255.0.0 192.168.3.1 192.168.3.254
12
client-to-client
13
keepalive 10 120
14
cipher AES-256-CBC
15
persist-key
16
persist-tun
17
status openvpn-status.log
18
verb 3

Server Log

Code: Select all

Sat Jan 06 11:04:18 2018 us=101200 Current Parameter Settings:
Sat Jan 06 11:04:18 2018 us=101200   config = 'server.ovpn'
Sat Jan 06 11:04:18 2018 us=101200   mode = 1
Sat Jan 06 11:04:18 2018 us=101200   show_ciphers = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   show_digests = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   show_engines = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   genkey = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   key_pass_file = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   show_tls_ciphers = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   connect_retry_max = 0
Sat Jan 06 11:04:18 2018 us=101200 Connection profiles [0]:
Sat Jan 06 11:04:18 2018 us=101200   proto = udp
Sat Jan 06 11:04:18 2018 us=101200   local = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   local_port = '1194'
Sat Jan 06 11:04:18 2018 us=101200   remote = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   remote_port = '1194'
Sat Jan 06 11:04:18 2018 us=101200   remote_float = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   bind_defined = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   bind_local = ENABLED
Sat Jan 06 11:04:18 2018 us=101200   bind_ipv6_only = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   connect_retry_seconds = 5
Sat Jan 06 11:04:18 2018 us=101200   connect_timeout = 120
Sat Jan 06 11:04:18 2018 us=101200   socks_proxy_server = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   socks_proxy_port = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   tun_mtu = 1500
Sat Jan 06 11:04:18 2018 us=101200   tun_mtu_defined = ENABLED
Sat Jan 06 11:04:18 2018 us=101200   link_mtu = 1500
Sat Jan 06 11:04:18 2018 us=101200   link_mtu_defined = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   tun_mtu_extra = 32
Sat Jan 06 11:04:18 2018 us=101200   tun_mtu_extra_defined = ENABLED
Sat Jan 06 11:04:18 2018 us=101200   mtu_discover_type = -1
Sat Jan 06 11:04:18 2018 us=101200   fragment = 0
Sat Jan 06 11:04:18 2018 us=101200   mssfix = 1450
Sat Jan 06 11:04:18 2018 us=101200   explicit_exit_notification = 1
Sat Jan 06 11:04:18 2018 us=101200 Connection profiles END
Sat Jan 06 11:04:18 2018 us=101200   remote_random = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   ipchange = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   dev = 'tap'
Sat Jan 06 11:04:18 2018 us=101200   dev_type = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   dev_node = 'Ethernet_7'
Sat Jan 06 11:04:18 2018 us=101200   lladdr = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   topology = 3
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_local = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_remote_netmask = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_noexec = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_nowarn = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_ipv6_local = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_ipv6_netbits = 0
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_ipv6_remote = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   shaper = 0
Sat Jan 06 11:04:18 2018 us=101200   mtu_test = 0
Sat Jan 06 11:04:18 2018 us=101200   mlock = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   keepalive_ping = 10
Sat Jan 06 11:04:18 2018 us=101200   keepalive_timeout = 120
Sat Jan 06 11:04:18 2018 us=101200   inactivity_timeout = 0
Sat Jan 06 11:04:18 2018 us=101200   ping_send_timeout = 10
Sat Jan 06 11:04:18 2018 us=101200   ping_rec_timeout = 240
Sat Jan 06 11:04:18 2018 us=101200   ping_rec_timeout_action = 2
Sat Jan 06 11:04:18 2018 us=101200   ping_timer_remote = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   remap_sigusr1 = 0
Sat Jan 06 11:04:18 2018 us=101200   persist_tun = ENABLED
Sat Jan 06 11:04:18 2018 us=101200   persist_local_ip = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   persist_remote_ip = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   persist_key = ENABLED
Sat Jan 06 11:04:18 2018 us=101200   passtos = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   resolve_retry_seconds = 1000000000
Sat Jan 06 11:04:18 2018 us=101200   resolve_in_advance = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   username = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   groupname = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   chroot_dir = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   cd_dir = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   writepid = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   up_script = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   down_script = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   down_pre = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   up_restart = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   up_delay = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   daemon = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   inetd = 0
Sat Jan 06 11:04:18 2018 us=101200   log = ENABLED
Sat Jan 06 11:04:18 2018 us=101200   suppress_timestamps = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   machine_readable_output = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   nice = 0
Sat Jan 06 11:04:18 2018 us=101200   verbosity = 4
Sat Jan 06 11:04:18 2018 us=101200   mute = 0
Sat Jan 06 11:04:18 2018 us=101200   gremlin = 0
Sat Jan 06 11:04:18 2018 us=101200   status_file = 'openvpn-status.log'
Sat Jan 06 11:04:18 2018 us=101200   status_file_version = 1
Sat Jan 06 11:04:18 2018 us=101200   status_file_update_freq = 60
Sat Jan 06 11:04:18 2018 us=101200   occ = ENABLED
Sat Jan 06 11:04:18 2018 us=101200   rcvbuf = 0
Sat Jan 06 11:04:18 2018 us=101200   sndbuf = 0
Sat Jan 06 11:04:18 2018 us=101200   sockflags = 0
Sat Jan 06 11:04:18 2018 us=101200   fast_io = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   comp.alg = 0
Sat Jan 06 11:04:18 2018 us=101200   comp.flags = 0
Sat Jan 06 11:04:18 2018 us=101200   route_script = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   route_default_gateway = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   route_default_metric = 0
Sat Jan 06 11:04:18 2018 us=101200   route_noexec = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   route_delay = 0
Sat Jan 06 11:04:18 2018 us=101200   route_delay_window = 30
Sat Jan 06 11:04:18 2018 us=101200   route_delay_defined = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   route_nopull = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   route_gateway_via_dhcp = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   allow_pull_fqdn = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   management_addr = '127.0.0.1'
Sat Jan 06 11:04:18 2018 us=101200   management_port = '25340'
Sat Jan 06 11:04:18 2018 us=101200   management_user_pass = 'stdin'
Sat Jan 06 11:04:18 2018 us=101200   management_log_history_cache = 250
Sat Jan 06 11:04:18 2018 us=101200   management_echo_buffer_size = 100
Sat Jan 06 11:04:18 2018 us=101200   management_write_peer_info_file = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   management_client_user = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   management_client_group = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   management_flags = 6
Sat Jan 06 11:04:18 2018 us=101200   shared_secret_file = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   key_direction = 0
Sat Jan 06 11:04:18 2018 us=101200   ciphername = 'AES-256-CBC'
Sat Jan 06 11:04:18 2018 us=101200   ncp_enabled = ENABLED
Sat Jan 06 11:04:18 2018 us=101200   ncp_ciphers = 'AES-256-GCM:AES-128-GCM'
Sat Jan 06 11:04:18 2018 us=101200   authname = 'SHA1'
Sat Jan 06 11:04:18 2018 us=101200   prng_hash = 'SHA1'
Sat Jan 06 11:04:18 2018 us=101200   prng_nonce_secret_len = 16
Sat Jan 06 11:04:18 2018 us=101200   keysize = 0
Sat Jan 06 11:04:18 2018 us=101200   engine = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   replay = ENABLED
Sat Jan 06 11:04:18 2018 us=101200   mute_replay_warnings = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   replay_window = 64
Sat Jan 06 11:04:18 2018 us=101200   replay_time = 15
Sat Jan 06 11:04:18 2018 us=101200   packet_id_file = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   use_iv = ENABLED
Sat Jan 06 11:04:18 2018 us=101200   test_crypto = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   tls_server = ENABLED
Sat Jan 06 11:04:18 2018 us=101200   tls_client = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   key_method = 2
Sat Jan 06 11:04:18 2018 us=101200   ca_file = 'C:\Program Files\OpenVPN\config\ca.crt'
Sat Jan 06 11:04:18 2018 us=101200   ca_path = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   dh_file = 'C:\Program Files\OpenVPN\config\dh4096.pem'
Sat Jan 06 11:04:18 2018 us=101200   cert_file = 'C:\Program Files\OpenVPN\config\server.crt'
Sat Jan 06 11:04:18 2018 us=101200   extra_certs_file = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   priv_key_file = 'C:\Program Files\OpenVPN\config\server.key'
Sat Jan 06 11:04:18 2018 us=101200   pkcs12_file = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   cryptoapi_cert = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   cipher_list = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   tls_verify = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   tls_export_cert = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   verify_x509_type = 0
Sat Jan 06 11:04:18 2018 us=101200   verify_x509_name = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   crl_file = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   ns_cert_type = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_ku[i] = 0
Sat Jan 06 11:04:18 2018 us=101200   remote_cert_eku = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   ssl_flags = 0
Sat Jan 06 11:04:18 2018 us=101200   tls_timeout = 2
Sat Jan 06 11:04:18 2018 us=101200   renegotiate_bytes = -1
Sat Jan 06 11:04:18 2018 us=101200   renegotiate_packets = 0
Sat Jan 06 11:04:18 2018 us=101200   renegotiate_seconds = 3600
Sat Jan 06 11:04:18 2018 us=101200   handshake_window = 60
Sat Jan 06 11:04:18 2018 us=101200   transition_window = 3600
Sat Jan 06 11:04:18 2018 us=101200   single_session = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   push_peer_info = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   tls_exit = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   tls_auth_file = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   tls_crypt_file = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_protected_authentication = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_private_mode = 00000000
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_cert_private = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_pin_cache_period = -1
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_id = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   pkcs11_id_management = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   server_network = 0.0.0.0
Sat Jan 06 11:04:18 2018 us=101200   server_netmask = 0.0.0.0
Sat Jan 06 11:04:18 2018 us=101200   server_network_ipv6 = ::
Sat Jan 06 11:04:18 2018 us=101200   server_netbits_ipv6 = 0
Sat Jan 06 11:04:18 2018 us=101200   server_bridge_ip = 192.168.1.227
Sat Jan 06 11:04:18 2018 us=101200   server_bridge_netmask = 255.255.0.0
Sat Jan 06 11:04:18 2018 us=101200   server_bridge_pool_start = 192.168.3.1
Sat Jan 06 11:04:18 2018 us=101200   server_bridge_pool_end = 192.168.3.254
Sat Jan 06 11:04:18 2018 us=101200   push_entry = 'route-gateway 192.168.1.227'
Sat Jan 06 11:04:18 2018 us=101200   push_entry = 'ping 10'
Sat Jan 06 11:04:18 2018 us=101200   push_entry = 'ping-restart 120'
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_pool_defined = ENABLED
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_pool_start = 192.168.3.1
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_pool_end = 192.168.3.254
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_pool_netmask = 255.255.0.0
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_pool_persist_filename = 'ipp.txt'
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_pool_persist_refresh_freq = 600
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_ipv6_pool_defined = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_ipv6_pool_base = ::
Sat Jan 06 11:04:18 2018 us=101200   ifconfig_ipv6_pool_netbits = 0
Sat Jan 06 11:04:18 2018 us=101200   n_bcast_buf = 256
Sat Jan 06 11:04:18 2018 us=101200   tcp_queue_limit = 64
Sat Jan 06 11:04:18 2018 us=101200   real_hash_size = 256
Sat Jan 06 11:04:18 2018 us=101200   virtual_hash_size = 256
Sat Jan 06 11:04:18 2018 us=101200   client_connect_script = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   learn_address_script = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   client_disconnect_script = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   client_config_dir = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   ccd_exclusive = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   tmp_dir = 'C:\Users\BRANTE~1\AppData\Local\Temp\'
Sat Jan 06 11:04:18 2018 us=101200   push_ifconfig_defined = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   push_ifconfig_local = 0.0.0.0
Sat Jan 06 11:04:18 2018 us=101200   push_ifconfig_remote_netmask = 0.0.0.0
Sat Jan 06 11:04:18 2018 us=101200   push_ifconfig_ipv6_defined = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   push_ifconfig_ipv6_local = ::/0
Sat Jan 06 11:04:18 2018 us=101200   push_ifconfig_ipv6_remote = ::
Sat Jan 06 11:04:18 2018 us=101200   enable_c2c = ENABLED
Sat Jan 06 11:04:18 2018 us=101200   duplicate_cn = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   cf_max = 0
Sat Jan 06 11:04:18 2018 us=101200   cf_per = 0
Sat Jan 06 11:04:18 2018 us=101200   max_clients = 1024
Sat Jan 06 11:04:18 2018 us=101200   max_routes_per_client = 256
Sat Jan 06 11:04:18 2018 us=101200   auth_user_pass_verify_script = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   auth_user_pass_verify_script_via_file = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   auth_token_generate = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   auth_token_lifetime = 0
Sat Jan 06 11:04:18 2018 us=101200   client = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   pull = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   auth_user_pass_file = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   show_net_up = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   route_method = 3
Sat Jan 06 11:04:18 2018 us=101200   block_outside_dns = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   ip_win32_defined = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   ip_win32_type = 3
Sat Jan 06 11:04:18 2018 us=101200   dhcp_masq_offset = 0
Sat Jan 06 11:04:18 2018 us=101200   dhcp_lease_time = 31536000
Sat Jan 06 11:04:18 2018 us=101200   tap_sleep = 10
Sat Jan 06 11:04:18 2018 us=101200   dhcp_options = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   dhcp_renew = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   dhcp_pre_release = DISABLED
Sat Jan 06 11:04:18 2018 us=101200   domain = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   netbios_scope = '[UNDEF]'
Sat Jan 06 11:04:18 2018 us=101200   netbios_node_type = 0
Sat Jan 06 11:04:18 2018 us=101200   disable_nbt = DISABLED
Sat Jan 06 11:04:18 2018 us=101200 OpenVPN 2.4.4 x86_64-w64-mingw32 [SSL (OpenSSL)] [LZO] [LZ4] [PKCS11] [AEAD] built on Sep 26 2017
Sat Jan 06 11:04:18 2018 us=101200 Windows version 6.2 (Windows 8 or greater) 64bit
Sat Jan 06 11:04:18 2018 us=101200 library versions: OpenSSL 1.0.2l  25 May 2017, LZO 2.10
Enter Management Password:
Sat Jan 06 11:04:18 2018 us=101200 MANAGEMENT: TCP Socket listening on [AF_INET]127.0.0.1:25340
Sat Jan 06 11:04:18 2018 us=101200 Need hold release from management interface, waiting...
Sat Jan 06 11:04:18 2018 us=523173 MANAGEMENT: Client connected from [AF_INET]127.0.0.1:25340
Sat Jan 06 11:04:18 2018 us=632544 MANAGEMENT: CMD 'state on'
Sat Jan 06 11:04:18 2018 us=663744 MANAGEMENT: CMD 'log all on'
Sat Jan 06 11:04:21 2018 us=804606 MANAGEMENT: CMD 'echo all on'
Sat Jan 06 11:04:21 2018 us=835859 MANAGEMENT: CMD 'hold off'
Sat Jan 06 11:04:21 2018 us=867111 MANAGEMENT: CMD 'hold release'
Sat Jan 06 11:04:21 2018 us=867111 NOTE: when bridging your LAN adapter with the TAP adapter, note that the new bridge adapter will often take on its own IP address that is different from what the LAN adapter was previously set to
Sat Jan 06 11:04:21 2018 us=867111 NOTE: your local LAN uses the extremely common subnet address 192.168.0.x or 192.168.1.x.  Be aware that this might create routing conflicts if you connect to the VPN server from public locations such as internet cafes that use the same subnet.
Sat Jan 06 11:04:22 2018 us=70303 Diffie-Hellman initialized with 4096 bit key
Sat Jan 06 11:04:22 2018 us=70303 TLS-Auth MTU parms [ L:1653 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Sat Jan 06 11:04:22 2018 us=70303 interactive service msg_channel=708
Sat Jan 06 11:04:22 2018 us=70303 open_tun
Sat Jan 06 11:04:22 2018 us=70303 TAP-WIN32 device [Ethernet_7] opened: \\.\Global\{8F042EC3-C372-4CBE-B6DE-0F7F2CF6C408}.tap
Sat Jan 06 11:04:22 2018 us=70303 TAP-Windows Driver Version 9.21 
Sat Jan 06 11:04:22 2018 us=70303 TAP-Windows MTU=1500
Sat Jan 06 11:04:22 2018 us=70303 Sleeping for 10 seconds...
Sat Jan 06 11:04:32 2018 us=71080 NOTE: FlushIpNetTable failed on interface [20] {8F042EC3-C372-4CBE-B6DE-0F7F2CF6C408} (status=1168) : Element not found.  
Sat Jan 06 11:04:32 2018 us=71080 Data Channel MTU parms [ L:1653 D:1450 EF:121 EB:411 ET:32 EL:3 ]
Sat Jan 06 11:04:32 2018 us=71080 Could not determine IPv4/IPv6 protocol. Using AF_INET6
Sat Jan 06 11:04:32 2018 us=71080 Socket Buffers: R=[65536->65536] S=[65536->65536]
Sat Jan 06 11:04:32 2018 us=71080 setsockopt(IPV6_V6ONLY=0)
Sat Jan 06 11:04:32 2018 us=71080 UDPv6 link local (bound): [AF_INET6][undef]:1194
Sat Jan 06 11:04:32 2018 us=71080 UDPv6 link remote: [AF_UNSPEC]
Sat Jan 06 11:04:32 2018 us=71080 MULTI: multi_init called, r=256 v=256
Sat Jan 06 11:04:32 2018 us=71080 IFCONFIG POOL: base=192.168.3.1 size=254, ipv6=0
Sat Jan 06 11:04:32 2018 us=71080 IFCONFIG POOL LIST
Sat Jan 06 11:04:32 2018 us=71080 Initialization Sequence Completed
Sat Jan 06 11:04:32 2018 us=71080 MANAGEMENT: >STATE:1515254672,CONNECTED,SUCCESS,,,,,
Sat Jan 06 11:05:12 2018 us=902307 MULTI: multi_create_instance called
Sat Jan 06 11:05:12 2018 us=902307 192.168.1.1 Re-using SSL/TLS context
Sat Jan 06 11:05:12 2018 us=902307 192.168.1.1 Control Channel MTU parms [ L:1653 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Sat Jan 06 11:05:12 2018 us=902307 192.168.1.1 Data Channel MTU parms [ L:1653 D:1450 EF:121 EB:411 ET:32 EL:3 ]
Sat Jan 06 11:05:12 2018 us=902307 192.168.1.1 Local Options String (VER=V4): 'V4,dev-type tap,link-mtu 1589,tun-mtu 1532,proto UDPv4,cipher AES-256-CBC,auth SHA1,keysize 256,key-method 2,tls-server'
Sat Jan 06 11:05:12 2018 us=902307 192.168.1.1 Expected Remote Options String (VER=V4): 'V4,dev-type tap,link-mtu 1589,tun-mtu 1532,proto UDPv4,cipher AES-256-CBC,auth SHA1,keysize 256,key-method 2,tls-client'
Sat Jan 06 11:05:12 2018 us=902307 192.168.1.1 TLS: Initial packet from [AF_INET6]::ffff:192.168.1.1:65260, sid=6d3b6096 978372b7
Sat Jan 06 11:06:12 2018 us=375576 MULTI: multi_create_instance called
Sat Jan 06 11:06:12 2018 us=375576 192.168.1.1 Re-using SSL/TLS context
Sat Jan 06 11:06:12 2018 us=375576 192.168.1.1 Control Channel MTU parms [ L:1653 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Sat Jan 06 11:06:12 2018 us=375576 192.168.1.1 Data Channel MTU parms [ L:1653 D:1450 EF:121 EB:411 ET:32 EL:3 ]
Sat Jan 06 11:06:12 2018 us=375576 192.168.1.1 Local Options String (VER=V4): 'V4,dev-type tap,link-mtu 1589,tun-mtu 1532,proto UDPv4,cipher AES-256-CBC,auth SHA1,keysize 256,key-method 2,tls-server'
Sat Jan 06 11:06:12 2018 us=375576 192.168.1.1 Expected Remote Options String (VER=V4): 'V4,dev-type tap,link-mtu 1589,tun-mtu 1532,proto UDPv4,cipher AES-256-CBC,auth SHA1,keysize 256,key-method 2,tls-client'
Sat Jan 06 11:06:12 2018 us=375576 192.168.1.1 TLS: Initial packet from [AF_INET6]::ffff:192.168.1.1:49292, sid=954f86f6 6aa19d64
Sat Jan 06 11:06:12 2018 us=672475 192.168.1.1 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Sat Jan 06 11:06:12 2018 us=672475 192.168.1.1 TLS Error: TLS handshake failed
Sat Jan 06 11:06:12 2018 us=672475 192.168.1.1 SIGUSR1[soft,tls-error] received, client-instance restarting
Sat Jan 06 11:07:12 2018 us=536467 MULTI: multi_create_instance called
Sat Jan 06 11:07:12 2018 us=536467 192.168.1.1 Re-using SSL/TLS context
Sat Jan 06 11:07:12 2018 us=536467 192.168.1.1 Control Channel MTU parms [ L:1653 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Sat Jan 06 11:07:12 2018 us=536467 192.168.1.1 Data Channel MTU parms [ L:1653 D:1450 EF:121 EB:411 ET:32 EL:3 ]
Sat Jan 06 11:07:12 2018 us=536467 192.168.1.1 Local Options String (VER=V4): 'V4,dev-type tap,link-mtu 1589,tun-mtu 1532,proto UDPv4,cipher AES-256-CBC,auth SHA1,keysize 256,key-method 2,tls-server'
Sat Jan 06 11:07:12 2018 us=536467 192.168.1.1 Expected Remote Options String (VER=V4): 'V4,dev-type tap,link-mtu 1589,tun-mtu 1532,proto UDPv4,cipher AES-256-CBC,auth SHA1,keysize 256,key-method 2,tls-client'
Sat Jan 06 11:07:12 2018 us=536467 192.168.1.1 TLS: Initial packet from [AF_INET6]::ffff:192.168.1.1:57462, sid=53b26929 1c6575c7
Sat Jan 06 11:07:12 2018 us=583332 192.168.1.1 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Sat Jan 06 11:07:12 2018 us=583332 192.168.1.1 TLS Error: TLS handshake failed
Sat Jan 06 11:07:12 2018 us=583332 192.168.1.1 SIGUSR1[soft,tls-error] received, client-instance restarting
Sat Jan 06 11:07:29 2018 us=365918 SENT CONTROL [UNDEF]: 'RESTART' (status=1)
Sat Jan 06 11:07:29 2018 us=365918 TCP/UDP: Closing socket
Sat Jan 06 11:07:29 2018 us=365918 Closing TUN/TAP interface
Sat Jan 06 11:07:29 2018 us=381489 SIGTERM[hard,] received, process exiting
Sat Jan 06 11:07:29 2018 us=381489 MANAGEMENT: >STATE:1515254849,EXITING,SIGTERM,,,,,

Client
1
client
2
dev tun
3
proto udp
4
remote [Public IP] 1194
5
resolv-retry infinite
6
nobind
7
persist-key
8
persist-tun
9
<ca>
10
--STRIPPED INLINE CA CERT--
11
</ca>
12
<cert>
13
--STRIPPED INLINE CERT--
14
</cert>
15
<key>
16
--STRIPPED INLINE KEY--
17
</key>
18
cipher AES-256-CBC
19
verb 4

Client Log

Code: Select all

*Tunnelblick: OS X 10.13.2; Tunnelblick 3.7.4b (build 4921)
2018-01-06 11:05:12 *Tunnelblick: Attempting connection with BransonMac using shadow copy; Set nameserver = 769; monitoring connection
2018-01-06 11:05:12 *Tunnelblick: openvpnstart start BransonMac.tblk 1337 769 0 1 0 1065264 -ptADGNWradsgnw 2.4.4-openssl-1.0.2n
2018-01-06 11:05:12 *Tunnelblick: openvpnstart log:
     OpenVPN started successfully. Command used to start OpenVPN (one argument per displayed line):
     
          /Applications/Tunnelblick.app/Contents/Resources/openvpn/openvpn-2.4.4-openssl-1.0.2n/openvpn
          --daemon
          --log
          /Library/Application Support/Tunnelblick/Logs/-SUsers-Sbransonbuchanan-SLibrary-SApplication Support-STunnelblick-SConfigurations-SBransonMac.tblk-SContents-SResources-Sconfig.ovpn.769_0_1_0_1065264.1337.openvpn.log
          --cd
          /Library/Application Support/Tunnelblick/Users/bransonbuchanan/BransonMac.tblk/Contents/Resources
          --setenv
          IV_GUI_VER
          "net.tunnelblick.tunnelblick 4921 3.7.4b (build 4921)"
          --verb
          3
          --config
          /Library/Application Support/Tunnelblick/Users/bransonbuchanan/BransonMac.tblk/Contents/Resources/config.ovpn
          --verb
          3
          --cd
          /Library/Application Support/Tunnelblick/Users/bransonbuchanan/BransonMac.tblk/Contents/Resources
          --management
          127.0.0.1
          1337
          --management-query-passwords
          --management-hold
          --script-security
          2
          --up
          /Applications/Tunnelblick.app/Contents/Resources/client.up.tunnelblick.sh -9 -d -f -m -w -ptADGNWradsgnw
          --down
          /Applications/Tunnelblick.app/Contents/Resources/client.down.tunnelblick.sh -9 -d -f -m -w -ptADGNWradsgnw

2018-01-06 11:05:12 *Tunnelblick: Established communication with OpenVPN
2018-01-06 11:05:12 OpenVPN 2.4.4 x86_64-apple-darwin [SSL (OpenSSL)] [LZO] [LZ4] [PKCS11] [MH/RECVDA] [AEAD] built on Dec  7 2017
2018-01-06 11:05:12 library versions: OpenSSL 1.0.2n  7 Dec 2017, LZO 2.10
2018-01-06 11:05:12 MANAGEMENT: TCP Socket listening on [AF_INET]127.0.0.1:1337
2018-01-06 11:05:12 Need hold release from management interface, waiting...
2018-01-06 11:05:12 MANAGEMENT: Client connected from [AF_INET]127.0.0.1:1337
2018-01-06 11:05:12 MANAGEMENT: CMD 'pid'
2018-01-06 11:05:12 MANAGEMENT: CMD 'state on'
2018-01-06 11:05:12 MANAGEMENT: CMD 'state'
2018-01-06 11:05:12 MANAGEMENT: CMD 'bytecount 1'
2018-01-06 11:05:12 MANAGEMENT: CMD 'hold release'
2018-01-06 11:05:12 WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
2018-01-06 11:05:12 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
2018-01-06 11:05:12 MANAGEMENT: >STATE:1515254712,RESOLVE,,,,,,
2018-01-06 11:05:12 TCP/UDP: Preserving recently used remote address: [AF_INET][Public IP]:1194
2018-01-06 11:05:12 Socket Buffers: R=[196724->196724] S=[9216->9216]
2018-01-06 11:05:12 UDP link local: (not bound)
2018-01-06 11:05:12 UDP link remote: [AF_INET][Public IP]:1194
2018-01-06 11:05:12 MANAGEMENT: >STATE:1515254712,WAIT,,,,,,
2018-01-06 11:05:12 *Tunnelblick: openvpnstart starting OpenVPN
2018-01-06 11:06:12 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
2018-01-06 11:06:12 TLS Error: TLS handshake failed
2018-01-06 11:06:12 SIGUSR1[soft,tls-error] received, process restarting
2018-01-06 11:06:12 MANAGEMENT: >STATE:1515254772,RECONNECTING,tls-error,,,,,
2018-01-06 11:06:12 MANAGEMENT: CMD 'hold release'
2018-01-06 11:06:12 WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
2018-01-06 11:06:12 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
2018-01-06 11:06:12 MANAGEMENT: >STATE:1515254772,RESOLVE,,,,,,
2018-01-06 11:06:12 TCP/UDP: Preserving recently used remote address: [AF_INET][Public IP]:1194
2018-01-06 11:06:12 Socket Buffers: R=[196724->196724] S=[9216->9216]
2018-01-06 11:06:12 UDP link local: (not bound)
2018-01-06 11:06:12 UDP link remote: [AF_INET][Public IP]:1194
2018-01-06 11:06:12 MANAGEMENT: >STATE:1515254772,WAIT,,,,,,
2018-01-06 11:07:12 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
2018-01-06 11:07:12 TLS Error: TLS handshake failed
2018-01-06 11:07:12 SIGUSR1[soft,tls-error] received, process restarting
2018-01-06 11:07:12 MANAGEMENT: >STATE:1515254832,RECONNECTING,tls-error,,,,,
2018-01-06 11:07:12 MANAGEMENT: CMD 'hold release'
2018-01-06 11:07:12 WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
2018-01-06 11:07:12 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
2018-01-06 11:07:12 MANAGEMENT: >STATE:1515254832,RESOLVE,,,,,,
2018-01-06 11:07:12 TCP/UDP: Preserving recently used remote address: [AF_INET][Public IP]:1194
2018-01-06 11:07:12 Socket Buffers: R=[196724->196724] S=[9216->9216]
2018-01-06 11:07:12 UDP link local: (not bound)
2018-01-06 11:07:12 UDP link remote: [AF_INET][Public IP]:1194
2018-01-06 11:07:12 MANAGEMENT: >STATE:1515254832,WAIT,,,,,,
2018-01-06 11:07:16 *Tunnelblick: Disconnecting; VPN Details… window disconnect button pressed
2018-01-06 11:07:16 *Tunnelblick: No 'pre-disconnect.sh' script to execute
2018-01-06 11:07:16 *Tunnelblick: Disconnecting using 'kill'
2018-01-06 11:07:16 event_wait : Interrupted system call (code=4)
2018-01-06 11:07:16 SIGTERM[hard,] received, process exiting
2018-01-06 11:07:16 MANAGEMENT: >STATE:1515254836,EXITING,SIGTERM,,,,,
2018-01-06 11:07:17 *Tunnelblick: No 'post-disconnect.sh' script to execute
2018-01-06 11:07:17 *Tunnelblick: Expected disconnection occurred.

TinCanTech
OpenVPN Protagonist
Posts: 11139
Joined: Fri Jun 03, 2016 1:17 pm

Re: TLS handshake failed

Post by TinCanTech » Sat Jan 06, 2018 5:24 pm

You appear to be using IPv6:
Bransonb3 wrote:
Sat Jan 06, 2018 4:57 pm
Sat Jan 06 11:05:12 2018 us=902307 192.168.1.1 TLS: Initial packet from [AF_INET6]::ffff:192.168.1.1:65260, sid=6d3b6096 978372b7
and bridging an IPv4 network:
Bransonb3 wrote:
Sat Jan 06, 2018 4:57 pm
server-bridge 192.168.1.227 255.255.0.0 192.168.3.1 192.168.3.254
So .. sort out which protocol you are using.

Bransonb3
OpenVpn Newbie
Posts: 4
Joined: Sat Jan 06, 2018 3:38 pm

Re: TLS handshake failed

Post by Bransonb3 » Sat Jan 06, 2018 11:51 pm

I am trying to use IPv4. I read this article https://community.openvpn.net/openvpn/wiki/IPv6 and don't have any of the settings to use IPv6 in my configs. I disabled v6 on the bridge in windows and I'm still getting the INET6 in the sever log but the client log says it is using UDPv4.
Thanks for the help.

TinCanTech
OpenVPN Protagonist
Posts: 11139
Joined: Fri Jun 03, 2016 1:17 pm

Re: TLS handshake failed

Post by TinCanTech » Sun Jan 07, 2018 1:28 pm

Your server log shows something strange:
Bransonb3 wrote:
Sat Jan 06, 2018 4:57 pm
Sat Jan 06 11:04:32 2018 us=71080 Could not determine IPv4/IPv6 protocol. Using AF_INET6
Due to your network setup (probably) openvpn is choosing IPv6.

The simple way to force IPv4 is like so in both your configs:

Code: Select all

proto udp4
Try that ..

Bransonb3
OpenVpn Newbie
Posts: 4
Joined: Sat Jan 06, 2018 3:38 pm

Re: TLS handshake failed

Post by Bransonb3 » Sun Jan 07, 2018 2:34 pm

I changed the proto to UDPv4 on both the client and sever config, but I am still getting the TLS errors.


Server Log

Code: Select all

Sun Jan 07 09:27:13 2018   pkcs11_private_mode = 00000000
Sun Jan 07 09:27:13 2018   pkcs11_private_mode = 00000000
Sun Jan 07 09:27:13 2018   pkcs11_private_mode = 00000000
Sun Jan 07 09:27:13 2018   pkcs11_private_mode = 00000000
Sun Jan 07 09:27:13 2018   pkcs11_private_mode = 00000000
Sun Jan 07 09:27:13 2018   pkcs11_private_mode = 00000000
Sun Jan 07 09:27:13 2018   pkcs11_private_mode = 00000000
Sun Jan 07 09:27:13 2018   pkcs11_private_mode = 00000000
Sun Jan 07 09:27:13 2018   pkcs11_private_mode = 00000000
Sun Jan 07 09:27:13 2018   pkcs11_private_mode = 00000000
Sun Jan 07 09:27:13 2018   pkcs11_private_mode = 00000000
Sun Jan 07 09:27:13 2018   pkcs11_private_mode = 00000000
Sun Jan 07 09:27:13 2018   pkcs11_private_mode = 00000000
Sun Jan 07 09:27:13 2018   pkcs11_private_mode = 00000000
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_cert_private = DISABLED
Sun Jan 07 09:27:13 2018   pkcs11_pin_cache_period = -1
Sun Jan 07 09:27:13 2018   pkcs11_id = '[UNDEF]'
Sun Jan 07 09:27:13 2018   pkcs11_id_management = DISABLED
Sun Jan 07 09:27:13 2018   server_network = 0.0.0.0
Sun Jan 07 09:27:13 2018   server_netmask = 0.0.0.0
Sun Jan 07 09:27:13 2018   server_network_ipv6 = ::
Sun Jan 07 09:27:13 2018   server_netbits_ipv6 = 0
Sun Jan 07 09:27:13 2018   server_bridge_ip = 192.168.1.227
Sun Jan 07 09:27:13 2018   server_bridge_netmask = 255.255.0.0
Sun Jan 07 09:27:13 2018   server_bridge_pool_start = 192.168.3.1
Sun Jan 07 09:27:13 2018   server_bridge_pool_end = 192.168.3.254
Sun Jan 07 09:27:13 2018   push_entry = 'route-gateway 192.168.1.227'
Sun Jan 07 09:27:13 2018   push_entry = 'ping 10'
Sun Jan 07 09:27:13 2018   push_entry = 'ping-restart 120'
Sun Jan 07 09:27:13 2018   ifconfig_pool_defined = ENABLED
Sun Jan 07 09:27:13 2018   ifconfig_pool_start = 192.168.3.1
Sun Jan 07 09:27:13 2018   ifconfig_pool_end = 192.168.3.254
Sun Jan 07 09:27:13 2018   ifconfig_pool_netmask = 255.255.0.0
Sun Jan 07 09:27:13 2018   ifconfig_pool_persist_filename = 'ipp.txt'
Sun Jan 07 09:27:13 2018   ifconfig_pool_persist_refresh_freq = 600
Sun Jan 07 09:27:13 2018   ifconfig_ipv6_pool_defined = DISABLED
Sun Jan 07 09:27:13 2018   ifconfig_ipv6_pool_base = ::
Sun Jan 07 09:27:13 2018   ifconfig_ipv6_pool_netbits = 0
Sun Jan 07 09:27:13 2018   n_bcast_buf = 256
Sun Jan 07 09:27:13 2018   tcp_queue_limit = 64
Sun Jan 07 09:27:13 2018   real_hash_size = 256
Sun Jan 07 09:27:13 2018   virtual_hash_size = 256
Sun Jan 07 09:27:13 2018   client_connect_script = '[UNDEF]'
Sun Jan 07 09:27:13 2018   learn_address_script = '[UNDEF]'
Sun Jan 07 09:27:13 2018   client_disconnect_script = '[UNDEF]'
Sun Jan 07 09:27:13 2018   client_config_dir = '[UNDEF]'
Sun Jan 07 09:27:13 2018   ccd_exclusive = DISABLED
Sun Jan 07 09:27:13 2018   tmp_dir = 'C:\Users\BRANTE~1\AppData\Local\Temp\'
Sun Jan 07 09:27:13 2018   push_ifconfig_defined = DISABLED
Sun Jan 07 09:27:13 2018   push_ifconfig_local = 0.0.0.0
Sun Jan 07 09:27:13 2018   push_ifconfig_remote_netmask = 0.0.0.0
Sun Jan 07 09:27:13 2018   push_ifconfig_ipv6_defined = DISABLED
Sun Jan 07 09:27:13 2018   push_ifconfig_ipv6_local = ::/0
Sun Jan 07 09:27:13 2018   push_ifconfig_ipv6_remote = ::
Sun Jan 07 09:27:13 2018   enable_c2c = ENABLED
Sun Jan 07 09:27:13 2018   duplicate_cn = DISABLED
Sun Jan 07 09:27:13 2018   cf_max = 0
Sun Jan 07 09:27:13 2018   cf_per = 0
Sun Jan 07 09:27:13 2018   max_clients = 1024
Sun Jan 07 09:27:13 2018   max_routes_per_client = 256
Sun Jan 07 09:27:13 2018   auth_user_pass_verify_script = '[UNDEF]'
Sun Jan 07 09:27:13 2018   auth_user_pass_verify_script_via_file = DISABLED
Sun Jan 07 09:27:13 2018   auth_token_generate = DISABLED
Sun Jan 07 09:27:13 2018   auth_token_lifetime = 0
Sun Jan 07 09:27:13 2018   client = DISABLED
Sun Jan 07 09:27:13 2018   pull = DISABLED
Sun Jan 07 09:27:13 2018   auth_user_pass_file = '[UNDEF]'
Sun Jan 07 09:27:13 2018   show_net_up = DISABLED
Sun Jan 07 09:27:13 2018   route_method = 3
Sun Jan 07 09:27:13 2018   block_outside_dns = DISABLED
Sun Jan 07 09:27:13 2018   ip_win32_defined = DISABLED
Sun Jan 07 09:27:13 2018   ip_win32_type = 3
Sun Jan 07 09:27:13 2018   dhcp_masq_offset = 0
Sun Jan 07 09:27:13 2018   dhcp_lease_time = 31536000
Sun Jan 07 09:27:13 2018   tap_sleep = 10
Sun Jan 07 09:27:13 2018   dhcp_options = DISABLED
Sun Jan 07 09:27:13 2018   dhcp_renew = DISABLED
Sun Jan 07 09:27:13 2018   dhcp_pre_release = DISABLED
Sun Jan 07 09:27:13 2018   domain = '[UNDEF]'
Sun Jan 07 09:27:13 2018   netbios_scope = '[UNDEF]'
Sun Jan 07 09:27:13 2018   netbios_node_type = 0
Sun Jan 07 09:27:13 2018   disable_nbt = DISABLED
Sun Jan 07 09:27:13 2018 OpenVPN 2.4.4 x86_64-w64-mingw32 [SSL (OpenSSL)] [LZO] [LZ4] [PKCS11] [AEAD] built on Sep 26 2017
Sun Jan 07 09:27:13 2018 Windows version 6.2 (Windows 8 or greater) 64bit
Sun Jan 07 09:27:13 2018 library versions: OpenSSL 1.0.2l  25 May 2017, LZO 2.10
Sun Jan 07 09:27:13 2018 MANAGEMENT: TCP Socket listening on [AF_INET]127.0.0.1:25340
Sun Jan 07 09:27:13 2018 Need hold release from management interface, waiting...
Sun Jan 07 09:27:14 2018 MANAGEMENT: Client connected from [AF_INET]127.0.0.1:25340
Sun Jan 07 09:27:14 2018 MANAGEMENT: CMD 'state on'
Sun Jan 07 09:27:14 2018 MANAGEMENT: CMD 'log all on'
Sun Jan 07 09:27:17 2018 MANAGEMENT: CMD 'echo all on'
Sun Jan 07 09:27:17 2018 MANAGEMENT: CMD 'hold off'
Sun Jan 07 09:27:17 2018 MANAGEMENT: CMD 'hold release'
Sun Jan 07 09:27:17 2018 NOTE: when bridging your LAN adapter with the TAP adapter, note that the new bridge adapter will often take on its own IP address that is different from what the LAN adapter was previously set to
Sun Jan 07 09:27:17 2018 NOTE: your local LAN uses the extremely common subnet address 192.168.0.x or 192.168.1.x.  Be aware that this might create routing conflicts if you connect to the VPN server from public locations such as internet cafes that use the same subnet.
Sun Jan 07 09:27:17 2018 Diffie-Hellman initialized with 4096 bit key
Sun Jan 07 09:27:17 2018 TLS-Auth MTU parms [ L:1653 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Sun Jan 07 09:27:17 2018 interactive service msg_channel=512
Sun Jan 07 09:27:17 2018 open_tun
Sun Jan 07 09:27:17 2018 TAP-WIN32 device [Ethernet_7] opened: \\.\Global\{8F042EC3-C372-4CBE-B6DE-0F7F2CF6C408}.tap
Sun Jan 07 09:27:17 2018 TAP-Windows Driver Version 9.21 
Sun Jan 07 09:27:17 2018 TAP-Windows MTU=1500
Sun Jan 07 09:27:17 2018 Sleeping for 10 seconds...
Sun Jan 07 09:27:27 2018 NOTE: FlushIpNetTable failed on interface [21] {8F042EC3-C372-4CBE-B6DE-0F7F2CF6C408} (status=1168) : Element not found.  
Sun Jan 07 09:27:27 2018 Data Channel MTU parms [ L:1653 D:1450 EF:121 EB:411 ET:32 EL:3 ]
Sun Jan 07 09:27:27 2018 Socket Buffers: R=[65536->65536] S=[65536->65536]
Sun Jan 07 09:27:27 2018 UDPv4 link local (bound): [AF_INET][undef]:1194
Sun Jan 07 09:27:27 2018 UDPv4 link remote: [AF_UNSPEC]
Sun Jan 07 09:27:27 2018 MULTI: multi_init called, r=256 v=256
Sun Jan 07 09:27:27 2018 IFCONFIG POOL: base=192.168.3.1 size=254, ipv6=0
Sun Jan 07 09:27:27 2018 IFCONFIG POOL LIST
Sun Jan 07 09:27:27 2018 Initialization Sequence Completed
Sun Jan 07 09:27:27 2018 MANAGEMENT: >STATE:1515335247,CONNECTED,SUCCESS,,,,,
Sun Jan 07 09:27:47 2018 MULTI: multi_create_instance called
Sun Jan 07 09:27:47 2018 174.194.43.119:3726 Re-using SSL/TLS context
Sun Jan 07 09:27:47 2018 174.194.43.119:3726 Control Channel MTU parms [ L:1653 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Sun Jan 07 09:27:47 2018 174.194.43.119:3726 Data Channel MTU parms [ L:1653 D:1450 EF:121 EB:411 ET:32 EL:3 ]
Sun Jan 07 09:27:47 2018 174.194.43.119:3726 Local Options String (VER=V4): 'V4,dev-type tap,link-mtu 1589,tun-mtu 1532,proto UDPv4,cipher AES-256-CBC,auth SHA1,keysize 256,key-method 2,tls-server'
Sun Jan 07 09:27:47 2018 174.194.43.119:3726 Expected Remote Options String (VER=V4): 'V4,dev-type tap,link-mtu 1589,tun-mtu 1532,proto UDPv4,cipher AES-256-CBC,auth SHA1,keysize 256,key-method 2,tls-client'
Sun Jan 07 09:27:47 2018 174.194.43.119:3726 TLS: Initial packet from [AF_INET]174.194.43.119:3726, sid=09c10ce7 e60996f9
Sun Jan 07 09:28:47 2018 174.194.43.119:3726 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Sun Jan 07 09:28:47 2018 174.194.43.119:3726 TLS Error: TLS handshake failed
Sun Jan 07 09:28:47 2018 174.194.43.119:3726 SIGUSR1[soft,tls-error] received, client-instance restarting
Sun Jan 07 09:28:47 2018 MULTI: multi_create_instance called
Sun Jan 07 09:28:47 2018 174.194.43.119:3733 Re-using SSL/TLS context
Sun Jan 07 09:28:47 2018 174.194.43.119:3733 Control Channel MTU parms [ L:1653 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Sun Jan 07 09:28:47 2018 174.194.43.119:3733 Data Channel MTU parms [ L:1653 D:1450 EF:121 EB:411 ET:32 EL:3 ]
Sun Jan 07 09:28:47 2018 174.194.43.119:3733 Local Options String (VER=V4): 'V4,dev-type tap,link-mtu 1589,tun-mtu 1532,proto UDPv4,cipher AES-256-CBC,auth SHA1,keysize 256,key-method 2,tls-server'
Sun Jan 07 09:28:47 2018 174.194.43.119:3733 Expected Remote Options String (VER=V4): 'V4,dev-type tap,link-mtu 1589,tun-mtu 1532,proto UDPv4,cipher AES-256-CBC,auth SHA1,keysize 256,key-method 2,tls-client'
Sun Jan 07 09:28:47 2018 174.194.43.119:3733 TLS: Initial packet from [AF_INET]174.194.43.119:3733, sid=0d4678da 93408c42

Client Log

Code: Select all

*Tunnelblick: OS X 10.13.2; Tunnelblick 3.7.4b (build 4921)
2018-01-07 09:27:46 *Tunnelblick: Attempting connection with BransonMac using shadow copy; Set nameserver = 769; monitoring connection
2018-01-07 09:27:46 *Tunnelblick: openvpnstart start BransonMac.tblk 1337 769 0 1 0 1065264 -ptADGNWradsgnw 2.4.4-openssl-1.0.2n
2018-01-07 09:27:46 *Tunnelblick: openvpnstart log:
     OpenVPN started successfully. Command used to start OpenVPN (one argument per displayed line):
     
          /Applications/Tunnelblick.app/Contents/Resources/openvpn/openvpn-2.4.4-openssl-1.0.2n/openvpn
          --daemon
          --log
          /Library/Application Support/Tunnelblick/Logs/-SUsers-Sbransonbuchanan-SLibrary-SApplication Support-STunnelblick-SConfigurations-SBransonMac.tblk-SContents-SResources-Sconfig.ovpn.769_0_1_0_1065264.1337.openvpn.log
          --cd
          /Library/Application Support/Tunnelblick/Users/bransonbuchanan/BransonMac.tblk/Contents/Resources
          --setenv
          IV_GUI_VER
          "net.tunnelblick.tunnelblick 4921 3.7.4b (build 4921)"
          --verb
          3
          --config
          /Library/Application Support/Tunnelblick/Users/bransonbuchanan/BransonMac.tblk/Contents/Resources/config.ovpn
          --verb
          3
          --cd
          /Library/Application Support/Tunnelblick/Users/bransonbuchanan/BransonMac.tblk/Contents/Resources
          --management
          127.0.0.1
          1337
          --management-query-passwords
          --management-hold
          --script-security
          2
          --up
          /Applications/Tunnelblick.app/Contents/Resources/client.up.tunnelblick.sh -9 -d -f -m -w -ptADGNWradsgnw
          --down
          /Applications/Tunnelblick.app/Contents/Resources/client.down.tunnelblick.sh -9 -d -f -m -w -ptADGNWradsgnw

2018-01-07 09:27:46 OpenVPN 2.4.4 x86_64-apple-darwin [SSL (OpenSSL)] [LZO] [LZ4] [PKCS11] [MH/RECVDA] [AEAD] built on Dec  7 2017
2018-01-07 09:27:46 library versions: OpenSSL 1.0.2n  7 Dec 2017, LZO 2.10
2018-01-07 09:27:46 MANAGEMENT: TCP Socket listening on [AF_INET]127.0.0.1:1337
2018-01-07 09:27:46 Need hold release from management interface, waiting...
2018-01-07 09:27:46 MANAGEMENT: Client connected from [AF_INET]127.0.0.1:1337
2018-01-07 09:27:46 *Tunnelblick: openvpnstart starting OpenVPN
2018-01-07 09:27:47 *Tunnelblick: Established communication with OpenVPN
2018-01-07 09:27:47 MANAGEMENT: CMD 'pid'
2018-01-07 09:27:47 MANAGEMENT: CMD 'state on'
2018-01-07 09:27:47 MANAGEMENT: CMD 'state'
2018-01-07 09:27:47 MANAGEMENT: CMD 'bytecount 1'
2018-01-07 09:27:47 MANAGEMENT: CMD 'hold release'
2018-01-07 09:27:47 WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
2018-01-07 09:27:47 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
2018-01-07 09:27:47 MANAGEMENT: >STATE:1515335267,RESOLVE,,,,,,
2018-01-07 09:27:47 TCP/UDP: Preserving recently used remote address: [AF_INET][ServerIP]:1194
2018-01-07 09:27:47 Socket Buffers: R=[196724->196724] S=[9216->9216]
2018-01-07 09:27:47 UDPv4 link local: (not bound)
2018-01-07 09:27:47 UDPv4 link remote: [AF_INET][ServerIP]:1194
2018-01-07 09:27:47 MANAGEMENT: >STATE:1515335267,WAIT,,,,,,
2018-01-07 09:28:47 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
2018-01-07 09:28:47 TLS Error: TLS handshake failed
2018-01-07 09:28:47 SIGUSR1[soft,tls-error] received, process restarting
2018-01-07 09:28:47 MANAGEMENT: >STATE:1515335327,RECONNECTING,tls-error,,,,,
2018-01-07 09:28:47 MANAGEMENT: CMD 'hold release'
2018-01-07 09:28:47 WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
2018-01-07 09:28:47 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
2018-01-07 09:28:47 MANAGEMENT: >STATE:1515335327,RESOLVE,,,,,,
2018-01-07 09:28:47 TCP/UDP: Preserving recently used remote address: [AF_INET][ServerIP]:1194
2018-01-07 09:28:47 Socket Buffers: R=[196724->196724] S=[9216->9216]
2018-01-07 09:28:47 UDPv4 link local: (not bound)
2018-01-07 09:28:47 UDPv4 link remote: [AF_INET][ServerIP]:1194
2018-01-07 09:28:47 MANAGEMENT: >STATE:1515335327,WAIT,,,,,,
2018-01-07 09:28:50 *Tunnelblick: Disconnecting; VPN Details… window disconnect button pressed
2018-01-07 09:28:51 *Tunnelblick: No 'pre-disconnect.sh' script to execute
2018-01-07 09:28:51 *Tunnelblick: Disconnecting using 'kill'
2018-01-07 09:28:51 event_wait : Interrupted system call (code=4)
2018-01-07 09:28:51 SIGTERM[hard,] received, process exiting
2018-01-07 09:28:51 MANAGEMENT: >STATE:1515335331,EXITING,SIGTERM,,,,,
2018-01-07 09:28:51 *Tunnelblick: No 'post-disconnect.sh' script to execute
2018-01-07 09:28:51 *Tunnelblick: Expected disconnection occurred.

TinCanTech
OpenVPN Protagonist
Posts: 11139
Joined: Fri Jun 03, 2016 1:17 pm

Re: TLS handshake failed

Post by TinCanTech » Sun Jan 07, 2018 8:45 pm

The IPv6 issue appears to be gone ..

Perhaps your bridge is at fault ?

Bransonb3
OpenVpn Newbie
Posts: 4
Joined: Sat Jan 06, 2018 3:38 pm

Re: TLS handshake failed

Post by Bransonb3 » Mon Jan 08, 2018 3:33 pm

I changed the server-bridge ip setup and remade the bridge and it is working now.
Thanks for your help.

Post Reply