Share via


Cummulative Updates fail under Windows 10 Build 1067

Question

Wednesday, March 15, 2017 7:39 PM

Hi,

I have an issue with Windows 10 Updates. It seems that the Windows Update is broken for all Cumulative Updates, i took a look at the Log, but as far as i can tell, there is nothing that i could fix. I only see an Microsoft provided Endpoint not responding with Status 200 but intstead with 304 (https://fe2.update.microsoft.com/v10/3/windowsupdate/) which seems to be queried by the Update Process. Also odd is this line "Failed to retrieve SLS response data for service 117cab2d-82b1-4b5a-a08c-4d62dbee7782, error = 0x80040154". Can anyone tell me what the real issue here is? Can anyone even make sense of the logs as a whole?

I had this issue since i installed Windows 10 Pro Build 1067 (made on USB Flash with Thirdparty Tool [since MS dont offer to select the creation of UEFI bootable Flash drives if your active System is no UEFI System] from an ISO downloaded via the Installation Media Creation Tool offered by MS) on a completely new System (only 1 SSD involved, SATA connected, no Fancy stuff at all).

Down there you see the Windows Update Logs (i hope its all the relevant data, couldn't quite keep track of time) that was outputted via the Get-WindowsUpdateLog PowerShell cmdlet.

I just want to be able to install the Cumulative Updates instead of having to hide em all after i see the Update boot (since it does update automatically) that always fails.

Or if any of you know, where to submit bugs or sorts, i am happy to let Microsoft know, but i couldn't find any such Option...

Logs in the following posts, seems it was too much to handle...

Please be so kind to close your Threads when you found an answer, these Threads should help everyone with similar issues.
You can close a Thread via the"Mark as Answer" link below posts. You can mark your own posts as answers if you were not helped out but found a solution, in such a case, please provide the answer.
Happy coding
PS: I assure everyone that I did not ever had the desire to offend anyone.

All replies (14)

Friday, March 17, 2017 9:34 AM ✅Answered | 1 vote

Yep...

either of those Services was the culprit, Error code hints the BITS, the Error Message Hints the AppReadiness (i guess)...

Cummulative Update finally installed.

Microsoft really needs to overwork Error Reporting and Handling, this wasn't even a big issue, it just cause a ton of work only because the Information is hidden from the User.

Again: THANK YOU BIG TIME Robert!

Also a bit to sum it up:

When encountering an Error in Windows Update:
    1. Start up PowerShell
        - Press WindowsKey
        - Type "PowerShell"
        - Hit enter
    2. Create a foloder to work in
        - Type "New-Item C:\Users\$Env:username\Analyze -ItemType Directory"
        - Hit enter
    3. Navigate to Windows Logs folder
        - Type "cd $Env:windir\Logs"
        - Hit enter
    4. Extract and move WindowsUpdateLog from etl files
        - Type "Get-WindowsUpdateLog"
        - Hit enter
        - Type "Move-Item C:\Users\$Env:username\Desktop\WindowsUpdate.log C:\Users\$Env:username\Analyze\WindowsUpdate.log"
        - Hit enter
    5. See which Logs are relevant by examining the timestamps (in Widnows Logs folder)
        - Type "dir -Re * | sort LastWriteTime | ft LastWriteTime, FullName -AutoSize"
        - Hit enter 
        - Examine output!
    6. Filter the Logs (picked by previous Examine step) for errors and failures
        - Type "Get-Content .\PathTo\LogTo.Examine | Select-String -Pattern '(Fail[^o]|Error)' | Out-File C:\Users\$Env:username\Analyze\LogTo.Examine.Filtered"
        - Hit enter
    7. Examine ReportingEvents.log
        - Type "Get-Content C:\Windows\SoftwareDistribution\ReportingEvents.log | Out-File C:\Users\$Env:username\Analyze\ReportingEvents.log"
        - Hit enter
        - Examine the C:\Users\$Env:username\Analyze\ReportingEvents.log for the errors timestamp
    8. Try to find the Error by the given Timestamp (if any)
        - You can use the Windows UI for this one, no need to use PowerShell here
        - Open the files in C:\Users\$Env:username\Analyze and search for the timestamp from ReportingEvents.log (if any, Ctrl-F helps)
    9. Ask for help and give a list of disabled Services
        - Type "Get-Service | Select-Object Status, StartType, Name, DisplayName | Where-Object StartType -EQ Disabled | Out-File C:\Users\$Env:username\Analyze\DisabledServices.txt"
        - Hit enter
        - Ask someone which service needs to be enabled 
          (give him the data in C:\Users\$Env:username\Analyze)

Hope it helps someone else

@Robert Please put up an answer, since you did solve this for me (i mean i had the work, but without you...) so i can mark that answer as the answer for this Thread (Disabled BITS or AppReadiness)

Please be so kind to close your Threads when you found an answer, these Threads should help everyone with similar issues.
You can close a Thread via the"Mark as Answer" link below posts. You can mark your own posts as answers if you were not helped out but found a solution, in such a case, please provide the answer.
Happy coding
PS: I assure everyone that I did not ever had the desire to offend anyone.


Wednesday, March 15, 2017 7:40 PM

2017.03.15 16:24:00.8259851 1272  3592  Agent           WU client version 10.0.14393.0
2017.03.15 16:24:00.8264823 1272  3592  Agent           SleepStudyTracker: Machine is non-AOAC. Sleep study tracker disabled.
2017.03.15 16:24:00.8266380 1272  3592  Agent           Base directory: C:\Windows\SoftwareDistribution
2017.03.15 16:24:00.8278543 1272  3592  Agent           Datastore directory: C:\Windows\SoftwareDistribution\DataStore\DataStore.edb
2017.03.15 16:24:00.8693593 1272  3592  Shared          UpdateNetworkState Ipv6, cNetworkInterfaces = 3.
2017.03.15 16:24:00.8693850 1272  3592  Shared          UpdateNetworkState Ipv4, cNetworkInterfaces = 1.
2017.03.15 16:24:00.8705023 1272  3592  Shared          Network state: Connected
2017.03.15 16:24:00.8780336 1272  3592  Misc            LoadHistoryEventFromRegistry completed, hr = 8024000C
2017.03.15 16:24:00.8807221 1272  3592  Shared          UpdateNetworkState Ipv6, cNetworkInterfaces = 3.
2017.03.15 16:24:00.8807300 1272  3592  Shared          UpdateNetworkState Ipv4, cNetworkInterfaces = 1.
2017.03.15 16:24:00.8807359 1272  3592  Shared          Power status changed
2017.03.15 16:24:00.8825202 1272  3592  Agent           Initializing global settings cache
2017.03.15 16:24:00.8825207 1272  3592  Agent           WSUS server: NULL
2017.03.15 16:24:00.8825210 1272  3592  Agent           WSUS status server: NULL
2017.03.15 16:24:00.8825215 1272  3592  Agent           Target group: (Unassigned Computers)
2017.03.15 16:24:00.8825218 1272  3592  Agent           Windows Update access disabled: No
2017.03.15 16:24:00.8832683 1272  3592  Agent               Timer: 9482F4B4-E343-43B6-B170-9A65BC822C77, Expires 2017-03-14 22:13:31, not idle-only, <NULL>network-only
2017.03.15 16:24:00.8832722 1272  3592  Agent               Timer: 29A863E7-8609-4D1E-B7CD-5668F857F1DB, Expires 2017-03-15 20:32:21, not idle-only, not network-only
2017.03.15 16:24:00.8895179 1272  3592  Agent           Initializing Windows Update Agent
2017.03.15 16:24:00.8899502 1272  3592  DownloadManager Download manager restoring 0 downloads
2017.03.15 16:24:00.8902525 1272  3592  Agent           CPersistentTimeoutScheduler | GetTimer, returned hr = 0x00000000
2017.03.15 16:24:00.8953517 1272  3592  Agent           Attempt 1 to obtain post-reboot results for event with cookie 30579975_3679505134.
2017.03.15 16:24:02.4529177 1272  3592  Handler         Post-reboot status for session 30579975_3679505134: 0x800f0922.
2017.03.15 16:24:03.9732410 1272  3880  DownloadManager PurgeExpiredFiles::Found 0 expired files to delete.
2017.03.15 16:24:03.9781227 1272  3880  DownloadManager PurgeExpiredUpdates::Found 394 non expired updates.
2017.03.15 16:24:04.0662892 1272  3880  DownloadManager PurgeExpiredUpdates::Found 0 expired updates.
2017.03.15 16:24:04.0730519 1272  3880  Shared          Effective power state: AC
2017.03.15 16:24:04.0730527 1272  3880  DownloadManager Power state change detected. Source now: AC
2017.03.15 16:28:50.9320081 7936  8108  ComApi          * START *   Init Search ClientId = NULL
2017.03.15 16:28:50.9320110 7936  8108  ComApi          * START *   Search ClientId = NULL
2017.03.15 16:28:50.9487915 1272  1460  Agent           * START * Queueing Finding updates [CallerId = <<PROCESS>>: PowerShell_ISE.exe  Id = 1]
2017.03.15 16:28:50.9487964 1272  1460  Agent           Added service 00000000-0000-0000-0000-000000000000 to sequential scan list
2017.03.15 16:28:50.9489119 7936  8108  ComApi          Search ClientId = NULL
2017.03.15 16:28:50.9544385 1272  7744  Agent           * END * Queueing Finding updates [CallerId = <<PROCESS>>: PowerShell_ISE.exe  Id = 1]
2017.03.15 16:28:50.9600560 1272  7744  Agent           * START * Finding updates CallerId = <<PROCESS>>: PowerShell_ISE.exe  Id = 1
2017.03.15 16:28:50.9600565 1272  7744  Agent           Online = Yes; AllowCachedResults = No; Ignore download priority = No
2017.03.15 16:28:50.9600570 1272  7744  Agent           Criteria = IsInstalled = 0""
2017.03.15 16:28:50.9600593 1272  7744  Agent           ServiceID = {00000000-0000-0000-0000-000000000000} Third party service
2017.03.15 16:28:50.9600598 1272  7744  Agent           Search Scope = {Machine}
2017.03.15 16:28:50.9600624 1272  7744  Agent           Caller SID for Applicability: S-1-5-21-2917774113-1701131450-827999470-1001
2017.03.15 16:28:50.9600629 1272  7744  Agent           RegisterService is set
2017.03.15 16:28:50.9637993 1272  7744  Misc            Got 9482F4B4-E343-43B6-B170-9A65BC822C77 redir SecondaryServiceAuth URL: 117cab2d-82b1-4b5a-a08c-4d62dbee7782""
2017.03.15 16:28:50.9653294 1272  7744  SLS             CoCreateInstance Failed: hr = 0x80040154
2017.03.15 16:28:50.9653310 1272  7744  Agent           Failed to retrieve SLS response data for service 117cab2d-82b1-4b5a-a08c-4d62dbee7782, error = 0x80040154
2017.03.15 16:28:50.9653335 1272  7744  Agent           Caller Service Recovery failed to opt in to service 117cab2d-82b1-4b5a-a08c-4d62dbee7782, hr=0X80040154
2017.03.15 16:28:50.9666401 1272  7744  Misc            Got 9482F4B4-E343-43B6-B170-9A65BC822C77 redir Client/Server URL: https://fe2.update.microsoft.com/v6/ClientWebService/client.asmx""
2017.03.15 16:28:51.6330857 1272  7744  ProtocolTalker  ServiceId = {9482F4B4-E343-43B6-B170-9A65BC822C77}, Server URL = https://fe2.update.microsoft.com/v6/ClientWebService/client.asmx
2017.03.15 16:28:51.6332165 1272  7744  ProtocolTalker  OK to reuse existing configuration
2017.03.15 16:28:51.6332193 1272  7744  ProtocolTalker  Existing cookie is valid, just use it
2017.03.15 16:28:53.0396057 1272  7744  WebServices     Auto proxy settings for this web service call.
2017.03.15 16:28:53.7796366 1272  7744  ProtocolTalker  PTInfo: syncing with server using normal query
2017.03.15 16:28:54.2136172 1272  7744  ProtocolTalker  SyncUpdates round trips: 2
2017.03.15 16:28:54.2436626 1272  7744  Driver          Matched driver to device PCI\VEN_1002&DEV_4385&SUBSYS_43851849&REV_42
2017.03.15 16:28:54.2436636 1272  7744  Driver          Status: 0x1802000, ProblemNumber: 00000000
2017.03.15 16:28:54.2437126 1272  7744  Driver          DrvUtil: {PCI\VEN_1002&DEV_4385}
2017.03.15 16:28:54.2437865 1272  7744  Driver          Matched driver to device PCI\VEN_1B21&DEV_1242&SUBSYS_12421B21&REV_00
2017.03.15 16:28:54.2437870 1272  7744  Driver          Status: 0x180200a, ProblemNumber: 00000000
2017.03.15 16:28:54.2438429 1272  7744  Driver          DrvUtil: {pci\ven_1b21&dev_1242}
2017.03.15 16:29:11.1467281 7280  7704  AppAU           * START *
2017.03.15 16:29:11.1510405 7280  7704  AppAU           * START * Finding app updates
2017.03.15 16:29:11.1510915 7280  7704  AppAU           Desktop OOBE is complete.
2017.03.15 16:29:11.1511077 7280  7704  AppAU           Watching user key Software\Microsoft\Windows\CurrentVersion\AppReadiness\S-1-5-21-2917774113-1701131450-827999470-1001 for app readiness state
2017.03.15 16:29:11.1511085 7280  7704  AppAU           Waiting for UserState to match 3
2017.03.15 16:29:11.1511942 7280  7704  AppAU           UserState value is now 3. Exiting wait.
2017.03.15 16:29:11.1512465 7280  7704  AppAU           User apps are ready for update, waiting 15 minutes
2017.03.15 16:29:55.6965375 1272  7744  ProtocolTalker  ServiceId = {9482F4B4-E343-43B6-B170-9A65BC822C77}, Server URL = https://fe2.update.microsoft.com/v6/ClientWebService/client.asmx
2017.03.15 16:29:55.6965390 1272  7744  ProtocolTalker  OK to reuse existing configuration
2017.03.15 16:29:55.6965426 1272  7744  ProtocolTalker  Existing cookie is valid, just use it
2017.03.15 16:29:57.3967076 1272  7744  Agent           Update 75D781B0-E1B6-453B-BA52-0275FF36DFCF.201 is pruned out due to potential supersedence
2017.03.15 16:29:57.3967137 1272  7744  Agent           Update 1D3328B4-DFA2-458B-B5F5-4C7AD45965E7.203 is pruned out due to potential supersedence
2017.03.15 16:29:57.3967165 1272  7744  Agent           Update 54496A25-D94B-4A92-A5CB-623AA204ED4A.202 is pruned out due to potential supersedence
2017.03.15 16:29:57.3967317 1272  7744  Agent           Added update AAA40E10-31CA-4E78-B3C4-AE0EBD4FC63A.200 to search result
2017.03.15 16:29:57.3967343 1272  7744  Agent           Update 02FF2578-9044-4FD8-9BE4-CD9CB4841990.201 is pruned out due to potential supersedence
2017.03.15 16:29:57.3967368 1272  7744  Agent           Update E185084F-42E1-4453-85F2-48DCD98D725B.202 is pruned out due to potential supersedence
2017.03.15 16:29:57.3967394 1272  7744  Agent           Update 9137A6EE-704D-4F8E-8274-2E8E52996AF2.203 is pruned out due to potential supersedence
2017.03.15 16:29:57.3967422 1272  7744  Agent           Update 4E7C8E1A-DD64-4E39-B519-19A33D996E28.201 is pruned out due to potential supersedence
2017.03.15 16:29:57.3967445 1272  7744  Agent           Update 669E5E1A-9590-4153-BC1B-929BE3652E49.200 is pruned out due to potential supersedence
2017.03.15 16:29:57.3967471 1272  7744  Agent           Update 58111EC9-BB9F-48BE-903C-F2588BFE68EC.204 is pruned out due to potential supersedence
2017.03.15 16:29:57.3967496 1272  7744  Agent           Update 4EC4F56F-EE3C-4015-92CF-358750F8F0FD.201 is pruned out due to potential supersedence
2017.03.15 16:29:57.3967520 1272  7744  Agent           Update B6FE6C4A-089B-43BE-8CD8-EBAA394A9424.200 is pruned out due to potential supersedence
2017.03.15 16:29:57.3967545 1272  7744  Agent           Update C0393EE2-FFF8-4B15-8C25-6DF800BE98D1.203 is pruned out due to potential supersedence
2017.03.15 16:29:57.3967584 1272  7744  Agent           Added update E29D1B22-493D-44DD-8857-7C6C7CB6D84C.201 to search result
2017.03.15 16:29:57.3967635 1272  7744  Agent           Added update 1F47C2F1-C116-47FF-AEE4-BC0E1F52E664.202 to search result
2017.03.15 16:29:57.3967709 1272  7744  Agent           Found 3 updates and 24 categories in search; evaluated appl. rules of 1070 out of 1554 deployed entities
2017.03.15 16:29:57.4269240 1272  7744  Agent           * END * Finding updates CallerId = <<PROCESS>>: PowerShell_ISE.exe  Id = 1
2017.03.15 16:29:57.4406550 7936  7968  ComApi          *RESUMED* Search ClientId = NULL
2017.03.15 16:29:57.6105783 7936  7968  ComApi          Updates found = 3
2017.03.15 16:29:57.6105791 7936  7968  ComApi          * END *   Search ClientId = NULL
2017.03.15 16:29:57.6117585 7936  8108  ComApi          ISusInternal:: DisconnectCall failed, hr=8024000C

Please be so kind to close your Threads when you found an answer, these Threads should help everyone with similar issues.
You can close a Thread via the"Mark as Answer" link below posts. You can mark your own posts as answers if you were not helped out but found a solution, in such a case, please provide the answer.
Happy coding
PS: I assure everyone that I did not ever had the desire to offend anyone.


Thursday, March 16, 2017 5:49 AM

Hi MD,

What if you reset the Windows Update Component?

How do I reset Windows Update components?

https://support.microsoft.com/en-us/help/971058/how-do-i-reset-windows-update-components

Please remember to mark the replies as answers if they help.
If you have feedback for TechNet Subscriber Support, contact [email protected].


Thursday, March 16, 2017 3:45 PM

Thx for your reply,

does this solution address the specific problems deferred from the logs?

If so, could you please elaborate which messages point to this exact problem and what the "real" problem is?

Please be so kind to close your Threads when you found an answer, these Threads should help everyone with similar issues.
You can close a Thread via the"Mark as Answer" link below posts. You can mark your own posts as answers if you were not helped out but found a solution, in such a case, please provide the answer.
Happy coding
PS: I assure everyone that I did not ever had the desire to offend anyone.


Thursday, March 16, 2017 5:05 PM

Yippiee... another waste of time with the provided tool at least...

the relevant data from the log i guess:

2017.03.16 17:36:26.6031727 1288  3616  ComApi          Updates found = 3
2017.03.16 17:36:26.6031775 1288  3616  ComApi          Reboot required
2017.03.16 17:36:26.6031791 1288  3616  ComApi          * END *   Search ClientId = UpdateOrchestrator
2017.03.16 17:36:26.6116502 1288  4752  ComApi          ISusInternal:: DisconnectCall failed, hr=8024000C
2017.03.16 17:37:25.5192393 1288  8300  ComApi          * START *   Commit   ClientId = UpdateOrchestrator
2017.03.16 17:37:25.5199826 1288  8300  DownloadManager Preparing update for install, updateId = {F2DEE25D-029C-47AE-BAC6-D28DFD3372FF}.201.
2017.03.16 17:37:25.5625117 1512  5896  Handler         * START *   CBS Commit
2017.03.16 17:37:25.5625140 1512  5896  Handler         Nothing to commit for non-OSUpgrade CBS updates.
2017.03.16 17:37:25.5625146 1512  5896  Handler         * END *   CBS Commit
2017.03.16 17:37:25.5625569 1288  8300  Agent           Commit update {F2DEE25D-029C-47AE-BAC6-D28DFD3372FF}.201 HR=0
2017.03.16 17:37:25.5628945 1288  8300  ComApi          * END *   Commit   hr=0x00000000
2017.03.16 17:43:06.4520349 1288  1392  Agent           Earliest future timer found:
2017.03.16 17:43:06.4520459 1288  1392  Agent               Timer: 29A863E7-8609-4D1E-B7CD-5668F857F1DB, Expires 2017-03-16 20:32:21, not idle-only, not network-only
2017.03.16 17:43:06.4589244 1288  1392  Misc            CWUTaskManager | GetWUTaskFolder, could not create WU task folder hr=800706b5
2017.03.16 17:43:06.4589267 1288  1392  Misc            WUTaskManager couldn't register scheduled start task, hr=800706b5
2017.03.16 17:43:06.4589823 1288  1392  Handler         CUHCbsHandler::CancelDownloadRequest called
2017.03.16 17:43:06.4599906 1288  1392  Agent           Sending shutdown notification to client
2017.03.16 17:43:06.4600165 1288  1392  Agent           Sending shutdown notification to client
2017.03.16 17:43:06.4600404 1288  1392  Agent           Sending shutdown notification to client
2017.03.16 17:43:06.4600642 1288  1392  Agent           Sending shutdown notification to client
2017.03.16 17:43:06.5202350 1288  1392  Shared          * END * Service exit Exit code = 0x240001
2017.03.16 17:46:42.5930218 1296  4024  Agent           WU client version 10.0.14393.953
2017.03.16 17:46:42.5933297 1296  4024  Agent           SleepStudyTracker: Machine is non-AOAC. Sleep study tracker disabled.
2017.03.16 17:46:42.5934405 1296  4024  Agent           Base directory: C:\Windows\SoftwareDistribution
2017.03.16 17:46:42.5941984 1296  4024  Agent           Datastore directory: C:\Windows\SoftwareDistribution\DataStore\DataStore.edb
2017.03.16 17:46:42.6406628 1296  4024  Shared          UpdateNetworkState Ipv6, cNetworkInterfaces = 3.
2017.03.16 17:46:42.6406882 1296  4024  Shared          UpdateNetworkState Ipv4, cNetworkInterfaces = 1.
2017.03.16 17:46:42.6415099 1296  4024  Shared          Network state: Connected
2017.03.16 17:46:42.6608656 1296  4024  Misc            LoadHistoryEventFromRegistry completed, hr = 8024000C
2017.03.16 17:46:42.6627305 1296  4024  Shared          UpdateNetworkState Ipv6, cNetworkInterfaces = 3.
2017.03.16 17:46:42.6627394 1296  4024  Shared          UpdateNetworkState Ipv4, cNetworkInterfaces = 1.
2017.03.16 17:46:42.6627453 1296  4024  Shared          Power status changed
1601.01.01 01:00:00.0000000 1296  1044                  Unknown( 20): GUID=dda1a5dd-dd95-32c5-9bfe-a11d867d19d2 (No Format Information found).
1601.01.01 01:00:00.0000000 1296  1044                  Unknown( 21): GUID=dda1a5dd-dd95-32c5-9bfe-a11d867d19d2 (No Format Information found).
1601.01.01 01:00:00.0000000 1296  1044                  Unknown( 22): GUID=dda1a5dd-dd95-32c5-9bfe-a11d867d19d2 (No Format Information found).
1601.01.01 01:00:00.0000000 1296  1044                  Unknown( 23): GUID=dda1a5dd-dd95-32c5-9bfe-a11d867d19d2 (No Format Information found).
1601.01.01 01:00:00.0000000 1296  1044                  Unknown( 24): GUID=dda1a5dd-dd95-32c5-9bfe-a11d867d19d2 (No Format Information found).
1601.01.01 01:00:00.0000000 1296  1044                  Unknown( 25): GUID=dda1a5dd-dd95-32c5-9bfe-a11d867d19d2 (No Format Information found).
2017.03.16 17:46:42.6637431 1296  1044  Agent               Timer: 29A863E7-8609-4D1E-B7CD-5668F857F1DB, Expires 2017-03-16 20:32:21, not idle-only, not network-only
2017.03.16 17:46:42.6702126 1296  1044  Agent           Initializing Windows Update Agent
1601.01.01 01:00:00.0000000 1296  1044                  Unknown( 196): GUID=e62b40d0-122f-3ac6-8b50-0b2c053df757 (No Format Information found).
2017.03.16 17:46:42.6709071 1296  1044  Agent           CPersistentTimeoutScheduler | GetTimer, returned hr = 0x00000000
1601.01.01 01:00:00.0000000 1296  1044                  Unknown( 133): GUID=8ce98bfa-21f0-385f-839a-1073774029ac (No Format Information found).
2017.03.16 17:48:38.3884028 1296  1044  Handler         Got error querying the post-reboot status for session 30580338_4042936166: 0x80010108.
2017.03.16 17:48:38.4751520 1296  4024  Agent           Earliest future timer found:
2017.03.16 17:48:38.4751709 1296  4024  Agent               Timer: 29A863E7-8609-4D1E-B7CD-5668F857F1DB, Expires 2017-03-16 20:32:21, not idle-only, not network-only
2017.03.16 17:48:38.4897224 1296  4024  Misc            CWUTaskManager | GetWUTaskFolder, could not create WU task folder hr=800706b5
2017.03.16 17:48:38.4897414 1296  4024  Misc            WUTaskManager couldn't register scheduled start task, hr=800706b5
1601.01.01 01:00:00.0000000 1296  2836                  Unknown( 148): GUID=e62b40d0-122f-3ac6-8b50-0b2c053df757 (No Format Information found).
1601.01.01 01:00:00.0000000 1296  2836                  Unknown( 155): GUID=e62b40d0-122f-3ac6-8b50-0b2c053df757 (No Format Information found).
2017.03.16 17:48:38.6996269 1296  4024  Handler         CUHCbsHandler::CancelDownloadRequest called
1601.01.01 01:00:00.0000000 1296  2836                  Unknown( 156): GUID=e62b40d0-122f-3ac6-8b50-0b2c053df757 (No Format Information found).
2017.03.16 17:48:38.7994491 1296  2836  Shared          Effective power state: AC
1601.01.01 01:00:00.0000000 1296  2836                  Unknown( 78): GUID=e62b40d0-122f-3ac6-8b50-0b2c053df757 (No Format Information found).
1601.01.01 01:00:00.0000000 1296  4024                  Unknown( 24): GUID=44e91a30-0234-37f0-d2f9-777240b02428 (No Format Information found).
2017.03.16 17:48:39.8563931 1296  4024  Shared          * END * Service exit Exit code = 0x240001
2017.03.16 17:51:10.5192658 1268  3088  Agent           WU client version 10.0.14393.0
2017.03.16 17:51:10.5195894 1268  3088  Agent           SleepStudyTracker: Machine is non-AOAC. Sleep study tracker disabled.
2017.03.16 17:51:10.5196861 1268  3088  Agent           Base directory: C:\Windows\SoftwareDistribution
2017.03.16 17:51:10.5207200 1268  3088  Agent           Datastore directory: C:\Windows\SoftwareDistribution\DataStore\DataStore.edb
2017.03.16 17:51:10.5582807 1268  3088  Shared          UpdateNetworkState Ipv6, cNetworkInterfaces = 3.
2017.03.16 17:51:10.5583164 1268  3088  Shared          UpdateNetworkState Ipv4, cNetworkInterfaces = 1.
2017.03.16 17:51:10.5590912 1268  3088  Shared          Network state: Connected
2017.03.16 17:51:10.5653747 1268  3088  Misc            LoadHistoryEventFromRegistry completed, hr = 8024000C
2017.03.16 17:51:10.5671154 1268  3088  Shared          UpdateNetworkState Ipv6, cNetworkInterfaces = 3.
2017.03.16 17:51:10.5671241 1268  3088  Shared          UpdateNetworkState Ipv4, cNetworkInterfaces = 1.
2017.03.16 17:51:10.5671303 1268  3088  Shared          Power status changed
2017.03.16 17:51:10.5687081 1268  3088  Agent           Initializing global settings cache
2017.03.16 17:51:10.5687086 1268  3088  Agent           WSUS server: NULL
2017.03.16 17:51:10.5687089 1268  3088  Agent           WSUS status server: NULL
2017.03.16 17:51:10.5687094 1268  3088  Agent           Target group: (Unassigned Computers)
2017.03.16 17:51:10.5687099 1268  3088  Agent           Windows Update access disabled: No
2017.03.16 17:51:10.5694836 1268  3088  Agent               Timer: 29A863E7-8609-4D1E-B7CD-5668F857F1DB, Expires 2017-03-16 20:32:21, not idle-only, not network-only
2017.03.16 17:51:10.5774240 1268  3088  Agent           Initializing Windows Update Agent
2017.03.16 17:51:10.5778350 1268  3088  DownloadManager Download manager restoring 0 downloads

Please be so kind to close your Threads when you found an answer, these Threads should help everyone with similar issues.
You can close a Thread via the"Mark as Answer" link below posts. You can mark your own posts as answers if you were not helped out but found a solution, in such a case, please provide the answer.
Happy coding
PS: I assure everyone that I did not ever had the desire to offend anyone.


Thursday, March 16, 2017 6:06 PM

Can anyone even make sense of the logs as a whole?

I think you may be diving in too deeply too soon.  E.g. what are your clues from ReportingEvents.log?  If  Get-WindowsUpdateLog  is working that could be next, for some particular timestamp context that ReportingEvents would indicate.  Then maybe you would know where to look for something in CBS.log (or archived portions).

However, recently I have also found some concentrated hints available from diagerr.xml and they can put a completely different perspective on what you think might be going wrong.

C.f.
https://answers.microsoft.com/message/83e069ae-c849-479a-aa91-cc93957eb1e1

 

HTH

Robert Aldwinckle

https://answers.microsoft.com/en-us/insider/forum/insider_wintp-insider_install-insiderplat_pc/update-from-15002-to-15048-always-fails/0a47e3ca-9d0c-4559-921e-b2da086a19d2?messageId=83e069ae-c849-479a-aa91-cc93957eb1e1 


Thursday, March 16, 2017 6:20 PM | 1 vote

2017.03.16 17:43:06.4589267 1288  1392  Misc            WUTaskManager couldn't register scheduled start task, hr=800706b5
2017.03.16 17:43:06.4589823 1288  1392  Handler         CUHCbsHandler::CancelDownloadRequest called

PS>net helpmsg (0x6B5)

The interface is unknown.

When you used the Windows Update Troubleshooter did you allow it to delete the downloads directory?  Did that work?  Some use a manual simulation of what the WUT is supposedly doing.  You may feel more in control doing that.  Otherwise you can watch what is going on by using ProcMon.  Then make sure you don't close the troubleshooter before you analyze the ProcMon trace or some of your resources will be deleted.

Robert Aldwinckle


Thursday, March 16, 2017 10:53 PM

Hey,

"I think you may be diving in too deeply too soon"

Probably yeah, but there is no other way to solve my issue, Tools wont work, Windows Update wont work, all the Cummulative Updates will fail to install... i have no other possibility.

"If  Get-WindowsUpdateLog  is working that could be next"

The presented Logs are the Get-WindowsUpdateLog Results

thx for your effort and hints.

The ReportingEvents.log seems to have only information that i already know (a failed update):

{2EC8D888-B512-40ED-AE7B-138C02B82ACC} 2017-03-16 17:51:12:349+0100   1   182 [AGENT_INSTALLING_FAILED]   101 {E29D1B22-493D-44DD-8857-7C6C7CB6D84C}  201 800f0922    UpdateOrchestrator  Failure Content Install Installation Failure: Windows failed to install the following update with error 0x800f0922: Cumulative Update for Windows 10 Version 1607 for x64-based Systems (KB4013429).

This Timestamp has no counterpart in the "Get-WindowsUpdateLog":

2017.03.16 17:51:10.5671303 1268  3088  Shared          Power status changed
2017.03.16 17:51:10.5687081 1268  3088  Agent           Initializing global settings cache
2017.03.16 17:51:10.5687086 1268  3088  Agent           WSUS server: NULL
2017.03.16 17:51:10.5687089 1268  3088  Agent           WSUS status server: NULL
2017.03.16 17:51:10.5687094 1268  3088  Agent           Target group: (Unassigned Computers)
2017.03.16 17:51:10.5687099 1268  3088  Agent           Windows Update access disabled: No
2017.03.16 17:51:10.5694836 1268  3088  Agent               Timer: 29A863E7-8609-4D1E-B7CD-5668F857F1DB, Expires 2017-03-16 20:32:21, not idle-only, not network-only
2017.03.16 17:51:10.5774240 1268  3088  Agent           Initializing Windows Update Agent
2017.03.16 17:51:10.5778350 1268  3088  DownloadManager Download manager restoring 0 downloads
2017.03.16 17:51:10.5781770 1268  3088  Agent           CPersistentTimeoutScheduler | GetTimer, returned hr = 0x00000000
2017.03.16 17:51:10.5838653 1268  3088  Agent           Attempt 2 to obtain post-reboot results for event with cookie 30580338_4042936166.
2017.03.16 17:51:11.5469226 1268  3088  Handler         Post-reboot status for session 30580338_4042936166: 0x800f0922.
2017.03.16 17:51:12.4751276 1268  3264  DownloadManager PurgeExpiredFiles::Found 0 expired files to delete.
2017.03.16 17:51:12.4803054 1268  3264  DownloadManager PurgeExpiredUpdates::Found 395 non expired updates.
2017.03.16 17:51:12.5798045 1268  3264  DownloadManager PurgeExpiredUpdates::Found 0 expired updates.
2017.03.16 17:51:12.5938626 1268  3264  Shared          Effective power state: AC
2017.03.16 17:51:12.5938632 1268  3264  DownloadManager Power state change detected. Source now: AC

The CBS log is huge and full of:

2017-03-16 18:06:10, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - CBS_E_INVALID_PACKAGE]
2017-03-16 18:06:10, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]

