Getting STATE:1473831582,CONNECTED,ERROR

iantidon

Hi,

One of my client is intermittently facing issues such as STATE:1473831582,CONNECTED,ERROR at the end of the log file.
Client is Windows Server 2008 R2 Standard.

It says connected but with error. I also can't access other servers within the subnet when CONNECTED,ERROR appears at the end of the log.

I can provide more details of the logs.

Please help.

Regards,
Ian

TinCanTech

Please read the Forum rules (top of this page)

iantidon

Hi,

Please help update this post and use the below post instead for the logs. Please help me remove IP address and domain names.

Appreciate your help.

##############################################################
Wed Sep 14 00:39:03 2016 OpenVPN 2.3.8 x86_64-w64-mingw32 [SSL (OpenSSL)] [LZO] [PKCS11] [IPv6] built on Aug 4 2015
Wed Sep 14 00:39:03 2016 library versions: OpenSSL 1.0.1p 9 Jul 2015, LZO 2.08
Enter Management Password:
Wed Sep 14 00:39:03 2016 MANAGEMENT: TCP Socket listening on [AF_INET]127.0.0.1:25343
Wed Sep 14 00:39:03 2016 Need hold release from management interface, waiting...
Wed Sep 14 00:39:04 2016 MANAGEMENT: Client connected from [AF_INET]127.0.0.1:25343
Wed Sep 14 00:39:04 2016 MANAGEMENT: CMD 'state on'
Wed Sep 14 00:39:04 2016 MANAGEMENT: CMD 'log all on'
Wed Sep 14 00:39:04 2016 MANAGEMENT: CMD 'hold off'
Wed Sep 14 00:39:04 2016 MANAGEMENT: CMD 'hold release'
Wed Sep 14 00:39:04 2016 Socket Buffers: R=[8192->100000] S=[8192->100000]
Wed Sep 14 00:39:04 2016 UDPv4 link local: [undef]
Wed Sep 14 00:39:04 2016 UDPv4 link remote: [AF_INET]XXX.XXX.XXX.220:1194
Wed Sep 14 00:39:04 2016 MANAGEMENT: >STATE:1473831544,WAIT,,,
Wed Sep 14 00:39:04 2016 MANAGEMENT: >STATE:1473831544,AUTH,,,
Wed Sep 14 00:39:04 2016 TLS: Initial packet from [AF_INET]XXX.XXX.XXX.220:1194, sid=543605d1 aa51e8d7
Wed Sep 14 00:39:04 2016 VERIFY OK: depth=1, C=US, ST=GA, L=XXXXta, O=Company, OU=MyOrganizationalUnit, CN=Company CA, name=EasyRSA, emailAddress=noreply@company.com
Wed Sep 14 00:39:04 2016 VERIFY OK: nsCertType=SERVER
Wed Sep 14 00:39:04 2016 VERIFY OK: depth=0, C=US, ST=GA, L=XXXXta, O=Company, OU=MyOrganizationalUnit, CN=Company CA, name=EasyRSA, emailAddress=noreply@company.com
Wed Sep 14 00:39:05 2016 Data Channel Encrypt: Cipher 'AES-256-CBC' initialized with 256 bit key
Wed Sep 14 00:39:05 2016 Data Channel Encrypt: Using 128 bit message hash 'MD5' for HMAC authentication
Wed Sep 14 00:39:05 2016 Data Channel Decrypt: Cipher 'AES-256-CBC' initialized with 256 bit key
Wed Sep 14 00:39:05 2016 Data Channel Decrypt: Using 128 bit message hash 'MD5' for HMAC authentication
Wed Sep 14 00:39:05 2016 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 2048 bit RSA
Wed Sep 14 00:39:05 2016 [server] Peer Connection Initiated with [AF_INET]XXX.XXX.XXX.220:1194
Wed Sep 14 00:39:06 2016 MANAGEMENT: >STATE:1473831546,GET_CONFIG,,,
Wed Sep 14 00:39:07 2016 SENT CONTROL [server]: 'PUSH_REQUEST' (status=1)
Wed Sep 14 00:39:07 2016 PUSH: Received control message: 'PUSH_REPLY,route 172.19.0.0 255.255.0.0,route XXX.XXX.XXX.1,topology net30,ping xxx.xxx.xxx.10,ping-restart xxx.xxx.xxx.120,ifconfig

