PXE-T04/PXE-E36/PXE-M0F suddenly with Windows Deployment Services/MDT
Hi guys, I've been having a strange issue with my WDS/MDT setup on Windows Server 2008 R2. We deployed WDS/MDT combo on WS08 R2 a while ago. The WDS is on a different subnet (192.168.20.x), apart from the user subnet (10.148.160.x). All of this has known a clean setup (the wds/mdt server). So we have a DNS/DHCP server with ip 10.148.160.6, which has a domain forwarder in dns to a second separate domain. On the 10.148.160.x dc we included dhcp option 66 and 67 to refer to the actual wds server on the other domain. The strange thing here? This setup worked until a few days ago to deploy os'es (we deployed successfully 3 Windows 7's without any probs)... as usual nothing has changed configuration-wise (or it should have been documented)... Up until now we did not change the current config and setup, primarely because it worked. Everything we have tried, we rolled back by undoing the change if it did not work (as trying to change backward slash to forward slash for example). Now we are getting the infamous PXE-T04 which has stalked the web before as we found out. All previous resolves relating to this particular issue, did not resolve ours. I'll enabled advanced tracing on the wds, so i'll include the logs... the first error appears nearly at the end with a (more errors follow this one in the included log underneath) : [d:\w7rtm\base\ntsetup\opktools\wds\transport\server\tftp\tftpsession.cpp:564] Expression: , Win32 Error=0x5 (This path must be created by the wds itself, as this does not relate in any way to an internal path to wim files or any other setup files we created). Any help would be welcome guys, as this one seems to burden our future deployment of Win 7. With kind regards, Sven The actual log: [2648] 10:04:39: ===>Starting logging of module [WDSServer] [2648] 10:04:42: [MADCAP] Initialized. [2648] 10:04:42: [UDPPorts] Policy: 1, Dynamic Port Range: 64001-65000. [2648] 10:04:42: [RPC] Using Tcp Port 5040 for Rpc Calls. [2648] 10:04:42: [RPC] Client Impersonation Logging=Disabled [2648] 10:04:42: [RPC] Host Name: GMMDTVM001.gmmeise.local [2648] 10:04:42: [RPC] NTLM/Kerberos Spn: ldap/GMMDTVM001.gmmeise.local [2648] 10:04:42: [RPC] Initialized [3256] 10:04:42: [RPC] Server Started. [2648] 10:04:42: [BINLSVC][RPC][Ep={5F4FB9F0-C0E3-41C1-AA00-9A7C690AC3A3}] Registered [2648] 10:04:42: [BINLSVC] Provider Initialized. [2648] 10:04:42: [WDSDDPS][RPC][Ep={FA0A27E1-C2BA-4B3B-94B2-025E82FFAA6D}] Registered [2648] 10:04:42: [WDSDDPS][RPC][Ep={011D24AC-CB3A-4739-A339-5D2E1B5306CE}] Registered [2648] 10:04:42: [WDSDDPS] Provider Initialized. [2648] 10:04:42: [3656][WdsImgSrv] -> WdsProviderInitialize [2648] 10:04:42: [3656][WdsImgSrv] GetDSSServer: Domain= [2648] 10:04:42: [3656][WdsImgSrv] GetDSServer: Ldap Server=GMDCVM002.gmmeise.local [2648] 10:04:42: [3656][WdsImgSrv] CLdap::Open: Server=GMDCVM002.gmmeise.local, Port=389, DN= [2648] 10:04:42: [3656][WdsImgSrv] GetDSSServer: Domain=gmmeise.local [2648] 10:04:42: [3656][WdsImgSrv] GetDSServer: Ldap Server=GMDCVM002.gmmeise.local [2648] 10:04:42: [3656][WdsImgSrv] CLdap::Open: Server=GMDCVM002.gmmeise.local, Port=389, DN= [2648] 10:04:42: [3656][WdsImgSrv] -> pInitializeManagement [2648] 10:04:42: [3687][WdsImgSrv] <- pInitializeManagement=0 [2648] 10:04:42: [3687][WdsImgSrv] -> pWdsImgSrvRefreshSettings [2648] 10:04:42: [3702][WdsImgSrv] <- pWdsImgSrvRefreshSettings=0 [2648] 10:04:42: [3702][WdsImgSrv] Image Cache: Refreshing... [2648] 10:04:42: [3702][WdsImgSrv] Image Cache: Total 0 Images Found. [2648] 10:04:42: [WdsImgSrv][RPC][Ep={D8DEEB5A-EFFD-43B2-99FC-1A8A5921C227}] Registered [2648] 10:04:42: [3702][WdsImgSrv] <- WdsProviderInitialize=0 [2648] 10:04:42: [WdsImgSrv] Provider Initialized. [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Exp Window Size=8 packets. [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Max Window Size=64 packets. [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Max Repairs=2 [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Client Inactive Timeout=60000ms [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Client Keep Alive Interval=15000ms [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Kick Interval=15000ms [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Socket Send Buffer Size=16384 bytes [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Socket Recv Buffer Size=16384 bytes [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Multicast TTL=32 [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Open Window Interval=40ms [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Open Cache Interval=20ms [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Close Cache Interval=600ms [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Security Mode=65537 [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: RSA Key Length=2048 [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Hash Algorithm=32780 [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: HMAC Algorithm=32777 [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: HMAC Symmetric Key Algorithm=26115 [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: HMAC Symmetric Key Length=0 [2648] 10:04:42: [3718][WDSMC] Profile[Default] Server: Maximum Bandwidth=100% [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Max Bandwidth Per Session Group=0 [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Slow Client Handling Policy=3 [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Slow Client Fallback=1 [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Slow Client Wait Time=5000 ms [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Total Streams=3 [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Multistream Demote Interval=500 ms [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Low Resources Monitor=0 [2648] 10:04:42: [3718][WDSMC] Profile[Default] Application: Block Size=8785 bytes [2648] 10:04:42: [3718][WDSMC] Profile[Default] Application: Boot Block Size=1024 bytes [2648] 10:04:42: [3718][WDSMC] Profile[Default] Application: Inactive Session Timeout=300000ms [2648] 10:04:42: [3718][WDSMC] Profile[Default] Application: Inactive Namespace Timeout=1800000ms [2648] 10:04:42: [3718][WDSMC] Profile[Default] Application: CRR Merge Interval=30000ms [2648] 10:04:42: [3718][WDSMC] Profile[Default] Application: Force CIR Interval=60000ms [2648] 10:04:42: [3718][WDSMC] Profile[Default] Server: Allow Boot Clients=1 [2648] 10:04:42: [3718][WDSMC] Profile[Default] Transport: Force IPv4=0 [2648] 10:04:42: [3718][WDSMC] [Bandwidth] Maximum 100% bandwidth usage allowed. [2648] 10:04:43: [3874][WDSMC] Content Provider: Name=WDS, DLL=C:\Windows\system32\wdscp.dll, InitRoutine=WdsTransportProviderInit [2648] 10:04:43: [3874][WDSMC] Content Provider 'WDS' Initialized. [2648] 10:04:43: [3874][WDSMC] Content Provider: Name=WDSBOOT, DLL=C:\Windows\system32\wdsbcp.dll, InitRoutine=WdsTransportProviderInitialize [2648] 10:04:43: [3874][WDSMC] Content Provider 'WDSBOOT' Initialized. [2648] 10:04:43: [3874][WDSMC] [Bandwidth] Maximum 100% bandwidth usage allowed. [2648] 10:04:43: [WDSMC][RPC][Ep={865BCE30-ACF1-410C-B5ED-142096663914}] Registered [2648] 10:04:43: [WDSMC][RPC][Ep={6F13A317-3687-4B54-81A5-504DAA9062FA}] Registered [2648] 10:04:43: [WDSMC][UDP][Ep=5041] Registered [2648] 10:04:43: UpdEndpoint::UdpEndpoint = 0000000001467160 [2648] 10:04:43: [WDSMC][UDP][Ep=192.168.20.15:5041][0x0000000001467160] Created [2648] 10:04:43: UpdEndpoint::UdpEndpoint = 00000000017D74A0 [2648] 10:04:43: [WDSMC][UDP][Ep=::1:5041][0x00000000017D74A0] Created [2648] 10:04:43: [WDSMC] Provider Initialized. [2648] 10:04:43: [3874][WDSPXE] -> CBannedGuids::Initialize [2648] 10:04:43: [3874][WDSPXE] -> CBannedGuids::Shutdown [2648] 10:04:43: [3874][WDSPXE] <- CBannedGuids::Shutdown=0 [2648] 10:04:43: [3874][WDSPXE] <- CBannedGuids::Initialize=0 [2648] 10:04:43: [3874][WDSPXE] Initializing BINLSVC Provider using 'C:\Windows\system32\binlsvc.dll'. [2648] 10:04:43: [3874][WDSPXE] [BINLSVC] GetDSSServer: Domain= [2648] 10:04:43: [3874][WDSPXE] [BINLSVC] GetDSServer: Ldap Server=GMDCVM002.gmmeise.local [2648] 10:04:43: [3874][WDSPXE] [BINLSVC] CLdap::Open: Server=GMDCVM002.gmmeise.local, Port=389, DN= [2648] 10:04:43: [3890][WDSPXE] [BINLSVC] GetDSSServer: Domain=gmmeise.local [2648] 10:04:43: [3890][WDSPXE] [BINLSVC] GetDSServer: Ldap Server=GMDCVM002.gmmeise.local [2648] 10:04:43: [3890][WDSPXE] [BINLSVC] CLdap::Open: Server=GMDCVM002.gmmeise.local, Port=389, DN= [2648] 10:04:44: [4968][WDSPXE] BINLSVC Provider initialized successfully. [2648] 10:04:44: [4968][WDSPXE] Bind Policy: Exclude Registered [2648] 10:04:44: [WDSPXE][UDP][Ep=67] Registered [2648] 10:04:44: UpdEndpoint::UdpEndpoint = 000000000164A5A0 [2648] 10:04:44: [WDSPXE][UDP][Ep=192.168.20.15:67][0x000000000164A5A0] Created [2648] 10:04:44: [WDSPXE][UDP][Ep=4011] Registered [2648] 10:04:44: UpdEndpoint::UdpEndpoint = 000000000174C860 [2648] 10:04:44: [WDSPXE][UDP][Ep=192.168.20.15:4011][0x000000000174C860] Created [2648] 10:04:44: [4968][WDSPXE] Rogue Detection: Auth Recheck Time=3600 [2648] 10:04:44: [4968][WDSPXE] Rogue Detection: Auth Failure Retry Time=30 [2648] 10:04:44: [4968][WDSPXE] Rogue Detection: Disable Rogue Detection=Yes [2648] 10:04:44: [4968][WDSPXE] Rogue Detection: Authorization State Changed. Old=Not Authorized, New=Authorized [2648] 10:04:44: [WDSPXE] Provider Initialized. [2452] 10:04:44: [4968][WDSPXE] Rogue Detection: Wait=0 [2452] 10:04:44: [4968][WDSPXE] Rogue Detection: Server Role=NT5 Domain [2452] 10:04:44: [4968][WDSPXE] Rogue Detection: Server Domain=gmmeise.local [2452] 10:04:44: [4968][WDSPXE] Rogue Detection: Wait=3600 [2648] 10:04:44: [4968][WDSTFTP] CTftpServer::ReadParameters: Root Folder=D:\RemoteInstall [2648] 10:04:44: [4968][WDSTFTP] CTftpServer::ReadParameters: File Cache Size=524288 Bytes [2648] 10:04:44: [4968][WDSTFTP] CTftpServer::ReadParameters: Socket Send Buffer Size=0 KB [2648] 10:04:44: [4968][WDSTFTP] CFolderFilter::ReadParameters: Read Filter[0] = '\boot\*' [2648] 10:04:44: [4968][WDSTFTP] CFolderFilter::ReadParameters: Read Filter[1] = '\tmp\*' [2648] 10:04:44: [4968][WDSTFTP] CFolderFilter::ReadParameters: Read Filter[2] = 'boot\*' [2648] 10:04:44: [4968][WDSTFTP] CFolderFilter::ReadParameters: Read Filter[3] = 'tmp\*' [2648] 10:04:44: [4968][WDSTFTP] CFolderFilter::ReadParameters: Read Filter[4] = '/boot/*' [2648] 10:04:44: [WDSTFTP][UDP][Ep=69] Registered [2648] 10:04:44: UpdEndpoint::UdpEndpoint = 00000000013BE760 [2648] 10:04:44: [WDSTFTP][UDP][Ep=192.168.20.15:69][0x00000000013BE760] Created [2648] 10:04:44: UpdEndpoint::UdpEndpoint = 000000000164FFA0 [2648] 10:04:44: [WDSTFTP][UDP][Ep=::1:69][0x000000000164FFA0] Created [2648] 10:04:44: [WDSTFTP] Provider Initialized. [2648] 10:04:44: Dynamic Update Timer: 900000 ms [2648] 10:04:44: WDS Server started successfully. [1060] 10:05:02: [23311][WDSTFTP] -> CClientContext::FindSession [1060] 10:05:02: [23311][WDSTFTP] <- CClientContext::FindSession=2 [1060] 10:05:02: [UDPPorts] Allocated dynamic port 64001. [1060] 10:05:02: Dynamic Port: 64001 [1060] 10:05:02: [WDSTFTP][UDP][Ep=64001] Registered [1060] 10:05:02: UpdEndpoint::UdpEndpoint = 0000000001551FA0 [1060] 10:05:02: [WDSTFTP][UDP][Ep=192.168.20.15:64001][0x0000000001551FA0] Created [1060] 10:05:02: [23311][WDSTFTP] TftpSession[0x0000000001473620:10.148.160.127:2070] - Initialized: Local=192.168.20.15:69 [1060] 10:05:02: [23311][WDSTFTP] TFTPParse[Request]: OpCode=1, File=gmmdtvm001.gmmeise.local\REMINST\Boot\x86\wdsnbp.com, Mode=1, BlkSize=512, WinSize=1, Timeout=2, TSize=0 [1060] 10:05:02: [23311][WDSTFTP] [d:\w7rtm\base\ntsetup\opktools\wds\transport\server\tftp\tftpsession.cpp:564] Expression: , Win32 Error=0x5 [1060] 10:05:02: [23311][WDSTFTP] [d:\w7rtm\base\ntsetup\opktools\wds\transport\server\tftp\tftpsession.cpp:612] Expression: , Win32 Error=0x5 [1060] 10:05:02: [23311][WDSTFTP] TFTPConstruct[ERROR]: Code=4(0x4), Desc=`Access violation.` [1060] 10:05:02: [23311][WDSTFTP] [d:\w7rtm\base\ntsetup\opktools\wds\transport\server\tftp\tftpserver.cpp:594] Expression: , Win32 Error=0x5 [1356] 10:05:02: [23311][WDSTFTP] -> CClientContext::FindSession [1356] 10:05:02: [23311][WDSTFTP] <- CClientContext::FindSession=2 [1356] 10:05:02: [UDPPorts] Allocated dynamic port 64362. [1356] 10:05:02: Dynamic Port: 64362 [1356] 10:05:02: [WDSTFTP][UDP][Ep=64362] Registered [1356] 10:05:02: UpdEndpoint::UdpEndpoint = 0000000001754CE0 [1356] 10:05:02: [WDSTFTP][UDP][Ep=192.168.20.15:64362][0x0000000001754CE0] Created [1356] 10:05:02: [23311][WDSTFTP] TftpSession[0x00000000016544A0:10.148.160.127:2071] - Initialized: Local=192.168.20.15:69 [1356] 10:05:02: [23311][WDSTFTP] TFTPParse[Request]: OpCode=1, File=gmmdtvm001.gmmeise.local\REMINST\Boot\x86\wdsnbp.com, Mode=1, BlkSize=1456, WinSize=1, Timeout=2, TSize=0 [1356] 10:05:02: [23311][WDSTFTP] [d:\w7rtm\base\ntsetup\opktools\wds\transport\server\tftp\tftpsession.cpp:564] Expression: , Win32 Error=0x5 [1356] 10:05:02: [23311][WDSTFTP] [d:\w7rtm\base\ntsetup\opktools\wds\transport\server\tftp\tftpsession.cpp:612] Expression: , Win32 Error=0x5 [1356] 10:05:02: [23311][WDSTFTP] TFTPConstruct[ERROR]: Code=4(0x4), Desc=`Access violation.` [1356] 10:05:02: [23311][WDSTFTP] [d:\w7rtm\base\ntsetup\opktools\wds\transport\server\tftp\tftpserver.cpp:594] Expression: , Win32 Error=0x5 [2944] 10:05:02: [WDSTFTP][UDP][Ep=192.168.20.15:64001] Deleted. [2944] 10:05:02: [23326][WDSTFTP] TftpSession[0x0000000001473620:10.148.160.127:2070] - Endpoint Removed. Killing session. [2944] 10:05:02: UpdEndpoint::~UdpEndpoint = 0000000001551FA0 [2944] 10:05:02: [WDSTFTP][UDP][Ep=0] Closed [2944] 10:05:02: [UDPPorts] Dynamic Port 64001 freed. [2944] 10:05:02: [23326][WDSTFTP] TftpSession[0x0000000001473620:10.148.160.127:2070] - Shutdown [2944] 10:05:02: [23326][WDSTFTP] TftpSession[(null)] - Shutdown [2944] 10:05:02: [WDSTFTP][UDP][Ep=1er92.168.20.15:64362] Deleted. [2944] 10:05:02: [23326][WDSTFTP] TftpSession[0x00000000016544A0:10.148.160.127:2071] - Endpoint Removed. Killing session. [2944] 10:05:02: UpdEndpoint::~UdpEndpoint = 0000000001754CE0 [2944] 10:05:02: [WDSTFTP][UDP][Ep=0] Closed [2944] 10:05:02: [UDPPorts] Dynamic Port 64362 freed. [2944] 10:05:02: [23326][WDSTFTP] TftpSession[0x00000000016544A0:10.148.160.127:2071] - Shutdown [2944] 10:05:02: [23326][WDSTFTP] TftpSession[(null)] - Shutdown
June 8th, 2010 11:45am

Hi, As this issue is related to MDT, I suggest discussing it in our MDT forum: http://social.technet.microsoft.com/Forums/en/mdt/threads Tim Quan - MSFT
Free Windows Admin Tool Kit Click here and download it now
June 9th, 2010 9:17am

This topic is archived. No further replies will be accepted.

Other recent topics Other recent topics