HTTP-Proxy connect failed after reconnect

This forum is for admins who are looking to build or expand their OpenVPN setup.
Forum rules
Please use the [oconf] BB tag for openvpn Configurations. See viewtopic.php?f=30&t=21589 for an example.
Post Reply
simu
OpenVpn Newbie
Posts: 7
Joined: Tue May 22, 2012 10:53 am

HTTP-Proxy connect failed after reconnect

Post by simu » Sat May 11, 2019 12:29 pm

Hi,
it seems since Version 2.4.x there is some change in the behaviour of http-proxy and cached username/password.
In my setup, openvpn-client is asking for http-proxy (Basic Auth) username/password to management-console. This works. But when there is a reconnect, it fails with:

Assertion failed at proxy.c:250 (strlen(p->up.username) > 0)

Log of my client
[olog]
Sat May 11 12:48:24 2019 us=695810 TCP connection established with [AF_INET] x.y.z.y:3128
Sat May 11 12:48:24 2019 us=695810 Send to HTTP proxy: 'CONNECT x.y.z.y:1194 HTTP/1.0'
Sat May 11 12:48:24 2019 us=696813 Send to HTTP proxy: 'Host: x.y.z.y'
Sat May 11 12:48:24 2019 us=703817 HTTP proxy returned: 'HTTP/1.0 407 Proxy Authentication Required'
Sat May 11 12:48:24 2019 us=705837 Proxy requires authentication
Sat May 11 12:48:24 2019 us=767861 PROXY AUTH BASIC: 'Proxy-Authenticate: Basic realm="proxy"'
Sat May 11 12:48:24 2019 us=781872 HTTP proxy authenticate 'realm="proxy"'
Sat May 11 12:48:24 2019 us=782872 Attempting to establish TCP connection with [AF_INET] x.y.z.y:3128 [nonblock]
Sat May 11 12:48:24 2019 us=782872 MANAGEMENT: >STATE:1557571704,TCP_CONNECT,,,,,,
Sat May 11 12:48:25 2019 us=796594 TCP connection established with [AF_INET] x.y.z.y:3128
Sat May 11 12:48:26 2019 us=806892 MANAGEMENT: CMD 'username "HTTP Proxy" admin'
Sat May 11 12:48:27 2019 us=320258 MANAGEMENT: CMD 'password [...]'
Sat May 11 12:48:27 2019 us=321258 Send to HTTP proxy: 'CONNECT x.y.z.y:1194 HTTP/1.0'
Sat May 11 12:48:27 2019 us=321258 Send to HTTP proxy: 'Host: x.y.z.y'
Sat May 11 12:48:27 2019 us=321258 Attempting Basic Proxy-Authorization
Sat May 11 12:48:27 2019 us=351280 HTTP proxy returned: 'HTTP/1.0 200 Connection established'
...
Sat May 11 12:48:42 2019 us=913241 Send to HTTP proxy: 'CONNECT x.y.z.y:1194 HTTP/1.0'
Sat May 11 12:48:42 2019 us=913241 Send to HTTP proxy: 'Host: x.y.z.y'
Sat May 11 12:48:42 2019 us=931255 HTTP proxy returned: 'HTTP/1.0 407 Proxy Authentication Required'
Sat May 11 12:48:42 2019 us=932255 Proxy requires authentication
Sat May 11 12:48:46 2019 us=47470 PROXY AUTH BASIC: 'Proxy-Authenticate: Basic realm="proxy"'
Sat May 11 12:48:47 2019 us=380605 HTTP proxy authenticate 'realm="proxy"'
Sat May 11 12:48:47 2019 us=381606 Attempting to establish TCP connection with [AF_INET] x.y.z.y:3128 [nonblock]
Sat May 11 12:48:47 2019 us=381606 MANAGEMENT: >STATE:1557571727,TCP_CONNECT,,,,,,
Sat May 11 12:48:48 2019 us=493396 TCP connection established with [AF_INET]x.y.z.y:3128
Sat May 11 12:48:48 2019 us=493396 Send to HTTP proxy: 'CONNECT x.y.z.y:1194 HTTP/1.0'
Sat May 11 12:48:48 2019 us=493396 Send to HTTP proxy: 'Host: x.y.z.y'
Sat May 11 12:48:48 2019 us=494397 MANAGEMENT: Client disconnected
Sat May 11 12:48:48 2019 us=494397 Assertion failed at proxy.c:250 (strlen(p->up.username) > 0)
Sat May 11 12:48:48 2019 us=494397 Exiting due to fatal error
[/olog]

It seems that the cached username/password is gone and openvpn is not asking again.
My setup is working fine on version 2.3.18, but not on any version of 2.4.x. I tried 2.4.3, 2.4.4, 2.4.5, 2.4.7.
Anyone had the same behaviour before?
BR
Simu

User avatar
TinCanTech
OpenVPN Protagonist
Posts: 5644
Joined: Fri Jun 03, 2016 1:17 pm

Re: HTTP-Proxy connect failed after reconnect

Post by TinCanTech » Sat May 11, 2019 1:47 pm

Thanks for opening ticket #1187

Can you please add your client config file and full log at --verb 4

simu
OpenVpn Newbie
Posts: 7
Joined: Tue May 22, 2012 10:53 am

Re: HTTP-Proxy connect failed after reconnect

Post by simu » Mon May 13, 2019 7:37 am

This logging was done with openvpn 2.4.5, but the same behaviour with every version at 2.4.x.

