A user came around with Win8 / 64 bit and openvpn 2.3.1
When connecting to our OpenVPN server, the log would have lots and
lots of 

"Fri May 24 12:09:48 2013 Route: Waiting for TUN/TAP interface to come
up..."

messages, and in the end the initialization sequence would fail.

So I left the machine as it was, did some looking here and there and
finally examined the network adapter
settings: I found that the TAP32 adapter did INDEED get the proper
address -- after 2-3 minutes! It literally took ages for it to accept
the address.

Susequent tests fortunately didn't show the same behaviour.
Has anybody seen that before?


Fri May 24 12:09:33 2013 OpenVPN 2.3.1 x86_64-w64-mingw32 [SSL (OpenSSL)] [LZO] 
[PKCS11] [eurephia] [IPv6] built on Mar 28 2013
Enter Management Password:
Fri May 24 12:09:33 2013 MANAGEMENT: TCP Socket listening on
[AF_INET]127.0.0.1:25340
Fri May 24 12:09:33 2013 Need hold release from management interface,
waiting...
Fri May 24 12:09:33 2013 MANAGEMENT: Client connected from
[AF_INET]127.0.0.1:25340
Fri May 24 12:09:33 2013 MANAGEMENT: CMD 'state on'
Fri May 24 12:09:33 2013 MANAGEMENT: CMD 'log all on'
Fri May 24 12:09:33 2013 MANAGEMENT: CMD 'hold off'
Fri May 24 12:09:33 2013 MANAGEMENT: CMD 'hold release'
Fri May 24 12:09:39 2013 MANAGEMENT: CMD 'username "Auth" "lauffmt"'
Fri May 24 12:09:39 2013 MANAGEMENT: CMD 'password [...]'
Fri May 24 12:09:39 2013 NOTE: the current --script-security setting
may allow this configuration to call user-defined scripts
Fri May 24 12:09:39 2013 Socket Buffers: R=[65536->65536]
S=[65536->65536]
Fri May 24 12:09:39 2013 MANAGEMENT: >STATE:1369390179,RESOLVE,,,
Fri May 24 12:09:39 2013 UDPv4 link local: [undef]
Fri May 24 12:09:39 2013 UDPv4 link remote: [AF_INET]193.175.73.100:1194
Fri May 24 12:09:39 2013 MANAGEMENT: >STATE:1369390179,WAIT,,,
Fri May 24 12:09:39 2013 MANAGEMENT: >STATE:1369390179,AUTH,,,
Fri May 24 12:09:39 2013 TLS: Initial packet from
[AF_INET]193.175.73.100:1194, sid=605e35c8 134eecea
Fri May 24 12:09:39 2013 WARNING: this configuration may cache
passwords in memory -- use the auth-nocache option to prevent this
Fri May 24 12:09:40 2013 VERIFY OK: depth=1, C=DE, ST=BERLIN,
L=BERLIN, O=OpenVPN-Charite, CN=OpenVPN-Charite-CA,
emailAddress=einwahl-ad...@charite.de
Fri May 24 12:09:40 2013 VERIFY OK: nsCertType=SERVER
Fri May 24 12:09:40 2013 VERIFY OK: depth=0, C=DE, ST=BERLIN,
O=OpenVPN-Charite, CN=server, emailAddress=einwahl-ad...@charite.de
Fri May 24 12:09:40 2013 Data Channel Encrypt: Cipher 'BF-CBC'
initialized with 128 bit key
Fri May 24 12:09:40 2013 Data Channel Encrypt: Using 160 bit message
hash 'SHA1' for HMAC authentication
Fri May 24 12:09:40 2013 Data Channel Decrypt: Cipher 'BF-CBC'
initialized with 128 bit key
Fri May 24 12:09:40 2013 Data Channel Decrypt: Using 160 bit message
hash 'SHA1' for HMAC authentication
Fri May 24 12:09:40 2013 Control Channel: TLSv1, cipher TLSv1/SSLv3
DHE-RSA-AES256-SHA, 1024 bit RSA
Fri May 24 12:09:40 2013 [server] Peer Connection Initiated with
[AF_INET]193.175.73.100:1194
Fri May 24 12:09:42 2013 MANAGEMENT: >STATE:1369390182,GET_CONFIG,,,
Fri May 24 12:09:43 2013 SENT CONTROL [server]: 'PUSH_REQUEST'
(status=1)
Fri May 24 12:09:43 2013 PUSH: Received control message:
'PUSH_REPLY,dhcp-option DNS 141.42.3.33,dhcp-option DNS
141.42.2.22,dhcp-option DOMAIN charite.de,register-dns,route
172.28.0.1,topology net30,ping 10,ping-restart 120,redirect-gateway
def1,ifconfig 172.28.28.61 172.28.28.62'
Fri May 24 12:09:43 2013 OPTIONS IMPORT: timers and/or timeouts modified
Fri May 24 12:09:43 2013 OPTIONS IMPORT: --ifconfig/up options modified
Fri May 24 12:09:43 2013 OPTIONS IMPORT: route options modified
Fri May 24 12:09:43 2013 OPTIONS IMPORT: --ip-win32 and/or
--dhcp-option options modified
Fri May 24 12:09:43 2013 do_ifconfig, tt->ipv6=0,
tt->did_ifconfig_ipv6_setup=0
Fri May 24 12:09:43 2013 MANAGEMENT:
>STATE:1369390183,ASSIGN_IP,,172.28.28.61,
Fri May 24 12:09:43 2013 open_tun, tt->ipv6=0
Fri May 24 12:09:43 2013 TAP-WIN32 device [LAN-Verbindung 2] opened:
\\.\Global\{E55B897B-802A-4BC8-A02A-E98C2D7A9BC8}.tap
Fri May 24 12:09:43 2013 TAP-Windows Driver Version 9.9 
Fri May 24 12:09:43 2013 Notified TAP-Windows driver to set a DHCP
IP/netmask of 172.28.28.61/255.255.255.252 on interface
{E55B897B-802A-4BC8-A02A-E98C2D7A9BC8} [DHCP-serv: 172.28.28.62,
lease-time: 31536000]
Fri May 24 12:09:43 2013 Successful ARP Flush on interface [50]
{E55B897B-802A-4BC8-A02A-E98C2D7A9BC8}
Fri May 24 12:09:48 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:09:48 2013 Route: Waiting for TUN/TAP interface to come
up...
Fri May 24 12:09:53 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:09:53 2013 Route: Waiting for TUN/TAP interface to come
up...
Fri May 24 12:09:54 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:09:54 2013 Route: Waiting for TUN/TAP interface to come
up...
Fri May 24 12:09:56 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:09:56 2013 Route: Waiting for TUN/TAP interface to come
up...
Fri May 24 12:09:57 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:09:57 2013 Route: Waiting for TUN/TAP interface to come
up...
Fri May 24 12:09:58 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:09:58 2013 Route: Waiting for TUN/TAP interface to come
up...
Fri May 24 12:09:59 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:09:59 2013 Route: Waiting for TUN/TAP interface to come
up...
Fri May 24 12:10:00 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0 u/d=down
Fri May 24 12:10:00 2013 Route: Waiting for TUN/TAP interface to come up...
Fri May 24 12:10:01 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0 u/d=down
Fri May 24 12:10:01 2013 Route: Waiting for TUN/TAP interface to come up...
Fri May 24 12:10:02 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0 u/d=down
Fri May 24 12:10:02 2013 Route: Waiting for TUN/TAP interface to come up...
Fri May 24 12:10:03 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0 u/d=down
Fri May 24 12:10:03 2013 Route: Waiting for TUN/TAP interface to come up...
Fri May 24 12:10:04 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0 u/d=down
Fri May 24 12:10:04 2013 Route: Waiting for TUN/TAP interface to come up...
Fri May 24 12:10:06 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0 u/d=down
Fri May 24 12:10:06 2013 Route: Waiting for TUN/TAP interface to come up...
Fri May 24 12:10:07 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0 u/d=down
Fri May 24 12:10:07 2013 Route: Waiting for TUN/TAP interface to come up...
Fri May 24 12:10:08 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0 u/d=down
Fri May 24 12:10:08 2013 Route: Waiting for TUN/TAP interface to come up...
Fri May 24 12:10:09 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:10:09 2013 Route: Waiting for TUN/TAP interface to come
up...
Fri May 24 12:10:10 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:10:10 2013 Route: Waiting for TUN/TAP interface to come
up...
Fri May 24 12:10:11 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:10:11 2013 Route: Waiting for TUN/TAP interface to come
up...
Fri May 24 12:10:13 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:10:13 2013 Route: Waiting for TUN/TAP interface to come
up...
Fri May 24 12:10:14 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:10:14 2013 Route: Waiting for TUN/TAP interface to come
up...
Fri May 24 12:10:15 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:10:15 2013 Route: Waiting for TUN/TAP interface to come
up...
Fri May 24 12:10:16 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:10:16 2013 Route: Waiting for TUN/TAP interface to come
up...
Fri May 24 12:10:18 2013 TEST ROUTES: 0/0 succeeded len=1 ret=0 a=0
u/d=down
Fri May 24 12:10:18 2013 C:\windows\system32\route.exe ADD
193.175.73.100 MASK 255.255.255.255 192.168.1.1
Fri May 24 12:10:18 2013 ROUTE: CreateIpForwardEntry succeeded with
dwForwardMetric1=25 and dwForwardType=4
Fri May 24 12:10:18 2013 Route addition via IPAPI succeeded [adaptive]
Fri May 24 12:10:18 2013 C:\windows\system32\route.exe ADD 0.0.0.0
MASK 128.0.0.0 172.28.28.62
Fri May 24 12:10:18 2013 Warning: route gateway is not reachable on
any active network adapters: 172.28.28.62
Fri May 24 12:10:18 2013 Route addition via IPAPI failed [adaptive]
Fri May 24 12:10:18 2013 Route addition fallback to route.exe
Fri May 24 12:10:18 2013 env_block: add
PATH=C:\Windows\System32;C:\WINDOWS;C:\WINDOWS\System32\Wbem
 OK!
 Fri May 24 12:10:18 2013 C:\windows\system32\route.exe ADD 128.0.0.0
