October 2009 - logback-dev - mailman3.qos.ch

Another random failure - LoggingEventSerializationPerfTest
by Ralph Goers 14 Oct '09

14 Oct '09

------------------------------------------------------------------------------- Test set: ch.qos.logback.classic.spi.LoggingEventSerializationPerfTest ------------------------------------------------------------------------------- Tests run: 2, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.894 sec <<< FAILURE! testPerformance (ch.qos.logback.classic.spi.LoggingEventSerializationPerfTest) Time elapsed: 0.363 sec testPerformanceWithParameters (ch.qos.logback.classic.spi.LoggingEventSerializationPerfTest) Time elapsed: 0.514 sec <<< FAILURE! junit.framework.AssertionFailedError: current duration 7681.4 exceeded expected 2332.89 (adjusted reference), 7000 (raw reference) at org.slf4j.helpers.BogoPerf.assertDuration(BogoPerf.java:121) at ch.qos.logback.classic.spi.LoggingEventSerializationPerfTest.testPerformanceWithParameters (LoggingEventSerializationPerfTest.java:135) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke (NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke (DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.junit.internal.runners.TestMethod.invoke (TestMethod.java:59) at org.junit.internal.runners.MethodRoadie.runTestMethod (MethodRoadie.java:98) at org.junit.internal.runners.MethodRoadie$2.run (MethodRoadie.java:79) at org.junit.internal.runners.MethodRoadie.runBeforesThenTestThenAfters (MethodRoadie.java:87) at org.junit.internal.runners.MethodRoadie.runTest (MethodRoadie.java:77) at org.junit.internal.runners.MethodRoadie.run (MethodRoadie.java:42) at org.junit.internal.runners.JUnit4ClassRunner.invokeTestMethod (JUnit4ClassRunner.java:88) at org.junit.internal.runners.JUnit4ClassRunner.runMethods (JUnit4ClassRunner.java:51) at org.junit.internal.runners.JUnit4ClassRunner$1.run (JUnit4ClassRunner.java:44) at org.junit.internal.runners.ClassRoadie.runUnprotected (ClassRoadie.java:27) at org.junit.internal.runners.ClassRoadie.runProtected (ClassRoadie.java:37) at org.junit.internal.runners.JUnit4ClassRunner.run (JUnit4ClassRunner.java:42) at org.apache.maven.surefire.junit4.JUnit4TestSet.execute (JUnit4TestSet.java:62) at org.apache.maven.surefire.suite.AbstractDirectoryTestSuite.executeTestSet (AbstractDirectoryTestSuite.java:140) at org.apache.maven.surefire.suite.AbstractDirectoryTestSuite.execute (AbstractDirectoryTestSuite.java:127) at org.apache.maven.surefire.Surefire.run(Surefire.java:177) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke (NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke (DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.apache.maven.surefire.booter.SurefireBooter.runSuitesInProcess (SurefireBooter.java:338) at org.apache.maven.surefire.booter.SurefireBooter.main (SurefireBooter.java:997)

1 0

SMTPAppender_SubethaSMTPTest hangs
by ralph.goers ＠dslextreme.com 13 Oct '09

13 Oct '09

I asked about this once before, but I still frequently get this. mvn clean install hangs when it gets to Running ch.qos.logback.classic.net.SMTPAppender_SubethaSMTPTest. It appears to be waiting forever for input on a socket. jstack 25458 2009-10-13 09:59:33 Full thread dump Java HotSpot(TM) 64-Bit Server VM (14.1-b02-90 mixed mode): "Attach Listener" daemon prio=9 tid=0x00000001022f4800 nid=0x1150c6000 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "SocketAcceptorIoProcessor-2.0" prio=5 tid=0x000000011316f000 nid=0x115487000 runnable [0x0000000115486000] java.lang.Thread.State: RUNNABLE at sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method) at sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:136) at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:69) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69) - locked <0x00000001064d86b8> (a sun.nio.ch.Util$1) - locked <0x00000001064d86a0> (a java.util.Collections$UnmodifiableSet) - locked <0x00000001064d8340> (a sun.nio.ch.KQueueSelectorImpl) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80) at org.apache.mina.transport.socket.nio.SocketIoProcessor$Worker.run(SocketIoProcessor.java:480) at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:51) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) at java.lang.Thread.run(Thread.java:637) "SocketAcceptor-2" prio=5 tid=0x0000000102196800 nid=0x114e4e000 runnable [0x0000000114e4d000] java.lang.Thread.State: RUNNABLE at sun.nio.ch.KQueueArrayWrapper.kevent0(Native Method) at sun.nio.ch.KQueueArrayWrapper.poll(KQueueArrayWrapper.java:136) at sun.nio.ch.KQueueSelectorImpl.doSelect(KQueueSelectorImpl.java:69) at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorImpl.java:69) - locked <0x0000000107ad72c0> (a sun.nio.ch.Util$1) - locked <0x0000000107ad72d8> (a java.util.Collections$UnmodifiableSet) - locked <0x0000000107aee2b8> (a sun.nio.ch.KQueueSelectorImpl) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:80) at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:84) at org.apache.mina.transport.socket.nio.SocketAcceptor$Worker.run(SocketAcceptor.java:220) at org.apache.mina.util.NamePreservingRunnable.run(NamePreservingRunnable.java:51) at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908) at java.lang.Thread.run(Thread.java:637) "HSQLDB Timer @e8ae59a" daemon prio=5 tid=0x0000000113421800 nid=0x114fc3000 in Object.wait() [0x0000000114fc2000] java.lang.Thread.State: TIMED_WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x00000001072e8c08> (a org.hsqldb.lib.HsqlTimer) at org.hsqldb.lib.HsqlTimer.nextTask(Unknown Source) - locked <0x00000001072e8c08> (a org.hsqldb.lib.HsqlTimer) at org.hsqldb.lib.HsqlTimer$TaskRunner.run(Unknown Source) at java.lang.Thread.run(Thread.java:637) "Thread-5" prio=5 tid=0x00000001023ac800 nid=0x114b1b000 runnable [0x0000000114b1a000] java.lang.Thread.State: RUNNABLE at java.net.PlainDatagramSocketImpl.receive0(Native Method) - locked <0x00000001071a3c58> (a java.net.PlainDatagramSocketImpl) at java.net.PlainDatagramSocketImpl.receive(PlainDatagramSocketImpl.java:136) - locked <0x00000001071a3c58> (a java.net.PlainDatagramSocketImpl) at java.net.DatagramSocket.receive(DatagramSocket.java:712) - locked <0x00000001071c6cd8> (a java.net.DatagramPacket) - locked <0x0000000107195e18> (a java.net.DatagramSocket) at ch.qos.logback.classic.net.mock.MockSyslogServer.run(MockSyslogServer.java:50) "Thread-4" prio=5 tid=0x000000011321f800 nid=0x114a18000 runnable [0x0000000114a17000] java.lang.Thread.State: RUNNABLE at java.net.PlainDatagramSocketImpl.receive0(Native Method) - locked <0x00000001071a3cb0> (a java.net.PlainDatagramSocketImpl) at java.net.PlainDatagramSocketImpl.receive(PlainDatagramSocketImpl.java:136) - locked <0x00000001071a3cb0> (a java.net.PlainDatagramSocketImpl) at java.net.DatagramSocket.receive(DatagramSocket.java:712) - locked <0x00000001071c6d08> (a java.net.DatagramPacket) - locked <0x0000000107195e50> (a java.net.DatagramSocket) at ch.qos.logback.classic.net.mock.MockSyslogServer.run(MockSyslogServer.java:50) "Thread-3" prio=5 tid=0x000000010241d800 nid=0x114915000 runnable [0x0000000114914000] java.lang.Thread.State: RUNNABLE at java.net.PlainDatagramSocketImpl.receive0(Native Method) - locked <0x0000000107104030> (a java.net.PlainDatagramSocketImpl) at java.net.PlainDatagramSocketImpl.receive(PlainDatagramSocketImpl.java:136) - locked <0x0000000107104030> (a java.net.PlainDatagramSocketImpl) at java.net.DatagramSocket.receive(DatagramSocket.java:712) - locked <0x0000000107125850> (a java.net.DatagramPacket) - locked <0x00000001071243f8> (a java.net.DatagramSocket) at ch.qos.logback.classic.net.mock.MockSyslogServer.run(MockSyslogServer.java:50) "Thread-1" prio=5 tid=0x000000011316c000 nid=0x114703000 runnable [0x0000000114702000] java.lang.Thread.State: RUNNABLE at java.net.PlainDatagramSocketImpl.receive0(Native Method) - locked <0x0000000107077898> (a java.net.PlainDatagramSocketImpl) at java.net.PlainDatagramSocketImpl.receive(PlainDatagramSocketImpl.java:136) - locked <0x0000000107077898> (a java.net.PlainDatagramSocketImpl) at java.net.DatagramSocket.receive(DatagramSocket.java:712) - locked <0x00000001070e9668> (a java.net.DatagramPacket) - locked <0x00000001070f1b20> (a java.net.DatagramSocket) at ch.qos.logback.classic.net.mock.MockSyslogServer.run(MockSyslogServer.java:50) "Thread-0" prio=5 tid=0x0000000113113000 nid=0x1145ed000 runnable [0x00000001145ec000] java.lang.Thread.State: RUNNABLE at java.net.PlainDatagramSocketImpl.receive0(Native Method) - locked <0x00000001070778f0> (a java.net.PlainDatagramSocketImpl) at java.net.PlainDatagramSocketImpl.receive(PlainDatagramSocketImpl.java:136) - locked <0x00000001070778f0> (a java.net.PlainDatagramSocketImpl) at java.net.DatagramSocket.receive(DatagramSocket.java:712) - locked <0x00000001070e9698> (a java.net.DatagramPacket) - locked <0x00000001070f1b58> (a java.net.DatagramSocket) at ch.qos.logback.classic.net.mock.MockSyslogServer.run(MockSyslogServer.java:50) "Low Memory Detector" daemon prio=5 tid=0x000000010208e000 nid=0x114290000 runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE "CompilerThread1" daemon prio=9 tid=0x000000010208d800 nid=0x11418d000 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "CompilerThread0" daemon prio=9 tid=0x000000010208c800 nid=0x11408a000 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Signal Dispatcher" daemon prio=9 tid=0x000000010208c000 nid=0x113f87000 runnable [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Surrogate Locker Thread (CMS)" daemon prio=5 tid=0x000000010208b000 nid=0x113e84000 waiting on condition [0x0000000000000000] java.lang.Thread.State: RUNNABLE "Finalizer" daemon prio=8 tid=0x0000000102075800 nid=0x113b07000 in Object.wait() [0x0000000113b06000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x0000000106ef0848> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:118) - locked <0x0000000106ef0848> (a java.lang.ref.ReferenceQueue$Lock) at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:134) at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159) "Reference Handler" daemon prio=10 tid=0x0000000113026000 nid=0x113a04000 in Object.wait() [0x0000000113a03000] java.lang.Thread.State: WAITING (on object monitor) at java.lang.Object.wait(Native Method) - waiting on <0x0000000106ef0860> (a java.lang.ref.Reference$Lock) at java.lang.Object.wait(Object.java:485) at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:116) - locked <0x0000000106ef0860> (a java.lang.ref.Reference$Lock) "main" prio=5 tid=0x0000000102000800 nid=0x101301000 runnable [0x00000001012fe000] java.lang.Thread.State: RUNNABLE at java.net.SocketInputStream.socketRead0(Native Method) at java.net.SocketInputStream.read(SocketInputStream.java:129) at com.sun.mail.util.TraceInputStream.read(TraceInputStream.java:97) at java.io.BufferedInputStream.fill(BufferedInputStream.java:218) at java.io.BufferedInputStream.read(BufferedInputStream.java:237) - locked <0x0000000106458610> (a java.io.BufferedInputStream) at com.sun.mail.util.LineInputStream.readLine(LineInputStream.java:75) at com.sun.mail.smtp.SMTPTransport.readServerResponse(SMTPTransport.java:1440) at com.sun.mail.smtp.SMTPTransport.issueSendCommand(SMTPTransport.java:1376) at com.sun.mail.smtp.SMTPTransport.finishData(SMTPTransport.java:1215) at com.sun.mail.smtp.SMTPTransport.sendMessage(SMTPTransport.java:586) - locked <0x0000000106454d10> (a com.sun.mail.smtp.SMTPTransport) at javax.mail.Transport.send0(Transport.java:169) at javax.mail.Transport.send(Transport.java:98) at ch.qos.logback.core.net.SMTPAppenderBase.sendBuffer(SMTPAppenderBase.java:282) at ch.qos.logback.core.net.SMTPAppenderBase.append(SMTPAppenderBase.java:153) at ch.qos.logback.core.AppenderBase.doAppend(AppenderBase.java:91) - locked <0x000000010710e350> (a ch.qos.logback.classic.net.SMTPAppender) at ch.qos.logback.core.spi.AppenderAttachableImpl.appendLoopOnAppenders(AppenderAttachableImpl.java:64) at ch.qos.logback.classic.Logger.appendLoopOnAppenders(Logger.java:276) at ch.qos.logback.classic.Logger.callAppenders(Logger.java:263) at ch.qos.logback.classic.Logger.buildLoggingEventAndAppend(Logger.java:445) at ch.qos.logback.classic.Logger.filterAndLog_0_Or3Plus(Logger.java:399) at ch.qos.logback.classic.Logger.error(Logger.java:562) at ch.qos.logback.classic.net.SMTPAppender_SubethaSMTPTest.htmlLong(SMTPAppender_SubethaSMTPTest.java:195) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.junit.internal.runners.TestMethod.invoke(TestMethod.java:59) at org.junit.internal.runners.MethodRoadie.runTestMethod(MethodRoadie.java:98) at org.junit.internal.runners.MethodRoadie$2.run(MethodRoadie.java:79) at org.junit.internal.runners.MethodRoadie.runBeforesThenTestThenAfters(MethodRoadie.java:87) at org.junit.internal.runners.MethodRoadie.runTest(MethodRoadie.java:77) at org.junit.internal.runners.MethodRoadie.run(MethodRoadie.java:42) at org.junit.internal.runners.JUnit4ClassRunner.invokeTestMethod(JUnit4ClassRunner.java:88) at org.junit.internal.runners.JUnit4ClassRunner.runMethods(JUnit4ClassRunner.java:51) at org.junit.internal.runners.JUnit4ClassRunner$1.run(JUnit4ClassRunner.java:44) at org.junit.internal.runners.ClassRoadie.runUnprotected(ClassRoadie.java:27) at org.junit.internal.runners.ClassRoadie.runProtected(ClassRoadie.java:37) at org.junit.internal.runners.JUnit4ClassRunner.run(JUnit4ClassRunner.java:42) at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:62) at org.apache.maven.surefire.suite.AbstractDirectoryTestSuite.executeTestSet(AbstractDirectoryTestSuite.java:140) at org.apache.maven.surefire.suite.AbstractDirectoryTestSuite.execute(AbstractDirectoryTestSuite.java:127) at org.apache.maven.surefire.Surefire.run(Surefire.java:177) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.apache.maven.surefire.booter.SurefireBooter.runSuitesInProcess(SurefireBooter.java:338) at org.apache.maven.surefire.booter.SurefireBooter.main(SurefireBooter.java:997) "VM Thread" prio=9 tid=0x0000000102070000 nid=0x113901000 runnable "Gang worker#0 (Parallel GC Threads)" prio=9 tid=0x0000000102002000 nid=0x105902000 runnable "Gang worker#1 (Parallel GC Threads)" prio=9 tid=0x0000000102002800 nid=0x105a05000 runnable "Concurrent Mark-Sweep GC Thread" prio=9 tid=0x0000000102042000 nid=0x112d9f000 runnable "VM Periodic Task Thread" prio=10 tid=0x000000010208f000 nid=0x114393000 waiting on condition "Exception Catcher Thread" prio=10 tid=0x0000000102001800 nid=0x101701000 runnable JNI global references: 939

