Answered by:
Windows 10 IoT 1607 and WSUS on Server 2012 R2

Question
-
Hello,
I am trying to get a standalone system that is running Windows 10 IoT v1607 to pull updates from a Windows Server 2012 R2 server running WSUS.
In the update logs on the Windows 10 system I am seeing this error: CUHCbsHandler: :CancelDownloadRequest called
I have Googled this error and I see replies about adding Windows updates to the Server, however, when I run Windows Update on the Server it tells me that all updates are installed. And if I try to manually install these updates I get the message that they are not applicable. Here are the updates I have tried: KB3095113, KB3159706, KB3193494, KB3913414, KB2919442.
I know there was some issue with Windows 10 1607 working with WSUS, but I am not sure what that issue was or how to fix it.
Has anyone see this error message?
Here are the last logs from my Windows 10 system:
2017/10/31 04:29:24.5129156 1000 1988 Agent WU client version 10.0.14393.1532
2017/10/31 04:29:24.5131413 1000 1988 Agent SleepStudyTracker: Machine is non-AOAC. Sleep study tracker disabled.
2017/10/31 04:29:24.5131921 1000 1988 Agent Base directory: C:\Windows\SoftwareDistribution
2017/10/31 04:29:24.5135087 1000 1988 Agent Datastore directory: C:\Windows\SoftwareDistribution\DataStore\DataStore.edb
2017/10/31 04:29:24.5250713 1000 1988 Shared UpdateNetworkState Ipv6, cNetworkInterfaces = 0.
2017/10/31 04:29:24.5250850 1000 1988 Shared UpdateNetworkState Ipv4, cNetworkInterfaces = 1.
2017/10/31 04:29:24.5254523 1000 1988 Shared Network state: Connected
2017/10/31 04:29:24.5273173 1000 1988 Misc LoadHistoryEventFromRegistry completed, hr = 8024000C
2017/10/31 04:29:24.5278800 1000 1988 Shared UpdateNetworkState Ipv6, cNetworkInterfaces = 0.
2017/10/31 04:29:24.5278843 1000 1988 Shared UpdateNetworkState Ipv4, cNetworkInterfaces = 1.
2017/10/31 04:29:24.5278886 1000 1988 Shared Power status changed
2017/10/31 04:29:24.5298043 1000 1128 Agent Timer: 29A863E7-8609-4D1E-B7CD-5668F857F1DB, Expires 2017-10-31 14:37:23, not idle-only, not network-only
2017/10/31 04:29:24.5300663 1000 1988 Agent Initializing global settings cache
2017/10/31 04:29:24.5300663 1000 1988 Agent WSUS server: http://VSTEST:8530
2017/10/31 04:29:24.5300667 1000 1988 Agent WSUS status server: http://VSTEST:8530
2017/10/31 04:29:24.5300671 1000 1988 Agent Alternate Download Server: NULL
2017/10/31 04:29:24.5300671 1000 1988 Agent Fill Empty Content Urls: No
2017/10/31 04:29:24.5300676 1000 1988 Agent Target group: BlizCons
2017/10/31 04:29:24.5300676 1000 1988 Agent Windows Update access disabled: No
2017/10/31 04:29:24.5337569 1000 1128 Agent Initializing Windows Update Agent
2017/10/31 04:29:24.5338218 1000 1128 DownloadManager Download manager restoring 0 downloads
2017/10/31 04:29:24.5338478 1000 1128 Agent CPersistentTimeoutScheduler | GetTimer, returned hr = 0x00000000
2017/10/31 04:29:24.5488511 1000 1128 DataStore Service 204FBF11-8A72-4606-8E0B-2FFDD8D4C0C9 added
2017/10/31 04:29:24.5489727 1000 588 DownloadManager PurgeExpiredFiles::Found 0 expired files to delete.
2017/10/31 04:29:24.5490269 1000 1128 Agent AddTargetedServiceMapping: 204FBF11-8A72-4606-8E0B-2FFDD8D4C0C9 -> 3DA21691-E39D-4DA6-8A4B-B43877BCB1B7
1600/12/31 19:00:00.0000000 2680 4852 Unknown( 10): GUID=eb73583d-5481-33b4-202d-9bb270eddffa (No Format Information found).
1600/12/31 19:00:00.0000000 2680 4852 Unknown( 11): GUID=eb73583d-5481-33b4-202d-9bb270eddffa (No Format Information found).
2017/10/31 04:29:24.5517729 1000 588 DownloadManager PurgeExpiredUpdates::Found 18 non expired updates.
2017/10/31 04:29:24.5557367 1000 588 DownloadManager PurgeExpiredUpdates::Found 0 expired updates.
2017/10/31 04:29:24.5586572 1000 588 Shared Effective power state: AC
2017/10/31 04:29:24.5586576 1000 588 DownloadManager Power state change detected. Source now: AC
2017/10/31 04:29:24.5932270 1000 3640 Agent * START * Queueing Finding updates [CallerId = <<PROCESS>>: CompatTelRunner.exe Id = 1]
2017/10/31 04:29:24.5932334 1000 3640 Agent Service 204FBF11-8A72-4606-8E0B-2FFDD8D4C0C9 is not in sequential scan list
2017/10/31 04:29:24.5932359 1000 3640 Agent Added service 204FBF11-8A72-4606-8E0B-2FFDD8D4C0C9 to sequential scan list
1600/12/31 19:00:00.0000000 2680 4852 Unknown( 12): GUID=eb73583d-5481-33b4-202d-9bb270eddffa (No Format Information found).
2017/10/31 04:29:24.5934825 1000 3228 Agent Service 204FBF11-8A72-4606-8E0B-2FFDD8D4C0C9 is in sequential scan list
2017/10/31 04:29:24.5942992 1000 3260 Agent * END * Queueing Finding updates [CallerId = <<PROCESS>>: CompatTelRunner.exe Id = 1]
2017/10/31 04:29:24.5950049 1000 3260 Agent * START * Finding updates CallerId = <<PROCESS>>: CompatTelRunner.exe Id = 1
2017/10/31 04:29:24.5950053 1000 3260 Agent Online = Yes; AllowCachedResults = No; Ignore download priority = No
2017/10/31 04:29:24.5950057 1000 3260 Agent Criteria = (CategoryIDs contains '405706ED-F1D7-47EA-91E1-EB8860039715' AND Type='Driver' AND VersionOverride='10.0.15063.2.0.0.256.1') OR (CategoryIDs contains '34F268B4-7E2D-40E1-8966-8BB6EA3DAD27' AND Type='Driver' AND VersionOverride='10.0.15063.2.0.0.256.1') OR (CategoryIDs contains '05EEBF61-148B-43CF-80DA-1C99AB0B8699' AND Type='Driver' AND VersionOverride='10.0.15063.2.0.0.256.1')""
2017/10/31 04:29:24.5950083 1000 3260 Agent ServiceID = {204FBF11-8A72-4606-8E0B-2FFDD8D4C0C9} Third party service
2017/10/31 04:29:24.5950083 1000 3260 Agent Search Scope = {Machine}
2017/10/31 04:29:24.5950100 1000 3260 Agent Caller SID for Applicability: S-1-5-18
2017/10/31 04:29:24.5950104 1000 3260 Agent RegisterService is set
2017/10/31 04:29:24.5953057 1000 3260 Misc EP: error: 0x8024500C : - failed to get SLS data
2017/10/31 04:29:24.5953057 1000 3260 Misc EP: error: 0x8024500C: GetSecondaryServicesEnabledState failed
2017/10/31 04:29:24.5953074 1000 3260 Agent AutoRecovery: DetectAndToggleServiceState failed 0x8024500c
2017/10/31 04:29:24.5953116 1000 3260 Agent GetTargetedServiceMapping: 204FBF11-8A72-4606-8E0B-2FFDD8D4C0C9 -> 3DA21691-E39D-4DA6-8A4B-B43877BCB1B7
2017/10/31 04:29:24.5957797 1000 3260 Misc Got WSUS Client/Server URL: http://VSTEST:8530/ClientWebService/client.asmx""
2017/10/31 04:29:24.8090492 1000 3260 ProtocolTalker ServiceId = {3DA21691-E39D-4DA6-8A4B-B43877BCB1B7}, Server URL = http://VSTEST:8530/ClientWebService/client.asmx
2017/10/31 04:29:24.8090500 1000 3260 ProtocolTalker OK to reuse existing configuration
2017/10/31 04:29:24.8090547 1000 3260 ProtocolTalker Cached cookie has expired or new PID is available
2017/10/31 04:29:24.8090675 1000 3260 Misc Got WSUS SimpleTargeting URL: http://VSTEST:8530""
2017/10/31 04:29:24.8091328 1000 3260 ProtocolTalker Initializing simple targeting cookie, clientId = f2e33155-869d-4469-ab22-927b6d4a5f21, target group = BlizCons, DNS name = blizcon8
2017/10/31 04:29:24.8091332 1000 3260 ProtocolTalker Server URL = http://VSTEST:8530/SimpleAuthWebService/SimpleAuth.asmx
2017/10/31 04:29:24.8091379 1000 3260 WebServices Auto proxy settings for this web service call.
2017/10/31 04:29:24.8324476 1000 3260 WebServices Auto proxy settings for this web service call.
2017/10/31 04:29:25.5304174 1000 3260 ProtocolTalker PTInfo: Server requested registration
2017/10/31 04:29:25.5851805 1000 3260 ProtocolTalker ServiceId = {3DA21691-E39D-4DA6-8A4B-B43877BCB1B7}, Server URL = http://VSTEST:8530/ClientWebService/client.asmx
2017/10/31 04:29:25.5851860 1000 3260 ProtocolTalker OK to reuse existing configuration
2017/10/31 04:29:25.5851881 1000 3260 ProtocolTalker Existing cookie is valid, just use it
2017/10/31 04:29:25.5851886 1000 3260 ProtocolTalker PTInfo: Server requested registration
2017/10/31 04:29:25.6581903 1000 3260 ProtocolTalker SyncUpdates round trips: 1
2017/10/31 04:29:25.6699432 1000 3260 Agent Found 0 updates and 5 categories in search; evaluated appl. rules of 5 out of 5 deployed entities
2017/10/31 04:29:25.6747710 1000 3260 Agent * END * Finding updates CallerId = <<PROCESS>>: CompatTelRunner.exe Id = 1
1600/12/31 19:00:00.0000000 2680 3740 Unknown( 16): GUID=eb73583d-5481-33b4-202d-9bb270eddffa (No Format Information found).
1600/12/31 19:00:00.0000000 2680 3740 Unknown( 17): GUID=eb73583d-5481-33b4-202d-9bb270eddffa (No Format Information found).
1600/12/31 19:00:00.0000000 2680 3740 Unknown( 20): GUID=eb73583d-5481-33b4-202d-9bb270eddffa (No Format Information found).
1600/12/31 19:00:00.0000000 2680 4852 Unknown( 10): GUID=fe1b6309-dedb-392e-308c-ec757efded1a (No Format Information found).
2017/10/31 04:29:25.6821386 1000 1128 DataStore Service 204FBF11-8A72-4606-8E0B-2FFDD8D4C0C9 removed
2017/10/31 04:29:25.6821472 1000 1128 Agent RemoveTargetedServiceMapping: 204FBF11-8A72-4606-8E0B-2FFDD8D4C0C9 -> 3DA21691-E39D-4DA6-8A4B-B43877BCB1B7
2017/10/31 04:37:25.6865448 1000 588 Misc Got WSUS Client/Server URL: http://VSTEST:8530/ClientWebService/client.asmx""
2017/10/31 04:37:25.6866033 1000 588 ProtocolTalker OK to reuse existing configuration
2017/10/31 04:37:25.6866105 1000 588 ProtocolTalker Existing cookie is valid, just use it
2017/10/31 04:37:25.6866122 1000 588 ProtocolTalker PTInfo: Server requested registration
2017/10/31 04:37:25.7269078 1000 588 Misc Got WSUS Reporting URL: http://VSTEST:8530/ReportingWebService/ReportingWebService.asmx""
2017/10/31 04:37:25.7269352 1000 588 WebServices Auto proxy settings for this web service call.
2017/10/31 04:47:26.0454645 1000 1128 Agent Earliest future timer found:
2017/10/31 04:47:26.0454858 1000 1128 Agent Timer: 29A863E7-8609-4D1E-B7CD-5668F857F1DB, Expires 2017-10-31 14:37:23, not idle-only, not network-only
2017/10/31 04:47:27.0468814 1000 1988 Agent Earliest future timer found:
2017/10/31 04:47:27.0469015 1000 1988 Agent Timer: 29A863E7-8609-4D1E-B7CD-5668F857F1DB, Expires 2017-10-31 14:37:23, not idle-only, not network-only
2017/10/31 04:47:27.0518794 1000 1988 Misc CreateSessionStateChangeTrigger, TYPE:2, Enable:No
2017/10/31 04:47:27.0518815 1000 1988 Misc CreateSessionStateChangeTrigger, TYPE:4, Enable:No
2017/10/31 04:47:27.0538156 1000 1988 Handler CUHCbsHandler::CancelDownloadRequest called
2017/10/31 04:47:27.0796485 1000 1988 Shared * END * Service exit Exit code = 0x240001Tuesday, October 31, 2017 12:57 PM
Answers
-
Hello,
Thank you for the information, unfortunately installing that update did not fix my issue.
Fortunately, I was able to talk to a MS Support Rep and he was able to give me the answer.
Turns out that in the 1607 version of Windows 10 the system is setup to do "Dual Scanning". To see what that is you can read these two posts:
https://blogs.technet.microsoft.com/wsus/2017/08/04/improving-dual-scan-on-1607/
https://blogs.technet.microsoft.com/wsus/2017/05/05/demystifying-dual-scan/
To fix this problem, I had to remove some registry keys. These are the ones I had to remove:
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\BranchReadinessLevel
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\DeferFeatureUpdatesPeriodInDays
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\DeferQualityUpdatesPeriodInDays
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\DeferUpdatePeriod
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\DeferUpgradePeriod
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\ExcludeWUDriversInQualityUpdate
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\PauseDeferrals
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\PauseFeatureUpdates
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\PauseQualityUpdates
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\RequreDeferUpgrade
HKLM\SOFTWARE\Policies\Micorsoft\Windows\WindowsUpdate\ExcludeWUDriversInQualityUpdate
Once I removed these registry keys the system communicated with WSUS correctly.
Thanks.
- Marked as answer by ISMTech Monday, November 6, 2017 2:00 PM
Monday, November 6, 2017 2:00 PM
All replies
-
Windows 10 1607 RTM has a known issue that it will lose communication with any WSUS server. The fix for this is to install a Cumulative Update (CU) past September 2016 as it was fixed in the September CU. It will then re-establish communication with the WSUS server. Unfortunately, if the system is already Windows 10 1607 RTM, you have no choice but to use a 3rd party tool like PDQ Deploy or install the CU Manually on the machine.
It's best to install the latest CU, but you can install any one past September and then WSUS will be able to communicate again with the machine.
All your 1607 machines have not been receiving updates if this is the case and they are still on the 1607 RTM version.Adam Marshall, MCSE: Security
http://www.adamj.org- Proposed as answer by Yan Li_ Thursday, November 2, 2017 7:51 AM
Tuesday, October 31, 2017 5:37 PM -
Hello,
Thank you for the information, unfortunately installing that update did not fix my issue.
Fortunately, I was able to talk to a MS Support Rep and he was able to give me the answer.
Turns out that in the 1607 version of Windows 10 the system is setup to do "Dual Scanning". To see what that is you can read these two posts:
https://blogs.technet.microsoft.com/wsus/2017/08/04/improving-dual-scan-on-1607/
https://blogs.technet.microsoft.com/wsus/2017/05/05/demystifying-dual-scan/
To fix this problem, I had to remove some registry keys. These are the ones I had to remove:
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\BranchReadinessLevel
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\DeferFeatureUpdatesPeriodInDays
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\DeferQualityUpdatesPeriodInDays
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\DeferUpdatePeriod
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\DeferUpgradePeriod
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\ExcludeWUDriversInQualityUpdate
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\PauseDeferrals
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\PauseFeatureUpdates
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\PauseQualityUpdates
HKLM\SOFTWARE\Microsoft\PolicyManager\default\Update\RequreDeferUpgrade
HKLM\SOFTWARE\Policies\Micorsoft\Windows\WindowsUpdate\ExcludeWUDriversInQualityUpdate
Once I removed these registry keys the system communicated with WSUS correctly.
Thanks.
- Marked as answer by ISMTech Monday, November 6, 2017 2:00 PM
Monday, November 6, 2017 2:00 PM