[olog]Mon May 13 09:29:10 2019 us=924629 Current Parameter Settings:
Mon May 13 09:29:10 2019 us=930632 config = 'C:\ProgramData\xyz\ovpn\config\config.ovpn'
Mon May 13 09:29:10 2019 us=931631 mode = 0
Mon May 13 09:29:10 2019 us=931631 show_ciphers = DISABLED
Mon May 13 09:29:10 2019 us=931631 show_digests = DISABLED
Mon May 13 09:29:10 2019 us=931631 show_engines = DISABLED
Mon May 13 09:29:10 2019 us=931631 genkey = DISABLED
Mon May 13 09:29:10 2019 us=931631 key_pass_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=931631 show_tls_ciphers = DISABLED
Mon May 13 09:29:10 2019 us=931631 connect_retry_max = 0
Mon May 13 09:29:10 2019 us=931631 Connection profiles [0]:
Mon May 13 09:29:10 2019 us=931631 proto = tcp-client
Mon May 13 09:29:10 2019 us=931631 local = '[UNDEF]'
Mon May 13 09:29:10 2019 us=931631 local_port = '[UNDEF]'
Mon May 13 09:29:10 2019 us=931631 remote = '1.2.3.4'
Mon May 13 09:29:10 2019 us=931631 remote_port = '1194'
Mon May 13 09:29:10 2019 us=931631 remote_float = DISABLED
Mon May 13 09:29:10 2019 us=931631 bind_defined = DISABLED
Mon May 13 09:29:10 2019 us=931631 bind_local = DISABLED
Mon May 13 09:29:10 2019 us=931631 bind_ipv6_only = DISABLED
Mon May 13 09:29:10 2019 us=931631 connect_retry_seconds = 5
Mon May 13 09:29:10 2019 us=931631 connect_timeout = 120
Mon May 13 09:29:10 2019 us=931631 BEGIN http_proxy
Mon May 13 09:29:10 2019 us=931631 server = '4.3.2.1'
Mon May 13 09:29:10 2019 us=931631 port = '3128'
Mon May 13 09:29:10 2019 us=931631 auth_method_string = '[UNDEF]'
Mon May 13 09:29:10 2019 us=931631 auth_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=932631 http_version = '1.0'
Mon May 13 09:29:10 2019 us=932631 user_agent = '[UNDEF]'
Mon May 13 09:29:10 2019 us=932631 END http_proxy
Mon May 13 09:29:10 2019 us=932631 socks_proxy_server = '[UNDEF]'
Mon May 13 09:29:10 2019 us=932631 socks_proxy_port = '[UNDEF]'
Mon May 13 09:29:10 2019 us=932631 tun_mtu = 1500
Mon May 13 09:29:10 2019 us=932631 tun_mtu_defined = ENABLED
Mon May 13 09:29:10 2019 us=932631 link_mtu = 1500
Mon May 13 09:29:10 2019 us=932631 link_mtu_defined = DISABLED
Mon May 13 09:29:10 2019 us=932631 tun_mtu_extra = 32
Mon May 13 09:29:10 2019 us=932631 tun_mtu_extra_defined = ENABLED
Mon May 13 09:29:10 2019 us=932631 mtu_discover_type = -1
Mon May 13 09:29:10 2019 us=932631 fragment = 0
Mon May 13 09:29:10 2019 us=932631 mssfix = 1450
Mon May 13 09:29:10 2019 us=932631 explicit_exit_notification = 0
Mon May 13 09:29:10 2019 us=932631 Connection profiles END
Mon May 13 09:29:10 2019 us=932631 remote_random = DISABLED
Mon May 13 09:29:10 2019 us=932631 ipchange = '[UNDEF]'
Mon May 13 09:29:10 2019 us=932631 dev = 'tap'
Mon May 13 09:29:10 2019 us=932631 dev_type = '[UNDEF]'
Mon May 13 09:29:10 2019 us=932631 dev_node = '[UNDEF]'
Mon May 13 09:29:10 2019 us=933632 lladdr = '[UNDEF]'
Mon May 13 09:29:10 2019 us=933632 topology = 1
Mon May 13 09:29:10 2019 us=933632 ifconfig_local = '[UNDEF]'
Mon May 13 09:29:10 2019 us=933632 ifconfig_remote_netmask = '[UNDEF]'
Mon May 13 09:29:10 2019 us=933632 ifconfig_noexec = DISABLED
Mon May 13 09:29:10 2019 us=933632 ifconfig_nowarn = DISABLED
Mon May 13 09:29:10 2019 us=933632 ifconfig_ipv6_local = '[UNDEF]'
Mon May 13 09:29:10 2019 us=933632 ifconfig_ipv6_netbits = 0
Mon May 13 09:29:10 2019 us=933632 ifconfig_ipv6_remote = '[UNDEF]'
Mon May 13 09:29:10 2019 us=933632 shaper = 0
Mon May 13 09:29:10 2019 us=933632 mtu_test = 0
Mon May 13 09:29:10 2019 us=933632 mlock = DISABLED
Mon May 13 09:29:10 2019 us=933632 keepalive_ping = 0
Mon May 13 09:29:10 2019 us=933632 keepalive_timeout = 0
Mon May 13 09:29:10 2019 us=933632 inactivity_timeout = 0
Mon May 13 09:29:10 2019 us=933632 ping_send_timeout = 0
Mon May 13 09:29:10 2019 us=934633 ping_rec_timeout = 0
Mon May 13 09:29:10 2019 us=934633 ping_rec_timeout_action = 0
Mon May 13 09:29:10 2019 us=934633 ping_timer_remote = DISABLED
Mon May 13 09:29:10 2019 us=934633 remap_sigusr1 = 0
Mon May 13 09:29:10 2019 us=934633 persist_tun = DISABLED
Mon May 13 09:29:10 2019 us=934633 persist_local_ip = DISABLED
Mon May 13 09:29:10 2019 us=934633 persist_remote_ip = DISABLED
Mon May 13 09:29:10 2019 us=934633 persist_key = DISABLED
Mon May 13 09:29:10 2019 us=934633 passtos = DISABLED
Mon May 13 09:29:10 2019 us=934633 resolve_retry_seconds = 1000000000
Mon May 13 09:29:10 2019 us=934633 resolve_in_advance = DISABLED
Mon May 13 09:29:10 2019 us=934633 username = '[UNDEF]'
Mon May 13 09:29:10 2019 us=934633 groupname = '[UNDEF]'
Mon May 13 09:29:10 2019 us=934633 chroot_dir = '[UNDEF]'
Mon May 13 09:29:10 2019 us=934633 cd_dir = '[UNDEF]'
Mon May 13 09:29:10 2019 us=934633 writepid = '[UNDEF]'
Mon May 13 09:29:10 2019 us=934633 up_script = '[UNDEF]'
Mon May 13 09:29:10 2019 us=934633 down_script = '[UNDEF]'
Mon May 13 09:29:10 2019 us=934633 down_pre = DISABLED
Mon May 13 09:29:10 2019 us=934633 up_restart = DISABLED
Mon May 13 09:29:10 2019 us=934633 up_delay = DISABLED
Mon May 13 09:29:10 2019 us=934633 daemon = DISABLED
Mon May 13 09:29:10 2019 us=935634 inetd = 0
Mon May 13 09:29:10 2019 us=935634 log = ENABLED
Mon May 13 09:29:10 2019 us=935634 suppress_timestamps = DISABLED
Mon May 13 09:29:10 2019 us=935634 machine_readable_output = DISABLED
Mon May 13 09:29:10 2019 us=935634 nice = 0
Mon May 13 09:29:10 2019 us=935634 verbosity = 4
Mon May 13 09:29:10 2019 us=935634 mute = 0
Mon May 13 09:29:10 2019 us=935634 gremlin = 0
Mon May 13 09:29:10 2019 us=935634 status_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=935634 status_file_version = 1
Mon May 13 09:29:10 2019 us=935634 status_file_update_freq = 60
Mon May 13 09:29:10 2019 us=935634 occ = ENABLED
Mon May 13 09:29:10 2019 us=935634 rcvbuf = 0
Mon May 13 09:29:10 2019 us=935634 sndbuf = 0
Mon May 13 09:29:10 2019 us=935634 sockflags = 0
Mon May 13 09:29:10 2019 us=935634 fast_io = DISABLED
Mon May 13 09:29:10 2019 us=935634 comp.alg = 2
Mon May 13 09:29:10 2019 us=935634 comp.flags = 1
Mon May 13 09:29:10 2019 us=935634 route_script = '[UNDEF]'
Mon May 13 09:29:10 2019 us=935634 route_default_gateway = '[UNDEF]'
Mon May 13 09:29:10 2019 us=935634 route_default_metric = 0
Mon May 13 09:29:10 2019 us=935634 route_noexec = DISABLED
Mon May 13 09:29:10 2019 us=935634 route_delay = 5
Mon May 13 09:29:10 2019 us=935634 route_delay_window = 300
Mon May 13 09:29:10 2019 us=935634 route_delay_defined = ENABLED
Mon May 13 09:29:10 2019 us=935634 route_nopull = DISABLED
Mon May 13 09:29:10 2019 us=936634 route_gateway_via_dhcp = DISABLED
Mon May 13 09:29:10 2019 us=936634 allow_pull_fqdn = DISABLED
Mon May 13 09:29:10 2019 us=936634 management_addr = '127.0.0.1'
Mon May 13 09:29:10 2019 us=936634 management_port = '3334'
Mon May 13 09:29:10 2019 us=936634 management_user_pass = '[UNDEF]'
Mon May 13 09:29:10 2019 us=936634 management_log_history_cache = 200
Mon May 13 09:29:10 2019 us=936634 management_echo_buffer_size = 100
Mon May 13 09:29:10 2019 us=936634 management_write_peer_info_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=936634 management_client_user = '[UNDEF]'
Mon May 13 09:29:10 2019 us=936634 management_client_group = '[UNDEF]'
Mon May 13 09:29:10 2019 us=936634 management_flags = 6
Mon May 13 09:29:10 2019 us=936634 shared_secret_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=937635 key_direction = not set
Mon May 13 09:29:10 2019 us=937635 ciphername = 'BF-CBC'
Mon May 13 09:29:10 2019 us=937635 ncp_enabled = ENABLED
Mon May 13 09:29:10 2019 us=937635 ncp_ciphers = 'AES-256-GCM:AES-128-GCM'
Mon May 13 09:29:10 2019 us=937635 authname = 'SHA1'
Mon May 13 09:29:10 2019 us=937635 prng_hash = 'SHA1'
Mon May 13 09:29:10 2019 us=937635 prng_nonce_secret_len = 16
Mon May 13 09:29:10 2019 us=937635 keysize = 0
Mon May 13 09:29:10 2019 us=937635 engine = DISABLED
Mon May 13 09:29:10 2019 us=937635 replay = ENABLED
Mon May 13 09:29:10 2019 us=937635 mute_replay_warnings = DISABLED
Mon May 13 09:29:10 2019 us=937635 replay_window = 64
Mon May 13 09:29:10 2019 us=937635 replay_time = 15
Mon May 13 09:29:10 2019 us=937635 packet_id_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=937635 use_iv = ENABLED
Mon May 13 09:29:10 2019 us=937635 test_crypto = DISABLED
Mon May 13 09:29:10 2019 us=937635 tls_server = DISABLED
Mon May 13 09:29:10 2019 us=937635 tls_client = ENABLED
Mon May 13 09:29:10 2019 us=937635 key_method = 2
Mon May 13 09:29:10 2019 us=937635 ca_file = '[[INLINE]]'
Mon May 13 09:29:10 2019 us=937635 ca_path = '[UNDEF]'
Mon May 13 09:29:10 2019 us=938636 dh_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=938636 cert_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=938636 extra_certs_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 priv_key_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 pkcs12_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 cryptoapi_cert = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 cipher_list = 'DEFAULT:@SECLEVEL=0'
Mon May 13 09:29:10 2019 us=939637 tls_cert_profile = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 tls_verify = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 tls_export_cert = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 verify_x509_type = 0
Mon May 13 09:29:10 2019 us=939637 verify_x509_name = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 crl_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 ns_cert_type = 1
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku[i] = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku[i] = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku[i] = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku[i] = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku[i] = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku[i] = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_eku = '[UNDEF]'
Mon May 13 09:29:10 2019 us=940638 ssl_flags = 0
Mon May 13 09:29:10 2019 us=940638 tls_timeout = 2
Mon May 13 09:29:10 2019 us=940638 renegotiate_bytes = -1
Mon May 13 09:29:10 2019 us=940638 renegotiate_packets = 0
Mon May 13 09:29:10 2019 us=941638 renegotiate_seconds = 3600
Mon May 13 09:29:10 2019 us=941638 handshake_window = 60
Mon May 13 09:29:10 2019 us=941638 transition_window = 3600
Mon May 13 09:29:10 2019 us=941638 single_session = DISABLED
Mon May 13 09:29:10 2019 us=941638 push_peer_info = DISABLED
Mon May 13 09:29:10 2019 us=941638 tls_exit = DISABLED
Mon May 13 09:29:10 2019 us=941638 tls_auth_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=941638 tls_crypt_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=942641 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=942641 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=942641 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=942641 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=943640 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=943640 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=943640 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=943640 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=943640 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=944641 pkcs11_pin_cache_period = -1
Mon May 13 09:29:10 2019 us=944641 pkcs11_id = '[UNDEF]'
Mon May 13 09:29:10 2019 us=944641 pkcs11_id_management = DISABLED
Mon May 13 09:29:10 2019 us=944641 server_network = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 server_netmask = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 server_network_ipv6 = ::
Mon May 13 09:29:10 2019 us=944641 server_netbits_ipv6 = 0
Mon May 13 09:29:10 2019 us=944641 server_bridge_ip = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 server_bridge_netmask = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 server_bridge_pool_start = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 server_bridge_pool_end = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 ifconfig_pool_defined = DISABLED
Mon May 13 09:29:10 2019 us=944641 ifconfig_pool_start = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 ifconfig_pool_end = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 ifconfig_pool_netmask = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 ifconfig_pool_persist_filename = '[UNDEF]'
Mon May 13 09:29:10 2019 us=945641 ifconfig_pool_persist_refresh_freq = 600
Mon May 13 09:29:10 2019 us=945641 ifconfig_ipv6_pool_defined = DISABLED
Mon May 13 09:29:10 2019 us=945641 ifconfig_ipv6_pool_base = ::
Mon May 13 09:29:10 2019 us=945641 ifconfig_ipv6_pool_netbits = 0
Mon May 13 09:29:10 2019 us=945641 n_bcast_buf = 256
Mon May 13 09:29:10 2019 us=945641 tcp_queue_limit = 64
Mon May 13 09:29:10 2019 us=945641 real_hash_size = 256
Mon May 13 09:29:10 2019 us=945641 virtual_hash_size = 256
Mon May 13 09:29:10 2019 us=945641 client_connect_script = '[UNDEF]'
Mon May 13 09:29:10 2019 us=945641 learn_address_script = '[UNDEF]'
Mon May 13 09:29:10 2019 us=945641 client_disconnect_script = '[UNDEF]'
Mon May 13 09:29:10 2019 us=945641 client_config_dir = '[UNDEF]'
Mon May 13 09:29:10 2019 us=945641 ccd_exclusive = DISABLED
Mon May 13 09:29:10 2019 us=945641 tmp_dir = 'C:\Windows\TEMP\'
Mon May 13 09:29:10 2019 us=945641 push_ifconfig_defined = DISABLED
Mon May 13 09:29:10 2019 us=945641 push_ifconfig_local = 0.0.0.0
Mon May 13 09:29:10 2019 us=945641 push_ifconfig_remote_netmask = 0.0.0.0
Mon May 13 09:29:10 2019 us=945641 push_ifconfig_ipv6_defined = DISABLED
Mon May 13 09:29:10 2019 us=945641 push_ifconfig_ipv6_local = ::/0
Mon May 13 09:29:10 2019 us=945641 push_ifconfig_ipv6_remote = ::
Mon May 13 09:29:10 2019 us=945641 enable_c2c = DISABLED
Mon May 13 09:29:10 2019 us=945641 duplicate_cn = DISABLED
Mon May 13 09:29:10 2019 us=945641 cf_max = 0
Mon May 13 09:29:10 2019 us=945641 cf_per = 0
Mon May 13 09:29:10 2019 us=945641 max_clients = 1024
Mon May 13 09:29:10 2019 us=945641 max_routes_per_client = 256
Mon May 13 09:29:10 2019 us=945641 auth_user_pass_verify_script = '[UNDEF]'
Mon May 13 09:29:10 2019 us=946643 auth_user_pass_verify_script_via_file = DISABLED
Mon May 13 09:29:10 2019 us=946643 auth_token_generate = DISABLED
Mon May 13 09:29:10 2019 us=946643 auth_token_lifetime = 0
Mon May 13 09:29:10 2019 us=946643 client = ENABLED
Mon May 13 09:29:10 2019 us=946643 pull = ENABLED
Mon May 13 09:29:10 2019 us=946643 auth_user_pass_file = 'stdin'
Mon May 13 09:29:10 2019 us=946643 show_net_up = DISABLED
Mon May 13 09:29:10 2019 us=946643 route_method = 0
Mon May 13 09:29:10 2019 us=946643 block_outside_dns = DISABLED
Mon May 13 09:29:10 2019 us=946643 ip_win32_defined = ENABLED
Mon May 13 09:29:10 2019 us=946643 ip_win32_type = 4
Mon May 13 09:29:10 2019 us=946643 dhcp_masq_offset = 0
Mon May 13 09:29:10 2019 us=946643 dhcp_lease_time = 31536000
Mon May 13 09:29:10 2019 us=946643 tap_sleep = 0
Mon May 13 09:29:10 2019 us=946643 dhcp_options = DISABLED
Mon May 13 09:29:10 2019 us=946643 dhcp_renew = DISABLED
Mon May 13 09:29:10 2019 us=946643 dhcp_pre_release = DISABLED
Mon May 13 09:29:10 2019 us=946643 domain = '[UNDEF]'
Mon May 13 09:29:10 2019 us=946643 netbios_scope = '[UNDEF]'
Mon May 13 09:29:10 2019 us=946643 netbios_node_type = 0
Mon May 13 09:29:10 2019 us=946643 disable_nbt = DISABLED
Mon May 13 09:29:10 2019 us=946643 OpenVPN 2.4.5 i686-w64-mingw32 [SSL (OpenSSL)] [LZO] [LZ4] [PKCS11] [AEAD] built on Mar 1 2018
Mon May 13 09:29:10 2019 us=947643 Windows version 6.2 (Windows 8 or greater) 64bit
Mon May 13 09:29:10 2019 us=947643 library versions: OpenSSL 1.1.0f 25 May 2017, LZO 2.10
Mon May 13 09:29:10 2019 us=952646 MANAGEMENT: TCP Socket listening on [AF_INET]127.0.0.1:3334
Mon May 13 09:29:10 2019 us=952646 Need hold release from management interface, waiting...
Mon May 13 09:29:11 2019 us=284883 MANAGEMENT: Client connected from [AF_INET]127.0.0.1:3334
Mon May 13 09:29:11 2019 us=304896 MANAGEMENT: CMD 'state on'
Mon May 13 09:29:12 2019 us=320141 MANAGEMENT: CMD 'state'
Mon May 13 09:29:13 2019 us=339648 MANAGEMENT: CMD 'hold off'
Mon May 13 09:29:13 2019 us=352656 MANAGEMENT: CMD 'hold release'
Mon May 13 09:29:14 2019 us=363373 MANAGEMENT: CMD 'username Auth admin@foo'
Mon May 13 09:29:14 2019 us=894757 MANAGEMENT: CMD 'password [...]'
Mon May 13 09:29:14 2019 us=896753 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Mon May 13 09:29:14 2019 us=902756 No valid translation found for TLS cipher '@SECLEVEL=0'
Mon May 13 09:29:14 2019 us=906766 LZO compression initializing
Mon May 13 09:29:14 2019 us=906766 Control Channel MTU parms [ L:1656 D:1210 EF:40 EB:0 ET:0 EL:3 ]
Mon May 13 09:29:14 2019 us=907769 Data Channel MTU parms [ L:1656 D:1450 EF:124 EB:412 ET:32 EL:3 ]
Mon May 13 09:29:14 2019 us=907769 Local Options String (VER=V4): 'V4,dev-type tap,link-mtu 1576,tun-mtu 1532,proto TCPv4_CLIENT,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
Mon May 13 09:29:14 2019 us=907769 Expected Remote Options String (VER=V4): 'V4,dev-type tap,link-mtu 1576,tun-mtu 1532,proto TCPv4_SERVER,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
Mon May 13 09:29:14 2019 us=907769 TCP/UDP: Preserving recently used remote address: [AF_INET]4.3.2.1:3128
Mon May 13 09:29:14 2019 us=907769 Socket Buffers: R=[65536->65536] S=[49152->49152]
Mon May 13 09:29:14 2019 us=907769 Attempting to establish TCP connection with [AF_INET]4.3.2.1:3128 [nonblock]
Mon May 13 09:29:14 2019 us=907769 MANAGEMENT: >STATE:1557732554,TCP_CONNECT,,,,,,
Mon May 13 09:29:15 2019 us=910473 TCP connection established with [AF_INET]4.3.2.1:3128
Mon May 13 09:29:15 2019 us=910473 Send to HTTP proxy: 'CONNECT 1.2.3.4:1194 HTTP/1.0'
Mon May 13 09:29:15 2019 us=910473 Send to HTTP proxy: 'Host: 1.2.3.4'
Mon May 13 09:29:15 2019 us=926484 HTTP proxy returned: 'HTTP/1.0 407 Proxy Authentication Required'
Mon May 13 09:29:15 2019 us=927486 Proxy requires authentication
Mon May 13 09:29:15 2019 us=965513 PROXY AUTH BASIC: 'Proxy-Authenticate: Basic realm="proxy"'
Mon May 13 09:29:15 2019 us=982529 HTTP proxy authenticate 'realm="proxy"'
Mon May 13 09:29:15 2019 us=983527 Attempting to establish TCP connection with [AF_INET]4.3.2.1:3128 [nonblock]
Mon May 13 09:29:15 2019 us=983527 MANAGEMENT: >STATE:1557732555,TCP_CONNECT,,,,,,
Mon May 13 09:29:16 2019 us=987238 TCP connection established with [AF_INET]4.3.2.1:3128
Mon May 13 09:29:18 2019 us=8536 MANAGEMENT: CMD 'username "HTTP Proxy" admin'
Mon May 13 09:29:18 2019 us=525909 MANAGEMENT: CMD 'password [...]'
Mon May 13 09:29:18 2019 us=532910 Send to HTTP proxy: 'CONNECT 1.2.3.4:1194 HTTP/1.0'
Mon May 13 09:29:18 2019 us=532910 Send to HTTP proxy: 'Host: 1.2.3.4'
Mon May 13 09:29:18 2019 us=532910 Attempting Basic Proxy-Authorization
Mon May 13 09:29:18 2019 us=569935 HTTP proxy returned: 'HTTP/1.0 200 Connection established'
Mon May 13 09:29:20 2019 us=587368 TCP_CLIENT link local: (not bound)
Mon May 13 09:29:20 2019 us=587368 TCP_CLIENT link remote: [AF_INET]4.3.2.1:3128
Mon May 13 09:29:20 2019 us=587368 MANAGEMENT: >STATE:1557732560,WAIT,,,,,,
Mon May 13 09:29:20 2019 us=615389 MANAGEMENT: >STATE:1557732560,AUTH,,,,,,
Mon May 13 09:29:20 2019 us=615389 TLS: Initial packet from [AF_INET]4.3.2.1:3128, sid=f4e44dd7 6d53c847
Mon May 13 09:29:20 2019 us=616389 WARNING: this configuration may cache passwords in memory -- use the auth-nocache option to prevent this
Mon May 13 09:29:20 2019 us=706453 VERIFY OK: depth=1, C=DE, ST=YOH, L=Haha, O=YEAH OU=CA, CN=RootCA, emailAddress=support.ca@gmail.com
Mon May 13 09:29:20 2019 us=707454 VERIFY OK: nsCertType=SERVER
Mon May 13 09:29:20 2019 us=707454 VERIFY OK: depth=0, C=DE, ST=YOH, L=Haha, O=YEAH, OU=CA, CN=Server, emailAddress=support.ca@gmail.com
Mon May 13 09:29:21 2019 us=153770 Control Channel: TLSv1.2, cipher TLSv1.2 ECDHE-RSA-AES256-GCM-SHA384, 1024 bit RSA
Mon May 13 09:29:21 2019 us=154773 [Server] Peer Connection Initiated with [AF_INET]4.3.2.1:3128
Mon May 13 09:29:22 2019 us=384728 MANAGEMENT: >STATE:1557732562,GET_CONFIG,,,,,,
Mon May 13 09:29:22 2019 us=385723 SENT CONTROL [Server]: 'PUSH_REQUEST' (status=1)
Mon May 13 09:29:22 2019 us=461777 PUSH: Received control message: 'PUSH_REPLY,ifconfig 10.0.0.95 255.255.255.0,peer-id 0,cipher AES-256-GCM'
Mon May 13 09:29:22 2019 us=462778 OPTIONS IMPORT: --ifconfig/up options modified
Mon May 13 09:29:22 2019 us=462778 OPTIONS IMPORT: peer-id set
Mon May 13 09:29:22 2019 us=462778 OPTIONS IMPORT: adjusting link_mtu to 1659
Mon May 13 09:29:22 2019 us=462778 OPTIONS IMPORT: data channel crypto options modified
Mon May 13 09:29:22 2019 us=462778 Data Channel: using negotiated cipher 'AES-256-GCM'
Mon May 13 09:29:22 2019 us=462778 Data Channel MTU parms [ L:1587 D:1450 EF:55 EB:412 ET:32 EL:3 ]
Mon May 13 09:29:22 2019 us=462778 Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Mon May 13 09:29:22 2019 us=462778 Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Mon May 13 09:29:22 2019 us=463778 interactive service msg_channel=0
Mon May 13 09:29:22 2019 us=463778 open_tun
Mon May 13 09:29:22 2019 us=477791 TAP-WIN32 device [mbDIALUP] opened: \\.\Global\{8BAF274D-316A-4090-A5E2-BC11175B31E7}.tap
Mon May 13 09:29:22 2019 us=479794 TAP-Windows Driver Version 9.21
Mon May 13 09:29:22 2019 us=479794 TAP-Windows MTU=1500
Mon May 13 09:29:22 2019 us=516817 Notified TAP-Windows driver to set a DHCP IP/netmask of 10.0.0.95/255.255.255.0 on interface {8BAF274D-316A-4090-A5E2-BC11175B31E7} [DHCP-serv: 10.0.0.0, lease-time: 31536000]
Mon May 13 09:29:22 2019 us=517817 Successful ARP Flush on interface [67] {8BAF274D-316A-4090-A5E2-BC11175B31E7}
Mon May 13 09:29:22 2019 us=517817 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Mon May 13 09:29:22 2019 us=517817 MANAGEMENT: >STATE:1557732562,ASSIGN_IP,,10.0.0.95,,,,
Mon May 13 09:29:22 2019 us=766993 Connection reset, restarting [0]
Mon May 13 09:29:22 2019 us=768996 TCP/UDP: Closing socket
Mon May 13 09:29:22 2019 us=768996 Closing TUN/TAP interface
Mon May 13 09:29:22 2019 us=770996 SIGUSR1[soft,connection-reset] received, process restarting
Mon May 13 09:29:22 2019 us=770996 MANAGEMENT: >STATE:1557732562,RECONNECTING,connection-reset,,,,,
Mon May 13 09:29:22 2019 us=770996 Restart pause, 5 second(s)
Mon May 13 09:29:28 2019 us=89850 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Mon May 13 09:29:28 2019 us=95848 No valid translation found for TLS cipher '@SECLEVEL=0'
Mon May 13 09:29:28 2019 us=96848 LZO compression initializing
Mon May 13 09:29:28 2019 us=96848 Control Channel MTU parms [ L:1656 D:1210 EF:40 EB:0 ET:0 EL:3 ]
Mon May 13 09:29:28 2019 us=97849 Data Channel MTU parms [ L:1656 D:1450 EF:124 EB:412 ET:32 EL:3 ]
Mon May 13 09:29:28 2019 us=97849 Local Options String (VER=V4): 'V4,dev-type tap,link-mtu 1584,tun-mtu 1532,proto TCPv4_CLIENT,comp-lzo,cipher AES-256-GCM,auth [null-digest],keysize 256,key-method 2,tls-client'
Mon May 13 09:29:28 2019 us=97849 Expected Remote Options String (VER=V4): 'V4,dev-type tap,link-mtu 1584,tun-mtu 1532,proto TCPv4_SERVER,comp-lzo,cipher AES-256-GCM,auth [null-digest],keysize 256,key-method 2,tls-server'
Mon May 13 09:29:28 2019 us=97849 TCP/UDP: Preserving recently used remote address: [AF_INET]4.3.2.1:3128
Mon May 13 09:29:28 2019 us=98850 Socket Buffers: R=[65536->65536] S=[49152->49152]
Mon May 13 09:29:28 2019 us=98850 Attempting to establish TCP connection with [AF_INET]4.3.2.1:3128 [nonblock]
Mon May 13 09:29:28 2019 us=98850 MANAGEMENT: >STATE:1557732568,TCP_CONNECT,,,,,,
Mon May 13 09:29:29 2019 us=159602 TCP connection established with [AF_INET]4.3.2.1:3128
Mon May 13 09:29:29 2019 us=159602 Send to HTTP proxy: 'CONNECT 1.2.3.4:1194 HTTP/1.0'
Mon May 13 09:29:29 2019 us=159602 Send to HTTP proxy: 'Host: 1.2.3.4'
Mon May 13 09:29:29 2019 us=178616 HTTP proxy returned: 'HTTP/1.0 407 Proxy Authentication Required'
Mon May 13 09:29:29 2019 us=178616 Proxy requires authentication
Mon May 13 09:29:29 2019 us=246666 PROXY AUTH BASIC: 'Proxy-Authenticate: Basic realm="proxy"'
Mon May 13 09:29:29 2019 us=281691 HTTP proxy authenticate 'realm="proxy"'
Mon May 13 09:29:29 2019 us=282690 Attempting to establish TCP connection with [AF_INET]4.3.2.1:3128 [nonblock]
Mon May 13 09:29:29 2019 us=282690 MANAGEMENT: >STATE:1557732569,TCP_CONNECT,,,,,,
Mon May 13 09:29:30 2019 us=295408 TCP connection established with [AF_INET]4.3.2.1:3128
Mon May 13 09:29:30 2019 us=295408 Send to HTTP proxy: 'CONNECT 1.2.3.4:1194 HTTP/1.0'
Mon May 13 09:29:30 2019 us=295408 Send to HTTP proxy: 'Host: 1.2.3.4'
Mon May 13 09:29:30 2019 us=296409 MANAGEMENT: Client disconnected
Mon May 13 09:29:30 2019 us=296409 Assertion failed at proxy.c:250 (strlen(p->up.username) > 0)
Mon May 13 09:29:30 2019 us=296409 Exiting due to fatal error[/olog]

