Can't get tunnelblick to open VPN

[Marc Schaefer]

Hi everybody,
I installed tunnelblick to setup a VPN tunnel to a server and I can't get it to work. Hope that somebody can give me a hint where the problem is located. I've already a couple of openvpn setups running fine, this one is the first where a Apple is involved.

First some infos:

Server: debian wheezy running openvpn 2.2.1 having a public IP
Config:
local w.x.y.z
port 1194
proto udp
dev tun
ca /etc/openvpn/pki/ca.crt
cert /etc/openvpn/pki/server.crt
key /etc/openvpn/pki/server.key
dh /etc/openvpn/pki/dh2048.pem
server 172.16.10.0 255.255.255.0
ifconfig-pool-persist ipp.txt
push "route 10.10.10.0 255.255.255.0"
keepalive 10 120
cipher AES-128-CBC
comp-lzo
user nobody
group nogroup
persist-key
persist-tun
status openvpn-status.log
verb 6

Client is a Mac Pro with OSX 10.6.8 running Tunnelblick 3.4beta20 ( tested it with 3.3 with the same result ) running with a local IP on en1 behind a NAT router
Config:
client
dev tun
proto udp
remote vpn.myserver.com 1194
resolv-retry infinite
nobind
persist-key
persist-tun
ca ca.crt
cert vpn.crt
key vpn.key
cipher AES-128-CBC
comp-lzo
verb 3

I can establish the VPN connection from another window box in the same net as the Apple and with the same config, so there shouldn't be any router problems. I can connect from my Laptop running Linux from anywhere I tried.

But when I try to connect with tunnelblick, it fails:

Here are the logs:

2014-02-06 17:34:23 *Tunnelblick: OS X 10.6.8; Tunnelblick 3.4beta20 (build 3727); prior version 3.3.0 (build 3518)
2014-02-06 17:34:23 *Tunnelblick: Attempting connection with Hetzner; Set nameserver = 1; monitoring connection
2014-02-06 17:34:23 *Tunnelblick: openvpnstart start Hetzner.tblk 1337 1 0 3 0 2353 -ptADGNWradsgnw 2.2.1
2014-02-06 17:34:23 *Tunnelblick: openvpnstart log:
     Loading tun.kext
    
     OpenVPN started successfully. Command used to start OpenVPN (one argument per displayed line):
    
          /Applications/Tunnelblick.app/Contents/Resources/openvpn/openvpn-2.2.1/openvpn
          --cd
          /Library/Application Support/Tunnelblick/Shared/Hetzner.tblk/Contents/Resources
          --daemon
          --management
          127.0.0.1
          1337
          --config
          /Library/Application Support/Tunnelblick/Shared/Hetzner.tblk/Contents/Resources/config.ovpn
          --log
          /Library/Application Support/Tunnelblick/Logs/-SLibrary-SApplication Support-STunnelblick-SShared-SHetzner.tblk-SContents-SResources-Sconfig.ovpn.1_0_3_0_2353.1337.openvpn.log
          --mtu-test
          --management-query-passwords
          --management-hold
          --script-security
          2
          --up
          /Applications/Tunnelblick.app/Contents/Resources/client.up.tunnelblick.sh -m -w -d -f -ptADGNWradsgnw
          --down
          /Applications/Tunnelblick.app/Contents/Resources/client.down.tunnelblick.sh -m -w -d -f -ptADGNWradsgnw

