HTTP-Proxy connect failed after reconnect

This forum is for admins who are looking to build or expand their OpenVPN setup.

Moderators: TinCanTech, TinCanTech, TinCanTech, TinCanTech, TinCanTech, TinCanTech

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: 10
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

TinCanTech
OpenVPN Protagonist
Posts: 11137
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: 10
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: 10
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: 10
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: 10
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?

TinCanTech
OpenVPN Protagonist
Posts: 11137
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/

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

Re: HTTP-Proxy connect failed after reconnect

Post by simu » Tue May 28, 2019 11:18 am

The http-proxy settings are done by the cmd line, but you see in the loggings since it is verb 4, the whole settings.

The logging shows clearly that after an re-connect it failed with HTTP Proxy, because it is not asking the management console for the credentials, but has string-len = 0 for the username.
I also tested it with 2.4.7, same result.

Any new advise? Please!

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

Re: HTTP-Proxy connect failed after reconnect

Post by simu » Fri Jun 07, 2019 11:44 am

Is there anybody who can help here?

TinCanTech
OpenVPN Protagonist
Posts: 11137
Joined: Fri Jun 03, 2016 1:17 pm

Re: HTTP-Proxy connect failed after reconnect

Post by TinCanTech » Fri Jun 07, 2019 11:49 am

Try putting the proxy settings in the config file ..

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

Re: HTTP-Proxy connect failed after reconnect

Post by simu » Wed Jun 12, 2019 1:52 pm

I put everything in the config file, proxy settings and user+password.
But nothing changed, the issue is still there.
Here is the log with verb4 to see also the config.

