
As shown on [1], the logback build on the Jenkins server on cloudbees is unstable. The failures are probably due to timeout issue. I'll work on ch.qos.logback.core.FileAppenderResilienceTest.smoke ch.qos.logback.core.recovery.ResilientOutputStreamTest.verifyRecuperationAfterFailure ch.qos.logback.core.spi.AppenderAttachableImplLockTest.detachAppenderBoom Carl, could you please have a look at ch.qos.logback.core.net.SocketConnectorBaseTest.testConnect ? You should be able to logon at https://grandcentral.cloudbees.com/ click on JenkinsCI (top right) and launch builds interactively. (You need to click on the login link in Jenkins located on the top right corner). Cheers, -- Ceki [1] https://logback.ci.cloudbees.com/job/logback/12/testReport/

Do we have stable builds yet? I cloned a fresh copy of logback yesterday, and "mvn install" still failed for me. Is it just me? Broc On Thu, Apr 4, 2013 at 9:20 AM, Ceki Gülcü <ceki@qos.ch> wrote:
As shown on [1], the logback build on the Jenkins server on cloudbees is unstable.
The failures are probably due to timeout issue. I'll work on
ch.qos.logback.core.**FileAppenderResilienceTest.**smoke ch.qos.logback.core.recovery.**ResilientOutputStreamTest.**verifyRecuperationAfterFailure
ch.qos.logback.core.spi.**AppenderAttachableImplLockTest** .detachAppenderBoom
Carl, could you please have a look at
ch.qos.logback.core.net.**SocketConnectorBaseTest.**testConnect ?
You should be able to logon at
https://grandcentral.**cloudbees.com/<https://grandcentral.cloudbees.com/>
click on JenkinsCI (top right) and launch builds interactively. (You need to click on the login link in Jenkins located on the top right corner).
Cheers,
-- Ceki
[1] https://logback.ci.cloudbees.**com/job/logback/12/testReport/<https://logback.ci.cloudbees.com/job/logback/12/testReport/> ______________________________**_________________ logback-dev mailing list logback-dev@qos.ch http://mailman.qos.ch/mailman/**listinfo/logback-dev<http://mailman.qos.ch/mailman/listinfo/logback-dev>

Hi Broc, No, it is not just you. Some of the logback test cases are time sensitive and fail from time to time, especially if the host machine is "slow". Making the build more robust is on our todo list. We now have jenkins running on such a slow machine. For example, on http://logback.qos.ch/jenkins/ build 24 was successful whereas build 25 failed: http://logback.qos.ch/jenkins/job/logback/24/ http://logback.qos.ch/jenkins/job/logback/25/ So, at least it's easier to reproduce the fails. Anyway, have you tried relaunching the build with "mvn clean; mvn install" ? BTW, since about 10 days, the logback build no longer depends on Scala for testing. It should be now much easier to set up logback within your favorite IDE. On 15.04.2013 20:11, Broc Seib wrote:
Do we have stable builds yet?
I cloned a fresh copy of logback yesterday, and "mvn install" still failed for me. Is it just me?
Broc
On Thu, Apr 4, 2013 at 9:20 AM, Ceki Gülcü <ceki@qos.ch <mailto:ceki@qos.ch>> wrote:
As shown on [1], the logback build on the Jenkins server on cloudbees is unstable.
The failures are probably due to timeout issue. I'll work on
ch.qos.logback.core.__FileAppenderResilienceTest.__smoke ch.qos.logback.core.recovery.__ResilientOutputStreamTest.__verifyRecuperationAfterFailure
ch.qos.logback.core.spi.__AppenderAttachableImplLockTest__.detachAppenderBoom
Carl, could you please have a look at
ch.qos.logback.core.net <http://ch.qos.logback.core.net>.__SocketConnectorBaseTest.__testConnect ?
You should be able to logon at
https://grandcentral.__cloudbees.com/ <https://grandcentral.cloudbees.com/>
click on JenkinsCI (top right) and launch builds interactively. (You need to click on the login link in Jenkins located on the top right corner).
Cheers,
-- Ceki
[1] https://logback.ci.cloudbees.__com/job/logback/12/testReport/ <https://logback.ci.cloudbees.com/job/logback/12/testReport/>
-- Ceki 65% of statistics are made up on the spot

Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"? Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE! withZipCompression_FileSet_NoRestart_7(ch.qos.logback.core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(Native Method) at java.io.FileInputStream.<init>(FileInputStream.java:138) at ch.qos.logback.core.testUtil.FileToBufferUtil.regularReadIntoList(Fil eToBufferUtil.java:60) at ch.qos.logback.core.testUtil.FileToBufferUtil.readIntoList(FileToBuff erUtil.java:38) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.fileContentChe ck(ScaffoldingForRollingTests.java:84) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.sortedContentC heck(ScaffoldingForRollingTests.java:99) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.generic(SizeAn dTimeBasedFNATP_Test.java:130) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.withGZCompress ion_FileBlank_NoRestart_6(SizeAndTimeBasedFNATP_Test.java:189) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl. java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(Framework Method.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCal lable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMe thod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMet hod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores. java:28) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:47 1) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor. java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.java:722) withGZCompression_FileSet_NoRestart_5(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec noCompression_FileSet_WithStopStart_4(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec noCompression_FileBlank_WithStopStart_3(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec noCompression_FileBlank_NoRestart_2(ch.qos.logback.core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec noCompression_FileSet_NoRestart_1(ch.qos.logback.core.rolling.SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec verifyRecuperationAfterFailure(ch.qos.logback.core.recovery.ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.012 sec Results : Tests in error: withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTim eBasedFNATP_Test): target\test-output\910764289\test6-2013-04-15_23_47_35-1.txt (The system cannot find the file specified) Tests run: 424, Failures: 0, Errors: 1, Skipped: 6 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.338s] [INFO] Logback Core Module ............................... FAILURE [28.279s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 29.140s [INFO] Finished at: Mon Apr 15 23:47:54 EDT 2013 [INFO] Final Memory: 28M/241M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2. 12.4:test (default-test) on project logback-core: There are test failures. On Mon, Apr 15, 2013 at 2:47 PM, ceki <ceki@qos.ch> wrote:
Hi Broc,
No, it is not just you. Some of the logback test cases are time sensitive and fail from time to time, especially if the host machine is "slow". Making the build more robust is on our todo list.
We now have jenkins running on such a slow machine. For example, on http://logback.qos.ch/jenkins/ build 24 was successful whereas build 25 failed:
http://logback.qos.ch/jenkins/**job/logback/24/<http://logback.qos.ch/jenkins/job/logback/24/> http://logback.qos.ch/jenkins/**job/logback/25/<http://logback.qos.ch/jenkins/job/logback/25/>
So, at least it's easier to reproduce the fails.
Anyway, have you tried relaunching the build with "mvn clean; mvn install" ?
BTW, since about 10 days, the logback build no longer depends on Scala for testing. It should be now much easier to set up logback within your favorite IDE.
On 15.04.2013 20:11, Broc Seib wrote:
Do we have stable builds yet?
I cloned a fresh copy of logback yesterday, and "mvn install" still failed for me. Is it just me?
Broc
On Thu, Apr 4, 2013 at 9:20 AM, Ceki Gülcü <ceki@qos.ch <mailto:ceki@qos.ch>> wrote:
As shown on [1], the logback build on the Jenkins server on cloudbees is unstable.
The failures are probably due to timeout issue. I'll work on
ch.qos.logback.core.__**FileAppenderResilienceTest.__**smoke ch.qos.logback.core.recovery._**_ResilientOutputStreamTest.__** verifyRecuperationAfterFailure
ch.qos.logback.core.spi.__**AppenderAttachableImplLockTest** __.detachAppenderBoom
Carl, could you please have a look at
ch.qos.logback.core.net <http://ch.qos.logback.core.**net <http://ch.qos.logback.core.net>
.__**SocketConnectorBaseTest.__**testConnect
?
You should be able to logon at
https://grandcentral.__cloudbe**es.com/ <http://cloudbees.com/>
<https://grandcentral.**cloudbees.com/<https://grandcentral.cloudbees.com/>
click on JenkinsCI (top right) and launch builds interactively. (You need to click on the login link in Jenkins located on the top right corner).
Cheers,
-- Ceki
[1] https://logback.ci.cloudbees._**_com/job/logback/12/**testReport/ <https://logback.ci.cloudbees.**com/job/logback/12/testReport/<https://logback.ci.cloudbees.com/job/logback/12/testReport/> **>
-- Ceki 65% of statistics are made up on the spot
______________________________**_________________ logback-dev mailing list logback-dev@qos.ch http://mailman.qos.ch/mailman/**listinfo/logback-dev<http://mailman.qos.ch/mailman/listinfo/logback-dev>

