Использование программы Service Trace Viewer для просмотра скоррелированных трассировок и устранения неполадок

Статья
02/28/2011

В этом разделе описываются формат данных трассировки, способ просмотра этих данных, а также подходы, в которых используется программа Service Trace Viewer для устранения неполадок приложения.

Использование программы Service Trace Viewer

Программа Service Trace Viewer системы Windows Communication Foundation (WCF) помогает сопоставлять диагностические трассировки, создаваемые прослушивателями WCF, для выявления основной причины ошибки. Эта программа позволяет легко просматривать, группировать и фильтровать трассировки в целях диагностики, устранения и проверки неисправностей, связанных со службами WCF. Дополнительные сведения об использовании этой программы см. в разделе Программа Service Trace Viewer (SvcTraceViewer.exe).

В этом разделе содержатся снимки экранов трассировок, созданные при выполнении образца Трассировка и ведение журнала сообщений и просмотре с помощью программы Программа Service Trace Viewer (SvcTraceViewer.exe). Показывается, как интерпретировать содержимое трассировки, действия и взаимосвязь между ними, а также как анализировать большое количество трассировок при устранении неполадок.

Просмотр содержимого трассировки

Событие трассировки содержит следующие наиболее важные сведения:

имя действия, если задано;
время создания;
уровень трассировки;
имя источника трассировки;
имя процесса;
идентификатор потока;
уникальный идентификатор трассировки, которым является URL-адрес, указывающий целевой объект в библиотеке MSDN в Интернете, из которой можно получить дополнительные данные, связанные с трассировкой.

Все эти сведения можно увидеть в верхней правой панели программы Service Trace Viewer или в разделе Основные сведения форматированного представления нижней правой панели при выборе трассировки.

Примечание
Если клиент и служба установлены на одном и том же компьютере, то будут присутствовать трассировки для обоих приложений. Их можно отфильтровать по столбцу Имя процесса.

Кроме того, в форматированном представлении отображаются также описание трассировки и дополнительные подробные сведения, если они доступны. Дополнительные подробные сведения могут включать тип и сообщение исключения, стеки вызовов, действие сообщения, поля «от/к» и другую информацию об исключении.

В представлении XML имеются следующие важные теги xml.

<SubType> (уровень трассировки).
<TimeCreated>.
<Source> (имя источника трассировки).
<Correlation> (идентификатор действия, заданный при создании трассировки).
<Execution> (процесс и идентификатор потока).
<Computer>.
<ExtendedData>, включая <Action>, <MessageID> и <ActivityId>, заданные в заголовке сообщения при отправке сообщения.

При анализе трассировки «Передано сообщение по каналу» отображаются следующие сведения.

<E2ETraceEvent xmlns="https://schemas.microsoft.com/2004/06/E2ETraceEvent">
   <System xmlns="https://schemas.microsoft.com/2004/06/windows/eventlog/system">
      <EventID>262163</EventID>
      <Type>3</Type>
      <SubType Name="Information">0</SubType>
      <Level>8</Level>
      <TimeCreated SystemTime="2006-08-04T18:45:30.8491051Z" />
      <Source Name="System.ServiceModel" />
       <Correlation ActivityID="{27c6331d-8998-43aa-a382-03239013a6bd}"/>
       <Execution ProcessName="client" ProcessID="1808" ThreadID="1" />
       <Channel />
       <Computer>TEST1</Computer>
   </System>
   <ApplicationData>
       <TraceData>
          <DataItem>
             <TraceRecord xmlns="https://schemas.microsoft.com/2004/10/E2ETraceEvent/TraceRecord" Severity="Information">
                 <TraceIdentifier>https://msdn.microsoft.com/ru-RU/library/System.ServiceModel.Channels.MessageSent.aspx</TraceIdentifier>
                 <Description>Sent a message over a channel.</Description>
                 <AppDomain>client.exe</AppDomain>
                 <Source>System.ServiceModel.Channels.ClientFramingDuplexSessionChannel/35191196</Source>
                <ExtendedData xmlns="https://schemas.microsoft.com/2006/08/ServiceModel/MessageTransmitTraceRecord">

                  <MessageProperties>
                     <AllowOutputBatching>False</AllowOutputBatching>
                  </MessageProperties>
                  <MessageHeaders>
                     <Action d4p1:mustUnderstand="1" xmlns:d4p1="http://www.w3.org/2003/05/soap-envelope" xmlns="http://www.w3.org/2005/08/addressing">http://Microsoft.ServiceModel.Samples/ICalculator/Multiply</Action>
                     <MessageID xmlns="http://www.w3.org/2005/08/addressing">urn:uuid:7c6670d8-4c9c-496e-b6a0-2ceb6db35338</MessageID>
                     <ActivityId CorrelationId="b02e2189-0816-4387-980c-dd8e306440f5" xmlns="https://schemas.microsoft.com/2004/09/ServiceModel/Diagnostics">27c6331d-8998-43aa-a382-03239013a6bd</ActivityId>
                     <ReplyTo xmlns="http://www.w3.org/2005/08/addressing">
                        <Address>http://www.w3.org/2005/08/addressing/anonymous</Address>
                    </ReplyTo>
                    <To d4p1:mustUnderstand="1" xmlns:d4p1="http://www.w3.org/2003/05/soap-envelope" xmlns="http://www.w3.org/2005/08/addressing">net.tcp://localhost/servicemodelsamples/service</To>
                  </MessageHeaders>
                  <RemoteAddress>net.tcp://localhost/servicemodelsamples/service</RemoteAddress>
                </ExtendedData>
            </TraceRecord>
          </DataItem>
       </TraceData>
   </ApplicationData>
