OpenVpn over SSL: Assertion failed at misc.c:785

user1314

Hi there. My first post here. Been advised from another forum to try my luck here.
Here's my problem: I can't connect my openvpn client over stunnel. I just can't figure out what I'm doing wrong. Tried difference distros, but no luck. Actually, every time I'm connecting from fresh install ubuntu, xubuntu or debian I succeed once. But after that getting same error. I have no problems connecting stunnel or openvpn separately. I would really appreciate if anyone pointed me to the right direction. Thanks!

OpenVPN

client
dev tun
proto tcp
remote 127.0.0.1 1413
resolv-retry infinite
nobind
persist-key
persist-tun
remote-cert-tls server
cipher AES-256-CBC
comp-lzo no
route-delay 5
verb 5
route 89.238.166.236 255.255.255.255 net_gateway
<ca>
</ca>
<cert>
</cert>
<key>
</key>
key-direction 1
<tls-auth>
</tls-auth>

Stunnel

foreground = yes
pid = /tmp/stunnel4.pid
options = NO_SSLv2
client = yes
debug = 7

[openvpn]
accept = 127.0.0.1:1413
connect = 89.238.166.236:443
TIMEOUTclose = 120
verify = 3
CAfile = /etc/stunnel/stunnel.crt

Stunnel log

