OpenVPN client claims to connect, but tun is not created, pings fail.

Issues related to configuring your network
Post Reply
charlweed
Posts: 12
Joined: 2016/01/22 16:54:25

OpenVPN client claims to connect, but tun is not created, pings fail.

Post by charlweed » 2019/12/27 15:56:33

This is a completely fresh install Of CentOS 7. I suspect that there is something missing/wrong in my overall client install, because my client config looks correct. Other (ios) openvpn clients can connect and tunnel with my target openvpn server.
A perhaps related symptom is that NetworkManager does not give me the option of adding a VPN connection in the GUI, even after installing, reinstalling or uninstalling the openvpn plugin.
Unlike logs seen posted online, my logs do NOT show "TUN/TAP device tun0 opened". I see that the device /dev/net/tun is present before openvpn starts, and I tried modprobe tun.
I've tried enabling the openvpn service in firewalld, and opening ports 1194 with legacy commands.
Here is my client config. I'm going to try replacing 'tun' with 'tun0' next.

Code: Select all

client
dev tun
proto udp
remote redacted 1194
resolv-retry infinite
nobind
user nobody
group nobody
persist-key
persist-tun
#remote-cert-tls server
cipher AES-256-CBC
verb 8
ca certificate-authority.crt
cert galileo7.crt
key  galileo7.key
I start the openvpn client with
systemctl start openvpn-client@starbug.service

There is no error reported, nor do the logs show that tun0 is created or used, sorry if it's too long:

Code: Select all