</E2ETraceEvent>

Трассировка ServiceModel E2E

Когда для источника трассировки System.ServiceModel задано значение switchValue, отличное от Off и ActivityTracing, WCF создает действия и перенаправления для обработки WCF.

Действие — это логический блок обработки, объединяющий все связанные с ним трассировки. Например, для каждого запроса можно определить одно действие. Перенаправления создают причинно-следственную связь между действиями внутри конечных точек. Распространение идентификатора действия позволяет соотносить действия в разных конечных точках. Это можно сделать, задав propagateActivity=true в конфигурации в каждой конечной точке. Действия, перенаправления и распространение позволяют выполнить корреляцию ошибок. Это ускоряет выявление основной причины ошибки.

На клиенте для каждого вызова объектной модели создается одно действие WCF (например, Open ChannelFactory, Add, Divide и т. д.). Каждый вызов операции обрабатывается в действии Process Action.

На левой панели приведенного ниже снимка экрана, полученного из образца Трассировка и ведение журнала сообщений, отображается список созданных в клиентском процессе действий, отсортированных по дате создания. Ниже приведен хронологический список действий.

Создана фабрика каналов (ClientBase).
Открыта фабрика каналов.
Обработано действие Add.
Установлен безопасный сеанс (это ПРОИЗОШЛО по первому запросу) и обработаны три ответных сообщения инфраструктуры безопасности: RST, RSTR, SCT (Process message 1, 2, 3).
Обработаны запросы "Вычесть", "Умножить" и "Разделить".
Закрыта фабрика каналов, в результате чего закрыт безопасный сеанс и обработана отмена ответа на сообщение безопасности.

Сообщения инфраструктуры безопасности видны из-за привязки wsHttpBinding.

Примечание
В WCF показываются ответные сообщения, первоначально обрабатываемые в отдельном действии (Process message) до их корреляции посредством перенаправления с соответствующим действием Process Action, которое включает сообщение запроса. Это происходит для сообщений инфраструктуры и асинхронных запросов из-за необходимости проверки сообщения, чтения заголовка с идентификатором activityId и идентификации существующего действия Process Action с помощью этого идентификатора для корреляции с ним. Для синхронных запросов ответ блокируется и поэтому известно, к какому действию Process Action относится ответ.

В WCF показываются ответные сообщения, первоначально обрабатываемые в отдельном действии (Process message) до их корреляции посредством перенаправления с соответствующим действием Process Action, которое включает сообщение запроса. Это происходит для сообщений инфраструктуры и асинхронных запросов из-за необходимости проверки сообщения, чтения заголовка с идентификатором activityId и идентификации существующего действия Process Action с помощью этого идентификатора для корреляции с ним. Для синхронных запросов ответ блокируется и поэтому известно, к какому действию Process Action относится ответ.