1 0

TimeBasedRollingWithConfigFileTest failure
by ralph.goers ＠dslextreme.com 13 Oct '09

13 Oct '09

I got this on my Mac. ------------------------------------------------------------------------------- Test set: ch.qos.logback.classic.rolling.TimeBasedRollingWithConfigFileTest ------------------------------------------------------------------------------- Tests run: 2, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.094 sec <<< FAILURE! basic(ch.qos.logback.classic.rolling.TimeBasedRollingWithConfigFileTest) Time elapsed: 0.048 sec timeAndSize(ch.qos.logback.classic.rolling.TimeBasedRollingWithConfigFileTest) Time elapsed: 0.04 sec <<< FAILURE! java.lang.AssertionError: eCount=4expectedFilenameList.size=6 at org.junit.Assert.fail(Assert.java:74) at org.junit.Assert.assertTrue(Assert.java:37) at ch.qos.logback.classic.rolling.TimeBasedRollingWithConfigFileTest.timeAndSize(TimeBasedRollingWithConfigFileTest.java:142) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.junit.internal.runners.TestMethod.invoke(TestMethod.java:59) at org.junit.internal.runners.MethodRoadie.runTestMethod(MethodRoadie.java:98) at org.junit.internal.runners.MethodRoadie$2.run(MethodRoadie.java:79) at org.junit.internal.runners.MethodRoadie.runBeforesThenTestThenAfters(MethodRoadie.java:87) at org.junit.internal.runners.MethodRoadie.runTest(MethodRoadie.java:77) at org.junit.internal.runners.MethodRoadie.run(MethodRoadie.java:42) at org.junit.internal.runners.JUnit4ClassRunner.invokeTestMethod(JUnit4ClassRunner.java:88) at org.junit.internal.runners.JUnit4ClassRunner.runMethods(JUnit4ClassRunner.java:51) at org.junit.internal.runners.JUnit4ClassRunner$1.run(JUnit4ClassRunner.java:44) at org.junit.internal.runners.ClassRoadie.runUnprotected(ClassRoadie.java:27) at org.junit.internal.runners.ClassRoadie.runProtected(ClassRoadie.java:37) at org.junit.internal.runners.JUnit4ClassRunner.run(JUnit4ClassRunner.java:42) at org.apache.maven.surefire.junit4.JUnit4TestSet.execute(JUnit4TestSet.java:62) at org.apache.maven.surefire.suite.AbstractDirectoryTestSuite.executeTestSet(AbstractDirectoryTestSuite.java:140) at org.apache.maven.surefire.suite.AbstractDirectoryTestSuite.execute(AbstractDirectoryTestSuite.java:127) at org.apache.maven.surefire.Surefire.run(Surefire.java:177) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) at java.lang.reflect.Method.invoke(Method.java:597) at org.apache.maven.surefire.booter.SurefireBooter.runSuitesInProcess(SurefireBooter.java:338) at org.apache.maven.surefire.booter.SurefireBooter.main(SurefireBooter.java:997)

