Thursday, 11 August 2016

Troubleshooting ConfigMgr Application Deployments: Detailed log file analysis

There are a lot of moving parts in a ConfigMgr 2012 application deployment.  The following is a detailed log file analysis of a successful deployment to aide in troubleshooting.
Client Side Application Deployment related log files:
    • AppDiscovery.log
    • AppEnforce.log
    • AppIntentEval.log
    • CAS.log
    • CIAgent.log
    • CIDownloader.log
    • CIStateStore.log
    • CIStore.log
    • CITaskMgr.log
    • DataTransferService.log
    • DCMAgent.log
    • DCMReporting.log
    • LocationServices.log
    • PolicyAgent.log
See this post for a script to easily open above logs merged in CMTRACE
Scenario:
Admin deploys required application (7-Zip 9.20 (x64 edition)) which depends on (Notepad++)
Application Details:

Component Flow with details from client and MP logs:
1. Policyagent.log
a. Compiling policy ‘{0E7601CA-32A4-4634-864A-2637953B5EC8}’ version ‘1.00’
— Note this is the deployment ID
b. Initializing download of policy ‘CCM_Policy_Policy5.PolicyID=”ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f/VI/VS”,PolicySource=”SMS:PRI”,PolicyVersion=”8.00″‘ from ‘http://DCS-ConfigMgr02.dcs.local/SMS_MP/.sms_pol?ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f/VI/VS.SHA256:60065CB91DBB818EB1133A21FBDA139C9BE5D1505DB00350ABB06844C3146CC3‘
2. DataTransferService.log
a. DTSJob {4DF683AB-99C6-4E40-AE0D-0DAA14E5DC69} created to download from ‘http://DCS-ConfigMgr02.dcs.local:80/SMS_MP‘ to ‘C:\Windows\CCM\Temp’.
b. DTSJob {4DF683AB-99C6-4E40-AE0D-0DAA14E5DC69} in state ‘PendingDownload’
c. Download of policy started – DTS job ID: ‘{4DF683AB-99C6-4E40-AE0D-0DAA14E5DC69}’
3. MP_GetPolicy.log
a. MP GP: Query String Before Decode : ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f/VI/VS.SHA256:60065CB91DBB818EB1133A21FBDA139C9BE5D1505DB00350ABB06844C3146CC3
b. MP IP: CMPISAPIProxy::GetPolicyBodyFromDB() succeeded
c. MP GP: CProcessPolicyBlobRequest::GetPolicyBlobFromDatabase() succeeded
4. DatatransferService.log
a. DTSJob {4DF683AB-99C6-4E40-AE0D-0DAA14E5DC69} in state ‘RetrievedData’.
b. DTSJob {4DF683AB-99C6-4E40-AE0D-0DAA14E5DC69} successfully completed download.
5. PolicyAgent.log
a. Download of policy CCM_Policy_Policy5.PolicyID=”ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f/VI/VS”,PolicySource=”SMS:PRI”,PolicyVersion=”8.00″ completed (DTS Job ID: {4DF683AB-99C6-4E40-AE0D-0DAA14E5DC69})
b. Applying policy {0E7601CA-32A4-4634-864A-2637953B5EC8}
c. The policy is applied to \root\ccm\policy\machine\actualconfig\ccm_applicationCIAssigment in WMI
6. PolicyEvaluator.log
a. Updating policy CCM_Policy_Policy5.PolicyID=”ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f/VI/VS”,PolicySource=”SMS:PRI”,PolicyVersion=”8.00″
b. Applied policy CCM_Policy_Policy5.PolicyID=”ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f/VI/VS”,PolicySource=”SMS:PRI”,PolicyVersion=”8.00″
7. PolicyAgentProvider.log
a. Picks up change to (\root\ccm\policy\machine\actualconfig\ccm_applicationCIAssigment ) via an instance creation event
b. __InstanceCreationEvent settings change on object CCM_ApplicationCIAssignment.AssignmentID=”{0E7601CA-32A4-4634-864A-2637953B5EC8}”.
8. CCMExec.log
a. Notifying endpoint ‘DCMAgent’ of __InstanceCreationEvent settings change on object CCM_ApplicationCIAssignment.AssignmentID=”{0E7601CA-32A4-4634-864A-2637953B5EC8}” for user ‘S-1-5-18’.
9. Scheduler.log
a. Initialized trigger (“A5C15AC000080001”) for schedule ‘Machine/DEADLINE:{0E7601CA-32A4-4634-864A-2637953B5EC8}’:
b. SMSTrigger ‘A5C15AC000080001’ for scheduler ‘Machine/DEADLINE:{0E7601CA-32A4-4634-864A-2637953B5EC8}’ will fire at 05/01/2013 09:47:00 AM without randomization.
10. CIStore.log
a. CCIStore::ProcessCITargetEvent – CI ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/Application_68919b74-5ef9-45ba-957b-c53fc683ec1f:8 will be targeted for SYSTEM
b. CCIStoreTargetedCIDownloader::AddCI – CI Modelname:ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/Application_68919b74-5ef9-45ba-957b-c53fc683ec1f Version:8 has been added for download
11. CIStateStore.log
a. CCIStateStore::CacheCIState – CI ModelName ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_6f921dcd-eabd-4801-a2b7-43488b19f429, Revision 5 will be cached for SYSTEM
b. CCIStateTransition::ExtractStateDetails – CI ModelName ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_6f921dcd-eabd-4801-a2b7-43488b19f429, version 5 not found in store.
12. Skipping some stuff….
13. DCMAgent.log
CDCMAgent::HandleMessage – Message received for machine: ‘<?xml version=’1.0′ ?>
<CIAssignmentMessage MessageType=’EnforcementDeadline’>
<AssignmentID>{0E7601CA-32A4-4634-864A-2637953B5EC8}</AssignmentID>
</CIAssignmentMessage>’
14. DCMAgent.log —-Starts CI Agent Job
a. Queued 0xe action for assignment id={0E7601CA-32A4-4634-864A-2637953B5EC8} name=7-Zip 9.20 (x64 edition)_Updates Test_Install targeted to machine
b. DCMAgentJob({8D792199-5EF3-4802-8776-A9188599D0E2}): CDCMAgent::InitiateCIAgentJob – Starting CI Agent Job {BEB10520-8FB4-4CA7-A560-D5F86FEF5A02} for target: machine. Refer to this CI agent job ID in ciagent.log for more details —–FOLLOW THIS JOB ID…..
15. CIAgent.log –queues job to download CIs
a. CIAgentJob({BEB10520-8FB4-4CA7-A560-D5F86FEF5A02}): TransitionState(From=WaitingForAssignedCI, To=DownloadingCIs) for Event=Transition        CIAgent        5/1/2013 9:47:02 AM        1564 (0x061C)
b. CIAgentJob({BEB10520-8FB4-4CA7-A560-D5F86FEF5A02}): CAgentJob::HandleEvent(Event=Transition, CurrentState=DownloadingCIs)        CIAgent        5/1/2013 9:47:02 AM
16. CIDownloader.log
a. [Calculate Scope] – Adding CI Modelname:ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f Version:8 to Scoped CIs List of root Modelname:ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f Version:8         CIDownloader
b. CIDownloaderJob({FF189CC4-A9FC-465D-8230-5DA2FA16340E}): CI with ModelName ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f, Version 8. Model:(null) added to job.        CIDownloader
c. CCIDownloader::StartJob        CIDownloader
17. CIAgent.log
a. CIAgentJob({BEB10520-8FB4-4CA7-A560-D5F86FEF5A02}): Started CIDownloadJob({FF189CC4-A9FC-465D-8230-5DA2FA16340E})
18. DataTransferService.log – Downloads the CI
a. Added (source=.sms_dcm?Id&DocumentId=ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f/8/MANIFEST&Hash=14E98FE0BA0C7471653D4A0E4A7DEB8C60493802BE334849CBB04F16D4F19B49&Compression=zlib,dest={9869B5A1-0182-45F0-BBF6-804B8514A568}_1.zip) pair from manifest.        DataTransferService        5/1/2013 9:47:03 AM        1564 (0x061C)
b. Added (source=.sms_dcm?Id&DocumentId=ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f/8/PROPERTIES&Hash=265C7854E451B918BF3A46C2A33A4FC7D3C86038C5565E69C8435F00B42175D4&Compression=zlib,dest={9869B5A1-0182-45F0-BBF6-804B8514A568}_2.zip) pair from manifest.        DataTransferService        5/1/2013 9:47:03 AM        1564 (0x061C)
c. DTSJob {00423329-6D3F-45B7-BBBB-B23DF662CEA8} created to download from ‘http://DCS-ConfigMgr02.dcs.local:80/SMS_MP‘ to ‘C:\Windows\CCM\CIDownloader\Staging’.        DataTransferService        5/1/2013 9:47:03 AM        1564 (0x061C)
19. MP_GetSDMPackage.log
a. MP GP: Query String Before Decode : Id&DocumentId=ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f/8/PROPERTIES&Hash=265C7854E451B918BF3A46C2A33A4FC7D3C86038C5565E69C8435F00B42175D4&Compression=zlib        MP_GetSdmPackage_ISAPI        5/1/2013 9:47:04 AM        3028 (0x0BD4)
b. MP GP: GetPolicySendResponseHeaders: Sending response 200 OK        MP_GetSdmPackage_ISAPI        5/1/2013 9:47:04 AM        3028 (0x0BD4)
c. MP GP: GetPolicyDoneWithSession: ServerSupportFunction() returned 0x0        MP_GetSdmPackage_ISAPI        5/1/2013 9:47:04 AM        3028 (0x0BD4)
20. DatatransferService.log
a. DTSJob {00423329-6D3F-45B7-BBBB-B23DF662CEA8} successfully completed download.
21. CIDownloader.log – verify hashes of downloaded SDM packages
a. VerifyCIDocumentHash – Preparing to verify hash for CI document ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f/8/MANIFEST        CIDownloader        5/1/2013 9:47:04 AM        1564 (0x061C)
b. VerifyCIDocumentHash – Preparing to verify hash for CI document ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f/8/PROPERTIES        CIDownloader        5/1/2013 9:47:04 AM        1564 (0x061C)
22. CIStore.log – persists CI in store…finds digest doesn’t exist
a. CCIStore:StoreCI        CIStore        5/1/2013 9:47:04 AM        1564 (0x061C)
Successfully persisted CI ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f.8        CIStore        5/1/2013 9:47:04 AM        1564 (0x061C)
b. CCIStore:StoreCI finished.        CIStore        5/1/2013 9:47:04 AM        1564 (0x061C)
23. CiDownloader.log –need to get the digest
a. CCIDigestStore::CheckCIExists – Digest missing. CI does not exist.        CIDownloader        5/1/2013 9:47:04 AM        1564 (0x061C)
b. CCIDownloadItem::AddToManifest – Starting download of DocumentType 1 for ModelName ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/Application_68919b74-5ef9-45ba-957b-c53fc683ec1f, Version 8        CIDownloader        5/1/2013 9:47:04 AM        2968 (0x0B98)
c. CCIDownloadItem::AddToManifest – Starting download of CI content document with DocumentName urn:policy-platform:policy.microsoft.com:smlif:ms.dcm.ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D.RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f:8, DocumentVersion 8 (VS)        CIDownloader        5/1/2013 9:47:04 AM        2968 (0x0B98)
d. CCIDownloadItem::AddToManifest – Starting download of DocumentType 2 for ModelName ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/Application_68919b74-5ef9-45ba-957b-c53fc683ec1f, Version 8        CIDownloader        5/1/2013 9:47:04 AM        2968 (0x0B98)
24. Digests are stored in C:\Windows\CCM\CIDownloader\DigestStore
a.
25. DataTransferService……
26. MP_GetSDMPackage.log — looks like its getting the lantern stuff
a. MP GP: Query String Before Decode : Id&DocumentId=urn:policy-platform:policy.microsoft.com:smlif:ms.dcm.ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D.RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f:8&Hash=E91C72BDA6C1FAB8C0409B578A3A08942E9AD2330781BA33380996CB3D7CC057&Compression=zlib        MP_GetSdmPackage_ISAPI        5/1/2013 9:47:06 AM        3028 (0x0BD4)
b. MP GP: Query String  After Decode : Id&DocumentId=urn:policy-platform:policy.microsoft.com:smlif:ms.dcm.ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D.RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f:8&Hash=E91C72BDA6C1FAB8C0409B578A3A08942E9AD2330781BA33380996CB3D7CC057&Compression=zlib        MP_GetSdmPackage_ISAPI        5/1/2013 9:47:06 AM        3028 (0x0BD4)
27. Skipping….
28. CIAgent.log
a. CIAgentJob({BEB10520-8FB4-4CA7-A560-D5F86FEF5A02}): StartEnactment – CI – ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f        CIAgent        5/1/2013 9:47:19 AM        1564 (0x061C)
b. CIAgentJob({BEB10520-8FB4-4CA7-A560-D5F86FEF5A02}): StartEnactment – Attempting to invoke Policy Platform Client        CIAgent        5/1/2013 9:47:19 AM        1564 (0x061C)
29. AppDiscovery.log –Lantern Job started now that we have all the Cis
a. Entering ExecQueryAsync for query “select * from CCM_AppDeliveryType where (AppDeliveryTypeId = “ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_ba2de68c-3fed-46e0-a35a-ac4abd8a8844″ AND Revision = 1)”        AppDiscovery        5/1/2013 9:47:22 AM        796 (0x031C)
b.    Performing detection of app deployment type automated(ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_ba2de68c-3fed-46e0-a35a-ac4abd8a8844, revision 1) for system.        AppDiscovery        5/1/2013 9:47:22 AM        796 (0x031C)
c. +++ Application not discovered. [AppDT Id: ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_ba2de68c-3fed-46e0-a35a-ac4abd8a8844, Revision: 1]        AppDiscovery        5/1/2013 9:47:22 AM        796 (0x031C)
d. +++ Did not detect app deployment type automated(ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_ba2de68c-3fed-46e0-a35a-ac4abd8a8844, revision 1) for system.        AppDiscovery        5/1/2013 9:47:22 AM        796 (0x031C)
e.     Performing detection of app deployment type 7-Zip 9.20 (x64 edition) – Windows Installer (*.msi file)(ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_6f921dcd-eabd-4801-a2b7-43488b19f429, revision 5) for system.        AppDiscovery        5/1/2013 9:47:22 AM        796 (0x031C)
f. +++ MSI application not discovered [MSI Product Code: {23170F69-40C1-2702-0920-000001000000}, MSI Product version: ]        AppDiscovery        5/1/2013 9:47:22 AM        796 (0x031C)
g. +++ Did not detect app deployment type 7-Zip 9.20 (x64 edition) – Windows Installer (*.msi file)(ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_6f921dcd-eabd-4801-a2b7-43488b19f429, revision 5) for system.        AppDiscovery        5/1/2013 9:47:22 AM        796 (0x031C)
h. +++ MSI application not discovered [MSI Product Code: {23170F69-40C1-2702-0920-000001000000}, MSI Product version: ]        AppDiscovery        5/1/2013 9:47:22 AM        796 (0x031C)
Lantern job:C92A474B-9A85-4D94-95A7-4C2073FD84BA succeeded.        CIAgent        5/1/2013 9:47:23 AM        1564 (0x061C)
30. CiAgent.log – got lantern reports  –HANDOFF FROM LANTERN…STEP 9 from PPT
a. CIAgentJob({BEB10520-8FB4-4CA7-A560-D5F86FEF5A02}): ReportMethodInvocation :: Obtained lantern reports        CIAgent        5/1/2013 9:47:23 AM        2816 (0x0B00)
31. AppIntentEval.log
a. ScopeId_C5E06358-8D5B-4184-A237-25DA7C6F83C7/DeploymentType_c0458a8b-9a04-4bd9-a9d3-e765305622a5/1 :- Current State = NotInstalled, Applicability = Applicable, ResolvedState = None, ConfigureState = NotNeeded, Title = tetretr        AppIntentEval        5/1/2013 9:47:24 AM        2816 (0x0B00)
b. ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_6f921dcd-eabd-4801-a2b7-43488b19f429/5 :- Title = 7-Zip 9.20 (x64 edition) – Windows Installer (*.msi file), Direct seleted dependencies = ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_ba2de68c-3fed-46e0-a35a-ac4abd8a8844/1 title=automated,         AppIntentEval        5/1/2013 9:47:24 AM        2816 (0x0B00)
c. ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_6f921dcd-eabd-4801-a2b7-43488b19f429/5 :- Current State = NotInstalled, Applicability = Applicable, ResolvedState = Installed, ConfigureState = NotNeeded, Title = 7-Zip 9.20 (x64 edition) – Windows Installer (*.msi file)        AppIntentEval        5/1/2013 9:47:24 AM        2816 (0x0B00)
d. ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_ba2de68c-3fed-46e0-a35a-ac4abd8a8844/1 :- Current State = NotInstalled, Applicability = Applicable, ResolvedState = Available, ConfigureState = NotNeeded, Title = automated        AppIntentEval        5/1/2013 9:47:24 AM        2816 (0x0B00)
e. ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/Application_68919b74-5ef9-45ba-957b-c53fc683ec1f/8 :- Current State = NotInstalled, Applicability = Applicable, ResolvedState = Installed, ConfigureState = NotNeeded, Title = 7-Zip 9.20 (x64 edition)        AppIntentEval        5/1/2013 9:47:24 AM        2816 (0x0B00)
f. ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/RequiredApplication_68919b74-5ef9-45ba-957b-c53fc683ec1f/8 :- Current State = NonCompliant, Applicability = Applicable, ResolvedState = Compliant, ConfigureState = NotNeeded, Title = ApplicationIntentPolicy        AppIntentEval        5/1/2013 9:47:24 AM        2816 (0x0B00)
32. DCMReporting.log
a. CDCMReporting::ProcessCIReports        DCMReporting        5/1/2013 9:47:24 AM        2816 (0x0B00)
b. CDCMReporting::GenerateXMLReports        DCMReporting        5/1/2013 9:47:24 AM        2816 (0x0B00)
33. CIStateStore.log
a. An existing CI state is changed        CIStateStore        5/1/2013 9:47:24 AM        2816 (0x0B00)
b. [ScopeId_C5E06358-8D5B-4184-A237-25DA7C6F83C7/DeploymentType_c0458a8b-9a04-4bd9-a9d3-e765305622a5:1] CIState changed: Unknown –> NonCompliant        CIStateStore        5/1/2013 9:47:24 AM        2816 (0x0B00)
c. [ScopeId_C5E06358-8D5B-4184-A237-25DA7C6F83C7/DeploymentType_c0458a8b-9a04-4bd9-a9d3-e765305622a5:1] CIResolvedState changed: Unknown –> None        CIStateStore        5/1/2013 9:47:24 AM        2816 (0x0B00)
d. [ScopeId_C5E06358-8D5B-4184-A237-25DA7C6F83C7/DeploymentType_c0458a8b-9a04-4bd9-a9d3-e765305622a5:1] CIApplicability changed: Unknown –> Applicable        CIStateStore        5/1/2013 9:47:24 AM        2816 (0x0B00)
e. [ScopeId_C5E06358-8D5B-4184-A237-25DA7C6F83C7/DeploymentType_c0458a8b-9a04-4bd9-a9d3-e765305622a5:1] AreDetailsUpdated: Yes        CIStateStore        5/1/2013 9:47:24 AM        2816 (0x0B00)
34. StateMessage
a. GetAllInstances – Executing SQL ‘SELECT * FROM CCM_StateMsg’        StateMessageProvider        5/1/2013 9:47:24 AM        3632 (0x0E30)
b. GetAllInstances – 491 instance(s) of ‘CCM_StateMsg’ found        StateMessageProvider        5/1/2013 9:47:24 AM        3632 (0x0E30)
c. State message(State ID : 2) with TopicType 1701 and TopicId ScopeId_C5E06358-8D5B-4184-A237-25DA7C6F83C7/DeploymentType_c0458a8b-9a04-4bd9-a9d3-e765305622a5/1 has been recorded for SYSTEM        StateMessage        5/1/2013 9:47:24 AM        2816 (0x0B00)
d. Adding message with TopicType 1701 and TopicId ScopeId_C5E06358-8D5B-4184-A237-25DA7C6F83C7/DeploymentType_c0458a8b-9a04-4bd9-a9d3-e765305622a5/1 to WMI        StateMessage        5/1/2013 9:47:24 AM        2816 (0x0B00)
35. CIAgentJob.log
a. CIAgentJob({BEB10520-8FB4-4CA7-A560-D5F86FEF5A02}):State – Reporting (scan):: AppModel – ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/Application_68919b74-5ef9-45ba-957b-c53fc683ec1f:8 – State = NotInstalled ResolvedState = Installed Applicability = Applicable ConfigureState= NotNeeded
36. CITaskMgr.log
a. Initiating task ContentDownload for CI ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_6f921dcd-eabd-4801-a2b7-43488b19f429.5 (7-Zip 9.20 (x64 edition) – Windows Installer (*.msi file)) for target: , consumer: {579D5D8C-39E2-47F1-8108-28DD1C98127E}
37. CAS.log
a. Requesting content Content_fa9a5c9a-06a7-4789-9229-3a71793f1b0c.1, size(KB) 5517, under context System with priority Medium
38. ContentTransferManager.log – 2 content requests….
a. Created and Sent Location Request ‘{08F507F0-DFC6-405E-AB8D-41FEAFD83A32}’ for package Content_2d7ca610-a9ea-4e9f-b7eb-fa81d6c8f9b8
b. Created and Sent Location Request ‘{BBE79B3A-37A5-4333-9ABC-311A9AE7FBB8}’ for package Content_fa9a5c9a-06a7-4789-9229-3a71793f1b0c
39. MP_Location.log
a. Content request
40. LocationServices.log
a. Calling back with the following distribution points
b. Distribution Point=’http://DCS-ConfigMgr02.dcs.local/SMS_DP_SMSPKG$/Content_fa9a5c9a-06a7-4789-9229-3a71793f1b0c.1′, Locality=’LOCAL’, DPType=’SERVER’, Version=’7804′, Capabilities='<Capabilities SchemaVersion=”1.0″><Property Name=”SSLState” Value=”0″/></Capabilities>’, Signature=’http://DCS-ConfigMgr02.dcs.local/SMS_DP_SMSSIG$/Content_fa9a5c9a-06a7-4789-9229-3a71793f1b0c.1.tar’, ForestTrust=’TRUE’,
41. CiAgent.log
a. CIAgentJob({BEB10520-8FB4-4CA7-A560-D5F86FEF5A02}): TransitionState(From=StateDownloadingContents, To=StateEnforcingCIs) for Event=Transition
b. The CI Agent Job is the key….
1. Filter CIAgent.log on CIAgentJob({BEB10520-8FB4-4CA7-A560-D5F86FEF5A02}): TransitionState
a.
42. CITaskMgr.log
a. Initiating task Enforce for CI ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_ba2de68c-3fed-46e0-a35a-ac4abd8a8844.1 (automated) for target: , consumer: {B0066C53-C911-49A7-B215-F3CBDE74BEA9}
43. MaintenanceCoordinator.log
a. Adding task for client “CITaskMgr”: id – “7b64e77b-f88d-40b1-ab36-bad61e656018”, pri – 20, service window – 0, max time for sw – 7200.
44. AppEnforce.log
a. +++ Starting Install enforcement for App DT “automated” ApplicationDeliveryType – ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_ba2de68c-3fed-46e0-a35a-ac4abd8a8844, Revision – 1, ContentPath – C:\Windows\ccmcache\1r, Execution Context – System        AppEnforce        5/1/2013 9:47:36 AM        796 (0x031C)
b.     Performing detection of app deployment type automated(ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_ba2de68c-3fed-46e0-a35a-ac4abd8a8844, revision 1) for system.        AppEnforce        5/1/2013 9:47:36 AM        796 (0x031C)
45. CIStateStore.log
a. Another state change event
i. [ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_ba2de68c-3fed-46e0-a35a-ac4abd8a8844:1] CIEnforceState changed: DownloadContentSuccess –> Enforcing        CIStateStore        5/1/2013 9:47:36 AM        1028 (0x0404)
46. AppEnforce.log – Dependency being installed
a. +++ Application not discovered. [AppDT Id: ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_ba2de68c-3fed-46e0-a35a-ac4abd8a8844, Revision: 1]        AppEnforce        5/1/2013 9:47:36 AM        796 (0x031C)
App enforcement environment:
Context: Machine
Command line: npp.5.9.8.Installer.exe /S
Allow user interaction: No
UI mode: 1
User token: null
Session Id: 4294967295
Content path: C:\Windows\ccmcache\1r
Working directory:         AppEnforce        5/1/2013 9:47:36 AM        796 (0x031C)
b.     Executing Command line: “C:\Windows\ccmcache\1r\npp.5.9.8.Installer.exe” /S with system context        AppEnforce        5/1/2013 9:47:36 AM        796 (0x031C)
c.     Waiting for process 3700 to finish.  Timeout = 120 minutes.        AppEnforce        5/1/2013 9:47:36 AM        796 (0x031C)
d.     Process 3700 terminated with exitcode: 0        AppEnforce        5/1/2013 9:47:51 AM        796 (0x031C)
e.     Performing detection of app deployment type automated(ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_ba2de68c-3fed-46e0-a35a-ac4abd8a8844, revision 1) for system.f.         AppEnforce        5/1/2013 9:47:51 AM        796 (0x031C)
g. +++ Discovered application [AppDT Id: ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_ba2de68c-3fed-46e0-a35a-ac4abd8a8844, Revision: 1]        AppEnforce        5/1/2013 9:47:51 AM        796 (0x031C)
h. ++++++ App enforcement completed (15 seconds) for App DT “automated” [ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_ba2de68c-3fed-46e0-a35a-ac4abd8a8844], Revision: 1, User SID: ] ++++++        AppEnforce        5/1/2013 9:47:51 AM        796 (0x031C)
i. ++++++ App enforcement completed (16 seconds) for App DT “7-Zip 9.20 (x64 edition) – Windows Installer (*.msi file)” [ScopeId_D147F349-64C0-460B-BDEC-32EA8C6FFD2D/DeploymentType_6f921dcd-eabd-4801-a2b7-43488b19f429], Revision: 5, User SID: ] ++++++
47. CIAgent Job Runs through the end…….
a. Key is to find the job and folllow it….
Posted by at

No comments:

Post a Comment

Subscribe to: Post Comments (Atom)