2016.10.19 14:53:02 LOG7[1569]: Clients allowed=32000
2016.10.19 14:53:02 LOG5[1569]: stunnel 5.06 on x86_64-pc-linux-gnu platform
2016.10.19 14:53:02 LOG5[1569]: Compiled with OpenSSL 1.0.1k 8 Jan 2015
2016.10.19 14:53:02 LOG5[1569]: Running with OpenSSL 1.0.1t 3 May 2016
2016.10.19 14:53:02 LOG5[1569]: Update OpenSSL shared libraries or rebuild stunnel
2016.10.19 14:53:02 LOG5[1569]: Threading:PTHREAD Sockets:POLL,IPv6,SYSTEMD SSL:ENGINE,OCSP,FIPS Auth:LIBWRAP
2016.10.19 14:53:02 LOG7[1569]: errno: (*__errno_location ())
2016.10.19 14:53:02 LOG5[1569]: Reading configuration from file /etc/stunnel/stunnel.conf
2016.10.19 14:53:02 LOG5[1569]: FIPS mode disabled
2016.10.19 14:53:02 LOG7[1569]: Compression disabled
2016.10.19 14:53:02 LOG7[1569]: PRNG seeded successfully
2016.10.19 14:53:02 LOG6[1569]: Initializing service [openvpn]
2016.10.19 14:53:02 LOG7[1569]: No private key specified
2016.10.19 14:53:02 LOG7[1569]: Loaded /etc/stunnel/stunnel.crt revocation lookup file
2016.10.19 14:53:02 LOG7[1569]: SSL options: 0x03000004 (+0x03000000, -0x00000000)
2016.10.19 14:53:02 LOG5[1569]: Configuration successful
2016.10.19 14:53:02 LOG7[1569]: Listening file descriptor created (FD=7)
2016.10.19 14:53:02 LOG7[1569]: Service [openvpn] (FD=7) bound to 127.0.0.1:1413
2016.10.19 14:53:02 LOG7[1569]: Created pid file /tmp/stunnel4.pid
2016.10.19 14:53:03 LOG7[1569]: Service [openvpn] accepted (FD=3) from 127.0.0.1:46027
2016.10.19 14:53:03 LOG7[1570]: Service [openvpn] started
2016.10.19 14:53:03 LOG5[1570]: Service [openvpn] accepted connection from 127.0.0.1:46027
2016.10.19 14:53:03 LOG6[1570]: s_connect: connecting 89.238.166.236:443
2016.10.19 14:53:03 LOG7[1570]: s_connect: s_poll_wait 89.238.166.236:443: waiting 10 seconds
2016.10.19 14:53:03 LOG5[1570]: s_connect: connected 89.238.166.236:443
2016.10.19 14:53:03 LOG5[1570]: Service [openvpn] connected remote server from 192.168.0.9:35571
2016.10.19 14:53:03 LOG7[1570]: Remote socket (FD=8) initialized
2016.10.19 14:53:03 LOG6[1570]: SNI: sending servername: 89.238.166.236
2016.10.19 14:53:03 LOG7[1570]: SSL state (connect): before/connect initialization
2016.10.19 14:53:03 LOG7[1570]: SSL state (connect): SSLv2/v3 write client hello A
2016.10.19 14:53:03 LOG7[1570]: SSL state (connect): unknown state
2016.10.19 14:53:03 LOG7[1570]: Verification started at depth=0: C=IT, ST=Italy, L=Perugia, O=AirVPN, OU=stunnel, CN=stunnel.airvpn.org, emailAddress=info@airvpn.org
2016.10.19 14:53:03 LOG7[1570]: CERT: preverify ok
2016.10.19 14:53:03 LOG6[1570]: CERT: Locally installed certificate matched
2016.10.19 14:53:03 LOG5[1570]: Certificate accepted at depth=0: C=IT, ST=Italy, L=Perugia, O=AirVPN, OU=stunnel, CN=stunnel.airvpn.org, emailAddress=info@airvpn.org
2016.10.19 14:53:03 LOG7[1570]: SSL state (connect): unknown state
2016.10.19 14:53:03 LOG7[1570]: SSL state (connect): unknown state
2016.10.19 14:53:03 LOG7[1570]: SSL state (connect): unknown state
2016.10.19 14:53:03 LOG7[1570]: SSL state (connect): unknown state
2016.10.19 14:53:03 LOG7[1570]: SSL state (connect): unknown state
2016.10.19 14:53:03 LOG7[1570]: SSL state (connect): unknown state
2016.10.19 14:53:03 LOG7[1570]: SSL state (connect): unknown state
2016.10.19 14:53:03 LOG7[1570]: SSL state (connect): unknown state
2016.10.19 14:53:03 LOG7[1570]: SSL state (connect): unknown state
2016.10.19 14:53:03 LOG7[1570]: 0 items in the session cache
2016.10.19 14:53:03 LOG7[1570]: 1 client connects (SSL_connect())
2016.10.19 14:53:03 LOG7[1570]: 1 client connects that finished
2016.10.19 14:53:03 LOG7[1570]: 0 client renegotiations requested
2016.10.19 14:53:03 LOG7[1570]: 0 server connects (SSL_accept())
2016.10.19 14:53:03 LOG7[1570]: 0 server connects that finished
2016.10.19 14:53:03 LOG7[1570]: 0 server renegotiations requested
2016.10.19 14:53:03 LOG7[1570]: 0 session cache hits
2016.10.19 14:53:03 LOG7[1570]: 0 external session cache hits
2016.10.19 14:53:03 LOG7[1570]: 0 session cache misses
2016.10.19 14:53:03 LOG7[1570]: 0 session cache timeouts
2016.10.19 14:53:03 LOG6[1570]: SSL connected: new session negotiated
2016.10.19 14:53:03 LOG7[1570]: Peer certificate was cached (2122 bytes)
2016.10.19 14:53:03 LOG6[1570]: Negotiated TLSv1.2 ciphersuite ECDHE-RSA-AES256-GCM-SHA384 (256-bit encryption)
2016.10.19 14:53:03 LOG6[1570]: Compression: null, expansion: null
2016.10.19 15:00:13 LOG7[1569]: Service [openvpn] accepted (FD=9) from 127.0.0.1:46029
2016.10.19 15:00:13 LOG7[2012]: Service [openvpn] started
2016.10.19 15:00:13 LOG5[2012]: Service [openvpn] accepted connection from 127.0.0.1:46029
2016.10.19 15:00:13 LOG6[2012]: s_connect: connecting 89.238.166.236:443
2016.10.19 15:00:13 LOG7[2012]: s_connect: s_poll_wait 89.238.166.236:443: waiting 10 seconds
2016.10.19 15:00:13 LOG5[2012]: s_connect: connected 89.238.166.236:443
2016.10.19 15:00:13 LOG5[2012]: Service [openvpn] connected remote server from 192.168.0.9:35573
2016.10.19 15:00:13 LOG7[2012]: Remote socket (FD=10) initialized
2016.10.19 15:00:13 LOG6[2012]: SNI: sending servername: 89.238.166.236
2016.10.19 15:00:13 LOG7[2012]: SSL state (connect): before/connect initialization
2016.10.19 15:00:13 LOG7[2012]: SSL state (connect): unknown state
2016.10.19 15:00:13 LOG7[2012]: SSL state (connect): unknown state
2016.10.19 15:00:13 LOG7[2012]: Verification started at depth=0: C=IT, ST=Italy, L=Perugia, O=AirVPN, OU=stunnel, CN=stunnel.airvpn.org, emailAddress=info@airvpn.org
2016.10.19 15:00:13 LOG7[2012]: CERT: preverify ok
2016.10.19 15:00:13 LOG6[2012]: CERT: Locally installed certificate matched
2016.10.19 15:00:13 LOG5[2012]: Certificate accepted at depth=0: C=IT, ST=Italy, L=Perugia, O=AirVPN, OU=stunnel, CN=stunnel.airvpn.org, emailAddress=info@airvpn.org
2016.10.19 15:00:13 LOG7[2012]: SSL state (connect): unknown state
2016.10.19 15:00:13 LOG7[2012]: SSL state (connect): unknown state
2016.10.19 15:00:13 LOG7[2012]: SSL state (connect): unknown state
2016.10.19 15:00:13 LOG7[2012]: SSL state (connect): unknown state
2016.10.19 15:00:13 LOG7[2012]: SSL state (connect): unknown state
2016.10.19 15:00:13 LOG7[2012]: SSL state (connect): unknown state
2016.10.19 15:00:13 LOG7[2012]: SSL state (connect): unknown state
2016.10.19 15:00:13 LOG7[2012]: SSL state (connect): unknown state
2016.10.19 15:00:13 LOG7[2012]: SSL state (connect): unknown state
2016.10.19 15:00:13 LOG7[2012]: 0 items in the session cache
2016.10.19 15:00:13 LOG7[2012]: 2 client connects (SSL_connect())
2016.10.19 15:00:13 LOG7[2012]: 2 client connects that finished
2016.10.19 15:00:13 LOG7[2012]: 0 client renegotiations requested
2016.10.19 15:00:13 LOG7[2012]: 0 server connects (SSL_accept())
2016.10.19 15:00:13 LOG7[2012]: 0 server connects that finished
2016.10.19 15:00:13 LOG7[2012]: 0 server renegotiations requested
2016.10.19 15:00:13 LOG7[2012]: 0 session cache hits
2016.10.19 15:00:13 LOG7[2012]: 0 external session cache hits
2016.10.19 15:00:13 LOG7[2012]: 0 session cache misses
2016.10.19 15:00:13 LOG7[2012]: 0 session cache timeouts
2016.10.19 15:00:13 LOG6[2012]: SSL connected: new session negotiated
2016.10.19 15:00:13 LOG6[2012]: Negotiated TLSv1.2 ciphersuite ECDHE-RSA-AES256-GCM-SHA384 (256-bit encryption)
2016.10.19 15:00:13 LOG6[2012]: Compression: null, expansion: null
2016.10.19 15:00:14 LOG7[2012]: SSL_read returned WANT_READ: retrying
2016.10.19 15:00:15 LOG7[1570]: SSL alert (read): warning: close notify
2016.10.19 15:00:15 LOG6[1570]: SSL closed (SSL_read)
2016.10.19 15:00:15 LOG7[1570]: Sent socket write shutdown
2016.10.19 15:00:15 LOG6[1570]: Read socket closed (readsocket)
2016.10.19 15:00:15 LOG6[1570]: Read socket closed (hangup)
2016.10.19 15:00:15 LOG6[1570]: Write socket closed (hangup)
2016.10.19 15:00:15 LOG7[1570]: Sending close_notify alert
2016.10.19 15:00:15 LOG7[1570]: SSL alert (write): warning: close notify
2016.10.19 15:00:15 LOG6[1570]: SSL_shutdown successfully sent close_notify alert
2016.10.19 15:00:15 LOG5[1570]: Connection closed: 8424 byte(s) sent to SSL, 9195 byte(s) sent to socket
2016.10.19 15:00:15 LOG7[1570]: Remote socket (FD=8) closed
2016.10.19 15:00:15 LOG7[1570]: Local socket (FD=3) closed
2016.10.19 15:00:15 LOG7[1570]: Service [openvpn] finished (1 left)
2016.10.19 15:00:20 LOG7[1569]: Service [openvpn] accepted (FD=3) from 127.0.0.1:46031
2016.10.19 15:00:20 LOG7[2057]: Service [openvpn] started
2016.10.19 15:00:20 LOG5[2057]: Service [openvpn] accepted connection from 127.0.0.1:46031
2016.10.19 15:00:20 LOG6[2057]: s_connect: connecting 89.238.166.236:443
2016.10.19 15:00:20 LOG7[2057]: s_connect: s_poll_wait 89.238.166.236:443: waiting 10 seconds
2016.10.19 15:00:20 LOG5[2057]: s_connect: connected 89.238.166.236:443
2016.10.19 15:00:20 LOG5[2057]: Service [openvpn] connected remote server from 192.168.0.9:35575
2016.10.19 15:00:20 LOG7[2057]: Remote socket (FD=8) initialized
2016.10.19 15:00:20 LOG6[2057]: SNI: sending servername: 89.238.166.236
2016.10.19 15:00:20 LOG7[2057]: SSL state (connect): before/connect initialization
2016.10.19 15:00:20 LOG7[2057]: SSL state (connect): unknown state
2016.10.19 15:00:20 LOG7[2057]: SSL state (connect): unknown state
2016.10.19 15:00:20 LOG7[2057]: SSL state (connect): unknown state
2016.10.19 15:00:20 LOG7[2057]: SSL state (connect): unknown state
2016.10.19 15:00:20 LOG7[2057]: SSL state (connect): unknown state
2016.10.19 15:00:20 LOG7[2057]: SSL state (connect): unknown state
2016.10.19 15:00:20 LOG7[2057]: 0 items in the session cache
2016.10.19 15:00:20 LOG7[2057]: 3 client connects (SSL_connect())
2016.10.19 15:00:20 LOG7[2057]: 3 client connects that finished
2016.10.19 15:00:20 LOG7[2057]: 0 client renegotiations requested
2016.10.19 15:00:20 LOG7[2057]: 0 server connects (SSL_accept())
2016.10.19 15:00:20 LOG7[2057]: 0 server connects that finished
2016.10.19 15:00:20 LOG7[2057]: 0 server renegotiations requested
2016.10.19 15:00:20 LOG7[2057]: 1 session cache hits
2016.10.19 15:00:20 LOG7[2057]: 0 external session cache hits
2016.10.19 15:00:20 LOG7[2057]: 0 session cache misses
2016.10.19 15:00:20 LOG7[2057]: 0 session cache timeouts
2016.10.19 15:00:20 LOG6[2057]: SSL connected: previous session reused
2016.10.19 15:00:21 LOG7[2057]: SSL_read returned WANT_READ: retrying
2016.10.19 15:00:22 LOG7[2012]: SSL alert (read): warning: close notify
2016.10.19 15:00:22 LOG6[2012]: SSL closed (SSL_read)
2016.10.19 15:00:22 LOG7[2012]: Sent socket write shutdown
2016.10.19 15:00:22 LOG6[2012]: Read socket closed (readsocket)
2016.10.19 15:00:22 LOG6[2012]: Read socket closed (hangup)
2016.10.19 15:00:22 LOG6[2012]: Write socket closed (hangup)
2016.10.19 15:00:22 LOG7[2012]: Sending close_notify alert
2016.10.19 15:00:22 LOG7[2012]: SSL alert (write): warning: close notify
2016.10.19 15:00:22 LOG6[2012]: SSL_shutdown successfully sent close_notify alert
2016.10.19 15:00:22 LOG5[2012]: Connection closed: 5442 byte(s) sent to SSL, 6213 byte(s) sent to socket
2016.10.19 15:00:22 LOG7[2012]: Remote socket (FD=10) closed
2016.10.19 15:00:22 LOG7[2012]: Local socket (FD=9) closed
2016.10.19 15:00:22 LOG7[2012]: Service [openvpn] finished (1 left)
^C2016.10.19 15:00:28 LOG7[1569]: Dispatching signals from the signal pipe
2016.10.19 15:00:28 LOG3[1569]: Received signal 2; terminating
2016.10.19 15:00:28 LOG7[1569]: Closing service [openvpn]
2016.10.19 15:00:28 LOG7[1569]: Service [openvpn] closed (FD=7)
2016.10.19 15:00:28 LOG7[1569]: Service [openvpn] closed
2016.10.19 15:00:28 LOG7[1569]: removing pid file /tmp/stunnel4.pid

