Troubleshooting Patch Caching

  • 7014061
  • 07-Nov-2013
  • 19-Nov-2013

Environment

Novell ZENworks Configuration Management 11.2

Situation

What to look for in loader-messages.log during patch caching.

Resolution

zman ql -s I (to show in progress queue jobs):

16063 | SUBSCRIPTION_DOWNLOAD   | I      |           | Nov 7, 2013 11:33 AM | sqllinux | sqllinux
16064 | settings.changed.notify | I      |           | Nov 7, 2013 11:33 AM | sqllinux | sqllinux

loader-messages.log:

[DEBUG] [10/31/13 10:12:50 AM] [] [Loader.MandatoryBaselineManager] [131] [] [Valid license state. Patch download process starts now.] [] []
[DEBUG] [10/31/13 10:12:50 AM] [] [Loader.MandatoryBaselineManager] [131] [] [ Caching Object MS13-080 Cumulative Security Update for Internet Explorer 10 for Windows 7 Service Pack 1 (KB 2879017)] [] []
[DEBUG] [10/31/13 10:12:50 AM] [] [Loader.MandatoryBaselineManager] [131] [] [ Caching Object windows] [] []
[DEBUG] [10/31/13 10:12:50 AM] [] [PatchManagement] [131] [] [Initializing PatchPlayer] [] []
[DEBUG] [10/31/13 10:12:50 AM] [] [PatchManagement] [131] [] [Create PatchPlayer] [] []
[DEBUG] [10/31/13 10:12:51 AM] [] [PatchManagement] [131] [] [Setting languages [[Lcom.patchlink.ppxlib.PPXLang;@45acfe7] [] []
[DEBUG] [10/31/13 10:12:51 AM] [] [PatchManagement] [131] [] [ Before import ] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [Import response code PPX_SUCCESS] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [PatchPlayer getPatch] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [PatchPlayer getArchitecture] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [PatchPlayer getCreatedBy] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [PatchPlayer getDescription] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [PatchPlayer getHyperlink] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [PatchPlayer getIdentifier] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [PatchPlayer Complete Initial get] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [SCR attribute URL,IE10-WINDOWS6.1-KB 2879017-X86.CAB,Q-Number,ServicePack,CVE,MaximumSeverityRating,R-UserRecommendation,Reboot,MaxPackageSize,BulletinID] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [PatchPlayer getting signature] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [PatchPlayer process signature] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [ # PackageFileNames ie10-windows6.1-kb2879017-x86.cab] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [ ## Token file name ie10-windows6.1-kb2879017-x86.cab] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [Destroying patch player] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [PatchPlayer Destroy Patch Player return code PPX_SUCCESS] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [Processing ie10-windows6.1-kb2879017-x86.cab] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [Processing MS13-080] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [Processing http://wsus.ds.download.windowsupdate.com/msdownload/update/software/secu/2013/09/ie10-windows6.1-kb2879017-x86_178a3f43ed3d3d89a31c86cba43c660abcca8f42.cab] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [Processing external content] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [Downloading file from windows site.false] [] []
[DEBUG] [10/31/13 10:12:52 AM] [] [PatchManagement] [131] [] [Downloading file from windows site.http://wsus.ds.download.windowsupdate.com/msdownload/update/software/secu/2013/09/ie10-windows6.1-kb2879017-x86_178a3f43ed3d3d89a31c86cba43c660abcca8f42.cab] [] []
[DEBUG] [10/31/13 10:13:05 AM] [] [PatchManagement] [131] [] [download success true] [] []
[DEBUG] [10/31/13 10:13:05 AM] [] [PatchManagement] [131] [] [Processing ie10-windows6.1-kb2879017-x86.cab] [] []
[DEBUG] [10/31/13 10:13:05 AM] [] [PatchManagement] [131] [] [Processing MS13-080] [] []
[DEBUG] [10/31/13 10:13:05 AM] [] [PatchManagement] [131] [] [Processing http://wsus.ds.download.windowsupdate.com/msdownload/update/software/secu/2013/09/ie10-windows6.1-kb2879017-x86_178a3f43ed3d3d89a31c86cba43c660abcca8f42.cab] [] []
[DEBUG] [10/31/13 10:13:05 AM] [] [PatchManagement] [131] [] [Processing external content] [] []
[DEBUG] [10/31/13 10:13:21 AM] [] [PatchManagement] [131] [] [download success true] [] []
[DEBUG] [10/31/13 10:13:21 AM] [] [PatchManagement] [131] [] [Downloading file from windows site.true] [] []
[DEBUG] [10/31/13 10:13:21 AM] [] [PatchManagement] [131] [] [Downloading file from windows site.http://wsus.ds.download.windowsupdate.com/msdownload/update/software/secu/2013/09/ie10-windows6.1-kb2879017-x86_178a3f43ed3d3d89a31c86cba43c660abcca8f42.cab] [] []
[DEBUG] [10/31/13 10:13:23 AM] [] [Loader.MandatoryBaselineManager] [131] [] [BundleTools.ImportFilesIntoContentSystem : Start time =2013-10-31 10:13:23.182] [] []
[DEBUG] [10/31/13 10:13:25 AM] [] [ImportContent] [131] [] [Entering ImportContent for ZUID: a6f21a59dac4240cf262d2646df4fcdf, File: null] [] []
[DEBUG] [10/31/13 10:13:25 AM] [] [ImportContent] [131] [] [    Content UID: a6f21a59dac4240cf262d2646df4fcdf] [] []
[DEBUG] [10/31/13 10:13:25 AM] [] [ImportContent] [131] [] [    Containing Object UID: f67059efe22d34785e595f1a1568c795] [] []
[DEBUG] [10/31/13 10:13:25 AM] [] [ImportContent] [131] [] [    Server UID: a2411301be6fc77ce50f2f57e963ac82] [] []
[DEBUG] [10/31/13 10:13:25 AM] [] [ImportContent] [131] [] [    Content Files Root Path: /var/opt/novell/zenworks/content-repo/tmp/zpm/plp] [] []
[DEBUG] [10/31/13 10:13:25 AM] [] [ImportContent] [131] [] [    Content File: /var/opt/novell/zenworks/content-repo/tmp/zpm/plp/ie10-windows6.1-kb2879017-x86.cab] [] []
[DEBUG] [10/31/13 10:13:25 AM] [] [ImportContent] [131] [] [    Include other files: false] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [Content Datamodel] [131] [] [Entered content session clean-up] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [Content Datamodel] [131] [] [setContentSyncStateBatch complete, time: 50ms, serverUID: a2411301be6fc77ce50f2f57e963ac82, size of contentIDs: 1, thread: 131] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Setting the tempFilename, externalContentType is false, unpackagedContentType is true, packagingType is 257, filename should be /var/opt/novell/zenworks/content-repo/tmp/zpm/plp/ie10-windows6.1-kb2879017-x86.cab] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Content already found in the system: a6f21a59dac4240cf262d2646df4fcdf] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Replacing with : 390c4e571260302371ae877efe17bf2b] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Not deleting /var/opt/novell/zenworks/content-repo/tmp/zpm/plp because it is non-empty] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Done processing: a6f21a59dac4240cf262d2646df4fcdf] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [Content Import] [131] [] [Moved checkpoint file a6f21a59dac4240cf262d2646df4fcdfto folder: recent] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Entering ImportContent for ZUID: 06ebff7d22f1a7b184bfd850fb4f32cf, File: null] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [    Content UID: 06ebff7d22f1a7b184bfd850fb4f32cf] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [    Containing Object UID: f67059efe22d34785e595f1a1568c795] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [    Server UID: a2411301be6fc77ce50f2f57e963ac82] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [    Content Files Root Path: /var/opt/novell/zenworks/content-repo/tmp/zpm/plp/windows] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [    Content File: /var/opt/novell/zenworks/content-repo/tmp/zpm/plp/windows/B44D5E75-36BF-49C0-93F9-148E8F3B7899.plp] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [    Include other files: false] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [Content Datamodel] [131] [] [Entered content session clean-up] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [Content Datamodel] [131] [] [setContentSyncStateBatch complete, time: 9ms, serverUID: a2411301be6fc77ce50f2f57e963ac82, size of contentIDs: 1, thread: 131] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Setting the tempFilename, externalContentType is false, unpackagedContentType is true, packagingType is 257, filename should be /var/opt/novell/zenworks/content-repo/tmp/zpm/plp/windows/B44D5E75-36BF-49C0-93F9-148E8F3B7899.plp] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Content already found in the system: 06ebff7d22f1a7b184bfd850fb4f32cf] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Replacing with : c083ee3741b58e4f84af0e72592369ab] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Not deleting /var/opt/novell/zenworks/content-repo/tmp/zpm/plp/windows because it is non-empty] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Done processing: 06ebff7d22f1a7b184bfd850fb4f32cf] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [Content Import] [131] [] [Moved checkpoint file 06ebff7d22f1a7b184bfd850fb4f32cfto folder: recent] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Entering ImportContent for ZUID: 8459875c202a4d52e8bd15c7cccec0cd, File: null] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [    Content UID: 8459875c202a4d52e8bd15c7cccec0cd] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [    Containing Object UID: f67059efe22d34785e595f1a1568c795] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [    Server UID: a2411301be6fc77ce50f2f57e963ac82] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [    Content Files Root Path: /var/opt/novell/zenworks/content-repo/tmp/zpm/plp/windows] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [    Content File: /var/opt/novell/zenworks/content-repo/tmp/zpm/plp/windows/MS13-080 Cumulative Security Update for Internet Explorer 10 for Windows 7 Service Pack 1 (KB 2879017).pls] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [    Include other files: false] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [Content Datamodel] [131] [] [Entered content session clean-up] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [Content Datamodel] [131] [] [setContentSyncStateBatch complete, time: 8ms, serverUID: a2411301be6fc77ce50f2f57e963ac82, size of contentIDs: 1, thread: 131] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Setting the tempFilename, externalContentType is false, unpackagedContentType is true, packagingType is 257, filename should be /var/opt/novell/zenworks/content-repo/tmp/zpm/plp/windows/MS13-080 Cumulative Security Update for Internet Explorer 10 for Windows 7 Service Pack 1 (KB 2879017).pls] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Content already found in the system: 8459875c202a4d52e8bd15c7cccec0cd] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Replacing with : d24d7e0ed9168ab8acb382ba89b79597] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Not deleting /var/opt/novell/zenworks/content-repo/tmp/zpm/plp/windows because it is non-empty] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [ImportContent] [131] [] [Done processing: 8459875c202a4d52e8bd15c7cccec0cd] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [Content Import] [131] [] [Moved checkpoint file 8459875c202a4d52e8bd15c7cccec0cdto folder: recent] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [Loader.MandatoryBaselineManager] [131] [] [BundleTools.ImportFilesIntoContentSystem : End time =2013-10-31 10:13:26.907] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [PatchManagement] [131] [] [Closing Patch Session] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [Loader.QueueRunner] [131] [] [Handler finished processing action ID: 14221, Type: SUBSCRIPTION_DOWNLOAD, Status: true] [] []
[DEBUG] [10/31/13 10:13:26 AM] [] [Loader.QueueRunner] [131] [] [Updated to database the action ID: 14221] [] []