If you set the date of your computer to October 31st 2011, at around 10:00 PM, then montlyRollover() TimeBasedRollingWithArchiveRemoval_STest fails systematically (or so it seems). Anyway, I intend to fix this shortly. On 04/11/2011 8:44 PM, ceki wrote:
Thanks for info Joern. I don't know why failed_rename() in TimeBasedRolling_STest is failing. On the other hand, montlyRollover() in TimeBasedRollingWithArchiveRemoval_STest is probably failing because we are in November and the test makes incorrect assumptions about date intervals. I am currently working to improve the logic.
On 01/11/2011 11:18 PM, Joern Huxhorn wrote:
Alright, 1-2 tests fail repeatedly at the moment. The second one less often than the first one.
They didn't fail (which may have been a strange coincidence) until 1b3439a5868d50b8423f675e15dc8db9a35b7102 and kept failing for every single build since then, i.e. 10 builds.
ch.qos.logback.core.rolling.TimeBasedRolling_STest.failed_rename
Stacktrace
java.lang.AssertionError: at org.junit.Assert.fail(Assert.java:91) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertTrue(Assert.java:54) at ch.qos.logback.core.rolling.TimeBasedRolling_STest.zCheck(TimeBasedRolling_STest.scala:111)
at ch.qos.logback.core.rolling.TimeBasedRolling_STest$$anonfun$failed_rename$1.apply(TimeBasedRolling_STest.scala:192)
at ch.qos.logback.core.rolling.TimeBasedRolling_STest$$anonfun$failed_rename$1.apply(TimeBasedRolling_STest.scala:192)
at ch.qos.logback.core.rolling.TimeBasedRolling_STest.genericTest(TimeBasedRolling_STest.scala:91)
at ch.qos.logback.core.rolling.TimeBasedRolling_STest.failed_rename(TimeBasedRolling_STest.scala:192)
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.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184) at org.junit.runners.ParentRunner.run(ParentRunner.java:236) at org.junit.runners.Suite.runChild(Suite.java:128) at org.junit.runners.Suite.runChild(Suite.java:24) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303) at java.util.concurrent.FutureTask.run(FutureTask.java:138) 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:680)
Standard Output
target/test-output/1518562641/failed_rename.log src/test/witness/rolling/tbr-failed_renameFiles [target/test-output/1518562641/failed_rename.log] and [src/test/witness/rolling/tbr-failed_rename] differ on line 1 One reads: [Hello---2]. Other reads:[Hello---0]. -------------------------------- Contents of target/test-output/1518562641/failed_rename.log: 1 : Hello---2 -------------------------------- Contents of src/test/witness/rolling/tbr-failed_rename: 1 : Hello---0 2 : Hello---1 3 : Hello---2 22:55:08,544 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy - No compression will be used 22:55:08,544 |-INFO in c.q.l.core.rolling.TimeBasedRollingPolicy - Will use the pattern target/test-output/1518562641/failed_rename-%d{yyyy-MM-dd_HH_mm_ss} for the active file 22:55:08,544 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - The date pattern is 'yyyy-MM-dd_HH_mm_ss' from file name pattern 'target/test-output/1518562641/failed_rename-%d{yyyy-MM-dd_HH_mm_ss}'. 22:55:08,544 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Roll-over every second. 22:55:08,544 |-INFO in c.q.l.core.rolling.DefaultTimeBasedFileNamingAndTriggeringPolicy - Setting initial period to Tue Nov 01 22:55:08 CET 2011 22:55:08,544 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[null] - Active log file name: target/test-output/1518562641/failed_rename.log 22:55:08,544 |-INFO in ch.qos.logback.core.rolling.RollingFileAppender[null] - File property is set to [target/test-output/1518562641/failed_rename.log] 22:55:08,544 |-INFO in c.q.l.co.rolling.helper.RenameUtil - Renaming file [target/test-output/1518562641/failed_rename.log] to [target/test-output/1518562641/failed_rename-2011-11-01_22_55_08] 22:55:08,545 |-INFO in c.q.l.co.rolling.helper.RenameUtil - Renaming file [target/test-output/1518562641/failed_rename.log] to [target/test-output/1518562641/failed_rename-2011-11-01_22_55_09]
ch.qos.logback.core.rolling.TimeBasedRollingWithArchiveRemoval_STest.montlyRollover
Error Message
expected:<7> but was:<6>
Stacktrace
java.lang.AssertionError: expected:<7> but was:<6> at org.junit.Assert.fail(Assert.java:91) at org.junit.Assert.failNotEquals(Assert.java:645) at org.junit.Assert.assertEquals(Assert.java:126) at org.junit.Assert.assertEquals(Assert.java:470) at org.junit.Assert.assertEquals(Assert.java:454) at ch.qos.logback.core.rolling.TimeBasedRollingWithArchiveRemoval_STest.check(TimeBasedRollingWithArchiveRemoval_STest.scala:266)
at ch.qos.logback.core.rolling.TimeBasedRollingWithArchiveRemoval_STest.genMontlyRollover(TimeBasedRollingWithArchiveRemoval_STest.scala:64)
at ch.qos.logback.core.rolling.TimeBasedRollingWithArchiveRemoval_STest.montlyRollover(TimeBasedRollingWithArchiveRemoval_STest.scala:73)
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.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:44)
at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15)
at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:41)
at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20)
at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28)
at org.junit.runners.BlockJUnit4ClassRunner.runNotIgnored(BlockJUnit4ClassRunner.java:79)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:71)
at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:49)
at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:52) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:191) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:42) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:184) at org.junit.runners.ParentRunner.run(ParentRunner.java:236) at org.junit.runners.Suite.runChild(Suite.java:128) at org.junit.runners.Suite.runChild(Suite.java:24) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:193) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:441) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303) at java.util.concurrent.FutureTask.run(FutureTask.java:138) 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:680)
Joern.
On 29.10.2011, at 16:32, ceki wrote:
Thanks Joern. There are still occasional build errors on heavily loaded or very slow machines but they occur less often than before. I'll have another go at the build after I build up the courage.
On 29/10/2011 3:09 PM, Joern Huxhorn wrote:
Hi Ceki,
just wanted to let you know that it appears like you really fixed the build/tests now. No random build failures on our systems anymore, thumbs up.
Cheers, Joern.
logback-dev mailing list logback-dev@qos.ch http://mailman.qos.ch/mailman/listinfo/logback-dev
-- Ceki http://twitter.com/#!/ceki