OpenVPN log

Wed Oct 19 15:00:13 2016 us=447008 Current Parameter Settings:
Wed Oct 19 15:00:13 2016 us=447099 config = '/etc/openvpn/Openvpn.conf'
Wed Oct 19 15:00:13 2016 us=447130 mode = 0
Wed Oct 19 15:00:13 2016 us=447155 persist_config = DISABLED
Wed Oct 19 15:00:13 2016 us=447180 persist_mode = 1
Wed Oct 19 15:00:13 2016 us=447205 show_ciphers = DISABLED
Wed Oct 19 15:00:13 2016 us=447230 show_digests = DISABLED
Wed Oct 19 15:00:13 2016 us=447257 show_engines = DISABLED
Wed Oct 19 15:00:13 2016 us=447283 genkey = DISABLED
Wed Oct 19 15:00:13 2016 us=447308 key_pass_file = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=447334 show_tls_ciphers = DISABLED
Wed Oct 19 15:00:13 2016 us=447359 Connection profiles [default]:
Wed Oct 19 15:00:13 2016 us=447384 proto = tcp-client
Wed Oct 19 15:00:13 2016 us=447421 local = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=447447 local_port = 0
Wed Oct 19 15:00:13 2016 us=447479 remote = '127.0.0.1'
Wed Oct 19 15:00:13 2016 us=447505 remote_port = 1413
Wed Oct 19 15:00:13 2016 us=447535 remote_float = DISABLED
Wed Oct 19 15:00:13 2016 us=447561 bind_defined = DISABLED
Wed Oct 19 15:00:13 2016 us=447590 bind_local = DISABLED
Wed Oct 19 15:00:13 2016 us=447617 connect_retry_seconds = 5
Wed Oct 19 15:00:13 2016 us=447646 connect_timeout = 10
Wed Oct 19 15:00:13 2016 us=447673 connect_retry_max = 0
Wed Oct 19 15:00:13 2016 us=447699 socks_proxy_server = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=447730 socks_proxy_port = 0
Wed Oct 19 15:00:13 2016 us=447757 socks_proxy_retry = DISABLED
Wed Oct 19 15:00:13 2016 us=447782 tun_mtu = 1500
Wed Oct 19 15:00:13 2016 us=447807 tun_mtu_defined = ENABLED
Wed Oct 19 15:00:13 2016 us=447833 link_mtu = 1500
Wed Oct 19 15:00:13 2016 us=447857 link_mtu_defined = DISABLED
Wed Oct 19 15:00:13 2016 us=447881 tun_mtu_extra = 0
Wed Oct 19 15:00:13 2016 us=447906 tun_mtu_extra_defined = DISABLED
Wed Oct 19 15:00:13 2016 us=447933 mtu_discover_type = -1
Wed Oct 19 15:00:13 2016 us=447959 fragment = 0
Wed Oct 19 15:00:13 2016 us=447985 mssfix = 1450
Wed Oct 19 15:00:13 2016 us=448011 explicit_exit_notification = 0
Wed Oct 19 15:00:13 2016 us=448035 Connection profiles END
Wed Oct 19 15:00:13 2016 us=448060 remote_random = DISABLED
Wed Oct 19 15:00:13 2016 us=448085 ipchange = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=448110 dev = 'tun'
Wed Oct 19 15:00:13 2016 us=448137 dev_type = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=448162 dev_node = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=448187 lladdr = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=448211 topology = 1
Wed Oct 19 15:00:13 2016 us=448236 tun_ipv6 = DISABLED
Wed Oct 19 15:00:13 2016 us=448260 ifconfig_local = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=448285 ifconfig_remote_netmask = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=448311 ifconfig_noexec = DISABLED
Wed Oct 19 15:00:13 2016 us=448336 ifconfig_nowarn = DISABLED
Wed Oct 19 15:00:13 2016 us=448363 ifconfig_ipv6_local = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=448388 ifconfig_ipv6_netbits = 0
Wed Oct 19 15:00:13 2016 us=448414 ifconfig_ipv6_remote = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=448440 shaper = 0
Wed Oct 19 15:00:13 2016 us=448467 mtu_test = 0
Wed Oct 19 15:00:13 2016 us=448492 mlock = DISABLED
Wed Oct 19 15:00:13 2016 us=448517 keepalive_ping = 0
Wed Oct 19 15:00:13 2016 us=448542 keepalive_timeout = 0
Wed Oct 19 15:00:13 2016 us=448567 inactivity_timeout = 0
Wed Oct 19 15:00:13 2016 us=448591 ping_send_timeout = 0
Wed Oct 19 15:00:13 2016 us=448615 ping_rec_timeout = 0
Wed Oct 19 15:00:13 2016 us=448640 ping_rec_timeout_action = 0
Wed Oct 19 15:00:13 2016 us=448664 ping_timer_remote = DISABLED
Wed Oct 19 15:00:13 2016 us=448692 remap_sigusr1 = 0
Wed Oct 19 15:00:13 2016 us=448717 persist_tun = ENABLED
Wed Oct 19 15:00:13 2016 us=448743 persist_local_ip = DISABLED
Wed Oct 19 15:00:13 2016 us=448769 persist_remote_ip = DISABLED
Wed Oct 19 15:00:13 2016 us=448794 persist_key = ENABLED
Wed Oct 19 15:00:13 2016 us=448820 passtos = DISABLED
Wed Oct 19 15:00:13 2016 us=448846 resolve_retry_seconds = 1000000000
Wed Oct 19 15:00:13 2016 us=448872 username = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=448896 groupname = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=448922 chroot_dir = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=448947 cd_dir = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=448972 writepid = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=448997 up_script = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=449021 down_script = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=449046 down_pre = DISABLED
Wed Oct 19 15:00:13 2016 us=449071 up_restart = DISABLED
Wed Oct 19 15:00:13 2016 us=449095 up_delay = DISABLED
Wed Oct 19 15:00:13 2016 us=449120 daemon = DISABLED
Wed Oct 19 15:00:13 2016 us=449145 inetd = 0
Wed Oct 19 15:00:13 2016 us=449170 log = DISABLED
Wed Oct 19 15:00:13 2016 us=449194 suppress_timestamps = DISABLED
Wed Oct 19 15:00:13 2016 us=449222 nice = 0
Wed Oct 19 15:00:13 2016 us=449247 verbosity = 5
Wed Oct 19 15:00:13 2016 us=449273 mute = 0
Wed Oct 19 15:00:13 2016 us=449298 gremlin = 0
Wed Oct 19 15:00:13 2016 us=449327 status_file = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=449355 status_file_version = 1
Wed Oct 19 15:00:13 2016 us=449381 status_file_update_freq = 60
Wed Oct 19 15:00:13 2016 us=449407 occ = ENABLED
Wed Oct 19 15:00:13 2016 us=449433 rcvbuf = 65536
Wed Oct 19 15:00:13 2016 us=449457 sndbuf = 65536
Wed Oct 19 15:00:13 2016 us=449481 mark = 0
Wed Oct 19 15:00:13 2016 us=449505 sockflags = 0
Wed Oct 19 15:00:13 2016 us=449529 fast_io = DISABLED
Wed Oct 19 15:00:13 2016 us=449555 lzo = 1
Wed Oct 19 15:00:13 2016 us=449579 route_script = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=449604 route_default_gateway = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=449632 route_default_metric = 0
Wed Oct 19 15:00:13 2016 us=449657 route_noexec = DISABLED
Wed Oct 19 15:00:13 2016 us=449683 route_delay = 5
Wed Oct 19 15:00:13 2016 us=449708 route_delay_window = 30
Wed Oct 19 15:00:13 2016 us=449734 route_delay_defined = ENABLED
Wed Oct 19 15:00:13 2016 us=449759 route_nopull = DISABLED
Wed Oct 19 15:00:13 2016 us=449782 route_gateway_via_dhcp = DISABLED
Wed Oct 19 15:00:13 2016 us=449808 max_routes = 100
Wed Oct 19 15:00:13 2016 us=449832 allow_pull_fqdn = DISABLED
Wed Oct 19 15:00:13 2016 us=449860 route 89.238.166.236/255.255.255.255/net_gateway/nil
Wed Oct 19 15:00:13 2016 us=449885 management_addr = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=449908 management_port = 0
Wed Oct 19 15:00:13 2016 us=449931 management_user_pass = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=449954 management_log_history_cache = 250
Wed Oct 19 15:00:13 2016 us=449977 management_echo_buffer_size = 100
Wed Oct 19 15:00:13 2016 us=450002 management_write_peer_info_file = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=450025 management_client_user = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=450048 management_client_group = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=450071 management_flags = 0
Wed Oct 19 15:00:13 2016 us=450094 shared_secret_file = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=450117 key_direction = 2
Wed Oct 19 15:00:13 2016 us=450140 ciphername_defined = ENABLED
Wed Oct 19 15:00:13 2016 us=450163 ciphername = 'AES-256-CBC'
Wed Oct 19 15:00:13 2016 us=450186 authname_defined = ENABLED
Wed Oct 19 15:00:13 2016 us=450208 authname = 'SHA1'
Wed Oct 19 15:00:13 2016 us=450233 prng_hash = 'SHA1'
Wed Oct 19 15:00:13 2016 us=450257 prng_nonce_secret_len = 16
Wed Oct 19 15:00:13 2016 us=450281 keysize = 0
Wed Oct 19 15:00:13 2016 us=450306 engine = DISABLED
Wed Oct 19 15:00:13 2016 us=450330 replay = ENABLED
Wed Oct 19 15:00:13 2016 us=450354 mute_replay_warnings = DISABLED
Wed Oct 19 15:00:13 2016 us=450379 replay_window = 64
Wed Oct 19 15:00:13 2016 us=450403 replay_time = 15
Wed Oct 19 15:00:13 2016 us=450427 packet_id_file = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=450451 use_iv = ENABLED
Wed Oct 19 15:00:13 2016 us=450475 test_crypto = DISABLED
Wed Oct 19 15:00:13 2016 us=450499 tls_server = DISABLED
Wed Oct 19 15:00:13 2016 us=450523 tls_client = ENABLED
Wed Oct 19 15:00:13 2016 us=450569 key_method = 2
Wed Oct 19 15:00:13 2016 us=450588 ca_file = '[[INLINE]]'
Wed Oct 19 15:00:13 2016 us=450607 ca_path = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=450625 dh_file = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=450644 cert_file = '[[INLINE]]'
Wed Oct 19 15:00:13 2016 us=450663 priv_key_file = '[[INLINE]]'
Wed Oct 19 15:00:13 2016 us=450682 pkcs12_file = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=450700 cipher_list = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=450719 tls_verify = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=450734 tls_export_cert = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=450751 verify_x509_type = 0
Wed Oct 19 15:00:13 2016 us=450768 verify_x509_name = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=450785 crl_file = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=450803 ns_cert_type = 0
Wed Oct 19 15:00:13 2016 us=450821 remote_cert_ku = 160
Wed Oct 19 15:00:13 2016 us=450840 remote_cert_ku = 136
Wed Oct 19 15:00:13 2016 us=450858 remote_cert_ku = 0
Wed Oct 19 15:00:13 2016 us=450869 remote_cert_ku = 0
Wed Oct 19 15:00:13 2016 us=450881 remote_cert_ku = 0
Wed Oct 19 15:00:13 2016 us=450897 remote_cert_ku = 0
Wed Oct 19 15:00:13 2016 us=450915 remote_cert_ku = 0
Wed Oct 19 15:00:13 2016 us=450932 remote_cert_ku = 0
Wed Oct 19 15:00:13 2016 us=450945 remote_cert_ku = 0
Wed Oct 19 15:00:13 2016 us=450955 remote_cert_ku = 0
Wed Oct 19 15:00:13 2016 us=450966 remote_cert_ku[i] = 0
Wed Oct 19 15:00:13 2016 us=450976 remote_cert_ku[i] = 0
Wed Oct 19 15:00:13 2016 us=450986 remote_cert_ku[i] = 0
Wed Oct 19 15:00:13 2016 us=450996 remote_cert_ku[i] = 0
Wed Oct 19 15:00:13 2016 us=451007 remote_cert_ku[i] = 0
Wed Oct 19 15:00:13 2016 us=451017 remote_cert_ku[i] = 0
Wed Oct 19 15:00:13 2016 us=451028 remote_cert_eku = 'TLS Web Server Authentication'
Wed Oct 19 15:00:13 2016 us=451039 ssl_flags = 0
Wed Oct 19 15:00:13 2016 us=451050 tls_timeout = 2
Wed Oct 19 15:00:13 2016 us=451060 renegotiate_bytes = 0
Wed Oct 19 15:00:13 2016 us=451071 renegotiate_packets = 0
Wed Oct 19 15:00:13 2016 us=451082 renegotiate_seconds = 3600
Wed Oct 19 15:00:13 2016 us=451092 handshake_window = 60
Wed Oct 19 15:00:13 2016 us=451103 transition_window = 3600
Wed Oct 19 15:00:13 2016 us=451114 single_session = DISABLED
Wed Oct 19 15:00:13 2016 us=451124 push_peer_info = DISABLED
Wed Oct 19 15:00:13 2016 us=451134 tls_exit = DISABLED
Wed Oct 19 15:00:13 2016 us=451145 tls_auth_file = '[[INLINE]]'
Wed Oct 19 15:00:13 2016 us=451156 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451166 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451177 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451187 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451198 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451208 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451218 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451229 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451239 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451250 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451261 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451271 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451282 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451292 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451302 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451313 pkcs11_protected_authentication = DISABLED
Wed Oct 19 15:00:13 2016 us=451324 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451335 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451345 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451355 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451365 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451375 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451386 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451396 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451406 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451416 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451427 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451437 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451447 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451457 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451468 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451478 pkcs11_private_mode = 00000000
Wed Oct 19 15:00:13 2016 us=451488 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451498 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451508 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451518 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451532 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451542 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451552 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451562 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451573 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451583 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451593 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451603 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451613 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451623 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451633 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451644 pkcs11_cert_private = DISABLED
Wed Oct 19 15:00:13 2016 us=451654 pkcs11_pin_cache_period = -1
Wed Oct 19 15:00:13 2016 us=451664 pkcs11_id = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=451675 pkcs11_id_management = DISABLED
Wed Oct 19 15:00:13 2016 us=451690 server_network = 0.0.0.0
Wed Oct 19 15:00:13 2016 us=451702 server_netmask = 0.0.0.0
Wed Oct 19 15:00:13 2016 us=451725 server_network_ipv6 = ::
Wed Oct 19 15:00:13 2016 us=451742 server_netbits_ipv6 = 0
Wed Oct 19 15:00:13 2016 us=451754 server_bridge_ip = 0.0.0.0
Wed Oct 19 15:00:13 2016 us=451765 server_bridge_netmask = 0.0.0.0
Wed Oct 19 15:00:13 2016 us=451777 server_bridge_pool_start = 0.0.0.0
Wed Oct 19 15:00:13 2016 us=451788 server_bridge_pool_end = 0.0.0.0
Wed Oct 19 15:00:13 2016 us=451799 ifconfig_pool_defined = DISABLED
Wed Oct 19 15:00:13 2016 us=451810 ifconfig_pool_start = 0.0.0.0
Wed Oct 19 15:00:13 2016 us=451822 ifconfig_pool_end = 0.0.0.0
Wed Oct 19 15:00:13 2016 us=451833 ifconfig_pool_netmask = 0.0.0.0
Wed Oct 19 15:00:13 2016 us=451843 ifconfig_pool_persist_filename = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=451854 ifconfig_pool_persist_refresh_freq = 600
Wed Oct 19 15:00:13 2016 us=451864 ifconfig_ipv6_pool_defined = DISABLED
Wed Oct 19 15:00:13 2016 us=451876 ifconfig_ipv6_pool_base = ::
Wed Oct 19 15:00:13 2016 us=451886 ifconfig_ipv6_pool_netbits = 0
Wed Oct 19 15:00:13 2016 us=451896 n_bcast_buf = 256
Wed Oct 19 15:00:13 2016 us=451907 tcp_queue_limit = 64
Wed Oct 19 15:00:13 2016 us=451917 real_hash_size = 256
Wed Oct 19 15:00:13 2016 us=451927 virtual_hash_size = 256
Wed Oct 19 15:00:13 2016 us=451938 client_connect_script = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=451948 learn_address_script = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=451959 client_disconnect_script = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=451970 client_config_dir = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=451980 ccd_exclusive = DISABLED
Wed Oct 19 15:00:13 2016 us=451990 tmp_dir = '/tmp'
Wed Oct 19 15:00:13 2016 us=452001 push_ifconfig_defined = DISABLED
Wed Oct 19 15:00:13 2016 us=452012 push_ifconfig_local = 0.0.0.0
Wed Oct 19 15:00:13 2016 us=452023 push_ifconfig_remote_netmask = 0.0.0.0
Wed Oct 19 15:00:13 2016 us=452034 push_ifconfig_ipv6_defined = DISABLED
Wed Oct 19 15:00:13 2016 us=452045 push_ifconfig_ipv6_local = ::/0
Wed Oct 19 15:00:13 2016 us=452056 push_ifconfig_ipv6_remote = ::
Wed Oct 19 15:00:13 2016 us=452066 enable_c2c = DISABLED
Wed Oct 19 15:00:13 2016 us=452076 duplicate_cn = DISABLED
Wed Oct 19 15:00:13 2016 us=452087 cf_max = 0
Wed Oct 19 15:00:13 2016 us=452097 cf_per = 0
Wed Oct 19 15:00:13 2016 us=452107 max_clients = 1024
Wed Oct 19 15:00:13 2016 us=452118 max_routes_per_client = 256
Wed Oct 19 15:00:13 2016 us=452128 auth_user_pass_verify_script = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=452139 auth_user_pass_verify_script_via_file = DISABLED
Wed Oct 19 15:00:13 2016 us=452149 port_share_host = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=452160 port_share_port = 0
Wed Oct 19 15:00:13 2016 us=452170 client = ENABLED
Wed Oct 19 15:00:13 2016 us=452180 pull = ENABLED
Wed Oct 19 15:00:13 2016 us=452191 auth_user_pass_file = '[UNDEF]'
Wed Oct 19 15:00:13 2016 us=452204 OpenVPN 2.3.4 x86_64-pc-linux-gnu [SSL (OpenSSL)] [LZO] [EPOLL] [PKCS11] [MH] [IPv6] built on Nov 12 2015
Wed Oct 19 15:00:13 2016 us=452223 library versions: OpenSSL 1.0.1t 3 May 2016, LZO 2.08
Wed Oct 19 15:00:13 2016 us=453119 Control Channel Authentication: tls-auth using INLINE static key file
Wed Oct 19 15:00:13 2016 us=453155 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Wed Oct 19 15:00:13 2016 us=453170 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Wed Oct 19 15:00:13 2016 us=453192 LZO compression initialized
Wed Oct 19 15:00:13 2016 us=453266 Control Channel MTU parms [ L:1560 D:168 EF:68 EB:0 ET:0 EL:0 ]
Wed Oct 19 15:00:13 2016 us=453316 Socket Buffers: R=[87380->131072] S=[16384->131072]
Wed Oct 19 15:00:13 2016 us=453337 Data Channel MTU parms [ L:1560 D:1450 EF:60 EB:135 ET:0 EL:0 AF:3/1 ]
Wed Oct 19 15:00:13 2016 us=453361 Local Options String: 'V4,dev-type tun,link-mtu 1560,tun-mtu 1500,proto TCPv4_CLIENT,comp-lzo,keydir 1,cipher AES-256-CBC,auth SHA1,keysize 256,tls-auth,key-method 2,tls-client'
Wed Oct 19 15:00:13 2016 us=453374 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1560,tun-mtu 1500,proto TCPv4_SERVER,comp-lzo,keydir 0,cipher AES-256-CBC,auth SHA1,keysize 256,tls-auth,key-method 2,tls-server'
Wed Oct 19 15:00:13 2016 us=453400 Local Options hash (VER=V4): '2f2c6498'
Wed Oct 19 15:00:13 2016 us=453418 Expected Remote Options hash (VER=V4): '9915e4a2'
Wed Oct 19 15:00:13 2016 us=453439 Attempting to establish TCP connection with [AF_INET]127.0.0.1:1413 [nonblock]
Wed Oct 19 15:00:13 2016 us=453521 TCP connection established with [AF_INET]127.0.0.1:1413
Wed Oct 19 15:00:13 2016 us=453546 TCPv4_CLIENT link local: [undef]
Wed Oct 19 15:00:13 2016 us=453568 TCPv4_CLIENT link remote: [AF_INET]127.0.0.1:1413
WRWed Oct 19 15:00:13 2016 us=891040 TLS: Initial packet from [AF_INET]127.0.0.1:1413, sid=cf2e6b63 a4899456
WWWRRWRWed Oct 19 15:00:14 2016 us=78608 VERIFY OK: depth=1, C=IT, ST=IT, L=Perugia, O=airvpn.org, CN=airvpn.org CA, emailAddress=info@airvpn.org
Wed Oct 19 15:00:14 2016 us=79375 Validating certificate key usage
Wed Oct 19 15:00:14 2016 us=79392 ++ Certificate has key usage 00a0, expects 00a0
Wed Oct 19 15:00:14 2016 us=79401 VERIFY KU OK
Wed Oct 19 15:00:14 2016 us=79414 Validating certificate extended key usage
Wed Oct 19 15:00:14 2016 us=79426 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Wed Oct 19 15:00:14 2016 us=79436 VERIFY EKU OK
Wed Oct 19 15:00:14 2016 us=79444 VERIFY OK: depth=0, C=IT, ST=IT, L=Perugia, O=airvpn.org, CN=server, emailAddress=info@airvpn.org
WRRWWWWWRRRWWWRWRWRWRRWWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRWRRRRWWWWRRRRWed Oct 19 15:00:15 2016 us=256760 Data Channel Encrypt: Cipher 'AES-256-CBC' initialized with 256 bit key
Wed Oct 19 15:00:15 2016 us=256779 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Wed Oct 19 15:00:15 2016 us=256785 Data Channel Decrypt: Cipher 'AES-256-CBC' initialized with 256 bit key
Wed Oct 19 15:00:15 2016 us=256790 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
WWed Oct 19 15:00:15 2016 us=256813 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 4096 bit RSA
Wed Oct 19 15:00:15 2016 us=256829 [server] Peer Connection Initiated with [AF_INET]127.0.0.1:1413
Wed Oct 19 15:00:17 2016 us=601455 SENT CONTROL [server]: 'PUSH_REQUEST' (status=1)
WRRWed Oct 19 15:00:17 2016 us=786229 PUSH: Received control message: 'PUSH_REPLY,redirect-gateway def1 bypass-dhcp,dhcp-option DNS 10.50.0.1,comp-lzo no,route-gateway 10.50.0.1,topology subnet,ping 10,ping-restart 60,ifconfig 10.50.1.190 255.255.0.0'
Wed Oct 19 15:00:17 2016 us=786350 OPTIONS IMPORT: timers and/or timeouts modified
Wed Oct 19 15:00:17 2016 us=786362 OPTIONS IMPORT: LZO parms modified
Wed Oct 19 15:00:17 2016 us=786371 OPTIONS IMPORT: --ifconfig/up options modified
Wed Oct 19 15:00:17 2016 us=786379 OPTIONS IMPORT: route options modified
Wed Oct 19 15:00:17 2016 us=786387 OPTIONS IMPORT: route-related options modified
Wed Oct 19 15:00:17 2016 us=786395 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Wed Oct 19 15:00:17 2016 us=786718 ROUTE_GATEWAY 192.168.0.1/255.255.255.0 IFACE=wlan1 HWADDR=00:c0:ca:84:a7:5e
Wed Oct 19 15:00:17 2016 us=787203 TUN/TAP device tun1 opened
Wed Oct 19 15:00:17 2016 us=787244 TUN/TAP TX queue length set to 100
Wed Oct 19 15:00:17 2016 us=787276 do_ifconfig, tt->ipv6=0, tt->did_ifconfig_ipv6_setup=0
Wed Oct 19 15:00:17 2016 us=787329 /sbin/ip link set dev tun1 up mtu 1500
Wed Oct 19 15:00:17 2016 us=790048 /sbin/ip addr add dev tun1 10.50.1.190/16 broadcast 10.50.255.255
WWed Oct 19 15:00:22 2016 us=739547 Connection reset, restarting [0]
Wed Oct 19 15:00:22 2016 us=739987 TCP/UDP: Closing socket
Wed Oct 19 15:00:22 2016 us=740133 SIGUSR1[soft,connection-reset] received, process restarting
Wed Oct 19 15:00:22 2016 us=740175 Restart pause, 5 second(s)
^CWed Oct 19 15:00:24 2016 us=894891 Assertion failed at misc.c:785
Wed Oct 19 15:00:24 2016 us=894919 Exiting due to fatal error

TinCanTech

You have not included your openvpn server config or log ..

user1314

I'm running just the client. Server side is on Airvpn