2014-02-06 17:34:23 *Tunnelblick: Established communication with OpenVPN
2014-02-06 17:34:23 us=757307 Current Parameter Settings:
2014-02-06 17:34:23 us=757412   config = '/Library/Application Support/Tunnelblick/Shared/Hetzner.tblk/Contents/Resources/config.ovpn'
2014-02-06 17:34:23 us=757420   mode = 0
2014-02-06 17:34:23 us=757426   show_ciphers = DISABLED
2014-02-06 17:34:23 us=757433   show_digests = DISABLED
2014-02-06 17:34:23 us=757439   show_engines = DISABLED
2014-02-06 17:34:23 us=757445   genkey = DISABLED
2014-02-06 17:34:23 us=757451   key_pass_file = '[UNDEF]'
2014-02-06 17:34:23 us=757457   show_tls_ciphers = DISABLED
2014-02-06 17:34:23 us=757463 Connection profiles [default]:
2014-02-06 17:34:23 us=757469   proto = udp
2014-02-06 17:34:23 us=757475   local = '[UNDEF]'
2014-02-06 17:34:23 us=757481   local_port = 0
2014-02-06 17:34:23 us=757487   remote = 'w.x..z.y'
2014-02-06 17:34:23 us=757494   remote_port = 1194
2014-02-06 17:34:23 us=757500   remote_float = DISABLED
2014-02-06 17:34:23 us=757506   bind_defined = DISABLED
2014-02-06 17:34:23 us=757512   bind_local = DISABLED
2014-02-06 17:34:23 us=757518   connect_retry_seconds = 5
2014-02-06 17:34:23 us=757524   connect_timeout = 10
2014-02-06 17:34:23 us=757530   connect_retry_max = 0
2014-02-06 17:34:23 us=757536   socks_proxy_server = '[UNDEF]'
2014-02-06 17:34:23 us=757542   socks_proxy_port = 0
2014-02-06 17:34:23 us=757548   socks_proxy_retry = DISABLED
2014-02-06 17:34:23 us=757554 Connection profiles END
2014-02-06 17:34:23 us=757560   remote_random = DISABLED
2014-02-06 17:34:23 us=757566   ipchange = '[UNDEF]'
2014-02-06 17:34:23 us=757572   dev = 'tun'
2014-02-06 17:34:23 us=757578   dev_type = '[UNDEF]'
2014-02-06 17:34:23 us=757584   dev_node = '[UNDEF]'
2014-02-06 17:34:23 us=757590   lladdr = '[UNDEF]'
2014-02-06 17:34:23 us=757596   topology = 1
2014-02-06 17:34:23 us=757602   tun_ipv6 = DISABLED
2014-02-06 17:34:23 us=757608   ifconfig_local = '[UNDEF]'
2014-02-06 17:34:23 us=757614   ifconfig_remote_netmask = '[UNDEF]'
2014-02-06 17:34:23 us=757620   ifconfig_noexec = DISABLED
2014-02-06 17:34:23 us=757626   ifconfig_nowarn = DISABLED
2014-02-06 17:34:23 us=757632   shaper = 0
2014-02-06 17:34:23 us=757638   tun_mtu = 1500
2014-02-06 17:34:23 us=757644   tun_mtu_defined = ENABLED
2014-02-06 17:34:23 us=757650   link_mtu = 1500
2014-02-06 17:34:23 us=757657   link_mtu_defined = DISABLED
2014-02-06 17:34:23 us=757663   tun_mtu_extra = 0
2014-02-06 17:34:23 us=757669   tun_mtu_extra_defined = DISABLED
2014-02-06 17:34:23 us=757675   fragment = 0
2014-02-06 17:34:23 us=757681   mtu_discover_type = -1
2014-02-06 17:34:23 us=757687   mtu_test = 1
2014-02-06 17:34:23 us=757693   mlock = DISABLED
2014-02-06 17:34:23 us=757699   keepalive_ping = 0
2014-02-06 17:34:23 us=757708   keepalive_timeout = 0
2014-02-06 17:34:23 us=757714   inactivity_timeout = 0
2014-02-06 17:34:23 us=757720   ping_send_timeout = 0
2014-02-06 17:34:23 us=757729   ping_rec_timeout = 0
2014-02-06 17:34:23 us=757736   ping_rec_timeout_action = 0
2014-02-06 17:34:23 us=757742   ping_timer_remote = DISABLED
2014-02-06 17:34:23 us=757748   remap_sigusr1 = 0
2014-02-06 17:34:23 us=757754   explicit_exit_notification = 0
2014-02-06 17:34:23 us=757760   persist_tun = ENABLED
2014-02-06 17:34:23 us=757766   persist_local_ip = DISABLED
2014-02-06 17:34:23 us=757772   persist_remote_ip = DISABLED
2014-02-06 17:34:23 us=757778   persist_key = ENABLED
2014-02-06 17:34:23 us=757784   mssfix = 1450
2014-02-06 17:34:23 us=757790   passtos = DISABLED
2014-02-06 17:34:23 us=757796   resolve_retry_seconds = 1000000000
2014-02-06 17:34:23 us=757802   username = '[UNDEF]'
2014-02-06 17:34:23 us=757808   groupname = '[UNDEF]'
2014-02-06 17:34:23 us=757814   chroot_dir = '[UNDEF]'
2014-02-06 17:34:23 us=757820   cd_dir = '/Library/Application Support/Tunnelblick/Shared/Hetzner.tblk/Contents/Resources'
2014-02-06 17:34:23 us=757835   writepid = '[UNDEF]'
2014-02-06 17:34:23 us=757842   up_script = '/Applications/Tunnelblick.app/Contents/Resources/client.up.tunnelblick.sh -m -w -d -f -ptADGNWradsgnw'
2014-02-06 17:34:23 us=757850   down_script = '/Applications/Tunnelblick.app/Contents/Resources/client.down.tunnelblick.sh -m -w -d -f -ptADGNWradsgnw'
2014-02-06 17:34:23 us=757857   down_pre = DISABLED
2014-02-06 17:34:23 us=757863   up_restart = DISABLED
2014-02-06 17:34:23 us=757869   up_delay = DISABLED
2014-02-06 17:34:23 us=757875   daemon = ENABLED
2014-02-06 17:34:23 us=757881   inetd = 0
2014-02-06 17:34:23 us=757887   log = ENABLED
2014-02-06 17:34:23 us=757893   suppress_timestamps = DISABLED
2014-02-06 17:34:23 us=757899   nice = 0
2014-02-06 17:34:23 us=757905   verbosity = 6
2014-02-06 17:34:23 us=757911   mute = 0
2014-02-06 17:34:23 us=757917   gremlin = 0
2014-02-06 17:34:23 us=757923   status_file = '[UNDEF]'
2014-02-06 17:34:23 us=757929   status_file_version = 1
2014-02-06 17:34:23 us=757935   status_file_update_freq = 60
2014-02-06 17:34:23 us=757941   occ = ENABLED
2014-02-06 17:34:23 us=757947   rcvbuf = 65536
2014-02-06 17:34:23 us=757953   sndbuf = 65536
2014-02-06 17:34:23 us=757959   sockflags = 0
2014-02-06 17:34:23 us=757965   fast_io = DISABLED
2014-02-06 17:34:23 us=757971   lzo = 7
2014-02-06 17:34:23 us=757977   route_script = '[UNDEF]'
2014-02-06 17:34:23 us=757983   route_default_gateway = '[UNDEF]'
2014-02-06 17:34:23 us=757989   route_default_metric = 0
2014-02-06 17:34:23 us=757995   route_noexec = DISABLED
2014-02-06 17:34:23 us=758001   route_delay = 0
2014-02-06 17:34:23 us=758007   route_delay_window = 30
2014-02-06 17:34:23 us=758013   route_delay_defined = DISABLED
2014-02-06 17:34:23 us=758019   route_nopull = DISABLED
2014-02-06 17:34:23 us=758025   route_gateway_via_dhcp = DISABLED
2014-02-06 17:34:23 us=758032   max_routes = 100
2014-02-06 17:34:23 us=758038   allow_pull_fqdn = DISABLED
2014-02-06 17:34:23 us=758044   management_addr = '127.0.0.1'
2014-02-06 17:34:23 us=758051   management_port = 1337
2014-02-06 17:34:23 us=758059   management_user_pass = '[UNDEF]'
2014-02-06 17:34:23 us=758065   management_log_history_cache = 250
2014-02-06 17:34:23 us=758072   management_echo_buffer_size = 100
2014-02-06 17:34:23 us=758078   management_write_peer_info_file = '[UNDEF]'
2014-02-06 17:34:23 us=758085   management_client_user = '[UNDEF]'
2014-02-06 17:34:23 us=758091   management_client_group = '[UNDEF]'
2014-02-06 17:34:23 us=758098   management_flags = 6
2014-02-06 17:34:23 us=758104   shared_secret_file = '[UNDEF]'
2014-02-06 17:34:23 us=758110   key_direction = 0
2014-02-06 17:34:23 us=758117   ciphername_defined = ENABLED
2014-02-06 17:34:23 us=758123   ciphername = 'AES-128-CBC'
2014-02-06 17:34:23 us=758129   authname_defined = ENABLED
2014-02-06 17:34:23 us=758136   authname = 'SHA1'
2014-02-06 17:34:23 us=758142   prng_hash = 'SHA1'
2014-02-06 17:34:23 us=758148   prng_nonce_secret_len = 16
2014-02-06 17:34:23 us=758154   keysize = 0
2014-02-06 17:34:23 us=758161   engine = DISABLED
2014-02-06 17:34:23 us=758167   replay = ENABLED
2014-02-06 17:34:23 us=758173   mute_replay_warnings = DISABLED
2014-02-06 17:34:23 us=758180   replay_window = 64
2014-02-06 17:34:23 us=758186   replay_time = 15
2014-02-06 17:34:23 us=758192   packet_id_file = '[UNDEF]'
2014-02-06 17:34:23 us=758198   use_iv = ENABLED
2014-02-06 17:34:23 us=758205   test_crypto = DISABLED
2014-02-06 17:34:23 us=758211   tls_server = DISABLED
2014-02-06 17:34:23 us=758217   tls_client = ENABLED
2014-02-06 17:34:23 us=758223   key_method = 2
2014-02-06 17:34:23 us=758230   ca_file = '[[INLINE]]'
2014-02-06 17:34:23 us=758236   ca_path = '[UNDEF]'
2014-02-06 17:34:23 us=758249   dh_file = '[UNDEF]'
2014-02-06 17:34:23 us=758256   cert_file = '[[INLINE]]'
2014-02-06 17:34:23 us=758263   priv_key_file = '[[INLINE]]'
2014-02-06 17:34:23 us=758269   pkcs12_file = '[UNDEF]'
2014-02-06 17:34:23 us=758275   cipher_list = '[UNDEF]'
2014-02-06 17:34:23 us=758282   tls_verify = '[UNDEF]'
2014-02-06 17:34:23 us=758288   tls_export_cert = '[UNDEF]'
2014-02-06 17:34:23 us=758294   tls_remote = '[UNDEF]'
2014-02-06 17:34:23 us=758300   crl_file = '[UNDEF]'
2014-02-06 17:34:23 us=758306   ns_cert_type = 0
2014-02-06 17:34:23 us=758312   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758319   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758325   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758331   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758337   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758343   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758349   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758355   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758361   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758367   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758373   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758380   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758386   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758392   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758398   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758404   remote_cert_ku[i] = 0
2014-02-06 17:34:23 us=758410   remote_cert_eku = '[UNDEF]'
2014-02-06 17:34:23 us=758416   tls_timeout = 2
2014-02-06 17:34:23 us=758422   renegotiate_bytes = 0
2014-02-06 17:34:23 us=758428   renegotiate_packets = 0
2014-02-06 17:34:23 us=758435   renegotiate_seconds = 3600
2014-02-06 17:34:23 us=758441   handshake_window = 60
2014-02-06 17:34:23 us=758447   transition_window = 3600
2014-02-06 17:34:23 us=758453   single_session = DISABLED
2014-02-06 17:34:23 us=758459   push_peer_info = DISABLED
2014-02-06 17:34:23 us=758466   tls_exit = DISABLED
2014-02-06 17:34:23 us=758472   tls_auth_file = '[UNDEF]'
2014-02-06 17:34:23 us=758479   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758485   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758491   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758498   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758504   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758510   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758516   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758523   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758529   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758535   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758541   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758548   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758554   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758560   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758566   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758573   pkcs11_protected_authentication = DISABLED
2014-02-06 17:34:23 us=758579   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758586   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758592   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758599   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758605   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758611   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758618   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758624   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758630   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758644   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758651   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758658   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758664   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758670   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758677   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758683   pkcs11_private_mode = 00000000
2014-02-06 17:34:23 us=758692   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758698   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758705   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758711   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758717   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758723   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758730   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758736   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758742   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758748   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758755   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758761   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758767   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758773   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758780   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758786   pkcs11_cert_private = DISABLED
2014-02-06 17:34:23 us=758792   pkcs11_pin_cache_period = -1
2014-02-06 17:34:23 us=758799   pkcs11_id = '[UNDEF]'
2014-02-06 17:34:23 us=758805   pkcs11_id_management = DISABLED
2014-02-06 17:34:23 us=758817   server_network = 0.0.0.0
2014-02-06 17:34:23 us=758825   server_netmask = 0.0.0.0
2014-02-06 17:34:23 us=758832   server_bridge_ip = 0.0.0.0
2014-02-06 17:34:23 us=758840   server_bridge_netmask = 0.0.0.0
2014-02-06 17:34:23 us=758847   server_bridge_pool_start = 0.0.0.0
2014-02-06 17:34:23 us=758854   server_bridge_pool_end = 0.0.0.0
2014-02-06 17:34:23 us=758861   ifconfig_pool_defined = DISABLED
2014-02-06 17:34:23 us=758868   ifconfig_pool_start = 0.0.0.0
2014-02-06 17:34:23 us=758875   ifconfig_pool_end = 0.0.0.0
2014-02-06 17:34:23 us=758883   ifconfig_pool_netmask = 0.0.0.0
2014-02-06 17:34:23 us=758889   ifconfig_pool_persist_filename = '[UNDEF]'
2014-02-06 17:34:23 us=758896   ifconfig_pool_persist_refresh_freq = 600
2014-02-06 17:34:23 us=758902   n_bcast_buf = 256
2014-02-06 17:34:23 us=758909   tcp_queue_limit = 64
2014-02-06 17:34:23 us=758915   real_hash_size = 256
2014-02-06 17:34:23 us=758921   virtual_hash_size = 256
2014-02-06 17:34:23 us=758927   client_connect_script = '[UNDEF]'
2014-02-06 17:34:23 us=758934   learn_address_script = '[UNDEF]'
2014-02-06 17:34:23 us=758940   client_disconnect_script = '[UNDEF]'
2014-02-06 17:34:23 us=758946   client_config_dir = '[UNDEF]'
2014-02-06 17:34:23 us=758953   ccd_exclusive = DISABLED
2014-02-06 17:34:23 us=758959   tmp_dir = '/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/'
2014-02-06 17:34:23 us=758966   push_ifconfig_defined = DISABLED
2014-02-06 17:34:23 us=758973   push_ifconfig_local = 0.0.0.0
2014-02-06 17:34:23 us=758980   push_ifconfig_remote_netmask = 0.0.0.0
2014-02-06 17:34:23 us=758987   enable_c2c = DISABLED
2014-02-06 17:34:23 us=758993   duplicate_cn = DISABLED
2014-02-06 17:34:23 us=758999   cf_max = 0
2014-02-06 17:34:23 us=759005   cf_per = 0
2014-02-06 17:34:23 us=759012   max_clients = 1024
2014-02-06 17:34:23 us=759018   max_routes_per_client = 256
2014-02-06 17:34:23 us=759024   auth_user_pass_verify_script = '[UNDEF]'
2014-02-06 17:34:23 us=759031   auth_user_pass_verify_script_via_file = DISABLED
2014-02-06 17:34:23 us=759037   ssl_flags = 0
2014-02-06 17:34:23 us=759044   port_share_host = '[UNDEF]'
2014-02-06 17:34:23 us=759057   port_share_port = 0
2014-02-06 17:34:23 us=759064   client = ENABLED
2014-02-06 17:34:23 us=759070   pull = ENABLED
2014-02-06 17:34:23 us=759077   auth_user_pass_file = '[UNDEF]'
2014-02-06 17:34:23 us=759087 OpenVPN 2.2.1 i386-apple-darwin10.8.0 [SSL] [LZO2] [PKCS11] [eurephia] built on Jan  6 2014
2014-02-06 17:34:23 us=759167 MANAGEMENT: TCP Socket listening on 127.0.0.1:1337
2014-02-06 17:34:23 us=759510 Need hold release from management interface, waiting...
2014-02-06 17:34:23 us=850835 MANAGEMENT: Client connected from 127.0.0.1:1337
2014-02-06 17:34:23 us=858419 MANAGEMENT: CMD 'pid'
2014-02-06 17:34:23 us=858459 MANAGEMENT: CMD 'state on'
2014-02-06 17:34:23 us=858483 MANAGEMENT: CMD 'state'
2014-02-06 17:34:23 us=858518 MANAGEMENT: CMD 'bytecount 1'
2014-02-06 17:34:23 us=858541 MANAGEMENT: CMD 'hold release'
2014-02-06 17:34:23 us=858690 WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
2014-02-06 17:34:23 us=858701 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
2014-02-06 17:34:23 us=859567 LZO compression initialized
2014-02-06 17:34:23 us=859646 Control Channel MTU parms [ L:1558 D:138 EF:38 EB:0 ET:0 EL:0 ]
2014-02-06 17:34:23 us=859683 Socket Buffers: R=[42080->65536] S=[9216->65536]
2014-02-06 17:34:23 us=859703 MANAGEMENT: >STATE:1391704463,RESOLVE,,,
2014-02-06 17:34:23 us=860834 Data Channel MTU parms [ L:1558 D:1450 EF:58 EB:135 ET:0 EL:0 AF:3/1 ]
2014-02-06 17:34:23 us=860860 Local Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
2014-02-06 17:34:23 us=860869 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
2014-02-06 17:34:23 us=860888 Local Options hash (VER=V4): '66096c33'
2014-02-06 17:34:23 us=860900 Expected Remote Options hash (VER=V4): '691e95c7'
2014-02-06 17:34:23 us=860914 UDPv4 link local: [undef]
2014-02-06 17:34:23 us=860923 UDPv4 link remote: w.x.z.y:1194
2014-02-06 17:34:23 us=860952 MANAGEMENT: >STATE:1391704463,WAIT,,,
2014-02-06 17:34:23 us=860995 UDPv4 WRITE [14] to w.x.z.y:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
2014-02-06 17:34:23 us=877087 UDPv4 READ [26] from w.x.z.y:1194: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
2014-02-06 17:34:23 us=877122 MANAGEMENT: >STATE:1391704463,AUTH,,,
2014-02-06 17:34:23 us=877138 TLS: Initial packet from w.x.z.y:1194, sid=5bb9363e 742e6ddd
2014-02-06 17:34:23 us=877214 UDPv4 WRITE [22] to w.x.z.y:1194: P_ACK_V1 kid=0 [ 0 ]
2014-02-06 17:34:23 us=877282 UDPv4 WRITE [114] to w.x..z.y:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 17:34:23 us=877326 UDPv4 WRITE [114] to w.x.z.y:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 17:34:23 us=877365 UDPv4 WRITE [42] to w.x.z.y:1194: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=28
2014-02-06 17:34:23 us=893267 UDPv4 READ [22] from w.x.z.y:1194: P_ACK_V1 kid=0 [ 3 ]
2014-02-06 17:34:23 *Tunnelblick: openvpnstart starting OpenVPN
2014-02-06 17:34:25 us=91311 UDPv4 WRITE [114] to w.x.z.y:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 17:34:26 us=289278 UDPv4 WRITE [114] to w.x.z.y:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 17:34:29 us=883033 UDPv4 WRITE [114] to w.x.z.y:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 17:34:31 us=81002 UDPv4 WRITE [114] to w.x.z.y:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 17:34:34 *Tunnelblick: Disconnecting; VPN Details… window disconnect button pressed
2014-02-06 17:34:34 *Tunnelblick: Disconnecting using 'killall'
2014-02-06 17:34:34 us=738888 event_wait : Interrupted system call (code=4)
2014-02-06 17:34:34 us=739033 TCP/UDP: Closing socket
2014-02-06 17:34:34 us=739062 SIGTERM[hard,] received, process exiting
2014-02-06 17:34:34 us=739097 MANAGEMENT: >STATE:1391704474,EXITING,SIGTERM,,
2014-02-06 17:34:35 *Tunnelblick: No 'post-disconnect.sh' script to executeg extracts:


And here's the server log:


