Windows Server Update Services(以下WSUS)を利用しているのですが、不定期のスパンで
EventID:10022「最新のカタログ同期の試みは正しく行われませんでした。」という異常終了と思しきイベントと
EventID:10020「カタログ同期は正しく動作しています。」という正常終了と思しきイベントが繰り返されています。
当環境ではWebプロキシは使用しておらず、WSUSからインターネットへのport80/443へは許可されています。
そこで質問が2つあります。
1つ目は、このイベント(10022)がなぜ発生しているかを把握する術をご教示ください。
2つ目は、このイベント(10022)が発生している状態でパッチを指定して承認した場合に正常に承認されるでしょうか。それとも承認が失敗するでしょうか。
直近の4セットの発生日時は以下の通りです。
2019/06/09 3:33:01 EventID:10020
2019/06/09 3:23:01 EventID:10022
2019/06/06 3:38:46 EventID:10020
2019/06/06 3:18:46 EventID:10022
2019/06/05 4:08:37 EventID:10020
2019/06/05 3:18:37 EventID:10022
2019/05/28 3:47:29 EventID:10020
2019/05/28 3:17:29 EventID:10022
発生環境は以下の通りです。
OS: Windows Server 2016 Datacenter バージョン1607 (OSビルド 14393.2906)
WSUS: サーバーバージョン 10.0.14393.2848
以下雑多ではありますが、調べた内容を記載しておきます。
ここTechnetフォーラムにも同様の質問があります。
https://social.technet.microsoft.com/Forums/ja-JP/58f98850-3ec3-4c65-83b7-e1ef46bfe0d3/wsus30-sp2-12456125211254012467125401248910022?forum=wsusja
この質問者の環境ではプロキシを利用しており、Microsoft側のサーバーの変更、プロキシサーバー側のURLフィルタリングの可能性が示唆されています。
しかし、Microsoft側のサーバーの変更であれば発生頻度が多すぎると感じますし、当環境ではWSUSからインターネットへの経路にWebプロキシ機能は使用していません。
https://social.technet.microsoft.com/Forums/ja-JP/f331a077-4175-44e2-bb90-28e47336bef2/wsus30?forum=wsusja
回答者から以下2点のログを確認することをお奨めされています。
%ProgramFiles%\Update Services\LogFiles\Change.log
%ProgramFiles%\Update Services\LogFiles\SoftwareDistribution.log
Change.logで 2019/06/09 3:23:01 EventID:10022 と同タイミングを探しますと、
2019-06-08 18:15:03.533 UTC WSUS configuration has been changed by NT AUTHORITY\NETWORK SERVICE
2019-06-08 18:30:10.672 UTC Downloading retried
というログが記録されていましたが、分単位で一致はしていません。
SoftwareDistribution.logで 2019/06/09 3:23:01 EventID:10022 と同タイミングを探しますと、Change.log同様分単位で一致するログはありませんでした。
SoftwareDistribution.logでChange.logに記録されている上記ログと同タイミングを探しますと、異常終了していると思しき時間帯には「リモート名を解決できませんでした。: 'sws.update.microsoft.com'」というメッセージが記録されています。
しかし、上記ログの後にnslookupコマンドを実行すると解決できることを確認しています。
●2019-06-08 18:15:03.533 UTC付近
2019-06-08 18:15:03.127 UTC Warning WsusService.22 WebServiceCommunicationHelper.ProcessWebServiceProxyException ProcessWebServiceProxyException found Exception was WebException. Action: Retry. Exception Details: System.Net.WebException: リモート名を解決できませんでした。: 'sws.update.microsoft.com'
2019-06-08 18:15:03.127 UTC Warning WsusService.22 WebServiceCommunicationHelper.ProcessWebServiceProxyException ProcessWebServiceProxyException found Exception was WebException. Action: Retry. Exception Details: System.Net.WebException: リモート名を解決できませんでした。: 'sws.update.microsoft.com'
2019-06-08 18:15:03.127 UTC Warning WsusService.22 WebServiceCommunicationHelper.ProcessWebServiceProxyException ProcessWebServiceProxyException found Exception was WebException. Action: Retry. Exception Details: System.Net.WebException: リモート名を解決できませんでした。: 'sws.update.microsoft.com'
2019-06-08 18:15:03.127 UTC Error WsusService.22 WebServiceCommunicationHelper.ProcessWebServiceProxyException ProcessWebServiceProxyException found Exception was WebException but Retry Limit Exceeded. Action: No Retry, Fail. Exception Details: System.Net.WebException: リモート名を解決できませんでした。: 'sws.update.microsoft.com'
2019-06-08 18:15:03.127 UTC Error WsusService.22 CatalogSyncAgentCore.ExecuteSyncProtocol System.Net.WebException: リモート名を解決できませんでした。: 'sws.update.microsoft.com'
2019-06-08 18:15:03.533 UTC Change WsusService.22 AdminDataAccess.ExecuteSPSetConfiguration WSUS configuration has been changed by NT AUTHORITY\NETWORK SERVICE
2019-06-08 18:15:03.533 UTC Info WsusService.22 AuthorizationManager.GetUpstreamServerUriHeader Found config says USS is MU site
2019-06-08 18:15:03.533 UTC Info WsusService.22 ServerSyncLib.GetWebServiceProxyInternal Found config says USS is MU site
2019-06-08 18:15:03.548 UTC Info WsusService.22 CatalogSyncAgentCore.UpdateSyncResultAndGenerateReportingEvent CatalogSyncThreadProcess: report subscription USS communication error
2019-06-08 18:15:03.548 UTC Info WsusService.22 EventLogEventReporter.ReportEvent EventId=386,Type=Error,Category=Synchronization,Message=同期に失敗しました。理由: リモート名を解決できませんでした。: 'sws.update.microsoft.com'。
●2019-06-08 18:30:10.672 UTC付近
2019-06-08 18:30:10.282 UTC Info WsusService.16 CatalogSyncAgentCore.SyncConfigUpdatesFromUSS Need 115 config updates, 0 are new
2019-06-08 18:30:10.282 UTC Info WsusService.16 CatalogSyncAgentCore.SyncConfigUpdatesFromUSS Category Sync: New Config Anchor: 2019-06-08 02:30:09.821
2019-06-08 18:30:10.282 UTC Info WsusService.16 CatalogSyncAgentCore.ExecuteSyncProtocol Catalog Sync: Filter: <filter SyncAnchor="2019-06-04 01:31:49.000"><CategoryList><category delta="1" value="569e8e8f-c6cd-42c8-92a3-efbb20a0f6f5" /><category delta="1" value="bfe5b177-a086-47a0-b102-097e4fa1f807" /></CategoryList><UCList><updateclassification delta="1" value="e6cf1350-c01b-414d-a61f-263d14d133b4" /><updateclassification delta="1" value="0fa1201d-4330-4fa8-8ae9-b877473b6441" /><updateclassification delta="1" value="28bc880e-0592-4cbf-8f95-c79b17911d5f" /><updateclassification delta="1" value="e0789628-ce08-4437-be74-2495b842f43b" /></UCList><languageList><language delta="1" value="1041" /></languageList></filter>
2019-06-08 18:30:10.657 UTC Info WsusService.16 CatalogSyncAgentCore.ExecuteSyncProtocol Catalog Sync: New Anchor: 2019-06-07 14:26:09.000
2019-06-08 18:30:10.657 UTC Info WsusService.16 CatalogSyncAgentCore.ExecuteSyncProtocol Need 0 updates, 0 are new
2019-06-08 18:30:10.657 UTC Info WsusService.16 CatalogSyncAgentCore.GetAndSaveUpdateMetadata Total actually inserted updates: 0
2019-06-08 18:30:10.672 UTC Change WsusService.16 AdminDataAccess.ExecuteSPResumeAllDownloads Downloading retried
2019-06-08 18:30:10.672 UTC Info WsusService.16 CatalogSyncAgentCore.UpdateSyncResultAndGenerateReportingEvent CatalogSyncThreadProcess: report subscription succeeded
2019-06-08 18:30:10.672 UTC Info WsusService.16 EventLogEventReporter.ReportEvent EventId=384,Type=Information,Category=Synchronization,Message=同期は正しく終了しました。
最初に述べました、イベントログのEventID:10022とEventID:10020について以下記載いたします。
Application/EventID:10020/Info
イベントログ詳細内容
ログの名前: Application
ソース: Windows Server Update Services
日付: 2019/06/09 3:33:01
イベント ID: 10020
タスクのカテゴリ: 7
レベル: 情報
キーワード: クラシック
ユーザー: N/A
コンピューター: wsus01
説明:
カタログ同期は正しく動作しています。
イベント XML:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
<System>
<Provider Name="Windows Server Update Services" />
<EventID Qualifiers="0">10020</EventID>
<Level>4</Level>
<Task>7</Task>
<Keywords>0x80000000000000</Keywords>
<TimeCreated SystemTime="2019-06-08T18:33:01.863849200Z" />
<EventRecordID>233177</EventRecordID>
<Channel>Application</Channel>
<Computer>wsus01</Computer>
<Security />
</System>
<EventData>
<Data>カタログ同期は正しく動作しています。</Data>
</EventData>
</Event>
Application/EventID:10022/Error
イベントログ詳細内容
ログの名前: Application
ソース: Windows Server Update Services
日付: 2019/06/09 3:23:01
イベント ID: 10022
タスクのカテゴリ: 7
レベル: エラー
キーワード: クラシック
ユーザー: N/A
コンピューター: wsus01
説明:
最新のカタログ同期の試みは正しく行われませんでした。
イベント XML:
<Event xmlns="http://schemas.microsoft.com/win/2004/08/events/event">
<System>
<Provider Name="Windows Server Update Services" />
<EventID Qualifiers="0">10022</EventID>
<Level>2</Level>
<Task>7</Task>
<Keywords>0x80000000000000</Keywords>
<TimeCreated SystemTime="2019-06-08T18:23:01.805873700Z" />
<EventRecordID>233176</EventRecordID>
<Channel>Application</Channel>
<Computer>wsus01</Computer>
<Security />
</System>
<EventData>
<Data>最新のカタログ同期の試みは正しく行われませんでした。</Data>
</EventData>
</Event>