[olog]
Wed Jun 12 15:42:27 2019 us=110606 Current Parameter Settings:
Wed Jun 12 15:42:27 2019 us=110606 config = 'C:\ProgramData\ovpn\config\config.ovpn'
Wed Jun 12 15:42:27 2019 us=110606 mode = 0
Wed Jun 12 15:42:27 2019 us=110606 show_ciphers = DISABLED
Wed Jun 12 15:42:27 2019 us=110606 show_digests = DISABLED
Wed Jun 12 15:42:27 2019 us=110606 show_engines = DISABLED
Wed Jun 12 15:42:27 2019 us=110606 genkey = DISABLED
Wed Jun 12 15:42:27 2019 us=110606 key_pass_file = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=110606 show_tls_ciphers = DISABLED
Wed Jun 12 15:42:27 2019 us=110606 connect_retry_max = 0
Wed Jun 12 15:42:27 2019 us=110606 Connection profiles [0]:
Wed Jun 12 15:42:27 2019 us=110606 proto = tcp-client
Wed Jun 12 15:42:27 2019 us=110606 local = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 local_port = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 remote = '5.39.123.17'
Wed Jun 12 15:42:27 2019 us=111607 remote_port = '1194'
Wed Jun 12 15:42:27 2019 us=111607 remote_float = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 bind_defined = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 bind_local = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 bind_ipv6_only = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 connect_retry_seconds = 5
Wed Jun 12 15:42:27 2019 us=111607 connect_timeout = 120
Wed Jun 12 15:42:27 2019 us=111607 BEGIN http_proxy
Wed Jun 12 15:42:27 2019 us=111607 server = '172.25.0.5'
Wed Jun 12 15:42:27 2019 us=111607 port = '3128'
Wed Jun 12 15:42:27 2019 us=111607 auth_method_string = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 auth_file = 'proxyuser
proxypassword
'
Wed Jun 12 15:42:27 2019 us=111607 http_version = '1.0'
Wed Jun 12 15:42:27 2019 us=111607 user_agent = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 END http_proxy
Wed Jun 12 15:42:27 2019 us=111607 socks_proxy_server = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 socks_proxy_port = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 tun_mtu = 1500
Wed Jun 12 15:42:27 2019 us=111607 tun_mtu_defined = ENABLED
Wed Jun 12 15:42:27 2019 us=111607 link_mtu = 1500
Wed Jun 12 15:42:27 2019 us=111607 link_mtu_defined = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 tun_mtu_extra = 32
Wed Jun 12 15:42:27 2019 us=111607 tun_mtu_extra_defined = ENABLED
Wed Jun 12 15:42:27 2019 us=111607 mtu_discover_type = -1
Wed Jun 12 15:42:27 2019 us=111607 fragment = 0
Wed Jun 12 15:42:27 2019 us=111607 mssfix = 1450
Wed Jun 12 15:42:27 2019 us=111607 explicit_exit_notification = 0
Wed Jun 12 15:42:27 2019 us=111607 Connection profiles END
Wed Jun 12 15:42:27 2019 us=111607 remote_random = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 ipchange = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 dev = 'tap'
Wed Jun 12 15:42:27 2019 us=111607 dev_type = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 dev_node = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 lladdr = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 topology = 1
Wed Jun 12 15:42:27 2019 us=111607 ifconfig_local = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 ifconfig_remote_netmask = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 ifconfig_noexec = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 ifconfig_nowarn = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 ifconfig_ipv6_local = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 ifconfig_ipv6_netbits = 0
Wed Jun 12 15:42:27 2019 us=111607 ifconfig_ipv6_remote = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 shaper = 0
Wed Jun 12 15:42:27 2019 us=111607 mtu_test = 0
Wed Jun 12 15:42:27 2019 us=111607 mlock = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 keepalive_ping = 0
Wed Jun 12 15:42:27 2019 us=111607 keepalive_timeout = 0
Wed Jun 12 15:42:27 2019 us=111607 inactivity_timeout = 0
Wed Jun 12 15:42:27 2019 us=111607 ping_send_timeout = 0
Wed Jun 12 15:42:27 2019 us=111607 ping_rec_timeout = 0
Wed Jun 12 15:42:27 2019 us=111607 ping_rec_timeout_action = 0
Wed Jun 12 15:42:27 2019 us=111607 ping_timer_remote = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 remap_sigusr1 = 0
Wed Jun 12 15:42:27 2019 us=111607 persist_tun = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 persist_local_ip = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 persist_remote_ip = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 persist_key = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 passtos = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 resolve_retry_seconds = 1000000000
Wed Jun 12 15:42:27 2019 us=111607 resolve_in_advance = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 username = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 groupname = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 chroot_dir = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 cd_dir = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 writepid = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 up_script = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 down_script = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 down_pre = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 up_restart = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 up_delay = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 daemon = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 inetd = 0
Wed Jun 12 15:42:27 2019 us=111607 log = ENABLED
Wed Jun 12 15:42:27 2019 us=111607 suppress_timestamps = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 machine_readable_output = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 nice = 0
Wed Jun 12 15:42:27 2019 us=111607 verbosity = 4
Wed Jun 12 15:42:27 2019 us=111607 mute = 0
Wed Jun 12 15:42:27 2019 us=111607 gremlin = 0
Wed Jun 12 15:42:27 2019 us=111607 status_file = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 status_file_version = 1
Wed Jun 12 15:42:27 2019 us=111607 status_file_update_freq = 60
Wed Jun 12 15:42:27 2019 us=111607 occ = ENABLED
Wed Jun 12 15:42:27 2019 us=111607 rcvbuf = 0
Wed Jun 12 15:42:27 2019 us=111607 sndbuf = 0
Wed Jun 12 15:42:27 2019 us=111607 sockflags = 0
Wed Jun 12 15:42:27 2019 us=111607 fast_io = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 comp.alg = 2
Wed Jun 12 15:42:27 2019 us=111607 comp.flags = 1
Wed Jun 12 15:42:27 2019 us=111607 route_script = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 route_default_gateway = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=111607 route_default_metric = 0
Wed Jun 12 15:42:27 2019 us=111607 route_noexec = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 route_delay = 5
Wed Jun 12 15:42:27 2019 us=111607 route_delay_window = 300
Wed Jun 12 15:42:27 2019 us=111607 route_delay_defined = ENABLED
Wed Jun 12 15:42:27 2019 us=111607 route_nopull = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 route_gateway_via_dhcp = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 allow_pull_fqdn = DISABLED
Wed Jun 12 15:42:27 2019 us=111607 management_addr = '127.0.0.1'
Wed Jun 12 15:42:27 2019 us=111607 management_port = '3334'
Wed Jun 12 15:42:27 2019 us=112607 management_user_pass = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 management_log_history_cache = 200
Wed Jun 12 15:42:27 2019 us=112607 management_echo_buffer_size = 100
Wed Jun 12 15:42:27 2019 us=112607 management_write_peer_info_file = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 management_client_user = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 management_client_group = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 management_flags = 6
Wed Jun 12 15:42:27 2019 us=112607 shared_secret_file = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 key_direction = not set
Wed Jun 12 15:42:27 2019 us=112607 ciphername = 'BF-CBC'
Wed Jun 12 15:42:27 2019 us=112607 ncp_enabled = ENABLED
Wed Jun 12 15:42:27 2019 us=112607 ncp_ciphers = 'AES-256-GCM:AES-128-GCM'
Wed Jun 12 15:42:27 2019 us=112607 authname = 'SHA1'
Wed Jun 12 15:42:27 2019 us=112607 prng_hash = 'SHA1'
Wed Jun 12 15:42:27 2019 us=112607 prng_nonce_secret_len = 16
Wed Jun 12 15:42:27 2019 us=112607 keysize = 0
Wed Jun 12 15:42:27 2019 us=112607 engine = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 replay = ENABLED
Wed Jun 12 15:42:27 2019 us=112607 mute_replay_warnings = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 replay_window = 64
Wed Jun 12 15:42:27 2019 us=112607 replay_time = 15
Wed Jun 12 15:42:27 2019 us=112607 packet_id_file = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 use_iv = ENABLED
Wed Jun 12 15:42:27 2019 us=112607 test_crypto = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 tls_server = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 tls_client = ENABLED
Wed Jun 12 15:42:27 2019 us=112607 key_method = 2
Wed Jun 12 15:42:27 2019 us=112607 ca_file = '[[INLINE]]'
Wed Jun 12 15:42:27 2019 us=112607 ca_path = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 dh_file = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 cert_file = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 extra_certs_file = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 priv_key_file = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 pkcs12_file = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 cryptoapi_cert = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 cipher_list = 'DEFAULT:@SECLEVEL=0'
Wed Jun 12 15:42:27 2019 us=112607 cipher_list_tls13 = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 tls_cert_profile = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 tls_verify = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 tls_export_cert = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 verify_x509_type = 0
Wed Jun 12 15:42:27 2019 us=112607 verify_x509_name = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 crl_file = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 ns_cert_type = 1
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku[i] = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku[i] = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku[i] = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku[i] = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku[i] = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_ku[i] = 0
Wed Jun 12 15:42:27 2019 us=112607 remote_cert_eku = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 ssl_flags = 0
Wed Jun 12 15:42:27 2019 us=112607 tls_timeout = 2
Wed Jun 12 15:42:27 2019 us=112607 renegotiate_bytes = -1
Wed Jun 12 15:42:27 2019 us=112607 renegotiate_packets = 0
Wed Jun 12 15:42:27 2019 us=112607 renegotiate_seconds = 3600
Wed Jun 12 15:42:27 2019 us=112607 handshake_window = 60
Wed Jun 12 15:42:27 2019 us=112607 transition_window = 3600
Wed Jun 12 15:42:27 2019 us=112607 single_session = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 push_peer_info = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 tls_exit = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 tls_auth_file = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 tls_crypt_file = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_protected_authentication = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_private_mode = 00000000
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_cert_private = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_pin_cache_period = -1
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_id = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=112607 pkcs11_id_management = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 server_network = 0.0.0.0
Wed Jun 12 15:42:27 2019 us=112607 server_netmask = 0.0.0.0
Wed Jun 12 15:42:27 2019 us=112607 server_network_ipv6 = ::
Wed Jun 12 15:42:27 2019 us=112607 server_netbits_ipv6 = 0
Wed Jun 12 15:42:27 2019 us=112607 server_bridge_ip = 0.0.0.0
Wed Jun 12 15:42:27 2019 us=112607 server_bridge_netmask = 0.0.0.0
Wed Jun 12 15:42:27 2019 us=112607 server_bridge_pool_start = 0.0.0.0
Wed Jun 12 15:42:27 2019 us=112607 server_bridge_pool_end = 0.0.0.0
Wed Jun 12 15:42:27 2019 us=112607 ifconfig_pool_defined = DISABLED
Wed Jun 12 15:42:27 2019 us=112607 ifconfig_pool_start = 0.0.0.0
Wed Jun 12 15:42:27 2019 us=113608 ifconfig_pool_end = 0.0.0.0
Wed Jun 12 15:42:27 2019 us=113608 ifconfig_pool_netmask = 0.0.0.0
Wed Jun 12 15:42:27 2019 us=113608 ifconfig_pool_persist_filename = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=113608 ifconfig_pool_persist_refresh_freq = 600
Wed Jun 12 15:42:27 2019 us=113608 ifconfig_ipv6_pool_defined = DISABLED
Wed Jun 12 15:42:27 2019 us=113608 ifconfig_ipv6_pool_base = ::
Wed Jun 12 15:42:27 2019 us=113608 ifconfig_ipv6_pool_netbits = 0
Wed Jun 12 15:42:27 2019 us=113608 n_bcast_buf = 256
Wed Jun 12 15:42:27 2019 us=113608 tcp_queue_limit = 64
Wed Jun 12 15:42:27 2019 us=113608 real_hash_size = 256
Wed Jun 12 15:42:27 2019 us=113608 virtual_hash_size = 256
Wed Jun 12 15:42:27 2019 us=113608 client_connect_script = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=113608 learn_address_script = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=113608 client_disconnect_script = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=113608 client_config_dir = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=113608 ccd_exclusive = DISABLED
Wed Jun 12 15:42:27 2019 us=113608 tmp_dir = 'C:\Windows\TEMP\'
Wed Jun 12 15:42:27 2019 us=113608 push_ifconfig_defined = DISABLED
Wed Jun 12 15:42:27 2019 us=113608 push_ifconfig_local = 0.0.0.0
Wed Jun 12 15:42:27 2019 us=113608 push_ifconfig_remote_netmask = 0.0.0.0
Wed Jun 12 15:42:27 2019 us=113608 push_ifconfig_ipv6_defined = DISABLED
Wed Jun 12 15:42:27 2019 us=113608 push_ifconfig_ipv6_local = ::/0
Wed Jun 12 15:42:27 2019 us=113608 push_ifconfig_ipv6_remote = ::
Wed Jun 12 15:42:27 2019 us=113608 enable_c2c = DISABLED
Wed Jun 12 15:42:27 2019 us=113608 duplicate_cn = DISABLED
Wed Jun 12 15:42:27 2019 us=113608 cf_max = 0
Wed Jun 12 15:42:27 2019 us=113608 cf_per = 0
Wed Jun 12 15:42:27 2019 us=113608 max_clients = 1024
Wed Jun 12 15:42:27 2019 us=113608 max_routes_per_client = 256
Wed Jun 12 15:42:27 2019 us=113608 auth_user_pass_verify_script = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=113608 auth_user_pass_verify_script_via_file = DISABLED
Wed Jun 12 15:42:27 2019 us=113608 auth_token_generate = DISABLED
Wed Jun 12 15:42:27 2019 us=113608 auth_token_lifetime = 0
Wed Jun 12 15:42:27 2019 us=113608 client = ENABLED
Wed Jun 12 15:42:27 2019 us=113608 pull = ENABLED
Wed Jun 12 15:42:27 2019 us=113608 auth_user_pass_file = 'stdin'
Wed Jun 12 15:42:27 2019 us=113608 show_net_up = DISABLED
Wed Jun 12 15:42:27 2019 us=113608 route_method = 0
Wed Jun 12 15:42:27 2019 us=113608 block_outside_dns = DISABLED
Wed Jun 12 15:42:27 2019 us=113608 ip_win32_defined = ENABLED
Wed Jun 12 15:42:27 2019 us=113608 ip_win32_type = 4
Wed Jun 12 15:42:27 2019 us=113608 dhcp_masq_offset = 0
Wed Jun 12 15:42:27 2019 us=113608 dhcp_lease_time = 31536000
Wed Jun 12 15:42:27 2019 us=113608 tap_sleep = 0
Wed Jun 12 15:42:27 2019 us=113608 dhcp_options = DISABLED
Wed Jun 12 15:42:27 2019 us=113608 dhcp_renew = DISABLED
Wed Jun 12 15:42:27 2019 us=113608 dhcp_pre_release = DISABLED
Wed Jun 12 15:42:27 2019 us=113608 domain = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=113608 netbios_scope = '[UNDEF]'
Wed Jun 12 15:42:27 2019 us=113608 netbios_node_type = 0
Wed Jun 12 15:42:27 2019 us=113608 disable_nbt = DISABLED