Feb  6 17:43:47 remote ovpn-server[4617]: GID set to nogroup
Feb  6 17:43:47 remote ovpn-server[4617]: UID set to nobody
Feb  6 17:43:47 remote ovpn-server[4617]: UDPv4 link local (bound): [AF_INET]144.76.108.61:1194
Feb  6 17:43:47 remote ovpn-server[4617]: UDPv4 link remote: [undef]
Feb  6 17:43:47 remote ovpn-server[4617]: MULTI: multi_init called, r=256 v=256
Feb  6 17:43:47 remote ovpn-server[4617]: IFCONFIG POOL: base=172.16.10.4 size=62, ipv6=0
Feb  6 17:43:47 remote ovpn-server[4617]: IFCONFIG POOL LIST
Feb  6 17:43:47 remote ovpn-server[4617]: Initialization Sequence Completed
Feb  6 17:43:55 remote ovpn-server[4617]: MULTI: multi_create_instance called
Feb  6 17:43:55 remote ovpn-server[4617]: s.t.u.v:60780 Re-using SSL/TLS context
Feb  6 17:43:55 remote ovpn-server[4617]: s.t.u.v:60780 LZO compression initialized
Feb  6 17:43:55 remote ovpn-server[4617]: s.t.u.v:60780 Control Channel MTU parms [ L:1558 D:138 EF:38 EB:0 ET:0 EL:0 ]
Feb  6 17:43:55 remote ovpn-server[4617]: s.t.u.v:60780 Data Channel MTU parms [ L:1558 D:1450 EF:58 EB:135 ET:0 EL:0 AF:3/1 ]
Feb  6 17:43:55 remote ovpn-server[4617]: s.t.u.v:60780 Local Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
Feb  6 17:43:55 remote ovpn-server[4617]: s.t.u.v:60780 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
Feb  6 17:43:55 remote ovpn-server[4617]: s.t.u.v:60780 Local Options hash (VER=V4): '691e95c7'
Feb  6 17:43:55 remote ovpn-server[4617]: s.t.u.v:60780 Expected Remote Options hash (VER=V4): '66096c33'
Feb  6 17:43:55 remote ovpn-server[4617]: s.t.u.v:60780 UDPv4 READ [14] from [AF_INET]s.t.u.v:60780: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Feb  6 17:43:55 remote ovpn-server[4617]: s.t.u.v:60780 TLS: Initial packet from [AF_INET]s.t.u.v:60780, sid=0d3889c4 2a40a1fc
Feb  6 17:43:55 remote ovpn-server[4617]: s.t.u.v:60780 UDPv4 WRITE [26] to [AF_INET]s.t.u.v:60780: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
Feb  6 17:43:55 remote ovpn-server[4617]: s.t.u.v:60780 UDPv4 READ [22] from [AF_INET]s.t.u.v:60780: P_ACK_V1 kid=0 [ 0 ]
Feb  6 17:43:55 remote ovpn-server[4617]: s.t.u.v:60780 UDPv4 READ [42] from [AF_INET]s.t.u.v:60780: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=28
Feb  6 17:43:55 remote ovpn-server[4617]: s.t.u.v:60780 UDPv4 WRITE [22] to [AF_INET]s.t.u.v:60780: P_ACK_V1 kid=0 [ 3 ]


I can't find any hint in this logs, hopefully some of you have a clue for me. I even setup the MAC as server and tried to connect same behaviour.


Thanx in advance for having a look at.

Regards


Marc

[jkbull...gmail.com]

Please post the full "Diagnostic Info" from Tunnelblick (use the button on the "Log" tab).

[Marc Schaefer]

So here we have the full debug log:

*Tunnelblick: OS X 10.6.8; Tunnelblick 3.4beta20 (build 3727); prior version 3.3.0 (build 3518); Admin user

"Sanitized" configuration file for /Library/Application Support/Tunnelblick/Shared/Hetzner.tblk:

client
dev tun
proto udp

remote w.x.y.z 1194

resolv-retry infinite
nobind
persist-key
persist-tun

ca [inline]
cert [inline]
key [inline]


cipher AES-128-CBC
comp-lzo
verb 6

<ca>
 [Security-related line(s) omitted]
</ca>

<key>
 [Security-related line(s) omitted]
</key>

<cert>
 [Security-related line(s) omitted]
</cert>


================================================================================

Configuration preferences:

-doNotDisconnectOnFastUserSwitch = 1
-doNotReconnectOnFastUserSwitch = 1
-runMtuTest = 1
-loadTap = never
-loadTun = always
-notOKToCheckThatIPAddressDidNotChangeAfterConnection = 1
-doNotDisconnectOnSleep = 1
-lastConnectionSucceeded = 0

================================================================================

Wildcard preferences:

-notOKToCheckThatIPAddressDidNotChangeAfterConnection = 1

================================================================================

Program preferences:

notOKToCheckThatIPAddressDidNotChangeAfterConnection = 1
askedUserIfOKToCheckThatIPAddressDidNotChangeAfterConnection = 1
tunnelblickVersionHistory = (
    "3.4beta20 (build 3727)",
    "3.3.0 (build 3518)"
)
showConnectedDurations = 1
connectionWindowDisplayCriteria = showWhenConnecting
maxLogDisplaySize = 102400
lastConnectedDisplayName = openvpn
installationUID = D1FCFA90-2FB4-4478-9B48-D736ABEAF6F5
keyboardShortcutIndex = 1
namedCredentialsThatAllConfigurationsUse = Gemeinsam
updateCheckAutomatically = 1
updateSendProfileInfo = 1
NSWindow Frame ConnectingWindow = 445 630 389 187 0 0 1280 1002
detailsWindowFrameVersion = 3727
detailsWindowFrame = {{383, 459}, {760, 468}}
detailsWindowLeftFrame = {{0, 0}, {135, 350}}
leftNavSelectedDisplayName = Hetzner
haveDealtWithSparkle1dot5b6 = 1
haveDealtWithOldTunTapPreferences = 1
SUEnableAutomaticChecks = 1
SUFeedURL = https://www.tunnelblick.net/appcast-b.rss
SUSendProfileInfo = 1
SULastCheckTime = 2014-02-06 19:23:57 +0100
SULastProfileSubmissionDate = 2014-02-06 11:47:56 +0100
SUHasLaunchedBefore = 1
WebKitDefaultFontSize = 16
WebKitStandardFont = Times

================================================================================

Tunnelblick Log:

2014-02-06 19:24:09 *Tunnelblick: OS X 10.6.8; Tunnelblick 3.4beta20 (build 3727); prior version 3.3.0 (build 3518)
2014-02-06 19:24:09 *Tunnelblick: Attempting connection with Hetzner; Set nameserver = 1; monitoring connection
2014-02-06 19:24:09 *Tunnelblick: openvpnstart start Hetzner.tblk 1337 1 0 3 0 2353 -ptADGNWradsgnw 2.2.1
2014-02-06 19:24:09 *Tunnelblick: openvpnstart log:

     Loading tun.kext
   
     OpenVPN started successfully. Command used to start OpenVPN (one argument per displayed line):
   
          /Applications/Tunnelblick.app/Contents/Resources/openvpn/openvpn-2.2.1/openvpn
          --cd
          /Library/Application Support/Tunnelblick/Shared/Hetzner.tblk/Contents/Resources
          --daemon
          --management
          127.0.0.1
          1337
          --config
          /Library/Application Support/Tunnelblick/Shared/Hetzner.tblk/Contents/Resources/config.ovpn
          --log
          /Library/Application Support/Tunnelblick/Logs/-SLibrary-SApplication Support-STunnelblick-SShared-SHetzner.tblk-SContents-SResources-Sconfig.ovpn.1_0_3_0_2353.1337.openvpn.log
          --mtu-test
          --management-query-passwords
          --management-hold
          --script-security
          2
          --up
          /Applications/Tunnelblick.app/Contents/Resources/client.up.tunnelblick.sh -m -w -d -f -ptADGNWradsgnw
          --down
          /Applications/Tunnelblick.app/Contents/Resources/client.down.tunnelblick.sh -m -w -d -f -ptADGNWradsgnw