MASK 128.0.0.0 172.28.28.62
Fri May 24 12:10:18 2013 Warning: route gateway is not reachable on
any active network adapters: 172.28.28.62
Fri May 24 12:10:18 2013 Route addition via IPAPI failed [adaptive]
Fri May 24 12:10:18 2013 Route addition fallback to route.exe
Fri May 24 12:10:18 2013 env_block: add
PATH=C:\Windows\System32;C:\WINDOWS;C:\WINDOWS\System32\Wbem
 OK!
 Fri May 24 12:10:18 2013 MANAGEMENT: >STATE:1369390218,ADD_ROUTES,,,
 Fri May 24 12:10:18 2013 C:\windows\system32\route.exe ADD 172.28.0.1
MASK 255.255.255.255 172.28.28.62
Fri May 24 12:10:18 2013 Warning: route gateway is not reachable on
any active network adapters: 172.28.28.62
Fri May 24 12:10:18 2013 Route addition via IPAPI failed [adaptive]
Fri May 24 12:10:18 2013 Route addition fallback to route.exe
Fri May 24 12:10:18 2013 env_block: add
PATH=C:\Windows\System32;C:\WINDOWS;C:\WINDOWS\System32\Wbem
 OK!
 SYSTEM ROUTING TABLE
 0.0.0.0 0.0.0.0 192.168.1.1 p=0 i=13 t=4 pr=3 a=289 h=0 m=25/0/0/0/0
 0.0.0.0 128.0.0.0 172.28.28.62 p=0 i=13 t=4 pr=3 a=0 h=0 m=26/0/0/0/0
 127.0.0.0 255.0.0.0 127.0.0.1 p=0 i=1 t=3 pr=2 a=65196 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=2 a=65196 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=2 a=65196 h=0
