Output sequence for WPP software tracing

TheMSsForum.com: The Microsoft Software Forum

Moin,

I use WPP software tracing in my driver. For each trace line the first item
shown is the CPU number in brackets.
I'm wondering if there is a output sequence for those trace messages for the
same time stamp.
I'm currently analyzing a problem within my driver. The driver has a queue
of messages with numbers in sequence.
For any reason I see the messages fetched in a different order:

[3]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173): PutMsgToIrpBuffer:
IRP=89528008 READ cmd=0x82ff nr=0x4fcf
[3]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173): PutMsgToIrpBuffer:
IRP=89528008 READ cmd=0x82ff nr=0x4fd1
[1]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173): PutMsgToIrpBuffer:
IRP=89337E68 READ cmd=0x82ff nr=0x4fce
[2]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173): PutMsgToIrpBuffer:
IRP=8987D8F8 READ cmd=0x82ff nr=0x4fd0

I would expect the following order:

[1]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173): PutMsgToIrpBuffer:
IRP=89337E68 READ cmd=0x82ff nr=0x4fce
[3]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173): PutMsgToIrpBuffer:
IRP=89528008 READ cmd=0x82ff nr=0x4fcf
[2]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173): PutMsgToIrpBuffer:
IRP=8987D8F8 READ cmd=0x82ff nr=0x4fd0
[3]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173): PutMsgToIrpBuffer:
IRP=89528008 READ cmd=0x82ff nr=0x4fd1

Is it simply a sequence issue caused by WPP ?

Bis gleich ...
Stefan
Top

Re: Output sequence for WPP software tracing by cristalink

cristalink
Tue Jan 30 13:45:44 CST 2007

I use TraceView to analyze .etl, and it displays event sequence numbers. Now
the events are frequently displayed out of sequence, so I have to click the
sequence column to order them.



"Stefan de Buhr" <stefan@sdb-consulting.de_nospam> wrote in message
news:udzol6JRHHA.4276@TK2MSFTNGP02.phx.gbl...
> Moin,
>
> I use WPP software tracing in my driver. For each trace line the first
> item shown is the CPU number in brackets.
> I'm wondering if there is a output sequence for those trace messages for
> the same time stamp.
> I'm currently analyzing a problem within my driver. The driver has a queue
> of messages with numbers in sequence.
> For any reason I see the messages fetched in a different order:
>
> [3]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
> PutMsgToIrpBuffer: IRP=89528008 READ cmd=0x82ff nr=0x4fcf
> [3]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
> PutMsgToIrpBuffer: IRP=89528008 READ cmd=0x82ff nr=0x4fd1
> [1]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
> PutMsgToIrpBuffer: IRP=89337E68 READ cmd=0x82ff nr=0x4fce
> [2]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
> PutMsgToIrpBuffer: IRP=8987D8F8 READ cmd=0x82ff nr=0x4fd0
>
> I would expect the following order:
>
> [1]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
> PutMsgToIrpBuffer: IRP=89337E68 READ cmd=0x82ff nr=0x4fce
> [3]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
> PutMsgToIrpBuffer: IRP=89528008 READ cmd=0x82ff nr=0x4fcf
> [2]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
> PutMsgToIrpBuffer: IRP=8987D8F8 READ cmd=0x82ff nr=0x4fd0
> [3]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
> PutMsgToIrpBuffer: IRP=89528008 READ cmd=0x82ff nr=0x4fd1
>
> Is it simply a sequence issue caused by WPP ?
>
> Bis gleich ...
> Stefan
>
>


Top

Re: Output sequence for WPP software tracing by Stefan

Stefan
Tue Jan 30 17:17:55 CST 2007

Good hint. Apparently I have the default trace prefix with my tracing, but
it can be changed to have sequence numbers included which can be decoded.

I found the flag "-seq" for tracefmt.exe to do that.

Anyway I need to do a quick test to verify the sequence assumption and to
get trust in the output ;-)

Bis gleich ...

Stefan



"cristalink" <cristalink@nospam.nospam> schrieb im Newsbeitrag
news:uBf08cKRHHA.4252@TK2MSFTNGP05.phx.gbl...
>I use TraceView to analyze .etl, and it displays event sequence numbers.
>Now the events are frequently displayed out of sequence, so I have to click
>the sequence column to order them.
>
>
>
> "Stefan de Buhr" <stefan@sdb-consulting.de_nospam> wrote in message
> news:udzol6JRHHA.4276@TK2MSFTNGP02.phx.gbl...
>> Moin,
>>
>> I use WPP software tracing in my driver. For each trace line the first
>> item shown is the CPU number in brackets.
>> I'm wondering if there is a output sequence for those trace messages for
>> the same time stamp.
>> I'm currently analyzing a problem within my driver. The driver has a
>> queue of messages with numbers in sequence.
>> For any reason I see the messages fetched in a different order:
>>
>> [3]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
>> PutMsgToIrpBuffer: IRP=89528008 READ cmd=0x82ff nr=0x4fcf
>> [3]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
>> PutMsgToIrpBuffer: IRP=89528008 READ cmd=0x82ff nr=0x4fd1
>> [1]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
>> PutMsgToIrpBuffer: IRP=89337E68 READ cmd=0x82ff nr=0x4fce
>> [2]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
>> PutMsgToIrpBuffer: IRP=8987D8F8 READ cmd=0x82ff nr=0x4fd0
>>
>> I would expect the following order:
>>
>> [1]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
>> PutMsgToIrpBuffer: IRP=89337E68 READ cmd=0x82ff nr=0x4fce
>> [3]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
>> PutMsgToIrpBuffer: IRP=89528008 READ cmd=0x82ff nr=0x4fcf
>> [2]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
>> PutMsgToIrpBuffer: IRP=8987D8F8 READ cmd=0x82ff nr=0x4fd0
>> [3]08EC.16B0::01/30/2007-13:05:25.839 [driver]CAPI(173):
>> PutMsgToIrpBuffer: IRP=89528008 READ cmd=0x82ff nr=0x4fd1
>>
>> Is it simply a sequence issue caused by WPP ?
>>
>> Bis gleich ...
>> Stefan
>>
>>
>
>


Top