locked
Windows 10 IoT 1607 and WSUS on Server 2012 R2 RRS feed

  • 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 = 0x240001

    Tuesday, 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