verdyp
Member
- Aug 10, 2016
- 10
I'm locked trying to install a Windows update. Apparently this is an issus with NTFS compression on some existing files that can be read from normal Windows boot, but not from PE while installing a new image.
Running "SFC /SCANNOW" stops at 16% :
Running "DISM /ONLINE /CLEANUP-IMAGE /ANALYZECOMPONENTSTORE" detects no error :
Running "DISM /ONLINE /CLEANUP-IMAGE /CHECKHEALTH" detects no error:
Running "DISM /ONLINE /CLEANUP-IMAGE /SCANHEALTH" stops at 35.2% with an internal WOF error 4448:
Running "DISM /ONLINE /CLEANUP-IMAGE /RESTOREHEALTH" stops at 26.1% with an internal WOF error 4448:
But SFCFIX does not find any error :
and gives this log:
Windows\Logs\DISM\DISM.LOG contains only these errors:
Windows\Logs\CBS\CBS.LOG terminates only with these messages:
So this is an internal error in WOF compression of one DLL:
"WINDOWS\WinSxS\amd64_netfx-normalization_dll_b03f5f7f11d50a3a_10.0.18342.1_none_53193016adf7f600\normalization.dll"
How can this be possible when the component store is OK andDISM checkhealth is OK ? most probably only a sign that a transaction (made by Windows Update) was completed successfully prematurely, before this file was finalized during WOF compression on NTFS, and the compression left some internal garbage in the storage, even if that garbage is normally unused.
Note that I can successfully read this DLL and and copy it elsewhere. I can sucessfully COMPACT and uncompact it from that copy, but not on its existing location. But I cannot remove this DLL (which is system protected).
The DLL content also show no incorrent format. That DLL fails on digital signature test, but this is not the case for any copy I make from it. So most probably this DLL is "virtualized" by the Windows kernel (possibly for the Hyper-V hypervisor or by Microsoft AntiMalware for by some of the new "containers" that appeared with recent builds): the in-memory image we get from the virtualized content does not match what is really on disk (the image is possibly "patched" by a SHIM or some other drivers once it is loaded.)
Note: I already performed several other system checks which detected no error:
Running "SFC /SCANNOW" stops at 16% :
Code:
Microsoft Windows [version 10.0.18342.1]
(c) 2018 Microsoft Corporation. Tous droits réservés.
C:\WINDOWS\system32>sfc /scannow
Début de l’analyse du système. Cette opération peut nécessiter un certain temps.
Démarrage de la phase de vérification de l’analyse du système.
La vérification est à 16% terminée.
La protection des ressources Windows n’a pas réussi à effectuer l’opération demandée.
C:\WINDOWS\system32>
Running "DISM /ONLINE /CLEANUP-IMAGE /ANALYZECOMPONENTSTORE" detects no error :
Code:
C:\WINDOWS\system32>dism /online /cleanup-image /analyzecomponentstore
Outil Gestion et maintenance des images de déploiement
Version : 10.0.18342.1
Version de l’image : 10.0.18342.1
[==========================100.0%==========================]
Informations sur le magasin de composants (WinSxS) :
L’Explorateur Windows a signalé la taille du magasin de composants. : 4.60 GB
Taille réelle du magasin de composants : 4.58 GB
Partagé avec Windows : 4.45 GB
Sauvegardes et fonctionnalités désactivées : 132.11 MB
Cache et données temporaires : 0 bytes
Date du dernier nettoyage : 2019-03-08 16:39:26
Nombre de packages récupérables : 0
Nettoyage du magasin de composants recommandé : Non
L’opération a réussi.
C:\WINDOWS\system32>
Running "DISM /ONLINE /CLEANUP-IMAGE /CHECKHEALTH" detects no error:
Code:
C:\WINDOWS\system32>DISM /ONLINE /CLEANUP-IMAGE /CHECKHEALTH
Outil Gestion et maintenance des images de déploiement
Version : 10.0.18342.1
Version de l’image : 10.0.18342.1
Aucun endommagement du magasin de composants n’a été détecté.
L’opération a réussi.
C:\WINDOWS\system32>
Running "DISM /ONLINE /CLEANUP-IMAGE /SCANHEALTH" stops at 35.2% with an internal WOF error 4448:
Code:
C:\WINDOWS\system32>DISM /ONLINE /CLEANUP-IMAGE /SCANHEALTH
Outil Gestion et maintenance des images de déploiement
Version : 10.0.18342.1
Version de l’image : 10.0.18342.1
[==================== 35.2% ]
Erreur : 4448
Le pilote WOF a rencontré une défaillance dans la Table des ressources du fichier compressé.
Le fichier journal DISM se trouve à l’emplacement C:\WINDOWS\Logs\DISM\dism.log
C:\WINDOWS\system32>
Running "DISM /ONLINE /CLEANUP-IMAGE /RESTOREHEALTH" stops at 26.1% with an internal WOF error 4448:
Code:
C:\WINDOWS\system32>DISM /ONLINE /CLEANUP-IMAGE /RESTOREHEALTH
Outil Gestion et maintenance des images de déploiement
Version : 10.0.18342.1
Version de l’image : 10.0.18342.1
[=============== 26.1% ]
Erreur : 4448
Le pilote WOF a rencontré une défaillance dans la Table des ressources du fichier compressé.
Le fichier journal DISM se trouve à l’emplacement C:\WINDOWS\Logs\DISM\dism.log
C:\WINDOWS\system32>
But SFCFIX does not find any error :
Code:
C:\WINDOWS\system32> SFCFIX
Checking for updates . . .
No new update is available at this time.
Processing directive 1 of 1 (AutoAnalysis::)
Checking store directories . . .
Checking CBS.log . . .
Checking CheckSUR.log . . .
Checking CheckSUR.persist.log . . .
Attempting repair . . .
Stage 1
Stage 2
Stage 3
Due to the nature of your corruptions, scan times have been extended by
approximately 15-20 minutes. Please be patient and allow the operation to
complete.
Outil Gestion et maintenance des images de déploiement
Version : 10.0.18342.1
Version de l’image : 10.0.18342.1
[=============== 26.1% ]
Erreur : 4448
Le pilote WOF a rencontré une défaillance dans la Table des ressources du fichier compressé.
Le fichier journal DISM se trouve à l’emplacement C:\WINDOWS\Logs\DISM\dism.log
SUMMARY:
CBS & SFC total detected corruption count: 0
CBS & SFC total unimportant corruption count: 0
CBS & SFC total fixed corruption count: 0
SURT total detected corruption count: 0
SURT total unimportant corruption count: 0
SURT total fixed corruption count: 0
Press any key to continue to explanation of summary . . .
--- Displaying Message 1 of 1 ---
No corruptions have been detected on this computer. Whilst this is a good thing,be aware that not all types of corruption can be detected by this tool.
Therefore, if you are currently experiencing continued problems with your
computer it is likely that you are going to need the assistance of a human
analyst in order to find and fix them.
If your problems persist, and you are currently being instructed by a helper,
you should let them know about this development by sending them the logfile
which will soon be generated and opened so that they can perform a manual fix.
If you are not currently being instructed by a helper but still need assistance,you should seek free advice from your favourite forum or sysnative.com. Create anew thread and provide this logfile in the first post of that thread along with
a complete description of the problems you were experiencing which led you to
run this tool.
* Press any key to continue . . .
Successfully processed all directives.
C:\WINDOWS\system32>
and gives this log:
Code:
SFCFix version 120.105.0.0 by niemiro.
Start time: 2019-03-08 18:08:39.856
Microsoft Windows 10 Insider Fast, Build 18342 - amd64
Not using a script file.
AutoAnalysis::
SUMMARY: No corruptions were detected.
AutoAnalysis:: directive completed successfully.
Successfully processed all directives.
SFCFix version 120.105.0.0 by niemiro has completed.
Currently storing 0 datablocks.
Finish time: 2019-03-08 18:11:48.428
----------------------EOF-----------------------
Windows\Logs\DISM\DISM.LOG contains only these errors:
Code:
...(snipped)...
2019-03-08 18:08:47, Info DISM DISM.EXE: Succesfully registered commands for the provider: SetupPlatformManager.
2019-03-08 18:08:47, Info DISM DISM Package Manager: PID=14020 TID=5820 Processing the top level command token(cleanup-image). - CPackageManagerCLIHandler::Private_ValidateCmdLine
2019-03-08 18:08:47, Info DISM DISM Package Manager: PID=14020 TID=5820 Attempting to route to appropriate command handler. - CPackageManagerCLIHandler::ExecuteCmdLine
2019-03-08 18:08:47, Info DISM DISM Package Manager: PID=14020 TID=5820 Routing the command... - CPackageManagerCLIHandler::ExecuteCmdLine
2019-03-08 18:08:47, Info DISM DISM Package Manager: PID=14020 TID=5820 CBS session options=0x40100! - CDISMPackageManager::Internal_Finalize
2019-03-08 18:09:28, Info DISM DISM Package Manager: PID=14020 TID=15992 Error in operation: (null) (CBS HRESULT=0x80071160) - CCbsConUIHandler::Error
2019-03-08 18:09:28, Error DISM DISM Package Manager: PID=14020 TID=5820 Failed finalizing changes. - CDISMPackageManager::Internal_Finalize(hr:0x80071160)
2019-03-08 18:09:28, Error DISM DISM Package Manager: PID=14020 TID=5820 Failed processing package changes with session option CbsSessionOptionRepairStoreCorruption - CDISMPackageManager::RestoreHealth(hr:0x80071160)
2019-03-08 18:09:28, Error DISM DISM Package Manager: PID=14020 TID=5820 Failed to restore the image health. - CPackageManagerCLIHandler::ProcessCmdLine_CleanupImage(hr:0x80071160)
2019-03-08 18:09:28, Error DISM DISM Package Manager: PID=14020 TID=5820 Failed while processing command cleanup-image. - CPackageManagerCLIHandler::ExecuteCmdLine(hr:0x80071160)
2019-03-08 18:09:28, Info DISM DISM Package Manager: PID=14020 TID=5820 Further logs for online package and feature related operations can be found at %WINDIR%\logs\CBS\cbs.log - CPackageManagerCLIHandler::ExecuteCmdLine
2019-03-08 18:09:28, Error DISM DISM.EXE: DISM Package Manager processed the command line but failed. HRESULT=80071160
2019-03-08 18:09:28, Info DISM DISM Provider Store: PID=14020 TID=5820 Found the OSServices. Waiting to finalize it until all other providers are unloaded. - CDISMProviderStore::Final_OnDisconnect
...
Windows\Logs\CBS\CBS.LOG terminates only with these messages:
Code:
...(snipped)...
2019-03-08 18:08:54, Info CSI 00000db8 Direct SIL provider: Number of files opened: 2.
2019-03-08 18:08:54, Info CBS Registry Root 8 not found, skip checking
2019-03-08 18:08:54, Info CBS Repr: CBS Store check completes
2019-03-08 18:08:54, Info CSI 00000002 IAdvancedInstallerAwareStore_ResolvePendingTransactions (call 1) (flags = 00000004, progress = NULL, phase = 0, pdwDisposition = @0x7e9167d980
2019-03-08 18:08:54, Info CSI 00000003 Poqexec successfully registered in [l:12 ml:13]'SetupExecute'
2019-03-08 18:08:54, Info CSI 00000004 CSI Store 2682683071568 initialized
2019-03-08 18:08:54, Info CSI 00000005 StoreCorruptionRepair transaction begun. WcpVersion: [l:35]'10.0.18342.1 (WinBuild.160101.0800)'.
2019-03-08 18:08:54, Info CSI 00000006@2019/3/8:17:08:54.811 Starting corruption detection (InnerFlags=2)
2019-03-08 18:08:54, Info CBS FLOW: Entering stage: CheckCsi
2019-03-08 18:09:28, Error CSI 00000007@2019/3/8:17:09:28.016 (F) onecore\base\wcp\sil\ntsystem.cpp(3556): Error c000a2a7 [Error,Facility=(system),Code=41639 (0xa2a7)] originated in function Windows::Rtl::SystemImplementation::DirectFileSystemProvider::SysReadFile expression: (null)
[gle=0x80004005]
2019-03-08 18:09:28, Info CBS Added C:\WINDOWS\Logs\CBS\CBS.log to WER report.
2019-03-08 18:09:28, Info CBS Not able to add current session file to Windows Error Report. [HRESULT = 0x80070002 - ERROR_FILE_NOT_FOUND]
2019-03-08 18:09:28, Info CBS Not able to add pending.xml to Windows Error Report. [HRESULT = 0x80070002 - ERROR_FILE_NOT_FOUND]
2019-03-08 18:09:28, Info CBS Not able to add pending.xml.bad to Windows Error Report. [HRESULT = 0x80070002 - ERROR_FILE_NOT_FOUND]
2019-03-08 18:09:28, Info CBS Not able to add poqexec.log to Windows Error Report. [HRESULT = 0x80070002 - ERROR_FILE_NOT_FOUND]
2019-03-08 18:09:28, Info CBS Not able to add SCM.EVM to Windows Error Report. [HRESULT = 0x80070002 - ERROR_FILE_NOT_FOUND]
2019-03-08 18:09:28, Error CSI 00000008 (F) c000a2a7 [Error,Facility=(system),Code=41639 (0xa2a7)] #777478# from Windows::Rtl::SystemImplementation::DirectFileSystemProvider::SysReadFile(h = 4154 ('\Device\HarddiskVolume3\WINDOWS\WinSxS\amd64_netfx-normalization_dll_b03f5f7f11d50a3a_10.0.18342.1_none_53193016adf7f600\normalization.dll'), evt = 0, apcr = NULL, apcc = NULL, iosb = @0x7e9167bb10, data = {l:0 b:}, byteoffset = 0, key = (null))
[gle=0xd000a2a7]
2019-03-08 18:09:28, Error CSI 00000009 (F) c000a2a7 [Error,Facility=(system),Code=41639 (0xa2a7)] #777477# from Windows::Rtl::SystemImplementation::CFile::ReadFile(Flags = 3, Buffer = {l:0 ml:25240 b:}, Offset = 0, Disposition = 0)[gle=0xd000a2a7]
2019-03-08 18:09:28, Info CSI 0000000a Direct SIL provider: Number of files opened: 21070.
2019-03-08 18:09:28, Error CSI 0000000b (F) c000a2a7 [Error,Facility=(system),Code=41639 (0xa2a7)] #233# from CCSDirect::EnumStoreCorruptions(...)[gle=0xd000a2a7]
2019-03-08 18:09:28, Error CSI 0000000c (F) HRESULT_FROM_WIN32(4448) #232# from Windows::COM::CStorePendingStoreRepairTxn_IStorePendingStoreRepairTransaction::Detect(Flags = 4, cancelEvt = 420 (''), disp = 0)[gle=0x80071160]
2019-03-08 18:09:28, Error CBS Rept: Failed to call CSI detect. [HRESULT = 0x80071160 - Unknown Error]
2019-03-08 18:09:28, Info CBS Failed to check CSI store. [HRESULT = 0x80071160 - Unknown Error]
2019-03-08 18:09:28, Info CBS
2019-03-08 18:09:28, Info CBS =================================
2019-03-08 18:09:28, Info CBS Checking System Update Readiness.
2019-03-08 18:09:28, Info CBS
2019-03-08 18:09:28, Info CBS
2019-03-08 18:09:28, Info CBS Summary:
2019-03-08 18:09:28, Info CBS Operation: Detect and Repair
2019-03-08 18:09:28, Info CBS Operation result: 0x80071160
2019-03-08 18:09:28, Info CBS Last Successful Step: CBS store detection completes.
2019-03-08 18:09:28, Info CBS Total Detected Corruption: 0
2019-03-08 18:09:28, Info CBS CBS Manifest Corruption: 0
2019-03-08 18:09:28, Info CBS CBS Metadata Corruption: 0
2019-03-08 18:09:28, Info CBS CSI Manifest Corruption: 0
2019-03-08 18:09:28, Info CBS CSI Metadata Corruption: 0
2019-03-08 18:09:28, Info CBS CSI Payload Corruption: 0
2019-03-08 18:09:28, Info CBS Total Repaired Corruption: 0
2019-03-08 18:09:28, Info CBS CBS Manifest Repaired: 0
2019-03-08 18:09:28, Info CBS CSI Manifest Repaired: 0
2019-03-08 18:09:28, Info CBS CSI Payload Repaired: 0
2019-03-08 18:09:28, Info CBS CSI Store Metadata refreshed: False
2019-03-08 18:09:28, Info CBS
2019-03-08 18:09:28, Info CBS Total Operation Time: 41 seconds.
2019-03-08 18:09:28, Info CBS Ensure CBS corruption flag is clear
2019-03-08 18:09:28, Info CBS Ensure WCP corruption flag is clear
2019-03-08 18:09:28, Info CBS All CSI corruption was fixed, ensure CorruptionDetectedDuringAcr is clear
2019-03-08 18:09:28, Info CBS Failed to clear CorruptionDetectedDuringAcr store corrupt flag (slow mode trigger). [HRESULT = 0x80070002 - ERROR_FILE_NOT_FOUND]
2019-03-08 18:09:28, Info CBS CheckSur: hrStatus: 0x80071160 [Unknown Error], download Result: 0x0 [S_OK]
2019-03-08 18:09:28, Info CBS Count of times corruption detected: 0
2019-03-08 18:09:28, Info CBS Seconds between initial corruption detections: -1
2019-03-08 18:09:28, Info CBS Seconds between corruption and repair: -1
2019-03-08 18:09:28, Info CBS Failed to run Detect and repair. [HRESULT = 0x80071160 - Unknown Error]
2019-03-08 18:09:28, Info CBS Reboot mark cleared
2019-03-08 18:09:28, Info CBS Winlogon: Simplifying Winlogon CreateSession notifications
2019-03-08 18:09:28, Info CBS Winlogon: Deregistering for CreateSession notifications
2019-03-08 18:09:28, Info CBS Exec: Processing complete, session(Corruption Repairing): 30725585_2546440391 [HRESULT = 0x80071160 - Unknown Error]
2019-03-08 18:09:28, Error CBS Session: 30725585_2546440391 failed to perform store corruption detect and repair operation. [HRESULT = 0x80071160 - Unknown Error]
2019-03-08 18:09:28, Info CBS Session: 30725585_2546440391 finalized. Reboot required: no [HRESULT = 0x80071160 - Unknown Error]
2019-03-08 18:09:28, Info CBS Failed to FinalizeEx using worker session [HRESULT = 0x80071160]
2019-03-08 18:11:28, Info CBS Trusted Installer is shutting down because: SHUTDOWN_REASON_AUTOSTOP
2019-03-08 18:11:28, Info CBS Maint: idle processing paused
2019-03-08 18:11:28, Info CBS TiWorker signaled for shutdown, going to exit.
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: ExecutionEngineFinalize
2019-03-08 18:11:28, Info CBS Execution Engine Finalize
2019-03-08 18:11:28, Info CBS Execution Engine Finalize
2019-03-08 18:11:28, Info CBS Lock: Lock removed: TiWorkerClassFactory, level: 30, total lock:3
2019-03-08 18:11:28, Info CBS Lock: Lock removed: CCbsWorker, level: 5, total lock:2
2019-03-08 18:11:28, Info CBS Ending the TiWorker main loop.
2019-03-08 18:11:28, Info CBS Starting TiWorker finalization.
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: ExecutionEngineFinalize
2019-03-08 18:11:28, Info CBS CBS Engine already deactivated
2019-03-08 18:11:28, Info CBS CBS Engine already deactivated
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: ComponentAnalyzerFinalize
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: PackageTrackerFinalize
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: CoreResourcesUnload
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: SessionManagerFinalize
2019-03-08 18:11:28, Info CBS Lock: Lock removed: CSIInventoryCriticalSection, level: 64, total lock:9
2019-03-08 18:11:28, Info CBS Lock: Lock removed: CCbsSessionManager, level: 11, total lock:8
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: CapabilityManagerFinalize
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: PublicObjectMonitorFinalize
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: Enter vCoreInitializeLock
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: WcpUnload
2019-03-08 18:11:28, Info CSI 0000000d Direct SIL provider: Number of files opened: 3860.
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: DrupUnload
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: CfgMgr32Unload
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: DpxUnload
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: SrUnload
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: CbsEsdUnload
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: CbsTraceInfoUninitialize
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: CbsEventUnregister
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: AppContainerUnload
2019-03-08 18:11:28, Info CBS CbsCoreFinalize: WdsUnload, logging from cbscore will end.
2019-03-08 18:11:28, Info CBS Ending TiWorker finalization.
2019-03-08 18:11:28, Info CBS Ending the TrustedInstaller main loop.
2019-03-08 18:11:28, Info CBS Starting TrustedInstaller finalization.
2019-03-08 18:11:28, Info CBS Winlogon: Stopping notify server
2019-03-08 18:11:28, Info CBS Winlogon: Unloading SysNotify DLL
2019-03-08 18:11:28, Info CBS Lock: Lock removed: WinlogonNotifyLock, level: 8, total lock:6
2019-03-08 18:11:28, Info CBS Ending TrustedInstaller finalization.
So this is an internal error in WOF compression of one DLL:
"WINDOWS\WinSxS\amd64_netfx-normalization_dll_b03f5f7f11d50a3a_10.0.18342.1_none_53193016adf7f600\normalization.dll"
How can this be possible when the component store is OK andDISM checkhealth is OK ? most probably only a sign that a transaction (made by Windows Update) was completed successfully prematurely, before this file was finalized during WOF compression on NTFS, and the compression left some internal garbage in the storage, even if that garbage is normally unused.
Note that I can successfully read this DLL and and copy it elsewhere. I can sucessfully COMPACT and uncompact it from that copy, but not on its existing location. But I cannot remove this DLL (which is system protected).
The DLL content also show no incorrent format. That DLL fails on digital signature test, but this is not the case for any copy I make from it. So most probably this DLL is "virtualized" by the Windows kernel (possibly for the Hyper-V hypervisor or by Microsoft AntiMalware for by some of the new "containers" that appeared with recent builds): the in-memory image we get from the virtualized content does not match what is really on disk (the image is possibly "patched" by a SHIM or some other drivers once it is loaded.)
Note: I already performed several other system checks which detected no error:
- CPU stress
- GPU stress
- memory test (at boot time, using windows utility or from a Linux live DVD)
- disk tests (including SMART, using Smartmontools)
- partitions structure test
- volume tests
- NTFS filesystem checks (CHKDSK on Windows, including at boot time).