if it would tell me what package, that'd be of help, but it doesn't...

anyways, those logs seem to be from the current startup (forced by windows updates) and the actual data about the update seems lost.

Regardless there are some weird errors like "00000a52 Couldn't mark for pending delete" up to "address" "00000f18" with "Resason: Cannot Delete".

I also see a ton of these "Current tick count lower than last tick count. [HRESULT = 0x8007000d - ERROR_INVALID_DATA]".

But well... all that was generated after the (failed) installation of the updates so i guess not really relevant?

I did let the Tool do all that it suggested as a "Fix" (i think i can remember a checkbox about the SoftwareDistribution folder beeing cleaned).

This is the frst time i get in contact with such logs (the CBS log scares me, there are up to 3000 (three thousand) entries in just 1 Second, either that is really just some overexcessive logging or something siriously wrong)

The only diagerr.xml files i could find are too old to add any information about this issue (LasModified in Februrary), however one of the files contained an interesting entry (although that Path in there points to an old HardDrive with a Windows 8.1 installation that has nothing to do with this system, the hard driveis only connected, the installation on there is not even made on this machine. that disk was not present at the date of the installation of the current system):

<rs:data>
<z:row Cls="D" Sev="33554432" Maj="Def" Min="Def" LN="1479" Fil="" Fun="SclLogCallback" Uid="33949358" Msg="[SetupCl lib
rary] Required profile hive does not exist: [\??\G:\WINDOWS\system32\config\systemprofile\NTUSER.DAT]." PID="956" TID="6
24" Con="" Exe="X:\Sources\setup.exe" Mod="WinSetup.dll" Err="0" MD="" DT="2017-02-02T22:01:24"/>
</rs:data>

