Can't get tunnelblick to open VPN

531 views

Skip to first unread message

Marc Schaefer

unread,

Feb 6, 2014, 11:52:03 AM2/6/14

to tunnelbli...@googlegroups.com

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

unread,

Feb 6, 2014, 12:27:02 PM2/6/14

to tunnelbli...@googlegroups.com

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

Marc Schaefer

unread,

Feb 6, 2014, 1:32:02 PM2/6/14

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

unread,

Feb 6, 2014, 1:51:21 PM2/6/14

to tunnelbli...@googlegroups.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:

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

unread,

Feb 7, 2014, 9:09:07 AM2/7/14

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

unread,

Feb 9, 2014, 1:42:34 PM2/9/14

to tunnelbli...@googlegroups.com

Some more testing and a resolution. :)

I shutdown all Vms, shutdown Virtualbox and rebooted

The VPN works flawlessly now on UDP

Regards

Marc

Reply all

Reply to author

Forward

0 new messages