Wed Jun 12 15:42:27 2019 us=113608 OpenVPN 2.4.7 i686-w64-mingw32 [SSL (OpenSSL)] [LZO] [LZ4] [PKCS11] [AEAD] built on Apr 25 2019
Wed Jun 12 15:42:27 2019 us=113608 Windows version 6.2 (Windows 8 or greater) 64bit
Wed Jun 12 15:42:27 2019 us=113608 library versions: OpenSSL 1.1.0j 20 Nov 2018, LZO 2.10
Wed Jun 12 15:42:27 2019 us=114609 MANAGEMENT: TCP Socket listening on [AF_INET]127.0.0.1:3334
Wed Jun 12 15:42:27 2019 us=114609 Need hold release from management interface, waiting...
Wed Jun 12 15:42:27 2019 us=518896 MANAGEMENT: Client connected from [AF_INET]127.0.0.1:3334
Wed Jun 12 15:42:27 2019 us=531906 MANAGEMENT: CMD 'state on'
Wed Jun 12 15:42:28 2019 us=535318 MANAGEMENT: CMD 'state'
Wed Jun 12 15:42:29 2019 us=552044 MANAGEMENT: CMD 'hold off'
Wed Jun 12 15:42:29 2019 us=564048 MANAGEMENT: CMD 'hold release'
Wed Jun 12 15:42:30 2019 us=581873 MANAGEMENT: CMD 'username Auth simu@mbc-alka'
Wed Jun 12 15:42:31 2019 us=110292 MANAGEMENT: CMD 'password [...]'
Wed Jun 12 15:42:31 2019 us=110292 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Wed Jun 12 15:42:31 2019 us=116296 No valid translation found for TLS cipher '@SECLEVEL=0'
Wed Jun 12 15:42:31 2019 us=117297 LZO compression initializing
Wed Jun 12 15:42:31 2019 us=117297 Control Channel MTU parms [ L:1656 D:1210 EF:40 EB:0 ET:0 EL:3 ]
Wed Jun 12 15:42:31 2019 us=118297 Data Channel MTU parms [ L:1656 D:1450 EF:124 EB:412 ET:32 EL:3 ]
Wed Jun 12 15:42:31 2019 us=118297 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'
Wed Jun 12 15:42:31 2019 us=118297 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'
Wed Jun 12 15:42:31 2019 us=118297 TCP/UDP: Preserving recently used remote address: [AF_INET]172.25.0.5:3128
Wed Jun 12 15:42:31 2019 us=118297 Socket Buffers: R=[65536->65536] S=[49152->49152]
Wed Jun 12 15:42:31 2019 us=118297 Attempting to establish TCP connection with [AF_INET]172.25.0.5:3128 [nonblock]
Wed Jun 12 15:42:31 2019 us=118297 MANAGEMENT: >STATE:1560346951,TCP_CONNECT,,,,,,
Wed Jun 12 15:42:32 2019 us=127012 TCP connection established with [AF_INET]172.25.0.5:3128
Wed Jun 12 15:42:32 2019 us=127012 Send to HTTP proxy: 'CONNECT 4.3.2.1:1194 HTTP/1.0'
Wed Jun 12 15:42:32 2019 us=127012 Send to HTTP proxy: 'Host: 4.3.2.1'
Wed Jun 12 15:42:32 2019 us=139020 HTTP proxy returned: 'HTTP/1.0 407 Proxy Authentication Required'
Wed Jun 12 15:42:32 2019 us=139020 Proxy requires authentication
Wed Jun 12 15:42:32 2019 us=145025 PROXY AUTH BASIC: 'Proxy-Authenticate: Basic realm="proxy"'
Wed Jun 12 15:42:32 2019 us=149028 HTTP proxy authenticate 'realm="proxy"'
Wed Jun 12 15:42:32 2019 us=150031 Attempting to establish TCP connection with [AF_INET]172.25.0.5:3128 [nonblock]
Wed Jun 12 15:42:32 2019 us=150031 MANAGEMENT: >STATE:1560346952,TCP_CONNECT,,,,,,
Wed Jun 12 15:42:33 2019 us=150637 TCP connection established with [AF_INET]172.25.0.5:3128
Wed Jun 12 15:42:33 2019 us=150637 Send to HTTP proxy: 'CONNECT 4.3.2.1:1194 HTTP/1.0'
Wed Jun 12 15:42:33 2019 us=150637 Send to HTTP proxy: 'Host: 4.3.2.1'
Wed Jun 12 15:42:33 2019 us=150637 Attempting Basic Proxy-Authorization
Wed Jun 12 15:42:33 2019 us=177656 HTTP proxy returned: 'HTTP/1.0 200 Connection established'
Wed Jun 12 15:42:35 2019 us=178095 TCP_CLIENT link local: (not bound)
Wed Jun 12 15:42:35 2019 us=178095 TCP_CLIENT link remote: [AF_INET]172.25.0.5:3128
Wed Jun 12 15:42:35 2019 us=178095 MANAGEMENT: >STATE:1560346955,WAIT,,,,,,
Wed Jun 12 15:42:35 2019 us=199110 MANAGEMENT: >STATE:1560346955,AUTH,,,,,,
Wed Jun 12 15:42:35 2019 us=200096 TLS: Initial packet from [AF_INET]172.25.0.5:3128, sid=02b7d5f9 ac8b7754
Wed Jun 12 15:42:35 2019 us=200096 WARNING: this configuration may cache passwords in memory -- use the auth-nocache option to prevent this
Wed Jun 12 15:42:35 2019 us=283151 VERIFY OK: depth=1, C=De, ST=12345, L=Dinkelsbühl, O=Muster GmbH, CN=Muster GmbH CA, emailAddress=foo@mail.com
Wed Jun 12 15:42:35 2019 us=283151 VERIFY OK: nsCertType=SERVER
Wed Jun 12 15:42:35 2019 us=283151 VERIFY OK: depth=0, C=De, ST=12345, L=Dinkelsbühl, O=Muster GmbH, CN=Server, emailAddress=foo@mail.com
Wed Jun 12 15:42:35 2019 us=784507 Control Channel: TLSv1.2, cipher TLSv1.2 ECDHE-RSA-AES256-GCM-SHA384, 1024 bit RSA
Wed Jun 12 15:42:35 2019 us=784507 [Server] Peer Connection Initiated with [AF_INET]172.25.0.5:3128
Wed Jun 12 15:42:36 2019 us=815238 MANAGEMENT: >STATE:1560346956,GET_CONFIG,,,,,,
Wed Jun 12 15:42:36 2019 us=815238 SENT CONTROL [Server]: 'PUSH_REQUEST' (status=1)
Wed Jun 12 15:42:36 2019 us=891292 PUSH: Received control message: 'PUSH_REPLY,ifconfig 10.0.0.78 255.255.255.0,peer-id 0,cipher AES-256-GCM'
Wed Jun 12 15:42:36 2019 us=891292 OPTIONS IMPORT: --ifconfig/up options modified
Wed Jun 12 15:42:36 2019 us=891292 OPTIONS IMPORT: peer-id set
Wed Jun 12 15:42:36 2019 us=891292 OPTIONS IMPORT: adjusting link_mtu to 1659
Wed Jun 12 15:42:36 2019 us=891292 OPTIONS IMPORT: data channel crypto options modified
Wed Jun 12 15:42:36 2019 us=891292 Data Channel: using negotiated cipher 'AES-256-GCM'
Wed Jun 12 15:42:36 2019 us=891292 Data Channel MTU parms [ L:1587 D:1450 EF:55 EB:412 ET:32 EL:3 ]
Wed Jun 12 15:42:36 2019 us=891292 Outgoing Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Wed Jun 12 15:42:36 2019 us=891292 Incoming Data Channel: Cipher 'AES-256-GCM' initialized with 256 bit key
Wed Jun 12 15:42:36 2019 us=891292 interactive service msg_channel=0
Wed Jun 12 15:42:36 2019 us=891292 open_tun
Wed Jun 12 15:42:36 2019 us=893294 TAP-WIN32 device [TAP-WIN32] opened: \\.\Global\{B43A7931-96D4-42E4-9489-78B410195C31}.tap
Wed Jun 12 15:42:36 2019 us=893294 TAP-Windows Driver Version 9.21
Wed Jun 12 15:42:36 2019 us=893294 TAP-Windows MTU=1500
Wed Jun 12 15:42:36 2019 us=901300 Notified TAP-Windows driver to set a DHCP IP/netmask of 10.0.0.78/255.255.255.0 on interface {B43A7931-96D4-42E4-9489-78B410195C31} [DHCP-serv: 10.0.0.0, lease-time: 31536000]
Wed Jun 12 15:42:36 2019 us=902301 Successful ARP Flush on interface [68] {B43A7931-96D4-42E4-9489-78B410195C31}
Wed Jun 12 15:42:36 2019 us=902301 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Wed Jun 12 15:42:36 2019 us=902301 MANAGEMENT: >STATE:1560346956,ASSIGN_IP,,10.0.0.78,,,,
Wed Jun 12 15:42:37 2019 us=35396 Connection reset, restarting [0]
Wed Jun 12 15:42:37 2019 us=35396 TCP/UDP: Closing socket
Wed Jun 12 15:42:37 2019 us=35396 Closing TUN/TAP interface
Wed Jun 12 15:42:37 2019 us=36397 SIGUSR1[soft,connection-reset] received, process restarting
Wed Jun 12 15:42:37 2019 us=36397 MANAGEMENT: >STATE:1560346957,RECONNECTING,connection-reset,,,,,
Wed Jun 12 15:42:37 2019 us=36397 Restart pause, 5 second(s)
Wed Jun 12 15:42:42 2019 us=42114 WARNING: --ns-cert-type is DEPRECATED. Use --remote-cert-tls instead.
Wed Jun 12 15:42:42 2019 us=42114 No valid translation found for TLS cipher '@SECLEVEL=0'
Wed Jun 12 15:42:42 2019 us=42114 LZO compression initializing
Wed Jun 12 15:42:42 2019 us=42114 Control Channel MTU parms [ L:1656 D:1210 EF:40 EB:0 ET:0 EL:3 ]
Wed Jun 12 15:42:42 2019 us=42114 Data Channel MTU parms [ L:1656 D:1450 EF:124 EB:412 ET:32 EL:3 ]
Wed Jun 12 15:42:42 2019 us=42114 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'
Wed Jun 12 15:42:42 2019 us=42114 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'
Wed Jun 12 15:42:42 2019 us=42114 TCP/UDP: Preserving recently used remote address: [AF_INET]172.25.0.5:3128
Wed Jun 12 15:42:42 2019 us=42114 Socket Buffers: R=[65536->65536] S=[49152->49152]
Wed Jun 12 15:42:42 2019 us=42114 Attempting to establish TCP connection with [AF_INET]172.25.0.5:3128 [nonblock]
Wed Jun 12 15:42:42 2019 us=42114 MANAGEMENT: >STATE:1560346962,TCP_CONNECT,,,,,,
Wed Jun 12 15:42:43 2019 us=42405 TCP connection established with [AF_INET]172.25.0.5:3128
Wed Jun 12 15:42:43 2019 us=42405 Send to HTTP proxy: 'CONNECT 4.3.2.1:1194 HTTP/1.0'
Wed Jun 12 15:42:43 2019 us=42405 Send to HTTP proxy: 'Host: 4.3.2.1'
Wed Jun 12 15:42:43 2019 us=51410 HTTP proxy returned: 'HTTP/1.0 407 Proxy Authentication Required'
Wed Jun 12 15:42:43 2019 us=51410 Proxy requires authentication
Wed Jun 12 15:42:43 2019 us=57415 PROXY AUTH BASIC: 'Proxy-Authenticate: Basic realm="proxy"'
Wed Jun 12 15:42:43 2019 us=62419 HTTP proxy authenticate 'realm="proxy"'
Wed Jun 12 15:42:43 2019 us=62419 Attempting to establish TCP connection with [AF_INET]172.25.0.5:3128 [nonblock]
Wed Jun 12 15:42:43 2019 us=62419 MANAGEMENT: >STATE:1560346963,TCP_CONNECT,,,,,,
Wed Jun 12 15:42:44 2019 us=63129 TCP connection established with [AF_INET]172.25.0.5:3128
Wed Jun 12 15:42:44 2019 us=63129 Send to HTTP proxy: 'CONNECT 4.3.2.1:1194 HTTP/1.0'
Wed Jun 12 15:42:44 2019 us=63129 Send to HTTP proxy: 'Host: 4.3.2.1'
Wed Jun 12 15:42:44 2019 us=64134 MANAGEMENT: Client disconnected
Wed Jun 12 15:42:44 2019 us=64134 Assertion failed at proxy.c:250 (strlen(p->up.username) > 0)
Wed Jun 12 15:42:44 2019 us=64134 Exiting due to fatal error
[/olog]

any further idea?

TinCanTech
OpenVPN Protagonist
Posts: 11137
Joined: Fri Jun 03, 2016 1:17 pm

Re: HTTP-Proxy connect failed after reconnect

Post by TinCanTech » Wed Jun 12, 2019 4:26 pm

The developers have accepted that there is likely a real bug but that it is not critical at this time.

The problem is that they have little enough time as it is and they are volunteers ..

Post Reply