2014-02-06 19:24:09 *Tunnelblick: Established communication with OpenVPN
2014-02-06 19:24:09 us=637276 Current Parameter Settings:
2014-02-06 19:24:09 us=637392   config = '/Library/Application Support/Tunnelblick/Shared/Hetzner.tblk/Contents/Resources/config.ovpn'
2014-02-06 19:24:09 us=637401   mode = 0
2014-02-06 19:24:09 us=637408   show_ciphers = DISABLED
2014-02-06 19:24:09 us=637414   show_digests = DISABLED
2014-02-06 19:24:09 us=637420   show_engines = DISABLED
2014-02-06 19:24:09 us=637426   genkey = DISABLED
2014-02-06 19:24:09 us=637432   key_pass_file = '[UNDEF]'
2014-02-06 19:24:09 us=637438   show_tls_ciphers = DISABLED
2014-02-06 19:24:09 us=637445 Connection profiles [default]:
2014-02-06 19:24:09 us=637456   proto = udp
2014-02-06 19:24:09 us=637463   local = '[UNDEF]'
2014-02-06 19:24:09 us=637469   local_port = 0
2014-02-06 19:24:09 us=637475   remote = 'w.x.y.z'
2014-02-06 19:24:09 us=637481   remote_port = 1194
2014-02-06 19:24:09 us=637488   remote_float = DISABLED
2014-02-06 19:24:09 us=637494   bind_defined = DISABLED
2014-02-06 19:24:09 us=637500   bind_local = DISABLED
2014-02-06 19:24:09 us=637506   connect_retry_seconds = 5
2014-02-06 19:24:09 us=637512   connect_timeout = 10
2014-02-06 19:24:09 us=637518   connect_retry_max = 0
2014-02-06 19:24:09 us=637524   socks_proxy_server = '[UNDEF]'
2014-02-06 19:24:09 us=637531   socks_proxy_port = 0
2014-02-06 19:24:09 us=637537   socks_proxy_retry = DISABLED
2014-02-06 19:24:09 us=637543 Connection profiles END
2014-02-06 19:24:09 us=637549   remote_random = DISABLED
2014-02-06 19:24:09 us=637555   ipchange = '[UNDEF]'
2014-02-06 19:24:09 us=637561   dev = 'tun'
2014-02-06 19:24:09 us=637567   dev_type = '[UNDEF]'
2014-02-06 19:24:09 us=637573   dev_node = '[UNDEF]'
2014-02-06 19:24:09 us=637579   lladdr = '[UNDEF]'
2014-02-06 19:24:09 us=637585   topology = 1
2014-02-06 19:24:09 us=637591   tun_ipv6 = DISABLED
2014-02-06 19:24:09 us=637598   ifconfig_local = '[UNDEF]'
2014-02-06 19:24:09 us=637604   ifconfig_remote_netmask = '[UNDEF]'
2014-02-06 19:24:09 us=637610   ifconfig_noexec = DISABLED
2014-02-06 19:24:09 us=637616   ifconfig_nowarn = DISABLED
2014-02-06 19:24:09 us=637622   shaper = 0
2014-02-06 19:24:09 us=637628   tun_mtu = 1500
2014-02-06 19:24:09 us=637635   tun_mtu_defined = ENABLED
2014-02-06 19:24:09 us=637641   link_mtu = 1500
2014-02-06 19:24:09 us=637647   link_mtu_defined = DISABLED
2014-02-06 19:24:09 us=637653   tun_mtu_extra = 0
2014-02-06 19:24:09 us=637659   tun_mtu_extra_defined = DISABLED
2014-02-06 19:24:09 us=637665   fragment = 0
2014-02-06 19:24:09 us=637672   mtu_discover_type = -1
2014-02-06 19:24:09 us=637678   mtu_test = 1
2014-02-06 19:24:09 us=637684   mlock = DISABLED
2014-02-06 19:24:09 us=637690   keepalive_ping = 0
2014-02-06 19:24:09 us=637699   keepalive_timeout = 0
2014-02-06 19:24:09 us=637705   inactivity_timeout = 0
2014-02-06 19:24:09 us=637712   ping_send_timeout = 0
2014-02-06 19:24:09 us=637721   ping_rec_timeout = 0
2014-02-06 19:24:09 us=637728   ping_rec_timeout_action = 0
2014-02-06 19:24:09 us=637734   ping_timer_remote = DISABLED
2014-02-06 19:24:09 us=637740   remap_sigusr1 = 0
2014-02-06 19:24:09 us=637746   explicit_exit_notification = 0
2014-02-06 19:24:09 us=637752   persist_tun = ENABLED
2014-02-06 19:24:09 us=637759   persist_local_ip = DISABLED
2014-02-06 19:24:09 us=637765   persist_remote_ip = DISABLED
2014-02-06 19:24:09 us=637771   persist_key = ENABLED
2014-02-06 19:24:09 us=637777   mssfix = 1450
2014-02-06 19:24:09 us=637783   passtos = DISABLED
2014-02-06 19:24:09 us=637790   resolve_retry_seconds = 1000000000
2014-02-06 19:24:09 us=637796   username = '[UNDEF]'
2014-02-06 19:24:09 us=637802   groupname = '[UNDEF]'
2014-02-06 19:24:09 us=637808   chroot_dir = '[UNDEF]'
2014-02-06 19:24:09 us=637815   cd_dir = '/Library/Application Support/Tunnelblick/Shared/Hetzner.tblk/Contents/Resources'
2014-02-06 19:24:09 us=637831   writepid = '[UNDEF]'
2014-02-06 19:24:09 us=637838   up_script = '/Applications/Tunnelblick.app/Contents/Resources/client.up.tunnelblick.sh -m -w -d -f -ptADGNWradsgnw'
2014-02-06 19:24:09 us=637847   down_script = '/Applications/Tunnelblick.app/Contents/Resources/client.down.tunnelblick.sh -m -w -d -f -ptADGNWradsgnw'
2014-02-06 19:24:09 us=637853   down_pre = DISABLED
2014-02-06 19:24:09 us=637860   up_restart = DISABLED
2014-02-06 19:24:09 us=637866   up_delay = DISABLED
2014-02-06 19:24:09 us=637872   daemon = ENABLED
2014-02-06 19:24:09 us=637878   inetd = 0
2014-02-06 19:24:09 us=637884   log = ENABLED
2014-02-06 19:24:09 us=637891   suppress_timestamps = DISABLED
2014-02-06 19:24:09 us=637897   nice = 0
2014-02-06 19:24:09 us=637903   verbosity = 6
2014-02-06 19:24:09 us=637909   mute = 0
2014-02-06 19:24:09 us=637915   gremlin = 0
2014-02-06 19:24:09 us=637921   status_file = '[UNDEF]'
2014-02-06 19:24:09 us=637928   status_file_version = 1
2014-02-06 19:24:09 us=637934   status_file_update_freq = 60
2014-02-06 19:24:09 us=637940   occ = ENABLED
2014-02-06 19:24:09 us=637946   rcvbuf = 65536
2014-02-06 19:24:09 us=637952   sndbuf = 65536
2014-02-06 19:24:09 us=637959   sockflags = 0
2014-02-06 19:24:09 us=637965   fast_io = DISABLED
2014-02-06 19:24:09 us=637971   lzo = 7
2014-02-06 19:24:09 us=637977   route_script = '[UNDEF]'
2014-02-06 19:24:09 us=637983   route_default_gateway = '[UNDEF]'
2014-02-06 19:24:09 us=637990   route_default_metric = 0
2014-02-06 19:24:09 us=637996   route_noexec = DISABLED
2014-02-06 19:24:09 us=638002   route_delay = 0
2014-02-06 19:24:09 us=638008   route_delay_window = 30
2014-02-06 19:24:09 us=638014   route_delay_defined = DISABLED
2014-02-06 19:24:09 us=638021   route_nopull = DISABLED
2014-02-06 19:24:09 us=638027   route_gateway_via_dhcp = DISABLED
2014-02-06 19:24:09 us=638033   max_routes = 100
2014-02-06 19:24:09 us=638040   allow_pull_fqdn = DISABLED
2014-02-06 19:24:09 us=638047   management_addr = '127.0.0.1'
2014-02-06 19:24:09 us=638053   management_port = 1337
2014-02-06 19:24:09 us=638061   management_user_pass = '[UNDEF]'
2014-02-06 19:24:09 us=638068   management_log_history_cache = 250
2014-02-06 19:24:09 us=638075   management_echo_buffer_size = 100
2014-02-06 19:24:09 us=638081   management_write_peer_info_file = '[UNDEF]'
2014-02-06 19:24:09 us=638088   management_client_user = '[UNDEF]'
2014-02-06 19:24:09 us=638095   management_client_group = '[UNDEF]'
2014-02-06 19:24:09 us=638101   management_flags = 6
2014-02-06 19:24:09 us=638108   shared_secret_file = '[UNDEF]'
2014-02-06 19:24:09 us=638114   key_direction = 0
2014-02-06 19:24:09 us=638121   ciphername_defined = ENABLED
2014-02-06 19:24:09 us=638127   ciphername = 'AES-128-CBC'
2014-02-06 19:24:09 us=638133   authname_defined = ENABLED
2014-02-06 19:24:09 us=638140   authname = 'SHA1'
2014-02-06 19:24:09 us=638146   prng_hash = 'SHA1'
2014-02-06 19:24:09 us=638152   prng_nonce_secret_len = 16
2014-02-06 19:24:09 us=638159   keysize = 0
2014-02-06 19:24:09 us=638165   engine = DISABLED
2014-02-06 19:24:09 us=638171   replay = ENABLED
2014-02-06 19:24:09 us=638178   mute_replay_warnings = DISABLED
2014-02-06 19:24:09 us=638184   replay_window = 64
2014-02-06 19:24:09 us=638190   replay_time = 15
2014-02-06 19:24:09 us=638197   packet_id_file = '[UNDEF]'
2014-02-06 19:24:09 us=638208   use_iv = ENABLED
2014-02-06 19:24:09 us=638214   test_crypto = DISABLED
2014-02-06 19:24:09 us=638221   tls_server = DISABLED
2014-02-06 19:24:09 us=638227   tls_client = ENABLED
2014-02-06 19:24:09 us=638233   key_method = 2
2014-02-06 19:24:09 us=638240   ca_file = '[[INLINE]]'
2014-02-06 19:24:09 us=638246   ca_path = '[UNDEF]'
2014-02-06 19:24:09 us=638261   dh_file = '[UNDEF]'
2014-02-06 19:24:09 us=638268   cert_file = '[[INLINE]]'
2014-02-06 19:24:09 us=638275   priv_key_file = '[[INLINE]]'
2014-02-06 19:24:09 us=638281   pkcs12_file = '[UNDEF]'
2014-02-06 19:24:09 us=638287   cipher_list = '[UNDEF]'
2014-02-06 19:24:09 us=638293   tls_verify = '[UNDEF]'
2014-02-06 19:24:09 us=638300   tls_export_cert = '[UNDEF]'
2014-02-06 19:24:09 us=638306   tls_remote = '[UNDEF]'
2014-02-06 19:24:09 us=638312   crl_file = '[UNDEF]'
2014-02-06 19:24:09 us=638318   ns_cert_type = 0
2014-02-06 19:24:09 us=638325   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638331   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638337   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638344   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638350   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638356   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638363   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638369   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638375   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638381   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638388   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638394   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638400   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638406   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638413   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638419   remote_cert_ku[i] = 0
2014-02-06 19:24:09 us=638425   remote_cert_eku = '[UNDEF]'
2014-02-06 19:24:09 us=638432   tls_timeout = 2
2014-02-06 19:24:09 us=638438   renegotiate_bytes = 0
2014-02-06 19:24:09 us=638444   renegotiate_packets = 0
2014-02-06 19:24:09 us=638451   renegotiate_seconds = 3600
2014-02-06 19:24:09 us=638457   handshake_window = 60
2014-02-06 19:24:09 us=638463   transition_window = 3600
2014-02-06 19:24:09 us=638469   single_session = DISABLED
2014-02-06 19:24:09 us=638476   push_peer_info = DISABLED
2014-02-06 19:24:09 us=638482   tls_exit = DISABLED
2014-02-06 19:24:09 us=638489   tls_auth_file = '[UNDEF]'
2014-02-06 19:24:09 us=638495   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638502   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638508   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638515   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638524   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638531   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638537   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638544   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638555   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638563   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638569   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638576   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638582   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638588   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638595   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638601   pkcs11_protected_authentication = DISABLED
2014-02-06 19:24:09 us=638608   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638615   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638621   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638627   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638634   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638640   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638647   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638653   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638660   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638675   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638683   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638689   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638695   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638702   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638708   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638715   pkcs11_private_mode = 00000000
2014-02-06 19:24:09 us=638723   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638730   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638737   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638743   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638749   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638756   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638762   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638768   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638774   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638781   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638787   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638793   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638800   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638806   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638812   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638819   pkcs11_cert_private = DISABLED
2014-02-06 19:24:09 us=638825   pkcs11_pin_cache_period = -1
2014-02-06 19:24:09 us=638831   pkcs11_id = '[UNDEF]'
2014-02-06 19:24:09 us=638838   pkcs11_id_management = DISABLED
2014-02-06 19:24:09 us=638850   server_network = 0.0.0.0
2014-02-06 19:24:09 us=638858   server_netmask = 0.0.0.0
2014-02-06 19:24:09 us=638865   server_bridge_ip = 0.0.0.0
2014-02-06 19:24:09 us=638873   server_bridge_netmask = 0.0.0.0
2014-02-06 19:24:09 us=638880   server_bridge_pool_start = 0.0.0.0
2014-02-06 19:24:09 us=638888   server_bridge_pool_end = 0.0.0.0
2014-02-06 19:24:09 us=638894   ifconfig_pool_defined = DISABLED
2014-02-06 19:24:09 us=638901   ifconfig_pool_start = 0.0.0.0
2014-02-06 19:24:09 us=638909   ifconfig_pool_end = 0.0.0.0
2014-02-06 19:24:09 us=638916   ifconfig_pool_netmask = 0.0.0.0
2014-02-06 19:24:09 us=638923   ifconfig_pool_persist_filename = '[UNDEF]'
2014-02-06 19:24:09 us=638929   ifconfig_pool_persist_refresh_freq = 600
2014-02-06 19:24:09 us=638935   n_bcast_buf = 256
2014-02-06 19:24:09 us=638942   tcp_queue_limit = 64
2014-02-06 19:24:09 us=638948   real_hash_size = 256
2014-02-06 19:24:09 us=638954   virtual_hash_size = 256
2014-02-06 19:24:09 us=638960   client_connect_script = '[UNDEF]'
2014-02-06 19:24:09 us=638967   learn_address_script = '[UNDEF]'
2014-02-06 19:24:09 us=638973   client_disconnect_script = '[UNDEF]'
2014-02-06 19:24:09 us=638980   client_config_dir = '[UNDEF]'
2014-02-06 19:24:09 us=638986   ccd_exclusive = DISABLED
2014-02-06 19:24:09 us=638993   tmp_dir = '/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/'
2014-02-06 19:24:09 us=638999   push_ifconfig_defined = DISABLED
2014-02-06 19:24:09 us=639007   push_ifconfig_local = 0.0.0.0
2014-02-06 19:24:09 us=639015   push_ifconfig_remote_netmask = 0.0.0.0
2014-02-06 19:24:09 us=639021   enable_c2c = DISABLED
2014-02-06 19:24:09 us=639027   duplicate_cn = DISABLED
2014-02-06 19:24:09 us=639033   cf_max = 0
2014-02-06 19:24:09 us=639040   cf_per = 0
2014-02-06 19:24:09 us=639046   max_clients = 1024
2014-02-06 19:24:09 us=639052   max_routes_per_client = 256
2014-02-06 19:24:09 us=639058   auth_user_pass_verify_script = '[UNDEF]'
2014-02-06 19:24:09 us=639065   auth_user_pass_verify_script_via_file = DISABLED
2014-02-06 19:24:09 us=639071   ssl_flags = 0
2014-02-06 19:24:09 us=639083   port_share_host = '[UNDEF]'
2014-02-06 19:24:09 us=639105   port_share_port = 0
2014-02-06 19:24:09 us=639112   client = ENABLED
2014-02-06 19:24:09 us=639119   pull = ENABLED
2014-02-06 19:24:09 us=639125   auth_user_pass_file = '[UNDEF]'
2014-02-06 19:24:09 us=639136 OpenVPN 2.2.1 i386-apple-darwin10.8.0 [SSL] [LZO2] [PKCS11] [eurephia] built on Jan  6 2014
2014-02-06 19:24:09 us=639219 MANAGEMENT: TCP Socket listening on 127.0.0.1:1337
2014-02-06 19:24:09 us=639571 Need hold release from management interface, waiting...
2014-02-06 19:24:09 us=742992 MANAGEMENT: Client connected from 127.0.0.1:1337
2014-02-06 19:24:09 us=751040 MANAGEMENT: CMD 'pid'
2014-02-06 19:24:09 us=751085 MANAGEMENT: CMD 'state on'
2014-02-06 19:24:09 us=751116 MANAGEMENT: CMD 'state'
2014-02-06 19:24:09 us=751153 MANAGEMENT: CMD 'bytecount 1'
2014-02-06 19:24:09 us=751178 MANAGEMENT: CMD 'hold release'
2014-02-06 19:24:09 us=751336 WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
2014-02-06 19:24:09 us=751347 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
2014-02-06 19:24:09 us=752233 LZO compression initialized
2014-02-06 19:24:09 us=752310 Control Channel MTU parms [ L:1558 D:138 EF:38 EB:0 ET:0 EL:0 ]
2014-02-06 19:24:09 us=752347 Socket Buffers: R=[42080->65536] S=[9216->65536]
2014-02-06 19:24:09 us=752367 MANAGEMENT: >STATE:1391711049,RESOLVE,,,
2014-02-06 19:24:09 us=753865 Data Channel MTU parms [ L:1558 D:1450 EF:58 EB:135 ET:0 EL:0 AF:3/1 ]
2014-02-06 19:24:09 us=753902 Local Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
2014-02-06 19:24:09 us=753915 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
2014-02-06 19:24:09 us=753940 Local Options hash (VER=V4): '66096c33'
2014-02-06 19:24:09 us=753957 Expected Remote Options hash (VER=V4): '691e95c7'
2014-02-06 19:24:09 us=753978 UDPv4 link local: [undef]
2014-02-06 19:24:09 us=753993 UDPv4 link remote: w.x.y.z:1194
2014-02-06 19:24:09 us=754036 MANAGEMENT: >STATE:1391711049,WAIT,,,
2014-02-06 19:24:09 us=754104 UDPv4 WRITE [14] to w.x.y.z:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
2014-02-06 19:24:09 us=785254 UDPv4 READ [26] from w.x.y.z:1194: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
2014-02-06 19:24:09 us=785291 MANAGEMENT: >STATE:1391711049,AUTH,,,
2014-02-06 19:24:09 us=785306 TLS: Initial packet from w.x.y.z:1194, sid=6af8656c 6d9140c0
2014-02-06 19:24:09 us=785377 UDPv4 WRITE [22] to w.x.y.z:1194: P_ACK_V1 kid=0 [ 0 ]
2014-02-06 19:24:09 us=785441 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:24:09 us=785484 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:24:09 us=785524 UDPv4 WRITE [42] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=28
2014-02-06 19:24:09 us=803805 UDPv4 READ [22] from w.x.y.z:1194: P_ACK_V1 kid=0 [ 3 ]
2014-02-06 19:24:09 *Tunnelblick: openvpnstart starting OpenVPN
2014-02-06 19:24:11 us=61623 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:24:12 us=319366 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:24:16 us=92569 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:24:16 us=92686 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:24:24 us=874333 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:24:25 us=876178 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:24:40 us=855537 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:24:42 us=26484 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:25:09 us=336730 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
2014-02-06 19:25:09 us=336781 TLS Error: TLS handshake failed
2014-02-06 19:25:09 us=336899 TCP/UDP: Closing socket
2014-02-06 19:25:09 us=336925 SIGUSR1[soft,tls-error] received, process restarting
2014-02-06 19:25:09 us=336936 MANAGEMENT: >STATE:1391711109,RECONNECTING,tls-error,,
2014-02-06 19:25:09 us=341276 MANAGEMENT: CMD 'hold release'
2014-02-06 19:25:09 us=341310 WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
2014-02-06 19:25:09 us=341321 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
2014-02-06 19:25:09 us=341333 Re-using SSL/TLS context
2014-02-06 19:25:09 us=341343 LZO compression initialized
2014-02-06 19:25:09 us=341371 Control Channel MTU parms [ L:1558 D:138 EF:38 EB:0 ET:0 EL:0 ]
2014-02-06 19:25:09 us=341394 Socket Buffers: R=[42080->65536] S=[9216->65536]
2014-02-06 19:25:09 us=341406 MANAGEMENT: >STATE:1391711109,RESOLVE,,,
2014-02-06 19:25:09 us=341985 Data Channel MTU parms [ L:1558 D:1450 EF:58 EB:135 ET:0 EL:0 AF:3/1 ]
2014-02-06 19:25:09 us=342008 Local Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
2014-02-06 19:25:09 us=342016 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
2014-02-06 19:25:09 us=342030 Local Options hash (VER=V4): '66096c33'
2014-02-06 19:25:09 us=342041 Expected Remote Options hash (VER=V4): '691e95c7'
2014-02-06 19:25:09 us=342053 UDPv4 link local: [undef]
2014-02-06 19:25:09 us=342063 UDPv4 link remote: w.x.y.z:1194
2014-02-06 19:25:09 us=342079 MANAGEMENT: >STATE:1391711109,WAIT,,,
2014-02-06 19:25:09 us=342114 UDPv4 WRITE [14] to w.x.y.z:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
2014-02-06 19:25:09 us=364255 UDPv4 READ [26] from w.x.y.z:1194: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
2014-02-06 19:25:09 us=364293 MANAGEMENT: >STATE:1391711109,AUTH,,,
2014-02-06 19:25:09 us=364318 TLS: Initial packet from w.x.y.z:1194, sid=47baebe1 f7568543
2014-02-06 19:25:09 us=364374 UDPv4 WRITE [22] to w.x.y.z:1194: P_ACK_V1 kid=0 [ 0 ]
2014-02-06 19:25:09 us=364428 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:25:09 us=364469 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:25:09 us=364507 UDPv4 WRITE [42] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=28
2014-02-06 19:25:09 us=386636 UDPv4 READ [22] from w.x.y.z:1194: P_ACK_V1 kid=0 [ 3 ]
2014-02-06 19:25:11 us=494395 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:25:12 us=548329 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:25:15 us=710048 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:25:16 us=764014 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:25:23 us=518005 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:25:24 us=715662 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:25:39 us=91869 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:25:40 us=235662 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:26:09 us=260258 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
2014-02-06 19:26:09 us=260307 TLS Error: TLS handshake failed
2014-02-06 19:26:09 us=260402 TCP/UDP: Closing socket
2014-02-06 19:26:09 us=260428 SIGUSR1[soft,tls-error] received, process restarting
2014-02-06 19:26:09 us=260439 MANAGEMENT: >STATE:1391711169,RECONNECTING,tls-error,,
2014-02-06 19:26:09 us=264163 MANAGEMENT: CMD 'hold release'
2014-02-06 19:26:09 us=264198 WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
2014-02-06 19:26:09 us=264208 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
2014-02-06 19:26:09 us=264221 Re-using SSL/TLS context
2014-02-06 19:26:09 us=264231 LZO compression initialized
2014-02-06 19:26:09 us=264259 Control Channel MTU parms [ L:1558 D:138 EF:38 EB:0 ET:0 EL:0 ]
2014-02-06 19:26:09 us=264282 Socket Buffers: R=[42080->65536] S=[9216->65536]
2014-02-06 19:26:09 us=264294 MANAGEMENT: >STATE:1391711169,RESOLVE,,,
2014-02-06 19:26:09 us=264875 Data Channel MTU parms [ L:1558 D:1450 EF:58 EB:135 ET:0 EL:0 AF:3/1 ]
2014-02-06 19:26:09 us=264908 Local Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
2014-02-06 19:26:09 us=264923 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
2014-02-06 19:26:09 us=264944 Local Options hash (VER=V4): '66096c33'
2014-02-06 19:26:09 us=264964 Expected Remote Options hash (VER=V4): '691e95c7'
2014-02-06 19:26:09 us=264984 UDPv4 link local: [undef]
2014-02-06 19:26:09 us=265005 UDPv4 link remote: w.x.y.z:1194
2014-02-06 19:26:09 us=265031 MANAGEMENT: >STATE:1391711169,WAIT,,,
2014-02-06 19:26:09 us=265088 UDPv4 WRITE [14] to w.x.y.z:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
2014-02-06 19:26:09 us=284227 UDPv4 READ [26] from w.x.y.z:1194: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
2014-02-06 19:26:09 us=284260 MANAGEMENT: >STATE:1391711169,AUTH,,,
2014-02-06 19:26:09 us=284284 TLS: Initial packet from w.x.y.z:1194, sid=7bb448d6 07df6581
2014-02-06 19:26:09 us=284342 UDPv4 WRITE [22] to w.x.y.z:1194: P_ACK_V1 kid=0 [ 0 ]
2014-02-06 19:26:09 us=284397 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:26:09 us=284438 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:26:09 us=284477 UDPv4 WRITE [42] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=28
2014-02-06 19:26:09 us=302647 UDPv4 READ [22] from w.x.y.z:1194: P_ACK_V1 kid=0 [ 3 ]
2014-02-06 19:26:11 us=720472 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:26:12 us=929525 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:26:15 us=347357 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:26:16 us=556399 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:26:23 us=791008 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:26:24 us=993925 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:26:39 us=568450 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:26:40 us=791759 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:27:09 us=667417 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
2014-02-06 19:27:09 us=667461 TLS Error: TLS handshake failed
2014-02-06 19:27:09 us=667562 TCP/UDP: Closing socket
2014-02-06 19:27:09 us=667587 SIGUSR1[soft,tls-error] received, process restarting
2014-02-06 19:27:09 us=667597 MANAGEMENT: >STATE:1391711229,RECONNECTING,tls-error,,
2014-02-06 19:27:09 us=671954 MANAGEMENT: CMD 'hold release'
2014-02-06 19:27:09 us=671991 WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
2014-02-06 19:27:09 us=672001 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
2014-02-06 19:27:09 us=672014 Re-using SSL/TLS context
2014-02-06 19:27:09 us=672024 LZO compression initialized
2014-02-06 19:27:09 us=672055 Control Channel MTU parms [ L:1558 D:138 EF:38 EB:0 ET:0 EL:0 ]
2014-02-06 19:27:09 us=672078 Socket Buffers: R=[42080->65536] S=[9216->65536]
2014-02-06 19:27:09 us=672090 MANAGEMENT: >STATE:1391711229,RESOLVE,,,
2014-02-06 19:27:09 us=672616 Data Channel MTU parms [ L:1558 D:1450 EF:58 EB:135 ET:0 EL:0 AF:3/1 ]
2014-02-06 19:27:09 us=672639 Local Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
2014-02-06 19:27:09 us=672647 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
2014-02-06 19:27:09 us=672660 Local Options hash (VER=V4): '66096c33'
2014-02-06 19:27:09 us=672671 Expected Remote Options hash (VER=V4): '691e95c7'
2014-02-06 19:27:09 us=672683 UDPv4 link local: [undef]
2014-02-06 19:27:09 us=672693 UDPv4 link remote: w.x.y.z:1194
2014-02-06 19:27:09 us=672709 MANAGEMENT: >STATE:1391711229,WAIT,,,
2014-02-06 19:27:09 us=672745 UDPv4 WRITE [14] to w.x.y.z:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
2014-02-06 19:27:09 us=692105 UDPv4 READ [26] from w.x.y.z:1194: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
2014-02-06 19:27:09 us=692139 MANAGEMENT: >STATE:1391711229,AUTH,,,
2014-02-06 19:27:09 us=692163 TLS: Initial packet from w.x.y.z:1194, sid=ef8bad17 bb8bd18f
2014-02-06 19:27:09 us=692229 UDPv4 WRITE [22] to w.x.y.z:1194: P_ACK_V1 kid=0 [ 0 ]
2014-02-06 19:27:09 us=692283 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:27:09 us=692325 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:27:09 us=692364 UDPv4 WRITE [42] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=28
2014-02-06 19:27:09 us=710496 UDPv4 READ [22] from w.x.y.z:1194: P_ACK_V1 kid=0 [ 3 ]
2014-02-06 19:27:11 us=763476 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:27:12 us=789969 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:27:15 us=869305 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:27:16 us=895799 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:27:23 us=581527 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:27:24 us=783809 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:27:39 us=665264 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:27:40 us=716504 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:28:09 us=35283 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
2014-02-06 19:28:09 us=35543 TLS Error: TLS handshake failed
2014-02-06 19:28:09 us=35617 TCP/UDP: Closing socket
2014-02-06 19:28:09 us=35642 SIGUSR1[soft,tls-error] received, process restarting
2014-02-06 19:28:09 us=35653 MANAGEMENT: >STATE:1391711289,RECONNECTING,tls-error,,
2014-02-06 19:28:09 us=41610 MANAGEMENT: CMD 'hold release'
2014-02-06 19:28:09 us=41656 WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
2014-02-06 19:28:09 us=41666 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
2014-02-06 19:28:09 us=41680 Re-using SSL/TLS context
2014-02-06 19:28:09 us=41690 LZO compression initialized
2014-02-06 19:28:09 us=41724 Control Channel MTU parms [ L:1558 D:138 EF:38 EB:0 ET:0 EL:0 ]
2014-02-06 19:28:09 us=41748 Socket Buffers: R=[42080->65536] S=[9216->65536]
2014-02-06 19:28:09 us=41760 MANAGEMENT: >STATE:1391711289,RESOLVE,,,
2014-02-06 19:28:09 us=42293 Data Channel MTU parms [ L:1558 D:1450 EF:58 EB:135 ET:0 EL:0 AF:3/1 ]
2014-02-06 19:28:09 us=42318 Local Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
2014-02-06 19:28:09 us=42326 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
2014-02-06 19:28:09 us=42340 Local Options hash (VER=V4): '66096c33'
2014-02-06 19:28:09 us=42352 Expected Remote Options hash (VER=V4): '691e95c7'
2014-02-06 19:28:09 us=42364 UDPv4 link local: [undef]
2014-02-06 19:28:09 us=42374 UDPv4 link remote: w.x.y.z:1194
2014-02-06 19:28:09 us=42392 MANAGEMENT: >STATE:1391711289,WAIT,,,
2014-02-06 19:28:09 us=42433 UDPv4 WRITE [14] to w.x.y.z:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
2014-02-06 19:28:09 us=58903 UDPv4 READ [26] from w.x.y.z:1194: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
2014-02-06 19:28:09 us=58938 MANAGEMENT: >STATE:1391711289,AUTH,,,
2014-02-06 19:28:09 us=58963 TLS: Initial packet from w.x.y.z:1194, sid=74b1a9e9 24d21415
2014-02-06 19:28:09 us=59016 UDPv4 WRITE [22] to w.x.y.z:1194: P_ACK_V1 kid=0 [ 0 ]
2014-02-06 19:28:09 us=59090 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:28:09 us=59154 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:28:09 us=59208 UDPv4 WRITE [42] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=28
2014-02-06 19:28:09 us=75475 UDPv4 READ [22] from w.x.y.z:1194: P_ACK_V1 kid=0 [ 3 ]
2014-02-06 19:28:11 us=309767 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:28:12 us=427054 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:28:15 us=778518 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:28:16 us=895733 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:28:23 us=749406 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:28:24 us=904474 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:28:40 us=53910 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:28:40 us=54010 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:29:09 us=602555 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
2014-02-06 19:29:09 us=602601 TLS Error: TLS handshake failed
2014-02-06 19:29:09 us=602702 TCP/UDP: Closing socket
2014-02-06 19:29:09 us=602926 SIGUSR1[soft,tls-error] received, process restarting
2014-02-06 19:29:09 us=602939 MANAGEMENT: >STATE:1391711349,RECONNECTING,tls-error,,
2014-02-06 19:29:09 us=606988 MANAGEMENT: CMD 'hold release'
2014-02-06 19:29:09 us=607028 WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
2014-02-06 19:29:09 us=607039 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
2014-02-06 19:29:09 us=607052 Re-using SSL/TLS context
2014-02-06 19:29:09 us=607062 LZO compression initialized
2014-02-06 19:29:09 us=607096 Control Channel MTU parms [ L:1558 D:138 EF:38 EB:0 ET:0 EL:0 ]
2014-02-06 19:29:09 us=607119 Socket Buffers: R=[42080->65536] S=[9216->65536]
2014-02-06 19:29:09 us=607131 MANAGEMENT: >STATE:1391711349,RESOLVE,,,
2014-02-06 19:29:09 us=607668 Data Channel MTU parms [ L:1558 D:1450 EF:58 EB:135 ET:0 EL:0 AF:3/1 ]
2014-02-06 19:29:09 us=607693 Local Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
2014-02-06 19:29:09 us=607702 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-128-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
2014-02-06 19:29:09 us=607716 Local Options hash (VER=V4): '66096c33'
2014-02-06 19:29:09 us=607727 Expected Remote Options hash (VER=V4): '691e95c7'
2014-02-06 19:29:09 us=607739 UDPv4 link local: [undef]
2014-02-06 19:29:09 us=607750 UDPv4 link remote: w.x.y.z:1194
2014-02-06 19:29:09 us=607776 MANAGEMENT: >STATE:1391711349,WAIT,,,
2014-02-06 19:29:09 us=607818 UDPv4 WRITE [14] to w.x.y.z:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
2014-02-06 19:29:09 us=629625 UDPv4 READ [26] from w.x.y.z:1194: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 [ 0 ] pid=0 DATA len=0
2014-02-06 19:29:09 us=629648 MANAGEMENT: >STATE:1391711349,AUTH,,,
2014-02-06 19:29:09 us=629667 TLS: Initial packet from w.x.y.z:1194, sid=8a22d294 4a31fcee
2014-02-06 19:29:09 us=629717 UDPv4 WRITE [22] to w.x.y.z:1194: P_ACK_V1 kid=0 [ 0 ]
2014-02-06 19:29:09 us=629774 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:29:09 us=629816 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:29:09 us=629855 UDPv4 WRITE [42] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=28
2014-02-06 19:29:09 us=648391 UDPv4 READ [22] from w.x.y.z:1194: P_ACK_V1 kid=0 [ 3 ]
2014-02-06 19:29:12 us=114818 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:29:12 us=114940 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:29:17 us=47490 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100
2014-02-06 19:29:17 us=47588 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100
2014-02-06 19:29:17 *Tunnelblick: Disconnecting; notification window disconnect button pressed
2014-02-06 19:29:17 *Tunnelblick: Disconnecting using 'killall'
2014-02-06 19:29:17 us=859913 event_wait : Interrupted system call (code=4)
2014-02-06 19:29:17 us=860056 TCP/UDP: Closing socket
2014-02-06 19:29:17 us=860086 SIGTERM[hard,] received, process exiting
2014-02-06 19:29:17 us=860099 MANAGEMENT: >STATE:1391711357,EXITING,SIGTERM,,
2014-02-06 19:29:18 *Tunnelblick: No 'post-disconnect.sh' script to execute
2014-02-06 19:29:18 *Tunnelblick: Expected disconnection occurred.