simu
OpenVpn Newbie
Posts: 7
Joined: Tue May 22, 2012 10:53 am

Re: HTTP-Proxy connect failed after reconnect

Post by simu » Mon May 13, 2019 11:07 am

sorry, forgot the config:
[oconf=]
<ca>
...
</ca>
ns-cert-type server
client
tls-client
tls-cipher "DEFAULT:@SECLEVEL=0"
auth-user-pass

dev tap
comp-lzo
cipher BF-CBC
tun-mtu 1500
pull
auth-retry interact
verb 4

management-hold
management-query-passwords
management 127.0.0.1 3334
management-log-cache 200
[/oconf]

simu
OpenVpn Newbie
Posts: 7
Joined: Tue May 22, 2012 10:53 am

Re: HTTP-Proxy connect failed after reconnect

Post by simu » Mon May 13, 2019 11:09 am

this is the log:
[olog]
Mon May 13 09:29:10 2019 us=924629 Current Parameter Settings:
Mon May 13 09:29:10 2019 us=930632 config = 'C:\ProgramData\xyz\ovpn\config\config.ovpn'
Mon May 13 09:29:10 2019 us=931631 mode = 0
Mon May 13 09:29:10 2019 us=931631 show_ciphers = DISABLED
Mon May 13 09:29:10 2019 us=931631 show_digests = DISABLED
Mon May 13 09:29:10 2019 us=931631 show_engines = DISABLED
Mon May 13 09:29:10 2019 us=931631 genkey = DISABLED
Mon May 13 09:29:10 2019 us=931631 key_pass_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=931631 show_tls_ciphers = DISABLED
Mon May 13 09:29:10 2019 us=931631 connect_retry_max = 0
Mon May 13 09:29:10 2019 us=931631 Connection profiles [0]:
Mon May 13 09:29:10 2019 us=931631 proto = tcp-client
Mon May 13 09:29:10 2019 us=931631 local = '[UNDEF]'
Mon May 13 09:29:10 2019 us=931631 local_port = '[UNDEF]'
Mon May 13 09:29:10 2019 us=931631 remote = '1.2.3.4'
Mon May 13 09:29:10 2019 us=931631 remote_port = '1194'
Mon May 13 09:29:10 2019 us=931631 remote_float = DISABLED
Mon May 13 09:29:10 2019 us=931631 bind_defined = DISABLED
Mon May 13 09:29:10 2019 us=931631 bind_local = DISABLED
Mon May 13 09:29:10 2019 us=931631 bind_ipv6_only = DISABLED
Mon May 13 09:29:10 2019 us=931631 connect_retry_seconds = 5
Mon May 13 09:29:10 2019 us=931631 connect_timeout = 120
Mon May 13 09:29:10 2019 us=931631 BEGIN http_proxy
Mon May 13 09:29:10 2019 us=931631 server = '4.3.2.1'
Mon May 13 09:29:10 2019 us=931631 port = '3128'
Mon May 13 09:29:10 2019 us=931631 auth_method_string = '[UNDEF]'
Mon May 13 09:29:10 2019 us=931631 auth_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=932631 http_version = '1.0'
Mon May 13 09:29:10 2019 us=932631 user_agent = '[UNDEF]'
Mon May 13 09:29:10 2019 us=932631 END http_proxy
Mon May 13 09:29:10 2019 us=932631 socks_proxy_server = '[UNDEF]'
Mon May 13 09:29:10 2019 us=932631 socks_proxy_port = '[UNDEF]'
Mon May 13 09:29:10 2019 us=932631 tun_mtu = 1500
Mon May 13 09:29:10 2019 us=932631 tun_mtu_defined = ENABLED
Mon May 13 09:29:10 2019 us=932631 link_mtu = 1500
Mon May 13 09:29:10 2019 us=932631 link_mtu_defined = DISABLED
Mon May 13 09:29:10 2019 us=932631 tun_mtu_extra = 32
Mon May 13 09:29:10 2019 us=932631 tun_mtu_extra_defined = ENABLED
Mon May 13 09:29:10 2019 us=932631 mtu_discover_type = -1
Mon May 13 09:29:10 2019 us=932631 fragment = 0
Mon May 13 09:29:10 2019 us=932631 mssfix = 1450
Mon May 13 09:29:10 2019 us=932631 explicit_exit_notification = 0
Mon May 13 09:29:10 2019 us=932631 Connection profiles END
Mon May 13 09:29:10 2019 us=932631 remote_random = DISABLED
Mon May 13 09:29:10 2019 us=932631 ipchange = '[UNDEF]'
Mon May 13 09:29:10 2019 us=932631 dev = 'tap'
Mon May 13 09:29:10 2019 us=932631 dev_type = '[UNDEF]'
Mon May 13 09:29:10 2019 us=932631 dev_node = '[UNDEF]'
Mon May 13 09:29:10 2019 us=933632 lladdr = '[UNDEF]'
Mon May 13 09:29:10 2019 us=933632 topology = 1
Mon May 13 09:29:10 2019 us=933632 ifconfig_local = '[UNDEF]'
Mon May 13 09:29:10 2019 us=933632 ifconfig_remote_netmask = '[UNDEF]'
Mon May 13 09:29:10 2019 us=933632 ifconfig_noexec = DISABLED
Mon May 13 09:29:10 2019 us=933632 ifconfig_nowarn = DISABLED
Mon May 13 09:29:10 2019 us=933632 ifconfig_ipv6_local = '[UNDEF]'
Mon May 13 09:29:10 2019 us=933632 ifconfig_ipv6_netbits = 0
Mon May 13 09:29:10 2019 us=933632 ifconfig_ipv6_remote = '[UNDEF]'
Mon May 13 09:29:10 2019 us=933632 shaper = 0
Mon May 13 09:29:10 2019 us=933632 mtu_test = 0
Mon May 13 09:29:10 2019 us=933632 mlock = DISABLED
Mon May 13 09:29:10 2019 us=933632 keepalive_ping = 0
Mon May 13 09:29:10 2019 us=933632 keepalive_timeout = 0
Mon May 13 09:29:10 2019 us=933632 inactivity_timeout = 0
Mon May 13 09:29:10 2019 us=933632 ping_send_timeout = 0
Mon May 13 09:29:10 2019 us=934633 ping_rec_timeout = 0
Mon May 13 09:29:10 2019 us=934633 ping_rec_timeout_action = 0
Mon May 13 09:29:10 2019 us=934633 ping_timer_remote = DISABLED
Mon May 13 09:29:10 2019 us=934633 remap_sigusr1 = 0
Mon May 13 09:29:10 2019 us=934633 persist_tun = DISABLED
Mon May 13 09:29:10 2019 us=934633 persist_local_ip = DISABLED
Mon May 13 09:29:10 2019 us=934633 persist_remote_ip = DISABLED
Mon May 13 09:29:10 2019 us=934633 persist_key = DISABLED
Mon May 13 09:29:10 2019 us=934633 passtos = DISABLED
Mon May 13 09:29:10 2019 us=934633 resolve_retry_seconds = 1000000000
Mon May 13 09:29:10 2019 us=934633 resolve_in_advance = DISABLED
Mon May 13 09:29:10 2019 us=934633 username = '[UNDEF]'
Mon May 13 09:29:10 2019 us=934633 groupname = '[UNDEF]'
Mon May 13 09:29:10 2019 us=934633 chroot_dir = '[UNDEF]'
Mon May 13 09:29:10 2019 us=934633 cd_dir = '[UNDEF]'
Mon May 13 09:29:10 2019 us=934633 writepid = '[UNDEF]'
Mon May 13 09:29:10 2019 us=934633 up_script = '[UNDEF]'
Mon May 13 09:29:10 2019 us=934633 down_script = '[UNDEF]'
Mon May 13 09:29:10 2019 us=934633 down_pre = DISABLED
Mon May 13 09:29:10 2019 us=934633 up_restart = DISABLED
Mon May 13 09:29:10 2019 us=934633 up_delay = DISABLED
Mon May 13 09:29:10 2019 us=934633 daemon = DISABLED
Mon May 13 09:29:10 2019 us=935634 inetd = 0
Mon May 13 09:29:10 2019 us=935634 log = ENABLED
Mon May 13 09:29:10 2019 us=935634 suppress_timestamps = DISABLED
Mon May 13 09:29:10 2019 us=935634 machine_readable_output = DISABLED
Mon May 13 09:29:10 2019 us=935634 nice = 0
Mon May 13 09:29:10 2019 us=935634 verbosity = 4
Mon May 13 09:29:10 2019 us=935634 mute = 0
Mon May 13 09:29:10 2019 us=935634 gremlin = 0
Mon May 13 09:29:10 2019 us=935634 status_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=935634 status_file_version = 1
Mon May 13 09:29:10 2019 us=935634 status_file_update_freq = 60
Mon May 13 09:29:10 2019 us=935634 occ = ENABLED
Mon May 13 09:29:10 2019 us=935634 rcvbuf = 0
Mon May 13 09:29:10 2019 us=935634 sndbuf = 0
Mon May 13 09:29:10 2019 us=935634 sockflags = 0
Mon May 13 09:29:10 2019 us=935634 fast_io = DISABLED
Mon May 13 09:29:10 2019 us=935634 comp.alg = 2
Mon May 13 09:29:10 2019 us=935634 comp.flags = 1
Mon May 13 09:29:10 2019 us=935634 route_script = '[UNDEF]'
Mon May 13 09:29:10 2019 us=935634 route_default_gateway = '[UNDEF]'
Mon May 13 09:29:10 2019 us=935634 route_default_metric = 0
Mon May 13 09:29:10 2019 us=935634 route_noexec = DISABLED
Mon May 13 09:29:10 2019 us=935634 route_delay = 5
Mon May 13 09:29:10 2019 us=935634 route_delay_window = 300
Mon May 13 09:29:10 2019 us=935634 route_delay_defined = ENABLED
Mon May 13 09:29:10 2019 us=935634 route_nopull = DISABLED
Mon May 13 09:29:10 2019 us=936634 route_gateway_via_dhcp = DISABLED
Mon May 13 09:29:10 2019 us=936634 allow_pull_fqdn = DISABLED
Mon May 13 09:29:10 2019 us=936634 management_addr = '127.0.0.1'
Mon May 13 09:29:10 2019 us=936634 management_port = '3334'
Mon May 13 09:29:10 2019 us=936634 management_user_pass = '[UNDEF]'
Mon May 13 09:29:10 2019 us=936634 management_log_history_cache = 200
Mon May 13 09:29:10 2019 us=936634 management_echo_buffer_size = 100
Mon May 13 09:29:10 2019 us=936634 management_write_peer_info_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=936634 management_client_user = '[UNDEF]'
Mon May 13 09:29:10 2019 us=936634 management_client_group = '[UNDEF]'
Mon May 13 09:29:10 2019 us=936634 management_flags = 6
Mon May 13 09:29:10 2019 us=936634 shared_secret_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=937635 key_direction = not set
Mon May 13 09:29:10 2019 us=937635 ciphername = 'BF-CBC'
Mon May 13 09:29:10 2019 us=937635 ncp_enabled = ENABLED
Mon May 13 09:29:10 2019 us=937635 ncp_ciphers = 'AES-256-GCM:AES-128-GCM'
Mon May 13 09:29:10 2019 us=937635 authname = 'SHA1'
Mon May 13 09:29:10 2019 us=937635 prng_hash = 'SHA1'
Mon May 13 09:29:10 2019 us=937635 prng_nonce_secret_len = 16
Mon May 13 09:29:10 2019 us=937635 keysize = 0
Mon May 13 09:29:10 2019 us=937635 engine = DISABLED
Mon May 13 09:29:10 2019 us=937635 replay = ENABLED
Mon May 13 09:29:10 2019 us=937635 mute_replay_warnings = DISABLED
Mon May 13 09:29:10 2019 us=937635 replay_window = 64
Mon May 13 09:29:10 2019 us=937635 replay_time = 15
Mon May 13 09:29:10 2019 us=937635 packet_id_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=937635 use_iv = ENABLED
Mon May 13 09:29:10 2019 us=937635 test_crypto = DISABLED
Mon May 13 09:29:10 2019 us=937635 tls_server = DISABLED
Mon May 13 09:29:10 2019 us=937635 tls_client = ENABLED
Mon May 13 09:29:10 2019 us=937635 key_method = 2
Mon May 13 09:29:10 2019 us=937635 ca_file = '[[INLINE]]'
Mon May 13 09:29:10 2019 us=937635 ca_path = '[UNDEF]'
Mon May 13 09:29:10 2019 us=938636 dh_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=938636 cert_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=938636 extra_certs_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 priv_key_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 pkcs12_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 cryptoapi_cert = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 cipher_list = 'DEFAULT:@SECLEVEL=0'
Mon May 13 09:29:10 2019 us=939637 tls_cert_profile = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 tls_verify = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 tls_export_cert = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 verify_x509_type = 0
Mon May 13 09:29:10 2019 us=939637 verify_x509_name = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 crl_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=939637 ns_cert_type = 1
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku[i] = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku[i] = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku[i] = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku[i] = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku[i] = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_ku[i] = 0
Mon May 13 09:29:10 2019 us=940638 remote_cert_eku = '[UNDEF]'
Mon May 13 09:29:10 2019 us=940638 ssl_flags = 0
Mon May 13 09:29:10 2019 us=940638 tls_timeout = 2
Mon May 13 09:29:10 2019 us=940638 renegotiate_bytes = -1
Mon May 13 09:29:10 2019 us=940638 renegotiate_packets = 0
Mon May 13 09:29:10 2019 us=941638 renegotiate_seconds = 3600
Mon May 13 09:29:10 2019 us=941638 handshake_window = 60
Mon May 13 09:29:10 2019 us=941638 transition_window = 3600
Mon May 13 09:29:10 2019 us=941638 single_session = DISABLED
Mon May 13 09:29:10 2019 us=941638 push_peer_info = DISABLED
Mon May 13 09:29:10 2019 us=941638 tls_exit = DISABLED
Mon May 13 09:29:10 2019 us=941638 tls_auth_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=941638 tls_crypt_file = '[UNDEF]'
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=941638 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=942641 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=942641 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=942641 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=942641 pkcs11_protected_authentication = DISABLED
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=942641 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=943640 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=943640 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=943640 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=943640 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=943640 pkcs11_private_mode = 00000000
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=943640 pkcs11_cert_private = DISABLED
Mon May 13 09:29:10 2019 us=944641 pkcs11_pin_cache_period = -1
Mon May 13 09:29:10 2019 us=944641 pkcs11_id = '[UNDEF]'
Mon May 13 09:29:10 2019 us=944641 pkcs11_id_management = DISABLED
Mon May 13 09:29:10 2019 us=944641 server_network = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 server_netmask = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 server_network_ipv6 = ::
Mon May 13 09:29:10 2019 us=944641 server_netbits_ipv6 = 0
Mon May 13 09:29:10 2019 us=944641 server_bridge_ip = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 server_bridge_netmask = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 server_bridge_pool_start = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 server_bridge_pool_end = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 ifconfig_pool_defined = DISABLED
Mon May 13 09:29:10 2019 us=944641 ifconfig_pool_start = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 ifconfig_pool_end = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 ifconfig_pool_netmask = 0.0.0.0
Mon May 13 09:29:10 2019 us=944641 ifconfig_pool_persist_filename = '[UNDEF]'
Mon May 13 09:29:10 2019 us=945641 ifconfig_pool_persist_refresh_freq = 600
Mon May 13 09:29:10 2019 us=945641 ifconfig_ipv6_pool_defined = DISABLED
Mon May 13 09:29:10 2019 us=945641 ifconfig_ipv6_pool_base = ::
Mon May 13 09:29:10 2019 us=945641 ifconfig_ipv6_pool_netbits = 0
Mon May 13 09:29:10 2019 us=945641 n_bcast_buf = 256
Mon May 13 09:29:10 2019 us=945641 tcp_queue_limit = 64
Mon May 13 09:29:10 2019 us=945641 real_hash_size = 256
Mon May 13 09:29:10 2019 us=945641 virtual_hash_size = 256
Mon May 13 09:29:10 2019 us=945641 client_connect_script = '[UNDEF]'
Mon May 13 09:29:10 2019 us=945641 learn_address_script = '[UNDEF]'
Mon May 13 09:29:10 2019 us=945641 client_disconnect_script = '[UNDEF]'
Mon May 13 09:29:10 2019 us=945641 client_config_dir = '[UNDEF]'
Mon May 13 09:29:10 2019 us=945641 ccd_exclusive = DISABLED
Mon May 13 09:29:10 2019 us=945641 tmp_dir = 'C:\Windows\TEMP\'
Mon May 13 09:29:10 2019 us=945641 push_ifconfig_defined = DISABLED
Mon May 13 09:29:10 2019 us=945641 push_ifconfig_local = 0.0.0.0
Mon May 13 09:29:10 2019 us=945641 push_ifconfig_remote_netmask = 0.0.0.0
Mon May 13 09:29:10 2019 us=945641 push_ifconfig_ipv6_defined = DISABLED
Mon May 13 09:29:10 2019 us=945641 push_ifconfig_ipv6_local = ::/0
Mon May 13 09:29:10 2019 us=945641 push_ifconfig_ipv6_remote = ::
Mon May 13 09:29:10 2019 us=945641 enable_c2c = DISABLED
Mon May 13 09:29:10 2019 us=945641 duplicate_cn = DISABLED
Mon May 13 09:29:10 2019 us=945641 cf_max = 0
Mon May 13 09:29:10 2019 us=945641 cf_per = 0
Mon May 13 09:29:10 2019 us=945641 max_clients = 1024
Mon May 13 09:29:10 2019 us=945641 max_routes_per_client = 256
Mon May 13 09:29:10 2019 us=945641 auth_user_pass_verify_script = '[UNDEF]'
Mon May 13 09:29:10 2019 us=946643 auth_user_pass_verify_script_via_file = DISABLED
Mon May 13 09:29:10 2019 us=946643 auth_token_generate = DISABLED
Mon May 13 09:29:10 2019 us=946643 auth_token_lifetime = 0
Mon May 13 09:29:10 2019 us=946643 client = ENABLED
Mon May 13 09:29:10 2019 us=946643 pull = ENABLED
Mon May 13 09:29:10 2019 us=946643 auth_user_pass_file = 'stdin'
Mon May 13 09:29:10 2019 us=946643 show_net_up = DISABLED
Mon May 13 09:29:10 2019 us=946643 route_method = 0
Mon May 13 09:29:10 2019 us=946643 block_outside_dns = DISABLED
Mon May 13 09:29:10 2019 us=946643 ip_win32_defined = ENABLED
Mon May 13 09:29:10 2019 us=946643 ip_win32_type = 4
Mon May 13 09:29:10 2019 us=946643 dhcp_masq_offset = 0
Mon May 13 09:29:10 2019 us=946643 dhcp_lease_time = 31536000
Mon May 13 09:29:10 2019 us=946643 tap_sleep = 0
Mon May 13 09:29:10 2019 us=946643 dhcp_options = DISABLED
Mon May 13 09:29:10 2019 us=946643 dhcp_renew = DISABLED
Mon May 13 09:29:10 2019 us=946643 dhcp_pre_release = DISABLED
Mon May 13 09:29:10 2019 us=946643 domain = '[UNDEF]'
Mon May 13 09:29:10 2019 us=946643 netbios_scope = '[UNDEF]'
Mon May 13 09:29:10 2019 us=946643 netbios_node_type = 0
Mon May 13 09:29:10 2019 us=946643 disable_nbt = DISABLED
Mon May 13 09:29:10 2019 us=946643 OpenVPN 2.4.5 i686-w64-mingw32 [SSL (OpenSSL)] [LZO] [LZ4] [PKCS11] [AEAD] built on Mar 1 2018
Mon May 13 09:29:10 2019 us=947643 Windows version 6.2 (Windows 8 or greater) 64bit
Mon May 13 09:29:10 2019 us=947643 library versions: OpenSSL 1.1.0f 25 May 2017, LZO 2.10
Mon May 13 09:29:10 2019 us=952646 MANAGEMENT: TCP Socket listening on [AF_INET]127.0.0.1:3334
Mon May 13 09:29:10 2019 us=952646 Need hold release from management interface, waiting...
Mon May 13 09:29:11 2019 us=284883 MANAGEMENT: Client connected from [AF_INET]127.0.0.1:3334
Mon May 13 09:29:11 2019 us=304896 MANAGEMENT: CMD 'state on'
Mon May 13 09:29:12 2019 us=320141 MANAGEMENT: CMD 'state'
Mon May 13 09:29:13 2019 us=339648 MANAGEMENT: CMD 'hold off'
Mon May 13 09:29:13 2019 us=352656 MANAGEMENT: CMD 'hold release'
Mon May 13 09:29:14 2019 us=363373 MANAGEMENT: CMD 'username Auth admin@foo'
Mon May 13 09:29:14 2019 us=894757 MANAGEMENT: CMD 'password [...]'
Mon May 13 09:29:14 2019 us=896753 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Mon May 13 09:29:14 2019 us=902756 No valid translation found for TLS cipher '@SECLEVEL=0'
Mon May 13 09:29:14 2019 us=906766 LZO compression initializing
Mon May 13 09:29:14 2019 us=906766 Control Channel MTU parms [ L:1656 D:1210 EF:40 EB:0 ET:0 EL:3 ]
Mon May 13 09:29:14 2019 us=907769 Data Channel MTU parms [ L:1656 D:1450 EF:124 EB:412 ET:32 EL:3 ]
Mon May 13 09:29:14 2019 us=907769 Local Options String (VER=V4): 'V4,dev-type tap,link-mtu 1576,tun-mtu 1532,proto TCPv4_CLIENT,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
Mon May 13 09:29:14 2019 us=907769 Expected Remote Options String (VER=V4): 'V4,dev-type tap,link-mtu 1576,tun-mtu 1532,proto TCPv4_SERVER,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
Mon May 13 09:29:14 2019 us=907769 TCP/UDP: Preserving recently used remote address: [AF_INET]4.3.2.1:3128
Mon May 13 09:29:14 2019 us=907769 Socket Buffers: R=[65536->65536] S=[49152->49152]
Mon May 13 09:29:14 2019 us=907769 Attempting to establish TCP connection with [AF_INET]4.3.2.1:3128 [nonblock]
Mon May 13 09:29:14 2019 us=907769 MANAGEMENT: >STATE:1557732554,TCP_CONNECT,,,,,,
Mon May 13 09:29:15 2019 us=910473 TCP connection established with [AF_INET]4.3.2.1:3128
Mon May 13 09:29:15 2019 us=910473 Send to HTTP proxy: 'CONNECT 1.2.3.4:1194 HTTP/1.0'
Mon May 13 09:29:15 2019 us=910473 Send to HTTP proxy: 'Host: 1.2.3.4'
Mon May 13 09:29:15 2019 us=926484 HTTP proxy returned: 'HTTP/1.0 407 Proxy Authentication Required'
Mon May 13 09:29:15 2019 us=927486 Proxy requires authentication
Mon May 13 09:29:15 2019 us=965513 PROXY AUTH BASIC: 'Proxy-Authenticate: Basic realm="proxy"'
Mon May 13 09:29:15 2019 us=982529 HTTP proxy authenticate 'realm="proxy"'
Mon May 13 09:29:15 2019 us=983527 Attempting to establish TCP connection with [AF_INET]4.3.2.1:3128 [nonblock]
Mon May 13 09:29:15 2019 us=983527 MANAGEMENT: >STATE:1557732555,TCP_CONNECT,,,,,,
Mon May 13 09:29:16 2019 us=987238 TCP connection established with [AF_INET]4.3.2.1:3128
Mon May 13 09:29:18 2019 us=8536 MANAGEMENT: CMD 'username "HTTP Proxy" admin'
Mon May 13 09:29:18 2019 us=525909 MANAGEMENT: CMD 'password [...]'
Mon May 13 09:29:18 2019 us=532910 Send to HTTP proxy: 'CONNECT 1.2.3.4:1194 HTTP/1.0'
Mon May 13 09:29:18 2019 us=532910 Send to HTTP proxy: 'Host: 1.2.3.4'
Mon May 13 09:29:18 2019 us=532910 Attempting Basic Proxy-Authorization
Mon May 13 09:29:18 2019 us=569935 HTTP proxy returned: 'HTTP/1.0 200 Connection established'
Mon May 13 09:29:20 2019 us=587368 TCP_CLIENT link local: (not bound)
Mon May 13 09:29:20 2019 us=587368 TCP_CLIENT link remote: [AF_INET]4.3.2.1:3128
Mon May 13 09:29:20 2019 us=587368 MANAGEMENT: >STATE:1557732560,WAIT,,,,,,
Mon May 13 09:29:20 2019 us=615389 MANAGEMENT: >STATE:1557732560,AUTH,,,,,,
Mon May 13 09:29:20 2019 us=615389 TLS: Initial packet from [AF_INET]4.3.2.1:3128, sid=f4e44dd7 6d53c847
Mon May 13 09:29:20 2019 us=616389 WARNING: this configuration may cache passwords in memory -- use the auth-nocache option to prevent this
Mon May 13 09:29:20 2019 us=706453 VERIFY OK: depth=1, C=DE, ST=YOH, L=Haha, O=YEAH OU=CA, CN=RootCA, emailAddress=support.ca@gmail.com
Mon May 13 09:29:20 2019 us=707454 VERIFY OK: nsCertType=SERVER
Mon May 13 09:29:20 2019 us=707454 VERIFY OK: depth=0, C=DE, ST=YOH, L=Haha, O=YEAH, OU=CA, CN=Server, emailAddress=support.ca@gmail.com
Mon May 13 09:29:21 2019 us=153770 Control Channel: TLSv1.2, cipher TLSv1.2 ECDHE-RSA-AES256-GCM-SHA384, 1024 bit RSA
Mon May 13 09:29:21 2019 us=154773 [Server] Peer Connection Initiated with [AF_INET]4.3.2.1:3128
Mon May 13 09:29:22 2019 us=384728 MANAGEMENT: >STATE:1557732562,GET_CONFIG,,,,,,
Mon May 13 09:29:22 2019 us=385723 SENT CONTROL [Server]: 'PUSH_REQUEST' (status=1)
Mon May 13 09:29:22 2019 us=461777 PUSH: Received control message: 'PUSH_REPLY,ifconfig 10.0.0.95 255.255.255.0,peer-id 0,cipher AES-256-GCM'
Mon May 13 09:29:22 2019 us=462778 OPTIONS IMPORT: --ifconfig/up options modified
Mon May 13 09:29:22 2019 us=462778 OPTIONS IMPORT: peer-id set
Mon May 13 09:29:22 2019 us=462778 OPTIONS IMPORT: adjusting link_mtu to 1659
Mon May 13 09:29:22 2019 us=462778 OPTIONS IMPORT: data channel crypto options modified
Mon May 13 09:29:22 2019 us=462778 Data Channel: using negotiated cipher 'AES-256-GCM'
Mon May 13 09:29:22 2019 us=462778 Data Channel MTU parms [ L:1587 D:1450 EF:55 EB:412 ET:32 EL:3 ]
Mon May 13 09:29:22 2019 us=462778 Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Mon May 13 09:29:22 2019 us=462778 Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Mon May 13 09:29:22 2019 us=463778 interactive service msg_channel=0
Mon May 13 09:29:22 2019 us=463778 open_tun
Mon May 13 09:29:22 2019 us=477791 TAP-WIN32 device [mbDIALUP] opened: \\.\Global\{8BAF274D-316A-4090-A5E2-BC11175B31E7}.tap
Mon May 13 09:29:22 2019 us=479794 TAP-Windows Driver Version 9.21
Mon May 13 09:29:22 2019 us=479794 TAP-Windows MTU=1500
Mon May 13 09:29:22 2019 us=516817 Notified TAP-Windows driver to set a DHCP IP/netmask of 10.0.0.95/255.255.255.0 on interface {8BAF274D-316A-4090-A5E2-BC11175B31E7} [DHCP-serv: 10.0.0.0, lease-time: 31536000]
Mon May 13 09:29:22 2019 us=517817 Successful ARP Flush on interface [67] {8BAF274D-316A-4090-A5E2-BC11175B31E7}
Mon May 13 09:29:22 2019 us=517817 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Mon May 13 09:29:22 2019 us=517817 MANAGEMENT: >STATE:1557732562,ASSIGN_IP,,10.0.0.95,,,,
Mon May 13 09:29:22 2019 us=766993 Connection reset, restarting [0]
Mon May 13 09:29:22 2019 us=768996 TCP/UDP: Closing socket
Mon May 13 09:29:22 2019 us=768996 Closing TUN/TAP interface
Mon May 13 09:29:22 2019 us=770996 SIGUSR1[soft,connection-reset] received, process restarting
Mon May 13 09:29:22 2019 us=770996 MANAGEMENT: >STATE:1557732562,RECONNECTING,connection-reset,,,,,
Mon May 13 09:29:22 2019 us=770996 Restart pause, 5 second(s)
Mon May 13 09:29:28 2019 us=89850 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Mon May 13 09:29:28 2019 us=95848 No valid translation found for TLS cipher '@SECLEVEL=0'
Mon May 13 09:29:28 2019 us=96848 LZO compression initializing
Mon May 13 09:29:28 2019 us=96848 Control Channel MTU parms [ L:1656 D:1210 EF:40 EB:0 ET:0 EL:3 ]
Mon May 13 09:29:28 2019 us=97849 Data Channel MTU parms [ L:1656 D:1450 EF:124 EB:412 ET:32 EL:3 ]
Mon May 13 09:29:28 2019 us=97849 Local Options String (VER=V4): 'V4,dev-type tap,link-mtu 1584,tun-mtu 1532,proto TCPv4_CLIENT,comp-lzo,cipher AES-256-GCM,auth [null-digest],keysize 256,key-method 2,tls-client'
Mon May 13 09:29:28 2019 us=97849 Expected Remote Options String (VER=V4): 'V4,dev-type tap,link-mtu 1584,tun-mtu 1532,proto TCPv4_SERVER,comp-lzo,cipher AES-256-GCM,auth [null-digest],keysize 256,key-method 2,tls-server'
Mon May 13 09:29:28 2019 us=97849 TCP/UDP: Preserving recently used remote address: [AF_INET]4.3.2.1:3128
Mon May 13 09:29:28 2019 us=98850 Socket Buffers: R=[65536->65536] S=[49152->49152]
Mon May 13 09:29:28 2019 us=98850 Attempting to establish TCP connection with [AF_INET]4.3.2.1:3128 [nonblock]
Mon May 13 09:29:28 2019 us=98850 MANAGEMENT: >STATE:1557732568,TCP_CONNECT,,,,,,
Mon May 13 09:29:29 2019 us=159602 TCP connection established with [AF_INET]4.3.2.1:3128
Mon May 13 09:29:29 2019 us=159602 Send to HTTP proxy: 'CONNECT 1.2.3.4:1194 HTTP/1.0'
Mon May 13 09:29:29 2019 us=159602 Send to HTTP proxy: 'Host: 1.2.3.4'
Mon May 13 09:29:29 2019 us=178616 HTTP proxy returned: 'HTTP/1.0 407 Proxy Authentication Required'
Mon May 13 09:29:29 2019 us=178616 Proxy requires authentication
Mon May 13 09:29:29 2019 us=246666 PROXY AUTH BASIC: 'Proxy-Authenticate: Basic realm="proxy"'
Mon May 13 09:29:29 2019 us=281691 HTTP proxy authenticate 'realm="proxy"'
Mon May 13 09:29:29 2019 us=282690 Attempting to establish TCP connection with [AF_INET]4.3.2.1:3128 [nonblock]
Mon May 13 09:29:29 2019 us=282690 MANAGEMENT: >STATE:1557732569,TCP_CONNECT,,,,,,
Mon May 13 09:29:30 2019 us=295408 TCP connection established with [AF_INET]4.3.2.1:3128
Mon May 13 09:29:30 2019 us=295408 Send to HTTP proxy: 'CONNECT 1.2.3.4:1194 HTTP/1.0'
Mon May 13 09:29:30 2019 us=295408 Send to HTTP proxy: 'Host: 1.2.3.4'
Mon May 13 09:29:30 2019 us=296409 MANAGEMENT: Client disconnected
Mon May 13 09:29:30 2019 us=296409 Assertion failed at proxy.c:250 (strlen(p->up.username) > 0)
Mon May 13 09:29:30 2019 us=296409 Exiting due to fatal error
[/olog]

simu
OpenVpn Newbie
Posts: 7
Joined: Tue May 22, 2012 10:53 am

Re: HTTP-Proxy connect failed after reconnect

Post by simu » Fri May 24, 2019 11:47 am

Hello,
is there more I can provide?

User avatar
TinCanTech
OpenVPN Protagonist
Posts: 5644
Joined: Fri Jun 03, 2016 1:17 pm

Re: HTTP-Proxy connect failed after reconnect

Post by TinCanTech » Fri May 24, 2019 1:04 pm

It looks like you missed out the proxy settings from your config ..

You should really be using the latest release:
https://openvpn.net/community-downloads/

Post Reply