m=306/0/0/0/0
128.0.0.0 128.0.0.0 172.28.28.62 p=0 i=13 t=4 pr=3 a=0 h=0 m=26/0/0/0/0
172.28.0.1 255.255.255.255 172.28.28.62 p=0 i=13 t=4 pr=3 a=0 h=0
m=26/0/0/0/0
192.168.1.0 255.255.255.0 192.168.1.16 p=0 i=13 t=3 pr=2 a=289 h=0
m=281/0/0/0/0
192.168.1.16 255.255.255.255 192.168.1.16 p=0 i=13 t=3 pr=2 a=289 h=0
m=281/0/0/0/0
192.168.1.255 255.255.255.255 192.168.1.16 p=0 i=13 t=3 pr=2 a=289 h=0
m=281/0/0/0/0
193.175.73.100 255.255.255.255 192.168.1.1 p=0 i=13 t=4 pr=3 a=0 h=0
m=25/0/0/0/0
224.0.0.0 240.0.0.0 127.0.0.1 p=0 i=1 t=3 pr=2 a=65196 h=0 m=306/0/0/0/0
224.0.0.0 240.0.0.0 192.168.1.16 p=0 i=13 t=3 pr=2 a=298 h=0
m=281/0/0/0/0
255.255.255.255 255.255.255.255 127.0.0.1 p=0 i=1 t=3 pr=2 a=65196 h=0
m=306/0/0/0/0
255.255.255.255 255.255.255.255 192.168.1.16 p=0 i=13 t=3 pr=2 a=298
h=0 m=281/0/0/0/0
SYSTEM ADAPTER LIST
TAP-Windows Adapter V9
  Index = 50
    GUID = {E55B897B-802A-4BC8-A02A-E98C2D7A9BC8}
      IP = 0.0.0.0/0.0.0.0 
        MAC = 00:ff:e5:5b:89:7b
          GATEWAY = 0.0.0.0/255.255.255.255 
            DHCP SERV =  
              DHCP LEASE OBTAINED = Fri May 24 12:10:18 2013
                DHCP LEASE EXPIRES  = Fri May 24 12:10:18 2013
                  DNS SERV =  
                  Microsoft Hosted Network Virtual Adapter
                    Index = 17
                      GUID = {079F3724-EEF3-433F-AEA9-173C39070EFE}
                        IP = 0.0.0.0/0.0.0.0 
                          MAC = 52:b7:c3:42:7b:20
                            GATEWAY = 0.0.0.0/255.255.255.255 
                              DHCP SERV =  
                                DHCP LEASE OBTAINED = Fri May 24
