You are viewing a plain text version of this content. The canonical link for it is here.
Posted to notifications@logging.apache.org by "Tonik (JIRA)" <ji...@apache.org> on 2019/03/03 10:20:00 UTC

[jira] [Created] (LOG4NET-626) Incorrect log entries order for multiple threads

Tonik created LOG4NET-626:
-----------------------------

             Summary: Incorrect log entries order for multiple threads
                 Key: LOG4NET-626
                 URL: https://issues.apache.org/jira/browse/LOG4NET-626
             Project: Log4net
          Issue Type: Bug
          Components: Other
         Environment: windows server 2016
            Reporter: Tonik


i'm using RollingLogFileAppender


i'm using log4net from multiple threads (~ 150 Tasks)
and timetstamp are ordered incorrectly, see sample.
you can see that sometimes i get log timestamps from past time. sometimes its only 
100 ms ago, sometimes like 1 second.

2019-02-24 00:00:22,085 [389] DEBUG [Worker TPID: 448] - XXXXXXXXXX
2019-02-24 00:00:22,085 [389] DEBUG [Worker TPID: 436] - XXXXXXXXXX
2019-02-24 00:00:22,163 [230] DEBUG [Worker TPID: 435] - XXXXXXXXXX
2019-02-24 00:00:22,132 [387] DEBUG [Worker TPID: 242] - XXXXXXXXXX
2019-02-24 00:00:22,132 [329] DEBUG [Worker TPID: 583] - XXXXXXXXXX
2019-02-24 *00:00:22,163* [387] DEBUG [Worker TPID: 242] - XXXXXXXXXX
2019-02-24 *00:00:22,132* [251] DEBUG [Worker TPID: 373] - XXXXXXXXXX
2019-02-24 00:00:22,163 [251] DEBUG [Worker TPID: 373] - XXXXXXXXXX
2019-02-24 00:00:22,148 [452] DEBUG [Worker TPID: 244] - XXXXXXXXXX
2019-02-24 00:00:22,148 [351] DEBUG [Worker TPID: 391] - XXXXXXXXXX
2019-02-24 00:00:22,163 [452] DEBUG [Worker TPID: 393] - XXXXXXXXXX
2019-02-24 00:00:22,163 [452] DEBUG [Worker TPID: 553] - XXXXXXXXXX
2019-02-24 00:00:22,163 [351] DEBUG [Worker TPID: 393] - XXXXXXXXXX
2019-02-24 00:00:22,163 [251] DEBUG [Worker TPID: 244] - XXXXXXXXXX
2019-02-24 00:00:22,163 [251] DEBUG [Worker TPID: 244] - XXXXXXXXXX
2019-02-24 00:00:22,163 [109] DEBUG [Worker TPID: 408] - XXXXXXXXXX
2019-02-24 00:00:22,163 [109] DEBUG [Worker TPID: 408] - XXXXXXXXXX
2019-02-24 00:00:22,163 [251] DEBUG [Worker TPID: 553] - XXXXXXXXXX
2019-02-24 00:00:22,163 [251] DEBUG [Worker TPID: 553] - XXXXXXXXXX
2019-02-24 00:00:22,163 [452] DEBUG [Worker TPID: 569] - XXXXXXXXXX
2019-02-24 00:00:22,163 [351] DEBUG [Worker TPID: 393] - XXXXXXXXXX
2019-02-24 00:00:22,163 [452] DEBUG [Worker TPID: 585] - XXXXXXXXXX
2019-02-24 00:00:22,163 [251] DEBUG [Worker TPID: 569] - XXXXXXXXXX
2019-02-24 00:00:22,163 [251] DEBUG [Worker TPID: 569] - XXXXXXXXXX
2019-02-24 *00:00:22,163* [351] DEBUG [Worker TPID: 585] - XXXXXXXXXX
2019-02-24 *00:00:22,085* [468] DEBUG [Worker TPID: 360] - XXXXXXXXXX
2019-02-24 00:00:22,163 [468] DEBUG [Worker TPID: 360] - XXXXXXXXXX
2019-02-24 00:00:22,163 [323] DEBUG [Worker TPID: 244] - XXXXXXXXXX
2019-02-24 00:00:22,163 [323] DEBUG [Worker TPID: 244] - XXXXXXXXXX
2019-02-24 00:00:22,163 [452] DEBUG [Worker TPID: 585] - XXXXXXXXXX
2019-02-24 00:00:22,163 [468] DEBUG [Worker TPID: 569] - XXXXXXXXXX
2019-02-24 00:00:22,163 [468] DEBUG [Worker TPID: 569] - XXXXXXXXXX
2019-02-24 00:00:22,148 [377] DEBUG [Worker TPID: 238] - XXXXXXXXXX
2019-02-24 00:00:22,163 [377] DEBUG [Worker TPID: 238] - XXXXXXXXXX
2019-02-24 00:00:22,132 [244] DEBUG [Worker TPID: 356] - XXXXXXXXXX
2019-02-24 00:00:22,163 [244] DEBUG [Worker TPID: 356] - XXXXXXXXXX
2019-02-24 00:00:22,945 [283] DEBUG [Worker TPID: 87] - XXXXXXXXXX
2019-02-24 00:00:22,945 [283] DEBUG [Worker TPID: 323] - XXXXXXXXXX
2019-02-24 00:00:22,945 [283] DEBUG [Worker TPID: 359] - XXXXXXXXXX



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