What would you advice me to do to resolve my problem or to find where it is actually happening?

PS: in the thread of the link you posted, there seems to be someone with some issue and is asking if anyone has the same setup (GPT, UEFI, NonWindows BootLoader) i do have these in common (except for the boot loader)

Please be so kind to close your Threads when you found an answer, these Threads should help everyone with similar issues.
You can close a Thread via the"Mark as Answer" link below posts. You can mark your own posts as answers if you were not helped out but found a solution, in such a case, please provide the answer.
Happy coding
PS: I assure everyone that I did not ever had the desire to offend anyone


Friday, March 17, 2017 3:52 AM | 1 vote

The ReportingEvents.log seems to have only information that i already know (a failed update):

{2EC8D888-B512-40ED-AE7B-138C02B82ACC}  2017-03-16 17:51:12:349+0100   1   182 [AGENT_INSTALLING_FAILED]   101 {E29D1B22-493D-44DD-8857-7C6C7CB6D84C}  201 800f0922    UpdateOrchestrator  Failure Content Install Installation Failure: Windows failed to install the following update with error 0x800f0922: Cumulative Update for Windows 10 Version 1607 for x64-based Systems (KB4013429).

 

Bingo!  error 0x800f0922  That's CBS.  Go into it at that time and you will see what it is complaining about.  As you noticed you will probably have to go into one of the archived pieces of it.