================================================================================

Console Log:

2014-02-06 14:39:15 Tunnelblick[21830] DEBUG: killAllConnectionsIncludingDaemons: will kill individually
2014-02-06 14:39:15 Tunnelblick[21830] DEBUG: killAllConnectionsIncludingDaemons: Not requesting disconnection of 'SBSNew' (pid 21889) because it is set to connect when the computer starts.
2014-02-06 14:39:20 Tunnelblick[21830] Error: Timeout (5 seconds) waiting for OpenVPN process 21889 to terminate
2014-02-06 14:39:20 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:39:20 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 17061376
2014-02-06 14:39:20 Tunnelblick[21830] disconnect: while disconnecting
2014-02-06 14:39:36 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:39:36 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 17061376
2014-02-06 14:39:36 Tunnelblick[21830] disconnect: while disconnecting
2014-02-06 14:39:39 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:39:39 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 17061376
2014-02-06 14:39:39 Tunnelblick[21830] disconnect: while disconnecting
2014-02-06 14:39:46 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:39:46 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 17061376
2014-02-06 14:39:46 Tunnelblick[21830] disconnect: while disconnecting
2014-02-06 14:39:57 authexec[21915] executing /Applications/Tunnelblick.app/Contents/Resources/atsystemstart
2014-02-06 14:39:57 Tunnelblick[21830] SBSNew will NOT be connected when the computer starts
2014-02-06 14:39:57 Tunnelblick[21830] Preferences for 'SBSNew' say it should NOT connect when the computer starts and a launchd .plist existed but has been removed.
2014-02-06 14:40:09 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:40:09 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 17061376
2014-02-06 14:40:09 Tunnelblick[21830] disconnect: while disconnecting
2014-02-06 14:40:13 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:40:13 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 17061376
2014-02-06 14:40:13 Tunnelblick[21830] disconnect: while disconnecting
2014-02-06 14:40:13 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:40:13 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 17061376
2014-02-06 14:40:13 Tunnelblick[21830] disconnect: while disconnecting
2014-02-06 14:40:14 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:40:14 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 17061376
2014-02-06 14:40:14 Tunnelblick[21830] disconnect: while disconnecting
2014-02-06 14:40:14 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:40:14 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 17061376
2014-02-06 14:40:14 Tunnelblick[21830] disconnect: while disconnecting
2014-02-06 14:40:15 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:40:15 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 17061376
2014-02-06 14:40:15 Tunnelblick[21830] disconnect: while disconnecting
2014-02-06 14:40:15 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:40:15 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 17061376
2014-02-06 14:40:15 Tunnelblick[21830] disconnect: while disconnecting
2014-02-06 14:40:25 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:40:25 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 17061376
2014-02-06 14:40:25 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:40:25 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 17061376
2014-02-06 14:40:25 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:40:25 Tunnelblick[21830] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 17061376
2014-02-06 14:41:33 authexec[21976] executing /Applications/Tunnelblick.app/Contents/Resources/atsystemstart
2014-02-06 14:41:33 Tunnelblick[21830] SBSNew will be connected using '/Applications/Tunnelblick.app/Contents/Resources/openvpnstart' when the computer starts
2014-02-06 14:47:58 Tunnelblick[21830] applicationShouldTerminate: termination because of Quit; delayed until 'shutdownTunnelblick' finishes
2014-02-06 14:47:58 Tunnelblick[21830] DEBUG: shutDownTunnelblick: started.
2014-02-06 14:47:58 Tunnelblick[21830] DEBUG: shutDownTunnelblick: Starting cleanup.
2014-02-06 14:47:58 Tunnelblick[21830] DEBUG: Cleanup: Entering cleanup
2014-02-06 14:47:58 Tunnelblick[21830] DEBUG: Cleanup: Setting callDelegateOnNetworkChange: NO
2014-02-06 14:47:58 Tunnelblick[21830] DEBUG: Cleanup: Unloading kexts
2014-02-06 14:47:58 Tunnelblick[21830] DEBUG: Cleanup: Deleting logs
2014-02-06 14:47:58 Tunnelblick[21830] DEBUG: Cleanup: Removing status bar item
2014-02-06 14:47:58 Tunnelblick[21830] DEBUG: Cleanup: Unregistering hotKeyEventHandler
2014-02-06 14:47:58 Tunnelblick[21830] DEBUG: shutDownTunnelblick: Cleanup finished.
2014-02-06 14:47:58 Tunnelblick[21830] Finished shutting down Tunnelblick; allowing termination
2014-02-06 14:47:58 Tunnelblick[21830] DEBUG: applicationWillTerminate: invoked
2014-02-06 14:48:45 Tunnelblick[22172] Set program update feedURL to http://tunnelblick.net/appcast.rss
2014-02-06 14:48:45 Tunnelblick[22172] DEBUG: Updater: systemVersion 10.6.8 does not satisfy minimumSystemVersion 10.9.0
2014-02-06 14:48:45 Tunnelblick[22172] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 14:48:45 Tunnelblick[22172] DEBUG: Updater: systemVersion 10.6.8 satisfies maximumSystemVersion 10.8.9
2014-02-06 14:48:45 Tunnelblick[22172] DEBUG: Updater: Satisfies tunnelblickTests:appInApplications
                                        isNotDeployed
                                        doesNotHaveDeployBackups
