Working OpenVPN (PIA) just stopped working?!
-
As stated, I
havehad a working OpenVPN config for many months now. All of the sudden today it goes to 100% packet loss and stays down. Nothing was happening (changing) with pfSense at the time. I've posted the log file at verbosity=11. From what I can tell it's an authentication failure, but I don't know what part of authentication is failing or why?Here's what I've tried so far.
-
Verified that the VPN servers I was trying to connect to were up
-
Rebooted my modem
-
Rebooted pfSense
-
Update to latest 2.4.0 BETA (2.4.0.b.20170621.0152)
-
Verified my VPN subscription was up to date
-
Verified my VPN user/pass was up to date/didn't need to be renewed
-
Verified my VPN user/pass was correct in pfSense auth file
-
Tried VPN user/pass in auth file (how it was originally) & in GUI fields
-
Disabled IDS/IPS & cleared snort2c table to ensure it wasn't blocking anything
-
Verified my VPN providers CA was correct & up to date in pfSense in case of file corruption or revocation/change
-
Scrubbed my zpool to ensure no corruption
Had to post log output to several posts. Included as an attachment as well.
EDIT: It looks like this might be an issue with the latest 2.4.0 BETA build. https://forum.pfsense.org/index.php?topic=132538.msg728694#msg728694
[OpenVPN log out.txt](/public/imported_attachments/1/OpenVPN log out.txt)
-
-
Jun 21 11:11:07 netbox openvpn[71164]: SIGTERM[soft,auth-failure] received, process exiting Jun 21 11:12:00 netbox openvpn[98704]: WARNING: file '/etc/openvpn-passwd.txt' is group or others accessible Jun 21 11:12:00 netbox openvpn[98704]: Current Parameter Settings: Jun 21 11:12:00 netbox openvpn[98704]: config = '/var/etc/openvpn/client1.conf' Jun 21 11:12:00 netbox openvpn[98704]: mode = 0 Jun 21 11:12:00 netbox openvpn[98704]: show_ciphers = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: show_digests = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: show_engines = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: genkey = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: key_pass_file = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: show_tls_ciphers = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: connect_retry_max = 0 Jun 21 11:12:00 netbox openvpn[98704]: Connection profiles [0]: Jun 21 11:12:00 netbox openvpn[98704]: proto = udp4 Jun 21 11:12:00 netbox openvpn[98704]: local = 'xxx.removedprivateip.xxx' Jun 21 11:12:00 netbox openvpn[98704]: local_port = '0' Jun 21 11:12:00 netbox openvpn[98704]: remote = 'us-california.privateinternetaccess.com' Jun 21 11:12:00 netbox openvpn[98704]: remote_port = '1197' Jun 21 11:12:00 netbox openvpn[98704]: remote_float = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: bind_defined = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: bind_local = ENABLED Jun 21 11:12:00 netbox openvpn[98704]: bind_ipv6_only = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: connect_retry_seconds = 5 Jun 21 11:12:00 netbox openvpn[98704]: connect_timeout = 120 Jun 21 11:12:00 netbox openvpn[98704]: socks_proxy_server = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: socks_proxy_port = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: tun_mtu = 1500 Jun 21 11:12:00 netbox openvpn[98704]: tun_mtu_defined = ENABLED Jun 21 11:12:00 netbox openvpn[98704]: link_mtu = 1500 Jun 21 11:12:00 netbox openvpn[98704]: link_mtu_defined = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: tun_mtu_extra = 0 Jun 21 11:12:00 netbox openvpn[98704]: tun_mtu_extra_defined = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: mtu_discover_type = -1 Jun 21 11:12:00 netbox openvpn[98704]: fragment = 0 Jun 21 11:12:00 netbox openvpn[98704]: mssfix = 1450 Jun 21 11:12:00 netbox openvpn[98704]: explicit_exit_notification = 0 Jun 21 11:12:00 netbox openvpn[98704]: Connection profiles END Jun 21 11:12:00 netbox openvpn[98704]: remote_random = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: ipchange = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: dev = 'ovpnc1' Jun 21 11:12:00 netbox openvpn[98704]: dev_type = 'tun' Jun 21 11:12:00 netbox openvpn[98704]: dev_node = '/dev/tun1' Jun 21 11:12:00 netbox openvpn[98704]: lladdr = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: topology = 1 Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_local = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_remote_netmask = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_noexec = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_nowarn = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_ipv6_local = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_ipv6_netbits = 0 Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_ipv6_remote = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: shaper = 0 Jun 21 11:12:00 netbox openvpn[98704]: mtu_test = 0 Jun 21 11:12:00 netbox openvpn[98704]: mlock = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: keepalive_ping = 10 Jun 21 11:12:00 netbox openvpn[98704]: keepalive_timeout = 60 Jun 21 11:12:00 netbox openvpn[98704]: inactivity_timeout = 0 Jun 21 11:12:00 netbox openvpn[98704]: ping_send_timeout = 10 Jun 21 11:12:00 netbox openvpn[98704]: ping_rec_timeout = 60 Jun 21 11:12:00 netbox openvpn[98704]: ping_rec_timeout_action = 2 Jun 21 11:12:00 netbox openvpn[98704]: ping_timer_remote = ENABLED Jun 21 11:12:00 netbox openvpn[98704]: remap_sigusr1 = 0 Jun 21 11:12:00 netbox openvpn[98704]: persist_tun = ENABLED Jun 21 11:12:00 netbox openvpn[98704]: persist_local_ip = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: persist_remote_ip = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: persist_key = ENABLED Jun 21 11:12:00 netbox openvpn[98704]: passtos = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: resolve_retry_seconds = 1000000000 Jun 21 11:12:00 netbox openvpn[98704]: resolve_in_advance = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: username = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: groupname = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: chroot_dir = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: cd_dir = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: writepid = '/var/run/openvpn_client1.pid' Jun 21 11:12:00 netbox openvpn[98704]: up_script = '/usr/local/sbin/ovpn-linkup' Jun 21 11:12:00 netbox openvpn[98704]: down_script = '/usr/local/sbin/ovpn-linkdown' Jun 21 11:12:00 netbox openvpn[98704]: down_pre = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: up_restart = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: up_delay = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: daemon = ENABLED Jun 21 11:12:00 netbox openvpn[98704]: inetd = 0 Jun 21 11:12:00 netbox openvpn[98704]: log = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: suppress_timestamps = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: machine_readable_output = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: nice = 0 Jun 21 11:12:00 netbox openvpn[98704]: verbosity = 11 Jun 21 11:12:00 netbox openvpn[98704]: mute = 0 Jun 21 11:12:00 netbox openvpn[98704]: gremlin = 0 Jun 21 11:12:00 netbox openvpn[98704]: status_file = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: status_file_version = 1 Jun 21 11:12:00 netbox openvpn[98704]: status_file_update_freq = 60 Jun 21 11:12:00 netbox openvpn[98704]: occ = ENABLED Jun 21 11:12:00 netbox openvpn[98704]: rcvbuf = 0 Jun 21 11:12:00 netbox openvpn[98704]: sndbuf = 0 Jun 21 11:12:00 netbox openvpn[98704]: sockflags = 0 Jun 21 11:12:00 netbox openvpn[98704]: fast_io = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: comp.alg = 2 Jun 21 11:12:00 netbox openvpn[98704]: comp.flags = 1 Jun 21 11:12:00 netbox openvpn[98704]: route_script = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: route_default_gateway = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: route_default_metric = 0 Jun 21 11:12:00 netbox openvpn[98704]: route_noexec = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: route_delay = 0 Jun 21 11:12:00 netbox openvpn[98704]: route_delay_window = 30 Jun 21 11:12:00 netbox openvpn[98704]: route_delay_defined = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: route_nopull = ENABLED Jun 21 11:12:00 netbox openvpn[98704]: route_gateway_via_dhcp = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: allow_pull_fqdn = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: management_addr = '/var/etc/openvpn/client1.sock' Jun 21 11:12:00 netbox openvpn[98704]: management_port = 'unix' Jun 21 11:12:00 netbox openvpn[98704]: management_user_pass = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: management_log_history_cache = 250 Jun 21 11:12:00 netbox openvpn[98704]: management_echo_buffer_size = 100 Jun 21 11:12:00 netbox openvpn[98704]: management_write_peer_info_file = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: management_client_user = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: management_client_group = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: management_flags = 256 Jun 21 11:12:00 netbox openvpn[98704]: shared_secret_file = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: key_direction = 0 Jun 21 11:12:00 netbox openvpn[98704]: ciphername = 'AES-256-CBC' Jun 21 11:12:00 netbox openvpn[98704]: ncp_enabled = ENABLED Jun 21 11:12:00 netbox openvpn[98704]: ncp_ciphers = 'AES-256-GCM:AES-128-GCM' Jun 21 11:12:00 netbox openvpn[98704]: authname = 'SHA256' Jun 21 11:12:00 netbox openvpn[98704]: prng_hash = 'SHA1' Jun 21 11:12:00 netbox openvpn[98704]: prng_nonce_secret_len = 16 Jun 21 11:12:00 netbox openvpn[98704]: keysize = 0 Jun 21 11:12:00 netbox openvpn[98704]: engine = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: replay = ENABLED Jun 21 11:12:00 netbox openvpn[98704]: mute_replay_warnings = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: replay_window = 64 Jun 21 11:12:00 netbox openvpn[98704]: replay_time = 15 Jun 21 11:12:00 netbox openvpn[98704]: packet_id_file = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: use_iv = ENABLED Jun 21 11:12:00 netbox openvpn[98704]: test_crypto = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: tls_server = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: tls_client = ENABLED Jun 21 11:12:00 netbox openvpn[98704]: key_method = 2 Jun 21 11:12:00 netbox openvpn[98704]: ca_file = '/var/etc/openvpn/client1.ca' Jun 21 11:12:00 netbox openvpn[98704]: ca_path = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: dh_file = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: cert_file = '/var/etc/openvpn/client1.cert' Jun 21 11:12:00 netbox openvpn[98704]: extra_certs_file = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: priv_key_file = '/var/etc/openvpn/client1.key' Jun 21 11:12:00 netbox openvpn[98704]: pkcs12_file = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: cipher_list = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: tls_verify = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: tls_export_cert = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: verify_x509_type = 0 Jun 21 11:12:00 netbox openvpn[98704]: verify_x509_name = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: crl_file = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: ns_cert_type = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 65535 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_ku[i] = 0 Jun 21 11:12:00 netbox openvpn[98704]: remote_cert_eku = 'TLS Web Server Authentication' Jun 21 11:12:00 netbox openvpn[98704]: ssl_flags = 0 Jun 21 11:12:00 netbox openvpn[98704]: tls_timeout = 2 Jun 21 11:12:00 netbox openvpn[98704]: renegotiate_bytes = -1 Jun 21 11:12:00 netbox openvpn[98704]: renegotiate_packets = 0 Jun 21 11:12:00 netbox openvpn[98704]: renegotiate_seconds = 3600 Jun 21 11:12:00 netbox openvpn[98704]: handshake_window = 60 Jun 21 11:12:00 netbox openvpn[98704]: transition_window = 3600 Jun 21 11:12:00 netbox openvpn[98704]: single_session = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: push_peer_info = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: tls_exit = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: tls_auth_file = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: tls_crypt_file = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: server_network = 0.0.0.0 Jun 21 11:12:00 netbox openvpn[98704]: server_netmask = 0.0.0.0 Jun 21 11:12:00 netbox openvpn[98704]: server_network_ipv6 = :: Jun 21 11:12:00 netbox openvpn[98704]: server_netbits_ipv6 = 0 Jun 21 11:12:00 netbox openvpn[98704]: server_bridge_ip = 0.0.0.0 Jun 21 11:12:00 netbox openvpn[98704]: server_bridge_netmask = 0.0.0.0 Jun 21 11:12:00 netbox openvpn[98704]: server_bridge_pool_start = 0.0.0.0 Jun 21 11:12:00 netbox openvpn[98704]: server_bridge_pool_end = 0.0.0.0 Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_pool_defined = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_pool_start = 0.0.0.0 Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_pool_end = 0.0.0.0 Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_pool_netmask = 0.0.0.0 Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_pool_persist_filename = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_pool_persist_refresh_freq = 600 Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_ipv6_pool_defined = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_ipv6_pool_base = :: Jun 21 11:12:00 netbox openvpn[98704]: ifconfig_ipv6_pool_netbits = 0 Jun 21 11:12:00 netbox openvpn[98704]: n_bcast_buf = 256 Jun 21 11:12:00 netbox openvpn[98704]: tcp_queue_limit = 64 Jun 21 11:12:00 netbox openvpn[98704]: real_hash_size = 256 Jun 21 11:12:00 netbox openvpn[98704]: virtual_hash_size = 256 Jun 21 11:12:00 netbox openvpn[98704]: client_connect_script = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: learn_address_script = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: client_disconnect_script = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: client_config_dir = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: ccd_exclusive = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: tmp_dir = '/tmp' Jun 21 11:12:00 netbox openvpn[98704]: push_ifconfig_defined = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: push_ifconfig_local = 0.0.0.0 Jun 21 11:12:00 netbox openvpn[98704]: push_ifconfig_remote_netmask = 0.0.0.0 Jun 21 11:12:00 netbox openvpn[98704]: push_ifconfig_ipv6_defined = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: push_ifconfig_ipv6_local = ::/0 Jun 21 11:12:00 netbox openvpn[98704]: push_ifconfig_ipv6_remote = :: Jun 21 11:12:00 netbox openvpn[98704]: enable_c2c = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: duplicate_cn = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: cf_max = 0 Jun 21 11:12:00 netbox openvpn[98704]: cf_per = 0 Jun 21 11:12:00 netbox openvpn[98704]: max_clients = 1024 Jun 21 11:12:00 netbox openvpn[98704]: max_routes_per_client = 256 Jun 21 11:12:00 netbox openvpn[98704]: auth_user_pass_verify_script = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: auth_user_pass_verify_script_via_file = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: auth_token_generate = DISABLED Jun 21 11:12:00 netbox openvpn[98704]: auth_token_lifetime = 0 Jun 21 11:12:00 netbox openvpn[98704]: port_share_host = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: port_share_port = '[UNDEF]' Jun 21 11:12:00 netbox openvpn[98704]: client = ENABLED Jun 21 11:12:00 netbox openvpn[98704]: pull = ENABLED Jun 21 11:12:00 netbox openvpn[98704]: auth_user_pass_file = '/etc/openvpn-passwd.txt' Jun 21 11:12:00 netbox openvpn[98704]: OpenVPN 2.4.3 amd64-portbld-freebsd11.0 [SSL (OpenSSL)] [LZO] [LZ4] [MH/RECVDA] [AEAD] built on Jun 21 2017 Jun 21 11:12:00 netbox openvpn[98704]: library versions: OpenSSL 1.0.2k-freebsd 26 Jan 2017, LZO 2.10 Jun 21 11:12:00 netbox openvpn[98932]: PO_INIT maxevents=1 flags=0x00000002 Jun 21 11:12:00 netbox openvpn[98932]: MANAGEMENT: unix domain socket listening on /var/etc/openvpn/client1.sock Jun 21 11:12:00 netbox openvpn[98932]: NOTE: the current --script-security setting may allow this configuration to call user-defined scripts Jun 21 11:12:00 netbox openvpn[98932]: PO_INIT maxevents=4 flags=0x00000002 Jun 21 11:12:00 netbox openvpn[98932]: PRNG init md=SHA1 size=36 Jun 21 11:12:00 netbox openvpn[98932]: LZO compression initializing Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_session_init: entry Jun 21 11:12:00 netbox openvpn[98932]: PID packet_id_init seq_backtrack=64 time_backtrack=15 Jun 21 11:12:00 netbox openvpn[98932]: PID packet_id_init seq_backtrack=64 time_backtrack=15 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_session_init: new session object, sid=49a6f68a 4d1397a7 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_session_init: entry Jun 21 11:12:00 netbox openvpn[98932]: PID packet_id_init seq_backtrack=64 time_backtrack=15 Jun 21 11:12:00 netbox openvpn[98932]: PID packet_id_init seq_backtrack=64 time_backtrack=15 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_session_init: new session object, sid=5025494d 40ee8975 Jun 21 11:12:00 netbox openvpn[98932]: Control Channel MTU parms [ L:1622 D:1212 EF:38 EB:0 ET:0 EL:3 ] Jun 21 11:12:00 netbox openvpn[98932]: MTU DYNAMIC mtu=1450, flags=2, 1622 -> 1450 Jun 21 11:12:00 netbox openvpn[98932]: GETADDRINFO flags=0x0901 ai_family=2 ai_socktype=2 Jun 21 11:12:00 netbox openvpn[98932]: RESOLVE_REMOTE flags=0x0901 phase=1 rrs=0 sig=-1 status=0 Jun 21 11:12:00 netbox openvpn[98932]: Data Channel MTU parms [ L:1622 D:1450 EF:122 EB:406 ET:0 EL:3 ] Jun 21 11:12:00 netbox openvpn[98932]: crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 68 bytes Jun 21 11:12:00 netbox openvpn[98932]: calc_options_string_link_mtu: link-mtu 1622 -> 1570 Jun 21 11:12:00 netbox openvpn[98932]: crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 68 bytes Jun 21 11:12:00 netbox openvpn[98932]: calc_options_string_link_mtu: link-mtu 1622 -> 1570 Jun 21 11:12:00 netbox openvpn[98932]: Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1570,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA256,keysize 256,key-method 2,tls-client' Jun 21 11:12:00 netbox openvpn[98932]: Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1570,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA256,keysize 256,key-method 2,tls-server' Jun 21 11:12:00 netbox openvpn[98932]: TCP/UDP: Preserving recently used remote address: [AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: Socket Buffers: R=[42080->42080] S=[57344->57344] Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 link local (bound): [AF_INET]xxx.removedprivateip.xxx:0 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 link remote: [AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS Warning: no data channel send key available: [key#0 state=S_INITIAL id=0 sid=00000000 00000000] [key#1 state=S_UNDEF id=0 sid=00000000 00000000] [key#2 state=S_UNDEF id=0 sid=00000000 00000000] Jun 21 11:12:00 netbox openvpn[98932]: SENT PING Jun 21 11:12:00 netbox openvpn[98932]: TIMER: coarse timer wakeup 1 seconds Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_INITIAL, mysid=49a6f68a 4d1397a7, stored-sid=00000000 00000000, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_INITIAL lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK mark active outgoing ID 0 Jun 21 11:12:00 netbox openvpn[98932]: TLS: Initial Handshake, sid=49a6f68a 4d1397a7 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=1 current=1 : [1] 0 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send ID 0 (size=4 to=2) Jun 21 11:12:00 netbox openvpn[98932]: Reliable -> TCP/UDP Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 2 [1] 0 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: RANDOM USEC=60863 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000004 rwflags=0x0002 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0002 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 WRITE [14] to [AF_INET]198.8.80.219:1197: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 sid=49a6f68a 4d1397a7 [ ] pid=0 DATA Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 write returned 14 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=49a6f68a 4d1397a7, stored-sid=00000000 00000000, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=1 current=0 : [1] 0 Jun 21 11:12:00 netbox openvpn[98932]: SSL state (connect): before/connect initialization Jun 21 11:12:00 netbox openvpn[98932]: SSL state (connect): SSLv2/v3 write client hello A Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 2 [1] 0 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 read returned 26 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 READ [26] from [AF_INET]198.8.80.219:1197: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 sid=3a2463a5 889b5e13 [ 0 sid=49a6f68a 4d1397a7 ] pid=0 DATA Jun 21 11:12:00 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_HARD_RESET_SERVER_V2, IP=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_PRE_START, mysid=49a6f68a 4d1397a7, rec-sid=3a2463a5 889b5e13, rec-ip=[AF_INET]198.8.80.219:1197, stored-sid=00000000 00000000, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: initial packet test, i=1 state=S_INITIAL, mysid=5025494d 40ee8975, rec-sid=3a2463a5 889b5e13, rec-ip=[AF_INET]198.8.80.219:1197, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: initial packet test, i=2 state=S_UNDEF, mysid=00000000 00000000, rec-sid=3a2463a5 889b5e13, rec-ip=[AF_INET]198.8.80.219:1197, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: Initial packet from [AF_INET]198.8.80.219:1197, sid=3a2463a5 889b5e13 Jun 21 11:12:00 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=3a2463a5 889b5e13 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_schedule_now Jun 21 11:12:00 netbox openvpn[98932]: ACK received for pid 0, deleting from send buffer Jun 21 11:12:00 netbox openvpn[98932]: ACK read ID 0 (buf->len=0) Jun 21 11:12:00 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000000 id=00000000 ret=1 Jun 21 11:12:00 netbox openvpn[98932]: ACK mark active incoming ID 0 Jun 21 11:12:00 netbox openvpn[98932]: ACK acknowledge ID 0 (ack->len=1) Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [1] Jun 21 11:12:00 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [1] Jun 21 11:12:00 netbox openvpn[98932]: ACK write ID 0 (ack->len=1, n=1) Jun 21 11:12:00 netbox openvpn[98932]: Dedicated ACK -> TCP/UDP Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [1] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000004 rwflags=0x0002 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0002 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 WRITE [22] to [AF_INET]198.8.80.219:1197: P_ACK_V1 kid=0 sid=49a6f68a 4d1397a7 [ 0 sid=3a2463a5 889b5e13 ] Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 write returned 22 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: STATE S_START Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [1] Jun 21 11:12:00 netbox openvpn[98932]: WARNING: this configuration may cache passwords in memory -- use the auth-nocache option to prevent this Jun 21 11:12:00 netbox openvpn[98932]: STATE S_SENT_KEY Jun 21 11:12:00 netbox openvpn[98932]: BIO read tls_read_ciphertext 173 bytes Jun 21 11:12:00 netbox openvpn[98932]: ACK mark active outgoing ID 1 Jun 21 11:12:00 netbox openvpn[98932]: Outgoing Ciphertext -> Reliable Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=1 current=1 : [2] 1 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send ID 1 (size=177 to=2) Jun 21 11:12:00 netbox openvpn[98932]: Reliable -> TCP/UDP Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 2 [2] 1 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000004 rwflags=0x0002 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0002 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 WRITE [187] to [AF_INET]198.8.80.219:1197: P_CONTROL_V1 kid=0 sid=49a6f68a 4d1397a7 [ ] pid=1 DATA 16030100 a8010000 a40303c4 2929cce0 c5ad2387 66213af9 5369c44f d53d88f[more...] Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 write returned 187 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=1 current=0 : [2] 1 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 2 [2] 1 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 read returned 1200 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 READ [1200] from [AF_INET]198.8.80.219:1197: P_CONTROL_V1 kid=0 sid=3a2463a5 889b5e13 [ 1 sid=49a6f68a 4d1397a7 ] pid=1 DATA 16030300 36020000 3203034f fc9bb96b 50c56adb f76d19dd 00c320af b79716a[more...] Jun 21 11:12:00 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, rec-sid=3a2463a5 889b5e13, rec-ip=[AF_INET]198.8.80.219:1197, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: found match, session[0], sid=3a2463a5 889b5e13 Jun 21 11:12:00 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=3a2463a5 889b5e13 Jun 21 11:12:00 netbox openvpn[98932]: ACK received for pid 1, deleting from send buffer Jun 21 11:12:00 netbox openvpn[98932]: ACK read ID 1 (buf->len=1174) Jun 21 11:12:00 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000001 id=00000001 ret=1 Jun 21 11:12:00 netbox openvpn[98932]: ACK mark active incoming ID 1 Jun 21 11:12:00 netbox openvpn[98932]: ACK acknowledge ID 1 (ack->len=1) Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:00 netbox openvpn[98932]: BIO write tls_write_ciphertext 1174 bytes Jun 21 11:12:00 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:00 netbox openvpn[98932]: SSL state (connect): SSLv3 read server hello A Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:00 netbox openvpn[98932]: ACK write ID 1 (ack->len=1, n=1) Jun 21 11:12:00 netbox openvpn[98932]: Dedicated ACK -> TCP/UDP Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [2] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000005 rwflags=0x0003 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0003 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 WRITE [22] to [AF_INET]198.8.80.219:1197: P_ACK_V1 kid=0 sid=49a6f68a 4d1397a7 [ 1 sid=3a2463a5 889b5e13 ] Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 write returned 22 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [2] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 read returned 1188 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 READ [1188] from [AF_INET]198.8.80.219:1197: P_CONTROL_V1 kid=0 sid=3a2463a5 889b5e13 [ ] pid=2 DATA 04030206 40301d06 03551d0e 04160414 400adc76 b9b4fbb4 962030e7 fa788b5[more...] Jun 21 11:12:00 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, rec-sid=3a2463a5 889b5e13, rec-ip=[AF_INET]198.8.80.219:1197, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: found match, session[0], sid=3a2463a5 889b5e13 Jun 21 11:12:00 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=3a2463a5 889b5e13 Jun 21 11:12:00 netbox openvpn[98932]: ACK read ID 2 (buf->len=1174) Jun 21 11:12:00 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000002 id=00000002 ret=1 Jun 21 11:12:00 netbox openvpn[98932]: ACK mark active incoming ID 2 Jun 21 11:12:00 netbox openvpn[98932]: ACK acknowledge ID 2 (ack->len=1) Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:00 netbox openvpn[98932]: BIO write tls_write_ciphertext 1174 bytes Jun 21 11:12:00 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:00 netbox openvpn[98932]: VERIFY OK: depth=1, C=US, ST=CA, L=LosAngeles, O=Private Internet Access, OU=Private Internet Access, CN=Private Internet Access, name=Private Internet Access, emailAddress=secure@privateinternetaccess.com Jun 21 11:12:00 netbox openvpn[98932]: VERIFY KU OK Jun 21 11:12:00 netbox openvpn[98932]: Validating certificate extended key usage Jun 21 11:12:00 netbox openvpn[98932]: ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication Jun 21 11:12:00 netbox openvpn[98932]: VERIFY EKU OK Jun 21 11:12:00 netbox openvpn[98932]: VERIFY OK: depth=0, C=US, ST=CA, L=LosAngeles, O=Private Internet Access, OU=Private Internet Access, CN=6d06d2334ed0762a14705ff715a12180, name=6d06d2334ed0762a14705ff715a12180 Jun 21 11:12:00 netbox openvpn[98932]: SSL state (connect): SSLv3 read server certificate A Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:00 netbox openvpn[98932]: ACK write ID 2 (ack->len=1, n=1) Jun 21 11:12:00 netbox openvpn[98932]: Dedicated ACK -> TCP/UDP Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [2] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000005 rwflags=0x0003 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0003 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 WRITE [22] to [AF_INET]198.8.80.219:1197: P_ACK_V1 kid=0 sid=49a6f68a 4d1397a7 [ 2 sid=3a2463a5 889b5e13 ] Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 write returned 22 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [2] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 read returned 1188 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 READ [1188] from [AF_INET]198.8.80.219:1197: P_CONTROL_V1 kid=0 sid=3a2463a5 889b5e13 [ ] pid=3 DATA ec9a3dcb 3588bc65 960374de cdeab7e2 ca46f378 fd7a4ff4 eead5755 f53fa6f[more...] Jun 21 11:12:00 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, rec-sid=3a2463a5 889b5e13, rec-ip=[AF_INET]198.8.80.219:1197, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: found match, session[0], sid=3a2463a5 889b5e13 Jun 21 11:12:00 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=3a2463a5 889b5e13 Jun 21 11:12:00 netbox openvpn[98932]: ACK read ID 3 (buf->len=1174) Jun 21 11:12:00 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000003 id=00000003 ret=1 Jun 21 11:12:00 netbox openvpn[98932]: ACK mark active incoming ID 3 Jun 21 11:12:00 netbox openvpn[98932]: ACK acknowledge ID 3 (ack->len=1) Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:00 netbox openvpn[98932]: BIO write tls_write_ciphertext 1174 bytes Jun 21 11:12:00 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:00 netbox openvpn[98932]: ACK write ID 3 (ack->len=1, n=1) Jun 21 11:12:00 netbox openvpn[98932]: Dedicated ACK -> TCP/UDP Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [2] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000005 rwflags=0x0003 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0003 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 WRITE [22] to [AF_INET]198.8.80.219:1197: P_ACK_V1 kid=0 sid=49a6f68a 4d1397a7 [ 3 sid=3a2463a5 889b5e13 ] Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 write returned 22 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [2] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 read returned 124 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 READ [124] from [AF_INET]198.8.80.219:1197: P_CONTROL_V1 kid=0 sid=3a2463a5 889b5e13 [ ] pid=4 DATA 37f0852d 29c10ae4 c730bd98 6cf2f46d 6e054f28 56def390 2ee65606 34ff907[more...] Jun 21 11:12:00 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, rec-sid=3a2463a5 889b5e13, rec-ip=[AF_INET]198.8.80.219:1197, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: found match, session[0], sid=3a2463a5 889b5e13 Jun 21 11:12:00 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=3a2463a5 889b5e13 Jun 21 11:12:00 netbox openvpn[98932]: ACK read ID 4 (buf->len=110) Jun 21 11:12:00 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000004 id=00000004 ret=1 Jun 21 11:12:00 netbox openvpn[98932]: ACK mark active incoming ID 4 Jun 21 11:12:00 netbox openvpn[98932]: ACK acknowledge ID 4 (ack->len=1) Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:00 netbox openvpn[98932]: BIO write tls_write_ciphertext 110 bytes Jun 21 11:12:00 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:00 netbox openvpn[98932]: SSL state (connect): SSLv3 read server key exchange A Jun 21 11:12:00 netbox openvpn[98932]: SSL state (connect): SSLv3 read server done A Jun 21 11:12:00 netbox openvpn[98932]: SSL state (connect): SSLv3 write client key exchange A Jun 21 11:12:00 netbox openvpn[98932]: SSL state (connect): SSLv3 write change cipher spec A Jun 21 11:12:00 netbox openvpn[98932]: SSL state (connect): SSLv3 write finished A Jun 21 11:12:00 netbox openvpn[98932]: SSL state (connect): SSLv3 flush data Jun 21 11:12:00 netbox openvpn[98932]: BIO read tls_read_ciphertext 574 bytes Jun 21 11:12:00 netbox openvpn[98932]: ACK mark active outgoing ID 2 Jun 21 11:12:00 netbox openvpn[98932]: Outgoing Ciphertext -> Reliable Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=1 current=1 : [3] 2 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send ID 2 (size=578 to=2) Jun 21 11:12:00 netbox openvpn[98932]: ACK write ID 4 (ack->len=1, n=1) Jun 21 11:12:00 netbox openvpn[98932]: Reliable -> TCP/UDP Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 2 [3] 2 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000004 rwflags=0x0002 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0002 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 WRITE [600] to [AF_INET]198.8.80.219:1197: P_CONTROL_V1 kid=0 sid=49a6f68a 4d1397a7 [ 4 sid=3a2463a5 889b5e13 ] pid=2 DATA 16030302 06100002 02020010 65036b2c a1f031f7 260a602a d1efd8d0 8bd018d[more...] Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 write returned 600 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=1 current=0 : [3] 2 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 2 [3] 2 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 read returned 77 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 READ [77] from [AF_INET]198.8.80.219:1197: P_CONTROL_V1 kid=0 sid=3a2463a5 889b5e13 [ 2 sid=49a6f68a 4d1397a7 ] pid=5 DATA 14030300 01011603 0300285c 67dbeaa4 a72fdd68 fc295d5b 64688e92 a5c5e0a[more...] Jun 21 11:12:00 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, rec-sid=3a2463a5 889b5e13, rec-ip=[AF_INET]198.8.80.219:1197, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: found match, session[0], sid=3a2463a5 889b5e13 Jun 21 11:12:00 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=3a2463a5 889b5e13 Jun 21 11:12:00 netbox openvpn[98932]: ACK received for pid 2, deleting from send buffer Jun 21 11:12:00 netbox openvpn[98932]: ACK read ID 5 (buf->len=51) Jun 21 11:12:00 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000005 id=00000005 ret=1 Jun 21 11:12:00 netbox openvpn[98932]: ACK mark active incoming ID 5 Jun 21 11:12:00 netbox openvpn[98932]: ACK acknowledge ID 5 (ack->len=1) Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [3] Jun 21 11:12:00 netbox openvpn[98932]: BIO write tls_write_ciphertext 51 bytes Jun 21 11:12:00 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:00 netbox openvpn[98932]: SSL state (connect): SSLv3 read finished A Jun 21 11:12:00 netbox openvpn[98932]: BIO write tls_write_plaintext 452 bytes Jun 21 11:12:00 netbox openvpn[98932]: Outgoing Plaintext -> TLS Jun 21 11:12:00 netbox openvpn[98932]: BIO read tls_read_ciphertext 481 bytes Jun 21 11:12:00 netbox openvpn[98932]: ACK mark active outgoing ID 3 Jun 21 11:12:00 netbox openvpn[98932]: Outgoing Ciphertext -> Reliable Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=1 current=1 : [4] 3 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send ID 3 (size=485 to=2) Jun 21 11:12:00 netbox openvpn[98932]: ACK write ID 5 (ack->len=1, n=1) Jun 21 11:12:00 netbox openvpn[98932]: Reliable -> TCP/UDP Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 2 [4] 3 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000004 rwflags=0x0002 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0002 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 WRITE [507] to [AF_INET]198.8.80.219:1197: P_CONTROL_V1 kid=0 sid=49a6f68a 4d1397a7 [ 5 sid=3a2463a5 889b5e13 ] pid=3 DATA 17030301 dc29ded6 f19a30ab 0efe0df4 aaaf357d 7a53a59d fddab0e4 35667c3[more...] Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 write returned 507 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=1 current=0 : [4] 3 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 2 [4] 3 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 read returned 256 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 READ [256] from [AF_INET]198.8.80.219:1197: P_CONTROL_V1 kid=0 sid=3a2463a5 889b5e13 [ 3 sid=49a6f68a 4d1397a7 ] pid=6 DATA 17030300 e15c67db eaa4a72f ded0f1f9 29c00923 242503d6 45058361 ab773a4[more...] Jun 21 11:12:00 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, rec-sid=3a2463a5 889b5e13, rec-ip=[AF_INET]198.8.80.219:1197, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: found match, session[0], sid=3a2463a5 889b5e13 Jun 21 11:12:00 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=3a2463a5 889b5e13 Jun 21 11:12:00 netbox openvpn[98932]: ACK received for pid 3, deleting from send buffer Jun 21 11:12:00 netbox openvpn[98932]: ACK read ID 6 (buf->len=230) Jun 21 11:12:00 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000006 id=00000006 ret=1 Jun 21 11:12:00 netbox openvpn[98932]: ACK mark active incoming ID 6 Jun 21 11:12:00 netbox openvpn[98932]: ACK acknowledge ID 6 (ack->len=1) Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [4] Jun 21 11:12:00 netbox openvpn[98932]: BIO write tls_write_ciphertext 230 bytes Jun 21 11:12:00 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:00 netbox openvpn[98932]: BIO read tls_read_plaintext 201 bytes Jun 21 11:12:00 netbox openvpn[98932]: TLS -> Incoming Plaintext Jun 21 11:12:00 netbox openvpn[98932]: WARNING: 'link-mtu' is used inconsistently, local='link-mtu 1570', remote='link-mtu 1542' Jun 21 11:12:00 netbox openvpn[98932]: WARNING: 'cipher' is used inconsistently, local='cipher AES-256-CBC', remote='cipher BF-CBC' Jun 21 11:12:00 netbox openvpn[98932]: WARNING: 'auth' is used inconsistently, local='auth SHA256', remote='auth SHA1' Jun 21 11:12:00 netbox openvpn[98932]: WARNING: 'keysize' is used inconsistently, local='keysize 256', remote='keysize 128' Jun 21 11:12:00 netbox openvpn[98932]: STATE S_GOT_KEY Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_GOT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [4] Jun 21 11:12:00 netbox openvpn[98932]: ACK write ID 6 (ack->len=1, n=1) Jun 21 11:12:00 netbox openvpn[98932]: Dedicated ACK -> TCP/UDP Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [4] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/60863] Jun 21 11:12:00 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000004 rwflags=0x0002 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT status=0x0002 Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 WRITE [22] to [AF_INET]198.8.80.219:1197: P_ACK_V1 kid=0 sid=49a6f68a 4d1397a7 [ 6 sid=3a2463a5 889b5e13 ] Jun 21 11:12:00 netbox openvpn[98932]: UDPv4 write returned 22 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_GOT_KEY, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_GOT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: STATE S_ACTIVE Jun 21 11:12:00 netbox openvpn[98932]: Control Channel: TLSv1.2, cipher TLSv1/SSLv3 DHE-RSA-AES256-GCM-SHA384, 4096 bit RSA Jun 21 11:12:00 netbox openvpn[98932]: [6d06d2334ed0762a14705ff715a12180] Peer Connection Initiated with [AF_INET]198.8.80.219:1197[/i][/i][/i][/i][/i][/i][/i][/i][/i][/i][/i][/i][/i][/i][/i][/i]
-
Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [4] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [4] Jun 21 11:12:00 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [4] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:00 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:00 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/60863] Jun 21 11:12:01 netbox openvpn[98932]: event_wait returned 0 Jun 21 11:12:01 netbox openvpn[98932]: I/O WAIT status=0x0020 Jun 21 11:12:01 netbox openvpn[98932]: BIO write tls_write_plaintext_const 13 bytes Jun 21 11:12:01 netbox openvpn[98932]: SENT CONTROL [6d06d2334ed0762a14705ff715a12180]: 'PUSH_REQUEST' (status=1) Jun 21 11:12:01 netbox openvpn[98932]: TIMER: coarse timer wakeup 0 seconds Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_ACTIVE, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:01 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [4] Jun 21 11:12:01 netbox openvpn[98932]: BIO read tls_read_ciphertext 42 bytes Jun 21 11:12:01 netbox openvpn[98932]: ACK mark active outgoing ID 4 Jun 21 11:12:01 netbox openvpn[98932]: Outgoing Ciphertext -> Reliable Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:01 netbox openvpn[98932]: ACK reliable_can_send active=1 current=1 : [5] 4 Jun 21 11:12:01 netbox openvpn[98932]: ACK reliable_send ID 4 (size=46 to=2) Jun 21 11:12:01 netbox openvpn[98932]: Reliable -> TCP/UDP Jun 21 11:12:01 netbox openvpn[98932]: ACK reliable_send_timeout 2 [5] 4 Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:01 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:01 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:01 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [0/0] Jun 21 11:12:01 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000004 rwflags=0x0002 arg=0x006a38c0 Jun 21 11:12:01 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:01 netbox openvpn[98932]: I/O WAIT status=0x0002 Jun 21 11:12:01 netbox openvpn[98932]: UDPv4 WRITE [56] to [AF_INET]198.8.80.219:1197: P_CONTROL_V1 kid=0 sid=49a6f68a 4d1397a7 [ ] pid=4 DATA 17030300 2529ded6 f19a30ab 0fff9a17 65422145 3e6a0629 9f537619 d2ea2e1[more...] Jun 21 11:12:01 netbox openvpn[98932]: UDPv4 write returned 56 Jun 21 11:12:01 netbox openvpn[98932]: TIMER: coarse timer wakeup 5 seconds Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_ACTIVE, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:01 netbox openvpn[98932]: ACK reliable_can_send active=1 current=0 : [5] 4 Jun 21 11:12:01 netbox openvpn[98932]: ACK reliable_send_timeout 2 [5] 4 Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:01 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:01 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:01 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [2/60863] Jun 21 11:12:01 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:01 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:01 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:01 netbox openvpn[98932]: UDPv4 read returned 22 Jun 21 11:12:01 netbox openvpn[98932]: UDPv4 READ [22] from [AF_INET]198.8.80.219:1197: P_ACK_V1 kid=0 sid=3a2463a5 889b5e13 [ 4 sid=49a6f68a 4d1397a7 ] Jun 21 11:12:01 netbox openvpn[98932]: TLS: control channel, op=P_ACK_V1, IP=[AF_INET]198.8.80.219:1197 Jun 21 11:12:01 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_ACTIVE, mysid=49a6f68a 4d1397a7, rec-sid=3a2463a5 889b5e13, rec-ip=[AF_INET]198.8.80.219:1197, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:01 netbox openvpn[98932]: TLS: found match, session[0], sid=3a2463a5 889b5e13 Jun 21 11:12:01 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=3a2463a5 889b5e13 Jun 21 11:12:01 netbox openvpn[98932]: ACK received for pid 4, deleting from send buffer Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_ACTIVE, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:01 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [5] Jun 21 11:12:01 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [5] Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_process: timeout set to 59 Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:01 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:01 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:01 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [5/60863] Jun 21 11:12:01 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:01 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:01 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:01 netbox openvpn[98932]: UDPv4 read returned 297 Jun 21 11:12:01 netbox openvpn[98932]: UDPv4 READ [297] from [AF_INET]198.8.80.219:1197: P_CONTROL_V1 kid=0 sid=3a2463a5 889b5e13 [ ] pid=7 DATA 17030301 165c67db eaa4a72f df8f273a 7ec4f7b7 c029998b ccf91900 fcee314[more...] Jun 21 11:12:01 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]198.8.80.219:1197 Jun 21 11:12:01 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_ACTIVE, mysid=49a6f68a 4d1397a7, rec-sid=3a2463a5 889b5e13, rec-ip=[AF_INET]198.8.80.219:1197, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:01 netbox openvpn[98932]: TLS: found match, session[0], sid=3a2463a5 889b5e13 Jun 21 11:12:01 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=3a2463a5 889b5e13 Jun 21 11:12:01 netbox openvpn[98932]: ACK read ID 7 (buf->len=283) Jun 21 11:12:01 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000007 id=00000007 ret=1 Jun 21 11:12:01 netbox openvpn[98932]: ACK mark active incoming ID 7 Jun 21 11:12:01 netbox openvpn[98932]: ACK acknowledge ID 7 (ack->len=1) Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_ACTIVE, mysid=49a6f68a 4d1397a7, stored-sid=3a2463a5 889b5e13, stored-ip=[AF_INET]198.8.80.219:1197 Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:01 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [5] Jun 21 11:12:01 netbox openvpn[98932]: BIO write tls_write_ciphertext 283 bytes Jun 21 11:12:01 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:01 netbox openvpn[98932]: BIO read tls_read_plaintext 254 bytes Jun 21 11:12:01 netbox openvpn[98932]: TLS -> Incoming Plaintext Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:01 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [5] Jun 21 11:12:01 netbox openvpn[98932]: ACK write ID 7 (ack->len=1, n=1) Jun 21 11:12:01 netbox openvpn[98932]: Dedicated ACK -> TCP/UDP Jun 21 11:12:01 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [5] Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_process: timeout set to 59 Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=5025494d 40ee8975, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:01 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:01 netbox openvpn[98932]: PUSH: Received control message: 'PUSH_REPLY,redirect-gateway def1,dhcp-option DNS 209.222.18.222,dhcp-option DNS 209.222.18.218,ping 10,comp-lzo no,route 10.43.10.1,topology net30,ifconfig 10.43.10.10 10.43.10.9,cipher aes-256-cbc,auth-token NO81+BdGf3hTGwHDJ/QsN3Pkh8MpwcXhOl/OUnvZDcI=' Jun 21 11:12:01 netbox openvpn[98932]: Options error: option 'redirect-gateway' cannot be used in this context ([PUSH-OPTIONS]) Jun 21 11:12:01 netbox openvpn[98932]: Options error: option 'dhcp-option' cannot be used in this context ([PUSH-OPTIONS]) Jun 21 11:12:01 netbox openvpn[98932]: Options error: option 'dhcp-option' cannot be used in this context ([PUSH-OPTIONS]) Jun 21 11:12:01 netbox openvpn[98932]: Options error: option 'route' cannot be used in this context ([PUSH-OPTIONS]) Jun 21 11:12:01 netbox openvpn[98932]: OPTIONS IMPORT: timers and/or timeouts modified Jun 21 11:12:01 netbox openvpn[98932]: OPTIONS IMPORT: compression parms modified Jun 21 11:12:01 netbox openvpn[98932]: OPTIONS IMPORT: --ifconfig/up options modified Jun 21 11:12:01 netbox openvpn[98932]: OPTIONS IMPORT: data channel crypto options modified Jun 21 11:12:01 netbox openvpn[98932]: Error: pushed cipher not allowed - aes-256-cbc not in AES-256-CBC or AES-256-GCM:AES-128-GCM Jun 21 11:12:01 netbox openvpn[98932]: OPTIONS ERROR: failed to import crypto options Jun 21 11:12:01 netbox openvpn[98932]: ERROR: Failed to apply push options Jun 21 11:12:01 netbox openvpn[98932]: Failed to open tun/tap interface Jun 21 11:12:01 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:01 netbox openvpn[98932]: SSL alert (write): warning: close notify Jun 21 11:12:01 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:01 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:01 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:01 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:01 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:01 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:01 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:01 netbox openvpn[98932]: TCP/UDP: Closing socket Jun 21 11:12:01 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:01 netbox openvpn[98932]: SIGUSR1[soft,process-push-msg-failed] received, process restarting Jun 21 11:12:01 netbox openvpn[98932]: Restart pause, 5 second(s) Jun 21 11:12:01 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x00000000 Jun 21 11:12:02 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x00000000 Jun 21 11:12:03 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x00000000 Jun 21 11:12:04 netbox openvpn[98932]: PO_WAIT[0,0] fd=4 rev=0x00000001 rwflags=0x0001 arg=0x00000000 Jun 21 11:12:04 netbox openvpn[98932]: MANAGEMENT: Client connected from /var/etc/openvpn/client1.sock Jun 21 11:12:04 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x00000000 Jun 21 11:12:04 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x00000000 Jun 21 11:12:04 netbox openvpn[98932]: MANAGEMENT: CMD 'state 1' Jun 21 11:12:04 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x00000000 Jun 21 11:12:04 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000011 rwflags=0x0001 arg=0x00000000 Jun 21 11:12:04 netbox openvpn[98932]: MANAGEMENT: Client disconnected Jun 21 11:12:04 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x00000000 Jun 21 11:12:05 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x00000000 Jun 21 11:12:06 netbox openvpn[98932]: NOTE: the current --script-security setting may allow this configuration to call user-defined scripts Jun 21 11:12:06 netbox openvpn[98932]: PO_INIT maxevents=4 flags=0x00000002 Jun 21 11:12:06 netbox openvpn[98932]: Re-using SSL/TLS context Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_session_init: entry Jun 21 11:12:06 netbox openvpn[98932]: PID packet_id_init seq_backtrack=64 time_backtrack=15 Jun 21 11:12:06 netbox openvpn[98932]: PID packet_id_init seq_backtrack=64 time_backtrack=15 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_session_init: new session object, sid=cb33d45e 1bfb5dae Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_session_init: entry Jun 21 11:12:06 netbox openvpn[98932]: PID packet_id_init seq_backtrack=64 time_backtrack=15 Jun 21 11:12:06 netbox openvpn[98932]: PID packet_id_init seq_backtrack=64 time_backtrack=15 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_session_init: new session object, sid=32e160cb df968b27 Jun 21 11:12:06 netbox openvpn[98932]: Control Channel MTU parms [ L:1622 D:1212 EF:38 EB:0 ET:0 EL:3 ] Jun 21 11:12:06 netbox openvpn[98932]: MTU DYNAMIC mtu=1450, flags=2, 1622 -> 1450 Jun 21 11:12:06 netbox openvpn[98932]: Data Channel MTU parms [ L:1622 D:1450 EF:122 EB:406 ET:0 EL:3 ] Jun 21 11:12:06 netbox openvpn[98932]: crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 68 bytes Jun 21 11:12:06 netbox openvpn[98932]: calc_options_string_link_mtu: link-mtu 1622 -> 1570 Jun 21 11:12:06 netbox openvpn[98932]: crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 68 bytes Jun 21 11:12:06 netbox openvpn[98932]: calc_options_string_link_mtu: link-mtu 1622 -> 1570 Jun 21 11:12:06 netbox openvpn[98932]: Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1570,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA256,keysize 256,key-method 2,tls-client' Jun 21 11:12:06 netbox openvpn[98932]: Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1570,tun-mtu 1500,proto UDPv4,comp-lzo,cipher AES-256-CBC,auth SHA256,keysize 256,key-method 2,tls-server' Jun 21 11:12:06 netbox openvpn[98932]: TCP/UDP: Preserving recently used remote address: [AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: Socket Buffers: R=[42080->42080] S=[57344->57344] Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 link local (bound): [AF_INET]xxx.removedprivateip.xxx:0 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 link remote: [AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS Warning: no data channel send key available: [key#0 state=S_INITIAL id=0 sid=00000000 00000000] [key#1 state=S_UNDEF id=0 sid=00000000 00000000] [key#2 state=S_UNDEF id=0 sid=00000000 00000000] Jun 21 11:12:06 netbox openvpn[98932]: SENT PING Jun 21 11:12:06 netbox openvpn[98932]: TIMER: coarse timer wakeup 1 seconds Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_INITIAL, mysid=cb33d45e 1bfb5dae, stored-sid=00000000 00000000, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_INITIAL lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK mark active outgoing ID 0 Jun 21 11:12:06 netbox openvpn[98932]: TLS: Initial Handshake, sid=cb33d45e 1bfb5dae Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=1 current=1 : [1] 0 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send ID 0 (size=4 to=2) Jun 21 11:12:06 netbox openvpn[98932]: Reliable -> TCP/UDP Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 2 [1] 0 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: RANDOM USEC=50141 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000004 rwflags=0x0002 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0002 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 WRITE [14] to [AF_INET]198.8.80.218:1197: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 sid=cb33d45e 1bfb5dae [ ] pid=0 DATA Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 write returned 14 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=cb33d45e 1bfb5dae, stored-sid=00000000 00000000, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=1 current=0 : [1] 0 Jun 21 11:12:06 netbox openvpn[98932]: SSL state (connect): before/connect initialization Jun 21 11:12:06 netbox openvpn[98932]: SSL state (connect): SSLv2/v3 write client hello A Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 2 [1] 0 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 read returned 26 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 READ [26] from [AF_INET]198.8.80.218:1197: P_CONTROL_HARD_RESET_SERVER_V2 kid=0 sid=c2c928fd 0375e529 [ 0 sid=cb33d45e 1bfb5dae ] pid=0 DATA Jun 21 11:12:06 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_HARD_RESET_SERVER_V2, IP=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_PRE_START, mysid=cb33d45e 1bfb5dae, rec-sid=c2c928fd 0375e529, rec-ip=[AF_INET]198.8.80.218:1197, stored-sid=00000000 00000000, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: initial packet test, i=1 state=S_INITIAL, mysid=32e160cb df968b27, rec-sid=c2c928fd 0375e529, rec-ip=[AF_INET]198.8.80.218:1197, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: initial packet test, i=2 state=S_UNDEF, mysid=00000000 00000000, rec-sid=c2c928fd 0375e529, rec-ip=[AF_INET]198.8.80.218:1197, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: Initial packet from [AF_INET]198.8.80.218:1197, sid=c2c928fd 0375e529 Jun 21 11:12:06 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=c2c928fd 0375e529 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_schedule_now Jun 21 11:12:06 netbox openvpn[98932]: ACK received for pid 0, deleting from send buffer Jun 21 11:12:06 netbox openvpn[98932]: ACK read ID 0 (buf->len=0) Jun 21 11:12:06 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000000 id=00000000 ret=1 Jun 21 11:12:06 netbox openvpn[98932]: ACK mark active incoming ID 0 Jun 21 11:12:06 netbox openvpn[98932]: ACK acknowledge ID 0 (ack->len=1) Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [1] Jun 21 11:12:06 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [1] Jun 21 11:12:06 netbox openvpn[98932]: ACK write ID 0 (ack->len=1, n=1) Jun 21 11:12:06 netbox openvpn[98932]: Dedicated ACK -> TCP/UDP Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [1] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000004 rwflags=0x0002 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0002 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 WRITE [22] to [AF_INET]198.8.80.218:1197: P_ACK_V1 kid=0 sid=cb33d45e 1bfb5dae [ 0 sid=c2c928fd 0375e529 ] Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 write returned 22 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: STATE S_START Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [1] Jun 21 11:12:06 netbox openvpn[98932]: STATE S_SENT_KEY Jun 21 11:12:06 netbox openvpn[98932]: BIO read tls_read_ciphertext 173 bytes Jun 21 11:12:06 netbox openvpn[98932]: ACK mark active outgoing ID 1 Jun 21 11:12:06 netbox openvpn[98932]: Outgoing Ciphertext -> Reliable Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=1 current=1 : [2] 1 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send ID 1 (size=177 to=2) Jun 21 11:12:06 netbox openvpn[98932]: Reliable -> TCP/UDP Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 2 [2] 1 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000004 rwflags=0x0002 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0002 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 WRITE [187] to [AF_INET]198.8.80.218:1197: P_CONTROL_V1 kid=0 sid=cb33d45e 1bfb5dae [ ] pid=1 DATA 16030100 a8010000 a403037c 3080319d 4e5a3889 3f125942 94ef2bff b4f842f[more...] Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 write returned 187 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=1 current=0 : [2] 1 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 2 [2] 1 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 read returned 1200 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 READ [1200] from [AF_INET]198.8.80.218:1197: P_CONTROL_V1 kid=0 sid=c2c928fd 0375e529 [ 1 sid=cb33d45e 1bfb5dae ] pid=1 DATA 16030300 36020000 32030364 7f9449c4 2083a255 271cb93b bbbf577f fe6596a[more...] Jun 21 11:12:06 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, rec-sid=c2c928fd 0375e529, rec-ip=[AF_INET]198.8.80.218:1197, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: found match, session[0], sid=c2c928fd 0375e529 Jun 21 11:12:06 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=c2c928fd 0375e529 Jun 21 11:12:06 netbox openvpn[98932]: ACK received for pid 1, deleting from send buffer Jun 21 11:12:06 netbox openvpn[98932]: ACK read ID 1 (buf->len=1174) Jun 21 11:12:06 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000001 id=00000001 ret=1 Jun 21 11:12:06 netbox openvpn[98932]: ACK mark active incoming ID 1 Jun 21 11:12:06 netbox openvpn[98932]: ACK acknowledge ID 1 (ack->len=1) Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:06 netbox openvpn[98932]: BIO write tls_write_ciphertext 1174 bytes Jun 21 11:12:06 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:06 netbox openvpn[98932]: SSL state (connect): SSLv3 read server hello A Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:06 netbox openvpn[98932]: ACK write ID 1 (ack->len=1, n=1) Jun 21 11:12:06 netbox openvpn[98932]: Dedicated ACK -> TCP/UDP Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [2] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000005 rwflags=0x0003 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0003 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 WRITE [22] to [AF_INET]198.8.80.218:1197: P_ACK_V1 kid=0 sid=cb33d45e 1bfb5dae [ 1 sid=c2c928fd 0375e529 ] Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 write returned 22 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [2] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 read returned 1188 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 READ [1188] from [AF_INET]198.8.80.218:1197: P_CONTROL_V1 kid=0 sid=c2c928fd 0375e529 [ ] pid=2 DATA 04040302 0640301d 0603551d 0e041604 14df268f 84dd347f a9f09d9a 76a414c[more...] Jun 21 11:12:06 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, rec-sid=c2c928fd 0375e529, rec-ip=[AF_INET]198.8.80.218:1197, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: found match, session[0], sid=c2c928fd 0375e529 Jun 21 11:12:06 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=c2c928fd 0375e529 Jun 21 11:12:06 netbox openvpn[98932]: ACK read ID 2 (buf->len=1174) Jun 21 11:12:06 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000002 id=00000002 ret=1 Jun 21 11:12:06 netbox openvpn[98932]: ACK mark active incoming ID 2 Jun 21 11:12:06 netbox openvpn[98932]: ACK acknowledge ID 2 (ack->len=1) Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:06 netbox openvpn[98932]: BIO write tls_write_ciphertext 1174 bytes Jun 21 11:12:06 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:06 netbox openvpn[98932]: VERIFY OK: depth=1, C=US, ST=CA, L=LosAngeles, O=Private Internet Access, OU=Private Internet Access, CN=Private Internet Access, name=Private Internet Access, emailAddress=secure@privateinternetaccess.com Jun 21 11:12:06 netbox openvpn[98932]: VERIFY KU OK Jun 21 11:12:06 netbox openvpn[98932]: Validating certificate extended key usage Jun 21 11:12:06 netbox openvpn[98932]: ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication Jun 21 11:12:06 netbox openvpn[98932]: VERIFY EKU OK Jun 21 11:12:06 netbox openvpn[98932]: VERIFY OK: depth=0, C=US, ST=CA, L=LosAngeles, O=Private Internet Access, OU=Private Internet Access, CN=cad10e306a226b20e547154b96cbb909, name=cad10e306a226b20e547154b96cbb909 Jun 21 11:12:06 netbox openvpn[98932]: SSL state (connect): SSLv3 read server certificate A Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:06 netbox openvpn[98932]: ACK write ID 2 (ack->len=1, n=1) Jun 21 11:12:06 netbox openvpn[98932]: Dedicated ACK -> TCP/UDP Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [2] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000005 rwflags=0x0003 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0003 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 WRITE [22] to [AF_INET]198.8.80.218:1197: P_ACK_V1 kid=0 sid=cb33d45e 1bfb5dae [ 2 sid=c2c928fd 0375e529 ] Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 write returned 22 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [2] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 read returned 1188 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 READ [1188] from [AF_INET]198.8.80.218:1197: P_CONTROL_V1 kid=0 sid=c2c928fd 0375e529 [ ] pid=3 DATA efec9a3d cb3588bc 65960374 decdeab7 e2ca46f3 78fd7a4f f4eead57 55f53fa[more...] Jun 21 11:12:06 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, rec-sid=c2c928fd 0375e529, rec-ip=[AF_INET]198.8.80.218:1197, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: found match, session[0], sid=c2c928fd 0375e529 Jun 21 11:12:06 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=c2c928fd 0375e529 Jun 21 11:12:06 netbox openvpn[98932]: ACK read ID 3 (buf->len=1174) Jun 21 11:12:06 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000003 id=00000003 ret=1 Jun 21 11:12:06 netbox openvpn[98932]: ACK mark active incoming ID 3 Jun 21 11:12:06 netbox openvpn[98932]: ACK acknowledge ID 3 (ack->len=1) Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:06 netbox openvpn[98932]: BIO write tls_write_ciphertext 1174 bytes Jun 21 11:12:06 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:06 netbox openvpn[98932]: ACK write ID 3 (ack->len=1, n=1) Jun 21 11:12:06 netbox openvpn[98932]: Dedicated ACK -> TCP/UDP Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [2] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000005 rwflags=0x0003 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0003 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 WRITE [22] to [AF_INET]198.8.80.218:1197: P_ACK_V1 kid=0 sid=cb33d45e 1bfb5dae [ 3 sid=c2c928fd 0375e529 ] Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 write returned 22 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [2] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 read returned 125 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 READ [125] from [AF_INET]198.8.80.218:1197: P_CONTROL_V1 kid=0 sid=c2c928fd 0375e529 [ ] pid=4 DATA 6ae4d77b 1a118d41 109b8b6c 783ece8a 6b83789b dfac8ebb 649be12e 0998707[more...] Jun 21 11:12:06 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, rec-sid=c2c928fd 0375e529, rec-ip=[AF_INET]198.8.80.218:1197, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: found match, session[0], sid=c2c928fd 0375e529 Jun 21 11:12:06 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=c2c928fd 0375e529 Jun 21 11:12:06 netbox openvpn[98932]: ACK read ID 4 (buf->len=111) Jun 21 11:12:06 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000004 id=00000004 ret=1 Jun 21 11:12:06 netbox openvpn[98932]: ACK mark active incoming ID 4 Jun 21 11:12:06 netbox openvpn[98932]: ACK acknowledge ID 4 (ack->len=1) Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [2] Jun 21 11:12:06 netbox openvpn[98932]: BIO write tls_write_ciphertext 111 bytes Jun 21 11:12:06 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:06 netbox openvpn[98932]: SSL state (connect): SSLv3 read server key exchange A Jun 21 11:12:06 netbox openvpn[98932]: SSL state (connect): SSLv3 read server done A Jun 21 11:12:06 netbox openvpn[98932]: SSL state (connect): SSLv3 write client key exchange A Jun 21 11:12:06 netbox openvpn[98932]: SSL state (connect): SSLv3 write change cipher spec A Jun 21 11:12:06 netbox openvpn[98932]: SSL state (connect): SSLv3 write finished A Jun 21 11:12:06 netbox openvpn[98932]: SSL state (connect): SSLv3 flush data Jun 21 11:12:06 netbox openvpn[98932]: BIO read tls_read_ciphertext 574 bytes Jun 21 11:12:06 netbox openvpn[98932]: ACK mark active outgoing ID 2 Jun 21 11:12:06 netbox openvpn[98932]: Outgoing Ciphertext -> Reliable Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=1 current=1 : [3] 2 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send ID 2 (size=578 to=2) Jun 21 11:12:06 netbox openvpn[98932]: ACK write ID 4 (ack->len=1, n=1) Jun 21 11:12:06 netbox openvpn[98932]: Reliable -> TCP/UDP Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 2 [3] 2 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000004 rwflags=0x0002 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0002 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 WRITE [600] to [AF_INET]198.8.80.218:1197: P_CONTROL_V1 kid=0 sid=cb33d45e 1bfb5dae [ 4 sid=c2c928fd 0375e529 ] pid=2 DATA 16030302 06100002 020200e3 e2bb3639 85136116 2206a544 80e368da f9f7ec6[more...] Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 write returned 600 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=1 current=0 : [3] 2 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 2 [3] 2 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/50141]
-
Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 read returned 77 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 READ [77] from [AF_INET]198.8.80.218:1197: P_CONTROL_V1 kid=0 sid=c2c928fd 0375e529 [ 2 sid=cb33d45e 1bfb5dae ] pid=5 DATA 14030300 01011603 030028b2 0ea752f8 8fac5ba4 a792ab6c f3808d1c 7ca246a[more...] Jun 21 11:12:06 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, rec-sid=c2c928fd 0375e529, rec-ip=[AF_INET]198.8.80.218:1197, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: found match, session[0], sid=c2c928fd 0375e529 Jun 21 11:12:06 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=c2c928fd 0375e529 Jun 21 11:12:06 netbox openvpn[98932]: ACK received for pid 2, deleting from send buffer Jun 21 11:12:06 netbox openvpn[98932]: ACK read ID 5 (buf->len=51) Jun 21 11:12:06 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000005 id=00000005 ret=1 Jun 21 11:12:06 netbox openvpn[98932]: ACK mark active incoming ID 5 Jun 21 11:12:06 netbox openvpn[98932]: ACK acknowledge ID 5 (ack->len=1) Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [3] Jun 21 11:12:06 netbox openvpn[98932]: BIO write tls_write_ciphertext 51 bytes Jun 21 11:12:06 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:06 netbox openvpn[98932]: SSL state (connect): SSLv3 read finished A Jun 21 11:12:06 netbox openvpn[98932]: BIO write tls_write_plaintext 432 bytes Jun 21 11:12:06 netbox openvpn[98932]: Outgoing Plaintext -> TLS Jun 21 11:12:06 netbox openvpn[98932]: BIO read tls_read_ciphertext 461 bytes Jun 21 11:12:06 netbox openvpn[98932]: ACK mark active outgoing ID 3 Jun 21 11:12:06 netbox openvpn[98932]: Outgoing Ciphertext -> Reliable Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=1 current=1 : [4] 3 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send ID 3 (size=465 to=2) Jun 21 11:12:06 netbox openvpn[98932]: ACK write ID 5 (ack->len=1, n=1) Jun 21 11:12:06 netbox openvpn[98932]: Reliable -> TCP/UDP Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 2 [4] 3 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000004 rwflags=0x0002 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0002 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 WRITE [487] to [AF_INET]198.8.80.218:1197: P_CONTROL_V1 kid=0 sid=cb33d45e 1bfb5dae [ 5 sid=c2c928fd 0375e529 ] pid=3 DATA 17030301 c8e24234 a8479e50 3ffd861b fdb77985 f7533ade 4914d8aa f06cc01[more...] Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 write returned 487 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=1 current=0 : [4] 3 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 2 [4] 3 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 read returned 256 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 READ [256] from [AF_INET]198.8.80.218:1197: P_CONTROL_V1 kid=0 sid=c2c928fd 0375e529 [ 3 sid=cb33d45e 1bfb5dae ] pid=6 DATA 17030300 e1b20ea7 52f88fac 5cfbe369 9a89d263 c3f9d664 174aaab3 0e0fb52[more...] Jun 21 11:12:06 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, rec-sid=c2c928fd 0375e529, rec-ip=[AF_INET]198.8.80.218:1197, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: found match, session[0], sid=c2c928fd 0375e529 Jun 21 11:12:06 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=c2c928fd 0375e529 Jun 21 11:12:06 netbox openvpn[98932]: ACK received for pid 3, deleting from send buffer Jun 21 11:12:06 netbox openvpn[98932]: ACK read ID 6 (buf->len=230) Jun 21 11:12:06 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000006 id=00000006 ret=1 Jun 21 11:12:06 netbox openvpn[98932]: ACK mark active incoming ID 6 Jun 21 11:12:06 netbox openvpn[98932]: ACK acknowledge ID 6 (ack->len=1) Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_SENT_KEY, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_SENT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [4] Jun 21 11:12:06 netbox openvpn[98932]: BIO write tls_write_ciphertext 230 bytes Jun 21 11:12:06 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:06 netbox openvpn[98932]: BIO read tls_read_plaintext 201 bytes Jun 21 11:12:06 netbox openvpn[98932]: TLS -> Incoming Plaintext Jun 21 11:12:06 netbox openvpn[98932]: WARNING: 'link-mtu' is used inconsistently, local='link-mtu 1570', remote='link-mtu 1542' Jun 21 11:12:06 netbox openvpn[98932]: WARNING: 'cipher' is used inconsistently, local='cipher AES-256-CBC', remote='cipher BF-CBC' Jun 21 11:12:06 netbox openvpn[98932]: WARNING: 'auth' is used inconsistently, local='auth SHA256', remote='auth SHA1' Jun 21 11:12:06 netbox openvpn[98932]: WARNING: 'keysize' is used inconsistently, local='keysize 256', remote='keysize 128' Jun 21 11:12:06 netbox openvpn[98932]: STATE S_GOT_KEY Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_GOT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [4] Jun 21 11:12:06 netbox openvpn[98932]: ACK write ID 6 (ack->len=1, n=1) Jun 21 11:12:06 netbox openvpn[98932]: Dedicated ACK -> TCP/UDP Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [4] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [1/50141] Jun 21 11:12:06 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000004 rwflags=0x0002 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT status=0x0002 Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 WRITE [22] to [AF_INET]198.8.80.218:1197: P_ACK_V1 kid=0 sid=cb33d45e 1bfb5dae [ 6 sid=c2c928fd 0375e529 ] Jun 21 11:12:06 netbox openvpn[98932]: UDPv4 write returned 22 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_GOT_KEY, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_GOT_KEY lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: STATE S_ACTIVE Jun 21 11:12:06 netbox openvpn[98932]: Control Channel: TLSv1.2, cipher TLSv1/SSLv3 DHE-RSA-AES256-GCM-SHA384, 4096 bit RSA Jun 21 11:12:06 netbox openvpn[98932]: [cad10e306a226b20e547154b96cbb909] Peer Connection Initiated with [AF_INET]198.8.80.218:1197 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [4] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [4] Jun 21 11:12:06 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [4] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_process: timeout set to 60 Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:06 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:06 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [1/50141] Jun 21 11:12:07 netbox openvpn[98932]: event_wait returned 0 Jun 21 11:12:07 netbox openvpn[98932]: I/O WAIT status=0x0020 Jun 21 11:12:07 netbox openvpn[98932]: BIO write tls_write_plaintext_const 13 bytes Jun 21 11:12:07 netbox openvpn[98932]: SENT CONTROL [cad10e306a226b20e547154b96cbb909]: 'PUSH_REQUEST' (status=1) Jun 21 11:12:07 netbox openvpn[98932]: TIMER: coarse timer wakeup 0 seconds Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_ACTIVE, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:07 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [4] Jun 21 11:12:07 netbox openvpn[98932]: BIO read tls_read_ciphertext 42 bytes Jun 21 11:12:07 netbox openvpn[98932]: ACK mark active outgoing ID 4 Jun 21 11:12:07 netbox openvpn[98932]: Outgoing Ciphertext -> Reliable Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:07 netbox openvpn[98932]: ACK reliable_can_send active=1 current=1 : [5] 4 Jun 21 11:12:07 netbox openvpn[98932]: ACK reliable_send ID 4 (size=46 to=2) Jun 21 11:12:07 netbox openvpn[98932]: Reliable -> TCP/UDP Jun 21 11:12:07 netbox openvpn[98932]: ACK reliable_send_timeout 2 [5] 4 Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:07 netbox openvpn[98932]: PO_CTL rwflags=0x0003 ev=5 arg=0x006a38c0 Jun 21 11:12:07 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:07 netbox openvpn[98932]: I/O WAIT T?|T?|SR|SW [0/0] Jun 21 11:12:07 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000004 rwflags=0x0002 arg=0x006a38c0 Jun 21 11:12:07 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:07 netbox openvpn[98932]: I/O WAIT status=0x0002 Jun 21 11:12:07 netbox openvpn[98932]: UDPv4 WRITE [56] to [AF_INET]198.8.80.218:1197: P_CONTROL_V1 kid=0 sid=cb33d45e 1bfb5dae [ ] pid=4 DATA 17030300 25e24234 a8479e50 404742e8 4721ed4e f0133815 94237643 3ce16cc[more...] Jun 21 11:12:07 netbox openvpn[98932]: UDPv4 write returned 56 Jun 21 11:12:07 netbox openvpn[98932]: TIMER: coarse timer wakeup 5 seconds Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_ACTIVE, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:07 netbox openvpn[98932]: ACK reliable_can_send active=1 current=0 : [5] 4 Jun 21 11:12:07 netbox openvpn[98932]: ACK reliable_send_timeout 2 [5] 4 Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_process: timeout set to 2 Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:07 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:07 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:07 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [2/50141] Jun 21 11:12:07 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:07 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:07 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:07 netbox openvpn[98932]: UDPv4 read returned 22 Jun 21 11:12:07 netbox openvpn[98932]: UDPv4 READ [22] from [AF_INET]198.8.80.218:1197: P_ACK_V1 kid=0 sid=c2c928fd 0375e529 [ 4 sid=cb33d45e 1bfb5dae ] Jun 21 11:12:07 netbox openvpn[98932]: TLS: control channel, op=P_ACK_V1, IP=[AF_INET]198.8.80.218:1197 Jun 21 11:12:07 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_ACTIVE, mysid=cb33d45e 1bfb5dae, rec-sid=c2c928fd 0375e529, rec-ip=[AF_INET]198.8.80.218:1197, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:07 netbox openvpn[98932]: TLS: found match, session[0], sid=c2c928fd 0375e529 Jun 21 11:12:07 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=c2c928fd 0375e529 Jun 21 11:12:07 netbox openvpn[98932]: ACK received for pid 4, deleting from send buffer Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_ACTIVE, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:07 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [5] Jun 21 11:12:07 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [5] Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_process: timeout set to 59 Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:07 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=5 arg=0x006a38c0 Jun 21 11:12:07 netbox openvpn[98932]: PO_CTL rwflags=0x0001 ev=4 arg=0x006a26e8 Jun 21 11:12:07 netbox openvpn[98932]: I/O WAIT T?|T?|SR|Sw [5/50141] Jun 21 11:12:07 netbox openvpn[98932]: PO_WAIT[0,0] fd=5 rev=0x00000001 rwflags=0x0001 arg=0x006a38c0 Jun 21 11:12:07 netbox openvpn[98932]: event_wait returned 1 Jun 21 11:12:07 netbox openvpn[98932]: I/O WAIT status=0x0001 Jun 21 11:12:07 netbox openvpn[98932]: UDPv4 read returned 55 Jun 21 11:12:07 netbox openvpn[98932]: UDPv4 READ [55] from [AF_INET]198.8.80.218:1197: P_CONTROL_V1 kid=0 sid=c2c928fd 0375e529 [ ] pid=7 DATA 17030300 24b20ea7 52f88fac 5d87bb3f 3ec003eb 34ccd696 812a1c4e 0a6e7ba[more...] Jun 21 11:12:07 netbox openvpn[98932]: TLS: control channel, op=P_CONTROL_V1, IP=[AF_INET]198.8.80.218:1197 Jun 21 11:12:07 netbox openvpn[98932]: TLS: initial packet test, i=0 state=S_ACTIVE, mysid=cb33d45e 1bfb5dae, rec-sid=c2c928fd 0375e529, rec-ip=[AF_INET]198.8.80.218:1197, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:07 netbox openvpn[98932]: TLS: found match, session[0], sid=c2c928fd 0375e529 Jun 21 11:12:07 netbox openvpn[98932]: TLS: received control channel packet s#=0 sid=c2c928fd 0375e529 Jun 21 11:12:07 netbox openvpn[98932]: ACK read ID 7 (buf->len=41) Jun 21 11:12:07 netbox openvpn[98932]: ACK RWBS rel->size=8 rel->packet_id=00000007 id=00000007 ret=1 Jun 21 11:12:07 netbox openvpn[98932]: ACK mark active incoming ID 7 Jun 21 11:12:07 netbox openvpn[98932]: ACK acknowledge ID 7 (ack->len=1) Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_multi_process: i=0 state=S_ACTIVE, mysid=cb33d45e 1bfb5dae, stored-sid=c2c928fd 0375e529, stored-ip=[AF_INET]198.8.80.218:1197 Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_process: chg=0 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:07 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [5] Jun 21 11:12:07 netbox openvpn[98932]: BIO write tls_write_ciphertext 41 bytes Jun 21 11:12:07 netbox openvpn[98932]: Incoming Ciphertext -> TLS Jun 21 11:12:07 netbox openvpn[98932]: BIO read tls_read_plaintext 12 bytes Jun 21 11:12:07 netbox openvpn[98932]: TLS -> Incoming Plaintext Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_process: chg=1 ks=S_ACTIVE lame=S_UNDEF to_link->len=0 wakeup=604800 Jun 21 11:12:07 netbox openvpn[98932]: ACK reliable_can_send active=0 current=0 : [5] Jun 21 11:12:07 netbox openvpn[98932]: ACK write ID 7 (ack->len=1, n=1) Jun 21 11:12:07 netbox openvpn[98932]: Dedicated ACK -> TCP/UDP Jun 21 11:12:07 netbox openvpn[98932]: ACK reliable_send_timeout 604800 [5] Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_process: timeout set to 59 Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=32e160cb df968b27, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:07 netbox openvpn[98932]: TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[AF_UNSPEC] Jun 21 11:12:07 netbox openvpn[98932]: AUTH: Received control message: AUTH_FAILED Jun 21 11:12:07 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:07 netbox openvpn[98932]: SSL alert (write): warning: close notify Jun 21 11:12:07 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:07 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:07 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:07 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:07 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:07 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:07 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:07 netbox openvpn[98932]: TCP/UDP: Closing socket Jun 21 11:12:07 netbox openvpn[98932]: PID packet_id_free Jun 21 11:12:07 netbox openvpn[98932]: SIGTERM[soft,auth-failure] received, process exiting Jun 21 11:13:00 netbox openvpn[10065]: WARNING: file '/etc/openvpn-passwd.txt' is group or others accessible
-
So you run OpenVPN 2.4.3 ?
Where did that come from, automatic update?As stated, I have had a working OpenVPN config for many months now
This seems to be your problem:
WARNING: 'link-mtu' is used inconsistently, local='link-mtu 1570', remote='link-mtu 1542' Jun 21 11:12:00 netbox openvpn{98932}: WARNING: 'cipher' is used inconsistently, local='cipher AES-256-CBC', remote='cipher BF-CBC' Jun 21 11:12:00 netbox openvpn{98932}: WARNING: 'auth' is used inconsistently, local='auth SHA256', remote='auth SHA1' Jun 21 11:12:00 netbox openvpn{98932}: WARNING: 'keysize' is used inconsistently, local='keysize 256', remote='keysize 128'
-
pfSense 2.4.0 runs OpenVPN 2.4.x, I haven't paid attention to which .x version is released with latest BETA builds, but I haven't sideloaded anything if that's what you're asking.
The "used inconsistently" logs I think I've always gotten? The VPN server I connect to is an AES-256-CBC, SHA-256 server, as is the CA I use. Nothing changed there, on my side at least. Possibly the provider changed their config? But I doubt it because I verified their CA today and it's the same as always?
-
"WARNING: 'cipher' is used inconsistently, local='cipher AES-256-CBC', remote='cipher BF-CBC'"
I don't see how it would ever work if your not using the same ciphers..
-
Why would that have changed then?
It looks like the latest 2.4.0 BETA broke OpenVPN, but idk why it would change the cipher that the provider is using?
https://forum.pfsense.org/index.php?topic=132538.0
-
I googled it and I was right, I have always received these warnings and they are normal. This isn't the issue.
https://helpdesk.privateinternetaccess.com/hc/en-us/articles/225409027-Why-do-I-get-cipher-auth-warnings-when-I-connect-
I see the same warnings from my VPN server if a client tries to connect with a non-standard or incorrect cipher, but it always just negotiates a working cipher and continues on.
-
Today PIA (Private Internet Access) VPN stop working after years of trouble free operation. Log file show: "AUTH: Received control message: AUTH_FAILED". Does your log file show this also?
On the site of PIA I can login with credentials.
Warnings about cipher are there for a long time and seems unrelated.
Also I did not update pfSense today, so problem seems to be at PIA side. Maybe related to new OpenVPN vulnerability, https://forum.pfsense.org/index.php?topic=132534.0. -
I do have that message in my logs.
Are you on pfSense 2.4.0 BETA?
I can connect (connected as I type this) to a PIA tunnel via their windows app using the same server, encryption, SHA & RSA settings as my pfSense uses. So I'm not sure what trouble PIA would be having, it looks like their end is working just fine?
-
Yes, I am using 2.4 Beta.
I don't think their end is working fine because last week I did't changes anything to my configuration (Beta update, configuration change).
-
-
Tried VPN user/pass in auth file (how it was originally) & in GUI fields
-
Disabled IDS/IPS & cleared snort2c table to ensure it wasn't blocking anything
-
Scrubbed my zpool to ensure no corruption
-
-
Hmm, when I try to submit an ticket on the site of PIA, it shows:
High Volume Queues We are currently experiencing higher than average ticket queues due to recent Customer Support and network changes. Please be assured we will answer your ticket.
So it seems they changes something and working on it.
-
At the time I noticed the issue I was still on beta release that was more than a week old. May have been a June 13 release… I made no changes to the config or update before the connection went down.
-
You shouldn't have to neg a cipher - you should set your client to use the cipher they are using.. This is going to prevent a shitton of problems..
Its possible they changed their config so before they had multiple ciphers possible, and now they only have the BF.. Change your client to use BF and see if you can connect.
edit: I just upgraded mine to the latest build, remotely even. built on Wed Jun 21 01:52:48 CDT 2017
It rebooted and I am back on the vpn from work without any changes having to be done. Currently running 2.4.3 on work machine, and my phone can connect in just fine as well. And the vpn client I have from pfsense to my VPS is up and running as well, so clearly latest 2.4 build did not break openvpn ;)
I like the new blue login screen btw… And openpvn on this build is
Jun 21 14:18:01 openvpn 45809 OpenVPN 2.4.3 amd64-portbld-freebsd11.0 [SSL (OpenSSL)] [LZO] [LZ4] [MH/RECVDA] [AEAD] built on Jun 21 2017My previous build was
Jun 21 14:17:02 openvpn 46849 OpenVPN 2.4.2 amd64-portbld-freebsd11.0 [SSL (OpenSSL)] [LZO] [LZ4] [MH/RECVDA] [AEAD] built on May 12 2017Since you did not change anything - I would think they broke something on their end. I can connect to pfsense from multiple devices, and my pfsense can as a client connect to a server I run..
-
My client is configured in a known working configuration. It's setup using the correct certificate on the correct port for the cipher and authentication that I'm using.
PIA made a post (I linked it a few posts earlier) stating that all those warnings are saying is that I'm requesting to use a cipher, auth, etc. that is non-default, but it will still work and connect in the method requested by the client. They are warnings not errors.
I did try changing my end to match the warnings, it doesn't do anything except fail with the same error sans those warnings.
-
It looks like it was on PIA's side. My desktop connection cycled, then my pfSense connection came back up with 0% packet loss. It looks like they fixed whatever it was on their end!
-
It is working again.
PIA instructions here https://helpdesk.privateinternetaccess.com/hc/en-us/articles/218984968-What-is-the-difference-between-the-OpenVPN-config-files-on-your-website- state:
"connect over UDP port 1198 with AES-128-CBC+SHA1, using the server name to connect."
And instructions here https://helpdesk.privateinternetaccess.com/hc/en-us/articles/225274288-Which-encryption-auth-settings-should-I-use-for-ports-on-your-gateways-
confirm using UDP port 1198 with AES-128-CBC and SHA1
This was my working config on a 2.4.0 beta release a week old. I did not make a change in the router config nor did I perform an update; it simply stopped working. However, I have a Tomato router that is using the same credentials, .crt, and username/password and it connects.
Strange that it was functional on a Tomato router during this 4 hour period but not on pfSense.
-
I spoke too soon. Down again on pfSense but still working on Tomato.
-
Now working on UDP port 1197 with AES-256-CBC and SHA256
-
Strange that it was functional on a Tomato router during this 4 hour period but not on pfSense.
Yeah that's really weird, or maybe not weird I just don't know the technical details to explain the reason why. My desktop client also never had an issue connecting on the same settings to the same server that pfSense was trying to.
-
I spoke too soon. Down again on pfSense but still working on Tomato.
Mine is still up on pfSense.
I wonder what's going on.
-
Working for 8 hours without issues on UDP port 1197 with AES-256-CBC and SHA256.
Seems it was an issue on the PIA side.Update: No issues since