BTW regarding doing finds in logs I pointed out somewhere recently that I have found using a Regular Expression with conditional negation is a real help.  The example I gave was  Fail(?!o) in order to go backwards finding Failed or Failing but skipping over Failover.  So similarly, I pointed out that they could "skip over a patch of  Failed ... 0x800F0805 ..."  by using  Fail(?!o)(?!.*805)  I use ISE for this.

It's too bad you don't have any diagerr.xml because it does have the detail that you want.  E.g. in the example we found that the errors were being generated by a setup program, so then that was a clue to look at the setup logs.  Etc.   However, I just checked mine since I have been doing a bunch of updates this evening after my Hyper-V got wrecked by one (again).  So, evidently the diagerr.xml don't get used as much by updates as they do by upgrades.   FWIW here is the pipeline I used to check and see this

PS>cd\
PS>LS -Re -Filt "diagerr.xml" -Fo -Ea:SilentlyContinue | sort LastWriteTime | ft LastWriteTime, Length, FullName -AutoSize

Apparently I have some errors that SetupCleanupTask always detects during updates but if those weren't present I would only see the empty shell for that one too.

This list could give you some perspective on what else could be available if done ASAP after an error you wanted more detail about

PS>gl

Path

C:\windows\logs


PS>dir -Re * | sort LastWriteTime | ft LastWriteTime, FullName -AutoSize

Robert Aldwinckle


Friday, March 17, 2017 7:47 AM

Hey again,

okay, so (with your help) i can finally make sense of (SOME!!) those 0x800f errors (i always opened the not even complete error list to look it up but that is barely user friendly) with net helpmsg and the important Braces around the important partial part of it (this is metioned nowhere). Thx for this (, and goddam why cant microsoft put that info on the site i used to search?!, i know its not your fault, just writing frustration off of my soul)

So i looked into the cab file (at least they finally made it accessible by default instead of having to use a third party tool to access files that miscrosoft windows constantly works with). 

