Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

argyrg · Post by **argyrg** » Thu Mar 07, 2019 12:09 pm

OK, now that I've caught your attention...

I have a fairly simple setup for testing purposes. I installed my server using the script put together by angristan on git-hub on a Debian Stretch cloud VPS and I have a raspberry pi 3B+ as my client. I connect to the internet by associating the wireless LAN on my Pi with the mobile hotspot using my Galaxy S6 which is on the Vodafone UK network.

Everything works fine until my hotspot drops when I switch it off, which I do to test if the vpn connection will stay alive or reconnect when the link comes back up. The Pi manages to automatically connect with the hotspot but the vpn stays down and produces the following output (I had to remove a few repeated lines to get it all in...)

My issue is that keepalive clearly doesn't work in the way that I think it should and my question is "Is there some way to fix this?"

Thanks in anticipation

Code: Select all

Thu Mar  7 11:50:40 2019 us=455287 Current Parameter Settings:
Thu Mar  7 11:50:40 2019 us=455482   config = '/etc/openvpn/russell.ovpn'
Thu Mar  7 11:50:40 2019 us=455540   mode = 0
Thu Mar  7 11:50:40 2019 us=455584   persist_config = DISABLED
Thu Mar  7 11:50:40 2019 us=455673   persist_mode = 1
Thu Mar  7 11:50:40 2019 us=455719   show_ciphers = DISABLED
Thu Mar  7 11:50:40 2019 us=455764   show_digests = DISABLED
Thu Mar  7 11:50:40 2019 us=455809   show_engines = DISABLED
Thu Mar  7 11:50:40 2019 us=455853   genkey = DISABLED
Thu Mar  7 11:50:40 2019 us=455898   key_pass_file = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=455943   show_tls_ciphers = DISABLED
Thu Mar  7 11:50:40 2019 us=455987   connect_retry_max = 0
Thu Mar  7 11:50:40 2019 us=456033 Connection profiles [0]:
Thu Mar  7 11:50:40 2019 us=456079   proto = udp
Thu Mar  7 11:50:40 2019 us=456124   local = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=456168   local_port = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=456211   remote = '159.69.7.156'
Thu Mar  7 11:50:40 2019 us=456255   remote_port = '1194'
Thu Mar  7 11:50:40 2019 us=456299   remote_float = DISABLED
Thu Mar  7 11:50:40 2019 us=456343   bind_defined = DISABLED
Thu Mar  7 11:50:40 2019 us=456387   bind_local = DISABLED
Thu Mar  7 11:50:40 2019 us=456431   bind_ipv6_only = DISABLED
Thu Mar  7 11:50:40 2019 us=456475   connect_retry_seconds = 5
Thu Mar  7 11:50:40 2019 us=456544   connect_timeout = 120
Thu Mar  7 11:50:40 2019 us=456589   socks_proxy_server = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=456636   socks_proxy_port = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=456680   tun_mtu = 1500
Thu Mar  7 11:50:40 2019 us=456723   tun_mtu_defined = ENABLED
Thu Mar  7 11:50:40 2019 us=456779   link_mtu = 1500
Thu Mar  7 11:50:40 2019 us=456822   link_mtu_defined = DISABLED
Thu Mar  7 11:50:40 2019 us=456869   tun_mtu_extra = 0
Thu Mar  7 11:50:40 2019 us=456911   tun_mtu_extra_defined = DISABLED
Thu Mar  7 11:50:40 2019 us=456955   mtu_discover_type = -1
Thu Mar  7 11:50:40 2019 us=457000   fragment = 0
Thu Mar  7 11:50:40 2019 us=457044   mssfix = 1450
Thu Mar  7 11:50:40 2019 us=457088   explicit_exit_notification = 0
Thu Mar  7 11:50:40 2019 us=457132 Connection profiles END
Thu Mar  7 11:50:40 2019 us=457176   remote_random = DISABLED
Thu Mar  7 11:50:40 2019 us=457219   ipchange = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=457263   dev = 'tun'
Thu Mar  7 11:50:40 2019 us=457306   dev_type = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=457349   dev_node = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=457392   lladdr = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=457436   topology = 1
Thu Mar  7 11:50:40 2019 us=457479   ifconfig_local = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=457524   ifconfig_remote_netmask = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=457569   ifconfig_noexec = DISABLED
Thu Mar  7 11:50:40 2019 us=457612   ifconfig_nowarn = DISABLED
Thu Mar  7 11:50:40 2019 us=457657   ifconfig_ipv6_local = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=457701   ifconfig_ipv6_netbits = 0
Thu Mar  7 11:50:40 2019 us=457747   ifconfig_ipv6_remote = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=457791   shaper = 0
Thu Mar  7 11:50:40 2019 us=457835   mtu_test = 0
Thu Mar  7 11:50:40 2019 us=457880   mlock = DISABLED
Thu Mar  7 11:50:40 2019 us=457923   keepalive_ping = 0
Thu Mar  7 11:50:40 2019 us=457968   keepalive_timeout = 0
Thu Mar  7 11:50:40 2019 us=458013   inactivity_timeout = 0
Thu Mar  7 11:50:40 2019 us=458057   ping_send_timeout = 0
Thu Mar  7 11:50:40 2019 us=458101   ping_rec_timeout = 0
Thu Mar  7 11:50:40 2019 us=458146   ping_rec_timeout_action = 0
Thu Mar  7 11:50:40 2019 us=458190   ping_timer_remote = DISABLED
Thu Mar  7 11:50:40 2019 us=458235   remap_sigusr1 = 0
Thu Mar  7 11:50:40 2019 us=458280   persist_tun = ENABLED
Thu Mar  7 11:50:40 2019 us=458324   persist_local_ip = DISABLED
Thu Mar  7 11:50:40 2019 us=458369   persist_remote_ip = DISABLED
Thu Mar  7 11:50:40 2019 us=458413   persist_key = ENABLED
Thu Mar  7 11:50:40 2019 us=458456   passtos = DISABLED
Thu Mar  7 11:50:40 2019 us=458521   resolve_retry_seconds = 1000000000
Thu Mar  7 11:50:40 2019 us=458566   resolve_in_advance = DISABLED
Thu Mar  7 11:50:40 2019 us=458609   username = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=458654   groupname = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=458696   chroot_dir = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=458738   cd_dir = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=458789   writepid = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=458832   up_script = '/etc/openvpn/update-resolv-conf'
Thu Mar  7 11:50:40 2019 us=458875   down_script = '/etc/openvpn/update-resolv-conf'
Thu Mar  7 11:50:40 2019 us=458918   down_pre = DISABLED
Thu Mar  7 11:50:40 2019 us=458960   up_restart = DISABLED
Thu Mar  7 11:50:40 2019 us=459002   up_delay = DISABLED
Thu Mar  7 11:50:40 2019 us=459044   daemon = DISABLED
Thu Mar  7 11:50:40 2019 us=459087   inetd = 0
Thu Mar  7 11:50:40 2019 us=459128   log = DISABLED
Thu Mar  7 11:50:40 2019 us=459170   suppress_timestamps = DISABLED
Thu Mar  7 11:50:40 2019 us=459213   machine_readable_output = DISABLED
Thu Mar  7 11:50:40 2019 us=459255   nice = 0
Thu Mar  7 11:50:40 2019 us=459297   verbosity = 4
Thu Mar  7 11:50:40 2019 us=459339   mute = 0
Thu Mar  7 11:50:40 2019 us=459380   gremlin = 0
Thu Mar  7 11:50:40 2019 us=459421   status_file = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=459464   status_file_version = 1
Thu Mar  7 11:50:40 2019 us=459506   status_file_update_freq = 60
Thu Mar  7 11:50:40 2019 us=459549   occ = ENABLED
Thu Mar  7 11:50:40 2019 us=459591   rcvbuf = 0
Thu Mar  7 11:50:40 2019 us=459633   sndbuf = 0
Thu Mar  7 11:50:40 2019 us=459674   mark = 0
Thu Mar  7 11:50:40 2019 us=459715   sockflags = 0
Thu Mar  7 11:50:40 2019 us=459756   fast_io = DISABLED
Thu Mar  7 11:50:40 2019 us=459798   comp.alg = 0
Thu Mar  7 11:50:40 2019 us=459840   comp.flags = 0
Thu Mar  7 11:50:40 2019 us=459881   route_script = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=459924   route_default_gateway = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=459967   route_default_metric = 0
Thu Mar  7 11:50:40 2019 us=460010   route_noexec = DISABLED
Thu Mar  7 11:50:40 2019 us=460053   route_delay = 0
Thu Mar  7 11:50:40 2019 us=460095   route_delay_window = 30
Thu Mar  7 11:50:40 2019 us=460137   route_delay_defined = DISABLED
Thu Mar  7 11:50:40 2019 us=460180   route_nopull = DISABLED
Thu Mar  7 11:50:40 2019 us=460222   route_gateway_via_dhcp = DISABLED
Thu Mar  7 11:50:40 2019 us=460265   allow_pull_fqdn = DISABLED
Thu Mar  7 11:50:40 2019 us=460310   management_addr = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=460353   management_port = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=460396   management_user_pass = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=460440   management_log_history_cache = 250
Thu Mar  7 11:50:40 2019 us=460483   management_echo_buffer_size = 100
Thu Mar  7 11:50:40 2019 us=460527   management_write_peer_info_file = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=460570   management_client_user = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=460613   management_client_group = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=460657   management_flags = 0
Thu Mar  7 11:50:40 2019 us=460700   shared_secret_file = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=460743   key_direction = 0
Thu Mar  7 11:50:40 2019 us=460786   ciphername = 'AES-128-GCM'
Thu Mar  7 11:50:40 2019 us=460828   ncp_enabled = ENABLED
Thu Mar  7 11:50:40 2019 us=460872   ncp_ciphers = 'AES-256-GCM:AES-128-GCM'
Thu Mar  7 11:50:40 2019 us=460915   authname = 'SHA256'
Thu Mar  7 11:50:40 2019 us=460957   prng_hash = 'SHA1'
Thu Mar  7 11:50:40 2019 us=461000   prng_nonce_secret_len = 16
Thu Mar  7 11:50:40 2019 us=461042   keysize = 0
Thu Mar  7 11:50:40 2019 us=461084   engine = DISABLED
Thu Mar  7 11:50:40 2019 us=461127   replay = ENABLED
Thu Mar  7 11:50:40 2019 us=461169   mute_replay_warnings = DISABLED
Thu Mar  7 11:50:40 2019 us=461212   replay_window = 64
Thu Mar  7 11:50:40 2019 us=461255   replay_time = 15
Thu Mar  7 11:50:40 2019 us=461297   packet_id_file = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=461340   use_iv = ENABLED
Thu Mar  7 11:50:40 2019 us=461383   test_crypto = DISABLED
Thu Mar  7 11:50:40 2019 us=461426   tls_server = DISABLED
Thu Mar  7 11:50:40 2019 us=461467   tls_client = ENABLED
Thu Mar  7 11:50:40 2019 us=461509   key_method = 2
Thu Mar  7 11:50:40 2019 us=461550   ca_file = '[[INLINE]]'
Thu Mar  7 11:50:40 2019 us=461591   ca_path = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=461633   dh_file = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=461673   cert_file = '[[INLINE]]'
Thu Mar  7 11:50:40 2019 us=461714   extra_certs_file = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=461756   priv_key_file = '[[INLINE]]'
Thu Mar  7 11:50:40 2019 us=461825   pkcs12_file = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=461868   cipher_list = 'TLS-ECDHE-ECDSA-WITH-AES-128-GCM-SHA256'
Thu Mar  7 11:50:40 2019 us=461912   tls_verify = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=461953   tls_export_cert = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=461997   verify_x509_type = 2
Thu Mar  7 11:50:40 2019 us=462039   verify_x509_name = 'server_baOUcI0m0z2674zq'
Thu Mar  7 11:50:40 2019 us=462081   crl_file = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=462123   ns_cert_type = 0
Thu Mar  7 11:50:40 2019 us=462164   remote_cert_ku[i] = 160
Thu Mar  7 11:50:40 2019 us=462206   remote_cert_ku[i] = 136
Thu Mar  7 11:50:40 2019 us=462248   remote_cert_ku[i] = 0
Thu Mar  7 11:50:40 2019 us=462289   remote_cert_ku[i] = 0
Thu Mar  7 11:50:40 2019 us=462330   remote_cert_ku[i] = 0
Thu Mar  7 11:50:40 2019 us=462372   remote_cert_ku[i] = 0
Thu Mar  7 11:50:40 2019 us=462413   remote_cert_ku[i] = 0
Thu Mar  7 11:50:40 2019 us=462454   remote_cert_ku[i] = 0
Thu Mar  7 11:50:40 2019 us=462496   remote_cert_ku[i] = 0
Thu Mar  7 11:50:40 2019 us=462537   remote_cert_ku[i] = 0
Thu Mar  7 11:50:40 2019 us=462579   remote_cert_ku[i] = 0
Thu Mar  7 11:50:40 2019 us=462621   remote_cert_ku[i] = 0
Thu Mar  7 11:50:40 2019 us=462662   remote_cert_ku[i] = 0
Thu Mar  7 11:50:40 2019 us=462702   remote_cert_ku[i] = 0
Thu Mar  7 11:50:40 2019 us=462744   remote_cert_ku[i] = 0
Thu Mar  7 11:50:40 2019 us=462786   remote_cert_ku[i] = 0
Thu Mar  7 11:50:40 2019 us=462828   remote_cert_eku = 'TLS Web Server Authentication'
Thu Mar  7 11:50:40 2019 us=462871   ssl_flags = 192
Thu Mar  7 11:50:40 2019 us=462912   tls_timeout = 2
Thu Mar  7 11:50:40 2019 us=462953   renegotiate_bytes = -1
Thu Mar  7 11:50:40 2019 us=462994   renegotiate_packets = 0
Thu Mar  7 11:50:40 2019 us=463035   renegotiate_seconds = 3600
Thu Mar  7 11:50:40 2019 us=463078   handshake_window = 60
Thu Mar  7 11:50:40 2019 us=463120   transition_window = 3600
Thu Mar  7 11:50:40 2019 us=463161   single_session = DISABLED
Thu Mar  7 11:50:40 2019 us=463202   push_peer_info = DISABLED
Thu Mar  7 11:50:40 2019 us=463243   tls_exit = DISABLED
Thu Mar  7 11:50:40 2019 us=463286   tls_auth_file = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=463327   tls_crypt_file = '[[INLINE]]'
Thu Mar  7 11:50:40 2019 us=463369   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=463411   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=463453   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=463496   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=463539   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=463580   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=463623   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=463666   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=463708   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=463750   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=463792   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=463833   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=463875   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=463917   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=463958   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=464002   pkcs11_protected_authentication = DISABLED
Thu Mar  7 11:50:40 2019 us=464046   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464088   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464130   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464172   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464214   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464256   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464299   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464341   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464383   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464425   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464467   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464520   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464562   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464604   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464648   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464690   pkcs11_private_mode = 00000000
Thu Mar  7 11:50:40 2019 us=464731   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=464782   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=464823   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=464865   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=464907   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=464949   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=464990   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=465032   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=465074   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=465115   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=465158   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=465200   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=465241   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=465283   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=465325   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=465368   pkcs11_cert_private = DISABLED
Thu Mar  7 11:50:40 2019 us=465410   pkcs11_pin_cache_period = -1
Thu Mar  7 11:50:40 2019 us=465452   pkcs11_id = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=465494   pkcs11_id_management = DISABLED
Thu Mar  7 11:50:40 2019 us=465566   server_network = 0.0.0.0
Thu Mar  7 11:50:40 2019 us=465640   server_netmask = 0.0.0.0
Thu Mar  7 11:50:40 2019 us=465724   server_network_ipv6 = ::
Thu Mar  7 11:50:40 2019 us=465769   server_netbits_ipv6 = 0
Thu Mar  7 11:50:40 2019 us=465815   server_bridge_ip = 0.0.0.0
Thu Mar  7 11:50:40 2019 us=465860   server_bridge_netmask = 0.0.0.0
Thu Mar  7 11:50:40 2019 us=465906   server_bridge_pool_start = 0.0.0.0
Thu Mar  7 11:50:40 2019 us=465951   server_bridge_pool_end = 0.0.0.0
Thu Mar  7 11:50:40 2019 us=465994   ifconfig_pool_defined = DISABLED
Thu Mar  7 11:50:40 2019 us=466040   ifconfig_pool_start = 0.0.0.0
Thu Mar  7 11:50:40 2019 us=466084   ifconfig_pool_end = 0.0.0.0
Thu Mar  7 11:50:40 2019 us=466130   ifconfig_pool_netmask = 0.0.0.0
Thu Mar  7 11:50:40 2019 us=466172   ifconfig_pool_persist_filename = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=466214   ifconfig_pool_persist_refresh_freq = 600
Thu Mar  7 11:50:40 2019 us=466256   ifconfig_ipv6_pool_defined = DISABLED
Thu Mar  7 11:50:40 2019 us=466299   ifconfig_ipv6_pool_base = ::
Thu Mar  7 11:50:40 2019 us=466341   ifconfig_ipv6_pool_netbits = 0
Thu Mar  7 11:50:40 2019 us=466383   n_bcast_buf = 256
Thu Mar  7 11:50:40 2019 us=466425   tcp_queue_limit = 64
Thu Mar  7 11:50:40 2019 us=466466   real_hash_size = 256
Thu Mar  7 11:50:40 2019 us=466524   virtual_hash_size = 256
Thu Mar  7 11:50:40 2019 us=466565   client_connect_script = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=466608   learn_address_script = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=466652   client_disconnect_script = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=466694   client_config_dir = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=466736   ccd_exclusive = DISABLED
Thu Mar  7 11:50:40 2019 us=466792   tmp_dir = '/tmp'
Thu Mar  7 11:50:40 2019 us=466833   push_ifconfig_defined = DISABLED
Thu Mar  7 11:50:40 2019 us=466878   push_ifconfig_local = 0.0.0.0
Thu Mar  7 11:50:40 2019 us=466923   push_ifconfig_remote_netmask = 0.0.0.0
Thu Mar  7 11:50:40 2019 us=466964   push_ifconfig_ipv6_defined = DISABLED
Thu Mar  7 11:50:40 2019 us=467009   push_ifconfig_ipv6_local = ::/0
Thu Mar  7 11:50:40 2019 us=467052   push_ifconfig_ipv6_remote = ::
Thu Mar  7 11:50:40 2019 us=467094   enable_c2c = DISABLED
Thu Mar  7 11:50:40 2019 us=467136   duplicate_cn = DISABLED
Thu Mar  7 11:50:40 2019 us=467177   cf_max = 0
Thu Mar  7 11:50:40 2019 us=467217   cf_per = 0
Thu Mar  7 11:50:40 2019 us=467259   max_clients = 1024
Thu Mar  7 11:50:40 2019 us=467301   max_routes_per_client = 256
Thu Mar  7 11:50:40 2019 us=467343   auth_user_pass_verify_script = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=467386   auth_user_pass_verify_script_via_file = DISABLED
Thu Mar  7 11:50:40 2019 us=467428   auth_token_generate = DISABLED
Thu Mar  7 11:50:40 2019 us=467471   auth_token_lifetime = 0
Thu Mar  7 11:50:40 2019 us=467511   port_share_host = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=467554   port_share_port = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=467595   client = ENABLED
Thu Mar  7 11:50:40 2019 us=467636   pull = ENABLED
Thu Mar  7 11:50:40 2019 us=467678   auth_user_pass_file = '[UNDEF]'
Thu Mar  7 11:50:40 2019 us=467723 OpenVPN 2.4.0 arm-unknown-linux-gnueabihf [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Oct 14 2018
Thu Mar  7 11:50:40 2019 us=467785 library versions: OpenSSL 1.0.2r  26 Feb 2019, LZO 2.08
Thu Mar  7 11:50:40 2019 us=468221 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 11:50:40 2019 us=470603 Outgoing Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
Thu Mar  7 11:50:40 2019 us=470767 Outgoing Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
Thu Mar  7 11:50:40 2019 us=470821 Incoming Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
Thu Mar  7 11:50:40 2019 us=470875 Incoming Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
Thu Mar  7 11:50:40 2019 us=471075 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 11:50:40 2019 us=471162 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 11:50:40 2019 us=471249 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 11:50:40 2019 us=471294 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 11:50:40 2019 us=471353 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 11:50:40 2019 us=471432 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 11:50:40 2019 us=471477 UDP link local: (not bound)
Thu Mar  7 11:50:40 2019 us=471522 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 11:50:40 2019 us=753196 TLS: Initial packet from [AF_INET]159.69.7.156:1194, sid=e85be7f7 f57109dc
Thu Mar  7 11:50:40 2019 us=827074 VERIFY OK: depth=1, CN=cn_W0ulBoBQjyXgMUEw
Thu Mar  7 11:50:40 2019 us=832002 Validating certificate key usage
Thu Mar  7 11:50:40 2019 us=832066 ++ Certificate has key usage  00a0, expects 00a0
Thu Mar  7 11:50:40 2019 us=832094 VERIFY KU OK
Thu Mar  7 11:50:40 2019 us=832131 Validating certificate extended key usage
Thu Mar  7 11:50:40 2019 us=832162 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Thu Mar  7 11:50:40 2019 us=832190 VERIFY EKU OK
Thu Mar  7 11:50:40 2019 us=832215 VERIFY X509NAME OK: CN=server_baOUcI0m0z2674zq
Thu Mar  7 11:50:40 2019 us=832242 VERIFY OK: depth=0, CN=server_baOUcI0m0z2674zq
Thu Mar  7 11:50:41 2019 us=17267 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 ECDHE-ECDSA-AES128-GCM-SHA256
Thu Mar  7 11:50:41 2019 us=17407 [server_baOUcI0m0z2674zq] Peer Connection Initiated with [AF_INET]159.69.7.156:1194
Thu Mar  7 11:50:42 2019 us=97722 SENT CONTROL [server_baOUcI0m0z2674zq]: 'PUSH_REQUEST' (status=1)
Thu Mar  7 11:50:42 2019 us=385118 PUSH: Received control message: 'PUSH_REPLY,dhcp-option DNS 8.8.8.8,dhcp-option DNS 8.8.4.4,redirect-gateway def1 bypass-dhcp,route-gateway 10.8.0.1,topology subnet,ping 10,ping-restart 120,ifconfig 10.8.0.2 255.255.255.0,peer-id 0,cipher AES-128-GCM'
Thu Mar  7 11:50:42 2019 us=385649 OPTIONS IMPORT: timers and/or timeouts modified
Thu Mar  7 11:50:42 2019 us=385724 OPTIONS IMPORT: --ifconfig/up options modified
Thu Mar  7 11:50:42 2019 us=385782 OPTIONS IMPORT: route options modified
Thu Mar  7 11:50:42 2019 us=385835 OPTIONS IMPORT: route-related options modified
Thu Mar  7 11:50:42 2019 us=385890 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Thu Mar  7 11:50:42 2019 us=385945 OPTIONS IMPORT: peer-id set
Thu Mar  7 11:50:42 2019 us=385999 OPTIONS IMPORT: adjusting link_mtu to 1624
Thu Mar  7 11:50:42 2019 us=386052 OPTIONS IMPORT: data channel crypto options modified
Thu Mar  7 11:50:42 2019 us=386144 Data Channel MTU parms [ L:1552 D:1450 EF:52 EB:406 ET:0 EL:3 ]
Thu Mar  7 11:50:42 2019 us=386795 Data Channel Encrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 11:50:42 2019 us=386898 Data Channel Decrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 11:50:42 2019 us=387496 ROUTE_GATEWAY 192.168.43.1/255.255.255.0 IFACE=wlan0 HWADDR=b8:27:eb:5c:71:46
Thu Mar  7 11:50:42 2019 us=388952 TUN/TAP device tun0 opened
Thu Mar  7 11:50:42 2019 us=389450 TUN/TAP TX queue length set to 100
Thu Mar  7 11:50:42 2019 us=389662 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Thu Mar  7 11:50:42 2019 us=389845 /sbin/ip link set dev tun0 up mtu 1500
Thu Mar  7 11:50:42 2019 us=397955 /sbin/ip addr add dev tun0 10.8.0.2/24 broadcast 10.8.0.255
Thu Mar  7 11:50:42 2019 us=408217 /etc/openvpn/update-resolv-conf tun0 1500 1552 10.8.0.2 255.255.255.0 init
dhcp-option DNS 8.8.8.8
dhcp-option DNS 8.8.4.4
Too few arguments.
Too few arguments.
Thu Mar  7 11:50:42 2019 us=565049 /sbin/ip route add 159.69.7.156/32 via 192.168.43.1
Thu Mar  7 11:50:42 2019 us=568229 /sbin/ip route add 0.0.0.0/1 via 10.8.0.1
Thu Mar  7 11:50:42 2019 us=577513 /sbin/ip route add 128.0.0.0/1 via 10.8.0.1
Thu Mar  7 11:50:42 2019 us=582116 Initialization Sequence Completed
Thu Mar  7 11:53:03 2019 us=325444 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:03 2019 us=732006 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:04 2019 us=142167 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:04 2019 us=972126 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:05 2019 us=327825 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:05 2019 us=328295 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:06 2019 us=92161 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:06 2019 us=652146 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:06 2019 us=972217 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:08 2019 us=330104 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:08 2019 us=330403 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:08 2019 us=330657 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:08 2019 us=330765 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:08 2019 us=352210 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:08 2019 us=352403 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:08 2019 us=732191 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:09 2019 us=52089 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:09 2019 us=292150 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:25 2019 us=347904 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:26 2019 us=345284 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:28 2019 us=334878 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:30 2019 us=252208 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:30 2019 us=352841 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:31 2019 us=350926 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:33 2019 us=340396 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:33 2019 us=452218 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:35 2019 us=357883 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:36 2019 us=356530 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:38 2019 us=345965 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:40 2019 us=362988 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:41 2019 us=362134 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:41 2019 us=772226 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:43 2019 us=113469 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:43 2019 us=351625 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:46 2019 us=367652 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:48 2019 us=119116 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:48 2019 us=357367 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:49 2019 us=114101 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:53 2019 us=124218 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:53 2019 us=362920 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:54 2019 us=119320 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:56 2019 us=492214 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:58 2019 us=129786 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:58 2019 us=368406 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:53:59 2019 us=124895 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:01 2019 us=115748 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:01 2019 us=612203 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:03 2019 us=135516 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:04 2019 us=130015 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:06 2019 us=121422 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:08 2019 us=141158 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:09 2019 us=135741 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:11 2019 us=127010 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:13 2019 us=146736 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:14 2019 us=141379 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:16 2019 us=132606 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:18 2019 us=152317 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:19 2019 us=146932 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:21 2019 us=138343 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:23 2019 us=287128 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:23 2019 us=291373 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:23 2019 us=299986 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:23 2019 us=303598 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:23 2019 us=592180 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:23 2019 us=863099 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:23 2019 us=902361 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:24 2019 us=152484 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:24 2019 us=293828 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:24 2019 us=294832 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:24 2019 us=296079 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:24 2019 us=300671 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:24 2019 us=512148 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:25 2019 us=301594 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:25 2019 us=772137 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:26 2019 us=144033 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:26 2019 us=292783 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:26 2019 us=294227 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:26 2019 us=294674 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:26 2019 us=295453 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:26 2019 us=295798 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:26 2019 us=301927 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:26 2019 us=332520 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:26 2019 us=332756 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:26 2019 us=952167 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:27 2019 us=52207 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:27 2019 us=296170 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:27 2019 us=296362 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:27 2019 us=296592 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:27 2019 us=296704 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:27 2019 us=610380 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:27 2019 us=627253 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:27 2019 us=755091 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:27 2019 us=760757 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:28 2019 us=252008 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:28 2019 us=296396 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:28 2019 us=308997 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:28 2019 us=868222 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:29 2019 us=296886 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:29 2019 us=297122 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:29 2019 us=297376 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:29 2019 us=297484 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:29 2019 us=297697 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:29 2019 us=297796 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:29 2019 us=301511 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:29 2019 us=600593 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:29 2019 us=606262 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:30 2019 us=602800 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:30 2019 us=604435 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:30 2019 us=606147 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:30 2019 us=606696 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:31 2019 us=149599 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:31 2019 us=298472 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:31 2019 us=607033 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:32 2019 us=603615 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:32 2019 us=603818 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:32 2019 us=604684 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:32 2019 us=604834 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:32 2019 us=607312 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:32 2019 us=607873 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:32 2019 us=622298 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:32 2019 us=622482 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:32 2019 us=632295 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:32 2019 us=759902 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:32 2019 us=766158 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:32 2019 us=952163 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:32 2019 us=972183 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:33 2019 us=132161 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:33 2019 us=262158 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:33 2019 us=301762 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:33 2019 us=314797 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:33 2019 us=605906 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:33 2019 us=606162 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:33 2019 us=607237 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:33 2019 us=607531 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:33 2019 us=762602 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:33 2019 us=873386 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:34 2019 us=302115 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:34 2019 us=302316 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:34 2019 us=306566 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:34 2019 us=601155 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:34 2019 us=603776 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:35 2019 us=601588 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:35 2019 us=607206 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:35 2019 us=607666 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:35 2019 us=607868 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:35 2019 us=608699 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:35 2019 us=609065 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:35 2019 us=609518 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:35 2019 us=609635 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:35 2019 us=919564 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:35 2019 us=920101 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:36 2019 us=35608 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:36 2019 us=154766 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:36 2019 us=303662 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:36 2019 us=603792 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:36 2019 us=605558 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:36 2019 us=607301 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:36 2019 us=972257 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:36 2019 us=972450 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:37 2019 us=608509 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:37 2019 us=634668 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:37 2019 us=682786 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:37 2019 us=871144 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:38 2019 us=307549 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:38 2019 us=320532 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:38 2019 us=732148 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:38 2019 us=768089 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:38 2019 us=873950 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:38 2019 us=892118 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:39 2019 us=52223 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:39 2019 us=52394 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:39 2019 us=307131 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:39 2019 us=307324 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:39 2019 us=311637 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:39 2019 us=605631 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:40 2019 us=604623 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:40 2019 us=606603 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:40 2019 us=812196 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:40 2019 us=972163 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:41 2019 us=40817 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:41 2019 us=309230 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:41 2019 us=610125 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:41 2019 us=610328 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:41 2019 us=611477 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:42 2019 us=36590 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:42 2019 us=613816 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:42 2019 us=639812 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:42 2019 us=849028 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:42 2019 us=868733 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:43 2019 us=132164 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:43 2019 us=132415 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:43 2019 us=212247 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:43 2019 us=313292 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:43 2019 us=326292 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:43 2019 us=852217 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:43 2019 us=879261 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:43 2019 us=932381 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:44 2019 us=113155 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:44 2019 us=892156 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:45 2019 us=52286 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:45 2019 us=132227 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:45 2019 us=609753 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:45 2019 us=932199 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:46 2019 us=12243 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:46 2019 us=313763 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:47 2019 us=41895 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:47 2019 us=212180 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:47 2019 us=619110 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:47 2019 us=645123 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:48 2019 us=318315 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:48 2019 us=328712 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:48 2019 us=884895 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:50 2019 us=12154 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:50 2019 us=92197 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:51 2019 us=292193 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:51 2019 us=316601 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:51 2019 us=532260 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:51 2019 us=532454 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:52 2019 us=624321 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:52 2019 us=650278 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:53 2019 us=323831 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:53 2019 us=334001 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:53 2019 us=452334 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:53 2019 us=452544 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:53 2019 us=890487 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:54:56 2019 us=84939 [server_baOUcI0m0z2674zq] Inactivity timeout (--ping-restart), restarting
Thu Mar  7 11:54:56 2019 us=85854 TCP/UDP: Closing socket
Thu Mar  7 11:54:56 2019 us=86055 SIGUSR1[soft,ping-restart] received, process restarting
Thu Mar  7 11:54:56 2019 us=86171 Restart pause, 5 second(s)
Thu Mar  7 11:55:01 2019 us=86371 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 11:55:01 2019 us=86582 Re-using SSL/TLS context
Thu Mar  7 11:55:01 2019 us=86961 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 11:55:01 2019 us=87075 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 11:55:01 2019 us=87232 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 11:55:01 2019 us=87297 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 11:55:01 2019 us=87390 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 11:55:01 2019 us=87506 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 11:55:01 2019 us=87570 UDP link local: (not bound)
Thu Mar  7 11:55:01 2019 us=87639 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 11:55:01 2019 us=88469 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:55:03 2019 us=52278 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:55:07 2019 us=974036 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:55:15 2019 us=212389 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:55:32 2019 us=159195 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:56:01 2019 us=161005 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Thu Mar  7 11:56:01 2019 us=161178 TLS Error: TLS handshake failed
Thu Mar  7 11:56:01 2019 us=161607 TCP/UDP: Closing socket
Thu Mar  7 11:56:01 2019 us=161750 SIGUSR1[soft,tls-error] received, process restarting
Thu Mar  7 11:56:01 2019 us=161976 Restart pause, 5 second(s)
Thu Mar  7 11:56:06 2019 us=162189 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 11:56:06 2019 us=162388 Re-using SSL/TLS context
Thu Mar  7 11:56:06 2019 us=162729 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 11:56:06 2019 us=162857 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 11:56:06 2019 us=163009 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 11:56:06 2019 us=163076 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 11:56:06 2019 us=163164 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 11:56:06 2019 us=163283 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 11:56:06 2019 us=163344 UDP link local: (not bound)
Thu Mar  7 11:56:06 2019 us=163411 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 11:56:06 2019 us=163995 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:56:08 2019 us=332426 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:56:12 2019 us=113859 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:56:20 2019 us=52420 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:56:36 2019 us=695146 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:57:06 2019 us=92063 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Thu Mar  7 11:57:06 2019 us=92238 TLS Error: TLS handshake failed
Thu Mar  7 11:57:06 2019 us=92686 TCP/UDP: Closing socket
Thu Mar  7 11:57:06 2019 us=92840 SIGUSR1[soft,tls-error] received, process restarting
Thu Mar  7 11:57:06 2019 us=92948 Restart pause, 5 second(s)
Thu Mar  7 11:57:11 2019 us=93133 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 11:57:11 2019 us=93345 Re-using SSL/TLS context
Thu Mar  7 11:57:11 2019 us=93692 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 11:57:11 2019 us=93818 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 11:57:11 2019 us=93963 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 11:57:11 2019 us=94029 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 11:57:11 2019 us=94117 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 11:57:11 2019 us=94231 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 11:57:11 2019 us=94292 UDP link local: (not bound)
Thu Mar  7 11:57:11 2019 us=94359 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 11:57:11 2019 us=94983 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:57:13 2019 us=611969 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:57:18 2019 us=89385 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:57:26 2019 us=409173 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:57:42 2019 us=234008 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:58:11 2019 us=159147 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Thu Mar  7 11:58:11 2019 us=159353 TLS Error: TLS handshake failed
Thu Mar  7 11:58:11 2019 us=159830 TCP/UDP: Closing socket
Thu Mar  7 11:58:11 2019 us=159995 SIGUSR1[soft,tls-error] received, process restarting
Thu Mar  7 11:58:11 2019 us=160102 Restart pause, 5 second(s)
Thu Mar  7 11:58:16 2019 us=160289 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 11:58:16 2019 us=160497 Re-using SSL/TLS context
Thu Mar  7 11:58:16 2019 us=160842 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 11:58:16 2019 us=160971 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 11:58:16 2019 us=161123 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 11:58:16 2019 us=161191 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 11:58:16 2019 us=161282 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 11:58:16 2019 us=161401 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 11:58:16 2019 us=161462 UDP link local: (not bound)
Thu Mar  7 11:58:16 2019 us=161532 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 11:58:16 2019 us=162427 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:58:18 2019 us=92364 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:58:22 2019 us=396938 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:58:30 2019 us=227272 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:58:46 2019 us=708033 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:59:17 2019 us=103548 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Thu Mar  7 11:59:17 2019 us=103738 TLS Error: TLS handshake failed
Thu Mar  7 11:59:17 2019 us=104155 TCP/UDP: Closing socket
Thu Mar  7 11:59:17 2019 us=104301 SIGUSR1[soft,tls-error] received, process restarting
Thu Mar  7 11:59:17 2019 us=104406 Restart pause, 5 second(s)
Thu Mar  7 11:59:22 2019 us=104619 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 11:59:22 2019 us=104838 Re-using SSL/TLS context
Thu Mar  7 11:59:22 2019 us=105223 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 11:59:22 2019 us=105420 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 11:59:22 2019 us=105582 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 11:59:22 2019 us=105657 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 11:59:22 2019 us=105753 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 11:59:22 2019 us=105880 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 11:59:22 2019 us=105948 UDP link local: (not bound)
Thu Mar  7 11:59:22 2019 us=106023 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 11:59:22 2019 us=106683 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:59:24 2019 us=412183 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 11:59:29 2019 us=90824 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194

TinCanTech · Post by **TinCanTech** » Thu Mar 07, 2019 1:06 pm

According to your log your --keepalive setting is working as expected:

argyrg wrote: ↑
Thu Mar 07, 2019 12:09 pm
Thu Mar 7 11:50:42 2019 us=385118 PUSH: Received control message: 'PUSH_REPLY,dhcp-option DNS 8.8.8.8,dhcp-option DNS 8.8.4.4,redirect-gateway def1 bypass-dhcp,route-gateway 10.8.0.1,topology subnet,ping 10,ping-restart 120,ifconfig 10.8.0.2 255.255.255.0,peer-id 0,cipher AES-128-GCM'

argyrg wrote: ↑
Thu Mar 07, 2019 12:09 pm
Thu Mar 7 11:53:03 2019 us=325444 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194

argyrg wrote: ↑
Thu Mar 07, 2019 12:09 pm
Thu Mar 7 11:54:56 2019 us=84939 [server_baOUcI0m0z2674zq] Inactivity timeout (--ping-restart), restarting

argyrg · Post by **argyrg** » Thu Mar 07, 2019 2:31 pm

Yes, I was fairly sure that it was hence my comment about this issue being more about my understanding of how keepalive works than about whether it was working.

If I attempt to start the VPN without any connections available (i.e. hotspot is switched off) then when a connection does become available the VPN connects without any issue. Here's the output from the client (set to verb 3 this time)

My suspicions are that when the mobile hotspot goes through a cycle then the link characteristics are sufficiently different, I'm thinking different IP address as a minimum, that the client can not re-authenticate and so the VPN stays down.

If this is the case, is there any way to avoid this such as setting each Pi with a unique static IP address on the VPN server's subnet? Though thinking about this again makes me think that client IP addresses must be in a different subnet than the vpn, so probably not a solution.

Cycling the hotspot must be similar to reconnecting over a different medium although I can see the differences between doing so through a local router compared with doing it over a mobile operator's network. Having said that, Local routers with dynamic IP addresses would behave similarly.

If re-establishing an existing connection isn't going to work then I'll have to find a solution which drops the VPN when the connection is lost and establishes a new one and then waits for the link to return. I need to get this automated because the Pis will need to operate autonomously.

Having gons through this thought exercise, if my conclusion is correct and if there isn't an openvpn solution then I'll have to take my thinking elsewhere, in any case, thanks again for taking a look.

argyrg

Code: Select all

Thu Mar  7 12:28:46 2019 library versions: OpenSSL 1.0.2r  26 Feb 2019, LZO 2.08
Thu Mar  7 12:28:46 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 12:28:46 2019 Outgoing Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
Thu Mar  7 12:28:46 2019 Outgoing Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
Thu Mar  7 12:28:46 2019 Incoming Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
Thu Mar  7 12:28:46 2019 Incoming Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
Thu Mar  7 12:28:46 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:28:46 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 12:28:46 2019 UDP link local: (not bound)
Thu Mar  7 12:28:46 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:28:46 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 12:28:46 2019 Network unreachable, restarting
Thu Mar  7 12:28:46 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 12:28:46 2019 Restart pause, 5 second(s)
Thu Mar  7 12:28:51 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 12:28:51 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:28:51 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 12:28:51 2019 UDP link local: (not bound)
Thu Mar  7 12:28:51 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:28:51 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 12:28:51 2019 Network unreachable, restarting
Thu Mar  7 12:28:51 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 12:28:51 2019 Restart pause, 5 second(s)
Thu Mar  7 12:28:56 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 12:28:56 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:28:56 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 12:28:56 2019 UDP link local: (not bound)
Thu Mar  7 12:28:56 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:28:56 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 12:28:56 2019 Network unreachable, restarting
Thu Mar  7 12:28:56 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 12:28:56 2019 Restart pause, 5 second(s)
Thu Mar  7 12:29:01 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 12:29:01 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:29:01 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 12:29:01 2019 UDP link local: (not bound)
Thu Mar  7 12:29:01 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:29:01 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 12:29:01 2019 Network unreachable, restarting
Thu Mar  7 12:29:01 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 12:29:01 2019 Restart pause, 5 second(s)
Thu Mar  7 12:29:06 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 12:29:06 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:29:06 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 12:29:06 2019 UDP link local: (not bound)
Thu Mar  7 12:29:06 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:29:06 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 12:29:06 2019 Network unreachable, restarting
Thu Mar  7 12:29:06 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 12:29:06 2019 Restart pause, 10 second(s)
Thu Mar  7 12:29:16 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 12:29:16 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:29:16 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 12:29:16 2019 UDP link local: (not bound)
Thu Mar  7 12:29:16 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:29:16 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 12:29:16 2019 Network unreachable, restarting
Thu Mar  7 12:29:16 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 12:29:16 2019 Restart pause, 20 second(s)
Thu Mar  7 12:29:36 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 12:29:36 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:29:36 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 12:29:36 2019 UDP link local: (not bound)
Thu Mar  7 12:29:36 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:29:36 2019 TLS: Initial packet from [AF_INET]159.69.7.156:1194, sid=b4cb515f 6169ea0a
Thu Mar  7 12:29:36 2019 VERIFY OK: depth=1, CN=cn_W0ulBoBQjyXgMUEw
Thu Mar  7 12:29:36 2019 Validating certificate key usage
Thu Mar  7 12:29:36 2019 ++ Certificate has key usage  00a0, expects 00a0
Thu Mar  7 12:29:36 2019 VERIFY KU OK
Thu Mar  7 12:29:36 2019 Validating certificate extended key usage
Thu Mar  7 12:29:36 2019 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Thu Mar  7 12:29:36 2019 VERIFY EKU OK
Thu Mar  7 12:29:36 2019 VERIFY X509NAME OK: CN=server_baOUcI0m0z2674zq
Thu Mar  7 12:29:36 2019 VERIFY OK: depth=0, CN=server_baOUcI0m0z2674zq
Thu Mar  7 12:29:37 2019 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 ECDHE-ECDSA-AES128-GCM-SHA256
Thu Mar  7 12:29:37 2019 [server_baOUcI0m0z2674zq] Peer Connection Initiated with [AF_INET]159.69.7.156:1194
Thu Mar  7 12:29:38 2019 SENT CONTROL [server_baOUcI0m0z2674zq]: 'PUSH_REQUEST' (status=1)
Thu Mar  7 12:29:38 2019 PUSH: Received control message: 'PUSH_REPLY,dhcp-option DNS 8.8.8.8,dhcp-option DNS 8.8.4.4,redirect-gateway def1 bypass-dhcp,route-gateway 10.8.0.1,topology subnet,ping 10,ping-restart 120,ifconfig 10.8.0.2 255.255.255.0,peer-id 0,cipher AES-128-GCM'
Thu Mar  7 12:29:38 2019 OPTIONS IMPORT: timers and/or timeouts modified
Thu Mar  7 12:29:38 2019 OPTIONS IMPORT: --ifconfig/up options modified
Thu Mar  7 12:29:38 2019 OPTIONS IMPORT: route options modified
Thu Mar  7 12:29:38 2019 OPTIONS IMPORT: route-related options modified
Thu Mar  7 12:29:38 2019 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Thu Mar  7 12:29:38 2019 OPTIONS IMPORT: peer-id set
Thu Mar  7 12:29:38 2019 OPTIONS IMPORT: adjusting link_mtu to 1624
Thu Mar  7 12:29:38 2019 OPTIONS IMPORT: data channel crypto options modified
Thu Mar  7 12:29:38 2019 Data Channel Encrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 12:29:38 2019 Data Channel Decrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 12:29:38 2019 ROUTE_GATEWAY 192.168.43.1/255.255.255.0 IFACE=wlan0 HWADDR=b8:27:eb:5c:71:46
Thu Mar  7 12:29:38 2019 TUN/TAP device tun0 opened
Thu Mar  7 12:29:38 2019 TUN/TAP TX queue length set to 100
Thu Mar  7 12:29:38 2019 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Thu Mar  7 12:29:38 2019 /sbin/ip link set dev tun0 up mtu 1500
Thu Mar  7 12:29:38 2019 /sbin/ip addr add dev tun0 10.8.0.2/24 broadcast 10.8.0.255
Thu Mar  7 12:29:38 2019 /etc/openvpn/update-resolv-conf tun0 1500 1552 10.8.0.2 255.255.255.0 init
dhcp-option DNS 8.8.8.8
dhcp-option DNS 8.8.4.4
Too few arguments.
Too few arguments.
Thu Mar  7 12:29:38 2019 /sbin/ip route add 159.69.7.156/32 via 192.168.43.1
Thu Mar  7 12:29:38 2019 /sbin/ip route add 0.0.0.0/1 via 10.8.0.1
Thu Mar  7 12:29:38 2019 /sbin/ip route add 128.0.0.0/1 via 10.8.0.1
Thu Mar  7 12:29:38 2019 Initialization Sequence Completed

TinCanTech · Post by **TinCanTech** » Thu Mar 07, 2019 3:07 pm

TinCanTech wrote: ↑
Thu Mar 07, 2019 1:06 pm
According to your log your --keepalive setting is working as expected

argyrg wrote: ↑
Thu Mar 07, 2019 2:31 pm
Yes, I was fairly sure that it was hence my comment about this issue being more about my understanding of how keepalive works than about whether it was working.

See --keepalive in the manual.

argyrg wrote: ↑
Thu Mar 07, 2019 2:31 pm
My suspicions are that when the mobile hotspot goes through a cycle then the link characteristics are sufficiently different, I'm thinking different IP address as a minimum, that the client can not re-authenticate and so the VPN stays down.

Your logs show that the hotspot is unavailable for a short period and a subsequent successful connection:

argyrg wrote: ↑

Thu Mar 07, 2019 2:31 pm

Code: Select all

Thu Mar  7 12:28:51 2019 write UDP: Network is unreachable (code=101)
<snip>
Thu Mar  7 12:29:16 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 12:29:16 2019 Network unreachable, restarting
Thu Mar  7 12:29:16 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 12:29:16 2019 Restart pause, 20 second(s)
Thu Mar  7 12:29:36 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 12:29:36 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:29:36 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 12:29:36 2019 UDP link local: (not bound)
Thu Mar  7 12:29:36 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 12:29:36 2019 TLS: Initial packet from [AF_INET]159.69.7.156:1194, sid=b4cb515f 6169ea0a
<snip>
Thu Mar  7 12:29:38 2019 Initialization Sequence Completed

This even took less than one minute to complete .. So I don't understand the problem ?

Note: Please use bbcode [code] for logfiles not [oconf]

argyrg · Post by **argyrg** » Thu Mar 07, 2019 4:08 pm

Thanks again for your feedback.

The log that you referenced is a normal startup but in the case where the connection request is made but where the wifi is down at the time of the request and then comes up some time later. I tested that functionality to see how it behaved differently to the case where the network link goes down once the VPN has been established and the network link is then cycled.

The following client log shows me issuing a connection request at 15:26:25 and then waiting until 15:56:56 when the wifi becomes available (which I switched on at 15:52:27)

This test simply confirms that the connect script is very patient and will eventually connect once a network interface becomes available.

However, I then disconnect the wifi again at 15:58:12 and leave it disconnected for around a minute before switching it on again at 15:59:58. If you look at the log after this time you will see that the connection can not be re-established. Keepalive seems to have found something but the connection is not established. This is verb 3 but no doubt there is much chattering going on at verb 4.

Code: Select all

Thu Mar  7 15:26:25 2019 library versions: OpenSSL 1.0.2r  26 Feb 2019, LZO 2.08
Thu Mar  7 15:26:25 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:26:26 2019 Outgoing Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
Thu Mar  7 15:26:26 2019 Outgoing Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
Thu Mar  7 15:26:26 2019 Incoming Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
Thu Mar  7 15:26:26 2019 Incoming Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
Thu Mar  7 15:26:26 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:26:26 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:26:26 2019 UDP link local: (not bound)
Thu Mar  7 15:26:26 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:26:26 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 15:26:26 2019 Network unreachable, restarting
Thu Mar  7 15:26:26 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 15:26:26 2019 Restart pause, 5 second(s)
Thu Mar  7 15:26:31 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:26:31 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:26:31 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:26:31 2019 UDP link local: (not bound)
Thu Mar  7 15:26:31 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:26:31 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 15:26:31 2019 Network unreachable, restarting
Thu Mar  7 15:26:31 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 15:26:31 2019 Restart pause, 5 second(s)
Thu Mar  7 15:26:36 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:26:36 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:26:36 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:26:36 2019 UDP link local: (not bound)
Thu Mar  7 15:26:36 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:26:36 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 15:26:36 2019 Network unreachable, restarting
Thu Mar  7 15:26:36 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 15:26:36 2019 Restart pause, 5 second(s)
Thu Mar  7 15:26:41 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:26:41 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:26:41 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:26:41 2019 UDP link local: (not bound)
Thu Mar  7 15:26:41 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:26:41 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 15:26:41 2019 Network unreachable, restarting
Thu Mar  7 15:26:41 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 15:26:41 2019 Restart pause, 5 second(s)
Thu Mar  7 15:26:46 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:26:46 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:26:46 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:26:46 2019 UDP link local: (not bound)
Thu Mar  7 15:26:46 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:26:46 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 15:26:46 2019 Network unreachable, restarting
Thu Mar  7 15:26:46 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 15:26:46 2019 Restart pause, 10 second(s)
Thu Mar  7 15:26:56 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:26:56 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:26:56 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:26:56 2019 UDP link local: (not bound)
Thu Mar  7 15:26:56 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:26:56 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 15:26:56 2019 Network unreachable, restarting
Thu Mar  7 15:26:56 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 15:26:56 2019 Restart pause, 20 second(s)
Thu Mar  7 15:27:16 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:27:16 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:27:16 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:27:16 2019 UDP link local: (not bound)
Thu Mar  7 15:27:16 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:27:16 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 15:27:16 2019 Network unreachable, restarting
Thu Mar  7 15:27:16 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 15:27:16 2019 Restart pause, 40 second(s)
Thu Mar  7 15:27:56 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:27:56 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:27:56 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:27:56 2019 UDP link local: (not bound)
Thu Mar  7 15:27:56 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:27:56 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 15:27:56 2019 Network unreachable, restarting
Thu Mar  7 15:27:56 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 15:27:56 2019 Restart pause, 80 second(s)
Thu Mar  7 15:29:16 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:29:16 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:29:16 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:29:16 2019 UDP link local: (not bound)
Thu Mar  7 15:29:16 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:29:16 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 15:29:16 2019 Network unreachable, restarting
Thu Mar  7 15:29:16 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 15:29:16 2019 Restart pause, 160 second(s)
Thu Mar  7 15:31:56 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:31:56 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:31:56 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:31:56 2019 UDP link local: (not bound)
Thu Mar  7 15:31:56 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:31:56 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 15:31:56 2019 Network unreachable, restarting
Thu Mar  7 15:31:56 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 15:31:56 2019 Restart pause, 300 second(s)
Thu Mar  7 15:36:56 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:36:56 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:36:56 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:36:56 2019 UDP link local: (not bound)
Thu Mar  7 15:36:56 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:36:56 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 15:36:56 2019 Network unreachable, restarting
Thu Mar  7 15:36:56 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 15:36:56 2019 Restart pause, 300 second(s)
Thu Mar  7 15:41:56 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:41:56 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:41:56 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:41:56 2019 UDP link local: (not bound)
Thu Mar  7 15:41:56 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:41:56 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 15:41:56 2019 Network unreachable, restarting
Thu Mar  7 15:41:56 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 15:41:56 2019 Restart pause, 300 second(s)
Thu Mar  7 15:46:56 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:46:56 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:46:56 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:46:56 2019 UDP link local: (not bound)
Thu Mar  7 15:46:56 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:46:56 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 15:46:56 2019 Network unreachable, restarting
Thu Mar  7 15:46:56 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 15:46:56 2019 Restart pause, 300 second(s)
Thu Mar  7 15:51:56 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:51:56 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:51:56 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:51:56 2019 UDP link local: (not bound)
Thu Mar  7 15:51:56 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:51:56 2019 write UDP: Network is unreachable (code=101)
Thu Mar  7 15:51:56 2019 Network unreachable, restarting
Thu Mar  7 15:51:56 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 15:51:56 2019 Restart pause, 300 second(s)
Thu Mar  7 15:56:56 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:56:56 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:56:56 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:56:56 2019 UDP link local: (not bound)
Thu Mar  7 15:56:56 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:56:56 2019 TLS: Initial packet from [AF_INET]159.69.7.156:1194, sid=c2cd50df ea815ac1
Thu Mar  7 15:56:56 2019 VERIFY OK: depth=1, CN=cn_W0ulBoBQjyXgMUEw
Thu Mar  7 15:56:56 2019 Validating certificate key usage
Thu Mar  7 15:56:56 2019 ++ Certificate has key usage  00a0, expects 00a0
Thu Mar  7 15:56:56 2019 VERIFY KU OK
Thu Mar  7 15:56:56 2019 Validating certificate extended key usage
Thu Mar  7 15:56:56 2019 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Thu Mar  7 15:56:56 2019 VERIFY EKU OK
Thu Mar  7 15:56:56 2019 VERIFY X509NAME OK: CN=server_baOUcI0m0z2674zq
Thu Mar  7 15:56:56 2019 VERIFY OK: depth=0, CN=server_baOUcI0m0z2674zq
Thu Mar  7 15:56:56 2019 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 ECDHE-ECDSA-AES128-GCM-SHA256
Thu Mar  7 15:56:56 2019 [server_baOUcI0m0z2674zq] Peer Connection Initiated with [AF_INET]159.69.7.156:1194
Thu Mar  7 15:56:57 2019 SENT CONTROL [server_baOUcI0m0z2674zq]: 'PUSH_REQUEST' (status=1)
Thu Mar  7 15:56:57 2019 PUSH: Received control message: 'PUSH_REPLY,dhcp-option DNS 8.8.8.8,dhcp-option DNS 8.8.4.4,redirect-gateway def1 bypass-dhcp,route-gateway 10.8.0.1,topology subnet,ping 10,ping-restart 120,ifconfig 10.8.0.2 255.255.255.0,peer-id 0,cipher AES-128-GCM'
Thu Mar  7 15:56:57 2019 OPTIONS IMPORT: timers and/or timeouts modified
Thu Mar  7 15:56:57 2019 OPTIONS IMPORT: --ifconfig/up options modified
Thu Mar  7 15:56:57 2019 OPTIONS IMPORT: route options modified
Thu Mar  7 15:56:57 2019 OPTIONS IMPORT: route-related options modified
Thu Mar  7 15:56:57 2019 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Thu Mar  7 15:56:57 2019 OPTIONS IMPORT: peer-id set
Thu Mar  7 15:56:57 2019 OPTIONS IMPORT: adjusting link_mtu to 1624
Thu Mar  7 15:56:57 2019 OPTIONS IMPORT: data channel crypto options modified
Thu Mar  7 15:56:57 2019 Data Channel Encrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 15:56:57 2019 Data Channel Decrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 15:56:57 2019 ROUTE_GATEWAY 192.168.43.1/255.255.255.0 IFACE=wlan0 HWADDR=b8:27:eb:5c:71:46
Thu Mar  7 15:56:57 2019 TUN/TAP device tun0 opened
Thu Mar  7 15:56:57 2019 TUN/TAP TX queue length set to 100
Thu Mar  7 15:56:57 2019 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Thu Mar  7 15:56:57 2019 /sbin/ip link set dev tun0 up mtu 1500
Thu Mar  7 15:56:57 2019 /sbin/ip addr add dev tun0 10.8.0.2/24 broadcast 10.8.0.255
Thu Mar  7 15:56:57 2019 /etc/openvpn/update-resolv-conf tun0 1500 1552 10.8.0.2 255.255.255.0 init
dhcp-option DNS 8.8.8.8
dhcp-option DNS 8.8.4.4
Too few arguments.
Too few arguments.
Thu Mar  7 15:56:57 2019 /sbin/ip route add 159.69.7.156/32 via 192.168.43.1
Thu Mar  7 15:56:57 2019 /sbin/ip route add 0.0.0.0/1 via 10.8.0.1
Thu Mar  7 15:56:57 2019 /sbin/ip route add 128.0.0.0/1 via 10.8.0.1
Thu Mar  7 15:56:57 2019 Initialization Sequence Completed
Thu Mar  7 15:59:50 2019 [server_baOUcI0m0z2674zq] Inactivity timeout (--ping-restart), restarting
Thu Mar  7 15:59:50 2019 SIGUSR1[soft,ping-restart] received, process restarting
Thu Mar  7 15:59:50 2019 Restart pause, 5 second(s)
Thu Mar  7 15:59:55 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 15:59:55 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 15:59:55 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 15:59:55 2019 UDP link local: (not bound)
Thu Mar  7 15:59:55 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:00:55 2019 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Thu Mar  7 16:00:55 2019 TLS Error: TLS handshake failed
Thu Mar  7 16:00:55 2019 SIGUSR1[soft,tls-error] received, process restarting
Thu Mar  7 16:00:55 2019 Restart pause, 5 second(s)
Thu Mar  7 16:01:00 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 16:01:00 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:01:00 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 16:01:00 2019 UDP link local: (not bound)
Thu Mar  7 16:01:00 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:02:00 2019 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Thu Mar  7 16:02:00 2019 TLS Error: TLS handshake failed
Thu Mar  7 16:02:00 2019 SIGUSR1[soft,tls-error] received, process restarting
Thu Mar  7 16:02:00 2019 Restart pause, 5 second(s)
Thu Mar  7 16:02:05 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 16:02:05 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:02:05 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 16:02:05 2019 UDP link local: (not bound)
Thu Mar  7 16:02:05 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:03:05 2019 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Thu Mar  7 16:03:05 2019 TLS Error: TLS handshake failed
Thu Mar  7 16:03:05 2019 SIGUSR1[soft,tls-error] received, process restarting
Thu Mar  7 16:03:05 2019 Restart pause, 5 second(s)
Thu Mar  7 16:03:10 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 16:03:10 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:03:10 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 16:03:10 2019 UDP link local: (not bound)
Thu Mar  7 16:03:10 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:04:10 2019 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Thu Mar  7 16:04:10 2019 TLS Error: TLS handshake failed
Thu Mar  7 16:04:10 2019 SIGUSR1[soft,tls-error] received, process restarting
Thu Mar  7 16:04:10 2019 Restart pause, 5 second(s)
Thu Mar  7 16:04:15 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 16:04:15 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:04:15 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 16:04:15 2019 UDP link local: (not bound)
Thu Mar  7 16:04:15 2019 UDP link remote: [AF_INET]159.69.7.156:1194

TinCanTech · Post by **TinCanTech** » Thu Mar 07, 2019 4:29 pm

Please post a log at verb 4 ..
You can use a paste serv if you prefer but we like to see logs here

Also, see --connect-retry in the manual for extra help.

argyrg · Post by **argyrg** » Thu Mar 07, 2019 4:35 pm

I'll set that up for a full test now at verb 4 and report back here with the logs.

While that's going on I'll take a look at the manual

argyrg · Post by **argyrg** » Thu Mar 07, 2019 4:58 pm

I started a connection without any network at 16:36:35

I then started my hotspot at around 16:36:55 and which resulted in a successful connection at 16:37:27. A PID error came up almost immediately but I let the network run for a minute or so before disconnecting from it and then reconnecting to it a minute or so later.

At 16:42:13 you can see that it is trying to reconnect but is failing in the same way that it failed in my initial posting. I let it try a couple of times but, from experience, I know that it just continues in the same vane without ever connecting again.

Code: Select all

Thu Mar  7 16:36:35 2019 us=796120 Current Parameter Settings:
Thu Mar  7 16:36:35 2019 us=796401   config = '/etc/openvpn/russell.ovpn'
Thu Mar  7 16:36:35 2019 us=796872   mode = 0
Thu Mar  7 16:36:35 2019 us=797027   persist_config = DISABLED
Thu Mar  7 16:36:35 2019 us=797181   persist_mode = 1
Thu Mar  7 16:36:35 2019 us=797328   show_ciphers = DISABLED
Thu Mar  7 16:36:35 2019 us=797476   show_digests = DISABLED
Thu Mar  7 16:36:35 2019 us=797622   show_engines = DISABLED
Thu Mar  7 16:36:35 2019 us=797766   genkey = DISABLED
Thu Mar  7 16:36:35 2019 us=797909   key_pass_file = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=798056   show_tls_ciphers = DISABLED
Thu Mar  7 16:36:35 2019 us=798205   connect_retry_max = 0
Thu Mar  7 16:36:35 2019 us=798355 Connection profiles [0]:
Thu Mar  7 16:36:35 2019 us=798503   proto = udp
Thu Mar  7 16:36:35 2019 us=798647   local = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=798792   local_port = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=798937   remote = '159.69.7.156'
Thu Mar  7 16:36:35 2019 us=799085   remote_port = '1194'
Thu Mar  7 16:36:35 2019 us=799233   remote_float = DISABLED
Thu Mar  7 16:36:35 2019 us=799379   bind_defined = DISABLED
Thu Mar  7 16:36:35 2019 us=799524   bind_local = DISABLED
Thu Mar  7 16:36:35 2019 us=799669   bind_ipv6_only = DISABLED
Thu Mar  7 16:36:35 2019 us=799818   connect_retry_seconds = 5
Thu Mar  7 16:36:35 2019 us=799967   connect_timeout = 120
Thu Mar  7 16:36:35 2019 us=800113   socks_proxy_server = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=800260   socks_proxy_port = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=800407   tun_mtu = 1500
Thu Mar  7 16:36:35 2019 us=800570   tun_mtu_defined = ENABLED
Thu Mar  7 16:36:35 2019 us=800736   link_mtu = 1500
Thu Mar  7 16:36:35 2019 us=800883   link_mtu_defined = DISABLED
Thu Mar  7 16:36:35 2019 us=801032   tun_mtu_extra = 0
Thu Mar  7 16:36:35 2019 us=801177   tun_mtu_extra_defined = DISABLED
Thu Mar  7 16:36:35 2019 us=801324   mtu_discover_type = -1
Thu Mar  7 16:36:35 2019 us=801472   fragment = 0
Thu Mar  7 16:36:35 2019 us=801619   mssfix = 1450
Thu Mar  7 16:36:35 2019 us=801766   explicit_exit_notification = 0
Thu Mar  7 16:36:35 2019 us=801877 Connection profiles END
Thu Mar  7 16:36:35 2019 us=801938   remote_random = DISABLED
Thu Mar  7 16:36:35 2019 us=802164   ipchange = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=802310   dev = 'tun'
Thu Mar  7 16:36:35 2019 us=802483   dev_type = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=802637   dev_node = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=802800   lladdr = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=802950   topology = 1
Thu Mar  7 16:36:35 2019 us=803098   ifconfig_local = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=803248   ifconfig_remote_netmask = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=803398   ifconfig_noexec = DISABLED
Thu Mar  7 16:36:35 2019 us=803549   ifconfig_nowarn = DISABLED
Thu Mar  7 16:36:35 2019 us=803698   ifconfig_ipv6_local = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=803847   ifconfig_ipv6_netbits = 0
Thu Mar  7 16:36:35 2019 us=803995   ifconfig_ipv6_remote = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=804144   shaper = 0
Thu Mar  7 16:36:35 2019 us=804288   mtu_test = 0
Thu Mar  7 16:36:35 2019 us=804433   mlock = DISABLED
Thu Mar  7 16:36:35 2019 us=804599   keepalive_ping = 0
Thu Mar  7 16:36:35 2019 us=804762   keepalive_timeout = 0
Thu Mar  7 16:36:35 2019 us=804909   inactivity_timeout = 0
Thu Mar  7 16:36:35 2019 us=805055   ping_send_timeout = 0
Thu Mar  7 16:36:35 2019 us=805203   ping_rec_timeout = 0
Thu Mar  7 16:36:35 2019 us=805351   ping_rec_timeout_action = 0
Thu Mar  7 16:36:35 2019 us=805500   ping_timer_remote = DISABLED
Thu Mar  7 16:36:35 2019 us=805651   remap_sigusr1 = 0
Thu Mar  7 16:36:35 2019 us=805797   persist_tun = ENABLED
Thu Mar  7 16:36:35 2019 us=805942   persist_local_ip = DISABLED
Thu Mar  7 16:36:35 2019 us=806093   persist_remote_ip = DISABLED
Thu Mar  7 16:36:35 2019 us=806240   persist_key = ENABLED
Thu Mar  7 16:36:35 2019 us=806388   passtos = DISABLED
Thu Mar  7 16:36:35 2019 us=806534   resolve_retry_seconds = 1000000000
Thu Mar  7 16:36:35 2019 us=811895   resolve_in_advance = DISABLED
Thu Mar  7 16:36:35 2019 us=811994   username = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=812059   groupname = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=812122   chroot_dir = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=812184   cd_dir = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=812245   writepid = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=812307   up_script = '/etc/openvpn/update-resolv-conf'
Thu Mar  7 16:36:35 2019 us=812371   down_script = '/etc/openvpn/update-resolv-conf'
Thu Mar  7 16:36:35 2019 us=812434   down_pre = DISABLED
Thu Mar  7 16:36:35 2019 us=812494   up_restart = DISABLED
Thu Mar  7 16:36:35 2019 us=812554   up_delay = DISABLED
Thu Mar  7 16:36:35 2019 us=812615   daemon = DISABLED
Thu Mar  7 16:36:35 2019 us=812677   inetd = 0
Thu Mar  7 16:36:35 2019 us=812736   log = DISABLED
Thu Mar  7 16:36:35 2019 us=812798   suppress_timestamps = DISABLED
Thu Mar  7 16:36:35 2019 us=812859   machine_readable_output = DISABLED
Thu Mar  7 16:36:35 2019 us=812922   nice = 0
Thu Mar  7 16:36:35 2019 us=812983   verbosity = 4
Thu Mar  7 16:36:35 2019 us=813044   mute = 0
Thu Mar  7 16:36:35 2019 us=813105   gremlin = 0
Thu Mar  7 16:36:35 2019 us=813166   status_file = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=813229   status_file_version = 1
Thu Mar  7 16:36:35 2019 us=813290   status_file_update_freq = 60
Thu Mar  7 16:36:35 2019 us=813353   occ = ENABLED
Thu Mar  7 16:36:35 2019 us=813414   rcvbuf = 0
Thu Mar  7 16:36:35 2019 us=813475   sndbuf = 0
Thu Mar  7 16:36:35 2019 us=813535   mark = 0
Thu Mar  7 16:36:35 2019 us=813595   sockflags = 0
Thu Mar  7 16:36:35 2019 us=813655   fast_io = DISABLED
Thu Mar  7 16:36:35 2019 us=813716   comp.alg = 0
Thu Mar  7 16:36:35 2019 us=813777   comp.flags = 0
Thu Mar  7 16:36:35 2019 us=813836   route_script = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=813899   route_default_gateway = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=813963   route_default_metric = 0
Thu Mar  7 16:36:35 2019 us=814025   route_noexec = DISABLED
Thu Mar  7 16:36:35 2019 us=814088   route_delay = 0
Thu Mar  7 16:36:35 2019 us=814149   route_delay_window = 30
Thu Mar  7 16:36:35 2019 us=814211   route_delay_defined = DISABLED
Thu Mar  7 16:36:35 2019 us=814273   route_nopull = DISABLED
Thu Mar  7 16:36:35 2019 us=814335   route_gateway_via_dhcp = DISABLED
Thu Mar  7 16:36:35 2019 us=814398   allow_pull_fqdn = DISABLED
Thu Mar  7 16:36:35 2019 us=814462   management_addr = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=815615   management_port = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=815721   management_user_pass = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=815790   management_log_history_cache = 250
Thu Mar  7 16:36:35 2019 us=815854   management_echo_buffer_size = 100
Thu Mar  7 16:36:35 2019 us=815919   management_write_peer_info_file = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=815985   management_client_user = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=816048   management_client_group = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=816112   management_flags = 0
Thu Mar  7 16:36:35 2019 us=816174   shared_secret_file = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=816237   key_direction = 0
Thu Mar  7 16:36:35 2019 us=816299   ciphername = 'AES-128-GCM'
Thu Mar  7 16:36:35 2019 us=816360   ncp_enabled = ENABLED
Thu Mar  7 16:36:35 2019 us=816423   ncp_ciphers = 'AES-256-GCM:AES-128-GCM'
Thu Mar  7 16:36:35 2019 us=816488   authname = 'SHA256'
Thu Mar  7 16:36:35 2019 us=816548   prng_hash = 'SHA1'
Thu Mar  7 16:36:35 2019 us=816610   prng_nonce_secret_len = 16
Thu Mar  7 16:36:35 2019 us=816672   keysize = 0
Thu Mar  7 16:36:35 2019 us=816733   engine = DISABLED
Thu Mar  7 16:36:35 2019 us=816793   replay = ENABLED
Thu Mar  7 16:36:35 2019 us=816854   mute_replay_warnings = DISABLED
Thu Mar  7 16:36:35 2019 us=816916   replay_window = 64
Thu Mar  7 16:36:35 2019 us=816978   replay_time = 15
Thu Mar  7 16:36:35 2019 us=817040   packet_id_file = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=817103   use_iv = ENABLED
Thu Mar  7 16:36:35 2019 us=817164   test_crypto = DISABLED
Thu Mar  7 16:36:35 2019 us=817225   tls_server = DISABLED
Thu Mar  7 16:36:35 2019 us=817285   tls_client = ENABLED
Thu Mar  7 16:36:35 2019 us=817312   key_method = 2
Thu Mar  7 16:36:35 2019 us=817338   ca_file = '[[INLINE]]'
Thu Mar  7 16:36:35 2019 us=817364   ca_path = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=817390   dh_file = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=817416   cert_file = '[[INLINE]]'
Thu Mar  7 16:36:35 2019 us=817442   extra_certs_file = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=817470   priv_key_file = '[[INLINE]]'
Thu Mar  7 16:36:35 2019 us=817497   pkcs12_file = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=817524   cipher_list = 'TLS-ECDHE-ECDSA-WITH-AES-128-GCM-SHA256'
Thu Mar  7 16:36:35 2019 us=817551   tls_verify = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=817586   tls_export_cert = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=817613   verify_x509_type = 2
Thu Mar  7 16:36:35 2019 us=817640   verify_x509_name = 'server_baOUcI0m0z2674zq'
Thu Mar  7 16:36:35 2019 us=817691   crl_file = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=817719   ns_cert_type = 0
Thu Mar  7 16:36:35 2019 us=817746   remote_cert_ku[i] = 160
Thu Mar  7 16:36:35 2019 us=817773   remote_cert_ku[i] = 136
Thu Mar  7 16:36:35 2019 us=817799   remote_cert_ku[i] = 0
Thu Mar  7 16:36:35 2019 us=817826   remote_cert_ku[i] = 0
Thu Mar  7 16:36:35 2019 us=817852   remote_cert_ku[i] = 0
Thu Mar  7 16:36:35 2019 us=817878   remote_cert_ku[i] = 0
Thu Mar  7 16:36:35 2019 us=817904   remote_cert_ku[i] = 0
Thu Mar  7 16:36:35 2019 us=817930   remote_cert_ku[i] = 0
Thu Mar  7 16:36:35 2019 us=817956   remote_cert_ku[i] = 0
Thu Mar  7 16:36:35 2019 us=817982   remote_cert_ku[i] = 0
Thu Mar  7 16:36:35 2019 us=818008   remote_cert_ku[i] = 0
Thu Mar  7 16:36:35 2019 us=818035   remote_cert_ku[i] = 0
Thu Mar  7 16:36:35 2019 us=818061   remote_cert_ku[i] = 0
Thu Mar  7 16:36:35 2019 us=818087   remote_cert_ku[i] = 0
Thu Mar  7 16:36:35 2019 us=818113   remote_cert_ku[i] = 0
Thu Mar  7 16:36:35 2019 us=818139   remote_cert_ku[i] = 0
Thu Mar  7 16:36:35 2019 us=818166   remote_cert_eku = 'TLS Web Server Authentication'
Thu Mar  7 16:36:35 2019 us=818193   ssl_flags = 192
Thu Mar  7 16:36:35 2019 us=818219   tls_timeout = 2
Thu Mar  7 16:36:35 2019 us=818245   renegotiate_bytes = -1
Thu Mar  7 16:36:35 2019 us=818272   renegotiate_packets = 0
Thu Mar  7 16:36:35 2019 us=818299   renegotiate_seconds = 3600
Thu Mar  7 16:36:35 2019 us=818326   handshake_window = 60
Thu Mar  7 16:36:35 2019 us=818352   transition_window = 3600
Thu Mar  7 16:36:35 2019 us=818378   single_session = DISABLED
Thu Mar  7 16:36:35 2019 us=818404   push_peer_info = DISABLED
Thu Mar  7 16:36:35 2019 us=818430   tls_exit = DISABLED
Thu Mar  7 16:36:35 2019 us=818456   tls_auth_file = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=818482   tls_crypt_file = '[[INLINE]]'
Thu Mar  7 16:36:35 2019 us=818510   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818537   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818564   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818591   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818618   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818644   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818671   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818700   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818727   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818754   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818781   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818807   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818834   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818861   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818888   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818915   pkcs11_protected_authentication = DISABLED
Thu Mar  7 16:36:35 2019 us=818943   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=818970   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=818996   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=819023   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=819050   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=819077   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=819104   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=819131   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=819158   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=819185   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=819211   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=819237   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=819264   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=819291   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=819317   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=819346   pkcs11_private_mode = 00000000
Thu Mar  7 16:36:35 2019 us=819372   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819398   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819425   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819451   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819478   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819504   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819530   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819556   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819583   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819609   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819636   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819662   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819689   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819716   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819742   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819768   pkcs11_cert_private = DISABLED
Thu Mar  7 16:36:35 2019 us=819795   pkcs11_pin_cache_period = -1
Thu Mar  7 16:36:35 2019 us=819821   pkcs11_id = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=819849   pkcs11_id_management = DISABLED
Thu Mar  7 16:36:35 2019 us=819912   server_network = 0.0.0.0
Thu Mar  7 16:36:35 2019 us=819971   server_netmask = 0.0.0.0
Thu Mar  7 16:36:35 2019 us=820030   server_network_ipv6 = ::
Thu Mar  7 16:36:35 2019 us=820058   server_netbits_ipv6 = 0
Thu Mar  7 16:36:35 2019 us=820088   server_bridge_ip = 0.0.0.0
Thu Mar  7 16:36:35 2019 us=820118   server_bridge_netmask = 0.0.0.0
Thu Mar  7 16:36:35 2019 us=820149   server_bridge_pool_start = 0.0.0.0
Thu Mar  7 16:36:35 2019 us=820178   server_bridge_pool_end = 0.0.0.0
Thu Mar  7 16:36:35 2019 us=820205   ifconfig_pool_defined = DISABLED
Thu Mar  7 16:36:35 2019 us=820235   ifconfig_pool_start = 0.0.0.0
Thu Mar  7 16:36:35 2019 us=820264   ifconfig_pool_end = 0.0.0.0
Thu Mar  7 16:36:35 2019 us=820294   ifconfig_pool_netmask = 0.0.0.0
Thu Mar  7 16:36:35 2019 us=820322   ifconfig_pool_persist_filename = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=820349   ifconfig_pool_persist_refresh_freq = 600
Thu Mar  7 16:36:35 2019 us=820377   ifconfig_ipv6_pool_defined = DISABLED
Thu Mar  7 16:36:35 2019 us=820406   ifconfig_ipv6_pool_base = ::
Thu Mar  7 16:36:35 2019 us=820432   ifconfig_ipv6_pool_netbits = 0
Thu Mar  7 16:36:35 2019 us=820459   n_bcast_buf = 256
Thu Mar  7 16:36:35 2019 us=820486   tcp_queue_limit = 64
Thu Mar  7 16:36:35 2019 us=820512   real_hash_size = 256
Thu Mar  7 16:36:35 2019 us=820539   virtual_hash_size = 256
Thu Mar  7 16:36:35 2019 us=820566   client_connect_script = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=820593   learn_address_script = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=820619   client_disconnect_script = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=820646   client_config_dir = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=820673   ccd_exclusive = DISABLED
Thu Mar  7 16:36:35 2019 us=820699   tmp_dir = '/tmp'
Thu Mar  7 16:36:35 2019 us=820725   push_ifconfig_defined = DISABLED
Thu Mar  7 16:36:35 2019 us=820755   push_ifconfig_local = 0.0.0.0
Thu Mar  7 16:36:35 2019 us=820785   push_ifconfig_remote_netmask = 0.0.0.0
Thu Mar  7 16:36:35 2019 us=820813   push_ifconfig_ipv6_defined = DISABLED
Thu Mar  7 16:36:35 2019 us=820841   push_ifconfig_ipv6_local = ::/0
Thu Mar  7 16:36:35 2019 us=820870   push_ifconfig_ipv6_remote = ::
Thu Mar  7 16:36:35 2019 us=820897   enable_c2c = DISABLED
Thu Mar  7 16:36:35 2019 us=820923   duplicate_cn = DISABLED
Thu Mar  7 16:36:35 2019 us=820949   cf_max = 0
Thu Mar  7 16:36:35 2019 us=820975   cf_per = 0
Thu Mar  7 16:36:35 2019 us=821001   max_clients = 1024
Thu Mar  7 16:36:35 2019 us=821027   max_routes_per_client = 256
Thu Mar  7 16:36:35 2019 us=821054   auth_user_pass_verify_script = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=821081   auth_user_pass_verify_script_via_file = DISABLED
Thu Mar  7 16:36:35 2019 us=821108   auth_token_generate = DISABLED
Thu Mar  7 16:36:35 2019 us=821135   auth_token_lifetime = 0
Thu Mar  7 16:36:35 2019 us=821161   port_share_host = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=821188   port_share_port = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=821214   client = ENABLED
Thu Mar  7 16:36:35 2019 us=821240   pull = ENABLED
Thu Mar  7 16:36:35 2019 us=821267   auth_user_pass_file = '[UNDEF]'
Thu Mar  7 16:36:35 2019 us=821297 OpenVPN 2.4.0 arm-unknown-linux-gnueabihf [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Oct 14 2018
Thu Mar  7 16:36:35 2019 us=821336 library versions: OpenSSL 1.0.2r  26 Feb 2019, LZO 2.08
Thu Mar  7 16:36:35 2019 us=821747 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 16:36:35 2019 us=824027 Outgoing Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
Thu Mar  7 16:36:35 2019 us=824117 Outgoing Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
Thu Mar  7 16:36:35 2019 us=824155 Incoming Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
Thu Mar  7 16:36:35 2019 us=824193 Incoming Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
Thu Mar  7 16:36:35 2019 us=824370 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 16:36:35 2019 us=824500 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 16:36:35 2019 us=824593 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 16:36:35 2019 us=824640 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 16:36:35 2019 us=824702 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:36:35 2019 us=824781 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 16:36:35 2019 us=824825 UDP link local: (not bound)
Thu Mar  7 16:36:35 2019 us=824871 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:36:35 2019 us=825063 write UDP: Network is unreachable (code=101)
Thu Mar  7 16:36:35 2019 us=825107 Network unreachable, restarting
Thu Mar  7 16:36:35 2019 us=825252 TCP/UDP: Closing socket
Thu Mar  7 16:36:35 2019 us=825333 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 16:36:35 2019 us=825396 Restart pause, 5 second(s)
Thu Mar  7 16:36:40 2019 us=825580 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 16:36:40 2019 us=825773 Re-using SSL/TLS context
Thu Mar  7 16:36:40 2019 us=826107 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 16:36:40 2019 us=826234 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 16:36:40 2019 us=826392 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 16:36:40 2019 us=826460 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 16:36:40 2019 us=826549 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:36:40 2019 us=826664 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 16:36:40 2019 us=826728 UDP link local: (not bound)
Thu Mar  7 16:36:40 2019 us=826795 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:36:40 2019 us=827041 write UDP: Network is unreachable (code=101)
Thu Mar  7 16:36:40 2019 us=827101 Network unreachable, restarting
Thu Mar  7 16:36:40 2019 us=827265 TCP/UDP: Closing socket
Thu Mar  7 16:36:40 2019 us=827385 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 16:36:40 2019 us=827478 Restart pause, 5 second(s)
Thu Mar  7 16:36:45 2019 us=827650 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 16:36:45 2019 us=827835 Re-using SSL/TLS context
Thu Mar  7 16:36:45 2019 us=828174 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 16:36:45 2019 us=828301 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 16:36:45 2019 us=828458 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 16:36:45 2019 us=828524 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 16:36:45 2019 us=828613 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:36:45 2019 us=828726 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 16:36:45 2019 us=828787 UDP link local: (not bound)
Thu Mar  7 16:36:45 2019 us=828857 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:36:45 2019 us=829098 write UDP: Network is unreachable (code=101)
Thu Mar  7 16:36:45 2019 us=829159 Network unreachable, restarting
Thu Mar  7 16:36:45 2019 us=829320 TCP/UDP: Closing socket
Thu Mar  7 16:36:45 2019 us=829438 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 16:36:45 2019 us=829532 Restart pause, 5 second(s)
Thu Mar  7 16:36:50 2019 us=829707 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 16:36:50 2019 us=829887 Re-using SSL/TLS context
Thu Mar  7 16:36:50 2019 us=830221 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 16:36:50 2019 us=830350 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 16:36:50 2019 us=830507 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 16:36:50 2019 us=830573 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 16:36:50 2019 us=830661 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:36:50 2019 us=830770 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 16:36:50 2019 us=830831 UDP link local: (not bound)
Thu Mar  7 16:36:50 2019 us=830897 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:36:50 2019 us=831140 write UDP: Network is unreachable (code=101)
Thu Mar  7 16:36:50 2019 us=831199 Network unreachable, restarting
Thu Mar  7 16:36:50 2019 us=831362 TCP/UDP: Closing socket
Thu Mar  7 16:36:50 2019 us=831482 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 16:36:50 2019 us=831577 Restart pause, 5 second(s)
Thu Mar  7 16:36:55 2019 us=831751 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 16:36:55 2019 us=832000 Re-using SSL/TLS context
Thu Mar  7 16:36:55 2019 us=832335 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 16:36:55 2019 us=832463 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 16:36:55 2019 us=832620 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 16:36:55 2019 us=832687 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 16:36:55 2019 us=832774 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:36:55 2019 us=832889 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 16:36:55 2019 us=832950 UDP link local: (not bound)
Thu Mar  7 16:36:55 2019 us=833017 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:36:55 2019 us=833262 write UDP: Network is unreachable (code=101)
Thu Mar  7 16:36:55 2019 us=833324 Network unreachable, restarting
Thu Mar  7 16:36:55 2019 us=833490 TCP/UDP: Closing socket
Thu Mar  7 16:36:55 2019 us=833608 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 16:36:55 2019 us=833702 Restart pause, 10 second(s)
Thu Mar  7 16:37:05 2019 us=833876 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 16:37:05 2019 us=834065 Re-using SSL/TLS context
Thu Mar  7 16:37:05 2019 us=834406 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 16:37:05 2019 us=834531 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 16:37:05 2019 us=834692 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 16:37:05 2019 us=834761 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 16:37:05 2019 us=834851 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:37:05 2019 us=834965 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 16:37:05 2019 us=835028 UDP link local: (not bound)
Thu Mar  7 16:37:05 2019 us=835097 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:37:05 2019 us=835340 write UDP: Network is unreachable (code=101)
Thu Mar  7 16:37:05 2019 us=835401 Network unreachable, restarting
Thu Mar  7 16:37:05 2019 us=835568 TCP/UDP: Closing socket
Thu Mar  7 16:37:05 2019 us=835689 SIGUSR1[soft,network-unreachable] received, process restarting
Thu Mar  7 16:37:05 2019 us=835787 Restart pause, 20 second(s)
Thu Mar  7 16:37:25 2019 us=835973 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 16:37:25 2019 us=836181 Re-using SSL/TLS context
Thu Mar  7 16:37:25 2019 us=836539 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 16:37:25 2019 us=836686 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 16:37:25 2019 us=836851 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 16:37:25 2019 us=836921 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 16:37:25 2019 us=837013 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:37:25 2019 us=837134 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 16:37:25 2019 us=837201 UDP link local: (not bound)
Thu Mar  7 16:37:25 2019 us=837272 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:37:25 2019 us=922361 TLS: Initial packet from [AF_INET]159.69.7.156:1194, sid=0ad05f64 99605be8
Thu Mar  7 16:37:25 2019 us=993022 VERIFY OK: depth=1, CN=cn_W0ulBoBQjyXgMUEw
Thu Mar  7 16:37:25 2019 us=997919 Validating certificate key usage
Thu Mar  7 16:37:25 2019 us=997963 ++ Certificate has key usage  00a0, expects 00a0
Thu Mar  7 16:37:25 2019 us=998013 VERIFY KU OK
Thu Mar  7 16:37:25 2019 us=998050 Validating certificate extended key usage
Thu Mar  7 16:37:25 2019 us=998084 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Thu Mar  7 16:37:25 2019 us=998113 VERIFY EKU OK
Thu Mar  7 16:37:25 2019 us=998135 VERIFY X509NAME OK: CN=server_baOUcI0m0z2674zq
Thu Mar  7 16:37:25 2019 us=998160 VERIFY OK: depth=0, CN=server_baOUcI0m0z2674zq
Thu Mar  7 16:37:26 2019 us=142096 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 ECDHE-ECDSA-AES128-GCM-SHA256
Thu Mar  7 16:37:26 2019 us=142250 [server_baOUcI0m0z2674zq] Peer Connection Initiated with [AF_INET]159.69.7.156:1194
Thu Mar  7 16:37:27 2019 us=340759 SENT CONTROL [server_baOUcI0m0z2674zq]: 'PUSH_REQUEST' (status=1)
Thu Mar  7 16:37:27 2019 us=448360 PUSH: Received control message: 'PUSH_REPLY,dhcp-option DNS 8.8.8.8,dhcp-option DNS 8.8.4.4,redirect-gateway def1 bypass-dhcp,route-gateway 10.8.0.1,topology subnet,ping 10,ping-restart 120,ifconfig 10.8.0.2 255.255.255.0,peer-id 0,cipher AES-128-GCM'
Thu Mar  7 16:37:27 2019 us=449002 OPTIONS IMPORT: timers and/or timeouts modified
Thu Mar  7 16:37:27 2019 us=449103 OPTIONS IMPORT: --ifconfig/up options modified
Thu Mar  7 16:37:27 2019 us=449168 OPTIONS IMPORT: route options modified
Thu Mar  7 16:37:27 2019 us=449227 OPTIONS IMPORT: route-related options modified
Thu Mar  7 16:37:27 2019 us=449288 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Thu Mar  7 16:37:27 2019 us=449347 OPTIONS IMPORT: peer-id set
Thu Mar  7 16:37:27 2019 us=449404 OPTIONS IMPORT: adjusting link_mtu to 1624
Thu Mar  7 16:37:27 2019 us=449465 OPTIONS IMPORT: data channel crypto options modified
Thu Mar  7 16:37:27 2019 us=449568 Data Channel MTU parms [ L:1552 D:1450 EF:52 EB:406 ET:0 EL:3 ]
Thu Mar  7 16:37:27 2019 us=450224 Data Channel Encrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 16:37:27 2019 us=450333 Data Channel Decrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 16:37:27 2019 us=450995 ROUTE_GATEWAY 192.168.43.1/255.255.255.0 IFACE=wlan0 HWADDR=b8:27:eb:5c:71:46
Thu Mar  7 16:37:27 2019 us=452893 TUN/TAP device tun0 opened
Thu Mar  7 16:37:27 2019 us=453497 TUN/TAP TX queue length set to 100
Thu Mar  7 16:37:27 2019 us=453730 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Thu Mar  7 16:37:27 2019 us=453970 /sbin/ip link set dev tun0 up mtu 1500
Thu Mar  7 16:37:27 2019 us=469599 /sbin/ip addr add dev tun0 10.8.0.2/24 broadcast 10.8.0.255
Thu Mar  7 16:37:27 2019 us=477441 /etc/openvpn/update-resolv-conf tun0 1500 1552 10.8.0.2 255.255.255.0 init
dhcp-option DNS 8.8.8.8
dhcp-option DNS 8.8.4.4
Too few arguments.
Too few arguments.
Thu Mar  7 16:37:27 2019 us=625646 /sbin/ip route add 159.69.7.156/32 via 192.168.43.1
Thu Mar  7 16:37:27 2019 us=628657 /sbin/ip route add 0.0.0.0/1 via 10.8.0.1
Thu Mar  7 16:37:27 2019 us=632231 /sbin/ip route add 128.0.0.0/1 via 10.8.0.1
Thu Mar  7 16:37:27 2019 us=636374 Initialization Sequence Completed
Thu Mar  7 16:37:29 2019 us=832242 PID_ERR replay-window backtrack occurred [1] [SSL-0] [0_0000] 0:6 0:5 t=1551976649[0] r=[0,64,15,1,1] sl=[58,6,64,272]
Thu Mar  7 16:40:24 2019 us=43808 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:40:24 2019 us=44047 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:40:24 2019 us=44100 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:40:24 2019 us=44286 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:40:24 2019 us=44543 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
<snip>

Thu Mar  7 16:41:21 2019 us=741297 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:21 2019 us=742613 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:22 2019 us=12291 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:22 2019 us=12492 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:22 2019 us=759535 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:22 2019 us=826899 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:23 2019 us=8951 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:23 2019 us=590914 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:23 2019 us=602195 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:23 2019 us=852240 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:23 2019 us=917723 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:24 2019 us=12081 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:24 2019 us=92106 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:24 2019 us=92223 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:24 2019 us=102520 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:24 2019 us=587714 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:24 2019 us=589424 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:24 2019 us=589484 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:25 2019 us=744687 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:25 2019 us=744887 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:25 2019 us=932022 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:26 2019 us=92047 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:26 2019 us=192422 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:26 2019 us=740559 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:26 2019 us=742047 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:26 2019 us=746699 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:26 2019 us=747918 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:27 2019 us=187811 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:27 2019 us=692193 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:27 2019 us=765220 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:28 2019 us=172254 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:28 2019 us=172447 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:28 2019 us=596474 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:28 2019 us=607324 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:28 2019 us=752399 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:28 2019 us=753955 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:29 2019 us=108012 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:29 2019 us=201055 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:29 2019 us=772037 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:29 2019 us=772145 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:30 2019 us=12247 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:30 2019 us=172173 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:30 2019 us=252271 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:31 2019 us=125687 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:31 2019 us=742090 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:31 2019 us=852104 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:31 2019 us=852231 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:32 2019 us=172081 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:32 2019 us=172192 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:32 2019 us=191932 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:32 2019 us=332052 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:32 2019 us=770800 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:33 2019 us=602016 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:33 2019 us=612434 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:34 2019 us=112213 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:34 2019 us=732351 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:34 2019 us=732568 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:35 2019 us=932319 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:35 2019 us=932515 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:36 2019 us=132213 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:36 2019 us=412324 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:36 2019 us=652094 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:36 2019 us=652210 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:37 2019 us=124421 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:37 2019 us=292214 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:37 2019 us=292335 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:37 2019 us=292375 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:37 2019 us=292416 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:37 2019 us=292454 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:37 2019 us=292492 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:37 2019 us=776927 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:38 2019 us=572049 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:38 2019 us=572152 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:38 2019 us=617856 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:38 2019 us=618750 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:39 2019 us=117573 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:39 2019 us=173704 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:39 2019 us=395902 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:39 2019 us=852140 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:39 2019 us=852270 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:39 2019 us=852330 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:40 2019 us=252313 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:40 2019 us=412267 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:42 2019 us=107673 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:42 2019 us=129853 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:42 2019 us=332218 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:42 2019 us=492413 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:42 2019 us=782795 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:43 2019 us=623901 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:43 2019 us=624096 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:44 2019 us=123056 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:44 2019 us=332123 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:44 2019 us=332250 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:44 2019 us=972190 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:46 2019 us=412425 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:46 2019 us=572023 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:46 2019 us=892076 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:47 2019 us=112095 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:47 2019 us=788263 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:48 2019 us=108200 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:50 2019 us=383995 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:50 2019 us=630159 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:51 2019 us=452247 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:53 2019 us=113362 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:53 2019 us=292297 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:53 2019 us=292682 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:53 2019 us=532074 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:54 2019 us=572199 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:55 2019 us=212346 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:55 2019 us=212510 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:55 2019 us=212554 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:55 2019 us=636600 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:56 2019 us=631306 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:57 2019 us=612284 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:58 2019 us=832589 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:41:59 2019 us=852049 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:00 2019 us=972496 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:00 2019 us=972737 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:01 2019 us=612076 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:01 2019 us=632112 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:01 2019 us=932309 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:06 2019 us=12211 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:06 2019 us=92573 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:11 2019 us=212067 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:11 2019 us=852270 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:13 2019 us=53730 [server_baOUcI0m0z2674zq] Inactivity timeout (--ping-restart), restarting
Thu Mar  7 16:42:13 2019 us=54705 TCP/UDP: Closing socket
Thu Mar  7 16:42:13 2019 us=54892 SIGUSR1[soft,ping-restart] received, process restarting
Thu Mar  7 16:42:13 2019 us=55010 Restart pause, 5 second(s)
Thu Mar  7 16:42:18 2019 us=55190 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 16:42:18 2019 us=55363 Re-using SSL/TLS context
Thu Mar  7 16:42:18 2019 us=55697 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 16:42:18 2019 us=55804 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 16:42:18 2019 us=55961 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 16:42:18 2019 us=56028 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 16:42:18 2019 us=56116 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:18 2019 us=56243 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 16:42:18 2019 us=56307 UDP link local: (not bound)
Thu Mar  7 16:42:18 2019 us=56377 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:18 2019 us=56963 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:20 2019 us=175733 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:24 2019 us=652251 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:32 2019 us=559598 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:42:48 2019 us=656796 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:43:18 2019 us=746012 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Thu Mar  7 16:43:18 2019 us=746222 TLS Error: TLS handshake failed
Thu Mar  7 16:43:18 2019 us=746634 TCP/UDP: Closing socket
Thu Mar  7 16:43:18 2019 us=746801 SIGUSR1[soft,tls-error] received, process restarting
Thu Mar  7 16:43:18 2019 us=746907 Restart pause, 5 second(s)
Thu Mar  7 16:43:23 2019 us=747087 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 16:43:23 2019 us=747297 Re-using SSL/TLS context
Thu Mar  7 16:43:23 2019 us=747638 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 16:43:23 2019 us=747809 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 16:43:23 2019 us=747963 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 16:43:23 2019 us=748030 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 16:43:23 2019 us=748122 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:43:23 2019 us=748241 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 16:43:23 2019 us=748305 UDP link local: (not bound)
Thu Mar  7 16:43:23 2019 us=748374 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:43:23 2019 us=748932 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:43:26 2019 us=119759 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:43:30 2019 us=382813 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:43:38 2019 us=793451 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:43:54 2019 us=812486 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:44:24 2019 us=54261 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Thu Mar  7 16:44:24 2019 us=54415 TLS Error: TLS handshake failed
Thu Mar  7 16:44:24 2019 us=54802 TCP/UDP: Closing socket
Thu Mar  7 16:44:24 2019 us=54941 SIGUSR1[soft,tls-error] received, process restarting
Thu Mar  7 16:44:24 2019 us=55041 Restart pause, 5 second(s)
Thu Mar  7 16:44:29 2019 us=55230 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 16:44:29 2019 us=55413 Re-using SSL/TLS context
Thu Mar  7 16:44:29 2019 us=55727 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 16:44:29 2019 us=55849 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 16:44:29 2019 us=55998 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 16:44:29 2019 us=56063 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 16:44:29 2019 us=56155 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:44:29 2019 us=56275 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 16:44:29 2019 us=56336 UDP link local: (not bound)
Thu Mar  7 16:44:29 2019 us=56405 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 16:44:29 2019 us=57108 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:44:31 2019 us=998691 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:44:36 2019 us=78442 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:44:44 2019 us=775706 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:45:00 2019 us=172274 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 16:45:29 2019 us=115464 TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity)
Thu Mar  7 16:45:29 2019 us=115640 TLS Error: TLS handshake failed
Thu Mar  7 16:45:29 2019 us=116049 TCP/UDP: Closing socket
Thu Mar  7 16:45:29 2019 us=116199 SIGUSR1[soft,tls-error] received, process restarting
Thu Mar  7 16:45:29 2019 us=116302 Restart pause, 5 second(s)

argyrg · Post by **argyrg** » Thu Mar 07, 2019 5:05 pm

I had previously looked at --connect-retry to see if there was any way to stop it doubling the retry interval and fixing it to a static value (such as 30 seconds) but reading through the manual there isn't any reference as to how this might be achieved.

I feel myself drifting towards having a script which, on reboot, will try to connect every 30 seconds until a link comes up and then, if the link subsequently goes down the script will then close the vpn connection before looping through the same actions (start, wait, check, close).

Post by **Pippin** » Thu Mar 07, 2019 5:23 pm

Everything works fine until my hotspot drops when I switch it off

Try comment/remove

Code: Select all

persist-tun

from config.

Post by **Pippin** » Thu Mar 07, 2019 5:29 pm

Code: Select all

Thu Mar  7 16:37:27 2019 us=477441 /etc/openvpn/update-resolv-conf tun0 1500 1552 10.8.0.2 255.255.255.0 init
dhcp-option DNS 8.8.8.8
dhcp-option DNS 8.8.4.4
Too few arguments.
Too few arguments.

Seems there is something wrong there too.....
https://github.com/angristan/openvpn-install/issues/149
https://bugs.debian.org/cgi-bin/bugrepo ... bug=881600

TinCanTech · Post by **TinCanTech** » Thu Mar 07, 2019 6:04 pm

@Pippin Thanks for the extra information, it is much appreciated.
Although, I'm not sure what we do now *scratches head* ..

@argyrg

argyrg wrote: ↑
Thu Mar 07, 2019 5:05 pm
I had previously looked at --connect-retry to see if there was any way to stop it doubling the retry interval and fixing it to a static value (such as 30 seconds) but reading through the manual there isn't any reference as to how this might be achieved

You must have read it wrong ..

The Manual wrote:--connect-retry n [max]
Wait n seconds between connection attempts (default=5). Repeated reconnection attempts are slowed down after 5 retries per remote by doubling the wait time after each unsuccessful attempt. The optional argument max specifies the maximum value of wait time in seconds at which it gets capped (default=300).

argyrg · Post by **argyrg** » Thu Mar 07, 2019 6:14 pm

Woooo

That made a difference. I commented persist-tun and my system now behaves like this..

Here's the verbose version (with a lot of repetitive stuff removed)

Code: Select all

Thu Mar  7 17:40:05 2019 us=542790 VERIFY OK: depth=0, CN=server_baOUcI0m0z2674zq
Thu Mar  7 17:40:05 2019 us=687462 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 ECDHE-ECDSA-AES128-GCM-SHA256
Thu Mar  7 17:40:05 2019 us=687606 [server_baOUcI0m0z2674zq] Peer Connection Initiated with [AF_INET]159.69.7.156:1194
Thu Mar  7 17:40:06 2019 us=869617 SENT CONTROL [server_baOUcI0m0z2674zq]: 'PUSH_REQUEST' (status=1)
Thu Mar  7 17:40:06 2019 us=935243 PUSH: Received control message: 'PUSH_REPLY,dhcp-option DNS 8.8.8.8,dhcp-option DNS 8.8.4.4,redirect-gateway def1 bypass-dhcp,route-gateway 10.8.0.1,topology subnet,ping 10,ping-restart 120,ifconfig 10.8.0.2 255.255.255.0,peer-id 0,cipher AES-128-GCM'
Thu Mar  7 17:40:06 2019 us=935818 OPTIONS IMPORT: timers and/or timeouts modified
Thu Mar  7 17:40:06 2019 us=935911 OPTIONS IMPORT: --ifconfig/up options modified
Thu Mar  7 17:40:06 2019 us=935972 OPTIONS IMPORT: route options modified
Thu Mar  7 17:40:06 2019 us=936158 OPTIONS IMPORT: route-related options modified
Thu Mar  7 17:40:06 2019 us=936259 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Thu Mar  7 17:40:06 2019 us=936360 OPTIONS IMPORT: peer-id set
Thu Mar  7 17:40:06 2019 us=936460 OPTIONS IMPORT: adjusting link_mtu to 1624
Thu Mar  7 17:40:06 2019 us=936557 OPTIONS IMPORT: data channel crypto options modified
Thu Mar  7 17:40:06 2019 us=936700 Data Channel MTU parms [ L:1552 D:1450 EF:52 EB:406 ET:0 EL:3 ]
Thu Mar  7 17:40:06 2019 us=937402 Data Channel Encrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 17:40:06 2019 us=937557 Data Channel Decrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 17:40:06 2019 us=938198 ROUTE_GATEWAY 192.168.43.1/255.255.255.0 IFACE=wlan0 HWADDR=b8:27:eb:5c:71:46
Thu Mar  7 17:40:06 2019 us=952277 TUN/TAP device tun0 opened
Thu Mar  7 17:40:06 2019 us=952934 TUN/TAP TX queue length set to 100
Thu Mar  7 17:40:06 2019 us=955941 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Thu Mar  7 17:40:06 2019 us=956135 /sbin/ip link set dev tun0 up mtu 1500
Thu Mar  7 17:40:06 2019 us=965186 /sbin/ip addr add dev tun0 10.8.0.2/24 broadcast 10.8.0.255
Thu Mar  7 17:40:06 2019 us=974355 /etc/openvpn/update-resolv-conf tun0 1500 1552 10.8.0.2 255.255.255.0 init
dhcp-option DNS 8.8.8.8
dhcp-option DNS 8.8.4.4
Too few arguments.
Too few arguments.
Thu Mar  7 17:40:07 2019 us=121103 /sbin/ip route add 159.69.7.156/32 via 192.168.43.1
Thu Mar  7 17:40:07 2019 us=124047 /sbin/ip route add 0.0.0.0/1 via 10.8.0.1
Thu Mar  7 17:40:07 2019 us=135317 /sbin/ip route add 128.0.0.0/1 via 10.8.0.1
Thu Mar  7 17:40:07 2019 us=138563 Initialization Sequence Completed
Thu Mar  7 17:40:16 2019 us=979966 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:40:18 2019 us=641216 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
<snip>
Thu Mar  7 17:40:20 2019 us=677478 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:43:07 2019 us=962133 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:43:09 2019 us=760972 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:43:11 2019 us=249964 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:43:11 2019 us=660450 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:43:13 2019 us=789954 [server_baOUcI0m0z2674zq] Inactivity timeout (--ping-restart), restarting
Thu Mar  7 17:43:13 2019 us=790913 TCP/UDP: Closing socket
Thu Mar  7 17:43:13 2019 us=791200 /sbin/ip route del 159.69.7.156/32
RTNETLINK answers: No such process
Thu Mar  7 17:43:13 2019 us=797059 ERROR: Linux route delete command failed: external program exited with error status: 2
Thu Mar  7 17:43:13 2019 us=797301 /sbin/ip route del 0.0.0.0/1
Thu Mar  7 17:43:13 2019 us=803071 /sbin/ip route del 128.0.0.0/1
Thu Mar  7 17:43:13 2019 us=809101 Closing TUN/TAP interface
Thu Mar  7 17:43:13 2019 us=809363 /sbin/ip addr del dev tun0 10.8.0.2/24
Thu Mar  7 17:43:13 2019 us=880851 /etc/openvpn/update-resolv-conf tun0 1500 1552 10.8.0.2 255.255.255.0 init
Too few arguments.
Too few arguments.
Thu Mar  7 17:43:14 2019 us=14102 SIGUSR1[soft,ping-restart] received, process restarting
Thu Mar  7 17:43:14 2019 us=14241 Restart pause, 5 second(s)
Thu Mar  7 17:43:19 2019 us=15116 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 17:43:19 2019 us=15303 Re-using SSL/TLS context
Thu Mar  7 17:43:19 2019 us=15724 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 17:43:19 2019 us=15862 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 17:43:19 2019 us=16083 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 17:43:19 2019 us=16157 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 17:43:19 2019 us=16283 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 17:43:19 2019 us=16416 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 17:43:19 2019 us=16490 UDP link local: (not bound)
Thu Mar  7 17:43:19 2019 us=16559 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 17:43:19 2019 us=291736 TLS: Initial packet from [AF_INET]159.69.7.156:1194, sid=510ddd84 327f8966
Thu Mar  7 17:43:19 2019 us=358909 VERIFY OK: depth=1, CN=cn_W0ulBoBQjyXgMUEw
Thu Mar  7 17:43:19 2019 us=370073 Validating certificate key usage
Thu Mar  7 17:43:19 2019 us=370256 ++ Certificate has key usage  00a0, expects 00a0
Thu Mar  7 17:43:19 2019 us=370375 VERIFY KU OK
Thu Mar  7 17:43:19 2019 us=370478 Validating certificate extended key usage
Thu Mar  7 17:43:19 2019 us=370556 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Thu Mar  7 17:43:19 2019 us=370625 VERIFY EKU OK
Thu Mar  7 17:43:19 2019 us=370682 VERIFY X509NAME OK: CN=server_baOUcI0m0z2674zq
Thu Mar  7 17:43:19 2019 us=370742 VERIFY OK: depth=0, CN=server_baOUcI0m0z2674zq
Thu Mar  7 17:43:19 2019 us=547300 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 ECDHE-ECDSA-AES128-GCM-SHA256
Thu Mar  7 17:43:19 2019 us=547451 [server_baOUcI0m0z2674zq] Peer Connection Initiated with [AF_INET]159.69.7.156:1194
Thu Mar  7 17:43:20 2019 us=563951 SENT CONTROL [server_baOUcI0m0z2674zq]: 'PUSH_REQUEST' (status=1)
Thu Mar  7 17:43:20 2019 us=856321 PUSH: Received control message: 'PUSH_REPLY,dhcp-option DNS 8.8.8.8,dhcp-option DNS 8.8.4.4,redirect-gateway def1 bypass-dhcp,route-gateway 10.8.0.1,topology subnet,ping 10,ping-restart 120,ifconfig 10.8.0.2 255.255.255.0,peer-id 1,cipher AES-128-GCM'
Thu Mar  7 17:43:20 2019 us=856620 OPTIONS IMPORT: timers and/or timeouts modified
Thu Mar  7 17:43:20 2019 us=856663 OPTIONS IMPORT: --ifconfig/up options modified
Thu Mar  7 17:43:20 2019 us=856691 OPTIONS IMPORT: route options modified
Thu Mar  7 17:43:20 2019 us=856717 OPTIONS IMPORT: route-related options modified
Thu Mar  7 17:43:20 2019 us=856742 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Thu Mar  7 17:43:20 2019 us=856767 OPTIONS IMPORT: peer-id set
Thu Mar  7 17:43:20 2019 us=856792 OPTIONS IMPORT: adjusting link_mtu to 1624
Thu Mar  7 17:43:20 2019 us=856816 OPTIONS IMPORT: data channel crypto options modified
Thu Mar  7 17:43:20 2019 us=856865 Data Channel MTU parms [ L:1552 D:1450 EF:52 EB:406 ET:0 EL:3 ]
Thu Mar  7 17:43:20 2019 us=857155 Data Channel Encrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 17:43:20 2019 us=857192 Data Channel Decrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 17:43:20 2019 us=857502 ROUTE_GATEWAY 192.168.43.1/255.255.255.0 IFACE=wlan0 HWADDR=b8:27:eb:5c:71:46
Thu Mar  7 17:43:20 2019 us=858222 TUN/TAP device tun0 opened
Thu Mar  7 17:43:20 2019 us=858414 TUN/TAP TX queue length set to 100
Thu Mar  7 17:43:20 2019 us=858495 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Thu Mar  7 17:43:20 2019 us=858556 /sbin/ip link set dev tun0 up mtu 1500
Thu Mar  7 17:43:20 2019 us=864152 /sbin/ip addr add dev tun0 10.8.0.2/24 broadcast 10.8.0.255
Thu Mar  7 17:43:20 2019 us=867950 /etc/openvpn/update-resolv-conf tun0 1500 1552 10.8.0.2 255.255.255.0 init
dhcp-option DNS 8.8.8.8
dhcp-option DNS 8.8.4.4
Too few arguments.
Too few arguments.
Thu Mar  7 17:43:20 2019 us=989541 /sbin/ip route add 159.69.7.156/32 via 192.168.43.1
Thu Mar  7 17:43:20 2019 us=992694 /sbin/ip route add 0.0.0.0/1 via 10.8.0.1
Thu Mar  7 17:43:20 2019 us=996094 /sbin/ip route add 128.0.0.0/1 via 10.8.0.1
Thu Mar  7 17:43:20 2019 us=999210 Initialization Sequence Completed
Thu Mar  7 17:44:20 2019 us=706365 PID_ERR replay-window backtrack occurred [1] [SSL-0] [0_00000000000000000111111111111111111111111111111111111111111111] 0:881 0:880 t=1551980660[0] r=[-1,64,15,1,1] sl=[15,64,64,272]
Thu Mar  7 17:44:50 2019 us=30895 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:44:50 2019 us=31088 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:44:50 2019 us=40800 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:44:50 2019 us=40965 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:44:50 2019 us=51065 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:44:50 2019 us=51254 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
<snip>
Thu Mar  7 17:46:34 2019 us=960890 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:35 2019 us=627404 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:36 2019 us=240667 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:36 2019 us=452329 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:36 2019 us=467181 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:38 2019 us=892403 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:39 2019 us=322044 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:40 2019 us=80780 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:43 2019 us=897912 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:44 2019 us=560848 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:44 2019 us=561038 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:46 2019 us=480839 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:46 2019 us=481047 Recursive routing detected, drop tun packet to [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:47 2019 us=86783 [server_baOUcI0m0z2674zq] Inactivity timeout (--ping-restart), restarting
Thu Mar  7 17:46:47 2019 us=87160 TCP/UDP: Closing socket
Thu Mar  7 17:46:47 2019 us=87295 /sbin/ip route del 159.69.7.156/32
RTNETLINK answers: No such process
Thu Mar  7 17:46:47 2019 us=92085 ERROR: Linux route delete command failed: external program exited with error status: 2
Thu Mar  7 17:46:47 2019 us=92203 /sbin/ip route del 0.0.0.0/1
Thu Mar  7 17:46:47 2019 us=95025 /sbin/ip route del 128.0.0.0/1
Thu Mar  7 17:46:47 2019 us=98035 Closing TUN/TAP interface
Thu Mar  7 17:46:47 2019 us=98202 /sbin/ip addr del dev tun0 10.8.0.2/24
Thu Mar  7 17:46:47 2019 us=140838 /etc/openvpn/update-resolv-conf tun0 1500 1552 10.8.0.2 255.255.255.0 init
Too few arguments.
Too few arguments.
Thu Mar  7 17:46:47 2019 us=251396 SIGUSR1[soft,ping-restart] received, process restarting
Thu Mar  7 17:46:47 2019 us=251724 Restart pause, 5 second(s)
Thu Mar  7 17:46:52 2019 us=251982 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 17:46:52 2019 us=252202 Re-using SSL/TLS context
Thu Mar  7 17:46:52 2019 us=252614 Control Channel MTU parms [ L:1621 D:1156 EF:94 EB:0 ET:0 EL:3 ]
Thu Mar  7 17:46:52 2019 us=252763 Data Channel MTU parms [ L:1621 D:1450 EF:121 EB:406 ET:0 EL:3 ]
Thu Mar  7 17:46:52 2019 us=252968 Local Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-client'
Thu Mar  7 17:46:52 2019 us=253043 Expected Remote Options String (VER=V4): 'V4,dev-type tun,link-mtu 1549,tun-mtu 1500,proto UDPv4,cipher AES-128-GCM,auth [null-digest],keysize 128,key-method 2,tls-server'
Thu Mar  7 17:46:52 2019 us=253146 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:52 2019 us=253277 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 17:46:52 2019 us=253339 UDP link local: (not bound)
Thu Mar  7 17:46:52 2019 us=253412 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:52 2019 us=385824 TLS: Initial packet from [AF_INET]159.69.7.156:1194, sid=3df6bd1c 16c0e717
Thu Mar  7 17:46:52 2019 us=463630 VERIFY OK: depth=1, CN=cn_W0ulBoBQjyXgMUEw
Thu Mar  7 17:46:52 2019 us=468314 Validating certificate key usage
Thu Mar  7 17:46:52 2019 us=468376 ++ Certificate has key usage  00a0, expects 00a0
Thu Mar  7 17:46:52 2019 us=468406 VERIFY KU OK
Thu Mar  7 17:46:52 2019 us=468446 Validating certificate extended key usage
Thu Mar  7 17:46:52 2019 us=468482 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Thu Mar  7 17:46:52 2019 us=468510 VERIFY EKU OK
Thu Mar  7 17:46:52 2019 us=468535 VERIFY X509NAME OK: CN=server_baOUcI0m0z2674zq
Thu Mar  7 17:46:52 2019 us=468560 VERIFY OK: depth=0, CN=server_baOUcI0m0z2674zq
Thu Mar  7 17:46:52 2019 us=642805 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 ECDHE-ECDSA-AES128-GCM-SHA256
Thu Mar  7 17:46:52 2019 us=642959 [server_baOUcI0m0z2674zq] Peer Connection Initiated with [AF_INET]159.69.7.156:1194
Thu Mar  7 17:46:53 2019 us=718191 SENT CONTROL [server_baOUcI0m0z2674zq]: 'PUSH_REQUEST' (status=1)
Thu Mar  7 17:46:53 2019 us=821297 PUSH: Received control message: 'PUSH_REPLY,dhcp-option DNS 8.8.8.8,dhcp-option DNS 8.8.4.4,redirect-gateway def1 bypass-dhcp,route-gateway 10.8.0.1,topology subnet,ping 10,ping-restart 120,ifconfig 10.8.0.2 255.255.255.0,peer-id 0,cipher AES-128-GCM'
Thu Mar  7 17:46:53 2019 us=821599 OPTIONS IMPORT: timers and/or timeouts modified
Thu Mar  7 17:46:53 2019 us=821645 OPTIONS IMPORT: --ifconfig/up options modified
Thu Mar  7 17:46:53 2019 us=821673 OPTIONS IMPORT: route options modified
Thu Mar  7 17:46:53 2019 us=821699 OPTIONS IMPORT: route-related options modified
Thu Mar  7 17:46:53 2019 us=821724 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Thu Mar  7 17:46:53 2019 us=821748 OPTIONS IMPORT: peer-id set
Thu Mar  7 17:46:53 2019 us=821774 OPTIONS IMPORT: adjusting link_mtu to 1624
Thu Mar  7 17:46:53 2019 us=821799 OPTIONS IMPORT: data channel crypto options modified
Thu Mar  7 17:46:53 2019 us=821845 Data Channel MTU parms [ L:1552 D:1450 EF:52 EB:406 ET:0 EL:3 ]
Thu Mar  7 17:46:53 2019 us=822139 Data Channel Encrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 17:46:53 2019 us=822174 Data Channel Decrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 17:46:53 2019 us=822483 ROUTE_GATEWAY 192.168.43.1/255.255.255.0 IFACE=wlan0 HWADDR=b8:27:eb:5c:71:46
Thu Mar  7 17:46:53 2019 us=823070 TUN/TAP device tun0 opened
Thu Mar  7 17:46:53 2019 us=823243 TUN/TAP TX queue length set to 100
Thu Mar  7 17:46:53 2019 us=823372 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Thu Mar  7 17:46:53 2019 us=823438 /sbin/ip link set dev tun0 up mtu 1500
Thu Mar  7 17:46:53 2019 us=827166 /sbin/ip addr add dev tun0 10.8.0.2/24 broadcast 10.8.0.255
Thu Mar  7 17:46:53 2019 us=832056 /etc/openvpn/update-resolv-conf tun0 1500 1552 10.8.0.2 255.255.255.0 init
dhcp-option DNS 8.8.8.8
dhcp-option DNS 8.8.4.4
Too few arguments.
Too few arguments.
Thu Mar  7 17:46:53 2019 us=950726 /sbin/ip route add 159.69.7.156/32 via 192.168.43.1
Thu Mar  7 17:46:53 2019 us=954153 /sbin/ip route add 0.0.0.0/1 via 10.8.0.1
Thu Mar  7 17:46:53 2019 us=957297 /sbin/ip route add 128.0.0.0/1 via 10.8.0.1
Thu Mar  7 17:46:53 2019 us=960454 Initialization Sequence Completed

and a second run at verb 3

Code: Select all

q
Thu Mar  7 17:49:17 2019 OpenVPN 2.4.0 arm-unknown-linux-gnueabihf [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Oct 14 2018
Thu Mar  7 17:49:17 2019 library versions: OpenSSL 1.0.2r  26 Feb 2019, LZO 2.08
Thu Mar  7 17:49:17 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 17:49:17 2019 Outgoing Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
Thu Mar  7 17:49:17 2019 Outgoing Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
Thu Mar  7 17:49:17 2019 Incoming Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
Thu Mar  7 17:49:17 2019 Incoming Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
Thu Mar  7 17:49:17 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 17:49:17 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 17:49:17 2019 UDP link local: (not bound)
Thu Mar  7 17:49:17 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 17:49:18 2019 TLS: Initial packet from [AF_INET]159.69.7.156:1194, sid=e475946d a25d4d0d
Thu Mar  7 17:49:18 2019 VERIFY OK: depth=1, CN=cn_W0ulBoBQjyXgMUEw
Thu Mar  7 17:49:18 2019 Validating certificate key usage
Thu Mar  7 17:49:18 2019 ++ Certificate has key usage  00a0, expects 00a0
Thu Mar  7 17:49:18 2019 VERIFY KU OK
Thu Mar  7 17:49:18 2019 Validating certificate extended key usage
Thu Mar  7 17:49:18 2019 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Thu Mar  7 17:49:18 2019 VERIFY EKU OK
Thu Mar  7 17:49:18 2019 VERIFY X509NAME OK: CN=server_baOUcI0m0z2674zq
Thu Mar  7 17:49:18 2019 VERIFY OK: depth=0, CN=server_baOUcI0m0z2674zq
Thu Mar  7 17:49:18 2019 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 ECDHE-ECDSA-AES128-GCM-SHA256
Thu Mar  7 17:49:18 2019 [server_baOUcI0m0z2674zq] Peer Connection Initiated with [AF_INET]159.69.7.156:1194
Thu Mar  7 17:49:19 2019 SENT CONTROL [server_baOUcI0m0z2674zq]: 'PUSH_REQUEST' (status=1)
Thu Mar  7 17:49:19 2019 PUSH: Received control message: 'PUSH_REPLY,dhcp-option DNS 8.8.8.8,dhcp-option DNS 8.8.4.4,redirect-gateway def1 bypass-dhcp,route-gateway 10.8.0.1,topology subnet,ping 10,ping-restart 120,ifconfig 10.8.0.2 255.255.255.0,peer-id 1,cipher AES-128-GCM'
Thu Mar  7 17:49:19 2019 OPTIONS IMPORT: timers and/or timeouts modified
Thu Mar  7 17:49:19 2019 OPTIONS IMPORT: --ifconfig/up options modified
Thu Mar  7 17:49:19 2019 OPTIONS IMPORT: route options modified
Thu Mar  7 17:49:19 2019 OPTIONS IMPORT: route-related options modified
Thu Mar  7 17:49:19 2019 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Thu Mar  7 17:49:19 2019 OPTIONS IMPORT: peer-id set
Thu Mar  7 17:49:19 2019 OPTIONS IMPORT: adjusting link_mtu to 1624
Thu Mar  7 17:49:19 2019 OPTIONS IMPORT: data channel crypto options modified
Thu Mar  7 17:49:19 2019 Data Channel Encrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 17:49:19 2019 Data Channel Decrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 17:49:19 2019 ROUTE_GATEWAY 192.168.43.1/255.255.255.0 IFACE=wlan0 HWADDR=b8:27:eb:5c:71:46
Thu Mar  7 17:49:19 2019 TUN/TAP device tun0 opened
Thu Mar  7 17:49:19 2019 TUN/TAP TX queue length set to 100
Thu Mar  7 17:49:19 2019 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Thu Mar  7 17:49:19 2019 /sbin/ip link set dev tun0 up mtu 1500
Thu Mar  7 17:49:19 2019 /sbin/ip addr add dev tun0 10.8.0.2/24 broadcast 10.8.0.255
Thu Mar  7 17:49:19 2019 /etc/openvpn/update-resolv-conf tun0 1500 1552 10.8.0.2 255.255.255.0 init
dhcp-option DNS 8.8.8.8
dhcp-option DNS 8.8.4.4
Too few arguments.
Too few arguments.
Thu Mar  7 17:49:19 2019 /sbin/ip route add 159.69.7.156/32 via 192.168.43.1
Thu Mar  7 17:49:19 2019 /sbin/ip route add 0.0.0.0/1 via 10.8.0.1
Thu Mar  7 17:49:19 2019 /sbin/ip route add 128.0.0.0/1 via 10.8.0.1
Thu Mar  7 17:49:19 2019 Initialization Sequence Completed
Thu Mar  7 17:51:22 2019 [server_baOUcI0m0z2674zq] Inactivity timeout (--ping-restart), restarting
Thu Mar  7 17:51:22 2019 /sbin/ip route del 159.69.7.156/32
RTNETLINK answers: No such process
Thu Mar  7 17:51:22 2019 ERROR: Linux route delete command failed: external program exited with error status: 2
Thu Mar  7 17:51:22 2019 /sbin/ip route del 0.0.0.0/1
Thu Mar  7 17:51:22 2019 /sbin/ip route del 128.0.0.0/1
Thu Mar  7 17:51:22 2019 Closing TUN/TAP interface
Thu Mar  7 17:51:22 2019 /sbin/ip addr del dev tun0 10.8.0.2/24
Thu Mar  7 17:51:22 2019 /etc/openvpn/update-resolv-conf tun0 1500 1552 10.8.0.2 255.255.255.0 init
Too few arguments.
Too few arguments.
Thu Mar  7 17:51:22 2019 SIGUSR1[soft,ping-restart] received, process restarting
Thu Mar  7 17:51:22 2019 Restart pause, 5 second(s)
Thu Mar  7 17:51:27 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Thu Mar  7 17:51:27 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Thu Mar  7 17:51:27 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Thu Mar  7 17:51:27 2019 UDP link local: (not bound)
Thu Mar  7 17:51:27 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Thu Mar  7 17:51:27 2019 TLS: Initial packet from [AF_INET]159.69.7.156:1194, sid=dd359947 f272a5d0
Thu Mar  7 17:51:27 2019 VERIFY OK: depth=1, CN=cn_W0ulBoBQjyXgMUEw
Thu Mar  7 17:51:27 2019 Validating certificate key usage
Thu Mar  7 17:51:27 2019 ++ Certificate has key usage  00a0, expects 00a0
Thu Mar  7 17:51:27 2019 VERIFY KU OK
Thu Mar  7 17:51:27 2019 Validating certificate extended key usage
Thu Mar  7 17:51:27 2019 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Thu Mar  7 17:51:27 2019 VERIFY EKU OK
Thu Mar  7 17:51:27 2019 VERIFY X509NAME OK: CN=server_baOUcI0m0z2674zq
Thu Mar  7 17:51:27 2019 VERIFY OK: depth=0, CN=server_baOUcI0m0z2674zq
Thu Mar  7 17:51:27 2019 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 ECDHE-ECDSA-AES128-GCM-SHA256
Thu Mar  7 17:51:27 2019 [server_baOUcI0m0z2674zq] Peer Connection Initiated with [AF_INET]159.69.7.156:1194
Thu Mar  7 17:51:28 2019 SENT CONTROL [server_baOUcI0m0z2674zq]: 'PUSH_REQUEST' (status=1)
Thu Mar  7 17:51:28 2019 PUSH: Received control message: 'PUSH_REPLY,dhcp-option DNS 8.8.8.8,dhcp-option DNS 8.8.4.4,redirect-gateway def1 bypass-dhcp,route-gateway 10.8.0.1,topology subnet,ping 10,ping-restart 120,ifconfig 10.8.0.2 255.255.255.0,peer-id 0,cipher AES-128-GCM'
Thu Mar  7 17:51:28 2019 OPTIONS IMPORT: timers and/or timeouts modified
Thu Mar  7 17:51:28 2019 OPTIONS IMPORT: --ifconfig/up options modified
Thu Mar  7 17:51:28 2019 OPTIONS IMPORT: route options modified
Thu Mar  7 17:51:28 2019 OPTIONS IMPORT: route-related options modified
Thu Mar  7 17:51:28 2019 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Thu Mar  7 17:51:28 2019 OPTIONS IMPORT: peer-id set
Thu Mar  7 17:51:28 2019 OPTIONS IMPORT: adjusting link_mtu to 1624
Thu Mar  7 17:51:28 2019 OPTIONS IMPORT: data channel crypto options modified
Thu Mar  7 17:51:28 2019 Data Channel Encrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 17:51:28 2019 Data Channel Decrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Thu Mar  7 17:51:28 2019 ROUTE_GATEWAY 192.168.43.1/255.255.255.0 IFACE=wlan0 HWADDR=b8:27:eb:5c:71:46
Thu Mar  7 17:51:28 2019 TUN/TAP device tun0 opened
Thu Mar  7 17:51:28 2019 TUN/TAP TX queue length set to 100
Thu Mar  7 17:51:28 2019 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Thu Mar  7 17:51:28 2019 /sbin/ip link set dev tun0 up mtu 1500
Thu Mar  7 17:51:28 2019 /sbin/ip addr add dev tun0 10.8.0.2/24 broadcast 10.8.0.255
Thu Mar  7 17:51:28 2019 /etc/openvpn/update-resolv-conf tun0 1500 1552 10.8.0.2 255.255.255.0 init
dhcp-option DNS 8.8.8.8
dhcp-option DNS 8.8.4.4
Too few arguments.
Too few arguments.
Thu Mar  7 17:51:28 2019 /sbin/ip route add 159.69.7.156/32 via 192.168.43.1
Thu Mar  7 17:51:28 2019 /sbin/ip route add 0.0.0.0/1 via 10.8.0.1
Thu Mar  7 17:51:28 2019 /sbin/ip route add 128.0.0.0/1 via 10.8.0.1
Thu Mar  7 17:51:28 2019 Initialization Sequence Completed

The "Too few arguments" bit made me wonder a bit too. I don't remember that appearing in the output until I made a different client installation with the openvpn-systemd-resolve-1.2.3-1 included. Doing that helped me to resolve my nameserver issue (Thanks TinCanTech) but I didn't dig any deeper because I moved on to this keepalive issue.

So, commenting out persist-tun seems to have done the job. I'll do a bit of stress testing on it over the next couple of days to see if I can break it.

Meanwhile I'll follow up your links which point to issues with my server installation.

Many, many thanks

argyrg

argyrg · Post by **argyrg** » Thu Mar 07, 2019 6:22 pm

Thank you for clarifying that, I had missed the important point

If I were to use "--connect-retry infinite 30" then it would forever wait for the connection to come up and retry every 30 seconds?

TinCanTech wrote: ↑
Thu Mar 07, 2019 6:04 pm
@Pippin Thanks for the extra information, it is much appreciated.
Although, I'm not sure what we do now *scratches head* ..

@argyrg
argyrg wrote: ↑
Thu Mar 07, 2019 5:05 pm
I had previously looked at --connect-retry to see if there was any way to stop it doubling the retry interval and fixing it to a static value (such as 30 seconds) but reading through the manual there isn't any reference as to how this might be achieved
You must have read it wrong ..
The Manual wrote:--connect-retry n [max]
Wait n seconds between connection attempts (default=5). Repeated reconnection attempts are slowed down after 5 retries per remote by doubling the wait time after each unsuccessful attempt. The optional argument max specifies the maximum value of wait time in seconds at which it gets capped (default=300).

TinCanTech · Post by **TinCanTech** » Thu Mar 07, 2019 6:46 pm

argyrg wrote: ↑
Thu Mar 07, 2019 6:22 pm
If I were to use "--connect-retry infinite 30" then it would forever wait for the connection to come up and retry every 30 seconds?

No, it would error out .. please re-read the manual. {hint i guess .. --connect-retry 5 30}

argyrg · Post by **argyrg** » Thu Mar 07, 2019 7:46 pm

That makes more sense

I was getting myself confused with resolve-retry infinite

That was a great hint

Post by **Pippin** » Thu Mar 07, 2019 8:49 pm

TinCanTech wrote: ↑
Thu Mar 07, 2019 6:04 pm
Although, I'm not sure what we do now *scratches head* ..

Just some more info, seems related to resolvconf/openresolv
https://github.com/wknapik/vpnfailsafe/issues/23
https://forums.linuxmint.com/viewtopic.php?t=272446

TinCanTech · Post by **TinCanTech** » Thu Mar 07, 2019 10:07 pm

This thread is now about resolv.conf

And not a config file in sight *sigh*

For the sake of sanity and posterity, in future please see:
viewtopic.php?f=30&t=22603#p68963

(@Pippin not directed at you

thanks for your help)

argyrg · Post by **argyrg** » Fri Mar 08, 2019 10:27 am

And I thought we'd resolved the resolv.conf issue when you helped me with my previous post

argyrg · Post by **argyrg** » Fri Mar 08, 2019 10:58 am

I really appreciate the support that I've received on this site from both TinCanTech and Pippin

I've just read through the guidelines about posting viewtopic.php?f=30&t=22603#p68963 so my apologies for not reading them earlier.

The issue which I raised in this post has been resolved by commenting out #persist-tun in my .ovpn file and I have been alerted to other issues which were originally raised in my earlier post viewtopic.php?f=4&t=28008 so I'm going to head over there and carry on the troubleshooting.

For reference, my ,ovpn file is shown below along with my server config and the resulting output when running the config file from the command line with
sudo openvpn --config /etc/openvpn/my.ovpn

server.conf

Code: Select all

port 1194
proto udp
dev tun
user nobody
group nogroup
persist-key
persist-tun
keepalive 10 120
topology subnet
server 10.8.0.0 255.255.255.0
ifconfig-pool-persist ipp.txt
push "dhcp-option DNS 8.8.8.8"
push "dhcp-option DNS 8.8.4.4"
push "redirect-gateway def1 bypass-dhcp"
dh none
ecdh-curve prime256v1
tls-crypt tls-crypt.key 0
crl-verify crl.pem
ca ca.crt
cert server_baOUcI0m0z2674zq.crt
key server_baOUcI0m0z2674zq.key
auth SHA256
cipher AES-128-GCM
ncp-ciphers AES-128-GCM
tls-server
tls-version-min 1.2
tls-cipher TLS-ECDHE-ECDSA-WITH-AES-128-GCM-SHA256
status /var/log/openvpn/status.log
verb 4

my.ovpn

Code: Select all

client
proto udp
remote 159.69.7.156 1194
dev tun
resolv-retry infinite
nobind
persist-key
#persist-tun
remote-cert-tls server
verify-x509-name server_baOUcI0m0z2674zq name
auth SHA256
auth-nocache
cipher AES-128-GCM
tls-client
tls-version-min 1.2
tls-cipher TLS-ECDHE-ECDSA-WITH-AES-128-GCM-SHA256
#setenv opt block-outside-dns # Prevent Windows 10 DNS leak
verb 3

script-security 2
up /etc/openvpn/update-resolv-conf
down /etc/openvpn/update-resolv-conf

Output of client when launched (note the presence of 2x "Too few arguments" near the bottom which I will troubleshoot on the other link as I understand that they are related to the poor way in which debian clients manage openvpn

Code: Select all

Fri Mar  8 10:33:20 2019 OpenVPN 2.4.0 arm-unknown-linux-gnueabihf [SSL (OpenSSL)] [LZO] [LZ4] [EPOLL] [PKCS11] [MH/PKTINFO] [AEAD] built on Oct 14 2018
Fri Mar  8 10:33:20 2019 library versions: OpenSSL 1.0.2r  26 Feb 2019, LZO 2.08
Fri Mar  8 10:33:20 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Fri Mar  8 10:33:20 2019 Outgoing Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
Fri Mar  8 10:33:20 2019 Outgoing Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
Fri Mar  8 10:33:20 2019 Incoming Control Channel Encryption: Cipher 'AES-256-CTR' initialized with 256 bit key
Fri Mar  8 10:33:20 2019 Incoming Control Channel Encryption: Using 256 bit message hash 'SHA256' for HMAC authentication
Fri Mar  8 10:33:20 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Fri Mar  8 10:33:20 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Fri Mar  8 10:33:20 2019 UDP link local: (not bound)
Fri Mar  8 10:33:20 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Fri Mar  8 10:33:20 2019 write UDP: Network is unreachable (code=101)
Fri Mar  8 10:33:20 2019 Network unreachable, restarting
Fri Mar  8 10:33:20 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Fri Mar  8 10:33:20 2019 Restart pause, 5 second(s)
Fri Mar  8 10:33:25 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Fri Mar  8 10:33:25 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Fri Mar  8 10:33:25 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Fri Mar  8 10:33:25 2019 UDP link local: (not bound)
Fri Mar  8 10:33:25 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Fri Mar  8 10:33:25 2019 write UDP: Network is unreachable (code=101)
Fri Mar  8 10:33:25 2019 Network unreachable, restarting
Fri Mar  8 10:33:25 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Fri Mar  8 10:33:25 2019 Restart pause, 5 second(s)
Fri Mar  8 10:33:30 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Fri Mar  8 10:33:30 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Fri Mar  8 10:33:30 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Fri Mar  8 10:33:30 2019 UDP link local: (not bound)
Fri Mar  8 10:33:30 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Fri Mar  8 10:33:30 2019 write UDP: Network is unreachable (code=101)
Fri Mar  8 10:33:30 2019 Network unreachable, restarting
Fri Mar  8 10:33:30 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Fri Mar  8 10:33:30 2019 Restart pause, 5 second(s)
Fri Mar  8 10:33:35 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Fri Mar  8 10:33:35 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Fri Mar  8 10:33:35 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Fri Mar  8 10:33:35 2019 UDP link local: (not bound)
Fri Mar  8 10:33:35 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Fri Mar  8 10:33:35 2019 write UDP: Network is unreachable (code=101)
Fri Mar  8 10:33:35 2019 Network unreachable, restarting
Fri Mar  8 10:33:35 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Fri Mar  8 10:33:35 2019 Restart pause, 5 second(s)
Fri Mar  8 10:33:40 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Fri Mar  8 10:33:40 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Fri Mar  8 10:33:40 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Fri Mar  8 10:33:40 2019 UDP link local: (not bound)
Fri Mar  8 10:33:40 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Fri Mar  8 10:33:40 2019 write UDP: Network is unreachable (code=101)
Fri Mar  8 10:33:40 2019 Network unreachable, restarting
Fri Mar  8 10:33:40 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Fri Mar  8 10:33:40 2019 Restart pause, 10 second(s)
Fri Mar  8 10:33:50 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Fri Mar  8 10:33:50 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Fri Mar  8 10:33:50 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Fri Mar  8 10:33:50 2019 UDP link local: (not bound)
Fri Mar  8 10:33:50 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Fri Mar  8 10:33:50 2019 write UDP: Network is unreachable (code=101)
Fri Mar  8 10:33:50 2019 Network unreachable, restarting
Fri Mar  8 10:33:50 2019 SIGUSR1[soft,network-unreachable] received, process restarting
Fri Mar  8 10:33:50 2019 Restart pause, 20 second(s)
Fri Mar  8 10:34:10 2019 NOTE: the current --script-security setting may allow this configuration to call user-defined scripts
Fri Mar  8 10:34:10 2019 TCP/UDP: Preserving recently used remote address: [AF_INET]159.69.7.156:1194
Fri Mar  8 10:34:10 2019 Socket Buffers: R=[163840->163840] S=[163840->163840]
Fri Mar  8 10:34:10 2019 UDP link local: (not bound)
Fri Mar  8 10:34:10 2019 UDP link remote: [AF_INET]159.69.7.156:1194
Fri Mar  8 10:34:10 2019 TLS: Initial packet from [AF_INET]159.69.7.156:1194, sid=ac00e41a cdafd7f8
Fri Mar  8 10:34:10 2019 VERIFY OK: depth=1, CN=cn_W0ulBoBQjyXgMUEw
Fri Mar  8 10:34:10 2019 Validating certificate key usage
Fri Mar  8 10:34:10 2019 ++ Certificate has key usage  00a0, expects 00a0
Fri Mar  8 10:34:10 2019 VERIFY KU OK
Fri Mar  8 10:34:10 2019 Validating certificate extended key usage
Fri Mar  8 10:34:10 2019 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication
Fri Mar  8 10:34:10 2019 VERIFY EKU OK
Fri Mar  8 10:34:10 2019 VERIFY X509NAME OK: CN=server_baOUcI0m0z2674zq
Fri Mar  8 10:34:10 2019 VERIFY OK: depth=0, CN=server_baOUcI0m0z2674zq
Fri Mar  8 10:34:10 2019 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 ECDHE-ECDSA-AES128-GCM-SHA256
Fri Mar  8 10:34:10 2019 [server_baOUcI0m0z2674zq] Peer Connection Initiated with [AF_INET]159.69.7.156:1194
Fri Mar  8 10:34:11 2019 SENT CONTROL [server_baOUcI0m0z2674zq]: 'PUSH_REQUEST' (status=1)
Fri Mar  8 10:34:11 2019 PUSH: Received control message: 'PUSH_REPLY,dhcp-option DNS 8.8.8.8,dhcp-option DNS 8.8.4.4,redirect-gateway def1 bypass-dhcp,route-gateway 10.8.0.1,topology subnet,ping 10,ping-restart 120,ifconfig 10.8.0.2 255.255.255.0,peer-id 0,cipher AES-128-GCM'
Fri Mar  8 10:34:11 2019 OPTIONS IMPORT: timers and/or timeouts modified
Fri Mar  8 10:34:11 2019 OPTIONS IMPORT: --ifconfig/up options modified
Fri Mar  8 10:34:11 2019 OPTIONS IMPORT: route options modified
Fri Mar  8 10:34:11 2019 OPTIONS IMPORT: route-related options modified
Fri Mar  8 10:34:11 2019 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Fri Mar  8 10:34:11 2019 OPTIONS IMPORT: peer-id set
Fri Mar  8 10:34:11 2019 OPTIONS IMPORT: adjusting link_mtu to 1624
Fri Mar  8 10:34:11 2019 OPTIONS IMPORT: data channel crypto options modified
Fri Mar  8 10:34:11 2019 Data Channel Encrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Fri Mar  8 10:34:11 2019 Data Channel Decrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Fri Mar  8 10:34:11 2019 ROUTE_GATEWAY 192.168.1.254/255.255.255.0 IFACE=wlan0 HWADDR=b8:27:eb:5c:71:46
Fri Mar  8 10:34:11 2019 TUN/TAP device tun0 opened
Fri Mar  8 10:34:11 2019 TUN/TAP TX queue length set to 100
Fri Mar  8 10:34:11 2019 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Fri Mar  8 10:34:11 2019 /sbin/ip link set dev tun0 up mtu 1500
Fri Mar  8 10:34:11 2019 /sbin/ip addr add dev tun0 10.8.0.2/24 broadcast 10.8.0.255
Fri Mar  8 10:34:11 2019 /etc/openvpn/update-resolv-conf tun0 1500 1552 10.8.0.2 255.255.255.0 init
dhcp-option DNS 8.8.8.8
dhcp-option DNS 8.8.4.4
Too few arguments.
Too few arguments.
Fri Mar  8 10:34:12 2019 /sbin/ip route add 159.69.7.156/32 via 192.168.1.254
Fri Mar  8 10:34:12 2019 /sbin/ip route add 0.0.0.0/1 via 10.8.0.1
Fri Mar  8 10:34:12 2019 /sbin/ip route add 128.0.0.0/1 via 10.8.0.1
Fri Mar  8 10:34:12 2019 Initialization Sequence Completed

I'm aware that I've included the IP address of my server in these posts but I will be getting a new IP address soon so I'm not overly concerned about that.

OpenVPN Support Forum

Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...

Re: Ah, Ah, Ah, Ah Keepin' alive, Keepin' alive...