2014-02-06 14:48:45 Tunnelblick[22172] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 14:48:45 Tunnelblick[22172] DEBUG: Updater: systemVersion 10.6.8 satisfies maximumSystemVersion 10.8.9
2014-02-06 14:48:45 Tunnelblick[22172] DEBUG: Updater: Satisfies tunnelblickTests:appInApplications
                                        isNotDeployed
                                        doesNotHaveDeployBackups
2014-02-06 14:49:25 Tunnelblick[22172] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:49:25 Tunnelblick[22172] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 8906752
2014-02-06 14:49:25 Tunnelblick[22172] DEBUG: killAllConnectionsIncludingDaemons: has checked for active daemons
2014-02-06 14:49:25 Tunnelblick[22172] DEBUG: includeDaemons = 0; noUnknownOpenVPNsRunning = 1; noActiveDaemons = 0; noDownRootsActive = 1
2014-02-06 14:49:25 Tunnelblick[22172] DEBUG: killAllConnectionsIncludingDaemons: will kill individually
2014-02-06 14:49:25 Tunnelblick[22172] DEBUG: killAllConnectionsIncludingDaemons: Not requesting disconnection of 'SBSNew' (pid 22183) because it is set to connect when the computer starts.
2014-02-06 14:49:30 Tunnelblick[22172] Error: Timeout (5 seconds) waiting for OpenVPN process 22183 to terminate
2014-02-06 14:49:33 Tunnelblick[22172] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:49:33 Tunnelblick[22172] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 8906752
2014-02-06 14:49:33 Tunnelblick[22172] disconnect: while disconnecting
2014-02-06 14:49:33 Tunnelblick[22172] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:49:33 Tunnelblick[22172] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 8906752
2014-02-06 14:49:33 Tunnelblick[22172] disconnect: while disconnecting
2014-02-06 14:49:37 Tunnelblick[22172] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:49:37 Tunnelblick[22172] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 8906752
2014-02-06 14:49:37 Tunnelblick[22172] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:49:37 Tunnelblick[22172] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 8906752
2014-02-06 14:49:37 Tunnelblick[22172] DEBUG: cancelAllIPCheckThreadsForConnection: Entered
2014-02-06 14:49:37 Tunnelblick[22172] DEBUG: cancelAllIPCheckThreadsForConnection: No active threads for connection 8906752
2014-02-06 14:55:24 Tunnelblick[22172] applicationShouldTerminate: termination because of Quit; delayed until 'shutdownTunnelblick' finishes
2014-02-06 14:55:24 Tunnelblick[22172] DEBUG: shutDownTunnelblick: started.
2014-02-06 14:55:24 Tunnelblick[22172] DEBUG: shutDownTunnelblick: Starting cleanup.
2014-02-06 14:55:24 Tunnelblick[22172] DEBUG: Cleanup: Entering cleanup
2014-02-06 14:55:24 Tunnelblick[22172] DEBUG: Cleanup: Setting callDelegateOnNetworkChange: NO
2014-02-06 14:55:24 Tunnelblick[22172] DEBUG: Cleanup: Unloading kexts
2014-02-06 14:55:24 Tunnelblick[22172] DEBUG: Cleanup: Deleting logs
2014-02-06 14:55:24 Tunnelblick[22172] DEBUG: Cleanup: Removing status bar item
2014-02-06 14:55:24 Tunnelblick[22172] DEBUG: Cleanup: Unregistering hotKeyEventHandler
2014-02-06 14:55:24 Tunnelblick[22172] DEBUG: shutDownTunnelblick: Cleanup finished.
2014-02-06 14:55:24 Tunnelblick[22172] Finished shutting down Tunnelblick; allowing termination
2014-02-06 14:55:24 Tunnelblick[22172] DEBUG: applicationWillTerminate: invoked
2014-02-06 14:55:38 Tunnelblick[22387] Tunnelblick cannot run when it is on /Volumes because the volume has the MNT_NOSUID statfs flag set.
2014-02-06 14:55:47 Tunnelblick[22387] Beginning installation or repair
2014-02-06 14:55:47 authexec[22396] executing /Volumes/Tunnelblick/Tunnelblick.app/Contents/Resources/installer
2014-02-06 14:55:48 Tunnelblick[22387] Installation or repair succeeded; Log:
                                       Tunnelblick installer started 2014-02-06 14:55:47. 1 arguments: 0x001f
                                       Moved /Applications/Tunnelblick.app to the Trash
                                       Copied /Volumes/Tunnelblick/Tunnelblick.app to /Applications/Tunnelblick.app
                                       Changed ownership of /Applications/Tunnelblick.app and its contents from 501:80 to 0:0
                                       Changed permissions from 755 to 744 on /Applications/Tunnelblick.app/Contents/Resources/atsystemstart
                                       Changed permissions from 755 to 744 on /Applications/Tunnelblick.app/Contents/Resources/installer
                                       Changed permissions from 755 to 744 on /Applications/Tunnelblick.app/Contents/Resources/leasewatch
                                       Changed permissions from 755 to 744 on /Applications/Tunnelblick.app/Contents/Resources/leasewatch3
                                       Changed permissions from 755 to 744 on /Applications/Tunnelblick.app/Contents/Resources/process-network-changes
                                       Changed permissions from 755 to 744 on /Applications/Tunnelblick.app/Contents/Resources/standardize-scutil-output
                                       Changed permissions from 755 to 744 on /Applications/Tunnelblick.app/Contents/Resources/client.up.tunnelblick.sh
                                       Changed permissions from 755 to 744 on /Applications/Tunnelblick.app/Contents/Resources/client.down.tunnelblick.sh
                                       Changed permissions from 644 to 744 on /Applications/Tunnelblick.app/Contents/Resources/client.route-pre-down.tunnelblick.sh
                                       Changed permissions from 644 to 744 on /Applications/Tunnelblick.app/Contents/Resources/client.1.up.tunnelblick.sh
                                       Changed permissions from 644 to 744 on /Applications/Tunnelblick.app/Contents/Resources/client.1.down.tunnelblick.sh
                                       Changed permissions from 644 to 744 on /Applications/Tunnelblick.app/Contents/Resources/client.2.up.tunnelblick.sh
                                       Changed permissions from 644 to 744 on /Applications/Tunnelblick.app/Contents/Resources/client.2.down.tunnelblick.sh
                                       Changed permissions from 755 to 744 on /Applications/Tunnelblick.app/Contents/Resources/client.3.up.tunnelblick.sh
                                       Changed permissions from 755 to 744 on /Applications/Tunnelblick.app/Contents/Resources/client.3.down.tunnelblick.sh
                                       Changed permissions from 755 to 744 on /Applications/Tunnelblick.app/Contents/Resources/openvpn/openvpn-2.2.1/openvpn-down-root.so
                                       Changed permissions from 755 to 744 on /Applications/Tunnelblick.app/Contents/Resources/openvpn/openvpn-2.3.2/openvpn-down-root.so
                                       Changed permissions from 755 to 4555 on /Applications/Tunnelblick.app/Contents/Resources/openvpnstart