XXX.XXX.XXX.22 XXX.XXX.XXX.21'
Wed Sep 14 00:39:07 2016 OPTIONS IMPORT: timers and/or timeouts modified
Wed Sep 14 00:39:07 2016 OPTIONS IMPORT: --ifconfig/up options modified
Wed Sep 14 00:39:07 2016 OPTIONS IMPORT: route options modified
Wed Sep 14 00:39:07 2016 do_ifconfig, tt->ipv6=0, tt->did_ifconfig_ipv6_setup=0
Wed Sep 14 00:39:07 2016 MANAGEMENT: >STATE:1473831547,ASSIGN_IP,,XXX.XXX.XXX.22,
Wed Sep 14 00:39:07 2016 open_tun, tt->ipv6=0
Wed Sep 14 00:39:07 2016 TAP-WIN32 device [Local Area Connection 3] opened: \\.\Global\{3DE33348-1E0F-4F38-ABF1-02946C82ADC8}.tap
Wed Sep 14 00:39:07 2016 TAP-Windows Driver Version 9.21
Wed Sep 14 00:39:07 2016 Notified TAP-Windows driver to set a DHCP IP/netmask of XXX.XXX.XXX.22/255.255.255.252 on interface {3DE33348-1E0F-4F38-ABF1-02946C82ADC8} [DHCP-serv: XXX.XXX.XXX.21,

lease-time: 31536000]
Wed Sep 14 00:39:07 2016 Successful ARP Flush on interface [18] {3DE33348-1E0F-4F38-ABF1-02946C82ADC8}
Wed Sep 14 00:39:12 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:12 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:15 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:15 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:16 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:16 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:17 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:17 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:18 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:18 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:19 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:19 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:20 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:20 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:21 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:21 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:22 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:22 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:23 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:23 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:24 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:24 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:25 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:25 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:26 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:26 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:27 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:27 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:28 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:28 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:29 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:29 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:30 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:30 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:31 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:31 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:32 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:32 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:33 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:33 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:34 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:34 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:35 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:35 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:36 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:36 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:37 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:37 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:38 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:38 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:39 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:39 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:40 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:40 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:41 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:41 2016 Route: Waiting for TUN/TAP interface to come up...
Wed Sep 14 00:39:42 2016 TEST ROUTES: 0/0 succeeded len=2 ret=0 a=0 u/d=down
Wed Sep 14 00:39:42 2016 MANAGEMENT: >STATE:1473831582,ADD_ROUTES,,,
Wed Sep 14 00:39:42 2016 C:\Windows\system32\route.exe ADD 172.19.0.0 MASK 255.255.0.0 XXX.XXX.XXX.21
Wed Sep 14 00:39:42 2016 Warning: route gateway is not reachable on any active network adapters: XXX.XXX.XXX.21
Wed Sep 14 00:39:42 2016 Route addition via IPAPI failed [adaptive]
Wed Sep 14 00:39:42 2016 Route addition fallback to route.exe
Wed Sep 14 00:39:42 2016 env_block: add PATH=C:\Windows\System32;C:\WINDOWS;C:\WINDOWS\System32\Wbem
Wed Sep 14 00:39:42 2016 C:\Windows\system32\route.exe ADD XXX.XXX.XXX.1 MASK 255.255.255.255 XXX.XXX.XXX.21
Wed Sep 14 00:39:42 2016 Warning: route gateway is not reachable on any active network adapters: XXX.XXX.XXX.21
Wed Sep 14 00:39:42 2016 Route addition via IPAPI failed [adaptive]
Wed Sep 14 00:39:42 2016 Route addition fallback to route.exe
Wed Sep 14 00:39:42 2016 env_block: add PATH=C:\Windows\System32;C:\WINDOWS;C:\WINDOWS\System32\Wbem
SYSTEM ROUTING TABLE
0.0.0.0 0.0.0.0 XXX.XXX.XXX.1 p=0 i=11 t=4 pr=3 a=3011801 h=0 m=10/0/0/0/0
XXX.XXX.XXX.1 255.255.255.255 XXX.XXX.XXX.21 p=0 i=11 t=4 pr=3 a=2457498 h=0 m=11/0/0/0/0
XXX.XXX.XXX.1 255.255.255.255 XXX.XXX.XXX.65 p=0 i=18 t=4 pr=3 a=69536 h=0 m=20/0/0/0/0
XXX.XXX.XXX.1 255.255.255.255 XXX.XXX.XXX.45 p=0 i=18 t=4 pr=3 a=68834 h=0 m=20/0/0/0/0
XXX.XXX.XXX.1 255.255.255.255 XXX.XXX.XXX.85 p=0 i=18 t=4 pr=3 a=67750 h=0 m=20/0/0/0/0
XXX.XXX.XXX.1 255.255.255.255 XXX.XXX.XXX.61 p=0 i=18 t=4 pr=3 a=66954 h=0 m=20/0/0/0/0
XXX.XXX.XXX.1 255.255.255.255 XXX.XXX.XXX.69 p=0 i=18 t=4 pr=3 a=66206 h=0 m=20/0/0/0/0
XXX.XXX.XXX.1 255.255.255.255 XXX.XXX.XXX.9 p=0 i=18 t=4 pr=3 a=65578 h=0 m=20/0/0/0/0
XXX.XXX.XXX.1 255.255.255.255 XXX.XXX.XXX.25 p=0 i=18 t=4 pr=3 a=62419 h=0 m=20/0/0/0/0
XXX.XXX.XXX.1 255.255.255.255 XXX.XXX.XXX.49 p=0 i=18 t=4 pr=3 a=9220 h=0 m=20/0/0/0/0
XXX.XXX.XXX.0 255.255.252.0 XXX.XXX.XXX.134 p=0 i=11 t=3 pr=3 a=3011801 h=0 m=266/0/0/0/0
XXX.XXX.XXX.134 255.255.255.255 XXX.XXX.XXX.134 p=0 i=11 t=3 pr=3 a=3011801 h=0 m=266/0/0/0/0
10.46.91.255 255.255.255.255 XXX.XXX.XXX.134 p=0 i=11 t=3 pr=3 a=3011801 h=0 m=266/0/0/0/0
127.0.0.0 255.0.0.0 127.0.0.1 p=0 i=1 t=3 pr=3 a=3011820 h=0 m=306/0/0/0/0
127.0.0.1 255.255.255.255 127.0.0.1 p=0 i=1 t=3 pr=3 a=3011820 h=0 m=306/0/0/0/0
127.255.255.255 255.255.255.255 127.0.0.1 p=0 i=1 t=3 pr=3 a=3011820 h=0 m=306/0/0/0/0
172.19.0.0 255.255.0.0 XXX.XXX.XXX.21 p=0 i=11 t=4 pr=3 a=2457498 h=0 m=11/0/0/0/0
172.19.0.0 255.255.0.0 XXX.XXX.XXX.65 p=0 i=18 t=4 pr=3 a=69536 h=0 m=20/0/0/0/0
172.19.0.0 255.255.0.0 XXX.XXX.XXX.45 p=0 i=18 t=4 pr=3 a=68834 h=0 m=20/0/0/0/0
172.19.0.0 255.255.0.0 XXX.XXX.XXX.85 p=0 i=18 t=4 pr=3 a=67750 h=0 m=20/0/0/0/0
172.19.0.0 255.255.0.0 XXX.XXX.XXX.61 p=0 i=18 t=4 pr=3 a=66954 h=0 m=20/0/0/0/0
172.19.0.0 255.255.0.0 XXX.XXX.XXX.69 p=0 i=18 t=4 pr=3 a=66206 h=0 m=20/0/0/0/0
172.19.0.0 255.255.0.0 XXX.XXX.XXX.9 p=0 i=18 t=4 pr=3 a=65578 h=0 m=20/0/0/0/0
172.19.0.0 255.255.0.0 XXX.XXX.XXX.25 p=0 i=18 t=4 pr=3 a=62419 h=0 m=20/0/0/0/0
172.19.0.0 255.255.0.0 XXX.XXX.XXX.49 p=0 i=18 t=4 pr=3 a=9220 h=0 m=20/0/0/0/0
224.0.0.0 240.0.0.0 127.0.0.1 p=0 i=1 t=3 pr=3 a=3011820 h=0 m=306/0/0/0/0
224.0.0.0 240.0.0.0 XXX.XXX.XXX.134 p=0 i=11 t=3 pr=3 a=3011810 h=0 m=266/0/0/0/0
224.0.0.0 240.0.0.0 0.0.0.0 p=0 i=18 t=3 pr=3 a=81296 h=0 m=276/0/0/0/0
255.255.255.255 255.255.255.255 127.0.0.1 p=0 i=1 t=3 pr=3 a=3011820 h=0 m=306/0/0/0/0
255.255.255.255 255.255.255.255 XXX.XXX.XXX.134 p=0 i=11 t=3 pr=3 a=3011810 h=0 m=266/0/0/0/0
255.255.255.255 255.255.255.255 0.0.0.0 p=0 i=18 t=3 pr=3 a=81296 h=0 m=276/0/0/0/0
SYSTEM ADAPTER LIST
TAP-Windows Adapter V9
Index = 18
GUID = {3DE33348-1E0F-4F38-ABF1-02946C82ADC8}
IP = XXX.XXX.XXX.16/255.255.0.0
MAC = 00:ff:3d:e3:33:48
GATEWAY = 0.0.0.0/255.255.255.255
DHCP SERV = 0.0.0.0/255.255.255.255
DHCP LEASE OBTAINED = Wed Sep 14 00:39:42 2016
DHCP LEASE EXPIRES = Wed Sep 14 00:39:42 2016
DNS SERV =
TAP-Win32 Adapter OAS
Index = 14
GUID = {8738E9C6-721A-474C-AD2B-9F8FD3FF7989}
IP = 0.0.0.0/0.0.0.0
MAC = 00:ff:87:38:e9:c6
GATEWAY = 0.0.0.0/255.255.255.255
DHCP SERV =
DHCP LEASE OBTAINED = Wed Sep 14 00:39:42 2016
DHCP LEASE EXPIRES = Wed Sep 14 00:39:42 2016
DNS SERV =
Intel(R) PRO/1000 MT Network Connection
Index = 11
GUID = {971EF447-2750-4644-9298-A22635466363}
IP = XXX.XXX.XXX.134/255.255.252.0
MAC = 00:50:56:bd:3b:91
GATEWAY = XXX.XXX.XXX.1/255.255.255.255
DHCP SERV = XXX.XXX.XXX.118/255.255.255.255
DHCP LEASE OBTAINED = Tue Sep 13 23:26:02 2016
DHCP LEASE EXPIRES = Wed Sep 14 07:26:02 2016
DNS SERV = XXX.XXX.XXX.118/255.255.255.255 .55/255.255.255.255 XXX.XXX.XXX.200/255.255.255.255
Wed Sep 14 00:39:42 2016 Initialization Sequence Completed With Errors ( see http://openvpn.net/faq.html#dhcpclientserv )
Wed Sep 14 00:39:42 2016 MANAGEMENT: >STATE:1473831582,CONNECTED,ERROR,XXX.XXX.XXX.22,XXX.XXX.XXX.220

TinCanTech

Ensure the Windows DHCP Client is started.

iantidon

I checked the DHCP Client service and its running fine. I tried to restart it and tried to reproduce the issue.
I wasn't able to reproduce the issue. We have a continues test running and repeatedly connecting to VPN. I will let you know If I will encounter it again.

Thanks