Hello
Thanks for your reply.
Unfortunately the options route-delay and tap-sleep don't help. I have captured the packets on the tap adapter, but there were no dhcp packets.
However I have a dump of a session, where openvpn was able to configure the tap adapter:
# tcpdump -r tap_dev_dump.pcap -vvv
reading from file tap_dev_dump.pcap, link-type EN10MB (Ethernet)
07:44:37.300279 IP (tos 0x0, ttl 128, id 0, offset 0, flags [none], proto UDP (17), length 340)
0.0.0.0.bootpc > 255.255.255.255.bootps: [udp sum ok] BOOTP/DHCP, Request from 00:ff:96:ca:5e:31 (oui Unknown), length 312, xid 0x5d8908f3, Flags [none] (0x0000)
Client-Ethernet-Address 00:ff:96:ca:5e:31 (oui Unknown)
Vendor-rfc1048 Extensions
Magic Cookie 0x63825363
DHCP-Message Option 53, length 1: Request
Client-ID Option 61, length 7: ether 00:ff:96:ca:5e:31
Requested-IP Option 50, length 4: 10.255.250.2
Hostname Option 12, length 11: "chgutXcl05^@"
FQDN Option 81, length 13: "chgutXcl05"
Vendor-Class Option 60, length 8: "MSFT 5.0"
Parameter-Request Option 55, length 13:
Subnet-Mask, Domain-Name, Default-Gateway, Domain-Name-Server
Netbios-Name-Server, Netbios-Node, Netbios-Scope, Router-Discovery
Static-Route, Classless-Static-Route, Classless-Static-Route-Microsoft, Option 252
Vendor-Option
END Option 255, length 0
07:44:37.300350 IP (tos 0x0, ttl 16, id 0, offset 0, flags [none], proto UDP (17), length 278)
10.255.250.254.bootps > 255.255.255.255.bootpc: [udp sum ok] BOOTP/DHCP, Reply, length 250, xid 0x5d8908f3, Flags [none] (0x0000)
Server-IP 10.255.250.254
Client-Ethernet-Address 00:ff:96:ca:5e:31 (oui Unknown)
Vendor-rfc1048 Extensions
Magic Cookie 0x63825363
DHCP-Message Option 53, length 1: NACK
Server-ID Option 54, length 4: 10.255.250.254
END Option 255, length 0
07:44:37.324250 IP (tos 0x0, ttl 16, id 0, offset 0, flags [none], proto UDP (17), length 300)
10.255.250.254.bootps > 255.255.255.255.bootpc: [udp sum ok] BOOTP/DHCP, Reply, length 272, xid 0x3f5bbd6b, Flags [none] (0x0000)
Your-IP 10.255.250.2
Server-IP 10.255.250.254
Client-Ethernet-Address 00:ff:96:ca:5e:31 (oui Unknown)
Vendor-rfc1048 Extensions
Magic Cookie 0x63825363
DHCP-Message Option 53, length 1: Offer
Server-ID Option 54, length 4: 10.255.250.254
Lease-Time Option 51, length 4: 31536000
Subnet-Mask Option 1, length 4: 255.255.255.0
Domain-Name Option 15, length 8: "u-s-p.ch"
END Option 255, length 0
07:44:37.324707 IP (tos 0x0, ttl 16, id 0, offset 0, flags [none], proto UDP (17), length 300)
10.255.250.254.bootps > 255.255.255.255.bootpc: [udp sum ok] BOOTP/DHCP, Reply, length 272, xid 0x3f5bbd6b, Flags [none] (0x0000)
Your-IP 10.255.250.2
Server-IP 10.255.250.254
Client-Ethernet-Address 00:ff:96:ca:5e:31 (oui Unknown)
Vendor-rfc1048 Extensions
Magic Cookie 0x63825363
DHCP-Message Option 53, length 1: ACK
Server-ID Option 54, length 4: 10.255.250.254
Lease-Time Option 51, length 4: 31536000
Subnet-Mask Option 1, length 4: 255.255.255.0
Domain-Name Option 15, length 8: "u-s-p.ch"
END Option 255, length 0
Here the log, where the configuration was unsuccesful:
Wed Mar 07 22:47:21 2012 us=718000 Current Parameter Settings:
Wed Mar 07 22:47:21 2012 us=718000 config = 'config.ovpn'
Wed Mar 07 22:47:21 2012 us=718000 mode = 0
Wed Mar 07 22:47:21 2012 us=718000 show_ciphers = DISABLED
Wed Mar 07 22:47:21 2012 us=718000 show_digests = DISABLED
Wed Mar 07 22:47:21 2012 us=718000 show_engines = DISABLED
Wed Mar 07 22:47:21 2012 us=718000 genkey = DISABLED
Wed Mar 07 22:47:21 2012 us=718000 key_pass_file = '[UNDEF]'
Wed Mar 07 22:47:21 2012 us=718000 show_tls_ciphers = DISABLED
Wed Mar 07 22:47:21 2012 us=718000 Connection profiles [default]:
Wed Mar 07 22:47:21 2012 us=718000 proto = udp
Wed Mar 07 22:47:21 2012 us=718000 local = '[UNDEF]'
Wed Mar 07 22:47:21 2012 us=718000 local_port = 0
Wed Mar 07 22:47:21 2012 us=718000 remote = '10.10.2.4'
Wed Mar 07 22:47:21 2012 us=718000 remote_port = 1194
Wed Mar 07 22:47:21 2012 us=718000 remote_float = DISABLED
Wed Mar 07 22:47:21 2012 us=718000 bind_defined = DISABLED
Wed Mar 07 22:47:21 2012 us=718000 bind_local = DISABLED
Wed Mar 07 22:47:21 2012 us=718000 connect_retry_seconds = 5
Wed Mar 07 22:47:21 2012 us=718000 connect_timeout = 10
Wed Mar 07 22:47:21 2012 us=718000 NOTE: --mute triggered...
Wed Mar 07 22:47:21 2012 us=718000 214 variation(s) on previous 20 message(s) suppressed by --mute
Wed Mar 07 22:47:21 2012 us=718000 OpenVPN 2.3-alpha1 Win32-MSVC++ [SSL (OpenSSL)] [LZO2] [PF_INET6] [IPv6 payload 20110522-1 (2.2.0)] built on Feb 21 2012
Wed Mar 07 22:47:21 2012 us=718000 WARNING: Make sure you understand the semantics of --tls-remote before using it (see the man page).
Wed Mar 07 22:47:21 2012 us=718000 NOTE: OpenVPN 2.1 requires '--script-security 2' or higher to call user-defined scripts or executables
Wed Mar 07 22:47:21 2012 us=828000 LZO compression initialized
Wed Mar 07 22:47:21 2012 us=828000 Control Channel MTU parms [ L:1542 D:138 EF:38 EB:0 ET:0 EL:0 ]
Wed Mar 07 22:47:21 2012 us=843000 Socket Buffers: R=[65536->65536] S=[65536->65536]
Wed Mar 07 22:47:21 2012 us=843000 Data Channel MTU parms [ L:1542 D:1450 EF:42 EB:135 ET:0 EL:0 AF:3/1 ]
Wed Mar 07 22:47:21 2012 us=843000 Local Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-client'
Wed Mar 07 22:47:21 2012 us=843000 Expected Remote Options String: 'V4,dev-type tun,link-mtu 1542,tun-mtu 1500,proto UDPv4,comp-lzo,cipher BF-CBC,auth SHA1,keysize 128,key-method 2,tls-server'
Wed Mar 07 22:47:21 2012 us=843000 Local Options hash (VER=V4): '41690919'
Wed Mar 07 22:47:21 2012 us=843000 Expected Remote Options hash (VER=V4): '530fdded'
Wed Mar 07 22:47:21 2012 us=843000 UDPv4 link local: [undef]
Wed Mar 07 22:47:21 2012 us=843000 UDPv4 link remote: [AF_INET]10.10.2.4:1194
Wed Mar 07 22:47:21 2012 us=843000 TLS: Initial packet from [AF_INET]10.10.2.4:1194, sid=8c59aa08 6f2ab081
Wed Mar 07 22:47:21 2012 us=859000 VERIFY OK: depth=1, C=CH, L=Guemligen, O=USP, OU=ca, CN=chgut1ca.u-s-p.ch
Wed Mar 07 22:47:21 2012 us=859000 VERIFY X509NAME OK: C=CH, L=Guemligen, O=USP, CN=chgut2fw01.u-s-p.ch
Wed Mar 07 22:47:21 2012 us=859000 VERIFY OK: depth=0, C=CH, L=Guemligen, O=USP, CN=chgut2fw01.u-s-p.ch
Wed Mar 07 22:47:21 2012 us=921000 Data Channel Encrypt: Cipher 'BF-CBC' initialized with 128 bit key
Wed Mar 07 22:47:21 2012 us=921000 Data Channel Encrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Wed Mar 07 22:47:21 2012 us=921000 Data Channel Decrypt: Cipher 'BF-CBC' initialized with 128 bit key
Wed Mar 07 22:47:21 2012 us=921000 Data Channel Decrypt: Using 160 bit message hash 'SHA1' for HMAC authentication
Wed Mar 07 22:47:21 2012 us=921000 Control Channel: TLSv1, cipher TLSv1/SSLv3 DHE-RSA-AES256-SHA, 1024 bit RSA
Wed Mar 07 22:47:21 2012 us=921000 [chgut2fw01.u-s-p.ch] Peer Connection Initiated with [AF_INET]10.10.2.4:1194
Wed Mar 07 22:47:24 2012 us=78000 SENT CONTROL [chgut2fw01.u-s-p.ch]: 'PUSH_REQUEST' (status=1)
Wed Mar 07 22:47:24 2012 us=78000 PUSH: Received control message: 'PUSH_REPLY,topology subnet,route-gateway 10.255.250.1,ping 10,ping-restart 120,ip-win32 dynamic,dhcp-option DOMAIN u-s-p.ch,route 172.16.20.0 255.255.255.0,route 192.168.200.0 255.255.255.0,route 192.168.220.0 255.255.255.0,ifconfig 10.255.250.2 255.255.255.0'
Wed Mar 07 22:47:24 2012 us=78000 OPTIONS IMPORT: timers and/or timeouts modified
Wed Mar 07 22:47:24 2012 us=78000 OPTIONS IMPORT: --ifconfig/up options modified
Wed Mar 07 22:47:24 2012 us=78000 OPTIONS IMPORT: route options modified
Wed Mar 07 22:47:24 2012 us=78000 OPTIONS IMPORT: route-related options modified
Wed Mar 07 22:47:24 2012 us=78000 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified
Wed Mar 07 22:47:24 2012 us=78000 ROUTE_GATEWAY 10.0.2.2/255.255.255.0 I=12 HWADDR=08:00:27:47:5e:2d
Wed Mar 07 22:47:24 2012 us=93000 do_ifconfig, tt->ipv6=0, tt->did_ifconfig_ipv6_setup=0
Wed Mar 07 22:47:24 2012 us=93000 open_tun, tt->ipv6=0
Wed Mar 07 22:47:24 2012 us=93000 TAP-WIN32 device [Local Area Connection] opened: \\.\Global\{96CA5E31-01B9-41F6-8078-2A760FDDFB09}.tap
Wed Mar 07 22:47:24 2012 us=93000 TAP-Win32 Driver Version 9.9
Wed Mar 07 22:47:24 2012 us=93000 TAP-Win32 MTU=1500
Wed Mar 07 22:47:24 2012 us=93000 Set TAP-Win32 TUN subnet mode network/local/netmask = 10.255.250.0/10.255.250.2/255.255.255.0 [SUCCEEDED]
Wed Mar 07 22:47:24 2012 us=93000 Notified TAP-Win32 driver to set a DHCP IP/netmask of 10.255.250.2/255.255.255.0 on interface {96CA5E31-01B9-41F6-8078-2A760FDDFB09} [DHCP-serv: 10.255.250.254, lease-time: 31536000]
Wed Mar 07 22:47:24 2012 us=93000 DHCP option string: 0f08752d 732d702e 6368
Wed Mar 07 22:47:24 2012 us=93000 Sleeping for 5 seconds...
Wed Mar 07 22:47:29 2012 us=109000 Successful ARP Flush on interface [15] {96CA5E31-01B9-41F6-8078-2A760FDDFB09}
Wed Mar 07 22:47:34 2012 us=187000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:34 2012 us=187000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:39 2012 us=203000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:39 2012 us=203000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:40 2012 us=218000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:40 2012 us=218000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:41 2012 us=234000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:41 2012 us=234000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:42 2012 us=250000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:42 2012 us=250000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:43 2012 us=265000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:43 2012 us=265000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:44 2012 us=281000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:44 2012 us=281000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:45 2012 us=546000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:45 2012 us=546000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:46 2012 us=812000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:46 2012 us=812000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:48 2012 us=78000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:48 2012 us=78000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:49 2012 us=687000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:49 2012 us=687000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:50 2012 us=953000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:50 2012 us=953000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:52 2012 us=218000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:52 2012 us=218000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:53 2012 us=484000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:53 2012 us=484000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:54 2012 us=750000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:54 2012 us=750000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:55 2012 us=968000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:55 2012 us=968000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:57 2012 us=187000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:57 2012 us=187000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:47:58 2012 us=406000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:47:58 2012 us=406000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:48:00 2012 us=109000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:48:00 2012 us=109000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:48:01 2012 us=328000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:48:01 2012 us=328000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:48:02 2012 us=546000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:48:02 2012 us=546000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:48:03 2012 us=765000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:48:03 2012 us=765000 Route: Waiting for TUN/TAP interface to come up...
Wed Mar 07 22:48:04 2012 us=984000 TEST ROUTES: 0/0 succeeded len=3 ret=0 a=0 u/d=down
Wed Mar 07 22:48:04 2012 us=984000 C:\Windows\system32\route.exe ADD 172.16.20.0 MASK 255.255.255.0 10.255.250.1
Wed Mar 07 22:48:04 2012 us=984000 Warning: route gateway is not reachable on any active network adapters: 10.255.250.1
Wed Mar 07 22:48:04 2012 us=984000 Route addition via IPAPI failed [adaptive]
Wed Mar 07 22:48:04 2012 us=984000 Route addition fallback to route.exe
Wed Mar 07 22:48:04 2012 us=984000 env_block: add PATH=C:\Windows\System32;C:\WINDOWS;C:\WINDOWS\System32\Wbem
OK!
Wed Mar 07 22:48:05 2012 C:\Windows\system32\route.exe ADD 192.168.200.0 MASK 255.255.255.0 10.255.250.1
Wed Mar 07 22:48:05 2012 Warning: route gateway is not reachable on any active network adapters: 10.255.250.1
Wed Mar 07 22:48:05 2012 Route addition via IPAPI failed [adaptive]
Wed Mar 07 22:48:05 2012 Route addition fallback to route.exe
Wed Mar 07 22:48:05 2012 env_block: add PATH=C:\Windows\System32;C:\WINDOWS;C:\WINDOWS\System32\Wbem
OK!
Wed Mar 07 22:48:05 2012 us=15000 C:\Windows\system32\route.exe ADD 192.168.220.0 MASK 255.255.255.0 10.255.250.1
Wed Mar 07 22:48:05 2012 us=31000 Warning: route gateway is not reachable on any active network adapters: 10.255.250.1
Wed Mar 07 22:48:05 2012 us=31000 Route addition via IPAPI failed [adaptive]
Wed Mar 07 22:48:05 2012 us=31000 Route addition fallback to route.exe
Wed Mar 07 22:48:05 2012 us=31000 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 10.0.2.2 p=0 i=12 t=4 pr=3 a=13924 h=0 m=10/0/0/0/0
10.0.2.0 255.255.255.0 10.0.2.15 p=0 i=12 t=3 pr=2 a=13924 h=0 m=266/0/0/0/0
10.0.2.15 255.255.255.255 10.0.2.15 p=0 i=12 t=3 pr=2 a=13924 h=0 m=266/0/0/0/0
10.0.2.255 255.255.255.255 10.0.2.15 p=0 i=12 t=3 pr=2 a=13924 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=2 a=13940 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=13940 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=13940 h=0 m=306/0/0/0/0
172.16.20.0 255.255.255.0 10.255.250.1 p=0 i=12 t=4 pr=3 a=0 h=0 m=11/0/0/0/0
192.168.200.0 255.255.255.0 10.255.250.1 p=0 i=12 t=4 pr=3 a=0 h=0 m=11/0/0/0/0
192.168.220.0 255.255.255.0 10.255.250.1 p=0 i=12 t=4 pr=3 a=0 h=0 m=11/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=13940 h=0 m=306/0/0/0/0
224.0.0.0 240.0.0.0 10.0.2.15 p=0 i=12 t=3 pr=2 a=13932 h=0 m=266/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=13940 h=0 m=306/0/0/0/0
255.255.255.255 255.255.255.255 10.0.2.15 p=0 i=12 t=3 pr=2 a=13932 h=0 m=266/0/0/0/0
SYSTEM ADAPTER LIST
TAP-Win32 Adapter V9
Index = 15
GUID = {96CA5E31-01B9-41F6-8078-2A760FDDFB09}
IP = 0.0.0.0/0.0.0.0
MAC = 00:ff:96:ca:5e:31
GATEWAY = 0.0.0.0/255.255.255.255
DHCP SERV =
DHCP LEASE OBTAINED = Wed Mar 07 22:48:05 2012
DHCP LEASE EXPIRES = Wed Dec 31 16:00:00 1969
DNS SERV =
Intel(R) PRO/1000 MT Desktop Adapter
Index = 12
GUID = {55CAE6F0-EA1F-4629-8074-378F39C7A5FE}
IP = 10.0.2.15/255.255.255.0
MAC = 08:00:27:47:5e:2d
GATEWAY = 10.0.2.2/255.255.255.255
DHCP SERV = 10.0.2.2/255.255.255.255
DHCP LEASE OBTAINED = Wed Mar 07 08:31:15 2012
DHCP LEASE EXPIRES = Wed Dec 31 16:00:00 1969
DNS SERV = 192.168.1.29/255.255.255.255 192.168.1.38/255.255.255.255 172.17.4.212/255.255.255.255
Wed Mar 07 22:48:05 2012 us=109000 Initialization Sequence Completed With Errors ( see
http://openvpn.net/faq.html#dhcpclientserv )
Best regards
Elmar