MoveComputerToOU run's Twice

Jun 7, 2011 at 4:17 PM

Hello,

I recently configured the MDT Web Services and added it into our MDT Build and although it is successfully moving the Computer Objects to the specified OU, it seems to be attempting to move the computer twice.  On the second attempt it fails with the message: "Unexpected Response from the Web Service: 500 Internal Server Error.  There is no such object on the server."   I am assuming this is because it is looking at the original computer DN which has now changed from the successfull first attempt.  I have followed all the documentation I have found on this but I am either missing something or have messed something up. 

I have included my cs.ini the output from the BDD.Log and the MDT Web Services debug log below.

CS.ini

[Settings]
Priority=GetCompName, Default,MoveComputerToOU
Properties=MyCustomProperty, StagingOU

[GetCompName]
ComputerName=#strCompNewName#
UserExit=CompName.vbs

[MoveComputerToOU]
WebService=http://mdtwebserver.my.company.com/mdtwebservices/AD.asmx/MoveComputerToOU
Parameters=ComputerName,MachineObjectOU
MachineObjectOU=OUPath

[Default]
_SMSTSORGNAME=XP Image Deployment
OSInstall=YES
DriverGroup001=WinXPx86\%Model%
TaskSequenceID=XPPROD-GM01
MachineObjectOU=OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=Com
FinishAction=Reboot

SkipAdminPassword=YES
SkipAppsOnUpgrade=YES
SkipBDDWelcome=YES
SkipBitLocker=YES
SkipBitLockerDetails=YES
SkipTaskSequence=YES
SkipApplications=YES
SkipCapture=YES
SkipComputerBackup=YES
SkipComputerName=YES
SkipDeploymentType=YES
SkipDomainMembership=YES
SkipFinalSummary=YES
SkipLocaleSelection=YES
SkipPackageDisplay=YES
SkipProductKey=YES
SkipSummary=YES
SkipTimeZone=YES
SkipUserData=YES

KeyboardLocale=en-US
UILanguage=en-US
UserLocale=en-US
SkipTimeZone=Yes
TimeZoneName=Central Standard Time

 

From BDD.log

LTICopyScripts processing completed successfully. LTICopyScripts 6/7/2011 7:38:17 AM 0 (0x0000)
Property wsSection is now = MoveComputerToOU ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)
Microsoft Deployment Toolkit version: 5.1.1642.01 ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)
The task sequencer log is located at X:\windows\TEMP\SMSTSLog\SMSTS.LOG.  For task sequence failures, please consult this log. ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)
Using DEFAULT VALUE: Ini file = \\mdtwebserver\MDT_Image\Control\CustomSettings.ini ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)
CHECKING the [MoveComputerToOU] section ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)
About to execute web service call using method POST to http://mdtwebserver.my.company.com/mdtwebservices/AD.asmx/MoveComputerToOU: ComputerName=DXPD-36QFPM1&OUPath=OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=Com ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)
Unexpected response from web service: 500 Internal Server Error
There is no such object on the server.
ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)
Web service returned no data. ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)
Clear supplied parameters. ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)
Property wsSection is now =  ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)
Property wsIniFile is now =  ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)
Property wsProperty is now =  ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)
Property wsResultName is now =  ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)
Property wsStoreResult is now =  ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)
ZTI ERROR - Non-zero return code by ZTI_ExecuteWebservice, rc = 1 ZTI_ExecuteWebservice 6/7/2011 7:38:18 AM 0 (0x0000)


From MDT Web Services Debug Log