1 0

LoggerContext logger cache
by Ralph Goers 07 Oct '09

07 Oct '09

LoggerContext uses a Hashtable for the logger cache. A Google on "hashtable ConcurrentHashMap performance" shows that ConcurrentHashMap should perform much better under load. I looked at doing this in my fork and can't see any reason why it would be a problem. I did see a post where ConcurrentHashMap is quite a bit larger than Hashtable, but since there is only going to be one of these that shouldn't be a problem. http://www.informit.com/guides/content.aspx?g=java&seqNum=246 Ralph

2 2

branch, master, updated. 3ad0c4474488b4c967e504acb7b9598200a5a048
by git-noreply＠pixie.qos.ch 06 Oct '09

06 Oct '09

The branch, master has been updated via 3ad0c4474488b4c967e504acb7b9598200a5a048 (commit) from e2bbfe2159e869b2ab0feeefeed1074fc6f44fea (commit) Those revisions listed above that are new to this repository have not appeared on any other notification email; so we list those revisions in full, below. - Log ----------------------------------------------------------------- http://git.qos.ch/gitweb/?p=logback.git;a=commit;h=3ad0c4474488b4c967e504ac… http://github.com/ceki/logback/commit/3ad0c4474488b4c967e504acb7b9598200a5a… commit 3ad0c4474488b4c967e504acb7b9598200a5a048 Author: Ceki Gulcu <ceki(a)qos.ch> Date: Tue Oct 6 18:00:41 2009 +0200 - trying dotted lines diff --git a/logback-site/src/site/pages/css/common.css b/logback-site/src/site/pages/css/common.css index 9bd6d28..1795ebc 100644 --- a/logback-site/src/site/pages/css/common.css +++ b/logback-site/src/site/pages/css/common.css @@ -222,10 +222,13 @@ code { } .option { - border: 1px solid black; - font-family: Arial, sans-serif; - padding-left: 3px; - padding-right: 3px; + border-top: 1px dotted black; + border-bottom: 1px dotted black; + border-left: 1px dotted black; + border-right: 1px dotted black; + padding-left: 2px; + padding-right: 2px; + font-family: Arial, sans-serif; } .highlight { ----------------------------------------------------------------------- Summary of changes: logback-site/src/site/pages/css/common.css | 11 +++++++---- 1 files changed, 7 insertions(+), 4 deletions(-) hooks/post-receive -- Logback: the generic, reliable, fast and flexible logging framework.

