Hi, I am trying to extend the TLS handshake process time at openvpn client side (set tls-timeout as 2400 in client config file). But keep getting the error message " TLS Error: TLS key negotiation failed to occur within 60 seconds " The configuration file for server and client is as follows:
server config file (server version openvpn-1.4.7):
port 1194
proto udp
dev tun
ca /etc/openvpn/ca.crt
cert /etc/openvpn/server.crt
key /etc/openvpn/server.key
dh none
server 10.8.0.0 255.255.255.0
ifconfig-pool-persist ipp.txt
keepalive 10 120
tls-auth /etc/openvpn/ta.key 0
key-direction 0
cipher AES-256-CBC
auth SHA384
user lw
group lw
persist-key
persist-tun
status openvpn-status.log
verb 4
explicit-exit-notify 1
client config file (client version openvpn-2.3.10):
client
dev tun
tls-timeout 2400
ping-restart 0
ping 1800
proto udp
script-security 2
up /etc/openvpn/update-resolv-conf
down /etc/openvpn/update-resolv-conf
remote 192.168.0.6 1194
resolv-retry infinite
nobind
user lw
group lw
ca ca.crt
cert client.crt
key client.key
remote-cert-tls server
tls-auth ta.key 1
cipher AES-256-CBC
auth SHA384
key-direction 1
verb 9
<ca> --STRIPPED INLINE CA CERT-- </ca>
<cert> --STRIPPED INLINE CERT-- </cert>
<key> --STRIPPED INLINE KEY-- </key>
<tls-auth> --STRIPPED INLINE TLS-AUTH KEY-- </tls-auth>
The client log:
Fri Jun 12 01:49:38 2020 us=604386 Current Parameter Settings:
Fri Jun 12 01:49:38 2020 us=604810 config = 'client2.ovpn'
Fri Jun 12 01:49:38 2020 us=604906 mode = 0
Fri Jun 12 01:49:38 2020 us=604969 persist_config = DISABLED
Fri Jun 12 01:49:38 2020 us=605021 persist_mode = 1
Fri Jun 12 01:49:38 2020 us=605072 show_ciphers = DISABLED
Fri Jun 12 01:49:38 2020 us=605118 show_digests = DISABLED
Fri Jun 12 01:49:38 2020 us=605169 show_engines = DISABLED
Fri Jun 12 01:49:38 2020 us=605216 genkey = DISABLED
Fri Jun 12 01:49:38 2020 us=605268 key_pass_file = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=605314 show_tls_ciphers = DISABLED
Fri Jun 12 01:49:38 2020 us=605362 Connection profiles [default]:
Fri Jun 12 01:49:38 2020 us=605413 proto = udp
Fri Jun 12 01:49:38 2020 us=605462 local = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=605511 local_port = 0
Fri Jun 12 01:49:38 2020 us=605680 remote = '192.168.0.6'
Fri Jun 12 01:49:38 2020 us=605759 remote_port = 1194
Fri Jun 12 01:49:38 2020 us=605807 remote_float = DISABLED
Fri Jun 12 01:49:38 2020 us=605855 bind_defined = DISABLED
Fri Jun 12 01:49:38 2020 us=605905 bind_local = DISABLED
Fri Jun 12 01:49:38 2020 us=605952 connect_retry_seconds = 5
Fri Jun 12 01:49:38 2020 us=606001 connect_timeout = 10
Fri Jun 12 01:49:38 2020 us=606031 connect_retry_max = 0
Fri Jun 12 01:49:38 2020 us=606056 socks_proxy_server = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=606093 socks_proxy_port = 0
Fri Jun 12 01:49:38 2020 us=606139 socks_proxy_retry = DISABLED
Fri Jun 12 01:49:38 2020 us=606188 tun_mtu = 1500
Fri Jun 12 01:49:38 2020 us=606232 tun_mtu_defined = ENABLED
Fri Jun 12 01:49:38 2020 us=606278 link_mtu = 1500
Fri Jun 12 01:49:38 2020 us=606321 link_mtu_defined = DISABLED
Fri Jun 12 01:49:38 2020 us=606365 tun_mtu_extra = 0
Fri Jun 12 01:49:38 2020 us=606414 tun_mtu_extra_defined = DISABLED
Fri Jun 12 01:49:38 2020 us=606466 mtu_discover_type = -1
Fri Jun 12 01:49:38 2020 us=606516 fragment = 0
Fri Jun 12 01:49:38 2020 us=606737 mssfix = 1450
Fri Jun 12 01:49:38 2020 us=606817 explicit_exit_notification = 0
Fri Jun 12 01:49:38 2020 us=606870 Connection profiles END
Fri Jun 12 01:49:38 2020 us=606921 remote_random = DISABLED
Fri Jun 12 01:49:38 2020 us=606966 ipchange = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=607014 dev = 'tun'
Fri Jun 12 01:49:38 2020 us=607062 dev_type = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=607114 dev_node = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=607160 lladdr = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=607211 topology = 1
Fri Jun 12 01:49:38 2020 us=607257 tun_ipv6 = DISABLED
Fri Jun 12 01:49:38 2020 us=607307 ifconfig_local = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=607354 ifconfig_remote_netmask = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=607403 ifconfig_noexec = DISABLED
Fri Jun 12 01:49:38 2020 us=607451 ifconfig_nowarn = DISABLED
Fri Jun 12 01:49:38 2020 us=607500 ifconfig_ipv6_local = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=607617 ifconfig_ipv6_netbits = 0
Fri Jun 12 01:49:38 2020 us=607690 ifconfig_ipv6_remote = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=607739 shaper = 0
Fri Jun 12 01:49:38 2020 us=607788 mtu_test = 0
Fri Jun 12 01:49:38 2020 us=607830 mlock = DISABLED
Fri Jun 12 01:49:38 2020 us=607877 keepalive_ping = 0
Fri Jun 12 01:49:38 2020 us=607922 keepalive_timeout = 0
Fri Jun 12 01:49:38 2020 us=607971 inactivity_timeout = 0
Fri Jun 12 01:49:38 2020 us=608015 [size=150]ping_send_timeout = 1800[/size] /* "ping" is set as 1800*/
Fri Jun 12 01:49:38 2020 us=608061 [size=150] ping_rec_timeout = 0 [/size] /* "ping-restart" is set as 0*/
Fri Jun 12 01:49:38 2020 us=608112 ping_rec_timeout_action = 2
Fri Jun 12 01:49:38 2020 us=608162 ping_timer_remote = DISABLED
Fri Jun 12 01:49:38 2020 us=608211 remap_sigusr1 = 0
Fri Jun 12 01:49:38 2020 us=608259 persist_tun = DISABLED
Fri Jun 12 01:49:38 2020 us=608307 persist_local_ip = DISABLED
Fri Jun 12 01:49:38 2020 us=608354 persist_remote_ip = DISABLED
Fri Jun 12 01:49:38 2020 us=608399 persist_key = DISABLED
Fri Jun 12 01:49:38 2020 us=608449 passtos = DISABLED
Fri Jun 12 01:49:38 2020 us=608493 resolve_retry_seconds = 1000000000
Fri Jun 12 01:49:38 2020 us=608687 username = 'lw'
Fri Jun 12 01:49:38 2020 us=608780 groupname = 'lw'
Fri Jun 12 01:49:38 2020 us=608833 chroot_dir = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=608878 cd_dir = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=608931 writepid = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=608979 up_script = '/etc/openvpn/update-resolv-conf'
Fri Jun 12 01:49:38 2020 us=609027 down_script = '/etc/openvpn/update-resolv-conf'
Fri Jun 12 01:49:38 2020 us=609076 down_pre = DISABLED
Fri Jun 12 01:49:38 2020 us=609119 up_restart = DISABLED
Fri Jun 12 01:49:38 2020 us=609164 up_delay = DISABLED
Fri Jun 12 01:49:38 2020 us=609213 daemon = DISABLED
Fri Jun 12 01:49:38 2020 us=609259 inetd = 0
Fri Jun 12 01:49:38 2020 us=609305 log = ENABLED
Fri Jun 12 01:49:38 2020 us=609351 suppress_timestamps = DISABLED
Fri Jun 12 01:49:38 2020 us=609399 nice = 0
Fri Jun 12 01:49:38 2020 us=609440 verbosity = 9
Fri Jun 12 01:49:38 2020 us=609475 mute = 0
Fri Jun 12 01:49:38 2020 us=609502 gremlin = 0
Fri Jun 12 01:49:38 2020 us=609593 status_file = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=609669 status_file_version = 1
Fri Jun 12 01:49:38 2020 us=609702 status_file_update_freq = 60
Fri Jun 12 01:49:38 2020 us=609737 occ = ENABLED
Fri Jun 12 01:49:38 2020 us=609768 rcvbuf = 0
Fri Jun 12 01:49:38 2020 us=609800 sndbuf = 0
Fri Jun 12 01:49:38 2020 us=609828 mark = 0
Fri Jun 12 01:49:38 2020 us=609857 sockflags = 0
Fri Jun 12 01:49:38 2020 us=609885 fast_io = DISABLED
Fri Jun 12 01:49:38 2020 us=609914 lzo = 0
Fri Jun 12 01:49:38 2020 us=609945 route_script = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=609974 route_default_gateway = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=610004 route_default_metric = 0
Fri Jun 12 01:49:38 2020 us=610034 route_noexec = DISABLED
Fri Jun 12 01:49:38 2020 us=610064 route_delay = 0
Fri Jun 12 01:49:38 2020 us=610094 route_delay_window = 30
Fri Jun 12 01:49:38 2020 us=610124 route_delay_defined = DISABLED
Fri Jun 12 01:49:38 2020 us=610154 route_nopull = DISABLED
Fri Jun 12 01:49:38 2020 us=610184 route_gateway_via_dhcp = DISABLED
Fri Jun 12 01:49:38 2020 us=610216 max_routes = 100
Fri Jun 12 01:49:38 2020 us=610246 allow_pull_fqdn = DISABLED
Fri Jun 12 01:49:38 2020 us=610277 management_addr = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=610308 management_port = 0
Fri Jun 12 01:49:38 2020 us=610338 management_user_pass = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=610369 management_log_history_cache = 250
Fri Jun 12 01:49:38 2020 us=610400 management_echo_buffer_size = 100
Fri Jun 12 01:49:38 2020 us=610428 management_write_peer_info_file = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=610460 management_client_user = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=610494 management_client_group = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=610699 management_flags = 0
Fri Jun 12 01:49:38 2020 us=610805 shared_secret_file = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=610843 key_direction = 2
Fri Jun 12 01:49:38 2020 us=610871 ciphername_defined = ENABLED
Fri Jun 12 01:49:38 2020 us=610906 ciphername = 'AES-256-CBC'
Fri Jun 12 01:49:38 2020 us=610939 authname_defined = ENABLED
Fri Jun 12 01:49:38 2020 us=610993 authname = 'SHA384'
Fri Jun 12 01:49:38 2020 us=611051 prng_hash = 'SHA1'
Fri Jun 12 01:49:38 2020 us=611093 prng_nonce_secret_len = 16
Fri Jun 12 01:49:38 2020 us=611127 keysize = 0
Fri Jun 12 01:49:38 2020 us=611170 engine = DISABLED
Fri Jun 12 01:49:38 2020 us=611209 replay = ENABLED
Fri Jun 12 01:49:38 2020 us=611253 mute_replay_warnings = DISABLED
Fri Jun 12 01:49:38 2020 us=611293 replay_window = 64
Fri Jun 12 01:49:38 2020 us=611341 replay_time = 15
Fri Jun 12 01:49:38 2020 us=611382 packet_id_file = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=611432 use_iv = ENABLED
Fri Jun 12 01:49:38 2020 us=611474 test_crypto = DISABLED
Fri Jun 12 01:49:38 2020 us=611516 tls_server = DISABLED
Fri Jun 12 01:49:38 2020 us=611561 tls_client = ENABLED
Fri Jun 12 01:49:38 2020 us=611886 key_method = 2
Fri Jun 12 01:49:38 2020 us=611971 ca_file = '[[INLINE]]'
Fri Jun 12 01:49:38 2020 us=612163 ca_path = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=612243 dh_file = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=612301 cert_file = '[[INLINE]]'
Fri Jun 12 01:49:38 2020 us=612356 extra_certs_file = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=612412 priv_key_file = '[[INLINE]]'
Fri Jun 12 01:49:38 2020 us=612463 pkcs12_file = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=612509 cipher_list = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=612700 tls_verify = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=612827 tls_export_cert = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=612896 verify_x509_type = 0
Fri Jun 12 01:49:38 2020 us=612965 verify_x509_name = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=613025 crl_file = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=613080 ns_cert_type = 0
Fri Jun 12 01:49:38 2020 us=613147 remote_cert_ku[i] = 160
Fri Jun 12 01:49:38 2020 us=613206 remote_cert_ku[i] = 136
Fri Jun 12 01:49:38 2020 us=613257 remote_cert_ku[i] = 0
Fri Jun 12 01:49:38 2020 us=613311 remote_cert_ku[i] = 0
Fri Jun 12 01:49:38 2020 us=613367 remote_cert_ku[i] = 0
Fri Jun 12 01:49:38 2020 us=613418 remote_cert_ku[i] = 0
Fri Jun 12 01:49:38 2020 us=613471 remote_cert_ku[i] = 0
Fri Jun 12 01:49:38 2020 us=613522 remote_cert_ku[i] = 0
Fri Jun 12 01:49:38 2020 us=613853 remote_cert_ku[i] = 0
Fri Jun 12 01:49:38 2020 us=614027 remote_cert_ku[i] = 0
Fri Jun 12 01:49:38 2020 us=614082 remote_cert_ku[i] = 0
Fri Jun 12 01:49:38 2020 us=614121 remote_cert_ku[i] = 0
Fri Jun 12 01:49:38 2020 us=614152 remote_cert_ku[i] = 0
Fri Jun 12 01:49:38 2020 us=614192 remote_cert_ku[i] = 0
Fri Jun 12 01:49:38 2020 us=614221 remote_cert_ku[i] = 0
Fri Jun 12 01:49:38 2020 us=614250 remote_cert_ku[i] = 0
Fri Jun 12 01:49:38 2020 us=614283 remote_cert_eku = 'TLS Web Server Authentication'
Fri Jun 12 01:49:38 2020 us=614315 ssl_flags = 0
Fri Jun 12 01:49:38 2020 us=614345 [size=150] tls_timeout = 2400 [/size] /* "tls-timeout" is set as 2400*/
Fri Jun 12 01:49:38 2020 us=614374 renegotiate_bytes = 0
Fri Jun 12 01:49:38 2020 us=614404 renegotiate_packets = 0
Fri Jun 12 01:49:38 2020 us=614434 renegotiate_seconds = 3600
Fri Jun 12 01:49:38 2020 us=614464 handshake_window = 60
Fri Jun 12 01:49:38 2020 us=614494 transition_window = 3600
Fri Jun 12 01:49:38 2020 us=614617 single_session = DISABLED
Fri Jun 12 01:49:38 2020 us=614691 push_peer_info = DISABLED
Fri Jun 12 01:49:38 2020 us=614722 tls_exit = DISABLED
Fri Jun 12 01:49:38 2020 us=614755 tls_auth_file = '[[INLINE]]'
Fri Jun 12 01:49:38 2020 us=614788 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=614819 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=614849 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=614879 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=614909 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=614939 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=614967 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=614995 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=615022 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=615047 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=615075 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=615108 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=615137 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=615170 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=615200 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=615230 pkcs11_protected_authentication = DISABLED
Fri Jun 12 01:49:38 2020 us=615261 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615292 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615322 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615351 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615381 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615443 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615476 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615506 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615606 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615651 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615683 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615713 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615743 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615773 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615825 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615859 pkcs11_private_mode = 00000000
Fri Jun 12 01:49:38 2020 us=615890 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=615917 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=615944 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=615975 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=616005 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=616039 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=616071 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=616100 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=616129 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=616158 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=616187 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=616216 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=616245 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=616274 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=616303 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=616331 pkcs11_cert_private = DISABLED
Fri Jun 12 01:49:38 2020 us=616363 pkcs11_pin_cache_period = -1
Fri Jun 12 01:49:38 2020 us=616393 pkcs11_id = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=616423 pkcs11_id_management = DISABLED
Fri Jun 12 01:49:38 2020 us=616459 server_network = 0.0.0.0
Fri Jun 12 01:49:38 2020 us=616492 server_netmask = 0.0.0.0
Fri Jun 12 01:49:38 2020 us=616605 server_network_ipv6 = ::
Fri Jun 12 01:49:38 2020 us=616664 server_netbits_ipv6 = 0
Fri Jun 12 01:49:38 2020 us=616699 server_bridge_ip = 0.0.0.0
Fri Jun 12 01:49:38 2020 us=616730 server_bridge_netmask = 0.0.0.0
Fri Jun 12 01:49:38 2020 us=616762 server_bridge_pool_start = 0.0.0.0
Fri Jun 12 01:49:38 2020 us=616793 server_bridge_pool_end = 0.0.0.0
Fri Jun 12 01:49:38 2020 us=616825 ifconfig_pool_defined = DISABLED
Fri Jun 12 01:49:38 2020 us=616862 ifconfig_pool_start = 0.0.0.0
Fri Jun 12 01:49:38 2020 us=616898 ifconfig_pool_end = 0.0.0.0
Fri Jun 12 01:49:38 2020 us=616932 ifconfig_pool_netmask = 0.0.0.0
Fri Jun 12 01:49:38 2020 us=616962 ifconfig_pool_persist_filename = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=616992 ifconfig_pool_persist_refresh_freq = 600
Fri Jun 12 01:49:38 2020 us=617023 ifconfig_ipv6_pool_defined = DISABLED
Fri Jun 12 01:49:38 2020 us=617055 ifconfig_ipv6_pool_base = ::
Fri Jun 12 01:49:38 2020 us=617086 ifconfig_ipv6_pool_netbits = 0
Fri Jun 12 01:49:38 2020 us=617116 n_bcast_buf = 256
Fri Jun 12 01:49:38 2020 us=617146 tcp_queue_limit = 64
Fri Jun 12 01:49:38 2020 us=617176 real_hash_size = 256
Fri Jun 12 01:49:38 2020 us=617206 virtual_hash_size = 256
Fri Jun 12 01:49:38 2020 us=617237 client_connect_script = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=617268 learn_address_script = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=617298 client_disconnect_script = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=617328 client_config_dir = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=617359 ccd_exclusive = DISABLED
Fri Jun 12 01:49:38 2020 us=617389 tmp_dir = '/tmp'
Fri Jun 12 01:49:38 2020 us=617418 push_ifconfig_defined = DISABLED
Fri Jun 12 01:49:38 2020 us=617451 push_ifconfig_local = 0.0.0.0
Fri Jun 12 01:49:38 2020 us=617483 push_ifconfig_remote_netmask = 0.0.0.0
Fri Jun 12 01:49:38 2020 us=617512 push_ifconfig_ipv6_defined = DISABLED
Fri Jun 12 01:49:38 2020 us=617637 push_ifconfig_ipv6_local = ::/0
Fri Jun 12 01:49:38 2020 us=617681 push_ifconfig_ipv6_remote = ::
Fri Jun 12 01:49:38 2020 us=617713 enable_c2c = DISABLED
Fri Jun 12 01:49:38 2020 us=617744 duplicate_cn = DISABLED
Fri Jun 12 01:49:38 2020 us=617778 cf_max = 0
Fri Jun 12 01:49:38 2020 us=617811 cf_per = 0
Fri Jun 12 01:49:38 2020 us=617841 max_clients = 1024
Fri Jun 12 01:49:38 2020 us=617873 max_routes_per_client = 256
Fri Jun 12 01:49:38 2020 us=617904 auth_user_pass_verify_script = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=617935 auth_user_pass_verify_script_via_file = DISABLED
Fri Jun 12 01:49:38 2020 us=617967 port_share_host = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=617997 port_share_port = 0
Fri Jun 12 01:49:38 2020 us=618028 client = ENABLED
Fri Jun 12 01:49:38 2020 us=618058 pull = ENABLED
Fri Jun 12 01:49:38 2020 us=618089 auth_user_pass_file = '[UNDEF]'
Fri Jun 12 01:49:38 2020 us=618127 OpenVPN 2.3.10 x86_64-pc-linux-gnu [SSL (OpenSSL)] [LZO] [EPOLL] [PKCS11] [MH] [IPv6] built on Jan 9 2019
Fri Jun 12 01:49:38 2020 us=618196 library versions: OpenSSL 1.0.2g 1 Mar 2016, LZO 2.08
Fri Jun 12 01:49:38 2020 us=618319 PKCS#11: pkcs11_initialize - entered
Fri Jun 12 01:49:38 2020 us=618636 PKCS#11: pkcs11_initialize - return 0-'CKR_OK'
Fri Jun 12 01:49:38 2020 us=618713 WARNING: --ping should normally be used with --ping-restart or --ping-exit
Fri Jun 12 01:49:38 2020 us=618746 WARNING: you are using user/group/chroot/setcon without persist-tun -- this may cause restarts to fail
Fri Jun 12 01:49:38 2020 us=618776 WARNING: you are using user/group/chroot/setcon without persist-key -- this may cause restarts to fail
Fri Jun 12 01:49:38 2020 us=618805 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Fri Jun 12 01:49:38 2020 us=618854 PO_INIT maxevents=4 flags=0x00000002
Fri Jun 12 01:49:38 2020 us=620464 PRNG init md=SHA1 size=36
Fri Jun 12 01:49:38 2020 us=620833 Control Channel Authentication: tls-auth using INLINE static key file
Fri Jun 12 01:49:38 2020 us=620935 Outgoing Control Channel Authentication: Using 384 bit message hash 'SHA384' for HMAC authentication
Fri Jun 12 01:49:38 2020 us=621012 Outgoing Control Channel Authentication: HMAC KEY: b065cfaa a43fda81 034e5a9b 5ad657f3 644db8c1 1b7e56ca 9b670252 bf00adf5 57ccc91e 6bbbbf15 d725ca1d 401209c7
Fri Jun 12 01:49:38 2020 us=621043 Outgoing Control Channel Authentication: HMAC size=48 block_size=48
Fri Jun 12 01:49:38 2020 us=621083 Incoming Control Channel Authentication: Using 384 bit message hash 'SHA384' for HMAC authentication
Fri Jun 12 01:49:38 2020 us=621151 Incoming Control Channel Authentication: HMAC KEY: bf9f3cd1 c04ec808 6a405a38 bdc3efe0 6869af6f 1094d420 6ab475a4 29a4dc75 fead84b5 2abfd96a ba05a2d1 0d6341c4
Fri Jun 12 01:49:38 2020 us=621187 Incoming Control Channel Authentication: HMAC size=48 block_size=48
Fri Jun 12 01:49:38 2020 us=621225 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 84 bytes
Fri Jun 12 01:49:38 2020 us=621259 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 56 bytes
Fri Jun 12 01:49:38 2020 us=621309 TLS: tls_session_init: entry
Fri Jun 12 01:49:38 2020 us=621351 PID packet_id_init tcp_mode=0 seq_backtrack=64 time_backtrack=15
Fri Jun 12 01:49:38 2020 us=621477 PID packet_id_init tcp_mode=0 seq_backtrack=64 time_backtrack=15
Fri Jun 12 01:49:38 2020 us=621564 TLS: tls_session_init: new session object, sid=472f66e6 ff7d0bae
Fri Jun 12 01:49:38 2020 us=621620 TLS: tls_session_init: entry
Fri Jun 12 01:49:38 2020 us=621658 PID packet_id_init tcp_mode=0 seq_backtrack=64 time_backtrack=15
Fri Jun 12 01:49:38 2020 us=621777 PID packet_id_init tcp_mode=0 seq_backtrack=64 time_backtrack=15
Fri Jun 12 01:49:38 2020 us=621825 TLS: tls_session_init: new session object, sid=d3cea09d 2068e985
Fri Jun 12 01:49:38 2020 us=621864 Control Channel MTU parms [ L:1585 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Fri Jun 12 01:49:38 2020 us=621930 MTU DYNAMIC mtu=1450, flags=2, 1585 -> 1450
Fri Jun 12 01:49:38 2020 us=622031 Socket Buffers: R=[212992->212992] S=[212992->212992]
Fri Jun 12 01:49:38 2020 us=622093 RESOLVE_REMOTE flags=0x0101 phase=1 rrs=0 sig=-1 status=0
Fri Jun 12 01:49:38 2020 us=622141 Data Channel MTU parms [ L:1585 D:1450 EF:85 EB:12 ET:0 EL:3 ]
Fri Jun 12 01:49:38 2020 us=622209 Local Options String: 'V4,dev-type tun,link-mtu 1585,tun-mtu 1500,proto UDPv4,keydir 1,cipher AES-256-CBC,auth SHA384,keysize 256,tls-auth,key-method 2,tls-client'
Fri Jun 12 01:49:38 2020 us=622243 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1585,tun-mtu 1500,proto UDPv4,keydir 0,cipher AES-256-CBC,auth SHA384,keysize 256,tls-auth,key-method 2,tls-server'
Fri Jun 12 01:49:38 2020 us=622318 Local Options hash (VER=V4): 'd11cd76f'
Fri Jun 12 01:49:38 2020 us=622387 Expected Remote Options hash (VER=V4): '8f4ffd01'
Fri Jun 12 01:49:38 2020 us=627766 NOTE: UID/GID downgrade will be delayed because of --client, --pull, or --up-delay
Fri Jun 12 01:49:38 2020 us=627883 UDPv4 link local: [undef]
Fri Jun 12 01:49:38 2020 us=627935 UDPv4 link remote: [AF_INET]192.168.0.6:1194
Fri Jun 12 01:49:38 2020 us=628025 TLS Warning: no data channel send key available: [key#0 state=S_INITIAL id=0 sid=00000000 00000000] [key#1 state=S_UNDEF id=0 sid=00000000 00000000] [key#2 state=S_UNDEF id=0 sid=00000000 00000000]
Fri Jun 12 01:49:38 2020 us=628062 SENT PING
Fri Jun 12 01:49:38 2020 us=628091 TIMER: coarse timer wakeup 1 seconds
Fri Jun 12 01:49:38 2020 us=628153 TLS: tls_multi_process: i=0 state=S_INITIAL, mysid=472f66e6 ff7d0bae, stored-sid=00000000 00000000, stored-ip=[AF_INET]192.168.0.6:1194
Fri Jun 12 01:49:38 2020 us=628189 TLS: tls_process: chg=0 ks=S_INITIAL lame=S_UNDEF to_link->len=0 wakeup=604800
Fri Jun 12 01:49:38 2020 us=628221 ACK mark active outgoing ID 0
Fri Jun 12 01:49:38 2020 us=628256 TLS: Initial Handshake, sid=472f66e6 ff7d0bae
Fri Jun 12 01:49:38 2020 us=628293 ACK reliable_can_send active=1 current=1 : [1] 0
Fri Jun 12 01:49:38 2020 us=628326 ACK reliable_send ID 0 (size=4 to=2400)
Fri Jun 12 01:49:38 2020 us=628376 Reliable -> TCP/UDP
Fri Jun 12 01:49:38 2020 us=628411 [size=150]ACK reliable_send_timeout 2400 [1] 0[/size]
Fri Jun 12 01:49:38 2020 us=628440 TLS: [size=150]tls_process: timeout set to 60[/size]
Fri Jun 12 01:49:38 2020 us=628486 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d3cea09d 2068e985, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:49:38 2020 us=628574 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:49:38 2020 us=628651 RANDOM USEC=142856
Fri Jun 12 01:49:38 2020 us=628689 PO_CTL rwflags=0x0003 ev=3 arg=0x5608fbc0d1a0
Fri Jun 12 01:49:38 2020 us=628758 I/O WAIT T?|T?|SR|SW [1/142856]
Fri Jun 12 01:49:38 2020 us=628827 PO_WAIT[0,0] fd=3 rev=0x00000004 rwflags=0x0002 arg=0x5608fbc0d1a0
Fri Jun 12 01:49:38 2020 us=628860 event_wait returned 1
Fri Jun 12 01:49:38 2020 us=628891 I/O WAIT status=0x0002
Fri Jun 12 01:49:38 2020 us=629010 UDPv4 WRITE [70] to [AF_INET]192.168.0.6:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 sid=472f66e6 ff7d0bae tls_hmac=17149635 3793d423 c918dd96 19513434 d9a623e0 bcd14f11 0d5b859c 92bb5e64 176aca9c 78db1c72 3dbfb112 0e7d6eb3 pid=[ #1 / time = (1591940978) Fri Jun 12 01:49:38 2020 ] [ ] pid=0 DATA
Fri Jun 12 01:49:38 2020 us=629283 UDPv4 write returned 70
Fri Jun 12 01:49:38 2020 us=629381 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=472f66e6 ff7d0bae, stored-sid=00000000 00000000, stored-ip=[AF_INET]192.168.0.6:1194
Fri Jun 12 01:49:38 2020 us=629441 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Fri Jun 12 01:49:38 2020 us=629479 ACK reliable_can_send active=1 current=0 : [1] 0
Fri Jun 12 01:49:38 2020 us=629693 SSL state (connect): before/connect initialization
Fri Jun 12 01:49:38 2020 us=629829 SSL state (connect): SSLv2/v3 write client hello A
Fri Jun 12 01:49:38 2020 us=629885 [size=150]ACK reliable_send_timeout 2400 [1] 0[/size]
Fri Jun 12 01:49:38 2020 us=629918 [size=150]TLS: tls_process: timeout set to 60[/size]
Fri Jun 12 01:49:38 2020 us=629997 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d3cea09d 2068e985, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:49:38 2020 us=630051 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:49:38 2020 us=630097 PO_CTL rwflags=0x0001 ev=3 arg=0x5608fbc0d1a0
Fri Jun 12 01:49:38 2020 us=630140 I/O WAIT T?|T?|SR|Sw [1/142856]
Fri Jun 12 01:49:39 2020 us=774820 event_wait returned 0
Fri Jun 12 01:49:39 2020 us=774959 I/O WAIT status=0x0020
Fri Jun 12 01:49:39 2020 us=775002 TIMER: coarse timer wakeup 1 seconds
Fri Jun 12 01:49:39 2020 us=775075 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=472f66e6 ff7d0bae, stored-sid=00000000 00000000, stored-ip=[AF_INET]192.168.0.6:1194
Fri Jun 12 01:49:39 2020 us=775115 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Fri Jun 12 01:49:39 2020 us=775150 ACK reliable_can_send active=1 current=0 : [1] 0
Fri Jun 12 01:49:39 2020 us=775214 [size=150]ACK reliable_send_timeout 2399 [1] 0[/size]
Fri Jun 12 01:49:39 2020 us=775245 [size=150]TLS: tls_process: timeout set to 59[/size]
Fri Jun 12 01:49:39 2020 us=775290 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d3cea09d 2068e985, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:49:39 2020 us=775334 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:49:39 2020 us=775376 PO_CTL rwflags=0x0001 ev=3 arg=0x5608fbc0d1a0
Fri Jun 12 01:49:39 2020 us=775420 I/O WAIT T?|T?|SR|Sw [1/142856]
Fri Jun 12 01:49:40 2020 us=924284 event_wait returned 0
Fri Jun 12 01:49:40 2020 us=924435 I/O WAIT status=0x0020
Fri Jun 12 01:49:40 2020 us=924495 TIMER: coarse timer wakeup 1 seconds
..........repeated part...........
Fri Jun 12 01:49:53 2020 us=438136 PO_CTL rwflags=0x0001 ev=3 arg=0x5608fbc0d1a0
Fri Jun 12 01:49:53 2020 us=438155 I/O WAIT T?|T?|SR|Sw [1/123046]
Fri Jun 12 01:49:54 2020 us=562545 event_wait returned 0
Fri Jun 12 01:49:54 2020 us=562611 I/O WAIT status=0x0020
Fri Jun 12 01:49:54 2020 us=562631 TIMER: coarse timer wakeup 1 seconds
Fri Jun 12 01:49:54 2020 us=562653 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=472f66e6 ff7d0bae, stored-sid=00000000 00000000, stored-ip=[AF_INET]192.168.0.6:1194
Fri Jun 12 01:49:54 2020 us=562663 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Fri Jun 12 01:49:54 2020 us=562671 ACK reliable_can_send active=1 current=0 : [1] 0
Fri Jun 12 01:49:54 2020 us=562691 [size=150]ACK reliable_send_timeout 2384 [1] 0[/size]
Fri Jun 12 01:49:54 2020 us=562703 [size=150]TLS: tls_process: timeout set to 44[/size]
Fri Jun 12 01:49:54 2020 us=562714 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d3cea09d 2068e985, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:49:54 2020 us=562724 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:49:54 2020 us=562735 PO_CTL rwflags=0x0001 ev=3 arg=0x5608fbc0d1a0
Fri Jun 12 01:49:54 2020 us=562745 I/O WAIT T?|T?|SR|Sw [1/123046]
Fri Jun 12 01:49:55 2020 us=687631 event_wait returned 0
Fri Jun 12 01:49:55 2020 us=687702 I/O WAIT status=0x0020
Fri Jun 12 01:49:55 2020 us=687721 TIMER: coarse timer wakeup 1 seconds
................repeated part.....................
Fri Jun 12 01:50:07 2020 us=784593 PO_CTL rwflags=0x0001 ev=3 arg=0x5608fbc0d1a0
Fri Jun 12 01:50:07 2020 us=784611 I/O WAIT T?|T?|SR|Sw [1/243108]
Fri Jun 12 01:50:09 2020 us=29468 event_wait returned 0
Fri Jun 12 01:50:09 2020 us=29573 I/O WAIT status=0x0020
Fri Jun 12 01:50:09 2020 us=29601 TIMER: coarse timer wakeup 1 seconds
Fri Jun 12 01:50:09 2020 us=29633 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=472f66e6 ff7d0bae, stored-sid=00000000 00000000, stored-ip=[AF_INET]192.168.0.6:1194
Fri Jun 12 01:50:09 2020 us=29650 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Fri Jun 12 01:50:09 2020 us=29662 ACK reliable_can_send active=1 current=0 : [1] 0
Fri Jun 12 01:50:09 2020 us=29766 [size=150]ACK reliable_send_timeout 2369 [1] 0[/size]
Fri Jun 12 01:50:09 2020 us=29801 [size=150]TLS: tls_process: timeout set to 29[/size]
Fri Jun 12 01:50:09 2020 us=29819 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d3cea09d 2068e985, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:50:09 2020 us=29835 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:50:09 2020 us=29853 RANDOM USEC=151341
Fri Jun 12 01:50:09 2020 us=29866 PO_CTL rwflags=0x0001 ev=3 arg=0x5608fbc0d1a0
Fri Jun 12 01:50:09 2020 us=29882 I/O WAIT T?|T?|SR|Sw [1/151341]
Fri Jun 12 01:50:10 2020 us=182424 event_wait returned 0
Fri Jun 12 01:50:10 2020 us=182490 I/O WAIT status=0x0020
Fri Jun 12 01:50:10 2020 us=182503 TIMER: coarse timer wakeup 1 seconds
...............repeated part......................
Fri Jun 12 01:50:23 2020 us=139606 PO_CTL rwflags=0x0001 ev=3 arg=0x5608fbc0d1a0
Fri Jun 12 01:50:23 2020 us=139652 I/O WAIT T?|T?|SR|Sw [1/240534]
Fri Jun 12 01:50:24 2020 us=396246 event_wait returned 0
Fri Jun 12 01:50:24 2020 us=396346 I/O WAIT status=0x0020
Fri Jun 12 01:50:24 2020 us=396374 TIMER: coarse timer wakeup 1 seconds
Fri Jun 12 01:50:24 2020 us=396412 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=472f66e6 ff7d0bae, stored-sid=00000000 00000000, stored-ip=[AF_INET]192.168.0.6:1194
Fri Jun 12 01:50:24 2020 us=396431 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Fri Jun 12 01:50:24 2020 us=396446 ACK reliable_can_send active=1 current=0 : [1] 0
Fri Jun 12 01:50:24 2020 us=396506 [size=150]ACK reliable_send_timeout 2354 [1] 0[/size]
Fri Jun 12 01:50:24 2020 us=396521 [size=150]TLS: tls_process: timeout set to 14[/size]
Fri Jun 12 01:50:24 2020 us=396538 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d3cea09d 2068e985, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:50:24 2020 us=396555 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:50:24 2020 us=396571 PO_CTL rwflags=0x0001 ev=3 arg=0x5608fbc0d1a0
Fri Jun 12 01:50:24 2020 us=396588 I/O WAIT T?|T?|SR|Sw [1/240534]
Fri Jun 12 01:50:25 2020 us=640420 event_wait returned 0
Fri Jun 12 01:50:25 2020 us=640503 I/O WAIT status=0x0020
Fri Jun 12 01:50:25 2020 us=640519 TIMER: coarse timer wakeup 1 seconds
................repeated part.................
Fri Jun 12 01:50:37 2020 us=330131 PO_CTL rwflags=0x0001 ev=3 arg=0x5608fbc0d1a0
Fri Jun 12 01:50:37 2020 us=330143 I/O WAIT T?|T?|SR|Sw [1/130721]
Fri Jun 12 01:50:38 2020 us=463106 event_wait returned 0
Fri Jun 12 01:50:38 2020 us=463175 I/O WAIT status=0x0020
Fri Jun 12 01:50:38 2020 us=463194 TIMER: coarse timer wakeup 1 seconds
Fri Jun 12 01:50:38 2020 us=463227 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=472f66e6 ff7d0bae, stored-sid=00000000 00000000, stored-ip=[AF_INET]192.168.0.6:1194
Fri Jun 12 01:50:38 2020 us=463243 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Fri Jun 12 01:50:38 2020 us=463254 [size=150]TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)[/size]
Fri Jun 12 01:50:38 2020 us=463266 [size=150]TLS Error: TLS handshake failed[/size]
Fri Jun 12 01:50:38 2020 us=463275 PID packet_id_free
Fri Jun 12 01:50:38 2020 us=463342 PID packet_id_free
Fri Jun 12 01:50:38 2020 us=463356 PID packet_id_free
Fri Jun 12 01:50:38 2020 us=463368 TLS: tls_session_init: entry
Fri Jun 12 01:50:38 2020 us=463387 PID packet_id_init tcp_mode=0 seq_backtrack=64 time_backtrack=15
Fri Jun 12 01:50:38 2020 us=463416 PID packet_id_init tcp_mode=0 seq_backtrack=64 time_backtrack=15
Fri Jun 12 01:50:38 2020 us=463429 TLS: tls_session_init: new session object, sid=69a69de2 7afd3783
Fri Jun 12 01:50:38 2020 us=463444 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=d3cea09d 2068e985, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:50:38 2020 us=463458 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:50:38 2020 us=463473 PID packet_id_free
Fri Jun 12 01:50:38 2020 us=463489 PID packet_id_free
Fri Jun 12 01:50:38 2020 us=463498 PID packet_id_free
Fri Jun 12 01:50:38 2020 us=463507 PID packet_id_free
Fri Jun 12 01:50:38 2020 us=463522 PID packet_id_free
Fri Jun 12 01:50:38 2020 us=463531 PID packet_id_free
Fri Jun 12 01:50:38 2020 us=463540 PID packet_id_free
Fri Jun 12 01:50:38 2020 us=463548 PID packet_id_free
Fri Jun 12 01:50:38 2020 us=463603 TCP/UDP: Closing socket
Fri Jun 12 01:50:38 2020 us=463634 PID packet_id_free
Fri Jun 12 01:50:38 2020 us=463648 SIGUSR1[soft,tls-error] received, process restarting
Fri Jun 12 01:50:38 2020 us=463666 Restart pause, 2 second(s)
Fri Jun 12 01:50:40 2020 us=464185 WARNING: --ping should normally be used with --ping-restart or --ping-exit
Fri Jun 12 01:50:40 2020 us=464270 WARNING: you are using user/group/chroot/setcon without persist-tun -- this may cause restarts to fail
Fri Jun 12 01:50:40 2020 us=464291 WARNING: you are using user/group/chroot/setcon without persist-key -- this may cause restarts to fail
Fri Jun 12 01:50:40 2020 us=464304 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Fri Jun 12 01:50:40 2020 us=464325 PO_INIT maxevents=4 flags=0x00000002
Fri Jun 12 01:50:40 2020 us=464706 PRNG init md=SHA1 size=36
Fri Jun 12 01:50:40 2020 us=464806 Control Channel Authentication: tls-auth using INLINE static key file
Fri Jun 12 01:50:40 2020 us=464826 Outgoing Control Channel Authentication: Using 384 bit message hash 'SHA384' for HMAC authentication
Fri Jun 12 01:50:40 2020 us=464850 Outgoing Control Channel Authentication: HMAC KEY: b065cfaa a43fda81 034e5a9b 5ad657f3 644db8c1 1b7e56ca 9b670252 bf00adf5 57ccc91e 6bbbbf15 d725ca1d 401209c7
Fri Jun 12 01:50:40 2020 us=464860 Outgoing Control Channel Authentication: HMAC size=48 block_size=48
Fri Jun 12 01:50:40 2020 us=464873 Incoming Control Channel Authentication: Using 384 bit message hash 'SHA384' for HMAC authentication
Fri Jun 12 01:50:40 2020 us=464895 Incoming Control Channel Authentication: HMAC KEY: bf9f3cd1 c04ec808 6a405a38 bdc3efe0 6869af6f 1094d420 6ab475a4 29a4dc75 fead84b5 2abfd96a ba05a2d1 0d6341c4
Fri Jun 12 01:50:40 2020 us=464903 Incoming Control Channel Authentication: HMAC size=48 block_size=48
Fri Jun 12 01:50:40 2020 us=464934 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 84 bytes
Fri Jun 12 01:50:40 2020 us=464946 crypto_adjust_frame_parameters: Adjusting frame parameters for crypto by 56 bytes
Fri Jun 12 01:50:40 2020 us=464957 TLS: tls_session_init: entry
Fri Jun 12 01:50:40 2020 us=464969 PID packet_id_init tcp_mode=0 seq_backtrack=64 time_backtrack=15
Fri Jun 12 01:50:40 2020 us=464999 PID packet_id_init tcp_mode=0 seq_backtrack=64 time_backtrack=15
Fri Jun 12 01:50:40 2020 us=465012 TLS: tls_session_init: new session object, sid=32e9ceff 0a645a79
Fri Jun 12 01:50:40 2020 us=465022 TLS: tls_session_init: entry
Fri Jun 12 01:50:40 2020 us=465032 PID packet_id_init tcp_mode=0 seq_backtrack=64 time_backtrack=15
Fri Jun 12 01:50:40 2020 us=465066 PID packet_id_init tcp_mode=0 seq_backtrack=64 time_backtrack=15
Fri Jun 12 01:50:40 2020 us=465177 TLS: tls_session_init: new session object, sid=fae3aac9 17bcb793
Fri Jun 12 01:50:40 2020 us=465198 Control Channel MTU parms [ L:1585 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Fri Jun 12 01:50:40 2020 us=465214 MTU DYNAMIC mtu=1450, flags=2, 1585 -> 1450
Fri Jun 12 01:50:40 2020 us=465253 Socket Buffers: R=[212992->212992] S=[212992->212992]
Fri Jun 12 01:50:40 2020 us=465267 RESOLVE_REMOTE flags=0x0101 phase=1 rrs=0 sig=-1 status=0
Fri Jun 12 01:50:40 2020 us=465283 Data Channel MTU parms [ L:1585 D:1450 EF:85 EB:12 ET:0 EL:3 ]
Fri Jun 12 01:50:40 2020 us=465310 Local Options String: 'V4,dev-type tun,link-mtu 1585,tun-mtu 1500,proto UDPv4,keydir 1,cipher AES-256-CBC,auth SHA384,keysize 256,tls-auth,key-method 2,tls-client'
Fri Jun 12 01:50:40 2020 us=465321 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1585,tun-mtu 1500,proto UDPv4,keydir 0,cipher AES-256-CBC,auth SHA384,keysize 256,tls-auth,key-method 2,tls-server'
Fri Jun 12 01:50:40 2020 us=465348 Local Options hash (VER=V4): 'd11cd76f'
Fri Jun 12 01:50:40 2020 us=465374 Expected Remote Options hash (VER=V4): '8f4ffd01'
Fri Jun 12 01:50:40 2020 us=465388 UDPv4 link local: [undef]
Fri Jun 12 01:50:40 2020 us=465410 UDPv4 link remote: [AF_INET]192.168.0.6:1194
Fri Jun 12 01:50:40 2020 us=465438 TLS Warning: no data channel send key available: [key#0 state=S_INITIAL id=0 sid=00000000 00000000] [key#1 state=S_UNDEF id=0 sid=00000000 00000000] [key#2 state=S_UNDEF id=0 sid=00000000 00000000]
Fri Jun 12 01:50:40 2020 us=465449 SENT PING
Fri Jun 12 01:50:40 2020 us=465457 TIMER: coarse timer wakeup 1 seconds
Fri Jun 12 01:50:40 2020 us=465475 TLS: tls_multi_process: i=0 state=S_INITIAL, mysid=32e9ceff 0a645a79, stored-sid=00000000 00000000, stored-ip=[AF_INET]192.168.0.6:1194
Fri Jun 12 01:50:40 2020 us=465486 TLS: tls_process: chg=0 ks=S_INITIAL lame=S_UNDEF to_link->len=0 wakeup=604800
Fri Jun 12 01:50:40 2020 us=465497 ACK mark active outgoing ID 0
Fri Jun 12 01:50:40 2020 us=465508 TLS: Initial Handshake, sid=32e9ceff 0a645a79
Fri Jun 12 01:50:40 2020 us=465518 ACK reliable_can_send active=1 current=1 : [1] 0
Fri Jun 12 01:50:40 2020 us=465527 ACK reliable_send ID 0 (size=4 to=2400)
Fri Jun 12 01:50:40 2020 us=465540 Reliable -> TCP/UDP
Fri Jun 12 01:50:40 2020 us=465550 [size=150]ACK reliable_send_timeout 2400 [1] 0[/size]
Fri Jun 12 01:50:40 2020 us=465560 [size=150]TLS: tls_process: timeout set to 60[/size]
Fri Jun 12 01:50:40 2020 us=465574 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=fae3aac9 17bcb793, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:50:40 2020 us=465587 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:50:40 2020 us=465599 RANDOM USEC=197798
Fri Jun 12 01:50:40 2020 us=465610 PO_CTL rwflags=0x0003 ev=3 arg=0x5608fbc0d1a0
Fri Jun 12 01:50:40 2020 us=465622 I/O WAIT T?|T?|SR|SW [1/197798]
Fri Jun 12 01:50:40 2020 us=465635 PO_WAIT[0,0] fd=3 rev=0x00000004 rwflags=0x0002 arg=0x5608fbc0d1a0
Fri Jun 12 01:50:40 2020 us=465645 event_wait returned 1
Fri Jun 12 01:50:40 2020 us=465653 I/O WAIT status=0x0002
Fri Jun 12 01:50:40 2020 us=465689 UDPv4 WRITE [70] to [AF_INET]192.168.0.6:1194: P_CONTROL_HARD_RESET_CLIENT_V2 kid=0 sid=32e9ceff 0a645a79 tls_hmac=6d67287c 861df105 f76b74de 72308868 881a6991 7ad3e8f9 04528565 9ed1a60b 804298a7 07fe8faf 49ca5890 f76f7e1c pid=[ #1 / time = (1591941040) Fri Jun 12 01:50:40 2020 ] [ ] pid=0 DATA
Fri Jun 12 01:50:40 2020 us=465830 UDPv4 write returned 70
Fri Jun 12 01:50:40 2020 us=465875 TLS: tls_multi_process: i=0 state=S_PRE_START, mysid=32e9ceff 0a645a79, stored-sid=00000000 00000000, stored-ip=[AF_INET]192.168.0.6:1194
Fri Jun 12 01:50:40 2020 us=465892 TLS: tls_process: chg=0 ks=S_PRE_START lame=S_UNDEF to_link->len=0 wakeup=604800
Fri Jun 12 01:50:40 2020 us=465904 ACK reliable_can_send active=1 current=0 : [1] 0
Fri Jun 12 01:50:40 2020 us=465960 SSL state (connect): before/connect initialization
Fri Jun 12 01:50:40 2020 us=465991 SSL state (connect): SSLv2/v3 write client hello A
Fri Jun 12 01:50:40 2020 us=466005 [size=150]ACK reliable_send_timeout 2400 [1] 0[/size]
Fri Jun 12 01:50:40 2020 us=466015 [size=150]TLS: tls_process: timeout set to 60[/size]
Fri Jun 12 01:50:40 2020 us=466030 TLS: tls_multi_process: i=1 state=S_INITIAL, mysid=fae3aac9 17bcb793, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:50:40 2020 us=466045 TLS: tls_multi_process: i=2 state=S_UNDEF, mysid=00000000 00000000, stored-sid=00000000 00000000, stored-ip=[undef]
Fri Jun 12 01:50:40 2020 us=466119 PO_CTL rwflags=0x0001 ev=3 arg=0x5608fbc0d1a0
The client config file contains " tls-timeout 2400 ping-restart 0 ping 1800 ", and these values are shown in the log file (please see the content in large font). However, during runtime, I can see there are two values related with TLS timeout, " reliable_send_timeout " and " tls_process " (please see the content in large font). The " tls-timeout "(set as 2400) in the client config file actually is the " reliable_send_timeout " value in the log file. Actually, the " tls_process " value also decides the TLS timeout (please see the "tls_process" value change process in large font in the client log).
My question is, in order to extend TLS process time for client side, what should I do? Or, how could I config the "tls_process" value (shown in the client log file)? By which flag in the configuration file or command line option? Thank you for your time and help!
