none
DHCP renew problem WinCE7 RRS feed

  • Question

  • The DHCP client in Windows Embedded Compact 7 always send a DHCPDECLINE message to the DHCP server when the system starts, and the system take a different IP address every time.
    This is the output of the Checked Image, in bold the error, because the IP that is considered in conflict was assigned previously  to itself.
    The KB2627558 of October 2011 has fixed a problem related to DHCP, this could be a side effect?
    19645 PID:400002 TID:c20006 Dhcp: dllentry() -1069204072
    19646 PID:400002 TID:c20006 Dhcp:Register: FT 1397597118, Xid 1397597118
    19650 PID:400002 TID:2fd0006 +DHCP: DhcpNSIMediaSenseCallback: NotificationType: 3
    19650 PID:400002 TID:2fd0006 +DHCP: DhcpEnumInterfaces: 
    19652 PID:400002 TID:2fd0006 +DHCP: DhcpOnMediaConnected: 
    19652 PID:400002 TID:2fd0006 DHCP:RequestDhcpAddr: IfIndex 0
    19653 PID:400002 TID:2fd0006 DHCP:: $$$ NewDhcpInfo: 0xdb7d0460
    19653 PID:400002 TID:1400056 DHCP:DhcpEventWorker: Processing DHCP_EVENT_OBTAIN_LEASE for VMINI1
    19654 PID:400002 TID:1400056 +DHCP:GetDhcpConfig(VMINI1):
    19654 PID:400002 TID:1400056 DHCP:StringToAddr: Converted 192.168.1.112 to address 7001A8C0
    19654 PID:400002 TID:1400056 DHCP:StringToAddr: Converted 255.255.255.0 to address FFFFFF
    19654 PID:400002 TID:1400056 DHCP:StringToAddr: Converted 192.168.1.1 to address 101A8C0
    19654 PID:400002 TID:1400056 DHCP:StringToAddr: Converted 192.168.1.254 to address FE01A8C0
    19654 PID:400002 TID:1400056 DHCP:StringToAddr: Converted 192.168.1.1 to address 101A8C0
    19654 PID:400002 TID:1400056 DHCP:StringToAddr: Converted 192.168.1.2 to address 201A8C0
    19655 PID:400002 TID:1400056 DHCP:StringToAddr: Converted 169.254.0.0 to address FEA9
    19655 PID:400002 TID:1400056 DHCP:StringToAddr: Converted 255.255.0.0 to address FFFF
    19661 PID:400002 TID:1400056 -DHCP:GetDhcpConfig(VMINI1):
    19663 PID:400002 TID:1400056 +DHCP:DhcpInitSock: pDhcp 0XDB7D0460
    19665 PID:400002 TID:2fd0006 -DHCP:RequestDhcpAddr: Context DB7D0460 IfIndex 3 Status 0
    19665 PID:400002 TID:2fd0006 -DHCP: DhcpOnMediaConnected: Status 0
    19665 PID:400002 TID:2fd0006 +DHCP: DhcpOnMediaConnected: 
    19665 PID:400002 TID:2fd0006 DHCP:RequestDhcpAddr: IfIndex 0
    19665 PID:400002 TID:2fd0006 DHCP:RequestDhcpAddr: Skip IfIndex 1 Loopback Pseudo-Interface 1
    19666 PID:400002 TID:2fd0006 -DHCP:RequestDhcpAddr: Context 0 IfIndex 1 Status 1
    19666 PID:400002 TID:2fd0006 -DHCP: DhcpOnMediaConnected: Status 1
    19666 PID:400002 TID:2fd0006 -DHCP: DhcpEnumInterfaces: Status 0
    19666 PID:400002 TID:2fd0006 -DHCP: DhcpNSIMediaSenseCallback: Status 0
    19666 PID:400002 TID:2fd0006 *DhcpInitNSIMediaSense: Status 0
    19704 PID:400002 TID:1400056 -DHCP:DhcpInitSock: pDhcp 0xDB7D0460/0x1 Ret: 0
    19707 PID:400002 TID:1400056 +DHCP:GetDhcpLease: pDhcp 0xDB7D0460/0x1 (VMINI1)
    19707 PID:400002 TID:1400056 +DHCP: DhcpSetIpRoute: Dhcp 0xDB7D0460 Dest 0 NextHop FE01A8C0
    19709 PID:400002 TID:1400056 -DHCP: DhcpSetIpRoute: Dhcp 0xDB7D0460 Status 0x0
    19709 PID:400002 TID:1400056 -DHCP: DhcpSetGateways: Dhcp 0xDB7D0460 Status 0x0
    19709 PID:400002 TID:1400056 DHCP: DhcpAddIPv4Address: Plumbing Addr 7001A8C0 subnet mask len 24
    19711 PID:400002 TID:1400056 DHCP: DhcpAddIPv4Address: waiting for addr change callback 10000 secs
    19714 PID:400002 TID:37d0006 +DHCP: DhcpAddressChangeCallback: Notification Type 1 Address 7001A8C0 DadState 0
    19869 PID:400002 TID:37d0006 +DHCP: DhcpNSIMediaSenseCallback: NotificationType: 0
    19870 PID:400002 TID:37d0006 +DHCP: DhcpOnMediaConnected: 
    23474 PID:400002 TID:3840006 +DHCP: DhcpAddressChangeCallback: Notification Type 0 Address 0 DadState 0
    29721 PID:400002 TID:1400056 DHCP: DhcpCheckDadAndUpdateAddress: Address not changed and Valid. Updating the other options
    29722 PID:400002 TID:1400056 -DHCP: DhcpCheckDadAndUpdateAddress: dwError 0
    29722 PID:400002 TID:1400056 DHCP: DhcpAddIPv4Address: successfully plumbed address 7001A8C0 (#1)
    29722 PID:400002 TID:1400056 *DHCP: DhcpRemoveIPAddrs: pDhcp DB7D0460 Except 7001A8C0
    29723 PID:400002 TID:1400056 DHCP: DhcpEnumIPv4Address: Skipping Addr 100007F
    29723 PID:400002 TID:1400056 -DHCP: DhcpEnumIPv4Address: cAddrs 1 Status 0x0
    34721 PID:400002 TID:1400056 +BuildDhcpPkt(VMINI1): REQUEST 0xDB7D0460
    34721 PID:400002 TID:1400056 DHCP:BuildDhcpPkt(VMINI1): TX REQUEST
    34721 PID:400002 TID:1400056 -BuildDhcpPkt(VMINI1): 0x1 Len 268
    34722 PID:400002 TID:1400056 +SendDhcpPkt(VMINI1): pPkt DAF4EF24
    34722 PID:400002 TID:37d0006 DHCP:HandleMediaConnect: pDhcp 0xDB7D0460 Index 3
    34722 PID:400002 TID:37d0006 -DHCP: DhcpOnMediaConnected: Status 0
    34722 PID:400002 TID:37d0006 -DHCP: DhcpNSIMediaSenseCallback: Status 0
    34726 PID:400002 TID:1400056 VMini:: Detected DHCP [REQUEST]
    34726 PID:400002 TID:1400056 *DhcpSendDown(VMINI1): Dest 67/7001a8c0, Flags 1, Sent 300/300 Status 0
    34728 PID:400002 TID:1400056 SendDhcpPkt(VMINI1): select() returned 1
    34728 PID:400002 TID:1400056 SendDhcpPkt(VMINI1): calling recv @ 34728 ms
    34728 PID:400002 TID:1400056 SendDhcpPkt(VMINI1): calling recv returned 301 @ 34728 ms
    34728 PID:400002 TID:1400056 DHCP:+TranslatePkt(VMINI1): OP 2, Htype 1, Xid 9aa24f50/9aa24f50
    34728 PID:400002 TID:1400056 DHCP:TranslatePkt(VMINI1): RX ACK
    34728 PID:400002 TID:1400056 DHCP:-TranslatePkt(VMINI1): Type ACK, Ret: 0
    34728 PID:400002 TID:1400056 -SendDhcpPkt(VMINI1): pPkt DAF4EF24 Ret: 0
    34729 PID:400002 TID:1400056 +DHCP:SetDhcpConfig(VMINI1):
    34729 PID:400002 TID:1400056 +DHCP:AddrToString: 7001A8C0
    34729 PID:400002 TID:1400056 -DHCP:AddrToString: 192.168.1.112
    34729 PID:400002 TID:1400056 DHCP:SetDhcpConfig: set IPAddr 7001A8C0
    34729 PID:400002 TID:1400056 +DHCP:AddrToString: FFFFFF
    34729 PID:400002 TID:1400056 -DHCP:AddrToString: 255.255.255.0
    34729 PID:400002 TID:1400056 +DHCP:AddrToString: 101A8C0
    34729 PID:400002 TID:1400056 -DHCP:AddrToString: 192.168.1.1
    34729 PID:400002 TID:1400056 +DHCP:AddrToString: FE01A8C0
    34729 PID:400002 TID:1400056 -DHCP:AddrToString: 192.168.1.254
    34729 PID:400002 TID:1400056 +DHCP:AddrToString: 101A8C0
    34729 PID:400002 TID:1400056 -DHCP:AddrToString: 192.168.1.1
    34729 PID:400002 TID:1400056 +DHCP:AddrToString: 201A8C0
    34729 PID:400002 TID:1400056 -DHCP:AddrToString: 192.168.1.2
    34731 PID:400002 TID:1400056 DHCP:SetDhcpConfig: set LeaseObtained 1ccb034 5bb0c080
    34731 PID:400002 TID:1400056 DHCP:SetDhcpConfig: T1 54600 T2 93a80 Lease a8c00
    34731 PID:400002 TID:1400056 +DHCP:AddrToString: FEA9
    34731 PID:400002 TID:1400056 -DHCP:AddrToString: 169.254.0.0
    34731 PID:400002 TID:1400056 +DHCP:AddrToString: FFFF
    34731 PID:400002 TID:1400056 -DHCP:AddrToString: 255.255.0.0
    34743 PID:400002 TID:1400056 -DHCP:SetDhcpConfig(VMINI1):
    34747 PID:400002 TID:1400056 +DHCP: DhcpSetIpRoute: Dhcp 0xDB7D0460 Dest 0 NextHop FE01A8C0
    34747 PID:400002 TID:1400056 -DHCP: DhcpSetIpRoute: Dhcp 0xDB7D0460 Status 0xC000022A
    34747 PID:400002 TID:1400056 -DHCP: DhcpSetGateways: Dhcp 0xDB7D0460 Status 0xC000022A
    34747 PID:400002 TID:1400056 DHCP: DhcpAddIPv4Address: Plumbing Addr 7001A8C0 subnet mask len 24
    34748 PID:400002 TID:1400056 DHCP: DhcpAddIPv4Address: IP address already in use by another adapter. Mark it a Collision
    34748 PID:400002 TID:1400056 DHCP: DhcpAddIPv4Address: failed with error 0x5
    34749 PID:400002 TID:1400056 -DHCP: DhcpDelIPv4Address: pDhcp DB7D0460 DeleteAddr 7001A8C0 Status 0x0
    34749 PID:400002 TID:1400056 *DHCP: DhcpRemoveIPAddrs: pDhcp DB7D0460 Except 7001A8C0
    34749 PID:400002 TID:1400056 DHCP: DhcpEnumIPv4Address: Skipping Addr 100007F
    34750 PID:400002 TID:1400056 -DHCP: DhcpEnumIPv4Address: cAddrs 0 Status 0x0
    34750 PID:400002 TID:1400056 +BuildDhcpPkt(VMINI1): DECLINE 0xDB7D0460
    34750 PID:400002 TID:1400056 DHCP:BuildDhcpPkt(VMINI1): TX DECLINE
    34750 PID:400002 TID:1400056 -BuildDhcpPkt(VMINI1): 0x1 Len 265
    34751 PID:400002 TID:1400056 VMini:: Detected DHCP [DECLINE]
    34751 PID:400002 TID:1400056 *DhcpSendDown(VMINI1): Dest 67/7001a8c0, Flags 1, Sent 300/300 Status 0
    34751 PID:400002 TID:1400056 DHCP:TakeNetDown: pDhcp 0xdb7d0460 IP 7001A8C0!
    34762 PID:400002 TID:1400056 +BuildDhcpPkt(VMINI1): DISCOVER 0xDB7D0460
    34765 PID:400002 TID:1400056 DHCP:BuildDhcpPkt(VMINI1): TX DISCOVER
    34765 PID:400002 TID:1400056 -BuildDhcpPkt(VMINI1): 0x1 Len 262
    34765 PID:400002 TID:1400056 +SendDhcpPkt(VMINI1): pPkt DAF4EF24
    34765 PID:400002 TID:1400056 VMini:: Detected DHCP [DISCOVER]
    34766 PID:400002 TID:1400056 *DhcpSendDown(VMINI1): Dest 67/0, Flags 1, Sent 300/300 Status 0
    34767 PID:400002 TID:1400056 SendDhcpPkt(VMINI1): select() returned 1
    34767 PID:400002 TID:1400056 SendDhcpPkt(VMINI1): calling recv @ 34767 ms
    34767 PID:400002 TID:1400056 SendDhcpPkt(VMINI1): calling recv returned 301 @ 34767 ms
    34767 PID:400002 TID:1400056 DHCP:+TranslatePkt(VMINI1): OP 2, Htype 1, Xid 9ca24f50/9ca24f50
    34768 PID:400002 TID:1400056 DHCP:TranslatePkt(VMINI1): RX OFFER
    34768 PID:400002 TID:1400056 DHCP:-TranslatePkt(VMINI1): Type OFFER, Ret: 0
    34768 PID:400002 TID:1400056 -SendDhcpPkt(VMINI1): pPkt DAF4EF24 Ret: 0
    34768 PID:400002 TID:1400056 +BuildDhcpPkt(VMINI1): REQUEST 0xDB7D0460
    34768 PID:400002 TID:1400056 DHCP:BuildDhcpPkt(VMINI1): TX REQUEST
    34768 PID:400002 TID:1400056 -BuildDhcpPkt(VMINI1): 0x1 Len 274
    34777 PID:400002 TID:1400056 +SendDhcpPkt(VMINI1): pPkt DAF4EF24
    34778 PID:400002 TID:1400056 VMini:: Detected DHCP [REQUEST]
    34778 PID:400002 TID:1400056 *DhcpSendDown(VMINI1): Dest 67/7801a8c0, Flags 1, Sent 300/300 Status 0
    34779 PID:400002 TID:1400056 SendDhcpPkt(VMINI1): select() returned 1
    34779 PID:400002 TID:1400056 SendDhcpPkt(VMINI1): calling recv @ 34779 ms
    34780 PID:400002 TID:1400056 SendDhcpPkt(VMINI1): calling recv returned 301 @ 34780 ms
    34780 PID:400002 TID:1400056 DHCP:+TranslatePkt(VMINI1): OP 2, Htype 1, Xid 9ca24f50/9ca24f50
    34780 PID:400002 TID:1400056 DHCP:TranslatePkt(VMINI1): RX ACK
    34794 PID:400002 TID:1400056 DHCP:-TranslatePkt(VMINI1): Type ACK, Ret: 0
    34794 PID:400002 TID:1400056 -SendDhcpPkt(VMINI1): pPkt DAF4EF24 Ret: 0
    34794 PID:400002 TID:1400056 +DHCP:SetDhcpConfig(VMINI1):
    34794 PID:400002 TID:1400056 +DHCP:AddrToString: 7801A8C0
    34794 PID:400002 TID:1400056 -DHCP:AddrToString: 192.168.1.120
    34794 PID:400002 TID:1400056 DHCP:SetDhcpConfig: set IPAddr 7801A8C0
    34795 PID:400002 TID:1400056 +DHCP:AddrToString: FFFFFF
    34795 PID:400002 TID:1400056 -DHCP:AddrToString: 255.255.255.0
    34795 PID:400002 TID:1400056 +DHCP:AddrToString: 101A8C0
    34795 PID:400002 TID:1400056 -DHCP:AddrToString: 192.168.1.1
    34795 PID:400002 TID:1400056 +DHCP:AddrToString: FE01A8C0
    34795 PID:400002 TID:1400056 -DHCP:AddrToString: 192.168.1.254
    34795 PID:400002 TID:1400056 +DHCP:AddrToString: 101A8C0
    34795 PID:400002 TID:1400056 -DHCP:AddrToString: 192.168.1.1
    34795 PID:400002 TID:1400056 +DHCP:AddrToString: 201A8C0
    34795 PID:400002 TID:1400056 -DHCP:AddrToString: 192.168.1.2
    34805 PID:400002 TID:1400056 DHCP:SetDhcpConfig: set LeaseObtained 1ccb034 5bb0c080
    34812 PID:400002 TID:1400056 DHCP:SetDhcpConfig: T1 54600 T2 93a80 Lease a8c00
    34818 PID:400002 TID:1400056 +DHCP:AddrToString: FEA9
    34818 PID:400002 TID:1400056 -DHCP:AddrToString: 169.254.0.0
    34821 PID:400002 TID:1400056 +DHCP:AddrToString: FFFF
    34821 PID:400002 TID:1400056 -DHCP:AddrToString: 255.255.0.0
    34822 PID:400002 TID:1400056 -DHCP:SetDhcpConfig(VMINI1):
    34822 PID:400002 TID:1400056 +DHCP: DhcpSetIpRoute: Dhcp 0xDB7D0460 Dest 0 NextHop FE01A8C0
    34822 PID:400002 TID:1400056 -DHCP: DhcpSetIpRoute: Dhcp 0xDB7D0460 Status 0xC000022A
    34823 PID:400002 TID:1400056 -DHCP: DhcpSetGateways: Dhcp 0xDB7D0460 Status 0xC000022A
    34823 PID:400002 TID:1400056 DHCP: DhcpAddIPv4Address: Plumbing Addr 7801A8C0 subnet mask len 24
    34825 PID:400002 TID:1400056 DHCP: DhcpAddIPv4Address: waiting for addr change callback 10000 secs
    34914 PID:400002 TID:3840006 +DHCP: DhcpAddressChangeCallback: Notification Type 1 Address 7801A8C0 DadState 0
    49381 PID:400002 TID:1400056 DHCP: DhcpCheckDadAndUpdateAddress: Address not changed and Valid. Updating the other options
    49382 PID:400002 TID:1400056 -DHCP: DhcpCheckDadAndUpdateAddress: dwError 0
    49382 PID:400002 TID:1400056 DHCP: DhcpAddIPv4Address: successfully plumbed address 7801A8C0 (#1)
    49382 PID:400002 TID:1400056 *DHCP: DhcpRemoveIPAddrs: pDhcp DB7D0460 Except 7801A8C0
    49383 PID:400002 TID:1400056 DHCP: DhcpEnumIPv4Address: Skipping Addr 100007F
    49383 PID:400002 TID:1400056 -DHCP: DhcpEnumIPv4Address: cAddrs 1 Status 0x0
    49383 PID:400002 TID:1400056 DHCP:StartT1Timer: init LO 1ccb034 5bb0c080 , T1 1ccb359 557c080
    49384 PID:400002 TID:1400056 -DHCP:GetDhcpLease: pDhcp 0xDB7D0460 (VMINI1) Ret: 0
    49384 PID:400002 TID:1400056 *DHCP:CloseDhcpSocket pDhcp 0xDB7D0460 Sock 1
    49384 PID:400002 TID:1400056 DHCP:DhcpEventWorker: Processing DHCP_EVENT_MEDIA_CONNECT for VMINI1
    49384 PID:400002 TID:1400056 +DHCP:EventMediaConnect: pDhcp VMINI1
    49384 PID:400002 TID:1400056 DHCP:EventMediaConnect: Adapter VMINI1 not disconnected
    61515 PID:400002 TID:1400056 DHCP:EventMediaConnect: Adapter VMINI1 could ping gateway. Skipping DHCP netgotiation
    61515 PID:400002 TID:1400056 -DHCP:EventMediaConnect: pDhcp VMINI1 Status 0
    Thanks
    Stefano Bianconi
    Thursday, December 1, 2011 4:07 PM

All replies

  • I think that, yes, the October 2011 update could be related to what you see. The release notes infacts say 'If a DHCP conflict is detected within a Windows Embedded Compact 7 device with 2 adapters, the device may not send a DHCP Decline to re-start the DHCP assignment'

    In addition other developers have experienced strange DHCP issues after the update, issues which vanished reverting to the previous dhcp.dll (see for example http://social.msdn.microsoft.com/Forums/en-US/winembplatdev/thread/860c7b61-cfb8-45bd-b788-f4f2d4c57045)


    Luca Calligaris lucaDOTcalligarisATeurotechDOTcom www.eurotech.com Check my blog: http://lcalligaris.wordpress.com
    Monday, December 5, 2011 10:48 AM
  • Hey Stefano,

    Could you tell me how you got these debug messages? Is this a registry setting?

    Thank You

    Monday, August 12, 2013 2:33 PM
  • This issue got resolved in subsequent monthly updates. Please update the kit to latest you wont see this issue. 
    Tuesday, August 13, 2013 12:49 PM