2014-02-06 14:55:52 Tunnelblick[22387] applicationShouldTerminate: termination because of Quit; delayed until 'shutdownTunnelblick' finishes
2014-02-06 14:55:52 Tunnelblick[22387] Finished shutting down Tunnelblick; allowing termination
2014-02-06 14:55:52 Tunnelblick[22411] Set program update feedURL to https://www.tunnelblick.net/appcast-b.rss
2014-02-06 14:55:53 Tunnelblick[22411] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 14:55:53 Tunnelblick[22411] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 14:56:18 Tunnelblick[22411] Disconnecting via management interface
2014-02-06 14:57:44 Tunnelblick[22411] Disconnecting via management interface
2014-02-06 14:59:38 Tunnelblick[22411] Disconnecting via management interface
2014-02-06 15:18:00 Tunnelblick[22411] Disconnecting via management interface
2014-02-06 15:25:20 Tunnelblick[22411] Disconnecting via management interface
2014-02-06 15:25:42 authexec[23233] executing /Applications/Tunnelblick.app/Contents/Resources/atsystemstart
2014-02-06 15:25:42 Tunnelblick[22411] SBSNew will NOT be connected when the computer starts
2014-02-06 15:25:42 Tunnelblick[22411] Preferences for 'SBSNew' say it should NOT connect when the computer starts and a launchd .plist existed but has been removed.
2014-02-06 15:25:51 Tunnelblick[22411] Beginning installation or repair
2014-02-06 15:25:51 authexec[23244] executing /Applications/Tunnelblick.app/Contents/Resources/installer
2014-02-06 15:25:51 Tunnelblick[22411] Installation or repair succeeded; Log:
                                       Tunnelblick installer started 2014-02-06 15:25:51. 2 arguments: 0x2001 /Library/Application Support/Tunnelblick/Shared/SBSNew.tblk
                                       removed /Library/Application Support/Tunnelblick/Shared/SBSNew.tblk
                                       DEBUG 005: firstPath prefix DID NOT match:
                                            /Library/Application Support/Tunnelblick/Shared/SBSNew.tblk
                                            /Users/schaefer/Library/Application Support/Tunnelblick/Configurations
2014-02-06 15:25:51 Tunnelblick[22411] Uninstalled configuration file /Library/Application Support/Tunnelblick/Shared/SBSNew.tblk
2014-02-06 15:33:21 Tunnelblick[22411] applicationShouldTerminate: termination because of Quit; delayed until 'shutdownTunnelblick' finishes
2014-02-06 15:33:21 Tunnelblick[22411] Finished shutting down Tunnelblick; allowing termination
2014-02-06 15:33:23 Tunnelblick[23514] Set program update feedURL to https://www.tunnelblick.net/appcast-b.rss
2014-02-06 15:33:23 Tunnelblick[23514] Configuration installer: CR characters are being removed or replaced with LF characters in the installed copy of /Users/schaefer/Desktop/Hetzner.tblk/config.ovpn
2014-02-06 15:33:33 Tunnelblick[23514] Beginning installation or repair
2014-02-06 15:33:33 authexec[23524] executing /Applications/Tunnelblick.app/Contents/Resources/installer
2014-02-06 15:33:33 Tunnelblick[23514] Installation or repair succeeded; Log:
                                       Tunnelblick installer started 2014-02-06 15:33:33. 3 arguments: 0x0001 /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk /private/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/TunnelblickTemporaryDotTblk-fYIuyw/Hetzner.tblk
                                       Copied /private/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/TunnelblickTemporaryDotTblk-fYIuyw/Hetzner.tblk to /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk.temp
                                       Copied /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk.temp to /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk
                                       Changed ownership of /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk and its contents from 501:20 to 501:80
                                       Changed permissions from 755 to 750 on /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk
                                       Changed permissions from 755 to 750 on /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk/Contents
                                       Changed permissions from 755 to 750 on /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk/Contents/Resources
                                       Changed permissions from 755 to 640 on /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk/Contents/Resources/ca.crt
                                       Changed permissions from 644 to 640 on /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk/Contents/Resources/config.ovpn
                                       Changed permissions from 755 to 640 on /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk/Contents/Resources/oldsbs.crt
                                       Changed permissions from 755 to 640 on /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk/Contents/Resources/oldsbs.key
                                       Copied /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk to /Library/Application Support/Tunnelblick/Users/schaefer/Hetzner.tblk.temp
                                       Copied /Library/Application Support/Tunnelblick/Users/schaefer/Hetzner.tblk.temp to /Library/Application Support/Tunnelblick/Users/schaefer/Hetzner.tblk
                                       Changed ownership of /Library/Application Support/Tunnelblick/Users/schaefer/Hetzner.tblk and its contents from 501:80 to 0:0
                                       Changed permissions from 640 to 600 on /Library/Application Support/Tunnelblick/Users/schaefer/Hetzner.tblk/Contents/Resources/ca.crt
                                       Changed permissions from 640 to 600 on /Library/Application Support/Tunnelblick/Users/schaefer/Hetzner.tblk/Contents/Resources/config.ovpn
                                       Changed permissions from 640 to 600 on /Library/Application Support/Tunnelblick/Users/schaefer/Hetzner.tblk/Contents/Resources/oldsbs.crt
                                       Changed permissions from 640 to 600 on /Library/Application Support/Tunnelblick/Users/schaefer/Hetzner.tblk/Contents/Resources/oldsbs.key
                                       Created secure (shadow) copy of Hetzner.tblk
2014-02-06 15:33:34 Tunnelblick[23514] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 15:33:34 Tunnelblick[23514] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 15:47:51 Tunnelblick[23514] applicationShouldTerminate: termination because of Quit; delayed until 'shutdownTunnelblick' finishes
2014-02-06 15:47:51 Tunnelblick[23514] Finished shutting down Tunnelblick; allowing termination
2014-02-06 16:04:22 Tunnelblick[24274] Set program update feedURL to https://www.tunnelblick.net/appcast-b.rss
2014-02-06 16:04:22 Tunnelblick[24274] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 16:04:22 Tunnelblick[24274] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 16:04:32 Tunnelblick[24274] Beginning installation or repair
2014-02-06 16:04:32 authexec[24285] executing /Applications/Tunnelblick.app/Contents/Resources/installer
2014-02-06 16:04:32 Tunnelblick[24274] Installation or repair succeeded; Log:
                                       Tunnelblick installer started 2014-02-06 16:04:32. 2 arguments: 0x2001 /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk
                                       removed /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk
                                       DEBUG 005: firstPath prefix DID match:
                                            /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk
                                            /Users/schaefer/Library/Application Support/Tunnelblick/Configurations
                                       removed /Library/Application Support/Tunnelblick/Users/schaefer/Hetzner.tblk
2014-02-06 16:04:32 Tunnelblick[24274] Uninstalled configuration file /Users/schaefer/Library/Application Support/Tunnelblick/Configurations/Hetzner.tblk
2014-02-06 16:04:36 Tunnelblick[24274] applicationShouldTerminate: termination because of Quit; delayed until 'shutdownTunnelblick' finishes
2014-02-06 16:04:36 Tunnelblick[24274] Finished shutting down Tunnelblick; allowing termination
2014-02-06 16:04:41 Tunnelblick[24298] Set program update feedURL to https://www.tunnelblick.net/appcast-b.rss
2014-02-06 16:04:41 Tunnelblick[24298] Configuration installer: CR characters are being removed or replaced with LF characters in the installed copy of /Users/schaefer/Desktop/Hetzner.tblk/config.ovpn
2014-02-06 16:04:41 Tunnelblick[24298] Configuration installer: Failed to parse configuration path '/private/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/TunnelblickTemporaryDotTblk-yxg03K/Hetzner.tblk/Contents/Resources/config.ovpn':
                                       Line 9 of the file contains a non-printable character (0xC3) which is not allowed
2014-02-06 16:04:56 Tunnelblick[24298] applicationShouldTerminate: termination because of Quit; delayed until 'shutdownTunnelblick' finishes
2014-02-06 16:04:56 Tunnelblick[24298] Finished shutting down Tunnelblick; allowing termination
2014-02-06 16:05:59 Tunnelblick[24350] Set program update feedURL to https://www.tunnelblick.net/appcast-b.rss
2014-02-06 16:05:59 Tunnelblick[24350] Configuration installer: CR characters are being removed or replaced with LF characters in the installed copy of /Users/schaefer/Desktop/Hetzner.tblk/config.ovpn
2014-02-06 16:06:11 Tunnelblick[24350] Beginning installation or repair
2014-02-06 16:06:11 authexec[24361] executing /Applications/Tunnelblick.app/Contents/Resources/installer
2014-02-06 16:06:11 Tunnelblick[24350] Installation or repair succeeded; Log:
                                       Tunnelblick installer started 2014-02-06 16:06:11. 3 arguments: 0x0001 /Library/Application Support/Tunnelblick/Shared/Hetzner.tblk /private/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/TunnelblickTemporaryDotTblk-IuhORP/Hetzner.tblk
                                       Copied /private/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/TunnelblickTemporaryDotTblk-IuhORP/Hetzner.tblk to /Library/Application Support/Tunnelblick/Shared/Hetzner.tblk.temp
                                       Copied /Library/Application Support/Tunnelblick/Shared/Hetzner.tblk.temp to /Library/Application Support/Tunnelblick/Shared/Hetzner.tblk
                                       Changed ownership of /Library/Application Support/Tunnelblick/Shared/Hetzner.tblk and its contents from 501:20 to 0:0
                                       Changed permissions from 755 to 750 on /Library/Application Support/Tunnelblick/Shared/Hetzner.tblk
                                       Changed permissions from 755 to 750 on /Library/Application Support/Tunnelblick/Shared/Hetzner.tblk/Contents
                                       Changed permissions from 755 to 750 on /Library/Application Support/Tunnelblick/Shared/Hetzner.tblk/Contents/Resources
                                       Changed permissions from 644 to 600 on /Library/Application Support/Tunnelblick/Shared/Hetzner.tblk/Contents/Resources/config.ovpn