1 0

branch, master, updated. e2bbfe2159e869b2ab0feeefeed1074fc6f44fea
by git-noreply＠pixie.qos.ch 06 Oct '09

06 Oct '09

The branch, master has been updated via e2bbfe2159e869b2ab0feeefeed1074fc6f44fea (commit) from 13ca0437f2069e812360f14deba53904f24ad7a9 (commit) Those revisions listed above that are new to this repository have not appeared on any other notification email; so we list those revisions in full, below. - Log ----------------------------------------------------------------- http://git.qos.ch/gitweb/?p=logback.git;a=commit;h=e2bbfe2159e869b2ab0feeef… http://github.com/ceki/logback/commit/e2bbfe2159e869b2ab0feeefeed1074fc6f44… commit e2bbfe2159e869b2ab0feeefeed1074fc6f44fea Author: Ceki Gulcu <ceki(a)qos.ch> Date: Tue Oct 6 17:41:10 2009 +0200 As discussed on IRC with Charles Johnson, added 3px padding around options which now look less cramped. diff --git a/logback-site/src/site/pages/css/common.css b/logback-site/src/site/pages/css/common.css index 65eec8a..9bd6d28 100644 --- a/logback-site/src/site/pages/css/common.css +++ b/logback-site/src/site/pages/css/common.css @@ -25,6 +25,7 @@ div.source { font-family: Courier, "MS Courier New", Prestige, monospace; padding-left: 1ex; white-space: pre; + overflow: scroll; } .source { @@ -217,11 +218,14 @@ code { .attr { font-family: Courier, monospace; font-style:italic; + } .option { border: 1px solid black; font-family: Arial, sans-serif; + padding-left: 3px; + padding-right: 3px; } .highlight { ----------------------------------------------------------------------- Summary of changes: logback-site/src/site/pages/css/common.css | 4 ++++ 1 files changed, 4 insertions(+), 0 deletions(-) hooks/post-receive -- Logback: the generic, reliable, fast and flexible logging framework.

