询问者
查看sccm 07 软件播发报表 客户端运行结果失败
问题
-
我在使用sccm 07为站点上的客户端分发软件后查看播发的报表, 发现有许多客户端一直显示"正在等待", 而不是像其他那些的显示"成功"
查看这些客户端的播发运行日志exemgr.log可以发现这些电脑的播发状态都会有"waitingcontent"的记录,无法开始安装
在DataTransferService和ContentTransferManager日志里可以找到客户端下载对应播发策略的记录
但就是无法开始下载包并开始安装, 一直是suspend, 或者waitingcontent
分发点是ok的, 其他成功的客户端也是从这些分发点进行下载的
我查找到的部分资料说是因为bits 版本太低, 但异常的客户端也有vista与win7
成功的客户端也有xp
为什么部分客户端会出现这种问题呢?
全部回复
-
Hi,
首先只有client所在的boundary是fast boundary，client才能获得advertised program content。你可以更改advertisement的设置，让slow boundary的clients也可以获得program content。
通常你需要看下面的log来troubleshooting：
- PolicyAgent.log - 确定client收到policy
- DataTransferService.log - 确定client下载了policy
- CAS.log - 确定client找到了DP
- LocationServices.log - 确定client从那个DP获得program content
- Execmgr.log - verify program的运行
你的情况是client的content request pending，这样的话你需要这么做：
这里是一段示例：
首先在execmgr.log中找到你的package相关的信息。
Policy arrived for parent package A010001D program Notepad++ silent install execmgr 3/14/2012 10:50:57 AM 2188 (0x088C) Raising event: [SMS_CodePage(437), SMS_LocaleID(1033)] instance of SoftDistProgramOfferReceivedEvent { AdvertisementId = "A0120009"; ClientID = "GUID:3377074A-2C5A-4A6B-82F3-D1783E3C95DB"; DateTime = "20120314025057.352000+000"; MachineName = "W7SP1X64CLIENT"; ProcessID = 1540; SiteCode = "A01"; ThreadID = 2188; }; execmgr 3/14/2012 10:50:57 AM 2188 (0x088C) Verifying content availability for package A010001D optional program Notepad++ silent install execmgr 3/14/2012 10:51:09 AM 2188 (0x088C) Requesting content from CAS for package A010001D version 1 execmgr 3/14/2012 10:51:10 AM 2188 (0x088C) Successfully created a content request handle {54B8ADAB-053D-46D1-AC9A-A1B27DB3BFD7} for the package A010001D version 1 execmgr 3/14/2012 10:51:10 AM 2188 (0x088C)从上面的log中可以看到content request已经发送了。
然后打开datatransferservices.log, 在这个log里搜索：<advertisement ID>-<packageID>. 根据上面的log，我们应该搜索A0120009-A010001D，会找到下面的内容：
UpdateURLWithTransportSettings(): OLD URL - http://SCCM2007SERVER.TEST.COM/SMS_MP/.sms_pol?A0120009-A010001D-978B3016.1_00 DataTransferService 3/14/2012 10:50:53 AM 4012 (0x0FAC) UpdateURLWithTransportSettings(): NEW URL - http://SCCM2007SERVER.TEST.COM:80/SMS_MP/.sms_pol?A0120009-A010001D-978B3016.1_00 DataTransferService 3/14/2012 10:50:53 AM 4012 (0x0FAC) DTSJob {DBA38E87-A373-4D88-AD56-8C2B1412D181} created to download from 'http://SCCM2007SERVER.TEST.COM/SMS_MP/.sms_pol?A0120009-A010001D-978B3016.1_00' to 'C:\Windows\SysWOW64\CCM\Temp\{8DAD9874-1C4A-4B69-93AC-A06B03AA0135}.tmp'. DataTransferService 3/14/2012 10:50:53 AM 4012 (0x0FAC) DTSJob {DBA38E87-A373-4D88-AD56-8C2B1412D181} in state 'PendingDownload'. DataTransferService 3/14/2012 10:50:53 AM 2068 (0x0814) DTSJob {DBA38E87-A373-4D88-AD56-8C2B1412D181} in state 'DownloadingData'. DataTransferService 3/14/2012 10:50:54 AM 2068 (0x0814) DTSJob {DBA38E87-A373-4D88-AD56-8C2B1412D181} in state 'RetrievedData'. DataTransferService 3/14/2012 10:50:54 AM 1908 (0x0774) DTSJob {DBA38E87-A373-4D88-AD56-8C2B1412D181} successfully completed download. DataTransferService 3/14/2012 10:50:54 AM 1908 (0x0774) DTSJob {DBA38E87-A373-4D88-AD56-8C2B1412D181} in state 'NotifiedComplete'. DataTransferService 3/14/2012 10:50:55 AM 2068 (0x0814) DTS job {DBA38E87-A373-4D88-AD56-8C2B1412D181} has completed: Status : SUCCESS Start time : 03/14/2012 10:50:53 Completion time : 03/14/2012 10:50:54 Elapsed time : 1 seconds DataTransferService 3/14/2012 10:50:55 AM 2068 (0x0814)最后，你的datatransferservices.log里是什么内容？
Thanks
-
你好，在execmgr.log日志中是有类似以下记录：Successfully created a content request handle {54B8ADAB-053D-46D1-AC9A-A1B27DB3BFD7} for the package A010001D version 1 execmgr 3/14/2012 10:51:10 AM 2188 (0x088C)
只不过这个记录的下一句就是Program xxxx change to state STATE_ADVANCED_DOWNLOAD content in progress另外在datatransferservices.log里搜索对应的<advertisement ID>-<packageID>应该是得到播发任务的策略，这个记录也是有的
不过下载包的记录：DTSJob xxxxx created to download from 'file:\\xxxx.paicdom.local\SMSPKGD$\xxxx' to 'C:\WINDOWS\system32\CCM\Cache\xxxx.System'. 在这些失败的电脑上无法找到
而在cas.log中会有以下记录：Releasing content request xxxx
Canceling CTM job xxxx for content xxxx
Error: DeleteDirectory:- Failed to delete Directory C:\Windows\system32\CCM\Cache\xxxx.System with Error 0x00000002.和边界的设定联系大吗？当前站点边界使用了ip地址范围，快速的设定
而且同样集合的同一个播发是部分计算机运行失败
-
Hi,
我认为你boundary的设定是正确的，因为你的client正确安装，并且client assignment也成功了。
是一下，在失败的client上，打开control panel的configuration manager applet。然后在advanced tab上选择“delete files...” 来清空client content cache。然后重启一下这个client。
看一下你的execmgr.log中是否有这些：
<![LOG[Software Distribution Site Settings for the client are missing from WMI.]LOG]!><time="11:22:46.852+-60" date="03-01-2012" component="execmgr" context="" type="3" thread="4552" file="softdistpolicy.cpp:1312">
<![LOG[Software Distribution Site Settings for the client are missing from WMI.]LOG]!><time="11:22:46.883+-60" date="03-01-2012" component="execmgr" context="" type="3" thread="4552" file="softdistpolicy.cpp:1312">
<![LOG[Common Client Agent Settings for the client are missing from WMI.]LOG]!><time="11:22:46.883+-60" date="03-01-2012" component="execmgr" context="" type="3" thread="4552" file="softdistpolicy.cpp:1413">我以前也碰到过同样的问题，解决的办法是重新安装了client，然后把client重启一下。最后All Fixed.
最后，你可以共享一下你的client的log：execmgr.log, datatransferservice.log, cas.log, LocationServices.log, ContentTransferManager.log吗？比如在skydrive上共享一下。或者你可以发送邮件到我的v-elixu@hotmail.com邮箱里。这些logs可以提供更多的信息来帮你troubleshooting。
谢谢
-
Hi,
我认为你boundary的设定是正确的，因为你的client正确安装，并且client assignment也成功了。
是一下，在失败的client上，打开control panel的configuration manager applet。然后在advanced tab上选择“delete files...” 来清空client content cache。然后重启一下这个client。
看一下你的execmgr.log中是否有这些：
<![LOG[Software Distribution Site Settings for the client are missing from WMI.]LOG]!><time="11:22:46.852+-60" date="03-01-2012" component="execmgr" context="" type="3" thread="4552" file="softdistpolicy.cpp:1312">
<![LOG[Software Distribution Site Settings for the client are missing from WMI.]LOG]!><time="11:22:46.883+-60" date="03-01-2012" component="execmgr" context="" type="3" thread="4552" file="softdistpolicy.cpp:1312">
<![LOG[Common Client Agent Settings for the client are missing from WMI.]LOG]!><time="11:22:46.883+-60" date="03-01-2012" component="execmgr" context="" type="3" thread="4552" file="softdistpolicy.cpp:1413">我以前也碰到过同样的问题，解决的办法是重新安装了client，然后把client重启一下。最后All Fixed.
最后，你可以共享一下你的client的log：execmgr.log, datatransferservice.log, cas.log, LocationServices.log, ContentTransferManager.log吗？比如在skydrive上共享一下。或者你可以发送邮件到我的v-elixu@hotmail.com邮箱里。这些logs可以提供更多的信息来帮你troubleshooting。
谢谢
清空client content cache后重启client是指重启sccm client, 把服务进程重启一遍吗? 要验证此办法是否有效需要为播发再分配一次程序吗?
execmgr.log里没有"Common Client Agent Settings for the client are missing from WMI"这种关键字信息的记录,基本上是我在前面的回复中有关于execmgr.log记录的信息的不断重复
晚些时候把相关日志内容提供出来,谢谢!
-
我发现我的站点中有以下的设置，不知道会不会影响到这些失败的计算机：
播发-属性-分发点下有一个设置如下：
当客户端在慢速或不可靠网络边界内连接时-选择不运行程序
这样会影响包的下载和运行吗？
当客户端在快速网络内连接-从分发点下载并本地程序日志内容如下：
cas.log GetLogonUserSid failed at GetTokenSids 0x800704dd ContentAccess 2012-04-12 10:18:10 3580 (0x0DFC) Releasing content request {024C0D51-63AE-4CD6-903B-0B4F9D5A6E0B} ContentAccess 2012-04-12 10:18:10 3580 (0x0DFC) Canceling CTM job {AB2A64A4-D256-43B2-BDEB-544A5B91ACF4} for content PAB00052.1 ContentAccess 2012-04-12 10:18:10 3580 (0x0DFC) Error: DeleteDirectory:- Failed to delete Directory C:\WINDOWS\system32\CCM\Cache\PAB00052.1.System with Error 0x00000002. ContentAccess 2012-04-12 10:18:11 3580 (0x0DFC) Requesting content PAB00052.1, size(KB) 2260, under context System with priority Low ContentAccess 2012-04-12 17:30:01 2508 (0x09CC) Submitted CTM job {A93E76F2-E804-4258-9B98-BB210EDB5C9E} to download Content PAB00052.1 under context System ContentAccess 2012-04-12 17:30:01 2508 (0x09CC) Successfully created download request {701D9D8F-A074-4A96-B6E1-455D97C25D1B} for content PAB00052.1 ContentAccess 2012-04-12 17:30:01 2508 (0x09CC) Releasing content request {701D9D8F-A074-4A96-B6E1-455D97C25D1B} ContentAccess 2012-04-12 19:30:00 1488 (0x05D0) Canceling CTM job {A93E76F2-E804-4258-9B98-BB210EDB5C9E} for content PAB00052.1 ContentAccess 2012-04-12 19:30:00 1488 (0x05D0) Error: DeleteDirectory:- Failed to delete Directory C:\WINDOWS\system32\CCM\Cache\PAB00052.1.System with Error 0x00000002. ContentAccess 2012-04-12 19:30:01 1488 (0x05D0) ContentTransferManager.log Starting CTM job {AB2A64A4-D256-43B2-BDEB-544A5B91ACF4}. ContentTransferManager 2012-04-12 10:17:59 2880 (0x0B40) Cancelled CTM job {AB2A64A4-D256-43B2-BDEB-544A5B91ACF4} ContentTransferManager 2012-04-12 10:18:11 3580 (0x0DFC) Starting CTM job {A93E76F2-E804-4258-9B98-BB210EDB5C9E}. ContentTransferManager 2012-04-12 17:30:01 2508 (0x09CC) Created CTM job {A93E76F2-E804-4258-9B98-BB210EDB5C9E} for user S-1-5-11 ContentTransferManager 2012-04-12 17:30:01 2508 (0x09CC) Created and Sent Location Request '{F8384117-95AA-4DE3-82EB-70D203584687}' for PABkage PAB00052 ContentTransferManager 2012-04-12 17:30:01 2356 (0x0934) CTM job {A93E76F2-E804-4258-9B98-BB210EDB5C9E} entered phase CCM_DOWNLOADSTATUS_DOWNLOADING_DATA ContentTransferManager 2012-04-12 17:30:01 2356 (0x0934) Queued location request '{F8384117-95AA-4DE3-82EB-70D203584687}' for CTM job '{A93E76F2-E804-4258-9B98-BB210EDB5C9E}'. ContentTransferManager 2012-04-12 17:30:01 2356 (0x0934) Cancelled CTM job {A93E76F2-E804-4258-9B98-BB210EDB5C9E} ContentTransferManager 2012-04-12 19:30:01 1488 (0x05D0) 此处日志后面就是重复以上内容，一直未下载包，成功运行的播发还会有以下内容： Created and Sent Location Request '{4376BE50-7197-4C7C-9CC6-416B7C830104}' for PABkage PAB00052 ContentTransferManager 2012-03-28 17:30:00 1908 (0x0774) CTM job {1EAC9623-9CF9-4CE1-8E3D-0CF85F01E2D4} entered phase CCM_DOWNLOADSTATUS_DOWNLOADING_DATA ContentTransferManager 2012-03-28 17:30:00 1908 (0x0774) Queued location request '{4376BE50-7197-4C7C-9CC6-416B7C830104}' for CTM job '{1EAC9623-9CF9-4CE1-8E3D-0CF85F01E2D4}'. ContentTransferManager 2012-03-28 17:30:00 1908 (0x0774) Persisted locations for CTM job {1EAC9623-9CF9-4CE1-8E3D-0CF85F01E2D4}: (LOCAL) file:\\SERVER.addom.LOCAL\SMSPKGE$\PAB00052 (LOCAL) file:\\SERVERDP.addom.LOCAL\SMSPKGD$\PAB00052 ContentTransferManager 2012-03-28 17:30:51 3916 (0x0F4C) CTM job {1EAC9623-9CF9-4CE1-8E3D-0CF85F01E2D4} (corresponding DTS job {10C1ACBB-39DE-47D9-8DB0-150B2E5ACDAD}) started download from 'file:\\SERVER.addom.LOCAL\SMSPKGE$\PAB00052' ContentTransferManager 2012-03-28 17:30:51 3916 (0x0F4C) CTM job {1EAC9623-9CF9-4CE1-8E3D-0CF85F01E2D4} entered phase CCM_DOWNLOADSTATUS_DOWNLOADING_DATA ContentTransferManager 2012-03-28 17:30:51 3672 (0x0E58) CTM job {1EAC9623-9CF9-4CE1-8E3D-0CF85F01E2D4} entered phase CCM_DOWNLOADSTATUS_DOWNLOADING_DATA ContentTransferManager 2012-03-28 17:30:55 3672 (0x0E58) CTM job {1EAC9623-9CF9-4CE1-8E3D-0CF85F01E2D4} entered phase CCM_DOWNLOADSTATUS_DOWNLOADING_DATA ContentTransferManager 2012-03-28 17:30:55 3672 (0x0E58) CTM job {1EAC9623-9CF9-4CE1-8E3D-0CF85F01E2D4} entered phase CCM_DOWNLOADSTATUS_DOWNLOADING_DATA ContentTransferManager 2012-03-28 17:30:55 3916 (0x0F4C) CTM job {1EAC9623-9CF9-4CE1-8E3D-0CF85F01E2D4} successfully processed download completion. ContentTransferManager 2012-03-28 17:33:37 3672 (0x0E58) DataTransferService.log Enumerating DTS jobs for logged on user 'XXX'. DataTransferService 2012-04-12 10:20:16 3172 (0x0C64) Enumeration of DTS jobs for logged on user 'XXX' is complete. DataTransferService 2012-04-12 10:20:16 3172 (0x0C64) UpdateURLWithTransportSettings(): OLD URL - http://SERVER.addom.LOCAL/SMS_MP/.sms_pol?PAB200BB-PAB00052-B047C04F.16_00 DataTransferService 2012-04-12 14:26:05 3428 (0x0D64) UpdateURLWithTransportSettings(): NEW URL - http://SERVER.addom.LOCAL:80/SMS_MP/.sms_pol?PAB200BB-PAB00052-B047C04F.16_00 DataTransferService 2012-04-12 14:26:05 3428 (0x0D64) DTSJob {5C0C36DC-1708-408B-979A-91A94E154258} created to download from 'http://SERVER.addom.LOCAL/SMS_MP/.sms_pol?PAB200BB-PAB00052-B047C04F.16_00' to 'C:\WINDOWS\system32\CCM\Temp\{F74CE1CB-65ED-482F-8833-FCED7B9969C2}.tmp'. DataTransferService 2012-04-12 14:26:05 3428 (0x0D64) DTSJob {5C0C36DC-1708-408B-979A-91A94E154258} in state 'PendingDownload'. DataTransferService 2012-04-12 14:26:05 248 (0x00F8) DTSJob {5C0C36DC-1708-408B-979A-91A94E154258} in state 'DownloadingData'. DataTransferService 2012-04-12 14:26:07 248 (0x00F8) DTSJob {5C0C36DC-1708-408B-979A-91A94E154258} in state 'RetrievedData'. DataTransferService 2012-04-12 14:26:35 1508 (0x05E4) DTSJob {5C0C36DC-1708-408B-979A-91A94E154258} successfully completed download. DataTransferService 2012-04-12 14:26:35 1508 (0x05E4) DTSJob {5C0C36DC-1708-408B-979A-91A94E154258} in state 'NotifiedComplete'. DataTransferService 2012-04-12 14:26:35 3428 (0x0D64) DTS job {5C0C36DC-1708-408B-979A-91A94E154258} has completed: Status : SUCCESS Start time : 04/12/2012 14:26:05 Completion time : 04/12/2012 14:26:35 Elapsed time : 29 seconds DataTransferService 2012-04-12 14:26:35 3428 (0x0D64) execmgr.log Could not find the policy in WMI for PABkage PAB00052 program Enterprise-v102 execmgr 2012-04-12 10:18:08 3580 (0x0DFC) DeletedPolicy object is not found.(0x80002004) execmgr 2012-04-12 10:18:08 3580 (0x0DFC) Could not find the policy in WMI for PABkage PAB00052 program Enterprise-v102 execmgr 2012-04-12 10:18:08 3580 (0x0DFC) DeletedPolicy object is not found.(0x80002004) execmgr 2012-04-12 10:18:08 3580 (0x0DFC) Policy was deleted or expired for program Enterprise-v102. Remove the execution request. execmgr 2012-04-12 10:18:08 3580 (0x0DFC) Raising event: [SMS_CodePage(936), SMS_LocaleID(2052)] instance of SoftDistOfferRejectedExpiredEvent { AdvertisementId = "PAB200BB"; ClientID = "GUID:F6655CC8-4BFE-45A4-AE7E-A8497DBB7BE8"; DateTime = "20120412021816.999000+000"; MachineName = "Computer"; PABkageName = "PAB00052"; ProcessID = 688; ProgramName = "Enterprise-v102"; SiteCode = "DM0"; ThreadID = 3976; }; execmgr 2012-04-12 10:18:16 3976 (0x0F88) Could not find the policy in WMI for PABkage PAB00052 program Enterprise-v102 execmgr 2012-04-12 10:18:17 3976 (0x0F88) DeletedPolicy object is not found.(0x80002004) execmgr 2012-04-12 10:18:17 3976 (0x0F88) A user has logged on. execmgr 2012-04-12 10:20:15 3144 (0x0C48) The logged on user is addom\zzg003 execmgr 2012-04-12 10:20:15 3144 (0x0C48) Policy is updated for Program: Enterprise-v102, PABkage: PAB00052, Advert: PAB200BB execmgr 2012-04-12 14:28:36 1508 (0x05E4) Raising event: [SMS_CodePage(936), SMS_LocaleID(2052)] instance of SoftDistProgramOfferReceivedEvent { AdvertisementId = "PAB200BB"; ClientID = "GUID:F6655CC8-4BFE-45A4-AE7E-A8497DBB7BE8"; DateTime = "20120412062836.305000+000"; MachineName = "Computer"; ProcessID = 688; SiteCode = "DM0"; ThreadID = 1508; }; execmgr 2012-04-12 14:28:36 1508 (0x05E4) Requesting content from CAS for PABkage PAB00052 version 1 execmgr 2012-04-12 17:30:01 2508 (0x09CC) Successfully created a content request handle {701D9D8F-A074-4A96-B6E1-455D97C25D1B} for the PABkage PAB00052 version 1 execmgr 2012-04-12 17:30:01 2508 (0x09CC) Program Enterprise-v102 change to state STATE_ADVANCED_DOWNLOAD content in progress execmgr 2012-04-12 17:30:01 2508 (0x09CC) LocationServices.log Attempting to retrieve local MP from AD LocationServices 2012-04-12 10:20:17 3144 (0x0C48) Current AD site of machine is sz LocationServices 2012-04-12 10:20:17 3144 (0x0C48) Retrieved local Management Point from AD: RISPJT LocationServices 2012-04-12 10:20:17 3144 (0x0C48) Refreshing the Management Point List for site DM0 LocationServices 2012-04-12 10:22:19 1488 (0x05D0) Retrieved management point encryption info from AD. LocationServices 2012-04-12 10:22:19 1488 (0x05D0) Raising event: instance of CCM_CcmHttp_Status { ClientID = "GUID:F6655CC8-4BFE-45A4-AE7E-A8497DBB7BE8"; DateTime = "20120412022219.561000+000"; HostName = "SERVER.addom.LOCAL"; HRESULT = "0x00000000"; ProcessID = 688; StatusCode = 0; ThreadID = 1488; }; LocationServices 2012-04-12 10:22:19 1488 (0x05D0) Refreshing trusted key information LocationServices 2012-04-12 10:22:19 1488 (0x05D0) Raising event: instance of CCM_CcmHttp_Status { ClientID = "GUID:F6655CC8-4BFE-45A4-AE7E-A8497DBB7BE8"; DateTime = "20120412022219.656000+000"; HostName = "SERVER.addom.LOCAL"; HRESULT = "0x00000000"; ProcessID = 688; StatusCode = 0; ThreadID = 1488; }; LocationServices 2012-04-12 10:22:19 1488 (0x05D0) Persisting the management point authentication information in WMI LocationServices 2012-04-12 10:22:19 1488 (0x05D0) Persisted Management Point Authentication Information locally LocationServices 2012-04-12 10:22:19 1488 (0x05D0) Executing Task LSRefreshLocationsTask LocationServices 2012-04-12 11:18:01 2952 (0x0B88) Executing Task LSRefreshLocationsTask LocationServices 2012-04-12 12:18:02 1868 (0x074C) Executing Task LSRefreshLocationsTask LocationServices 2012-04-12 13:18:03 2428 (0x097C) Executing Task LSRefreshLocationsTask LocationServices 2012-04-12 14:18:04 3656 (0x0E48) Executing Task LSRefreshLocationsTask LocationServices 2012-04-12 15:18:05 3744 (0x0EA0) Executing Task LSRefreshLocationsTask LocationServices 2012-04-12 16:18:05 216 (0x00D8) Executing Task LSRefreshLocationsTask LocationServices 2012-04-12 17:18:06 3356 (0x0D1C) Current AD site of machine is sz LocationServices 2012-04-12 17:30:01 2356 (0x0934) Executing Task LSRefreshLocationsTask LocationServices 2012-04-12 18:18:07 2820 (0x0B04) Current AD site of machine is sz LocationServices 2012-04-12 18:18:07 2820 (0x0B04) Executing Task LSRefreshLocationsTask LocationServices 2012-04-12 19:18:08 1672 (0x0688) Current AD site of machine is sz LocationServices 2012-04-12 19:18:08 1672 (0x0688) Executing Task LSRefreshLocationsTask LocationServices 2012-04-12 20:18:09 2252 (0x08CC)
