qpid-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Chuck Rolke (JIRA)" <j...@apache.org>
Subject [jira] [Commented] (DISPATCH-1094) Log file messages out of order according to time stamps
Date Wed, 01 Aug 2018 20:07:00 GMT

    [ https://issues.apache.org/jira/browse/DISPATCH-1094?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=16565905#comment-16565905
] 

Chuck Rolke commented on DISPATCH-1094:
---------------------------------------

The log file in question had several backwards steps in time. These measurements were only
for AMQP performatives and not for general log lines.

The largest backward step in time was *6.084 mS*.

{{new a_b. a: 2018-08-01 10:45:25.207209  b: 2018-08-01 10:45:25.206902  diff:  0:00:00.000307 
a-lineno:  1440}}
{{2018-08-01 10:45:25.207209 -0400 SERVER (trace) [7]:0 <- @disposition(21) [role=true,
first=5, last=7, settled=true,}}
{{2018-08-01 10:45:25.206902 -0400 SERVER (trace) [6]:0 -> @transfer(20) [handle=0, delivery-id=60,}}

{{new a_b. a: 2018-08-01 10:45:25.214388  b: 2018-08-01 10:45:25.213739  diff:  0:00:00.000649 
a-lineno:  2040}}
{{2018-08-01 10:45:25.214388 -0400 SERVER (trace) [5]:0 <- @transfer(20) [handle=0, delivery-id=37,}}
{{2018-08-01 10:45:25.213739 -0400 SERVER (trace) [7]:0 -> @transfer(20) [handle=0, delivery-id=18,}}

{{new a_b. a: 2018-08-01 10:45:25.220572  b: 2018-08-01 10:45:25.214488  diff:  0:00:00.006084 
a-lineno:  2093}}
{{2018-08-01 10:45:25.220572 -0400 SERVER (trace) [6]:0 <- @disposition(21) [role=true,
first=0, last=4, settled=true}}
{{2018-08-01 10:45:25.214488 -0400 SERVER (trace) [7]:0 -> @transfer(20) [handle=0, delivery-id=19,}}

 

> Log file messages out of order according to time stamps
> -------------------------------------------------------
>
>                 Key: DISPATCH-1094
>                 URL: https://issues.apache.org/jira/browse/DISPATCH-1094
>             Project: Qpid Dispatch
>          Issue Type: Bug
>    Affects Versions: 1.2.0
>         Environment: Fedora 27
>            Reporter: Chuck Rolke
>            Priority: Major
>
> In a recent run with trace logging turned on the trace file had 5,335 lines with several
hundred instances of non-increasing timestamps.
> {{2018-08-01 10:45:25.198173}}
> {{2018-08-01 10:45:25.198187}}
> {{2018-08-01 10:45:25.197941}}
> {{2018-08-01 10:45:25.197727}}
> {{2018-08-01 10:45:25.198238}}
> Log file readers need to know to SORT the log file before scrutinizing it too carefully.



--
This message was sent by Atlassian JIRA
(v7.6.3#76005)

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@qpid.apache.org
For additional commands, e-mail: dev-help@qpid.apache.org


Mime
View raw message