1 0

[JIRA] Created: (LBCLASSIC-158) Interleaved messages from multiple threads
by Michael Olson (JIRA) 05 Oct '09

05 Oct '09

Interleaved messages from multiple threads ------------------------------------------ Key: LBCLASSIC-158 URL: http://jira.qos.ch/browse/LBCLASSIC-158 Project: logback-classic Issue Type: Bug Components: appender Affects Versions: 0.917 Environment: Mac OS X 10.5.8, Eclipse 20090920-1017, Java SE 6, Mac Pro with 2 processors with 4 cores each Reporter: Michael Olson Assignee: Logback dev list Priority: Critical Note: I'm using pastebin so that the formatting is not messed with by JIRA and so that I can refer to line numbers. My apologies if that makes it more difficult to examine my files. I've just moved to logback from log4j (which I was using only with the default configuration) using the slf4j wrappers. I'm using a configuration which is a slightly changed version of one found in the documentation; the configuration can be found here: http://logback.pastebin.com/m1a6f2875 These two log file excerpts are from that configuration: http://logback.pastebin.com/m5e08cb57 - note lines 3 and 4 http://logback.pastebin.com/m7e575564 - note lines 2, 3, and 4 Then this configuration, which only changes the class levels, is for the next log (I wanted to be precise): http://logback.pastebin.com/f6195004e This log file is from this morning: http://logback.pastebin.com/f42bdb83f Note lines 2 and 3. You'll notice, I'm sure, that these are all from the end of my log files; I have not observed this behavior elsewhere in my logs. The observed problems have been during my shutdown procedure. Specifically, the class PostItemSubmitter, with 4 threads, is waiting on input from CalaisSubmitter (it is trying to retrieve an object from a LinkedBlockingQueue with a get() operation). In these particular logs, CalaisSubmitter has run into fatal errors (over SLA, whoops) and has put no data in the queue, so all four threads are currently blocked on their input queue. After all of the CalaisSubmitter threads are shutdown, the main thread tries to shut down the PostItemSubmitter threads. It does this by calling a function in the thread's instance which sets a boolean value to false that controls the continued execution of the main program loop. After it has set those variables, the main thread will check the size of the queue; if it is 0, which it is in this case, then it will interrupt any threads that have not stopped after one second. It will do this immediately, so all four threads will receive Thread.interrupt() calls in sequence. When those threads are interrupted, they throw an InterruptedException. The catch statement looks like this: catch (InterruptedException e) { if (running) logger.error("Interrupted getting item from queue"); else logger.info("Shutdown aborted queue operation"); } In at least two of these three logs, it seems clear that the problem lies in how the interrupted threads are using the static logger defined for their class, which looks like this: private static Logger logger = LoggerFactory.getLogger(PostItemSubmitter.class); In the other log, the interleaving comes with the CalaisSubmitter, but you can see at least one aborted queue operation, so the reason could be similar (that class is also interrupted on an empty queue and catches those interrupts in the same way). Let me know if you need any more information to help diagnose the problem. -- This message is automatically generated by JIRA. - If you think it was sent incorrectly contact one of the administrators: http://jira.qos.ch/secure/Administrators.jspa - For more information on JIRA, see: http://www.atlassian.com/software/jira

2 3