D
Dave Petzel
Guest
I have seen several threads asking about this but no answers, so I am going
to post again.
I have recently deployed WSUS 3.0 and everything seems to be working EXCEPT
service pack 2 for Windows 2003 on some machines. ALL other updates seem to
be working OK and even SP2 on some machines appears to be working. Here is
what I can share about the machines that are not succeeding.
When I view the status report for one of the problem machines it lists the
update "Windows Server 2003 Service Pack 2 (32-bit x86)" with an approval of
install and a status of downloaded.
Machines are configured VIA GPO and are set to install updates every day at
3:00 AM (This is DEV).
When I check the Event Logs as well as %systemroot%\windowsupdate.log I do
not that jumps out as a major problem. One line that does catch my eye is
"Forced install timer expired for scheduled install" I am not really sure
what this mean, but it occurs 10 seconds after the scheduled start time. I
have pasted a snippet of the log file below.
I was also caught off guard by this line "Setting AU scheduled install time
to 2007-07-23 07:00:00" Why does it list 07:00:00 instead of 03:00:00. I am
in EST and the only thing I can think of is this log entry is written in GMT?
Has anyone been able to to resolve this? I need to get this process working
before approving the update in higher environments.
Log Snippet:
2007-07-22 05:07:43:367 1476 b1c Report Reporter successfully uploaded 2
events.
2007-07-23 01:46:03:648 1476 34c AU #############
2007-07-23 01:46:03:648 1476 34c AU ## START ## AU: Search for updates
2007-07-23 01:46:03:648 1476 34c AU #########
2007-07-23 01:46:03:648 1476 34c AU <<## SUBMITTED ## AU: Search for updates
[CallId = {B42794ED-5C1C-44BC-AE49-DDFB42EA19A1}]
2007-07-23 01:46:03:648 1476 bb8 Agent *************
2007-07-23 01:46:03:648 1476 bb8 Agent ** START ** Agent: Finding updates
[CallerId = AutomaticUpdates]
2007-07-23 01:46:03:648 1476 bb8 Agent *********
2007-07-23 01:46:03:648 1476 bb8 Agent * Online = Yes; Ignore download
priority = No
2007-07-23 01:46:03:648 1476 bb8 Agent * Criteria = "IsHidden=0 and
IsInstalled=0 and DeploymentAction='Installation' and IsAssigned=1 or
IsHidden=0 and IsPresent=1 and DeploymentAction='Uninstallation' and
IsAssigned=1 or IsHidden=0 and IsInstalled=1 and
DeploymentAction='Installation' and IsAssigned=1 and RebootRequired=1 or
IsHidden=0 and IsInstalled=0 and DeploymentAction='Uninstallation' and
IsAssigned=1 and RebootRequired=1"
2007-07-23 01:46:03:648 1476 bb8 Agent * ServiceID =
{3DA21691-E39D-4DA6-8A4B-B43877BCB1B7}
2007-07-23 01:46:03:648 1476 bb8 Misc Validating signature for
C:\WINDOWS\SoftwareDistribution\SelfUpdate\Default\wuident.cab:
2007-07-23 01:46:03:648 1476 bb8 Misc Microsoft signed: Yes
2007-07-23 01:46:03:898 1476 bb8 Misc Validating signature for
C:\WINDOWS\SoftwareDistribution\SelfUpdate\Default\wuident.cab:
2007-07-23 01:46:03:898 1476 bb8 Misc Microsoft signed: Yes
2007-07-23 01:46:03:914 1476 bb8 Misc Validating signature for
C:\WINDOWS\SoftwareDistribution\SelfUpdate\Default\wsus3setup.cab:
2007-07-23 01:46:03:914 1476 bb8 Misc Microsoft signed: Yes
2007-07-23 01:46:03:914 1476 bb8 Setup *********** Setup: Checking whether
self-update is required ***********
2007-07-23 01:46:03:914 1476 bb8 Setup * Inf file:
C:\WINDOWS\SoftwareDistribution\SelfUpdate\Default\wsus3setup.inf
2007-07-23 01:46:03:914 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\cdm.dll: target version = 7.0.6000.374, required version
= 7.0.6000.374
2007-07-23 01:46:03:914 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuapi.dll: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuapi.dll.mui: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuauclt.exe: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuaucpl.cpl: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuaucpl.cpl.mui: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuaueng.dll: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuaueng.dll.mui: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wucltui.dll: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wucltui.dll.mui: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wups.dll: target version = 7.0.6000.374, required version
= 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wups2.dll: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuweb.dll: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup * IsUpdateRequired = No
2007-07-23 01:46:26:461 1476 bb8 PT +++++++++++ PT: Synchronizing server
updates +++++++++++
2007-07-23 01:46:26:461 1476 bb8 PT + ServiceId =
{3DA21691-E39D-4DA6-8A4B-B43877BCB1B7}, Server URL =
http://WSUS01/ClientWebService/client.asmx
2007-07-23 01:46:26:555 1476 bb8 PT WARNING: Cached cookie has expired or
new PID is available
2007-07-23 01:46:26:555 1476 bb8 PT Initializing simple targeting cookie,
clientId = 75cf5c29-fede-4282-81e4-746f0c90e6c3, target group = DEV, DNS name
= buildman.gobluefrog.com
2007-07-23 01:46:26:555 1476 bb8 PT Server URL =
http://WSUS01/SimpleAuthWebService/SimpleAuth.asmx
2007-07-23 01:46:32:461 1476 bb8 PT +++++++++++ PT: Synchronizing extended
update info +++++++++++
2007-07-23 01:46:32:461 1476 bb8 PT + ServiceId =
{3DA21691-E39D-4DA6-8A4B-B43877BCB1B7}, Server URL =
http://WSUS01/ClientWebService/client.asmx
2007-07-23 01:46:49:508 1476 bb8 Agent * Added update
{9452503C-05BE-4A64-99ED-9A59F7D65398}.112 to search result
2007-07-23 01:46:49:508 1476 bb8 Agent * Found 1 updates and 34 categories
in search; evaluated appl. rules of 432 out of 542 deployed entities
2007-07-23 01:46:49:805 1476 bb8 Agent *********
2007-07-23 01:46:49:805 1476 bb8 Agent ** END ** Agent: Finding updates
[CallerId = AutomaticUpdates]
2007-07-23 01:46:49:805 1476 bb8 Agent *************
2007-07-23 01:46:49:805 1476 b48 AU >>## RESUMED ## AU: Search for updates
[CallId = {B42794ED-5C1C-44BC-AE49-DDFB42EA19A1}]
2007-07-23 01:46:49:805 1476 b48 AU # 1 updates detected
2007-07-23 01:46:49:805 1476 b48 AU #########
2007-07-23 01:46:49:805 1476 b48 AU ## END ## AU: Search for updates
[CallId = {B42794ED-5C1C-44BC-AE49-DDFB42EA19A1}]
2007-07-23 01:46:49:805 1476 b48 AU #############
2007-07-23 01:46:49:805 1476 b48 AU AU setting next detection timeout to
2007-07-24 00:55:07
2007-07-23 01:46:49:805 1476 b48 AU Setting AU scheduled install time to
2007-07-23 07:00:00
2007-07-23 01:46:54:805 1476 bb8 Report REPORT EVENT:
{ABFE8F6C-5852-49D3-ACFD-50D13D7E1323} 2007-07-23
01:46:49:805-0400 1 147 101 {00000000-0000-0000-0000-000000000000} 0 0 AutomaticUpdates Success Software
Synchronization Windows Update Client successfully detected 1 updates.
2007-07-23 01:46:54:805 1476 bb8 Report REPORT EVENT:
{0CCCE788-0D91-459B-B496-BBB5DB8613D8} 2007-07-23
01:46:49:805-0400 1 156 101 {00000000-0000-0000-0000-000000000000} 0 0 AutomaticUpdates Success Pre-Deployment Check Reporting client status.
2007-07-23 01:48:46:886 1476 bb8 Report Uploading 2 events using cached
cookie, reporting URL =
http://WSUS01/ReportingWebService/ReportingWebService.asmx
2007-07-23 01:48:46:886 1476 bb8 Report Reporter successfully uploaded 2
events.
2007-07-23 03:00:10:148 1476 34c AU Forced install timer expired for
scheduled install
2007-07-23 03:00:10:148 1476 34c AU UpdateDownloadProperties: 0 download(s)
are still in progress.
2007-07-23 03:00:10:148 1476 34c AU Setting AU scheduled install time to
2007-07-24 07:00:00
2007-07-23 20:45:05:478 1476 34c AU Launched new AU client for directive
'Install Approval', session id = 0x2
2007-07-23 20:45:05:509 4052 b5c Misc =========== Logging initialized
(build: 7.0.6000.374, tz: -0400) ===========
2007-07-23 20:45:05:509 4052 b5c Misc = Process:
C:\WINDOWS\system32\wuauclt.exe
2007-07-23 20:45:05:509 4052 b5c AUClnt Launched Client UI process
2007-07-23 20:45:05:572 4052 b5c Misc =========== Logging initialized
(build: 7.0.6000.374, tz: -0400) ===========
2007-07-23 20:45:05:572 4052 b5c Misc = Process:
C:\WINDOWS\system32\wuauclt.exe
2007-07-23 20:45:05:572 4052 b5c Misc = Module:
C:\WINDOWS\system32\wucltui.dll
2007-07-23 20:45:05:572 4052 b5c CltUI AU client got new directive =
'Install Approval', serviceId = {3DA21691-E39D-4DA6-8A4B-B43877BCB1B7},
return = 0x00000000
2007-07-23 20:45:05:572 4052 b5c CltUI AU client creating default WU/WSUS UI
plugin
to post again.
I have recently deployed WSUS 3.0 and everything seems to be working EXCEPT
service pack 2 for Windows 2003 on some machines. ALL other updates seem to
be working OK and even SP2 on some machines appears to be working. Here is
what I can share about the machines that are not succeeding.
When I view the status report for one of the problem machines it lists the
update "Windows Server 2003 Service Pack 2 (32-bit x86)" with an approval of
install and a status of downloaded.
Machines are configured VIA GPO and are set to install updates every day at
3:00 AM (This is DEV).
When I check the Event Logs as well as %systemroot%\windowsupdate.log I do
not that jumps out as a major problem. One line that does catch my eye is
"Forced install timer expired for scheduled install" I am not really sure
what this mean, but it occurs 10 seconds after the scheduled start time. I
have pasted a snippet of the log file below.
I was also caught off guard by this line "Setting AU scheduled install time
to 2007-07-23 07:00:00" Why does it list 07:00:00 instead of 03:00:00. I am
in EST and the only thing I can think of is this log entry is written in GMT?
Has anyone been able to to resolve this? I need to get this process working
before approving the update in higher environments.
Log Snippet:
2007-07-22 05:07:43:367 1476 b1c Report Reporter successfully uploaded 2
events.
2007-07-23 01:46:03:648 1476 34c AU #############
2007-07-23 01:46:03:648 1476 34c AU ## START ## AU: Search for updates
2007-07-23 01:46:03:648 1476 34c AU #########
2007-07-23 01:46:03:648 1476 34c AU <<## SUBMITTED ## AU: Search for updates
[CallId = {B42794ED-5C1C-44BC-AE49-DDFB42EA19A1}]
2007-07-23 01:46:03:648 1476 bb8 Agent *************
2007-07-23 01:46:03:648 1476 bb8 Agent ** START ** Agent: Finding updates
[CallerId = AutomaticUpdates]
2007-07-23 01:46:03:648 1476 bb8 Agent *********
2007-07-23 01:46:03:648 1476 bb8 Agent * Online = Yes; Ignore download
priority = No
2007-07-23 01:46:03:648 1476 bb8 Agent * Criteria = "IsHidden=0 and
IsInstalled=0 and DeploymentAction='Installation' and IsAssigned=1 or
IsHidden=0 and IsPresent=1 and DeploymentAction='Uninstallation' and
IsAssigned=1 or IsHidden=0 and IsInstalled=1 and
DeploymentAction='Installation' and IsAssigned=1 and RebootRequired=1 or
IsHidden=0 and IsInstalled=0 and DeploymentAction='Uninstallation' and
IsAssigned=1 and RebootRequired=1"
2007-07-23 01:46:03:648 1476 bb8 Agent * ServiceID =
{3DA21691-E39D-4DA6-8A4B-B43877BCB1B7}
2007-07-23 01:46:03:648 1476 bb8 Misc Validating signature for
C:\WINDOWS\SoftwareDistribution\SelfUpdate\Default\wuident.cab:
2007-07-23 01:46:03:648 1476 bb8 Misc Microsoft signed: Yes
2007-07-23 01:46:03:898 1476 bb8 Misc Validating signature for
C:\WINDOWS\SoftwareDistribution\SelfUpdate\Default\wuident.cab:
2007-07-23 01:46:03:898 1476 bb8 Misc Microsoft signed: Yes
2007-07-23 01:46:03:914 1476 bb8 Misc Validating signature for
C:\WINDOWS\SoftwareDistribution\SelfUpdate\Default\wsus3setup.cab:
2007-07-23 01:46:03:914 1476 bb8 Misc Microsoft signed: Yes
2007-07-23 01:46:03:914 1476 bb8 Setup *********** Setup: Checking whether
self-update is required ***********
2007-07-23 01:46:03:914 1476 bb8 Setup * Inf file:
C:\WINDOWS\SoftwareDistribution\SelfUpdate\Default\wsus3setup.inf
2007-07-23 01:46:03:914 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\cdm.dll: target version = 7.0.6000.374, required version
= 7.0.6000.374
2007-07-23 01:46:03:914 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuapi.dll: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuapi.dll.mui: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuauclt.exe: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuaucpl.cpl: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuaucpl.cpl.mui: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuaueng.dll: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuaueng.dll.mui: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wucltui.dll: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wucltui.dll.mui: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wups.dll: target version = 7.0.6000.374, required version
= 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wups2.dll: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup Update NOT required for
C:\WINDOWS\system32\wuweb.dll: target version = 7.0.6000.374, required
version = 7.0.6000.374
2007-07-23 01:46:03:929 1476 bb8 Setup * IsUpdateRequired = No
2007-07-23 01:46:26:461 1476 bb8 PT +++++++++++ PT: Synchronizing server
updates +++++++++++
2007-07-23 01:46:26:461 1476 bb8 PT + ServiceId =
{3DA21691-E39D-4DA6-8A4B-B43877BCB1B7}, Server URL =
http://WSUS01/ClientWebService/client.asmx
2007-07-23 01:46:26:555 1476 bb8 PT WARNING: Cached cookie has expired or
new PID is available
2007-07-23 01:46:26:555 1476 bb8 PT Initializing simple targeting cookie,
clientId = 75cf5c29-fede-4282-81e4-746f0c90e6c3, target group = DEV, DNS name
= buildman.gobluefrog.com
2007-07-23 01:46:26:555 1476 bb8 PT Server URL =
http://WSUS01/SimpleAuthWebService/SimpleAuth.asmx
2007-07-23 01:46:32:461 1476 bb8 PT +++++++++++ PT: Synchronizing extended
update info +++++++++++
2007-07-23 01:46:32:461 1476 bb8 PT + ServiceId =
{3DA21691-E39D-4DA6-8A4B-B43877BCB1B7}, Server URL =
http://WSUS01/ClientWebService/client.asmx
2007-07-23 01:46:49:508 1476 bb8 Agent * Added update
{9452503C-05BE-4A64-99ED-9A59F7D65398}.112 to search result
2007-07-23 01:46:49:508 1476 bb8 Agent * Found 1 updates and 34 categories
in search; evaluated appl. rules of 432 out of 542 deployed entities
2007-07-23 01:46:49:805 1476 bb8 Agent *********
2007-07-23 01:46:49:805 1476 bb8 Agent ** END ** Agent: Finding updates
[CallerId = AutomaticUpdates]
2007-07-23 01:46:49:805 1476 bb8 Agent *************
2007-07-23 01:46:49:805 1476 b48 AU >>## RESUMED ## AU: Search for updates
[CallId = {B42794ED-5C1C-44BC-AE49-DDFB42EA19A1}]
2007-07-23 01:46:49:805 1476 b48 AU # 1 updates detected
2007-07-23 01:46:49:805 1476 b48 AU #########
2007-07-23 01:46:49:805 1476 b48 AU ## END ## AU: Search for updates
[CallId = {B42794ED-5C1C-44BC-AE49-DDFB42EA19A1}]
2007-07-23 01:46:49:805 1476 b48 AU #############
2007-07-23 01:46:49:805 1476 b48 AU AU setting next detection timeout to
2007-07-24 00:55:07
2007-07-23 01:46:49:805 1476 b48 AU Setting AU scheduled install time to
2007-07-23 07:00:00
2007-07-23 01:46:54:805 1476 bb8 Report REPORT EVENT:
{ABFE8F6C-5852-49D3-ACFD-50D13D7E1323} 2007-07-23
01:46:49:805-0400 1 147 101 {00000000-0000-0000-0000-000000000000} 0 0 AutomaticUpdates Success Software
Synchronization Windows Update Client successfully detected 1 updates.
2007-07-23 01:46:54:805 1476 bb8 Report REPORT EVENT:
{0CCCE788-0D91-459B-B496-BBB5DB8613D8} 2007-07-23
01:46:49:805-0400 1 156 101 {00000000-0000-0000-0000-000000000000} 0 0 AutomaticUpdates Success Pre-Deployment Check Reporting client status.
2007-07-23 01:48:46:886 1476 bb8 Report Uploading 2 events using cached
cookie, reporting URL =
http://WSUS01/ReportingWebService/ReportingWebService.asmx
2007-07-23 01:48:46:886 1476 bb8 Report Reporter successfully uploaded 2
events.
2007-07-23 03:00:10:148 1476 34c AU Forced install timer expired for
scheduled install
2007-07-23 03:00:10:148 1476 34c AU UpdateDownloadProperties: 0 download(s)
are still in progress.
2007-07-23 03:00:10:148 1476 34c AU Setting AU scheduled install time to
2007-07-24 07:00:00
2007-07-23 20:45:05:478 1476 34c AU Launched new AU client for directive
'Install Approval', session id = 0x2
2007-07-23 20:45:05:509 4052 b5c Misc =========== Logging initialized
(build: 7.0.6000.374, tz: -0400) ===========
2007-07-23 20:45:05:509 4052 b5c Misc = Process:
C:\WINDOWS\system32\wuauclt.exe
2007-07-23 20:45:05:509 4052 b5c AUClnt Launched Client UI process
2007-07-23 20:45:05:572 4052 b5c Misc =========== Logging initialized
(build: 7.0.6000.374, tz: -0400) ===========
2007-07-23 20:45:05:572 4052 b5c Misc = Process:
C:\WINDOWS\system32\wuauclt.exe
2007-07-23 20:45:05:572 4052 b5c Misc = Module:
C:\WINDOWS\system32\wucltui.dll
2007-07-23 20:45:05:572 4052 b5c CltUI AU client got new directive =
'Install Approval', serviceId = {3DA21691-E39D-4DA6-8A4B-B43877BCB1B7},
return = 0x00000000
2007-07-23 20:45:05:572 4052 b5c CltUI AU client creating default WU/WSUS UI
plugin