I see those (some repeated in slight variations):

  • Failed to internally open package. [HRESULT = 0x800f0805 (<- Error Code has no message apperently)
  • 2017-03-16 17:29:45, Info                  DPX    Extraction of file: update.ses failed because it is not present in the container

and apperntly the one and only Error log in that whole mess (everything else is logged under loglevel Info, why ever that is done, only makes things harder):

  • 2017-03-16 17:35:59, Error                 CBS    Session: 30580339_1199728715 failed to perform Synchronous Cleanup operation [HRESULT = 0x800f0806 - CBS_E_PENDING]

inside this:

2017-03-16 17:35:59, Info                  CBS    Session: 30580339_1693729833 initialized by client WindowsUpdateAgent, external staging directory: (null), external registry directory: (null
2017-03-16 17:35:59, Info                  CBS    No call is made on InitiateChanges, Synchronous Cleanup only.
2017-03-16 17:35:59, Info                  CBS    Maint: processing started.  Client: DISM Package Manager Provider, Session: 30580339_1199728715
2017-03-16 17:35:59, Info                  CBS    Maint: launch type: CleanupWizard
2017-03-16 17:35:59, Info                  CBS    Maint: Pending content found, aborting maintenance
2017-03-16 17:35:59, Info                  CBS    Maint: completed, interruptions: 0, (this pass: time: 0 seconds, used space change: 0 KB, hr: 0x800f0806, source: Other)
2017-03-16 17:35:59, Info                  CBS    SQM: Upload requested for report: CBSScavengeSession, session id: 101457977, sample type: Standard
2017-03-16 17:35:59, Info                  CBS    SQM: Ignoring upload request because SQM is not initialized or not running online.
2017-03-16 17:35:59, Info                  CBS    Maint: processing complete.  Session: 30580339_1199728715. [HRESULT = 0x800f0806 - CBS_E_PENDING]
2017-03-16 17:35:59, Error                 CBS    Session: 30580339_1199728715 failed to perform Synchronous Cleanup operation [HRESULT = 0x800f0806 - CBS_E_PENDING]
2017-03-16 17:35:59, Info                  CBS    Received notification that the operation is complete.
2017-03-16 17:35:59, Info                  CBS    Session: 30580339_1199728715 finalized. Reboot required: yes [HRESULT = 0x800f0806 - CBS_E_PENDING]
2017-03-16 17:35:59, Info                  CBS    Failed to FinalizeEx using worker session [HRESULT = 0x800f0806]
2017-03-16 17:35:59, Info                  CBS    progress thread terminated normally
2017-03-16 17:36:00, Info                  CBS    Session: 30580339_1694839902 initialized by client DISM Package Manager Provider, external staging directory: (null), external registry directory: (null

seems like its not a real error even...

and this is where the Update gets marked as failed as it seems:

2017-03-16 17:40:43, Info                  CBS    Exec: Install package: Package_6080_for_KB4013429~31bf3856ad364e35~amd64~~10.0.1.2
2017-03-16 17:40:43, Info                  CBS    Exec: Install package: Package_6080_for_KB4013429~31bf3856ad364e35~amd64~~10.0.1.2, Update: 4013429-12190_neutral
2017-03-16 17:40:43, Info                  CBS    Exec: Installing Package: Package_6080_for_KB4013429~31bf3856ad364e35~amd64~~10.0.1.2, Update: 4013429-12190_neutral, InstallDeployment: amd64_bb5a7773b65c3978ec0af6cfac173d7a_31bf3856ad364e35_10.0.14393.351_none_93564b8940a309fb
2017-03-16 17:40:43, Info                  CSI    000014ab Performing 1 operations as follows:
  (0)  AddCat: flags: 1 catfile: @0x204833280c0
2017-03-16 17:40:43, Info                  CBS    Exec: The Package or one of its Updates required a reboot so transaction commit was skipped, Package's changes need to be pended.
2017-03-16 17:40:43, Info                  CBS    Setting ExecuteState key to: CbsExecuteStateFailed
2017-03-16 17:40:43, Info                  CBS    Attempting to remove poqexec from SetupExecute
2017-03-16 17:40:43, Info                  CBS    Removed poqexec from SetupExecute.
2017-03-16 17:40:43, Info                  CBS    Configured poqexec to not pend to SetupExecute.
2017-03-16 17:40:45, Info                  CSI    000014ac Performing 2635 operations as follows:

but there is no information about the "why"

Please be so kind to close your Threads when you found an answer, these Threads should help everyone with similar issues.
You can close a Thread via the"Mark as Answer" link below posts. You can mark your own posts as answers if you were not helped out but found a solution, in such a case, please provide the answer.
Happy coding
PS: I assure everyone that I did not ever had the desire to offend anyone.


Friday, March 17, 2017 7:58 AM

Then finally some information, here is the complete "Get-Content .\CBS.log | Select-String Fail[^o]" (the [^o] is easier to remember and understand, also no groups need to be created) Output:

2017-03-15 19:44:48, Info                  CBS    Setting ExecuteState key to: CbsExecuteStateFailed
2017-03-15 19:44:50, Info                  CBS    Setting RollbackFailed flag to 0
2017-03-15 19:44:50, Info                  CBS    Reporting package change for package: 
Microsoft-Windows-InternetExplorer-Optional-Package~31bf3856ad364e35~amd64~~11.0.14393.0, current: Installed, pending: 
Default, start: Installed, applicable: Installed, target: Installed, limit: Installed, hotpatch status: StillGoing, 
status: 0x0, failure source: Not Applicable, reboot required: True, client id: Windows Optional Component Manager, 
initiated offline: False, execution sequence: 42, first merged sequence: 42, reboot reason: 
STATICMARKING_REBOOTREQUIRED, RM App session: -1, RM App name: N/A, FileName in use: N/A, release type: Feature Pack, 
release quality: final, OC operation: True, download source: 0, download time (secs): 4294967295, download status: 0x0 
(S_OK), Express download: False, Download Size: 0
2017-03-15 19:44:50, Info                  CBS    Reporting package change for package: 
Microsoft-Windows-MediaPlayback-OC-Package~31bf3856ad364e35~amd64~~10.0.14393.0, current: Installed, pending: Default, 
start: Installed, applicable: Installed, target: Installed, limit: Installed, hotpatch status: StillGoing, status: 
0x0, failure source: Not Applicable, reboot required: True, client id: Windows Optional Component Manager, initiated 
offline: False, execution sequence: 42, first merged sequence: 42, reboot reason: STATICMARKING_REBOOTREQUIRED, RM App 
session: -1, RM App name: N/A, FileName in use: N/A, release type: Feature Pack, release quality: final, OC operation: 
True, download source: 0, download time (secs): 4294967295, download status: 0x0 (S_OK), Express download: False, 
Download Size: 0
2017-03-15 19:44:50, Info                  CBS    Reporting package change for package: 
Microsoft-Windows-MediaPlayer-Package~31bf3856ad364e35~amd64~~10.0.14393.0, current: Installed, pending: Default, 
start: Installed, applicable: Installed, target: Installed, limit: Installed, hotpatch status: StillGoing, status: 
0x0, failure source: Not Applicable, reboot required: True, client id: Windows Optional Component Manager, initiated 
offline: False, execution sequence: 42, first merged sequence: 42, reboot reason: STATICMARKING_REBOOTREQUIRED, RM App 
session: -1, RM App name: N/A, FileName in use: N/A, release type: Feature Pack, release quality: final, OC operation: 
True, download source: 0, download time (secs): 4294967295, download status: 0x0 (S_OK), Express download: False, 
Download Size: 0
2017-03-15 19:44:50, Info                  CBS    Reporting package change for package: 
Microsoft-Windows-EnterpriseClientSync-Host-Package~31bf3856ad364e35~amd64~~10.0.14393.0, current: Installed, pending: 
Default, start: Installed, applicable: Installed, target: Installed, limit: Installed, hotpatch status: StillGoing, 
status: 0x0, failure source: Not Applicable, reboot required: True, client id: Windows Optional Component Manager, 
initiated offline: False, execution sequence: 42, first merged sequence: 42, reboot reason: 
STATICMARKING_REBOOTREQUIRED, RM App session: -1, RM App name: N/A, FileName in use: N/A, release type: Feature Pack, 
release quality: final, OC operation: True, download source: 0, download time (secs): 4294967295, download status: 0x0 
(S_OK), Express download: False, Download Size: 0
2017-03-15 21:23:10, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-15 21:23:10, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-15 21:23:10, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-15 21:23:10, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-15 23:44:57, Info                  CBS    Reporting poqexec status with status: 0x0, failed file: (null), 
interfering process: (null), context: Shutdown, first merged sequence: 42
2017-03-15 23:45:31, Info                  CBS    Retrieved original failure status: 0x00000000, last forward execute 
state: CbsExecuteStateStageDrivers
2017-03-15 23:45:36, Info                  CBS    Reporting package change completion for package: 
Microsoft-Windows-EnterpriseClientSync-Host-Package~31bf3856ad364e35~amd64~~10.0.14393.0, current: Installed, 
original: Installed, target: Installed, status: 0x0, failure source: Not Applicable, failure details: "(null)", client 
id: Windows Optional Component Manager, initiated offline: False, execution sequence: 42, first merged sequence: 42, 
pending decision: PackagePended_NoSmartPendingFlagSet, primitive execution context: Shutdown Flight: False
2017-03-15 23:45:36, Info                  CBS    Reporting package change completion for package: 
Microsoft-Windows-InternetExplorer-Optional-Package~31bf3856ad364e35~amd64~~11.0.14393.0, current: Installed, 
original: Installed, target: Installed, status: 0x0, failure source: Not Applicable, failure details: "(null)", client 
id: Windows Optional Component Manager, initiated offline: False, execution sequence: 42, first merged sequence: 42, 
pending decision: PackagePended_NoSmartPendingFlagSet, primitive execution context: Shutdown Flight: False
2017-03-15 23:45:36, Info                  CBS    Reporting package change completion for package: 
Microsoft-Windows-MediaPlayback-OC-Package~31bf3856ad364e35~amd64~~10.0.14393.0, current: Installed, original: 
Installed, target: Installed, status: 0x0, failure source: Not Applicable, failure details: "(null)", client id: 
Windows Optional Component Manager, initiated offline: False, execution sequence: 42, first merged sequence: 42, 
pending decision: PackagePended_NoSmartPendingFlagSet, primitive execution context: Shutdown Flight: False
2017-03-15 23:45:36, Info                  CBS    Reporting package change completion for package: 
Microsoft-Windows-MediaPlayer-Package~31bf3856ad364e35~amd64~~10.0.14393.0, current: Installed, original: Installed, 
target: Installed, status: 0x0, failure source: Not Applicable, failure details: "(null)", client id: Windows Optional 
Component Manager, initiated offline: False, execution sequence: 42, first merged sequence: 42, pending decision: 
PackagePended_NoSmartPendingFlagSet, primitive execution context: Shutdown Flight: False
2017-03-15 23:45:36, Info                  CBS    Clearing original failure status: 0x00000000
2017-03-15 23:45:36, Info                  CBS    Setting RollbackFailed flag to 0
2017-03-15 23:45:37, Info                  CBS    Startup: Retrying failed packages.
2017-03-16 16:36:36, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-16 16:36:36, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-16 16:36:36, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-16 16:36:36, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-16 17:27:35, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-16 17:27:35, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-16 17:27:35, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-16 17:27:35, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-16 17:28:38, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-16 17:28:38, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-16 17:28:38, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-16 17:28:38, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-16 17:28:59, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-16 17:28:59, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-16 17:28:59, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-16 17:28:59, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-16 17:29:07, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-16 17:29:07, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-16 17:29:07, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-16 17:29:07, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-16 17:29:22, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-16 17:29:22, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-16 17:29:22, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-16 17:29:22, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-16 17:29:45, Info                  DPX    Extraction of file: update.ses failed because it is not present in 
the container 
(\\?\C:\Windows\SoftwareDistribution\Download\990d8bb4ab99378b8c039a5600702e5c\Windows10.0-KB4013429-x64-EXPRESS.cab).
2017-03-16 17:35:04, Info                  CBS    Failed performing maintenance task [HRESULT = 0x800f0806 - 
CBS_E_PENDING]
2017-03-16 17:35:04, Info                  CBS    Warning: Failed while executing service idle processing. [HRESULT = 
0x800f0806 - CBS_E_PENDING]
2017-03-16 17:35:04, Info                  CBS    Failed to run idle scavenge [HRESULT = 0x800f0806 - CBS_E_PENDING]
2017-03-16 17:35:04, Info                  CBS    Warning: Failed to execute idle scavenge start. Error code: 
0X800f0806 [HRESULT = 0x800f0806]
2017-03-16 17:35:07, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-16 17:35:07, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-16 17:35:07, Info                  CBS    Failed to internally open package. [HRESULT = 0x800f0805 - 
CBS_E_INVALID_PACKAGE]
2017-03-16 17:35:07, Info                  CBS    Failed to OpenPackage using worker session [HRESULT = 0x800f0805]
2017-03-16 17:35:59, Error                 CBS    Session: 30580339_1199728715 failed to perform Synchronous Cleanup 
operation [HRESULT = 0x800f0806 - CBS_E_PENDING]
2017-03-16 17:35:59, Info                  CBS    Failed to FinalizeEx using worker session [HRESULT = 0x800f0806]
2017-03-16 17:40:43, Info                  CBS    Setting ExecuteState key to: CbsExecuteStateFailed
2017-03-16 17:41:55, Info                  CBS    Setting RollbackFailed flag to 0
2017-03-16 17:41:55, Info                  CBS    Setting RollbackFailed flag to 0
2017-03-16 17:42:15, Info                  CBS    Reporting package change for package: 
Package_for_RollupFix~31bf3856ad364e35~amd64~~14393.953.1.2, current: Staged, pending: Default, start: Staged, 
applicable: Installed, target: Installed, limit: Installed, hotpatch status: StillGoing, status: 0x0, failure source: 
Not Applicable, reboot required: True, client id: WindowsUpdateAgent, initiated offline: False, execution sequence: 
46, first merged sequence: 46, reboot reason: DRIVERSPRESENT, RM App session: -1, RM App name: N/A, FileName in use: 
N/A, release type: Security Update, release quality: final, OC operation: False, download source: 0, download time 
(secs): 4294967295, download status: 0x0 (S_OK), Express download: False, Download Size: 0
2017-03-16 17:42:22, Info                  CBS    DriverUpdateInstallUpdates failed [HRESULT = 0x80070bc2 - 
ERROR_SUCCESS_REBOOT_REQUIRED]
2017-03-16 17:43:11, Info                  CBS    Reporting poqexec status with status: 0x0, failed file: (null), 
interfering process: (null), context: Shutdown, first merged sequence: 46
2017-03-16 17:43:43, Info                  CBS    Retrieved original failure status: 0x00000000, last forward execute 
state: CbsExecuteStateStageDrivers
2017-03-16 17:46:30, Error      [0x018051] CSI    00000960 (F) Failed execution of queue item Installer: AppX 
Registration Installer ({f1aab1ba-6ee0-4d94-baeb-c9fd61f365cf}) with HRESULT HRESULT_FROM_WIN32(1058).  Failure will 
not be ignored: A rollback will be initiated after all the operations in the installer queue are completed; installer 
is reliable[gle=0x80004005]
    Completion status: HRESULT_FROM_WIN32(ERROR_ADVANCED_INSTALLER_FAILED) 
2017-03-16 17:46:30, Error      [0x018051] CSI    0000096e (F) Failed execution of queue item Installer: AppX 
Registration Installer ({f1aab1ba-6ee0-4d94-baeb-c9fd61f365cf}) with HRESULT HRESULT_FROM_WIN32(1058).  Failure will 
not be ignored: A rollback will be initiated after all the operations in the installer queue are completed; installer 
is reliable[gle=0x80004005]
    Completion status: HRESULT_FROM_WIN32(ERROR_ADVANCED_INSTALLER_FAILED) 
2017-03-16 17:46:30, Error      [0x018051] CSI    00000987 (F) Failed execution of queue item Installer: AppX 
Registration Installer ({f1aab1ba-6ee0-4d94-baeb-c9fd61f365cf}) with HRESULT HRESULT_FROM_WIN32(1058).  Failure will 
not be ignored: A rollback will be initiated after all the operations in the installer queue are completed; installer 
is reliable[gle=0x80004005]
    Completion status: HRESULT_FROM_WIN32(ERROR_ADVANCED_INSTALLER_FAILED) 
2017-03-16 17:46:33, Error                 CBS    Startup: Failed to process advanced operation queue, startupPhase: 
0.  A rollback transaction will be created. [HRESULT = 0x800f0922 - CBS_E_INSTALLERS_FAILED]
2017-03-16 17:46:33, Info                  CBS    Setting ExecuteState key to: CbsExecuteStateInitiateRollback | 
CbsExecuteStateFlagAdvancedInstallersFailed
2017-03-16 17:46:33, Info                  CBS    SetProgressMessage: progressMessageStage: -1, ExecuteState: 
CbsExecuteStateInitiateRollback | CbsExecuteStateFlagAdvancedInstallersFailed, SubStage: 0
2017-03-16 17:46:33, Info                  CBS    Setting original failure status: 0x800f0922, last forward execute 
state: CbsExecuteStateResolvePending
2017-03-16 17:47:45, Info                  CBS    Startup: Failed to process advanced operation queue.  Drivers and 
primitives will be rolled back and a restart initiated.
2017-03-16 17:47:45, Info                  CBS    Setting ExecuteState key to: CbsExecuteStateUnstageDrivers | 
CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed
2017-03-16 17:47:45, Info                  CBS    SetProgressMessage: progressMessageStage: -1, ExecuteState: 
CbsExecuteStateUnstageDrivers | CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed, SubStage: 0
2017-03-16 17:47:45, Info                  CBS    SetProgressMessage: progressMessageStage: -1, ExecuteState: 
CbsExecuteStateUnstageDrivers | CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed, SubStage: 1
2017-03-16 17:47:45, Info                  CBS    Setting ExecuteState key to: CbsExecuteStatePrimitives | 
CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed
2017-03-16 17:47:45, Info                  CBS    SetProgressMessage: progressMessageStage: -1, ExecuteState: 
CbsExecuteStatePrimitives | CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed, SubStage: 0
2017-03-16 17:47:45, Info                  CBS    Setting ExecuteState key to: CbsExecuteStatePrimitives | 
CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed
2017-03-16 17:47:45, Info                  CBS    SetProgressMessage: progressMessageStage: -1, ExecuteState: 
CbsExecuteStatePrimitives | CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed, SubStage: 2
2017-03-16 17:47:50, Info                  CBS    DriverUpdateUninstallUpdates failed [HRESULT = 0x80070bc2 - 
ERROR_SUCCESS_REBOOT_REQUIRED]
2017-03-16 17:47:50, Info                  CBS    Setting ExecuteState key to: CbsExecuteStatePrimitives | 
CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed [2]
2017-03-16 17:47:50, Info                  CBS    SetProgressMessage: progressMessageStage: -1, ExecuteState: 
CbsExecuteStatePrimitives | CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed [2], SubStage: 3
2017-03-16 17:48:37, Info                  CBS    Setting ExecuteState key to: CbsExecuteStateStageDrivers | 
CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed
2017-03-16 17:48:37, Info                  CBS    Current global progress. Current: 93, Limit: 105, ExecuteState: 
CbsExecuteStateStageDrivers | CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed
2017-03-16 17:48:37, Info                  CBS    Saved last global progress. Current: 93, Limit: 105, ExecuteState: 
CbsExecuteStateStageDrivers | CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed
2017-03-16 17:48:38, Info                  CBS    Startup: Failed, restart required to try again.
2017-03-16 17:48:38, Info                  CBS    Failed to initialize TiWorker. [HRESULT = 0x80010108]
2017-03-16 17:48:38, Info                  CBS    Failed to get worker process. [HRESULT = 0x80010108]
2017-03-16 17:48:38, Info                  CBS    Failed to create worker session. [HRESULT = 0x80010108]
2017-03-16 17:48:38, Info                  CBS    Failed to get worker session. [HRESULT = 0x80010108]
2017-03-16 17:49:08, Info                  CBS    Retrieved original failure status: 0x800f0922, last forward execute 
state: CbsExecuteStateResolvePending
2017-03-16 17:49:08, Info                  CBS    Startup: current ExecuteState is CbsExecuteStateStageDrivers | 
CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed
2017-03-16 17:49:08, Info                  CBS    SetProgressMessage: progressMessageStage: 0, ExecuteState: 
CbsExecuteStateStageDrivers | CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed, SubStage: 0
2017-03-16 17:49:09, Info                  CBS    SetProgressMessage: progressMessageStage: -1, ExecuteState: 
CbsExecuteStateStageDrivers | CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed, SubStage: 0
2017-03-16 17:49:09, Info                  CBS    SetProgressMessage: progressMessageStage: -1, ExecuteState: 
CbsExecuteStateStageDrivers | CbsExecuteStateFlagRollback | CbsExecuteStateFlagAdvancedInstallersFailed, SubStage: 1
2017-03-16 17:49:52, Error      [0x018051] CSI    00000983 (F) Failed execution of queue item Installer: AppX 
Registration Installer ({f1aab1ba-6ee0-4d94-baeb-c9fd61f365cf}) with HRESULT HRESULT_FROM_WIN32(1058).  Failure will 
be ignored: The failure was encountered during rollback; installer is reliable[gle=0x80004005]
2017-03-16 17:49:52, Error      [0x018051] CSI    0000098d (F) Failed execution of queue item Installer: AppX 
Registration Installer ({f1aab1ba-6ee0-4d94-baeb-c9fd61f365cf}) with HRESULT HRESULT_FROM_WIN32(1058).  Failure will 
be ignored: The failure was encountered during rollback; installer is reliable[gle=0x80004005]
2017-03-16 17:49:52, Error      [0x018051] CSI    000009a6 (F) Failed execution of queue item Installer: AppX 
Registration Installer ({f1aab1ba-6ee0-4d94-baeb-c9fd61f365cf}) with HRESULT HRESULT_FROM_WIN32(1058).  Failure will 
be ignored: The failure was encountered during rollback; installer is reliable[gle=0x80004005]
2017-03-16 17:51:04, Info                  CBS    Setting ExecuteState key to: CbsExecuteStateFailed
2017-03-16 17:51:04, Info                  CBS    SetProgressMessage: progressMessageStage: -1, ExecuteState: 
CbsExecuteStateFailed, SubStage: 0
2017-03-16 17:51:09, Info                  CBS    Saved last global progress. Current: 1, Limit: 1, ExecuteState: 
CbsExecuteStateFailed
2017-03-16 17:51:09, Info                  CBS    WER: Generating failure report for package: 
Package_for_RollupFix~31bf3856ad364e35~amd64~~14393.953.1.2, status: 0x80070422, failure source: AI, start state: 
Staged, target state: Installed, client id: WindowsUpdateAgent
2017-03-16 17:51:09, Info                  CBS    Reporting package change completion for package: 
Package_for_RollupFix~31bf3856ad364e35~amd64~~14393.953.1.2, current: Staged, original: Staged, target: Installed, 
status: 0x80070422, failure source: AI, failure details: "AppX Registration Installer  80070422    38  Install (upgrade)   
Microsoft-Windows-Cortana.Desktop.AppxSetup, Culture=neutral, PublicKeyToken=31bf3856ad364e35, 
ProcessorArchitecture=amd64, versionScope=NonSxS    ", client id: WindowsUpdateAgent, initiated offline: False, execution 
sequence: 46, first merged sequence: 46, pending decision: Unknown, primitive execution context: Shutdown Flight: False
2017-03-16 17:51:10, Info                  CBS    Clearing original failure status: 0x00000000
2017-03-16 17:51:10, Info                  CBS    Setting RollbackFailed flag to 0
2017-03-16 17:51:10, Info                  CBS    Startup: Retrying failed packages.
2017-03-16 17:51:10, Info                  CBS    Startup: Processing complete. [HRESULT = 0x800f0922 - 
CBS_E_INSTALLERS_FAILED]

AppX updates fail as it seems

Just so i got everything right:

The AppX Service must be the Problem right? since:

2017-03-16 17:51:09, Info                  CBS    WER: Generating failure report for package: 
Package_for_RollupFix~31bf3856ad364e35~amd64~~14393.953.1.2, status: 0x80070422, failure source: AI, start state: 
Staged, target state: Installed, client id: WindowsUpdateAgent
2017-03-16 17:51:09, Info                  CBS    Reporting package change completion for package: 
Package_for_RollupFix~31bf3856ad364e35~amd64~~14393.953.1.2, current: Staged, original: Staged, target: Installed, 
status: 0x80070422, failure source: AI, failure details: "AppX Registration Installer  80070422    38  Install (upgrade)   
Microsoft-Windows-Cortana.Desktop.AppxSetup, Culture=neutral, PublicKeyToken=31bf3856ad364e35, 
ProcessorArchitecture=amd64, versionScope=NonSxS    ", client id: WindowsUpdateAgent, initiated offline: False, execution 
sequence: 46, first merged sequence: 46, pending decision: Unknown, primitive execution context: Shutdown Flight: False

There is a WER log mentioned though, where that error should have been logged to, can you tell me where that is?

AND BIG THX MAN! This was really helpful and informative... if you know any other error sources, tricks or stuf that can help finding issues, please let me know ;-)

Please be so kind to close your Threads when you found an answer, these Threads should help everyone with similar issues.
You can close a Thread via the"Mark as Answer" link below posts. You can mark your own posts as answers if you were not helped out but found a solution, in such a case, please provide the answer.
Happy coding
PS: I assure everyone that I did not ever had the desire to offend anyone.


Friday, March 17, 2017 8:32 AM

Here is a list of Disabled Services, i don't know which one the "AppX Registration Installer" needs:

PS C:> Get-Service | Select-Object StartType, ServiceName, Name, DisplayName | Wher
e-Object StartType -EQ Disabled

StartType ServiceName      Name             DisplayName
                    
 Disabled AJRouter         AJRouter         AllJoyn Router Service
 Disabled ALG              ALG              Application Layer Gateway Service
 Disabled AppMgmt          AppMgmt          Application Management
 Disabled AppVClient       AppVClient       Microsoft App-V Client
 Disabled BDESVC           BDESVC           BitLocker Drive Encryption Service
 Disabled BITS             BITS             Background Intelligent Transfer Service
 Disabled BthHFSrv         BthHFSrv         Bluetooth Handsfree Service
 Disabled bthserv          bthserv          Bluetooth Support Service
 Disabled CertPropSvc      CertPropSvc      Certificate Propagation
 Disabled CscService       CscService       Offline Files
 Disabled DcpSvc           DcpSvc           DataCollectionPublishingService
 Disabled defragsvc        defragsvc        Optimize drives
 Disabled DevQueryBroker   DevQueryBroker   DevQuery Background Discovery Broker
 Disabled DiagTrack        DiagTrack        Connected User Experiences and Telemetry
 Disabled DsmSvc           DsmSvc           Device Setup Manager
 Disabled Fax              Fax              Fax
 Disabled fhsvc            fhsvc            File History Service
 Disabled FrameServer      FrameServer      Windows Camera Frame Server
 Disabled icssvc           icssvc           Windows Mobile Hotspot Service
 Disabled lfsvc            lfsvc            Geolocation Service
 Disabled MapsBroker       MapsBroker       Downloaded Maps Manager
 Disabled NcbService       NcbService       Network Connection Broker
 Disabled PeerDistSvc      PeerDistSvc      BranchCache
 Disabled PhoneSvc         PhoneSvc         Phone Service
 Disabled RemoteAccess     RemoteAccess     Routing and Remote Access
 Disabled RemoteRegistry   RemoteRegistry   Remote Registry
 Disabled RmSvc            RmSvc            Radio Management Service
 Disabled SCardSvr         SCardSvr         Smart Card
 Disabled ScDeviceEnum     ScDeviceEnum     Smart Card Device Enumeration Service
 Disabled SCPolicySvc      SCPolicySvc      Smart Card Removal Policy
 Disabled ShellHWDetection ShellHWDetection Shell Hardware Detection
 Disabled shpamsvc         shpamsvc         Shared PC Account Manager
 Disabled SmsRouter        SmsRouter        Microsoft Windows SMS Router Service.
 Disabled TapiSrv          TapiSrv          Telephony
 Disabled tzautoupdate     tzautoupdate     Auto Time Zone Updater
 Disabled UevAgentService  UevAgentService  User Experience Virtualization Service
 Disabled wbengine         wbengine         Block Level Backup Engine Service
 Disabled WbioSrvc         WbioSrvc         Windows Biometric Service
 Disabled Wcmsvc           Wcmsvc           Windows Connection Manager
 Disabled wcncsvc          wcncsvc          Windows Connect Now - Config Registrar
 Disabled wercplsupport    wercplsupport    Problem Reports and Solutions Control Panel Support
 Disabled wisvc            wisvc            Windows Insider Service
 Disabled wlidsvc          wlidsvc          Microsoft Account Sign-in Assistant
 Disabled XblAuthManager   XblAuthManager   Xbox Live Auth Manager
 Disabled XblGameSave      XblGameSave      Xbox Live Game Save
 Disabled XboxNetApiSvc    XboxNetApiSvc    Xbox Live Networking Service

I'll try installing again, cause the "App Readiness" Service was stopped until now...

Also enabled BITS since the error "0x80070422" hints exaclty that.

Isn't it nice how the informative "0x80070422" error just gets hidden under a non-informative "0x800f0922"...

Gotta love Error Handling in Windows

Please be so kind to close your Threads when you found an answer, these Threads should help everyone with similar issues.
You can close a Thread via the"Mark as Answer" link below posts. You can mark your own posts as answers if you were not helped out but found a solution, in such a case, please provide the answer.
Happy coding
PS: I assure everyone that I did not ever had the desire to offend anyone.


Saturday, February 3, 2018 12:28 AM

I had a different problem on this last update.  I get to a point where it should continue and a very long delay ensues.  If I leave it overnight, it continues to hang there.  Note the events just after 15:41... and yes I have plenty of space on the 1TB disk drive,500GB.  

2018/02/02 15:31:52.9471041 3940  6092  IdleTimer       WU operation (CDiscoveryCall::Init ID 13, operation # 130) stopped; does use network; is not at background priority

2018/02/02 15:31:52.9596942 1912  796   ComApi          *RESUMED* Discovery

2018/02/02 15:31:52.9599123 1912  796   Api             * END *   Discovery ClientId

2018/02/02 15:31:52.9673510 1912  6428  ComApi          *FAILED* [80070490] Method failed [CSLSClientProxy::GetSLSDataChunk:247]

2018/02/02 15:31:52.9706025 1912  6428  ComApi          * START *   SLS Discovery

2018/02/02 15:31:52.9720822 3940  4556  IdleTimer       WU operation (CDiscoveryCall::Init ID 14) started; operation # 135; does use network; is not at background priority

2018/02/02 15:31:52.9722686 1912  6428  ComApi          *QUEUED* SLS Discovery

2018/02/02 15:31:52.9807268 3940  7044  SLS             Retrieving SLS response from server using ETAG +4bx0N8gIIyyugF41U0bsVvqhygqUFfGXdMpO5EP1As=_1440"..."

2018/02/02 15:31:52.9913644 3940  7044  SLS             Making request with URL HTTPS://sls.update.microsoft.com/SLS/{855E8A7C-ECB4-4CA3-B045-1DFA50104289}/x64/10.0.17046.0/0?CH=913&L=en-US&P=RingInsiderFast;WUMUDCat&PT=0x30&WUA=10.0.17046.1000&MK=Gigabyte+Technology+Co.%2C+Ltd.&MD=Default+string

2018/02/02 15:31:53.2003033 3940  7044  Misc            StatusCode for transaction returned from WinHttpQueryHeaders is 304

2018/02/02 15:31:53.2677872 3940  6092  IdleTimer       WU operation (CDiscoveryCall::Init ID 14, operation # 135) stopped; does use network; is not at background priority

2018/02/02 15:31:53.2776391 1912  796   ComApi          *RESUMED* Discovery

2018/02/02 15:31:53.2778548 1912  796   Api             * END *   Discovery ClientId

2018/02/02 15:41:53.3004538 3940  5364  Agent           Earliest future timer found:

2018/02/02 15:41:53.3004801 3940  5364  Agent               Timer: 29A863E7-8609-4D1E-B7CD-5668F857F1DB, Expires 2018-02-03 21:20:15, not idle-only, not network-only

2018/02/02 15:41:54.3027848 3940  4120  Misc            CSusClientGlobal::DoServicePreShutdown

2018/02/02 15:41:54.3029861 3940  4120  IdleTimer       Idle timer disabled in preparation for service shutdown

2018/02/02 15:41:54.3030032 3940  4120  Misc            WUTaskManager uninit

2018/02/02 15:41:54.3030073 3940  4120  Agent           Earliest future timer found:

2018/02/02 15:41:54.3030216 3940  4120  Agent               Timer: 7971F918-A847-4430-9279-4A52D1EFE18D, Expires 2018-02-02 22:27:04, not idle-only, network-only

2018/02/02 15:41:54.3071866 3940  4120  Misc            CreateSessionStateChangeTrigger, TYPE:2, Enable:No

2018/02/02 15:41:54.3072190 3940  4120  Misc            CreateSessionStateChangeTrigger, TYPE:4, Enable:No

2018/02/02 15:41:54.3527863 3940  4120  Misc            Agent uninit

2018/02/02 15:41:54.3546646 3940  4120  Agent           Sending shutdown notification to client

2018/02/02 15:41:54.3547377 3940  4120  Agent           Sending shutdown notification to client

2018/02/02 15:41:54.3567772 3940  4120  Misc            Reporter uninit

2018/02/02 15:41:54.3574810 3940  4120  Misc            network cost manager uninit

2018/02/02 15:41:54.3575619 3940  4120  Misc            Eventer uninit

2018/02/02 15:41:54.3577257 3940  4120  Misc            ServiceManager uninit

2018/02/02 15:41:54.3579428 3940  4120  Misc            PersistentTimeoutScheduler uninit

2018/02/02 15:41:54.3579466 3940  4120  Misc            datastore uninit

2018/02/02 15:41:54.4029005 3940  4120  Misc            setting cache uninit

2018/02/02 15:41:54.4029039 3940  4120  Misc            security checker uninit

2018/02/02 15:41:54.4029138 3940  4120  Misc            Test Hook uninit

2018/02/02 15:41:54.4029151 3940  4120  Misc            IdleTimer uninit

2018/02/02 15:41:54.4040880 3940  4120  Shared          * END * Service exit Exit code = 0x240001

**********************Reset button here********************

2018/02/02 16:10:42.2431380 3940  7936  Shared          * START * Service startup

2018/02/02 16:10:42.2522420 3940  7936  IdleTimer       Non-AoAc machine.  Aoac operations will be ignored.

2018/02/02 16:10:42.2523307 3940  7936  Agent           WU client version 10.0.17046.1000

2018/02/02 16:10:42.2526567 3940  7936  Agent           SleepStudyTracker: Machine is non-AOAC. Sleep study tracker disabled.

2018/02/02 16:10:42.2527318 3940  7936  Agent           Base directory: C:\WINDOWS\SoftwareDistribution

In God We Trust. All others bring data from a reputable source.


Sunday, February 18, 2018 10:35 PM

This problem is still occurring under  Windows 10 Insider Preview 17101.1000 (rs4_release) 

2018/02/18 15:17:20.1643819 6820  5000  ComApi          * END *   Install ClientId = Windows Defender Antivirus (77BDAF73-B396-481F-9042-AD358843EC24)

2018/02/18 15:17:20.1644481 3764  1176  Agent           WU client calls back to install call {847AAD97-DA44-4E54-9B41-B29F61C2C668} with code Call complete and error 0

2018/02/18 15:17:20.1645645 6820  6516  ComApi          Install call complete (succeeded = 1, succeeded with errors = 0, failed = 0, cancelled = 0, unaccounted = 0

2018/02/18 15:17:20.1646092 6820  6516  ComApi          * END *   All federated installs have completed. ClientId = Windows Defender Antivirus (77BDAF73-B396-481F-9042-AD358843EC24) (cV = D2Alq1SynUiBEwde.3.0)

2018/02/18 15:17:20.1657281 3764  4380  IdleTimer       WU operation (CInstallCall::Init ID 5, operation # 40) stopped; does not use network; is not at background priority

2018/02/18 15:18:05.2318871 5844  1288  ComApi          * START *   SLS Discovery

2018/02/18 15:18:05.2350476 3764  5596  IdleTimer       WU operation (CDiscoveryCall::Init ID 6) started; operation # 56; does use network; is not at background priority

2018/02/18 15:18:05.2353722 5844  1288  ComApi          *QUEUED* SLS Discovery

2018/02/18 15:18:05.3042721 3764  3952  IdleTimer       WU operation (CDiscoveryCall::Init ID 6, operation # 56) stopped; does use network; is not at background priority

2018/02/18 15:18:05.3074953 5844  4052  ComApi          *RESUMED* Discovery

2018/02/18 15:18:05.3076295 5844  4052  Api             * END *   Discovery ClientId

2018/02/18 15:18:05.3149095 5844  1288  ComApi          *FAILED* [80070490] Method failed [CSLSClientProxy::GetSLSDataChunk:247]

2018/02/18 15:18:05.3176743 5844  1288  ComApi          * START *   SLS Discovery

2018/02/18 15:18:05.3191499 3764  5596  IdleTimer       WU operation (CDiscoveryCall::Init ID 7) started; operation # 61; does use network; is not at background priority

2018/02/18 15:18:05.3193335 5844  1288  ComApi          *QUEUED* SLS Discovery

2018/02/18 15:18:05.3277648 3764  3396  SLS             Retrieving SLS response from server using ETAG +4bx0N8gIIyyugF41U0bsVvqhygqUFfGXdMpO5EP1As=_1440"..."

2018/02/18 15:18:05.3405863 3764  3396  SLS             Making request with URL HTTPS://sls.update.microsoft.com/SLS/{855E8A7C-ECB4-4CA3-B045-1DFA50104289}/x64/10.0.17046.0/0?CH=913&L=en-US&P=RingInsiderFast;WUMUDCat&PT=0x30&WUA=10.0.17046.1000&MK=Gigabyte+Technology+Co.%2C+Ltd.&MD=Default+string

 

2018/02/18 15:18:05.5656957 3764  3396  Misc            StatusCode for transaction returned from WinHttpQueryHeaders is 304

2018/02/18 15:18:05.6430512 3764  3952  IdleTimer       WU operation (CDiscoveryCall::Init ID 7, operation # 61) stopped; does use network; is not at background priority

2018/02/18 15:18:05.6512723 5844  4052  ComApi          *RESUMED* Discovery

2018/02/18 15:18:05.6514921 5844  4052  Api             * END *   Discovery ClientId

2018/02/18 15:28:05.6808304 3764  5596  Agent           Earliest future timer found:

2018/02/18 15:28:05.6808499 3764  5596  Agent               Timer: 29A863E7-8609-4D1E-B7CD-5668F857F1DB, Expires 2018-02-19 00:09:59, not idle-only, not network-only

2018/02/18 15:28:06.6816742 3764  3988  Misc            CSusClientGlobal::DoServicePreShutdown

2018/02/18 15:28:06.6816817 3764  3988  IdleTimer       Idle timer disabled in preparation for service shutdown

2018/02/18 15:28:06.6816913 3764  3988  Misc            WUTaskManager uninit

2018/02/18 15:28:06.6816950 3764  3988  Agent           Earliest future timer found:

2018/02/18 15:28:06.6817087 3764  3988  Agent               Timer: 29A863E7-8609-4D1E-B7CD-5668F857F1DB, Expires 2018-02-19 00:09:59, not idle-only, not network-only

2018/02/18 15:28:06.6856555 3764  3988  Misc            CreateSessionStateChangeTrigger, TYPE:2, Enable:No

2018/02/18 15:28:06.6856879 3764  3988  Misc            CreateSessionStateChangeTrigger, TYPE:4, Enable:No

2018/02/18 15:28:06.7516081 3764  3988  Misc            Agent uninit

2018/02/18 15:28:06.7539653 3764  3988  Misc            Reporter uninit

2018/02/18 15:28:06.7545708 3764  3988  Misc            network cost manager uninit

2018/02/18 15:28:06.7546425 3764  3988  Misc            Eventer uninit

2018/02/18 15:28:06.7547999 3764  3988  Misc            ServiceManager uninit

2018/02/18 15:28:06.7549886 3764  3988  Misc            PersistentTimeoutScheduler uninit

2018/02/18 15:28:06.7549917 3764  3988  Misc            datastore uninit

2018/02/18 15:28:06.8264991 3764  3988  Misc            setting cache uninit

2018/02/18 15:28:06.8265025 3764  3988  Misc            security checker uninit

2018/02/18 15:28:06.8265128 3764  3988  Misc            Test Hook uninit

2018/02/18 15:28:06.8265145 3764  3988  Misc            IdleTimer uninit

2018/02/18 15:28:06.8271845 3764  3988  Shared          * END * Service exit Exit code = 0x240001

 

 

 

 

 

 

2018/02/18 15:28:32.6640013 3764  11004 Shared          * START * Service startup

2018/02/18 15:28:32.6789203 3764  11004 IdleTimer       Non-AoAc machine.  Aoac operations will be ignored.

2018/02/18 15:28:32.6790524 3764  11004 Agent           WU client version 10.0.17046.1000

2018/02/18 15:28:32.6795429 3764  11004 Agent           SleepStudyTracker: Machine is non-AOAC. Sleep study tracker disabled.

2018/02/18 15:28:32.6796662 3764  11004 Agent           Base directory: C:\WINDOWS\SoftwareDistribution

2018/02/18 15:28:32.6803898 3764  11004 Agent           Datastore directory: C:\WINDOWS\SoftwareDistribution\DataStore\DataStore.edb

2018/02/18 15:28:32.6819183 3764  11004 DataStore       JetEnableMultiInstance succeeded - applicable param count: 5, applied param count: 5

2018/02/18 15:28:32.7172597 3764  11004 Shared          UpdateNetworkState Ipv6, cNetworkInterfaces = 2.

2018/02/18 15:28:32.7172832 3764  11004 Shared          UpdateNetworkState Ipv4, cNetworkInterfaces = 2.

2018/02/18 15:28:32.7175993 3764  11004 Shared          Network state: Connected

2018/02/18 15:28:33.9096502 3764  11004 Misc            *FAILED* [8024000C] LoadHistoryEventFromRegistry completed

2018/02/18 15:28:33.9098529 3764  11004 Shared          UpdateNetworkState Ipv6, cNetworkInterfaces = 2.

2018/02/18 15:28:33.9098683 3764  11004 Shared          UpdateNetworkState Ipv4, cNetworkInterfaces = 2.

2018/02/18 15:28:33.9098826 3764  11004 Shared          Power status changed

2018/02/18 15:28:33.9116347 3764  11004 Agent           Initializing global settings cache

2018/02/18 15:28:33.9116374 3764  11004 Agent           WSUS server: (null)

2018/02/18 15:28:33.9116398 3764  11004 Agent           WSUS status server: (null)

2018/02/18 15:28:33.9116453 3764  11004 Agent           Alternate Download Server: (null)

2018/02/18 15:28:33.9116476 3764  11004 Agent           Fill Empty Content Urls: No

2018/02/18 15:28:33.9116500 3764  11004 Agent           Target group: (Unassigned Computers)

2018/02/18 15:28:33.9116521 3764  11004 Agent           Windows Update access disabled: No

2018/02/18 15:28:33.9116552 3764  11004 Agent           Do not connect to Windows Update Internet locations: No

2018/02/18 15:28:33.9118842 3764  11008 Agent               Timer: 29A863E7-8609-4D1E-B7CD-5668F857F1DB, Expires 2018-02-19 00:09:59, not idle-only, not network-only

2018/02/18 15:28:33.9184026 3764  11008 Agent           Initializing Windows Update Agent

2018/02/18 15:28:33.9185545 3764  11008 Agent           CPersistentTimeoutScheduler | GetTimer, returned hr = 0x00000000

2018/02/18 15:28:33.9423339 3764  10872 DownloadManager PurgeExpiredFiles::Found 0 expired files to delete.

2018/02/18 15:28:33.9425189 3764  10872 DownloadManager PurgeExpiredUpdates::Found 49 non expired updates.

2018/02/18 15:28:34.0103676 3764  10872 DownloadManager PurgeExpiredUpdates::Found 0 expired updates.

2018/02/18 15:28:34.0126354 3764  10872 DownloadManager Received power state change notification: Old: <unknown>; New: AC.

2018/02/18 15:28:34.0126378 3764  10872 DownloadManager Power state changed from <unknown> to AC.

2018/02/18 15:29:16.5188221 3764  11008 ComApi          * START *   Federated Search ClientId = UpdateOrchestrator (cV: /V1e15iGGU2ipX3w.0.1.0)

2018/02/18 15:29:16.5190098 3764  11008 IdleTimer       WU operation (SR.UpdateOrchestrator ID 1) started; operation # 13; does use network; is not at background priority

2018/02/18 15:29:16.5194365 3764  10792 Agent           Processing auto/pending service registrations and recovery.

2018/02/18 15:29:16.5561944 3764  10792 Misc            *FAILED* [80245002] Failed to obtain 9482F4B4-E343-43B6-B170-9A65BC822C77 redir SecondaryServiceAuth URL

2018/02/18 15:29:16.5562654 3764  10792 Agent           Failed to verify authenticity of service 117cab2d-82b1-4b5a-a08c-4d62dbee7782, hr=0

2018/02/18 15:29:16.6325354 3764  10792 IdleTimer       WU operation (SR.UpdateOrchestrator ID 1, operation # 13) stopped; does use network; is not at background priority

2018/02/18 15:29:16.6334618 3764  10928 ComApi          Federated Search: Starting search against 2 service(s) (cV = /V1e15iGGU2ipX3w.0.1.0)

2018/02/18 15:29:16.6336294 3764  10928 ComApi          * START *   Search ClientId = UpdateOrchestrator, ServiceId = 7971F918-A847-4430-9279-4A52D1EFE18D (cV = /V1e15iGGU2ipX3w.0.1.0.0)

2018/02/18 15:29:16.6343681 3764  10928 IdleTimer       WU operation (CSearchCall::Init ID 2) started; operation # 16; does use network; is not at background priority

2018/02/18 15:29:16.6568196 3764  10928 Reporter        OS Product Type = 0x00000030

2018/02/18 15:29:16.6703450 3764  10928 Agent           * START * Queueing Finding updates [CallerId = UpdateOrchestrator  Id = 2]

2018/02/18 15:29:16.6703525 3764  10928 Agent           Service 7971F918-A847-4430-9279-4A52D1EFE18D is not in sequential scan list

2018/02/18 15:29:16.6703566 3764  10928 Agent           Added service 7971F918-A847-4430-9279-4A52D1EFE18D to sequential scan list

2018/02/18 15:29:16.6704624 3764  10928 ComApi          * START *   Search ClientId = UpdateOrchestrator, ServiceId = 8B24B027-1DEE-BABB-9A95-3517DFB9C552 (cV = /V1e15iGGU2ipX3w.0.1.0.0)

2018/02/18 15:29:16.6707829 3764  10944 Agent           Service 7971F918-A847-4430-9279-4A52D1EFE18D is in sequential scan list

2018/02/18 15:29:16.6708980 3764  10928 IdleTimer       WU operation (CSearchCall::Init ID 3) started; operation # 19; does use network; is not at background priority

2018/02/18 15:29:16.6718598 3764  10764 Agent           * END * Queueing Finding updates [CallerId = UpdateOrchestrator  Id = 2]

2018/02/18 15:29:16.6726265 3764  10764 Agent           * START * Finding updates CallerId = UpdateOrchestrator  Id = 2

2018/02/18 15:29:16.6726316 3764  10764 Agent           Online = Yes; Interactive = Yes; AllowCachedResults = No; Ignore download priority = No

2018/02/18 15:29:16.6726394 3764  10764 Agent           Criteria = IsInstalled=0 and DeploymentAction='Installation' or IsPresent=1 and DeploymentAction='Uninstallation' or IsInstalled=1 and DeploymentAction='Installation' and RebootRequired=1 or IsInstalled=0 and DeploymentAction='Uninstallation' and RebootRequired=1""

In God We Trust. All others bring data from a reputable source.