Recently we are running into problem with our advertisement. In the windows\system32\ccm\log\execmgr.log file the package is been reinstalled once a day. In the past we never had this problem. According to the log the program did install successfully but it starts again. It does not happen with all the systems. Any one had similar experience. I already spent long hours looking into this and still no answers...
here is part of the log.
--------------------------------------------
A duplicate execution request is found for program Microsoft Update - KB958644 Patch execmgr 11/11/2008 9:17:00 PM 924 (0x039C)
Policy is updated for Program: Microsoft Update - KB958644 Patch, Package: S0100094, Advert: S0120189 execmgr 11/12/2008 11:28:33 AM 576 (0x0240)
Mandatory program Microsoft Update - KB958644 Patch is now enabled execmgr 11/12/2008 11:28:33 AM 576 (0x0240)
Creating mandatory request for advert S0120189, program Microsoft Update - KB958644 Patch, package S0100094 execmgr 11/12/2008 11:28:33 AM 576 (0x0240)
Requesting content from CAS for package S0100094 version 7 execmgr 11/12/2008 11:28:33 AM 576 (0x0240)
Raising event:
[SMS_CodePage(437), SMS_LocaleID(1033)]
instance of SoftDistWaitingContentEvent
{
AdvertisementId = "S0120189";
ClientID = "GUID:69C4DEB4-DAF9-4B1B-9AED-400BA30C6C1F";
DateTime = "20081112162833.682000+000";
MachineName = "PC4262";
PackageName = "S0100094";
PackageVersion = "7";
ProcessID = 568;
ProgramName = "Microsoft Update - KB958644 Patch";
SiteCode = "S01";
ThreadID = 576;
};
execmgr 11/12/2008 11:28:33 AM 576 (0x0240)
Successfully raised SoftDistWaitingContentEvent event for program Microsoft Update - KB958644 Patch execmgr 11/12/2008 11:28:33 AM 576 (0x0240)
Execution Request for program Microsoft Update - KB958644 Patch state change from NotExist to WaitingContent execmgr 11/12/2008 11:28:33 AM 576 (0x0240)
Policy arrived for parent program Microsoft Update - KB958644 Patch execmgr 11/12/2008 11:28:33 AM 2308 (0x0904)
Raising event:
[SMS_CodePage(437), SMS_LocaleID(1033)]
instance of SoftDistProgramOfferReceivedEvent
{
AdvertisementId = "S01201AC";
ClientID = "GUID:69C4DEB4-DAF9-4B1B-9AED-400BA30C6C1F";
DateTime = "20081112162833.698000+000";
MachineName = "PC4262";
ProcessID = 568;
SiteCode = "S01";
ThreadID = 2308;
};
execmgr 11/12/2008 11:28:33 AM 2308 (0x0904)
Content is available for program Microsoft Update - KB958644 Patch. execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Executing program PatchInstall.exe /g:336 /z:w /q /c:1 /t:30 /m:"PatchAuthorize.xml" in Admin context execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Execution Request for program Microsoft Update - KB958644 Patch state change from WaitingContent to Running execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Checking content location \\SMS01\SMSPKGD$\S0100094\ for use execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Successfully selected content location \\SMS01\SMSPKGD$\S0100094 execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Executing program as a patch. execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Executing Patch Program execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Patch Installation started for the passed command line execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Raising event:
[SMS_CodePage(437), SMS_LocaleID(1033)]
instance of SoftDistProgramStartedEvent
{
AdvertisementId = "S0120189";
ClientID = "GUID:69C4DEB4-DAF9-4B1B-9AED-400BA30C6C1F";
CommandLine = "";
DateTime = "20081112162833.979000+000";
MachineName = "PC4262";
PackageName = "S0100094";
ProcessID = 568;
ProgramName = "Microsoft Update - KB958644 Patch";
SiteCode = "S01";
ThreadID = 1196;
UserContext = "NT AUTHORITY\\SYSTEM";
WorkingDirectory = "\\\\SMS01\\SMSPKGD$\\S0100094\\";
};
execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Raised Program Started Event for Ad:S0120189, Package:S0100094, Program: Microsoft Update - KB958644 Patch execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Looking for MIF file to get program status execmgr 11/12/2008 11:29:34 AM 2068 (0x0814)
A Matching MIF file PIAStat.mif was found execmgr 11/12/2008 11:29:34 AM 2068 (0x0814)
Raising event:
[SMS_CodePage(437), SMS_LocaleID(1033)]
instance of SoftDistProgramPrelimSuccessEvent
{
AdvertisementId = "S0120189";
ClientID = "GUID:69C4DEB4-DAF9-4B1B-9AED-400BA30C6C1F";
DateTime = "20081112162934.385000+000";
ExitCode = "0";
MachineName = "PC4262";
PackageName = "S0100094";
ProcessID = 568;
ProgramName = "Microsoft Update - KB958644 Patch";
SiteCode = "S01";
ThreadID = 2068;
};
execmgr 11/12/2008 11:29:34 AM 2068 (0x0814)
Raised Program Prelim Success Event for Ad:S0120189, Package:S0100094, Program: Microsoft Update - KB958644 Patch execmgr 11/12/2008 11:29:34 AM 2068 (0x0814)
Execution is complete for program Microsoft Update - KB958644 Patch. The exit code is 0, the execution status is SuccessRebootRequired execmgr 11/12/2008 11:29:34 AM 2640 (0x0A50)
Rebooting the computer - InitiateSystemShutdownEx succeeded execmgr 11/12/2008 11:29:34 AM 2640 (0x0A50)
Request in running or report status found for program Microsoft Update - KB958644 Patch package S0100094 execmgr 11/12/2008 11:33:01 AM 2856 (0x0B28)
System restarted while program Microsoft Update - KB958644 Patch is prompting user for reboot execmgr 11/12/2008 11:33:01 AM 2856 (0x0B28)
Looking for MIF file to get program status execmgr 11/12/2008 11:33:01 AM 2856 (0x0B28)
A Matching MIF file PIAStat.mif was found execmgr 11/12/2008 11:33:01 AM 2856 (0x0B28)
Raising event:
[SMS_CodePage(437), SMS_LocaleID(1033)]
instance of SoftDistProgramCompletedSuccessfulMIFEvent
{
AdvertisementId = "S0120189";
ClientID = "GUID:69C4DEB4-DAF9-4B1B-9AED-400BA30C6C1F";
DateTime = "20081112163301.801000+000";
MachineName = "PC4262";
MIFDescription = "";
MIFDescription7 = "";
MIFDescription8 = "";
MIFDescription9 = "";
PackageName = "S0100094";
ProcessID = 404;
ProgramName = "Microsoft Update - KB958644 Patch";
SiteCode = "S01";
ThreadID = 2856;
UserContext = "NT AUTHORITY\\SYSTEM";
};
execmgr 11/12/2008 11:33:01 AM 2856 (0x0B28)
Raised Program MIF Success Event for Ad:S0120189, Package:S0100094, Program: Microsoft Update - KB958644 Patch execmgr 11/12/2008 11:33:01 AM 2856 (0x0B28)
Policy is updated for Program: Microsoft Updates Tool, Package: S0100002, Advert: S0120000 execmgr 11/12/2008 1:38:12 PM 2316 (0x090C)
here is part of the log.
--------------------------------------------
A duplicate execution request is found for program Microsoft Update - KB958644 Patch execmgr 11/11/2008 9:17:00 PM 924 (0x039C)
Policy is updated for Program: Microsoft Update - KB958644 Patch, Package: S0100094, Advert: S0120189 execmgr 11/12/2008 11:28:33 AM 576 (0x0240)
Mandatory program Microsoft Update - KB958644 Patch is now enabled execmgr 11/12/2008 11:28:33 AM 576 (0x0240)
Creating mandatory request for advert S0120189, program Microsoft Update - KB958644 Patch, package S0100094 execmgr 11/12/2008 11:28:33 AM 576 (0x0240)
Requesting content from CAS for package S0100094 version 7 execmgr 11/12/2008 11:28:33 AM 576 (0x0240)
Raising event:
[SMS_CodePage(437), SMS_LocaleID(1033)]
instance of SoftDistWaitingContentEvent
{
AdvertisementId = "S0120189";
ClientID = "GUID:69C4DEB4-DAF9-4B1B-9AED-400BA30C6C1F";
DateTime = "20081112162833.682000+000";
MachineName = "PC4262";
PackageName = "S0100094";
PackageVersion = "7";
ProcessID = 568;
ProgramName = "Microsoft Update - KB958644 Patch";
SiteCode = "S01";
ThreadID = 576;
};
execmgr 11/12/2008 11:28:33 AM 576 (0x0240)
Successfully raised SoftDistWaitingContentEvent event for program Microsoft Update - KB958644 Patch execmgr 11/12/2008 11:28:33 AM 576 (0x0240)
Execution Request for program Microsoft Update - KB958644 Patch state change from NotExist to WaitingContent execmgr 11/12/2008 11:28:33 AM 576 (0x0240)
Policy arrived for parent program Microsoft Update - KB958644 Patch execmgr 11/12/2008 11:28:33 AM 2308 (0x0904)
Raising event:
[SMS_CodePage(437), SMS_LocaleID(1033)]
instance of SoftDistProgramOfferReceivedEvent
{
AdvertisementId = "S01201AC";
ClientID = "GUID:69C4DEB4-DAF9-4B1B-9AED-400BA30C6C1F";
DateTime = "20081112162833.698000+000";
MachineName = "PC4262";
ProcessID = 568;
SiteCode = "S01";
ThreadID = 2308;
};
execmgr 11/12/2008 11:28:33 AM 2308 (0x0904)
Content is available for program Microsoft Update - KB958644 Patch. execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Executing program PatchInstall.exe /g:336 /z:w /q /c:1 /t:30 /m:"PatchAuthorize.xml" in Admin context execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Execution Request for program Microsoft Update - KB958644 Patch state change from WaitingContent to Running execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Checking content location \\SMS01\SMSPKGD$\S0100094\ for use execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Successfully selected content location \\SMS01\SMSPKGD$\S0100094 execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Executing program as a patch. execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Executing Patch Program execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Patch Installation started for the passed command line execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Raising event:
[SMS_CodePage(437), SMS_LocaleID(1033)]
instance of SoftDistProgramStartedEvent
{
AdvertisementId = "S0120189";
ClientID = "GUID:69C4DEB4-DAF9-4B1B-9AED-400BA30C6C1F";
CommandLine = "";
DateTime = "20081112162833.979000+000";
MachineName = "PC4262";
PackageName = "S0100094";
ProcessID = 568;
ProgramName = "Microsoft Update - KB958644 Patch";
SiteCode = "S01";
ThreadID = 1196;
UserContext = "NT AUTHORITY\\SYSTEM";
WorkingDirectory = "\\\\SMS01\\SMSPKGD$\\S0100094\\";
};
execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Raised Program Started Event for Ad:S0120189, Package:S0100094, Program: Microsoft Update - KB958644 Patch execmgr 11/12/2008 11:28:33 AM 1196 (0x04AC)
Looking for MIF file to get program status execmgr 11/12/2008 11:29:34 AM 2068 (0x0814)
A Matching MIF file PIAStat.mif was found execmgr 11/12/2008 11:29:34 AM 2068 (0x0814)
Raising event:
[SMS_CodePage(437), SMS_LocaleID(1033)]
instance of SoftDistProgramPrelimSuccessEvent
{
AdvertisementId = "S0120189";
ClientID = "GUID:69C4DEB4-DAF9-4B1B-9AED-400BA30C6C1F";
DateTime = "20081112162934.385000+000";
ExitCode = "0";
MachineName = "PC4262";
PackageName = "S0100094";
ProcessID = 568;
ProgramName = "Microsoft Update - KB958644 Patch";
SiteCode = "S01";
ThreadID = 2068;
};
execmgr 11/12/2008 11:29:34 AM 2068 (0x0814)
Raised Program Prelim Success Event for Ad:S0120189, Package:S0100094, Program: Microsoft Update - KB958644 Patch execmgr 11/12/2008 11:29:34 AM 2068 (0x0814)
Execution is complete for program Microsoft Update - KB958644 Patch. The exit code is 0, the execution status is SuccessRebootRequired execmgr 11/12/2008 11:29:34 AM 2640 (0x0A50)
Rebooting the computer - InitiateSystemShutdownEx succeeded execmgr 11/12/2008 11:29:34 AM 2640 (0x0A50)
Request in running or report status found for program Microsoft Update - KB958644 Patch package S0100094 execmgr 11/12/2008 11:33:01 AM 2856 (0x0B28)
System restarted while program Microsoft Update - KB958644 Patch is prompting user for reboot execmgr 11/12/2008 11:33:01 AM 2856 (0x0B28)
Looking for MIF file to get program status execmgr 11/12/2008 11:33:01 AM 2856 (0x0B28)
A Matching MIF file PIAStat.mif was found execmgr 11/12/2008 11:33:01 AM 2856 (0x0B28)
Raising event:
[SMS_CodePage(437), SMS_LocaleID(1033)]
instance of SoftDistProgramCompletedSuccessfulMIFEvent
{
AdvertisementId = "S0120189";
ClientID = "GUID:69C4DEB4-DAF9-4B1B-9AED-400BA30C6C1F";
DateTime = "20081112163301.801000+000";
MachineName = "PC4262";
MIFDescription = "";
MIFDescription7 = "";
MIFDescription8 = "";
MIFDescription9 = "";
PackageName = "S0100094";
ProcessID = 404;
ProgramName = "Microsoft Update - KB958644 Patch";
SiteCode = "S01";
ThreadID = 2856;
UserContext = "NT AUTHORITY\\SYSTEM";
};
execmgr 11/12/2008 11:33:01 AM 2856 (0x0B28)
Raised Program MIF Success Event for Ad:S0120189, Package:S0100094, Program: Microsoft Update - KB958644 Patch execmgr 11/12/2008 11:33:01 AM 2856 (0x0B28)
Policy is updated for Program: Microsoft Updates Tool, Package: S0100002, Advert: S0120000 execmgr 11/12/2008 1:38:12 PM 2316 (0x090C)