12:10:18 2013
  DHCP LEASE EXPIRES  = Fri May 24 12:10:18 2013
    DNS SERV =  
    Bluetooth Device (Personal Area Network)
      Index = 16
        GUID = {5EF954EF-45C1-4485-8828-1A060E16EFF4}
          IP = 0.0.0.0/0.0.0.0 
            MAC = 50:b7:c3:42:7b:21
              GATEWAY = 0.0.0.0/255.255.255.255 
                DHCP SERV =  
                  DHCP LEASE OBTAINED = Fri May 24 12:10:18 2013
                    DHCP LEASE EXPIRES  = Fri May 24 12:10:18 2013
                      DNS SERV =  
                      Microsoft Wi-Fi Direct Virtual Adapter
                        Index = 14
                          GUID = {E212469D-D2CA-4D95-BD8C-C3628BCFAEB9}
                            IP = 0.0.0.0/0.0.0.0 
                              MAC = 12:b7:c3:42:7b:20
                                GATEWAY = 0.0.0.0/255.255.255.255 
                                  DHCP SERV =  
                                    DHCP LEASE OBTAINED = Fri May 24
12:10:18 2013
  DHCP LEASE EXPIRES  = Fri May 24 12:10:18 2013
    DNS SERV =  
    Qualcomm Atheros AR946x Wireless Network Adapter
      Index = 13
        GUID = {38204274-5A3B-4AD4-B28E-463BBE4B38FF}
          IP = 192.168.1.16/255.255.255.0 
            MAC = 50:b7:c3:42:7b:20
              GATEWAY = 192.168.1.1/255.255.255.255 
                DHCP SERV = 192.168.1.1/255.255.255.255 
                  DHCP LEASE OBTAINED = Fri May 24 12:05:33 2013
                    DHCP LEASE EXPIRES  = Fri May 31 12:05:33 2013
                      DNS SERV = 192.168.1.1/255.255.255.255 
                      Realtek PCIe GBE Family Controller
                        Index = 12
                          GUID = {55114975-A07E-466B-9673-C3FF0AE1143B}
                            IP = 0.0.0.0/0.0.0.0 
                              MAC = 50:b7:c3:6e:ea:55
                                GATEWAY = 0.0.0.0/255.255.255.255 
                                  DHCP SERV =  
                                    DHCP LEASE OBTAINED = Fri May 24
