Verbosity increased to 5.
Server log:
Sat Apr 27 17:18:46 2013 us=508253 Current Parameter Settings:
Sat Apr 27 17:18:46 2013 us=508367 config = '/etc/openvpn/cdp.conf'
Sat Apr 27 17:18:46 2013 us=508384 mode = 1
Sat Apr 27 17:18:46 2013 us=508399 persist_config = DISABLED
Sat Apr 27 17:18:46 2013 us=508409 persist_mode = 1
Sat Apr 27 17:18:46 2013 us=508418 show_ciphers = DISABLED
Sat Apr 27 17:18:46 2013 us=508427 show_digests = DISABLED
Sat Apr 27 17:18:46 2013 us=508436 show_engines = DISABLED
Sat Apr 27 17:18:46 2013 us=508445 genkey = DISABLED
Sat Apr 27 17:18:46 2013 us=508454 key_pass_file = '[UNDEF]'
Sat Apr 27 17:18:46 2013 us=508463 show_tls_ciphers = DISABLED
Sat Apr 27 17:18:46 2013 us=508472 Connection profiles [default]:
Sat Apr 27 17:18:46 2013 us=508482 proto = udp
Sat Apr 27 17:18:46 2013 us=508491 local = '[UNDEF]'
Sat Apr 27 17:18:46 2013 us=508500 local_port = 1194
Sat Apr 27 17:18:46 2013 us=508509 remote = '[UNDEF]'
Sat Apr 27 17:18:46 2013 us=508518 remote_port = 1194
Sat Apr 27 17:18:46 2013 us=508526 remote_float = DISABLED
Sat Apr 27 17:18:46 2013 us=508538 bind_defined = DISABLED
Sat Apr 27 17:18:46 2013 us=508547 bind_local = ENABLED
Sat Apr 27 17:18:46 2013 us=508563 NOTE: --mute triggered...
Sat Apr 27 17:18:46 2013 us=508593 244 variation(s) on previous 20 message(s) suppressed by --mute
Sat Apr 27 17:18:46 2013 us=508608 OpenVPN 2.1.0 x86_64-pc-linux-gnu [SSL] [LZO2] [EPOLL] [PKCS11] [MH] [PF_INET6] [eurephia] built on Jul 20 2010
Sat Apr 27 17:18:46 2013 us=508847 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Sat Apr 27 17:18:46 2013 us=508965 Initializing OpenSSL support for engine 'aesni'
Sat Apr 27 17:18:46 2013 us=511267 Diffie-Hellman initialized with 1024 bit key
Sat Apr 27 17:18:46 2013 us=512000 /usr/bin/openssl-vulnkey -q -b 1024 -m <modulus omitted>
Sat Apr 27 17:18:46 2013 us=707634 Control Channel Authentication: using 'ta.key' as a OpenVPN static key file
Sat Apr 27 17:18:46 2013 us=707790 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Sat Apr 27 17:18:46 2013 us=707819 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Sat Apr 27 17:18:46 2013 us=707859 TLS-Auth MTU parms [ L:1558 D:166 EF:66 EB:0 ET:0 EL:0 ]
Sat Apr 27 17:18:46 2013 us=710184 TUN/TAP device tun0 opened
Sat Apr 27 17:18:46 2013 us=710242 TUN/TAP TX queue length set to 100
Sat Apr 27 17:18:46 2013 us=710290 /sbin/ifconfig tun0 172.16.32.1 netmask 255.255.255.0 mtu 1500 broadcast 172.16.32.255
Sat Apr 27 17:18:46 2013 us=719638 Data Channel MTU parms [ L:1558 D:1450 EF:58 EB:135 ET:0 EL:0 AF:3/1 ]
Sat Apr 27 17:18:46 2013 us=725383 GID set to nogroup
Sat Apr 27 17:18:46 2013 us=725517 UID set to nobody
Sat Apr 27 17:18:46 2013 us=725588 Socket Buffers: R=[124928->131072] S=[124928->131072]
Sat Apr 27 17:18:46 2013 us=725624 UDPv4 link local (bound): [undef]
Sat Apr 27 17:18:46 2013 us=725634 UDPv4 link remote: [undef]
Sat Apr 27 17:18:46 2013 us=725653 MULTI: multi_init called, r=256 v=256
Sat Apr 27 17:18:46 2013 us=725812 IFCONFIG POOL: base=172.16.32.2 size=252
Sat Apr 27 17:18:46 2013 us=725877 IFCONFIG POOL LIST
Sat Apr 27 17:18:46 2013 us=725891 paul-crown,172.16.32.2
Sat Apr 27 17:18:46 2013 us=725927 Initialization Sequence Completed
Log after reconnect:
Sat Apr 27 17:19:20 2013 us=578480 MULTI: multi_create_instance called
Sat Apr 27 17:19:20 2013 us=578681 dyn.am.ic.234:58858 Re-using SSL/TLS context
Sat Apr 27 17:19:20 2013 us=578823 dyn.am.ic.234:58858 LZO compression initialized
Sat Apr 27 17:19:20 2013 us=579338 dyn.am.ic.234:58858 Control Channel MTU parms [ L:1558 D:166 EF:66 EB:0 ET:0 EL:0 ]
Sat Apr 27 17:19:20 2013 us=579409 dyn.am.ic.234:58858 Data Channel MTU parms [ L:1558 D:1450 EF:58 EB:135 ET:0 EL:0 AF:3/1 ]
Sat Apr 27 17:19:20 2013 us=579518 dyn.am.ic.234:58858 Local Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 0,cipher AES-256-CBC,auth SHA1,keysize 256,tls-auth,key-method 2,tls-server'
Sat Apr 27 17:19:20 2013 us=579554 dyn.am.ic.234:58858 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1558,tun-mtu 1500,proto UDPv4,comp-lzo,keydir 1,cipher AES-256-CBC,auth SHA1,keysize 256,tls-auth,key-method 2,tls-client'
Sat Apr 27 17:19:20 2013 us=579644 dyn.am.ic.234:58858 Local Options hash (VER=V4): '162b04de'
Sat Apr 27 17:19:20 2013 us=579680 dyn.am.ic.234:58858 Expected Remote Options hash (VER=V4): '9e7066d2'
RSat Apr 27 17:19:20 2013 us=579869 dyn.am.ic.234:58858 TLS: Initial packet from [AF_INET]dyn.am.ic.234:58858, sid=450d9cb5 e416a2f5
WRRWWWWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRRRRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRSat Apr 27 17:19:21 2013 us=163171 dyn.am.ic.234:58858 VERIFY OK: depth=1, /C=US/ST=CO/L=Denver/O=Company_Name_LLC/CN=Company_Name_LLC_CA/emailAddress=_dns-admin@domain.com
Sat Apr 27 17:19:21 2013 us=163751 dyn.am.ic.234:58858 VERIFY OK: depth=0, /C=US/ST=CO/L=Denver/O=Company_Name_LLC/CN=paul-crown/emailAddress=_dns-admin@domain.com
WRWRWRWWWWRWRWRWRWRWRWRWRWRWRWRRRRWRWRWRSat Apr 27 17:19:21 2013 us=394736 dyn.am.ic.234:58858 Data Channel Encrypt: Cipher 'AES-256-CBC' initialized with 256 bit key
Sat Apr 27 17:19:21 2013 us=394798 dyn.am.ic.234:58858 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Sat Apr 27 17:19:21 2013 us=394828 dyn.am.ic.234:58858 Data Channel Decrypt: Cipher 'AES-256-CBC' initialized with 256 bit key
Sat Apr 27 17:19:21 2013 us=394854 dyn.am.ic.234:58858 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
WWWRRRSat Apr 27 17:19:21 2013 us=436424 dyn.am.ic.234:58858 Control Channel: TLSv1, cipher TLSv1/SSLv3 AES256-SHA, 1024 bit RSA
Sat Apr 27 17:19:21 2013 us=436516 dyn.am.ic.234:58858 [paul-crown] Peer Connection Initiated with [AF_INET]dyn.am.ic.234:58858
Sat Apr 27 17:19:21 2013 us=436622 paul-crown/dyn.am.ic.234:58858 OPTIONS IMPORT: reading client specific options from: ccd/paul-crown
Sat Apr 27 17:19:21 2013 us=436889 paul-crown/dyn.am.ic.234:58858 MULTI: Learn: 172.16.32.6 -> paul-crown/dyn.am.ic.234:58858
Sat Apr 27 17:19:21 2013 us=436922 paul-crown/dyn.am.ic.234:58858 MULTI: primary virtual IP for paul-crown/dyn.am.ic.234:58858: 172.16.32.6
RSat Apr 27 17:19:23 2013 us=541471 paul-crown/dyn.am.ic.234:58858 PUSH: Received control message: 'PUSH_REQUEST'
Sat Apr 27 17:19:23 2013 us=541676 paul-crown/dyn.am.ic.234:58858 SENT CONTROL [paul-crown]: 'PUSH_REPLY,route-gateway 172.16.32.1,topology subnet,ping 10,ping-restart 120,ifconfig 172.16.32.6 255.255.255.0' (status=1)
I attempted to ping 172.16.32.1 with no response on the client side. On the server side, the log file continued to grow with the following the longer I let the client attempt to ping.
WRWRRWWRWRRWRWRwRwRwRwRwRwRwRwRwRwRWwRwRwRwRwRwRwRwRwRwRWwRwRwRwRwRwRwRwRwRwRWwRwRwRwRwRwRwRwRwRWwRwRwRwRwRwRwRwRwRwRWwRwRwRwRwRwRwRwRwRw
Pinging the Server's public IP pu.bl.ic.165 works fine, so I know the server does respond to ping requests.