Uso del visor de seguimiento de servicios para ver seguimientos asociados y para la solución de problemas

Artículo
10/07/2008

En este tema se describe el formato de datos de seguimiento, cómo verlo y enfoques sobre el uso de Service Trace Viewer para solucionar problemas de la aplicación.

Uso de Service Trace Viewer

La herramienta Service Trace Viewer de Windows Communication Foundation (WCF) le ayuda a poner en correlación seguimientos de diagnósticos generados por agentes de escucha de WCF para buscar la causa principal de un error. La herramienta le proporciona una forma de ver, agrupar y filtrar seguimientos de manera sencilla para que pueda diagnosticar, reparar y comprobar los problemas de los servicios de WCF. Para obtener más información sobre el uso de esta herramienta, consulte Service Trace Viewer Tool.

Este tema contiene las capturas de pantalla de seguimientos generados mediante la ejecución del ejemplo de Tracing and Message Logging, cuando se ve mediante la Service Trace Viewer Tool. En este tema se muestra cómo entender el contenido de los seguimientos, las actividades y su correlación y cómo analizar grandes cantidades de seguimientos al solucionar problemas.

Ver el contenido del seguimiento

Un evento de seguimiento contiene la siguiente información más significativa:

Nombre de actividad cuando se establece.
Hora de emisión.
Nivel de seguimiento.
Nombre del origen del seguimiento.
Nombre de proceso.
Id. del subproceso.
Un identificador de seguimiento único, que es una dirección URL que señala a un destino en la biblioteca MSDN en línea, de la que puede obtener más información relacionada con el seguimiento.

Todo esto se puede ver en el panel derecho superior de eService Trace Viewer o en la sección Información básica de la vista con formato del panel inferior derecho al seleccionar un seguimiento.

Además, la vista con formato también proporciona una descripción del seguimiento e información detallada adicional cuando esté disponible. Lo último puede incluir el tipo y mensaje de excepción, pilas de llamadas, la acción del mensaje, campos desde/hasta y otras informaciones de la excepción.

En la vista XML, entre las etiquetas xml útiles se encuentran las siguientes:

<SubType> (nivel de seguimiento).
<TimeCreated>.
<Source> (nombre del origen del seguimiento).
<Correlation> (id. de actividad establecido al emitir el seguimiento).
<Execution> (Id. de proceso y subproceso).
<Computer>.
<ExtendedData>, incluyendo <Action>, <MessageID> e <ActivityId> establecidos en el encabezado del mensaje al enviar un mensaje.

Si examina el seguimiento "Se envió un mensaje a través de un canal", puede ver el contenido siguiente.

<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/en-US/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>

Seguimiento ServiceModel E2E

Cuando el origen del seguimiento System.ServiceModel se establece con un switchValue que no sea Off, e ActivityTracing, WCF crea actividades y transferencias para el procesamiento de WCF.

Una actividad es una unidad lógica de procesamiento que agrupa todos los seguimientos relacionados con esa unidad de procesamiento. Por ejemplo, puede definir una actividad para cada solicitud. Las transferencias crean una relación causal entre las actividades dentro de extremos. La propagación del Id. de actividad le permite relacionar actividades en los extremos. Esto se puede hacer estableciendo propagateActivity=true mediante configuración en cada extremo. Las actividades, transferencias y la propagación le permiten realizar la correlación de errores. De esta manera, puede encontrar más rápidamente la causa raíz de un error.

En el cliente, se crea una actividad de WCF para cada llamada del modelo de objetos (por ejemplo, Abrir ChannelFactory, Agregar, Dividir, etc.) Cada una de las llamadas de operación se procesa en una actividad "Procesar acción".

En la siguiente captura de pantalla, extraída de la muestra del Tracing and Message Logging, el panel izquierdo muestra la lista de actividades creadas en el proceso de cliente, ordenada por hora de creación. A continuación, se muestra una lista cronológica de actividades.

Construyó el generador de canales (ClientBase).
Abrió el generador de canales.
Procesó la acción Agregar.
Configuró la sesión segura (esto OCURRIÓ en la primera solicitud) y procesó tres mensajes de respuesta de la infraestructura de seguridad: RST, RSTR, SCT (Mensaje de proceso 1, 2, 3).
Procesó las solicitudes de sustracción, multiplicación y división.
Cerró el generador de canales y, debido a ello, cerró la sesión segura y procesó la anulación de la respuesta del mensaje de seguridad.

Vemos los mensajes de la infraestructura de seguridad debido al wsHttpBinding.

Nota

En WCF, mostramos mensajes de respuesta que se procesan inicialmente en una actividad independiente (Mensaje de proceso) antes de ponerlos en correlación con la actividad Procesar acción correspondiente que incluye el mensaje de solicitud, a través de una transferencia. Esto sucede para los mensajes de infraestructura y para las solicitudes asincrónicas y se debe al hecho de que debemos inspeccionar el mensaje, leer el encabezado activityId e identificar la actividad Procesar acción existente con ese Id. para correlacionarlo con él. Para solicitudes sincrónicas, bloqueamos la respuesta y, por lo tanto, conocemos con qué actividad Procesar acción se relaciona la respuesta.