2014-02-06 16:06:11 Tunnelblick[24350] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 16:06:11 Tunnelblick[24350] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 16:07:31 Tunnelblick[24350] openvpnstart stderr from killall:
                                       stderr from killall: No matching processes were found
2014-02-06 16:20:35 Tunnelblick[24350] applicationShouldTerminate: termination because of Quit; delayed until 'shutdownTunnelblick' finishes
2014-02-06 16:20:35 Tunnelblick[24350] Finished shutting down Tunnelblick; allowing termination
2014-02-06 16:20:56 Tunnelblick[24727] Set program update feedURL to https://www.tunnelblick.net/appcast-b.rss
2014-02-06 16:20:57 Tunnelblick[24727] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 16:20:57 Tunnelblick[24727] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 16:31:21 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/ipp.txt' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:31:21 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/openvpn-status.log' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:31:21 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/index.txt' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:31:21 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/index.txt.attr' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:31:21 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/index.txt.attr.old' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:31:21 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/index.txt.old' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:31:21 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/oldsbs.csr' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:31:21 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/w.x.y.z.csr' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:31:21 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/serial' does not have an extension. Each file in a Tunnelblick VPN Configuration must have an extension. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:31:21 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/serial.old' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:31:21 Tunnelblick[24727] Configuration installer: Failed to parse configuration path '/private/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/TunnelblickTemporaryDotTblk-NPvyxv/openvpn.tblk/Contents/Resources/config.ovpn':
                                       line 224: The 'status' option is not allowed in an OpenVPN configuration file that is in a Tunnelblick VPN Configuration unless the file it references is specified with an absolute path.
2014-02-06 16:32:04 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/index.txt' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:32:04 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/index.txt.attr' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:32:04 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/index.txt.attr.old' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:32:04 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/index.txt.old' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:32:04 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/oldsbs.csr' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:32:04 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/w.x.y.z.csr' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:32:04 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/serial' does not have an extension. Each file in a Tunnelblick VPN Configuration must have an extension. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:32:04 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/serial.old' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:32:04 Tunnelblick[24727] Configuration installer: Failed to parse configuration path '/private/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/TunnelblickTemporaryDotTblk-lnAVJm/openvpn.tblk/Contents/Resources/config.ovpn':
                                       line 224: The 'status' option is not allowed in an OpenVPN configuration file that is in a Tunnelblick VPN Configuration unless the file it references is specified with an absolute path.
2014-02-06 16:32:57 Tunnelblick[24727] Configuration installer: File '/Users/schaefer/Desktop/openvpn.tblk/pki/oldsbs.csr' has an extension that may not appear in a Tunnelblick VPN Configuration. Tunnelblick uses the extension to determine how to secure the file properly.
2014-02-06 16:32:57 Tunnelblick[24727] Configuration installer: Failed to parse configuration path '/private/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/TunnelblickTemporaryDotTblk-n9Za4r/openvpn.tblk/Contents/Resources/config.ovpn':
                                       line 224: The 'status' option is not allowed in an OpenVPN configuration file that is in a Tunnelblick VPN Configuration unless the file it references is specified with an absolute path.
2014-02-06 16:33:11 Tunnelblick[24727] Configuration installer: Failed to parse configuration path '/private/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/TunnelblickTemporaryDotTblk-NrDll4/openvpn.tblk/Contents/Resources/config.ovpn':
                                       line 224: The 'status' option is not allowed in an OpenVPN configuration file that is in a Tunnelblick VPN Configuration unless the file it references is specified with an absolute path.
2014-02-06 16:34:09 Tunnelblick[24727] Beginning installation or repair
2014-02-06 16:34:09 authexec[25091] executing /Applications/Tunnelblick.app/Contents/Resources/installer
2014-02-06 16:34:09 Tunnelblick[24727] Installation or repair succeeded; Log:
                                       Tunnelblick installer started 2014-02-06 16:34:09. 3 arguments: 0x0001 /Library/Application Support/Tunnelblick/Shared/openvpn.tblk /private/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/TunnelblickTemporaryDotTblk-qSGKlN/openvpn.tblk
                                       Copied /private/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/TunnelblickTemporaryDotTblk-qSGKlN/openvpn.tblk to /Library/Application Support/Tunnelblick/Shared/openvpn.tblk.temp
                                       Copied /Library/Application Support/Tunnelblick/Shared/openvpn.tblk.temp to /Library/Application Support/Tunnelblick/Shared/openvpn.tblk
                                       Changed ownership of /Library/Application Support/Tunnelblick/Shared/openvpn.tblk and its contents from 501:20 to 0:0
                                       Changed permissions from 755 to 750 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk
                                       Changed permissions from 755 to 750 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk/Contents
                                       Changed permissions from 755 to 750 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk/Contents/Resources
                                       Changed permissions from 644 to 600 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk/Contents/Resources/ca.crt
                                       Changed permissions from 644 to 600 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk/Contents/Resources/config.ovpn
                                       Changed permissions from 644 to 600 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk/Contents/Resources/dh2048.pem
                                       Changed permissions from 644 to 600 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk/Contents/Resources/oldsbs.crt
                                       Changed permissions from 644 to 600 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk/Contents/Resources/w.x.y.z.crt
2014-02-06 16:36:20 Tunnelblick[24727] Beginning installation or repair
2014-02-06 16:36:20 authexec[25148] executing /Applications/Tunnelblick.app/Contents/Resources/installer
2014-02-06 16:36:20 Tunnelblick[24727] Installation or repair succeeded; Log:
                                       Tunnelblick installer started 2014-02-06 16:36:20. 2 arguments: 0x2001 /Library/Application Support/Tunnelblick/Shared/openvpn.tblk
                                       removed /Library/Application Support/Tunnelblick/Shared/openvpn.tblk
                                       DEBUG 005: firstPath prefix DID NOT match:
                                            /Library/Application Support/Tunnelblick/Shared/openvpn.tblk
                                            /Users/schaefer/Library/Application Support/Tunnelblick/Configurations
2014-02-06 16:36:20 Tunnelblick[24727] Uninstalled configuration file /Library/Application Support/Tunnelblick/Shared/openvpn.tblk
2014-02-06 16:38:06 Tunnelblick[24727] Beginning installation or repair
2014-02-06 16:38:06 authexec[25204] executing /Applications/Tunnelblick.app/Contents/Resources/installer
2014-02-06 16:38:06 Tunnelblick[24727] Installation or repair succeeded; Log:
                                       Tunnelblick installer started 2014-02-06 16:38:06. 3 arguments: 0x0001 /Library/Application Support/Tunnelblick/Shared/openvpn.tblk /private/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/TunnelblickTemporaryDotTblk-MRfxTP/openvpn.tblk
                                       Copied /private/var/folders/7S/7SgVFloYFoOC292miCwQ3E+++TI/-Tmp-/TunnelblickTemporaryDotTblk-MRfxTP/openvpn.tblk to /Library/Application Support/Tunnelblick/Shared/openvpn.tblk.temp
                                       Copied /Library/Application Support/Tunnelblick/Shared/openvpn.tblk.temp to /Library/Application Support/Tunnelblick/Shared/openvpn.tblk
                                       Changed ownership of /Library/Application Support/Tunnelblick/Shared/openvpn.tblk and its contents from 501:20 to 0:0
                                       Changed permissions from 755 to 750 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk
                                       Changed permissions from 755 to 750 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk/Contents
                                       Changed permissions from 755 to 750 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk/Contents/Resources
                                       Changed permissions from 644 to 600 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk/Contents/Resources/ca.crt
                                       Changed permissions from 644 to 600 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk/Contents/Resources/config.ovpn
                                       Changed permissions from 644 to 600 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk/Contents/Resources/dh2048.pem
                                       Changed permissions from 644 to 600 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk/Contents/Resources/oldsbs.crt
                                       Changed permissions from 644 to 600 on /Library/Application Support/Tunnelblick/Shared/openvpn.tblk/Contents/Resources/w.x.y.z.crt
2014-02-06 17:34:06 Tunnelblick[24727] applicationShouldTerminate: termination because of Quit; delayed until 'shutdownTunnelblick' finishes
2014-02-06 17:34:06 Tunnelblick[24727] Finished shutting down Tunnelblick; allowing termination
2014-02-06 17:34:14 Tunnelblick[26602] Set program update feedURL to https://www.tunnelblick.net/appcast-b.rss
2014-02-06 17:34:15 Tunnelblick[26602] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 17:34:15 Tunnelblick[26602] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 18:04:53 Tunnelblick[26602] applicationShouldTerminate: termination because of Quit; delayed until 'shutdownTunnelblick' finishes
2014-02-06 18:04:54 Tunnelblick[26602] Finished shutting down Tunnelblick; allowing termination
2014-02-06 19:23:57 Tunnelblick[29058] Set program update feedURL to https://www.tunnelblick.net/appcast-b.rss
2014-02-06 19:23:58 Tunnelblick[29058] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0
2014-02-06 19:23:58 Tunnelblick[29058] DEBUG: Updater: systemVersion 10.6.8 satisfies minimumSystemVersion 10.4.0

================================================================================

Non-Apple kexts that are loaded:

Index Refs Address            Size       Wired      Name (Version) <Linked Against>
  118    3 0xffffff7f815ee000 0x3c000    0x3c000    org.virtualbox.kext.VBoxDrv (4.3.4) <7 5 4 3 1>
  119    0 0xffffff7f8162a000 0x8000     0x8000     org.virtualbox.kext.VBoxUSB (4.3.4) <118 38 29 7 5 4 3 1>
  120    0 0xffffff7f81632000 0x5000     0x5000     org.virtualbox.kext.VBoxNetFlt (4.3.4) <118 7 5 4 3 1>
  121    0 0xffffff7f81637000 0x6000     0x6000     org.virtualbox.kext.VBoxNetAdp (4.3.4) <118 5 4 1>

[jkbull...gmail.com]

This:

2014-02-06 19:26:09 us=284284 TLS: Initial packet from w.x.y.z:1194, sid=7bb448d6 07df6581

2014-02-06 19:26:09 us=284342 UDPv4 WRITE [22] to w.x.y.z:1194: P_ACK_V1 kid=0 [ 0 ]

2014-02-06 19:26:09 us=284397 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100

2014-02-06 19:26:09 us=284438 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100

2014-02-06 19:26:09 us=284477 UDPv4 WRITE [42] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=3 DATA len=28

2014-02-06 19:26:09 us=302647 UDPv4 READ [22] from w.x.y.z:1194: P_ACK_V1 kid=0 [ 3 ]

shows that OpenVPN contacted the VPN server and received an acknowledgement. (That means that your computer was able to contact the OpenVPN server -- that is, there is probably not a network problem.)

The following (which immediately follows the foregoing):

 

2014-02-06 19:26:11 us=720472 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100

2014-02-06 19:26:12 us=929525 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100

2014-02-06 19:26:15 us=347357 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100

2014-02-06 19:26:16 us=556399 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100

2014-02-06 19:26:23 us=791008 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100

2014-02-06 19:26:24 us=993925 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100

2014-02-06 19:26:39 us=568450 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=1 DATA len=100

2014-02-06 19:26:40 us=791759 UDPv4 WRITE [114] to w.x.y.z:1194: P_CONTROL_V1 kid=0 [ ] pid=2 DATA len=100

2014-02-06 19:27:09 us=667417 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)

shows that when OpenVPN tried to negotiate a TLS key, the OpenVPN server did not respond.

The problem may be that your client configuration is missing a "tls-client" option.

You should consult with the OpenVPN documentation and/or experts:

• OpenVPN FAQ
• OpenVPN 2.3 Man Page
• OpenVPN Documentation
• OpenVPN Users Forum
• OpenVPN Users Mailing List

It is also possible, though apparently unlikely in your case, that a firewall between your computer and the OpenVPN server is blocking communication after the initial "ACK".

[Marc Schaefer]

I did some further testing and think I found the problem.

I observed that after the initial packet something blocked the conversation. When I switched to TCP everything worked out fine so  only UDP is affected.

So I had a look at the kernel log while starting a connection which leads to following lines:

kernel    in_delayed_cksum_offset: ip_len 36352 (142) doesn't match actual length 156


Googling showed up only few references, one having DNS problems, the others a UDP related service as well. And all of them had one common point, Virtualbox.

One could show that shutting down Virtualbox solved the problem, so it seems there's a problem with the network plugins introduced by Virtualbox.

I'll be able to test it during the weekend when the MAc isn't used and will report my results


Regards

Marc

[Marc Schaefer]

Some more testing and a resolution. :)

I shutdown all Vms, shutdown Virtualbox and rebooted

The VPN works flawlessly now on UDP


Regards

Marc