2011-06-07 09:37:18.3956|DEBUG|MaikKoster.Deployment.WebService.AD|Creating new instance of Active Directory webservice. Current version is 7.2.0.85
2011-06-07 09:37:18.3956|INFO|MaikKoster.Deployment.WebService.AD|Reading configuration
2011-06-07 09:37:18.3956|DEBUG|MaikKoster.Deployment.WebService.AD|Specified Domain is
2011-06-07 09:37:18.3956|DEBUG|MaikKoster.Deployment.WebService.AD|No Username configured. Using Application pool account
2011-06-07 09:37:18.3956|DEBUG|MaikKoster.Deployment.WebService.AD|Creating new AD Controller
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.AD.Controller|Created new controller Instance for Domain My.Company.com.
2011-06-07 09:37:18.4112|INFO|MaikKoster.Deployment.WebService.AD|Requested to move computer DXPD-36QFPM1 to OU OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=Com
2011-06-07 09:37:18.4112|INFO|MaikKoster.Deployment.WebService.AD|Getting local Domain controller of requester
2011-06-07 09:37:18.4112|INFO|MaikKoster.Deployment.WebService.AD|Requested local Domain Controller name
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.WebService.AD|Resolving User Host Address "10.108.101.108"
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.WebService.AD|Resolving to IPv4 Address
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.WebService.AD|Resolved to IPv4 Address "10.108.101.108"
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.AD.Controller|Get Active Directory Site for IP Address 10.108.101.108.
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.AD.Controller|Get Directory Entry for ObjectDN "LDAP://rootDSE".
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Directory Entry for object LDAP://rootDSE.
2011-06-07 09:37:18.4112|DEBUG|IP|Get all possible Subnets for IP Address 10.108.101.108.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP 10.108.101.108 to decimal value.
2011-06-07 09:37:18.4112|DEBUG|IP|Parsed IP Address 10.108.101.108 into Decimal value 174876012.
2011-06-07 09:37:18.4112|DEBUG|IP|Calculating Subnets. Mininum A Class.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 0 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 0 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 0 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 0 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 134217728 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 134217728 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 167772160 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 167772160 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 167772160 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.0.0.0/9 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 171966464 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.64.0.0/10 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174063616 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.96.0.0/11 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174063616 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.96.0.0/12 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174587904 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.104.0.0/13 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174850048 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.0.0/14 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174850048 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.0.0/15 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174850048 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.0.0/16 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174850048 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.0.0/17 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174866432 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.64.0/18 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174874624 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.96.0/19 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174874624 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.96.0/20 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174874624 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.96.0/21 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174875648 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.100.0/22 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174875648 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.100.0/23 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174875904 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.101.0/24 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174875904 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.101.0/25 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174875968 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.101.64/26 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174876000 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.101.96/27 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174876000 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.101.96/28 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174876008 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.101.104/29 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174876012 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.101.108/30 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174876012 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.101.108/31 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|IP|Convert IP value 174876012 to dotted representation.
2011-06-07 09:37:18.4112|DEBUG|IP|Added subnet 10.108.101.108/32 to list of possible subnets.
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.AD.Controller|ConfigurationNamingCOntext: CN=Configuration,DC=prod-rt,DC=local.
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.AD.Controller|Get Directory Entry for ObjectDN "CN=Configuration,DC=prod-rt,DC=local".
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Directory Entry for object CN=Configuration,DC=prod-rt,DC=local.
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.AD.Controller|Added LDAP:// to supplied Path "CN=Configuration,DC=prod-rt,DC=local".
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.AD.Controller|Executing query "(&(objectClass=subnet)(|(cn=10.0.0.0/9)(cn=10.64.0.0/10)(cn=10.96.0.0/11)(cn=10.96.0.0/12)(cn=10.104.0.0/13)(cn=10.108.0.0/14)(cn=10.108.0.0/15)(cn=10.108.0.0/16)(cn=10.108.0.0/17)(cn=10.108.64.0/18)(cn=10.108.96.0/19)(cn=10.108.96.0/20)(cn=10.108.96.0/21)(cn=10.108.100.0/22)(cn=10.108.100.0/23)(cn=10.108.101.0/24)(cn=10.108.101.0/25)(cn=10.108.101.64/26)(cn=10.108.101.96/27)(cn=10.108.101.96/28)(cn=10.108.101.104/29)(cn=10.108.101.108/30)(cn=10.108.101.108/31)(cn=10.108.101.108/32)))" on configuration container CN=Configuration.
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.AD.Controller|Found Subnet 10.108.0.0/16.
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.AD.Controller|Found additional subnets. Catch all subnets configured. Getting best match.
2011-06-07 09:37:18.4112|DEBUG|MaikKoster.Deployment.AD.Controller|Current Best Match = 10.108.0.0/16. Compare to new Subnet 10.108.101.0/24.
2011-06-07 09:37:18.4268|DEBUG|MaikKoster.Deployment.AD.Controller|Found closer match. Using Subnet 10.108.101.0/24 now.
2011-06-07 09:37:18.4268|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Site based on siteObject property of selected subnet.
2011-06-07 09:37:18.4268|DEBUG|MaikKoster.Deployment.AD.Controller|Get Directory Entry for ObjectDN "CN=OCP,CN=Sites,CN=Configuration,DC=prod-rt,DC=local".
2011-06-07 09:37:18.4268|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Directory Entry for object CN=OCP,CN=Sites,CN=Configuration,DC=prod-rt,DC=local.
2011-06-07 09:37:18.4268|DEBUG|MaikKoster.Deployment.AD.Controller|Added LDAP:// to supplied Path "CN=OCP,CN=Sites,CN=Configuration,DC=prod-rt,DC=local".
2011-06-07 09:37:18.4268|DEBUG|MaikKoster.Deployment.AD.Controller|Found site OCP.
2011-06-07 09:37:18.4268|INFO|MaikKoster.Deployment.WebService.AD|Found site code OCP for IP Address 10.108.101.108. Getting Domain Controller for site.
2011-06-07 09:37:18.4268|DEBUG|MaikKoster.Deployment.AD.Controller|Get Domain Controler for Site OCP.
2011-06-07 09:37:18.4268|DEBUG|MaikKoster.Deployment.AD.Controller|Get current Forest
2011-06-07 09:37:18.4268|DEBUG|MaikKoster.Deployment.AD.Controller|Found Forest prod-rt.local for Domain My.Company.com.
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Found site OCP. Get Domain Controller.
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Found Domain Controller mdtserver.My.Company.com for site OCP.
2011-06-07 09:37:18.4737|INFO|MaikKoster.Deployment.WebService.AD|Returning Domain Controller mdtserver.My.Company.com
2011-06-07 09:37:18.4737|INFO|MaikKoster.Deployment.WebService.AD|Getting computer from Active Directory
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Get distinguished name for Computer "DXPD-36QFPM1" on Server "
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Get Directory Entry for ObjectDN "".
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|No ObjectDN supplied. Using Domain My.Company.com.
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Directory Entry for object .
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Added LDAP:// to supplied Path "My.Company.com".
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Preparing AD Search.
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Search Root is "LDAP://My.Company.com".
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Executing query "(&(objectClass=computer)(|(cn=DXPD-36QFPM1)(distinguishedName=DXPD-36QFPM1)(sAMAccountName=DXPD-36QFPM1$)))".
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Found object at path LDAP://My.Company.com/CN=DXPD-36QFPM1,OU=Windows XP Desktops,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com. Get Directory Entry.
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Found distinguished name "CN=DXPD-36QFPM1,OU=Windows XP Desktops,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com".
2011-06-07 09:37:18.4737|INFO|MaikKoster.Deployment.WebService.AD|Getting OU from Active Directory
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Get distinguished name for organizationalUnit "OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=Com" on Server "
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Get Directory Entry for ObjectDN "".
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|No ObjectDN supplied. Using Domain My.Company.com.
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Directory Entry for object .
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Added LDAP:// to supplied Path "My.Company.com".
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Preparing AD Search.
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Search Root is "LDAP://My.Company.com".
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Executing query "(&(objectClass=organizationalUnit)(distinguishedName=OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=Com))".
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Found object at path LDAP://My.Company.com/OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com. Get Directory Entry.
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Found distinguished name "OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com".
2011-06-07 09:37:18.4737|INFO|MaikKoster.Deployment.WebService.AD|Moving computer on Domain Controller mdtserver.My.Company.com
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Move Active Directory object "mdtserver.My.Company.com/CN=DXPD-36QFPM1,OU=Windows XP Desktops,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com" to "mdtserver.My.Company.com/OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com".
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Get Directory Entry for ObjectDN "mdtserver.My.Company.com/CN=DXPD-36QFPM1,OU=Windows XP Desktops,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com".
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Directory Entry for object mdtserver.My.Company.com/CN=DXPD-36QFPM1,OU=Windows XP Desktops,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com.
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Added LDAP:// to supplied Path "mdtserver.My.Company.com/CN=DXPD-36QFPM1,OU=Windows XP Desktops,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com".
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Get Directory Entry for ObjectDN "mdtserver.My.Company.com/OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com".
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Directory Entry for object mdtserver.My.Company.com/OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com.
2011-06-07 09:37:18.4737|DEBUG|MaikKoster.Deployment.AD.Controller|Added LDAP:// to supplied Path "mdtserver.My.Company.com/OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com".
2011-06-07 09:37:18.5675|DEBUG|MaikKoster.Deployment.AD.Controller|Moving object "CN=DXPD-36QFPM1" from "LDAP://mdtserver.My.Company.com/OU=Windows XP Desktops,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com" to "LDAP://mdtserver.My.Company.com/OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com"
2011-06-07 09:37:18.6768|DEBUG|MaikKoster.Deployment.AD.Controller|Moved object "CN=DXPD-36QFPM1" to Destination "LDAP://mdtserver.My.Company.com/OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com"
2011-06-07 09:37:18.6768|INFO|MaikKoster.Deployment.WebService.AD|successfuly moved computer DXPD-36QFPM1 to new OU OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=Com. Returning "True"
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.WebService.AD|Creating new instance of Active Directory webservice. Current version is 7.2.0.85
2011-06-07 09:38:09.8809|INFO|MaikKoster.Deployment.WebService.AD|Reading configuration
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.WebService.AD|Specified Domain is
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.WebService.AD|No Username configured. Using Application pool account
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.WebService.AD|Creating new AD Controller
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.AD.Controller|Created new controller Instance for Domain My.Company.com.
2011-06-07 09:38:09.8809|INFO|MaikKoster.Deployment.WebService.AD|Requested to move computer DXPD-36QFPM1 to OU OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=Com
2011-06-07 09:38:09.8809|INFO|MaikKoster.Deployment.WebService.AD|Getting local Domain controller of requester
2011-06-07 09:38:09.8809|INFO|MaikKoster.Deployment.WebService.AD|Requested local Domain Controller name
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.WebService.AD|Resolving User Host Address "10.108.101.108"
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.WebService.AD|Resolving to IPv4 Address
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.WebService.AD|Resolved to IPv4 Address "10.108.101.108"
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.AD.Controller|Get Active Directory Site for IP Address 10.108.101.108.
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.AD.Controller|Get Directory Entry for ObjectDN "LDAP://rootDSE".
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Directory Entry for object LDAP://rootDSE.
2011-06-07 09:38:09.8809|DEBUG|IP|Get all possible Subnets for IP Address 10.108.101.108.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP 10.108.101.108 to decimal value.
2011-06-07 09:38:09.8809|DEBUG|IP|Parsed IP Address 10.108.101.108 into Decimal value 174876012.
2011-06-07 09:38:09.8809|DEBUG|IP|Calculating Subnets. Mininum A Class.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 0 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 0 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 0 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 0 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 134217728 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 134217728 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 167772160 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 167772160 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 167772160 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.0.0.0/9 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 171966464 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.64.0.0/10 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174063616 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.96.0.0/11 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174063616 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.96.0.0/12 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174587904 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.104.0.0/13 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174850048 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.0.0/14 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174850048 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.0.0/15 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174850048 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.0.0/16 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174850048 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.0.0/17 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174866432 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.64.0/18 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174874624 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.96.0/19 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174874624 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.96.0/20 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174874624 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.96.0/21 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174875648 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.100.0/22 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174875648 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.100.0/23 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174875904 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.101.0/24 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174875904 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.101.0/25 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174875968 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.101.64/26 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174876000 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.101.96/27 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174876000 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.101.96/28 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174876008 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.101.104/29 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174876012 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.101.108/30 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174876012 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.101.108/31 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|IP|Convert IP value 174876012 to dotted representation.
2011-06-07 09:38:09.8809|DEBUG|IP|Added subnet 10.108.101.108/32 to list of possible subnets.
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.AD.Controller|ConfigurationNamingCOntext: CN=Configuration,DC=prod-rt,DC=local.
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.AD.Controller|Get Directory Entry for ObjectDN "CN=Configuration,DC=prod-rt,DC=local".
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Directory Entry for object CN=Configuration,DC=prod-rt,DC=local.
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.AD.Controller|Added LDAP:// to supplied Path "CN=Configuration,DC=prod-rt,DC=local".
2011-06-07 09:38:09.8809|DEBUG|MaikKoster.Deployment.AD.Controller|Executing query "(&(objectClass=subnet)(|(cn=10.0.0.0/9)(cn=10.64.0.0/10)(cn=10.96.0.0/11)(cn=10.96.0.0/12)(cn=10.104.0.0/13)(cn=10.108.0.0/14)(cn=10.108.0.0/15)(cn=10.108.0.0/16)(cn=10.108.0.0/17)(cn=10.108.64.0/18)(cn=10.108.96.0/19)(cn=10.108.96.0/20)(cn=10.108.96.0/21)(cn=10.108.100.0/22)(cn=10.108.100.0/23)(cn=10.108.101.0/24)(cn=10.108.101.0/25)(cn=10.108.101.64/26)(cn=10.108.101.96/27)(cn=10.108.101.96/28)(cn=10.108.101.104/29)(cn=10.108.101.108/30)(cn=10.108.101.108/31)(cn=10.108.101.108/32)))" on configuration container CN=Configuration.
2011-06-07 09:38:09.8966|DEBUG|MaikKoster.Deployment.AD.Controller|Found Subnet 10.108.0.0/16.
2011-06-07 09:38:09.8966|DEBUG|MaikKoster.Deployment.AD.Controller|Found additional subnets. Catch all subnets configured. Getting best match.
2011-06-07 09:38:09.8966|DEBUG|MaikKoster.Deployment.AD.Controller|Current Best Match = 10.108.0.0/16. Compare to new Subnet 10.108.101.0/24.
2011-06-07 09:38:09.8966|DEBUG|MaikKoster.Deployment.AD.Controller|Found closer match. Using Subnet 10.108.101.0/24 now.
2011-06-07 09:38:09.8966|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Site based on siteObject property of selected subnet.
2011-06-07 09:38:09.8966|DEBUG|MaikKoster.Deployment.AD.Controller|Get Directory Entry for ObjectDN "CN=OCP,CN=Sites,CN=Configuration,DC=prod-rt,DC=local".
2011-06-07 09:38:09.8966|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Directory Entry for object CN=OCP,CN=Sites,CN=Configuration,DC=prod-rt,DC=local.
2011-06-07 09:38:09.8966|DEBUG|MaikKoster.Deployment.AD.Controller|Added LDAP:// to supplied Path "CN=OCP,CN=Sites,CN=Configuration,DC=prod-rt,DC=local".
2011-06-07 09:38:09.8966|DEBUG|MaikKoster.Deployment.AD.Controller|Found site OCP.
2011-06-07 09:38:09.8966|INFO|MaikKoster.Deployment.WebService.AD|Found site code OCP for IP Address 10.108.101.108. Getting Domain Controller for site.
2011-06-07 09:38:09.8966|DEBUG|MaikKoster.Deployment.AD.Controller|Get Domain Controler for Site OCP.
2011-06-07 09:38:09.8966|DEBUG|MaikKoster.Deployment.AD.Controller|Get current Forest
2011-06-07 09:38:09.8966|DEBUG|MaikKoster.Deployment.AD.Controller|Found Forest prod-rt.local for Domain My.Company.com.
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Found site OCP. Get Domain Controller.
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Found Domain Controller mdtserver.My.Company.com for site OCP.
2011-06-07 09:38:09.9434|INFO|MaikKoster.Deployment.WebService.AD|Returning Domain Controller mdtserver.My.Company.com
2011-06-07 09:38:09.9434|INFO|MaikKoster.Deployment.WebService.AD|Getting computer from Active Directory
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Get distinguished name for Computer "DXPD-36QFPM1" on Server "
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Get Directory Entry for ObjectDN "".
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|No ObjectDN supplied. Using Domain My.Company.com.
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Directory Entry for object .
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Added LDAP:// to supplied Path "My.Company.com".
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Preparing AD Search.
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Search Root is "LDAP://My.Company.com".
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Executing query "(&(objectClass=computer)(|(cn=DXPD-36QFPM1)(distinguishedName=DXPD-36QFPM1)(sAMAccountName=DXPD-36QFPM1$)))".
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Found object at path LDAP://My.Company.com/CN=DXPD-36QFPM1,OU=Windows XP Desktops,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com. Get Directory Entry.
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Found distinguished name "CN=DXPD-36QFPM1,OU=Windows XP Desktops,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com".
2011-06-07 09:38:09.9434|INFO|MaikKoster.Deployment.WebService.AD|Getting OU from Active Directory
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Get distinguished name for organizationalUnit "OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=Com" on Server "
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Get Directory Entry for ObjectDN "".
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|No ObjectDN supplied. Using Domain My.Company.com.
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Directory Entry for object .
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Added LDAP:// to supplied Path "My.Company.com".
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Preparing AD Search.
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Search Root is "LDAP://My.Company.com".
2011-06-07 09:38:09.9434|DEBUG|MaikKoster.Deployment.AD.Controller|Executing query "(&(objectClass=organizationalUnit)(distinguishedName=OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=Com))".
2011-06-07 09:38:09.9591|DEBUG|MaikKoster.Deployment.AD.Controller|Found object at path LDAP://My.Company.com/OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com. Get Directory Entry.
2011-06-07 09:38:09.9591|DEBUG|MaikKoster.Deployment.AD.Controller|Found distinguished name "OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com".
2011-06-07 09:38:09.9591|INFO|MaikKoster.Deployment.WebService.AD|Moving computer on Domain Controller mdtserver.My.Company.com
2011-06-07 09:38:09.9591|DEBUG|MaikKoster.Deployment.AD.Controller|Move Active Directory object "mdtserver.My.Company.com/CN=DXPD-36QFPM1,OU=Windows XP Desktops,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com" to "mdtserver.My.Company.com/OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com".
2011-06-07 09:38:09.9591|DEBUG|MaikKoster.Deployment.AD.Controller|Get Directory Entry for ObjectDN "mdtserver.My.Company.com/CN=DXPD-36QFPM1,OU=Windows XP Desktops,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com".
2011-06-07 09:38:09.9591|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Directory Entry for object mdtserver.My.Company.com/CN=DXPD-36QFPM1,OU=Windows XP Desktops,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com.
2011-06-07 09:38:09.9591|DEBUG|MaikKoster.Deployment.AD.Controller|Added LDAP:// to supplied Path "mdtserver.My.Company.com/CN=DXPD-36QFPM1,OU=Windows XP Desktops,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com".
2011-06-07 09:38:09.9591|DEBUG|MaikKoster.Deployment.AD.Controller|Get Directory Entry for ObjectDN "mdtserver.My.Company.com/OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com".
2011-06-07 09:38:09.9591|DEBUG|MaikKoster.Deployment.AD.Controller|Getting Directory Entry for object mdtserver.My.Company.com/OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com.
2011-06-07 09:38:09.9591|DEBUG|MaikKoster.Deployment.AD.Controller|Added LDAP:// to supplied Path "mdtserver.My.Company.com/OU=Image Staging,OU=Managed,OU=Workstations,DC=My,DC=Company,DC=com".

Jun 14, 2011 at 8:19 AM

You have configured the Gather step to execute MoveComputerToOU via the priority entry. (Priority defines what sections shall be called in what order by the Gather step). This happens at the very beginning of your Task Sequence. So you move the computer account there, if it exists already. Looking at the log, you are calling ZTIExecuteWebservice.wsf to execute this a second time just two minutes later. You should do either or.

Calling this several times makes sense if moving the computer to a staging ou first (as it seems based on the OU name) and then to the target OU at the end of the process. But this typically happens at the end of the task sequence, so 15-20 minutes later which gives replication enough time to distribute this information troughout the DCs. The log doesn't show an error, at least I can't see any.