-- Logs begin at Thu 2019-12-26 12:15:21 EST, end at Thu 2019-12-26 19:30:01 EST. --
Dec 26 18:17:32 starbug.busted.org systemd[1]: Started OpenVPN tunnel for starbug.
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: verbosity = 8
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: mute = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: gremlin = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: status_file = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: status_file_version = 1
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: status_file_update_freq = 60
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: occ = ENABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: rcvbuf = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: sndbuf = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: mark = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: sockflags = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: fast_io = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: comp.alg = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: comp.flags = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: route_script = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: route_default_gateway = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: route_default_metric = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: route_noexec = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: route_delay = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: route_delay_window = 30
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: route_delay_defined = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: route_nopull = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: route_gateway_via_dhcp = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: allow_pull_fqdn = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: management_addr = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: management_port = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: management_user_pass = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: management_log_history_cache = 250
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: management_echo_buffer_size = 100
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: management_write_peer_info_file = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: management_client_user = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: management_client_group = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: management_flags = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: shared_secret_file = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: key_direction = not set
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ciphername = 'AES-256-CBC'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ncp_enabled = ENABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ncp_ciphers = 'AES-256-GCM:AES-128-GCM'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: authname = 'SHA1'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: prng_hash = 'SHA1'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: prng_nonce_secret_len = 16
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: keysize = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: engine = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: replay = ENABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: mute_replay_warnings = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: replay_window = 64
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: replay_time = 15
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: packet_id_file = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: use_iv = ENABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: test_crypto = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: tls_server = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: tls_client = ENABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: key_method = 2
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ca_file = 'certificate_authority.crt'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ca_path = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: dh_file = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: cert_file = 'galileo7.crt'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: extra_certs_file = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: priv_key_file = 'galileo7.key'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs12_file = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: cipher_list = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: cipher_list_tls13 = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: tls_cert_profile = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: tls_verify = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: tls_export_cert = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: verify_x509_type = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: verify_x509_name = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: crl_file = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ns_cert_type = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_ku[i] = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: remote_cert_eku = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ssl_flags = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: tls_timeout = 2
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: renegotiate_bytes = -1
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: renegotiate_packets = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: renegotiate_seconds = 3600
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: handshake_window = 60
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: transition_window = 3600
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: single_session = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: push_peer_info = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: tls_exit = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: tls_auth_file = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: tls_crypt_file = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_protected_authentication = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_private_mode = 00000000
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_cert_private = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_pin_cache_period = -1
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_id = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pkcs11_id_management = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: server_network = 0.0.0.0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: server_netmask = 0.0.0.0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: server_network_ipv6 = ::
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: server_netbits_ipv6 = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: server_bridge_ip = 0.0.0.0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: server_bridge_netmask = 0.0.0.0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: server_bridge_pool_start = 0.0.0.0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: server_bridge_pool_end = 0.0.0.0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ifconfig_pool_defined = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ifconfig_pool_start = 0.0.0.0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ifconfig_pool_end = 0.0.0.0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ifconfig_pool_netmask = 0.0.0.0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ifconfig_pool_persist_filename = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ifconfig_pool_persist_refresh_freq = 600
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ifconfig_ipv6_pool_defined = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ifconfig_ipv6_pool_base = ::
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ifconfig_ipv6_pool_netbits = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: n_bcast_buf = 256
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: tcp_queue_limit = 64
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: real_hash_size = 256
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: virtual_hash_size = 256
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: client_connect_script = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: learn_address_script = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: client_disconnect_script = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: client_config_dir = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ccd_exclusive = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: tmp_dir = '/tmp'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: push_ifconfig_defined = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: push_ifconfig_local = 0.0.0.0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: push_ifconfig_remote_netmask = 0.0.0.0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: push_ifconfig_ipv6_defined = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: push_ifconfig_ipv6_local = ::/0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: push_ifconfig_ipv6_remote = ::
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: enable_c2c = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: duplicate_cn = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: cf_max = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: cf_per = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: max_clients = 1024
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: max_routes_per_client = 256
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: auth_user_pass_verify_script = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: auth_user_pass_verify_script_via_file = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: auth_token_generate = DISABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: auth_token_lifetime = 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: port_share_host = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: port_share_port = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: client = ENABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: pull = ENABLED
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: auth_user_pass_file = '[UNDEF]'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: OpenVPN 2.4.8 x86_64-redhat-linux-gnu [Fedora EPEL patched] [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Nov  1 2019
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: library versions: OpenSSL 1.0.2k-fips  26 Jan 2017, LZO 2.06
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: WARNING: No server certificate verification method has been enabled.  See http://openvpn.net/howto.html#mitm for more info.
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: PO_INIT maxevents=4 flags=0x00000002
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: PRNG init md=SHA1 size=36
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: PID packet_id_init seq_backtrack=64 time_backtrack=15
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: PID packet_id_init seq_backtrack=64 time_backtrack=15
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: PID packet_id_init seq_backtrack=64 time_backtrack=15
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: PID packet_id_init seq_backtrack=64 time_backtrack=15
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: Control Channel MTU parms [ L:1621 D:1212 EF:38 EB:0 ET:0 EL:3 ]
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: MTU DYNAMIC mtu=1450, flags=2, 1621 -> 1450
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: RESOLVE_REMOTE flags=0x0901 phase=1 rrs=0 sig=-1 status=0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 56 bytes
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: calc_options_string_link_mtu: link-mtu 1621 -> 1557
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 56 bytes
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: calc_options_string_link_mtu: link-mtu 1621 -> 1557
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1557,tun-mtu 1500,proto UDPv4,cipher AES-256-CBC,auth SHA1,keysize 256,key-method 2,tls-client'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1557,tun-mtu 1500,proto UDPv4,cipher AES-256-CBC,auth SHA1,keysize 256,key-method 2,tls-server'
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: TCP/UDP: Preserving recently used remote address: [AF_INET]redacted:1194
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: Socket Buffers: R=[212992->212992] S=[212992->212992]
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: UDP link local: (not bound)
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: UDP link remote: [AF_INET]redacted:1194
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: NOTE: UID/GID downgrade will be delayed because of --client, --pull, or --up-delay
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: TIMER: coarse timer wakeup 1 seconds
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ACK mark active outgoing ID 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ACK reliable_can_send active=1 current=1 : [1] 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ACK reliable_send ID 0 (size=4 to=2)
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ACK reliable_send_timeout 2 [1] 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: RANDOM USEC=225053
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: PO_CTL rwflags=0x0003 ev=3 arg=0x5620030b2170
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: I/O WAIT T?|T?|SR|SW [1/225053]
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: PO_WAIT[0,0] fd=3 rev=0x00000004 rwflags=0x0002 arg=0x5620030b2170
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: I/O WAIT status=0x0002
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: UDP WRITE [14] to [AF_INET]redacted:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ACK reliable_can_send active=1 current=0 : [1] 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: SSL state (connect): before/connect initialization
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: SSL state (connect): SSLv2/v3 write client hello A
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: ACK reliable_send_timeout 2 [1] 0
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: PO_CTL rwflags=0x0001 ev=3 arg=0x5620030b2170
Dec 26 18:17:32 starbug.busted.org openvpn[4158]: I/O WAIT T?|T?|SR|Sw [1/225053]
Dec 26 18:17:33 starbug.busted.org openvpn[4158]: I/O WAIT status=0x0020
Dec 26 18:17:33 starbug.busted.org openvpn[4158]: TIMER: coarse timer wakeup 1 seconds
Dec 26 18:17:33 starbug.busted.org openvpn[4158]: ACK reliable_can_send active=1 current=0 : [1] 0
Dec 26 18:17:33 starbug.busted.org openvpn[4158]: ACK reliable_send_timeout 1 [1] 0
Dec 26 18:17:33 starbug.busted.org openvpn[4158]: PO_CTL rwflags=0x0001 ev=3 arg=0x5620030b2170
Dec 26 18:17:33 starbug.busted.org openvpn[4158]: I/O WAIT T?|T?|SR|Sw [1/225053]
Dec 26 18:17:34 starbug.busted.org openvpn[4158]: I/O WAIT status=0x0020
Dec 26 18:17:34 starbug.busted.org openvpn[4158]: TIMER: coarse timer wakeup 1 seconds
Dec 26 18:17:34 starbug.busted.org openvpn[4158]: ACK reliable_can_send active=1 current=1 : [1] 0
Dec 26 18:17:34 starbug.busted.org openvpn[4158]: ACK reliable_send ID 0 (size=4 to=4)
Dec 26 18:17:34 starbug.busted.org openvpn[4158]: ACK reliable_send_timeout 4 [1] 0
Dec 26 18:17:34 starbug.busted.org openvpn[4158]: PO_CTL rwflags=0x0003 ev=3 arg=0x5620030b2170
Dec 26 18:17:34 starbug.busted.org openvpn[4158]: I/O WAIT T?|T?|SR|SW [1/225053]
Dec 26 18:17:34 starbug.busted.org openvpn[4158]: PO_WAIT[0,0] fd=3 rev=0x00000004 rwflags=0x0002 arg=0x5620030b2170
Dec 26 18:17:34 starbug.busted.org openvpn[4158]: I/O WAIT status=0x0002
Dec 26 18:17:34 starbug.busted.org openvpn[4158]: UDP WRITE [14] to [AF_INET]redacted:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Dec 26 18:17:34 starbug.busted.org openvpn[4158]: ACK reliable_can_send active=1 current=0 : [1] 0
Dec 26 18:17:34 starbug.busted.org openvpn[4158]: ACK reliable_send_timeout 4 [1] 0
Dec 26 18:17:34 starbug.busted.org openvpn[4158]: PO_CTL rwflags=0x0001 ev=3 arg=0x5620030b2170
Dec 26 18:17:34 starbug.busted.org openvpn[4158]: I/O WAIT T?|T?|SR|Sw [1/225053]
Dec 26 18:17:35 starbug.busted.org openvpn[4158]: I/O WAIT status=0x0020
Dec 26 18:17:35 starbug.busted.org openvpn[4158]: TIMER: coarse timer wakeup 1 seconds
Dec 26 18:17:35 starbug.busted.org openvpn[4158]: ACK reliable_can_send active=1 current=0 : [1] 0
Dec 26 18:17:35 starbug.busted.org openvpn[4158]: ACK reliable_send_timeout 3 [1] 0
Dec 26 18:17:35 starbug.busted.org openvpn[4158]: PO_CTL rwflags=0x0001 ev=3 arg=0x5620030b2170
Dec 26 18:17:35 starbug.busted.org openvpn[4158]: I/O WAIT T?|T?|SR|Sw [1/225053]
Dec 26 18:17:37 starbug.busted.org openvpn[4158]: I/O WAIT status=0x0020
Dec 26 18:17:37 starbug.busted.org openvpn[4158]: TIMER: coarse timer wakeup 1 seconds
Dec 26 18:17:37 starbug.busted.org openvpn[4158]: PO_CTL rwflags=0x0001 ev=3 arg=0x5620030b2170
Dec 26 18:17:37 starbug.busted.org openvpn[4158]: I/O WAIT T?|T?|SR|Sw [1/225053]
Dec 26 18:17:38 starbug.busted.org openvpn[4158]: I/O WAIT status=0x0020
Dec 26 18:17:38 starbug.busted.org openvpn[4158]: TIMER: coarse timer wakeup 1 seconds
Dec 26 18:17:38 starbug.busted.org openvpn[4158]: ACK reliable_can_send active=1 current=1 : [1] 0
Dec 26 18:17:38 starbug.busted.org openvpn[4158]: ACK reliable_send ID 0 (size=4 to=8)
Dec 26 18:17:38 starbug.busted.org openvpn[4158]: ACK reliable_send_timeout 8 [1] 0
Dec 26 18:17:38 starbug.busted.org openvpn[4158]: PO_CTL rwflags=0x0003 ev=3 arg=0x5620030b2170
Dec 26 18:17:38 starbug.busted.org openvpn[4158]: I/O WAIT T?|T?|SR|SW [1/225053]
Dec 26 18:17:38 starbug.busted.org openvpn[4158]: PO_WAIT[0,0] fd=3 rev=0x00000004 rwflags=0x0002 arg=0x5620030b2170
Dec 26 18:17:38 starbug.busted.org openvpn[4158]: I/O WAIT status=0x0002
Dec 26 18:17:38 starbug.busted.org openvpn[4158]: UDP WRITE [14] to [AF_INET]redacted:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 [ ] pid=0 DATA len=0
Dec 26 18:17:38 starbug.busted.org openvpn[4158]: ACK reliable_can_send active=1 current=0 : [1] 0
Dec 26 18:17:38 starbug.busted.org openvpn[4158]: ACK reliable_send_timeout 8 [1] 0
Dec 26 18:17:38 starbug.busted.org openvpn[4158]: PO_CTL rwflags=0x0001 ev=3 arg=0x5620030b2170
Dec 26 18:17:38 starbug.busted.org openvpn[4158]: I/O WAIT T?|T?|SR|Sw [1/225053]
Dec 26 18:17:39 starbug.busted.org openvpn[4158]: I/O WAIT status=0x0020
Dec 26 18:17:39 starbug.busted.org openvpn[4158]: TIMER: coarse timer wakeup 1 seconds
Dec 26 18:17:39 starbug.busted.org openvpn[4158]: ACK reliable_can_send active=1 current=0 : [1] 0
Dec 26 18:17:39 starbug.busted.org openvpn[4158]: ACK reliable_send_timeout 7 [1] 0
Dec 26 18:17:39 starbug.busted.org openvpn[4158]: PO_CTL rwflags=0x0001 ev=3 arg=0x5620030b2170
Dec 26 18:17:39 starbug.busted.org openvpn[4158]: I/O WAIT T?|T?|SR|Sw [1/225053]
Dec 26 18:17:40 starbug.busted.org openvpn[4158]: I/O WAIT status=0x0020
Dec 26 18:17:40 starbug.busted.org openvpn[4158]: TIMER: coarse timer wakeup 1 seconds
Dec 26 18:17:40 starbug.busted.org openvpn[4158]: PO_CTL rwflags=0x0001 ev=3 arg=0x5620030b2170
Dec 26 18:17:40 starbug.busted.org openvpn[4158]: I/O WAIT T?|T?|SR|Sw [1/225053]
Dec 26 18:17:41 starbug.busted.org openvpn[4158]: I/O WAIT status=0x0020
Dec 26 18:17:41 starbug.busted.org openvpn[4158]: TIMER: coarse timer wakeup 1 seconds
Dec 26 18:17:41 starbug.busted.org openvpn[4158]: PO_CTL rwflags=0x0001 ev=3 arg=0x5620030b2170
Dec 26 18:17:41 starbug.busted.org openvpn[4158]: I/O WAIT T?|T?|SR|Sw [1/225053]
Dec 26 18:17:43 starbug.busted.org openvpn[4158]: I/O WAIT status=0x0020
Dec 26 18:17:43 starbug.busted.org openvpn[4158]: TIMER: coarse timer wakeup 1 seconds
Dec 26 18:17:43 starbug.busted.org openvpn[4158]: RANDOM USEC=90921
Dec 26 18:17:43 starbug.busted.org openvpn[4158]: PO_CTL rwflags=0x0001 ev=3 arg=0x5620030b2170
Dec 26 18:17:43 starbug.busted.org openvpn[4158]: I/O WAIT T?|T?|SR|Sw [1/90921]
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: event_wait : Interrupted system call (code=4)
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: I/O WAIT status=0x0010
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: PID packet_id_free
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: PID packet_id_free
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: PID packet_id_free
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: PID packet_id_free
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: PID packet_id_free
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: PID packet_id_free
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: PID packet_id_free
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: PID packet_id_free
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: TCP/UDP: Closing socket
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: PID packet_id_free
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: SIGTERM[hard,] received, process exiting
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: PKCS#11: Terminating openssl
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: PKCS#11: Removing providers
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: PKCS#11: Releasing sessions
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: PKCS#11: Terminating slotevent
Dec 26 18:17:44 starbug.busted.org openvpn[4158]: PKCS#11: Marking as uninitialized
Dec 26 18:17:44 starbug.busted.org systemd[1]: Stopping OpenVPN tunnel for starbug...
Dec 26 18:17:44 starbug.busted.org systemd[1]: Stopped OpenVPN tunnel for starbug.

charlweed
Posts: 12
Joined: 2016/01/22 16:54:25

Re: OpenVPN client claims to connect, but tun is not created, pings fail.

Post by charlweed » 2019/12/27 22:02:06

Actually, I discovered that `lsmod | grep tun` does NOT show that the tun module is loaded. I loaded manually with modprobe, but that did not fix openvpn. I'll try adding to to /etc/modules-load.d tomorrow.

Post Reply

Return to “CentOS 7 - Networking Support”