OpenVPN-GUI: connection via proxy too long

JohnTurner · Post by **JohnTurner** » Fri Jan 06, 2017 3:14 am

Good day,

I`ve installed OpenVPN 2.4.0 with GUI v11.4.0.0 on Win7x64, and use it to Internet connection via our proxy server.
GUI configured with manual proxy settings HTTP 151.175.192.226:8080

When I start connection, sometime it required 5-15 minutes to connect. See log file:

Code: Select all

Fri Jan 06 08:04:14 2017 OpenVPN 2.4.0 x86_64-w64-mingw32 [SSL (OpenSSL)] [LZO] [LZ4] [PKCS11] [AEAD] built on Dec 27 2016
Fri Jan 06 08:04:14 2017 Windows version 6.1 (Windows 7) 64bit
Fri Jan 06 08:04:14 2017 library versions: OpenSSL 1.0.2i  22 Sep 2016, LZO 2.09
Enter Management Password:
Fri Jan 06 08:04:14 2017 MANAGEMENT: TCP Socket listening on [AF_INET]127.0.0.1:25349
Fri Jan 06 08:04:14 2017 Need hold release from management interface, waiting...
Fri Jan 06 08:04:15 2017 MANAGEMENT: Client connected from [AF_INET]127.0.0.1:25349
Fri Jan 06 08:04:15 2017 MANAGEMENT: CMD 'state on'
Fri Jan 06 08:04:15 2017 MANAGEMENT: CMD 'log all on'
Fri Jan 06 08:04:15 2017 MANAGEMENT: CMD 'hold off'
Fri Jan 06 08:04:15 2017 MANAGEMENT: CMD 'hold release'
Fri Jan 06 08:04:15 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:04:16 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:16 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:16 2017 MANAGEMENT: >STATE:1483650256,RESOLVE,,,,,,
Fri Jan 06 08:04:16 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]188.126.94.9:1194
Fri Jan 06 08:04:16 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:04:16 2017 UDP link local: (not bound)
Fri Jan 06 08:04:16 2017 UDP link remote: [AF_INET]188.126.94.9:1194
Fri Jan 06 08:04:16 2017 MANAGEMENT: >STATE:1483650256,WAIT,,,,,,
Fri Jan 06 08:04:20 2017 Server poll timeout, restarting
Fri Jan 06 08:04:20 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:04:20 2017 MANAGEMENT: >STATE:1483650260,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:04:20 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:04:21 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:21 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:21 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]46.246.31.49:1194
Fri Jan 06 08:04:21 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:04:21 2017 UDP link local: (not bound)
Fri Jan 06 08:04:21 2017 UDP link remote: [AF_INET]46.246.31.49:1194
Fri Jan 06 08:04:21 2017 MANAGEMENT: >STATE:1483650261,WAIT,,,,,,
Fri Jan 06 08:04:25 2017 Server poll timeout, restarting
Fri Jan 06 08:04:25 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:04:25 2017 MANAGEMENT: >STATE:1483650265,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:04:25 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:04:26 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:26 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:26 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]46.246.31.50:1194
Fri Jan 06 08:04:26 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:04:26 2017 UDP link local: (not bound)
Fri Jan 06 08:04:26 2017 UDP link remote: [AF_INET]46.246.31.50:1194
Fri Jan 06 08:04:26 2017 MANAGEMENT: >STATE:1483650266,WAIT,,,,,,
Fri Jan 06 08:04:30 2017 Server poll timeout, restarting
Fri Jan 06 08:04:30 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:04:30 2017 MANAGEMENT: >STATE:1483650270,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:04:30 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:04:31 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:31 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:31 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]46.246.31.51:1194
Fri Jan 06 08:04:31 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:04:31 2017 UDP link local: (not bound)
Fri Jan 06 08:04:31 2017 UDP link remote: [AF_INET]46.246.31.51:1194
Fri Jan 06 08:04:31 2017 MANAGEMENT: >STATE:1483650271,WAIT,,,,,,
Fri Jan 06 08:04:36 2017 Server poll timeout, restarting
Fri Jan 06 08:04:36 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:04:36 2017 MANAGEMENT: >STATE:1483650276,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:04:36 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:04:37 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:37 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:37 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]46.246.31.52:1194
Fri Jan 06 08:04:37 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:04:37 2017 UDP link local: (not bound)
Fri Jan 06 08:04:37 2017 UDP link remote: [AF_INET]46.246.31.52:1194
Fri Jan 06 08:04:37 2017 MANAGEMENT: >STATE:1483650277,WAIT,,,,,,
Fri Jan 06 08:04:41 2017 Server poll timeout, restarting
Fri Jan 06 08:04:41 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:04:41 2017 MANAGEMENT: >STATE:1483650281,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:04:41 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:04:42 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:42 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:42 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]46.246.31.53:1194
Fri Jan 06 08:04:42 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:04:42 2017 UDP link local: (not bound)
Fri Jan 06 08:04:42 2017 UDP link remote: [AF_INET]46.246.31.53:1194
Fri Jan 06 08:04:42 2017 MANAGEMENT: >STATE:1483650282,WAIT,,,,,,
Fri Jan 06 08:04:46 2017 Server poll timeout, restarting
Fri Jan 06 08:04:46 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:04:46 2017 MANAGEMENT: >STATE:1483650286,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:04:46 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:04:47 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:47 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:47 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]46.246.31.54:1194
Fri Jan 06 08:04:47 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:04:47 2017 UDP link local: (not bound)
Fri Jan 06 08:04:47 2017 UDP link remote: [AF_INET]46.246.31.54:1194
Fri Jan 06 08:04:47 2017 MANAGEMENT: >STATE:1483650287,WAIT,,,,,,
Fri Jan 06 08:04:51 2017 Server poll timeout, restarting
Fri Jan 06 08:04:51 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:04:51 2017 MANAGEMENT: >STATE:1483650291,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:04:51 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:04:52 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:52 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:52 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]188.126.94.4:1194
Fri Jan 06 08:04:52 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:04:52 2017 UDP link local: (not bound)
Fri Jan 06 08:04:52 2017 UDP link remote: [AF_INET]188.126.94.4:1194
Fri Jan 06 08:04:52 2017 MANAGEMENT: >STATE:1483650292,WAIT,,,,,,
Fri Jan 06 08:04:56 2017 Server poll timeout, restarting
Fri Jan 06 08:04:56 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:04:56 2017 MANAGEMENT: >STATE:1483650296,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:04:56 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:04:57 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:57 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:04:57 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]188.126.94.5:1194
Fri Jan 06 08:04:57 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:04:57 2017 UDP link local: (not bound)
Fri Jan 06 08:04:57 2017 UDP link remote: [AF_INET]188.126.94.5:1194
Fri Jan 06 08:04:57 2017 MANAGEMENT: >STATE:1483650297,WAIT,,,,,,
Fri Jan 06 08:05:01 2017 Server poll timeout, restarting
Fri Jan 06 08:05:01 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:05:01 2017 MANAGEMENT: >STATE:1483650301,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:05:01 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:05:02 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:02 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:02 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]188.126.94.6:1194
Fri Jan 06 08:05:02 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:05:02 2017 UDP link local: (not bound)
Fri Jan 06 08:05:02 2017 UDP link remote: [AF_INET]188.126.94.6:1194
Fri Jan 06 08:05:02 2017 MANAGEMENT: >STATE:1483650302,WAIT,,,,,,
Fri Jan 06 08:05:06 2017 Server poll timeout, restarting
Fri Jan 06 08:05:06 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:05:06 2017 MANAGEMENT: >STATE:1483650306,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:05:06 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:05:07 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:07 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:07 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]188.126.94.7:1194
Fri Jan 06 08:05:07 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:05:07 2017 UDP link local: (not bound)
Fri Jan 06 08:05:07 2017 UDP link remote: [AF_INET]188.126.94.7:1194
Fri Jan 06 08:05:07 2017 MANAGEMENT: >STATE:1483650307,WAIT,,,,,,
Fri Jan 06 08:05:12 2017 Server poll timeout, restarting
Fri Jan 06 08:05:12 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:05:12 2017 MANAGEMENT: >STATE:1483650312,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:05:12 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:05:13 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:13 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:13 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]188.126.94.8:1194
Fri Jan 06 08:05:13 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:05:13 2017 UDP link local: (not bound)
Fri Jan 06 08:05:13 2017 UDP link remote: [AF_INET]188.126.94.8:1194
Fri Jan 06 08:05:13 2017 MANAGEMENT: >STATE:1483650313,WAIT,,,,,,
Fri Jan 06 08:05:17 2017 Server poll timeout, restarting
Fri Jan 06 08:05:17 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:05:17 2017 MANAGEMENT: >STATE:1483650317,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:05:17 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:05:18 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:18 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:18 2017 MANAGEMENT: >STATE:1483650318,RESOLVE,,,,,,
Fri Jan 06 08:05:18 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]188.126.94.9:1194
Fri Jan 06 08:05:18 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:05:18 2017 UDP link local: (not bound)
Fri Jan 06 08:05:18 2017 UDP link remote: [AF_INET]188.126.94.9:1194
Fri Jan 06 08:05:18 2017 MANAGEMENT: >STATE:1483650318,WAIT,,,,,,
Fri Jan 06 08:05:22 2017 Server poll timeout, restarting
Fri Jan 06 08:05:22 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:05:22 2017 MANAGEMENT: >STATE:1483650322,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:05:22 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:05:23 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:23 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:23 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]46.246.31.49:1194
Fri Jan 06 08:05:23 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:05:23 2017 UDP link local: (not bound)
Fri Jan 06 08:05:23 2017 UDP link remote: [AF_INET]46.246.31.49:1194
Fri Jan 06 08:05:23 2017 MANAGEMENT: >STATE:1483650323,WAIT,,,,,,
Fri Jan 06 08:05:27 2017 Server poll timeout, restarting
Fri Jan 06 08:05:27 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:05:27 2017 MANAGEMENT: >STATE:1483650327,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:05:27 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:05:28 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:28 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:28 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]46.246.31.50:1194
Fri Jan 06 08:05:28 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:05:28 2017 UDP link local: (not bound)
Fri Jan 06 08:05:28 2017 UDP link remote: [AF_INET]46.246.31.50:1194
Fri Jan 06 08:05:28 2017 MANAGEMENT: >STATE:1483650328,WAIT,,,,,,
Fri Jan 06 08:05:33 2017 Server poll timeout, restarting
Fri Jan 06 08:05:33 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:05:33 2017 MANAGEMENT: >STATE:1483650333,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:05:33 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:05:34 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:34 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:34 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]46.246.31.51:1194
Fri Jan 06 08:05:34 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:05:34 2017 UDP link local: (not bound)
Fri Jan 06 08:05:34 2017 UDP link remote: [AF_INET]46.246.31.51:1194
Fri Jan 06 08:05:34 2017 MANAGEMENT: >STATE:1483650334,WAIT,,,,,,
Fri Jan 06 08:05:38 2017 Server poll timeout, restarting
Fri Jan 06 08:05:38 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:05:38 2017 MANAGEMENT: >STATE:1483650338,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:05:38 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:05:39 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:39 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:39 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]46.246.31.52:1194
Fri Jan 06 08:05:39 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:05:39 2017 UDP link local: (not bound)
Fri Jan 06 08:05:39 2017 UDP link remote: [AF_INET]46.246.31.52:1194
Fri Jan 06 08:05:39 2017 MANAGEMENT: >STATE:1483650339,WAIT,,,,,,
Fri Jan 06 08:05:43 2017 Server poll timeout, restarting
Fri Jan 06 08:05:43 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:05:43 2017 MANAGEMENT: >STATE:1483650343,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:05:43 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:05:44 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:44 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:44 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]46.246.31.53:1194
Fri Jan 06 08:05:44 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:05:44 2017 UDP link local: (not bound)
Fri Jan 06 08:05:44 2017 UDP link remote: [AF_INET]46.246.31.53:1194
Fri Jan 06 08:05:44 2017 MANAGEMENT: >STATE:1483650344,WAIT,,,,,,
Fri Jan 06 08:05:49 2017 Server poll timeout, restarting
Fri Jan 06 08:05:49 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:05:49 2017 MANAGEMENT: >STATE:1483650349,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:05:49 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:05:50 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:50 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:50 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]46.246.31.54:1194
Fri Jan 06 08:05:50 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:05:50 2017 UDP link local: (not bound)
Fri Jan 06 08:05:50 2017 UDP link remote: [AF_INET]46.246.31.54:1194
Fri Jan 06 08:05:50 2017 MANAGEMENT: >STATE:1483650350,WAIT,,,,,,
Fri Jan 06 08:05:54 2017 Server poll timeout, restarting
Fri Jan 06 08:05:54 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:05:54 2017 MANAGEMENT: >STATE:1483650354,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:05:54 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:05:55 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:55 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:55 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]188.126.94.4:1194
Fri Jan 06 08:05:55 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:05:55 2017 UDP link local: (not bound)
Fri Jan 06 08:05:55 2017 UDP link remote: [AF_INET]188.126.94.4:1194
Fri Jan 06 08:05:55 2017 MANAGEMENT: >STATE:1483650355,WAIT,,,,,,
Fri Jan 06 08:05:59 2017 Server poll timeout, restarting
Fri Jan 06 08:05:59 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:05:59 2017 MANAGEMENT: >STATE:1483650359,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:05:59 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:06:00 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:06:00 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:06:00 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]188.126.94.5:1194
Fri Jan 06 08:06:00 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:06:00 2017 UDP link local: (not bound)
Fri Jan 06 08:06:00 2017 UDP link remote: [AF_INET]188.126.94.5:1194
Fri Jan 06 08:06:00 2017 MANAGEMENT: >STATE:1483650360,WAIT,,,,,,
Fri Jan 06 08:06:04 2017 Server poll timeout, restarting
Fri Jan 06 08:06:04 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:06:04 2017 MANAGEMENT: >STATE:1483650364,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:06:04 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:06:05 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:06:05 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:06:05 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]188.126.94.6:1194
Fri Jan 06 08:06:05 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:06:05 2017 UDP link local: (not bound)
Fri Jan 06 08:06:05 2017 UDP link remote: [AF_INET]188.126.94.6:1194
Fri Jan 06 08:06:05 2017 MANAGEMENT: >STATE:1483650365,WAIT,,,,,,
Fri Jan 06 08:06:09 2017 Server poll timeout, restarting
Fri Jan 06 08:06:09 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:06:09 2017 MANAGEMENT: >STATE:1483650369,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:06:09 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:06:10 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:06:10 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:06:10 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]188.126.94.7:1194
Fri Jan 06 08:06:10 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:06:10 2017 UDP link local: (not bound)
Fri Jan 06 08:06:10 2017 UDP link remote: [AF_INET]188.126.94.7:1194
Fri Jan 06 08:06:10 2017 MANAGEMENT: >STATE:1483650370,WAIT,,,,,,
Fri Jan 06 08:06:14 2017 Server poll timeout, restarting
Fri Jan 06 08:06:14 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:06:14 2017 MANAGEMENT: >STATE:1483650374,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:06:14 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:06:15 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:06:15 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:06:15 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]188.126.94.8:1194
Fri Jan 06 08:06:15 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:06:15 2017 UDP link local: (not bound)
Fri Jan 06 08:06:15 2017 UDP link remote: [AF_INET]188.126.94.8:1194
Fri Jan 06 08:06:15 2017 MANAGEMENT: >STATE:1483650375,WAIT,,,,,,
Fri Jan 06 08:06:20 2017 Server poll timeout, restarting
Fri Jan 06 08:06:20 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:06:20 2017 MANAGEMENT: >STATE:1483650380,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:06:20 2017 MANAGEMENT: CMD 'proxy HTTP 151.175.192.226 8080'
Fri Jan 06 08:06:21 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:06:21 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:06:21 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]151.175.192.226:8080
Fri Jan 06 08:06:21 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:06:21 2017 Attempting to establish TCP connection with [AF_INET]151.175.192.226:8080 [nonblock]
Fri Jan 06 08:06:21 2017 MANAGEMENT: >STATE:1483650381,TCP_CONNECT,,,,,,
Fri Jan 06 08:06:22 2017 TCP connection established with [AF_INET]151.175.192.226:8080
Fri Jan 06 08:06:22 2017 Send to HTTP proxy: 'CONNECT se-st-001.privatetunnel.com:443 HTTP/1.0'
Fri Jan 06 08:06:22 2017 Send to HTTP proxy: 'Host: se-st-001.privatetunnel.com'
Fri Jan 06 08:06:22 2017 HTTP proxy returned: 'HTTP/1.0 200 Connection established'
Fri Jan 06 08:06:24 2017 TCP_CLIENT link local: (not bound)
Fri Jan 06 08:06:24 2017 TCP_CLIENT link remote: [AF_INET]151.175.192.226:8080
Fri Jan 06 08:06:24 2017 MANAGEMENT: >STATE:1483650384,WAIT,,,,,,
Fri Jan 06 08:06:24 2017 MANAGEMENT: >STATE:1483650384,AUTH,,,,,,
Fri Jan 06 08:06:24 2017 TLS: Initial packet from [AF_INET]151.175.192.226:8080, sid=963e135c ec5d5b4d
Fri Jan 06 08:06:24 2017 VERIFY OK: depth=2, CN=OpenVPN CA
Fri Jan 06 08:06:24 2017 VERIFY OK: depth=1, CN=PT Transitional 20150615
Fri Jan 06 08:06:24 2017 VERIFY OK: nsCertType=SERVER
Fri Jan 06 08:06:24 2017 VERIFY OK: depth=0, CN=sw2.privatetunnel.com
Fri Jan 06 08:06:25 2017 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 ECDHE-RSA-AES256-GCM-SHA384, 2048 bit RSA
Fri Jan 06 08:06:25 2017 [sw2.privatetunnel.com] Peer Connection Initiated with [AF_INET]151.175.192.226:8080
Fri Jan 06 08:06:26 2017 MANAGEMENT: >STATE:1483650386,GET_CONFIG,,,,,,
Fri Jan 06 08:06:26 2017 SENT CONTROL [sw2.privatetunnel.com]: 'PUSH_REQUEST' (status=1)
Fri Jan 06 08:06:27 2017 PUSH: Received control message: 'PUSH_REPLY,route-gateway 10.9.0.1,ifconfig 10.9.192.88 255.255.0.0,client-ip 185.40.0.135,ping 8,ping-restart 40,reneg-sec 3600,cipher AES-128-GCM,compress lz4-v2,peer-id 5793,topology subnet,explicit-exit-notify,redirect-gateway def1,dhcp-option DNS 10.9.0.1,sndbuf 0,rcvbuf 0,socket-flags TCP_NODELAY,block-ipv6'
Fri Jan 06 08:06:27 2017 Unrecognized option or missing or extra parameter(s) in [PUSH-OPTIONS]:3: client-ip (2.4.0)
Fri Jan 06 08:06:27 2017 Options error: option 'reneg-sec' cannot be used in this context ([PUSH-OPTIONS])
Fri Jan 06 08:06:27 2017 Option 'explicit-exit-notify' in [PUSH-OPTIONS]:11 is ignored by previous <connection> blocks 
Fri Jan 06 08:06:27 2017 Unrecognized option or missing or extra parameter(s) in [PUSH-OPTIONS]:17: block-ipv6 (2.4.0)
Fri Jan 06 08:06:27 2017 OPTIONS IMPORT: timers and/or timeouts modified
Fri Jan 06 08:06:27 2017 OPTIONS IMPORT: --explicit-exit-notify can only be used with --proto udp
Fri Jan 06 08:06:27 2017 OPTIONS IMPORT: compression parms modified
Fri Jan 06 08:06:27 2017 OPTIONS IMPORT: --sndbuf/--rcvbuf options modified
Fri Jan 06 08:06:27 2017 Socket Buffers: R=[100000->100000] S=[100000->100000]
Fri Jan 06 08:06:27 2017 OPTIONS IMPORT: --socket-flags option modified
Fri Jan 06 08:06:27 2017 Socket flags: TCP_NODELAY=1 succeeded
Fri Jan 06 08:06:27 2017 OPTIONS IMPORT: --ifconfig/up options modified
Fri Jan 06 08:06:27 2017 OPTIONS IMPORT: route options modified
Fri Jan 06 08:06:27 2017 OPTIONS IMPORT: route-related options modified
Fri Jan 06 08:06:27 2017 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Fri Jan 06 08:06:27 2017 OPTIONS IMPORT: peer-id set
Fri Jan 06 08:06:27 2017 OPTIONS IMPORT: adjusting link_mtu to 1627
Fri Jan 06 08:06:27 2017 OPTIONS IMPORT: data channel crypto options modified
Fri Jan 06 08:06:27 2017 Data Channel Encrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Fri Jan 06 08:06:27 2017 Data Channel Decrypt: Cipher 'AES-128-GCM' initialized with 128 bit key
Fri Jan 06 08:06:27 2017 interactive service msg_channel=0
Fri Jan 06 08:06:27 2017 ROUTE_GATEWAY 172.18.32.1/255.255.255.0 I=13 HWADDR=ec:b1:d7:69:bd:dd
Fri Jan 06 08:06:27 2017 open_tun
Fri Jan 06 08:06:27 2017 TAP-WIN32 device [Подключение по локальной сети 2] opened: \\.\Global\{18FAE9C7-2D54-40FB-9FCB-53B1AACC70A2}.tap
Fri Jan 06 08:06:27 2017 TAP-Windows Driver Version 9.21 
Fri Jan 06 08:06:27 2017 Set TAP-Windows TUN subnet mode network/local/netmask = 10.9.0.0/10.9.192.88/255.255.0.0 [SUCCEEDED]
Fri Jan 06 08:06:27 2017 Notified TAP-Windows driver to set a DHCP IP/netmask of 10.9.192.88/255.255.0.0 on interface {18FAE9C7-2D54-40FB-9FCB-53B1AACC70A2} [DHCP-serv: 10.9.255.254, lease-time: 31536000]
Fri Jan 06 08:06:27 2017 Successful ARP Flush on interface [15] {18FAE9C7-2D54-40FB-9FCB-53B1AACC70A2}
Fri Jan 06 08:06:27 2017 do_ifconfig, tt->did_ifconfig_ipv6_setup=0
Fri Jan 06 08:06:27 2017 MANAGEMENT: >STATE:1483650387,ASSIGN_IP,,10.9.192.88,,,,
Fri Jan 06 08:06:32 2017 TEST ROUTES: 1/1 succeeded len=0 ret=1 a=0 u/d=up
Fri Jan 06 08:06:32 2017 C:\Windows\system32\route.exe ADD 151.175.192.226 MASK 255.255.255.255 172.18.32.1
Fri Jan 06 08:06:32 2017 ROUTE: CreateIpForwardEntry succeeded with dwForwardMetric1=20 and dwForwardType=4
Fri Jan 06 08:06:32 2017 Route addition via IPAPI succeeded [adaptive]
Fri Jan 06 08:06:32 2017 C:\Windows\system32\route.exe ADD 0.0.0.0 MASK 128.0.0.0 10.9.0.1
Fri Jan 06 08:06:32 2017 ROUTE: CreateIpForwardEntry succeeded with dwForwardMetric1=20 and dwForwardType=4
Fri Jan 06 08:06:32 2017 Route addition via IPAPI succeeded [adaptive]
Fri Jan 06 08:06:32 2017 C:\Windows\system32\route.exe ADD 128.0.0.0 MASK 128.0.0.0 10.9.0.1
Fri Jan 06 08:06:32 2017 ROUTE: CreateIpForwardEntry succeeded with dwForwardMetric1=20 and dwForwardType=4
Fri Jan 06 08:06:32 2017 Route addition via IPAPI succeeded [adaptive]
Fri Jan 06 08:06:32 2017 Initialization Sequence Completed
Fri Jan 06 08:06:32 2017 MANAGEMENT: >STATE:1483650392,CONNECTED,SUCCESS,10.9.192.88,151.175.192.226,8080,172.18.32.56,49506
Fri Jan 06 08:26:20 2017 C:\Windows\system32\route.exe DELETE 151.175.192.226 MASK 255.255.255.255 172.18.32.1
Fri Jan 06 08:26:20 2017 Route deletion via IPAPI succeeded [adaptive]
Fri Jan 06 08:26:20 2017 C:\Windows\system32\route.exe DELETE 0.0.0.0 MASK 128.0.0.0 10.9.0.1
Fri Jan 06 08:26:20 2017 Route deletion via IPAPI succeeded [adaptive]
Fri Jan 06 08:26:20 2017 C:\Windows\system32\route.exe DELETE 128.0.0.0 MASK 128.0.0.0 10.9.0.1
Fri Jan 06 08:26:20 2017 Route deletion via IPAPI succeeded [adaptive]
Fri Jan 06 08:26:20 2017 Closing TUN/TAP interface
Fri Jan 06 08:26:20 2017 SIGTERM[hard,] received, process exiting
Fri Jan 06 08:26:20 2017 MANAGEMENT: >STATE:1483651580,EXITING,SIGTERM,,,,,

As you can see, part

Code: Select all

Fri Jan 06 08:05:06 2017 Server poll timeout, restarting
Fri Jan 06 08:05:06 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:05:06 2017 MANAGEMENT: >STATE:1483650306,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:05:06 2017 MANAGEMENT: CMD 'proxy NONE  '
Fri Jan 06 08:05:07 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:07 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:05:07 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]188.126.94.7:1194
Fri Jan 06 08:05:07 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:05:07 2017 UDP link local: (not bound)
Fri Jan 06 08:05:07 2017 UDP link remote: [AF_INET]188.126.94.7:1194
Fri Jan 06 08:05:07 2017 MANAGEMENT: >STATE:1483650307,WAIT,,,,,,
Fri Jan 06 08:05:12 2017 Server poll timeout, restarting

repeated several times with line MANAGEMENT: CMD 'proxy NONE'
after that in log you can see proxy setting appeared MANAGEMENT: CMD 'proxy HTTP 151.175.192.226 8080' and connection has been established

Code: Select all

Fri Jan 06 08:06:20 2017 Server poll timeout, restarting
Fri Jan 06 08:06:20 2017 SIGUSR1[soft,server_poll] received, process restarting
Fri Jan 06 08:06:20 2017 MANAGEMENT: >STATE:1483650380,RECONNECTING,server_poll,,,,,
Fri Jan 06 08:06:20 2017 MANAGEMENT: CMD 'proxy HTTP 151.175.192.226 8080'
Fri Jan 06 08:06:21 2017 Outgoing Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:06:21 2017 Incoming Control Channel Authentication: Using 160 bit message hash 'SHA1' for HMAC authentication
Fri Jan 06 08:06:21 2017 TCP/UDP: Preserving recently used remote address: [AF_INET]151.175.192.226:8080
Fri Jan 06 08:06:21 2017 Socket Buffers: R=[8192->100000] S=[8192->100000]
Fri Jan 06 08:06:21 2017 Attempting to establish TCP connection with [AF_INET]151.175.192.226:8080 [nonblock]
Fri Jan 06 08:06:21 2017 MANAGEMENT: >STATE:1483650381,TCP_CONNECT,,,,,,
Fri Jan 06 08:06:22 2017 TCP connection established with [AF_INET]151.175.192.226:8080
Fri Jan 06 08:06:22 2017 Send to HTTP proxy: 'CONNECT se-st-001.privatetunnel.com:443 HTTP/1.0'
Fri Jan 06 08:06:22 2017 Send to HTTP proxy: 'Host: se-st-001.privatetunnel.com'
Fri Jan 06 08:06:22 2017 HTTP proxy returned: 'HTTP/1.0 200 Connection established'
Fri Jan 06 08:06:24 2017 TCP_CLIENT link local: (not bound)
Fri Jan 06 08:06:24 2017 TCP_CLIENT link remote: [AF_INET]151.175.192.226:8080
Fri Jan 06 08:06:24 2017 MANAGEMENT: >STATE:1483650384,WAIT,,,,,,
Fri Jan 06 08:06:24 2017 MANAGEMENT: >STATE:1483650384,AUTH,,,,,,
Fri Jan 06 08:06:24 2017 TLS: Initial packet from [AF_INET]151.175.192.226:8080, sid=963e135c ec5d5b4d
Fri Jan 06 08:06:24 2017 VERIFY OK: depth=2, CN=OpenVPN CA
Fri Jan 06 08:06:24 2017 VERIFY OK: depth=1, CN=PT Transitional 20150615
Fri Jan 06 08:06:24 2017 VERIFY OK: nsCertType=SERVER
Fri Jan 06 08:06:24 2017 VERIFY OK: depth=0, CN=sw2.privatetunnel.com
Fri Jan 06 08:06:25 2017 Control Channel: TLSv1.2, cipher TLSv1/SSLv3 ECDHE-RSA-AES256-GCM-SHA384, 2048 bit RSA
Fri Jan 06 08:06:25 2017 [sw2.privatetunnel.com] Peer Connection Initiated with [AF_INET]151.175.192.226:8080
Fri Jan 06 08:06:26 2017 MANAGEMENT: >STATE:1483650386,GET_CONFIG,,,,,,

So process takes too long time (5-20 minutes, somtimes infitity), I`ve tried different .ovpn profiles with the same result.
This profiles are working well on Android OpenVPN apps.

How I can fix this issue?
Why OpenVPN start connection with NONE proxy settings and then goes to normal settings?

TinCanTech · Post by **TinCanTech** » Fri Jan 06, 2017 12:52 pm

JohnTurner wrote:I`ve installed OpenVPN 2.4.0 with GUI v11.4.0.0 on Win7x64, and use it to Internet connection via our proxy server

Speak to your server admin.

JohnTurner wrote:Fri Jan 06 08:06:22 2017 Send to HTTP proxy: 'CONNECT se-st-001.privatetunnel.com:443 HTTP/1.0'
Fri Jan 06 08:06:22 2017 Send to HTTP proxy: 'Host: se-st-001.privatetunnel.com'

Contact privatetunnel.com/support

OpenVPN Support Forum

OpenVPN-GUI: connection via proxy too long

OpenVPN-GUI: connection via proxy too long

Re: OpenVPN-GUI: connection via proxy too long