I get a similar failure. I suspect the "rolling" tests fail on certain dates (on the wall clock). I'll look into it. On 16.04.2013 06:05, Broc Seib wrote:
Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"?
Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE! withZipCompression_FileSet_NoRestart_7(ch.qos.logback.core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(Native Method) at java.io.FileInputStream.<init>(FileInputStream.java:138) at ch.qos.logback.core.testUtil.FileToBufferUtil.regularReadIntoList(Fil eToBufferUtil.java:60) at ch.qos.logback.core.testUtil.FileToBufferUtil.readIntoList(FileToBuff erUtil.java:38) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.fileContentChe ck(ScaffoldingForRollingTests.java:84) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.sortedContentC heck(ScaffoldingForRollingTests.java:99) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.generic(SizeAn dTimeBasedFNATP_Test.java:130) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.withGZCompress ion_FileBlank_NoRestart_6(SizeAndTimeBasedFNATP_Test.java:189) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl. java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(Framework Method.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCal lable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMe thod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMet hod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores. java:28) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:47 1) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor. java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.java:722)
withGZCompression_FileSet_NoRestart_5(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec noCompression_FileSet_WithStopStart_4(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec noCompression_FileBlank_WithStopStart_3(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec noCompression_FileBlank_NoRestart_2(ch.qos.logback.core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec noCompression_FileSet_NoRestart_1(ch.qos.logback.core.rolling.SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec verifyRecuperationAfterFailure(ch.qos.logback.core.recovery.ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.012 sec
Results :
Tests in error:
withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTim eBasedFNATP_Test): target\test-output\910764289\test6-2013-04-15_23_47_35-1.txt (The system cannot find the file specified)
Tests run: 424, Failures: 0, Errors: 1, Skipped: 6
[INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.338s] [INFO] Logback Core Module ............................... FAILURE [28.279s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 29.140s [INFO] Finished at: Mon Apr 15 23:47:54 EDT 2013 [INFO] Final Memory: 28M/241M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2. 12.4:test (default-test) on project logback-core: There are test failures.
On Mon, Apr 15, 2013 at 2:47 PM, ceki <ceki@qos.ch <mailto:ceki@qos.ch>> wrote:
Hi Broc,
No, it is not just you. Some of the logback test cases are time sensitive and fail from time to time, especially if the host machine is "slow". Making the build more robust is on our todo list.
We now have jenkins running on such a slow machine. For example, on http://logback.qos.ch/jenkins/ build 24 was successful whereas build 25 failed:
http://logback.qos.ch/jenkins/__job/logback/24/ <http://logback.qos.ch/jenkins/job/logback/24/> http://logback.qos.ch/jenkins/__job/logback/25/ <http://logback.qos.ch/jenkins/job/logback/25/>
So, at least it's easier to reproduce the fails.
Anyway, have you tried relaunching the build with "mvn clean; mvn install" ?
BTW, since about 10 days, the logback build no longer depends on Scala for testing. It should be now much easier to set up logback within your favorite IDE.
On 15.04.2013 20:11, Broc Seib wrote:
Do we have stable builds yet?
I cloned a fresh copy of logback yesterday, and "mvn install" still failed for me. Is it just me?
Broc
On Thu, Apr 4, 2013 at 9:20 AM, Ceki Gülcü <ceki@qos.ch <mailto:ceki@qos.ch> <mailto:ceki@qos.ch <mailto:ceki@qos.ch>>> wrote:
As shown on [1], the logback build on the Jenkins server on cloudbees is unstable.
The failures are probably due to timeout issue. I'll work on
ch.qos.logback.core.____FileAppenderResilienceTest.____smoke
ch.qos.logback.core.recovery.____ResilientOutputStreamTest.____verifyRecuperationAfterFailure
ch.qos.logback.core.spi.____AppenderAttachableImplLockTest____.detachAppenderBoom
Carl, could you please have a look at
ch.qos.logback.core.net <http://ch.qos.logback.core.net> <http://ch.qos.logback.core.__net <http://ch.qos.logback.core.net>>.____SocketConnectorBaseTest.____testConnect
?
You should be able to logon at
https://grandcentral.__cloudbe__es.com/ <http://cloudbees.com/>
<https://grandcentral.__cloudbees.com/ <https://grandcentral.cloudbees.com/>>
click on JenkinsCI (top right) and launch builds interactively. (You need to click on the login link in Jenkins located on the top right corner).
Cheers,
-- Ceki
[1] https://logback.ci.cloudbees.____com/job/logback/12/__testReport/
<https://logback.ci.cloudbees.__com/job/logback/12/testReport/ <https://logback.ci.cloudbees.com/job/logback/12/testReport/>__>
-- Ceki 65% of statistics are made up on the spot
-- Ceki 65% of statistics are made up on the spot

I am hoping that commit [1] should make the test failures in logback-core/src/test/java/ch/qos/logback/core/rolling disappear. [1] https://github.com/qos-ch/logback/commit/2ff61c7c1cb0562 On 16.04.2013 06:05, Broc Seib wrote:
Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"?
Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE! withZipCompression_FileSet_NoRestart_7(ch.qos.logback.core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(Native Method) at java.io.FileInputStream.<init>(FileInputStream.java:138) at ch.qos.logback.core.testUtil.FileToBufferUtil.regularReadIntoList(Fil eToBufferUtil.java:60) at ch.qos.logback.core.testUtil.FileToBufferUtil.readIntoList(FileToBuff erUtil.java:38) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.fileContentChe ck(ScaffoldingForRollingTests.java:84) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.sortedContentC heck(ScaffoldingForRollingTests.java:99) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.generic(SizeAn dTimeBasedFNATP_Test.java:130) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.withGZCompress ion_FileBlank_NoRestart_6(SizeAndTimeBasedFNATP_Test.java:189) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl. java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(Framework Method.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCal lable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMe thod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMet hod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores. java:28) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:47 1) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor. java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.java:722)
withGZCompression_FileSet_NoRestart_5(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec noCompression_FileSet_WithStopStart_4(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec noCompression_FileBlank_WithStopStart_3(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec noCompression_FileBlank_NoRestart_2(ch.qos.logback.core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec noCompression_FileSet_NoRestart_1(ch.qos.logback.core.rolling.SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec verifyRecuperationAfterFailure(ch.qos.logback.core.recovery.ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.012 sec
Results :
Tests in error:
withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTim eBasedFNATP_Test): target\test-output\910764289\test6-2013-04-15_23_47_35-1.txt (The system cannot find the file specified)
Tests run: 424, Failures: 0, Errors: 1, Skipped: 6
[INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.338s] [INFO] Logback Core Module ............................... FAILURE [28.279s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 29.140s [INFO] Finished at: Mon Apr 15 23:47:54 EDT 2013 [INFO] Final Memory: 28M/241M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2. 12.4:test (default-test) on project logback-core: There are test failures.
On Mon, Apr 15, 2013 at 2:47 PM, ceki <ceki@qos.ch <mailto:ceki@qos.ch>> wrote:
Hi Broc,
No, it is not just you. Some of the logback test cases are time sensitive and fail from time to time, especially if the host machine is "slow". Making the build more robust is on our todo list.
We now have jenkins running on such a slow machine. For example, on http://logback.qos.ch/jenkins/ build 24 was successful whereas build 25 failed:
http://logback.qos.ch/jenkins/__job/logback/24/ <http://logback.qos.ch/jenkins/job/logback/24/> http://logback.qos.ch/jenkins/__job/logback/25/ <http://logback.qos.ch/jenkins/job/logback/25/>
So, at least it's easier to reproduce the fails.
Anyway, have you tried relaunching the build with "mvn clean; mvn install" ?
BTW, since about 10 days, the logback build no longer depends on Scala for testing. It should be now much easier to set up logback within your favorite IDE.
On 15.04.2013 20:11, Broc Seib wrote:
Do we have stable builds yet?
I cloned a fresh copy of logback yesterday, and "mvn install" still failed for me. Is it just me?
Broc
On Thu, Apr 4, 2013 at 9:20 AM, Ceki Gülcü <ceki@qos.ch <mailto:ceki@qos.ch> <mailto:ceki@qos.ch <mailto:ceki@qos.ch>>> wrote:
As shown on [1], the logback build on the Jenkins server on cloudbees is unstable.
The failures are probably due to timeout issue. I'll work on
ch.qos.logback.core.____FileAppenderResilienceTest.____smoke
ch.qos.logback.core.recovery.____ResilientOutputStreamTest.____verifyRecuperationAfterFailure
ch.qos.logback.core.spi.____AppenderAttachableImplLockTest____.detachAppenderBoom
Carl, could you please have a look at
ch.qos.logback.core.net <http://ch.qos.logback.core.net> <http://ch.qos.logback.core.__net <http://ch.qos.logback.core.net>>.____SocketConnectorBaseTest.____testConnect
?
You should be able to logon at
https://grandcentral.__cloudbe__es.com/ <http://cloudbees.com/>
<https://grandcentral.__cloudbees.com/ <https://grandcentral.cloudbees.com/>>
click on JenkinsCI (top right) and launch builds interactively. (You need to click on the login link in Jenkins located on the top right corner).
Cheers,
-- Ceki
[1] https://logback.ci.cloudbees.____com/job/logback/12/__testReport/
<https://logback.ci.cloudbees.__com/job/logback/12/testReport/ <https://logback.ci.cloudbees.com/job/logback/12/testReport/>__>
-- Ceki 65% of statistics are made up on the spot
_________________________________________________ logback-dev mailing list logback-dev@qos.ch <mailto:logback-dev@qos.ch> http://mailman.qos.ch/mailman/__listinfo/logback-dev <http://mailman.qos.ch/mailman/listinfo/logback-dev>
_______________________________________________ logback-dev mailing list logback-dev@qos.ch http://mailman.qos.ch/mailman/listinfo/logback-dev
-- Ceki 65% of statistics are made up on the spot

Hi Ceki, I just updated to the latest code you just committed, 56e8ab2192697bc27a8cfbd8441db62aff53e87c but I think there is a different problem now. bseib@BSEIB-WIN7 /c/gitrepo $ rm -rf logback bseib@BSEIB-WIN7 /c/gitrepo $ git clone https://github.com/qos-ch/logback.git Cloning into 'logback'... remote: Counting objects: 52855, done. remote: Compressing objects: 100% (13701/13701), done. remote: Total 52855 (delta 26607), reused 52138 (delta 26038) Receiving objects: 100% (52855/52855), 20.13 MiB | 1.17 MiB/s, done. Resolving deltas: 100% (26607/26607), done. bseib@BSEIB-WIN7 /c/gitrepo $ cd logback/ bseib@BSEIB-WIN7 /c/gitrepo/logback (master) $ git log -1 commit 56e8ab2192697bc27a8cfbd8441db62aff53e87c Merge: 2ff61c7 513e380 Author: Ceki Gulcu <ceki@qos.ch> Date: Tue Apr 16 18:09:23 2013 +0200 Merge branch 'master' of github.com:qos-ch/logback And then 'mvn clean; mvn install': [...] Running ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.635 sec includeNestedWithinIf(ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest) Time elapsed: 0.635 sec Results : Failed tests: testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest) lossyAppenderShouldOnlyLooseCertainEvents(ch.qos.logback.core.AsyncAppenderBaseTest): expected:<8> but was:<10> Tests run: 424, Failures: 2, Errors: 0, Skipped: 6 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.244s] [INFO] Logback Core Module ............................... FAILURE [27.251s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 28.014s [INFO] Finished at: Tue Apr 16 12:51:50 EDT 2013 [INFO] Final Memory: 28M/308M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4:test (default-test) on project logback-core: There are test failures. On Tue, Apr 16, 2013 at 12:42 PM, ceki <ceki@qos.ch> wrote:
I am hoping that commit [1] should make the test failures in logback-core/src/test/java/ch/**qos/logback/core/rolling disappear.
On 16.04.2013 06:05, Broc Seib wrote:
Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"?
Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE! withZipCompression_FileSet_**NoRestart_7(ch.qos.logback.** core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec withGZCompression_FileBlank_**NoRestart_6(ch.qos.logback.** core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\**test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(**Native Method) at java.io.FileInputStream.<init>**(FileInputStream.java:138) at ch.qos.logback.core.testUtil.**FileToBufferUtil.**regularReadIntoList(Fil eToBufferUtil.java:60) at ch.qos.logback.core.testUtil.**FileToBufferUtil.readIntoList(**FileToBuff erUtil.java:38) at ch.qos.logback.core.rolling.**ScaffoldingForRollingTests.**fileContentChe ck(ScaffoldingForRollingTests.**java:84) at ch.qos.logback.core.rolling.**ScaffoldingForRollingTests.**sortedContentC heck(**ScaffoldingForRollingTests.**java:99) at ch.qos.logback.core.rolling.**SizeAndTimeBasedFNATP_Test.**generic(SizeAn dTimeBasedFNATP_Test.java:130) at ch.qos.logback.core.rolling.**SizeAndTimeBasedFNATP_Test.**withGZCompress ion_FileBlank_NoRestart_6(**SizeAndTimeBasedFNATP_Test.**java:189) at sun.reflect.**NativeMethodAccessorImpl.**invoke0(Native Method) at sun.reflect.**NativeMethodAccessorImpl.**invoke(** NativeMethodAccessorImpl. java:57) at sun.reflect.**DelegatingMethodAccessorImpl.**invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.**invoke(Method.java:601) at org.junit.runners.model.**FrameworkMethod$1.**runReflectiveCall(Framework Method.java:45) at org.junit.internal.runners.**model.ReflectiveCallable.run(**ReflectiveCal lable.java:15) at org.junit.runners.model.**FrameworkMethod.**invokeExplosively(FrameworkMe thod.java:42) at org.junit.internal.runners.**statements.InvokeMethod.**evaluate(InvokeMet hod.java:20) at org.junit.internal.runners.**statements.RunBefores.**evaluate(RunBefores. java:28) at org.junit.runners.**ParentRunner.runLeaf(** ParentRunner.java:263) at org.junit.runners.**BlockJUnit4ClassRunner.**runChild(BlockJUnit4ClassRun ner.java:68) at org.junit.runners.**BlockJUnit4ClassRunner.**runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.**ParentRunner$3.run(** ParentRunner.java:231) at org.junit.runners.**ParentRunner$1.schedule(** ParentRunner.java:60) at org.junit.runners.**ParentRunner.runChildren(**ParentRunner.java:229) at org.junit.runners.**ParentRunner.access$000(** ParentRunner.java:50) at org.junit.runners.**ParentRunner$2.evaluate(** ParentRunner.java:222) at org.junit.runners.**ParentRunner.run(ParentRunner.**java:300) 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:231) at java.util.concurrent.**Executors$RunnableAdapter.**call(Executors.java:47 1) at java.util.concurrent.**FutureTask$Sync.innerRun(**FutureTask.java:334) at java.util.concurrent.**FutureTask.run(FutureTask.**java:166) at java.util.concurrent.**ThreadPoolExecutor.runWorker(**ThreadPoolExecutor. java:1110) at java.util.concurrent.**ThreadPoolExecutor$Worker.run(**ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.**java:722)
withGZCompression_FileSet_**NoRestart_5(ch.qos.logback.** core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec noCompression_FileSet_**WithStopStart_4(ch.qos.**logback.core.rolling.** SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec noCompression_FileBlank_**WithStopStart_3(ch.qos.**logback.core.rolling.* *SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec noCompression_FileBlank_**NoRestart_2(ch.qos.logback.** core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec noCompression_FileSet_**NoRestart_1(ch.qos.logback.**core.rolling.** SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.**ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec verifyRecuperationAfterFailure**(ch.qos.logback.core.recovery.** ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.**helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.**rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.**rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.**rolling.helper.CompressTest) Time elapsed: 0.012 sec
Results :
Tests in error:
withGZCompression_FileBlank_**NoRestart_6(ch.qos.logback.** core.rolling.SizeAndTim eBasedFNATP_Test): target\test-output\910764289\**test6-2013-04-15_23_47_35-1.**txt (The system cannot find the file specified)
Tests run: 424, Failures: 0, Errors: 1, Skipped: 6
[INFO] ------------------------------**------------------------------** ------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent ..............................**...... SUCCESS [0.338s] [INFO] Logback Core Module ..............................**. FAILURE [28.279s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ..............................**........ SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------**------------------------------** ------------ [INFO] BUILD FAILURE [INFO] ------------------------------**------------------------------** ------------ [INFO] Total time: 29.140s [INFO] Finished at: Mon Apr 15 23:47:54 EDT 2013 [INFO] Final Memory: 28M/241M [INFO] ------------------------------**------------------------------** ------------ [ERROR] Failed to execute goal org.apache.maven.plugins:**maven-surefire-plugin:2. 12.4:test (default-test) on project logback-core: There are test failures.
On Mon, Apr 15, 2013 at 2:47 PM, ceki <ceki@qos.ch <mailto:ceki@qos.ch>>
wrote:
Hi Broc,
No, it is not just you. Some of the logback test cases are time sensitive and fail from time to time, especially if the host machine is "slow". Making the build more robust is on our todo list.
We now have jenkins running on such a slow machine. For example, on http://logback.qos.ch/jenkins/ build 24 was successful whereas build 25 failed:
http://logback.qos.ch/jenkins/**__job/logback/24/<http://logback.qos.ch/jenkins/__job/logback/24/> <http://logback.qos.ch/**jenkins/job/logback/24/<http://logback.qos.ch/jenkins/job/logback/24/>
http://logback.qos.ch/jenkins/**__job/logback/25/<http://logback.qos.ch/jenkins/__job/logback/25/> <http://logback.qos.ch/**jenkins/job/logback/25/<http://logback.qos.ch/jenkins/job/logback/25/>
So, at least it's easier to reproduce the fails.
Anyway, have you tried relaunching the build with "mvn clean; mvn install" ?
BTW, since about 10 days, the logback build no longer depends on Scala for testing. It should be now much easier to set up logback within your favorite IDE.
On 15.04.2013 20:11, Broc Seib wrote:
Do we have stable builds yet?
I cloned a fresh copy of logback yesterday, and "mvn install" still failed for me. Is it just me?
Broc
On Thu, Apr 4, 2013 at 9:20 AM, Ceki Gülcü <ceki@qos.ch <mailto:ceki@qos.ch> <mailto:ceki@qos.ch <mailto:ceki@qos.ch>>> wrote:
As shown on [1], the logback build on the Jenkins server on cloudbees is unstable.
The failures are probably due to timeout issue. I'll work on
ch.qos.logback.core.____**FileAppenderResilienceTest.___** _smoke
ch.qos.logback.core.recovery._**___ResilientOutputStreamTest._** ___**verifyRecuperationAfterFailure
ch.qos.logback.core.spi.____**AppenderAttachableImplLockTest** ____.detachAppenderBoom
Carl, could you please have a look at
ch.qos.logback.core.net <http://ch.qos.logback.core.**net<http://ch.qos.logback.core.net>
<http://ch.qos.logback.core.__**net <http://ch.qos.logback.core.**net<http://ch.qos.logback.core.net>
.____**SocketConnectorBaseTest.____**testConnect
?
You should be able to logon at
https://grandcentral.__cloudbe**__es.com/<http://cloudbe__es.com/>< http://cloudbees.com/>
<https://grandcentral.__cloudb**ees.com/<http://cloudbees.com/> <https://grandcentral.**cloudbees.com/<https://grandcentral.cloudbees.com/>
click on JenkinsCI (top right) and launch builds interactively. (You need to click on the login link in Jenkins located on the top right corner).
Cheers,
-- Ceki
[1] https://logback.ci.cloudbees._**___com/job/logback/12/__** testReport/
<https://logback.ci.cloudbees.**__com/job/logback/12/** testReport/ <https://logback.ci.cloudbees.**com/job/logback/12/testReport/<https://logback.ci.cloudbees.com/job/logback/12/testReport/> **>__>
-- Ceki 65% of statistics are made up on the spot
______________________________**___________________ logback-dev mailing list logback-dev@qos.ch <mailto:logback-dev@qos.ch> http://mailman.qos.ch/mailman/**__listinfo/logback-dev<http://mailman.qos.ch/mailman/__listinfo/logback-dev> <http://mailman.qos.ch/**mailman/listinfo/logback-dev<http://mailman.qos.ch/mailman/listinfo/logback-dev>
______________________________**_________________ logback-dev mailing list logback-dev@qos.ch http://mailman.qos.ch/mailman/**listinfo/logback-dev<http://mailman.qos.ch/mailman/listinfo/logback-dev>
-- Ceki 65% of statistics are made up on the spot ______________________________**_________________ logback-dev mailing list logback-dev@qos.ch http://mailman.qos.ch/mailman/**listinfo/logback-dev<http://mailman.qos.ch/mailman/listinfo/logback-dev>

Broc, Would you please send the surefire-report for the test ch.qos.logback.core.net.SocketConnectorBaseTest, so I can take a look to see where/why it is failing? logback-core/target/surefire-reports/ch.qos.logback.core.net.SocketConnectorBaseTest.txt Thanks, carl On Apr 16, 2013, at 1:02 PM, Broc Seib wrote: Hi Ceki, I just updated to the latest code you just committed, 56e8ab2192697bc27a8cfbd8441db62aff53e87c but I think there is a different problem now. bseib@BSEIB-WIN7 /c/gitrepo $ rm -rf logback bseib@BSEIB-WIN7 /c/gitrepo $ git clone https://github.com/qos-ch/logback.git Cloning into 'logback'... remote: Counting objects: 52855, done. remote: Compressing objects: 100% (13701/13701), done. remote: Total 52855 (delta 26607), reused 52138 (delta 26038) Receiving objects: 100% (52855/52855), 20.13 MiB | 1.17 MiB/s, done. Resolving deltas: 100% (26607/26607), done. bseib@BSEIB-WIN7 /c/gitrepo $ cd logback/ bseib@BSEIB-WIN7 /c/gitrepo/logback (master) $ git log -1 commit 56e8ab2192697bc27a8cfbd8441db62aff53e87c Merge: 2ff61c7 513e380 Author: Ceki Gulcu <ceki@qos.ch<mailto:ceki@qos.ch>> Date: Tue Apr 16 18:09:23 2013 +0200 Merge branch 'master' of github.com:qos-ch/logback And then 'mvn clean; mvn install': [...] Running ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.635 sec includeNestedWithinIf(ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest) Time elapsed: 0.635 sec Results : Failed tests: testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest) lossyAppenderShouldOnlyLooseCertainEvents(ch.qos.logback.core.AsyncAppenderBaseTest): expected:<8> but was:<10> Tests run: 424, Failures: 2, Errors: 0, Skipped: 6 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.244s] [INFO] Logback Core Module ............................... FAILURE [27.251s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 28.014s [INFO] Finished at: Tue Apr 16 12:51:50 EDT 2013 [INFO] Final Memory: 28M/308M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4:test (default-test) on project logback-core: There are test failures. On Tue, Apr 16, 2013 at 12:42 PM, ceki <ceki@qos.ch<mailto:ceki@qos.ch>> wrote: I am hoping that commit [1] should make the test failures in logback-core/src/test/java/ch/qos/logback/core/rolling disappear. [1] https://github.com/qos-ch/logback/commit/2ff61c7c1cb0562 On 16.04.2013 06:05, Broc Seib wrote: Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"? Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE! withZipCompression_FileSet_NoRestart_7(ch.qos.logback.core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(Native Method) at java.io.FileInputStream.<init>(FileInputStream.java:138) at ch.qos.logback.core.testUtil.FileToBufferUtil.regularReadIntoList(Fil eToBufferUtil.java:60) at ch.qos.logback.core.testUtil.FileToBufferUtil.readIntoList(FileToBuff erUtil.java:38) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.fileContentChe ck(ScaffoldingForRollingTests.java:84) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.sortedContentC heck(ScaffoldingForRollingTests.java:99) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.generic(SizeAn dTimeBasedFNATP_Test.java:130) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.withGZCompress ion_FileBlank_NoRestart_6(SizeAndTimeBasedFNATP_Test.java:189) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl. java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(Framework Method.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCal lable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMe thod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMet hod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores. java:28) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:47 1) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor. java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.java:722) withGZCompression_FileSet_NoRestart_5(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec noCompression_FileSet_WithStopStart_4(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec noCompression_FileBlank_WithStopStart_3(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec noCompression_FileBlank_NoRestart_2(ch.qos.logback.core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec noCompression_FileSet_NoRestart_1(ch.qos.logback.core.rolling.SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec verifyRecuperationAfterFailure(ch.qos.logback.core.recovery.ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.012 sec Results : Tests in error: withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTim eBasedFNATP_Test): target\test-output\910764289\test6-2013-04-15_23_47_35-1.txt (The system cannot find the file specified) Tests run: 424, Failures: 0, Errors: 1, Skipped: 6 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.338s] [INFO] Logback Core Module ............................... FAILURE [28.279s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 29.140s [INFO] Finished at: Mon Apr 15 23:47:54 EDT 2013 [INFO] Final Memory: 28M/241M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2. 12.4:test (default-test) on project logback-core: There are test failures. On Mon, Apr 15, 2013 at 2:47 PM, ceki <ceki@qos.ch<mailto:ceki@qos.ch> <mailto:ceki@qos.ch<mailto:ceki@qos.ch>>> wrote: Hi Broc, No, it is not just you. Some of the logback test cases are time sensitive and fail from time to time, especially if the host machine is "slow". Making the build more robust is on our todo list. We now have jenkins running on such a slow machine. For example, on http://logback.qos.ch/jenkins/ build 24 was successful whereas build 25 failed: http://logback.qos.ch/jenkins/__job/logback/24/ <http://logback.qos.ch/jenkins/job/logback/24/> http://logback.qos.ch/jenkins/__job/logback/25/ <http://logback.qos.ch/jenkins/job/logback/25/> So, at least it's easier to reproduce the fails. Anyway, have you tried relaunching the build with "mvn clean; mvn install" ? BTW, since about 10 days, the logback build no longer depends on Scala for testing. It should be now much easier to set up logback within your favorite IDE. On 15.04.2013 20:11, Broc Seib wrote: Do we have stable builds yet? I cloned a fresh copy of logback yesterday, and "mvn install" still failed for me. Is it just me? Broc On Thu, Apr 4, 2013 at 9:20 AM, Ceki Gülcü <ceki@qos.ch<mailto:ceki@qos.ch> <mailto:ceki@qos.ch<mailto:ceki@qos.ch>> <mailto:ceki@qos.ch<mailto:ceki@qos.ch> <mailto:ceki@qos.ch<mailto:ceki@qos.ch>>>> wrote: As shown on [1], the logback build on the Jenkins server on cloudbees is unstable. The failures are probably due to timeout issue. I'll work on ch.qos.logback.core.____FileAppenderResilienceTest.____smoke ch.qos.logback.core.recovery.____ResilientOutputStreamTest.____verifyRecuperationAfterFailure ch.qos.logback.core.spi.____AppenderAttachableImplLockTest____.detachAppenderBoom Carl, could you please have a look at ch.qos.logback.core.net<http://ch.qos.logback.core.net/> <http://ch.qos.logback.core.net<http://ch.qos.logback.core.net/>> <http://ch.qos.logback.core.<http://ch.qos.logback.core./>__net <http://ch.qos.logback.core.net<http://ch.qos.logback.core.net/>>>.____SocketConnectorBaseTest.____testConnect ? You should be able to logon at https://grandcentral.<https://grandcentral./>__cloudbe__es.com/<http://cloudbe__es.com/> <http://cloudbees.com/> <https://grandcentral.<https://grandcentral./>__cloudbees.com/<http://cloudbees.com/> <https://grandcentral.cloudbees.com/>> click on JenkinsCI (top right) and launch builds interactively. (You need to click on the login link in Jenkins located on the top right corner). Cheers, -- Ceki [1] https://logback.ci.cloudbees.<https://logback.ci.cloudbees./>____com/job/logback/12/__testReport/ <https://logback.ci.cloudbees.<https://logback.ci.cloudbees./>__com/job/logback/12/testReport/ <https://logback.ci.cloudbees.com/job/logback/12/testReport/>__> -- Ceki 65% of statistics are made up on the spot _________________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch> <mailto:logback-dev@qos.ch<mailto:logback-dev@qos.ch>> http://mailman.qos.ch/mailman/__listinfo/logback-dev <http://mailman.qos.ch/mailman/listinfo/logback-dev> _______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch> http://mailman.qos.ch/mailman/listinfo/logback-dev -- Ceki 65% of statistics are made up on the spot _______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch> http://mailman.qos.ch/mailman/listinfo/logback-dev _______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch> http://mailman.qos.ch/mailman/listinfo/logback-dev

Here it is: ------------------------------------------------------------------------------- Test set: ch.qos.logback.core.net.SocketConnectorBaseTest ------------------------------------------------------------------------------- Tests run: 3, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 5.596 sec <<< FAILURE! testConnect(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 0.081 sec testConnectEventually(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 2.514 sec testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertFalse(Assert.java:68) at org.junit.Assert.assertFalse(Assert.java:79) at ch.qos.logback.core.net.SocketConnectorBaseTest.testConnectionFails(SocketConnectorBaseTest.java:89) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) 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.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) On Tue, Apr 16, 2013 at 1:56 PM, Harris, Carl <ceharris@vt.edu> wrote:
Broc,
Would you please send the surefire-report for the test ch.qos.logback.core.net.SocketConnectorBaseTest, so I can take a look to see where/why it is failing?
logback-core/target/surefire-reports/ch.qos.logback.core.net.SocketConnectorBaseTest.txt
Thanks,
carl
On Apr 16, 2013, at 1:02 PM, Broc Seib wrote:
Hi Ceki,
I just updated to the latest code you just committed, 56e8ab2192697bc27a8cfbd8441db62aff53e87c but I think there is a different problem now.
bseib@BSEIB-WIN7 /c/gitrepo $ rm -rf logback
bseib@BSEIB-WIN7 /c/gitrepo $ git clone https://github.com/qos-ch/logback.git Cloning into 'logback'... remote: Counting objects: 52855, done. remote: Compressing objects: 100% (13701/13701), done. remote: Total 52855 (delta 26607), reused 52138 (delta 26038) Receiving objects: 100% (52855/52855), 20.13 MiB | 1.17 MiB/s, done. Resolving deltas: 100% (26607/26607), done.
bseib@BSEIB-WIN7 /c/gitrepo $ cd logback/
bseib@BSEIB-WIN7 /c/gitrepo/logback (master) $ git log -1 commit 56e8ab2192697bc27a8cfbd8441db62aff53e87c Merge: 2ff61c7 513e380 Author: Ceki Gulcu <ceki@qos.ch<mailto:ceki@qos.ch>> Date: Tue Apr 16 18:09:23 2013 +0200
Merge branch 'master' of github.com:qos-ch/logback
And then 'mvn clean; mvn install':
[...] Running ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.635 sec includeNestedWithinIf(ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest) Time elapsed: 0.635 sec
Results :
Failed tests: testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest)
lossyAppenderShouldOnlyLooseCertainEvents(ch.qos.logback.core.AsyncAppenderBaseTest): expected:<8> but was:<10>
Tests run: 424, Failures: 2, Errors: 0, Skipped: 6
[INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.244s] [INFO] Logback Core Module ............................... FAILURE [27.251s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 28.014s [INFO] Finished at: Tue Apr 16 12:51:50 EDT 2013 [INFO] Final Memory: 28M/308M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4:test (default-test) on project logback-core: There are test failures.
On Tue, Apr 16, 2013 at 12:42 PM, ceki <ceki@qos.ch<mailto:ceki@qos.ch>> wrote:
I am hoping that commit [1] should make the test failures in logback-core/src/test/java/ch/qos/logback/core/rolling disappear.
[1] https://github.com/qos-ch/logback/commit/2ff61c7c1cb0562
On 16.04.2013 06:05, Broc Seib wrote: Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"?
Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE!
withZipCompression_FileSet_NoRestart_7(ch.qos.logback.core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec
withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(Native Method) at java.io.FileInputStream.<init>(FileInputStream.java:138) at ch.qos.logback.core.testUtil.FileToBufferUtil.regularReadIntoList(Fil eToBufferUtil.java:60) at ch.qos.logback.core.testUtil.FileToBufferUtil.readIntoList(FileToBuff erUtil.java:38) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.fileContentChe ck(ScaffoldingForRollingTests.java:84) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.sortedContentC heck(ScaffoldingForRollingTests.java:99) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.generic(SizeAn dTimeBasedFNATP_Test.java:130) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.withGZCompress ion_FileBlank_NoRestart_6(SizeAndTimeBasedFNATP_Test.java:189) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl. java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(Framework Method.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCal lable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMe thod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMet hod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores. java:28) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:47 1) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor. java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.java:722)
withGZCompression_FileSet_NoRestart_5(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec
noCompression_FileSet_WithStopStart_4(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec
noCompression_FileBlank_WithStopStart_3(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec
noCompression_FileBlank_NoRestart_2(ch.qos.logback.core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec
noCompression_FileSet_NoRestart_1(ch.qos.logback.core.rolling.SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec
verifyRecuperationAfterFailure(ch.qos.logback.core.recovery.ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.012 sec
Results :
Tests in error:
withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTim eBasedFNATP_Test): target\test-output\910764289\test6-2013-04-15_23_47_35-1.txt (The system cannot find the file specified)
Tests run: 424, Failures: 0, Errors: 1, Skipped: 6
[INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.338s] [INFO] Logback Core Module ............................... FAILURE [28.279s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 29.140s [INFO] Finished at: Mon Apr 15 23:47:54 EDT 2013 [INFO] Final Memory: 28M/241M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2. 12.4:test (default-test) on project logback-core: There are test failures.
On Mon, Apr 15, 2013 at 2:47 PM, ceki <ceki@qos.ch<mailto:ceki@qos.ch> <mailto:ceki@qos.ch<mailto:ceki@qos.ch>>>
wrote:
Hi Broc,
No, it is not just you. Some of the logback test cases are time sensitive and fail from time to time, especially if the host machine is "slow". Making the build more robust is on our todo list.
We now have jenkins running on such a slow machine. For example, on http://logback.qos.ch/jenkins/ build 24 was successful whereas build 25 failed:
http://logback.qos.ch/jenkins/__job/logback/24/ <http://logback.qos.ch/jenkins/job/logback/24/> http://logback.qos.ch/jenkins/__job/logback/25/ <http://logback.qos.ch/jenkins/job/logback/25/>
So, at least it's easier to reproduce the fails.
Anyway, have you tried relaunching the build with "mvn clean; mvn install" ?
BTW, since about 10 days, the logback build no longer depends on Scala for testing. It should be now much easier to set up logback within your favorite IDE.
On 15.04.2013 20:11, Broc Seib wrote:
Do we have stable builds yet?
I cloned a fresh copy of logback yesterday, and "mvn install" still failed for me. Is it just me?
Broc
On Thu, Apr 4, 2013 at 9:20 AM, Ceki Gülcü <ceki@qos.ch<mailto: ceki@qos.ch> <mailto:ceki@qos.ch<mailto:ceki@qos.ch>> <mailto:ceki@qos.ch<mailto:ceki@qos.ch> <mailto:ceki@qos.ch <mailto:ceki@qos.ch>>>> wrote:
As shown on [1], the logback build on the Jenkins server on cloudbees is unstable.
The failures are probably due to timeout issue. I'll work on
ch.qos.logback.core.____FileAppenderResilienceTest.____smoke
ch.qos.logback.core.recovery.____ResilientOutputStreamTest.____verifyRecuperationAfterFailure
ch.qos.logback.core.spi.____AppenderAttachableImplLockTest____.detachAppenderBoom
Carl, could you please have a look at
ch.qos.logback.core.net<http://ch.qos.logback.core.net/> < http://ch.qos.logback.core.net<http://ch.qos.logback.core.net/>> <http://ch.qos.logback.core.<http://ch.qos.logback.core./
__net <http://ch.qos.logback.core.net<http://ch.qos.logback.core.net/
.____SocketConnectorBaseTest.____testConnect
?
You should be able to logon at
https://grandcentral.<https://grandcentral./>__cloudbe__es.com/< http://cloudbe__es.com/> <http://cloudbees.com/>
<https://grandcentral.<https://grandcentral./>__ cloudbees.com/<http://cloudbees.com/> <https://grandcentral.cloudbees.com/>>
click on JenkinsCI (top right) and launch builds interactively. (You need to click on the login link in Jenkins located on the top right corner).
Cheers,
-- Ceki
[1] https://logback.ci.cloudbees.<https://logback.ci.cloudbees./
____com/job/logback/12/__testReport/
<https://logback.ci.cloudbees.<https://logback.ci.cloudbees./
__com/job/logback/12/testReport/ <https://logback.ci.cloudbees.com/job/logback/12/testReport/>__>
-- Ceki 65% of statistics are made up on the spot
_________________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch> <mailto: logback-dev@qos.ch<mailto:logback-dev@qos.ch>> http://mailman.qos.ch/mailman/__listinfo/logback-dev <http://mailman.qos.ch/mailman/listinfo/logback-dev>
_______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch> http://mailman.qos.ch/mailman/listinfo/logback-dev
-- Ceki 65% of statistics are made up on the spot _______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch> http://mailman.qos.ch/mailman/listinfo/logback-dev
_______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch> http://mailman.qos.ch/mailman/listinfo/logback-dev
_______________________________________________ logback-dev mailing list logback-dev@qos.ch http://mailman.qos.ch/mailman/listinfo/logback-dev

This might also be useful: http://logback.qos.ch/jenkins/job/logback/30/ Test Result (2 failures / +2) ch.qos.logback.classic.net.SocketAppenderTest.receiveMessage ch.qos.logback.classic.net.SocketAppenderTest.receiveWithContext On 16.04.2013 20:00, Broc Seib wrote:
Here it is:
------------------------------------------------------------------------------- Test set: ch.qos.logback.core.net.SocketConnectorBaseTest ------------------------------------------------------------------------------- Tests run: 3, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 5.596 sec <<< FAILURE! testConnect(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 0.081 sec testConnectEventually(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 2.514 sec testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertFalse(Assert.java:68) at org.junit.Assert.assertFalse(Assert.java:79) at ch.qos.logback.core.net.SocketConnectorBaseTest.testConnectionFails(SocketConnectorBaseTest.java:89) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) 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.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722)
On Tue, Apr 16, 2013 at 1:56 PM, Harris, Carl <ceharris@vt.edu <mailto:ceharris@vt.edu>> wrote:
Broc,
Would you please send the surefire-report for the test ch.qos.logback.core.net.SocketConnectorBaseTest, so I can take a look to see where/why it is failing?
logback-core/target/surefire-reports/ch.qos.logback.core.net.SocketConnectorBaseTest.txt
Thanks,
carl
On Apr 16, 2013, at 1:02 PM, Broc Seib wrote:
Hi Ceki,
I just updated to the latest code you just committed, 56e8ab2192697bc27a8cfbd8441db62aff53e87c but I think there is a different problem now.
bseib@BSEIB-WIN7 /c/gitrepo $ rm -rf logback
bseib@BSEIB-WIN7 /c/gitrepo $ git clone https://github.com/qos-ch/logback.git Cloning into 'logback'... remote: Counting objects: 52855, done. remote: Compressing objects: 100% (13701/13701), done. remote: Total 52855 (delta 26607), reused 52138 (delta 26038) Receiving objects: 100% (52855/52855), 20.13 MiB | 1.17 MiB/s, done. Resolving deltas: 100% (26607/26607), done.
bseib@BSEIB-WIN7 /c/gitrepo $ cd logback/
bseib@BSEIB-WIN7 /c/gitrepo/logback (master) $ git log -1 commit 56e8ab2192697bc27a8cfbd8441db62aff53e87c Merge: 2ff61c7 513e380 Author: Ceki Gulcu <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>> Date: Tue Apr 16 18:09:23 2013 +0200
Merge branch 'master' of github.com:qos-ch/logback
And then 'mvn clean; mvn install':
[...] Running ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.635 sec includeNestedWithinIf(ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest) Time elapsed: 0.635 sec
Results :
Failed tests: testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest)
lossyAppenderShouldOnlyLooseCertainEvents(ch.qos.logback.core.AsyncAppenderBaseTest): expected:<8> but was:<10>
Tests run: 424, Failures: 2, Errors: 0, Skipped: 6
[INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.244s] [INFO] Logback Core Module ............................... FAILURE [27.251s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 28.014s [INFO] Finished at: Tue Apr 16 12:51:50 EDT 2013 [INFO] Final Memory: 28M/308M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4:test (default-test) on project logback-core: There are test failures.
On Tue, Apr 16, 2013 at 12:42 PM, ceki <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>> wrote:
I am hoping that commit [1] should make the test failures in logback-core/src/test/java/ch/qos/logback/core/rolling disappear.
[1] https://github.com/qos-ch/logback/commit/2ff61c7c1cb0562
On 16.04.2013 06:05, Broc Seib wrote: Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"?
Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE! withZipCompression_FileSet_NoRestart_7(ch.qos.logback.core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(Native Method) at java.io.FileInputStream.<init>(FileInputStream.java:138) at ch.qos.logback.core.testUtil.FileToBufferUtil.regularReadIntoList(Fil eToBufferUtil.java:60) at ch.qos.logback.core.testUtil.FileToBufferUtil.readIntoList(FileToBuff erUtil.java:38) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.fileContentChe ck(ScaffoldingForRollingTests.java:84) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.sortedContentC heck(ScaffoldingForRollingTests.java:99) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.generic(SizeAn dTimeBasedFNATP_Test.java:130) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.withGZCompress ion_FileBlank_NoRestart_6(SizeAndTimeBasedFNATP_Test.java:189) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl. java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(Framework Method.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCal lable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMe thod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMet hod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores. java:28) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:47 1) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor. java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.java:722)
withGZCompression_FileSet_NoRestart_5(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec noCompression_FileSet_WithStopStart_4(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec noCompression_FileBlank_WithStopStart_3(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec noCompression_FileBlank_NoRestart_2(ch.qos.logback.core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec noCompression_FileSet_NoRestart_1(ch.qos.logback.core.rolling.SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec verifyRecuperationAfterFailure(ch.qos.logback.core.recovery.ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.012 sec
Results :
Tests in error:
withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTim eBasedFNATP_Test): target\test-output\910764289\test6-2013-04-15_23_47_35-1.txt (The system cannot find the file specified)
Tests run: 424, Failures: 0, Errors: 1, Skipped: 6
[INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.338s] [INFO] Logback Core Module ............................... FAILURE [28.279s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 29.140s [INFO] Finished at: Mon Apr 15 23:47:54 EDT 2013 [INFO] Final Memory: 28M/241M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2. 12.4:test (default-test) on project logback-core: There are test failures.
On Mon, Apr 15, 2013 at 2:47 PM, ceki <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>> <mailto:ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>>>
wrote:
Hi Broc,
No, it is not just you. Some of the logback test cases are time sensitive and fail from time to time, especially if the host machine is "slow". Making the build more robust is on our todo list.
We now have jenkins running on such a slow machine. For example, on http://logback.qos.ch/jenkins/ build 24 was successful whereas build 25 failed:
http://logback.qos.ch/jenkins/__job/logback/24/ <http://logback.qos.ch/jenkins/job/logback/24/> http://logback.qos.ch/jenkins/__job/logback/25/ <http://logback.qos.ch/jenkins/job/logback/25/>
So, at least it's easier to reproduce the fails.
Anyway, have you tried relaunching the build with "mvn clean; mvn install" ?
BTW, since about 10 days, the logback build no longer depends on Scala for testing. It should be now much easier to set up logback within your favorite IDE.
On 15.04.2013 20:11, Broc Seib wrote:
Do we have stable builds yet?
I cloned a fresh copy of logback yesterday, and "mvn install" still failed for me. Is it just me?
Broc
On Thu, Apr 4, 2013 at 9:20 AM, Ceki Gülcü <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>> <mailto:ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>> <mailto:ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>> <mailto:ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>>>> wrote:
As shown on [1], the logback build on the Jenkins server on cloudbees is unstable.
The failures are probably due to timeout issue. I'll work on
ch.qos.logback.core.____FileAppenderResilienceTest.____smoke
ch.qos.logback.core.recovery.____ResilientOutputStreamTest.____verifyRecuperationAfterFailure
ch.qos.logback.core.spi.____AppenderAttachableImplLockTest____.detachAppenderBoom
Carl, could you please have a look at
ch.qos.logback.core.net <http://ch.qos.logback.core.net><http://ch.qos.logback.core.net/> <http://ch.qos.logback.core.net<http://ch.qos.logback.core.net/>>
<http://ch.qos.logback.core.<http://ch.qos.logback.core./>__net
?
You should be able to logon at
https://grandcentral.<https://grandcentral./>__cloudbe__es.com/ <http://cloudbe__es.com/><http://cloudbe__es.com/> <http://cloudbees.com/>
<https://grandcentral.<https://grandcentral./>__cloudbees.com/ <http://cloudbees.com/><http://cloudbees.com/> <https://grandcentral.cloudbees.com/>>
click on JenkinsCI (top right) and launch builds interactively. (You need to click on the login link in Jenkins located on the top right corner).
Cheers,
-- Ceki
[1] https://logback.ci.cloudbees.<https://logback.ci.cloudbees./>____com/job/logback/12/__testReport/
<https://logback.ci.cloudbees.<https://logback.ci.cloudbees./>__com/job/logback/12/testReport/
<https://logback.ci.cloudbees.com/job/logback/12/testReport/>__>
-- Ceki 65% of statistics are made up on the spot
_________________________________________________ logback-dev mailing list logback-dev@qos.ch <mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch <mailto:logback-dev@qos.ch>> <mailto:logback-dev@qos.ch <mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch <mailto:logback-dev@qos.ch>>> http://mailman.qos.ch/mailman/__listinfo/logback-dev <http://mailman.qos.ch/mailman/listinfo/logback-dev>
_______________________________________________ logback-dev mailing list logback-dev@qos.ch <mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch <mailto:logback-dev@qos.ch>> http://mailman.qos.ch/mailman/listinfo/logback-dev
-- Ceki 65% of statistics are made up on the spot

SocketAppenderTest isn't my work, but I'll try to take a look at it. carl On Apr 16, 2013, at 2:20 PM, ceki wrote:
This might also be useful:
http://logback.qos.ch/jenkins/job/logback/30/
Test Result (2 failures / +2) ch.qos.logback.classic.net.SocketAppenderTest.receiveMessage ch.qos.logback.classic.net.SocketAppenderTest.receiveWithContext
On 16.04.2013 20:00, Broc Seib wrote:
Here it is:
------------------------------------------------------------------------------- Test set: ch.qos.logback.core.net.SocketConnectorBaseTest ------------------------------------------------------------------------------- Tests run: 3, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 5.596 sec <<< FAILURE! testConnect(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 0.081 sec testConnectEventually(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 2.514 sec testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertFalse(Assert.java:68) at org.junit.Assert.assertFalse(Assert.java:79) at ch.qos.logback.core.net.SocketConnectorBaseTest.testConnectionFails(SocketConnectorBaseTest.java:89) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) 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.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722)
On Tue, Apr 16, 2013 at 1:56 PM, Harris, Carl <ceharris@vt.edu <mailto:ceharris@vt.edu>> wrote:
Broc,
Would you please send the surefire-report for the test ch.qos.logback.core.net.SocketConnectorBaseTest, so I can take a look to see where/why it is failing?
logback-core/target/surefire-reports/ch.qos.logback.core.net.SocketConnectorBaseTest.txt
Thanks,
carl
On Apr 16, 2013, at 1:02 PM, Broc Seib wrote:
Hi Ceki,
I just updated to the latest code you just committed, 56e8ab2192697bc27a8cfbd8441db62aff53e87c but I think there is a different problem now.
bseib@BSEIB-WIN7 /c/gitrepo $ rm -rf logback
bseib@BSEIB-WIN7 /c/gitrepo $ git clone https://github.com/qos-ch/logback.git Cloning into 'logback'... remote: Counting objects: 52855, done. remote: Compressing objects: 100% (13701/13701), done. remote: Total 52855 (delta 26607), reused 52138 (delta 26038) Receiving objects: 100% (52855/52855), 20.13 MiB | 1.17 MiB/s, done. Resolving deltas: 100% (26607/26607), done.
bseib@BSEIB-WIN7 /c/gitrepo $ cd logback/
bseib@BSEIB-WIN7 /c/gitrepo/logback (master) $ git log -1 commit 56e8ab2192697bc27a8cfbd8441db62aff53e87c Merge: 2ff61c7 513e380 Author: Ceki Gulcu <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>> Date: Tue Apr 16 18:09:23 2013 +0200
Merge branch 'master' of github.com:qos-ch/logback
And then 'mvn clean; mvn install':
[...] Running ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.635 sec includeNestedWithinIf(ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest) Time elapsed: 0.635 sec
Results :
Failed tests: testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest)
lossyAppenderShouldOnlyLooseCertainEvents(ch.qos.logback.core.AsyncAppenderBaseTest): expected:<8> but was:<10>
Tests run: 424, Failures: 2, Errors: 0, Skipped: 6
[INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.244s] [INFO] Logback Core Module ............................... FAILURE [27.251s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 28.014s [INFO] Finished at: Tue Apr 16 12:51:50 EDT 2013 [INFO] Final Memory: 28M/308M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4:test (default-test) on project logback-core: There are test failures.
On Tue, Apr 16, 2013 at 12:42 PM, ceki <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>> wrote:
I am hoping that commit [1] should make the test failures in logback-core/src/test/java/ch/qos/logback/core/rolling disappear.
[1] https://github.com/qos-ch/logback/commit/2ff61c7c1cb0562
On 16.04.2013 06:05, Broc Seib wrote: Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"?
Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE! withZipCompression_FileSet_NoRestart_7(ch.qos.logback.core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(Native Method) at java.io.FileInputStream.<init>(FileInputStream.java:138) at ch.qos.logback.core.testUtil.FileToBufferUtil.regularReadIntoList(Fil eToBufferUtil.java:60) at ch.qos.logback.core.testUtil.FileToBufferUtil.readIntoList(FileToBuff erUtil.java:38) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.fileContentChe ck(ScaffoldingForRollingTests.java:84) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.sortedContentC heck(ScaffoldingForRollingTests.java:99) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.generic(SizeAn dTimeBasedFNATP_Test.java:130) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.withGZCompress ion_FileBlank_NoRestart_6(SizeAndTimeBasedFNATP_Test.java:189) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl. java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(Framework Method.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCal lable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMe thod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMet hod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores. java:28) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:47 1) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor. java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.java:722)
withGZCompression_FileSet_NoRestart_5(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec noCompression_FileSet_WithStopStart_4(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec noCompression_FileBlank_WithStopStart_3(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec noCompression_FileBlank_NoRestart_2(ch.qos.logback.core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec noCompression_FileSet_NoRestart_1(ch.qos.logback.core.rolling.SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec verifyRecuperationAfterFailure(ch.qos.logback.core.recovery.ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.012 sec
Results :
Tests in error:
withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTim eBasedFNATP_Test): target\test-output\910764289\test6-2013-04-15_23_47_35-1.txt (The system cannot find the file specified)
Tests run: 424, Failures: 0, Errors: 1, Skipped: 6
[INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.338s] [INFO] Logback Core Module ............................... FAILURE [28.279s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 29.140s [INFO] Finished at: Mon Apr 15 23:47:54 EDT 2013 [INFO] Final Memory: 28M/241M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2. 12.4:test (default-test) on project logback-core: There are test failures.
On Mon, Apr 15, 2013 at 2:47 PM, ceki <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>> <mailto:ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>>>
wrote:
Hi Broc,
No, it is not just you. Some of the logback test cases are time sensitive and fail from time to time, especially if the host machine is "slow". Making the build more robust is on our todo list.
We now have jenkins running on such a slow machine. For example, on http://logback.qos.ch/jenkins/ build 24 was successful whereas build 25 failed:
http://logback.qos.ch/jenkins/__job/logback/24/ <http://logback.qos.ch/jenkins/job/logback/24/> http://logback.qos.ch/jenkins/__job/logback/25/ <http://logback.qos.ch/jenkins/job/logback/25/>
So, at least it's easier to reproduce the fails.
Anyway, have you tried relaunching the build with "mvn clean; mvn install" ?
BTW, since about 10 days, the logback build no longer depends on Scala for testing. It should be now much easier to set up logback within your favorite IDE.
On 15.04.2013 20:11, Broc Seib wrote:
Do we have stable builds yet?
I cloned a fresh copy of logback yesterday, and "mvn install" still failed for me. Is it just me?
Broc
On Thu, Apr 4, 2013 at 9:20 AM, Ceki Gülcü <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>> <mailto:ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>> <mailto:ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>> <mailto:ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>>>> wrote:
As shown on [1], the logback build on the Jenkins server on cloudbees is unstable.
The failures are probably due to timeout issue. I'll work on
ch.qos.logback.core.____FileAppenderResilienceTest.____smoke
ch.qos.logback.core.recovery.____ResilientOutputStreamTest.____verifyRecuperationAfterFailure
ch.qos.logback.core.spi.____AppenderAttachableImplLockTest____.detachAppenderBoom
Carl, could you please have a look at
ch.qos.logback.core.net <http://ch.qos.logback.core.net><http://ch.qos.logback.core.net/> <http://ch.qos.logback.core.net<http://ch.qos.logback.core.net/>>
<http://ch.qos.logback.core.<http://ch.qos.logback.core./>__net
?
You should be able to logon at
https://grandcentral.<https://grandcentral./>__cloudbe__es.com/ <http://cloudbe__es.com/><http://cloudbe__es.com/> <http://cloudbees.com/>
<https://grandcentral.<https://grandcentral./>__cloudbees.com/ <http://cloudbees.com/><http://cloudbees.com/> <https://grandcentral.cloudbees.com/>>
click on JenkinsCI (top right) and launch builds interactively. (You need to click on the login link in Jenkins located on the top right corner).
Cheers,
-- Ceki
[1] https://logback.ci.cloudbees.<https://logback.ci.cloudbees./>____com/job/logback/12/__testReport/
<https://logback.ci.cloudbees.<https://logback.ci.cloudbees./>__com/job/logback/12/testReport/
<https://logback.ci.cloudbees.com/job/logback/12/testReport/>__>
-- Ceki 65% of statistics are made up on the spot
_________________________________________________ logback-dev mailing list logback-dev@qos.ch <mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch <mailto:logback-dev@qos.ch>> <mailto:logback-dev@qos.ch <mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch <mailto:logback-dev@qos.ch>>> http://mailman.qos.ch/mailman/__listinfo/logback-dev <http://mailman.qos.ch/mailman/listinfo/logback-dev>
_______________________________________________ logback-dev mailing list logback-dev@qos.ch <mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch <mailto:logback-dev@qos.ch>> http://mailman.qos.ch/mailman/listinfo/logback-dev
-- Ceki 65% of statistics are made up on the spot _______________________________________________ logback-dev mailing list logback-dev@qos.ch http://mailman.qos.ch/mailman/listinfo/logback-dev

Thanks Carl. Please pick it if it's a low hanging fruit. On 16.04.2013 20:25, Harris, Carl wrote:
SocketAppenderTest isn't my work, but I'll try to take a look at it.
carl
On Apr 16, 2013, at 2:20 PM, ceki wrote:
This might also be useful:
http://logback.qos.ch/jenkins/job/logback/30/
Test Result (2 failures / +2) ch.qos.logback.classic.net.SocketAppenderTest.receiveMessage ch.qos.logback.classic.net.SocketAppenderTest.receiveWithContext
On 16.04.2013 20:00, Broc Seib wrote:
Here it is:
------------------------------------------------------------------------------- Test set: ch.qos.logback.core.net.SocketConnectorBaseTest ------------------------------------------------------------------------------- Tests run: 3, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 5.596 sec <<< FAILURE! testConnect(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 0.081 sec testConnectEventually(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 2.514 sec testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertFalse(Assert.java:68) at org.junit.Assert.assertFalse(Assert.java:79) at ch.qos.logback.core.net.SocketConnectorBaseTest.testConnectionFails(SocketConnectorBaseTest.java:89) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) 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.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722)
On Tue, Apr 16, 2013 at 1:56 PM, Harris, Carl <ceharris@vt.edu <mailto:ceharris@vt.edu>> wrote:
Broc,
Would you please send the surefire-report for the test ch.qos.logback.core.net.SocketConnectorBaseTest, so I can take a look to see where/why it is failing?
logback-core/target/surefire-reports/ch.qos.logback.core.net.SocketConnectorBaseTest.txt
Thanks,
carl
On Apr 16, 2013, at 1:02 PM, Broc Seib wrote:
Hi Ceki,
I just updated to the latest code you just committed, 56e8ab2192697bc27a8cfbd8441db62aff53e87c but I think there is a different problem now.
bseib@BSEIB-WIN7 /c/gitrepo $ rm -rf logback
bseib@BSEIB-WIN7 /c/gitrepo $ git clone https://github.com/qos-ch/logback.git Cloning into 'logback'... remote: Counting objects: 52855, done. remote: Compressing objects: 100% (13701/13701), done. remote: Total 52855 (delta 26607), reused 52138 (delta 26038) Receiving objects: 100% (52855/52855), 20.13 MiB | 1.17 MiB/s, done. Resolving deltas: 100% (26607/26607), done.
bseib@BSEIB-WIN7 /c/gitrepo $ cd logback/
bseib@BSEIB-WIN7 /c/gitrepo/logback (master) $ git log -1 commit 56e8ab2192697bc27a8cfbd8441db62aff53e87c Merge: 2ff61c7 513e380 Author: Ceki Gulcu <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>> Date: Tue Apr 16 18:09:23 2013 +0200
Merge branch 'master' of github.com:qos-ch/logback
And then 'mvn clean; mvn install':
[...] Running ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.635 sec includeNestedWithinIf(ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest) Time elapsed: 0.635 sec
Results :
Failed tests: testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest)
lossyAppenderShouldOnlyLooseCertainEvents(ch.qos.logback.core.AsyncAppenderBaseTest): expected:<8> but was:<10>
Tests run: 424, Failures: 2, Errors: 0, Skipped: 6
[INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.244s] [INFO] Logback Core Module ............................... FAILURE [27.251s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 28.014s [INFO] Finished at: Tue Apr 16 12:51:50 EDT 2013 [INFO] Final Memory: 28M/308M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4:test (default-test) on project logback-core: There are test failures.
On Tue, Apr 16, 2013 at 12:42 PM, ceki <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>> wrote:
I am hoping that commit [1] should make the test failures in logback-core/src/test/java/ch/qos/logback/core/rolling disappear.
[1] https://github.com/qos-ch/logback/commit/2ff61c7c1cb0562
On 16.04.2013 06:05, Broc Seib wrote: Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"?
Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE! withZipCompression_FileSet_NoRestart_7(ch.qos.logback.core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(Native Method) at java.io.FileInputStream.<init>(FileInputStream.java:138) at ch.qos.logback.core.testUtil.FileToBufferUtil.regularReadIntoList(Fil eToBufferUtil.java:60) at ch.qos.logback.core.testUtil.FileToBufferUtil.readIntoList(FileToBuff erUtil.java:38) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.fileContentChe ck(ScaffoldingForRollingTests.java:84) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.sortedContentC heck(ScaffoldingForRollingTests.java:99) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.generic(SizeAn dTimeBasedFNATP_Test.java:130) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.withGZCompress ion_FileBlank_NoRestart_6(SizeAndTimeBasedFNATP_Test.java:189) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl. java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(Framework Method.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCal lable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMe thod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMet hod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores. java:28) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:47 1) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor. java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.java:722)
withGZCompression_FileSet_NoRestart_5(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec noCompression_FileSet_WithStopStart_4(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec noCompression_FileBlank_WithStopStart_3(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec noCompression_FileBlank_NoRestart_2(ch.qos.logback.core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec noCompression_FileSet_NoRestart_1(ch.qos.logback.core.rolling.SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec verifyRecuperationAfterFailure(ch.qos.logback.core.recovery.ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.012 sec
Results :
Tests in error:
withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTim eBasedFNATP_Test): target\test-output\910764289\test6-2013-04-15_23_47_35-1.txt (The system cannot find the file specified)
Tests run: 424, Failures: 0, Errors: 1, Skipped: 6
[INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.338s] [INFO] Logback Core Module ............................... FAILURE [28.279s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 29.140s [INFO] Finished at: Mon Apr 15 23:47:54 EDT 2013 [INFO] Final Memory: 28M/241M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2. 12.4:test (default-test) on project logback-core: There are test failures.
On Mon, Apr 15, 2013 at 2:47 PM, ceki <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>> <mailto:ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>>>
wrote:
Hi Broc,
No, it is not just you. Some of the logback test cases are time sensitive and fail from time to time, especially if the host machine is "slow". Making the build more robust is on our todo list.
We now have jenkins running on such a slow machine. For example, on http://logback.qos.ch/jenkins/ build 24 was successful whereas build 25 failed:
http://logback.qos.ch/jenkins/__job/logback/24/ <http://logback.qos.ch/jenkins/job/logback/24/> http://logback.qos.ch/jenkins/__job/logback/25/ <http://logback.qos.ch/jenkins/job/logback/25/>
So, at least it's easier to reproduce the fails.
Anyway, have you tried relaunching the build with "mvn clean; mvn install" ?
BTW, since about 10 days, the logback build no longer depends on Scala for testing. It should be now much easier to set up logback within your favorite IDE.
On 15.04.2013 20:11, Broc Seib wrote:
Do we have stable builds yet?
I cloned a fresh copy of logback yesterday, and "mvn install" still failed for me. Is it just me?
Broc
On Thu, Apr 4, 2013 at 9:20 AM, Ceki Gülcü <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>> <mailto:ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>> <mailto:ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>> <mailto:ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>>>> wrote:
As shown on [1], the logback build on the Jenkins server on cloudbees is unstable.
The failures are probably due to timeout issue. I'll work on
ch.qos.logback.core.____FileAppenderResilienceTest.____smoke
ch.qos.logback.core.recovery.____ResilientOutputStreamTest.____verifyRecuperationAfterFailure
ch.qos.logback.core.spi.____AppenderAttachableImplLockTest____.detachAppenderBoom
Carl, could you please have a look at
ch.qos.logback.core.net <http://ch.qos.logback.core.net><http://ch.qos.logback.core.net/> <http://ch.qos.logback.core.net<http://ch.qos.logback.core.net/>>
<http://ch.qos.logback.core.<http://ch.qos.logback.core./>__net
?
You should be able to logon at
https://grandcentral.<https://grandcentral./>__cloudbe__es.com/ <http://cloudbe__es.com/><http://cloudbe__es.com/> <http://cloudbees.com/>
<https://grandcentral.<https://grandcentral./>__cloudbees.com/ <http://cloudbees.com/><http://cloudbees.com/> <https://grandcentral.cloudbees.com/>>
click on JenkinsCI (top right) and launch builds interactively. (You need to click on the login link in Jenkins located on the top right corner).
Cheers,
-- Ceki
[1] https://logback.ci.cloudbees.<https://logback.ci.cloudbees./>____com/job/logback/12/__testReport/
<https://logback.ci.cloudbees.<https://logback.ci.cloudbees./>__com/job/logback/12/testReport/
<https://logback.ci.cloudbees.com/job/logback/12/testReport/>__>
-- Ceki 65% of statistics are made up on the spot

Last couple of commits should help reduce timing sensitivity in the SocketConnectorBaseTest and SocketAppenderTest. Anxious to hear if this improves matters for you Broc. carl On Apr 16, 2013, at 2:00 PM, Broc Seib wrote: Here it is: ------------------------------------------------------------------------------- Test set: ch.qos.logback.core.net.SocketConnectorBaseTest ------------------------------------------------------------------------------- Tests run: 3, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 5.596 sec <<< FAILURE! testConnect(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 0.081 sec testConnectEventually(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 2.514 sec testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertFalse(Assert.java:68) at org.junit.Assert.assertFalse(Assert.java:79) at ch.qos.logback.core.net.SocketConnectorBaseTest.testConnectionFails(SocketConnectorBaseTest.java:89) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) 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.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) On Tue, Apr 16, 2013 at 1:56 PM, Harris, Carl <ceharris@vt.edu<mailto:ceharris@vt.edu>> wrote: Broc, Would you please send the surefire-report for the test ch.qos.logback.core.net.SocketConnectorBaseTest, so I can take a look to see where/why it is failing? logback-core/target/surefire-reports/ch.qos.logback.core.net.SocketConnectorBaseTest.txt Thanks, carl On Apr 16, 2013, at 1:02 PM, Broc Seib wrote: Hi Ceki, I just updated to the latest code you just committed, 56e8ab2192697bc27a8cfbd8441db62aff53e87c but I think there is a different problem now. bseib@BSEIB-WIN7 /c/gitrepo $ rm -rf logback bseib@BSEIB-WIN7 /c/gitrepo $ git clone https://github.com/qos-ch/logback.git Cloning into 'logback'... remote: Counting objects: 52855, done. remote: Compressing objects: 100% (13701/13701), done. remote: Total 52855 (delta 26607), reused 52138 (delta 26038) Receiving objects: 100% (52855/52855), 20.13 MiB | 1.17 MiB/s, done. Resolving deltas: 100% (26607/26607), done. bseib@BSEIB-WIN7 /c/gitrepo $ cd logback/ bseib@BSEIB-WIN7 /c/gitrepo/logback (master) $ git log -1 commit 56e8ab2192697bc27a8cfbd8441db62aff53e87c Merge: 2ff61c7 513e380 Author: Ceki Gulcu <ceki@qos.ch<mailto:ceki@qos.ch><mailto:ceki@qos.ch<mailto:ceki@qos.ch>>> Date: Tue Apr 16 18:09:23 2013 +0200 Merge branch 'master' of github.com:qos-ch/logback And then 'mvn clean; mvn install': [...] Running ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.635 sec includeNestedWithinIf(ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest) Time elapsed: 0.635 sec Results : Failed tests: testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest) lossyAppenderShouldOnlyLooseCertainEvents(ch.qos.logback.core.AsyncAppenderBaseTest): expected:<8> but was:<10> Tests run: 424, Failures: 2, Errors: 0, Skipped: 6 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.244s] [INFO] Logback Core Module ............................... FAILURE [27.251s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 28.014s [INFO] Finished at: Tue Apr 16 12:51:50 EDT 2013 [INFO] Final Memory: 28M/308M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4:test (default-test) on project logback-core: There are test failures. On Tue, Apr 16, 2013 at 12:42 PM, ceki <ceki@qos.ch<mailto:ceki@qos.ch><mailto:ceki@qos.ch<mailto:ceki@qos.ch>>> wrote: I am hoping that commit [1] should make the test failures in logback-core/src/test/java/ch/qos/logback/core/rolling disappear. [1] https://github.com/qos-ch/logback/commit/2ff61c7c1cb0562 On 16.04.2013 06:05, Broc Seib wrote: Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"? Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE! withZipCompression_FileSet_NoRestart_7(ch.qos.logback.core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(Native Method) at java.io.FileInputStream.<init>(FileInputStream.java:138) at ch.qos.logback.core.testUtil.FileToBufferUtil.regularReadIntoList(Fil eToBufferUtil.java:60) at ch.qos.logback.core.testUtil.FileToBufferUtil.readIntoList(FileToBuff erUtil.java:38) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.fileContentChe ck(ScaffoldingForRollingTests.java:84) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.sortedContentC heck(ScaffoldingForRollingTests.java:99) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.generic(SizeAn dTimeBasedFNATP_Test.java:130) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.withGZCompress ion_FileBlank_NoRestart_6(SizeAndTimeBasedFNATP_Test.java:189) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl. java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(Framework Method.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCal lable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMe thod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMet hod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores. java:28) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:47 1) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor. java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.java:722) withGZCompression_FileSet_NoRestart_5(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec noCompression_FileSet_WithStopStart_4(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec noCompression_FileBlank_WithStopStart_3(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec noCompression_FileBlank_NoRestart_2(ch.qos.logback.core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec noCompression_FileSet_NoRestart_1(ch.qos.logback.core.rolling.SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec verifyRecuperationAfterFailure(ch.qos.logback.core.recovery.ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.012 sec Results : Tests in error: withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTim eBasedFNATP_Test): target\test-output\910764289\test6-2013-04-15_23_47_35-1.txt (The system cannot find the file specified) Tests run: 424, Failures: 0, Errors: 1, Skipped: 6 [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.338s] [INFO] Logback Core Module ............................... FAILURE [28.279s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 29.140s [INFO] Finished at: Mon Apr 15 23:47:54 EDT 2013 [INFO] Final Memory: 28M/241M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2. 12.4:test (default-test) on project logback-core: There are test failures. On Mon, Apr 15, 2013 at 2:47 PM, ceki <ceki@qos.ch<mailto:ceki@qos.ch><mailto:ceki@qos.ch<mailto:ceki@qos.ch>> <mailto:ceki@qos.ch<mailto:ceki@qos.ch><mailto:ceki@qos.ch<mailto:ceki@qos.ch>>>> wrote: Hi Broc, No, it is not just you. Some of the logback test cases are time sensitive and fail from time to time, especially if the host machine is "slow". Making the build more robust is on our todo list. We now have jenkins running on such a slow machine. For example, on http://logback.qos.ch/jenkins/ build 24 was successful whereas build 25 failed: http://logback.qos.ch/jenkins/__job/logback/24/ <http://logback.qos.ch/jenkins/job/logback/24/> http://logback.qos.ch/jenkins/__job/logback/25/ <http://logback.qos.ch/jenkins/job/logback/25/> So, at least it's easier to reproduce the fails. Anyway, have you tried relaunching the build with "mvn clean; mvn install" ? BTW, since about 10 days, the logback build no longer depends on Scala for testing. It should be now much easier to set up logback within your favorite IDE. On 15.04.2013 20:11, Broc Seib wrote: Do we have stable builds yet? I cloned a fresh copy of logback yesterday, and "mvn install" still failed for me. Is it just me? Broc On Thu, Apr 4, 2013 at 9:20 AM, Ceki Gülcü <ceki@qos.ch<mailto:ceki@qos.ch><mailto:ceki@qos.ch<mailto:ceki@qos.ch>> <mailto:ceki@qos.ch<mailto:ceki@qos.ch><mailto:ceki@qos.ch<mailto:ceki@qos.ch>>> <mailto:ceki@qos.ch<mailto:ceki@qos.ch><mailto:ceki@qos.ch<mailto:ceki@qos.ch>> <mailto:ceki@qos.ch<mailto:ceki@qos.ch><mailto:ceki@qos.ch<mailto:ceki@qos.ch>>>>> wrote: As shown on [1], the logback build on the Jenkins server on cloudbees is unstable. The failures are probably due to timeout issue. I'll work on ch.qos.logback.core.____FileAppenderResilienceTest.____smoke ch.qos.logback.core.recovery.____ResilientOutputStreamTest.____verifyRecuperationAfterFailure ch.qos.logback.core.spi.____AppenderAttachableImplLockTest____.detachAppenderBoom Carl, could you please have a look at ch.qos.logback.core.net<http://ch.qos.logback.core.net/><http://ch.qos.logback.core.net/> <http://ch.qos.logback.core.net<http://ch.qos.logback.core.net/><http://ch.qos.logback.core.net/>> <http://ch.qos.logback.core<http://ch.qos.logback.core/>.<http://ch.qos.logback.core./>__net <http://ch.qos.logback.core.net<http://ch.qos.logback.core.net/><http://ch.qos.logback.core.net/>>>.____SocketConnectorBaseTest.____testConnect ? You should be able to logon at https://grandcentral<https://grandcentral/>.<https://grandcentral./>__cloudbe__es.com/<http://cloudbe__es.com/><http://cloudbe__es.com/> <http://cloudbees.com/> <https://grandcentral<https://grandcentral/>.<https://grandcentral./>__cloudbees.com/<http://cloudbees.com/><http://cloudbees.com/> <https://grandcentral.cloudbees.com/>> click on JenkinsCI (top right) and launch builds interactively. (You need to click on the login link in Jenkins located on the top right corner). Cheers, -- Ceki [1] https://logback.ci.cloudbees<https://logback.ci.cloudbees/>.<https://logback.ci.cloudbees./>____com/job/logback/12/__testReport/ <https://logback.ci.cloudbees<https://logback.ci.cloudbees/>.<https://logback.ci.cloudbees./>__com/job/logback/12/testReport/ <https://logback.ci.cloudbees.com/job/logback/12/testReport/>__> -- Ceki 65% of statistics are made up on the spot _________________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch<mailto:logback-dev@qos.ch>> <mailto:logback-dev@qos.ch<mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch<mailto:logback-dev@qos.ch>>> http://mailman.qos.ch/mailman/__listinfo/logback-dev <http://mailman.qos.ch/mailman/listinfo/logback-dev> _______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch<mailto:logback-dev@qos.ch>> http://mailman.qos.ch/mailman/listinfo/logback-dev -- Ceki 65% of statistics are made up on the spot _______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch<mailto:logback-dev@qos.ch>> http://mailman.qos.ch/mailman/listinfo/logback-dev _______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch<mailto:logback-dev@qos.ch>> http://mailman.qos.ch/mailman/listinfo/logback-dev _______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch> http://mailman.qos.ch/mailman/listinfo/logback-dev _______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch> http://mailman.qos.ch/mailman/listinfo/logback-dev

Hi Carl and Ceki, I grabbed 3727d7ff0fd33a9584a9ba0ef0369506fa8f562e right after Carl committed it. $ git log -1 commit 3727d7ff0fd33a9584a9ba0ef0369506fa8f562e Author: Carl Harris <ceharris@vt.edu> Date: Tue Apr 16 14:43:35 2013 -0400 removed an unused import to silence a build warning in eclipse I believe Carl's patch addressed the previous problem I was seeing. But I still have some mixed results getting a clean build. When I first ran 'mvn clean; mvn install' I got an error in Logback Core Module, in ch.qos.logback.core.appender.FileAppenderTest. But when I did 'mvn clean; mvn install' again, FileAppenderTest worked fine. (Failed surefire-report included below.) After Logback Core built, Logback Access Module build fails. See the test set for ch.qos.logback.access.db.DBAppenderHSQLTest below. ------------------------------------------------------------------------------- Test set: ch.qos.logback.core.appender.FileAppenderTest ------------------------------------------------------------------------------- Tests run: 6, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.079 sec <<< FAILURE! testCreateParentFolders(ch.qos.logback.core.appender.FileAppenderTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError: failed to delete C:\gitrepo\logback\logback-core\target\test-output\fat992837029 at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at ch.qos.logback.core.appender.FileAppenderTest.testCreateParentFolders(FileAppenderTest.java:93) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722) smoke(ch.qos.logback.core.appender.FileAppenderTest) Time elapsed: 0.049 sec testPrudentModeLogicalImplications(ch.qos.logback.core.appender.FileAppenderTest) Time elapsed: 0.02 sec testNewAppender(ch.qos.logback.core.appender.FileAppenderTest) Time elapsed: 0 sec testConfiguredAppender(ch.qos.logback.core.appender.FileAppenderTest) Time elapsed: 0.002 sec testNoStart(ch.qos.logback.core.appender.FileAppenderTest) Time elapsed: 0.001 sec ------------------------------------------------------------------------------- Test set: ch.qos.logback.access.db.DBAppenderHSQLTest ------------------------------------------------------------------------------- Tests run: 4, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.207 sec <<< FAILURE! testAppendAccessEvent(ch.qos.logback.access.db.DBAppenderHSQLTest) Time elapsed: 0.026 sec testAppendHeaders(ch.qos.logback.access.db.DBAppenderHSQLTest) Time elapsed: 0.006 sec testAppendMultipleEvents(ch.qos.logback.access.db.DBAppenderHSQLTest) Time elapsed: 0.022 sec testCheckNoHeadersAreInserted(ch.qos.logback.access.db.DBAppenderHSQLTest) Time elapsed: 0.009 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertFalse(Assert.java:68) at org.junit.Assert.assertFalse(Assert.java:79) at ch.qos.logback.access.db.DBAppenderHSQLTest.testCheckNoHeadersAreInserted(DBAppenderHSQLTest.java:148) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) 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.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189) at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165) at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75) On Tue, Apr 16, 2013 at 2:48 PM, Harris, Carl <ceharris@vt.edu> wrote:
Last couple of commits should help reduce timing sensitivity in the SocketConnectorBaseTest and SocketAppenderTest. Anxious to hear if this improves matters for you Broc.
carl
On Apr 16, 2013, at 2:00 PM, Broc Seib wrote:
Here it is:
------------------------------------------------------------------------------- Test set: ch.qos.logback.core.net.SocketConnectorBaseTest
------------------------------------------------------------------------------- Tests run: 3, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 5.596 sec <<< FAILURE! testConnect(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 0.081 sec testConnectEventually(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 2.514 sec testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertFalse(Assert.java:68) at org.junit.Assert.assertFalse(Assert.java:79) at ch.qos.logback.core.net.SocketConnectorBaseTest.testConnectionFails(SocketConnectorBaseTest.java:89) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) 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.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722)
On Tue, Apr 16, 2013 at 1:56 PM, Harris, Carl <ceharris@vt.edu<mailto: ceharris@vt.edu>> wrote: Broc,
Would you please send the surefire-report for the test ch.qos.logback.core.net.SocketConnectorBaseTest, so I can take a look to see where/why it is failing?
logback-core/target/surefire-reports/ch.qos.logback.core.net.SocketConnectorBaseTest.txt
Thanks,
carl
On Apr 16, 2013, at 1:02 PM, Broc Seib wrote:
Hi Ceki,
I just updated to the latest code you just committed, 56e8ab2192697bc27a8cfbd8441db62aff53e87c but I think there is a different problem now.
bseib@BSEIB-WIN7 /c/gitrepo $ rm -rf logback
bseib@BSEIB-WIN7 /c/gitrepo $ git clone https://github.com/qos-ch/logback.git Cloning into 'logback'... remote: Counting objects: 52855, done. remote: Compressing objects: 100% (13701/13701), done. remote: Total 52855 (delta 26607), reused 52138 (delta 26038) Receiving objects: 100% (52855/52855), 20.13 MiB | 1.17 MiB/s, done. Resolving deltas: 100% (26607/26607), done.
bseib@BSEIB-WIN7 /c/gitrepo $ cd logback/
bseib@BSEIB-WIN7 /c/gitrepo/logback (master) $ git log -1 commit 56e8ab2192697bc27a8cfbd8441db62aff53e87c Merge: 2ff61c7 513e380 Author: Ceki Gulcu <ceki@qos.ch<mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>> Date: Tue Apr 16 18:09:23 2013 +0200
Merge branch 'master' of github.com:qos-ch/logback
And then 'mvn clean; mvn install':
[...] Running ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.635 sec includeNestedWithinIf(ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest) Time elapsed: 0.635 sec
Results :
Failed tests: testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest)
lossyAppenderShouldOnlyLooseCertainEvents(ch.qos.logback.core.AsyncAppenderBaseTest): expected:<8> but was:<10>
Tests run: 424, Failures: 2, Errors: 0, Skipped: 6
[INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.244s] [INFO] Logback Core Module ............................... FAILURE [27.251s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 28.014s [INFO] Finished at: Tue Apr 16 12:51:50 EDT 2013 [INFO] Final Memory: 28M/308M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4:test (default-test) on project logback-core: There are test failures.
On Tue, Apr 16, 2013 at 12:42 PM, ceki <ceki@qos.ch<mailto:ceki@qos.ch
<mailto:ceki@qos.ch<mailto:ceki@qos.ch>>> wrote:
I am hoping that commit [1] should make the test failures in logback-core/src/test/java/ch/qos/logback/core/rolling disappear.
[1] https://github.com/qos-ch/logback/commit/2ff61c7c1cb0562
On 16.04.2013 06:05, Broc Seib wrote: Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"?
Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE!
withZipCompression_FileSet_NoRestart_7(ch.qos.logback.core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec
withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(Native Method) at java.io.FileInputStream.<init>(FileInputStream.java:138) at ch.qos.logback.core.testUtil.FileToBufferUtil.regularReadIntoList(Fil eToBufferUtil.java:60) at ch.qos.logback.core.testUtil.FileToBufferUtil.readIntoList(FileToBuff erUtil.java:38) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.fileContentChe ck(ScaffoldingForRollingTests.java:84) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.sortedContentC heck(ScaffoldingForRollingTests.java:99) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.generic(SizeAn dTimeBasedFNATP_Test.java:130) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.withGZCompress ion_FileBlank_NoRestart_6(SizeAndTimeBasedFNATP_Test.java:189) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl. java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(Framework Method.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCal lable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMe thod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMet hod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores. java:28) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:47 1) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor. java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.java:722)
withGZCompression_FileSet_NoRestart_5(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec
noCompression_FileSet_WithStopStart_4(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec
noCompression_FileBlank_WithStopStart_3(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec
noCompression_FileBlank_NoRestart_2(ch.qos.logback.core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec
noCompression_FileSet_NoRestart_1(ch.qos.logback.core.rolling.SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec
verifyRecuperationAfterFailure(ch.qos.logback.core.recovery.ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.012 sec
Results :
Tests in error:
withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTim eBasedFNATP_Test): target\test-output\910764289\test6-2013-04-15_23_47_35-1.txt (The system cannot find the file specified)
Tests run: 424, Failures: 0, Errors: 1, Skipped: 6
[INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.338s] [INFO] Logback Core Module ............................... FAILURE [28.279s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 29.140s [INFO] Finished at: Mon Apr 15 23:47:54 EDT 2013 [INFO] Final Memory: 28M/241M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2. 12.4:test (default-test) on project logback-core: There are test failures.
On Mon, Apr 15, 2013 at 2:47 PM, ceki <ceki@qos.ch<mailto:ceki@qos.ch
<mailto:ceki@qos.ch<mailto:ceki@qos.ch>> <mailto:ceki@qos.ch<mailto: ceki@qos.ch><mailto:ceki@qos.ch<mailto:ceki@qos.ch>>>>
wrote:
Hi Broc,
No, it is not just you. Some of the logback test cases are time sensitive and fail from time to time, especially if the host machine is "slow". Making the build more robust is on our todo list.
We now have jenkins running on such a slow machine. For example, on http://logback.qos.ch/jenkins/ build 24 was successful whereas build 25 failed:
http://logback.qos.ch/jenkins/__job/logback/24/ <http://logback.qos.ch/jenkins/job/logback/24/> http://logback.qos.ch/jenkins/__job/logback/25/ <http://logback.qos.ch/jenkins/job/logback/25/>
So, at least it's easier to reproduce the fails.
Anyway, have you tried relaunching the build with "mvn clean; mvn install" ?
BTW, since about 10 days, the logback build no longer depends on Scala for testing. It should be now much easier to set up logback within your favorite IDE.
On 15.04.2013 20:11, Broc Seib wrote:
Do we have stable builds yet?
I cloned a fresh copy of logback yesterday, and "mvn install" still failed for me. Is it just me?
Broc
On Thu, Apr 4, 2013 at 9:20 AM, Ceki Gülcü <ceki@qos.ch<mailto: ceki@qos.ch><mailto:ceki@qos.ch<mailto:ceki@qos.ch>> <mailto:ceki@qos.ch<mailto:ceki@qos.ch><mailto:ceki@qos.ch<mailto: ceki@qos.ch>>> <mailto:ceki@qos.ch<mailto:ceki@qos.ch><mailto:ceki@qos.ch<mailto: ceki@qos.ch>> <mailto:ceki@qos.ch<mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>>>> wrote:
As shown on [1], the logback build on the Jenkins server on cloudbees is unstable.
The failures are probably due to timeout issue. I'll work on
ch.qos.logback.core.____FileAppenderResilienceTest.____smoke
ch.qos.logback.core.recovery.____ResilientOutputStreamTest.____verifyRecuperationAfterFailure
ch.qos.logback.core.spi.____AppenderAttachableImplLockTest____.detachAppenderBoom
Carl, could you please have a look at
ch.qos.logback.core.net<http://ch.qos.logback.core.net/>< http://ch.qos.logback.core.net/> <http://ch.qos.logback.core.net< http://ch.qos.logback.core.net/><http://ch.qos.logback.core.net/>> <http://ch.qos.logback.core<http://ch.qos.logback.core/>.< http://ch.qos.logback.core./>__net <http://ch.qos.logback.core.net<http://ch.qos.logback.core.net/>< http://ch.qos.logback.core.net/
.____SocketConnectorBaseTest.____testConnect
?
You should be able to logon at
https://grandcentral<https://grandcentral/>.< https://grandcentral./>__cloudbe__es.com/<http://cloudbe__es.com/>< http://cloudbe__es.com/> <http://cloudbees.com/>
<https://grandcentral<https://grandcentral/>.< https://grandcentral./>__cloudbees.com/<http://cloudbees.com/>< http://cloudbees.com/> <https://grandcentral.cloudbees.com/>>
click on JenkinsCI (top right) and launch builds interactively. (You need to click on the login link in Jenkins located on the top right corner).
Cheers,
-- Ceki
[1] https://logback.ci.cloudbees<https://logback.ci.cloudbees/>.< https://logback.ci.cloudbees./>____com/job/logback/12/__testReport/
<https://logback.ci.cloudbees<https://logback.ci.cloudbees/>.< https://logback.ci.cloudbees./>__com/job/logback/12/testReport/ <https://logback.ci.cloudbees.com/job/logback/12/testReport/>__>
-- Ceki 65% of statistics are made up on the spot
_________________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch><mailto: logback-dev@qos.ch<mailto:logback-dev@qos.ch>> <mailto:logback-dev@qos.ch <mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch<mailto: logback-dev@qos.ch>>> http://mailman.qos.ch/mailman/__listinfo/logback-dev <http://mailman.qos.ch/mailman/listinfo/logback-dev>
_______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch <mailto:logback-dev@qos.ch>> http://mailman.qos.ch/mailman/listinfo/logback-dev
-- Ceki 65% of statistics are made up on the spot _______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch <mailto:logback-dev@qos.ch>> http://mailman.qos.ch/mailman/listinfo/logback-dev
_______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch><mailto:logback-dev@qos.ch <mailto:logback-dev@qos.ch>> http://mailman.qos.ch/mailman/listinfo/logback-dev
_______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch> http://mailman.qos.ch/mailman/listinfo/logback-dev
_______________________________________________ logback-dev mailing list logback-dev@qos.ch<mailto:logback-dev@qos.ch> http://mailman.qos.ch/mailman/listinfo/logback-dev
_______________________________________________ logback-dev mailing list logback-dev@qos.ch http://mailman.qos.ch/mailman/listinfo/logback-dev

Which JDK are you running? Which exact CPU? On 16.04.2013 23:07, Broc Seib wrote:
Hi Carl and Ceki,
I grabbed 3727d7ff0fd33a9584a9ba0ef0369506fa8f562e right after Carl committed it.
$ git log -1 commit 3727d7ff0fd33a9584a9ba0ef0369506fa8f562e Author: Carl Harris <ceharris@vt.edu <mailto:ceharris@vt.edu>> Date: Tue Apr 16 14:43:35 2013 -0400
removed an unused import to silence a build warning in eclipse
I believe Carl's patch addressed the previous problem I was seeing. But I still have some mixed results getting a clean build. When I first ran 'mvn clean; mvn install' I got an error in Logback Core Module, in ch.qos.logback.core.appender.FileAppenderTest. But when I did 'mvn clean; mvn install' again, FileAppenderTest worked fine. (Failed surefire-report included below.)
After Logback Core built, Logback Access Module build fails. See the test set for ch.qos.logback.access.db.DBAppenderHSQLTest below.
------------------------------------------------------------------------------- Test set: ch.qos.logback.core.appender.FileAppenderTest ------------------------------------------------------------------------------- Tests run: 6, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.079 sec <<< FAILURE! testCreateParentFolders(ch.qos.logback.core.appender.FileAppenderTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError: failed to delete C:\gitrepo\logback\logback-core\target\test-output\fat992837029 at org.junit.Assert.fail(Assert.java:93) at org.junit.Assert.assertTrue(Assert.java:43) at ch.qos.logback.core.appender.FileAppenderTest.testCreateParentFolders(FileAppenderTest.java:93) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMethod.java:20) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722)
smoke(ch.qos.logback.core.appender.FileAppenderTest) Time elapsed: 0.049 sec testPrudentModeLogicalImplications(ch.qos.logback.core.appender.FileAppenderTest) Time elapsed: 0.02 sec testNewAppender(ch.qos.logback.core.appender.FileAppenderTest) Time elapsed: 0 sec testConfiguredAppender(ch.qos.logback.core.appender.FileAppenderTest) Time elapsed: 0.002 sec testNoStart(ch.qos.logback.core.appender.FileAppenderTest) Time elapsed: 0.001 sec
------------------------------------------------------------------------------- Test set: ch.qos.logback.access.db.DBAppenderHSQLTest ------------------------------------------------------------------------------- Tests run: 4, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.207 sec <<< FAILURE! testAppendAccessEvent(ch.qos.logback.access.db.DBAppenderHSQLTest) Time elapsed: 0.026 sec testAppendHeaders(ch.qos.logback.access.db.DBAppenderHSQLTest) Time elapsed: 0.006 sec testAppendMultipleEvents(ch.qos.logback.access.db.DBAppenderHSQLTest) Time elapsed: 0.022 sec testCheckNoHeadersAreInserted(ch.qos.logback.access.db.DBAppenderHSQLTest) Time elapsed: 0.009 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertFalse(Assert.java:68) at org.junit.Assert.assertFalse(Assert.java:79) at ch.qos.logback.access.db.DBAppenderHSQLTest.testCheckNoHeadersAreInserted(DBAppenderHSQLTest.java:148) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) 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.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores.java:28) at org.junit.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:252) at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:141) at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:112) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.apache.maven.surefire.util.ReflectionUtils.invokeMethodWithArray(ReflectionUtils.java:189) at org.apache.maven.surefire.booter.ProviderFactory$ProviderProxy.invoke(ProviderFactory.java:165) at org.apache.maven.surefire.booter.ProviderFactory.invokeProvider(ProviderFactory.java:85) at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:115) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:75)
On Tue, Apr 16, 2013 at 2:48 PM, Harris, Carl <ceharris@vt.edu <mailto:ceharris@vt.edu>> wrote:
Last couple of commits should help reduce timing sensitivity in the SocketConnectorBaseTest and SocketAppenderTest. Anxious to hear if this improves matters for you Broc.
carl
On Apr 16, 2013, at 2:00 PM, Broc Seib wrote:
Here it is:
------------------------------------------------------------------------------- Test set: ch.qos.logback.core.net.SocketConnectorBaseTest ------------------------------------------------------------------------------- Tests run: 3, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 5.596 sec <<< FAILURE! testConnect(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 0.081 sec testConnectEventually(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 2.514 sec testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.java:92) at org.junit.Assert.assertTrue(Assert.java:43) at org.junit.Assert.assertFalse(Assert.java:68) at org.junit.Assert.assertFalse(Assert.java:79) at ch.qos.logback.core.net.SocketConnectorBaseTest.testConnectionFails(SocketConnectorBaseTest.java:89) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(FrameworkMethod.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCallable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMethod.java:42) 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.internal.runners.statements.RunAfters.evaluate(RunAfters.java:30) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRunner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.java:722)
On Tue, Apr 16, 2013 at 1:56 PM, Harris, Carl <ceharris@vt.edu <mailto:ceharris@vt.edu><mailto:ceharris@vt.edu <mailto:ceharris@vt.edu>>> wrote: Broc,
Would you please send the surefire-report for the test ch.qos.logback.core.net.SocketConnectorBaseTest, so I can take a look to see where/why it is failing?
logback-core/target/surefire-reports/ch.qos.logback.core.net.SocketConnectorBaseTest.txt
Thanks,
carl
On Apr 16, 2013, at 1:02 PM, Broc Seib wrote:
Hi Ceki,
I just updated to the latest code you just committed, 56e8ab2192697bc27a8cfbd8441db62aff53e87c but I think there is a different problem now.
bseib@BSEIB-WIN7 /c/gitrepo $ rm -rf logback
bseib@BSEIB-WIN7 /c/gitrepo $ git clone https://github.com/qos-ch/logback.git Cloning into 'logback'... remote: Counting objects: 52855, done. remote: Compressing objects: 100% (13701/13701), done. remote: Total 52855 (delta 26607), reused 52138 (delta 26038) Receiving objects: 100% (52855/52855), 20.13 MiB | 1.17 MiB/s, done. Resolving deltas: 100% (26607/26607), done.
bseib@BSEIB-WIN7 /c/gitrepo $ cd logback/
bseib@BSEIB-WIN7 /c/gitrepo/logback (master) $ git log -1 commit 56e8ab2192697bc27a8cfbd8441db62aff53e87c Merge: 2ff61c7 513e380 Author: Ceki Gulcu <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>><mailto:ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>>> Date: Tue Apr 16 18:09:23 2013 +0200
Merge branch 'master' of github.com:qos-ch/logback
And then 'mvn clean; mvn install':
[...] Running ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.635 sec includeNestedWithinIf(ch.qos.logback.core.joran.conditional.IfThenElseAndIncludeCompositionTest) Time elapsed: 0.635 sec
Results :
Failed tests: testConnectionFails(ch.qos.logback.core.net.SocketConnectorBaseTest)
lossyAppenderShouldOnlyLooseCertainEvents(ch.qos.logback.core.AsyncAppenderBaseTest): expected:<8> but was:<10>
Tests run: 424, Failures: 2, Errors: 0, Skipped: 6
[INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.244s] [INFO] Logback Core Module ............................... FAILURE [27.251s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 28.014s [INFO] Finished at: Tue Apr 16 12:51:50 EDT 2013 [INFO] Final Memory: 28M/308M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.12.4:test (default-test) on project logback-core: There are test failures.
On Tue, Apr 16, 2013 at 12:42 PM, ceki <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>><mailto:ceki@qos.ch <mailto:ceki@qos.ch><mailto:ceki@qos.ch <mailto:ceki@qos.ch>>>> wrote:
I am hoping that commit [1] should make the test failures in logback-core/src/test/java/ch/qos/logback/core/rolling disappear.
[1] https://github.com/qos-ch/logback/commit/2ff61c7c1cb0562
On 16.04.2013 06:05, Broc Seib wrote: Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"?
Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE! withZipCompression_FileSet_NoRestart_7(ch.qos.logback.core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(Native Method) at java.io.FileInputStream.<init>(FileInputStream.java:138) at ch.qos.logback.core.testUtil.FileToBufferUtil.regularReadIntoList(Fil eToBufferUtil.java:60) at ch.qos.logback.core.testUtil.FileToBufferUtil.readIntoList(FileToBuff erUtil.java:38) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.fileContentChe ck(ScaffoldingForRollingTests.java:84) at ch.qos.logback.core.rolling.ScaffoldingForRollingTests.sortedContentC heck(ScaffoldingForRollingTests.java:99) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.generic(SizeAn dTimeBasedFNATP_Test.java:130) at ch.qos.logback.core.rolling.SizeAndTimeBasedFNATP_Test.withGZCompress ion_FileBlank_NoRestart_6(SizeAndTimeBasedFNATP_Test.java:189) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl. java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:601) at org.junit.runners.model.FrameworkMethod$1.runReflectiveCall(Framework Method.java:45) at org.junit.internal.runners.model.ReflectiveCallable.run(ReflectiveCal lable.java:15) at org.junit.runners.model.FrameworkMethod.invokeExplosively(FrameworkMe thod.java:42) at org.junit.internal.runners.statements.InvokeMethod.evaluate(InvokeMet hod.java:20) at org.junit.internal.runners.statements.RunBefores.evaluate(RunBefores. java:28) at org.junit.runners.ParentRunner.runLeaf(ParentRunner.java:263) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:68) at org.junit.runners.BlockJUnit4ClassRunner.runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.ParentRunner$3.run(ParentRunner.java:231) at org.junit.runners.ParentRunner$1.schedule(ParentRunner.java:60) at org.junit.runners.ParentRunner.runChildren(ParentRunner.java:229) at org.junit.runners.ParentRunner.access$000(ParentRunner.java:50) at org.junit.runners.ParentRunner$2.evaluate(ParentRunner.java:222) at org.junit.runners.ParentRunner.run(ParentRunner.java:300) 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:231) at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:47 1) at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) at java.util.concurrent.FutureTask.run(FutureTask.java:166) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor. java:1110) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.java:722)
withGZCompression_FileSet_NoRestart_5(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec noCompression_FileSet_WithStopStart_4(ch.qos.logback.core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec noCompression_FileBlank_WithStopStart_3(ch.qos.logback.core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec noCompression_FileBlank_NoRestart_2(ch.qos.logback.core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec noCompression_FileSet_NoRestart_1(ch.qos.logback.core.rolling.SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec verifyRecuperationAfterFailure(ch.qos.logback.core.recovery.ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.rolling.helper.CompressTest) Time elapsed: 0.012 sec
Results :
Tests in error:
withGZCompression_FileBlank_NoRestart_6(ch.qos.logback.core.rolling.SizeAndTim eBasedFNATP_Test): target\test-output\910764289\test6-2013-04-15_23_47_35-1.txt (The system cannot find the file specified)
Tests run: 424, Failures: 0, Errors: 1, Skipped: 6
[INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent .................................... SUCCESS [0.338s] [INFO] Logback Core Module ............................... FAILURE [28.279s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ...................................... SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 29.140s [INFO] Finished at: Mon Apr 15 23:47:54 EDT 2013 [INFO] Final Memory: 28M/241M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2. 12.4:test (default-test) on project logback-core: There are test failures.
-- Ceki 65% of statistics are made up on the spot

$ "$JAVA_HOME"/bin/java.exe -version java version "1.7.0" Java(TM) SE Runtime Environment (build 1.7.0-b147) Java HotSpot(TM) 64-Bit Server VM (build 21.0-b17, mixed mode) Processor: Intel(R) Core(TM) i7-3770 CPU @ 3.40GHz 3.40 GHz Installed memory (RAM): 16.0 GB (15.8 GB usable) System type: 64-bit Operating System. On Tue, Apr 16, 2013 at 5:34 PM, ceki <ceki@qos.ch> wrote:
Which JDK are you running? Which exact CPU?
On 16.04.2013 23:07, Broc Seib wrote:
Hi Carl and Ceki,
I grabbed 3727d7ff0fd33a9584a9ba0ef03695**06fa8f562e right after Carl committed it.
$ git log -1 commit 3727d7ff0fd33a9584a9ba0ef03695**06fa8f562e Author: Carl Harris <ceharris@vt.edu <mailto:ceharris@vt.edu>>
Date: Tue Apr 16 14:43:35 2013 -0400
removed an unused import to silence a build warning in eclipse
I believe Carl's patch addressed the previous problem I was seeing. But I still have some mixed results getting a clean build. When I first ran 'mvn clean; mvn install' I got an error in Logback Core Module, in ch.qos.logback.core.appender.**FileAppenderTest. But when I did 'mvn clean; mvn install' again, FileAppenderTest worked fine. (Failed surefire-report included below.)
After Logback Core built, Logback Access Module build fails. See the test set for ch.qos.logback.access.db.**DBAppenderHSQLTest below.
------------------------------**------------------------------** ------------------- Test set: ch.qos.logback.core.appender.**FileAppenderTest ------------------------------**------------------------------** ------------------- Tests run: 6, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.079 sec <<< FAILURE! testCreateParentFolders(ch.**qos.logback.core.appender.** FileAppenderTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError: failed to delete C:\gitrepo\logback\logback-**core\target\test-output\**fat992837029 at org.junit.Assert.fail(Assert.**java:93) at org.junit.Assert.assertTrue(**Assert.java:43) at ch.qos.logback.core.appender.**FileAppenderTest.** testCreateParentFolders(**FileAppenderTest.java:93) at sun.reflect.**NativeMethodAccessorImpl.**invoke0(Native Method) at sun.reflect.**NativeMethodAccessorImpl.**invoke(** NativeMethodAccessorImpl.java:**57) at sun.reflect.**DelegatingMethodAccessorImpl.**invoke(** DelegatingMethodAccessorImpl.**java:43) at java.lang.reflect.Method.**invoke(Method.java:601) at org.junit.runners.model.**FrameworkMethod$1.**runReflectiveCall(** FrameworkMethod.java:45) at org.junit.internal.runners.**model.ReflectiveCallable.run(** ReflectiveCallable.java:15) at org.junit.runners.model.**FrameworkMethod.**invokeExplosively(** FrameworkMethod.java:42) at org.junit.internal.runners.**statements.InvokeMethod.** evaluate(InvokeMethod.java:20) at org.junit.runners.**ParentRunner.runLeaf(**ParentRunner.java:263) at org.junit.runners.**BlockJUnit4ClassRunner.**runChild(** BlockJUnit4ClassRunner.java:**68) at org.junit.runners.**BlockJUnit4ClassRunner.**runChild(** BlockJUnit4ClassRunner.java:**47) at org.junit.runners.**ParentRunner$3.run(**ParentRunner.java:231) at org.junit.runners.**ParentRunner$1.schedule(**ParentRunner.java:60) at org.junit.runners.**ParentRunner.runChildren(**ParentRunner.java:229) at org.junit.runners.**ParentRunner.access$000(**ParentRunner.java:50) at org.junit.runners.**ParentRunner$2.evaluate(**ParentRunner.java:222) at org.junit.runners.**ParentRunner.run(ParentRunner.**java:300) 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:231) at java.util.concurrent.**Executors$RunnableAdapter.** call(Executors.java:471) at java.util.concurrent.**FutureTask$Sync.innerRun(**FutureTask.java:334) at java.util.concurrent.**FutureTask.run(FutureTask.**java:166) at java.util.concurrent.**ThreadPoolExecutor.runWorker(** ThreadPoolExecutor.java:1110) at java.util.concurrent.**ThreadPoolExecutor$Worker.run(** ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.**java:722)
smoke(ch.qos.logback.core.**appender.FileAppenderTest) Time elapsed: 0.049 sec testPrudentModeLogicalImplicat**ions(ch.qos.logback.core.** appender.FileAppenderTest) Time elapsed: 0.02 sec testNewAppender(ch.qos.**logback.core.appender.**FileAppenderTest) Time elapsed: 0 sec testConfiguredAppender(ch.qos.**logback.core.appender.**FileAppenderTest) Time elapsed: 0.002 sec testNoStart(ch.qos.logback.**core.appender.**FileAppenderTest) Time elapsed: 0.001 sec
------------------------------**------------------------------** ------------------- Test set: ch.qos.logback.access.db.**DBAppenderHSQLTest ------------------------------**------------------------------** ------------------- Tests run: 4, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.207 sec <<< FAILURE! testAppendAccessEvent(ch.qos.**logback.access.db.**DBAppenderHSQLTest) Time elapsed: 0.026 sec testAppendHeaders(ch.qos.**logback.access.db.**DBAppenderHSQLTest) Time elapsed: 0.006 sec testAppendMultipleEvents(ch.**qos.logback.access.db.**DBAppenderHSQLTest) Time elapsed: 0.022 sec testCheckNoHeadersAreInserted(**ch.qos.logback.access.db.** DBAppenderHSQLTest) Time elapsed: 0.009 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.**java:92) at org.junit.Assert.assertTrue(**Assert.java:43) at org.junit.Assert.assertFalse(**Assert.java:68) at org.junit.Assert.assertFalse(**Assert.java:79) at ch.qos.logback.access.db.**DBAppenderHSQLTest.** testCheckNoHeadersAreInserted(**DBAppenderHSQLTest.java:148) at sun.reflect.**NativeMethodAccessorImpl.**invoke0(Native Method) at sun.reflect.**NativeMethodAccessorImpl.**invoke(** NativeMethodAccessorImpl.java:**57) at sun.reflect.**DelegatingMethodAccessorImpl.**invoke(** DelegatingMethodAccessorImpl.**java:43) at java.lang.reflect.Method.**invoke(Method.java:601) at org.junit.runners.model.**FrameworkMethod$1.**runReflectiveCall(** FrameworkMethod.java:45) at org.junit.internal.runners.**model.ReflectiveCallable.run(** ReflectiveCallable.java:15) at org.junit.runners.model.**FrameworkMethod.**invokeExplosively(** FrameworkMethod.java:42) 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.internal.runners.**statements.RunAfters.evaluate(** RunAfters.java:30) at org.junit.runners.**ParentRunner.runLeaf(**ParentRunner.java:263) at org.junit.runners.**BlockJUnit4ClassRunner.**runChild(** BlockJUnit4ClassRunner.java:**68) at org.junit.runners.**BlockJUnit4ClassRunner.**runChild(** BlockJUnit4ClassRunner.java:**47) at org.junit.runners.**ParentRunner$3.run(**ParentRunner.java:231) at org.junit.runners.**ParentRunner$1.schedule(**ParentRunner.java:60) at org.junit.runners.**ParentRunner.runChildren(**ParentRunner.java:229) at org.junit.runners.**ParentRunner.access$000(**ParentRunner.java:50) at org.junit.runners.**ParentRunner$2.evaluate(**ParentRunner.java:222) at org.junit.internal.runners.**statements.RunBefores.** evaluate(RunBefores.java:28) at org.junit.internal.runners.**statements.RunAfters.evaluate(** RunAfters.java:30) at org.junit.runners.**ParentRunner.run(ParentRunner.**java:300) at org.apache.maven.surefire.**junit4.JUnit4Provider.execute(** JUnit4Provider.java:252) at org.apache.maven.surefire.**junit4.JUnit4Provider.** executeTestSet(JUnit4Provider.**java:141) at org.apache.maven.surefire.**junit4.JUnit4Provider.invoke(** JUnit4Provider.java:112) at sun.reflect.**NativeMethodAccessorImpl.**invoke0(Native Method) at sun.reflect.**NativeMethodAccessorImpl.**invoke(** NativeMethodAccessorImpl.java:**57) at sun.reflect.**DelegatingMethodAccessorImpl.**invoke(** DelegatingMethodAccessorImpl.**java:43) at java.lang.reflect.Method.**invoke(Method.java:601) at org.apache.maven.surefire.**util.ReflectionUtils.**invokeMethodWithArray( **ReflectionUtils.java:189) at org.apache.maven.surefire.**booter.ProviderFactory$** ProviderProxy.invoke(**ProviderFactory.java:165) at org.apache.maven.surefire.**booter.ProviderFactory.**invokeProvider(** ProviderFactory.java:85) at org.apache.maven.surefire.**booter.ForkedBooter.**runSuitesInProcess(** ForkedBooter.java:115) at org.apache.maven.surefire.**booter.ForkedBooter.main(** ForkedBooter.java:75)
On Tue, Apr 16, 2013 at 2:48 PM, Harris, Carl <ceharris@vt.edu <mailto:ceharris@vt.edu>> wrote:
Last couple of commits should help reduce timing sensitivity in the SocketConnectorBaseTest and SocketAppenderTest. Anxious to hear if this improves matters for you Broc.
carl
On Apr 16, 2013, at 2:00 PM, Broc Seib wrote:
Here it is:
------------------------------**------------------------------** ------------------- Test set: ch.qos.logback.core.net.**SocketConnectorBaseTest ------------------------------**------------------------------** ------------------- Tests run: 3, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 5.596 sec <<< FAILURE! testConnect(ch.qos.logback.**core.net.**SocketConnectorBaseTest) Time elapsed: 0.081 sec testConnectEventually(ch.qos.**logback.core.net.** SocketConnectorBaseTest) Time elapsed: 2.514 sec testConnectionFails(ch.qos.**logback.core.net.** SocketConnectorBaseTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.**java:92) at org.junit.Assert.assertTrue(**Assert.java:43) at org.junit.Assert.assertFalse(**Assert.java:68) at org.junit.Assert.assertFalse(**Assert.java:79) at ch.qos.logback.core.net.**SocketConnectorBaseTest.** testConnectionFails(**SocketConnectorBaseTest.java:**89) at sun.reflect.**NativeMethodAccessorImpl.**invoke0(Native Method) at sun.reflect.**NativeMethodAccessorImpl.**invoke(** NativeMethodAccessorImpl.java:**57) at sun.reflect.**DelegatingMethodAccessorImpl.**invoke(** DelegatingMethodAccessorImpl.**java:43) at java.lang.reflect.Method.**invoke(Method.java:601) at org.junit.runners.model.**FrameworkMethod$1.**runReflectiveCall(** FrameworkMethod.java:45) at org.junit.internal.runners.**model.ReflectiveCallable.run(** ReflectiveCallable.java:15) at org.junit.runners.model.**FrameworkMethod.**invokeExplosively(** FrameworkMethod.java:42) 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.internal.runners.**statements.RunAfters.evaluate(** RunAfters.java:30) at org.junit.runners.**ParentRunner.runLeaf(**ParentRunner.java:263) at org.junit.runners.**BlockJUnit4ClassRunner.**runChild(** BlockJUnit4ClassRunner.java:**68) at org.junit.runners.**BlockJUnit4ClassRunner.**runChild(** BlockJUnit4ClassRunner.java:**47) at org.junit.runners.**ParentRunner$3.run(**ParentRunner.java:231) at org.junit.runners.**ParentRunner$1.schedule(** ParentRunner.java:60) at org.junit.runners.**ParentRunner.runChildren(** ParentRunner.java:229) at org.junit.runners.**ParentRunner.access$000(** ParentRunner.java:50) at org.junit.runners.**ParentRunner$2.evaluate(** ParentRunner.java:222) at org.junit.runners.**ParentRunner.run(ParentRunner.**java:300) 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:231) at java.util.concurrent.**Executors$RunnableAdapter.** call(Executors.java:471) at java.util.concurrent.**FutureTask$Sync.innerRun(** FutureTask.java:334) at java.util.concurrent.**FutureTask.run(FutureTask.**java:166) at java.util.concurrent.**ThreadPoolExecutor.runWorker(** ThreadPoolExecutor.java:1110) at java.util.concurrent.**ThreadPoolExecutor$Worker.run(** ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.**java:722)
On Tue, Apr 16, 2013 at 1:56 PM, Harris, Carl <ceharris@vt.edu <mailto:ceharris@vt.edu><**mailto:ceharris@vt.edu
<mailto:ceharris@vt.edu>>> wrote: Broc,
Would you please send the surefire-report for the test ch.qos.logback.core.net.**SocketConnectorBaseTest, so I can take a look to see where/why it is failing?
logback-core/target/surefire-**reports/ch.qos.logback.core.** net.SocketConnectorBaseTest.**txt
Thanks,
carl
On Apr 16, 2013, at 1:02 PM, Broc Seib wrote:
Hi Ceki,
I just updated to the latest code you just committed, 56e8ab2192697bc27a8cfbd8441db6**2aff53e87c but I think there is a different problem now.
bseib@BSEIB-WIN7 /c/gitrepo $ rm -rf logback
bseib@BSEIB-WIN7 /c/gitrepo $ git clone https://github.com/qos-ch/**logback.git<https://github.com/qos-ch/logback.git> Cloning into 'logback'... remote: Counting objects: 52855, done. remote: Compressing objects: 100% (13701/13701), done. remote: Total 52855 (delta 26607), reused 52138 (delta 26038) Receiving objects: 100% (52855/52855), 20.13 MiB | 1.17 MiB/s, done. Resolving deltas: 100% (26607/26607), done.
bseib@BSEIB-WIN7 /c/gitrepo $ cd logback/
bseib@BSEIB-WIN7 /c/gitrepo/logback (master) $ git log -1 commit 56e8ab2192697bc27a8cfbd8441db6**2aff53e87c Merge: 2ff61c7 513e380 Author: Ceki Gulcu <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ce**ki@qos.ch <ceki@qos.ch> <mailto:ceki@qos.ch>><mailto:c**eki@qos.ch <ceki@qos.ch>
<mailto:ceki@qos.ch><mailto:ce**ki@qos.ch <ceki@qos.ch> <mailto: ceki@qos.ch>>>> Date: Tue Apr 16 18:09:23 2013 +0200
Merge branch 'master' of github.com:qos-ch/logback
And then 'mvn clean; mvn install':
[...] Running ch.qos.logback.core.joran.**conditional.** IfThenElseAndIncludeCompositio**nTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.635 sec includeNestedWithinIf(ch.qos.**logback.core.joran.**conditional.** IfThenElseAndIncludeCompositio**nTest) Time elapsed: 0.635 sec
Results :
Failed tests: testConnectionFails(ch.qos.**logback.core.net.** SocketConnectorBaseTest)
lossyAppenderShouldOnlyLooseCe**rtainEvents(ch.qos.logback.** core.AsyncAppenderBaseTest): expected:<8> but was:<10>
Tests run: 424, Failures: 2, Errors: 0, Skipped: 6
[INFO] ------------------------------**------------------------------** ------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent ..............................**...... SUCCESS [0.244s] [INFO] Logback Core Module ..............................**. FAILURE [27.251s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ..............................**........ SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------**------------------------------** ------------ [INFO] BUILD FAILURE [INFO] ------------------------------**------------------------------** ------------ [INFO] Total time: 28.014s [INFO] Finished at: Tue Apr 16 12:51:50 EDT 2013 [INFO] Final Memory: 28M/308M [INFO] ------------------------------**------------------------------** ------------ [ERROR] Failed to execute goal org.apache.maven.plugins:**maven-surefire-plugin:2.12.4:**test (default-test) on project logback-core: There are test failures.
On Tue, Apr 16, 2013 at 12:42 PM, ceki <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ce**ki@qos.ch <ceki@qos.ch> <mailto:ceki@qos.ch>><mailto:c**eki@qos.ch <ceki@qos.ch> <mailto:ceki@qos.ch><mailto:ce**ki@qos.ch <ceki@qos.ch> <mailto: ceki@qos.ch>>>> wrote:
I am hoping that commit [1] should make the test failures in logback-core/src/test/java/ch/**qos/logback/core/rolling disappear.
On 16.04.2013 06:05, Broc Seib wrote: Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"?
Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE! withZipCompression_FileSet_**NoRestart_7(ch.qos.logback.** core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec withGZCompression_FileBlank_**NoRestart_6(ch.qos.logback.** core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\**test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(**Native Method) at java.io.FileInputStream.<init>** (FileInputStream.java:138) at ch.qos.logback.core.testUtil.**FileToBufferUtil.** regularReadIntoList(Fil eToBufferUtil.java:60) at ch.qos.logback.core.testUtil.**FileToBufferUtil.readIntoList(** FileToBuff erUtil.java:38) at ch.qos.logback.core.rolling.**ScaffoldingForRollingTests.** fileContentChe ck(ScaffoldingForRollingTests.**java:84) at ch.qos.logback.core.rolling.**ScaffoldingForRollingTests.** sortedContentC heck(**ScaffoldingForRollingTests.**java:99) at ch.qos.logback.core.rolling.**SizeAndTimeBasedFNATP_Test.** generic(SizeAn dTimeBasedFNATP_Test.java:130) at ch.qos.logback.core.rolling.**SizeAndTimeBasedFNATP_Test.** withGZCompress ion_FileBlank_NoRestart_6(**SizeAndTimeBasedFNATP_Test.**java:189) at sun.reflect.**NativeMethodAccessorImpl.**invoke0(Native Method) at sun.reflect.**NativeMethodAccessorImpl.**invoke(** NativeMethodAccessorImpl. java:57) at sun.reflect.**DelegatingMethodAccessorImpl.** invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.**invoke(Method.java:601) at org.junit.runners.model.**FrameworkMethod$1.** runReflectiveCall(Framework Method.java:45) at org.junit.internal.runners.**model.ReflectiveCallable.run(** ReflectiveCal lable.java:15) at org.junit.runners.model.**FrameworkMethod.** invokeExplosively(FrameworkMe thod.java:42) at org.junit.internal.runners.**statements.InvokeMethod.** evaluate(InvokeMet hod.java:20) at org.junit.internal.runners.**statements.RunBefores.** evaluate(RunBefores. java:28) at org.junit.runners.**ParentRunner.runLeaf(**ParentRunner.java:263) at org.junit.runners.**BlockJUnit4ClassRunner.** runChild(BlockJUnit4ClassRun ner.java:68) at org.junit.runners.**BlockJUnit4ClassRunner.** runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.**ParentRunner$3.run(** ParentRunner.java:231) at org.junit.runners.**ParentRunner$1.schedule(**ParentRunner.java:60) at org.junit.runners.**ParentRunner.runChildren(**ParentRunner.java:229) at org.junit.runners.**ParentRunner.access$000(**ParentRunner.java:50) at org.junit.runners.**ParentRunner$2.evaluate(**ParentRunner.java:222) at org.junit.runners.**ParentRunner.run(ParentRunner.** java:300) 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:231) at java.util.concurrent.**Executors$RunnableAdapter.** call(Executors.java:47 1) at java.util.concurrent.**FutureTask$Sync.innerRun(** FutureTask.java:334) at java.util.concurrent.**FutureTask.run(FutureTask.** java:166) at java.util.concurrent.**ThreadPoolExecutor.runWorker(** ThreadPoolExecutor. java:1110) at java.util.concurrent.**ThreadPoolExecutor$Worker.run(** ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.**java:722)
withGZCompression_FileSet_**NoRestart_5(ch.qos.logback.** core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec noCompression_FileSet_**WithStopStart_4(ch.qos.** logback.core.rolling.**SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec noCompression_FileBlank_**WithStopStart_3(ch.qos.** logback.core.rolling.**SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec noCompression_FileBlank_**NoRestart_2(ch.qos.logback.** core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec noCompression_FileSet_**NoRestart_1(ch.qos.logback.**core.rolling.** SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.**ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec verifyRecuperationAfterFailure**(ch.qos.logback.core.recovery.** ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.**helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.**rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.**rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.**rolling.helper.CompressTest) Time elapsed: 0.012 sec
Results :
Tests in error:
withGZCompression_FileBlank_**NoRestart_6(ch.qos.logback.** core.rolling.SizeAndTim eBasedFNATP_Test): target\test-output\910764289\**test6-2013-04-15_23_47_35-1.**txt (The system cannot find the file specified)
Tests run: 424, Failures: 0, Errors: 1, Skipped: 6
[INFO] ------------------------------**------------------------------** ------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent ..............................**...... SUCCESS [0.338s] [INFO] Logback Core Module ..............................**. FAILURE [28.279s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ..............................**........ SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------**------------------------------** ------------ [INFO] BUILD FAILURE [INFO] ------------------------------**------------------------------** ------------ [INFO] Total time: 29.140s [INFO] Finished at: Mon Apr 15 23:47:54 EDT 2013 [INFO] Final Memory: 28M/241M [INFO] ------------------------------**------------------------------** ------------ [ERROR] Failed to execute goal org.apache.maven.plugins:**maven-surefire-plugin:2. 12.4:test (default-test) on project logback-core: There are test failures.
-- Ceki 65% of statistics are made up on the spot ______________________________**_________________ logback-dev mailing list logback-dev@qos.ch http://mailman.qos.ch/mailman/**listinfo/logback-dev<http://mailman.qos.ch/mailman/listinfo/logback-dev>

I'm also using maven 3.0.5: $ mvn -version Apache Maven 3.0.5 (r01de14724cdef164cd33c7c8c2fe155faf9602da; 2013-02-19 08:51: 28-0500) Maven home: c:\apache-maven-3.0.5 Java version: 1.7.0, vendor: Oracle Corporation Java home: c:\Program Files\Java\jdk1.7.0\jre Default locale: en_US, platform encoding: Cp1252 OS name: "windows 7", version: "6.1", arch: "amd64", family: "windows" On Tue, Apr 16, 2013 at 7:42 PM, Broc Seib <broc.seib@gmail.com> wrote:
$ "$JAVA_HOME"/bin/java.exe -version java version "1.7.0" Java(TM) SE Runtime Environment (build 1.7.0-b147) Java HotSpot(TM) 64-Bit Server VM (build 21.0-b17, mixed mode)
Processor: Intel(R) Core(TM) i7-3770 CPU @ 3.40GHz 3.40 GHz Installed memory (RAM): 16.0 GB (15.8 GB usable) System type: 64-bit Operating System.
On Tue, Apr 16, 2013 at 5:34 PM, ceki <ceki@qos.ch> wrote:
Which JDK are you running? Which exact CPU?
On 16.04.2013 23:07, Broc Seib wrote:
Hi Carl and Ceki,
I grabbed 3727d7ff0fd33a9584a9ba0ef03695**06fa8f562e right after Carl committed it.
$ git log -1 commit 3727d7ff0fd33a9584a9ba0ef03695**06fa8f562e Author: Carl Harris <ceharris@vt.edu <mailto:ceharris@vt.edu>>
Date: Tue Apr 16 14:43:35 2013 -0400
removed an unused import to silence a build warning in eclipse
I believe Carl's patch addressed the previous problem I was seeing. But I still have some mixed results getting a clean build. When I first ran 'mvn clean; mvn install' I got an error in Logback Core Module, in ch.qos.logback.core.appender.**FileAppenderTest. But when I did 'mvn clean; mvn install' again, FileAppenderTest worked fine. (Failed surefire-report included below.)
After Logback Core built, Logback Access Module build fails. See the test set for ch.qos.logback.access.db.**DBAppenderHSQLTest below.
------------------------------**------------------------------** ------------------- Test set: ch.qos.logback.core.appender.**FileAppenderTest ------------------------------**------------------------------** ------------------- Tests run: 6, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.079 sec <<< FAILURE! testCreateParentFolders(ch.**qos.logback.core.appender.** FileAppenderTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError: failed to delete C:\gitrepo\logback\logback-**core\target\test-output\**fat992837029 at org.junit.Assert.fail(Assert.**java:93) at org.junit.Assert.assertTrue(**Assert.java:43) at ch.qos.logback.core.appender.**FileAppenderTest.** testCreateParentFolders(**FileAppenderTest.java:93) at sun.reflect.**NativeMethodAccessorImpl.**invoke0(Native Method) at sun.reflect.**NativeMethodAccessorImpl.**invoke(** NativeMethodAccessorImpl.java:**57) at sun.reflect.**DelegatingMethodAccessorImpl.**invoke(** DelegatingMethodAccessorImpl.**java:43) at java.lang.reflect.Method.**invoke(Method.java:601) at org.junit.runners.model.**FrameworkMethod$1.**runReflectiveCall(** FrameworkMethod.java:45) at org.junit.internal.runners.**model.ReflectiveCallable.run(** ReflectiveCallable.java:15) at org.junit.runners.model.**FrameworkMethod.**invokeExplosively(** FrameworkMethod.java:42) at org.junit.internal.runners.**statements.InvokeMethod.** evaluate(InvokeMethod.java:20) at org.junit.runners.**ParentRunner.runLeaf(**ParentRunner.java:263) at org.junit.runners.**BlockJUnit4ClassRunner.**runChild(** BlockJUnit4ClassRunner.java:**68) at org.junit.runners.**BlockJUnit4ClassRunner.**runChild(** BlockJUnit4ClassRunner.java:**47) at org.junit.runners.**ParentRunner$3.run(**ParentRunner.java:231) at org.junit.runners.**ParentRunner$1.schedule(**ParentRunner.java:60) at org.junit.runners.**ParentRunner.runChildren(**ParentRunner.java:229) at org.junit.runners.**ParentRunner.access$000(**ParentRunner.java:50) at org.junit.runners.**ParentRunner$2.evaluate(**ParentRunner.java:222) at org.junit.runners.**ParentRunner.run(ParentRunner.**java:300) 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:231) at java.util.concurrent.**Executors$RunnableAdapter.** call(Executors.java:471) at java.util.concurrent.**FutureTask$Sync.innerRun(** FutureTask.java:334) at java.util.concurrent.**FutureTask.run(FutureTask.**java:166) at java.util.concurrent.**ThreadPoolExecutor.runWorker(** ThreadPoolExecutor.java:1110) at java.util.concurrent.**ThreadPoolExecutor$Worker.run(** ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.**java:722)
smoke(ch.qos.logback.core.**appender.FileAppenderTest) Time elapsed: 0.049 sec testPrudentModeLogicalImplicat**ions(ch.qos.logback.core.** appender.FileAppenderTest) Time elapsed: 0.02 sec testNewAppender(ch.qos.**logback.core.appender.**FileAppenderTest) Time elapsed: 0 sec testConfiguredAppender(ch.qos.**logback.core.appender.** FileAppenderTest) Time elapsed: 0.002 sec testNoStart(ch.qos.logback.**core.appender.**FileAppenderTest) Time elapsed: 0.001 sec
------------------------------**------------------------------** ------------------- Test set: ch.qos.logback.access.db.**DBAppenderHSQLTest ------------------------------**------------------------------** ------------------- Tests run: 4, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.207 sec <<< FAILURE! testAppendAccessEvent(ch.qos.**logback.access.db.**DBAppenderHSQLTest) Time elapsed: 0.026 sec testAppendHeaders(ch.qos.**logback.access.db.**DBAppenderHSQLTest) Time elapsed: 0.006 sec testAppendMultipleEvents(ch.**qos.logback.access.db.** DBAppenderHSQLTest) Time elapsed: 0.022 sec testCheckNoHeadersAreInserted(**ch.qos.logback.access.db.** DBAppenderHSQLTest) Time elapsed: 0.009 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.**java:92) at org.junit.Assert.assertTrue(**Assert.java:43) at org.junit.Assert.assertFalse(**Assert.java:68) at org.junit.Assert.assertFalse(**Assert.java:79) at ch.qos.logback.access.db.**DBAppenderHSQLTest.** testCheckNoHeadersAreInserted(**DBAppenderHSQLTest.java:148) at sun.reflect.**NativeMethodAccessorImpl.**invoke0(Native Method) at sun.reflect.**NativeMethodAccessorImpl.**invoke(** NativeMethodAccessorImpl.java:**57) at sun.reflect.**DelegatingMethodAccessorImpl.**invoke(** DelegatingMethodAccessorImpl.**java:43) at java.lang.reflect.Method.**invoke(Method.java:601) at org.junit.runners.model.**FrameworkMethod$1.**runReflectiveCall(** FrameworkMethod.java:45) at org.junit.internal.runners.**model.ReflectiveCallable.run(** ReflectiveCallable.java:15) at org.junit.runners.model.**FrameworkMethod.**invokeExplosively(** FrameworkMethod.java:42) 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.internal.runners.**statements.RunAfters.evaluate(** RunAfters.java:30) at org.junit.runners.**ParentRunner.runLeaf(**ParentRunner.java:263) at org.junit.runners.**BlockJUnit4ClassRunner.**runChild(** BlockJUnit4ClassRunner.java:**68) at org.junit.runners.**BlockJUnit4ClassRunner.**runChild(** BlockJUnit4ClassRunner.java:**47) at org.junit.runners.**ParentRunner$3.run(**ParentRunner.java:231) at org.junit.runners.**ParentRunner$1.schedule(**ParentRunner.java:60) at org.junit.runners.**ParentRunner.runChildren(**ParentRunner.java:229) at org.junit.runners.**ParentRunner.access$000(**ParentRunner.java:50) at org.junit.runners.**ParentRunner$2.evaluate(**ParentRunner.java:222) at org.junit.internal.runners.**statements.RunBefores.** evaluate(RunBefores.java:28) at org.junit.internal.runners.**statements.RunAfters.evaluate(** RunAfters.java:30) at org.junit.runners.**ParentRunner.run(ParentRunner.**java:300) at org.apache.maven.surefire.**junit4.JUnit4Provider.execute(** JUnit4Provider.java:252) at org.apache.maven.surefire.**junit4.JUnit4Provider.** executeTestSet(JUnit4Provider.**java:141) at org.apache.maven.surefire.**junit4.JUnit4Provider.invoke(** JUnit4Provider.java:112) at sun.reflect.**NativeMethodAccessorImpl.**invoke0(Native Method) at sun.reflect.**NativeMethodAccessorImpl.**invoke(** NativeMethodAccessorImpl.java:**57) at sun.reflect.**DelegatingMethodAccessorImpl.**invoke(** DelegatingMethodAccessorImpl.**java:43) at java.lang.reflect.Method.**invoke(Method.java:601) at org.apache.maven.surefire.**util.ReflectionUtils.** invokeMethodWithArray(**ReflectionUtils.java:189) at org.apache.maven.surefire.**booter.ProviderFactory$** ProviderProxy.invoke(**ProviderFactory.java:165) at org.apache.maven.surefire.**booter.ProviderFactory.**invokeProvider(** ProviderFactory.java:85) at org.apache.maven.surefire.**booter.ForkedBooter.**runSuitesInProcess(** ForkedBooter.java:115) at org.apache.maven.surefire.**booter.ForkedBooter.main(** ForkedBooter.java:75)
On Tue, Apr 16, 2013 at 2:48 PM, Harris, Carl <ceharris@vt.edu <mailto:ceharris@vt.edu>> wrote:
Last couple of commits should help reduce timing sensitivity in the SocketConnectorBaseTest and SocketAppenderTest. Anxious to hear if this improves matters for you Broc.
carl
On Apr 16, 2013, at 2:00 PM, Broc Seib wrote:
Here it is:
------------------------------**------------------------------** ------------------- Test set: ch.qos.logback.core.net.**SocketConnectorBaseTest ------------------------------**------------------------------** ------------------- Tests run: 3, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 5.596 sec <<< FAILURE! testConnect(ch.qos.logback.**core.net.**SocketConnectorBaseTest) Time elapsed: 0.081 sec testConnectEventually(ch.qos.**logback.core.net.** SocketConnectorBaseTest) Time elapsed: 2.514 sec testConnectionFails(ch.qos.**logback.core.net.** SocketConnectorBaseTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.**java:92) at org.junit.Assert.assertTrue(**Assert.java:43) at org.junit.Assert.assertFalse(**Assert.java:68) at org.junit.Assert.assertFalse(**Assert.java:79) at ch.qos.logback.core.net.**SocketConnectorBaseTest.** testConnectionFails(**SocketConnectorBaseTest.java:**89) at sun.reflect.**NativeMethodAccessorImpl.**invoke0(Native Method) at sun.reflect.**NativeMethodAccessorImpl.**invoke(** NativeMethodAccessorImpl.java:**57) at sun.reflect.**DelegatingMethodAccessorImpl.**invoke(** DelegatingMethodAccessorImpl.**java:43) at java.lang.reflect.Method.**invoke(Method.java:601) at org.junit.runners.model.**FrameworkMethod$1.**runReflectiveCall(** FrameworkMethod.java:45) at org.junit.internal.runners.**model.ReflectiveCallable.run(** ReflectiveCallable.java:15) at org.junit.runners.model.**FrameworkMethod.**invokeExplosively(** FrameworkMethod.java:42) 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.internal.runners.**statements.RunAfters.evaluate(** RunAfters.java:30) at org.junit.runners.**ParentRunner.runLeaf(**ParentRunner.java:263) at org.junit.runners.**BlockJUnit4ClassRunner.**runChild(** BlockJUnit4ClassRunner.java:**68) at org.junit.runners.**BlockJUnit4ClassRunner.**runChild(** BlockJUnit4ClassRunner.java:**47) at org.junit.runners.**ParentRunner$3.run(**ParentRunner.java:231) at org.junit.runners.**ParentRunner$1.schedule(** ParentRunner.java:60) at org.junit.runners.**ParentRunner.runChildren(** ParentRunner.java:229) at org.junit.runners.**ParentRunner.access$000(** ParentRunner.java:50) at org.junit.runners.**ParentRunner$2.evaluate(** ParentRunner.java:222) at org.junit.runners.**ParentRunner.run(ParentRunner.**java:300) 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:231) at java.util.concurrent.**Executors$RunnableAdapter.** call(Executors.java:471) at java.util.concurrent.**FutureTask$Sync.innerRun(** FutureTask.java:334) at java.util.concurrent.**FutureTask.run(FutureTask.**java:166) at java.util.concurrent.**ThreadPoolExecutor.runWorker(** ThreadPoolExecutor.java:1110) at java.util.concurrent.**ThreadPoolExecutor$Worker.run(** ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.**java:722)
On Tue, Apr 16, 2013 at 1:56 PM, Harris, Carl <ceharris@vt.edu <mailto:ceharris@vt.edu><**mailto:ceharris@vt.edu
<mailto:ceharris@vt.edu>>> wrote: Broc,
Would you please send the surefire-report for the test ch.qos.logback.core.net.**SocketConnectorBaseTest, so I can take a look to see where/why it is failing?
logback-core/target/surefire-**reports/ch.qos.logback.core.** net.SocketConnectorBaseTest.**txt
Thanks,
carl
On Apr 16, 2013, at 1:02 PM, Broc Seib wrote:
Hi Ceki,
I just updated to the latest code you just committed, 56e8ab2192697bc27a8cfbd8441db6**2aff53e87c but I think there is a different problem now.
bseib@BSEIB-WIN7 /c/gitrepo $ rm -rf logback
bseib@BSEIB-WIN7 /c/gitrepo $ git clone https://github.com/qos-ch/**logback.git<https://github.com/qos-ch/logback.git> Cloning into 'logback'... remote: Counting objects: 52855, done. remote: Compressing objects: 100% (13701/13701), done. remote: Total 52855 (delta 26607), reused 52138 (delta 26038) Receiving objects: 100% (52855/52855), 20.13 MiB | 1.17 MiB/s, done. Resolving deltas: 100% (26607/26607), done.
bseib@BSEIB-WIN7 /c/gitrepo $ cd logback/
bseib@BSEIB-WIN7 /c/gitrepo/logback (master) $ git log -1 commit 56e8ab2192697bc27a8cfbd8441db6**2aff53e87c Merge: 2ff61c7 513e380 Author: Ceki Gulcu <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ce**ki@qos.ch <ceki@qos.ch> <mailto:ceki@qos.ch>><mailto:c**eki@qos.ch <ceki@qos.ch>
<mailto:ceki@qos.ch><mailto:ce**ki@qos.ch <ceki@qos.ch> <mailto: ceki@qos.ch>>>> Date: Tue Apr 16 18:09:23 2013 +0200
Merge branch 'master' of github.com:qos-ch/logback
And then 'mvn clean; mvn install':
[...] Running ch.qos.logback.core.joran.**conditional.** IfThenElseAndIncludeCompositio**nTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.635 sec includeNestedWithinIf(ch.qos.**logback.core.joran.**conditional.** IfThenElseAndIncludeCompositio**nTest) Time elapsed: 0.635 sec
Results :
Failed tests: testConnectionFails(ch.qos.**logback.core.net.** SocketConnectorBaseTest)
lossyAppenderShouldOnlyLooseCe**rtainEvents(ch.qos.logback.** core.AsyncAppenderBaseTest): expected:<8> but was:<10>
Tests run: 424, Failures: 2, Errors: 0, Skipped: 6
[INFO] ------------------------------**------------------------------** ------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent ..............................**...... SUCCESS [0.244s] [INFO] Logback Core Module ..............................**. FAILURE [27.251s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ..............................**........ SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------**------------------------------** ------------ [INFO] BUILD FAILURE [INFO] ------------------------------**------------------------------** ------------ [INFO] Total time: 28.014s [INFO] Finished at: Tue Apr 16 12:51:50 EDT 2013 [INFO] Final Memory: 28M/308M [INFO] ------------------------------**------------------------------** ------------ [ERROR] Failed to execute goal org.apache.maven.plugins:**maven-surefire-plugin:2.12.4:**test (default-test) on project logback-core: There are test failures.
On Tue, Apr 16, 2013 at 12:42 PM, ceki <ceki@qos.ch <mailto:ceki@qos.ch><mailto:ce**ki@qos.ch <ceki@qos.ch> <mailto:ceki@qos.ch>><mailto:c**eki@qos.ch <ceki@qos.ch> <mailto:ceki@qos.ch><mailto:ce**ki@qos.ch <ceki@qos.ch> <mailto: ceki@qos.ch>>>> wrote:
I am hoping that commit [1] should make the test failures in logback-core/src/test/java/ch/**qos/logback/core/rolling disappear.
On 16.04.2013 06:05, Broc Seib wrote: Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"?
Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE! withZipCompression_FileSet_**NoRestart_7(ch.qos.logback.** core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec withGZCompression_FileBlank_**NoRestart_6(ch.qos.logback.** core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\**test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(**Native Method) at java.io.FileInputStream.<init>** (FileInputStream.java:138) at ch.qos.logback.core.testUtil.**FileToBufferUtil.** regularReadIntoList(Fil eToBufferUtil.java:60) at ch.qos.logback.core.testUtil.**FileToBufferUtil.readIntoList(** FileToBuff erUtil.java:38) at ch.qos.logback.core.rolling.**ScaffoldingForRollingTests.** fileContentChe ck(ScaffoldingForRollingTests.**java:84) at ch.qos.logback.core.rolling.**ScaffoldingForRollingTests.** sortedContentC heck(**ScaffoldingForRollingTests.**java:99) at ch.qos.logback.core.rolling.**SizeAndTimeBasedFNATP_Test.** generic(SizeAn dTimeBasedFNATP_Test.java:130) at ch.qos.logback.core.rolling.**SizeAndTimeBasedFNATP_Test.** withGZCompress ion_FileBlank_NoRestart_6(**SizeAndTimeBasedFNATP_Test.**java:189) at sun.reflect.**NativeMethodAccessorImpl.**invoke0(Native Method) at sun.reflect.**NativeMethodAccessorImpl.**invoke(** NativeMethodAccessorImpl. java:57) at sun.reflect.**DelegatingMethodAccessorImpl.** invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.**invoke(Method.java:601) at org.junit.runners.model.**FrameworkMethod$1.** runReflectiveCall(Framework Method.java:45) at org.junit.internal.runners.**model.ReflectiveCallable.run(** ReflectiveCal lable.java:15) at org.junit.runners.model.**FrameworkMethod.** invokeExplosively(FrameworkMe thod.java:42) at org.junit.internal.runners.**statements.InvokeMethod.** evaluate(InvokeMet hod.java:20) at org.junit.internal.runners.**statements.RunBefores.** evaluate(RunBefores. java:28) at org.junit.runners.**ParentRunner.runLeaf(**ParentRunner.java:263) at org.junit.runners.**BlockJUnit4ClassRunner.** runChild(BlockJUnit4ClassRun ner.java:68) at org.junit.runners.**BlockJUnit4ClassRunner.** runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.**ParentRunner$3.run(** ParentRunner.java:231) at org.junit.runners.**ParentRunner$1.schedule(**ParentRunner.java:60) at org.junit.runners.**ParentRunner.runChildren(** ParentRunner.java:229) at org.junit.runners.**ParentRunner.access$000(**ParentRunner.java:50) at org.junit.runners.**ParentRunner$2.evaluate(**ParentRunner.java:222) at org.junit.runners.**ParentRunner.run(ParentRunner.** java:300) 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:231) at java.util.concurrent.**Executors$RunnableAdapter.** call(Executors.java:47 1) at java.util.concurrent.**FutureTask$Sync.innerRun(** FutureTask.java:334) at java.util.concurrent.**FutureTask.run(FutureTask.** java:166) at java.util.concurrent.**ThreadPoolExecutor.runWorker(** ThreadPoolExecutor. java:1110) at java.util.concurrent.**ThreadPoolExecutor$Worker.run(** ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.**java:722)
withGZCompression_FileSet_**NoRestart_5(ch.qos.logback.** core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec noCompression_FileSet_**WithStopStart_4(ch.qos.** logback.core.rolling.**SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec noCompression_FileBlank_**WithStopStart_3(ch.qos.** logback.core.rolling.**SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec noCompression_FileBlank_**NoRestart_2(ch.qos.logback.** core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec noCompression_FileSet_**NoRestart_1(ch.qos.logback.**core.rolling.** SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.**ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec verifyRecuperationAfterFailure**(ch.qos.logback.core.recovery.** ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.**helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.**rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.**rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.**rolling.helper.CompressTest) Time elapsed: 0.012 sec
Results :
Tests in error:
withGZCompression_FileBlank_**NoRestart_6(ch.qos.logback.** core.rolling.SizeAndTim eBasedFNATP_Test): target\test-output\910764289\**test6-2013-04-15_23_47_35-1.**txt (The system cannot find the file specified)
Tests run: 424, Failures: 0, Errors: 1, Skipped: 6
[INFO] ------------------------------**------------------------------** ------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent ..............................**...... SUCCESS [0.338s] [INFO] Logback Core Module ..............................**. FAILURE [28.279s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ..............................**........ SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO] ------------------------------**------------------------------** ------------ [INFO] BUILD FAILURE [INFO] ------------------------------**------------------------------** ------------ [INFO] Total time: 29.140s [INFO] Finished at: Mon Apr 15 23:47:54 EDT 2013 [INFO] Final Memory: 28M/241M [INFO] ------------------------------**------------------------------** ------------ [ERROR] Failed to execute goal org.apache.maven.plugins:**maven-surefire-plugin:2. 12.4:test (default-test) on project logback-core: There are test failures.
-- Ceki 65% of statistics are made up on the spot ______________________________**_________________ logback-dev mailing list logback-dev@qos.ch http://mailman.qos.ch/mailman/**listinfo/logback-dev<http://mailman.qos.ch/mailman/listinfo/logback-dev>

If I switch JDK 7, I also get Failed tests: testCheckNoHeadersAreInserted(ch.qos.logback.access.db.DBAppenderHSQLTest) On 17.04.2013 01:42, Broc Seib wrote:
$ "$JAVA_HOME"/bin/java.exe -version java version "1.7.0" Java(TM) SE Runtime Environment (build 1.7.0-b147) Java HotSpot(TM) 64-Bit Server VM (build 21.0-b17, mixed mode)
Processor: Intel(R) Core(TM) i7-3770 CPU @ 3.40GHz 3.40 GHz Installed memory (RAM): 16.0 GB (15.8 GB usable) System type: 64-bit Operating System.
On Tue, Apr 16, 2013 at 5:34 PM, ceki <ceki@qos.ch <mailto:ceki@qos.ch>> wrote:
Which JDK are you running? Which exact CPU?
On 16.04.2013 23:07, Broc Seib wrote:
Hi Carl and Ceki,
I grabbed 3727d7ff0fd33a9584a9ba0ef03695__06fa8f562e right after Carl committed it.
$ git log -1 commit 3727d7ff0fd33a9584a9ba0ef03695__06fa8f562e Author: Carl Harris <ceharris@vt.edu <mailto:ceharris@vt.edu> <mailto:ceharris@vt.edu <mailto:ceharris@vt.edu>>>
Date: Tue Apr 16 14:43:35 2013 -0400
removed an unused import to silence a build warning in eclipse
I believe Carl's patch addressed the previous problem I was seeing. But I still have some mixed results getting a clean build. When I first ran 'mvn clean; mvn install' I got an error in Logback Core Module, in ch.qos.logback.core.appender.__FileAppenderTest. But when I did 'mvn clean; mvn install' again, FileAppenderTest worked fine. (Failed surefire-report included below.)
After Logback Core built, Logback Access Module build fails. See the test set for ch.qos.logback.access.db.__DBAppenderHSQLTest below.
------------------------------__------------------------------__------------------- Test set: ch.qos.logback.core.appender.__FileAppenderTest ------------------------------__------------------------------__------------------- Tests run: 6, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.079 sec <<< FAILURE! testCreateParentFolders(ch.__qos.logback.core.appender.__FileAppenderTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError: failed to delete C:\gitrepo\logback\logback-__core\target\test-output\__fat992837029 at org.junit.Assert.fail(Assert.__java:93) at org.junit.Assert.assertTrue(__Assert.java:43) at ch.qos.logback.core.appender.__FileAppenderTest.__testCreateParentFolders(__FileAppenderTest.java:93) at sun.reflect.__NativeMethodAccessorImpl.__invoke0(Native Method) at sun.reflect.__NativeMethodAccessorImpl.__invoke(__NativeMethodAccessorImpl.java:__57) at sun.reflect.__DelegatingMethodAccessorImpl.__invoke(__DelegatingMethodAccessorImpl.__java:43) at java.lang.reflect.Method.__invoke(Method.java:601) at org.junit.runners.model.__FrameworkMethod$1.__runReflectiveCall(__FrameworkMethod.java:45) at org.junit.internal.runners.__model.ReflectiveCallable.run(__ReflectiveCallable.java:15) at org.junit.runners.model.__FrameworkMethod.__invokeExplosively(__FrameworkMethod.java:42) at org.junit.internal.runners.__statements.InvokeMethod.__evaluate(InvokeMethod.java:20) at org.junit.runners.__ParentRunner.runLeaf(__ParentRunner.java:263) at org.junit.runners.__BlockJUnit4ClassRunner.__runChild(__BlockJUnit4ClassRunner.java:__68) at org.junit.runners.__BlockJUnit4ClassRunner.__runChild(__BlockJUnit4ClassRunner.java:__47) at org.junit.runners.__ParentRunner$3.run(__ParentRunner.java:231) at org.junit.runners.__ParentRunner$1.schedule(__ParentRunner.java:60) at org.junit.runners.__ParentRunner.runChildren(__ParentRunner.java:229) at org.junit.runners.__ParentRunner.access$000(__ParentRunner.java:50) at org.junit.runners.__ParentRunner$2.evaluate(__ParentRunner.java:222) at org.junit.runners.__ParentRunner.run(ParentRunner.__java:300) 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:231) at java.util.concurrent.__Executors$RunnableAdapter.__call(Executors.java:471) at java.util.concurrent.__FutureTask$Sync.innerRun(__FutureTask.java:334) at java.util.concurrent.__FutureTask.run(FutureTask.__java:166) at java.util.concurrent.__ThreadPoolExecutor.runWorker(__ThreadPoolExecutor.java:1110) at java.util.concurrent.__ThreadPoolExecutor$Worker.run(__ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.__java:722)
smoke(ch.qos.logback.core.__appender.FileAppenderTest) Time elapsed: 0.049 sec testPrudentModeLogicalImplicat__ions(ch.qos.logback.core.__appender.FileAppenderTest) Time elapsed: 0.02 sec testNewAppender(ch.qos.__logback.core.appender.__FileAppenderTest) Time elapsed: 0 sec testConfiguredAppender(ch.qos.__logback.core.appender.__FileAppenderTest) Time elapsed: 0.002 sec testNoStart(ch.qos.logback.__core.appender.__FileAppenderTest) Time elapsed: 0.001 sec
------------------------------__------------------------------__------------------- Test set: ch.qos.logback.access.db.__DBAppenderHSQLTest ------------------------------__------------------------------__------------------- Tests run: 4, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 0.207 sec <<< FAILURE! testAppendAccessEvent(ch.qos.__logback.access.db.__DBAppenderHSQLTest) Time elapsed: 0.026 sec testAppendHeaders(ch.qos.__logback.access.db.__DBAppenderHSQLTest) Time elapsed: 0.006 sec testAppendMultipleEvents(ch.__qos.logback.access.db.__DBAppenderHSQLTest) Time elapsed: 0.022 sec testCheckNoHeadersAreInserted(__ch.qos.logback.access.db.__DBAppenderHSQLTest) Time elapsed: 0.009 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.__java:92) at org.junit.Assert.assertTrue(__Assert.java:43) at org.junit.Assert.assertFalse(__Assert.java:68) at org.junit.Assert.assertFalse(__Assert.java:79) at ch.qos.logback.access.db.__DBAppenderHSQLTest.__testCheckNoHeadersAreInserted(__DBAppenderHSQLTest.java:148) at sun.reflect.__NativeMethodAccessorImpl.__invoke0(Native Method) at sun.reflect.__NativeMethodAccessorImpl.__invoke(__NativeMethodAccessorImpl.java:__57) at sun.reflect.__DelegatingMethodAccessorImpl.__invoke(__DelegatingMethodAccessorImpl.__java:43) at java.lang.reflect.Method.__invoke(Method.java:601) at org.junit.runners.model.__FrameworkMethod$1.__runReflectiveCall(__FrameworkMethod.java:45) at org.junit.internal.runners.__model.ReflectiveCallable.run(__ReflectiveCallable.java:15) at org.junit.runners.model.__FrameworkMethod.__invokeExplosively(__FrameworkMethod.java:42) 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.internal.runners.__statements.RunAfters.evaluate(__RunAfters.java:30) at org.junit.runners.__ParentRunner.runLeaf(__ParentRunner.java:263) at org.junit.runners.__BlockJUnit4ClassRunner.__runChild(__BlockJUnit4ClassRunner.java:__68) at org.junit.runners.__BlockJUnit4ClassRunner.__runChild(__BlockJUnit4ClassRunner.java:__47) at org.junit.runners.__ParentRunner$3.run(__ParentRunner.java:231) at org.junit.runners.__ParentRunner$1.schedule(__ParentRunner.java:60) at org.junit.runners.__ParentRunner.runChildren(__ParentRunner.java:229) at org.junit.runners.__ParentRunner.access$000(__ParentRunner.java:50) at org.junit.runners.__ParentRunner$2.evaluate(__ParentRunner.java:222) at org.junit.internal.runners.__statements.RunBefores.__evaluate(RunBefores.java:28) at org.junit.internal.runners.__statements.RunAfters.evaluate(__RunAfters.java:30) at org.junit.runners.__ParentRunner.run(ParentRunner.__java:300) at org.apache.maven.surefire.__junit4.JUnit4Provider.execute(__JUnit4Provider.java:252) at org.apache.maven.surefire.__junit4.JUnit4Provider.__executeTestSet(JUnit4Provider.__java:141) at org.apache.maven.surefire.__junit4.JUnit4Provider.invoke(__JUnit4Provider.java:112) at sun.reflect.__NativeMethodAccessorImpl.__invoke0(Native Method) at sun.reflect.__NativeMethodAccessorImpl.__invoke(__NativeMethodAccessorImpl.java:__57) at sun.reflect.__DelegatingMethodAccessorImpl.__invoke(__DelegatingMethodAccessorImpl.__java:43) at java.lang.reflect.Method.__invoke(Method.java:601) at org.apache.maven.surefire.__util.ReflectionUtils.__invokeMethodWithArray(__ReflectionUtils.java:189) at org.apache.maven.surefire.__booter.ProviderFactory$__ProviderProxy.invoke(__ProviderFactory.java:165) at org.apache.maven.surefire.__booter.ProviderFactory.__invokeProvider(__ProviderFactory.java:85) at org.apache.maven.surefire.__booter.ForkedBooter.__runSuitesInProcess(__ForkedBooter.java:115) at org.apache.maven.surefire.__booter.ForkedBooter.main(__ForkedBooter.java:75)
On Tue, Apr 16, 2013 at 2:48 PM, Harris, Carl <ceharris@vt.edu <mailto:ceharris@vt.edu> <mailto:ceharris@vt.edu <mailto:ceharris@vt.edu>>> wrote:
Last couple of commits should help reduce timing sensitivity in the SocketConnectorBaseTest and SocketAppenderTest. Anxious to hear if this improves matters for you Broc.
carl
On Apr 16, 2013, at 2:00 PM, Broc Seib wrote:
Here it is:
------------------------------__------------------------------__------------------- Test set: ch.qos.logback.core.net <http://ch.qos.logback.core.net>.__SocketConnectorBaseTest
------------------------------__------------------------------__------------------- Tests run: 3, Failures: 1, Errors: 0, Skipped: 0, Time elapsed: 5.596 sec <<< FAILURE! testConnect(ch.qos.logback.__core.net <http://core.net>.__SocketConnectorBaseTest) Time elapsed: 0.081 sec testConnectEventually(ch.qos.__logback.core.net <http://logback.core.net>.__SocketConnectorBaseTest) Time elapsed: 2.514 sec testConnectionFails(ch.qos.__logback.core.net <http://logback.core.net>.__SocketConnectorBaseTest) Time elapsed: 0.001 sec <<< FAILURE! java.lang.AssertionError at org.junit.Assert.fail(Assert.__java:92) at org.junit.Assert.assertTrue(__Assert.java:43) at org.junit.Assert.assertFalse(__Assert.java:68) at org.junit.Assert.assertFalse(__Assert.java:79) at ch.qos.logback.core.net <http://ch.qos.logback.core.net>.__SocketConnectorBaseTest.__testConnectionFails(__SocketConnectorBaseTest.java:__89) at sun.reflect.__NativeMethodAccessorImpl.__invoke0(Native Method) at
sun.reflect.__NativeMethodAccessorImpl.__invoke(__NativeMethodAccessorImpl.java:__57) at
sun.reflect.__DelegatingMethodAccessorImpl.__invoke(__DelegatingMethodAccessorImpl.__java:43) at java.lang.reflect.Method.__invoke(Method.java:601) at
org.junit.runners.model.__FrameworkMethod$1.__runReflectiveCall(__FrameworkMethod.java:45) at
org.junit.internal.runners.__model.ReflectiveCallable.run(__ReflectiveCallable.java:15) at
org.junit.runners.model.__FrameworkMethod.__invokeExplosively(__FrameworkMethod.java:42) 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.internal.runners.__statements.RunAfters.evaluate(__RunAfters.java:30) at org.junit.runners.__ParentRunner.runLeaf(__ParentRunner.java:263) at
org.junit.runners.__BlockJUnit4ClassRunner.__runChild(__BlockJUnit4ClassRunner.java:__68) at
org.junit.runners.__BlockJUnit4ClassRunner.__runChild(__BlockJUnit4ClassRunner.java:__47) at org.junit.runners.__ParentRunner$3.run(__ParentRunner.java:231) at org.junit.runners.__ParentRunner$1.schedule(__ParentRunner.java:60) at org.junit.runners.__ParentRunner.runChildren(__ParentRunner.java:229) at org.junit.runners.__ParentRunner.access$000(__ParentRunner.java:50) at org.junit.runners.__ParentRunner$2.evaluate(__ParentRunner.java:222) at org.junit.runners.__ParentRunner.run(ParentRunner.__java:300) 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:231) at
java.util.concurrent.__Executors$RunnableAdapter.__call(Executors.java:471) at java.util.concurrent.__FutureTask$Sync.innerRun(__FutureTask.java:334) at java.util.concurrent.__FutureTask.run(FutureTask.__java:166) at
java.util.concurrent.__ThreadPoolExecutor.runWorker(__ThreadPoolExecutor.java:1110) at
java.util.concurrent.__ThreadPoolExecutor$Worker.run(__ThreadPoolExecutor.java:603) at java.lang.Thread.run(Thread.__java:722)
On Tue, Apr 16, 2013 at 1:56 PM, Harris, Carl <ceharris@vt.edu <mailto:ceharris@vt.edu> <mailto:ceharris@vt.edu <mailto:ceharris@vt.edu>><__mailto:ceharris@vt.edu <mailto:ceharris@vt.edu>
<mailto:ceharris@vt.edu <mailto:ceharris@vt.edu>>>> wrote: Broc,
Would you please send the surefire-report for the test ch.qos.logback.core.net <http://ch.qos.logback.core.net>.__SocketConnectorBaseTest, so I can take a look to see where/why it is failing?
logback-core/target/surefire-__reports/ch.qos.logback.core.__net.SocketConnectorBaseTest.__txt
Thanks,
carl
On Apr 16, 2013, at 1:02 PM, Broc Seib wrote:
Hi Ceki,
I just updated to the latest code you just committed, 56e8ab2192697bc27a8cfbd8441db6__2aff53e87c but I think there is a different problem now.
bseib@BSEIB-WIN7 /c/gitrepo $ rm -rf logback
bseib@BSEIB-WIN7 /c/gitrepo $ git clone https://github.com/qos-ch/__logback.git <https://github.com/qos-ch/logback.git> Cloning into 'logback'... remote: Counting objects: 52855, done. remote: Compressing objects: 100% (13701/13701), done. remote: Total 52855 (delta 26607), reused 52138 (delta 26038) Receiving objects: 100% (52855/52855), 20.13 MiB | 1.17 MiB/s, done. Resolving deltas: 100% (26607/26607), done.
bseib@BSEIB-WIN7 /c/gitrepo $ cd logback/
bseib@BSEIB-WIN7 /c/gitrepo/logback (master) $ git log -1 commit 56e8ab2192697bc27a8cfbd8441db6__2aff53e87c Merge: 2ff61c7 513e380 Author: Ceki Gulcu <ceki@qos.ch <mailto:ceki@qos.ch> <mailto:ceki@qos.ch <mailto:ceki@qos.ch>><mailto:ce__ki@qos.ch <mailto:ceki@qos.ch> <mailto:ceki@qos.ch <mailto:ceki@qos.ch>>><mailto:c__eki@qos.ch <mailto:ceki@qos.ch>
<mailto:ceki@qos.ch <mailto:ceki@qos.ch>><mailto:ce__ki@qos.ch <mailto:ceki@qos.ch> <mailto:ceki@qos.ch <mailto:ceki@qos.ch>>>>> Date: Tue Apr 16 18:09:23 2013 +0200
Merge branch 'master' of github.com:qos-ch/logback
And then 'mvn clean; mvn install':
[...] Running
ch.qos.logback.core.joran.__conditional.__IfThenElseAndIncludeCompositio__nTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.635 sec
includeNestedWithinIf(ch.qos.__logback.core.joran.__conditional.__IfThenElseAndIncludeCompositio__nTest) Time elapsed: 0.635 sec
Results :
Failed tests: testConnectionFails(ch.qos.__logback.core.net <http://logback.core.net>.__SocketConnectorBaseTest)
lossyAppenderShouldOnlyLooseCe__rtainEvents(ch.qos.logback.__core.AsyncAppenderBaseTest): expected:<8> but was:<10>
Tests run: 424, Failures: 2, Errors: 0, Skipped: 6
[INFO]
------------------------------__------------------------------__------------ [INFO] Reactor Summary: [INFO] [INFO] Logback-Parent ..............................__...... SUCCESS [0.244s] [INFO] Logback Core Module ..............................__. FAILURE [27.251s] [INFO] Logback Classic Module ............................ SKIPPED [INFO] Logback Access Module ............................. SKIPPED [INFO] Logback Site ..............................__........ SKIPPED [INFO] Logback Examples Module ........................... SKIPPED [INFO]
------------------------------__------------------------------__------------ [INFO] BUILD FAILURE [INFO]
------------------------------__------------------------------__------------ [INFO] Total time: 28.014s [INFO] Finished at: Tue Apr 16 12:51:50 EDT 2013 [INFO] Final Memory: 28M/308M [INFO]
------------------------------__------------------------------__------------ [ERROR] Failed to execute goal org.apache.maven.plugins:__maven-surefire-plugin:2.12.4:__test (default-test) on project logback-core: There are test failures.
On Tue, Apr 16, 2013 at 12:42 PM, ceki <ceki@qos.ch <mailto:ceki@qos.ch> <mailto:ceki@qos.ch <mailto:ceki@qos.ch>><mailto:ce__ki@qos.ch <mailto:ceki@qos.ch> <mailto:ceki@qos.ch <mailto:ceki@qos.ch>>><mailto:c__eki@qos.ch <mailto:ceki@qos.ch> <mailto:ceki@qos.ch <mailto:ceki@qos.ch>><mailto:ce__ki@qos.ch <mailto:ceki@qos.ch> <mailto:ceki@qos.ch <mailto:ceki@qos.ch>>>>> wrote:
I am hoping that commit [1] should make the test failures in logback-core/src/test/java/ch/__qos/logback/core/rolling disappear.
[1] https://github.com/qos-ch/__logback/commit/2ff61c7c1cb0562 <https://github.com/qos-ch/logback/commit/2ff61c7c1cb0562>
On 16.04.2013 06:05, Broc Seib wrote: Yes, I've done "mvn clean; mvn install" but still get a failure. I'm also on a 3.4GHz Win7 box with a SSD. Is there any possibility of a timing sensitivity for being "too fast"?
Tests run: 7, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 0.557 sec <<< FA ILURE!
withZipCompression_FileSet___NoRestart_7(ch.qos.logback.__core.rolling.SizeAndTimeBa sedFNATP_Test) Time elapsed: 0.201 sec
withGZCompression_FileBlank___NoRestart_6(ch.qos.logback.__core.rolling.SizeAndTimeB asedFNATP_Test) Time elapsed: 0.006 sec <<< ERROR! java.io.FileNotFoundException: target\test-output\910764289\__test6-2013-04-15_23_ 47_35-1.txt (The system cannot find the file specified) at java.io.FileInputStream.open(__Native Method) at java.io.FileInputStream.<init>__(FileInputStream.java:138) at
ch.qos.logback.core.testUtil.__FileToBufferUtil.__regularReadIntoList(Fil eToBufferUtil.java:60) at
ch.qos.logback.core.testUtil.__FileToBufferUtil.readIntoList(__FileToBuff erUtil.java:38) at
ch.qos.logback.core.rolling.__ScaffoldingForRollingTests.__fileContentChe ck(ScaffoldingForRollingTests.__java:84) at
ch.qos.logback.core.rolling.__ScaffoldingForRollingTests.__sortedContentC heck(__ScaffoldingForRollingTests.__java:99) at
ch.qos.logback.core.rolling.__SizeAndTimeBasedFNATP_Test.__generic(SizeAn dTimeBasedFNATP_Test.java:130) at
ch.qos.logback.core.rolling.__SizeAndTimeBasedFNATP_Test.__withGZCompress
ion_FileBlank_NoRestart_6(__SizeAndTimeBasedFNATP_Test.__java:189) at sun.reflect.__NativeMethodAccessorImpl.__invoke0(Native Method) at
sun.reflect.__NativeMethodAccessorImpl.__invoke(__NativeMethodAccessorImpl. java:57) at
sun.reflect.__DelegatingMethodAccessorImpl.__invoke(DelegatingMethodAcces sorImpl.java:43) at java.lang.reflect.Method.__invoke(Method.java:601) at
org.junit.runners.model.__FrameworkMethod$1.__runReflectiveCall(Framework Method.java:45) at
org.junit.internal.runners.__model.ReflectiveCallable.run(__ReflectiveCal lable.java:15) at
org.junit.runners.model.__FrameworkMethod.__invokeExplosively(FrameworkMe thod.java:42) at
org.junit.internal.runners.__statements.InvokeMethod.__evaluate(InvokeMet hod.java:20) at
org.junit.internal.runners.__statements.RunBefores.__evaluate(RunBefores. java:28) at
org.junit.runners.__ParentRunner.runLeaf(__ParentRunner.java:263) at
org.junit.runners.__BlockJUnit4ClassRunner.__runChild(BlockJUnit4ClassRun ner.java:68) at
org.junit.runners.__BlockJUnit4ClassRunner.__runChild(BlockJUnit4ClassRun ner.java:47) at org.junit.runners.__ParentRunner$3.run(__ParentRunner.java:231) at
org.junit.runners.__ParentRunner$1.schedule(__ParentRunner.java:60) at
org.junit.runners.__ParentRunner.runChildren(__ParentRunner.java:229) at
org.junit.runners.__ParentRunner.access$000(__ParentRunner.java:50) at
org.junit.runners.__ParentRunner$2.evaluate(__ParentRunner.java:222) at org.junit.runners.__ParentRunner.run(ParentRunner.__java:300) 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:231) at
java.util.concurrent.__Executors$RunnableAdapter.__call(Executors.java:47 1) at
java.util.concurrent.__FutureTask$Sync.innerRun(__FutureTask.java:334) at java.util.concurrent.__FutureTask.run(FutureTask.__java:166) at
java.util.concurrent.__ThreadPoolExecutor.runWorker(__ThreadPoolExecutor. java:1110) at
java.util.concurrent.__ThreadPoolExecutor$Worker.run(__ThreadPoolExecutor .java:603) at java.lang.Thread.run(Thread.__java:722)
withGZCompression_FileSet___NoRestart_5(ch.qos.logback.__core.rolling.SizeAndTimeBas edFNATP_Test) Time elapsed: 0.067 sec
noCompression_FileSet___WithStopStart_4(ch.qos.__logback.core.rolling.__SizeAndTimeBas edFNATP_Test) Time elapsed: 0.102 sec
noCompression_FileBlank___WithStopStart_3(ch.qos.__logback.core.rolling.__SizeAndTimeB asedFNATP_Test) Time elapsed: 0.061 sec
noCompression_FileBlank___NoRestart_2(ch.qos.logback.__core.rolling.SizeAndTimeBased FNATP_Test) Time elapsed: 0.036 sec
noCompression_FileSet___NoRestart_1(ch.qos.logback.__core.rolling.__SizeAndTimeBasedFN ATP_Test) Time elapsed: 0.039 sec Running ch.qos.logback.core.recovery.__ResilientOutputStreamTest Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.288 sec
verifyRecuperationAfterFailure__(ch.qos.logback.core.recovery.__ResilientOutputStrea mTest) Time elapsed: 0.288 sec Running ch.qos.logback.core.rolling.__helper.CompressTest Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.253 sec test2(ch.qos.logback.core.__rolling.helper.CompressTest) Time elapsed: 0.223 sec test1(ch.qos.logback.core.__rolling.helper.CompressTest) Time elapsed: 0.018 sec test3(ch.qos.logback.core.__rolling.helper.CompressTest) Time elapsed: 0.012 sec
Results :
Tests in error:
withGZCompression_FileBlank___NoRestart_6(ch.qos.logback.__core.rolling.SizeAndTim eBasedFNATP_Test):
target\test-output\910764289\__test6-2013-04-15_23_47_35-1.__txt (The system cannot find the file specified)
Tests run: 424, Failures: 0, Errors: 1, Skipped: 6
[INFO]
-- Ceki 65% of statistics are made up on the spot
participants (4)
-
Broc Seib
-
ceki
-
Ceki Gülcü
-
Harris, Carl