12:10:18 2013
  DHCP LEASE EXPIRES  = Fri May 24 12:10:18 2013
    DNS SERV =  
    Fri May 24 12:10:18 2013 Initialization Sequence Completed With
Errors ( see http://openvpn.net/faq.html#dhcpclientserv )
Fri May 24 12:10:18 2013 MANAGEMENT:
>STATE:1369390218,CONNECTED,ERROR,172.28.28.61,193.175.73.100
Fri May 24 12:10:19 2013 Start net commands...
Fri May 24 12:10:19 2013 C:\windows\system32\net.exe stop dnscache
DNS-Client wird beendet.
DNS-Client konnte nicht beendet werden.

Fri May 24 12:10:21 2013 C:\windows\system32\net.exe start dnscache
Der angeforderte Dienst wurde bereits gestartet.

Sie erhalten weitere Hilfe, wenn Sie NET HELPMSG 2182 eingeben.

Fri May 24 12:10:21 2013 ERROR: Windows ipconfig command failed:
returned error code 2
Fri May 24 12:10:21 2013 C:\windows\system32\ipconfig.exe /flushdns

Windows-IP-Konfiguration

Der DNS-Aufl”sungscache wurde geleert.
Fri May 24 12:10:21 2013 C:\windows\system32\ipconfig.exe /registerdns

Windows-IP-Konfiguration

Die Registrierung der DNS-Ressourceneintr„ge fr alle Adapter
dieses Computer wurde initialisiert. Fehler werden in der
Ereignisanzeige in 15 Minuten aufgefhrt.
Fri May 24 12:10:24 2013 End net commands...


-- 
Ralf Hildebrandt                   Charite Universitätsmedizin Berlin
ralf.hildebra...@charite.de        Campus Benjamin Franklin
http://www.charite.de              Hindenburgdamm 30, 12203 Berlin
Geschäftsbereich IT, Abt. Netzwerk fon: +49-30-450.570.155

------------------------------------------------------------------------------
Try New Relic Now & We'll Send You this Cool Shirt
New Relic is the only SaaS-based application performance monitoring service 
that delivers powerful full stack analytics. Optimize and monitor your
browser, app, & servers with just a few lines of code. Try New Relic
and get this awesome Nerd Life shirt! http://p.sf.net/sfu/newrelic_d2d_may
_______________________________________________
Openvpn-users mailing list
Openvpn-users@lists.sourceforge.net
https://lists.sourceforge.net/lists/listinfo/openvpn-users

Reply via email to